[57.901118] Initializing watchdogs
[57.901213]   /dev/watchdog0
[57.916400] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.921069] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.923511] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.926895] [001/133] (960s left) kms_pm_backlight (fade)
[58.056318] Starting subtest: fade
[58.154051] Starting dynamic subtest: eDP-1
[60.635475] Dynamic subtest eDP-1: SUCCESS (2.482s)
[60.637829] Subtest fade: SUCCESS (2.579s)
[61.512895] [002/133] (956s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[61.570605] Starting subtest: single-wait-for-submit-available-submitted
[61.572929] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[61.649988] [003/133] (956s left) gem_exec_reloc (basic-wc-gtt)
[61.717844] Subtest basic-wc-gtt: SKIP (0.000s)
[61.794880] [004/133] (956s left) kms_async_flips (async-flip-dpms)
[61.891176] Starting subtest: async-flip-dpms
[62.268308] Starting dynamic subtest: pipe-A-eDP-1
[66.115478] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.846s)
[66.496444] Starting dynamic subtest: pipe-B-eDP-1
[69.271055] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.780s)
[69.657825] Starting dynamic subtest: pipe-C-eDP-1
[72.438129] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.789s)
[72.813651] Starting dynamic subtest: pipe-D-eDP-1
[75.605052] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.797s)
[75.607460] Subtest async-flip-dpms: SUCCESS (13.714s)
[76.788416] [005/133] (941s left) perf_pmu (render-node-busy-idle)
[76.860894] Starting subtest: render-node-busy-idle
[76.863426] Starting dynamic subtest: rcs0
[77.525559] Dynamic subtest rcs0: SUCCESS (0.665s)
[77.535661] Starting dynamic subtest: bcs0
[78.187708] Dynamic subtest bcs0: SUCCESS (0.661s)
[78.198288] Starting dynamic subtest: vcs0
[79.893903] Dynamic subtest vcs0: SUCCESS (1.703s)
[79.902657] Starting dynamic subtest: vcs1
[81.877511] Dynamic subtest vcs1: SUCCESS (1.983s)
[81.885923] Starting dynamic subtest: vecs0
[83.923687] Dynamic subtest vecs0: SUCCESS (2.046s)
[83.932829] Starting dynamic subtest: ccs0
[84.599767] Dynamic subtest ccs0: SUCCESS (0.675s)
[84.602556] Subtest render-node-busy-idle: SUCCESS (7.739s)
[84.673352] [006/133] (933s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[84.784817] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[84.789228] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[84.878629] [007/133] (933s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear)
[84.975550] Starting subtest: fbcpsr-tiling-linear
[91.581944] Subtest fbcpsr-tiling-linear: SUCCESS (6.610s)
[91.728506] [008/133] (926s left) gem_exec_parallel (contexts)
[91.793128] Starting subtest: contexts
[91.795490] Starting dynamic subtest: rcs0
[94.561779] Dynamic subtest rcs0: SUCCESS (2.767s)
[94.571317] Starting dynamic subtest: bcs0
[97.919044] Dynamic subtest bcs0: SUCCESS (3.356s)
[97.928212] Starting dynamic subtest: vcs0
[102.265664] Dynamic subtest vcs0: SUCCESS (4.346s)
[102.276855] Starting dynamic subtest: vcs1
[106.313474] Dynamic subtest vcs1: SUCCESS (4.047s)
[106.321862] Starting dynamic subtest: vecs0
[110.425045] Dynamic subtest vecs0: SUCCESS (4.111s)
[110.436047] Starting dynamic subtest: ccs0
[113.926707] Dynamic subtest ccs0: SUCCESS (3.500s)
[113.929284] Subtest contexts: SUCCESS (22.131s)
[114.126672] [009/133] (904s left) kms_lease (implicit-plane-lease)
[114.226946] Starting subtest: implicit-plane-lease
[114.229382] Subtest implicit-plane-lease: SUCCESS (0.002s)
[114.335894] [010/133] (904s left) gem_lmem_swapping (random-engines)
[114.375458] Subtest random-engines: SKIP (0.000s)
[114.415351] [011/133] (904s left) perf_pmu (frequency)
[114.457847] Starting subtest: frequency
[114.460253] Starting dynamic subtest: gt0
[116.598244] Dynamic subtest gt0: SUCCESS (2.134s)
[116.608419] Starting dynamic subtest: idle-gt0
[117.103028] Dynamic subtest idle-gt0: SUCCESS (0.504s)
[117.113806] Starting dynamic subtest: gt1
[121.865329] Dynamic subtest gt1: SUCCESS (4.759s)
[121.875677] Starting dynamic subtest: idle-gt1
[122.394407] Dynamic subtest idle-gt1: SUCCESS (0.530s)
[122.397105] Subtest frequency: SUCCESS (7.939s)
[122.503537] [012/133] (896s left) kms_busy (extended-modeset-hang-newfb-with-reset)
[122.605600] Starting subtest: extended-modeset-hang-newfb-with-reset
[122.608083] Starting dynamic subtest: pipe-A
[138.195693] Dynamic subtest pipe-A: SUCCESS (15.589s)
[138.205209] Starting dynamic subtest: pipe-D
[153.774718] Dynamic subtest pipe-D: SUCCESS (15.574s)
[153.777069] Subtest extended-modeset-hang-newfb-with-reset: SUCCESS (31.167s)
[154.584094] [013/133] (864s left) kms_psr (psr2-cursor-render)
[154.677702] Starting subtest: psr2-cursor-render
[154.679885] Starting dynamic subtest: eDP-1
[156.350202] Dynamic subtest eDP-1: SUCCESS (1.673s)
[156.352637] Subtest psr2-cursor-render: SUCCESS (1.674s)
[157.153250] [014/133] (861s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[157.244225] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[157.255674] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.010s)
[157.334749] [015/133] (861s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[157.446552] Starting subtest: cursorB-vs-flipB-toggle
[157.450982] Subtest cursorB-vs-flipB-toggle: SKIP (0.001s)
[157.588829] [016/133] (861s left) syncobj_wait (multi-wait-for-submit-signaled)
[157.645077] Starting subtest: multi-wait-for-submit-signaled
[157.647367] Subtest multi-wait-for-submit-signaled: SUCCESS (0.000s)
[157.749576] [017/133] (861s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[157.808865] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[157.912268] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.106s)
[157.987881] [018/133] (861s left) kms_prime (basic-crc-vgem)
[158.144416] Starting subtest: basic-crc-vgem
[158.146367] Starting dynamic subtest: second-to-first
[159.127261] Dynamic subtest second-to-first: SUCCESS (0.985s)
[159.129172] Subtest basic-crc-vgem: SUCCESS (0.985s)
[159.215956] [FACT kms_prime (basic-crc-vgem)] new: kernel.kmod_is_loaded.vgem: true
[159.219410] [019/133] (859s left) kms_hdr (static-toggle-suspend)
[159.314450] Starting subtest: static-toggle-suspend
[159.319783] Subtest static-toggle-suspend: SKIP (0.000s)
[159.401110] [020/133] (859s left) gem_exec_flush (basic-uc-pro-default)
[159.451037] Starting subtest: basic-uc-pro-default
[164.905777] Subtest basic-uc-pro-default: SUCCESS (5.454s)
[165.094183] [021/133] (854s left) gem_exec_params (rs-invalid)
[165.156670] Starting subtest: rs-invalid
[165.161323] Subtest rs-invalid: SUCCESS (0.002s)
[165.284467] [022/133] (853s left) gen9_exec_parse (bb-start-out)
[165.358260] Subtest bb-start-out: SKIP (0.000s)
[165.405471] [023/133] (853s left) gem_exec_reloc (basic-wc-read)
[165.473487] Subtest basic-wc-read: SKIP (0.000s)
[165.551202] [024/133] (853s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[165.645068] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[165.649297] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[165.729786] [025/133] (853s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[165.824466] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[165.828825] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[165.906843] [026/133] (853s left) syncobj_wait (multi-wait-all-submitted-signaled)
[165.967800] Starting subtest: multi-wait-all-submitted-signaled
[166.073868] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.106s)
[166.164116] [027/133] (853s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[166.248132] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[166.252163] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[166.337489] [028/133] (853s left) gem_pipe_control_store_loop (fresh-buffer)
[166.398093] Starting subtest: fresh-buffer
[168.551445] Subtest fresh-buffer: SUCCESS (2.152s)
[168.650528] [029/133] (850s left) kms_properties (plane-properties-legacy)
[168.743090] Starting subtest: plane-properties-legacy
[168.745438] Starting dynamic subtest: pipe-A-eDP-1
[169.290882] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.549s)
[169.299540] Starting dynamic subtest: pipe-B-eDP-1
[170.531512] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.238s)
[170.541833] Starting dynamic subtest: pipe-C-eDP-1
[171.756908] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.223s)
[171.766461] Starting dynamic subtest: pipe-D-eDP-1
[172.989582] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.233s)
[172.991745] Subtest plane-properties-legacy: SUCCESS (4.247s)
[173.795873] [030/133] (845s left) kms_content_protection (uevent-hdcp14)
[173.936000] Starting subtest: uevent-hdcp14
[173.940680] Subtest uevent-hdcp14: SKIP (0.000s)
[174.061111] [031/133] (845s left) kms_cursor_edge_walk (256x256-top-edge)
[174.162726] Starting subtest: 256x256-top-edge
[174.164992] Starting dynamic subtest: pipe-A-eDP-1
[177.646902] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.484s)
[177.658644] Starting dynamic subtest: pipe-D-eDP-1
[180.776430] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.130s)
[180.778639] Subtest 256x256-top-edge: SUCCESS (6.616s)
[181.578243] [032/133] (838s left) kms_vblank (query-busy)
[181.682176] Starting subtest: query-busy
[181.684302] Starting dynamic subtest: pipe-A-eDP-1
[183.562798] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.880s)
[183.575627] Starting dynamic subtest: pipe-D-eDP-1
[186.132159] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.568s)
[186.134473] Subtest query-busy: SUCCESS (4.452s)
[186.953454] [033/133] (832s left) kms_atomic_transition (plane-all-modeset-transition-fencing)
[187.064374] Starting subtest: plane-all-modeset-transition-fencing
[187.068936] Subtest plane-all-modeset-transition-fencing: SKIP (0.000s)
[187.135788] [034/133] (832s left) gem_mmap_gtt (big-copy-odd)
[187.201172] Subtest big-copy-odd: SKIP (0.000s)
[187.260016] [035/133] (832s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[187.358555] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip
[188.329474] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (0.971s)
[188.458354] [036/133] (831s left) i915_drm_fdinfo (virtual-busy-all)
[188.501215] Starting subtest: virtual-busy-all
[188.505330] Subtest virtual-busy-all: SKIP (0.000s)
[188.575830] [037/133] (831s left) syncobj_wait (invalid-signal-zero-handles)
[188.632924] Starting subtest: invalid-signal-zero-handles
[188.634918] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[188.729617] [038/133] (831s left) gem_userptr_blits (dmabuf-sync)
[188.796236] Starting subtest: dmabuf-sync
[188.801683] Subtest dmabuf-sync: SKIP (0.004s)
[188.935494] [039/133] (831s left) gem_exec_balancer (full)
[189.005890] Starting subtest: full
[193.782888] Subtest full: SUCCESS (4.778s)
[194.102800] [040/133] (825s left) gem_exec_await (wide-contexts)
[194.330096] Starting subtest: wide-contexts
[217.818499] Subtest wide-contexts: SUCCESS (23.487s)
[218.021613] [041/133] (802s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[218.162711] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[218.164968] Starting dynamic subtest: pipe-A-eDP-1
[218.186766] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[218.196517] Starting dynamic subtest: pipe-B-eDP-1
[218.217537] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[218.226176] Starting dynamic subtest: pipe-C-eDP-1
[218.246936] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[218.255249] Starting dynamic subtest: pipe-D-eDP-1
[218.277163] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[218.281488] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.004s)
[220.340727] [042/133] (799s left) gem_ctx_exec (basic-close-race)
[220.409180] Starting subtest: basic-close-race
[226.360831] Subtest basic-close-race: SUCCESS (5.951s)
[226.502087] [043/133] (793s left) kms_psr (fbc-psr-sprite-mmap-gtt)
[226.601667] Starting subtest: fbc-psr-sprite-mmap-gtt
[226.603956] Starting dynamic subtest: eDP-1
[226.608412] Dynamic subtest eDP-1: SKIP (0.000s)
[226.612873] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.001s)
[226.671767] [044/133] (793s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[226.772197] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[226.776871] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.002s)
[226.863901] [045/133] (793s left) gem_exec_schedule (preempt-other)
[226.930079] Starting subtest: preempt-other
[226.932272] Starting dynamic subtest: rcs0
[226.994999] Dynamic subtest rcs0: SUCCESS (0.070s)
[227.005365] Starting dynamic subtest: bcs0
[227.065536] Dynamic subtest bcs0: SUCCESS (0.069s)
[227.074525] Starting dynamic subtest: vcs0
[227.131640] Dynamic subtest vcs0: SUCCESS (0.065s)
[227.145938] Starting dynamic subtest: vcs1
[227.195780] Dynamic subtest vcs1: SUCCESS (0.065s)
[227.208978] Starting dynamic subtest: vecs0
[227.260791] Dynamic subtest vecs0: SUCCESS (0.065s)
[227.273962] Starting dynamic subtest: ccs0
[227.326051] Dynamic subtest ccs0: SUCCESS (0.065s)
[227.328812] Subtest preempt-other: SUCCESS (0.401s)
[228.984145] [046/133] (791s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[229.095320] Starting subtest: 4-tiled-32bpp-rotate-270
[229.114612] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.018s)
[229.239503] [047/133] (791s left) gem_linear_blits (basic)
[229.389606] Starting subtest: basic
[229.398295] Subtest basic: SUCCESS (0.009s)
[229.533343] [048/133] (790s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[229.634819] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[229.637308] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[229.729261] [049/133] (790s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[229.824292] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[229.826599] Starting dynamic subtest: pipe-A-eDP-1
[229.849602] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[229.859666] Starting dynamic subtest: pipe-B-eDP-1
[229.880919] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[229.889346] Starting dynamic subtest: pipe-C-eDP-1
[229.911047] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[229.919179] Starting dynamic subtest: pipe-D-eDP-1
[229.939638] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[229.943237] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[232.117500] [050/133] (788s left) api_intel_allocator (alloc-simple)
[232.179537] Starting subtest: alloc-simple
[232.184680] Subtest alloc-simple: SUCCESS (0.006s)
[232.290389] [051/133] (788s left) gem_eio (in-flight-immediate)
[232.504069] Starting subtest: in-flight-immediate
[240.682828] Subtest in-flight-immediate: SUCCESS (8.178s)
[240.836584] [052/133] (779s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area)
[240.945801] Starting subtest: pr-primary-plane-update-sf-dmg-area
[240.949969] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[241.027809] [053/133] (779s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-render)
[241.129215] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-render
[248.062804] Subtest psr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.935s)
[248.196245] [054/133] (772s left) kms_vblank (wait-forked-busy)
[248.285254] Starting subtest: wait-forked-busy
[248.287167] Starting dynamic subtest: pipe-A-eDP-1
[250.169093] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.885s)
[250.183909] Starting dynamic subtest: pipe-D-eDP-1
[252.714764] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.543s)
[252.717199] Subtest wait-forked-busy: SUCCESS (4.431s)
[253.520759] [055/133] (767s left) syncobj_eventfd (invalid-illegal-eventfd)
[253.583404] Starting subtest: invalid-illegal-eventfd
[253.585403] Subtest invalid-illegal-eventfd: SUCCESS (0.000s)
[253.671794] [056/133] (766s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[253.769029] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[253.779078] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.010s)
[253.896687] [057/133] (766s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[253.992641] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[253.996707] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[254.089350] [058/133] (766s left) kms_color (legacy-gamma-reset)
[254.186166] Starting subtest: legacy-gamma-reset
[254.188499] Starting dynamic subtest: pipe-A-eDP-1
[254.561949] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.378s)
[254.571600] Starting dynamic subtest: pipe-B-eDP-1
[254.573569] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.011s)
[254.582682] Starting dynamic subtest: pipe-C-eDP-1
[254.587661] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.012s)
[254.596859] Starting dynamic subtest: pipe-D-eDP-1
[254.598851] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[254.601237] Subtest legacy-gamma-reset: SUCCESS (0.416s)
[255.366300] [059/133] (765s left) gem_close (many-handles-one-vma)
[255.430212] Starting subtest: many-handles-one-vma
[255.453656] Subtest many-handles-one-vma: SUCCESS (0.024s)
[255.581725] [060/133] (765s left) gem_ctx_shared (create-shared-gtt)
[255.646800] Starting subtest: create-shared-gtt
[257.801108] Subtest create-shared-gtt: SUCCESS (2.153s)
[258.128494] [061/133] (762s left) gem_pxp (hw-rejects-pxp-context)
[258.253987] Starting subtest: hw-rejects-pxp-context
[258.258767] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[258.376384] [062/133] (762s left) syncobj_wait (multi-wait-submitted)
[258.439278] Starting subtest: multi-wait-submitted
[258.546664] Subtest multi-wait-submitted: SUCCESS (0.107s)
[258.623112] [063/133] (762s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc)
[258.715387] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc
[258.717779] Starting dynamic subtest: pipe-A-eDP-1
[258.739058] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[258.750419] Starting dynamic subtest: pipe-B-eDP-1
[258.770517] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[258.779134] Starting dynamic subtest: pipe-C-eDP-1
[258.799761] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[258.808572] Starting dynamic subtest: pipe-D-eDP-1
[258.828087] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[258.830295] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.005s)
[261.016702] [064/133] (759s left) gem_exec_whisper (basic-queues-forked-all)
[261.075305] Starting subtest: basic-queues-forked-all
[283.221586] Subtest basic-queues-forked-all: SUCCESS (22.143s)
[283.411127] [065/133] (737s left) gem_ccs (block-multicopy-compressed)
[283.472364] Starting subtest: block-multicopy-compressed
[283.476519] Subtest block-multicopy-compressed: SKIP (0.000s)
[283.568163] [066/133] (737s left) kms_chamelium_audio (hdmi-audio-edid)
[283.960795] Subtest hdmi-audio-edid: SKIP (0.000s)
[284.815677] [067/133] (736s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[284.923646] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[284.925997] Starting dynamic subtest: pipe-A-eDP-1
[300.908458] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.371s)
[300.920657] Starting dynamic subtest: pipe-B-eDP-1
[317.876326] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.446s)
[317.886166] Starting dynamic subtest: pipe-C-eDP-1
[334.850254] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.328s)
[334.861239] Starting dynamic subtest: pipe-D-eDP-1
[351.879744] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.393s)
[351.882157] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (9.542s)
[353.095524] [068/133] (668s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[353.203200] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[360.001245] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.796s)
[360.149069] [069/133] (661s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[360.244197] Starting subtest: plane-scaler-unity-scaling-with-rotation
[360.246575] Starting dynamic subtest: pipe-A
[360.492697] Dynamic subtest pipe-A: SUCCESS (0.251s)
[360.504996] Starting dynamic subtest: pipe-B
[361.847740] Dynamic subtest pipe-B: SUCCESS (1.352s)
[361.858513] Starting dynamic subtest: pipe-C
[363.155042] Dynamic subtest pipe-C: SUCCESS (1.305s)
[363.168492] Starting dynamic subtest: pipe-D
[364.502892] Dynamic subtest pipe-D: SUCCESS (1.345s)
[364.505717] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.260s)
[365.669745] [070/133] (655s left) kms_psr (fbc-pr-cursor-blt)
[365.780124] Starting subtest: fbc-pr-cursor-blt
[365.785698] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[365.854308] [071/133] (655s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[365.925652] Starting subtest: y-tiled-to-vebox-x-tiled
[365.929972] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[366.111068] [072/133] (655s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[366.203764] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[366.206050] Starting dynamic subtest: pipe-A-default-mode
[368.920744] Dynamic subtest pipe-A-default-mode: SUCCESS (2.714s)
[368.923151] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.716s)
[369.749076] [073/133] (651s left) syncobj_basic (bad-pad-fd-to-handle)
[369.817063] Starting subtest: bad-pad-fd-to-handle
[369.819605] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[369.906125] [074/133] (651s left) gem_wait (invalid-flags)
[369.960922] Starting subtest: invalid-flags
[369.963075] Subtest invalid-flags: SUCCESS (0.000s)
[370.190139] [075/133] (651s left) i915_pm_rpm (reg-read-ioctl)
[372.423903] Starting subtest: reg-read-ioctl
[374.713908] Subtest reg-read-ioctl: SUCCESS (2.293s)
[375.110981] [076/133] (646s left) device_reset (unbind-cold-reset-rebind)
[375.165140] Starting subtest: unbind-cold-reset-rebind
[375.170876] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[375.239918] [077/133] (646s left) kms_getfb (getfb-handle-closed)
[375.334336] Starting subtest: getfb-handle-closed
[375.337107] Subtest getfb-handle-closed: SUCCESS (0.000s)
[375.435537] [078/133] (646s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[375.529862] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[375.532204] Starting dynamic subtest: pipe-A-default-mode
[378.262353] Dynamic subtest pipe-A-default-mode: SUCCESS (2.731s)
[378.265155] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.732s)
[379.123574] [079/133] (642s left) i915_query (query-regions-sanity-check)
[379.183441] Starting subtest: query-regions-sanity-check
[379.190390] Subtest query-regions-sanity-check: SUCCESS (0.009s)
[379.310928] [080/133] (642s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[379.408501] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[379.412944] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[379.522622] [081/133] (642s left) gem_softpin (noreloc)
[379.587634] Starting subtest: noreloc
[385.011638] Subtest noreloc: SUCCESS (5.420s)
[385.154299] [082/133] (636s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[385.261021] Starting subtest: fbc-rgb101010-draw-render
[389.920569] Subtest fbc-rgb101010-draw-render: SUCCESS (4.661s)
[391.112158] [083/133] (630s left) kms_atomic (plane-invalid-params-fence)
[391.199578] Starting subtest: plane-invalid-params-fence
[391.201795] Starting dynamic subtest: pipe-A-eDP-1
[391.564644] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.364s)
[391.567067] Subtest plane-invalid-params-fence: SUCCESS (0.365s)
[392.432869] [084/133] (629s left) i915_hangman (engine-error-state-capture)
[392.499286] Starting subtest: engine-error-state-capture
[392.502272] Starting dynamic subtest: rcs0
[396.274502] Dynamic subtest rcs0: SUCCESS (3.772s)
[396.287497] Starting dynamic subtest: bcs0
[402.290551] Dynamic subtest bcs0: SUCCESS (6.011s)
[402.303119] Starting dynamic subtest: vcs0
[408.242269] Dynamic subtest vcs0: SUCCESS (5.947s)
[408.255208] Starting dynamic subtest: vcs1
[413.234311] Dynamic subtest vcs1: SUCCESS (4.987s)
[413.247031] Starting dynamic subtest: vecs0
[417.265144] Dynamic subtest vecs0: SUCCESS (4.027s)
[417.278950] Starting dynamic subtest: ccs0
[423.282208] Dynamic subtest ccs0: SUCCESS (6.012s)
[423.284964] Subtest engine-error-state-capture: SUCCESS (30.773s)
[423.366836] [085/133] (598s left) i915_query (query-topology-known-pci-ids)
[423.427950] Starting subtest: query-topology-known-pci-ids
[423.432991] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[423.522906] [086/133] (598s left) kms_feature_discovery (dp-mst)
[423.622150] Starting subtest: dp-mst
[423.627217] Subtest dp-mst: SKIP (0.000s)
[423.694454] [087/133] (598s left) kms_plane_scaling (planes-upscale-factor-0-25)
[423.797906] Starting subtest: planes-upscale-factor-0-25
[423.800581] Starting dynamic subtest: pipe-A
[423.858217] Dynamic subtest pipe-A: SUCCESS (0.062s)
[423.868877] Starting dynamic subtest: pipe-B
[424.973533] Dynamic subtest pipe-B: SUCCESS (1.114s)
[424.986864] Starting dynamic subtest: pipe-C
[426.082231] Dynamic subtest pipe-C: SUCCESS (1.108s)
[426.092202] Starting dynamic subtest: pipe-D
[427.210020] Dynamic subtest pipe-D: SUCCESS (1.125s)
[427.212892] Subtest planes-upscale-factor-0-25: SUCCESS (3.414s)
[428.374993] [088/133] (593s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[428.466091] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[428.471833] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[428.594786] [089/133] (593s left) gem_mmap_offset (blt-coherency)
[428.656941] Starting subtest: blt-coherency
[428.662468] Subtest blt-coherency: SUCCESS (0.007s)
[428.779265] [090/133] (593s left) api_intel_bb (intel-bb-blit-none)
[428.832926] Starting subtest: intel-bb-blit-none
[433.011906] Subtest intel-bb-blit-none: SUCCESS (4.178s)
[433.129573] [091/133] (589s left) prime_mmap_kms (buffer-sharing)
[433.231962] Starting subtest: buffer-sharing
[433.692229] Subtest buffer-sharing: SUCCESS (0.459s)
[434.508047] [092/133] (587s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[434.601072] Starting subtest: flip-primary-invalidate-overlay
[446.268521] Subtest flip-primary-invalidate-overlay: SUCCESS (11.666s)
[446.399416] [093/133] (575s left) sysfs_defaults (readonly)
[446.477049] Starting subtest: readonly
[446.479264] Starting dynamic subtest: vecs0
[446.481633] Dynamic subtest vecs0: SUCCESS (0.001s)
[446.492020] Starting dynamic subtest: ccs0
[446.494018] Dynamic subtest ccs0: SUCCESS (0.001s)
[446.503406] Starting dynamic subtest: vcs0
[446.505111] Dynamic subtest vcs0: SUCCESS (0.001s)
[446.514442] Starting dynamic subtest: bcs0
[446.516346] Dynamic subtest bcs0: SUCCESS (0.001s)
[446.525898] Starting dynamic subtest: rcs0
[446.527632] Dynamic subtest rcs0: SUCCESS (0.001s)
[446.536896] Starting dynamic subtest: vcs1
[446.538895] Dynamic subtest vcs1: SUCCESS (0.001s)
[446.540912] Subtest readonly: SUCCESS (0.019s)
[446.578551] [094/133] (575s left) gem_ctx_shared (q-in-order)
[446.642954] Starting subtest: Q-in-order
[446.645224] Starting dynamic subtest: rcs0
[446.759605] Dynamic subtest rcs0: SUCCESS (0.115s)
[446.770206] Starting dynamic subtest: vcs0
[446.853292] Dynamic subtest vcs0: SUCCESS (0.093s)
[446.863774] Starting dynamic subtest: vcs1
[446.941053] Dynamic subtest vcs1: SUCCESS (0.087s)
[446.960114] Starting dynamic subtest: vecs0
[447.026904] Dynamic subtest vecs0: SUCCESS (0.085s)
[447.040840] Starting dynamic subtest: ccs0
[447.110236] Dynamic subtest ccs0: SUCCESS (0.081s)
[447.113155] Subtest Q-in-order: SUCCESS (0.467s)
[448.430018] [095/133] (573s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf)
[448.529951] Starting subtest: fbc-pr-cursor-plane-move-continuous-sf
[448.534224] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[448.599039] [096/133] (573s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[448.658756] Subtest writes-after-reads-uncached: SKIP (0.000s)
[448.730258] [097/133] (573s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode)
[449.111067] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s)
[449.927506] [098/133] (572s left) kms_cursor_legacy (torture-bo)
[450.048703] Starting subtest: torture-bo
[450.051030] Starting dynamic subtest: pipe-A
[456.190333] Dynamic subtest pipe-A: SUCCESS (6.140s)
[456.202028] Starting dynamic subtest: pipe-B
[462.976875] Dynamic subtest pipe-B: SUCCESS (6.788s)
[462.988040] Starting dynamic subtest: pipe-C
[470.152469] Dynamic subtest pipe-C: SUCCESS (7.175s)
[470.169062] Starting dynamic subtest: pipe-D
[478.225122] Dynamic subtest pipe-D: SUCCESS (8.071s)
[478.239224] Starting dynamic subtest: all-pipes
[486.655255] Dynamic subtest all-pipes: SUCCESS (8.427s)
[486.658054] Subtest torture-bo: SUCCESS (36.613s)
[486.832724] [099/133] (535s left) kms_psr (fbc-psr2-dpms)
[486.941106] Starting subtest: fbc-psr2-dpms
[486.943500] Starting dynamic subtest: eDP-1
[486.948087] Dynamic subtest eDP-1: SKIP (0.000s)
[486.952958] Subtest fbc-psr2-dpms: SKIP (0.001s)
[487.014532] [100/133] (535s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-blt)
[487.125191] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-blt
[487.129535] Subtest psr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[487.224998] [101/133] (535s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-plflip-blt)
[487.319965] Starting subtest: psr-1p-primscrn-shrfb-plflip-blt
[494.824564] Subtest psr-1p-primscrn-shrfb-plflip-blt: SUCCESS (7.508s)
[494.975846] [102/133] (527s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[495.083016] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[503.697256] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.616s)
[503.809237] [103/133] (518s left) gem_exec_balancer (parallel-dmabuf-import-out-fence)
[504.071565] Starting subtest: parallel-dmabuf-import-out-fence
[505.382922] Subtest parallel-dmabuf-import-out-fence: SUCCESS (1.311s)
[505.476141] [104/133] (517s left) gem_exec_schedule (preempt-self)
[505.556082] Starting subtest: preempt-self
[505.559069] Starting dynamic subtest: rcs0
[505.566006] Dynamic subtest rcs0: SUCCESS (0.021s)
[505.577248] Starting dynamic subtest: bcs0
[505.586936] Dynamic subtest bcs0: SUCCESS (0.021s)
[505.598228] Starting dynamic subtest: vcs0
[505.617215] Dynamic subtest vcs0: SUCCESS (0.030s)
[505.627262] Starting dynamic subtest: vcs1
[505.638871] Dynamic subtest vcs1: SUCCESS (0.020s)
[505.648852] Starting dynamic subtest: vecs0
[505.657999] Dynamic subtest vecs0: SUCCESS (0.019s)
[505.668225] Starting dynamic subtest: ccs0
[505.674142] Dynamic subtest ccs0: SUCCESS (0.014s)
[505.677062] Subtest preempt-self: SUCCESS (0.131s)
[507.435521] [105/133] (515s left) perf_pmu (semaphore-wait-idle)
[507.484701] Starting subtest: semaphore-wait-idle
[507.487305] Starting dynamic subtest: rcs0
[508.003223] Dynamic subtest rcs0: SUCCESS (0.518s)
[508.016593] Starting dynamic subtest: bcs0
[508.518873] Dynamic subtest bcs0: SUCCESS (0.513s)
[508.541587] Starting dynamic subtest: vcs0
[509.033137] Dynamic subtest vcs0: SUCCESS (0.512s)
[509.046122] Starting dynamic subtest: vcs1
[509.548230] Dynamic subtest vcs1: SUCCESS (0.512s)
[509.561282] Starting dynamic subtest: vecs0
[510.064110] Dynamic subtest vecs0: SUCCESS (0.512s)
[510.077913] Starting dynamic subtest: ccs0
[510.579728] Dynamic subtest ccs0: SUCCESS (0.512s)
[510.582799] Subtest semaphore-wait-idle: SUCCESS (3.098s)
[510.701961] [106/133] (512s left) gem_tiled_blits (basic)
[510.764246] Subtest basic: SKIP (0.000s)
[510.837062] [107/133] (512s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[511.175752] Starting subtest: cursor-rapid-movement-max-size
[511.183671] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[511.292543] [108/133] (511s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[511.395986] Starting subtest: fbc-1p-pri-indfb-multidraw
[524.442965] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.051s)
[525.803967] [109/133] (497s left) gem_softpin (allocator-basic-reserve)
[525.868863] Starting subtest: allocator-basic-reserve
[525.944066] Subtest allocator-basic-reserve: SUCCESS (0.077s)
[526.060435] [110/133] (496s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[526.606849] Starting subtest: syncobj-timeline-multiple-ext-nodes
[526.609758] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.005s)
[528.429175] [111/133] (494s left) syncobj_timeline (invalid-signal-zero-handles)
[528.477061] Starting subtest: invalid-signal-zero-handles
[528.480059] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[528.589381] [112/133] (494s left) kms_rmfb (close-fd)
[528.687213] Starting subtest: close-fd
[528.689245] Starting dynamic subtest: pipe-A-eDP-1
[528.794716] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.107s)
[528.804893] Starting dynamic subtest: pipe-B-eDP-1
[529.955198] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.160s)
[529.965092] Starting dynamic subtest: pipe-C-eDP-1
[531.089654] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.131s)
[531.099510] Starting dynamic subtest: pipe-D-eDP-1
[532.217100] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[532.219786] Subtest close-fd: SUCCESS (3.529s)
[533.434507] [113/133] (489s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[533.526520] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[533.531008] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[533.625232] [114/133] (489s left) gem_pwrite (basic-self)
[533.683007] Subtest basic-self: SKIP (0.000s)
[533.747904] [115/133] (489s left) gem_exec_whisper (basic-contexts-forked)
[533.810201] Starting subtest: basic-contexts-forked
[557.835164] Subtest basic-contexts-forked: SUCCESS (24.016s)
[558.018644] [116/133] (465s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[558.408522] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[559.248993] [117/133] (464s left) kms_psr (pr-dpms)
[559.335407] Starting subtest: pr-dpms
[559.340209] Subtest pr-dpms: SKIP (0.000s)
[559.421833] [118/133] (463s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[559.519739] Starting subtest: x-tiled-32bpp-rotate-90
[559.541881] Subtest x-tiled-32bpp-rotate-90: SKIP (0.022s)
[559.648755] [119/133] (463s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[559.738222] Starting subtest: fbcpsr-rgb565-draw-render
[566.772281] Subtest fbcpsr-rgb565-draw-render: SUCCESS (7.035s)
[566.896595] [120/133] (456s left) kms_cursor_crc (cursor-onscreen-256x85)
[567.174234] Starting subtest: cursor-onscreen-256x85
[567.225255] Subtest cursor-onscreen-256x85: SKIP (0.045s)
[567.388678] [121/133] (456s left) gem_exec_flush (basic-wb-rw-before-default)
[567.455013] Starting subtest: basic-wb-rw-before-default
[572.932481] Subtest basic-wb-rw-before-default: SUCCESS (5.477s)
[573.141623] [122/133] (450s left) perf (sysctl-defaults)
[573.233168] Starting subtest: sysctl-defaults
[573.235863] Subtest sysctl-defaults: SUCCESS (0.000s)
[573.329080] [123/133] (450s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[573.427048] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[577.142420] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.720s)
[578.283451] [124/133] (445s left) gem_wait (wait)
[578.345038] Starting subtest: wait
[578.348070] Starting dynamic subtest: all-engines
[580.307740] Dynamic subtest all-engines: SUCCESS (1.961s)
[580.320853] Starting dynamic subtest: rcs0
[581.359639] Dynamic subtest rcs0: SUCCESS (1.050s)
[581.373239] Starting dynamic subtest: bcs0
[582.411958] Dynamic subtest bcs0: SUCCESS (1.050s)
[582.424611] Starting dynamic subtest: vcs0
[583.461440] Dynamic subtest vcs0: SUCCESS (1.047s)
[583.473173] Starting dynamic subtest: vcs1
[584.512256] Dynamic subtest vcs1: SUCCESS (1.049s)
[584.524879] Starting dynamic subtest: vecs0
[585.562952] Dynamic subtest vecs0: SUCCESS (1.049s)
[585.578610] Starting dynamic subtest: ccs0
[586.617573] Dynamic subtest ccs0: SUCCESS (1.052s)
[586.620384] Subtest wait: SUCCESS (8.275s)
[586.819090] [125/133] (436s left) sw_sync (sync_multi_producer_single_consumer)
[586.863550] Starting subtest: sync_multi_producer_single_consumer
[587.936023] Subtest sync_multi_producer_single_consumer: SUCCESS (1.076s)
[588.006627] [126/133] (435s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[588.103033] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[588.107990] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[588.203605] [127/133] (435s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[588.299471] Starting subtest: read-crc-frame-sequence
[588.301976] Starting dynamic subtest: pipe-A-eDP-1
[588.982076] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.683s)
[588.993705] Starting dynamic subtest: pipe-B-eDP-1
[590.350613] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.366s)
[590.362015] Starting dynamic subtest: pipe-C-eDP-1
[591.725469] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.375s)
[591.737277] Starting dynamic subtest: pipe-D-eDP-1
[593.057452] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.329s)
[593.060164] Subtest read-crc-frame-sequence: SUCCESS (4.759s)
[593.899563] [128/133] (429s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[593.995127] Starting subtest: fbcpsr-suspend
[634.335133] Subtest fbcpsr-suspend: SUCCESS (10.048s)
[634.457824] [129/133] (389s left) gem_ctx_exec (basic-nohangcheck)
[634.523996] Starting subtest: basic-nohangcheck
[634.768643] Subtest basic-nohangcheck: SUCCESS (0.245s)
[634.898272] [130/133] (389s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[634.997545] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[636.015916] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.017s)
[636.140006] [131/133] (387s left) kms_panel_fitting (atomic-fastset)
[636.231603] Starting subtest: atomic-fastset
[636.234063] Starting dynamic subtest: pipe-A-eDP-1
[636.381353] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.152s)
[636.395710] Starting dynamic subtest: pipe-B-eDP-1
[637.600378] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.215s)
[637.611854] Starting dynamic subtest: pipe-C-eDP-1
[638.797336] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.194s)
[638.810342] Starting dynamic subtest: pipe-D-eDP-1
[639.928038] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[639.930196] Subtest atomic-fastset: SUCCESS (3.694s)
[641.083140] [132/133] (382s left) gem_eio (in-flight-internal-immediate)
[641.307118] Starting subtest: in-flight-internal-immediate
[642.650350] Subtest in-flight-internal-immediate: SUCCESS (1.341s)
[642.798916] [133/133] (381s left) perf_pmu (busy-double-start)
[642.841067] Starting subtest: busy-double-start
[642.843346] Starting dynamic subtest: rcs0
[646.487588] Dynamic subtest rcs0: FAIL (3.589s)
[646.499714] Starting dynamic subtest: bcs0
[650.000357] Dynamic subtest bcs0: SUCCESS (3.565s)
[650.012892] Starting dynamic subtest: vcs0
[654.146812] Dynamic subtest vcs0: SUCCESS (4.142s)
[654.162108] Starting dynamic subtest: vcs1
[658.189441] Dynamic subtest vcs1: SUCCESS (4.037s)
[658.207339] Starting dynamic subtest: vecs0
[662.135021] Dynamic subtest vecs0: SUCCESS (3.940s)
[662.148829] Starting dynamic subtest: ccs0
[665.769171] Dynamic subtest ccs0: FAIL (3.563s)
[665.771301] Subtest busy-double-start: FAIL (22.849s)
[665.813937] Closing watchdogs
results: parsing output: 0/ for test: kms_pm_backlight
results: parsing output: 1/ for test: syncobj_timeline
results: parsing output: 2/ for test: gem_exec_reloc
results: parsing output: 3/ for test: kms_async_flips
results: parsing output: 4/ for test: perf_pmu
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_exec_parallel
results: parsing output: 8/ for test: kms_lease
results: parsing output: 9/ for test: gem_lmem_swapping
results: parsing output: 10/ for test: perf_pmu
results: parsing output: 11/ for test: kms_busy
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: kms_flip
results: parsing output: 14/ for test: kms_cursor_legacy
results: parsing output: 15/ for test: syncobj_wait
results: parsing output: 16/ for test: syncobj_timeline
results: parsing output: 17/ for test: kms_prime
results: parsing output: 18/ for test: kms_hdr
results: parsing output: 19/ for test: gem_exec_flush
results: parsing output: 20/ for test: gem_exec_params
results: parsing output: 21/ for test: gen9_exec_parse
results: parsing output: 22/ for test: gem_exec_reloc
results: parsing output: 23/ for test: kms_plane
results: parsing output: 24/ for test: kms_plane
results: parsing output: 25/ for test: syncobj_wait
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: gem_pipe_control_store_loop
results: parsing output: 28/ for test: kms_properties
results: parsing output: 29/ for test: kms_content_protection
results: parsing output: 30/ for test: kms_cursor_edge_walk
results: parsing output: 31/ for test: kms_vblank
results: parsing output: 32/ for test: kms_atomic_transition
results: parsing output: 33/ for test: gem_mmap_gtt
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: i915_drm_fdinfo
results: parsing output: 36/ for test: syncobj_wait
results: parsing output: 37/ for test: gem_userptr_blits
results: parsing output: 38/ for test: gem_exec_balancer
results: parsing output: 39/ for test: gem_exec_await
results: parsing output: 40/ for test: kms_ccs
results: parsing output: 41/ for test: gem_ctx_exec
results: parsing output: 42/ for test: kms_psr
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: gem_exec_schedule
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: gem_linear_blits
results: parsing output: 47/ for test: syncobj_timeline
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: api_intel_allocator
results: parsing output: 50/ for test: gem_eio
results: parsing output: 51/ for test: kms_psr2_sf
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_vblank
results: parsing output: 54/ for test: syncobj_eventfd
results: parsing output: 55/ for test: kms_flip
results: parsing output: 56/ for test: kms_big_fb
results: parsing output: 57/ for test: kms_color
results: parsing output: 58/ for test: gem_close
results: parsing output: 59/ for test: gem_ctx_shared
results: parsing output: 60/ for test: gem_pxp
results: parsing output: 61/ for test: syncobj_wait
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: gem_exec_whisper
results: parsing output: 64/ for test: gem_ccs
results: parsing output: 65/ for test: kms_chamelium_audio
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_plane_scaling
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: gem_render_copy
results: parsing output: 71/ for test: kms_flip_scaled_crc
results: parsing output: 72/ for test: syncobj_basic
results: parsing output: 73/ for test: gem_wait
results: parsing output: 74/ for test: i915_pm_rpm
results: parsing output: 75/ for test: device_reset
results: parsing output: 76/ for test: kms_getfb
results: parsing output: 77/ for test: kms_flip_scaled_crc
results: parsing output: 78/ for test: i915_query
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: gem_softpin
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_atomic
results: parsing output: 83/ for test: i915_hangman
results: parsing output: 84/ for test: i915_query
results: parsing output: 85/ for test: kms_feature_discovery
results: parsing output: 86/ for test: kms_plane_scaling
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_mmap_offset
results: parsing output: 89/ for test: api_intel_bb
results: parsing output: 90/ for test: prime_mmap_kms
results: parsing output: 91/ for test: kms_psr_stress_test
results: parsing output: 92/ for test: sysfs_defaults
results: parsing output: 93/ for test: gem_ctx_shared
results: parsing output: 94/ for test: kms_psr2_sf
results: parsing output: 95/ for test: gem_partial_pwrite_pread
results: parsing output: 96/ for test: kms_chamelium_hpd
results: parsing output: 97/ for test: kms_cursor_legacy
results: parsing output: 98/ for test: kms_psr
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: gem_exec_balancer
results: parsing output: 103/ for test: gem_exec_schedule
results: parsing output: 104/ for test: perf_pmu
results: parsing output: 105/ for test: gem_tiled_blits
results: parsing output: 106/ for test: kms_cursor_crc
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: gem_softpin
results: parsing output: 109/ for test: gem_exec_fence
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: kms_rmfb
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_pwrite
results: parsing output: 114/ for test: gem_exec_whisper
results: parsing output: 115/ for test: kms_chamelium_frames
results: parsing output: 116/ for test: kms_psr
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_cursor_crc
results: parsing output: 120/ for test: gem_exec_flush
results: parsing output: 121/ for test: perf
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gem_wait
results: parsing output: 124/ for test: sw_sync
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_pipe_crc_basic
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gem_ctx_exec
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: kms_panel_fitting
results: parsing output: 131/ for test: gem_eio
results: parsing output: 132/ for test: perf_pmu
Done.