[57.932853] Initializing watchdogs
[57.932898]   /dev/watchdog0
[57.941092] [001/130] (960s left) gem_readwrite (new-obj)
[58.012411] Subtest new-obj: SKIP (0.000s)
[58.046856] [002/130] (960s left) gem_exec_fence (submit67)
[58.223854] Starting subtest: submit67
[58.228495] Subtest submit67: SKIP (0.000s)
[60.012164] [003/130] (958s left) gem_mmap_gtt (zero-extend)
[60.066885] Subtest zero-extend: SKIP (0.000s)
[60.109098] [004/130] (958s left) kms_pm_lpsp (screens-disabled)
[61.229356] Starting subtest: screens-disabled
[61.233663] Subtest screens-disabled: SKIP (0.000s)
[61.278103] [005/130] (957s left) kms_rotation_crc (sprite-rotation-270)
[61.386932] Starting subtest: sprite-rotation-270
[61.391452] Subtest sprite-rotation-270: SKIP (0.001s)
[61.439521] [006/130] (957s left) gem_exec_whisper (basic-normal-all)
[61.510380] Starting subtest: basic-normal-all
[63.792315] Subtest basic-normal-all: SUCCESS (2.282s)
[63.952071] [007/130] (954s left) prime_vgem (wait)
[64.014177] Starting subtest: wait
[64.016602] Starting dynamic subtest: rcs0
[64.044749] Dynamic subtest rcs0: SUCCESS (0.031s)
[64.050940] Starting dynamic subtest: bcs0
[64.106114] Dynamic subtest bcs0: SUCCESS (0.061s)
[64.112283] Starting dynamic subtest: vcs0
[64.148850] Dynamic subtest vcs0: SUCCESS (0.043s)
[64.155246] Starting dynamic subtest: vcs1
[64.195331] Dynamic subtest vcs1: SUCCESS (0.046s)
[64.201774] Starting dynamic subtest: vecs0
[64.244035] Dynamic subtest vecs0: SUCCESS (0.048s)
[64.250557] Starting dynamic subtest: ccs0
[64.291144] Dynamic subtest ccs0: SUCCESS (0.047s)
[64.293355] Subtest wait: SUCCESS (0.277s)
[64.396921] [008/130] (954s left) gem_exec_fence (parallel)
[64.574449] Starting subtest: parallel
[64.576951] Starting dynamic subtest: rcs0
[66.727276] Dynamic subtest rcs0: SUCCESS (2.153s)
[66.736105] Starting dynamic subtest: bcs0
[68.881781] Dynamic subtest bcs0: SUCCESS (2.154s)
[68.888832] Starting dynamic subtest: vcs0
[71.034792] Dynamic subtest vcs0: SUCCESS (2.153s)
[71.041754] Starting dynamic subtest: vcs1
[73.190224] Dynamic subtest vcs1: SUCCESS (2.155s)
[73.199261] Starting dynamic subtest: vecs0
[75.342099] Dynamic subtest vecs0: SUCCESS (2.151s)
[75.350922] Starting dynamic subtest: ccs0
[77.492318] Dynamic subtest ccs0: SUCCESS (2.150s)
[77.494247] Subtest parallel: SUCCESS (12.918s)
[78.049031] [009/130] (940s left) i915_selftest (mock)
[82.257335] Starting subtest: mock
[82.259641] Starting dynamic subtest: sanitycheck
[82.863349] Dynamic subtest sanitycheck: SUCCESS (0.606s)
[82.870593] Starting dynamic subtest: shmem
[83.460262] Dynamic subtest shmem: SUCCESS (0.597s)
[83.468204] Starting dynamic subtest: fence
[91.095984] Dynamic subtest fence: SUCCESS (7.634s)
[91.101909] Starting dynamic subtest: scatterlist
[92.692898] Dynamic subtest scatterlist: SUCCESS (1.597s)
[92.703205] Starting dynamic subtest: syncmap
[96.175817] Dynamic subtest syncmap: SUCCESS (3.482s)
[96.184334] Starting dynamic subtest: uncore
[96.727619] Dynamic subtest uncore: SUCCESS (0.552s)
[96.737368] Starting dynamic subtest: ring
[97.330097] Dynamic subtest ring: SUCCESS (0.602s)
[97.336097] Starting dynamic subtest: engine
[97.901555] Dynamic subtest engine: SUCCESS (0.571s)
[97.909667] Starting dynamic subtest: timelines
[101.490942] Dynamic subtest timelines: SUCCESS (3.589s)
[101.498687] Starting dynamic subtest: requests
[105.367808] Dynamic subtest requests: SUCCESS (3.877s)
[105.376388] Starting dynamic subtest: objects
[106.086882] Dynamic subtest objects: SUCCESS (0.719s)
[106.094963] Starting dynamic subtest: phys
[106.825764] Dynamic subtest phys: SUCCESS (0.738s)
[106.833708] Starting dynamic subtest: dmabuf
[107.575985] Dynamic subtest dmabuf: SUCCESS (0.750s)
[107.586463] Starting dynamic subtest: vma
[112.677842] Dynamic subtest vma: SUCCESS (5.101s)
[112.684709] Starting dynamic subtest: evict
[113.933964] Dynamic subtest evict: SUCCESS (1.255s)
[113.942102] Starting dynamic subtest: gtt
[117.030704] Dynamic subtest gtt: SUCCESS (3.097s)
[117.039216] Starting dynamic subtest: hugepages
[117.833743] Dynamic subtest hugepages: SUCCESS (0.803s)
[117.843563] Starting dynamic subtest: memory_region
[121.428654] Dynamic subtest memory_region: SUCCESS (3.595s)
[121.430878] Subtest mock: SUCCESS (39.171s)
[121.731953] [010/130] (896s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[123.310906] Starting subtest: x-tiled-16bpp-rotate-270
[123.344365] Subtest x-tiled-16bpp-rotate-270: SKIP (0.032s)
[123.453192] [011/130] (895s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary)
[123.567041] Starting subtest: psr-indfb-scaledprimary
[127.282840] Subtest psr-indfb-scaledprimary: SUCCESS (3.718s)
[127.394582] [012/130] (891s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[127.501413] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[127.505666] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[127.586637] [013/130] (891s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[127.683788] Starting subtest: plane-upscale-20x20-with-pixel-format
[127.685917] Starting dynamic subtest: pipe-A
[128.776759] Dynamic subtest pipe-A: SUCCESS (1.093s)
[128.786931] Starting dynamic subtest: pipe-B
[131.021254] Dynamic subtest pipe-B: SUCCESS (2.244s)
[131.028921] Starting dynamic subtest: pipe-C
[132.285770] Dynamic subtest pipe-C: SUCCESS (1.264s)
[132.293450] Starting dynamic subtest: pipe-D
[133.565164] Dynamic subtest pipe-D: SUCCESS (1.279s)
[133.567349] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.881s)
[134.784102] [014/130] (883s left) prime_mmap (test_errors)
[134.847182] Starting subtest: test_errors
[134.849259] Starting dynamic subtest: test_errors-smem
[134.951223] Dynamic subtest test_errors-smem: SUCCESS (0.104s)
[134.953929] Subtest test_errors: SUCCESS (0.104s)
[135.041166] [015/130] (883s left) gem_pread (exhaustion)
[135.106981] Subtest exhaustion: SKIP (0.000s)
[135.141244] [016/130] (883s left) gem_create (create-invalid-size)
[135.198115] Starting subtest: create-invalid-size
[135.200138] Subtest create-invalid-size: SUCCESS (0.000s)
[135.361345] [017/130] (883s left) kms_big_fb (x-tiled-addfb-size-overflow)
[135.463080] Starting subtest: x-tiled-addfb-size-overflow
[135.465128] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[135.534987] [018/130] (883s left) kms_addfb_basic (bad-pitch-128)
[135.595662] Starting subtest: bad-pitch-128
[135.598000] Subtest bad-pitch-128: SUCCESS (0.000s)
[135.716689] [019/130] (883s left) syncobj_timeline (reset-signaled)
[135.765890] Starting subtest: reset-signaled
[135.768156] Subtest reset-signaled: SUCCESS (0.000s)
[135.846815] [020/130] (882s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-pgflip-blt)
[135.946258] Starting subtest: fbc-2p-scndscrn-indfb-pgflip-blt
[135.950482] Subtest fbc-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[136.034909] [021/130] (882s left) kms_chamelium_color (ctm-0-25)
[136.142243] Subtest ctm-0-25: SKIP (0.000s)
[136.164542] [022/130] (882s left) kms_flip (2x-flip-vs-absolute-wf_vblank)
[136.260260] Starting subtest: 2x-flip-vs-absolute-wf_vblank
[136.271538] Subtest 2x-flip-vs-absolute-wf_vblank: SKIP (0.011s)
[136.329749] [023/130] (882s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-blt)
[136.433458] Starting subtest: psr-2p-primscrn-cur-indfb-draw-blt
[136.437202] Subtest psr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[136.514160] [024/130] (882s left) gem_pipe_control_store_loop (reused-buffer)
[136.586364] Starting subtest: reused-buffer
[138.739793] Subtest reused-buffer: SUCCESS (2.151s)
[138.850337] [025/130] (880s left) kms_flip (flip-vs-dpms-off-vs-modeset-interruptible)
[138.949438] Starting subtest: flip-vs-dpms-off-vs-modeset-interruptible
[138.951730] Starting dynamic subtest: A-eDP1
[142.771805] Dynamic subtest A-eDP1: SUCCESS (3.820s)
[142.780675] Starting dynamic subtest: B-eDP1
[146.239846] Dynamic subtest B-eDP1: SUCCESS (3.467s)
[146.248289] Starting dynamic subtest: C-eDP1
[149.697126] Dynamic subtest C-eDP1: SUCCESS (3.456s)
[149.704721] Starting dynamic subtest: D-eDP1
[153.146211] Dynamic subtest D-eDP1: SUCCESS (3.447s)
[153.148507] Subtest flip-vs-dpms-off-vs-modeset-interruptible: SUCCESS (14.196s)
[154.046330] [026/130] (864s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[154.161708] Starting subtest: 4-tiled-32bpp-rotate-180
[155.361075] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.200s)
[155.484380] [027/130] (863s left) gem_exec_schedule (semaphore-user)
[155.561697] Starting subtest: semaphore-user
[155.611059] Subtest semaphore-user: SUCCESS (0.056s)
[156.908291] [028/130] (862s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[157.006077] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[157.008393] Starting dynamic subtest: pipe-A-default-mode
[157.322765] Dynamic subtest pipe-A-default-mode: SKIP (0.313s)
[157.327129] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.314s)
[158.151711] [029/130] (860s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[158.265361] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[158.269721] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[158.364604] [030/130] (860s left) sw_sync (sync_merge_invalid)
[158.402270] Starting subtest: sync_merge_invalid
[158.434849] Subtest sync_merge_invalid: SUCCESS (0.032s)
[158.497612] [031/130] (860s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions)
[158.617022] Starting subtest: cursorA-vs-flipA-atomic-transitions
[169.796444] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.180s)
[170.686493] [032/130] (848s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[170.812216] Starting subtest: flip-vs-cursor-crc-legacy
[171.426510] Subtest flip-vs-cursor-crc-legacy: SUCCESS (0.613s)
[172.287211] [033/130] (846s left) gem_userptr_blits (sd-probe)
[172.346997] Starting subtest: sd-probe
[172.349408] Subtest sd-probe: SUCCESS (0.000s)
[172.456187] [034/130] (846s left) kms_force_connector_basic (force-edid)
[172.536684] Starting subtest: force-edid
[172.870686] Subtest force-edid: SUCCESS (0.334s)
[173.008183] [035/130] (846s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[173.102165] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs
[173.104442] Starting dynamic subtest: pipe-A-eDP-1
[173.125252] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[173.134484] Starting dynamic subtest: pipe-B-eDP-1
[173.153601] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[173.160178] Starting dynamic subtest: pipe-C-eDP-1
[173.179614] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[173.186727] Starting dynamic subtest: pipe-D-eDP-1
[173.206223] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[173.208109] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.007s)
[175.488246] [036/130] (843s left) dmabuf_sync_file (import-multiple-read-only)
[175.597036] Starting subtest: import-multiple-read-only
[175.599270] Subtest import-multiple-read-only: SUCCESS (0.002s)
[175.631501] [037/130] (843s left) gem_exec_params (cliprects_ptr-dirt)
[175.680439] Starting subtest: cliprects_ptr-dirt
[175.682683] Subtest cliprects_ptr-dirt: SUCCESS (0.000s)
[175.760148] [038/130] (843s left) kms_prop_blob (basic)
[175.812626] Starting subtest: basic
[175.815062] Subtest basic: SUCCESS (0.000s)
[175.902760] [039/130] (843s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[176.006496] Starting subtest: y-tiled-8bpp-rotate-270
[176.010582] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[176.102315] [040/130] (843s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[176.207024] Starting subtest: fbc-rgb565-draw-pwrite
[179.856882] Subtest fbc-rgb565-draw-pwrite: SUCCESS (3.653s)
[179.962299] [041/130] (839s left) gem_lmem_swapping (verify-random-ccs)
[180.010994] Subtest verify-random-ccs: SKIP (0.000s)
[180.035536] [042/130] (839s left) gem_busy (extended)
[180.224270] Starting subtest: extended
[180.226768] Starting dynamic subtest: rcs0
[180.308178] Dynamic subtest rcs0: SUCCESS (0.084s)
[180.318067] Starting dynamic subtest: bcs0
[180.350259] Dynamic subtest bcs0: SUCCESS (0.040s)
[180.359221] Starting dynamic subtest: vcs0
[181.915305] Dynamic subtest vcs0: SUCCESS (1.562s)
[181.925489] Starting dynamic subtest: vcs1
[183.897529] Dynamic subtest vcs1: SUCCESS (1.980s)
[183.906341] Starting dynamic subtest: vecs0
[185.883320] Dynamic subtest vecs0: SUCCESS (1.983s)
[185.893097] Starting dynamic subtest: ccs0
[185.930322] Dynamic subtest ccs0: SUCCESS (0.047s)
[185.933047] Subtest extended: SUCCESS (5.707s)
[186.158874] [043/130] (833s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[186.232416] Starting subtest: mixed-tiled-to-y-tiled-ccs
[186.237102] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[186.399697] [044/130] (832s left) kms_chamelium_edid (hdmi-edid-stress-resolution-4k)
[186.797069] Subtest hdmi-edid-stress-resolution-4k: SKIP (0.000s)
[187.688813] [045/130] (831s left) kms_display_modes (extended-mode-basic)
[187.787133] Starting subtest: extended-mode-basic
[187.791594] Subtest extended-mode-basic: SKIP (0.000s)
[187.813771] [046/130] (831s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted)
[187.872471] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted
[187.974950] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.102s)
[188.029659] [047/130] (831s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-pwrite)
[188.141596] Starting subtest: psr-1p-offscren-pri-indfb-draw-pwrite
[193.080797] Subtest psr-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (4.942s)
[193.185440] [048/130] (826s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[193.293404] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[193.297824] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[193.369380] [049/130] (825s left) gem_vm_create (invalid-destroy)
[193.432488] Starting subtest: invalid-destroy
[193.438320] Subtest invalid-destroy: SUCCESS (0.003s)
[193.537916] [050/130] (825s left) syncobj_timeline (wait-zero-handles)
[193.590347] Starting subtest: wait-zero-handles
[193.592683] Subtest wait-zero-handles: SUCCESS (0.000s)
[193.658784] [051/130] (825s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[193.782928] Starting subtest: cursorA-vs-flipB-toggle
[193.787514] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[193.903186] [052/130] (825s left) i915_pm_freq_api (freq-reset-multiple)
[193.969927] Starting subtest: freq-reset-multiple
[193.972269] Starting dynamic subtest: gt0
[203.840605] Dynamic subtest gt0: SUCCESS (9.867s)
[203.848473] Starting dynamic subtest: gt1
[211.134722] Dynamic subtest gt1: SUCCESS (7.294s)
[211.137446] Subtest freq-reset-multiple: SUCCESS (17.163s)
[211.193115] [053/130] (808s left) kms_cursor_edge_walk (128x128-top-bottom)
[211.289838] Starting subtest: 128x128-top-bottom
[211.292128] Starting dynamic subtest: pipe-A-eDP-1
[214.826031] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.534s)
[214.833321] Starting dynamic subtest: pipe-D-eDP-1
[218.089792] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.263s)
[218.092310] Subtest 128x128-top-bottom: SUCCESS (6.799s)
[218.952891] [054/130] (800s left) syncobj_wait (wait-any-complex)
[219.007183] Starting subtest: wait-any-complex
[219.068119] Subtest wait-any-complex: SUCCESS (0.062s)
[219.123208] [055/130] (800s left) gem_softpin (allocator-basic-reserve)
[219.188028] Starting subtest: allocator-basic-reserve
[219.251898] Subtest allocator-basic-reserve: SUCCESS (0.064s)
[219.391212] [056/130] (800s left) kms_plane_alpha_blend (constant-alpha-max)
[219.496445] Starting subtest: constant-alpha-max
[219.778298] Starting dynamic subtest: pipe-A-eDP-1
[220.667259] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.888s)
[220.675409] Starting dynamic subtest: pipe-D-eDP-1
[222.360051] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.692s)
[222.362481] Subtest constant-alpha-max: SUCCESS (2.864s)
[223.227071] [057/130] (796s left) syncobj_eventfd (timeline-wait-before-signal)
[223.295467] Starting subtest: timeline-wait-before-signal
[223.326921] Subtest timeline-wait-before-signal: SUCCESS (0.031s)
[223.382142] [058/130] (796s left) kms_vblank (ts-continuation-idle)
[223.490747] Starting subtest: ts-continuation-idle
[223.493049] Starting dynamic subtest: pipe-A-eDP-1
[223.876072] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.383s)
[223.887449] Starting dynamic subtest: pipe-D-eDP-1
[225.048086] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.169s)
[225.050502] Subtest ts-continuation-idle: SUCCESS (1.556s)
[225.920642] [059/130] (793s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[226.038521] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[228.757687] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (2.723s)
[228.884575] [060/130] (790s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[228.987882] Starting subtest: yf-tiled-64bpp-rotate-90
[228.991218] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[229.061722] [061/130] (790s left) gem_mmap_gtt (basic-small-copy-xy)
[229.119079] Subtest basic-small-copy-XY: SKIP (0.000s)
[229.151315] [062/130] (790s left) gem_mmap (short-mmap)
[229.212274] Subtest short-mmap: SKIP (0.000s)
[229.254887] [063/130] (790s left) gem_exec_reloc (basic-gtt-cpu-active)
[229.308485] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[229.387950] [064/130] (790s left) fbdev (nullptr)
[229.430169] Starting subtest: nullptr
[229.432190] Subtest nullptr: SUCCESS (0.000s)
[229.467527] [065/130] (790s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0)
[229.567888] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0
[229.571474] Subtest y-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[229.650374] [066/130] (789s left) gem_partial_pwrite_pread (write-display)
[229.712309] Subtest write-display: SKIP (0.000s)
[229.770181] [067/130] (789s left) gem_exec_balancer (full-pulse)
[229.832538] Starting subtest: full-pulse
[234.764908] Subtest full-pulse: SUCCESS (4.932s)
[235.027325] [068/130] (784s left) kms_feature_discovery (psr2)
[235.131935] Starting subtest: psr2
[235.134191] Subtest psr2: SUCCESS (0.000s)
[235.184300] [069/130] (784s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[235.239637] Starting subtest: wait-all-for-submit-delayed-submit
[235.340984] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[235.396430] [070/130] (784s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[235.493947] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[235.496398] Starting dynamic subtest: pipe-A
[235.602526] Dynamic subtest pipe-A: SKIP (0.106s)
[235.610408] Starting dynamic subtest: pipe-B
[236.864808] Dynamic subtest pipe-B: SKIP (1.262s)
[236.873211] Starting dynamic subtest: pipe-C
[238.139786] Dynamic subtest pipe-C: SUCCESS (1.279s)
[238.147802] Starting dynamic subtest: pipe-D
[239.420196] Dynamic subtest pipe-D: SUCCESS (1.280s)
[239.422551] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.929s)
[240.640483] [071/130] (779s left) gem_exec_schedule (reorder-wide)
[240.819890] Subtest reorder-wide: SKIP (0.000s)
[240.981478] [072/130] (778s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-blt)
[241.087705] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-blt
[246.198828] Subtest psr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (5.113s)
[246.301553] [073/130] (773s left) gem_mmap_gtt (basic-read-write)
[246.356490] Subtest basic-read-write: SKIP (0.000s)
[246.393980] [074/130] (773s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[246.489771] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[246.492085] Starting dynamic subtest: pipe-A-eDP-1
[246.514379] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[246.522517] Starting dynamic subtest: pipe-B-eDP-1
[246.543762] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[246.551827] Starting dynamic subtest: pipe-C-eDP-1
[246.573722] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[246.582523] Starting dynamic subtest: pipe-D-eDP-1
[246.604066] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[246.608398] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[248.833359] [075/130] (770s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[248.920138] Starting subtest: primary-4-tiled-reflect-x-180
[256.116433] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.196s)
[257.409822] [076/130] (762s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[257.508806] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[257.511141] Starting dynamic subtest: pipe-A-eDP-1
[257.532113] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[257.541906] Starting dynamic subtest: pipe-B-eDP-1
[257.561619] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[257.568969] Starting dynamic subtest: pipe-C-eDP-1
[257.588738] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[257.595982] Starting dynamic subtest: pipe-D-eDP-1
[257.615716] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[257.617958] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[259.840426] [077/130] (759s left) kms_content_protection (atomic)
[259.991672] Starting subtest: atomic
[263.527143] Subtest atomic: SKIP (3.533s)
[264.767539] [078/130] (755s left) kms_psr (pr-no-drrs)
[264.869329] Starting subtest: pr-no-drrs
[264.873846] Subtest pr-no-drrs: SKIP (0.000s)
[264.920200] [079/130] (754s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[265.310375] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[266.155924] [080/130] (753s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[271.297241] Starting subtest: pr-plane-move-sf-dmg-area
[271.302055] Subtest pr-plane-move-sf-dmg-area: SKIP (0.002s)
[272.191545] [081/130] (747s left) kms_vblank (query-forked-hang)
[272.280051] Starting subtest: query-forked-hang
[272.282339] Starting dynamic subtest: pipe-A-eDP-1
[298.640998] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.357s)
[298.654654] Starting dynamic subtest: pipe-D-eDP-1
[323.666689] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.021s)
[323.668624] Subtest query-forked-hang: SUCCESS (51.383s)
[324.547055] [082/130] (695s left) syncobj_timeline (invalid-multi-wait-unsubmitted-signaled)
[324.608624] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[324.610639] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[324.679096] [083/130] (695s left) gem_lmem_swapping (parallel-random-engines)
[324.726343] Subtest parallel-random-engines: SKIP (0.000s)
[324.753175] [084/130] (695s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[329.916311] Starting subtest: fbc-pr-overlay-plane-update-sf-dmg-area
[329.920853] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[330.817120] [085/130] (689s left) kms_lease (implicit-plane-lease)
[330.910156] Starting subtest: implicit-plane-lease
[330.914818] Subtest implicit-plane-lease: SUCCESS (0.003s)
[330.989636] [086/130] (688s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs)
[331.084045] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs
[331.086249] Starting dynamic subtest: pipe-A-eDP-1
[331.106317] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[331.115208] Starting dynamic subtest: pipe-B-eDP-1
[331.134692] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[331.141908] Starting dynamic subtest: pipe-C-eDP-1
[331.161259] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[331.168398] Starting dynamic subtest: pipe-D-eDP-1
[331.187930] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[331.189874] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[333.439332] [087/130] (686s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[333.497728] Starting subtest: legacy-engines-hostile-preempt
[333.500043] Starting dynamic subtest: render
[333.590936] Dynamic subtest render: SUCCESS (0.092s)
[333.602511] Starting dynamic subtest: bsd1
[334.932920] Dynamic subtest bsd1: SUCCESS (1.339s)
[334.944190] Starting dynamic subtest: bsd2
[336.904307] Dynamic subtest bsd2: SUCCESS (1.968s)
[336.914622] Starting dynamic subtest: blt
[337.031515] Dynamic subtest blt: SUCCESS (0.125s)
[337.042024] Starting dynamic subtest: vebox
[338.920622] Dynamic subtest vebox: SUCCESS (1.885s)
[338.923826] Subtest legacy-engines-hostile-preempt: SUCCESS (5.421s)
[338.978837] [088/130] (681s left) gem_exec_suspend (basic)
[339.039947] Starting subtest: basic
[339.042330] Starting dynamic subtest: smem
[340.890689] Dynamic subtest smem: SUCCESS (1.848s)
[340.893241] Subtest basic: SUCCESS (1.850s)
[341.061673] [089/130] (678s left) core_getversion (all-cards)
[341.099899] Starting subtest: all-cards
[342.826144] Subtest all-cards: SUCCESS (1.728s)
[342.846570] [090/130] (677s left) kms_addfb_basic (size-max)
[342.946079] Starting subtest: size-max
[342.948246] Subtest size-max: SUCCESS (0.000s)
[343.026949] [091/130] (677s left) i915_query (query-garbage)
[343.080769] Starting subtest: query-garbage
[343.082642] Subtest query-garbage: SUCCESS (0.000s)
[343.171164] [092/130] (676s left) kms_async_flips (invalid-async-flip)
[343.261292] Starting subtest: invalid-async-flip
[343.265603] Subtest invalid-async-flip: SKIP (0.000s)
[344.512599] [093/130] (675s left) gem_exec_schedule (fifo)
[344.587814] Starting subtest: fifo
[344.589772] Starting dynamic subtest: rcs0
[344.679331] Dynamic subtest rcs0: SUCCESS (0.100s)
[344.690532] Starting dynamic subtest: vcs0
[344.753680] Dynamic subtest vcs0: SUCCESS (0.074s)
[344.763487] Starting dynamic subtest: vcs1
[344.831303] Dynamic subtest vcs1: SUCCESS (0.077s)
[344.843177] Starting dynamic subtest: vecs0
[344.902329] Dynamic subtest vecs0: SUCCESS (0.070s)
[344.912631] Starting dynamic subtest: ccs0
[344.975692] Dynamic subtest ccs0: SUCCESS (0.073s)
[344.978076] Subtest fifo: SUCCESS (0.397s)
[345.968529] [094/130] (674s left) gem_mmap_gtt (basic-read)
[346.039188] Subtest basic-read: SKIP (0.000s)
[346.083165] [095/130] (674s left) kms_cursor_crc (cursor-alpha-transparent)
[346.188075] Starting subtest: cursor-alpha-transparent
[346.190266] Starting dynamic subtest: pipe-A-eDP-1
[346.774363] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.584s)
[346.782482] Starting dynamic subtest: pipe-D-eDP-1
[348.105251] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.328s)
[348.107659] Subtest cursor-alpha-transparent: SUCCESS (1.915s)
[348.973394] [096/130] (671s left) perf_pmu (busy-idle)
[349.012318] Starting subtest: busy-idle
[349.014668] Starting dynamic subtest: rcs0
[349.725583] Dynamic subtest rcs0: SUCCESS (0.715s)
[349.736485] Starting dynamic subtest: bcs0
[350.399661] Dynamic subtest bcs0: SUCCESS (0.670s)
[350.410125] Starting dynamic subtest: vcs0
[351.898977] Dynamic subtest vcs0: SUCCESS (1.498s)
[351.907842] Starting dynamic subtest: vcs1
[353.881881] Dynamic subtest vcs1: SUCCESS (1.981s)
[353.890624] Starting dynamic subtest: vecs0
[355.930469] Dynamic subtest vecs0: SUCCESS (2.046s)
[355.939185] Starting dynamic subtest: ccs0
[356.608697] Dynamic subtest ccs0: SUCCESS (0.677s)
[356.611172] Subtest busy-idle: SUCCESS (7.599s)
[356.717044] [097/130] (663s left) kms_dp_aux_dev
[357.065316] [098/130] (663s left) kms_psr (fbc-psr2-dpms)
[357.172392] Starting subtest: fbc-psr2-dpms
[357.174743] Starting dynamic subtest: eDP-1
[357.179071] Dynamic subtest eDP-1: SKIP (0.000s)
[357.183305] Subtest fbc-psr2-dpms: SKIP (0.001s)
[357.227280] [099/130] (663s left) kms_getfb (getfb2-handle-closed)
[357.278413] Starting subtest: getfb2-handle-closed
[357.280748] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[357.351300] [100/130] (662s left) kms_writeback (writeback-pixel-formats)
[357.456761] Subtest writeback-pixel-formats: SKIP (0.000s)
[357.481104] [101/130] (662s left) gem_partial_pwrite_pread (reads)
[357.541866] Subtest reads: SKIP (0.000s)
[357.594845] [102/130] (662s left) gem_ctx_isolation (clean)
[357.646699] Starting subtest: clean
[357.649161] Starting dynamic subtest: rcs0
[357.690600] Dynamic subtest rcs0: SUCCESS (0.044s)
[357.700478] Starting dynamic subtest: bcs0
[357.751657] Dynamic subtest bcs0: SUCCESS (0.060s)
[357.759523] Starting dynamic subtest: vcs0
[357.843928] Dynamic subtest vcs0: SUCCESS (0.091s)
[357.853273] Starting dynamic subtest: vcs1
[358.977504] Dynamic subtest vcs1: SUCCESS (1.133s)
[358.985577] Starting dynamic subtest: vecs0
[360.925562] Dynamic subtest vecs0: SUCCESS (1.947s)
[360.935711] Starting dynamic subtest: ccs0
[362.958890] Dynamic subtest ccs0: SUCCESS (2.033s)
[362.961308] Subtest clean: SUCCESS (5.312s)
[363.182212] [103/130] (657s left) kms_psr (fbc-pr-primary-render)
[363.275250] Starting subtest: fbc-pr-primary-render
[363.279594] Subtest fbc-pr-primary-render: SKIP (0.000s)
[363.326899] [104/130] (657s left) kms_psr (psr2-no-drrs)
[363.438768] Starting subtest: psr2-no-drrs
[363.441102] Starting dynamic subtest: eDP-1
[365.090071] Dynamic subtest eDP-1: SUCCESS (1.649s)
[365.092290] Subtest psr2-no-drrs: SUCCESS (1.650s)
[365.956336] [105/130] (654s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[366.086306] Starting subtest: 2x-long-flip-vs-cursor-atomic
[366.090877] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[366.211840] [106/130] (654s left) gem_exec_async (forked-writes)
[366.288452] Starting subtest: forked-writes
[366.290956] Starting dynamic subtest: rcs0
[366.323539] Dynamic subtest rcs0: SUCCESS (0.035s)
[366.334525] Starting dynamic subtest: bcs0
[366.351026] Dynamic subtest bcs0: SUCCESS (0.027s)
[366.359174] Starting dynamic subtest: vcs0
[366.379045] Dynamic subtest vcs0: SUCCESS (0.027s)
[366.393741] Starting dynamic subtest: vcs1
[366.407037] Dynamic subtest vcs1: SUCCESS (0.027s)
[366.415259] Starting dynamic subtest: vecs0
[366.433486] Dynamic subtest vecs0: SUCCESS (0.026s)
[366.442401] Starting dynamic subtest: ccs0
[366.462708] Dynamic subtest ccs0: SUCCESS (0.028s)
[366.465086] Subtest forked-writes: SUCCESS (0.174s)
[366.692012] [107/130] (653s left) gem_eio (in-flight-suspend)
[366.898127] Starting subtest: in-flight-suspend
[409.173990] Subtest in-flight-suspend: SUCCESS (13.024s)
[409.271830] [108/130] (640s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[409.377846] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[409.382091] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[409.465546] [109/130] (640s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[409.570582] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[409.574571] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[409.646105] [110/130] (640s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[409.757106] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[410.516139] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.759s)
[410.667731] [111/130] (639s left) i915_pm_rps (thresholds)
[410.722091] Starting subtest: thresholds
[410.726871] Subtest thresholds: SKIP (0.000s)
[410.783753] [112/130] (638s left) gem_ctx_isolation (nonpriv)
[410.854616] Starting subtest: nonpriv
[410.856792] Starting dynamic subtest: rcs0
[411.086863] Dynamic subtest rcs0: SUCCESS (0.231s)
[411.096810] Starting dynamic subtest: bcs0
[411.346320] Dynamic subtest bcs0: SUCCESS (0.259s)
[411.355295] Starting dynamic subtest: vcs0
[411.612979] Dynamic subtest vcs0: SUCCESS (0.266s)
[411.621633] Starting dynamic subtest: vcs1
[413.362299] Dynamic subtest vcs1: SUCCESS (1.745s)
[413.371315] Starting dynamic subtest: vecs0
[415.395048] Dynamic subtest vecs0: SUCCESS (2.030s)
[415.404910] Starting dynamic subtest: ccs0
[417.480264] Dynamic subtest ccs0: SUCCESS (2.083s)
[417.482762] Subtest nonpriv: SUCCESS (6.617s)
[417.723109] [113/130] (632s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[417.833095] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[422.702346] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.868s)
[422.841536] [114/130] (626s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[422.946731] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu
[422.950792] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[423.030188] [115/130] (626s left) perf (oa-exponents)
[423.159738] Starting subtest: oa-exponents
[423.161992] Starting dynamic subtest: 0-rcs0
[424.940753] Dynamic subtest 0-rcs0: SUCCESS (1.778s)
[424.955988] Starting dynamic subtest: 1-vcs1
[426.770730] Dynamic subtest 1-vcs1: SUCCESS (1.829s)
[426.773136] Subtest oa-exponents: SUCCESS (3.609s)
[426.800274] [116/130] (623s left) kms_getfb (getfb2-handle-not-fb)
[426.848283] Starting subtest: getfb2-handle-not-fb
[426.893388] Subtest getfb2-handle-not-fb: SUCCESS (0.046s)
[426.984663] [117/130] (622s left) gem_readwrite (write-bad-handle)
[427.038168] Subtest write-bad-handle: SKIP (0.000s)
[427.077814] [118/130] (622s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[427.296065] Starting subtest: cursor-rapid-movement-256x256
[427.301060] Starting dynamic subtest: pipe-A-eDP-1
[427.819819] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.517s)
[427.844101] Starting dynamic subtest: pipe-D-eDP-1
[429.142810] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.307s)
[429.144974] Subtest cursor-rapid-movement-256x256: SUCCESS (1.845s)
[430.018587] [119/130] (619s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[430.148795] Starting subtest: flip-vs-cursor-busy-crc-legacy
[430.939094] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.792s)
[431.804785] [120/130] (618s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-move)
[431.904948] Starting subtest: fbc-2p-primscrn-cur-indfb-move
[431.909018] Subtest fbc-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[432.006801] [121/130] (617s left) api_intel_allocator (fork-simple-once)
[432.072467] Starting subtest: fork-simple-once
[432.090924] Subtest fork-simple-once: SUCCESS (0.019s)
[432.213379] [122/130] (617s left) kms_plane_multiple (tiling-y)
[432.319780] Starting subtest: tiling-y
[432.324304] Subtest tiling-y: SKIP (0.000s)
[432.382429] [123/130] (617s left) i915_fb_tiling
[432.491552] [124/130] (617s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen)
[432.594693] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen
[432.598583] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[432.665187] [125/130] (617s left) kms_rmfb (close-fd)
[432.770269] Starting subtest: close-fd
[432.772267] Starting dynamic subtest: pipe-A-eDP-1
[432.886936] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.115s)
[432.896296] Starting dynamic subtest: pipe-B-eDP-1
[434.165936] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.278s)
[434.176915] Starting dynamic subtest: pipe-C-eDP-1
[435.373651] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.204s)
[435.382423] Starting dynamic subtest: pipe-D-eDP-1
[436.589305] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.215s)
[436.591644] Subtest close-fd: SUCCESS (3.816s)
[437.829364] [126/130] (612s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[437.895461] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[437.947361] [127/130] (612s left) syncobj_wait (signal)
[438.002975] Starting subtest: signal
[438.005377] Subtest signal: SUCCESS (0.000s)
[438.078764] [128/130] (612s left) kms_psr (fbc-psr-sprite-blt)
[438.175266] Starting subtest: fbc-psr-sprite-blt
[438.177623] Starting dynamic subtest: eDP-1
[438.182081] Dynamic subtest eDP-1: SKIP (0.000s)
[438.186176] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[438.237666] [129/130] (611s left) gem_basic (create-fd-close)
[438.292357] Starting subtest: create-fd-close
[438.296624] Subtest create-fd-close: SUCCESS (0.000s)
[438.381881] [130/130] (611s left) kms_rotation_crc (bad-tiling)
[438.475683] Starting subtest: bad-tiling
[438.480178] Subtest bad-tiling: SKIP (0.001s)
[438.532779] Closing watchdogs
Done.