[57.793932] Initializing watchdogs
[57.793977]   /dev/watchdog0
[57.809252] [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]
[57.813872] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.816198] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.819555] [001/133] (960s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[57.937113] Starting subtest: 2x-absolute-wf_vblank-interruptible
[57.948834] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[58.067928] [002/133] (960s left) vgem_basic (create)
[58.147263] Starting subtest: create
[58.149582] Subtest create: SUCCESS (0.000s)
[58.203097] [FACT vgem_basic (create)] new: kernel.kmod_is_loaded.vgem: true
[58.206296] [003/133] (960s left) gem_exec_fence (basic-wait)
[58.391602] Starting subtest: basic-wait
[58.394107] Starting dynamic subtest: rcs0
[58.445906] Dynamic subtest rcs0: SUCCESS (0.054s)
[58.456083] Starting dynamic subtest: bcs0
[58.506312] Dynamic subtest bcs0: SUCCESS (0.059s)
[58.516027] Starting dynamic subtest: vcs0
[58.566069] Dynamic subtest vcs0: SUCCESS (0.059s)
[58.576059] Starting dynamic subtest: vcs1
[58.629986] Dynamic subtest vcs1: SUCCESS (0.062s)
[58.639636] Starting dynamic subtest: vecs0
[58.687837] Dynamic subtest vecs0: SUCCESS (0.057s)
[58.697848] Starting dynamic subtest: ccs0
[58.745638] Dynamic subtest ccs0: SUCCESS (0.056s)
[58.748612] Subtest basic-wait: SUCCESS (0.352s)
[60.964706] [004/133] (957s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[61.021483] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[61.084827] [005/133] (957s left) gem_softpin (invalid)
[61.149918] Starting subtest: invalid
[61.160812] Subtest invalid: SUCCESS (0.011s)
[61.288534] [006/133] (957s left) kms_psr (psr2-cursor-plane-onoff)
[61.391989] Starting subtest: psr2-cursor-plane-onoff
[61.394431] Starting dynamic subtest: eDP-1
[63.108742] Dynamic subtest eDP-1: SUCCESS (1.709s)
[63.111199] Subtest psr2-cursor-plane-onoff: SUCCESS (1.709s)
[63.917397] [007/133] (954s left) syncobj_wait (single-wait-for-submit-signaled)
[63.965953] Starting subtest: single-wait-for-submit-signaled
[63.968351] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[64.062800] [008/133] (954s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[64.117800] Starting subtest: etime-single-wait-for-submit-unsubmitted
[64.227438] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.108s)
[64.300917] [009/133] (954s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[64.395359] Starting subtest: psr-rgb101010-draw-mmap-wc
[71.925838] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (7.515s)
[72.054184] [010/133] (946s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[72.161136] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[72.165372] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[72.255627] [011/133] (946s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[72.312079] Starting subtest: y-tiled-ccs-to-yf-tiled
[72.316590] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[72.507089] [012/133] (946s left) gem_shrink (reclaim)
[72.566955] Starting subtest: reclaim
[75.067881] Subtest reclaim: SUCCESS (2.500s)
[75.203444] [013/133] (943s left) kms_dsc (dsc-with-output-formats)
[75.293321] Starting subtest: dsc-with-output-formats
[75.315198] Subtest dsc-with-output-formats: SKIP (0.001s)
[75.364482] [014/133] (943s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt)
[75.458268] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt
[75.462268] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[75.551671] [015/133] (943s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs-cc)
[75.640730] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs-cc
[75.642985] Starting dynamic subtest: pipe-A-eDP-1
[75.665637] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[75.675048] Starting dynamic subtest: pipe-B-eDP-1
[75.696356] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[75.704048] Starting dynamic subtest: pipe-C-eDP-1
[75.725776] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[75.733358] Starting dynamic subtest: pipe-D-eDP-1
[75.755197] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[75.759202] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[77.841101] [016/133] (941s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[77.895884] Starting subtest: etime-multi-wait-all-for-submit-submitted
[78.002103] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.106s)
[78.077184] [017/133] (940s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[78.170016] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[78.174291] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[78.265234] [018/133] (940s left) gen9_exec_parse (basic-rejected)
[78.325115] Subtest basic-rejected: SKIP (0.000s)
[78.399773] [019/133] (940s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[78.495416] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu
[78.499880] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[78.593342] [020/133] (940s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping)
[78.692873] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping
[78.697083] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping: SKIP (0.000s)
[78.768466] [021/133] (940s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[78.860071] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[78.863690] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[78.960918] [022/133] (940s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-pwrite)
[79.056991] Starting subtest: psr-2p-primscrn-cur-indfb-draw-pwrite
[79.060641] Subtest psr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[79.144886] [023/133] (940s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[79.207656] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[79.211614] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[79.403749] [024/133] (939s left) kms_flip (nonexisting-fb-interruptible)
[79.511926] Starting subtest: nonexisting-fb-interruptible
[79.514368] Starting dynamic subtest: A-eDP1
[80.731266] Dynamic subtest A-eDP1: SUCCESS (1.218s)
[80.738916] Starting dynamic subtest: B-eDP1
[81.864703] Dynamic subtest B-eDP1: SUCCESS (1.130s)
[81.874816] Starting dynamic subtest: C-eDP1
[83.001093] Dynamic subtest C-eDP1: SUCCESS (1.136s)
[83.012079] Starting dynamic subtest: D-eDP1
[84.128629] Dynamic subtest D-eDP1: SUCCESS (1.126s)
[84.133336] Subtest nonexisting-fb-interruptible: SUCCESS (4.618s)
[85.321559] [025/133] (934s left) kms_atomic_transition (plane-all-transition-fencing)
[85.424657] Starting subtest: plane-all-transition-fencing
[85.426991] Starting dynamic subtest: pipe-A-eDP-1
[87.932112] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.507s)
[88.236364] Starting dynamic subtest: pipe-B-eDP-1
[91.449490] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.219s)
[91.748080] Subtest plane-all-transition-fencing: SUCCESS (6.320s)
[92.576902] [026/133] (926s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[92.669613] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[92.671882] Starting dynamic subtest: pipe-A-eDP-1
[108.894766] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.943s)
[108.903887] Starting dynamic subtest: pipe-B-eDP-1
[126.878452] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.885s)
[126.887982] Starting dynamic subtest: pipe-C-eDP-1
[144.831171] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.820s)
[144.840374] Starting dynamic subtest: pipe-D-eDP-1
[162.828604] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.765s)
[162.831002] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (10.418s)
[169.992816] [027/133] (855s left) gem_userptr_blits (dmabuf-sync)
[170.073363] Starting subtest: dmabuf-sync
[170.079002] Subtest dmabuf-sync: SKIP (0.002s)
[170.213611] [028/133] (855s left) kms_panel_fitting (atomic-fastset)
[170.320353] Starting subtest: atomic-fastset
[170.323117] Starting dynamic subtest: pipe-A-eDP-1
[170.471707] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.147s)
[170.486770] Starting dynamic subtest: pipe-B-eDP-1
[171.682733] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.168s)
[171.694810] Starting dynamic subtest: pipe-C-eDP-1
[172.895356] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.177s)
[172.905645] Starting dynamic subtest: pipe-D-eDP-1
[174.060051] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.134s)
[174.063070] Subtest atomic-fastset: SUCCESS (3.630s)
[175.224283] [029/133] (850s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[175.334417] Starting subtest: read-crc-frame-sequence
[175.337351] Starting dynamic subtest: pipe-A-eDP-1
[176.079704] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.727s)
[176.091969] Starting dynamic subtest: pipe-B-eDP-1
[177.435165] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.334s)
[177.445255] Starting dynamic subtest: pipe-C-eDP-1
[178.732352] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.279s)
[178.745328] Starting dynamic subtest: pipe-D-eDP-1
[180.094117] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.343s)
[180.096459] Subtest read-crc-frame-sequence: SUCCESS (4.688s)
[180.937609] [030/133] (844s left) gem_render_tiled_blits (basic)
[181.018307] Subtest basic: SKIP (0.000s)
[181.085714] [031/133] (844s left) kms_vblank (crtc-id)
[181.185666] Starting subtest: crtc-id
[181.187974] Starting dynamic subtest: pipe-A-eDP-1
[181.631209] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.442s)
[181.647040] Starting dynamic subtest: pipe-D-eDP-1
[182.771275] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.129s)
[182.774254] Subtest crtc-id: SUCCESS (1.575s)
[183.619746] [032/133] (842s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[183.707587] Starting subtest: fbc-psr-cursor-mmap-cpu
[183.710287] Starting dynamic subtest: eDP-1
[183.715299] Dynamic subtest eDP-1: SKIP (0.000s)
[183.720453] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[183.783784] [033/133] (841s left) gem_lmem_swapping (heavy-verify-random-ccs)
[183.826071] Subtest heavy-verify-random-ccs: SKIP (0.000s)
[183.880673] [034/133] (841s left) gem_mmap_wc (write-prefaulted)
[183.936047] Subtest write-prefaulted: SKIP (0.000s)
[183.993242] [035/133] (841s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[184.108972] Starting subtest: short-flip-before-cursor-toggle
[185.026450] Subtest short-flip-before-cursor-toggle: SUCCESS (0.913s)
[185.870625] [036/133] (839s left) api_intel_bb (intel-bb-blit-none)
[185.925137] Starting subtest: intel-bb-blit-none
[190.153315] Subtest intel-bb-blit-none: SUCCESS (4.211s)
[190.263249] [037/133] (835s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[190.375842] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[191.370042] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (0.991s)
[191.492438] [038/133] (834s left) gen7_exec_parse (basic-allowed)
[191.547085] Subtest basic-allowed: SKIP (0.000s)
[191.620795] [039/133] (834s left) kms_flip (2x-blocking-wf_vblank)
[191.710716] Starting subtest: 2x-blocking-wf_vblank
[191.723074] Subtest 2x-blocking-wf_vblank: SKIP (0.013s)
[191.807742] [040/133] (834s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[191.897278] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[191.899815] Starting dynamic subtest: pipe-A
[191.919996] Dynamic subtest pipe-A: SKIP (0.018s)
[191.929006] Starting dynamic subtest: pipe-B
[191.951249] Dynamic subtest pipe-B: SKIP (0.031s)
[191.960054] Starting dynamic subtest: pipe-C
[191.983710] Dynamic subtest pipe-C: SKIP (0.031s)
[191.993747] Starting dynamic subtest: pipe-D
[192.021942] Dynamic subtest pipe-D: SKIP (0.039s)
[192.025686] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.122s)
[192.114468] [041/133] (833s left) syncobj_timeline (wait-any-interrupted)
[192.177679] Starting subtest: wait-any-interrupted
[192.280473] Subtest wait-any-interrupted: SUCCESS (0.101s)
[192.361829] [042/133] (833s left) gem_spin_batch (resubmit-all)
[192.421026] Starting subtest: resubmit-all
[192.423399] Starting dynamic subtest: rcs0
[192.435289] Dynamic subtest rcs0: SUCCESS (0.014s)
[192.447979] Starting dynamic subtest: bcs0
[192.450382] Dynamic subtest bcs0: SUCCESS (0.003s)
[192.459296] Starting dynamic subtest: vcs0
[192.461608] Dynamic subtest vcs0: SUCCESS (0.002s)
[192.470227] Starting dynamic subtest: vcs1
[192.472419] Dynamic subtest vcs1: SUCCESS (0.002s)
[192.482395] Starting dynamic subtest: vecs0
[192.484875] Dynamic subtest vecs0: SUCCESS (0.002s)
[192.493137] Starting dynamic subtest: ccs0
[192.495321] Dynamic subtest ccs0: SUCCESS (0.002s)
[192.497827] Subtest resubmit-all: SUCCESS (0.028s)
[193.690057] [043/133] (832s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[193.803191] Starting subtest: plane-all-modeset-transition-internal-panels
[193.806042] Starting dynamic subtest: pipe-A-eDP-1
[195.136843] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.331s)
[195.423997] Starting dynamic subtest: pipe-B-eDP-1
[196.470739] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.052s)
[196.743414] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.937s)
[197.594475] [044/133] (828s left) kms_flip (2x-flip-vs-dpms-on-nop)
[197.688110] Starting subtest: 2x-flip-vs-dpms-on-nop
[197.699258] Subtest 2x-flip-vs-dpms-on-nop: SKIP (0.010s)
[197.781829] [045/133] (828s left) gem_exec_params (cliprects-invalid)
[197.840438] Starting subtest: cliprects-invalid
[197.843359] Subtest cliprects-invalid: SUCCESS (0.000s)
[197.962942] [046/133] (828s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[198.064641] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[199.156221] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.092s)
[199.266639] [047/133] (827s left) gem_exec_reloc (basic-wc-read-noreloc)
[199.323260] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[199.395597] [048/133] (826s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-sf-dmg-area)
[199.494643] Starting subtest: fbc-psr2-overlay-plane-update-sf-dmg-area
[199.496659] Starting dynamic subtest: pipe-A-eDP-1
[199.604059] Dynamic subtest pipe-A-eDP-1: SKIP (0.111s)
[199.614194] Starting dynamic subtest: pipe-B-eDP-1
[200.768136] Dynamic subtest pipe-B-eDP-1: SKIP (1.160s)
[200.773145] Subtest fbc-psr2-overlay-plane-update-sf-dmg-area: SKIP (1.275s)
[201.955096] [049/133] (824s left) gem_ctx_persistence (legacy-engines-persistence)
[202.010413] Starting subtest: legacy-engines-persistence
[202.013190] Starting dynamic subtest: render
[202.779280] Dynamic subtest render: SUCCESS (0.768s)
[202.789212] Starting dynamic subtest: bsd1
[203.546438] Dynamic subtest bsd1: SUCCESS (0.765s)
[203.557602] Starting dynamic subtest: bsd2
[204.310396] Dynamic subtest bsd2: SUCCESS (0.761s)
[204.321416] Starting dynamic subtest: blt
[205.110776] Dynamic subtest blt: SUCCESS (0.797s)
[205.122212] Starting dynamic subtest: vebox
[205.865841] Dynamic subtest vebox: SUCCESS (0.752s)
[205.868832] Subtest legacy-engines-persistence: SUCCESS (3.855s)
[205.950816] [050/133] (820s left) sysfs_heartbeat_interval (mixed)
[206.002801] Starting subtest: mixed
[206.005247] Starting dynamic subtest: vecs0
[211.234964] Dynamic subtest vecs0: SUCCESS (5.229s)
[211.245355] Starting dynamic subtest: ccs0
[216.427392] Dynamic subtest ccs0: SUCCESS (5.188s)
[216.438985] Starting dynamic subtest: vcs0
[221.653279] Dynamic subtest vcs0: SUCCESS (5.222s)
[221.663618] Starting dynamic subtest: bcs0
[226.852333] Dynamic subtest bcs0: SUCCESS (5.194s)
[226.862129] Starting dynamic subtest: rcs0
[232.095275] Dynamic subtest rcs0: SUCCESS (5.238s)
[232.107058] Starting dynamic subtest: vcs1
[237.339377] Dynamic subtest vcs1: SUCCESS (5.240s)
[237.342333] Subtest mixed: SUCCESS (31.323s)
[237.428018] [051/133] (789s left) gem_exec_fence (syncobj-timeline-chain-engines)
[238.008332] Starting subtest: syncobj-timeline-chain-engines
[238.203412] Subtest syncobj-timeline-chain-engines: SUCCESS (0.195s)
[239.724013] [052/133] (786s left) kms_plane_lowres (tiling-yf)
[239.836472] Starting subtest: tiling-yf
[239.841920] Subtest tiling-yf: SKIP (0.000s)
[239.910002] [053/133] (786s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[240.014035] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[246.609182] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.595s)
[246.727145] [054/133] (779s left) kms_addfb_basic (too-wide)
[246.826198] Starting subtest: too-wide
[246.828967] Subtest too-wide: SUCCESS (0.000s)
[246.925264] [055/133] (779s left) gem_exec_schedule (u-semaphore-resolve)
[246.998096] Starting subtest: u-semaphore-resolve
[247.023641] Subtest u-semaphore-resolve: SUCCESS (0.038s)
[247.370214] [056/133] (779s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[247.466091] Starting subtest: fbcpsr-rgb565-draw-blt
[254.499785] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (7.035s)
[254.625293] [057/133] (772s left) i915_pm_rpm (gem-execbuf)
[256.758590] Starting subtest: gem-execbuf
[256.761454] Starting dynamic subtest: smem0
[258.497171] Dynamic subtest smem0: SUCCESS (1.735s)
[258.499907] Subtest gem-execbuf: SUCCESS (1.737s)
[258.581932] [058/133] (768s left) kms_cursor_crc (cursor-offscreen-512x512)
[258.866247] Starting subtest: cursor-offscreen-512x512
[258.871400] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[259.037237] [059/133] (767s left) kms_pm_dc (dc6-psr)
[260.134359] Starting subtest: dc6-psr
[263.030301] Subtest dc6-psr: SUCCESS (2.894s)
[263.894929] [060/133] (762s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[264.291011] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[265.096319] [061/133] (761s left) gem_exec_await (wide-all)
[265.282416] Starting subtest: wide-all
[288.588213] Subtest wide-all: SUCCESS (23.308s)
[288.797486] [062/133] (738s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[288.957101] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[288.959659] Starting dynamic subtest: pipe-A-eDP-1
[288.980915] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[288.993271] Starting dynamic subtest: pipe-B-eDP-1
[289.016119] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[289.024393] Starting dynamic subtest: pipe-C-eDP-1
[289.043808] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[289.052026] Starting dynamic subtest: pipe-D-eDP-1
[289.072599] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[289.074579] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.008s)
[291.283068] [063/133] (735s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[291.383400] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[298.677037] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.297s)
[299.809014] [064/133] (727s left) gem_request_retire (retire-vma-not-inactive)
[299.875582] Starting subtest: retire-vma-not-inactive
[299.936980] Subtest retire-vma-not-inactive: SUCCESS (0.059s)
[300.049244] [065/133] (727s left) perf_pmu (frequency)
[300.090561] Starting subtest: frequency
[300.096180] Starting dynamic subtest: gt0
[302.231345] Dynamic subtest gt0: SUCCESS (2.132s)
[302.245295] Starting dynamic subtest: idle-gt0
[302.736707] Dynamic subtest idle-gt0: SUCCESS (0.504s)
[302.749202] Starting dynamic subtest: gt1
[307.636601] Dynamic subtest gt1: SUCCESS (4.894s)
[307.649284] Starting dynamic subtest: idle-gt1
[308.141095] Dynamic subtest idle-gt1: SUCCESS (0.505s)
[308.143450] Subtest frequency: SUCCESS (8.054s)
[308.253512] [066/133] (718s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[308.371276] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[315.496051] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.130s)
[315.620683] [067/133] (711s left) kms_sequence (queue-idle)
[315.712688] Starting subtest: queue-idle
[315.715531] Starting dynamic subtest: pipe-A-eDP-1
[317.529929] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.818s)
[317.540818] Starting dynamic subtest: pipe-B-eDP-1
[320.010544] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.478s)
[320.021436] Starting dynamic subtest: pipe-C-eDP-1
[322.438583] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.426s)
[322.449151] Starting dynamic subtest: pipe-D-eDP-1
[324.878344] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.438s)
[324.881302] Subtest queue-idle: SUCCESS (9.166s)
[325.688679] [068/133] (701s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[325.790288] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[325.796113] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[325.885702] [069/133] (701s left) kms_content_protection (dp-mst-type-0)
[326.037450] Starting subtest: dp-mst-type-0
[326.042299] Subtest dp-mst-type-0: SKIP (0.000s)
[326.152513] [070/133] (701s left) perf (non-zero-reason)
[326.307118] Starting subtest: non-zero-reason
[326.309921] Starting dynamic subtest: 0-rcs0
[331.715077] Dynamic subtest 0-rcs0: SUCCESS (5.407s)
[331.729427] Starting dynamic subtest: 1-vecs0
[335.629177] Dynamic subtest 1-vecs0: SUCCESS (3.912s)
[335.632334] Subtest non-zero-reason: SUCCESS (9.321s)
[335.677154] [071/133] (691s left) perf_pmu (rc6-all-gts)
[335.720284] Starting subtest: rc6-all-gts
[342.074090] Subtest rc6-all-gts: SUCCESS (6.354s)
[343.080299] [072/133] (684s left) gem_create (create-massive)
[343.139965] Starting subtest: create-massive
[343.142091] Subtest create-massive: SUCCESS (0.000s)
[343.347289] [073/133] (684s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[343.447008] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[347.984983] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.540s)
[349.193736] [074/133] (678s left) kms_cursor_crc (cursor-size-hints)
[349.335177] Starting subtest: cursor-size-hints
[349.338016] Starting dynamic subtest: pipe-A-eDP-1
[349.980401] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.646s)
[349.990536] Starting dynamic subtest: pipe-D-eDP-1
[351.271092] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.290s)
[351.273574] Subtest cursor-size-hints: SUCCESS (1.939s)
[352.154380] [075/133] (675s left) gem_exec_reloc (basic-softpin)
[352.228213] Subtest basic-softpin: SKIP (0.000s)
[352.298489] [076/133] (675s left) syncobj_wait (multi-wait-submitted-signaled)
[352.351417] Starting subtest: multi-wait-submitted-signaled
[352.354273] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[352.459115] [077/133] (675s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[352.576531] Starting subtest: 4-tiled-32bpp-rotate-270
[352.597274] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.020s)
[352.703698] [078/133] (674s left) kms_flip (2x-flip-vs-expired-vblank)
[352.807162] Starting subtest: 2x-flip-vs-expired-vblank
[352.821002] Subtest 2x-flip-vs-expired-vblank: SKIP (0.012s)
[352.919239] [079/133] (674s left) kms_chamelium_color (ctm-limited-range)
[353.015395] Subtest ctm-limited-range: SKIP (0.000s)
[353.061214] [080/133] (674s left) kms_vrr (flip-dpms)
[353.165396] Starting subtest: flip-dpms
[353.172567] Subtest flip-dpms: SKIP (0.000s)
[353.239799] [081/133] (674s left) gem_ctx_shared (q-out-order)
[353.298357] Starting subtest: Q-out-order
[353.300988] Starting dynamic subtest: rcs0
[353.419648] Dynamic subtest rcs0: SUCCESS (0.119s)
[353.437324] Starting dynamic subtest: vcs0
[353.515417] Dynamic subtest vcs0: SUCCESS (0.096s)
[353.533823] Starting dynamic subtest: vcs1
[353.606992] Dynamic subtest vcs1: SUCCESS (0.091s)
[353.625138] Starting dynamic subtest: vecs0
[353.693016] Dynamic subtest vecs0: SUCCESS (0.084s)
[353.710916] Starting dynamic subtest: ccs0
[353.771076] Dynamic subtest ccs0: SUCCESS (0.076s)
[353.773777] Subtest Q-out-order: SUCCESS (0.474s)
[354.779561] [082/133] (673s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[354.880488] Starting subtest: fbc-rgb101010-draw-mmap-wc
[359.456084] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (4.576s)
[360.560662] [083/133] (667s left) kms_psr (psr-primary-blt)
[360.666165] Starting subtest: psr-primary-blt
[360.668809] Starting dynamic subtest: eDP-1
[362.453136] Dynamic subtest eDP-1: SUCCESS (1.786s)
[362.455212] Subtest psr-primary-blt: SUCCESS (1.787s)
[363.323885] [084/133] (664s left) gem_exec_schedule (out-order)
[363.404391] Starting subtest: out-order
[363.406908] Starting dynamic subtest: rcs0
[363.491336] Dynamic subtest rcs0: SUCCESS (0.098s)
[363.504940] Starting dynamic subtest: vcs0
[363.578060] Dynamic subtest vcs0: SUCCESS (0.086s)
[363.590652] Starting dynamic subtest: vcs1
[363.668818] Dynamic subtest vcs1: SUCCESS (0.089s)
[363.682287] Starting dynamic subtest: vecs0
[363.745472] Dynamic subtest vecs0: SUCCESS (0.077s)
[363.760249] Starting dynamic subtest: ccs0
[363.836284] Dynamic subtest ccs0: SUCCESS (0.090s)
[363.839285] Subtest out-order: SUCCESS (0.443s)
[364.744654] [085/133] (663s left) kms_big_fb (x-tiled-8bpp-rotate-90)
[364.837807] Starting subtest: x-tiled-8bpp-rotate-90
[364.862001] Subtest x-tiled-8bpp-rotate-90: SKIP (0.024s)
[364.969252] [086/133] (663s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[365.072002] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[365.083864] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.010s)
[365.161749] [087/133] (662s left) syncobj_timeline (multi-wait-all-available-submitted-signaled)
[365.217965] Starting subtest: multi-wait-all-available-submitted-signaled
[365.220357] Subtest multi-wait-all-available-submitted-signaled: SUCCESS (0.000s)
[365.312327] [088/133] (662s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[365.441947] Starting subtest: 2x-long-cursor-vs-flip-legacy
[365.446415] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.001s)
[365.612998] [089/133] (662s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[365.669248] Starting subtest: multi-wait-for-submit-available-signaled
[365.671996] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s)
[365.780817] [090/133] (662s left) i915_hangman (engine-error-state-capture)
[365.843984] Starting subtest: engine-error-state-capture
[365.845715] Starting dynamic subtest: rcs0
[369.614442] Dynamic subtest rcs0: SUCCESS (3.768s)
[369.628440] Starting dynamic subtest: bcs0
[375.631583] Dynamic subtest bcs0: SUCCESS (6.012s)
[375.645052] Starting dynamic subtest: vcs0
[380.560695] Dynamic subtest vcs0: SUCCESS (4.925s)
[380.573844] Starting dynamic subtest: vcs1
[384.589994] Dynamic subtest vcs1: SUCCESS (4.025s)
[384.603173] Starting dynamic subtest: vecs0
[390.606292] Dynamic subtest vecs0: SUCCESS (6.011s)
[390.619030] Starting dynamic subtest: ccs0
[396.622597] Dynamic subtest ccs0: SUCCESS (6.014s)
[396.625755] Subtest engine-error-state-capture: SUCCESS (30.772s)
[396.707546] [091/133] (631s left) kms_atomic_transition (modeset-transition-nonblocking)
[396.802818] Starting subtest: modeset-transition-nonblocking
[396.805003] Starting dynamic subtest: 1x-outputs
[407.823214] Dynamic subtest 1x-outputs: SUCCESS (11.024s)
[407.825725] Subtest modeset-transition-nonblocking: SUCCESS (11.026s)
[408.681713] [092/133] (619s left) kms_invalid_mode (bad-hsync-start)
[408.784439] Starting subtest: bad-hsync-start
[408.786898] Starting dynamic subtest: pipe-A-eDP-1
[409.071034] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[409.093295] Starting dynamic subtest: pipe-B-eDP-1
[409.095885] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[409.109151] Starting dynamic subtest: pipe-C-eDP-1
[409.111433] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[409.121040] Starting dynamic subtest: pipe-D-eDP-1
[409.123280] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[409.125863] Subtest bad-hsync-start: SUCCESS (0.293s)
[409.907733] [093/133] (618s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[410.014457] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip
[410.019312] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[410.108607] [094/133] (618s left) api_intel_allocator (alloc-simple)
[410.162305] Starting subtest: alloc-simple
[410.168354] Subtest alloc-simple: SUCCESS (0.006s)
[410.291301] [095/133] (618s left) fbdev (unaligned-read)
[410.343981] Starting subtest: unaligned-read
[410.358802] Subtest unaligned-read: SUCCESS (0.015s)
[410.415293] [096/133] (618s left) i915_query (test-query-geometry-subslices)
[410.470682] Starting subtest: test-query-geometry-subslices
[410.473255] Subtest test-query-geometry-subslices: SUCCESS (0.000s)
[410.565972] [097/133] (617s left) sriov_basic (enable-vfs-autoprobe-on)
[410.612889] Starting subtest: enable-vfs-autoprobe-on
[410.615438] Starting dynamic subtest: numvfs-1
[410.706153] Dynamic subtest numvfs-1: FAIL (0.030s)
[410.716965] Starting dynamic subtest: numvfs-2
[410.803157] Dynamic subtest numvfs-2: FAIL (0.004s)
[410.813991] Starting dynamic subtest: numvfs-3
[410.886653] Dynamic subtest numvfs-3: FAIL (0.003s)
[410.896830] Starting dynamic subtest: numvfs-4
[410.968930] Dynamic subtest numvfs-4: FAIL (0.003s)
[410.979812] Starting dynamic subtest: numvfs-5
[411.051358] Dynamic subtest numvfs-5: FAIL (0.004s)
[411.061940] Starting dynamic subtest: numvfs-6
[411.135153] Dynamic subtest numvfs-6: FAIL (0.004s)
[411.145140] Starting dynamic subtest: numvfs-7
[411.219821] Dynamic subtest numvfs-7: FAIL (0.003s)
[411.230205] Starting dynamic subtest: numvfs-random
[411.301158] Dynamic subtest numvfs-random: FAIL (0.004s)
[411.311953] Starting dynamic subtest: numvfs-all
[411.385395] Dynamic subtest numvfs-all: FAIL (0.531s)
[411.387665] Subtest enable-vfs-autoprobe-on: FAIL (0.594s)
[411.427118] [098/133] (617s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[411.529197] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[418.209721] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.684s)
[418.337033] [099/133] (610s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[418.442127] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[418.444723] Starting dynamic subtest: pipe-A-eDP-1
[418.516923] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[418.528296] Starting dynamic subtest: pipe-B-eDP-1
[419.608115] Dynamic subtest pipe-B-eDP-1: SKIP (1.088s)
[419.613329] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.168s)
[420.807291] [100/133] (607s left) kms_psr (pr-cursor-mmap-gtt)
[420.902297] Starting subtest: pr-cursor-mmap-gtt
[420.906779] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[420.978241] [101/133] (607s left) gem_mmap_offset (bad-extensions)
[421.033302] Starting subtest: bad-extensions
[421.036189] Subtest bad-extensions: SUCCESS (0.000s)
[421.142108] [102/133] (607s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[421.237237] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[421.242239] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[421.334858] [103/133] (607s left) kms_pm_rpm (modeset-non-lpsp)
[422.857356] Starting subtest: modeset-non-lpsp
[424.009776] Subtest modeset-non-lpsp: SKIP (1.153s)
[424.390482] [104/133] (604s left) syncobj_wait (multi-wait-submitted)
[424.443255] Starting subtest: multi-wait-submitted
[424.548623] Subtest multi-wait-submitted: SUCCESS (0.105s)
[424.632914] [105/133] (604s left) gem_ctx_persistence (engines-hang)
[424.692247] Starting subtest: engines-hang
[424.694656] Starting dynamic subtest: rcs0
[424.758689] Dynamic subtest rcs0: SUCCESS (0.067s)
[424.772434] Starting dynamic subtest: bcs0
[424.842821] Dynamic subtest bcs0: SUCCESS (0.082s)
[424.855720] Starting dynamic subtest: vcs0
[424.932150] Dynamic subtest vcs0: SUCCESS (0.087s)
[424.944755] Starting dynamic subtest: vcs1
[425.026810] Dynamic subtest vcs1: SUCCESS (0.093s)
[425.042136] Starting dynamic subtest: vecs0
[425.115659] Dynamic subtest vecs0: SUCCESS (0.087s)
[425.127769] Starting dynamic subtest: ccs0
[425.214432] Dynamic subtest ccs0: SUCCESS (0.097s)
[425.217393] Subtest engines-hang: SUCCESS (0.525s)
[425.310197] [106/133] (603s left) kms_color_pipeline (plane-lut1d-ctm3x4)
[425.406169] Starting subtest: plane-lut1d-ctm3x4
[425.529728] Starting dynamic subtest: pipe-A-plane-0
[425.708275] Dynamic subtest pipe-A-plane-0: SUCCESS (0.178s)
[425.719130] Starting dynamic subtest: pipe-A-plane-1
[425.862701] Dynamic subtest pipe-A-plane-1: SUCCESS (0.155s)
[425.872896] Starting dynamic subtest: pipe-A-plane-2
[426.017959] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[427.190420] Starting dynamic subtest: pipe-B-plane-0
[427.349142] Dynamic subtest pipe-B-plane-0: SUCCESS (0.166s)
[427.360222] Starting dynamic subtest: pipe-B-plane-1
[427.496069] Dynamic subtest pipe-B-plane-1: SUCCESS (0.144s)
[427.506404] Starting dynamic subtest: pipe-B-plane-2
[427.683298] Dynamic subtest pipe-B-plane-2: SUCCESS (0.188s)
[428.908846] Starting dynamic subtest: pipe-C-plane-0
[429.056288] Dynamic subtest pipe-C-plane-0: SUCCESS (0.155s)
[429.069683] Starting dynamic subtest: pipe-C-plane-1
[429.213293] Dynamic subtest pipe-C-plane-1: SUCCESS (0.155s)
[429.228405] Starting dynamic subtest: pipe-C-plane-2
[429.367125] Dynamic subtest pipe-C-plane-2: SUCCESS (0.155s)
[430.529249] Starting dynamic subtest: pipe-D-plane-0
[430.688420] Dynamic subtest pipe-D-plane-0: SUCCESS (0.166s)
[430.699481] Starting dynamic subtest: pipe-D-plane-1
[430.855906] Dynamic subtest pipe-D-plane-1: SUCCESS (0.166s)
[430.867349] Starting dynamic subtest: pipe-D-plane-2
[431.021127] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[431.320145] Subtest plane-lut1d-ctm3x4: SUCCESS (5.915s)
[432.128331] [107/133] (596s left) kms_psr (fbc-psr2-primary-page-flip)
[432.221188] Starting subtest: fbc-psr2-primary-page-flip
[432.223870] Starting dynamic subtest: eDP-1
[432.229050] Dynamic subtest eDP-1: SKIP (0.000s)
[432.234219] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[432.307972] [108/133] (596s left) gem_exec_whisper (basic-queues)
[432.370796] Starting subtest: basic-queues
[437.623771] Subtest basic-queues: SUCCESS (5.249s)
[437.808203] [109/133] (591s left) kms_lease (simple-lease)
[437.908326] Starting subtest: simple-lease
[437.910884] Starting dynamic subtest: pipe-A-eDP-1
[438.410266] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.502s)
[438.422750] Starting dynamic subtest: pipe-B-eDP-1
[439.861036] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.448s)
[439.871433] Starting dynamic subtest: pipe-C-eDP-1
[441.273328] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.408s)
[441.283531] Starting dynamic subtest: pipe-D-eDP-1
[442.675599] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.403s)
[442.677893] Subtest simple-lease: SUCCESS (4.769s)
[443.501146] [110/133] (585s left) gem_exec_schedule (implicit-boths)
[443.575026] Starting subtest: implicit-boths
[443.577267] Starting dynamic subtest: rcs0
[443.677175] Dynamic subtest rcs0: SUCCESS (0.116s)
[443.689012] Starting dynamic subtest: vcs0
[443.762549] Dynamic subtest vcs0: SUCCESS (0.085s)
[443.774953] Starting dynamic subtest: vcs1
[443.854403] Dynamic subtest vcs1: SUCCESS (0.091s)
[443.867280] Starting dynamic subtest: vecs0
[443.939596] Dynamic subtest vecs0: SUCCESS (0.084s)
[443.952420] Starting dynamic subtest: ccs0
[444.023942] Dynamic subtest ccs0: SUCCESS (0.084s)
[444.026206] Subtest implicit-boths: SUCCESS (0.463s)
[445.705195] [111/133] (583s left) drm_buddy (drm_buddy)
[445.759326] Starting subtest: drm_buddy
[445.765036] Subtest drm_buddy: SKIP (0.000s)
[445.819444] [112/133] (583s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[445.916101] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[445.920338] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[446.012585] [113/133] (583s left) gem_linear_blits (basic)
[446.241415] Starting subtest: basic
[446.247929] Subtest basic: SUCCESS (0.006s)
[446.371101] [114/133] (582s left) kms_plane (plane-position-hole)
[446.506969] Starting subtest: plane-position-hole
[447.013211] Starting dynamic subtest: pipe-A-plane-1
[448.296155] Dynamic subtest pipe-A-plane-1: SUCCESS (1.283s)
[448.307405] Starting dynamic subtest: pipe-A-plane-2
[449.613156] Dynamic subtest pipe-A-plane-2: SUCCESS (1.316s)
[449.625239] Starting dynamic subtest: pipe-A-plane-3
[450.951422] Dynamic subtest pipe-A-plane-3: SUCCESS (1.335s)
[450.962444] Starting dynamic subtest: pipe-A-plane-4
[452.292266] Dynamic subtest pipe-A-plane-4: SUCCESS (1.341s)
[452.303009] Starting dynamic subtest: pipe-A-plane-5
[452.317208] Dynamic subtest pipe-A-plane-5: SUCCESS (0.023s)
[453.519573] Starting dynamic subtest: pipe-B-plane-1
[454.918952] Dynamic subtest pipe-B-plane-1: SUCCESS (1.404s)
[454.930104] Starting dynamic subtest: pipe-B-plane-2
[456.184254] Dynamic subtest pipe-B-plane-2: SUCCESS (1.265s)
[456.195390] Starting dynamic subtest: pipe-B-plane-3
[457.436395] Dynamic subtest pipe-B-plane-3: SUCCESS (1.254s)
[457.447845] Starting dynamic subtest: pipe-B-plane-4
[458.789171] Dynamic subtest pipe-B-plane-4: SUCCESS (1.350s)
[458.800258] Starting dynamic subtest: pipe-B-plane-5
[458.820344] Dynamic subtest pipe-B-plane-5: SUCCESS (0.029s)
[458.823232] Subtest plane-position-hole: SUCCESS (12.313s)
[459.640882] [115/133] (569s left) gem_ctx_create (basic-files)
[459.710437] Starting subtest: basic-files
[462.007742] Subtest basic-files: SUCCESS (2.297s)
[463.657780] [116/133] (565s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[464.052686] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[464.858522] [117/133] (564s left) kms_ccs (crc-primary-basic-4-tiled-bmg-ccs)
[464.953400] Starting subtest: crc-primary-basic-4-tiled-bmg-ccs
[464.958067] Subtest crc-primary-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[466.101591] [118/133] (563s left) gem_userptr_blits (sync-unmap-after-close)
[466.178503] Starting subtest: sync-unmap-after-close
[466.194295] Subtest sync-unmap-after-close: SUCCESS (0.018s)
[466.313499] [119/133] (563s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[466.402050] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[466.407067] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[467.527922] [120/133] (561s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling)
[467.623909] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling
[467.629076] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SKIP (0.000s)
[467.711150] [121/133] (561s left) gem_exec_balancer (invalid-balancer)
[467.770948] Starting subtest: invalid-balancer
[468.692274] Subtest invalid-balancer: SUCCESS (0.921s)
[469.000911] [122/133] (560s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[469.100609] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[469.105201] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[469.193460] [123/133] (560s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[469.283697] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[469.288840] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[469.383773] [124/133] (560s left) kms_big_fb (yf-tiled-32bpp-rotate-90)
[469.489062] Starting subtest: yf-tiled-32bpp-rotate-90
[469.494285] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s)
[469.577894] [125/133] (560s left) i915_drm_fdinfo (busy-idle)
[469.619904] Starting subtest: busy-idle
[469.622331] Starting dynamic subtest: rcs0
[469.646020] Dynamic subtest rcs0: SKIP (0.025s)
[469.656698] Starting dynamic subtest: bcs0
[469.661454] Dynamic subtest bcs0: SKIP (0.000s)
[469.671539] Starting dynamic subtest: vcs0
[469.676992] Dynamic subtest vcs0: SKIP (0.000s)
[469.691783] Starting dynamic subtest: vcs1
[469.696873] Dynamic subtest vcs1: SKIP (0.000s)
[469.711197] Starting dynamic subtest: vecs0
[469.716143] Dynamic subtest vecs0: SKIP (0.000s)
[469.728432] Starting dynamic subtest: ccs0
[469.734060] Dynamic subtest ccs0: SKIP (0.000s)
[469.739051] Subtest busy-idle: SKIP (0.030s)
[469.779661] [126/133] (560s left) kms_dp_link_training (non-uhbr-sst)
[469.878438] Starting subtest: non-uhbr-sst
[469.888416] Subtest non-uhbr-sst: SKIP (0.000s)
[469.950330] [127/133] (559s left) kms_psr2_su (page_flip-p010)
[470.013246] Subtest page_flip-P010: SKIP (0.000s)
[470.067078] [128/133] (559s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw)
[470.161344] Starting subtest: fbc-1p-pri-indfb-multidraw
[483.183222] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (13.022s)
[484.561356] [129/133] (545s left) gem_mmap_gtt (bad-object)
[484.610990] Subtest bad-object: SKIP (0.000s)
[484.673925] [130/133] (545s left) gem_madvise (dontneed-before-exec)
[484.709430] Starting subtest: dontneed-before-exec
[484.733688] Subtest dontneed-before-exec: SKIP (0.020s)
[484.799105] [131/133] (545s left) kms_cursor_edge_walk (128x128-left-edge)
[484.886165] Starting subtest: 128x128-left-edge
[484.889096] Starting dynamic subtest: pipe-A-eDP-1
[488.320038] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.434s)
[488.331328] Starting dynamic subtest: pipe-D-eDP-1
[491.445346] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.123s)
[491.448279] Subtest 128x128-left-edge: SUCCESS (6.560s)
[492.257753] [132/133] (537s left) kms_atomic_interruptible (legacy-setmode)
[492.370101] Starting subtest: legacy-setmode
[492.372590] Starting dynamic subtest: pipe-A-eDP-1
[499.265354] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.894s)
[499.268565] Subtest legacy-setmode: SUCCESS (6.896s)
[500.137794] [133/133] (529s left) kms_addfb_basic (basic-x-tiled-legacy)
[500.194095] Starting subtest: basic-x-tiled-legacy
[500.198653] Subtest basic-x-tiled-legacy: SKIP (0.000s)
[500.334630] Closing watchdogs
results: parsing output: 0/ for test: kms_flip
results: parsing output: 1/ for test: vgem_basic
results: parsing output: 2/ for test: gem_exec_fence
results: parsing output: 3/ for test: gem_exec_reloc
results: parsing output: 4/ for test: gem_softpin
results: parsing output: 5/ for test: kms_psr
results: parsing output: 6/ for test: syncobj_wait
results: parsing output: 7/ for test: syncobj_timeline
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: gem_render_copy
results: parsing output: 11/ for test: gem_shrink
results: parsing output: 12/ for test: kms_dsc
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gen9_exec_parse
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_plane
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: gem_render_copy
results: parsing output: 23/ for test: kms_flip
results: parsing output: 24/ for test: kms_atomic_transition
results: parsing output: 25/ for test: kms_ccs
results: parsing output: 26/ for test: gem_userptr_blits
results: parsing output: 27/ for test: kms_panel_fitting
results: parsing output: 28/ for test: kms_pipe_crc_basic
results: parsing output: 29/ for test: gem_render_tiled_blits
results: parsing output: 30/ for test: kms_vblank
results: parsing output: 31/ for test: kms_psr
results: parsing output: 32/ for test: gem_lmem_swapping
results: parsing output: 33/ for test: gem_mmap_wc
results: parsing output: 34/ for test: kms_cursor_legacy
results: parsing output: 35/ for test: api_intel_bb
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: gen7_exec_parse
results: parsing output: 38/ for test: kms_flip
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: gem_spin_batch
results: parsing output: 42/ for test: kms_atomic_transition
results: parsing output: 43/ for test: kms_flip
results: parsing output: 44/ for test: gem_exec_params
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: gem_exec_reloc
results: parsing output: 47/ for test: kms_psr2_sf
results: parsing output: 48/ for test: gem_ctx_persistence
results: parsing output: 49/ for test: sysfs_heartbeat_interval
results: parsing output: 50/ for test: gem_exec_fence
results: parsing output: 51/ for test: kms_plane_lowres
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_addfb_basic
results: parsing output: 54/ for test: gem_exec_schedule
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: i915_pm_rpm
results: parsing output: 57/ for test: kms_cursor_crc
results: parsing output: 58/ for test: kms_pm_dc
results: parsing output: 59/ for test: kms_chamelium_edid
results: parsing output: 60/ for test: gem_exec_await
results: parsing output: 61/ for test: kms_ccs
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: gem_request_retire
results: parsing output: 64/ for test: perf_pmu
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_sequence
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_content_protection
results: parsing output: 69/ for test: perf
results: parsing output: 70/ for test: perf_pmu
results: parsing output: 71/ for test: gem_create
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_cursor_crc
results: parsing output: 74/ for test: gem_exec_reloc
results: parsing output: 75/ for test: syncobj_wait
results: parsing output: 76/ for test: kms_big_fb
results: parsing output: 77/ for test: kms_flip
results: parsing output: 78/ for test: kms_chamelium_color
results: parsing output: 79/ for test: kms_vrr
results: parsing output: 80/ for test: gem_ctx_shared
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_psr
results: parsing output: 83/ for test: gem_exec_schedule
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: kms_flip
results: parsing output: 86/ for test: syncobj_timeline
results: parsing output: 87/ for test: kms_cursor_legacy
results: parsing output: 88/ for test: syncobj_timeline
results: parsing output: 89/ for test: i915_hangman
results: parsing output: 90/ for test: kms_atomic_transition
results: parsing output: 91/ for test: kms_invalid_mode
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: api_intel_allocator
results: parsing output: 94/ for test: fbdev
results: parsing output: 95/ for test: i915_query
results: parsing output: 96/ for test: sriov_basic
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_psr2_sf
results: parsing output: 99/ for test: kms_psr
results: parsing output: 100/ for test: gem_mmap_offset
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_pm_rpm
results: parsing output: 103/ for test: syncobj_wait
results: parsing output: 104/ for test: gem_ctx_persistence
results: parsing output: 105/ for test: kms_color_pipeline
results: parsing output: 106/ for test: kms_psr
results: parsing output: 107/ for test: gem_exec_whisper
results: parsing output: 108/ for test: kms_lease
results: parsing output: 109/ for test: gem_exec_schedule
results: parsing output: 110/ for test: drm_buddy
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: gem_linear_blits
results: parsing output: 113/ for test: kms_plane
results: parsing output: 114/ for test: gem_ctx_create
results: parsing output: 115/ for test: kms_chamelium_hpd
results: parsing output: 116/ for test: kms_ccs
results: parsing output: 117/ for test: gem_userptr_blits
results: parsing output: 118/ for test: kms_ccs
results: parsing output: 119/ for test: kms_flip_scaled_crc
results: parsing output: 120/ for test: gem_exec_balancer
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_big_fb
results: parsing output: 124/ for test: i915_drm_fdinfo
results: parsing output: 125/ for test: kms_dp_link_training
results: parsing output: 126/ for test: kms_psr2_su
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: gem_mmap_gtt
results: parsing output: 129/ for test: gem_madvise
results: parsing output: 130/ for test: kms_cursor_edge_walk
results: parsing output: 131/ for test: kms_atomic_interruptible
results: parsing output: 132/ for test: kms_addfb_basic
Done.