[57.957647] Initializing watchdogs
[57.957772]   /dev/watchdog0
[57.974159] [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]
[57.979100] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.981487] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.984851] [001/156] (960s left) gem_close (basic)
[58.083792] Starting subtest: basic
[58.086097] Subtest basic: SUCCESS (0.000s)
[58.193866] [002/156] (960s left) syncobj_timeline (multi-wait-all-available-signaled)
[58.251428] Starting subtest: multi-wait-all-available-signaled
[58.253783] Subtest multi-wait-all-available-signaled: SUCCESS (0.001s)
[58.367622] [003/156] (960s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[58.478608] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[58.482822] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[58.564323] [004/156] (960s left) kms_plane_multiple (tiling-y)
[58.656406] Starting subtest: tiling-y
[58.660908] Subtest tiling-y: SKIP (0.000s)
[58.729151] [005/156] (959s left) perf_pmu (busy-idle-check-all)
[58.764952] Starting subtest: busy-idle-check-all
[58.767097] Starting dynamic subtest: rcs0
[59.438089] Dynamic subtest rcs0: SUCCESS (0.676s)
[59.450642] Starting dynamic subtest: bcs0
[60.089618] Dynamic subtest bcs0: SUCCESS (0.654s)
[60.100771] Starting dynamic subtest: vcs0
[61.323252] Dynamic subtest vcs0: SUCCESS (1.235s)
[61.333985] Starting dynamic subtest: vcs1
[63.304427] Dynamic subtest vcs1: SUCCESS (1.984s)
[63.315426] Starting dynamic subtest: vecs0
[65.286848] Dynamic subtest vecs0: SUCCESS (1.986s)
[65.299535] Starting dynamic subtest: ccs0
[65.941970] Dynamic subtest ccs0: SUCCESS (0.657s)
[65.944456] Subtest busy-idle-check-all: SUCCESS (7.201s)
[66.058626] [006/156] (952s left) syncobj_timeline (signal-point-0)
[66.109487] Starting subtest: signal-point-0
[66.111507] Subtest signal-point-0: SUCCESS (0.000s)
[66.205746] [007/156] (952s left) kms_scaling_modes (scaling-mode-full-aspect)
[66.303604] Starting subtest: scaling-mode-full-aspect
[66.305769] Starting dynamic subtest: pipe-A-eDP-1
[66.423822] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.122s)
[66.434693] Starting dynamic subtest: pipe-B-eDP-1
[67.615391] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.192s)
[67.623837] Starting dynamic subtest: pipe-C-eDP-1
[68.817452] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.202s)
[68.826068] Starting dynamic subtest: pipe-D-eDP-1
[69.955964] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.139s)
[69.958389] Subtest scaling-mode-full-aspect: SUCCESS (3.660s)
[71.206162] [008/156] (947s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[71.313531] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[71.317883] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[71.410306] [009/156] (947s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[71.514152] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[71.518497] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[71.628470] [010/156] (947s left) gem_exec_fence (syncobj-timeline-wait)
[72.191918] Starting subtest: syncobj-timeline-wait
[73.293600] Subtest syncobj-timeline-wait: SUCCESS (1.107s)
[75.349957] [FACT gem_exec_fence (syncobj-timeline-wait)] new: kernel.kmod_is_loaded.vgem: true
[75.355100] [011/156] (943s left) i915_pciid
[75.516713] [012/156] (943s left) kms_chamelium_color (ctm-limited-range)
[75.612235] Subtest ctm-limited-range: SKIP (0.000s)
[75.655621] [013/156] (943s left) kms_color (legacy-gamma-reset)
[75.751141] Starting subtest: legacy-gamma-reset
[75.753527] Starting dynamic subtest: pipe-A-eDP-1
[76.127905] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.377s)
[76.136252] Starting dynamic subtest: pipe-B-eDP-1
[76.138507] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.011s)
[76.147786] Starting dynamic subtest: pipe-C-eDP-1
[76.152295] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.012s)
[76.160392] Starting dynamic subtest: pipe-D-eDP-1
[76.162738] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[76.165102] Subtest legacy-gamma-reset: SUCCESS (0.412s)
[76.937410] [014/156] (942s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt)
[77.040786] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt
[77.045222] Subtest fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[77.132518] [015/156] (941s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[77.226123] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[77.228477] Starting dynamic subtest: pipe-A-default-mode
[79.980798] Dynamic subtest pipe-A-default-mode: SUCCESS (2.757s)
[79.983261] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.758s)
[80.851240] [016/156] (938s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[80.946830] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[80.950931] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[81.034702] [017/156] (938s left) gem_ctx_sseu (engines)
[81.097480] Subtest engines: SKIP (0.000s)
[81.165792] [018/156] (937s left) gem_pxp (fail-invalid-protected-context)
[81.291299] Starting subtest: fail-invalid-protected-context
[81.293343] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[81.409083] [019/156] (937s left) gem_exec_params (no-vebox)
[81.471775] Starting subtest: no-vebox
[81.474744] Subtest no-vebox: SUCCESS (0.002s)
[83.353526] [020/156] (935s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[85.424623] Starting subtest: modeset-non-lpsp-stress-no-wait
[85.489530] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.065s)
[85.860560] [021/156] (933s left) i915_pm_rps (thresholds-idle-park)
[85.922203] Starting subtest: thresholds-idle-park
[85.926824] Subtest thresholds-idle-park: SKIP (0.001s)
[86.033715] [022/156] (933s left) gem_lmem_swapping (verify-random-ccs)
[86.076152] Subtest verify-random-ccs: SKIP (0.000s)
[86.121293] [023/156] (933s left) gem_eio (in-flight-internal-1us)
[86.330085] Starting subtest: in-flight-internal-1us
[87.369065] Subtest in-flight-internal-1us: SUCCESS (1.038s)
[87.539134] [024/156] (931s left) gem_basic (bad-close)
[87.590570] Starting subtest: bad-close
[87.595216] Subtest bad-close: SUCCESS (0.000s)
[87.698239] [025/156] (931s left) kms_flip (basic-plain-flip)
[87.784460] Starting subtest: basic-plain-flip
[87.786870] Starting dynamic subtest: A-eDP1
[89.447939] Dynamic subtest A-eDP1: SUCCESS (1.662s)
[89.459091] Starting dynamic subtest: B-eDP1
[91.188499] Dynamic subtest B-eDP1: SUCCESS (1.740s)
[91.197914] Starting dynamic subtest: C-eDP1
[92.830499] Dynamic subtest C-eDP1: SUCCESS (1.640s)
[92.840188] Starting dynamic subtest: D-eDP1
[94.469950] Dynamic subtest D-eDP1: SUCCESS (1.639s)
[94.474799] Subtest basic-plain-flip: SUCCESS (6.690s)
[95.662054] [026/156] (923s left) kms_hdmi_inject (inject-audio)
[96.026324] Starting subtest: inject-audio
[96.320281] Subtest inject-audio: SUCCESS (0.297s)
[96.918914] [027/156] (922s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[97.133716] Starting subtest: cursor-rapid-movement-128x42
[97.179512] Subtest cursor-rapid-movement-128x42: SKIP (0.043s)
[97.377668] [028/156] (922s left) kms_invalid_mode (bad-vtotal)
[97.473978] Starting subtest: bad-vtotal
[97.476366] Starting dynamic subtest: pipe-A-eDP-1
[97.761515] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[97.772622] Starting dynamic subtest: pipe-B-eDP-1
[97.774975] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[97.784082] Starting dynamic subtest: pipe-C-eDP-1
[97.786326] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[97.794794] Starting dynamic subtest: pipe-D-eDP-1
[97.797106] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[97.799529] Subtest bad-vtotal: SUCCESS (0.293s)
[98.585228] [029/156] (921s left) kms_cursor_crc (cursor-size-change)
[98.686469] Starting subtest: cursor-size-change
[98.688766] Starting dynamic subtest: pipe-A-eDP-1
[99.484602] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.798s)
[99.493967] Starting dynamic subtest: pipe-D-eDP-1
[100.949859] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.465s)
[100.952508] Subtest cursor-size-change: SUCCESS (2.265s)
[101.796819] [030/156] (917s left) gem_softpin (reverse)
[101.863033] Starting subtest: reverse
[101.870259] Subtest reverse: SUCCESS (0.007s)
[101.993393] [031/156] (917s left) syncobj_basic (bad-flags-fd-to-handle)
[102.046926] Starting subtest: bad-flags-fd-to-handle
[102.049606] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[102.140217] [032/156] (917s left) syncobj_eventfd (invalid-bad-flags)
[102.191732] Starting subtest: invalid-bad-flags
[102.193914] Subtest invalid-bad-flags: SUCCESS (0.000s)
[102.287195] [033/156] (917s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[102.346116] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[102.451537] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[102.528990] [034/156] (917s left) gem_ctx_persistence (legacy-engines-persistence)
[102.593477] Starting subtest: legacy-engines-persistence
[102.595813] Starting dynamic subtest: render
[103.440166] Dynamic subtest render: SUCCESS (0.846s)
[103.452343] Starting dynamic subtest: bsd1
[104.196022] Dynamic subtest bsd1: SUCCESS (0.754s)
[104.208843] Starting dynamic subtest: bsd2
[104.964192] Dynamic subtest bsd2: SUCCESS (0.766s)
[104.975981] Starting dynamic subtest: blt
[105.736356] Dynamic subtest blt: SUCCESS (0.770s)
[105.747903] Starting dynamic subtest: vebox
[106.477913] Dynamic subtest vebox: SUCCESS (0.740s)
[106.480491] Subtest legacy-engines-persistence: SUCCESS (3.885s)
[106.617374] [035/156] (913s left) gem_mmap_offset (mmap-unmap)
[106.676165] Starting subtest: mmap-unmap
[106.678265] Starting dynamic subtest: smem0
[130.695603] Dynamic subtest smem0: SUCCESS (24.019s)
[130.698268] Subtest mmap-unmap: SUCCESS (24.019s)
[130.830958] [036/156] (889s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[130.902493] Starting subtest: stress-mm-invalidate-close-overlap
[133.050915] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.149s)
[133.194060] [037/156] (886s left) kms_vblank (query-idle)
[133.303909] Starting subtest: query-idle
[133.306129] Starting dynamic subtest: pipe-A-eDP-1
[135.045663] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.741s)
[135.059745] Starting dynamic subtest: pipe-D-eDP-1
[137.500556] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.455s)
[137.502944] Subtest query-idle: SUCCESS (4.199s)
[138.380431] [038/156] (881s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[138.479674] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[146.083753] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.610s)
[146.209332] [039/156] (873s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[146.261841] Subtest tiled-to-untiled: SKIP (0.000s)
[146.335409] [040/156] (873s left) i915_pm_rc6_residency (rc6-accuracy)
[146.394054] Starting subtest: rc6-accuracy
[146.396346] Starting dynamic subtest: gt0
[149.567385] Dynamic subtest gt0: SUCCESS (3.169s)
[149.579466] Starting dynamic subtest: gt1
[152.739580] Dynamic subtest gt1: SUCCESS (3.169s)
[152.742277] Subtest rc6-accuracy: SUCCESS (6.342s)
[152.813648] [041/156] (867s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[152.862646] Starting subtest: yf-tiled-ccs-to-yf-tiled
[152.866945] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[153.049246] [042/156] (867s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[153.154317] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[153.159088] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[153.270216] [043/156] (866s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[153.379928] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[157.319756] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.946s)
[158.501817] [044/156] (861s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-shrfb-msflip-blt)
[158.603329] Starting subtest: hdr-2p-scndscrn-shrfb-msflip-blt
[158.607648] Subtest hdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[158.707622] [045/156] (861s left) gem_ctx_param (invalid-get-engines)
[158.759724] Starting subtest: invalid-get-engines
[158.762063] Subtest invalid-get-engines: SUCCESS (0.001s)
[158.872620] [046/156] (861s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[158.972742] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[158.976903] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[159.074229] [047/156] (861s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[159.186245] Starting subtest: y-tiled-32bpp-rotate-0
[159.190694] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[159.286270] [048/156] (861s left) kms_hdr (static-toggle-dpms)
[159.385268] Starting subtest: static-toggle-dpms
[159.387576] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[159.392049] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[159.401522] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[159.404995] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[159.408580] Subtest static-toggle-dpms: SKIP (0.002s)
[159.457211] [049/156] (861s left) gem_mmap_gtt (basic-small-bo)
[159.517343] Subtest basic-small-bo: SKIP (0.000s)
[159.575712] [050/156] (860s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[159.666835] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[159.670148] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[159.791841] [051/156] (860s left) gem_exec_nop (basic-parallel)
[159.858215] Starting subtest: basic-parallel
[176.329795] Subtest basic-parallel: SUCCESS (16.478s)
[176.556648] [052/156] (844s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[176.668970] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[176.673287] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[176.762485] [053/156] (843s left) gem_exec_fence (parallel)
[176.948308] Starting subtest: parallel
[176.950269] Starting dynamic subtest: rcs0
[179.096493] Dynamic subtest rcs0: SUCCESS (2.151s)
[179.105542] Starting dynamic subtest: bcs0
[181.246974] Dynamic subtest bcs0: SUCCESS (2.149s)
[181.257284] Starting dynamic subtest: vcs0
[183.398441] Dynamic subtest vcs0: SUCCESS (2.151s)
[183.409035] Starting dynamic subtest: vcs1
[185.552239] Dynamic subtest vcs1: SUCCESS (2.153s)
[185.563401] Starting dynamic subtest: vecs0
[187.704067] Dynamic subtest vecs0: SUCCESS (2.151s)
[187.715262] Starting dynamic subtest: ccs0
[189.852477] Dynamic subtest ccs0: SUCCESS (2.148s)
[189.854544] Subtest parallel: SUCCESS (12.907s)
[190.431782] [054/156] (830s left) kms_psr (psr2-cursor-mmap-cpu)
[190.533281] Starting subtest: psr2-cursor-mmap-cpu
[190.535572] Starting dynamic subtest: eDP-1
[192.229642] Dynamic subtest eDP-1: SUCCESS (1.694s)
[192.232284] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.696s)
[193.087817] [055/156] (827s left) gem_exec_params (secure-non-root)
[193.148482] Starting subtest: secure-non-root
[193.152977] Subtest secure-non-root: SKIP (0.000s)
[193.244807] [056/156] (827s left) gem_ctx_param (root-set)
[193.292949] Starting subtest: root-set
[193.295434] Subtest root-set: SUCCESS (0.001s)
[193.408572] [057/156] (827s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[193.507256] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[193.511580] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[193.609440] [058/156] (827s left) kms_chamelium_hpd (hdmi-hpd-fast)
[193.997207] Subtest hdmi-hpd-fast: SKIP (0.000s)
[194.860972] [059/156] (826s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-pwrite)
[194.973676] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-pwrite
[194.978332] Subtest psrhdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[195.070496] [060/156] (825s left) kms_chamelium_frames (hdmi-aspect-ratio)
[195.476136] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[196.253899] [061/156] (824s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[196.354347] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[196.359729] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[196.456998] [062/156] (824s left) kms_flip (dpms-vs-vblank-race)
[196.554291] Starting subtest: dpms-vs-vblank-race
[196.556593] Starting dynamic subtest: A-eDP1
[200.443731] Dynamic subtest A-eDP1: SUCCESS (3.887s)
[200.453654] Starting dynamic subtest: B-eDP1
[203.943482] Dynamic subtest B-eDP1: SUCCESS (3.498s)
[203.953102] Starting dynamic subtest: C-eDP1
[207.423644] Dynamic subtest C-eDP1: SUCCESS (3.480s)
[207.434152] Starting dynamic subtest: D-eDP1
[210.935072] Dynamic subtest D-eDP1: SUCCESS (3.508s)
[210.937788] Subtest dpms-vs-vblank-race: SUCCESS (14.383s)
[211.776081] [063/156] (809s left) gem_exec_schedule (noreorder-priority)
[211.948761] Starting subtest: noreorder-priority
[211.951320] Starting dynamic subtest: rcs0
[213.086236] Dynamic subtest rcs0: SUCCESS (1.138s)
[213.099079] Starting dynamic subtest: vcs0
[214.276771] Dynamic subtest vcs0: SUCCESS (1.188s)
[214.288938] Starting dynamic subtest: vcs1
[216.338094] Dynamic subtest vcs1: SUCCESS (2.056s)
[216.349110] Starting dynamic subtest: vecs0
[218.284339] Dynamic subtest vecs0: SUCCESS (1.946s)
[218.295722] Starting dynamic subtest: ccs0
[219.426524] Dynamic subtest ccs0: SUCCESS (1.141s)
[219.428917] Subtest noreorder-priority: SUCCESS (7.480s)
[219.624897] [064/156] (801s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[219.687604] Starting subtest: single-wait-all-for-submit-available-signaled
[219.690022] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[219.801794] [065/156] (801s left) perf_pmu (semaphore-busy)
[219.838220] Starting subtest: semaphore-busy
[219.840330] Starting dynamic subtest: rcs0
[221.478223] Dynamic subtest rcs0: SUCCESS (1.637s)
[221.488813] Starting dynamic subtest: bcs0
[223.114100] Dynamic subtest bcs0: SUCCESS (1.633s)
[223.126310] Starting dynamic subtest: vcs0
[226.817111] Dynamic subtest vcs0: SUCCESS (3.701s)
[226.828698] Starting dynamic subtest: vcs1
[232.770221] Dynamic subtest vcs1: SUCCESS (5.950s)
[232.782737] Starting dynamic subtest: vecs0
[238.786800] Dynamic subtest vecs0: SUCCESS (6.014s)
[238.800973] Starting dynamic subtest: ccs0
[241.921859] Dynamic subtest ccs0: SUCCESS (3.132s)
[241.924302] Subtest semaphore-busy: SUCCESS (22.082s)
[242.039085] [066/156] (779s left) kms_psr (fbc-pr-primary-render)
[242.134082] Starting subtest: fbc-pr-primary-render
[242.138410] Subtest fbc-pr-primary-render: SKIP (0.000s)
[242.220085] [067/156] (779s left) kms_plane_lowres (tiling-none)
[242.310032] Starting subtest: tiling-none
[242.312364] Starting dynamic subtest: pipe-A-eDP-1
[242.316911] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[242.604489] Starting dynamic subtest: pipe-B-eDP-1
[242.609143] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[242.618349] Starting dynamic subtest: pipe-C-eDP-1
[242.622946] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[242.632085] Starting dynamic subtest: pipe-D-eDP-1
[242.636213] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[242.640311] Subtest tiling-none: SKIP (0.293s)
[243.477115] [068/156] (777s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[243.571949] Starting subtest: psr2-cursor-plane-move-continuous-sf
[243.574311] Starting dynamic subtest: pipe-A-eDP-1
[253.920240] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.347s)
[253.929932] Starting dynamic subtest: pipe-B-eDP-1
[265.012645] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.095s)
[265.015082] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.445s)
[265.878566] [069/156] (755s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[265.980455] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[265.984639] Subtest fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[266.097023] [070/156] (755s left) gem_mmap_gtt (medium-copy-odd)
[266.144587] Subtest medium-copy-odd: SKIP (0.000s)
[266.201349] [071/156] (755s left) kms_feature_discovery (chamelium)
[266.295214] Starting subtest: chamelium
[266.299535] Subtest chamelium: SKIP (0.000s)
[266.369402] [072/156] (755s left) gem_cs_tlb (engines)
[266.432909] Starting subtest: engines
[266.435128] Starting dynamic subtest: rcs0
[268.588543] Dynamic subtest rcs0: SUCCESS (2.152s)
[268.600365] Starting dynamic subtest: bcs0
[270.741632] Dynamic subtest bcs0: SUCCESS (2.151s)
[270.752764] Starting dynamic subtest: vcs0
[272.894089] Dynamic subtest vcs0: SUCCESS (2.152s)
[272.904627] Starting dynamic subtest: vcs1
[275.047579] Dynamic subtest vcs1: SUCCESS (2.152s)
[275.059412] Starting dynamic subtest: vecs0
[277.199903] Dynamic subtest vecs0: SUCCESS (2.151s)
[277.211777] Starting dynamic subtest: ccs0
[279.355251] Dynamic subtest ccs0: SUCCESS (2.154s)
[279.357596] Subtest engines: SUCCESS (12.919s)
[279.474813] [073/156] (742s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[279.583591] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[286.260561] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.680s)
[286.387721] [074/156] (735s left) kms_vblank (ts-continuation-modeset-rpm)
[286.493708] Starting subtest: ts-continuation-modeset-rpm
[286.495651] Starting dynamic subtest: pipe-A-eDP-1
[289.030841] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.538s)
[289.045592] Starting dynamic subtest: pipe-D-eDP-1
[291.221755] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.182s)
[291.226157] Subtest ts-continuation-modeset-rpm: SUCCESS (4.723s)
[292.084289] [075/156] (729s left) gem_exec_reloc (basic-range)
[292.150590] Subtest basic-range: SKIP (0.000s)
[292.219465] [076/156] (729s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-pwrite)
[292.312600] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-pwrite
[292.316844] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[292.411450] [077/156] (729s left) kms_content_protection (dp-mst-lic-type-1)
[292.553860] Starting subtest: dp-mst-lic-type-1
[292.559015] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[292.681493] [078/156] (729s left) gem_exec_fence (basic-await)
[292.865921] Starting subtest: basic-await
[292.868343] Starting dynamic subtest: rcs0
[292.942915] Dynamic subtest rcs0: SUCCESS (0.076s)
[292.954714] Starting dynamic subtest: bcs0
[293.015745] Dynamic subtest bcs0: SUCCESS (0.072s)
[293.027022] Starting dynamic subtest: vcs0
[293.093845] Dynamic subtest vcs0: SUCCESS (0.075s)
[293.105918] Starting dynamic subtest: vcs1
[293.167954] Dynamic subtest vcs1: SUCCESS (0.072s)
[293.180906] Starting dynamic subtest: vecs0
[293.241964] Dynamic subtest vecs0: SUCCESS (0.072s)
[293.255059] Starting dynamic subtest: ccs0
[293.316066] Dynamic subtest ccs0: SUCCESS (0.072s)
[293.319069] Subtest basic-await: SUCCESS (0.450s)
[295.391403] [079/156] (726s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[295.501481] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[295.505795] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[295.594838] [080/156] (726s left) kms_async_flips (invalid-async-flip)
[295.696071] Starting subtest: invalid-async-flip
[296.070267] Starting dynamic subtest: pipe-A-eDP-1
[296.883316] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.813s)
[297.243661] Starting dynamic subtest: pipe-B-eDP-1
[298.063870] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.829s)
[298.418693] Starting dynamic subtest: pipe-C-eDP-1
[299.258903] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.847s)
[299.615338] Starting dynamic subtest: pipe-D-eDP-1
[300.460492] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.853s)
[300.462777] Subtest invalid-async-flip: SUCCESS (4.767s)
[301.676529] [081/156] (720s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-plflip-blt)
[301.791375] Starting subtest: hdr-1p-primscrn-indfb-plflip-blt
[301.795652] Subtest hdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[301.905890] [082/156] (719s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[302.007419] Starting subtest: crc-primary-suspend-y-tiled-ccs
[302.009734] Starting dynamic subtest: pipe-A-eDP-1
[302.018777] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[302.028616] Starting dynamic subtest: pipe-B-eDP-1
[302.037795] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[302.052100] Starting dynamic subtest: pipe-C-eDP-1
[302.060532] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[302.069291] Starting dynamic subtest: pipe-D-eDP-1
[302.077769] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[302.081878] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.005s)
[304.268400] [083/156] (717s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-onoff)
[304.370789] Starting subtest: hdr-2p-primscrn-cur-indfb-onoff
[304.375119] Subtest hdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[304.478430] [084/156] (717s left) gem_userptr_blits (coherency-sync)
[304.531955] Starting subtest: coherency-sync
[304.838284] Subtest coherency-sync: SKIP (0.272s)
[304.946106] [085/156] (717s left) kms_dirtyfb (drrs-dirtyfb-ioctl)
[305.078946] Starting subtest: drrs-dirtyfb-ioctl
[305.091499] Subtest drrs-dirtyfb-ioctl: SKIP (0.001s)
[305.201349] [086/156] (716s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[305.307835] Starting subtest: x-tiled-16bpp-rotate-0
[306.654105] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.346s)
[306.788768] [087/156] (715s left) kms_prop_blob (invalid-set-prop-any)
[306.838498] Starting subtest: invalid-set-prop-any
[306.840484] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[306.931085] [088/156] (715s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[307.022139] Starting subtest: 4-tiled-32bpp-rotate-270
[307.041498] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.018s)
[307.146447] [089/156] (715s left) sysfs_heartbeat_interval (nopreempt)
[307.203027] Starting subtest: nopreempt
[307.205608] Starting dynamic subtest: vecs0
[316.288844] Dynamic subtest vecs0: SUCCESS (9.080s)
[316.300876] Starting dynamic subtest: ccs0
[320.314334] Dynamic subtest ccs0: SUCCESS (4.025s)
[320.325224] Starting dynamic subtest: vcs0
[330.307367] Dynamic subtest vcs0: SUCCESS (9.990s)
[330.321951] Starting dynamic subtest: bcs0
[334.219814] Dynamic subtest bcs0: SUCCESS (3.913s)
[334.230447] Starting dynamic subtest: rcs0
[338.258060] Dynamic subtest rcs0: SUCCESS (4.036s)
[338.269804] Starting dynamic subtest: vcs1
[348.292995] Dynamic subtest vcs1: SUCCESS (10.031s)
[348.295679] Subtest nopreempt: SUCCESS (41.086s)
[348.373626] [090/156] (673s left) kms_psr (psr-cursor-mmap-cpu)
[348.480842] Starting subtest: psr-cursor-mmap-cpu
[348.483221] Starting dynamic subtest: eDP-1
[350.247644] Dynamic subtest eDP-1: SUCCESS (1.767s)
[350.250296] Subtest psr-cursor-mmap-cpu: SUCCESS (1.768s)
[351.076559] [091/156] (671s left) prime_self_import (basic-with_one_bo_two_files)
[351.114505] Starting subtest: basic-with_one_bo_two_files
[351.132239] Subtest basic-with_one_bo_two_files: SUCCESS (0.018s)
[351.256034] [092/156] (671s left) gem_mmap_gtt (basic-small-copy)
[351.311305] Subtest basic-small-copy: SKIP (0.000s)
[351.366170] [093/156] (671s left) gem_exec_balancer (bonded-sync)
[351.424066] Starting subtest: bonded-sync
[351.428481] Subtest bonded-sync: SKIP (0.000s)
[351.736422] [094/156] (670s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[351.837238] Starting subtest: basic-max-pipe-crc-check
[351.841164] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[351.885583] [095/156] (670s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[351.992217] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[351.996620] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[352.117681] [096/156] (670s left) gem_exec_reloc (basic-gtt-wc-active)
[352.170814] Subtest basic-gtt-wc-active: SKIP (0.000s)
[352.260958] [097/156] (670s left) core_auth (getclient-master-drop)
[352.297973] Starting subtest: getclient-master-drop
[352.325531] Subtest getclient-master-drop: SUCCESS (0.029s)
[352.417476] [098/156] (670s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[352.517053] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[352.521247] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[352.616892] [099/156] (670s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt)
[352.707741] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt
[352.711072] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[352.793697] [100/156] (669s left) kms_cursor_crc (cursor-offscreen-32x10)
[352.963546] Starting subtest: cursor-offscreen-32x10
[353.010109] Subtest cursor-offscreen-32x10: SKIP (0.042s)
[353.302812] [101/156] (669s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[353.400763] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[353.404129] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[353.505862] [102/156] (669s left) kms_rotation_crc (sprite-rotation-180)
[353.597822] Starting subtest: sprite-rotation-180
[360.506075] Subtest sprite-rotation-180: SUCCESS (6.909s)
[361.685029] [103/156] (661s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-plflip-blt)
[361.786174] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-plflip-blt
[361.790310] Subtest fbcpsrhdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[361.903259] [104/156] (660s left) device_reset (cold-reset-bound)
[361.951171] Starting subtest: cold-reset-bound
[361.955734] Subtest cold-reset-bound: SKIP (0.000s)
[362.023699] [105/156] (660s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-gtt)
[362.125520] Starting subtest: hdr-rgb565-draw-mmap-gtt
[362.129792] Subtest hdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[362.244652] [106/156] (660s left) gem_ppgtt (blt-vs-render-ctxn)
[362.312055] Starting subtest: blt-vs-render-ctxN
[395.021647] Subtest blt-vs-render-ctxN: SUCCESS (32.707s)
[395.143108] [107/156] (627s left) gem_exec_reloc (basic-write-gtt-noreloc)
[395.232319] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[395.313135] [108/156] (627s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[395.421285] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[395.425603] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[395.532241] [109/156] (627s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[395.621359] Starting subtest: primary-yf-tiled-reflect-x-90
[395.625768] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[395.696069] [110/156] (627s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[395.793172] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[399.514291] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.728s)
[400.679447] [111/156] (622s left) gem_exec_whisper (basic-queues-forked)
[400.750547] Starting subtest: basic-queues-forked
[425.297128] Subtest basic-queues-forked: SUCCESS (24.542s)
[425.482266] [112/156] (597s left) gem_watchdog (default-virtual)
[425.556016] Starting subtest: default-virtual
[431.838083] Subtest default-virtual: SUCCESS (6.290s)
[431.939623] [113/156] (591s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[432.038196] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[432.042425] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[432.129221] [114/156] (591s left) gem_workarounds (reset-context)
[432.189166] Starting subtest: reset-context
[432.328170] Subtest reset-context: SUCCESS (0.137s)
[432.447515] [115/156] (590s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[432.576509] Starting subtest: 2x-long-cursor-vs-flip-atomic
[432.580940] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.001s)
[432.721979] [116/156] (590s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[432.786262] Starting subtest: etime-multi-wait-all-for-submit-submitted
[432.891323] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.102s)
[432.969116] [117/156] (590s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[433.079130] Starting subtest: psr-indfb-scaledprimary
[438.857912] Subtest psr-indfb-scaledprimary: SUCCESS (5.783s)
[438.985678] [118/156] (584s left) sw_sync (sync_random_merge)
[439.021057] Starting subtest: sync_random_merge
[439.063883] Subtest sync_random_merge: SUCCESS (0.045s)
[439.130217] [119/156] (584s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[439.229367] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[439.231583] Starting dynamic subtest: pipe-A-eDP-1
[439.254077] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[439.265369] Starting dynamic subtest: pipe-B-eDP-1
[439.287041] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[439.297772] Starting dynamic subtest: pipe-C-eDP-1
[439.319790] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[439.330301] Starting dynamic subtest: pipe-D-eDP-1
[439.352755] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[439.356836] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.007s)
[441.456631] [120/156] (582s left) kms_plane (pixel-format-y-tiled-ccs-modifier)
[441.565047] Starting subtest: pixel-format-y-tiled-ccs-modifier
[441.569198] Subtest pixel-format-y-tiled-ccs-modifier: SKIP (0.000s)
[441.637929] [121/156] (581s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[441.730456] Starting subtest: addfb25-y-tiled-small-legacy
[441.734577] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[441.822196] [122/156] (581s left) kms_busy (extended-pageflip-hang-oldfb)
[441.927580] Starting subtest: extended-pageflip-hang-oldfb
[441.929772] Starting dynamic subtest: pipe-A
[442.363740] Dynamic subtest pipe-A: SUCCESS (0.435s)
[442.375701] Starting dynamic subtest: pipe-D
[443.543696] Dynamic subtest pipe-D: SUCCESS (1.179s)
[443.546097] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.618s)
[444.364552] [123/156] (579s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[444.459937] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[445.295059] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.837s)
[445.446373] [124/156] (578s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[445.555056] Starting subtest: flip-vs-dpms-on-nop-interruptible
[445.557298] Starting dynamic subtest: A-eDP1
[447.413780] Dynamic subtest A-eDP1: SUCCESS (1.856s)
[447.425228] Starting dynamic subtest: B-eDP1
[449.229780] Dynamic subtest B-eDP1: SUCCESS (1.814s)
[449.241616] Starting dynamic subtest: C-eDP1
[451.097249] Dynamic subtest C-eDP1: SUCCESS (1.866s)
[451.109652] Starting dynamic subtest: D-eDP1
[452.908722] Dynamic subtest D-eDP1: SUCCESS (1.809s)
[452.911266] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.356s)
[454.151342] [125/156] (569s left) kms_cursor_crc (cursor-sliding-512x512)
[454.464696] Starting subtest: cursor-sliding-512x512
[454.468978] Subtest cursor-sliding-512x512: SKIP (0.001s)
[454.641761] [126/156] (569s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[454.743988] Starting subtest: fbcpsr-1p-rte
[461.779044] Subtest fbcpsr-1p-rte: SUCCESS (7.041s)
[461.937222] [127/156] (561s left) gem_exec_schedule (semaphore-codependency)
[462.016479] Starting subtest: semaphore-codependency
[462.021766] Subtest semaphore-codependency: SUCCESS (0.012s)
[462.373483] [128/156] (561s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[462.471748] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc
[462.476125] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[462.572780] [129/156] (561s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[462.684859] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[462.689118] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[462.771718] [130/156] (561s left) i915_query (query-topology-known-pci-ids)
[462.824477] Starting subtest: query-topology-known-pci-ids
[462.828786] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[462.924821] [131/156] (561s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[463.026581] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[464.196065] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.169s)
[464.340435] [132/156] (559s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[464.441115] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[464.444479] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[464.541140] [133/156] (559s left) kms_psr (fbc-psr-no-drrs)
[464.634133] Starting subtest: fbc-psr-no-drrs
[464.636172] Starting dynamic subtest: eDP-1
[464.639681] Dynamic subtest eDP-1: SKIP (0.000s)
[464.643159] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[464.721945] [134/156] (559s left) kms_pm_lpsp (kms-lpsp)
[465.825339] Starting subtest: kms-lpsp
[465.827554] Starting dynamic subtest: pipe-A-eDP-1
[465.973801] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.150s)
[466.272836] Subtest kms-lpsp: SUCCESS (0.446s)
[467.170736] [135/156] (557s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[467.264039] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[467.266188] Starting dynamic subtest: pipe-A-eDP-1
[467.322156] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[467.331975] Starting dynamic subtest: pipe-B-eDP-1
[467.388951] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[467.400055] Starting dynamic subtest: pipe-C-eDP-1
[467.456446] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[467.467485] Starting dynamic subtest: pipe-D-eDP-1
[467.524593] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[467.528812] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.009s)
[469.556432] [136/156] (554s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-blt)
[469.663846] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-blt
[469.668404] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[469.749365] [137/156] (554s left) kms_pm_backlight (bad-brightness)
[469.852825] Starting subtest: bad-brightness
[469.902236] Starting dynamic subtest: eDP-1
[470.228384] Dynamic subtest eDP-1: SUCCESS (0.322s)
[470.230590] Subtest bad-brightness: SUCCESS (0.375s)
[471.062664] [138/156] (553s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[471.116778] Starting subtest: wait-all-for-submit-delayed-submit
[471.219194] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[471.298924] [139/156] (553s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[471.357136] Starting subtest: y-tiled-to-vebox-x-tiled
[471.361841] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[471.543858] [140/156] (552s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[471.644860] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[471.647717] Starting dynamic subtest: pipe-A
[471.983181] Dynamic subtest pipe-A: SUCCESS (0.340s)
[471.993625] Starting dynamic subtest: pipe-B
[473.409352] Dynamic subtest pipe-B: SUCCESS (1.425s)
[473.420554] Starting dynamic subtest: pipe-C
[474.829760] Dynamic subtest pipe-C: SUCCESS (1.420s)
[474.840454] Starting dynamic subtest: pipe-D
[476.242337] Dynamic subtest pipe-D: SUCCESS (1.411s)
[476.244679] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.600s)
[477.446245] [141/156] (546s left) gem_exec_whisper (basic-contexts-priority)
[477.507559] Starting subtest: basic-contexts-priority
[500.744071] Subtest basic-contexts-priority: SUCCESS (23.236s)
[500.928132] [142/156] (523s left) i915_drm_fdinfo (virtual-busy)
[500.969681] Starting subtest: virtual-busy
[500.974784] Subtest virtual-busy: SKIP (0.000s)
[501.044435] [143/156] (523s left) api_intel_allocator (reserve-simple)
[501.096625] Starting subtest: reserve-simple
[501.099392] Subtest reserve-simple: SUCCESS (0.006s)
[501.247508] [144/156] (523s left) fbdev (unaligned-write)
[501.290552] Starting subtest: unaligned-write
[501.412676] Subtest unaligned-write: SUCCESS (0.119s)
[501.477326] [145/156] (523s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[501.585311] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[501.587717] Starting dynamic subtest: pipe-A-eDP-1
[501.611672] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[501.624599] Starting dynamic subtest: pipe-B-eDP-1
[501.647489] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[501.658088] Starting dynamic subtest: pipe-C-eDP-1
[501.680323] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[501.690159] Starting dynamic subtest: pipe-D-eDP-1
[501.712305] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[501.716763] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[503.857317] [146/156] (520s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt)
[503.964955] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt
[503.969406] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[504.065203] [147/156] (520s left) gem_sync (basic-each)
[504.143647] Starting subtest: basic-each
[506.457169] Subtest basic-each: SUCCESS (2.323s)
[506.641742] [148/156] (518s left) gem_basic (create-close)
[506.695911] Starting subtest: create-close
[506.700316] Subtest create-close: SUCCESS (0.000s)
[506.795231] [149/156] (517s left) gem_tiled_fence_blits (normal)
[506.851620] Subtest normal: SKIP (0.000s)
[506.920535] [150/156] (517s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[507.024363] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[507.028584] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[507.112128] [151/156] (517s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[507.208385] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[507.212715] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[507.308429] [152/156] (517s left) gem_close_race (basic-process)
[507.372766] Starting subtest: basic-process
[507.413673] Subtest basic-process: SUCCESS (0.041s)
[507.601614] [153/156] (517s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-render)
[507.697192] Starting subtest: fbcpsrhdr-rgb565-draw-render
[507.701325] Subtest fbcpsrhdr-rgb565-draw-render: SKIP (0.000s)
[507.833962] [154/156] (517s left) kms_psr (fbc-psr2-suspend)
[507.927955] Starting subtest: fbc-psr2-suspend
[507.930360] Starting dynamic subtest: eDP-1
[507.934652] Dynamic subtest eDP-1: SKIP (0.000s)
[507.938943] Subtest fbc-psr2-suspend: SKIP (0.001s)
[508.018927] [155/156] (517s left) gen9_exec_parse (bb-large)
[508.079334] Subtest bb-large: SKIP (0.000s)
[508.142632] [156/156] (516s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-indfb-msflip-blt)
[508.250352] Starting subtest: fbchdr-2p-scndscrn-indfb-msflip-blt
[508.254636] Subtest fbchdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[508.357093] Closing watchdogs
results: parsing output: 0/ for test: gem_close
results: parsing output: 1/ for test: syncobj_timeline
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_plane_multiple
results: parsing output: 4/ for test: perf_pmu
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: kms_scaling_modes
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_exec_fence
results: parsing output: 10/ for test: i915_pciid
results: parsing output: 11/ for test: kms_chamelium_color
results: parsing output: 12/ for test: kms_color
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_flip_scaled_crc
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: gem_ctx_sseu
results: parsing output: 17/ for test: gem_pxp
results: parsing output: 18/ for test: gem_exec_params
results: parsing output: 19/ for test: kms_pm_rpm
results: parsing output: 20/ for test: i915_pm_rps
results: parsing output: 21/ for test: gem_lmem_swapping
results: parsing output: 22/ for test: gem_eio
results: parsing output: 23/ for test: gem_basic
results: parsing output: 24/ for test: kms_flip
results: parsing output: 25/ for test: kms_hdmi_inject
results: parsing output: 26/ for test: kms_cursor_crc
results: parsing output: 27/ for test: kms_invalid_mode
results: parsing output: 28/ for test: kms_cursor_crc
results: parsing output: 29/ for test: gem_softpin
results: parsing output: 30/ for test: syncobj_basic
results: parsing output: 31/ for test: syncobj_eventfd
results: parsing output: 32/ for test: syncobj_timeline
results: parsing output: 33/ for test: gem_ctx_persistence
results: parsing output: 34/ for test: gem_mmap_offset
results: parsing output: 35/ for test: gem_userptr_blits
results: parsing output: 36/ for test: kms_vblank
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: gem_set_tiling_vs_blt
results: parsing output: 39/ for test: i915_pm_rc6_residency
results: parsing output: 40/ for test: gem_render_copy
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_ctx_param
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: kms_hdr
results: parsing output: 48/ for test: gem_mmap_gtt
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_exec_nop
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_exec_fence
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: gem_exec_params
results: parsing output: 55/ for test: gem_ctx_param
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_chamelium_hpd
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_chamelium_frames
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: gem_exec_schedule
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: perf_pmu
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: kms_plane_lowres
results: parsing output: 67/ for test: kms_psr2_sf
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_mmap_gtt
results: parsing output: 70/ for test: kms_feature_discovery
results: parsing output: 71/ for test: gem_cs_tlb
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_vblank
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_content_protection
results: parsing output: 77/ for test: gem_exec_fence
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_async_flips
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_ccs
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_userptr_blits
results: parsing output: 84/ for test: kms_dirtyfb
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_prop_blob
results: parsing output: 87/ for test: kms_big_fb
results: parsing output: 88/ for test: sysfs_heartbeat_interval
results: parsing output: 89/ for test: kms_psr
results: parsing output: 90/ for test: prime_self_import
results: parsing output: 91/ for test: gem_mmap_gtt
results: parsing output: 92/ for test: gem_exec_balancer
results: parsing output: 93/ for test: kms_multipipe_modeset
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_reloc
results: parsing output: 96/ for test: core_auth
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_cursor_crc
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_rotation_crc
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: device_reset
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_ppgtt
results: parsing output: 106/ for test: gem_exec_reloc
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_rotation_crc
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_exec_whisper
results: parsing output: 111/ for test: gem_watchdog
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_workarounds
results: parsing output: 114/ for test: kms_cursor_legacy
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: sw_sync
results: parsing output: 118/ for test: kms_ccs
results: parsing output: 119/ for test: kms_plane
results: parsing output: 120/ for test: kms_addfb_basic
results: parsing output: 121/ for test: kms_busy
results: parsing output: 122/ for test: kms_big_fb
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: kms_cursor_crc
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_exec_schedule
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: i915_query
results: parsing output: 130/ for test: kms_big_fb
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_psr
results: parsing output: 133/ for test: kms_pm_lpsp
results: parsing output: 134/ for test: kms_ccs
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_pm_backlight
results: parsing output: 137/ for test: syncobj_wait
results: parsing output: 138/ for test: gem_render_copy
results: parsing output: 139/ for test: kms_plane_scaling
results: parsing output: 140/ for test: gem_exec_whisper
results: parsing output: 141/ for test: i915_drm_fdinfo
results: parsing output: 142/ for test: api_intel_allocator
results: parsing output: 143/ for test: fbdev
results: parsing output: 144/ for test: kms_ccs
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: gem_sync
results: parsing output: 147/ for test: gem_basic
results: parsing output: 148/ for test: gem_tiled_fence_blits
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: gem_close_race
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: kms_psr
results: parsing output: 154/ for test: gen9_exec_parse
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.