[62.397308] Initializing watchdogs
[62.397369]   /dev/watchdog0
[62.412015] [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]
[62.416480] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[62.419275] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.422707] [001/131] (960s left) gen9_exec_parse (bb-chained)
[62.514325] Subtest bb-chained: SKIP (0.000s)
[62.556332] [002/131] (960s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[62.661938] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[62.664326] Starting dynamic subtest: pipe-A-eDP-1
[62.687147] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[62.695899] Starting dynamic subtest: pipe-B-eDP-1
[62.717882] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[62.724634] Starting dynamic subtest: pipe-C-eDP-1
[62.746157] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[62.753196] Starting dynamic subtest: pipe-D-eDP-1
[62.774884] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[62.779241] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[64.982353] [003/131] (958s left) kms_properties (crtc-properties-atomic)
[65.075663] Starting subtest: crtc-properties-atomic
[65.078057] Starting dynamic subtest: pipe-A-eDP-1
[65.547494] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.474s)
[65.556119] Starting dynamic subtest: pipe-B-eDP-1
[66.735092] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.188s)
[66.743965] Starting dynamic subtest: pipe-C-eDP-1
[67.869944] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.134s)
[67.879597] Starting dynamic subtest: pipe-D-eDP-1
[68.992087] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[68.994441] Subtest crtc-properties-atomic: SUCCESS (3.924s)
[69.819035] [004/131] (953s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[69.935607] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[69.939649] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[70.063950] [005/131] (953s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[70.166428] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[70.168814] Starting dynamic subtest: pipe-A-default-mode
[70.456461] Dynamic subtest pipe-A-default-mode: SKIP (0.287s)
[70.461158] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.288s)
[71.373500] [006/131] (951s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[71.718110] Starting subtest: psr-1p-primscrn-cur-indfb-move
[79.168083] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.458s)
[79.328789] [007/131] (943s left) kms_psr (psr2-primary-mmap-cpu)
[79.429306] Starting subtest: psr2-primary-mmap-cpu
[79.431985] Starting dynamic subtest: eDP-1
[81.149837] Dynamic subtest eDP-1: SUCCESS (1.720s)
[81.152222] Subtest psr2-primary-mmap-cpu: SUCCESS (1.721s)
[82.029309] [008/131] (941s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[82.130026] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[82.134223] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[82.260032] [009/131] (941s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[82.352388] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[82.356719] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[82.457187] [010/131] (940s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[82.565312] Starting subtest: 4-tiled-addfb-size-offset-overflow
[82.567257] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[82.693182] [011/131] (940s left) gem_eio (wait-immediate)
[82.857934] Starting subtest: wait-immediate
[83.086081] Subtest wait-immediate: SUCCESS (0.228s)
[83.231618] [012/131] (940s left) kms_psr2_sf (pr-overlay-primary-update-sf-dmg-area)
[88.208268] Starting subtest: pr-overlay-primary-update-sf-dmg-area
[88.212890] Subtest pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[89.061359] [013/131] (934s left) gem_exec_alignment (many)
[89.122476] Starting subtest: many
[110.684708] Subtest many: SUCCESS (21.508s)
[111.865117] [014/131] (911s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[112.023182] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[116.656914] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (4.639s)
[117.877850] [015/131] (905s left) kms_psr (fbc-psr-cursor-blt)
[117.965044] Starting subtest: fbc-psr-cursor-blt
[117.967429] Starting dynamic subtest: eDP-1
[117.971711] Dynamic subtest eDP-1: SKIP (0.000s)
[117.975772] Subtest fbc-psr-cursor-blt: SKIP (0.001s)
[118.051859] [016/131] (905s left) gem_fence_thrash (bo-write-verify-threaded-y)
[118.082746] Starting subtest: bo-write-verify-threaded-y
[118.111979] Subtest bo-write-verify-threaded-y: SKIP (0.026s)
[118.170110] [017/131] (905s left) fbdev (unaligned-read)
[118.215549] Starting subtest: unaligned-read
[118.231613] Subtest unaligned-read: SUCCESS (0.016s)
[118.294106] [018/131] (905s left) kms_lease (lease-unleased-crtc)
[118.395472] Starting subtest: lease-unleased-crtc
[118.397567] Starting dynamic subtest: pipe-A-eDP-1
[118.693596] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.295s)
[118.703766] Starting dynamic subtest: pipe-B-eDP-1
[119.415823] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.723s)
[119.423642] Starting dynamic subtest: pipe-C-eDP-1
[119.425906] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[119.433843] Starting dynamic subtest: pipe-D-eDP-1
[119.435757] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.005s)
[119.437653] Subtest lease-unleased-crtc: SUCCESS (1.034s)
[119.838646] [019/131] (903s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[119.943502] Starting subtest: x-tiled-16bpp-rotate-0
[121.346330] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.402s)
[121.476914] [020/131] (902s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[126.505712] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[126.510244] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.002s)
[127.353841] [021/131] (896s left) gem_exec_balancer (bonded-false-hang)
[127.625054] Starting subtest: bonded-false-hang
[127.629914] Subtest bonded-false-hang: SKIP (0.000s)
[127.756198] [022/131] (896s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[127.849642] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[134.498745] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (6.649s)
[134.668315] [023/131] (889s left) gem_exec_params (mmapped)
[134.719481] Starting subtest: mmapped
[134.721881] Subtest mmapped: SUCCESS (0.003s)
[134.887453] [024/131] (888s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[134.992129] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[142.155248] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.166s)
[143.326434] [025/131] (880s left) gem_mmap_gtt (cpuset-big-copy)
[143.383784] Subtest cpuset-big-copy: SKIP (0.000s)
[143.441144] [026/131] (880s left) kms_psr (fbc-psr2-sprite-blt)
[143.529034] Starting subtest: fbc-psr2-sprite-blt
[143.531239] Starting dynamic subtest: eDP-1
[143.535411] Dynamic subtest eDP-1: SKIP (0.000s)
[143.539589] Subtest fbc-psr2-sprite-blt: SKIP (0.001s)
[143.618370] [027/131] (880s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[143.707373] Starting subtest: flip-primary-invalidate-overlay
[155.317974] Subtest flip-primary-invalidate-overlay: SUCCESS (11.612s)
[155.509026] [028/131] (868s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[155.611526] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[155.615335] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[155.724279] [029/131] (868s left) kms_getfb (getfb-handle-protection)
[155.824517] Starting subtest: getfb-handle-protection
[155.849826] Subtest getfb-handle-protection: SUCCESS (0.026s)
[155.965826] [030/131] (868s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[156.030823] Starting subtest: multi-wait-all-for-submit-submitted
[156.135510] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.102s)
[156.224550] [031/131] (867s left) kms_flip (2x-plain-flip-ts-check)
[156.323918] Starting subtest: 2x-plain-flip-ts-check
[156.333817] Subtest 2x-plain-flip-ts-check: SKIP (0.011s)
[156.423047] [032/131] (867s left) gem_pxp (reject-modify-context-protection-on)
[156.538645] Starting subtest: reject-modify-context-protection-on
[156.540935] Subtest reject-modify-context-protection-on: SUCCESS (0.002s)
[156.661763] [033/131] (867s left) kms_chamelium_audio (dp-audio-edid)
[157.052837] Subtest dp-audio-edid: SKIP (0.000s)
[157.887629] [034/131] (866s left) sysfs_timeslice_duration (timeout)
[157.960391] Starting subtest: timeout
[157.962666] Starting dynamic subtest: vecs0
[167.909404] Dynamic subtest vecs0: SUCCESS (9.944s)
[167.924035] Starting dynamic subtest: ccs0
[169.346126] Dynamic subtest ccs0: SUCCESS (1.436s)
[169.359347] Starting dynamic subtest: vcs0
[178.921490] Dynamic subtest vcs0: SUCCESS (9.572s)
[178.932722] Starting dynamic subtest: bcs0
[179.548040] Dynamic subtest bcs0: SUCCESS (0.626s)
[179.558532] Starting dynamic subtest: rcs0
[180.957294] Dynamic subtest rcs0: SUCCESS (1.408s)
[180.965460] Starting dynamic subtest: vcs1
[190.956541] Dynamic subtest vcs1: SUCCESS (9.996s)
[190.958962] Subtest timeout: SUCCESS (32.992s)
[191.041760] [035/131] (833s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[191.098784] Starting subtest: invalid-bsd2-flag-on-vebox
[191.101213] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[191.198593] [036/131] (833s left) gem_exercise_blt (fast-copy-emit)
[191.263529] Starting subtest: fast-copy-emit
[191.265790] Starting dynamic subtest: linear-smem-smem-emit
[191.289030] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.025s)
[191.299659] Starting dynamic subtest: xmajor-smem-smem-emit
[191.308058] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.019s)
[191.316165] Starting dynamic subtest: tile4-smem-smem-emit
[191.326200] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.018s)
[191.334400] Starting dynamic subtest: tile64-smem-smem-emit
[191.344565] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.018s)
[191.346741] Subtest fast-copy-emit: SUCCESS (0.081s)
[191.486590] [037/131] (832s left) kms_plane_multiple (2x-tiling-4)
[191.587935] Starting subtest: 2x-tiling-4
[191.592218] Subtest 2x-tiling-4: SKIP (0.000s)
[191.671938] [038/131] (832s left) dmabuf_sync_file (export-before-signal)
[191.791042] Starting subtest: export-before-signal
[191.792975] Subtest export-before-signal: SUCCESS (0.001s)
[191.856326] [FACT dmabuf_sync_file (export-before-signal)] new: kernel.kmod_is_loaded.vgem: true
[191.860051] [039/131] (832s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[191.956650] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[191.958899] Starting dynamic subtest: pipe-A-eDP-1
[192.007674] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[192.015622] Starting dynamic subtest: pipe-B-eDP-1
[192.061314] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[192.069059] Starting dynamic subtest: pipe-C-eDP-1
[192.114411] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[192.121890] Starting dynamic subtest: pipe-D-eDP-1
[192.165863] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[192.169334] Subtest crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.009s)
[194.287463] [040/131] (830s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180)
[194.397272] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180
[194.401800] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[194.508652] [041/131] (829s left) i915_pm_rpm (gem-pread)
[196.051391] Starting subtest: gem-pread
[198.472070] Subtest gem-pread: SUCCESS (2.423s)
[198.600222] [042/131] (825s left) prime_vgem (fence-wait)
[198.695562] Starting subtest: fence-wait
[198.697827] Starting dynamic subtest: rcs0
[199.746064] Dynamic subtest rcs0: SUCCESS (1.049s)
[199.757020] Starting dynamic subtest: bcs0
[200.803066] Dynamic subtest bcs0: SUCCESS (1.056s)
[200.813833] Starting dynamic subtest: vcs0
[201.847620] Dynamic subtest vcs0: SUCCESS (1.042s)
[201.857104] Starting dynamic subtest: vcs1
[202.887694] Dynamic subtest vcs1: SUCCESS (1.038s)
[202.898777] Starting dynamic subtest: vecs0
[203.929035] Dynamic subtest vecs0: SUCCESS (1.039s)
[203.940368] Starting dynamic subtest: ccs0
[204.979364] Dynamic subtest ccs0: SUCCESS (1.042s)
[204.981468] Subtest fence-wait: SUCCESS (6.276s)
[205.110352] [043/131] (819s left) syncobj_timeline (invalid-wait-bad-flags)
[205.168311] Starting subtest: invalid-wait-bad-flags
[205.170218] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[205.285805] [044/131] (819s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[205.381224] Starting subtest: fbcpsr-rgb565-draw-render
[212.420391] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.041s)
[212.578365] [045/131] (812s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[212.644382] Starting subtest: wait-all-for-submit-delayed-submit
[212.749225] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[212.852209] [046/131] (811s left) gem_exec_flush (basic-wb-rw-default)
[212.917911] Starting subtest: basic-wb-rw-default
[218.401740] Subtest basic-wb-rw-default: SUCCESS (5.450s)
[218.566541] [047/131] (806s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[218.671324] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[218.675696] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[218.795533] [048/131] (806s left) kms_pipe_crc_basic (suspend-read-crc)
[218.883678] Starting subtest: suspend-read-crc
[218.885885] Starting dynamic subtest: pipe-A-eDP-1
[237.162360] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.082s)
[237.174198] Starting dynamic subtest: pipe-B-eDP-1
[255.855493] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.269s)
[255.866347] Starting dynamic subtest: pipe-C-eDP-1
[273.863313] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.158s)
[273.873930] Starting dynamic subtest: pipe-D-eDP-1
[291.812191] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.133s)
[291.814383] Subtest suspend-read-crc: SUCCESS (12.648s)
[298.670634] [049/131] (732s left) gem_exec_reloc (basic-gtt-wc)
[298.741149] Subtest basic-gtt-wc: SKIP (0.000s)
[298.834194] [050/131] (732s left) gem_watchdog (default-physical)
[298.909111] Starting subtest: default-physical
[300.667875] Subtest default-physical: SUCCESS (1.791s)
[300.776772] [051/131] (730s left) gem_fence_thrash (bo-write-verify-threaded-x)
[300.812381] Starting subtest: bo-write-verify-threaded-x
[300.834814] Subtest bo-write-verify-threaded-x: SKIP (0.020s)
[300.901599] [052/131] (730s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt)
[300.996610] Starting subtest: fbc-rgb565-draw-blt
[305.231856] Subtest fbc-rgb565-draw-blt: SUCCESS (4.265s)
[306.407671] [053/131] (724s left) kms_universal_plane (disable-primary-vs-flip)
[306.496594] Starting subtest: disable-primary-vs-flip
[306.498922] Starting dynamic subtest: pipe-A-eDP-1
[308.039027] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.548s)
[308.048289] Starting dynamic subtest: pipe-B-eDP-1
[310.211833] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.177s)
[310.222222] Starting dynamic subtest: pipe-C-eDP-1
[312.456890] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.250s)
[312.466256] Starting dynamic subtest: pipe-D-eDP-1
[314.621053] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.166s)
[314.623696] Subtest disable-primary-vs-flip: SUCCESS (8.149s)
[315.432544] [054/131] (715s left) kms_cursor_edge_walk (128x128-top-edge)
[315.525114] Starting subtest: 128x128-top-edge
[315.527323] Starting dynamic subtest: pipe-A-eDP-1
[318.979440] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.458s)
[318.989569] Starting dynamic subtest: pipe-D-eDP-1
[322.083590] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.106s)
[322.085939] Subtest 128x128-top-edge: SUCCESS (6.567s)
[322.916057] [055/131] (708s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[323.045830] Starting subtest: flip-vs-cursor-busy-crc-legacy
[323.856554] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.812s)
[324.732576] [056/131] (706s left) kms_flip (modeset-vs-vblank-race-interruptible)
[324.842879] Starting subtest: modeset-vs-vblank-race-interruptible
[324.845292] Starting dynamic subtest: A-eDP1
[329.535206] Dynamic subtest A-eDP1: SUCCESS (4.695s)
[329.548055] Starting dynamic subtest: B-eDP1
[334.159056] Dynamic subtest B-eDP1: SUCCESS (4.624s)
[334.170677] Starting dynamic subtest: C-eDP1
[338.689083] Dynamic subtest C-eDP1: SUCCESS (4.531s)
[338.699320] Starting dynamic subtest: D-eDP1
[343.218181] Dynamic subtest D-eDP1: SUCCESS (4.531s)
[343.220409] Subtest modeset-vs-vblank-race-interruptible: SUCCESS (18.391s)
[344.404583] [057/131] (686s left) perf_pmu (busy-start)
[344.449843] Starting subtest: busy-start
[344.452168] Starting dynamic subtest: rcs0
[346.999711] Dynamic subtest rcs0: SUCCESS (2.552s)
[347.012090] Starting dynamic subtest: bcs0
[349.570383] Dynamic subtest bcs0: SUCCESS (2.549s)
[349.581713] Starting dynamic subtest: vcs0
[353.163277] Dynamic subtest vcs0: SUCCESS (3.612s)
[353.175212] Starting dynamic subtest: vcs1
[357.196599] Dynamic subtest vcs1: SUCCESS (4.032s)
[357.209223] Starting dynamic subtest: vecs0
[361.163764] Dynamic subtest vecs0: SUCCESS (3.966s)
[361.180885] Starting dynamic subtest: ccs0
[363.732140] Dynamic subtest ccs0: SUCCESS (2.548s)
[363.734250] Subtest busy-start: SUCCESS (19.274s)
[363.863146] [058/131] (667s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[363.961979] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[370.534537] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.579s)
[370.693173] [059/131] (660s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[370.795302] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[370.797651] Starting dynamic subtest: pipe-A-eDP-1
[387.441103] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.815s)
[387.452107] Starting dynamic subtest: pipe-B-eDP-1
[405.407248] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.826s)
[405.417321] Starting dynamic subtest: pipe-C-eDP-1
[423.335931] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.717s)
[423.346130] Starting dynamic subtest: pipe-D-eDP-1
[441.387821] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.700s)
[441.390556] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (10.063s)
[447.642711] [060/131] (588s left) kms_flip (2x-flip-vs-expired-vblank)
[447.765549] Starting subtest: 2x-flip-vs-expired-vblank
[447.778723] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s)
[447.884366] [061/131] (588s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[448.008777] Starting subtest: basic-flip-before-cursor-atomic
[448.932908] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.930s)
[449.790167] [062/131] (586s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-pgflip-blt)
[449.892873] Starting subtest: fbc-2p-scndscrn-shrfb-pgflip-blt
[449.898043] Subtest fbc-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[449.991877] [063/131] (586s left) gem_ctx_param (basic)
[450.043222] Starting subtest: basic
[450.045955] Subtest basic: SUCCESS (0.001s)
[450.151305] [064/131] (586s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[450.250715] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[450.253024] Starting dynamic subtest: pipe-A-default-mode
[450.553901] Dynamic subtest pipe-A-default-mode: SKIP (0.304s)
[450.559206] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.306s)
[451.409700] [065/131] (585s left) gem_create (create-ext-placement-each)
[451.575063] Starting subtest: create-ext-placement-each
[451.577978] Subtest create-ext-placement-each: SUCCESS (0.000s)
[451.698157] [066/131] (584s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[452.092729] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[452.906392] [067/131] (583s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[453.003749] Starting subtest: extended-pageflip-modeset-hang-oldfb
[453.006413] Starting dynamic subtest: pipe-A
[460.877685] Dynamic subtest pipe-A: SUCCESS (7.900s)
[460.891208] Starting dynamic subtest: pipe-D
[469.502380] Dynamic subtest pipe-D: SUCCESS (8.632s)
[469.505343] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.538s)
[470.361443] [068/131] (566s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[470.411784] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[470.414347] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[470.531858] [069/131] (566s left) api_intel_bb (add-remove-objects)
[470.579725] Starting subtest: add-remove-objects
[470.586761] Subtest add-remove-objects: SUCCESS (0.009s)
[470.730037] [070/131] (566s left) gem_exec_fence (keep-in-fence)
[470.899705] Starting subtest: keep-in-fence
[470.902652] Starting dynamic subtest: rcs0
[470.986436] Dynamic subtest rcs0: SUCCESS (0.086s)
[470.998786] Starting dynamic subtest: bcs0
[471.041078] Dynamic subtest bcs0: SUCCESS (0.053s)
[471.052554] Starting dynamic subtest: vcs0
[472.704851] Dynamic subtest vcs0: SUCCESS (1.662s)
[472.718072] Starting dynamic subtest: vcs1
[474.753790] Dynamic subtest vcs1: SUCCESS (2.047s)
[474.766659] Starting dynamic subtest: vecs0
[476.789298] Dynamic subtest vecs0: SUCCESS (1.982s)
[476.802018] Starting dynamic subtest: ccs0
[476.967361] Dynamic subtest ccs0: SUCCESS (0.231s)
[476.970360] Subtest keep-in-fence: SUCCESS (6.074s)
[478.826317] [071/131] (558s left) api_intel_bb (destroy-bb)
[478.890749] Starting subtest: destroy-bb
[478.904983] Subtest destroy-bb: SUCCESS (0.014s)
[479.015719] [072/131] (557s left) kms_psr (fbc-pr-cursor-render)
[479.120921] Starting subtest: fbc-pr-cursor-render
[479.125624] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[479.205374] [073/131] (557s left) gem_softpin (safe-alignment)
[479.257591] Starting subtest: safe-alignment
[479.282838] Subtest safe-alignment: SUCCESS (0.026s)
[480.755649] [074/131] (556s left) i915_hangman (gt-engine-error)
[480.823945] Starting subtest: gt-engine-error
[480.826367] Starting dynamic subtest: rcs0
[482.652595] Dynamic subtest rcs0: SUCCESS (1.828s)
[482.665217] Starting dynamic subtest: bcs0
[484.700081] Dynamic subtest bcs0: SUCCESS (2.044s)
[484.712718] Starting dynamic subtest: vcs0
[486.684305] Dynamic subtest vcs0: SUCCESS (1.981s)
[486.696948] Starting dynamic subtest: vcs1
[488.668758] Dynamic subtest vcs1: SUCCESS (1.981s)
[488.680073] Starting dynamic subtest: vecs0
[490.652222] Dynamic subtest vecs0: SUCCESS (1.980s)
[490.665321] Starting dynamic subtest: ccs0
[492.701202] Dynamic subtest ccs0: SUCCESS (2.046s)
[492.704061] Subtest gt-engine-error: SUCCESS (11.876s)
[492.794068] [075/131] (544s left) kms_cursor_crc (cursor-onscreen-512x512)
[493.050120] Starting subtest: cursor-onscreen-512x512
[493.054481] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[493.233964] [076/131] (543s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[493.324555] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[493.327035] Starting dynamic subtest: pipe-A
[493.387818] Dynamic subtest pipe-A: SUCCESS (0.065s)
[493.399524] Starting dynamic subtest: pipe-B
[494.573057] Dynamic subtest pipe-B: SUCCESS (1.183s)
[494.583906] Starting dynamic subtest: pipe-C
[495.699749] Dynamic subtest pipe-C: SUCCESS (1.126s)
[495.716888] Starting dynamic subtest: pipe-D
[496.827202] Dynamic subtest pipe-D: SUCCESS (1.125s)
[496.830030] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.506s)
[498.005052] [077/131] (539s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[498.144851] Starting subtest: flip-vs-cursor-busy-crc-atomic
[498.967048] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.822s)
[499.841927] [078/131] (537s left) kms_dp_link_training (uhbr-sst)
[499.937344] Starting subtest: uhbr-sst
[499.947624] Subtest uhbr-sst: SKIP (0.000s)
[500.023019] [079/131] (537s left) perf (blocking-parameterized)
[500.153048] Starting subtest: blocking-parameterized
[520.229287] Subtest blocking-parameterized: SUCCESS (20.028s)
[520.271949] [080/131] (516s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[520.380898] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[520.386058] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[520.485560] [081/131] (516s left) vgem_basic (sysfs)
[520.525086] Starting subtest: sysfs
[520.527466] Subtest sysfs: SUCCESS (0.000s)
[520.594139] [082/131] (516s left) gem_ctx_persistence (engines-hostile)
[520.650825] Starting subtest: engines-hostile
[520.653723] Starting dynamic subtest: rcs0
[520.805394] Dynamic subtest rcs0: SUCCESS (0.154s)
[520.818038] Starting dynamic subtest: bcs0
[520.927238] Dynamic subtest bcs0: SUCCESS (0.120s)
[520.939796] Starting dynamic subtest: vcs0
[521.038319] Dynamic subtest vcs0: SUCCESS (0.109s)
[521.055685] Starting dynamic subtest: vcs1
[521.141723] Dynamic subtest vcs1: SUCCESS (0.101s)
[521.154231] Starting dynamic subtest: vecs0
[521.255026] Dynamic subtest vecs0: SUCCESS (0.112s)
[521.267853] Starting dynamic subtest: ccs0
[521.372726] Dynamic subtest ccs0: SUCCESS (0.115s)
[521.375281] Subtest engines-hostile: SUCCESS (0.724s)
[521.465398] [083/131] (515s left) i915_suspend (fence-restore-untiled)
[521.521045] Starting subtest: fence-restore-untiled
[521.526292] Subtest fence-restore-untiled: SKIP (0.000s)
[521.623812] [084/131] (515s left) syncobj_wait (invalid-reset-illegal-handle)
[521.675858] Starting subtest: invalid-reset-illegal-handle
[521.678358] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[521.802031] [085/131] (515s left) gem_readwrite (write-bad-handle)
[521.868950] Subtest write-bad-handle: SKIP (0.000s)
[521.926329] [086/131] (515s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[521.988937] Subtest no-spare-fences-busy: SKIP (0.000s)
[522.073628] [087/131] (515s left) gem_ctx_persistence (heartbeat-close)
[522.126365] Starting subtest: heartbeat-close
[522.130788] Subtest heartbeat-close: SKIP (0.000s)
[522.257722] [088/131] (515s left) kms_cursor_crc (cursor-onscreen-256x256)
[522.488013] Starting subtest: cursor-onscreen-256x256
[522.493596] Starting dynamic subtest: pipe-A-eDP-1
[524.602923] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.103s)
[524.623482] Starting dynamic subtest: pipe-D-eDP-1
[527.409363] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.791s)
[527.411666] Subtest cursor-onscreen-256x256: SUCCESS (4.920s)
[528.233428] [089/131] (509s left) gem_ringfill (engines-basic)
[533.699800] Starting subtest: engines-basic
[533.702783] Starting dynamic subtest: rcs0
[533.754186] Dynamic subtest rcs0: SUCCESS (0.056s)
[533.765058] Starting dynamic subtest: bcs0
[533.800720] Dynamic subtest bcs0: SUCCESS (0.044s)
[533.812938] Starting dynamic subtest: vcs0
[533.845309] Dynamic subtest vcs0: SUCCESS (0.043s)
[533.857666] Starting dynamic subtest: vcs1
[533.889087] Dynamic subtest vcs1: SUCCESS (0.042s)
[533.901088] Starting dynamic subtest: vecs0
[533.931944] Dynamic subtest vecs0: SUCCESS (0.041s)
[533.943849] Starting dynamic subtest: ccs0
[533.982096] Dynamic subtest ccs0: SUCCESS (0.047s)
[533.984973] Subtest engines-basic: SUCCESS (0.285s)
[534.071545] [090/131] (503s left) kms_pm_rpm (cursor)
[535.913477] Starting subtest: cursor
[535.918791] Subtest cursor: SKIP (0.000s)
[536.369949] [091/131] (501s left) gem_pread (bench)
[536.426702] Subtest bench: SKIP (0.000s)
[536.498538] [092/131] (501s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[536.594791] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[540.195637] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (3.606s)
[541.369522] [093/131] (496s left) gem_exec_schedule (submit-golden-slice)
[541.441641] Starting subtest: submit-golden-slice
[541.443883] Starting dynamic subtest: rcs0
[541.478861] Dynamic subtest rcs0: SUCCESS (0.048s)
[541.489468] Starting dynamic subtest: bcs0
[541.508175] Dynamic subtest bcs0: SUCCESS (0.028s)
[541.518566] Starting dynamic subtest: vcs0
[541.542044] Dynamic subtest vcs0: SUCCESS (0.033s)
[541.554443] Starting dynamic subtest: vcs1
[541.575635] Dynamic subtest vcs1: SUCCESS (0.033s)
[541.585854] Starting dynamic subtest: vecs0
[541.610097] Dynamic subtest vecs0: SUCCESS (0.033s)
[541.621084] Starting dynamic subtest: ccs0
[541.641841] Dynamic subtest ccs0: SUCCESS (0.032s)
[541.644537] Subtest submit-golden-slice: SUCCESS (0.212s)
[542.751230] [094/131] (495s left) gem_exec_schedule (preempt-contexts)
[542.815797] Starting subtest: preempt-contexts
[542.818345] Starting dynamic subtest: rcs0
[542.868748] Dynamic subtest rcs0: SUCCESS (0.062s)
[542.878749] Starting dynamic subtest: bcs0
[542.922993] Dynamic subtest bcs0: SUCCESS (0.055s)
[542.933137] Starting dynamic subtest: vcs0
[542.974169] Dynamic subtest vcs0: SUCCESS (0.047s)
[542.985042] Starting dynamic subtest: vcs1
[543.020900] Dynamic subtest vcs1: SUCCESS (0.047s)
[543.030810] Starting dynamic subtest: vecs0
[543.066684] Dynamic subtest vecs0: SUCCESS (0.046s)
[543.078096] Starting dynamic subtest: ccs0
[543.115949] Dynamic subtest ccs0: SUCCESS (0.048s)
[543.118305] Subtest preempt-contexts: SUCCESS (0.310s)
[544.835017] [095/131] (493s left) kms_frontbuffer_tracking (psr-suspend)
[544.941490] Starting subtest: psr-suspend
[583.489366] Subtest psr-suspend: SUCCESS (8.451s)
[592.714790] [096/131] (454s left) gem_exec_capture (capture-invisible)
[592.781133] Starting subtest: capture-invisible
[592.783290] Starting dynamic subtest: smem0
[592.787563] Dynamic subtest smem0: SKIP (0.000s)
[592.791766] Subtest capture-invisible: SKIP (0.001s)
[592.918422] [097/131] (454s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[593.018429] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[593.020690] Starting dynamic subtest: pipe-A-eDP-1
[593.041398] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.023s)
[593.056138] Starting dynamic subtest: pipe-B-eDP-1
[593.076174] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.022s)
[593.088189] Starting dynamic subtest: pipe-C-eDP-1
[593.108261] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.023s)
[593.120594] Starting dynamic subtest: pipe-D-eDP-1
[593.142220] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.023s)
[593.144550] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.096s)
[595.344683] [098/131] (451s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0)
[595.449760] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0
[596.477708] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.034s)
[596.636916] [099/131] (450s left) kms_big_fb (linear-64bpp-rotate-90)
[596.743861] Starting subtest: linear-64bpp-rotate-90
[596.763582] Subtest linear-64bpp-rotate-90: SKIP (0.019s)
[596.934300] [100/131] (450s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[597.025035] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[603.619354] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.623s)
[603.788336] [101/131] (443s left) kms_content_protection (dp-mst-suspend-resume)
[603.944006] Starting subtest: dp-mst-suspend-resume
[603.948577] Subtest dp-mst-suspend-resume: SKIP (0.000s)
[604.096117] [102/131] (443s left) kms_atomic (plane-primary-legacy)
[604.187650] Starting subtest: plane-primary-legacy
[604.189956] Starting dynamic subtest: pipe-A-eDP-1
[604.652641] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.467s)
[604.655011] Subtest plane-primary-legacy: SUCCESS (0.468s)
[605.475634] [103/131] (441s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[605.540789] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[605.543177] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[605.669648] [104/131] (441s left) gem_userptr_blits (unsync-unmap-after-close)
[605.746584] Subtest unsync-unmap-after-close: SKIP (0.000s)
[605.915250] [105/131] (441s left) drm_read (empty-nonblock)
[606.059280] Starting subtest: empty-nonblock
[606.061648] Subtest empty-nonblock: SUCCESS (0.000s)
[606.221922] [106/131] (441s left) gem_eio (suspend)
[606.454546] Starting subtest: suspend
[618.400853] Subtest suspend: SUCCESS (11.961s)
[628.597536] [107/131] (428s left) kms_psr (pr-primary-render)
[628.722130] Starting subtest: pr-primary-render
[628.726604] Subtest pr-primary-render: SKIP (0.000s)
[628.808623] [108/131] (428s left) kms_vblank (ts-continuation-suspend)
[628.913235] Starting subtest: ts-continuation-suspend
[628.915147] Starting dynamic subtest: pipe-A-eDP-1
[645.531787] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.927s)
[645.548773] Starting dynamic subtest: pipe-D-eDP-1
[663.490575] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.552s)
[663.492890] Subtest ts-continuation-suspend: SUCCESS (4.483s)
[674.428847] [109/131] (393s left) kms_flip (2x-nonexisting-fb-interruptible)
[674.528152] Starting subtest: 2x-nonexisting-fb-interruptible
[674.540575] Subtest 2x-nonexisting-fb-interruptible: SKIP (0.011s)
[674.663979] [110/131] (393s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[674.762434] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[681.805448] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (7.088s)
[681.979374] [111/131] (385s left) kms_invalid_mode (uint-max-clock)
[682.080365] Starting subtest: uint-max-clock
[682.082690] Starting dynamic subtest: pipe-A-eDP-1
[682.367380] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[682.377767] Starting dynamic subtest: pipe-B-eDP-1
[682.379895] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[682.389900] Starting dynamic subtest: pipe-C-eDP-1
[682.392213] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[682.402540] Starting dynamic subtest: pipe-D-eDP-1
[682.411330] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[682.413499] Subtest uint-max-clock: SUCCESS (0.293s)
[683.222924] [112/131] (384s left) kms_properties (get_properties-sanity-non-atomic)
[683.336419] Starting subtest: get_properties-sanity-non-atomic
[683.338736] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[683.435396] [113/131] (384s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[684.956344] Starting subtest: modeset-non-lpsp-stress-no-wait
[685.007845] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.052s)
[685.743014] [114/131] (382s left) kms_flip (flip-vs-absolute-wf_vblank)
[685.832875] Starting subtest: flip-vs-absolute-wf_vblank
[685.835113] Starting dynamic subtest: A-eDP1
[694.771764] Dynamic subtest A-eDP1: SUCCESS (8.952s)
[694.783861] Starting dynamic subtest: B-eDP1
[703.688472] Dynamic subtest B-eDP1: SUCCESS (8.923s)
[703.700707] Starting dynamic subtest: C-eDP1
[712.655535] Dynamic subtest C-eDP1: SUCCESS (8.916s)
[712.667158] Starting dynamic subtest: D-eDP1
[721.579318] Dynamic subtest D-eDP1: SUCCESS (8.919s)
[721.581403] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.722s)
[722.771806] [115/131] (345s left) gem_exec_flush (basic-batch-kernel-default-cmd)
[722.834792] Starting subtest: basic-batch-kernel-default-cmd
[722.838934] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s)
[723.031121] [116/131] (344s left) gem_exec_whisper (basic-fds-all)
[723.092668] Starting subtest: basic-fds-all
[734.847294] Subtest basic-fds-all: SUCCESS (11.751s)
[735.042373] [117/131] (332s left) kms_properties (invalid-properties-atomic)
[735.157026] Starting subtest: invalid-properties-atomic
[735.478688] Subtest invalid-properties-atomic: SUCCESS (0.323s)
[735.568774] [118/131] (332s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[735.674606] Starting subtest: plane-scaler-unity-scaling-with-rotation
[735.676594] Starting dynamic subtest: pipe-A
[735.900239] Dynamic subtest pipe-A: SUCCESS (0.229s)
[735.911700] Starting dynamic subtest: pipe-B
[737.229744] Dynamic subtest pipe-B: SUCCESS (1.329s)
[737.240384] Starting dynamic subtest: pipe-C
[738.556695] Dynamic subtest pipe-C: SUCCESS (1.326s)
[738.574711] Starting dynamic subtest: pipe-D
[739.889758] Dynamic subtest pipe-D: SUCCESS (1.333s)
[739.892093] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.220s)
[741.091815] [119/131] (327s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[741.136529] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[741.241246] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.104s)
[741.342556] [120/131] (326s left) syncobj_basic (bad-pad-handle-to-fd)
[741.400720] Starting subtest: bad-pad-handle-to-fd
[741.402619] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[741.541838] [121/131] (326s left) kms_chamelium_hpd (dp-hpd)
[741.936241] Subtest dp-hpd: SKIP (0.000s)
[742.798694] [122/131] (325s left) kms_async_flips (async-flip-with-page-flip-events-tiled-atomic)
[742.904959] Starting subtest: async-flip-with-page-flip-events-tiled-atomic
[743.286864] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[744.687927] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: FAIL (0.838s)
[744.700917] Starting dynamic subtest: pipe-A-eDP-1-4
[745.112978] Dynamic subtest pipe-A-eDP-1-4: FAIL (0.640s)
[745.124422] Starting dynamic subtest: pipe-A-eDP-1-4
[745.285587] Dynamic subtest pipe-A-eDP-1-4: FAIL (0.019s)
[745.298018] Starting dynamic subtest: pipe-A-eDP-1-x
[745.644106] Dynamic subtest pipe-A-eDP-1-x: FAIL (0.612s)
[745.786363] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[747.135408] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: FAIL (1.160s)
[747.145662] Starting dynamic subtest: pipe-B-eDP-1-4
[747.545731] Dynamic subtest pipe-B-eDP-1-4: FAIL (0.187s)
[747.556229] Starting dynamic subtest: pipe-B-eDP-1-4
[747.706321] Dynamic subtest pipe-B-eDP-1-4: FAIL (0.008s)
[747.716706] Starting dynamic subtest: pipe-B-eDP-1-x
[748.054375] Dynamic subtest pipe-B-eDP-1-x: FAIL (0.592s)
[748.112483] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[750.908667] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.753s)
[750.922072] Starting dynamic subtest: pipe-C-eDP-1-4
[753.072052] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.163s)
[753.082489] Starting dynamic subtest: pipe-C-eDP-1-4
[755.029408] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[755.042215] Starting dynamic subtest: pipe-C-eDP-1-x
[757.248118] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.166s)
[757.560731] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[760.337337] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.736s)
[760.353624] Starting dynamic subtest: pipe-D-eDP-1-4
[762.512721] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.174s)
[762.522592] Starting dynamic subtest: pipe-D-eDP-1-4
[764.470279] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.008s)
[764.481172] Starting dynamic subtest: pipe-D-eDP-1-x
[766.701198] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.178s)
[766.703974] Subtest async-flip-with-page-flip-events-tiled-atomic: FAIL (23.753s)
[767.895294] [123/131] (300s left) i915_drm_fdinfo (most-busy-check-all)
[767.941730] Starting subtest: most-busy-check-all
[767.943718] Starting dynamic subtest: rcs0
[767.948377] Dynamic subtest rcs0: SKIP (0.004s)
[767.960722] Starting dynamic subtest: bcs0
[767.965025] Dynamic subtest bcs0: SKIP (0.000s)
[767.977992] Starting dynamic subtest: vcs0
[767.982177] Dynamic subtest vcs0: SKIP (0.000s)
[767.992163] Starting dynamic subtest: vcs1
[767.996493] Dynamic subtest vcs1: SKIP (0.000s)
[768.008946] Starting dynamic subtest: vecs0
[768.013187] Dynamic subtest vecs0: SKIP (0.000s)
[768.023245] Starting dynamic subtest: ccs0
[768.027426] Dynamic subtest ccs0: SKIP (0.000s)
[768.031750] Subtest most-busy-check-all: SKIP (0.009s)
[768.072326] [124/131] (300s left) kms_dither (fb-8bpc-vs-panel-6bpc)
[768.184284] Starting subtest: fb-8bpc-vs-panel-6bpc
[768.186567] Starting dynamic subtest: pipe-A-eDP-1
[769.565596] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.383s)
[769.567876] Subtest fb-8bpc-vs-panel-6bpc: SUCCESS (1.384s)
[770.441401] [125/131] (297s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[770.567239] Starting subtest: cursorB-vs-flipA-atomic-transitions
[770.571604] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.002s)
[770.726218] [126/131] (297s left) gem_set_tiling_vs_pwrite
[770.859609] [127/131] (297s left) gem_mmap (bad-object)
[770.922849] Subtest bad-object: SKIP (0.000s)
[770.994002] [128/131] (297s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[771.095986] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[771.100026] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[771.236158] [129/131] (297s left) syncobj_wait (multi-wait-for-submit-submitted)
[771.296233] Starting subtest: multi-wait-for-submit-submitted
[771.401020] Subtest multi-wait-for-submit-submitted: SUCCESS (0.105s)
[771.501152] [130/131] (297s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[771.597065] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[771.601326] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[771.733500] [131/131] (296s left) gem_mmap_offset (isolation)
[771.791314] Starting subtest: isolation
[771.808182] Subtest isolation: SUCCESS (0.010s)
[771.938120] Closing watchdogs
results: parsing output: 0/ for test: gen9_exec_parse
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: kms_properties
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_flip_scaled_crc
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_psr
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: gem_eio
results: parsing output: 11/ for test: kms_psr2_sf
results: parsing output: 12/ for test: gem_exec_alignment
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_psr
results: parsing output: 15/ for test: gem_fence_thrash
results: parsing output: 16/ for test: fbdev
results: parsing output: 17/ for test: kms_lease
results: parsing output: 18/ for test: kms_big_fb
results: parsing output: 19/ for test: kms_psr2_sf
results: parsing output: 20/ for test: gem_exec_balancer
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_exec_params
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_mmap_gtt
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: kms_psr_stress_test
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_getfb
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: gem_pxp
results: parsing output: 32/ for test: kms_chamelium_audio
results: parsing output: 33/ for test: sysfs_timeslice_duration
results: parsing output: 34/ for test: gem_exec_params
results: parsing output: 35/ for test: gem_exercise_blt
results: parsing output: 36/ for test: kms_plane_multiple
results: parsing output: 37/ for test: dmabuf_sync_file
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: kms_big_fb
results: parsing output: 40/ for test: i915_pm_rpm
results: parsing output: 41/ for test: prime_vgem
results: parsing output: 42/ for test: syncobj_timeline
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: gem_exec_flush
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_pipe_crc_basic
results: parsing output: 48/ for test: gem_exec_reloc
results: parsing output: 49/ for test: gem_watchdog
results: parsing output: 50/ for test: gem_fence_thrash
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_universal_plane
results: parsing output: 53/ for test: kms_cursor_edge_walk
results: parsing output: 54/ for test: kms_cursor_legacy
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: perf_pmu
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_ccs
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: kms_cursor_legacy
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_ctx_param
results: parsing output: 63/ for test: kms_flip_scaled_crc
results: parsing output: 64/ for test: gem_create
results: parsing output: 65/ for test: kms_chamelium_hpd
results: parsing output: 66/ for test: kms_busy
results: parsing output: 67/ for test: syncobj_timeline
results: parsing output: 68/ for test: api_intel_bb
results: parsing output: 69/ for test: gem_exec_fence
results: parsing output: 70/ for test: api_intel_bb
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: gem_softpin
results: parsing output: 73/ for test: i915_hangman
results: parsing output: 74/ for test: kms_cursor_crc
results: parsing output: 75/ for test: kms_plane_scaling
results: parsing output: 76/ for test: kms_cursor_legacy
results: parsing output: 77/ for test: kms_dp_link_training
results: parsing output: 78/ for test: perf
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: vgem_basic
results: parsing output: 81/ for test: gem_ctx_persistence
results: parsing output: 82/ for test: i915_suspend
results: parsing output: 83/ for test: syncobj_wait
results: parsing output: 84/ for test: gem_readwrite
results: parsing output: 85/ for test: gem_fenced_exec_thrash
results: parsing output: 86/ for test: gem_ctx_persistence
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: gem_ringfill
results: parsing output: 89/ for test: kms_pm_rpm
results: parsing output: 90/ for test: gem_pread
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_exec_schedule
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_capture
results: parsing output: 96/ for test: kms_ccs
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_content_protection
results: parsing output: 101/ for test: kms_atomic
results: parsing output: 102/ for test: syncobj_timeline
results: parsing output: 103/ for test: gem_userptr_blits
results: parsing output: 104/ for test: drm_read
results: parsing output: 105/ for test: gem_eio
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: kms_vblank
results: parsing output: 108/ for test: kms_flip
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_invalid_mode
results: parsing output: 111/ for test: kms_properties
results: parsing output: 112/ for test: kms_pm_rpm
results: parsing output: 113/ for test: kms_flip
results: parsing output: 114/ for test: gem_exec_flush
results: parsing output: 115/ for test: gem_exec_whisper
results: parsing output: 116/ for test: kms_properties
results: parsing output: 117/ for test: kms_plane_scaling
results: parsing output: 118/ for test: syncobj_timeline
results: parsing output: 119/ for test: syncobj_basic
results: parsing output: 120/ for test: kms_chamelium_hpd
results: parsing output: 121/ for test: kms_async_flips
results: parsing output: 122/ for test: i915_drm_fdinfo
results: parsing output: 123/ for test: kms_dither
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 126/ for test: gem_mmap
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_mmap_offset
Done.