[61.919686] Initializing watchdogs
[61.919733]   /dev/watchdog0
[61.933887] [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]
[61.939335] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.941581] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.944867] [001/156] (960s left) kms_psr (pr-basic)
[62.067227] Starting subtest: pr-basic
[62.071474] Subtest pr-basic: SKIP (0.000s)
[62.145059] [002/156] (960s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[62.256980] Starting subtest: short-flip-before-cursor-toggle
[63.234798] Subtest short-flip-before-cursor-toggle: SUCCESS (0.975s)
[64.083203] [003/156] (958s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[64.179067] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[64.183605] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[64.266474] [004/156] (958s left) kms_plane_scaling (plane-upscale-factor-0-25-with-pixel-format)
[64.369297] Starting subtest: plane-upscale-factor-0-25-with-pixel-format
[64.371663] Starting dynamic subtest: pipe-A
[65.417681] Dynamic subtest pipe-A: SUCCESS (1.050s)
[65.425142] Starting dynamic subtest: pipe-B
[67.589851] Dynamic subtest pipe-B: SUCCESS (2.172s)
[67.598105] Starting dynamic subtest: pipe-C
[68.787066] Dynamic subtest pipe-C: SUCCESS (1.196s)
[68.796736] Starting dynamic subtest: pipe-D
[70.018438] Dynamic subtest pipe-D: SUCCESS (1.231s)
[70.020850] Subtest plane-upscale-factor-0-25-with-pixel-format: SUCCESS (5.651s)
[71.234158] [005/156] (951s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[71.362384] Starting subtest: cursor-vs-flip-legacy
[101.369551] Subtest cursor-vs-flip-legacy: SUCCESS (30.003s)
[102.240369] [006/156] (920s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render)
[102.344333] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render
[106.869500] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.531s)
[108.038462] [007/156] (914s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-mmap-wc)
[108.137818] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-mmap-wc
[108.142103] Subtest hdr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[108.221765] [008/156] (914s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[108.328150] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[108.332051] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[108.437225] [009/156] (914s left) kms_chamelium_hpd (common-hpd-after-suspend)
[108.822628] Subtest common-hpd-after-suspend: SKIP (0.000s)
[109.639423] [010/156] (913s left) gem_exec_schedule (in-order)
[109.702492] Starting subtest: in-order
[109.706091] Starting dynamic subtest: rcs0
[109.800078] Dynamic subtest rcs0: SUCCESS (0.101s)
[109.811221] Starting dynamic subtest: vcs0
[109.882563] Dynamic subtest vcs0: SUCCESS (0.083s)
[109.893811] Starting dynamic subtest: vcs1
[109.960595] Dynamic subtest vcs1: SUCCESS (0.077s)
[109.972327] Starting dynamic subtest: vecs0
[110.042039] Dynamic subtest vecs0: SUCCESS (0.082s)
[110.053976] Starting dynamic subtest: ccs0
[110.124055] Dynamic subtest ccs0: SUCCESS (0.081s)
[110.126890] Subtest in-order: SUCCESS (0.426s)
[111.394584] [011/156] (911s left) gem_ctx_exec (basic)
[111.451959] Starting subtest: basic
[111.457861] Subtest basic: SUCCESS (0.007s)
[111.565782] [012/156] (911s left) syncobj_wait (invalid-wait-bad-flags)
[111.622048] Starting subtest: invalid-wait-bad-flags
[111.624077] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[111.710811] [013/156] (911s left) kms_plane_multiple (tiling-x)
[111.810603] Starting subtest: tiling-x
[111.812856] Starting dynamic subtest: pipe-A-eDP-1
[115.612563] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.800s)
[115.621822] Starting dynamic subtest: pipe-B-eDP-1
[120.154514] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.541s)
[120.166654] Starting dynamic subtest: pipe-C-eDP-1
[124.531423] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.376s)
[124.541716] Starting dynamic subtest: pipe-D-eDP-1
[128.979265] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.447s)
[128.981210] Subtest tiling-x: SUCCESS (17.169s)
[129.782394] [014/156] (893s left) perf (short-reads)
[129.901931] Starting subtest: short-reads
[129.916599] Subtest short-reads: SUCCESS (0.016s)
[129.962081] [015/156] (893s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[130.075143] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-gtt
[130.079290] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[130.176207] [016/156] (892s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[130.236336] Starting subtest: y-tiled-ccs-to-y-tiled
[130.240932] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[130.416048] [017/156] (892s left) api_intel_bb (misplaced-blitter)
[130.470208] Starting subtest: misplaced-blitter
[130.475212] Subtest misplaced-blitter: SUCCESS (0.007s)
[130.603446] [018/156] (892s left) kms_atomic_transition (plane-all-transition-fencing)
[130.692318] Starting subtest: plane-all-transition-fencing
[130.694640] Starting dynamic subtest: pipe-A-eDP-1
[133.185809] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[133.490099] Starting dynamic subtest: pipe-B-eDP-1
[136.715473] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.233s)
[137.027713] Subtest plane-all-transition-fencing: SUCCESS (6.335s)
[137.857234] [019/156] (885s left) gem_reg_read (timestamp-monotonic)
[137.909280] Starting subtest: timestamp-monotonic
[141.962331] Subtest timestamp-monotonic: SUCCESS (4.053s)
[142.034871] [020/156] (881s left) kms_plane_cursor (primary)
[142.136353] Starting subtest: primary
[142.140997] Starting dynamic subtest: pipe-A-eDP-1-size-64
[144.706104] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.569s)
[144.729328] Starting dynamic subtest: pipe-A-eDP-1-size-128
[147.272439] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.550s)
[147.294968] Starting dynamic subtest: pipe-A-eDP-1-size-256
[149.896587] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.608s)
[150.216547] Starting dynamic subtest: pipe-B-eDP-1-size-64
[153.623457] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.416s)
[153.648450] Starting dynamic subtest: pipe-B-eDP-1-size-128
[156.236144] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.594s)
[156.258766] Starting dynamic subtest: pipe-B-eDP-1-size-256
[158.858801] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.607s)
[159.172321] Starting dynamic subtest: pipe-C-eDP-1-size-64
[162.105632] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.944s)
[162.132074] Starting dynamic subtest: pipe-C-eDP-1-size-128
[164.349575] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.225s)
[164.375789] Starting dynamic subtest: pipe-C-eDP-1-size-256
[166.560538] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.192s)
[166.883517] Starting dynamic subtest: pipe-D-eDP-1-size-64
[169.839662] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.967s)
[169.866120] Starting dynamic subtest: pipe-D-eDP-1-size-128
[172.061667] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.203s)
[172.087482] Starting dynamic subtest: pipe-D-eDP-1-size-256
[174.272514] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.192s)
[174.582406] Subtest primary: SUCCESS (32.445s)
[175.473979] [021/156] (847s left) gem_ctx_create (basic-files)
[175.530926] Starting subtest: basic-files
[177.539915] Subtest basic-files: SUCCESS (2.008s)
[179.300030] [022/156] (843s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-indfb-pgflip-blt)
[179.406677] Starting subtest: psrhdr-2p-primscrn-indfb-pgflip-blt
[179.411078] Subtest psrhdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[179.505059] [023/156] (843s left) kms_cursor_crc (cursor-tearing-position-change)
[179.621615] Starting subtest: cursor-tearing-position-change
[179.623893] Starting dynamic subtest: pipe-A-eDP-1
[180.179111] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.557s)
[180.190387] Starting dynamic subtest: pipe-D-eDP-1
[181.386128] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.201s)
[181.388136] Subtest cursor-tearing-position-change: SUCCESS (1.762s)
[182.218757] [024/156] (841s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[182.324383] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[182.326668] Starting dynamic subtest: pipe-A-eDP-1
[188.204199] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.880s)
[188.212703] Starting dynamic subtest: pipe-B-eDP-1
[194.859384] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.656s)
[194.861586] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (12.539s)
[195.724887] [025/156] (827s left) syncobj_timeline (signal-point-0)
[195.776208] Starting subtest: signal-point-0
[195.778132] Subtest signal-point-0: SUCCESS (0.000s)
[195.869467] [026/156] (827s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[195.977161] Starting subtest: yf-tiled-16bpp-rotate-180
[195.981576] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[196.066156] [027/156] (827s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[196.160907] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[196.165080] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[196.251143] [028/156] (827s left) drm_read (empty-block)
[196.384517] Starting subtest: empty-block
[197.383351] Subtest empty-block: SUCCESS (1.001s)
[197.511336] [029/156] (826s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping)
[197.598154] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping
[197.602196] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[197.667400] [030/156] (825s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[197.763755] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[197.767987] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[197.871559] [031/156] (825s left) kms_vblank (ts-continuation-dpms-rpm)
[197.972812] Starting subtest: ts-continuation-dpms-rpm
[197.975051] Starting dynamic subtest: pipe-A-eDP-1
[200.525700] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.553s)
[200.539202] Starting dynamic subtest: pipe-D-eDP-1
[202.759031] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.230s)
[202.761291] Subtest ts-continuation-dpms-rpm: SUCCESS (4.786s)
[203.637868] [032/156] (819s left) gem_exec_schedule (submit-late-slice)
[203.703168] Starting subtest: submit-late-slice
[203.705249] Starting dynamic subtest: rcs0
[203.734892] Dynamic subtest rcs0: SUCCESS (0.041s)
[203.747444] Starting dynamic subtest: bcs0
[203.772738] Dynamic subtest bcs0: SUCCESS (0.037s)
[203.781568] Starting dynamic subtest: vcs0
[203.805085] Dynamic subtest vcs0: SUCCESS (0.031s)
[203.814596] Starting dynamic subtest: vcs1
[203.838174] Dynamic subtest vcs1: SUCCESS (0.032s)
[203.849084] Starting dynamic subtest: vecs0
[203.867947] Dynamic subtest vecs0: SUCCESS (0.029s)
[203.876534] Starting dynamic subtest: ccs0
[203.906907] Dynamic subtest ccs0: SUCCESS (0.037s)
[203.909711] Subtest submit-late-slice: SUCCESS (0.214s)
[205.383409] [033/156] (818s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[205.494852] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[205.499424] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[205.598327] [034/156] (818s left) syncobj_eventfd (binary-wait-signaled)
[205.654518] Starting subtest: binary-wait-signaled
[205.656695] Subtest binary-wait-signaled: SUCCESS (0.000s)
[205.742411] [035/156] (818s left) syncobj_wait (multi-wait-all-submitted-signaled)
[205.793843] Starting subtest: multi-wait-all-submitted-signaled
[205.902427] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.108s)
[205.976601] [036/156] (817s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[206.072788] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[206.076768] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[206.169662] [037/156] (817s left) kms_psr (fbc-psr2-sprite-mmap-cpu)
[206.266213] Starting subtest: fbc-psr2-sprite-mmap-cpu
[206.268496] Starting dynamic subtest: eDP-1
[206.272655] Dynamic subtest eDP-1: SKIP (0.000s)
[206.276791] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.001s)
[206.334693] [038/156] (817s left) kms_chamelium_frames (hdmi-crc-fast)
[206.714158] Subtest hdmi-crc-fast: SKIP (0.000s)
[207.548251] [039/156] (816s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[207.596566] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[207.599048] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[207.686250] [040/156] (816s left) gem_ctx_engines (execute-one)
[207.744506] Starting subtest: execute-one
[210.075234] Subtest execute-one: SUCCESS (2.331s)
[210.335698] [041/156] (813s left) gem_partial_pwrite_pread (write-snoop)
[210.389633] Subtest write-snoop: SKIP (0.000s)
[210.458736] [042/156] (813s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[210.503457] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[210.505695] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[210.616869] [043/156] (813s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling)
[210.712782] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling
[210.714936] Starting dynamic subtest: pipe-A-default-mode
[211.938821] Dynamic subtest pipe-A-default-mode: SKIP (1.224s)
[211.943437] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.225s)
[212.095959] [044/156] (811s left) prime_mmap_kms (buffer-sharing)
[212.186833] Starting subtest: buffer-sharing
[212.612917] Subtest buffer-sharing: SUCCESS (0.426s)
[213.462350] [045/156] (810s left) kms_content_protection (dp-mst-type-1)
[213.597520] Starting subtest: dp-mst-type-1
[213.602366] Subtest dp-mst-type-1: SKIP (0.000s)
[213.731257] [046/156] (810s left) core_setmaster (master-drop-set-root)
[213.788271] Starting subtest: master-drop-set-root
[213.804104] Subtest master-drop-set-root: SUCCESS (0.016s)
[213.858038] [047/156] (810s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-mmap-gtt)
[213.960839] Starting subtest: fbchdr-rgb565-draw-mmap-gtt
[213.965262] Subtest fbchdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[214.052134] [048/156] (810s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[214.143604] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[214.147989] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[214.245300] [049/156] (810s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[214.387037] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[214.391069] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s)
[214.553556] [050/156] (809s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[214.648903] Starting subtest: crc-primary-basic-yf-tiled-ccs
[214.650921] Starting dynamic subtest: pipe-A-eDP-1
[214.669687] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[214.681916] Starting dynamic subtest: pipe-B-eDP-1
[214.700890] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[214.708294] Starting dynamic subtest: pipe-C-eDP-1
[214.726214] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[214.733699] Starting dynamic subtest: pipe-D-eDP-1
[214.751746] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[214.755305] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.006s)
[216.900057] [051/156] (807s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-pwrite)
[217.001172] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-pwrite
[217.005640] Subtest hdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[217.111587] [052/156] (807s left) gem_ctx_sseu (engines)
[217.161973] Subtest engines: SKIP (0.000s)
[217.225131] [053/156] (807s left) gem_exec_store (basic)
[217.282089] Starting subtest: basic
[217.384837] Subtest basic: SUCCESS (0.105s)
[217.566140] [054/156] (806s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move)
[217.666841] Starting subtest: fbc-2p-primscrn-spr-indfb-move
[217.671273] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[217.772797] [055/156] (806s left) gem_mmap_gtt (hang)
[217.823513] Subtest hang: SKIP (0.000s)
[217.880898] [056/156] (806s left) kms_psr (psr2-sprite-mmap-gtt)
[217.969196] Starting subtest: psr2-sprite-mmap-gtt
[217.971517] Starting dynamic subtest: eDP-1
[219.400691] Dynamic subtest eDP-1: SKIP (1.428s)
[219.405574] Subtest psr2-sprite-mmap-gtt: SKIP (1.430s)
[219.559715] [057/156] (805s left) syncobj_timeline (etime-multi-wait-all-submitted)
[219.611988] Starting subtest: etime-multi-wait-all-submitted
[219.718612] Subtest etime-multi-wait-all-submitted: SUCCESS (0.106s)
[219.792686] [058/156] (804s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-plflip-blt)
[219.890118] Starting subtest: fbcpsr-2p-scndscrn-shrfb-plflip-blt
[219.894289] Subtest fbcpsr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[220.017621] [059/156] (804s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[220.112851] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[220.116912] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[220.188808] [060/156] (804s left) i915_drm_fdinfo (virtual-busy-hang)
[220.225820] Starting subtest: virtual-busy-hang
[220.230741] Subtest virtual-busy-hang: SKIP (0.000s)
[220.296837] [061/156] (804s left) gem_exec_fence (invalid-fence-array)
[220.756038] Starting subtest: invalid-fence-array
[220.758725] Subtest invalid-fence-array: SUCCESS (0.002s)
[222.353608] [FACT gem_exec_fence (invalid-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[222.357425] [062/156] (802s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-blt)
[222.460849] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-blt
[222.465130] Subtest fbchdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[222.582558] [063/156] (802s left) gen9_exec_parse (bb-start-far)
[222.641590] Subtest bb-start-far: SKIP (0.000s)
[222.723457] [064/156] (802s left) kms_ccs (random-ccs-data-4-tiled-mtl-mc-ccs)
[222.814822] Starting subtest: random-ccs-data-4-tiled-mtl-mc-ccs
[222.817084] Starting dynamic subtest: pipe-A-eDP-1
[224.035159] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.221s)
[224.043581] Starting dynamic subtest: pipe-B-eDP-1
[226.336093] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.299s)
[226.345048] Starting dynamic subtest: pipe-C-eDP-1
[228.685937] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.348s)
[228.694731] Starting dynamic subtest: pipe-D-eDP-1
[231.033496] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.347s)
[231.035913] Subtest random-ccs-data-4-tiled-mtl-mc-ccs: SUCCESS (8.221s)
[232.273880] [065/156] (792s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[232.368482] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[232.370726] Starting dynamic subtest: pipe-A-eDP-1
[232.379619] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[232.388231] Starting dynamic subtest: pipe-B-eDP-1
[232.397401] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[232.405858] Starting dynamic subtest: pipe-C-eDP-1
[232.414640] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[232.422585] Starting dynamic subtest: pipe-D-eDP-1
[232.431123] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[232.435186] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.005s)
[234.629267] [066/156] (790s left) sysfs_timeslice_duration (idempotent)
[234.693137] Starting subtest: idempotent
[234.695447] Starting dynamic subtest: vecs0
[234.697720] Dynamic subtest vecs0: SUCCESS (0.000s)
[234.707244] Starting dynamic subtest: ccs0
[234.709468] Dynamic subtest ccs0: SUCCESS (0.000s)
[234.717838] Starting dynamic subtest: vcs0
[234.720081] Dynamic subtest vcs0: SUCCESS (0.000s)
[234.728113] Starting dynamic subtest: bcs0
[234.730275] Dynamic subtest bcs0: SUCCESS (0.000s)
[234.738340] Starting dynamic subtest: rcs0
[234.740569] Dynamic subtest rcs0: SUCCESS (0.000s)
[234.749071] Starting dynamic subtest: vcs1
[234.751047] Dynamic subtest vcs1: SUCCESS (0.000s)
[234.752933] Subtest idempotent: SUCCESS (0.006s)
[234.802823] [067/156] (790s left) gem_lmem_swapping (verify-random-ccs)
[234.841635] Subtest verify-random-ccs: SKIP (0.000s)
[234.880834] [068/156] (790s left) kms_joiner (basic-force-ultra-joiner)
[234.984035] Starting subtest: basic-force-ultra-joiner
[234.988570] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[235.050983] [069/156] (790s left) perf (sysctl-defaults)
[235.137034] Starting subtest: sysctl-defaults
[235.139362] Subtest sysctl-defaults: SUCCESS (0.000s)
[235.204601] [070/156] (789s left) i915_pm_rpm (system-suspend-execbuf)
[237.448840] Starting subtest: system-suspend-execbuf
[295.292146] Subtest system-suspend-execbuf: SUCCESS (42.942s)
[295.700607] [071/156] (729s left) gem_render_copy (yf-tiled)
[295.767773] Starting subtest: yf-tiled
[295.772340] Subtest yf-tiled: SKIP (0.000s)
[295.949514] [072/156] (729s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[296.050007] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[296.052174] Starting dynamic subtest: pipe-A-eDP-1
[296.078772] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[296.089820] Starting dynamic subtest: pipe-B-eDP-1
[296.114810] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[296.124217] Starting dynamic subtest: pipe-C-eDP-1
[296.147684] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[296.156563] Starting dynamic subtest: pipe-D-eDP-1
[296.180694] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[296.185165] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.005s)
[298.338831] [073/156] (726s left) kms_flip (blocking-wf_vblank)
[298.428252] Starting subtest: blocking-wf_vblank
[298.430151] Starting dynamic subtest: A-eDP1
[307.397878] Dynamic subtest A-eDP1: SUCCESS (8.970s)
[307.409749] Starting dynamic subtest: B-eDP1
[316.319609] Dynamic subtest B-eDP1: SUCCESS (8.920s)
[316.334579] Starting dynamic subtest: C-eDP1
[325.242310] Dynamic subtest C-eDP1: SUCCESS (8.918s)
[325.251946] Starting dynamic subtest: D-eDP1
[334.176883] Dynamic subtest D-eDP1: SUCCESS (8.929s)
[334.179220] Subtest blocking-wf_vblank: SUCCESS (35.749s)
[335.414480] [074/156] (689s left) kms_atomic (crtc-invalid-params-fence)
[335.514176] Starting subtest: crtc-invalid-params-fence
[335.516451] Starting dynamic subtest: pipe-A-eDP-1
[335.917774] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.404s)
[335.920050] Subtest crtc-invalid-params-fence: SUCCESS (0.405s)
[336.751826] [075/156] (688s left) gem_exec_params (dr1-dirt)
[336.818990] Starting subtest: DR1-dirt
[336.820901] Subtest DR1-dirt: SUCCESS (0.000s)
[336.912010] [076/156] (688s left) gem_softpin (allocator-fork)
[336.967846] Starting subtest: allocator-fork
[339.175172] Subtest allocator-fork: SUCCESS (2.206s)
[339.296471] [077/156] (686s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[339.391796] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[339.396289] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[339.489528] [078/156] (685s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[339.542013] Starting subtest: single-wait-for-submit-available-signaled
[339.544336] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[339.650009] [079/156] (685s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[339.738027] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[339.740243] Starting dynamic subtest: pipe-A-eDP-1
[339.762903] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[339.774140] Starting dynamic subtest: pipe-B-eDP-1
[339.795538] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[339.804785] Starting dynamic subtest: pipe-C-eDP-1
[339.826687] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[339.835812] Starting dynamic subtest: pipe-D-eDP-1
[339.857914] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[339.861971] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[342.006537] [080/156] (683s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[342.115003] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu
[348.656277] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.545s)
[348.782278] [081/156] (676s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-render)
[348.888706] Starting subtest: hdr-rgb101010-draw-render
[348.893594] Subtest hdr-rgb101010-draw-render: SKIP (0.000s)
[348.996295] [082/156] (676s left) gem_mmap_gtt (basic-write-read)
[349.053779] Subtest basic-write-read: SKIP (0.000s)
[349.117652] [083/156] (676s left) kms_big_fb (4-tiled-64bpp-rotate-0)
[349.221978] Starting subtest: 4-tiled-64bpp-rotate-0
[350.790500] Subtest 4-tiled-64bpp-rotate-0: SUCCESS (1.570s)
[350.943371] [084/156] (674s left) gem_ctx_persistence (many-contexts)
[351.003276] Starting subtest: many-contexts
[396.185063] Subtest many-contexts: SUCCESS (45.183s)
[396.265154] [085/156] (629s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-pwrite)
[396.370970] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-pwrite
[396.374959] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[396.462400] [086/156] (629s left) kms_feature_discovery (display)
[396.552684] Starting subtest: display
[396.554976] Subtest display: SUCCESS (0.000s)
[396.639681] [087/156] (629s left) kms_psr (psr-sprite-mmap-cpu)
[396.728984] Starting subtest: psr-sprite-mmap-cpu
[396.731298] Starting dynamic subtest: eDP-1
[398.440540] Dynamic subtest eDP-1: SUCCESS (1.711s)
[398.442907] Subtest psr-sprite-mmap-cpu: SUCCESS (1.712s)
[399.296789] [088/156] (626s left) gem_madvise (dontneed-before-exec)
[399.333129] Starting subtest: dontneed-before-exec
[399.356037] Subtest dontneed-before-exec: SKIP (0.020s)
[399.424163] [089/156] (626s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[399.519983] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc
[399.524086] Subtest psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[399.615292] [090/156] (626s left) api_intel_allocator (reloc-allocator)
[399.678523] Starting subtest: reloc-allocator
[399.680833] Starting dynamic subtest: basic
[399.682766] Dynamic subtest basic: SUCCESS (0.003s)
[399.692236] Starting dynamic subtest: parallel-one
[399.694426] Dynamic subtest parallel-one: SUCCESS (0.008s)
[399.703925] Starting dynamic subtest: print
[399.706209] Dynamic subtest print: SUCCESS (0.000s)
[399.715279] Starting dynamic subtest: reuse
[399.717511] Dynamic subtest reuse: SUCCESS (0.000s)
[399.726521] Starting dynamic subtest: fork-reopen-allocator
[399.728798] Dynamic subtest fork-reopen-allocator: SUCCESS (0.013s)
[399.730981] Subtest reloc-allocator: SUCCESS (0.028s)
[399.840059] [091/156] (626s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-blt)
[399.930300] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-blt
[399.934210] Subtest fbchdr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[400.017241] [092/156] (626s left) gem_exec_reloc (basic-wc-gtt-active)
[400.070225] Subtest basic-wc-gtt-active: SKIP (0.000s)
[400.137794] [093/156] (625s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[400.188749] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[400.193080] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[400.381812] [094/156] (625s left) kms_pm_rpm (cursor)
[402.405898] Starting subtest: cursor
[402.411131] Subtest cursor: SKIP (0.000s)
[402.792780] [095/156] (623s left) kms_debugfs (display-off-read-all)
[402.878868] Starting subtest: display-off-read-all
[403.166436] Subtest display-off-read-all: SUCCESS (0.289s)
[404.003173] [096/156] (622s left) i915_module_load (resize-bar)
[404.038474] Starting subtest: resize-bar
[404.046023] Subtest resize-bar: SKIP (0.006s)
[404.093892] [097/156] (622s left) perf_pmu (event-wait)
[404.133438] Starting subtest: event-wait
[404.135742] Starting dynamic subtest: rcs0
[404.140282] Dynamic subtest rcs0: SKIP (0.000s)
[404.144560] Subtest event-wait: SKIP (0.001s)
[404.237223] [098/156] (622s left) perf (non-system-wide-paranoid)
[404.333174] Starting subtest: non-system-wide-paranoid
[404.358088] Subtest non-system-wide-paranoid: SUCCESS (0.025s)
[404.399515] [099/156] (621s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[404.459449] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[404.534557] [100/156] (621s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[404.624655] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[404.628978] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[404.736170] [101/156] (621s left) kms_frontbuffer_tracking (fbcpsrhdr-slowdraw)
[404.842767] Starting subtest: fbcpsrhdr-slowdraw
[404.846862] Subtest fbcpsrhdr-slowdraw: SKIP (0.000s)
[404.950765] [102/156] (621s left) kms_panel_fitting (atomic-fastset)
[405.057213] Starting subtest: atomic-fastset
[405.058884] Starting dynamic subtest: pipe-A-eDP-1
[405.194632] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.138s)
[405.207315] Starting dynamic subtest: pipe-B-eDP-1
[406.455888] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.260s)
[406.466545] Starting dynamic subtest: pipe-C-eDP-1
[407.638462] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.181s)
[407.651631] Starting dynamic subtest: pipe-D-eDP-1
[408.784607] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.146s)
[408.787004] Subtest atomic-fastset: SUCCESS (3.729s)
[409.945214] [103/156] (616s left) fbdev (unaligned-read)
[409.983650] Starting subtest: unaligned-read
[410.000251] Subtest unaligned-read: SUCCESS (0.017s)
[410.078629] [104/156] (616s left) gem_ctx_exec (basic-norecovery)
[410.143670] Starting subtest: basic-norecovery
[410.435989] Subtest basic-norecovery: SUCCESS (0.292s)
[410.574056] [105/156] (616s left) gem_mmap_offset (bad-extensions)
[410.628256] Starting subtest: bad-extensions
[410.630180] Subtest bad-extensions: SUCCESS (0.000s)
[410.733243] [106/156] (615s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[410.823714] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[417.529516] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.710s)
[417.645037] [107/156] (609s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-msflip-blt)
[417.736853] Starting subtest: psr-2p-primscrn-indfb-msflip-blt
[417.740861] Subtest psr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[417.836274] [108/156] (608s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[417.937708] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[417.939981] Starting dynamic subtest: pipe-A-eDP-1
[429.250879] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.317s)
[429.262336] Starting dynamic subtest: pipe-B-eDP-1
[441.269667] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.015s)
[441.271881] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (23.335s)
[442.120502] [109/156] (584s left) gem_userptr_blits (readonly-pwrite-unsync)
[442.192803] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[442.329148] [110/156] (584s left) gem_pxp (create-protected-buffer)
[442.448428] Starting subtest: create-protected-buffer
[442.450537] Subtest create-protected-buffer: SUCCESS (0.000s)
[442.566568] [111/156] (584s left) kms_invalid_mode (bad-hsync-end)
[442.652061] Starting subtest: bad-hsync-end
[442.654764] Starting dynamic subtest: pipe-A-eDP-1
[442.937778] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[442.950536] Starting dynamic subtest: pipe-B-eDP-1
[442.952561] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[442.962249] Starting dynamic subtest: pipe-C-eDP-1
[442.964526] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[442.973963] Starting dynamic subtest: pipe-D-eDP-1
[442.976343] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[442.978682] Subtest bad-hsync-end: SUCCESS (0.291s)
[443.749591] [112/156] (583s left) kms_frontbuffer_tracking (psrhdr-farfromfence-mmap-gtt)
[443.845402] Starting subtest: psrhdr-farfromfence-mmap-gtt
[443.849758] Subtest psrhdr-farfromfence-mmap-gtt: SKIP (0.000s)
[443.950185] [113/156] (582s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[444.056797] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[444.060916] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[444.150288] [114/156] (582s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[444.256129] Starting subtest: addfb25-yf-tiled-legacy
[444.258030] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[444.348638] [115/156] (582s left) gem_exec_reloc (basic-wc-read)
[444.410981] Subtest basic-wc-read: SKIP (0.000s)
[444.478121] [116/156] (582s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[444.570561] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[444.574869] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[444.661806] [117/156] (582s left) kms_big_fb (y-tiled-8bpp-rotate-90)
[444.750981] Starting subtest: y-tiled-8bpp-rotate-90
[444.754567] Subtest y-tiled-8bpp-rotate-90: SKIP (0.000s)
[444.842773] [118/156] (582s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[444.932794] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[444.936323] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[445.037199] [119/156] (582s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[445.135997] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[445.139719] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[445.231353] [120/156] (582s left) kms_vblank (accuracy-idle)
[445.315263] Starting subtest: accuracy-idle
[445.316846] Starting dynamic subtest: pipe-A-eDP-1
[447.080734] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.764s)
[447.098962] Starting dynamic subtest: pipe-D-eDP-1
[449.489225] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.405s)
[449.491577] Subtest accuracy-idle: SUCCESS (4.175s)
[450.322044] [121/156] (576s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[450.424759] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[451.503288] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.079s)
[451.628784] [122/156] (575s left) intel_hwmon (hwmon-read)
[451.685326] Subtest hwmon-read: SKIP (0.000s)
[451.743525] [123/156] (575s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt)
[451.837489] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt
[451.840921] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[451.932792] [124/156] (575s left) kms_flip (bo-too-big-interruptible)
[452.022530] Starting subtest: bo-too-big-interruptible
[452.024349] Starting dynamic subtest: A-eDP1
[453.373927] Dynamic subtest A-eDP1: SUCCESS (1.349s)
[453.387310] Starting dynamic subtest: B-eDP1
[454.702126] Dynamic subtest B-eDP1: SUCCESS (1.325s)
[454.712717] Starting dynamic subtest: C-eDP1
[455.948582] Dynamic subtest C-eDP1: SUCCESS (1.245s)
[455.960474] Starting dynamic subtest: D-eDP1
[457.276839] Dynamic subtest D-eDP1: SUCCESS (1.327s)
[457.279225] Subtest bo-too-big-interruptible: SUCCESS (5.256s)
[458.487851] [125/156] (568s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[458.587394] Starting subtest: extended-modeset-hang-oldfb-with-reset
[458.589894] Starting dynamic subtest: pipe-A
[470.670836] Dynamic subtest pipe-A: SUCCESS (12.081s)
[470.681755] Starting dynamic subtest: pipe-D
[483.128250] Dynamic subtest pipe-D: SUCCESS (12.454s)
[483.130660] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.539s)
[484.012800] [126/156] (543s left) gem_render_copy_redux (normal)
[484.071326] Starting subtest: normal
[484.357236] Subtest normal: SUCCESS (0.286s)
[484.486372] [127/156] (543s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[484.577938] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[484.581865] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[484.669272] [128/156] (542s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-shrfb-pgflip-blt)
[484.760403] Starting subtest: fbchdr-2p-scndscrn-shrfb-pgflip-blt
[484.764440] Subtest fbchdr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[484.857625] [129/156] (542s left) kms_psr (pr-cursor-render)
[484.955268] Starting subtest: pr-cursor-render
[484.959143] Subtest pr-cursor-render: SKIP (0.000s)
[485.040625] [130/156] (542s left) perf_pmu (busy-idle-check-all)
[485.080763] Starting subtest: busy-idle-check-all
[485.083084] Starting dynamic subtest: rcs0
[485.734622] Dynamic subtest rcs0: SUCCESS (0.652s)
[485.748624] Starting dynamic subtest: bcs0
[486.387122] Dynamic subtest bcs0: SUCCESS (0.651s)
[486.399665] Starting dynamic subtest: vcs0
[488.169205] Dynamic subtest vcs0: SUCCESS (1.778s)
[488.184395] Starting dynamic subtest: vcs1
[490.153978] Dynamic subtest vcs1: SUCCESS (1.982s)
[490.167530] Starting dynamic subtest: vecs0
[492.201817] Dynamic subtest vecs0: SUCCESS (2.045s)
[492.214737] Starting dynamic subtest: ccs0
[492.857972] Dynamic subtest ccs0: SUCCESS (0.655s)
[492.864826] Subtest busy-idle-check-all: SUCCESS (7.779s)
[492.995994] [131/156] (534s left) gem_exec_schedule (deep)
[493.175434] Starting subtest: deep
[493.177402] Starting dynamic subtest: rcs0
[499.841215] Dynamic subtest rcs0: SKIP (6.665s)
[499.862068] Starting dynamic subtest: vcs0
[510.846562] Dynamic subtest vcs0: SUCCESS (11.004s)
[510.869409] Starting dynamic subtest: vcs1
[522.293447] Dynamic subtest vcs1: SUCCESS (11.447s)
[522.307081] Starting dynamic subtest: vecs0
[533.640544] Dynamic subtest vecs0: SUCCESS (11.345s)
[533.652341] Starting dynamic subtest: ccs0
[547.247400] Dynamic subtest ccs0: SUCCESS (13.606s)
[547.249744] Subtest deep: SUCCESS (54.072s)
[548.211806] [132/156] (479s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-downscaling)
[548.306927] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-downscaling
[548.311323] Subtest flip-32bpp-yftile-to-64bpp-yftile-downscaling: SKIP (0.000s)
[548.378255] [133/156] (479s left) kms_fence_pin_leak
[548.539810] [134/156] (479s left) kms_cursor_crc (cursor-sliding-256x256)
[548.795635] Starting subtest: cursor-sliding-256x256
[548.797910] Starting dynamic subtest: pipe-A-eDP-1
[552.022812] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.224s)
[552.054892] Starting dynamic subtest: pipe-D-eDP-1
[555.963768] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.916s)
[555.966117] Subtest cursor-sliding-256x256: SUCCESS (7.168s)
[556.804647] [135/156] (471s left) gem_exec_reloc (basic-range-active)
[556.866193] Subtest basic-range-active: SKIP (0.000s)
[556.947875] [136/156] (470s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-move)
[557.040649] Starting subtest: fbchdr-1p-primscrn-cur-indfb-move
[557.044811] Subtest fbchdr-1p-primscrn-cur-indfb-move: SKIP (0.000s)
[557.132375] [137/156] (470s left) kms_color (ctm-0-50)
[557.216279] Starting subtest: ctm-0-50
[557.218231] Starting dynamic subtest: pipe-A-eDP-1
[560.796829] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.582s)
[560.808635] Starting dynamic subtest: pipe-B-eDP-1
[565.085342] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.287s)
[565.095847] Starting dynamic subtest: pipe-C-eDP-1
[569.155555] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.069s)
[569.166193] Starting dynamic subtest: pipe-D-eDP-1
[573.163440] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.005s)
[573.165378] Subtest ctm-0-50: SUCCESS (15.947s)
[573.998196] [138/156] (454s left) gem_exec_balancer (invalid-balancer)
[574.060494] Starting subtest: invalid-balancer
[574.968634] Subtest invalid-balancer: SUCCESS (0.906s)
[575.297819] [139/156] (452s left) kms_cursor_crc (cursor-offscreen-256x256)
[575.563569] Starting subtest: cursor-offscreen-256x256
[575.565877] Starting dynamic subtest: pipe-A-eDP-1
[577.512809] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.948s)
[577.544755] Starting dynamic subtest: pipe-D-eDP-1
[580.121134] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.585s)
[580.123502] Subtest cursor-offscreen-256x256: SUCCESS (4.558s)
[580.954409] [140/156] (447s left) gem_exec_params (no-vebox)
[581.012004] Starting subtest: no-vebox
[581.014348] Subtest no-vebox: SUCCESS (0.002s)
[582.253113] [141/156] (445s left) gem_exec_fence (syncobj-stationary-timeline-chain-engines)
[582.824386] Starting subtest: syncobj-stationary-timeline-chain-engines
[582.951980] Subtest syncobj-stationary-timeline-chain-engines: SUCCESS (0.127s)
[584.250062] [142/156] (443s left) gem_vm_create (isolation)
[584.312365] Starting subtest: isolation
[584.329209] Subtest isolation: SUCCESS (0.017s)
[584.446622] [143/156] (443s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[584.554113] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[584.558691] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[584.647298] [144/156] (443s left) kms_lease (lease-get)
[584.729946] Starting subtest: lease-get
[584.732267] Starting dynamic subtest: pipe-A-eDP-1
[584.734588] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[584.746810] Starting dynamic subtest: pipe-B-eDP-1
[584.748685] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[584.758929] Starting dynamic subtest: pipe-C-eDP-1
[584.760767] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[584.770691] Starting dynamic subtest: pipe-D-eDP-1
[584.772766] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[584.774624] Subtest lease-get: SUCCESS (0.010s)
[584.859328] [145/156] (443s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[584.963016] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[584.967575] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[585.063378] [146/156] (443s left) gem_exec_reloc (basic-cpu-active)
[585.116533] Subtest basic-cpu-active: SKIP (0.000s)
[585.204335] [147/156] (443s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[585.294437] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[585.296561] Starting dynamic subtest: pipe-A
[585.645312] Dynamic subtest pipe-A: SUCCESS (0.352s)
[585.655556] Starting dynamic subtest: pipe-B
[587.083078] Dynamic subtest pipe-B: SUCCESS (1.436s)
[587.095271] Starting dynamic subtest: pipe-C
[588.474032] Dynamic subtest pipe-C: SUCCESS (1.390s)
[588.485482] Starting dynamic subtest: pipe-D
[589.922948] Dynamic subtest pipe-D: SUCCESS (1.448s)
[589.925739] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.630s)
[591.088426] [148/156] (437s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[591.193583] Starting subtest: x-tiled-8bpp-rotate-90
[591.215980] Subtest x-tiled-8bpp-rotate-90: SKIP (0.022s)
[591.318150] [149/156] (437s left) gem_pread (snoop)
[591.381642] Subtest snoop: SKIP (0.000s)
[591.440137] [150/156] (437s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[591.539902] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc
[591.544198] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[591.635330] [151/156] (436s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[591.738899] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[599.129195] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.395s)
[599.252010] [152/156] (429s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render)
[599.348332] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render
[599.352750] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[599.446192] [153/156] (429s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[599.533018] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[599.543956] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.010s)
[599.635678] [154/156] (429s left) kms_pm_rpm (system-suspend-modeset)
[601.355783] Starting subtest: system-suspend-modeset
[620.790376] Subtest system-suspend-modeset: SUCCESS (4.336s)
[621.173970] [155/156] (407s left) gem_lmem_swapping (parallel-random-verify)
[621.226047] Subtest parallel-random-verify: SKIP (0.000s)
[621.272734] [156/156] (407s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[621.367117] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[624.916041] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (3.549s)
[626.064959] Closing watchdogs
results: parsing output: 0/ for test: kms_psr
results: parsing output: 1/ for test: kms_cursor_legacy
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_plane_scaling
results: parsing output: 4/ for test: kms_cursor_legacy
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: kms_chamelium_hpd
results: parsing output: 9/ for test: gem_exec_schedule
results: parsing output: 10/ for test: gem_ctx_exec
results: parsing output: 11/ for test: syncobj_wait
results: parsing output: 12/ for test: kms_plane_multiple
results: parsing output: 13/ for test: perf
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_render_copy
results: parsing output: 16/ for test: api_intel_bb
results: parsing output: 17/ for test: kms_atomic_transition
results: parsing output: 18/ for test: gem_reg_read
results: parsing output: 19/ for test: kms_plane_cursor
results: parsing output: 20/ for test: gem_ctx_create
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_cursor_crc
results: parsing output: 23/ for test: kms_psr2_sf
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: drm_read
results: parsing output: 28/ for test: kms_plane
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_vblank
results: parsing output: 31/ for test: gem_exec_schedule
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: syncobj_eventfd
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_psr
results: parsing output: 37/ for test: kms_chamelium_frames
results: parsing output: 38/ for test: syncobj_timeline
results: parsing output: 39/ for test: gem_ctx_engines
results: parsing output: 40/ for test: gem_partial_pwrite_pread
results: parsing output: 41/ for test: syncobj_timeline
results: parsing output: 42/ for test: kms_flip_scaled_crc
results: parsing output: 43/ for test: prime_mmap_kms
results: parsing output: 44/ for test: kms_content_protection
results: parsing output: 45/ for test: core_setmaster
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_cursor_legacy
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_ctx_sseu
results: parsing output: 52/ for test: gem_exec_store
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_mmap_gtt
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: syncobj_timeline
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_psr2_sf
results: parsing output: 59/ for test: i915_drm_fdinfo
results: parsing output: 60/ for test: gem_exec_fence
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: gen9_exec_parse
results: parsing output: 63/ for test: kms_ccs
results: parsing output: 64/ for test: kms_ccs
results: parsing output: 65/ for test: sysfs_timeslice_duration
results: parsing output: 66/ for test: gem_lmem_swapping
results: parsing output: 67/ for test: kms_joiner
results: parsing output: 68/ for test: perf
results: parsing output: 69/ for test: i915_pm_rpm
results: parsing output: 70/ for test: gem_render_copy
results: parsing output: 71/ for test: kms_ccs
results: parsing output: 72/ for test: kms_flip
results: parsing output: 73/ for test: kms_atomic
results: parsing output: 74/ for test: gem_exec_params
results: parsing output: 75/ for test: gem_softpin
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: syncobj_timeline
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_mmap_gtt
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: gem_ctx_persistence
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_feature_discovery
results: parsing output: 86/ for test: kms_psr
results: parsing output: 87/ for test: gem_madvise
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: api_intel_allocator
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gem_exec_reloc
results: parsing output: 92/ for test: gem_render_copy
results: parsing output: 93/ for test: kms_pm_rpm
results: parsing output: 94/ for test: kms_debugfs
results: parsing output: 95/ for test: i915_module_load
results: parsing output: 96/ for test: perf_pmu
results: parsing output: 97/ for test: perf
results: parsing output: 98/ for test: gem_exec_reloc
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_panel_fitting
results: parsing output: 102/ for test: fbdev
results: parsing output: 103/ for test: gem_ctx_exec
results: parsing output: 104/ for test: gem_mmap_offset
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_psr2_sf
results: parsing output: 108/ for test: gem_userptr_blits
results: parsing output: 109/ for test: gem_pxp
results: parsing output: 110/ for test: kms_invalid_mode
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_addfb_basic
results: parsing output: 114/ for test: gem_exec_reloc
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_big_fb
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: kms_vblank
results: parsing output: 120/ for test: kms_big_fb
results: parsing output: 121/ for test: intel_hwmon
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_flip
results: parsing output: 124/ for test: kms_busy
results: parsing output: 125/ for test: gem_render_copy_redux
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: perf_pmu
results: parsing output: 130/ for test: gem_exec_schedule
results: parsing output: 131/ for test: kms_flip_scaled_crc
results: parsing output: 132/ for test: kms_fence_pin_leak
results: parsing output: 133/ for test: kms_cursor_crc
results: parsing output: 134/ for test: gem_exec_reloc
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_color
results: parsing output: 137/ for test: gem_exec_balancer
results: parsing output: 138/ for test: kms_cursor_crc
results: parsing output: 139/ for test: gem_exec_params
results: parsing output: 140/ for test: gem_exec_fence
results: parsing output: 141/ for test: gem_vm_create
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: kms_lease
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: gem_exec_reloc
results: parsing output: 146/ for test: kms_plane_scaling
results: parsing output: 147/ for test: kms_big_fb
results: parsing output: 148/ for test: gem_pread
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_frontbuffer_tracking
results: parsing output: 152/ for test: kms_flip
results: parsing output: 153/ for test: kms_pm_rpm
results: parsing output: 154/ for test: gem_lmem_swapping
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.