[61.274808] Initializing watchdogs
[61.274876]   /dev/watchdog0
[61.291764] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:9a49 Intel Tigerlake (Gen12) TigerLake-LP GT2 [Iris Xe Graphics]
[61.297581] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.300213] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.302163] [001/156] (960s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[61.400088] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (0.000s)
[61.441570] [002/156] (960s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[61.533483] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[61.534332] Starting dynamic subtest: pipe-A-HDMI-A-1
[62.305670] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.764s)
[62.315345] Starting dynamic subtest: pipe-B-HDMI-A-1
[62.718673] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.408s)
[62.733050] Starting dynamic subtest: pipe-C-HDMI-A-1
[63.114079] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.391s)
[63.127218] Starting dynamic subtest: pipe-D-HDMI-A-1
[63.509740] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.391s)
[63.510869] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SUCCESS (1.962s)
[63.695601] [003/156] (958s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[63.790269] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[63.791634] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[63.860977] [004/156] (958s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[63.949844] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[63.951320] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[64.124285] [005/156] (957s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[64.223363] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[64.224761] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[64.319649] [006/156] (957s left) gem_exec_reloc (basic-cpu-wc-active)
[64.362587] Starting subtest: basic-cpu-wc-active
[64.494670] Subtest basic-cpu-wc-active: SUCCESS (0.123s)
[64.600767] [007/156] (957s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-y)
[64.694160] Starting subtest: fbcpsrhdr-tiling-y
[64.695555] Subtest fbcpsrhdr-tiling-y: SKIP (0.000s)
[64.787696] [008/156] (957s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[64.876746] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[64.878219] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[64.947472] [009/156] (957s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[65.035839] Starting subtest: compare-crc-sanitycheck-nv12
[65.036577] Starting dynamic subtest: pipe-A-HDMI-A-1
[65.387186] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.349s)
[65.393214] Starting dynamic subtest: pipe-B-HDMI-A-1
[65.707303] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.318s)
[65.714312] Starting dynamic subtest: pipe-C-HDMI-A-1
[66.036669] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.326s)
[66.044820] Starting dynamic subtest: pipe-D-HDMI-A-1
[66.352899] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.314s)
[66.353659] Subtest compare-crc-sanitycheck-nv12: SUCCESS (1.312s)
[66.523180] [010/156] (955s left) tools_test (sysfs_l3_parity)
[66.563724] Starting subtest: sysfs_l3_parity
[66.615413] Subtest sysfs_l3_parity: SKIP (0.055s)
[66.684140] [011/156] (955s left) kms_feature_discovery (display-4x)
[66.777262] Starting subtest: display-4x
[66.778800] Subtest display-4x: SKIP (0.000s)
[66.831802] [012/156] (955s left) gem_exec_fence (syncobj-timeline-export)
[67.404090] Starting subtest: syncobj-timeline-export
[67.405058] Subtest syncobj-timeline-export: SUCCESS (0.004s)
[67.616195] [FACT gem_exec_fence (syncobj-timeline-export)] new: kernel.kmod_is_loaded.vgem: true
[67.618752] [013/156] (954s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[67.946821] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[67.948818] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[68.155934] [014/156] (954s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[68.245049] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[68.245774] Starting dynamic subtest: pipe-A
[68.385668] Dynamic subtest pipe-A: SUCCESS (0.140s)
[68.392328] Starting dynamic subtest: pipe-B
[68.521087] Dynamic subtest pipe-B: SUCCESS (0.135s)
[68.527331] Starting dynamic subtest: pipe-C
[68.656034] Dynamic subtest pipe-C: SUCCESS (0.134s)
[68.669548] Starting dynamic subtest: pipe-D
[68.808499] Dynamic subtest pipe-D: SUCCESS (0.152s)
[68.809226] Subtest planes-unity-scaling-downscale-factor-0-5: SUCCESS (0.563s)
[68.988566] [015/156] (953s left) gem_busy (extended)
[69.166304] Starting subtest: extended
[69.167235] Starting dynamic subtest: rcs0
[69.193568] Dynamic subtest rcs0: SUCCESS (0.036s)
[69.211680] Starting dynamic subtest: bcs0
[69.224332] Dynamic subtest bcs0: SUCCESS (0.030s)
[69.236621] Starting dynamic subtest: vcs0
[69.248403] Dynamic subtest vcs0: SUCCESS (0.023s)
[69.260149] Starting dynamic subtest: vcs1
[69.263932] Dynamic subtest vcs1: SUCCESS (0.015s)
[69.271962] Starting dynamic subtest: vecs0
[69.280016] Dynamic subtest vecs0: SUCCESS (0.016s)
[69.280778] Subtest extended: SUCCESS (0.123s)
[69.510499] [016/156] (952s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[69.611813] Starting subtest: y-tiled-64bpp-rotate-0
[71.063913] Subtest y-tiled-64bpp-rotate-0: SUCCESS (1.449s)
[71.175510] [017/156] (951s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[71.265732] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[71.266489] Starting dynamic subtest: pipe-A-HDMI-A-1
[71.273618] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.001s)
[71.282406] Starting dynamic subtest: pipe-B-HDMI-A-1
[71.289554] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.001s)
[71.296019] Starting dynamic subtest: pipe-C-HDMI-A-1
[71.302770] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.001s)
[71.308896] Starting dynamic subtest: pipe-D-HDMI-A-1
[71.315815] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.001s)
[71.317166] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[71.525856] [018/156] (950s left) kms_chamelium_color (ctm-0-50)
[71.618453] Subtest ctm-0-50: SKIP (0.000s)
[71.661874] [019/156] (950s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[71.754493] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[71.755895] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[71.854709] [020/156] (950s left) syncobj_timeline (reset-during-wait-for-submit)
[71.890063] Starting subtest: reset-during-wait-for-submit
[71.992882] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[72.072344] [021/156] (950s left) kms_frontbuffer_tracking (fbchdr-modesetfrombusy)
[72.163657] Starting subtest: fbchdr-modesetfrombusy
[72.165143] Subtest fbchdr-modesetfrombusy: SKIP (0.000s)
[72.250588] [022/156] (950s left) gem_create (create-size-update)
[72.284722] Starting subtest: create-size-update
[72.285451] Subtest create-size-update: SUCCESS (0.000s)
[72.510541] [023/156] (950s left) kms_chamelium_hpd (vga-hpd-fast)
[72.652768] Subtest vga-hpd-fast: SKIP (0.000s)
[72.757482] [024/156] (949s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[72.852213] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[72.853625] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[72.945024] [025/156] (949s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-move)
[73.037341] Starting subtest: psrhdr-1p-primscrn-cur-indfb-move
[73.038798] Subtest psrhdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[73.130762] [026/156] (949s left) gem_pxp (fail-invalid-protected-context)
[73.234073] Starting subtest: fail-invalid-protected-context
[73.235337] Subtest fail-invalid-protected-context: SUCCESS (0.000s)
[73.304542] [027/156] (949s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-pwrite)
[73.401188] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-pwrite
[73.402547] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[73.507756] [028/156] (949s left) kms_psr2_su (page_flip-xrgb8888)
[73.542194] Subtest page_flip-XRGB8888: SKIP (0.000s)
[73.580873] [029/156] (949s left) kms_cursor_legacy (2x-nonblocking-modeset-vs-cursor-atomic)
[73.668889] Starting subtest: 2x-nonblocking-modeset-vs-cursor-atomic
[73.671297] Subtest 2x-nonblocking-modeset-vs-cursor-atomic: SKIP (0.002s)
[73.726991] [030/156] (949s left) kms_psr (fbc-pr-suspend)
[73.825620] Subtest fbc-pr-suspend: SKIP (0.000s)
[73.870697] [031/156] (949s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[73.905890] Starting subtest: etime-single-wait-all-for-submit-submitted
[74.015173] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.108s)
[74.100324] [032/156] (948s left) kms_psr (fbc-psr2-suspend)
[74.189398] Subtest fbc-psr2-suspend: SKIP (0.000s)
[74.232900] [033/156] (948s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs)
[74.273037] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs
[74.273764] Starting dynamic subtest: smem
[74.392167] Dynamic subtest smem: SUCCESS (0.116s)
[74.395416] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SUCCESS (0.116s)
[74.560840] [034/156] (948s left) gem_exec_capture (pi)
[74.599659] Starting subtest: pi
[74.600397] Starting dynamic subtest: rcs0
[156.980181] Dynamic subtest rcs0: SUCCESS (82.361s)
[156.989734] Starting dynamic subtest: bcs0
[167.170579] Dynamic subtest bcs0: SUCCESS (10.190s)
[167.180766] Starting dynamic subtest: vcs0
[177.400619] Dynamic subtest vcs0: SUCCESS (10.229s)
[177.409670] Starting dynamic subtest: vcs1
[187.646362] Dynamic subtest vcs1: SUCCESS (10.245s)
[187.660752] Starting dynamic subtest: vecs0
[270.008649] Dynamic subtest vecs0: SUCCESS (82.357s)
[270.009549] Subtest pi: SUCCESS (195.386s)
[270.088419] [035/156] (753s left) gem_exec_balancer (indices)
[270.173290] Starting subtest: indices
[271.525559] Subtest indices: SUCCESS (1.352s)
[271.720777] [036/156] (751s left) i915_query (query-regions-sanity-check)
[271.754281] Starting subtest: query-regions-sanity-check
[271.764430] Subtest query-regions-sanity-check: SUCCESS (0.010s)
[271.879604] [037/156] (751s left) syncobj_wait (wait-all-delayed-signal)
[271.923648] Starting subtest: wait-all-delayed-signal
[272.026321] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[272.106448] [038/156] (751s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-onoff)
[272.208608] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-onoff
[272.210017] Subtest fbchdr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[272.311614] [039/156] (751s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[272.401648] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[272.402400] Starting dynamic subtest: pipe-A
[273.648500] Dynamic subtest pipe-A: SUCCESS (1.248s)
[273.663416] Starting dynamic subtest: pipe-B
[274.917144] Dynamic subtest pipe-B: SUCCESS (1.268s)
[274.925865] Starting dynamic subtest: pipe-C
[275.125224] Dynamic subtest pipe-C: SUCCESS (0.208s)
[275.132731] Starting dynamic subtest: pipe-D
[275.335856] Dynamic subtest pipe-D: SUCCESS (0.210s)
[275.336572] Subtest plane-downscale-factor-0-5-with-pixel-format: SUCCESS (2.936s)
[275.531622] [040/156] (747s left) gem_render_copy (yf-tiled-ccs-to-linear)
[275.573498] Starting subtest: yf-tiled-ccs-to-linear
[275.574214] Starting dynamic subtest: smem
[275.643355] Dynamic subtest smem: SUCCESS (0.069s)
[275.644120] Subtest yf-tiled-ccs-to-linear: SUCCESS (0.070s)
[275.824890] [041/156] (747s left) gem_exec_schedule (preempt-self)
[275.872322] Starting subtest: preempt-self
[275.873115] Starting dynamic subtest: rcs0
[275.885203] Dynamic subtest rcs0: SUCCESS (0.013s)
[275.893646] Starting dynamic subtest: bcs0
[275.898347] Dynamic subtest bcs0: SUCCESS (0.012s)
[275.906212] Starting dynamic subtest: vcs0
[275.912391] Dynamic subtest vcs0: SUCCESS (0.013s)
[275.921197] Starting dynamic subtest: vcs1
[275.926908] Dynamic subtest vcs1: SUCCESS (0.014s)
[275.935069] Starting dynamic subtest: vecs0
[275.939973] Dynamic subtest vecs0: SUCCESS (0.012s)
[275.940850] Subtest preempt-self: SUCCESS (0.068s)
[276.242934] [042/156] (747s left) core_setmaster (master-drop-set-root)
[276.318858] Starting subtest: master-drop-set-root
[276.328363] Subtest master-drop-set-root: SUCCESS (0.010s)
[276.373472] [043/156] (747s left) kms_big_fb (linear-16bpp-rotate-90)
[276.476527] Starting subtest: linear-16bpp-rotate-90
[276.554456] Subtest linear-16bpp-rotate-90: SKIP (0.076s)
[276.655898] [044/156] (746s left) syncobj_timeline (wait-for-submit-delayed-submit)
[276.691808] Starting subtest: wait-for-submit-delayed-submit
[276.794230] Subtest wait-for-submit-delayed-submit: SUCCESS (0.102s)
[276.879887] [045/156] (746s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[276.973599] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[276.975068] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[277.078539] [046/156] (746s left) kms_cursor_crc (cursor-onscreen-32x10)
[277.171587] Starting subtest: cursor-onscreen-32x10
[277.224995] Subtest cursor-onscreen-32x10: SKIP (0.052s)
[277.336485] [047/156] (746s left) api_intel_bb (misplaced-blitter)
[277.370267] Starting subtest: misplaced-blitter
[277.374924] Subtest misplaced-blitter: SUCCESS (0.005s)
[277.476218] [048/156] (746s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[277.566112] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s)
[277.609475] [049/156] (746s left) kms_flip (2x-flip-vs-panning-vs-hang)
[277.701654] Starting subtest: 2x-flip-vs-panning-vs-hang
[277.703999] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.002s)
[277.810946] [050/156] (745s left) kms_flip (2x-blocking-absolute-wf_vblank)
[277.901591] Starting subtest: 2x-blocking-absolute-wf_vblank
[277.910347] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.009s)
[278.007674] [051/156] (745s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[278.102450] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[278.103842] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[278.199107] [052/156] (745s left) gem_mmap_gtt (cpuset-basic-small-copy)
[278.233658] Starting subtest: cpuset-basic-small-copy
[281.271964] Subtest cpuset-basic-small-copy: SUCCESS (3.039s)
[281.335320] [053/156] (742s left) kms_invalid_mode (clock-too-high)
[281.474379] Starting subtest: clock-too-high
[281.475098] Starting dynamic subtest: pipe-A-HDMI-A-1
[281.538473] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.063s)
[281.550366] Starting dynamic subtest: pipe-B-HDMI-A-1
[281.553448] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.012s)
[281.564081] Starting dynamic subtest: pipe-C-HDMI-A-1
[281.566923] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.012s)
[281.577059] Starting dynamic subtest: pipe-D-HDMI-A-1
[281.577884] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.009s)
[281.578650] Subtest clock-too-high: SUCCESS (0.102s)
[281.712875] [054/156] (742s left) gem_exec_reloc (basic-cpu-read-noreloc)
[281.754838] Starting subtest: basic-cpu-read-noreloc
[281.757116] Subtest basic-cpu-read-noreloc: SUCCESS (0.002s)
[281.879486] [055/156] (742s left) gen9_exec_parse (allowed-all)
[281.917457] Subtest allowed-all: SKIP (0.000s)
[281.956176] [056/156] (742s left) gem_exec_params (invalid-bsd-ring)
[281.992959] Starting subtest: invalid-bsd-ring
[281.993755] Subtest invalid-bsd-ring: SUCCESS (0.000s)
[282.093812] [057/156] (741s left) gem_vm_create (create-ext)
[282.131964] Starting subtest: create-ext
[282.133845] Subtest create-ext: SUCCESS (0.002s)
[282.258932] [058/156] (741s left) perf (polling-small-buf)
[282.365235] Starting subtest: polling-small-buf
[282.454218] Subtest polling-small-buf: SUCCESS (0.090s)
[282.492284] [059/156] (741s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[282.587354] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[282.588724] Subtest psr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[282.687750] [060/156] (741s left) api_intel_allocator (alloc-simple)
[282.721654] Starting subtest: alloc-simple
[282.722380] Subtest alloc-simple: SUCCESS (0.001s)
[282.830812] [061/156] (741s left) kms_flip (plain-flip-ts-check)
[282.922737] Starting subtest: plain-flip-ts-check
[282.925221] Starting dynamic subtest: A-HDMI-A1
[290.791867] Dynamic subtest A-HDMI-A1: SUCCESS (7.849s)
[290.799264] Starting dynamic subtest: B-HDMI-A1
[298.603063] Dynamic subtest B-HDMI-A1: SUCCESS (7.801s)
[298.610375] Starting dynamic subtest: C-HDMI-A1
[306.389178] Dynamic subtest C-HDMI-A1: SUCCESS (7.807s)
[306.397736] Starting dynamic subtest: D-HDMI-A1
[314.195394] Dynamic subtest D-HDMI-A1: SUCCESS (7.804s)
[314.196174] Subtest plain-flip-ts-check: SUCCESS (31.274s)
[314.351519] [062/156] (709s left) syncobj_basic (bad-handle-to-fd)
[314.386606] Starting subtest: bad-handle-to-fd
[314.387381] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[314.447020] [063/156] (709s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[314.484621] Starting subtest: basic-wc-gtt-noreloc
[314.487707] Subtest basic-wc-gtt-noreloc: SUCCESS (0.003s)
[314.603110] [064/156] (709s left) gem_mmap_wc (write-read)
[314.636842] Starting subtest: write-read
[314.638698] Subtest write-read: SUCCESS (0.002s)
[314.729737] [065/156] (709s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[314.830249] Starting subtest: 4-tiled-16bpp-rotate-180
[314.831749] Subtest 4-tiled-16bpp-rotate-180: SKIP (0.000s)
[314.921637] [066/156] (709s left) gem_softpin (invalid)
[314.958625] Starting subtest: invalid
[314.962935] Subtest invalid: SUCCESS (0.004s)
[315.065791] [067/156] (709s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[315.105901] Starting subtest: y-tiled-ccs-to-x-tiled
[315.106599] Starting dynamic subtest: smem
[315.210948] Dynamic subtest smem: SUCCESS (0.105s)
[315.211781] Subtest y-tiled-ccs-to-x-tiled: SUCCESS (0.105s)
[315.403848] [068/156] (709s left) gem_ctx_persistence (engines-queued)
[315.445763] Starting subtest: engines-queued
[315.446462] Starting dynamic subtest: rcs0
[315.491153] Dynamic subtest rcs0: SUCCESS (0.045s)
[315.498869] Starting dynamic subtest: bcs0
[315.536237] Dynamic subtest bcs0: SUCCESS (0.044s)
[315.544099] Starting dynamic subtest: vcs0
[315.580945] Dynamic subtest vcs0: SUCCESS (0.043s)
[315.596851] Starting dynamic subtest: vcs1
[315.632700] Dynamic subtest vcs1: SUCCESS (0.050s)
[315.650165] Starting dynamic subtest: vecs0
[315.690645] Dynamic subtest vecs0: SUCCESS (0.056s)
[315.691835] Subtest engines-queued: SUCCESS (0.245s)
[315.776043] [069/156] (708s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-msflip-blt)
[315.869374] Starting subtest: hdr-1p-primscrn-shrfb-msflip-blt
[315.870847] Subtest hdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[315.983974] [070/156] (708s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-downscaling)
[316.076758] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-downscaling
[316.077530] Starting dynamic subtest: pipe-A-valid-mode
[318.027151] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.950s)
[318.027935] Subtest flip-32bpp-xtile-to-64bpp-xtile-downscaling: SUCCESS (1.952s)
[318.198671] [071/156] (706s left) kms_color (ctm-signed)
[318.291808] Starting subtest: ctm-signed
[318.292493] Starting dynamic subtest: pipe-A-HDMI-A-1
[318.847034] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.555s)
[318.854588] Starting dynamic subtest: pipe-B-HDMI-A-1
[319.392363] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.545s)
[319.400348] Starting dynamic subtest: pipe-C-HDMI-A-1
[319.938288] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.545s)
[319.946207] Starting dynamic subtest: pipe-D-HDMI-A-1
[320.482324] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.542s)
[320.483098] Subtest ctm-signed: SUCCESS (2.191s)
[320.651147] [072/156] (704s left) kms_cursor_crc (cursor-sliding-128x128)
[320.753891] Starting subtest: cursor-sliding-128x128
[320.758999] Starting dynamic subtest: pipe-A-HDMI-A-1
[322.589163] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.829s)
[322.621501] Starting dynamic subtest: pipe-D-HDMI-A-1
[324.436559] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (1.822s)
[324.437290] Subtest cursor-sliding-128x128: SUCCESS (3.683s)
[324.604447] [073/156] (700s left) kms_getfb (getfb-handle-protection)
[324.699040] Starting subtest: getfb-handle-protection
[324.718126] Subtest getfb-handle-protection: SUCCESS (0.019s)
[324.832066] [074/156] (699s left) kms_flip (bo-too-big)
[324.920583] Starting subtest: bo-too-big
[324.923241] Starting dynamic subtest: A-HDMI-A1
[325.281408] Dynamic subtest A-HDMI-A1: SUCCESS (0.354s)
[325.295628] Starting dynamic subtest: B-HDMI-A1
[325.621312] Dynamic subtest B-HDMI-A1: SUCCESS (0.326s)
[325.638542] Starting dynamic subtest: C-HDMI-A1
[325.934116] Dynamic subtest C-HDMI-A1: SUCCESS (0.324s)
[325.944989] Starting dynamic subtest: D-HDMI-A1
[326.261210] Dynamic subtest D-HDMI-A1: SUCCESS (0.320s)
[326.262047] Subtest bo-too-big: SUCCESS (1.339s)
[326.461792] [075/156] (698s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[326.557847] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[326.559313] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[326.643243] [076/156] (698s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[326.735547] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[326.737078] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[326.821323] [077/156] (698s left) syncobj_eventfd (timeline-wait-before-signal)
[326.858807] Starting subtest: timeline-wait-before-signal
[326.889885] Subtest timeline-wait-before-signal: SUCCESS (0.031s)
[326.966435] [078/156] (697s left) syncobj_wait (invalid-wait-bad-flags)
[327.001527] Starting subtest: invalid-wait-bad-flags
[327.002245] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[327.080870] [079/156] (697s left) kms_content_protection (lic-type-1)
[327.175262] Starting subtest: lic-type-1
[327.181572] Subtest lic-type-1: SKIP (0.004s)
[327.271180] [080/156] (697s left) kms_pm_rpm (fences)
[328.679775] Starting subtest: fences
[333.436814] Subtest fences: SUCCESS (4.749s)
[333.524747] [081/156] (691s left) kms_plane_alpha_blend (alpha-7efc)
[333.615157] Starting subtest: alpha-7efc
[333.682446] Starting dynamic subtest: pipe-A-HDMI-A-1
[333.998068] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.315s)
[334.032643] Starting dynamic subtest: pipe-D-HDMI-A-1
[334.509067] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (0.484s)
[334.509862] Subtest alpha-7efc: SUCCESS (0.895s)
[334.661085] [082/156] (690s left) gem_spin_batch (legacy-resubmit-new)
[334.706211] Starting subtest: legacy-resubmit-new
[334.706954] Starting dynamic subtest: default
[334.710108] Dynamic subtest default: SUCCESS (0.003s)
[334.722901] Starting dynamic subtest: render
[334.723743] Dynamic subtest render: SUCCESS (0.004s)
[334.731754] Starting dynamic subtest: bsd
[334.732494] Dynamic subtest bsd: SUCCESS (0.004s)
[334.740489] Starting dynamic subtest: bsd1
[334.741245] Dynamic subtest bsd1: SUCCESS (0.003s)
[334.749155] Starting dynamic subtest: bsd2
[334.749873] Dynamic subtest bsd2: SUCCESS (0.003s)
[334.757077] Starting dynamic subtest: blt
[334.757787] Dynamic subtest blt: SUCCESS (0.003s)
[334.764900] Starting dynamic subtest: vebox
[334.765617] Dynamic subtest vebox: SUCCESS (0.003s)
[334.766315] Subtest legacy-resubmit-new: SUCCESS (0.034s)
[334.926790] [083/156] (690s left) i915_hangman (hangcheck-unterminated)
[334.971681] Starting subtest: hangcheck-unterminated
[344.933623] Subtest hangcheck-unterminated: SUCCESS (9.962s)
[345.059494] [084/156] (680s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[345.160116] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[345.161557] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[345.227806] [085/156] (679s left) gem_exec_schedule (pi-distinct-iova)
[345.396480] Starting subtest: pi-distinct-iova
[345.397427] Starting dynamic subtest: rcs0
[345.427206] Dynamic subtest rcs0: SUCCESS (0.030s)
[345.439538] Starting dynamic subtest: bcs0
[345.453365] Dynamic subtest bcs0: SUCCESS (0.025s)
[345.465375] Starting dynamic subtest: vcs0
[345.479538] Dynamic subtest vcs0: SUCCESS (0.025s)
[345.491816] Starting dynamic subtest: vcs1
[345.505970] Dynamic subtest vcs1: SUCCESS (0.025s)
[345.518037] Starting dynamic subtest: vecs0
[345.533545] Dynamic subtest vecs0: SUCCESS (0.025s)
[345.534304] Subtest pi-distinct-iova: SUCCESS (0.137s)
[345.716376] [086/156] (679s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[345.815993] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip
[346.500371] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.684s)
[346.619317] [087/156] (678s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-shrfb-fliptrack-mmap-gtt)
[346.712085] Starting subtest: fbcpsrhdr-1p-shrfb-fliptrack-mmap-gtt
[346.713490] Subtest fbcpsrhdr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[346.812662] [088/156] (678s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[346.904173] Starting subtest: cursorA-vs-flipB-legacy
[346.905548] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[346.985930] [089/156] (678s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[347.084700] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[347.675687] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (0.590s)
[347.817666] [090/156] (677s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[347.913802] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[347.915270] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[348.007270] [091/156] (677s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt)
[348.102230] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt
[348.103732] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[348.208517] [092/156] (677s left) kms_getfb (getfb-handle-not-fb)
[348.299425] Starting subtest: getfb-handle-not-fb
[348.300207] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[348.408154] [093/156] (677s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[348.501837] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[348.503375] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[348.597804] [094/156] (676s left) kms_psr (psr2-cursor-mmap-cpu)
[348.688978] Subtest psr2-cursor-mmap-cpu: SKIP (0.000s)
[348.731200] [095/156] (676s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[348.822527] Starting subtest: addfb25-yf-tiled-legacy
[348.823348] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[348.933826] [096/156] (676s left) kms_cursor_legacy (short-flip-after-cursor-toggle)
[349.023048] Starting subtest: short-flip-after-cursor-toggle
[349.549502] Subtest short-flip-after-cursor-toggle: SUCCESS (0.525s)
[349.690914] [097/156] (676s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[349.728054] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[349.773455] [098/156] (675s left) prime_self_import (reimport-vs-gem_close-race)
[349.807436] Starting subtest: reimport-vs-gem_close-race
[354.841131] Subtest reimport-vs-gem_close-race: SUCCESS (5.033s)
[354.942951] [099/156] (670s left) kms_chamelium_frames (hdmi-crc-multiple)
[355.087219] Subtest hdmi-crc-multiple: SKIP (0.000s)
[355.189936] [100/156] (670s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-wc)
[355.286721] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-wc
[355.288148] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[355.381022] [101/156] (670s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[355.481005] Starting subtest: x-tiled-64bpp-rotate-0
[356.909453] Subtest x-tiled-64bpp-rotate-0: SUCCESS (1.428s)
[357.022485] [102/156] (668s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-wc)
[357.119176] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-wc
[357.120666] Subtest fbcpsrhdr-rgb101010-draw-mmap-wc: SKIP (0.000s)
[357.213923] [103/156] (668s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-msflip-blt)
[357.307417] Starting subtest: fbcpsr-1p-primscrn-indfb-msflip-blt
[357.308815] Subtest fbcpsr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[357.393972] [104/156] (668s left) kms_frontbuffer_tracking (basic)
[357.488056] Starting subtest: basic
[359.101160] Subtest basic: SUCCESS (1.613s)
[359.214808] [105/156] (666s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-pwrite)
[359.315083] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-pwrite
[359.316531] Subtest hdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[359.413166] [106/156] (666s left) kms_prime (basic-crc-hybrid)
[359.519372] Subtest basic-crc-hybrid: SKIP (0.000s)
[359.615045] [107/156] (666s left) kms_vblank (query-forked-hang)
[359.708422] Starting subtest: query-forked-hang
[359.709177] Starting dynamic subtest: pipe-A-HDMI-A-1
[373.903104] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (14.194s)
[373.914901] Starting dynamic subtest: pipe-D-HDMI-A-1
[387.715118] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (13.808s)
[387.715839] Subtest query-forked-hang: SUCCESS (28.008s)
[387.899581] [108/156] (638s left) gem_gpgpu_fill (offset-16x16)
[387.937243] Starting subtest: offset-16x16
[387.941940] Subtest offset-16x16: SUCCESS (0.005s)
[388.076768] [109/156] (638s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[388.175112] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[388.176551] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[388.256319] [110/156] (638s left) sysfs_preempt_timeout (invalid)
[388.294840] Starting subtest: invalid
[388.295568] Starting dynamic subtest: vecs0
[388.296325] Dynamic subtest vecs0: SUCCESS (0.000s)
[388.304639] Starting dynamic subtest: vcs0
[388.305345] Dynamic subtest vcs0: SUCCESS (0.000s)
[388.313039] Starting dynamic subtest: bcs0
[388.313741] Dynamic subtest bcs0: SUCCESS (0.000s)
[388.321459] Starting dynamic subtest: rcs0
[388.322152] Dynamic subtest rcs0: SUCCESS (0.000s)
[388.330067] Starting dynamic subtest: vcs1
[388.330779] Dynamic subtest vcs1: SUCCESS (0.000s)
[388.331470] Subtest invalid: SUCCESS (0.007s)
[388.364396] [111/156] (637s left) gem_partial_pwrite_pread (reads-snoop)
[388.404900] Starting subtest: reads-snoop
[390.191534] Subtest reads-snoop: SUCCESS (1.788s)
[390.300948] [112/156] (636s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-mmap-wc)
[390.397529] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-mmap-wc
[390.398943] Subtest fbchdr-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[390.492121] [113/156] (635s left) syncobj_wait (wait-for-submit-complex)
[390.527115] Starting subtest: wait-for-submit-complex
[390.589316] Subtest wait-for-submit-complex: SUCCESS (0.062s)
[390.665064] [114/156] (635s left) kms_vblank (wait-busy-hang)
[390.753107] Starting subtest: wait-busy-hang
[390.754923] Starting dynamic subtest: pipe-A-HDMI-A-1
[404.629011] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (13.873s)
[404.640336] Starting dynamic subtest: pipe-D-HDMI-A-1
[418.438136] Dynamic subtest pipe-D-HDMI-A-1: SUCCESS (13.805s)
[418.439989] Subtest wait-busy-hang: SUCCESS (27.685s)
[418.604363] [115/156] (607s left) kms_cursor_legacy (single-move)
[418.698135] Starting subtest: single-move
[418.698887] Starting dynamic subtest: pipe-A
[424.111880] Dynamic subtest pipe-A: SUCCESS (5.379s)
[424.119256] Starting dynamic subtest: pipe-B
[429.491421] Dynamic subtest pipe-B: SUCCESS (5.378s)
[429.498827] Starting dynamic subtest: pipe-C
[434.871154] Dynamic subtest pipe-C: SUCCESS (5.378s)
[434.878583] Starting dynamic subtest: pipe-D
[440.250708] Dynamic subtest pipe-D: SUCCESS (5.378s)
[440.258400] Starting dynamic subtest: all-pipes
[445.631148] Dynamic subtest all-pipes: SUCCESS (5.379s)
[445.631879] Subtest single-move: SUCCESS (26.899s)
[445.668840] [116/156] (580s left) gem_exec_whisper (basic-queues)
[445.712414] Starting subtest: basic-queues
[447.466863] Subtest basic-queues: SUCCESS (1.753s)
[447.649977] [117/156] (578s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[447.748602] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[449.421929] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SUCCESS (1.674s)
[449.563891] [118/156] (577s left) kms_addfb_basic (size-max)
[449.657381] Starting subtest: size-max
[449.658153] Subtest size-max: SUCCESS (0.000s)
[449.753693] [119/156] (576s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[449.848548] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[449.849990] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[449.946746] [120/156] (576s left) kms_big_fb (4-tiled-addfb)
[450.045468] Starting subtest: 4-tiled-addfb
[450.046968] Subtest 4-tiled-addfb: SKIP (0.000s)
[450.142880] [121/156] (576s left) kms_plane_multiple (tiling-4)
[450.236314] Starting subtest: tiling-4
[450.237809] Subtest tiling-4: SKIP (0.000s)
[450.299272] [122/156] (576s left) gem_exec_gttfill (multigpu-basic)
[450.460707] Starting subtest: multigpu-basic
[462.690619] Subtest multigpu-basic: SUCCESS (12.230s)
[462.764921] [123/156] (564s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-shrfb-msflip-blt)
[462.923534] Starting subtest: fbchdr-2p-primscrn-shrfb-msflip-blt
[462.924936] Subtest fbchdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[463.020338] [124/156] (563s left) gem_ctx_persistence (legacy-engines-mixed)
[463.061603] Starting subtest: legacy-engines-mixed
[463.062348] Starting dynamic subtest: render
[463.103701] Dynamic subtest render: SUCCESS (0.041s)
[463.123682] Starting dynamic subtest: bsd1
[463.161703] Dynamic subtest bsd1: SUCCESS (0.055s)
[463.181195] Starting dynamic subtest: bsd2
[463.217609] Dynamic subtest bsd2: SUCCESS (0.053s)
[463.236905] Starting dynamic subtest: blt
[463.278619] Dynamic subtest blt: SUCCESS (0.058s)
[463.299890] Starting dynamic subtest: vebox
[463.330606] Dynamic subtest vebox: SUCCESS (0.049s)
[463.331762] Subtest legacy-engines-mixed: SUCCESS (0.270s)
[463.423170] [125/156] (563s left) perf (gen8-unprivileged-single-ctx-counters)
[463.517964] Starting subtest: gen8-unprivileged-single-ctx-counters
[463.519313] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[463.560369] [126/156] (563s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-pwrite)
[463.654150] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-pwrite
[463.655570] Subtest hdr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[463.777099] [127/156] (563s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite)
[463.871588] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite
[463.873041] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[463.963463] [128/156] (563s left) gem_mmap_gtt (basic-read-write-distinct)
[463.999945] Starting subtest: basic-read-write-distinct
[464.002326] Subtest basic-read-write-distinct: SUCCESS (0.003s)
[464.096361] [129/156] (562s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[464.191492] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite
[464.192972] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[464.271973] [130/156] (562s left) gem_mmap_offset (mmap-unmap)
[464.307658] Starting subtest: mmap-unmap
[464.308376] Starting dynamic subtest: smem0
[497.945283] Dynamic subtest smem0: SUCCESS (33.601s)
[497.946310] Subtest mmap-unmap: SUCCESS (33.602s)
[498.019817] [131/156] (529s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[498.114111] Starting subtest: basic-busy-flip-before-cursor-varying-size
[498.115530] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[498.186708] [132/156] (529s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[498.277338] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[498.278054] Starting dynamic subtest: pipe-A-valid-mode
[499.136170] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.858s)
[499.136940] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (0.859s)
[499.275670] [133/156] (527s left) i915_drm_fdinfo (all-busy-check-all)
[499.319037] Starting subtest: all-busy-check-all
[499.841283] Subtest all-busy-check-all: SUCCESS (0.522s)
[499.926093] [134/156] (527s left) syncobj_timeline (multi-wait-available-signaled)
[499.961701] Starting subtest: multi-wait-available-signaled
[499.962465] Subtest multi-wait-available-signaled: SUCCESS (0.001s)
[500.024973] [135/156] (527s left) gem_mmap_gtt (basic-small-bo-tiledx)
[500.059211] Starting subtest: basic-small-bo-tiledX
[500.145609] Subtest basic-small-bo-tiledX: SUCCESS (0.087s)
[500.251605] [136/156] (527s left) kms_psr (psr2-no-drrs)
[500.393742] Subtest psr2-no-drrs: SKIP (0.000s)
[500.436750] [137/156] (526s left) gem_userptr_blits (create-destroy-unsync)
[500.478144] Subtest create-destroy-unsync: SKIP (0.000s)
[500.595549] [138/156] (526s left) gem_exec_parallel (fds)
[500.638565] Starting subtest: fds
[500.639318] Starting dynamic subtest: rcs0
[503.451236] Dynamic subtest rcs0: SUCCESS (2.811s)
[503.477233] Starting dynamic subtest: bcs0
[505.374737] Dynamic subtest bcs0: SUCCESS (1.921s)
[505.388913] Starting dynamic subtest: vcs0
[507.295635] Dynamic subtest vcs0: SUCCESS (1.920s)
[507.305837] Starting dynamic subtest: vcs1
[509.294158] Dynamic subtest vcs1: SUCCESS (1.997s)
[509.316271] Starting dynamic subtest: vecs0
[514.140833] Dynamic subtest vecs0: SUCCESS (4.844s)
[514.141951] Subtest fds: SUCCESS (13.503s)
[514.355698] [139/156] (513s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-indfb-msflip-blt)
[514.457584] Starting subtest: fbcpsrhdr-2p-scndscrn-indfb-msflip-blt
[514.458984] Subtest fbcpsrhdr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[514.555007] [140/156] (513s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[514.644058] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[514.644779] Starting dynamic subtest: pipe-A-HDMI-A-1
[514.662328] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.002s)
[514.671346] Starting dynamic subtest: pipe-B-HDMI-A-1
[514.689224] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.002s)
[514.698286] Starting dynamic subtest: pipe-C-HDMI-A-1
[514.715657] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[514.727128] Starting dynamic subtest: pipe-D-HDMI-A-1
[514.746622] Dynamic subtest pipe-D-HDMI-A-1: SKIP (0.002s)
[514.748011] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.013s)
[514.917530] [141/156] (512s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[515.014417] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[515.015797] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[515.118154] [142/156] (512s left) perf_pmu (semaphore-busy)
[515.156678] Starting subtest: semaphore-busy
[515.157413] Starting dynamic subtest: rcs0
[516.740099] Dynamic subtest rcs0: SUCCESS (1.573s)
[516.752086] Starting dynamic subtest: bcs0
[518.286370] Dynamic subtest bcs0: SUCCESS (1.543s)
[518.298914] Starting dynamic subtest: vcs0
[519.836304] Dynamic subtest vcs0: SUCCESS (1.547s)
[519.848334] Starting dynamic subtest: vcs1
[521.387549] Dynamic subtest vcs1: SUCCESS (1.549s)
[521.399791] Starting dynamic subtest: vecs0
[522.938918] Dynamic subtest vecs0: SUCCESS (1.549s)
[522.939894] Subtest semaphore-busy: SUCCESS (7.773s)
[523.032489] [143/156] (504s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[523.127933] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[523.129411] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[523.227729] [144/156] (504s left) gem_ctx_shared (q-promotion)
[523.270561] Starting subtest: Q-promotion
[523.271357] Starting dynamic subtest: rcs0
[523.332070] Dynamic subtest rcs0: SUCCESS (0.061s)
[523.347484] Starting dynamic subtest: bcs0
[523.395763] Dynamic subtest bcs0: SUCCESS (0.061s)
[523.409622] Starting dynamic subtest: vcs0
[523.452096] Dynamic subtest vcs0: SUCCESS (0.054s)
[523.466160] Starting dynamic subtest: vcs1
[523.506383] Dynamic subtest vcs1: SUCCESS (0.052s)
[523.520566] Starting dynamic subtest: vecs0
[523.560236] Dynamic subtest vecs0: SUCCESS (0.052s)
[523.561591] Subtest Q-promotion: SUCCESS (0.290s)
[523.755989] [145/156] (504s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[523.858318] Starting subtest: yf-tiled-addfb-size-offset-overflow
[523.859826] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[523.949207] [146/156] (503s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[524.041313] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[524.042765] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[524.144782] [147/156] (503s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[524.238609] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[524.240028] Subtest hdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[524.321810] [148/156] (503s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-move)
[524.414916] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-move
[524.416314] Subtest psrhdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[524.542269] [149/156] (503s left) perf_pmu (busy-check-all)
[524.578352] Starting subtest: busy-check-all
[524.579087] Starting dynamic subtest: rcs0
[525.102197] Dynamic subtest rcs0: SUCCESS (0.523s)
[525.115207] Starting dynamic subtest: bcs0
[525.626148] Dynamic subtest bcs0: SUCCESS (0.522s)
[525.638756] Starting dynamic subtest: vcs0
[526.155266] Dynamic subtest vcs0: SUCCESS (0.527s)
[526.168113] Starting dynamic subtest: vcs1
[526.678412] Dynamic subtest vcs1: SUCCESS (0.520s)
[526.689834] Starting dynamic subtest: vecs0
[527.201264] Dynamic subtest vecs0: SUCCESS (0.522s)
[527.205177] Subtest busy-check-all: SUCCESS (2.624s)
[527.275666] [150/156] (500s left) i915_pm_rc6_residency (rc6-fence)
[527.311143] Starting subtest: rc6-fence
[527.314635] Starting dynamic subtest: gt0
[545.611367] Dynamic subtest gt0: SUCCESS (18.279s)
[545.612343] Subtest rc6-fence: SUCCESS (18.285s)
[545.665901] [151/156] (482s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[545.761165] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-wc
[545.762566] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[545.853768] [152/156] (482s left) gem_mmap_wc (write-gtt-read-wc)
[545.888190] Starting subtest: write-gtt-read-wc
[546.206200] Subtest write-gtt-read-wc: SUCCESS (0.309s)
[546.291639] [153/156] (481s left) kms_frontbuffer_tracking (fbchdr-1p-rte)
[546.386754] Starting subtest: fbchdr-1p-rte
[546.388149] Subtest fbchdr-1p-rte: SKIP (0.000s)
[546.488363] [154/156] (481s left) kms_psr_stress_test (invalidate-primary-flip-overlay)
[546.577910] Subtest invalidate-primary-flip-overlay: SKIP (0.000s)
[546.622110] [155/156] (481s left) sw_sync (sync_busy_fork_unixsocket)
[546.656052] Starting subtest: sync_busy_fork_unixsocket
[546.665238] Subtest sync_busy_fork_unixsocket: SUCCESS (0.009s)
[546.721736] [156/156] (481s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-plflip-blt)
[546.816133] Starting subtest: hdr-1p-primscrn-indfb-plflip-blt
[546.817552] Subtest hdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[546.908445] Closing watchdogs
results: parsing output: 0/ for test: kms_psr2_sf
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: gem_exec_reloc
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_plane
results: parsing output: 8/ for test: kms_pipe_crc_basic
results: parsing output: 9/ for test: tools_test
results: parsing output: 10/ for test: kms_feature_discovery
results: parsing output: 11/ for test: gem_exec_fence
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_plane_scaling
results: parsing output: 14/ for test: gem_busy
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_chamelium_color
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: syncobj_timeline
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: gem_create
results: parsing output: 22/ for test: kms_chamelium_hpd
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: gem_pxp
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_psr2_su
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: gem_render_copy
results: parsing output: 33/ for test: gem_exec_capture
results: parsing output: 34/ for test: gem_exec_balancer
results: parsing output: 35/ for test: i915_query
results: parsing output: 36/ for test: syncobj_wait
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_plane_scaling
results: parsing output: 39/ for test: gem_render_copy
results: parsing output: 40/ for test: gem_exec_schedule
results: parsing output: 41/ for test: core_setmaster
results: parsing output: 42/ for test: kms_big_fb
results: parsing output: 43/ for test: syncobj_timeline
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_cursor_crc
results: parsing output: 46/ for test: api_intel_bb
results: parsing output: 47/ for test: kms_psr2_sf
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: kms_flip
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_mmap_gtt
results: parsing output: 52/ for test: kms_invalid_mode
results: parsing output: 53/ for test: gem_exec_reloc
results: parsing output: 54/ for test: gen9_exec_parse
results: parsing output: 55/ for test: gem_exec_params
results: parsing output: 56/ for test: gem_vm_create
results: parsing output: 57/ for test: perf
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: api_intel_allocator
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: syncobj_basic
results: parsing output: 62/ for test: gem_exec_reloc
results: parsing output: 63/ for test: gem_mmap_wc
results: parsing output: 64/ for test: kms_big_fb
results: parsing output: 65/ for test: gem_softpin
results: parsing output: 66/ for test: gem_render_copy
results: parsing output: 67/ for test: gem_ctx_persistence
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_flip_scaled_crc
results: parsing output: 70/ for test: kms_color
results: parsing output: 71/ for test: kms_cursor_crc
results: parsing output: 72/ for test: kms_getfb
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: syncobj_eventfd
results: parsing output: 77/ for test: syncobj_wait
results: parsing output: 78/ for test: kms_content_protection
results: parsing output: 79/ for test: kms_pm_rpm
results: parsing output: 80/ for test: kms_plane_alpha_blend
results: parsing output: 81/ for test: gem_spin_batch
results: parsing output: 82/ for test: i915_hangman
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_exec_schedule
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_cursor_legacy
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_getfb
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_psr
results: parsing output: 94/ for test: kms_addfb_basic
results: parsing output: 95/ for test: kms_cursor_legacy
results: parsing output: 96/ for test: kms_pipe_b_c_ivb
results: parsing output: 97/ for test: prime_self_import
results: parsing output: 98/ for test: kms_chamelium_frames
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_big_fb
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_prime
results: parsing output: 106/ for test: kms_vblank
results: parsing output: 107/ for test: gem_gpgpu_fill
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: sysfs_preempt_timeout
results: parsing output: 110/ for test: gem_partial_pwrite_pread
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: syncobj_wait
results: parsing output: 113/ for test: kms_vblank
results: parsing output: 114/ for test: kms_cursor_legacy
results: parsing output: 115/ for test: gem_exec_whisper
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_addfb_basic
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: kms_plane_multiple
results: parsing output: 121/ for test: gem_exec_gttfill
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: gem_ctx_persistence
results: parsing output: 124/ for test: perf
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: gem_mmap_gtt
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gem_mmap_offset
results: parsing output: 130/ for test: kms_cursor_legacy
results: parsing output: 131/ for test: kms_flip_scaled_crc
results: parsing output: 132/ for test: i915_drm_fdinfo
results: parsing output: 133/ for test: syncobj_timeline
results: parsing output: 134/ for test: gem_mmap_gtt
results: parsing output: 135/ for test: kms_psr
results: parsing output: 136/ for test: gem_userptr_blits
results: parsing output: 137/ for test: gem_exec_parallel
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_ccs
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: perf_pmu
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: gem_ctx_shared
results: parsing output: 144/ for test: kms_big_fb
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: perf_pmu
results: parsing output: 149/ for test: i915_pm_rc6_residency
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: gem_mmap_wc
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: kms_psr_stress_test
results: parsing output: 154/ for test: sw_sync
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.