[59.379987] Initializing watchdogs
[59.380067]   /dev/watchdog0
[59.397409] [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.402180] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.404209] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.406689] [001/132] (960s left) gem_exec_store (cachelines)
[59.468505] Starting subtest: cachelines
[59.469580] Starting dynamic subtest: rcs0
[59.579523] Dynamic subtest rcs0: SUCCESS (0.110s)
[59.587871] Starting dynamic subtest: bcs0
[59.648145] Dynamic subtest bcs0: SUCCESS (0.069s)
[59.655473] Starting dynamic subtest: vcs0
[59.700725] Dynamic subtest vcs0: SUCCESS (0.052s)
[59.708570] Starting dynamic subtest: vcs1
[59.765746] Dynamic subtest vcs1: SUCCESS (0.064s)
[59.773600] Starting dynamic subtest: vecs0
[59.828809] Dynamic subtest vecs0: SUCCESS (0.062s)
[59.836525] Starting dynamic subtest: ccs0
[59.897359] Dynamic subtest ccs0: SUCCESS (0.067s)
[59.899028] Subtest cachelines: SUCCESS (0.429s)
[60.093621] [002/132] (959s left) gem_pread (bench)
[60.142082] Subtest bench: SKIP (0.000s)
[60.200827] [003/132] (959s left) syncobj_wait (invalid-signal-illegal-handle)
[60.268073] Starting subtest: invalid-signal-illegal-handle
[60.269147] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[60.398191] [004/132] (959s left) kms_vblank (query-forked-busy-hang)
[60.498053] Starting subtest: query-forked-busy-hang
[60.498986] Starting dynamic subtest: pipe-A-eDP-1
[86.733755] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.234s)
[86.741664] Starting dynamic subtest: pipe-D-eDP-1
[101.652812] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.916s)
[101.653755] Subtest query-forked-busy-hang: SUCCESS (41.154s)
[102.543635] [005/132] (917s left) kms_psr (fbc-pr-primary-mmap-cpu)
[102.657179] Starting subtest: fbc-pr-primary-mmap-cpu
[102.658897] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[102.742135] [006/132] (917s left) kms_plane_cursor (overlay)
[102.836422] Starting subtest: overlay
[102.838348] Starting dynamic subtest: pipe-A-eDP-1-size-64
[105.217970] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.382s)
[105.277063] Starting dynamic subtest: pipe-A-eDP-1-size-128
[107.674247] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.405s)
[107.733431] Starting dynamic subtest: pipe-A-eDP-1-size-256
[110.131046] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.405s)
[110.474250] Starting dynamic subtest: pipe-B-eDP-1-size-64
[113.629324] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.161s)
[113.687031] Starting dynamic subtest: pipe-B-eDP-1-size-128
[116.095064] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.415s)
[116.154619] Starting dynamic subtest: pipe-B-eDP-1-size-256
[118.564952] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.418s)
[118.904757] Starting dynamic subtest: pipe-C-eDP-1-size-64
[121.635803] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.737s)
[121.672204] Starting dynamic subtest: pipe-C-eDP-1-size-128
[123.769037] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.102s)
[123.806138] Starting dynamic subtest: pipe-C-eDP-1-size-256
[125.846877] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.046s)
[126.178170] Starting dynamic subtest: pipe-D-eDP-1-size-64
[128.925862] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.753s)
[128.964061] Starting dynamic subtest: pipe-D-eDP-1-size-128
[130.983076] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.024s)
[131.018083] Starting dynamic subtest: pipe-D-eDP-1-size-256
[133.048165] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.035s)
[133.372171] Subtest overlay: SUCCESS (30.536s)
[134.172978] [007/132] (885s left) perf (i915-ref-count)
[134.249444] Starting subtest: i915-ref-count
[134.311212] Subtest i915-ref-count: SUCCESS (0.061s)
[134.357967] [008/132] (885s left) gem_exec_schedule (smoketest-all)
[134.420330] Starting subtest: smoketest-all
[166.772379] Subtest smoketest-all: SUCCESS (32.327s)
[167.955474] [009/132] (852s left) gem_lmem_swapping (heavy-random)
[167.992428] Subtest heavy-random: SKIP (0.000s)
[168.029250] [010/132] (852s left) kms_psr (psr-primary-mmap-gtt)
[168.118199] Starting subtest: psr-primary-mmap-gtt
[168.119117] Starting dynamic subtest: eDP-1
[169.546709] Dynamic subtest eDP-1: SKIP (1.425s)
[169.549102] Subtest psr-primary-mmap-gtt: SKIP (1.427s)
[169.744955] [011/132] (850s left) perf (whitelisted-registers-userspace-config)
[169.848417] Starting subtest: whitelisted-registers-userspace-config
[169.849350] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[169.882627] [012/132] (850s left) gem_ppgtt (blt-vs-render-ctx0)
[169.947066] Starting subtest: blt-vs-render-ctx0
[202.880419] Subtest blt-vs-render-ctx0: SUCCESS (32.930s)
[203.031564] [013/132] (817s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[203.156878] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[203.158748] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[203.276814] [014/132] (817s left) gem_mmap_gtt (basic)
[203.327439] Subtest basic: SKIP (0.000s)
[203.383601] [015/132] (816s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[203.434682] Starting subtest: invalid-single-wait-all-unsubmitted
[203.435724] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[203.563450] [016/132] (816s left) gem_ctx_engines (execute-one)
[203.629299] Starting subtest: execute-one
[205.901564] Subtest execute-one: SUCCESS (2.273s)
[206.099610] [017/132] (814s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[206.187929] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[206.189877] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[206.297308] [018/132] (814s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[206.389749] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[206.390642] Starting dynamic subtest: pipe-A-eDP-1
[206.398212] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[206.406329] Starting dynamic subtest: pipe-B-eDP-1
[206.414071] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[206.419861] Starting dynamic subtest: pipe-C-eDP-1
[206.427495] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[206.433435] Starting dynamic subtest: pipe-D-eDP-1
[206.445168] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[206.445946] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[208.547043] [019/132] (811s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[208.599468] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[208.656329] [020/132] (811s left) api_intel_allocator (two-level-inception-interruptible)
[208.712444] Starting subtest: two-level-inception-interruptible
[214.124360] Subtest two-level-inception-interruptible: SUCCESS (5.410s)
[214.284133] [021/132] (806s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[219.339072] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[219.341369] Starting dynamic subtest: pipe-A-eDP-1
[225.982634] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.640s)
[225.988943] Starting dynamic subtest: pipe-B-eDP-1
[232.632749] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.649s)
[232.633744] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.294s)
[233.523474] [022/132] (787s left) i915_pciid
[233.706384] [023/132] (786s left) i915_pm_rpm (sysfs-read)
[235.246482] Starting subtest: sysfs-read
[236.399722] Subtest sysfs-read: SUCCESS (1.153s)
[236.771911] [024/132] (783s left) gem_exec_reloc (basic-cpu-noreloc)
[236.832515] Subtest basic-cpu-noreloc: SKIP (0.000s)
[236.902984] [025/132] (783s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[236.994423] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[236.996114] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[237.104718] [026/132] (783s left) sw_sync (timeline_closed_signaled)
[237.135457] Starting subtest: timeline_closed_signaled
[237.136476] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[237.200230] [027/132] (783s left) gem_eio (context-create)
[237.432140] Starting subtest: context-create
[237.760186] Subtest context-create: SUCCESS (0.327s)
[237.923506] [028/132] (782s left) kms_fb_coherency (memset-crc)
[238.018595] Starting subtest: memset-crc
[238.019556] Starting dynamic subtest: mmap-offset-wc
[238.384495] Dynamic subtest mmap-offset-wc: SUCCESS (0.366s)
[238.670204] Starting dynamic subtest: mmap-offset-uc
[244.762801] Dynamic subtest mmap-offset-uc: SUCCESS (6.099s)
[245.054411] Subtest memset-crc: SUCCESS (7.036s)
[245.924579] [029/132] (774s left) gem_ctx_persistence (engines-hostile-preempt)
[245.990280] Starting subtest: engines-hostile-preempt
[245.991392] Starting dynamic subtest: rcs0
[246.102616] Dynamic subtest rcs0: SUCCESS (0.112s)
[246.113732] Starting dynamic subtest: bcs0
[246.129355] Dynamic subtest bcs0: SUCCESS (0.025s)
[246.138795] Starting dynamic subtest: vcs0
[247.912766] Dynamic subtest vcs0: SUCCESS (1.780s)
[247.921236] Starting dynamic subtest: vcs1
[249.908872] Dynamic subtest vcs1: SUCCESS (1.994s)
[249.917202] Starting dynamic subtest: vecs0
[251.845271] Dynamic subtest vecs0: SUCCESS (1.933s)
[251.854565] Starting dynamic subtest: ccs0
[251.907337] Dynamic subtest ccs0: SUCCESS (0.062s)
[251.908482] Subtest engines-hostile-preempt: SUCCESS (5.917s)
[252.057888] [030/132] (768s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[252.164132] Starting subtest: psr-rgb565-draw-pwrite
[259.051546] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.889s)
[259.180564] [031/132] (761s left) gem_render_copy (y-tiled-to-vebox-linear)
[259.237784] Starting subtest: y-tiled-to-vebox-linear
[259.239655] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[259.434314] [032/132] (761s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[259.497249] Starting subtest: multi-wait-for-submit-submitted-signaled
[259.498248] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[259.609395] [033/132] (761s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[259.699924] Starting subtest: x-tiled-64bpp-rotate-90
[259.719100] Subtest x-tiled-64bpp-rotate-90: SKIP (0.018s)
[259.872720] [034/132] (761s left) kms_psr (fbc-psr2-cursor-render)
[259.973079] Starting subtest: fbc-psr2-cursor-render
[259.973878] Starting dynamic subtest: eDP-1
[259.975518] Dynamic subtest eDP-1: SKIP (0.000s)
[259.977199] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[260.065365] [035/132] (760s left) kms_getfb (getfb-handle-protection)
[260.151071] Starting subtest: getfb-handle-protection
[260.179320] Subtest getfb-handle-protection: SUCCESS (0.028s)
[260.340310] [036/132] (760s left) sw_sync (sync_merge_invalid)
[260.373537] Starting subtest: sync_merge_invalid
[260.399945] Subtest sync_merge_invalid: SUCCESS (0.026s)
[260.477382] [037/132] (760s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[260.575906] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[265.211200] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.635s)
[266.362039] [038/132] (754s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[266.450551] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[273.730821] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.281s)
[273.905378] [039/132] (747s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[274.029327] Starting subtest: cursorB-vs-flipA-toggle
[274.032452] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[274.196015] [040/132] (746s left) kms_rmfb (rmfb-ioctl)
[274.285105] Starting subtest: rmfb-ioctl
[274.285943] Starting dynamic subtest: pipe-A-eDP-1
[274.390786] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[274.401365] Starting dynamic subtest: pipe-B-eDP-1
[275.531057] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.137s)
[275.540824] Starting dynamic subtest: pipe-C-eDP-1
[276.647091] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.115s)
[276.655070] Starting dynamic subtest: pipe-D-eDP-1
[277.776513] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.127s)
[277.777585] Subtest rmfb-ioctl: SUCCESS (3.492s)
[279.016598] [041/132] (742s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[279.406125] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[280.186996] [042/132] (740s left) sysfs_timeslice_duration (invalid)
[280.238449] Starting subtest: invalid
[280.239516] Starting dynamic subtest: vecs0
[280.240509] Dynamic subtest vecs0: SUCCESS (0.000s)
[280.248946] Starting dynamic subtest: ccs0
[280.249811] Dynamic subtest ccs0: SUCCESS (0.000s)
[280.256185] Starting dynamic subtest: vcs0
[280.256982] Dynamic subtest vcs0: SUCCESS (0.000s)
[280.263361] Starting dynamic subtest: bcs0
[280.264209] Dynamic subtest bcs0: SUCCESS (0.000s)
[280.270574] Starting dynamic subtest: rcs0
[280.271432] Dynamic subtest rcs0: SUCCESS (0.000s)
[280.277896] Starting dynamic subtest: vcs1
[280.278814] Dynamic subtest vcs1: SUCCESS (0.000s)
[280.279710] Subtest invalid: SUCCESS (0.006s)
[280.388896] [043/132] (740s left) syncobj_eventfd (timeline-wait)
[280.445331] Starting subtest: timeline-wait
[280.453766] Subtest timeline-wait: SUCCESS (0.011s)
[280.563765] [044/132] (740s left) kms_pm_backlight (brightness-with-dpms)
[280.647708] Starting subtest: brightness-with-dpms
[280.699755] Starting dynamic subtest: eDP-1
[283.108388] Dynamic subtest eDP-1: SUCCESS (2.409s)
[283.109943] Subtest brightness-with-dpms: SUCCESS (2.461s)
[283.993886] [045/132] (737s left) kms_busy (extended-modeset-hang-oldfb)
[284.091471] Starting subtest: extended-modeset-hang-oldfb
[284.092490] Starting dynamic subtest: pipe-A
[295.846966] Dynamic subtest pipe-A: SUCCESS (11.754s)
[295.860054] Starting dynamic subtest: pipe-D
[308.123464] Dynamic subtest pipe-D: SUCCESS (12.273s)
[308.124427] Subtest extended-modeset-hang-oldfb: SUCCESS (24.031s)
[308.935746] [046/132] (712s left) syncobj_wait (multi-wait-all-signaled)
[308.995410] Starting subtest: multi-wait-all-signaled
[308.996392] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[309.126525] [047/132] (712s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[309.205310] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[309.206234] Starting dynamic subtest: pipe-A-eDP-1
[309.214714] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[309.221929] Starting dynamic subtest: pipe-B-eDP-1
[309.230677] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[309.238074] Starting dynamic subtest: pipe-C-eDP-1
[309.246273] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[309.252990] Starting dynamic subtest: pipe-D-eDP-1
[309.261578] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[309.263233] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[311.413919] [048/132] (709s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[311.524070] Starting subtest: yf-tiled-8bpp-rotate-180
[311.525814] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[311.652472] [049/132] (709s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[311.744977] Starting subtest: psr-rgb565-draw-mmap-cpu
[318.435735] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.691s)
[318.567958] [050/132] (702s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[318.667457] Starting subtest: yf-tiled-16bpp-rotate-90
[318.669395] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[318.788755] [051/132] (702s left) kms_addfb_basic (clobberred-modifier)
[318.840071] Starting subtest: clobberred-modifier
[318.842064] Subtest clobberred-modifier: SKIP (0.000s)
[319.007093] [052/132] (702s left) gem_exec_reloc (basic-write-read-active)
[319.067811] Subtest basic-write-read-active: SKIP (0.000s)
[319.142094] [053/132] (702s left) dmabuf (all-tests)
[319.178607] Starting subtest: all-tests
[319.179604] Starting dynamic subtest: sanitycheck
[319.341336] Dynamic subtest sanitycheck: SUCCESS (0.160s)
[319.351901] Starting dynamic subtest: dma_fence
[319.594299] Dynamic subtest dma_fence: SUCCESS (0.250s)
[319.604481] Starting dynamic subtest: dma_fence_chain
[327.888381] Dynamic subtest dma_fence_chain: SUCCESS (8.291s)
[327.897697] Starting dynamic subtest: dma_fence_unwrap
[327.988462] Dynamic subtest dma_fence_unwrap: SUCCESS (0.099s)
[328.000291] Starting dynamic subtest: dma_resv
[328.105205] Dynamic subtest dma_resv: SUCCESS (0.112s)
[328.106573] Subtest all-tests: SUCCESS (8.926s)
[328.183123] [054/132] (693s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[328.490954] Starting subtest: cursor-rapid-movement-512x170
[328.492613] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[328.667241] [055/132] (692s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[328.721431] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[328.722393] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[328.840100] [056/132] (692s left) kms_big_fb (linear-16bpp-rotate-90)
[328.944222] Starting subtest: linear-16bpp-rotate-90
[328.964286] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[329.120393] [057/132] (692s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[329.215107] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[329.216886] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[329.336822] [058/132] (692s left) gem_exec_schedule (noreorder-priority)
[329.527778] Starting subtest: noreorder-priority
[329.528741] Starting dynamic subtest: rcs0
[330.694200] Dynamic subtest rcs0: SUCCESS (1.165s)
[330.705363] Starting dynamic subtest: vcs0
[331.950497] Dynamic subtest vcs0: SUCCESS (1.252s)
[331.959465] Starting dynamic subtest: vcs1
[333.930765] Dynamic subtest vcs1: SUCCESS (1.978s)
[333.940377] Starting dynamic subtest: vecs0
[335.920927] Dynamic subtest vecs0: SUCCESS (1.988s)
[335.930263] Starting dynamic subtest: ccs0
[337.088746] Dynamic subtest ccs0: SUCCESS (1.168s)
[337.089827] Subtest noreorder-priority: SUCCESS (7.562s)
[337.288891] [059/132] (684s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[337.384643] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[343.982289] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.597s)
[344.147697] [060/132] (677s left) perf (polling-small-buf)
[344.266017] Starting subtest: polling-small-buf
[344.357399] Subtest polling-small-buf: SUCCESS (0.090s)
[344.441633] [061/132] (677s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[344.543294] Starting subtest: bad-pixel-format-y-tiled-ccs
[344.544178] Starting dynamic subtest: pipe-A-eDP-1
[344.552711] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[344.560200] Starting dynamic subtest: pipe-B-eDP-1
[344.568559] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[344.575219] Starting dynamic subtest: pipe-C-eDP-1
[344.583527] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[344.589928] Starting dynamic subtest: pipe-D-eDP-1
[344.598374] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[344.599962] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[346.740029] [062/132] (675s left) i915_drm_fdinfo (busy-hang)
[346.775868] Starting subtest: busy-hang
[346.776755] Starting dynamic subtest: rcs0
[346.818936] Dynamic subtest rcs0: SKIP (0.042s)
[346.827692] Starting dynamic subtest: bcs0
[346.829393] Dynamic subtest bcs0: SKIP (0.001s)
[346.836313] Starting dynamic subtest: vcs0
[346.838253] Dynamic subtest vcs0: SKIP (0.001s)
[346.846764] Starting dynamic subtest: vcs1
[346.848402] Dynamic subtest vcs1: SKIP (0.001s)
[346.855286] Starting dynamic subtest: vecs0
[346.856848] Dynamic subtest vecs0: SKIP (0.001s)
[346.863556] Starting dynamic subtest: ccs0
[346.865281] Dynamic subtest ccs0: SKIP (0.001s)
[346.866795] Subtest busy-hang: SKIP (0.060s)
[346.930428] [063/132] (674s left) kms_cursor_crc (cursor-sliding-max-size)
[347.212520] Starting subtest: cursor-sliding-max-size
[347.215045] Subtest cursor-sliding-max-size: SKIP (0.000s)
[347.354461] [064/132] (674s left) gem_exec_schedule (preempt-queue-contexts-chain)
[347.407620] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[347.713112] [065/132] (674s left) gem_mmap_gtt (big-copy)
[347.760736] Subtest big-copy: SKIP (0.000s)
[347.820042] [066/132] (674s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[347.910760] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[347.911661] Starting dynamic subtest: pipe-A
[347.979159] Dynamic subtest pipe-A: SUCCESS (0.068s)
[347.989952] Starting dynamic subtest: pipe-B
[349.154807] Dynamic subtest pipe-B: SUCCESS (1.173s)
[349.164525] Starting dynamic subtest: pipe-C
[350.257527] Dynamic subtest pipe-C: SUCCESS (1.100s)
[350.266033] Starting dynamic subtest: pipe-D
[351.388916] Dynamic subtest pipe-D: SUCCESS (1.129s)
[351.389893] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.479s)
[352.569222] [067/132] (669s left) kms_chamelium_color (ctm-0-50)
[352.670746] Subtest ctm-0-50: SKIP (0.000s)
[352.706869] [068/132] (669s left) kms_psr (pr-primary-page-flip)
[352.796976] Starting subtest: pr-primary-page-flip
[352.798613] Subtest pr-primary-page-flip: SKIP (0.000s)
[352.887041] [069/132] (669s left) kms_atomic (test-only)
[352.976133] Starting subtest: test-only
[353.058262] Starting dynamic subtest: pipe-A-eDP-1
[355.554472] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.497s)
[355.555416] Subtest test-only: SUCCESS (2.578s)
[356.371426] [070/132] (665s left) gem_spin_batch (spin-each)
[356.433613] Starting subtest: spin-each
[359.700063] Subtest spin-each: SUCCESS (3.264s)
[359.987773] [071/132] (662s left) gem_exec_params (batch-first)
[360.049281] Starting subtest: batch-first
[360.059410] Subtest batch-first: SUCCESS (0.010s)
[360.223085] [072/132] (661s left) gem_busy (semaphore)
[360.417550] Subtest semaphore: SKIP (0.000s)
[360.680031] [073/132] (661s left) kms_psr (pr-primary-blt)
[360.790514] Starting subtest: pr-primary-blt
[360.792809] Subtest pr-primary-blt: SKIP (0.000s)
[360.881919] [074/132] (661s left) kms_dp_link_training (uhbr-mst)
[360.985261] Starting subtest: uhbr-mst
[360.988807] Subtest uhbr-mst: SKIP (0.000s)
[361.074210] [075/132] (661s left) i915_suspend (debugfs-reader)
[361.131034] Starting subtest: debugfs-reader
[379.974284] Subtest debugfs-reader: SUCCESS (8.086s)
[386.040458] [076/132] (642s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[386.149175] Starting subtest: compare-crc-sanitycheck-nv12
[386.151225] Starting dynamic subtest: pipe-A-eDP-1
[386.822012] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.670s)
[386.841376] Starting dynamic subtest: pipe-B-eDP-1
[388.071160] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.245s)
[388.079190] Starting dynamic subtest: pipe-C-eDP-1
[389.299995] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.227s)
[389.309589] Starting dynamic subtest: pipe-D-eDP-1
[390.498629] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.196s)
[390.499651] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.345s)
[391.381080] [077/132] (636s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[391.474854] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[391.476530] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[391.613621] [078/132] (636s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[391.708056] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[391.709698] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[392.813453] [079/132] (635s left) perf_pmu (busy-idle-check-all)
[392.859831] Starting subtest: busy-idle-check-all
[392.861026] Starting dynamic subtest: rcs0
[393.520544] Dynamic subtest rcs0: SUCCESS (0.660s)
[393.532843] Starting dynamic subtest: bcs0
[394.173215] Dynamic subtest bcs0: SUCCESS (0.650s)
[394.185367] Starting dynamic subtest: vcs0
[394.831050] Dynamic subtest vcs0: FAIL (0.632s)
[394.838715] Starting dynamic subtest: vcs1
[395.459835] Dynamic subtest vcs1: FAIL (0.620s)
[395.468197] Starting dynamic subtest: vecs0
[396.083859] Dynamic subtest vecs0: FAIL (0.623s)
[396.092414] Starting dynamic subtest: ccs0
[396.712478] Dynamic subtest ccs0: FAIL (0.627s)
[396.713387] Subtest busy-idle-check-all: FAIL (3.820s)
[397.752077] [080/132] (630s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[397.858047] Starting subtest: psr-2p-pri-indfb-multidraw
[397.859906] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[397.987915] [081/132] (630s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[398.079231] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[398.081004] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[398.193450] [082/132] (630s left) gem_exec_balancer (fairslice)
[398.255727] Starting subtest: fairslice
[401.156486] Subtest fairslice: SUCCESS (2.897s)
[401.807343] [083/132] (626s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[401.851316] Starting subtest: etime-single-wait-all-available-unsubmitted
[401.960876] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.107s)
[402.055001] [084/132] (626s left) gem_exec_fence (syncobj-timeline-chain-engines)
[402.628354] Starting subtest: syncobj-timeline-chain-engines
[402.782856] Subtest syncobj-timeline-chain-engines: SUCCESS (0.155s)
[403.808784] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[403.810812] [085/132] (624s left) gem_pread (display)
[403.861964] Subtest display: SKIP (0.000s)
[403.926154] [086/132] (624s left) gem_sync (basic-all)
[403.992603] Starting subtest: basic-all
[407.646052] Subtest basic-all: SUCCESS (3.650s)
[407.861477] [087/132] (620s left) gem_mmap_offset (isolation)
[407.919477] Starting subtest: isolation
[407.928387] Subtest isolation: SUCCESS (0.006s)
[408.100521] [088/132] (620s left) kms_atomic_interruptible (legacy-dpms)
[408.213649] Starting subtest: legacy-dpms
[408.214675] Starting dynamic subtest: pipe-A-eDP-1
[415.645771] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.429s)
[415.646647] Subtest legacy-dpms: SUCCESS (7.431s)
[416.490822] [089/132] (612s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[416.613461] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[416.615132] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[416.749995] [090/132] (612s left) gem_flink_basic (bad-open)
[416.807744] Starting subtest: bad-open
[416.808685] Subtest bad-open: SUCCESS (0.000s)
[416.932876] [091/132] (611s left) kms_invalid_mode (int-max-clock)
[417.026330] Starting subtest: int-max-clock
[417.027189] Starting dynamic subtest: pipe-A-eDP-1
[417.312321] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[417.327197] Starting dynamic subtest: pipe-B-eDP-1
[417.328081] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[417.335902] Starting dynamic subtest: pipe-C-eDP-1
[417.337007] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[417.347162] Starting dynamic subtest: pipe-D-eDP-1
[417.348254] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[417.349223] Subtest int-max-clock: SUCCESS (0.292s)
[418.217105] [092/132] (610s left) gem_exec_params (mmapped)
[418.267095] Starting subtest: mmapped
[418.269446] Subtest mmapped: SUCCESS (0.003s)
[418.426716] [093/132] (610s left) kms_plane_multiple (tiling-x)
[418.525113] Starting subtest: tiling-x
[418.525977] Starting dynamic subtest: pipe-A-eDP-1
[422.229235] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.702s)
[422.237877] Starting dynamic subtest: pipe-B-eDP-1
[426.570110] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.340s)
[426.580121] Starting dynamic subtest: pipe-C-eDP-1
[430.900143] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.327s)
[430.908421] Starting dynamic subtest: pipe-D-eDP-1
[435.243046] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.342s)
[435.244012] Subtest tiling-x: SUCCESS (16.716s)
[436.068969] [094/132] (592s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[436.163471] Starting subtest: fbc-rgb101010-draw-pwrite
[441.533757] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.369s)
[442.664381] [095/132] (586s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[442.755295] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[449.638900] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.880s)
[449.804969] [096/132] (579s left) kms_flip (2x-flip-vs-dpms)
[449.903082] Starting subtest: 2x-flip-vs-dpms
[449.913480] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[450.051409] [097/132] (578s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[450.171069] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[450.182045] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[450.312474] [098/132] (578s left) kms_content_protection (atomic-hdcp14)
[450.444729] Starting subtest: atomic-hdcp14
[450.446668] Subtest atomic-hdcp14: SKIP (0.000s)
[450.592887] [099/132] (578s left) sw_sync (sync_merge)
[450.624926] Starting subtest: sync_merge
[450.626442] Subtest sync_merge: SUCCESS (0.000s)
[450.701250] [100/132] (578s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[450.798715] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[450.799596] Starting dynamic subtest: pipe-A-default-mode
[451.082594] Dynamic subtest pipe-A-default-mode: SKIP (0.282s)
[451.084317] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.284s)
[451.914064] [101/132] (577s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[452.027159] Starting subtest: cursor-vs-flip-atomic-transitions
[482.179597] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.150s)
[483.035948] [102/132] (546s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[483.133285] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[483.134178] Starting dynamic subtest: pipe-A-eDP-1
[483.194797] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.062s)
[483.492148] Starting dynamic subtest: pipe-B-eDP-1
[484.312309] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.828s)
[484.606548] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.474s)
[485.477594] [103/132] (543s left) kms_prime (basic-crc-vgem)
[485.554302] Starting subtest: basic-crc-vgem
[485.555183] Starting dynamic subtest: second-to-first
[486.450043] Dynamic subtest second-to-first: SUCCESS (0.897s)
[486.452959] Subtest basic-crc-vgem: SUCCESS (0.899s)
[486.563770] [104/132] (542s left) kms_properties (invalid-properties-legacy)
[486.694031] Starting subtest: invalid-properties-legacy
[486.803365] Subtest invalid-properties-legacy: SUCCESS (0.107s)
[486.891441] [105/132] (542s left) syncobj_timeline (wait-zero-handles)
[486.947521] Starting subtest: wait-zero-handles
[486.948539] Subtest wait-zero-handles: SUCCESS (0.000s)
[487.083052] [106/132] (542s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[492.042072] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[492.045067] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[492.907251] [107/132] (536s left) gem_pxp (reject-modify-context-protection-off-1)
[493.030195] Starting subtest: reject-modify-context-protection-off-1
[493.031491] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[493.168210] [108/132] (536s left) kms_selftest (drm_format_helper)
[493.208463] Starting subtest: drm_format_helper
[493.218708] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[493.340933] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.121s)
[493.356514] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[493.489924] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.148s)
[493.498036] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[493.641547] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.150s)
[493.649760] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[493.787567] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.146s)
[493.795802] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[493.931753] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.143s)
[493.940039] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[494.046072] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.114s)
[494.055725] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[494.140782] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.095s)
[494.150245] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[494.267350] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.124s)
[494.276813] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[494.402278] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.134s)
[494.410360] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[494.516491] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.114s)
[494.525707] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[494.662494] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.143s)
[494.671073] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[494.800660] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.137s)
[494.808861] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[494.929097] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.129s)
[494.937003] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[495.046491] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.116s)
[495.056113] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[495.187370] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.139s)
[495.196593] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[495.322392] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.135s)
[495.330227] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[495.450044] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.128s)
[495.450926] Subtest drm_format_helper: SUCCESS (2.243s)
[495.636548] [109/132] (533s left) gen3_mixed_blits
[495.762718] [110/132] (533s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[495.865807] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[495.867431] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[495.999766] [111/132] (533s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[496.084182] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[502.837029] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.754s)
[502.982109] [112/132] (526s left) gen7_exec_parse (cmd-crossing-page)
[503.040494] Subtest cmd-crossing-page: SKIP (0.000s)
[503.117949] [113/132] (526s left) gem_softpin (overlap)
[503.179010] Starting subtest: overlap
[504.082885] Subtest overlap: SUCCESS (0.901s)
[504.245899] [114/132] (525s left) gem_eio (in-flight-internal-immediate)
[504.490209] Starting subtest: in-flight-internal-immediate
[505.720040] Subtest in-flight-internal-immediate: SUCCESS (1.229s)
[505.890382] [115/132] (523s left) gem_wait (write-wait)
[505.961102] Starting subtest: write-wait
[505.962194] Starting dynamic subtest: all-engines
[507.086334] Dynamic subtest all-engines: SUCCESS (1.125s)
[507.103177] Starting dynamic subtest: rcs0
[508.145167] Dynamic subtest rcs0: SUCCESS (1.057s)
[508.162384] Starting dynamic subtest: bcs0
[509.203378] Dynamic subtest bcs0: SUCCESS (1.056s)
[509.220642] Starting dynamic subtest: vcs0
[510.258173] Dynamic subtest vcs0: SUCCESS (1.053s)
[510.275550] Starting dynamic subtest: vcs1
[511.310921] Dynamic subtest vcs1: SUCCESS (1.051s)
[511.334355] Starting dynamic subtest: vecs0
[512.370045] Dynamic subtest vecs0: SUCCESS (1.055s)
[512.387429] Starting dynamic subtest: ccs0
[513.426976] Dynamic subtest ccs0: SUCCESS (1.055s)
[513.428262] Subtest write-wait: SUCCESS (7.468s)
[513.644918] [116/132] (515s left) kms_chamelium_edid (dp-edid-read)
[514.022274] Subtest dp-edid-read: SKIP (0.000s)
[514.792894] [117/132] (514s left) gem_exec_balancer (parallel-bb-first)
[515.044231] Starting subtest: parallel-bb-first
[515.954860] Subtest parallel-bb-first: SUCCESS (0.910s)
[516.757128] [118/132] (512s left) kms_pm_rpm (fences)
[518.878743] Starting subtest: fences
[518.881445] Subtest fences: SKIP (0.000s)
[519.229590] [119/132] (510s left) gem_ctx_persistence (legacy-engines-queued)
[519.282378] Starting subtest: legacy-engines-queued
[519.283369] Starting dynamic subtest: render
[519.372877] Dynamic subtest render: SUCCESS (0.091s)
[519.385573] Starting dynamic subtest: bsd1
[520.635054] Dynamic subtest bsd1: SUCCESS (1.257s)
[520.645974] Starting dynamic subtest: bsd2
[522.621360] Dynamic subtest bsd2: SUCCESS (1.983s)
[522.631774] Starting dynamic subtest: blt
[522.700754] Dynamic subtest blt: SUCCESS (0.080s)
[522.711950] Starting dynamic subtest: vebox
[524.667295] Dynamic subtest vebox: SUCCESS (1.962s)
[524.668724] Subtest legacy-engines-queued: SUCCESS (5.385s)
[524.762796] [120/132] (505s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[524.864442] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[525.534333] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.670s)
[525.704859] [121/132] (504s left) gem_mmap_gtt (basic-wc)
[525.769964] Subtest basic-wc: SKIP (0.000s)
[525.838138] [122/132] (504s left) core_auth (getclient-simple)
[525.868246] Starting subtest: getclient-simple
[525.897133] Subtest getclient-simple: SUCCESS (0.029s)
[526.034467] [123/132] (503s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[526.139737] Starting subtest: addfb25-y-tiled-small-legacy
[526.141708] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[526.280646] [124/132] (503s left) perf (invalid-oa-exponent)
[526.381765] Starting subtest: invalid-oa-exponent
[526.392698] Subtest invalid-oa-exponent: SUCCESS (0.011s)
[526.430816] [125/132] (503s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[526.518584] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[526.519595] Starting dynamic subtest: pipe-A-default-mode
[527.692104] Dynamic subtest pipe-A-default-mode: SKIP (1.172s)
[527.693799] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.173s)
[527.871522] [126/132] (502s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[527.968016] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[527.971192] Starting dynamic subtest: A-eDP1
[531.594204] Dynamic subtest A-eDP1: SUCCESS (3.623s)
[531.603087] Starting dynamic subtest: B-eDP1
[534.770661] Dynamic subtest B-eDP1: SUCCESS (3.175s)
[534.779253] Starting dynamic subtest: C-eDP1
[537.874161] Dynamic subtest C-eDP1: SUCCESS (3.103s)
[537.882626] Starting dynamic subtest: D-eDP1
[540.965716] Dynamic subtest D-eDP1: SUCCESS (3.090s)
[540.967953] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (12.999s)
[541.784494] [127/132] (488s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[541.844114] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[541.845259] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[541.965398] [128/132] (488s left) kms_vrr (max-min)
[542.062433] Starting subtest: max-min
[542.065262] Subtest max-min: SKIP (0.000s)
[542.154054] [129/132] (487s left) kms_rotation_crc (sprite-rotation-90)
[542.247212] Starting subtest: sprite-rotation-90
[542.248852] Subtest sprite-rotation-90: SKIP (0.001s)
[542.341784] [130/132] (487s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[542.444675] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[542.446752] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[542.574439] [131/132] (487s left) kms_cursor_crc (cursor-offscreen-64x64)
[542.738964] Starting subtest: cursor-offscreen-64x64
[542.742380] Starting dynamic subtest: pipe-A-eDP-1
[544.680826] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.937s)
[544.707608] Starting dynamic subtest: pipe-D-eDP-1
[547.276913] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.577s)
[547.278076] Subtest cursor-offscreen-64x64: SUCCESS (4.538s)
[548.141286] [132/132] (482s left) kms_color_pipeline (plane-lut1d-lut1d)
[548.237513] Starting subtest: plane-lut1d-lut1d
[548.251507] Starting dynamic subtest: pipe-A-eDP-1
[548.718333] Dynamic subtest pipe-A-eDP-1: FAIL (0.316s)
[549.590803] Starting dynamic subtest: pipe-B-eDP-1
[550.083481] Dynamic subtest pipe-B-eDP-1: FAIL (0.302s)
[550.922510] Starting dynamic subtest: pipe-C-eDP-1
[551.391195] Dynamic subtest pipe-C-eDP-1: FAIL (0.258s)
[552.257851] Starting dynamic subtest: pipe-D-eDP-1
[552.733563] Dynamic subtest pipe-D-eDP-1: FAIL (0.269s)
[552.811441] Subtest plane-lut1d-lut1d: FAIL (4.574s)
[553.668545] 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.