[54.173298] Initializing watchdogs
[54.173347]   /dev/watchdog0
[54.187303] [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]
[54.191701] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.193816] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.197149] [001/130] (960s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[54.314537] Starting subtest: plane-upscale-20x20-with-rotation
[54.316710] Starting dynamic subtest: pipe-A
[54.542763] Dynamic subtest pipe-A: SUCCESS (0.236s)
[54.551060] Starting dynamic subtest: pipe-B
[55.811422] Dynamic subtest pipe-B: SUCCESS (1.296s)
[55.819713] Starting dynamic subtest: pipe-C
[57.111868] Dynamic subtest pipe-C: SUCCESS (1.324s)
[57.120287] Starting dynamic subtest: pipe-D
[58.424475] Dynamic subtest pipe-D: SUCCESS (1.332s)
[58.426788] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.189s)
[59.601070] [002/130] (955s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[59.710240] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[59.714180] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[59.821361] [003/130] (954s left) kms_big_fb (linear-8bpp-rotate-180)
[59.928243] Starting subtest: linear-8bpp-rotate-180
[61.119406] Subtest linear-8bpp-rotate-180: SUCCESS (1.203s)
[61.277811] [004/130] (953s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[61.373101] Starting subtest: blocking-absolute-wf_vblank-interruptible
[61.375136] Starting dynamic subtest: A-eDP1
[70.098310] Dynamic subtest A-eDP1: SUCCESS (8.773s)
[70.107412] Starting dynamic subtest: B-eDP1
[78.817301] Dynamic subtest B-eDP1: SUCCESS (8.733s)
[78.827517] Starting dynamic subtest: C-eDP1
[87.541126] Dynamic subtest C-eDP1: SUCCESS (8.724s)
[87.550756] Starting dynamic subtest: D-eDP1
[96.280793] Dynamic subtest D-eDP1: SUCCESS (8.738s)
[96.283402] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.973s)
[97.502749] [005/130] (917s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[97.606575] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[97.610779] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[97.751529] [006/130] (917s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[97.850174] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[104.447379] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (6.598s)
[104.592463] [007/130] (910s left) gem_ctx_exec (basic-norecovery)
[104.651987] Starting subtest: basic-norecovery
[104.935114] Subtest basic-norecovery: SUCCESS (0.282s)
[105.087701] [008/130] (909s left) kms_psr (psr-primary-page-flip)
[105.184361] Starting subtest: psr-primary-page-flip
[105.186816] Starting dynamic subtest: eDP-1
[106.936250] Dynamic subtest eDP-1: SUCCESS (1.753s)
[106.938663] Subtest psr-primary-page-flip: SUCCESS (1.754s)
[107.813759] [009/130] (907s left) core_getstats
[107.984117] [010/130] (907s left) i915_hangman (detector)
[108.051183] Starting subtest: detector
[108.053461] Starting dynamic subtest: rcs0
[119.860224] Dynamic subtest rcs0: SUCCESS (11.807s)
[119.870529] Starting dynamic subtest: bcs0
[131.891520] Dynamic subtest bcs0: SUCCESS (12.030s)
[131.901984] Starting dynamic subtest: vcs0
[143.795393] Dynamic subtest vcs0: SUCCESS (11.903s)
[143.805240] Starting dynamic subtest: vcs1
[153.843522] Dynamic subtest vcs1: SUCCESS (10.047s)
[153.854089] Starting dynamic subtest: vecs0
[165.811472] Dynamic subtest vecs0: SUCCESS (11.967s)
[165.822422] Starting dynamic subtest: ccs0
[176.885428] Dynamic subtest ccs0: SUCCESS (11.073s)
[176.887983] Subtest detector: SUCCESS (68.830s)
[176.974718] [011/130] (838s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[177.077423] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[177.079843] Starting dynamic subtest: pipe-A-eDP-1
[179.129066] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.050s)
[179.137283] Starting dynamic subtest: pipe-B-eDP-1
[181.215234] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.084s)
[181.225044] Starting dynamic subtest: pipe-C-eDP-1
[183.226039] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.010s)
[183.235011] Starting dynamic subtest: pipe-D-eDP-1
[185.262599] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.038s)
[185.264955] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.185s)
[186.550958] [012/130] (828s left) gem_lmem_swapping (parallel-random-engines)
[186.594070] Subtest parallel-random-engines: SKIP (0.000s)
[186.631667] [013/130] (828s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[186.735056] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[186.739472] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[186.853734] [014/130] (828s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[186.965267] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[186.969742] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[187.070290] [015/130] (828s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[187.170996] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[187.175340] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[187.310222] [016/130] (827s left) gem_ctx_persistence (legacy-engines-hostile)
[187.368093] Starting subtest: legacy-engines-hostile
[187.370388] Starting dynamic subtest: render
[187.512332] Dynamic subtest render: SUCCESS (0.144s)
[187.522653] Starting dynamic subtest: bsd1
[187.634617] Dynamic subtest bsd1: SUCCESS (0.120s)
[187.645139] Starting dynamic subtest: bsd2
[187.753702] Dynamic subtest bsd2: SUCCESS (0.117s)
[187.763986] Starting dynamic subtest: blt
[187.868663] Dynamic subtest blt: SUCCESS (0.113s)
[187.879443] Starting dynamic subtest: vebox
[187.982939] Dynamic subtest vebox: SUCCESS (0.112s)
[187.985490] Subtest legacy-engines-hostile: SUCCESS (0.615s)
[188.067472] [017/130] (827s left) prime_vgem (shrink)
[188.186951] Starting subtest: shrink
[188.204116] Subtest shrink: SUCCESS (0.015s)
[188.312277] [FACT prime_vgem (shrink)] new: kernel.kmod_is_loaded.vgem: true
[188.315175] [018/130] (827s left) gem_exec_schedule (preempt-queue-chain)
[188.381940] Subtest preempt-queue-chain: SKIP (0.000s)
[188.680565] [019/130] (826s left) kms_flip (flip-vs-panning-vs-hang)
[188.779489] Starting subtest: flip-vs-panning-vs-hang
[188.781717] Starting dynamic subtest: A-eDP1
[228.413805] Dynamic subtest A-eDP1: SUCCESS (39.636s)
[228.424364] Starting dynamic subtest: D-eDP1
[278.397809] Dynamic subtest D-eDP1: SUCCESS (49.981s)
[278.400320] Subtest flip-vs-panning-vs-hang: SUCCESS (89.621s)
[279.623487] [020/130] (735s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[279.853579] Starting subtest: cursor-rapid-movement-256x256
[279.858680] Starting dynamic subtest: pipe-A-eDP-1
[280.386128] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.529s)
[280.411913] Starting dynamic subtest: pipe-D-eDP-1
[281.619806] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.211s)
[281.621958] Subtest cursor-rapid-movement-256x256: SUCCESS (1.763s)
[282.486708] [021/130] (732s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[282.585766] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[282.589659] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[282.702808] [022/130] (732s left) gem_exec_balancer (sliced)
[282.771895] Starting subtest: sliced
[282.776498] Subtest sliced: SKIP (0.000s)
[283.091910] [023/130] (732s left) kms_psr (fbc-psr-suspend)
[283.184758] Starting subtest: fbc-psr-suspend
[283.186633] Starting dynamic subtest: eDP-1
[283.190677] Dynamic subtest eDP-1: SKIP (0.000s)
[283.194636] Subtest fbc-psr-suspend: SKIP (0.001s)
[283.266141] [024/130] (732s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[283.370607] Starting subtest: fbcpsr-stridechange
[289.491298] Subtest fbcpsr-stridechange: SUCCESS (6.123s)
[289.650078] [025/130] (725s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[289.746400] Starting subtest: 4-tiled-32bpp-rotate-180
[291.124697] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.378s)
[291.280355] [026/130] (724s left) gem_mmap_gtt (basic-read-write-distinct)
[291.347547] Subtest basic-read-write-distinct: SKIP (0.000s)
[291.412451] [027/130] (724s left) perf_pmu (busy-accuracy-2)
[291.449812] Starting subtest: busy-accuracy-2
[291.451425] Starting dynamic subtest: rcs0
[294.489602] Dynamic subtest rcs0: SUCCESS (3.038s)
[294.497685] Starting dynamic subtest: bcs0
[297.546451] Dynamic subtest bcs0: SUCCESS (3.055s)
[297.554845] Starting dynamic subtest: vcs0
[300.592173] Dynamic subtest vcs0: SUCCESS (3.044s)
[300.602586] Starting dynamic subtest: vcs1
[303.639387] Dynamic subtest vcs1: SUCCESS (3.045s)
[303.650081] Starting dynamic subtest: vecs0
[306.686838] Dynamic subtest vecs0: SUCCESS (3.046s)
[306.697179] Starting dynamic subtest: ccs0
[309.747508] Dynamic subtest ccs0: SUCCESS (3.059s)
[309.749694] Subtest busy-accuracy-2: SUCCESS (18.297s)
[309.886851] [028/130] (705s left) gem_create (create-ext-set-pat)
[310.050878] Starting subtest: create-ext-set-pat
[310.053205] Subtest create-ext-set-pat: SUCCESS (0.001s)
[310.175245] [029/130] (705s left) gem_exec_reloc (basic-wc-cpu-active)
[310.241964] Subtest basic-wc-cpu-active: SKIP (0.000s)
[310.341923] [030/130] (705s left) gem_ctx_persistence (userptr)
[310.410319] Starting subtest: userptr
[310.665934] Subtest userptr: SUCCESS (0.255s)
[310.765345] [031/130] (705s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[310.825430] Starting subtest: multi-wait-all-for-submit-unsubmitted
[310.928231] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.104s)
[311.036984] [032/130] (704s left) gem_busy (close-race)
[311.094631] Starting subtest: close-race
[336.852649] Subtest close-race: SUCCESS (25.754s)
[337.246942] [033/130] (678s left) kms_addfb_basic (small-bo)
[337.385092] Starting subtest: small-bo
[337.387014] Subtest small-bo: SUCCESS (0.000s)
[337.504279] [034/130] (678s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[337.606025] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[342.079641] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.477s)
[343.212886] [035/130] (672s left) syncobj_timeline (invalid-transfer-non-existent-point)
[343.277818] Starting subtest: invalid-transfer-non-existent-point
[343.280169] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[343.410567] [036/130] (672s left) kms_lease (lease-revoke)
[343.513146] Starting subtest: lease-revoke
[343.515484] Starting dynamic subtest: pipe-A-eDP-1
[343.547527] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.036s)
[343.559707] Starting dynamic subtest: pipe-B-eDP-1
[343.579195] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.028s)
[343.588744] Starting dynamic subtest: pipe-C-eDP-1
[343.607161] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.027s)
[343.617551] Starting dynamic subtest: pipe-D-eDP-1
[343.631801] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.026s)
[343.634306] Subtest lease-revoke: SUCCESS (0.121s)
[343.790857] [037/130] (672s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[343.903258] Starting subtest: psr-rgb101010-draw-pwrite
[351.481195] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.580s)
[351.606137] [038/130] (664s left) gem_mmap_offset (partial-remap)
[351.667646] Starting subtest: partial-remap
[351.669974] Starting dynamic subtest: smem0
[351.679299] Dynamic subtest smem0: SUCCESS (0.003s)
[351.681281] Subtest partial-remap: SUCCESS (0.004s)
[351.813487] [039/130] (664s left) gem_exec_whisper (basic-fds-priority)
[351.881515] Starting subtest: basic-fds-priority
[375.747364] Subtest basic-fds-priority: SUCCESS (23.857s)
[375.938491] [040/130] (640s left) gem_softpin (allocator-nopin)
[376.007331] Starting subtest: allocator-nopin
[376.111860] Subtest allocator-nopin: SUCCESS (0.103s)
[376.270250] [041/130] (639s left) gem_exec_reloc (basic-concurrent16)
[376.339420] Subtest basic-concurrent16: SKIP (0.000s)
[376.429414] [042/130] (639s left) kms_vrr (negative-basic)
[376.528947] Starting subtest: negative-basic
[376.535202] Starting dynamic subtest: pipe-A-eDP-1
[381.952471] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.416s)
[382.237362] Subtest negative-basic: SUCCESS (5.710s)
[383.061040] [043/130] (633s left) syncobj_timeline (single-wait-all-signaled)
[383.117140] Starting subtest: single-wait-all-signaled
[383.119389] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[383.230489] [044/130] (633s left) kms_psr (fbc-psr2-primary-page-flip)
[383.322837] Starting subtest: fbc-psr2-primary-page-flip
[383.325185] Starting dynamic subtest: eDP-1
[383.329681] Dynamic subtest eDP-1: SKIP (0.000s)
[383.334311] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[383.402843] [045/130] (633s left) kms_feature_discovery (display-4x)
[383.498422] Starting subtest: display-4x
[383.502597] Subtest display-4x: SKIP (0.000s)
[383.594272] [046/130] (632s left) kms_pm_rpm (universal-planes)
[385.144753] Starting subtest: universal-planes
[385.146841] Starting dynamic subtest: plane-33
[393.560945] Dynamic subtest plane-33: SUCCESS (8.418s)
[393.571595] Starting dynamic subtest: plane-43
[401.839090] Dynamic subtest plane-43: SUCCESS (8.275s)
[401.850970] Starting dynamic subtest: plane-53
[410.227545] Dynamic subtest plane-53: SUCCESS (8.385s)
[410.239561] Starting dynamic subtest: plane-63
[418.540603] Dynamic subtest plane-63: SUCCESS (8.312s)
[418.552522] Starting dynamic subtest: plane-73
[426.806549] Dynamic subtest plane-73: SUCCESS (8.264s)
[426.817865] Starting dynamic subtest: plane-83
[435.059949] Dynamic subtest plane-83: SUCCESS (8.251s)
[435.062865] Subtest universal-planes: SUCCESS (49.918s)
[435.453333] [047/130] (581s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[435.566074] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[436.545149] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (0.979s)
[436.725139] [048/130] (579s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[436.789984] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[436.881668] [049/130] (579s left) kms_scaling_modes (scaling-mode-full-aspect)
[436.977954] Starting subtest: scaling-mode-full-aspect
[436.979986] Starting dynamic subtest: pipe-A-eDP-1
[437.070305] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.094s)
[437.082359] Starting dynamic subtest: pipe-B-eDP-1
[438.236754] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.163s)
[438.246871] Starting dynamic subtest: pipe-C-eDP-1
[439.355455] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.118s)
[439.365436] Starting dynamic subtest: pipe-D-eDP-1
[440.488781] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.132s)
[440.491110] Subtest scaling-mode-full-aspect: SUCCESS (3.513s)
[441.683381] [050/130] (574s left) kms_color (degamma)
[441.788090] Starting subtest: degamma
[441.790280] Starting dynamic subtest: pipe-A-eDP-1
[442.503198] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.716s)
[442.514640] Starting dynamic subtest: pipe-B-eDP-1
[443.853866] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.349s)
[443.864569] Starting dynamic subtest: pipe-C-eDP-1
[445.177458] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.323s)
[445.190398] Starting dynamic subtest: pipe-D-eDP-1
[446.503848] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.325s)
[446.506276] Subtest degamma: SUCCESS (4.715s)
[447.331394] [051/130] (569s left) kms_cursor_crc (cursor-offscreen-256x85)
[447.582757] Starting subtest: cursor-offscreen-256x85
[447.626426] Subtest cursor-offscreen-256x85: SKIP (0.040s)
[447.823194] [052/130] (568s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[447.939096] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[447.943738] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[448.109343] [053/130] (568s left) perf_pmu (busy-start)
[448.148290] Starting subtest: busy-start
[448.150575] Starting dynamic subtest: rcs0
[450.698293] Dynamic subtest rcs0: SUCCESS (2.550s)
[450.710610] Starting dynamic subtest: bcs0
[453.248892] Dynamic subtest bcs0: SUCCESS (2.548s)
[453.259237] Starting dynamic subtest: vcs0
[456.916632] Dynamic subtest vcs0: SUCCESS (3.664s)
[456.928732] Starting dynamic subtest: vcs1
[460.885063] Dynamic subtest vcs1: SUCCESS (3.966s)
[460.896081] Starting dynamic subtest: vecs0
[464.916264] Dynamic subtest vecs0: SUCCESS (4.029s)
[464.926863] Starting dynamic subtest: ccs0
[467.464045] Dynamic subtest ccs0: SUCCESS (2.547s)
[467.466389] Subtest busy-start: SUCCESS (19.317s)
[467.602182] [054/130] (549s left) gem_spin_batch (spin-each)
[467.665754] Starting subtest: spin-each
[471.027278] Subtest spin-each: SUCCESS (3.360s)
[472.967925] [055/130] (543s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[473.072465] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[473.076513] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[473.184527] [056/130] (543s left) kms_chamelium_frames (hdmi-crc-multiple)
[473.570848] Subtest hdmi-crc-multiple: SKIP (0.000s)
[474.426261] [057/130] (542s left) gen9_exec_parse (bb-start-out)
[474.485822] Subtest bb-start-out: SKIP (0.000s)
[474.574902] [058/130] (542s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[474.693640] Starting subtest: cursorB-vs-flipA-atomic-transitions
[474.698064] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[474.851011] [059/130] (542s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[474.954772] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[474.958885] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[475.065277] [060/130] (541s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[475.165378] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[475.167818] Starting dynamic subtest: pipe-A-eDP-1
[475.191131] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[475.203079] Starting dynamic subtest: pipe-B-eDP-1
[475.224926] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[475.234130] Starting dynamic subtest: pipe-C-eDP-1
[475.256480] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[475.265672] Starting dynamic subtest: pipe-D-eDP-1
[475.287862] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[475.292049] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[477.338216] [061/130] (539s left) kms_flip (absolute-wf_vblank)
[477.433321] Starting subtest: absolute-wf_vblank
[477.435209] Starting dynamic subtest: A-eDP1
[486.185684] Dynamic subtest A-eDP1: SUCCESS (8.753s)
[486.196704] Starting dynamic subtest: B-eDP1
[494.917083] Dynamic subtest B-eDP1: SUCCESS (8.730s)
[494.927868] Starting dynamic subtest: C-eDP1
[503.639262] Dynamic subtest C-eDP1: SUCCESS (8.720s)
[503.651672] Starting dynamic subtest: D-eDP1
[512.374526] Dynamic subtest D-eDP1: SUCCESS (8.733s)
[512.376926] Subtest absolute-wf_vblank: SUCCESS (34.945s)
[513.603114] [062/130] (503s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[513.654179] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[513.761332] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s)
[513.848159] [063/130] (503s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[513.904681] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[513.907085] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[514.034135] [064/130] (503s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[514.132852] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[514.144359] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[514.295030] [065/130] (502s left) kms_cursor_crc (cursor-onscreen-128x42)
[514.515371] Starting subtest: cursor-onscreen-128x42
[514.560488] Subtest cursor-onscreen-128x42: SKIP (0.040s)
[514.817782] [066/130] (502s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[514.910723] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[514.912640] Starting dynamic subtest: pipe-A-eDP-1
[514.961283] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[514.971663] Starting dynamic subtest: pipe-B-eDP-1
[515.018233] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[515.028070] Starting dynamic subtest: pipe-C-eDP-1
[515.074743] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[515.084354] Starting dynamic subtest: pipe-D-eDP-1
[515.130841] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[515.134410] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[517.069133] [067/130] (500s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[517.166037] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[517.168317] Starting dynamic subtest: pipe-A-eDP-1
[517.190444] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[517.200191] Starting dynamic subtest: pipe-B-eDP-1
[517.221847] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[517.230809] Starting dynamic subtest: pipe-C-eDP-1
[517.251580] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[517.260055] Starting dynamic subtest: pipe-D-eDP-1
[517.281062] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[517.285095] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[519.355674] [068/130] (497s left) kms_vblank (query-idle)
[519.460271] Starting subtest: query-idle
[519.462082] Starting dynamic subtest: pipe-A-eDP-1
[521.197928] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.738s)
[521.213166] Starting dynamic subtest: pipe-D-eDP-1
[523.665630] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.465s)
[523.667956] Subtest query-idle: SUCCESS (4.206s)
[524.508204] [069/130] (492s left) gem_exec_reloc (basic-cpu-read-active)
[524.563912] Subtest basic-cpu-read-active: SKIP (0.000s)
[524.639379] [070/130] (492s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[524.737384] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[524.741959] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[524.875345] [071/130] (492s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[529.862265] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[529.864737] Starting dynamic subtest: pipe-A-eDP-1
[530.682060] Dynamic subtest pipe-A-eDP-1: SKIP (0.814s)
[530.692073] Starting dynamic subtest: pipe-B-eDP-1
[531.820687] Dynamic subtest pipe-B-eDP-1: SKIP (1.137s)
[531.826279] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.955s)
[533.020948] [072/130] (484s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[533.115295] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[539.310990] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.199s)
[540.457301] [073/130] (477s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[540.563936] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[540.568592] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[540.709987] [074/130] (476s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[540.809900] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[548.203332] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.397s)
[548.383347] [075/130] (469s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[548.494059] Starting subtest: fbc-modesetfrombusy
[552.209941] Subtest fbc-modesetfrombusy: SUCCESS (3.727s)
[553.407147] [076/130] (464s left) gem_tiled_blits (basic)
[553.470437] Subtest basic: SKIP (0.000s)
[553.548217] [077/130] (464s left) gem_eio (context-create)
[553.764056] Starting subtest: context-create
[554.073408] Subtest context-create: SUCCESS (0.307s)
[554.219148] [078/130] (463s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[554.283175] Starting subtest: x-tiled-to-vebox-y-tiled
[554.287815] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[554.480142] [079/130] (463s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[554.593661] Starting subtest: basic-busy-flip-before-cursor-varying-size
[554.598274] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[554.765563] [080/130] (463s left) kms_plane_alpha_blend (alpha-7efc)
[554.869301] Starting subtest: alpha-7efc
[555.155803] Starting dynamic subtest: pipe-A-eDP-1
[555.741069] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.585s)
[555.768422] Starting dynamic subtest: pipe-D-eDP-1
[557.054938] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.295s)
[557.057210] Subtest alpha-7efc: SUCCESS (2.185s)
[557.954017] [081/130] (459s left) i915_selftest (perf)
[561.468087] Starting subtest: perf
[561.470681] Starting dynamic subtest: engine_cs
[565.925467] Dynamic subtest engine_cs: SUCCESS (4.456s)
[565.934483] Starting dynamic subtest: request
[573.293510] Dynamic subtest request: SUCCESS (7.366s)
[573.302775] Starting dynamic subtest: migrate
[577.791538] Dynamic subtest migrate: SUCCESS (4.496s)
[577.801127] Starting dynamic subtest: region
[583.695370] Dynamic subtest region: SUCCESS (5.901s)
[583.697913] Subtest perf: SUCCESS (22.226s)
[583.726390] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[583.727131] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[583.730262] [082/130] (434s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[585.260541] Starting subtest: short-flip-before-cursor-toggle
[586.198699] Subtest short-flip-before-cursor-toggle: SUCCESS (0.937s)
[587.025700] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[587.027629] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: kernel.kmod_is_loaded.i915: true
[587.031789] [083/130] (430s left) kms_pm_rpm (modeset-lpsp-stress)
[589.048463] Starting subtest: modeset-lpsp-stress
[605.202973] Subtest modeset-lpsp-stress: SUCCESS (16.154s)
[605.303052] [084/130] (412s left) gem_exec_flush (basic-wb-rw-before-default)
[605.374036] Starting subtest: basic-wb-rw-before-default
[610.824553] Subtest basic-wb-rw-before-default: SUCCESS (5.451s)
[611.017140] [085/130] (407s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[611.126284] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[611.894420] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.770s)
[612.107156] [086/130] (405s left) i915_query (query-regions-unallocated)
[612.164766] Starting subtest: query-regions-unallocated
[612.167484] Subtest query-regions-unallocated: SUCCESS (0.000s)
[612.297548] [087/130] (405s left) gem_exec_flush (basic-batch-kernel-default-wb)
[612.348374] Starting subtest: basic-batch-kernel-default-wb
[619.220332] Subtest basic-batch-kernel-default-wb: SUCCESS (6.873s)
[619.419867] [088/130] (398s left) syncobj_wait (multi-wait-all-signaled)
[619.485504] Starting subtest: multi-wait-all-signaled
[619.487775] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[619.627307] [089/130] (398s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[619.724950] Starting subtest: fbc-dirtyfb-ioctl
[619.727260] Starting dynamic subtest: A-eDP-1
[622.323921] Dynamic subtest A-eDP-1: SUCCESS (2.598s)
[622.331606] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.600s)
[623.127799] [090/130] (395s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[628.082642] Starting subtest: psr2-overlay-plane-update-continuous-sf
[628.085058] Starting dynamic subtest: pipe-A-eDP-1
[629.862186] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.776s)
[629.871536] Starting dynamic subtest: pipe-B-eDP-1
[631.573774] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.710s)
[631.576178] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (3.490s)
[632.420572] [091/130] (385s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[632.516868] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[632.521246] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[632.639266] [092/130] (385s left) kms_fbcon_fbt (fbc-suspend)
[632.698023] Starting subtest: fbc-suspend
[682.394483] Subtest fbc-suspend: SUCCESS (19.217s)
[683.497421] [093/130] (334s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[683.612635] Starting subtest: y-tiled-8bpp-rotate-270
[683.617012] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[683.747410] [094/130] (334s left) gem_exec_schedule (u-submit-early-slice)
[683.816819] Starting subtest: u-submit-early-slice
[683.818725] Starting dynamic subtest: rcs0
[683.851996] Dynamic subtest rcs0: SUCCESS (0.043s)
[683.863676] Starting dynamic subtest: bcs0
[683.887375] Dynamic subtest bcs0: SUCCESS (0.036s)
[683.896882] Starting dynamic subtest: vcs0
[683.925377] Dynamic subtest vcs0: SUCCESS (0.038s)
[683.936216] Starting dynamic subtest: vcs1
[683.960444] Dynamic subtest vcs1: SUCCESS (0.035s)
[683.972316] Starting dynamic subtest: vecs0
[683.997718] Dynamic subtest vecs0: SUCCESS (0.038s)
[684.007464] Starting dynamic subtest: ccs0
[684.031019] Dynamic subtest ccs0: SUCCESS (0.033s)
[684.033237] Subtest u-submit-early-slice: SUCCESS (0.226s)
[685.903838] [095/130] (332s left) vgem_basic (debugfs)
[685.950491] Starting subtest: debugfs
[685.952394] Subtest debugfs: SUCCESS (0.000s)
[686.023210] [096/130] (332s left) gen7_exec_parse (batch-without-end)
[686.087936] Subtest batch-without-end: SKIP (0.000s)
[686.176128] [097/130] (332s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[686.272810] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[708.417633] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (22.246s)
[708.564015] [098/130] (310s left) dmabuf_sync_file (export-basic)
[708.618650] Starting subtest: export-basic
[708.620441] Subtest export-basic: SUCCESS (0.001s)
[708.694854] [099/130] (309s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[708.787425] Starting subtest: random-ccs-data-y-tiled-ccs
[708.789304] Starting dynamic subtest: pipe-A-eDP-1
[708.811039] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[708.821072] Starting dynamic subtest: pipe-B-eDP-1
[708.839841] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[708.849124] Starting dynamic subtest: pipe-C-eDP-1
[708.867964] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[708.876810] Starting dynamic subtest: pipe-D-eDP-1
[708.896613] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[708.899941] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[711.078001] [100/130] (307s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[711.193670] Starting subtest: cursorB-vs-flipB-atomic
[711.198177] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[711.361994] [101/130] (307s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[711.420553] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[711.478597] [102/130] (307s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[711.604057] Starting subtest: protected-encrypted-src-copy-not-readible
[711.716869] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.114s)
[711.860602] [103/130] (306s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[711.950368] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[711.952612] Starting dynamic subtest: pipe-A-default-mode
[713.126497] Dynamic subtest pipe-A-default-mode: SKIP (1.173s)
[713.130943] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.174s)
[713.316265] [104/130] (305s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[713.420736] Starting subtest: fbcpsr-tiling-y
[713.425141] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[713.545690] [105/130] (305s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[714.089966] Starting subtest: syncobj-timeline-invalid-flags
[714.092830] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[715.857501] [106/130] (303s left) gem_basic (create-close)
[715.918519] Starting subtest: create-close
[715.922922] Subtest create-close: SUCCESS (0.000s)
[716.034757] [107/130] (302s left) api_intel_allocator (reloc-allocator)
[716.097329] Starting subtest: reloc-allocator
[716.099542] Starting dynamic subtest: basic
[716.101484] Dynamic subtest basic: SUCCESS (0.005s)
[716.112877] Starting dynamic subtest: parallel-one
[716.114977] Dynamic subtest parallel-one: SUCCESS (0.007s)
[716.124669] Starting dynamic subtest: print
[716.128353] Dynamic subtest print: SUCCESS (0.000s)
[716.137622] Starting dynamic subtest: reuse
[716.139537] Dynamic subtest reuse: SUCCESS (0.000s)
[716.148655] Starting dynamic subtest: fork-reopen-allocator
[716.150535] Dynamic subtest fork-reopen-allocator: SUCCESS (0.015s)
[716.152467] Subtest reloc-allocator: SUCCESS (0.031s)
[716.292537] [108/130] (302s left) kms_psr (pr-primary-blt)
[716.389178] Starting subtest: pr-primary-blt
[716.393216] Subtest pr-primary-blt: SKIP (0.000s)
[716.473789] [109/130] (302s left) gem_ctx_bad_destroy (double-destroy)
[716.529899] Starting subtest: double-destroy
[716.532221] Subtest double-destroy: SUCCESS (0.000s)
[716.617496] [110/130] (302s left) core_getclient
[716.801438] [111/130] (302s left) syncobj_wait (wait-all-delayed-signal)
[716.862514] Starting subtest: wait-all-delayed-signal
[716.964851] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[717.052815] [112/130] (302s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[717.141586] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[717.145119] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[717.267562] [113/130] (301s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[717.368109] Starting subtest: yf-tiled-16bpp-rotate-0
[717.371797] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[717.479710] [114/130] (301s left) kms_async_flips (test-time-stamp)
[717.570654] Starting subtest: test-time-stamp
[717.945892] Starting dynamic subtest: pipe-A-eDP-1
[718.702587] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.755s)
[719.083394] Starting dynamic subtest: pipe-B-eDP-1
[719.843320] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.765s)
[720.218452] Starting dynamic subtest: pipe-C-eDP-1
[720.978829] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.768s)
[721.344059] Starting dynamic subtest: pipe-D-eDP-1
[722.115299] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.776s)
[722.117674] Subtest test-time-stamp: SUCCESS (4.542s)
[723.290583] [115/130] (295s left) kms_invalid_mode (bad-vsync-end)
[723.395447] Starting subtest: bad-vsync-end
[723.397575] Starting dynamic subtest: pipe-A-eDP-1
[723.678254] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[723.688083] Starting dynamic subtest: pipe-B-eDP-1
[723.690340] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[723.699917] Starting dynamic subtest: pipe-C-eDP-1
[723.701946] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[723.711318] Starting dynamic subtest: pipe-D-eDP-1
[723.713217] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[723.715147] Subtest bad-vsync-end: SUCCESS (0.288s)
[724.581488] [116/130] (294s left) gem_exec_suspend (basic)
[724.678177] Starting subtest: basic
[724.680643] Starting dynamic subtest: smem
[726.736860] Dynamic subtest smem: SUCCESS (2.056s)
[726.739832] Subtest basic: SUCCESS (2.058s)
[726.924251] [117/130] (292s left) api_intel_bb (blit-noreloc-purge-cache)
[726.971414] Starting subtest: blit-noreloc-purge-cache
[726.985774] Subtest blit-noreloc-purge-cache: SUCCESS (0.015s)
[727.145682] [118/130] (292s left) kms_plane (pixel-format-source-clamping)
[727.243083] Starting subtest: pixel-format-source-clamping
[727.270051] Starting dynamic subtest: pipe-A-plane-0
[732.092038] Dynamic subtest pipe-A-plane-0: SUCCESS (4.822s)
[732.103303] Starting dynamic subtest: pipe-A-plane-3
[734.403251] Dynamic subtest pipe-A-plane-3: SUCCESS (2.311s)
[734.412974] Starting dynamic subtest: pipe-A-plane-5
[734.414524] Dynamic subtest pipe-A-plane-5: SUCCESS (0.000s)
[735.487687] Starting dynamic subtest: pipe-B-plane-0
[740.255724] Dynamic subtest pipe-B-plane-0: SUCCESS (4.778s)
[740.266687] Starting dynamic subtest: pipe-B-plane-3
[742.611084] Dynamic subtest pipe-B-plane-3: SUCCESS (2.355s)
[742.621232] Starting dynamic subtest: pipe-B-plane-5
[742.623276] Dynamic subtest pipe-B-plane-5: SUCCESS (0.000s)
[742.922889] Subtest pixel-format-source-clamping: SUCCESS (15.678s)
[743.781251] [119/130] (275s left) i915_query (query-topology-kernel-writes)
[743.838746] Starting subtest: query-topology-kernel-writes
[743.840726] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[743.955240] [120/130] (275s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[744.339751] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[745.166467] [121/130] (274s left) gem_exec_reloc (basic-cpu-active)
[745.226911] Subtest basic-cpu-active: SKIP (0.000s)
[745.310530] [122/130] (274s left) gem_exec_fence (concurrent)
[745.484658] Starting subtest: concurrent
[745.489463] Subtest concurrent: SKIP (0.000s)
[746.809366] [123/130] (272s left) perf (short-reads)
[746.951856] Starting subtest: short-reads
[746.968420] Subtest short-reads: SUCCESS (0.017s)
[747.017840] [124/130] (272s left) dumb_buffer (create-clear)
[747.074198] Starting subtest: create-clear
[781.211119] Subtest create-clear: SUCCESS (34.140s)
[781.354516] [125/130] (238s left) kms_psr2_su (page_flip-p010)
[781.414093] Subtest page_flip-P010: SKIP (0.000s)
[781.467533] [126/130] (238s left) i915_pm_rc6_residency (rc6-idle)
[781.525962] Starting subtest: rc6-idle
[781.569619] Starting dynamic subtest: gt0-rcs0
[805.842066] Dynamic subtest gt0-rcs0: SUCCESS (24.269s)
[805.853142] Starting dynamic subtest: gt0-bcs0
[830.190219] Dynamic subtest gt0-bcs0: SUCCESS (24.349s)
[830.203534] Starting dynamic subtest: gt0-ccs0
[854.398706] Dynamic subtest gt0-ccs0: SUCCESS (24.211s)
[854.408974] Starting dynamic subtest: gt1-vcs0
[860.572924] Dynamic subtest gt1-vcs0: SUCCESS (6.171s)
[860.583698] Starting dynamic subtest: gt1-vecs0
[866.744847] Dynamic subtest gt1-vecs0: SUCCESS (6.171s)
[866.747560] Subtest rc6-idle: SUCCESS (85.228s)
[866.837687] [127/130] (152s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[866.942226] Starting subtest: plane-scaler-unity-scaling-with-rotation
[866.944377] Starting dynamic subtest: pipe-A
[867.169242] Dynamic subtest pipe-A: SUCCESS (0.229s)
[867.182201] Starting dynamic subtest: pipe-B
[868.433581] Dynamic subtest pipe-B: SUCCESS (1.264s)
[868.444340] Starting dynamic subtest: pipe-C
[869.769537] Dynamic subtest pipe-C: SUCCESS (1.336s)
[869.780536] Starting dynamic subtest: pipe-D
[871.093811] Dynamic subtest pipe-D: SUCCESS (1.324s)
[871.096237] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.155s)
[872.287527] [128/130] (147s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[872.380629] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[872.384843] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[872.500011] [129/130] (147s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[872.623282] Starting subtest: cursor-vs-flip-legacy
[901.952884] Subtest cursor-vs-flip-legacy: SUCCESS (29.338s)
[902.805461] [130/130] (117s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[902.870113] Starting subtest: multi-wait-available-submitted-signaled
[902.872475] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[902.995436] 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.