[57.307753] Initializing watchdogs
[57.307805]   /dev/watchdog0
[57.321686] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.326572] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.328950] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.332294] [001/133] (960s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[57.460932] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[57.498504] Starting dynamic subtest: pipe-A-plane-0
[57.930378] Dynamic subtest pipe-A-plane-0: SUCCESS (0.434s)
[57.938272] Starting dynamic subtest: pipe-A-plane-3
[58.183354] Dynamic subtest pipe-A-plane-3: SUCCESS (0.255s)
[59.251041] Starting dynamic subtest: pipe-B-plane-0
[59.676327] Dynamic subtest pipe-B-plane-0: SUCCESS (0.433s)
[59.684286] Starting dynamic subtest: pipe-B-plane-3
[59.930992] Dynamic subtest pipe-B-plane-3: SUCCESS (0.255s)
[60.262767] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.815s)
[61.133746] [002/133] (956s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[61.242888] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[61.247396] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[61.364081] [003/133] (956s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[61.468082] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[61.472063] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[61.601934] [004/133] (956s left) prime_mmap_coherency (ioctl-errors)
[61.658530] Starting subtest: ioctl-errors
[77.089932] Subtest ioctl-errors: SUCCESS (15.451s)
[77.267856] [005/133] (940s left) perf (non-sampling-read-error)
[77.453643] Starting subtest: non-sampling-read-error
[77.465770] Subtest non-sampling-read-error: SUCCESS (0.010s)
[77.506190] [006/133] (940s left) core_getversion (basic)
[77.552036] Starting subtest: basic
[77.556464] Subtest basic: SUCCESS (0.000s)
[77.618881] [007/133] (940s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[77.727913] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[77.736744] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[77.824192] [008/133] (940s left) api_intel_allocator (execbuf-with-allocator)
[77.875717] Starting subtest: execbuf-with-allocator
[77.880160] Subtest execbuf-with-allocator: SUCCESS (0.005s)
[77.980292] [009/133] (940s left) tools_test (sysfs_l3_parity)
[78.028621] Starting subtest: sysfs_l3_parity
[78.108688] Subtest sysfs_l3_parity: SKIP (0.091s)
[78.174570] [010/133] (939s left) kms_invalid_mode (bad-htotal)
[78.274792] Starting subtest: bad-htotal
[78.277073] Starting dynamic subtest: pipe-A-eDP-1
[78.560880] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[78.569028] Starting dynamic subtest: pipe-B-eDP-1
[78.570966] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[78.578154] Starting dynamic subtest: pipe-C-eDP-1
[78.580090] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[78.587592] Starting dynamic subtest: pipe-D-eDP-1
[78.589859] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[78.592121] Subtest bad-htotal: SUCCESS (0.289s)
[79.453517] [011/133] (938s left) kms_getfb (getfb2-handle-closed)
[79.555803] Starting subtest: getfb2-handle-closed
[79.558015] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[79.690612] [012/133] (938s left) gen3_mixed_blits
[79.824156] [013/133] (938s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[79.928749] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[87.290359] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.363s)
[87.474143] [014/133] (930s left) kms_cursor_crc (cursor-tearing-position-change)
[87.612097] Starting subtest: cursor-tearing-position-change
[87.614442] Starting dynamic subtest: pipe-A-eDP-1
[88.206730] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.592s)
[88.216095] Starting dynamic subtest: pipe-D-eDP-1
[89.411249] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.203s)
[89.413424] Subtest cursor-tearing-position-change: SUCCESS (1.797s)
[90.258421] [015/133] (928s left) kms_flip (absolute-wf_vblank)
[90.358782] Starting subtest: absolute-wf_vblank
[90.361158] Starting dynamic subtest: A-eDP1
[99.097915] Dynamic subtest A-eDP1: SUCCESS (8.738s)
[99.110187] Starting dynamic subtest: B-eDP1
[107.820132] Dynamic subtest B-eDP1: SUCCESS (8.720s)
[107.830149] Starting dynamic subtest: C-eDP1
[116.546463] Dynamic subtest C-eDP1: SUCCESS (8.725s)
[116.556418] Starting dynamic subtest: D-eDP1
[125.269938] Dynamic subtest D-eDP1: SUCCESS (8.721s)
[125.272407] Subtest absolute-wf_vblank: SUCCESS (34.913s)
[126.485263] [016/133] (891s left) kms_pm_backlight (basic-brightness)
[126.587261] Starting subtest: basic-brightness
[126.639129] Starting dynamic subtest: eDP-1
[127.135244] Dynamic subtest eDP-1: SUCCESS (0.497s)
[127.137621] Subtest basic-brightness: SUCCESS (0.549s)
[128.016043] [017/133] (890s left) kms_color (ctm-max)
[128.114157] Starting subtest: ctm-max
[128.116520] Starting dynamic subtest: pipe-A-eDP-1
[128.825399] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.712s)
[128.833790] Starting dynamic subtest: pipe-B-eDP-1
[130.217080] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.390s)
[130.225988] Starting dynamic subtest: pipe-C-eDP-1
[131.606550] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.392s)
[131.615549] Starting dynamic subtest: pipe-D-eDP-1
[132.930937] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.322s)
[132.933306] Subtest ctm-max: SUCCESS (4.817s)
[133.744888] [018/133] (884s left) gem_exec_params (rel-constants-invalid)
[133.826932] Starting subtest: rel-constants-invalid
[133.829274] Subtest rel-constants-invalid: SUCCESS (0.000s)
[133.945963] [019/133] (884s left) gem_exec_schedule (noreorder-corked)
[134.130912] Starting subtest: noreorder-corked
[134.133436] Starting dynamic subtest: rcs0
[135.299027] Dynamic subtest rcs0: SUCCESS (1.169s)
[135.310735] Starting dynamic subtest: vcs0
[136.956478] Dynamic subtest vcs0: SUCCESS (1.654s)
[136.968192] Starting dynamic subtest: vcs1
[138.886243] Dynamic subtest vcs1: SUCCESS (1.928s)
[138.897072] Starting dynamic subtest: vecs0
[140.901686] Dynamic subtest vecs0: SUCCESS (2.012s)
[140.913113] Starting dynamic subtest: ccs0
[142.082966] Dynamic subtest ccs0: SUCCESS (1.180s)
[142.085567] Subtest noreorder-corked: SUCCESS (7.954s)
[142.278046] [020/133] (876s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[142.349925] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[142.408192] [021/133] (876s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[142.792738] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[143.644387] [022/133] (875s left) gem_exec_whisper (basic-contexts-priority-all)
[143.706826] Starting subtest: basic-contexts-priority-all
[165.861315] Subtest basic-contexts-priority-all: SUCCESS (22.147s)
[166.057978] [023/133] (852s left) gen9_exec_parse (allowed-all)
[166.128851] Subtest allowed-all: SKIP (0.000s)
[166.214328] [024/133] (852s left) perf_pmu (busy-accuracy-98)
[166.254809] Starting subtest: busy-accuracy-98
[166.257049] Starting dynamic subtest: rcs0
[169.294418] Dynamic subtest rcs0: SUCCESS (3.037s)
[169.302913] Starting dynamic subtest: bcs0
[172.345418] Dynamic subtest bcs0: SUCCESS (3.049s)
[172.353720] Starting dynamic subtest: vcs0
[175.389099] Dynamic subtest vcs0: SUCCESS (3.042s)
[175.398106] Starting dynamic subtest: vcs1
[178.429596] Dynamic subtest vcs1: SUCCESS (3.038s)
[178.440383] Starting dynamic subtest: vecs0
[181.470726] Dynamic subtest vecs0: SUCCESS (3.039s)
[181.482074] Starting dynamic subtest: ccs0
[184.516835] Dynamic subtest ccs0: SUCCESS (3.045s)
[184.519056] Subtest busy-accuracy-98: SUCCESS (18.261s)
[184.650718] [025/133] (834s left) gem_workarounds (suspend-resume-fd)
[184.707811] Starting subtest: suspend-resume-fd
[201.179569] Subtest suspend-resume-fd: SUCCESS (2.025s)
[201.336848] [026/133] (817s left) kms_psr (fbc-pr-sprite-plane-move)
[201.452121] Starting subtest: fbc-pr-sprite-plane-move
[201.456773] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[201.536270] [027/133] (817s left) syncobj_wait (invalid-wait-bad-flags)
[201.585664] Starting subtest: invalid-wait-bad-flags
[201.587967] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[201.703166] [028/133] (817s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[201.789356] Starting subtest: fbc-rgb565-draw-mmap-wc
[205.791560] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.080s)
[206.945671] [029/133] (811s left) kms_addfb_basic (unused-offsets)
[207.008627] Starting subtest: unused-offsets
[207.010640] Subtest unused-offsets: SUCCESS (0.000s)
[207.163352] [030/133] (811s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[207.217554] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[207.219892] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[207.342914] [031/133] (811s left) kms_psr (fbc-pr-cursor-plane-move)
[207.445228] Starting subtest: fbc-pr-cursor-plane-move
[207.449485] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[207.529043] [032/133] (811s left) kms_psr (psr-cursor-render)
[207.624023] Starting subtest: psr-cursor-render
[207.626334] Starting dynamic subtest: eDP-1
[209.300119] Dynamic subtest eDP-1: SUCCESS (1.693s)
[209.302320] Subtest psr-cursor-render: SUCCESS (1.695s)
[210.170717] [033/133] (808s left) kms_flip (2x-dpms-vs-vblank-race-interruptible)
[210.259229] Starting subtest: 2x-dpms-vs-vblank-race-interruptible
[210.270876] Subtest 2x-dpms-vs-vblank-race-interruptible: SKIP (0.011s)
[210.352733] [034/133] (808s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[210.450586] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[210.454914] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[210.564810] [035/133] (808s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[210.664249] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[210.668320] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.000s)
[210.747293] [036/133] (808s left) kms_addfb_basic (invalid-get-prop)
[210.808264] Starting subtest: invalid-get-prop
[210.810372] Subtest invalid-get-prop: SUCCESS (0.000s)
[210.976446] [037/133] (808s left) gem_softpin (evict-prime-sanity-check)
[211.041904] Starting subtest: evict-prime-sanity-check
[211.044225] Starting dynamic subtest: rcs0
[212.214936] Dynamic subtest rcs0: SUCCESS (1.179s)
[212.227192] Starting dynamic subtest: bcs0
[213.371238] Dynamic subtest bcs0: SUCCESS (1.162s)
[213.383114] Starting dynamic subtest: vcs0
[215.283251] Dynamic subtest vcs0: SUCCESS (1.917s)
[215.295343] Starting dynamic subtest: vcs1
[217.378869] Dynamic subtest vcs1: SUCCESS (2.101s)
[217.390940] Starting dynamic subtest: vecs0
[219.362912] Dynamic subtest vecs0: SUCCESS (1.988s)
[219.375213] Starting dynamic subtest: ccs0
[220.494440] Dynamic subtest ccs0: SUCCESS (1.134s)
[220.506635] Starting dynamic subtest: all
[221.637388] Dynamic subtest all: SUCCESS (1.144s)
[221.640038] Subtest evict-prime-sanity-check: SUCCESS (10.639s)
[221.729623] [038/133] (797s left) i915_suspend (fence-restore-untiled)
[221.786035] Starting subtest: fence-restore-untiled
[221.790984] Subtest fence-restore-untiled: SKIP (0.000s)
[221.916970] [039/133] (797s left) kms_psr (psr-sprite-mmap-gtt)
[222.019885] Starting subtest: psr-sprite-mmap-gtt
[222.022002] Starting dynamic subtest: eDP-1
[223.528726] Dynamic subtest eDP-1: SKIP (1.509s)
[223.533597] Subtest psr-sprite-mmap-gtt: SKIP (1.511s)
[223.682267] [040/133] (795s left) gem_userptr_blits (unsync-unmap)
[223.762226] Subtest unsync-unmap: SKIP (0.000s)
[223.911271] [041/133] (795s left) gem_exec_balancer (bonded-true-hang)
[224.183018] Starting subtest: bonded-true-hang
[224.188280] Subtest bonded-true-hang: SKIP (0.000s)
[224.285655] [042/133] (795s left) gem_lmem_swapping (parallel-random-engines)
[224.336920] Subtest parallel-random-engines: SKIP (0.000s)
[224.385098] [043/133] (795s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[224.452472] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[224.456864] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[224.647280] [044/133] (794s left) syncobj_timeline (etime-single-wait-all-submitted)
[224.707158] Starting subtest: etime-single-wait-all-submitted
[224.814545] Subtest etime-single-wait-all-submitted: SUCCESS (0.107s)
[224.896821] [045/133] (794s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[225.024615] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[225.029156] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[225.151587] [046/133] (794s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[225.248461] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[225.252939] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[225.389505] [047/133] (794s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[225.525421] Starting subtest: short-busy-flip-before-cursor-toggle
[225.529891] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[225.678389] [048/133] (793s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[225.782964] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[232.769043] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (6.997s)
[232.930362] [049/133] (786s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[233.032214] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[233.034503] Starting dynamic subtest: pipe-A-eDP-1
[233.057237] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[233.067994] Starting dynamic subtest: pipe-B-eDP-1
[233.088414] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[233.097429] Starting dynamic subtest: pipe-C-eDP-1
[233.118321] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[233.127995] Starting dynamic subtest: pipe-D-eDP-1
[233.149367] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[233.153705] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[235.249364] [050/133] (784s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[235.347108] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[235.349416] Starting dynamic subtest: pipe-A-eDP-1
[235.369502] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[235.380499] Starting dynamic subtest: pipe-B-eDP-1
[235.399967] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[235.409146] Starting dynamic subtest: pipe-C-eDP-1
[235.428947] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[235.439748] Starting dynamic subtest: pipe-D-eDP-1
[235.458763] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[235.460930] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[237.612248] [051/133] (782s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[237.719860] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[237.724206] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[237.817600] [052/133] (782s left) gem_partial_pwrite_pread (reads-snoop)
[237.886436] Subtest reads-snoop: SKIP (0.000s)
[237.975745] [053/133] (781s left) syncobj_timeline (invalid-query-illegal-handle)
[238.035877] Starting subtest: invalid-query-illegal-handle
[238.038145] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[238.169359] [054/133] (781s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[238.267570] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[245.414354] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.150s)
[245.606443] [055/133] (774s left) kms_lease (lease-unleased-connector)
[245.709266] Starting subtest: lease-unleased-connector
[245.711424] Starting dynamic subtest: pipe-A-eDP-1
[245.716533] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[245.727049] Starting dynamic subtest: pipe-B-eDP-1
[245.729243] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s)
[245.738948] Starting dynamic subtest: pipe-C-eDP-1
[245.741245] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[245.750343] Starting dynamic subtest: pipe-D-eDP-1
[245.752436] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[245.754530] Subtest lease-unleased-connector: SUCCESS (0.032s)
[245.862478] [056/133] (774s left) syncobj_wait (invalid-single-wait-unsubmitted)
[245.918212] Starting subtest: invalid-single-wait-unsubmitted
[245.920531] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[246.055734] [057/133] (773s left) gem_mmap_gtt (ptrace)
[246.123860] Subtest ptrace: SKIP (0.000s)
[246.193386] [058/133] (773s left) syncobj_timeline (multi-wait-submitted-signaled)
[246.256661] Starting subtest: multi-wait-submitted-signaled
[246.259025] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[246.360496] [059/133] (773s left) gem_madvise (dontneed-before-exec)
[246.394329] Starting subtest: dontneed-before-exec
[246.422309] Subtest dontneed-before-exec: SKIP (0.026s)
[246.490337] [060/133] (773s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[246.594885] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[246.598259] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[246.705233] [061/133] (773s left) gem_ccs (ctrl-surf-copy-new-ctx)
[246.770320] Starting subtest: ctrl-surf-copy-new-ctx
[246.774336] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[246.907711] [062/133] (773s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[247.011089] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[251.456900] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.449s)
[252.634547] [063/133] (767s left) syncobj_wait (single-wait-all-for-submit-signaled)
[252.697134] Starting subtest: single-wait-all-for-submit-signaled
[252.699019] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[252.810574] [064/133] (767s left) gem_ctx_isolation (nonpriv-switch)
[252.882139] Starting subtest: nonpriv-switch
[252.884511] Starting dynamic subtest: rcs0
[253.164335] Dynamic subtest rcs0: SUCCESS (0.282s)
[253.176436] Starting dynamic subtest: bcs0
[253.479457] Dynamic subtest bcs0: SUCCESS (0.314s)
[253.490688] Starting dynamic subtest: vcs0
[253.788838] Dynamic subtest vcs0: SUCCESS (0.309s)
[253.800075] Starting dynamic subtest: vcs1
[255.700718] Dynamic subtest vcs1: SUCCESS (1.911s)
[255.711525] Starting dynamic subtest: vecs0
[257.634148] Dynamic subtest vecs0: SUCCESS (1.933s)
[257.643368] Starting dynamic subtest: ccs0
[259.681397] Dynamic subtest ccs0: SUCCESS (2.047s)
[259.683780] Subtest nonpriv-switch: SUCCESS (6.801s)
[259.919813] [065/133] (760s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[260.022525] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[260.026851] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s)
[260.114802] [066/133] (760s left) i915_drm_fdinfo (most-busy-check-all)
[260.151168] Starting subtest: most-busy-check-all
[260.153044] Starting dynamic subtest: rcs0
[260.157199] Dynamic subtest rcs0: SKIP (0.005s)
[260.167190] Starting dynamic subtest: bcs0
[260.171407] Dynamic subtest bcs0: SKIP (0.000s)
[260.180849] Starting dynamic subtest: vcs0
[260.185141] Dynamic subtest vcs0: SKIP (0.000s)
[260.193826] Starting dynamic subtest: vcs1
[260.198108] Dynamic subtest vcs1: SKIP (0.000s)
[260.207012] Starting dynamic subtest: vecs0
[260.211004] Dynamic subtest vecs0: SKIP (0.000s)
[260.219664] Starting dynamic subtest: ccs0
[260.223713] Dynamic subtest ccs0: SKIP (0.000s)
[260.227773] Subtest most-busy-check-all: SKIP (0.010s)
[260.263754] [067/133] (760s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[260.391045] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[260.393292] Starting dynamic subtest: pipe-A-eDP-1
[260.509301] Dynamic subtest pipe-A-eDP-1: SKIP (0.122s)
[260.520241] Starting dynamic subtest: pipe-B-eDP-1
[261.687475] Dynamic subtest pipe-B-eDP-1: SKIP (1.177s)
[261.692212] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.302s)
[262.981739] [068/133] (757s left) gem_create (create-ext-placement-each)
[263.154535] Starting subtest: create-ext-placement-each
[263.157398] Subtest create-ext-placement-each: SUCCESS (0.000s)
[263.280389] [069/133] (757s left) gen7_exec_parse (basic-allocation)
[263.349353] Subtest basic-allocation: SKIP (0.000s)
[263.441252] [070/133] (757s left) api_intel_bb (intel-bb-blit-none)
[263.501120] Starting subtest: intel-bb-blit-none
[267.573644] Subtest intel-bb-blit-none: SUCCESS (4.074s)
[267.712803] [071/133] (752s left) kms_chamelium_hpd (vga-hpd)
[268.091931] Subtest vga-hpd: SKIP (0.000s)
[268.865420] [072/133] (751s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[268.924342] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[268.989126] [073/133] (751s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[269.055450] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[269.138876] [074/133] (751s left) i915_pm_rps (reset)
[269.198112] Starting subtest: reset
[277.672159] Subtest reset: SUCCESS (8.480s)
[277.836416] [075/133] (742s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[277.965444] Starting subtest: cursor-vs-flip-legacy
[307.314913] Subtest cursor-vs-flip-legacy: SUCCESS (29.350s)
[308.118515] [076/133] (712s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[308.225275] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[308.227542] Starting dynamic subtest: pipe-A-eDP-1
[308.236565] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[308.247889] Starting dynamic subtest: pipe-B-eDP-1
[308.256840] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[308.266135] Starting dynamic subtest: pipe-C-eDP-1
[308.274348] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[308.283683] Starting dynamic subtest: pipe-D-eDP-1
[308.292054] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[308.296109] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.005s)
[310.488218] [077/133] (710s left) gem_exec_balancer (parallel-keep-in-fence)
[310.741123] Starting subtest: parallel-keep-in-fence
[311.965273] Subtest parallel-keep-in-fence: SUCCESS (1.224s)
[312.099968] [078/133] (708s left) prime_busy (after-wait)
[312.159941] Starting subtest: after-wait
[312.164760] Starting dynamic subtest: rcs0
[312.210468] Dynamic subtest rcs0: SUCCESS (0.048s)
[312.220369] Starting dynamic subtest: bcs0
[312.273833] Dynamic subtest bcs0: SUCCESS (0.062s)
[312.284242] Starting dynamic subtest: vcs0
[312.313532] Dynamic subtest vcs0: SUCCESS (0.038s)
[312.323162] Starting dynamic subtest: vcs1
[312.354157] Dynamic subtest vcs1: SUCCESS (0.039s)
[312.363922] Starting dynamic subtest: vecs0
[312.394332] Dynamic subtest vecs0: SUCCESS (0.039s)
[312.403726] Starting dynamic subtest: ccs0
[312.435597] Dynamic subtest ccs0: SUCCESS (0.041s)
[312.544543] Subtest after-wait: SUCCESS (0.383s)
[312.631242] [079/133] (708s left) gem_exec_schedule (u-independent)
[312.703736] Starting subtest: u-independent
[312.705663] Starting dynamic subtest: rcs0
[314.371943] Dynamic subtest rcs0: SUCCESS (1.678s)
[314.384239] Starting dynamic subtest: vcs0
[316.397496] Dynamic subtest vcs0: SUCCESS (2.023s)
[316.408250] Starting dynamic subtest: vcs1
[318.382723] Dynamic subtest vcs1: SUCCESS (1.982s)
[318.397286] Starting dynamic subtest: vecs0
[320.362747] Dynamic subtest vecs0: SUCCESS (1.977s)
[320.375373] Starting dynamic subtest: ccs0
[322.370913] Dynamic subtest ccs0: SUCCESS (2.006s)
[322.373494] Subtest u-independent: SUCCESS (9.678s)
[322.727726] [080/133] (698s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[322.834668] Starting subtest: fbc-pr-cursor-plane-update-sf
[322.839115] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.000s)
[322.915224] [081/133] (698s left) kms_sequence (queue-busy)
[323.025481] Starting subtest: queue-busy
[323.027832] Starting dynamic subtest: pipe-A-eDP-1
[324.963105] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.937s)
[324.974310] Starting dynamic subtest: pipe-B-eDP-1
[327.582817] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.618s)
[327.593921] Starting dynamic subtest: pipe-C-eDP-1
[330.190938] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.609s)
[330.202077] Starting dynamic subtest: pipe-D-eDP-1
[332.720096] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.526s)
[332.722428] Subtest queue-busy: SUCCESS (9.696s)
[333.525135] [082/133] (687s left) kms_chamelium_audio (hdmi-audio)
[333.909877] Subtest hdmi-audio: SKIP (0.000s)
[334.722550] [083/133] (686s left) gem_mmap_gtt (big-bo-tiledy)
[334.807519] Subtest big-bo-tiledY: SKIP (0.000s)
[334.893570] [084/133] (686s left) gem_mmap_wc (write-prefaulted)
[334.958115] Subtest write-prefaulted: SKIP (0.000s)
[335.027805] [085/133] (686s left) gem_exec_fence (syncobj-unused-fence)
[335.513695] Starting subtest: syncobj-unused-fence
[335.519486] Subtest syncobj-unused-fence: SUCCESS (0.005s)
[337.476162] [FACT gem_exec_fence (syncobj-unused-fence)] new: kernel.kmod_is_loaded.vgem: true
[337.479777] [086/133] (683s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[337.537832] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[337.647602] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.108s)
[337.738575] [087/133] (683s left) i915_hangman (engine-engine-hang)
[337.811925] Starting subtest: engine-engine-hang
[337.813854] Starting dynamic subtest: rcs0
[339.297422] Dynamic subtest rcs0: SUCCESS (1.486s)
[339.309640] Starting dynamic subtest: bcs0
[341.344249] Dynamic subtest bcs0: SUCCESS (2.044s)
[341.356638] Starting dynamic subtest: vcs0
[343.328556] Dynamic subtest vcs0: SUCCESS (1.982s)
[343.352151] Starting dynamic subtest: vcs1
[345.249388] Dynamic subtest vcs1: SUCCESS (1.919s)
[345.273078] Starting dynamic subtest: vecs0
[347.298627] Dynamic subtest vecs0: SUCCESS (2.047s)
[347.321066] Starting dynamic subtest: ccs0
[349.283059] Dynamic subtest ccs0: SUCCESS (1.982s)
[349.285893] Subtest engine-engine-hang: SUCCESS (11.474s)
[349.419841] [088/133] (671s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[349.533865] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[349.536195] Starting dynamic subtest: pipe-A-eDP-1
[349.594914] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[349.615518] Starting dynamic subtest: pipe-B-eDP-1
[349.674647] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[349.695652] Starting dynamic subtest: pipe-C-eDP-1
[349.753226] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[349.772599] Starting dynamic subtest: pipe-D-eDP-1
[349.831464] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[349.836247] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.009s)
[351.771101] [089/133] (669s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[351.879033] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[351.883401] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[352.011166] [090/133] (669s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[352.110270] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[352.114345] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[352.194101] [091/133] (669s left) prime_mmap (test_map_unmap)
[352.264661] Starting subtest: test_map_unmap
[352.266574] Starting dynamic subtest: test_map_unmap-smem
[352.293659] Dynamic subtest test_map_unmap-smem: SUCCESS (0.031s)
[352.295993] Subtest test_map_unmap: SUCCESS (0.032s)
[352.429228] [092/133] (669s left) prime_self_import (basic-with_two_bos)
[352.467782] Starting subtest: basic-with_two_bos
[352.491712] Subtest basic-with_two_bos: SUCCESS (0.024s)
[352.625466] [093/133] (669s left) sysfs_preempt_timeout (invalid)
[352.691202] Starting subtest: invalid
[352.693336] Starting dynamic subtest: vecs0
[352.695225] Dynamic subtest vecs0: SUCCESS (0.000s)
[352.706757] Starting dynamic subtest: ccs0
[352.708470] Dynamic subtest ccs0: SUCCESS (0.000s)
[352.717799] Starting dynamic subtest: vcs0
[352.719475] Dynamic subtest vcs0: SUCCESS (0.000s)
[352.728821] Starting dynamic subtest: bcs0
[352.730551] Dynamic subtest bcs0: SUCCESS (0.000s)
[352.739388] Starting dynamic subtest: rcs0
[352.741164] Dynamic subtest rcs0: SUCCESS (0.000s)
[352.750049] Starting dynamic subtest: vcs1
[352.752077] Dynamic subtest vcs1: SUCCESS (0.000s)
[352.753973] Subtest invalid: SUCCESS (0.006s)
[352.822591] [094/133] (668s left) kms_big_fb (linear-64bpp-rotate-180)
[352.928218] Starting subtest: linear-64bpp-rotate-180
[354.630027] Subtest linear-64bpp-rotate-180: SUCCESS (1.701s)
[354.822113] [095/133] (666s left) gem_tiled_pread_pwrite
[354.950107] [096/133] (666s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[355.056899] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[360.339354] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.284s)
[361.537835] [097/133] (660s left) gem_fence_thrash (bo-write-verify-y)
[361.576034] Starting subtest: bo-write-verify-y
[361.602161] Subtest bo-write-verify-y: SKIP (0.022s)
[361.666979] [098/133] (660s left) kms_big_fb (4-tiled-addfb)
[361.769037] Starting subtest: 4-tiled-addfb
[361.771495] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[361.900773] [099/133] (659s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[362.005817] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[366.425120] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.424s)
[367.632071] [100/133] (654s left) gem_exec_fence (syncobj-timeline-chain-engines)
[368.167433] Starting subtest: syncobj-timeline-chain-engines
[368.293422] Subtest syncobj-timeline-chain-engines: SUCCESS (0.126s)
[369.478601] [101/133] (652s left) kms_pm_rpm (modeset-non-lpsp)
[371.507639] Starting subtest: modeset-non-lpsp
[372.669086] Subtest modeset-non-lpsp: SKIP (1.159s)
[373.036113] [102/133] (648s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[373.136229] Subtest addfb25-x-tiled-legacy: SKIP (0.000s)
[373.285954] [103/133] (648s left) kms_cursor_crc (cursor-size-hints)
[373.421201] Starting subtest: cursor-size-hints
[373.423015] Starting dynamic subtest: pipe-A-eDP-1
[374.037656] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.617s)
[374.048447] Starting dynamic subtest: pipe-D-eDP-1
[375.282875] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.245s)
[375.285230] Subtest cursor-size-hints: SUCCESS (1.864s)
[376.096292] [104/133] (646s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[376.203649] Starting subtest: yf-tiled-addfb-size-overflow
[376.208096] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[376.331551] [105/133] (645s left) gem_pxp (verify-pxp-stale-ctx-execution)
[376.459899] Starting subtest: verify-pxp-stale-ctx-execution
[377.522065] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.062s)
[377.684514] [106/133] (644s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[377.795518] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[378.840388] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.047s)
[379.020944] [107/133] (643s left) kms_psr (psr2-cursor-mmap-cpu)
[379.118622] Starting subtest: psr2-cursor-mmap-cpu
[379.120521] Starting dynamic subtest: eDP-1
[380.849273] Dynamic subtest eDP-1: SUCCESS (1.730s)
[380.851681] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.731s)
[381.657330] [108/133] (640s left) kms_psr (fbc-psr-sprite-plane-onoff)
[381.753375] Starting subtest: fbc-psr-sprite-plane-onoff
[381.755630] Starting dynamic subtest: eDP-1
[381.759768] Dynamic subtest eDP-1: SKIP (0.000s)
[381.763892] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[381.839102] [109/133] (640s left) kms_pm_rpm (fences-dpms)
[383.564947] Starting subtest: fences-dpms
[383.569835] Subtest fences-dpms: SKIP (0.000s)
[384.125371] [110/133] (638s left) prime_mmap_coherency (read)
[384.187499] Starting subtest: read
[389.564794] Subtest read: SUCCESS (5.377s)
[389.715126] [111/133] (632s left) gem_exec_fence (basic-busy-all)
[389.777097] Starting subtest: basic-busy-all
[389.893351] Subtest basic-busy-all: SUCCESS (0.117s)
[391.439571] [112/133] (631s left) prime_vgem (basic-fence-flip)
[391.532237] Starting subtest: basic-fence-flip
[391.843575] Subtest basic-fence-flip: SUCCESS (0.311s)
[391.999471] [113/133] (630s left) syncobj_wait (invalid-signal-bad-pad)
[392.063916] Starting subtest: invalid-signal-bad-pad
[392.066241] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[392.185846] [114/133] (630s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[392.288810] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[392.293266] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[392.422614] [115/133] (630s left) kms_big_fb (x-tiled-addfb)
[392.524363] Starting subtest: x-tiled-addfb
[392.526722] Subtest x-tiled-addfb: SUCCESS (0.000s)
[392.640385] [116/133] (629s left) gem_ctx_persistence (legacy-engines-hostile)
[392.707731] Starting subtest: legacy-engines-hostile
[392.709938] Starting dynamic subtest: render
[392.815906] Dynamic subtest render: SUCCESS (0.107s)
[392.832383] Starting dynamic subtest: bsd1
[392.938717] Dynamic subtest bsd1: SUCCESS (0.120s)
[392.952685] Starting dynamic subtest: bsd2
[393.055008] Dynamic subtest bsd2: SUCCESS (0.113s)
[393.067715] Starting dynamic subtest: blt
[393.171936] Dynamic subtest blt: SUCCESS (0.114s)
[393.184655] Starting dynamic subtest: vebox
[393.290975] Dynamic subtest vebox: SUCCESS (0.116s)
[393.293349] Subtest legacy-engines-hostile: SUCCESS (0.584s)
[393.381381] [117/133] (629s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[393.483921] Starting subtest: 4-tiled-16bpp-rotate-180
[394.742352] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.257s)
[394.904069] [118/133] (627s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[395.009579] Starting subtest: compare-crc-sanitycheck-nv12
[395.011231] Starting dynamic subtest: pipe-A-eDP-1
[395.668852] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.659s)
[395.680213] Starting dynamic subtest: pipe-B-eDP-1
[396.928757] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.257s)
[396.939618] Starting dynamic subtest: pipe-C-eDP-1
[398.114859] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.185s)
[398.125737] Starting dynamic subtest: pipe-D-eDP-1
[399.358284] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.242s)
[399.360709] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.349s)
[400.188695] [119/133] (622s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[400.289712] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[406.986598] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.700s)
[407.164139] [120/133] (615s left) kms_flip (wf_vblank-ts-check-interruptible)
[407.260292] Starting subtest: wf_vblank-ts-check-interruptible
[407.262620] Starting dynamic subtest: A-eDP1
[416.148102] Dynamic subtest A-eDP1: SUCCESS (8.890s)
[416.160249] Starting dynamic subtest: B-eDP1
[425.069270] Dynamic subtest B-eDP1: SUCCESS (8.922s)
[425.081252] Starting dynamic subtest: C-eDP1
[433.985988] Dynamic subtest C-eDP1: SUCCESS (8.911s)
[433.997268] Starting dynamic subtest: D-eDP1
[442.831515] Dynamic subtest D-eDP1: SUCCESS (8.840s)
[442.833687] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.573s)
[444.090628] [121/133] (578s left) gem_eio (create)
[444.324843] Starting subtest: create
[444.691861] Subtest create: SUCCESS (0.367s)
[444.858774] [122/133] (578s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[445.019729] Starting subtest: dp-mst-lic-type-0-hdcp14
[445.023555] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[445.169623] [123/133] (577s left) kms_vblank (query-idle)
[445.270624] Starting subtest: query-idle
[445.272550] Starting dynamic subtest: pipe-A-eDP-1
[447.023383] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.752s)
[447.038453] Starting dynamic subtest: pipe-D-eDP-1
[449.523209] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.497s)
[449.525483] Subtest query-idle: SUCCESS (4.252s)
[450.364024] [124/133] (572s left) kms_plane_scaling (invalid-num-scalers)
[450.470219] Starting subtest: invalid-num-scalers
[450.472415] Starting dynamic subtest: pipe-A-eDP-1-invalid-num-scalers
[450.495804] Dynamic subtest pipe-A-eDP-1-invalid-num-scalers: SUCCESS (0.025s)
[450.508242] Starting dynamic subtest: pipe-B-eDP-1-invalid-num-scalers
[450.532804] Dynamic subtest pipe-B-eDP-1-invalid-num-scalers: SUCCESS (0.032s)
[450.542972] Starting dynamic subtest: pipe-C-eDP-1-invalid-num-scalers
[450.564208] Dynamic subtest pipe-C-eDP-1-invalid-num-scalers: SUCCESS (0.031s)
[450.574372] Starting dynamic subtest: pipe-D-eDP-1-invalid-num-scalers
[450.595865] Dynamic subtest pipe-D-eDP-1-invalid-num-scalers: SUCCESS (0.031s)
[450.598159] Subtest invalid-num-scalers: SUCCESS (0.127s)
[450.719658] [125/133] (572s left) kms_cursor_crc (cursor-onscreen-512x170)
[451.027939] Starting subtest: cursor-onscreen-512x170
[451.032395] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[451.188773] [126/133] (571s left) kms_rotation_crc (primary-rotation-270)
[451.285877] Starting subtest: primary-rotation-270
[451.289516] Subtest primary-rotation-270: SKIP (0.001s)
[451.389735] [127/133] (571s left) perf (invalid-oa-metric-set-id)
[451.481495] Starting subtest: invalid-oa-metric-set-id
[451.492543] Subtest invalid-oa-metric-set-id: SUCCESS (0.011s)
[451.535236] [128/133] (571s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[451.593362] Starting subtest: single-wait-all-for-submit-available-submitted
[451.595055] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[451.707236] [129/133] (571s left) kms_draw_crc (draw-method-render)
[451.813312] Starting subtest: draw-method-render
[451.814859] Starting dynamic subtest: xrgb8888-untiled
[452.079699] Dynamic subtest xrgb8888-untiled: SUCCESS (0.267s)
[452.089687] Starting dynamic subtest: rgb565-untiled
[452.301675] Dynamic subtest rgb565-untiled: SUCCESS (0.222s)
[452.311812] Starting dynamic subtest: xrgb2101010-untiled
[452.524270] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[452.535006] Starting dynamic subtest: xbgr16161616f-untiled
[452.757488] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.233s)
[452.769749] Starting dynamic subtest: xrgb8888-xtiled
[452.890705] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s)
[452.901551] Starting dynamic subtest: rgb565-xtiled
[453.013155] Dynamic subtest rgb565-xtiled: SUCCESS (0.121s)
[453.023259] Starting dynamic subtest: xrgb2101010-xtiled
[453.135507] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[453.147993] Starting dynamic subtest: xbgr16161616f-xtiled
[453.268699] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[453.280107] Starting dynamic subtest: xrgb8888-4tiled
[453.402063] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.133s)
[453.412120] Starting dynamic subtest: rgb565-4tiled
[453.512985] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[453.522981] Starting dynamic subtest: xrgb2101010-4tiled
[453.635224] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.121s)
[453.645321] Starting dynamic subtest: xbgr16161616f-4tiled
[453.757569] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.121s)
[453.759475] Subtest draw-method-render: SUCCESS (1.945s)
[453.923065] [130/133] (569s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[454.054215] Starting subtest: basic-flip-before-cursor-legacy
[454.968654] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.914s)
[455.791253] [131/133] (567s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[455.890074] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[455.894372] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[456.001781] [132/133] (567s left) perf_pmu (idle-no-semaphores)
[456.044716] Starting subtest: idle-no-semaphores
[456.047017] Starting dynamic subtest: rcs0
[456.600099] Dynamic subtest rcs0: SUCCESS (0.556s)
[456.613244] Starting dynamic subtest: bcs0
[457.123201] Dynamic subtest bcs0: SUCCESS (0.520s)
[457.135234] Starting dynamic subtest: vcs0
[457.645073] Dynamic subtest vcs0: SUCCESS (0.518s)
[457.658603] Starting dynamic subtest: vcs1
[458.160668] Dynamic subtest vcs1: SUCCESS (0.513s)
[458.173411] Starting dynamic subtest: vecs0
[458.688220] Dynamic subtest vecs0: SUCCESS (0.524s)
[458.701441] Starting dynamic subtest: ccs0
[459.204034] Dynamic subtest ccs0: SUCCESS (0.513s)
[459.206935] Subtest idle-no-semaphores: SUCCESS (3.161s)
[459.332247] [133/133] (564s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[459.397656] Starting subtest: multi-wait-all-for-submit-signaled
[459.399990] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[459.524628] Closing watchdogs
results: parsing output: 0/ for test: kms_plane
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: prime_mmap_coherency
results: parsing output: 4/ for test: perf
results: parsing output: 5/ for test: core_getversion
results: parsing output: 6/ for test: kms_flip
results: parsing output: 7/ for test: api_intel_allocator
results: parsing output: 8/ for test: tools_test
results: parsing output: 9/ for test: kms_invalid_mode
results: parsing output: 10/ for test: kms_getfb
results: parsing output: 11/ for test: gen3_mixed_blits
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_cursor_crc
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: kms_pm_backlight
results: parsing output: 16/ for test: kms_color
results: parsing output: 17/ for test: gem_exec_params
results: parsing output: 18/ for test: gem_exec_schedule
results: parsing output: 19/ for test: gem_mmap_gtt
results: parsing output: 20/ for test: kms_chamelium_frames
results: parsing output: 21/ for test: gem_exec_whisper
results: parsing output: 22/ for test: gen9_exec_parse
results: parsing output: 23/ for test: perf_pmu
results: parsing output: 24/ for test: gem_workarounds
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: syncobj_wait
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_addfb_basic
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: kms_psr
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_flip_scaled_crc
results: parsing output: 35/ for test: kms_addfb_basic
results: parsing output: 36/ for test: gem_softpin
results: parsing output: 37/ for test: i915_suspend
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: gem_userptr_blits
results: parsing output: 40/ for test: gem_exec_balancer
results: parsing output: 41/ for test: gem_lmem_swapping
results: parsing output: 42/ for test: gem_render_copy
results: parsing output: 43/ for test: syncobj_timeline
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_cursor_legacy
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_partial_pwrite_pread
results: parsing output: 52/ for test: syncobj_timeline
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_lease
results: parsing output: 55/ for test: syncobj_wait
results: parsing output: 56/ for test: gem_mmap_gtt
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: gem_madvise
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_ccs
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: syncobj_wait
results: parsing output: 63/ for test: gem_ctx_isolation
results: parsing output: 64/ for test: kms_flip_scaled_crc
results: parsing output: 65/ for test: i915_drm_fdinfo
results: parsing output: 66/ for test: kms_psr2_sf
results: parsing output: 67/ for test: gem_create
results: parsing output: 68/ for test: gen7_exec_parse
results: parsing output: 69/ for test: api_intel_bb
results: parsing output: 70/ for test: kms_chamelium_hpd
results: parsing output: 71/ for test: kms_pipe_b_c_ivb
results: parsing output: 72/ for test: gem_exec_reloc
results: parsing output: 73/ for test: i915_pm_rps
results: parsing output: 74/ for test: kms_cursor_legacy
results: parsing output: 75/ for test: kms_ccs
results: parsing output: 76/ for test: gem_exec_balancer
results: parsing output: 77/ for test: prime_busy
results: parsing output: 78/ for test: gem_exec_schedule
results: parsing output: 79/ for test: kms_psr2_sf
results: parsing output: 80/ for test: kms_sequence
results: parsing output: 81/ for test: kms_chamelium_audio
results: parsing output: 82/ for test: gem_mmap_gtt
results: parsing output: 83/ for test: gem_mmap_wc
results: parsing output: 84/ for test: gem_exec_fence
results: parsing output: 85/ for test: syncobj_timeline
results: parsing output: 86/ for test: i915_hangman
results: parsing output: 87/ for test: kms_ccs
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_plane
results: parsing output: 90/ for test: prime_mmap
results: parsing output: 91/ for test: prime_self_import
results: parsing output: 92/ for test: sysfs_preempt_timeout
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: gem_tiled_pread_pwrite
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_fence_thrash
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_exec_fence
results: parsing output: 100/ for test: kms_pm_rpm
results: parsing output: 101/ for test: kms_addfb_basic
results: parsing output: 102/ for test: kms_cursor_crc
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: gem_pxp
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: kms_pm_rpm
results: parsing output: 109/ for test: prime_mmap_coherency
results: parsing output: 110/ for test: gem_exec_fence
results: parsing output: 111/ for test: prime_vgem
results: parsing output: 112/ for test: syncobj_wait
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: gem_ctx_persistence
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_pipe_crc_basic
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_flip
results: parsing output: 120/ for test: gem_eio
results: parsing output: 121/ for test: kms_content_protection
results: parsing output: 122/ for test: kms_vblank
results: parsing output: 123/ for test: kms_plane_scaling
results: parsing output: 124/ for test: kms_cursor_crc
results: parsing output: 125/ for test: kms_rotation_crc
results: parsing output: 126/ for test: perf
results: parsing output: 127/ for test: syncobj_timeline
results: parsing output: 128/ for test: kms_draw_crc
results: parsing output: 129/ for test: kms_cursor_legacy
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: perf_pmu
results: parsing output: 132/ for test: syncobj_wait
Done.