[61.662185] Initializing watchdogs
[61.662258]   /dev/watchdog0
[61.691068] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[61.702255] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[61.706778] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.709878] [001/157] (960s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[61.907150] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[61.908910] Subtest psr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[62.053266] [002/157] (960s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[62.243931] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[73.509940] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.031s)
[73.818922] [003/157] (948s left) gem_partial_pwrite_pread (write)
[73.906402] Subtest write: SKIP (0.000s)
[73.991113] [004/157] (948s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[74.194433] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[75.491140] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.274s)
[75.646247] [005/157] (946s left) kms_big_fb (4-tiled-16bpp-rotate-0)
[75.851465] Starting subtest: 4-tiled-16bpp-rotate-0
[77.675931] Subtest 4-tiled-16bpp-rotate-0: SUCCESS (1.813s)
[77.911642] [006/157] (944s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[78.091677] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[78.093395] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[78.405618] [007/157] (944s left) gem_exec_params (invalid-ring)
[78.484382] Starting subtest: invalid-ring
[78.485426] Subtest invalid-ring: SUCCESS (0.000s)
[78.629943] [008/157] (944s left) gem_mmap_offset (clear)
[78.699560] Starting subtest: clear
[78.700642] Starting dynamic subtest: lmem0
[104.182812] Dynamic subtest lmem0: SUCCESS (25.439s)
[104.209542] Starting dynamic subtest: smem0
[129.728191] Dynamic subtest smem0: SUCCESS (25.542s)
[129.729234] Subtest clear: SUCCESS (50.982s)
[130.086936] [009/157] (892s left) kms_rotation_crc (primary-y-tiled-reflect-x-180)
[130.345407] Starting subtest: primary-y-tiled-reflect-x-180
[130.438143] Subtest primary-y-tiled-reflect-x-180: SKIP (0.091s)
[130.577292] [010/157] (892s left) syncobj_timeline (multi-wait-for-submit-available-submitted)
[130.655388] Starting subtest: multi-wait-for-submit-available-submitted
[130.657406] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.001s)
[130.818392] [011/157] (892s left) gem_userptr_blits (map-fixed-invalidate-busy)
[130.912291] Starting subtest: map-fixed-invalidate-busy
[130.914163] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[131.053040] [012/157] (891s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[131.248401] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[131.987078] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.739s)
[132.212641] [013/157] (890s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[132.401595] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[132.403241] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[132.549108] [014/157] (890s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[132.734421] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs
[132.735300] Starting dynamic subtest: pipe-A-DP-3
[132.743798] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[132.753963] Starting dynamic subtest: pipe-B-DP-3
[132.762398] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[132.772387] Starting dynamic subtest: pipe-C-DP-3
[132.780575] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[132.790757] Starting dynamic subtest: pipe-D-DP-3
[132.801485] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[132.803395] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs: SKIP (0.004s)
[133.232132] [015/157] (889s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf)
[133.418656] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[133.473163] [016/157] (889s left) kms_lease (multimaster-lease)
[133.656800] Starting subtest: multimaster-lease
[133.673836] Subtest multimaster-lease: SUCCESS (0.017s)
[133.831321] [017/157] (889s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[134.016693] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[134.388825] Subtest hdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (0.367s)
[134.555541] [018/157] (888s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-mmap-gtt)
[134.739701] Starting subtest: hdr-rgb101010-draw-mmap-gtt
[134.741399] Subtest hdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[134.879192] [019/157] (888s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-blt)
[135.066816] Starting subtest: psrhdr-rgb565-draw-blt
[135.068484] Subtest psrhdr-rgb565-draw-blt: SKIP (0.000s)
[135.218728] [020/157] (888s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[135.406634] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc
[135.408310] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[135.548530] [021/157] (888s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[135.734284] Starting subtest: basic-flip-after-cursor-atomic
[136.948204] Subtest basic-flip-after-cursor-atomic: SUCCESS (1.214s)
[137.244666] [022/157] (886s left) kms_cursor_legacy (cursorb-vs-flipa-atomic)
[137.427054] Starting subtest: cursorB-vs-flipA-atomic
[137.429578] Subtest cursorB-vs-flipA-atomic: SKIP (0.002s)
[137.537638] [023/157] (886s left) i915_query (query-topology-coherent-slice-mask)
[137.608098] Starting subtest: query-topology-coherent-slice-mask
[137.609918] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[137.778016] [024/157] (886s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[137.963927] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[137.965660] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[138.097668] [025/157] (885s left) gem_pxp (verify-pxp-stale-ctx-execution)
[140.699546] Starting subtest: verify-pxp-stale-ctx-execution
[140.701474] Subtest verify-pxp-stale-ctx-execution: SKIP (0.000s)
[140.837072] [026/157] (883s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[141.022710] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[141.024414] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[141.233652] [027/157] (882s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[141.421578] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-mmap-wc
[141.423268] Subtest psrhdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[141.548488] [028/157] (882s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[141.731344] Starting subtest: addfb25-yf-tiled-legacy
[141.732231] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[141.923914] [029/157] (882s left) gem_lmem_swapping (verify-random-ccs)
[144.551558] Starting subtest: verify-random-ccs
[144.552409] Starting dynamic subtest: lmem0
[148.918925] Dynamic subtest lmem0: SUCCESS (4.367s)
[148.919919] Subtest verify-random-ccs: SUCCESS (4.368s)
[150.026867] [FACT gem_lmem_swapping (verify-random-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[150.029734] [030/157] (874s left) gem_exec_params (invalid-batch-start-offset)
[151.583823] Starting subtest: invalid-batch-start-offset
[151.587314] Subtest invalid-batch-start-offset: SUCCESS (0.004s)
[151.669065] [FACT gem_exec_params (invalid-batch-start-offset)] new: kernel.kmod_is_loaded.i915: true
[151.672261] [031/157] (872s left) gem_exec_params (rel-constants-invalid-ring)
[151.750168] Starting subtest: rel-constants-invalid-ring
[151.751129] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[151.884735] [032/157] (872s left) syncobj_timeline (etime-single-wait-all-submitted)
[151.957435] Starting subtest: etime-single-wait-all-submitted
[152.071964] Subtest etime-single-wait-all-submitted: SUCCESS (0.106s)
[152.193804] [033/157] (872s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[152.379650] Starting subtest: fbcpsr-2p-rte
[152.381351] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[152.518341] [034/157] (872s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-plflip-blt)
[152.705867] Starting subtest: psrhdr-2p-primscrn-shrfb-plflip-blt
[152.707584] Subtest psrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[152.851364] [035/157] (871s left) kms_big_fb (x-tiled-64bpp-rotate-0)
[153.050230] Starting subtest: x-tiled-64bpp-rotate-0
[155.178067] Subtest x-tiled-64bpp-rotate-0: SUCCESS (2.127s)
[155.383907] [036/157] (869s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[155.568425] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[155.570150] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[155.732254] [037/157] (869s left) kms_psr (psr-primary-mmap-cpu)
[155.914284] Subtest psr-primary-mmap-cpu: SKIP (0.000s)
[155.970375] [038/157] (868s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-move)
[156.159851] Starting subtest: hdr-1p-primscrn-spr-indfb-move
[156.883595] Subtest hdr-1p-primscrn-spr-indfb-move: SUCCESS (0.723s)
[157.102186] [039/157] (867s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs)
[157.283427] Starting subtest: missing-ccs-buffer-yf-tiled-ccs
[157.284433] Starting dynamic subtest: pipe-A-DP-3
[157.292862] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[157.303538] Starting dynamic subtest: pipe-B-DP-3
[157.311949] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[157.322780] Starting dynamic subtest: pipe-C-DP-3
[157.331007] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[157.341624] Starting dynamic subtest: pipe-D-DP-3
[157.351460] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[157.353442] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.005s)
[157.784565] [040/157] (867s left) kms_busy (extended-modeset-hang-oldfb)
[157.979162] Starting subtest: extended-modeset-hang-oldfb
[157.980024] Starting dynamic subtest: pipe-A
[166.093789] Dynamic subtest pipe-A: SUCCESS (8.114s)
[166.113643] Starting dynamic subtest: pipe-D
[174.217847] Dynamic subtest pipe-D: SUCCESS (8.120s)
[174.218752] Subtest extended-modeset-hang-oldfb: SUCCESS (16.242s)
[174.512690] [041/157] (850s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[174.699861] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[175.058264] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (0.358s)
[175.229733] [042/157] (849s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[175.412984] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[175.414587] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[175.618721] [043/157] (849s left) syncobj_wait (invalid-signal-zero-handles)
[175.691356] Starting subtest: invalid-signal-zero-handles
[175.692314] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[175.832393] [044/157] (849s left) kms_psr (fbc-pr-primary-blt)
[176.013479] Subtest fbc-pr-primary-blt: SKIP (0.000s)
[176.072999] [045/157] (849s left) kms_frontbuffer_tracking (hdr-2p-primscrn-shrfb-pgflip-blt)
[176.257908] Starting subtest: hdr-2p-primscrn-shrfb-pgflip-blt
[176.259723] Subtest hdr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[176.447956] [046/157] (849s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping)
[176.633641] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping
[176.809596] Starting dynamic subtest: pipe-A-plane-0
[177.193741] Dynamic subtest pipe-A-plane-0: SUCCESS (0.383s)
[177.237746] Starting dynamic subtest: pipe-A-plane-3
[177.477078] Dynamic subtest pipe-A-plane-3: SUCCESS (0.280s)
[177.770426] Starting dynamic subtest: pipe-B-plane-0
[178.109478] Dynamic subtest pipe-B-plane-0: SUCCESS (0.366s)
[178.156327] Starting dynamic subtest: pipe-B-plane-3
[178.410121] Dynamic subtest pipe-B-plane-3: SUCCESS (0.298s)
[178.532305] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping: SUCCESS (1.899s)
[178.885981] [047/157] (846s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[179.076048] Starting subtest: fbcpsr-1p-rte
[179.077875] Subtest fbcpsr-1p-rte: SKIP (0.000s)
[179.208992] [048/157] (846s left) kms_psr (pr-cursor-mmap-cpu)
[179.392314] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[179.448397] [049/157] (846s left) kms_flip (plain-flip-fb-recreate-interruptible)
[179.633227] Starting subtest: plain-flip-fb-recreate-interruptible
[179.638138] Starting dynamic subtest: A-DP3
[187.775454] Dynamic subtest A-DP3: SUCCESS (8.136s)
[187.816680] Starting dynamic subtest: B-DP3
[195.833146] Dynamic subtest B-DP3: SUCCESS (8.051s)
[195.876216] Starting dynamic subtest: C-DP3
[203.889279] Dynamic subtest C-DP3: SUCCESS (8.049s)
[203.935226] Starting dynamic subtest: D-DP3
[211.965141] Dynamic subtest D-DP3: SUCCESS (8.069s)
[211.974473] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (32.339s)
[212.350731] [050/157] (813s left) gem_eio (wait-immediate)
[212.638117] Starting subtest: wait-immediate
[213.202421] Subtest wait-immediate: SUCCESS (0.566s)
[213.422606] [051/157] (812s left) syncobj_timeline (32bits-limit)
[213.500769] Starting subtest: 32bits-limit
[213.501668] Subtest 32bits-limit: SUCCESS (0.001s)
[213.660981] [052/157] (812s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[213.848056] Starting subtest: fbc-shrfb-scaledprimary
[215.343665] Subtest fbc-shrfb-scaledprimary: SUCCESS (1.495s)
[215.556881] [053/157] (810s left) gem_exec_whisper (basic-contexts-forked-all)
[215.643295] Starting subtest: basic-contexts-forked-all
[238.184418] Subtest basic-contexts-forked-all: SUCCESS (22.540s)
[238.409313] [054/157] (787s left) perf_pmu (multi-client)
[238.475298] Starting subtest: multi-client
[238.476213] Starting dynamic subtest: rcs0
[239.563265] Dynamic subtest rcs0: SUCCESS (1.086s)
[239.580234] Starting dynamic subtest: bcs0
[240.617529] Dynamic subtest bcs0: SUCCESS (1.050s)
[240.634480] Starting dynamic subtest: vcs0
[241.673282] Dynamic subtest vcs0: SUCCESS (1.051s)
[241.689650] Starting dynamic subtest: vcs1
[242.728579] Dynamic subtest vcs1: SUCCESS (1.051s)
[242.744577] Starting dynamic subtest: vecs0
[243.782233] Dynamic subtest vecs0: SUCCESS (1.049s)
[243.798634] Starting dynamic subtest: vecs1
[244.836312] Dynamic subtest vecs1: SUCCESS (1.049s)
[244.852487] Starting dynamic subtest: ccs0
[245.892111] Dynamic subtest ccs0: SUCCESS (1.051s)
[245.894419] Subtest multi-client: SUCCESS (7.418s)
[246.082036] [055/157] (780s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-blt)
[246.266066] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-blt
[246.267808] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[246.399701] [056/157] (779s left) i915_query (query-topology-kernel-writes)
[246.471262] Starting subtest: query-topology-kernel-writes
[246.472160] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[246.634134] [057/157] (779s left) gem_exec_flush (basic-uc-pro-default)
[246.715838] Subtest basic-uc-pro-default: SKIP (0.000s)
[246.789619] [058/157] (779s left) gem_exec_reloc (basic-wc-read-active)
[246.873464] Subtest basic-wc-read-active: SKIP (0.000s)
[246.958647] [059/157] (779s left) sysfs_preempt_timeout (timeout)
[247.038518] Starting subtest: timeout
[247.039481] Starting dynamic subtest: vecs0
[248.735613] Dynamic subtest vecs0: SUCCESS (1.696s)
[248.750398] Starting dynamic subtest: ccs0
[250.473163] Dynamic subtest ccs0: SUCCESS (1.736s)
[250.486272] Starting dynamic subtest: vcs0
[252.157159] Dynamic subtest vcs0: SUCCESS (1.683s)
[252.170535] Starting dynamic subtest: bcs0
[253.845278] Dynamic subtest bcs0: SUCCESS (1.687s)
[253.858898] Starting dynamic subtest: vecs1
[255.569033] Dynamic subtest vecs1: SUCCESS (1.723s)
[255.582225] Starting dynamic subtest: rcs0
[257.264136] Dynamic subtest rcs0: SUCCESS (1.694s)
[257.277480] Starting dynamic subtest: vcs1
[258.951336] Dynamic subtest vcs1: SUCCESS (1.686s)
[258.952380] Subtest timeout: SUCCESS (11.912s)
[259.114431] [060/157] (767s left) gem_userptr_blits (huge-split)
[259.206347] Starting subtest: huge-split
[259.239931] Subtest huge-split: SUCCESS (0.033s)
[259.432658] [061/157] (767s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[259.620503] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[259.622927] Starting dynamic subtest: pipe-A-DP-3
[259.631320] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[259.644574] Starting dynamic subtest: pipe-B-DP-3
[259.652972] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[259.665921] Starting dynamic subtest: pipe-C-DP-3
[259.674026] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[259.686772] Starting dynamic subtest: pipe-D-DP-3
[259.697358] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[259.699392] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SKIP (0.006s)
[260.090247] [062/157] (766s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-onoff)
[260.280852] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-onoff
[260.282561] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[260.438349] [063/157] (766s left) gem_exec_balancer (sliced)
[260.523380] Starting subtest: sliced
[260.525582] Subtest sliced: SKIP (0.000s)
[260.939920] [064/157] (765s left) kms_dsc (dsc-with-bpc-formats-bigjoiner)
[261.119463] Starting subtest: dsc-with-bpc-formats-bigjoiner
[261.121199] Subtest dsc-with-bpc-formats-bigjoiner: SKIP (0.000s)
[261.238202] [065/157] (765s left) core_getstats (basic)
[261.311337] Starting subtest: basic
[261.312238] Subtest basic: SUCCESS (0.000s)
[261.465853] [066/157] (765s left) kms_big_fb (4-tiled-8bpp-rotate-270)
[261.661902] Starting subtest: 4-tiled-8bpp-rotate-270
[261.722946] Subtest 4-tiled-8bpp-rotate-270: SKIP (0.060s)
[261.944697] [067/157] (765s left) gem_fence_thrash (bo-write-verify-threaded-x)
[261.990974] Starting subtest: bo-write-verify-threaded-x
[262.026148] Subtest bo-write-verify-threaded-x: SKIP (0.035s)
[262.103464] [068/157] (764s left) gem_request_retire (retire-vma-not-inactive)
[262.182234] Starting subtest: retire-vma-not-inactive
[262.267488] Subtest retire-vma-not-inactive: SUCCESS (0.085s)
[262.460983] [069/157] (764s left) kms_cursor_crc (cursor-onscreen-256x256)
[262.878242] Starting subtest: cursor-onscreen-256x256
[262.883776] Starting dynamic subtest: pipe-A-DP-3
[265.614395] Dynamic subtest pipe-A-DP-3: SUCCESS (2.731s)
[265.653468] Starting dynamic subtest: pipe-D-DP-3
[268.391159] Dynamic subtest pipe-D-DP-3: SUCCESS (2.751s)
[268.392129] Subtest cursor-onscreen-256x256: SUCCESS (5.513s)
[268.776152] [070/157] (758s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-cpu)
[268.965949] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-cpu
[270.984767] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (2.020s)
[271.189272] [071/157] (756s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[271.372324] Starting subtest: plane-upscale-20x20-with-modifiers
[271.373083] Starting dynamic subtest: pipe-A
[271.951703] Dynamic subtest pipe-A: SUCCESS (0.580s)
[271.975725] Starting dynamic subtest: pipe-B
[272.649536] Dynamic subtest pipe-B: SUCCESS (0.696s)
[272.679622] Starting dynamic subtest: pipe-C
[273.311348] Dynamic subtest pipe-C: SUCCESS (0.660s)
[273.339109] Starting dynamic subtest: pipe-D
[273.974175] Dynamic subtest pipe-D: SUCCESS (0.661s)
[273.975349] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (2.604s)
[274.360842] [072/157] (752s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-shrfb-msflip-blt)
[274.546991] Starting subtest: hdr-2p-scndscrn-shrfb-msflip-blt
[274.548612] Subtest hdr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[274.675791] [073/157] (752s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-pwrite)
[274.862017] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-pwrite
[276.146555] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (1.286s)
[276.289590] [074/157] (751s left) kms_flip (2x-flip-vs-expired-vblank)
[276.473158] Starting subtest: 2x-flip-vs-expired-vblank
[276.488436] Subtest 2x-flip-vs-expired-vblank: SKIP (0.014s)
[276.664446] [075/157] (750s left) kms_addfb_basic (invalid-set-prop)
[276.738788] Starting subtest: invalid-set-prop
[276.739656] Subtest invalid-set-prop: SUCCESS (0.000s)
[276.964472] [076/157] (750s left) prime_vgem (basic-fence-flip)
[277.128989] Starting subtest: basic-fence-flip
[277.252131] Subtest basic-fence-flip: SKIP (0.121s)
[277.397410] [FACT prime_vgem (basic-fence-flip)] new: kernel.kmod_is_loaded.vgem: true
[277.399839] [077/157] (750s left) gem_spin_batch (legacy)
[277.488467] Starting subtest: legacy
[277.489397] Starting dynamic subtest: default
[280.750968] Dynamic subtest default: SUCCESS (3.258s)
[280.773094] Starting dynamic subtest: render
[283.992274] Dynamic subtest render: SUCCESS (3.236s)
[284.014567] Starting dynamic subtest: bsd
[287.232666] Dynamic subtest bsd: SUCCESS (3.236s)
[287.248864] Starting dynamic subtest: bsd1
[290.468070] Dynamic subtest bsd1: SUCCESS (3.232s)
[290.483583] Starting dynamic subtest: bsd2
[293.703280] Dynamic subtest bsd2: SUCCESS (3.231s)
[293.725774] Starting dynamic subtest: blt
[296.945118] Dynamic subtest blt: SUCCESS (3.235s)
[296.967836] Starting dynamic subtest: vebox
[300.187724] Dynamic subtest vebox: SUCCESS (3.237s)
[300.189709] Subtest legacy: SUCCESS (22.699s)
[300.427956] [078/157] (727s left) perf_pmu (init-busy)
[300.502309] Starting subtest: init-busy
[300.503212] Starting dynamic subtest: rcs0
[300.504080] Dynamic subtest rcs0: SUCCESS (0.002s)
[300.519205] Starting dynamic subtest: bcs0
[300.520143] Dynamic subtest bcs0: SUCCESS (0.000s)
[300.533377] Starting dynamic subtest: vcs0
[300.534263] Dynamic subtest vcs0: SUCCESS (0.001s)
[300.547368] Starting dynamic subtest: vcs1
[300.548558] Dynamic subtest vcs1: SUCCESS (0.001s)
[300.562537] Starting dynamic subtest: vecs0
[300.563791] Dynamic subtest vecs0: SUCCESS (0.001s)
[300.577524] Starting dynamic subtest: vecs1
[300.578620] Dynamic subtest vecs1: SUCCESS (0.001s)
[300.593604] Starting dynamic subtest: ccs0
[300.594829] Dynamic subtest ccs0: SUCCESS (0.001s)
[300.596066] Subtest init-busy: SUCCESS (0.013s)
[300.647431] [079/157] (727s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[300.837572] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.000s)
[300.903743] [080/157] (726s left) gem_userptr_blits (stress-mm)
[300.999615] Starting subtest: stress-mm
[306.369716] Subtest stress-mm: SUCCESS (5.369s)
[306.535765] [081/157] (721s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[306.729170] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[306.730909] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[306.863607] [082/157] (721s left) gem_pxp (fail-invalid-protected-context)
[309.475419] Starting subtest: fail-invalid-protected-context
[309.477512] Subtest fail-invalid-protected-context: SKIP (0.000s)
[309.582291] [083/157] (718s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[310.027419] Starting subtest: cursor-rapid-movement-256x85
[310.043270] Starting dynamic subtest: pipe-A-DP-3
[310.691375] Dynamic subtest pipe-A-DP-3: SUCCESS (0.649s)
[310.746034] Starting dynamic subtest: pipe-D-DP-3
[311.120526] Dynamic subtest pipe-D-DP-3: SUCCESS (0.387s)
[311.121567] Subtest cursor-rapid-movement-256x85: SUCCESS (1.093s)
[311.482992] [084/157] (716s left) gem_ctx_exec (basic-norecovery)
[311.565500] Starting subtest: basic-norecovery
[311.956957] Subtest basic-norecovery: SUCCESS (0.391s)
[312.098130] [085/157] (716s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[312.295793] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (0.000s)
[312.356453] [086/157] (715s left) gem_close_race (multigpu-basic-process)
[312.484197] Starting subtest: multigpu-basic-process
[312.486210] Subtest multigpu-basic-process: SKIP (0.000s)
[312.832429] [087/157] (715s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[313.024855] Starting subtest: fbc-dirtyfb-ioctl
[313.027139] Starting dynamic subtest: A-DP-3
[314.496831] Dynamic subtest A-DP-3: SUCCESS (1.470s)
[314.497685] Subtest fbc-dirtyfb-ioctl: SUCCESS (1.472s)
[314.784982] [088/157] (713s left) i915_suspend (fence-restore-untiled)
[314.884433] Starting subtest: fence-restore-untiled
[314.886164] Subtest fence-restore-untiled: SKIP (0.000s)
[315.038976] [089/157] (713s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[315.235244] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[315.236947] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[315.386180] [090/157] (713s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[315.579875] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[315.581571] Subtest psr-1p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[315.741467] [091/157] (712s left) perf (oa-formats)
[316.128971] Starting subtest: oa-formats
[316.129893] Starting dynamic subtest: 0-rcs0
[316.155185] Dynamic subtest 0-rcs0: SUCCESS (0.026s)
[316.156151] Subtest oa-formats: SUCCESS (0.027s)
[316.224360] [092/157] (712s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-16bpp-yftile-downscaling)
[316.420945] Starting subtest: flip-64bpp-yftile-to-16bpp-yftile-downscaling
[316.422815] Subtest flip-64bpp-yftile-to-16bpp-yftile-downscaling: SKIP (0.000s)
[316.536422] [093/157] (712s left) gen9_exec_parse (basic-rejected)
[316.621190] Subtest basic-rejected: SKIP (0.000s)
[316.705537] [094/157] (712s left) kms_getfb (getfb-addfb-different-handles)
[316.900302] Starting subtest: getfb-addfb-different-handles
[316.901213] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[317.098485] [095/157] (711s left) gem_exec_balancer (parallel-bb-first)
[317.491205] Starting subtest: parallel-bb-first
[319.281798] Subtest parallel-bb-first: SUCCESS (1.787s)
[319.411444] [096/157] (709s left) gem_caching (read-writes)
[319.506863] Starting subtest: read-writes
[328.793295] Subtest read-writes: SUCCESS (9.286s)
[328.942114] [097/157] (700s left) syncobj_wait (invalid-wait-illegal-handle)
[329.024575] Starting subtest: invalid-wait-illegal-handle
[329.025509] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[329.186682] [098/157] (699s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[329.856947] Starting subtest: syncobj-timeline-invalid-wait
[329.858623] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.007s)
[330.177518] [099/157] (699s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[330.385651] Starting subtest: yf-tiled-64bpp-rotate-90
[330.387386] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[330.536913] [100/157] (698s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[330.733337] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[330.735083] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[330.859881] [101/157] (698s left) api_intel_allocator (simple-allocator)
[330.940351] Starting subtest: simple-allocator
[330.941282] Starting dynamic subtest: basic
[330.970175] Dynamic subtest basic: SUCCESS (0.029s)
[330.985779] Starting dynamic subtest: parallel-one
[331.156726] Dynamic subtest parallel-one: SUCCESS (0.180s)
[331.171173] Starting dynamic subtest: print
[331.172200] Dynamic subtest print: SUCCESS (0.000s)
[331.186644] Starting dynamic subtest: reuse
[331.187626] Dynamic subtest reuse: SUCCESS (0.000s)
[331.201914] Starting dynamic subtest: reserve
[331.203206] Dynamic subtest reserve: SUCCESS (0.000s)
[331.216682] Starting dynamic subtest: fork-reopen-allocator
[331.217782] Dynamic subtest fork-reopen-allocator: SUCCESS (0.014s)
[331.218917] Subtest simple-allocator: SUCCESS (0.230s)
[331.316368] [102/157] (698s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling)
[331.509325] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling
[331.511121] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.000s)
[331.617773] [103/157] (697s left) kms_content_protection (uevent)
[331.875920] Starting subtest: uevent
[331.905154] Starting dynamic subtest: pipe-A-DP-3
[366.122533] Dynamic subtest pipe-A-DP-3: FAIL (34.163s)
[366.164233] Subtest uevent: FAIL (34.287s)
[366.447972] [104/157] (663s left) i915_drm_fdinfo (virtual-busy-idle)
[366.523673] Starting subtest: virtual-busy-idle
[366.525423] Subtest virtual-busy-idle: SKIP (0.000s)
[366.627390] [105/157] (663s left) kms_color (ctm-blue-to-red)
[366.817958] Starting subtest: ctm-blue-to-red
[366.818863] Starting dynamic subtest: pipe-A-DP-3
[367.516613] Dynamic subtest pipe-A-DP-3: SUCCESS (0.696s)
[367.548272] Starting dynamic subtest: pipe-B-DP-3
[368.131652] Dynamic subtest pipe-B-DP-3: SUCCESS (0.614s)
[368.148809] Starting dynamic subtest: pipe-C-DP-3
[368.734843] Dynamic subtest pipe-C-DP-3: SUCCESS (0.602s)
[368.755156] Starting dynamic subtest: pipe-D-DP-3
[369.348304] Dynamic subtest pipe-D-DP-3: SUCCESS (0.610s)
[369.349327] Subtest ctm-blue-to-red: SUCCESS (2.529s)
[369.683404] [106/157] (660s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-gtt)
[369.887048] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-gtt
[369.888871] Subtest fbcpsrhdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[370.045993] [107/157] (659s left) kms_plane (pixel-format-yf-tiled-modifier-source-clamping)
[370.238414] Starting subtest: pixel-format-yf-tiled-modifier-source-clamping
[370.240126] Subtest pixel-format-yf-tiled-modifier-source-clamping: SKIP (0.000s)
[370.419566] [108/157] (659s left) i915_pm_rpm (sysfs-read)
[372.028304] Starting subtest: sysfs-read
[372.441124] Subtest sysfs-read: SUCCESS (0.411s)
[372.844388] [109/157] (657s left) kms_atomic_transition (plane-all-modeset-transition)
[373.036883] Starting subtest: plane-all-modeset-transition
[373.039250] Starting dynamic subtest: pipe-A-DP-3
[403.544620] Dynamic subtest pipe-A-DP-3: SUCCESS (30.503s)
[403.646202] Starting dynamic subtest: pipe-B-DP-3
[430.576508] Dynamic subtest pipe-B-DP-3: SUCCESS (26.953s)
[430.628666] Subtest plane-all-modeset-transition: SUCCESS (57.588s)
[430.910369] [110/157] (599s left) kms_addfb_basic (small-bo)
[431.100900] Starting subtest: small-bo
[431.101727] Subtest small-bo: SUCCESS (0.000s)
[431.243909] [111/157] (598s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[431.435353] Starting subtest: plane-downscale-factor-0-5-with-rotation
[431.436134] Starting dynamic subtest: pipe-A
[431.797392] Dynamic subtest pipe-A: SUCCESS (0.363s)
[431.829057] Starting dynamic subtest: pipe-B
[432.310244] Dynamic subtest pipe-B: SUCCESS (0.511s)
[432.339544] Starting dynamic subtest: pipe-C
[432.806305] Dynamic subtest pipe-C: SUCCESS (0.495s)
[432.826875] Starting dynamic subtest: pipe-D
[433.285559] Dynamic subtest pipe-D: SUCCESS (0.478s)
[433.286765] Subtest plane-downscale-factor-0-5-with-rotation: SUCCESS (1.852s)
[433.625512] [112/157] (596s left) gem_exec_schedule (pi-distinct-iova)
[433.840474] Starting subtest: pi-distinct-iova
[433.841354] Starting dynamic subtest: rcs0
[434.104564] Dynamic subtest rcs0: SUCCESS (0.263s)
[434.120892] Starting dynamic subtest: bcs0
[434.285010] Dynamic subtest bcs0: SUCCESS (0.179s)
[434.299948] Starting dynamic subtest: vcs0
[434.478868] Dynamic subtest vcs0: SUCCESS (0.193s)
[434.493658] Starting dynamic subtest: vcs1
[434.670818] Dynamic subtest vcs1: SUCCESS (0.191s)
[434.685719] Starting dynamic subtest: vecs0
[434.863188] Dynamic subtest vecs0: SUCCESS (0.191s)
[434.878049] Starting dynamic subtest: vecs1
[435.056932] Dynamic subtest vecs1: SUCCESS (0.193s)
[435.071665] Starting dynamic subtest: ccs0
[435.245074] Dynamic subtest ccs0: SUCCESS (0.187s)
[435.245924] Subtest pi-distinct-iova: SUCCESS (1.405s)
[435.655141] [113/157] (594s left) kms_setmode (invalid-clone-single-crtc)
[435.735634] Starting subtest: invalid-clone-single-crtc
[435.738530] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[435.936655] [114/157] (594s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[436.131205] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[436.132797] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[436.339959] [115/157] (594s left) syncobj_wait (invalid-signal-illegal-handle)
[436.425494] Starting subtest: invalid-signal-illegal-handle
[436.426429] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[436.558558] [116/157] (594s left) gem_ctx_persistence (smoketest)
[436.660632] Starting subtest: smoketest
[475.348555] Subtest smoketest: SUCCESS (38.685s)
[475.506107] [117/157] (555s left) gem_exec_params (batch-first)
[475.591253] Starting subtest: batch-first
[475.621446] Subtest batch-first: SUCCESS (0.030s)
[475.799622] [118/157] (554s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-plflip-blt)
[475.994362] Starting subtest: fbc-2p-primscrn-indfb-plflip-blt
[475.996167] Subtest fbc-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[476.127586] [119/157] (554s left) kms_prop_blob (blob-prop-core)
[476.209814] Starting subtest: blob-prop-core
[476.210800] Subtest blob-prop-core: SUCCESS (0.000s)
[476.369371] [120/157] (554s left) fbdev (unaligned-read)
[476.436209] Starting subtest: unaligned-read
[476.448641] Subtest unaligned-read: SUCCESS (0.012s)
[476.537408] [121/157] (554s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[476.733800] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[476.735426] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[476.883322] [122/157] (554s left) kms_hdr (invalid-hdr)
[477.079654] Starting subtest: invalid-hdr
[477.082171] Starting dynamic subtest: pipe-A-DP-3-XRGB2101010
[477.083853] Dynamic subtest pipe-A-DP-3-XRGB2101010: SKIP (0.000s)
[477.098929] Starting dynamic subtest: pipe-A-DP-3-XRGB16161616F
[477.101051] Dynamic subtest pipe-A-DP-3-XRGB16161616F: SKIP (0.000s)
[477.102799] Subtest invalid-hdr: SKIP (0.004s)
[477.200319] [123/157] (553s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[477.290014] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[477.291954] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[477.540413] [124/157] (553s left) kms_vblank (query-forked)
[477.729736] Starting subtest: query-forked
[477.732157] Starting dynamic subtest: pipe-A-DP-3
[480.092400] Dynamic subtest pipe-A-DP-3: SUCCESS (2.361s)
[480.114045] Starting dynamic subtest: pipe-D-DP-3
[482.442071] Dynamic subtest pipe-D-DP-3: SUCCESS (2.345s)
[482.442945] Subtest query-forked: SUCCESS (4.712s)
[482.711284] [125/157] (548s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[482.791599] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[482.894972] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.103s)
[483.012899] [126/157] (548s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-pgflip-blt)
[483.207341] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-pgflip-blt
[483.209065] Subtest fbcpsrhdr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[483.350505] [127/157] (548s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[483.541871] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc
[483.543578] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[483.686114] [128/157] (547s left) kms_frontbuffer_tracking (fbchdr-shrfb-scaledprimary)
[483.883005] Starting subtest: fbchdr-shrfb-scaledprimary
[484.672726] Subtest fbchdr-shrfb-scaledprimary: SUCCESS (0.790s)
[484.945876] [129/157] (546s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-pwrite)
[485.139407] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-pwrite
[486.060695] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (0.922s)
[486.226018] [130/157] (545s left) gem_ctx_shared (q-in-order)
[486.319755] Starting subtest: Q-in-order
[486.320689] Starting dynamic subtest: rcs0
[486.587493] Dynamic subtest rcs0: SUCCESS (0.267s)
[486.602596] Starting dynamic subtest: bcs0
[486.771158] Dynamic subtest bcs0: SUCCESS (0.182s)
[486.786464] Starting dynamic subtest: vcs0
[486.969160] Dynamic subtest vcs0: SUCCESS (0.197s)
[486.984699] Starting dynamic subtest: vcs1
[487.162063] Dynamic subtest vcs1: SUCCESS (0.192s)
[487.177925] Starting dynamic subtest: vecs0
[487.355923] Dynamic subtest vecs0: SUCCESS (0.193s)
[487.371690] Starting dynamic subtest: vecs1
[487.547380] Dynamic subtest vecs1: SUCCESS (0.190s)
[487.563203] Starting dynamic subtest: ccs0
[487.735484] Dynamic subtest ccs0: SUCCESS (0.187s)
[487.736424] Subtest Q-in-order: SUCCESS (1.416s)
[488.095114] [131/157] (543s left) gem_readwrite (beyond-eob)
[488.174255] Subtest beyond-EOB: SKIP (0.000s)
[488.257421] [132/157] (543s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[488.452549] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[488.467928] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.014s)
[488.595950] [133/157] (543s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-plflip-blt)
[488.792802] Starting subtest: fbchdr-2p-primscrn-indfb-plflip-blt
[488.794456] Subtest fbchdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[488.930896] [134/157] (543s left) kms_plane_multiple (tiling-4)
[489.122341] Starting subtest: tiling-4
[489.123221] Starting dynamic subtest: pipe-A-DP-3
[490.515089] Dynamic subtest pipe-A-DP-3: SUCCESS (1.390s)
[490.555406] Starting dynamic subtest: pipe-B-DP-3
[491.698207] Dynamic subtest pipe-B-DP-3: SUCCESS (1.180s)
[491.715067] Starting dynamic subtest: pipe-C-DP-3
[492.828133] Dynamic subtest pipe-C-DP-3: SUCCESS (1.128s)
[492.844313] Starting dynamic subtest: pipe-D-DP-3
[493.974971] Dynamic subtest pipe-D-DP-3: SUCCESS (1.145s)
[493.975812] Subtest tiling-4: SUCCESS (4.854s)
[494.283719] [135/157] (537s left) gem_lmem_swapping (massive-random)
[496.884470] Starting subtest: massive-random
[496.885420] Starting dynamic subtest: lmem0
[497.213999] Dynamic subtest lmem0: SUCCESS (0.330s)
[497.214865] Subtest massive-random: SUCCESS (0.332s)
[500.809452] [FACT gem_lmem_swapping (massive-random)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[500.810670] [FACT gem_lmem_swapping (massive-random)] deleted: kernel.kmod_is_loaded.i915: true
[500.812921] [136/157] (531s left) i915_drm_fdinfo (memory-info-resident)
[502.374450] Starting subtest: memory-info-resident
[502.375259] Starting dynamic subtest: lmem0
[506.036909] Dynamic subtest lmem0: SUCCESS (3.661s)
[506.056775] Starting dynamic subtest: smem0
[509.829942] Dynamic subtest smem0: SUCCESS (3.789s)
[509.830951] Subtest memory-info-resident: SUCCESS (7.456s)
[509.884121] [FACT i915_drm_fdinfo (memory-info-resident)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[509.886770] [FACT i915_drm_fdinfo (memory-info-resident)] new: kernel.kmod_is_loaded.i915: true
[509.889957] [137/157] (522s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[510.091275] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[510.093014] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[510.231805] [138/157] (522s left) kms_selftest (drm_format_helper)
[510.285486] Starting subtest: drm_format_helper
[510.300930] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[510.364510] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.063s)
[510.390058] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[510.447556] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.079s)
[510.492997] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[510.518432] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.068s)
[510.534621] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[510.614444] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.092s)
[510.656441] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[510.688290] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.070s)
[510.704586] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[510.765943] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.074s)
[510.806170] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[510.838332] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.070s)
[510.855062] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[510.920382] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.077s)
[510.962123] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[510.997238] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.075s)
[511.013387] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[511.081190] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.080s)
[511.129472] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[511.159576] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.074s)
[511.175909] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[511.240592] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.078s)
[511.282875] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[511.313610] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.071s)
[511.334830] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[511.402546] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.081s)
[511.446035] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[511.479233] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.076s)
[511.495530] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[511.559461] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.076s)
[511.599457] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[511.636258] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.076s)
[511.637187] Subtest drm_format_helper: SUCCESS (1.351s)
[511.898963] [139/157] (520s left) kms_chamelium_frames (dp-crc-single)
[512.167967] Subtest dp-crc-single: SKIP (0.000s)
[512.349274] [140/157] (520s left) kms_chamelium_hpd (common-hpd-after-suspend)
[512.617170] Subtest common-hpd-after-suspend: SKIP (0.000s)
[512.795611] [141/157] (519s left) gem_exec_params (no-blt)
[512.883343] Starting subtest: no-blt
[512.884225] Subtest no-blt: SUCCESS (0.001s)
[513.080239] [142/157] (519s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[513.286151] Starting subtest: y-tiled-16bpp-rotate-0
[513.287843] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[513.498197] [143/157] (519s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[513.689421] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[513.690273] Starting dynamic subtest: pipe-A-DP-3
[513.699475] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[513.712147] Starting dynamic subtest: pipe-B-DP-3
[513.721261] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[513.733590] Starting dynamic subtest: pipe-C-DP-3
[513.742701] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[513.755138] Starting dynamic subtest: pipe-D-DP-3
[513.765526] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[513.767419] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SKIP (0.007s)
[514.225961] [144/157] (518s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[514.306592] Starting subtest: wait-all-for-submit-delayed-submit
[514.408950] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[514.558412] [145/157] (518s left) perf (blocking-parameterized)
[514.904864] Starting subtest: blocking-parameterized
[534.941205] Subtest blocking-parameterized: SUCCESS (20.036s)
[535.003427] [146/157] (497s left) kms_lease (cursor-implicit-plane)
[535.213777] Starting subtest: cursor-implicit-plane
[535.214767] Starting dynamic subtest: pipe-A-DP-3
[535.609507] Dynamic subtest pipe-A-DP-3: SUCCESS (0.395s)
[535.626059] Starting dynamic subtest: pipe-B-DP-3
[535.926409] Dynamic subtest pipe-B-DP-3: SUCCESS (0.315s)
[535.942500] Starting dynamic subtest: pipe-C-DP-3
[536.249921] Dynamic subtest pipe-C-DP-3: SUCCESS (0.321s)
[536.267874] Starting dynamic subtest: pipe-D-DP-3
[536.584150] Dynamic subtest pipe-D-DP-3: SUCCESS (0.332s)
[536.585157] Subtest cursor-implicit-plane: SUCCESS (1.373s)
[536.941582] [147/157] (496s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-pwrite)
[537.141136] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-pwrite
[537.142936] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[537.294520] [148/157] (495s left) kms_cursor_crc (cursor-sliding-32x10)
[537.615362] Starting subtest: cursor-sliding-32x10
[537.662182] Subtest cursor-sliding-32x10: SKIP (0.045s)
[538.088896] [149/157] (495s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-render)
[538.287134] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-render
[538.288834] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[538.488508] [150/157] (494s left) kms_pm_rpm (legacy-planes)
[540.104686] Starting subtest: legacy-planes
[540.108130] Starting dynamic subtest: plane-65
[542.835072] Dynamic subtest plane-65: SUCCESS (2.733s)
[542.875828] Starting dynamic subtest: plane-95
[545.640344] Dynamic subtest plane-95: SUCCESS (2.802s)
[545.691437] Starting dynamic subtest: plane-125
[548.367359] Dynamic subtest plane-125: SUCCESS (2.722s)
[548.418183] Starting dynamic subtest: plane-135
[551.234579] Dynamic subtest plane-135: SUCCESS (2.862s)
[551.241122] Subtest legacy-planes: SUCCESS (11.138s)
[551.699932] [151/157] (481s left) gem_exec_schedule (promotion)
[551.799818] Starting subtest: promotion
[551.800846] Starting dynamic subtest: rcs0
[552.111140] Dynamic subtest rcs0: SUCCESS (0.310s)
[552.124590] Starting dynamic subtest: bcs0
[552.337689] Dynamic subtest bcs0: SUCCESS (0.225s)
[552.351173] Starting dynamic subtest: vcs0
[552.570837] Dynamic subtest vcs0: SUCCESS (0.232s)
[552.584372] Starting dynamic subtest: vcs1
[552.805394] Dynamic subtest vcs1: SUCCESS (0.233s)
[552.818596] Starting dynamic subtest: vecs0
[553.045244] Dynamic subtest vecs0: SUCCESS (0.239s)
[553.058384] Starting dynamic subtest: vecs1
[553.257254] Dynamic subtest vecs1: SUCCESS (0.211s)
[553.272198] Starting dynamic subtest: ccs0
[553.486881] Dynamic subtest ccs0: SUCCESS (0.225s)
[553.490404] Subtest promotion: SUCCESS (1.684s)
[553.905969] [152/157] (479s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[554.098423] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[554.100164] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[554.260431] [153/157] (479s left) device_reset (unbind-reset-rebind)
[554.351690] Starting subtest: unbind-reset-rebind
[557.272430] Subtest unbind-reset-rebind: SUCCESS (2.920s)
[557.336108] [154/157] (476s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[557.533178] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc
[557.535045] Subtest psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[557.679665] [155/157] (475s left) kms_psr (pr-sprite-mmap-gtt)
[557.873590] Subtest pr-sprite-mmap-gtt: SKIP (0.000s)
[557.934768] [156/157] (475s left) gem_eio (in-flight-external)
[558.244303] Starting subtest: in-flight-external
[559.875846] Subtest in-flight-external: SUCCESS (1.633s)
[560.129914] [157/157] (473s left) syncobj_timeline (multi-wait-for-submit-unsubmitted-signaled)
[560.212491] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[560.213424] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[560.389613] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_cursor_legacy
results: parsing output: 2/ for test: gem_partial_pwrite_pread
results: parsing output: 3/ for test: kms_big_fb
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: kms_ccs
results: parsing output: 6/ for test: gem_exec_params
results: parsing output: 7/ for test: gem_mmap_offset
results: parsing output: 8/ for test: kms_rotation_crc
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: gem_userptr_blits
results: parsing output: 11/ for test: kms_big_fb
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_psr2_sf
results: parsing output: 15/ for test: kms_lease
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_cursor_legacy
results: parsing output: 21/ for test: kms_cursor_legacy
results: parsing output: 22/ for test: i915_query
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: gem_pxp
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_addfb_basic
results: parsing output: 28/ for test: gem_lmem_swapping
results: parsing output: 29/ for test: gem_exec_params
results: parsing output: 30/ for test: gem_exec_params
results: parsing output: 31/ for test: syncobj_timeline
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_big_fb
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: kms_busy
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: syncobj_wait
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_plane
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: gem_eio
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_exec_whisper
results: parsing output: 53/ for test: perf_pmu
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: i915_query
results: parsing output: 56/ for test: gem_exec_flush
results: parsing output: 57/ for test: gem_exec_reloc
results: parsing output: 58/ for test: sysfs_preempt_timeout
results: parsing output: 59/ for test: gem_userptr_blits
results: parsing output: 60/ for test: kms_ccs
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gem_exec_balancer
results: parsing output: 63/ for test: kms_dsc
results: parsing output: 64/ for test: core_getstats
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: gem_fence_thrash
results: parsing output: 67/ for test: gem_request_retire
results: parsing output: 68/ for test: kms_cursor_crc
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_plane_scaling
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: kms_addfb_basic
results: parsing output: 75/ for test: prime_vgem
results: parsing output: 76/ for test: gem_spin_batch
results: parsing output: 77/ for test: perf_pmu
results: parsing output: 78/ for test: kms_psr
results: parsing output: 79/ for test: gem_userptr_blits
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_pxp
results: parsing output: 82/ for test: kms_cursor_crc
results: parsing output: 83/ for test: gem_ctx_exec
results: parsing output: 84/ for test: kms_psr2_sf
results: parsing output: 85/ for test: gem_close_race
results: parsing output: 86/ for test: kms_dirtyfb
results: parsing output: 87/ for test: i915_suspend
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: perf
results: parsing output: 91/ for test: kms_flip_scaled_crc
results: parsing output: 92/ for test: gen9_exec_parse
results: parsing output: 93/ for test: kms_getfb
results: parsing output: 94/ for test: gem_exec_balancer
results: parsing output: 95/ for test: gem_caching
results: parsing output: 96/ for test: syncobj_wait
results: parsing output: 97/ for test: gem_exec_fence
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: api_intel_allocator
results: parsing output: 101/ for test: kms_flip_scaled_crc
results: parsing output: 102/ for test: kms_content_protection
results: parsing output: 103/ for test: i915_drm_fdinfo
results: parsing output: 104/ for test: kms_color
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_plane
results: parsing output: 107/ for test: i915_pm_rpm
results: parsing output: 108/ for test: kms_atomic_transition
results: parsing output: 109/ for test: kms_addfb_basic
results: parsing output: 110/ for test: kms_plane_scaling
results: parsing output: 111/ for test: gem_exec_schedule
results: parsing output: 112/ for test: kms_setmode
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: syncobj_wait
results: parsing output: 115/ for test: gem_ctx_persistence
results: parsing output: 116/ for test: gem_exec_params
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_prop_blob
results: parsing output: 119/ for test: fbdev
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_hdr
results: parsing output: 122/ for test: gem_render_copy
results: parsing output: 123/ for test: kms_vblank
results: parsing output: 124/ for test: syncobj_timeline
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gem_ctx_shared
results: parsing output: 130/ for test: gem_readwrite
results: parsing output: 131/ for test: kms_flip
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_plane_multiple
results: parsing output: 134/ for test: gem_lmem_swapping
results: parsing output: 135/ for test: i915_drm_fdinfo
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_selftest
results: parsing output: 138/ for test: kms_chamelium_frames
results: parsing output: 139/ for test: kms_chamelium_hpd
results: parsing output: 140/ for test: gem_exec_params
results: parsing output: 141/ for test: kms_big_fb
results: parsing output: 142/ for test: kms_ccs
results: parsing output: 143/ for test: syncobj_timeline
results: parsing output: 144/ for test: perf
results: parsing output: 145/ for test: kms_lease
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: kms_cursor_crc
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_pm_rpm
results: parsing output: 150/ for test: gem_exec_schedule
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: device_reset
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_psr
results: parsing output: 155/ for test: gem_eio
results: parsing output: 156/ for test: syncobj_timeline
Done.