[60.833755] Initializing watchdogs
[60.833831]   /dev/watchdog0
[60.848157] [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]
[60.852522] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.854742] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.857946] [001/131] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render)
[60.982231] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render
[67.612653] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SUCCESS (6.635s)
[67.746574] [002/131] (953s left) gem_exec_balancer (parallel-contexts)
[67.991373] Starting subtest: parallel-contexts
[70.560092] Subtest parallel-contexts: SUCCESS (2.568s)
[70.663278] [003/131] (950s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[70.759290] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[70.763641] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[70.970435] [004/131] (950s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[71.075797] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[71.080317] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[71.338175] [005/131] (950s left) gem_mmap_gtt (flink-race)
[71.518846] Subtest flink-race: SKIP (0.000s)
[71.664405] [006/131] (949s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[71.810324] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[71.812676] Starting dynamic subtest: pipe-A-eDP-1
[74.041178] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.230s)
[74.049344] Starting dynamic subtest: pipe-B-eDP-1
[76.149845] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.092s)
[76.158977] Starting dynamic subtest: pipe-C-eDP-1
[78.121935] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.985s)
[78.130344] Starting dynamic subtest: pipe-D-eDP-1
[80.142869] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.020s)
[80.145249] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.331s)
[81.390434] [007/131] (940s left) kms_cursor_edge_walk (128x128-right-edge)
[81.486811] Starting subtest: 128x128-right-edge
[81.489272] Starting dynamic subtest: pipe-A-eDP-1
[84.951076] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.461s)
[84.959662] Starting dynamic subtest: pipe-D-eDP-1
[88.061624] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.110s)
[88.063693] Subtest 128x128-right-edge: SUCCESS (6.572s)
[88.870481] [008/131] (932s left) kms_psr (fbc-psr-sprite-render)
[88.964194] Starting subtest: fbc-psr-sprite-render
[88.966491] Starting dynamic subtest: eDP-1
[88.970615] Dynamic subtest eDP-1: SKIP (0.000s)
[88.974759] Subtest fbc-psr-sprite-render: SKIP (0.000s)
[89.038123] [009/131] (932s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs)
[89.120055] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs
[89.122418] Starting dynamic subtest: pipe-A-eDP-1
[90.002784] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.873s)
[90.010935] Starting dynamic subtest: pipe-B-eDP-1
[91.496991] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.491s)
[91.505492] Starting dynamic subtest: pipe-C-eDP-1
[92.980158] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.483s)
[92.987839] Starting dynamic subtest: pipe-D-eDP-1
[94.504846] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.523s)
[94.507191] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.374s)
[95.679607] [010/131] (926s left) api_intel_allocator (execbuf-with-allocator)
[95.746368] Starting subtest: execbuf-with-allocator
[95.751607] Subtest execbuf-with-allocator: SUCCESS (0.006s)
[95.891105] [011/131] (925s left) gem_ctx_param (set-priority-invalid-size)
[95.940731] Starting subtest: set-priority-invalid-size
[95.943056] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[96.013079] [012/131] (925s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[96.116350] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[96.120209] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[96.212675] [013/131] (925s left) gem_userptr_blits (process-exit-busy)
[96.274949] Starting subtest: process-exit-busy
[96.287500] Subtest process-exit-busy: SUCCESS (0.013s)
[96.418382] [014/131] (925s left) prime_mmap (test_aperture_limit)
[96.470114] Starting subtest: test_aperture_limit
[96.472153] Starting dynamic subtest: test_aperture_limit-smem
[96.482142] Dynamic subtest test_aperture_limit-smem: SKIP (0.010s)
[96.486667] Subtest test_aperture_limit: SKIP (0.011s)
[96.552405] [015/131] (925s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[101.545905] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[101.551208] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.003s)
[102.383335] [016/131] (919s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[102.763846] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[103.580010] [017/131] (918s left) kms_prop_blob (invalid-set-prop-any)
[103.643541] Starting subtest: invalid-set-prop-any
[103.645935] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[103.761515] [018/131] (918s left) gem_tiled_fence_blits (normal)
[103.820274] Subtest normal: SKIP (0.000s)
[103.889733] [019/131] (918s left) gem_basic (create-close)
[103.938455] Starting subtest: create-close
[103.942718] Subtest create-close: SUCCESS (0.000s)
[104.026008] [020/131] (918s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[109.059261] Starting subtest: psr2-primary-plane-update-sf-dmg-area-big-fb
[109.061522] Starting dynamic subtest: pipe-A-eDP-1
[119.776079] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.716s)
[119.784796] Starting dynamic subtest: pipe-B-eDP-1
[131.447525] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.670s)
[131.449868] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SUCCESS (22.390s)
[132.279361] [021/131] (889s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[132.388761] Starting subtest: psr-rgb565-draw-mmap-gtt
[132.392854] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[132.498534] [022/131] (889s left) kms_cursor_crc (cursor-random-64x21)
[132.682723] Starting subtest: cursor-random-64x21
[132.732699] Subtest cursor-random-64x21: SKIP (0.044s)
[132.964673] [023/131] (889s left) kms_pm_dc (dc6-dpms)
[134.064315] Starting subtest: dc6-dpms
[136.157140] Subtest dc6-dpms: SUCCESS (2.093s)
[136.230754] [024/131] (886s left) gem_exec_reloc (basic-wc-read-noreloc)
[136.293767] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[136.380900] [025/131] (886s left) gem_exec_params (invalid-ring)
[136.437651] Starting subtest: invalid-ring
[136.439987] Subtest invalid-ring: SUCCESS (0.000s)
[136.556663] [026/131] (885s left) i915_selftest (perf)
[139.997653] Starting subtest: perf
[139.999792] Starting dynamic subtest: engine_cs
[144.326273] Dynamic subtest engine_cs: SUCCESS (4.328s)
[144.334256] Starting dynamic subtest: request
[151.738697] Dynamic subtest request: SUCCESS (7.410s)
[151.746112] Starting dynamic subtest: migrate
[156.120863] Dynamic subtest migrate: SUCCESS (4.380s)
[156.129692] Starting dynamic subtest: region
[162.201901] Dynamic subtest region: SUCCESS (6.078s)
[162.204769] Subtest perf: SUCCESS (22.203s)
[162.231969] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[162.235184] [027/131] (860s left) gem_mmap_gtt (basic-small-bo-tiledx)
[163.703721] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[163.762335] [FACT gem_mmap_gtt (basic-small-bo-tiledx)] new: kernel.kmod_is_loaded.i915: true
[163.766065] [028/131] (858s left) gem_mmap_wc (write-gtt-read-wc)
[163.825716] Subtest write-gtt-read-wc: SKIP (0.000s)
[163.871845] [029/131] (858s left) kms_pm_rpm (universal-planes)
[166.117502] Starting subtest: universal-planes
[166.120454] Starting dynamic subtest: plane-33
[174.431643] Dynamic subtest plane-33: SUCCESS (8.314s)
[174.441086] Starting dynamic subtest: plane-43
[182.799082] Dynamic subtest plane-43: SUCCESS (8.365s)
[182.808948] Starting dynamic subtest: plane-53
[191.602241] Dynamic subtest plane-53: SUCCESS (8.799s)
[191.611360] Starting dynamic subtest: plane-63
[200.180655] Dynamic subtest plane-63: SUCCESS (8.576s)
[200.190935] Starting dynamic subtest: plane-73
[208.562657] Dynamic subtest plane-73: SUCCESS (8.380s)
[208.572660] Starting dynamic subtest: plane-83
[216.936064] Dynamic subtest plane-83: SUCCESS (8.371s)
[216.938563] Subtest universal-planes: SUCCESS (50.820s)
[217.306140] [030/131] (805s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[217.423427] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[217.427787] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[217.526507] [031/131] (805s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels)
[217.634262] Starting subtest: plane-all-modeset-transition-fencing-internal-panels
[217.636153] Starting dynamic subtest: pipe-A-eDP-1
[218.937292] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.304s)
[219.214351] Starting dynamic subtest: pipe-B-eDP-1
[220.258699] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.052s)
[220.525548] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.892s)
[221.361956] [032/131] (801s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[221.469795] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[221.474324] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[221.600621] [033/131] (801s left) gem_ppgtt (shrink-vs-evict-pinned)
[221.669212] Starting subtest: shrink-vs-evict-pinned
[226.928752] Subtest shrink-vs-evict-pinned: SUCCESS (5.257s)
[227.044965] [034/131] (795s left) gem_spin_batch (resubmit-all)
[227.113052] Starting subtest: resubmit-all
[227.115427] Starting dynamic subtest: rcs0
[227.133517] Dynamic subtest rcs0: SUCCESS (0.020s)
[227.143628] Starting dynamic subtest: bcs0
[227.145975] Dynamic subtest bcs0: SUCCESS (0.003s)
[227.153726] Starting dynamic subtest: vcs0
[227.155808] Dynamic subtest vcs0: SUCCESS (0.003s)
[227.163071] Starting dynamic subtest: vcs1
[227.165170] Dynamic subtest vcs1: SUCCESS (0.003s)
[227.172383] Starting dynamic subtest: vecs0
[227.174539] Dynamic subtest vecs0: SUCCESS (0.003s)
[227.181713] Starting dynamic subtest: ccs0
[227.183805] Dynamic subtest ccs0: SUCCESS (0.002s)
[227.185941] Subtest resubmit-all: SUCCESS (0.038s)
[228.922156] [035/131] (793s left) kms_plane_multiple (2x-tiling-x)
[229.019289] Starting subtest: 2x-tiling-x
[229.023915] Subtest 2x-tiling-x: SKIP (0.000s)
[229.092827] [036/131] (793s left) kms_plane_alpha_blend (alpha-basic)
[229.186401] Starting subtest: alpha-basic
[229.652014] Starting dynamic subtest: pipe-A-eDP-1
[231.562229] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.909s)
[231.590987] Starting dynamic subtest: pipe-D-eDP-1
[234.211265] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.623s)
[234.213479] Subtest alpha-basic: SUCCESS (5.021s)
[235.004155] [037/131] (787s left) kms_hdr (bpc-switch-dpms)
[235.099202] Starting subtest: bpc-switch-dpms
[236.173022] Starting dynamic subtest: pipe-A-eDP-1
[240.659304] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.485s)
[240.661566] Subtest bpc-switch-dpms: SUCCESS (5.559s)
[240.803385] [038/131] (782s left) kms_frontbuffer_tracking (fbc-rgb565-draw-render)
[240.914306] Starting subtest: fbc-rgb565-draw-render
[245.125704] Subtest fbc-rgb565-draw-render: SUCCESS (4.215s)
[246.299646] [039/131] (776s left) kms_cursor_crc (cursor-onscreen-64x64)
[246.471220] Starting subtest: cursor-onscreen-64x64
[246.475969] Starting dynamic subtest: pipe-A-eDP-1
[248.872268] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.395s)
[248.899875] Starting dynamic subtest: pipe-D-eDP-1
[251.997578] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.103s)
[252.000267] Subtest cursor-onscreen-64x64: SUCCESS (5.525s)
[252.840682] [040/131] (770s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[252.925313] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[252.927591] Starting dynamic subtest: pipe-A-eDP-1
[252.949046] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[252.958469] Starting dynamic subtest: pipe-B-eDP-1
[252.976973] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[252.984787] Starting dynamic subtest: pipe-C-eDP-1
[253.003500] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[253.011953] Starting dynamic subtest: pipe-D-eDP-1
[253.030975] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[253.032869] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[255.097853] [041/131] (767s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[255.155859] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[255.158296] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[255.267947] [042/131] (767s left) gem_softpin (zero)
[255.325425] Starting subtest: zero
[255.332912] Subtest zero: SUCCESS (0.005s)
[255.470069] [043/131] (767s left) gem_exec_whisper (basic-fds-priority-all)
[255.534563] Starting subtest: basic-fds-priority-all
[277.607494] Subtest basic-fds-priority-all: SUCCESS (22.070s)
[277.788042] [044/131] (745s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[277.888271] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[277.890399] Starting dynamic subtest: pipe-A-default-mode
[278.181116] Dynamic subtest pipe-A-default-mode: SKIP (0.287s)
[278.185453] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.289s)
[279.018622] [045/131] (744s left) gem_busy (busy)
[279.078451] Starting subtest: busy
[279.080911] Starting dynamic subtest: all-engines
[279.134995] Dynamic subtest all-engines: SUCCESS (0.056s)
[279.144209] Starting dynamic subtest: rcs0
[280.857922] Dynamic subtest rcs0: SUCCESS (1.722s)
[280.866868] Starting dynamic subtest: bcs0
[280.917300] Dynamic subtest bcs0: SUCCESS (0.056s)
[280.927901] Starting dynamic subtest: vcs0
[280.954111] Dynamic subtest vcs0: SUCCESS (0.038s)
[280.962689] Starting dynamic subtest: vcs1
[282.903415] Dynamic subtest vcs1: SUCCESS (1.947s)
[282.912029] Starting dynamic subtest: vecs0
[284.888836] Dynamic subtest vecs0: SUCCESS (1.983s)
[284.898447] Starting dynamic subtest: ccs0
[286.936898] Dynamic subtest ccs0: SUCCESS (2.047s)
[286.939289] Subtest busy: SUCCESS (7.855s)
[287.309444] [046/131] (735s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[287.416456] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[287.420479] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[287.504563] [047/131] (735s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[287.625063] Starting subtest: cursorA-vs-flipB-legacy
[287.628952] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[287.756726] [048/131] (735s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted)
[287.809281] Starting subtest: invalid-multi-wait-all-unsubmitted
[287.811243] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[287.900197] [049/131] (735s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[287.987158] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[287.989416] Starting dynamic subtest: pipe-A-eDP-1
[288.012357] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[288.023975] Starting dynamic subtest: pipe-B-eDP-1
[288.046281] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[288.054808] Starting dynamic subtest: pipe-C-eDP-1
[288.076693] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[288.086973] Starting dynamic subtest: pipe-D-eDP-1
[288.109119] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[288.113380] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[290.270325] [050/131] (733s left) gem_exec_schedule (preempt-hang)
[290.439754] Starting subtest: preempt-hang
[290.442320] Starting dynamic subtest: rcs0
[290.683903] Dynamic subtest rcs0: SUCCESS (0.242s)
[290.693530] Starting dynamic subtest: bcs0
[290.915832] Dynamic subtest bcs0: SUCCESS (0.231s)
[290.926592] Starting dynamic subtest: vcs0
[291.139652] Dynamic subtest vcs0: SUCCESS (0.223s)
[291.150594] Starting dynamic subtest: vcs1
[291.363088] Dynamic subtest vcs1: SUCCESS (0.223s)
[291.373861] Starting dynamic subtest: vecs0
[291.587270] Dynamic subtest vecs0: SUCCESS (0.224s)
[291.598811] Starting dynamic subtest: ccs0
[291.816251] Dynamic subtest ccs0: SUCCESS (0.228s)
[291.818593] Subtest preempt-hang: SUCCESS (1.375s)
[292.994578] [051/131] (730s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[293.097285] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[294.151269] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.055s)
[294.309118] [052/131] (729s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[294.426717] Starting subtest: verify-pxp-execution-after-suspend-resume
[301.454359] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.029s)
[312.614121] [053/131] (721s left) gem_close (basic)
[312.681041] Starting subtest: basic
[312.683410] Subtest basic: SUCCESS (0.000s)
[312.780946] [054/131] (721s left) kms_content_protection (uevent)
[312.960659] Starting subtest: uevent
[316.307228] Subtest uevent: SKIP (3.344s)
[317.480583] [055/131] (717s left) kms_feature_discovery (psr1)
[317.580274] Starting subtest: psr1
[317.582229] Subtest psr1: SUCCESS (0.000s)
[317.650163] [056/131] (716s left) syncobj_timeline (invalid-wait-bad-flags)
[317.705463] Starting subtest: invalid-wait-bad-flags
[317.707802] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[317.813851] [057/131] (716s left) gem_fence_thrash (bo-write-verify-y)
[317.842079] Starting subtest: bo-write-verify-y
[317.862173] Subtest bo-write-verify-y: SKIP (0.017s)
[317.928660] [058/131] (716s left) kms_chamelium_color (ctm-0-50)
[318.025309] Subtest ctm-0-50: SKIP (0.000s)
[318.072765] [059/131] (716s left) gem_tiled_partial_pwrite_pread (writes)
[318.135509] Subtest writes: SKIP (0.000s)
[318.191635] [060/131] (716s left) kms_flip (2x-flip-vs-dpms)
[318.286043] Starting subtest: 2x-flip-vs-dpms
[318.297492] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[318.375298] [061/131] (716s left) kms_lease (lease-invalid-connector)
[318.454789] Starting subtest: lease-invalid-connector
[318.456743] Starting dynamic subtest: pipe-A-eDP-1
[318.458769] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[318.468243] Starting dynamic subtest: pipe-B-eDP-1
[318.469820] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[318.478614] Starting dynamic subtest: pipe-C-eDP-1
[318.480495] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[318.489055] Starting dynamic subtest: pipe-D-eDP-1
[318.490928] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[318.492609] Subtest lease-invalid-connector: SUCCESS (0.009s)
[318.533649] [062/131] (716s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[318.588704] Subtest writes-after-reads-uncached: SKIP (0.000s)
[318.656294] [063/131] (716s left) perf_pmu (busy-accuracy-50)
[318.695577] Starting subtest: busy-accuracy-50
[318.697628] Starting dynamic subtest: rcs0
[320.227248] Dynamic subtest rcs0: SUCCESS (1.528s)
[320.239762] Starting dynamic subtest: bcs0
[321.770967] Dynamic subtest bcs0: SUCCESS (1.542s)
[321.782655] Starting dynamic subtest: vcs0
[323.314985] Dynamic subtest vcs0: SUCCESS (1.542s)
[323.324468] Starting dynamic subtest: vcs1
[324.855572] Dynamic subtest vcs1: SUCCESS (1.538s)
[324.866838] Starting dynamic subtest: vecs0
[326.395680] Dynamic subtest vecs0: SUCCESS (1.537s)
[326.408140] Starting dynamic subtest: ccs0
[327.942896] Dynamic subtest ccs0: SUCCESS (1.546s)
[327.945945] Subtest busy-accuracy-50: SUCCESS (9.243s)
[328.084357] [064/131] (706s left) perf (disabled-read-error)
[328.206213] Starting subtest: disabled-read-error
[328.241738] Subtest disabled-read-error: SUCCESS (0.038s)
[328.292326] [065/131] (706s left) gem_exec_fence (syncobj-export)
[328.797209] Starting subtest: syncobj-export
[328.804135] Subtest syncobj-export: SUCCESS (0.007s)
[330.037862] [FACT gem_exec_fence (syncobj-export)] new: kernel.kmod_is_loaded.vgem: true
[330.041277] [066/131] (704s left) kms_dirtyfb (psr-dirtyfb-ioctl)
[330.146999] Starting subtest: psr-dirtyfb-ioctl
[330.148960] Starting dynamic subtest: A-eDP-1
[334.069365] Dynamic subtest A-eDP-1: SUCCESS (3.920s)
[334.071285] Subtest psr-dirtyfb-ioctl: SUCCESS (3.921s)
[334.861008] [067/131] (700s left) kms_hdmi_inject (inject-audio)
[335.199341] Starting subtest: inject-audio
[335.462293] Subtest inject-audio: SUCCESS (0.264s)
[336.019716] [068/131] (699s left) fbdev (info)
[336.053046] Starting subtest: info
[336.055131] Subtest info: SUCCESS (0.000s)
[336.113108] [069/131] (699s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[336.216997] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[336.221628] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[336.316373] [070/131] (698s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[336.373612] Starting subtest: multi-wait-available-submitted-signaled
[336.375632] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[336.474795] [071/131] (698s left) gem_lmem_swapping (heavy-verify-random)
[336.513937] Subtest heavy-verify-random: SKIP (0.000s)
[336.558547] [072/131] (698s left) kms_vblank (query-forked-busy)
[336.651289] Starting subtest: query-forked-busy
[336.653602] Starting dynamic subtest: pipe-A-eDP-1
[338.561066] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.908s)
[338.578111] Starting dynamic subtest: pipe-D-eDP-1
[341.106693] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.542s)
[341.109115] Subtest query-forked-busy: SUCCESS (4.454s)
[341.978912] [073/131] (693s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[342.033153] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[342.037660] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[342.216587] [074/131] (693s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[342.308171] Starting subtest: plane-use-after-nonblocking-unbind
[342.310449] Starting dynamic subtest: pipe-A-eDP-1
[342.379549] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.070s)
[342.669524] Starting dynamic subtest: pipe-B-eDP-1
[343.453040] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.793s)
[343.729032] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.418s)
[344.583947] [075/131] (690s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[344.695881] Starting subtest: 4-tiled-8bpp-rotate-90
[344.717302] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.021s)
[344.868525] [076/131] (690s left) gem_ctx_persistence (legacy-engines-mixed-process)
[344.936473] Starting subtest: legacy-engines-mixed-process
[344.938749] Starting dynamic subtest: render
[346.921834] Dynamic subtest render: SUCCESS (1.984s)
[346.932895] Starting dynamic subtest: bsd1
[348.926817] Dynamic subtest bsd1: SUCCESS (2.002s)
[348.939537] Starting dynamic subtest: bsd2
[350.913461] Dynamic subtest bsd2: SUCCESS (1.984s)
[350.925198] Starting dynamic subtest: blt
[352.954047] Dynamic subtest blt: SUCCESS (2.040s)
[352.966597] Starting dynamic subtest: vebox
[354.934845] Dynamic subtest vebox: SUCCESS (1.979s)
[354.937347] Subtest legacy-engines-mixed-process: SUCCESS (9.998s)
[355.016788] [077/131] (680s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[355.131276] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[355.135764] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[355.233605] [078/131] (680s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[355.329650] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[359.885575] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (4.559s)
[360.991823] [079/131] (674s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[361.096828] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[361.101087] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[361.206111] [080/131] (674s left) vgem_basic (second-client)
[361.245346] Starting subtest: second-client
[361.267075] Subtest second-client: SUCCESS (0.022s)
[361.324635] [081/131] (674s left) api_intel_bb (blit-noreloc-purge-cache)
[361.379754] Starting subtest: blit-noreloc-purge-cache
[361.391419] Subtest blit-noreloc-purge-cache: SUCCESS (0.014s)
[361.517444] [082/131] (674s left) gem_render_copy_redux (normal)
[361.572386] Starting subtest: normal
[361.997580] Subtest normal: SUCCESS (0.425s)
[362.114931] [083/131] (673s left) kms_psr (fbc-psr2-sprite-plane-move)
[362.211510] Starting subtest: fbc-psr2-sprite-plane-move
[362.213087] Starting dynamic subtest: eDP-1
[362.216385] Dynamic subtest eDP-1: SKIP (0.000s)
[362.219850] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s)
[362.285162] [084/131] (673s left) api_intel_bb (purge-bb)
[362.335139] Starting subtest: purge-bb
[362.336887] Subtest purge-bb: SUCCESS (0.004s)
[362.450949] [085/131] (673s left) gem_ctx_persistence (legacy-engines-hostile)
[362.509789] Starting subtest: legacy-engines-hostile
[362.511806] Starting dynamic subtest: render
[362.652239] Dynamic subtest render: SUCCESS (0.142s)
[362.667584] Starting dynamic subtest: bsd1
[362.780555] Dynamic subtest bsd1: SUCCESS (0.126s)
[362.793607] Starting dynamic subtest: bsd2
[362.878229] Dynamic subtest bsd2: SUCCESS (0.095s)
[362.890990] Starting dynamic subtest: blt
[362.979425] Dynamic subtest blt: SUCCESS (0.099s)
[362.994491] Starting dynamic subtest: vebox
[363.078377] Dynamic subtest vebox: SUCCESS (0.096s)
[363.080509] Subtest legacy-engines-hostile: SUCCESS (0.569s)
[363.156890] [086/131] (672s left) kms_vrr (flip-suspend)
[363.246042] Starting subtest: flip-suspend
[363.251582] Subtest flip-suspend: SKIP (0.000s)
[363.319036] [087/131] (672s left) kms_plane_scaling (planes-downscale-factor-0-5)
[363.413039] Starting subtest: planes-downscale-factor-0-5
[363.415071] Starting dynamic subtest: pipe-A
[363.422918] Dynamic subtest pipe-A: SKIP (0.005s)
[363.433898] Starting dynamic subtest: pipe-B
[363.444042] Dynamic subtest pipe-B: SKIP (0.019s)
[363.453831] Starting dynamic subtest: pipe-C
[363.461639] Dynamic subtest pipe-C: SKIP (0.019s)
[363.471176] Starting dynamic subtest: pipe-D
[363.481115] Dynamic subtest pipe-D: SKIP (0.019s)
[363.484330] Subtest planes-downscale-factor-0-5: SKIP (0.064s)
[363.587481] [088/131] (672s left) kms_plane_scaling (planes-upscale-factor-0-25)
[363.680478] Starting subtest: planes-upscale-factor-0-25
[363.682516] Starting dynamic subtest: pipe-A
[363.742939] Dynamic subtest pipe-A: SUCCESS (0.065s)
[363.753502] Starting dynamic subtest: pipe-B
[364.873993] Dynamic subtest pipe-B: SUCCESS (1.130s)
[364.885388] Starting dynamic subtest: pipe-C
[365.964269] Dynamic subtest pipe-C: SUCCESS (1.088s)
[365.975788] Starting dynamic subtest: pipe-D
[367.054497] Dynamic subtest pipe-D: SUCCESS (1.089s)
[367.056839] Subtest planes-upscale-factor-0-25: SUCCESS (3.376s)
[368.180897] [089/131] (667s left) kms_psr (pr-sprite-mmap-cpu)
[368.288962] Starting subtest: pr-sprite-mmap-cpu
[368.293291] Subtest pr-sprite-mmap-cpu: SKIP (0.000s)
[368.361212] [090/131] (667s left) syncobj_timeline (wait-all-delayed-signal)
[368.418677] Starting subtest: wait-all-delayed-signal
[368.519276] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[368.595118] [091/131] (667s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[368.653901] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[368.760027] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.104s)
[368.840829] [092/131] (667s left) gem_mmap_gtt (big-copy-xy)
[368.897055] Subtest big-copy-XY: SKIP (0.000s)
[368.953392] [093/131] (667s left) sysfs_heartbeat_interval (mixed)
[369.004540] Starting subtest: mixed
[369.007020] Starting dynamic subtest: vecs0
[374.283669] Dynamic subtest vecs0: SUCCESS (5.277s)
[374.296768] Starting dynamic subtest: ccs0
[379.525706] Dynamic subtest ccs0: SUCCESS (5.239s)
[379.538785] Starting dynamic subtest: vcs0
[384.775237] Dynamic subtest vcs0: SUCCESS (5.248s)
[384.786785] Starting dynamic subtest: bcs0
[390.003082] Dynamic subtest bcs0: SUCCESS (5.225s)
[390.017656] Starting dynamic subtest: rcs0
[395.191086] Dynamic subtest rcs0: SUCCESS (5.184s)
[395.204184] Starting dynamic subtest: vcs1
[400.435984] Dynamic subtest vcs1: SUCCESS (5.243s)
[400.438596] Subtest mixed: SUCCESS (31.429s)
[400.516264] [094/131] (635s left) gem_wait (write-wait)
[400.588861] Starting subtest: write-wait
[400.591275] Starting dynamic subtest: all-engines
[401.752861] Dynamic subtest all-engines: SUCCESS (1.163s)
[401.771988] Starting dynamic subtest: rcs0
[402.817219] Dynamic subtest rcs0: SUCCESS (1.062s)
[402.832894] Starting dynamic subtest: bcs0
[403.876671] Dynamic subtest bcs0: SUCCESS (1.058s)
[403.892798] Starting dynamic subtest: vcs0
[404.930332] Dynamic subtest vcs0: SUCCESS (1.052s)
[404.948361] Starting dynamic subtest: vcs1
[405.985450] Dynamic subtest vcs1: SUCCESS (1.052s)
[406.002942] Starting dynamic subtest: vecs0
[407.038265] Dynamic subtest vecs0: SUCCESS (1.051s)
[407.056325] Starting dynamic subtest: ccs0
[408.095311] Dynamic subtest ccs0: SUCCESS (1.055s)
[408.098452] Subtest write-wait: SUCCESS (7.506s)
[408.295035] [095/131] (627s left) i915_hangman (gt-error-state-capture)
[408.365435] Starting subtest: gt-error-state-capture
[408.367704] Starting dynamic subtest: rcs0
[419.889652] Dynamic subtest rcs0: SUCCESS (11.522s)
[419.901772] Starting dynamic subtest: bcs0
[430.899204] Dynamic subtest bcs0: SUCCESS (11.006s)
[430.911983] Starting dynamic subtest: vcs0
[441.842213] Dynamic subtest vcs0: SUCCESS (10.940s)
[441.855850] Starting dynamic subtest: vcs1
[453.810270] Dynamic subtest vcs1: SUCCESS (11.965s)
[453.822961] Starting dynamic subtest: vecs0
[463.794353] Dynamic subtest vecs0: SUCCESS (9.981s)
[463.807096] Starting dynamic subtest: ccs0
[473.841511] Dynamic subtest ccs0: SUCCESS (10.044s)
[473.844362] Subtest gt-error-state-capture: SUCCESS (65.471s)
[473.926610] [096/131] (562s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[473.993695] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[474.082232] [097/131] (562s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[474.186449] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[481.340171] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.157s)
[481.467189] [098/131] (554s left) kms_psr (psr-primary-page-flip)
[481.569567] Starting subtest: psr-primary-page-flip
[481.571887] Starting dynamic subtest: eDP-1
[483.344052] Dynamic subtest eDP-1: SUCCESS (1.776s)
[483.346458] Subtest psr-primary-page-flip: SUCCESS (1.777s)
[484.175848] [099/131] (552s left) gen9_exec_parse (valid-registers)
[484.235053] Subtest valid-registers: SKIP (0.000s)
[484.316968] [100/131] (552s left) gem_exec_whisper (basic-contexts-all)
[484.371898] Starting subtest: basic-contexts-all
[495.348611] Subtest basic-contexts-all: SUCCESS (10.971s)
[495.533078] [101/131] (540s left) kms_pipe_crc_basic (bad-source)
[495.632371] Starting subtest: bad-source
[495.634737] Subtest bad-source: SUCCESS (0.000s)
[495.707878] [102/131] (540s left) vgem_basic (setversion)
[495.763195] Starting subtest: setversion
[495.767172] Subtest setversion: SUCCESS (0.000s)
[495.819555] [103/131] (540s left) kms_chamelium_color (ctm-0-25)
[495.914215] Subtest ctm-0-25: SKIP (0.000s)
[495.963796] [104/131] (540s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[496.059271] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[496.061544] Starting dynamic subtest: pipe-A-default-mode
[496.359540] Dynamic subtest pipe-A-default-mode: SKIP (0.298s)
[496.364224] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.299s)
[497.175310] [105/131] (539s left) gem_ctx_engines (independent)
[497.232478] Starting subtest: independent
[497.234876] Starting dynamic subtest: rcs0
[497.380369] Dynamic subtest rcs0: SUCCESS (0.146s)
[497.391493] Starting dynamic subtest: bcs0
[497.527851] Dynamic subtest bcs0: SUCCESS (0.147s)
[497.540616] Starting dynamic subtest: vcs0
[497.678629] Dynamic subtest vcs0: SUCCESS (0.150s)
[497.689184] Starting dynamic subtest: vcs1
[497.841062] Dynamic subtest vcs1: SUCCESS (0.162s)
[497.853822] Starting dynamic subtest: vecs0
[498.006377] Dynamic subtest vecs0: SUCCESS (0.165s)
[498.016641] Starting dynamic subtest: ccs0
[498.181321] Dynamic subtest ccs0: SUCCESS (0.174s)
[498.193741] Starting dynamic subtest: all-engines
[498.890200] Dynamic subtest all-engines: SUCCESS (0.708s)
[498.892730] Subtest independent: SUCCESS (1.657s)
[500.930337] [106/131] (535s left) kms_psr (psr2-sprite-blt)
[501.028864] Starting subtest: psr2-sprite-blt
[501.031729] Starting dynamic subtest: eDP-1
[502.729516] Dynamic subtest eDP-1: SUCCESS (1.704s)
[502.731518] Subtest psr2-sprite-blt: SUCCESS (1.705s)
[503.578198] [107/131] (533s left) kms_big_fb (linear-8bpp-rotate-180)
[503.687215] Starting subtest: linear-8bpp-rotate-180
[504.947948] Subtest linear-8bpp-rotate-180: SUCCESS (1.260s)
[505.071682] [108/131] (531s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[505.178787] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[505.182978] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[505.277587] [109/131] (531s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[505.380239] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[506.436882] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.055s)
[506.571350] [110/131] (530s left) gem_exec_fence (syncobj-timeline-unused-fence)
[507.128711] Starting subtest: syncobj-timeline-unused-fence
[507.134592] Subtest syncobj-timeline-unused-fence: SUCCESS (0.007s)
[508.997562] [111/131] (527s left) perf (invalid-open-flags)
[509.084359] Starting subtest: invalid-open-flags
[509.086712] Subtest invalid-open-flags: SUCCESS (0.000s)
[509.129434] [112/131] (527s left) sriov_basic (enable-vfs-bind-unbind-each)
[509.179524] Starting subtest: enable-vfs-bind-unbind-each
[509.181952] Starting dynamic subtest: numvfs-1
[509.255085] Dynamic subtest numvfs-1: FAIL (0.012s)
[509.266615] Starting dynamic subtest: numvfs-2
[509.335607] Dynamic subtest numvfs-2: FAIL (0.006s)
[509.345988] Starting dynamic subtest: numvfs-3
[509.414782] Dynamic subtest numvfs-3: FAIL (0.003s)
[509.424834] Starting dynamic subtest: numvfs-4
[509.493126] Dynamic subtest numvfs-4: FAIL (0.003s)
[509.503060] Starting dynamic subtest: numvfs-5
[509.572455] Dynamic subtest numvfs-5: FAIL (0.004s)
[509.582715] Starting dynamic subtest: numvfs-6
[509.654947] Dynamic subtest numvfs-6: FAIL (0.003s)
[509.665448] Starting dynamic subtest: numvfs-7
[509.739910] Dynamic subtest numvfs-7: FAIL (0.004s)
[509.749889] Starting dynamic subtest: numvfs-random
[509.823284] Dynamic subtest numvfs-random: FAIL (0.003s)
[509.825659] Subtest enable-vfs-bind-unbind-each: FAIL (0.045s)
[509.866940] [113/131] (527s left) kms_flip (plain-flip-ts-check-interruptible)
[509.967901] Starting subtest: plain-flip-ts-check-interruptible
[509.969928] Starting dynamic subtest: A-eDP1
[518.834702] Dynamic subtest A-eDP1: SUCCESS (8.864s)
[518.845889] Starting dynamic subtest: B-eDP1
[527.735262] Dynamic subtest B-eDP1: SUCCESS (8.899s)
[527.746219] Starting dynamic subtest: C-eDP1
[536.622054] Dynamic subtest C-eDP1: SUCCESS (8.885s)
[536.633455] Starting dynamic subtest: D-eDP1
[545.519881] Dynamic subtest D-eDP1: SUCCESS (8.896s)
[545.522338] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.552s)
[546.673346] [114/131] (490s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[546.773068] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[546.777346] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[546.872737] [115/131] (490s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[546.954858] Starting subtest: bo-too-small-due-to-tiling
[546.959457] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[547.067736] [116/131] (489s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[547.158240] Starting subtest: async-flip-with-page-flip-events-linear
[547.533187] Starting dynamic subtest: pipe-A-eDP-1
[550.315581] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.782s)
[550.680609] Starting dynamic subtest: pipe-B-eDP-1
[553.415637] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.744s)
[553.778366] Starting dynamic subtest: pipe-C-eDP-1
[556.517279] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.747s)
[556.878807] Starting dynamic subtest: pipe-D-eDP-1
[559.614533] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.744s)
[559.617180] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.454s)
[560.772132] [117/131] (476s left) gem_exec_flush (basic-wb-rw-default)
[560.837714] Starting subtest: basic-wb-rw-default
[566.287187] Subtest basic-wb-rw-default: SUCCESS (5.450s)
[566.480858] [118/131] (470s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[566.533476] Subtest no-spare-fences-busy: SKIP (0.000s)
[566.619495] [119/131] (470s left) gem_exec_balancer (bonded-pair)
[566.688479] Starting subtest: bonded-pair
[566.692823] Subtest bonded-pair: SKIP (0.000s)
[566.997580] [120/131] (470s left) gem_userptr_blits (unsync-unmap-after-close)
[567.060470] Subtest unsync-unmap-after-close: SKIP (0.000s)
[567.199531] [121/131] (470s left) kms_chamelium_hpd (vga-hpd-fast)
[567.593681] Subtest vga-hpd-fast: SKIP (0.000s)
[568.357107] [122/131] (468s left) kms_invalid_mode (bad-vsync-start)
[568.459356] Starting subtest: bad-vsync-start
[568.461730] Starting dynamic subtest: pipe-A-eDP-1
[568.752709] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.293s)
[568.764933] Starting dynamic subtest: pipe-B-eDP-1
[568.767379] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[568.777704] Starting dynamic subtest: pipe-C-eDP-1
[568.779688] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[568.789580] Starting dynamic subtest: pipe-D-eDP-1
[568.791591] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[568.793483] Subtest bad-vsync-start: SUCCESS (0.299s)
[569.568603] [123/131] (467s left) gem_tiled_pread_pwrite
[569.696147] [124/131] (467s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[569.800506] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[576.379606] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.582s)
[576.528185] [125/131] (460s left) i915_pm_rpm (gem-evict-pwrite)
[578.061981] Starting subtest: gem-evict-pwrite
[578.067033] Subtest gem-evict-pwrite: SKIP (0.000s)
[578.783920] [126/131] (458s left) kms_flip (2x-flip-vs-rmfb)
[578.881082] Starting subtest: 2x-flip-vs-rmfb
[578.892433] Subtest 2x-flip-vs-rmfb: SKIP (0.011s)
[578.970730] [127/131] (458s left) gem_exec_suspend (basic-s3-devices)
[579.035200] Starting subtest: basic-S3-devices
[579.037521] Starting dynamic subtest: smem
[589.908097] Dynamic subtest smem: SUCCESS (10.871s)
[589.911075] Subtest basic-S3-devices: SUCCESS (10.872s)
[595.103836] [128/131] (447s left) i915_pm_rps (basic-api)
[595.172639] Starting subtest: basic-api
[595.177388] Subtest basic-api: SKIP (0.001s)
[595.255965] [129/131] (447s left) gem_exec_schedule (manyslice)
[595.325670] Starting subtest: manyslice
[595.327690] Starting dynamic subtest: rcs0
[601.511561] Dynamic subtest rcs0: SUCCESS (6.194s)
[601.525087] Starting dynamic subtest: bcs0
[608.680523] Dynamic subtest bcs0: SUCCESS (7.166s)
[608.695793] Starting dynamic subtest: vcs0
[613.763016] Dynamic subtest vcs0: SUCCESS (5.081s)
[613.778420] Starting dynamic subtest: vcs1
[619.851327] Dynamic subtest vcs1: SUCCESS (6.087s)
[619.862831] Starting dynamic subtest: vecs0
[625.940239] Dynamic subtest vecs0: SUCCESS (6.087s)
[625.952024] Starting dynamic subtest: ccs0
[632.131709] Dynamic subtest ccs0: SUCCESS (6.190s)
[632.134883] Subtest manyslice: SUCCESS (36.815s)
[632.436978] [130/131] (410s left) perf_pmu (busy-hang)
[632.479080] Starting subtest: busy-hang
[632.481410] Starting dynamic subtest: rcs0
[633.791092] Dynamic subtest rcs0: SUCCESS (1.315s)
[633.803025] Starting dynamic subtest: bcs0
[635.041539] Dynamic subtest bcs0: SUCCESS (1.245s)
[635.053920] Starting dynamic subtest: vcs0
[637.400394] Dynamic subtest vcs0: SUCCESS (2.358s)
[637.410718] Starting dynamic subtest: vcs1
[639.444649] Dynamic subtest vcs1: SUCCESS (2.045s)
[639.456996] Starting dynamic subtest: vecs0
[641.434132] Dynamic subtest vecs0: SUCCESS (1.985s)
[641.445990] Starting dynamic subtest: ccs0
[642.681237] Dynamic subtest ccs0: SUCCESS (1.245s)
[642.683588] Subtest busy-hang: SUCCESS (10.203s)
[642.792417] [131/131] (399s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[642.899953] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[642.904515] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[643.006875] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_exec_balancer
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_mmap_gtt
results: parsing output: 5/ for test: kms_ccs
results: parsing output: 6/ for test: kms_cursor_edge_walk
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: kms_ccs
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: gem_ctx_param
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: gem_userptr_blits
results: parsing output: 13/ for test: prime_mmap
results: parsing output: 14/ for test: kms_psr2_sf
results: parsing output: 15/ for test: kms_chamelium_hpd
results: parsing output: 16/ for test: kms_prop_blob
results: parsing output: 17/ for test: gem_tiled_fence_blits
results: parsing output: 18/ for test: gem_basic
results: parsing output: 19/ for test: kms_psr2_sf
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_cursor_crc
results: parsing output: 22/ for test: kms_pm_dc
results: parsing output: 23/ for test: gem_exec_reloc
results: parsing output: 24/ for test: gem_exec_params
results: parsing output: 25/ for test: i915_selftest
results: parsing output: 26/ for test: gem_mmap_gtt
results: parsing output: 27/ for test: gem_mmap_wc
results: parsing output: 28/ for test: kms_pm_rpm
results: parsing output: 29/ for test: kms_big_fb
results: parsing output: 30/ for test: kms_atomic_transition
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_ppgtt
results: parsing output: 33/ for test: gem_spin_batch
results: parsing output: 34/ for test: kms_plane_multiple
results: parsing output: 35/ for test: kms_plane_alpha_blend
results: parsing output: 36/ for test: kms_hdr
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_cursor_crc
results: parsing output: 39/ for test: kms_ccs
results: parsing output: 40/ for test: syncobj_wait
results: parsing output: 41/ for test: gem_softpin
results: parsing output: 42/ for test: gem_exec_whisper
results: parsing output: 43/ for test: kms_flip_scaled_crc
results: parsing output: 44/ for test: gem_busy
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_cursor_legacy
results: parsing output: 47/ for test: syncobj_timeline
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: gem_exec_schedule
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: gem_pxp
results: parsing output: 52/ for test: gem_close
results: parsing output: 53/ for test: kms_content_protection
results: parsing output: 54/ for test: kms_feature_discovery
results: parsing output: 55/ for test: syncobj_timeline
results: parsing output: 56/ for test: gem_fence_thrash
results: parsing output: 57/ for test: kms_chamelium_color
results: parsing output: 58/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: kms_lease
results: parsing output: 61/ for test: gem_partial_pwrite_pread
results: parsing output: 62/ for test: perf_pmu
results: parsing output: 63/ for test: perf
results: parsing output: 64/ for test: gem_exec_fence
results: parsing output: 65/ for test: kms_dirtyfb
results: parsing output: 66/ for test: kms_hdmi_inject
results: parsing output: 67/ for test: fbdev
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: syncobj_timeline
results: parsing output: 70/ for test: gem_lmem_swapping
results: parsing output: 71/ for test: kms_vblank
results: parsing output: 72/ for test: gem_render_copy
results: parsing output: 73/ for test: kms_atomic_transition
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: gem_ctx_persistence
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: vgem_basic
results: parsing output: 80/ for test: api_intel_bb
results: parsing output: 81/ for test: gem_render_copy_redux
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: api_intel_bb
results: parsing output: 84/ for test: gem_ctx_persistence
results: parsing output: 85/ for test: kms_vrr
results: parsing output: 86/ for test: kms_plane_scaling
results: parsing output: 87/ for test: kms_plane_scaling
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: syncobj_timeline
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: gem_mmap_gtt
results: parsing output: 92/ for test: sysfs_heartbeat_interval
results: parsing output: 93/ for test: gem_wait
results: parsing output: 94/ for test: i915_hangman
results: parsing output: 95/ for test: gem_exec_reloc
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: gen9_exec_parse
results: parsing output: 99/ for test: gem_exec_whisper
results: parsing output: 100/ for test: kms_pipe_crc_basic
results: parsing output: 101/ for test: vgem_basic
results: parsing output: 102/ for test: kms_chamelium_color
results: parsing output: 103/ for test: kms_flip_scaled_crc
results: parsing output: 104/ for test: gem_ctx_engines
results: parsing output: 105/ for test: kms_psr
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: gem_exec_fence
results: parsing output: 110/ for test: perf
results: parsing output: 111/ for test: sriov_basic
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_addfb_basic
results: parsing output: 115/ for test: kms_async_flips
results: parsing output: 116/ for test: gem_exec_flush
results: parsing output: 117/ for test: gem_fenced_exec_thrash
results: parsing output: 118/ for test: gem_exec_balancer
results: parsing output: 119/ for test: gem_userptr_blits
results: parsing output: 120/ for test: kms_chamelium_hpd
results: parsing output: 121/ for test: kms_invalid_mode
results: parsing output: 122/ for test: gem_tiled_pread_pwrite
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: i915_pm_rpm
results: parsing output: 125/ for test: kms_flip
results: parsing output: 126/ for test: gem_exec_suspend
results: parsing output: 127/ for test: i915_pm_rps
results: parsing output: 128/ for test: gem_exec_schedule
results: parsing output: 129/ for test: perf_pmu
results: parsing output: 130/ for test: kms_big_fb
Done.