[57.475670] Initializing watchdogs
[57.475714]   /dev/watchdog0
[57.489159] [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.493286] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.495446] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.498696] [001/133] (960s left) kms_plane (pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping)
[57.616761] Starting subtest: pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping
[57.654906] Starting dynamic subtest: pipe-A-plane-0
[58.088369] Dynamic subtest pipe-A-plane-0: SUCCESS (0.434s)
[58.095435] Starting dynamic subtest: pipe-A-plane-3
[58.332466] Dynamic subtest pipe-A-plane-3: SUCCESS (0.244s)
[59.442122] Starting dynamic subtest: pipe-B-plane-0
[59.858262] Dynamic subtest pipe-B-plane-0: SUCCESS (0.422s)
[59.866369] Starting dynamic subtest: pipe-B-plane-3
[60.113393] Dynamic subtest pipe-B-plane-3: SUCCESS (0.255s)
[60.422749] Subtest pixel-format-4-tiled-mtl-mc-ccs-modifier-source-clamping: SUCCESS (2.807s)
[61.236733] [002/133] (956s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[61.375051] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[61.379268] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SKIP (0.000s)
[61.498491] [003/133] (956s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[61.597060] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[61.601380] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[61.716173] [004/133] (956s left) prime_mmap_coherency (ioctl-errors)
[61.781835] Starting subtest: ioctl-errors
[77.809865] Subtest ioctl-errors: SUCCESS (16.028s)
[77.993991] [005/133] (940s left) perf (non-sampling-read-error)
[78.168714] Starting subtest: non-sampling-read-error
[78.179247] Subtest non-sampling-read-error: SUCCESS (0.010s)
[78.220518] [006/133] (939s left) core_getversion (basic)
[78.257569] Starting subtest: basic
[78.260939] Subtest basic: SUCCESS (0.000s)
[78.337947] [007/133] (939s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset)
[78.440494] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset
[78.451796] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.011s)
[78.549707] [008/133] (939s left) api_intel_allocator (execbuf-with-allocator)
[78.603206] Starting subtest: execbuf-with-allocator
[78.607041] Subtest execbuf-with-allocator: SUCCESS (0.006s)
[78.728732] [009/133] (939s left) tools_test (sysfs_l3_parity)
[78.775137] Starting subtest: sysfs_l3_parity
[78.867362] Subtest sysfs_l3_parity: SKIP (0.102s)
[78.931679] [010/133] (939s left) kms_invalid_mode (bad-htotal)
[79.011705] Starting subtest: bad-htotal
[79.013688] Starting dynamic subtest: pipe-A-eDP-1
[79.307664] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.296s)
[79.317090] Starting dynamic subtest: pipe-B-eDP-1
[79.318903] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[79.326133] Starting dynamic subtest: pipe-C-eDP-1
[79.328062] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[79.335751] Starting dynamic subtest: pipe-D-eDP-1
[79.337859] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[79.339990] Subtest bad-htotal: SUCCESS (0.300s)
[80.159295] [011/133] (938s left) kms_getfb (getfb2-handle-closed)
[80.263796] Starting subtest: getfb2-handle-closed
[80.266079] Subtest getfb2-handle-closed: SUCCESS (0.000s)
[80.385901] [012/133] (938s left) gen3_mixed_blits
[80.511364] [013/133] (937s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[80.607912] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[87.818213] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.211s)
[87.972594] [014/133] (930s left) kms_cursor_crc (cursor-tearing-position-change)
[88.101230] Starting subtest: cursor-tearing-position-change
[88.103545] Starting dynamic subtest: pipe-A-eDP-1
[88.680533] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.576s)
[88.696781] Starting dynamic subtest: pipe-D-eDP-1
[89.938802] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.258s)
[89.941142] Subtest cursor-tearing-position-change: SUCCESS (1.836s)
[90.759802] [015/133] (927s left) kms_flip (absolute-wf_vblank)
[90.860174] Starting subtest: absolute-wf_vblank
[90.862567] Starting dynamic subtest: A-eDP1
[99.604784] Dynamic subtest A-eDP1: SUCCESS (8.743s)
[99.615087] Starting dynamic subtest: B-eDP1
[108.337204] Dynamic subtest B-eDP1: SUCCESS (8.731s)
[108.345080] Starting dynamic subtest: C-eDP1
[117.056883] Dynamic subtest C-eDP1: SUCCESS (8.718s)
[117.065960] Starting dynamic subtest: D-eDP1
[125.779478] Dynamic subtest D-eDP1: SUCCESS (8.723s)
[125.781938] Subtest absolute-wf_vblank: SUCCESS (34.922s)
[127.009131] [016/133] (891s left) kms_pm_backlight (basic-brightness)
[127.106060] Starting subtest: basic-brightness
[127.160298] Starting dynamic subtest: eDP-1
[127.669270] Dynamic subtest eDP-1: SUCCESS (0.509s)
[127.671520] Subtest basic-brightness: SUCCESS (0.564s)
[128.548086] [017/133] (890s left) kms_color (ctm-max)
[128.657237] Starting subtest: ctm-max
[128.659450] Starting dynamic subtest: pipe-A-eDP-1
[129.372243] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.715s)
[129.380967] Starting dynamic subtest: pipe-B-eDP-1
[130.746311] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.373s)
[130.755193] Starting dynamic subtest: pipe-C-eDP-1
[132.135975] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.388s)
[132.144637] Starting dynamic subtest: pipe-D-eDP-1
[133.471222] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.337s)
[133.473508] Subtest ctm-max: SUCCESS (4.815s)
[134.298890] [018/133] (884s left) gem_exec_params (rel-constants-invalid)
[134.355117] Starting subtest: rel-constants-invalid
[134.357537] Subtest rel-constants-invalid: SUCCESS (0.000s)
[134.480980] [019/133] (884s left) gem_exec_schedule (noreorder-corked)
[134.670996] Starting subtest: noreorder-corked
[134.673546] Starting dynamic subtest: rcs0
[135.837855] Dynamic subtest rcs0: SUCCESS (1.167s)
[135.847704] Starting dynamic subtest: vcs0
[137.913800] Dynamic subtest vcs0: SUCCESS (2.072s)
[137.925583] Starting dynamic subtest: vcs1
[139.937464] Dynamic subtest vcs1: SUCCESS (2.022s)
[139.948957] Starting dynamic subtest: vecs0
[141.925608] Dynamic subtest vecs0: SUCCESS (1.986s)
[141.938608] Starting dynamic subtest: ccs0
[143.102298] Dynamic subtest ccs0: SUCCESS (1.176s)
[143.104902] Subtest noreorder-corked: SUCCESS (8.433s)
[143.299078] [020/133] (875s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[143.356617] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[143.425014] [021/133] (875s left) kms_chamelium_frames (hdmi-cmp-planar-formats)
[143.807296] Subtest hdmi-cmp-planar-formats: SKIP (0.000s)
[144.577247] [022/133] (874s left) gem_exec_whisper (basic-contexts-priority-all)
[144.647111] Starting subtest: basic-contexts-priority-all
[166.802244] Subtest basic-contexts-priority-all: SUCCESS (22.151s)
[167.012925] [023/133] (851s left) gen9_exec_parse (allowed-all)
[167.079892] Subtest allowed-all: SKIP (0.000s)
[167.152468] [024/133] (851s left) perf_pmu (busy-accuracy-98)
[167.190076] Starting subtest: busy-accuracy-98
[167.192404] Starting dynamic subtest: rcs0
[170.227778] Dynamic subtest rcs0: SUCCESS (3.037s)
[170.236782] Starting dynamic subtest: bcs0
[173.278580] Dynamic subtest bcs0: SUCCESS (3.048s)
[173.288396] Starting dynamic subtest: vcs0
[176.320197] Dynamic subtest vcs0: SUCCESS (3.041s)
[176.332254] Starting dynamic subtest: vcs1
[179.360496] Dynamic subtest vcs1: SUCCESS (3.038s)
[179.369155] Starting dynamic subtest: vecs0
[182.397195] Dynamic subtest vecs0: SUCCESS (3.035s)
[182.405963] Starting dynamic subtest: ccs0
[185.445670] Dynamic subtest ccs0: SUCCESS (3.047s)
[185.448259] Subtest busy-accuracy-98: SUCCESS (18.257s)
[185.584587] [025/133] (833s left) gem_workarounds (suspend-resume-fd)
[185.663086] Starting subtest: suspend-resume-fd
[201.360926] Subtest suspend-resume-fd: SUCCESS (1.305s)
[202.508154] [026/133] (817s left) kms_psr (fbc-pr-sprite-plane-move)
[202.608528] Starting subtest: fbc-pr-sprite-plane-move
[202.612959] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[202.705062] [027/133] (817s left) syncobj_wait (invalid-wait-bad-flags)
[202.771937] Starting subtest: invalid-wait-bad-flags
[202.774146] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[202.878017] [028/133] (817s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[202.996486] Starting subtest: fbc-rgb565-draw-mmap-wc
[207.007037] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (4.023s)
[208.155213] [029/133] (811s left) kms_addfb_basic (unused-offsets)
[208.223512] Starting subtest: unused-offsets
[208.225688] Subtest unused-offsets: SUCCESS (0.000s)
[208.377534] [030/133] (811s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[208.426610] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[208.428984] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[208.528943] [031/133] (811s left) kms_psr (fbc-pr-cursor-plane-move)
[208.622740] Starting subtest: fbc-pr-cursor-plane-move
[208.626977] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[208.701088] [032/133] (811s left) kms_psr (psr-cursor-render)
[208.794680] Starting subtest: psr-cursor-render
[208.796952] Starting dynamic subtest: eDP-1
[210.514132] Dynamic subtest eDP-1: SUCCESS (1.720s)
[210.516328] Subtest psr-cursor-render: SUCCESS (1.721s)
[211.427201] [033/133] (808s left) kms_flip (2x-dpms-vs-vblank-race-interruptible)
[211.529770] Starting subtest: 2x-dpms-vs-vblank-race-interruptible
[211.541958] Subtest 2x-dpms-vs-vblank-race-interruptible: SKIP (0.011s)
[211.665732] [034/133] (808s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[211.770713] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[211.774707] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[211.884611] [035/133] (808s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[211.999616] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[212.003667] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.000s)
[212.100702] [036/133] (808s left) kms_addfb_basic (invalid-get-prop)
[212.171942] Starting subtest: invalid-get-prop
[212.173888] Subtest invalid-get-prop: SUCCESS (0.000s)
[212.324297] [037/133] (808s left) gem_softpin (evict-prime-sanity-check)
[212.391714] Starting subtest: evict-prime-sanity-check
[212.393563] Starting dynamic subtest: rcs0
[213.600682] Dynamic subtest rcs0: SUCCESS (1.208s)
[213.613116] Starting dynamic subtest: bcs0
[214.746207] Dynamic subtest bcs0: SUCCESS (1.144s)
[214.758693] Starting dynamic subtest: vcs0
[216.276440] Dynamic subtest vcs0: SUCCESS (1.526s)
[216.288500] Starting dynamic subtest: vcs1
[218.235655] Dynamic subtest vcs1: SUCCESS (1.957s)
[218.247992] Starting dynamic subtest: vecs0
[220.325750] Dynamic subtest vecs0: SUCCESS (2.088s)
[220.335811] Starting dynamic subtest: ccs0
[221.476562] Dynamic subtest ccs0: SUCCESS (1.150s)
[221.488927] Starting dynamic subtest: all
[222.625387] Dynamic subtest all: SUCCESS (1.147s)
[222.628360] Subtest evict-prime-sanity-check: SUCCESS (10.234s)
[222.715070] [038/133] (797s left) i915_suspend (fence-restore-untiled)
[222.780067] Starting subtest: fence-restore-untiled
[222.784413] Subtest fence-restore-untiled: SKIP (0.000s)
[222.910878] [039/133] (797s left) kms_psr (psr-sprite-mmap-gtt)
[223.019161] Starting subtest: psr-sprite-mmap-gtt
[223.021375] Starting dynamic subtest: eDP-1
[224.517339] Dynamic subtest eDP-1: SKIP (1.494s)
[224.522161] Subtest psr-sprite-mmap-gtt: SKIP (1.496s)
[224.709892] [040/133] (795s left) gem_userptr_blits (unsync-unmap)
[224.779940] Subtest unsync-unmap: SKIP (0.000s)
[224.979970] [041/133] (795s left) gem_exec_balancer (bonded-true-hang)
[225.240841] Starting subtest: bonded-true-hang
[225.245729] Subtest bonded-true-hang: SKIP (0.000s)
[225.383646] [042/133] (795s left) gem_lmem_swapping (parallel-random-engines)
[225.430984] Subtest parallel-random-engines: SKIP (0.000s)
[225.467049] [043/133] (795s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[225.523830] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[225.528815] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[225.718707] [044/133] (794s left) syncobj_timeline (etime-single-wait-all-submitted)
[225.780036] Starting subtest: etime-single-wait-all-submitted
[225.882315] Subtest etime-single-wait-all-submitted: SUCCESS (0.103s)
[225.969437] [045/133] (794s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[226.068884] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[226.073202] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[226.206846] [046/133] (794s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[226.314137] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[226.318535] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[226.441654] [047/133] (794s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[226.563604] Starting subtest: short-busy-flip-before-cursor-toggle
[226.567835] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[226.709565] [048/133] (794s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[226.806854] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[233.769279] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (6.964s)
[233.927570] [049/133] (786s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[234.025663] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[234.027908] Starting dynamic subtest: pipe-A-eDP-1
[234.049588] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[234.060152] Starting dynamic subtest: pipe-B-eDP-1
[234.080563] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[234.088980] Starting dynamic subtest: pipe-C-eDP-1
[234.109735] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[234.117777] Starting dynamic subtest: pipe-D-eDP-1
[234.139412] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[234.143645] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[236.233480] [050/133] (784s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[236.335603] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[236.337807] Starting dynamic subtest: pipe-A-eDP-1
[236.358541] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[236.369925] Starting dynamic subtest: pipe-B-eDP-1
[236.389004] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[236.398421] Starting dynamic subtest: pipe-C-eDP-1
[236.418257] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[236.427546] Starting dynamic subtest: pipe-D-eDP-1
[236.445686] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[236.447701] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.008s)
[238.507200] [051/133] (782s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[238.626484] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[238.630739] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[238.754793] [052/133] (782s left) gem_partial_pwrite_pread (reads-snoop)
[238.838291] Subtest reads-snoop: SKIP (0.000s)
[238.912718] [053/133] (782s left) syncobj_timeline (invalid-query-illegal-handle)
[238.969807] Starting subtest: invalid-query-illegal-handle
[238.971738] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[239.086153] [054/133] (782s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[239.179105] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[246.323639] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.146s)
[246.504756] [055/133] (774s left) kms_lease (lease-unleased-connector)
[246.601791] Starting subtest: lease-unleased-connector
[246.604009] Starting dynamic subtest: pipe-A-eDP-1
[246.606218] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.007s)
[246.617206] Starting dynamic subtest: pipe-B-eDP-1
[246.619544] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.008s)
[246.633708] Starting dynamic subtest: pipe-C-eDP-1
[246.635599] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.006s)
[246.644647] Starting dynamic subtest: pipe-D-eDP-1
[246.646454] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.006s)
[246.648310] Subtest lease-unleased-connector: SUCCESS (0.032s)
[246.766911] [056/133] (774s left) syncobj_wait (invalid-single-wait-unsubmitted)
[246.824528] Starting subtest: invalid-single-wait-unsubmitted
[246.826879] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[246.953521] [057/133] (774s left) gem_mmap_gtt (ptrace)
[247.008003] Subtest ptrace: SKIP (0.000s)
[247.081752] [058/133] (774s left) syncobj_timeline (multi-wait-submitted-signaled)
[247.136051] Starting subtest: multi-wait-submitted-signaled
[247.138485] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[247.239310] [059/133] (774s left) gem_madvise (dontneed-before-exec)
[247.275669] Starting subtest: dontneed-before-exec
[247.300243] Subtest dontneed-before-exec: SKIP (0.023s)
[247.369801] [060/133] (773s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[247.462582] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[247.466054] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[247.595630] [061/133] (773s left) gem_ccs (ctrl-surf-copy-new-ctx)
[247.665518] Starting subtest: ctrl-surf-copy-new-ctx
[247.668942] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[247.787141] [062/133] (773s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite)
[247.888410] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite
[252.320260] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.433s)
[253.518110] [063/133] (767s left) syncobj_wait (single-wait-all-for-submit-signaled)
[253.584182] Starting subtest: single-wait-all-for-submit-signaled
[253.586705] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[253.711737] [064/133] (767s left) gem_ctx_isolation (nonpriv-switch)
[253.782419] Starting subtest: nonpriv-switch
[253.784852] Starting dynamic subtest: rcs0
[254.071228] Dynamic subtest rcs0: SUCCESS (0.288s)
[254.083635] Starting dynamic subtest: bcs0
[254.383577] Dynamic subtest bcs0: SUCCESS (0.311s)
[254.395653] Starting dynamic subtest: vcs0
[254.692888] Dynamic subtest vcs0: SUCCESS (0.309s)
[254.704366] Starting dynamic subtest: vcs1
[256.640807] Dynamic subtest vcs1: SUCCESS (1.947s)
[256.650413] Starting dynamic subtest: vecs0
[258.591327] Dynamic subtest vecs0: SUCCESS (1.949s)
[258.602630] Starting dynamic subtest: ccs0
[260.632516] Dynamic subtest ccs0: SUCCESS (2.040s)
[260.634910] Subtest nonpriv-switch: SUCCESS (6.850s)
[260.886718] [065/133] (760s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling)
[260.989146] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling
[260.993161] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.000s)
[261.070820] [066/133] (760s left) i915_drm_fdinfo (most-busy-check-all)
[261.116559] Starting subtest: most-busy-check-all
[261.118681] Starting dynamic subtest: rcs0
[261.123033] Dynamic subtest rcs0: SKIP (0.004s)
[261.133005] Starting dynamic subtest: bcs0
[261.137102] Dynamic subtest bcs0: SKIP (0.000s)
[261.145632] Starting dynamic subtest: vcs0
[261.149761] Dynamic subtest vcs0: SKIP (0.000s)
[261.157861] Starting dynamic subtest: vcs1
[261.161715] Dynamic subtest vcs1: SKIP (0.000s)
[261.169768] Starting dynamic subtest: vecs0
[261.173591] Dynamic subtest vecs0: SKIP (0.000s)
[261.181693] Starting dynamic subtest: ccs0
[261.185563] Dynamic subtest ccs0: SKIP (0.000s)
[261.189440] Subtest most-busy-check-all: SKIP (0.009s)
[261.226532] [067/133] (760s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[261.371830] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[261.374117] Starting dynamic subtest: pipe-A-eDP-1
[261.454993] Dynamic subtest pipe-A-eDP-1: SKIP (0.088s)
[261.467292] Starting dynamic subtest: pipe-B-eDP-1
[262.574334] Dynamic subtest pipe-B-eDP-1: SKIP (1.117s)
[262.578716] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.207s)
[263.792057] [068/133] (757s left) gem_create (create-ext-placement-each)
[263.973312] Starting subtest: create-ext-placement-each
[263.975799] Subtest create-ext-placement-each: SUCCESS (0.000s)
[264.094006] [069/133] (757s left) gen7_exec_parse (basic-allocation)
[264.166285] Subtest basic-allocation: SKIP (0.000s)
[264.245306] [070/133] (757s left) api_intel_bb (intel-bb-blit-none)
[264.311971] Starting subtest: intel-bb-blit-none
[268.554904] Subtest intel-bb-blit-none: SUCCESS (4.241s)
[268.682508] [071/133] (753s left) kms_chamelium_hpd (vga-hpd)
[269.067079] Subtest vga-hpd: SKIP (0.000s)
[269.875547] [072/133] (751s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[269.941939] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[269.999698] [073/133] (751s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[270.073519] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[270.146203] [074/133] (751s left) i915_pm_rps (reset)
[270.209418] Starting subtest: reset
[278.655246] Subtest reset: SUCCESS (8.442s)
[278.748970] [075/133] (743s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[278.890884] Starting subtest: cursor-vs-flip-legacy
[308.439002] Subtest cursor-vs-flip-legacy: SUCCESS (29.546s)
[309.337610] [076/133] (712s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[309.435686] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[309.437939] Starting dynamic subtest: pipe-A-eDP-1
[309.446646] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[309.462734] Starting dynamic subtest: pipe-B-eDP-1
[309.472107] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[309.483529] Starting dynamic subtest: pipe-C-eDP-1
[309.492235] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[309.501809] Starting dynamic subtest: pipe-D-eDP-1
[309.510721] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[309.515104] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.005s)
[311.600912] [077/133] (710s left) gem_exec_balancer (parallel-keep-in-fence)
[311.856824] Starting subtest: parallel-keep-in-fence
[313.063684] Subtest parallel-keep-in-fence: SUCCESS (1.207s)
[313.190863] [078/133] (708s left) prime_busy (after-wait)
[313.256639] Starting subtest: after-wait
[313.259095] Starting dynamic subtest: rcs0
[313.314349] Dynamic subtest rcs0: SUCCESS (0.056s)
[313.325116] Starting dynamic subtest: bcs0
[313.381982] Dynamic subtest bcs0: SUCCESS (0.067s)
[313.393049] Starting dynamic subtest: vcs0
[313.422937] Dynamic subtest vcs0: SUCCESS (0.040s)
[313.432712] Starting dynamic subtest: vcs1
[313.465293] Dynamic subtest vcs1: SUCCESS (0.042s)
[313.474764] Starting dynamic subtest: vecs0
[313.506148] Dynamic subtest vecs0: SUCCESS (0.040s)
[313.515865] Starting dynamic subtest: ccs0
[313.547432] Dynamic subtest ccs0: SUCCESS (0.040s)
[313.656411] Subtest after-wait: SUCCESS (0.400s)
[313.741337] [079/133] (708s left) gem_exec_schedule (u-independent)
[313.816635] Starting subtest: u-independent
[313.818787] Starting dynamic subtest: rcs0
[315.279136] Dynamic subtest rcs0: SUCCESS (1.471s)
[315.291333] Starting dynamic subtest: vcs0
[317.355150] Dynamic subtest vcs0: SUCCESS (2.073s)
[317.368078] Starting dynamic subtest: vcs1
[319.341682] Dynamic subtest vcs1: SUCCESS (1.985s)
[319.353372] Starting dynamic subtest: vecs0
[321.338434] Dynamic subtest vecs0: SUCCESS (1.993s)
[321.350565] Starting dynamic subtest: ccs0
[323.279491] Dynamic subtest ccs0: SUCCESS (1.938s)
[323.282150] Subtest u-independent: SUCCESS (9.472s)
[323.589935] [080/133] (698s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[323.694870] Starting subtest: fbc-pr-cursor-plane-update-sf
[323.699151] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.000s)
[323.781260] [081/133] (698s left) kms_sequence (queue-busy)
[323.880576] Starting subtest: queue-busy
[323.883068] Starting dynamic subtest: pipe-A-eDP-1
[325.809522] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.930s)
[325.820686] Starting dynamic subtest: pipe-B-eDP-1
[328.391966] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.579s)
[328.402929] Starting dynamic subtest: pipe-C-eDP-1
[331.008680] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.617s)
[331.019156] Starting dynamic subtest: pipe-D-eDP-1
[333.553005] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.541s)
[333.555293] Subtest queue-busy: SUCCESS (9.673s)
[334.354983] [082/133] (687s left) kms_chamelium_audio (hdmi-audio)
[334.756036] Subtest hdmi-audio: SKIP (0.000s)
[335.550697] [083/133] (686s left) gem_mmap_gtt (big-bo-tiledy)
[335.620660] Subtest big-bo-tiledY: SKIP (0.000s)
[335.685820] [084/133] (686s left) gem_mmap_wc (write-prefaulted)
[335.758509] Subtest write-prefaulted: SKIP (0.000s)
[335.826282] [085/133] (686s left) gem_exec_fence (syncobj-unused-fence)
[336.321734] Starting subtest: syncobj-unused-fence
[336.326901] Subtest syncobj-unused-fence: SUCCESS (0.005s)
[337.419885] [FACT gem_exec_fence (syncobj-unused-fence)] new: kernel.kmod_is_loaded.vgem: true
[337.423293] [086/133] (684s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[337.498939] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[337.602510] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.101s)
[337.690913] [087/133] (684s left) i915_hangman (engine-engine-hang)
[337.767054] Starting subtest: engine-engine-hang
[337.768922] Starting dynamic subtest: rcs0
[339.245395] Dynamic subtest rcs0: SUCCESS (1.477s)
[339.262002] Starting dynamic subtest: bcs0
[341.290885] Dynamic subtest bcs0: SUCCESS (2.043s)
[341.304051] Starting dynamic subtest: vcs0
[343.276885] Dynamic subtest vcs0: SUCCESS (1.983s)
[343.290169] Starting dynamic subtest: vcs1
[345.260679] Dynamic subtest vcs1: SUCCESS (1.981s)
[345.274250] Starting dynamic subtest: vecs0
[347.244009] Dynamic subtest vecs0: SUCCESS (1.980s)
[347.257353] Starting dynamic subtest: ccs0
[349.292881] Dynamic subtest ccs0: SUCCESS (2.046s)
[349.296046] Subtest engine-engine-hang: SUCCESS (11.525s)
[349.400176] [088/133] (673s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[349.515235] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[349.517514] Starting dynamic subtest: pipe-A-eDP-1
[349.573843] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[349.584039] Starting dynamic subtest: pipe-B-eDP-1
[349.638382] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[349.648507] Starting dynamic subtest: pipe-C-eDP-1
[349.701559] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[349.711504] Starting dynamic subtest: pipe-D-eDP-1
[349.765696] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[349.769955] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.008s)
[351.762131] [089/133] (670s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[351.854076] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[351.858532] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[351.974066] [090/133] (670s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[352.072604] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[352.076539] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (0.000s)
[352.157742] [091/133] (670s left) prime_mmap (test_map_unmap)
[352.216953] Starting subtest: test_map_unmap
[352.218942] Starting dynamic subtest: test_map_unmap-smem
[352.243742] Dynamic subtest test_map_unmap-smem: SUCCESS (0.029s)
[352.246142] Subtest test_map_unmap: SUCCESS (0.030s)
[352.355182] [092/133] (670s left) prime_self_import (basic-with_two_bos)
[352.399086] Starting subtest: basic-with_two_bos
[352.422848] Subtest basic-with_two_bos: SUCCESS (0.025s)
[352.549911] [093/133] (670s left) sysfs_preempt_timeout (invalid)
[352.614911] Starting subtest: invalid
[352.617313] Starting dynamic subtest: vecs0
[352.619712] Dynamic subtest vecs0: SUCCESS (0.000s)
[352.631910] Starting dynamic subtest: ccs0
[352.633802] Dynamic subtest ccs0: SUCCESS (0.000s)
[352.643129] Starting dynamic subtest: vcs0
[352.645114] Dynamic subtest vcs0: SUCCESS (0.000s)
[352.654352] Starting dynamic subtest: bcs0
[352.656233] Dynamic subtest bcs0: SUCCESS (0.000s)
[352.665554] Starting dynamic subtest: rcs0
[352.667563] Dynamic subtest rcs0: SUCCESS (0.000s)
[352.676930] Starting dynamic subtest: vcs1
[352.678709] Dynamic subtest vcs1: SUCCESS (0.000s)
[352.680569] Subtest invalid: SUCCESS (0.005s)
[352.740686] [094/133] (669s left) kms_big_fb (linear-64bpp-rotate-180)
[352.838984] Starting subtest: linear-64bpp-rotate-180
[354.463038] Subtest linear-64bpp-rotate-180: SUCCESS (1.623s)
[354.641455] [095/133] (668s left) gem_tiled_pread_pwrite
[354.780318] [096/133] (668s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[354.877425] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[360.081692] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.206s)
[361.249905] [097/133] (661s left) gem_fence_thrash (bo-write-verify-y)
[361.291618] Starting subtest: bo-write-verify-y
[361.318998] Subtest bo-write-verify-y: SKIP (0.027s)
[361.386990] [098/133] (661s left) kms_big_fb (4-tiled-addfb)
[361.494767] Starting subtest: 4-tiled-addfb
[361.496642] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[361.617406] [099/133] (661s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[361.715920] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[366.171525] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.459s)
[367.349630] [100/133] (655s left) gem_exec_fence (syncobj-timeline-chain-engines)
[367.897281] Starting subtest: syncobj-timeline-chain-engines
[368.014153] Subtest syncobj-timeline-chain-engines: SUCCESS (0.117s)
[369.369443] [101/133] (653s left) kms_pm_rpm (modeset-non-lpsp)
[371.508003] Starting subtest: modeset-non-lpsp
[372.656693] Subtest modeset-non-lpsp: SKIP (1.146s)
[373.054482] [102/133] (650s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[373.146936] Subtest addfb25-x-tiled-legacy: SKIP (0.000s)
[373.282857] [103/133] (649s left) kms_cursor_crc (cursor-size-hints)
[373.410078] Starting subtest: cursor-size-hints
[373.412347] Starting dynamic subtest: pipe-A-eDP-1
[374.033179] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.621s)
[374.044487] Starting dynamic subtest: pipe-D-eDP-1
[375.309952] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.275s)
[375.312311] Subtest cursor-size-hints: SUCCESS (1.899s)
[376.130408] [104/133] (647s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[376.260276] Starting subtest: yf-tiled-addfb-size-overflow
[376.264674] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[376.375316] [105/133] (646s left) gem_pxp (verify-pxp-stale-ctx-execution)
[376.485159] Starting subtest: verify-pxp-stale-ctx-execution
[377.544264] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.061s)
[377.699892] [106/133] (645s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[377.796851] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[378.808115] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.011s)
[378.962917] [107/133] (644s left) kms_psr (psr2-cursor-mmap-cpu)
[379.067820] Starting subtest: psr2-cursor-mmap-cpu
[379.069726] Starting dynamic subtest: eDP-1
[380.771924] Dynamic subtest eDP-1: SUCCESS (1.703s)
[380.774220] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.704s)
[381.575909] [108/133] (641s left) kms_psr (fbc-psr-sprite-plane-onoff)
[381.679623] Starting subtest: fbc-psr-sprite-plane-onoff
[381.681868] Starting dynamic subtest: eDP-1
[381.685926] Dynamic subtest eDP-1: SKIP (0.000s)
[381.690132] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[381.765021] [109/133] (641s left) kms_pm_rpm (fences-dpms)
[383.490181] Starting subtest: fences-dpms
[383.495249] Subtest fences-dpms: SKIP (0.000s)
[384.019614] [110/133] (639s left) prime_mmap_coherency (read)
[384.087804] Starting subtest: read
[389.461974] Subtest read: SUCCESS (5.375s)
[389.605344] [111/133] (633s left) gem_exec_fence (basic-busy-all)
[389.674053] Starting subtest: basic-busy-all
[389.795808] Subtest basic-busy-all: SUCCESS (0.122s)
[391.387067] [112/133] (632s left) prime_vgem (basic-fence-flip)
[391.470035] Starting subtest: basic-fence-flip
[391.759201] Subtest basic-fence-flip: SUCCESS (0.290s)
[391.914356] [113/133] (631s left) syncobj_wait (invalid-signal-bad-pad)
[391.973449] Starting subtest: invalid-signal-bad-pad
[391.975813] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[392.099547] [114/133] (631s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[392.201182] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[392.205124] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[392.329851] [115/133] (631s left) kms_big_fb (x-tiled-addfb)
[392.435596] Starting subtest: x-tiled-addfb
[392.437879] Subtest x-tiled-addfb: SUCCESS (0.000s)
[392.565905] [116/133] (631s left) gem_ctx_persistence (legacy-engines-hostile)
[392.645074] Starting subtest: legacy-engines-hostile
[392.647456] Starting dynamic subtest: render
[392.758361] Dynamic subtest render: SUCCESS (0.112s)
[392.769910] Starting dynamic subtest: bsd1
[392.882747] Dynamic subtest bsd1: SUCCESS (0.122s)
[392.895859] Starting dynamic subtest: bsd2
[393.020099] Dynamic subtest bsd2: SUCCESS (0.134s)
[393.033894] Starting dynamic subtest: blt
[393.146007] Dynamic subtest blt: SUCCESS (0.123s)
[393.159064] Starting dynamic subtest: vebox
[393.261076] Dynamic subtest vebox: SUCCESS (0.112s)
[393.263757] Subtest legacy-engines-hostile: SUCCESS (0.617s)
[393.364314] [117/133] (630s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[393.475186] Starting subtest: 4-tiled-16bpp-rotate-180
[394.865233] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.389s)
[395.047004] [118/133] (628s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[395.136150] Starting subtest: compare-crc-sanitycheck-nv12
[395.137998] Starting dynamic subtest: pipe-A-eDP-1
[395.813363] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.675s)
[395.824912] Starting dynamic subtest: pipe-B-eDP-1
[397.092450] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.279s)
[397.102214] Starting dynamic subtest: pipe-C-eDP-1
[398.320845] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.226s)
[398.331785] Starting dynamic subtest: pipe-D-eDP-1
[399.578022] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.256s)
[399.580302] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.441s)
[400.426126] [119/133] (623s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[400.529332] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[407.084609] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.557s)
[407.213641] [120/133] (616s left) kms_flip (wf_vblank-ts-check-interruptible)
[407.315784] Starting subtest: wf_vblank-ts-check-interruptible
[407.318126] Starting dynamic subtest: A-eDP1
[416.203704] Dynamic subtest A-eDP1: SUCCESS (8.878s)
[416.216951] Starting dynamic subtest: B-eDP1
[425.131129] Dynamic subtest B-eDP1: SUCCESS (8.932s)
[425.144254] Starting dynamic subtest: C-eDP1
[434.044484] Dynamic subtest C-eDP1: SUCCESS (8.912s)
[434.059486] Starting dynamic subtest: D-eDP1
[442.887783] Dynamic subtest D-eDP1: SUCCESS (8.840s)
[442.893249] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.574s)
[444.135664] [121/133] (579s left) gem_eio (create)
[444.377897] Starting subtest: create
[444.714887] Subtest create: SUCCESS (0.339s)
[444.840659] [122/133] (579s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[444.987831] Starting subtest: dp-mst-lic-type-0-hdcp14
[444.993172] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[445.129719] [123/133] (578s left) kms_vblank (query-idle)
[445.230906] Starting subtest: query-idle
[445.232722] Starting dynamic subtest: pipe-A-eDP-1
[446.981797] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.750s)
[446.997152] Starting dynamic subtest: pipe-D-eDP-1
[449.458174] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.473s)
[449.460077] Subtest query-idle: SUCCESS (4.227s)
[450.290402] [124/133] (573s left) kms_plane_scaling (invalid-num-scalers)
[450.382626] Starting subtest: invalid-num-scalers
[450.384888] Starting dynamic subtest: pipe-A-eDP-1-invalid-num-scalers
[450.399542] Dynamic subtest pipe-A-eDP-1-invalid-num-scalers: SUCCESS (0.018s)
[450.414805] Starting dynamic subtest: pipe-B-eDP-1-invalid-num-scalers
[450.432272] Dynamic subtest pipe-B-eDP-1-invalid-num-scalers: SUCCESS (0.032s)
[450.445216] Starting dynamic subtest: pipe-C-eDP-1-invalid-num-scalers
[450.464033] Dynamic subtest pipe-C-eDP-1-invalid-num-scalers: SUCCESS (0.029s)
[450.473800] Starting dynamic subtest: pipe-D-eDP-1-invalid-num-scalers
[450.494475] Dynamic subtest pipe-D-eDP-1-invalid-num-scalers: SUCCESS (0.029s)
[450.496767] Subtest invalid-num-scalers: SUCCESS (0.113s)
[450.621236] [125/133] (573s left) kms_cursor_crc (cursor-onscreen-512x170)
[450.948225] Starting subtest: cursor-onscreen-512x170
[450.952797] Subtest cursor-onscreen-512x170: SKIP (0.001s)
[451.118468] [126/133] (572s left) kms_rotation_crc (primary-rotation-270)
[451.207620] Starting subtest: primary-rotation-270
[451.212073] Subtest primary-rotation-270: SKIP (0.001s)
[451.293904] [127/133] (572s left) perf (invalid-oa-metric-set-id)
[451.379430] Starting subtest: invalid-oa-metric-set-id
[451.388338] Subtest invalid-oa-metric-set-id: SUCCESS (0.008s)
[451.445170] [128/133] (572s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[451.501838] Starting subtest: single-wait-all-for-submit-available-submitted
[451.503940] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[451.638615] [129/133] (572s left) kms_draw_crc (draw-method-render)
[451.730061] Starting subtest: draw-method-render
[451.731674] Starting dynamic subtest: xrgb8888-untiled
[451.994161] Dynamic subtest xrgb8888-untiled: SUCCESS (0.266s)
[452.004097] Starting dynamic subtest: rgb565-untiled
[452.216249] Dynamic subtest rgb565-untiled: SUCCESS (0.222s)
[452.226002] Starting dynamic subtest: xrgb2101010-untiled
[452.450099] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.232s)
[452.459808] Starting dynamic subtest: xbgr16161616f-untiled
[452.694139] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.244s)
[452.706296] Starting dynamic subtest: xrgb8888-xtiled
[452.816361] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.120s)
[452.827013] Starting dynamic subtest: rgb565-xtiled
[452.927437] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[452.937738] Starting dynamic subtest: xrgb2101010-xtiled
[453.038609] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[453.048553] Starting dynamic subtest: xbgr16161616f-xtiled
[453.160970] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.122s)
[453.171442] Starting dynamic subtest: xrgb8888-4tiled
[453.283674] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[453.293210] Starting dynamic subtest: rgb565-4tiled
[453.419066] Dynamic subtest rgb565-4tiled: SUCCESS (0.132s)
[453.429431] Starting dynamic subtest: xrgb2101010-4tiled
[453.538750] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.120s)
[453.549015] Starting dynamic subtest: xbgr16161616f-4tiled
[453.660981] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.121s)
[453.663046] Subtest draw-method-render: SUCCESS (1.934s)
[453.825841] [130/133] (570s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[453.954922] Starting subtest: basic-flip-before-cursor-legacy
[454.873454] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.916s)
[455.730896] [131/133] (568s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[455.836161] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[455.840630] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[455.958426] [132/133] (568s left) perf_pmu (idle-no-semaphores)
[455.997795] Starting subtest: idle-no-semaphores
[456.000101] Starting dynamic subtest: rcs0
[456.562973] Dynamic subtest rcs0: SUCCESS (0.565s)
[456.576025] Starting dynamic subtest: bcs0
[457.092105] Dynamic subtest bcs0: SUCCESS (0.526s)
[457.104762] Starting dynamic subtest: vcs0
[457.612075] Dynamic subtest vcs0: SUCCESS (0.517s)
[457.624599] Starting dynamic subtest: vcs1
[458.142322] Dynamic subtest vcs1: SUCCESS (0.527s)
[458.155125] Starting dynamic subtest: vecs0
[458.669694] Dynamic subtest vecs0: SUCCESS (0.524s)
[458.682954] Starting dynamic subtest: ccs0
[459.194803] Dynamic subtest ccs0: SUCCESS (0.522s)
[459.197695] Subtest idle-no-semaphores: SUCCESS (3.198s)
[459.308033] [133/133] (565s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[459.365682] Starting subtest: multi-wait-all-for-submit-signaled
[459.367998] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[459.477548] 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.