[61.524329] Initializing watchdogs
[61.524375]   /dev/watchdog0
[61.538054] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[61.542178] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.544321] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.547715] [001/132] (960s left) gem_exec_store (cachelines)
[61.635269] Starting subtest: cachelines
[61.637611] Starting dynamic subtest: rcs0
[61.732068] Dynamic subtest rcs0: SUCCESS (0.098s)
[61.741833] Starting dynamic subtest: bcs0
[61.795598] Dynamic subtest bcs0: SUCCESS (0.063s)
[61.804489] Starting dynamic subtest: vcs0
[61.862046] Dynamic subtest vcs0: SUCCESS (0.066s)
[61.868948] Starting dynamic subtest: vcs1
[61.917048] Dynamic subtest vcs1: SUCCESS (0.054s)
[61.924574] Starting dynamic subtest: vecs0
[61.980509] Dynamic subtest vecs0: SUCCESS (0.064s)
[61.990043] Starting dynamic subtest: ccs0
[62.039024] Dynamic subtest ccs0: SUCCESS (0.058s)
[62.041429] Subtest cachelines: SUCCESS (0.408s)
[62.264106] [002/132] (959s left) gem_pread (bench)
[62.323540] Subtest bench: SKIP (0.000s)
[62.404499] [003/132] (959s left) syncobj_wait (invalid-signal-illegal-handle)
[62.473460] Starting subtest: invalid-signal-illegal-handle
[62.475730] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[62.597939] [004/132] (959s left) kms_vblank (query-forked-busy-hang)
[62.698858] Starting subtest: query-forked-busy-hang
[62.701219] Starting dynamic subtest: pipe-A-eDP-1
[89.685495] Dynamic subtest pipe-A-eDP-1: SUCCESS (27.017s)
[89.699087] Starting dynamic subtest: pipe-D-eDP-1
[104.656848] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.964s)
[104.658963] Subtest query-forked-busy-hang: SUCCESS (41.985s)
[105.549990] [005/132] (916s left) kms_psr (fbc-pr-primary-mmap-cpu)
[105.667438] Starting subtest: fbc-pr-primary-mmap-cpu
[105.671816] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[105.760883] [006/132] (916s left) kms_plane_cursor (overlay)
[105.857482] Starting subtest: overlay
[105.861846] Starting dynamic subtest: pipe-A-eDP-1-size-64
[108.340047] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.482s)
[108.400848] Starting dynamic subtest: pipe-A-eDP-1-size-128
[110.850651] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.460s)
[110.911134] Starting dynamic subtest: pipe-A-eDP-1-size-256
[113.318329] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.415s)
[113.666967] Starting dynamic subtest: pipe-B-eDP-1-size-64
[116.832475] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.173s)
[116.890831] Starting dynamic subtest: pipe-B-eDP-1-size-128
[119.422782] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.539s)
[119.481652] Starting dynamic subtest: pipe-B-eDP-1-size-256
[121.979804] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.504s)
[122.331269] Starting dynamic subtest: pipe-C-eDP-1-size-64
[125.257454] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.939s)
[125.294317] Starting dynamic subtest: pipe-C-eDP-1-size-128
[127.356494] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.069s)
[127.392861] Starting dynamic subtest: pipe-C-eDP-1-size-256
[129.445764] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.058s)
[129.771568] Starting dynamic subtest: pipe-D-eDP-1-size-64
[132.535798] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.773s)
[132.573470] Starting dynamic subtest: pipe-D-eDP-1-size-128
[134.626746] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.057s)
[134.663986] Starting dynamic subtest: pipe-D-eDP-1-size-256
[136.802840] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.147s)
[137.127332] Subtest overlay: SUCCESS (31.265s)
[137.965508] [007/132] (884s left) perf (i915-ref-count)
[138.057703] Starting subtest: i915-ref-count
[138.119490] Subtest i915-ref-count: SUCCESS (0.059s)
[138.171141] [008/132] (884s left) gem_exec_schedule (smoketest-all)
[138.245873] Starting subtest: smoketest-all
[170.629681] Subtest smoketest-all: SUCCESS (32.339s)
[171.997591] [009/132] (850s left) gem_lmem_swapping (heavy-random)
[172.042695] Subtest heavy-random: SKIP (0.000s)
[172.084315] [010/132] (850s left) kms_psr (psr-primary-mmap-gtt)
[172.178075] Starting subtest: psr-primary-mmap-gtt
[172.180273] Starting dynamic subtest: eDP-1
[173.636259] Dynamic subtest eDP-1: SKIP (1.455s)
[173.640829] Subtest psr-primary-mmap-gtt: SKIP (1.457s)
[173.836468] [011/132] (848s left) perf (whitelisted-registers-userspace-config)
[173.933583] Starting subtest: whitelisted-registers-userspace-config
[173.935944] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[173.977084] [012/132] (848s left) gem_ppgtt (blt-vs-render-ctx0)
[174.044419] Starting subtest: blt-vs-render-ctx0
[207.214349] Subtest blt-vs-render-ctx0: SUCCESS (33.166s)
[207.383368] [013/132] (815s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[207.527812] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[207.531713] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[207.654038] [014/132] (814s left) gem_mmap_gtt (basic)
[207.703623] Subtest basic: SKIP (0.000s)
[207.765957] [015/132] (814s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[207.826715] Starting subtest: invalid-single-wait-all-unsubmitted
[207.829049] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[207.920713] [016/132] (814s left) gem_ctx_engines (execute-one)
[207.989809] Starting subtest: execute-one
[210.093518] Subtest execute-one: SUCCESS (2.107s)
[210.301741] [017/132] (812s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[210.420537] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[210.424663] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[210.564191] [018/132] (812s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[210.667493] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[210.669716] Starting dynamic subtest: pipe-A-eDP-1
[210.707621] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[210.715809] Starting dynamic subtest: pipe-B-eDP-1
[210.734561] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[210.741763] Starting dynamic subtest: pipe-C-eDP-1
[210.761863] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[210.770192] Starting dynamic subtest: pipe-D-eDP-1
[210.789416] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[210.791302] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.008s)
[212.924241] [019/132] (809s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[212.984641] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[213.047652] [020/132] (809s left) api_intel_allocator (two-level-inception-interruptible)
[213.103629] Starting subtest: two-level-inception-interruptible
[218.505806] Subtest two-level-inception-interruptible: SUCCESS (5.404s)
[218.666441] [021/132] (804s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[223.656139] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[223.658586] Starting dynamic subtest: pipe-A-eDP-1
[230.315676] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.657s)
[230.323710] Starting dynamic subtest: pipe-B-eDP-1
[236.974166] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.657s)
[236.976635] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.318s)
[237.857989] [022/132] (785s left) i915_pciid
[238.022475] [023/132] (784s left) i915_pm_rpm (sysfs-read)
[240.076045] Starting subtest: sysfs-read
[241.243727] Subtest sysfs-read: SUCCESS (1.166s)
[241.631469] [024/132] (781s left) gem_exec_reloc (basic-cpu-noreloc)
[241.707838] Subtest basic-cpu-noreloc: SKIP (0.000s)
[241.782471] [025/132] (781s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[241.880334] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[241.884717] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[241.998292] [026/132] (781s left) sw_sync (timeline_closed_signaled)
[242.033997] Starting subtest: timeline_closed_signaled
[242.036306] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[242.104858] [027/132] (780s left) gem_eio (context-create)
[242.364645] Starting subtest: context-create
[242.718858] Subtest context-create: SUCCESS (0.354s)
[242.893157] [028/132] (780s left) kms_fb_coherency (memset-crc)
[242.991108] Starting subtest: memset-crc
[242.992902] Starting dynamic subtest: mmap-offset-wc
[243.379480] Dynamic subtest mmap-offset-wc: SUCCESS (0.388s)
[243.682765] Starting dynamic subtest: mmap-offset-uc
[249.752455] Dynamic subtest mmap-offset-uc: SUCCESS (6.079s)
[250.039595] Subtest memset-crc: SUCCESS (7.049s)
[250.886936] [029/132] (772s left) gem_ctx_persistence (engines-hostile-preempt)
[250.944270] Starting subtest: engines-hostile-preempt
[250.946121] Starting dynamic subtest: rcs0
[251.053756] Dynamic subtest rcs0: SUCCESS (0.112s)
[251.067851] Starting dynamic subtest: bcs0
[251.134706] Dynamic subtest bcs0: SUCCESS (0.078s)
[251.144988] Starting dynamic subtest: vcs0
[252.918715] Dynamic subtest vcs0: SUCCESS (1.779s)
[252.931048] Starting dynamic subtest: vcs1
[254.916231] Dynamic subtest vcs1: SUCCESS (1.995s)
[254.927082] Starting dynamic subtest: vecs0
[256.899753] Dynamic subtest vecs0: SUCCESS (1.981s)
[256.910408] Starting dynamic subtest: ccs0
[256.961187] Dynamic subtest ccs0: SUCCESS (0.060s)
[256.963515] Subtest engines-hostile-preempt: SUCCESS (6.019s)
[257.105332] [030/132] (766s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[257.216443] Starting subtest: psr-rgb565-draw-pwrite
[264.126378] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.912s)
[264.264385] [031/132] (758s left) gem_render_copy (y-tiled-to-vebox-linear)
[264.333971] Starting subtest: y-tiled-to-vebox-linear
[264.338488] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[264.553209] [032/132] (758s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[264.614016] Starting subtest: multi-wait-for-submit-submitted-signaled
[264.615938] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[264.741604] [033/132] (758s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[264.838481] Starting subtest: x-tiled-64bpp-rotate-90
[264.857026] Subtest x-tiled-64bpp-rotate-90: SKIP (0.018s)
[265.018147] [034/132] (758s left) kms_psr (fbc-psr2-cursor-render)
[265.123860] Starting subtest: fbc-psr2-cursor-render
[265.126106] Starting dynamic subtest: eDP-1
[265.130191] Dynamic subtest eDP-1: SKIP (0.000s)
[265.134264] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[265.218925] [035/132] (758s left) kms_getfb (getfb-handle-protection)
[265.325654] Starting subtest: getfb-handle-protection
[265.347365] Subtest getfb-handle-protection: SUCCESS (0.022s)
[265.500367] [036/132] (757s left) sw_sync (sync_merge_invalid)
[265.546575] Starting subtest: sync_merge_invalid
[265.576579] Subtest sync_merge_invalid: SUCCESS (0.031s)
[265.652429] [037/132] (757s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[265.754797] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[270.371026] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.619s)
[271.531369] [038/132] (752s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[271.636975] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[278.880056] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.244s)
[279.046183] [039/132] (744s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[279.177337] Starting subtest: cursorB-vs-flipA-toggle
[279.181887] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[279.340751] [040/132] (744s left) kms_rmfb (rmfb-ioctl)
[279.439728] Starting subtest: rmfb-ioctl
[279.441957] Starting dynamic subtest: pipe-A-eDP-1
[279.544486] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[279.553548] Starting dynamic subtest: pipe-B-eDP-1
[280.678494] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.133s)
[280.692634] Starting dynamic subtest: pipe-C-eDP-1
[281.807101] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.125s)
[281.818282] Starting dynamic subtest: pipe-D-eDP-1
[283.036599] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.226s)
[283.038955] Subtest rmfb-ioctl: SUCCESS (3.599s)
[284.180689] [041/132] (739s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[284.568272] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[285.368197] [042/132] (738s left) sysfs_timeslice_duration (invalid)
[285.427332] Starting subtest: invalid
[285.429601] Starting dynamic subtest: vecs0
[285.431895] Dynamic subtest vecs0: SUCCESS (0.000s)
[285.441727] Starting dynamic subtest: ccs0
[285.443588] Dynamic subtest ccs0: SUCCESS (0.000s)
[285.451538] Starting dynamic subtest: vcs0
[285.453663] Dynamic subtest vcs0: SUCCESS (0.000s)
[285.461130] Starting dynamic subtest: bcs0
[285.463030] Dynamic subtest bcs0: SUCCESS (0.000s)
[285.470459] Starting dynamic subtest: rcs0
[285.472374] Dynamic subtest rcs0: SUCCESS (0.000s)
[285.479876] Starting dynamic subtest: vcs1
[285.481788] Dynamic subtest vcs1: SUCCESS (0.000s)
[285.483667] Subtest invalid: SUCCESS (0.005s)
[285.572285] [043/132] (738s left) syncobj_eventfd (timeline-wait)
[285.640892] Starting subtest: timeline-wait
[285.651138] Subtest timeline-wait: SUCCESS (0.011s)
[285.785675] [044/132] (737s left) kms_pm_backlight (brightness-with-dpms)
[285.881709] Starting subtest: brightness-with-dpms
[285.929565] Starting dynamic subtest: eDP-1
[288.651532] Dynamic subtest eDP-1: SUCCESS (2.722s)
[288.653957] Subtest brightness-with-dpms: SUCCESS (2.772s)
[289.546767] [045/132] (734s left) kms_busy (extended-modeset-hang-oldfb)
[289.669174] Starting subtest: extended-modeset-hang-oldfb
[289.671446] Starting dynamic subtest: pipe-A
[301.506048] Dynamic subtest pipe-A: SUCCESS (11.836s)
[301.515994] Starting dynamic subtest: pipe-D
[313.788301] Dynamic subtest pipe-D: SUCCESS (12.280s)
[313.790680] Subtest extended-modeset-hang-oldfb: SUCCESS (24.121s)
[314.604305] [046/132] (709s left) syncobj_wait (multi-wait-all-signaled)
[314.672504] Starting subtest: multi-wait-all-signaled
[314.674819] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[314.794348] [047/132] (709s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[314.888745] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[314.891052] Starting dynamic subtest: pipe-A-eDP-1
[314.914201] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[314.924347] Starting dynamic subtest: pipe-B-eDP-1
[314.946207] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[314.954398] Starting dynamic subtest: pipe-C-eDP-1
[314.975848] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[314.984188] Starting dynamic subtest: pipe-D-eDP-1
[315.006240] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[315.010338] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[317.078475] [048/132] (706s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[317.171546] Starting subtest: yf-tiled-8bpp-rotate-180
[317.175644] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[317.300395] [049/132] (706s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[317.400655] Starting subtest: psr-rgb565-draw-mmap-cpu
[324.086088] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.687s)
[324.237878] [050/132] (699s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[324.349224] Starting subtest: yf-tiled-16bpp-rotate-90
[324.353127] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[324.485252] [051/132] (699s left) kms_addfb_basic (clobberred-modifier)
[324.556658] Starting subtest: clobberred-modifier
[324.560981] Subtest clobberred-modifier: SKIP (0.000s)
[324.726888] [052/132] (699s left) gem_exec_reloc (basic-write-read-active)
[324.794717] Subtest basic-write-read-active: SKIP (0.000s)
[324.872155] [053/132] (699s left) dmabuf (all-tests)
[324.913061] Starting subtest: all-tests
[324.915571] Starting dynamic subtest: sanitycheck
[325.102170] Dynamic subtest sanitycheck: SUCCESS (0.188s)
[325.114076] Starting dynamic subtest: dma_fence
[325.375632] Dynamic subtest dma_fence: SUCCESS (0.270s)
[325.386994] Starting dynamic subtest: dma_fence_chain
[333.865707] Dynamic subtest dma_fence_chain: SUCCESS (8.487s)
[333.877748] Starting dynamic subtest: dma_fence_unwrap
[334.013315] Dynamic subtest dma_fence_unwrap: SUCCESS (0.146s)
[334.024872] Starting dynamic subtest: dma_resv
[334.124687] Dynamic subtest dma_resv: SUCCESS (0.110s)
[334.127295] Subtest all-tests: SUCCESS (9.212s)
[334.214352] [054/132] (689s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[334.524757] Starting subtest: cursor-rapid-movement-512x170
[334.529102] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[334.724479] [055/132] (689s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[334.781893] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[334.784069] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[334.918285] [056/132] (689s left) kms_big_fb (linear-16bpp-rotate-90)
[335.020907] Starting subtest: linear-16bpp-rotate-90
[335.039482] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[335.186129] [057/132] (689s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[335.292187] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[335.296602] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[335.418981] [058/132] (688s left) gem_exec_schedule (noreorder-priority)
[335.596865] Starting subtest: noreorder-priority
[335.599328] Starting dynamic subtest: rcs0
[336.747985] Dynamic subtest rcs0: SUCCESS (1.151s)
[336.760598] Starting dynamic subtest: vcs0
[339.938261] Dynamic subtest vcs0: SUCCESS (3.187s)
[339.949786] Starting dynamic subtest: vcs1
[341.931629] Dynamic subtest vcs1: SUCCESS (1.990s)
[341.943199] Starting dynamic subtest: vecs0
[343.907833] Dynamic subtest vecs0: SUCCESS (1.973s)
[343.919688] Starting dynamic subtest: ccs0
[345.092339] Dynamic subtest ccs0: SUCCESS (1.183s)
[345.095219] Subtest noreorder-priority: SUCCESS (9.497s)
[345.299460] [059/132] (679s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[345.410600] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[351.998373] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.590s)
[352.157556] [060/132] (672s left) perf (polling-small-buf)
[352.277131] Starting subtest: polling-small-buf
[352.367060] Subtest polling-small-buf: SUCCESS (0.087s)
[352.411878] [061/132] (672s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[352.509986] Starting subtest: bad-pixel-format-y-tiled-ccs
[352.512177] Starting dynamic subtest: pipe-A-eDP-1
[352.533557] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[352.543968] Starting dynamic subtest: pipe-B-eDP-1
[352.564125] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[352.572492] Starting dynamic subtest: pipe-C-eDP-1
[352.593354] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[352.601665] Starting dynamic subtest: pipe-D-eDP-1
[352.623554] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[352.627546] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[354.729550] [062/132] (669s left) i915_drm_fdinfo (busy-hang)
[354.772742] Starting subtest: busy-hang
[354.774992] Starting dynamic subtest: rcs0
[354.805669] Dynamic subtest rcs0: SKIP (0.031s)
[354.815846] Starting dynamic subtest: bcs0
[354.820112] Dynamic subtest bcs0: SKIP (0.002s)
[354.827871] Starting dynamic subtest: vcs0
[354.832139] Dynamic subtest vcs0: SKIP (0.001s)
[354.839686] Starting dynamic subtest: vcs1
[354.843902] Dynamic subtest vcs1: SKIP (0.001s)
[354.851639] Starting dynamic subtest: vecs0
[354.855732] Dynamic subtest vecs0: SKIP (0.001s)
[354.863618] Starting dynamic subtest: ccs0
[354.867689] Dynamic subtest ccs0: SKIP (0.000s)
[354.872489] Subtest busy-hang: SKIP (0.043s)
[354.906078] [063/132] (669s left) kms_cursor_crc (cursor-sliding-max-size)
[355.211675] Starting subtest: cursor-sliding-max-size
[355.218288] Subtest cursor-sliding-max-size: SKIP (0.000s)
[355.391568] [064/132] (669s left) gem_exec_schedule (preempt-queue-contexts-chain)
[355.471804] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[355.779448] [065/132] (668s left) gem_mmap_gtt (big-copy)
[355.844887] Subtest big-copy: SKIP (0.000s)
[355.905868] [066/132] (668s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[356.010376] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[356.012695] Starting dynamic subtest: pipe-A
[356.072708] Dynamic subtest pipe-A: SUCCESS (0.065s)
[356.081833] Starting dynamic subtest: pipe-B
[357.234689] Dynamic subtest pipe-B: SUCCESS (1.160s)
[357.245321] Starting dynamic subtest: pipe-C
[358.349448] Dynamic subtest pipe-C: SUCCESS (1.112s)
[358.358722] Starting dynamic subtest: pipe-D
[359.473612] Dynamic subtest pipe-D: SUCCESS (1.123s)
[359.475846] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.466s)
[360.661781] [067/132] (664s left) kms_chamelium_color (ctm-0-50)
[360.765680] Subtest ctm-0-50: SKIP (0.000s)
[360.833724] [068/132] (663s left) kms_psr (pr-primary-page-flip)
[360.922865] Starting subtest: pr-primary-page-flip
[360.927181] Subtest pr-primary-page-flip: SKIP (0.000s)
[361.014170] [069/132] (663s left) kms_atomic (test-only)
[361.108079] Starting subtest: test-only
[361.156399] Starting dynamic subtest: pipe-A-eDP-1
[363.645105] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.489s)
[363.647428] Subtest test-only: SUCCESS (2.538s)
[364.469127] [070/132] (660s left) gem_spin_batch (spin-each)
[364.547173] Starting subtest: spin-each
[367.816326] Subtest spin-each: SUCCESS (3.261s)
[368.065630] [071/132] (656s left) gem_exec_params (batch-first)
[368.126415] Starting subtest: batch-first
[368.137105] Subtest batch-first: SUCCESS (0.011s)
[368.289062] [072/132] (656s left) gem_busy (semaphore)
[368.479273] Subtest semaphore: SKIP (0.000s)
[368.737793] [073/132] (656s left) kms_psr (pr-primary-blt)
[368.831981] Starting subtest: pr-primary-blt
[368.836309] Subtest pr-primary-blt: SKIP (0.000s)
[368.927465] [074/132] (656s left) kms_dp_link_training (uhbr-mst)
[369.023518] Starting subtest: uhbr-mst
[369.031802] Subtest uhbr-mst: SKIP (0.000s)
[369.116635] [075/132] (655s left) i915_suspend (debugfs-reader)
[369.178188] Starting subtest: debugfs-reader
[387.348236] Subtest debugfs-reader: SUCCESS (6.787s)
[387.465157] [076/132] (637s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[387.573781] Starting subtest: compare-crc-sanitycheck-nv12
[387.575711] Starting dynamic subtest: pipe-A-eDP-1
[388.204985] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.655s)
[388.214730] Starting dynamic subtest: pipe-B-eDP-1
[389.449182] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.290s)
[389.459683] Starting dynamic subtest: pipe-C-eDP-1
[390.610280] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.194s)
[390.620021] Starting dynamic subtest: pipe-D-eDP-1
[391.837741] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.258s)
[391.840065] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.403s)
[392.670371] [077/132] (632s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[392.773501] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[392.777812] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[392.900891] [078/132] (632s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[392.993161] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[392.997574] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[394.175194] [079/132] (631s left) perf_pmu (busy-idle-check-all)
[394.215789] Starting subtest: busy-idle-check-all
[394.217973] Starting dynamic subtest: rcs0
[394.887420] Dynamic subtest rcs0: SUCCESS (0.684s)
[394.904274] Starting dynamic subtest: bcs0
[395.538869] Dynamic subtest bcs0: SUCCESS (0.659s)
[395.550752] Starting dynamic subtest: vcs0
[396.243710] Dynamic subtest vcs0: FAIL (0.648s)
[396.252988] Starting dynamic subtest: vcs1
[396.876673] Dynamic subtest vcs1: FAIL (0.628s)
[396.886231] Starting dynamic subtest: vecs0
[397.501158] Dynamic subtest vecs0: FAIL (0.625s)
[397.516598] Starting dynamic subtest: ccs0
[398.119381] Dynamic subtest ccs0: FAIL (0.628s)
[398.121490] Subtest busy-idle-check-all: FAIL (3.879s)
[398.197683] [080/132] (627s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[398.293536] Starting subtest: psr-2p-pri-indfb-multidraw
[398.297643] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[398.408426] [081/132] (626s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[398.528481] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[398.532829] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[398.649456] [082/132] (626s left) gem_exec_balancer (fairslice)
[398.714457] Starting subtest: fairslice
[401.573593] Subtest fairslice: SUCCESS (2.880s)
[402.458453] [083/132] (622s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[402.512858] Starting subtest: etime-single-wait-all-available-unsubmitted
[402.617985] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.105s)
[402.721722] [084/132] (622s left) gem_exec_fence (syncobj-timeline-chain-engines)
[403.302762] Starting subtest: syncobj-timeline-chain-engines
[403.459861] Subtest syncobj-timeline-chain-engines: SUCCESS (0.157s)
[404.432153] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[404.435124] [085/132] (621s left) gem_pread (display)
[404.493168] Subtest display: SKIP (0.000s)
[404.560440] [086/132] (620s left) gem_sync (basic-all)
[404.637874] Starting subtest: basic-all
[408.293465] Subtest basic-all: SUCCESS (3.678s)
[408.507187] [087/132] (617s left) gem_mmap_offset (isolation)
[408.566238] Starting subtest: isolation
[408.583329] Subtest isolation: SUCCESS (0.006s)
[408.716091] [088/132] (616s left) kms_atomic_interruptible (legacy-dpms)
[408.825909] Starting subtest: legacy-dpms
[408.828166] Starting dynamic subtest: pipe-A-eDP-1
[416.257652] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.445s)
[416.259842] Subtest legacy-dpms: SUCCESS (7.447s)
[417.099684] [089/132] (608s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[417.220621] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[417.225141] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[417.344647] [090/132] (608s left) gem_flink_basic (bad-open)
[417.407775] Starting subtest: bad-open
[417.409839] Subtest bad-open: SUCCESS (0.000s)
[417.547095] [091/132] (608s left) kms_invalid_mode (int-max-clock)
[417.647491] Starting subtest: int-max-clock
[417.649629] Starting dynamic subtest: pipe-A-eDP-1
[417.931358] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[417.941239] Starting dynamic subtest: pipe-B-eDP-1
[417.943185] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[417.951748] Starting dynamic subtest: pipe-C-eDP-1
[417.954155] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[417.963141] Starting dynamic subtest: pipe-D-eDP-1
[417.965484] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[417.967814] Subtest int-max-clock: SUCCESS (0.292s)
[418.833592] [092/132] (606s left) gem_exec_params (mmapped)
[418.900995] Starting subtest: mmapped
[418.906759] Subtest mmapped: SUCCESS (0.003s)
[419.078092] [093/132] (606s left) kms_plane_multiple (tiling-x)
[419.178696] Starting subtest: tiling-x
[419.180846] Starting dynamic subtest: pipe-A-eDP-1
[422.851154] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.671s)
[422.862139] Starting dynamic subtest: pipe-B-eDP-1
[427.293017] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.439s)
[427.303656] Starting dynamic subtest: pipe-C-eDP-1
[431.635989] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.343s)
[431.647583] Starting dynamic subtest: pipe-D-eDP-1
[435.977834] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.338s)
[435.980264] Subtest tiling-x: SUCCESS (16.796s)
[436.812586] [094/132] (589s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[436.912005] Starting subtest: fbc-rgb101010-draw-pwrite
[442.250378] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.342s)
[443.405183] [095/132] (582s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[443.499472] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[450.232445] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.736s)
[450.425331] [096/132] (575s left) kms_flip (2x-flip-vs-dpms)
[450.525714] Starting subtest: 2x-flip-vs-dpms
[450.537290] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[450.644657] [097/132] (575s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[450.737799] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[450.749453] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.010s)
[450.886844] [098/132] (575s left) kms_content_protection (atomic-hdcp14)
[451.025078] Starting subtest: atomic-hdcp14
[451.029462] Subtest atomic-hdcp14: SKIP (0.000s)
[451.179929] [099/132] (574s left) sw_sync (sync_merge)
[451.215002] Starting subtest: sync_merge
[451.217175] Subtest sync_merge: SUCCESS (0.000s)
[451.299242] [100/132] (574s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[451.398134] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[451.399927] Starting dynamic subtest: pipe-A-default-mode
[451.689721] Dynamic subtest pipe-A-default-mode: SKIP (0.287s)
[451.694240] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.289s)
[452.524036] [101/132] (573s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[452.635766] Starting subtest: cursor-vs-flip-atomic-transitions
[482.424058] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (29.796s)
[483.246851] [102/132] (542s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[483.343810] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[483.346021] Starting dynamic subtest: pipe-A-eDP-1
[483.404232] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.062s)
[483.697452] Starting dynamic subtest: pipe-B-eDP-1
[484.523254] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.834s)
[484.824945] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.480s)
[485.714177] [103/132] (540s left) kms_prime (basic-crc-vgem)
[485.803927] Starting subtest: basic-crc-vgem
[485.806264] Starting dynamic subtest: second-to-first
[486.739910] Dynamic subtest second-to-first: SUCCESS (0.939s)
[486.741803] Subtest basic-crc-vgem: SUCCESS (0.940s)
[486.856053] [104/132] (539s left) kms_properties (invalid-properties-legacy)
[486.988133] Starting subtest: invalid-properties-legacy
[487.089555] Subtest invalid-properties-legacy: SUCCESS (0.102s)
[487.189365] [105/132] (539s left) syncobj_timeline (wait-zero-handles)
[487.259602] Starting subtest: wait-zero-handles
[487.261910] Subtest wait-zero-handles: SUCCESS (0.000s)
[487.405598] [106/132] (538s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[492.568928] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[492.573695] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[493.441154] [107/132] (532s left) gem_pxp (reject-modify-context-protection-off-1)
[493.580053] Starting subtest: reject-modify-context-protection-off-1
[493.582775] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[493.728140] [108/132] (532s left) kms_selftest (drm_format_helper)
[493.762742] Starting subtest: drm_format_helper
[493.775403] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[493.924398] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.151s)
[493.935635] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[494.081941] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.154s)
[494.091519] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[494.236377] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.154s)
[494.245716] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[494.376545] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.140s)
[494.385852] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[494.535467] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.158s)
[494.544756] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[494.682967] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.147s)
[494.692699] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[494.823719] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.139s)
[494.832888] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[494.989259] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.163s)
[494.998614] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[495.135909] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.145s)
[495.145052] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[495.293436] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.156s)
[495.303950] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[495.451156] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.157s)
[495.461374] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[495.613507] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.163s)
[495.622750] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[495.773410] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.157s)
[495.782893] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[495.929981] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.157s)
[495.941076] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[496.040234] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.108s)
[496.051080] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[496.154142] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.114s)
[496.165190] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[496.272081] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.116s)
[496.273998] Subtest drm_format_helper: SUCCESS (2.508s)
[496.488335] [109/132] (530s left) gen3_mixed_blits
[496.642207] [110/132] (529s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[496.743364] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[496.747153] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[496.871423] [111/132] (529s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[496.977339] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[503.682133] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.706s)
[503.839833] [112/132] (522s left) gen7_exec_parse (cmd-crossing-page)
[503.904809] Subtest cmd-crossing-page: SKIP (0.000s)
[503.975533] [113/132] (522s left) gem_softpin (overlap)
[504.038209] Starting subtest: overlap
[504.905667] Subtest overlap: SUCCESS (0.866s)
[505.062542] [114/132] (521s left) gem_eio (in-flight-internal-immediate)
[505.279757] Starting subtest: in-flight-internal-immediate
[506.429726] Subtest in-flight-internal-immediate: SUCCESS (1.149s)
[506.606441] [115/132] (520s left) gem_wait (write-wait)
[506.679386] Starting subtest: write-wait
[506.681583] Starting dynamic subtest: all-engines
[507.843049] Dynamic subtest all-engines: SUCCESS (1.163s)
[507.860599] Starting dynamic subtest: rcs0
[508.902953] Dynamic subtest rcs0: SUCCESS (1.057s)
[508.920361] Starting dynamic subtest: bcs0
[509.962711] Dynamic subtest bcs0: SUCCESS (1.057s)
[509.981737] Starting dynamic subtest: vcs0
[511.016223] Dynamic subtest vcs0: SUCCESS (1.051s)
[511.033187] Starting dynamic subtest: vcs1
[512.071258] Dynamic subtest vcs1: SUCCESS (1.053s)
[512.087972] Starting dynamic subtest: vecs0
[513.125394] Dynamic subtest vecs0: SUCCESS (1.052s)
[513.141838] Starting dynamic subtest: ccs0
[514.180192] Dynamic subtest ccs0: SUCCESS (1.054s)
[514.182636] Subtest write-wait: SUCCESS (7.501s)
[514.408366] [116/132] (512s left) kms_chamelium_edid (dp-edid-read)
[514.796610] Subtest dp-edid-read: SKIP (0.000s)
[515.601707] [117/132] (511s left) gem_exec_balancer (parallel-bb-first)
[515.871121] Starting subtest: parallel-bb-first
[516.755107] Subtest parallel-bb-first: SUCCESS (0.884s)
[517.413695] [118/132] (509s left) kms_pm_rpm (fences)
[519.542815] Starting subtest: fences
[519.547626] Subtest fences: SKIP (0.000s)
[519.924160] [119/132] (507s left) gem_ctx_persistence (legacy-engines-queued)
[519.976148] Starting subtest: legacy-engines-queued
[519.978357] Starting dynamic subtest: render
[520.063004] Dynamic subtest render: SUCCESS (0.086s)
[520.076643] Starting dynamic subtest: bsd1
[521.284826] Dynamic subtest bsd1: SUCCESS (1.217s)
[521.298122] Starting dynamic subtest: bsd2
[523.268440] Dynamic subtest bsd2: SUCCESS (1.980s)
[523.281158] Starting dynamic subtest: blt
[523.346989] Dynamic subtest blt: SUCCESS (0.076s)
[523.359022] Starting dynamic subtest: vebox
[525.252466] Dynamic subtest vebox: SUCCESS (1.901s)
[525.255436] Subtest legacy-engines-queued: SUCCESS (5.277s)
[525.358747] [120/132] (501s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[525.464589] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[526.110065] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.648s)
[526.344372] [121/132] (500s left) gem_mmap_gtt (basic-wc)
[526.405706] Subtest basic-wc: SKIP (0.000s)
[526.477124] [122/132] (500s left) core_auth (getclient-simple)
[526.512465] Starting subtest: getclient-simple
[526.533251] Subtest getclient-simple: SUCCESS (0.021s)
[526.638377] [123/132] (500s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[526.739148] Starting subtest: addfb25-y-tiled-small-legacy
[526.743452] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[526.875842] [124/132] (500s left) perf (invalid-oa-exponent)
[526.976010] Starting subtest: invalid-oa-exponent
[526.994223] Subtest invalid-oa-exponent: SUCCESS (0.015s)
[527.037677] [125/132] (500s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[527.131717] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[527.133703] Starting dynamic subtest: pipe-A-default-mode
[528.267284] Dynamic subtest pipe-A-default-mode: SKIP (1.132s)
[528.271722] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.133s)
[528.459235] [126/132] (498s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[528.563649] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[528.565887] Starting dynamic subtest: A-eDP1
[532.170899] Dynamic subtest A-eDP1: SUCCESS (3.604s)
[532.181766] Starting dynamic subtest: B-eDP1
[535.360345] Dynamic subtest B-eDP1: SUCCESS (3.189s)
[535.370760] Starting dynamic subtest: C-eDP1
[538.448079] Dynamic subtest C-eDP1: SUCCESS (3.087s)
[538.458703] Starting dynamic subtest: D-eDP1
[541.577891] Dynamic subtest D-eDP1: SUCCESS (3.131s)
[541.580274] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.019s)
[542.402419] [127/132] (484s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[542.470409] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[542.472709] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[542.588480] [128/132] (484s left) kms_vrr (max-min)
[542.691175] Starting subtest: max-min
[542.697360] Subtest max-min: SKIP (0.000s)
[542.787494] [129/132] (484s left) kms_rotation_crc (sprite-rotation-90)
[542.892010] Starting subtest: sprite-rotation-90
[542.896253] Subtest sprite-rotation-90: SKIP (0.001s)
[542.995903] [130/132] (484s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[543.094170] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[543.098278] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[543.232745] [131/132] (484s left) kms_cursor_crc (cursor-offscreen-64x64)
[543.415088] Starting subtest: cursor-offscreen-64x64
[543.417041] Starting dynamic subtest: pipe-A-eDP-1
[545.358388] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.941s)
[545.387280] Starting dynamic subtest: pipe-D-eDP-1
[547.984296] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.607s)
[547.986604] Subtest cursor-offscreen-64x64: SUCCESS (4.572s)
[548.858070] [132/132] (478s left) kms_color_pipeline (plane-lut1d-lut1d)
[548.965721] Starting subtest: plane-lut1d-lut1d
[548.978796] Starting dynamic subtest: pipe-A-eDP-1
[549.678388] Dynamic subtest pipe-A-eDP-1: FAIL (0.294s)
[550.318472] Starting dynamic subtest: pipe-B-eDP-1
[551.172043] Dynamic subtest pipe-B-eDP-1: FAIL (0.335s)
[551.747279] Starting dynamic subtest: pipe-C-eDP-1
[552.588296] Dynamic subtest pipe-C-eDP-1: FAIL (0.258s)
[553.070335] Starting dynamic subtest: pipe-D-eDP-1
[553.949620] Dynamic subtest pipe-D-eDP-1: FAIL (0.290s)
[553.951667] Subtest plane-lut1d-lut1d: FAIL (4.689s)
[554.473347] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_store
results: parsing output: 1/ for test: gem_pread
results: parsing output: 2/ for test: syncobj_wait
results: parsing output: 3/ for test: kms_vblank
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: kms_plane_cursor
results: parsing output: 6/ for test: perf
results: parsing output: 7/ for test: gem_exec_schedule
results: parsing output: 8/ for test: gem_lmem_swapping
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: perf
results: parsing output: 11/ for test: gem_ppgtt
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: gem_mmap_gtt
results: parsing output: 14/ for test: syncobj_timeline
results: parsing output: 15/ for test: gem_ctx_engines
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_ccs
results: parsing output: 18/ for test: gem_mmap_gtt
results: parsing output: 19/ for test: api_intel_allocator
results: parsing output: 20/ for test: kms_psr2_sf
results: parsing output: 21/ for test: i915_pciid
results: parsing output: 22/ for test: i915_pm_rpm
results: parsing output: 23/ for test: gem_exec_reloc
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: sw_sync
results: parsing output: 26/ for test: gem_eio
results: parsing output: 27/ for test: kms_fb_coherency
results: parsing output: 28/ for test: gem_ctx_persistence
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: gem_render_copy
results: parsing output: 31/ for test: syncobj_timeline
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: kms_psr
results: parsing output: 34/ for test: kms_getfb
results: parsing output: 35/ for test: sw_sync
results: parsing output: 36/ for test: kms_frontbuffer_tracking
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_rmfb
results: parsing output: 40/ for test: kms_chamelium_hpd
results: parsing output: 41/ for test: sysfs_timeslice_duration
results: parsing output: 42/ for test: syncobj_eventfd
results: parsing output: 43/ for test: kms_pm_backlight
results: parsing output: 44/ for test: kms_busy
results: parsing output: 45/ for test: syncobj_wait
results: parsing output: 46/ for test: kms_ccs
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: kms_addfb_basic
results: parsing output: 51/ for test: gem_exec_reloc
results: parsing output: 52/ for test: dmabuf
results: parsing output: 53/ for test: kms_cursor_crc
results: parsing output: 54/ for test: syncobj_timeline
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_exec_schedule
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: perf
results: parsing output: 60/ for test: kms_ccs
results: parsing output: 61/ for test: i915_drm_fdinfo
results: parsing output: 62/ for test: kms_cursor_crc
results: parsing output: 63/ for test: gem_exec_schedule
results: parsing output: 64/ for test: gem_mmap_gtt
results: parsing output: 65/ for test: kms_plane_scaling
results: parsing output: 66/ for test: kms_chamelium_color
results: parsing output: 67/ for test: kms_psr
results: parsing output: 68/ for test: kms_atomic
results: parsing output: 69/ for test: gem_spin_batch
results: parsing output: 70/ for test: gem_exec_params
results: parsing output: 71/ for test: gem_busy
results: parsing output: 72/ for test: kms_psr
results: parsing output: 73/ for test: kms_dp_link_training
results: parsing output: 74/ for test: i915_suspend
results: parsing output: 75/ for test: kms_pipe_crc_basic
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_ccs
results: parsing output: 78/ for test: perf_pmu
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_balancer
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: gem_exec_fence
results: parsing output: 84/ for test: gem_pread
results: parsing output: 85/ for test: gem_sync
results: parsing output: 86/ for test: gem_mmap_offset
results: parsing output: 87/ for test: kms_atomic_interruptible
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: gem_flink_basic
results: parsing output: 90/ for test: kms_invalid_mode
results: parsing output: 91/ for test: gem_exec_params
results: parsing output: 92/ for test: kms_plane_multiple
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_flip
results: parsing output: 96/ for test: kms_flip
results: parsing output: 97/ for test: kms_content_protection
results: parsing output: 98/ for test: sw_sync
results: parsing output: 99/ for test: kms_flip_scaled_crc
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: kms_atomic_transition
results: parsing output: 102/ for test: kms_prime
results: parsing output: 103/ for test: kms_properties
results: parsing output: 104/ for test: syncobj_timeline
results: parsing output: 105/ for test: kms_psr2_sf
results: parsing output: 106/ for test: gem_pxp
results: parsing output: 107/ for test: kms_selftest
results: parsing output: 108/ for test: gen3_mixed_blits
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: gen7_exec_parse
results: parsing output: 112/ for test: gem_softpin
results: parsing output: 113/ for test: gem_eio
results: parsing output: 114/ for test: gem_wait
results: parsing output: 115/ for test: kms_chamelium_edid
results: parsing output: 116/ for test: gem_exec_balancer
results: parsing output: 117/ for test: kms_pm_rpm
results: parsing output: 118/ for test: gem_ctx_persistence
results: parsing output: 119/ for test: kms_big_fb
results: parsing output: 120/ for test: gem_mmap_gtt
results: parsing output: 121/ for test: core_auth
results: parsing output: 122/ for test: kms_addfb_basic
results: parsing output: 123/ for test: perf
results: parsing output: 124/ for test: kms_flip_scaled_crc
results: parsing output: 125/ for test: kms_flip
results: parsing output: 126/ for test: syncobj_wait
results: parsing output: 127/ for test: kms_vrr
results: parsing output: 128/ for test: kms_rotation_crc
results: parsing output: 129/ for test: kms_frontbuffer_tracking
results: parsing output: 130/ for test: kms_cursor_crc
results: parsing output: 131/ for test: kms_color_pipeline
Done.