[58.108620] Initializing watchdogs
[58.108669]   /dev/watchdog0
[58.123028] [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]
[58.127465] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.129835] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.133218] [001/132] (960s left) gem_exec_store (cachelines)
[58.225040] Starting subtest: cachelines
[58.227428] Starting dynamic subtest: rcs0
[58.328333] Dynamic subtest rcs0: SUCCESS (0.103s)
[58.337372] Starting dynamic subtest: bcs0
[58.391062] Dynamic subtest bcs0: SUCCESS (0.063s)
[58.400147] Starting dynamic subtest: vcs0
[58.452744] Dynamic subtest vcs0: SUCCESS (0.061s)
[58.462101] Starting dynamic subtest: vcs1
[58.517694] Dynamic subtest vcs1: SUCCESS (0.064s)
[58.525503] Starting dynamic subtest: vecs0
[58.580734] Dynamic subtest vecs0: SUCCESS (0.063s)
[58.589502] Starting dynamic subtest: ccs0
[58.642269] Dynamic subtest ccs0: SUCCESS (0.060s)
[58.644757] Subtest cachelines: SUCCESS (0.418s)
[58.847809] [002/132] (959s left) gem_pread (bench)
[58.902879] Subtest bench: SKIP (0.000s)
[58.955467] [003/132] (959s left) syncobj_wait (invalid-signal-illegal-handle)
[59.010053] Starting subtest: invalid-signal-illegal-handle
[59.012404] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[59.125876] [004/132] (959s left) kms_vblank (query-forked-busy-hang)
[59.220692] Starting subtest: query-forked-busy-hang
[59.222594] Starting dynamic subtest: pipe-A-eDP-1
[85.652801] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.433s)
[85.665737] Starting dynamic subtest: pipe-D-eDP-1
[100.694465] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.039s)
[100.696487] Subtest query-forked-busy-hang: SUCCESS (41.476s)
[101.560234] [005/132] (917s left) kms_psr (fbc-pr-primary-mmap-cpu)
[101.654463] Starting subtest: fbc-pr-primary-mmap-cpu
[101.658980] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[101.743075] [006/132] (917s left) kms_plane_cursor (overlay)
[101.833089] Starting subtest: overlay
[101.837228] Starting dynamic subtest: pipe-A-eDP-1-size-64
[104.344744] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.513s)
[104.402135] Starting dynamic subtest: pipe-A-eDP-1-size-128
[106.934952] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.538s)
[106.992525] Starting dynamic subtest: pipe-A-eDP-1-size-256
[109.447137] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.460s)
[109.795503] Starting dynamic subtest: pipe-B-eDP-1-size-64
[112.993510] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.207s)
[113.051259] Starting dynamic subtest: pipe-B-eDP-1-size-128
[115.549486] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.504s)
[115.608083] Starting dynamic subtest: pipe-B-eDP-1-size-256
[118.139821] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.538s)
[118.485816] Starting dynamic subtest: pipe-C-eDP-1-size-64
[121.290587] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.811s)
[121.326713] Starting dynamic subtest: pipe-C-eDP-1-size-128
[123.435639] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.113s)
[123.471497] Starting dynamic subtest: pipe-C-eDP-1-size-256
[125.601442] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.135s)
[125.927157] Starting dynamic subtest: pipe-D-eDP-1-size-64
[128.721483] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.803s)
[128.758751] Starting dynamic subtest: pipe-D-eDP-1-size-128
[130.866316] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.113s)
[130.901966] Starting dynamic subtest: pipe-D-eDP-1-size-256
[132.965841] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.069s)
[133.286944] Subtest overlay: SUCCESS (31.454s)
[134.165849] [007/132] (884s left) perf (i915-ref-count)
[134.256848] Starting subtest: i915-ref-count
[134.328871] Subtest i915-ref-count: SUCCESS (0.070s)
[134.381615] [008/132] (884s left) gem_exec_schedule (smoketest-all)
[134.459716] Starting subtest: smoketest-all
[166.835533] Subtest smoketest-all: SUCCESS (32.334s)
[168.046763] [009/132] (850s left) gem_lmem_swapping (heavy-random)
[168.095069] Subtest heavy-random: SKIP (0.000s)
[168.139234] [010/132] (850s left) kms_psr (psr-primary-mmap-gtt)
[168.242055] Starting subtest: psr-primary-mmap-gtt
[168.244297] Starting dynamic subtest: eDP-1
[169.636033] Dynamic subtest eDP-1: SKIP (1.392s)
[169.640370] Subtest psr-primary-mmap-gtt: SKIP (1.393s)
[169.836738] [011/132] (849s left) perf (whitelisted-registers-userspace-config)
[169.934468] Starting subtest: whitelisted-registers-userspace-config
[169.936829] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[169.978307] [012/132] (849s left) gem_ppgtt (blt-vs-render-ctx0)
[170.043052] Starting subtest: blt-vs-render-ctx0
[203.057650] Subtest blt-vs-render-ctx0: SUCCESS (33.011s)
[203.217151] [013/132] (815s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[203.367855] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[203.372559] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[203.514463] [014/132] (815s left) gem_mmap_gtt (basic)
[203.574825] Subtest basic: SKIP (0.000s)
[203.648387] [015/132] (815s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[203.719199] Starting subtest: invalid-single-wait-all-unsubmitted
[203.721647] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[203.860876] [016/132] (815s left) gem_ctx_engines (execute-one)
[203.924349] Starting subtest: execute-one
[206.335112] Subtest execute-one: SUCCESS (2.414s)
[206.543019] [017/132] (812s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[206.654626] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[206.659159] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[206.789792] [018/132] (812s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[206.889158] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[206.894613] Starting dynamic subtest: pipe-A-eDP-1
[206.914762] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[206.923557] Starting dynamic subtest: pipe-B-eDP-1
[206.943049] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[206.952268] Starting dynamic subtest: pipe-C-eDP-1
[206.971578] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[206.979622] Starting dynamic subtest: pipe-D-eDP-1
[206.999141] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[207.001302] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[209.136851] [019/132] (810s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[209.204564] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[209.278749] [020/132] (810s left) api_intel_allocator (two-level-inception-interruptible)
[209.344037] Starting subtest: two-level-inception-interruptible
[214.750484] Subtest two-level-inception-interruptible: SUCCESS (5.405s)
[214.917038] [021/132] (804s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[219.973433] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[219.975961] Starting dynamic subtest: pipe-A-eDP-1
[226.533590] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.560s)
[226.542546] Starting dynamic subtest: pipe-B-eDP-1
[233.213629] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.678s)
[233.216064] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.242s)
[234.074505] [022/132] (785s left) i915_pciid
[234.251182] [023/132] (785s left) i915_pm_rpm (sysfs-read)
[236.063190] Starting subtest: sysfs-read
[237.236047] Subtest sysfs-read: SUCCESS (1.167s)
[237.630042] [024/132] (781s left) gem_exec_reloc (basic-cpu-noreloc)
[237.703822] Subtest basic-cpu-noreloc: SKIP (0.000s)
[237.780332] [025/132] (781s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[237.883442] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[237.888340] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[238.046156] [026/132] (781s left) sw_sync (timeline_closed_signaled)
[238.084456] Starting subtest: timeline_closed_signaled
[238.086914] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[238.170713] [027/132] (781s left) gem_eio (context-create)
[238.412072] Starting subtest: context-create
[238.774850] Subtest context-create: SUCCESS (0.364s)
[238.925570] [028/132] (780s left) kms_fb_coherency (memset-crc)
[239.026268] Starting subtest: memset-crc
[239.028163] Starting dynamic subtest: mmap-offset-wc
[239.412705] Dynamic subtest mmap-offset-wc: SUCCESS (0.386s)
[239.714847] Starting dynamic subtest: mmap-offset-uc
[245.922668] Dynamic subtest mmap-offset-uc: SUCCESS (6.214s)
[246.203688] Subtest memset-crc: SUCCESS (7.176s)
[247.077548] [029/132] (772s left) gem_ctx_persistence (engines-hostile-preempt)
[247.129726] Starting subtest: engines-hostile-preempt
[247.132057] Starting dynamic subtest: rcs0
[247.212391] Dynamic subtest rcs0: SUCCESS (0.082s)
[247.223727] Starting dynamic subtest: bcs0
[247.277256] Dynamic subtest bcs0: SUCCESS (0.063s)
[247.287154] Starting dynamic subtest: vcs0
[248.939312] Dynamic subtest vcs0: SUCCESS (1.658s)
[248.950070] Starting dynamic subtest: vcs1
[250.889252] Dynamic subtest vcs1: SUCCESS (1.947s)
[250.900070] Starting dynamic subtest: vecs0
[252.897299] Dynamic subtest vecs0: SUCCESS (2.005s)
[252.908235] Starting dynamic subtest: ccs0
[252.983977] Dynamic subtest ccs0: SUCCESS (0.085s)
[252.986575] Subtest engines-hostile-preempt: SUCCESS (5.855s)
[253.082977] [030/132] (766s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[253.199347] Starting subtest: psr-rgb565-draw-pwrite
[260.126984] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.932s)
[260.270926] [031/132] (759s left) gem_render_copy (y-tiled-to-vebox-linear)
[260.332799] Starting subtest: y-tiled-to-vebox-linear
[260.337325] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[260.542971] [032/132] (759s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[260.593882] Starting subtest: multi-wait-for-submit-submitted-signaled
[260.596293] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[260.709226] [033/132] (759s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[260.825790] Starting subtest: x-tiled-64bpp-rotate-90
[260.845726] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[261.006291] [034/132] (758s left) kms_psr (fbc-psr2-cursor-render)
[261.106860] Starting subtest: fbc-psr2-cursor-render
[261.109173] Starting dynamic subtest: eDP-1
[261.113478] Dynamic subtest eDP-1: SKIP (0.000s)
[261.117854] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[261.202928] [035/132] (758s left) kms_getfb (getfb-handle-protection)
[261.300143] Starting subtest: getfb-handle-protection
[261.321304] Subtest getfb-handle-protection: SUCCESS (0.022s)
[261.483829] [036/132] (758s left) sw_sync (sync_merge_invalid)
[261.520225] Starting subtest: sync_merge_invalid
[261.540752] Subtest sync_merge_invalid: SUCCESS (0.020s)
[261.614211] [037/132] (758s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[261.708704] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[266.376319] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.669s)
[267.616523] [038/132] (752s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[267.720066] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[274.995429] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.277s)
[275.187021] [039/132] (744s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[275.317701] Starting subtest: cursorB-vs-flipA-toggle
[275.322339] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[275.486622] [040/132] (744s left) kms_rmfb (rmfb-ioctl)
[275.576312] Starting subtest: rmfb-ioctl
[275.578267] Starting dynamic subtest: pipe-A-eDP-1
[275.680058] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[275.690070] Starting dynamic subtest: pipe-B-eDP-1
[276.869392] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.187s)
[276.881957] Starting dynamic subtest: pipe-C-eDP-1
[277.989974] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.118s)
[277.999056] Starting dynamic subtest: pipe-D-eDP-1
[279.122720] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.131s)
[279.125111] Subtest rmfb-ioctl: SUCCESS (3.549s)
[280.355432] [041/132] (739s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[280.750456] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[281.540876] [042/132] (738s left) sysfs_timeslice_duration (invalid)
[281.622238] Starting subtest: invalid
[281.624632] Starting dynamic subtest: vecs0
[281.626824] Dynamic subtest vecs0: SUCCESS (0.000s)
[281.636594] Starting dynamic subtest: ccs0
[281.638755] Dynamic subtest ccs0: SUCCESS (0.000s)
[281.646659] Starting dynamic subtest: vcs0
[281.648800] Dynamic subtest vcs0: SUCCESS (0.000s)
[281.656630] Starting dynamic subtest: bcs0
[281.659043] Dynamic subtest bcs0: SUCCESS (0.000s)
[281.666855] Starting dynamic subtest: rcs0
[281.669104] Dynamic subtest rcs0: SUCCESS (0.000s)
[281.676849] Starting dynamic subtest: vcs1
[281.679129] Dynamic subtest vcs1: SUCCESS (0.000s)
[281.681341] Subtest invalid: SUCCESS (0.006s)
[281.771471] [043/132] (738s left) syncobj_eventfd (timeline-wait)
[281.824680] Starting subtest: timeline-wait
[281.833267] Subtest timeline-wait: SUCCESS (0.011s)
[281.931376] [044/132] (738s left) kms_pm_backlight (brightness-with-dpms)
[282.039199] Starting subtest: brightness-with-dpms
[282.091524] Starting dynamic subtest: eDP-1
[284.732079] Dynamic subtest eDP-1: SUCCESS (2.641s)
[284.734505] Subtest brightness-with-dpms: SUCCESS (2.693s)
[285.601078] [045/132] (734s left) kms_busy (extended-modeset-hang-oldfb)
[285.710371] Starting subtest: extended-modeset-hang-oldfb
[285.712667] Starting dynamic subtest: pipe-A
[297.471442] Dynamic subtest pipe-A: SUCCESS (11.758s)
[297.489372] Starting dynamic subtest: pipe-D
[309.732642] Dynamic subtest pipe-D: SUCCESS (12.257s)
[309.735058] Subtest extended-modeset-hang-oldfb: SUCCESS (24.020s)
[310.548210] [046/132] (709s left) syncobj_wait (multi-wait-all-signaled)
[310.617810] Starting subtest: multi-wait-all-signaled
[310.620147] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[310.749963] [047/132] (709s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[310.852077] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[310.854331] Starting dynamic subtest: pipe-A-eDP-1
[310.877102] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[310.890548] Starting dynamic subtest: pipe-B-eDP-1
[310.911748] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[310.921444] Starting dynamic subtest: pipe-C-eDP-1
[310.942748] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[310.950687] Starting dynamic subtest: pipe-D-eDP-1
[310.973170] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[310.977319] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[313.046450] [048/132] (707s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[313.167716] Starting subtest: yf-tiled-8bpp-rotate-180
[313.172176] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[313.307035] [049/132] (707s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[313.414778] Starting subtest: psr-rgb565-draw-mmap-cpu
[320.166016] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.753s)
[320.280950] [050/132] (700s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[320.394508] Starting subtest: yf-tiled-16bpp-rotate-90
[320.398805] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[320.547028] [051/132] (700s left) kms_addfb_basic (clobberred-modifier)
[320.609691] Starting subtest: clobberred-modifier
[320.614241] Subtest clobberred-modifier: SKIP (0.000s)
[320.763546] [052/132] (699s left) gem_exec_reloc (basic-write-read-active)
[320.820776] Subtest basic-write-read-active: SKIP (0.000s)
[320.896450] [053/132] (699s left) dmabuf (all-tests)
[320.937786] Starting subtest: all-tests
[320.940338] Starting dynamic subtest: sanitycheck
[321.099072] Dynamic subtest sanitycheck: SUCCESS (0.159s)
[321.110397] Starting dynamic subtest: dma_fence
[321.390693] Dynamic subtest dma_fence: SUCCESS (0.289s)
[321.402094] Starting dynamic subtest: dma_fence_chain
[329.877387] Dynamic subtest dma_fence_chain: SUCCESS (8.484s)
[329.888692] Starting dynamic subtest: dma_fence_unwrap
[329.967754] Dynamic subtest dma_fence_unwrap: SUCCESS (0.089s)
[329.979044] Starting dynamic subtest: dma_resv
[330.104506] Dynamic subtest dma_resv: SUCCESS (0.133s)
[330.107029] Subtest all-tests: SUCCESS (9.166s)
[330.195277] [054/132] (690s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[330.490739] Starting subtest: cursor-rapid-movement-512x170
[330.494471] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[330.666632] [055/132] (690s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[330.736692] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[330.739037] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[330.873069] [056/132] (689s left) kms_big_fb (linear-16bpp-rotate-90)
[330.977175] Starting subtest: linear-16bpp-rotate-90
[330.996718] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[331.152923] [057/132] (689s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[331.256783] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[331.260273] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[331.392495] [058/132] (689s left) gem_exec_schedule (noreorder-priority)
[331.578573] Starting subtest: noreorder-priority
[331.581082] Starting dynamic subtest: rcs0
[332.746864] Dynamic subtest rcs0: SUCCESS (1.169s)
[332.757932] Starting dynamic subtest: vcs0
[333.928070] Dynamic subtest vcs0: SUCCESS (1.179s)
[333.939916] Starting dynamic subtest: vcs1
[335.919280] Dynamic subtest vcs1: SUCCESS (1.988s)
[335.930659] Starting dynamic subtest: vecs0
[337.920058] Dynamic subtest vecs0: SUCCESS (1.998s)
[337.931623] Starting dynamic subtest: ccs0
[339.090669] Dynamic subtest ccs0: SUCCESS (1.170s)
[339.093079] Subtest noreorder-priority: SUCCESS (7.514s)
[339.300160] [059/132] (681s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[339.407784] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[346.130373] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.724s)
[346.303727] [060/132] (674s left) perf (polling-small-buf)
[346.432056] Starting subtest: polling-small-buf
[346.522162] Subtest polling-small-buf: SUCCESS (0.088s)
[346.566321] [061/132] (674s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[346.667487] Starting subtest: bad-pixel-format-y-tiled-ccs
[346.669838] Starting dynamic subtest: pipe-A-eDP-1
[346.692573] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[346.705514] Starting dynamic subtest: pipe-B-eDP-1
[346.727247] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[346.734950] Starting dynamic subtest: pipe-C-eDP-1
[346.756587] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[346.764232] Starting dynamic subtest: pipe-D-eDP-1
[346.785948] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[346.790273] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.005s)
[348.848577] [062/132] (672s left) i915_drm_fdinfo (busy-hang)
[348.890758] Starting subtest: busy-hang
[348.893166] Starting dynamic subtest: rcs0
[348.930971] Dynamic subtest rcs0: SKIP (0.037s)
[348.941680] Starting dynamic subtest: bcs0
[348.945764] Dynamic subtest bcs0: SKIP (0.001s)
[348.953907] Starting dynamic subtest: vcs0
[348.958464] Dynamic subtest vcs0: SKIP (0.001s)
[348.968295] Starting dynamic subtest: vcs1
[348.972241] Dynamic subtest vcs1: SKIP (0.001s)
[348.979852] Starting dynamic subtest: vecs0
[348.984026] Dynamic subtest vecs0: SKIP (0.001s)
[348.991492] Starting dynamic subtest: ccs0
[348.995583] Dynamic subtest ccs0: SKIP (0.001s)
[348.999674] Subtest busy-hang: SKIP (0.047s)
[349.033035] [063/132] (672s left) kms_cursor_crc (cursor-sliding-max-size)
[349.342848] Starting subtest: cursor-sliding-max-size
[349.349182] Subtest cursor-sliding-max-size: SKIP (0.000s)
[349.498171] [064/132] (671s left) gem_exec_schedule (preempt-queue-contexts-chain)
[349.583728] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[349.893294] [065/132] (671s left) gem_mmap_gtt (big-copy)
[349.957708] Subtest big-copy: SKIP (0.000s)
[350.023518] [066/132] (671s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[350.124280] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[350.126593] Starting dynamic subtest: pipe-A
[350.186596] Dynamic subtest pipe-A: SUCCESS (0.064s)
[350.196635] Starting dynamic subtest: pipe-B
[351.358670] Dynamic subtest pipe-B: SUCCESS (1.171s)
[351.367308] Starting dynamic subtest: pipe-C
[352.468781] Dynamic subtest pipe-C: SUCCESS (1.109s)
[352.478954] Starting dynamic subtest: pipe-D
[353.604248] Dynamic subtest pipe-D: SUCCESS (1.133s)
[353.606609] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.483s)
[354.782479] [067/132] (666s left) kms_chamelium_color (ctm-0-50)
[354.885479] Subtest ctm-0-50: SKIP (0.000s)
[354.927081] [068/132] (666s left) kms_psr (pr-primary-page-flip)
[355.014054] Starting subtest: pr-primary-page-flip
[355.018430] Subtest pr-primary-page-flip: SKIP (0.000s)
[355.123778] [069/132] (666s left) kms_atomic (test-only)
[355.219820] Starting subtest: test-only
[355.267081] Starting dynamic subtest: pipe-A-eDP-1
[357.745613] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.477s)
[357.748064] Subtest test-only: SUCCESS (2.527s)
[358.567889] [070/132] (662s left) gem_spin_batch (spin-each)
[358.629738] Starting subtest: spin-each
[361.991987] Subtest spin-each: SUCCESS (3.358s)
[363.013400] [071/132] (658s left) gem_exec_params (batch-first)
[363.075589] Starting subtest: batch-first
[363.085339] Subtest batch-first: SUCCESS (0.010s)
[363.255520] [072/132] (658s left) gem_busy (semaphore)
[363.451841] Subtest semaphore: SKIP (0.000s)
[363.735864] [073/132] (657s left) kms_psr (pr-primary-blt)
[363.845391] Starting subtest: pr-primary-blt
[363.849761] Subtest pr-primary-blt: SKIP (0.000s)
[363.938428] [074/132] (657s left) kms_dp_link_training (uhbr-mst)
[364.019965] Starting subtest: uhbr-mst
[364.028758] Subtest uhbr-mst: SKIP (0.000s)
[364.114522] [075/132] (657s left) i915_suspend (debugfs-reader)
[364.168326] Starting subtest: debugfs-reader
[382.567468] Subtest debugfs-reader: SUCCESS (6.889s)
[382.690957] [076/132] (638s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[382.802867] Starting subtest: compare-crc-sanitycheck-nv12
[382.805150] Starting dynamic subtest: pipe-A-eDP-1
[383.486954] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.682s)
[383.497005] Starting dynamic subtest: pipe-B-eDP-1
[384.718886] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.231s)
[384.728215] Starting dynamic subtest: pipe-C-eDP-1
[385.899276] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.179s)
[385.912435] Starting dynamic subtest: pipe-D-eDP-1
[387.132438] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.231s)
[387.134850] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.329s)
[388.004777] [077/132] (633s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[388.121372] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[388.125758] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[388.264841] [078/132] (633s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[388.366923] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[388.371400] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[389.536434] [079/132] (632s left) perf_pmu (busy-idle-check-all)
[389.583876] Starting subtest: busy-idle-check-all
[389.585697] Starting dynamic subtest: rcs0
[390.252778] Dynamic subtest rcs0: SUCCESS (0.670s)
[390.264820] Starting dynamic subtest: bcs0
[390.903376] Dynamic subtest bcs0: SUCCESS (0.648s)
[390.915336] Starting dynamic subtest: vcs0
[391.614504] Dynamic subtest vcs0: FAIL (0.644s)
[391.625868] Starting dynamic subtest: vcs1
[392.253038] Dynamic subtest vcs1: FAIL (0.621s)
[392.263551] Starting dynamic subtest: vecs0
[392.876387] Dynamic subtest vecs0: FAIL (0.624s)
[392.886931] Starting dynamic subtest: ccs0
[393.502578] Dynamic subtest ccs0: FAIL (0.626s)
[393.504852] Subtest busy-idle-check-all: FAIL (3.842s)
[394.474825] [080/132] (627s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[394.588262] Starting subtest: psr-2p-pri-indfb-multidraw
[394.592214] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[394.734261] [081/132] (627s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[394.849425] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[394.853849] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[394.965147] [082/132] (626s left) gem_exec_balancer (fairslice)
[395.030471] Starting subtest: fairslice
[397.926915] Subtest fairslice: SUCCESS (2.894s)
[398.467149] [083/132] (623s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[398.542665] Starting subtest: etime-single-wait-all-available-unsubmitted
[398.652261] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.108s)
[398.759229] [084/132] (623s left) gem_exec_fence (syncobj-timeline-chain-engines)
[399.326967] Starting subtest: syncobj-timeline-chain-engines
[399.485898] Subtest syncobj-timeline-chain-engines: SUCCESS (0.159s)
[400.527317] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[400.530831] [085/132] (621s left) gem_pread (display)
[400.591668] Subtest display: SKIP (0.000s)
[400.664018] [086/132] (621s left) gem_sync (basic-all)
[400.744495] Starting subtest: basic-all
[404.425182] Subtest basic-all: SUCCESS (3.689s)
[404.637649] [087/132] (617s left) gem_mmap_offset (isolation)
[404.699278] Starting subtest: isolation
[404.717130] Subtest isolation: SUCCESS (0.006s)
[404.835003] [088/132] (617s left) kms_atomic_interruptible (legacy-dpms)
[404.930624] Starting subtest: legacy-dpms
[404.932836] Starting dynamic subtest: pipe-A-eDP-1
[412.363605] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.432s)
[412.365858] Subtest legacy-dpms: SUCCESS (7.433s)
[413.208158] [089/132] (609s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[413.307876] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[413.312393] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[413.417906] [090/132] (608s left) gem_flink_basic (bad-open)
[413.477579] Starting subtest: bad-open
[413.479487] Subtest bad-open: SUCCESS (0.000s)
[413.607869] [091/132] (608s left) kms_invalid_mode (int-max-clock)
[413.702944] Starting subtest: int-max-clock
[413.705221] Starting dynamic subtest: pipe-A-eDP-1
[413.986029] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[413.996739] Starting dynamic subtest: pipe-B-eDP-1
[413.998857] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[414.007510] Starting dynamic subtest: pipe-C-eDP-1
[414.009874] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[414.021083] Starting dynamic subtest: pipe-D-eDP-1
[414.023283] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[414.025427] Subtest int-max-clock: SUCCESS (0.289s)
[414.858933] [092/132] (607s left) gem_exec_params (mmapped)
[414.916383] Starting subtest: mmapped
[414.922017] Subtest mmapped: SUCCESS (0.002s)
[415.081970] [093/132] (607s left) kms_plane_multiple (tiling-x)
[415.182093] Starting subtest: tiling-x
[415.184465] Starting dynamic subtest: pipe-A-eDP-1
[418.990134] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.807s)
[419.000686] Starting dynamic subtest: pipe-B-eDP-1
[423.432177] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.440s)
[423.442491] Starting dynamic subtest: pipe-C-eDP-1
[427.767876] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.335s)
[427.777862] Starting dynamic subtest: pipe-D-eDP-1
[432.103165] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.334s)
[432.105141] Subtest tiling-x: SUCCESS (16.921s)
[432.934566] [094/132] (589s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[433.045833] Starting subtest: fbc-rgb101010-draw-pwrite
[438.357639] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.313s)
[439.535909] [095/132] (582s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[439.639228] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[446.481268] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.844s)
[446.662743] [096/132] (575s left) kms_flip (2x-flip-vs-dpms)
[446.757924] Starting subtest: 2x-flip-vs-dpms
[446.767690] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[446.905818] [097/132] (575s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[447.005711] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[447.017996] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[447.161952] [098/132] (575s left) kms_content_protection (atomic-hdcp14)
[447.305161] Starting subtest: atomic-hdcp14
[447.309411] Subtest atomic-hdcp14: SKIP (0.000s)
[447.466609] [099/132] (575s left) sw_sync (sync_merge)
[447.508612] Starting subtest: sync_merge
[447.510545] Subtest sync_merge: SUCCESS (0.000s)
[447.585717] [100/132] (575s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[447.679104] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[447.681022] Starting dynamic subtest: pipe-A-default-mode
[447.966507] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[447.971150] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.286s)
[448.776315] [101/132] (573s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[448.899551] Starting subtest: cursor-vs-flip-atomic-transitions
[478.769469] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (29.868s)
[479.612004] [102/132] (543s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[479.701702] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[479.703969] Starting dynamic subtest: pipe-A-eDP-1
[479.763298] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.063s)
[480.074001] Starting dynamic subtest: pipe-B-eDP-1
[480.878821] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.814s)
[481.163754] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.464s)
[482.056411] [103/132] (540s left) kms_prime (basic-crc-vgem)
[482.158389] Starting subtest: basic-crc-vgem
[482.160435] Starting dynamic subtest: second-to-first
[483.112067] Dynamic subtest second-to-first: SUCCESS (0.959s)
[483.113992] Subtest basic-crc-vgem: SUCCESS (0.960s)
[483.231954] [104/132] (539s left) kms_properties (invalid-properties-legacy)
[483.322940] Starting subtest: invalid-properties-legacy
[483.429751] Subtest invalid-properties-legacy: SUCCESS (0.106s)
[483.524686] [105/132] (539s left) syncobj_timeline (wait-zero-handles)
[483.593451] Starting subtest: wait-zero-handles
[483.595820] Subtest wait-zero-handles: SUCCESS (0.000s)
[483.726136] [106/132] (539s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[488.717298] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[488.721861] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[489.589729] [107/132] (533s left) gem_pxp (reject-modify-context-protection-off-1)
[489.714747] Starting subtest: reject-modify-context-protection-off-1
[489.717516] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[489.860746] [108/132] (533s left) kms_selftest (drm_format_helper)
[489.896505] Starting subtest: drm_format_helper
[489.907946] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[490.060713] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.152s)
[490.070097] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[490.202959] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.141s)
[490.213617] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[490.355363] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.153s)
[490.364686] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[490.457306] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.101s)
[490.466536] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[490.569202] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.111s)
[490.579292] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[490.669190] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.097s)
[490.678554] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[490.793991] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.124s)
[490.803264] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[490.943217] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.150s)
[490.952233] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[491.087031] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.142s)
[491.097707] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[491.224296] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.138s)
[491.233692] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[491.353251] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.126s)
[491.362251] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[491.477025] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.123s)
[491.487075] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[491.601921] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.124s)
[491.610941] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[491.692976] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.091s)
[491.702415] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[491.831633] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.138s)
[491.841020] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[491.924842] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.093s)
[491.935550] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[492.015280] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.090s)
[492.017544] Subtest drm_format_helper: SUCCESS (2.120s)
[492.210040] [109/132] (530s left) gen3_mixed_blits
[492.332154] [110/132] (530s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[492.429815] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[492.433816] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[492.578054] [111/132] (530s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[492.687493] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[499.479469] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.795s)
[499.627074] [112/132] (523s left) gen7_exec_parse (cmd-crossing-page)
[499.704266] Subtest cmd-crossing-page: SKIP (0.000s)
[499.789428] [113/132] (523s left) gem_softpin (overlap)
[499.856539] Starting subtest: overlap
[500.785381] Subtest overlap: SUCCESS (0.927s)
[500.957244] [114/132] (522s left) gem_eio (in-flight-internal-immediate)
[501.201450] Starting subtest: in-flight-internal-immediate
[502.505853] Subtest in-flight-internal-immediate: SUCCESS (1.304s)
[502.694463] [115/132] (520s left) gem_wait (write-wait)
[502.764909] Starting subtest: write-wait
[502.766770] Starting dynamic subtest: all-engines
[503.920052] Dynamic subtest all-engines: SUCCESS (1.157s)
[503.937727] Starting dynamic subtest: rcs0
[504.981842] Dynamic subtest rcs0: SUCCESS (1.059s)
[505.005332] Starting dynamic subtest: bcs0
[506.042068] Dynamic subtest bcs0: SUCCESS (1.058s)
[506.059656] Starting dynamic subtest: vcs0
[507.095963] Dynamic subtest vcs0: SUCCESS (1.052s)
[507.112856] Starting dynamic subtest: vcs1
[508.150543] Dynamic subtest vcs1: SUCCESS (1.052s)
[508.166981] Starting dynamic subtest: vecs0
[509.207899] Dynamic subtest vecs0: SUCCESS (1.056s)
[509.224789] Starting dynamic subtest: ccs0
[510.266528] Dynamic subtest ccs0: SUCCESS (1.056s)
[510.269294] Subtest write-wait: SUCCESS (7.505s)
[510.494326] [116/132] (512s left) kms_chamelium_edid (dp-edid-read)
[510.888476] Subtest dp-edid-read: SKIP (0.000s)
[511.684926] [117/132] (511s left) gem_exec_balancer (parallel-bb-first)
[511.956727] Starting subtest: parallel-bb-first
[512.862887] Subtest parallel-bb-first: SUCCESS (0.909s)
[513.477453] [118/132] (509s left) kms_pm_rpm (fences)
[515.509763] Starting subtest: fences
[515.514579] Subtest fences: SKIP (0.000s)
[515.888205] [119/132] (507s left) gem_ctx_persistence (legacy-engines-queued)
[515.943128] Starting subtest: legacy-engines-queued
[515.945494] Starting dynamic subtest: render
[516.030703] Dynamic subtest render: SUCCESS (0.087s)
[516.048816] Starting dynamic subtest: bsd1
[517.351199] Dynamic subtest bsd1: SUCCESS (1.316s)
[517.363861] Starting dynamic subtest: bsd2
[519.333341] Dynamic subtest bsd2: SUCCESS (1.979s)
[519.345910] Starting dynamic subtest: blt
[519.363041] Dynamic subtest blt: SUCCESS (0.028s)
[519.372843] Starting dynamic subtest: vebox
[521.383063] Dynamic subtest vebox: SUCCESS (2.017s)
[521.385824] Subtest legacy-engines-queued: SUCCESS (5.440s)
[521.487591] [120/132] (501s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[521.602193] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[522.270956] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.671s)
[522.448830] [121/132] (501s left) gem_mmap_gtt (basic-wc)
[522.513574] Subtest basic-wc: SKIP (0.000s)
[522.576970] [122/132] (500s left) core_auth (getclient-simple)
[522.609169] Starting subtest: getclient-simple
[522.638075] Subtest getclient-simple: SUCCESS (0.030s)
[522.767852] [123/132] (500s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[522.868998] Starting subtest: addfb25-y-tiled-small-legacy
[522.873357] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[522.991445] [124/132] (500s left) perf (invalid-oa-exponent)
[523.096605] Starting subtest: invalid-oa-exponent
[523.110024] Subtest invalid-oa-exponent: SUCCESS (0.011s)
[523.154977] [125/132] (500s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[523.251869] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[523.253629] Starting dynamic subtest: pipe-A-default-mode
[524.430262] Dynamic subtest pipe-A-default-mode: SKIP (1.176s)
[524.434055] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.177s)
[524.637212] [126/132] (499s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[524.736514] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[524.738770] Starting dynamic subtest: A-eDP1
[528.322753] Dynamic subtest A-eDP1: SUCCESS (3.584s)
[528.333678] Starting dynamic subtest: B-eDP1
[531.530648] Dynamic subtest B-eDP1: SUCCESS (3.207s)
[531.551487] Starting dynamic subtest: C-eDP1
[534.616750] Dynamic subtest C-eDP1: SUCCESS (3.087s)
[534.628680] Starting dynamic subtest: D-eDP1
[537.732625] Dynamic subtest D-eDP1: SUCCESS (3.113s)
[537.734855] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (12.999s)
[538.556923] [127/132] (485s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[538.620205] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[538.622089] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[538.745054] [128/132] (485s left) kms_vrr (max-min)
[538.839413] Starting subtest: max-min
[538.845736] Subtest max-min: SKIP (0.000s)
[538.940032] [129/132] (484s left) kms_rotation_crc (sprite-rotation-90)
[539.033482] Starting subtest: sprite-rotation-90
[539.037846] Subtest sprite-rotation-90: SKIP (0.001s)
[539.131730] [130/132] (484s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[539.226106] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[539.230499] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[539.367967] [131/132] (484s left) kms_cursor_crc (cursor-offscreen-64x64)
[539.542406] Starting subtest: cursor-offscreen-64x64
[539.544311] Starting dynamic subtest: pipe-A-eDP-1
[541.495881] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.950s)
[541.534999] Starting dynamic subtest: pipe-D-eDP-1
[544.133935] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.615s)
[544.136106] Subtest cursor-offscreen-64x64: SUCCESS (4.591s)
[545.017944] [132/132] (478s left) kms_color_pipeline (plane-lut1d-lut1d)
[545.123956] Starting subtest: plane-lut1d-lut1d
[545.139665] Starting dynamic subtest: pipe-A-eDP-1
[545.833126] Dynamic subtest pipe-A-eDP-1: FAIL (0.305s)
[546.573189] Starting dynamic subtest: pipe-B-eDP-1
[547.390585] Dynamic subtest pipe-B-eDP-1: FAIL (0.303s)
[547.911272] Starting dynamic subtest: pipe-C-eDP-1
[548.740142] Dynamic subtest pipe-C-eDP-1: FAIL (0.235s)
[549.242275] Starting dynamic subtest: pipe-D-eDP-1
[550.063082] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s)
[550.065090] Subtest plane-lut1d-lut1d: FAIL (4.646s)
[550.644032] 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.