[56.361328] Initializing watchdogs
[56.361375]   /dev/watchdog0
[56.374816] [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.378880] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.380973] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.384341] [001/133] (960s left) kms_busy (extended-modeset-hang-newfb)
[56.508090] Starting subtest: extended-modeset-hang-newfb
[56.510561] Starting dynamic subtest: pipe-A
[91.792868] Dynamic subtest pipe-A: SUCCESS (35.252s)
[91.799818] Starting dynamic subtest: pipe-D
[114.769837] Dynamic subtest pipe-D: SUCCESS (22.971s)
[114.772234] Subtest extended-modeset-hang-newfb: SUCCESS (58.227s)
[115.593703] [002/133] (901s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[115.697806] Starting subtest: plane-upscale-factor-0-25-with-rotation
[115.700295] Starting dynamic subtest: pipe-A
[115.924314] Dynamic subtest pipe-A: SUCCESS (0.227s)
[115.932396] Starting dynamic subtest: pipe-B
[117.253943] Dynamic subtest pipe-B: SUCCESS (1.329s)
[117.260810] Starting dynamic subtest: pipe-C
[118.585807] Dynamic subtest pipe-C: SUCCESS (1.331s)
[118.594813] Starting dynamic subtest: pipe-D
[119.915688] Dynamic subtest pipe-D: SUCCESS (1.329s)
[119.918157] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.218s)
[121.107982] [003/133] (895s left) gem_pxp (fail-invalid-protected-context)
[121.244041] Starting subtest: fail-invalid-protected-context
[121.246670] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[121.370516] [004/133] (895s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[121.475236] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[121.479176] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[121.589814] [005/133] (895s left) kms_properties (colorop-properties-legacy)
[121.690354] Starting subtest: colorop-properties-legacy
[121.692670] Starting dynamic subtest: pipe-A-eDP-1
[122.026119] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.333s)
[122.035092] Starting dynamic subtest: pipe-B-eDP-1
[123.070323] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.043s)
[123.077694] Starting dynamic subtest: pipe-C-eDP-1
[124.101641] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.032s)
[124.110624] Starting dynamic subtest: pipe-D-eDP-1
[125.136325] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.034s)
[125.138715] Subtest colorop-properties-legacy: SUCCESS (3.446s)
[125.959582] [006/133] (891s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[126.061764] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[130.156071] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.098s)
[131.360546] [007/133] (885s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[131.456112] Starting subtest: basic-modeset-with-all-modifiers-formats
[131.843036] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[132.629717] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.787s)
[132.638246] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[132.651364] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.021s)
[132.660239] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[132.673593] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s)
[132.680755] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[132.818259] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.144s)
[132.827140] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[132.840424] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.022s)
[132.847518] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[132.862579] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[132.872130] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[132.884621] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[132.892197] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[132.906978] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[132.913534] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[132.928896] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[132.935743] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[132.951303] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[132.957812] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[132.973581] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.022s)
[132.980148] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[132.995825] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[133.002671] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[133.151443] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.155s)
[133.159898] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[133.173741] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s)
[133.181029] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[133.196072] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[133.203276] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[133.218301] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[133.225400] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[133.240250] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[133.248563] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[133.262802] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[133.270090] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[133.284519] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.021s)
[133.291970] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[133.307101] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[133.314113] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[133.329285] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[133.336689] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[133.484907] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.155s)
[133.493647] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[133.507079] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s)
[133.515297] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[133.529630] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[133.538712] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[133.551892] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[133.558791] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[133.573771] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[133.580873] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[133.596153] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[133.604134] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[133.618043] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[133.624684] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[133.640330] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[133.646898] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[133.662562] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[134.057174] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.603s)
[134.828643] [008/133] (882s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[134.937149] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[134.941273] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s)
[135.016196] [009/133] (882s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[135.081250] Starting subtest: rel-constants-invalid-rel-gen5
[135.083588] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[135.198387] [010/133] (882s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[135.320921] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[135.325455] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[135.485344] [011/133] (881s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[135.580166] Starting subtest: fbcpsr-indfb-scaledprimary
[141.333919] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.756s)
[141.475896] [012/133] (875s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[141.586691] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[141.594995] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.010s)
[141.709456] [013/133] (875s left) gem_exec_schedule (promotion)
[141.787532] Starting subtest: promotion
[141.789647] Starting dynamic subtest: rcs0
[141.885323] Dynamic subtest rcs0: SUCCESS (0.099s)
[141.894658] Starting dynamic subtest: vcs0
[141.972425] Dynamic subtest vcs0: SUCCESS (0.087s)
[141.983426] Starting dynamic subtest: vcs1
[142.052878] Dynamic subtest vcs1: SUCCESS (0.080s)
[142.063612] Starting dynamic subtest: vecs0
[142.129728] Dynamic subtest vecs0: SUCCESS (0.077s)
[142.140442] Starting dynamic subtest: ccs0
[142.213200] Dynamic subtest ccs0: SUCCESS (0.083s)
[142.215672] Subtest promotion: SUCCESS (0.427s)
[144.017483] [014/133] (873s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[144.062259] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[144.111052] [015/133] (873s left) gem_ctx_shared (disjoint-timelines)
[144.173074] Starting subtest: disjoint-timelines
[144.230128] Subtest disjoint-timelines: SUCCESS (0.056s)
[144.489971] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[144.493060] [016/133] (872s left) gem_userptr_blits (map-fixed-invalidate)
[144.565535] Starting subtest: map-fixed-invalidate
[144.569810] Subtest map-fixed-invalidate: SKIP (0.000s)
[144.721840] [017/133] (872s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[144.825976] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[144.828346] Starting dynamic subtest: pipe-A-default-mode
[146.021267] Dynamic subtest pipe-A-default-mode: SKIP (1.193s)
[146.025827] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.194s)
[146.178675] [018/133] (871s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[146.270597] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[155.575318] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.306s)
[155.731947] [019/133] (861s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[155.839677] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[155.844202] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[155.949442] [020/133] (861s left) gem_ctx_persistence (engines-hang)
[156.026430] Starting subtest: engines-hang
[156.028726] Starting dynamic subtest: rcs0
[156.161282] Dynamic subtest rcs0: SUCCESS (0.134s)
[156.170915] Starting dynamic subtest: bcs0
[156.283341] Dynamic subtest bcs0: SUCCESS (0.121s)
[156.294242] Starting dynamic subtest: vcs0
[156.391466] Dynamic subtest vcs0: SUCCESS (0.107s)
[156.401678] Starting dynamic subtest: vcs1
[156.497770] Dynamic subtest vcs1: SUCCESS (0.105s)
[156.507981] Starting dynamic subtest: vecs0
[156.576499] Dynamic subtest vecs0: SUCCESS (0.078s)
[156.585295] Starting dynamic subtest: ccs0
[156.733496] Dynamic subtest ccs0: SUCCESS (0.156s)
[156.735733] Subtest engines-hang: SUCCESS (0.708s)
[156.823050] [021/133] (860s left) i915_pm_rpm (sysfs-read)
[159.146928] Starting subtest: sysfs-read
[160.305757] Subtest sysfs-read: SUCCESS (1.160s)
[160.698307] [022/133] (856s left) prime_vgem (coherency-blt)
[160.782110] Starting subtest: coherency-blt
[161.836245] Subtest coherency-blt: SUCCESS (1.052s)
[161.995605] [023/133] (855s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[162.049159] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[162.051143] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[162.165388] [024/133] (855s left) gen9_exec_parse (batch-without-end)
[162.226088] Subtest batch-without-end: SKIP (0.000s)
[162.302889] [025/133] (855s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[162.409343] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[162.413592] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[162.539881] [026/133] (855s left) gem_partial_pwrite_pread (writes-after-reads-display)
[162.612620] Subtest writes-after-reads-display: SKIP (0.000s)
[162.675632] [027/133] (855s left) gem_wait (wait)
[162.748282] Starting subtest: wait
[162.750271] Starting dynamic subtest: all-engines
[163.927594] Dynamic subtest all-engines: SUCCESS (1.180s)
[163.938264] Starting dynamic subtest: rcs0
[164.973357] Dynamic subtest rcs0: SUCCESS (1.046s)
[164.987315] Starting dynamic subtest: bcs0
[166.019953] Dynamic subtest bcs0: SUCCESS (1.045s)
[166.030636] Starting dynamic subtest: vcs0
[167.063075] Dynamic subtest vcs0: SUCCESS (1.042s)
[167.073350] Starting dynamic subtest: vcs1
[168.108240] Dynamic subtest vcs1: SUCCESS (1.044s)
[168.118364] Starting dynamic subtest: vecs0
[169.152264] Dynamic subtest vecs0: SUCCESS (1.043s)
[169.162854] Starting dynamic subtest: ccs0
[170.200710] Dynamic subtest ccs0: SUCCESS (1.047s)
[170.203349] Subtest wait: SUCCESS (7.455s)
[170.410978] [028/133] (847s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[170.524108] Starting subtest: 4-tiled-32bpp-rotate-180
[171.948861] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.424s)
[172.130109] [029/133] (845s left) kms_plane_multiple (tiling-yf)
[172.233730] Starting subtest: tiling-yf
[172.237596] Subtest tiling-yf: SKIP (0.000s)
[172.317788] [030/133] (845s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[172.412766] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[172.416255] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[172.542279] [031/133] (845s left) kms_color (ctm-0-75)
[172.638388] Starting subtest: ctm-0-75
[172.639990] Starting dynamic subtest: pipe-A-eDP-1
[177.545282] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.905s)
[177.553892] Starting dynamic subtest: pipe-B-eDP-1
[183.181514] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.638s)
[183.190839] Starting dynamic subtest: pipe-C-eDP-1
[188.538310] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.354s)
[188.546487] Starting dynamic subtest: pipe-D-eDP-1
[193.901432] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.361s)
[193.903661] Subtest ctm-0-75: SUCCESS (21.261s)
[194.760029] [032/133] (823s left) kms_chamelium_frames (dp-crc-single)
[195.166203] Subtest dp-crc-single: SKIP (0.000s)
[195.945788] [033/133] (822s left) kms_draw_crc (draw-method-render)
[196.060080] Starting subtest: draw-method-render
[196.062247] Starting dynamic subtest: xrgb8888-untiled
[196.279987] Dynamic subtest xrgb8888-untiled: SUCCESS (0.221s)
[196.288156] Starting dynamic subtest: rgb565-untiled
[196.491402] Dynamic subtest rgb565-untiled: SUCCESS (0.211s)
[196.499545] Starting dynamic subtest: xrgb2101010-untiled
[196.702121] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.211s)
[196.710337] Starting dynamic subtest: xbgr16161616f-untiled
[196.924255] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.221s)
[196.931509] Starting dynamic subtest: xrgb8888-xtiled
[197.047440] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[197.054636] Starting dynamic subtest: rgb565-xtiled
[197.168541] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[197.175748] Starting dynamic subtest: xrgb2101010-xtiled
[197.279695] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[197.286776] Starting dynamic subtest: xbgr16161616f-xtiled
[197.401926] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.122s)
[197.409054] Starting dynamic subtest: xrgb8888-4tiled
[197.524071] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[197.531353] Starting dynamic subtest: rgb565-4tiled
[197.646509] Dynamic subtest rgb565-4tiled: SUCCESS (0.122s)
[197.653693] Starting dynamic subtest: xrgb2101010-4tiled
[197.757613] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[197.765061] Starting dynamic subtest: xbgr16161616f-4tiled
[197.891066] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[197.893040] Subtest draw-method-render: SUCCESS (1.833s)
[198.057810] [034/133] (820s left) gem_softpin (safe-alignment)
[198.128756] Starting subtest: safe-alignment
[198.147866] Subtest safe-alignment: SUCCESS (0.019s)
[199.946748] [035/133] (818s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[200.058387] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[200.062811] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[200.160206] [036/133] (818s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[200.265567] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[207.142790] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (6.880s)
[207.282223] [037/133] (811s left) gem_ctx_persistence (many-contexts)
[207.347046] Starting subtest: many-contexts
[252.546818] Subtest many-contexts: SUCCESS (45.200s)
[252.634296] [038/133] (765s left) gem_reg_read (bad-register)
[252.686896] Starting subtest: bad-register
[252.689283] Subtest bad-register: SUCCESS (0.000s)
[252.806120] [039/133] (765s left) kms_pm_dc (dc5-psr)
[253.900109] Starting subtest: dc5-psr
[256.783691] Subtest dc5-psr: SUCCESS (2.882s)
[257.622178] [040/133] (760s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[257.746430] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[257.750934] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[257.905012] [041/133] (760s left) gem_ctx_engines (none)
[257.974239] Starting subtest: none
[257.976566] Subtest none: SUCCESS (0.001s)
[258.169753] [042/133] (760s left) kms_addfb_basic (no-handle)
[258.224997] Starting subtest: no-handle
[258.227073] Subtest no-handle: SUCCESS (0.000s)
[258.371956] [043/133] (760s left) i915_drm_fdinfo (busy-idle)
[258.418841] Starting subtest: busy-idle
[258.421185] Starting dynamic subtest: rcs0
[258.450186] Dynamic subtest rcs0: SKIP (0.029s)
[258.460758] Starting dynamic subtest: bcs0
[258.465286] Dynamic subtest bcs0: SKIP (0.001s)
[258.472730] Starting dynamic subtest: vcs0
[258.476866] Dynamic subtest vcs0: SKIP (0.000s)
[258.484090] Starting dynamic subtest: vcs1
[258.488356] Dynamic subtest vcs1: SKIP (0.000s)
[258.495292] Starting dynamic subtest: vecs0
[258.499589] Dynamic subtest vecs0: SKIP (0.000s)
[258.506606] Starting dynamic subtest: ccs0
[258.511093] Dynamic subtest ccs0: SKIP (0.000s)
[258.515368] Subtest busy-idle: SKIP (0.035s)
[258.549797] [044/133] (760s left) perf_pmu (busy-start)
[258.586927] Starting subtest: busy-start
[258.589388] Starting dynamic subtest: rcs0
[261.138227] Dynamic subtest rcs0: SUCCESS (2.551s)
[261.149452] Starting dynamic subtest: bcs0
[263.688988] Dynamic subtest bcs0: SUCCESS (2.548s)
[263.699630] Starting dynamic subtest: vcs0
[266.902997] Dynamic subtest vcs0: SUCCESS (3.211s)
[266.912998] Starting dynamic subtest: vcs1
[270.934511] Dynamic subtest vcs1: SUCCESS (4.029s)
[270.945315] Starting dynamic subtest: vecs0
[274.902819] Dynamic subtest vecs0: SUCCESS (3.965s)
[274.913754] Starting dynamic subtest: ccs0
[277.451364] Dynamic subtest ccs0: SUCCESS (2.547s)
[277.454081] Subtest busy-start: SUCCESS (18.865s)
[277.580161] [045/133] (741s left) kms_selftest (drm_dp_mst_helper)
[277.627782] Starting subtest: drm_dp_mst_helper
[277.653147] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[277.807310] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.155s)
[277.816907] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[277.954385] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.145s)
[277.966417] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[278.109062] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.154s)
[278.111259] Subtest drm_dp_mst_helper: SUCCESS (0.482s)
[278.331388] [046/133] (740s left) gem_exec_schedule (pi-userfault)
[278.530859] Starting subtest: pi-userfault
[278.533257] Starting dynamic subtest: rcs0
[278.541378] Dynamic subtest rcs0: SUCCESS (0.015s)
[278.549780] Starting dynamic subtest: bcs0
[278.551845] Dynamic subtest bcs0: SUCCESS (0.009s)
[278.562516] Starting dynamic subtest: vcs0
[278.564966] Dynamic subtest vcs0: SUCCESS (0.011s)
[278.573060] Starting dynamic subtest: vcs1
[278.577656] Dynamic subtest vcs1: SUCCESS (0.012s)
[278.586284] Starting dynamic subtest: vecs0
[278.588612] Dynamic subtest vecs0: SUCCESS (0.010s)
[278.599538] Starting dynamic subtest: ccs0
[278.602585] Dynamic subtest ccs0: SUCCESS (0.012s)
[278.604726] Subtest pi-userfault: SUCCESS (0.073s)
[278.798487] [047/133] (739s left) gem_userptr_blits (input-checking)
[278.883356] Starting subtest: input-checking
[278.885272] Subtest input-checking: SUCCESS (0.000s)
[279.045152] [048/133] (739s left) kms_psr (fbc-pr-primary-page-flip)
[279.143637] Starting subtest: fbc-pr-primary-page-flip
[279.148044] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[279.225641] [049/133] (739s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[279.326622] Starting subtest: fbc-psr-sprite-mmap-cpu
[279.328647] Starting dynamic subtest: eDP-1
[279.332701] Dynamic subtest eDP-1: SKIP (0.000s)
[279.336814] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[279.411381] [050/133] (739s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[279.515779] Starting subtest: y-tiled-8bpp-rotate-270
[279.520055] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[279.648641] [051/133] (739s left) kms_psr (pr-primary-page-flip)
[279.741481] Starting subtest: pr-primary-page-flip
[279.745052] Subtest pr-primary-page-flip: SKIP (0.000s)
[279.825923] [052/133] (739s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[279.925753] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[279.929022] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[280.062889] [053/133] (738s left) syncobj_wait (multi-wait-submitted-signaled)
[280.128871] Starting subtest: multi-wait-submitted-signaled
[280.130752] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[280.245704] [054/133] (738s left) gem_flink_basic (basic)
[280.311911] Starting subtest: basic
[280.313882] Subtest basic: SUCCESS (0.000s)
[280.443371] [055/133] (738s left) kms_invalid_mode (zero-clock)
[280.540865] Starting subtest: zero-clock
[280.542773] Starting dynamic subtest: pipe-A-eDP-1
[280.826299] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[280.835074] Starting dynamic subtest: pipe-B-eDP-1
[280.836891] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[280.844549] Starting dynamic subtest: pipe-C-eDP-1
[280.846800] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[280.855268] Starting dynamic subtest: pipe-D-eDP-1
[280.857482] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[280.859597] Subtest zero-clock: SUCCESS (0.289s)
[281.652822] [056/133] (737s left) sysfs_preempt_timeout (idempotent)
[281.716165] Starting subtest: idempotent
[281.718350] Starting dynamic subtest: vecs0
[281.720729] Dynamic subtest vecs0: SUCCESS (0.000s)
[281.729260] Starting dynamic subtest: ccs0
[281.731106] Dynamic subtest ccs0: SUCCESS (0.000s)
[281.738684] Starting dynamic subtest: vcs0
[281.740810] Dynamic subtest vcs0: SUCCESS (0.000s)
[281.748295] Starting dynamic subtest: bcs0
[281.750150] Dynamic subtest bcs0: SUCCESS (0.000s)
[281.757216] Starting dynamic subtest: rcs0
[281.759064] Dynamic subtest rcs0: SUCCESS (0.000s)
[281.766140] Starting dynamic subtest: vcs1
[281.768047] Dynamic subtest vcs1: SUCCESS (0.000s)
[281.769851] Subtest idempotent: SUCCESS (0.006s)
[281.805632] [057/133] (737s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[281.928624] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[281.959166] Starting dynamic subtest: pipe-A-plane-0
[282.581387] Dynamic subtest pipe-A-plane-0: SUCCESS (0.622s)
[282.590841] Starting dynamic subtest: pipe-A-plane-3
[282.969866] Dynamic subtest pipe-A-plane-3: SUCCESS (0.388s)
[284.031841] Starting dynamic subtest: pipe-B-plane-0
[284.669649] Dynamic subtest pipe-B-plane-0: SUCCESS (0.644s)
[284.678132] Starting dynamic subtest: pipe-B-plane-3
[285.080626] Dynamic subtest pipe-B-plane-3: SUCCESS (0.410s)
[285.394428] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (3.464s)
[286.223550] [058/133] (732s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[286.319950] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[286.322189] Starting dynamic subtest: A-eDP1
[289.948626] Dynamic subtest A-eDP1: SUCCESS (3.626s)
[289.958640] Starting dynamic subtest: B-eDP1
[293.154155] Dynamic subtest B-eDP1: SUCCESS (3.203s)
[293.163558] Starting dynamic subtest: C-eDP1
[296.272936] Dynamic subtest C-eDP1: SUCCESS (3.119s)
[296.282018] Starting dynamic subtest: D-eDP1
[299.502478] Dynamic subtest D-eDP1: SUCCESS (3.230s)
[299.504579] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.185s)
[300.308313] [059/133] (718s left) gem_basic (bad-close)
[300.372326] Starting subtest: bad-close
[300.376970] Subtest bad-close: SUCCESS (0.000s)
[300.488734] [060/133] (718s left) kms_chamelium_color (ctm-0-75)
[300.590083] Subtest ctm-0-75: SKIP (0.000s)
[300.636165] [061/133] (718s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[300.738354] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[300.740846] Starting dynamic subtest: pipe-A
[301.064867] Dynamic subtest pipe-A: SUCCESS (0.329s)
[301.075465] Starting dynamic subtest: pipe-B
[302.409405] Dynamic subtest pipe-B: SUCCESS (1.343s)
[302.418166] Starting dynamic subtest: pipe-C
[303.760341] Dynamic subtest pipe-C: SUCCESS (1.349s)
[303.769855] Starting dynamic subtest: pipe-D
[305.022471] Dynamic subtest pipe-D: SUCCESS (1.260s)
[305.024833] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.288s)
[306.216283] [062/133] (713s left) kms_psr (psr2-no-drrs)
[306.318166] Starting subtest: psr2-no-drrs
[306.320452] Starting dynamic subtest: eDP-1
[307.979182] Dynamic subtest eDP-1: SUCCESS (1.662s)
[307.981597] Subtest psr2-no-drrs: SUCCESS (1.663s)
[308.787929] [063/133] (710s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[308.854548] Starting subtest: single-wait-for-submit-available-submitted
[308.856441] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[308.972087] [064/133] (710s left) kms_big_fb (x-tiled-addfb)
[309.076303] Starting subtest: x-tiled-addfb
[309.078246] Subtest x-tiled-addfb: SUCCESS (0.000s)
[309.215678] [065/133] (710s left) gem_exec_reloc (basic-range-active)
[309.283649] Subtest basic-range-active: SKIP (0.000s)
[309.358370] [066/133] (710s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[309.447110] Starting subtest: bad-pixel-format-y-tiled-ccs
[309.449447] Starting dynamic subtest: pipe-A-eDP-1
[309.472624] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[309.482724] Starting dynamic subtest: pipe-B-eDP-1
[309.504392] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[309.512219] Starting dynamic subtest: pipe-C-eDP-1
[309.533507] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[309.540972] Starting dynamic subtest: pipe-D-eDP-1
[309.562666] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[309.567042] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[311.672233] [067/133] (707s left) syncobj_timeline (reset-during-wait-for-submit)
[311.729916] Starting subtest: reset-during-wait-for-submit
[311.834506] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[311.927601] [068/133] (707s left) api_intel_bb (blit-reloc-purge-cache)
[311.977431] Starting subtest: blit-reloc-purge-cache
[311.982060] Subtest blit-reloc-purge-cache: SKIP (0.000s)
[312.117192] [069/133] (707s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[312.232504] Starting subtest: cursorA-vs-flipB-varying-size
[312.237034] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[312.402346] [070/133] (707s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[312.501114] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[312.505269] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[312.580061] [071/133] (707s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[312.680723] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[312.685090] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[312.761011] [072/133] (706s left) dmabuf_sync_file (import-multiple-read-only)
[312.811395] Starting subtest: import-multiple-read-only
[312.813643] Subtest import-multiple-read-only: SUCCESS (0.003s)
[312.877707] [073/133] (706s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[312.989744] Starting subtest: cursorA-vs-flipA-legacy
[324.113425] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.121s)
[324.983540] [074/133] (694s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[325.090427] Starting subtest: 2x-plain-flip-ts-check-interruptible
[325.101743] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.011s)
[325.230879] [075/133] (694s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[325.336406] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[326.470829] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.134s)
[326.624061] [076/133] (693s left) api_intel_bb (object-reloc-purge-cache)
[326.690674] Starting subtest: object-reloc-purge-cache
[326.694632] Subtest object-reloc-purge-cache: SKIP (0.002s)
[326.845350] [077/133] (693s left) syncobj_basic (bad-destroy-pad)
[326.905481] Starting subtest: bad-destroy-pad
[326.907327] Subtest bad-destroy-pad: SUCCESS (0.000s)
[327.025090] [078/133] (692s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[327.072699] Starting subtest: invalid-bsd1-flag-on-vebox
[327.074615] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[327.187053] [079/133] (692s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[327.235532] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[327.341051] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.103s)
[327.439820] [080/133] (692s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[327.504704] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[327.612260] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[327.713549] [081/133] (692s left) i915_power (sanity)
[327.770426] Starting subtest: sanity
[331.830644] Subtest sanity: SUCCESS (4.055s)
[332.991561] [082/133] (687s left) syncobj_basic (bad-flags-fd-to-handle)
[333.047374] Starting subtest: bad-flags-fd-to-handle
[333.049359] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[333.185390] [083/133] (686s left) kms_cursor_crc (cursor-offscreen-64x64)
[333.360902] Starting subtest: cursor-offscreen-64x64
[333.370755] Starting dynamic subtest: pipe-A-eDP-1
[335.341158] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.969s)
[335.375567] Starting dynamic subtest: pipe-D-eDP-1
[337.965569] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.598s)
[337.967905] Subtest cursor-offscreen-64x64: SUCCESS (4.605s)
[338.847822] [084/133] (681s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[338.956405] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[339.736231] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.781s)
[339.919072] [085/133] (680s left) gem_exec_fence (invalid-timeline-fence-array)
[340.467352] Starting subtest: invalid-timeline-fence-array
[340.473939] Subtest invalid-timeline-fence-array: SUCCESS (0.005s)
[342.032146] [086/133] (678s left) gem_exec_balancer (bonded-sync)
[342.088470] Starting subtest: bonded-sync
[342.092655] Subtest bonded-sync: SKIP (0.000s)
[342.414491] [087/133] (677s left) gem_workarounds (suspend-resume-fd)
[342.484199] Starting subtest: suspend-resume-fd
[358.869789] Subtest suspend-resume-fd: SUCCESS (1.327s)
[360.044722] [088/133] (661s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[360.167187] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[367.248173] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.012s)
[367.396106] [089/133] (654s left) kms_hdr (invalid-metadata-sizes)
[367.496552] Starting subtest: invalid-metadata-sizes
[367.502937] Subtest invalid-metadata-sizes: SKIP (0.000s)
[367.597614] [090/133] (653s left) gem_softpin (evict-snoop)
[367.662334] Starting subtest: evict-snoop
[367.666854] Subtest evict-snoop: SKIP (0.000s)
[367.804984] [091/133] (653s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[367.895588] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[367.899898] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[367.996743] [092/133] (653s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[368.108914] Starting subtest: verify-pxp-stale-buf-optout-execution
[369.169951] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.057s)
[369.337437] [093/133] (652s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[369.463337] Starting subtest: cursor-vs-flip-toggle
[400.247721] Subtest cursor-vs-flip-toggle: SUCCESS (30.739s)
[401.122957] [094/133] (620s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[401.234536] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[401.238530] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[401.352785] [095/133] (620s left) gem_userptr_blits (invalid-null-pointer)
[401.418191] Starting subtest: invalid-null-pointer
[401.420093] Subtest invalid-null-pointer: SUCCESS (0.001s)
[401.575678] [096/133] (620s left) kms_cursor_crc (cursor-random-32x10)
[401.735130] Starting subtest: cursor-random-32x10
[401.779174] Subtest cursor-random-32x10: SKIP (0.039s)
[402.064568] [097/133] (619s left) syncobj_wait (single-wait-for-submit-signaled)
[402.127278] Starting subtest: single-wait-for-submit-signaled
[402.129306] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[402.247663] [098/133] (619s left) gem_exec_whisper (basic-fds-priority-all)
[402.315943] Starting subtest: basic-fds-priority-all
[425.935062] Subtest basic-fds-priority-all: SUCCESS (23.604s)
[426.127418] [099/133] (595s left) gem_exec_flush (basic-uc-set-default)
[426.197646] Starting subtest: basic-uc-set-default
[431.667003] Subtest basic-uc-set-default: SUCCESS (5.466s)
[431.862296] [100/133] (590s left) gem_eio (in-flight-contexts-immediate)
[432.097481] Starting subtest: in-flight-contexts-immediate
[446.030950] Subtest in-flight-contexts-immediate: SUCCESS (13.927s)
[446.240522] [101/133] (575s left) kms_vrr (flip-basic-fastset)
[446.343183] Starting subtest: flip-basic-fastset
[446.349177] Subtest flip-basic-fastset: SKIP (0.000s)
[446.430074] [102/133] (575s left) kms_addfb_basic (small-bo)
[446.523348] Starting subtest: small-bo
[446.525213] Subtest small-bo: SUCCESS (0.000s)
[446.655428] [103/133] (575s left) kms_content_protection (atomic-dpms-hdcp14)
[446.800174] Starting subtest: atomic-dpms-hdcp14
[446.804688] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[446.968394] [104/133] (575s left) gem_exec_suspend (basic-s3)
[447.026373] Starting subtest: basic-S3
[447.028386] Starting dynamic subtest: smem
[467.754519] Dynamic subtest smem: SUCCESS (6.879s)
[467.758809] Subtest basic-S3: SUCCESS (6.881s)
[467.947103] [105/133] (554s left) syncobj_timeline (invalid-signal-bad-pad)
[468.016191] Starting subtest: invalid-signal-bad-pad
[468.018318] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[468.133242] [106/133] (553s left) gem_lmem_swapping (verify-random)
[468.173894] Subtest verify-random: SKIP (0.000s)
[468.219526] [107/133] (553s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[468.326229] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[468.330573] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[468.440480] [108/133] (553s left) gem_mmap_offset (perf)
[468.509762] Starting subtest: perf
[468.511973] Starting dynamic subtest: smem0
[468.851238] Dynamic subtest smem0: SUCCESS (0.335s)
[468.853904] Subtest perf: SUCCESS (0.336s)
[468.993909] [109/133] (553s left) kms_prop_blob (basic)
[469.060074] Starting subtest: basic
[469.062063] Subtest basic: SUCCESS (0.000s)
[469.163892] [110/133] (553s left) kms_cursor_edge_walk (128x128-top-bottom)
[469.265331] Starting subtest: 128x128-top-bottom
[469.267752] Starting dynamic subtest: pipe-A-eDP-1
[472.759500] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.462s)
[472.770705] Starting dynamic subtest: pipe-D-eDP-1
[475.903986] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.126s)
[475.906254] Subtest 128x128-top-bottom: SUCCESS (6.591s)
[476.714914] [111/133] (545s left) kms_psr2_su (frontbuffer-xrgb8888)
[476.783776] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[476.860225] [112/133] (545s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[476.925938] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[476.989664] [113/133] (545s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[477.109686] Starting subtest: cursorA-vs-flipA-atomic
[488.263441] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.126s)
[489.075170] [114/133] (533s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[489.142555] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[489.220154] [115/133] (533s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[489.326628] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[489.328887] Starting dynamic subtest: pipe-A-eDP-1
[505.074903] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.439s)
[505.084941] Starting dynamic subtest: pipe-B-eDP-1
[522.046609] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.436s)
[522.057339] Starting dynamic subtest: pipe-C-eDP-1
[539.022573] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.336s)
[539.032735] Starting dynamic subtest: pipe-D-eDP-1
[556.051350] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.371s)
[556.054026] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.586s)
[557.309306] [116/133] (465s left) perf (gen12-mi-rpc)
[557.446505] Starting subtest: gen12-mi-rpc
[557.448823] Starting dynamic subtest: rcs0
[557.470970] Dynamic subtest rcs0: SUCCESS (0.023s)
[557.473360] Subtest gen12-mi-rpc: SUCCESS (0.024s)
[557.511626] [117/133] (465s left) gem_busy (parallel)
[557.715863] Starting subtest: parallel
[557.720254] Starting dynamic subtest: rcs0
[559.834304] Dynamic subtest rcs0: SUCCESS (2.109s)
[559.847048] Starting dynamic subtest: bcs0
[561.821043] Dynamic subtest bcs0: SUCCESS (1.965s)
[561.833236] Starting dynamic subtest: vcs0
[563.805327] Dynamic subtest vcs0: SUCCESS (1.968s)
[563.819022] Starting dynamic subtest: vcs1
[565.851789] Dynamic subtest vcs1: SUCCESS (2.033s)
[565.865582] Starting dynamic subtest: vecs0
[567.837111] Dynamic subtest vecs0: SUCCESS (1.973s)
[567.850577] Starting dynamic subtest: ccs0
[569.820300] Dynamic subtest ccs0: SUCCESS (1.973s)
[569.823324] Subtest parallel: SUCCESS (12.036s)
[570.059533] [118/133] (452s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[570.126591] Starting subtest: y-tiled-to-vebox-y-tiled
[570.132128] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[570.324824] [119/133] (452s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[570.440803] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[577.170042] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.719s)
[577.322664] [120/133] (445s left) kms_async_flips (invalid-async-flip-atomic)
[577.439123] Starting subtest: invalid-async-flip-atomic
[577.801327] Starting dynamic subtest: pipe-A-eDP-1
[578.584990] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.784s)
[578.949794] Starting dynamic subtest: pipe-B-eDP-1
[579.725872] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.784s)
[580.083314] Starting dynamic subtest: pipe-C-eDP-1
[580.832482] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.756s)
[581.190499] Starting dynamic subtest: pipe-D-eDP-1
[581.959567] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.775s)
[581.961991] Subtest invalid-async-flip-atomic: SUCCESS (4.516s)
[583.128900] [121/133] (439s left) gem_render_copy_redux (normal)
[583.198044] Starting subtest: normal
[583.474694] Subtest normal: SUCCESS (0.276s)
[583.614232] [122/133] (439s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[583.723248] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[583.728467] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[583.860313] [123/133] (438s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[583.958842] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[583.961145] Starting dynamic subtest: pipe-A-eDP-1
[583.984607] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[584.001057] Starting dynamic subtest: pipe-B-eDP-1
[584.023826] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[584.033205] Starting dynamic subtest: pipe-C-eDP-1
[584.054840] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[584.063965] Starting dynamic subtest: pipe-D-eDP-1
[584.083315] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[584.086795] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[586.211428] [124/133] (436s left) gem_exec_params (larger-than-life-batch)
[586.283348] Starting subtest: larger-than-life-batch
[588.607803] Subtest larger-than-life-batch: SUCCESS (2.328s)
[589.917669] [125/133] (433s left) kms_chamelium_hpd (hdmi-hpd)
[590.352587] Subtest hdmi-hpd: SKIP (0.000s)
[591.213370] [126/133] (431s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[591.307936] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[591.310441] Starting dynamic subtest: pipe-A-eDP-1
[591.571722] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.228s)
[591.583989] Starting dynamic subtest: pipe-B-eDP-1
[592.784147] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.212s)
[592.797186] Starting dynamic subtest: pipe-C-eDP-1
[593.983683] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.203s)
[593.995224] Starting dynamic subtest: pipe-D-eDP-1
[595.229165] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.241s)
[595.231683] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.889s)
[596.363096] [127/133] (426s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[596.456102] Starting subtest: disable-crc-after-crtc
[596.458907] Starting dynamic subtest: pipe-A-eDP-1
[598.083096] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.628s)
[598.094454] Starting dynamic subtest: pipe-B-eDP-1
[600.461464] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.377s)
[600.472335] Starting dynamic subtest: pipe-C-eDP-1
[602.718107] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.253s)
[602.729926] Starting dynamic subtest: pipe-D-eDP-1
[604.973134] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.256s)
[604.976150] Subtest disable-crc-after-crtc: SUCCESS (8.520s)
[605.824638] [128/133] (417s left) sw_sync (sync_multi_producer_single_consumer)
[605.877280] Starting subtest: sync_multi_producer_single_consumer
[606.951631] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[607.022825] [129/133] (416s left) kms_psr (psr-primary-mmap-gtt)
[607.132090] Starting subtest: psr-primary-mmap-gtt
[607.134840] Starting dynamic subtest: eDP-1
[608.613960] Dynamic subtest eDP-1: SKIP (1.479s)
[608.620054] Subtest psr-primary-mmap-gtt: SKIP (1.481s)
[608.810075] [130/133] (414s left) gem_exec_capture (capture-recoverable)
[608.893112] Starting subtest: capture-recoverable
[608.896064] Subtest capture-recoverable: SUCCESS (0.003s)
[609.051243] [131/133] (414s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[609.159093] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[609.164073] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[609.281547] [132/133] (413s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[609.384909] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[613.521194] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.139s)
[614.700071] [133/133] (408s left) i915_module_load (reload-no-display)
[614.752042] Starting subtest: reload-no-display
[620.791733] Subtest reload-no-display: SUCCESS (6.042s)
[620.821671] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[620.822403] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[620.822548] 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.