[57.577348] Initializing watchdogs
[57.577395]   /dev/watchdog0
[57.590799] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.594948] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.597481] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.600591] [001/130] (960s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[57.712764] Starting subtest: plane-upscale-20x20-with-rotation
[57.714943] Starting dynamic subtest: pipe-A
[57.944277] Dynamic subtest pipe-A: SUCCESS (0.235s)
[57.952982] Starting dynamic subtest: pipe-B
[59.258944] Dynamic subtest pipe-B: SUCCESS (1.321s)
[59.267875] Starting dynamic subtest: pipe-C
[60.580786] Dynamic subtest pipe-C: SUCCESS (1.328s)
[60.589907] Starting dynamic subtest: pipe-D
[61.910406] Dynamic subtest pipe-D: SUCCESS (1.335s)
[61.912682] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.219s)
[63.107034] [002/130] (955s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[63.207264] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[63.211403] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[63.314637] [003/130] (954s left) kms_big_fb (linear-8bpp-rotate-180)
[63.412501] Starting subtest: linear-8bpp-rotate-180
[64.590084] Subtest linear-8bpp-rotate-180: SUCCESS (1.182s)
[64.733510] [004/130] (953s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[64.826752] Starting subtest: blocking-absolute-wf_vblank-interruptible
[64.828969] Starting dynamic subtest: A-eDP1
[73.533123] Dynamic subtest A-eDP1: SUCCESS (8.718s)
[73.542076] Starting dynamic subtest: B-eDP1
[82.263338] Dynamic subtest B-eDP1: SUCCESS (8.732s)
[82.271422] Starting dynamic subtest: C-eDP1
[90.970822] Dynamic subtest C-eDP1: SUCCESS (8.707s)
[90.980909] Starting dynamic subtest: D-eDP1
[99.699887] Dynamic subtest D-eDP1: SUCCESS (8.728s)
[99.702422] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.892s)
[100.915835] [005/130] (917s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[101.017587] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[101.021843] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[101.130627] [006/130] (917s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[101.232878] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[107.741484] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (6.510s)
[107.894919] [007/130] (910s left) gem_ctx_exec (basic-norecovery)
[107.950778] Starting subtest: basic-norecovery
[108.233874] Subtest basic-norecovery: SUCCESS (0.285s)
[108.395603] [008/130] (909s left) kms_psr (psr-primary-page-flip)
[108.492956] Starting subtest: psr-primary-page-flip
[108.495438] Starting dynamic subtest: eDP-1
[110.227543] Dynamic subtest eDP-1: SUCCESS (1.733s)
[110.229764] Subtest psr-primary-page-flip: SUCCESS (1.733s)
[111.091158] [009/130] (907s left) core_getstats
[111.279789] [010/130] (907s left) i915_hangman (detector)
[111.340403] Starting subtest: detector
[111.342390] Starting dynamic subtest: rcs0
[122.869974] Dynamic subtest rcs0: SUCCESS (11.529s)
[122.880681] Starting dynamic subtest: bcs0
[134.898116] Dynamic subtest bcs0: SUCCESS (12.028s)
[134.908106] Starting dynamic subtest: vcs0
[146.803222] Dynamic subtest vcs0: SUCCESS (11.904s)
[146.814110] Starting dynamic subtest: vcs1
[156.853458] Dynamic subtest vcs1: SUCCESS (10.048s)
[156.863817] Starting dynamic subtest: vecs0
[168.821025] Dynamic subtest vecs0: SUCCESS (11.966s)
[168.829474] Starting dynamic subtest: ccs0
[178.869097] Dynamic subtest ccs0: SUCCESS (10.047s)
[178.871973] Subtest detector: SUCCESS (67.526s)
[178.961225] [011/130] (839s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[179.061745] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[179.063879] Starting dynamic subtest: pipe-A-eDP-1
[181.108692] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.046s)
[181.117130] Starting dynamic subtest: pipe-B-eDP-1
[183.218908] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.110s)
[183.226889] Starting dynamic subtest: pipe-C-eDP-1
[185.240014] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.021s)
[185.248516] Starting dynamic subtest: pipe-D-eDP-1
[187.281818] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.041s)
[187.284125] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.220s)
[188.541617] [012/130] (829s left) gem_lmem_swapping (parallel-random-engines)
[188.588142] Subtest parallel-random-engines: SKIP (0.000s)
[188.623274] [013/130] (829s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[188.714050] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[188.718331] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[188.842360] [014/130] (829s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[188.942359] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[188.946915] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[189.071441] [015/130] (829s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[189.158693] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[189.163203] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[189.271615] [016/130] (829s left) gem_ctx_persistence (legacy-engines-hostile)
[189.333137] Starting subtest: legacy-engines-hostile
[189.335518] Starting dynamic subtest: render
[189.473931] Dynamic subtest render: SUCCESS (0.140s)
[189.486074] Starting dynamic subtest: bsd1
[189.600105] Dynamic subtest bsd1: SUCCESS (0.125s)
[189.610220] Starting dynamic subtest: bsd2
[189.715776] Dynamic subtest bsd2: SUCCESS (0.113s)
[189.726358] Starting dynamic subtest: blt
[189.832729] Dynamic subtest blt: SUCCESS (0.115s)
[189.843083] Starting dynamic subtest: vebox
[189.911185] Dynamic subtest vebox: SUCCESS (0.077s)
[189.913232] Subtest legacy-engines-hostile: SUCCESS (0.579s)
[190.066190] [017/130] (828s left) prime_vgem (shrink)
[190.185751] Starting subtest: shrink
[190.197197] Subtest shrink: SUCCESS (0.012s)
[190.339508] [FACT prime_vgem (shrink)] new: kernel.kmod_is_loaded.vgem: true
[190.342278] [018/130] (828s left) gem_exec_schedule (preempt-queue-chain)
[190.414097] Subtest preempt-queue-chain: SKIP (0.000s)
[190.725734] [019/130] (828s left) kms_flip (flip-vs-panning-vs-hang)
[190.826448] Starting subtest: flip-vs-panning-vs-hang
[190.828758] Starting dynamic subtest: A-eDP1
[230.393444] Dynamic subtest A-eDP1: SUCCESS (39.566s)
[230.402085] Starting dynamic subtest: D-eDP1
[270.398987] Dynamic subtest D-eDP1: SUCCESS (40.004s)
[270.401827] Subtest flip-vs-panning-vs-hang: SUCCESS (79.574s)
[271.638769] [020/130] (747s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[271.894127] Starting subtest: cursor-rapid-movement-256x256
[271.896387] Starting dynamic subtest: pipe-A-eDP-1
[272.422655] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.528s)
[272.452245] Starting dynamic subtest: pipe-D-eDP-1
[273.638184] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.196s)
[273.640439] Subtest cursor-rapid-movement-256x256: SUCCESS (1.745s)
[274.495893] [021/130] (744s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[274.596172] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[274.600451] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[274.692953] [022/130] (744s left) gem_exec_balancer (sliced)
[274.748395] Starting subtest: sliced
[274.753096] Subtest sliced: SKIP (0.000s)
[275.094559] [023/130] (743s left) kms_psr (fbc-psr-suspend)
[275.189208] Starting subtest: fbc-psr-suspend
[275.191308] Starting dynamic subtest: eDP-1
[275.195153] Dynamic subtest eDP-1: SKIP (0.000s)
[275.199069] Subtest fbc-psr-suspend: SKIP (0.001s)
[275.273746] [024/130] (743s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[275.371289] Starting subtest: fbcpsr-stridechange
[281.503117] Subtest fbcpsr-stridechange: SUCCESS (6.133s)
[281.651480] [025/130] (737s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[281.751278] Starting subtest: 4-tiled-32bpp-rotate-180
[283.194184] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.444s)
[283.363835] [026/130] (735s left) gem_mmap_gtt (basic-read-write-distinct)
[283.420658] Subtest basic-read-write-distinct: SKIP (0.000s)
[283.488058] [027/130] (735s left) perf_pmu (busy-accuracy-2)
[283.526099] Starting subtest: busy-accuracy-2
[283.527887] Starting dynamic subtest: rcs0
[286.569099] Dynamic subtest rcs0: SUCCESS (3.040s)
[286.579143] Starting dynamic subtest: bcs0
[289.621982] Dynamic subtest bcs0: SUCCESS (3.051s)
[289.629909] Starting dynamic subtest: vcs0
[292.666728] Dynamic subtest vcs0: SUCCESS (3.042s)
[292.677326] Starting dynamic subtest: vcs1
[295.712549] Dynamic subtest vcs1: SUCCESS (3.044s)
[295.722994] Starting dynamic subtest: vecs0
[298.757206] Dynamic subtest vecs0: SUCCESS (3.043s)
[298.767556] Starting dynamic subtest: ccs0
[301.819368] Dynamic subtest ccs0: SUCCESS (3.055s)
[301.823267] Subtest busy-accuracy-2: SUCCESS (18.285s)
[301.952270] [028/130] (717s left) gem_create (create-ext-set-pat)
[302.115551] Starting subtest: create-ext-set-pat
[302.117895] Subtest create-ext-set-pat: SUCCESS (0.001s)
[302.241057] [029/130] (716s left) gem_exec_reloc (basic-wc-cpu-active)
[302.301296] Subtest basic-wc-cpu-active: SKIP (0.000s)
[302.383230] [030/130] (716s left) gem_ctx_persistence (userptr)
[302.449059] Starting subtest: userptr
[302.687603] Subtest userptr: SUCCESS (0.238s)
[302.788510] [031/130] (716s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[302.837064] Starting subtest: multi-wait-all-for-submit-unsubmitted
[302.945391] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.108s)
[303.041341] [032/130] (716s left) gem_busy (close-race)
[303.094803] Starting subtest: close-race
[326.932220] Subtest close-race: SUCCESS (23.835s)
[327.334957] [033/130] (691s left) kms_addfb_basic (small-bo)
[327.473966] Starting subtest: small-bo
[327.475915] Subtest small-bo: SUCCESS (0.000s)
[327.588284] [034/130] (691s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[327.704439] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[332.179489] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.481s)
[333.370057] [035/130] (686s left) syncobj_timeline (invalid-transfer-non-existent-point)
[333.427823] Starting subtest: invalid-transfer-non-existent-point
[333.430114] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[333.562320] [036/130] (685s left) kms_lease (lease-revoke)
[333.659254] Starting subtest: lease-revoke
[333.661593] Starting dynamic subtest: pipe-A-eDP-1
[333.694278] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.034s)
[333.704424] Starting dynamic subtest: pipe-B-eDP-1
[333.718701] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.026s)
[333.728293] Starting dynamic subtest: pipe-C-eDP-1
[333.746231] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.026s)
[333.755251] Starting dynamic subtest: pipe-D-eDP-1
[333.776791] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.028s)
[333.778640] Subtest lease-revoke: SUCCESS (0.119s)
[333.935344] [037/130] (685s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[334.032318] Starting subtest: psr-rgb101010-draw-pwrite
[341.456812] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.427s)
[341.592176] [038/130] (677s left) gem_mmap_offset (partial-remap)
[341.660609] Starting subtest: partial-remap
[341.663018] Starting dynamic subtest: smem0
[341.671765] Dynamic subtest smem0: SUCCESS (0.003s)
[341.674083] Subtest partial-remap: SUCCESS (0.004s)
[341.808100] [039/130] (677s left) gem_exec_whisper (basic-fds-priority)
[341.875457] Starting subtest: basic-fds-priority
[364.795581] Subtest basic-fds-priority: SUCCESS (22.917s)
[364.987774] [040/130] (654s left) gem_softpin (allocator-nopin)
[365.048932] Starting subtest: allocator-nopin
[365.143551] Subtest allocator-nopin: SUCCESS (0.095s)
[365.296789] [041/130] (654s left) gem_exec_reloc (basic-concurrent16)
[365.358788] Subtest basic-concurrent16: SKIP (0.000s)
[365.438071] [042/130] (654s left) kms_vrr (negative-basic)
[365.537062] Starting subtest: negative-basic
[365.543741] Starting dynamic subtest: pipe-A-eDP-1
[370.956306] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.413s)
[371.254672] Subtest negative-basic: SUCCESS (5.719s)
[372.150359] [043/130] (647s left) syncobj_timeline (single-wait-all-signaled)
[372.216092] Starting subtest: single-wait-all-signaled
[372.218582] Subtest single-wait-all-signaled: SUCCESS (0.001s)
[372.358751] [044/130] (647s left) kms_psr (fbc-psr2-primary-page-flip)
[372.450788] Starting subtest: fbc-psr2-primary-page-flip
[372.453090] Starting dynamic subtest: eDP-1
[372.457553] Dynamic subtest eDP-1: SKIP (0.000s)
[372.462059] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[372.545837] [045/130] (647s left) kms_feature_discovery (display-4x)
[372.641959] Starting subtest: display-4x
[372.646608] Subtest display-4x: SKIP (0.000s)
[372.723899] [046/130] (647s left) kms_pm_rpm (universal-planes)
[374.292557] Starting subtest: universal-planes
[374.295482] Starting dynamic subtest: plane-33
[382.564362] Dynamic subtest plane-33: SUCCESS (8.270s)
[382.576052] Starting dynamic subtest: plane-43
[390.838352] Dynamic subtest plane-43: SUCCESS (8.271s)
[390.848297] Starting dynamic subtest: plane-53
[399.154362] Dynamic subtest plane-53: SUCCESS (8.313s)
[399.166741] Starting dynamic subtest: plane-63
[407.443457] Dynamic subtest plane-63: SUCCESS (8.287s)
[407.452686] Starting dynamic subtest: plane-73
[415.762448] Dynamic subtest plane-73: SUCCESS (8.317s)
[415.773970] Starting dynamic subtest: plane-83
[424.041990] Dynamic subtest plane-83: SUCCESS (8.277s)
[424.044515] Subtest universal-planes: SUCCESS (49.750s)
[424.435201] [047/130] (595s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[424.538313] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[425.593260] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.057s)
[425.725824] [048/130] (594s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[425.794678] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[425.885153] [049/130] (594s left) kms_scaling_modes (scaling-mode-full-aspect)
[425.982718] Starting subtest: scaling-mode-full-aspect
[425.984331] Starting dynamic subtest: pipe-A-eDP-1
[426.077033] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.096s)
[426.086141] Starting dynamic subtest: pipe-B-eDP-1
[427.229382] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.151s)
[427.240182] Starting dynamic subtest: pipe-C-eDP-1
[428.349273] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.119s)
[428.362068] Starting dynamic subtest: pipe-D-eDP-1
[429.479194] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.129s)
[429.481469] Subtest scaling-mode-full-aspect: SUCCESS (3.498s)
[430.669611] [050/130] (589s left) kms_color (degamma)
[430.773622] Starting subtest: degamma
[430.775486] Starting dynamic subtest: pipe-A-eDP-1
[431.457065] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.685s)
[431.466802] Starting dynamic subtest: pipe-B-eDP-1
[432.855547] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.398s)
[432.867689] Starting dynamic subtest: pipe-C-eDP-1
[434.189370] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.331s)
[434.198611] Starting dynamic subtest: pipe-D-eDP-1
[435.531277] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.344s)
[435.533552] Subtest degamma: SUCCESS (4.760s)
[436.362487] [051/130] (583s left) kms_cursor_crc (cursor-offscreen-256x85)
[436.605021] Starting subtest: cursor-offscreen-256x85
[436.646091] Subtest cursor-offscreen-256x85: SKIP (0.036s)
[436.843077] [052/130] (583s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[436.950733] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[436.955469] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[437.118291] [053/130] (582s left) perf_pmu (busy-start)
[437.160709] Starting subtest: busy-start
[437.162643] Starting dynamic subtest: rcs0
[439.711237] Dynamic subtest rcs0: SUCCESS (2.552s)
[439.722784] Starting dynamic subtest: bcs0
[442.261102] Dynamic subtest bcs0: SUCCESS (2.548s)
[442.272471] Starting dynamic subtest: vcs0
[445.908295] Dynamic subtest vcs0: SUCCESS (3.643s)
[445.919765] Starting dynamic subtest: vcs1
[449.939802] Dynamic subtest vcs1: SUCCESS (4.030s)
[449.951210] Starting dynamic subtest: vecs0
[453.907729] Dynamic subtest vecs0: SUCCESS (3.965s)
[453.918074] Starting dynamic subtest: ccs0
[456.462032] Dynamic subtest ccs0: SUCCESS (2.550s)
[456.464683] Subtest busy-start: SUCCESS (19.300s)
[456.610179] [054/130] (563s left) gem_spin_batch (spin-each)
[456.683593] Starting subtest: spin-each
[460.048702] Subtest spin-each: SUCCESS (3.362s)
[461.964237] [055/130] (558s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[462.065602] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[462.069701] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[462.194311] [056/130] (558s left) kms_chamelium_frames (hdmi-crc-multiple)
[462.573792] Subtest hdmi-crc-multiple: SKIP (0.000s)
[463.364871] [057/130] (556s left) gen9_exec_parse (bb-start-out)
[463.420766] Subtest bb-start-out: SKIP (0.000s)
[463.486478] [058/130] (556s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[463.600346] Starting subtest: cursorB-vs-flipA-atomic-transitions
[463.604698] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[463.772462] [059/130] (556s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[463.879160] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[463.883537] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[464.007952] [060/130] (556s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[464.093811] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[464.096016] Starting dynamic subtest: pipe-A-eDP-1
[464.120606] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[464.131933] Starting dynamic subtest: pipe-B-eDP-1
[464.153196] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[464.161787] Starting dynamic subtest: pipe-C-eDP-1
[464.183524] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[464.191949] Starting dynamic subtest: pipe-D-eDP-1
[464.214043] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[464.218088] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[466.271575] [061/130] (554s left) kms_flip (absolute-wf_vblank)
[466.362207] Starting subtest: absolute-wf_vblank
[466.364287] Starting dynamic subtest: A-eDP1
[475.126434] Dynamic subtest A-eDP1: SUCCESS (8.766s)
[475.135265] Starting dynamic subtest: B-eDP1
[483.856700] Dynamic subtest B-eDP1: SUCCESS (8.729s)
[483.871464] Starting dynamic subtest: C-eDP1
[492.576862] Dynamic subtest C-eDP1: SUCCESS (8.718s)
[492.587332] Starting dynamic subtest: D-eDP1
[501.296499] Dynamic subtest D-eDP1: SUCCESS (8.719s)
[501.302293] Subtest absolute-wf_vblank: SUCCESS (34.942s)
[502.525028] [062/130] (517s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[502.582507] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[502.689457] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[502.777164] [063/130] (517s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[502.822031] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[502.824319] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[502.926816] [064/130] (517s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[503.019046] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[503.030237] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.010s)
[503.147784] [065/130] (517s left) kms_cursor_crc (cursor-onscreen-128x42)
[503.357777] Starting subtest: cursor-onscreen-128x42
[503.400812] Subtest cursor-onscreen-128x42: SKIP (0.035s)
[503.613515] [066/130] (517s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[503.707343] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[503.709271] Starting dynamic subtest: pipe-A-eDP-1
[503.758468] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[503.768189] Starting dynamic subtest: pipe-B-eDP-1
[503.816368] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[503.826154] Starting dynamic subtest: pipe-C-eDP-1
[503.873793] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[503.882749] Starting dynamic subtest: pipe-D-eDP-1
[503.929323] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[503.932651] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[505.888288] [067/130] (514s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[505.973699] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[505.975962] Starting dynamic subtest: pipe-A-eDP-1
[505.997912] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[506.007104] Starting dynamic subtest: pipe-B-eDP-1
[506.028155] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[506.036856] Starting dynamic subtest: pipe-C-eDP-1
[506.059477] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[506.069147] Starting dynamic subtest: pipe-D-eDP-1
[506.090374] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[506.094725] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[508.160387] [068/130] (512s left) kms_vblank (query-idle)
[508.260027] Starting subtest: query-idle
[508.261934] Starting dynamic subtest: pipe-A-eDP-1
[510.011625] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.750s)
[510.026123] Starting dynamic subtest: pipe-D-eDP-1
[512.492073] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.479s)
[512.494416] Subtest query-idle: SUCCESS (4.233s)
[513.335221] [069/130] (507s left) gem_exec_reloc (basic-cpu-read-active)
[513.393703] Subtest basic-cpu-read-active: SKIP (0.000s)
[513.472632] [070/130] (507s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[513.574240] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[513.578588] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[513.694291] [071/130] (507s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[518.704951] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[518.707259] Starting dynamic subtest: pipe-A-eDP-1
[519.488065] Dynamic subtest pipe-A-eDP-1: SKIP (0.778s)
[519.497960] Starting dynamic subtest: pipe-B-eDP-1
[520.620939] Dynamic subtest pipe-B-eDP-1: SKIP (1.132s)
[520.625427] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.913s)
[521.832999] [072/130] (499s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[521.945076] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[528.095235] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.154s)
[529.239247] [073/130] (491s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[529.337702] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[529.342201] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[529.476662] [074/130] (491s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[529.567156] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[536.978719] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.414s)
[537.148571] [075/130] (483s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[537.251386] Starting subtest: fbc-modesetfrombusy
[541.021406] Subtest fbc-modesetfrombusy: SUCCESS (3.775s)
[542.247411] [076/130] (478s left) gem_tiled_blits (basic)
[542.299385] Subtest basic: SKIP (0.000s)
[542.371594] [077/130] (478s left) gem_eio (context-create)
[542.593078] Starting subtest: context-create
[542.894527] Subtest context-create: SUCCESS (0.301s)
[543.058459] [078/130] (478s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[543.118822] Starting subtest: x-tiled-to-vebox-y-tiled
[543.123084] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[543.310314] [079/130] (477s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[543.419582] Starting subtest: basic-busy-flip-before-cursor-varying-size
[543.424088] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[543.589461] [080/130] (477s left) kms_plane_alpha_blend (alpha-7efc)
[543.665808] Starting subtest: alpha-7efc
[543.950606] Starting dynamic subtest: pipe-A-eDP-1
[544.542185] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.589s)
[544.571438] Starting dynamic subtest: pipe-D-eDP-1
[545.837660] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.275s)
[545.839973] Subtest alpha-7efc: SUCCESS (2.173s)
[546.650332] [081/130] (474s left) i915_selftest (perf)
[548.589352] Starting subtest: perf
[548.591953] Starting dynamic subtest: engine_cs
[553.012704] Dynamic subtest engine_cs: SUCCESS (4.422s)
[553.020676] Starting dynamic subtest: request
[558.362755] Dynamic subtest request: SUCCESS (5.348s)
[558.371438] Starting dynamic subtest: migrate
[562.766610] Dynamic subtest migrate: SUCCESS (4.401s)
[562.776242] Starting dynamic subtest: region
[568.670992] Dynamic subtest region: SUCCESS (5.903s)
[568.673626] Subtest perf: SUCCESS (20.081s)
[568.710156] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[568.710863] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[568.714048] [082/130] (452s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[570.207731] Starting subtest: short-flip-before-cursor-toggle
[571.135400] Subtest short-flip-before-cursor-toggle: SUCCESS (0.926s)
[572.001007] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[572.002119] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: kernel.kmod_is_loaded.i915: true
[572.005963] [083/130] (449s left) kms_pm_rpm (modeset-lpsp-stress)
[574.045539] Starting subtest: modeset-lpsp-stress
[590.144544] Subtest modeset-lpsp-stress: SUCCESS (16.100s)
[590.243598] [084/130] (431s left) gem_exec_flush (basic-wb-rw-before-default)
[590.309477] Starting subtest: basic-wb-rw-before-default
[595.758265] Subtest basic-wb-rw-before-default: SUCCESS (5.449s)
[595.971601] [085/130] (425s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[596.071478] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[596.818130] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.749s)
[596.990449] [086/130] (424s left) i915_query (query-regions-unallocated)
[597.045173] Starting subtest: query-regions-unallocated
[597.047344] Subtest query-regions-unallocated: SUCCESS (0.000s)
[597.151245] [087/130] (424s left) gem_exec_flush (basic-batch-kernel-default-wb)
[597.198459] Starting subtest: basic-batch-kernel-default-wb
[604.029138] Subtest basic-batch-kernel-default-wb: SUCCESS (6.831s)
[604.238406] [088/130] (417s left) syncobj_wait (multi-wait-all-signaled)
[604.299511] Starting subtest: multi-wait-all-signaled
[604.301782] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[604.403993] [089/130] (417s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[604.507944] Starting subtest: fbc-dirtyfb-ioctl
[604.510225] Starting dynamic subtest: A-eDP-1
[607.076781] Dynamic subtest A-eDP-1: SUCCESS (2.567s)
[607.078646] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.568s)
[607.878746] [090/130] (413s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[612.884931] Starting subtest: psr2-overlay-plane-update-continuous-sf
[612.887272] Starting dynamic subtest: pipe-A-eDP-1
[614.626077] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.740s)
[614.634984] Starting dynamic subtest: pipe-B-eDP-1
[616.359899] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.732s)
[616.362229] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (3.477s)
[617.182079] [091/130] (404s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[617.280415] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[617.284804] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[617.399861] [092/130] (404s left) kms_fbcon_fbt (fbc-suspend)
[617.454428] Starting subtest: fbc-suspend
[665.561579] Subtest fbc-suspend: SUCCESS (18.969s)
[666.691558] [093/130] (355s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[666.798385] Starting subtest: y-tiled-8bpp-rotate-270
[666.802748] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[666.908627] [094/130] (354s left) gem_exec_schedule (u-submit-early-slice)
[666.985055] Starting subtest: u-submit-early-slice
[666.987060] Starting dynamic subtest: rcs0
[667.021935] Dynamic subtest rcs0: SUCCESS (0.046s)
[667.032054] Starting dynamic subtest: bcs0
[667.062891] Dynamic subtest bcs0: SUCCESS (0.041s)
[667.073862] Starting dynamic subtest: vcs0
[667.101340] Dynamic subtest vcs0: SUCCESS (0.038s)
[667.116869] Starting dynamic subtest: vcs1
[667.135633] Dynamic subtest vcs1: SUCCESS (0.034s)
[667.144952] Starting dynamic subtest: vecs0
[667.167038] Dynamic subtest vecs0: SUCCESS (0.031s)
[667.176635] Starting dynamic subtest: ccs0
[667.207591] Dynamic subtest ccs0: SUCCESS (0.040s)
[667.209876] Subtest u-submit-early-slice: SUCCESS (0.233s)
[668.318195] [095/130] (353s left) vgem_basic (debugfs)
[668.370644] Starting subtest: debugfs
[668.372865] Subtest debugfs: SUCCESS (0.001s)
[668.450834] [096/130] (353s left) gen7_exec_parse (batch-without-end)
[668.511494] Subtest batch-without-end: SKIP (0.000s)
[668.584804] [097/130] (353s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[668.680891] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[690.507525] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (21.869s)
[690.668953] [098/130] (331s left) dmabuf_sync_file (export-basic)
[690.717109] Starting subtest: export-basic
[690.719046] Subtest export-basic: SUCCESS (0.002s)
[690.794463] [099/130] (331s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[690.884893] Starting subtest: random-ccs-data-y-tiled-ccs
[690.886829] Starting dynamic subtest: pipe-A-eDP-1
[690.907042] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[690.916881] Starting dynamic subtest: pipe-B-eDP-1
[690.936325] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[690.944994] Starting dynamic subtest: pipe-C-eDP-1
[690.964057] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[690.972642] Starting dynamic subtest: pipe-D-eDP-1
[690.992500] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[690.996104] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.005s)
[693.089450] [100/130] (328s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[693.205181] Starting subtest: cursorB-vs-flipB-atomic
[693.209721] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[693.374041] [101/130] (328s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[693.443268] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[693.497421] [102/130] (328s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[693.625374] Starting subtest: protected-encrypted-src-copy-not-readible
[693.734214] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.111s)
[693.872174] [103/130] (328s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[693.964343] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[693.966649] Starting dynamic subtest: pipe-A-default-mode
[695.127249] Dynamic subtest pipe-A-default-mode: SKIP (1.158s)
[695.131812] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.160s)
[695.306127] [104/130] (326s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[695.399516] Starting subtest: fbcpsr-tiling-y
[695.403676] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[695.502345] [105/130] (326s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[696.036154] Starting subtest: syncobj-timeline-invalid-flags
[696.038430] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[697.240547] [106/130] (325s left) gem_basic (create-close)
[697.294104] Starting subtest: create-close
[697.298768] Subtest create-close: SUCCESS (0.000s)
[697.410139] [107/130] (324s left) api_intel_allocator (reloc-allocator)
[697.468951] Starting subtest: reloc-allocator
[697.471219] Starting dynamic subtest: basic
[697.473612] Dynamic subtest basic: SUCCESS (0.005s)
[697.484844] Starting dynamic subtest: parallel-one
[697.487022] Dynamic subtest parallel-one: SUCCESS (0.008s)
[697.496038] Starting dynamic subtest: print
[697.497910] Dynamic subtest print: SUCCESS (0.000s)
[697.506432] Starting dynamic subtest: reuse
[697.508368] Dynamic subtest reuse: SUCCESS (0.000s)
[697.516889] Starting dynamic subtest: fork-reopen-allocator
[697.518814] Dynamic subtest fork-reopen-allocator: SUCCESS (0.010s)
[697.520661] Subtest reloc-allocator: SUCCESS (0.025s)
[697.665690] [108/130] (324s left) kms_psr (pr-primary-blt)
[697.762799] Starting subtest: pr-primary-blt
[697.766790] Subtest pr-primary-blt: SKIP (0.000s)
[697.848679] [109/130] (324s left) gem_ctx_bad_destroy (double-destroy)
[697.913134] Starting subtest: double-destroy
[697.915480] Subtest double-destroy: SUCCESS (0.000s)
[697.998666] [110/130] (324s left) core_getclient
[698.175450] [111/130] (324s left) syncobj_wait (wait-all-delayed-signal)
[698.228357] Starting subtest: wait-all-delayed-signal
[698.334174] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[698.434055] [112/130] (324s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[698.523855] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[698.528243] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[698.648352] [113/130] (323s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[698.747044] Starting subtest: yf-tiled-16bpp-rotate-0
[698.751221] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[698.875643] [114/130] (323s left) kms_async_flips (test-time-stamp)
[698.957149] Starting subtest: test-time-stamp
[699.329870] Starting dynamic subtest: pipe-A-eDP-1
[700.108246] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.775s)
[700.484374] Starting dynamic subtest: pipe-B-eDP-1
[701.247581] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.768s)
[701.627202] Starting dynamic subtest: pipe-C-eDP-1
[702.385315] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.765s)
[702.747339] Starting dynamic subtest: pipe-D-eDP-1
[703.515367] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.773s)
[703.517778] Subtest test-time-stamp: SUCCESS (4.556s)
[704.701002] [115/130] (317s left) kms_invalid_mode (bad-vsync-end)
[704.788133] Starting subtest: bad-vsync-end
[704.790408] Starting dynamic subtest: pipe-A-eDP-1
[705.079106] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[705.088743] Starting dynamic subtest: pipe-B-eDP-1
[705.090609] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[705.099977] Starting dynamic subtest: pipe-C-eDP-1
[705.102249] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[705.113356] Starting dynamic subtest: pipe-D-eDP-1
[705.115722] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[705.118120] Subtest bad-vsync-end: SUCCESS (0.294s)
[705.921404] [116/130] (316s left) gem_exec_suspend (basic)
[705.980872] Starting subtest: basic
[705.983346] Starting dynamic subtest: smem
[707.159250] Dynamic subtest smem: SUCCESS (1.177s)
[707.161765] Subtest basic: SUCCESS (1.178s)
[707.366161] [117/130] (315s left) api_intel_bb (blit-noreloc-purge-cache)
[707.424996] Starting subtest: blit-noreloc-purge-cache
[707.435272] Subtest blit-noreloc-purge-cache: SUCCESS (0.012s)
[707.573746] [118/130] (315s left) kms_plane (pixel-format-source-clamping)
[707.657037] Starting subtest: pixel-format-source-clamping
[707.684595] Starting dynamic subtest: pipe-A-plane-0
[712.517543] Dynamic subtest pipe-A-plane-0: SUCCESS (4.833s)
[712.529730] Starting dynamic subtest: pipe-A-plane-3
[714.772856] Dynamic subtest pipe-A-plane-3: SUCCESS (2.255s)
[714.782574] Starting dynamic subtest: pipe-A-plane-5
[714.784445] Dynamic subtest pipe-A-plane-5: SUCCESS (0.000s)
[715.890027] Starting dynamic subtest: pipe-B-plane-0
[720.659504] Dynamic subtest pipe-B-plane-0: SUCCESS (4.777s)
[720.672317] Starting dynamic subtest: pipe-B-plane-3
[723.003399] Dynamic subtest pipe-B-plane-3: SUCCESS (2.344s)
[723.013776] Starting dynamic subtest: pipe-B-plane-5
[723.015793] Dynamic subtest pipe-B-plane-5: SUCCESS (0.000s)
[723.309983] Subtest pixel-format-source-clamping: SUCCESS (15.654s)
[724.184326] [119/130] (298s left) i915_query (query-topology-kernel-writes)
[724.251513] Starting subtest: query-topology-kernel-writes
[724.253821] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[724.394476] [120/130] (298s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[724.764602] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[725.612109] [121/130] (297s left) gem_exec_reloc (basic-cpu-active)
[725.669049] Subtest basic-cpu-active: SKIP (0.000s)
[725.739148] [122/130] (297s left) gem_exec_fence (concurrent)
[725.918917] Starting subtest: concurrent
[725.923623] Subtest concurrent: SKIP (0.000s)
[727.265629] [123/130] (295s left) perf (short-reads)
[727.405911] Starting subtest: short-reads
[727.422522] Subtest short-reads: SUCCESS (0.017s)
[727.479350] [124/130] (295s left) dumb_buffer (create-clear)
[727.534704] Starting subtest: create-clear
[761.555611] Subtest create-clear: SUCCESS (34.024s)
[761.672567] [125/130] (261s left) kms_psr2_su (page_flip-p010)
[761.734372] Subtest page_flip-P010: SKIP (0.000s)
[761.797929] [126/130] (261s left) i915_pm_rc6_residency (rc6-idle)
[761.858038] Starting subtest: rc6-idle
[761.899385] Starting dynamic subtest: gt0-rcs0
[786.176213] Dynamic subtest gt0-rcs0: SUCCESS (24.273s)
[786.186022] Starting dynamic subtest: gt0-bcs0
[810.466508] Dynamic subtest gt0-bcs0: SUCCESS (24.290s)
[810.476024] Starting dynamic subtest: gt0-ccs0
[834.736574] Dynamic subtest gt0-ccs0: SUCCESS (24.273s)
[834.745774] Starting dynamic subtest: gt1-vcs0
[840.910598] Dynamic subtest gt1-vcs0: SUCCESS (6.172s)
[840.921110] Starting dynamic subtest: gt1-vecs0
[847.082591] Dynamic subtest gt1-vecs0: SUCCESS (6.170s)
[847.085614] Subtest rc6-idle: SUCCESS (85.230s)
[847.188026] [127/130] (176s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[847.282362] Starting subtest: plane-scaler-unity-scaling-with-rotation
[847.284741] Starting dynamic subtest: pipe-A
[847.510474] Dynamic subtest pipe-A: SUCCESS (0.228s)
[847.522856] Starting dynamic subtest: pipe-B
[848.853347] Dynamic subtest pipe-B: SUCCESS (1.342s)
[848.863370] Starting dynamic subtest: pipe-C
[850.182785] Dynamic subtest pipe-C: SUCCESS (1.329s)
[850.193501] Starting dynamic subtest: pipe-D
[851.507993] Dynamic subtest pipe-D: SUCCESS (1.325s)
[851.510294] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.226s)
[852.707803] [128/130] (170s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[852.818500] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[852.822616] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[852.959938] [129/130] (170s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[853.079389] Starting subtest: cursor-vs-flip-legacy
[882.292258] Subtest cursor-vs-flip-legacy: SUCCESS (29.221s)
[883.134343] [130/130] (140s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[883.192991] Starting subtest: multi-wait-available-submitted-signaled
[883.195237] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[883.328849] Closing watchdogs
results: parsing output: 0/ for test: kms_plane_scaling
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: kms_flip
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: gem_ctx_exec
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: core_getstats
results: parsing output: 9/ for test: i915_hangman
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: gem_lmem_swapping
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_ctx_persistence
results: parsing output: 16/ for test: prime_vgem
results: parsing output: 17/ for test: gem_exec_schedule
results: parsing output: 18/ for test: kms_flip
results: parsing output: 19/ for test: kms_cursor_crc
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: gem_exec_balancer
results: parsing output: 22/ for test: kms_psr
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: gem_mmap_gtt
results: parsing output: 26/ for test: perf_pmu
results: parsing output: 27/ for test: gem_create
results: parsing output: 28/ for test: gem_exec_reloc
results: parsing output: 29/ for test: gem_ctx_persistence
results: parsing output: 30/ for test: syncobj_wait
results: parsing output: 31/ for test: gem_busy
results: parsing output: 32/ for test: kms_addfb_basic
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: syncobj_timeline
results: parsing output: 35/ for test: kms_lease
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_mmap_offset
results: parsing output: 38/ for test: gem_exec_whisper
results: parsing output: 39/ for test: gem_softpin
results: parsing output: 40/ for test: gem_exec_reloc
results: parsing output: 41/ for test: kms_vrr
results: parsing output: 42/ for test: syncobj_timeline
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: kms_feature_discovery
results: parsing output: 45/ for test: kms_pm_rpm
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: gem_exec_reloc
results: parsing output: 48/ for test: kms_scaling_modes
results: parsing output: 49/ for test: kms_color
results: parsing output: 50/ for test: kms_cursor_crc
results: parsing output: 51/ for test: kms_cursor_legacy
results: parsing output: 52/ for test: perf_pmu
results: parsing output: 53/ for test: gem_spin_batch
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_chamelium_frames
results: parsing output: 56/ for test: gen9_exec_parse
results: parsing output: 57/ for test: kms_cursor_legacy
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: kms_flip
results: parsing output: 64/ for test: kms_cursor_crc
results: parsing output: 65/ for test: kms_ccs
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: kms_vblank
results: parsing output: 68/ for test: gem_exec_reloc
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_psr2_sf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: gem_tiled_blits
results: parsing output: 76/ for test: gem_eio
results: parsing output: 77/ for test: gem_render_copy
results: parsing output: 78/ for test: kms_cursor_legacy
results: parsing output: 79/ for test: kms_plane_alpha_blend
results: parsing output: 80/ for test: i915_selftest
results: parsing output: 81/ for test: kms_cursor_legacy
results: parsing output: 82/ for test: kms_pm_rpm
results: parsing output: 83/ for test: gem_exec_flush
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: i915_query
results: parsing output: 86/ for test: gem_exec_flush
results: parsing output: 87/ for test: syncobj_wait
results: parsing output: 88/ for test: kms_dirtyfb
results: parsing output: 89/ for test: kms_psr2_sf
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_fbcon_fbt
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: vgem_basic
results: parsing output: 95/ for test: gen7_exec_parse
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: dmabuf_sync_file
results: parsing output: 98/ for test: kms_ccs
results: parsing output: 99/ for test: kms_cursor_legacy
results: parsing output: 100/ for test: gem_mmap_gtt
results: parsing output: 101/ for test: gem_pxp
results: parsing output: 102/ for test: kms_flip_scaled_crc
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_fence
results: parsing output: 105/ for test: gem_basic
results: parsing output: 106/ for test: api_intel_allocator
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: gem_ctx_bad_destroy
results: parsing output: 109/ for test: core_getclient
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: kms_async_flips
results: parsing output: 114/ for test: kms_invalid_mode
results: parsing output: 115/ for test: gem_exec_suspend
results: parsing output: 116/ for test: api_intel_bb
results: parsing output: 117/ for test: kms_plane
results: parsing output: 118/ for test: i915_query
results: parsing output: 119/ for test: kms_chamelium_edid
results: parsing output: 120/ for test: gem_exec_reloc
results: parsing output: 121/ for test: gem_exec_fence
results: parsing output: 122/ for test: perf
results: parsing output: 123/ for test: dumb_buffer
results: parsing output: 124/ for test: kms_psr2_su
results: parsing output: 125/ for test: i915_pm_rc6_residency
results: parsing output: 126/ for test: kms_plane_scaling
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_cursor_legacy
results: parsing output: 129/ for test: syncobj_timeline
Done.