[52.292679] Initializing watchdogs
[52.292726]   /dev/watchdog0
[52.305498] [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]
[52.310306] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[52.312448] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[52.315787] [001/132] (960s left) gem_exec_store (cachelines)
[52.403957] Starting subtest: cachelines
[52.406101] Starting dynamic subtest: rcs0
[52.536219] Dynamic subtest rcs0: SUCCESS (0.135s)
[52.544926] Starting dynamic subtest: bcs0
[52.593844] Dynamic subtest bcs0: SUCCESS (0.058s)
[52.601148] Starting dynamic subtest: vcs0
[52.661341] Dynamic subtest vcs0: SUCCESS (0.067s)
[52.668438] Starting dynamic subtest: vcs1
[52.719853] Dynamic subtest vcs1: SUCCESS (0.059s)
[52.726628] Starting dynamic subtest: vecs0
[52.782538] Dynamic subtest vecs0: SUCCESS (0.062s)
[52.791881] Starting dynamic subtest: ccs0
[52.851052] Dynamic subtest ccs0: SUCCESS (0.069s)
[52.853774] Subtest cachelines: SUCCESS (0.455s)
[53.054488] [002/132] (959s left) gem_pread (bench)
[53.114971] Subtest bench: SKIP (0.000s)
[53.180678] [003/132] (959s left) syncobj_wait (invalid-signal-illegal-handle)
[53.237698] Starting subtest: invalid-signal-illegal-handle
[53.239961] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[53.376215] [004/132] (959s left) kms_vblank (query-forked-busy-hang)
[53.473632] Starting subtest: query-forked-busy-hang
[53.475916] Starting dynamic subtest: pipe-A-eDP-1
[79.711160] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.315s)
[79.725862] Starting dynamic subtest: pipe-D-eDP-1
[94.746147] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.036s)
[94.748202] Subtest query-forked-busy-hang: SUCCESS (41.354s)
[95.591411] [005/132] (917s left) kms_psr (fbc-pr-primary-mmap-cpu)
[95.691074] Starting subtest: fbc-pr-primary-mmap-cpu
[95.695595] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[95.790187] [006/132] (917s left) kms_plane_cursor (overlay)
[95.886092] Starting subtest: overlay
[95.890769] Starting dynamic subtest: pipe-A-eDP-1-size-64
[98.309293] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.424s)
[98.367910] Starting dynamic subtest: pipe-A-eDP-1-size-128
[100.777151] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.415s)
[100.834514] Starting dynamic subtest: pipe-A-eDP-1-size-256
[103.255682] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.426s)
[103.601890] Starting dynamic subtest: pipe-B-eDP-1-size-64
[106.718671] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.126s)
[106.778637] Starting dynamic subtest: pipe-B-eDP-1-size-128
[109.175665] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.404s)
[109.233773] Starting dynamic subtest: pipe-B-eDP-1-size-256
[111.710830] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.485s)
[112.080343] Starting dynamic subtest: pipe-C-eDP-1-size-64
[114.982708] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.910s)
[115.017698] Starting dynamic subtest: pipe-C-eDP-1-size-128
[117.148759] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.136s)
[117.184612] Starting dynamic subtest: pipe-C-eDP-1-size-256
[119.293160] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.112s)
[119.618886] Starting dynamic subtest: pipe-D-eDP-1-size-64
[122.375954] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.767s)
[122.412224] Starting dynamic subtest: pipe-D-eDP-1-size-128
[124.443113] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.036s)
[124.478994] Starting dynamic subtest: pipe-D-eDP-1-size-256
[126.588114] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.112s)
[126.924907] Subtest overlay: SUCCESS (31.043s)
[127.813430] [007/132] (885s left) perf (i915-ref-count)
[127.908187] Starting subtest: i915-ref-count
[127.981312] Subtest i915-ref-count: SUCCESS (0.071s)
[128.033892] [008/132] (885s left) gem_exec_schedule (smoketest-all)
[128.111174] Starting subtest: smoketest-all
[160.475051] Subtest smoketest-all: SUCCESS (32.317s)
[162.045748] [009/132] (851s left) gem_lmem_swapping (heavy-random)
[162.096071] Subtest heavy-random: SKIP (0.000s)
[162.145207] [010/132] (851s left) kms_psr (psr-primary-mmap-gtt)
[162.255036] Starting subtest: psr-primary-mmap-gtt
[162.257164] Starting dynamic subtest: eDP-1
[163.691793] Dynamic subtest eDP-1: SKIP (1.433s)
[163.696587] Subtest psr-primary-mmap-gtt: SKIP (1.435s)
[163.905361] [011/132] (849s left) perf (whitelisted-registers-userspace-config)
[164.011038] Starting subtest: whitelisted-registers-userspace-config
[164.013735] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[164.055454] [012/132] (849s left) gem_ppgtt (blt-vs-render-ctx0)
[164.115261] Starting subtest: blt-vs-render-ctx0
[197.135896] Subtest blt-vs-render-ctx0: SUCCESS (33.017s)
[197.307264] [013/132] (815s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[197.458373] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[197.462600] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[197.597279] [014/132] (815s left) gem_mmap_gtt (basic)
[197.667097] Subtest basic: SKIP (0.000s)
[197.732251] [015/132] (815s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[197.803897] Starting subtest: invalid-single-wait-all-unsubmitted
[197.806488] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[197.943246] [016/132] (815s left) gem_ctx_engines (execute-one)
[198.009074] Starting subtest: execute-one
[200.169957] Subtest execute-one: SUCCESS (2.161s)
[200.375806] [017/132] (813s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[200.487382] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[200.491864] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[200.643326] [018/132] (812s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[200.739221] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[200.741595] Starting dynamic subtest: pipe-A-eDP-1
[200.762609] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[200.771373] Starting dynamic subtest: pipe-B-eDP-1
[200.788811] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[200.796142] Starting dynamic subtest: pipe-C-eDP-1
[200.815790] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[200.823100] Starting dynamic subtest: pipe-D-eDP-1
[200.842661] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[200.844963] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[202.903674] [019/132] (810s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[202.962639] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[203.027651] [020/132] (810s left) api_intel_allocator (two-level-inception-interruptible)
[203.089997] Starting subtest: two-level-inception-interruptible
[208.500303] Subtest two-level-inception-interruptible: SUCCESS (5.408s)
[208.607272] [021/132] (805s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[213.665357] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[213.667703] Starting dynamic subtest: pipe-A-eDP-1
[220.281451] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.616s)
[220.291223] Starting dynamic subtest: pipe-B-eDP-1
[226.910981] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.625s)
[226.913335] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.246s)
[227.808400] [022/132] (785s left) i915_pciid
[228.004083] [023/132] (785s left) i915_pm_rpm (sysfs-read)
[230.056938] Starting subtest: sysfs-read
[231.220798] Subtest sysfs-read: SUCCESS (1.165s)
[231.623536] [024/132] (782s left) gem_exec_reloc (basic-cpu-noreloc)
[231.675862] Subtest basic-cpu-noreloc: SKIP (0.000s)
[231.752635] [025/132] (782s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[231.867065] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[231.871634] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[231.984444] [026/132] (781s left) sw_sync (timeline_closed_signaled)
[232.016457] Starting subtest: timeline_closed_signaled
[232.018686] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[232.093178] [027/132] (781s left) gem_eio (context-create)
[232.316304] Starting subtest: context-create
[232.648353] Subtest context-create: SUCCESS (0.333s)
[232.819781] [028/132] (781s left) kms_fb_coherency (memset-crc)
[232.917964] Starting subtest: memset-crc
[232.920207] Starting dynamic subtest: mmap-offset-wc
[233.337472] Dynamic subtest mmap-offset-wc: SUCCESS (0.417s)
[233.624586] Starting dynamic subtest: mmap-offset-uc
[239.631304] Dynamic subtest mmap-offset-uc: SUCCESS (6.015s)
[239.926715] Subtest memset-crc: SUCCESS (7.007s)
[240.776418] [029/132] (773s left) gem_ctx_persistence (engines-hostile-preempt)
[240.837875] Starting subtest: engines-hostile-preempt
[240.840360] Starting dynamic subtest: rcs0
[240.942340] Dynamic subtest rcs0: SUCCESS (0.103s)
[240.953171] Starting dynamic subtest: bcs0
[241.021979] Dynamic subtest bcs0: SUCCESS (0.078s)
[241.032328] Starting dynamic subtest: vcs0
[242.915198] Dynamic subtest vcs0: SUCCESS (1.890s)
[242.925764] Starting dynamic subtest: vcs1
[244.866950] Dynamic subtest vcs1: SUCCESS (1.949s)
[244.877212] Starting dynamic subtest: vecs0
[246.954808] Dynamic subtest vecs0: SUCCESS (2.085s)
[246.965712] Starting dynamic subtest: ccs0
[247.027505] Dynamic subtest ccs0: SUCCESS (0.071s)
[247.030387] Subtest engines-hostile-preempt: SUCCESS (6.190s)
[247.132452] [030/132] (766s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[247.230625] Starting subtest: psr-rgb565-draw-pwrite
[254.223439] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.995s)
[254.411128] [031/132] (759s left) gem_render_copy (y-tiled-to-vebox-linear)
[254.485663] Starting subtest: y-tiled-to-vebox-linear
[254.490098] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[254.689920] [032/132] (759s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[254.749524] Starting subtest: multi-wait-for-submit-submitted-signaled
[254.751886] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[254.889104] [033/132] (759s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[254.993389] Starting subtest: x-tiled-64bpp-rotate-90
[255.016690] Subtest x-tiled-64bpp-rotate-90: SKIP (0.023s)
[255.165091] [034/132] (758s left) kms_psr (fbc-psr2-cursor-render)
[255.260305] Starting subtest: fbc-psr2-cursor-render
[255.262176] Starting dynamic subtest: eDP-1
[255.266158] Dynamic subtest eDP-1: SKIP (0.000s)
[255.270109] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[255.352537] [035/132] (758s left) kms_getfb (getfb-handle-protection)
[255.439142] Starting subtest: getfb-handle-protection
[255.463667] Subtest getfb-handle-protection: SUCCESS (0.026s)
[255.614163] [036/132] (758s left) sw_sync (sync_merge_invalid)
[255.656291] Starting subtest: sync_merge_invalid
[255.678439] Subtest sync_merge_invalid: SUCCESS (0.026s)
[255.767753] [037/132] (758s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[255.875981] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[260.482448] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.608s)
[261.634014] [038/132] (752s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[261.744315] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[268.883412] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.143s)
[269.051107] [039/132] (745s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[269.178302] Starting subtest: cursorB-vs-flipA-toggle
[269.182778] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[269.346423] [040/132] (745s left) kms_rmfb (rmfb-ioctl)
[269.443723] Starting subtest: rmfb-ioctl
[269.445929] Starting dynamic subtest: pipe-A-eDP-1
[269.549738] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.107s)
[269.562722] Starting dynamic subtest: pipe-B-eDP-1
[270.686132] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.133s)
[270.697443] Starting dynamic subtest: pipe-C-eDP-1
[271.796696] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.107s)
[271.809259] Starting dynamic subtest: pipe-D-eDP-1
[273.040538] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.239s)
[273.043101] Subtest rmfb-ioctl: SUCCESS (3.598s)
[274.280131] [041/132] (740s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[274.681847] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[275.469518] [042/132] (738s left) sysfs_timeslice_duration (invalid)
[275.533630] Starting subtest: invalid
[275.536114] Starting dynamic subtest: vecs0
[275.538551] Dynamic subtest vecs0: SUCCESS (0.000s)
[275.547844] Starting dynamic subtest: ccs0
[275.549852] Dynamic subtest ccs0: SUCCESS (0.000s)
[275.557150] Starting dynamic subtest: vcs0
[275.559069] Dynamic subtest vcs0: SUCCESS (0.000s)
[275.566025] Starting dynamic subtest: bcs0
[275.568028] Dynamic subtest bcs0: SUCCESS (0.000s)
[275.575119] Starting dynamic subtest: rcs0
[275.577077] Dynamic subtest rcs0: SUCCESS (0.000s)
[275.584112] Starting dynamic subtest: vcs1
[275.586046] Dynamic subtest vcs1: SUCCESS (0.000s)
[275.587972] Subtest invalid: SUCCESS (0.005s)
[275.674431] [043/132] (738s left) syncobj_eventfd (timeline-wait)
[275.738077] Starting subtest: timeline-wait
[275.747617] Subtest timeline-wait: SUCCESS (0.011s)
[275.882863] [044/132] (738s left) kms_pm_backlight (brightness-with-dpms)
[275.981896] Starting subtest: brightness-with-dpms
[276.034663] Starting dynamic subtest: eDP-1
[278.687920] Dynamic subtest eDP-1: SUCCESS (2.655s)
[278.690263] Subtest brightness-with-dpms: SUCCESS (2.708s)
[279.571017] [045/132] (734s left) kms_busy (extended-modeset-hang-oldfb)
[279.664308] Starting subtest: extended-modeset-hang-oldfb
[279.666626] Starting dynamic subtest: pipe-A
[291.237743] Dynamic subtest pipe-A: SUCCESS (11.571s)
[291.245829] Starting dynamic subtest: pipe-D
[303.518993] Dynamic subtest pipe-D: SUCCESS (12.277s)
[303.521394] Subtest extended-modeset-hang-oldfb: SUCCESS (23.853s)
[304.419717] [046/132] (710s left) syncobj_wait (multi-wait-all-signaled)
[304.481523] Starting subtest: multi-wait-all-signaled
[304.483972] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[304.633833] [047/132] (710s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[304.741381] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[304.743625] Starting dynamic subtest: pipe-A-eDP-1
[304.766268] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[304.776145] Starting dynamic subtest: pipe-B-eDP-1
[304.797580] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[304.805669] Starting dynamic subtest: pipe-C-eDP-1
[304.828831] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[304.837800] Starting dynamic subtest: pipe-D-eDP-1
[304.860294] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[304.864387] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[307.081412] [048/132] (707s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[307.185107] Starting subtest: yf-tiled-8bpp-rotate-180
[307.189690] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[307.305167] [049/132] (707s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[307.416854] Starting subtest: psr-rgb565-draw-mmap-cpu
[314.086284] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.672s)
[314.210419] [050/132] (700s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[314.312607] Starting subtest: yf-tiled-16bpp-rotate-90
[314.317086] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[314.452764] [051/132] (700s left) kms_addfb_basic (clobberred-modifier)
[314.515065] Starting subtest: clobberred-modifier
[314.519516] Subtest clobberred-modifier: SKIP (0.000s)
[314.687178] [052/132] (700s left) gem_exec_reloc (basic-write-read-active)
[314.752233] Subtest basic-write-read-active: SKIP (0.000s)
[314.818966] [053/132] (700s left) dmabuf (all-tests)
[314.861391] Starting subtest: all-tests
[314.863781] Starting dynamic subtest: sanitycheck
[314.993938] Dynamic subtest sanitycheck: SUCCESS (0.130s)
[315.004994] Starting dynamic subtest: dma_fence
[315.282668] Dynamic subtest dma_fence: SUCCESS (0.287s)
[315.293932] Starting dynamic subtest: dma_fence_chain
[323.693354] Dynamic subtest dma_fence_chain: SUCCESS (8.408s)
[323.704412] Starting dynamic subtest: dma_fence_unwrap
[323.834225] Dynamic subtest dma_fence_unwrap: SUCCESS (0.139s)
[323.845060] Starting dynamic subtest: dma_resv
[323.988043] Dynamic subtest dma_resv: SUCCESS (0.151s)
[323.990673] Subtest all-tests: SUCCESS (9.127s)
[324.077311] [054/132] (690s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[324.400794] Starting subtest: cursor-rapid-movement-512x170
[324.405428] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[324.585352] [055/132] (690s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[324.645816] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[324.648191] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[324.772134] [056/132] (690s left) kms_big_fb (linear-16bpp-rotate-90)
[324.885843] Starting subtest: linear-16bpp-rotate-90
[324.906043] Subtest linear-16bpp-rotate-90: SKIP (0.020s)
[325.049882] [057/132] (689s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[325.143561] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[325.147144] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[325.273612] [058/132] (689s left) gem_exec_schedule (noreorder-priority)
[325.456170] Starting subtest: noreorder-priority
[325.458763] Starting dynamic subtest: rcs0
[326.620960] Dynamic subtest rcs0: SUCCESS (1.165s)
[326.632909] Starting dynamic subtest: vcs0
[327.912390] Dynamic subtest vcs0: SUCCESS (1.288s)
[327.924780] Starting dynamic subtest: vcs1
[329.925086] Dynamic subtest vcs1: SUCCESS (2.010s)
[329.936187] Starting dynamic subtest: vecs0
[331.912185] Dynamic subtest vecs0: SUCCESS (1.985s)
[331.922995] Starting dynamic subtest: ccs0
[333.085750] Dynamic subtest ccs0: SUCCESS (1.173s)
[333.088276] Subtest noreorder-priority: SUCCESS (7.632s)
[333.294635] [059/132] (681s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[333.416936] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[340.046462] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.631s)
[340.206699] [060/132] (674s left) perf (polling-small-buf)
[340.333164] Starting subtest: polling-small-buf
[340.422994] Subtest polling-small-buf: SUCCESS (0.087s)
[340.464766] [061/132] (674s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[340.549432] Starting subtest: bad-pixel-format-y-tiled-ccs
[340.551747] Starting dynamic subtest: pipe-A-eDP-1
[340.575427] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[340.589173] Starting dynamic subtest: pipe-B-eDP-1
[340.611582] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[340.620739] Starting dynamic subtest: pipe-C-eDP-1
[340.642439] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[340.650346] Starting dynamic subtest: pipe-D-eDP-1
[340.672954] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[340.677129] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[342.800430] [062/132] (672s left) i915_drm_fdinfo (busy-hang)
[342.847805] Starting subtest: busy-hang
[342.850195] Starting dynamic subtest: rcs0
[342.887714] Dynamic subtest rcs0: SKIP (0.038s)
[342.899764] Starting dynamic subtest: bcs0
[342.904376] Dynamic subtest bcs0: SKIP (0.001s)
[342.912647] Starting dynamic subtest: vcs0
[342.917090] Dynamic subtest vcs0: SKIP (0.000s)
[342.924908] Starting dynamic subtest: vcs1
[342.929218] Dynamic subtest vcs1: SKIP (0.000s)
[342.937084] Starting dynamic subtest: vecs0
[342.941375] Dynamic subtest vecs0: SKIP (0.000s)
[342.949687] Starting dynamic subtest: ccs0
[342.954578] Dynamic subtest ccs0: SKIP (0.000s)
[342.959211] Subtest busy-hang: SKIP (0.045s)
[342.995033] [063/132] (672s left) kms_cursor_crc (cursor-sliding-max-size)
[343.313172] Starting subtest: cursor-sliding-max-size
[343.319633] Subtest cursor-sliding-max-size: SKIP (0.000s)
[343.475609] [064/132] (671s left) gem_exec_schedule (preempt-queue-contexts-chain)
[343.554921] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[343.861714] [065/132] (671s left) gem_mmap_gtt (big-copy)
[343.929023] Subtest big-copy: SKIP (0.000s)
[343.997067] [066/132] (671s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[344.086415] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[344.088743] Starting dynamic subtest: pipe-A
[344.148479] Dynamic subtest pipe-A: SUCCESS (0.064s)
[344.156757] Starting dynamic subtest: pipe-B
[345.302076] Dynamic subtest pipe-B: SUCCESS (1.152s)
[345.311842] Starting dynamic subtest: pipe-C
[346.420568] Dynamic subtest pipe-C: SUCCESS (1.116s)
[346.429130] Starting dynamic subtest: pipe-D
[347.545111] Dynamic subtest pipe-D: SUCCESS (1.123s)
[347.547530] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.461s)
[348.730162] [067/132] (666s left) kms_chamelium_color (ctm-0-50)
[348.836578] Subtest ctm-0-50: SKIP (0.000s)
[348.880153] [068/132] (666s left) kms_psr (pr-primary-page-flip)
[348.972212] Starting subtest: pr-primary-page-flip
[348.976181] Subtest pr-primary-page-flip: SKIP (0.000s)
[349.069751] [069/132] (666s left) kms_atomic (test-only)
[349.168088] Starting subtest: test-only
[349.215785] Starting dynamic subtest: pipe-A-eDP-1
[351.713648] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.497s)
[351.715676] Subtest test-only: SUCCESS (2.545s)
[352.529562] [070/132] (663s left) gem_spin_batch (spin-each)
[352.592764] Starting subtest: spin-each
[355.859213] Subtest spin-each: SUCCESS (3.260s)
[357.984707] [071/132] (657s left) gem_exec_params (batch-first)
[358.058122] Starting subtest: batch-first
[358.065454] Subtest batch-first: SUCCESS (0.010s)
[358.247356] [072/132] (657s left) gem_busy (semaphore)
[358.460429] Subtest semaphore: SKIP (0.000s)
[358.721966] [073/132] (656s left) kms_psr (pr-primary-blt)
[358.819410] Starting subtest: pr-primary-blt
[358.823830] Subtest pr-primary-blt: SKIP (0.000s)
[358.917453] [074/132] (656s left) kms_dp_link_training (uhbr-mst)
[359.018172] Starting subtest: uhbr-mst
[359.026699] Subtest uhbr-mst: SKIP (0.000s)
[359.111908] [075/132] (656s left) i915_suspend (debugfs-reader)
[359.175204] Starting subtest: debugfs-reader
[377.184471] Subtest debugfs-reader: SUCCESS (6.874s)
[377.251443] [076/132] (638s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[377.358412] Starting subtest: compare-crc-sanitycheck-nv12
[377.361054] Starting dynamic subtest: pipe-A-eDP-1
[378.029783] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.685s)
[378.038497] Starting dynamic subtest: pipe-B-eDP-1
[379.296778] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.291s)
[379.306342] Starting dynamic subtest: pipe-C-eDP-1
[380.534378] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.256s)
[380.546152] Starting dynamic subtest: pipe-D-eDP-1
[381.758238] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.237s)
[381.760531] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.478s)
[382.609532] [077/132] (633s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[382.725916] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[382.730324] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[382.868571] [078/132] (633s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[382.975599] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[382.980100] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[384.133676] [079/132] (631s left) perf_pmu (busy-idle-check-all)
[384.175991] Starting subtest: busy-idle-check-all
[384.178286] Starting dynamic subtest: rcs0
[384.842180] Dynamic subtest rcs0: SUCCESS (0.673s)
[384.854747] Starting dynamic subtest: bcs0
[385.494665] Dynamic subtest bcs0: SUCCESS (0.656s)
[385.505841] Starting dynamic subtest: vcs0
[386.204608] Dynamic subtest vcs0: FAIL (0.644s)
[386.215253] Starting dynamic subtest: vcs1
[386.840359] Dynamic subtest vcs1: FAIL (0.623s)
[386.851084] Starting dynamic subtest: vecs0
[387.462398] Dynamic subtest vecs0: FAIL (0.629s)
[387.473389] Starting dynamic subtest: ccs0
[388.088661] Dynamic subtest ccs0: FAIL (0.627s)
[388.090898] Subtest busy-idle-check-all: FAIL (3.859s)
[389.127309] [080/132] (626s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[389.240039] Starting subtest: psr-2p-pri-indfb-multidraw
[389.244003] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[389.356686] [081/132] (626s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[389.450743] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[389.454982] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[389.583897] [082/132] (626s left) gem_exec_balancer (fairslice)
[389.660874] Starting subtest: fairslice
[392.543073] Subtest fairslice: SUCCESS (2.893s)
[393.121534] [083/132] (622s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[393.192235] Starting subtest: etime-single-wait-all-available-unsubmitted
[393.295382] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.103s)
[393.397117] [084/132] (622s left) gem_exec_fence (syncobj-timeline-chain-engines)
[393.995310] Starting subtest: syncobj-timeline-chain-engines
[394.183932] Subtest syncobj-timeline-chain-engines: SUCCESS (0.192s)
[395.132692] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[395.136313] [085/132] (621s left) gem_pread (display)
[395.208364] Subtest display: SKIP (0.000s)
[395.277966] [086/132] (620s left) gem_sync (basic-all)
[395.361426] Starting subtest: basic-all
[399.011086] Subtest basic-all: SUCCESS (3.665s)
[399.219675] [087/132] (617s left) gem_mmap_offset (isolation)
[399.275673] Starting subtest: isolation
[399.293711] Subtest isolation: SUCCESS (0.006s)
[399.416269] [088/132] (616s left) kms_atomic_interruptible (legacy-dpms)
[399.523279] Starting subtest: legacy-dpms
[399.525696] Starting dynamic subtest: pipe-A-eDP-1
[406.985009] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.470s)
[406.987418] Subtest legacy-dpms: SUCCESS (7.471s)
[407.835535] [089/132] (608s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[407.942063] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[407.946395] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[408.069386] [090/132] (608s left) gem_flink_basic (bad-open)
[408.121784] Starting subtest: bad-open
[408.124141] Subtest bad-open: SUCCESS (0.000s)
[408.230744] [091/132] (608s left) kms_invalid_mode (int-max-clock)
[408.330782] Starting subtest: int-max-clock
[408.333130] Starting dynamic subtest: pipe-A-eDP-1
[408.618914] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.290s)
[408.628473] Starting dynamic subtest: pipe-B-eDP-1
[408.630772] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[408.641081] Starting dynamic subtest: pipe-C-eDP-1
[408.643410] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[408.652745] Starting dynamic subtest: pipe-D-eDP-1
[408.655182] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[408.657432] Subtest int-max-clock: SUCCESS (0.296s)
[409.474710] [092/132] (607s left) gem_exec_params (mmapped)
[409.543233] Starting subtest: mmapped
[409.545871] Subtest mmapped: SUCCESS (0.002s)
[409.723575] [093/132] (606s left) kms_plane_multiple (tiling-x)
[409.835583] Starting subtest: tiling-x
[409.837779] Starting dynamic subtest: pipe-A-eDP-1
[413.474659] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.636s)
[413.485116] Starting dynamic subtest: pipe-B-eDP-1
[417.889850] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.416s)
[417.900522] Starting dynamic subtest: pipe-C-eDP-1
[422.194574] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.303s)
[422.204045] Starting dynamic subtest: pipe-D-eDP-1
[426.559801] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.365s)
[426.562218] Subtest tiling-x: SUCCESS (16.725s)
[427.388708] [094/132] (589s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[427.498235] Starting subtest: fbc-rgb101010-draw-pwrite
[432.786899] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.293s)
[433.992205] [095/132] (582s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[434.104032] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[440.834369] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.734s)
[441.032516] [096/132] (575s left) kms_flip (2x-flip-vs-dpms)
[441.149567] Starting subtest: 2x-flip-vs-dpms
[441.157523] Subtest 2x-flip-vs-dpms: SKIP (0.010s)
[441.260630] [097/132] (575s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[441.379548] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[441.406001] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.024s)
[441.530238] [098/132] (575s left) kms_content_protection (atomic-hdcp14)
[441.680757] Starting subtest: atomic-hdcp14
[441.685287] Subtest atomic-hdcp14: SKIP (0.000s)
[441.848350] [099/132] (574s left) sw_sync (sync_merge)
[441.890719] Starting subtest: sync_merge
[441.892525] Subtest sync_merge: SUCCESS (0.000s)
[441.977208] [100/132] (574s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[442.071914] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[442.073991] Starting dynamic subtest: pipe-A-default-mode
[442.359320] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[442.364081] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.285s)
[443.233518] [101/132] (573s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[443.361853] Starting subtest: cursor-vs-flip-atomic-transitions
[473.046263] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (29.693s)
[473.950275] [102/132] (542s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[474.050614] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[474.052983] Starting dynamic subtest: pipe-A-eDP-1
[474.112385] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.063s)
[474.423846] Starting dynamic subtest: pipe-B-eDP-1
[475.229340] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.815s)
[475.513771] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.464s)
[476.406652] [103/132] (540s left) kms_prime (basic-crc-vgem)
[476.495898] Starting subtest: basic-crc-vgem
[476.498334] Starting dynamic subtest: second-to-first
[477.412074] Dynamic subtest second-to-first: SUCCESS (0.919s)
[477.414389] Subtest basic-crc-vgem: SUCCESS (0.921s)
[477.534394] [104/132] (539s left) kms_properties (invalid-properties-legacy)
[477.626085] Starting subtest: invalid-properties-legacy
[477.729375] Subtest invalid-properties-legacy: SUCCESS (0.102s)
[477.826207] [105/132] (539s left) syncobj_timeline (wait-zero-handles)
[477.884353] Starting subtest: wait-zero-handles
[477.886273] Subtest wait-zero-handles: SUCCESS (0.000s)
[478.005856] [106/132] (539s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[483.058912] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[483.063822] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[483.925345] [107/132] (533s left) gem_pxp (reject-modify-context-protection-off-1)
[484.063813] Starting subtest: reject-modify-context-protection-off-1
[484.066389] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[484.216944] [108/132] (532s left) kms_selftest (drm_format_helper)
[484.265248] Starting subtest: drm_format_helper
[484.276487] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[484.426497] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.148s)
[484.437097] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[484.561708] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.135s)
[484.572072] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[484.695288] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.131s)
[484.704265] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[484.830422] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.134s)
[484.840886] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[484.921651] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.092s)
[484.932413] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[485.066453] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.143s)
[485.075352] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[485.222441] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.155s)
[485.231525] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[485.359942] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.138s)
[485.369123] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[485.500228] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.137s)
[485.509376] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[485.648592] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.147s)
[485.657682] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[485.797822] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.149s)
[485.806871] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[485.948612] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.150s)
[485.958005] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[486.099798] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.151s)
[486.108941] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[486.252613] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.152s)
[486.261179] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[486.391887] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.136s)
[486.401899] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[486.526599] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.135s)
[486.537447] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[486.682521] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.156s)
[486.684733] Subtest drm_format_helper: SUCCESS (2.416s)
[486.910335] [109/132] (530s left) gen3_mixed_blits
[487.053579] [110/132] (530s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[487.160198] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[487.164420] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[487.301746] [111/132] (530s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[487.409125] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[494.149554] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.742s)
[494.321093] [112/132] (523s left) gen7_exec_parse (cmd-crossing-page)
[494.382969] Subtest cmd-crossing-page: SKIP (0.000s)
[494.465471] [113/132] (522s left) gem_softpin (overlap)
[494.523719] Starting subtest: overlap
[495.402431] Subtest overlap: SUCCESS (0.880s)
[495.578883] [114/132] (521s left) gem_eio (in-flight-internal-immediate)
[495.818686] Starting subtest: in-flight-internal-immediate
[497.149127] Subtest in-flight-internal-immediate: SUCCESS (1.329s)
[497.323425] [115/132] (520s left) gem_wait (write-wait)
[497.397863] Starting subtest: write-wait
[497.400366] Starting dynamic subtest: all-engines
[498.529149] Dynamic subtest all-engines: SUCCESS (1.130s)
[498.546566] Starting dynamic subtest: rcs0
[499.586985] Dynamic subtest rcs0: SUCCESS (1.057s)
[499.604222] Starting dynamic subtest: bcs0
[500.649589] Dynamic subtest bcs0: SUCCESS (1.060s)
[500.674095] Starting dynamic subtest: vcs0
[501.705943] Dynamic subtest vcs0: SUCCESS (1.053s)
[501.723510] Starting dynamic subtest: vcs1
[502.761760] Dynamic subtest vcs1: SUCCESS (1.054s)
[502.784839] Starting dynamic subtest: vecs0
[503.821128] Dynamic subtest vecs0: SUCCESS (1.057s)
[503.844249] Starting dynamic subtest: ccs0
[504.882286] Dynamic subtest ccs0: SUCCESS (1.059s)
[504.884810] Subtest write-wait: SUCCESS (7.485s)
[505.107524] [116/132] (512s left) kms_chamelium_edid (dp-edid-read)
[505.509212] Subtest dp-edid-read: SKIP (0.000s)
[506.279641] [117/132] (511s left) gem_exec_balancer (parallel-bb-first)
[506.543465] Starting subtest: parallel-bb-first
[507.440006] Subtest parallel-bb-first: SUCCESS (0.900s)
[508.138983] [118/132] (509s left) kms_pm_rpm (fences)
[510.166298] Starting subtest: fences
[510.171384] Subtest fences: SKIP (0.000s)
[510.569905] [119/132] (507s left) gem_ctx_persistence (legacy-engines-queued)
[510.624542] Starting subtest: legacy-engines-queued
[510.626985] Starting dynamic subtest: render
[510.720063] Dynamic subtest render: SUCCESS (0.095s)
[510.735877] Starting dynamic subtest: bsd1
[512.002499] Dynamic subtest bsd1: SUCCESS (1.277s)
[512.015814] Starting dynamic subtest: bsd2
[514.049615] Dynamic subtest bsd2: SUCCESS (2.044s)
[514.062224] Starting dynamic subtest: blt
[514.121244] Dynamic subtest blt: SUCCESS (0.069s)
[514.133345] Starting dynamic subtest: vebox
[516.034011] Dynamic subtest vebox: SUCCESS (1.908s)
[516.036947] Subtest legacy-engines-queued: SUCCESS (5.409s)
[516.137955] [120/132] (501s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[516.242167] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[516.899597] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.659s)
[517.099456] [121/132] (500s left) gem_mmap_gtt (basic-wc)
[517.155744] Subtest basic-wc: SKIP (0.000s)
[517.218330] [122/132] (500s left) core_auth (getclient-simple)
[517.251154] Starting subtest: getclient-simple
[517.275441] Subtest getclient-simple: SUCCESS (0.023s)
[517.407503] [123/132] (500s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[517.510946] Starting subtest: addfb25-y-tiled-small-legacy
[517.515482] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[517.639553] [124/132] (500s left) perf (invalid-oa-exponent)
[517.752894] Starting subtest: invalid-oa-exponent
[517.767484] Subtest invalid-oa-exponent: SUCCESS (0.013s)
[517.808561] [125/132] (500s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[517.894119] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[517.896092] Starting dynamic subtest: pipe-A-default-mode
[519.115523] Dynamic subtest pipe-A-default-mode: SKIP (1.218s)
[519.120181] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.221s)
[519.337250] [126/132] (498s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[519.438876] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[519.440775] Starting dynamic subtest: A-eDP1
[523.081373] Dynamic subtest A-eDP1: SUCCESS (3.645s)
[523.093847] Starting dynamic subtest: B-eDP1
[526.265738] Dynamic subtest B-eDP1: SUCCESS (3.181s)
[526.281483] Starting dynamic subtest: C-eDP1
[529.372529] Dynamic subtest C-eDP1: SUCCESS (3.105s)
[529.385449] Starting dynamic subtest: D-eDP1
[532.473669] Dynamic subtest D-eDP1: SUCCESS (3.101s)
[532.475691] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.040s)
[533.300089] [127/132] (484s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[533.358241] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[533.360483] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[533.482109] [128/132] (484s left) kms_vrr (max-min)
[533.575113] Starting subtest: max-min
[533.581402] Subtest max-min: SKIP (0.000s)
[533.674729] [129/132] (484s left) kms_rotation_crc (sprite-rotation-90)
[533.769070] Starting subtest: sprite-rotation-90
[533.773667] Subtest sprite-rotation-90: SKIP (0.001s)
[533.872244] [130/132] (484s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[533.981720] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[533.985918] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[534.120374] [131/132] (484s left) kms_cursor_crc (cursor-offscreen-64x64)
[534.308633] Starting subtest: cursor-offscreen-64x64
[534.310631] Starting dynamic subtest: pipe-A-eDP-1
[536.241547] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.931s)
[536.271634] Starting dynamic subtest: pipe-D-eDP-1
[538.864737] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.600s)
[538.866732] Subtest cursor-offscreen-64x64: SUCCESS (4.556s)
[539.757730] [132/132] (478s left) kms_color_pipeline (plane-lut1d-lut1d)
[539.861049] Starting subtest: plane-lut1d-lut1d
[539.877170] Starting dynamic subtest: pipe-A-eDP-1
[540.582824] Dynamic subtest pipe-A-eDP-1: FAIL (0.305s)
[541.217368] Starting dynamic subtest: pipe-B-eDP-1
[542.052280] Dynamic subtest pipe-B-eDP-1: FAIL (0.313s)
[542.644881] Starting dynamic subtest: pipe-C-eDP-1
[543.541685] Dynamic subtest pipe-C-eDP-1: FAIL (0.301s)
[543.982793] Starting dynamic subtest: pipe-D-eDP-1
[544.826491] Dynamic subtest pipe-D-eDP-1: FAIL (0.257s)
[544.828502] Subtest plane-lut1d-lut1d: FAIL (4.649s)
[545.376407] 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.