[59.912252] Initializing watchdogs
[59.912305]   /dev/watchdog0
[59.927634] [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]
[59.932340] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.934845] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.938132] [001/155] (960s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-render)
[60.067235] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-render
[60.071491] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[60.173754] [002/155] (960s left) kms_big_fb (4-tiled-64bpp-rotate-180)
[60.285510] Starting subtest: 4-tiled-64bpp-rotate-180
[62.189030] Subtest 4-tiled-64bpp-rotate-180: FAIL (1.305s)
[62.223923] [003/155] (958s left) kms_psr2_su (page_flip-p010)
[62.277217] Subtest page_flip-P010: SKIP (0.000s)
[62.332761] [004/155] (958s left) kms_pm_rpm (cursor-dpms)
[64.465563] Starting subtest: cursor-dpms
[64.470563] Subtest cursor-dpms: SKIP (0.000s)
[64.862230] [005/155] (955s left) kms_cursor_crc (cursor-rapid-movement-256x85)
[65.116723] Starting subtest: cursor-rapid-movement-256x85
[65.165973] Subtest cursor-rapid-movement-256x85: SKIP (0.046s)
[65.343636] [006/155] (955s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[65.399347] Starting subtest: single-wait-for-submit-available-signaled
[65.401661] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[65.495253] [007/155] (955s left) syncobj_basic (bad-create-flags)
[65.549793] Starting subtest: bad-create-flags
[65.552064] Subtest bad-create-flags: SUCCESS (0.000s)
[65.644702] [008/155] (955s left) gem_mmap_offset (blt-coherency)
[65.703037] Starting subtest: blt-coherency
[65.709773] Subtest blt-coherency: SUCCESS (0.007s)
[65.841876] [009/155] (954s left) kms_pipe_crc_basic (suspend-read-crc)
[65.928259] Starting subtest: suspend-read-crc
[65.929974] Starting dynamic subtest: pipe-A-eDP-1
[83.104899] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.689s)
[83.116422] Starting dynamic subtest: pipe-B-eDP-1
[101.093863] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.205s)
[101.104347] Starting dynamic subtest: pipe-C-eDP-1
[119.061284] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.222s)
[119.071967] Starting dynamic subtest: pipe-D-eDP-1
[137.057447] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.075s)
[137.060028] Subtest suspend-read-crc: SUCCESS (12.196s)
[137.922350] [010/155] (882s left) perf (oa-exponents)
[138.055178] Starting subtest: oa-exponents
[138.058007] Starting dynamic subtest: 0-rcs0
[139.875448] Dynamic subtest 0-rcs0: SUCCESS (1.792s)
[139.885683] Starting dynamic subtest: 1-vcs0
[141.730860] Dynamic subtest 1-vcs0: SUCCESS (1.831s)
[141.733539] Subtest oa-exponents: SUCCESS (3.624s)
[141.771869] [011/155] (879s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-pwrite)
[141.880119] Starting subtest: fbcpsrhdr-rgb565-draw-pwrite
[141.885149] Subtest fbcpsrhdr-rgb565-draw-pwrite: SKIP (0.000s)
[141.969732] [012/155] (878s left) dmabuf_sync_file (import-multiple-read-only)
[142.052738] Starting subtest: import-multiple-read-only
[142.055177] Subtest import-multiple-read-only: SUCCESS (0.003s)
[142.106619] [FACT dmabuf_sync_file (import-multiple-read-only)] new: kernel.kmod_is_loaded.vgem: true
[142.110265] [013/155] (878s left) kms_chamelium_hpd (dp-hpd)
[142.494179] Subtest dp-hpd: SKIP (0.000s)
[143.300115] [014/155] (877s left) gem_render_linear_blits (basic)
[143.360986] Starting subtest: basic
[143.382058] Subtest basic: SUCCESS (0.024s)
[143.478160] [015/155] (877s left) kms_chamelium_frames (dp-crc-single)
[143.861846] Subtest dp-crc-single: SKIP (0.000s)
[144.754345] [016/155] (876s left) gem_softpin (allocator-evict)
[144.816988] Starting subtest: allocator-evict
[144.819304] Starting dynamic subtest: rcs0
[168.005787] Dynamic subtest rcs0: SUCCESS (23.127s)
[168.015187] Starting dynamic subtest: bcs0
[191.991956] Dynamic subtest bcs0: SUCCESS (23.994s)
[192.002489] Starting dynamic subtest: vcs0
[215.740170] Dynamic subtest vcs0: SUCCESS (23.743s)
[215.755609] Starting dynamic subtest: vcs1
[239.466487] Dynamic subtest vcs1: SUCCESS (23.720s)
[239.477945] Starting dynamic subtest: vecs0
[263.274144] Dynamic subtest vecs0: SUCCESS (23.803s)
[263.285470] Starting dynamic subtest: ccs0
[286.979770] Dynamic subtest ccs0: SUCCESS (23.698s)
[286.982926] Subtest allocator-evict: SUCCESS (142.091s)
[287.064164] [017/155] (734s left) kms_vblank (query-forked-busy)
[287.213633] Starting subtest: query-forked-busy
[287.216182] Starting dynamic subtest: pipe-A-eDP-1
[289.090104] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.874s)
[289.106051] Starting dynamic subtest: pipe-D-eDP-1
[291.688190] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.595s)
[291.690577] Subtest query-forked-busy: SUCCESS (4.471s)
[292.499015] [018/155] (728s left) gem_pxp (display-protected-crc)
[292.625836] Starting subtest: display-protected-crc
[294.478200] Subtest display-protected-crc: SUCCESS (1.855s)
[294.596305] [019/155] (726s left) kms_feature_discovery (psr2)
[294.695094] Starting subtest: psr2
[294.697973] Subtest psr2: SUCCESS (0.000s)
[294.766644] [020/155] (726s left) gem_ppgtt (blt-vs-render-ctxn)
[294.825063] Starting subtest: blt-vs-render-ctxN
[327.554082] Subtest blt-vs-render-ctxN: SUCCESS (32.718s)
[327.682117] [021/155] (693s left) kms_ccs (missing-ccs-buffer-y-tiled-ccs)
[327.835114] Starting subtest: missing-ccs-buffer-y-tiled-ccs
[327.837650] Starting dynamic subtest: pipe-A-eDP-1
[327.860977] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[327.870870] Starting dynamic subtest: pipe-B-eDP-1
[327.896993] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[327.907673] Starting dynamic subtest: pipe-C-eDP-1
[327.932002] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[327.940848] Starting dynamic subtest: pipe-D-eDP-1
[327.963688] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[327.968058] Subtest missing-ccs-buffer-y-tiled-ccs: SKIP (0.003s)
[330.169983] [022/155] (691s left) gem_render_copy (y-tiled)
[330.241635] Starting subtest: y-tiled
[330.247039] Subtest y-tiled: SKIP (0.000s)
[330.424771] [023/155] (690s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-render)
[330.531195] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-render
[330.536608] Subtest fbc-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[330.632659] [024/155] (690s left) kms_addfb_basic (addfb25-bad-modifier)
[330.729164] Starting subtest: addfb25-bad-modifier
[330.732003] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[330.838558] [025/155] (690s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[330.940025] Starting subtest: yf-tiled-32bpp-rotate-180
[330.945066] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[331.047289] [026/155] (690s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[331.149100] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[331.151428] Starting dynamic subtest: pipe-A-eDP-1
[347.374591] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.191s)
[347.384391] Starting dynamic subtest: pipe-B-eDP-1
[364.397361] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.465s)
[364.408025] Starting dynamic subtest: pipe-C-eDP-1
[381.323443] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.386s)
[381.334341] Starting dynamic subtest: pipe-D-eDP-1
[398.318287] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.348s)
[398.320696] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (9.393s)
[399.499104] [027/155] (622s left) gem_userptr_blits (huge-split)
[399.592805] Starting subtest: huge-split
[399.599514] Subtest huge-split: SUCCESS (0.007s)
[399.716442] [028/155] (621s left) perf_pmu (invalid-open)
[399.754295] Starting subtest: invalid-open
[399.756838] Subtest invalid-open: SUCCESS (0.000s)
[399.878513] [029/155] (621s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[399.974919] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[404.814670] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (4.777s)
[404.917509] [030/155] (616s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[405.019685] Starting subtest: fbc-rgb565-draw-pwrite
[409.752455] Subtest fbc-rgb565-draw-pwrite: SUCCESS (4.703s)
[410.961310] [031/155] (610s left) kms_hdr (brightness-with-hdr)
[411.065624] Starting subtest: brightness-with-hdr
[411.068006] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[411.072392] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SKIP (0.000s)
[411.083496] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[411.087611] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SKIP (0.000s)
[411.091994] Subtest brightness-with-hdr: SKIP (0.002s)
[411.153319] [032/155] (610s left) kms_chamelium_color (ctm-max)
[411.258337] Subtest ctm-max: SKIP (0.000s)
[411.303046] [033/155] (610s left) gem_ctx_persistence (engines-persistence)
[411.364768] Starting subtest: engines-persistence
[411.367108] Starting dynamic subtest: rcs0
[412.184030] Dynamic subtest rcs0: SUCCESS (0.813s)
[412.195120] Starting dynamic subtest: bcs0
[412.951242] Dynamic subtest bcs0: SUCCESS (0.764s)
[412.962445] Starting dynamic subtest: vcs0
[413.736302] Dynamic subtest vcs0: SUCCESS (0.780s)
[413.747486] Starting dynamic subtest: vcs1
[414.488318] Dynamic subtest vcs1: SUCCESS (0.748s)
[414.501122] Starting dynamic subtest: vecs0
[415.245146] Dynamic subtest vecs0: SUCCESS (0.753s)
[415.257710] Starting dynamic subtest: ccs0
[416.000403] Dynamic subtest ccs0: SUCCESS (0.751s)
[416.003248] Subtest engines-persistence: SUCCESS (4.620s)
[416.118850] [034/155] (605s left) perf_pmu (interrupts)
[416.158865] Starting subtest: interrupts
[417.268195] Subtest interrupts: SUCCESS (1.106s)
[417.405484] [035/155] (604s left) gem_softpin (noreloc-interruptible)
[417.456531] Starting subtest: noreloc-interruptible
[422.911577] Subtest noreloc-interruptible: SUCCESS (5.391s)
[422.993955] [036/155] (598s left) gem_exec_params (no-vebox)
[423.056539] Starting subtest: no-vebox
[423.061957] Subtest no-vebox: SUCCESS (0.004s)
[424.719807] [037/155] (597s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-mmap-wc)
[424.828645] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-mmap-wc
[424.833009] Subtest fbchdr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[424.933329] [038/155] (597s left) gem_exec_reloc (basic-concurrent16)
[424.992600] Subtest basic-concurrent16: SKIP (0.000s)
[425.072288] [039/155] (596s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[425.170174] Starting subtest: fbc-psr2-sprite-plane-onoff
[425.172458] Starting dynamic subtest: eDP-1
[425.176931] Dynamic subtest eDP-1: SKIP (0.000s)
[425.181303] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[425.246004] [040/155] (596s left) kms_content_protection (mei-interface)
[425.380118] Starting subtest: mei-interface
[425.392057] Subtest mei-interface: SKIP (0.010s)
[425.508192] [041/155] (596s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-shrfb-pgflip-blt)
[425.599399] Starting subtest: fbcpsrhdr-2p-scndscrn-shrfb-pgflip-blt
[425.603767] Subtest fbcpsrhdr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[425.707828] [042/155] (596s left) kms_atomic_transition (modeset-transition-nonblocking)
[425.796782] Starting subtest: modeset-transition-nonblocking
[425.798431] Starting dynamic subtest: 1x-outputs
[437.140380] Dynamic subtest 1x-outputs: SUCCESS (11.347s)
[437.142860] Subtest modeset-transition-nonblocking: SUCCESS (11.349s)
[437.995717] [043/155] (584s left) perf (blocking)
[438.123845] Starting subtest: blocking
[438.126306] Starting dynamic subtest: 0-rcs0
[448.198453] Dynamic subtest 0-rcs0: SUCCESS (10.023s)
[448.211285] Starting dynamic subtest: 1-vcs0
[458.222214] Dynamic subtest 1-vcs0: SUCCESS (10.023s)
[458.224652] Subtest blocking: SUCCESS (20.048s)
[458.265920] [044/155] (563s left) gem_exec_balancer (busy)
[458.339791] Starting subtest: busy
[459.681425] Subtest busy: SUCCESS (1.342s)
[460.012939] [045/155] (562s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-wc)
[460.120436] Starting subtest: psr-rgb101010-draw-mmap-wc
[467.188617] Subtest psr-rgb101010-draw-mmap-wc: SUCCESS (7.071s)
[467.289153] [046/155] (555s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-upscaling)
[467.401628] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-upscaling
[467.403951] Starting dynamic subtest: pipe-A-default-mode
[470.201980] Dynamic subtest pipe-A-default-mode: SUCCESS (2.798s)
[470.204245] Subtest flip-64bpp-4tile-to-16bpp-4tile-upscaling: SUCCESS (2.800s)
[471.048244] [047/155] (551s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-move)
[471.150571] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-move
[471.154966] Subtest psrhdr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[471.254648] [048/155] (551s left) kms_color (ctm-blue-to-red)
[471.343316] Starting subtest: ctm-blue-to-red
[471.345612] Starting dynamic subtest: pipe-A-eDP-1
[472.065580] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.722s)
[472.077159] Starting dynamic subtest: pipe-B-eDP-1
[473.415033] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.346s)
[473.426123] Starting dynamic subtest: pipe-C-eDP-1
[474.801960] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.388s)
[474.813654] Starting dynamic subtest: pipe-D-eDP-1
[476.134424] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.329s)
[476.136520] Subtest ctm-blue-to-red: SUCCESS (4.787s)
[476.963995] [049/155] (545s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[477.068225] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[477.072207] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[477.185653] [050/155] (545s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-render)
[477.287654] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-render
[477.291788] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[477.390220] [051/155] (545s left) gem_exec_store (cachelines)
[477.462926] Starting subtest: cachelines
[477.465136] Starting dynamic subtest: rcs0
[477.529256] Dynamic subtest rcs0: SUCCESS (0.067s)
[477.548910] Starting dynamic subtest: bcs0
[477.591822] Dynamic subtest bcs0: SUCCESS (0.061s)
[477.604264] Starting dynamic subtest: vcs0
[477.655008] Dynamic subtest vcs0: SUCCESS (0.061s)
[477.667580] Starting dynamic subtest: vcs1
[477.709522] Dynamic subtest vcs1: SUCCESS (0.053s)
[477.723335] Starting dynamic subtest: vecs0
[477.774784] Dynamic subtest vecs0: SUCCESS (0.064s)
[477.787155] Starting dynamic subtest: ccs0
[477.836654] Dynamic subtest ccs0: SUCCESS (0.060s)
[477.839117] Subtest cachelines: SUCCESS (0.377s)
[478.023099] [052/155] (544s left) syncobj_wait (wait-for-submit-complex)
[478.077819] Starting subtest: wait-for-submit-complex
[478.137998] Subtest wait-for-submit-complex: SUCCESS (0.061s)
[478.230566] [053/155] (544s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc)
[478.335887] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc
[478.339946] Subtest fbchdr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[478.452100] [054/155] (544s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[479.013427] Starting subtest: syncobj-timeline-invalid-wait
[479.020424] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.005s)
[480.725864] [055/155] (542s left) gem_exec_reloc (basic-range)
[480.788835] Subtest basic-range: SKIP (0.000s)
[480.898696] [056/155] (541s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[480.997321] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[481.001786] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[481.099862] [057/155] (541s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[481.194766] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[481.197000] Starting dynamic subtest: pipe-A
[481.216529] Dynamic subtest pipe-A: SKIP (0.017s)
[481.228180] Starting dynamic subtest: pipe-B
[481.245559] Dynamic subtest pipe-B: SKIP (0.030s)
[481.256310] Starting dynamic subtest: pipe-C
[481.275991] Dynamic subtest pipe-C: SKIP (0.030s)
[481.286455] Starting dynamic subtest: pipe-D
[481.307317] Dynamic subtest pipe-D: SKIP (0.030s)
[481.311598] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.110s)
[481.405126] [058/155] (541s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-fullscreen)
[481.515157] Starting subtest: psrhdr-1p-primscrn-spr-indfb-fullscreen
[481.519620] Subtest psrhdr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[481.615049] [059/155] (541s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[481.713881] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[481.717511] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.000s)
[481.784204] [060/155] (541s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-shrfb-plflip-blt)
[481.892684] Starting subtest: fbchdr-2p-primscrn-shrfb-plflip-blt
[481.896148] Subtest fbchdr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[481.980438] [061/155] (541s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[482.075570] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[482.079170] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[482.200156] [062/155] (540s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-spr-indfb-draw-blt)
[482.298884] Starting subtest: hdr-2p-scndscrn-spr-indfb-draw-blt
[482.302195] Subtest hdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[482.395101] [063/155] (540s left) kms_scaling_modes (scaling-mode-full)
[482.498101] Starting subtest: scaling-mode-full
[482.500030] Starting dynamic subtest: pipe-A-eDP-1
[482.596802] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.101s)
[482.610322] Starting dynamic subtest: pipe-B-eDP-1
[483.789595] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.191s)
[483.801776] Starting dynamic subtest: pipe-C-eDP-1
[484.996281] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.205s)
[485.009973] Starting dynamic subtest: pipe-D-eDP-1
[486.125841] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.128s)
[486.128271] Subtest scaling-mode-full: SUCCESS (3.629s)
[487.307014] [064/155] (535s left) syncobj_eventfd (binary-wait-signaled)
[487.356793] Starting subtest: binary-wait-signaled
[487.358924] Subtest binary-wait-signaled: SUCCESS (0.000s)
[487.459853] [065/155] (535s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-render)
[487.564212] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-render
[487.568422] Subtest psr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[487.675637] [066/155] (535s left) gen9_exec_parse (bb-start-cmd)
[487.750970] Subtest bb-start-cmd: SKIP (0.000s)
[487.810679] [067/155] (535s left) kms_addfb_basic (basic-x-tiled-legacy)
[487.866491] Starting subtest: basic-x-tiled-legacy
[487.870728] Subtest basic-x-tiled-legacy: SKIP (0.000s)
[488.013311] [068/155] (535s left) kms_cursor_legacy (cursora-vs-flipa-varying-size)
[488.146745] Starting subtest: cursorA-vs-flipA-varying-size
[499.307605] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.160s)
[500.105848] [069/155] (523s left) syncobj_timeline (etime-single-wait-submitted)
[500.170341] Starting subtest: etime-single-wait-submitted
[500.279900] Subtest etime-single-wait-submitted: SUCCESS (0.109s)
[500.358675] [070/155] (523s left) kms_cursor_edge_walk (256x256-top-edge)
[500.447404] Starting subtest: 256x256-top-edge
[500.449642] Starting dynamic subtest: pipe-A-eDP-1
[503.892719] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.445s)
[503.904229] Starting dynamic subtest: pipe-D-eDP-1
[507.111480] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.219s)
[507.113596] Subtest 256x256-top-edge: SUCCESS (6.667s)
[507.919857] [071/155] (515s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[508.009801] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[508.014009] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[508.105881] [072/155] (515s left) kms_big_fb (yf-tiled-8bpp-rotate-270)
[508.200430] Starting subtest: yf-tiled-8bpp-rotate-270
[508.204954] Subtest yf-tiled-8bpp-rotate-270: SKIP (0.000s)
[508.297119] [073/155] (515s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render)
[508.394243] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render
[508.398516] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[508.500831] [074/155] (515s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-render)
[508.606454] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-render
[508.610901] Subtest hdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[508.714204] [075/155] (514s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-sf)
[508.812663] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-sf
[508.815121] Starting dynamic subtest: pipe-A-eDP-1
[508.920925] Dynamic subtest pipe-A-eDP-1: SKIP (0.111s)
[508.932503] Starting dynamic subtest: pipe-B-eDP-1
[510.109830] Dynamic subtest pipe-B-eDP-1: SKIP (1.183s)
[510.114573] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-sf: SKIP (1.299s)
[511.395765] [076/155] (512s left) api_intel_bb (blit-noreloc-keep-cache)
[511.443393] Starting subtest: blit-noreloc-keep-cache
[511.453163] Subtest blit-noreloc-keep-cache: SUCCESS (0.011s)
[511.570364] [077/155] (512s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff)
[511.665024] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff
[511.669399] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[511.757777] [078/155] (511s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt)
[511.847947] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt
[511.852294] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[511.942036] [079/155] (511s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[512.052409] Starting subtest: x-tiled-32bpp-rotate-0
[513.463848] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.412s)
[513.597654] [080/155] (510s left) kms_flip (nonexisting-fb)
[513.687608] Starting subtest: nonexisting-fb
[513.689576] Starting dynamic subtest: A-eDP1
[514.885930] Dynamic subtest A-eDP1: SUCCESS (1.198s)
[514.897546] Starting dynamic subtest: B-eDP1
[516.027581] Dynamic subtest B-eDP1: SUCCESS (1.141s)
[516.039545] Starting dynamic subtest: C-eDP1
[517.161812] Dynamic subtest C-eDP1: SUCCESS (1.133s)
[517.172469] Starting dynamic subtest: D-eDP1
[518.304990] Dynamic subtest D-eDP1: SUCCESS (1.141s)
[518.307404] Subtest nonexisting-fb: SUCCESS (4.622s)
[519.488273] [081/155] (504s left) gem_exec_parallel (basic)
[519.543107] Starting subtest: basic
[519.545761] Starting dynamic subtest: rcs0
[520.978489] Dynamic subtest rcs0: SUCCESS (1.435s)
[520.992080] Starting dynamic subtest: bcs0
[522.468656] Dynamic subtest bcs0: SUCCESS (1.488s)
[522.480072] Starting dynamic subtest: vcs0
[524.635969] Dynamic subtest vcs0: SUCCESS (2.163s)
[524.649623] Starting dynamic subtest: vcs1
[526.682767] Dynamic subtest vcs1: SUCCESS (2.044s)
[526.695397] Starting dynamic subtest: vecs0
[528.666798] Dynamic subtest vecs0: SUCCESS (1.981s)
[528.681084] Starting dynamic subtest: ccs0
[530.148721] Dynamic subtest ccs0: SUCCESS (1.480s)
[530.151031] Subtest basic: SUCCESS (10.607s)
[530.341020] [082/155] (493s left) i915_drm_fdinfo (virtual-idle)
[530.387606] Starting subtest: virtual-idle
[533.449605] Subtest virtual-idle: SUCCESS (3.061s)
[533.543772] [083/155] (490s left) kms_lease (multimaster-lease)
[533.651262] Starting subtest: multimaster-lease
[533.653086] Subtest multimaster-lease: SUCCESS (0.003s)
[533.781735] [084/155] (490s left) gem_ccs (block-copy-uncompressed)
[533.833655] Starting subtest: block-copy-uncompressed
[533.836107] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem
[533.858147] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.024s)
[533.871183] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem
[533.877922] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s)
[533.888201] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem
[533.895420] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s)
[533.905632] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem
[533.912964] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s)
[533.915400] Subtest block-copy-uncompressed: SUCCESS (0.080s)
[534.047824] [085/155] (489s left) kms_psr (pr-primary-render)
[534.142743] Starting subtest: pr-primary-render
[534.146824] Subtest pr-primary-render: SKIP (0.000s)
[534.227027] [086/155] (489s left) core_getversion (all-cards)
[534.268605] Starting subtest: all-cards
[534.279178] Subtest all-cards: SUCCESS (0.013s)
[534.332066] [087/155] (489s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-wc)
[534.428004] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-wc
[540.975833] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (6.553s)
[541.083991] [088/155] (483s left) gem_media_fill (media-fill)
[541.145527] Subtest media-fill: SKIP (0.000s)
[541.288014] [089/155] (482s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[541.393516] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[541.395661] Starting dynamic subtest: pipe-A
[541.415864] Dynamic subtest pipe-A: SKIP (0.019s)
[541.427278] Starting dynamic subtest: pipe-B
[541.448941] Dynamic subtest pipe-B: SKIP (0.032s)
[541.461902] Starting dynamic subtest: pipe-C
[541.483185] Dynamic subtest pipe-C: SKIP (0.032s)
[541.494279] Starting dynamic subtest: pipe-D
[541.514338] Dynamic subtest pipe-D: SKIP (0.032s)
[541.518789] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.117s)
[541.611232] [090/155] (482s left) i915_query (hwconfig_table)
[541.667410] Starting subtest: hwconfig_table
[541.958626] Subtest hwconfig_table: SUCCESS (0.001s)
[541.999757] [091/155] (482s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[542.095237] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[542.098971] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[542.170810] [092/155] (482s left) kms_big_fb (y-tiled-addfb-size-offset-overflow)
[542.276829] Starting subtest: y-tiled-addfb-size-offset-overflow
[542.280603] Subtest y-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[542.382258] [093/155] (482s left) gem_exec_reloc (basic-write-read)
[542.444273] Subtest basic-write-read: SKIP (0.000s)
[542.514986] [094/155] (481s left) kms_cursor_crc (cursor-offscreen-256x256)
[542.743219] Starting subtest: cursor-offscreen-256x256
[542.747413] Starting dynamic subtest: pipe-A-eDP-1
[544.688913] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.939s)
[544.717533] Starting dynamic subtest: pipe-D-eDP-1
[547.338402] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.626s)
[547.340672] Subtest cursor-offscreen-256x256: SUCCESS (4.592s)
[548.202043] [095/155] (476s left) gem_partial_pwrite_pread (write)
[548.268726] Subtest write: SKIP (0.000s)
[548.351308] [096/155] (476s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-pwrite)
[548.451989] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-pwrite
[548.456012] Subtest hdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[548.572229] [097/155] (476s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-ccs)
[548.637007] Starting subtest: y-tiled-ccs-to-yf-tiled-ccs
[548.641589] Subtest y-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[548.823362] [098/155] (475s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[548.928125] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[548.932204] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[549.034979] [099/155] (475s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt)
[549.140197] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt
[549.144297] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[549.246308] [100/155] (475s left) kms_frontbuffer_tracking (fbcpsrhdr-slowdraw)
[549.341091] Starting subtest: fbcpsrhdr-slowdraw
[549.345430] Subtest fbcpsrhdr-slowdraw: SKIP (0.000s)
[549.436696] [101/155] (475s left) prime_busy (after-wait)
[549.503969] Starting subtest: after-wait
[549.508740] Starting dynamic subtest: rcs0
[549.537097] Dynamic subtest rcs0: SUCCESS (0.030s)
[549.548380] Starting dynamic subtest: bcs0
[549.577552] Dynamic subtest bcs0: SUCCESS (0.040s)
[549.587685] Starting dynamic subtest: vcs0
[549.617214] Dynamic subtest vcs0: SUCCESS (0.039s)
[549.627899] Starting dynamic subtest: vcs1
[549.657419] Dynamic subtest vcs1: SUCCESS (0.039s)
[549.667526] Starting dynamic subtest: vecs0
[549.696265] Dynamic subtest vecs0: SUCCESS (0.038s)
[549.706396] Starting dynamic subtest: ccs0
[549.735287] Dynamic subtest ccs0: SUCCESS (0.038s)
[549.841603] Subtest after-wait: SUCCESS (0.336s)
[549.924796] [102/155] (474s left) drm_read (fault-buffer)
[550.077982] Starting subtest: fault-buffer
[550.080386] Subtest fault-buffer: SUCCESS (0.001s)
[550.204781] [103/155] (474s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt)
[550.297252] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt
[557.765864] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.474s)
[557.916171] [104/155] (466s left) kms_flip (basic-flip-vs-modeset)
[558.004510] Starting subtest: basic-flip-vs-modeset
[558.006681] Starting dynamic subtest: A-eDP1
[560.615272] Dynamic subtest A-eDP1: SUCCESS (2.608s)
[560.627131] Starting dynamic subtest: B-eDP1
[562.800980] Dynamic subtest B-eDP1: SUCCESS (2.182s)
[562.812276] Starting dynamic subtest: C-eDP1
[564.970729] Dynamic subtest C-eDP1: SUCCESS (2.170s)
[564.982546] Starting dynamic subtest: D-eDP1
[567.143726] Dynamic subtest D-eDP1: SUCCESS (2.172s)
[567.146280] Subtest basic-flip-vs-modeset: SUCCESS (9.141s)
[567.950416] [105/155] (456s left) syncobj_timeline (invalid-query-zero-handles)
[568.003468] Starting subtest: invalid-query-zero-handles
[568.005661] Subtest invalid-query-zero-handles: SUCCESS (0.000s)
[568.105424] [106/155] (456s left) kms_lease (atomic-implicit-crtc)
[568.198204] Starting subtest: atomic-implicit-crtc
[568.200453] Starting dynamic subtest: pipe-A-eDP-1
[568.211420] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[568.222227] Starting dynamic subtest: pipe-B-eDP-1
[568.234509] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.010s)
[568.246756] Starting dynamic subtest: pipe-C-eDP-1
[568.257082] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s)
[568.267506] Starting dynamic subtest: pipe-D-eDP-1
[568.277946] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.010s)
[568.279831] Subtest atomic-implicit-crtc: SUCCESS (0.036s)
[568.355778] [107/155] (456s left) sw_sync (sync_busy_fork)
[568.392423] Starting subtest: sync_busy_fork
[569.404297] Subtest sync_busy_fork: SUCCESS (1.011s)
[569.469224] [108/155] (455s left) gem_render_copy_redux (flink)
[569.539878] Starting subtest: flink
[570.288625] Subtest flink: SUCCESS (0.748s)
[570.421962] [109/155] (454s left) gem_exec_schedule (preempt-queue-contexts-chain)
[570.495883] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[570.786900] [110/155] (454s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[570.903906] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[570.908171] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[571.006756] [111/155] (454s left) perf_pmu (busy-accuracy-98)
[571.047153] Starting subtest: busy-accuracy-98
[571.050078] Starting dynamic subtest: rcs0
[574.084208] Dynamic subtest rcs0: SUCCESS (3.034s)
[574.095912] Starting dynamic subtest: bcs0
[577.185895] Dynamic subtest bcs0: SUCCESS (3.048s)
[577.198224] Starting dynamic subtest: vcs0
[580.231472] Dynamic subtest vcs0: SUCCESS (3.041s)
[580.242684] Starting dynamic subtest: vcs1
[583.273556] Dynamic subtest vcs1: SUCCESS (3.038s)
[583.285965] Starting dynamic subtest: vecs0
[586.317258] Dynamic subtest vecs0: SUCCESS (3.040s)
[586.328136] Starting dynamic subtest: ccs0
[589.365980] Dynamic subtest ccs0: SUCCESS (3.049s)
[589.368032] Subtest busy-accuracy-98: SUCCESS (18.266s)
[589.434022] [112/155] (435s left) kms_frontbuffer_tracking (psrhdr-indfb-scaledprimary)
[589.548690] Starting subtest: psrhdr-indfb-scaledprimary
[589.552823] Subtest psrhdr-indfb-scaledprimary: SKIP (0.000s)
[589.645056] [113/155] (435s left) i915_pm_rpm (gem-idle)
[591.771382] Starting subtest: gem-idle
[597.980026] Subtest gem-idle: SUCCESS (6.192s)
[598.318399] [114/155] (426s left) kms_cursor_legacy (flip-vs-cursor-varying-size)
[599.681740] Starting subtest: flip-vs-cursor-varying-size
[608.877290] Subtest flip-vs-cursor-varying-size: SUCCESS (9.196s)
[609.749946] [115/155] (415s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping)
[609.835646] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping
[609.861221] Starting dynamic subtest: pipe-A-plane-0
[610.149806] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[610.160637] Starting dynamic subtest: pipe-A-plane-3
[610.383030] Dynamic subtest pipe-A-plane-3: SUCCESS (0.232s)
[611.542044] Starting dynamic subtest: pipe-B-plane-0
[611.832617] Dynamic subtest pipe-B-plane-0: SUCCESS (0.300s)
[611.846335] Starting dynamic subtest: pipe-B-plane-3
[612.065980] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[612.395348] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier-source-clamping: SUCCESS (2.559s)
[613.230430] [116/155] (412s left) gem_ctx_persistence (legacy-engines-cleanup)
[613.284056] Starting subtest: legacy-engines-cleanup
[613.286404] Starting dynamic subtest: render
[613.370219] Dynamic subtest render: SUCCESS (0.085s)
[613.383678] Starting dynamic subtest: bsd1
[613.407768] Dynamic subtest bsd1: SUCCESS (0.035s)
[613.419809] Starting dynamic subtest: bsd2
[613.443627] Dynamic subtest bsd2: SUCCESS (0.035s)
[613.455705] Starting dynamic subtest: blt
[613.500145] Dynamic subtest blt: SUCCESS (0.055s)
[613.518083] Starting dynamic subtest: vebox
[613.540509] Dynamic subtest vebox: SUCCESS (0.037s)
[613.542933] Subtest legacy-engines-cleanup: SUCCESS (0.257s)
[613.621087] [117/155] (411s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[613.714552] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[613.719012] Subtest hdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[613.813893] [118/155] (411s left) kms_flip (flip-vs-dpms-on-nop)
[613.918837] Starting subtest: flip-vs-dpms-on-nop
[613.921277] Starting dynamic subtest: A-eDP1
[615.815490] Dynamic subtest A-eDP1: SUCCESS (1.895s)
[615.830071] Starting dynamic subtest: B-eDP1
[617.654487] Dynamic subtest B-eDP1: SUCCESS (1.837s)
[617.665771] Starting dynamic subtest: C-eDP1
[619.482701] Dynamic subtest C-eDP1: SUCCESS (1.827s)
[619.493746] Starting dynamic subtest: D-eDP1
[621.323914] Dynamic subtest D-eDP1: SUCCESS (1.839s)
[621.328980] Subtest flip-vs-dpms-on-nop: SUCCESS (7.409s)
[622.508487] [119/155] (403s left) gem_exec_reloc (basic-cpu-read-noreloc)
[622.571431] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[622.649577] [120/155] (402s left) i915_drm_fdinfo (memory-info-idle)
[622.689441] Starting subtest: memory-info-idle
[622.691805] Starting dynamic subtest: smem0
[622.748304] Dynamic subtest smem0: SUCCESS (0.058s)
[622.751118] Subtest memory-info-idle: SUCCESS (0.059s)
[622.835952] [121/155] (402s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[622.947461] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt
[622.951925] Subtest psrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[623.031030] [122/155] (402s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs-cc)
[623.117590] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs-cc
[623.119898] Starting dynamic subtest: pipe-A-eDP-1
[623.143450] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[623.156620] Starting dynamic subtest: pipe-B-eDP-1
[623.178620] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[623.191676] Starting dynamic subtest: pipe-C-eDP-1
[623.212845] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[623.222841] Starting dynamic subtest: pipe-D-eDP-1
[623.245095] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[623.249570] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.006s)
[625.389718] [123/155] (400s left) kms_dirtyfb (drrs-dirtyfb-ioctl)
[625.491278] Starting subtest: drrs-dirtyfb-ioctl
[625.504836] Subtest drrs-dirtyfb-ioctl: SKIP (0.001s)
[625.617761] [124/155] (400s left) kms_busy (extended-modeset-hang-newfb)
[625.720570] Starting subtest: extended-modeset-hang-newfb
[625.722899] Starting dynamic subtest: pipe-A
[660.583468] Dynamic subtest pipe-A: SUCCESS (34.868s)
[660.594699] Starting dynamic subtest: pipe-D
[683.555344] Dynamic subtest pipe-D: SUCCESS (22.969s)
[683.557899] Subtest extended-modeset-hang-newfb: SUCCESS (57.842s)
[684.450710] [125/155] (341s left) gem_create (create-ext-cpu-access-sanity-check)
[684.623999] Starting subtest: create-ext-cpu-access-sanity-check
[684.628555] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[684.702516] [126/155] (341s left) gem_ctx_param (non-root-set)
[684.747128] Starting subtest: non-root-set
[684.763055] Subtest non-root-set: SUCCESS (0.016s)
[684.859633] [127/155] (341s left) kms_vrr (flip-dpms)
[684.954484] Starting subtest: flip-dpms
[684.960775] Subtest flip-dpms: SKIP (0.000s)
[685.045127] [128/155] (340s left) kms_frontbuffer_tracking (hdr-suspend)
[685.149410] Starting subtest: hdr-suspend
[685.153775] Subtest hdr-suspend: SKIP (0.000s)
[685.236279] [129/155] (340s left) syncobj_wait (invalid-wait-illegal-handle)
[685.301084] Starting subtest: invalid-wait-illegal-handle
[685.303749] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[685.405565] [130/155] (340s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs)
[685.476398] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs
[685.480552] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[685.672674] [131/155] (340s left) gem_lmem_swapping (basic)
[685.718422] Subtest basic: SKIP (0.000s)
[685.767879] [132/155] (340s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[685.873250] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[689.541293] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.673s)
[690.750744] [133/155] (335s left) prime_vgem (basic-fence-read)
[690.844515] Starting subtest: basic-fence-read
[690.870657] Subtest basic-fence-read: SKIP (0.024s)
[690.982918] [134/155] (335s left) kms_fbcon_fbt (fbc-suspend)
[691.048058] Starting subtest: fbc-suspend
[740.249547] Subtest fbc-suspend: SUCCESS (18.751s)
[741.633387] [135/155] (284s left) perf (disabled-read-error)
[741.731162] Starting subtest: disabled-read-error
[741.745249] Subtest disabled-read-error: SUCCESS (0.012s)
[741.791686] [136/155] (284s left) kms_psr (psr2-cursor-render)
[741.897271] Starting subtest: psr2-cursor-render
[741.899544] Starting dynamic subtest: eDP-1
[743.622469] Dynamic subtest eDP-1: SUCCESS (1.713s)
[743.624811] Subtest psr2-cursor-render: SUCCESS (1.714s)
[744.509111] [137/155] (281s left) kms_pipe_b_c_ivb (from-pipe-c-to-b-with-3-lanes)
[744.566415] Subtest from-pipe-C-to-B-with-3-lanes: SKIP (0.000s)
[744.636616] [138/155] (281s left) sysfs_heartbeat_interval (mixed)
[744.688796] Starting subtest: mixed
[744.691146] Starting dynamic subtest: vecs0
[749.905383] Dynamic subtest vecs0: SUCCESS (5.193s)
[749.920836] Starting dynamic subtest: ccs0
[755.094124] Dynamic subtest ccs0: SUCCESS (5.174s)
[755.107211] Starting dynamic subtest: vcs0
[760.291835] Dynamic subtest vcs0: SUCCESS (5.188s)
[760.305213] Starting dynamic subtest: bcs0
[765.472796] Dynamic subtest bcs0: SUCCESS (5.173s)
[765.484388] Starting dynamic subtest: rcs0
[770.683261] Dynamic subtest rcs0: SUCCESS (5.208s)
[770.698630] Starting dynamic subtest: vcs1
[775.881973] Dynamic subtest vcs1: SUCCESS (5.199s)
[775.884608] Subtest mixed: SUCCESS (31.147s)
[775.975163] [139/155] (250s left) gem_mmap_wc (write-cpu-read-wc-unflushed)
[776.037271] Subtest write-cpu-read-wc-unflushed: SKIP (0.000s)
[776.109638] [140/155] (250s left) gem_userptr_blits (create-destroy-unsync)
[776.171120] Subtest create-destroy-unsync: SKIP (0.000s)
[776.306635] [141/155] (250s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[776.415375] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[776.419782] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[776.534819] [142/155] (250s left) syncobj_wait (invalid-signal-illegal-handle)
[776.583620] Starting subtest: invalid-signal-illegal-handle
[776.585838] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[776.677060] [143/155] (249s left) syncobj_basic (bad-pad-fd-to-handle)
[776.724164] Starting subtest: bad-pad-fd-to-handle
[776.726307] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[776.823377] [144/155] (249s left) kms_pm_rpm (modeset-pc8-residency-stress)
[779.064906] Starting subtest: modeset-pc8-residency-stress
[779.069933] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[779.455223] [145/155] (247s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[779.564440] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[779.568881] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[779.685710] [146/155] (247s left) gem_readwrite (read-write)
[779.748520] Subtest read-write: SKIP (0.000s)
[779.818510] [147/155] (246s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite)
[779.913895] Starting subtest: fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite
[779.918008] Subtest fbcpsrhdr-1p-offscreen-pri-shrfb-draw-pwrite: SKIP (0.000s)
[780.039446] [148/155] (246s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs-cc)
[780.124611] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs-cc
[780.126955] Starting dynamic subtest: pipe-A-eDP-1
[780.151240] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[780.164822] Starting dynamic subtest: pipe-B-eDP-1
[780.186537] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[780.197088] Starting dynamic subtest: pipe-C-eDP-1
[780.218652] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[780.231245] Starting dynamic subtest: pipe-D-eDP-1
[780.253049] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[780.257427] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[782.400162] [149/155] (244s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[782.505064] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[782.507429] Starting dynamic subtest: pipe-A-eDP-1
[798.978258] Dynamic subtest pipe-A-eDP-1: SUCCESS (16.475s)
[798.990229] Starting dynamic subtest: pipe-B-eDP-1
[816.163363] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.177s)
[816.165246] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (33.655s)
[816.979019] [150/155] (209s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-signaled)
[817.046422] Starting subtest: multi-wait-for-submit-available-unsubmitted-signaled
[817.048265] Subtest multi-wait-for-submit-available-unsubmitted-signaled: SUCCESS (0.001s)
[817.147829] [151/155] (209s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[817.238438] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[826.179745] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.942s)
[826.300804] [152/155] (200s left) gem_exec_create (legacy)
[826.361824] Starting subtest: legacy
[826.363999] Starting dynamic subtest: smem
[829.897492] Dynamic subtest smem: SUCCESS (3.532s)
[829.900037] Subtest legacy: SUCCESS (3.534s)
[830.087951] [153/155] (196s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[830.188720] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-hflip
[831.191307] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.004s)
[831.338776] [154/155] (195s left) gem_exec_schedule (submit-late-slice)
[831.404427] Starting subtest: submit-late-slice
[831.406739] Starting dynamic subtest: rcs0
[831.429445] Dynamic subtest rcs0: SUCCESS (0.034s)
[831.445463] Starting dynamic subtest: bcs0
[831.458767] Dynamic subtest bcs0: SUCCESS (0.029s)
[831.470220] Starting dynamic subtest: vcs0
[831.488450] Dynamic subtest vcs0: SUCCESS (0.029s)
[831.500882] Starting dynamic subtest: vcs1
[831.518649] Dynamic subtest vcs1: SUCCESS (0.029s)
[831.533116] Starting dynamic subtest: vecs0
[831.552366] Dynamic subtest vecs0: SUCCESS (0.032s)
[831.563929] Starting dynamic subtest: ccs0
[831.584398] Dynamic subtest ccs0: SUCCESS (0.031s)
[831.586359] Subtest submit-late-slice: SUCCESS (0.191s)
[832.986878] [155/155] (194s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[833.077004] Starting subtest: fbc-pr-sprite-mmap-cpu
[833.081265] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[833.149383] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_big_fb
results: parsing output: 2/ for test: kms_psr2_su
results: parsing output: 3/ for test: kms_pm_rpm
results: parsing output: 4/ for test: kms_cursor_crc
results: parsing output: 5/ for test: syncobj_timeline
results: parsing output: 6/ for test: syncobj_basic
results: parsing output: 7/ for test: gem_mmap_offset
results: parsing output: 8/ for test: kms_pipe_crc_basic
results: parsing output: 9/ for test: perf
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: dmabuf_sync_file
results: parsing output: 12/ for test: kms_chamelium_hpd
results: parsing output: 13/ for test: gem_render_linear_blits
results: parsing output: 14/ for test: kms_chamelium_frames
results: parsing output: 15/ for test: gem_softpin
results: parsing output: 16/ for test: kms_vblank
results: parsing output: 17/ for test: gem_pxp
results: parsing output: 18/ for test: kms_feature_discovery
results: parsing output: 19/ for test: gem_ppgtt
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: gem_render_copy
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_addfb_basic
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: kms_ccs
results: parsing output: 26/ for test: gem_userptr_blits
results: parsing output: 27/ for test: perf_pmu
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_hdr
results: parsing output: 31/ for test: kms_chamelium_color
results: parsing output: 32/ for test: gem_ctx_persistence
results: parsing output: 33/ for test: perf_pmu
results: parsing output: 34/ for test: gem_softpin
results: parsing output: 35/ for test: gem_exec_params
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_exec_reloc
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: kms_content_protection
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_atomic_transition
results: parsing output: 42/ for test: perf
results: parsing output: 43/ for test: gem_exec_balancer
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_flip_scaled_crc
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_color
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: gem_exec_store
results: parsing output: 51/ for test: syncobj_wait
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: gem_exec_fence
results: parsing output: 54/ for test: gem_exec_reloc
results: parsing output: 55/ for test: kms_frontbuffer_tracking
results: parsing output: 56/ for test: kms_plane_scaling
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: kms_flip_scaled_crc
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: kms_scaling_modes
results: parsing output: 63/ for test: syncobj_eventfd
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: gen9_exec_parse
results: parsing output: 66/ for test: kms_addfb_basic
results: parsing output: 67/ for test: kms_cursor_legacy
results: parsing output: 68/ for test: syncobj_timeline
results: parsing output: 69/ for test: kms_cursor_edge_walk
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_big_fb
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_psr2_sf
results: parsing output: 75/ for test: api_intel_bb
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_flip
results: parsing output: 80/ for test: gem_exec_parallel
results: parsing output: 81/ for test: i915_drm_fdinfo
results: parsing output: 82/ for test: kms_lease
results: parsing output: 83/ for test: gem_ccs
results: parsing output: 84/ for test: kms_psr
results: parsing output: 85/ for test: core_getversion
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: gem_media_fill
results: parsing output: 88/ for test: kms_plane_scaling
results: parsing output: 89/ for test: i915_query
results: parsing output: 90/ for test: kms_plane
results: parsing output: 91/ for test: kms_big_fb
results: parsing output: 92/ for test: gem_exec_reloc
results: parsing output: 93/ for test: kms_cursor_crc
results: parsing output: 94/ for test: gem_partial_pwrite_pread
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_render_copy
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: prime_busy
results: parsing output: 101/ for test: drm_read
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_flip
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: kms_lease
results: parsing output: 106/ for test: sw_sync
results: parsing output: 107/ for test: gem_render_copy_redux
results: parsing output: 108/ for test: gem_exec_schedule
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: perf_pmu
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: i915_pm_rpm
results: parsing output: 113/ for test: kms_cursor_legacy
results: parsing output: 114/ for test: kms_plane
results: parsing output: 115/ for test: gem_ctx_persistence
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_flip
results: parsing output: 118/ for test: gem_exec_reloc
results: parsing output: 119/ for test: i915_drm_fdinfo
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_ccs
results: parsing output: 122/ for test: kms_dirtyfb
results: parsing output: 123/ for test: kms_busy
results: parsing output: 124/ for test: gem_create
results: parsing output: 125/ for test: gem_ctx_param
results: parsing output: 126/ for test: kms_vrr
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: syncobj_wait
results: parsing output: 129/ for test: gem_render_copy
results: parsing output: 130/ for test: gem_lmem_swapping
results: parsing output: 131/ for test: kms_frontbuffer_tracking
results: parsing output: 132/ for test: prime_vgem
results: parsing output: 133/ for test: kms_fbcon_fbt
results: parsing output: 134/ for test: perf
results: parsing output: 135/ for test: kms_psr
results: parsing output: 136/ for test: kms_pipe_b_c_ivb
results: parsing output: 137/ for test: sysfs_heartbeat_interval
results: parsing output: 138/ for test: gem_mmap_wc
results: parsing output: 139/ for test: gem_userptr_blits
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: syncobj_wait
results: parsing output: 142/ for test: syncobj_basic
results: parsing output: 143/ for test: kms_pm_rpm
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: gem_readwrite
results: parsing output: 146/ for test: kms_frontbuffer_tracking
results: parsing output: 147/ for test: kms_ccs
results: parsing output: 148/ for test: kms_psr2_sf
results: parsing output: 149/ for test: syncobj_timeline
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: gem_exec_create
results: parsing output: 152/ for test: kms_big_fb
results: parsing output: 153/ for test: gem_exec_schedule
results: parsing output: 154/ for test: kms_psr
Done.