[58.711841] Initializing watchdogs
[58.711897]   /dev/watchdog0
[58.727311] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.732021] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.734400] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.738134] [001/133] (960s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[58.872922] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[58.876968] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[58.940502] [002/133] (960s left) kms_flip (flip-vs-suspend-interruptible)
[59.043884] Starting subtest: flip-vs-suspend-interruptible
[59.046322] Starting dynamic subtest: A-eDP1
[92.310215] Dynamic subtest A-eDP1: SUCCESS (3.802s)
[92.319185] Starting dynamic subtest: B-eDP1
[141.327128] Dynamic subtest B-eDP1: SUCCESS (5.568s)
[141.341515] Starting dynamic subtest: C-eDP1
[190.305400] Dynamic subtest C-eDP1: SUCCESS (4.421s)
[190.314321] Starting dynamic subtest: D-eDP1
[239.292427] Dynamic subtest D-eDP1: SUCCESS (4.468s)
[239.294712] Subtest flip-vs-suspend-interruptible: SUCCESS (18.267s)
[240.525669] [003/133] (778s left) syncobj_wait (single-wait-all-for-submit-submitted)
[240.587832] Starting subtest: single-wait-all-for-submit-submitted
[240.691395] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.102s)
[240.791478] [004/133] (778s left) gem_eio (throttle)
[240.943577] Starting subtest: throttle
[241.114087] Subtest throttle: SUCCESS (0.167s)
[241.241732] [005/133] (778s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[241.344343] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[241.348620] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[241.438168] [006/133] (778s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[241.536712] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[241.539152] Starting dynamic subtest: pipe-A-default-mode
[242.691426] Dynamic subtest pipe-A-default-mode: SKIP (1.139s)
[242.695870] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.141s)
[242.846537] [007/133] (776s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[242.951177] Starting subtest: yf-tiled-16bpp-rotate-90
[242.955542] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[243.031798] [008/133] (776s left) kms_psr (fbc-pr-primary-mmap-cpu)
[243.135159] Starting subtest: fbc-pr-primary-mmap-cpu
[243.139448] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[243.209459] [009/133] (776s left) gem_exec_balancer (bonded-dual)
[243.277066] Starting subtest: bonded-dual
[243.281583] Subtest bonded-dual: SKIP (0.000s)
[243.574275] [010/133] (776s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[243.671209] Starting subtest: yf-tiled-32bpp-rotate-90
[243.675316] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[243.770162] [011/133] (775s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[243.874787] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[243.877065] Starting dynamic subtest: pipe-A
[244.214505] Dynamic subtest pipe-A: SUCCESS (0.339s)
[244.223151] Starting dynamic subtest: pipe-B
[245.667181] Dynamic subtest pipe-B: SUCCESS (1.442s)
[245.676936] Starting dynamic subtest: pipe-C
[247.130003] Dynamic subtest pipe-C: SUCCESS (1.454s)
[247.139277] Starting dynamic subtest: pipe-D
[248.562899] Dynamic subtest pipe-D: SUCCESS (1.426s)
[248.565206] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.664s)
[249.733263] [012/133] (769s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[249.838126] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[256.489523] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.635s)
[256.619093] [013/133] (763s left) kms_cursor_legacy (single-move)
[256.745372] Starting subtest: single-move
[256.747510] Starting dynamic subtest: pipe-A
[262.135794] Dynamic subtest pipe-A: SUCCESS (5.379s)
[262.145823] Starting dynamic subtest: pipe-B
[267.522702] Dynamic subtest pipe-B: SUCCESS (5.380s)
[267.532052] Starting dynamic subtest: pipe-C
[272.953410] Dynamic subtest pipe-C: SUCCESS (5.381s)
[272.964581] Starting dynamic subtest: pipe-D
[278.405154] Dynamic subtest pipe-D: SUCCESS (5.380s)
[278.416450] Starting dynamic subtest: all-pipes
[283.829030] Dynamic subtest all-pipes: SUCCESS (5.387s)
[283.831276] Subtest single-move: SUCCESS (26.916s)
[283.973263] [014/133] (735s left) gem_exec_reloc (basic-wc-read)
[284.034338] Subtest basic-wc-read: SKIP (0.000s)
[284.102934] [015/133] (735s left) kms_atomic (plane-invalid-params)
[284.191650] Starting subtest: plane-invalid-params
[284.193970] Starting dynamic subtest: pipe-A-eDP-1
[284.595883] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.403s)
[284.598149] Subtest plane-invalid-params: SUCCESS (0.404s)
[285.478457] [016/133] (734s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[285.573999] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[285.577944] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[285.669700] [017/133] (734s left) kms_addfb_basic (unused-handle)
[285.734075] Starting subtest: unused-handle
[285.736551] Subtest unused-handle: SUCCESS (0.000s)
[285.866805] [018/133] (734s left) gem_softpin (reverse)
[285.926318] Starting subtest: reverse
[285.932285] Subtest reverse: SUCCESS (0.006s)
[286.057648] [019/133] (733s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[286.161199] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[290.690593] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (4.520s)
[291.823525] [020/133] (728s left) gem_ctx_engines (independent)
[291.898050] Starting subtest: independent
[291.900113] Starting dynamic subtest: rcs0
[292.039723] Dynamic subtest rcs0: SUCCESS (0.142s)
[292.051212] Starting dynamic subtest: bcs0
[292.177173] Dynamic subtest bcs0: SUCCESS (0.137s)
[292.188158] Starting dynamic subtest: vcs0
[292.333526] Dynamic subtest vcs0: SUCCESS (0.155s)
[292.344525] Starting dynamic subtest: vcs1
[292.504078] Dynamic subtest vcs1: SUCCESS (0.170s)
[292.512961] Starting dynamic subtest: vecs0
[292.681260] Dynamic subtest vecs0: SUCCESS (0.176s)
[292.692482] Starting dynamic subtest: ccs0
[292.854816] Dynamic subtest ccs0: SUCCESS (0.173s)
[292.864321] Starting dynamic subtest: all-engines
[293.497853] Dynamic subtest all-engines: SUCCESS (0.641s)
[293.500084] Subtest independent: SUCCESS (1.599s)
[293.791578] [021/133] (726s left) kms_addfb_basic (bad-pitch-128)
[293.852999] Starting subtest: bad-pitch-128
[293.854847] Subtest bad-pitch-128: SUCCESS (0.000s)
[293.985512] [022/133] (726s left) kms_psr (psr-cursor-mmap-cpu)
[294.071952] Starting subtest: psr-cursor-mmap-cpu
[294.074250] Starting dynamic subtest: eDP-1
[295.817120] Dynamic subtest eDP-1: SUCCESS (1.743s)
[295.819460] Subtest psr-cursor-mmap-cpu: SUCCESS (1.744s)
[296.684320] [023/133] (723s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[296.807554] Starting subtest: cursorB-vs-flipB-atomic-transitions
[296.812202] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[296.975548] [024/133] (723s left) kms_content_protection (suspend-resume)
[297.109618] Starting subtest: suspend-resume
[297.113778] Subtest suspend-resume: SKIP (0.000s)
[297.223038] [025/133] (722s left) kms_cursor_edge_walk (256x256-top-bottom)
[297.316907] Starting subtest: 256x256-top-bottom
[297.319232] Starting dynamic subtest: pipe-A-eDP-1
[300.771362] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.450s)
[300.781254] Starting dynamic subtest: pipe-D-eDP-1
[303.899264] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.128s)
[303.901501] Subtest 256x256-top-bottom: SUCCESS (6.580s)
[304.706487] [026/133] (715s left) api_intel_allocator (reloc-allocator)
[304.769974] Starting subtest: reloc-allocator
[304.772530] Starting dynamic subtest: basic
[304.775018] Dynamic subtest basic: SUCCESS (0.003s)
[304.788163] Starting dynamic subtest: parallel-one
[304.790153] Dynamic subtest parallel-one: SUCCESS (0.007s)
[304.798940] Starting dynamic subtest: print
[304.801056] Dynamic subtest print: SUCCESS (0.000s)
[304.811778] Starting dynamic subtest: reuse
[304.813912] Dynamic subtest reuse: SUCCESS (0.000s)
[304.822250] Starting dynamic subtest: fork-reopen-allocator
[304.824411] Dynamic subtest fork-reopen-allocator: SUCCESS (0.013s)
[304.826556] Subtest reloc-allocator: SUCCESS (0.025s)
[304.919521] [027/133] (715s left) syncobj_basic (bad-destroy)
[304.974654] Starting subtest: bad-destroy
[304.976856] Subtest bad-destroy: SUCCESS (0.000s)
[305.073063] [028/133] (715s left) kms_vblank (ts-continuation-idle)
[305.165375] Starting subtest: ts-continuation-idle
[305.167531] Starting dynamic subtest: pipe-A-eDP-1
[305.581680] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.417s)
[305.595501] Starting dynamic subtest: pipe-D-eDP-1
[306.710250] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[306.712243] Subtest ts-continuation-idle: SUCCESS (1.545s)
[307.569972] [029/133] (712s left) gem_exec_capture (pi)
[307.629444] Starting subtest: pi
[307.631580] Starting dynamic subtest: rcs0
[311.893378] Dynamic subtest rcs0: SUCCESS (4.264s)
[311.908601] Starting dynamic subtest: bcs0
[315.819814] Dynamic subtest bcs0: SUCCESS (3.925s)
[315.831562] Starting dynamic subtest: vcs0
[319.765524] Dynamic subtest vcs0: SUCCESS (3.943s)
[319.779941] Starting dynamic subtest: vcs1
[323.766002] Dynamic subtest vcs1: SUCCESS (3.999s)
[323.777777] Starting dynamic subtest: vecs0
[327.729950] Dynamic subtest vecs0: SUCCESS (3.961s)
[327.739883] Starting dynamic subtest: ccs0
[331.728441] Dynamic subtest ccs0: SUCCESS (3.996s)
[331.730627] Subtest pi: SUCCESS (24.097s)
[331.809218] [030/133] (688s left) fbdev (read)
[331.903150] Starting subtest: read
[332.437923] Subtest read: SUCCESS (0.532s)
[332.502001] [031/133] (687s left) kms_cursor_crc (cursor-random-256x256)
[332.752518] Starting subtest: cursor-random-256x256
[332.754833] Starting dynamic subtest: pipe-A-eDP-1
[336.111394] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.357s)
[336.145943] Starting dynamic subtest: pipe-D-eDP-1
[340.099085] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.961s)
[340.100924] Subtest cursor-random-256x256: SUCCESS (7.345s)
[340.938815] [032/133] (679s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[341.035891] Subtest addfb25-x-tiled-mismatch-legacy: SKIP (0.000s)
[341.129928] [033/133] (679s left) kms_atomic_transition (plane-all-modeset-transition)
[341.236668] Starting subtest: plane-all-modeset-transition
[341.240852] Subtest plane-all-modeset-transition: SKIP (0.000s)
[341.307387] [034/133] (679s left) core_auth (getclient-simple)
[341.344420] Starting subtest: getclient-simple
[341.366802] Subtest getclient-simple: SUCCESS (0.022s)
[341.476294] [035/133] (679s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[341.572135] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[342.408028] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.837s)
[342.557880] [036/133] (678s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[342.656550] Starting subtest: blocking-absolute-wf_vblank-interruptible
[342.658884] Starting dynamic subtest: A-eDP1
[351.389035] Dynamic subtest A-eDP1: SUCCESS (8.728s)
[351.400534] Starting dynamic subtest: B-eDP1
[360.124574] Dynamic subtest B-eDP1: SUCCESS (8.730s)
[360.134295] Starting dynamic subtest: C-eDP1
[368.851522] Dynamic subtest C-eDP1: SUCCESS (8.723s)
[368.862577] Starting dynamic subtest: D-eDP1
[377.586760] Dynamic subtest D-eDP1: SUCCESS (8.733s)
[377.589133] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.926s)
[378.794958] [037/133] (641s left) kms_lease (page-flip-implicit-plane)
[378.908427] Starting subtest: page-flip-implicit-plane
[378.910818] Starting dynamic subtest: pipe-A-eDP-1
[379.413820] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.502s)
[379.423881] Starting dynamic subtest: pipe-B-eDP-1
[380.547309] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.132s)
[380.557469] Starting dynamic subtest: pipe-C-eDP-1
[381.656057] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.108s)
[381.665904] Starting dynamic subtest: pipe-D-eDP-1
[382.782688] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[382.784808] Subtest page-flip-implicit-plane: SUCCESS (3.874s)
[383.616769] [038/133] (637s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[383.705076] Starting subtest: bad-aux-stride-y-tiled-ccs
[383.707477] Starting dynamic subtest: pipe-A-eDP-1
[383.730853] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[383.743725] Starting dynamic subtest: pipe-B-eDP-1
[383.765132] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[383.777190] Starting dynamic subtest: pipe-C-eDP-1
[383.798531] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[383.808283] Starting dynamic subtest: pipe-D-eDP-1
[383.830394] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[383.834781] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s)
[385.897344] [039/133] (634s left) kms_fence_pin_leak
[386.040357] [040/133] (634s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[386.148069] Starting subtest: extended-modeset-hang-oldfb-with-reset
[386.150488] Starting dynamic subtest: pipe-A
[398.079068] Dynamic subtest pipe-A: SUCCESS (11.930s)
[398.088801] Starting dynamic subtest: pipe-D
[410.368829] Dynamic subtest pipe-D: SUCCESS (12.285s)
[410.371174] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.220s)
[411.159927] [041/133] (609s left) perf_pmu (busy-idle-no-semaphores)
[411.208933] Starting subtest: busy-idle-no-semaphores
[411.210769] Starting dynamic subtest: rcs0
[411.869847] Dynamic subtest rcs0: SUCCESS (0.661s)
[411.881340] Starting dynamic subtest: bcs0
[412.519081] Dynamic subtest bcs0: SUCCESS (0.646s)
[412.532306] Starting dynamic subtest: vcs0
[413.170392] Dynamic subtest vcs0: SUCCESS (0.648s)
[413.183506] Starting dynamic subtest: vcs1
[415.097808] Dynamic subtest vcs1: SUCCESS (1.924s)
[415.109818] Starting dynamic subtest: vecs0
[417.143630] Dynamic subtest vecs0: SUCCESS (2.043s)
[417.156436] Starting dynamic subtest: ccs0
[419.129657] Dynamic subtest ccs0: SUCCESS (1.983s)
[419.132522] Subtest busy-idle-no-semaphores: SUCCESS (7.921s)
[419.261666] [042/133] (601s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[419.364795] Starting subtest: plane-use-after-nonblocking-unbind
[419.367109] Starting dynamic subtest: pipe-A-eDP-1
[419.427832] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.063s)
[419.737437] Starting dynamic subtest: pipe-B-eDP-1
[420.562945] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.834s)
[420.860182] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.496s)
[421.746129] [043/133] (599s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[421.863840] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[421.867985] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[421.968511] [044/133] (599s left) kms_psr (psr-sprite-render)
[422.076518] Starting subtest: psr-sprite-render
[422.078772] Starting dynamic subtest: eDP-1
[423.847470] Dynamic subtest eDP-1: SUCCESS (1.770s)
[423.849844] Subtest psr-sprite-render: SUCCESS (1.771s)
[424.676161] [045/133] (596s left) kms_getfb (getfb-handle-protection)
[424.786456] Starting subtest: getfb-handle-protection
[424.807208] Subtest getfb-handle-protection: SUCCESS (0.021s)
[424.924676] [046/133] (596s left) i915_hangman (engine-engine-error)
[424.992714] Starting subtest: engine-engine-error
[424.994599] Starting dynamic subtest: rcs0
[426.477804] Dynamic subtest rcs0: SUCCESS (1.485s)
[426.490801] Starting dynamic subtest: bcs0
[428.461087] Dynamic subtest bcs0: SUCCESS (1.980s)
[428.474462] Starting dynamic subtest: vcs0
[430.446147] Dynamic subtest vcs0: SUCCESS (1.982s)
[430.459491] Starting dynamic subtest: vcs1
[432.429187] Dynamic subtest vcs1: SUCCESS (1.979s)
[432.442111] Starting dynamic subtest: vecs0
[434.478124] Dynamic subtest vecs0: SUCCESS (2.046s)
[434.491160] Starting dynamic subtest: ccs0
[436.462328] Dynamic subtest ccs0: SUCCESS (1.981s)
[436.465318] Subtest engine-engine-error: SUCCESS (11.470s)
[436.608549] [047/133] (584s left) gem_pxp (display-protected-crc)
[436.728787] Starting subtest: display-protected-crc
[438.364247] Subtest display-protected-crc: SUCCESS (1.638s)
[438.493674] [048/133] (582s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[438.591956] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[438.595886] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[438.692362] [049/133] (582s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[438.779606] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[438.783973] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[438.870341] [050/133] (582s left) kms_chamelium_frames (dp-frame-dump)
[439.274367] Subtest dp-frame-dump: SKIP (0.000s)
[440.084376] [051/133] (581s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[440.193673] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[441.205167] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.012s)
[441.338021] [052/133] (579s left) kms_atomic_interruptible (legacy-pageflip)
[441.440685] Starting subtest: legacy-pageflip
[441.442689] Starting dynamic subtest: pipe-A-eDP-1
[448.831560] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.387s)
[448.834060] Subtest legacy-pageflip: SUCCESS (7.388s)
[449.680162] [053/133] (571s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping)
[449.791401] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping
[449.795595] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[449.867864] [054/133] (571s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[449.971917] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[450.024007] [055/133] (571s left) gem_mmap_gtt (zero-extend)
[450.078238] Subtest zero-extend: SKIP (0.000s)
[450.138133] [056/133] (571s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[450.226583] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[450.228793] Starting dynamic subtest: pipe-A-eDP-1
[450.250517] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[450.262936] Starting dynamic subtest: pipe-B-eDP-1
[450.283599] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[450.292856] Starting dynamic subtest: pipe-C-eDP-1
[450.313424] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[450.322950] Starting dynamic subtest: pipe-D-eDP-1
[450.343861] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[450.347801] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[452.524369] [057/133] (569s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[452.651129] Starting subtest: flip-vs-cursor-crc-atomic
[453.330777] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.679s)
[454.156473] [058/133] (567s left) gem_exec_schedule (pi-ringfull)
[454.334439] Starting subtest: pi-ringfull
[454.336831] Starting dynamic subtest: rcs0
[454.415722] Dynamic subtest rcs0: SUCCESS (0.085s)
[454.431323] Starting dynamic subtest: bcs0
[454.443881] Dynamic subtest bcs0: SUCCESS (0.027s)
[454.458676] Starting dynamic subtest: vcs0
[454.470376] Dynamic subtest vcs0: SUCCESS (0.026s)
[454.485695] Starting dynamic subtest: vcs1
[454.496885] Dynamic subtest vcs1: SUCCESS (0.026s)
[454.511320] Starting dynamic subtest: vecs0
[454.523715] Dynamic subtest vecs0: SUCCESS (0.026s)
[454.538265] Starting dynamic subtest: ccs0
[454.549615] Dynamic subtest ccs0: SUCCESS (0.025s)
[454.551805] Subtest pi-ringfull: SUCCESS (0.220s)
[454.764681] [FACT gem_exec_schedule (pi-ringfull)] new: kernel.kmod_is_loaded.vgem: true
[454.768432] [059/133] (566s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[454.853738] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[454.858204] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[454.943300] [060/133] (566s left) kms_flip (dpms-off-confusion-interruptible)
[455.037007] Starting subtest: dpms-off-confusion-interruptible
[455.039296] Starting dynamic subtest: A-eDP1
[463.666149] Dynamic subtest A-eDP1: SUCCESS (8.625s)
[463.678402] Starting dynamic subtest: B-eDP1
[472.299164] Dynamic subtest B-eDP1: SUCCESS (8.629s)
[472.312017] Starting dynamic subtest: C-eDP1
[480.926022] Dynamic subtest C-eDP1: SUCCESS (8.622s)
[480.938847] Starting dynamic subtest: D-eDP1
[489.558326] Dynamic subtest D-eDP1: SUCCESS (8.627s)
[489.564624] Subtest dpms-off-confusion-interruptible: SUCCESS (34.515s)
[490.737418] [061/133] (530s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[490.841245] Starting subtest: x-tiled-16bpp-rotate-0
[492.232091] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.390s)
[492.356887] [062/133] (529s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[492.411150] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[492.515629] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[492.593092] [063/133] (529s left) gem_lmem_swapping (verify-random)
[492.639044] Subtest verify-random: SKIP (0.000s)
[492.695565] [064/133] (529s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[492.808571] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[492.812846] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[492.894809] [065/133] (528s left) prime_busy (after)
[492.972417] Starting subtest: after
[492.974707] Starting dynamic subtest: rcs0
[493.000557] Dynamic subtest rcs0: SUCCESS (0.026s)
[493.012814] Starting dynamic subtest: bcs0
[493.048678] Dynamic subtest bcs0: SUCCESS (0.047s)
[493.059998] Starting dynamic subtest: vcs0
[493.088576] Dynamic subtest vcs0: SUCCESS (0.039s)
[493.100127] Starting dynamic subtest: vcs1
[493.129018] Dynamic subtest vcs1: SUCCESS (0.039s)
[493.139313] Starting dynamic subtest: vecs0
[493.169926] Dynamic subtest vecs0: SUCCESS (0.040s)
[493.180166] Starting dynamic subtest: ccs0
[493.210026] Dynamic subtest ccs0: SUCCESS (0.039s)
[493.319697] Subtest after: SUCCESS (0.346s)
[493.395292] [066/133] (528s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[493.456476] Starting subtest: y-tiled-ccs-to-x-tiled
[493.460703] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[493.651478] [067/133] (528s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[493.715714] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[493.720342] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[493.899836] [068/133] (528s left) sysfs_heartbeat_interval (precise)
[493.959352] Starting subtest: precise
[493.961557] Starting dynamic subtest: vecs0
[501.519687] Dynamic subtest vecs0: SUCCESS (7.558s)
[501.532107] Starting dynamic subtest: ccs0
[502.708940] Dynamic subtest ccs0: SUCCESS (1.190s)
[502.723153] Starting dynamic subtest: vcs0
[510.474839] Dynamic subtest vcs0: SUCCESS (7.761s)
[510.487056] Starting dynamic subtest: bcs0
[511.562877] Dynamic subtest bcs0: SUCCESS (1.089s)
[511.574669] Starting dynamic subtest: rcs0
[512.679794] Dynamic subtest rcs0: SUCCESS (1.115s)
[512.692771] Starting dynamic subtest: vcs1
[520.467566] Dynamic subtest vcs1: SUCCESS (7.788s)
[520.470431] Subtest precise: SUCCESS (26.512s)
[520.547717] [069/133] (501s left) syncobj_timeline (invalid-transfer-illegal-handle)
[520.620092] Starting subtest: invalid-transfer-illegal-handle
[520.622498] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[520.715102] [070/133] (501s left) kms_pm_rpm (legacy-planes)
[522.646801] Starting subtest: legacy-planes
[522.649810] Starting dynamic subtest: plane-65
[530.956478] Dynamic subtest plane-65: SUCCESS (8.310s)
[530.969596] Starting dynamic subtest: plane-95
[539.232396] Dynamic subtest plane-95: SUCCESS (8.275s)
[539.244926] Starting dynamic subtest: plane-125
[547.510444] Dynamic subtest plane-125: SUCCESS (8.275s)
[547.523710] Starting dynamic subtest: plane-135
[555.942930] Dynamic subtest plane-135: SUCCESS (8.430s)
[555.945983] Subtest legacy-planes: SUCCESS (33.299s)
[556.318998] [071/133] (465s left) gem_exec_fence (syncobj-export)
[556.774777] Starting subtest: syncobj-export
[556.776903] Subtest syncobj-export: SUCCESS (0.005s)
[558.561396] [072/133] (463s left) gem_exec_whisper (basic-queues-forked)
[558.633234] Starting subtest: basic-queues-forked
[581.551087] Subtest basic-queues-forked: SUCCESS (22.917s)
[581.756798] [073/133] (440s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[581.863013] Starting subtest: read-crc-frame-sequence
[581.865364] Starting dynamic subtest: pipe-A-eDP-1
[582.579793] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.716s)
[582.591391] Starting dynamic subtest: pipe-B-eDP-1
[583.989174] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.407s)
[584.000472] Starting dynamic subtest: pipe-C-eDP-1
[585.364479] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.373s)
[585.375980] Starting dynamic subtest: pipe-D-eDP-1
[586.696864] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.330s)
[586.699219] Subtest read-crc-frame-sequence: SUCCESS (4.833s)
[587.526711] [074/133] (434s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[587.643157] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[587.647534] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[587.748468] [075/133] (434s left) prime_mmap (test_aperture_limit)
[587.817942] Starting subtest: test_aperture_limit
[587.819810] Starting dynamic subtest: test_aperture_limit-smem
[587.838172] Dynamic subtest test_aperture_limit-smem: SKIP (0.020s)
[587.843281] Subtest test_aperture_limit: SKIP (0.023s)
[587.927878] [076/133] (434s left) i915_pm_rc6_residency (rc6-accuracy)
[587.994344] Starting subtest: rc6-accuracy
[587.996677] Starting dynamic subtest: gt0
[591.163793] Dynamic subtest gt0: SUCCESS (3.169s)
[591.176877] Starting dynamic subtest: gt1
[594.335821] Dynamic subtest gt1: SUCCESS (3.168s)
[594.338565] Subtest rc6-accuracy: SUCCESS (6.343s)
[594.413696] [077/133] (427s left) perf (global-sseu-config-invalid)
[594.547823] Starting subtest: global-sseu-config-invalid
[594.551826] Subtest global-sseu-config-invalid: SKIP (0.000s)
[594.603765] [078/133] (427s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[594.710792] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[599.021987] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.314s)
[600.225927] [079/133] (422s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[600.338220] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[600.342311] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[600.423860] [080/133] (422s left) kms_pm_rpm (cursor)
[602.658362] Starting subtest: cursor
[602.663376] Subtest cursor: SKIP (0.000s)
[603.039875] [081/133] (419s left) sw_sync (sync_multi_consumer)
[603.078211] Starting subtest: sync_multi_consumer
[603.510717] Subtest sync_multi_consumer: SUCCESS (0.435s)
[603.573445] [082/133] (419s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[603.659776] Starting subtest: fbc-psr-cursor-mmap-cpu
[603.662028] Starting dynamic subtest: eDP-1
[603.666110] Dynamic subtest eDP-1: SKIP (0.000s)
[603.670214] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[603.737349] [083/133] (418s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[603.828844] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[612.542086] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.716s)
[612.665445] [084/133] (410s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[612.765538] Starting subtest: y-tiled-8bpp-rotate-180
[612.769853] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[612.866636] [085/133] (409s left) gem_exec_reloc (basic-softpin)
[612.945448] Subtest basic-softpin: SKIP (0.000s)
[613.040073] [086/133] (409s left) gem_exec_schedule (timeslicing)
[613.105206] Starting subtest: timeslicing
[613.107191] Starting dynamic subtest: rcs0
[613.115616] Dynamic subtest rcs0: SUCCESS (0.021s)
[613.128451] Starting dynamic subtest: bcs0
[613.136109] Dynamic subtest bcs0: SUCCESS (0.020s)
[613.146568] Starting dynamic subtest: vcs0
[613.157928] Dynamic subtest vcs0: SUCCESS (0.021s)
[613.170727] Starting dynamic subtest: vcs1
[613.180498] Dynamic subtest vcs1: SUCCESS (0.021s)
[613.190663] Starting dynamic subtest: vecs0
[613.201606] Dynamic subtest vecs0: SUCCESS (0.020s)
[613.211936] Starting dynamic subtest: ccs0
[613.223260] Dynamic subtest ccs0: SUCCESS (0.021s)
[613.225652] Subtest timeslicing: SUCCESS (0.130s)
[614.526560] [087/133] (408s left) kms_chamelium_color (ctm-green-to-red)
[614.619161] Subtest ctm-green-to-red: SKIP (0.000s)
[614.675067] [088/133] (408s left) gen9_exec_parse (batch-zero-length)
[614.734164] Subtest batch-zero-length: SKIP (0.000s)
[614.809205] [089/133] (408s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt)
[614.913019] Starting subtest: psr-2p-primscrn-indfb-plflip-blt
[614.917384] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[615.000739] [090/133] (407s left) dmabuf_sync_file (export-multiwait)
[615.041251] Starting subtest: export-multiwait
[615.043470] Subtest export-multiwait: SUCCESS (0.000s)
[615.101895] [091/133] (407s left) gen3_render_tiledx_blits
[615.224868] [092/133] (407s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[615.316714] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[615.319008] Starting dynamic subtest: pipe-A-eDP-1
[615.341802] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[615.355429] Starting dynamic subtest: pipe-B-eDP-1
[615.376422] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[615.386829] Starting dynamic subtest: pipe-C-eDP-1
[615.408767] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[615.419187] Starting dynamic subtest: pipe-D-eDP-1
[615.441405] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[615.445521] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.006s)
[617.526313] [093/133] (405s left) kms_color (gamma)
[617.622459] Starting subtest: gamma
[617.624707] Starting dynamic subtest: pipe-A-eDP-1
[618.340519] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.717s)
[618.351200] Starting dynamic subtest: pipe-B-eDP-1
[619.737788] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.397s)
[619.749533] Starting dynamic subtest: pipe-C-eDP-1
[621.148671] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.408s)
[621.159541] Starting dynamic subtest: pipe-D-eDP-1
[622.544034] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.396s)
[622.546337] Subtest gamma: SUCCESS (4.922s)
[623.354705] [094/133] (399s left) gem_ctx_persistence (legacy-engines-mixed-process)
[623.418001] Starting subtest: legacy-engines-mixed-process
[623.420190] Starting dynamic subtest: render
[625.500147] Dynamic subtest render: SUCCESS (2.081s)
[625.514817] Starting dynamic subtest: bsd1
[627.555607] Dynamic subtest bsd1: SUCCESS (2.053s)
[627.568497] Starting dynamic subtest: bsd2
[629.524806] Dynamic subtest bsd2: SUCCESS (1.967s)
[629.535656] Starting dynamic subtest: blt
[631.507629] Dynamic subtest blt: SUCCESS (1.981s)
[631.520705] Starting dynamic subtest: vebox
[633.497565] Dynamic subtest vebox: SUCCESS (1.988s)
[633.499986] Subtest legacy-engines-mixed-process: SUCCESS (10.079s)
[633.596452] [095/133] (389s left) kms_plane (pixel-format-y-tiled-modifier)
[633.689549] Starting subtest: pixel-format-y-tiled-modifier
[633.693481] Subtest pixel-format-y-tiled-modifier: SKIP (0.000s)
[633.763072] [096/133] (389s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[633.849829] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[633.852137] Starting dynamic subtest: pipe-A-eDP-1
[633.908032] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[633.919898] Starting dynamic subtest: pipe-B-eDP-1
[633.976197] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[633.989554] Starting dynamic subtest: pipe-C-eDP-1
[634.046111] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[634.058130] Starting dynamic subtest: pipe-D-eDP-1
[634.119079] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[634.125074] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s)
[636.116211] [097/133] (387s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[636.220670] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[643.028800] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.812s)
[643.182092] [098/133] (380s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[643.278451] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[643.282974] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[643.377922] [099/133] (379s left) gem_exec_reloc (basic-scanout)
[643.447083] Subtest basic-scanout: SKIP (0.000s)
[643.526353] [100/133] (379s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[643.624031] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[643.628362] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[643.713334] [101/133] (379s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[643.831630] Starting subtest: cursor-vs-flip-toggle
[675.299275] Subtest cursor-vs-flip-toggle: SUCCESS (31.460s)
[676.195134] [102/133] (347s left) syncobj_timeline (single-wait-available-signaled)
[676.246622] Starting subtest: single-wait-available-signaled
[676.248915] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[676.343361] [103/133] (347s left) gem_ctx_isolation (clean)
[676.414915] Starting subtest: clean
[676.417335] Starting dynamic subtest: rcs0
[676.473359] Dynamic subtest rcs0: SUCCESS (0.058s)
[676.484609] Starting dynamic subtest: bcs0
[676.542570] Dynamic subtest bcs0: SUCCESS (0.068s)
[676.553084] Starting dynamic subtest: vcs0
[676.599045] Dynamic subtest vcs0: SUCCESS (0.056s)
[676.611245] Starting dynamic subtest: vcs1
[678.478416] Dynamic subtest vcs1: SUCCESS (1.878s)
[678.490555] Starting dynamic subtest: vecs0
[680.531280] Dynamic subtest vecs0: SUCCESS (2.051s)
[680.544547] Starting dynamic subtest: ccs0
[682.540197] Dynamic subtest ccs0: SUCCESS (2.008s)
[682.542624] Subtest clean: SUCCESS (6.125s)
[682.772205] [104/133] (340s left) kms_plane_multiple (tiling-4)
[682.860308] Starting subtest: tiling-4
[682.862638] Starting dynamic subtest: pipe-A-eDP-1
[686.619960] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.758s)
[686.631351] Starting dynamic subtest: pipe-B-eDP-1
[691.051594] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.428s)
[691.062900] Starting dynamic subtest: pipe-C-eDP-1
[695.392854] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.339s)
[695.403868] Starting dynamic subtest: pipe-D-eDP-1
[699.740914] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.346s)
[699.742858] Subtest tiling-4: SUCCESS (16.878s)
[700.553378] [105/133] (323s left) syncobj_timeline (multi-wait-submitted-signaled)
[700.612948] Starting subtest: multi-wait-submitted-signaled
[700.614840] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[700.717369] [106/133] (322s left) gem_exec_flush (basic-uc-set-default)
[700.798371] Starting subtest: basic-uc-set-default
[706.254709] Subtest basic-uc-set-default: SUCCESS (5.456s)
[706.455719] [107/133] (317s left) gem_readwrite (new-obj)
[706.515804] Subtest new-obj: SKIP (0.000s)
[706.575376] [108/133] (317s left) kms_joiner (basic-ultra-joiner)
[706.685012] Starting subtest: basic-ultra-joiner
[706.689177] Subtest basic-ultra-joiner: SKIP (0.000s)
[706.758632] [109/133] (317s left) i915_drm_fdinfo (most-busy-idle-check-all)
[706.797455] Starting subtest: most-busy-idle-check-all
[706.799471] Starting dynamic subtest: rcs0
[706.803615] Dynamic subtest rcs0: SKIP (0.005s)
[706.815888] Starting dynamic subtest: bcs0
[706.819932] Dynamic subtest bcs0: SKIP (0.000s)
[706.830050] Starting dynamic subtest: vcs0
[706.834387] Dynamic subtest vcs0: SKIP (0.000s)
[706.844455] Starting dynamic subtest: vcs1
[706.848385] Dynamic subtest vcs1: SKIP (0.000s)
[706.858003] Starting dynamic subtest: vecs0
[706.861895] Dynamic subtest vecs0: SKIP (0.000s)
[706.872469] Starting dynamic subtest: ccs0
[706.876695] Dynamic subtest ccs0: SKIP (0.000s)
[706.880557] Subtest most-busy-idle-check-all: SKIP (0.010s)
[706.918286] [110/133] (316s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[707.052357] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[707.057393] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[707.201015] [111/133] (316s left) gem_exec_alignment (forked)
[707.262328] Starting subtest: forked
[745.388210] Subtest forked: SUCCESS (38.116s)
[745.474820] [112/133] (278s left) kms_chamelium_hpd (vga-hpd-fast)
[745.909798] Subtest vga-hpd-fast: SKIP (0.000s)
[746.773596] [113/133] (277s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[746.876021] Starting subtest: fbc-rgb101010-draw-pwrite
[752.292698] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.418s)
[753.397451] [114/133] (270s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[753.463804] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[753.520539] [115/133] (270s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[753.616443] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[753.620642] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[753.712043] [116/133] (270s left) syncobj_wait (wait-any-interrupted)
[753.773782] Starting subtest: wait-any-interrupted
[753.875774] Subtest wait-any-interrupted: SUCCESS (0.101s)
[753.958213] [117/133] (270s left) kms_flip (2x-modeset-vs-vblank-race)
[754.060103] Starting subtest: 2x-modeset-vs-vblank-race
[754.071811] Subtest 2x-modeset-vs-vblank-race: SKIP (0.011s)
[754.169458] [118/133] (270s left) gem_exec_reloc (basic-cpu-gtt)
[754.229017] Subtest basic-cpu-gtt: SKIP (0.000s)
[754.301333] [119/133] (269s left) gem_exec_whisper (basic-contexts-all)
[754.363418] Starting subtest: basic-contexts-all
[765.598492] Subtest basic-contexts-all: SUCCESS (11.241s)
[765.784835] [120/133] (258s left) prime_mmap (test_correct_cpu_write)
[765.848819] Starting subtest: test_correct_cpu_write
[765.851165] Starting dynamic subtest: test_correct_cpu_write-smem
[765.853549] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[765.855903] Subtest test_correct_cpu_write: SUCCESS (0.003s)
[765.953914] [121/133] (258s left) kms_dp_link_training (uhbr-sst)
[766.050544] Starting subtest: uhbr-sst
[766.058990] Subtest uhbr-sst: SKIP (0.000s)
[766.122802] [122/133] (258s left) kms_flip (basic-flip-vs-wf_vblank)
[766.222446] Starting subtest: basic-flip-vs-wf_vblank
[766.224538] Starting dynamic subtest: A-eDP1
[768.131843] Dynamic subtest A-eDP1: SUCCESS (1.912s)
[768.147221] Starting dynamic subtest: B-eDP1
[770.058306] Dynamic subtest B-eDP1: SUCCESS (1.925s)
[770.071628] Starting dynamic subtest: C-eDP1
[771.981067] Dynamic subtest C-eDP1: SUCCESS (1.922s)
[771.992366] Starting dynamic subtest: D-eDP1
[773.920705] Dynamic subtest D-eDP1: SUCCESS (1.938s)
[773.923086] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.708s)
[775.139441] [123/133] (249s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[775.239538] Starting subtest: fbc-farfromfence-mmap-gtt
[775.244005] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[775.330459] [124/133] (249s left) syncobj_timeline (device-signal-unordered)
[775.381789] Starting subtest: device-signal-unordered
[775.384100] Subtest device-signal-unordered: SUCCESS (0.000s)
[775.512861] [125/133] (248s left) kms_psr (fbc-psr2-cursor-plane-move)
[775.608448] Starting subtest: fbc-psr2-cursor-plane-move
[775.610684] Starting dynamic subtest: eDP-1
[775.614804] Dynamic subtest eDP-1: SKIP (0.000s)
[775.618900] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[775.696891] [126/133] (248s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[775.751122] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[775.823123] [127/133] (248s left) gem_watchdog (default-physical)
[775.889113] Starting subtest: default-physical
[777.644203] Subtest default-physical: SUCCESS (1.768s)
[777.734258] [128/133] (246s left) kms_psr (pr-primary-page-flip)
[777.830063] Starting subtest: pr-primary-page-flip
[777.834168] Subtest pr-primary-page-flip: SKIP (0.000s)
[777.908191] [129/133] (246s left) gem_tiled_blits (basic)
[777.964156] Subtest basic: SKIP (0.000s)
[778.023260] [130/133] (246s left) kms_cursor_crc (cursor-offscreen-512x512)
[778.309266] Starting subtest: cursor-offscreen-512x512
[778.312989] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[778.455570] [131/133] (246s left) kms_setmode (basic)
[778.525585] Starting subtest: basic
[778.863421] Starting dynamic subtest: pipe-A-eDP-1
[780.969527] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.103s)
[781.315068] Starting dynamic subtest: pipe-B-eDP-1
[783.403238] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.094s)
[783.713225] Subtest basic: SUCCESS (5.188s)
[784.561988] [132/133] (240s left) i915_query (engine-info)
[784.628067] Starting subtest: engine-info
[784.630442] Subtest engine-info: SUCCESS (0.005s)
[784.741703] [133/133] (240s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[784.800533] Starting subtest: yf-tiled-ccs-to-x-tiled
[784.804929] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[784.981180] Closing watchdogs
results: parsing output: 0/ for test: kms_psr2_sf
results: parsing output: 1/ for test: kms_flip
results: parsing output: 2/ for test: syncobj_wait
results: parsing output: 3/ for test: gem_eio
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_flip_scaled_crc
results: parsing output: 6/ for test: kms_big_fb
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: gem_exec_balancer
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: kms_plane_scaling
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_cursor_legacy
results: parsing output: 13/ for test: gem_exec_reloc
results: parsing output: 14/ for test: kms_atomic
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_addfb_basic
results: parsing output: 17/ for test: gem_softpin
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_ctx_engines
results: parsing output: 20/ for test: kms_addfb_basic
results: parsing output: 21/ for test: kms_psr
results: parsing output: 22/ for test: kms_cursor_legacy
results: parsing output: 23/ for test: kms_content_protection
results: parsing output: 24/ for test: kms_cursor_edge_walk
results: parsing output: 25/ for test: api_intel_allocator
results: parsing output: 26/ for test: syncobj_basic
results: parsing output: 27/ for test: kms_vblank
results: parsing output: 28/ for test: gem_exec_capture
results: parsing output: 29/ for test: fbdev
results: parsing output: 30/ for test: kms_cursor_crc
results: parsing output: 31/ for test: kms_addfb_basic
results: parsing output: 32/ for test: kms_atomic_transition
results: parsing output: 33/ for test: core_auth
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: kms_flip
results: parsing output: 36/ for test: kms_lease
results: parsing output: 37/ for test: kms_ccs
results: parsing output: 38/ for test: kms_fence_pin_leak
results: parsing output: 39/ for test: kms_busy
results: parsing output: 40/ for test: perf_pmu
results: parsing output: 41/ for test: kms_atomic_transition
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: kms_getfb
results: parsing output: 45/ for test: i915_hangman
results: parsing output: 46/ for test: gem_pxp
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_chamelium_frames
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: kms_atomic_interruptible
results: parsing output: 52/ for test: kms_plane
results: parsing output: 53/ for test: kms_tiled_display
results: parsing output: 54/ for test: gem_mmap_gtt
results: parsing output: 55/ for test: kms_ccs
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: gem_exec_schedule
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: gem_lmem_swapping
results: parsing output: 63/ for test: kms_psr2_sf
results: parsing output: 64/ for test: prime_busy
results: parsing output: 65/ for test: gem_render_copy
results: parsing output: 66/ for test: gem_render_copy
results: parsing output: 67/ for test: sysfs_heartbeat_interval
results: parsing output: 68/ for test: syncobj_timeline
results: parsing output: 69/ for test: kms_pm_rpm
results: parsing output: 70/ for test: gem_exec_fence
results: parsing output: 71/ for test: gem_exec_whisper
results: parsing output: 72/ for test: kms_pipe_crc_basic
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: prime_mmap
results: parsing output: 75/ for test: i915_pm_rc6_residency
results: parsing output: 76/ for test: perf
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: kms_pm_rpm
results: parsing output: 80/ for test: sw_sync
results: parsing output: 81/ for test: kms_psr
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_big_fb
results: parsing output: 84/ for test: gem_exec_reloc
results: parsing output: 85/ for test: gem_exec_schedule
results: parsing output: 86/ for test: kms_chamelium_color
results: parsing output: 87/ for test: gen9_exec_parse
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: dmabuf_sync_file
results: parsing output: 90/ for test: gen3_render_tiledx_blits
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: kms_color
results: parsing output: 93/ for test: gem_ctx_persistence
results: parsing output: 94/ for test: kms_plane
results: parsing output: 95/ for test: kms_ccs
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: gem_exec_reloc
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: syncobj_timeline
results: parsing output: 102/ for test: gem_ctx_isolation
results: parsing output: 103/ for test: kms_plane_multiple
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: gem_exec_flush
results: parsing output: 106/ for test: gem_readwrite
results: parsing output: 107/ for test: kms_joiner
results: parsing output: 108/ for test: i915_drm_fdinfo
results: parsing output: 109/ for test: kms_cursor_legacy
results: parsing output: 110/ for test: gem_exec_alignment
results: parsing output: 111/ for test: kms_chamelium_hpd
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_pipe_b_c_ivb
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: syncobj_wait
results: parsing output: 116/ for test: kms_flip
results: parsing output: 117/ for test: gem_exec_reloc
results: parsing output: 118/ for test: gem_exec_whisper
results: parsing output: 119/ for test: prime_mmap
results: parsing output: 120/ for test: kms_dp_link_training
results: parsing output: 121/ for test: kms_flip
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: syncobj_timeline
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: gem_mmap_wc
results: parsing output: 126/ for test: gem_watchdog
results: parsing output: 127/ for test: kms_psr
results: parsing output: 128/ for test: gem_tiled_blits
results: parsing output: 129/ for test: kms_cursor_crc
results: parsing output: 130/ for test: kms_setmode
results: parsing output: 131/ for test: i915_query
results: parsing output: 132/ for test: gem_render_copy
Done.