[60.231577] Initializing watchdogs
[60.231698]   /dev/watchdog0
[60.246750] [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]
[60.251664] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.253968] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.257204] [001/132] (960s left) gem_exec_balancer (nohangcheck)
[60.548637] Starting subtest: nohangcheck
[61.499044] Subtest nohangcheck: SUCCESS (0.951s)
[61.614516] [002/132] (959s left) kms_chamelium_audio (hdmi-audio)
[62.004815] Subtest hdmi-audio: SKIP (0.000s)
[62.837789] [003/132] (957s left) kms_pm_lpsp (kms-lpsp)
[63.948962] Starting subtest: kms-lpsp
[63.951511] Starting dynamic subtest: pipe-A-eDP-1
[64.121588] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.177s)
[64.433058] Subtest kms-lpsp: SUCCESS (0.488s)
[65.294173] [004/132] (955s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[65.395640] Starting subtest: crc-primary-suspend-y-tiled-ccs
[65.397934] Starting dynamic subtest: pipe-A-eDP-1
[65.407152] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[65.414707] Starting dynamic subtest: pipe-B-eDP-1
[65.423892] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[65.431121] Starting dynamic subtest: pipe-C-eDP-1
[65.438784] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[65.445465] Starting dynamic subtest: pipe-D-eDP-1
[65.453697] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[65.457701] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.003s)
[67.593774] [005/132] (953s left) syncobj_timeline (single-wait-available-submitted)
[67.700445] Starting subtest: single-wait-available-submitted
[67.703059] Subtest single-wait-available-submitted: SUCCESS (0.001s)
[67.859665] [006/132] (953s left) gem_ccs (ctrl-surf-copy)
[68.025435] Starting subtest: ctrl-surf-copy
[68.030075] Subtest ctrl-surf-copy: SKIP (0.000s)
[68.137554] [007/132] (952s left) gem_ctx_isolation (preservation-s3)
[68.222223] Starting subtest: preservation-S3
[68.224414] Starting dynamic subtest: rcs0
[84.834077] Dynamic subtest rcs0: SUCCESS (1.827s)
[84.843803] Starting dynamic subtest: bcs0
[101.820353] Dynamic subtest bcs0: SUCCESS (1.708s)
[101.829770] Starting dynamic subtest: vcs0
[118.810661] Dynamic subtest vcs0: SUCCESS (2.640s)
[118.820282] Starting dynamic subtest: vcs1
[136.820650] Dynamic subtest vcs1: SUCCESS (4.976s)
[136.831308] Starting dynamic subtest: vecs0
[154.822315] Dynamic subtest vecs0: SUCCESS (4.994s)
[154.834425] Starting dynamic subtest: ccs0
[172.817628] Dynamic subtest ccs0: SUCCESS (3.130s)
[172.820056] Subtest preservation-S3: SUCCESS (19.278s)
[182.064042] [008/132] (848s left) gem_pwrite (basic-self)
[182.138215] Subtest basic-self: SKIP (0.000s)
[182.208967] [009/132] (847s left) gen9_exec_parse (bb-start-out)
[182.274645] Subtest bb-start-out: SKIP (0.000s)
[182.348002] [010/132] (847s left) kms_addfb_basic (basic-x-tiled-legacy)
[182.407705] Starting subtest: basic-x-tiled-legacy
[182.412120] Subtest basic-x-tiled-legacy: SKIP (0.000s)
[182.549034] [011/132] (847s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[182.646246] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[182.650584] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[182.743538] [012/132] (847s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite)
[182.843229] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite
[182.847465] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[182.919518] [013/132] (847s left) gem_mmap_gtt (cpuset-big-copy)
[182.984118] Subtest cpuset-big-copy: SKIP (0.000s)
[183.039022] [014/132] (847s left) gem_exec_fence (syncobj-wait)
[183.489001] Starting subtest: syncobj-wait
[185.268168] Subtest syncobj-wait: SUCCESS (1.780s)
[185.602069] [FACT gem_exec_fence (syncobj-wait)] new: kernel.kmod_is_loaded.vgem: true
[185.605408] [015/132] (844s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[185.709697] Starting subtest: psr2-primary-plane-update-sf-dmg-area-big-fb
[185.712233] Starting dynamic subtest: pipe-A-eDP-1
[192.358710] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.653s)
[192.367323] Starting dynamic subtest: pipe-B-eDP-1
[199.287026] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.927s)
[199.289352] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SUCCESS (13.582s)
[200.136730] [016/132] (830s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs)
[200.229981] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs
[200.232268] Starting dynamic subtest: pipe-A-eDP-1
[200.255140] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[200.270017] Starting dynamic subtest: pipe-B-eDP-1
[200.291741] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[200.301489] Starting dynamic subtest: pipe-C-eDP-1
[200.321749] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[200.329421] Starting dynamic subtest: pipe-D-eDP-1
[200.350084] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[200.354332] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[202.513240] [017/132] (827s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[202.603976] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[206.613846] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (4.014s)
[207.825684] [018/132] (822s left) gem_exec_suspend (basic-s0)
[207.896198] Starting subtest: basic-S0
[207.898340] Starting dynamic subtest: smem
[228.267782] Dynamic subtest smem: SUCCESS (6.282s)
[228.270793] Subtest basic-S0: SUCCESS (6.283s)
[234.468837] [019/132] (802s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[234.576488] Starting subtest: flip-primary-invalidate-overlay
[246.179251] Subtest flip-primary-invalidate-overlay: SUCCESS (11.608s)
[246.331028] [020/132] (790s left) gem_exec_fence (expired-history)
[246.776522] Starting subtest: expired-history
[254.014146] Subtest expired-history: SUCCESS (7.240s)
[254.714153] [021/132] (781s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier)
[254.819273] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier
[254.823443] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier: SKIP (0.000s)
[254.898553] [022/132] (781s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[255.001875] Starting subtest: y-tiled-16bpp-rotate-180
[255.006054] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[255.102553] [023/132] (781s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[255.194559] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[262.680664] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.491s)
[262.806011] [024/132] (773s left) gem_render_tiled_blits (basic)
[262.861788] Subtest basic: SKIP (0.000s)
[262.924844] [025/132] (773s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[263.013887] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[263.016133] Starting dynamic subtest: pipe-A-default-mode
[264.132705] Dynamic subtest pipe-A-default-mode: SKIP (1.114s)
[264.136875] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.115s)
[264.288704] [026/132] (772s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled)
[264.345571] Starting subtest: etime-multi-wait-all-submitted-signaled
[264.455631] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.109s)
[264.533058] [027/132] (772s left) kms_cursor_legacy (cursora-vs-flipa-atomic)
[264.678221] Starting subtest: cursorA-vs-flipA-atomic
[275.803395] Subtest cursorA-vs-flipA-atomic: SUCCESS (11.131s)
[276.627651] [028/132] (760s left) perf_pmu (busy-idle-check-all)
[276.670634] Starting subtest: busy-idle-check-all
[276.672867] Starting dynamic subtest: rcs0
[277.334340] Dynamic subtest rcs0: SUCCESS (0.665s)
[277.346349] Starting dynamic subtest: bcs0
[277.986237] Dynamic subtest bcs0: SUCCESS (0.651s)
[277.998101] Starting dynamic subtest: vcs0
[279.339508] Dynamic subtest vcs0: SUCCESS (1.350s)
[279.351637] Starting dynamic subtest: vcs1
[281.323295] Dynamic subtest vcs1: SUCCESS (1.983s)
[281.339434] Starting dynamic subtest: vecs0
[283.307123] Dynamic subtest vecs0: SUCCESS (1.982s)
[283.321661] Starting dynamic subtest: ccs0
[283.964925] Dynamic subtest ccs0: SUCCESS (0.657s)
[283.967708] Subtest busy-idle-check-all: SUCCESS (7.299s)
[284.090861] [029/132] (752s left) kms_plane_scaling (planes-downscale-factor-0-5)
[284.199479] Starting subtest: planes-downscale-factor-0-5
[284.201738] Starting dynamic subtest: pipe-A
[284.220864] Dynamic subtest pipe-A: SKIP (0.018s)
[284.230644] Starting dynamic subtest: pipe-B
[284.252422] Dynamic subtest pipe-B: SKIP (0.031s)
[284.261229] Starting dynamic subtest: pipe-C
[284.287750] Dynamic subtest pipe-C: SKIP (0.035s)
[284.296600] Starting dynamic subtest: pipe-D
[284.318652] Dynamic subtest pipe-D: SKIP (0.031s)
[284.322936] Subtest planes-downscale-factor-0-5: SKIP (0.116s)
[284.429722] [030/132] (752s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[284.533575] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt
[284.537725] Subtest fbc-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[284.626195] [031/132] (752s left) gem_userptr_blits (sd-probe)
[284.694530] Starting subtest: sd-probe
[284.696432] Subtest sd-probe: SUCCESS (0.000s)
[284.840734] [032/132] (752s left) gem_create (create-ext-cpu-access-sanity-check)
[285.003646] Starting subtest: create-ext-cpu-access-sanity-check
[285.009254] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[285.078247] [033/132] (752s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[285.164708] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[285.166604] Starting dynamic subtest: pipe-A-eDP-1
[285.430184] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.234s)
[285.441300] Starting dynamic subtest: pipe-B-eDP-1
[286.730750] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.302s)
[286.741746] Starting dynamic subtest: pipe-C-eDP-1
[288.029652] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.296s)
[288.040345] Starting dynamic subtest: pipe-D-eDP-1
[289.265651] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.235s)
[289.268115] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.072s)
[290.394190] [034/132] (746s left) kms_cursor_crc (cursor-alpha-opaque)
[290.488246] Starting subtest: cursor-alpha-opaque
[290.490514] Starting dynamic subtest: pipe-A-eDP-1
[291.098655] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.610s)
[291.108948] Starting dynamic subtest: pipe-D-eDP-1
[292.397670] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.298s)
[292.399926] Subtest cursor-alpha-opaque: SUCCESS (1.911s)
[293.255027] [035/132] (743s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs)
[293.323202] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs
[293.327540] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[293.508007] [036/132] (743s left) kms_big_fb (4-tiled-32bpp-rotate-0)
[293.607123] Starting subtest: 4-tiled-32bpp-rotate-0
[294.985012] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.379s)
[295.130061] [037/132] (742s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[295.246631] Starting subtest: 2x-long-cursor-vs-flip-legacy
[295.250361] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.001s)
[295.392172] [038/132] (741s left) gem_exec_flush (basic-wb-pro-default)
[295.453826] Starting subtest: basic-wb-pro-default
[300.918416] Subtest basic-wb-pro-default: SUCCESS (5.467s)
[301.121557] [039/132] (736s left) kms_big_fb (yf-tiled-8bpp-rotate-0)
[301.234691] Starting subtest: yf-tiled-8bpp-rotate-0
[301.239240] Subtest yf-tiled-8bpp-rotate-0: SKIP (0.000s)
[301.334717] [040/132] (736s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render)
[301.440073] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render
[308.237884] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (6.802s)
[308.369433] [041/132] (729s left) gem_reg_read (timestamp-moving)
[308.428805] Starting subtest: timestamp-moving
[309.430011] Subtest timestamp-moving: SUCCESS (1.001s)
[309.529678] [042/132] (728s left) gem_lmem_swapping (massive)
[309.569306] Subtest massive: SKIP (0.000s)
[309.618102] [043/132] (727s left) kms_properties (crtc-properties-atomic)
[309.705207] Starting subtest: crtc-properties-atomic
[309.707327] Starting dynamic subtest: pipe-A-eDP-1
[310.151952] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.445s)
[310.161311] Starting dynamic subtest: pipe-B-eDP-1
[311.322825] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.171s)
[311.332737] Starting dynamic subtest: pipe-C-eDP-1
[312.450648] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.127s)
[312.461285] Starting dynamic subtest: pipe-D-eDP-1
[313.573448] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.122s)
[313.575730] Subtest crtc-properties-atomic: SUCCESS (3.870s)
[314.394369] [044/132] (723s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[314.513224] Starting subtest: cursor-vs-flip-atomic-transitions
[344.923220] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.415s)
[345.727508] [045/132] (691s left) perf_pmu (interrupts-sync)
[345.769217] Starting subtest: interrupts-sync
[346.847804] Subtest interrupts-sync: SUCCESS (1.078s)
[346.977568] [046/132] (690s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render)
[347.085425] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render
[347.090277] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[347.183787] [047/132] (690s left) gem_exec_reloc (basic-cpu-gtt)
[347.251334] Subtest basic-cpu-gtt: SKIP (0.000s)
[347.323513] [048/132] (690s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[347.416697] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[347.420886] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[347.509862] [049/132] (690s left) vgem_basic (dmabuf-fence-before)
[347.561871] Starting subtest: dmabuf-fence-before
[347.564155] Subtest dmabuf-fence-before: SUCCESS (0.001s)
[347.619495] [050/132] (690s left) i915_pm_rps (min-max-config-loaded)
[347.686877] Starting subtest: min-max-config-loaded
[347.691185] Subtest min-max-config-loaded: SKIP (0.000s)
[347.759032] [051/132] (690s left) kms_async_flips (test-cursor-atomic)
[347.852682] Starting subtest: test-cursor-atomic
[348.222569] Starting dynamic subtest: pipe-A-eDP-1
[349.014718] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.792s)
[349.389256] Starting dynamic subtest: pipe-B-eDP-1
[350.144330] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.765s)
[350.523529] Starting dynamic subtest: pipe-C-eDP-1
[351.280203] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.764s)
[351.646731] Starting dynamic subtest: pipe-D-eDP-1
[352.410601] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.773s)
[352.412896] Subtest test-cursor-atomic: SUCCESS (4.561s)
[353.586263] [052/132] (684s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[353.684506] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[353.688435] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[353.775512] [053/132] (684s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-0-hflip)
[353.888299] Starting subtest: linear-max-hw-stride-32bpp-rotate-0-hflip
[353.892749] Subtest linear-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[353.979920] [054/132] (684s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[354.070847] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[354.074673] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[354.156159] [055/132] (683s left) api_intel_bb (simple-bb)
[354.216500] Starting subtest: simple-bb
[354.218863] Subtest simple-bb: SUCCESS (0.005s)
[354.333825] [056/132] (683s left) gem_ppgtt (blt-vs-render-ctxn)
[354.392576] Starting subtest: blt-vs-render-ctxN
[387.325804] Subtest blt-vs-render-ctxN: SUCCESS (32.935s)
[387.460852] [057/132] (650s left) i915_query (query-regions-garbage-items)
[387.559242] Starting subtest: query-regions-garbage-items
[387.561614] Subtest query-regions-garbage-items: SUCCESS (0.000s)
[387.680174] [058/132] (650s left) perf_pmu (semaphore-wait)
[387.724303] Starting subtest: semaphore-wait
[387.726869] Starting dynamic subtest: rcs0
[388.243570] Dynamic subtest rcs0: SUCCESS (0.517s)
[388.256158] Starting dynamic subtest: bcs0
[388.759046] Dynamic subtest bcs0: SUCCESS (0.512s)
[388.771655] Starting dynamic subtest: vcs0
[389.274641] Dynamic subtest vcs0: SUCCESS (0.512s)
[389.285642] Starting dynamic subtest: vcs1
[389.792101] Dynamic subtest vcs1: SUCCESS (0.513s)
[389.803866] Starting dynamic subtest: vecs0
[390.307265] Dynamic subtest vecs0: SUCCESS (0.513s)
[390.319610] Starting dynamic subtest: ccs0
[390.821330] Dynamic subtest ccs0: SUCCESS (0.512s)
[390.824312] Subtest semaphore-wait: SUCCESS (3.098s)
[392.377475] [059/132] (645s left) gem_exec_reloc (basic-cpu-read-active)
[392.441554] Subtest basic-cpu-read-active: SKIP (0.000s)
[392.514808] [060/132] (645s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[392.626024] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[399.589689] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.964s)
[399.712607] [061/132] (638s left) vgem_basic (setversion)
[399.756046] Starting subtest: setversion
[399.760158] Subtest setversion: SUCCESS (0.000s)
[399.814700] [062/132] (638s left) kms_invalid_mode (bad-hsync-start)
[399.916663] Starting subtest: bad-hsync-start
[399.918915] Starting dynamic subtest: pipe-A-eDP-1
[400.211623] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.297s)
[400.222250] Starting dynamic subtest: pipe-B-eDP-1
[400.224252] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[400.233220] Starting dynamic subtest: pipe-C-eDP-1
[400.235608] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[400.245707] Starting dynamic subtest: pipe-D-eDP-1
[400.248136] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[400.250805] Subtest bad-hsync-start: SUCCESS (0.304s)
[401.023477] [063/132] (637s left) kms_flip (modeset-vs-vblank-race)
[401.108918] Starting subtest: modeset-vs-vblank-race
[401.111158] Starting dynamic subtest: A-eDP1
[405.835500] Dynamic subtest A-eDP1: SUCCESS (4.726s)
[405.852576] Starting dynamic subtest: B-eDP1
[410.607608] Dynamic subtest B-eDP1: SUCCESS (4.770s)
[410.621099] Starting dynamic subtest: C-eDP1
[415.304533] Dynamic subtest C-eDP1: SUCCESS (4.695s)
[415.317747] Starting dynamic subtest: D-eDP1
[419.750456] Dynamic subtest D-eDP1: SUCCESS (4.444s)
[419.756543] Subtest modeset-vs-vblank-race: SUCCESS (18.649s)
[420.915394] [064/132] (617s left) kms_psr (pr-sprite-blt)
[421.011786] Starting subtest: pr-sprite-blt
[421.015880] Subtest pr-sprite-blt: SKIP (0.000s)
[421.085962] [065/132] (617s left) kms_pm_rpm (system-suspend-idle)
[422.612961] Starting subtest: system-suspend-idle
[443.313567] Subtest system-suspend-idle: SUCCESS (5.028s)
[447.710232] [066/132] (594s left) gem_pxp (reject-modify-context-protection-on)
[447.846943] Starting subtest: reject-modify-context-protection-on
[447.849396] Subtest reject-modify-context-protection-on: SUCCESS (0.002s)
[447.968602] [067/132] (594s left) i915_module_load (resize-bar)
[448.018464] Starting subtest: resize-bar
[448.035499] Subtest resize-bar: SKIP (0.017s)
[448.085483] [068/132] (594s left) kms_dsc (dsc-with-bpc-formats)
[448.188072] Starting subtest: dsc-with-bpc-formats
[448.301552] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[448.342350] [069/132] (594s left) gem_mmap_gtt (hang-busy)
[448.407166] Subtest hang-busy: SKIP (0.000s)
[448.471624] [070/132] (594s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[448.562135] Starting subtest: fbcpsr-indfb-scaledprimary
[454.382416] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.823s)
[454.521676] [071/132] (588s left) gem_render_copy_redux (flink-interruptible)
[454.578451] Starting subtest: flink-interruptible
[455.354326] Subtest flink-interruptible: SUCCESS (0.776s)
[455.489939] [072/132] (587s left) kms_psr (fbc-psr2-sprite-render)
[455.578948] Starting subtest: fbc-psr2-sprite-render
[455.580669] Starting dynamic subtest: eDP-1
[455.583920] Dynamic subtest eDP-1: SKIP (0.000s)
[455.587144] Subtest fbc-psr2-sprite-render: SKIP (0.001s)
[455.654284] [073/132] (587s left) kms_chamelium_frames (dp-frame-dump)
[456.034689] Subtest dp-frame-dump: SKIP (0.000s)
[456.832468] [074/132] (586s left) kms_getfb (getfb-handle-valid)
[456.930623] Starting subtest: getfb-handle-valid
[456.932839] Subtest getfb-handle-valid: SUCCESS (0.000s)
[457.033653] [075/132] (585s left) gem_softpin (allocator-basic)
[457.096207] Starting subtest: allocator-basic
[457.161369] Subtest allocator-basic: SUCCESS (0.064s)
[457.307793] [076/132] (585s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[457.374710] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[457.449603] [077/132] (585s left) dmabuf_sync_file (export-basic)
[457.502662] Starting subtest: export-basic
[457.505031] Subtest export-basic: SUCCESS (0.001s)
[457.561479] [078/132] (585s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[457.685926] Starting subtest: 2x-flip-vs-cursor-legacy
[457.690180] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s)
[457.829848] [079/132] (585s left) perf (mi-rpc)
[457.962451] Starting subtest: mi-rpc
[457.965727] Subtest mi-rpc: SKIP (0.000s)
[458.008117] [080/132] (585s left) gem_exec_fence (syncobj-signal)
[458.491529] Starting subtest: syncobj-signal
[458.493716] Subtest syncobj-signal: SUCCESS (0.005s)
[460.087531] [081/132] (583s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[460.184515] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[464.582499] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.402s)
[465.734839] [082/132] (577s left) kms_flip (dpms-off-confusion-interruptible)
[465.840836] Starting subtest: dpms-off-confusion-interruptible
[465.843302] Starting dynamic subtest: A-eDP1
[474.529132] Dynamic subtest A-eDP1: SUCCESS (8.689s)
[474.540154] Starting dynamic subtest: B-eDP1
[483.152593] Dynamic subtest B-eDP1: SUCCESS (8.617s)
[483.165226] Starting dynamic subtest: C-eDP1
[491.768092] Dynamic subtest C-eDP1: SUCCESS (8.618s)
[491.787934] Starting dynamic subtest: D-eDP1
[500.412273] Dynamic subtest D-eDP1: SUCCESS (8.644s)
[500.417559] Subtest dpms-off-confusion-interruptible: SUCCESS (34.577s)
[501.583299] [083/132] (541s left) kms_addfb_basic (invalid-get-prop-any)
[501.637831] Starting subtest: invalid-get-prop-any
[501.640050] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[501.779221] [084/132] (541s left) gem_eio (wait-wedge-10ms)
[502.001683] Starting subtest: wait-wedge-10ms
[503.082453] Subtest wait-wedge-10ms: SUCCESS (1.080s)
[503.220653] [085/132] (540s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[503.319696] Starting subtest: fbc-pr-sprite-mmap-cpu
[503.324015] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[503.429787] [086/132] (540s left) gem_ctx_param (basic-default)
[503.479826] Starting subtest: basic-default
[503.482110] Subtest basic-default: SUCCESS (0.000s)
[503.556145] [087/132] (539s left) syncobj_timeline (invalid-signal-illegal-point)
[503.607494] Starting subtest: invalid-signal-illegal-point
[503.609874] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[503.707569] [088/132] (539s left) kms_chamelium_hpd (hdmi-hpd-storm)
[504.095827] Subtest hdmi-hpd-storm: SKIP (0.000s)
[504.902750] [089/132] (538s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[505.001230] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[512.279636] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.281s)
[512.409083] [090/132] (531s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[512.504072] Starting subtest: fbc-psr2-cursor-plane-update-sf
[512.506134] Starting dynamic subtest: pipe-A-eDP-1
[512.578808] Dynamic subtest pipe-A-eDP-1: SKIP (0.078s)
[512.592679] Starting dynamic subtest: pipe-B-eDP-1
[513.707637] Dynamic subtest pipe-B-eDP-1: SKIP (1.127s)
[513.714367] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.208s)
[514.908157] [091/132] (528s left) vgem_basic (bad-flag)
[514.951962] Starting subtest: bad-flag
[514.954485] Subtest bad-flag: SUCCESS (0.000s)
[515.021230] [092/132] (528s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[515.125235] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[521.845078] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.724s)
[521.973799] [093/132] (521s left) gem_ctx_persistence (hostile)
[522.040979] Starting subtest: hostile
[532.186439] Subtest hostile: SUCCESS (10.141s)
[532.302512] [094/132] (511s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt)
[532.414690] Starting subtest: psr-farfromfence-mmap-gtt
[532.419081] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s)
[532.512088] [095/132] (511s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[532.608388] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[532.610680] Starting dynamic subtest: pipe-A-eDP-1
[532.632075] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[532.652514] Starting dynamic subtest: pipe-B-eDP-1
[532.674423] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[532.687927] Starting dynamic subtest: pipe-C-eDP-1
[532.708643] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[532.717939] Starting dynamic subtest: pipe-D-eDP-1
[532.738535] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[532.742597] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[534.814189] [096/132] (509s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier-source-clamping)
[534.903081] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier-source-clamping
[534.907240] Subtest pixel-format-4-tiled-lnl-ccs-modifier-source-clamping: SKIP (0.000s)
[534.977970] [097/132] (508s left) kms_cursor_crc (cursor-random-32x32)
[535.182626] Starting subtest: cursor-random-32x32
[535.233675] Subtest cursor-random-32x32: SKIP (0.044s)
[535.575263] [098/132] (508s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[535.685693] Starting subtest: yf-tiled-16bpp-rotate-180
[535.690342] Subtest yf-tiled-16bpp-rotate-180: SKIP (0.000s)
[535.774007] [099/132] (508s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[535.869768] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[535.873305] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[535.963153] [100/132] (508s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[536.063844] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[536.067649] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[536.159824] [101/132] (507s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[536.214266] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[536.216177] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[536.312300] [102/132] (507s left) kms_cursor_crc (cursor-sliding-64x21)
[536.510006] Starting subtest: cursor-sliding-64x21
[536.554555] Subtest cursor-sliding-64x21: SKIP (0.042s)
[536.817874] [103/132] (507s left) prime_udl
[536.904713] [104/132] (507s left) kms_psr (psr2-cursor-mmap-cpu)
[537.004064] Starting subtest: psr2-cursor-mmap-cpu
[537.005960] Starting dynamic subtest: eDP-1
[538.691670] Dynamic subtest eDP-1: SUCCESS (1.685s)
[538.694062] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.686s)
[539.589723] [105/132] (504s left) kms_plane_scaling (invalid-num-scalers)
[539.690949] Starting subtest: invalid-num-scalers
[539.693238] Starting dynamic subtest: pipe-A-eDP-1-invalid-num-scalers
[539.709166] Dynamic subtest pipe-A-eDP-1-invalid-num-scalers: SUCCESS (0.018s)
[539.719787] Starting dynamic subtest: pipe-B-eDP-1-invalid-num-scalers
[539.740601] Dynamic subtest pipe-B-eDP-1-invalid-num-scalers: SUCCESS (0.031s)
[539.751156] Starting dynamic subtest: pipe-C-eDP-1-invalid-num-scalers
[539.774525] Dynamic subtest pipe-C-eDP-1-invalid-num-scalers: SUCCESS (0.031s)
[539.784687] Starting dynamic subtest: pipe-D-eDP-1-invalid-num-scalers
[539.807876] Dynamic subtest pipe-D-eDP-1-invalid-num-scalers: SUCCESS (0.031s)
[539.810171] Subtest invalid-num-scalers: SUCCESS (0.117s)
[539.901471] [106/132] (504s left) syncobj_timeline (multi-wait-all-signaled)
[539.957807] Starting subtest: multi-wait-all-signaled
[539.960190] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[540.078817] [107/132] (504s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[540.175784] Starting subtest: basic-max-pipe-crc-check
[540.180249] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[540.226843] [108/132] (504s left) kms_selftest (drm_framebuffer)
[540.275772] Starting subtest: drm_framebuffer
[540.294447] Starting dynamic subtest: drm_test_framebuffer_check_src_coords
[540.390009] Dynamic subtest drm_test_framebuffer_check_src_coords: SUCCESS (0.094s)
[540.400454] Starting dynamic subtest: drm_test_framebuffer_cleanup
[540.499625] Dynamic subtest drm_test_framebuffer_cleanup: SUCCESS (0.107s)
[540.510402] Starting dynamic subtest: drm_test_framebuffer_create
[540.685843] Dynamic subtest drm_test_framebuffer_create: SUCCESS (0.189s)
[540.699492] Starting dynamic subtest: drm_test_framebuffer_free
[540.911912] Dynamic subtest drm_test_framebuffer_free: FAIL (0.178s)
[540.916172] Subtest drm_framebuffer: FAIL (0.590s)
[541.002090] Aborting: Kernel badly tainted (0x44244, 0x200) (check dmesg for details):
	TAINT_WARN: WARN_ON has happened.

[541.022364] [FACT kms_selftest (drm_framebuffer)] new: kernel.is_tainted.taint_warn: true
[541.023288] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_balancer
results: parsing output: 1/ for test: kms_chamelium_audio
results: parsing output: 2/ for test: kms_pm_lpsp
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: syncobj_timeline
results: parsing output: 5/ for test: gem_ccs
results: parsing output: 6/ for test: gem_ctx_isolation
results: parsing output: 7/ for test: gem_pwrite
results: parsing output: 8/ for test: gen9_exec_parse
results: parsing output: 9/ for test: kms_addfb_basic
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: gem_mmap_gtt
results: parsing output: 13/ for test: gem_exec_fence
results: parsing output: 14/ for test: kms_psr2_sf
results: parsing output: 15/ for test: kms_ccs
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_exec_suspend
results: parsing output: 18/ for test: kms_psr_stress_test
results: parsing output: 19/ for test: gem_exec_fence
results: parsing output: 20/ for test: kms_plane
results: parsing output: 21/ for test: kms_big_fb
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: gem_render_tiled_blits
results: parsing output: 24/ for test: kms_flip_scaled_crc
results: parsing output: 25/ for test: syncobj_timeline
results: parsing output: 26/ for test: kms_cursor_legacy
results: parsing output: 27/ for test: perf_pmu
results: parsing output: 28/ for test: kms_plane_scaling
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: gem_userptr_blits
results: parsing output: 31/ for test: gem_create
results: parsing output: 32/ for test: kms_ccs
results: parsing output: 33/ for test: kms_cursor_crc
results: parsing output: 34/ for test: gem_render_copy
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: kms_cursor_legacy
results: parsing output: 37/ for test: gem_exec_flush
results: parsing output: 38/ for test: kms_big_fb
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gem_reg_read
results: parsing output: 41/ for test: gem_lmem_swapping
results: parsing output: 42/ for test: kms_properties
results: parsing output: 43/ for test: kms_cursor_legacy
results: parsing output: 44/ for test: perf_pmu
results: parsing output: 45/ for test: kms_frontbuffer_tracking
results: parsing output: 46/ for test: gem_exec_reloc
results: parsing output: 47/ for test: kms_frontbuffer_tracking
results: parsing output: 48/ for test: vgem_basic
results: parsing output: 49/ for test: i915_pm_rps
results: parsing output: 50/ for test: kms_async_flips
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: api_intel_bb
results: parsing output: 55/ for test: gem_ppgtt
results: parsing output: 56/ for test: i915_query
results: parsing output: 57/ for test: perf_pmu
results: parsing output: 58/ for test: gem_exec_reloc
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: vgem_basic
results: parsing output: 61/ for test: kms_invalid_mode
results: parsing output: 62/ for test: kms_flip
results: parsing output: 63/ for test: kms_psr
results: parsing output: 64/ for test: kms_pm_rpm
results: parsing output: 65/ for test: gem_pxp
results: parsing output: 66/ for test: i915_module_load
results: parsing output: 67/ for test: kms_dsc
results: parsing output: 68/ for test: gem_mmap_gtt
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_render_copy_redux
results: parsing output: 71/ for test: kms_psr
results: parsing output: 72/ for test: kms_chamelium_frames
results: parsing output: 73/ for test: kms_getfb
results: parsing output: 74/ for test: gem_softpin
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: dmabuf_sync_file
results: parsing output: 77/ for test: kms_cursor_legacy
results: parsing output: 78/ for test: perf
results: parsing output: 79/ for test: gem_exec_fence
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_flip
results: parsing output: 82/ for test: kms_addfb_basic
results: parsing output: 83/ for test: gem_eio
results: parsing output: 84/ for test: kms_psr
results: parsing output: 85/ for test: gem_ctx_param
results: parsing output: 86/ for test: syncobj_timeline
results: parsing output: 87/ for test: kms_chamelium_hpd
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_psr2_sf
results: parsing output: 90/ for test: vgem_basic
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_ctx_persistence
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_ccs
results: parsing output: 95/ for test: kms_plane
results: parsing output: 96/ for test: kms_cursor_crc
results: parsing output: 97/ for test: kms_big_fb
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: syncobj_wait
results: parsing output: 101/ for test: kms_cursor_crc
results: parsing output: 102/ for test: prime_udl
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: kms_plane_scaling
results: parsing output: 105/ for test: syncobj_timeline
results: parsing output: 106/ for test: kms_multipipe_modeset
results: parsing output: 107/ for test: kms_selftest
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 109/ for test: kms_vrr
results: no output, setting notrun
results: parsing output: 110/ for test: kms_content_protection
results: no output, setting notrun
results: parsing output: 111/ for test: gem_exec_schedule
results: no output, setting notrun
results: parsing output: 112/ for test: kms_psr2_sf
results: no output, setting notrun
results: parsing output: 113/ for test: kms_lease
results: no output, setting notrun
results: parsing output: 114/ for test: gem_render_copy
results: no output, setting notrun
results: parsing output: 115/ for test: gem_exec_params
results: no output, setting notrun
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 117/ for test: kms_color
results: no output, setting notrun
results: parsing output: 118/ for test: gem_caching
results: no output, setting notrun
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: no output, setting notrun
results: parsing output: 120/ for test: syncobj_eventfd
results: no output, setting notrun
results: parsing output: 121/ for test: kms_big_fb
results: no output, setting notrun
results: parsing output: 122/ for test: api_intel_allocator
results: no output, setting notrun
results: parsing output: 123/ for test: gem_exec_params
results: no output, setting notrun
results: parsing output: 124/ for test: gem_exec_reloc
results: no output, setting notrun
results: parsing output: 125/ for test: i915_drm_fdinfo
results: no output, setting notrun
results: parsing output: 126/ for test: gem_exec_schedule
results: no output, setting notrun
results: parsing output: 127/ for test: kms_vblank
results: no output, setting notrun
results: parsing output: 128/ for test: gem_gtt_cpu_tlb
results: no output, setting notrun
results: parsing output: 129/ for test: gem_exec_suspend
results: no output, setting notrun
results: parsing output: 130/ for test: kms_flip_scaled_crc
results: no output, setting notrun
results: parsing output: 131/ for test: kms_dirtyfb
results: no output, setting notrun
Done.