[63.011840] Initializing watchdogs
[63.011887]   /dev/watchdog0
[63.026325] [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]
[63.030703] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[63.033246] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.035091] [001/133] (960s left) kms_busy (extended-modeset-hang-newfb)
[63.131976] Starting subtest: extended-modeset-hang-newfb
[63.132860] Starting dynamic subtest: pipe-A
[97.811530] Dynamic subtest pipe-A: SUCCESS (34.641s)
[97.818767] Starting dynamic subtest: pipe-D
[120.860567] Dynamic subtest pipe-D: SUCCESS (23.041s)
[120.861462] Subtest extended-modeset-hang-newfb: SUCCESS (57.687s)
[121.655820] [002/133] (901s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[121.753480] Starting subtest: plane-upscale-factor-0-25-with-rotation
[121.754542] Starting dynamic subtest: pipe-A
[121.979421] Dynamic subtest pipe-A: SUCCESS (0.227s)
[121.985827] Starting dynamic subtest: pipe-B
[123.238079] Dynamic subtest pipe-B: SUCCESS (1.258s)
[123.244854] Starting dynamic subtest: pipe-C
[124.579668] Dynamic subtest pipe-C: SUCCESS (1.340s)
[124.587452] Starting dynamic subtest: pipe-D
[125.910381] Dynamic subtest pipe-D: SUCCESS (1.330s)
[125.911401] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.158s)
[127.112925] [003/133] (896s left) gem_pxp (fail-invalid-protected-context)
[127.242674] Starting subtest: fail-invalid-protected-context
[127.243885] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[127.352937] [004/133] (896s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[127.456811] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[127.458560] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[127.576228] [005/133] (896s left) kms_properties (colorop-properties-legacy)
[127.673427] Starting subtest: colorop-properties-legacy
[127.674434] Starting dynamic subtest: pipe-A-eDP-1
[128.009159] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.336s)
[128.016072] Starting dynamic subtest: pipe-B-eDP-1
[129.061931] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.051s)
[129.069038] Starting dynamic subtest: pipe-C-eDP-1
[130.093329] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.031s)
[130.100230] Starting dynamic subtest: pipe-D-eDP-1
[131.130850] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.037s)
[131.131832] Subtest colorop-properties-legacy: SUCCESS (3.458s)
[131.950268] [006/133] (891s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[132.052541] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[136.194743] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.142s)
[137.331065] [007/133] (886s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[137.428078] Starting subtest: basic-modeset-with-all-modifiers-formats
[137.800342] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[138.606974] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.807s)
[138.614051] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[138.628842] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.022s)
[138.634749] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[138.651042] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.022s)
[138.659747] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[138.795951] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.144s)
[138.807075] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[138.817836] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.022s)
[138.824645] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[138.840048] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[138.846063] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[138.862191] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[138.867891] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[138.884658] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.022s)
[138.894997] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[138.906648] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.021s)
[138.915087] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[138.928885] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.022s)
[138.937320] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[138.951189] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.022s)
[138.958917] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[138.973561] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.022s)
[138.980326] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[139.117996] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.144s)
[139.126381] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[139.139979] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.021s)
[139.145976] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[139.162260] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[139.167795] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[139.184510] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[139.190112] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[139.206819] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[139.212343] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[139.228950] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[139.235360] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[139.251151] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[139.259432] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[139.273855] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[139.281822] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[139.295727] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[139.303652] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[139.451705] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.156s)
[139.459367] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[139.473793] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s)
[139.479659] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[139.495895] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[139.501527] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[139.518362] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[139.523973] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[139.540545] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[139.546121] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[139.562804] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.022s)
[139.568300] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[139.584937] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[139.590403] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[139.607113] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[139.612510] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[139.629439] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[140.012009] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.584s)
[140.784417] [008/133] (882s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[140.878078] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[140.879895] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s)
[140.957270] [009/133] (882s left) gem_exec_params (rel-constants-invalid-rel-gen5)
[141.024270] Starting subtest: rel-constants-invalid-rel-gen5
[141.025222] Subtest rel-constants-invalid-rel-gen5: SUCCESS (0.000s)
[141.145607] [010/133] (882s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[141.256253] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[141.258331] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[141.412772] [011/133] (882s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[141.497470] Starting subtest: fbcpsr-indfb-scaledprimary
[147.253038] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.757s)
[147.397422] [012/133] (876s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[147.494932] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[147.505351] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.011s)
[147.632094] [013/133] (876s left) gem_exec_schedule (promotion)
[147.699240] Starting subtest: promotion
[147.700313] Starting dynamic subtest: rcs0
[147.798595] Dynamic subtest rcs0: SUCCESS (0.099s)
[147.807661] Starting dynamic subtest: vcs0
[147.890274] Dynamic subtest vcs0: SUCCESS (0.091s)
[147.899563] Starting dynamic subtest: vcs1
[147.976640] Dynamic subtest vcs1: SUCCESS (0.085s)
[147.986483] Starting dynamic subtest: vecs0
[148.055360] Dynamic subtest vecs0: SUCCESS (0.079s)
[148.065836] Starting dynamic subtest: ccs0
[148.139473] Dynamic subtest ccs0: SUCCESS (0.083s)
[148.140838] Subtest promotion: SUCCESS (0.439s)
[149.019296] [014/133] (874s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[149.065773] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[149.100138] [015/133] (874s left) gem_ctx_shared (disjoint-timelines)
[149.161498] Starting subtest: disjoint-timelines
[149.209692] Subtest disjoint-timelines: SUCCESS (0.048s)
[149.450403] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[149.451924] [016/133] (874s left) gem_userptr_blits (map-fixed-invalidate)
[149.522340] Starting subtest: map-fixed-invalidate
[149.524344] Subtest map-fixed-invalidate: SKIP (0.000s)
[149.673553] [017/133] (874s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[149.768255] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[149.769042] Starting dynamic subtest: pipe-A-default-mode
[150.958846] Dynamic subtest pipe-A-default-mode: SKIP (1.190s)
[150.960503] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.191s)
[151.124032] [018/133] (872s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite)
[151.221497] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite
[160.610253] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (9.390s)
[160.766968] [019/133] (863s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move)
[160.869581] Starting subtest: psr-2p-scndscrn-spr-indfb-move
[160.871509] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[160.999894] [020/133] (863s left) gem_ctx_persistence (engines-hang)
[161.056855] Starting subtest: engines-hang
[161.057889] Starting dynamic subtest: rcs0
[161.176911] Dynamic subtest rcs0: SUCCESS (0.119s)
[161.186579] Starting dynamic subtest: bcs0
[161.289796] Dynamic subtest bcs0: SUCCESS (0.112s)
[161.299248] Starting dynamic subtest: vcs0
[161.409845] Dynamic subtest vcs0: SUCCESS (0.119s)
[161.419089] Starting dynamic subtest: vcs1
[161.528766] Dynamic subtest vcs1: SUCCESS (0.118s)
[161.537950] Starting dynamic subtest: vecs0
[161.639831] Dynamic subtest vecs0: SUCCESS (0.110s)
[161.649077] Starting dynamic subtest: ccs0
[161.760449] Dynamic subtest ccs0: SUCCESS (0.120s)
[161.761648] Subtest engines-hang: SUCCESS (0.704s)
[161.854784] [021/133] (862s left) i915_pm_rpm (sysfs-read)
[164.075640] Starting subtest: sysfs-read
[165.245359] Subtest sysfs-read: SUCCESS (1.168s)
[165.637470] [022/133] (858s left) prime_vgem (coherency-blt)
[165.726057] Starting subtest: coherency-blt
[166.799697] Subtest coherency-blt: SUCCESS (1.064s)
[166.949490] [023/133] (857s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[166.994289] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[166.995281] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[167.106244] [024/133] (857s left) gen9_exec_parse (batch-without-end)
[167.167872] Subtest batch-without-end: SKIP (0.000s)
[167.240887] [025/133] (857s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[167.339150] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[167.340788] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[167.436346] [026/133] (856s left) gem_partial_pwrite_pread (writes-after-reads-display)
[167.499062] Subtest writes-after-reads-display: SKIP (0.000s)
[167.565888] [027/133] (856s left) gem_wait (wait)
[167.631495] Starting subtest: wait
[167.632470] Starting dynamic subtest: all-engines
[168.926969] Dynamic subtest all-engines: SUCCESS (1.286s)
[168.935624] Starting dynamic subtest: rcs0
[169.965354] Dynamic subtest rcs0: SUCCESS (1.046s)
[169.976883] Starting dynamic subtest: bcs0
[171.012126] Dynamic subtest bcs0: SUCCESS (1.046s)
[171.021080] Starting dynamic subtest: vcs0
[172.054993] Dynamic subtest vcs0: SUCCESS (1.042s)
[172.065063] Starting dynamic subtest: vcs1
[173.099170] Dynamic subtest vcs1: SUCCESS (1.043s)
[173.107898] Starting dynamic subtest: vecs0
[174.145272] Dynamic subtest vecs0: SUCCESS (1.045s)
[174.154825] Starting dynamic subtest: ccs0
[175.192901] Dynamic subtest ccs0: SUCCESS (1.047s)
[175.193959] Subtest wait: SUCCESS (7.563s)
[175.399262] [028/133] (849s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[175.509352] Starting subtest: 4-tiled-32bpp-rotate-180
[176.977533] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.469s)
[177.129624] [029/133] (847s left) kms_plane_multiple (tiling-yf)
[177.231386] Starting subtest: tiling-yf
[177.233092] Subtest tiling-yf: SKIP (0.000s)
[177.311181] [030/133] (847s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[177.413482] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[177.415112] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[177.550726] [031/133] (846s left) kms_color (ctm-0-75)
[177.640074] Starting subtest: ctm-0-75
[177.640859] Starting dynamic subtest: pipe-A-eDP-1
[182.543717] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.904s)
[182.549989] Starting dynamic subtest: pipe-B-eDP-1
[188.168665] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.624s)
[188.175039] Starting dynamic subtest: pipe-C-eDP-1
[193.557931] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.390s)
[193.565068] Starting dynamic subtest: pipe-D-eDP-1
[198.875482] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.317s)
[198.876405] Subtest ctm-0-75: SUCCESS (21.236s)
[199.689737] [032/133] (824s left) kms_chamelium_frames (dp-crc-single)
[200.071722] Subtest dp-crc-single: SKIP (0.000s)
[200.882085] [033/133] (823s left) kms_draw_crc (draw-method-render)
[200.978128] Starting subtest: draw-method-render
[200.978965] Starting dynamic subtest: xrgb8888-untiled
[201.222871] Dynamic subtest xrgb8888-untiled: SUCCESS (0.245s)
[201.229766] Starting dynamic subtest: rgb565-untiled
[201.434142] Dynamic subtest rgb565-untiled: SUCCESS (0.210s)
[201.443135] Starting dynamic subtest: xrgb2101010-untiled
[201.700720] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.266s)
[201.710581] Starting dynamic subtest: xbgr16161616f-untiled
[201.945348] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.244s)
[201.951982] Starting dynamic subtest: xrgb8888-xtiled
[202.067457] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[202.074696] Starting dynamic subtest: rgb565-xtiled
[202.189700] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[202.196299] Starting dynamic subtest: xrgb2101010-xtiled
[202.345260] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.155s)
[202.351911] Starting dynamic subtest: xbgr16161616f-xtiled
[202.478891] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[202.485485] Starting dynamic subtest: xrgb8888-4tiled
[202.601070] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[202.609989] Starting dynamic subtest: rgb565-4tiled
[202.712290] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[202.720949] Starting dynamic subtest: xrgb2101010-4tiled
[202.834213] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.120s)
[202.841034] Starting dynamic subtest: xbgr16161616f-4tiled
[202.967707] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[202.968620] Subtest draw-method-render: SUCCESS (1.991s)
[203.112086] [034/133] (821s left) gem_softpin (safe-alignment)
[203.176620] Starting subtest: safe-alignment
[203.196747] Subtest safe-alignment: SUCCESS (0.020s)
[204.929519] [035/133] (819s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[205.027149] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[205.028817] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[205.145177] [036/133] (819s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[205.235237] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[212.275658] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.041s)
[212.431238] [037/133] (812s left) gem_ctx_persistence (many-contexts)
[212.504442] Starting subtest: many-contexts
[250.222818] Subtest many-contexts: SUCCESS (37.716s)
[250.308608] [038/133] (774s left) gem_reg_read (bad-register)
[250.361239] Starting subtest: bad-register
[250.362080] Subtest bad-register: SUCCESS (0.000s)
[250.500555] [039/133] (774s left) kms_pm_dc (dc5-psr)
[251.601430] Starting subtest: dc5-psr
[254.492935] Subtest dc5-psr: SUCCESS (2.890s)
[255.379582] [040/133] (769s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[255.507469] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[255.511263] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[255.657724] [041/133] (769s left) gem_ctx_engines (none)
[255.719217] Starting subtest: none
[255.721927] Subtest none: SUCCESS (0.002s)
[255.912323] [042/133] (768s left) kms_addfb_basic (no-handle)
[255.962286] Starting subtest: no-handle
[255.963329] Subtest no-handle: SUCCESS (0.000s)
[256.126663] [043/133] (768s left) i915_drm_fdinfo (busy-idle)
[256.159250] Starting subtest: busy-idle
[256.160240] Starting dynamic subtest: rcs0
[256.196368] Dynamic subtest rcs0: SKIP (0.036s)
[256.205292] Starting dynamic subtest: bcs0
[256.207073] Dynamic subtest bcs0: SKIP (0.000s)
[256.212770] Starting dynamic subtest: vcs0
[256.214478] Dynamic subtest vcs0: SKIP (0.000s)
[256.220301] Starting dynamic subtest: vcs1
[256.221886] Dynamic subtest vcs1: SKIP (0.000s)
[256.227797] Starting dynamic subtest: vecs0
[256.229698] Dynamic subtest vecs0: SKIP (0.000s)
[256.235579] Starting dynamic subtest: ccs0
[256.237374] Dynamic subtest ccs0: SKIP (0.000s)
[256.239096] Subtest busy-idle: SKIP (0.042s)
[256.277616] [044/133] (768s left) perf_pmu (busy-start)
[256.309617] Starting subtest: busy-start
[256.310528] Starting dynamic subtest: rcs0
[258.863128] Dynamic subtest rcs0: SUCCESS (2.553s)
[258.872897] Starting dynamic subtest: bcs0
[261.426850] Dynamic subtest bcs0: SUCCESS (2.548s)
[261.435250] Starting dynamic subtest: vcs0
[264.927286] Dynamic subtest vcs0: SUCCESS (3.501s)
[264.936682] Starting dynamic subtest: vcs1
[268.885318] Dynamic subtest vcs1: SUCCESS (3.965s)
[268.893377] Starting dynamic subtest: vecs0
[272.927024] Dynamic subtest vecs0: SUCCESS (4.029s)
[272.936306] Starting dynamic subtest: ccs0
[275.479214] Dynamic subtest ccs0: SUCCESS (2.547s)
[275.480056] Subtest busy-start: SUCCESS (19.158s)
[275.594749] [045/133] (749s left) kms_selftest (drm_dp_mst_helper)
[275.637058] Starting subtest: drm_dp_mst_helper
[275.663092] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[275.805244] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.135s)
[275.813879] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[275.914025] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.115s)
[275.920667] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[276.013113] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.091s)
[276.014108] Subtest drm_dp_mst_helper: SUCCESS (0.369s)
[276.205694] [046/133] (748s left) gem_exec_schedule (pi-userfault)
[276.400550] Starting subtest: pi-userfault
[276.401720] Starting dynamic subtest: rcs0
[276.407340] Dynamic subtest rcs0: SUCCESS (0.015s)
[276.415883] Starting dynamic subtest: bcs0
[276.419371] Dynamic subtest bcs0: SUCCESS (0.012s)
[276.429034] Starting dynamic subtest: vcs0
[276.433557] Dynamic subtest vcs0: SUCCESS (0.012s)
[276.440313] Starting dynamic subtest: vcs1
[276.446173] Dynamic subtest vcs1: SUCCESS (0.012s)
[276.452949] Starting dynamic subtest: vecs0
[276.455148] Dynamic subtest vecs0: SUCCESS (0.008s)
[276.462419] Starting dynamic subtest: ccs0
[276.466145] Dynamic subtest ccs0: SUCCESS (0.010s)
[276.467152] Subtest pi-userfault: SUCCESS (0.076s)
[276.656263] [047/133] (748s left) gem_userptr_blits (input-checking)
[276.708434] Starting subtest: input-checking
[276.709338] Subtest input-checking: SUCCESS (0.000s)
[276.870766] [048/133] (748s left) kms_psr (fbc-pr-primary-page-flip)
[276.967299] Starting subtest: fbc-pr-primary-page-flip
[276.969072] Subtest fbc-pr-primary-page-flip: SKIP (0.000s)
[277.048446] [049/133] (748s left) kms_psr (fbc-psr-sprite-mmap-cpu)
[277.145153] Starting subtest: fbc-psr-sprite-mmap-cpu
[277.146039] Starting dynamic subtest: eDP-1
[277.147695] Dynamic subtest eDP-1: SKIP (0.000s)
[277.149454] Subtest fbc-psr-sprite-mmap-cpu: SKIP (0.001s)
[277.227223] [050/133] (747s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[277.322819] Starting subtest: y-tiled-8bpp-rotate-270
[277.324496] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[277.429925] [051/133] (747s left) kms_psr (pr-primary-page-flip)
[277.523553] Starting subtest: pr-primary-page-flip
[277.525369] Subtest pr-primary-page-flip: SKIP (0.000s)
[277.605173] [052/133] (747s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[277.695673] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[277.697407] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[277.822510] [053/133] (747s left) syncobj_wait (multi-wait-submitted-signaled)
[277.871157] Starting subtest: multi-wait-submitted-signaled
[277.872055] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[277.986698] [054/133] (747s left) gem_flink_basic (basic)
[278.040580] Starting subtest: basic
[278.041481] Subtest basic: SUCCESS (0.000s)
[278.151417] [055/133] (747s left) kms_invalid_mode (zero-clock)
[278.243287] Starting subtest: zero-clock
[278.244117] Starting dynamic subtest: pipe-A-eDP-1
[278.533139] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[278.541054] Starting dynamic subtest: pipe-B-eDP-1
[278.541889] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[278.548069] Starting dynamic subtest: pipe-C-eDP-1
[278.548872] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[278.555058] Starting dynamic subtest: pipe-D-eDP-1
[278.555860] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[278.556698] Subtest zero-clock: SUCCESS (0.297s)
[279.398739] [056/133] (745s left) sysfs_preempt_timeout (idempotent)
[279.446395] Starting subtest: idempotent
[279.447303] Starting dynamic subtest: vecs0
[279.448161] Dynamic subtest vecs0: SUCCESS (0.000s)
[279.458753] Starting dynamic subtest: ccs0
[279.459660] Dynamic subtest ccs0: SUCCESS (0.000s)
[279.468068] Starting dynamic subtest: vcs0
[279.468846] Dynamic subtest vcs0: SUCCESS (0.000s)
[279.475026] Starting dynamic subtest: bcs0
[279.475833] Dynamic subtest bcs0: SUCCESS (0.000s)
[279.481852] Starting dynamic subtest: rcs0
[279.482793] Dynamic subtest rcs0: SUCCESS (0.000s)
[279.488825] Starting dynamic subtest: vcs1
[279.489692] Dynamic subtest vcs1: SUCCESS (0.000s)
[279.490503] Subtest idempotent: SUCCESS (0.006s)
[279.582833] [057/133] (745s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[279.673194] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[279.702824] Starting dynamic subtest: pipe-A-plane-0
[280.322473] Dynamic subtest pipe-A-plane-0: SUCCESS (0.621s)
[280.329383] Starting dynamic subtest: pipe-A-plane-3
[280.755754] Dynamic subtest pipe-A-plane-3: SUCCESS (0.433s)
[281.879082] Starting dynamic subtest: pipe-B-plane-0
[282.505532] Dynamic subtest pipe-B-plane-0: SUCCESS (0.633s)
[282.513455] Starting dynamic subtest: pipe-B-plane-3
[282.916404] Dynamic subtest pipe-B-plane-3: SUCCESS (0.409s)
[283.224807] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (3.551s)
[284.064465] [058/133] (741s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[284.159063] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[284.161364] Starting dynamic subtest: A-eDP1
[287.786091] Dynamic subtest A-eDP1: SUCCESS (3.624s)
[287.793214] Starting dynamic subtest: B-eDP1
[290.957735] Dynamic subtest B-eDP1: SUCCESS (3.170s)
[290.965439] Starting dynamic subtest: C-eDP1
[294.070020] Dynamic subtest C-eDP1: SUCCESS (3.110s)
[294.077223] Starting dynamic subtest: D-eDP1
[297.175103] Dynamic subtest D-eDP1: SUCCESS (3.103s)
[297.177269] Subtest single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (13.017s)
[297.981837] [059/133] (727s left) gem_basic (bad-close)
[298.034156] Starting subtest: bad-close
[298.036031] Subtest bad-close: SUCCESS (0.000s)
[298.142332] [060/133] (727s left) kms_chamelium_color (ctm-0-75)
[298.228231] Subtest ctm-0-75: SKIP (0.000s)
[298.267629] [061/133] (727s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[298.356352] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[298.357184] Starting dynamic subtest: pipe-A
[298.583926] Dynamic subtest pipe-A: SUCCESS (0.229s)
[298.591163] Starting dynamic subtest: pipe-B
[299.848346] Dynamic subtest pipe-B: SUCCESS (1.263s)
[299.855404] Starting dynamic subtest: pipe-C
[301.191566] Dynamic subtest pipe-C: SUCCESS (1.342s)
[301.202400] Starting dynamic subtest: pipe-D
[302.496601] Dynamic subtest pipe-D: SUCCESS (1.304s)
[302.497543] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.142s)
[303.682837] [062/133] (721s left) kms_psr (psr2-no-drrs)
[303.780333] Starting subtest: psr2-no-drrs
[303.781148] Starting dynamic subtest: eDP-1
[305.347136] Dynamic subtest eDP-1: SUCCESS (1.565s)
[305.347995] Subtest psr2-no-drrs: SUCCESS (1.567s)
[306.145075] [063/133] (719s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[306.207653] Starting subtest: single-wait-for-submit-available-submitted
[306.208849] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[306.331990] [064/133] (719s left) kms_big_fb (x-tiled-addfb)
[306.434196] Starting subtest: x-tiled-addfb
[306.435072] Subtest x-tiled-addfb: SUCCESS (0.000s)
[306.546374] [065/133] (719s left) gem_exec_reloc (basic-range-active)
[306.605983] Subtest basic-range-active: SKIP (0.000s)
[306.682862] [066/133] (718s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[306.776060] Starting subtest: bad-pixel-format-y-tiled-ccs
[306.776886] Starting dynamic subtest: pipe-A-eDP-1
[306.785525] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[306.792624] Starting dynamic subtest: pipe-B-eDP-1
[306.801355] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[306.810160] Starting dynamic subtest: pipe-C-eDP-1
[306.818294] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[306.824864] Starting dynamic subtest: pipe-D-eDP-1
[306.833201] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[306.834828] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.005s)
[309.030393] [067/133] (716s left) syncobj_timeline (reset-during-wait-for-submit)
[309.082223] Starting subtest: reset-during-wait-for-submit
[309.187032] Subtest reset-during-wait-for-submit: SUCCESS (0.104s)
[309.272555] [068/133] (716s left) api_intel_bb (blit-reloc-purge-cache)
[309.321974] Starting subtest: blit-reloc-purge-cache
[309.323709] Subtest blit-reloc-purge-cache: SKIP (0.000s)
[309.463801] [069/133] (716s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[309.580717] Starting subtest: cursorA-vs-flipB-varying-size
[309.582743] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[309.746867] [070/133] (716s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[309.834964] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[309.837039] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[309.914752] [071/133] (715s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[310.008873] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[310.010586] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[310.087957] [072/133] (715s left) dmabuf_sync_file (import-multiple-read-only)
[310.121817] Starting subtest: import-multiple-read-only
[310.124429] Subtest import-multiple-read-only: SUCCESS (0.003s)
[310.187632] [073/133] (715s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[310.293368] Starting subtest: cursorA-vs-flipA-legacy
[321.419390] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.125s)
[322.273591] [074/133] (703s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[322.377696] Starting subtest: 2x-plain-flip-ts-check-interruptible
[322.389247] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.011s)
[322.484520] [075/133] (703s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[322.580406] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[323.581462] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.001s)
[323.733579] [076/133] (702s left) api_intel_bb (object-reloc-purge-cache)
[323.794851] Starting subtest: object-reloc-purge-cache
[323.798283] Subtest object-reloc-purge-cache: SKIP (0.003s)
[323.936448] [077/133] (702s left) syncobj_basic (bad-destroy-pad)
[323.985047] Starting subtest: bad-destroy-pad
[323.985895] Subtest bad-destroy-pad: SUCCESS (0.000s)
[324.097197] [078/133] (701s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[324.149123] Starting subtest: invalid-bsd1-flag-on-vebox
[324.150175] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[324.279765] [079/133] (701s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[324.335828] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[324.442402] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[324.528952] [080/133] (701s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[324.580870] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[324.682513] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.101s)
[324.768260] [081/133] (701s left) i915_power (sanity)
[324.814493] Starting subtest: sanity
[328.880583] Subtest sanity: SUCCESS (4.055s)
[329.033201] [082/133] (697s left) syncobj_basic (bad-flags-fd-to-handle)
[329.088904] Starting subtest: bad-flags-fd-to-handle
[329.089816] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[329.211601] [083/133] (697s left) kms_cursor_crc (cursor-offscreen-64x64)
[329.383185] Starting subtest: cursor-offscreen-64x64
[329.385618] Starting dynamic subtest: pipe-A-eDP-1
[331.407459] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.022s)
[331.436440] Starting dynamic subtest: pipe-D-eDP-1
[333.972518] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.543s)
[333.973404] Subtest cursor-offscreen-64x64: SUCCESS (4.591s)
[334.836478] [084/133] (691s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[334.935475] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[335.727928] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.792s)
[335.941652] [085/133] (690s left) gem_exec_fence (invalid-timeline-fence-array)
[336.530824] Starting subtest: invalid-timeline-fence-array
[336.532225] Subtest invalid-timeline-fence-array: SUCCESS (0.006s)
[338.036719] [086/133] (688s left) gem_exec_balancer (bonded-sync)
[338.103223] Starting subtest: bonded-sync
[338.105334] Subtest bonded-sync: SKIP (0.000s)
[338.436405] [087/133] (687s left) gem_workarounds (suspend-resume-fd)
[338.496771] Starting subtest: suspend-resume-fd
[354.512382] Subtest suspend-resume-fd: SUCCESS (1.724s)
[361.706254] [088/133] (671s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[361.809208] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[369.089251] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.097s)
[369.227023] [089/133] (664s left) kms_hdr (invalid-metadata-sizes)
[369.344839] Starting subtest: invalid-metadata-sizes
[369.347744] Subtest invalid-metadata-sizes: SKIP (0.001s)
[369.431950] [090/133] (664s left) gem_softpin (evict-snoop)
[369.497659] Starting subtest: evict-snoop
[369.499598] Subtest evict-snoop: SKIP (0.000s)
[369.651808] [091/133] (663s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[369.758251] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[369.759815] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[369.873778] [092/133] (663s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[369.967371] Starting subtest: verify-pxp-stale-buf-optout-execution
[371.031614] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.051s)
[371.184839] [093/133] (662s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[371.310514] Starting subtest: cursor-vs-flip-toggle
[402.239085] Subtest cursor-vs-flip-toggle: SUCCESS (30.836s)
[403.046452] [094/133] (630s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[403.143082] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[403.144686] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[403.255878] [095/133] (630s left) gem_userptr_blits (invalid-null-pointer)
[403.318129] Starting subtest: invalid-null-pointer
[403.319020] Subtest invalid-null-pointer: SUCCESS (0.001s)
[403.467340] [096/133] (630s left) kms_cursor_crc (cursor-random-32x10)
[403.624767] Starting subtest: cursor-random-32x10
[403.669500] Subtest cursor-random-32x10: SKIP (0.043s)
[403.961946] [097/133] (629s left) syncobj_wait (single-wait-for-submit-signaled)
[404.034241] Starting subtest: single-wait-for-submit-signaled
[404.035221] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[404.157705] [098/133] (629s left) gem_exec_whisper (basic-fds-priority-all)
[404.236474] Starting subtest: basic-fds-priority-all
[426.329814] Subtest basic-fds-priority-all: SUCCESS (22.072s)
[426.522783] [099/133] (607s left) gem_exec_flush (basic-uc-set-default)
[426.576835] Starting subtest: basic-uc-set-default
[432.040833] Subtest basic-uc-set-default: SUCCESS (5.460s)
[432.268621] [100/133] (601s left) gem_eio (in-flight-contexts-immediate)
[432.510770] Starting subtest: in-flight-contexts-immediate
[446.196045] Subtest in-flight-contexts-immediate: SUCCESS (13.679s)
[446.381635] [101/133] (587s left) kms_vrr (flip-basic-fastset)
[446.481076] Starting subtest: flip-basic-fastset
[446.483975] Subtest flip-basic-fastset: SKIP (0.000s)
[446.558983] [102/133] (587s left) kms_addfb_basic (small-bo)
[446.647920] Starting subtest: small-bo
[446.648834] Subtest small-bo: SUCCESS (0.000s)
[446.793759] [103/133] (587s left) kms_content_protection (atomic-dpms-hdcp14)
[446.941947] Starting subtest: atomic-dpms-hdcp14
[446.943674] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[447.086682] [104/133] (586s left) gem_exec_suspend (basic-s3)
[447.155055] Starting subtest: basic-S3
[447.155989] Starting dynamic subtest: smem
[468.062049] Dynamic subtest smem: SUCCESS (7.027s)
[468.063213] Subtest basic-S3: SUCCESS (7.029s)
[474.309915] [105/133] (565s left) syncobj_timeline (invalid-signal-bad-pad)
[474.365966] Starting subtest: invalid-signal-bad-pad
[474.366958] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[474.496426] [106/133] (565s left) gem_lmem_swapping (verify-random)
[474.531794] Subtest verify-random: SKIP (0.000s)
[474.573670] [107/133] (565s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[474.674683] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[474.676613] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[474.798896] [108/133] (565s left) gem_mmap_offset (perf)
[474.859503] Starting subtest: perf
[474.860434] Starting dynamic subtest: smem0
[475.160280] Dynamic subtest smem0: SUCCESS (0.289s)
[475.161383] Subtest perf: SUCCESS (0.290s)
[475.309454] [109/133] (564s left) kms_prop_blob (basic)
[475.356575] Starting subtest: basic
[475.357505] Subtest basic: SUCCESS (0.000s)
[475.498764] [110/133] (564s left) kms_cursor_edge_walk (128x128-top-bottom)
[475.589634] Starting subtest: 128x128-top-bottom
[475.590543] Starting dynamic subtest: pipe-A-eDP-1
[479.084285] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.409s)
[479.093609] Starting dynamic subtest: pipe-D-eDP-1
[482.266740] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.130s)
[482.267672] Subtest 128x128-top-bottom: SUCCESS (6.541s)
[483.094830] [111/133] (557s left) kms_psr2_su (frontbuffer-xrgb8888)
[483.151876] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[483.218494] [112/133] (557s left) gem_mmap_gtt (basic-write-cpu-read-gtt)
[483.283079] Subtest basic-write-cpu-read-gtt: SKIP (0.000s)
[483.351369] [113/133] (557s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[483.469108] Starting subtest: cursorA-vs-flipA-atomic
[494.663525] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.125s)
[495.507453] [114/133] (544s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[495.571727] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[495.636536] [115/133] (544s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[495.720550] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[495.721456] Starting dynamic subtest: pipe-A-eDP-1
[511.695695] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.815s)
[511.710810] Starting dynamic subtest: pipe-B-eDP-1
[529.671644] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.801s)
[529.682705] Starting dynamic subtest: pipe-C-eDP-1
[547.666827] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.730s)
[547.675940] Starting dynamic subtest: pipe-D-eDP-1
[565.657183] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.728s)
[565.658148] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (10.080s)
[571.864037] [116/133] (473s left) perf (gen12-mi-rpc)
[571.994507] Starting subtest: gen12-mi-rpc
[571.995620] Starting dynamic subtest: rcs0
[572.020883] Dynamic subtest rcs0: SUCCESS (0.025s)
[572.022274] Subtest gen12-mi-rpc: SUCCESS (0.026s)
[572.062937] [117/133] (473s left) gem_busy (parallel)
[572.271608] Starting subtest: parallel
[572.273036] Starting dynamic subtest: rcs0
[573.904542] Dynamic subtest rcs0: SUCCESS (1.581s)
[573.918783] Starting dynamic subtest: bcs0
[575.898463] Dynamic subtest bcs0: SUCCESS (1.930s)
[575.910593] Starting dynamic subtest: vcs0
[577.884362] Dynamic subtest vcs0: SUCCESS (1.942s)
[577.895231] Starting dynamic subtest: vcs1
[579.929625] Dynamic subtest vcs1: SUCCESS (2.011s)
[579.940357] Starting dynamic subtest: vecs0
[581.914241] Dynamic subtest vecs0: SUCCESS (1.958s)
[581.924855] Starting dynamic subtest: ccs0
[583.898974] Dynamic subtest ccs0: SUCCESS (1.963s)
[583.900600] Subtest parallel: SUCCESS (11.402s)
[584.111433] [118/133] (461s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[584.176955] Starting subtest: y-tiled-to-vebox-y-tiled
[584.180021] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[584.372741] [119/133] (461s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render)
[584.463606] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render
[591.232458] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.734s)
[591.363950] [120/133] (454s left) kms_async_flips (invalid-async-flip-atomic)
[591.460634] Starting subtest: invalid-async-flip-atomic
[591.834365] Starting dynamic subtest: pipe-A-eDP-1
[592.653863] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.817s)
[593.014684] Starting dynamic subtest: pipe-B-eDP-1
[593.787329] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.778s)
[594.142913] Starting dynamic subtest: pipe-C-eDP-1
[594.909950] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.773s)
[595.262760] Starting dynamic subtest: pipe-D-eDP-1
[596.042320] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.785s)
[596.043558] Subtest invalid-async-flip-atomic: SUCCESS (4.571s)
[597.200978] [121/133] (448s left) gem_render_copy_redux (normal)
[597.260206] Starting subtest: normal
[597.519361] Subtest normal: SUCCESS (0.259s)
[597.655655] [122/133] (448s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[597.758849] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[597.760627] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[597.852747] [123/133] (447s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[597.943411] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[597.944464] Starting dynamic subtest: pipe-A-eDP-1
[597.954005] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[597.962515] Starting dynamic subtest: pipe-B-eDP-1
[597.971897] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[597.980559] Starting dynamic subtest: pipe-C-eDP-1
[597.989989] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[597.998823] Starting dynamic subtest: pipe-D-eDP-1
[598.010006] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[598.011925] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.006s)
[600.187104] [124/133] (445s left) gem_exec_params (larger-than-life-batch)
[600.246596] Starting subtest: larger-than-life-batch
[602.708562] Subtest larger-than-life-batch: SUCCESS (2.461s)
[603.991006] [125/133] (441s left) kms_chamelium_hpd (hdmi-hpd)
[604.440555] Subtest hdmi-hpd: SKIP (0.000s)
[605.207483] [126/133] (440s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[605.307253] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[605.308444] Starting dynamic subtest: pipe-A-eDP-1
[605.555181] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.237s)
[605.563996] Starting dynamic subtest: pipe-B-eDP-1
[606.788941] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.232s)
[606.799257] Starting dynamic subtest: pipe-C-eDP-1
[607.990776] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.198s)
[608.000011] Starting dynamic subtest: pipe-D-eDP-1
[609.220940] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.230s)
[609.221854] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.901s)
[610.374471] [127/133] (435s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[610.471602] Starting subtest: disable-crc-after-crtc
[610.472938] Starting dynamic subtest: pipe-A-eDP-1
[612.110034] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.637s)
[612.119896] Starting dynamic subtest: pipe-B-eDP-1
[614.472374] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.361s)
[614.483627] Starting dynamic subtest: pipe-C-eDP-1
[616.716774] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.241s)
[616.726341] Starting dynamic subtest: pipe-D-eDP-1
[618.979157] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.261s)
[618.980087] Subtest disable-crc-after-crtc: SUCCESS (8.506s)
[619.823761] [128/133] (426s left) sw_sync (sync_multi_producer_single_consumer)
[619.875077] Starting subtest: sync_multi_producer_single_consumer
[620.960987] Subtest sync_multi_producer_single_consumer: SUCCESS (1.075s)
[621.022147] [129/133] (425s left) kms_psr (psr-primary-mmap-gtt)
[621.103370] Starting subtest: psr-primary-mmap-gtt
[621.104560] Starting dynamic subtest: eDP-1
[622.523857] Dynamic subtest eDP-1: SKIP (1.408s)
[622.526384] Subtest psr-primary-mmap-gtt: SKIP (1.410s)
[622.703253] [130/133] (423s left) gem_exec_capture (capture-recoverable)
[622.756867] Starting subtest: capture-recoverable
[622.760572] Subtest capture-recoverable: SUCCESS (0.005s)
[622.904132] [131/133] (423s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[623.022044] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[623.023615] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[623.138586] [132/133] (422s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[623.249173] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[627.522265] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.272s)
[628.663892] [133/133] (417s left) i915_module_load (reload-no-display)
[628.701157] Starting subtest: reload-no-display
[634.961856] Subtest reload-no-display: SUCCESS (6.249s)
[634.986895] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[634.987621] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[634.987774] 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.