[61.523678] Initializing watchdogs
[61.523816]   /dev/watchdog0
[61.539185] [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.544468] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.546680] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.548754] [001/133] (960s left) kms_psr (fbc-psr2-cursor-blt)
[61.662772] Starting subtest: fbc-psr2-cursor-blt
[61.663694] Starting dynamic subtest: eDP-1
[61.665348] Dynamic subtest eDP-1: SKIP (0.000s)
[61.666950] Subtest fbc-psr2-cursor-blt: SKIP (0.000s)
[61.739958] [002/133] (960s left) gem_exec_balancer (parallel-bb-first)
[62.008040] Starting subtest: parallel-bb-first
[63.007962] Subtest parallel-bb-first: SUCCESS (1.002s)
[63.927128] [003/133] (958s left) kms_cursor_edge_walk (128x128-top-edge)
[64.008382] Starting subtest: 128x128-top-edge
[64.009356] Starting dynamic subtest: pipe-A-eDP-1
[67.423092] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.418s)
[67.430368] Starting dynamic subtest: pipe-D-eDP-1
[70.542982] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.120s)
[70.543893] Subtest 128x128-top-edge: SUCCESS (6.541s)
[71.333695] [004/133] (950s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier)
[71.417467] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier
[71.419154] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier: SKIP (0.000s)
[71.482809] [005/133] (950s left) gem_exec_parallel (contexts)
[71.538949] Starting subtest: contexts
[71.540399] Starting dynamic subtest: rcs0
[74.497999] Dynamic subtest rcs0: SUCCESS (2.950s)
[74.504499] Starting dynamic subtest: bcs0
[77.910296] Dynamic subtest bcs0: SUCCESS (3.412s)
[77.917060] Starting dynamic subtest: vcs0
[82.306389] Dynamic subtest vcs0: SUCCESS (4.394s)
[82.312924] Starting dynamic subtest: vcs1
[86.436099] Dynamic subtest vcs1: SUCCESS (4.129s)
[86.442553] Starting dynamic subtest: vecs0
[90.433941] Dynamic subtest vecs0: SUCCESS (3.995s)
[90.442481] Starting dynamic subtest: ccs0
[93.972504] Dynamic subtest ccs0: SUCCESS (3.536s)
[93.973853] Subtest contexts: SUCCESS (22.421s)
[94.140244] [006/133] (928s left) gem_exec_schedule (u-semaphore-resolve)
[94.220384] Starting subtest: u-semaphore-resolve
[94.285408] Subtest u-semaphore-resolve: SUCCESS (0.064s)
[94.621088] [007/133] (927s left) gem_exec_gttfill (engines)
[94.674197] Starting subtest: engines
[94.675146] Starting dynamic subtest: rcs0
[118.177027] Dynamic subtest rcs0: SUCCESS (23.499s)
[118.188630] Starting dynamic subtest: bcs0
[141.495870] Dynamic subtest bcs0: SUCCESS (23.317s)
[141.506859] Starting dynamic subtest: vcs0
[164.803788] Dynamic subtest vcs0: SUCCESS (23.305s)
[164.816529] Starting dynamic subtest: vcs1
[188.086042] Dynamic subtest vcs1: SUCCESS (23.280s)
[188.092921] Starting dynamic subtest: vecs0
[211.382831] Dynamic subtest vecs0: SUCCESS (23.294s)
[211.395531] Starting dynamic subtest: ccs0
[234.711083] Dynamic subtest ccs0: SUCCESS (23.326s)
[234.712143] Subtest engines: SUCCESS (140.028s)
[234.892584] [008/133] (787s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[235.042411] Starting subtest: yf-tiled-16bpp-rotate-0
[235.044122] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[235.131686] [009/133] (787s left) kms_big_fb (4-tiled-addfb)
[235.227508] Starting subtest: 4-tiled-addfb
[235.228468] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[235.331045] [010/133] (786s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[235.422347] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[235.423292] Starting dynamic subtest: pipe-A-eDP-1
[236.182565] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.757s)
[236.189601] Starting dynamic subtest: pipe-B-eDP-1
[237.998111] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.814s)
[238.009362] Starting dynamic subtest: pipe-C-eDP-1
[239.833216] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.832s)
[239.848213] Starting dynamic subtest: pipe-D-eDP-1
[241.655561] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.821s)
[241.656549] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.230s)
[242.789652] [011/133] (779s left) kms_content_protection (mei-interface)
[242.940342] Starting subtest: mei-interface
[242.948457] Subtest mei-interface: SKIP (0.007s)
[243.048503] [012/133] (779s left) i915_suspend (fence-restore-untiled)
[243.100063] Starting subtest: fence-restore-untiled
[243.101850] Subtest fence-restore-untiled: SKIP (0.000s)
[243.200310] [013/133] (779s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[243.281382] Starting subtest: fbc-psr-cursor-mmap-cpu
[243.282285] Starting dynamic subtest: eDP-1
[243.283945] Dynamic subtest eDP-1: SKIP (0.000s)
[243.285624] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.000s)
[243.345156] [014/133] (779s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[243.448207] Starting subtest: flip-vs-cursor-crc-legacy
[244.165511] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.717s)
[244.997922] [015/133] (777s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[245.099587] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[245.101529] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[245.187969] [016/133] (777s left) gem_mmap_gtt (basic-small-bo-tiledy)
[245.236677] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[245.284552] [017/133] (777s left) kms_pm_rpm (basic-rte)
[245.314796] Starting subtest: basic-rte
[248.581044] Subtest basic-rte: SUCCESS (3.267s)
[250.393278] [018/133] (772s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[250.439407] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[250.440432] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[250.521192] [019/133] (772s left) i915_drm_fdinfo (virtual-busy-idle)
[250.559198] Starting subtest: virtual-busy-idle
[250.561146] Subtest virtual-busy-idle: SKIP (0.000s)
[250.625545] [020/133] (772s left) kms_psr (pr-primary-render)
[250.718035] Starting subtest: pr-primary-render
[250.719823] Subtest pr-primary-render: SKIP (0.000s)
[250.783265] [021/133] (771s left) gem_exec_big (single)
[250.830661] Starting subtest: single
[254.275568] Subtest single: FAIL (3.423s)
[254.333586] [022/133] (768s left) i915_drm_fdinfo (busy-idle-check-all)
[254.411118] Starting subtest: busy-idle-check-all
[254.412141] Starting dynamic subtest: rcs0
[254.419133] Dynamic subtest rcs0: SKIP (0.008s)
[254.426342] Starting dynamic subtest: bcs0
[254.427964] Dynamic subtest bcs0: SKIP (0.001s)
[254.434343] Starting dynamic subtest: vcs0
[254.436053] Dynamic subtest vcs0: SKIP (0.000s)
[254.442343] Starting dynamic subtest: vcs1
[254.443989] Dynamic subtest vcs1: SKIP (0.000s)
[254.450535] Starting dynamic subtest: vecs0
[254.452284] Dynamic subtest vecs0: SKIP (0.000s)
[254.458455] Starting dynamic subtest: ccs0
[254.460202] Dynamic subtest ccs0: SKIP (0.000s)
[254.461931] Subtest busy-idle-check-all: SKIP (0.011s)
[254.488096] [023/133] (768s left) kms_async_flips (crc)
[254.594939] Starting subtest: crc
[254.967481] Starting dynamic subtest: pipe-A-eDP-1
[257.912860] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.945s)
[258.316505] Starting dynamic subtest: pipe-B-eDP-1
[261.240069] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.934s)
[261.624609] Starting dynamic subtest: pipe-C-eDP-1
[264.543392] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.926s)
[264.923077] Starting dynamic subtest: pipe-D-eDP-1
[267.774363] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.858s)
[267.775345] Subtest crc: SUCCESS (13.179s)
[268.926744] [024/133] (753s left) gem_userptr_blits (coherency-unsync)
[268.982856] Subtest coherency-unsync: SKIP (0.000s)
[269.101759] [025/133] (753s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[269.198031] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[269.199864] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[269.296351] [026/133] (753s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[269.378265] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[273.747744] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (4.369s)
[274.958258] [027/133] (747s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[275.046118] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[275.048093] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[275.110954] [028/133] (747s left) kms_feature_discovery (psr2)
[275.192104] Starting subtest: psr2
[275.193150] Subtest psr2: SUCCESS (0.000s)
[275.263435] [029/133] (747s left) kms_pm_rpm (pm-tiling)
[277.017804] Starting subtest: pm-tiling
[277.020362] Subtest pm-tiling: SKIP (0.000s)
[277.582774] [030/133] (745s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[277.672164] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[277.673899] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[277.770338] [031/133] (745s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[277.855811] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[284.490460] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.635s)
[284.613730] [032/133] (738s left) kms_color (invalid-gamma-lut-sizes)
[284.723334] Starting subtest: invalid-gamma-lut-sizes
[284.724177] Starting dynamic subtest: pipe-A
[285.023821] Dynamic subtest pipe-A: SUCCESS (0.301s)
[285.030579] Starting dynamic subtest: pipe-B
[285.031428] Dynamic subtest pipe-B: SUCCESS (0.000s)
[285.046189] Starting dynamic subtest: pipe-C
[285.046967] Dynamic subtest pipe-C: SUCCESS (0.000s)
[285.053962] Starting dynamic subtest: pipe-D
[285.054974] Dynamic subtest pipe-D: SUCCESS (0.000s)
[285.056095] Subtest invalid-gamma-lut-sizes: SUCCESS (0.303s)
[285.849993] [033/133] (737s left) kms_dsc (dsc-basic)
[285.936332] Starting subtest: dsc-basic
[285.941051] Subtest dsc-basic: SKIP (0.000s)
[286.000591] [034/133] (737s left) gem_ctx_persistence (legacy-engines-persistence)
[286.064496] Starting subtest: legacy-engines-persistence
[286.065345] Starting dynamic subtest: render
[286.868974] Dynamic subtest render: SUCCESS (0.803s)
[286.880615] Starting dynamic subtest: bsd1
[287.635606] Dynamic subtest bsd1: SUCCESS (0.765s)
[287.647075] Starting dynamic subtest: bsd2
[288.393467] Dynamic subtest bsd2: SUCCESS (0.756s)
[288.403956] Starting dynamic subtest: blt
[289.150032] Dynamic subtest blt: SUCCESS (0.753s)
[289.159663] Starting dynamic subtest: vebox
[289.937413] Dynamic subtest vebox: SUCCESS (0.787s)
[289.938456] Subtest legacy-engines-persistence: SUCCESS (3.874s)
[290.010017] [035/133] (733s left) gem_ctx_param (invalid-get-ringsize)
[290.064337] Starting subtest: invalid-get-ringsize
[290.065309] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[290.171562] [036/133] (732s left) kms_psr (psr2-primary-mmap-cpu)
[290.261122] Starting subtest: psr2-primary-mmap-cpu
[290.261966] Starting dynamic subtest: eDP-1
[292.022046] Dynamic subtest eDP-1: SUCCESS (1.760s)
[292.022911] Subtest psr2-primary-mmap-cpu: SUCCESS (1.761s)
[292.880519] [037/133] (730s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[292.960163] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[292.961822] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.000s)
[293.026713] [038/133] (730s left) gem_userptr_blits (unsync-overlap)
[293.081075] Subtest unsync-overlap: SKIP (0.000s)
[293.204310] [039/133] (730s left) kms_dirtyfb (default-dirtyfb-ioctl)
[293.291191] Starting subtest: default-dirtyfb-ioctl
[293.292079] Starting dynamic subtest: A-eDP-1
[294.874839] Dynamic subtest A-eDP-1: SUCCESS (1.583s)
[294.876069] Subtest default-dirtyfb-ioctl: SUCCESS (1.584s)
[295.716417] [040/133] (727s left) sysfs_heartbeat_interval (idempotent)
[295.782524] Starting subtest: idempotent
[295.783466] Starting dynamic subtest: vecs0
[295.784348] Dynamic subtest vecs0: SUCCESS (0.001s)
[295.792889] Starting dynamic subtest: ccs0
[295.793816] Dynamic subtest ccs0: SUCCESS (0.001s)
[295.800204] Starting dynamic subtest: vcs0
[295.801037] Dynamic subtest vcs0: SUCCESS (0.000s)
[295.807713] Starting dynamic subtest: bcs0
[295.808609] Dynamic subtest bcs0: SUCCESS (0.001s)
[295.815413] Starting dynamic subtest: rcs0
[295.816292] Dynamic subtest rcs0: SUCCESS (0.000s)
[295.822943] Starting dynamic subtest: vcs1
[295.823822] Dynamic subtest vcs1: SUCCESS (0.001s)
[295.824779] Subtest idempotent: SUCCESS (0.007s)
[295.855410] [041/133] (727s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[295.959496] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[297.049058] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.089s)
[297.213334] [042/133] (726s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[297.291395] Starting subtest: single-wait-all-for-submit-signaled
[297.292418] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[297.382983] [043/133] (726s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[297.476207] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[297.477961] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[297.539831] [044/133] (725s left) kms_async_flips (invalid-async-flip-atomic)
[297.625368] Starting subtest: invalid-async-flip-atomic
[298.000141] Starting dynamic subtest: pipe-A-eDP-1
[298.850187] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.850s)
[299.212196] Starting dynamic subtest: pipe-B-eDP-1
[299.987992] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.782s)
[300.343159] Starting dynamic subtest: pipe-C-eDP-1
[301.113629] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.777s)
[301.475791] Starting dynamic subtest: pipe-D-eDP-1
[302.244356] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.775s)
[302.245250] Subtest invalid-async-flip-atomic: SUCCESS (4.620s)
[303.389392] [045/133] (720s left) gem_softpin (evict-prime-sanity-check)
[303.446654] Starting subtest: evict-prime-sanity-check
[303.447719] Starting dynamic subtest: rcs0
[304.586316] Dynamic subtest rcs0: SUCCESS (1.139s)
[304.594563] Starting dynamic subtest: bcs0
[305.704219] Dynamic subtest bcs0: SUCCESS (1.116s)
[305.712201] Starting dynamic subtest: vcs0
[306.900627] Dynamic subtest vcs0: SUCCESS (1.195s)
[306.909299] Starting dynamic subtest: vcs1
[308.892707] Dynamic subtest vcs1: SUCCESS (1.981s)
[308.902208] Starting dynamic subtest: vecs0
[310.877870] Dynamic subtest vecs0: SUCCESS (1.983s)
[310.887133] Starting dynamic subtest: ccs0
[311.979668] Dynamic subtest ccs0: SUCCESS (1.109s)
[311.987364] Starting dynamic subtest: all
[313.102618] Dynamic subtest all: SUCCESS (1.122s)
[313.103730] Subtest evict-prime-sanity-check: SUCCESS (9.656s)
[313.175451] [046/133] (710s left) gem_ctx_param (basic-default)
[313.228397] Starting subtest: basic-default
[313.229303] Subtest basic-default: SUCCESS (0.000s)
[313.284833] [047/133] (710s left) kms_pipe_crc_basic (nonblocking-crc)
[313.383979] Starting subtest: nonblocking-crc
[313.384950] Starting dynamic subtest: pipe-A-eDP-1
[314.154192] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.769s)
[314.161567] Starting dynamic subtest: pipe-B-eDP-1
[315.600347] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.445s)
[315.607925] Starting dynamic subtest: pipe-C-eDP-1
[316.982820] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.381s)
[316.989821] Starting dynamic subtest: pipe-D-eDP-1
[318.309732] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.326s)
[318.310650] Subtest nonblocking-crc: SUCCESS (4.926s)
[319.099805] [048/133] (704s left) kms_flip (2x-absolute-wf_vblank)
[319.190026] Starting subtest: 2x-absolute-wf_vblank
[319.200510] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[319.271387] [049/133] (704s left) perf_pmu (invalid-init)
[319.309167] Starting subtest: invalid-init
[319.310073] Subtest invalid-init: SUCCESS (0.000s)
[319.418765] [050/133] (704s left) kms_addfb_basic (invalid-get-prop)
[319.464952] Starting subtest: invalid-get-prop
[319.465811] Subtest invalid-get-prop: SUCCESS (0.000s)
[319.613909] [051/133] (704s left) gem_exec_schedule (pi-shared-iova)
[319.798196] Starting subtest: pi-shared-iova
[319.799073] Starting dynamic subtest: rcs0
[319.872589] Dynamic subtest rcs0: SUCCESS (0.082s)
[319.882181] Starting dynamic subtest: vcs0
[319.941395] Dynamic subtest vcs0: SUCCESS (0.067s)
[319.950335] Starting dynamic subtest: vcs1
[319.995403] Dynamic subtest vcs1: SUCCESS (0.054s)
[320.003877] Starting dynamic subtest: vecs0
[320.052598] Dynamic subtest vecs0: SUCCESS (0.056s)
[320.062532] Starting dynamic subtest: ccs0
[320.109861] Dynamic subtest ccs0: SUCCESS (0.057s)
[320.111393] Subtest pi-shared-iova: SUCCESS (0.320s)
[322.020713] [052/133] (701s left) prime_mmap (test_forked)
[322.070383] Starting subtest: test_forked
[322.071460] Starting dynamic subtest: test_forked-smem
[322.111311] Dynamic subtest test_forked-smem: SUCCESS (0.041s)
[322.114427] Subtest test_forked: SUCCESS (0.042s)
[322.215687] [053/133] (701s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[322.300064] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[322.301027] Starting dynamic subtest: pipe-A
[322.638118] Dynamic subtest pipe-A: SUCCESS (0.338s)
[322.645796] Starting dynamic subtest: pipe-B
[324.005057] Dynamic subtest pipe-B: SUCCESS (1.366s)
[324.012934] Starting dynamic subtest: pipe-C
[325.438159] Dynamic subtest pipe-C: SUCCESS (1.433s)
[325.445830] Starting dynamic subtest: pipe-D
[326.885134] Dynamic subtest pipe-D: SUCCESS (1.446s)
[326.886070] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.586s)
[328.031750] [054/133] (695s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[328.122323] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[328.123982] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[328.219375] [055/133] (695s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[328.303383] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[328.305039] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[328.405962] [056/133] (695s left) gem_wait (wait)
[328.467672] Starting subtest: wait
[328.468758] Starting dynamic subtest: all-engines
[329.885543] Dynamic subtest all-engines: SUCCESS (1.409s)
[329.895459] Starting dynamic subtest: rcs0
[330.923765] Dynamic subtest rcs0: SUCCESS (1.045s)
[330.934386] Starting dynamic subtest: bcs0
[331.970067] Dynamic subtest bcs0: SUCCESS (1.044s)
[331.980976] Starting dynamic subtest: vcs0
[333.014469] Dynamic subtest vcs0: SUCCESS (1.042s)
[333.024971] Starting dynamic subtest: vcs1
[334.059072] Dynamic subtest vcs1: SUCCESS (1.042s)
[334.070714] Starting dynamic subtest: vecs0
[335.102940] Dynamic subtest vecs0: SUCCESS (1.042s)
[335.113774] Starting dynamic subtest: ccs0
[336.149269] Dynamic subtest ccs0: SUCCESS (1.044s)
[336.150970] Subtest wait: SUCCESS (7.682s)
[336.338587] [057/133] (687s left) perf (mi-rpc)
[336.467373] Starting subtest: mi-rpc
[336.469022] Subtest mi-rpc: SKIP (0.000s)
[336.509743] [058/133] (687s left) kms_flip (2x-flip-vs-dpms)
[336.599921] Starting subtest: 2x-flip-vs-dpms
[336.609349] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[336.698998] [059/133] (687s left) syncobj_basic (bad-create-flags)
[336.753697] Starting subtest: bad-create-flags
[336.754777] Subtest bad-create-flags: SUCCESS (0.000s)
[336.845921] [060/133] (687s left) api_intel_allocator (gem-pool)
[336.886450] Starting subtest: gem-pool
[336.897180] Subtest gem-pool: SUCCESS (0.012s)
[337.009511] [061/133] (686s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[337.095299] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[337.097069] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[337.173843] [062/133] (686s left) gem_exec_whisper (basic-sync)
[337.229374] Starting subtest: basic-sync
[343.847934] Subtest basic-sync: SUCCESS (6.607s)
[344.016960] [063/133] (680s left) perf_pmu (busy-start)
[344.054055] Starting subtest: busy-start
[344.054896] Starting dynamic subtest: rcs0
[346.618211] Dynamic subtest rcs0: SUCCESS (2.551s)
[346.628195] Starting dynamic subtest: bcs0
[349.171264] Dynamic subtest bcs0: SUCCESS (2.550s)
[349.179846] Starting dynamic subtest: vcs0
[352.925921] Dynamic subtest vcs0: SUCCESS (3.756s)
[352.936048] Starting dynamic subtest: vcs1
[356.893548] Dynamic subtest vcs1: SUCCESS (3.965s)
[356.903901] Starting dynamic subtest: vecs0
[360.925766] Dynamic subtest vecs0: SUCCESS (4.029s)
[360.936243] Starting dynamic subtest: ccs0
[363.478826] Dynamic subtest ccs0: SUCCESS (2.548s)
[363.479805] Subtest busy-start: SUCCESS (19.413s)
[363.560377] [064/133] (660s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs)
[363.646995] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs
[363.647931] Starting dynamic subtest: pipe-A-eDP-1
[363.669562] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[363.677128] Starting dynamic subtest: pipe-B-eDP-1
[363.698427] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[363.705901] Starting dynamic subtest: pipe-C-eDP-1
[363.726438] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[363.733286] Starting dynamic subtest: pipe-D-eDP-1
[363.753347] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[363.754911] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs: SKIP (0.009s)
[365.914544] [065/133] (658s left) kms_lease (lease-unleased-crtc)
[366.007391] Starting subtest: lease-unleased-crtc
[366.008478] Starting dynamic subtest: pipe-A-eDP-1
[366.307524] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.300s)
[366.317989] Starting dynamic subtest: pipe-B-eDP-1
[367.081445] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.769s)
[367.090889] Starting dynamic subtest: pipe-C-eDP-1
[367.092299] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[367.100264] Starting dynamic subtest: pipe-D-eDP-1
[367.101092] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[367.101832] Subtest lease-unleased-crtc: SUCCESS (1.084s)
[367.456245] [066/133] (656s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[367.554940] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[367.556617] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[367.660797] [067/133] (656s left) prime_vgem (basic-fence-read)
[367.812354] Starting subtest: basic-fence-read
[367.818873] Subtest basic-fence-read: SKIP (0.007s)
[367.915539] [FACT prime_vgem (basic-fence-read)] new: kernel.kmod_is_loaded.vgem: true
[367.917762] [068/133] (656s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[368.011757] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[368.013576] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[368.110860] [069/133] (656s left) gem_exec_params (dr4-dirt)
[368.168505] Starting subtest: DR4-dirt
[368.169465] Subtest DR4-dirt: SUCCESS (0.000s)
[368.253913] [070/133] (655s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[368.338808] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[376.127324] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.789s)
[376.237765] [071/133] (648s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[376.331293] Starting subtest: fbc-modesetfrombusy
[380.113426] Subtest fbc-modesetfrombusy: SUCCESS (3.782s)
[381.287882] [072/133] (643s left) gem_create (create-ext-placement-sanity-check)
[381.452537] Starting subtest: create-ext-placement-sanity-check
[381.453624] Subtest create-ext-placement-sanity-check: SUCCESS (0.004s)
[381.547752] [073/133] (642s left) gem_partial_pwrite_pread (reads-uncached)
[381.603866] Subtest reads-uncached: SKIP (0.000s)
[381.667561] [074/133] (642s left) gem_reg_read (timestamp-monotonic)
[381.733986] Starting subtest: timestamp-monotonic
[385.989320] Subtest timestamp-monotonic: SUCCESS (4.210s)
[386.021394] [075/133] (638s left) kms_invalid_mode (int-max-clock)
[386.111599] Starting subtest: int-max-clock
[386.112514] Starting dynamic subtest: pipe-A-eDP-1
[386.396185] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[386.404045] Starting dynamic subtest: pipe-B-eDP-1
[386.405231] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[386.412258] Starting dynamic subtest: pipe-C-eDP-1
[386.413194] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[386.420225] Starting dynamic subtest: pipe-D-eDP-1
[386.421236] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[386.422213] Subtest int-max-clock: SUCCESS (0.290s)
[387.200123] [076/133] (637s left) gen7_exec_parse (oacontrol-tracking)
[387.266830] Subtest oacontrol-tracking: SKIP (0.000s)
[387.332528] [077/133] (637s left) gem_flink_basic (bad-flink)
[387.380082] Starting subtest: bad-flink
[387.380909] Subtest bad-flink: SUCCESS (0.000s)
[387.494025] [078/133] (637s left) kms_chamelium_edid (dp-edid-resolution-list)
[387.879507] Subtest dp-edid-resolution-list: SKIP (0.000s)
[388.703988] [079/133] (635s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[388.791450] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[388.792360] Starting dynamic subtest: pipe-A-eDP-1
[388.801247] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[388.808786] Starting dynamic subtest: pipe-B-eDP-1
[388.817293] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[388.824651] Starting dynamic subtest: pipe-C-eDP-1
[388.833128] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[388.840381] Starting dynamic subtest: pipe-D-eDP-1
[388.848947] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[388.850609] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[391.070659] [080/133] (633s left) gen3_render_linear_blits
[391.181799] [081/133] (633s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[391.244069] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[391.304890] [082/133] (633s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[391.413603] Starting subtest: 2x-long-flip-vs-cursor-legacy
[391.416660] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[391.557544] [083/133] (633s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[391.620249] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[391.622113] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[391.802490] [084/133] (632s left) kms_busy (extended-modeset-hang-oldfb)
[391.884454] Starting subtest: extended-modeset-hang-oldfb
[391.885401] Starting dynamic subtest: pipe-A
[403.362584] Dynamic subtest pipe-A: SUCCESS (11.477s)
[403.372030] Starting dynamic subtest: pipe-D
[415.638506] Dynamic subtest pipe-D: SUCCESS (12.273s)
[415.639255] Subtest extended-modeset-hang-oldfb: SUCCESS (23.754s)
[416.433764] [085/133] (608s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[416.547278] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[423.248253] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.701s)
[423.367968] [086/133] (601s left) perf_pmu (busy-check-all)
[423.404780] Starting subtest: busy-check-all
[423.405778] Starting dynamic subtest: rcs0
[423.959753] Dynamic subtest rcs0: SUCCESS (0.554s)
[423.970955] Starting dynamic subtest: bcs0
[424.509300] Dynamic subtest bcs0: SUCCESS (0.548s)
[424.520823] Starting dynamic subtest: vcs0
[425.941704] Dynamic subtest vcs0: SUCCESS (1.429s)
[425.954187] Starting dynamic subtest: vcs1
[427.934192] Dynamic subtest vcs1: SUCCESS (1.981s)
[427.942073] Starting dynamic subtest: vecs0
[429.918563] Dynamic subtest vecs0: SUCCESS (1.982s)
[429.928822] Starting dynamic subtest: ccs0
[430.464879] Dynamic subtest ccs0: SUCCESS (0.553s)
[430.466252] Subtest busy-check-all: SUCCESS (7.061s)
[430.564771] [087/133] (594s left) gem_exec_whisper (basic-fds-all)
[430.623051] Starting subtest: basic-fds-all
[442.617550] Subtest basic-fds-all: SUCCESS (11.993s)
[442.818259] [088/133] (582s left) kms_addfb_basic (unused-pitches)
[442.875616] Starting subtest: unused-pitches
[442.876518] Subtest unused-pitches: SUCCESS (0.000s)
[443.016967] [089/133] (581s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[443.113146] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[443.114209] Starting dynamic subtest: pipe-A-eDP-1
[443.131077] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[443.139550] Starting dynamic subtest: pipe-B-eDP-1
[443.147836] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[443.155360] Starting dynamic subtest: pipe-C-eDP-1
[443.163317] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[443.170408] Starting dynamic subtest: pipe-D-eDP-1
[443.179267] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[443.181075] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[445.414245] [090/133] (579s left) kms_psr (psr-primary-mmap-gtt)
[445.511276] Starting subtest: psr-primary-mmap-gtt
[445.512146] Starting dynamic subtest: eDP-1
[446.924871] Dynamic subtest eDP-1: SKIP (1.403s)
[446.927056] Subtest psr-primary-mmap-gtt: SKIP (1.405s)
[447.050318] [091/133] (577s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-mc-ccs)
[447.135427] Starting subtest: crc-primary-suspend-y-tiled-gen12-mc-ccs
[447.136394] Starting dynamic subtest: pipe-A-eDP-1
[447.140120] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[447.150553] Starting dynamic subtest: pipe-B-eDP-1
[447.153824] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[447.162354] Starting dynamic subtest: pipe-C-eDP-1
[447.165462] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[447.173175] Starting dynamic subtest: pipe-D-eDP-1
[447.176490] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[447.178064] Subtest crc-primary-suspend-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[449.420579] [092/133] (575s left) kms_flip (2x-nonexisting-fb)
[449.515046] Starting subtest: 2x-nonexisting-fb
[449.525908] Subtest 2x-nonexisting-fb: SKIP (0.011s)
[449.602795] [093/133] (575s left) fbdev (nullptr)
[449.646976] Starting subtest: nullptr
[449.647928] Subtest nullptr: SUCCESS (0.000s)
[449.714436] [094/133] (575s left) vgem_basic (unload)
[449.745466] Starting subtest: unload
[478.866225] Subtest unload: SUCCESS (29.074s)
[478.914822] [095/133] (546s left) gem_lmem_swapping (smem-oom)
[478.960376] Subtest smem-oom: SKIP (0.000s)
[478.998807] [096/133] (546s left) dmabuf_sync_file (export-wait-after-attach)
[479.038087] Starting subtest: export-wait-after-attach
[479.039055] Subtest export-wait-after-attach: SUCCESS (0.001s)
[479.091355] [097/133] (546s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move)
[479.190027] Starting subtest: psr-2p-primscrn-cur-indfb-move
[479.191622] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[479.279231] [098/133] (545s left) perf (invalid-create-userspace-config)
[479.398306] Starting subtest: invalid-create-userspace-config
[479.399358] Subtest invalid-create-userspace-config: SUCCESS (0.002s)
[479.433662] [099/133] (545s left) gem_ctx_sseu (invalid-args)
[479.483409] Subtest invalid-args: SKIP (0.000s)
[479.543412] [100/133] (545s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[479.639583] Starting subtest: yf-tiled-8bpp-rotate-270
[479.641548] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[479.725721] [101/133] (545s left) syncobj_timeline (32bits-limit)
[479.779366] Starting subtest: 32bits-limit
[479.780291] Subtest 32bits-limit: SUCCESS (0.001s)
[479.867184] [102/133] (545s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[479.957126] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[479.958867] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[480.037850] [103/133] (545s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[480.096075] Starting subtest: etime-single-wait-all-for-submit-submitted
[480.202050] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.106s)
[480.285356] [104/133] (545s left) gem_userptr_blits (unsync-unmap-cycles)
[480.335225] Subtest unsync-unmap-cycles: SKIP (0.000s)
[480.457831] [105/133] (544s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[480.501451] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[480.502367] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[480.588765] [106/133] (544s left) gem_mmap_gtt (big-bo-tiledx)
[480.644084] Subtest big-bo-tiledX: SKIP (0.000s)
[480.699694] [107/133] (544s left) sw_sync (sync_merge_invalid)
[480.744199] Starting subtest: sync_merge_invalid
[480.767268] Subtest sync_merge_invalid: SUCCESS (0.022s)
[480.831755] [108/133] (544s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[480.917276] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[480.918860] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[480.997510] [109/133] (544s left) i915_pm_rc6_residency (rc6-accuracy)
[481.048290] Starting subtest: rc6-accuracy
[481.049223] Starting dynamic subtest: gt0
[484.229431] Dynamic subtest gt0: SUCCESS (3.169s)
[484.239783] Starting dynamic subtest: gt1
[487.437221] Dynamic subtest gt1: SUCCESS (3.169s)
[487.438378] Subtest rc6-accuracy: SUCCESS (6.344s)
[487.469318] [110/133] (538s left) perf (polling)
[487.545119] Starting subtest: polling
[487.546036] Starting dynamic subtest: 0-rcs0
[497.576052] Dynamic subtest 0-rcs0: SUCCESS (10.020s)
[497.585901] Starting dynamic subtest: 1-vcs1
[507.633589] Dynamic subtest 1-vcs1: SUCCESS (10.019s)
[507.635042] Subtest polling: SUCCESS (20.042s)
[507.666922] [111/133] (517s left) kms_cursor_crc (cursor-alpha-transparent)
[507.753216] Starting subtest: cursor-alpha-transparent
[507.754513] Starting dynamic subtest: pipe-A-eDP-1
[508.355964] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.602s)
[508.364832] Starting dynamic subtest: pipe-D-eDP-1
[509.604999] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.246s)
[509.606101] Subtest cursor-alpha-transparent: SUCCESS (1.851s)
[510.475699] [112/133] (515s left) gem_render_copy (linear-to-vebox-y-tiled)
[510.538926] Starting subtest: linear-to-vebox-y-tiled
[510.540683] Subtest linear-to-vebox-y-tiled: SKIP (0.000s)
[510.716099] [113/133] (514s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[510.812134] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[510.813916] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[510.897192] [114/133] (514s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[511.085991] Starting subtest: cursor-rapid-movement-32x10
[511.133896] Subtest cursor-rapid-movement-32x10: SKIP (0.046s)
[511.412692] [115/133] (514s left) api_intel_bb (simple-bb)
[511.461752] Starting subtest: simple-bb
[511.464884] Subtest simple-bb: SUCCESS (0.004s)
[511.595912] [116/133] (514s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[511.698213] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[518.490207] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.786s)
[518.606736] [117/133] (507s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[518.714361] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[518.754520] [118/133] (507s left) gem_mmap_offset (oob-read)
[518.807524] Starting subtest: oob-read
[518.808442] Subtest oob-read: SUCCESS (0.000s)
[518.888624] [119/133] (507s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[518.981482] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[519.994554] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.013s)
[520.113882] [120/133] (505s left) prime_busy (hang-wait)
[520.163537] Starting subtest: hang-wait
[520.165500] Starting dynamic subtest: rcs0
[546.354937] Dynamic subtest rcs0: SUCCESS (26.187s)
[546.364316] Starting dynamic subtest: bcs0
[553.512219] Dynamic subtest bcs0: SUCCESS (7.154s)
[553.521579] Starting dynamic subtest: vcs0
[560.547829] Dynamic subtest vcs0: SUCCESS (7.033s)
[560.557222] Starting dynamic subtest: vcs1
[567.587594] Dynamic subtest vcs1: SUCCESS (7.037s)
[567.595159] Starting dynamic subtest: vecs0
[574.502609] Dynamic subtest vecs0: SUCCESS (6.912s)
[574.511536] Starting dynamic subtest: ccs0
[588.399936] Dynamic subtest ccs0: SUCCESS (13.896s)
[588.403717] Subtest hang-wait: SUCCESS (68.234s)
[588.525824] [121/133] (437s left) gem_tiled_fence_blits (basic)
[588.579194] Subtest basic: SKIP (0.000s)
[588.646626] [122/133] (437s left) gem_exec_fence (syncobj-timeline-export)
[589.212515] Starting subtest: syncobj-timeline-export
[589.214731] Subtest syncobj-timeline-export: SUCCESS (0.012s)
[591.014387] [123/133] (435s left) gem_fence_thrash (bo-write-verify-none)
[591.046975] Starting subtest: bo-write-verify-none
[591.066267] Subtest bo-write-verify-none: SKIP (0.018s)
[591.127393] [124/133] (434s left) gem_tiled_blits (normal)
[591.181606] Subtest normal: SKIP (0.000s)
[591.250860] [125/133] (434s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[591.354681] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[591.356293] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[591.452601] [126/133] (434s left) kms_chamelium_hpd (hdmi-hpd)
[591.835056] Subtest hdmi-hpd: SKIP (0.000s)
[592.601529] [127/133] (433s left) kms_vblank (query-forked-busy-hang)
[592.687616] Starting subtest: query-forked-busy-hang
[592.688571] Starting dynamic subtest: pipe-A-eDP-1
[618.710575] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.021s)
[618.719683] Starting dynamic subtest: pipe-D-eDP-1
[633.750957] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.037s)
[633.751921] Subtest query-forked-busy-hang: SUCCESS (41.062s)
[634.573800] [128/133] (391s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled)
[634.620212] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[634.621238] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[634.719730] [129/133] (391s left) gem_mmap (pf-nonblock)
[634.767936] Subtest pf-nonblock: SKIP (0.000s)
[634.819259] [130/133] (391s left) kms_flip (blocking-absolute-wf_vblank)
[634.909192] Starting subtest: blocking-absolute-wf_vblank
[634.911365] Starting dynamic subtest: A-eDP1
[643.666861] Dynamic subtest A-eDP1: SUCCESS (8.728s)
[643.677188] Starting dynamic subtest: B-eDP1
[652.400706] Dynamic subtest B-eDP1: SUCCESS (8.730s)
[652.410455] Starting dynamic subtest: C-eDP1
[661.131333] Dynamic subtest C-eDP1: SUCCESS (8.715s)
[661.141335] Starting dynamic subtest: D-eDP1
[669.815657] Dynamic subtest D-eDP1: SUCCESS (8.717s)
[669.818967] Subtest blocking-absolute-wf_vblank: SUCCESS (34.907s)
[671.031968] [131/133] (355s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[671.126897] Starting subtest: psr2-cursor-plane-update-sf
[671.127738] Starting dynamic subtest: pipe-A-eDP-1
[671.702694] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.575s)
[671.710402] Starting dynamic subtest: pipe-B-eDP-1
[673.022808] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.319s)
[673.023689] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.898s)
[673.893406] [132/133] (352s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[673.983242] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[673.985129] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[674.094041] [133/133] (352s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[674.185540] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[674.186608] Starting dynamic subtest: pipe-A
[674.307625] Dynamic subtest pipe-A: SKIP (0.120s)
[674.315368] Starting dynamic subtest: pipe-B
[675.465255] Dynamic subtest pipe-B: SKIP (1.157s)
[675.473353] Starting dynamic subtest: pipe-C
[676.694845] Dynamic subtest pipe-C: SUCCESS (1.232s)
[676.703873] Starting dynamic subtest: pipe-D
[677.944052] Dynamic subtest pipe-D: SUCCESS (1.248s)
[677.946527] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.761s)
[679.111438] Closing watchdogs
results: parsing output: 0/ for test: kms_psr
results: parsing output: 1/ for test: gem_exec_balancer
results: parsing output: 2/ for test: kms_cursor_edge_walk
results: parsing output: 3/ for test: kms_plane
results: parsing output: 4/ for test: gem_exec_parallel
results: parsing output: 5/ for test: gem_exec_schedule
results: parsing output: 6/ for test: gem_exec_gttfill
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: kms_content_protection
results: parsing output: 11/ for test: i915_suspend
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: kms_cursor_legacy
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: gem_mmap_gtt
results: parsing output: 16/ for test: kms_pm_rpm
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: i915_drm_fdinfo
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: gem_exec_big
results: parsing output: 21/ for test: i915_drm_fdinfo
results: parsing output: 22/ for test: kms_async_flips
results: parsing output: 23/ for test: gem_userptr_blits
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_psr2_sf
results: parsing output: 27/ for test: kms_feature_discovery
results: parsing output: 28/ for test: kms_pm_rpm
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_color
results: parsing output: 32/ for test: kms_dsc
results: parsing output: 33/ for test: gem_ctx_persistence
results: parsing output: 34/ for test: gem_ctx_param
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: kms_flip_scaled_crc
results: parsing output: 37/ for test: gem_userptr_blits
results: parsing output: 38/ for test: kms_dirtyfb
results: parsing output: 39/ for test: sysfs_heartbeat_interval
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: syncobj_timeline
results: parsing output: 42/ for test: kms_psr2_sf
results: parsing output: 43/ for test: kms_async_flips
results: parsing output: 44/ for test: gem_softpin
results: parsing output: 45/ for test: gem_ctx_param
results: parsing output: 46/ for test: kms_pipe_crc_basic
results: parsing output: 47/ for test: kms_flip
results: parsing output: 48/ for test: perf_pmu
results: parsing output: 49/ for test: kms_addfb_basic
results: parsing output: 50/ for test: gem_exec_schedule
results: parsing output: 51/ for test: prime_mmap
results: parsing output: 52/ for test: kms_plane_scaling
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_wait
results: parsing output: 56/ for test: perf
results: parsing output: 57/ for test: kms_flip
results: parsing output: 58/ for test: syncobj_basic
results: parsing output: 59/ for test: api_intel_allocator
results: parsing output: 60/ for test: kms_plane
results: parsing output: 61/ for test: gem_exec_whisper
results: parsing output: 62/ for test: perf_pmu
results: parsing output: 63/ for test: kms_ccs
results: parsing output: 64/ for test: kms_lease
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: prime_vgem
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gem_exec_params
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_create
results: parsing output: 72/ for test: gem_partial_pwrite_pread
results: parsing output: 73/ for test: gem_reg_read
results: parsing output: 74/ for test: kms_invalid_mode
results: parsing output: 75/ for test: gen7_exec_parse
results: parsing output: 76/ for test: gem_flink_basic
results: parsing output: 77/ for test: kms_chamelium_edid
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: gen3_render_linear_blits
results: parsing output: 80/ for test: kms_pipe_b_c_ivb
results: parsing output: 81/ for test: kms_cursor_legacy
results: parsing output: 82/ for test: gem_render_copy
results: parsing output: 83/ for test: kms_busy
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: perf_pmu
results: parsing output: 86/ for test: gem_exec_whisper
results: parsing output: 87/ for test: kms_addfb_basic
results: parsing output: 88/ for test: kms_ccs
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: kms_flip
results: parsing output: 92/ for test: fbdev
results: parsing output: 93/ for test: vgem_basic
results: parsing output: 94/ for test: gem_lmem_swapping
results: parsing output: 95/ for test: dmabuf_sync_file
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: perf
results: parsing output: 98/ for test: gem_ctx_sseu
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: kms_flip_scaled_crc
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: gem_userptr_blits
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: gem_mmap_gtt
results: parsing output: 106/ for test: sw_sync
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: i915_pm_rc6_residency
results: parsing output: 109/ for test: perf
results: parsing output: 110/ for test: kms_cursor_crc
results: parsing output: 111/ for test: gem_render_copy
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_cursor_crc
results: parsing output: 114/ for test: api_intel_bb
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_tiled_display
results: parsing output: 117/ for test: gem_mmap_offset
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: prime_busy
results: parsing output: 120/ for test: gem_tiled_fence_blits
results: parsing output: 121/ for test: gem_exec_fence
results: parsing output: 122/ for test: gem_fence_thrash
results: parsing output: 123/ for test: gem_tiled_blits
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_chamelium_hpd
results: parsing output: 126/ for test: kms_vblank
results: parsing output: 127/ for test: syncobj_wait
results: parsing output: 128/ for test: gem_mmap
results: parsing output: 129/ for test: kms_flip
results: parsing output: 130/ for test: kms_psr2_sf
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_plane_scaling
Done.