[57.639432] Initializing watchdogs
[57.639518]   /dev/watchdog0
[57.656250] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.661202] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.663587] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.665804] [001/156] (960s left) kms_invalid_mode (clock-too-high)
[57.772096] Starting subtest: clock-too-high
[57.773078] Starting dynamic subtest: pipe-A-eDP-1
[58.058208] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[58.065382] Starting dynamic subtest: pipe-B-eDP-1
[58.067079] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[58.072938] Starting dynamic subtest: pipe-C-eDP-1
[58.074756] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[58.081158] Starting dynamic subtest: pipe-D-eDP-1
[58.082992] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[58.083938] Subtest clock-too-high: SUCCESS (0.291s)
[58.867545] [002/156] (959s left) gem_render_copy (mixed-tiled-to-y-tiled-ccs)
[58.926134] Starting subtest: mixed-tiled-to-y-tiled-ccs
[58.928105] Subtest mixed-tiled-to-y-tiled-ccs: SKIP (0.000s)
[59.103558] [003/156] (959s left) gem_exec_fence (basic-busy)
[59.288718] Starting subtest: basic-busy
[59.289734] Starting dynamic subtest: rcs0
[59.357183] Dynamic subtest rcs0: SUCCESS (0.077s)
[59.365805] Starting dynamic subtest: bcs0
[59.419394] Dynamic subtest bcs0: SUCCESS (0.061s)
[59.428806] Starting dynamic subtest: vcs0
[59.490838] Dynamic subtest vcs0: SUCCESS (0.071s)
[59.499423] Starting dynamic subtest: vcs1
[59.554864] Dynamic subtest vcs1: SUCCESS (0.061s)
[59.564471] Starting dynamic subtest: vecs0
[59.606892] Dynamic subtest vecs0: SUCCESS (0.053s)
[59.615236] Starting dynamic subtest: ccs0
[59.657384] Dynamic subtest ccs0: SUCCESS (0.049s)
[59.658522] Subtest basic-busy: SUCCESS (0.377s)
[61.389688] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true
[61.391674] [004/156] (956s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[61.485628] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[61.487247] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[61.591201] [005/156] (956s left) kms_pm_rpm (modeset-non-lpsp-stress)
[63.455830] Starting subtest: modeset-non-lpsp-stress
[64.614763] Subtest modeset-non-lpsp-stress: SKIP (1.157s)
[64.988917] [006/156] (953s left) template (b)
[65.048761] Starting subtest: B
[65.049918] Subtest B: SUCCESS (0.000s)
[65.155219] [007/156] (953s left) kms_vblank (invalid)
[65.259392] Starting subtest: invalid
[65.260421] Starting dynamic subtest: pipe-A-eDP-1
[65.688455] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.430s)
[65.689398] Subtest invalid: SUCCESS (0.431s)
[66.502621] [008/156] (951s left) gem_exec_store (basic)
[66.555415] Starting subtest: basic
[66.664444] Subtest basic: SUCCESS (0.112s)
[66.841165] [009/156] (951s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-blt)
[66.940162] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-blt
[66.942051] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[67.055622] [010/156] (951s left) gem_spin_batch (resubmit-new)
[67.112272] Starting subtest: resubmit-new
[67.113199] Starting dynamic subtest: rcs0
[67.120076] Dynamic subtest rcs0: SUCCESS (0.009s)
[67.130108] Starting dynamic subtest: bcs0
[67.131047] Dynamic subtest bcs0: SUCCESS (0.004s)
[67.138860] Starting dynamic subtest: vcs0
[67.139914] Dynamic subtest vcs0: SUCCESS (0.006s)
[67.150364] Starting dynamic subtest: vcs1
[67.151147] Dynamic subtest vcs1: SUCCESS (0.006s)
[67.157450] Starting dynamic subtest: vecs0
[67.158336] Dynamic subtest vecs0: SUCCESS (0.005s)
[67.164899] Starting dynamic subtest: ccs0
[67.165852] Dynamic subtest ccs0: SUCCESS (0.004s)
[67.166930] Subtest resubmit-new: SUCCESS (0.036s)
[68.350801] [011/156] (950s left) api_intel_bb (lot-of-buffers)
[68.394074] Starting subtest: lot-of-buffers
[69.506210] Subtest lot-of-buffers: SUCCESS (1.111s)
[70.369694] [012/156] (948s left) kms_psr (psr-sprite-mmap-cpu)
[70.468246] Starting subtest: psr-sprite-mmap-cpu
[70.469263] Starting dynamic subtest: eDP-1
[72.201884] Dynamic subtest eDP-1: SUCCESS (1.733s)
[72.202872] Subtest psr-sprite-mmap-cpu: SUCCESS (1.734s)
[73.087364] [013/156] (945s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[73.189528] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[73.190544] Starting dynamic subtest: pipe-A-default-mode
[75.932347] Dynamic subtest pipe-A-default-mode: SUCCESS (2.742s)
[75.933233] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.743s)
[76.757574] [014/156] (941s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[76.844197] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[84.295124] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.452s)
[84.425726] [015/156] (934s left) kms_cursor_edge_walk (256x256-top-bottom)
[84.517608] Starting subtest: 256x256-top-bottom
[84.518559] Starting dynamic subtest: pipe-A-eDP-1
[87.950083] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.433s)
[87.959290] Starting dynamic subtest: pipe-D-eDP-1
[91.080975] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.130s)
[91.081922] Subtest 256x256-top-bottom: SUCCESS (6.564s)
[91.901554] [016/156] (926s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions)
[92.009934] Starting subtest: short-flip-before-cursor-atomic-transitions
[92.969297] Subtest short-flip-before-cursor-atomic-transitions: SUCCESS (0.959s)
[93.826122] [017/156] (924s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[93.919254] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[93.920820] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[94.031917] [018/156] (924s left) gem_exec_nop (basic-sequential)
[94.094997] Starting subtest: basic-sequential
[108.791996] Subtest basic-sequential: SUCCESS (14.698s)
[109.026086] [019/156] (909s left) perf_pmu (busy-no-semaphores)
[109.059946] Starting subtest: busy-no-semaphores
[109.060871] Starting dynamic subtest: rcs0
[109.625446] Dynamic subtest rcs0: SUCCESS (0.554s)
[109.635585] Starting dynamic subtest: bcs0
[110.175471] Dynamic subtest bcs0: SUCCESS (0.548s)
[110.185679] Starting dynamic subtest: vcs0
[110.727457] Dynamic subtest vcs0: SUCCESS (0.551s)
[110.737928] Starting dynamic subtest: vcs1
[111.847060] Dynamic subtest vcs1: SUCCESS (1.118s)
[111.857537] Starting dynamic subtest: vecs0
[113.833881] Dynamic subtest vecs0: SUCCESS (1.985s)
[113.844610] Starting dynamic subtest: ccs0
[115.818064] Dynamic subtest ccs0: SUCCESS (1.983s)
[115.819372] Subtest busy-no-semaphores: SUCCESS (6.748s)
[115.919493] [020/156] (902s left) kms_big_fb (linear-32bpp-rotate-180)
[116.020972] Starting subtest: linear-32bpp-rotate-180
[117.556242] Subtest linear-32bpp-rotate-180: SUCCESS (1.535s)
[117.693988] [021/156] (901s left) kms_cursor_crc (cursor-random-64x64)
[117.856472] Starting subtest: cursor-random-64x64
[117.860107] Starting dynamic subtest: pipe-A-eDP-1
[121.224363] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.364s)
[121.256457] Starting dynamic subtest: pipe-D-eDP-1
[125.228204] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.981s)
[125.229133] Subtest cursor-random-64x64: SUCCESS (7.371s)
[126.095546] [022/156] (892s left) gem_mmap_gtt (pf-nonblock)
[126.147983] Subtest pf-nonblock: SKIP (0.000s)
[126.205611] [023/156] (892s left) gem_ctx_isolation (preservation-s3)
[126.260364] Starting subtest: preservation-S3
[126.261204] Starting dynamic subtest: rcs0
[142.505213] Dynamic subtest rcs0: SUCCESS (2.553s)
[142.515500] Starting dynamic subtest: bcs0
[159.495742] Dynamic subtest bcs0: SUCCESS (1.670s)
[159.507210] Starting dynamic subtest: vcs0
[176.508312] Dynamic subtest vcs0: SUCCESS (3.327s)
[176.520232] Starting dynamic subtest: vcs1
[194.506482] Dynamic subtest vcs1: SUCCESS (4.992s)
[194.516643] Starting dynamic subtest: vecs0
[212.485543] Dynamic subtest vecs0: SUCCESS (4.959s)
[212.495113] Starting dynamic subtest: ccs0
[230.500600] Dynamic subtest ccs0: SUCCESS (3.213s)
[230.501497] Subtest preservation-S3: SUCCESS (20.720s)
[236.757453] [024/156] (788s left) i915_drm_fdinfo (virtual-busy-idle-all)
[236.794733] Starting subtest: virtual-busy-idle-all
[236.796293] Subtest virtual-busy-idle-all: SKIP (0.000s)
[236.863819] [025/156] (788s left) gem_exec_schedule (pi-common)
[237.038633] Starting subtest: pi-common
[237.039508] Starting dynamic subtest: rcs0
[237.070271] Dynamic subtest rcs0: SUCCESS (0.042s)
[237.078396] Starting dynamic subtest: bcs0
[237.107972] Dynamic subtest bcs0: SUCCESS (0.037s)
[237.116266] Starting dynamic subtest: vcs0
[237.141191] Dynamic subtest vcs0: SUCCESS (0.033s)
[237.149505] Starting dynamic subtest: vcs1
[237.172436] Dynamic subtest vcs1: SUCCESS (0.031s)
[237.180659] Starting dynamic subtest: vecs0
[237.201562] Dynamic subtest vecs0: SUCCESS (0.029s)
[237.209944] Starting dynamic subtest: ccs0
[237.229645] Dynamic subtest ccs0: SUCCESS (0.027s)
[237.230447] Subtest pi-common: SUCCESS (0.202s)
[237.454389] [026/156] (787s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-shrfb-plflip-blt)
[237.544808] Starting subtest: hdr-2p-scndscrn-shrfb-plflip-blt
[237.546516] Subtest hdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[237.636562] [027/156] (787s left) kms_display_modes (extended-mode-basic)
[237.722641] Starting subtest: extended-mode-basic
[237.724396] Subtest extended-mode-basic: SKIP (0.000s)
[237.765202] [028/156] (787s left) kms_flip (flip-vs-dpms-on-nop)
[237.851578] Starting subtest: flip-vs-dpms-on-nop
[237.853499] Starting dynamic subtest: A-eDP1
[239.720521] Dynamic subtest A-eDP1: SUCCESS (1.864s)
[239.734182] Starting dynamic subtest: B-eDP1
[241.565934] Dynamic subtest B-eDP1: SUCCESS (1.842s)
[241.576723] Starting dynamic subtest: C-eDP1
[243.482138] Dynamic subtest C-eDP1: SUCCESS (1.911s)
[243.492775] Starting dynamic subtest: D-eDP1
[245.316280] Dynamic subtest D-eDP1: SUCCESS (1.830s)
[245.317864] Subtest flip-vs-dpms-on-nop: SUCCESS (7.455s)
[246.487916] [029/156] (778s left) gem_eio (in-flight-1us)
[246.709193] Starting subtest: in-flight-1us
[254.669173] Subtest in-flight-1us: SUCCESS (7.956s)
[254.796698] [030/156] (770s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-shrfb-draw-blt)
[254.881587] Starting subtest: fbcpsrhdr-2p-primscrn-pri-shrfb-draw-blt
[254.883144] Subtest fbcpsrhdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[254.974296] [031/156] (770s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[255.067953] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[255.070882] Starting dynamic subtest: A-eDP1
[258.745663] Dynamic subtest A-eDP1: SUCCESS (3.673s)
[258.755176] Starting dynamic subtest: B-eDP1
[261.975132] Dynamic subtest B-eDP1: SUCCESS (3.228s)
[261.987114] Starting dynamic subtest: C-eDP1
[265.203515] Dynamic subtest C-eDP1: SUCCESS (3.225s)
[265.215927] Starting dynamic subtest: D-eDP1
[268.433430] Dynamic subtest D-eDP1: SUCCESS (3.228s)
[268.435425] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.362s)
[269.248071] [032/156] (756s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[269.333725] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[269.335415] Subtest hdr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[269.433339] [033/156] (755s left) kms_flip (plain-flip-ts-check-interruptible)
[269.522977] Starting subtest: plain-flip-ts-check-interruptible
[269.523973] Starting dynamic subtest: A-eDP1
[278.469280] Dynamic subtest A-eDP1: SUCCESS (8.917s)
[278.482857] Starting dynamic subtest: B-eDP1
[287.309955] Dynamic subtest B-eDP1: SUCCESS (8.837s)
[287.321820] Starting dynamic subtest: C-eDP1
[296.100533] Dynamic subtest C-eDP1: SUCCESS (8.810s)
[296.109706] Starting dynamic subtest: D-eDP1
[304.963671] Dynamic subtest D-eDP1: SUCCESS (8.825s)
[304.964614] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.401s)
[306.121560] [034/156] (719s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[306.210074] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt
[306.211717] Subtest psrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[306.304007] [035/156] (719s left) gem_exec_flush (basic-wb-ro-default)
[306.357763] Starting subtest: basic-wb-ro-default
[311.817913] Subtest basic-wb-ro-default: SUCCESS (5.459s)
[312.000883] [036/156] (713s left) kms_color (invalid-gamma-lut-sizes)
[312.089633] Starting subtest: invalid-gamma-lut-sizes
[312.090536] Starting dynamic subtest: pipe-A
[312.383892] Dynamic subtest pipe-A: SUCCESS (0.293s)
[312.394707] Starting dynamic subtest: pipe-B
[312.395609] Dynamic subtest pipe-B: SUCCESS (0.000s)
[312.404256] Starting dynamic subtest: pipe-C
[312.405223] Dynamic subtest pipe-C: SUCCESS (0.000s)
[312.413957] Starting dynamic subtest: pipe-D
[312.415186] Dynamic subtest pipe-D: SUCCESS (0.000s)
[312.416215] Subtest invalid-gamma-lut-sizes: SUCCESS (0.296s)
[313.252116] [037/156] (712s left) prime_vgem (basic-read)
[313.306907] Starting subtest: basic-read
[313.313237] Subtest basic-read: SKIP (0.007s)
[313.409820] [038/156] (712s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite)
[313.511292] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite
[313.513176] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[313.604493] [039/156] (711s left) kms_cursor_legacy (flip-vs-cursor-varying-size)
[313.716584] Starting subtest: flip-vs-cursor-varying-size
[322.916979] Subtest flip-vs-cursor-varying-size: SUCCESS (9.196s)
[323.770601] [040/156] (701s left) kms_psr2_su (frontbuffer-xrgb8888)
[323.818880] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[323.875386] [041/156] (701s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-shrfb-msflip-blt)
[323.961773] Starting subtest: psrhdr-1p-primscrn-shrfb-msflip-blt
[323.963448] Subtest psrhdr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[324.054785] [042/156] (701s left) i915_drm_fdinfo (isolation)
[324.087240] Starting subtest: isolation
[324.088121] Starting dynamic subtest: rcs0
[324.111466] Dynamic subtest rcs0: SKIP (0.023s)
[324.121056] Starting dynamic subtest: bcs0
[324.122827] Dynamic subtest bcs0: SKIP (0.000s)
[324.134610] Starting dynamic subtest: vcs0
[324.136277] Dynamic subtest vcs0: SKIP (0.000s)
[324.145198] Starting dynamic subtest: vcs1
[324.147070] Dynamic subtest vcs1: SKIP (0.000s)
[324.156092] Starting dynamic subtest: vecs0
[324.158591] Dynamic subtest vecs0: SKIP (0.000s)
[324.167125] Starting dynamic subtest: ccs0
[324.168995] Dynamic subtest ccs0: SKIP (0.000s)
[324.170689] Subtest isolation: SKIP (0.028s)
[324.201655] [043/156] (701s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[324.295323] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[324.296260] Starting dynamic subtest: pipe-A-eDP-1
[326.543487] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.250s)
[326.553564] Starting dynamic subtest: pipe-B-eDP-1
[328.944627] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.399s)
[328.955131] Starting dynamic subtest: pipe-C-eDP-1
[331.150932] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.204s)
[331.161606] Starting dynamic subtest: pipe-D-eDP-1
[333.214976] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.062s)
[333.215853] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.920s)
[334.415216] [044/156] (691s left) kms_frontbuffer_tracking (fbchdr-2p-pri-indfb-multidraw)
[334.509761] Starting subtest: fbchdr-2p-pri-indfb-multidraw
[334.511920] Subtest fbchdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[334.613840] [045/156] (691s left) syncobj_timeline (signal-point-0)
[334.659769] Starting subtest: signal-point-0
[334.660715] Subtest signal-point-0: SUCCESS (0.000s)
[334.750544] [046/156] (690s left) kms_cursor_crc (cursor-offscreen-max-size)
[335.057745] Starting subtest: cursor-offscreen-max-size
[335.060202] Subtest cursor-offscreen-max-size: SKIP (0.000s)
[335.187201] [047/156] (690s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-msflip-blt)
[335.277957] Starting subtest: fbchdr-1p-primscrn-indfb-msflip-blt
[335.279786] Subtest fbchdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[335.382805] [048/156] (690s left) kms_pm_rpm (basic-pci-d3-state)
[337.010836] Starting subtest: basic-pci-d3-state
[338.507718] Subtest basic-pci-d3-state: SUCCESS (1.500s)
[338.629297] [049/156] (687s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[338.724977] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[338.725916] Starting dynamic subtest: pipe-A-eDP-1
[338.883141] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.154s)
[338.893608] Starting dynamic subtest: pipe-B-eDP-1
[338.901844] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.011s)
[338.910966] Starting dynamic subtest: pipe-C-eDP-1
[338.918990] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s)
[338.927814] Starting dynamic subtest: pipe-D-eDP-1
[338.935932] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.012s)
[338.936841] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.193s)
[341.235157] [050/156] (684s left) kms_lease (lease-get)
[341.318749] Starting subtest: lease-get
[341.319675] Starting dynamic subtest: pipe-A-eDP-1
[341.320726] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[341.331893] Starting dynamic subtest: pipe-B-eDP-1
[341.332826] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[341.341677] Starting dynamic subtest: pipe-C-eDP-1
[341.342525] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[341.351279] Starting dynamic subtest: pipe-D-eDP-1
[341.352212] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[341.353293] Subtest lease-get: SUCCESS (0.010s)
[341.419130] [051/156] (684s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-pwrite)
[341.506867] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-pwrite
[348.891621] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (7.387s)
[349.025195] [052/156] (676s left) gem_reg_read (bad-register)
[349.085400] Starting subtest: bad-register
[349.086839] Subtest bad-register: SUCCESS (0.000s)
[349.188673] [053/156] (676s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-pwrite)
[349.288728] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-pwrite
[349.290582] Subtest hdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[349.384640] [054/156] (676s left) kms_chamelium_color (degamma)
[349.469114] Subtest degamma: SKIP (0.000s)
[349.509912] [055/156] (676s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[349.614082] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc
[349.616135] Subtest fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[349.710695] [056/156] (676s left) gem_close_race (multigpu-basic-threads)
[349.776893] Starting subtest: multigpu-basic-threads
[349.778830] Subtest multigpu-basic-threads: SKIP (0.000s)
[350.002237] [057/156] (676s left) syncobj_basic (bad-pad-handle-to-fd)
[350.057558] Starting subtest: bad-pad-handle-to-fd
[350.058513] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[350.176835] [058/156] (676s left) kms_addfb_basic (bad-pitch-63)
[350.220508] Starting subtest: bad-pitch-63
[350.221463] Subtest bad-pitch-63: SUCCESS (0.000s)
[350.347508] [059/156] (675s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[350.399746] Starting subtest: etime-single-wait-all-available-unsubmitted
[350.521359] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.108s)
[350.583519] [060/156] (675s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[350.679706] Starting subtest: y-tiled-8bpp-rotate-270
[350.681413] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[350.788416] [061/156] (675s left) gem_mmap_wc (copy)
[350.840296] Subtest copy: SKIP (0.000s)
[350.909231] [062/156] (675s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[350.997154] Starting subtest: fbcpsr-1p-rte
[357.995100] Subtest fbcpsr-1p-rte: SUCCESS (7.001s)
[358.138963] [063/156] (668s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[358.240084] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[358.242029] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[358.333919] [064/156] (668s left) kms_rotation_crc (primary-yf-tiled-reflect-x-0)
[358.423566] Starting subtest: primary-yf-tiled-reflect-x-0
[358.495180] Subtest primary-yf-tiled-reflect-x-0: SKIP (0.071s)
[358.595664] [065/156] (667s left) gem_mmap (bad-offset)
[358.643933] Subtest bad-offset: SKIP (0.000s)
[358.691232] [066/156] (667s left) sw_sync (timeline_closed)
[358.721930] Starting subtest: timeline_closed
[358.722917] Subtest timeline_closed: SUCCESS (0.000s)
[358.769972] [067/156] (667s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[358.866700] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[358.868466] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[358.967309] [068/156] (667s left) prime_mmap (test_correct)
[359.020975] Starting subtest: test_correct
[359.022154] Starting dynamic subtest: test_correct-smem
[359.054226] Dynamic subtest test_correct-smem: SUCCESS (0.033s)
[359.055885] Subtest test_correct: SUCCESS (0.034s)
[359.158078] [069/156] (667s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[359.247348] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[359.249464] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[359.341719] [070/156] (667s left) dumb_buffer (map-uaf)
[359.395046] Starting subtest: map-uaf
[359.400152] Subtest map-uaf: SUCCESS (0.005s)
[359.524329] [071/156] (667s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[359.625461] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-mmap-wc
[359.627219] Subtest hdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[359.710724] [072/156] (666s left) gem_ccs (ctrl-surf-copy-new-ctx)
[359.765424] Starting subtest: ctrl-surf-copy-new-ctx
[359.767158] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[359.856301] [073/156] (666s left) gem_unref_active_buffers
[360.047767] [074/156] (666s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[360.134441] Starting subtest: fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt
[360.136287] Subtest fbcpsrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[360.232430] [075/156] (666s left) prime_busy (hang-wait)
[360.296818] Starting subtest: hang-wait
[360.297831] Starting dynamic subtest: rcs0
[386.299743] Dynamic subtest rcs0: SUCCESS (25.997s)
[386.319765] Starting dynamic subtest: bcs0
[393.455710] Dynamic subtest bcs0: SUCCESS (7.155s)
[393.464545] Starting dynamic subtest: vcs0
[400.492728] Dynamic subtest vcs0: SUCCESS (7.037s)
[400.502570] Starting dynamic subtest: vcs1
[407.465127] Dynamic subtest vcs1: SUCCESS (6.968s)
[407.476257] Starting dynamic subtest: vecs0
[414.441249] Dynamic subtest vecs0: SUCCESS (6.973s)
[414.451623] Starting dynamic subtest: ccs0
[428.277136] Dynamic subtest ccs0: SUCCESS (13.831s)
[428.278596] Subtest hang-wait: SUCCESS (67.975s)
[428.386846] [076/156] (598s left) gem_ctx_exec (basic)
[428.442582] Starting subtest: basic
[428.449907] Subtest basic: SUCCESS (0.008s)
[428.596353] [077/156] (598s left) kms_addfb_basic (addfb25-x-tiled-mismatch-legacy)
[428.693619] Subtest addfb25-x-tiled-mismatch-legacy: SKIP (0.000s)
[428.788092] [078/156] (598s left) kms_busy (extended-pageflip-hang-newfb)
[428.886654] Starting subtest: extended-pageflip-hang-newfb
[428.887465] Starting dynamic subtest: pipe-A
[455.605995] Dynamic subtest pipe-A: SUCCESS (26.714s)
[455.616860] Starting dynamic subtest: pipe-D
[480.620743] Dynamic subtest pipe-D: SUCCESS (25.008s)
[480.621728] Subtest extended-pageflip-hang-newfb: SUCCESS (51.726s)
[481.475712] [079/156] (545s left) kms_plane_scaling (planes-scaler-unity-scaling)
[481.572877] Starting subtest: planes-scaler-unity-scaling
[481.574348] Starting dynamic subtest: pipe-A
[481.636563] Dynamic subtest pipe-A: SUCCESS (0.064s)
[481.650047] Starting dynamic subtest: pipe-B
[482.808930] Dynamic subtest pipe-B: SUCCESS (1.170s)
[482.824406] Starting dynamic subtest: pipe-C
[483.936195] Dynamic subtest pipe-C: SUCCESS (1.126s)
[483.948735] Starting dynamic subtest: pipe-D
[485.074291] Dynamic subtest pipe-D: SUCCESS (1.136s)
[485.075143] Subtest planes-scaler-unity-scaling: SUCCESS (3.502s)
[486.223188] [080/156] (540s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[486.324182] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip
[487.381558] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.058s)
[487.504444] [081/156] (539s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[487.611901] Starting subtest: fbc-2p-pri-indfb-multidraw
[487.613651] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[487.699472] [082/156] (539s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[487.784050] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-gtt
[487.785718] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[487.902673] [083/156] (539s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[487.991023] Starting subtest: hdr-1p-primscrn-pri-indfb-draw-mmap-wc
[487.992629] Subtest hdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[488.078099] [084/156] (539s left) gem_ctx_shared (q-out-order)
[488.140916] Starting subtest: Q-out-order
[488.142111] Starting dynamic subtest: rcs0
[488.249960] Dynamic subtest rcs0: SUCCESS (0.107s)
[488.270696] Starting dynamic subtest: vcs0
[488.337253] Dynamic subtest vcs0: SUCCESS (0.088s)
[488.356636] Starting dynamic subtest: vcs1
[488.418713] Dynamic subtest vcs1: SUCCESS (0.078s)
[488.435898] Starting dynamic subtest: vecs0
[488.494881] Dynamic subtest vecs0: SUCCESS (0.075s)
[488.512141] Starting dynamic subtest: ccs0
[488.586767] Dynamic subtest ccs0: SUCCESS (0.090s)
[488.588443] Subtest Q-out-order: SUCCESS (0.445s)
[489.891193] [085/156] (537s left) gen9_exec_parse (basic-rejected)
[489.945588] Subtest basic-rejected: SKIP (0.000s)
[490.013749] [086/156] (537s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[490.107732] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[490.109407] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[490.207750] [087/156] (537s left) perf_pmu (semaphore-wait)
[490.245797] Starting subtest: semaphore-wait
[490.246725] Starting dynamic subtest: rcs0
[490.769924] Dynamic subtest rcs0: SUCCESS (0.513s)
[490.781706] Starting dynamic subtest: bcs0
[491.283462] Dynamic subtest bcs0: SUCCESS (0.510s)
[491.295602] Starting dynamic subtest: vcs0
[491.796865] Dynamic subtest vcs0: SUCCESS (0.510s)
[491.809638] Starting dynamic subtest: vcs1
[492.311724] Dynamic subtest vcs1: SUCCESS (0.511s)
[492.323835] Starting dynamic subtest: vecs0
[492.825184] Dynamic subtest vecs0: SUCCESS (0.510s)
[492.835316] Starting dynamic subtest: ccs0
[493.340991] Dynamic subtest ccs0: SUCCESS (0.512s)
[493.342572] Subtest semaphore-wait: SUCCESS (3.086s)
[494.943951] [088/156] (532s left) kms_getfb (getfb-reject-nv12)
[495.038802] Starting subtest: getfb-reject-nv12
[495.039748] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[495.135086] [089/156] (532s left) kms_psr (fbc-psr2-cursor-blt)
[495.225830] Starting subtest: fbc-psr2-cursor-blt
[495.226737] Starting dynamic subtest: eDP-1
[495.228348] Dynamic subtest eDP-1: SKIP (0.000s)
[495.230159] Subtest fbc-psr2-cursor-blt: SKIP (0.001s)
[495.312166] [090/156] (532s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[495.404430] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[502.878536] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.478s)
[503.035773] [091/156] (524s left) syncobj_eventfd (timeline-wait-before-signal)
[503.088241] Starting subtest: timeline-wait-before-signal
[503.119239] Subtest timeline-wait-before-signal: SUCCESS (0.031s)
[503.191984] [092/156] (524s left) kms_psr (fbc-psr-cursor-plane-onoff)
[503.284783] Starting subtest: fbc-psr-cursor-plane-onoff
[503.285749] Starting dynamic subtest: eDP-1
[503.287516] Dynamic subtest eDP-1: SKIP (0.000s)
[503.289216] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.001s)
[503.355601] [093/156] (524s left) gem_sync (basic-many-each)
[503.413757] Starting subtest: basic-many-each
[505.698824] Subtest basic-many-each: SUCCESS (2.286s)
[505.885897] [094/156] (521s left) gem_userptr_blits (probe)
[505.955495] Starting subtest: probe
[506.033311] Subtest probe: SUCCESS (0.079s)
[506.138272] [095/156] (521s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-blt)
[506.232440] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-blt
[506.234364] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[506.323137] [096/156] (521s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[506.431986] Starting subtest: yf-tiled-addfb-size-overflow
[506.434083] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[506.532222] [097/156] (521s left) sw_sync (timeline_closed_signaled)
[506.563425] Starting subtest: timeline_closed_signaled
[506.564650] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[506.619752] [098/156] (521s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[506.720357] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[506.722228] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[506.818351] [099/156] (520s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-180)
[506.926779] Starting subtest: linear-max-hw-stride-64bpp-rotate-180
[507.939223] Subtest linear-max-hw-stride-64bpp-rotate-180: SUCCESS (1.012s)
[508.046754] [100/156] (519s left) kms_flip (flip-vs-panning-interruptible)
[508.142294] Starting subtest: flip-vs-panning-interruptible
[508.144577] Starting dynamic subtest: A-eDP1
[516.877972] Dynamic subtest A-eDP1: SUCCESS (8.731s)
[516.890628] Starting dynamic subtest: B-eDP1
[525.628466] Dynamic subtest B-eDP1: SUCCESS (8.746s)
[525.641328] Starting dynamic subtest: C-eDP1
[534.331082] Dynamic subtest C-eDP1: SUCCESS (8.698s)
[534.345258] Starting dynamic subtest: D-eDP1
[543.061729] Dynamic subtest D-eDP1: SUCCESS (8.727s)
[543.064766] Subtest flip-vs-panning-interruptible: SUCCESS (34.915s)
[544.229165] [101/156] (483s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-shrfb-plflip-blt)
[544.324224] Starting subtest: psrhdr-2p-primscrn-shrfb-plflip-blt
[544.325865] Subtest psrhdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[544.427358] [102/156] (483s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[544.523175] Starting subtest: psr2-cursor-plane-move-continuous-sf
[544.524259] Starting dynamic subtest: pipe-A-eDP-1
[554.887440] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.370s)
[554.896879] Starting dynamic subtest: pipe-B-eDP-1
[565.988970] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.098s)
[565.990006] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (21.470s)
[566.887183] [103/156] (460s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[566.982388] Starting subtest: fbc-rgb565-draw-pwrite
[571.725366] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.743s)
[572.880492] [104/156] (454s left) kms_feature_discovery (psr2)
[572.980648] Starting subtest: psr2
[572.981481] Subtest psr2: SUCCESS (0.000s)
[573.051790] [105/156] (454s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-move)
[573.140902] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-move
[573.142531] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[573.226714] [106/156] (454s left) syncobj_basic (bad-fd-to-handle)
[573.275934] Starting subtest: bad-fd-to-handle
[573.276842] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[573.365946] [107/156] (454s left) syncobj_wait (signal)
[573.428571] Starting subtest: signal
[573.429917] Subtest signal: SUCCESS (0.000s)
[573.534777] [108/156] (454s left) kms_content_protection (dp-mst-type-0)
[573.678198] Starting subtest: dp-mst-type-0
[573.680071] Subtest dp-mst-type-0: SKIP (0.000s)
[573.783601] [109/156] (454s left) gem_exec_schedule (semaphore-noskip)
[573.840040] Starting subtest: semaphore-noskip
[574.285802] Subtest semaphore-noskip: SUCCESS (0.436s)
[574.622827] [110/156] (453s left) kms_psr (pr-sprite-render)
[574.711662] Starting subtest: pr-sprite-render
[574.713259] Subtest pr-sprite-render: SKIP (0.000s)
[574.780338] [111/156] (453s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-plflip-blt)
[574.878239] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-plflip-blt
[574.879766] Subtest fbcpsrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[574.958972] [112/156] (453s left) kms_plane_multiple (tiling-4)
[575.051815] Starting subtest: tiling-4
[575.052726] Starting dynamic subtest: pipe-A-eDP-1
[578.808964] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.756s)
[578.820879] Starting dynamic subtest: pipe-B-eDP-1
[583.372289] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.562s)
[583.383640] Starting dynamic subtest: pipe-C-eDP-1
[587.797043] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.423s)
[587.806781] Starting dynamic subtest: pipe-D-eDP-1
[592.234284] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.435s)
[592.235561] Subtest tiling-4: SUCCESS (17.180s)
[593.055505] [113/156] (435s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-blt)
[593.152331] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-blt
[593.154181] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-blt: SKIP (0.000s)
[593.239232] [114/156] (434s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[593.326345] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[593.328591] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[593.417118] [115/156] (434s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[593.459700] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[593.461181] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[593.559880] [116/156] (434s left) gem_exec_params (dr1-dirt)
[593.604000] Starting subtest: DR1-dirt
[593.605290] Subtest DR1-dirt: SUCCESS (0.000s)
[593.714879] [117/156] (434s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[593.810996] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[593.812801] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[593.915776] [118/156] (434s left) gem_exec_balancer (hang)
[594.192275] Starting subtest: hang
[595.904520] Subtest hang: SUCCESS (1.724s)
[596.024738] [119/156] (432s left) gem_ctx_persistence (legacy-engines-hostile-preempt)
[596.074478] Starting subtest: legacy-engines-hostile-preempt
[596.077624] Starting dynamic subtest: render
[596.140740] Dynamic subtest render: SUCCESS (0.063s)
[596.154318] Starting dynamic subtest: bsd1
[597.861056] Dynamic subtest bsd1: SUCCESS (1.708s)
[597.872992] Starting dynamic subtest: bsd2
[599.838888] Dynamic subtest bsd2: SUCCESS (1.974s)
[599.850710] Starting dynamic subtest: blt
[599.855579] Dynamic subtest blt: SUCCESS (0.023s)
[599.864665] Starting dynamic subtest: vebox
[601.800702] Dynamic subtest vebox: SUCCESS (1.934s)
[601.801907] Subtest legacy-engines-hostile-preempt: SUCCESS (5.717s)
[601.915909] [120/156] (426s left) gem_pxp (create-regular-context-1)
[602.028137] Starting subtest: create-regular-context-1
[602.029703] Subtest create-regular-context-1: SUCCESS (0.002s)
[602.134498] [121/156] (426s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-blt)
[602.228021] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-blt
[602.229648] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[602.319956] [122/156] (426s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[602.398945] Starting subtest: read-crc-frame-sequence
[602.400121] Starting dynamic subtest: pipe-A-eDP-1
[603.111818] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.713s)
[603.121871] Starting dynamic subtest: pipe-B-eDP-1
[604.532401] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.418s)
[604.543674] Starting dynamic subtest: pipe-C-eDP-1
[605.920572] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.387s)
[605.931995] Starting dynamic subtest: pipe-D-eDP-1
[607.263270] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.341s)
[607.264426] Subtest read-crc-frame-sequence: SUCCESS (4.865s)
[608.114956] [123/156] (420s left) i915_pm_rps (min-max-config-idle)
[608.188784] Starting subtest: min-max-config-idle
[608.190464] Subtest min-max-config-idle: SKIP (0.001s)
[608.271109] [124/156] (420s left) kms_big_fb (x-tiled-addfb-size-overflow)
[608.361247] Starting subtest: x-tiled-addfb-size-overflow
[608.362112] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.000s)
[608.456465] [125/156] (420s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[608.573705] Starting subtest: cursorB-vs-flipA-varying-size
[608.576702] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[608.714202] [126/156] (419s left) kms_ccs (random-ccs-data-4-tiled-bmg-ccs)
[608.804147] Starting subtest: random-ccs-data-4-tiled-bmg-ccs
[608.806387] Subtest random-ccs-data-4-tiled-bmg-ccs: SKIP (0.000s)
[609.922192] [127/156] (418s left) gem_exec_fence (submit67)
[610.092202] Starting subtest: submit67
[610.094233] Subtest submit67: SKIP (0.000s)
[611.872120] [128/156] (416s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[611.974340] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[611.976927] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[612.095111] [129/156] (416s left) kms_psr (psr-primary-mmap-cpu)
[612.180545] Starting subtest: psr-primary-mmap-cpu
[612.181359] Starting dynamic subtest: eDP-1
[613.904665] Dynamic subtest eDP-1: SUCCESS (1.723s)
[613.905581] Subtest psr-primary-mmap-cpu: SUCCESS (1.724s)
[614.767167] [130/156] (414s left) gem_exercise_blt (fast-copy)
[614.823079] Starting subtest: fast-copy
[614.823910] Starting dynamic subtest: linear-smem-smem
[614.846289] Dynamic subtest linear-smem-smem: SUCCESS (0.023s)
[614.856312] Starting dynamic subtest: xmajor-smem-smem
[614.864068] Dynamic subtest xmajor-smem-smem: SUCCESS (0.017s)
[614.873568] Starting dynamic subtest: tile4-smem-smem
[614.881718] Dynamic subtest tile4-smem-smem: SUCCESS (0.017s)
[614.891245] Starting dynamic subtest: tile64-smem-smem
[614.899250] Dynamic subtest tile64-smem-smem: SUCCESS (0.017s)
[614.900270] Subtest fast-copy: SUCCESS (0.076s)
[615.019652] [131/156] (413s left) prime_mmap (test_forked_cpu_write)
[615.076026] Starting subtest: test_forked_cpu_write
[615.076969] Starting dynamic subtest: test_forked_cpu_write-smem
[615.083848] Dynamic subtest test_forked_cpu_write-smem: SUCCESS (0.009s)
[615.084868] Subtest test_forked_cpu_write: SUCCESS (0.010s)
[615.197200] [132/156] (413s left) gem_exec_params (cliprects-invalid)
[615.252368] Starting subtest: cliprects-invalid
[615.253328] Subtest cliprects-invalid: SUCCESS (0.000s)
[615.339084] [133/156] (413s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[615.424204] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[619.059659] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (3.636s)
[620.272476] [134/156] (408s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[620.374981] Starting subtest: 4-tiled-16bpp-rotate-180
[621.780028] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.405s)
[621.902801] [135/156] (407s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[622.293120] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[623.076022] [136/156] (405s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[623.165939] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[623.193569] Starting dynamic subtest: pipe-A-plane-0
[623.482168] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[623.492069] Starting dynamic subtest: pipe-A-plane-3
[623.715444] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[624.790203] Starting dynamic subtest: pipe-B-plane-0
[625.068546] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[625.078314] Starting dynamic subtest: pipe-B-plane-3
[625.301786] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[625.612630] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.447s)
[626.464772] [137/156] (402s left) gem_exec_schedule (implicit-boths)
[626.527430] Starting subtest: implicit-boths
[626.528886] Starting dynamic subtest: rcs0
[626.645115] Dynamic subtest rcs0: SUCCESS (0.118s)
[626.657285] Starting dynamic subtest: vcs0
[626.736544] Dynamic subtest vcs0: SUCCESS (0.089s)
[626.753532] Starting dynamic subtest: vcs1
[626.818206] Dynamic subtest vcs1: SUCCESS (0.081s)
[626.831868] Starting dynamic subtest: vecs0
[626.896210] Dynamic subtest vecs0: SUCCESS (0.078s)
[626.908234] Starting dynamic subtest: ccs0
[626.987335] Dynamic subtest ccs0: SUCCESS (0.090s)
[626.988643] Subtest implicit-boths: SUCCESS (0.461s)
[627.964841] [138/156] (401s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[628.051722] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[628.140221] [139/156] (401s left) gem_exec_create (madvise)
[628.195111] Starting subtest: madvise
[628.196147] Starting dynamic subtest: smem
[649.889962] Dynamic subtest smem: SUCCESS (21.687s)
[649.891603] Subtest madvise: SUCCESS (21.688s)
[650.642798] [140/156] (378s left) kms_plane (plane-position-covered)
[650.744540] Starting subtest: plane-position-covered
[651.283130] Starting dynamic subtest: pipe-A-plane-1
[652.565610] Dynamic subtest pipe-A-plane-1: SUCCESS (1.283s)
[652.578945] Starting dynamic subtest: pipe-A-plane-2
[653.832072] Dynamic subtest pipe-A-plane-2: SUCCESS (1.266s)
[653.842847] Starting dynamic subtest: pipe-A-plane-3
[655.058571] Dynamic subtest pipe-A-plane-3: SUCCESS (1.225s)
[655.068854] Starting dynamic subtest: pipe-A-plane-4
[656.295020] Dynamic subtest pipe-A-plane-4: SUCCESS (1.236s)
[656.309617] Starting dynamic subtest: pipe-A-plane-5
[656.322971] Dynamic subtest pipe-A-plane-5: SUCCESS (0.028s)
[657.489894] Starting dynamic subtest: pipe-B-plane-1
[658.792549] Dynamic subtest pipe-B-plane-1: SUCCESS (1.311s)
[658.802439] Starting dynamic subtest: pipe-B-plane-2
[660.120583] Dynamic subtest pipe-B-plane-2: SUCCESS (1.328s)
[660.130774] Starting dynamic subtest: pipe-B-plane-3
[661.359426] Dynamic subtest pipe-B-plane-3: SUCCESS (1.238s)
[661.369607] Starting dynamic subtest: pipe-B-plane-4
[662.578651] Dynamic subtest pipe-B-plane-4: SUCCESS (1.219s)
[662.588868] Starting dynamic subtest: pipe-B-plane-5
[662.604874] Dynamic subtest pipe-B-plane-5: SUCCESS (0.025s)
[662.605728] Subtest plane-position-covered: SUCCESS (11.861s)
[663.397045] [141/156] (365s left) api_intel_bb (blit-noreloc-keep-cache)
[663.447510] Starting subtest: blit-noreloc-keep-cache
[663.460768] Subtest blit-noreloc-keep-cache: SUCCESS (0.014s)
[663.584476] [142/156] (365s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[663.668035] Starting subtest: psr-rgb101010-draw-blt
[671.117881] Subtest psr-rgb101010-draw-blt: SUCCESS (7.452s)
[671.251344] [143/156] (358s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-move)
[671.352802] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-move
[671.354582] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[671.469150] [144/156] (357s left) kms_vrr (flip-basic-fastset)
[671.560974] Starting subtest: flip-basic-fastset
[671.563559] Subtest flip-basic-fastset: SKIP (0.000s)
[671.631761] [145/156] (357s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[671.727162] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[671.728304] Starting dynamic subtest: pipe-A-eDP-1
[672.607155] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.877s)
[672.617653] Starting dynamic subtest: pipe-B-eDP-1
[674.180898] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.573s)
[674.190770] Starting dynamic subtest: pipe-C-eDP-1
[675.734495] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.553s)
[675.744486] Starting dynamic subtest: pipe-D-eDP-1
[677.246743] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.511s)
[677.247844] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.519s)
[678.428717] [146/156] (350s left) syncobj_wait (wait-zero-handles)
[678.476573] Starting subtest: wait-zero-handles
[678.477340] Subtest wait-zero-handles: SUCCESS (0.000s)
[678.601427] [147/156] (350s left) gem_exec_params (invalid-bsd1-flag-on-render)
[678.655617] Starting subtest: invalid-bsd1-flag-on-render
[678.656650] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[678.753840] [148/156] (350s left) kms_properties (crtc-properties-atomic)
[678.842474] Starting subtest: crtc-properties-atomic
[678.843317] Starting dynamic subtest: pipe-A-eDP-1
[679.281408] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.437s)
[679.291467] Starting dynamic subtest: pipe-B-eDP-1
[680.488758] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.206s)
[680.501810] Starting dynamic subtest: pipe-C-eDP-1
[681.614756] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.124s)
[681.625700] Starting dynamic subtest: pipe-D-eDP-1
[682.729567] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.114s)
[682.730522] Subtest crtc-properties-atomic: SUCCESS (3.887s)
[683.549577] [149/156] (345s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-mmap-cpu)
[683.646933] Starting subtest: psrhdr-rgb101010-draw-mmap-cpu
[683.648917] Subtest psrhdr-rgb101010-draw-mmap-cpu: SKIP (0.000s)
[683.737061] [150/156] (345s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[683.822825] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[683.824410] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[684.993650] [151/156] (344s left) i915_suspend (basic-s2idle-without-i915)
[685.022809] Starting subtest: basic-s2idle-without-i915
[705.953428] Subtest basic-s2idle-without-i915: SUCCESS (6.113s)
[710.108841] [152/156] (323s left) kms_atomic_transition (plane-all-transition-nonblocking)
[710.206819] Starting subtest: plane-all-transition-nonblocking
[710.207854] Starting dynamic subtest: pipe-A-eDP-1
[712.755572] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.540s)
[713.072384] Starting dynamic subtest: pipe-B-eDP-1
[716.316612] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.244s)
[716.618511] Subtest plane-all-transition-nonblocking: SUCCESS (6.394s)
[717.441369] [153/156] (316s left) kms_psr2_sf (fbc-pr-primary-plane-update-sf-dmg-area)
[717.529452] Starting subtest: fbc-pr-primary-plane-update-sf-dmg-area
[717.531256] Subtest fbc-pr-primary-plane-update-sf-dmg-area: SKIP (0.000s)
[717.599260] [154/156] (316s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[717.643525] Starting subtest: single-wait-all-for-submit-available-submitted
[717.644620] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[717.754876] [155/156] (316s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[717.804385] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[717.806254] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[717.983154] [156/156] (315s left) kms_setmode (invalid-clone-single-crtc)
[718.046644] Starting subtest: invalid-clone-single-crtc
[718.049268] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[718.155357] Closing watchdogs
results: parsing output: 0/ for test: kms_invalid_mode
results: parsing output: 1/ for test: gem_render_copy
results: parsing output: 2/ for test: gem_exec_fence
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_pm_rpm
results: parsing output: 5/ for test: template
results: parsing output: 6/ for test: kms_vblank
results: parsing output: 7/ for test: gem_exec_store
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: gem_spin_batch
results: parsing output: 10/ for test: api_intel_bb
results: parsing output: 11/ for test: kms_psr
results: parsing output: 12/ for test: kms_flip_scaled_crc
results: parsing output: 13/ for test: kms_frontbuffer_tracking
results: parsing output: 14/ for test: kms_cursor_edge_walk
results: parsing output: 15/ for test: kms_cursor_legacy
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: gem_exec_nop
results: parsing output: 18/ for test: perf_pmu
results: parsing output: 19/ for test: kms_big_fb
results: parsing output: 20/ for test: kms_cursor_crc
results: parsing output: 21/ for test: gem_mmap_gtt
results: parsing output: 22/ for test: gem_ctx_isolation
results: parsing output: 23/ for test: i915_drm_fdinfo
results: parsing output: 24/ for test: gem_exec_schedule
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_display_modes
results: parsing output: 27/ for test: kms_flip
results: parsing output: 28/ for test: gem_eio
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_flip
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: kms_flip
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_exec_flush
results: parsing output: 35/ for test: kms_color
results: parsing output: 36/ for test: prime_vgem
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_cursor_legacy
results: parsing output: 39/ for test: kms_psr2_su
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: i915_drm_fdinfo
results: parsing output: 42/ for test: kms_ccs
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: syncobj_timeline
results: parsing output: 45/ for test: kms_cursor_crc
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_pm_rpm
results: parsing output: 48/ for test: kms_ccs
results: parsing output: 49/ for test: kms_lease
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_reg_read
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_chamelium_color
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_close_race
results: parsing output: 56/ for test: syncobj_basic
results: parsing output: 57/ for test: kms_addfb_basic
results: parsing output: 58/ for test: syncobj_timeline
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: gem_mmap_wc
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_rotation_crc
results: parsing output: 64/ for test: gem_mmap
results: parsing output: 65/ for test: sw_sync
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: prime_mmap
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: dumb_buffer
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: gem_ccs
results: parsing output: 72/ for test: gem_unref_active_buffers
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: prime_busy
results: parsing output: 75/ for test: gem_ctx_exec
results: parsing output: 76/ for test: kms_addfb_basic
results: parsing output: 77/ for test: kms_busy
results: parsing output: 78/ for test: kms_plane_scaling
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: gem_ctx_shared
results: parsing output: 84/ for test: gen9_exec_parse
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: perf_pmu
results: parsing output: 87/ for test: kms_getfb
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: syncobj_eventfd
results: parsing output: 91/ for test: kms_psr
results: parsing output: 92/ for test: gem_sync
results: parsing output: 93/ for test: gem_userptr_blits
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: sw_sync
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_psr2_sf
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_feature_discovery
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: syncobj_basic
results: parsing output: 106/ for test: syncobj_wait
results: parsing output: 107/ for test: kms_content_protection
results: parsing output: 108/ for test: gem_exec_schedule
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_plane_multiple
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: syncobj_timeline
results: parsing output: 115/ for test: gem_exec_params
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: gem_exec_balancer
results: parsing output: 118/ for test: gem_ctx_persistence
results: parsing output: 119/ for test: gem_pxp
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_pipe_crc_basic
results: parsing output: 122/ for test: i915_pm_rps
results: parsing output: 123/ for test: kms_big_fb
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: kms_ccs
results: parsing output: 126/ for test: gem_exec_fence
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_psr
results: parsing output: 129/ for test: gem_exercise_blt
results: parsing output: 130/ for test: prime_mmap
results: parsing output: 131/ for test: gem_exec_params
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_big_fb
results: parsing output: 134/ for test: kms_chamelium_hpd
results: parsing output: 135/ for test: kms_plane
results: parsing output: 136/ for test: gem_exec_schedule
results: parsing output: 137/ for test: kms_addfb_basic
results: parsing output: 138/ for test: gem_exec_create
results: parsing output: 139/ for test: kms_plane
results: parsing output: 140/ for test: api_intel_bb
results: parsing output: 141/ for test: kms_frontbuffer_tracking
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: kms_vrr
results: parsing output: 144/ for test: kms_ccs
results: parsing output: 145/ for test: syncobj_wait
results: parsing output: 146/ for test: gem_exec_params
results: parsing output: 147/ for test: kms_properties
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: kms_ccs
results: parsing output: 150/ for test: i915_suspend
results: parsing output: 151/ for test: kms_atomic_transition
results: parsing output: 152/ for test: kms_psr2_sf
results: parsing output: 153/ for test: syncobj_timeline
results: parsing output: 154/ for test: gem_render_copy
results: parsing output: 155/ for test: kms_setmode
Done.