[56.293212] Initializing watchdogs
[56.293362]   /dev/watchdog0
[56.310501] [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]
[56.315466] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.317987] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.322067] [001/156] (960s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[56.469083] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[56.610644] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.144s)
[56.733549] [002/156] (960s left) gem_exec_balancer (parallel-out-fence)
[56.992344] Starting subtest: parallel-out-fence
[58.103026] Subtest parallel-out-fence: SUCCESS (1.115s)
[58.213119] [003/156] (958s left) kms_pm_dc (dc9-dpms)
[59.322903] Starting subtest: dc9-dpms
[60.758898] Subtest dc9-dpms: SUCCESS (1.440s)
[60.848428] [004/156] (956s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping)
[60.952954] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping
[60.957433] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[61.020164] [005/156] (955s left) gem_exec_suspend (basic-s3)
[61.080630] Starting subtest: basic-S3
[61.082874] Starting dynamic subtest: smem
[81.358625] Dynamic subtest smem: SUCCESS (6.219s)
[81.360934] Subtest basic-S3: SUCCESS (6.220s)
[91.571014] [006/156] (935s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[91.687633] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[91.692119] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[91.770032] [007/156] (935s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-shrfb-plflip-blt)
[91.867009] Starting subtest: fbcpsrhdr-2p-primscrn-shrfb-plflip-blt
[91.871151] Subtest fbcpsrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[91.953624] [008/156] (935s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-plflip-blt)
[92.052464] Starting subtest: fbchdr-2p-primscrn-indfb-plflip-blt
[92.056847] Subtest fbchdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[92.151951] [009/156] (935s left) gen9_exec_parse (bb-large)
[92.218229] Subtest bb-large: SKIP (0.000s)
[92.284892] [010/156] (934s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[92.371849] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[92.374081] Starting dynamic subtest: pipe-A-eDP-1
[109.170478] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.829s)
[109.180197] Starting dynamic subtest: pipe-B-eDP-1
[127.137765] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.819s)
[127.148678] Starting dynamic subtest: pipe-C-eDP-1
[145.107714] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.747s)
[145.118208] Starting dynamic subtest: pipe-D-eDP-1
[163.111636] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.759s)
[163.114108] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (10.157s)
[169.514052] [011/156] (862s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[169.583205] Subtest writes-after-reads-snoop: SKIP (0.000s)
[169.655380] [012/156] (862s left) kms_async_flips (async-flip-hang)
[169.741323] Starting subtest: async-flip-hang
[170.130670] Starting dynamic subtest: pipe-A-eDP-1
[196.515362] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.413s)
[196.898519] Starting dynamic subtest: pipe-B-eDP-1
[212.518420] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.624s)
[212.914996] Starting dynamic subtest: pipe-C-eDP-1
[228.511975] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.602s)
[228.886155] Starting dynamic subtest: pipe-D-eDP-1
[244.510786] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.634s)
[244.513472] Subtest async-flip-hang: SUCCESS (74.802s)
[245.735780] [013/156] (786s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[245.838026] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[245.842175] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[245.930639] [014/156] (786s left) prime_vgem (basic-fence-flip)
[246.070491] Starting subtest: basic-fence-flip
[246.367033] Subtest basic-fence-flip: SUCCESS (0.297s)
[246.484347] [FACT prime_vgem (basic-fence-flip)] new: kernel.kmod_is_loaded.vgem: true
[246.487769] [015/156] (785s left) core_setmaster (master-drop-set-user)
[246.558155] Starting subtest: master-drop-set-user
[246.569814] Subtest master-drop-set-user: SUCCESS (0.014s)
[246.607829] [016/156] (785s left) kms_getfb (getfb2-handle-protection)
[246.698782] Starting subtest: getfb2-handle-protection
[246.713768] Subtest getfb2-handle-protection: SUCCESS (0.015s)
[246.838968] [017/156] (785s left) gem_partial_pwrite_pread (writes-after-reads)
[246.890312] Subtest writes-after-reads: SKIP (0.000s)
[246.959440] [018/156] (785s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-pwrite)
[247.048180] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-pwrite
[247.052684] Subtest hdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[247.142992] [019/156] (785s left) kms_cursor_edge_walk (128x128-left-edge)
[247.225402] Starting subtest: 128x128-left-edge
[247.227835] Starting dynamic subtest: pipe-A-eDP-1
[250.699889] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.477s)
[250.713520] Starting dynamic subtest: pipe-D-eDP-1
[253.834763] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.134s)
[253.837071] Subtest 128x128-left-edge: SUCCESS (6.614s)
[254.650064] [020/156] (777s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite)
[254.757436] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite
[254.761684] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[254.856903] [021/156] (777s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[254.969325] Starting subtest: fbcpsr-rgb101010-draw-render
[259.757458] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (4.797s)
[259.884634] [022/156] (772s left) i915_drm_fdinfo (memory-info-shared)
[259.935064] Starting subtest: memory-info-shared
[259.937306] Starting dynamic subtest: smem0
[259.998062] Dynamic subtest smem0: SUCCESS (0.064s)
[260.000651] Subtest memory-info-shared: SUCCESS (0.064s)
[260.071240] [023/156] (772s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[260.165937] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[260.170065] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[260.288326] [024/156] (772s left) perf_pmu (busy-idle-no-semaphores)
[260.330853] Starting subtest: busy-idle-no-semaphores
[260.332846] Starting dynamic subtest: rcs0
[260.977334] Dynamic subtest rcs0: SUCCESS (0.646s)
[260.988234] Starting dynamic subtest: bcs0
[261.630126] Dynamic subtest bcs0: SUCCESS (0.651s)
[261.642662] Starting dynamic subtest: vcs0
[262.281308] Dynamic subtest vcs0: SUCCESS (0.650s)
[262.293500] Starting dynamic subtest: vcs1
[263.599273] Dynamic subtest vcs1: SUCCESS (1.316s)
[263.611972] Starting dynamic subtest: vecs0
[265.586741] Dynamic subtest vecs0: SUCCESS (1.986s)
[265.599149] Starting dynamic subtest: ccs0
[267.569659] Dynamic subtest ccs0: SUCCESS (1.980s)
[267.572420] Subtest busy-idle-no-semaphores: SUCCESS (7.240s)
[267.701291] [025/156] (765s left) gem_ccs (block-multicopy-inplace)
[267.760471] Starting subtest: block-multicopy-inplace
[267.764766] Subtest block-multicopy-inplace: SKIP (0.000s)
[267.861869] [026/156] (765s left) gem_exec_flush (basic-wb-pro-default)
[267.916811] Starting subtest: basic-wb-pro-default
[273.386640] Subtest basic-wb-pro-default: SUCCESS (5.467s)
[273.577111] [027/156] (759s left) kms_plane_cursor (viewport)
[273.677486] Starting subtest: viewport
[273.682157] Starting dynamic subtest: pipe-A-eDP-1-size-64
[276.253068] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.576s)
[276.313068] Starting dynamic subtest: pipe-A-eDP-1-size-128
[278.878345] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.573s)
[278.938462] Starting dynamic subtest: pipe-A-eDP-1-size-256
[281.537386] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.609s)
[281.901252] Starting dynamic subtest: pipe-B-eDP-1-size-64
[285.219331] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.330s)
[285.280267] Starting dynamic subtest: pipe-B-eDP-1-size-128
[287.887916] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.617s)
[287.948632] Starting dynamic subtest: pipe-B-eDP-1-size-256
[290.534346] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.596s)
[290.882682] Starting dynamic subtest: pipe-C-eDP-1-size-64
[293.801921] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.930s)
[293.840863] Starting dynamic subtest: pipe-C-eDP-1-size-128
[296.058413] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.225s)
[296.100331] Starting dynamic subtest: pipe-C-eDP-1-size-256
[298.279958] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.192s)
[298.616709] Starting dynamic subtest: pipe-D-eDP-1-size-64
[301.561551] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.959s)
[301.600494] Starting dynamic subtest: pipe-D-eDP-1-size-128
[303.883790] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.292s)
[303.925989] Starting dynamic subtest: pipe-D-eDP-1-size-256
[306.127926] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.214s)
[306.450202] Subtest viewport: SUCCESS (32.765s)
[307.345171] [028/156] (725s left) gem_mmap_gtt (big-bo)
[307.398325] Subtest big-bo: SKIP (0.000s)
[307.456942] [029/156] (725s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[307.569719] Starting subtest: 2x-flip-vs-cursor-legacy
[307.574166] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s)
[307.716868] [030/156] (725s left) syncobj_wait (reset-signaled)
[307.781248] Starting subtest: reset-signaled
[307.783806] Subtest reset-signaled: SUCCESS (0.000s)
[307.897143] [031/156] (725s left) kms_plane_multiple (tiling-yf)
[307.992191] Starting subtest: tiling-yf
[307.996588] Subtest tiling-yf: SKIP (0.000s)
[308.069668] [032/156] (725s left) kms_psr (pr-primary-mmap-gtt)
[308.165489] Starting subtest: pr-primary-mmap-gtt
[308.169754] Subtest pr-primary-mmap-gtt: SKIP (0.000s)
[308.236914] [033/156] (724s left) gem_ctx_shared (disjoint-timelines)
[308.295402] Starting subtest: disjoint-timelines
[308.323020] Subtest disjoint-timelines: SUCCESS (0.028s)
[308.543267] [034/156] (724s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-render)
[308.637795] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-render
[308.641998] Subtest fbchdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[308.736337] [035/156] (724s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-pgflip-blt)
[308.840052] Starting subtest: hdr-2p-primscrn-indfb-pgflip-blt
[308.844656] Subtest hdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[308.936738] [036/156] (724s left) gem_mmap_offset (partial-remap)
[308.997341] Starting subtest: partial-remap
[308.999744] Starting dynamic subtest: smem0
[309.008899] Dynamic subtest smem0: SUCCESS (0.004s)
[309.011197] Subtest partial-remap: SUCCESS (0.005s)
[309.124308] [037/156] (724s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[309.221475] Starting subtest: 4-tiled-addfb-size-offset-overflow
[309.223795] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[309.318043] [038/156] (724s left) api_intel_allocator (execbuf-with-allocator)
[309.380254] Starting subtest: execbuf-with-allocator
[309.385045] Subtest execbuf-with-allocator: SUCCESS (0.008s)
[309.534652] [039/156] (723s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[309.587318] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[309.698701] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.109s)
[309.776411] [040/156] (723s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-pwrite)
[309.878052] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-pwrite
[309.882284] Subtest psrhdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[309.992103] [041/156] (723s left) drm_read (invalid-buffer)
[310.126989] Starting subtest: invalid-buffer
[310.129316] Subtest invalid-buffer: SUCCESS (0.000s)
[310.256610] [042/156] (723s left) api_intel_bb (object-reloc-purge-cache)
[310.306360] Starting subtest: object-reloc-purge-cache
[310.310879] Subtest object-reloc-purge-cache: SKIP (0.003s)
[310.402592] [043/156] (723s left) gem_exec_schedule (fairslice-all)
[310.469715] Starting subtest: fairslice-all
[312.523737] Subtest fairslice-all: SUCCESS (2.059s)
[313.045867] [044/156] (720s left) kms_lease (lease-invalid-crtc)
[313.139452] Starting subtest: lease-invalid-crtc
[313.141718] Starting dynamic subtest: pipe-A-eDP-1
[313.143897] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[313.153556] Starting dynamic subtest: pipe-B-eDP-1
[313.155734] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[313.165328] Starting dynamic subtest: pipe-C-eDP-1
[313.167705] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[313.180012] Starting dynamic subtest: pipe-D-eDP-1
[313.182231] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[313.184409] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[313.224152] [045/156] (720s left) kms_cursor_crc (cursor-dpms)
[313.366067] Starting subtest: cursor-dpms
[313.368312] Starting dynamic subtest: pipe-A-eDP-1
[316.407850] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.039s)
[316.418562] Starting dynamic subtest: pipe-D-eDP-1
[320.094072] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.683s)
[320.096380] Subtest cursor-dpms: SUCCESS (6.725s)
[320.939415] [046/156] (712s left) kms_big_fb (linear-64bpp-rotate-0)
[321.050371] Starting subtest: linear-64bpp-rotate-0
[322.650518] Subtest linear-64bpp-rotate-0: SUCCESS (1.601s)
[322.854328] [047/156] (710s left) kms_psr (psr-sprite-render)
[322.945533] Starting subtest: psr-sprite-render
[322.947787] Starting dynamic subtest: eDP-1
[324.653906] Dynamic subtest eDP-1: SUCCESS (1.706s)
[324.656053] Subtest psr-sprite-render: SUCCESS (1.707s)
[325.524889] [048/156] (708s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[325.620853] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[325.624987] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[325.696130] [049/156] (708s left) gem_ctx_persistence (engines-queued)
[325.766712] Starting subtest: engines-queued
[325.769000] Starting dynamic subtest: rcs0
[325.840393] Dynamic subtest rcs0: SUCCESS (0.072s)
[325.853093] Starting dynamic subtest: bcs0
[325.870804] Dynamic subtest bcs0: SUCCESS (0.029s)
[325.880813] Starting dynamic subtest: vcs0
[326.949467] Dynamic subtest vcs0: SUCCESS (1.076s)
[326.963461] Starting dynamic subtest: vcs1
[328.931361] Dynamic subtest vcs1: SUCCESS (1.980s)
[328.945027] Starting dynamic subtest: vecs0
[330.979381] Dynamic subtest vecs0: SUCCESS (2.045s)
[330.991210] Starting dynamic subtest: ccs0
[331.159486] Dynamic subtest ccs0: SUCCESS (0.179s)
[331.162233] Subtest engines-queued: SUCCESS (5.393s)
[331.275987] [050/156] (702s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[331.337141] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[331.401208] [051/156] (702s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-render)
[331.505965] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-render
[331.509990] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[331.589774] [052/156] (702s left) kms_sequence (queue-busy)
[331.673516] Starting subtest: queue-busy
[331.675734] Starting dynamic subtest: pipe-A-eDP-1
[333.606986] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.934s)
[333.618480] Starting dynamic subtest: pipe-B-eDP-1
[336.213245] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.605s)
[336.224797] Starting dynamic subtest: pipe-C-eDP-1
[338.839098] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.623s)
[338.849905] Starting dynamic subtest: pipe-D-eDP-1
[341.459591] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.621s)
[341.462015] Subtest queue-busy: SUCCESS (9.789s)
[342.342371] [053/156] (691s left) kms_vrr (lobf)
[342.437134] Starting subtest: lobf
[342.441559] Subtest lobf: SKIP (0.000s)
[342.512008] [054/156] (691s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[342.600493] Starting subtest: modeset-transition-nonblocking-fencing
[342.602368] Starting dynamic subtest: 1x-outputs
[353.896530] Dynamic subtest 1x-outputs: SUCCESS (11.296s)
[353.899169] Subtest modeset-transition-nonblocking-fencing: SUCCESS (11.298s)
[354.757390] [055/156] (679s left) i915_hangman (gt-engine-error)
[354.816901] Starting subtest: gt-engine-error
[354.819139] Starting dynamic subtest: rcs0
[355.942807] Dynamic subtest rcs0: SUCCESS (1.124s)
[355.953756] Starting dynamic subtest: bcs0
[357.924269] Dynamic subtest bcs0: SUCCESS (1.980s)
[357.939266] Starting dynamic subtest: vcs0
[359.972801] Dynamic subtest vcs0: SUCCESS (2.045s)
[359.985813] Starting dynamic subtest: vcs1
[361.963394] Dynamic subtest vcs1: SUCCESS (1.982s)
[361.977068] Starting dynamic subtest: vecs0
[363.940775] Dynamic subtest vecs0: SUCCESS (1.982s)
[363.956411] Starting dynamic subtest: ccs0
[365.987622] Dynamic subtest ccs0: SUCCESS (2.045s)
[365.990204] Subtest gt-engine-error: SUCCESS (11.173s)
[366.124077] [056/156] (668s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[366.224275] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[366.237466] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.011s)
[366.315494] [057/156] (667s left) gem_mmap_gtt (big-bo-tiledy)
[366.375286] Subtest big-bo-tiledY: SKIP (0.000s)
[366.436821] [058/156] (667s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-onoff)
[366.545971] Starting subtest: hdr-2p-scndscrn-cur-indfb-onoff
[366.549997] Subtest hdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[366.648635] [059/156] (667s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[366.751008] Starting subtest: yf-tiled-8bpp-rotate-180
[366.755378] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[366.841373] [060/156] (667s left) sriov_basic (enable-vfs-bind-unbind-each)
[366.889866] Starting subtest: enable-vfs-bind-unbind-each
[366.892345] Starting dynamic subtest: numvfs-1
[366.965417] Dynamic subtest numvfs-1: FAIL (0.016s)
[366.976464] Starting dynamic subtest: numvfs-2
[367.038461] Dynamic subtest numvfs-2: FAIL (0.004s)
[367.049515] Starting dynamic subtest: numvfs-3
[367.109271] Dynamic subtest numvfs-3: FAIL (0.004s)
[367.119921] Starting dynamic subtest: numvfs-4
[367.180834] Dynamic subtest numvfs-4: FAIL (0.004s)
[367.191829] Starting dynamic subtest: numvfs-5
[367.247478] Dynamic subtest numvfs-5: FAIL (0.004s)
[367.256951] Starting dynamic subtest: numvfs-6
[367.313414] Dynamic subtest numvfs-6: FAIL (0.004s)
[367.322584] Starting dynamic subtest: numvfs-7
[367.379001] Dynamic subtest numvfs-7: FAIL (0.004s)
[367.388417] Starting dynamic subtest: numvfs-random
[367.443895] Dynamic subtest numvfs-random: FAIL (0.004s)
[367.445642] Subtest enable-vfs-bind-unbind-each: FAIL (0.050s)
[367.483026] [061/156] (666s left) gem_exec_suspend (basic-s0)
[367.534973] Starting subtest: basic-S0
[367.536968] Starting dynamic subtest: smem
[387.506436] Dynamic subtest smem: SUCCESS (6.487s)
[387.509739] Subtest basic-S0: SUCCESS (6.489s)
[392.706096] [062/156] (646s left) kms_psr (psr2-no-drrs)
[392.818756] Starting subtest: psr2-no-drrs
[392.820917] Starting dynamic subtest: eDP-1
[394.412863] Dynamic subtest eDP-1: SUCCESS (1.599s)
[394.415299] Subtest psr2-no-drrs: SUCCESS (1.600s)
[395.298867] [063/156] (644s left) gem_softpin (evict-active-interruptible)
[395.367652] Starting subtest: evict-active-interruptible
[395.373717] Subtest evict-active-interruptible: SUCCESS (0.006s)
[395.506869] [064/156] (644s left) gem_spin_batch (legacy-resubmit)
[395.570560] Starting subtest: legacy-resubmit
[395.573356] Starting dynamic subtest: default
[395.579597] Dynamic subtest default: SUCCESS (0.006s)
[395.591487] Starting dynamic subtest: render
[395.593790] Dynamic subtest render: SUCCESS (0.001s)
[395.604076] Starting dynamic subtest: bsd
[395.606342] Dynamic subtest bsd: SUCCESS (0.003s)
[395.616247] Starting dynamic subtest: bsd1
[395.618510] Dynamic subtest bsd1: SUCCESS (0.002s)
[395.628280] Starting dynamic subtest: bsd2
[395.630539] Dynamic subtest bsd2: SUCCESS (0.001s)
[395.640061] Starting dynamic subtest: blt
[395.642316] Dynamic subtest blt: SUCCESS (0.002s)
[395.652019] Starting dynamic subtest: vebox
[395.654328] Dynamic subtest vebox: SUCCESS (0.003s)
[395.656625] Subtest legacy-resubmit: SUCCESS (0.026s)
[395.826062] [065/156] (643s left) kms_frontbuffer_tracking (fbchdr-stridechange)
[395.919268] Starting subtest: fbchdr-stridechange
[395.923622] Subtest fbchdr-stridechange: SKIP (0.000s)
[396.023987] [066/156] (643s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move)
[396.120881] Starting subtest: psr-1p-primscrn-cur-indfb-move
[403.503466] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (7.401s)
[403.640121] [067/156] (636s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[403.738731] Starting subtest: plane-use-after-nonblocking-unbind
[403.741039] Starting dynamic subtest: pipe-A-eDP-1
[403.800147] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.062s)
[404.108610] Starting dynamic subtest: pipe-B-eDP-1
[404.911131] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.816s)
[405.215137] Subtest plane-use-after-nonblocking-unbind: SUCCESS (1.479s)
[406.064054] [068/156] (633s left) kms_content_protection (type1)
[406.207968] Starting subtest: type1
[406.212797] Subtest type1: SKIP (0.000s)
[406.342999] [069/156] (633s left) gem_exec_schedule (timeslicing)
[406.416358] Starting subtest: timeslicing
[406.418744] Starting dynamic subtest: rcs0
[406.428017] Dynamic subtest rcs0: SUCCESS (0.021s)
[406.441009] Starting dynamic subtest: bcs0
[406.449095] Dynamic subtest bcs0: SUCCESS (0.020s)
[406.460683] Starting dynamic subtest: vcs0
[406.469698] Dynamic subtest vcs0: SUCCESS (0.020s)
[406.481903] Starting dynamic subtest: vcs1
[406.489617] Dynamic subtest vcs1: SUCCESS (0.019s)
[406.500133] Starting dynamic subtest: vecs0
[406.510712] Dynamic subtest vecs0: SUCCESS (0.020s)
[406.520871] Starting dynamic subtest: ccs0
[406.532473] Dynamic subtest ccs0: SUCCESS (0.021s)
[406.534809] Subtest timeslicing: SUCCESS (0.127s)
[408.542509] [070/156] (631s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[408.599195] Starting subtest: multi-wait-for-submit-available-signaled
[408.601455] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s)
[408.709460] [071/156] (631s left) gem_mmap_gtt (basic-read-write-distinct)
[408.765395] Subtest basic-read-write-distinct: SKIP (0.000s)
[408.829985] [072/156] (631s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled)
[408.876603] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[408.878819] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[408.989800] [073/156] (630s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[410.627447] Starting subtest: modeset-lpsp-stress-no-wait
[420.875363] Subtest modeset-lpsp-stress-no-wait: SUCCESS (10.255s)
[420.952806] [074/156] (619s left) gem_userptr_blits (huge-split)
[421.028633] Starting subtest: huge-split
[421.038386] Subtest huge-split: SUCCESS (0.009s)
[421.168928] [075/156] (618s left) gem_exec_balancer (bonded-pair)
[421.236210] Starting subtest: bonded-pair
[421.240927] Subtest bonded-pair: SKIP (0.000s)
[421.572632] [076/156] (618s left) kms_feature_discovery (psr2)
[421.664360] Starting subtest: psr2
[421.666527] Subtest psr2: SUCCESS (0.000s)
[421.740839] [077/156] (618s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[421.830867] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[421.834976] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[421.921765] [078/156] (618s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[422.020495] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[422.022809] Starting dynamic subtest: pipe-A
[422.042597] Dynamic subtest pipe-A: SKIP (0.019s)
[422.053403] Starting dynamic subtest: pipe-B
[422.078507] Dynamic subtest pipe-B: SKIP (0.033s)
[422.089072] Starting dynamic subtest: pipe-C
[422.111171] Dynamic subtest pipe-C: SKIP (0.034s)
[422.121721] Starting dynamic subtest: pipe-D
[422.145307] Dynamic subtest pipe-D: SKIP (0.034s)
[422.149023] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.122s)
[422.255147] [079/156] (617s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[422.375272] Starting subtest: flip-vs-cursor-busy-crc-legacy
[423.091729] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.716s)
[423.958930] [080/156] (616s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[424.044972] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[424.047320] Starting dynamic subtest: pipe-A-eDP-1
[424.070584] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[424.085330] Starting dynamic subtest: pipe-B-eDP-1
[424.107514] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[424.117902] Starting dynamic subtest: pipe-C-eDP-1
[424.139340] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[424.149008] Starting dynamic subtest: pipe-D-eDP-1
[424.171073] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[424.175484] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.004s)
[426.335633] [081/156] (613s left) gem_exec_flush (basic-uc-rw-default)
[426.394256] Starting subtest: basic-uc-rw-default
[431.851486] Subtest basic-uc-rw-default: SUCCESS (5.458s)
[432.042898] [082/156] (608s left) kms_color_pipeline (plane-ctm3x4)
[432.144684] Starting subtest: plane-ctm3x4
[432.313258] Starting dynamic subtest: pipe-A-plane-0
[432.480474] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[432.493221] Starting dynamic subtest: pipe-A-plane-1
[432.635149] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[432.646378] Starting dynamic subtest: pipe-A-plane-2
[432.792292] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[434.041566] Starting dynamic subtest: pipe-B-plane-0
[434.187504] Dynamic subtest pipe-B-plane-0: SUCCESS (0.155s)
[434.198632] Starting dynamic subtest: pipe-B-plane-1
[434.320631] Dynamic subtest pipe-B-plane-1: SUCCESS (0.132s)
[434.332291] Starting dynamic subtest: pipe-B-plane-2
[434.456399] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[435.660453] Starting dynamic subtest: pipe-C-plane-0
[435.784516] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[435.795229] Starting dynamic subtest: pipe-C-plane-1
[435.906554] Dynamic subtest pipe-C-plane-1: SUCCESS (0.121s)
[435.917623] Starting dynamic subtest: pipe-C-plane-2
[436.039873] Dynamic subtest pipe-C-plane-2: SUCCESS (0.133s)
[437.305491] Starting dynamic subtest: pipe-D-plane-0
[437.419998] Dynamic subtest pipe-D-plane-0: SUCCESS (0.122s)
[437.430600] Starting dynamic subtest: pipe-D-plane-1
[437.551733] Dynamic subtest pipe-D-plane-1: SUCCESS (0.132s)
[437.562415] Starting dynamic subtest: pipe-D-plane-2
[437.673424] Dynamic subtest pipe-D-plane-2: SUCCESS (0.121s)
[437.962812] Subtest plane-ctm3x4: SUCCESS (5.818s)
[438.861790] [083/156] (601s left) syncobj_timeline (single-wait-all-signaled)
[438.932498] Starting subtest: single-wait-all-signaled
[438.934968] Subtest single-wait-all-signaled: SUCCESS (0.001s)
[439.043331] [084/156] (601s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-pwrite)
[439.140629] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-pwrite
[439.145351] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[439.253302] [085/156] (601s left) gem_mmap_gtt (fault-concurrent-y)
[439.303918] Subtest fault-concurrent-Y: SKIP (0.000s)
[439.358577] [086/156] (601s left) kms_psr2_su (page_flip-p010)
[439.403614] Subtest page_flip-P010: SKIP (0.000s)
[439.471245] [087/156] (601s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[439.564718] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu
[439.568946] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[439.654004] [088/156] (600s left) gem_ctx_param (set-priority-not-supported)
[439.701604] Starting subtest: set-priority-not-supported
[439.706115] Subtest set-priority-not-supported: SKIP (0.000s)
[439.775085] [089/156] (600s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[439.873934] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[439.877200] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[439.967832] [090/156] (600s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[440.082162] Starting subtest: short-busy-flip-before-cursor-toggle
[440.085963] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[440.229265] [091/156] (600s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[440.322161] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[440.326210] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[440.417463] [092/156] (600s left) gem_eio (wait-wedge-10ms)
[440.635861] Starting subtest: wait-wedge-10ms
[442.559279] Subtest wait-wedge-10ms: SUCCESS (1.921s)
[442.692138] [093/156] (598s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[442.796886] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[443.857723] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.060s)
[444.005000] [094/156] (596s left) kms_psr (fbc-psr-sprite-render)
[444.096896] Starting subtest: fbc-psr-sprite-render
[444.098810] Starting dynamic subtest: eDP-1
[444.102344] Dynamic subtest eDP-1: SKIP (0.000s)
[444.105897] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[444.170631] [095/156] (596s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[444.280165] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[445.303288] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.024s)
[445.439585] [096/156] (595s left) gem_fence_thrash (bo-write-verify-none)
[445.474500] Starting subtest: bo-write-verify-none
[445.493773] Subtest bo-write-verify-none: SKIP (0.019s)
[445.563101] [097/156] (595s left) gem_set_tiling_vs_blt (tiled-to-untiled)
[445.618377] Subtest tiled-to-untiled: SKIP (0.000s)
[445.703741] [098/156] (595s left) kms_busy (basic-hang)
[445.799921] Starting subtest: basic-hang
[445.801895] Starting dynamic subtest: flip-pipe-A
[446.399682] Dynamic subtest flip-pipe-A: SUCCESS (0.598s)
[446.412410] Starting dynamic subtest: modeset-pipe-A
[449.889028] Dynamic subtest modeset-pipe-A: SUCCESS (3.489s)
[449.900611] Starting dynamic subtest: flip-pipe-D
[451.107101] Dynamic subtest flip-pipe-D: SUCCESS (1.214s)
[451.118580] Starting dynamic subtest: modeset-pipe-D
[454.758486] Dynamic subtest modeset-pipe-D: SUCCESS (3.655s)
[454.761049] Subtest basic-hang: SUCCESS (8.964s)
[455.569498] [099/156] (585s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[455.673033] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite
[455.677263] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[455.783285] [100/156] (585s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[455.877879] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[455.881842] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[455.964796] [101/156] (585s left) syncobj_wait (multi-wait-for-submit-submitted)
[456.017305] Starting subtest: multi-wait-for-submit-submitted
[456.125196] Subtest multi-wait-for-submit-submitted: SUCCESS (0.108s)
[456.217392] [102/156] (584s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[456.324127] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc
[456.328410] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[456.440257] [103/156] (584s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[456.536351] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc
[456.540298] Subtest fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[456.637512] [104/156] (584s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[456.736719] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[456.740854] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[456.848282] [105/156] (584s left) gem_mmap_gtt (hang)
[456.909541] Subtest hang: SKIP (0.000s)
[456.966904] [106/156] (584s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[457.066849] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[457.070764] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[457.167655] [107/156] (584s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted)
[457.216606] Starting subtest: multi-wait-all-for-submit-available-submitted
[457.219467] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[457.311289] [108/156] (584s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[457.407877] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[457.411588] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[457.522224] [109/156] (583s left) gem_exec_fence (syncobj-signal)
[457.992781] Starting subtest: syncobj-signal
[458.003699] Subtest syncobj-signal: SUCCESS (0.011s)
[459.515434] [110/156] (582s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[459.616105] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[459.620089] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[459.712273] [111/156] (581s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[459.822184] Starting subtest: pipe-fbc-rte
[459.829155] Starting dynamic subtest: pipe-B-eDP-1
[465.339742] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.526s)
[465.347693] Subtest pipe-fbc-rte: SUCCESS (5.529s)
[467.629353] [112/156] (573s left) gem_pxp (verify-pxp-stale-buf-execution)
[467.741446] Starting subtest: verify-pxp-stale-buf-execution
[468.802934] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.063s)
[468.928581] [113/156] (572s left) kms_frontbuffer_tracking (hdr-modesetfrombusy)
[469.034456] Starting subtest: hdr-modesetfrombusy
[469.038933] Subtest hdr-modesetfrombusy: SKIP (0.000s)
[469.139326] [114/156] (572s left) perf_pmu (most-busy-idle-check-all)
[469.177731] Starting subtest: most-busy-idle-check-all
[469.180031] Starting dynamic subtest: rcs0
[470.449780] Dynamic subtest rcs0: SUCCESS (1.272s)
[470.461481] Starting dynamic subtest: bcs0
[472.434251] Dynamic subtest bcs0: SUCCESS (1.982s)
[472.449002] Starting dynamic subtest: vcs0
[474.481784] Dynamic subtest vcs0: SUCCESS (2.046s)
[474.493528] Starting dynamic subtest: vcs1
[476.465779] Dynamic subtest vcs1: SUCCESS (1.982s)
[476.481515] Starting dynamic subtest: vecs0
[478.449761] Dynamic subtest vecs0: SUCCESS (1.982s)
[478.463531] Starting dynamic subtest: ccs0
[480.434230] Dynamic subtest ccs0: SUCCESS (1.982s)
[480.437264] Subtest most-busy-idle-check-all: SUCCESS (11.262s)
[480.546049] [115/156] (561s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-pri-indfb-multidraw)
[480.648768] Starting subtest: fbcpsrhdr-2p-pri-indfb-multidraw
[480.652837] Subtest fbcpsrhdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[480.747118] [116/156] (561s left) kms_fbcon_fbt (psr)
[480.801335] Starting subtest: psr
[488.230510] Subtest psr: SUCCESS (7.422s)
[488.309772] [117/156] (553s left) gem_eio (in-flight-contexts-immediate)
[488.493756] Starting subtest: in-flight-contexts-immediate
[500.478612] Subtest in-flight-contexts-immediate: SUCCESS (11.979s)
[500.602628] [118/156] (541s left) kms_chamelium_color (ctm-0-75)
[500.707867] Subtest ctm-0-75: SKIP (0.000s)
[500.753817] [119/156] (541s left) drm_read (fault-buffer)
[500.890855] Starting subtest: fault-buffer
[500.893164] Subtest fault-buffer: SUCCESS (0.001s)
[501.032073] [120/156] (540s left) kms_psr2_sf (psr2-cursor-plane-update-sf)
[501.130411] Starting subtest: psr2-cursor-plane-update-sf
[501.132683] Starting dynamic subtest: pipe-A-eDP-1
[501.698290] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.571s)
[501.710504] Starting dynamic subtest: pipe-B-eDP-1
[503.043126] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.342s)
[503.045468] Subtest psr2-cursor-plane-update-sf: SUCCESS (1.916s)
[503.917953] [121/156] (538s left) gem_ctx_param (non-root-set)
[503.974729] Starting subtest: non-root-set
[503.993493] Subtest non-root-set: SUCCESS (0.023s)
[504.100813] [122/156] (537s left) prime_mmap (test_dup)
[504.145873] Starting subtest: test_dup
[504.147822] Starting dynamic subtest: test_dup-smem
[504.176454] Dynamic subtest test_dup-smem: SUCCESS (0.032s)
[504.178946] Subtest test_dup: SUCCESS (0.033s)
[504.252653] [123/156] (537s left) kms_psr (fbc-psr2-sprite-mmap-gtt)
[504.344888] Starting subtest: fbc-psr2-sprite-mmap-gtt
[504.347111] Starting dynamic subtest: eDP-1
[504.351468] Dynamic subtest eDP-1: SKIP (0.000s)
[504.356252] Subtest fbc-psr2-sprite-mmap-gtt: SKIP (0.002s)
[504.437199] [124/156] (537s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[504.533946] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[504.538176] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[504.632823] [125/156] (537s left) gem_pread (display)
[504.683800] Subtest display: SKIP (0.000s)
[504.745413] [126/156] (537s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-msflip-blt)
[504.838686] Starting subtest: psrhdr-2p-primscrn-shrfb-msflip-blt
[504.842353] Subtest psrhdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[504.930771] [127/156] (537s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[505.027329] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[505.029249] Starting dynamic subtest: pipe-A-eDP-1
[505.114155] Dynamic subtest pipe-A-eDP-1: SKIP (0.089s)
[505.126473] Starting dynamic subtest: pipe-B-eDP-1
[506.307233] Dynamic subtest pipe-B-eDP-1: SKIP (1.189s)
[506.312004] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.281s)
[507.498898] [128/156] (534s left) i915_pm_rpm (gem-pread)
[509.628845] Starting subtest: gem-pread
[511.689144] Subtest gem-pread: SUCCESS (2.060s)
[511.801921] [129/156] (530s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-blt)
[511.909557] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-blt
[511.913468] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[511.993152] [130/156] (530s left) i915_query (test-query-geometry-subslices)
[512.048274] Starting subtest: test-query-geometry-subslices
[512.050619] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[512.146218] [131/156] (530s left) gem_fence_thrash (bo-copy)
[512.181183] Starting subtest: bo-copy
[512.199846] Subtest bo-copy: SKIP (0.016s)
[512.265912] [132/156] (530s left) gem_ctx_exec (basic-close-race)
[512.328493] Starting subtest: basic-close-race
[518.004736] Subtest basic-close-race: SUCCESS (5.675s)
[518.141075] [133/156] (524s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[518.241514] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[518.243406] Starting dynamic subtest: pipe-A
[518.578990] Dynamic subtest pipe-A: SUCCESS (0.339s)
[518.590734] Starting dynamic subtest: pipe-B
[520.058378] Dynamic subtest pipe-B: SUCCESS (1.477s)
[520.072124] Starting dynamic subtest: pipe-C
[521.467003] Dynamic subtest pipe-C: SUCCESS (1.408s)
[521.478730] Starting dynamic subtest: pipe-D
[522.905008] Dynamic subtest pipe-D: SUCCESS (1.437s)
[522.907258] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.666s)
[524.082772] [134/156] (518s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[524.182737] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[524.185064] Starting dynamic subtest: pipe-A-eDP-1
[524.208400] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[524.221696] Starting dynamic subtest: pipe-B-eDP-1
[524.244110] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[524.254549] Starting dynamic subtest: pipe-C-eDP-1
[524.276137] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[524.286762] Starting dynamic subtest: pipe-D-eDP-1
[524.310543] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[524.314649] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.005s)
[526.455142] [135/156] (516s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[526.526064] Starting subtest: map-fixed-invalidate-overlap
[526.530247] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[526.662362] [136/156] (516s left) kms_chamelium_hpd (hdmi-hpd-fast)
[527.033867] Subtest hdmi-hpd-fast: SKIP (0.000s)
[527.844200] [137/156] (514s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[527.940494] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[527.944862] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[528.027599] [138/156] (514s left) gem_lmem_swapping (parallel-random-verify)
[528.068829] Subtest parallel-random-verify: SKIP (0.000s)
[528.120166] [139/156] (514s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[528.223466] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[528.227907] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[528.326408] [140/156] (514s left) syncobj_basic (bad-pad-handle-to-fd)
[528.377289] Starting subtest: bad-pad-handle-to-fd
[528.379700] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[528.477198] [141/156] (514s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-blt)
[528.571081] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-blt
[528.575439] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[528.672668] [142/156] (514s left) kms_joiner (basic-big-joiner)
[528.763411] Starting subtest: basic-big-joiner
[528.767804] Subtest basic-big-joiner: SKIP (0.000s)
[528.830690] [143/156] (514s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[529.099984] Starting subtest: cursor-rapid-movement-512x512
[529.103558] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[529.253330] [144/156] (513s left) gem_ctx_persistence (legacy-engines-queued)
[529.312939] Starting subtest: legacy-engines-queued
[529.314525] Starting dynamic subtest: render
[529.392559] Dynamic subtest render: SUCCESS (0.082s)
[529.405619] Starting dynamic subtest: bsd1
[531.412196] Dynamic subtest bsd1: SUCCESS (2.011s)
[531.424319] Starting dynamic subtest: bsd2
[533.455111] Dynamic subtest bsd2: SUCCESS (2.042s)
[533.469529] Starting dynamic subtest: blt
[533.492961] Dynamic subtest blt: SUCCESS (0.036s)
[533.503580] Starting dynamic subtest: vebox
[535.440602] Dynamic subtest vebox: SUCCESS (1.945s)
[535.443347] Subtest legacy-engines-queued: SUCCESS (6.130s)
[535.528223] [145/156] (507s left) syncobj_timeline (etime-multi-wait-submitted)
[535.583486] Starting subtest: etime-multi-wait-submitted
[535.685511] Subtest etime-multi-wait-submitted: SUCCESS (0.102s)
[535.786973] [146/156] (507s left) gem_set_tiling_vs_gtt
[535.908174] [147/156] (507s left) kms_ccs (bad-rotation-90-4-tiled-dg2-mc-ccs)
[536.009650] Starting subtest: bad-rotation-90-4-tiled-dg2-mc-ccs
[536.011924] Starting dynamic subtest: pipe-A-eDP-1
[536.034023] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[536.046830] Starting dynamic subtest: pipe-B-eDP-1
[536.067306] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[536.078266] Starting dynamic subtest: pipe-C-eDP-1
[536.098978] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[536.109313] Starting dynamic subtest: pipe-D-eDP-1
[536.131138] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[536.135099] Subtest bad-rotation-90-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[538.349012] [148/156] (504s left) sysfs_heartbeat_interval (precise)
[538.397002] Starting subtest: precise
[538.399267] Starting dynamic subtest: vecs0
[546.418093] Dynamic subtest vecs0: SUCCESS (8.021s)
[546.432296] Starting dynamic subtest: ccs0
[547.626050] Dynamic subtest ccs0: SUCCESS (1.204s)
[547.638365] Starting dynamic subtest: vcs0
[555.507870] Dynamic subtest vcs0: SUCCESS (7.884s)
[555.522339] Starting dynamic subtest: bcs0
[556.640465] Dynamic subtest bcs0: SUCCESS (1.130s)
[556.652084] Starting dynamic subtest: rcs0
[557.708272] Dynamic subtest rcs0: SUCCESS (1.066s)
[557.719250] Starting dynamic subtest: vcs1
[565.489962] Dynamic subtest vcs1: SUCCESS (7.782s)
[565.492993] Subtest precise: SUCCESS (27.099s)
[565.588609] [149/156] (477s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-fullscreen)
[565.701507] Starting subtest: hdr-2p-primscrn-spr-indfb-fullscreen
[565.705744] Subtest hdr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[565.811327] [150/156] (477s left) kms_flip (2x-flip-vs-suspend-interruptible)
[565.916007] Starting subtest: 2x-flip-vs-suspend-interruptible
[565.921169] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.002s)
[566.020032] [151/156] (477s left) gem_exec_schedule (noreorder-corked)
[566.204890] Starting subtest: noreorder-corked
[566.207123] Starting dynamic subtest: rcs0
[567.352340] Dynamic subtest rcs0: SUCCESS (1.150s)
[567.366955] Starting dynamic subtest: vcs0
[568.502725] Dynamic subtest vcs0: SUCCESS (1.148s)
[568.513966] Starting dynamic subtest: vcs1
[570.481136] Dynamic subtest vcs1: SUCCESS (1.976s)
[570.494698] Starting dynamic subtest: vecs0
[572.461855] Dynamic subtest vecs0: SUCCESS (1.979s)
[572.474986] Starting dynamic subtest: ccs0
[573.610752] Dynamic subtest ccs0: SUCCESS (1.148s)
[573.613468] Subtest noreorder-corked: SUCCESS (7.411s)
[573.806493] [152/156] (469s left) template (b)
[573.870306] Starting subtest: B
[573.872739] Subtest B: SUCCESS (0.000s)
[573.984817] [153/156] (469s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[574.041514] Starting subtest: y-tiled-to-vebox-yf-tiled
[574.046059] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[574.228857] [154/156] (469s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[574.327776] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[574.331980] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[574.423311] [155/156] (469s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[574.518121] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[574.522348] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[574.619021] [156/156] (468s left) kms_chamelium_edid (dp-edid-stress-resolution-non-4k)
[575.019318] Subtest dp-edid-stress-resolution-non-4k: SKIP (0.000s)
[575.843033] Closing watchdogs
results: parsing output: 0/ for test: gem_pxp
results: parsing output: 1/ for test: gem_exec_balancer
results: parsing output: 2/ for test: kms_pm_dc
results: parsing output: 3/ for test: kms_plane
results: parsing output: 4/ for test: gem_exec_suspend
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: gen9_exec_parse
results: parsing output: 9/ for test: kms_ccs
results: parsing output: 10/ for test: gem_partial_pwrite_pread
results: parsing output: 11/ for test: kms_async_flips
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: prime_vgem
results: parsing output: 14/ for test: core_setmaster
results: parsing output: 15/ for test: kms_getfb
results: parsing output: 16/ for test: gem_partial_pwrite_pread
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_cursor_edge_walk
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: i915_drm_fdinfo
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: perf_pmu
results: parsing output: 24/ for test: gem_ccs
results: parsing output: 25/ for test: gem_exec_flush
results: parsing output: 26/ for test: kms_plane_cursor
results: parsing output: 27/ for test: gem_mmap_gtt
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: kms_plane_multiple
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: gem_ctx_shared
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: gem_mmap_offset
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: api_intel_allocator
results: parsing output: 38/ for test: syncobj_timeline
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: drm_read
results: parsing output: 41/ for test: api_intel_bb
results: parsing output: 42/ for test: gem_exec_schedule
results: parsing output: 43/ for test: kms_lease
results: parsing output: 44/ for test: kms_cursor_crc
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_flip_scaled_crc
results: parsing output: 48/ for test: gem_ctx_persistence
results: parsing output: 49/ for test: kms_pipe_b_c_ivb
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_sequence
results: parsing output: 52/ for test: kms_vrr
results: parsing output: 53/ for test: kms_atomic_transition
results: parsing output: 54/ for test: i915_hangman
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: gem_mmap_gtt
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: sriov_basic
results: parsing output: 60/ for test: gem_exec_suspend
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: gem_softpin
results: parsing output: 63/ for test: gem_spin_batch
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_atomic_transition
results: parsing output: 67/ for test: kms_content_protection
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: syncobj_timeline
results: parsing output: 70/ for test: gem_mmap_gtt
results: parsing output: 71/ for test: syncobj_wait
results: parsing output: 72/ for test: kms_pm_rpm
results: parsing output: 73/ for test: gem_userptr_blits
results: parsing output: 74/ for test: gem_exec_balancer
results: parsing output: 75/ for test: kms_feature_discovery
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_plane_scaling
results: parsing output: 78/ for test: kms_cursor_legacy
results: parsing output: 79/ for test: kms_ccs
results: parsing output: 80/ for test: gem_exec_flush
results: parsing output: 81/ for test: kms_color_pipeline
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_mmap_gtt
results: parsing output: 85/ for test: kms_psr2_su
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_ctx_param
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_cursor_legacy
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: gem_eio
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: kms_psr
results: parsing output: 94/ for test: kms_big_fb
results: parsing output: 95/ for test: gem_fence_thrash
results: parsing output: 96/ for test: gem_set_tiling_vs_blt
results: parsing output: 97/ for test: kms_busy
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: syncobj_wait
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_mmap_gtt
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: syncobj_timeline
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: gem_exec_fence
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gem_pxp
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: perf_pmu
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_fbcon_fbt
results: parsing output: 116/ for test: gem_eio
results: parsing output: 117/ for test: kms_chamelium_color
results: parsing output: 118/ for test: drm_read
results: parsing output: 119/ for test: kms_psr2_sf
results: parsing output: 120/ for test: gem_ctx_param
results: parsing output: 121/ for test: prime_mmap
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_pread
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_psr2_sf
results: parsing output: 127/ for test: i915_pm_rpm
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: i915_query
results: parsing output: 130/ for test: gem_fence_thrash
results: parsing output: 131/ for test: gem_ctx_exec
results: parsing output: 132/ for test: kms_plane_scaling
results: parsing output: 133/ for test: kms_ccs
results: parsing output: 134/ for test: gem_userptr_blits
results: parsing output: 135/ for test: kms_chamelium_hpd
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: gem_lmem_swapping
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: syncobj_basic
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: kms_joiner
results: parsing output: 142/ for test: kms_cursor_crc
results: parsing output: 143/ for test: gem_ctx_persistence
results: parsing output: 144/ for test: syncobj_timeline
results: parsing output: 145/ for test: gem_set_tiling_vs_gtt
results: parsing output: 146/ for test: kms_ccs
results: parsing output: 147/ for test: sysfs_heartbeat_interval
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_flip
results: parsing output: 150/ for test: gem_exec_schedule
results: parsing output: 151/ for test: template
results: parsing output: 152/ for test: gem_render_copy
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_frontbuffer_tracking
results: parsing output: 155/ for test: kms_chamelium_edid
Done.