[56.566628] Initializing watchdogs
[56.566740]   /dev/watchdog0
[56.580973] [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]
[56.585118] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.587213] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.590687] [001/130] (960s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[56.723789] Starting subtest: plane-upscale-20x20-with-rotation
[56.726144] Starting dynamic subtest: pipe-A
[56.957932] Dynamic subtest pipe-A: SUCCESS (0.236s)
[56.966459] Starting dynamic subtest: pipe-B
[58.276649] Dynamic subtest pipe-B: SUCCESS (1.273s)
[58.285187] Starting dynamic subtest: pipe-C
[59.557815] Dynamic subtest pipe-C: SUCCESS (1.235s)
[59.566680] Starting dynamic subtest: pipe-D
[60.823417] Dynamic subtest pipe-D: SUCCESS (1.226s)
[60.825907] Subtest plane-upscale-20x20-with-rotation: SUCCESS (3.971s)
[62.046597] [002/130] (955s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[62.159830] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[62.164090] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[62.306057] [003/130] (954s left) kms_big_fb (linear-8bpp-rotate-180)
[62.400597] Starting subtest: linear-8bpp-rotate-180
[63.525692] Subtest linear-8bpp-rotate-180: SUCCESS (1.101s)
[63.684271] [004/130] (953s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[63.784643] Starting subtest: blocking-absolute-wf_vblank-interruptible
[63.787123] Starting dynamic subtest: A-eDP1
[72.531883] Dynamic subtest A-eDP1: SUCCESS (8.646s)
[72.541517] Starting dynamic subtest: B-eDP1
[81.299393] Dynamic subtest B-eDP1: SUCCESS (8.735s)
[81.306502] Starting dynamic subtest: C-eDP1
[90.030515] Dynamic subtest C-eDP1: SUCCESS (8.721s)
[90.039573] Starting dynamic subtest: D-eDP1
[98.763610] Dynamic subtest D-eDP1: SUCCESS (8.727s)
[98.766102] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.834s)
[99.993728] [005/130] (917s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[100.097436] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[100.101458] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[100.224212] [006/130] (917s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[100.331347] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[106.841602] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (6.509s)
[106.996497] [007/130] (910s left) gem_ctx_exec (basic-norecovery)
[107.057691] Starting subtest: basic-norecovery
[107.340790] Subtest basic-norecovery: SUCCESS (0.284s)
[107.497794] [008/130] (909s left) kms_psr (psr-primary-page-flip)
[107.591857] Starting subtest: psr-primary-page-flip
[107.594680] Starting dynamic subtest: eDP-1
[109.328948] Dynamic subtest eDP-1: SUCCESS (1.734s)
[109.331345] Subtest psr-primary-page-flip: SUCCESS (1.735s)
[110.198048] [009/130] (907s left) core_getstats
[110.373191] [010/130] (907s left) i915_hangman (detector)
[110.446506] Starting subtest: detector
[110.448901] Starting dynamic subtest: rcs0
[121.909706] Dynamic subtest rcs0: SUCCESS (11.460s)
[121.919788] Starting dynamic subtest: bcs0
[133.873361] Dynamic subtest bcs0: SUCCESS (11.961s)
[133.905433] Starting dynamic subtest: vcs0
[145.843838] Dynamic subtest vcs0: SUCCESS (11.968s)
[145.854269] Starting dynamic subtest: vcs1
[157.876753] Dynamic subtest vcs1: SUCCESS (12.031s)
[157.887217] Starting dynamic subtest: vecs0
[168.818441] Dynamic subtest vecs0: SUCCESS (10.942s)
[168.828616] Starting dynamic subtest: ccs0
[179.890388] Dynamic subtest ccs0: SUCCESS (11.071s)
[179.893247] Subtest detector: SUCCESS (69.436s)
[179.991942] [011/130] (837s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[180.091043] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[180.093435] Starting dynamic subtest: pipe-A-eDP-1
[182.091864] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.001s)
[182.099956] Starting dynamic subtest: pipe-B-eDP-1
[184.127874] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.037s)
[184.136447] Starting dynamic subtest: pipe-C-eDP-1
[186.218121] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.089s)
[186.227071] Starting dynamic subtest: pipe-D-eDP-1
[188.292455] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.072s)
[188.294777] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.201s)
[189.557309] [012/130] (827s left) gem_lmem_swapping (parallel-random-engines)
[189.603437] Subtest parallel-random-engines: SKIP (0.000s)
[189.645483] [013/130] (827s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[189.747660] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[189.752315] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[189.883074] [014/130] (827s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[189.982202] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[189.986113] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[190.097372] [015/130] (827s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[190.193279] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[190.197190] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[190.302619] [016/130] (827s left) gem_ctx_persistence (legacy-engines-hostile)
[190.362894] Starting subtest: legacy-engines-hostile
[190.365336] Starting dynamic subtest: render
[190.496896] Dynamic subtest render: SUCCESS (0.133s)
[190.507645] Starting dynamic subtest: bsd1
[190.605812] Dynamic subtest bsd1: SUCCESS (0.104s)
[190.616322] Starting dynamic subtest: bsd2
[190.717617] Dynamic subtest bsd2: SUCCESS (0.113s)
[190.727563] Starting dynamic subtest: blt
[190.819206] Dynamic subtest blt: SUCCESS (0.100s)
[190.829505] Starting dynamic subtest: vebox
[190.929479] Dynamic subtest vebox: SUCCESS (0.108s)
[190.931687] Subtest legacy-engines-hostile: SUCCESS (0.567s)
[191.023800] [017/130] (826s left) prime_vgem (shrink)
[191.147089] Starting subtest: shrink
[191.161179] Subtest shrink: SUCCESS (0.012s)
[191.281811] [FACT prime_vgem (shrink)] new: kernel.kmod_is_loaded.vgem: true
[191.284720] [018/130] (826s left) gem_exec_schedule (preempt-queue-chain)
[191.350853] Subtest preempt-queue-chain: SKIP (0.000s)
[191.665512] [019/130] (826s left) kms_flip (flip-vs-panning-vs-hang)
[191.757415] Starting subtest: flip-vs-panning-vs-hang
[191.759726] Starting dynamic subtest: A-eDP1
[232.377437] Dynamic subtest A-eDP1: SUCCESS (40.620s)
[232.387674] Starting dynamic subtest: D-eDP1
[272.382446] Dynamic subtest D-eDP1: SUCCESS (40.001s)
[272.385284] Subtest flip-vs-panning-vs-hang: SUCCESS (80.624s)
[273.653839] [020/130] (744s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[273.880692] Starting subtest: cursor-rapid-movement-256x256
[273.886675] Starting dynamic subtest: pipe-A-eDP-1
[274.424723] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.541s)
[274.451344] Starting dynamic subtest: pipe-D-eDP-1
[275.634863] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.191s)
[275.637249] Subtest cursor-rapid-movement-256x256: SUCCESS (1.754s)
[276.505316] [021/130] (741s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[276.611612] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[276.615676] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[276.720072] [022/130] (741s left) gem_exec_balancer (sliced)
[276.783625] Starting subtest: sliced
[276.788267] Subtest sliced: SKIP (0.000s)
[277.099403] [023/130] (740s left) kms_psr (fbc-psr-suspend)
[277.194130] Starting subtest: fbc-psr-suspend
[277.196397] Starting dynamic subtest: eDP-1
[277.200748] Dynamic subtest eDP-1: SKIP (0.000s)
[277.205068] Subtest fbc-psr-suspend: SKIP (0.001s)
[277.275176] [024/130] (740s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[277.379556] Starting subtest: fbcpsr-stridechange
[283.501303] Subtest fbcpsr-stridechange: SUCCESS (6.124s)
[283.666124] [025/130] (734s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[283.774924] Starting subtest: 4-tiled-32bpp-rotate-180
[285.231415] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.458s)
[285.370408] [026/130] (732s left) gem_mmap_gtt (basic-read-write-distinct)
[285.432233] Subtest basic-read-write-distinct: SKIP (0.000s)
[285.499677] [027/130] (732s left) perf_pmu (busy-accuracy-2)
[285.536692] Starting subtest: busy-accuracy-2
[285.538827] Starting dynamic subtest: rcs0
[288.575207] Dynamic subtest rcs0: SUCCESS (3.037s)
[288.585633] Starting dynamic subtest: bcs0
[291.627708] Dynamic subtest bcs0: SUCCESS (3.051s)
[291.637907] Starting dynamic subtest: vcs0
[294.672471] Dynamic subtest vcs0: SUCCESS (3.044s)
[294.682867] Starting dynamic subtest: vcs1
[297.717882] Dynamic subtest vcs1: SUCCESS (3.044s)
[297.727508] Starting dynamic subtest: vecs0
[300.764234] Dynamic subtest vecs0: SUCCESS (3.045s)
[300.773101] Starting dynamic subtest: ccs0
[303.821668] Dynamic subtest ccs0: SUCCESS (3.056s)
[303.824076] Subtest busy-accuracy-2: SUCCESS (18.285s)
[303.960508] [028/130] (714s left) gem_create (create-ext-set-pat)
[304.126996] Starting subtest: create-ext-set-pat
[304.129983] Subtest create-ext-set-pat: SUCCESS (0.001s)
[304.266052] [029/130] (713s left) gem_exec_reloc (basic-wc-cpu-active)
[304.326596] Subtest basic-wc-cpu-active: SKIP (0.000s)
[304.402948] [030/130] (713s left) gem_ctx_persistence (userptr)
[304.470123] Starting subtest: userptr
[304.678460] Subtest userptr: SUCCESS (0.207s)
[304.768360] [031/130] (713s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[304.816281] Starting subtest: multi-wait-all-for-submit-unsubmitted
[304.920913] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[305.019868] [032/130] (713s left) gem_busy (close-race)
[305.076682] Starting subtest: close-race
[328.894352] Subtest close-race: SUCCESS (23.816s)
[329.289200] [033/130] (688s left) kms_addfb_basic (small-bo)
[329.422261] Starting subtest: small-bo
[329.424210] Subtest small-bo: SUCCESS (0.000s)
[329.535792] [034/130] (688s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[329.631766] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[334.087364] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.460s)
[335.231883] [035/130] (683s left) syncobj_timeline (invalid-transfer-non-existent-point)
[335.291977] Starting subtest: invalid-transfer-non-existent-point
[335.294759] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[335.420405] [036/130] (682s left) kms_lease (lease-revoke)
[335.513302] Starting subtest: lease-revoke
[335.515717] Starting dynamic subtest: pipe-A-eDP-1
[335.549490] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.035s)
[335.561240] Starting dynamic subtest: pipe-B-eDP-1
[335.578196] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.027s)
[335.587812] Starting dynamic subtest: pipe-C-eDP-1
[335.608430] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.027s)
[335.618077] Starting dynamic subtest: pipe-D-eDP-1
[335.633440] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.026s)
[335.635590] Subtest lease-revoke: SUCCESS (0.120s)
[335.800514] [037/130] (682s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[335.898788] Starting subtest: psr-rgb101010-draw-pwrite
[343.467548] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.572s)
[343.589957] [038/130] (674s left) gem_mmap_offset (partial-remap)
[343.649389] Starting subtest: partial-remap
[343.651736] Starting dynamic subtest: smem0
[343.660597] Dynamic subtest smem0: SUCCESS (0.004s)
[343.662796] Subtest partial-remap: SUCCESS (0.004s)
[343.810673] [039/130] (674s left) gem_exec_whisper (basic-fds-priority)
[343.868647] Starting subtest: basic-fds-priority
[366.762828] Subtest basic-fds-priority: SUCCESS (22.889s)
[366.955830] [040/130] (651s left) gem_softpin (allocator-nopin)
[367.016497] Starting subtest: allocator-nopin
[367.135929] Subtest allocator-nopin: SUCCESS (0.118s)
[367.278860] [041/130] (651s left) gem_exec_reloc (basic-concurrent16)
[367.336444] Subtest basic-concurrent16: SKIP (0.000s)
[367.426854] [042/130] (651s left) kms_vrr (negative-basic)
[367.519801] Starting subtest: negative-basic
[367.526095] Starting dynamic subtest: pipe-A-eDP-1
[372.942989] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.414s)
[373.240903] Subtest negative-basic: SUCCESS (5.722s)
[374.127970] [043/130] (644s left) syncobj_timeline (single-wait-all-signaled)
[374.176898] Starting subtest: single-wait-all-signaled
[374.179145] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[374.296885] [044/130] (644s left) kms_psr (fbc-psr2-primary-page-flip)
[374.399878] Starting subtest: fbc-psr2-primary-page-flip
[374.401712] Starting dynamic subtest: eDP-1
[374.405709] Dynamic subtest eDP-1: SKIP (0.000s)
[374.409785] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[374.482136] [045/130] (644s left) kms_feature_discovery (display-4x)
[374.584258] Starting subtest: display-4x
[374.588219] Subtest display-4x: SKIP (0.000s)
[374.666146] [046/130] (644s left) kms_pm_rpm (universal-planes)
[376.204915] Starting subtest: universal-planes
[376.207637] Starting dynamic subtest: plane-33
[384.501075] Dynamic subtest plane-33: SUCCESS (8.294s)
[384.513054] Starting dynamic subtest: plane-43
[392.897726] Dynamic subtest plane-43: SUCCESS (8.394s)
[392.910732] Starting dynamic subtest: plane-53
[401.211598] Dynamic subtest plane-53: SUCCESS (8.311s)
[401.222041] Starting dynamic subtest: plane-63
[409.496656] Dynamic subtest plane-63: SUCCESS (8.283s)
[409.506427] Starting dynamic subtest: plane-73
[417.770040] Dynamic subtest plane-73: SUCCESS (8.271s)
[417.781388] Starting dynamic subtest: plane-83
[426.143726] Dynamic subtest plane-83: SUCCESS (8.371s)
[426.146394] Subtest universal-planes: SUCCESS (49.936s)
[426.548618] [047/130] (592s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[426.652823] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[427.733070] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.082s)
[427.884648] [048/130] (590s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[427.944240] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[428.031136] [049/130] (590s left) kms_scaling_modes (scaling-mode-full-aspect)
[428.125490] Starting subtest: scaling-mode-full-aspect
[428.127163] Starting dynamic subtest: pipe-A-eDP-1
[428.219196] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.095s)
[428.229115] Starting dynamic subtest: pipe-B-eDP-1
[429.340618] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.120s)
[429.353384] Starting dynamic subtest: pipe-C-eDP-1
[430.453460] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.110s)
[430.465338] Starting dynamic subtest: pipe-D-eDP-1
[431.583893] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.128s)
[431.586477] Subtest scaling-mode-full-aspect: SUCCESS (3.460s)
[432.764129] [050/130] (586s left) kms_color (degamma)
[432.859393] Starting subtest: degamma
[432.861628] Starting dynamic subtest: pipe-A-eDP-1
[433.556695] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.698s)
[433.566914] Starting dynamic subtest: pipe-B-eDP-1
[434.939859] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.381s)
[434.948599] Starting dynamic subtest: pipe-C-eDP-1
[436.313330] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.374s)
[436.323681] Starting dynamic subtest: pipe-D-eDP-1
[437.623795] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.310s)
[437.626143] Subtest degamma: SUCCESS (4.766s)
[438.469561] [051/130] (580s left) kms_cursor_crc (cursor-offscreen-256x85)
[438.739377] Starting subtest: cursor-offscreen-256x85
[438.782202] Subtest cursor-offscreen-256x85: SKIP (0.036s)
[438.983687] [052/130] (580s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[439.104352] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[439.108968] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[439.271219] [053/130] (579s left) perf_pmu (busy-start)
[439.312396] Starting subtest: busy-start
[439.314746] Starting dynamic subtest: rcs0
[441.861948] Dynamic subtest rcs0: SUCCESS (2.552s)
[441.873854] Starting dynamic subtest: bcs0
[444.412311] Dynamic subtest bcs0: SUCCESS (2.548s)
[444.423457] Starting dynamic subtest: vcs0
[447.892906] Dynamic subtest vcs0: SUCCESS (3.477s)
[447.904203] Starting dynamic subtest: vcs1
[451.924620] Dynamic subtest vcs1: SUCCESS (4.029s)
[451.936430] Starting dynamic subtest: vecs0
[455.892029] Dynamic subtest vecs0: SUCCESS (3.965s)
[455.903767] Starting dynamic subtest: ccs0
[458.441371] Dynamic subtest ccs0: SUCCESS (2.549s)
[458.444027] Subtest busy-start: SUCCESS (19.131s)
[458.577460] [054/130] (560s left) gem_spin_batch (spin-each)
[458.639314] Starting subtest: spin-each
[462.007349] Subtest spin-each: SUCCESS (3.367s)
[463.964029] [055/130] (555s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[464.065661] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[464.069996] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[464.173856] [056/130] (555s left) kms_chamelium_frames (hdmi-crc-multiple)
[464.560390] Subtest hdmi-crc-multiple: SKIP (0.000s)
[465.360761] [057/130] (553s left) gen9_exec_parse (bb-start-out)
[465.419064] Subtest bb-start-out: SKIP (0.000s)
[465.493867] [058/130] (553s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[465.614485] Starting subtest: cursorB-vs-flipA-atomic-transitions
[465.619171] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[465.772061] [059/130] (553s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[465.864571] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[465.868675] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[465.985172] [060/130] (553s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[466.070409] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[466.072836] Starting dynamic subtest: pipe-A-eDP-1
[466.095973] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[466.107291] Starting dynamic subtest: pipe-B-eDP-1
[466.128115] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[466.136312] Starting dynamic subtest: pipe-C-eDP-1
[466.158242] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[466.166226] Starting dynamic subtest: pipe-D-eDP-1
[466.188590] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[466.192885] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[468.279112] [061/130] (551s left) kms_flip (absolute-wf_vblank)
[468.380246] Starting subtest: absolute-wf_vblank
[468.382156] Starting dynamic subtest: A-eDP1
[477.117724] Dynamic subtest A-eDP1: SUCCESS (8.738s)
[477.129634] Starting dynamic subtest: B-eDP1
[485.849618] Dynamic subtest B-eDP1: SUCCESS (8.731s)
[485.858958] Starting dynamic subtest: C-eDP1
[494.569051] Dynamic subtest C-eDP1: SUCCESS (8.717s)
[494.579130] Starting dynamic subtest: D-eDP1
[503.290135] Dynamic subtest D-eDP1: SUCCESS (8.720s)
[503.295662] Subtest absolute-wf_vblank: SUCCESS (34.914s)
[504.521343] [062/130] (514s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[504.582924] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[504.688995] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[504.778604] [063/130] (514s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[504.835665] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[504.838078] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[504.967086] [064/130] (514s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[505.059242] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[505.070145] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.010s)
[505.196117] [065/130] (514s left) kms_cursor_crc (cursor-onscreen-128x42)
[505.417125] Starting subtest: cursor-onscreen-128x42
[505.459853] Subtest cursor-onscreen-128x42: SKIP (0.041s)
[505.711835] [066/130] (513s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[505.800483] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[505.802349] Starting dynamic subtest: pipe-A-eDP-1
[505.852063] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[505.863244] Starting dynamic subtest: pipe-B-eDP-1
[505.911650] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[505.922107] Starting dynamic subtest: pipe-C-eDP-1
[505.972700] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[505.982235] Starting dynamic subtest: pipe-D-eDP-1
[506.029196] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[506.032906] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[507.971621] [067/130] (511s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[508.068687] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[508.071024] Starting dynamic subtest: pipe-A-eDP-1
[508.094342] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[508.105908] Starting dynamic subtest: pipe-B-eDP-1
[508.127691] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[508.136948] Starting dynamic subtest: pipe-C-eDP-1
[508.160133] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[508.170527] Starting dynamic subtest: pipe-D-eDP-1
[508.191655] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[508.195983] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[510.247802] [068/130] (509s left) kms_vblank (query-idle)
[510.346150] Starting subtest: query-idle
[510.348356] Starting dynamic subtest: pipe-A-eDP-1
[512.102618] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.755s)
[512.118920] Starting dynamic subtest: pipe-D-eDP-1
[514.577541] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.474s)
[514.579916] Subtest query-idle: SUCCESS (4.234s)
[515.439786] [069/130] (504s left) gem_exec_reloc (basic-cpu-read-active)
[515.494653] Subtest basic-cpu-read-active: SKIP (0.000s)
[515.578944] [070/130] (504s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[515.677193] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[515.681139] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[515.782835] [071/130] (504s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[520.786232] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[520.788647] Starting dynamic subtest: pipe-A-eDP-1
[521.573069] Dynamic subtest pipe-A-eDP-1: SKIP (0.786s)
[521.582786] Starting dynamic subtest: pipe-B-eDP-1
[522.701883] Dynamic subtest pipe-B-eDP-1: SKIP (1.126s)
[522.706188] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.916s)
[523.908267] [072/130] (495s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[524.016722] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[530.106786] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.094s)
[531.293115] [073/130] (488s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[531.398574] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[531.402576] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[531.502496] [074/130] (488s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[531.602578] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[539.003929] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.404s)
[539.196237] [075/130] (480s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[539.297834] Starting subtest: fbc-modesetfrombusy
[543.162248] Subtest fbc-modesetfrombusy: SUCCESS (3.869s)
[544.355017] [076/130] (475s left) gem_tiled_blits (basic)
[544.421454] Subtest basic: SKIP (0.000s)
[544.510110] [077/130] (475s left) gem_eio (context-create)
[544.729668] Starting subtest: context-create
[545.041105] Subtest context-create: SUCCESS (0.311s)
[545.167095] [078/130] (474s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[545.229521] Starting subtest: x-tiled-to-vebox-y-tiled
[545.233934] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[545.430957] [079/130] (474s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[545.551507] Starting subtest: basic-busy-flip-before-cursor-varying-size
[545.556057] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[545.706668] [080/130] (474s left) kms_plane_alpha_blend (alpha-7efc)
[545.796126] Starting subtest: alpha-7efc
[546.082421] Starting dynamic subtest: pipe-A-eDP-1
[546.646203] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.564s)
[546.674479] Starting dynamic subtest: pipe-D-eDP-1
[547.904895] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.238s)
[547.907467] Subtest alpha-7efc: SUCCESS (2.108s)
[548.714252] [081/130] (471s left) i915_selftest (perf)
[552.220396] Starting subtest: perf
[552.223211] Starting dynamic subtest: engine_cs
[556.606316] Dynamic subtest engine_cs: SUCCESS (4.384s)
[556.615206] Starting dynamic subtest: request
[563.913764] Dynamic subtest request: SUCCESS (7.307s)
[563.927825] Starting dynamic subtest: migrate
[568.343024] Dynamic subtest migrate: SUCCESS (4.428s)
[568.357316] Starting dynamic subtest: region
[574.212540] Dynamic subtest region: SUCCESS (5.867s)
[574.214977] Subtest perf: SUCCESS (21.992s)
[574.243055] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[574.243750] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[574.246820] [082/130] (445s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[575.751494] Starting subtest: short-flip-before-cursor-toggle
[576.688065] Subtest short-flip-before-cursor-toggle: SUCCESS (0.936s)
[577.521079] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[577.522241] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: kernel.kmod_is_loaded.i915: true
[577.526150] [083/130] (442s left) kms_pm_rpm (modeset-lpsp-stress)
[579.059567] Starting subtest: modeset-lpsp-stress
[594.878992] Subtest modeset-lpsp-stress: SUCCESS (15.818s)
[594.977482] [084/130] (425s left) gem_exec_flush (basic-wb-rw-before-default)
[595.048309] Starting subtest: basic-wb-rw-before-default
[600.496324] Subtest basic-wb-rw-before-default: SUCCESS (5.448s)
[600.695419] [085/130] (419s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[600.805777] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[601.563293] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.759s)
[601.780407] [086/130] (418s left) i915_query (query-regions-unallocated)
[601.830950] Starting subtest: query-regions-unallocated
[601.833417] Subtest query-regions-unallocated: SUCCESS (0.000s)
[601.950105] [087/130] (418s left) gem_exec_flush (basic-batch-kernel-default-wb)
[602.012488] Starting subtest: basic-batch-kernel-default-wb
[609.003193] Subtest basic-batch-kernel-default-wb: SUCCESS (6.991s)
[609.217782] [088/130] (411s left) syncobj_wait (multi-wait-all-signaled)
[609.268730] Starting subtest: multi-wait-all-signaled
[609.271306] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[609.406086] [089/130] (411s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[609.501181] Starting subtest: fbc-dirtyfb-ioctl
[609.503093] Starting dynamic subtest: A-eDP-1
[612.097145] Dynamic subtest A-eDP-1: SUCCESS (2.594s)
[612.099442] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.596s)
[612.904811] [090/130] (407s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[617.893800] Starting subtest: psr2-overlay-plane-update-continuous-sf
[617.896070] Starting dynamic subtest: pipe-A-eDP-1
[619.654754] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.758s)
[619.664511] Starting dynamic subtest: pipe-B-eDP-1
[621.362665] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.708s)
[621.365068] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (3.470s)
[622.200456] [091/130] (398s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[622.307516] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[622.311630] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[622.437410] [092/130] (398s left) kms_fbcon_fbt (fbc-suspend)
[622.509050] Starting subtest: fbc-suspend
[671.065308] Subtest fbc-suspend: SUCCESS (18.702s)
[672.231312] [093/130] (348s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[672.350360] Starting subtest: y-tiled-8bpp-rotate-270
[672.354860] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[672.474698] [094/130] (348s left) gem_exec_schedule (u-submit-early-slice)
[672.548695] Starting subtest: u-submit-early-slice
[672.550586] Starting dynamic subtest: rcs0
[672.583125] Dynamic subtest rcs0: SUCCESS (0.041s)
[672.592963] Starting dynamic subtest: bcs0
[672.621301] Dynamic subtest bcs0: SUCCESS (0.037s)
[672.631201] Starting dynamic subtest: vcs0
[672.662186] Dynamic subtest vcs0: SUCCESS (0.039s)
[672.676849] Starting dynamic subtest: vcs1
[672.708249] Dynamic subtest vcs1: SUCCESS (0.044s)
[672.718910] Starting dynamic subtest: vecs0
[672.744904] Dynamic subtest vecs0: SUCCESS (0.035s)
[672.757232] Starting dynamic subtest: ccs0
[672.776318] Dynamic subtest ccs0: SUCCESS (0.030s)
[672.778804] Subtest u-submit-early-slice: SUCCESS (0.232s)
[674.492570] [095/130] (346s left) vgem_basic (debugfs)
[674.533191] Starting subtest: debugfs
[674.535671] Subtest debugfs: SUCCESS (0.000s)
[674.615585] [096/130] (346s left) gen7_exec_parse (batch-without-end)
[674.683598] Subtest batch-without-end: SKIP (0.000s)
[674.767321] [097/130] (346s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[674.868633] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[696.838309] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (21.872s)
[697.001355] [098/130] (323s left) dmabuf_sync_file (export-basic)
[697.047367] Starting subtest: export-basic
[697.049449] Subtest export-basic: SUCCESS (0.001s)
[697.118800] [099/130] (323s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[697.205482] Starting subtest: random-ccs-data-y-tiled-ccs
[697.207472] Starting dynamic subtest: pipe-A-eDP-1
[697.228658] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[697.240004] Starting dynamic subtest: pipe-B-eDP-1
[697.258894] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[697.267528] Starting dynamic subtest: pipe-C-eDP-1
[697.286871] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[697.295409] Starting dynamic subtest: pipe-D-eDP-1
[697.315437] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[697.319011] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[699.391952] [100/130] (321s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[699.518690] Starting subtest: cursorB-vs-flipB-atomic
[699.523657] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[699.687322] [101/130] (321s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[699.749379] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[699.823273] [102/130] (321s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[699.948631] Starting subtest: protected-encrypted-src-copy-not-readible
[700.059184] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.113s)
[700.221938] [103/130] (320s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[700.323529] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[700.325799] Starting dynamic subtest: pipe-A-default-mode
[701.432231] Dynamic subtest pipe-A-default-mode: SKIP (1.106s)
[701.437370] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.108s)
[701.608935] [104/130] (319s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[701.717106] Starting subtest: fbcpsr-tiling-y
[701.721218] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[701.841708] [105/130] (319s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[702.380803] Starting subtest: syncobj-timeline-invalid-flags
[702.383592] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[703.535367] [106/130] (317s left) gem_basic (create-close)
[703.586681] Starting subtest: create-close
[703.591075] Subtest create-close: SUCCESS (0.000s)
[703.702541] [107/130] (317s left) api_intel_allocator (reloc-allocator)
[703.761464] Starting subtest: reloc-allocator
[703.763308] Starting dynamic subtest: basic
[703.765604] Dynamic subtest basic: SUCCESS (0.003s)
[703.775188] Starting dynamic subtest: parallel-one
[703.777115] Dynamic subtest parallel-one: SUCCESS (0.008s)
[703.786941] Starting dynamic subtest: print
[703.789410] Dynamic subtest print: SUCCESS (0.000s)
[703.798437] Starting dynamic subtest: reuse
[703.800287] Dynamic subtest reuse: SUCCESS (0.000s)
[703.809129] Starting dynamic subtest: fork-reopen-allocator
[703.810989] Dynamic subtest fork-reopen-allocator: SUCCESS (0.013s)
[703.812888] Subtest reloc-allocator: SUCCESS (0.027s)
[703.936840] [108/130] (317s left) kms_psr (pr-primary-blt)
[704.020409] Starting subtest: pr-primary-blt
[704.024504] Subtest pr-primary-blt: SKIP (0.000s)
[704.102971] [109/130] (317s left) gem_ctx_bad_destroy (double-destroy)
[704.161578] Starting subtest: double-destroy
[704.163995] Subtest double-destroy: SUCCESS (0.000s)
[704.252270] [110/130] (317s left) core_getclient
[704.426449] [111/130] (316s left) syncobj_wait (wait-all-delayed-signal)
[704.480286] Starting subtest: wait-all-delayed-signal
[704.584734] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[704.670578] [112/130] (316s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[704.769230] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[704.773692] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[704.882746] [113/130] (316s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[704.979727] Starting subtest: yf-tiled-16bpp-rotate-0
[704.983774] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[705.086277] [114/130] (316s left) kms_async_flips (test-time-stamp)
[705.183279] Starting subtest: test-time-stamp
[705.567274] Starting dynamic subtest: pipe-A-eDP-1
[706.316046] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.746s)
[706.698076] Starting dynamic subtest: pipe-B-eDP-1
[707.452235] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.758s)
[707.836229] Starting dynamic subtest: pipe-C-eDP-1
[708.595067] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.766s)
[708.957648] Starting dynamic subtest: pipe-D-eDP-1
[709.731147] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.779s)
[709.733014] Subtest test-time-stamp: SUCCESS (4.546s)
[710.899680] [115/130] (310s left) kms_invalid_mode (bad-vsync-end)
[710.985544] Starting subtest: bad-vsync-end
[710.987750] Starting dynamic subtest: pipe-A-eDP-1
[711.278407] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.292s)
[711.289040] Starting dynamic subtest: pipe-B-eDP-1
[711.291060] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[711.300458] Starting dynamic subtest: pipe-C-eDP-1
[711.302894] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[711.311835] Starting dynamic subtest: pipe-D-eDP-1
[711.313759] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[711.315837] Subtest bad-vsync-end: SUCCESS (0.297s)
[712.123060] [116/130] (309s left) gem_exec_suspend (basic)
[712.185813] Starting subtest: basic
[712.188210] Starting dynamic subtest: smem
[713.408007] Dynamic subtest smem: SUCCESS (1.219s)
[713.410553] Subtest basic: SUCCESS (1.221s)
[713.613438] [117/130] (308s left) api_intel_bb (blit-noreloc-purge-cache)
[713.669692] Starting subtest: blit-noreloc-purge-cache
[713.677743] Subtest blit-noreloc-purge-cache: SUCCESS (0.011s)
[713.805263] [118/130] (307s left) kms_plane (pixel-format-source-clamping)
[713.910441] Starting subtest: pixel-format-source-clamping
[713.937809] Starting dynamic subtest: pipe-A-plane-0
[718.727993] Dynamic subtest pipe-A-plane-0: SUCCESS (4.789s)
[718.744123] Starting dynamic subtest: pipe-A-plane-3
[721.017120] Dynamic subtest pipe-A-plane-3: SUCCESS (2.288s)
[721.026922] Starting dynamic subtest: pipe-A-plane-5
[721.028450] Dynamic subtest pipe-A-plane-5: SUCCESS (0.000s)
[722.105792] Starting dynamic subtest: pipe-B-plane-0
[727.177834] Dynamic subtest pipe-B-plane-0: SUCCESS (5.078s)
[727.187582] Starting dynamic subtest: pipe-B-plane-3
[729.480043] Dynamic subtest pipe-B-plane-3: SUCCESS (2.300s)
[729.489507] Starting dynamic subtest: pipe-B-plane-5
[729.491239] Dynamic subtest pipe-B-plane-5: SUCCESS (0.000s)
[729.802138] Subtest pixel-format-source-clamping: SUCCESS (15.886s)
[730.694789] [119/130] (291s left) i915_query (query-topology-kernel-writes)
[730.752862] Starting subtest: query-topology-kernel-writes
[730.755302] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[730.857324] [120/130] (290s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[731.242911] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[732.087859] [121/130] (289s left) gem_exec_reloc (basic-cpu-active)
[732.151495] Subtest basic-cpu-active: SKIP (0.000s)
[732.232703] [122/130] (289s left) gem_exec_fence (concurrent)
[732.405468] Starting subtest: concurrent
[732.409907] Subtest concurrent: SKIP (0.000s)
[734.510911] [123/130] (287s left) perf (short-reads)
[734.649633] Starting subtest: short-reads
[734.666005] Subtest short-reads: SUCCESS (0.017s)
[734.706290] [124/130] (287s left) dumb_buffer (create-clear)
[734.767526] Starting subtest: create-clear
[769.085396] Subtest create-clear: SUCCESS (34.304s)
[769.222377] [125/130] (252s left) kms_psr2_su (page_flip-p010)
[769.278514] Subtest page_flip-P010: SKIP (0.000s)
[769.343012] [126/130] (252s left) i915_pm_rc6_residency (rc6-idle)
[769.397400] Starting subtest: rc6-idle
[769.427942] Starting dynamic subtest: gt0-rcs0
[793.777327] Dynamic subtest gt0-rcs0: SUCCESS (24.353s)
[793.787651] Starting dynamic subtest: gt0-bcs0
[818.168730] Dynamic subtest gt0-bcs0: SUCCESS (24.387s)
[818.178614] Starting dynamic subtest: gt0-ccs0
[842.380630] Dynamic subtest gt0-ccs0: SUCCESS (24.205s)
[842.392419] Starting dynamic subtest: gt1-vcs0
[848.555615] Dynamic subtest gt1-vcs0: SUCCESS (6.170s)
[848.567338] Starting dynamic subtest: gt1-vecs0
[854.729427] Dynamic subtest gt1-vecs0: SUCCESS (6.170s)
[854.732263] Subtest rc6-idle: SUCCESS (85.325s)
[854.827037] [127/130] (167s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[854.927297] Starting subtest: plane-scaler-unity-scaling-with-rotation
[854.929566] Starting dynamic subtest: pipe-A
[855.155658] Dynamic subtest pipe-A: SUCCESS (0.230s)
[855.165570] Starting dynamic subtest: pipe-B
[856.493790] Dynamic subtest pipe-B: SUCCESS (1.337s)
[856.505101] Starting dynamic subtest: pipe-C
[857.827333] Dynamic subtest pipe-C: SUCCESS (1.333s)
[857.837985] Starting dynamic subtest: pipe-D
[859.162448] Dynamic subtest pipe-D: SUCCESS (1.334s)
[859.164811] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.236s)
[860.359491] [128/130] (161s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[860.455032] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[860.459109] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[860.566451] [129/130] (161s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[860.679893] Starting subtest: cursor-vs-flip-legacy
[889.928474] Subtest cursor-vs-flip-legacy: SUCCESS (29.240s)
[890.815302] [130/130] (131s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[890.872117] Starting subtest: multi-wait-available-submitted-signaled
[890.874276] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[890.993182] 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.