[61.774278] Initializing watchdogs
[61.774329]   /dev/watchdog0
[61.789192] [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]
[61.793611] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.795998] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.799596] [001/131] (960s left) kms_psr (fbc-psr-primary-render)
[61.912784] Starting subtest: fbc-psr-primary-render
[61.915099] Starting dynamic subtest: eDP-1
[61.919102] Dynamic subtest eDP-1: SKIP (0.000s)
[61.923199] Subtest fbc-psr-primary-render: SKIP (0.000s)
[61.985172] [002/131] (960s left) gem_exec_reloc (basic-write-wc-noreloc)
[62.044806] Subtest basic-write-wc-noreloc: SKIP (0.000s)
[62.114507] [003/131] (960s left) gen7_exec_parse (cmd-crossing-page)
[62.169392] Subtest cmd-crossing-page: SKIP (0.000s)
[62.240353] [004/131] (960s left) gem_lmem_swapping (verify-random)
[62.280627] Subtest verify-random: SKIP (0.000s)
[62.319683] [005/131] (960s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[62.442081] Starting subtest: cursorA-vs-flipB-legacy
[62.446731] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[62.583894] [006/131] (959s left) gem_exec_balancer (busy)
[62.642481] Starting subtest: busy
[63.895589] Subtest busy: SUCCESS (1.248s)
[64.232335] [007/131] (958s left) kms_psr (pr-cursor-blt)
[64.319154] Starting subtest: pr-cursor-blt
[64.323697] Subtest pr-cursor-blt: SKIP (0.000s)
[64.401326] [008/131] (958s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[64.495667] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[64.497979] Starting dynamic subtest: pipe-A-eDP-1
[64.521201] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[64.530220] Starting dynamic subtest: pipe-B-eDP-1
[64.551478] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[64.558802] Starting dynamic subtest: pipe-C-eDP-1
[64.579953] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[64.587078] Starting dynamic subtest: pipe-D-eDP-1
[64.609454] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[64.613600] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[66.744523] [009/131] (955s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[66.845471] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[66.847878] Starting dynamic subtest: pipe-A-default-mode
[68.005849] Dynamic subtest pipe-A-default-mode: SKIP (1.156s)
[68.010405] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SKIP (1.158s)
[68.259126] [010/131] (954s left) syncobj_timeline (invalid-signal-illegal-handle)
[68.324822] Starting subtest: invalid-signal-illegal-handle
[68.327151] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[68.413215] [011/131] (954s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[68.518078] Starting subtest: psr-rgb565-draw-mmap-cpu
[75.287696] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.769s)
[75.394925] [012/131] (947s left) kms_content_protection (lic-type-0-hdcp14)
[75.552437] Starting subtest: lic-type-0-hdcp14
[75.556996] Subtest lic-type-0-hdcp14: SKIP (0.000s)
[75.699092] [013/131] (947s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[75.796606] Starting subtest: primary-yf-tiled-reflect-x-90
[75.800995] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[75.866400] [014/131] (946s left) gem_render_copy (y-tiled-ccs-to-linear)
[75.924529] Starting subtest: y-tiled-ccs-to-linear
[75.928844] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[76.104813] [015/131] (946s left) kms_flip (2x-plain-flip-ts-check)
[76.212882] Starting subtest: 2x-plain-flip-ts-check
[76.223520] Subtest 2x-plain-flip-ts-check: SKIP (0.011s)
[76.297764] [016/131] (946s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf)
[76.404974] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf
[76.407209] Starting dynamic subtest: pipe-A-eDP-1
[76.502186] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[76.511866] Starting dynamic subtest: pipe-B-eDP-1
[77.709918] Dynamic subtest pipe-B-eDP-1: SKIP (1.205s)
[77.714775] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (1.307s)
[78.892197] [017/131] (944s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[78.992923] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[78.997288] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[79.096854] [018/131] (943s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[79.194311] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[85.763930] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.570s)
[85.870676] [019/131] (937s left) gem_exec_params (sol-reset-invalid)
[85.932449] Starting subtest: sol-reset-invalid
[85.934737] Subtest sol-reset-invalid: SUCCESS (0.004s)
[87.964536] [020/131] (935s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[88.066308] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[88.070649] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[88.160055] [021/131] (934s left) kms_busy (extended-modeset-hang-newfb)
[88.257182] Starting subtest: extended-modeset-hang-newfb
[88.259562] Starting dynamic subtest: pipe-A
[122.867975] Dynamic subtest pipe-A: SUCCESS (34.606s)
[122.876582] Starting dynamic subtest: pipe-D
[145.809447] Dynamic subtest pipe-D: SUCCESS (22.939s)
[145.811859] Subtest extended-modeset-hang-newfb: SUCCESS (57.549s)
[146.603905] [022/131] (876s left) syncobj_timeline (etime-single-wait-for-submit-available-unsubmitted)
[146.676650] Starting subtest: etime-single-wait-for-submit-available-unsubmitted
[146.785201] Subtest etime-single-wait-for-submit-available-unsubmitted: SUCCESS (0.107s)
[146.857982] [023/131] (876s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[146.951760] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc
[153.562323] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.613s)
[153.684138] [024/131] (869s left) core_setmaster (master-drop-set-root)
[153.766288] Starting subtest: master-drop-set-root
[153.783154] Subtest master-drop-set-root: SUCCESS (0.019s)
[153.837468] [025/131] (869s left) kms_psr (fbc-psr2-primary-render)
[153.923722] Starting subtest: fbc-psr2-primary-render
[153.926012] Starting dynamic subtest: eDP-1
[153.930211] Dynamic subtest eDP-1: SKIP (0.000s)
[153.934491] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[153.995550] [026/131] (869s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[154.097501] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[154.099910] Starting dynamic subtest: pipe-A
[154.120322] Dynamic subtest pipe-A: SKIP (0.017s)
[154.128665] Starting dynamic subtest: pipe-B
[154.151094] Dynamic subtest pipe-B: SKIP (0.031s)
[154.159061] Starting dynamic subtest: pipe-C
[154.185216] Dynamic subtest pipe-C: SKIP (0.034s)
[154.192993] Starting dynamic subtest: pipe-D
[154.215867] Dynamic subtest pipe-D: SKIP (0.030s)
[154.220153] Subtest plane-downscale-factor-0-5-with-pixel-format: SKIP (0.113s)
[154.305261] [027/131] (868s left) kms_force_connector_basic (force-load-detect)
[154.371714] Starting subtest: force-load-detect
[154.376102] Subtest force-load-detect: SKIP (0.000s)
[154.485539] [028/131] (868s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[154.589032] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[154.593364] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[154.729891] [029/131] (868s left) i915_query (query-regions-unallocated)
[154.782901] Starting subtest: query-regions-unallocated
[154.784491] Subtest query-regions-unallocated: SUCCESS (0.000s)
[154.882663] [030/131] (868s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[154.961996] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[154.963921] Starting dynamic subtest: pipe-A
[155.298624] Dynamic subtest pipe-A: SUCCESS (0.338s)
[155.306481] Starting dynamic subtest: pipe-B
[156.710753] Dynamic subtest pipe-B: SUCCESS (1.411s)
[156.719626] Starting dynamic subtest: pipe-C
[158.133663] Dynamic subtest pipe-C: SUCCESS (1.422s)
[158.142020] Starting dynamic subtest: pipe-D
[159.569619] Dynamic subtest pipe-D: SUCCESS (1.435s)
[159.571790] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.609s)
[160.726720] [031/131] (862s left) kms_psr (psr-cursor-mmap-gtt)
[160.820075] Starting subtest: psr-cursor-mmap-gtt
[160.822522] Starting dynamic subtest: eDP-1
[162.539708] Dynamic subtest eDP-1: SUCCESS (1.719s)
[162.541661] Subtest psr-cursor-mmap-gtt: SUCCESS (1.720s)
[163.405922] [032/131] (860s left) gem_ctx_persistence (legacy-engines-mixed)
[163.479658] Starting subtest: legacy-engines-mixed
[163.481983] Starting dynamic subtest: render
[163.560435] Dynamic subtest render: SUCCESS (0.080s)
[163.571464] Starting dynamic subtest: bsd1
[163.607634] Dynamic subtest bsd1: SUCCESS (0.045s)
[163.617123] Starting dynamic subtest: bsd2
[163.661554] Dynamic subtest bsd2: SUCCESS (0.052s)
[163.670641] Starting dynamic subtest: blt
[163.714688] Dynamic subtest blt: SUCCESS (0.052s)
[163.727932] Starting dynamic subtest: vebox
[163.764823] Dynamic subtest vebox: SUCCESS (0.049s)
[163.767943] Subtest legacy-engines-mixed: SUCCESS (0.286s)
[163.843711] [033/131] (859s left) gem_render_copy_redux (normal)
[163.900608] Starting subtest: normal
[164.163926] Subtest normal: SUCCESS (0.261s)
[164.299372] [034/131] (859s left) kms_pm_rpm (dpms-mode-unset-non-lpsp)
[166.031070] Starting subtest: dpms-mode-unset-non-lpsp
[167.199899] Subtest dpms-mode-unset-non-lpsp: SKIP (1.167s)
[167.573916] [035/131] (856s left) kms_rotation_crc (sprite-rotation-90)
[167.661198] Starting subtest: sprite-rotation-90
[167.665391] Subtest sprite-rotation-90: SKIP (0.001s)
[167.735793] [036/131] (855s left) gem_fence_thrash (bo-write-verify-threaded-none)
[167.778520] Starting subtest: bo-write-verify-threaded-none
[167.791568] Subtest bo-write-verify-threaded-none: SKIP (0.012s)
[167.853825] [037/131] (855s left) kms_psr (psr-primary-mmap-gtt)
[167.946854] Starting subtest: psr-primary-mmap-gtt
[167.949252] Starting dynamic subtest: eDP-1
[169.361769] Dynamic subtest eDP-1: SKIP (1.411s)
[169.366973] Subtest psr-primary-mmap-gtt: SKIP (1.412s)
[169.554014] [038/131] (854s left) gem_mmap_gtt (fault-concurrent-y)
[169.617173] Subtest fault-concurrent-Y: SKIP (0.000s)
[169.670382] [039/131] (854s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[169.767105] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs
[169.769376] Starting dynamic subtest: pipe-A-eDP-1
[169.789637] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[169.799250] Starting dynamic subtest: pipe-B-eDP-1
[169.818718] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[169.826750] Starting dynamic subtest: pipe-C-eDP-1
[169.845748] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[169.853363] Starting dynamic subtest: pipe-D-eDP-1
[169.872675] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[169.874862] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.007s)
[171.966733] [040/131] (851s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[172.071348] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[172.075439] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[172.164781] [041/131] (851s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[172.264456] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[173.290589] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.026s)
[173.424092] [042/131] (850s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[173.806327] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[174.577227] [043/131] (849s left) kms_feature_discovery (dp-mst)
[174.664156] Starting subtest: dp-mst
[174.668709] Subtest dp-mst: SKIP (0.000s)
[174.735169] [044/131] (849s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-move)
[174.831415] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-move
[174.835666] Subtest fbcpsr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[174.963980] [045/131] (849s left) kms_ccs (crc-sprite-planes-basic-yf-tiled-ccs)
[175.060082] Starting subtest: crc-sprite-planes-basic-yf-tiled-ccs
[175.062321] Starting dynamic subtest: pipe-A-eDP-1
[175.120122] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[175.129655] Starting dynamic subtest: pipe-B-eDP-1
[175.187067] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[175.198902] Starting dynamic subtest: pipe-C-eDP-1
[175.255449] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[175.264853] Starting dynamic subtest: pipe-D-eDP-1
[175.321797] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[175.326303] Subtest crc-sprite-planes-basic-yf-tiled-ccs: SKIP (0.008s)
[177.266929] [046/131] (846s left) kms_lease (lease-revoke)
[177.366916] Starting subtest: lease-revoke
[177.369274] Starting dynamic subtest: pipe-A-eDP-1
[177.396076] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.030s)
[177.406985] Starting dynamic subtest: pipe-B-eDP-1
[177.424174] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.027s)
[177.432584] Starting dynamic subtest: pipe-C-eDP-1
[177.453262] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.028s)
[177.461461] Starting dynamic subtest: pipe-D-eDP-1
[177.487083] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.030s)
[177.488981] Subtest lease-revoke: SUCCESS (0.120s)
[177.612910] [047/131] (846s left) gem_partial_pwrite_pread (write-uncached)
[177.677582] Subtest write-uncached: SKIP (0.000s)
[177.741242] [048/131] (846s left) kms_async_flips (alternate-sync-async-flip)
[177.834725] Starting subtest: alternate-sync-async-flip
[178.214877] Starting dynamic subtest: pipe-A-eDP-1
[180.967334] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.753s)
[181.351384] Starting dynamic subtest: pipe-B-eDP-1
[184.096320] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.752s)
[184.473678] Starting dynamic subtest: pipe-C-eDP-1
[187.229062] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.761s)
[187.595079] Starting dynamic subtest: pipe-D-eDP-1
[190.354277] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.766s)
[190.356490] Subtest alternate-sync-async-flip: SUCCESS (12.522s)
[191.527950] [049/131] (832s left) gem_eio (reset-stress)
[191.763127] Starting subtest: reset-stress
[191.765461] Starting dynamic subtest: render
[196.710166] Dynamic subtest render: SUCCESS (4.946s)
[196.725416] Starting dynamic subtest: bsd1
[214.463299] Dynamic subtest bsd1: SUCCESS (17.751s)
[214.478145] Starting dynamic subtest: bsd2
[232.400973] Dynamic subtest bsd2: SUCCESS (17.936s)
[232.414878] Starting dynamic subtest: blt
[232.419614] Dynamic subtest blt: SKIP (0.000s)
[232.427394] Starting dynamic subtest: vebox
[250.400048] Dynamic subtest vebox: SUCCESS (17.997s)
[250.402517] Subtest reset-stress: SUCCESS (58.634s)
[251.973068] [FACT gem_eio (reset-stress)] new: kernel.kmod_is_loaded.vgem: true
[251.976530] [050/131] (772s left) kms_invalid_mode (bad-vsync-start)
[252.076872] Starting subtest: bad-vsync-start
[252.079046] Starting dynamic subtest: pipe-A-eDP-1
[252.365334] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.288s)
[252.374269] Starting dynamic subtest: pipe-B-eDP-1
[252.376558] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[252.384523] Starting dynamic subtest: pipe-C-eDP-1
[252.386916] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[252.394837] Starting dynamic subtest: pipe-D-eDP-1
[252.397236] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[252.399550] Subtest bad-vsync-start: SUCCESS (0.294s)
[253.179286] [051/131] (771s left) gem_exec_whisper (basic-queues-all)
[253.245796] Starting subtest: basic-queues-all
[263.948209] Subtest basic-queues-all: SUCCESS (10.702s)
[264.138713] [052/131] (760s left) gem_create (create-ext-cpu-access-sanity-check)
[264.316899] Starting subtest: create-ext-cpu-access-sanity-check
[264.321763] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[264.397863] [053/131] (759s left) kms_flip (2x-blocking-wf_vblank)
[264.498903] Starting subtest: 2x-blocking-wf_vblank
[264.511107] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[264.616213] [054/131] (759s left) i915_drm_fdinfo (busy-check-all)
[264.658968] Starting subtest: busy-check-all
[264.661354] Starting dynamic subtest: rcs0
[264.665844] Dynamic subtest rcs0: SKIP (0.004s)
[264.678508] Starting dynamic subtest: bcs0
[264.682924] Dynamic subtest bcs0: SKIP (0.000s)
[264.693608] Starting dynamic subtest: vcs0
[264.697598] Dynamic subtest vcs0: SKIP (0.000s)
[264.705203] Starting dynamic subtest: vcs1
[264.709122] Dynamic subtest vcs1: SKIP (0.000s)
[264.716641] Starting dynamic subtest: vecs0
[264.720633] Dynamic subtest vecs0: SKIP (0.000s)
[264.728162] Starting dynamic subtest: ccs0
[264.732161] Dynamic subtest ccs0: SKIP (0.000s)
[264.736132] Subtest busy-check-all: SKIP (0.010s)
[264.773550] [055/131] (759s left) kms_psr2_su (page_flip-xrgb8888)
[264.830404] Subtest page_flip-XRGB8888: SKIP (0.000s)
[264.910039] [056/131] (759s left) kms_pm_rpm (pc8-residency)
[267.033498] Starting subtest: pc8-residency
[267.038702] Subtest pc8-residency: SKIP (0.000s)
[267.407914] [057/131] (757s left) gem_blits (basic)
[267.473641] Starting subtest: basic
[268.891268] Subtest basic: SUCCESS (1.417s)
[268.995785] [058/131] (755s left) gem_exec_parallel (engines)
[269.065560] Starting subtest: engines
[269.067962] Starting dynamic subtest: basic
[270.893419] Dynamic subtest basic: SUCCESS (1.829s)
[270.904619] Starting dynamic subtest: contexts
[275.386941] Dynamic subtest contexts: SUCCESS (4.491s)
[275.397383] Starting dynamic subtest: fds
[279.313657] Dynamic subtest fds: SUCCESS (3.925s)
[279.324779] Starting dynamic subtest: userptr
[280.824132] Dynamic subtest userptr: SUCCESS (1.509s)
[280.826466] Subtest engines: SUCCESS (11.763s)
[281.038201] [059/131] (743s left) kms_plane (pixel-format-linear-modifier)
[281.132873] Starting subtest: pixel-format-linear-modifier
[281.161480] Starting dynamic subtest: pipe-A-plane-0
[284.772106] Dynamic subtest pipe-A-plane-0: SUCCESS (3.611s)
[284.782773] Starting dynamic subtest: pipe-A-plane-3
[286.538855] Dynamic subtest pipe-A-plane-3: SUCCESS (1.765s)
[286.549685] Starting dynamic subtest: pipe-A-plane-5
[286.749977] Dynamic subtest pipe-A-plane-5: SUCCESS (0.209s)
[287.875868] Starting dynamic subtest: pipe-B-plane-0
[291.467916] Dynamic subtest pipe-B-plane-0: SUCCESS (3.599s)
[291.477957] Starting dynamic subtest: pipe-B-plane-3
[293.245586] Dynamic subtest pipe-B-plane-3: SUCCESS (1.777s)
[293.256212] Starting dynamic subtest: pipe-B-plane-5
[293.445725] Dynamic subtest pipe-B-plane-5: SUCCESS (0.198s)
[293.756188] Subtest pixel-format-linear-modifier: SUCCESS (12.621s)
[294.549371] [060/131] (730s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[294.650939] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[299.119597] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.472s)
[300.261058] [061/131] (724s left) kms_frontbuffer_tracking (fbc-stridechange)
[300.357802] Starting subtest: fbc-stridechange
[303.662063] Subtest fbc-stridechange: SUCCESS (3.307s)
[304.877236] [062/131] (719s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[304.975841] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[309.468411] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (4.496s)
[310.686090] [063/131] (714s left) api_intel_allocator (simple-allocator)
[310.744994] Starting subtest: simple-allocator
[310.747257] Starting dynamic subtest: basic
[310.749585] Dynamic subtest basic: SUCCESS (0.003s)
[310.759467] Starting dynamic subtest: parallel-one
[310.940403] Dynamic subtest parallel-one: SUCCESS (0.191s)
[310.956032] Starting dynamic subtest: print
[310.958750] Dynamic subtest print: SUCCESS (0.000s)
[310.967483] Starting dynamic subtest: reuse
[310.969391] Dynamic subtest reuse: SUCCESS (0.000s)
[310.977219] Starting dynamic subtest: reserve
[310.979087] Dynamic subtest reserve: SUCCESS (0.000s)
[310.986770] Starting dynamic subtest: fork-reopen-allocator
[310.988689] Dynamic subtest fork-reopen-allocator: SUCCESS (0.018s)
[310.990590] Subtest simple-allocator: SUCCESS (0.221s)
[311.083659] [064/131] (713s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[311.141122] Starting subtest: single-wait-all-for-submit-available-submitted
[311.143475] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[311.253796] [065/131] (713s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[311.358926] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[317.985383] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.629s)
[318.102030] [066/131] (706s left) gem_exec_store (basic)
[318.166048] Starting subtest: basic
[318.271442] Subtest basic: SUCCESS (0.107s)
[318.469748] [067/131] (706s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[318.529838] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[318.534472] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[318.726955] [068/131] (706s left) perf (oa-exponents)
[318.858243] Starting subtest: oa-exponents
[318.860629] Starting dynamic subtest: 0-rcs0
[320.620541] Dynamic subtest 0-rcs0: SUCCESS (1.762s)
[320.630066] Starting dynamic subtest: 1-vecs0
[322.450832] Dynamic subtest 1-vecs0: SUCCESS (1.828s)
[322.453147] Subtest oa-exponents: SUCCESS (3.592s)
[322.499785] [069/131] (702s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[322.586787] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[322.589043] Starting dynamic subtest: pipe-A-default-mode
[325.361349] Dynamic subtest pipe-A-default-mode: SUCCESS (2.774s)
[325.363627] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.776s)
[326.227436] [070/131] (698s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[326.322706] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[326.326814] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[326.433049] [071/131] (698s left) kms_flip (plain-flip-interruptible)
[326.525774] Starting subtest: plain-flip-interruptible
[326.527668] Starting dynamic subtest: A-eDP1
[328.221994] Dynamic subtest A-eDP1: SUCCESS (1.694s)
[328.231087] Starting dynamic subtest: B-eDP1
[329.849069] Dynamic subtest B-eDP1: SUCCESS (1.625s)
[329.857857] Starting dynamic subtest: C-eDP1
[331.483371] Dynamic subtest C-eDP1: SUCCESS (1.633s)
[331.500656] Starting dynamic subtest: D-eDP1
[333.099080] Dynamic subtest D-eDP1: SUCCESS (1.613s)
[333.105642] Subtest plain-flip-interruptible: SUCCESS (6.580s)
[334.269429] [072/131] (690s left) kms_cursor_crc (cursor-sliding-128x128)
[334.499721] Starting subtest: cursor-sliding-128x128
[334.504751] Starting dynamic subtest: pipe-A-eDP-1
[337.736526] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.231s)
[337.774388] Starting dynamic subtest: pipe-D-eDP-1
[341.710212] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.943s)
[341.712811] Subtest cursor-sliding-128x128: SUCCESS (7.205s)
[342.540409] [073/131] (682s left) i915_pciid
[342.706455] [074/131] (682s left) gem_userptr_blits (vma-merge)
[342.774848] Starting subtest: vma-merge
[342.817218] Subtest vma-merge: SUCCESS (0.042s)
[342.930288] [075/131] (682s left) syncobj_timeline (multi-wait-for-submit-available-submitted-signaled)
[342.989031] Starting subtest: multi-wait-for-submit-available-submitted-signaled
[342.991480] Subtest multi-wait-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[343.084248] [076/131] (682s left) gem_ctx_persistence (engines-persistence)
[343.148299] Starting subtest: engines-persistence
[343.150243] Starting dynamic subtest: rcs0
[343.957480] Dynamic subtest rcs0: SUCCESS (0.811s)
[343.969565] Starting dynamic subtest: bcs0
[344.732569] Dynamic subtest bcs0: SUCCESS (0.773s)
[344.744331] Starting dynamic subtest: vcs0
[345.489230] Dynamic subtest vcs0: SUCCESS (0.754s)
[345.500727] Starting dynamic subtest: vcs1
[346.255540] Dynamic subtest vcs1: SUCCESS (0.764s)
[346.267375] Starting dynamic subtest: vecs0
[347.022453] Dynamic subtest vecs0: SUCCESS (0.765s)
[347.034373] Starting dynamic subtest: ccs0
[347.771239] Dynamic subtest ccs0: SUCCESS (0.747s)
[347.773840] Subtest engines-persistence: SUCCESS (4.626s)
[347.852626] [077/131] (677s left) kms_prop_blob (blob-prop-lifetime)
[347.903745] Starting subtest: blob-prop-lifetime
[347.906188] Subtest blob-prop-lifetime: SUCCESS (0.001s)
[348.009864] [078/131] (677s left) kms_cursor_crc (cursor-onscreen-32x10)
[348.195632] Starting subtest: cursor-onscreen-32x10
[348.244877] Subtest cursor-onscreen-32x10: SKIP (0.043s)
[348.544902] [079/131] (676s left) kms_dp_link_training (non-uhbr-mst)
[348.646051] Starting subtest: non-uhbr-mst
[348.654860] Subtest non-uhbr-mst: SKIP (0.000s)
[348.742702] [080/131] (676s left) gem_exec_params (cliprects-invalid)
[348.795157] Starting subtest: cliprects-invalid
[348.797259] Subtest cliprects-invalid: SUCCESS (0.000s)
[348.934208] [081/131] (676s left) gem_userptr_blits (sync-overlap)
[348.990564] Starting subtest: sync-overlap
[348.992615] Subtest sync-overlap: SUCCESS (0.000s)
[349.128840] [082/131] (676s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[349.226224] Starting subtest: x-tiled-32bpp-rotate-270
[349.245475] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[349.362178] [083/131] (676s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[349.456457] Starting subtest: fbc-rgb565-draw-mmap-wc
[353.428948] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (3.975s)
[354.574415] [084/131] (671s left) kms_properties (crtc-properties-atomic)
[354.678063] Starting subtest: crtc-properties-atomic
[354.680246] Starting dynamic subtest: pipe-A-eDP-1
[355.114596] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.435s)
[355.125208] Starting dynamic subtest: pipe-B-eDP-1
[356.311453] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.195s)
[356.320591] Starting dynamic subtest: pipe-C-eDP-1
[357.428025] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.116s)
[357.436985] Starting dynamic subtest: pipe-D-eDP-1
[358.563334] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.132s)
[358.565256] Subtest crtc-properties-atomic: SUCCESS (3.884s)
[359.368441] [085/131] (666s left) gem_eio (wait-immediate)
[359.564241] Starting subtest: wait-immediate
[359.877601] Subtest wait-immediate: SUCCESS (0.314s)
[360.049141] [086/131] (665s left) gem_ppgtt (flink-and-close-vma-leak)
[360.113387] Starting subtest: flink-and-close-vma-leak
[360.152590] Subtest flink-and-close-vma-leak: SUCCESS (0.042s)
[360.259949] [087/131] (665s left) i915_pm_rpm (system-suspend)
[362.094970] Starting subtest: system-suspend
[380.560573] Subtest system-suspend: SUCCESS (3.374s)
[386.953692] [088/131] (644s left) gem_exec_flush (basic-wb-rw-before-default)
[387.037909] Starting subtest: basic-wb-rw-before-default
[392.580936] Subtest basic-wb-rw-before-default: SUCCESS (5.466s)
[392.773726] [089/131] (639s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-plflip-blt)
[392.878262] Starting subtest: fbcpsr-1p-primscrn-shrfb-plflip-blt
[400.459319] Subtest fbcpsr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.537s)
[400.596525] [090/131] (631s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[400.700033] Starting subtest: 4-tiled-32bpp-rotate-0
[402.129458] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.425s)
[402.263768] [091/131] (629s left) gem_exec_params (no-blt)
[402.334984] Starting subtest: no-blt
[402.341826] Subtest no-blt: SUCCESS (0.003s)
[402.438062] [092/131] (629s left) perf_pmu (busy-check-all)
[402.484937] Starting subtest: busy-check-all
[402.487353] Starting dynamic subtest: rcs0
[403.037278] Dynamic subtest rcs0: SUCCESS (0.552s)
[403.050009] Starting dynamic subtest: bcs0
[403.592180] Dynamic subtest bcs0: SUCCESS (0.550s)
[403.608668] Starting dynamic subtest: vcs0
[405.004484] Dynamic subtest vcs0: SUCCESS (1.404s)
[405.018269] Starting dynamic subtest: vcs1
[406.988003] Dynamic subtest vcs1: SUCCESS (1.976s)
[407.000369] Starting dynamic subtest: vecs0
[409.035655] Dynamic subtest vecs0: SUCCESS (2.041s)
[409.048554] Starting dynamic subtest: ccs0
[409.591575] Dynamic subtest ccs0: SUCCESS (0.553s)
[409.594588] Subtest busy-check-all: SUCCESS (7.094s)
[409.707098] [093/131] (622s left) syncobj_eventfd (binary-wait-signaled)
[409.772238] Starting subtest: binary-wait-signaled
[409.774797] Subtest binary-wait-signaled: SUCCESS (0.000s)
[409.876364] [094/131] (622s left) kms_selftest (drm_damage_helper)
[409.918903] Starting subtest: drm_damage_helper
[409.961187] Starting dynamic subtest: drm_test_damage_iter_no_damage
[410.038616] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.078s)
[410.048892] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[410.128657] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.086s)
[410.138252] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[410.216050] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.089s)
[410.226144] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[410.298993] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.081s)
[410.310766] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[410.372932] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.073s)
[410.382925] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[410.464851] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.090s)
[410.476313] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[410.550014] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.083s)
[410.563812] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[410.625567] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.076s)
[410.636894] Starting dynamic subtest: drm_test_damage_iter_single_damage
[410.703903] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.078s)
[410.713600] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[410.788730] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.083s)
[410.800332] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[410.866844] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.078s)
[410.876119] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[410.948937] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.082s)
[410.959570] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[411.032789] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.084s)
[411.044247] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[411.124976] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.090s)
[411.135939] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[411.210600] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.083s)
[411.221444] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[411.288509] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.077s)
[411.298870] Starting dynamic subtest: drm_test_damage_iter_damage
[411.375661] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.086s)
[411.386226] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[411.464569] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.089s)
[411.474937] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[411.558615] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.092s)
[411.568793] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[411.645690] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.085s)
[411.655615] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[411.732495] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.086s)
[411.734729] Subtest drm_damage_helper: SUCCESS (1.813s)
[411.868347] [095/131] (620s left) kms_atomic (crtc-invalid-params-fence)
[411.970815] Starting subtest: crtc-invalid-params-fence
[411.973484] Starting dynamic subtest: pipe-A-eDP-1
[412.370903] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.399s)
[412.373841] Subtest crtc-invalid-params-fence: SUCCESS (0.401s)
[413.225490] [096/131] (619s left) kms_plane_multiple (2x-tiling-y)
[413.313981] Starting subtest: 2x-tiling-y
[413.318736] Subtest 2x-tiling-y: SKIP (0.000s)
[413.387860] [097/131] (618s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[413.480232] Starting subtest: yf-tiled-8bpp-rotate-0
[413.485529] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[413.576086] [098/131] (618s left) gem_mmap_wc (write)
[413.632808] Subtest write: SKIP (0.000s)
[413.694422] [099/131] (618s left) gem_exec_balancer (sliced)
[413.753811] Starting subtest: sliced
[413.759520] Subtest sliced: SKIP (0.000s)
[414.044040] [100/131] (618s left) kms_color (degamma)
[414.132713] Starting subtest: degamma
[414.134974] Starting dynamic subtest: pipe-A-eDP-1
[414.852141] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.722s)
[414.862972] Starting dynamic subtest: pipe-B-eDP-1
[416.270807] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.416s)
[416.282293] Starting dynamic subtest: pipe-C-eDP-1
[417.633775] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.361s)
[417.645000] Starting dynamic subtest: pipe-D-eDP-1
[418.965993] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[418.969021] Subtest degamma: SUCCESS (4.833s)
[419.768427] [101/131] (612s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt)
[419.865800] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt
[419.871002] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[419.961868] [102/131] (612s left) gem_ctx_param (root-set)
[420.024499] Starting subtest: root-set
[420.027158] Subtest root-set: SUCCESS (0.001s)
[420.130137] [103/131] (612s left) perf (stress-open-close)
[420.221151] Starting subtest: stress-open-close
[420.223365] Starting dynamic subtest: 0-rcs0
[422.385406] Dynamic subtest 0-rcs0: SUCCESS (2.167s)
[422.395389] Starting dynamic subtest: 1-vecs0
[424.550611] Dynamic subtest 1-vecs0: SUCCESS (2.164s)
[424.552871] Subtest stress-open-close: SUCCESS (4.332s)
[424.598327] [104/131] (608s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[425.147126] Starting subtest: syncobj-timeline-multiple-ext-nodes
[425.153168] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.007s)
[426.051655] [105/131] (606s left) perf_pmu (event-wait)
[426.093517] Starting subtest: event-wait
[426.096307] Starting dynamic subtest: rcs0
[426.101774] Dynamic subtest rcs0: SKIP (0.000s)
[426.107151] Subtest event-wait: SKIP (0.001s)
[426.201220] [106/131] (606s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt)
[426.306642] Starting subtest: psr-2p-primscrn-indfb-plflip-blt
[426.311128] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[426.401053] [107/131] (606s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[426.502625] Starting subtest: y-tiled-64bpp-rotate-90
[426.507676] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[426.613649] [108/131] (606s left) prime_mmap (test_userptr)
[426.679252] Starting subtest: test_userptr
[426.681755] Starting dynamic subtest: test_userptr-smem
[426.684453] Dynamic subtest test_userptr-smem: SUCCESS (0.000s)
[426.686937] Subtest test_userptr: SUCCESS (0.001s)
[426.763190] [109/131] (606s left) gem_exec_reloc (basic-gtt-read-active)
[426.829819] Subtest basic-gtt-read-active: SKIP (0.000s)
[426.902482] [110/131] (605s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[426.955826] Starting subtest: wait-all-for-submit-delayed-submit
[427.057561] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[427.152892] [111/131] (605s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[427.252517] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[427.258019] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[428.403492] [112/131] (604s left) kms_vblank (query-forked-busy-hang)
[428.496020] Starting subtest: query-forked-busy-hang
[428.498766] Starting dynamic subtest: pipe-A-eDP-1
[454.731989] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.224s)
[454.748478] Starting dynamic subtest: pipe-D-eDP-1
[478.791868] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.049s)
[478.795371] Subtest query-forked-busy-hang: SUCCESS (50.277s)
[479.603253] [113/131] (553s left) kms_cursor_crc (cursor-sliding-64x21)
[479.789377] Starting subtest: cursor-sliding-64x21
[479.836502] Subtest cursor-sliding-64x21: SKIP (0.041s)
[480.071208] [114/131] (552s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[480.171128] Starting subtest: disable-crc-after-crtc
[480.173761] Starting dynamic subtest: pipe-A-eDP-1
[481.841794] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.672s)
[481.854238] Starting dynamic subtest: pipe-B-eDP-1
[484.173627] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.333s)
[484.184271] Starting dynamic subtest: pipe-C-eDP-1
[486.450475] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.273s)
[486.461731] Starting dynamic subtest: pipe-D-eDP-1
[488.721703] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.274s)
[488.724494] Subtest disable-crc-after-crtc: SUCCESS (8.558s)
[489.547544] [115/131] (543s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-wc)
[489.667463] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-wc
[496.359870] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.696s)
[496.487209] [116/131] (536s left) sysfs_timeslice_duration (timeout)
[496.541428] Starting subtest: timeout
[496.544007] Starting dynamic subtest: vecs0
[505.990761] Dynamic subtest vecs0: SUCCESS (9.445s)
[506.003813] Starting dynamic subtest: ccs0
[507.212673] Dynamic subtest ccs0: SUCCESS (1.221s)
[507.224434] Starting dynamic subtest: vcs0
[517.001043] Dynamic subtest vcs0: SUCCESS (9.787s)
[517.012410] Starting dynamic subtest: bcs0
[517.605892] Dynamic subtest bcs0: SUCCESS (0.603s)
[517.618102] Starting dynamic subtest: rcs0
[518.857292] Dynamic subtest rcs0: SUCCESS (1.249s)
[518.869722] Starting dynamic subtest: vcs1
[528.009003] Dynamic subtest vcs1: SUCCESS (9.147s)
[528.011998] Subtest timeout: SUCCESS (31.464s)
[528.096239] [117/131] (505s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[528.218312] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[529.276348] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.058s)
[529.403980] [118/131] (503s left) kms_chamelium_edid (hdmi-edid-read)
[529.798774] Subtest hdmi-edid-read: SKIP (0.000s)
[530.604109] [119/131] (502s left) api_intel_bb (intel-bb-blit-y)
[530.657399] Starting subtest: intel-bb-blit-y
[534.156385] Subtest intel-bb-blit-y: SUCCESS (3.500s)
[534.272055] [120/131] (499s left) gem_exec_schedule (submit-late-slice)
[534.347637] Starting subtest: submit-late-slice
[534.350304] Starting dynamic subtest: rcs0
[534.398721] Dynamic subtest rcs0: SUCCESS (0.061s)
[534.408936] Starting dynamic subtest: bcs0
[534.462409] Dynamic subtest bcs0: SUCCESS (0.063s)
[534.475025] Starting dynamic subtest: vcs0
[534.519241] Dynamic subtest vcs0: SUCCESS (0.057s)
[534.529573] Starting dynamic subtest: vcs1
[534.578759] Dynamic subtest vcs1: SUCCESS (0.058s)
[534.596517] Starting dynamic subtest: vecs0
[534.631918] Dynamic subtest vecs0: SUCCESS (0.050s)
[534.642836] Starting dynamic subtest: ccs0
[534.686460] Dynamic subtest ccs0: SUCCESS (0.054s)
[534.688868] Subtest submit-late-slice: SUCCESS (0.350s)
[536.108697] [121/131] (497s left) gem_tiled_blits (basic)
[536.175881] Subtest basic: SKIP (0.000s)
[536.253327] [122/131] (497s left) kms_flip (2x-flip-vs-expired-vblank-interruptible)
[536.350203] Starting subtest: 2x-flip-vs-expired-vblank-interruptible
[536.362014] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.011s)
[536.448367] [123/131] (497s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[536.540187] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[543.867027] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.328s)
[543.994368] [124/131] (489s left) gem_ctx_param (set-priority-not-supported)
[544.048603] Starting subtest: set-priority-not-supported
[544.053835] Subtest set-priority-not-supported: SKIP (0.000s)
[544.122107] [125/131] (489s left) gem_ppgtt (shrink-vs-evict-any)
[544.174807] Starting subtest: shrink-vs-evict-any
[551.931937] Subtest shrink-vs-evict-any: SUCCESS (7.754s)
[552.020132] [126/131] (481s left) gem_spin_batch (resubmit-all)
[552.084994] Starting subtest: resubmit-all
[552.087969] Starting dynamic subtest: rcs0
[552.106972] Dynamic subtest rcs0: SUCCESS (0.022s)
[552.120494] Starting dynamic subtest: bcs0
[552.123012] Dynamic subtest bcs0: SUCCESS (0.003s)
[552.133449] Starting dynamic subtest: vcs0
[552.135910] Dynamic subtest vcs0: SUCCESS (0.004s)
[552.145889] Starting dynamic subtest: vcs1
[552.148683] Dynamic subtest vcs1: SUCCESS (0.003s)
[552.159315] Starting dynamic subtest: vecs0
[552.161837] Dynamic subtest vecs0: SUCCESS (0.002s)
[552.171899] Starting dynamic subtest: ccs0
[552.174706] Dynamic subtest ccs0: SUCCESS (0.002s)
[552.177321] Subtest resubmit-all: SUCCESS (0.041s)
[554.045103] [127/131] (479s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[554.137317] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[554.139901] Starting dynamic subtest: pipe-A-eDP-1
[554.167616] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[554.177822] Starting dynamic subtest: pipe-B-eDP-1
[554.206403] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[554.216401] Starting dynamic subtest: pipe-C-eDP-1
[554.244296] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[554.253803] Starting dynamic subtest: pipe-D-eDP-1
[554.281745] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[554.286815] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[556.347495] [128/131] (477s left) vgem_basic (dmabuf-mmap)
[556.397803] Starting subtest: dmabuf-mmap
[556.436756] Subtest dmabuf-mmap: SUCCESS (0.041s)
[556.494040] [129/131] (477s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[556.554179] Starting subtest: yf-tiled-to-vebox-x-tiled
[556.558387] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[556.742804] [130/131] (477s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[556.848581] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[561.096229] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.252s)
[562.233666] [131/131] (471s left) gem_exec_schedule (noreorder-priority)
[562.430753] Starting subtest: noreorder-priority
[562.433980] Starting dynamic subtest: rcs0
[563.557483] Dynamic subtest rcs0: SUCCESS (1.126s)
[563.571635] Starting dynamic subtest: vcs0
[564.996386] Dynamic subtest vcs0: SUCCESS (1.436s)
[565.009852] Starting dynamic subtest: vcs1
[566.986012] Dynamic subtest vcs1: SUCCESS (1.986s)
[566.999261] Starting dynamic subtest: vecs0
[568.946408] Dynamic subtest vecs0: SUCCESS (1.958s)
[568.958992] Starting dynamic subtest: ccs0
[570.084137] Dynamic subtest ccs0: SUCCESS (1.136s)
[570.086915] Subtest noreorder-priority: SUCCESS (7.652s)
[570.268676] Closing watchdogs
results: parsing output: 0/ for test: kms_psr
results: parsing output: 1/ for test: gem_exec_reloc
results: parsing output: 2/ for test: gen7_exec_parse
results: parsing output: 3/ for test: gem_lmem_swapping
results: parsing output: 4/ for test: kms_cursor_legacy
results: parsing output: 5/ for test: gem_exec_balancer
results: parsing output: 6/ for test: kms_psr
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: kms_flip_scaled_crc
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_content_protection
results: parsing output: 12/ for test: kms_rotation_crc
results: parsing output: 13/ for test: gem_render_copy
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: kms_psr2_sf
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_exec_params
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_busy
results: parsing output: 21/ for test: syncobj_timeline
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: core_setmaster
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: kms_plane_scaling
results: parsing output: 26/ for test: kms_force_connector_basic
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: i915_query
results: parsing output: 29/ for test: kms_plane_scaling
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: gem_ctx_persistence
results: parsing output: 32/ for test: gem_render_copy_redux
results: parsing output: 33/ for test: kms_pm_rpm
results: parsing output: 34/ for test: kms_rotation_crc
results: parsing output: 35/ for test: gem_fence_thrash
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: gem_mmap_gtt
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: kms_chamelium_hpd
results: parsing output: 42/ for test: kms_feature_discovery
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_ccs
results: parsing output: 45/ for test: kms_lease
results: parsing output: 46/ for test: gem_partial_pwrite_pread
results: parsing output: 47/ for test: kms_async_flips
results: parsing output: 48/ for test: gem_eio
results: parsing output: 49/ for test: kms_invalid_mode
results: parsing output: 50/ for test: gem_exec_whisper
results: parsing output: 51/ for test: gem_create
results: parsing output: 52/ for test: kms_flip
results: parsing output: 53/ for test: i915_drm_fdinfo
results: parsing output: 54/ for test: kms_psr2_su
results: parsing output: 55/ for test: kms_pm_rpm
results: parsing output: 56/ for test: gem_blits
results: parsing output: 57/ for test: gem_exec_parallel
results: parsing output: 58/ for test: kms_plane
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: api_intel_allocator
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: gem_exec_store
results: parsing output: 66/ for test: gem_render_copy
results: parsing output: 67/ for test: perf
results: parsing output: 68/ for test: kms_flip_scaled_crc
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_flip
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: i915_pciid
results: parsing output: 73/ for test: gem_userptr_blits
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: gem_ctx_persistence
results: parsing output: 76/ for test: kms_prop_blob
results: parsing output: 77/ for test: kms_cursor_crc
results: parsing output: 78/ for test: kms_dp_link_training
results: parsing output: 79/ for test: gem_exec_params
results: parsing output: 80/ for test: gem_userptr_blits
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_properties
results: parsing output: 84/ for test: gem_eio
results: parsing output: 85/ for test: gem_ppgtt
results: parsing output: 86/ for test: i915_pm_rpm
results: parsing output: 87/ for test: gem_exec_flush
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_big_fb
results: parsing output: 90/ for test: gem_exec_params
results: parsing output: 91/ for test: perf_pmu
results: parsing output: 92/ for test: syncobj_eventfd
results: parsing output: 93/ for test: kms_selftest
results: parsing output: 94/ for test: kms_atomic
results: parsing output: 95/ for test: kms_plane_multiple
results: parsing output: 96/ for test: kms_big_fb
results: parsing output: 97/ for test: gem_mmap_wc
results: parsing output: 98/ for test: gem_exec_balancer
results: parsing output: 99/ for test: kms_color
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: gem_ctx_param
results: parsing output: 102/ for test: perf
results: parsing output: 103/ for test: gem_exec_fence
results: parsing output: 104/ for test: perf_pmu
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: prime_mmap
results: parsing output: 108/ for test: gem_exec_reloc
results: parsing output: 109/ for test: syncobj_wait
results: parsing output: 110/ for test: kms_ccs
results: parsing output: 111/ for test: kms_vblank
results: parsing output: 112/ for test: kms_cursor_crc
results: parsing output: 113/ for test: kms_pipe_crc_basic
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: sysfs_timeslice_duration
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_chamelium_edid
results: parsing output: 118/ for test: api_intel_bb
results: parsing output: 119/ for test: gem_exec_schedule
results: parsing output: 120/ for test: gem_tiled_blits
results: parsing output: 121/ for test: kms_flip
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gem_ctx_param
results: parsing output: 124/ for test: gem_ppgtt
results: parsing output: 125/ for test: gem_spin_batch
results: parsing output: 126/ for test: kms_ccs
results: parsing output: 127/ for test: vgem_basic
results: parsing output: 128/ for test: gem_render_copy
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_exec_schedule
Done.