[54.955837] Initializing watchdogs
[54.955883]   /dev/watchdog0
[54.970720] [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]
[54.975151] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.977469] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.980897] [001/131] (960s left) kms_plane_scaling (planes-scaler-unity-scaling)
[55.087080] Starting subtest: planes-scaler-unity-scaling
[55.089490] Starting dynamic subtest: pipe-A
[55.192576] Dynamic subtest pipe-A: SUCCESS (0.105s)
[55.200519] Starting dynamic subtest: pipe-B
[56.329307] Dynamic subtest pipe-B: SUCCESS (1.137s)
[56.338707] Starting dynamic subtest: pipe-C
[57.442452] Dynamic subtest pipe-C: SUCCESS (1.113s)
[57.451306] Starting dynamic subtest: pipe-D
[58.562163] Dynamic subtest pipe-D: SUCCESS (1.119s)
[58.564656] Subtest planes-scaler-unity-scaling: SUCCESS (3.475s)
[59.697641] [002/131] (955s left) syncobj_wait (invalid-signal-one-illegal-handle)
[59.763797] Starting subtest: invalid-signal-one-illegal-handle
[59.766217] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[59.873862] [003/131] (955s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[59.936310] Starting subtest: y-tiled-ccs-to-y-tiled
[59.941117] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[60.110301] [004/131] (955s left) intel_sysfs_debugfs (i915-debugfs-read-all-entries-display-off)
[60.207032] Starting subtest: i915-debugfs-read-all-entries-display-off
[61.254435] Subtest i915-debugfs-read-all-entries-display-off: SUCCESS (1.046s)
[61.606673] [005/131] (954s left) syncobj_wait (wait-all-interrupted)
[61.663049] Starting subtest: wait-all-interrupted
[61.764000] Subtest wait-all-interrupted: SUCCESS (0.102s)
[61.834957] [006/131] (953s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[61.933115] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[61.935407] Starting dynamic subtest: pipe-A-eDP-1
[61.944460] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[61.952960] Starting dynamic subtest: pipe-B-eDP-1
[61.961927] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[61.968719] Starting dynamic subtest: pipe-C-eDP-1
[61.976972] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[61.983460] Starting dynamic subtest: pipe-D-eDP-1
[61.991977] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[61.996128] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.002s)
[64.057940] [007/131] (951s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[64.183182] Starting subtest: cursorA-vs-flipB-legacy
[64.187342] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[64.314567] [008/131] (951s left) gem_exec_fence (basic-busy-all)
[64.380335] Starting subtest: basic-busy-all
[64.483200] Subtest basic-busy-all: SUCCESS (0.105s)
[65.970505] [FACT gem_exec_fence (basic-busy-all)] new: kernel.kmod_is_loaded.vgem: true
[65.973443] [009/131] (949s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[66.069801] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[66.074279] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[66.163641] [010/131] (949s left) gem_exec_suspend (basic)
[66.226013] Starting subtest: basic
[66.228756] Starting dynamic subtest: smem
[67.925935] Dynamic subtest smem: SUCCESS (1.698s)
[67.928956] Subtest basic: SUCCESS (1.698s)
[68.113884] [011/131] (947s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[68.218629] Starting subtest: 4-tiled-8bpp-rotate-270
[68.238858] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.020s)
[68.359828] [012/131] (947s left) prime_mmap_coherency (read)
[68.414655] Starting subtest: read
[73.794859] Subtest read: SUCCESS (5.379s)
[73.929115] [013/131] (942s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[74.036752] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[74.041136] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[74.132679] [014/131] (941s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[74.240268] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[74.242620] Starting dynamic subtest: pipe-A-eDP-1
[74.264796] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[74.274030] Starting dynamic subtest: pipe-B-eDP-1
[74.295397] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[74.302646] Starting dynamic subtest: pipe-C-eDP-1
[74.324452] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[74.331795] Starting dynamic subtest: pipe-D-eDP-1
[74.355380] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[74.359646] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.002s)
[76.422303] [015/131] (939s left) gem_exec_schedule (implicit-write-read)
[76.492606] Starting subtest: implicit-write-read
[76.494942] Starting dynamic subtest: rcs0
[76.595970] Dynamic subtest rcs0: SUCCESS (0.114s)
[76.610145] Starting dynamic subtest: vcs0
[76.671036] Dynamic subtest vcs0: SUCCESS (0.075s)
[76.680362] Starting dynamic subtest: vcs1
[76.749390] Dynamic subtest vcs1: SUCCESS (0.078s)
[76.759123] Starting dynamic subtest: vecs0
[76.820948] Dynamic subtest vecs0: SUCCESS (0.072s)
[76.831242] Starting dynamic subtest: ccs0
[76.900277] Dynamic subtest ccs0: SUCCESS (0.079s)
[76.902852] Subtest implicit-write-read: SUCCESS (0.418s)
[78.018128] [016/131] (938s left) kms_big_fb (linear-8bpp-rotate-180)
[78.126580] Starting subtest: linear-8bpp-rotate-180
[79.354206] Subtest linear-8bpp-rotate-180: SUCCESS (1.228s)
[79.530152] [017/131] (936s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[79.637510] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[79.641618] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[79.724247] [018/131] (936s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180)
[79.829568] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180
[79.833351] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s)
[79.926880] [019/131] (936s left) gem_reg_read (bad-register)
[79.968788] Starting subtest: bad-register
[79.970942] Subtest bad-register: SUCCESS (0.000s)
[80.085716] [020/131] (936s left) kms_frontbuffer_tracking (fbc-1p-rte)
[80.189895] Starting subtest: fbc-1p-rte
[85.694728] Subtest fbc-1p-rte: SUCCESS (5.508s)
[86.896732] [021/131] (929s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[87.009916] Starting subtest: short-busy-flip-before-cursor-toggle
[87.014650] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[87.143171] [022/131] (929s left) gem_ctx_persistence (engines-queued)
[87.199469] Starting subtest: engines-queued
[87.201889] Starting dynamic subtest: rcs0
[87.305336] Dynamic subtest rcs0: SUCCESS (0.106s)
[87.316562] Starting dynamic subtest: bcs0
[87.382390] Dynamic subtest bcs0: SUCCESS (0.077s)
[87.392860] Starting dynamic subtest: vcs0
[88.884404] Dynamic subtest vcs0: SUCCESS (1.500s)
[88.894757] Starting dynamic subtest: vcs1
[90.868916] Dynamic subtest vcs1: SUCCESS (1.984s)
[90.878307] Starting dynamic subtest: vecs0
[92.851908] Dynamic subtest vecs0: SUCCESS (1.982s)
[92.860718] Starting dynamic subtest: ccs0
[93.064937] Dynamic subtest ccs0: SUCCESS (0.214s)
[93.067761] Subtest engines-queued: SUCCESS (5.865s)
[93.148611] [023/131] (923s left) sriov_basic (enable-vfs-autoprobe-on)
[93.207391] Starting subtest: enable-vfs-autoprobe-on
[93.209841] Starting dynamic subtest: numvfs-1
[93.288585] Dynamic subtest numvfs-1: FAIL (0.031s)
[93.298129] Starting dynamic subtest: numvfs-2
[93.371640] Dynamic subtest numvfs-2: FAIL (0.004s)
[93.381087] Starting dynamic subtest: numvfs-3
[93.454092] Dynamic subtest numvfs-3: FAIL (0.003s)
[93.462590] Starting dynamic subtest: numvfs-4
[93.535853] Dynamic subtest numvfs-4: FAIL (0.003s)
[93.544606] Starting dynamic subtest: numvfs-5
[93.618618] Dynamic subtest numvfs-5: FAIL (0.003s)
[93.627766] Starting dynamic subtest: numvfs-6
[93.700769] Dynamic subtest numvfs-6: FAIL (0.003s)
[93.709841] Starting dynamic subtest: numvfs-7
[93.783872] Dynamic subtest numvfs-7: FAIL (0.004s)
[93.791346] Starting dynamic subtest: numvfs-random
[93.861442] Dynamic subtest numvfs-random: FAIL (0.003s)
[93.868696] Starting dynamic subtest: numvfs-all
[93.937750] Dynamic subtest numvfs-all: FAIL (0.507s)
[93.939964] Subtest enable-vfs-autoprobe-on: FAIL (0.565s)
[93.975215] [024/131] (922s left) kms_psr (psr-sprite-blt)
[94.063933] Starting subtest: psr-sprite-blt
[94.066020] Starting dynamic subtest: eDP-1
[95.811485] Dynamic subtest eDP-1: SUCCESS (1.745s)
[95.813812] Subtest psr-sprite-blt: SUCCESS (1.745s)
[96.639087] [025/131] (919s left) kms_plane (plane-position-hole-dpms)
[96.732095] Starting subtest: plane-position-hole-dpms
[97.235934] Starting dynamic subtest: pipe-A-plane-1
[99.590956] Dynamic subtest pipe-A-plane-1: SUCCESS (2.353s)
[99.600100] Starting dynamic subtest: pipe-A-plane-2
[101.851681] Dynamic subtest pipe-A-plane-2: SUCCESS (2.260s)
[101.860508] Starting dynamic subtest: pipe-A-plane-3
[104.202870] Dynamic subtest pipe-A-plane-3: SUCCESS (2.353s)
[104.211642] Starting dynamic subtest: pipe-A-plane-4
[106.550041] Dynamic subtest pipe-A-plane-4: SUCCESS (2.343s)
[106.558299] Starting dynamic subtest: pipe-A-plane-5
[106.592555] Dynamic subtest pipe-A-plane-5: SUCCESS (0.044s)
[107.725946] Starting dynamic subtest: pipe-B-plane-1
[110.069739] Dynamic subtest pipe-B-plane-1: SUCCESS (2.354s)
[110.078050] Starting dynamic subtest: pipe-B-plane-2
[112.383625] Dynamic subtest pipe-B-plane-2: SUCCESS (2.311s)
[112.396570] Starting dynamic subtest: pipe-B-plane-3
[114.726032] Dynamic subtest pipe-B-plane-3: SUCCESS (2.342s)
[114.733572] Starting dynamic subtest: pipe-B-plane-4
[117.087761] Dynamic subtest pipe-B-plane-4: SUCCESS (2.362s)
[117.096597] Starting dynamic subtest: pipe-B-plane-5
[117.126700] Dynamic subtest pipe-B-plane-5: SUCCESS (0.039s)
[117.128886] Subtest plane-position-hole-dpms: SUCCESS (20.392s)
[117.891308] [026/131] (898s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[117.996043] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[125.301056] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.309s)
[125.457215] [027/131] (891s left) perf_pmu (busy-double-start)
[125.494734] Starting subtest: busy-double-start
[125.497344] Starting dynamic subtest: rcs0
[129.139272] Dynamic subtest rcs0: FAIL (3.575s)
[129.148833] Starting dynamic subtest: bcs0
[132.683861] Dynamic subtest bcs0: SUCCESS (3.614s)
[132.694525] Starting dynamic subtest: vcs0
[136.443856] Dynamic subtest vcs0: SUCCESS (3.759s)
[136.455162] Starting dynamic subtest: vcs1
[140.482066] Dynamic subtest vcs1: SUCCESS (4.037s)
[140.493228] Starting dynamic subtest: vecs0
[144.410342] Dynamic subtest vecs0: SUCCESS (3.927s)
[144.421074] Starting dynamic subtest: ccs0
[148.047108] Dynamic subtest ccs0: FAIL (3.565s)
[148.049470] Subtest busy-double-start: FAIL (22.483s)
[148.119402] [028/131] (868s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[148.215520] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[148.217553] Starting dynamic subtest: pipe-A-eDP-1
[148.416406] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.191s)
[148.430216] Starting dynamic subtest: pipe-B-eDP-1
[148.452035] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.009s)
[148.461702] Starting dynamic subtest: pipe-C-eDP-1
[148.481682] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.009s)
[148.490760] Starting dynamic subtest: pipe-D-eDP-1
[148.509654] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.011s)
[148.511571] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.223s)
[150.726757] [029/131] (865s left) gen9_exec_parse (allowed-all)
[150.788826] Subtest allowed-all: SKIP (0.000s)
[150.856546] [030/131] (865s left) gem_mmap_gtt (big-copy)
[150.921598] Subtest big-copy: SKIP (0.000s)
[150.988405] [031/131] (865s left) kms_chamelium_hpd (dp-hpd)
[151.379086] Subtest dp-hpd: SKIP (0.000s)
[152.231902] [032/131] (864s left) api_intel_bb (add-remove-objects)
[152.286092] Starting subtest: add-remove-objects
[152.290632] Subtest add-remove-objects: SUCCESS (0.007s)
[152.384140] [033/131] (864s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[152.489518] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[152.493687] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[152.581070] [034/131] (864s left) gem_exec_reloc (basic-wc-cpu)
[152.642706] Subtest basic-wc-cpu: SKIP (0.000s)
[152.700901] [035/131] (864s left) kms_atomic_interruptible (legacy-cursor)
[152.802327] Starting subtest: legacy-cursor
[152.804727] Starting dynamic subtest: pipe-A-eDP-1
[160.146868] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.346s)
[160.149276] Subtest legacy-cursor: SUCCESS (7.347s)
[161.014318] [036/131] (855s left) kms_flip (2x-modeset-vs-vblank-race)
[161.119364] Starting subtest: 2x-modeset-vs-vblank-race
[161.131856] Subtest 2x-modeset-vs-vblank-race: SKIP (0.012s)
[161.207986] [037/131] (855s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[161.308734] Starting subtest: fbc-shrfb-scaledprimary
[166.410741] Subtest fbc-shrfb-scaledprimary: SUCCESS (5.106s)
[167.577039] [038/131] (849s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu)
[167.684164] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu
[171.314232] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (3.634s)
[172.439605] [039/131] (844s left) syncobj_timeline (etime-multi-wait-all-submitted)
[172.497465] Starting subtest: etime-multi-wait-all-submitted
[172.601716] Subtest etime-multi-wait-all-submitted: SUCCESS (0.104s)
[172.674737] [040/131] (844s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[172.774555] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[172.778897] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[172.888176] [041/131] (844s left) kms_cursor_crc (cursor-rapid-movement-64x21)
[173.080860] Starting subtest: cursor-rapid-movement-64x21
[173.136449] Subtest cursor-rapid-movement-64x21: SKIP (0.051s)
[173.377545] [042/131] (843s left) syncobj_timeline (invalid-signal-zero-handles)
[173.433668] Starting subtest: invalid-signal-zero-handles
[173.436328] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[173.541049] [043/131] (843s left) gem_pxp (create-regular-context-2)
[173.663564] Starting subtest: create-regular-context-2
[173.665790] Subtest create-regular-context-2: SUCCESS (0.002s)
[173.783577] [044/131] (843s left) gem_exec_flush (basic-batch-kernel-default-uc)
[173.844177] Starting subtest: basic-batch-kernel-default-uc
[181.016076] Subtest basic-batch-kernel-default-uc: SUCCESS (7.167s)
[181.201999] [045/131] (836s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[181.312603] Starting subtest: x-tiled-8bpp-rotate-0
[182.493508] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.179s)
[182.682804] [046/131] (834s left) kms_addfb_basic (no-handle)
[182.738529] Starting subtest: no-handle
[182.740797] Subtest no-handle: SUCCESS (0.000s)
[182.907982] [047/131] (834s left) kms_lease (implicit-plane-lease)
[182.998736] Starting subtest: implicit-plane-lease
[183.003922] Subtest implicit-plane-lease: SUCCESS (0.003s)
[183.098628] [048/131] (834s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[183.204075] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[183.207395] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[183.328938] [049/131] (834s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[183.421808] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[183.423903] Starting dynamic subtest: pipe-A-eDP-1
[184.187429] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.756s)
[184.196520] Starting dynamic subtest: pipe-B-eDP-1
[185.968086] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.779s)
[185.975905] Starting dynamic subtest: pipe-C-eDP-1
[187.789618] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.821s)
[187.797456] Starting dynamic subtest: pipe-D-eDP-1
[189.620066] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.829s)
[189.622309] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.189s)
[190.730174] [050/131] (826s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[190.818152] Starting subtest: primary-yf-tiled-reflect-x-90
[190.822626] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.001s)
[190.894104] [051/131] (826s left) syncobj_wait (multi-wait-submitted-signaled)
[190.947302] Starting subtest: multi-wait-submitted-signaled
[190.949237] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[191.048298] [052/131] (826s left) kms_flip (2x-wf_vblank-ts-check)
[191.150315] Starting subtest: 2x-wf_vblank-ts-check
[191.161637] Subtest 2x-wf_vblank-ts-check: SKIP (0.011s)
[191.241847] [053/131] (826s left) kms_psr (pr-cursor-plane-move)
[191.348247] Starting subtest: pr-cursor-plane-move
[191.352494] Subtest pr-cursor-plane-move: SKIP (0.000s)
[191.419357] [054/131] (826s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[191.518713] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[191.521083] Starting dynamic subtest: pipe-A-default-mode
[191.807812] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[191.812541] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.287s)
[192.648014] [055/131] (824s left) kms_pm_rpm (basic-rte)
[192.679277] Starting subtest: basic-rte
[195.636825] Subtest basic-rte: SUCCESS (2.957s)
[197.445529] [056/131] (820s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[197.571248] Starting subtest: 2x-long-flip-vs-cursor-atomic
[197.575700] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[197.704988] [057/131] (819s left) sysfs_preempt_timeout (timeout)
[197.763555] Starting subtest: timeout
[197.766046] Starting dynamic subtest: vecs0
[206.958784] Dynamic subtest vecs0: SUCCESS (9.191s)
[206.969756] Starting dynamic subtest: ccs0
[208.239718] Dynamic subtest ccs0: SUCCESS (1.283s)
[208.250231] Starting dynamic subtest: vcs0
[217.963143] Dynamic subtest vcs0: SUCCESS (9.719s)
[217.972301] Starting dynamic subtest: bcs0
[219.328774] Dynamic subtest bcs0: SUCCESS (1.365s)
[219.338825] Starting dynamic subtest: rcs0
[220.698724] Dynamic subtest rcs0: SUCCESS (1.368s)
[220.709895] Starting dynamic subtest: vcs1
[229.927183] Dynamic subtest vcs1: SUCCESS (9.224s)
[229.929595] Subtest timeout: SUCCESS (32.160s)
[230.004566] [058/131] (787s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-sf)
[235.065696] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-sf
[235.070515] Subtest fbc-pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.002s)
[235.924461] [059/131] (781s left) gem_exec_balancer (hog)
[235.990479] Starting subtest: hog
[235.995651] Subtest hog: SKIP (0.000s)
[236.302979] [060/131] (781s left) gem_exec_capture (capture-recoverable)
[236.370536] Starting subtest: capture-recoverable
[236.372534] Subtest capture-recoverable: SUCCESS (0.003s)
[236.496418] [061/131] (781s left) gem_mmap_gtt (basic-write-read-distinct)
[236.562750] Subtest basic-write-read-distinct: SKIP (0.000s)
[236.617317] [062/131] (781s left) syncobj_timeline (device-signal-unordered)
[236.669764] Starting subtest: device-signal-unordered
[236.672269] Subtest device-signal-unordered: SUCCESS (0.001s)
[236.771259] [063/131] (781s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[236.873207] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[236.875378] Starting dynamic subtest: pipe-A-eDP-1
[236.897669] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[236.907407] Starting dynamic subtest: pipe-B-eDP-1
[236.928856] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[236.936912] Starting dynamic subtest: pipe-C-eDP-1
[236.958584] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[236.966163] Starting dynamic subtest: pipe-D-eDP-1
[236.988198] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[236.992617] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[239.077359] [064/131] (778s left) gem_render_copy (linear-to-vebox-yf-tiled)
[239.134343] Starting subtest: linear-to-vebox-yf-tiled
[239.138895] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[239.314431] [065/131] (778s left) kms_setmode (invalid-clone-single-crtc)
[239.360476] Starting subtest: invalid-clone-single-crtc
[239.367040] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[239.474039] [066/131] (778s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[239.566850] Starting subtest: y-tiled-32bpp-rotate-270
[239.571309] Subtest y-tiled-32bpp-rotate-270: SKIP (0.000s)
[239.655989] [067/131] (778s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[239.754359] Starting subtest: plane-downscale-factor-0-5-with-rotation
[239.756648] Starting dynamic subtest: pipe-A
[239.765729] Dynamic subtest pipe-A: SKIP (0.004s)
[239.775044] Starting dynamic subtest: pipe-B
[239.784280] Dynamic subtest pipe-B: SKIP (0.018s)
[239.792390] Starting dynamic subtest: pipe-C
[239.801866] Dynamic subtest pipe-C: SKIP (0.018s)
[239.809903] Starting dynamic subtest: pipe-D
[239.818732] Dynamic subtest pipe-D: SKIP (0.018s)
[239.823258] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.060s)
[239.913942] [068/131] (778s left) gem_ctx_param (set-priority-range)
[239.973977] Starting subtest: set-priority-range
[239.989126] Subtest set-priority-range: SUCCESS (0.015s)
[240.110817] [069/131] (778s left) kms_async_flips (basic-modeset-with-all-modifiers-formats)
[240.212938] Starting subtest: basic-modeset-with-all-modifiers-formats
[240.595026] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB8888
[241.357914] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB8888: SUCCESS (0.763s)
[241.366677] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB2101010
[241.380340] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB2101010: SUCCESS (0.022s)
[241.388400] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-XRGB16161616F
[241.402087] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-XRGB16161616F: SUCCESS (0.021s)
[241.410260] Starting dynamic subtest: pipe-A-eDP-1-4-RGB565
[241.624661] Dynamic subtest pipe-A-eDP-1-4-RGB565: SUCCESS (0.222s)
[241.634201] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB8888
[241.646521] Dynamic subtest pipe-A-eDP-1-4-XRGB8888: SUCCESS (0.022s)
[241.654920] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB2101010
[241.669118] Dynamic subtest pipe-A-eDP-1-4-XRGB2101010: SUCCESS (0.022s)
[241.680189] Starting dynamic subtest: pipe-A-eDP-1-4-XRGB16161616F
[241.691360] Dynamic subtest pipe-A-eDP-1-4-XRGB16161616F: SUCCESS (0.022s)
[241.700645] Starting dynamic subtest: pipe-A-eDP-1-4-YUYV
[241.713457] Dynamic subtest pipe-A-eDP-1-4-YUYV: SUCCESS (0.021s)
[241.723569] Starting dynamic subtest: pipe-A-eDP-1-4-Y210
[241.735930] Dynamic subtest pipe-A-eDP-1-4-Y210: SUCCESS (0.022s)
[241.749890] Starting dynamic subtest: pipe-A-eDP-1-4-XYUV8888
[241.758090] Dynamic subtest pipe-A-eDP-1-4-XYUV8888: SUCCESS (0.021s)
[241.767899] Starting dynamic subtest: pipe-A-eDP-1-4-XV30
[241.781478] Dynamic subtest pipe-A-eDP-1-4-XV30: SUCCESS (0.021s)
[241.793543] Starting dynamic subtest: pipe-A-eDP-1-4-XV36
[241.804252] Dynamic subtest pipe-A-eDP-1-4-XV36: SUCCESS (0.021s)
[241.814087] Starting dynamic subtest: pipe-A-eDP-1-x-RGB565
[242.002486] Dynamic subtest pipe-A-eDP-1-x-RGB565: SUCCESS (0.199s)
[242.011073] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB8888
[242.024772] Dynamic subtest pipe-A-eDP-1-x-XRGB8888: SUCCESS (0.022s)
[242.035959] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB2101010
[242.046987] Dynamic subtest pipe-A-eDP-1-x-XRGB2101010: SUCCESS (0.022s)
[242.057099] Starting dynamic subtest: pipe-A-eDP-1-x-XRGB16161616F
[242.069202] Dynamic subtest pipe-A-eDP-1-x-XRGB16161616F: SUCCESS (0.022s)
[242.077231] Starting dynamic subtest: pipe-A-eDP-1-x-YUYV
[242.091289] Dynamic subtest pipe-A-eDP-1-x-YUYV: SUCCESS (0.022s)
[242.099135] Starting dynamic subtest: pipe-A-eDP-1-x-Y210
[242.113530] Dynamic subtest pipe-A-eDP-1-x-Y210: SUCCESS (0.022s)
[242.121834] Starting dynamic subtest: pipe-A-eDP-1-x-XYUV8888
[242.135797] Dynamic subtest pipe-A-eDP-1-x-XYUV8888: SUCCESS (0.022s)
[242.144205] Starting dynamic subtest: pipe-A-eDP-1-x-XV30
[242.158017] Dynamic subtest pipe-A-eDP-1-x-XV30: SUCCESS (0.022s)
[242.168489] Starting dynamic subtest: pipe-A-eDP-1-x-XV36
[242.180365] Dynamic subtest pipe-A-eDP-1-x-XV36: SUCCESS (0.022s)
[242.191154] Starting dynamic subtest: pipe-A-eDP-1-linear-RGB565
[242.402683] Dynamic subtest pipe-A-eDP-1-linear-RGB565: SUCCESS (0.222s)
[242.412585] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB8888
[242.424691] Dynamic subtest pipe-A-eDP-1-linear-XRGB8888: SUCCESS (0.022s)
[242.432545] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB2101010
[242.447399] Dynamic subtest pipe-A-eDP-1-linear-XRGB2101010: SUCCESS (0.022s)
[242.461741] Starting dynamic subtest: pipe-A-eDP-1-linear-XRGB16161616F
[242.469031] Dynamic subtest pipe-A-eDP-1-linear-XRGB16161616F: SUCCESS (0.022s)
[242.476674] Starting dynamic subtest: pipe-A-eDP-1-linear-YUYV
[242.491658] Dynamic subtest pipe-A-eDP-1-linear-YUYV: SUCCESS (0.022s)
[242.504726] Starting dynamic subtest: pipe-A-eDP-1-linear-Y210
[242.513753] Dynamic subtest pipe-A-eDP-1-linear-Y210: SUCCESS (0.021s)
[242.523494] Starting dynamic subtest: pipe-A-eDP-1-linear-XYUV8888
[242.535815] Dynamic subtest pipe-A-eDP-1-linear-XYUV8888: SUCCESS (0.022s)
[242.544021] Starting dynamic subtest: pipe-A-eDP-1-linear-XV30
[242.557693] Dynamic subtest pipe-A-eDP-1-linear-XV30: SUCCESS (0.022s)
[242.565923] Starting dynamic subtest: pipe-A-eDP-1-linear-XV36
[242.580671] Dynamic subtest pipe-A-eDP-1-linear-XV36: SUCCESS (0.022s)
[242.959883] Subtest basic-modeset-with-all-modifiers-formats: SUCCESS (2.749s)
[243.732231] [070/131] (774s left) gem_lmem_swapping (parallel-random-verify-ccs)
[243.778199] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[243.825859] [071/131] (774s left) kms_cursor_crc (cursor-random-256x256)
[244.062437] Starting subtest: cursor-random-256x256
[244.067589] Starting dynamic subtest: pipe-A-eDP-1
[247.919877] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.853s)
[247.946539] Starting dynamic subtest: pipe-D-eDP-1
[252.340377] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.397s)
[252.342777] Subtest cursor-random-256x256: SUCCESS (8.275s)
[253.185094] [072/131] (765s left) i915_hangman (engine-error-state-capture)
[253.259589] Starting subtest: engine-error-state-capture
[253.262020] Starting dynamic subtest: rcs0
[256.883874] Dynamic subtest rcs0: SUCCESS (3.624s)
[256.895669] Starting dynamic subtest: bcs0
[262.900944] Dynamic subtest bcs0: SUCCESS (6.015s)
[262.912866] Starting dynamic subtest: vcs0
[268.852066] Dynamic subtest vcs0: SUCCESS (5.950s)
[268.863708] Starting dynamic subtest: vcs1
[274.803779] Dynamic subtest vcs1: SUCCESS (5.950s)
[274.814131] Starting dynamic subtest: vecs0
[279.795428] Dynamic subtest vecs0: SUCCESS (4.989s)
[279.805677] Starting dynamic subtest: ccs0
[284.852258] Dynamic subtest ccs0: SUCCESS (5.054s)
[284.855223] Subtest engine-error-state-capture: SUCCESS (31.593s)
[284.930884] [073/131] (733s left) kms_invalid_mode (bad-hsync-end)
[285.033284] Starting subtest: bad-hsync-end
[285.035422] Starting dynamic subtest: pipe-A-eDP-1
[285.318761] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[285.326872] Starting dynamic subtest: pipe-B-eDP-1
[285.328728] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[285.336181] Starting dynamic subtest: pipe-C-eDP-1
[285.338032] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[285.345794] Starting dynamic subtest: pipe-D-eDP-1
[285.347876] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[285.350088] Subtest bad-hsync-end: SUCCESS (0.290s)
[286.105151] [074/131] (732s left) kms_dp_link_training (uhbr-sst)
[286.202278] Starting subtest: uhbr-sst
[286.210969] Subtest uhbr-sst: SKIP (0.000s)
[286.292452] [075/131] (732s left) kms_psr (fbc-pr-cursor-render)
[286.396310] Starting subtest: fbc-pr-cursor-render
[286.400623] Subtest fbc-pr-cursor-render: SKIP (0.000s)
[286.465966] [076/131] (732s left) kms_psr (fbc-psr-sprite-blt)
[286.564175] Starting subtest: fbc-psr-sprite-blt
[286.566499] Starting dynamic subtest: eDP-1
[286.570974] Dynamic subtest eDP-1: SKIP (0.000s)
[286.575495] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[286.634792] [077/131] (731s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu)
[286.743922] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu
[293.279318] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (6.537s)
[293.414586] [078/131] (725s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-fully-sf)
[298.446678] Starting subtest: psr2-cursor-plane-move-continuous-exceed-fully-sf
[298.449096] Starting dynamic subtest: pipe-A-eDP-1
[310.172451] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.721s)
[310.180300] Starting dynamic subtest: pipe-B-eDP-1
[321.901321] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.726s)
[321.903847] Subtest psr2-cursor-plane-move-continuous-exceed-fully-sf: SUCCESS (23.452s)
[322.745383] [079/131] (695s left) kms_vblank (invalid)
[322.852893] Starting subtest: invalid
[322.855214] Starting dynamic subtest: pipe-A-eDP-1
[323.264577] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.407s)
[323.266657] Subtest invalid: SUCCESS (0.408s)
[324.073833] [080/131] (694s left) gem_tiled_wc
[324.205984] [081/131] (694s left) gem_softpin (evict-active)
[324.266667] Starting subtest: evict-active
[324.272381] Subtest evict-active: SUCCESS (0.006s)
[324.407860] [082/131] (694s left) kms_big_fb (linear-16bpp-rotate-90)
[324.510935] Starting subtest: linear-16bpp-rotate-90
[324.530349] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[324.641179] [083/131] (694s left) i915_pm_rpm (gem-idle)
[326.172853] Starting subtest: gem-idle
[332.379579] Subtest gem-idle: SUCCESS (6.208s)
[332.693664] [084/131] (686s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[333.860687] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[333.864908] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[333.945483] [085/131] (684s left) gem_ccs (block-multicopy-compressed)
[334.007715] Starting subtest: block-multicopy-compressed
[334.012076] Subtest block-multicopy-compressed: SKIP (0.000s)
[334.119849] [086/131] (684s left) gem_exec_schedule (submit-golden-slice)
[334.190115] Starting subtest: submit-golden-slice
[334.192491] Starting dynamic subtest: rcs0
[334.216419] Dynamic subtest rcs0: SUCCESS (0.035s)
[334.231278] Starting dynamic subtest: bcs0
[334.250795] Dynamic subtest bcs0: SUCCESS (0.034s)
[334.259568] Starting dynamic subtest: vcs0
[334.281279] Dynamic subtest vcs0: SUCCESS (0.030s)
[334.289885] Starting dynamic subtest: vcs1
[334.314420] Dynamic subtest vcs1: SUCCESS (0.032s)
[334.323607] Starting dynamic subtest: vecs0
[334.346535] Dynamic subtest vecs0: SUCCESS (0.031s)
[334.355674] Starting dynamic subtest: ccs0
[334.377780] Dynamic subtest ccs0: SUCCESS (0.031s)
[334.380094] Subtest submit-golden-slice: SUCCESS (0.197s)
[335.976694] [087/131] (682s left) prime_mmap (test_correct_cpu_write)
[336.027651] Starting subtest: test_correct_cpu_write
[336.030166] Starting dynamic subtest: test_correct_cpu_write-smem
[336.032516] Dynamic subtest test_correct_cpu_write-smem: SUCCESS (0.000s)
[336.034838] Subtest test_correct_cpu_write: SUCCESS (0.001s)
[336.135471] [088/131] (682s left) gem_mmap_offset (ptrace)
[336.195597] Starting subtest: ptrace
[336.200776] Starting dynamic subtest: smem0-wb
[336.214036] Dynamic subtest smem0-wb: SUCCESS (0.016s)
[336.224637] Starting dynamic subtest: smem0-wc
[336.234503] Dynamic subtest smem0-wc: SUCCESS (0.020s)
[336.244538] Starting dynamic subtest: smem0-uc
[336.254776] Dynamic subtest smem0-uc: SUCCESS (0.019s)
[336.257076] Subtest ptrace: SUCCESS (0.060s)
[336.368895] [089/131] (682s left) drm_buddy (drm_buddy)
[336.401084] Starting subtest: drm_buddy
[336.424613] Starting dynamic subtest: drm_test_buddy_alloc_limit
[336.559162] Dynamic subtest drm_test_buddy_alloc_limit: SUCCESS (0.134s)
[336.568200] Starting dynamic subtest: drm_test_buddy_alloc_optimistic
[336.684060] Dynamic subtest drm_test_buddy_alloc_optimistic: SUCCESS (0.122s)
[336.691999] Starting dynamic subtest: drm_test_buddy_alloc_pessimistic
[336.782634] Dynamic subtest drm_test_buddy_alloc_pessimistic: SUCCESS (0.097s)
[336.790766] Starting dynamic subtest: drm_test_buddy_alloc_pathological
[336.903063] Dynamic subtest drm_test_buddy_alloc_pathological: SUCCESS (0.117s)
[336.913134] Starting dynamic subtest: drm_test_buddy_alloc_contiguous
[337.019891] Dynamic subtest drm_test_buddy_alloc_contiguous: SUCCESS (0.118s)
[337.028119] Starting dynamic subtest: drm_test_buddy_alloc_clear
[337.149329] Dynamic subtest drm_test_buddy_alloc_clear: SUCCESS (0.127s)
[337.158051] Starting dynamic subtest: drm_test_buddy_alloc_range_bias
[337.275696] Dynamic subtest drm_test_buddy_alloc_range_bias: SUCCESS (0.126s)
[337.277694] Subtest drm_buddy: SUCCESS (0.876s)
[337.483050] [090/131] (681s left) kms_flip (plain-flip-fb-recreate)
[337.581100] Starting subtest: plain-flip-fb-recreate
[337.585953] Starting dynamic subtest: A-eDP1
[346.480812] Dynamic subtest A-eDP1: SUCCESS (8.898s)
[346.489072] Starting dynamic subtest: B-eDP1
[355.400452] Dynamic subtest B-eDP1: SUCCESS (8.919s)
[355.410080] Starting dynamic subtest: C-eDP1
[364.314922] Dynamic subtest C-eDP1: SUCCESS (8.913s)
[364.324264] Starting dynamic subtest: D-eDP1
[373.142684] Dynamic subtest D-eDP1: SUCCESS (8.827s)
[373.146766] Subtest plain-flip-fb-recreate: SUCCESS (35.565s)
[374.315756] [091/131] (644s left) kms_flip_event_leak (basic)
[374.411386] Starting subtest: basic
[374.413772] Starting dynamic subtest: pipe-A-eDP-1
[374.855791] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.442s)
[374.865220] Starting dynamic subtest: pipe-B-eDP-1
[375.932552] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.075s)
[375.943413] Starting dynamic subtest: pipe-C-eDP-1
[377.044341] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.112s)
[377.053562] Starting dynamic subtest: pipe-D-eDP-1
[378.169026] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.121s)
[378.171076] Subtest basic: SUCCESS (3.755s)
[379.011907] [092/131] (640s left) core_auth (getclient-master-drop)
[379.045898] Starting subtest: getclient-master-drop
[379.066135] Subtest getclient-master-drop: SUCCESS (0.022s)
[379.186884] [093/131] (639s left) kms_psr (fbc-psr2-cursor-mmap-gtt)
[379.284070] Starting subtest: fbc-psr2-cursor-mmap-gtt
[379.286233] Starting dynamic subtest: eDP-1
[379.290229] Dynamic subtest eDP-1: SKIP (0.000s)
[379.294193] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s)
[379.359310] [094/131] (639s left) kms_chamelium_hpd (vga-hpd)
[379.743745] Subtest vga-hpd: SKIP (0.000s)
[380.511013] [095/131] (638s left) kms_pipe_stress (stress-xrgb8888-ytiled)
[380.615086] Starting subtest: stress-xrgb8888-ytiled
[380.619721] Subtest stress-xrgb8888-ytiled: SKIP (0.000s)
[380.683211] [096/131] (638s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu)
[380.787196] Starting subtest: psr-rgb101010-draw-mmap-cpu
[387.684992] Subtest psr-rgb101010-draw-mmap-cpu: SUCCESS (6.899s)
[387.796250] [097/131] (631s left) kms_content_protection (dp-mst-type-1)
[387.971723] Starting subtest: dp-mst-type-1
[387.976326] Subtest dp-mst-type-1: SKIP (0.000s)
[388.109079] [098/131] (631s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[388.206059] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[388.208212] Starting dynamic subtest: pipe-A-default-mode
[389.383616] Dynamic subtest pipe-A-default-mode: SKIP (1.173s)
[389.388325] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.175s)
[389.534545] [099/131] (629s left) gem_mmap_wc (write-read)
[389.598998] Subtest write-read: SKIP (0.000s)
[389.658793] [100/131] (629s left) syncobj_timeline (reset-multiple-signaled)
[389.714446] Starting subtest: reset-multiple-signaled
[389.716746] Subtest reset-multiple-signaled: SUCCESS (0.001s)
[389.822787] [101/131] (629s left) gem_huc_copy (huc-copy)
[389.880785] Starting subtest: huc-copy
[389.885591] Subtest huc-copy: SUCCESS (0.003s)
[390.020168] [102/131] (629s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[390.115659] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[390.119843] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[390.227156] [103/131] (629s left) perf_pmu (busy-accuracy-2)
[390.266583] Starting subtest: busy-accuracy-2
[390.268367] Starting dynamic subtest: rcs0
[393.307095] Dynamic subtest rcs0: SUCCESS (3.039s)
[393.317152] Starting dynamic subtest: bcs0
[396.364283] Dynamic subtest bcs0: SUCCESS (3.055s)
[396.374706] Starting dynamic subtest: vcs0
[399.406742] Dynamic subtest vcs0: SUCCESS (3.041s)
[399.417226] Starting dynamic subtest: vcs1
[402.452119] Dynamic subtest vcs1: SUCCESS (3.043s)
[402.462465] Starting dynamic subtest: vecs0
[405.497703] Dynamic subtest vecs0: SUCCESS (3.043s)
[405.508024] Starting dynamic subtest: ccs0
[408.558823] Dynamic subtest ccs0: SUCCESS (3.059s)
[408.561328] Subtest busy-accuracy-2: SUCCESS (18.292s)
[408.695294] [104/131] (610s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[408.801303] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[408.805323] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[408.922657] [105/131] (610s left) gem_exec_fence (syncobj-timeline-wait)
[409.443050] Starting subtest: syncobj-timeline-wait
[410.905312] Subtest syncobj-timeline-wait: SUCCESS (1.463s)
[412.943920] [106/131] (606s left) gen9_exec_parse (cmd-crossing-page)
[413.012358] Subtest cmd-crossing-page: SKIP (0.000s)
[413.093497] [107/131] (606s left) kms_pwrite_crc
[414.665279] [108/131] (605s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[414.776732] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[414.781043] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[414.879363] [109/131] (604s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[414.979911] Starting subtest: fbc-tiling-linear
[418.769505] Subtest fbc-tiling-linear: SUCCESS (3.794s)
[419.948858] [110/131] (599s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc)
[420.055192] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc
[424.116459] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (4.066s)
[425.221808] [111/131] (594s left) gem_exec_whisper (basic-contexts)
[425.288552] Starting subtest: basic-contexts
[430.898928] Subtest basic-contexts: SUCCESS (5.608s)
[431.077550] [112/131] (588s left) gem_userptr_blits (probe)
[431.147561] Starting subtest: probe
[431.226273] Subtest probe: SUCCESS (0.078s)
[431.340728] [113/131] (588s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[431.396173] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[431.457575] [114/131] (588s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-pwrite)
[431.562710] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-pwrite
[440.861031] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (9.301s)
[440.997015] [115/131] (579s left) kms_vblank (ts-continuation-idle)
[441.093329] Starting subtest: ts-continuation-idle
[441.095680] Starting dynamic subtest: pipe-A-eDP-1
[441.527508] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.430s)
[441.543059] Starting dynamic subtest: pipe-D-eDP-1
[442.572770] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.044s)
[442.575073] Subtest ts-continuation-idle: SUCCESS (1.480s)
[443.445084] [116/131] (576s left) kms_psr (psr-primary-blt)
[443.553555] Starting subtest: psr-primary-blt
[443.555868] Starting dynamic subtest: eDP-1
[445.322667] Dynamic subtest eDP-1: SUCCESS (1.771s)
[445.325284] Subtest psr-primary-blt: SUCCESS (1.772s)
[446.184865] [117/131] (573s left) kms_chamelium_edid (dp-edid-read)
[446.562484] Subtest dp-edid-read: SKIP (0.000s)
[447.388432] [118/131] (572s left) kms_cursor_legacy (2x-cursor-vs-flip-atomic)
[447.510618] Starting subtest: 2x-cursor-vs-flip-atomic
[447.514696] Subtest 2x-cursor-vs-flip-atomic: SKIP (0.001s)
[447.666588] [119/131] (572s left) kms_atomic_transition (plane-all-modeset-transition)
[447.760177] Starting subtest: plane-all-modeset-transition
[447.764195] Subtest plane-all-modeset-transition: SKIP (0.000s)
[447.830556] [120/131] (572s left) i915_query (query-regions-sanity-check)
[447.881282] Starting subtest: query-regions-sanity-check
[447.890184] Subtest query-regions-sanity-check: SUCCESS (0.012s)
[448.022173] [121/131] (572s left) kms_frontbuffer_tracking (fbcpsr-suspend)
[448.121077] Starting subtest: fbcpsr-suspend
[488.649754] Subtest fbcpsr-suspend: SUCCESS (9.726s)
[488.774160] [122/131] (531s left) gem_mmap_gtt (medium-copy)
[488.839066] Subtest medium-copy: SKIP (0.000s)
[488.898782] [123/131] (531s left) syncobj_basic (bad-handle-to-fd)
[488.951391] Starting subtest: bad-handle-to-fd
[488.953685] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[489.073762] [124/131] (531s left) gem_softpin (full)
[489.145302] Starting subtest: full
[489.153428] Subtest full: SUCCESS (0.008s)
[489.265101] [125/131] (531s left) gem_ctx_param (non-root-set)
[489.322962] Starting subtest: non-root-set
[489.348338] Subtest non-root-set: SUCCESS (0.025s)
[489.450018] [126/131] (531s left) syncobj_timeline (single-wait-available-submitted)
[489.503876] Starting subtest: single-wait-available-submitted
[489.506254] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[489.615070] [127/131] (530s left) gem_ctx_shared (q-out-order)
[489.671840] Starting subtest: Q-out-order
[489.673876] Starting dynamic subtest: rcs0
[489.773250] Dynamic subtest rcs0: SUCCESS (0.100s)
[489.790812] Starting dynamic subtest: vcs0
[489.856138] Dynamic subtest vcs0: SUCCESS (0.083s)
[489.871759] Starting dynamic subtest: vcs1
[489.936935] Dynamic subtest vcs1: SUCCESS (0.080s)
[489.956552] Starting dynamic subtest: vecs0
[490.014705] Dynamic subtest vecs0: SUCCESS (0.076s)
[490.031077] Starting dynamic subtest: ccs0
[490.093914] Dynamic subtest ccs0: SUCCESS (0.078s)
[490.096269] Subtest Q-out-order: SUCCESS (0.421s)
[491.774503] [128/131] (528s left) gem_exec_balancer (bonded-chain)
[491.828736] Starting subtest: bonded-chain
[498.836356] Subtest bonded-chain: SUCCESS (6.999s)
[499.168024] [129/131] (521s left) gem_mmap_wc (read)
[499.233571] Subtest read: SKIP (0.000s)
[499.297406] [130/131] (521s left) kms_pm_rpm (universal-planes)
[500.830018] Starting subtest: universal-planes
[500.832534] Starting dynamic subtest: plane-33
[509.132992] Dynamic subtest plane-33: SUCCESS (8.297s)
[509.145216] Starting dynamic subtest: plane-43
[517.531455] Dynamic subtest plane-43: SUCCESS (8.393s)
[517.544060] Starting dynamic subtest: plane-53
[525.828600] Dynamic subtest plane-53: SUCCESS (8.291s)
[525.840536] Starting dynamic subtest: plane-63
[534.319154] Dynamic subtest plane-63: SUCCESS (8.486s)
[534.331108] Starting dynamic subtest: plane-73
[542.721655] Dynamic subtest plane-73: SUCCESS (8.395s)
[542.733043] Starting dynamic subtest: plane-83
[551.009634] Dynamic subtest plane-83: SUCCESS (8.284s)
[551.011968] Subtest universal-planes: SUCCESS (50.158s)
[551.381498] [131/131] (469s left) gem_ctx_engines (execute-one)
[551.446718] Starting subtest: execute-one
[553.592476] Subtest execute-one: SUCCESS (2.145s)
[553.829370] Closing watchdogs
results: parsing output: 0/ for test: kms_plane_scaling
results: parsing output: 1/ for test: syncobj_wait
results: parsing output: 2/ for test: gem_render_copy
results: parsing output: 3/ for test: intel_sysfs_debugfs
results: parsing output: 4/ for test: syncobj_wait
results: parsing output: 5/ for test: kms_ccs
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: gem_exec_fence
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_exec_suspend
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: prime_mmap_coherency
results: parsing output: 12/ for test: kms_big_fb
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: gem_exec_schedule
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: gem_reg_read
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_cursor_legacy
results: parsing output: 21/ for test: gem_ctx_persistence
results: parsing output: 22/ for test: sriov_basic
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: kms_plane
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: perf_pmu
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: gen9_exec_parse
results: parsing output: 29/ for test: gem_mmap_gtt
results: parsing output: 30/ for test: kms_chamelium_hpd
results: parsing output: 31/ for test: api_intel_bb
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: gem_exec_reloc
results: parsing output: 34/ for test: kms_atomic_interruptible
results: parsing output: 35/ for test: kms_flip
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: syncobj_timeline
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: kms_cursor_crc
results: parsing output: 41/ for test: syncobj_timeline
results: parsing output: 42/ for test: gem_pxp
results: parsing output: 43/ for test: gem_exec_flush
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: kms_addfb_basic
results: parsing output: 46/ for test: kms_lease
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: kms_rotation_crc
results: parsing output: 50/ for test: syncobj_wait
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: kms_psr
results: parsing output: 53/ for test: kms_flip_scaled_crc
results: parsing output: 54/ for test: kms_pm_rpm
results: parsing output: 55/ for test: kms_cursor_legacy
results: parsing output: 56/ for test: sysfs_preempt_timeout
results: parsing output: 57/ for test: kms_psr2_sf
results: parsing output: 58/ for test: gem_exec_balancer
results: parsing output: 59/ for test: gem_exec_capture
results: parsing output: 60/ for test: gem_mmap_gtt
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: gem_render_copy
results: parsing output: 64/ for test: kms_setmode
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: kms_plane_scaling
results: parsing output: 67/ for test: gem_ctx_param
results: parsing output: 68/ for test: kms_async_flips
results: parsing output: 69/ for test: gem_lmem_swapping
results: parsing output: 70/ for test: kms_cursor_crc
results: parsing output: 71/ for test: i915_hangman
results: parsing output: 72/ for test: kms_invalid_mode
results: parsing output: 73/ for test: kms_dp_link_training
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_psr
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_psr2_sf
results: parsing output: 78/ for test: kms_vblank
results: parsing output: 79/ for test: gem_tiled_wc
results: parsing output: 80/ for test: gem_softpin
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: i915_pm_rpm
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_ccs
results: parsing output: 85/ for test: gem_exec_schedule
results: parsing output: 86/ for test: prime_mmap
results: parsing output: 87/ for test: gem_mmap_offset
results: parsing output: 88/ for test: drm_buddy
results: parsing output: 89/ for test: kms_flip
results: parsing output: 90/ for test: kms_flip_event_leak
results: parsing output: 91/ for test: core_auth
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: kms_chamelium_hpd
results: parsing output: 94/ for test: kms_pipe_stress
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_content_protection
results: parsing output: 97/ for test: kms_flip_scaled_crc
results: parsing output: 98/ for test: gem_mmap_wc
results: parsing output: 99/ for test: syncobj_timeline
results: parsing output: 100/ for test: gem_huc_copy
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: perf_pmu
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_fence
results: parsing output: 105/ for test: gen9_exec_parse
results: parsing output: 106/ for test: kms_pwrite_crc
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_exec_whisper
results: parsing output: 111/ for test: gem_userptr_blits
results: parsing output: 112/ for test: gem_mmap_gtt
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_vblank
results: parsing output: 115/ for test: kms_psr
results: parsing output: 116/ for test: kms_chamelium_edid
results: parsing output: 117/ for test: kms_cursor_legacy
results: parsing output: 118/ for test: kms_atomic_transition
results: parsing output: 119/ for test: i915_query
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: gem_mmap_gtt
results: parsing output: 122/ for test: syncobj_basic
results: parsing output: 123/ for test: gem_softpin
results: parsing output: 124/ for test: gem_ctx_param
results: parsing output: 125/ for test: syncobj_timeline
results: parsing output: 126/ for test: gem_ctx_shared
results: parsing output: 127/ for test: gem_exec_balancer
results: parsing output: 128/ for test: gem_mmap_wc
results: parsing output: 129/ for test: kms_pm_rpm
results: parsing output: 130/ for test: gem_ctx_engines
Done.