[57.106795] Initializing watchdogs
[57.106843]   /dev/watchdog0
[57.122163] [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.126764] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.129249] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.132734] [001/156] (960s left) gem_close (basic)
[57.225660] Starting subtest: basic
[57.228087] Subtest basic: SUCCESS (0.000s)
[57.339015] [002/156] (960s left) syncobj_timeline (multi-wait-all-available-signaled)
[57.398555] Starting subtest: multi-wait-all-available-signaled
[57.400826] Subtest multi-wait-all-available-signaled: SUCCESS (0.001s)
[57.508113] [003/156] (960s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[57.603104] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[57.607439] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[57.684130] [004/156] (960s left) kms_plane_multiple (tiling-y)
[57.773611] Starting subtest: tiling-y
[57.777603] Subtest tiling-y: SKIP (0.000s)
[57.860608] [005/156] (959s left) perf_pmu (busy-idle-check-all)
[57.902139] Starting subtest: busy-idle-check-all
[57.903835] Starting dynamic subtest: rcs0
[58.559534] Dynamic subtest rcs0: SUCCESS (0.656s)
[58.570885] Starting dynamic subtest: bcs0
[59.210785] Dynamic subtest bcs0: SUCCESS (0.652s)
[59.223022] Starting dynamic subtest: vcs0
[60.297072] Dynamic subtest vcs0: SUCCESS (1.083s)
[60.310213] Starting dynamic subtest: vcs1
[62.277860] Dynamic subtest vcs1: SUCCESS (1.980s)
[62.288749] Starting dynamic subtest: vecs0
[64.326438] Dynamic subtest vecs0: SUCCESS (2.047s)
[64.336993] Starting dynamic subtest: ccs0
[64.983610] Dynamic subtest ccs0: SUCCESS (0.658s)
[64.986202] Subtest busy-idle-check-all: SUCCESS (7.083s)
[65.089820] [006/156] (952s left) syncobj_timeline (signal-point-0)
[65.149846] Starting subtest: signal-point-0
[65.152949] Subtest signal-point-0: SUCCESS (0.000s)
[65.265887] [007/156] (952s left) kms_scaling_modes (scaling-mode-full-aspect)
[65.371372] Starting subtest: scaling-mode-full-aspect
[65.373734] Starting dynamic subtest: pipe-A-eDP-1
[65.529379] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.158s)
[65.540198] Starting dynamic subtest: pipe-B-eDP-1
[66.723886] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.192s)
[66.734691] Starting dynamic subtest: pipe-C-eDP-1
[67.854067] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.129s)
[67.864296] Starting dynamic subtest: pipe-D-eDP-1
[68.999314] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.144s)
[69.001731] Subtest scaling-mode-full-aspect: SUCCESS (3.629s)
[70.175321] [008/156] (947s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[70.287183] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[70.291726] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[70.389231] [009/156] (947s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-pwrite)
[70.493669] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-pwrite
[70.497970] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[70.584159] [010/156] (947s left) gem_exec_fence (syncobj-timeline-wait)
[71.136624] Starting subtest: syncobj-timeline-wait
[72.386326] Subtest syncobj-timeline-wait: SUCCESS (1.249s)
[72.892646] [FACT gem_exec_fence (syncobj-timeline-wait)] new: kernel.kmod_is_loaded.vgem: true
[72.896258] [011/156] (945s left) i915_pciid
[73.186874] [012/156] (944s left) kms_chamelium_color (ctm-limited-range)
[73.331899] Subtest ctm-limited-range: SKIP (0.000s)
[73.382797] [013/156] (944s left) kms_color (legacy-gamma-reset)
[73.542747] Starting subtest: legacy-gamma-reset
[73.544603] Starting dynamic subtest: pipe-A-eDP-1
[73.909826] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.367s)
[73.918268] Starting dynamic subtest: pipe-B-eDP-1
[73.923092] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.013s)
[73.932971] Starting dynamic subtest: pipe-C-eDP-1
[73.935464] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.012s)
[73.945294] Starting dynamic subtest: pipe-D-eDP-1
[73.947633] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.012s)
[73.950077] Subtest legacy-gamma-reset: SUCCESS (0.405s)
[74.704313] [014/156] (943s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt)
[74.816172] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt
[74.820230] Subtest fbcpsrhdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[74.907315] [015/156] (943s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[74.999976] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[75.002311] Starting dynamic subtest: pipe-A-default-mode
[77.729681] Dynamic subtest pipe-A-default-mode: SUCCESS (2.728s)
[77.732069] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.729s)
[78.610821] [016/156] (939s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[78.712386] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[78.716943] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[78.817390] [017/156] (939s left) gem_ctx_sseu (engines)
[78.880284] Subtest engines: SKIP (0.000s)
[78.947860] [018/156] (939s left) gem_pxp (fail-invalid-protected-context)
[79.071381] Starting subtest: fail-invalid-protected-context
[79.073934] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[79.175793] [019/156] (939s left) gem_exec_params (no-vebox)
[79.230979] Starting subtest: no-vebox
[79.233321] Subtest no-vebox: SUCCESS (0.002s)
[80.384287] [020/156] (938s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[82.440418] Starting subtest: modeset-non-lpsp-stress-no-wait
[82.488497] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.045s)
[82.852502] [021/156] (935s left) i915_pm_rps (thresholds-idle-park)
[82.920510] Starting subtest: thresholds-idle-park
[82.924898] Subtest thresholds-idle-park: SKIP (0.001s)
[83.002603] [022/156] (935s left) gem_lmem_swapping (verify-random-ccs)
[83.043899] Subtest verify-random-ccs: SKIP (0.000s)
[83.090998] [023/156] (935s left) gem_eio (in-flight-internal-1us)
[83.306402] Starting subtest: in-flight-internal-1us
[84.361472] Subtest in-flight-internal-1us: SUCCESS (1.054s)
[84.536541] [024/156] (934s left) gem_basic (bad-close)
[84.591739] Starting subtest: bad-close
[84.596222] Subtest bad-close: SUCCESS (0.000s)
[84.710896] [025/156] (933s left) kms_flip (basic-plain-flip)
[84.804150] Starting subtest: basic-plain-flip
[84.806611] Starting dynamic subtest: A-eDP1
[86.529836] Dynamic subtest A-eDP1: SUCCESS (1.727s)
[86.540459] Starting dynamic subtest: B-eDP1
[88.268970] Dynamic subtest B-eDP1: SUCCESS (1.738s)
[88.282605] Starting dynamic subtest: C-eDP1
[90.008322] Dynamic subtest C-eDP1: SUCCESS (1.738s)
[90.020731] Starting dynamic subtest: D-eDP1
[91.720447] Dynamic subtest D-eDP1: SUCCESS (1.711s)
[91.722751] Subtest basic-plain-flip: SUCCESS (6.922s)
[92.900666] [026/156] (925s left) kms_hdmi_inject (inject-audio)
[93.257459] Starting subtest: inject-audio
[93.552237] Subtest inject-audio: SUCCESS (0.297s)
[94.158756] [027/156] (924s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[94.384758] Starting subtest: cursor-rapid-movement-128x42
[94.432452] Subtest cursor-rapid-movement-128x42: SKIP (0.045s)
[94.624326] [028/156] (924s left) kms_invalid_mode (bad-vtotal)
[94.720454] Starting subtest: bad-vtotal
[94.722729] Starting dynamic subtest: pipe-A-eDP-1
[95.008508] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[95.019057] Starting dynamic subtest: pipe-B-eDP-1
[95.021413] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[95.030659] Starting dynamic subtest: pipe-C-eDP-1
[95.033085] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[95.042285] Starting dynamic subtest: pipe-D-eDP-1
[95.044598] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[95.046960] Subtest bad-vtotal: SUCCESS (0.293s)
[95.841916] [029/156] (922s left) kms_cursor_crc (cursor-size-change)
[95.937105] Starting subtest: cursor-size-change
[95.939267] Starting dynamic subtest: pipe-A-eDP-1
[96.743684] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.805s)
[96.752580] Starting dynamic subtest: pipe-D-eDP-1
[99.031333] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.285s)
[99.033708] Subtest cursor-size-change: SUCCESS (3.093s)
[99.656763] [030/156] (919s left) gem_softpin (reverse)
[99.719730] Starting subtest: reverse
[99.727400] Subtest reverse: SUCCESS (0.008s)
[99.854697] [031/156] (919s left) syncobj_basic (bad-flags-fd-to-handle)
[99.909803] Starting subtest: bad-flags-fd-to-handle
[99.911618] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s)
[100.008155] [032/156] (918s left) syncobj_eventfd (invalid-bad-flags)
[100.063867] Starting subtest: invalid-bad-flags
[100.066274] Subtest invalid-bad-flags: SUCCESS (0.000s)
[100.148614] [033/156] (918s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[100.196859] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[100.306186] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.108s)
[100.395041] [034/156] (918s left) gem_ctx_persistence (legacy-engines-persistence)
[100.453619] Starting subtest: legacy-engines-persistence
[100.456270] Starting dynamic subtest: render
[101.258111] Dynamic subtest render: SUCCESS (0.806s)
[101.269557] Starting dynamic subtest: bsd1
[102.020776] Dynamic subtest bsd1: SUCCESS (0.761s)
[102.032750] Starting dynamic subtest: bsd2
[102.769117] Dynamic subtest bsd2: SUCCESS (0.746s)
[102.780536] Starting dynamic subtest: blt
[103.551643] Dynamic subtest blt: SUCCESS (0.781s)
[103.562999] Starting dynamic subtest: vebox
[104.332801] Dynamic subtest vebox: SUCCESS (0.779s)
[104.335420] Subtest legacy-engines-persistence: SUCCESS (3.882s)
[104.416422] [035/156] (914s left) gem_mmap_offset (mmap-unmap)
[104.477367] Starting subtest: mmap-unmap
[104.479252] Starting dynamic subtest: smem0
[128.735025] Dynamic subtest smem0: SUCCESS (24.258s)
[128.737781] Subtest mmap-unmap: SUCCESS (24.259s)
[128.860822] [036/156] (890s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[128.923747] Starting subtest: stress-mm-invalidate-close-overlap
[131.073081] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[131.206928] [037/156] (887s left) kms_vblank (query-idle)
[131.303192] Starting subtest: query-idle
[131.305114] Starting dynamic subtest: pipe-A-eDP-1
[133.068510] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.766s)
[133.083716] Starting dynamic subtest: pipe-D-eDP-1
[135.517062] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.448s)
[135.519394] Subtest query-idle: SUCCESS (4.216s)
[136.328936] [038/156] (882s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[136.432514] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[143.834545] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.409s)
[143.985855] [039/156] (875s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[144.055809] Subtest tiled-to-untiled: SKIP (0.000s)
[144.130598] [040/156] (875s left) i915_pm_rc6_residency (rc6-accuracy)
[144.189611] Starting subtest: rc6-accuracy
[144.191895] Starting dynamic subtest: gt0
[147.363036] Dynamic subtest gt0: SUCCESS (3.170s)
[147.375500] Starting dynamic subtest: gt1
[150.533438] Dynamic subtest gt1: SUCCESS (3.169s)
[150.536069] Subtest rc6-accuracy: SUCCESS (6.343s)
[150.608619] [041/156] (868s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled)
[150.660808] Starting subtest: yf-tiled-ccs-to-yf-tiled
[150.665159] Subtest yf-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[150.845871] [042/156] (868s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[150.957631] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[150.962106] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[151.039011] [043/156] (868s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[151.132515] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[154.794998] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.669s)
[155.954312] [044/156] (863s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-shrfb-msflip-blt)
[156.058850] Starting subtest: hdr-2p-scndscrn-shrfb-msflip-blt
[156.063362] Subtest hdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[156.155897] [045/156] (863s left) gem_ctx_param (invalid-get-engines)
[156.205604] Starting subtest: invalid-get-engines
[156.208059] Subtest invalid-get-engines: SUCCESS (0.001s)
[156.310963] [046/156] (863s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[156.410359] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[156.415021] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[156.506189] [047/156] (863s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[156.609853] Starting subtest: y-tiled-32bpp-rotate-0
[156.614143] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[156.705790] [048/156] (862s left) kms_hdr (static-toggle-dpms)
[156.801969] Starting subtest: static-toggle-dpms
[156.804290] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[156.808299] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[156.817892] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[156.822170] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[156.826695] Subtest static-toggle-dpms: SKIP (0.002s)
[156.872705] [049/156] (862s left) gem_mmap_gtt (basic-small-bo)
[156.931330] Subtest basic-small-bo: SKIP (0.000s)
[156.991589] [050/156] (862s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[157.079856] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[157.083451] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[157.189698] [051/156] (862s left) gem_exec_nop (basic-parallel)
[157.270781] Starting subtest: basic-parallel
[173.254517] Subtest basic-parallel: SUCCESS (15.990s)
[173.491926] [052/156] (846s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[173.592401] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[173.597066] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[173.700113] [053/156] (846s left) gem_exec_fence (parallel)
[173.872689] Starting subtest: parallel
[173.875241] Starting dynamic subtest: rcs0
[176.024937] Dynamic subtest rcs0: SUCCESS (2.152s)
[176.036082] Starting dynamic subtest: bcs0
[178.178056] Dynamic subtest bcs0: SUCCESS (2.152s)
[178.187470] Starting dynamic subtest: vcs0
[180.331930] Dynamic subtest vcs0: SUCCESS (2.153s)
[180.343474] Starting dynamic subtest: vcs1
[182.483708] Dynamic subtest vcs1: SUCCESS (2.151s)
[182.495629] Starting dynamic subtest: vecs0
[184.638061] Dynamic subtest vecs0: SUCCESS (2.154s)
[184.649560] Starting dynamic subtest: ccs0
[186.791284] Dynamic subtest ccs0: SUCCESS (2.153s)
[186.793321] Subtest parallel: SUCCESS (12.919s)
[188.389412] [054/156] (831s left) kms_psr (psr2-cursor-mmap-cpu)
[188.487090] Starting subtest: psr2-cursor-mmap-cpu
[188.489564] Starting dynamic subtest: eDP-1
[190.165144] Dynamic subtest eDP-1: SUCCESS (1.677s)
[190.167505] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.677s)
[191.014888] [055/156] (828s left) gem_exec_params (secure-non-root)
[191.074575] Starting subtest: secure-non-root
[191.078830] Subtest secure-non-root: SKIP (0.000s)
[191.180489] [056/156] (828s left) gem_ctx_param (root-set)
[191.222845] Starting subtest: root-set
[191.225291] Subtest root-set: SUCCESS (0.001s)
[191.320471] [057/156] (828s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[191.412717] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[191.416867] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[191.533362] [058/156] (828s left) kms_chamelium_hpd (hdmi-hpd-fast)
[191.919606] Subtest hdmi-hpd-fast: SKIP (0.000s)
[192.699735] [059/156] (827s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-pwrite)
[192.798275] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-pwrite
[192.802176] Subtest psrhdr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[192.912342] [060/156] (827s left) kms_chamelium_frames (hdmi-aspect-ratio)
[193.302128] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[194.093012] [061/156] (826s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[194.200814] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt
[194.205206] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[194.302418] [062/156] (825s left) kms_flip (dpms-vs-vblank-race)
[194.389324] Starting subtest: dpms-vs-vblank-race
[194.391676] Starting dynamic subtest: A-eDP1
[198.270247] Dynamic subtest A-eDP1: SUCCESS (3.878s)
[198.280595] Starting dynamic subtest: B-eDP1
[201.785426] Dynamic subtest B-eDP1: SUCCESS (3.515s)
[201.796296] Starting dynamic subtest: C-eDP1
[205.253585] Dynamic subtest C-eDP1: SUCCESS (3.466s)
[205.264499] Starting dynamic subtest: D-eDP1
[208.778849] Dynamic subtest D-eDP1: SUCCESS (3.523s)
[208.781225] Subtest dpms-vs-vblank-race: SUCCESS (14.393s)
[209.611727] [063/156] (810s left) gem_exec_schedule (noreorder-priority)
[209.788494] Starting subtest: noreorder-priority
[209.790920] Starting dynamic subtest: rcs0
[210.923710] Dynamic subtest rcs0: SUCCESS (1.134s)
[210.934727] Starting dynamic subtest: vcs0
[212.288194] Dynamic subtest vcs0: SUCCESS (1.362s)
[212.301604] Starting dynamic subtest: vcs1
[214.277369] Dynamic subtest vcs1: SUCCESS (1.987s)
[214.289868] Starting dynamic subtest: vecs0
[216.299650] Dynamic subtest vecs0: SUCCESS (2.020s)
[216.312098] Starting dynamic subtest: ccs0
[217.449819] Dynamic subtest ccs0: SUCCESS (1.149s)
[217.452517] Subtest noreorder-priority: SUCCESS (7.661s)
[217.636366] [064/156] (802s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[217.691580] Starting subtest: single-wait-all-for-submit-available-signaled
[217.694059] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[217.788408] [065/156] (802s left) perf_pmu (semaphore-busy)
[217.829709] Starting subtest: semaphore-busy
[217.832038] Starting dynamic subtest: rcs0
[219.477397] Dynamic subtest rcs0: SUCCESS (1.642s)
[219.489948] Starting dynamic subtest: bcs0
[221.111081] Dynamic subtest bcs0: SUCCESS (1.631s)
[221.123490] Starting dynamic subtest: vcs0
[224.833037] Dynamic subtest vcs0: SUCCESS (3.719s)
[224.845840] Starting dynamic subtest: vcs1
[230.787546] Dynamic subtest vcs1: SUCCESS (5.951s)
[230.799875] Starting dynamic subtest: vecs0
[236.800894] Dynamic subtest vecs0: SUCCESS (6.010s)
[236.814030] Starting dynamic subtest: ccs0
[239.876294] Dynamic subtest ccs0: SUCCESS (3.073s)
[239.879356] Subtest semaphore-busy: SUCCESS (22.044s)
[239.996805] [066/156] (780s left) kms_psr (fbc-pr-primary-render)
[240.100057] Starting subtest: fbc-pr-primary-render
[240.104285] Subtest fbc-pr-primary-render: SKIP (0.000s)
[240.186738] [067/156] (780s left) kms_plane_lowres (tiling-none)
[240.285160] Starting subtest: tiling-none
[240.287445] Starting dynamic subtest: pipe-A-eDP-1
[240.292111] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[240.593254] Starting dynamic subtest: pipe-B-eDP-1
[240.597929] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[240.607283] Starting dynamic subtest: pipe-C-eDP-1
[240.611950] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[240.621197] Starting dynamic subtest: pipe-D-eDP-1
[240.625370] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[240.629665] Subtest tiling-none: SKIP (0.305s)
[241.408819] [068/156] (779s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[241.513381] Starting subtest: psr2-cursor-plane-move-continuous-sf
[241.515673] Starting dynamic subtest: pipe-A-eDP-1
[251.854614] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.345s)
[251.864661] Starting dynamic subtest: pipe-B-eDP-1
[262.942057] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.087s)
[262.944392] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.435s)
[263.799584] [069/156] (756s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[263.899482] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[263.903467] Subtest fbchdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[264.008521] [070/156] (756s left) gem_mmap_gtt (medium-copy-odd)
[264.070644] Subtest medium-copy-odd: SKIP (0.000s)
[264.130355] [071/156] (756s left) kms_feature_discovery (chamelium)
[264.221675] Starting subtest: chamelium
[264.226251] Subtest chamelium: SKIP (0.000s)
[264.304733] [072/156] (756s left) gem_cs_tlb (engines)
[264.360187] Starting subtest: engines
[264.362223] Starting dynamic subtest: rcs0
[266.515204] Dynamic subtest rcs0: SUCCESS (2.152s)
[266.527761] Starting dynamic subtest: bcs0
[268.668790] Dynamic subtest bcs0: SUCCESS (2.152s)
[268.681630] Starting dynamic subtest: vcs0
[270.821563] Dynamic subtest vcs0: SUCCESS (2.153s)
[270.834083] Starting dynamic subtest: vcs1
[272.975273] Dynamic subtest vcs1: SUCCESS (2.152s)
[272.984759] Starting dynamic subtest: vecs0
[275.129327] Dynamic subtest vecs0: SUCCESS (2.152s)
[275.141643] Starting dynamic subtest: ccs0
[277.285084] Dynamic subtest ccs0: SUCCESS (2.156s)
[277.287718] Subtest engines: SUCCESS (12.925s)
[277.411691] [073/156] (743s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[277.516494] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[284.269524] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.757s)
[284.420616] [074/156] (736s left) kms_vblank (ts-continuation-modeset-rpm)
[284.515488] Starting subtest: ts-continuation-modeset-rpm
[284.517788] Starting dynamic subtest: pipe-A-eDP-1
[287.068470] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.552s)
[287.083928] Starting dynamic subtest: pipe-D-eDP-1
[289.244535] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.176s)
[289.247114] Subtest ts-continuation-modeset-rpm: SUCCESS (4.731s)
[290.098442] [075/156] (730s left) gem_exec_reloc (basic-range)
[290.165713] Subtest basic-range: SKIP (0.000s)
[290.238196] [076/156] (730s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-pwrite)
[290.335587] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-pwrite
[290.339789] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[290.456839] [077/156] (730s left) kms_content_protection (dp-mst-lic-type-1)
[290.600912] Starting subtest: dp-mst-lic-type-1
[290.605705] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[290.719876] [078/156] (730s left) gem_exec_fence (basic-await)
[290.896525] Starting subtest: basic-await
[290.898772] Starting dynamic subtest: rcs0
[290.972820] Dynamic subtest rcs0: SUCCESS (0.076s)
[290.986906] Starting dynamic subtest: bcs0
[291.047847] Dynamic subtest bcs0: SUCCESS (0.073s)
[291.061460] Starting dynamic subtest: vcs0
[291.122405] Dynamic subtest vcs0: SUCCESS (0.073s)
[291.135322] Starting dynamic subtest: vcs1
[291.197085] Dynamic subtest vcs1: SUCCESS (0.072s)
[291.209671] Starting dynamic subtest: vecs0
[291.271915] Dynamic subtest vecs0: SUCCESS (0.073s)
[291.284661] Starting dynamic subtest: ccs0
[291.345346] Dynamic subtest ccs0: SUCCESS (0.072s)
[291.348184] Subtest basic-await: SUCCESS (0.449s)
[293.412104] [079/156] (727s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[293.521596] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[293.525628] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[293.602182] [080/156] (727s left) kms_async_flips (invalid-async-flip)
[293.688285] Starting subtest: invalid-async-flip
[294.054200] Starting dynamic subtest: pipe-A-eDP-1
[294.894332] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.842s)
[295.255840] Starting dynamic subtest: pipe-B-eDP-1
[296.100160] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.852s)
[296.473870] Starting dynamic subtest: pipe-C-eDP-1
[297.290127] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.827s)
[297.643695] Starting dynamic subtest: pipe-D-eDP-1
[298.498819] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.863s)
[298.501180] Subtest invalid-async-flip: SUCCESS (4.813s)
[299.710611] [081/156] (721s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-plflip-blt)
[299.816056] Starting subtest: hdr-1p-primscrn-indfb-plflip-blt
[299.820325] Subtest hdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[299.925512] [082/156] (721s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[300.025796] Starting subtest: crc-primary-suspend-y-tiled-ccs
[300.028094] Starting dynamic subtest: pipe-A-eDP-1
[300.036813] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[300.046997] Starting dynamic subtest: pipe-B-eDP-1
[300.055722] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[300.065639] Starting dynamic subtest: pipe-C-eDP-1
[300.074181] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[300.083573] Starting dynamic subtest: pipe-D-eDP-1
[300.092609] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[300.097116] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.005s)
[302.302242] [083/156] (718s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-onoff)
[302.393861] Starting subtest: hdr-2p-primscrn-cur-indfb-onoff
[302.398107] Subtest hdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[302.498524] [084/156] (718s left) gem_userptr_blits (coherency-sync)
[302.567505] Starting subtest: coherency-sync
[302.895366] Subtest coherency-sync: SKIP (0.287s)
[302.985737] [085/156] (718s left) kms_dirtyfb (drrs-dirtyfb-ioctl)
[303.126342] Starting subtest: drrs-dirtyfb-ioctl
[303.139631] Subtest drrs-dirtyfb-ioctl: SKIP (0.001s)
[303.233405] [086/156] (717s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[303.344364] Starting subtest: x-tiled-16bpp-rotate-0
[304.759218] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.417s)
[304.907473] [087/156] (716s left) kms_prop_blob (invalid-set-prop-any)
[304.964444] Starting subtest: invalid-set-prop-any
[304.966435] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[305.079381] [088/156] (716s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[305.183266] Starting subtest: 4-tiled-32bpp-rotate-270
[305.202928] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[305.331477] [089/156] (716s left) sysfs_heartbeat_interval (nopreempt)
[305.386307] Starting subtest: nopreempt
[305.388311] Starting dynamic subtest: vecs0
[315.274348] Dynamic subtest vecs0: SUCCESS (9.883s)
[315.286509] Starting dynamic subtest: ccs0
[319.267500] Dynamic subtest ccs0: SUCCESS (3.992s)
[319.278767] Starting dynamic subtest: vcs0
[329.299185] Dynamic subtest vcs0: SUCCESS (10.031s)
[329.314961] Starting dynamic subtest: bcs0
[333.226889] Dynamic subtest bcs0: SUCCESS (3.924s)
[333.238962] Starting dynamic subtest: rcs0
[337.207306] Dynamic subtest rcs0: SUCCESS (3.977s)
[337.218220] Starting dynamic subtest: vcs1
[346.315314] Dynamic subtest vcs1: SUCCESS (9.108s)
[346.317846] Subtest nopreempt: SUCCESS (40.928s)
[346.399605] [090/156] (674s left) kms_psr (psr-cursor-mmap-cpu)
[346.497391] Starting subtest: psr-cursor-mmap-cpu
[346.499826] Starting dynamic subtest: eDP-1
[348.262063] Dynamic subtest eDP-1: SUCCESS (1.762s)
[348.264133] Subtest psr-cursor-mmap-cpu: SUCCESS (1.763s)
[349.110933] [091/156] (672s left) prime_self_import (basic-with_one_bo_two_files)
[349.148964] Starting subtest: basic-with_one_bo_two_files
[349.173526] Subtest basic-with_one_bo_two_files: SUCCESS (0.025s)
[349.290707] [092/156] (672s left) gem_mmap_gtt (basic-small-copy)
[349.357093] Subtest basic-small-copy: SKIP (0.000s)
[349.419571] [093/156] (672s left) gem_exec_balancer (bonded-sync)
[349.485330] Starting subtest: bonded-sync
[349.489595] Subtest bonded-sync: SKIP (0.000s)
[349.783019] [094/156] (671s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[349.881219] Starting subtest: basic-max-pipe-crc-check
[349.885797] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[349.930037] [095/156] (671s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[350.043273] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[350.047501] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[350.159056] [096/156] (671s left) gem_exec_reloc (basic-gtt-wc-active)
[350.225075] Subtest basic-gtt-wc-active: SKIP (0.000s)
[350.303319] [097/156] (671s left) core_auth (getclient-master-drop)
[350.336741] Starting subtest: getclient-master-drop
[350.363406] Subtest getclient-master-drop: SUCCESS (0.027s)
[350.480218] [098/156] (671s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[350.575126] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[350.579299] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[350.683055] [099/156] (671s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt)
[350.772688] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt
[350.776254] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[350.875398] [100/156] (670s left) kms_cursor_crc (cursor-offscreen-32x10)
[351.049312] Starting subtest: cursor-offscreen-32x10
[351.095190] Subtest cursor-offscreen-32x10: SKIP (0.042s)
[351.409202] [101/156] (670s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[351.521552] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[351.524806] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[351.643284] [102/156] (670s left) kms_rotation_crc (sprite-rotation-180)
[351.731166] Starting subtest: sprite-rotation-180
[358.586598] Subtest sprite-rotation-180: SUCCESS (6.856s)
[359.796410] [103/156] (662s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-indfb-plflip-blt)
[359.904666] Starting subtest: fbcpsrhdr-2p-primscrn-indfb-plflip-blt
[359.908772] Subtest fbcpsrhdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[360.018429] [104/156] (661s left) device_reset (cold-reset-bound)
[360.068061] Starting subtest: cold-reset-bound
[360.072219] Subtest cold-reset-bound: SKIP (0.000s)
[360.141884] [105/156] (661s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-gtt)
[360.241660] Starting subtest: hdr-rgb565-draw-mmap-gtt
[360.246107] Subtest hdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[360.340125] [106/156] (661s left) gem_ppgtt (blt-vs-render-ctxn)
[360.400583] Starting subtest: blt-vs-render-ctxN
[393.137388] Subtest blt-vs-render-ctxN: SUCCESS (32.733s)
[393.283300] [107/156] (628s left) gem_exec_reloc (basic-write-gtt-noreloc)
[393.383061] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[393.458807] [108/156] (628s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[393.580345] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[393.584204] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[393.696894] [109/156] (628s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[393.792070] Starting subtest: primary-yf-tiled-reflect-x-90
[393.796228] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[393.885570] [110/156] (628s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[393.984594] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[397.652304] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.673s)
[398.808517] [111/156] (623s left) gem_exec_whisper (basic-queues-forked)
[398.882757] Starting subtest: basic-queues-forked
[423.214960] Subtest basic-queues-forked: SUCCESS (24.328s)
[423.404725] [112/156] (598s left) gem_watchdog (default-virtual)
[423.480828] Starting subtest: default-virtual
[429.765673] Subtest default-virtual: SUCCESS (6.296s)
[429.863007] [113/156] (592s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[429.977298] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[429.981546] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[430.076409] [114/156] (592s left) gem_workarounds (reset-context)
[430.149164] Starting subtest: reset-context
[430.282744] Subtest reset-context: SUCCESS (0.134s)
[430.430700] [115/156] (592s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[430.554477] Starting subtest: 2x-long-cursor-vs-flip-atomic
[430.558924] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.001s)
[430.714626] [116/156] (591s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[430.779996] Starting subtest: etime-multi-wait-all-for-submit-submitted
[430.890526] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.108s)
[430.969959] [117/156] (591s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[431.064532] Starting subtest: psr-indfb-scaledprimary
[436.794591] Subtest psr-indfb-scaledprimary: SUCCESS (5.735s)
[436.917237] [118/156] (585s left) sw_sync (sync_random_merge)
[436.949913] Starting subtest: sync_random_merge
[436.996541] Subtest sync_random_merge: SUCCESS (0.048s)
[437.072870] [119/156] (585s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[437.172265] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[437.174547] Starting dynamic subtest: pipe-A-eDP-1
[437.196706] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[437.210584] Starting dynamic subtest: pipe-B-eDP-1
[437.230986] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[437.241086] Starting dynamic subtest: pipe-C-eDP-1
[437.263066] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[437.272807] Starting dynamic subtest: pipe-D-eDP-1
[437.294520] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[437.298953] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[439.384420] [120/156] (583s left) kms_plane (pixel-format-y-tiled-ccs-modifier)
[439.483145] Starting subtest: pixel-format-y-tiled-ccs-modifier
[439.487255] Subtest pixel-format-y-tiled-ccs-modifier: SKIP (0.000s)
[439.569929] [121/156] (583s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[439.671419] Starting subtest: addfb25-y-tiled-small-legacy
[439.675529] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[439.773434] [122/156] (582s left) kms_busy (extended-pageflip-hang-oldfb)
[439.879936] Starting subtest: extended-pageflip-hang-oldfb
[439.882318] Starting dynamic subtest: pipe-A
[440.320249] Dynamic subtest pipe-A: SUCCESS (0.440s)
[440.331853] Starting dynamic subtest: pipe-D
[441.504435] Dynamic subtest pipe-D: SUCCESS (1.183s)
[441.506799] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.628s)
[442.397601] [123/156] (580s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[442.503382] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[443.372952] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.871s)
[443.537365] [124/156] (579s left) kms_flip (flip-vs-dpms-on-nop-interruptible)
[443.642247] Starting subtest: flip-vs-dpms-on-nop-interruptible
[443.644789] Starting dynamic subtest: A-eDP1
[445.556299] Dynamic subtest A-eDP1: SUCCESS (1.913s)
[445.571894] Starting dynamic subtest: B-eDP1
[447.446528] Dynamic subtest B-eDP1: SUCCESS (1.887s)
[447.457485] Starting dynamic subtest: C-eDP1
[449.255174] Dynamic subtest C-eDP1: SUCCESS (1.806s)
[449.267246] Starting dynamic subtest: D-eDP1
[451.135349] Dynamic subtest D-eDP1: SUCCESS (1.880s)
[451.137680] Subtest flip-vs-dpms-on-nop-interruptible: SUCCESS (7.496s)
[452.388810] [125/156] (570s left) kms_cursor_crc (cursor-sliding-512x512)
[452.693709] Starting subtest: cursor-sliding-512x512
[452.698783] Subtest cursor-sliding-512x512: SKIP (0.001s)
[452.849532] [126/156] (570s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[452.947066] Starting subtest: fbcpsr-1p-rte
[459.906727] Subtest fbcpsr-1p-rte: SUCCESS (6.965s)
[460.057227] [127/156] (562s left) gem_exec_schedule (semaphore-codependency)
[460.124094] Starting subtest: semaphore-codependency
[460.131711] Subtest semaphore-codependency: SUCCESS (0.016s)
[460.485564] [128/156] (562s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[460.590616] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc
[460.594986] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[460.678854] [129/156] (562s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[460.777035] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[460.781186] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[460.861789] [130/156] (562s left) i915_query (query-topology-known-pci-ids)
[460.915520] Starting subtest: query-topology-known-pci-ids
[460.920153] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[461.009469] [131/156] (562s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0)
[461.103714] Starting subtest: linear-max-hw-stride-32bpp-rotate-0
[462.216435] Subtest linear-max-hw-stride-32bpp-rotate-0: SUCCESS (1.112s)
[462.332205] [132/156] (560s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[462.435321] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[462.438792] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[462.541453] [133/156] (560s left) kms_psr (fbc-psr-no-drrs)
[462.627848] Starting subtest: fbc-psr-no-drrs
[462.629961] Starting dynamic subtest: eDP-1
[462.633703] Dynamic subtest eDP-1: SKIP (0.000s)
[462.637322] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[462.704970] [134/156] (560s left) kms_pm_lpsp (kms-lpsp)
[463.799421] Starting subtest: kms-lpsp
[463.801437] Starting dynamic subtest: pipe-A-eDP-1
[463.947527] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.149s)
[464.242970] Subtest kms-lpsp: SUCCESS (0.445s)
[465.084386] [135/156] (558s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[465.185245] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[465.187548] Starting dynamic subtest: pipe-A-eDP-1
[465.246615] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[465.256770] Starting dynamic subtest: pipe-B-eDP-1
[465.312250] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[465.322983] Starting dynamic subtest: pipe-C-eDP-1
[465.377054] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[465.388786] Starting dynamic subtest: pipe-D-eDP-1
[465.444986] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[465.448934] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.009s)
[467.484145] [136/156] (555s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-blt)
[467.587293] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-blt
[467.591880] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[467.693715] [137/156] (555s left) kms_pm_backlight (bad-brightness)
[467.783281] Starting subtest: bad-brightness
[467.834720] Starting dynamic subtest: eDP-1
[468.141521] Dynamic subtest eDP-1: SUCCESS (0.308s)
[468.143879] Subtest bad-brightness: SUCCESS (0.361s)
[468.996432] [138/156] (554s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[469.060618] Starting subtest: wait-all-for-submit-delayed-submit
[469.162038] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[469.246122] [139/156] (554s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[469.304572] Starting subtest: y-tiled-to-vebox-x-tiled
[469.308776] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[469.509136] [140/156] (554s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[469.610081] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[469.612419] Starting dynamic subtest: pipe-A
[469.960322] Dynamic subtest pipe-A: SUCCESS (0.351s)
[469.977365] Starting dynamic subtest: pipe-B
[471.354347] Dynamic subtest pipe-B: SUCCESS (1.392s)
[471.365830] Starting dynamic subtest: pipe-C
[472.766838] Dynamic subtest pipe-C: SUCCESS (1.412s)
[472.777884] Starting dynamic subtest: pipe-D
[474.143804] Dynamic subtest pipe-D: SUCCESS (1.376s)
[474.146242] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.536s)
[475.284595] [141/156] (548s left) gem_exec_whisper (basic-contexts-priority)
[475.349686] Starting subtest: basic-contexts-priority
[499.656083] Subtest basic-contexts-priority: SUCCESS (24.302s)
[499.842307] [142/156] (523s left) i915_drm_fdinfo (virtual-busy)
[499.883947] Starting subtest: virtual-busy
[499.888434] Subtest virtual-busy: SKIP (0.000s)
[499.953516] [143/156] (523s left) api_intel_allocator (reserve-simple)
[500.016485] Starting subtest: reserve-simple
[500.021769] Subtest reserve-simple: SUCCESS (0.007s)
[500.129532] [144/156] (523s left) fbdev (unaligned-write)
[500.178462] Starting subtest: unaligned-write
[500.282634] Subtest unaligned-write: SUCCESS (0.103s)
[500.354395] [145/156] (523s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[500.453069] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[500.455343] Starting dynamic subtest: pipe-A-eDP-1
[500.477948] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[500.489921] Starting dynamic subtest: pipe-B-eDP-1
[500.511587] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[500.521620] Starting dynamic subtest: pipe-C-eDP-1
[500.543338] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[500.553475] Starting dynamic subtest: pipe-D-eDP-1
[500.576534] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[500.581427] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[502.681362] [146/156] (521s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt)
[502.785640] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt
[502.789932] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[502.878110] [147/156] (520s left) gem_sync (basic-each)
[502.946139] Starting subtest: basic-each
[505.220156] Subtest basic-each: SUCCESS (2.285s)
[505.407593] [148/156] (518s left) gem_basic (create-close)
[505.454887] Starting subtest: create-close
[505.459364] Subtest create-close: SUCCESS (0.000s)
[505.581405] [149/156] (518s left) gem_tiled_fence_blits (normal)
[505.645629] Subtest normal: SKIP (0.000s)
[505.714407] [150/156] (518s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[505.813238] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[505.817128] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[505.908213] [151/156] (518s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[506.000418] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[506.004644] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[506.092257] [152/156] (517s left) gem_close_race (basic-process)
[506.145679] Starting subtest: basic-process
[506.192776] Subtest basic-process: SUCCESS (0.046s)
[506.392919] [153/156] (517s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-render)
[506.489750] Starting subtest: fbcpsrhdr-rgb565-draw-render
[506.493831] Subtest fbcpsrhdr-rgb565-draw-render: SKIP (0.000s)
[506.592460] [154/156] (517s left) kms_psr (fbc-psr2-suspend)
[506.694242] Starting subtest: fbc-psr2-suspend
[506.696544] Starting dynamic subtest: eDP-1
[506.700661] Dynamic subtest eDP-1: SKIP (0.000s)
[506.704811] Subtest fbc-psr2-suspend: SKIP (0.001s)
[506.775781] [155/156] (517s left) gen9_exec_parse (bb-large)
[506.834611] Subtest bb-large: SKIP (0.000s)
[506.904989] [156/156] (517s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-indfb-msflip-blt)
[507.012449] Starting subtest: fbchdr-2p-scndscrn-indfb-msflip-blt
[507.016608] Subtest fbchdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[507.111153] 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.