[87.440849] Initializing watchdogs
[87.441091]   /dev/watchdog0
[87.477904] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[87.491863] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[87.498698] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[87.503961] [001/130] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[87.671413] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[87.751461] [002/130] (960s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[87.913502] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[87.916155] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.001s)
[87.993326] [003/130] (960s left) kms_cursor_edge_walk (128x128-top-edge)
[88.140371] Subtest 128x128-top-edge: SKIP (0.000s)
[88.215657] [004/130] (960s left) kms_content_protection (dp-mst-lic-type-0)
[88.361945] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[88.432884] [005/130] (959s left) kms_joiner (basic-max-non-joiner)
[88.580426] Starting subtest: basic-max-non-joiner
[88.582103] Subtest basic-max-non-joiner: SKIP (0.000s)
[88.654023] [006/130] (959s left) gem_ctx_isolation (preservation-reset)
[88.884542] Starting subtest: preservation-reset
[88.885626] Starting dynamic subtest: rcs0
[89.995659] Dynamic subtest rcs0: SUCCESS (1.110s)
[90.011743] Starting dynamic subtest: bcs0
[91.245944] Dynamic subtest bcs0: SUCCESS (1.247s)
[91.263184] Starting dynamic subtest: vcs0
[92.220035] Dynamic subtest vcs0: SUCCESS (0.976s)
[92.233999] Starting dynamic subtest: vecs0
[93.199226] Dynamic subtest vecs0: SUCCESS (0.978s)
[93.201536] Subtest preservation-reset: SUCCESS (4.317s)
[93.319041] [007/130] (955s left) prime_mmap (test_dup)
[93.416028] Starting subtest: test_dup
[93.416868] Starting dynamic subtest: test_dup-smem
[93.421654] Dynamic subtest test_dup-smem: SUCCESS (0.006s)
[93.422616] Subtest test_dup: SUCCESS (0.007s)
[93.491726] [008/130] (955s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[93.587123] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[93.588061] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.001s)
[93.658960] [009/130] (954s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[93.808421] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[93.809339] Starting dynamic subtest: pipe-A
[93.811168] Dynamic subtest pipe-A: SKIP (0.000s)
[93.824184] Starting dynamic subtest: pipe-B
[93.825797] Dynamic subtest pipe-B: SKIP (0.000s)
[93.838521] Starting dynamic subtest: pipe-C
[93.840172] Dynamic subtest pipe-C: SKIP (0.000s)
[93.841798] Subtest plane-scaler-with-clipping-clamping-modifiers: SKIP (0.001s)
[93.905264] [010/130] (954s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[94.054494] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[94.134840] [011/130] (954s left) kms_joiner (invalid-modeset-ultra-joiner)
[94.283527] Starting subtest: invalid-modeset-ultra-joiner
[94.285407] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[94.354830] [012/130] (954s left) i915_pm_rc6_residency (media-rc6-accuracy)
[94.449640] Starting subtest: media-rc6-accuracy
[94.451926] Subtest media-rc6-accuracy: SKIP (0.000s)
[94.521757] [013/130] (954s left) kms_pm_rpm (pm-caching)
[95.927332] Starting subtest: pm-caching
[97.174692] Subtest pm-caching: SUCCESS (1.247s)
[97.253617] [014/130] (951s left) kms_addfb_basic (too-high)
[97.441911] Starting subtest: too-high
[97.444281] Subtest too-high: SUCCESS (0.002s)
[97.538185] [015/130] (951s left) gem_eio (context-create)
[97.656067] Starting subtest: context-create
[97.692405] Subtest context-create: SUCCESS (0.039s)
[97.823806] [016/130] (951s left) gem_exec_suspend (basic-s4-devices)
[97.941826] Starting subtest: basic-S4-devices
[97.942912] Starting dynamic subtest: smem
[107.950683] Dynamic subtest smem: SUCCESS (10.008s)
[107.951989] Subtest basic-S4-devices: SUCCESS (10.009s)
[115.149126] [017/130] (941s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[115.317518] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[115.401778] [018/130] (940s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[115.555565] Subtest cursorB-vs-flipB-atomic: SKIP (0.000s)
[115.626478] [019/130] (940s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[115.775756] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[115.849703] [020/130] (940s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-gtt)
[115.999353] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[116.076734] [021/130] (940s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[116.223849] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[116.302461] [022/130] (940s left) sw_sync (sync_busy_fork_unixsocket)
[116.398437] Starting subtest: sync_busy_fork_unixsocket
[116.418933] Subtest sync_busy_fork_unixsocket: SUCCESS (0.022s)
[116.499803] [023/130] (940s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-submitted-signaled)
[116.597218] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[116.599711] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.002s)
[116.680105] [024/130] (940s left) gem_exec_params (invalid-bsd-ring)
[116.786390] Starting subtest: invalid-bsd-ring
[116.788448] Subtest invalid-bsd-ring: SUCCESS (0.002s)
[116.901611] [025/130] (939s left) syncobj_timeline (invalid-wait-illegal-handle)
[116.999782] Starting subtest: invalid-wait-illegal-handle
[117.000859] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[117.083514] [026/130] (939s left) kms_psr (psr2-cursor-plane-onoff)
[117.232891] Subtest psr2-cursor-plane-onoff: SKIP (0.000s)
[117.312695] [027/130] (939s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[117.425623] Starting subtest: y-tiled-ccs-to-y-tiled
[117.427559] Starting dynamic subtest: smem
[117.669622] Dynamic subtest smem: SUCCESS (0.246s)
[117.670537] Subtest y-tiled-ccs-to-y-tiled: SUCCESS (0.246s)
[117.862994] [028/130] (939s left) gen9_exec_parse (allowed-all)
[117.983398] Starting subtest: allowed-all
[117.987174] Subtest allowed-all: SUCCESS (0.003s)
[118.202460] [029/130] (938s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[118.349856] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[118.424640] [030/130] (938s left) gem_userptr_blits (stress-purge)
[118.554428] Starting subtest: stress-purge
[123.977314] Subtest stress-purge: SUCCESS (5.423s)
[124.137685] [031/130] (933s left) gem_render_copy (y-tiled-ccs-to-y-tiled-ccs)
[124.333385] Starting subtest: y-tiled-ccs-to-y-tiled-ccs
[124.334384] Starting dynamic subtest: smem
[124.597832] Dynamic subtest smem: SUCCESS (0.263s)
[124.598984] Subtest y-tiled-ccs-to-y-tiled-ccs: SUCCESS (0.264s)
[124.784797] [032/130] (932s left) core_setmaster (master-drop-set-root)
[124.906494] Starting subtest: master-drop-set-root
[124.908324] Subtest master-drop-set-root: SUCCESS (0.002s)
[124.989459] [033/130] (932s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[125.145322] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[125.231964] [034/130] (932s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[125.379952] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[125.461981] [035/130] (932s left) kms_psr (psr-primary-mmap-gtt)
[125.607676] Subtest psr-primary-mmap-gtt: SKIP (0.000s)
[125.683568] [036/130] (932s left) api_intel_allocator (reopen)
[125.774555] Starting subtest: reopen
[125.782878] Subtest reopen: SUCCESS (0.008s)
[125.889680] [037/130] (931s left) gem_ctx_persistence (idempotent)
[125.997090] Starting subtest: idempotent
[125.998113] Subtest idempotent: SUCCESS (0.000s)
[126.107651] [038/130] (931s left) syncobj_wait (multi-wait-for-submit-unsubmitted)
[126.202988] Starting subtest: multi-wait-for-submit-unsubmitted
[126.313074] Subtest multi-wait-for-submit-unsubmitted: SUCCESS (0.107s)
[126.393932] [039/130] (931s left) gem_mmap_gtt (medium-copy-odd)
[126.487158] Starting subtest: medium-copy-odd
[131.313722] Subtest medium-copy-odd: SUCCESS (4.826s)
[131.442843] [040/130] (926s left) kms_psr (fbc-psr-sprite-blt)
[131.673793] Subtest fbc-psr-sprite-blt: SKIP (0.000s)
[131.748156] [041/130] (926s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[131.895122] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[131.976450] [042/130] (926s left) perf (create-destroy-userspace-config)
[132.174927] Starting subtest: create-destroy-userspace-config
[132.243827] Subtest create-destroy-userspace-config: SUCCESS (0.069s)
[132.318563] [043/130] (925s left) gem_exec_reloc (basic-gtt-wc)
[132.426019] Starting subtest: basic-gtt-wc
[132.443416] Subtest basic-gtt-wc: SUCCESS (0.017s)
[132.553136] [044/130] (925s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc)
[132.697963] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[132.784861] [045/130] (925s left) gem_mmap (basic)
[132.876337] Starting subtest: basic
[132.880539] Subtest basic: SUCCESS (0.001s)
[132.974779] [046/130] (925s left) gem_bad_reloc (negative-reloc-bltcopy)
[133.081141] Starting subtest: negative-reloc-bltcopy
[135.903117] Subtest negative-reloc-bltcopy: SUCCESS (2.819s)
[136.135056] [047/130] (922s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[136.279297] Starting subtest: sprite-rotation-90-pos-100-0
[136.281131] Subtest sprite-rotation-90-pos-100-0: SKIP (0.000s)
[136.355330] [048/130] (922s left) perf (sysctl-defaults)
[136.482301] Starting subtest: sysctl-defaults
[136.483379] Subtest sysctl-defaults: SUCCESS (0.000s)
[136.577129] [049/130] (922s left) gem_ctx_persistence (legacy-engines-queued)
[136.684520] Starting subtest: legacy-engines-queued
[136.686162] Starting dynamic subtest: render
[136.734876] Dynamic subtest render: SUCCESS (0.048s)
[136.753269] Starting dynamic subtest: bsd
[136.799311] Dynamic subtest bsd: SUCCESS (0.062s)
[136.817035] Starting dynamic subtest: blt
[136.929396] Dynamic subtest blt: SUCCESS (0.128s)
[136.947540] Starting dynamic subtest: vebox
[136.990402] Dynamic subtest vebox: SUCCESS (0.058s)
[136.993476] Subtest legacy-engines-queued: SUCCESS (0.305s)
[137.076369] [050/130] (921s left) kms_psr (fbc-pr-sprite-render)
[137.221754] Subtest fbc-pr-sprite-render: SKIP (0.000s)
[137.298975] [051/130] (921s left) gem_exercise_blt (fast-copy)
[137.405073] Starting subtest: fast-copy
[137.406014] Starting dynamic subtest: linear-smem-smem
[137.465296] Dynamic subtest linear-smem-smem: SUCCESS (0.060s)
[137.482904] Starting dynamic subtest: ymajor-smem-smem
[137.508900] Dynamic subtest ymajor-smem-smem: SUCCESS (0.043s)
[137.528589] Starting dynamic subtest: yfmajor-smem-smem
[137.552988] Dynamic subtest yfmajor-smem-smem: SUCCESS (0.043s)
[137.571565] Starting dynamic subtest: tile64-smem-smem
[137.595055] Dynamic subtest tile64-smem-smem: SUCCESS (0.041s)
[137.596078] Subtest fast-copy: SUCCESS (0.189s)
[137.699080] [052/130] (921s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[137.843974] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[137.926409] [053/130] (921s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs)
[138.071726] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[138.146645] [054/130] (921s left) gem_mmap_gtt (cpuset-big-copy-xy)
[138.239369] Starting subtest: cpuset-big-copy-XY
[158.674711] Subtest cpuset-big-copy-XY: SUCCESS (20.434s)
[158.972094] [055/130] (900s left) gem_exec_schedule (fifo)
[159.185997] Starting subtest: fifo
[159.186937] Starting dynamic subtest: rcs0
[159.311799] Dynamic subtest rcs0: SUCCESS (0.121s)
[159.331640] Starting dynamic subtest: bcs0
[159.429336] Dynamic subtest bcs0: SUCCESS (0.120s)
[159.449635] Starting dynamic subtest: vcs0
[159.543381] Dynamic subtest vcs0: SUCCESS (0.110s)
[159.562666] Starting dynamic subtest: vecs0
[159.650723] Dynamic subtest vecs0: SUCCESS (0.109s)
[159.654641] Subtest fifo: SUCCESS (0.464s)
[160.008712] [056/130] (899s left) gem_create (create-ext-cpu-access-big)
[160.223666] Starting subtest: create-ext-cpu-access-big
[160.225893] Subtest create-ext-cpu-access-big: SKIP (0.001s)
[160.304243] [057/130] (899s left) kms_plane_multiple (2x-tiling-y)
[160.453492] Subtest 2x-tiling-y: SKIP (0.000s)
[160.530358] [058/130] (898s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[160.677245] Subtest psr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[160.764272] [059/130] (898s left) kms_scaling_modes (scaling-mode-none)
[160.907499] Subtest scaling-mode-none: SKIP (0.000s)
[160.982606] [060/130] (898s left) gem_pxp (create-protected-buffer)
[163.615376] Starting subtest: create-protected-buffer
[163.617323] Subtest create-protected-buffer: SKIP (0.000s)
[163.696634] [061/130] (896s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-mc-ccs)
[163.840434] Subtest crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.000s)
[163.915132] [062/130] (895s left) perf_pmu (busy-start)
[164.021177] Starting subtest: busy-start
[164.022117] Starting dynamic subtest: rcs0
[166.553656] Dynamic subtest rcs0: SUCCESS (2.532s)
[166.578080] Starting dynamic subtest: bcs0
[169.079998] Dynamic subtest bcs0: SUCCESS (2.527s)
[169.112661] Starting dynamic subtest: vcs0
[171.609372] Dynamic subtest vcs0: SUCCESS (2.529s)
[171.639268] Starting dynamic subtest: vecs0
[174.138862] Dynamic subtest vecs0: SUCCESS (2.528s)
[174.140377] Subtest busy-start: SUCCESS (10.118s)
[174.221983] [063/130] (885s left) kms_color (legacy-gamma)
[174.368464] Starting subtest: legacy-gamma
[174.370602] Subtest legacy-gamma: SKIP (0.000s)
[174.447799] [064/130] (885s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[174.594766] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[174.684548] [065/130] (885s left) kms_dp_link_training (uhbr-mst)
[174.833196] Subtest uhbr-mst: SKIP (0.000s)
[174.909439] [066/130] (885s left) kms_psr2_sf (psr2-plane-move-sf-dmg-area)
[175.059315] Subtest psr2-plane-move-sf-dmg-area: SKIP (0.000s)
[175.136434] [067/130] (885s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[175.245461] Starting subtest: y-tiled-to-vebox-y-tiled
[175.247263] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[175.445476] [068/130] (884s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[175.592194] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[175.682409] [069/130] (884s left) i915_drm_fdinfo (busy-hang)
[175.784820] Starting subtest: busy-hang
[175.785820] Starting dynamic subtest: rcs0
[176.822959] Dynamic subtest rcs0: SUCCESS (1.038s)
[176.842491] Starting dynamic subtest: bcs0
[177.875462] Dynamic subtest bcs0: SUCCESS (1.051s)
[177.895568] Starting dynamic subtest: vcs0
[178.916310] Dynamic subtest vcs0: SUCCESS (1.041s)
[178.936192] Starting dynamic subtest: vecs0
[179.958972] Dynamic subtest vecs0: SUCCESS (1.041s)
[179.959956] Subtest busy-hang: SUCCESS (4.173s)
[180.040561] [070/130] (880s left) kms_flip (2x-flip-vs-wf_vblank)
[180.190196] Starting subtest: 2x-flip-vs-wf_vblank
[180.191870] Subtest 2x-flip-vs-wf_vblank: SKIP (0.001s)
[180.290577] [071/130] (880s left) gem_lmem_swapping (basic)
[180.397321] Subtest basic: SKIP (0.000s)
[180.475391] [072/130] (880s left) kms_atomic_transition (plane-all-transition-nonblocking)
[180.620637] Subtest plane-all-transition-nonblocking: SKIP (0.000s)
[180.696799] [073/130] (880s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[180.849234] Subtest cursorA-vs-flipA-atomic: SKIP (0.000s)
[180.924810] [074/130] (879s left) syncobj_timeline (single-wait-all-available-submitted)
[181.021338] Starting subtest: single-wait-all-available-submitted
[181.022389] Subtest single-wait-all-available-submitted: SUCCESS (0.001s)
[181.104542] [075/130] (879s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[181.252529] Subtest psr-rgb565-draw-blt: SKIP (0.000s)
[181.353310] [076/130] (879s left) kms_vblank (wait-forked-busy)
[181.502240] Subtest wait-forked-busy: SKIP (0.000s)
[181.580220] [077/130] (879s left) kms_properties (crtc-properties-atomic)
[181.724727] Starting subtest: crtc-properties-atomic
[181.726486] Subtest crtc-properties-atomic: SKIP (0.000s)
[181.803319] [078/130] (879s left) kms_big_fb (y-tiled-addfb-size-overflow)
[181.950276] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[182.028411] [079/130] (879s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[182.123991] Starting subtest: invalid-multi-wait-unsubmitted
[182.125061] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[182.206419] [080/130] (879s left) kms_addfb_basic (master-rmfb)
[182.300219] Starting subtest: master-rmfb
[182.301114] Subtest master-rmfb: SUCCESS (0.002s)
[182.451770] [081/130] (878s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[182.599627] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[182.684245] [082/130] (878s left) gem_exec_store (pages)
[182.798903] Starting subtest: pages
[182.799819] Starting dynamic subtest: rcs0
[182.884994] Dynamic subtest rcs0: SUCCESS (0.085s)
[182.907276] Starting dynamic subtest: bcs0
[182.973360] Dynamic subtest bcs0: SUCCESS (0.087s)
[182.992620] Starting dynamic subtest: vcs0
[183.054050] Dynamic subtest vcs0: SUCCESS (0.078s)
[183.074203] Starting dynamic subtest: vecs0
[183.156238] Dynamic subtest vecs0: SUCCESS (0.104s)
[183.157324] Subtest pages: SUCCESS (0.357s)
[183.351824] [083/130] (878s left) kms_plane_alpha_blend (constant-alpha-mid)
[183.498276] Starting subtest: constant-alpha-mid
[183.499905] Subtest constant-alpha-mid: SKIP (0.000s)
[183.582541] [084/130] (878s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[183.731228] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[183.816107] [085/130] (877s left) kms_pm_dc (dc5-dpms-negative)
[185.262471] Starting subtest: dc5-dpms-negative
[185.264271] Subtest dc5-dpms-negative: SKIP (0.000s)
[185.344174] [086/130] (876s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[185.535459] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[185.618513] [087/130] (876s left) kms_atomic_transition (modeset-transition-nonblocking-fencing)
[185.766560] Subtest modeset-transition-nonblocking-fencing: SKIP (0.000s)
[185.844181] [088/130] (876s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[185.989324] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.000s)
[186.066174] [089/130] (875s left) kms_busy (extended-pageflip-hang-oldfb)
[186.204127] Subtest extended-pageflip-hang-oldfb: SKIP (0.000s)
[186.280571] [090/130] (875s left) kms_chamelium_color (ctm-blue-to-red)
[186.425680] Subtest ctm-blue-to-red: SKIP (0.000s)
[186.503172] [091/130] (875s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[186.647359] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[186.726362] [092/130] (875s left) gem_readwrite (read-write)
[186.819809] Starting subtest: read-write
[186.823353] Subtest read-write: SUCCESS (0.001s)
[186.922742] [093/130] (875s left) gem_exec_create (legacy)
[187.036764] Starting subtest: legacy
[187.037845] Starting dynamic subtest: smem
[189.093285] Dynamic subtest smem: SUCCESS (2.054s)
[189.094395] Subtest legacy: SUCCESS (2.054s)
[189.291180] [094/130] (873s left) kms_async_flips (invalid-async-flip-atomic)
[189.439574] Subtest invalid-async-flip-atomic: SKIP (0.000s)
[189.518356] [095/130] (873s left) kms_cursor_legacy (flip-vs-cursor-crc-legacy)
[189.669272] Subtest flip-vs-cursor-crc-legacy: SKIP (0.000s)
[189.743552] [096/130] (872s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[189.889640] Subtest x-tiled-32bpp-rotate-90: SKIP (0.000s)
[189.965905] [097/130] (872s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[190.116456] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[190.199778] [098/130] (872s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[190.346608] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[190.432342] [099/130] (872s left) prime_busy (before)
[190.536887] Starting subtest: before
[190.548602] Starting dynamic subtest: rcs0
[190.567623] Dynamic subtest rcs0: SUCCESS (0.018s)
[190.588337] Starting dynamic subtest: bcs0
[190.591316] Dynamic subtest bcs0: SUCCESS (0.020s)
[190.613037] Starting dynamic subtest: vcs0
[190.613952] Dynamic subtest vcs0: SUCCESS (0.020s)
[190.634116] Starting dynamic subtest: vecs0
[190.634959] Dynamic subtest vecs0: SUCCESS (0.017s)
[190.736112] Subtest before: SUCCESS (0.198s)
[190.815939] [100/130] (872s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[190.962996] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[191.047494] [101/130] (871s left) gem_mmap_offset (partial-remap)
[191.143239] Starting subtest: partial-remap
[191.144327] Starting dynamic subtest: smem0
[191.156189] Dynamic subtest smem0: SUCCESS (0.014s)
[191.157361] Subtest partial-remap: SUCCESS (0.016s)
[191.265118] [102/130] (871s left) kms_atomic_transition (plane-use-after-nonblocking-unbind)
[191.408688] Subtest plane-use-after-nonblocking-unbind: SKIP (0.000s)
[191.484972] [103/130] (871s left) syncobj_basic (bad-flags-handle-to-fd)
[191.578447] Starting subtest: bad-flags-handle-to-fd
[191.579419] Subtest bad-flags-handle-to-fd: SUCCESS (0.000s)
[191.655697] [104/130] (871s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[191.803030] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[191.805370] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (0.000s)
[191.882251] [105/130] (871s left) dmabuf_sync_file (import-basic)
[192.025974] Starting subtest: import-basic
[192.030057] Subtest import-basic: SUCCESS (0.005s)
[192.113782] [FACT dmabuf_sync_file (import-basic)] new: kernel.kmod_is_loaded.vgem: true
[192.117398] [106/130] (871s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[192.270078] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[192.356933] [107/130] (871s left) gem_render_copy_redux (flink)
[192.464576] Starting subtest: flink
[195.861377] Subtest flink: SUCCESS (3.397s)
[195.987417] [108/130] (867s left) gem_exec_schedule (preempt-contexts)
[196.112372] Starting subtest: preempt-contexts
[196.113589] Starting dynamic subtest: rcs0
[196.225371] Dynamic subtest rcs0: SUCCESS (0.112s)
[196.247206] Starting dynamic subtest: bcs0
[196.347703] Dynamic subtest bcs0: SUCCESS (0.123s)
[196.370792] Starting dynamic subtest: vcs0
[196.452590] Dynamic subtest vcs0: SUCCESS (0.103s)
[196.473881] Starting dynamic subtest: vecs0
[196.556094] Dynamic subtest vecs0: SUCCESS (0.104s)
[196.557860] Subtest preempt-contexts: SUCCESS (0.444s)
[196.921428] [109/130] (866s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[197.078049] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[197.080420] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.001s)
[197.173436] [110/130] (866s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[197.319330] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[197.321061] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SKIP (0.000s)
[197.406328] [111/130] (866s left) gem_exec_balancer (persistence)
[197.647893] Starting subtest: persistence
[201.779143] Subtest persistence: SUCCESS (4.131s)
[201.874592] [112/130] (862s left) i915_pm_rps (thresholds-idle)
[201.986182] Starting subtest: thresholds-idle
[201.987111] Starting dynamic subtest: gt0
[212.378884] Dynamic subtest gt0: SUCCESS (10.392s)
[212.380174] Subtest thresholds-idle: SUCCESS (10.394s)
[212.478442] [113/130] (851s left) gem_exec_params (sol-reset-not-gen7)
[212.581671] Starting subtest: sol-reset-not-gen7
[212.585501] Subtest sol-reset-not-gen7: SUCCESS (0.003s)
[212.691932] [114/130] (851s left) kms_big_fb (linear-16bpp-rotate-180)
[212.837573] Subtest linear-16bpp-rotate-180: SKIP (0.000s)
[212.925487] [115/130] (851s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[213.071110] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[213.163848] [116/130] (851s left) kms_psr (fbc-psr2-sprite-mmap-cpu)
[213.309025] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.000s)
[213.396696] [117/130] (851s left) kms_cursor_crc (cursor-random-256x256)
[213.541688] Subtest cursor-random-256x256: SKIP (0.000s)
[213.632066] [118/130] (850s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[213.775708] Starting subtest: plane-upscale-20x20-with-pixel-format
[213.776589] Starting dynamic subtest: pipe-A
[213.778287] Dynamic subtest pipe-A: SKIP (0.000s)
[213.797732] Starting dynamic subtest: pipe-B
[213.799371] Dynamic subtest pipe-B: SKIP (0.000s)
[213.824239] Starting dynamic subtest: pipe-C
[213.827111] Dynamic subtest pipe-C: SKIP (0.000s)
[213.829222] Subtest plane-upscale-20x20-with-pixel-format: SKIP (0.001s)
[213.913617] [119/130] (850s left) perf (global-sseu-config)
[214.106735] Starting subtest: global-sseu-config
[214.107678] Starting dynamic subtest: 0-rcs0
[214.199249] Dynamic subtest 0-rcs0: SUCCESS (0.092s)
[214.200247] Subtest global-sseu-config: SUCCESS (0.093s)
[214.288925] [120/130] (850s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[214.382411] Starting subtest: etime-multi-wait-all-for-submit-submitted
[214.486802] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.103s)
[214.582920] [121/130] (850s left) kms_flip (nonexisting-fb-interruptible)
[214.737280] Starting subtest: nonexisting-fb-interruptible
[214.742329] Subtest nonexisting-fb-interruptible: SKIP (0.002s)
[214.837891] [122/130] (850s left) i915_drm_fdinfo (isolation)
[214.938872] Starting subtest: isolation
[214.939910] Starting dynamic subtest: rcs0
[215.474822] Dynamic subtest rcs0: SUCCESS (0.536s)
[215.494703] Starting dynamic subtest: bcs0
[216.011853] Dynamic subtest bcs0: SUCCESS (0.536s)
[216.032269] Starting dynamic subtest: vcs0
[216.550805] Dynamic subtest vcs0: SUCCESS (0.538s)
[216.571255] Starting dynamic subtest: vecs0
[217.088879] Dynamic subtest vecs0: SUCCESS (0.537s)
[217.090217] Subtest isolation: SUCCESS (2.150s)
[217.185837] [123/130] (847s left) gem_exec_await (wide-contexts)
[217.448609] Starting subtest: wide-contexts
[239.295375] Subtest wide-contexts: SUCCESS (21.847s)
[239.535625] [124/130] (825s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[239.771071] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.000s)
[239.857253] [125/130] (825s left) gem_exec_params (invalid-fence-in-submit)
[239.961979] Starting subtest: invalid-fence-in-submit
[239.968026] Subtest invalid-fence-in-submit: SUCCESS (0.004s)
[240.097601] [126/130] (825s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[240.244543] Starting subtest: primary-yf-tiled-reflect-x-90
[240.246404] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.000s)
[240.331650] [127/130] (825s left) kms_sequence (get-idle)
[240.476104] Subtest get-idle: SKIP (0.000s)
[240.561788] [128/130] (824s left) kms_properties (invalid-properties-atomic)
[240.708295] Starting subtest: invalid-properties-atomic
[241.033648] Subtest invalid-properties-atomic: SUCCESS (0.326s)
[241.126469] [129/130] (824s left) kms_cursor_crc (cursor-sliding-max-size)
[241.274128] Subtest cursor-sliding-max-size: SKIP (0.000s)
[241.360746] [130/130] (824s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[241.512702] Subtest basic-flip-after-cursor-varying-size: SKIP (0.000s)
[241.592734] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_flip
results: parsing output: 2/ for test: kms_cursor_edge_walk
results: parsing output: 3/ for test: kms_content_protection
results: parsing output: 4/ for test: kms_joiner
results: parsing output: 5/ for test: gem_ctx_isolation
results: parsing output: 6/ for test: prime_mmap
results: parsing output: 7/ for test: syncobj_wait
results: parsing output: 8/ for test: kms_plane_scaling
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_joiner
results: parsing output: 11/ for test: i915_pm_rc6_residency
results: parsing output: 12/ for test: kms_pm_rpm
results: parsing output: 13/ for test: kms_addfb_basic
results: parsing output: 14/ for test: gem_eio
results: parsing output: 15/ for test: gem_exec_suspend
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_cursor_legacy
results: parsing output: 18/ for test: kms_psr2_sf
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_big_fb
results: parsing output: 21/ for test: sw_sync
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: gem_exec_params
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: gem_render_copy
results: parsing output: 27/ for test: gen9_exec_parse
results: parsing output: 28/ for test: kms_big_fb
results: parsing output: 29/ for test: gem_userptr_blits
results: parsing output: 30/ for test: gem_render_copy
results: parsing output: 31/ for test: core_setmaster
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_psr
results: parsing output: 35/ for test: api_intel_allocator
results: parsing output: 36/ for test: gem_ctx_persistence
results: parsing output: 37/ for test: syncobj_wait
results: parsing output: 38/ for test: gem_mmap_gtt
results: parsing output: 39/ for test: kms_psr
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: perf
results: parsing output: 42/ for test: gem_exec_reloc
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: gem_mmap
results: parsing output: 45/ for test: gem_bad_reloc
results: parsing output: 46/ for test: kms_rotation_crc
results: parsing output: 47/ for test: perf
results: parsing output: 48/ for test: gem_ctx_persistence
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: gem_exercise_blt
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_ccs
results: parsing output: 53/ for test: gem_mmap_gtt
results: parsing output: 54/ for test: gem_exec_schedule
results: parsing output: 55/ for test: gem_create
results: parsing output: 56/ for test: kms_plane_multiple
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_scaling_modes
results: parsing output: 59/ for test: gem_pxp
results: parsing output: 60/ for test: kms_ccs
results: parsing output: 61/ for test: perf_pmu
results: parsing output: 62/ for test: kms_color
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_dp_link_training
results: parsing output: 65/ for test: kms_psr2_sf
results: parsing output: 66/ for test: gem_render_copy
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: i915_drm_fdinfo
results: parsing output: 69/ for test: kms_flip
results: parsing output: 70/ for test: gem_lmem_swapping
results: parsing output: 71/ for test: kms_atomic_transition
results: parsing output: 72/ for test: kms_cursor_legacy
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_vblank
results: parsing output: 76/ for test: kms_properties
results: parsing output: 77/ for test: kms_big_fb
results: parsing output: 78/ for test: syncobj_timeline
results: parsing output: 79/ for test: kms_addfb_basic
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_store
results: parsing output: 82/ for test: kms_plane_alpha_blend
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_pm_dc
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_atomic_transition
results: parsing output: 87/ for test: kms_ccs
results: parsing output: 88/ for test: kms_busy
results: parsing output: 89/ for test: kms_chamelium_color
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: gem_readwrite
results: parsing output: 92/ for test: gem_exec_create
results: parsing output: 93/ for test: kms_async_flips
results: parsing output: 94/ for test: kms_cursor_legacy
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: prime_busy
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: gem_mmap_offset
results: parsing output: 101/ for test: kms_atomic_transition
results: parsing output: 102/ for test: syncobj_basic
results: parsing output: 103/ for test: kms_flip_scaled_crc
results: parsing output: 104/ for test: dmabuf_sync_file
results: parsing output: 105/ for test: kms_chamelium_frames
results: parsing output: 106/ for test: gem_render_copy_redux
results: parsing output: 107/ for test: gem_exec_schedule
results: parsing output: 108/ for test: kms_flip
results: parsing output: 109/ for test: kms_flip_scaled_crc
results: parsing output: 110/ for test: gem_exec_balancer
results: parsing output: 111/ for test: i915_pm_rps
results: parsing output: 112/ for test: gem_exec_params
results: parsing output: 113/ for test: kms_big_fb
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_psr
results: parsing output: 116/ for test: kms_cursor_crc
results: parsing output: 117/ for test: kms_plane_scaling
results: parsing output: 118/ for test: perf
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: i915_drm_fdinfo
results: parsing output: 122/ for test: gem_exec_await
results: parsing output: 123/ for test: kms_ccs
results: parsing output: 124/ for test: gem_exec_params
results: parsing output: 125/ for test: kms_rotation_crc
results: parsing output: 126/ for test: kms_sequence
results: parsing output: 127/ for test: kms_properties
results: parsing output: 128/ for test: kms_cursor_crc
results: parsing output: 129/ for test: kms_cursor_legacy
Done.