[57.127119] Initializing watchdogs
[57.127162]   /dev/watchdog0
[57.141665] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.146031] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.148360] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.151818] [001/133] (960s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[57.264443] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[57.268898] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[57.339048] [002/133] (960s left) kms_flip (flip-vs-suspend-interruptible)
[57.438233] Starting subtest: flip-vs-suspend-interruptible
[57.440600] Starting dynamic subtest: A-eDP1
[90.476371] Dynamic subtest A-eDP1: SUCCESS (4.306s)
[90.495078] Starting dynamic subtest: B-eDP1
[139.473410] Dynamic subtest B-eDP1: SUCCESS (4.534s)
[139.481967] Starting dynamic subtest: C-eDP1
[188.478131] Dynamic subtest C-eDP1: SUCCESS (4.726s)
[188.490877] Starting dynamic subtest: D-eDP1
[237.479586] Dynamic subtest D-eDP1: SUCCESS (4.551s)
[237.482111] Subtest flip-vs-suspend-interruptible: SUCCESS (18.125s)
[238.627792] [003/133] (779s left) syncobj_wait (single-wait-all-for-submit-submitted)
[238.693008] Starting subtest: single-wait-all-for-submit-submitted
[238.800518] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.108s)
[238.868492] [004/133] (778s left) gem_eio (throttle)
[238.998625] Starting subtest: throttle
[239.162892] Subtest throttle: SUCCESS (0.165s)
[239.305894] [005/133] (778s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-blt)
[239.396068] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-blt
[239.400100] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[239.497165] [006/133] (778s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[239.594733] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[239.596934] Starting dynamic subtest: pipe-A-default-mode
[240.773285] Dynamic subtest pipe-A-default-mode: SKIP (1.191s)
[240.777194] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.192s)
[240.913475] [007/133] (776s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[241.009422] Starting subtest: yf-tiled-16bpp-rotate-90
[241.013858] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[241.116343] [008/133] (776s left) kms_psr (fbc-pr-primary-mmap-cpu)
[241.204209] Starting subtest: fbc-pr-primary-mmap-cpu
[241.208711] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[241.292218] [009/133] (776s left) gem_exec_balancer (bonded-dual)
[241.367673] Starting subtest: bonded-dual
[241.371970] Subtest bonded-dual: SKIP (0.000s)
[241.694367] [010/133] (776s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[241.783124] Starting subtest: yf-tiled-32bpp-rotate-90
[241.787405] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[241.869850] [011/133] (776s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[241.954956] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[241.957104] Starting dynamic subtest: pipe-A
[242.290042] Dynamic subtest pipe-A: SUCCESS (0.338s)
[242.298969] Starting dynamic subtest: pipe-B
[243.727662] Dynamic subtest pipe-B: SUCCESS (1.448s)
[243.737902] Starting dynamic subtest: pipe-C
[245.139243] Dynamic subtest pipe-C: SUCCESS (1.420s)
[245.148700] Starting dynamic subtest: pipe-D
[246.562385] Dynamic subtest pipe-D: SUCCESS (1.430s)
[246.564460] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.638s)
[247.724918] [012/133] (770s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[247.844764] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[254.440119] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.617s)
[254.562982] [013/133] (763s left) kms_cursor_legacy (single-move)
[254.696144] Starting subtest: single-move
[254.698071] Starting dynamic subtest: pipe-A
[260.123890] Dynamic subtest pipe-A: SUCCESS (5.380s)
[260.134585] Starting dynamic subtest: pipe-B
[265.499623] Dynamic subtest pipe-B: SUCCESS (5.380s)
[265.510351] Starting dynamic subtest: pipe-C
[270.860178] Dynamic subtest pipe-C: SUCCESS (5.379s)
[270.871286] Starting dynamic subtest: pipe-D
[276.219666] Dynamic subtest pipe-D: SUCCESS (5.379s)
[276.230484] Starting dynamic subtest: all-pipes
[281.596491] Dynamic subtest all-pipes: SUCCESS (5.387s)
[281.598548] Subtest single-move: SUCCESS (26.914s)
[281.700482] [014/133] (736s left) gem_exec_reloc (basic-wc-read)
[281.779200] Subtest basic-wc-read: SKIP (0.000s)
[281.850594] [015/133] (736s left) kms_atomic (plane-invalid-params)
[281.954367] Starting subtest: plane-invalid-params
[281.956793] Starting dynamic subtest: pipe-A-eDP-1
[282.341129] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.388s)
[282.343534] Subtest plane-invalid-params: SUCCESS (0.389s)
[283.205092] [016/133] (735s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[283.305356] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[283.309780] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[283.396624] [017/133] (734s left) kms_addfb_basic (unused-handle)
[283.454359] Starting subtest: unused-handle
[283.456754] Subtest unused-handle: SUCCESS (0.000s)
[283.589828] [018/133] (734s left) gem_softpin (reverse)
[283.656605] Starting subtest: reverse
[283.663734] Subtest reverse: SUCCESS (0.007s)
[283.796642] [019/133] (734s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[283.897919] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[288.439903] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (4.550s)
[289.621907] [020/133] (728s left) gem_ctx_engines (independent)
[289.694364] Starting subtest: independent
[289.696743] Starting dynamic subtest: rcs0
[289.839682] Dynamic subtest rcs0: SUCCESS (0.143s)
[289.851719] Starting dynamic subtest: bcs0
[289.981485] Dynamic subtest bcs0: SUCCESS (0.142s)
[290.001432] Starting dynamic subtest: vcs0
[290.121423] Dynamic subtest vcs0: SUCCESS (0.139s)
[290.132117] Starting dynamic subtest: vcs1
[290.267321] Dynamic subtest vcs1: SUCCESS (0.145s)
[290.278742] Starting dynamic subtest: vecs0
[290.439589] Dynamic subtest vecs0: SUCCESS (0.172s)
[290.447965] Starting dynamic subtest: ccs0
[290.596397] Dynamic subtest ccs0: SUCCESS (0.156s)
[290.604854] Starting dynamic subtest: all-engines
[291.403265] Dynamic subtest all-engines: SUCCESS (0.806s)
[291.406530] Subtest independent: SUCCESS (1.709s)
[293.155731] [021/133] (725s left) kms_addfb_basic (bad-pitch-128)
[293.219917] Starting subtest: bad-pitch-128
[293.222164] Subtest bad-pitch-128: SUCCESS (0.000s)
[293.361349] [022/133] (725s left) kms_psr (psr-cursor-mmap-cpu)
[293.467954] Starting subtest: psr-cursor-mmap-cpu
[293.470308] Starting dynamic subtest: eDP-1
[295.206814] Dynamic subtest eDP-1: SUCCESS (1.741s)
[295.209227] Subtest psr-cursor-mmap-cpu: SUCCESS (1.741s)
[296.067029] [023/133] (722s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[296.182748] Starting subtest: cursorB-vs-flipB-atomic-transitions
[296.187571] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[296.324808] [024/133] (722s left) kms_content_protection (suspend-resume)
[296.450556] Starting subtest: suspend-resume
[296.455168] Subtest suspend-resume: SKIP (0.000s)
[296.581402] [025/133] (722s left) kms_cursor_edge_walk (256x256-top-bottom)
[296.671969] Starting subtest: 256x256-top-bottom
[296.674399] Starting dynamic subtest: pipe-A-eDP-1
[300.141842] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.473s)
[300.153219] Starting dynamic subtest: pipe-D-eDP-1
[303.270441] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.126s)
[303.272716] Subtest 256x256-top-bottom: SUCCESS (6.601s)
[304.070597] [026/133] (714s left) api_intel_allocator (reloc-allocator)
[304.125506] Starting subtest: reloc-allocator
[304.128347] Starting dynamic subtest: basic
[304.130633] Dynamic subtest basic: SUCCESS (0.004s)
[304.147741] Starting dynamic subtest: parallel-one
[304.150513] Dynamic subtest parallel-one: SUCCESS (0.009s)
[304.169003] Starting dynamic subtest: print
[304.171194] Dynamic subtest print: SUCCESS (0.000s)
[304.182197] Starting dynamic subtest: reuse
[304.184391] Dynamic subtest reuse: SUCCESS (0.000s)
[304.192369] Starting dynamic subtest: fork-reopen-allocator
[304.194515] Dynamic subtest fork-reopen-allocator: SUCCESS (0.012s)
[304.196749] Subtest reloc-allocator: SUCCESS (0.028s)
[304.261765] [027/133] (714s left) syncobj_basic (bad-destroy)
[304.309772] Starting subtest: bad-destroy
[304.312349] Subtest bad-destroy: SUCCESS (0.000s)
[304.399657] [028/133] (714s left) kms_vblank (ts-continuation-idle)
[304.494757] Starting subtest: ts-continuation-idle
[304.496633] Starting dynamic subtest: pipe-A-eDP-1
[304.891512] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.396s)
[304.907229] Starting dynamic subtest: pipe-D-eDP-1
[305.953469] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.061s)
[305.955963] Subtest ts-continuation-idle: SUCCESS (1.461s)
[306.820545] [029/133] (711s left) gem_exec_capture (pi)
[306.897951] Starting subtest: pi
[306.900255] Starting dynamic subtest: rcs0
[311.047905] Dynamic subtest rcs0: SUCCESS (4.151s)
[311.056528] Starting dynamic subtest: bcs0
[314.908598] Dynamic subtest bcs0: SUCCESS (3.858s)
[314.919586] Starting dynamic subtest: vcs0
[318.770705] Dynamic subtest vcs0: SUCCESS (3.861s)
[318.782730] Starting dynamic subtest: vcs1
[322.657785] Dynamic subtest vcs1: SUCCESS (3.886s)
[322.669380] Starting dynamic subtest: vecs0
[326.511153] Dynamic subtest vecs0: SUCCESS (3.852s)
[326.522990] Starting dynamic subtest: ccs0
[330.440675] Dynamic subtest ccs0: SUCCESS (3.929s)
[330.443333] Subtest pi: SUCCESS (23.546s)
[330.523561] [030/133] (688s left) fbdev (read)
[330.628374] Starting subtest: read
[331.158333] Subtest read: SUCCESS (0.528s)
[331.221358] [031/133] (687s left) kms_cursor_crc (cursor-random-256x256)
[331.510346] Starting subtest: cursor-random-256x256
[331.515266] Starting dynamic subtest: pipe-A-eDP-1
[334.906946] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.392s)
[334.938603] Starting dynamic subtest: pipe-D-eDP-1
[338.935110] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.008s)
[338.937466] Subtest cursor-random-256x256: SUCCESS (7.426s)
[339.782270] [032/133] (679s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[339.876989] Subtest addfb25-x-tiled-mismatch-legacy: SKIP (0.000s)
[339.957099] [033/133] (678s left) kms_atomic_transition (plane-all-modeset-transition)
[340.056709] Starting subtest: plane-all-modeset-transition
[340.060981] Subtest plane-all-modeset-transition: SKIP (0.000s)
[340.127593] [034/133] (678s left) core_auth (getclient-simple)
[340.164122] Starting subtest: getclient-simple
[340.185670] Subtest getclient-simple: SUCCESS (0.021s)
[340.288107] [035/133] (678s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[340.388465] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[341.201079] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.814s)
[341.359895] [036/133] (677s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[341.463369] Starting subtest: blocking-absolute-wf_vblank-interruptible
[341.465486] Starting dynamic subtest: A-eDP1
[350.170904] Dynamic subtest A-eDP1: SUCCESS (8.675s)
[350.179720] Starting dynamic subtest: B-eDP1
[358.907752] Dynamic subtest B-eDP1: SUCCESS (8.737s)
[358.919319] Starting dynamic subtest: C-eDP1
[367.597502] Dynamic subtest C-eDP1: SUCCESS (8.724s)
[367.606321] Starting dynamic subtest: D-eDP1
[376.371596] Dynamic subtest D-eDP1: SUCCESS (8.721s)
[376.373972] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.866s)
[377.544785] [037/133] (641s left) kms_lease (page-flip-implicit-plane)
[377.630962] Starting subtest: page-flip-implicit-plane
[377.633261] Starting dynamic subtest: pipe-A-eDP-1
[378.117702] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.487s)
[378.126978] Starting dynamic subtest: pipe-B-eDP-1
[379.290387] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.171s)
[379.299709] Starting dynamic subtest: pipe-C-eDP-1
[380.404253] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.112s)
[380.413122] Starting dynamic subtest: pipe-D-eDP-1
[381.537023] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[381.539338] Subtest page-flip-implicit-plane: SUCCESS (3.907s)
[382.389707] [038/133] (636s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[382.496718] Starting subtest: bad-aux-stride-y-tiled-ccs
[382.498862] Starting dynamic subtest: pipe-A-eDP-1
[382.520373] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[382.533403] Starting dynamic subtest: pipe-B-eDP-1
[382.553974] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[382.562972] Starting dynamic subtest: pipe-C-eDP-1
[382.584689] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[382.593293] Starting dynamic subtest: pipe-D-eDP-1
[382.615455] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[382.619853] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.005s)
[384.770725] [039/133] (634s left) kms_fence_pin_leak
[384.937361] [040/133] (634s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[385.034262] Starting subtest: extended-modeset-hang-oldfb-with-reset
[385.036411] Starting dynamic subtest: pipe-A
[396.637080] Dynamic subtest pipe-A: SUCCESS (11.600s)
[396.646666] Starting dynamic subtest: pipe-D
[408.908430] Dynamic subtest pipe-D: SUCCESS (12.268s)
[408.910615] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (23.875s)
[409.710168] [041/133] (609s left) perf_pmu (busy-idle-no-semaphores)
[409.753324] Starting subtest: busy-idle-no-semaphores
[409.755471] Starting dynamic subtest: rcs0
[410.404899] Dynamic subtest rcs0: SUCCESS (0.651s)
[410.417490] Starting dynamic subtest: bcs0
[411.055758] Dynamic subtest bcs0: SUCCESS (0.649s)
[411.066540] Starting dynamic subtest: vcs0
[411.707504] Dynamic subtest vcs0: SUCCESS (0.650s)
[411.720317] Starting dynamic subtest: vcs1
[413.717312] Dynamic subtest vcs1: SUCCESS (2.007s)
[413.727952] Starting dynamic subtest: vecs0
[415.700207] Dynamic subtest vecs0: SUCCESS (1.981s)
[415.710546] Starting dynamic subtest: ccs0
[417.751246] Dynamic subtest ccs0: SUCCESS (2.048s)
[417.753974] Subtest busy-idle-no-semaphores: SUCCESS (8.000s)
[417.864982] [042/133] (601s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[417.961173] Starting subtest: plane-use-after-nonblocking-unbind
[417.963477] Starting dynamic subtest: pipe-A-eDP-1
[418.031011] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.071s)
[418.329850] Starting dynamic subtest: pipe-B-eDP-1
[419.120763] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.800s)
[419.424147] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.462s)
[420.282777] [043/133] (599s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[420.391912] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[420.396188] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[420.486825] [044/133] (598s left) kms_psr (psr-sprite-render)
[420.586884] Starting subtest: psr-sprite-render
[420.589137] Starting dynamic subtest: eDP-1
[422.319508] Dynamic subtest eDP-1: SUCCESS (1.731s)
[422.321751] Subtest psr-sprite-render: SUCCESS (1.733s)
[423.147718] [045/133] (596s left) kms_getfb (getfb-handle-protection)
[423.252923] Starting subtest: getfb-handle-protection
[423.272746] Subtest getfb-handle-protection: SUCCESS (0.020s)
[423.409747] [046/133] (596s left) i915_hangman (engine-engine-error)
[423.489015] Starting subtest: engine-engine-error
[423.491395] Starting dynamic subtest: rcs0
[425.034057] Dynamic subtest rcs0: SUCCESS (1.544s)
[425.045898] Starting dynamic subtest: bcs0
[427.081356] Dynamic subtest bcs0: SUCCESS (2.045s)
[427.094596] Starting dynamic subtest: vcs0
[429.068058] Dynamic subtest vcs0: SUCCESS (1.984s)
[429.081501] Starting dynamic subtest: vcs1
[431.050297] Dynamic subtest vcs1: SUCCESS (1.979s)
[431.063380] Starting dynamic subtest: vecs0
[433.034120] Dynamic subtest vecs0: SUCCESS (1.981s)
[433.047013] Starting dynamic subtest: ccs0
[435.081711] Dynamic subtest ccs0: SUCCESS (2.045s)
[435.084454] Subtest engine-engine-error: SUCCESS (11.596s)
[435.210182] [047/133] (584s left) gem_pxp (display-protected-crc)
[435.350020] Starting subtest: display-protected-crc
[437.009777] Subtest display-protected-crc: SUCCESS (1.663s)
[437.121510] [048/133] (582s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[437.216787] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-gtt
[437.220755] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[437.327597] [049/133] (582s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[437.425401] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[437.429766] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[437.520065] [050/133] (582s left) kms_chamelium_frames (dp-frame-dump)
[437.910422] Subtest dp-frame-dump: SKIP (0.000s)
[438.744406] [051/133] (580s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180)
[438.852926] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180
[439.875462] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.023s)
[440.012169] [052/133] (579s left) kms_atomic_interruptible (legacy-pageflip)
[440.115904] Starting subtest: legacy-pageflip
[440.117728] Starting dynamic subtest: pipe-A-eDP-1
[447.514298] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.398s)
[447.516746] Subtest legacy-pageflip: SUCCESS (7.400s)
[448.341206] [053/133] (571s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping)
[448.434763] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping
[448.438771] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[448.511227] [054/133] (571s left) kms_tiled_display (basic-test-pattern-with-chamelium)
[448.614938] Subtest basic-test-pattern-with-chamelium: SKIP (0.000s)
[448.656941] [055/133] (571s left) gem_mmap_gtt (zero-extend)
[448.721540] Subtest zero-extend: SKIP (0.000s)
[448.777133] [056/133] (571s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[448.854842] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[448.857083] Starting dynamic subtest: pipe-A-eDP-1
[448.881836] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[448.893292] Starting dynamic subtest: pipe-B-eDP-1
[448.914025] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[448.923072] Starting dynamic subtest: pipe-C-eDP-1
[448.943850] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[448.952969] Starting dynamic subtest: pipe-D-eDP-1
[448.973850] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[448.977985] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[451.022723] [057/133] (568s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[451.147432] Starting subtest: flip-vs-cursor-crc-atomic
[451.799484] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.650s)
[452.631116] [058/133] (567s left) gem_exec_schedule (pi-ringfull)
[452.822097] Starting subtest: pi-ringfull
[452.824294] Starting dynamic subtest: rcs0
[452.913489] Dynamic subtest rcs0: SUCCESS (0.094s)
[452.930737] Starting dynamic subtest: bcs0
[452.941305] Dynamic subtest bcs0: SUCCESS (0.027s)
[452.956364] Starting dynamic subtest: vcs0
[452.968403] Dynamic subtest vcs0: SUCCESS (0.026s)
[452.984530] Starting dynamic subtest: vcs1
[452.995359] Dynamic subtest vcs1: SUCCESS (0.026s)
[453.009690] Starting dynamic subtest: vecs0
[453.022399] Dynamic subtest vecs0: SUCCESS (0.026s)
[453.036578] Starting dynamic subtest: ccs0
[453.048710] Dynamic subtest ccs0: SUCCESS (0.026s)
[453.050911] Subtest pi-ringfull: SUCCESS (0.230s)
[453.287832] [FACT gem_exec_schedule (pi-ringfull)] new: kernel.kmod_is_loaded.vgem: true
[453.291747] [059/133] (566s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[453.401083] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[453.404901] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[453.495679] [060/133] (566s left) kms_flip (dpms-off-confusion-interruptible)
[453.588300] Starting subtest: dpms-off-confusion-interruptible
[453.590539] Starting dynamic subtest: A-eDP1
[462.290428] Dynamic subtest A-eDP1: SUCCESS (8.656s)
[462.302663] Starting dynamic subtest: B-eDP1
[470.903753] Dynamic subtest B-eDP1: SUCCESS (8.624s)
[470.916662] Starting dynamic subtest: C-eDP1
[479.551318] Dynamic subtest C-eDP1: SUCCESS (8.636s)
[479.564415] Starting dynamic subtest: D-eDP1
[488.148785] Dynamic subtest D-eDP1: SUCCESS (8.644s)
[488.151639] Subtest dpms-off-confusion-interruptible: SUCCESS (34.571s)
[489.327381] [061/133] (530s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[489.430665] Starting subtest: x-tiled-16bpp-rotate-0
[490.799644] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.368s)
[490.943022] [062/133] (529s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[490.998491] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[491.104211] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.104s)
[491.192491] [063/133] (528s left) gem_lmem_swapping (verify-random)
[491.238945] Subtest verify-random: SKIP (0.000s)
[491.292869] [064/133] (528s left) kms_psr2_sf (pr-overlay-plane-update-sf-dmg-area)
[491.389517] Starting subtest: pr-overlay-plane-update-sf-dmg-area
[491.393655] Subtest pr-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[491.461374] [065/133] (528s left) prime_busy (after)
[491.529470] Starting subtest: after
[491.531763] Starting dynamic subtest: rcs0
[491.572748] Dynamic subtest rcs0: SUCCESS (0.043s)
[491.582826] Starting dynamic subtest: bcs0
[491.622601] Dynamic subtest bcs0: SUCCESS (0.049s)
[491.633809] Starting dynamic subtest: vcs0
[491.662630] Dynamic subtest vcs0: SUCCESS (0.040s)
[491.672372] Starting dynamic subtest: vcs1
[491.702898] Dynamic subtest vcs1: SUCCESS (0.040s)
[491.712782] Starting dynamic subtest: vecs0
[491.744769] Dynamic subtest vecs0: SUCCESS (0.041s)
[491.754767] Starting dynamic subtest: ccs0
[491.785258] Dynamic subtest ccs0: SUCCESS (0.040s)
[491.894016] Subtest after: SUCCESS (0.366s)
[491.985054] [066/133] (528s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[492.054461] Starting subtest: y-tiled-ccs-to-x-tiled
[492.059466] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[492.239819] [067/133] (528s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[492.300263] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[492.304464] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[492.481786] [068/133] (527s left) sysfs_heartbeat_interval (precise)
[492.543986] Starting subtest: precise
[492.546385] Starting dynamic subtest: vecs0
[500.070038] Dynamic subtest vecs0: SUCCESS (7.523s)
[500.082213] Starting dynamic subtest: ccs0
[503.116561] Dynamic subtest ccs0: SUCCESS (3.042s)
[503.130164] Starting dynamic subtest: vcs0
[511.075894] Dynamic subtest vcs0: SUCCESS (7.956s)
[511.092587] Starting dynamic subtest: bcs0
[512.223901] Dynamic subtest bcs0: SUCCESS (1.145s)
[512.237578] Starting dynamic subtest: rcs0
[513.270493] Dynamic subtest rcs0: SUCCESS (1.043s)
[513.284222] Starting dynamic subtest: vcs1
[521.129791] Dynamic subtest vcs1: SUCCESS (7.853s)
[521.132423] Subtest precise: SUCCESS (28.576s)
[521.206377] [069/133] (499s left) syncobj_timeline (invalid-transfer-illegal-handle)
[521.262717] Starting subtest: invalid-transfer-illegal-handle
[521.265008] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[521.369876] [070/133] (499s left) kms_pm_rpm (legacy-planes)
[523.309761] Starting subtest: legacy-planes
[523.312101] Starting dynamic subtest: plane-65
[531.583337] Dynamic subtest plane-65: SUCCESS (8.275s)
[531.595478] Starting dynamic subtest: plane-95
[539.848397] Dynamic subtest plane-95: SUCCESS (8.261s)
[539.860454] Starting dynamic subtest: plane-125
[548.118374] Dynamic subtest plane-125: SUCCESS (8.266s)
[548.131665] Starting dynamic subtest: plane-135
[556.392159] Dynamic subtest plane-135: SUCCESS (8.271s)
[556.394654] Subtest legacy-planes: SUCCESS (33.085s)
[556.782424] [071/133] (463s left) gem_exec_fence (syncobj-export)
[557.219830] Starting subtest: syncobj-export
[557.225812] Subtest syncobj-export: SUCCESS (0.004s)
[559.181469] [072/133] (461s left) gem_exec_whisper (basic-queues-forked)
[559.247577] Starting subtest: basic-queues-forked
[582.208566] Subtest basic-queues-forked: SUCCESS (22.958s)
[582.410773] [073/133] (438s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[582.502785] Starting subtest: read-crc-frame-sequence
[582.505151] Starting dynamic subtest: pipe-A-eDP-1
[583.232272] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.728s)
[583.242179] Starting dynamic subtest: pipe-B-eDP-1
[584.634894] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.402s)
[584.648986] Starting dynamic subtest: pipe-C-eDP-1
[586.023206] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.386s)
[586.033809] Starting dynamic subtest: pipe-D-eDP-1
[587.357608] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.331s)
[587.359490] Subtest read-crc-frame-sequence: SUCCESS (4.854s)
[588.193040] [074/133] (432s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt)
[588.283880] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt
[588.287779] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[588.376386] [075/133] (432s left) prime_mmap (test_aperture_limit)
[588.426192] Starting subtest: test_aperture_limit
[588.428435] Starting dynamic subtest: test_aperture_limit-smem
[588.435390] Dynamic subtest test_aperture_limit-smem: SKIP (0.007s)
[588.439532] Subtest test_aperture_limit: SKIP (0.008s)
[588.526592] [076/133] (432s left) i915_pm_rc6_residency (rc6-accuracy)
[588.580666] Starting subtest: rc6-accuracy
[588.582527] Starting dynamic subtest: gt0
[591.752587] Dynamic subtest gt0: SUCCESS (3.169s)
[591.764600] Starting dynamic subtest: gt1
[594.976480] Dynamic subtest gt1: SUCCESS (3.168s)
[594.979245] Subtest rc6-accuracy: SUCCESS (6.343s)
[595.022840] [077/133] (425s left) perf (global-sseu-config-invalid)
[595.169975] Starting subtest: global-sseu-config-invalid
[595.174136] Subtest global-sseu-config-invalid: SKIP (0.000s)
[595.212066] [078/133] (425s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[595.305067] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[599.537802] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.237s)
[600.678177] [079/133] (420s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[600.780796] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[600.785131] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[600.911014] [080/133] (419s left) kms_pm_rpm (cursor)
[602.431587] Starting subtest: cursor
[602.436948] Subtest cursor: SKIP (0.000s)
[603.206172] [081/133] (417s left) sw_sync (sync_multi_consumer)
[603.247581] Starting subtest: sync_multi_consumer
[603.697549] Subtest sync_multi_consumer: SUCCESS (0.454s)
[603.760705] [082/133] (417s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[603.855999] Starting subtest: fbc-psr-cursor-mmap-cpu
[603.858527] Starting dynamic subtest: eDP-1
[603.862464] Dynamic subtest eDP-1: SKIP (0.000s)
[603.866406] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[603.931829] [083/133] (417s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[604.042760] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[612.622917] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.584s)
[612.761466] [084/133] (408s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[612.865961] Starting subtest: y-tiled-8bpp-rotate-180
[612.870063] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[612.954980] [085/133] (408s left) gem_exec_reloc (basic-softpin)
[613.023540] Subtest basic-softpin: SKIP (0.000s)
[613.100798] [086/133] (408s left) gem_exec_schedule (timeslicing)
[613.173062] Starting subtest: timeslicing
[613.175277] Starting dynamic subtest: rcs0
[613.181798] Dynamic subtest rcs0: SUCCESS (0.021s)
[613.192375] Starting dynamic subtest: bcs0
[613.204126] Dynamic subtest bcs0: SUCCESS (0.021s)
[613.217466] Starting dynamic subtest: vcs0
[613.224724] Dynamic subtest vcs0: SUCCESS (0.020s)
[613.235391] Starting dynamic subtest: vcs1
[613.245966] Dynamic subtest vcs1: SUCCESS (0.020s)
[613.255778] Starting dynamic subtest: vecs0
[613.265974] Dynamic subtest vecs0: SUCCESS (0.019s)
[613.275767] Starting dynamic subtest: ccs0
[613.287210] Dynamic subtest ccs0: SUCCESS (0.021s)
[613.289408] Subtest timeslicing: SUCCESS (0.128s)
[615.123707] [087/133] (406s left) kms_chamelium_color (ctm-green-to-red)
[615.227966] Subtest ctm-green-to-red: SKIP (0.000s)
[615.275778] [088/133] (405s left) gen9_exec_parse (batch-zero-length)
[615.335641] Subtest batch-zero-length: SKIP (0.000s)
[615.411984] [089/133] (405s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt)
[615.511050] Starting subtest: psr-2p-primscrn-indfb-plflip-blt
[615.515299] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[615.611891] [090/133] (405s left) dmabuf_sync_file (export-multiwait)
[615.662639] Starting subtest: export-multiwait
[615.664993] Subtest export-multiwait: SUCCESS (0.000s)
[615.720930] [091/133] (405s left) gen3_render_tiledx_blits
[615.831719] [092/133] (405s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[615.930744] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[615.932956] Starting dynamic subtest: pipe-A-eDP-1
[615.955769] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[615.967716] Starting dynamic subtest: pipe-B-eDP-1
[615.990597] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[616.000978] Starting dynamic subtest: pipe-C-eDP-1
[616.021313] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[616.031114] Starting dynamic subtest: pipe-D-eDP-1
[616.052518] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[616.056852] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.017s)
[618.212585] [093/133] (403s left) kms_color (gamma)
[618.309842] Starting subtest: gamma
[618.312082] Starting dynamic subtest: pipe-A-eDP-1
[619.025347] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.713s)
[619.036161] Starting dynamic subtest: pipe-B-eDP-1
[620.401554] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.376s)
[620.411938] Starting dynamic subtest: pipe-C-eDP-1
[621.913943] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.509s)
[621.926202] Starting dynamic subtest: pipe-D-eDP-1
[623.140121] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.229s)
[623.142365] Subtest gamma: SUCCESS (4.831s)
[623.957450] [094/133] (397s left) gem_ctx_persistence (legacy-engines-mixed-process)
[624.029132] Starting subtest: legacy-engines-mixed-process
[624.031384] Starting dynamic subtest: render
[626.100533] Dynamic subtest render: SUCCESS (2.074s)
[626.113361] Starting dynamic subtest: bsd1
[628.080401] Dynamic subtest bsd1: SUCCESS (1.978s)
[628.091896] Starting dynamic subtest: bsd2
[630.070464] Dynamic subtest bsd2: SUCCESS (1.988s)
[630.082355] Starting dynamic subtest: blt
[632.042430] Dynamic subtest blt: SUCCESS (1.970s)
[632.052387] Starting dynamic subtest: vebox
[634.075502] Dynamic subtest vebox: SUCCESS (2.031s)
[634.077833] Subtest legacy-engines-mixed-process: SUCCESS (10.050s)
[634.206333] [095/133] (387s left) kms_plane (pixel-format-y-tiled-modifier)
[634.297407] Starting subtest: pixel-format-y-tiled-modifier
[634.301346] Subtest pixel-format-y-tiled-modifier: SKIP (0.000s)
[634.370342] [096/133] (387s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[634.462917] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[634.465075] Starting dynamic subtest: pipe-A-eDP-1
[634.522778] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[634.534757] Starting dynamic subtest: pipe-B-eDP-1
[634.592512] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[634.603746] Starting dynamic subtest: pipe-C-eDP-1
[634.661093] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[634.672447] Starting dynamic subtest: pipe-D-eDP-1
[634.729453] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[634.733354] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s)
[636.710648] [097/133] (384s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[636.812986] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[643.540953] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.734s)
[643.669304] [098/133] (377s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[643.773395] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[643.777633] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[643.864567] [099/133] (377s left) gem_exec_reloc (basic-scanout)
[643.922876] Subtest basic-scanout: SKIP (0.000s)
[643.996458] [100/133] (377s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[644.095904] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[644.099781] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[644.191556] [101/133] (377s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[644.303872] Starting subtest: cursor-vs-flip-toggle
[675.445262] Subtest cursor-vs-flip-toggle: SUCCESS (31.143s)
[676.246668] [102/133] (345s left) syncobj_timeline (single-wait-available-signaled)
[676.303759] Starting subtest: single-wait-available-signaled
[676.305965] Subtest single-wait-available-signaled: SUCCESS (0.000s)
[676.387840] [103/133] (345s left) gem_ctx_isolation (clean)
[676.447757] Starting subtest: clean
[676.449908] Starting dynamic subtest: rcs0
[676.505067] Dynamic subtest rcs0: SUCCESS (0.057s)
[676.515486] Starting dynamic subtest: bcs0
[676.560886] Dynamic subtest bcs0: SUCCESS (0.055s)
[676.574652] Starting dynamic subtest: vcs0
[676.625316] Dynamic subtest vcs0: SUCCESS (0.063s)
[676.637550] Starting dynamic subtest: vcs1
[678.120205] Dynamic subtest vcs1: SUCCESS (1.494s)
[678.132906] Starting dynamic subtest: vecs0
[680.130082] Dynamic subtest vecs0: SUCCESS (2.009s)
[680.143223] Starting dynamic subtest: ccs0
[682.113845] Dynamic subtest ccs0: SUCCESS (1.983s)
[682.116220] Subtest clean: SUCCESS (5.667s)
[682.349433] [104/133] (339s left) kms_plane_multiple (tiling-4)
[682.450771] Starting subtest: tiling-4
[682.452619] Starting dynamic subtest: pipe-A-eDP-1
[686.094439] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.643s)
[686.106310] Starting dynamic subtest: pipe-B-eDP-1
[690.429942] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.335s)
[690.440925] Starting dynamic subtest: pipe-C-eDP-1
[694.760079] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.330s)
[694.771130] Starting dynamic subtest: pipe-D-eDP-1
[699.105744] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.344s)
[699.107986] Subtest tiling-4: SUCCESS (16.658s)
[699.934484] [105/133] (322s left) syncobj_timeline (multi-wait-submitted-signaled)
[700.001293] Starting subtest: multi-wait-submitted-signaled
[700.003643] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[700.117489] [106/133] (321s left) gem_exec_flush (basic-uc-set-default)
[700.187108] Starting subtest: basic-uc-set-default
[705.667419] Subtest basic-uc-set-default: SUCCESS (5.455s)
[705.828198] [107/133] (316s left) gem_readwrite (new-obj)
[705.892607] Subtest new-obj: SKIP (0.000s)
[705.954660] [108/133] (316s left) kms_joiner (basic-ultra-joiner)
[706.057123] Starting subtest: basic-ultra-joiner
[706.061084] Subtest basic-ultra-joiner: SKIP (0.000s)
[706.140754] [109/133] (315s left) i915_drm_fdinfo (most-busy-idle-check-all)
[706.190020] Starting subtest: most-busy-idle-check-all
[706.191897] Starting dynamic subtest: rcs0
[706.196596] Dynamic subtest rcs0: SKIP (0.004s)
[706.209738] Starting dynamic subtest: bcs0
[706.213995] Dynamic subtest bcs0: SKIP (0.000s)
[706.223951] Starting dynamic subtest: vcs0
[706.228338] Dynamic subtest vcs0: SKIP (0.000s)
[706.238035] Starting dynamic subtest: vcs1
[706.242433] Dynamic subtest vcs1: SKIP (0.000s)
[706.252381] Starting dynamic subtest: vecs0
[706.256743] Dynamic subtest vecs0: SKIP (0.000s)
[706.266353] Starting dynamic subtest: ccs0
[706.270707] Dynamic subtest ccs0: SKIP (0.001s)
[706.275021] Subtest most-busy-idle-check-all: SKIP (0.012s)
[706.313336] [110/133] (315s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[706.442947] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[706.447254] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[706.590705] [111/133] (315s left) gem_exec_alignment (forked)
[706.647742] Starting subtest: forked
[745.664763] Subtest forked: SUCCESS (38.974s)
[746.006678] [112/133] (276s left) kms_chamelium_hpd (vga-hpd-fast)
[746.443592] Subtest vga-hpd-fast: SKIP (0.000s)
[747.217551] [113/133] (275s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[747.324776] Starting subtest: fbc-rgb101010-draw-pwrite
[752.725122] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.400s)
[753.859850] [114/133] (268s left) kms_pipe_b_c_ivb (pipe-b-dpms-off-modeset-pipe-c)
[753.923717] Subtest pipe-B-dpms-off-modeset-pipe-C: SKIP (0.000s)
[753.981819] [115/133] (268s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[754.080358] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[754.084441] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[754.170163] [116/133] (268s left) syncobj_wait (wait-any-interrupted)
[754.240635] Starting subtest: wait-any-interrupted
[754.343724] Subtest wait-any-interrupted: SUCCESS (0.101s)
[754.421316] [117/133] (268s left) kms_flip (2x-modeset-vs-vblank-race)
[754.504752] Starting subtest: 2x-modeset-vs-vblank-race
[754.513621] Subtest 2x-modeset-vs-vblank-race: SKIP (0.010s)
[754.598181] [118/133] (267s left) gem_exec_reloc (basic-cpu-gtt)
[754.669058] Subtest basic-cpu-gtt: SKIP (0.000s)
[754.756552] [119/133] (267s left) gem_exec_whisper (basic-contexts-all)
[754.818737] Starting subtest: basic-contexts-all
[766.094157] Subtest basic-contexts-all: SUCCESS (11.262s)
[766.277794] [120/133] (256s left) prime_mmap (test_correct_cpu_write)
[766.333660] Starting subtest: test_correct_cpu_write
[766.336087] Starting dynamic subtest: test_correct_cpu_write-smem
[766.338360] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[766.340542] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[766.438579] [121/133] (256s left) kms_dp_link_training (uhbr-sst)
[766.539770] Starting subtest: uhbr-sst
[766.548421] Subtest uhbr-sst: SKIP (0.000s)
[766.622485] [122/133] (256s left) kms_flip (basic-flip-vs-wf_vblank)
[766.717724] Starting subtest: basic-flip-vs-wf_vblank
[766.720091] Starting dynamic subtest: A-eDP1
[768.688353] Dynamic subtest A-eDP1: SUCCESS (1.971s)
[768.701220] Starting dynamic subtest: B-eDP1
[770.622545] Dynamic subtest B-eDP1: SUCCESS (1.931s)
[770.638993] Starting dynamic subtest: C-eDP1
[772.550763] Dynamic subtest C-eDP1: SUCCESS (1.924s)
[772.563111] Starting dynamic subtest: D-eDP1
[774.490754] Dynamic subtest D-eDP1: SUCCESS (1.938s)
[774.495482] Subtest basic-flip-vs-wf_vblank: SUCCESS (7.778s)
[775.724614] [123/133] (246s left) kms_frontbuffer_tracking (fbc-farfromfence-mmap-gtt)
[775.828799] Starting subtest: fbc-farfromfence-mmap-gtt
[775.832872] Subtest fbc-farfromfence-mmap-gtt: SKIP (0.000s)
[775.923389] [124/133] (246s left) syncobj_timeline (device-signal-unordered)
[775.982873] Starting subtest: device-signal-unordered
[775.985213] Subtest device-signal-unordered: SUCCESS (0.000s)
[776.076128] [125/133] (246s left) kms_psr (fbc-psr2-cursor-plane-move)
[776.169361] Starting subtest: fbc-psr2-cursor-plane-move
[776.171476] Starting dynamic subtest: eDP-1
[776.175402] Dynamic subtest eDP-1: SKIP (0.000s)
[776.179351] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[776.243343] [126/133] (246s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[776.298939] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[776.358675] [127/133] (246s left) gem_watchdog (default-physical)
[776.424988] Starting subtest: default-physical
[778.184180] Subtest default-physical: SUCCESS (1.769s)
[778.282341] [128/133] (244s left) kms_psr (pr-primary-page-flip)
[778.389365] Starting subtest: pr-primary-page-flip
[778.394081] Subtest pr-primary-page-flip: SKIP (0.000s)
[778.479376] [129/133] (244s left) gem_tiled_blits (basic)
[778.534194] Subtest basic: SKIP (0.000s)
[778.602049] [130/133] (244s left) kms_cursor_crc (cursor-offscreen-512x512)
[778.886459] Starting subtest: cursor-offscreen-512x512
[778.891308] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[779.033130] [131/133] (244s left) kms_setmode (basic)
[779.084755] Starting subtest: basic
[779.409074] Starting dynamic subtest: pipe-A-eDP-1
[781.531307] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.117s)
[781.874688] Starting dynamic subtest: pipe-B-eDP-1
[783.966307] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.096s)
[784.284192] Subtest basic: SUCCESS (5.196s)
[785.124598] [132/133] (237s left) i915_query (engine-info)
[785.191224] Starting subtest: engine-info
[785.193576] Subtest engine-info: SUCCESS (0.004s)
[785.307044] [133/133] (237s left) gem_render_copy (yf-tiled-ccs-to-x-tiled)
[785.362022] Starting subtest: yf-tiled-ccs-to-x-tiled
[785.366481] Subtest yf-tiled-ccs-to-x-tiled: SKIP (0.000s)
[785.544025] 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.