[61.875776] Initializing watchdogs
[61.875820]   /dev/watchdog0
[61.889829] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[61.894079] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.896700] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.899988] [001/131] (960s left) kms_properties (crtc-properties-atomic)
[62.026304] Starting subtest: crtc-properties-atomic
[62.028723] Starting dynamic subtest: pipe-A-eDP-1
[62.499208] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.472s)
[62.513532] Starting dynamic subtest: pipe-B-eDP-1
[63.682640] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.183s)
[63.691494] Starting dynamic subtest: pipe-C-eDP-1
[64.808775] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.125s)
[64.819815] Starting dynamic subtest: pipe-D-eDP-1
[65.946812] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.138s)
[65.949224] Subtest crtc-properties-atomic: SUCCESS (3.921s)
[66.755629] [002/131] (955s left) kms_async_flips (crc)
[66.887714] Starting subtest: crc
[67.269432] Starting dynamic subtest: pipe-A-eDP-1
[70.174225] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.902s)
[70.549916] Starting dynamic subtest: pipe-B-eDP-1
[73.482792] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.939s)
[73.856421] Starting dynamic subtest: pipe-C-eDP-1
[76.710711] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.862s)
[77.079069] Starting dynamic subtest: pipe-D-eDP-1
[79.915426] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.843s)
[79.917766] Subtest crc: SUCCESS (13.025s)
[81.105224] [003/131] (941s left) gem_softpin (evict-prime-sanity-check)
[81.177959] Starting subtest: evict-prime-sanity-check
[81.180062] Starting dynamic subtest: rcs0
[82.335079] Dynamic subtest rcs0: SUCCESS (1.159s)
[82.345291] Starting dynamic subtest: bcs0
[83.453751] Dynamic subtest bcs0: SUCCESS (1.118s)
[83.463998] Starting dynamic subtest: vcs0
[84.938915] Dynamic subtest vcs0: SUCCESS (1.476s)
[84.948631] Starting dynamic subtest: vcs1
[86.931320] Dynamic subtest vcs1: SUCCESS (1.998s)
[86.940599] Starting dynamic subtest: vecs0
[88.892386] Dynamic subtest vecs0: SUCCESS (1.959s)
[88.901922] Starting dynamic subtest: ccs0
[90.036068] Dynamic subtest ccs0: SUCCESS (1.144s)
[90.045913] Starting dynamic subtest: all
[91.172264] Dynamic subtest all: SUCCESS (1.135s)
[91.175224] Subtest evict-prime-sanity-check: SUCCESS (9.995s)
[91.250917] [004/131] (931s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[91.374259] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[91.378186] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[91.489399] [005/131] (931s left) gem_lmem_swapping (random)
[91.535128] Subtest random: SKIP (0.000s)
[91.571887] [006/131] (931s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[91.672312] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-wc
[91.677054] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[91.764875] [007/131] (930s left) kms_vblank (query-forked-busy)
[91.855031] Starting subtest: query-forked-busy
[91.857273] Starting dynamic subtest: pipe-A-eDP-1
[93.755038] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.896s)
[93.767640] Starting dynamic subtest: pipe-D-eDP-1
[96.361348] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.605s)
[96.363812] Subtest query-forked-busy: SUCCESS (4.505s)
[97.167376] [008/131] (925s left) gem_ctx_param (basic)
[97.216933] Starting subtest: basic
[97.221674] Subtest basic: SUCCESS (0.003s)
[97.322823] [009/131] (925s left) kms_properties (colorop-properties-legacy)
[97.409079] Starting subtest: colorop-properties-legacy
[97.411394] Starting dynamic subtest: pipe-A-eDP-1
[97.784163] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.376s)
[97.797450] Starting dynamic subtest: pipe-B-eDP-1
[98.839202] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.053s)
[98.846306] Starting dynamic subtest: pipe-C-eDP-1
[99.868812] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.029s)
[99.876453] Starting dynamic subtest: pipe-D-eDP-1
[100.904613] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.035s)
[100.906950] Subtest colorop-properties-legacy: SUCCESS (3.496s)
[101.707519] [010/131] (921s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[101.805093] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[108.629871] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.827s)
[108.751889] [011/131] (914s left) kms_async_flips (test-time-stamp-atomic)
[108.842404] Starting subtest: test-time-stamp-atomic
[109.219813] Starting dynamic subtest: pipe-A-eDP-1
[110.014422] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.792s)
[110.403742] Starting dynamic subtest: pipe-B-eDP-1
[111.155094] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.756s)
[111.532935] Starting dynamic subtest: pipe-C-eDP-1
[112.289174] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.761s)
[112.653285] Starting dynamic subtest: pipe-D-eDP-1
[113.419750] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.770s)
[113.422241] Subtest test-time-stamp-atomic: SUCCESS (4.577s)
[114.581242] [012/131] (908s left) kms_pm_backlight (basic-brightness)
[114.680157] Starting subtest: basic-brightness
[114.729140] Starting dynamic subtest: eDP-1
[115.036511] Dynamic subtest eDP-1: SUCCESS (0.307s)
[115.038927] Subtest basic-brightness: SUCCESS (0.357s)
[115.914467] [013/131] (906s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[116.010260] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[123.368195] Subtest psr-1p-primscrn-indfb-msflip-blt: SUCCESS (7.361s)
[123.479632] [014/131] (899s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs)
[123.566773] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs
[123.571345] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[124.697252] [015/131] (898s left) gem_exec_schedule (pi-userfault)
[124.876450] Starting subtest: pi-userfault
[124.878980] Starting dynamic subtest: rcs0
[124.884838] Dynamic subtest rcs0: SUCCESS (0.011s)
[124.895338] Starting dynamic subtest: bcs0
[124.897256] Dynamic subtest bcs0: SUCCESS (0.007s)
[124.904540] Starting dynamic subtest: vcs0
[124.907187] Dynamic subtest vcs0: SUCCESS (0.008s)
[124.916645] Starting dynamic subtest: vcs1
[124.918963] Dynamic subtest vcs1: SUCCESS (0.009s)
[124.926331] Starting dynamic subtest: vecs0
[124.928523] Dynamic subtest vecs0: SUCCESS (0.008s)
[124.935358] Starting dynamic subtest: ccs0
[124.937371] Dynamic subtest ccs0: SUCCESS (0.008s)
[124.939249] Subtest pi-userfault: SUCCESS (0.053s)
[125.108315] [016/131] (897s left) perf_pmu (event-wait)
[125.151635] Starting subtest: event-wait
[125.154307] Starting dynamic subtest: rcs0
[125.158882] Dynamic subtest rcs0: SKIP (0.000s)
[125.163573] Subtest event-wait: SKIP (0.000s)
[125.265176] [017/131] (897s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[125.361128] Starting subtest: fbcpsr-2p-rte
[125.365244] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[125.443329] [018/131] (897s left) kms_big_fb (4-tiled-addfb)
[125.561686] Starting subtest: 4-tiled-addfb
[125.563981] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[125.661523] [019/131] (897s left) gen7_exec_parse (cmd-crossing-page)
[125.712328] Subtest cmd-crossing-page: SKIP (0.000s)
[125.776649] [020/131] (897s left) kms_psr (fbc-psr-cursor-mmap-gtt)
[125.868736] Starting subtest: fbc-psr-cursor-mmap-gtt
[125.871081] Starting dynamic subtest: eDP-1
[125.875355] Dynamic subtest eDP-1: SKIP (0.000s)
[125.879425] Subtest fbc-psr-cursor-mmap-gtt: SKIP (0.001s)
[125.934104] [021/131] (897s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-move)
[126.039212] Starting subtest: psr-1p-primscrn-spr-indfb-move
[133.591056] Subtest psr-1p-primscrn-spr-indfb-move: SUCCESS (7.554s)
[133.699316] [022/131] (889s left) gem_exec_flush (basic-wb-set-default)
[133.759959] Starting subtest: basic-wb-set-default
[139.214685] Subtest basic-wb-set-default: SUCCESS (5.457s)
[139.402727] [023/131] (883s left) gen9_exec_parse (unaligned-jump)
[139.467210] Subtest unaligned-jump: SKIP (0.000s)
[139.534487] [024/131] (883s left) kms_scaling_modes (scaling-mode-full-aspect)
[139.622904] Starting subtest: scaling-mode-full-aspect
[139.625206] Starting dynamic subtest: pipe-A-eDP-1
[139.714373] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.093s)
[139.722251] Starting dynamic subtest: pipe-B-eDP-1
[140.936148] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.220s)
[140.944354] Starting dynamic subtest: pipe-C-eDP-1
[142.052549] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.114s)
[142.062270] Starting dynamic subtest: pipe-D-eDP-1
[143.196683] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.143s)
[143.199102] Subtest scaling-mode-full-aspect: SUCCESS (3.577s)
[144.354519] [025/131] (878s left) perf_pmu (busy)
[144.390464] Starting subtest: busy
[144.392772] Starting dynamic subtest: rcs0
[144.975928] Dynamic subtest rcs0: SUCCESS (0.585s)
[144.986741] Starting dynamic subtest: bcs0
[145.541080] Dynamic subtest bcs0: SUCCESS (0.564s)
[145.551844] Starting dynamic subtest: vcs0
[146.902665] Dynamic subtest vcs0: SUCCESS (1.359s)
[146.913373] Starting dynamic subtest: vcs1
[148.948990] Dynamic subtest vcs1: SUCCESS (2.042s)
[148.958503] Starting dynamic subtest: vecs0
[150.933356] Dynamic subtest vecs0: SUCCESS (1.982s)
[150.943074] Starting dynamic subtest: ccs0
[151.493677] Dynamic subtest ccs0: SUCCESS (0.560s)
[151.496497] Subtest busy: SUCCESS (7.104s)
[151.601541] [026/131] (871s left) kms_draw_crc (draw-method-render)
[151.710836] Starting subtest: draw-method-render
[151.713079] Starting dynamic subtest: xrgb8888-untiled
[151.995542] Dynamic subtest xrgb8888-untiled: SUCCESS (0.286s)
[152.004273] Starting dynamic subtest: rgb565-untiled
[152.296495] Dynamic subtest rgb565-untiled: SUCCESS (0.300s)
[152.304246] Starting dynamic subtest: xrgb2101010-untiled
[152.539595] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.243s)
[152.549727] Starting dynamic subtest: xbgr16161616f-untiled
[152.761733] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.222s)
[152.769481] Starting dynamic subtest: xrgb8888-xtiled
[152.884151] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.121s)
[152.891841] Starting dynamic subtest: rgb565-xtiled
[153.039754] Dynamic subtest rgb565-xtiled: SUCCESS (0.154s)
[153.047088] Starting dynamic subtest: xrgb2101010-xtiled
[153.161665] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.120s)
[153.169237] Starting dynamic subtest: xbgr16161616f-xtiled
[153.295344] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[153.303384] Starting dynamic subtest: xrgb8888-4tiled
[153.419469] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[153.435227] Starting dynamic subtest: rgb565-4tiled
[153.540234] Dynamic subtest rgb565-4tiled: SUCCESS (0.121s)
[153.547753] Starting dynamic subtest: xrgb2101010-4tiled
[153.662680] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.120s)
[153.672892] Starting dynamic subtest: xbgr16161616f-4tiled
[153.795751] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.132s)
[153.797793] Subtest draw-method-render: SUCCESS (2.088s)
[153.916641] [027/131] (869s left) gem_partial_pwrite_pread (writes-after-reads-snoop)
[153.968115] Subtest writes-after-reads-snoop: SKIP (0.000s)
[154.032052] [028/131] (869s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc)
[154.131259] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc
[154.133162] Starting dynamic subtest: pipe-A-eDP-1
[154.150084] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[154.159026] Starting dynamic subtest: pipe-B-eDP-1
[154.175224] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[154.182200] Starting dynamic subtest: pipe-C-eDP-1
[154.197923] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[154.206255] Starting dynamic subtest: pipe-D-eDP-1
[154.221562] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[154.223062] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.005s)
[156.334153] [029/131] (867s left) gem_mmap_gtt (big-bo-tiledy)
[156.402625] Subtest big-bo-tiledY: SKIP (0.000s)
[156.458994] [030/131] (867s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[156.524155] Starting subtest: multi-wait-available-submitted-signaled
[156.526481] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.001s)
[156.640568] [031/131] (866s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[156.741690] Starting subtest: primary-x-tiled-reflect-x-0
[163.776063] Subtest primary-x-tiled-reflect-x-0: SUCCESS (7.036s)
[165.026125] [032/131] (858s left) perf (invalid-oa-format-id)
[165.146766] Starting subtest: invalid-oa-format-id
[165.159977] Subtest invalid-oa-format-id: SUCCESS (0.014s)
[165.202701] [033/131] (858s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[165.303799] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[165.306088] Starting dynamic subtest: pipe-A
[165.654376] Dynamic subtest pipe-A: SUCCESS (0.352s)
[165.662633] Starting dynamic subtest: pipe-B
[167.043058] Dynamic subtest pipe-B: SUCCESS (1.388s)
[167.050993] Starting dynamic subtest: pipe-C
[168.499478] Dynamic subtest pipe-C: SUCCESS (1.456s)
[168.507784] Starting dynamic subtest: pipe-D
[169.937629] Dynamic subtest pipe-D: SUCCESS (1.437s)
[169.939999] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (4.637s)
[171.082158] [034/131] (852s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[171.139461] Starting subtest: invalid-bsd2-flag-on-vebox
[171.141954] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[171.256227] [035/131] (852s left) syncobj_timeline (single-wait-all-signaled)
[171.322058] Starting subtest: single-wait-all-signaled
[171.323911] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[171.452242] [036/131] (852s left) kms_rotation_crc (sprite-rotation-90)
[171.565248] Starting subtest: sprite-rotation-90
[171.569120] Subtest sprite-rotation-90: SKIP (0.001s)
[171.634770] [037/131] (852s left) syncobj_timeline (invalid-signal-bad-pad)
[171.693661] Starting subtest: invalid-signal-bad-pad
[171.696044] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[171.809818] [038/131] (852s left) drm_buddy (drm_buddy)
[171.842465] Starting subtest: drm_buddy
[171.846735] Subtest drm_buddy: SKIP (0.001s)
[171.897854] [039/131] (851s left) gem_exec_balancer (nop)
[171.957185] Starting subtest: nop
[218.746582] Subtest nop: SUCCESS (46.788s)
[219.040375] [040/131] (804s left) gem_exec_reloc (basic-write-read-active)
[219.099016] Subtest basic-write-read-active: SKIP (0.000s)
[219.177422] [041/131] (804s left) gem_softpin (noreloc)
[219.231880] Starting subtest: noreloc
[224.651096] Subtest noreloc: SUCCESS (5.417s)
[224.779361] [042/131] (799s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[224.882295] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[229.658118] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.779s)
[230.796584] [043/131] (793s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[230.859258] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[230.863675] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[231.036878] [044/131] (793s left) kms_cursor_crc (cursor-rapid-movement-32x10)
[231.215246] Starting subtest: cursor-rapid-movement-32x10
[231.258674] Subtest cursor-rapid-movement-32x10: SKIP (0.041s)
[231.552529] [045/131] (792s left) gem_exec_params (mmapped)
[231.622874] Starting subtest: mmapped
[231.627833] Subtest mmapped: SUCCESS (0.003s)
[231.731364] [046/131] (792s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[231.841150] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[231.845411] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[231.936420] [047/131] (792s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[232.032238] Starting subtest: blocking-absolute-wf_vblank-interruptible
[232.036338] Starting dynamic subtest: A-eDP1
[240.727153] Dynamic subtest A-eDP1: SUCCESS (8.691s)
[240.738249] Starting dynamic subtest: B-eDP1
[249.460685] Dynamic subtest B-eDP1: SUCCESS (8.731s)
[249.474358] Starting dynamic subtest: C-eDP1
[258.182741] Dynamic subtest C-eDP1: SUCCESS (8.720s)
[258.191115] Starting dynamic subtest: D-eDP1
[266.915300] Dynamic subtest D-eDP1: SUCCESS (8.730s)
[266.920558] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.886s)
[268.129504] [048/131] (756s left) kms_psr (psr2-cursor-blt)
[268.219207] Starting subtest: psr2-cursor-blt
[268.221518] Starting dynamic subtest: eDP-1
[269.911120] Dynamic subtest eDP-1: SUCCESS (1.693s)
[269.913382] Subtest psr2-cursor-blt: SUCCESS (1.694s)
[270.718947] [049/131] (753s left) kms_lease (lease-invalid-crtc)
[270.810934] Starting subtest: lease-invalid-crtc
[270.813294] Starting dynamic subtest: pipe-A-eDP-1
[270.815681] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[270.824267] Starting dynamic subtest: pipe-B-eDP-1
[270.826505] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[270.834585] Starting dynamic subtest: pipe-C-eDP-1
[270.836583] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[270.844440] Starting dynamic subtest: pipe-D-eDP-1
[270.846772] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[270.849074] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[270.885020] [050/131] (753s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[270.996101] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[276.279460] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.286s)
[277.420917] [051/131] (746s left) kms_pipe_crc_basic (read-crc)
[277.511056] Starting subtest: read-crc
[277.513353] Starting dynamic subtest: pipe-A-eDP-1
[278.230900] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.720s)
[278.239266] Starting dynamic subtest: pipe-B-eDP-1
[279.585565] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.354s)
[279.593634] Starting dynamic subtest: pipe-C-eDP-1
[280.889079] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.301s)
[280.897527] Starting dynamic subtest: pipe-D-eDP-1
[282.220544] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.328s)
[282.222828] Subtest read-crc: SUCCESS (4.709s)
[283.047384] [052/131] (741s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0)
[283.147204] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0
[283.151184] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[283.250959] [053/131] (741s left) kms_addfb_basic (invalid-set-prop-any)
[283.302183] Starting subtest: invalid-set-prop-any
[283.304323] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[283.454254] [054/131] (741s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-upscaling)
[283.568780] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-upscaling
[283.573229] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-upscaling: SKIP (0.000s)
[283.637384] [055/131] (740s left) gem_exec_fence (nb-await)
[283.812738] Starting subtest: nb-await
[283.815208] Starting dynamic subtest: rcs0
[283.882306] Dynamic subtest rcs0: SUCCESS (0.069s)
[283.893620] Starting dynamic subtest: bcs0
[283.940673] Dynamic subtest bcs0: SUCCESS (0.056s)
[283.949804] Starting dynamic subtest: vcs0
[284.000057] Dynamic subtest vcs0: SUCCESS (0.057s)
[284.009796] Starting dynamic subtest: vcs1
[284.059554] Dynamic subtest vcs1: SUCCESS (0.057s)
[284.069793] Starting dynamic subtest: vecs0
[284.116648] Dynamic subtest vecs0: SUCCESS (0.056s)
[284.127554] Starting dynamic subtest: ccs0
[284.174940] Dynamic subtest ccs0: SUCCESS (0.056s)
[284.177590] Subtest nb-await: SUCCESS (0.364s)
[285.978924] [FACT gem_exec_fence (nb-await)] new: kernel.kmod_is_loaded.vgem: true
[285.982198] [056/131] (738s left) gem_gtt_cpu_tlb
[286.098375] [057/131] (738s left) kms_flip (dpms-off-confusion-interruptible)
[286.182844] Starting subtest: dpms-off-confusion-interruptible
[286.184734] Starting dynamic subtest: A-eDP1
[294.855515] Dynamic subtest A-eDP1: SUCCESS (8.668s)
[294.864557] Starting dynamic subtest: B-eDP1
[303.478819] Dynamic subtest B-eDP1: SUCCESS (8.623s)
[303.491438] Starting dynamic subtest: C-eDP1
[312.101701] Dynamic subtest C-eDP1: SUCCESS (8.620s)
[312.109837] Starting dynamic subtest: D-eDP1
[320.729736] Dynamic subtest D-eDP1: SUCCESS (8.626s)
[320.734713] Subtest dpms-off-confusion-interruptible: SUCCESS (34.550s)
[321.902166] [058/131] (702s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[322.009457] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[322.013894] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[322.104591] [059/131] (702s left) gem_mmap_gtt (flink-race)
[322.167606] Subtest flink-race: SKIP (0.000s)
[322.228897] [060/131] (702s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[322.358334] Starting subtest: basic-busy-flip-before-cursor-varying-size
[322.362658] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[322.503949] [061/131] (702s left) sysfs_defaults (readonly)
[322.556411] Starting subtest: readonly
[322.558762] Starting dynamic subtest: vecs0
[322.561166] Dynamic subtest vecs0: SUCCESS (0.000s)
[322.570809] Starting dynamic subtest: ccs0
[322.572790] Dynamic subtest ccs0: SUCCESS (0.000s)
[322.580908] Starting dynamic subtest: vcs0
[322.583148] Dynamic subtest vcs0: SUCCESS (0.000s)
[322.591244] Starting dynamic subtest: bcs0
[322.593182] Dynamic subtest bcs0: SUCCESS (0.000s)
[322.600755] Starting dynamic subtest: rcs0
[322.602672] Dynamic subtest rcs0: SUCCESS (0.000s)
[322.610184] Starting dynamic subtest: vcs1
[322.612062] Dynamic subtest vcs1: SUCCESS (0.000s)
[322.613985] Subtest readonly: SUCCESS (0.006s)
[322.654448] [062/131] (702s left) kms_psr (psr2-sprite-blt)
[322.747217] Starting subtest: psr2-sprite-blt
[322.749504] Starting dynamic subtest: eDP-1
[324.502554] Dynamic subtest eDP-1: SUCCESS (1.755s)
[324.504860] Subtest psr2-sprite-blt: SUCCESS (1.756s)
[325.372489] [063/131] (699s left) gem_reg_read (timestamp-moving)
[325.432752] Starting subtest: timestamp-moving
[326.433275] Subtest timestamp-moving: SUCCESS (1.001s)
[326.511893] [064/131] (698s left) kms_color_pipeline (plane-lut1d-lut1d)
[326.608974] Starting subtest: plane-lut1d-lut1d
[326.776833] Starting dynamic subtest: pipe-A-plane-0
[326.932094] Dynamic subtest pipe-A-plane-0: SUCCESS (0.155s)
[326.940202] Starting dynamic subtest: pipe-A-plane-1
[327.065389] Dynamic subtest pipe-A-plane-1: SUCCESS (0.133s)
[327.073266] Starting dynamic subtest: pipe-A-plane-2
[327.221164] Dynamic subtest pipe-A-plane-2: SUCCESS (0.155s)
[328.373068] Starting dynamic subtest: pipe-B-plane-0
[328.520879] Dynamic subtest pipe-B-plane-0: SUCCESS (0.155s)
[328.528934] Starting dynamic subtest: pipe-B-plane-1
[328.654075] Dynamic subtest pipe-B-plane-1: SUCCESS (0.133s)
[328.662322] Starting dynamic subtest: pipe-B-plane-2
[328.787594] Dynamic subtest pipe-B-plane-2: SUCCESS (0.133s)
[329.990390] Starting dynamic subtest: pipe-C-plane-0
[330.116991] Dynamic subtest pipe-C-plane-0: SUCCESS (0.133s)
[330.125190] Starting dynamic subtest: pipe-C-plane-1
[330.250512] Dynamic subtest pipe-C-plane-1: SUCCESS (0.133s)
[330.259243] Starting dynamic subtest: pipe-C-plane-2
[330.383804] Dynamic subtest pipe-C-plane-2: SUCCESS (0.132s)
[331.524823] Starting dynamic subtest: pipe-D-plane-0
[331.651293] Dynamic subtest pipe-D-plane-0: SUCCESS (0.133s)
[331.659956] Starting dynamic subtest: pipe-D-plane-1
[331.784600] Dynamic subtest pipe-D-plane-1: SUCCESS (0.133s)
[331.793368] Starting dynamic subtest: pipe-D-plane-2
[331.918566] Dynamic subtest pipe-D-plane-2: SUCCESS (0.133s)
[332.205214] Subtest plane-lut1d-lut1d: SUCCESS (5.597s)
[333.002191] [065/131] (691s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[333.108958] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[333.136056] Starting dynamic subtest: pipe-A-plane-0
[333.424424] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[333.433866] Starting dynamic subtest: pipe-A-plane-3
[333.691188] Dynamic subtest pipe-A-plane-3: SUCCESS (0.266s)
[333.699201] Starting dynamic subtest: pipe-A-plane-5
[333.703454] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[334.802396] Starting dynamic subtest: pipe-B-plane-0
[335.107761] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[335.117421] Starting dynamic subtest: pipe-B-plane-3
[335.340138] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[335.348789] Starting dynamic subtest: pipe-B-plane-5
[335.353046] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[335.661682] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.551s)
[336.489547] [066/131] (688s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[336.618425] Starting subtest: modeset-atomic-cursor-hotspot
[336.623201] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[336.762847] [067/131] (688s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy)
[336.827273] Starting subtest: map-fixed-invalidate-overlap-busy
[336.831864] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s)
[336.986620] [068/131] (688s left) gem_exec_schedule (u-semaphore-user)
[337.053275] Starting subtest: u-semaphore-user
[337.103541] Subtest u-semaphore-user: SUCCESS (0.057s)
[338.951809] [069/131] (686s left) kms_chamelium_color (gamma)
[339.052511] Subtest gamma: SKIP (0.000s)
[339.095534] [070/131] (686s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc)
[339.185593] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc
[345.762305] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.580s)
[345.882970] [071/131] (679s left) perf (polling-parameterized)
[345.987212] Starting subtest: polling-parameterized
[366.005302] Subtest polling-parameterized: SUCCESS (20.016s)
[366.053470] [072/131] (659s left) gem_pxp (create-protected-buffer)
[366.186781] Starting subtest: create-protected-buffer
[366.189265] Subtest create-protected-buffer: SUCCESS (0.000s)
[366.322573] [073/131] (658s left) gem_ctx_persistence (engines-cleanup)
[366.380639] Starting subtest: engines-cleanup
[366.383052] Starting dynamic subtest: rcs0
[366.459658] Dynamic subtest rcs0: SUCCESS (0.078s)
[366.471850] Starting dynamic subtest: bcs0
[366.517661] Dynamic subtest bcs0: SUCCESS (0.056s)
[366.528357] Starting dynamic subtest: vcs0
[366.573777] Dynamic subtest vcs0: SUCCESS (0.054s)
[366.584535] Starting dynamic subtest: vcs1
[366.621485] Dynamic subtest vcs1: SUCCESS (0.046s)
[366.632063] Starting dynamic subtest: vecs0
[366.670843] Dynamic subtest vecs0: SUCCESS (0.046s)
[366.681201] Starting dynamic subtest: ccs0
[366.719485] Dynamic subtest ccs0: SUCCESS (0.047s)
[366.722379] Subtest engines-cleanup: SUCCESS (0.339s)
[366.801205] [074/131] (658s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[366.914525] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[366.918590] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[367.007115] [075/131] (658s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[367.109188] Starting subtest: yf-tiled-16bpp-rotate-0
[367.112867] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[367.195037] [076/131] (658s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[367.299879] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[367.303432] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[367.417913] [077/131] (658s left) gem_ctx_engines (execute-one)
[367.480876] Starting subtest: execute-one
[369.752630] Subtest execute-one: SUCCESS (2.272s)
[369.971082] [078/131] (655s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted)
[370.022877] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted
[370.024969] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s)
[370.112773] [079/131] (655s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[370.223617] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[370.227218] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s)
[370.329433] [080/131] (655s left) gem_exec_reloc (basic-wc-gtt-noreloc)
[370.382144] Subtest basic-wc-gtt-noreloc: SKIP (0.000s)
[370.453093] [081/131] (655s left) kms_selftest (drm_cmdline_parser)
[370.492833] Starting subtest: drm_cmdline_parser
[370.522760] Starting dynamic subtest: drm_test_cmdline_force_d_only
[370.620531] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.099s)
[370.629978] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[370.703887] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.081s)
[370.714026] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[370.796945] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.091s)
[370.806173] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[370.881078] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.083s)
[370.890701] Starting dynamic subtest: drm_test_cmdline_force_e_only
[370.975802] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.094s)
[370.984970] Starting dynamic subtest: drm_test_cmdline_res
[371.087099] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.111s)
[371.096384] Starting dynamic subtest: drm_test_cmdline_res_vesa
[371.196590] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.108s)
[371.205819] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[371.292533] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.096s)
[371.301917] Starting dynamic subtest: drm_test_cmdline_res_rblank
[371.377175] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.084s)
[371.386359] Starting dynamic subtest: drm_test_cmdline_res_bpp
[371.473604] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.096s)
[371.483527] Starting dynamic subtest: drm_test_cmdline_res_refresh
[371.569572] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.094s)
[371.577755] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[371.661587] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.091s)
[371.670060] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[371.761013] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.099s)
[371.768415] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[371.869572] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.107s)
[371.878890] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[371.957447] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.088s)
[371.966997] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[372.064645] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.103s)
[372.077598] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[372.170564] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.106s)
[372.179181] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[372.270552] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.099s)
[372.279083] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[372.386075] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.113s)
[372.395085] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[372.488866] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.104s)
[372.499100] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[372.582502] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.090s)
[372.592525] Starting dynamic subtest: drm_test_cmdline_name
[372.681544] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.098s)
[372.694671] Starting dynamic subtest: drm_test_cmdline_name_bpp
[372.789402] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.106s)
[372.799529] Starting dynamic subtest: drm_test_cmdline_name_option
[372.892289] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.103s)
[372.901376] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[372.999451] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.106s)
[373.008311] Starting dynamic subtest: drm_test_cmdline_rotate_0
[373.111533] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.109s)
[373.121057] Starting dynamic subtest: drm_test_cmdline_rotate_90
[373.200403] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.088s)
[373.210405] Starting dynamic subtest: drm_test_cmdline_rotate_180
[373.300337] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.099s)
[373.310091] Starting dynamic subtest: drm_test_cmdline_rotate_270
[373.379086] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.078s)
[373.388632] Starting dynamic subtest: drm_test_cmdline_hmirror
[373.489315] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.110s)
[373.499255] Starting dynamic subtest: drm_test_cmdline_vmirror
[373.599678] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.110s)
[373.609849] Starting dynamic subtest: drm_test_cmdline_margin_options
[373.715765] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.114s)
[373.723836] Starting dynamic subtest: drm_test_cmdline_multiple_options
[373.825078] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.110s)
[373.834062] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[373.924211] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.097s)
[373.932922] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[373.999862] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.076s)
[374.008541] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[374.085932] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.085s)
[374.094352] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[374.166877] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.080s)
[374.183666] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[374.243883] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.076s)
[374.254206] Starting dynamic subtest: drm_test_cmdline_invalid
[374.337434] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.093s)
[374.346459] Starting dynamic subtest: drm_test_cmdline_tv_options
[374.460121] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.122s)
[374.462322] Subtest drm_cmdline_parser: SUCCESS (3.968s)
[374.619488] [082/131] (651s left) kms_getfb (getfb2-handle-zero)
[374.736085] Starting subtest: getfb2-handle-zero
[374.738371] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[374.845342] [083/131] (650s left) gem_exec_whisper (basic-contexts-forked-all)
[374.921051] Starting subtest: basic-contexts-forked-all
[395.793038] Subtest basic-contexts-forked-all: SUCCESS (20.867s)
[395.974905] [084/131] (629s left) kms_invalid_mode (clock-too-high)
[396.076605] Starting subtest: clock-too-high
[396.078935] Starting dynamic subtest: pipe-A-eDP-1
[396.083367] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[396.092607] Starting dynamic subtest: pipe-B-eDP-1
[396.096714] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[396.105228] Starting dynamic subtest: pipe-C-eDP-1
[396.109582] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[396.117712] Starting dynamic subtest: pipe-D-eDP-1
[396.122140] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[396.126567] Subtest clock-too-high: SKIP (0.004s)
[396.162905] [085/131] (629s left) syncobj_wait (single-wait-all-for-submit-unsubmitted)
[396.221324] Starting subtest: single-wait-all-for-submit-unsubmitted
[396.328643] Subtest single-wait-all-for-submit-unsubmitted: SUCCESS (0.107s)
[396.403379] [086/131] (629s left) i915_hangman (detector)
[396.476886] Starting subtest: detector
[396.478921] Starting dynamic subtest: rcs0
[407.859873] Dynamic subtest rcs0: SUCCESS (11.382s)
[407.871556] Starting dynamic subtest: bcs0
[419.891373] Dynamic subtest bcs0: SUCCESS (12.029s)
[419.903048] Starting dynamic subtest: vcs0
[431.860054] Dynamic subtest vcs0: SUCCESS (11.963s)
[431.871030] Starting dynamic subtest: vcs1
[442.867502] Dynamic subtest vcs1: SUCCESS (11.004s)
[442.879774] Starting dynamic subtest: vecs0
[454.899034] Dynamic subtest vecs0: SUCCESS (12.028s)
[454.911056] Starting dynamic subtest: ccs0
[466.867599] Dynamic subtest ccs0: SUCCESS (11.964s)
[466.870622] Subtest detector: SUCCESS (70.388s)
[466.945733] [087/131] (558s left) gem_eio (in-flight-internal-1us)
[467.136827] Starting subtest: in-flight-internal-1us
[468.946646] Subtest in-flight-internal-1us: SUCCESS (1.810s)
[469.091976] [088/131] (556s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling)
[469.193994] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling
[469.197742] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.000s)
[469.264171] [089/131] (556s left) gem_mmap_gtt (basic-write-gtt)
[469.332616] Subtest basic-write-gtt: SKIP (0.000s)
[469.396421] [090/131] (556s left) i915_pm_rc6_residency (rc6-accuracy)
[469.458603] Starting subtest: rc6-accuracy
[469.460490] Starting dynamic subtest: gt0
[472.629892] Dynamic subtest gt0: SUCCESS (3.169s)
[472.641281] Starting dynamic subtest: gt1
[475.801841] Dynamic subtest gt1: SUCCESS (3.168s)
[475.804937] Subtest rc6-accuracy: SUCCESS (6.344s)
[475.893310] [091/131] (550s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[475.955386] Starting subtest: invalid-multi-wait-all-unsubmitted
[475.957737] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[476.048055] [092/131] (549s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[476.142608] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[482.924769] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.781s)
[483.059491] [093/131] (543s left) kms_chamelium_hpd (hdmi-hpd-after-suspend)
[483.452595] Subtest hdmi-hpd-after-suspend: SKIP (0.000s)
[484.243944] [094/131] (541s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[484.330481] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[488.734129] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.407s)
[489.872986] [095/131] (536s left) kms_content_protection (uevent)
[490.027159] Starting subtest: uevent
[490.031581] Subtest uevent: SKIP (0.000s)
[490.168189] [096/131] (536s left) api_intel_bb (add-remove-objects)
[490.235452] Starting subtest: add-remove-objects
[490.240379] Subtest add-remove-objects: SUCCESS (0.006s)
[490.360790] [097/131] (535s left) gem_pread (snoop)
[490.421258] Subtest snoop: SKIP (0.000s)
[490.493260] [098/131] (535s left) gem_mmap_gtt (big-bo)
[490.564291] Subtest big-bo: SKIP (0.000s)
[490.623837] [099/131] (535s left) kms_psr (fbc-pr-primary-mmap-cpu)
[490.722897] Starting subtest: fbc-pr-primary-mmap-cpu
[490.727320] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[490.794125] [100/131] (535s left) gem_exec_whisper (basic-queues-priority-all)
[490.859687] Starting subtest: basic-queues-priority-all
[513.333223] Subtest basic-queues-priority-all: SUCCESS (22.469s)
[513.522370] [101/131] (512s left) vgem_basic (dmabuf-export)
[513.569188] Starting subtest: dmabuf-export
[513.617437] Subtest dmabuf-export: SUCCESS (0.050s)
[513.755619] [102/131] (512s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[513.852908] Starting subtest: fbc-rgb565-draw-mmap-cpu
[517.894825] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (4.046s)
[519.053814] [103/131] (507s left) gem_render_linear_blits (basic)
[519.112471] Starting subtest: basic
[519.135465] Subtest basic: SUCCESS (0.023s)
[519.219295] [104/131] (507s left) gem_workarounds (basic-read-context)
[519.280475] Starting subtest: basic-read-context
[519.291593] Subtest basic-read-context: SUCCESS (0.010s)
[519.425112] [105/131] (507s left) kms_fbcon_fbt (fbc)
[519.480703] Starting subtest: fbc
[525.770220] Subtest fbc: SUCCESS (6.289s)
[526.864120] [106/131] (499s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs)
[526.962130] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs
[526.964398] Starting dynamic subtest: pipe-A-eDP-1
[526.973415] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[526.982841] Starting dynamic subtest: pipe-B-eDP-1
[526.992332] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[527.001634] Starting dynamic subtest: pipe-C-eDP-1
[527.010345] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[527.018897] Starting dynamic subtest: pipe-D-eDP-1
[527.027666] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[527.031985] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[529.253577] [107/131] (497s left) gem_render_copy (linear-to-vebox-yf-tiled)
[529.307485] Starting subtest: linear-to-vebox-yf-tiled
[529.312058] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[529.499129] [108/131] (497s left) kms_ccs (bad-rotation-90-4-tiled-bmg-ccs)
[529.584738] Starting subtest: bad-rotation-90-4-tiled-bmg-ccs
[529.589541] Subtest bad-rotation-90-4-tiled-bmg-ccs: SKIP (0.000s)
[530.683673] [109/131] (496s left) kms_addfb_basic (too-high)
[530.773963] Starting subtest: too-high
[530.776193] Subtest too-high: SUCCESS (0.001s)
[530.878870] [110/131] (495s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[530.984079] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc
[530.985997] Starting dynamic subtest: pipe-A-eDP-1
[531.701387] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.710s)
[531.711058] Starting dynamic subtest: pipe-B-eDP-1
[533.486653] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.784s)
[533.497081] Starting dynamic subtest: pipe-C-eDP-1
[535.234946] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.747s)
[535.244531] Starting dynamic subtest: pipe-D-eDP-1
[537.051354] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.816s)
[537.053618] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (6.062s)
[538.189225] [111/131] (488s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[538.281643] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu
[538.286135] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[538.386702] [112/131] (488s left) kms_big_fb (linear-16bpp-rotate-180)
[538.490360] Starting subtest: linear-16bpp-rotate-180
[539.969128] Subtest linear-16bpp-rotate-180: SUCCESS (1.478s)
[540.087327] [113/131] (486s left) api_intel_bb (intel-bb-blit-x)
[540.131471] Starting subtest: intel-bb-blit-x
[543.583928] Subtest intel-bb-blit-x: SUCCESS (3.452s)
[543.719785] [114/131] (483s left) prime_mmap_coherency (read)
[543.781620] Starting subtest: read
[549.161606] Subtest read: SUCCESS (5.380s)
[549.296820] [115/131] (477s left) gem_softpin (softpin)
[549.348616] Starting subtest: softpin
[581.614816] Subtest softpin: SUCCESS (32.261s)
[581.740527] [116/131] (445s left) fbdev (info)
[581.773618] Starting subtest: info
[581.775934] Subtest info: SUCCESS (0.000s)
[581.837429] [117/131] (445s left) gem_exec_balancer (sliced)
[581.890050] Starting subtest: sliced
[581.894532] Subtest sliced: SKIP (0.000s)
[582.194421] [118/131] (444s left) kms_psr (fbc-psr2-primary-mmap-gtt)
[582.292975] Starting subtest: fbc-psr2-primary-mmap-gtt
[582.295200] Starting dynamic subtest: eDP-1
[582.299184] Dynamic subtest eDP-1: SKIP (0.000s)
[582.303215] Subtest fbc-psr2-primary-mmap-gtt: SKIP (0.001s)
[582.367400] [119/131] (444s left) kms_big_fb (x-tiled-16bpp-rotate-180)
[582.470692] Starting subtest: x-tiled-16bpp-rotate-180
[583.728838] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.258s)
[583.869544] [120/131] (443s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[583.954693] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[583.956722] Starting dynamic subtest: pipe-A
[583.975183] Dynamic subtest pipe-A: SKIP (0.017s)
[583.987748] Starting dynamic subtest: pipe-B
[584.005821] Dynamic subtest pipe-B: SKIP (0.030s)
[584.014662] Starting dynamic subtest: pipe-C
[584.035192] Dynamic subtest pipe-C: SKIP (0.030s)
[584.043817] Starting dynamic subtest: pipe-D
[584.066455] Dynamic subtest pipe-D: SKIP (0.030s)
[584.069972] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.110s)
[584.188320] [121/131] (443s left) kms_vblank (crtc-id)
[584.292467] Starting subtest: crtc-id
[584.294419] Starting dynamic subtest: pipe-A-eDP-1
[584.749758] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.452s)
[584.763939] Starting dynamic subtest: pipe-D-eDP-1
[585.905175] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.156s)
[585.907526] Subtest crtc-id: SUCCESS (1.612s)
[586.750651] [122/131] (440s left) i915_selftest (mock)
[588.595506] Starting subtest: mock
[588.598267] Starting dynamic subtest: sanitycheck
[589.255342] Dynamic subtest sanitycheck: SUCCESS (0.660s)
[589.264780] Starting dynamic subtest: shmem
[589.904211] Dynamic subtest shmem: SUCCESS (0.647s)
[589.913760] Starting dynamic subtest: fence
[598.046135] Dynamic subtest fence: SUCCESS (8.139s)
[598.055522] Starting dynamic subtest: scatterlist
[599.703477] Dynamic subtest scatterlist: SUCCESS (1.656s)
[599.713332] Starting dynamic subtest: syncmap
[602.913439] Dynamic subtest syncmap: SUCCESS (3.208s)
[602.923659] Starting dynamic subtest: uncore
[603.559402] Dynamic subtest uncore: SUCCESS (0.645s)
[603.568457] Starting dynamic subtest: ring
[604.212394] Dynamic subtest ring: SUCCESS (0.651s)
[604.221440] Starting dynamic subtest: engine
[604.879950] Dynamic subtest engine: SUCCESS (0.666s)
[604.889045] Starting dynamic subtest: timelines
[608.627932] Dynamic subtest timelines: SUCCESS (3.745s)
[608.636770] Starting dynamic subtest: requests
[611.898520] Dynamic subtest requests: SUCCESS (3.270s)
[611.908775] Starting dynamic subtest: objects
[612.629102] Dynamic subtest objects: SUCCESS (0.729s)
[612.639649] Starting dynamic subtest: phys
[613.385042] Dynamic subtest phys: SUCCESS (0.754s)
[613.394127] Starting dynamic subtest: dmabuf
[614.136201] Dynamic subtest dmabuf: SUCCESS (0.750s)
[614.145547] Starting dynamic subtest: vma
[619.829238] Dynamic subtest vma: SUCCESS (5.690s)
[619.838875] Starting dynamic subtest: evict
[621.180021] Dynamic subtest evict: SUCCESS (1.349s)
[621.188915] Starting dynamic subtest: gtt
[624.455226] Dynamic subtest gtt: SUCCESS (3.274s)
[624.464829] Starting dynamic subtest: hugepages
[625.354878] Dynamic subtest hugepages: SUCCESS (0.898s)
[625.364450] Starting dynamic subtest: memory_region
[627.801031] Dynamic subtest memory_region: SUCCESS (2.444s)
[627.803783] Subtest mock: SUCCESS (39.205s)
[627.829220] [FACT i915_selftest (mock)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[627.829904] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[627.833111] [123/131] (399s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[629.369045] Starting subtest: psr-rgb101010-draw-mmap-wc
[636.443417] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (7.075s)
[636.573467] [FACT kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[636.574752] [FACT kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)] new: kernel.kmod_is_loaded.i915: true
[636.579373] [124/131] (390s left) kms_psr2_sf (pr-cursor-plane-move-continuous-sf)
[636.708162] Starting subtest: pr-cursor-plane-move-continuous-sf
[636.712177] Subtest pr-cursor-plane-move-continuous-sf: SKIP (0.000s)
[636.772910] [125/131] (390s left) kms_rotation_crc (bad-pixel-format)
[636.862349] Starting subtest: bad-pixel-format
[636.866352] Subtest bad-pixel-format: SKIP (0.001s)
[636.950099] [126/131] (390s left) syncobj_wait (invalid-signal-one-illegal-handle)
[637.017790] Starting subtest: invalid-signal-one-illegal-handle
[637.019828] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[637.108193] [127/131] (390s left) gem_watchdog (default-physical)
[637.171895] Starting subtest: default-physical
[638.934792] Subtest default-physical: SUCCESS (1.772s)
[639.027995] [128/131] (388s left) syncobj_eventfd (invalid-illegal-handle)
[639.095249] Starting subtest: invalid-illegal-handle
[639.097138] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[639.223755] [129/131] (388s left) kms_plane_cursor (primary)
[639.324302] Starting subtest: primary
[639.328537] Starting dynamic subtest: pipe-A-eDP-1-size-64
[641.880056] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.556s)
[641.903373] Starting dynamic subtest: pipe-A-eDP-1-size-128
[644.466600] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.571s)
[644.491561] Starting dynamic subtest: pipe-A-eDP-1-size-256
[647.079952] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.593s)
[647.398611] Starting dynamic subtest: pipe-B-eDP-1-size-64
[650.719998] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.330s)
[650.742473] Starting dynamic subtest: pipe-B-eDP-1-size-128
[653.353872] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.616s)
[653.377151] Starting dynamic subtest: pipe-B-eDP-1-size-256
[655.933695] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.562s)
[656.248525] Starting dynamic subtest: pipe-C-eDP-1-size-64
[659.183182] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.944s)
[659.209459] Starting dynamic subtest: pipe-C-eDP-1-size-128
[661.406609] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.203s)
[661.431141] Starting dynamic subtest: pipe-C-eDP-1-size-256
[663.617310] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.192s)
[663.931890] Starting dynamic subtest: pipe-D-eDP-1-size-64
[666.770182] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.848s)
[666.797872] Starting dynamic subtest: pipe-D-eDP-1-size-128
[668.959247] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.169s)
[668.985318] Starting dynamic subtest: pipe-D-eDP-1-size-256
[671.148260] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.169s)
[671.460630] Subtest primary: SUCCESS (32.135s)
[672.260546] [130/131] (355s left) kms_cursor_crc (cursor-onscreen-max-size)
[672.557911] Starting subtest: cursor-onscreen-max-size
[672.564712] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[672.686852] [131/131] (354s left) gem_ctx_persistence (heartbeat-hang)
[672.749491] Starting subtest: heartbeat-hang
[672.754104] Subtest heartbeat-hang: SKIP (0.000s)
[672.846859] Closing watchdogs
results: parsing output: 0/ for test: kms_properties
results: parsing output: 1/ for test: kms_async_flips
results: parsing output: 2/ for test: gem_softpin
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: gem_lmem_swapping
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: kms_vblank
results: parsing output: 7/ for test: gem_ctx_param
results: parsing output: 8/ for test: kms_properties
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_async_flips
results: parsing output: 11/ for test: kms_pm_backlight
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: gem_exec_schedule
results: parsing output: 15/ for test: perf_pmu
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: gen7_exec_parse
results: parsing output: 19/ for test: kms_psr
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: gem_exec_flush
results: parsing output: 22/ for test: gen9_exec_parse
results: parsing output: 23/ for test: kms_scaling_modes
results: parsing output: 24/ for test: perf_pmu
results: parsing output: 25/ for test: kms_draw_crc
results: parsing output: 26/ for test: gem_partial_pwrite_pread
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: gem_mmap_gtt
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: kms_rotation_crc
results: parsing output: 31/ for test: perf
results: parsing output: 32/ for test: kms_plane_scaling
results: parsing output: 33/ for test: gem_exec_params
results: parsing output: 34/ for test: syncobj_timeline
results: parsing output: 35/ for test: kms_rotation_crc
results: parsing output: 36/ for test: syncobj_timeline
results: parsing output: 37/ for test: drm_buddy
results: parsing output: 38/ for test: gem_exec_balancer
results: parsing output: 39/ for test: gem_exec_reloc
results: parsing output: 40/ for test: gem_softpin
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: gem_render_copy
results: parsing output: 43/ for test: kms_cursor_crc
results: parsing output: 44/ for test: gem_exec_params
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: kms_flip
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: kms_lease
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_pipe_crc_basic
results: parsing output: 51/ for test: kms_big_fb
results: parsing output: 52/ for test: kms_addfb_basic
results: parsing output: 53/ for test: kms_flip_scaled_crc
results: parsing output: 54/ for test: gem_exec_fence
results: parsing output: 55/ for test: gem_gtt_cpu_tlb
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: gem_mmap_gtt
results: parsing output: 59/ for test: kms_cursor_legacy
results: parsing output: 60/ for test: sysfs_defaults
results: parsing output: 61/ for test: kms_psr
results: parsing output: 62/ for test: gem_reg_read
results: parsing output: 63/ for test: kms_color_pipeline
results: parsing output: 64/ for test: kms_plane
results: parsing output: 65/ for test: kms_cursor_legacy
results: parsing output: 66/ for test: gem_userptr_blits
results: parsing output: 67/ for test: gem_exec_schedule
results: parsing output: 68/ for test: kms_chamelium_color
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: perf
results: parsing output: 71/ for test: gem_pxp
results: parsing output: 72/ for test: gem_ctx_persistence
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: gem_ctx_engines
results: parsing output: 77/ for test: syncobj_timeline
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: gem_exec_reloc
results: parsing output: 80/ for test: kms_selftest
results: parsing output: 81/ for test: kms_getfb
results: parsing output: 82/ for test: gem_exec_whisper
results: parsing output: 83/ for test: kms_invalid_mode
results: parsing output: 84/ for test: syncobj_wait
results: parsing output: 85/ for test: i915_hangman
results: parsing output: 86/ for test: gem_eio
results: parsing output: 87/ for test: kms_flip_scaled_crc
results: parsing output: 88/ for test: gem_mmap_gtt
results: parsing output: 89/ for test: i915_pm_rc6_residency
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_chamelium_hpd
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_content_protection
results: parsing output: 95/ for test: api_intel_bb
results: parsing output: 96/ for test: gem_pread
results: parsing output: 97/ for test: gem_mmap_gtt
results: parsing output: 98/ for test: kms_psr
results: parsing output: 99/ for test: gem_exec_whisper
results: parsing output: 100/ for test: vgem_basic
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: gem_render_linear_blits
results: parsing output: 103/ for test: gem_workarounds
results: parsing output: 104/ for test: kms_fbcon_fbt
results: parsing output: 105/ for test: kms_ccs
results: parsing output: 106/ for test: gem_render_copy
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: kms_addfb_basic
results: parsing output: 109/ for test: kms_ccs
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_big_fb
results: parsing output: 112/ for test: api_intel_bb
results: parsing output: 113/ for test: prime_mmap_coherency
results: parsing output: 114/ for test: gem_softpin
results: parsing output: 115/ for test: fbdev
results: parsing output: 116/ for test: gem_exec_balancer
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: kms_plane_scaling
results: parsing output: 120/ for test: kms_vblank
results: parsing output: 121/ for test: i915_selftest
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_psr2_sf
results: parsing output: 124/ for test: kms_rotation_crc
results: parsing output: 125/ for test: syncobj_wait
results: parsing output: 126/ for test: gem_watchdog
results: parsing output: 127/ for test: syncobj_eventfd
results: parsing output: 128/ for test: kms_plane_cursor
results: parsing output: 129/ for test: kms_cursor_crc
results: parsing output: 130/ for test: gem_ctx_persistence
Done.