[58.365581] Initializing watchdogs
[58.365627]   /dev/watchdog0
[58.379361] [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]
[58.383612] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.385777] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.388976] [001/133] (960s left) gem_exec_balancer (invalid-bonds)
[58.487715] Starting subtest: invalid-bonds
[58.492455] Subtest invalid-bonds: SKIP (0.000s)
[58.800498] [002/133] (960s left) gem_exec_schedule (out-order)
[58.869904] Starting subtest: out-order
[58.872377] Starting dynamic subtest: rcs0
[59.024856] Dynamic subtest rcs0: SUCCESS (0.165s)
[59.037915] Starting dynamic subtest: vcs0
[59.130579] Dynamic subtest vcs0: SUCCESS (0.108s)
[59.140780] Starting dynamic subtest: vcs1
[59.237263] Dynamic subtest vcs1: SUCCESS (0.108s)
[59.247788] Starting dynamic subtest: vecs0
[59.347383] Dynamic subtest vecs0: SUCCESS (0.112s)
[59.356937] Starting dynamic subtest: ccs0
[59.485791] Dynamic subtest ccs0: SUCCESS (0.141s)
[59.488301] Subtest out-order: SUCCESS (0.634s)
[60.385340] [003/133] (958s left) syncobj_timeline (wait-delayed-signal)
[60.440784] Starting subtest: wait-delayed-signal
[60.544526] Subtest wait-delayed-signal: SUCCESS (0.105s)
[60.628062] [004/133] (958s left) kms_cursor_legacy (single-move)
[60.752971] Starting subtest: single-move
[60.754865] Starting dynamic subtest: pipe-A
[66.078240] Dynamic subtest pipe-A: SUCCESS (5.380s)
[66.087475] Starting dynamic subtest: pipe-B
[71.432721] Dynamic subtest pipe-B: SUCCESS (5.381s)
[71.442217] Starting dynamic subtest: pipe-C
[76.801039] Dynamic subtest pipe-C: SUCCESS (5.381s)
[76.810617] Starting dynamic subtest: pipe-D
[82.174874] Dynamic subtest pipe-D: SUCCESS (5.380s)
[82.186682] Starting dynamic subtest: all-pipes
[87.567594] Dynamic subtest all-pipes: SUCCESS (5.389s)
[87.570549] Subtest single-move: SUCCESS (26.919s)
[87.705331] [005/133] (931s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-mc-ccs)
[87.800347] Starting subtest: missing-ccs-buffer-y-tiled-gen12-mc-ccs
[87.802741] Starting dynamic subtest: pipe-A-eDP-1
[87.826097] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[87.836607] Starting dynamic subtest: pipe-B-eDP-1
[87.861400] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[87.869880] Starting dynamic subtest: pipe-C-eDP-1
[87.895527] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[87.904201] Starting dynamic subtest: pipe-D-eDP-1
[87.925951] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[87.930062] Subtest missing-ccs-buffer-y-tiled-gen12-mc-ccs: SKIP (0.003s)
[90.082031] [006/133] (928s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[90.182786] Starting subtest: addfb25-yf-tiled-legacy
[90.185226] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[90.275387] [007/133] (928s left) kms_pm_dc (dc9-dpms)
[91.385690] Starting subtest: dc9-dpms
[92.812729] Subtest dc9-dpms: SUCCESS (1.426s)
[92.896993] [008/133] (926s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[93.003515] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[99.574371] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.574s)
[99.683851] [009/133] (919s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[99.794624] Starting subtest: psr-shrfb-scaledprimary
[105.558922] Subtest psr-shrfb-scaledprimary: SUCCESS (5.767s)
[105.703836] [010/133] (913s left) sw_sync (alloc_fence_invalid_timeline)
[105.740655] Starting subtest: alloc_fence_invalid_timeline
[105.742629] Subtest alloc_fence_invalid_timeline: SUCCESS (0.000s)
[105.792861] [011/133] (913s left) gem_mmap_gtt (basic-write-gtt)
[105.843482] Subtest basic-write-gtt: SKIP (0.000s)
[105.897529] [012/133] (913s left) gem_mmap_wc (bad-size)
[105.943919] Subtest bad-size: SKIP (0.000s)
[105.998999] [013/133] (913s left) kms_atomic_interruptible (atomic-setmode)
[106.085175] Starting subtest: atomic-setmode
[106.087510] Starting dynamic subtest: pipe-A-eDP-1
[112.988979] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.903s)
[112.991492] Subtest atomic-setmode: SUCCESS (6.904s)
[113.862203] [014/133] (905s left) kms_cursor_crc (cursor-sliding-128x42)
[114.091092] Starting subtest: cursor-sliding-128x42
[114.137989] Subtest cursor-sliding-128x42: SKIP (0.043s)
[114.348809] [015/133] (905s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[114.442427] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[114.444789] Starting dynamic subtest: pipe-A-eDP-1
[114.468778] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[114.478566] Starting dynamic subtest: pipe-B-eDP-1
[114.500183] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[114.507352] Starting dynamic subtest: pipe-C-eDP-1
[114.528998] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[114.536279] Starting dynamic subtest: pipe-D-eDP-1
[114.558206] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[114.562572] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.002s)
[116.723616] [016/133] (902s left) kms_big_fb (x-tiled-8bpp-rotate-180)
[116.833926] Starting subtest: x-tiled-8bpp-rotate-180
[118.047511] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.214s)
[118.169702] [017/133] (901s left) prime_mmap (test_forked_cpu_write)
[118.223974] Starting subtest: test_forked_cpu_write
[118.226303] Starting dynamic subtest: test_forked_cpu_write-smem
[118.230927] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.009s)
[118.232897] Subtest test_forked_cpu_write: SUCCESS (0.009s)
[118.344202] [018/133] (901s left) gen7_exec_parse (cmd-crossing-page)
[118.402040] Subtest cmd-crossing-page: SKIP (0.000s)
[118.461565] [019/133] (901s left) gem_lmem_swapping (parallel-random-verify-ccs)
[118.501685] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[118.538861] [020/133] (901s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[118.593497] Starting subtest: etime-single-wait-all-for-submit-submitted
[118.696887] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.103s)
[118.767689] [021/133] (900s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[118.867480] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[118.892436] Starting dynamic subtest: pipe-A-plane-0
[119.303198] Dynamic subtest pipe-A-plane-0: SUCCESS (0.411s)
[119.312264] Starting dynamic subtest: pipe-A-plane-3
[119.627303] Dynamic subtest pipe-A-plane-3: SUCCESS (0.322s)
[120.723671] Starting dynamic subtest: pipe-B-plane-0
[121.103415] Dynamic subtest pipe-B-plane-0: SUCCESS (0.389s)
[121.112860] Starting dynamic subtest: pipe-B-plane-3
[121.414490] Dynamic subtest pipe-B-plane-3: SUCCESS (0.311s)
[121.724924] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (2.857s)
[122.609277] [022/133] (897s left) gem_ringfill (engines-basic)
[128.329327] Starting subtest: engines-basic
[128.332049] Starting dynamic subtest: rcs0
[128.392553] Dynamic subtest rcs0: SUCCESS (0.065s)
[128.404631] Starting dynamic subtest: bcs0
[128.439122] Dynamic subtest bcs0: SUCCESS (0.045s)
[128.448959] Starting dynamic subtest: vcs0
[128.481983] Dynamic subtest vcs0: SUCCESS (0.042s)
[128.492245] Starting dynamic subtest: vcs1
[128.524971] Dynamic subtest vcs1: SUCCESS (0.042s)
[128.534459] Starting dynamic subtest: vecs0
[128.568525] Dynamic subtest vecs0: SUCCESS (0.042s)
[128.578064] Starting dynamic subtest: ccs0
[128.612687] Dynamic subtest ccs0: SUCCESS (0.043s)
[128.615284] Subtest engines-basic: SUCCESS (0.286s)
[128.701553] [FACT gem_ringfill (engines-basic)] new: kernel.kmod_is_loaded.vgem: true
[128.704951] [023/133] (891s left) kms_chamelium_hpd (dp-hpd-storm)
[129.104384] Subtest dp-hpd-storm: SKIP (0.000s)
[129.909399] [024/133] (889s left) kms_cursor_edge_walk (64x64-top-bottom)
[130.005403] Starting subtest: 64x64-top-bottom
[130.007665] Starting dynamic subtest: pipe-A-eDP-1
[133.480932] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.474s)
[133.490748] Starting dynamic subtest: pipe-D-eDP-1
[136.612738] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.132s)
[136.614968] Subtest 64x64-top-bottom: SUCCESS (6.609s)
[137.423696] [025/133] (882s left) gem_mmap_offset (partial-remap)
[137.480563] Starting subtest: partial-remap
[137.482768] Starting dynamic subtest: smem0
[137.492842] Dynamic subtest smem0: SUCCESS (0.004s)
[137.495292] Subtest partial-remap: SUCCESS (0.004s)
[137.585086] [026/133] (882s left) kms_flip (bo-too-big-interruptible)
[137.682473] Starting subtest: bo-too-big-interruptible
[137.684825] Starting dynamic subtest: A-eDP1
[138.975548] Dynamic subtest A-eDP1: SUCCESS (1.292s)
[138.986348] Starting dynamic subtest: B-eDP1
[140.217134] Dynamic subtest B-eDP1: SUCCESS (1.241s)
[140.227844] Starting dynamic subtest: C-eDP1
[141.448202] Dynamic subtest C-eDP1: SUCCESS (1.231s)
[141.457573] Starting dynamic subtest: D-eDP1
[142.685958] Dynamic subtest D-eDP1: SUCCESS (1.237s)
[142.688604] Subtest bo-too-big-interruptible: SUCCESS (5.007s)
[143.907707] [027/133] (875s left) kms_feature_discovery (display-1x)
[144.007069] Starting subtest: display-1x
[144.009294] Subtest display-1x: SUCCESS (0.000s)
[144.096353] [028/133] (875s left) gem_gtt_cpu_tlb
[144.218093] [029/133] (875s left) core_setmaster (master-drop-set-root)
[144.277292] Starting subtest: master-drop-set-root
[144.301856] Subtest master-drop-set-root: SUCCESS (0.025s)
[144.348731] [030/133] (875s left) gem_gpgpu_fill (basic)
[144.392573] Starting subtest: basic
[144.394627] Starting dynamic subtest: smem
[144.398821] Dynamic subtest smem: SUCCESS (0.007s)
[144.401228] Subtest basic: SUCCESS (0.008s)
[144.548016] [031/133] (875s left) gem_softpin (evict-prime-sanity-check)
[144.599872] Starting subtest: evict-prime-sanity-check
[144.602071] Starting dynamic subtest: rcs0
[145.746627] Dynamic subtest rcs0: SUCCESS (1.148s)
[145.756925] Starting dynamic subtest: bcs0
[146.867629] Dynamic subtest bcs0: SUCCESS (1.120s)
[146.878509] Starting dynamic subtest: vcs0
[148.267236] Dynamic subtest vcs0: SUCCESS (1.397s)
[148.278506] Starting dynamic subtest: vcs1
[150.251559] Dynamic subtest vcs1: SUCCESS (1.982s)
[150.259887] Starting dynamic subtest: vecs0
[152.300537] Dynamic subtest vecs0: SUCCESS (2.048s)
[152.312579] Starting dynamic subtest: ccs0
[153.437304] Dynamic subtest ccs0: SUCCESS (1.137s)
[153.449587] Starting dynamic subtest: all
[154.551758] Dynamic subtest all: SUCCESS (1.113s)
[154.554276] Subtest evict-prime-sanity-check: SUCCESS (9.954s)
[154.628197] [032/133] (865s left) prime_vgem (basic-gtt)
[154.705834] Starting subtest: basic-gtt
[154.710800] Subtest basic-gtt: SKIP (0.000s)
[154.795179] [033/133] (865s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[154.897140] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[154.902031] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[154.961152] [034/133] (865s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[155.065753] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[155.070234] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[155.167465] [035/133] (865s left) gem_reg_read (timestamp-moving)
[155.228067] Starting subtest: timestamp-moving
[156.229581] Subtest timestamp-moving: SUCCESS (1.001s)
[156.309256] [036/133] (863s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[156.388833] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[156.391682] Starting dynamic subtest: pipe-A-eDP-1
[156.403626] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[156.413731] Starting dynamic subtest: pipe-B-eDP-1
[156.422767] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[156.431067] Starting dynamic subtest: pipe-C-eDP-1
[156.439337] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[156.447424] Starting dynamic subtest: pipe-D-eDP-1
[156.455735] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[156.459971] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.003s)
[158.662720] [037/133] (861s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[158.756492] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[158.760536] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[158.865712] [038/133] (861s left) syncobj_basic (illegal-fd-to-handle)
[158.925910] Starting subtest: illegal-fd-to-handle
[158.927844] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[159.009301] [039/133] (861s left) kms_content_protection (atomic-dpms-hdcp14)
[159.148098] Starting subtest: atomic-dpms-hdcp14
[159.152814] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[159.253116] [040/133] (861s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[159.346862] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[166.658642] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.314s)
[166.795145] [041/133] (853s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[166.898440] Starting subtest: y-tiled-8bpp-rotate-0
[166.902641] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[167.012549] [042/133] (853s left) kms_psr (psr2-sprite-mmap-cpu)
[167.106962] Starting subtest: psr2-sprite-mmap-cpu
[167.109298] Starting dynamic subtest: eDP-1
[168.866318] Dynamic subtest eDP-1: SUCCESS (1.759s)
[168.868312] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.760s)
[169.739761] [043/133] (850s left) kms_big_fb (linear-32bpp-rotate-270)
[169.835062] Starting subtest: linear-32bpp-rotate-270
[169.855601] Subtest linear-32bpp-rotate-270: SKIP (0.020s)
[169.967481] [044/133] (850s left) prime_self_import (basic-with_two_bos)
[170.006259] Starting subtest: basic-with_two_bos
[170.016811] Subtest basic-with_two_bos: SUCCESS (0.013s)
[170.105619] [045/133] (850s left) syncobj_timeline (multi-wait-all-signaled)
[170.172536] Starting subtest: multi-wait-all-signaled
[170.174493] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[170.259602] [046/133] (850s left) i915_pm_rpm (system-suspend-execbuf)
[172.500945] Starting subtest: system-suspend-execbuf
[230.403637] Subtest system-suspend-execbuf: SUCCESS (43.330s)
[230.787591] [047/133] (789s left) syncobj_wait (single-wait-for-submit-submitted)
[230.849053] Starting subtest: single-wait-for-submit-submitted
[230.956888] Subtest single-wait-for-submit-submitted: SUCCESS (0.105s)
[231.035118] [048/133] (789s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[231.123394] Starting subtest: plane-lut1d-post-ctm3x4
[231.269648] Starting dynamic subtest: pipe-A-plane-0
[231.424775] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[231.434608] Starting dynamic subtest: pipe-A-plane-1
[231.558771] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[231.568425] Starting dynamic subtest: pipe-A-plane-2
[231.702551] Dynamic subtest pipe-A-plane-2: SUCCESS (0.144s)
[232.858463] Starting dynamic subtest: pipe-B-plane-0
[233.017472] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[233.026704] Starting dynamic subtest: pipe-B-plane-1
[233.151218] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[233.160410] Starting dynamic subtest: pipe-B-plane-2
[233.328901] Dynamic subtest pipe-B-plane-2: SUCCESS (0.177s)
[234.498391] Starting dynamic subtest: pipe-C-plane-0
[234.629661] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[234.639275] Starting dynamic subtest: pipe-C-plane-1
[234.774181] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[234.783862] Starting dynamic subtest: pipe-C-plane-2
[234.918636] Dynamic subtest pipe-C-plane-2: SUCCESS (0.144s)
[236.121640] Starting dynamic subtest: pipe-D-plane-0
[236.257572] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[236.267126] Starting dynamic subtest: pipe-D-plane-1
[236.401764] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[236.411289] Starting dynamic subtest: pipe-D-plane-2
[236.535305] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s)
[236.824053] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.703s)
[237.694488] [049/133] (783s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[237.816116] Starting subtest: basic-flip-after-cursor-atomic
[238.728736] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.914s)
[239.541437] [050/133] (781s left) kms_vblank (query-forked-busy-hang)
[239.631433] Starting subtest: query-forked-busy-hang
[239.633674] Starting dynamic subtest: pipe-A-eDP-1
[265.992279] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.369s)
[266.008064] Starting dynamic subtest: pipe-D-eDP-1
[290.955060] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.964s)
[290.957433] Subtest query-forked-busy-hang: SUCCESS (51.337s)
[291.774953] [051/133] (729s left) kms_psr (fbc-pr-cursor-plane-onoff)
[291.869464] Starting subtest: fbc-pr-cursor-plane-onoff
[291.873613] Subtest fbc-pr-cursor-plane-onoff: SKIP (0.000s)
[291.946459] [052/133] (728s left) syncobj_wait (single-wait-all-submitted)
[292.000610] Starting subtest: single-wait-all-submitted
[292.108384] Subtest single-wait-all-submitted: SUCCESS (0.108s)
[292.186035] [053/133] (728s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[292.284194] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu
[292.288537] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[292.377012] [054/133] (728s left) kms_plane_alpha_blend (constant-alpha-mid)
[292.460661] Starting subtest: constant-alpha-mid
[292.751311] Starting dynamic subtest: pipe-A-eDP-1
[293.566639] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.813s)
[293.576569] Starting dynamic subtest: pipe-D-eDP-1
[295.062602] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.495s)
[295.065012] Subtest constant-alpha-mid: SUCCESS (2.603s)
[295.949268] [055/133] (725s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[296.051019] Starting subtest: fbc-farfromfence-mmap-gtt
[296.055445] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[296.153249] [056/133] (724s left) gem_create (create-ext-placement-all)
[296.311119] Starting subtest: create-ext-placement-all
[296.313818] Subtest create-ext-placement-all: SUCCESS (0.000s)
[296.422804] [057/133] (724s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[296.515495] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[296.519836] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[296.593837] [058/133] (724s left) gem_exec_schedule (deep)
[296.777905] Starting subtest: deep
[296.780409] Starting dynamic subtest: rcs0
[301.916903] Dynamic subtest rcs0: SKIP (5.132s)
[301.928708] Starting dynamic subtest: vcs0
[312.101986] Dynamic subtest vcs0: SUCCESS (10.184s)
[312.115220] Starting dynamic subtest: vcs1
[323.119311] Dynamic subtest vcs1: SUCCESS (11.016s)
[323.136376] Starting dynamic subtest: vecs0
[333.835766] Dynamic subtest vecs0: SUCCESS (10.716s)
[333.846263] Starting dynamic subtest: ccs0
[346.634594] Dynamic subtest ccs0: SUCCESS (12.799s)
[346.637477] Subtest deep: SUCCESS (49.854s)
[348.128025] [059/133] (673s left) gem_exec_await (wide-all)
[348.403441] Starting subtest: wide-all
[372.122886] Subtest wide-all: SUCCESS (23.723s)
[372.318896] [060/133] (648s left) kms_flip (2x-modeset-vs-vblank-race)
[372.480711] Starting subtest: 2x-modeset-vs-vblank-race
[372.489516] Subtest 2x-modeset-vs-vblank-race: SKIP (0.011s)
[372.565201] [061/133] (648s left) perf_pmu (enable-race)
[372.608878] Starting subtest: enable-race
[372.611171] Starting dynamic subtest: rcs0
[385.391665] Dynamic subtest rcs0: SUCCESS (12.786s)
[385.402940] Starting dynamic subtest: bcs0
[398.135131] Dynamic subtest bcs0: SUCCESS (12.745s)
[398.145018] Starting dynamic subtest: vcs0
[414.152343] Dynamic subtest vcs0: SUCCESS (16.017s)
[414.164928] Starting dynamic subtest: vcs1
[430.152846] Dynamic subtest vcs1: SUCCESS (15.992s)
[430.163077] Starting dynamic subtest: vecs0
[446.218291] Dynamic subtest vecs0: SUCCESS (16.058s)
[446.228412] Starting dynamic subtest: ccs0
[458.978601] Dynamic subtest ccs0: SUCCESS (12.759s)
[458.981114] Subtest enable-race: SUCCESS (86.371s)
[459.082318] [062/133] (562s left) kms_chamelium_frames (dp-crc-fast)
[459.467138] Subtest dp-crc-fast: SKIP (0.000s)
[460.242555] [063/133] (561s left) sw_sync (timeline_closed)
[460.292175] Starting subtest: timeline_closed
[460.294609] Subtest timeline_closed: SUCCESS (0.000s)
[460.351692] [064/133] (561s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[460.451400] Starting subtest: fbc-psr2-primary-mmap-cpu
[460.453774] Starting dynamic subtest: eDP-1
[460.458182] Dynamic subtest eDP-1: SKIP (0.000s)
[460.462573] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[460.525156] [065/133] (560s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[460.622731] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[460.627327] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[460.691991] [066/133] (560s left) gem_exec_fence (syncobj-channel)
[461.097092] Starting subtest: syncobj-channel
[462.120668] Subtest syncobj-channel: SUCCESS (1.019s)
[462.411652] [067/133] (559s left) gem_exec_fence (basic-wait-all)
[462.461552] Starting subtest: basic-wait-all
[462.545837] Subtest basic-wait-all: SUCCESS (0.085s)
[464.267226] [068/133] (557s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[464.372543] Starting subtest: 2x-absolute-wf_vblank-interruptible
[464.384460] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.010s)
[464.459990] [069/133] (557s left) fbdev (info)
[464.497651] Starting subtest: info
[464.499870] Subtest info: SUCCESS (0.000s)
[464.563545] [070/133] (557s left) gem_render_copy_redux (normal)
[464.616161] Starting subtest: normal
[464.884592] Subtest normal: SUCCESS (0.269s)
[465.025071] [071/133] (556s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs-cc)
[465.116232] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs-cc
[465.119038] Starting dynamic subtest: pipe-A-eDP-1
[465.144472] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[465.155692] Starting dynamic subtest: pipe-B-eDP-1
[465.177424] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[465.186814] Starting dynamic subtest: pipe-C-eDP-1
[465.208908] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[465.219391] Starting dynamic subtest: pipe-D-eDP-1
[465.237629] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[465.241170] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[467.357941] [072/133] (554s left) gem_ctx_persistence (engines-mixed-process)
[467.414675] Starting subtest: engines-mixed-process
[467.417237] Starting dynamic subtest: rcs0
[469.178823] Dynamic subtest rcs0: SUCCESS (1.763s)
[469.191176] Starting dynamic subtest: bcs0
[471.244294] Dynamic subtest bcs0: SUCCESS (2.065s)
[471.256295] Starting dynamic subtest: vcs0
[473.207380] Dynamic subtest vcs0: SUCCESS (1.961s)
[473.220543] Starting dynamic subtest: vcs1
[475.214381] Dynamic subtest vcs1: SUCCESS (2.007s)
[475.226415] Starting dynamic subtest: vecs0
[477.208167] Dynamic subtest vecs0: SUCCESS (1.992s)
[477.223407] Starting dynamic subtest: ccs0
[479.191686] Dynamic subtest ccs0: SUCCESS (1.982s)
[479.194099] Subtest engines-mixed-process: SUCCESS (11.778s)
[479.272534] [073/133] (542s left) kms_addfb_basic (no-handle)
[479.321208] Starting subtest: no-handle
[479.323510] Subtest no-handle: SUCCESS (0.000s)
[479.453944] [074/133] (542s left) gem_exec_whisper (basic-fds-forked-all)
[479.512801] Starting subtest: basic-fds-forked-all
[501.987265] Subtest basic-fds-forked-all: SUCCESS (22.472s)
[502.188227] [075/133] (519s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy)
[502.321110] Starting subtest: 2x-cursor-vs-flip-legacy
[502.325574] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.001s)
[502.463613] [076/133] (519s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[502.577956] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[502.582519] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[502.683161] [077/133] (519s left) api_intel_bb (reset-bb)
[502.738636] Starting subtest: reset-bb
[502.742977] Subtest reset-bb: SUCCESS (0.006s)
[502.866122] [078/133] (519s left) gem_wait (wait)
[502.938356] Starting subtest: wait
[502.940838] Starting dynamic subtest: all-engines
[504.200040] Dynamic subtest all-engines: SUCCESS (1.259s)
[504.214986] Starting dynamic subtest: rcs0
[505.246967] Dynamic subtest rcs0: SUCCESS (1.046s)
[505.260426] Starting dynamic subtest: bcs0
[506.293652] Dynamic subtest bcs0: SUCCESS (1.045s)
[506.305816] Starting dynamic subtest: vcs0
[507.340889] Dynamic subtest vcs0: SUCCESS (1.045s)
[507.354432] Starting dynamic subtest: vcs1
[508.385723] Dynamic subtest vcs1: SUCCESS (1.043s)
[508.399827] Starting dynamic subtest: vecs0
[509.430518] Dynamic subtest vecs0: SUCCESS (1.043s)
[509.443936] Starting dynamic subtest: ccs0
[510.479060] Dynamic subtest ccs0: SUCCESS (1.046s)
[510.482039] Subtest wait: SUCCESS (7.542s)
[510.677428] [079/133] (511s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[510.776579] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[510.780491] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[510.882518] [080/133] (511s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[510.977094] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[510.981073] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[511.073385] [081/133] (511s left) gem_exec_suspend (basic-s3)
[511.140713] Starting subtest: basic-S3
[511.142650] Starting dynamic subtest: smem
[531.817851] Dynamic subtest smem: SUCCESS (7.110s)
[531.820476] Subtest basic-S3: SUCCESS (7.112s)
[532.001806] [082/133] (490s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[532.102643] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[532.104698] Starting dynamic subtest: pipe-A-default-mode
[534.823792] Dynamic subtest pipe-A-default-mode: SUCCESS (2.811s)
[534.826195] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.812s)
[535.642857] [083/133] (486s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[535.735177] Starting subtest: yf-tiled-8bpp-rotate-270
[535.739655] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[535.839964] [084/133] (486s left) prime_self_import (reimport-vs-gem_close-race)
[535.878446] Starting subtest: reimport-vs-gem_close-race
[540.955929] Subtest reimport-vs-gem_close-race: SUCCESS (5.166s)
[541.047270] [085/133] (481s left) gem_exec_schedule (submit-golden-slice)
[541.110245] Starting subtest: submit-golden-slice
[541.112613] Starting dynamic subtest: rcs0
[541.164370] Dynamic subtest rcs0: SUCCESS (0.060s)
[541.175487] Starting dynamic subtest: bcs0
[541.222106] Dynamic subtest bcs0: SUCCESS (0.057s)
[541.235875] Starting dynamic subtest: vcs0
[541.278471] Dynamic subtest vcs0: SUCCESS (0.056s)
[541.288586] Starting dynamic subtest: vcs1
[541.339733] Dynamic subtest vcs1: SUCCESS (0.062s)
[541.355384] Starting dynamic subtest: vecs0
[541.392598] Dynamic subtest vecs0: SUCCESS (0.053s)
[541.403451] Starting dynamic subtest: ccs0
[541.447061] Dynamic subtest ccs0: SUCCESS (0.054s)
[541.449286] Subtest submit-golden-slice: SUCCESS (0.347s)
[542.922079] [086/133] (479s left) vgem_basic (unload)
[542.956522] Starting subtest: unload
[572.076951] Subtest unload: SUCCESS (29.188s)
[572.176533] [087/133] (450s left) kms_sequence (queue-idle)
[572.287405] Starting subtest: queue-idle
[572.289215] Starting dynamic subtest: pipe-A-eDP-1
[574.094913] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.808s)
[574.107008] Starting dynamic subtest: pipe-B-eDP-1
[576.522930] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.424s)
[576.534465] Starting dynamic subtest: pipe-C-eDP-1
[578.950640] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.427s)
[578.962583] Starting dynamic subtest: pipe-D-eDP-1
[581.378012] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.427s)
[581.380450] Subtest queue-idle: SUCCESS (9.091s)
[582.210132] [088/133] (440s left) kms_plane_scaling (planes-scaler-unity-scaling)
[582.318268] Starting subtest: planes-scaler-unity-scaling
[582.320751] Starting dynamic subtest: pipe-A
[582.378040] Dynamic subtest pipe-A: SUCCESS (0.063s)
[582.391289] Starting dynamic subtest: pipe-B
[583.502483] Dynamic subtest pipe-B: SUCCESS (1.124s)
[583.522677] Starting dynamic subtest: pipe-C
[584.613712] Dynamic subtest pipe-C: SUCCESS (1.109s)
[584.624451] Starting dynamic subtest: pipe-D
[585.745827] Dynamic subtest pipe-D: SUCCESS (1.132s)
[585.748221] Subtest planes-scaler-unity-scaling: SUCCESS (3.432s)
[586.910862] [089/133] (435s left) gem_render_copy (x-tiled-to-vebox-yf-tiled)
[586.972500] Starting subtest: x-tiled-to-vebox-yf-tiled
[586.977115] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[587.168861] [090/133] (435s left) i915_suspend (debugfs-reader)
[587.237798] Starting subtest: debugfs-reader
[605.596018] Subtest debugfs-reader: SUCCESS (3.161s)
[605.768604] [091/133] (416s left) kms_psr (fbc-psr-sprite-plane-move)
[605.873832] Starting subtest: fbc-psr-sprite-plane-move
[605.876019] Starting dynamic subtest: eDP-1
[605.880892] Dynamic subtest eDP-1: SKIP (0.000s)
[605.885535] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[605.957937] [092/133] (416s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt)
[606.052578] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt
[606.057491] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[606.156600] [093/133] (416s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[606.247959] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[606.252574] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[606.342113] [094/133] (416s left) sw_sync (sync_multi_consumer)
[606.378917] Starting subtest: sync_multi_consumer
[607.378448] Subtest sync_multi_consumer: SUCCESS (1.041s)
[607.454608] [095/133] (415s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[607.550904] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[613.999057] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.591s)
[614.133554] [096/133] (408s left) gem_ctx_shared (q-promotion)
[614.195372] Starting subtest: Q-promotion
[614.198224] Starting dynamic subtest: rcs0
[614.297269] Dynamic subtest rcs0: SUCCESS (0.103s)
[614.314592] Starting dynamic subtest: vcs0
[614.379576] Dynamic subtest vcs0: SUCCESS (0.080s)
[614.395886] Starting dynamic subtest: vcs1
[614.460677] Dynamic subtest vcs1: SUCCESS (0.081s)
[614.478067] Starting dynamic subtest: vecs0
[614.534558] Dynamic subtest vecs0: SUCCESS (0.074s)
[614.551554] Starting dynamic subtest: ccs0
[614.611133] Dynamic subtest ccs0: SUCCESS (0.076s)
[614.614130] Subtest Q-promotion: SUCCESS (0.421s)
[616.121962] [097/133] (406s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[616.209494] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[616.211921] Starting dynamic subtest: pipe-A-default-mode
[617.398007] Dynamic subtest pipe-A-default-mode: SKIP (1.195s)
[617.402918] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.197s)
[617.542892] [098/133] (405s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[617.640999] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[626.247018] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.655s)
[626.351448] [099/133] (396s left) i915_drm_fdinfo (most-busy-check-all)
[626.395890] Starting subtest: most-busy-check-all
[626.398187] Starting dynamic subtest: rcs0
[626.402877] Dynamic subtest rcs0: SKIP (0.004s)
[626.413394] Starting dynamic subtest: bcs0
[626.418033] Dynamic subtest bcs0: SKIP (0.000s)
[626.428487] Starting dynamic subtest: vcs0
[626.433206] Dynamic subtest vcs0: SKIP (0.000s)
[626.443176] Starting dynamic subtest: vcs1
[626.447776] Dynamic subtest vcs1: SKIP (0.000s)
[626.457920] Starting dynamic subtest: vecs0
[626.462781] Dynamic subtest vecs0: SKIP (0.000s)
[626.472989] Starting dynamic subtest: ccs0
[626.477714] Dynamic subtest ccs0: SKIP (0.000s)
[626.482340] Subtest most-busy-check-all: SKIP (0.010s)
[626.523435] [100/133] (396s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted)
[626.601453] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[626.604077] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[626.709959] [101/133] (396s left) kms_psr (pr-primary-mmap-cpu)
[626.804200] Starting subtest: pr-primary-mmap-cpu
[626.809182] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[626.882190] [102/133] (396s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[626.972082] Starting subtest: plane-primary-overlay-mutable-zpos
[630.432062] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.466s)
[631.607059] [103/133] (391s left) kms_pwrite_crc (basic)
[631.696608] Starting subtest: basic
[632.340068] Subtest basic: SUCCESS (0.645s)
[633.132546] [104/133] (390s left) kms_lease (possible-crtcs-filtering)
[633.225193] Starting subtest: possible-crtcs-filtering
[633.231124] Subtest possible-crtcs-filtering: SUCCESS (0.008s)
[633.357369] [105/133] (389s left) gem_flink_basic (flink-lifetime)
[633.419974] Starting subtest: flink-lifetime
[633.422969] Subtest flink-lifetime: SUCCESS (0.002s)
[633.522797] [106/133] (389s left) kms_cursor_crc (cursor-dpms)
[633.658140] Starting subtest: cursor-dpms
[633.661025] Starting dynamic subtest: pipe-A-eDP-1
[636.689720] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.030s)
[636.700196] Starting dynamic subtest: pipe-D-eDP-1
[640.379971] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.687s)
[640.382148] Subtest cursor-dpms: SUCCESS (6.720s)
[641.216761] [107/133] (382s left) prime_vgem (basic-fence-blt)
[641.291119] Starting subtest: basic-fence-blt
[641.403405] Subtest basic-fence-blt: SUCCESS (0.112s)
[641.539568] [108/133] (381s left) kms_plane (plane-panning-top-left)
[641.629070] Starting subtest: plane-panning-top-left
[641.631934] Starting dynamic subtest: pipe-A
[643.266494] Dynamic subtest pipe-A: SUCCESS (1.639s)
[643.277251] Starting dynamic subtest: pipe-B
[645.631344] Dynamic subtest pipe-B: SUCCESS (2.360s)
[645.633784] Subtest plane-panning-top-left: SUCCESS (4.002s)
[646.451488] [109/133] (377s left) gem_ctx_param (invalid-param-get)
[646.501413] Starting subtest: invalid-param-get
[646.504050] Subtest invalid-param-get: SUCCESS (0.001s)
[646.596286] [110/133] (376s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs)
[646.685477] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs
[646.688041] Starting dynamic subtest: pipe-A-eDP-1
[646.716183] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[646.726998] Starting dynamic subtest: pipe-B-eDP-1
[646.754844] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[646.765699] Starting dynamic subtest: pipe-C-eDP-1
[646.792982] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[646.803183] Starting dynamic subtest: pipe-D-eDP-1
[646.830194] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[646.835226] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[648.925609] [111/133] (374s left) gem_exec_params (invalid-bsd1-flag-on-render)
[648.983897] Starting subtest: invalid-bsd1-flag-on-render
[648.986237] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[649.085462] [112/133] (374s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[649.178709] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[650.047198] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.870s)
[650.202146] [113/133] (373s left) gem_ctx_persistence (legacy-engines-hang)
[650.260111] Starting subtest: legacy-engines-hang
[650.263077] Starting dynamic subtest: render
[650.388767] Dynamic subtest render: SUCCESS (0.128s)
[650.401692] Starting dynamic subtest: bsd1
[650.490580] Dynamic subtest bsd1: SUCCESS (0.099s)
[650.503330] Starting dynamic subtest: bsd2
[650.580199] Dynamic subtest bsd2: SUCCESS (0.087s)
[650.592218] Starting dynamic subtest: blt
[650.682787] Dynamic subtest blt: SUCCESS (0.099s)
[650.696509] Starting dynamic subtest: vebox
[650.787568] Dynamic subtest vebox: SUCCESS (0.101s)
[650.790224] Subtest legacy-engines-hang: SUCCESS (0.529s)
[650.869807] [114/133] (372s left) perf_pmu (busy-accuracy-50)
[650.910390] Starting subtest: busy-accuracy-50
[650.913029] Starting dynamic subtest: rcs0
[652.445940] Dynamic subtest rcs0: SUCCESS (1.532s)
[652.460363] Starting dynamic subtest: bcs0
[653.982466] Dynamic subtest bcs0: SUCCESS (1.536s)
[653.994191] Starting dynamic subtest: vcs0
[655.525081] Dynamic subtest vcs0: SUCCESS (1.539s)
[655.537222] Starting dynamic subtest: vcs1
[657.063519] Dynamic subtest vcs1: SUCCESS (1.539s)
[657.073992] Starting dynamic subtest: vecs0
[658.599186] Dynamic subtest vecs0: SUCCESS (1.532s)
[658.612209] Starting dynamic subtest: ccs0
[660.144686] Dynamic subtest ccs0: SUCCESS (1.544s)
[660.147221] Subtest busy-accuracy-50: SUCCESS (9.234s)
[660.273638] [115/133] (363s left) gem_userptr_blits (relocations)
[660.339131] Starting subtest: relocations
[660.344201] Subtest relocations: SKIP (0.000s)
[660.477608] [116/133] (363s left) kms_rotation_crc (bad-tiling)
[660.576034] Starting subtest: bad-tiling
[660.580825] Subtest bad-tiling: SKIP (0.001s)
[660.647222] [117/133] (363s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[660.743840] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[667.626070] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.886s)
[667.787242] [118/133] (356s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[667.850004] Starting subtest: invalid-single-wait-all-unsubmitted
[667.852619] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[667.952289] [119/133] (355s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[668.046005] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[668.050967] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[668.171390] [120/133] (355s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[668.260100] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[668.264971] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[668.347031] [121/133] (355s left) kms_rotation_crc (sprite-rotation-180)
[668.437760] Starting subtest: sprite-rotation-180
[675.273666] Subtest sprite-rotation-180: SUCCESS (6.839s)
[676.474122] [122/133] (347s left) syncobj_timeline (single-wait-all-for-submit-signaled)
[676.527196] Starting subtest: single-wait-all-for-submit-signaled
[676.530018] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[676.624736] [123/133] (347s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[676.725864] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[684.101210] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.381s)
[684.228199] [124/133] (339s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[684.327184] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[684.332495] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[684.411612] [125/133] (339s left) api_intel_allocator (gem-pool)
[684.467101] Starting subtest: gem-pool
[684.472356] Subtest gem-pool: SUCCESS (0.007s)
[684.566493] [126/133] (339s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[684.662059] Starting subtest: fbc-pr-cursor-plane-update-sf
[684.667623] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.000s)
[684.734527] [127/133] (339s left) gem_exec_balancer (parallel-ordering)
[684.986392] Starting subtest: parallel-ordering
[685.937878] Subtest parallel-ordering: SUCCESS (0.952s)
[686.070023] [128/133] (338s left) drm_read (empty-nonblock)
[686.223022] Starting subtest: empty-nonblock
[686.226102] Subtest empty-nonblock: SUCCESS (0.000s)
[686.350588] [129/133] (338s left) i915_module_load (reload-no-display)
[686.385172] Starting subtest: reload-no-display
[693.217371] Subtest reload-no-display: SUCCESS (6.834s)
[693.248657] [FACT i915_module_load (reload-no-display)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[693.249389] [FACT i915_module_load (reload-no-display)] deleted: kernel.kmod_is_loaded.i915: true
[693.253088] [130/133] (331s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)
[695.606229] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled
[695.706909] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.102s)
[695.754322] [FACT syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[695.755894] [FACT syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled)] new: kernel.kmod_is_loaded.i915: true
[695.760061] [131/133] (328s left) kms_invalid_mode (bad-hsync-start)
[695.861199] Starting subtest: bad-hsync-start
[695.863720] Starting dynamic subtest: pipe-A-eDP-1
[696.149744] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[696.160059] Starting dynamic subtest: pipe-B-eDP-1
[696.162473] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[696.171459] Starting dynamic subtest: pipe-C-eDP-1
[696.173764] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[696.182872] Starting dynamic subtest: pipe-D-eDP-1
[696.185179] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[696.187441] Subtest bad-hsync-start: SUCCESS (0.295s)
[696.943924] [132/133] (327s left) kms_pm_rpm (pm-caching)
[699.193624] Starting subtest: pm-caching
[699.198610] Subtest pm-caching: SKIP (0.000s)
[699.560659] [133/133] (324s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[699.661344] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[700.329403] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.670s)
[700.510840] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_balancer
results: parsing output: 1/ for test: gem_exec_schedule
results: parsing output: 2/ for test: syncobj_timeline
results: parsing output: 3/ for test: kms_cursor_legacy
results: parsing output: 4/ for test: kms_ccs
results: parsing output: 5/ for test: kms_addfb_basic
results: parsing output: 6/ for test: kms_pm_dc
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: sw_sync
results: parsing output: 10/ for test: gem_mmap_gtt
results: parsing output: 11/ for test: gem_mmap_wc
results: parsing output: 12/ for test: kms_atomic_interruptible
results: parsing output: 13/ for test: kms_cursor_crc
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: prime_mmap
results: parsing output: 17/ for test: gen7_exec_parse
results: parsing output: 18/ for test: gem_lmem_swapping
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: kms_plane
results: parsing output: 21/ for test: gem_ringfill
results: parsing output: 22/ for test: kms_chamelium_hpd
results: parsing output: 23/ for test: kms_cursor_edge_walk
results: parsing output: 24/ for test: gem_mmap_offset
results: parsing output: 25/ for test: kms_flip
results: parsing output: 26/ for test: kms_feature_discovery
results: parsing output: 27/ for test: gem_gtt_cpu_tlb
results: parsing output: 28/ for test: core_setmaster
results: parsing output: 29/ for test: gem_gpgpu_fill
results: parsing output: 30/ for test: gem_softpin
results: parsing output: 31/ for test: prime_vgem
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_reg_read
results: parsing output: 35/ for test: kms_ccs
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: syncobj_basic
results: parsing output: 38/ for test: kms_content_protection
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_big_fb
results: parsing output: 41/ for test: kms_psr
results: parsing output: 42/ for test: kms_big_fb
results: parsing output: 43/ for test: prime_self_import
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: i915_pm_rpm
results: parsing output: 46/ for test: syncobj_wait
results: parsing output: 47/ for test: kms_color_pipeline
results: parsing output: 48/ for test: kms_cursor_legacy
results: parsing output: 49/ for test: kms_vblank
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: syncobj_wait
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_plane_alpha_blend
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_create
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_exec_schedule
results: parsing output: 58/ for test: gem_exec_await
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: perf_pmu
results: parsing output: 61/ for test: kms_chamelium_frames
results: parsing output: 62/ for test: sw_sync
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_plane
results: parsing output: 65/ for test: gem_exec_fence
results: parsing output: 66/ for test: gem_exec_fence
results: parsing output: 67/ for test: kms_flip
results: parsing output: 68/ for test: fbdev
results: parsing output: 69/ for test: gem_render_copy_redux
results: parsing output: 70/ for test: kms_ccs
results: parsing output: 71/ for test: gem_ctx_persistence
results: parsing output: 72/ for test: kms_addfb_basic
results: parsing output: 73/ for test: gem_exec_whisper
results: parsing output: 74/ for test: kms_cursor_legacy
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: api_intel_bb
results: parsing output: 77/ for test: gem_wait
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: gem_exec_suspend
results: parsing output: 81/ for test: kms_flip_scaled_crc
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: prime_self_import
results: parsing output: 84/ for test: gem_exec_schedule
results: parsing output: 85/ for test: vgem_basic
results: parsing output: 86/ for test: kms_sequence
results: parsing output: 87/ for test: kms_plane_scaling
results: parsing output: 88/ for test: gem_render_copy
results: parsing output: 89/ for test: i915_suspend
results: parsing output: 90/ for test: kms_psr
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: sw_sync
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_ctx_shared
results: parsing output: 96/ for test: kms_flip_scaled_crc
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: i915_drm_fdinfo
results: parsing output: 99/ for test: syncobj_timeline
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: kms_atomic
results: parsing output: 102/ for test: kms_pwrite_crc
results: parsing output: 103/ for test: kms_lease
results: parsing output: 104/ for test: gem_flink_basic
results: parsing output: 105/ for test: kms_cursor_crc
results: parsing output: 106/ for test: prime_vgem
results: parsing output: 107/ for test: kms_plane
results: parsing output: 108/ for test: gem_ctx_param
results: parsing output: 109/ for test: kms_ccs
results: parsing output: 110/ for test: gem_exec_params
results: parsing output: 111/ for test: kms_big_fb
results: parsing output: 112/ for test: gem_ctx_persistence
results: parsing output: 113/ for test: perf_pmu
results: parsing output: 114/ for test: gem_userptr_blits
results: parsing output: 115/ for test: kms_rotation_crc
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: syncobj_wait
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_rotation_crc
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: api_intel_allocator
results: parsing output: 125/ for test: kms_psr2_sf
results: parsing output: 126/ for test: gem_exec_balancer
results: parsing output: 127/ for test: drm_read
results: parsing output: 128/ for test: i915_module_load
results: parsing output: 129/ for test: syncobj_timeline
results: parsing output: 130/ for test: kms_invalid_mode
results: parsing output: 131/ for test: kms_pm_rpm
results: parsing output: 132/ for test: kms_big_fb
Done.