[58.748462] Initializing watchdogs
[58.748508]   /dev/watchdog0
[58.762755] [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]
[58.767140] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.769396] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.772862] [001/156] (960s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-upscaling)
[58.904061] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-upscaling
[58.908675] Subtest flip-64bpp-yftile-to-32bpp-yftile-upscaling: SKIP (0.000s)
[58.970990] [002/156] (960s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[59.081069] Starting subtest: cursorB-vs-flipA-varying-size
[59.085685] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[59.222618] [003/156] (960s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[59.314623] Starting subtest: read-crc-frame-sequence
[59.316510] Starting dynamic subtest: pipe-A-eDP-1
[60.035730] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.719s)
[60.045929] Starting dynamic subtest: pipe-B-eDP-1
[61.431282] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.395s)
[61.442267] Starting dynamic subtest: pipe-C-eDP-1
[62.823538] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.392s)
[62.832862] Starting dynamic subtest: pipe-D-eDP-1
[64.151701] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.326s)
[64.154079] Subtest read-crc-frame-sequence: SUCCESS (4.837s)
[64.988496] [004/156] (954s left) kms_frontbuffer_tracking (psr-2p-rte)
[65.084660] Starting subtest: psr-2p-rte
[65.088589] Subtest psr-2p-rte: SKIP (0.000s)
[65.190899] [005/156] (954s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[65.298241] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[65.302666] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[65.397235] [006/156] (954s left) core_hotunplug (unbind-rebind)
[65.477672] Starting subtest: unbind-rebind
[71.463672] Subtest unbind-rebind: SUCCESS (5.987s)
[71.500094] [007/156] (948s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[73.319038] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[73.323640] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[73.577683] [008/156] (945s left) kms_atomic_interruptible (legacy-pageflip)
[73.671145] Starting subtest: legacy-pageflip
[73.673582] Starting dynamic subtest: pipe-A-eDP-1
[81.096083] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.424s)
[81.098475] Subtest legacy-pageflip: SUCCESS (7.425s)
[81.938602] [009/156] (937s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[82.001040] Subtest writes-after-reads: SKIP (0.000s)
[82.073473] [010/156] (937s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[82.179832] Starting subtest: x-tiled-8bpp-rotate-0
[83.393653] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.214s)
[83.503831] [011/156] (936s left) i915_pm_rps (fence-order)
[83.572754] Starting subtest: fence-order
[86.547923] Subtest fence-order: SUCCESS (2.970s)
[86.676952] [012/156] (933s left) gem_softpin (noreloc)
[86.739065] Starting subtest: noreloc
[92.203817] Subtest noreloc: SUCCESS (5.414s)
[92.287954] [013/156] (927s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted-signal
[92.358312] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted-signaled
[92.469225] Subtest etime-multi-wait-all-available-unsubmitted-submitted-signaled: SUCCESS (0.110s)
[92.542067] [014/156] (927s left) kms_cursor_crc (cursor-rapid-movement-64x64)
[92.724757] Starting subtest: cursor-rapid-movement-64x64
[92.727730] Starting dynamic subtest: pipe-A-eDP-1
[93.272191] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.544s)
[93.307220] Starting dynamic subtest: pipe-D-eDP-1
[94.544801] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.247s)
[94.547302] Subtest cursor-rapid-movement-64x64: SUCCESS (1.820s)
[95.403020] [015/156] (924s left) i915_drm_fdinfo (isolation)
[95.447102] Starting subtest: isolation
[95.448906] Starting dynamic subtest: rcs0
[95.474586] Dynamic subtest rcs0: SKIP (0.027s)
[95.485081] Starting dynamic subtest: bcs0
[95.489031] Dynamic subtest bcs0: SKIP (0.001s)
[95.497097] Starting dynamic subtest: vcs0
[95.501173] Dynamic subtest vcs0: SKIP (0.001s)
[95.509255] Starting dynamic subtest: vcs1
[95.513219] Dynamic subtest vcs1: SKIP (0.001s)
[95.520850] Starting dynamic subtest: vecs0
[95.524989] Dynamic subtest vecs0: SKIP (0.000s)
[95.533071] Starting dynamic subtest: ccs0
[95.537058] Dynamic subtest ccs0: SKIP (0.000s)
[95.541048] Subtest isolation: SKIP (0.033s)
[95.574335] [016/156] (924s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[95.696379] Starting subtest: basic-busy-flip-before-cursor-atomic
[95.700921] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[95.850943] [017/156] (924s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs)
[95.944108] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs
[95.948726] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s)
[97.052345] [018/156] (922s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[97.151561] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-wc
[97.155525] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[97.252103] [019/156] (922s left) gem_softpin (evict-snoop)
[97.310956] Starting subtest: evict-snoop
[97.315500] Subtest evict-snoop: SKIP (0.000s)
[97.417017] [020/156] (922s left) kms_addfb_basic (invalid-set-prop-any)
[97.484121] Starting subtest: invalid-set-prop-any
[97.486502] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[97.625195] [021/156] (922s left) gem_mmap_gtt (fault-concurrent-x)
[97.680666] Subtest fault-concurrent-X: SKIP (0.000s)
[97.739885] [022/156] (922s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[97.847745] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[97.852121] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[97.945342] [023/156] (922s left) sw_sync (sync_expired_merge)
[97.978293] Starting subtest: sync_expired_merge
[99.051198] Subtest sync_expired_merge: SUCCESS (1.074s)
[99.108606] [024/156] (921s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-msflip-blt)
[99.198086] Starting subtest: fbchdr-1p-primscrn-indfb-msflip-blt
[99.202472] Subtest fbchdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[99.299363] [025/156] (920s left) vgem_basic (unload)
[99.330569] Starting subtest: unload
[128.406919] Subtest unload: SUCCESS (29.072s)
[128.499180] [FACT vgem_basic (unload)] new: kernel.kmod_is_loaded.vgem: true
[128.502731] [026/156] (891s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[128.617022] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[132.819686] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (4.210s)
[133.992373] [027/156] (886s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-blt)
[134.103303] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-blt
[134.107700] Subtest psrhdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[134.193100] [028/156] (886s left) kms_cursor_crc (cursor-sliding-512x170)
[134.471999] Starting subtest: cursor-sliding-512x170
[134.476232] Subtest cursor-sliding-512x170: SKIP (0.001s)
[134.618572] [029/156] (885s left) gem_pxp (verify-pxp-stale-buf-execution)
[134.746908] Starting subtest: verify-pxp-stale-buf-execution
[135.805888] Subtest verify-pxp-stale-buf-execution: SUCCESS (1.060s)
[135.950026] [030/156] (884s left) gem_tiled_blits (normal)
[136.010605] Subtest normal: SKIP (0.000s)
[136.086248] [031/156] (884s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[136.193861] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[143.518158] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.331s)
[143.630379] [032/156] (876s left) gem_ctx_exec (basic-norecovery)
[143.685261] Starting subtest: basic-norecovery
[144.023160] Subtest basic-norecovery: SUCCESS (0.336s)
[144.142250] [033/156] (876s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[144.185438] Starting subtest: single-wait-all-for-submit-unsubmitted
[144.291903] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[144.378706] [034/156] (876s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-move)
[144.478326] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-move
[144.482795] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[144.590093] [035/156] (876s left) kms_flip (dpms-off-confusion-interruptible)
[144.687715] Starting subtest: dpms-off-confusion-interruptible
[144.690071] Starting dynamic subtest: A-eDP1
[153.419178] Dynamic subtest A-eDP1: SUCCESS (8.697s)
[153.428432] Starting dynamic subtest: B-eDP1
[162.057434] Dynamic subtest B-eDP1: SUCCESS (8.634s)
[162.067905] Starting dynamic subtest: C-eDP1
[170.686198] Dynamic subtest C-eDP1: SUCCESS (8.618s)
[170.697566] Starting dynamic subtest: D-eDP1
[179.321291] Dynamic subtest D-eDP1: SUCCESS (8.630s)
[179.323449] Subtest dpms-off-confusion-interruptible: SUCCESS (34.587s)
[180.455197] [036/156] (840s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[180.559100] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[180.563304] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[180.647117] [037/156] (840s left) gem_create (create-ext-placement-all)
[180.805241] Starting subtest: create-ext-placement-all
[180.807448] Subtest create-ext-placement-all: SUCCESS (0.000s)
[180.903820] [038/156] (839s left) gem_mmap_gtt (big-bo-tiledx)
[180.949305] Subtest big-bo-tiledX: SKIP (0.000s)
[181.010406] [039/156] (839s left) kms_async_flips (async-flip-dpms)
[181.094291] Starting subtest: async-flip-dpms
[181.489947] Starting dynamic subtest: pipe-A-eDP-1
[185.372086] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.881s)
[185.754350] Starting dynamic subtest: pipe-B-eDP-1
[188.627233] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.879s)
[189.026471] Starting dynamic subtest: pipe-C-eDP-1
[191.894366] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.874s)
[192.270675] Starting dynamic subtest: pipe-D-eDP-1
[195.074576] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.810s)
[195.076892] Subtest async-flip-dpms: SUCCESS (13.977s)
[196.249095] [040/156] (824s left) syncobj_wait (wait-any-snapshot)
[196.303242] Starting subtest: wait-any-snapshot
[196.385236] Subtest wait-any-snapshot: SUCCESS (0.082s)
[196.460998] [041/156] (824s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-shrfb-draw-blt)
[196.555242] Starting subtest: hdr-1p-offscreen-pri-shrfb-draw-blt
[196.559497] Subtest hdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[196.678481] [042/156] (824s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-render)
[196.777614] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-render
[196.782079] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[196.878011] [043/156] (824s left) kms_flip (2x-nonexisting-fb)
[196.970319] Starting subtest: 2x-nonexisting-fb
[196.979017] Subtest 2x-nonexisting-fb: SKIP (0.010s)
[197.058915] [044/156] (823s left) kms_setmode (basic)
[197.114151] Starting subtest: basic
[197.434821] Starting dynamic subtest: pipe-A-eDP-1
[199.573835] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.135s)
[199.918820] Starting dynamic subtest: pipe-B-eDP-1
[202.023359] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.107s)
[202.334213] Subtest basic: SUCCESS (5.215s)
[203.201903] [045/156] (817s left) syncobj_timeline (wait-all-for-submit-delayed-submit)
[203.252872] Starting subtest: wait-all-for-submit-delayed-submit
[203.356293] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s)
[203.432418] [046/156] (817s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-blt)
[203.522821] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-blt
[203.527392] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[203.636188] [047/156] (817s left) gem_mmap_gtt (big-copy-odd)
[203.689211] Subtest big-copy-odd: SKIP (0.000s)
[203.750466] [048/156] (817s left) kms_frontbuffer_tracking (fbchdr-1p-shrfb-fliptrack-mmap-gtt)
[203.840327] Starting subtest: fbchdr-1p-shrfb-fliptrack-mmap-gtt
[203.844667] Subtest fbchdr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[203.922363] [049/156] (817s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs)
[204.023719] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs
[204.025956] Starting dynamic subtest: pipe-A-eDP-1
[204.052737] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[204.064291] Starting dynamic subtest: pipe-B-eDP-1
[204.088754] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[204.097805] Starting dynamic subtest: pipe-C-eDP-1
[204.121913] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[204.131089] Starting dynamic subtest: pipe-D-eDP-1
[204.155604] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[204.159773] Subtest random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[206.328174] [050/156] (814s left) prime_mmap (test_invalid_sync_flags)
[206.386494] Starting subtest: test_invalid_sync_flags
[206.388552] Starting dynamic subtest: test_invalid_sync_flags-smem
[206.390600] Dynamic subtest test_invalid_sync_flags-smem: SUCCESS (0.000s)
[206.392663] Subtest test_invalid_sync_flags: SUCCESS (0.001s)
[206.495498] [051/156] (814s left) kms_psr (fbc-psr-primary-mmap-gtt)
[206.593747] Starting subtest: fbc-psr-primary-mmap-gtt
[206.596093] Starting dynamic subtest: eDP-1
[206.600215] Dynamic subtest eDP-1: SKIP (0.000s)
[206.604219] Subtest fbc-psr-primary-mmap-gtt: SKIP (0.001s)
[206.690285] [052/156] (814s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[206.790593] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[206.795069] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[206.884768] [053/156] (814s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[206.983056] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite
[206.987240] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[207.093830] [054/156] (814s left) kms_plane (plane-position-hole)
[207.185143] Starting subtest: plane-position-hole
[207.690813] Starting dynamic subtest: pipe-A-plane-1
[208.983350] Dynamic subtest pipe-A-plane-1: SUCCESS (1.291s)
[208.992838] Starting dynamic subtest: pipe-A-plane-2
[210.203402] Dynamic subtest pipe-A-plane-2: SUCCESS (1.221s)
[210.213840] Starting dynamic subtest: pipe-A-plane-3
[211.468374] Dynamic subtest pipe-A-plane-3: SUCCESS (1.261s)
[211.481237] Starting dynamic subtest: pipe-A-plane-4
[212.648076] Dynamic subtest pipe-A-plane-4: SUCCESS (1.179s)
[212.659685] Starting dynamic subtest: pipe-A-plane-5
[212.672992] Dynamic subtest pipe-A-plane-5: SUCCESS (0.025s)
[213.880221] Starting dynamic subtest: pipe-B-plane-1
[215.150032] Dynamic subtest pipe-B-plane-1: SUCCESS (1.280s)
[215.159332] Starting dynamic subtest: pipe-B-plane-2
[216.382622] Dynamic subtest pipe-B-plane-2: SUCCESS (1.228s)
[216.394791] Starting dynamic subtest: pipe-B-plane-3
[217.671454] Dynamic subtest pipe-B-plane-3: SUCCESS (1.290s)
[217.683594] Starting dynamic subtest: pipe-B-plane-4
[218.965781] Dynamic subtest pipe-B-plane-4: SUCCESS (1.295s)
[218.976725] Starting dynamic subtest: pipe-B-plane-5
[218.995390] Dynamic subtest pipe-B-plane-5: SUCCESS (0.027s)
[218.997753] Subtest plane-position-hole: SUCCESS (11.810s)
[219.792740] [055/156] (801s left) perf (global-sseu-config)
[219.921059] Starting subtest: global-sseu-config
[219.925700] Subtest global-sseu-config: SKIP (0.000s)
[219.967212] [056/156] (801s left) i915_query (query-topology-coherent-slice-mask)
[220.023164] Starting subtest: query-topology-coherent-slice-mask
[220.027591] Subtest query-topology-coherent-slice-mask: SKIP (0.000s)
[220.136902] [057/156] (801s left) kms_hdr (bpc-switch-suspend)
[220.233070] Starting subtest: bpc-switch-suspend
[220.235355] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[241.521466] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (6.113s)
[241.535112] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[262.244738] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (5.728s)
[262.247168] Subtest bpc-switch-suspend: SUCCESS (11.845s)
[262.365651] [058/156] (759s left) gem_ctx_persistence (hang)
[262.426176] Starting subtest: hang
[262.431117] Subtest hang: SKIP (0.000s)
[262.551699] [059/156] (759s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[262.654367] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[262.658507] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[262.755678] [060/156] (758s left) kms_flip (plain-flip-ts-check)
[262.840425] Starting subtest: plain-flip-ts-check
[262.842720] Starting dynamic subtest: A-eDP1
[271.745503] Dynamic subtest A-eDP1: SUCCESS (8.860s)
[271.758330] Starting dynamic subtest: B-eDP1
[280.591811] Dynamic subtest B-eDP1: SUCCESS (8.838s)
[280.604340] Starting dynamic subtest: C-eDP1
[289.441604] Dynamic subtest C-eDP1: SUCCESS (8.833s)
[289.454480] Starting dynamic subtest: D-eDP1
[298.285380] Dynamic subtest D-eDP1: SUCCESS (8.840s)
[298.287314] Subtest plain-flip-ts-check: SUCCESS (35.383s)
[299.620541] [061/156] (722s left) kms_psr (pr-sprite-blt)
[299.711673] Starting subtest: pr-sprite-blt
[299.716116] Subtest pr-sprite-blt: SKIP (0.000s)
[299.785915] [062/156] (721s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[299.833453] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[299.837943] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[300.034049] [063/156] (721s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc)
[300.132470] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc
[300.134740] Starting dynamic subtest: pipe-A-eDP-1
[300.156795] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[300.169860] Starting dynamic subtest: pipe-B-eDP-1
[300.191162] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[300.200180] Starting dynamic subtest: pipe-C-eDP-1
[300.221863] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[300.230699] Starting dynamic subtest: pipe-D-eDP-1
[300.252643] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[300.257062] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[302.402865] [064/156] (719s left) kms_color (ctm-green-to-red)
[302.508645] Starting subtest: ctm-green-to-red
[302.510767] Starting dynamic subtest: pipe-A-eDP-1
[303.173063] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.667s)
[303.184091] Starting dynamic subtest: pipe-B-eDP-1
[304.591764] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.417s)
[304.601821] Starting dynamic subtest: pipe-C-eDP-1
[305.967234] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.373s)
[305.977279] Starting dynamic subtest: pipe-D-eDP-1
[307.284925] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.318s)
[307.287081] Subtest ctm-green-to-red: SUCCESS (4.778s)
[308.134726] [065/156] (713s left) gem_exec_whisper (basic-contexts)
[308.202021] Starting subtest: basic-contexts
[313.514215] Subtest basic-contexts: SUCCESS (5.310s)
[313.698423] [066/156] (708s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[313.794224] Starting subtest: fbc-psr2-primary-mmap-gtt
[313.796502] Starting dynamic subtest: eDP-1
[313.800802] Dynamic subtest eDP-1: SKIP (0.000s)
[313.805071] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[313.867880] [067/156] (708s left) gem_exec_schedule (timeslicing)
[313.941572] Starting subtest: timeslicing
[313.943528] Starting dynamic subtest: rcs0
[313.951504] Dynamic subtest rcs0: SUCCESS (0.021s)
[313.962382] Starting dynamic subtest: bcs0
[313.971655] Dynamic subtest bcs0: SUCCESS (0.020s)
[313.982357] Starting dynamic subtest: vcs0
[313.992345] Dynamic subtest vcs0: SUCCESS (0.020s)
[314.002368] Starting dynamic subtest: vcs1
[314.012577] Dynamic subtest vcs1: SUCCESS (0.020s)
[314.022916] Starting dynamic subtest: vecs0
[314.033628] Dynamic subtest vecs0: SUCCESS (0.020s)
[314.043913] Starting dynamic subtest: ccs0
[314.054671] Dynamic subtest ccs0: SUCCESS (0.020s)
[314.057005] Subtest timeslicing: SUCCESS (0.125s)
[315.525234] [068/156] (706s left) kms_chamelium_frames (vga-frame-dump)
[315.911093] Subtest vga-frame-dump: SKIP (0.000s)
[316.727169] [069/156] (705s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[316.831877] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[316.836175] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[316.947152] [070/156] (705s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[317.048991] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[317.053000] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[317.128822] [071/156] (705s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-blt)
[317.226153] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-blt
[317.230315] Subtest hdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[317.320513] [072/156] (704s left) syncobj_eventfd (binary-wait)
[317.367635] Starting subtest: binary-wait
[317.375644] Subtest binary-wait: SUCCESS (0.010s)
[317.470252] [073/156] (704s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[317.581099] Starting subtest: y-tiled-64bpp-rotate-0
[317.586694] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[317.705086] [074/156] (704s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[317.746121] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[317.748229] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[317.834060] [075/156] (704s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[317.925455] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[317.929070] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[318.033991] [076/156] (704s left) kms_invalid_mode (bad-vsync-start)
[318.120883] Starting subtest: bad-vsync-start
[318.122845] Starting dynamic subtest: pipe-A-eDP-1
[318.420835] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.301s)
[318.431847] Starting dynamic subtest: pipe-B-eDP-1
[318.433809] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[318.443091] Starting dynamic subtest: pipe-C-eDP-1
[318.445501] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[318.455006] Starting dynamic subtest: pipe-D-eDP-1
[318.457341] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[318.459608] Subtest bad-vsync-start: SUCCESS (0.309s)
[319.299076] [077/156] (703s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[319.396536] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[328.087977] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.693s)
[328.227700] [078/156] (694s left) i915_power (sanity)
[328.280683] Starting subtest: sanity
[328.285080] Subtest sanity: SKIP (0.000s)
[328.370652] [079/156] (694s left) i915_drm_fdinfo (virtual-busy-idle-all)
[328.411604] Starting subtest: virtual-busy-idle-all
[328.415861] Subtest virtual-busy-idle-all: SKIP (0.000s)
[328.482818] [080/156] (694s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[328.579939] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[328.584531] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[328.675183] [081/156] (693s left) kms_draw_crc (draw-method-pwrite)
[328.762076] Starting subtest: draw-method-pwrite
[328.764348] Starting dynamic subtest: xrgb8888-untiled
[329.305463] Dynamic subtest xrgb8888-untiled: SUCCESS (0.543s)
[329.317256] Starting dynamic subtest: rgb565-untiled
[329.748975] Dynamic subtest rgb565-untiled: SUCCESS (0.444s)
[329.759027] Starting dynamic subtest: xrgb2101010-untiled
[330.315627] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.566s)
[330.326641] Starting dynamic subtest: xbgr16161616f-untiled
[331.082094] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.766s)
[331.093298] Starting dynamic subtest: xrgb8888-xtiled
[332.871955] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.788s)
[332.882942] Starting dynamic subtest: rgb565-xtiled
[333.961086] Dynamic subtest rgb565-xtiled: SUCCESS (1.087s)
[333.971833] Starting dynamic subtest: xrgb2101010-xtiled
[335.750601] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.787s)
[335.760936] Starting dynamic subtest: xbgr16161616f-xtiled
[339.084940] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.332s)
[339.098138] Starting dynamic subtest: xrgb8888-4tiled
[340.196395] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.110s)
[340.207037] Starting dynamic subtest: rgb565-4tiled
[340.762631] Dynamic subtest rgb565-4tiled: SUCCESS (0.566s)
[340.773293] Starting dynamic subtest: xrgb2101010-4tiled
[341.885670] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.121s)
[341.895931] Starting dynamic subtest: xbgr16161616f-4tiled
[344.953050] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.066s)
[344.955340] Subtest draw-method-pwrite: SUCCESS (16.188s)
[345.049541] [082/156] (677s left) kms_vblank (ts-continuation-suspend)
[345.138531] Starting subtest: ts-continuation-suspend
[345.140396] Starting dynamic subtest: pipe-A-eDP-1
[361.391621] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.529s)
[361.406563] Starting dynamic subtest: pipe-D-eDP-1
[378.377614] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.157s)
[378.379939] Subtest ts-continuation-suspend: SUCCESS (3.690s)
[379.208111] [083/156] (643s left) syncobj_wait (multi-wait-submitted)
[379.260846] Starting subtest: multi-wait-submitted
[379.367443] Subtest multi-wait-submitted: SUCCESS (0.107s)
[379.442102] [084/156] (643s left) kms_frontbuffer_tracking (hdr-modesetfrombusy)
[379.540051] Starting subtest: hdr-modesetfrombusy
[379.544573] Subtest hdr-modesetfrombusy: SKIP (0.000s)
[379.628271] [085/156] (643s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[379.745728] Starting subtest: flip-vs-cursor-busy-crc-atomic
[380.470808] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.721s)
[381.349358] [086/156] (641s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[381.445381] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[381.449789] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[381.537514] [087/156] (641s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[381.627095] Starting subtest: primary-yf-tiled-reflect-x-180
[381.687024] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[381.794870] [088/156] (641s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-sf)
[381.886163] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-sf
[381.890256] Subtest fbc-pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[381.972923] [089/156] (640s left) kms_atomic (plane-invalid-params)
[382.068276] Starting subtest: plane-invalid-params
[382.070860] Starting dynamic subtest: pipe-A-eDP-1
[382.453652] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.385s)
[382.456045] Subtest plane-invalid-params: SUCCESS (0.387s)
[383.275669] [090/156] (639s left) gen9_exec_parse (batch-without-end)
[383.341742] Subtest batch-without-end: SKIP (0.000s)
[383.428800] [091/156] (639s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-render)
[383.526219] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-render
[383.530260] Subtest psrhdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[383.620231] [092/156] (639s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[383.723594] Starting subtest: crc-primary-basic-y-tiled-ccs
[383.725925] Starting dynamic subtest: pipe-A-eDP-1
[383.749255] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[383.762345] Starting dynamic subtest: pipe-B-eDP-1
[383.783892] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[383.793591] Starting dynamic subtest: pipe-C-eDP-1
[383.815191] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[383.824789] Starting dynamic subtest: pipe-D-eDP-1
[383.846763] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[383.851161] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.005s)
[386.053305] [093/156] (637s left) kms_psr (fbc-pr-cursor-blt)
[386.146994] Starting subtest: fbc-pr-cursor-blt
[386.151526] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[386.228638] [094/156] (636s left) gem_spin_batch (resubmit-all)
[386.284443] Starting subtest: resubmit-all
[386.287079] Starting dynamic subtest: rcs0
[386.294477] Dynamic subtest rcs0: SUCCESS (0.011s)
[386.307752] Starting dynamic subtest: bcs0
[386.310024] Dynamic subtest bcs0: SUCCESS (0.002s)
[386.319344] Starting dynamic subtest: vcs0
[386.321290] Dynamic subtest vcs0: SUCCESS (0.002s)
[386.331884] Starting dynamic subtest: vcs1
[386.333879] Dynamic subtest vcs1: SUCCESS (0.002s)
[386.343373] Starting dynamic subtest: vecs0
[386.345333] Dynamic subtest vecs0: SUCCESS (0.002s)
[386.354712] Starting dynamic subtest: ccs0
[386.356565] Dynamic subtest ccs0: SUCCESS (0.002s)
[386.358490] Subtest resubmit-all: SUCCESS (0.025s)
[388.066701] [095/156] (635s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[388.127087] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[388.129615] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[388.239836] [096/156] (635s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-pwrite)
[388.336117] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-pwrite
[388.340150] Subtest hdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[388.433585] [097/156] (634s left) gem_exec_params (larger-than-life-batch)
[388.483904] Starting subtest: larger-than-life-batch
[390.857103] Subtest larger-than-life-batch: SUCCESS (2.374s)
[392.068238] [098/156] (631s left) perf (non-sampling-read-error)
[392.233056] Starting subtest: non-sampling-read-error
[392.245658] Subtest non-sampling-read-error: SUCCESS (0.010s)
[392.284753] [099/156] (631s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-render)
[392.400743] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-render
[392.404733] Subtest psrhdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[392.499865] [100/156] (630s left) gem_softpin (overlap)
[392.565140] Starting subtest: overlap
[393.456342] Subtest overlap: SUCCESS (0.890s)
[393.576089] [101/156] (629s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[393.668597] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[393.670951] Starting dynamic subtest: pipe-A-default-mode
[394.801388] Dynamic subtest pipe-A-default-mode: SKIP (1.128s)
[394.806089] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.130s)
[394.939134] [102/156] (628s left) gem_workarounds (reset-fd)
[395.015585] Starting subtest: reset-fd
[395.193974] Subtest reset-fd: SUCCESS (0.181s)
[395.358914] [103/156] (628s left) gem_exec_balancer (bonded-chain)
[395.420279] Starting subtest: bonded-chain
[402.429627] Subtest bonded-chain: SUCCESS (7.004s)
[402.766325] [104/156] (620s left) gem_exec_fence (syncobj-channel)
[403.255853] Starting subtest: syncobj-channel
[404.276635] Subtest syncobj-channel: SUCCESS (1.017s)
[405.080161] [105/156] (618s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[405.137212] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[405.139674] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[405.243763] [106/156] (618s left) api_intel_bb (destroy-bb)
[405.292593] Starting subtest: destroy-bb
[405.308859] Subtest destroy-bb: SUCCESS (0.015s)
[405.445095] [107/156] (618s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[405.546079] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[405.550627] Subtest fbchdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[405.666829] [108/156] (618s left) gem_exec_params (secure-non-master)
[405.725907] Starting subtest: secure-non-master
[405.730484] Subtest secure-non-master: SKIP (0.000s)
[405.855736] [109/156] (617s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[405.957259] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[405.961681] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[406.056762] [110/156] (617s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[406.154793] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[410.283636] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (4.132s)
[411.502842] [111/156] (612s left) kms_prop_blob (blob-prop-validate)
[411.563156] Starting subtest: blob-prop-validate
[411.565446] Subtest blob-prop-validate: SUCCESS (0.000s)
[411.668055] [112/156] (612s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-5)
[411.757491] Starting subtest: planes-upscale-20x20-downscale-factor-0-5
[411.759722] Starting dynamic subtest: pipe-A
[411.778739] Dynamic subtest pipe-A: SKIP (0.017s)
[411.793094] Starting dynamic subtest: pipe-B
[411.810451] Dynamic subtest pipe-B: SKIP (0.030s)
[411.821371] Starting dynamic subtest: pipe-C
[411.840663] Dynamic subtest pipe-C: SKIP (0.030s)
[411.851599] Starting dynamic subtest: pipe-D
[411.870305] Dynamic subtest pipe-D: SKIP (0.030s)
[411.874042] Subtest planes-upscale-20x20-downscale-factor-0-5: SKIP (0.109s)
[411.970379] [113/156] (612s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier)
[412.061549] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier
[412.066017] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier: SKIP (0.000s)
[412.135014] [114/156] (611s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[412.226329] Starting subtest: pr-overlay-plane-update-continuous-sf
[412.230602] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[412.293925] [115/156] (611s left) syncobj_wait (multi-wait-all-submitted-signaled)
[412.339587] Starting subtest: multi-wait-all-submitted-signaled
[412.449666] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.107s)
[412.537367] [116/156] (611s left) prime_vgem (basic-read)
[412.602262] Starting subtest: basic-read
[412.610648] Subtest basic-read: SKIP (0.006s)
[412.715688] [117/156] (611s left) gem_busy (parallel)
[412.895296] Starting subtest: parallel
[412.897863] Starting dynamic subtest: rcs0
[414.973107] Dynamic subtest rcs0: SUCCESS (2.076s)
[414.987527] Starting dynamic subtest: bcs0
[417.072490] Dynamic subtest bcs0: SUCCESS (2.044s)
[417.084119] Starting dynamic subtest: vcs0
[419.005670] Dynamic subtest vcs0: SUCCESS (1.982s)
[419.024524] Starting dynamic subtest: vcs1
[421.039912] Dynamic subtest vcs1: SUCCESS (1.980s)
[421.052622] Starting dynamic subtest: vecs0
[422.974946] Dynamic subtest vecs0: SUCCESS (1.981s)
[422.987270] Starting dynamic subtest: ccs0
[425.021560] Dynamic subtest ccs0: SUCCESS (2.044s)
[425.024198] Subtest parallel: SUCCESS (12.126s)
[425.244989] [118/156] (598s left) kms_selftest (drm_plane_helper)
[425.287190] Starting subtest: drm_plane_helper
[425.295270] Starting dynamic subtest: drm_test_check_plane_state
[425.386623] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.092s)
[425.399775] Starting dynamic subtest: drm_test_check_invalid_plane_state
[425.511178] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.122s)
[425.513540] Subtest drm_plane_helper: SUCCESS (0.223s)
[425.697159] [119/156] (598s left) perf_pmu (faulting-read)
[425.749952] Starting subtest: faulting-read
[425.752271] Starting dynamic subtest: wb
[425.754523] Dynamic subtest wb: SUCCESS (0.001s)
[425.767815] Starting dynamic subtest: wc
[425.769946] Dynamic subtest wc: SUCCESS (0.000s)
[425.780217] Starting dynamic subtest: uc
[425.782489] Dynamic subtest uc: SUCCESS (0.000s)
[425.784597] Subtest faulting-read: SUCCESS (0.003s)
[425.865466] [120/156] (598s left) gem_mmap_offset (perf)
[425.925049] Starting subtest: perf
[425.926998] Starting dynamic subtest: smem0
[426.290924] Dynamic subtest smem0: SUCCESS (0.363s)
[426.293476] Subtest perf: SUCCESS (0.365s)
[426.415834] [121/156] (597s left) kms_content_protection (uevent)
[426.560530] Starting subtest: uevent
[426.565035] Subtest uevent: SKIP (0.000s)
[426.694664] [122/156] (597s left) kms_pm_dc (dc6-psr)
[427.799327] Starting subtest: dc6-psr
[430.684141] Subtest dc6-psr: SUCCESS (2.883s)
[431.525885] [123/156] (592s left) gem_exec_flush (basic-wb-rw-default)
[431.586017] Starting subtest: basic-wb-rw-default
[437.071142] Subtest basic-wb-rw-default: SUCCESS (5.455s)
[437.244210] [124/156] (587s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[437.356877] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt
[437.360982] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[437.438331] [125/156] (587s left) api_intel_allocator (default-alignment)
[437.497260] Starting subtest: default-alignment
[437.499851] Subtest default-alignment: SUCCESS (0.004s)
[437.616376] [126/156] (586s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[437.705302] Starting subtest: multiplane-rotation-cropping-bottom
[455.135960] Subtest multiplane-rotation-cropping-bottom: SUCCESS (17.426s)
[455.258455] [127/156] (569s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[455.319590] Starting subtest: multi-wait-for-submit-available-signaled
[455.321575] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s)
[455.402102] [128/156] (569s left) kms_big_fb (linear-8bpp-rotate-0)
[455.510546] Starting subtest: linear-8bpp-rotate-0
[456.815337] Subtest linear-8bpp-rotate-0: SUCCESS (1.303s)
[456.930241] [129/156] (567s left) gem_exercise_blt (fast-copy)
[456.985191] Starting subtest: fast-copy
[456.986895] Starting dynamic subtest: linear-smem-smem
[457.005358] Dynamic subtest linear-smem-smem: SUCCESS (0.023s)
[457.020249] Starting dynamic subtest: xmajor-smem-smem
[457.024496] Dynamic subtest xmajor-smem-smem: SUCCESS (0.016s)
[457.034723] Starting dynamic subtest: tile4-smem-smem
[457.040010] Dynamic subtest tile4-smem-smem: SUCCESS (0.016s)
[457.050537] Starting dynamic subtest: tile64-smem-smem
[457.057233] Dynamic subtest tile64-smem-smem: SUCCESS (0.017s)
[457.059280] Subtest fast-copy: SUCCESS (0.075s)
[457.206541] [130/156] (567s left) gem_pread (self)
[457.263869] Subtest self: SKIP (0.000s)
[457.356166] [131/156] (567s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-gtt)
[457.455780] Starting subtest: fbchdr-rgb565-draw-mmap-gtt
[457.459468] Subtest fbchdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[457.558727] [132/156] (567s left) kms_big_fb (4-tiled-addfb-size-overflow)
[457.653906] Starting subtest: 4-tiled-addfb-size-overflow
[457.655822] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s)
[457.761113] [133/156] (567s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[457.862157] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu
[457.865624] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[457.976481] [134/156] (566s left) gem_exec_balancer (busy)
[458.030791] Starting subtest: busy
[460.026112] Subtest busy: SUCCESS (1.993s)
[460.344741] [135/156] (564s left) gem_exec_balancer (parallel-keep-in-fence)
[460.603269] Starting subtest: parallel-keep-in-fence
[461.761296] Subtest parallel-keep-in-fence: SUCCESS (1.159s)
[461.862770] [136/156] (563s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[461.968377] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-cpu
[468.449756] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.484s)
[468.591124] [137/156] (556s left) kms_plane_multiple (tiling-none)
[468.690322] Starting subtest: tiling-none
[468.692587] Starting dynamic subtest: pipe-A-eDP-1
[472.388164] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.697s)
[472.401474] Starting dynamic subtest: pipe-B-eDP-1
[476.928937] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.540s)
[476.941050] Starting dynamic subtest: pipe-C-eDP-1
[481.331609] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.405s)
[481.342636] Starting dynamic subtest: pipe-D-eDP-1
[485.662138] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.327s)
[485.664496] Subtest tiling-none: SUCCESS (16.973s)
[486.478168] [138/156] (538s left) gem_mmap (short-mmap)
[486.551759] Subtest short-mmap: SKIP (0.000s)
[486.613672] [139/156] (538s left) gem_exec_schedule (preempt-queue-chain)
[486.687765] Subtest preempt-queue-chain: SKIP (0.000s)
[486.977897] [140/156] (538s left) gem_readwrite (read-bad-handle)
[487.025628] Subtest read-bad-handle: SKIP (0.000s)
[487.100620] [141/156] (538s left) kms_lease (setcrtc-implicit-plane)
[487.193334] Starting subtest: setcrtc-implicit-plane
[487.195666] Starting dynamic subtest: pipe-A-eDP-1
[487.664267] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.469s)
[487.678069] Starting dynamic subtest: pipe-B-eDP-1
[488.829308] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.162s)
[488.842284] Starting dynamic subtest: pipe-C-eDP-1
[489.932376] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.105s)
[489.946824] Starting dynamic subtest: pipe-D-eDP-1
[491.076949] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.143s)
[491.079232] Subtest setcrtc-implicit-plane: SUCCESS (3.886s)
[491.917232] [142/156] (533s left) kms_cursor_crc (cursor-offscreen-max-size)
[492.261852] Starting subtest: cursor-offscreen-max-size
[492.268216] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[492.388913] [143/156] (532s left) gem_userptr_blits (mmap-offset-banned)
[492.451017] Starting subtest: mmap-offset-banned
[492.453463] Starting dynamic subtest: wb
[492.455744] Dynamic subtest wb: SUCCESS (0.000s)
[492.469023] Starting dynamic subtest: wc
[492.470907] Dynamic subtest wc: SUCCESS (0.000s)
[492.480869] Starting dynamic subtest: uc
[492.482755] Dynamic subtest uc: SUCCESS (0.000s)
[492.484588] Subtest mmap-offset-banned: SUCCESS (0.002s)
[492.567162] [144/156] (532s left) syncobj_timeline (invalid-query-one-illegal-handle)
[492.627980] Starting subtest: invalid-query-one-illegal-handle
[492.629926] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s)
[492.720666] [145/156] (532s left) kms_psr (psr-primary-blt)
[492.807021] Starting subtest: psr-primary-blt
[492.809970] Starting dynamic subtest: eDP-1
[494.522199] Dynamic subtest eDP-1: SUCCESS (1.716s)
[494.524552] Subtest psr-primary-blt: SUCCESS (1.717s)
[495.389011] [146/156] (530s left) kms_busy (extended-modeset-hang-oldfb)
[495.485442] Starting subtest: extended-modeset-hang-oldfb
[495.487804] Starting dynamic subtest: pipe-A
[506.962112] Dynamic subtest pipe-A: SUCCESS (11.474s)
[506.974616] Starting dynamic subtest: pipe-D
[519.316506] Dynamic subtest pipe-D: SUCCESS (12.352s)
[519.318659] Subtest extended-modeset-hang-oldfb: SUCCESS (23.831s)
[520.222730] [147/156] (505s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite)
[520.320091] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite
[520.324340] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[520.422464] [148/156] (505s left) sysfs_preempt_timeout (idempotent)
[520.472393] Starting subtest: idempotent
[520.474672] Starting dynamic subtest: vecs0
[520.476983] Dynamic subtest vecs0: SUCCESS (0.000s)
[520.488239] Starting dynamic subtest: ccs0
[520.490279] Dynamic subtest ccs0: SUCCESS (0.000s)
[520.501107] Starting dynamic subtest: vcs0
[520.503402] Dynamic subtest vcs0: SUCCESS (0.000s)
[520.513752] Starting dynamic subtest: bcs0
[520.515758] Dynamic subtest bcs0: SUCCESS (0.000s)
[520.525789] Starting dynamic subtest: rcs0
[520.527694] Dynamic subtest rcs0: SUCCESS (0.000s)
[520.537923] Starting dynamic subtest: vcs1
[520.539843] Dynamic subtest vcs1: SUCCESS (0.000s)
[520.541764] Subtest idempotent: SUCCESS (0.007s)
[520.581226] [149/156] (504s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-render)
[520.669895] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-render
[520.674022] Subtest psrhdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[520.784082] [150/156] (504s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[520.889437] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[520.894368] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[521.006609] [151/156] (504s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[521.112068] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[522.157518] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.045s)
[522.292909] [152/156] (503s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-render)
[522.404501] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-render
[522.408150] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[522.519130] [153/156] (503s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[522.603596] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[522.605365] Starting dynamic subtest: pipe-A-eDP-1
[522.621859] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[522.633483] Starting dynamic subtest: pipe-B-eDP-1
[522.649339] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[522.660247] Starting dynamic subtest: pipe-C-eDP-1
[522.676225] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[522.686736] Starting dynamic subtest: pipe-D-eDP-1
[522.702790] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[522.704612] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.005s)
[524.868982] [154/156] (500s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-blt)
[524.972167] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-blt
[524.976594] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[525.065839] [155/156] (500s left) kms_flip (dpms-vs-vblank-race-interruptible)
[525.159712] Starting subtest: dpms-vs-vblank-race-interruptible
[525.162153] Starting dynamic subtest: A-eDP1
[529.122892] Dynamic subtest A-eDP1: SUCCESS (3.959s)
[529.134556] Starting dynamic subtest: B-eDP1
[533.760142] Dynamic subtest B-eDP1: SUCCESS (4.633s)
[533.771939] Starting dynamic subtest: C-eDP1
[537.286486] Dynamic subtest C-eDP1: SUCCESS (3.523s)
[537.298375] Starting dynamic subtest: D-eDP1
[540.886563] Dynamic subtest D-eDP1: SUCCESS (3.601s)
[540.888796] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (15.728s)
[541.756340] [156/156] (484s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[542.139349] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[542.963338] Closing watchdogs
results: parsing output: 0/ for test: kms_flip_scaled_crc
results: parsing output: 1/ for test: kms_cursor_legacy
results: parsing output: 2/ for test: kms_pipe_crc_basic
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: core_hotunplug
results: parsing output: 6/ for test: kms_cursor_legacy
results: parsing output: 7/ for test: kms_atomic_interruptible
results: parsing output: 8/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 9/ for test: kms_big_fb
results: parsing output: 10/ for test: i915_pm_rps
results: parsing output: 11/ for test: gem_softpin
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_cursor_crc
results: parsing output: 14/ for test: i915_drm_fdinfo
results: parsing output: 15/ for test: kms_cursor_legacy
results: parsing output: 16/ for test: kms_ccs
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_softpin
results: parsing output: 19/ for test: kms_addfb_basic
results: parsing output: 20/ for test: gem_mmap_gtt
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: sw_sync
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: vgem_basic
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_cursor_crc
results: parsing output: 28/ for test: gem_pxp
results: parsing output: 29/ for test: gem_tiled_blits
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: gem_ctx_exec
results: parsing output: 32/ for test: syncobj_wait
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_flip
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: gem_create
results: parsing output: 37/ for test: gem_mmap_gtt
results: parsing output: 38/ for test: kms_async_flips
results: parsing output: 39/ for test: syncobj_wait
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_flip
results: parsing output: 43/ for test: kms_setmode
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: gem_mmap_gtt
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: prime_mmap
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_plane
results: parsing output: 54/ for test: perf
results: parsing output: 55/ for test: i915_query
results: parsing output: 56/ for test: kms_hdr
results: parsing output: 57/ for test: gem_ctx_persistence
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: gem_render_copy
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: kms_color
results: parsing output: 64/ for test: gem_exec_whisper
results: parsing output: 65/ for test: kms_psr
results: parsing output: 66/ for test: gem_exec_schedule
results: parsing output: 67/ for test: kms_chamelium_frames
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: syncobj_eventfd
results: parsing output: 72/ for test: kms_big_fb
results: parsing output: 73/ for test: syncobj_wait
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_invalid_mode
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: i915_power
results: parsing output: 78/ for test: i915_drm_fdinfo
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_draw_crc
results: parsing output: 81/ for test: kms_vblank
results: parsing output: 82/ for test: syncobj_wait
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: kms_cursor_legacy
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_rotation_crc
results: parsing output: 87/ for test: kms_psr2_sf
results: parsing output: 88/ for test: kms_atomic
results: parsing output: 89/ for test: gen9_exec_parse
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: kms_psr
results: parsing output: 93/ for test: gem_spin_batch
results: parsing output: 94/ for test: syncobj_timeline
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_exec_params
results: parsing output: 97/ for test: perf
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_softpin
results: parsing output: 100/ for test: kms_flip_scaled_crc
results: parsing output: 101/ for test: gem_workarounds
results: parsing output: 102/ for test: gem_exec_balancer
results: parsing output: 103/ for test: gem_exec_fence
results: parsing output: 104/ for test: syncobj_wait
results: parsing output: 105/ for test: api_intel_bb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_params
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_prop_blob
results: parsing output: 111/ for test: kms_plane_scaling
results: parsing output: 112/ for test: kms_plane
results: parsing output: 113/ for test: kms_psr2_sf
results: parsing output: 114/ for test: syncobj_wait
results: parsing output: 115/ for test: prime_vgem
results: parsing output: 116/ for test: gem_busy
results: parsing output: 117/ for test: kms_selftest
results: parsing output: 118/ for test: perf_pmu
results: parsing output: 119/ for test: gem_mmap_offset
results: parsing output: 120/ for test: kms_content_protection
results: parsing output: 121/ for test: kms_pm_dc
results: parsing output: 122/ for test: gem_exec_flush
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: api_intel_allocator
results: parsing output: 125/ for test: kms_rotation_crc
results: parsing output: 126/ for test: syncobj_timeline
results: parsing output: 127/ for test: kms_big_fb
results: parsing output: 128/ for test: gem_exercise_blt
results: parsing output: 129/ for test: gem_pread
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_big_fb
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: gem_exec_balancer
results: parsing output: 134/ for test: gem_exec_balancer
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_plane_multiple
results: parsing output: 137/ for test: gem_mmap
results: parsing output: 138/ for test: gem_exec_schedule
results: parsing output: 139/ for test: gem_readwrite
results: parsing output: 140/ for test: kms_lease
results: parsing output: 141/ for test: kms_cursor_crc
results: parsing output: 142/ for test: gem_userptr_blits
results: parsing output: 143/ for test: syncobj_timeline
results: parsing output: 144/ for test: kms_psr
results: parsing output: 145/ for test: kms_busy
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: sysfs_preempt_timeout
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_big_fb
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: kms_ccs
results: parsing output: 153/ for test: kms_frontbuffer_tracking
results: parsing output: 154/ for test: kms_flip
results: parsing output: 155/ for test: kms_chamelium_hpd
Done.