[89.629766] Initializing watchdogs
[89.629817]   /dev/watchdog0
[89.644061] [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]
[89.648699] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[89.651351] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[89.654757] [001/133] (960s left) syncobj_wait (multi-wait-submitted)
[89.728740] Starting subtest: multi-wait-submitted
[89.837124] Subtest multi-wait-submitted: SUCCESS (0.107s)
[89.919410] [002/133] (960s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[89.970435] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[89.974801] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[90.153617] [003/133] (960s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[90.254977] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[91.112880] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.860s)
[91.309983] [004/133] (958s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[91.419122] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[91.423234] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[91.519377] [005/133] (958s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[91.604982] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[91.607400] Starting dynamic subtest: pipe-A-eDP-1
[91.632589] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[91.642862] Starting dynamic subtest: pipe-B-eDP-1
[91.666457] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[91.673164] Starting dynamic subtest: pipe-C-eDP-1
[91.696773] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[91.703498] Starting dynamic subtest: pipe-D-eDP-1
[91.727214] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[91.731563] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[93.927437] [006/133] (956s left) perf_pmu (busy-double-start)
[93.974526] Starting subtest: busy-double-start
[93.976898] Starting dynamic subtest: rcs0
[97.618892] Dynamic subtest rcs0: FAIL (3.591s)
[97.628207] Starting dynamic subtest: bcs0
[101.139976] Dynamic subtest bcs0: SUCCESS (3.573s)
[101.150337] Starting dynamic subtest: vcs0
[105.453902] Dynamic subtest vcs0: SUCCESS (4.314s)
[105.465098] Starting dynamic subtest: vcs1
[109.416033] Dynamic subtest vcs1: SUCCESS (3.961s)
[109.433938] Starting dynamic subtest: vecs0
[113.452566] Dynamic subtest vecs0: SUCCESS (4.036s)
[113.462710] Starting dynamic subtest: ccs0
[117.086569] Dynamic subtest ccs0: FAIL (3.566s)
[117.088902] Subtest busy-double-start: FAIL (23.044s)
[117.128394] [007/133] (933s left) sysfs_heartbeat_interval (idempotent)
[117.195730] Starting subtest: idempotent
[117.198156] Starting dynamic subtest: vecs0
[117.200626] Dynamic subtest vecs0: SUCCESS (0.001s)
[117.212204] Starting dynamic subtest: ccs0
[117.214426] Dynamic subtest ccs0: SUCCESS (0.002s)
[117.221723] Starting dynamic subtest: vcs0
[117.223652] Dynamic subtest vcs0: SUCCESS (0.001s)
[117.231327] Starting dynamic subtest: bcs0
[117.233535] Dynamic subtest bcs0: SUCCESS (0.003s)
[117.241079] Starting dynamic subtest: rcs0
[117.242933] Dynamic subtest rcs0: SUCCESS (0.002s)
[117.250353] Starting dynamic subtest: vcs1
[117.252265] Dynamic subtest vcs1: SUCCESS (0.000s)
[117.254156] Subtest idempotent: SUCCESS (0.012s)
[117.292534] [008/133] (933s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[117.872348] Starting subtest: syncobj-timeline-invalid-flags
[117.875113] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[119.020431] [FACT gem_exec_fence (syncobj-timeline-invalid-flags)] new: kernel.kmod_is_loaded.vgem: true
[119.023607] [009/133] (931s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[119.151986] Starting subtest: cursorB-vs-flipA-toggle
[119.156457] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[119.307710] [010/133] (931s left) gem_spin_batch (legacy-resubmit-new)
[119.367705] Starting subtest: legacy-resubmit-new
[119.370213] Starting dynamic subtest: default
[119.376726] Dynamic subtest default: SUCCESS (0.009s)
[119.387818] Starting dynamic subtest: render
[119.390141] Dynamic subtest render: SUCCESS (0.006s)
[119.398610] Starting dynamic subtest: bsd
[119.400952] Dynamic subtest bsd: SUCCESS (0.004s)
[119.409060] Starting dynamic subtest: bsd1
[119.411017] Dynamic subtest bsd1: SUCCESS (0.004s)
[119.418382] Starting dynamic subtest: bsd2
[119.420333] Dynamic subtest bsd2: SUCCESS (0.003s)
[119.427703] Starting dynamic subtest: blt
[119.429652] Dynamic subtest blt: SUCCESS (0.004s)
[119.436992] Starting dynamic subtest: vebox
[119.438924] Dynamic subtest vebox: SUCCESS (0.005s)
[119.440883] Subtest legacy-resubmit-new: SUCCESS (0.037s)
[120.960319] [011/133] (929s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[121.010743] Starting subtest: etime-multi-wait-all-available-unsubmitted
[121.116637] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.105s)
[121.190183] [012/133] (929s left) gem_exec_balancer (parallel-balancer)
[121.447091] Starting subtest: parallel-balancer
[122.361053] Subtest parallel-balancer: SUCCESS (0.914s)
[122.471682] [013/133] (928s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[122.581862] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[122.584220] Starting dynamic subtest: pipe-A-default-mode
[123.705708] Dynamic subtest pipe-A-default-mode: SKIP (1.121s)
[123.710220] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.122s)
[123.848145] [014/133] (926s left) gem_ctx_persistence (file)
[123.900907] Starting subtest: file
[124.004064] Subtest file: SUCCESS (0.104s)
[124.120015] [015/133] (926s left) kms_flip (flip-vs-expired-vblank-interruptible)
[124.214410] Starting subtest: flip-vs-expired-vblank-interruptible
[124.216860] Starting dynamic subtest: A-eDP1
[132.893878] Dynamic subtest A-eDP1: SUCCESS (8.678s)
[132.908682] Starting dynamic subtest: B-eDP1
[141.522747] Dynamic subtest B-eDP1: SUCCESS (8.625s)
[141.533642] Starting dynamic subtest: C-eDP1
[150.138308] Dynamic subtest C-eDP1: SUCCESS (8.617s)
[150.146353] Starting dynamic subtest: D-eDP1
[158.775680] Dynamic subtest D-eDP1: SUCCESS (8.635s)
[158.780700] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.566s)
[159.942341] [016/133] (890s left) gem_exec_params (secure-non-root)
[160.016330] Starting subtest: secure-non-root
[160.020984] Subtest secure-non-root: SKIP (0.000s)
[160.136207] [017/133] (890s left) i915_query (query-topology-unsupported)
[160.185898] Starting subtest: query-topology-unsupported
[160.190403] Subtest query-topology-unsupported: SKIP (0.000s)
[160.298275] [018/133] (890s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[160.363374] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[160.433531] [019/133] (890s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[160.540361] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[167.494608] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.958s)
[167.606903] [020/133] (883s left) kms_color (legacy-gamma-reset)
[167.714158] Starting subtest: legacy-gamma-reset
[167.716102] Starting dynamic subtest: pipe-A-eDP-1
[168.088585] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.376s)
[168.098201] Starting dynamic subtest: pipe-B-eDP-1
[168.102972] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.013s)
[168.113454] Starting dynamic subtest: pipe-C-eDP-1
[168.115846] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.013s)
[168.123833] Starting dynamic subtest: pipe-D-eDP-1
[168.128794] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.013s)
[168.131157] Subtest legacy-gamma-reset: SUCCESS (0.415s)
[168.932243] [021/133] (882s left) gem_mmap_wc (close)
[168.984974] Subtest close: SKIP (0.000s)
[169.050237] [022/133] (881s left) kms_joiner (basic-force-big-joiner)
[169.153389] Starting subtest: basic-force-big-joiner
[169.157974] Subtest basic-force-big-joiner: SKIP (0.000s)
[169.219242] [023/133] (881s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[169.323558] Starting subtest: x-tiled-32bpp-rotate-180
[170.746447] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.423s)
[170.881992] [024/133] (880s left) gem_exec_schedule (semaphore-power)
[171.069668] Subtest semaphore-power: SKIP (0.000s)
[171.252335] [025/133] (879s left) dumb_buffer (map-valid)
[171.318057] Starting subtest: map-valid
[171.319971] Subtest map-valid: SUCCESS (0.000s)
[171.429610] [026/133] (879s left) kms_cursor_edge_walk (64x64-top-edge)
[171.521073] Starting subtest: 64x64-top-edge
[171.523303] Starting dynamic subtest: pipe-A-eDP-1
[174.993220] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.472s)
[175.002078] Starting dynamic subtest: pipe-D-eDP-1
[178.114012] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.121s)
[178.116283] Subtest 64x64-top-edge: SUCCESS (6.595s)
[178.916657] [027/133] (872s left) gem_userptr_blits (sd-probe)
[178.978907] Starting subtest: sd-probe
[178.981255] Subtest sd-probe: SUCCESS (0.000s)
[179.112197] [028/133] (872s left) kms_psr (fbc-pr-sprite-blt)
[179.203149] Starting subtest: fbc-pr-sprite-blt
[179.207631] Subtest fbc-pr-sprite-blt: SKIP (0.000s)
[179.288643] [029/133] (871s left) kms_psr (fbc-psr-primary-mmap-cpu)
[179.379744] Starting subtest: fbc-psr-primary-mmap-cpu
[179.382088] Starting dynamic subtest: eDP-1
[179.386437] Dynamic subtest eDP-1: SKIP (0.000s)
[179.390827] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.001s)
[179.457203] [030/133] (871s left) kms_cursor_crc (cursor-alpha-opaque)
[179.541825] Starting subtest: cursor-alpha-opaque
[179.543745] Starting dynamic subtest: pipe-A-eDP-1
[180.173534] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.632s)
[180.182903] Starting dynamic subtest: pipe-D-eDP-1
[181.486568] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.310s)
[181.488516] Subtest cursor-alpha-opaque: SUCCESS (1.944s)
[182.285199] [031/133] (869s left) gem_mmap_wc (pf-nonblock)
[182.350263] Subtest pf-nonblock: SKIP (0.000s)
[182.408197] [032/133] (869s left) kms_ccs (crc-sprite-planes-basic-yf-tiled-ccs)
[182.498071] Starting subtest: crc-sprite-planes-basic-yf-tiled-ccs
[182.500397] Starting dynamic subtest: pipe-A-eDP-1
[182.558511] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[182.566502] Starting dynamic subtest: pipe-B-eDP-1
[182.623238] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[182.632777] Starting dynamic subtest: pipe-C-eDP-1
[182.689550] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[182.699093] Starting dynamic subtest: pipe-D-eDP-1
[182.756522] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[182.760843] Subtest crc-sprite-planes-basic-yf-tiled-ccs: SKIP (0.008s)
[184.742146] [033/133] (866s left) gem_mmap_offset (bad-flags)
[184.804349] Starting subtest: bad-flags
[184.806814] Subtest bad-flags: SUCCESS (0.000s)
[184.919582] [034/133] (866s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[185.016186] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[189.633554] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.620s)
[190.752873] [035/133] (860s left) kms_addfb_basic (basic)
[190.810118] Starting subtest: basic
[190.812376] Subtest basic: SUCCESS (0.000s)
[190.950123] [036/133] (860s left) kms_flip (basic-flip-vs-dpms)
[191.060693] Starting subtest: basic-flip-vs-dpms
[191.063166] Starting dynamic subtest: A-eDP1
[193.645197] Dynamic subtest A-eDP1: SUCCESS (2.580s)
[193.654832] Starting dynamic subtest: B-eDP1
[195.819071] Dynamic subtest B-eDP1: SUCCESS (2.172s)
[195.828887] Starting dynamic subtest: C-eDP1
[197.900269] Dynamic subtest C-eDP1: SUCCESS (2.081s)
[197.909847] Starting dynamic subtest: D-eDP1
[199.973303] Dynamic subtest D-eDP1: SUCCESS (2.073s)
[199.977909] Subtest basic-flip-vs-dpms: SUCCESS (8.918s)
[200.811113] [037/133] (850s left) kms_rmfb (close-fd)
[200.922800] Starting subtest: close-fd
[200.924648] Starting dynamic subtest: pipe-A-eDP-1
[201.029949] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[201.039341] Starting dynamic subtest: pipe-B-eDP-1
[202.236215] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.205s)
[202.246431] Starting dynamic subtest: pipe-C-eDP-1
[203.357052] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.120s)
[203.365865] Starting dynamic subtest: pipe-D-eDP-1
[204.485110] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.127s)
[204.487495] Subtest close-fd: SUCCESS (3.562s)
[205.691606] [038/133] (846s left) gem_mmap (bad-offset)
[205.758609] Subtest bad-offset: SKIP (0.000s)
[205.837434] [039/133] (845s left) kms_pm_dc (dc6-dpms)
[206.934744] Starting subtest: dc6-dpms
[208.967662] Subtest dc6-dpms: SUCCESS (2.032s)
[209.045010] [040/133] (842s left) gem_partial_pwrite_pread (write-display)
[209.106561] Subtest write-display: SKIP (0.000s)
[209.174332] [041/133] (842s left) gem_ctx_isolation (preservation)
[209.223531] Starting subtest: preservation
[209.225959] Starting dynamic subtest: rcs0
[209.353968] Dynamic subtest rcs0: SUCCESS (0.130s)
[209.365584] Starting dynamic subtest: bcs0
[209.498135] Dynamic subtest bcs0: SUCCESS (0.144s)
[209.509187] Starting dynamic subtest: vcs0
[209.647947] Dynamic subtest vcs0: SUCCESS (0.148s)
[209.659208] Starting dynamic subtest: vcs1
[211.030420] Dynamic subtest vcs1: SUCCESS (1.381s)
[211.041884] Starting dynamic subtest: vecs0
[213.010825] Dynamic subtest vecs0: SUCCESS (1.980s)
[213.022076] Starting dynamic subtest: ccs0
[215.066850] Dynamic subtest ccs0: SUCCESS (2.056s)
[215.069588] Subtest preservation: SUCCESS (5.843s)
[215.307934] [042/133] (836s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[215.372706] Starting subtest: y-tiled-ccs-to-y-tiled
[215.377484] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[215.559465] [043/133] (836s left) dmabuf_sync_file (export-wait-after-attach)
[215.601440] Starting subtest: export-wait-after-attach
[215.603894] Subtest export-wait-after-attach: SUCCESS (0.001s)
[215.658946] [044/133] (836s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[215.759050] Starting subtest: yf-tiled-16bpp-rotate-180
[215.763397] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[215.851998] [045/133] (836s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-downscaling)
[215.954914] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-downscaling
[215.959811] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-downscaling: SKIP (0.000s)
[216.035868] [046/133] (836s left) i915_hangman (gt-engine-error)
[216.108249] Starting subtest: gt-engine-error
[216.110415] Starting dynamic subtest: rcs0
[217.912328] Dynamic subtest rcs0: SUCCESS (1.803s)
[217.924239] Starting dynamic subtest: bcs0
[219.893819] Dynamic subtest bcs0: SUCCESS (1.979s)
[219.906987] Starting dynamic subtest: vcs0
[221.879230] Dynamic subtest vcs0: SUCCESS (1.983s)
[221.890836] Starting dynamic subtest: vcs1
[223.862046] Dynamic subtest vcs1: SUCCESS (1.980s)
[223.873235] Starting dynamic subtest: vecs0
[225.909985] Dynamic subtest vecs0: SUCCESS (2.045s)
[225.921262] Starting dynamic subtest: ccs0
[227.895182] Dynamic subtest ccs0: SUCCESS (1.983s)
[227.897982] Subtest gt-engine-error: SUCCESS (11.787s)
[227.983919] [047/133] (824s left) kms_flip (2x-wf_vblank-ts-check-interruptible)
[228.086059] Starting subtest: 2x-wf_vblank-ts-check-interruptible
[228.098479] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.011s)
[228.171706] [048/133] (823s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-5)
[228.261463] Starting subtest: planes-unity-scaling-downscale-factor-0-5
[228.263574] Starting dynamic subtest: pipe-A
[228.286103] Dynamic subtest pipe-A: SKIP (0.018s)
[228.301297] Starting dynamic subtest: pipe-B
[228.315745] Dynamic subtest pipe-B: SKIP (0.031s)
[228.323574] Starting dynamic subtest: pipe-C
[228.349254] Dynamic subtest pipe-C: SKIP (0.032s)
[228.357319] Starting dynamic subtest: pipe-D
[228.381046] Dynamic subtest pipe-D: SKIP (0.032s)
[228.384800] Subtest planes-unity-scaling-downscale-factor-0-5: SKIP (0.114s)
[228.475310] [049/133] (823s left) syncobj_timeline (invalid-query-zero-handles)
[228.542253] Starting subtest: invalid-query-zero-handles
[228.544460] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[228.645323] [050/133] (823s left) gem_exec_capture (capture-recoverable)
[228.699251] Starting subtest: capture-recoverable
[228.704539] Subtest capture-recoverable: SUCCESS (0.003s)
[228.825492] [051/133] (823s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc)
[228.914921] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc
[235.984258] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (7.073s)
[236.108758] [052/133] (816s left) kms_tiled_display (basic-test-pattern)
[236.226976] Subtest basic-test-pattern: SKIP (0.000s)
[236.273844] [053/133] (816s left) gem_exec_reloc (basic-write-gtt)
[236.327020] Subtest basic-write-gtt: SKIP (0.000s)
[236.400613] [054/133] (815s left) kms_joiner (invalid-modeset-ultra-joiner)
[236.509064] Starting subtest: invalid-modeset-ultra-joiner
[236.513211] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[236.576130] [055/133] (815s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[236.671405] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[236.675582] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[236.760797] [056/133] (815s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[236.855177] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[236.859204] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[236.946302] [057/133] (815s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[237.048422] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[237.052139] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[237.128934] [058/133] (815s left) kms_pm_rpm (fences-dpms)
[239.058787] Starting subtest: fences-dpms
[239.064798] Subtest fences-dpms: SKIP (0.000s)
[239.434662] [059/133] (813s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[239.534176] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[239.538811] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[239.657431] [060/133] (812s left) kms_rotation_crc (primary-y-tiled-reflect-x-0)
[239.756626] Starting subtest: primary-y-tiled-reflect-x-0
[239.831567] Subtest primary-y-tiled-reflect-x-0: SKIP (0.071s)
[239.935671] [061/133] (812s left) gem_exec_schedule (u-submit-late-slice)
[240.022445] Starting subtest: u-submit-late-slice
[240.024646] Starting dynamic subtest: rcs0
[240.055659] Dynamic subtest rcs0: SUCCESS (0.034s)
[240.065384] Starting dynamic subtest: bcs0
[240.090517] Dynamic subtest bcs0: SUCCESS (0.036s)
[240.098286] Starting dynamic subtest: vcs0
[240.121857] Dynamic subtest vcs0: SUCCESS (0.031s)
[240.129978] Starting dynamic subtest: vcs1
[240.153079] Dynamic subtest vcs1: SUCCESS (0.030s)
[240.161694] Starting dynamic subtest: vecs0
[240.179618] Dynamic subtest vecs0: SUCCESS (0.026s)
[240.188131] Starting dynamic subtest: ccs0
[240.214286] Dynamic subtest ccs0: SUCCESS (0.034s)
[240.216438] Subtest u-submit-late-slice: SUCCESS (0.195s)
[241.951328] [062/133] (810s left) i915_drm_fdinfo (idle)
[241.989808] Starting subtest: idle
[241.992176] Starting dynamic subtest: rcs0
[242.556670] Dynamic subtest rcs0: SUCCESS (0.569s)
[242.567444] Starting dynamic subtest: bcs0
[243.063781] Dynamic subtest bcs0: SUCCESS (0.504s)
[243.074668] Starting dynamic subtest: vcs0
[243.569533] Dynamic subtest vcs0: SUCCESS (0.503s)
[243.580163] Starting dynamic subtest: vcs1
[244.074783] Dynamic subtest vcs1: SUCCESS (0.503s)
[244.085536] Starting dynamic subtest: vecs0
[244.583636] Dynamic subtest vecs0: SUCCESS (0.503s)
[244.594037] Starting dynamic subtest: ccs0
[245.085849] Dynamic subtest ccs0: SUCCESS (0.503s)
[245.088782] Subtest idle: SUCCESS (3.098s)
[245.180395] [063/133] (807s left) gem_set_tiling_vs_pwrite
[245.330828] [064/133] (807s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move)
[245.428292] Starting subtest: fbc-1p-primscrn-cur-indfb-move
[249.533310] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.108s)
[250.723086] [065/133] (802s left) kms_plane_cursor (viewport)
[250.823788] Starting subtest: viewport
[250.828338] Starting dynamic subtest: pipe-A-eDP-1-size-64
[253.407900] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.585s)
[253.467442] Starting dynamic subtest: pipe-A-eDP-1-size-128
[256.020032] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.560s)
[256.080071] Starting dynamic subtest: pipe-A-eDP-1-size-256
[258.554958] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.482s)
[258.912343] Starting dynamic subtest: pipe-B-eDP-1-size-64
[262.182177] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.278s)
[262.242187] Starting dynamic subtest: pipe-B-eDP-1-size-128
[264.871686] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.638s)
[264.931143] Starting dynamic subtest: pipe-B-eDP-1-size-256
[267.506531] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.582s)
[267.856565] Starting dynamic subtest: pipe-C-eDP-1-size-64
[270.742579] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.894s)
[270.778402] Starting dynamic subtest: pipe-C-eDP-1-size-128
[273.008135] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.236s)
[273.049455] Starting dynamic subtest: pipe-C-eDP-1-size-256
[275.241137] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.202s)
[275.577311] Starting dynamic subtest: pipe-D-eDP-1-size-64
[278.452246] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.884s)
[278.487799] Starting dynamic subtest: pipe-D-eDP-1-size-128
[280.740024] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.258s)
[280.781516] Starting dynamic subtest: pipe-D-eDP-1-size-256
[283.017130] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.247s)
[283.336357] Subtest viewport: SUCCESS (32.513s)
[284.226749] [066/133] (768s left) gem_userptr_blits (sync-unmap-after-close)
[284.293254] Starting subtest: sync-unmap-after-close
[284.302225] Subtest sync-unmap-after-close: SUCCESS (0.012s)
[284.425374] [067/133] (768s left) syncobj_timeline (reset-unsignaled)
[284.475755] Starting subtest: reset-unsignaled
[284.477661] Subtest reset-unsignaled: SUCCESS (0.000s)
[284.574258] [068/133] (768s left) prime_busy (before)
[284.633468] Starting subtest: before
[284.638434] Starting dynamic subtest: rcs0
[284.662198] Dynamic subtest rcs0: SUCCESS (0.025s)
[284.670895] Starting dynamic subtest: bcs0
[284.701399] Dynamic subtest bcs0: SUCCESS (0.039s)
[284.709767] Starting dynamic subtest: vcs0
[284.742694] Dynamic subtest vcs0: SUCCESS (0.040s)
[284.758516] Starting dynamic subtest: vcs1
[284.783063] Dynamic subtest vcs1: SUCCESS (0.039s)
[284.791050] Starting dynamic subtest: vecs0
[284.824708] Dynamic subtest vecs0: SUCCESS (0.040s)
[284.838857] Starting dynamic subtest: ccs0
[284.865709] Dynamic subtest ccs0: SUCCESS (0.039s)
[284.975264] Subtest before: SUCCESS (0.340s)
[285.063574] [069/133] (767s left) gem_exec_fence (syncobj-timeline-signal)
[285.626640] Starting subtest: syncobj-timeline-signal
[285.629286] Subtest syncobj-timeline-signal: SUCCESS (0.008s)
[287.023557] [070/133] (766s left) perf_pmu (most-busy-check-all)
[287.060933] Starting subtest: most-busy-check-all
[287.063251] Starting dynamic subtest: rcs0
[288.920800] Dynamic subtest rcs0: SUCCESS (1.857s)
[288.930518] Starting dynamic subtest: bcs0
[290.842384] Dynamic subtest bcs0: SUCCESS (1.918s)
[290.854053] Starting dynamic subtest: vcs0
[292.888467] Dynamic subtest vcs0: SUCCESS (2.043s)
[292.900672] Starting dynamic subtest: vcs1
[294.935611] Dynamic subtest vcs1: SUCCESS (2.044s)
[294.946961] Starting dynamic subtest: vecs0
[296.921099] Dynamic subtest vecs0: SUCCESS (1.981s)
[296.932619] Starting dynamic subtest: ccs0
[298.905271] Dynamic subtest ccs0: SUCCESS (1.981s)
[298.907952] Subtest most-busy-check-all: SUCCESS (11.844s)
[299.011281] [071/133] (754s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[299.118487] Starting subtest: y-tiled-16bpp-rotate-0
[299.122585] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[299.200766] [072/133] (753s left) gem_tiled_pread_pwrite
[299.313966] [073/133] (753s left) gem_ctx_sseu (mmap-args)
[299.363623] Subtest mmap-args: SKIP (0.000s)
[299.432083] [074/133] (753s left) gem_exec_suspend (basic)
[299.494060] Starting subtest: basic
[299.496270] Starting dynamic subtest: smem
[300.886908] Dynamic subtest smem: SUCCESS (1.394s)
[300.889605] Subtest basic: SUCCESS (1.396s)
[301.071507] [075/133] (752s left) gen9_exec_parse (bb-large)
[301.136313] Subtest bb-large: SKIP (0.000s)
[301.205924] [076/133] (752s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[301.301714] Starting subtest: psr-modesetfrombusy
[307.498130] Subtest psr-modesetfrombusy: SUCCESS (6.200s)
[307.637228] [077/133] (745s left) perf (non-system-wide-paranoid)
[307.769354] Starting subtest: non-system-wide-paranoid
[307.791469] Subtest non-system-wide-paranoid: SUCCESS (0.023s)
[307.843903] [078/133] (745s left) kms_chamelium_hpd (dp-hpd)
[308.235097] Subtest dp-hpd: SKIP (0.000s)
[309.014185] [079/133] (744s left) kms_chamelium_frames (dp-frame-dump)
[309.385889] Subtest dp-frame-dump: SKIP (0.000s)
[310.242767] [080/133] (743s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[310.347094] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-cpu
[317.093546] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.747s)
[317.207640] [081/133] (736s left) gem_gpgpu_fill (offset-16x16)
[317.261692] Starting subtest: offset-16x16
[317.266818] Subtest offset-16x16: SUCCESS (0.006s)
[317.396179] [082/133] (736s left) prime_mmap (test_forked_cpu_write)
[317.451084] Starting subtest: test_forked_cpu_write
[317.452990] Starting dynamic subtest: test_forked_cpu_write-smem
[317.458069] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.009s)
[317.460338] Subtest test_forked_cpu_write: SUCCESS (0.010s)
[317.568493] [083/133] (736s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[317.612632] Starting subtest: invalid-smem-bo-on-discrete
[317.616854] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[317.743968] [084/133] (735s left) kms_psr (pr-primary-blt)
[317.834706] Starting subtest: pr-primary-blt
[317.838932] Subtest pr-primary-blt: SKIP (0.000s)
[317.906284] [085/133] (735s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[318.005908] Starting subtest: plane-scaler-unity-scaling-with-rotation
[318.008161] Starting dynamic subtest: pipe-A
[318.265820] Dynamic subtest pipe-A: SUCCESS (0.262s)
[318.274424] Starting dynamic subtest: pipe-B
[319.587139] Dynamic subtest pipe-B: SUCCESS (1.320s)
[319.595817] Starting dynamic subtest: pipe-C
[320.942784] Dynamic subtest pipe-C: SUCCESS (1.354s)
[320.958162] Starting dynamic subtest: pipe-D
[322.268341] Dynamic subtest pipe-D: SUCCESS (1.324s)
[322.270666] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.265s)
[323.440316] [086/133] (730s left) syncobj_timeline (wait-any-interrupted)
[323.496825] Starting subtest: wait-any-interrupted
[323.599384] Subtest wait-any-interrupted: SUCCESS (0.103s)
[323.679749] [087/133] (730s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[323.780436] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[324.415992] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.637s)
[324.577724] [088/133] (729s left) kms_getfb (getfb-handle-zero)
[324.667748] Starting subtest: getfb-handle-zero
[324.669987] Subtest getfb-handle-zero: SUCCESS (0.000s)
[324.760094] [089/133] (729s left) kms_content_protection (dp-mst-type-0-hdcp14)
[324.890795] Starting subtest: dp-mst-type-0-hdcp14
[324.895295] Subtest dp-mst-type-0-hdcp14: SKIP (0.000s)
[325.014040] [090/133] (728s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[325.073750] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[325.075751] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[325.164931] [091/133] (728s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs)
[325.256217] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs
[325.258486] Starting dynamic subtest: pipe-A-eDP-1
[325.280272] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[325.295759] Starting dynamic subtest: pipe-B-eDP-1
[325.317197] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[325.325582] Starting dynamic subtest: pipe-C-eDP-1
[325.342694] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[325.350498] Starting dynamic subtest: pipe-D-eDP-1
[325.369066] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[325.372701] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[327.498921] [092/133] (726s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[327.601663] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[327.606090] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[327.673999] [093/133] (726s left) kms_atomic (plane-cursor-legacy)
[327.774734] Starting subtest: plane-cursor-legacy
[327.776959] Starting dynamic subtest: pipe-A-eDP-1
[328.145958] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.369s)
[328.148321] Subtest plane-cursor-legacy: SUCCESS (0.371s)
[329.043760] [094/133] (725s left) kms_psr (psr-sprite-render)
[329.145045] Starting subtest: psr-sprite-render
[329.147396] Starting dynamic subtest: eDP-1
[330.861101] Dynamic subtest eDP-1: SUCCESS (1.716s)
[330.863369] Subtest psr-sprite-render: SUCCESS (1.717s)
[331.694343] [095/133] (722s left) kms_vblank (wait-idle-hang)
[331.798716] Starting subtest: wait-idle-hang
[331.800960] Starting dynamic subtest: pipe-A-eDP-1
[358.682624] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.881s)
[358.695347] Starting dynamic subtest: pipe-D-eDP-1
[383.654823] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.968s)
[383.657382] Subtest wait-idle-hang: SUCCESS (51.854s)
[384.455834] [096/133] (669s left) kms_busy (extended-pageflip-hang-oldfb)
[384.559191] Starting subtest: extended-pageflip-hang-oldfb
[384.561603] Starting dynamic subtest: pipe-A
[384.950333] Dynamic subtest pipe-A: SUCCESS (0.390s)
[384.969557] Starting dynamic subtest: pipe-D
[386.086728] Dynamic subtest pipe-D: SUCCESS (1.134s)
[386.088960] Subtest extended-pageflip-hang-oldfb: SUCCESS (1.529s)
[386.913159] [097/133] (667s left) kms_mmap_write_crc (main)
[387.017564] Starting subtest: main
[387.019848] Starting dynamic subtest: pipe-A-eDP-1
[390.589068] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.567s)
[390.591380] Subtest main: SUCCESS (3.569s)
[391.446680] [098/133] (662s left) gem_madvise (dontneed-before-pwrite)
[391.482670] Starting subtest: dontneed-before-pwrite
[391.507870] Subtest dontneed-before-pwrite: SKIP (0.022s)
[391.574288] [099/133] (662s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[391.672532] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[391.676670] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[391.756579] [100/133] (662s left) gem_pxp (verify-pxp-stale-ctx-execution)
[391.875300] Starting subtest: verify-pxp-stale-ctx-execution
[392.932077] Subtest verify-pxp-stale-ctx-execution: SUCCESS (1.060s)
[393.054423] [101/133] (661s left) gem_mmap_gtt (coherency)
[393.113875] Subtest coherency: SKIP (0.000s)
[393.177940] [102/133] (661s left) kms_dirtyfb (default-dirtyfb-ioctl)
[393.270061] Starting subtest: default-dirtyfb-ioctl
[393.272322] Starting dynamic subtest: A-eDP-1
[394.896883] Dynamic subtest A-eDP-1: SUCCESS (1.626s)
[394.898770] Subtest default-dirtyfb-ioctl: SUCCESS (1.627s)
[395.778439] [103/133] (658s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[395.878430] Starting subtest: pr-overlay-plane-move-continuous-exceed-fully-sf
[395.882890] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[395.945815] [104/133] (658s left) kms_psr (fbc-pr-dpms)
[396.040419] Starting subtest: fbc-pr-dpms
[396.044414] Subtest fbc-pr-dpms: SKIP (0.000s)
[396.130601] [105/133] (658s left) gem_caching (reads)
[396.193704] Subtest reads: SKIP (0.000s)
[396.274536] [106/133] (658s left) gem_reg_read (timestamp-monotonic)
[396.336466] Starting subtest: timestamp-monotonic
[400.413285] Subtest timestamp-monotonic: SUCCESS (4.077s)
[400.486476] [107/133] (654s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[400.580343] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[400.584709] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[400.675679] [108/133] (653s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[400.765825] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[400.768153] Starting dynamic subtest: pipe-A-eDP-1
[400.790239] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[400.799340] Starting dynamic subtest: pipe-B-eDP-1
[400.820205] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[400.828758] Starting dynamic subtest: pipe-C-eDP-1
[400.850895] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[400.859320] Starting dynamic subtest: pipe-D-eDP-1
[400.881586] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[400.885594] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[403.023899] [109/133] (651s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier)
[403.114255] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier
[403.118332] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier: SKIP (0.000s)
[403.188538] [110/133] (651s left) gem_mmap_gtt (basic-wc)
[403.251033] Subtest basic-wc: SKIP (0.000s)
[403.314930] [111/133] (651s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[403.405448] Starting subtest: fbc-1p-pri-indfb-multidraw
[416.340197] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (12.939s)
[417.765802] [112/133] (637s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[417.866240] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[417.870612] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[417.952710] [113/133] (636s left) gem_exec_reloc (basic-gtt-active)
[418.007326] Subtest basic-gtt-active: SKIP (0.000s)
[418.073765] [114/133] (636s left) gem_pread (uncached)
[418.123402] Subtest uncached: SKIP (0.000s)
[418.188416] [115/133] (636s left) kms_psr (psr-cursor-plane-move)
[418.289689] Starting subtest: psr-cursor-plane-move
[418.291823] Starting dynamic subtest: eDP-1
[420.045822] Dynamic subtest eDP-1: SUCCESS (1.757s)
[420.048106] Subtest psr-cursor-plane-move: SUCCESS (1.758s)
[420.911716] [116/133] (634s left) i915_pm_rpm (gem-execbuf-stress)
[423.071064] Starting subtest: gem-execbuf-stress
[423.073324] Starting dynamic subtest: smem0
[435.989210] Dynamic subtest smem0: SUCCESS (12.920s)
[436.000900] Starting dynamic subtest: extra-wait-smem0
[498.915884] Dynamic subtest extra-wait-smem0: SUCCESS (62.921s)
[498.918589] Subtest gem-execbuf-stress: SUCCESS (75.847s)
[499.306582] [117/133] (555s left) kms_prop_blob (invalid-set-prop-any)
[499.362067] Starting subtest: invalid-set-prop-any
[499.364358] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[499.469418] [118/133] (555s left) api_intel_bb (intel-bb-blit-x)
[499.531052] Starting subtest: intel-bb-blit-x
[503.027240] Subtest intel-bb-blit-x: SUCCESS (3.496s)
[503.144861] [119/133] (551s left) i915_power (sanity)
[503.203278] Starting subtest: sanity
[507.251286] Subtest sanity: SUCCESS (4.042s)
[508.950300] [120/133] (546s left) gem_exec_schedule (preempt-other)
[509.023201] Starting subtest: preempt-other
[509.025518] Starting dynamic subtest: rcs0
[509.148210] Dynamic subtest rcs0: SUCCESS (0.128s)
[509.157974] Starting dynamic subtest: bcs0
[509.293990] Dynamic subtest bcs0: SUCCESS (0.146s)
[509.307544] Starting dynamic subtest: vcs0
[509.417664] Dynamic subtest vcs0: SUCCESS (0.123s)
[509.432136] Starting dynamic subtest: vcs1
[509.528742] Dynamic subtest vcs1: SUCCESS (0.112s)
[509.539937] Starting dynamic subtest: vecs0
[509.640538] Dynamic subtest vecs0: SUCCESS (0.111s)
[509.655856] Starting dynamic subtest: ccs0
[509.753753] Dynamic subtest ccs0: SUCCESS (0.113s)
[509.756229] Subtest preempt-other: SUCCESS (0.737s)
[511.027582] [121/133] (544s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-msflip-blt)
[511.131437] Starting subtest: fbcpsr-2p-scndscrn-indfb-msflip-blt
[511.135599] Subtest fbcpsr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[511.241291] [122/133] (543s left) perf (invalid-oa-exponent)
[511.341608] Starting subtest: invalid-oa-exponent
[511.350873] Subtest invalid-oa-exponent: SUCCESS (0.011s)
[511.395133] [123/133] (543s left) kms_cursor_crc (cursor-random-32x10)
[511.543639] Starting subtest: cursor-random-32x10
[511.617979] Subtest cursor-random-32x10: SKIP (0.068s)
[511.877856] [124/133] (543s left) kms_fence_pin_leak
[512.043699] [125/133] (543s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[512.153310] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[512.156731] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[512.251955] [126/133] (543s left) gem_tiled_fence_blits (normal)
[512.319550] Subtest normal: SKIP (0.000s)
[512.385652] [127/133] (543s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-pwrite)
[512.484067] Starting subtest: psr-1p-offscreen-pri-indfb-draw-pwrite
[519.538312] Subtest psr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.054s)
[519.650131] [128/133] (535s left) syncobj_timeline (etime-multi-wait-for-submit-available-unsubmitted)
[519.710662] Starting subtest: etime-multi-wait-for-submit-available-unsubmitted
[519.810506] Subtest etime-multi-wait-for-submit-available-unsubmitted: SUCCESS (0.101s)
[519.889695] [129/133] (535s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[519.955949] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[519.958457] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[520.054307] [130/133] (535s left) kms_explicit_fence (multiplane-atomic-fence-wait)
[520.186198] Starting subtest: multiplane-atomic-fence-wait
[520.506258] Subtest multiplane-atomic-fence-wait: SUCCESS (0.318s)
[521.655903] [131/133] (533s left) gem_create (create-ext-placement-sanity-check)
[521.834045] Starting subtest: create-ext-placement-sanity-check
[521.836939] Subtest create-ext-placement-sanity-check: SUCCESS (0.004s)
[521.937210] [132/133] (533s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[522.046622] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[522.048914] Starting dynamic subtest: pipe-A-eDP-1
[554.228753] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.791s)
[554.238598] Starting dynamic subtest: pipe-B-eDP-1
[587.175503] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.723s)
[587.185965] Starting dynamic subtest: pipe-C-eDP-1
[620.156619] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.643s)
[620.166747] Starting dynamic subtest: pipe-D-eDP-1
[653.120863] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.623s)
[653.123413] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SUCCESS (14.785s)
[654.353138] [133/133] (401s left) api_intel_allocator (gem-pool)
[654.410212] Starting subtest: gem-pool
[654.416110] Subtest gem-pool: SUCCESS (0.009s)
[654.537915] Closing watchdogs
results: parsing output: 0/ for test: syncobj_wait
results: parsing output: 1/ for test: gem_render_copy
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_ccs
results: parsing output: 5/ for test: perf_pmu
results: parsing output: 6/ for test: sysfs_heartbeat_interval
results: parsing output: 7/ for test: gem_exec_fence
results: parsing output: 8/ for test: kms_cursor_legacy
results: parsing output: 9/ for test: gem_spin_batch
results: parsing output: 10/ for test: syncobj_timeline
results: parsing output: 11/ for test: gem_exec_balancer
results: parsing output: 12/ for test: kms_flip_scaled_crc
results: parsing output: 13/ for test: gem_ctx_persistence
results: parsing output: 14/ for test: kms_flip
results: parsing output: 15/ for test: gem_exec_params
results: parsing output: 16/ for test: i915_query
results: parsing output: 17/ for test: gem_exec_reloc
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_color
results: parsing output: 20/ for test: gem_mmap_wc
results: parsing output: 21/ for test: kms_joiner
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: gem_exec_schedule
results: parsing output: 24/ for test: dumb_buffer
results: parsing output: 25/ for test: kms_cursor_edge_walk
results: parsing output: 26/ for test: gem_userptr_blits
results: parsing output: 27/ for test: kms_psr
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: kms_cursor_crc
results: parsing output: 30/ for test: gem_mmap_wc
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: gem_mmap_offset
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_addfb_basic
results: parsing output: 35/ for test: kms_flip
results: parsing output: 36/ for test: kms_rmfb
results: parsing output: 37/ for test: gem_mmap
results: parsing output: 38/ for test: kms_pm_dc
results: parsing output: 39/ for test: gem_partial_pwrite_pread
results: parsing output: 40/ for test: gem_ctx_isolation
results: parsing output: 41/ for test: gem_render_copy
results: parsing output: 42/ for test: dmabuf_sync_file
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: kms_flip_scaled_crc
results: parsing output: 45/ for test: i915_hangman
results: parsing output: 46/ for test: kms_flip
results: parsing output: 47/ for test: kms_plane_scaling
results: parsing output: 48/ for test: syncobj_timeline
results: parsing output: 49/ for test: gem_exec_capture
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_tiled_display
results: parsing output: 52/ for test: gem_exec_reloc
results: parsing output: 53/ for test: kms_joiner
results: parsing output: 54/ for test: kms_big_fb
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_pm_rpm
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_rotation_crc
results: parsing output: 60/ for test: gem_exec_schedule
results: parsing output: 61/ for test: i915_drm_fdinfo
results: parsing output: 62/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_plane_cursor
results: parsing output: 65/ for test: gem_userptr_blits
results: parsing output: 66/ for test: syncobj_timeline
results: parsing output: 67/ for test: prime_busy
results: parsing output: 68/ for test: gem_exec_fence
results: parsing output: 69/ for test: perf_pmu
results: parsing output: 70/ for test: kms_big_fb
results: parsing output: 71/ for test: gem_tiled_pread_pwrite
results: parsing output: 72/ for test: gem_ctx_sseu
results: parsing output: 73/ for test: gem_exec_suspend
results: parsing output: 74/ for test: gen9_exec_parse
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: perf
results: parsing output: 77/ for test: kms_chamelium_hpd
results: parsing output: 78/ for test: kms_chamelium_frames
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: gem_gpgpu_fill
results: parsing output: 81/ for test: prime_mmap
results: parsing output: 82/ for test: kms_addfb_basic
results: parsing output: 83/ for test: kms_psr
results: parsing output: 84/ for test: kms_plane_scaling
results: parsing output: 85/ for test: syncobj_timeline
results: parsing output: 86/ for test: kms_big_fb
results: parsing output: 87/ for test: kms_getfb
results: parsing output: 88/ for test: kms_content_protection
results: parsing output: 89/ for test: syncobj_wait
results: parsing output: 90/ for test: kms_ccs
results: parsing output: 91/ for test: kms_flip_scaled_crc
results: parsing output: 92/ for test: kms_atomic
results: parsing output: 93/ for test: kms_psr
results: parsing output: 94/ for test: kms_vblank
results: parsing output: 95/ for test: kms_busy
results: parsing output: 96/ for test: kms_mmap_write_crc
results: parsing output: 97/ for test: gem_madvise
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_pxp
results: parsing output: 100/ for test: gem_mmap_gtt
results: parsing output: 101/ for test: kms_dirtyfb
results: parsing output: 102/ for test: kms_psr2_sf
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: gem_caching
results: parsing output: 105/ for test: gem_reg_read
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: kms_plane
results: parsing output: 109/ for test: gem_mmap_gtt
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: gem_exec_reloc
results: parsing output: 113/ for test: gem_pread
results: parsing output: 114/ for test: kms_psr
results: parsing output: 115/ for test: i915_pm_rpm
results: parsing output: 116/ for test: kms_prop_blob
results: parsing output: 117/ for test: api_intel_bb
results: parsing output: 118/ for test: i915_power
results: parsing output: 119/ for test: gem_exec_schedule
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: perf
results: parsing output: 122/ for test: kms_cursor_crc
results: parsing output: 123/ for test: kms_fence_pin_leak
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_tiled_fence_blits
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: syncobj_timeline
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: kms_explicit_fence
results: parsing output: 130/ for test: gem_create
results: parsing output: 131/ for test: kms_ccs
results: parsing output: 132/ for test: api_intel_allocator
Done.