[55.658737] Initializing watchdogs
[55.658784]   /dev/watchdog0
[55.673970] [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]
[55.678916] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[55.681916] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[55.685239] [001/156] (960s left) kms_ccs (bad-rotation-90-y-tiled-ccs)
[55.801511] Starting subtest: bad-rotation-90-y-tiled-ccs
[55.803974] Starting dynamic subtest: pipe-A-eDP-1
[55.827483] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[55.837813] Starting dynamic subtest: pipe-B-eDP-1
[55.859787] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[55.867634] Starting dynamic subtest: pipe-C-eDP-1
[55.889159] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[55.899209] Starting dynamic subtest: pipe-D-eDP-1
[55.921452] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[55.925949] Subtest bad-rotation-90-y-tiled-ccs: SKIP (0.003s)
[58.106209] [002/156] (958s left) kms_addfb_basic (invalid-get-prop)
[58.162191] Starting subtest: invalid-get-prop
[58.164586] Subtest invalid-get-prop: SUCCESS (0.000s)
[58.297377] [003/156] (957s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[58.397782] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[58.402250] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.002s)
[58.494031] [004/156] (957s left) i915_hangman (error-state-basic)
[58.552494] Starting subtest: error-state-basic
[58.618878] Subtest error-state-basic: SUCCESS (0.065s)
[58.699983] [005/156] (957s left) gem_exec_params (batch-first)
[58.754864] Starting subtest: batch-first
[58.761422] Subtest batch-first: SUCCESS (0.007s)
[58.891113] [006/156] (957s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[58.992069] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[58.996501] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[59.085212] [007/156] (957s left) kms_frontbuffer_tracking (psrhdr-indfb-scaledprimary)
[59.179254] Starting subtest: psrhdr-indfb-scaledprimary
[59.182748] Subtest psrhdr-indfb-scaledprimary: SKIP (0.000s)
[59.264603] [008/156] (957s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[59.317133] Subtest no-spare-fences-interruptible: SKIP (0.000s)
[59.386081] [009/156] (957s left) kms_psr (psr2-primary-mmap-gtt)
[59.491366] Starting subtest: psr2-primary-mmap-gtt
[59.493409] Starting dynamic subtest: eDP-1
[60.924693] Dynamic subtest eDP-1: SKIP (1.429s)
[60.929335] Subtest psr2-primary-mmap-gtt: SKIP (1.430s)
[61.062701] [010/156] (955s left) gem_ctx_persistence (legacy-engines-persistence)
[61.128647] Starting subtest: legacy-engines-persistence
[61.130588] Starting dynamic subtest: render
[61.944936] Dynamic subtest render: SUCCESS (0.818s)
[61.955210] Starting dynamic subtest: bsd1
[62.713755] Dynamic subtest bsd1: SUCCESS (0.767s)
[62.726667] Starting dynamic subtest: bsd2
[63.457924] Dynamic subtest bsd2: SUCCESS (0.742s)
[63.470040] Starting dynamic subtest: blt
[64.265833] Dynamic subtest blt: SUCCESS (0.807s)
[64.279426] Starting dynamic subtest: vebox
[65.003264] Dynamic subtest vebox: SUCCESS (0.735s)
[65.005957] Subtest legacy-engines-persistence: SUCCESS (3.877s)
[65.083583] [011/156] (951s left) kms_chamelium_hpd (vga-hpd)
[65.481816] Subtest vga-hpd: SKIP (0.000s)
[66.267284] [012/156] (950s left) kms_invalid_mode (zero-clock)
[66.357699] Starting subtest: zero-clock
[66.360095] Starting dynamic subtest: pipe-A-eDP-1
[66.640983] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[66.651918] Starting dynamic subtest: pipe-B-eDP-1
[66.654073] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[66.662182] Starting dynamic subtest: pipe-C-eDP-1
[66.664649] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[66.672989] Starting dynamic subtest: pipe-D-eDP-1
[66.675442] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[66.677887] Subtest zero-clock: SUCCESS (0.289s)
[67.517841] [013/156] (949s left) kms_pm_dc (dc5-retention-flops)
[68.616100] Starting subtest: dc5-retention-flops
[68.620399] Subtest dc5-retention-flops: SKIP (0.000s)
[68.697242] [014/156] (947s left) prime_vgem (basic-fence-flip)
[68.801974] Starting subtest: basic-fence-flip
[69.090900] Subtest basic-fence-flip: SUCCESS (0.292s)
[69.196307] [FACT prime_vgem (basic-fence-flip)] new: kernel.kmod_is_loaded.vgem: true
[69.199907] [015/156] (947s left) kms_frontbuffer_tracking (psrhdr-2p-pri-indfb-multidraw)
[69.301918] Starting subtest: psrhdr-2p-pri-indfb-multidraw
[69.306346] Subtest psrhdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[69.408625] [016/156] (947s left) gem_pxp (protected-raw-src-copy-not-readible)
[69.531191] Starting subtest: protected-raw-src-copy-not-readible
[69.589721] Subtest protected-raw-src-copy-not-readible: SUCCESS (0.060s)
[69.712543] [017/156] (947s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[69.831711] Starting subtest: modeset-atomic-cursor-hotspot
[69.836933] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[69.978190] [018/156] (946s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[70.085019] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[70.089293] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[70.196677] [019/156] (946s left) kms_psr (psr2-dpms)
[70.292755] Starting subtest: psr2-dpms
[70.295057] Starting dynamic subtest: eDP-1
[73.245985] Dynamic subtest eDP-1: SUCCESS (2.952s)
[73.248765] Subtest psr2-dpms: SUCCESS (2.952s)
[74.113379] [020/156] (942s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[74.219746] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[74.224357] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[75.336712] [021/156] (941s left) gem_exec_schedule (u-submit-golden-slice)
[75.410142] Starting subtest: u-submit-golden-slice
[75.412218] Starting dynamic subtest: rcs0
[75.444300] Dynamic subtest rcs0: SUCCESS (0.042s)
[75.456861] Starting dynamic subtest: bcs0
[75.474094] Dynamic subtest bcs0: SUCCESS (0.030s)
[75.487411] Starting dynamic subtest: vcs0
[75.506611] Dynamic subtest vcs0: SUCCESS (0.032s)
[75.515924] Starting dynamic subtest: vcs1
[75.538026] Dynamic subtest vcs1: SUCCESS (0.031s)
[75.547252] Starting dynamic subtest: vecs0
[75.569855] Dynamic subtest vecs0: SUCCESS (0.032s)
[75.578831] Starting dynamic subtest: ccs0
[75.600485] Dynamic subtest ccs0: SUCCESS (0.030s)
[75.602931] Subtest u-submit-golden-slice: SUCCESS (0.199s)
[77.342658] [022/156] (939s left) gem_userptr_blits (dmabuf-sync)
[77.408639] Starting subtest: dmabuf-sync
[77.413315] Subtest dmabuf-sync: SKIP (0.002s)
[77.547728] [023/156] (939s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-blt)
[77.645298] Starting subtest: fbchdr-rgb565-draw-blt
[77.649366] Subtest fbchdr-rgb565-draw-blt: SKIP (0.000s)
[77.746891] [024/156] (939s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[78.287854] Starting subtest: syncobj-timeline-invalid-flags
[78.291581] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[79.378797] [025/156] (937s left) kms_vblank (ts-continuation-idle-hang)
[79.474233] Starting subtest: ts-continuation-idle-hang
[79.476682] Starting dynamic subtest: pipe-A-eDP-1
[107.072391] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.594s)
[107.087448] Starting dynamic subtest: pipe-D-eDP-1
[122.051346] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.978s)
[122.053876] Subtest ts-continuation-idle-hang: SUCCESS (42.576s)
[122.919241] [026/156] (894s left) syncobj_wait (single-wait-signaled)
[122.976999] Starting subtest: single-wait-signaled
[122.979421] Subtest single-wait-signaled: SUCCESS (0.001s)
[123.079144] [027/156] (894s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[123.184955] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[123.211536] Starting dynamic subtest: pipe-A-plane-0
[123.622464] Dynamic subtest pipe-A-plane-0: SUCCESS (0.411s)
[123.632294] Starting dynamic subtest: pipe-A-plane-3
[123.944471] Dynamic subtest pipe-A-plane-3: SUCCESS (0.322s)
[125.119304] Starting dynamic subtest: pipe-B-plane-0
[125.511248] Dynamic subtest pipe-B-plane-0: SUCCESS (0.400s)
[125.521266] Starting dynamic subtest: pipe-B-plane-3
[125.844871] Dynamic subtest pipe-B-plane-3: SUCCESS (0.333s)
[126.152062] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (2.968s)
[126.990742] [028/156] (890s left) gem_ctx_shared (q-independent)
[127.047677] Starting subtest: Q-independent
[127.050203] Starting dynamic subtest: rcs0
[127.120068] Dynamic subtest rcs0: SUCCESS (0.072s)
[127.132271] Starting dynamic subtest: vcs0
[127.161612] Dynamic subtest vcs0: SUCCESS (0.042s)
[127.171598] Starting dynamic subtest: vcs1
[127.205848] Dynamic subtest vcs1: SUCCESS (0.044s)
[127.218774] Starting dynamic subtest: vecs0
[127.246126] Dynamic subtest vecs0: SUCCESS (0.040s)
[127.259204] Starting dynamic subtest: ccs0
[127.292441] Dynamic subtest ccs0: SUCCESS (0.046s)
[127.295101] Subtest Q-independent: SUCCESS (0.245s)
[128.411603] [029/156] (888s left) perf_pmu (invalid-open)
[128.454653] Starting subtest: invalid-open
[128.456907] Subtest invalid-open: SUCCESS (0.000s)
[128.556042] [030/156] (888s left) kms_flip (2x-flip-vs-dpms-on-nop-interruptible)
[128.649972] Starting subtest: 2x-flip-vs-dpms-on-nop-interruptible
[128.662450] Subtest 2x-flip-vs-dpms-on-nop-interruptible: SKIP (0.011s)
[128.773693] [031/156] (888s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[128.877185] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[128.903431] Starting dynamic subtest: pipe-A-plane-0
[129.303293] Dynamic subtest pipe-A-plane-0: SUCCESS (0.400s)
[129.313157] Starting dynamic subtest: pipe-A-plane-3
[129.636074] Dynamic subtest pipe-A-plane-3: SUCCESS (0.333s)
[130.772870] Starting dynamic subtest: pipe-B-plane-0
[131.165284] Dynamic subtest pipe-B-plane-0: SUCCESS (0.400s)
[131.175118] Starting dynamic subtest: pipe-B-plane-3
[131.474819] Dynamic subtest pipe-B-plane-3: SUCCESS (0.311s)
[131.785821] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (2.911s)
[132.648641] [032/156] (884s left) perf_pmu (busy-no-semaphores)
[132.689515] Starting subtest: busy-no-semaphores
[132.692150] Starting dynamic subtest: rcs0
[133.234035] Dynamic subtest rcs0: SUCCESS (0.544s)
[133.246346] Starting dynamic subtest: bcs0
[133.782896] Dynamic subtest bcs0: SUCCESS (0.547s)
[133.795423] Starting dynamic subtest: vcs0
[134.332443] Dynamic subtest vcs0: SUCCESS (0.548s)
[134.344375] Starting dynamic subtest: vcs1
[135.807388] Dynamic subtest vcs1: SUCCESS (1.473s)
[135.819341] Starting dynamic subtest: vecs0
[137.789787] Dynamic subtest vecs0: SUCCESS (1.981s)
[137.803574] Starting dynamic subtest: ccs0
[139.838619] Dynamic subtest ccs0: SUCCESS (2.047s)
[139.841473] Subtest busy-no-semaphores: SUCCESS (7.150s)
[139.954495] [033/156] (877s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-blt)
[140.062077] Starting subtest: fbcpsrhdr-rgb101010-draw-blt
[140.066074] Subtest fbcpsrhdr-rgb101010-draw-blt: SKIP (0.000s)
[140.165702] [034/156] (877s left) kms_rotation_crc (primary-4-tiled-reflect-x-0)
[140.263733] Starting subtest: primary-4-tiled-reflect-x-0
[147.217117] Subtest primary-4-tiled-reflect-x-0: SUCCESS (6.955s)
[148.408775] [035/156] (869s left) gem_ctx_engines (invalid-engines)
[148.475314] Starting subtest: invalid-engines
[148.482641] Subtest invalid-engines: SUCCESS (0.007s)
[148.716852] [036/156] (868s left) kms_psr2_sf (pr-plane-move-sf-dmg-area)
[148.815042] Starting subtest: pr-plane-move-sf-dmg-area
[148.819220] Subtest pr-plane-move-sf-dmg-area: SKIP (0.000s)
[148.906814] [037/156] (868s left) kms_addfb_basic (unused-offsets)
[148.962218] Starting subtest: unused-offsets
[148.964613] Subtest unused-offsets: SUCCESS (0.000s)
[149.120350] [038/156] (868s left) gem_ctx_param (invalid-set-no-zeromap)
[149.186031] Starting subtest: invalid-set-no-zeromap
[149.188353] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[149.272640] [039/156] (868s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[149.372546] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[149.376621] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[149.496271] [040/156] (868s left) kms_rotation_crc (primary-rotation-90)
[149.586225] Starting subtest: primary-rotation-90
[149.590732] Subtest primary-rotation-90: SKIP (0.001s)
[149.657681] [041/156] (868s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[149.714799] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[149.718761] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[149.910263] [042/156] (867s left) kms_pm_rpm (pm-caching)
[151.459791] Starting subtest: pm-caching
[151.466458] Subtest pm-caching: SKIP (0.000s)
[152.258565] [043/156] (865s left) kms_legacy_colorkey (basic)
[152.345783] Starting subtest: basic
[152.347626] Starting dynamic subtest: pipe-A
[152.389297] Dynamic subtest pipe-A: SUCCESS (0.045s)
[152.402051] Starting dynamic subtest: pipe-B
[152.404327] Dynamic subtest pipe-B: SUCCESS (0.001s)
[152.413362] Starting dynamic subtest: pipe-C
[152.415179] Dynamic subtest pipe-C: SUCCESS (0.001s)
[152.424070] Starting dynamic subtest: pipe-D
[152.425955] Dynamic subtest pipe-D: SUCCESS (0.000s)
[152.427868] Subtest basic: SUCCESS (0.050s)
[152.466463] [044/156] (865s left) kms_psr (fbc-psr-no-drrs)
[152.555932] Starting subtest: fbc-psr-no-drrs
[152.558271] Starting dynamic subtest: eDP-1
[152.562241] Dynamic subtest eDP-1: SKIP (0.000s)
[152.566277] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[152.629251] [045/156] (865s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-blt)
[152.734583] Starting subtest: hdr-rgb101010-draw-blt
[152.738962] Subtest hdr-rgb101010-draw-blt: SKIP (0.000s)
[152.831176] [046/156] (865s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[152.923282] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[152.927260] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[153.016777] [047/156] (865s left) prime_mmap (test_forked_cpu_write)
[153.067213] Starting subtest: test_forked_cpu_write
[153.069657] Starting dynamic subtest: test_forked_cpu_write-smem
[153.074852] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.009s)
[153.077273] Subtest test_forked_cpu_write: SUCCESS (0.010s)
[153.174269] [048/156] (864s left) kms_content_protection (legacy-hdcp14)
[153.303702] Starting subtest: legacy-hdcp14
[153.307946] Subtest legacy-hdcp14: SKIP (0.000s)
[153.427182] [049/156] (864s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[153.523277] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[153.526835] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[153.618463] [050/156] (864s left) gem_spin_batch (user-each)
[153.676047] Starting subtest: user-each
[156.945993] Subtest user-each: SUCCESS (3.262s)
[158.376260] [051/156] (859s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[158.485630] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[158.490063] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[158.594307] [052/156] (859s left) kms_vblank (accuracy-idle)
[158.695846] Starting subtest: accuracy-idle
[158.698052] Starting dynamic subtest: pipe-A-eDP-1
[160.473219] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.778s)
[160.489299] Starting dynamic subtest: pipe-D-eDP-1
[162.906095] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.427s)
[162.908521] Subtest accuracy-idle: SUCCESS (4.210s)
[163.728570] [053/156] (854s left) gen7_exec_parse (basic-allocation)
[163.798045] Subtest basic-allocation: SKIP (0.000s)
[163.864849] [054/156] (854s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[163.969096] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-gtt
[163.973187] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[164.080596] [055/156] (854s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-upscaling)
[164.179678] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-upscaling
[164.183727] Subtest flip-64bpp-ytile-to-32bpp-ytile-upscaling: SKIP (0.000s)
[164.269665] [056/156] (854s left) kms_chamelium_audio (dp-audio-edid)
[164.659456] Subtest dp-audio-edid: SKIP (0.000s)
[165.471839] [057/156] (853s left) gem_eio (wait-immediate)
[165.661488] Starting subtest: wait-immediate
[165.990302] Subtest wait-immediate: SUCCESS (0.330s)
[166.134083] [058/156] (852s left) gem_lmem_swapping (parallel-random)
[166.177079] Subtest parallel-random: SKIP (0.000s)
[166.217424] [059/156] (852s left) gem_exec_reloc (basic-wc-cpu)
[166.275144] Subtest basic-wc-cpu: SKIP (0.000s)
[166.348742] [060/156] (852s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[166.443376] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[167.134694] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.692s)
[167.294954] [061/156] (851s left) kms_rotation_crc (primary-yf-tiled-reflect-x-270)
[167.392269] Starting subtest: primary-yf-tiled-reflect-x-270
[167.396756] Subtest primary-yf-tiled-reflect-x-270: SKIP (0.001s)
[167.479600] [062/156] (851s left) kms_flip (flip-vs-expired-vblank-interruptible)
[167.577295] Starting subtest: flip-vs-expired-vblank-interruptible
[167.579964] Starting dynamic subtest: A-eDP1
[176.306542] Dynamic subtest A-eDP1: SUCCESS (8.694s)
[176.319211] Starting dynamic subtest: B-eDP1
[184.944969] Dynamic subtest B-eDP1: SUCCESS (8.636s)
[184.956318] Starting dynamic subtest: C-eDP1
[193.576646] Dynamic subtest C-eDP1: SUCCESS (8.620s)
[193.590249] Starting dynamic subtest: D-eDP1
[202.203836] Dynamic subtest D-eDP1: SUCCESS (8.625s)
[202.206403] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.586s)
[203.357148] [063/156] (815s left) gem_partial_pwrite_pread (reads)
[203.415887] Subtest reads: SKIP (0.000s)
[203.487950] [064/156] (815s left) i915_drm_fdinfo (most-busy-check-all)
[203.528630] Starting subtest: most-busy-check-all
[203.530551] Starting dynamic subtest: rcs0
[203.535270] Dynamic subtest rcs0: SKIP (0.004s)
[203.546758] Starting dynamic subtest: bcs0
[203.551222] Dynamic subtest bcs0: SKIP (0.000s)
[203.560950] Starting dynamic subtest: vcs0
[203.565286] Dynamic subtest vcs0: SKIP (0.000s)
[203.574809] Starting dynamic subtest: vcs1
[203.579201] Dynamic subtest vcs1: SKIP (0.000s)
[203.591032] Starting dynamic subtest: vecs0
[203.595768] Dynamic subtest vecs0: SKIP (0.000s)
[203.605316] Starting dynamic subtest: ccs0
[203.609713] Dynamic subtest ccs0: SKIP (0.000s)
[203.614427] Subtest most-busy-check-all: SKIP (0.008s)
[203.653055] [065/156] (815s left) gem_mmap_wc (write-gtt-read-wc)
[203.739834] Subtest write-gtt-read-wc: SKIP (0.000s)
[203.810730] [066/156] (815s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[203.918917] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[203.923085] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[204.039693] [067/156] (814s left) gem_readwrite (read-bad-handle)
[204.093448] Subtest read-bad-handle: SKIP (0.000s)
[204.155920] [068/156] (814s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[204.253700] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[204.258082] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[204.350764] [069/156] (814s left) kms_plane (plane-position-hole-dpms)
[204.454399] Starting subtest: plane-position-hole-dpms
[204.999068] Starting dynamic subtest: pipe-A-plane-1
[207.433431] Dynamic subtest pipe-A-plane-1: SUCCESS (2.432s)
[207.444875] Starting dynamic subtest: pipe-A-plane-2
[209.808600] Dynamic subtest pipe-A-plane-2: SUCCESS (2.376s)
[209.821844] Starting dynamic subtest: pipe-A-plane-3
[212.178515] Dynamic subtest pipe-A-plane-3: SUCCESS (2.368s)
[212.189227] Starting dynamic subtest: pipe-A-plane-4
[214.547793] Dynamic subtest pipe-A-plane-4: SUCCESS (2.369s)
[214.558306] Starting dynamic subtest: pipe-A-plane-5
[214.572122] Dynamic subtest pipe-A-plane-5: SUCCESS (0.024s)
[215.733359] Starting dynamic subtest: pipe-B-plane-1
[218.187334] Dynamic subtest pipe-B-plane-1: SUCCESS (2.462s)
[218.197394] Starting dynamic subtest: pipe-B-plane-2
[220.637976] Dynamic subtest pipe-B-plane-2: SUCCESS (2.449s)
[220.649092] Starting dynamic subtest: pipe-B-plane-3
[223.104665] Dynamic subtest pipe-B-plane-3: SUCCESS (2.468s)
[223.116079] Starting dynamic subtest: pipe-B-plane-4
[225.573054] Dynamic subtest pipe-B-plane-4: SUCCESS (2.466s)
[225.582952] Starting dynamic subtest: pipe-B-plane-5
[225.598098] Dynamic subtest pipe-B-plane-5: SUCCESS (0.024s)
[225.600504] Subtest plane-position-hole-dpms: SUCCESS (21.143s)
[226.455226] [070/156] (792s left) i915_pm_rc6_residency (rc6-accuracy)
[226.515601] Starting subtest: rc6-accuracy
[226.517982] Starting dynamic subtest: gt0
[229.688051] Dynamic subtest gt0: SUCCESS (3.170s)
[229.700266] Starting dynamic subtest: gt1
[232.909052] Dynamic subtest gt1: SUCCESS (3.168s)
[232.911185] Subtest rc6-accuracy: SUCCESS (6.343s)
[232.955144] [071/156] (786s left) syncobj_timeline (etime-multi-wait-submitted)
[233.007317] Starting subtest: etime-multi-wait-submitted
[233.110519] Subtest etime-multi-wait-submitted: SUCCESS (0.103s)
[233.206803] [072/156] (785s left) kms_hdr (bpc-switch-dpms)
[233.309565] Starting subtest: bpc-switch-dpms
[233.311752] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[238.969223] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (5.662s)
[238.980870] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[244.525053] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (5.554s)
[244.527814] Subtest bpc-switch-dpms: SUCCESS (11.219s)
[244.641686] [073/156] (774s left) vgem_basic (busy-fence)
[244.689607] Starting subtest: busy-fence
[244.692048] Subtest busy-fence: SUCCESS (0.000s)
[244.760291] [074/156] (774s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-signaled)
[244.809461] Starting subtest: etime-multi-wait-all-available-unsubmitted-signaled
[244.919260] Subtest etime-multi-wait-all-available-unsubmitted-signaled: SUCCESS (0.107s)
[244.997360] [075/156] (774s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[245.089298] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[245.091118] Starting dynamic subtest: pipe-A-eDP-1
[245.110940] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.021s)
[245.121129] Starting dynamic subtest: pipe-B-eDP-1
[245.141551] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.020s)
[245.152708] Starting dynamic subtest: pipe-C-eDP-1
[245.172195] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.020s)
[245.183613] Starting dynamic subtest: pipe-D-eDP-1
[245.206665] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.021s)
[245.208999] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.088s)
[247.446900] [076/156] (771s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[247.549880] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[248.617355] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.068s)
[248.755677] [077/156] (770s left) kms_psr (fbc-pr-sprite-plane-move)
[248.843450] Starting subtest: fbc-pr-sprite-plane-move
[248.847153] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[248.925978] [078/156] (770s left) kms_draw_crc (draw-method-mmap-wc)
[249.015586] Starting subtest: draw-method-mmap-wc
[249.017272] Starting dynamic subtest: xrgb8888-untiled
[249.238505] Dynamic subtest xrgb8888-untiled: SUCCESS (0.222s)
[249.250915] Starting dynamic subtest: rgb565-untiled
[249.469852] Dynamic subtest rgb565-untiled: SUCCESS (0.232s)
[249.479542] Starting dynamic subtest: xrgb2101010-untiled
[249.692653] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.222s)
[249.703066] Starting dynamic subtest: xbgr16161616f-untiled
[249.925535] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.232s)
[249.935765] Starting dynamic subtest: xrgb8888-xtiled
[250.072370] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.143s)
[250.081944] Starting dynamic subtest: rgb565-xtiled
[250.203086] Dynamic subtest rgb565-xtiled: SUCCESS (0.132s)
[250.213195] Starting dynamic subtest: xrgb2101010-xtiled
[250.336579] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.132s)
[250.346512] Starting dynamic subtest: xbgr16161616f-xtiled
[250.458806] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.121s)
[250.468712] Starting dynamic subtest: xrgb8888-4tiled
[250.614377] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.155s)
[250.623769] Starting dynamic subtest: rgb565-4tiled
[250.747840] Dynamic subtest rgb565-4tiled: SUCCESS (0.132s)
[250.757725] Starting dynamic subtest: xrgb2101010-4tiled
[250.892148] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.143s)
[250.902068] Starting dynamic subtest: xbgr16161616f-4tiled
[251.069783] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.177s)
[251.071737] Subtest draw-method-mmap-wc: SUCCESS (2.057s)
[251.164446] [079/156] (768s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[251.269590] Starting subtest: plane-upscale-20x20-with-modifiers
[251.271615] Starting dynamic subtest: pipe-A
[251.605464] Dynamic subtest pipe-A: SUCCESS (0.338s)
[251.615836] Starting dynamic subtest: pipe-B
[253.036795] Dynamic subtest pipe-B: SUCCESS (1.430s)
[253.046617] Starting dynamic subtest: pipe-C
[254.466599] Dynamic subtest pipe-C: SUCCESS (1.429s)
[254.478235] Starting dynamic subtest: pipe-D
[255.913880] Dynamic subtest pipe-D: SUCCESS (1.447s)
[255.916241] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (4.647s)
[257.075631] [080/156] (762s left) gem_exec_params (secure-non-root)
[257.138331] Starting subtest: secure-non-root
[257.143070] Subtest secure-non-root: SKIP (0.000s)
[257.262023] [081/156] (762s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-pwrite)
[257.374861] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-pwrite
[257.379075] Subtest hdr-1p-offscreen-pri-indfb-draw-pwrite: SKIP (0.000s)
[257.471181] [082/156] (762s left) gem_userptr_blits (unsync-unmap-cycles)
[257.537768] Subtest unsync-unmap-cycles: SKIP (0.000s)
[257.664348] [083/156] (761s left) gem_exec_balancer (bonded-true-hang)
[257.923861] Starting subtest: bonded-true-hang
[257.929423] Subtest bonded-true-hang: SKIP (0.000s)
[258.048164] [084/156] (761s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-render)
[258.151654] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-render
[258.155961] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[258.253803] [085/156] (761s left) syncobj_wait (invalid-signal-zero-handles)
[258.306253] Starting subtest: invalid-signal-zero-handles
[258.308607] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[258.409828] [086/156] (761s left) kms_flip (plain-flip-fb-recreate-interruptible)
[258.509340] Starting subtest: plain-flip-fb-recreate-interruptible
[258.511647] Starting dynamic subtest: A-eDP1
[267.367290] Dynamic subtest A-eDP1: SUCCESS (8.856s)
[267.379226] Starting dynamic subtest: B-eDP1
[276.203132] Dynamic subtest B-eDP1: SUCCESS (8.834s)
[276.216927] Starting dynamic subtest: C-eDP1
[285.014828] Dynamic subtest C-eDP1: SUCCESS (8.810s)
[285.024599] Starting dynamic subtest: D-eDP1
[293.847696] Dynamic subtest D-eDP1: SUCCESS (8.831s)
[293.852664] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (35.343s)
[295.051802] [087/156] (724s left) gem_sync (basic-many-each)
[295.122459] Starting subtest: basic-many-each
[297.409585] Subtest basic-many-each: SUCCESS (2.286s)
[297.587332] [088/156] (722s left) gem_exec_params (larger-than-life-batch)
[297.643551] Starting subtest: larger-than-life-batch
[300.059262] Subtest larger-than-life-batch: SUCCESS (2.414s)
[301.361309] [089/156] (718s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[301.511776] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[301.516081] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[301.587931] [090/156] (718s left) kms_frontbuffer_tracking (hdr-1p-primscrn-spr-indfb-draw-render)
[301.696156] Starting subtest: hdr-1p-primscrn-spr-indfb-draw-render
[301.700588] Subtest hdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.001s)
[301.791941] [091/156] (718s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-blt)
[301.895725] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-blt
[301.899762] Subtest hdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[301.993884] [092/156] (718s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[302.050332] Starting subtest: single-wait-for-submit-available-submitted
[302.052784] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.001s)
[302.127551] [093/156] (717s left) kms_cursor_crc (cursor-offscreen-256x256)
[302.376578] Starting subtest: cursor-offscreen-256x256
[302.380378] Starting dynamic subtest: pipe-A-eDP-1
[304.332623] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.948s)
[304.360556] Starting dynamic subtest: pipe-D-eDP-1
[306.963085] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.609s)
[306.965638] Subtest cursor-offscreen-256x256: SUCCESS (4.587s)
[307.804946] [094/156] (712s left) kms_async_flips (async-flip-dpms)
[307.899762] Starting subtest: async-flip-dpms
[308.282856] Starting dynamic subtest: pipe-A-eDP-1
[312.219032] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.934s)
[312.595216] Starting dynamic subtest: pipe-B-eDP-1
[315.486410] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.901s)
[315.887351] Starting dynamic subtest: pipe-C-eDP-1
[318.670376] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.791s)
[319.046704] Starting dynamic subtest: pipe-D-eDP-1
[321.934912] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.895s)
[321.937372] Subtest async-flip-dpms: SUCCESS (14.035s)
[323.111746] [095/156] (697s left) gem_busy (extended)
[323.292105] Starting subtest: extended
[323.293986] Starting dynamic subtest: rcs0
[323.366532] Dynamic subtest rcs0: SUCCESS (0.077s)
[323.382867] Starting dynamic subtest: bcs0
[323.409716] Dynamic subtest bcs0: SUCCESS (0.041s)
[323.422218] Starting dynamic subtest: vcs0
[325.306922] Dynamic subtest vcs0: SUCCESS (1.893s)
[325.319043] Starting dynamic subtest: vcs1
[327.290728] Dynamic subtest vcs1: SUCCESS (1.981s)
[327.304912] Starting dynamic subtest: vecs0
[329.332414] Dynamic subtest vecs0: SUCCESS (1.981s)
[329.343076] Starting dynamic subtest: ccs0
[329.345049] Dynamic subtest ccs0: SUCCESS (0.045s)
[329.347062] Subtest extended: SUCCESS (6.033s)
[329.581842] [096/156] (690s left) syncobj_wait (single-wait-all-for-submit-signaled)
[329.637411] Starting subtest: single-wait-all-for-submit-signaled
[329.639855] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[329.743858] [097/156] (690s left) kms_pipe_stress (stress-xrgb8888-ytiled)
[329.839029] Starting subtest: stress-xrgb8888-ytiled
[329.843751] Subtest stress-xrgb8888-ytiled: SKIP (0.000s)
[329.915252] [098/156] (690s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-blt)
[330.013840] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-blt
[330.018093] Subtest psrhdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[330.109247] [099/156] (690s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render)
[330.209309] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render
[330.213624] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[330.304135] [100/156] (690s left) gem_mmap_gtt (cpuset-big-copy-xy)
[330.360221] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[330.425879] [101/156] (690s left) gem_linear_blits (normal)
[330.606526] Starting subtest: normal
[334.391859] Subtest normal: SUCCESS (3.786s)
[334.563527] [102/156] (685s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-mc-ccs)
[334.714976] Starting subtest: crc-primary-suspend-y-tiled-gen12-mc-ccs
[334.717338] Starting dynamic subtest: pipe-A-eDP-1
[334.726492] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[334.738093] Starting dynamic subtest: pipe-B-eDP-1
[334.747154] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[334.757928] Starting dynamic subtest: pipe-C-eDP-1
[334.766771] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[334.777188] Starting dynamic subtest: pipe-D-eDP-1
[334.786092] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[334.790468] Subtest crc-primary-suspend-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[336.989832] [103/156] (683s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[337.101294] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[337.105393] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[337.196222] [104/156] (683s left) kms_psr2_su (page_flip-xrgb8888)
[337.257882] Subtest page_flip-XRGB8888: SKIP (0.000s)
[337.320461] [105/156] (683s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[337.429759] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[337.432081] Starting dynamic subtest: pipe-A-eDP-1
[337.503349] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[337.514360] Starting dynamic subtest: pipe-B-eDP-1
[338.665746] Dynamic subtest pipe-B-eDP-1: SKIP (1.159s)
[338.670197] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.240s)
[339.863481] [106/156] (680s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[339.956758] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[339.960807] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[340.039541] [107/156] (680s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-render)
[340.131930] Starting subtest: fbcpsr-rgb101010-draw-render
[344.930434] Subtest fbcpsr-rgb101010-draw-render: SUCCESS (4.803s)
[345.056827] [108/156] (675s left) api_intel_bb (blit-noreloc-keep-cache)
[345.102856] Starting subtest: blit-noreloc-keep-cache
[345.111192] Subtest blit-noreloc-keep-cache: SUCCESS (0.010s)
[345.245457] [109/156] (675s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[345.370036] Starting subtest: 4-tiled-16bpp-rotate-90
[345.390749] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.021s)
[345.495547] [110/156] (675s left) syncobj_basic (bad-pad-fd-to-handle)
[345.550621] Starting subtest: bad-pad-fd-to-handle
[345.552952] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[345.653387] [111/156] (675s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-indfb-pgflip-blt)
[345.750059] Starting subtest: psrhdr-2p-scndscrn-indfb-pgflip-blt
[345.754229] Subtest psrhdr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[345.855097] [112/156] (675s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions-varying-size)
[345.980090] Starting subtest: cursorB-vs-flipB-atomic-transitions-varying-size
[345.983722] Subtest cursorB-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[346.123753] [113/156] (674s left) gem_mmap_wc (read)
[346.174213] Subtest read: SKIP (0.000s)
[346.241962] [114/156] (674s left) gem_mmap_wc (write-prefaulted)
[346.298275] Subtest write-prefaulted: SKIP (0.000s)
[346.381415] [115/156] (674s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[346.478185] Starting subtest: y-tiled-16bpp-rotate-90
[346.481872] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[346.581062] [116/156] (674s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[346.674085] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc
[346.677427] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[346.774562] [117/156] (674s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-indfb-pgflip-blt)
[346.877228] Starting subtest: fbchdr-2p-primscrn-indfb-pgflip-blt
[346.880737] Subtest fbchdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[346.984297] [118/156] (674s left) gem_userptr_blits (create-destroy-unsync)
[347.051085] Subtest create-destroy-unsync: SKIP (0.000s)
[347.166286] [119/156] (674s left) kms_atomic_transition (modeset-transition)
[347.258507] Starting subtest: modeset-transition
[347.260339] Starting dynamic subtest: 1x-outputs
[358.410789] Dynamic subtest 1x-outputs: SUCCESS (11.152s)
[358.413280] Subtest modeset-transition: SUCCESS (11.154s)
[359.272263] [120/156] (661s left) syncobj_timeline (multi-wait-signaled)
[359.332521] Starting subtest: multi-wait-signaled
[359.334848] Subtest multi-wait-signaled: SUCCESS (0.001s)
[359.440550] [121/156] (661s left) gem_flink_basic (basic)
[359.500277] Starting subtest: basic
[359.502613] Subtest basic: SUCCESS (0.000s)
[359.605442] [122/156] (661s left) kms_chamelium_edid (dp-edid-read)
[359.993240] Subtest dp-edid-read: SKIP (0.000s)
[360.779981] [123/156] (660s left) gem_exec_schedule (noreorder)
[360.964832] Starting subtest: noreorder
[360.967259] Starting dynamic subtest: rcs0
[361.027114] Dynamic subtest rcs0: SUCCESS (0.061s)
[361.041283] Starting dynamic subtest: vcs0
[362.273886] Dynamic subtest vcs0: SUCCESS (1.243s)
[362.287987] Starting dynamic subtest: vcs1
[364.257857] Dynamic subtest vcs1: SUCCESS (1.980s)
[364.274043] Starting dynamic subtest: vecs0
[366.260534] Dynamic subtest vecs0: SUCCESS (2.000s)
[366.272529] Starting dynamic subtest: ccs0
[366.341072] Dynamic subtest ccs0: SUCCESS (0.079s)
[366.343771] Subtest noreorder: SUCCESS (5.377s)
[366.528716] [124/156] (654s left) core_sysfs (read-all-entries)
[366.584826] Starting subtest: read-all-entries
[366.587501] Subtest read-all-entries: SUCCESS (0.003s)
[366.702288] [125/156] (654s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt)
[366.811620] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt
[366.815738] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[366.947943] [126/156] (654s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[367.054234] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[370.836371] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.788s)
[372.022638] [127/156] (649s left) syncobj_basic (bad-pad-handle-to-fd)
[372.081493] Starting subtest: bad-pad-handle-to-fd
[372.083519] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[372.188184] [128/156] (649s left) gem_exec_balancer (semaphore)
[372.248715] Starting subtest: semaphore
[374.240671] Subtest semaphore: SUCCESS (1.990s)
[374.559387] [129/156] (647s left) gem_exec_flush (basic-batch-kernel-default-wb)
[374.612575] Starting subtest: basic-batch-kernel-default-wb
[381.089595] Subtest basic-batch-kernel-default-wb: SUCCESS (6.476s)
[381.279846] [130/156] (640s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[381.375739] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[382.211552] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SUCCESS (0.837s)
[382.375311] [131/156] (639s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[382.433932] Starting subtest: invalid-single-wait-all-unsubmitted
[382.436646] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[382.531837] [132/156] (639s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[382.641799] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[382.645983] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[382.740078] [133/156] (639s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-move)
[382.835717] Starting subtest: psrhdr-2p-primscrn-spr-indfb-move
[382.840111] Subtest psrhdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[382.934222] [134/156] (638s left) gem_exec_reloc (basic-gtt-wc-active)
[383.004573] Subtest basic-gtt-wc-active: SKIP (0.000s)
[383.074895] [135/156] (638s left) kms_cursor_crc (cursor-rapid-movement-64x64)
[383.272547] Starting subtest: cursor-rapid-movement-64x64
[383.274499] Starting dynamic subtest: pipe-A-eDP-1
[383.829705] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.552s)
[383.858432] Starting dynamic subtest: pipe-D-eDP-1
[385.018916] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.167s)
[385.020911] Subtest cursor-rapid-movement-64x64: SUCCESS (1.746s)
[385.872917] [136/156] (636s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-pwrite)
[385.972810] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-pwrite
[385.976967] Subtest fbchdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[386.073929] [137/156] (635s left) gen9_exec_parse (bb-secure)
[386.143728] Subtest bb-secure: SKIP (0.000s)
[386.227517] [138/156] (635s left) perf (blocking)
[386.374590] Starting subtest: blocking
[386.376913] Starting dynamic subtest: 0-rcs0
[396.451815] Dynamic subtest 0-rcs0: SUCCESS (10.021s)
[396.465290] Starting dynamic subtest: 1-vcs0
[406.419037] Dynamic subtest 1-vcs0: SUCCESS (10.019s)
[406.422041] Subtest blocking: SUCCESS (20.043s)
[406.471312] [139/156] (615s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[406.592258] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[406.596645] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[406.706259] [140/156] (615s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[406.801166] Starting subtest: plane-lut1d-post-ctm3x4
[406.969571] Starting dynamic subtest: pipe-A-plane-0
[407.147198] Dynamic subtest pipe-A-plane-0: SUCCESS (0.178s)
[407.158294] Starting dynamic subtest: pipe-A-plane-1
[407.314075] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[407.327236] Starting dynamic subtest: pipe-A-plane-2
[407.480057] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[408.677422] Starting dynamic subtest: pipe-B-plane-0
[408.834514] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[408.846127] Starting dynamic subtest: pipe-B-plane-1
[409.023299] Dynamic subtest pipe-B-plane-1: SUCCESS (0.188s)
[409.034521] Starting dynamic subtest: pipe-B-plane-2
[409.179335] Dynamic subtest pipe-B-plane-2: SUCCESS (0.155s)
[410.395996] Starting dynamic subtest: pipe-C-plane-0
[410.542044] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[410.553757] Starting dynamic subtest: pipe-C-plane-1
[410.697267] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[410.708721] Starting dynamic subtest: pipe-C-plane-2
[410.853052] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[412.046482] Starting dynamic subtest: pipe-D-plane-0
[412.192248] Dynamic subtest pipe-D-plane-0: SUCCESS (0.155s)
[412.203821] Starting dynamic subtest: pipe-D-plane-1
[412.337132] Dynamic subtest pipe-D-plane-1: SUCCESS (0.144s)
[412.348280] Starting dynamic subtest: pipe-D-plane-2
[412.481477] Dynamic subtest pipe-D-plane-2: SUCCESS (0.142s)
[412.768716] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.967s)
[413.601708] [141/156] (608s left) api_intel_bb (intel-bb-blit-y)
[413.653177] Starting subtest: intel-bb-blit-y
[417.668369] Subtest intel-bb-blit-y: SUCCESS (4.015s)
[417.790772] [142/156] (604s left) gem_exec_whisper (basic-fds-priority-all)
[417.851499] Starting subtest: basic-fds-priority-all
[441.437817] Subtest basic-fds-priority-all: SUCCESS (23.581s)
[441.621415] [143/156] (580s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[441.727261] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[465.124044] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (23.401s)
[465.264150] [144/156] (557s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[465.369398] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[472.028765] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.662s)
[472.172634] [145/156] (550s left) gem_render_copy_redux (normal)
[472.232728] Starting subtest: normal
[472.560592] Subtest normal: SUCCESS (0.328s)
[472.672880] [146/156] (549s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[472.767401] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[472.771446] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[472.866500] [147/156] (549s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[472.963695] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[472.967959] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[473.056762] [148/156] (549s left) i915_query (engine-info)
[473.106458] Starting subtest: engine-info
[473.109167] Subtest engine-info: SUCCESS (0.005s)
[473.230777] [149/156] (549s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-msflip-blt)
[473.319056] Starting subtest: fbchdr-1p-primscrn-indfb-msflip-blt
[473.322557] Subtest fbchdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[473.417027] [150/156] (549s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-render)
[473.528523] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-render
[473.531771] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[473.622213] [151/156] (549s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[473.726024] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[480.848089] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (7.126s)
[480.962767] [152/156] (541s left) kms_cursor_edge_walk (128x128-top-edge)
[481.055179] Starting subtest: 128x128-top-edge
[481.057846] Starting dynamic subtest: pipe-A-eDP-1
[484.499641] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.444s)
[484.511338] Starting dynamic subtest: pipe-D-eDP-1
[487.645730] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.144s)
[487.648100] Subtest 128x128-top-edge: SUCCESS (6.591s)
[488.455448] [153/156] (534s left) prime_busy (hang-wait)
[488.512750] Starting subtest: hang-wait
[488.515261] Starting dynamic subtest: rcs0
[514.783877] Dynamic subtest rcs0: SUCCESS (26.261s)
[514.796652] Starting dynamic subtest: bcs0
[521.874158] Dynamic subtest bcs0: SUCCESS (7.091s)
[521.884430] Starting dynamic subtest: vcs0
[528.913813] Dynamic subtest vcs0: SUCCESS (7.039s)
[528.924033] Starting dynamic subtest: vcs1
[535.882088] Dynamic subtest vcs1: SUCCESS (6.971s)
[535.892590] Starting dynamic subtest: vecs0
[542.927375] Dynamic subtest vecs0: SUCCESS (7.032s)
[542.938325] Starting dynamic subtest: ccs0
[556.760181] Dynamic subtest ccs0: SUCCESS (13.841s)
[556.762639] Subtest hang-wait: SUCCESS (68.248s)
[556.893890] [154/156] (465s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-pwrite)
[556.996133] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-pwrite
[557.000173] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[557.105689] [155/156] (465s left) gem_mmap_offset (partial-remap)
[557.165695] Starting subtest: partial-remap
[557.167616] Starting dynamic subtest: smem0
[557.176185] Dynamic subtest smem0: SUCCESS (0.006s)
[557.178310] Subtest partial-remap: SUCCESS (0.007s)
[557.286627] [156/156] (465s left) gem_ctx_bad_destroy (invalid-ctx)
[557.342020] Starting subtest: invalid-ctx
[557.344358] Subtest invalid-ctx: SUCCESS (0.000s)
[557.422671] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: kms_addfb_basic
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: i915_hangman
results: parsing output: 4/ for test: gem_exec_params
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: gem_fenced_exec_thrash
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: gem_ctx_persistence
results: parsing output: 10/ for test: kms_chamelium_hpd
results: parsing output: 11/ for test: kms_invalid_mode
results: parsing output: 12/ for test: kms_pm_dc
results: parsing output: 13/ for test: prime_vgem
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_pxp
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_psr
results: parsing output: 19/ for test: kms_ccs
results: parsing output: 20/ for test: gem_exec_schedule
results: parsing output: 21/ for test: gem_userptr_blits
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: gem_exec_fence
results: parsing output: 24/ for test: kms_vblank
results: parsing output: 25/ for test: syncobj_wait
results: parsing output: 26/ for test: kms_plane
results: parsing output: 27/ for test: gem_ctx_shared
results: parsing output: 28/ for test: perf_pmu
results: parsing output: 29/ for test: kms_flip
results: parsing output: 30/ for test: kms_plane
results: parsing output: 31/ for test: perf_pmu
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_rotation_crc
results: parsing output: 34/ for test: gem_ctx_engines
results: parsing output: 35/ for test: kms_psr2_sf
results: parsing output: 36/ for test: kms_addfb_basic
results: parsing output: 37/ for test: gem_ctx_param
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_rotation_crc
results: parsing output: 40/ for test: gem_render_copy
results: parsing output: 41/ for test: kms_pm_rpm
results: parsing output: 42/ for test: kms_legacy_colorkey
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: prime_mmap
results: parsing output: 47/ for test: kms_content_protection
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_spin_batch
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_vblank
results: parsing output: 52/ for test: gen7_exec_parse
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_flip_scaled_crc
results: parsing output: 55/ for test: kms_chamelium_audio
results: parsing output: 56/ for test: gem_eio
results: parsing output: 57/ for test: gem_lmem_swapping
results: parsing output: 58/ for test: gem_exec_reloc
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: kms_rotation_crc
results: parsing output: 61/ for test: kms_flip
results: parsing output: 62/ for test: gem_partial_pwrite_pread
results: parsing output: 63/ for test: i915_drm_fdinfo
results: parsing output: 64/ for test: gem_mmap_wc
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_readwrite
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_plane
results: parsing output: 69/ for test: i915_pm_rc6_residency
results: parsing output: 70/ for test: syncobj_timeline
results: parsing output: 71/ for test: kms_hdr
results: parsing output: 72/ for test: vgem_basic
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_ccs
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_psr
results: parsing output: 77/ for test: kms_draw_crc
results: parsing output: 78/ for test: kms_plane_scaling
results: parsing output: 79/ for test: gem_exec_params
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_userptr_blits
results: parsing output: 82/ for test: gem_exec_balancer
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: syncobj_wait
results: parsing output: 85/ for test: kms_flip
results: parsing output: 86/ for test: gem_sync
results: parsing output: 87/ for test: gem_exec_params
results: parsing output: 88/ for test: kms_flip_scaled_crc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: syncobj_timeline
results: parsing output: 92/ for test: kms_cursor_crc
results: parsing output: 93/ for test: kms_async_flips
results: parsing output: 94/ for test: gem_busy
results: parsing output: 95/ for test: syncobj_wait
results: parsing output: 96/ for test: kms_pipe_stress
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_mmap_gtt
results: parsing output: 100/ for test: gem_linear_blits
results: parsing output: 101/ for test: kms_ccs
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_psr2_su
results: parsing output: 104/ for test: kms_psr2_sf
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: api_intel_bb
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: syncobj_basic
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_cursor_legacy
results: parsing output: 112/ for test: gem_mmap_wc
results: parsing output: 113/ for test: gem_mmap_wc
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: gem_userptr_blits
results: parsing output: 118/ for test: kms_atomic_transition
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: gem_flink_basic
results: parsing output: 121/ for test: kms_chamelium_edid
results: parsing output: 122/ for test: gem_exec_schedule
results: parsing output: 123/ for test: core_sysfs
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: syncobj_basic
results: parsing output: 127/ for test: gem_exec_balancer
results: parsing output: 128/ for test: gem_exec_flush
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: syncobj_timeline
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: gem_exec_reloc
results: parsing output: 134/ for test: kms_cursor_crc
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: gen9_exec_parse
results: parsing output: 137/ for test: perf
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_color_pipeline
results: parsing output: 140/ for test: api_intel_bb
results: parsing output: 141/ for test: gem_exec_whisper
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: gem_render_copy_redux
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: i915_query
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: kms_cursor_edge_walk
results: parsing output: 152/ for test: prime_busy
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: gem_mmap_offset
results: parsing output: 155/ for test: gem_ctx_bad_destroy
Done.