[56.905889] Initializing watchdogs
[56.905933]   /dev/watchdog0
[56.918628] [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.922649] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.924701] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.927882] [001/131] (960s left) gem_exec_balancer (semaphore)
[57.052926] Starting subtest: semaphore
[58.941725] Subtest semaphore: SUCCESS (1.887s)
[59.254157] [002/131] (958s left) syncobj_timeline (wait-all-complex)
[59.306089] Starting subtest: wait-all-complex
[59.519046] Subtest wait-all-complex: SUCCESS (0.213s)
[59.584338] [003/131] (957s left) gem_softpin (evict-snoop)
[59.641556] Starting subtest: evict-snoop
[59.645762] Subtest evict-snoop: SKIP (0.000s)
[59.767221] [004/131] (957s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[59.878982] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[59.881312] Starting dynamic subtest: pipe-A-default-mode
[60.167652] Dynamic subtest pipe-A-default-mode: SKIP (0.283s)
[60.172770] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.284s)
[60.964977] [005/131] (956s left) kms_invalid_mode (zero-clock)
[61.059384] Starting subtest: zero-clock
[61.061830] Starting dynamic subtest: pipe-A-eDP-1
[61.353194] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.293s)
[61.359720] Starting dynamic subtest: pipe-B-eDP-1
[61.361696] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[61.367618] Starting dynamic subtest: pipe-C-eDP-1
[61.369596] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[61.375912] Starting dynamic subtest: pipe-D-eDP-1
[61.378266] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[61.380619] Subtest zero-clock: SUCCESS (0.297s)
[62.198350] [006/131] (955s left) kms_prop_blob (blob-prop-validate)
[62.248333] Starting subtest: blob-prop-validate
[62.250815] Subtest blob-prop-validate: SUCCESS (0.000s)
[62.354394] [007/131] (955s left) kms_draw_crc (draw-method-render)
[62.460826] Starting subtest: draw-method-render
[62.463121] Starting dynamic subtest: xrgb8888-untiled
[62.689790] Dynamic subtest xrgb8888-untiled: SUCCESS (0.231s)
[62.696773] Starting dynamic subtest: rgb565-untiled
[62.910100] Dynamic subtest rgb565-untiled: SUCCESS (0.220s)
[62.916525] Starting dynamic subtest: xrgb2101010-untiled
[63.109811] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.200s)
[63.116834] Starting dynamic subtest: xbgr16161616f-untiled
[63.365439] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.255s)
[63.371429] Starting dynamic subtest: xrgb8888-xtiled
[63.487921] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[63.495536] Starting dynamic subtest: rgb565-xtiled
[63.621064] Dynamic subtest rgb565-xtiled: SUCCESS (0.133s)
[63.629172] Starting dynamic subtest: xrgb2101010-xtiled
[63.743157] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[63.749339] Starting dynamic subtest: xbgr16161616f-xtiled
[63.876497] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[63.883100] Starting dynamic subtest: xrgb8888-4tiled
[63.998973] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[64.006079] Starting dynamic subtest: rgb565-4tiled
[64.110152] Dynamic subtest rgb565-4tiled: SUCCESS (0.111s)
[64.116973] Starting dynamic subtest: xrgb2101010-4tiled
[64.220765] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.111s)
[64.228356] Starting dynamic subtest: xbgr16161616f-4tiled
[64.376423] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.155s)
[64.378443] Subtest draw-method-render: SUCCESS (1.918s)
[64.474445] [008/131] (953s left) gem_exec_parallel (contexts)
[64.533566] Starting subtest: contexts
[64.535639] Starting dynamic subtest: rcs0
[66.926923] Dynamic subtest rcs0: SUCCESS (2.391s)
[66.936078] Starting dynamic subtest: bcs0
[69.959453] Dynamic subtest bcs0: SUCCESS (3.033s)
[69.968453] Starting dynamic subtest: vcs0
[74.245323] Dynamic subtest vcs0: SUCCESS (4.285s)
[74.254913] Starting dynamic subtest: vcs1
[78.301658] Dynamic subtest vcs1: SUCCESS (4.055s)
[78.313850] Starting dynamic subtest: vecs0
[82.333555] Dynamic subtest vecs0: SUCCESS (4.031s)
[82.343319] Starting dynamic subtest: ccs0
[85.569708] Dynamic subtest ccs0: SUCCESS (3.236s)
[85.571899] Subtest contexts: SUCCESS (21.034s)
[85.748421] [009/131] (931s left) perf_pmu (busy-accuracy-98)
[85.785403] Starting subtest: busy-accuracy-98
[85.788114] Starting dynamic subtest: rcs0
[88.837550] Dynamic subtest rcs0: SUCCESS (3.051s)
[88.844520] Starting dynamic subtest: bcs0
[91.884926] Dynamic subtest bcs0: SUCCESS (3.047s)
[91.892381] Starting dynamic subtest: vcs0
[94.926726] Dynamic subtest vcs0: SUCCESS (3.041s)
[94.933856] Starting dynamic subtest: vcs1
[97.967066] Dynamic subtest vcs1: SUCCESS (3.040s)
[97.977232] Starting dynamic subtest: vecs0
[101.006266] Dynamic subtest vecs0: SUCCESS (3.039s)
[101.015703] Starting dynamic subtest: ccs0
[104.054275] Dynamic subtest ccs0: SUCCESS (3.048s)
[104.056781] Subtest busy-accuracy-98: SUCCESS (18.267s)
[104.158821] [010/131] (913s left) gem_pxp (display-protected-crc)
[104.274761] Starting subtest: display-protected-crc
[105.953845] Subtest display-protected-crc: SUCCESS (1.681s)
[106.076114] [011/131] (911s left) sw_sync (sync_busy)
[106.116029] Starting subtest: sync_busy
[106.118751] Subtest sync_busy: SUCCESS (0.002s)
[106.157582] [012/131] (911s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[106.242078] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[106.244535] Starting dynamic subtest: pipe-A-eDP-1
[106.254039] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[106.262896] Starting dynamic subtest: pipe-B-eDP-1
[106.271979] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[106.279071] Starting dynamic subtest: pipe-C-eDP-1
[106.287193] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[106.293726] Starting dynamic subtest: pipe-D-eDP-1
[106.301767] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[106.305671] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.002s)
[108.348328] [013/131] (909s left) kms_vblank (query-forked-busy)
[108.436913] Starting subtest: query-forked-busy
[108.439322] Starting dynamic subtest: pipe-A-eDP-1
[110.336701] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.896s)
[110.348649] Starting dynamic subtest: pipe-D-eDP-1
[112.928197] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.591s)
[112.930607] Subtest query-forked-busy: SUCCESS (4.490s)
[113.713179] [014/131] (904s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[113.814776] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[113.817679] Starting dynamic subtest: pipe-A-default-mode
[114.108196] Dynamic subtest pipe-A-default-mode: SKIP (0.283s)
[114.114780] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.284s)
[114.933620] [015/131] (902s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[115.033395] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[115.037954] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[115.140747] [016/131] (902s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff)
[115.239387] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff
[115.243441] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[115.327692] [017/131] (902s left) gem_exec_fence (syncobj-invalid-flags)
[115.804145] Starting subtest: syncobj-invalid-flags
[115.806763] Subtest syncobj-invalid-flags: SUCCESS (0.000s)
[116.996848] [FACT gem_exec_fence (syncobj-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[116.999875] [018/131] (900s left) gem_exec_whisper (basic-fds-priority)
[117.073470] Starting subtest: basic-fds-priority
[141.176928] Subtest basic-fds-priority: SUCCESS (24.099s)
[141.354507] [019/131] (876s left) debugfs_test (read_all_entries_display_on)
[141.450859] Starting subtest: read_all_entries_display_on
[141.793983] Subtest read_all_entries_display_on: SUCCESS (0.341s)
[141.919310] [020/131] (876s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[146.870317] Starting subtest: pr-plane-move-sf-dmg-area
[146.875513] Subtest pr-plane-move-sf-dmg-area: SKIP (0.002s)
[147.731903] [021/131] (870s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[147.847235] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[147.851601] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[147.961656] [022/131] (870s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[148.056934] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[148.059345] Starting dynamic subtest: pipe-A-eDP-1
[148.084541] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[148.093438] Starting dynamic subtest: pipe-B-eDP-1
[148.117433] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[148.125386] Starting dynamic subtest: pipe-C-eDP-1
[148.149595] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[148.156653] Starting dynamic subtest: pipe-D-eDP-1
[148.181796] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[148.186246] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.002s)
[150.182168] [023/131] (867s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[150.292209] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[150.296546] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[150.401536] [024/131] (867s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[150.526077] Starting subtest: cursorB-vs-flipA-toggle
[150.530536] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[150.623231] [025/131] (867s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[150.736783] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu
[155.206841] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (4.473s)
[155.334096] [026/131] (862s left) kms_color (deep-color)
[155.426701] Starting subtest: deep-color
[156.501233] Starting dynamic subtest: pipe-A-eDP-1-gamma
[157.195664] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.692s)
[157.205909] Starting dynamic subtest: pipe-A-eDP-1-degamma
[158.548639] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.355s)
[158.557888] Starting dynamic subtest: pipe-A-eDP-1-ctm
[159.993570] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.444s)
[160.781238] Starting dynamic subtest: pipe-B-eDP-1-gamma
[161.492850] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.715s)
[161.501676] Starting dynamic subtest: pipe-B-eDP-1-degamma
[162.940907] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.448s)
[162.950835] Starting dynamic subtest: pipe-B-eDP-1-ctm
[164.352387] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.413s)
[165.161255] Starting dynamic subtest: pipe-C-eDP-1-gamma
[165.857938] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.701s)
[165.867630] Starting dynamic subtest: pipe-C-eDP-1-degamma
[167.192831] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.333s)
[167.202313] Starting dynamic subtest: pipe-C-eDP-1-ctm
[168.507430] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.316s)
[169.255114] Starting dynamic subtest: pipe-D-eDP-1-gamma
[169.904762] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.660s)
[169.914373] Starting dynamic subtest: pipe-D-eDP-1-degamma
[171.284197] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.378s)
[171.292813] Starting dynamic subtest: pipe-D-eDP-1-ctm
[172.688946] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.404s)
[172.691321] Subtest deep-color: SUCCESS (17.260s)
[173.475029] [027/131] (844s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[173.579783] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[178.133095] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (4.557s)
[178.253535] [028/131] (840s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff)
[178.362494] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff
[181.232064] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (2.873s)
[181.373188] [029/131] (836s left) i915_getparams_basic (basic-eu-total)
[181.429382] Starting subtest: basic-eu-total
[181.433224] Subtest basic-eu-total: SUCCESS (0.000s)
[181.508163] [030/131] (836s left) gem_mmap_gtt (basic-read-write)
[181.570528] Subtest basic-read-write: SKIP (0.000s)
[181.633699] [031/131] (836s left) api_intel_allocator (default-alignment)
[181.692550] Starting subtest: default-alignment
[181.697238] Subtest default-alignment: SUCCESS (0.004s)
[181.815290] [032/131] (836s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[181.942410] Starting subtest: basic-flip-before-cursor-legacy
[182.862984] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.917s)
[183.667622] [033/131] (834s left) gem_mmap_gtt (big-bo)
[183.715875] Subtest big-bo: SKIP (0.000s)
[183.778594] [034/131] (834s left) gem_exec_reloc (basic-cpu)
[183.833691] Subtest basic-cpu: SKIP (0.000s)
[183.896662] [035/131] (834s left) gem_render_copy (yf-tiled)
[183.962607] Starting subtest: yf-tiled
[183.967240] Subtest yf-tiled: SKIP (0.000s)
[184.136874] [036/131] (834s left) gem_exec_reloc (basic-write-cpu-active)
[184.198371] Subtest basic-write-cpu-active: SKIP (0.000s)
[184.264733] [037/131] (834s left) gem_softpin (reverse)
[184.325725] Starting subtest: reverse
[184.335105] Subtest reverse: SUCCESS (0.009s)
[184.455784] [038/131] (834s left) kms_cursor_crc (cursor-random-32x32)
[184.589638] Starting subtest: cursor-random-32x32
[184.628281] Subtest cursor-random-32x32: SKIP (0.032s)
[184.914158] [039/131] (833s left) gem_exec_reloc (basic-gtt-wc-noreloc)
[184.981874] Subtest basic-gtt-wc-noreloc: SKIP (0.000s)
[185.042987] [040/131] (833s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[185.146309] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[185.149993] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[185.252041] [041/131] (833s left) gem_lmem_swapping (parallel-random-verify-ccs)
[185.290559] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[185.324388] [042/131] (833s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[185.427029] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[190.566493] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (5.142s)
[190.680774] [043/131] (828s left) gem_ctx_param (invalid-set-no-zeromap)
[190.747481] Starting subtest: invalid-set-no-zeromap
[190.749802] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[190.821210] [044/131] (828s left) gem_workarounds (reset-context)
[190.882923] Starting subtest: reset-context
[191.063466] Subtest reset-context: SUCCESS (0.181s)
[191.207219] [045/131] (827s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[191.308873] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[191.312975] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[191.418077] [046/131] (827s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[191.469513] Starting subtest: multi-wait-for-submit-unsubmitted
[191.573501] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.104s)
[191.643791] [047/131] (827s left) gem_ctx_shared (q-smoketest)
[191.717761] Starting subtest: Q-smoketest
[191.720303] Starting dynamic subtest: rcs0
[197.287362] Dynamic subtest rcs0: SUCCESS (5.523s)
[197.295946] Starting dynamic subtest: bcs0
[202.821129] Dynamic subtest bcs0: SUCCESS (5.528s)
[202.830324] Starting dynamic subtest: vcs0
[208.317983] Dynamic subtest vcs0: SUCCESS (5.502s)
[208.327685] Starting dynamic subtest: vcs1
[213.837516] Dynamic subtest vcs1: SUCCESS (5.513s)
[213.855081] Starting dynamic subtest: vecs0
[219.350105] Dynamic subtest vecs0: SUCCESS (5.516s)
[219.362409] Starting dynamic subtest: ccs0
[224.873076] Dynamic subtest ccs0: SUCCESS (5.516s)
[224.875177] Subtest Q-smoketest: SUCCESS (33.103s)
[225.041952] [048/131] (793s left) kms_flip (flip-vs-suspend)
[225.142850] Starting subtest: flip-vs-suspend
[225.145342] Starting dynamic subtest: A-eDP1
[273.912885] Dynamic subtest A-eDP1: SUCCESS (4.475s)
[273.922154] Starting dynamic subtest: B-eDP1
[322.926459] Dynamic subtest B-eDP1: SUCCESS (4.249s)
[322.935812] Starting dynamic subtest: C-eDP1
[371.902808] Dynamic subtest C-eDP1: SUCCESS (4.254s)
[371.913830] Starting dynamic subtest: D-eDP1
[420.898481] Dynamic subtest D-eDP1: SUCCESS (4.310s)
[420.920879] Subtest flip-vs-suspend: SUCCESS (17.297s)
[422.141715] [049/131] (775s left) kms_big_fb (linear-16bpp-rotate-90)
[422.253137] Starting subtest: linear-16bpp-rotate-90
[422.272235] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[422.389962] [050/131] (775s left) kms_cursor_crc (cursor-onscreen-32x32)
[422.523264] Starting subtest: cursor-onscreen-32x32
[422.556995] Subtest cursor-onscreen-32x32: SKIP (0.032s)
[422.809954] [051/131] (774s left) kms_plane (plane-position-covered)
[422.910168] Starting subtest: plane-position-covered
[423.415998] Starting dynamic subtest: pipe-A-plane-1
[424.701776] Dynamic subtest pipe-A-plane-1: SUCCESS (1.286s)
[424.710024] Starting dynamic subtest: pipe-A-plane-2
[425.923778] Dynamic subtest pipe-A-plane-2: SUCCESS (1.221s)
[425.933789] Starting dynamic subtest: pipe-A-plane-3
[427.166221] Dynamic subtest pipe-A-plane-3: SUCCESS (1.240s)
[427.176040] Starting dynamic subtest: pipe-A-plane-4
[428.388837] Dynamic subtest pipe-A-plane-4: SUCCESS (1.224s)
[428.399759] Starting dynamic subtest: pipe-A-plane-5
[428.427011] Dynamic subtest pipe-A-plane-5: SUCCESS (0.036s)
[429.524893] Starting dynamic subtest: pipe-B-plane-1
[430.727335] Dynamic subtest pipe-B-plane-1: SUCCESS (1.210s)
[430.738357] Starting dynamic subtest: pipe-B-plane-2
[431.957673] Dynamic subtest pipe-B-plane-2: SUCCESS (1.229s)
[431.969687] Starting dynamic subtest: pipe-B-plane-3
[433.184518] Dynamic subtest pipe-B-plane-3: SUCCESS (1.226s)
[433.194328] Starting dynamic subtest: pipe-B-plane-4
[434.401966] Dynamic subtest pipe-B-plane-4: SUCCESS (1.217s)
[434.412683] Starting dynamic subtest: pipe-B-plane-5
[434.438958] Dynamic subtest pipe-B-plane-5: SUCCESS (0.035s)
[434.440867] Subtest plane-position-covered: SUCCESS (11.520s)
[435.203886] [052/131] (762s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[435.303204] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[435.305569] Starting dynamic subtest: pipe-A-eDP-1
[435.501724] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.186s)
[435.511941] Starting dynamic subtest: pipe-B-eDP-1
[435.533260] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[435.542863] Starting dynamic subtest: pipe-C-eDP-1
[435.562210] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s)
[435.571755] Starting dynamic subtest: pipe-D-eDP-1
[435.590739] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.009s)
[435.592675] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.218s)
[437.752162] [053/131] (759s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[437.854384] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[439.068137] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (1.215s)
[439.285674] [054/131] (758s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[439.388463] Starting subtest: fbc-tiling-linear
[442.066428] Subtest fbc-tiling-linear: SUCCESS (2.681s)
[442.210559] [055/131] (755s left) drm_fdinfo (busy-idle)
[442.249025] Starting subtest: busy-idle
[442.250719] Starting dynamic subtest: rcs0
[442.284068] Dynamic subtest rcs0: SKIP (0.035s)
[442.294377] Starting dynamic subtest: bcs0
[442.297691] Dynamic subtest bcs0: SKIP (0.000s)
[442.305990] Starting dynamic subtest: vcs0
[442.309673] Dynamic subtest vcs0: SKIP (0.000s)
[442.317576] Starting dynamic subtest: vcs1
[442.321013] Dynamic subtest vcs1: SKIP (0.000s)
[442.328553] Starting dynamic subtest: vecs0
[442.331981] Dynamic subtest vecs0: SKIP (0.000s)
[442.339480] Starting dynamic subtest: ccs0
[442.343030] Dynamic subtest ccs0: SKIP (0.000s)
[442.346499] Subtest busy-idle: SKIP (0.039s)
[442.376558] [056/131] (755s left) sriov_basic (enable-vfs-bind-unbind-each)
[442.429412] Starting subtest: enable-vfs-bind-unbind-each
[442.431578] Starting dynamic subtest: numvfs-1
[442.492874] Dynamic subtest numvfs-1: FAIL (0.012s)
[442.501212] Starting dynamic subtest: numvfs-2
[442.558584] Dynamic subtest numvfs-2: FAIL (0.003s)
[442.568146] Starting dynamic subtest: numvfs-3
[442.627842] Dynamic subtest numvfs-3: FAIL (0.003s)
[442.636772] Starting dynamic subtest: numvfs-4
[442.696763] Dynamic subtest numvfs-4: FAIL (0.003s)
[442.705946] Starting dynamic subtest: numvfs-5
[442.765235] Dynamic subtest numvfs-5: FAIL (0.003s)
[442.774359] Starting dynamic subtest: numvfs-6
[442.834043] Dynamic subtest numvfs-6: FAIL (0.003s)
[442.842996] Starting dynamic subtest: numvfs-7
[442.903121] Dynamic subtest numvfs-7: FAIL (0.003s)
[442.912827] Starting dynamic subtest: numvfs-random
[442.972333] Dynamic subtest numvfs-random: FAIL (0.003s)
[442.974228] Subtest enable-vfs-bind-unbind-each: FAIL (0.039s)
[443.004741] [057/131] (754s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[443.106373] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[443.109982] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[443.203134] [058/131] (754s left) intel_kernelfs (intel-sysfs-read-all-entries)
[443.263479] Starting subtest: intel-sysfs-read-all-entries
[443.401572] Subtest intel-sysfs-read-all-entries: SUCCESS (0.136s)
[443.481205] [059/131] (754s left) gem_exec_schedule (in-order)
[443.551668] Starting subtest: in-order
[443.553630] Starting dynamic subtest: rcs0
[443.652029] Dynamic subtest rcs0: SUCCESS (0.102s)
[443.661865] Starting dynamic subtest: vcs0
[443.731427] Dynamic subtest vcs0: SUCCESS (0.078s)
[443.743673] Starting dynamic subtest: vcs1
[443.819336] Dynamic subtest vcs1: SUCCESS (0.087s)
[443.829885] Starting dynamic subtest: vecs0
[443.903618] Dynamic subtest vecs0: SUCCESS (0.083s)
[443.915684] Starting dynamic subtest: ccs0
[443.988287] Dynamic subtest ccs0: SUCCESS (0.083s)
[443.990443] Subtest in-order: SUCCESS (0.439s)
[445.452510] [060/131] (752s left) gem_userptr_blits (forbidden-operations)
[445.522808] Starting subtest: forbidden-operations
[445.527173] Subtest forbidden-operations: SKIP (0.000s)
[445.659437] [061/131] (752s left) kms_vrr (flip-basic-fastset)
[445.752951] Starting subtest: flip-basic-fastset
[445.759264] Subtest flip-basic-fastset: SKIP (0.000s)
[445.826491] [062/131] (752s left) vgem_basic (dmabuf-mmap)
[445.869126] Starting subtest: dmabuf-mmap
[445.910274] Subtest dmabuf-mmap: SUCCESS (0.044s)
[445.965522] [063/131] (751s left) kms_psr (fbc-psr-cursor-render)
[446.047341] Starting subtest: fbc-psr-cursor-render
[446.049512] Starting dynamic subtest: eDP-1
[446.053477] Dynamic subtest eDP-1: SKIP (0.000s)
[446.057345] Subtest fbc-psr-cursor-render: SKIP (0.001s)
[446.113109] [064/131] (751s left) gem_exec_schedule (u-fairslice-all)
[446.183677] Starting subtest: u-fairslice-all
[448.233775] Subtest u-fairslice-all: SUCCESS (2.056s)
[449.431757] [065/131] (748s left) api_intel_allocator (simple-allocator)
[449.501002] Starting subtest: simple-allocator
[449.503689] Starting dynamic subtest: basic
[449.509428] Dynamic subtest basic: SUCCESS (0.007s)
[449.520910] Starting dynamic subtest: parallel-one
[449.682608] Dynamic subtest parallel-one: SUCCESS (0.173s)
[449.694810] Starting dynamic subtest: print
[449.696843] Dynamic subtest print: SUCCESS (0.000s)
[449.705875] Starting dynamic subtest: reuse
[449.708039] Dynamic subtest reuse: SUCCESS (0.000s)
[449.716778] Starting dynamic subtest: reserve
[449.718907] Dynamic subtest reserve: SUCCESS (0.000s)
[449.727874] Starting dynamic subtest: fork-reopen-allocator
[449.730176] Dynamic subtest fork-reopen-allocator: SUCCESS (0.015s)
[449.732385] Subtest simple-allocator: SUCCESS (0.201s)
[449.836054] [066/131] (748s left) kms_cursor_edge_walk (256x256-right-edge)
[449.942054] Starting subtest: 256x256-right-edge
[449.944240] Starting dynamic subtest: pipe-A-eDP-1
[453.344871] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.398s)
[453.356397] Starting dynamic subtest: pipe-D-eDP-1
[456.447668] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.102s)
[456.450212] Subtest 256x256-right-edge: SUCCESS (6.503s)
[457.272445] [067/131] (740s left) kms_color (legacy-gamma)
[457.373198] Starting subtest: legacy-gamma
[457.375522] Starting dynamic subtest: pipe-A-eDP-1
[458.074498] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.697s)
[458.084280] Starting dynamic subtest: pipe-B-eDP-1
[459.444388] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.371s)
[459.454574] Starting dynamic subtest: pipe-C-eDP-1
[460.734989] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.287s)
[460.744222] Starting dynamic subtest: pipe-D-eDP-1
[462.082640] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.348s)
[462.084960] Subtest legacy-gamma: SUCCESS (4.707s)
[462.880788] [068/131] (735s left) i915_pm_freq_api (freq-reset-multiple)
[462.949928] Starting subtest: freq-reset-multiple
[462.952252] Starting dynamic subtest: gt0
[472.490748] Dynamic subtest gt0: SUCCESS (9.537s)
[472.501351] Starting dynamic subtest: gt1
[480.808588] Dynamic subtest gt1: SUCCESS (8.311s)
[480.811216] Subtest freq-reset-multiple: SUCCESS (17.850s)
[480.877368] [069/131] (717s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[480.994042] Starting subtest: 4-tiled-8bpp-rotate-180
[482.107434] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.112s)
[482.259992] [070/131] (715s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[482.372163] Starting subtest: yf-tiled-32bpp-rotate-90
[482.376597] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[482.477788] [071/131] (715s left) kms_flip (plain-flip-interruptible)
[482.569630] Starting subtest: plain-flip-interruptible
[482.573820] Starting dynamic subtest: A-eDP1
[484.282422] Dynamic subtest A-eDP1: SUCCESS (1.709s)
[484.293409] Starting dynamic subtest: B-eDP1
[485.912585] Dynamic subtest B-eDP1: SUCCESS (1.629s)
[485.923953] Starting dynamic subtest: C-eDP1
[487.527336] Dynamic subtest C-eDP1: SUCCESS (1.612s)
[487.543184] Starting dynamic subtest: D-eDP1
[489.148952] Dynamic subtest D-eDP1: SUCCESS (1.620s)
[489.153809] Subtest plain-flip-interruptible: SUCCESS (6.580s)
[490.316763] [072/131] (707s left) kms_rotation_crc (sprite-rotation-270)
[490.415251] Starting subtest: sprite-rotation-270
[490.419829] Subtest sprite-rotation-270: SKIP (0.001s)
[490.483831] [073/131] (707s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[490.540604] Subtest tiled-to-untiled: SKIP (0.000s)
[490.608481] [074/131] (707s left) syncobj_timeline (multi-wait-signaled)
[490.657661] Starting subtest: multi-wait-signaled
[490.659577] Subtest multi-wait-signaled: SUCCESS (0.001s)
[490.764667] [075/131] (707s left) kms_properties (crtc-properties-legacy)
[490.858178] Starting subtest: crtc-properties-legacy
[490.860509] Starting dynamic subtest: pipe-A-eDP-1
[491.309545] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.448s)
[491.319898] Starting dynamic subtest: pipe-B-eDP-1
[492.476618] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.168s)
[492.486803] Starting dynamic subtest: pipe-C-eDP-1
[493.592172] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.113s)
[493.602282] Starting dynamic subtest: pipe-D-eDP-1
[494.712286] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.119s)
[494.714832] Subtest crtc-properties-legacy: SUCCESS (3.851s)
[495.515714] [076/131] (702s left) perf (buffer-fill)
[495.621379] Starting subtest: buffer-fill
[495.623608] Starting dynamic subtest: 0-rcs0
[497.881839] Dynamic subtest 0-rcs0: SUCCESS (2.260s)
[497.891258] Starting dynamic subtest: 1-vcs1
[502.299658] Dynamic subtest 1-vcs1: SUCCESS (4.415s)
[502.302410] Subtest buffer-fill: SUCCESS (6.677s)
[502.339038] [077/131] (696s left) perf_pmu (busy-idle-check-all)
[502.380951] Starting subtest: busy-idle-check-all
[502.383717] Starting dynamic subtest: rcs0
[503.060356] Dynamic subtest rcs0: SUCCESS (0.679s)
[503.072154] Starting dynamic subtest: bcs0
[503.717579] Dynamic subtest bcs0: SUCCESS (0.656s)
[503.729392] Starting dynamic subtest: vcs0
[505.404159] Dynamic subtest vcs0: SUCCESS (1.683s)
[505.420930] Starting dynamic subtest: vcs1
[507.389345] Dynamic subtest vcs1: SUCCESS (1.982s)
[507.401986] Starting dynamic subtest: vecs0
[509.373442] Dynamic subtest vecs0: SUCCESS (1.981s)
[509.384960] Starting dynamic subtest: ccs0
[510.028565] Dynamic subtest ccs0: SUCCESS (0.654s)
[510.031320] Subtest busy-idle-check-all: SUCCESS (7.646s)
[510.121644] [078/131] (688s left) gem_partial_pwrite_pread (write-display)
[510.185065] Subtest write-display: SKIP (0.000s)
[510.239128] [079/131] (688s left) kms_plane_scaling (planes-downscale-factor-0-75-unity-scaling)
[510.327498] Starting subtest: planes-downscale-factor-0-75-unity-scaling
[510.329842] Starting dynamic subtest: pipe-A
[510.410604] Dynamic subtest pipe-A: SUCCESS (0.083s)
[510.421687] Starting dynamic subtest: pipe-B
[511.538009] Dynamic subtest pipe-B: SUCCESS (1.127s)
[511.548784] Starting dynamic subtest: pipe-C
[512.635016] Dynamic subtest pipe-C: SUCCESS (1.096s)
[512.646180] Starting dynamic subtest: pipe-D
[513.763116] Dynamic subtest pipe-D: SUCCESS (1.126s)
[513.765830] Subtest planes-downscale-factor-0-75-unity-scaling: SUCCESS (3.435s)
[514.904568] [080/131] (683s left) kms_atomic_interruptible (legacy-cursor)
[515.002738] Starting subtest: legacy-cursor
[515.005136] Starting dynamic subtest: pipe-A-eDP-1
[522.429527] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.425s)
[522.431934] Subtest legacy-cursor: SUCCESS (7.426s)
[523.269433] [081/131] (675s left) kms_chamelium_audio (dp-audio)
[523.641157] Subtest dp-audio: SKIP (0.000s)
[524.471402] [082/131] (674s left) gem_create (create-ext-set-pat)
[524.647148] Starting subtest: create-ext-set-pat
[524.650020] Subtest create-ext-set-pat: SUCCESS (0.001s)
[524.760422] [083/131] (673s left) kms_flip (basic-flip-vs-dpms)
[524.858262] Starting subtest: basic-flip-vs-dpms
[524.860504] Starting dynamic subtest: A-eDP1
[527.483190] Dynamic subtest A-eDP1: SUCCESS (2.620s)
[527.492166] Starting dynamic subtest: B-eDP1
[529.636651] Dynamic subtest B-eDP1: SUCCESS (2.153s)
[529.645796] Starting dynamic subtest: C-eDP1
[531.690721] Dynamic subtest C-eDP1: SUCCESS (2.054s)
[531.702943] Starting dynamic subtest: D-eDP1
[533.746834] Dynamic subtest D-eDP1: SUCCESS (2.054s)
[533.749540] Subtest basic-flip-vs-dpms: SUCCESS (8.888s)
[534.537962] [084/131] (664s left) syncobj_timeline (invalid-transfer-bad-pad)
[534.601561] Starting subtest: invalid-transfer-bad-pad
[534.603843] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s)
[534.708447] [085/131] (664s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[534.817832] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[534.822725] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[534.877892] [086/131] (663s left) kms_pm_rpm (dpms-lpsp)
[536.513106] Starting subtest: dpms-lpsp
[537.109777] Subtest dpms-lpsp: SUCCESS (0.594s)
[537.190706] [087/131] (661s left) kms_flip (2x-wf_vblank-ts-check)
[537.294347] Starting subtest: 2x-wf_vblank-ts-check
[537.305579] Subtest 2x-wf_vblank-ts-check: SKIP (0.010s)
[537.382312] [088/131] (661s left) gem_mmap_gtt (cpuset-big-copy-xy)
[537.442272] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[537.500824] [089/131] (661s left) gem_exec_params (sol-reset-invalid)
[537.573464] Starting subtest: sol-reset-invalid
[537.578859] Subtest sol-reset-invalid: SUCCESS (0.002s)
[539.451281] [090/131] (659s left) gem_fenced_exec_thrash (2-spare-fences)
[539.518864] Subtest 2-spare-fences: SKIP (0.000s)
[539.569364] [091/131] (659s left) drm_fdinfo (most-busy-idle-check-all)
[539.608821] Starting subtest: most-busy-idle-check-all
[539.610866] Starting dynamic subtest: rcs0
[539.615532] Dynamic subtest rcs0: SKIP (0.004s)
[539.625271] Starting dynamic subtest: bcs0
[539.629895] Dynamic subtest bcs0: SKIP (0.000s)
[539.640965] Starting dynamic subtest: vcs0
[539.645191] Dynamic subtest vcs0: SKIP (0.000s)
[539.653907] Starting dynamic subtest: vcs1
[539.658007] Dynamic subtest vcs1: SKIP (0.000s)
[539.666880] Starting dynamic subtest: vecs0
[539.671186] Dynamic subtest vecs0: SKIP (0.000s)
[539.681748] Starting dynamic subtest: ccs0
[539.685883] Dynamic subtest ccs0: SKIP (0.000s)
[539.689957] Subtest most-busy-idle-check-all: SKIP (0.008s)
[539.729283] [092/131] (659s left) gem_request_retire (retire-vma-not-inactive)
[539.799030] Starting subtest: retire-vma-not-inactive
[539.869269] Subtest retire-vma-not-inactive: SUCCESS (0.066s)
[539.998133] [093/131] (659s left) kms_psr (fbc-pr-sprite-blt)
[540.101550] Starting subtest: fbc-pr-sprite-blt
[540.106276] Subtest fbc-pr-sprite-blt: SKIP (0.000s)
[540.168064] [094/131] (658s left) syncobj_timeline (etime-single-wait-submitted)
[540.235722] Starting subtest: etime-single-wait-submitted
[540.345301] Subtest etime-single-wait-submitted: SUCCESS (0.107s)
[540.415845] [095/131] (658s left) kms_psr (psr-primary-render)
[540.518642] Starting subtest: psr-primary-render
[540.521009] Starting dynamic subtest: eDP-1
[542.260561] Dynamic subtest eDP-1: SUCCESS (1.742s)
[542.262565] Subtest psr-primary-render: SUCCESS (1.743s)
[543.104431] [096/131] (656s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25)
[543.198278] Starting subtest: planes-unity-scaling-downscale-factor-0-25
[543.200923] Starting dynamic subtest: pipe-A
[543.217446] Dynamic subtest pipe-A: SKIP (0.012s)
[543.227477] Starting dynamic subtest: pipe-B
[543.240051] Dynamic subtest pipe-B: SKIP (0.023s)
[543.249525] Starting dynamic subtest: pipe-C
[543.262029] Dynamic subtest pipe-C: SKIP (0.021s)
[543.271080] Starting dynamic subtest: pipe-D
[543.286253] Dynamic subtest pipe-D: SKIP (0.023s)
[543.290839] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (0.082s)
[543.393449] [097/131] (655s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[543.503579] Starting subtest: 4-tiled-16bpp-rotate-0
[544.737911] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.234s)
[544.888566] [098/131] (654s left) gem_ctx_shared (q-independent)
[544.944086] Starting subtest: Q-independent
[544.946525] Starting dynamic subtest: rcs0
[545.006898] Dynamic subtest rcs0: SUCCESS (0.062s)
[545.019116] Starting dynamic subtest: vcs0
[545.042172] Dynamic subtest vcs0: SUCCESS (0.035s)
[545.051698] Starting dynamic subtest: vcs1
[545.079917] Dynamic subtest vcs1: SUCCESS (0.037s)
[545.089409] Starting dynamic subtest: vecs0
[545.115167] Dynamic subtest vecs0: SUCCESS (0.035s)
[545.124499] Starting dynamic subtest: ccs0
[545.158389] Dynamic subtest ccs0: SUCCESS (0.043s)
[545.160525] Subtest Q-independent: SUCCESS (0.215s)
[546.473224] [099/131] (652s left) gem_tiled_partial_pwrite_pread (writes)
[546.535467] Subtest writes: SKIP (0.000s)
[546.621793] [100/131] (652s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[546.733013] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[546.737290] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[546.845477] [101/131] (652s left) gem_exec_schedule (preempt-queue)
[546.919884] Subtest preempt-queue: SKIP (0.000s)
[547.203299] [102/131] (652s left) kms_ccs (bad-pixel-format-4-tiled-mtl-mc-ccs)
[547.302159] Starting subtest: bad-pixel-format-4-tiled-mtl-mc-ccs
[547.304355] Starting dynamic subtest: pipe-A-eDP-1
[547.324734] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[547.336521] Starting dynamic subtest: pipe-B-eDP-1
[547.356041] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[547.365148] Starting dynamic subtest: pipe-C-eDP-1
[547.384815] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[547.394554] Starting dynamic subtest: pipe-D-eDP-1
[547.415411] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[547.417441] Subtest bad-pixel-format-4-tiled-mtl-mc-ccs: SUCCESS (0.006s)
[549.466496] [103/131] (649s left) kms_flip (blocking-wf_vblank)
[549.564887] Starting subtest: blocking-wf_vblank
[549.567155] Starting dynamic subtest: A-eDP1
[558.516638] Dynamic subtest A-eDP1: SUCCESS (8.947s)
[558.532386] Starting dynamic subtest: B-eDP1
[567.450177] Dynamic subtest B-eDP1: SUCCESS (8.928s)
[567.460439] Starting dynamic subtest: C-eDP1
[576.366756] Dynamic subtest C-eDP1: SUCCESS (8.913s)
[576.378483] Starting dynamic subtest: D-eDP1
[583.765997] Dynamic subtest D-eDP1: FAIL (7.167s)
[583.768070] Subtest blocking-wf_vblank: FAIL (33.963s)
[584.671938] [104/131] (614s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[584.726028] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[584.833259] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.109s)
[584.882883] [105/131] (614s left) gem_eio (in-flight-10ms)
[585.100555] Starting subtest: in-flight-10ms
[593.306060] Subtest in-flight-10ms: SUCCESS (8.226s)
[593.463039] [106/131] (606s left) kms_psr (pr-primary-blt)
[593.564164] Starting subtest: pr-primary-blt
[593.568403] Subtest pr-primary-blt: SKIP (0.000s)
[593.611027] [107/131] (605s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[593.722310] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[593.726369] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[593.803410] [108/131] (605s left) gem_linear_blits (basic)
[594.266198] Starting subtest: basic
[594.274620] Subtest basic: SUCCESS (0.008s)
[594.394164] [109/131] (605s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[594.540270] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[594.542576] Starting dynamic subtest: pipe-A-eDP-1
[594.565410] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[594.577238] Starting dynamic subtest: pipe-B-eDP-1
[594.598367] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[594.608018] Starting dynamic subtest: pipe-C-eDP-1
[594.629749] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[594.639440] Starting dynamic subtest: pipe-D-eDP-1
[594.662620] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[594.667209] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[596.689936] [110/131] (602s left) gem_ctx_param (invalid-ctx-set)
[596.743603] Starting subtest: invalid-ctx-set
[596.746139] Subtest invalid-ctx-set: SUCCESS (0.001s)
[596.834767] [111/131] (602s left) kms_flip (flip-vs-modeset-vs-hang)
[596.943376] Starting subtest: flip-vs-modeset-vs-hang
[596.945679] Starting dynamic subtest: A-eDP1
[648.849143] Dynamic subtest A-eDP1: SUCCESS (51.913s)
[648.862115] Starting dynamic subtest: D-eDP1
[698.834954] Dynamic subtest D-eDP1: SUCCESS (49.985s)
[698.837355] Subtest flip-vs-modeset-vs-hang: SUCCESS (101.903s)
[699.193614] [112/131] (500s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[699.303829] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[699.308148] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[699.377131] [113/131] (500s left) kms_pipe_crc_basic (compare-crc-sanitycheck-xr24)
[699.483158] Starting subtest: compare-crc-sanitycheck-xr24
[699.485343] Starting dynamic subtest: pipe-A-eDP-1
[700.105160] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.619s)
[700.115881] Starting dynamic subtest: pipe-B-eDP-1
[701.350045] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.245s)
[701.367738] Starting dynamic subtest: pipe-C-eDP-1
[702.527615] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.176s)
[702.537514] Starting dynamic subtest: pipe-D-eDP-1
[703.753612] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.224s)
[703.756060] Subtest compare-crc-sanitycheck-xr24: SUCCESS (4.269s)
[704.585176] [114/131] (495s left) kms_psr (fbc-psr2-sprite-render)
[704.684468] Starting subtest: fbc-psr2-sprite-render
[704.686674] Starting dynamic subtest: eDP-1
[704.691122] Dynamic subtest eDP-1: SKIP (0.000s)
[704.696312] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[704.733586] [115/131] (495s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[704.790698] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[704.795321] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s)
[704.944556] [116/131] (494s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-onoff)
[705.047720] Starting subtest: fbc-2p-scndscrn-cur-indfb-onoff
[705.051879] Subtest fbc-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[705.104521] [117/131] (494s left) kms_getfb (getfb-reject-ccs)
[705.156846] Starting subtest: getfb-reject-ccs
[705.159148] Subtest getfb-reject-ccs: SUCCESS (0.000s)
[705.227879] [118/131] (494s left) gem_create (create-ext-cpu-access-big)
[705.399163] Starting subtest: create-ext-cpu-access-big
[705.404163] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[705.445128] [119/131] (494s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[710.460192] Starting subtest: fbc-pr-cursor-plane-update-sf
[710.464684] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.002s)
[711.277621] [120/131] (488s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[711.390945] Starting subtest: y-tiled-64bpp-rotate-90
[711.395556] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[711.466518] [121/131] (488s left) syncobj_wait (invalid-reset-one-illegal-handle)
[711.533964] Starting subtest: invalid-reset-one-illegal-handle
[711.536250] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[711.582200] [122/131] (488s left) kms_psr (psr-sprite-plane-onoff)
[711.675389] Starting subtest: psr-sprite-plane-onoff
[711.677664] Starting dynamic subtest: eDP-1
[713.427828] Dynamic subtest eDP-1: SUCCESS (1.754s)
[713.430189] Subtest psr-sprite-plane-onoff: SUCCESS (1.755s)
[714.229879] [123/131] (485s left) gem_mmap_wc (bad-offset)
[714.299911] Subtest bad-offset: SKIP (0.000s)
[714.365922] [124/131] (485s left) gem_exec_fence (basic-await)
[714.542002] Starting subtest: basic-await
[714.544536] Starting dynamic subtest: rcs0
[714.621143] Dynamic subtest rcs0: SUCCESS (0.079s)
[714.634463] Starting dynamic subtest: bcs0
[714.699141] Dynamic subtest bcs0: SUCCESS (0.076s)
[714.711911] Starting dynamic subtest: vcs0
[714.776777] Dynamic subtest vcs0: SUCCESS (0.076s)
[714.789197] Starting dynamic subtest: vcs1
[714.854464] Dynamic subtest vcs1: SUCCESS (0.076s)
[714.867428] Starting dynamic subtest: vecs0
[714.931655] Dynamic subtest vecs0: SUCCESS (0.076s)
[714.944591] Starting dynamic subtest: ccs0
[715.007361] Dynamic subtest ccs0: SUCCESS (0.074s)
[715.009954] Subtest basic-await: SUCCESS (0.466s)
[716.459640] [125/131] (483s left) sysfs_preempt_timeout (timeout)
[716.521338] Starting subtest: timeout
[716.523743] Starting dynamic subtest: vecs0
[726.414209] Dynamic subtest vecs0: SUCCESS (9.889s)
[726.426721] Starting dynamic subtest: ccs0
[727.757319] Dynamic subtest ccs0: SUCCESS (1.342s)
[727.775458] Starting dynamic subtest: vcs0
[737.419189] Dynamic subtest vcs0: SUCCESS (9.663s)
[737.432565] Starting dynamic subtest: bcs0
[738.739879] Dynamic subtest bcs0: SUCCESS (1.317s)
[738.750594] Starting dynamic subtest: rcs0
[740.023837] Dynamic subtest rcs0: SUCCESS (1.285s)
[740.036729] Starting dynamic subtest: vcs1
[749.385190] Dynamic subtest vcs1: SUCCESS (9.360s)
[749.388803] Subtest timeout: SUCCESS (32.867s)
[749.433841] [126/131] (450s left) kms_chamelium_hpd (common-hpd-after-suspend)
[749.825349] Subtest common-hpd-after-suspend: SKIP (0.000s)
[750.665341] [127/131] (449s left) kms_async_flips (invalid-async-flip)
[750.775341] Starting subtest: invalid-async-flip
[750.779817] Subtest invalid-async-flip: SKIP (0.000s)
[751.973142] [128/131] (448s left) i915_pm_rpm (system-suspend-execbuf)
[753.510912] Starting subtest: system-suspend-execbuf
[811.858533] Subtest system-suspend-execbuf: SUCCESS (42.992s)
[812.186342] [129/131] (403s left) gen7_exec_parse (load-register-reg)
[812.255415] Subtest load-register-reg: SKIP (0.000s)
[812.357383] [130/131] (403s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[812.464922] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[812.470245] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[812.544918] [131/131] (403s left) gem_exec_reloc (basic-gtt-wc-active)
[812.612043] Subtest basic-gtt-wc-active: SKIP (0.000s)
[812.698158] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_balancer
results: parsing output: 1/ for test: syncobj_timeline
results: parsing output: 2/ for test: gem_softpin
results: parsing output: 3/ for test: kms_flip_scaled_crc
results: parsing output: 4/ for test: kms_invalid_mode
results: parsing output: 5/ for test: kms_prop_blob
results: parsing output: 6/ for test: kms_draw_crc
results: parsing output: 7/ for test: gem_exec_parallel
results: parsing output: 8/ for test: perf_pmu
results: parsing output: 9/ for test: gem_pxp
results: parsing output: 10/ for test: sw_sync
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: kms_vblank
results: parsing output: 13/ for test: kms_flip_scaled_crc
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_exec_fence
results: parsing output: 17/ for test: gem_exec_whisper
results: parsing output: 18/ for test: debugfs_test
results: parsing output: 19/ for test: kms_psr2_sf
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_cursor_legacy
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_color
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: i915_getparams_basic
results: parsing output: 29/ for test: gem_mmap_gtt
results: parsing output: 30/ for test: api_intel_allocator
results: parsing output: 31/ for test: kms_cursor_legacy
results: parsing output: 32/ for test: gem_mmap_gtt
results: parsing output: 33/ for test: gem_exec_reloc
results: parsing output: 34/ for test: gem_render_copy
results: parsing output: 35/ for test: gem_exec_reloc
results: parsing output: 36/ for test: gem_softpin
results: parsing output: 37/ for test: kms_cursor_crc
results: parsing output: 38/ for test: gem_exec_reloc
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gem_lmem_swapping
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_ctx_param
results: parsing output: 43/ for test: gem_workarounds
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: syncobj_wait
results: parsing output: 46/ for test: gem_ctx_shared
results: parsing output: 47/ for test: kms_flip
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: kms_cursor_crc
results: parsing output: 50/ for test: kms_plane
results: parsing output: 51/ for test: kms_ccs
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: drm_fdinfo
results: parsing output: 55/ for test: sriov_basic
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: intel_kernelfs
results: parsing output: 58/ for test: gem_exec_schedule
results: parsing output: 59/ for test: gem_userptr_blits
results: parsing output: 60/ for test: kms_vrr
results: parsing output: 61/ for test: vgem_basic
results: parsing output: 62/ for test: kms_psr
results: parsing output: 63/ for test: gem_exec_schedule
results: parsing output: 64/ for test: api_intel_allocator
results: parsing output: 65/ for test: kms_cursor_edge_walk
results: parsing output: 66/ for test: kms_color
results: parsing output: 67/ for test: i915_pm_freq_api
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_rotation_crc
results: parsing output: 72/ for test: gem_set_tiling_vs_blt
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_properties
results: parsing output: 75/ for test: perf
results: parsing output: 76/ for test: perf_pmu
results: parsing output: 77/ for test: gem_partial_pwrite_pread
results: parsing output: 78/ for test: kms_plane_scaling
results: parsing output: 79/ for test: kms_atomic_interruptible
results: parsing output: 80/ for test: kms_chamelium_audio
results: parsing output: 81/ for test: gem_create
results: parsing output: 82/ for test: kms_flip
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: kms_joiner
results: parsing output: 85/ for test: kms_pm_rpm
results: parsing output: 86/ for test: kms_flip
results: parsing output: 87/ for test: gem_mmap_gtt
results: parsing output: 88/ for test: gem_exec_params
results: parsing output: 89/ for test: gem_fenced_exec_thrash
results: parsing output: 90/ for test: drm_fdinfo
results: parsing output: 91/ for test: gem_request_retire
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_plane_scaling
results: parsing output: 96/ for test: kms_big_fb
results: parsing output: 97/ for test: gem_ctx_shared
results: parsing output: 98/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_exec_schedule
results: parsing output: 101/ for test: kms_ccs
results: parsing output: 102/ for test: kms_flip
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: gem_eio
results: parsing output: 105/ for test: kms_psr
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: gem_linear_blits
results: parsing output: 108/ for test: kms_ccs
results: parsing output: 109/ for test: gem_ctx_param
results: parsing output: 110/ for test: kms_flip
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_pipe_crc_basic
results: parsing output: 113/ for test: kms_psr
results: parsing output: 114/ for test: gem_render_copy
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_getfb
results: parsing output: 117/ for test: gem_create
results: parsing output: 118/ for test: kms_psr2_sf
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: syncobj_wait
results: parsing output: 121/ for test: kms_psr
results: parsing output: 122/ for test: gem_mmap_wc
results: parsing output: 123/ for test: gem_exec_fence
results: parsing output: 124/ for test: sysfs_preempt_timeout
results: parsing output: 125/ for test: kms_chamelium_hpd
results: parsing output: 126/ for test: kms_async_flips
results: parsing output: 127/ for test: i915_pm_rpm
results: parsing output: 128/ for test: gen7_exec_parse
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_exec_reloc
Done.