[51.673329] Initializing watchdogs
[51.673371]   /dev/watchdog0
[51.691122] [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]
[51.698031] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[51.700504] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[51.703974] [001/156] (960s left) kms_flip (2x-blocking-absolute-wf_vblank)
[51.828300] Starting subtest: 2x-blocking-absolute-wf_vblank
[51.837515] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.011s)
[51.907315] [002/156] (960s left) kms_flip (2x-flip-vs-expired-vblank)
[52.005793] Starting subtest: 2x-flip-vs-expired-vblank
[52.015069] Subtest 2x-flip-vs-expired-vblank: SKIP (0.011s)
[52.090266] [003/156] (960s left) kms_psr2_sf (pr-overlay-plane-update-continuous-sf)
[52.187098] Starting subtest: pr-overlay-plane-update-continuous-sf
[52.191175] Subtest pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[52.249660] [004/156] (960s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[52.355663] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[52.360017] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[52.453329] [005/156] (959s left) kms_lease (lessee-list)
[52.547131] Starting subtest: lessee-list
[52.549194] Starting dynamic subtest: pipe-A-eDP-1
[52.551199] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[52.559795] Starting dynamic subtest: pipe-B-eDP-1
[52.561401] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[52.568481] Starting dynamic subtest: pipe-C-eDP-1
[52.570160] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[52.577183] Starting dynamic subtest: pipe-D-eDP-1
[52.578663] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[52.580163] Subtest lessee-list: SUCCESS (0.008s)
[52.662716] [006/156] (959s left) kms_cursor_crc (cursor-onscreen-128x128)
[52.877026] Starting subtest: cursor-onscreen-128x128
[52.891901] Starting dynamic subtest: pipe-A-eDP-1
[54.997495] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.158s)
[55.029814] Starting dynamic subtest: pipe-D-eDP-1
[57.729185] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.755s)
[57.731588] Subtest cursor-onscreen-128x128: SUCCESS (4.957s)
[58.585395] [007/156] (953s left) kms_vrr (flip-basic)
[58.683157] Starting subtest: flip-basic
[58.689747] Subtest flip-basic: SKIP (0.000s)
[58.762810] [008/156] (953s left) kms_psr (pr-sprite-render)
[58.854098] Starting subtest: pr-sprite-render
[58.858427] Subtest pr-sprite-render: SKIP (0.000s)
[58.923595] [009/156] (953s left) gem_exec_schedule (submit-late-slice)
[59.000887] Starting subtest: submit-late-slice
[59.003311] Starting dynamic subtest: rcs0
[59.032790] Dynamic subtest rcs0: SUCCESS (0.035s)
[59.042841] Starting dynamic subtest: bcs0
[59.062566] Dynamic subtest bcs0: SUCCESS (0.030s)
[59.075103] Starting dynamic subtest: vcs0
[59.094957] Dynamic subtest vcs0: SUCCESS (0.033s)
[59.103308] Starting dynamic subtest: vcs1
[59.124080] Dynamic subtest vcs1: SUCCESS (0.029s)
[59.133417] Starting dynamic subtest: vecs0
[59.152542] Dynamic subtest vecs0: SUCCESS (0.028s)
[59.162237] Starting dynamic subtest: ccs0
[59.190269] Dynamic subtest ccs0: SUCCESS (0.038s)
[59.192636] Subtest submit-late-slice: SUCCESS (0.195s)
[60.384083] [010/156] (952s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[60.424782] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[60.464199] [011/156] (952s left) kms_psr (fbc-psr-primary-render)
[60.555217] Starting subtest: fbc-psr-primary-render
[60.557061] Starting dynamic subtest: eDP-1
[60.561028] Dynamic subtest eDP-1: SKIP (0.000s)
[60.566125] Subtest fbc-psr-primary-render: SKIP (0.000s)
[60.634384] [012/156] (951s left) i915_pm_rpm (sysfs-read)
[62.473136] Starting subtest: sysfs-read
[63.631454] Subtest sysfs-read: SUCCESS (1.165s)
[64.025742] [013/156] (948s left) kms_psr (fbc-pr-cursor-blt)
[64.126930] Starting subtest: fbc-pr-cursor-blt
[64.131043] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[64.197296] [014/156] (948s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[64.297320] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[64.301194] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[64.411552] [015/156] (948s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[64.503780] Starting subtest: fbc-rgb101010-draw-mmap-wc
[66.986377] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (2.503s)
[68.191560] [016/156] (944s left) gem_softpin (evict-single-offset)
[68.249467] Starting subtest: evict-single-offset
[97.293397] Subtest evict-single-offset: SUCCESS (29.065s)
[97.375984] [017/156] (915s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[97.546212] Starting subtest: verify-pxp-key-change-after-suspend-resume
[105.608127] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (8.064s)
[105.730199] [018/156] (907s left) kms_psr (fbc-psr2-sprite-render)
[105.841017] Starting subtest: fbc-psr2-sprite-render
[105.842996] Starting dynamic subtest: eDP-1
[105.847214] Dynamic subtest eDP-1: SKIP (0.000s)
[105.851469] Subtest fbc-psr2-sprite-render: SKIP (0.000s)
[105.918977] [019/156] (906s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[106.027437] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc
[106.031573] Subtest fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[106.117203] [020/156] (906s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[106.228084] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[106.232294] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[106.321789] [021/156] (906s left) syncobj_wait (multi-wait-submitted)
[106.388251] Starting subtest: multi-wait-submitted
[106.491363] Subtest multi-wait-submitted: SUCCESS (0.101s)
[106.567753] [022/156] (906s left) gen7_exec_parse (batch-without-end)
[106.622997] Subtest batch-without-end: SKIP (0.000s)
[106.691951] [023/156] (906s left) kms_draw_crc (draw-method-pwrite)
[106.782414] Starting subtest: draw-method-pwrite
[106.784237] Starting dynamic subtest: xrgb8888-untiled
[107.381779] Dynamic subtest xrgb8888-untiled: SUCCESS (0.600s)
[107.391246] Starting dynamic subtest: rgb565-untiled
[107.848435] Dynamic subtest rgb565-untiled: SUCCESS (0.466s)
[107.858892] Starting dynamic subtest: xrgb2101010-untiled
[108.426399] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.577s)
[108.438584] Starting dynamic subtest: xbgr16161616f-untiled
[109.214989] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.789s)
[109.224853] Starting dynamic subtest: xrgb8888-xtiled
[111.115489] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.900s)
[111.125391] Starting dynamic subtest: rgb565-xtiled
[112.259893] Dynamic subtest rgb565-xtiled: SUCCESS (1.144s)
[112.270978] Starting dynamic subtest: xrgb2101010-xtiled
[114.148666] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.888s)
[114.159680] Starting dynamic subtest: xbgr16161616f-xtiled
[117.660240] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.511s)
[117.671647] Starting dynamic subtest: xrgb8888-4tiled
[118.838448] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.177s)
[118.849134] Starting dynamic subtest: rgb565-4tiled
[119.437719] Dynamic subtest rgb565-4tiled: SUCCESS (0.599s)
[119.449162] Starting dynamic subtest: xrgb2101010-4tiled
[120.593593] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.156s)
[120.604150] Starting dynamic subtest: xbgr16161616f-4tiled
[123.771678] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.177s)
[123.774063] Subtest draw-method-pwrite: SUCCESS (16.990s)
[123.861994] [024/156] (889s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling)
[123.963312] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling
[123.968051] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.000s)
[124.035175] [025/156] (889s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[124.100367] Starting subtest: etime-single-wait-for-submit-unsubmitted
[124.203961] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[124.281625] [026/156] (888s left) prime_self_import (basic-with_one_bo)
[124.317470] Starting subtest: basic-with_one_bo
[124.342335] Subtest basic-with_one_bo: SUCCESS (0.027s)
[124.474819] [027/156] (888s left) gem_ctx_persistence (heartbeat-stop)
[124.535077] Starting subtest: heartbeat-stop
[124.539637] Subtest heartbeat-stop: SKIP (0.000s)
[124.645811] [028/156] (888s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-plflip-blt)
[124.740953] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-plflip-blt
[124.745309] Subtest fbcpsrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[124.851256] [029/156] (888s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-wc)
[124.945602] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-wc
[124.949690] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[125.051313] [030/156] (888s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs)
[125.149456] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs
[125.151775] Starting dynamic subtest: pipe-A-eDP-1
[125.176618] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[125.187080] Starting dynamic subtest: pipe-B-eDP-1
[125.209607] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[125.218612] Starting dynamic subtest: pipe-C-eDP-1
[125.237466] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[125.245762] Starting dynamic subtest: pipe-D-eDP-1
[125.265765] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[125.269351] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[127.441011] [031/156] (885s left) gem_exec_balancer (full-late)
[127.503562] Starting subtest: full-late
[132.093097] Subtest full-late: SUCCESS (4.591s)
[132.440356] [032/156] (880s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-blt)
[132.551453] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-blt
[132.555732] Subtest hdr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[132.653206] [033/156] (880s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[132.760511] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc
[132.764506] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[132.849123] [034/156] (880s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[132.956337] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[132.960568] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[133.060197] [035/156] (880s left) gem_exec_fence (invalid-timeline-fence-array)
[133.661560] Starting subtest: invalid-timeline-fence-array
[133.666455] Subtest invalid-timeline-fence-array: SUCCESS (0.005s)
[135.374388] [FACT gem_exec_fence (invalid-timeline-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[135.378091] [036/156] (878s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[135.479308] Starting subtest: fbc-psr2-cursor-plane-update-sf
[135.481683] Starting dynamic subtest: pipe-A-eDP-1
[135.567863] Dynamic subtest pipe-A-eDP-1: SKIP (0.092s)
[135.579398] Starting dynamic subtest: pipe-B-eDP-1
[136.716841] Dynamic subtest pipe-B-eDP-1: SKIP (1.149s)
[136.721564] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.242s)
[138.016224] [037/156] (875s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[138.119426] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip
[139.404467] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: FAIL (1.127s)
[139.445827] [038/156] (874s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[139.554269] Starting subtest: x-tiled-32bpp-rotate-270
[139.573528] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[139.689331] [039/156] (874s left) i915_hangman (engine-error-state-capture)
[139.756236] Starting subtest: engine-error-state-capture
[139.758143] Starting dynamic subtest: rcs0
[143.271368] Dynamic subtest rcs0: SUCCESS (3.514s)
[143.286726] Starting dynamic subtest: bcs0
[149.286456] Dynamic subtest bcs0: SUCCESS (6.013s)
[149.300186] Starting dynamic subtest: vcs0
[155.238668] Dynamic subtest vcs0: SUCCESS (5.951s)
[155.251395] Starting dynamic subtest: vcs1
[161.191836] Dynamic subtest vcs1: SUCCESS (5.951s)
[161.204248] Starting dynamic subtest: vecs0
[166.246476] Dynamic subtest vecs0: SUCCESS (5.053s)
[166.263072] Starting dynamic subtest: ccs0
[172.262701] Dynamic subtest ccs0: SUCCESS (6.015s)
[172.265560] Subtest engine-error-state-capture: SUCCESS (32.506s)
[172.346216] [040/156] (841s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[172.405655] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[172.549812] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.143s)
[172.676392] [041/156] (841s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[172.776859] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[181.800303] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (9.029s)
[181.907965] [042/156] (831s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-onoff)
[182.005324] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-onoff
[182.009191] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[182.112406] [043/156] (831s left) gem_basic (multigpu-create-close)
[182.171193] Starting subtest: multigpu-create-close
[182.176156] Subtest multigpu-create-close: SKIP (0.000s)
[182.282385] [044/156] (831s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-fullscreen)
[182.378869] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-fullscreen
[182.383060] Subtest psrhdr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[182.483463] [045/156] (831s left) kms_vblank (accuracy-idle)
[182.579477] Starting subtest: accuracy-idle
[182.581489] Starting dynamic subtest: pipe-A-eDP-1
[184.343310] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.764s)
[184.358505] Starting dynamic subtest: pipe-D-eDP-1
[186.757141] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.413s)
[186.759404] Subtest accuracy-idle: SUCCESS (4.179s)
[187.577394] [046/156] (826s left) i915_suspend (debugfs-reader)
[187.640513] Starting subtest: debugfs-reader
[205.873405] Subtest debugfs-reader: SUCCESS (3.164s)
[206.016868] [047/156] (808s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[206.128595] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[206.132721] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[206.238096] [048/156] (807s left) perf (unprivileged-single-ctx-counters)
[206.386601] Starting subtest: unprivileged-single-ctx-counters
[206.390694] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[206.436314] [049/156] (807s left) sw_sync (sync_busy_fork_unixsocket)
[206.469469] Starting subtest: sync_busy_fork_unixsocket
[206.476561] Subtest sync_busy_fork_unixsocket: SUCCESS (0.008s)
[206.537025] [050/156] (807s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[206.619371] Starting subtest: bad-aux-stride-y-tiled-ccs
[206.621603] Starting dynamic subtest: pipe-A-eDP-1
[206.643610] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[206.656573] Starting dynamic subtest: pipe-B-eDP-1
[206.676983] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[206.686849] Starting dynamic subtest: pipe-C-eDP-1
[206.707095] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[206.716757] Starting dynamic subtest: pipe-D-eDP-1
[206.738693] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[206.742610] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.003s)
[208.854681] [051/156] (805s left) api_intel_allocator (fork-simple-once)
[208.910389] Starting subtest: fork-simple-once
[208.923501] Subtest fork-simple-once: SUCCESS (0.015s)
[209.034831] [052/156] (805s left) gem_exec_flush (basic-uc-ro-default)
[209.095902] Starting subtest: basic-uc-ro-default
[214.506035] Subtest basic-uc-ro-default: SUCCESS (5.459s)
[214.667716] [053/156] (799s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[214.766543] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[214.770405] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[214.875641] [054/156] (799s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-render)
[214.970743] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-render
[214.974708] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[215.086010] [055/156] (799s left) gem_exec_gttfill (engines)
[215.147808] Starting subtest: engines
[215.150244] Starting dynamic subtest: rcs0
[237.688539] Dynamic subtest rcs0: SUCCESS (22.613s)
[237.701224] Starting dynamic subtest: bcs0
[261.002710] Dynamic subtest bcs0: SUCCESS (23.311s)
[261.013927] Starting dynamic subtest: vcs0
[284.268310] Dynamic subtest vcs0: SUCCESS (23.271s)
[284.279095] Starting dynamic subtest: vcs1
[307.549008] Dynamic subtest vcs1: SUCCESS (23.282s)
[307.566523] Starting dynamic subtest: vecs0
[330.893241] Dynamic subtest vecs0: SUCCESS (23.334s)
[330.904888] Starting dynamic subtest: ccs0
[354.262960] Dynamic subtest ccs0: SUCCESS (23.367s)
[354.265590] Subtest engines: SUCCESS (139.187s)
[354.453194] [056/156] (660s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[354.611856] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-cpu
[354.616314] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[354.713620] [057/156] (659s left) kms_cursor_legacy (torture-bo)
[354.835520] Starting subtest: torture-bo
[354.837857] Starting dynamic subtest: pipe-A
[360.768409] Dynamic subtest pipe-A: SUCCESS (5.877s)
[360.781394] Starting dynamic subtest: pipe-B
[367.726597] Dynamic subtest pipe-B: SUCCESS (7.011s)
[367.740050] Starting dynamic subtest: pipe-C
[374.754057] Dynamic subtest pipe-C: SUCCESS (7.025s)
[374.766144] Starting dynamic subtest: pipe-D
[381.037572] Dynamic subtest pipe-D: SUCCESS (6.286s)
[381.053937] Starting dynamic subtest: all-pipes
[387.201302] Dynamic subtest all-pipes: SUCCESS (6.164s)
[387.203743] Subtest torture-bo: SUCCESS (32.369s)
[387.356419] [058/156] (627s left) syncobj_wait (multi-wait-for-submit-submitted)
[387.428923] Starting subtest: multi-wait-for-submit-submitted
[387.538615] Subtest multi-wait-for-submit-submitted: SUCCESS (0.107s)
[387.615843] [059/156] (627s left) gem_eio (in-flight-internal-immediate)
[387.869573] Starting subtest: in-flight-internal-immediate
[389.439805] Subtest in-flight-internal-immediate: SUCCESS (1.571s)
[389.586083] [060/156] (625s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[389.683117] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[389.685406] Starting dynamic subtest: pipe-A-eDP-1
[389.705099] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[389.717580] Starting dynamic subtest: pipe-B-eDP-1
[389.736608] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[389.747246] Starting dynamic subtest: pipe-C-eDP-1
[389.766247] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[389.776619] Starting dynamic subtest: pipe-D-eDP-1
[389.795936] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[389.797835] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.007s)
[392.034685] [061/156] (622s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[392.085978] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[392.159783] [062/156] (622s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[392.210034] Starting subtest: invalid-multi-wait-unsubmitted
[392.212209] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[392.299511] [063/156] (622s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-pixel-formats)
[392.389496] Starting subtest: plane-scaler-with-clipping-clamping-pixel-formats
[392.391851] Starting dynamic subtest: pipe-A
[392.511181] Dynamic subtest pipe-A: SKIP (0.119s)
[392.526290] Starting dynamic subtest: pipe-B
[393.704744] Dynamic subtest pipe-B: SKIP (1.191s)
[393.716680] Starting dynamic subtest: pipe-C
[394.934592] Dynamic subtest pipe-C: SUCCESS (1.236s)
[394.946191] Starting dynamic subtest: pipe-D
[396.160978] Dynamic subtest pipe-D: SUCCESS (1.226s)
[396.163289] Subtest plane-scaler-with-clipping-clamping-pixel-formats: SUCCESS (3.775s)
[397.361239] [064/156] (617s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[397.480871] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[398.488121] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (1.005s)
[399.304521] [065/156] (615s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted)
[399.363117] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted
[399.466315] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.102s)
[399.546084] [066/156] (615s left) syncobj_timeline (multi-wait-available-submitted)
[399.612861] Starting subtest: multi-wait-available-submitted
[399.615009] Subtest multi-wait-available-submitted: SUCCESS (0.001s)
[399.717734] [067/156] (615s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[399.999234] Starting subtest: cursor-rapid-movement-256x85
[400.051024] Subtest cursor-rapid-movement-256x85: SKIP (0.046s)
[400.233103] [068/156] (614s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[400.335747] Starting subtest: yf-tiled-64bpp-rotate-180
[400.339842] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[400.433369] [069/156] (614s left) gen9_exec_parse (valid-registers)
[400.496932] Subtest valid-registers: SKIP (0.000s)
[400.565262] [070/156] (614s left) gem_softpin (noreloc)
[400.626792] Starting subtest: noreloc
[406.073533] Subtest noreloc: SUCCESS (5.392s)
[406.131902] [071/156] (609s left) kms_plane_alpha_blend (alpha-basic)
[406.219288] Starting subtest: alpha-basic
[406.510911] Starting dynamic subtest: pipe-A-eDP-1
[408.429572] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.918s)
[408.462416] Starting dynamic subtest: pipe-D-eDP-1
[411.131664] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.676s)
[411.134039] Subtest alpha-basic: SUCCESS (4.913s)
[411.976106] [072/156] (603s left) kms_chamelium_edid (hdmi-edid-read)
[412.358168] Subtest hdmi-edid-read: SKIP (0.000s)
[413.192206] [073/156] (602s left) kms_panel_fitting (atomic-fastset)
[413.296663] Starting subtest: atomic-fastset
[413.299035] Starting dynamic subtest: pipe-A-eDP-1
[413.435161] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.140s)
[413.447600] Starting dynamic subtest: pipe-B-eDP-1
[414.683981] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.247s)
[414.695950] Starting dynamic subtest: pipe-C-eDP-1
[415.868892] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.183s)
[415.883883] Starting dynamic subtest: pipe-D-eDP-1
[417.115279] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.247s)
[417.117703] Subtest atomic-fastset: SUCCESS (3.822s)
[418.269932] [074/156] (597s left) gem_userptr_blits (create-destroy-sync)
[418.343925] Starting subtest: create-destroy-sync
[423.410502] Subtest create-destroy-sync: SUCCESS (5.016s)
[423.475698] [075/156] (591s left) kms_frontbuffer_tracking (psrhdr-rgb565-draw-mmap-wc)
[423.577402] Starting subtest: psrhdr-rgb565-draw-mmap-wc
[423.581973] Subtest psrhdr-rgb565-draw-mmap-wc: SKIP (0.000s)
[423.673528] [076/156] (591s left) kms_rotation_crc (multiplane-rotation)
[423.773674] Starting subtest: multiplane-rotation
[441.334559] Subtest multiplane-rotation: SUCCESS (17.560s)
[441.465163] [077/156] (573s left) kms_plane_scaling (plane-upscale-20x20-with-modifiers)
[441.557474] Starting subtest: plane-upscale-20x20-with-modifiers
[441.559319] Starting dynamic subtest: pipe-A
[441.904459] Dynamic subtest pipe-A: SUCCESS (0.349s)
[441.915435] Starting dynamic subtest: pipe-B
[443.359703] Dynamic subtest pipe-B: SUCCESS (1.453s)
[443.371598] Starting dynamic subtest: pipe-C
[444.783113] Dynamic subtest pipe-C: SUCCESS (1.421s)
[444.798189] Starting dynamic subtest: pipe-D
[446.205369] Dynamic subtest pipe-D: SUCCESS (1.420s)
[446.207953] Subtest plane-upscale-20x20-with-modifiers: SUCCESS (4.650s)
[447.377501] [078/156] (568s left) syncobj_timeline (invalid-query-illegal-handle)
[447.430240] Starting subtest: invalid-query-illegal-handle
[447.432642] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[447.523549] [079/156] (568s left) kms_big_fb (4-tiled-8bpp-rotate-180)
[447.621602] Starting subtest: 4-tiled-8bpp-rotate-180
[448.858021] Subtest 4-tiled-8bpp-rotate-180: SUCCESS (1.237s)
[448.951689] [080/156] (566s left) api_intel_bb (simple-bb)
[449.009465] Starting subtest: simple-bb
[449.015374] Subtest simple-bb: SUCCESS (0.008s)
[449.131656] [081/156] (566s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[449.249501] Starting subtest: cursor-vs-flip-varying-size
[480.525565] Subtest cursor-vs-flip-varying-size: SUCCESS (31.268s)
[481.397227] [082/156] (534s left) sysfs_timeslice_duration (timeout)
[481.458691] Starting subtest: timeout
[481.460971] Starting dynamic subtest: vecs0
[491.375226] Dynamic subtest vecs0: SUCCESS (9.912s)
[491.388240] Starting dynamic subtest: ccs0
[492.663212] Dynamic subtest ccs0: SUCCESS (1.288s)
[492.675286] Starting dynamic subtest: vcs0
[502.387692] Dynamic subtest vcs0: SUCCESS (9.720s)
[502.399959] Starting dynamic subtest: bcs0
[503.054822] Dynamic subtest bcs0: SUCCESS (0.667s)
[503.066961] Starting dynamic subtest: rcs0
[504.330076] Dynamic subtest rcs0: SUCCESS (1.275s)
[504.344490] Starting dynamic subtest: vcs1
[514.359210] Dynamic subtest vcs1: SUCCESS (10.025s)
[514.362224] Subtest timeout: SUCCESS (32.899s)
[514.438752] [083/156] (501s left) kms_prime (d3hot)
[514.543774] Subtest D3hot: SKIP (0.000s)
[514.664675] [084/156] (501s left) vgem_basic (setversion)
[514.716456] Starting subtest: setversion
[514.720460] Subtest setversion: SUCCESS (0.000s)
[514.774713] [085/156] (501s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[514.845039] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[514.849492] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[515.054640] [086/156] (500s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[515.155791] Starting subtest: y-tiled-16bpp-rotate-0
[515.160401] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[515.256934] [087/156] (500s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[515.360271] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[518.983562] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.630s)
[520.214873] [088/156] (495s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[520.309710] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[520.337990] Starting dynamic subtest: pipe-A-plane-0
[520.728016] Dynamic subtest pipe-A-plane-0: SUCCESS (0.389s)
[520.739780] Starting dynamic subtest: pipe-A-plane-3
[521.046975] Dynamic subtest pipe-A-plane-3: SUCCESS (0.320s)
[522.181919] Starting dynamic subtest: pipe-B-plane-0
[522.549058] Dynamic subtest pipe-B-plane-0: SUCCESS (0.378s)
[522.560997] Starting dynamic subtest: pipe-B-plane-3
[522.859538] Dynamic subtest pipe-B-plane-3: SUCCESS (0.310s)
[523.181250] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (2.873s)
[524.069948] [089/156] (491s left) gem_mmap_gtt (cpuset-big-copy-xy)
[524.132910] Subtest cpuset-big-copy-XY: SKIP (0.000s)
[524.191146] [090/156] (491s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[524.311081] Starting subtest: cursorB-vs-flipB-toggle
[524.315767] Subtest cursorB-vs-flipB-toggle: SKIP (0.001s)
[524.473403] [091/156] (491s left) kms_flip (dpms-vs-vblank-race)
[524.586254] Starting subtest: dpms-vs-vblank-race
[524.588647] Starting dynamic subtest: A-eDP1
[528.513320] Dynamic subtest A-eDP1: SUCCESS (3.928s)
[528.525964] Starting dynamic subtest: B-eDP1
[532.021646] Dynamic subtest B-eDP1: SUCCESS (3.505s)
[532.033698] Starting dynamic subtest: C-eDP1
[535.596940] Dynamic subtest C-eDP1: SUCCESS (3.574s)
[535.608378] Starting dynamic subtest: D-eDP1
[539.205883] Dynamic subtest D-eDP1: SUCCESS (3.605s)
[539.208248] Subtest dpms-vs-vblank-race: SUCCESS (14.625s)
[540.085042] [092/156] (476s left) gem_ctx_isolation (preservation)
[540.146724] Starting subtest: preservation
[540.149308] Starting dynamic subtest: rcs0
[540.279942] Dynamic subtest rcs0: SUCCESS (0.132s)
[540.294257] Starting dynamic subtest: bcs0
[540.444716] Dynamic subtest bcs0: SUCCESS (0.164s)
[540.456201] Starting dynamic subtest: vcs0
[540.607901] Dynamic subtest vcs0: SUCCESS (0.161s)
[540.623382] Starting dynamic subtest: vcs1
[542.503100] Dynamic subtest vcs1: SUCCESS (1.895s)
[542.518145] Starting dynamic subtest: vecs0
[544.486402] Dynamic subtest vecs0: SUCCESS (1.982s)
[544.499128] Starting dynamic subtest: ccs0
[546.495627] Dynamic subtest ccs0: SUCCESS (2.008s)
[546.498929] Subtest preservation: SUCCESS (6.349s)
[546.736150] [093/156] (469s left) perf (gen12-oa-tlb-invalidate)
[546.851102] Starting subtest: gen12-oa-tlb-invalidate
[546.853430] Starting dynamic subtest: 0-rcs0
[556.954933] Dynamic subtest 0-rcs0: SUCCESS (10.051s)
[556.969126] Starting dynamic subtest: 1-vecs0
[567.006441] Dynamic subtest 1-vecs0: SUCCESS (10.051s)
[567.008957] Subtest gen12-oa-tlb-invalidate: SUCCESS (20.105s)
[567.049884] [094/156] (449s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[567.148169] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[567.152495] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[567.222658] [095/156] (449s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-pwrite)
[567.317642] Starting subtest: hdr-rgb101010-draw-pwrite
[567.321903] Subtest hdr-rgb101010-draw-pwrite: SKIP (0.000s)
[567.438287] [096/156] (448s left) gen7_exec_parse (basic-allocation)
[567.498779] Subtest basic-allocation: SKIP (0.000s)
[567.577028] [097/156] (448s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[567.678514] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[567.680682] Starting dynamic subtest: pipe-A-eDP-1
[567.689543] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[567.701834] Starting dynamic subtest: pipe-B-eDP-1
[567.710724] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[567.722181] Starting dynamic subtest: pipe-C-eDP-1
[567.730405] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[567.741401] Starting dynamic subtest: pipe-D-eDP-1
[567.749894] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[567.753932] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.004s)
[569.926822] [098/156] (446s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-render)
[570.022683] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-render
[570.026932] Subtest psrhdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[570.127242] [099/156] (446s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[570.222972] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[574.772183] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.555s)
[575.941436] [100/156] (440s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-blt)
[576.051892] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-blt
[576.056290] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[576.140289] [101/156] (440s left) syncobj_eventfd (binary-wait-signaled)
[576.202376] Starting subtest: binary-wait-signaled
[576.204658] Subtest binary-wait-signaled: SUCCESS (0.000s)
[576.300389] [102/156] (440s left) gem_exec_schedule (preempt-queue-chain)
[576.382743] Subtest preempt-queue-chain: SKIP (0.000s)
[576.672189] [103/156] (439s left) gem_ctx_shared (q-smoketest)
[576.736445] Starting subtest: Q-smoketest
[576.738364] Starting dynamic subtest: rcs0
[582.363925] Dynamic subtest rcs0: SUCCESS (5.526s)
[582.378180] Starting dynamic subtest: bcs0
[587.845613] Dynamic subtest bcs0: SUCCESS (5.482s)
[587.862666] Starting dynamic subtest: vcs0
[593.332136] Dynamic subtest vcs0: SUCCESS (5.487s)
[593.348265] Starting dynamic subtest: vcs1
[598.810652] Dynamic subtest vcs1: SUCCESS (5.477s)
[598.825406] Starting dynamic subtest: vecs0
[604.275895] Dynamic subtest vecs0: SUCCESS (5.465s)
[604.291797] Starting dynamic subtest: ccs0
[609.741713] Dynamic subtest ccs0: SUCCESS (5.508s)
[609.744024] Subtest Q-smoketest: SUCCESS (32.952s)
[609.872889] [104/156] (406s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-blt)
[609.978725] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-blt
[616.967376] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.996s)
[617.122351] [105/156] (399s left) gem_exec_create (legacy)
[617.188772] Starting subtest: legacy
[617.191047] Starting dynamic subtest: smem
[619.381536] Dynamic subtest smem: SUCCESS (2.194s)
[619.384375] Subtest legacy: SUCCESS (2.196s)
[619.566877] [106/156] (397s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling)
[619.669277] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling
[619.671630] Starting dynamic subtest: pipe-A-default-mode
[622.400636] Dynamic subtest pipe-A-default-mode: SUCCESS (2.730s)
[622.402631] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.731s)
[623.235175] [107/156] (393s left) gem_userptr_blits (sync-overlap)
[623.306409] Starting subtest: sync-overlap
[623.308885] Subtest sync-overlap: SUCCESS (0.000s)
[623.432297] [108/156] (393s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[623.528272] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[623.530432] Starting dynamic subtest: pipe-A-default-mode
[624.700264] Dynamic subtest pipe-A-default-mode: SKIP (1.169s)
[624.704471] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.170s)
[624.869425] [109/156] (392s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[624.958353] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[624.960506] Starting dynamic subtest: pipe-A-eDP-1
[625.708179] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.741s)
[625.720112] Starting dynamic subtest: pipe-B-eDP-1
[627.490464] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.783s)
[627.504108] Starting dynamic subtest: pipe-C-eDP-1
[629.335058] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.843s)
[629.350301] Starting dynamic subtest: pipe-D-eDP-1
[631.149121] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.813s)
[631.151157] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.185s)
[632.285095] [110/156] (384s left) gem_exec_whisper (basic-forked-all)
[632.348753] Starting subtest: basic-forked-all
[646.393223] Subtest basic-forked-all: SUCCESS (14.043s)
[646.579255] [111/156] (370s left) gem_mmap_gtt (fault-concurrent)
[646.637097] Subtest fault-concurrent: SKIP (0.000s)
[646.705602] [112/156] (370s left) syncobj_timeline (single-wait-signaled)
[646.756745] Starting subtest: single-wait-signaled
[646.759149] Subtest single-wait-signaled: SUCCESS (0.000s)
[646.880563] [113/156] (370s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[646.981418] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[646.985551] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[647.082830] [114/156] (370s left) syncobj_wait (single-wait-all-for-submit-signaled)
[647.138928] Starting subtest: single-wait-all-for-submit-signaled
[647.141255] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[647.236107] [115/156] (369s left) gem_mmap_wc (coherency)
[647.302283] Subtest coherency: SKIP (0.000s)
[647.387241] [116/156] (369s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[647.483874] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[647.487893] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[647.591204] [117/156] (369s left) kms_flip (plain-flip-ts-check-interruptible)
[647.691806] Starting subtest: plain-flip-ts-check-interruptible
[647.695972] Starting dynamic subtest: A-eDP1
[656.604203] Dynamic subtest A-eDP1: SUCCESS (8.878s)
[656.617941] Starting dynamic subtest: B-eDP1
[665.441558] Dynamic subtest B-eDP1: SUCCESS (8.835s)
[665.455914] Starting dynamic subtest: C-eDP1
[674.268788] Dynamic subtest C-eDP1: SUCCESS (8.815s)
[674.280547] Starting dynamic subtest: D-eDP1
[683.096992] Dynamic subtest D-eDP1: SUCCESS (8.826s)
[683.099083] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.371s)
[684.315942] [118/156] (333s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt)
[684.411758] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt
[684.416236] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[684.488003] [119/156] (332s left) gem_workarounds (basic-read)
[684.566497] Starting subtest: basic-read
[684.576799] Subtest basic-read: SUCCESS (0.012s)
[684.710927] [120/156] (332s left) kms_psr (psr-basic)
[684.801575] Starting subtest: psr-basic
[684.803856] Starting dynamic subtest: eDP-1
[686.406020] Dynamic subtest eDP-1: SUCCESS (1.605s)
[686.408433] Subtest psr-basic: SUCCESS (1.606s)
[687.283092] [121/156] (330s left) kms_content_protection (dp-mst-lic-type-0-hdcp14)
[687.421650] Starting subtest: dp-mst-lic-type-0-hdcp14
[687.426399] Subtest dp-mst-lic-type-0-hdcp14: SKIP (0.000s)
[687.543410] [122/156] (329s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size)
[687.659173] Starting subtest: basic-flip-before-cursor-varying-size
[688.589954] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.929s)
[689.429337] [123/156] (328s left) kms_chamelium_hpd (vga-hpd-for-each-pipe)
[689.826213] Subtest vga-hpd-for-each-pipe: SKIP (0.000s)
[690.628568] [124/156] (326s left) perf_pmu (idle-no-semaphores)
[690.669704] Starting subtest: idle-no-semaphores
[690.671887] Starting dynamic subtest: rcs0
[691.206395] Dynamic subtest rcs0: SUCCESS (0.537s)
[691.220314] Starting dynamic subtest: bcs0
[691.724760] Dynamic subtest bcs0: SUCCESS (0.513s)
[691.740046] Starting dynamic subtest: vcs0
[692.241569] Dynamic subtest vcs0: SUCCESS (0.513s)
[692.255174] Starting dynamic subtest: vcs1
[692.769528] Dynamic subtest vcs1: SUCCESS (0.524s)
[692.784131] Starting dynamic subtest: vecs0
[693.287972] Dynamic subtest vecs0: SUCCESS (0.515s)
[693.300431] Starting dynamic subtest: ccs0
[693.808451] Dynamic subtest ccs0: SUCCESS (0.517s)
[693.811531] Subtest idle-no-semaphores: SUCCESS (3.138s)
[693.925247] [125/156] (323s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[694.036309] Starting subtest: plane-primary-overlay-mutable-zpos
[697.488793] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.449s)
[698.671134] [126/156] (319s left) syncobj_wait (wait-all-delayed-signal)
[698.729747] Starting subtest: wait-all-delayed-signal
[698.831805] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[698.915515] [127/156] (318s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[699.023516] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[699.027392] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[699.125844] [128/156] (318s left) kms_lease (empty-lease)
[699.221571] Starting subtest: empty-lease
[699.223997] Starting dynamic subtest: pipe-A-eDP-1
[699.226426] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[699.240243] Starting dynamic subtest: pipe-B-eDP-1
[699.242202] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[699.253600] Starting dynamic subtest: pipe-C-eDP-1
[699.255461] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[699.266565] Starting dynamic subtest: pipe-D-eDP-1
[699.268424] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[699.270323] Subtest empty-lease: SUCCESS (0.008s)
[699.333474] [129/156] (318s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite)
[699.433173] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite
[699.437638] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[699.530722] [130/156] (318s left) kms_big_fb (yf-tiled-32bpp-rotate-270)
[699.639291] Starting subtest: yf-tiled-32bpp-rotate-270
[699.645866] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s)
[699.749926] [131/156] (318s left) gem_exec_reloc (basic-write-gtt-noreloc)
[699.804642] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[699.872681] [132/156] (318s left) gem_pread (uncached)
[699.920107] Subtest uncached: SKIP (0.000s)
[699.984613] [133/156] (318s left) kms_frontbuffer_tracking (psrhdr-1p-rte)
[700.081199] Starting subtest: psrhdr-1p-rte
[700.084640] Subtest psrhdr-1p-rte: SKIP (0.000s)
[700.185179] [134/156] (317s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-indfb-msflip-blt)
[700.282095] Starting subtest: psrhdr-1p-primscrn-indfb-msflip-blt
[700.285614] Subtest psrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[700.377486] [135/156] (317s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[700.472108] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[700.475955] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[700.580464] [136/156] (317s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-render)
[700.676985] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-render
[700.680343] Subtest psrhdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[700.778558] [137/156] (317s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[700.870439] Starting subtest: psr-modesetfrombusy
[707.060957] Subtest psr-modesetfrombusy: SUCCESS (6.190s)
[707.213783] [138/156] (310s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-onoff)
[707.309734] Starting subtest: fbchdr-1p-primscrn-cur-indfb-onoff
[707.313487] Subtest fbchdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[707.405367] [139/156] (310s left) device_reset (unbind-cold-reset-rebind)
[707.455480] Starting subtest: unbind-cold-reset-rebind
[707.459375] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[707.532011] [140/156] (310s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[707.637662] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[707.642031] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[707.737689] [141/156] (310s left) gem_create (create-ext-cpu-access-sanity-check)
[707.894462] Starting subtest: create-ext-cpu-access-sanity-check
[707.899370] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[707.983439] [142/156] (310s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[708.086302] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[708.090611] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[708.194145] [143/156] (310s left) kms_pm_rpm (i2c)
[709.726735] Starting subtest: i2c
[715.468484] Subtest i2c: SUCCESS (5.740s)
[715.569997] [144/156] (302s left) gem_mmap_gtt (hang-busy)
[715.628308] Subtest hang-busy: SKIP (0.000s)
[715.684774] [145/156] (302s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[715.781119] Starting subtest: fbcpsr-tiling-y
[715.785284] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[715.879253] [146/156] (302s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[715.981393] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[715.985496] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[716.073348] [147/156] (302s left) gem_partial_pwrite_pread (reads-display)
[716.135832] Subtest reads-display: SKIP (0.000s)
[716.214075] [148/156] (302s left) kms_cursor_edge_walk (64x64-right-edge)
[716.304494] Starting subtest: 64x64-right-edge
[716.306752] Starting dynamic subtest: pipe-A-eDP-1
[719.816913] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.510s)
[719.829669] Starting dynamic subtest: pipe-D-eDP-1
[722.953972] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.134s)
[722.956386] Subtest 64x64-right-edge: SUCCESS (6.647s)
[723.790601] [149/156] (294s left) kms_addfb_basic (bad-pitch-65536)
[723.845878] Starting subtest: bad-pitch-65536
[723.848265] Subtest bad-pitch-65536: SUCCESS (0.000s)
[724.002728] [150/156] (294s left) kms_addfb_basic (invalid-smem-bo-on-discrete)
[724.068130] Starting subtest: invalid-smem-bo-on-discrete
[724.072245] Subtest invalid-smem-bo-on-discrete: SKIP (0.000s)
[724.203002] [151/156] (294s left) kms_flip (flip-vs-panning-vs-hang)
[724.297664] Starting subtest: flip-vs-panning-vs-hang
[724.299959] Starting dynamic subtest: A-eDP1
[775.842279] Dynamic subtest A-eDP1: SUCCESS (51.530s)
[775.854417] Starting dynamic subtest: D-eDP1
[825.887777] Dynamic subtest D-eDP1: SUCCESS (50.038s)
[825.890349] Subtest flip-vs-panning-vs-hang: SUCCESS (101.572s)
[827.083273] [152/156] (191s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[827.186209] Starting subtest: plane-ctm3x4-lut1d
[827.331998] Starting dynamic subtest: pipe-A-plane-0
[827.509364] Dynamic subtest pipe-A-plane-0: SUCCESS (0.177s)
[827.523249] Starting dynamic subtest: pipe-A-plane-1
[827.698204] Dynamic subtest pipe-A-plane-1: SUCCESS (0.188s)
[827.710585] Starting dynamic subtest: pipe-A-plane-2
[827.864854] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[829.093610] Starting dynamic subtest: pipe-B-plane-0
[829.259638] Dynamic subtest pipe-B-plane-0: SUCCESS (0.177s)
[829.272770] Starting dynamic subtest: pipe-B-plane-1
[829.437762] Dynamic subtest pipe-B-plane-1: SUCCESS (0.177s)
[829.451264] Starting dynamic subtest: pipe-B-plane-2
[829.604132] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[830.808244] Starting dynamic subtest: pipe-C-plane-0
[830.965066] Dynamic subtest pipe-C-plane-0: SUCCESS (0.166s)
[830.982007] Starting dynamic subtest: pipe-C-plane-1
[831.120903] Dynamic subtest pipe-C-plane-1: SUCCESS (0.154s)
[831.139321] Starting dynamic subtest: pipe-C-plane-2
[831.275594] Dynamic subtest pipe-C-plane-2: SUCCESS (0.154s)
[832.453341] Starting dynamic subtest: pipe-D-plane-0
[832.599244] Dynamic subtest pipe-D-plane-0: SUCCESS (0.155s)
[832.613233] Starting dynamic subtest: pipe-D-plane-1
[832.764729] Dynamic subtest pipe-D-plane-1: SUCCESS (0.166s)
[832.776499] Starting dynamic subtest: pipe-D-plane-2
[832.931369] Dynamic subtest pipe-D-plane-2: SUCCESS (0.166s)
[833.231068] Subtest plane-ctm3x4-lut1d: SUCCESS (6.045s)
[834.122196] [153/156] (184s left) gem_ctx_param (set-priority-invalid-size)
[834.191105] Starting subtest: set-priority-invalid-size
[834.193464] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[834.268128] [154/156] (184s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels)
[834.360902] Starting subtest: plane-all-modeset-transition-internal-panels
[834.363218] Starting dynamic subtest: pipe-A-eDP-1
[835.668248] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.305s)
[835.949423] Starting dynamic subtest: pipe-B-eDP-1
[836.994762] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.057s)
[837.267750] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (2.907s)
[838.115766] [155/156] (180s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[838.220994] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[841.926988] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (3.711s)
[843.119498] [156/156] (175s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[843.226462] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt
[843.230866] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[843.321446] Closing watchdogs
results: parsing output: 0/ for test: kms_flip
results: parsing output: 1/ for test: kms_flip
results: parsing output: 2/ for test: kms_psr2_sf
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_lease
results: parsing output: 5/ for test: kms_cursor_crc
results: parsing output: 6/ for test: kms_vrr
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: gem_exec_schedule
results: parsing output: 9/ for test: gem_lmem_swapping
results: parsing output: 10/ for test: kms_psr
results: parsing output: 11/ for test: i915_pm_rpm
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_softpin
results: parsing output: 16/ for test: gem_pxp
results: parsing output: 17/ for test: kms_psr
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: syncobj_wait
results: parsing output: 21/ for test: gen7_exec_parse
results: parsing output: 22/ for test: kms_draw_crc
results: parsing output: 23/ for test: kms_flip_scaled_crc
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: prime_self_import
results: parsing output: 26/ for test: gem_ctx_persistence
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_ccs
results: parsing output: 30/ for test: gem_exec_balancer
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_exec_fence
results: parsing output: 35/ for test: kms_psr2_sf
results: parsing output: 36/ for test: kms_big_fb
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: i915_hangman
results: parsing output: 39/ for test: gem_pxp
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_basic
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_vblank
results: parsing output: 45/ for test: i915_suspend
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: perf
results: parsing output: 48/ for test: sw_sync
results: parsing output: 49/ for test: kms_ccs
results: parsing output: 50/ for test: api_intel_allocator
results: parsing output: 51/ for test: gem_exec_flush
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_exec_gttfill
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_cursor_legacy
results: parsing output: 57/ for test: syncobj_wait
results: parsing output: 58/ for test: gem_eio
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: gem_exec_reloc
results: parsing output: 61/ for test: syncobj_wait
results: parsing output: 62/ for test: kms_plane_scaling
results: parsing output: 63/ for test: kms_cursor_legacy
results: parsing output: 64/ for test: syncobj_timeline
results: parsing output: 65/ for test: syncobj_timeline
results: parsing output: 66/ for test: kms_cursor_crc
results: parsing output: 67/ for test: kms_big_fb
results: parsing output: 68/ for test: gen9_exec_parse
results: parsing output: 69/ for test: gem_softpin
results: parsing output: 70/ for test: kms_plane_alpha_blend
results: parsing output: 71/ for test: kms_chamelium_edid
results: parsing output: 72/ for test: kms_panel_fitting
results: parsing output: 73/ for test: gem_userptr_blits
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_rotation_crc
results: parsing output: 76/ for test: kms_plane_scaling
results: parsing output: 77/ for test: syncobj_timeline
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: api_intel_bb
results: parsing output: 80/ for test: kms_cursor_legacy
results: parsing output: 81/ for test: sysfs_timeslice_duration
results: parsing output: 82/ for test: kms_prime
results: parsing output: 83/ for test: vgem_basic
results: parsing output: 84/ for test: gem_render_copy
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_plane
results: parsing output: 88/ for test: gem_mmap_gtt
results: parsing output: 89/ for test: kms_cursor_legacy
results: parsing output: 90/ for test: kms_flip
results: parsing output: 91/ for test: gem_ctx_isolation
results: parsing output: 92/ for test: perf
results: parsing output: 93/ for test: kms_plane
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gen7_exec_parse
results: parsing output: 96/ for test: kms_ccs
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: syncobj_eventfd
results: parsing output: 101/ for test: gem_exec_schedule
results: parsing output: 102/ for test: gem_ctx_shared
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_create
results: parsing output: 105/ for test: kms_flip_scaled_crc
results: parsing output: 106/ for test: gem_userptr_blits
results: parsing output: 107/ for test: kms_flip_scaled_crc
results: parsing output: 108/ for test: kms_ccs
results: parsing output: 109/ for test: gem_exec_whisper
results: parsing output: 110/ for test: gem_mmap_gtt
results: parsing output: 111/ for test: syncobj_timeline
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: syncobj_wait
results: parsing output: 114/ for test: gem_mmap_wc
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_flip
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: gem_workarounds
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: kms_content_protection
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: kms_chamelium_hpd
results: parsing output: 123/ for test: perf_pmu
results: parsing output: 124/ for test: kms_atomic
results: parsing output: 125/ for test: syncobj_wait
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_lease
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: gem_exec_reloc
results: parsing output: 131/ for test: gem_pread
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_frontbuffer_tracking
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: kms_frontbuffer_tracking
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: device_reset
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: gem_create
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: kms_pm_rpm
results: parsing output: 143/ for test: gem_mmap_gtt
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: gem_partial_pwrite_pread
results: parsing output: 147/ for test: kms_cursor_edge_walk
results: parsing output: 148/ for test: kms_addfb_basic
results: parsing output: 149/ for test: kms_addfb_basic
results: parsing output: 150/ for test: kms_flip
results: parsing output: 151/ for test: kms_color_pipeline
results: parsing output: 152/ for test: gem_ctx_param
results: parsing output: 153/ for test: kms_atomic_transition
results: parsing output: 154/ for test: kms_frontbuffer_tracking
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.