[56.950331] Initializing watchdogs
[56.950377]   /dev/watchdog0
[56.965501] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7dd5 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Graphics]
[56.969739] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.972078] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.975583] [001/155] (960s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-blt)
[57.091998] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-blt
[57.096274] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[57.183379] [002/155] (960s left) kms_chamelium_frames (hdmi-crc-single)
[57.553251] Subtest hdmi-crc-single: SKIP (0.000s)
[58.368633] [003/155] (959s left) kms_chamelium_audio (hdmi-audio-edid)
[58.753945] Subtest hdmi-audio-edid: SKIP (0.000s)
[59.607597] [004/155] (957s left) kms_addfb_basic (basic-x-tiled-legacy)
[59.666632] Starting subtest: basic-x-tiled-legacy
[59.671371] Subtest basic-x-tiled-legacy: SKIP (0.000s)
[59.792434] [005/155] (957s left) i915_pm_rpm (system-suspend-devices)
[61.423842] Starting subtest: system-suspend-devices
[69.234383] Subtest system-suspend-devices: SUCCESS (7.813s)
[69.634383] [006/155] (948s left) kms_frontbuffer_tracking (fbchdr-suspend)
[69.727589] Starting subtest: fbchdr-suspend
[69.731857] Subtest fbchdr-suspend: SKIP (0.000s)
[69.833407] [007/155] (947s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[69.915890] Starting subtest: psr-modesetfrombusy
[76.187615] Subtest psr-modesetfrombusy: SUCCESS (6.279s)
[76.313451] [008/155] (941s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-pwrite)
[76.404201] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-pwrite
[76.407783] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[76.512525] [009/155] (941s left) kms_addfb_basic (bo-too-small)
[76.588251] Starting subtest: bo-too-small
[76.590213] Subtest bo-too-small: SUCCESS (0.000s)
[76.691888] [010/155] (941s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[76.755021] Starting subtest: plane-upscale-20x20-with-rotation
[76.756823] Starting dynamic subtest: pipe-A
[76.979773] Dynamic subtest pipe-A: SUCCESS (0.227s)
[76.989728] Starting dynamic subtest: pipe-B
[78.268900] Dynamic subtest pipe-B: SUCCESS (1.288s)
[78.279390] Starting dynamic subtest: pipe-C
[79.598305] Dynamic subtest pipe-C: SUCCESS (1.329s)
[79.608339] Starting dynamic subtest: pipe-D
[80.919276] Dynamic subtest pipe-D: SUCCESS (1.321s)
[80.921764] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.166s)
[82.110347] [011/155] (935s left) gem_ctx_isolation (nonpriv-switch)
[82.174394] Starting subtest: nonpriv-switch
[82.176944] Starting dynamic subtest: rcs0
[82.455073] Dynamic subtest rcs0: SUCCESS (0.281s)
[82.464354] Starting dynamic subtest: bcs0
[82.754101] Dynamic subtest bcs0: SUCCESS (0.298s)
[82.763589] Starting dynamic subtest: vcs0
[83.034598] Dynamic subtest vcs0: SUCCESS (0.281s)
[83.048432] Starting dynamic subtest: vcs1
[84.475948] Dynamic subtest vcs1: SUCCESS (1.440s)
[84.485856] Starting dynamic subtest: vecs0
[86.484640] Dynamic subtest vecs0: SUCCESS (2.008s)
[86.494105] Starting dynamic subtest: ccs0
[88.560721] Dynamic subtest ccs0: SUCCESS (2.076s)
[88.562977] Subtest nonpriv-switch: SUCCESS (6.386s)
[88.797990] [012/155] (929s left) kms_frontbuffer_tracking (psr-2p-rte)
[88.872128] Starting subtest: psr-2p-rte
[88.876350] Subtest psr-2p-rte: SKIP (0.000s)
[88.974902] [013/155] (928s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[89.055183] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[89.059392] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[89.137237] [014/155] (928s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[89.224141] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite
[89.228516] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[89.318142] [015/155] (928s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[89.388896] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[89.393371] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[89.457528] [016/155] (928s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[89.541423] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[89.545559] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[89.649438] [017/155] (928s left) gem_mmap_offset (partial-remap)
[89.707538] Starting subtest: partial-remap
[89.709486] Starting dynamic subtest: smem0
[89.716833] Dynamic subtest smem0: SUCCESS (0.004s)
[89.718541] Subtest partial-remap: SUCCESS (0.005s)
[89.812841] [018/155] (928s left) i915_selftest (perf)
[93.258347] Starting subtest: perf
[93.260764] Starting dynamic subtest: engine_cs
[97.596587] Dynamic subtest engine_cs: SUCCESS (4.338s)
[97.606047] Starting dynamic subtest: request
[104.959602] Dynamic subtest request: SUCCESS (7.362s)
[104.969850] Starting dynamic subtest: migrate
[109.319605] Dynamic subtest migrate: SUCCESS (4.358s)
[109.329079] Starting dynamic subtest: region
[114.961924] Dynamic subtest region: SUCCESS (5.642s)
[114.964940] Subtest perf: SUCCESS (21.705s)
[114.992590] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[114.995716] [019/155] (903s left) kms_scaling_modes (scaling-mode-full)
[116.540298] Starting subtest: scaling-mode-full
[116.542347] Starting dynamic subtest: pipe-A-eDP-1
[116.703266] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.163s)
[116.716305] Starting dynamic subtest: pipe-B-eDP-1
[117.916898] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.213s)
[117.930450] Starting dynamic subtest: pipe-C-eDP-1
[119.122713] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.203s)
[119.132624] Starting dynamic subtest: pipe-D-eDP-1
[120.265227] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.140s)
[120.267496] Subtest scaling-mode-full: SUCCESS (3.727s)
[121.468785] [FACT kms_scaling_modes (scaling-mode-full)] new: kernel.kmod_is_loaded.i915: true
[121.473079] [020/155] (896s left) kms_addfb_basic (invalid-set-prop)
[121.534301] Starting subtest: invalid-set-prop
[121.536667] Subtest invalid-set-prop: SUCCESS (0.000s)
[121.656698] [021/155] (896s left) gem_lmem_swapping (heavy-verify-random)
[121.700696] Subtest heavy-verify-random: SKIP (0.000s)
[121.739774] [022/155] (896s left) kms_cursor_crc (cursor-sliding-512x512)
[121.979905] Starting subtest: cursor-sliding-512x512
[121.984529] Subtest cursor-sliding-512x512: SKIP (0.001s)
[122.153624] [023/155] (896s left) gem_pwrite (basic-random)
[122.210240] Subtest basic-random: SKIP (0.000s)
[122.272207] [024/155] (896s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-blt)
[122.350852] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-blt
[122.354310] Subtest hdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[122.437100] [025/155] (895s left) kms_color (ctm-0-25)
[122.519382] Starting subtest: ctm-0-25
[122.521393] Starting dynamic subtest: pipe-A-eDP-1
[126.035436] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.515s)
[126.045475] Starting dynamic subtest: pipe-B-eDP-1
[130.318400] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.281s)
[130.327301] Starting dynamic subtest: pipe-C-eDP-1
[134.363083] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.045s)
[134.372541] Starting dynamic subtest: pipe-D-eDP-1
[138.465767] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.102s)
[138.468179] Subtest ctm-0-25: SUCCESS (15.946s)
[139.285112] [026/155] (879s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[139.369879] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[139.372214] Starting dynamic subtest: pipe-A-eDP-1
[139.454813] Dynamic subtest pipe-A-eDP-1: SKIP (0.089s)
[139.463960] Starting dynamic subtest: pipe-B-eDP-1
[140.571371] Dynamic subtest pipe-B-eDP-1: SKIP (1.113s)
[140.576219] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.204s)
[141.763206] [027/155] (876s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[141.844316] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[141.848809] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[141.936954] [028/155] (876s left) kms_psr (psr2-primary-blt)
[142.014040] Starting subtest: psr2-primary-blt
[142.016246] Starting dynamic subtest: eDP-1
[143.771220] Dynamic subtest eDP-1: SUCCESS (1.755s)
[143.773517] Subtest psr2-primary-blt: SUCCESS (1.756s)
[144.629755] [029/155] (873s left) api_intel_allocator (alloc-simple)
[144.677829] Starting subtest: alloc-simple
[144.680118] Subtest alloc-simple: SUCCESS (0.002s)
[144.796036] [030/155] (873s left) gem_request_retire (retire-vma-not-inactive)
[144.843409] Starting subtest: retire-vma-not-inactive
[144.904087] Subtest retire-vma-not-inactive: SUCCESS (0.058s)
[145.026551] [031/155] (873s left) perf_pmu (enable-race)
[145.064990] Starting subtest: enable-race
[145.067254] Starting dynamic subtest: rcs0
[157.838790] Dynamic subtest rcs0: SUCCESS (12.774s)
[157.847645] Starting dynamic subtest: bcs0
[170.579877] Dynamic subtest bcs0: SUCCESS (12.740s)
[170.588652] Starting dynamic subtest: vcs0
[186.236769] Dynamic subtest vcs0: SUCCESS (15.655s)
[186.252149] Starting dynamic subtest: vcs1
[202.239690] Dynamic subtest vcs1: SUCCESS (16.000s)
[202.249549] Starting dynamic subtest: vecs0
[218.174279] Dynamic subtest vecs0: SUCCESS (15.932s)
[218.186976] Starting dynamic subtest: ccs0
[230.957543] Dynamic subtest ccs0: SUCCESS (12.783s)
[230.960020] Subtest enable-race: SUCCESS (85.894s)
[231.059330] [032/155] (787s left) i915_drm_fdinfo (busy-idle-check-all)
[231.097473] Starting subtest: busy-idle-check-all
[231.099869] Starting dynamic subtest: rcs0
[231.104649] Dynamic subtest rcs0: SKIP (0.004s)
[231.114230] Starting dynamic subtest: bcs0
[231.118814] Dynamic subtest bcs0: SKIP (0.000s)
[231.127524] Starting dynamic subtest: vcs0
[231.131884] Dynamic subtest vcs0: SKIP (0.000s)
[231.140244] Starting dynamic subtest: vcs1
[231.144671] Dynamic subtest vcs1: SKIP (0.001s)
[231.153304] Starting dynamic subtest: vecs0
[231.157343] Dynamic subtest vecs0: SKIP (0.000s)
[231.165422] Starting dynamic subtest: ccs0
[231.169584] Dynamic subtest ccs0: SKIP (0.000s)
[231.173726] Subtest busy-idle-check-all: SKIP (0.008s)
[231.208826] [033/155] (787s left) gem_partial_pwrite_pread (write-uncached)
[231.301018] Subtest write-uncached: SKIP (0.000s)
[231.375624] [034/155] (787s left) kms_addfb_basic (unused-handle)
[231.428411] Starting subtest: unused-handle
[231.430557] Subtest unused-handle: SUCCESS (0.000s)
[231.580293] [035/155] (787s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[231.662589] Starting subtest: fbcpsr-2p-rte
[231.667063] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[231.758253] [036/155] (787s left) kms_hdr (bpc-switch)
[231.825848] Starting subtest: bpc-switch
[232.907094] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[236.307665] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (3.403s)
[237.459465] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[240.938622] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (3.489s)
[240.941086] Subtest bpc-switch: SUCCESS (9.115s)
[241.047778] [037/155] (777s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-blt)
[241.131991] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-blt
[241.136517] Subtest hdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[241.230432] [038/155] (777s left) gem_exec_whisper (basic-fds-priority)
[241.289940] Starting subtest: basic-fds-priority
[264.369831] Subtest basic-fds-priority: SUCCESS (23.075s)
[264.574399] [039/155] (754s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-pwrite)
[264.666539] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-pwrite
[264.670928] Subtest hdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[264.757763] [040/155] (754s left) kms_ccs (bad-aux-stride-yf-tiled-ccs)
[264.839977] Starting subtest: bad-aux-stride-yf-tiled-ccs
[264.843171] Starting dynamic subtest: pipe-A-eDP-1
[264.865927] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[264.877494] Starting dynamic subtest: pipe-B-eDP-1
[264.900483] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[264.909199] Starting dynamic subtest: pipe-C-eDP-1
[264.931227] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[264.940100] Starting dynamic subtest: pipe-D-eDP-1
[264.962411] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[264.966486] Subtest bad-aux-stride-yf-tiled-ccs: SKIP (0.004s)
[267.160195] [041/155] (751s left) dumb_buffer (invalid-bpp)
[267.220421] Starting subtest: invalid-bpp
[267.222401] Subtest invalid-bpp: SUCCESS (0.000s)
[267.312218] [042/155] (751s left) gem_exec_balancer (parallel-balancer)
[267.577432] Starting subtest: parallel-balancer
[268.467433] Subtest parallel-balancer: SUCCESS (0.890s)
[268.584448] [043/155] (750s left) kms_color_pipeline (plane-lut1d-ctm3x4-lut1d)
[268.660162] Starting subtest: plane-lut1d-ctm3x4-lut1d
[268.818928] Starting dynamic subtest: pipe-A-plane-0
[269.017644] Dynamic subtest pipe-A-plane-0: SUCCESS (0.200s)
[269.027567] Starting dynamic subtest: pipe-A-plane-1
[269.207122] Dynamic subtest pipe-A-plane-1: SUCCESS (0.188s)
[269.216741] Starting dynamic subtest: pipe-A-plane-2
[269.373419] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[270.568837] Starting dynamic subtest: pipe-B-plane-0
[270.738780] Dynamic subtest pipe-B-plane-0: SUCCESS (0.177s)
[270.749612] Starting dynamic subtest: pipe-B-plane-1
[270.894479] Dynamic subtest pipe-B-plane-1: SUCCESS (0.155s)
[270.904299] Starting dynamic subtest: pipe-B-plane-2
[271.060983] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[272.302415] Starting dynamic subtest: pipe-C-plane-0
[272.438993] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[272.449985] Starting dynamic subtest: pipe-C-plane-1
[272.604753] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s)
[272.614253] Starting dynamic subtest: pipe-C-plane-2
[272.749567] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[273.947388] Starting dynamic subtest: pipe-D-plane-0
[274.105688] Dynamic subtest pipe-D-plane-0: SUCCESS (0.166s)
[274.118446] Starting dynamic subtest: pipe-D-plane-1
[274.250810] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[274.260115] Starting dynamic subtest: pipe-D-plane-2
[274.394797] Dynamic subtest pipe-D-plane-2: SUCCESS (0.144s)
[274.683269] Subtest plane-lut1d-ctm3x4-lut1d: SUCCESS (6.023s)
[275.485722] [044/155] (743s left) kms_flip (plain-flip-ts-check)
[275.565661] Starting subtest: plain-flip-ts-check
[275.567634] Starting dynamic subtest: A-eDP1
[284.486210] Dynamic subtest A-eDP1: SUCCESS (8.922s)
[284.495659] Starting dynamic subtest: B-eDP1
[293.413950] Dynamic subtest B-eDP1: SUCCESS (8.926s)
[293.424632] Starting dynamic subtest: C-eDP1
[302.235329] Dynamic subtest C-eDP1: SUCCESS (8.819s)
[302.246228] Starting dynamic subtest: D-eDP1
[311.081330] Dynamic subtest D-eDP1: SUCCESS (8.846s)
[311.084007] Subtest plain-flip-ts-check: SUCCESS (35.522s)
[312.255320] [045/155] (706s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[312.333771] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[312.335949] Starting dynamic subtest: pipe-A-eDP-1
[313.211935] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.871s)
[313.222230] Starting dynamic subtest: pipe-B-eDP-1
[314.853870] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.641s)
[314.863335] Starting dynamic subtest: pipe-C-eDP-1
[316.367121] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.510s)
[316.377580] Starting dynamic subtest: pipe-D-eDP-1
[317.916964] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.549s)
[317.919097] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.575s)
[319.073408] [046/155] (700s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[319.158969] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[319.160925] Starting dynamic subtest: pipe-A-eDP-1
[320.517352] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.357s)
[320.792492] Starting dynamic subtest: pipe-B-eDP-1
[321.871987] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.088s)
[322.140604] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.980s)
[322.969438] [047/155] (696s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions-varying-s
[323.074789] Starting subtest: short-busy-flip-before-cursor-atomic-transitions-varying-size
[323.079491] Subtest short-busy-flip-before-cursor-atomic-transitions-varying-size: SKIP (0.000s)
[323.219366] [048/155] (696s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-blt)
[323.308240] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-blt
[323.312264] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[323.389263] [049/155] (695s left) syncobj_wait (multi-wait-all-submitted)
[323.444360] Starting subtest: multi-wait-all-submitted
[323.554793] Subtest multi-wait-all-submitted: SUCCESS (0.108s)
[323.626536] [050/155] (695s left) kms_async_flips (test-time-stamp-atomic)
[323.702624] Starting subtest: test-time-stamp-atomic
[324.089229] Starting dynamic subtest: pipe-A-eDP-1
[324.863724] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.774s)
[325.251828] Starting dynamic subtest: pipe-B-eDP-1
[326.098182] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.851s)
[326.473994] Starting dynamic subtest: pipe-C-eDP-1
[327.326476] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.857s)
[327.694195] Starting dynamic subtest: pipe-D-eDP-1
[328.464409] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.775s)
[328.466896] Subtest test-time-stamp-atomic: SUCCESS (4.762s)
[329.613561] [051/155] (689s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[329.686538] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[329.690769] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.000s)
[329.755900] [052/155] (689s left) kms_lease (empty-lease)
[329.829059] Starting subtest: empty-lease
[329.831434] Starting dynamic subtest: pipe-A-eDP-1
[329.833795] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[329.844075] Starting dynamic subtest: pipe-B-eDP-1
[329.845843] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[329.854450] Starting dynamic subtest: pipe-C-eDP-1
[329.856323] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[329.865027] Starting dynamic subtest: pipe-D-eDP-1
[329.866923] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[329.868742] Subtest empty-lease: SUCCESS (0.014s)
[329.933634] [053/155] (689s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[330.011192] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[330.015134] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[330.110701] [054/155] (689s left) gem_exec_balancer (parallel-out-fence)
[330.366663] Starting subtest: parallel-out-fence
[331.536532] Subtest parallel-out-fence: SUCCESS (1.170s)
[331.638987] [055/155] (687s left) prime_vgem (sync)
[331.786959] Starting subtest: sync
[331.789202] Starting dynamic subtest: rcs0
[331.826486] Dynamic subtest rcs0: SUCCESS (0.042s)
[331.835798] Starting dynamic subtest: bcs0
[331.871786] Dynamic subtest bcs0: SUCCESS (0.044s)
[331.881041] Starting dynamic subtest: vcs0
[331.916554] Dynamic subtest vcs0: SUCCESS (0.044s)
[331.926163] Starting dynamic subtest: vcs1
[331.964333] Dynamic subtest vcs1: SUCCESS (0.047s)
[331.973609] Starting dynamic subtest: vecs0
[332.010298] Dynamic subtest vecs0: SUCCESS (0.045s)
[332.019400] Starting dynamic subtest: ccs0
[332.056971] Dynamic subtest ccs0: SUCCESS (0.046s)
[332.059297] Subtest sync: SUCCESS (0.273s)
[332.171489] [FACT prime_vgem (sync)] new: kernel.kmod_is_loaded.vgem: true
[332.176618] [056/155] (687s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-blt)
[332.251327] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-blt
[332.255344] Subtest hdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[332.341401] [057/155] (687s left) kms_addfb_basic (no-handle)
[332.397124] Starting subtest: no-handle
[332.399261] Subtest no-handle: SUCCESS (0.000s)
[332.519295] [058/155] (687s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[332.591899] Starting subtest: 4-tiled-32bpp-rotate-0
[333.982644] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.390s)
[334.105148] [059/155] (685s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[334.173106] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[334.176760] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[335.351245] [060/155] (684s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[335.441744] Starting subtest: psr-1p-pri-indfb-multidraw
[358.362135] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (22.928s)
[358.474917] [061/155] (661s left) gem_exec_schedule (in-order)
[358.541063] Starting subtest: in-order
[358.542580] Starting dynamic subtest: rcs0
[358.639582] Dynamic subtest rcs0: SUCCESS (0.102s)
[358.648508] Starting dynamic subtest: vcs0
[358.726661] Dynamic subtest vcs0: SUCCESS (0.085s)
[358.741488] Starting dynamic subtest: vcs1
[358.803660] Dynamic subtest vcs1: SUCCESS (0.077s)
[358.814226] Starting dynamic subtest: vecs0
[358.883315] Dynamic subtest vecs0: SUCCESS (0.078s)
[358.894394] Starting dynamic subtest: ccs0
[358.966043] Dynamic subtest ccs0: SUCCESS (0.080s)
[358.968501] Subtest in-order: SUCCESS (0.429s)
[360.293522] [062/155] (659s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[360.363896] Starting subtest: map-fixed-invalidate-overlap-busy
[360.368351] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[360.488804] [063/155] (659s left) gem_mmap_gtt (basic-wc)
[360.543128] Subtest basic-wc: SKIP (0.000s)
[360.602960] [064/155] (659s left) kms_psr (psr2-cursor-mmap-cpu)
[360.682664] Starting subtest: psr2-cursor-mmap-cpu
[360.684876] Starting dynamic subtest: eDP-1
[362.375709] Dynamic subtest eDP-1: SUCCESS (1.692s)
[362.378088] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.693s)
[363.258089] [065/155] (656s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[363.326975] Starting subtest: fbc-pr-sprite-mmap-gtt
[363.331359] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[363.400444] [066/155] (656s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[363.482156] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[363.486110] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[363.604759] [067/155] (656s left) kms_lease (lessee-list)
[363.674966] Starting subtest: lessee-list
[363.677333] Starting dynamic subtest: pipe-A-eDP-1
[363.679690] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[363.689740] Starting dynamic subtest: pipe-B-eDP-1
[363.691632] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[363.700467] Starting dynamic subtest: pipe-C-eDP-1
[363.702327] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[363.711414] Starting dynamic subtest: pipe-D-eDP-1
[363.713315] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[363.715143] Subtest lessee-list: SUCCESS (0.008s)
[363.776188] [068/155] (656s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[363.868400] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[363.872499] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[363.984960] [069/155] (656s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[364.061218] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[364.065608] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[364.163315] [070/155] (656s left) gem_wait (invalid-flags)
[364.217383] Starting subtest: invalid-flags
[364.219454] Subtest invalid-flags: SUCCESS (0.000s)
[364.413303] [071/155] (655s left) kms_frontbuffer_tracking (psr-slowdraw)
[364.501557] Starting subtest: psr-slowdraw
[389.664901] Subtest psr-slowdraw: SUCCESS (25.169s)
[389.801953] [072/155] (630s left) kms_rotation_crc (sprite-rotation-270)
[389.879038] Starting subtest: sprite-rotation-270
[389.883531] Subtest sprite-rotation-270: SKIP (0.001s)
[389.955775] [073/155] (630s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[390.001428] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[390.003933] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.001s)
[390.115790] [074/155] (630s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[390.222420] Starting subtest: 2x-long-flip-vs-cursor-atomic
[390.226931] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[390.369680] [075/155] (630s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[390.442326] Starting subtest: nonblocking-crc-frame-sequence
[390.444275] Starting dynamic subtest: pipe-A-eDP-1
[391.177318] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.733s)
[391.188328] Starting dynamic subtest: pipe-B-eDP-1
[392.602581] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.425s)
[392.612816] Starting dynamic subtest: pipe-C-eDP-1
[393.981072] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.376s)
[393.990804] Starting dynamic subtest: pipe-D-eDP-1
[395.321934] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.341s)
[395.324822] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.881s)
[396.127906] [076/155] (624s left) syncobj_timeline (multi-wait-all-available-submitted)
[396.183137] Starting subtest: multi-wait-all-available-submitted
[396.185563] Subtest multi-wait-all-available-submitted: SUCCESS (0.001s)
[396.289868] [077/155] (624s left) gem_exec_schedule (reorder-wide)
[396.464675] Subtest reorder-wide: SKIP (0.000s)
[396.656918] [078/155] (623s left) kms_big_fb (linear-64bpp-rotate-270)
[396.738944] Starting subtest: linear-64bpp-rotate-270
[396.760786] Subtest linear-64bpp-rotate-270: SKIP (0.021s)
[396.859533] [079/155] (623s left) gem_spin_batch (user-each)
[396.919332] Starting subtest: user-each
[400.188367] Subtest user-each: SUCCESS (3.261s)
[401.287376] [080/155] (619s left) kms_plane (plane-position-hole)
[401.358087] Starting subtest: plane-position-hole
[401.887335] Starting dynamic subtest: pipe-A-plane-1
[403.201230] Dynamic subtest pipe-A-plane-1: SUCCESS (1.314s)
[403.213656] Starting dynamic subtest: pipe-A-plane-2
[404.517050] Dynamic subtest pipe-A-plane-2: SUCCESS (1.316s)
[404.527718] Starting dynamic subtest: pipe-A-plane-3
[405.752205] Dynamic subtest pipe-A-plane-3: SUCCESS (1.234s)
[405.762884] Starting dynamic subtest: pipe-A-plane-4
[407.022974] Dynamic subtest pipe-A-plane-4: SUCCESS (1.269s)
[407.033456] Starting dynamic subtest: pipe-A-plane-5
[407.047551] Dynamic subtest pipe-A-plane-5: SUCCESS (0.023s)
[408.278517] Starting dynamic subtest: pipe-B-plane-1
[409.584526] Dynamic subtest pipe-B-plane-1: SUCCESS (1.315s)
[409.594170] Starting dynamic subtest: pipe-B-plane-2
[410.950761] Dynamic subtest pipe-B-plane-2: SUCCESS (1.364s)
[410.960465] Starting dynamic subtest: pipe-B-plane-3
[412.223847] Dynamic subtest pipe-B-plane-3: SUCCESS (1.275s)
[412.237984] Starting dynamic subtest: pipe-B-plane-4
[413.493945] Dynamic subtest pipe-B-plane-4: SUCCESS (1.270s)
[413.504183] Starting dynamic subtest: pipe-B-plane-5
[413.520530] Dynamic subtest pipe-B-plane-5: SUCCESS (0.024s)
[413.522701] Subtest plane-position-hole: SUCCESS (12.163s)
[414.371566] [081/155] (606s left) gem_exec_reloc (basic-cpu-wc)
[414.440153] Subtest basic-cpu-wc: SKIP (0.000s)
[414.514179] [082/155] (606s left) gem_mmap_gtt (bad-object)
[414.576574] Subtest bad-object: SKIP (0.000s)
[414.632703] [083/155] (606s left) kms_psr (fbc-psr-sprite-plane-move)
[414.707079] Starting subtest: fbc-psr-sprite-plane-move
[414.709306] Starting dynamic subtest: eDP-1
[414.713335] Dynamic subtest eDP-1: SKIP (0.000s)
[414.717408] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[414.792702] [084/155] (605s left) kms_setmode (basic)
[414.846609] Starting subtest: basic
[415.173831] Starting dynamic subtest: pipe-A-eDP-1
[417.279177] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.100s)
[417.636835] Starting dynamic subtest: pipe-B-eDP-1
[419.710437] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.077s)
[420.021098] Subtest basic: SUCCESS (5.172s)
[420.857298] [085/155] (599s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[420.938484] Starting subtest: psr-2p-pri-indfb-multidraw
[420.942363] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[421.049751] [086/155] (599s left) syncobj_wait (multi-wait-all-submitted-signaled)
[421.093053] Starting subtest: multi-wait-all-submitted-signaled
[421.201796] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.108s)
[421.278622] [087/155] (599s left) kms_feature_discovery (display)
[421.346398] Starting subtest: display
[421.348751] Subtest display: SUCCESS (0.000s)
[421.421428] [088/155] (599s left) gem_sync (basic-many-each)
[421.492500] Starting subtest: basic-many-each
[423.752798] Subtest basic-many-each: SUCCESS (2.272s)
[423.936058] [089/155] (597s left) gem_pxp (reject-modify-context-protection-off-1)
[424.064881] Starting subtest: reject-modify-context-protection-off-1
[424.067510] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[424.186637] [090/155] (596s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[424.284271] Starting subtest: fbcpsr-rgb565-draw-pwrite
[432.062612] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (7.784s)
[432.196231] [091/155] (588s left) api_intel_bb (intel-bb-blit-y)
[432.249832] Starting subtest: intel-bb-blit-y
[435.794725] Subtest intel-bb-blit-y: SUCCESS (3.545s)
[435.910599] [092/155] (585s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[435.994610] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[435.998572] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[436.094954] [093/155] (585s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[436.145995] Starting subtest: y-tiled-to-vebox-y-tiled
[436.150495] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[436.342809] [094/155] (584s left) syncobj_timeline (signal-array)
[436.386737] Starting subtest: signal-array
[436.389153] Subtest signal-array: SUCCESS (0.000s)
[436.478727] [095/155] (584s left) gem_exec_params (invalid-bsd2-flag-on-render)
[436.530501] Starting subtest: invalid-bsd2-flag-on-render
[436.533049] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[436.631649] [096/155] (584s left) perf (non-zero-reason)
[436.750205] Starting subtest: non-zero-reason
[436.752083] Starting dynamic subtest: 0-rcs0
[442.095955] Dynamic subtest 0-rcs0: SUCCESS (5.346s)
[442.112932] Starting dynamic subtest: 1-vcs0
[445.977887] Dynamic subtest 1-vcs0: SUCCESS (3.881s)
[445.980886] Subtest non-zero-reason: SUCCESS (9.229s)
[446.029871] [097/155] (575s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-wc)
[446.118262] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-wc
[450.131070] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (4.018s)
[451.269026] [098/155] (570s left) gem_exec_fence (syncobj-timeline-repeat)
[451.822627] Starting subtest: syncobj-timeline-repeat
[452.054625] Subtest syncobj-timeline-repeat: SUCCESS (0.231s)
[453.343692] [099/155] (568s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-pwrite)
[453.426409] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-pwrite
[453.430504] Subtest fbchdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[453.526247] [100/155] (567s left) gem_basic (bad-close)
[453.579031] Starting subtest: bad-close
[453.583569] Subtest bad-close: SUCCESS (0.000s)
[453.693420] [101/155] (567s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[453.763757] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[453.768066] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[453.837081] [102/155] (567s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs-cc)
[453.911130] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs-cc
[453.913379] Starting dynamic subtest: pipe-A-eDP-1
[453.922245] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[453.933328] Starting dynamic subtest: pipe-B-eDP-1
[453.942240] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[453.952053] Starting dynamic subtest: pipe-C-eDP-1
[453.960752] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[453.970302] Starting dynamic subtest: pipe-D-eDP-1
[453.979049] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[453.983329] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[456.219512] [103/155] (565s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[456.309897] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[457.357544] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.048s)
[457.490161] [104/155] (564s left) gem_exec_params (invalid-bsd1-flag-on-vebox)
[457.550800] Starting subtest: invalid-bsd1-flag-on-vebox
[457.552841] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.000s)
[457.670427] [105/155] (563s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-pwrite)
[457.755591] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-pwrite
[457.759118] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[457.859120] [106/155] (563s left) gem_create (create-ext-placement-sanity-check)
[458.027068] Starting subtest: create-ext-placement-sanity-check
[458.029338] Subtest create-ext-placement-sanity-check: SUCCESS (0.004s)
[458.135582] [107/155] (563s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-cpu)
[458.209605] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-cpu
[458.214107] Subtest fbcpsrhdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[458.315984] [108/155] (563s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[458.390592] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[458.394597] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[458.486217] [109/155] (563s left) syncobj_timeline (wait-for-submit-snapshot)
[458.543585] Starting subtest: wait-for-submit-snapshot
[458.625104] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[458.704880] [110/155] (563s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[458.786035] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[458.788177] Starting dynamic subtest: pipe-A-eDP-1
[458.808057] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[458.818633] Starting dynamic subtest: pipe-B-eDP-1
[458.838367] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[458.848658] Starting dynamic subtest: pipe-C-eDP-1
[458.869081] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[458.879763] Starting dynamic subtest: pipe-D-eDP-1
[458.899460] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[458.901685] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.007s)
[461.075481] [111/155] (560s left) gem_ctx_bad_destroy (invalid-ctx)
[461.133257] Starting subtest: invalid-ctx
[461.135711] Subtest invalid-ctx: SUCCESS (0.000s)
[461.235471] [112/155] (560s left) gem_exec_params (invalid-flag)
[461.293881] Starting subtest: invalid-flag
[461.296201] Subtest invalid-flag: SUCCESS (0.000s)
[461.424479] [113/155] (560s left) gem_readwrite (new-obj)
[461.476292] Subtest new-obj: SKIP (0.000s)
[461.541578] [114/155] (560s left) kms_vblank (ts-continuation-dpms-rpm)
[461.610783] Starting subtest: ts-continuation-dpms-rpm
[461.612994] Starting dynamic subtest: pipe-A-eDP-1
[464.147108] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.533s)
[464.164235] Starting dynamic subtest: pipe-D-eDP-1
[466.409836] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.264s)
[466.412350] Subtest ts-continuation-dpms-rpm: SUCCESS (4.800s)
[467.304361] [115/155] (554s left) gem_exec_reloc (basic-wc-gtt-active)
[467.371317] Subtest basic-wc-gtt-active: SKIP (0.000s)
[467.441395] [116/155] (554s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[467.526193] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[467.530388] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[467.627850] [117/155] (554s left) sw_sync (sync_busy_fork)
[467.664441] Starting subtest: sync_busy_fork
[468.675791] Subtest sync_busy_fork: SUCCESS (1.013s)
[468.743913] [118/155] (553s left) gem_render_copy (x-tiled)
[468.791281] Starting subtest: x-tiled
[468.793742] Starting dynamic subtest: smem
[468.833865] Dynamic subtest smem: SUCCESS (0.043s)
[468.836156] Subtest x-tiled: SUCCESS (0.043s)
[469.056552] [119/155] (553s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[469.135200] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[469.139668] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[469.246564] [120/155] (553s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[469.305192] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[469.409777] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.103s)
[469.485966] [121/155] (552s left) gem_watchdog (default-virtual)
[469.573088] Starting subtest: default-virtual
[475.849309] Subtest default-virtual: SUCCESS (6.284s)
[475.943341] [122/155] (546s left) gem_userptr_blits (sync-unmap)
[476.001744] Starting subtest: sync-unmap
[476.017774] Subtest sync-unmap: SUCCESS (0.018s)
[476.131864] [123/155] (546s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-move)
[476.223672] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-move
[476.227813] Subtest fbchdr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[476.337221] [124/155] (546s left) i915_hangman (engine-error-state-capture)
[476.407837] Starting subtest: engine-error-state-capture
[476.410583] Starting dynamic subtest: rcs0
[480.220863] Dynamic subtest rcs0: SUCCESS (3.810s)
[480.232409] Starting dynamic subtest: bcs0
[486.172029] Dynamic subtest bcs0: SUCCESS (5.949s)
[486.186935] Starting dynamic subtest: vcs0
[492.188341] Dynamic subtest vcs0: SUCCESS (6.013s)
[492.201516] Starting dynamic subtest: vcs1
[498.140375] Dynamic subtest vcs1: SUCCESS (5.949s)
[498.153464] Starting dynamic subtest: vecs0
[503.196789] Dynamic subtest vecs0: SUCCESS (5.054s)
[503.209892] Starting dynamic subtest: ccs0
[509.212994] Dynamic subtest ccs0: SUCCESS (6.013s)
[509.215378] Subtest engine-error-state-capture: SUCCESS (32.806s)
[509.292915] [125/155] (513s left) fbdev (nullptr)
[509.337682] Starting subtest: nullptr
[509.339947] Subtest nullptr: SUCCESS (0.000s)
[509.396821] [126/155] (513s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[509.483707] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[509.487845] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[509.587523] [127/155] (512s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[509.670891] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu
[509.675296] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[509.763458] [128/155] (512s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[509.864370] Starting subtest: 2x-long-cursor-vs-flip-atomic
[509.868783] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.001s)
[510.009805] [129/155] (512s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-render)
[510.096911] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-render
[510.101147] Subtest psrhdr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[510.205481] [130/155] (512s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[510.283781] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[511.485088] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.201s)
[511.630237] [131/155] (511s left) kms_prime (basic-modeset-hybrid)
[511.696424] Subtest basic-modeset-hybrid: SKIP (0.000s)
[511.800414] [132/155] (510s left) gem_exec_alignment (forked)
[511.850869] Starting subtest: forked
[549.060326] Subtest forked: SUCCESS (37.208s)
[549.582795] [133/155] (473s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf)
[549.708441] Starting subtest: pr-overlay-plane-move-continuous-exceed-sf
[549.713398] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[549.776891] [134/155] (473s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[549.844714] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[549.873685] Starting dynamic subtest: pipe-A-plane-0
[550.261868] Dynamic subtest pipe-A-plane-0: SUCCESS (0.388s)
[550.272776] Starting dynamic subtest: pipe-A-plane-3
[550.605958] Dynamic subtest pipe-A-plane-3: SUCCESS (0.344s)
[551.671147] Starting dynamic subtest: pipe-B-plane-0
[552.074044] Dynamic subtest pipe-B-plane-0: SUCCESS (0.411s)
[552.086515] Starting dynamic subtest: pipe-B-plane-3
[552.374404] Dynamic subtest pipe-B-plane-3: SUCCESS (0.299s)
[552.686302] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (2.842s)
[553.544432] [135/155] (469s left) kms_frontbuffer_tracking (fbchdr-2p-pri-indfb-multidraw)
[553.629861] Starting subtest: fbchdr-2p-pri-indfb-multidraw
[553.634341] Subtest fbchdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[553.735054] [136/155] (469s left) kms_pm_dc (dc6-dpms)
[554.816585] Starting subtest: dc6-dpms
[556.921528] Subtest dc6-dpms: SUCCESS (2.105s)
[556.999421] [137/155] (465s left) kms_cursor_crc (cursor-offscreen-128x42)
[557.203576] Starting subtest: cursor-offscreen-128x42
[557.251710] Subtest cursor-offscreen-128x42: SKIP (0.043s)
[557.441376] [138/155] (465s left) kms_psr (fbc-pr-sprite-plane-move)
[557.512057] Starting subtest: fbc-pr-sprite-plane-move
[557.516631] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[557.590470] [139/155] (465s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[557.678404] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[557.682603] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[557.782899] [140/155] (465s left) gen7_exec_parse (batch-without-end)
[557.832715] Subtest batch-without-end: SKIP (0.000s)
[557.901554] [141/155] (465s left) perf (oa-formats)
[557.993531] Starting subtest: oa-formats
[557.996116] Starting dynamic subtest: 0-rcs0
[558.007125] Dynamic subtest 0-rcs0: SUCCESS (0.013s)
[558.017269] Starting dynamic subtest: 1-vcs1
[558.029868] Dynamic subtest 1-vcs1: SUCCESS (0.022s)
[558.031893] Subtest oa-formats: SUCCESS (0.037s)
[558.074785] [142/155] (465s left) gem_exec_reloc (basic-scanout)
[558.136133] Subtest basic-scanout: SKIP (0.000s)
[558.201336] [143/155] (465s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[558.310978] Starting subtest: cursorB-vs-flipA-varying-size
[558.314634] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[558.457093] [144/155] (464s left) kms_frontbuffer_tracking (fbchdr-1p-pri-indfb-multidraw)
[558.536869] Starting subtest: fbchdr-1p-pri-indfb-multidraw
[558.540542] Subtest fbchdr-1p-pri-indfb-multidraw: SKIP (0.000s)
[558.632222] [145/155] (464s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite)
[558.711105] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite
[558.714795] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[558.810523] [146/155] (464s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[558.862691] Starting subtest: legacy-engines-hostile-preempt
[558.864463] Starting dynamic subtest: render
[558.935211] Dynamic subtest render: SUCCESS (0.072s)
[558.950569] Starting dynamic subtest: bsd1
[560.184371] Dynamic subtest bsd1: SUCCESS (1.244s)
[560.198564] Starting dynamic subtest: bsd2
[562.231741] Dynamic subtest bsd2: SUCCESS (2.044s)
[562.244621] Starting dynamic subtest: blt
[562.285478] Dynamic subtest blt: SUCCESS (0.053s)
[562.297579] Starting dynamic subtest: vebox
[564.197096] Dynamic subtest vebox: SUCCESS (1.907s)
[564.199947] Subtest legacy-engines-hostile-preempt: SUCCESS (5.334s)
[564.326697] [147/155] (459s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[564.413131] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[564.417274] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[564.524780] [148/155] (458s left) kms_content_protection (dp-mst-type-1)
[564.657857] Starting subtest: dp-mst-type-1
[564.662482] Subtest dp-mst-type-1: SKIP (0.000s)
[564.772335] [149/155] (458s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[564.847849] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-wc
[564.852177] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[564.950208] [150/155] (458s left) kms_vblank (query-forked-busy-hang)
[565.023256] Starting subtest: query-forked-busy-hang
[565.025078] Starting dynamic subtest: pipe-A-eDP-1
[590.968063] Dynamic subtest pipe-A-eDP-1: SUCCESS (25.943s)
[590.983188] Starting dynamic subtest: pipe-D-eDP-1
[616.005896] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.036s)
[616.008475] Subtest query-forked-busy-hang: SUCCESS (50.984s)
[616.834331] [151/155] (406s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite)
[616.926694] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite
[616.930919] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[617.022031] [152/155] (406s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-onoff)
[617.104964] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-onoff
[617.109523] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[617.209065] [153/155] (406s left) kms_cursor_crc (cursor-random-64x21)
[617.377783] Starting subtest: cursor-random-64x21
[617.424132] Subtest cursor-random-64x21: SKIP (0.042s)
[617.643056] [154/155] (406s left) gem_softpin (noreloc-s3)
[617.705734] Starting subtest: noreloc-S3
[638.169739] Subtest noreloc-S3: SUCCESS (5.397s)
[638.298925] [155/155] (385s left) sw_sync (sync_multi_timeline_wait)
[638.348626] Starting subtest: sync_multi_timeline_wait
[638.350866] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[638.408644] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_chamelium_frames
results: parsing output: 2/ for test: kms_chamelium_audio
results: parsing output: 3/ for test: kms_addfb_basic
results: parsing output: 4/ for test: i915_pm_rpm
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_addfb_basic
results: parsing output: 9/ for test: kms_plane_scaling
results: parsing output: 10/ for test: gem_ctx_isolation
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_plane
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: gem_mmap_offset
results: parsing output: 17/ for test: i915_selftest
results: parsing output: 18/ for test: kms_scaling_modes
results: parsing output: 19/ for test: kms_addfb_basic
results: parsing output: 20/ for test: gem_lmem_swapping
results: parsing output: 21/ for test: kms_cursor_crc
results: parsing output: 22/ for test: gem_pwrite
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_color
results: parsing output: 25/ for test: kms_psr2_sf
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_psr
results: parsing output: 28/ for test: api_intel_allocator
results: parsing output: 29/ for test: gem_request_retire
results: parsing output: 30/ for test: perf_pmu
results: parsing output: 31/ for test: i915_drm_fdinfo
results: parsing output: 32/ for test: gem_partial_pwrite_pread
results: parsing output: 33/ for test: kms_addfb_basic
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_hdr
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_exec_whisper
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_ccs
results: parsing output: 40/ for test: dumb_buffer
results: parsing output: 41/ for test: gem_exec_balancer
results: parsing output: 42/ for test: kms_color_pipeline
results: parsing output: 43/ for test: kms_flip
results: parsing output: 44/ for test: kms_ccs
results: parsing output: 45/ for test: kms_atomic_transition
results: parsing output: 46/ for test: kms_cursor_legacy
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: syncobj_wait
results: parsing output: 49/ for test: kms_async_flips
results: parsing output: 50/ for test: kms_flip_scaled_crc
results: parsing output: 51/ for test: kms_lease
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: gem_exec_balancer
results: parsing output: 54/ for test: prime_vgem
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_addfb_basic
results: parsing output: 57/ for test: kms_big_fb
results: parsing output: 58/ for test: kms_ccs
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_exec_schedule
results: parsing output: 61/ for test: gem_userptr_blits
results: parsing output: 62/ for test: gem_mmap_gtt
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_psr
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_lease
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_wait
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_rotation_crc
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: kms_cursor_legacy
results: parsing output: 74/ for test: kms_pipe_crc_basic
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: gem_exec_schedule
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: gem_spin_batch
results: parsing output: 79/ for test: kms_plane
results: parsing output: 80/ for test: gem_exec_reloc
results: parsing output: 81/ for test: gem_mmap_gtt
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: kms_setmode
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: syncobj_wait
results: parsing output: 86/ for test: kms_feature_discovery
results: parsing output: 87/ for test: gem_sync
results: parsing output: 88/ for test: gem_pxp
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: api_intel_bb
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_render_copy
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: gem_exec_params
results: parsing output: 95/ for test: perf
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: gem_exec_fence
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_basic
results: parsing output: 100/ for test: kms_flip_scaled_crc
results: parsing output: 101/ for test: kms_ccs
results: parsing output: 102/ for test: kms_big_fb
results: parsing output: 103/ for test: gem_exec_params
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_create
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_big_fb
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_ccs
results: parsing output: 110/ for test: gem_ctx_bad_destroy
results: parsing output: 111/ for test: gem_exec_params
results: parsing output: 112/ for test: gem_readwrite
results: parsing output: 113/ for test: kms_vblank
results: parsing output: 114/ for test: gem_exec_reloc
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: sw_sync
results: parsing output: 117/ for test: gem_render_copy
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: gem_watchdog
results: parsing output: 121/ for test: gem_userptr_blits
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: i915_hangman
results: parsing output: 124/ for test: fbdev
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_cursor_legacy
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: kms_prime
results: parsing output: 131/ for test: gem_exec_alignment
results: parsing output: 132/ for test: kms_psr2_sf
results: parsing output: 133/ for test: kms_plane
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: kms_pm_dc
results: parsing output: 136/ for test: kms_cursor_crc
results: parsing output: 137/ for test: kms_psr
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: gen7_exec_parse
results: parsing output: 140/ for test: perf
results: parsing output: 141/ for test: gem_exec_reloc
results: parsing output: 142/ for test: kms_cursor_legacy
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: gem_ctx_persistence
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: kms_content_protection
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_vblank
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: kms_cursor_crc
results: parsing output: 153/ for test: gem_softpin
results: parsing output: 154/ for test: sw_sync
Done.