[56.586225] Initializing watchdogs
[56.586276]   /dev/watchdog0
[56.602079] [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]
[56.606674] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.609054] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.612575] [001/156] (960s left) kms_pipe_crc_basic (hang-read-crc)
[56.731963] Starting subtest: hang-read-crc
[56.734552] Starting dynamic subtest: pipe-A-eDP-1
[58.929418] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.171s)
[58.937741] Starting dynamic subtest: pipe-B-eDP-1
[61.805969] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.853s)
[61.815906] Starting dynamic subtest: pipe-C-eDP-1
[64.543896] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.720s)
[64.553685] Starting dynamic subtest: pipe-D-eDP-1
[67.210979] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.655s)
[67.213413] Subtest hang-read-crc: SUCCESS (10.401s)
[68.058951] [002/156] (949s left) kms_chamelium_audio (dp-audio)
[68.459800] Subtest dp-audio: SKIP (0.000s)
[69.260884] [003/156] (947s left) gem_eio (hibernate)
[69.413062] Starting subtest: hibernate
[84.136756] Subtest hibernate: SUCCESS (14.707s)
[92.281359] [004/156] (932s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[92.428911] Starting subtest: protected-encrypted-src-copy-not-readible
[92.557756] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.130s)
[92.710551] [005/156] (932s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[92.823850] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt
[92.827805] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[92.940595] [006/156] (932s left) kms_atomic_transition (modeset-transition)
[93.041678] Starting subtest: modeset-transition
[93.043967] Starting dynamic subtest: 1x-outputs
[104.136869] Dynamic subtest 1x-outputs: SUCCESS (11.092s)
[104.139545] Subtest modeset-transition: SUCCESS (11.093s)
[105.004947] [007/156] (920s left) kms_psr (pr-primary-mmap-cpu)
[105.106253] Starting subtest: pr-primary-mmap-cpu
[105.110216] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[105.177186] [008/156] (920s left) kms_async_flips (crc)
[105.264079] Starting subtest: crc
[105.651511] Starting dynamic subtest: pipe-A-eDP-1
[108.556951] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.907s)
[108.944154] Starting dynamic subtest: pipe-B-eDP-1
[111.883717] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.948s)
[112.261084] Starting dynamic subtest: pipe-C-eDP-1
[115.181735] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.933s)
[115.549082] Starting dynamic subtest: pipe-D-eDP-1
[118.422393] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.881s)
[118.424658] Subtest crc: SUCCESS (13.158s)
[119.581996] [009/156] (905s left) kms_vblank (query-forked-hang)
[119.673666] Starting subtest: query-forked-hang
[119.675986] Starting dynamic subtest: pipe-A-eDP-1
[146.058826] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.382s)
[146.075209] Starting dynamic subtest: pipe-D-eDP-1
[171.080649] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.021s)
[171.083048] Subtest query-forked-hang: SUCCESS (51.407s)
[171.965399] [010/156] (853s left) kms_prop_blob (invalid-get-prop)
[172.036334] Starting subtest: invalid-get-prop
[172.038665] Subtest invalid-get-prop: SUCCESS (0.000s)
[172.141709] [011/156] (853s left) i915_query (query-topology-known-pci-ids)
[172.189529] Starting subtest: query-topology-known-pci-ids
[172.194180] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[172.272260] [012/156] (853s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[172.372134] Starting subtest: plane-lut1d-post-ctm3x4
[172.541154] Starting dynamic subtest: pipe-A-plane-0
[172.739685] Dynamic subtest pipe-A-plane-0: SUCCESS (0.200s)
[172.751263] Starting dynamic subtest: pipe-A-plane-1
[172.917401] Dynamic subtest pipe-A-plane-1: SUCCESS (0.177s)
[172.927289] Starting dynamic subtest: pipe-A-plane-2
[173.083856] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[174.303195] Starting dynamic subtest: pipe-B-plane-0
[174.492876] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[174.502989] Starting dynamic subtest: pipe-B-plane-1
[174.681888] Dynamic subtest pipe-B-plane-1: SUCCESS (0.189s)
[174.692535] Starting dynamic subtest: pipe-B-plane-2
[174.848581] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[176.115906] Starting dynamic subtest: pipe-C-plane-0
[176.274077] Dynamic subtest pipe-C-plane-0: SUCCESS (0.167s)
[176.284837] Starting dynamic subtest: pipe-C-plane-1
[176.440728] Dynamic subtest pipe-C-plane-1: SUCCESS (0.166s)
[176.452922] Starting dynamic subtest: pipe-C-plane-2
[176.607238] Dynamic subtest pipe-C-plane-2: SUCCESS (0.166s)
[177.831994] Starting dynamic subtest: pipe-D-plane-0
[177.991174] Dynamic subtest pipe-D-plane-0: SUCCESS (0.167s)
[178.007550] Starting dynamic subtest: pipe-D-plane-1
[178.157154] Dynamic subtest pipe-D-plane-1: SUCCESS (0.165s)
[178.171584] Starting dynamic subtest: pipe-D-plane-2
[178.324079] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[178.623297] Subtest plane-lut1d-post-ctm3x4: SUCCESS (6.251s)
[179.540172] [013/156] (846s left) gem_eio (create)
[179.717930] Starting subtest: create
[179.948802] Subtest create: SUCCESS (0.231s)
[180.097056] [014/156] (845s left) i915_suspend (sysfs-reader)
[180.163127] Starting subtest: sysfs-reader
[198.047450] Subtest sysfs-reader: SUCCESS (3.177s)
[198.095497] [015/156] (827s left) gen3_mixed_blits
[198.226610] [016/156] (827s left) kms_vblank (wait-forked-busy)
[198.316950] Starting subtest: wait-forked-busy
[198.318553] Starting dynamic subtest: pipe-A-eDP-1
[200.205858] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.888s)
[200.223101] Starting dynamic subtest: pipe-D-eDP-1
[202.872591] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.610s)
[202.875088] Subtest wait-forked-busy: SUCCESS (4.503s)
[203.686916] [017/156] (822s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[203.793824] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[203.798392] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[203.892354] [018/156] (822s left) kms_atomic_transition (plane-toggle-modeset-transition)
[203.979287] Starting subtest: plane-toggle-modeset-transition
[203.981554] Starting dynamic subtest: pipe-A-eDP-1
[216.847103] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.761s)
[216.859536] Starting dynamic subtest: pipe-B-eDP-1
[229.302480] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.429s)
[229.305111] Subtest plane-toggle-modeset-transition: SUCCESS (25.195s)
[230.121631] [019/156] (795s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[230.230265] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[230.234305] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[230.319832] [020/156] (795s left) gem_mmap_wc (write)
[230.380900] Subtest write: SKIP (0.000s)
[230.434683] [021/156] (795s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[230.531594] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[230.534313] Starting dynamic subtest: pipe-A-default-mode
[233.270220] Dynamic subtest pipe-A-default-mode: SUCCESS (2.736s)
[233.272219] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.737s)
[234.103056] [022/156] (791s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[234.213509] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[234.215730] Starting dynamic subtest: pipe-A-eDP-1
[234.531616] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[234.543486] Starting dynamic subtest: pipe-B-eDP-1
[235.820729] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.287s)
[235.837112] Starting dynamic subtest: pipe-C-eDP-1
[237.079623] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.259s)
[237.091610] Starting dynamic subtest: pipe-D-eDP-1
[238.339149] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.260s)
[238.341386] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.096s)
[239.542718] [023/156] (786s left) gem_exec_create (forked)
[239.600308] Starting subtest: forked
[239.602741] Starting dynamic subtest: smem
[261.002548] Dynamic subtest smem: SUCCESS (21.373s)
[261.004862] Subtest forked: SUCCESS (21.375s)
[261.190660] [024/156] (764s left) kms_draw_crc (draw-method-blt)
[261.293613] Starting subtest: draw-method-blt
[261.295903] Starting dynamic subtest: xrgb8888-untiled
[261.562256] Dynamic subtest xrgb8888-untiled: SUCCESS (0.269s)
[261.578433] Starting dynamic subtest: rgb565-untiled
[261.794952] Dynamic subtest rgb565-untiled: SUCCESS (0.231s)
[261.806265] Starting dynamic subtest: xrgb2101010-untiled
[262.019747] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[262.029740] Starting dynamic subtest: xbgr16161616f-untiled
[262.239210] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.222s)
[262.251969] Starting dynamic subtest: xrgb8888-xtiled
[262.362220] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[262.375490] Starting dynamic subtest: rgb565-xtiled
[262.484046] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[262.495013] Starting dynamic subtest: xrgb2101010-xtiled
[262.594886] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.110s)
[262.605156] Starting dynamic subtest: xbgr16161616f-xtiled
[262.717224] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.122s)
[262.728328] Starting dynamic subtest: xrgb8888-4tiled
[262.840064] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.120s)
[262.851741] Starting dynamic subtest: rgb565-4tiled
[262.961880] Dynamic subtest rgb565-4tiled: SUCCESS (0.122s)
[262.973752] Starting dynamic subtest: xrgb2101010-4tiled
[263.072792] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[263.088811] Starting dynamic subtest: xbgr16161616f-4tiled
[263.183757] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.110s)
[263.185957] Subtest draw-method-blt: SUCCESS (1.892s)
[263.316513] [025/156] (762s left) kms_big_fb (yf-tiled-8bpp-rotate-90)
[263.424627] Starting subtest: yf-tiled-8bpp-rotate-90
[263.428369] Subtest yf-tiled-8bpp-rotate-90: SKIP (0.000s)
[263.530123] [026/156] (762s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[263.629474] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[263.632869] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[263.724155] [027/156] (762s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[263.843363] Starting subtest: fbcpsr-slowdraw
[289.172880] Subtest fbcpsr-slowdraw: SUCCESS (25.325s)
[289.318679] [028/156] (736s left) syncobj_wait (invalid-reset-one-illegal-handle)
[289.375944] Starting subtest: invalid-reset-one-illegal-handle
[289.378136] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[289.469173] [029/156] (736s left) i915_drm_fdinfo (idle)
[289.513519] Starting subtest: idle
[289.515847] Starting dynamic subtest: rcs0
[290.031965] Dynamic subtest rcs0: SUCCESS (0.517s)
[290.045440] Starting dynamic subtest: bcs0
[290.570157] Dynamic subtest bcs0: SUCCESS (0.535s)
[290.583743] Starting dynamic subtest: vcs0
[291.075183] Dynamic subtest vcs0: SUCCESS (0.502s)
[291.088318] Starting dynamic subtest: vcs1
[291.580757] Dynamic subtest vcs1: SUCCESS (0.503s)
[291.593615] Starting dynamic subtest: vecs0
[292.086457] Dynamic subtest vecs0: SUCCESS (0.503s)
[292.099013] Starting dynamic subtest: ccs0
[292.592589] Dynamic subtest ccs0: SUCCESS (0.503s)
[292.595277] Subtest idle: SUCCESS (3.077s)
[292.668811] [030/156] (733s left) kms_lease (atomic-implicit-crtc)
[292.762634] Starting subtest: atomic-implicit-crtc
[292.764967] Starting dynamic subtest: pipe-A-eDP-1
[292.776677] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[292.790460] Starting dynamic subtest: pipe-B-eDP-1
[292.801456] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[292.812968] Starting dynamic subtest: pipe-C-eDP-1
[292.823716] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s)
[292.833896] Starting dynamic subtest: pipe-D-eDP-1
[292.844736] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.010s)
[292.846899] Subtest atomic-implicit-crtc: SUCCESS (0.038s)
[292.914564] [031/156] (733s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-blt)
[293.013336] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-blt
[293.017822] Subtest hdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[293.110976] [032/156] (733s left) kms_chamelium_edid (dp-edid-stress-resolution-non-4k)
[293.499029] Subtest dp-edid-stress-resolution-non-4k: SKIP (0.000s)
[294.291822] [033/156] (732s left) gem_exec_schedule (in-order)
[294.369331] Starting subtest: in-order
[294.371358] Starting dynamic subtest: rcs0
[294.463309] Dynamic subtest rcs0: SUCCESS (0.100s)
[294.476234] Starting dynamic subtest: vcs0
[294.552784] Dynamic subtest vcs0: SUCCESS (0.089s)
[294.566551] Starting dynamic subtest: vcs1
[294.635616] Dynamic subtest vcs1: SUCCESS (0.081s)
[294.648618] Starting dynamic subtest: vecs0
[294.717429] Dynamic subtest vecs0: SUCCESS (0.080s)
[294.730159] Starting dynamic subtest: ccs0
[294.799149] Dynamic subtest ccs0: SUCCESS (0.080s)
[294.801725] Subtest in-order: SUCCESS (0.437s)
[296.074196] [034/156] (730s left) gem_flink_basic (bad-open)
[296.127287] Starting subtest: bad-open
[296.129268] Subtest bad-open: SUCCESS (0.000s)
[296.224241] [035/156] (730s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff)
[296.317592] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff
[296.321860] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[296.427575] [036/156] (730s left) api_intel_bb (intel-bb-blit-x)
[296.484302] Starting subtest: intel-bb-blit-x
[299.955283] Subtest intel-bb-blit-x: SUCCESS (3.472s)
[300.066043] [037/156] (726s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[300.168721] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[300.181275] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.011s)
[300.260429] [038/156] (726s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[300.353430] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu
[300.357530] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[300.450707] [039/156] (726s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-render)
[300.558193] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-render
[300.562869] Subtest hdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[300.659129] [040/156] (726s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-mmap-cpu)
[300.752342] Starting subtest: psrhdr-rgb565-draw-mmap-cpu
[300.756478] Subtest psrhdr-rgb565-draw-mmap-cpu: SKIP (0.000s)
[300.851930] [041/156] (725s left) i915_fb_tiling (basic-x-tiling)
[300.899895] Subtest basic-x-tiling: SKIP (0.000s)
[300.958179] [042/156] (725s left) gem_softpin (evict-active-interruptible)
[301.011487] Starting subtest: evict-active-interruptible
[301.025588] Subtest evict-active-interruptible: SUCCESS (0.013s)
[301.124884] [043/156] (725s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[301.217615] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[301.221116] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[301.318501] [044/156] (725s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers)
[301.407015] Starting subtest: plane-upscale-factor-0-25-with-modifiers
[301.408987] Starting dynamic subtest: pipe-A
[301.743741] Dynamic subtest pipe-A: SUCCESS (0.338s)
[301.756929] Starting dynamic subtest: pipe-B
[303.192773] Dynamic subtest pipe-B: SUCCESS (1.448s)
[303.203795] Starting dynamic subtest: pipe-C
[304.619038] Dynamic subtest pipe-C: SUCCESS (1.426s)
[304.630202] Starting dynamic subtest: pipe-D
[306.058573] Dynamic subtest pipe-D: SUCCESS (1.439s)
[306.061019] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (4.655s)
[307.237816] [045/156] (719s left) api_intel_allocator (gem-pool)
[307.297500] Starting subtest: gem-pool
[307.303994] Subtest gem-pool: SUCCESS (0.009s)
[307.426039] [046/156] (719s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[307.519123] Starting subtest: x-tiled-32bpp-rotate-0
[308.965505] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.446s)
[309.109862] [047/156] (717s left) kms_cursor_edge_walk (128x128-top-edge)
[309.208717] Starting subtest: 128x128-top-edge
[309.210582] Starting dynamic subtest: pipe-A-eDP-1
[312.690299] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.481s)
[312.701904] Starting dynamic subtest: pipe-D-eDP-1
[315.813099] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.122s)
[315.815380] Subtest 128x128-top-edge: SUCCESS (6.606s)
[316.627945] [048/156] (710s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-move)
[316.726282] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-move
[316.730489] Subtest fbchdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[316.825370] [049/156] (710s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-pwrite)
[316.921445] Starting subtest: psrhdr-rgb565-draw-pwrite
[316.925896] Subtest psrhdr-rgb565-draw-pwrite: SKIP (0.000s)
[317.027906] [050/156] (710s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[317.118304] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[317.122749] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[317.222977] [051/156] (710s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render)
[317.330985] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render
[317.335266] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[317.437469] [052/156] (709s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-draw-render)
[317.533693] Starting subtest: fbchdr-1p-primscrn-cur-indfb-draw-render
[317.537827] Subtest fbchdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[317.632412] [053/156] (709s left) kms_addfb_basic (unused-modifier)
[317.690272] Starting subtest: unused-modifier
[317.692134] Subtest unused-modifier: SUCCESS (0.000s)
[317.846636] [054/156] (709s left) kms_psr (fbc-psr2-no-drrs)
[317.936036] Starting subtest: fbc-psr2-no-drrs
[317.938007] Starting dynamic subtest: eDP-1
[317.941618] Dynamic subtest eDP-1: SKIP (0.000s)
[317.945308] Subtest fbc-psr2-no-drrs: SKIP (0.001s)
[318.022563] [055/156] (709s left) perf_pmu (busy-idle-check-all)
[318.060195] Starting subtest: busy-idle-check-all
[318.062131] Starting dynamic subtest: rcs0
[318.714490] Dynamic subtest rcs0: SUCCESS (0.654s)
[318.727571] Starting dynamic subtest: bcs0
[319.367606] Dynamic subtest bcs0: SUCCESS (0.651s)
[319.381572] Starting dynamic subtest: vcs0
[321.010337] Dynamic subtest vcs0: SUCCESS (1.640s)
[321.024321] Starting dynamic subtest: vcs1
[322.997585] Dynamic subtest vcs1: SUCCESS (1.983s)
[323.010609] Starting dynamic subtest: vecs0
[324.981635] Dynamic subtest vecs0: SUCCESS (1.981s)
[324.995203] Starting dynamic subtest: ccs0
[325.639420] Dynamic subtest ccs0: SUCCESS (0.656s)
[325.642023] Subtest busy-idle-check-all: SUCCESS (7.581s)
[325.748108] [056/156] (701s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[325.858291] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-wc
[325.862557] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[325.964481] [057/156] (701s left) gem_exec_whisper (basic-normal-all)
[326.025760] Starting subtest: basic-normal-all
[328.290234] Subtest basic-normal-all: SUCCESS (2.261s)
[328.476524] [058/156] (699s left) gem_exec_schedule (u-lateslice)
[328.542501] Starting subtest: u-lateslice
[328.544858] Starting dynamic subtest: rcs0
[328.566133] Dynamic subtest rcs0: SUCCESS (0.032s)
[328.579713] Starting dynamic subtest: bcs0
[328.588762] Dynamic subtest bcs0: SUCCESS (0.023s)
[328.599856] Starting dynamic subtest: vcs0
[328.611300] Dynamic subtest vcs0: SUCCESS (0.022s)
[328.623565] Starting dynamic subtest: vcs1
[328.634477] Dynamic subtest vcs1: SUCCESS (0.023s)
[328.645686] Starting dynamic subtest: vecs0
[328.656739] Dynamic subtest vecs0: SUCCESS (0.021s)
[328.667712] Starting dynamic subtest: ccs0
[328.679279] Dynamic subtest ccs0: SUCCESS (0.022s)
[328.681623] Subtest u-lateslice: SUCCESS (0.146s)
[330.128630] [059/156] (697s left) perf (invalid-remove-userspace-config)
[330.274859] Starting subtest: invalid-remove-userspace-config
[330.281753] Subtest invalid-remove-userspace-config: SUCCESS (0.008s)
[330.325372] [060/156] (697s left) gem_ctx_param (invalid-ctx-get)
[330.384785] Starting subtest: invalid-ctx-get
[330.387065] Subtest invalid-ctx-get: SUCCESS (0.000s)
[330.476815] [061/156] (697s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt)
[330.587352] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt
[330.591818] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[330.682018] [062/156] (697s left) kms_content_protection (suspend-resume)
[330.828878] Starting subtest: suspend-resume
[330.833367] Subtest suspend-resume: SKIP (0.000s)
[330.946173] [063/156] (696s left) drm_buddy (drm_buddy)
[330.985186] Starting subtest: drm_buddy
[330.989397] Subtest drm_buddy: SKIP (0.001s)
[331.044661] [064/156] (696s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-downscaling)
[331.139938] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-downscaling
[331.143656] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-downscaling: SKIP (0.000s)
[331.226042] [065/156] (696s left) kms_psr (psr-cursor-blt)
[331.329975] Starting subtest: psr-cursor-blt
[331.331964] Starting dynamic subtest: eDP-1
[333.039605] Dynamic subtest eDP-1: SUCCESS (1.708s)
[333.041791] Subtest psr-cursor-blt: SUCCESS (1.709s)
[333.891087] [066/156] (694s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[333.991531] Starting subtest: 2x-absolute-wf_vblank-interruptible
[334.003818] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[334.088684] [067/156] (693s left) kms_pm_dc (dc5-dpms-negative)
[335.200787] Starting subtest: dc5-dpms-negative
[335.205438] Subtest dc5-dpms-negative: SKIP (0.000s)
[335.277981] [068/156] (692s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[335.371530] Starting subtest: plane-downscale-factor-0-5-with-rotation
[335.373776] Starting dynamic subtest: pipe-A
[335.395712] Dynamic subtest pipe-A: SKIP (0.020s)
[335.406557] Starting dynamic subtest: pipe-B
[335.429308] Dynamic subtest pipe-B: SKIP (0.032s)
[335.440480] Starting dynamic subtest: pipe-C
[335.462696] Dynamic subtest pipe-C: SKIP (0.032s)
[335.473728] Starting dynamic subtest: pipe-D
[335.496190] Dynamic subtest pipe-D: SKIP (0.032s)
[335.500191] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.119s)
[335.593762] [069/156] (692s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[335.713990] Starting subtest: cursorA-vs-flipA-toggle
[346.854000] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.136s)
[347.722547] [070/156] (680s left) gem_userptr_blits (unsync-overlap)
[347.795058] Subtest unsync-overlap: SKIP (0.000s)
[347.908474] [071/156] (680s left) gem_exec_reloc (basic-gtt-wc-active)
[347.973469] Subtest basic-gtt-wc-active: SKIP (0.000s)
[348.043991] [072/156] (680s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-gtt)
[348.147875] Starting subtest: hdr-rgb565-draw-mmap-gtt
[348.151842] Subtest hdr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[348.261183] [073/156] (679s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[348.361039] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[355.817346] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.462s)
[355.954134] [074/156] (672s left) gen9_exec_parse (bb-start-far)
[356.035750] Subtest bb-start-far: SKIP (0.000s)
[356.098734] [075/156] (672s left) gem_mmap_gtt (basic-write-gtt)
[356.164785] Subtest basic-write-gtt: SKIP (0.000s)
[356.234244] [076/156] (672s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[356.290615] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[356.293416] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[356.405332] [077/156] (671s left) sysfs_heartbeat_interval (precise)
[356.458169] Starting subtest: precise
[356.460426] Starting dynamic subtest: vecs0
[364.030636] Dynamic subtest vecs0: SUCCESS (7.569s)
[364.045168] Starting dynamic subtest: ccs0
[365.219747] Dynamic subtest ccs0: SUCCESS (1.186s)
[365.230742] Starting dynamic subtest: vcs0
[372.975283] Dynamic subtest vcs0: SUCCESS (7.753s)
[372.988656] Starting dynamic subtest: bcs0
[374.128804] Dynamic subtest bcs0: SUCCESS (1.149s)
[374.142286] Starting dynamic subtest: rcs0
[375.225907] Dynamic subtest rcs0: SUCCESS (1.098s)
[375.238376] Starting dynamic subtest: vcs1
[383.030304] Dynamic subtest vcs1: SUCCESS (7.797s)
[383.032908] Subtest precise: SUCCESS (26.563s)
[383.108884] [078/156] (645s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[383.224050] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[383.226271] Starting dynamic subtest: pipe-A-eDP-1
[389.133298] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.911s)
[389.145547] Starting dynamic subtest: pipe-B-eDP-1
[395.811577] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.675s)
[395.813998] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.588s)
[396.682179] [079/156] (631s left) gem_exec_flush (basic-wb-ro-before-default)
[396.752474] Starting subtest: basic-wb-ro-before-default
[402.212477] Subtest basic-wb-ro-before-default: SUCCESS (5.458s)
[402.418571] [080/156] (626s left) gem_ctx_persistence (legacy-engines-cleanup)
[402.490151] Starting subtest: legacy-engines-cleanup
[402.492612] Starting dynamic subtest: render
[402.565619] Dynamic subtest render: SUCCESS (0.075s)
[402.579027] Starting dynamic subtest: bsd1
[402.603318] Dynamic subtest bsd1: SUCCESS (0.035s)
[402.617476] Starting dynamic subtest: bsd2
[402.647154] Dynamic subtest bsd2: SUCCESS (0.041s)
[402.658973] Starting dynamic subtest: blt
[402.688346] Dynamic subtest blt: SUCCESS (0.039s)
[402.700095] Starting dynamic subtest: vebox
[402.711090] Dynamic subtest vebox: SUCCESS (0.021s)
[402.713433] Subtest legacy-engines-cleanup: SUCCESS (0.222s)
[402.824147] [081/156] (625s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-sf)
[402.936902] Starting subtest: fbc-psr2-overlay-plane-move-continuous-sf
[402.939374] Starting dynamic subtest: pipe-A-eDP-1
[403.035203] Dynamic subtest pipe-A-eDP-1: SKIP (0.102s)
[403.047899] Starting dynamic subtest: pipe-B-eDP-1
[404.155437] Dynamic subtest pipe-B-eDP-1: SKIP (1.116s)
[404.160211] Subtest fbc-psr2-overlay-plane-move-continuous-sf: SKIP (1.221s)
[405.358604] [082/156] (623s left) kms_frontbuffer_tracking (fbc-1p-rte)
[405.452834] Starting subtest: fbc-1p-rte
[410.917417] Subtest fbc-1p-rte: SUCCESS (5.469s)
[412.077984] [083/156] (616s left) gem_softpin (allocator-basic-reserve)
[412.135066] Starting subtest: allocator-basic-reserve
[412.206944] Subtest allocator-basic-reserve: SUCCESS (0.071s)
[412.331175] [084/156] (616s left) kms_busy (basic-hang)
[412.433717] Starting subtest: basic-hang
[412.436106] Starting dynamic subtest: flip-pipe-A
[413.034732] Dynamic subtest flip-pipe-A: SUCCESS (0.600s)
[413.047482] Starting dynamic subtest: modeset-pipe-A
[416.728738] Dynamic subtest modeset-pipe-A: SUCCESS (3.692s)
[416.741496] Starting dynamic subtest: flip-pipe-D
[418.006484] Dynamic subtest flip-pipe-D: SUCCESS (1.277s)
[418.019280] Starting dynamic subtest: modeset-pipe-D
[421.445276] Dynamic subtest modeset-pipe-D: SUCCESS (3.435s)
[421.447719] Subtest basic-hang: SUCCESS (9.011s)
[422.346053] [085/156] (606s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-shrfb-msflip-blt)
[422.445704] Starting subtest: fbchdr-1p-primscrn-shrfb-msflip-blt
[422.449737] Subtest fbchdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[422.544849] [086/156] (606s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[422.600256] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[422.658123] [087/156] (606s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[422.750484] Starting subtest: fbc-rgb101010-draw-pwrite
[426.066732] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (3.320s)
[427.254436] [088/156] (601s left) perf_pmu (semaphore-busy)
[427.293887] Starting subtest: semaphore-busy
[427.296327] Starting dynamic subtest: rcs0
[428.943965] Dynamic subtest rcs0: SUCCESS (1.649s)
[428.957863] Starting dynamic subtest: bcs0
[430.576219] Dynamic subtest bcs0: SUCCESS (1.629s)
[430.590381] Starting dynamic subtest: vcs0
[434.542771] Dynamic subtest vcs0: SUCCESS (3.965s)
[434.556170] Starting dynamic subtest: vcs1
[440.495407] Dynamic subtest vcs1: SUCCESS (5.951s)
[440.508240] Starting dynamic subtest: vecs0
[446.511954] Dynamic subtest vecs0: SUCCESS (6.014s)
[446.524446] Starting dynamic subtest: ccs0
[449.585930] Dynamic subtest ccs0: SUCCESS (3.072s)
[449.588431] Subtest semaphore-busy: SUCCESS (22.297s)
[449.727234] [089/156] (579s left) kms_cursor_crc (cursor-offscreen-256x256)
[449.965313] Starting subtest: cursor-offscreen-256x256
[449.970761] Starting dynamic subtest: pipe-A-eDP-1
[451.912350] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.944s)
[451.951623] Starting dynamic subtest: pipe-D-eDP-1
[454.565788] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.622s)
[454.568107] Subtest cursor-offscreen-256x256: SUCCESS (4.598s)
[455.414205] [090/156] (573s left) gem_lmem_swapping (heavy-verify-multi)
[455.456721] Subtest heavy-verify-multi: SKIP (0.000s)
[455.498249] [091/156] (573s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[455.590464] Starting subtest: multiplane-rotation-cropping-bottom
[473.057122] Subtest multiplane-rotation-cropping-bottom: SUCCESS (17.471s)
[473.173898] [092/156] (555s left) gem_exec_reloc (basic-active)
[473.241289] Subtest basic-active: SKIP (0.000s)
[473.316447] [093/156] (555s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[473.424280] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[474.525071] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.101s)
[474.648650] [094/156] (554s left) gem_create (create-clear)
[474.709461] Starting subtest: create-clear
[474.711391] Starting dynamic subtest: smem0
[507.425056] Dynamic subtest smem0: SUCCESS (32.713s)
[507.427445] Subtest create-clear: SUCCESS (32.714s)
[507.621594] [095/156] (521s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-render)
[507.730002] Starting subtest: fbchdr-rgb101010-draw-render
[507.733915] Subtest fbchdr-rgb101010-draw-render: SKIP (0.000s)
[507.832758] [096/156] (521s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-pwrite)
[507.931194] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-pwrite
[507.935559] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[508.027837] [097/156] (521s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-onoff)
[508.116329] Starting subtest: psrhdr-2p-primscrn-cur-indfb-onoff
[508.120486] Subtest psrhdr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[508.221956] [098/156] (521s left) kms_display_modes (extended-mode-basic)
[508.317232] Starting subtest: extended-mode-basic
[508.321718] Subtest extended-mode-basic: SKIP (0.000s)
[508.366443] [099/156] (520s left) gem_ccs (block-copy-uncompressed)
[508.421742] Starting subtest: block-copy-uncompressed
[508.424063] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[508.443974] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.021s)
[508.457339] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[508.462391] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[508.474055] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[508.478893] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[508.489654] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[508.494869] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[508.497193] Subtest block-copy-uncompressed: SUCCESS (0.074s)
[508.606678] [100/156] (520s left) i915_pm_rpm (gem-idle)
[510.135362] Starting subtest: gem-idle
[516.336195] Subtest gem-idle: SUCCESS (6.203s)
[516.688743] [101/156] (512s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[518.024831] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[522.352223] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.332s)
[523.502555] [102/156] (505s left) kms_cursor_crc (cursor-size-hints)
[523.644954] Starting subtest: cursor-size-hints
[523.646884] Starting dynamic subtest: pipe-A-eDP-1
[524.267278] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.622s)
[524.278653] Starting dynamic subtest: pipe-D-eDP-1
[525.555478] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.289s)
[525.557819] Subtest cursor-size-hints: SUCCESS (1.913s)
[526.361393] [103/156] (503s left) prime_vgem (basic-fence-blt)
[526.526663] Starting subtest: basic-fence-blt
[526.609079] Subtest basic-fence-blt: SUCCESS (0.082s)
[526.743213] [FACT prime_vgem (basic-fence-blt)] new: kernel.kmod_is_loaded.vgem: true
[526.746884] [104/156] (502s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[526.840461] Starting subtest: 4-tiled-32bpp-rotate-0
[528.309553] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.469s)
[528.435065] [105/156] (501s left) gem_userptr_blits (mmap-offset-banned)
[528.494267] Starting subtest: mmap-offset-banned
[528.496177] Starting dynamic subtest: wb
[528.498103] Dynamic subtest wb: SUCCESS (0.000s)
[528.511010] Starting dynamic subtest: wc
[528.512769] Dynamic subtest wc: SUCCESS (0.000s)
[528.523541] Starting dynamic subtest: uc
[528.525306] Dynamic subtest uc: SUCCESS (0.000s)
[528.527126] Subtest mmap-offset-banned: SUCCESS (0.003s)
[528.637120] [106/156] (500s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs)
[528.729217] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs
[528.733022] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s)
[529.892829] [107/156] (499s left) gem_exec_flush (basic-wb-prw-default)
[529.953457] Starting subtest: basic-wb-prw-default
[535.431046] Subtest basic-wb-prw-default: SUCCESS (5.476s)
[535.620271] [108/156] (494s left) gem_pxp (create-regular-buffer)
[535.734485] Starting subtest: create-regular-buffer
[535.737133] Subtest create-regular-buffer: SUCCESS (0.000s)
[535.843803] [109/156] (493s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[536.250011] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[537.108907] [110/156] (492s left) syncobj_timeline (single-wait-all-signaled)
[537.171553] Starting subtest: single-wait-all-signaled
[537.173958] Subtest single-wait-all-signaled: SUCCESS (0.001s)
[537.296633] [111/156] (492s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-msflip-blt)
[537.394924] Starting subtest: hdr-2p-primscrn-indfb-msflip-blt
[537.399080] Subtest hdr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[537.485721] [112/156] (492s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[537.614656] Starting subtest: cursorA-vs-flipA-legacy
[548.727576] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.111s)
[549.557093] [113/156] (480s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-blt)
[549.663885] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-blt
[549.667867] Subtest hdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[549.766539] [114/156] (480s left) gem_exec_capture (userptr)
[549.833139] Starting subtest: userptr
[550.668591] Subtest userptr: SUCCESS (0.833s)
[550.804856] [115/156] (479s left) gem_mmap_offset (bad-extensions)
[550.864280] Starting subtest: bad-extensions
[550.866473] Subtest bad-extensions: SUCCESS (0.000s)
[550.967013] [116/156] (479s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[551.059341] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[551.063635] Subtest fbchdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[551.155292] [117/156] (478s left) gem_shrink (reclaim)
[551.214941] Starting subtest: reclaim
[553.659555] Subtest reclaim: SUCCESS (2.439s)
[553.809260] [118/156] (476s left) kms_plane_multiple (tiling-x)
[553.901617] Starting subtest: tiling-x
[553.903964] Starting dynamic subtest: pipe-A-eDP-1
[557.886762] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.984s)
[557.900621] Starting dynamic subtest: pipe-B-eDP-1
[562.378287] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.486s)
[562.392490] Starting dynamic subtest: pipe-C-eDP-1
[566.888833] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.509s)
[566.900928] Starting dynamic subtest: pipe-D-eDP-1
[571.354757] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.464s)
[571.356998] Subtest tiling-x: SUCCESS (17.450s)
[572.167991] [119/156] (458s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-pgflip-blt)
[572.280119] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-pgflip-blt
[572.284446] Subtest fbcpsrhdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[572.397442] [120/156] (457s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[572.491983] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[572.496316] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[572.576346] [121/156] (457s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[572.676491] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[581.586447] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.914s)
[581.724474] [122/156] (448s left) gem_wait (invalid-buf)
[581.779519] Starting subtest: invalid-buf
[581.781838] Subtest invalid-buf: SUCCESS (0.000s)
[582.012555] [123/156] (448s left) kms_frontbuffer_tracking (fbcpsrhdr-farfromfence-mmap-gtt)
[582.116382] Starting subtest: fbcpsrhdr-farfromfence-mmap-gtt
[582.120827] Subtest fbcpsrhdr-farfromfence-mmap-gtt: SKIP (0.000s)
[582.217912] [124/156] (448s left) prime_self_import (basic-llseek-size)
[582.255620] Starting subtest: basic-llseek-size
[582.273300] Subtest basic-llseek-size: SUCCESS (0.019s)
[582.370978] [125/156] (448s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[582.460595] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[582.462770] Starting dynamic subtest: pipe-A-eDP-1
[582.484578] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[582.496318] Starting dynamic subtest: pipe-B-eDP-1
[582.517151] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[582.528306] Starting dynamic subtest: pipe-C-eDP-1
[582.549340] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[582.560637] Starting dynamic subtest: pipe-D-eDP-1
[582.583556] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[582.587723] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[584.721608] [126/156] (445s left) gem_exec_big (single)
[584.784684] Starting subtest: single
[587.229968] Subtest single: SUCCESS (2.447s)
[587.460208] [127/156] (443s left) perf (i915-ref-count)
[587.600264] Starting subtest: i915-ref-count
[587.638611] Subtest i915-ref-count: SUCCESS (0.037s)
[587.707664] [128/156] (442s left) kms_cursor_legacy (cursor-vs-flip-toggle)
[587.843298] Starting subtest: cursor-vs-flip-toggle
[619.223739] Subtest cursor-vs-flip-toggle: SUCCESS (31.372s)
[620.064798] [129/156] (410s left) syncobj_timeline (host-signal-ordered)
[620.112581] Starting subtest: host-signal-ordered
[620.115040] Subtest host-signal-ordered: SUCCESS (0.001s)
[620.218436] [130/156] (410s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-move)
[620.320618] Starting subtest: fbchdr-2p-primscrn-cur-indfb-move
[620.324874] Subtest fbchdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[620.440399] [131/156] (410s left) gem_ctx_persistence (engines-mixed-process)
[620.502596] Starting subtest: engines-mixed-process
[620.504531] Starting dynamic subtest: rcs0
[622.017581] Dynamic subtest rcs0: SUCCESS (1.517s)
[622.033464] Starting dynamic subtest: bcs0
[623.973800] Dynamic subtest bcs0: SUCCESS (1.950s)
[623.987133] Starting dynamic subtest: vcs0
[626.039937] Dynamic subtest vcs0: SUCCESS (2.068s)
[626.053798] Starting dynamic subtest: vcs1
[628.027798] Dynamic subtest vcs1: SUCCESS (1.986s)
[628.041373] Starting dynamic subtest: vecs0
[630.026477] Dynamic subtest vecs0: SUCCESS (1.996s)
[630.039913] Starting dynamic subtest: ccs0
[631.979601] Dynamic subtest ccs0: SUCCESS (1.951s)
[631.981987] Subtest engines-mixed-process: SUCCESS (11.477s)
[632.076619] [132/156] (398s left) kms_psr (fbc-psr-cursor-plane-move)
[632.174953] Starting subtest: fbc-psr-cursor-plane-move
[632.177295] Starting dynamic subtest: eDP-1
[632.181352] Dynamic subtest eDP-1: SKIP (0.000s)
[632.185441] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[632.253188] [133/156] (398s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[632.357455] Starting subtest: y-tiled-64bpp-rotate-90
[632.361873] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[632.457818] [134/156] (398s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[632.547824] Starting subtest: psr2-primary-plane-update-sf-dmg-area-big-fb
[632.550082] Starting dynamic subtest: pipe-A-eDP-1
[639.513265] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.968s)
[639.526606] Starting dynamic subtest: pipe-B-eDP-1
[646.677384] Dynamic subtest pipe-B-eDP-1: SUCCESS (7.162s)
[646.679807] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SUCCESS (14.132s)
[647.552427] [135/156] (383s left) gem_softpin (32b-excludes-last-page)
[647.614945] Starting subtest: 32b-excludes-last-page
[647.619875] Subtest 32b-excludes-last-page: SUCCESS (0.003s)
[647.750907] [136/156] (383s left) kms_plane (pixel-format-linear-modifier)
[647.846480] Starting subtest: pixel-format-linear-modifier
[647.872014] Starting dynamic subtest: pipe-A-plane-0
[648.460610] Dynamic subtest pipe-A-plane-0: SUCCESS (0.589s)
[648.472552] Starting dynamic subtest: pipe-A-plane-3
[648.938370] Dynamic subtest pipe-A-plane-3: SUCCESS (0.476s)
[648.952370] Starting dynamic subtest: pipe-A-plane-5
[649.149367] Dynamic subtest pipe-A-plane-5: SUCCESS (0.209s)
[650.246014] Starting dynamic subtest: pipe-B-plane-0
[650.844122] Dynamic subtest pipe-B-plane-0: SUCCESS (0.611s)
[650.856169] Starting dynamic subtest: pipe-B-plane-3
[651.310724] Dynamic subtest pipe-B-plane-3: SUCCESS (0.466s)
[651.323756] Starting dynamic subtest: pipe-B-plane-5
[651.521727] Dynamic subtest pipe-B-plane-5: SUCCESS (0.210s)
[651.852779] Subtest pixel-format-linear-modifier: SUCCESS (4.006s)
[652.723802] [137/156] (378s left) gem_flink_basic (flink-lifetime)
[652.776466] Starting subtest: flink-lifetime
[652.778823] Subtest flink-lifetime: SUCCESS (0.002s)
[652.896800] [138/156] (378s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[652.993028] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[654.114326] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: FAIL (0.522s)
[654.160785] [139/156] (376s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[654.261129] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[654.264474] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[654.366066] [140/156] (376s left) gem_exec_balancer (bonded-semaphore)
[654.437170] Starting subtest: bonded-semaphore
[654.440919] Subtest bonded-semaphore: SKIP (0.000s)
[654.759764] [141/156] (376s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[654.858531] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[654.863188] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[654.961782] [142/156] (376s left) perf (invalid-create-userspace-config)
[655.054374] Starting subtest: invalid-create-userspace-config
[655.056684] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[655.104028] [143/156] (376s left) syncobj_timeline (32bits-limit)
[655.152286] Starting subtest: 32bits-limit
[655.154756] Subtest 32bits-limit: SUCCESS (0.001s)
[655.262945] [144/156] (376s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[655.359478] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[655.363571] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[655.455870] [145/156] (375s left) gem_exec_reloc (basic-cpu-active)
[655.502453] Subtest basic-cpu-active: SKIP (0.000s)
[655.598826] [146/156] (375s left) gem_mmap_gtt (bad-object)
[655.656339] Subtest bad-object: SKIP (0.000s)
[655.716763] [147/156] (375s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[655.817663] Starting subtest: psr-rgb565-draw-blt
[662.882878] Subtest psr-rgb565-draw-blt: SUCCESS (7.070s)
[663.023226] [148/156] (368s left) gem_exec_fence (syncobj-channel)
[663.473715] Starting subtest: syncobj-channel
[664.493609] Subtest syncobj-channel: SUCCESS (1.015s)
[665.076730] [149/156] (366s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[665.180047] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[665.183945] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[665.286827] [150/156] (366s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[665.384886] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-gtt
[665.389117] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[665.482064] [151/156] (366s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[665.584418] Starting subtest: crc-primary-basic-yf-tiled-ccs
[665.586841] Starting dynamic subtest: pipe-A-eDP-1
[665.610015] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[665.624460] Starting dynamic subtest: pipe-B-eDP-1
[665.645489] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[665.657364] Starting dynamic subtest: pipe-C-eDP-1
[665.678331] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[665.689660] Starting dynamic subtest: pipe-D-eDP-1
[665.711093] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[665.715402] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.006s)
[667.911178] [152/156] (363s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[668.035290] Starting subtest: flip-vs-cursor-busy-crc-atomic
[668.762910] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.726s)
[669.630004] [153/156] (362s left) kms_plane_alpha_blend (coverage-7efc)
[669.726751] Starting subtest: coverage-7efc
[670.014359] Starting dynamic subtest: pipe-A-eDP-1
[670.626302] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.609s)
[670.658857] Starting dynamic subtest: pipe-D-eDP-1
[671.949003] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.299s)
[671.951560] Subtest coverage-7efc: SUCCESS (2.222s)
[672.782929] [154/156] (359s left) kms_properties (plane-properties-atomic)
[672.875821] Starting subtest: plane-properties-atomic
[672.878199] Starting dynamic subtest: pipe-A-eDP-1
[673.419072] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.544s)
[673.433566] Starting dynamic subtest: pipe-B-eDP-1
[674.660756] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.240s)
[674.673837] Starting dynamic subtest: pipe-C-eDP-1
[675.991474] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.328s)
[676.003815] Starting dynamic subtest: pipe-D-eDP-1
[677.219315] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.228s)
[677.221767] Subtest plane-properties-atomic: SUCCESS (4.344s)
[678.034018] [155/156] (353s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[678.142490] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt
[678.146988] Subtest psrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[678.241137] [156/156] (353s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[678.300878] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[678.303159] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[678.406843] Closing watchdogs
results: parsing output: 0/ for test: kms_pipe_crc_basic
results: parsing output: 1/ for test: kms_chamelium_audio
results: parsing output: 2/ for test: gem_eio
results: parsing output: 3/ for test: gem_pxp
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_atomic_transition
results: parsing output: 6/ for test: kms_psr
results: parsing output: 7/ for test: kms_async_flips
results: parsing output: 8/ for test: kms_vblank
results: parsing output: 9/ for test: kms_prop_blob
results: parsing output: 10/ for test: i915_query
results: parsing output: 11/ for test: kms_color_pipeline
results: parsing output: 12/ for test: gem_eio
results: parsing output: 13/ for test: i915_suspend
results: parsing output: 14/ for test: gen3_mixed_blits
results: parsing output: 15/ for test: kms_vblank
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_atomic_transition
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_mmap_wc
results: parsing output: 20/ for test: kms_flip_scaled_crc
results: parsing output: 21/ for test: kms_ccs
results: parsing output: 22/ for test: gem_exec_create
results: parsing output: 23/ for test: kms_draw_crc
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: syncobj_wait
results: parsing output: 28/ for test: i915_drm_fdinfo
results: parsing output: 29/ for test: kms_lease
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_chamelium_edid
results: parsing output: 32/ for test: gem_exec_schedule
results: parsing output: 33/ for test: gem_flink_basic
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: api_intel_bb
results: parsing output: 36/ for test: kms_flip
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: i915_fb_tiling
results: parsing output: 41/ for test: gem_softpin
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_plane_scaling
results: parsing output: 44/ for test: api_intel_allocator
results: parsing output: 45/ for test: kms_big_fb
results: parsing output: 46/ for test: kms_cursor_edge_walk
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_addfb_basic
results: parsing output: 53/ for test: kms_psr
results: parsing output: 54/ for test: perf_pmu
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: gem_exec_whisper
results: parsing output: 57/ for test: gem_exec_schedule
results: parsing output: 58/ for test: perf
results: parsing output: 59/ for test: gem_ctx_param
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_content_protection
results: parsing output: 62/ for test: drm_buddy
results: parsing output: 63/ for test: kms_flip_scaled_crc
results: parsing output: 64/ for test: kms_psr
results: parsing output: 65/ for test: kms_flip
results: parsing output: 66/ for test: kms_pm_dc
results: parsing output: 67/ for test: kms_plane_scaling
results: parsing output: 68/ for test: kms_cursor_legacy
results: parsing output: 69/ for test: gem_userptr_blits
results: parsing output: 70/ for test: gem_exec_reloc
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: gen9_exec_parse
results: parsing output: 74/ for test: gem_mmap_gtt
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: sysfs_heartbeat_interval
results: parsing output: 77/ for test: kms_psr2_sf
results: parsing output: 78/ for test: gem_exec_flush
results: parsing output: 79/ for test: gem_ctx_persistence
results: parsing output: 80/ for test: kms_psr2_sf
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: gem_softpin
results: parsing output: 83/ for test: kms_busy
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_pipe_b_c_ivb
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: perf_pmu
results: parsing output: 88/ for test: kms_cursor_crc
results: parsing output: 89/ for test: gem_lmem_swapping
results: parsing output: 90/ for test: kms_rotation_crc
results: parsing output: 91/ for test: gem_exec_reloc
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: gem_create
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_display_modes
results: parsing output: 98/ for test: gem_ccs
results: parsing output: 99/ for test: i915_pm_rpm
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_cursor_crc
results: parsing output: 102/ for test: prime_vgem
results: parsing output: 103/ for test: kms_big_fb
results: parsing output: 104/ for test: gem_userptr_blits
results: parsing output: 105/ for test: kms_ccs
results: parsing output: 106/ for test: gem_exec_flush
results: parsing output: 107/ for test: gem_pxp
results: parsing output: 108/ for test: kms_chamelium_hpd
results: parsing output: 109/ for test: syncobj_timeline
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_cursor_legacy
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: gem_exec_capture
results: parsing output: 114/ for test: gem_mmap_offset
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: gem_shrink
results: parsing output: 117/ for test: kms_plane_multiple
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: gem_wait
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: prime_self_import
results: parsing output: 124/ for test: kms_ccs
results: parsing output: 125/ for test: gem_exec_big
results: parsing output: 126/ for test: perf
results: parsing output: 127/ for test: kms_cursor_legacy
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: gem_ctx_persistence
results: parsing output: 131/ for test: kms_psr
results: parsing output: 132/ for test: kms_big_fb
results: parsing output: 133/ for test: kms_psr2_sf
results: parsing output: 134/ for test: gem_softpin
results: parsing output: 135/ for test: kms_plane
results: parsing output: 136/ for test: gem_flink_basic
results: parsing output: 137/ for test: kms_big_fb
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: gem_exec_balancer
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: perf
results: parsing output: 142/ for test: syncobj_timeline
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: gem_exec_reloc
results: parsing output: 145/ for test: gem_mmap_gtt
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: gem_exec_fence
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_frontbuffer_tracking
results: parsing output: 150/ for test: kms_ccs
results: parsing output: 151/ for test: kms_cursor_legacy
results: parsing output: 152/ for test: kms_plane_alpha_blend
results: parsing output: 153/ for test: kms_properties
results: parsing output: 154/ for test: kms_frontbuffer_tracking
results: parsing output: 155/ for test: syncobj_timeline
Done.