[57.514155] Initializing watchdogs
[57.514203]   /dev/watchdog0
[57.528117] [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]
[57.532317] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.534778] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.538259] [001/132] (960s left) gem_exec_store (cachelines)
[57.629859] Starting subtest: cachelines
[57.632052] Starting dynamic subtest: rcs0
[57.704738] Dynamic subtest rcs0: SUCCESS (0.077s)
[57.711334] Starting dynamic subtest: bcs0
[57.771992] Dynamic subtest bcs0: SUCCESS (0.066s)
[57.779928] Starting dynamic subtest: vcs0
[57.835887] Dynamic subtest vcs0: SUCCESS (0.063s)
[57.844309] Starting dynamic subtest: vcs1
[57.891067] Dynamic subtest vcs1: SUCCESS (0.054s)
[57.898547] Starting dynamic subtest: vecs0
[57.948085] Dynamic subtest vecs0: SUCCESS (0.056s)
[57.956599] Starting dynamic subtest: ccs0
[58.013768] Dynamic subtest ccs0: SUCCESS (0.064s)
[58.016356] Subtest cachelines: SUCCESS (0.383s)
[58.220519] [002/132] (959s left) gem_pread (bench)
[58.286557] Subtest bench: SKIP (0.000s)
[58.345614] [003/132] (959s left) syncobj_wait (invalid-signal-illegal-handle)
[58.402552] Starting subtest: invalid-signal-illegal-handle
[58.404725] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[58.521870] [004/132] (959s left) kms_vblank (query-forked-busy-hang)
[58.614922] Starting subtest: query-forked-busy-hang
[58.616882] Starting dynamic subtest: pipe-A-eDP-1
[85.654482] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.988s)
[85.671053] Starting dynamic subtest: pipe-D-eDP-1
[110.677359] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.014s)
[110.679672] Subtest query-forked-busy-hang: SUCCESS (52.005s)
[111.513610] [005/132] (906s left) kms_psr (fbc-pr-primary-mmap-cpu)
[111.608908] Starting subtest: fbc-pr-primary-mmap-cpu
[111.613134] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[111.704347] [006/132] (906s left) kms_plane_cursor (overlay)
[111.804284] Starting subtest: overlay
[111.808844] Starting dynamic subtest: pipe-A-eDP-1-size-64
[114.320646] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.515s)
[114.378230] Starting dynamic subtest: pipe-A-eDP-1-size-128
[116.855217] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.483s)
[116.912446] Starting dynamic subtest: pipe-A-eDP-1-size-256
[119.334428] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.426s)
[119.680607] Starting dynamic subtest: pipe-B-eDP-1-size-64
[122.908134] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.236s)
[122.965823] Starting dynamic subtest: pipe-B-eDP-1-size-128
[125.397624] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.438s)
[125.455327] Starting dynamic subtest: pipe-B-eDP-1-size-256
[127.832502] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.382s)
[128.182560] Starting dynamic subtest: pipe-C-eDP-1-size-64
[130.857847] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.682s)
[130.893112] Starting dynamic subtest: pipe-C-eDP-1-size-128
[132.913395] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.024s)
[132.948942] Starting dynamic subtest: pipe-C-eDP-1-size-256
[134.991290] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.047s)
[135.321302] Starting dynamic subtest: pipe-D-eDP-1-size-64
[138.122063] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.808s)
[138.158382] Starting dynamic subtest: pipe-D-eDP-1-size-128
[140.243718] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.091s)
[140.279737] Starting dynamic subtest: pipe-D-eDP-1-size-256
[142.343410] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.069s)
[142.662586] Subtest overlay: SUCCESS (30.853s)
[143.529967] [007/132] (874s left) perf (i915-ref-count)
[143.624883] Starting subtest: i915-ref-count
[143.686956] Subtest i915-ref-count: SUCCESS (0.060s)
[143.738084] [008/132] (874s left) gem_exec_schedule (smoketest-all)
[143.815271] Starting subtest: smoketest-all
[176.184314] Subtest smoketest-all: SUCCESS (32.327s)
[177.945238] [009/132] (840s left) gem_lmem_swapping (heavy-random)
[177.989886] Subtest heavy-random: SKIP (0.000s)
[178.034170] [010/132] (840s left) kms_psr (psr-primary-mmap-gtt)
[178.124034] Starting subtest: psr-primary-mmap-gtt
[178.126384] Starting dynamic subtest: eDP-1
[179.509740] Dynamic subtest eDP-1: SKIP (1.383s)
[179.514206] Subtest psr-primary-mmap-gtt: SKIP (1.385s)
[179.708509] [011/132] (838s left) perf (whitelisted-registers-userspace-config)
[179.809770] Starting subtest: whitelisted-registers-userspace-config
[179.811748] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[179.860014] [012/132] (838s left) gem_ppgtt (blt-vs-render-ctx0)
[179.923844] Starting subtest: blt-vs-render-ctx0
[212.870218] Subtest blt-vs-render-ctx0: SUCCESS (32.944s)
[213.040136] [013/132] (805s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[213.178536] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[213.182981] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[213.292715] [014/132] (805s left) gem_mmap_gtt (basic)
[213.351634] Subtest basic: SKIP (0.000s)
[213.409056] [015/132] (805s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[213.469824] Starting subtest: invalid-single-wait-all-unsubmitted
[213.472182] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[213.597263] [016/132] (805s left) gem_ctx_engines (execute-one)
[213.663230] Starting subtest: execute-one
[215.781140] Subtest execute-one: SUCCESS (2.118s)
[215.987271] [017/132] (802s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[216.105642] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[216.109871] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[216.210424] [018/132] (802s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[216.297442] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[216.299832] Starting dynamic subtest: pipe-A-eDP-1
[216.320957] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[216.330860] Starting dynamic subtest: pipe-B-eDP-1
[216.350123] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[216.357470] Starting dynamic subtest: pipe-C-eDP-1
[216.376717] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[216.384165] Starting dynamic subtest: pipe-D-eDP-1
[216.403411] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[216.405290] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[218.527990] [019/132] (800s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[218.582751] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[218.634400] [020/132] (800s left) api_intel_allocator (two-level-inception-interruptible)
[218.693604] Starting subtest: two-level-inception-interruptible
[224.094833] Subtest two-level-inception-interruptible: SUCCESS (5.403s)
[224.264559] [021/132] (794s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[229.278311] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[229.280631] Starting dynamic subtest: pipe-A-eDP-1
[235.942633] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.663s)
[235.950581] Starting dynamic subtest: pipe-B-eDP-1
[242.589166] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.643s)
[242.591716] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.311s)
[243.471290] [022/132] (775s left) i915_pciid
[243.672813] [023/132] (775s left) i915_pm_rpm (sysfs-read)
[245.227604] Starting subtest: sysfs-read
[246.399030] Subtest sysfs-read: SUCCESS (1.166s)
[246.775219] [024/132] (772s left) gem_exec_reloc (basic-cpu-noreloc)
[246.834760] Subtest basic-cpu-noreloc: SKIP (0.000s)
[246.923740] [025/132] (772s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[247.009199] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[247.013592] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[247.133232] [026/132] (771s left) sw_sync (timeline_closed_signaled)
[247.174094] Starting subtest: timeline_closed_signaled
[247.176279] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[247.264958] [027/132] (771s left) gem_eio (context-create)
[247.492017] Starting subtest: context-create
[247.822840] Subtest context-create: SUCCESS (0.331s)
[248.003884] [028/132] (771s left) kms_fb_coherency (memset-crc)
[248.122995] Starting subtest: memset-crc
[248.125052] Starting dynamic subtest: mmap-offset-wc
[248.500435] Dynamic subtest mmap-offset-wc: SUCCESS (0.376s)
[248.798256] Starting dynamic subtest: mmap-offset-uc
[254.747833] Dynamic subtest mmap-offset-uc: SUCCESS (5.956s)
[255.049049] Subtest memset-crc: SUCCESS (6.922s)
[255.943453] [029/132] (763s left) gem_ctx_persistence (engines-hostile-preempt)
[256.022641] Starting subtest: engines-hostile-preempt
[256.025065] Starting dynamic subtest: rcs0
[256.144934] Dynamic subtest rcs0: SUCCESS (0.122s)
[256.155767] Starting dynamic subtest: bcs0
[256.225096] Dynamic subtest bcs0: SUCCESS (0.078s)
[256.235449] Starting dynamic subtest: vcs0
[257.925643] Dynamic subtest vcs0: SUCCESS (1.697s)
[257.936066] Starting dynamic subtest: vcs1
[259.915139] Dynamic subtest vcs1: SUCCESS (1.988s)
[259.925283] Starting dynamic subtest: vecs0
[261.891654] Dynamic subtest vecs0: SUCCESS (1.974s)
[261.902289] Starting dynamic subtest: ccs0
[261.994970] Dynamic subtest ccs0: SUCCESS (0.103s)
[261.997698] Subtest engines-hostile-preempt: SUCCESS (5.973s)
[262.095121] [030/132] (757s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[262.206343] Starting subtest: psr-rgb565-draw-pwrite
[269.199868] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.996s)
[269.344125] [031/132] (749s left) gem_render_copy (y-tiled-to-vebox-linear)
[269.403728] Starting subtest: y-tiled-to-vebox-linear
[269.408130] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[269.611471] [032/132] (749s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[269.665676] Starting subtest: multi-wait-for-submit-submitted-signaled
[269.667694] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[269.798296] [033/132] (749s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[269.897291] Starting subtest: x-tiled-64bpp-rotate-90
[269.916611] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[270.063982] [034/132] (749s left) kms_psr (fbc-psr2-cursor-render)
[270.163094] Starting subtest: fbc-psr2-cursor-render
[270.165250] Starting dynamic subtest: eDP-1
[270.169126] Dynamic subtest eDP-1: SKIP (0.000s)
[270.173212] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[270.257737] [035/132] (749s left) kms_getfb (getfb-handle-protection)
[270.358980] Starting subtest: getfb-handle-protection
[270.389293] Subtest getfb-handle-protection: SUCCESS (0.031s)
[270.541253] [036/132] (748s left) sw_sync (sync_merge_invalid)
[270.582063] Starting subtest: sync_merge_invalid
[270.605068] Subtest sync_merge_invalid: SUCCESS (0.023s)
[270.690357] [037/132] (748s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[270.782180] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[275.397278] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.617s)
[276.585816] [038/132] (742s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[276.690503] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[283.865459] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.176s)
[284.022251] [039/132] (735s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[284.176894] Starting subtest: cursorB-vs-flipA-toggle
[284.181161] Subtest cursorB-vs-flipA-toggle: SKIP (0.003s)
[284.356776] [040/132] (735s left) kms_rmfb (rmfb-ioctl)
[284.456080] Starting subtest: rmfb-ioctl
[284.458338] Starting dynamic subtest: pipe-A-eDP-1
[284.561214] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.106s)
[284.586978] Starting dynamic subtest: pipe-B-eDP-1
[285.758637] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.194s)
[285.768407] Starting dynamic subtest: pipe-C-eDP-1
[286.964064] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.204s)
[286.976246] Starting dynamic subtest: pipe-D-eDP-1
[288.095377] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.128s)
[288.097827] Subtest rmfb-ioctl: SUCCESS (3.641s)
[289.239830] [041/132] (730s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[289.620922] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[290.426191] [042/132] (729s left) sysfs_timeslice_duration (invalid)
[290.500887] Starting subtest: invalid
[290.503320] Starting dynamic subtest: vecs0
[290.505747] Dynamic subtest vecs0: SUCCESS (0.000s)
[290.515534] Starting dynamic subtest: ccs0
[290.517744] Dynamic subtest ccs0: SUCCESS (0.000s)
[290.525322] Starting dynamic subtest: vcs0
[290.527623] Dynamic subtest vcs0: SUCCESS (0.000s)
[290.535203] Starting dynamic subtest: bcs0
[290.537365] Dynamic subtest bcs0: SUCCESS (0.000s)
[290.544896] Starting dynamic subtest: rcs0
[290.547075] Dynamic subtest rcs0: SUCCESS (0.000s)
[290.554571] Starting dynamic subtest: vcs1
[290.556493] Dynamic subtest vcs1: SUCCESS (0.000s)
[290.558413] Subtest invalid: SUCCESS (0.006s)
[290.631143] [043/132] (729s left) syncobj_eventfd (timeline-wait)
[290.688368] Starting subtest: timeline-wait
[290.697081] Subtest timeline-wait: SUCCESS (0.011s)
[290.828299] [044/132] (728s left) kms_pm_backlight (brightness-with-dpms)
[290.919952] Starting subtest: brightness-with-dpms
[290.973569] Starting dynamic subtest: eDP-1
[293.691979] Dynamic subtest eDP-1: SUCCESS (2.719s)
[293.694160] Subtest brightness-with-dpms: SUCCESS (2.772s)
[294.579025] [045/132] (725s left) kms_busy (extended-modeset-hang-oldfb)
[294.675083] Starting subtest: extended-modeset-hang-oldfb
[294.677332] Starting dynamic subtest: pipe-A
[306.625062] Dynamic subtest pipe-A: SUCCESS (11.947s)
[306.633928] Starting dynamic subtest: pipe-D
[318.909503] Dynamic subtest pipe-D: SUCCESS (12.281s)
[318.911896] Subtest extended-modeset-hang-oldfb: SUCCESS (24.233s)
[319.723588] [046/132] (700s left) syncobj_wait (multi-wait-all-signaled)
[319.803244] Starting subtest: multi-wait-all-signaled
[319.805165] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[319.911152] [047/132] (699s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[320.008820] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[320.011061] Starting dynamic subtest: pipe-A-eDP-1
[320.032820] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[320.042030] Starting dynamic subtest: pipe-B-eDP-1
[320.062073] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[320.070068] Starting dynamic subtest: pipe-C-eDP-1
[320.090308] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[320.098607] Starting dynamic subtest: pipe-D-eDP-1
[320.120172] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[320.124188] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[322.193829] [048/132] (697s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[322.322061] Starting subtest: yf-tiled-8bpp-rotate-180
[322.326625] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[322.451964] [049/132] (697s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[322.559294] Starting subtest: psr-rgb565-draw-mmap-cpu
[329.260820] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.703s)
[329.395446] [050/132] (690s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[329.496397] Starting subtest: yf-tiled-16bpp-rotate-90
[329.500575] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[329.615442] [051/132] (690s left) kms_addfb_basic (clobberred-modifier)
[329.666217] Starting subtest: clobberred-modifier
[329.670591] Subtest clobberred-modifier: SKIP (0.000s)
[329.836496] [052/132] (690s left) gem_exec_reloc (basic-write-read-active)
[329.903414] Subtest basic-write-read-active: SKIP (0.000s)
[329.985952] [053/132] (690s left) dmabuf (all-tests)
[330.021695] Starting subtest: all-tests
[330.024025] Starting dynamic subtest: sanitycheck
[330.204125] Dynamic subtest sanitycheck: SUCCESS (0.181s)
[330.215655] Starting dynamic subtest: dma_fence
[330.446582] Dynamic subtest dma_fence: SUCCESS (0.240s)
[330.457882] Starting dynamic subtest: dma_fence_chain
[338.966520] Dynamic subtest dma_fence_chain: SUCCESS (8.517s)
[338.977752] Starting dynamic subtest: dma_fence_unwrap
[339.099354] Dynamic subtest dma_fence_unwrap: SUCCESS (0.131s)
[339.110391] Starting dynamic subtest: dma_resv
[339.235475] Dynamic subtest dma_resv: SUCCESS (0.134s)
[339.238623] Subtest all-tests: SUCCESS (9.213s)
[339.324050] [054/132] (680s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[339.636817] Starting subtest: cursor-rapid-movement-512x170
[339.641342] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[339.819403] [055/132] (680s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[339.881841] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[339.884128] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[339.993743] [056/132] (680s left) kms_big_fb (linear-16bpp-rotate-90)
[340.088299] Starting subtest: linear-16bpp-rotate-90
[340.108021] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[340.245572] [057/132] (679s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[340.349804] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[340.353089] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[340.482773] [058/132] (679s left) gem_exec_schedule (noreorder-priority)
[340.665309] Starting subtest: noreorder-priority
[340.667782] Starting dynamic subtest: rcs0
[341.832786] Dynamic subtest rcs0: SUCCESS (1.167s)
[341.845212] Starting dynamic subtest: vcs0
[344.946923] Dynamic subtest vcs0: SUCCESS (3.110s)
[344.957807] Starting dynamic subtest: vcs1
[346.916649] Dynamic subtest vcs1: SUCCESS (1.967s)
[346.928057] Starting dynamic subtest: vecs0
[348.926079] Dynamic subtest vecs0: SUCCESS (2.007s)
[348.936818] Starting dynamic subtest: ccs0
[350.104098] Dynamic subtest ccs0: SUCCESS (1.177s)
[350.106658] Subtest noreorder-priority: SUCCESS (9.439s)
[350.312570] [059/132] (669s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[350.424632] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[356.964394] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.541s)
[357.135109] [060/132] (663s left) perf (polling-small-buf)
[357.262611] Starting subtest: polling-small-buf
[357.353079] Subtest polling-small-buf: SUCCESS (0.088s)
[357.396332] [061/132] (662s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[357.488891] Starting subtest: bad-pixel-format-y-tiled-ccs
[357.491148] Starting dynamic subtest: pipe-A-eDP-1
[357.513355] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[357.521785] Starting dynamic subtest: pipe-B-eDP-1
[357.542799] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[357.550605] Starting dynamic subtest: pipe-C-eDP-1
[357.571972] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[357.579900] Starting dynamic subtest: pipe-D-eDP-1
[357.601675] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[357.606181] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.005s)
[359.725687] [062/132] (660s left) i915_drm_fdinfo (busy-hang)
[359.770621] Starting subtest: busy-hang
[359.773031] Starting dynamic subtest: rcs0
[359.803022] Dynamic subtest rcs0: SKIP (0.031s)
[359.813347] Starting dynamic subtest: bcs0
[359.817424] Dynamic subtest bcs0: SKIP (0.001s)
[359.825878] Starting dynamic subtest: vcs0
[359.830615] Dynamic subtest vcs0: SKIP (0.000s)
[359.840050] Starting dynamic subtest: vcs1
[359.844135] Dynamic subtest vcs1: SKIP (0.000s)
[359.851613] Starting dynamic subtest: vecs0
[359.855724] Dynamic subtest vecs0: SKIP (0.000s)
[359.863418] Starting dynamic subtest: ccs0
[359.867525] Dynamic subtest ccs0: SKIP (0.000s)
[359.871625] Subtest busy-hang: SKIP (0.036s)
[359.905142] [063/132] (660s left) kms_cursor_crc (cursor-sliding-max-size)
[360.216477] Starting subtest: cursor-sliding-max-size
[360.222881] Subtest cursor-sliding-max-size: SKIP (0.000s)
[360.385523] [064/132] (660s left) gem_exec_schedule (preempt-queue-contexts-chain)
[360.478177] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[360.784897] [065/132] (659s left) gem_mmap_gtt (big-copy)
[360.849532] Subtest big-copy: SKIP (0.000s)
[360.919019] [066/132] (659s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[361.015757] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[361.018035] Starting dynamic subtest: pipe-A
[361.078684] Dynamic subtest pipe-A: SUCCESS (0.065s)
[361.087063] Starting dynamic subtest: pipe-B
[362.223124] Dynamic subtest pipe-B: SUCCESS (1.142s)
[362.234219] Starting dynamic subtest: pipe-C
[363.327718] Dynamic subtest pipe-C: SUCCESS (1.103s)
[363.335829] Starting dynamic subtest: pipe-D
[364.456677] Dynamic subtest pipe-D: SUCCESS (1.128s)
[364.459088] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.443s)
[365.640469] [067/132] (654s left) kms_chamelium_color (ctm-0-50)
[365.750217] Subtest ctm-0-50: SKIP (0.000s)
[365.793108] [068/132] (654s left) kms_psr (pr-primary-page-flip)
[365.891663] Starting subtest: pr-primary-page-flip
[365.896243] Subtest pr-primary-page-flip: SKIP (0.000s)
[365.989196] [069/132] (654s left) kms_atomic (test-only)
[366.099635] Starting subtest: test-only
[366.146265] Starting dynamic subtest: pipe-A-eDP-1
[368.657948] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.509s)
[368.660350] Subtest test-only: SUCCESS (2.559s)
[369.553549] [070/132] (651s left) gem_spin_batch (spin-each)
[369.631572] Starting subtest: spin-each
[372.893991] Subtest spin-each: SUCCESS (3.258s)
[374.974772] [071/132] (645s left) gem_exec_params (batch-first)
[375.036251] Starting subtest: batch-first
[375.042771] Subtest batch-first: SUCCESS (0.009s)
[375.202489] [072/132] (645s left) gem_busy (semaphore)
[375.396423] Subtest semaphore: SKIP (0.000s)
[375.667530] [073/132] (645s left) kms_psr (pr-primary-blt)
[375.773050] Starting subtest: pr-primary-blt
[375.777572] Subtest pr-primary-blt: SKIP (0.000s)
[375.870637] [074/132] (644s left) kms_dp_link_training (uhbr-mst)
[375.966441] Starting subtest: uhbr-mst
[375.975035] Subtest uhbr-mst: SKIP (0.000s)
[376.059233] [075/132] (644s left) i915_suspend (debugfs-reader)
[376.126082] Starting subtest: debugfs-reader
[393.890544] Subtest debugfs-reader: SUCCESS (7.517s)
[394.075816] [076/132] (626s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[394.197787] Starting subtest: compare-crc-sanitycheck-nv12
[394.199861] Starting dynamic subtest: pipe-A-eDP-1
[394.850132] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.633s)
[394.861150] Starting dynamic subtest: pipe-B-eDP-1
[396.090070] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.202s)
[396.099829] Starting dynamic subtest: pipe-C-eDP-1
[397.312599] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.190s)
[397.322610] Starting dynamic subtest: pipe-D-eDP-1
[398.573712] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.236s)
[398.576114] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.267s)
[399.466639] [077/132] (621s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[399.574657] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[399.578956] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[399.710879] [078/132] (621s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[399.801749] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[399.806117] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[400.913799] [079/132] (620s left) perf_pmu (busy-idle-check-all)
[400.961610] Starting subtest: busy-idle-check-all
[400.963739] Starting dynamic subtest: rcs0
[401.636476] Dynamic subtest rcs0: SUCCESS (0.667s)
[401.649208] Starting dynamic subtest: bcs0
[402.295787] Dynamic subtest bcs0: SUCCESS (0.649s)
[402.307290] Starting dynamic subtest: vcs0
[403.014226] Dynamic subtest vcs0: FAIL (0.642s)
[403.024696] Starting dynamic subtest: vcs1
[403.656819] Dynamic subtest vcs1: FAIL (0.621s)
[403.667547] Starting dynamic subtest: vecs0
[404.286135] Dynamic subtest vecs0: FAIL (0.621s)
[404.297088] Starting dynamic subtest: ccs0
[404.915424] Dynamic subtest ccs0: FAIL (0.623s)
[404.917362] Subtest busy-idle-check-all: FAIL (3.832s)
[404.996308] [080/132] (616s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[405.114514] Starting subtest: psr-2p-pri-indfb-multidraw
[405.118433] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[405.260651] [081/132] (616s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[405.359745] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[405.363796] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[405.486331] [082/132] (615s left) gem_exec_balancer (fairslice)
[405.548926] Starting subtest: fairslice
[408.414455] Subtest fairslice: SUCCESS (2.844s)
[409.217348] [083/132] (612s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[409.290261] Starting subtest: etime-single-wait-all-available-unsubmitted
[409.398613] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.107s)
[409.501483] [084/132] (611s left) gem_exec_fence (syncobj-timeline-chain-engines)
[410.083210] Starting subtest: syncobj-timeline-chain-engines
[410.238540] Subtest syncobj-timeline-chain-engines: SUCCESS (0.158s)
[411.262639] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[411.266551] [085/132] (610s left) gem_pread (display)
[411.322950] Subtest display: SKIP (0.000s)
[411.386502] [086/132] (610s left) gem_sync (basic-all)
[411.466508] Starting subtest: basic-all
[415.114814] Subtest basic-all: SUCCESS (3.645s)
[415.328139] [087/132] (606s left) gem_mmap_offset (isolation)
[415.388759] Starting subtest: isolation
[415.406739] Subtest isolation: SUCCESS (0.006s)
[415.552010] [088/132] (605s left) kms_atomic_interruptible (legacy-dpms)
[415.653304] Starting subtest: legacy-dpms
[415.655677] Starting dynamic subtest: pipe-A-eDP-1
[423.123645] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.456s)
[423.126030] Subtest legacy-dpms: SUCCESS (7.459s)
[423.985898] [089/132] (597s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[424.090651] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[424.094721] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[424.227479] [090/132] (597s left) gem_flink_basic (bad-open)
[424.287462] Starting subtest: bad-open
[424.289639] Subtest bad-open: SUCCESS (0.000s)
[424.428774] [091/132] (597s left) kms_invalid_mode (int-max-clock)
[424.520415] Starting subtest: int-max-clock
[424.522686] Starting dynamic subtest: pipe-A-eDP-1
[424.803813] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[424.813230] Starting dynamic subtest: pipe-B-eDP-1
[424.815072] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[424.823569] Starting dynamic subtest: pipe-C-eDP-1
[424.825871] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[424.834394] Starting dynamic subtest: pipe-D-eDP-1
[424.836550] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[424.838669] Subtest int-max-clock: SUCCESS (0.290s)
[425.626233] [092/132] (596s left) gem_exec_params (mmapped)
[425.684809] Starting subtest: mmapped
[425.690096] Subtest mmapped: SUCCESS (0.003s)
[425.846590] [093/132] (595s left) kms_plane_multiple (tiling-x)
[425.947331] Starting subtest: tiling-x
[425.949670] Starting dynamic subtest: pipe-A-eDP-1
[429.733037] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.785s)
[429.744228] Starting dynamic subtest: pipe-B-eDP-1
[434.070204] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.333s)
[434.081441] Starting dynamic subtest: pipe-C-eDP-1
[438.392322] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.319s)
[438.405344] Starting dynamic subtest: pipe-D-eDP-1
[442.715037] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.318s)
[442.717159] Subtest tiling-x: SUCCESS (16.761s)
[443.552312] [094/132] (578s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[443.668812] Starting subtest: fbc-rgb101010-draw-pwrite
[448.953858] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.285s)
[450.138193] [095/132] (571s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[450.230711] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[456.956672] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.724s)
[457.118486] [096/132] (564s left) kms_flip (2x-flip-vs-dpms)
[457.217975] Starting subtest: 2x-flip-vs-dpms
[457.226583] Subtest 2x-flip-vs-dpms: SKIP (0.010s)
[457.369885] [097/132] (564s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[457.470561] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[457.481744] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.010s)
[457.625116] [098/132] (564s left) kms_content_protection (atomic-hdcp14)
[457.766141] Starting subtest: atomic-hdcp14
[457.770720] Subtest atomic-hdcp14: SKIP (0.000s)
[457.918275] [099/132] (564s left) sw_sync (sync_merge)
[457.953444] Starting subtest: sync_merge
[457.955805] Subtest sync_merge: SUCCESS (0.000s)
[458.025332] [100/132] (564s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[458.120531] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[458.122377] Starting dynamic subtest: pipe-A-default-mode
[458.422226] Dynamic subtest pipe-A-default-mode: SKIP (0.297s)
[458.427118] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.298s)
[459.282310] [101/132] (562s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[459.400069] Starting subtest: cursor-vs-flip-atomic-transitions
[489.231951] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (29.815s)
[490.131735] [102/132] (531s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[490.228402] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[490.230712] Starting dynamic subtest: pipe-A-eDP-1
[490.291790] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.065s)
[490.586189] Starting dynamic subtest: pipe-B-eDP-1
[491.403510] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.825s)
[491.689021] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.463s)
[492.589817] [103/132] (529s left) kms_prime (basic-crc-vgem)
[492.674264] Starting subtest: basic-crc-vgem
[492.676575] Starting dynamic subtest: second-to-first
[493.597199] Dynamic subtest second-to-first: SUCCESS (0.927s)
[493.599106] Subtest basic-crc-vgem: SUCCESS (0.928s)
[493.703473] [104/132] (528s left) kms_properties (invalid-properties-legacy)
[493.822445] Starting subtest: invalid-properties-legacy
[493.924557] Subtest invalid-properties-legacy: SUCCESS (0.102s)
[494.028619] [105/132] (528s left) syncobj_timeline (wait-zero-handles)
[494.085144] Starting subtest: wait-zero-handles
[494.087024] Subtest wait-zero-handles: SUCCESS (0.000s)
[494.213667] [106/132] (528s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[499.218342] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[499.222669] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[500.103230] [107/132] (522s left) gem_pxp (reject-modify-context-protection-off-1)
[500.223332] Starting subtest: reject-modify-context-protection-off-1
[500.225948] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[500.363032] [108/132] (522s left) kms_selftest (drm_format_helper)
[500.404104] Starting subtest: drm_format_helper
[500.416367] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[500.554057] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.137s)
[500.565409] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[500.677121] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.123s)
[500.685983] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[500.801522] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.123s)
[500.810519] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[500.951969] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.150s)
[500.960981] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[501.092542] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.139s)
[501.103098] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[501.220790] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.128s)
[501.229791] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[501.341246] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.120s)
[501.351479] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[501.483646] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.141s)
[501.492375] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[501.615464] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.130s)
[501.625761] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[501.743266] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.129s)
[501.752136] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[501.877600] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.130s)
[501.888980] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[502.019453] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.142s)
[502.030436] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[502.138385] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.119s)
[502.149480] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[502.279298] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.139s)
[502.289894] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[502.381630] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.102s)
[502.392692] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[502.531266] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.150s)
[502.542041] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[502.686269] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.152s)
[502.688294] Subtest drm_format_helper: SUCCESS (2.281s)
[502.926022] [109/132] (519s left) gen3_mixed_blits
[503.063098] [110/132] (519s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[503.165698] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[503.170072] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[503.292264] [111/132] (519s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[503.387506] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[510.328079] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.945s)
[510.488105] [112/132] (512s left) gen7_exec_parse (cmd-crossing-page)
[510.552054] Subtest cmd-crossing-page: SKIP (0.000s)
[510.626689] [113/132] (511s left) gem_softpin (overlap)
[510.676044] Starting subtest: overlap
[511.547636] Subtest overlap: SUCCESS (0.870s)
[511.718042] [114/132] (510s left) gem_eio (in-flight-internal-immediate)
[511.961803] Starting subtest: in-flight-internal-immediate
[513.249997] Subtest in-flight-internal-immediate: SUCCESS (1.286s)
[513.432796] [115/132] (509s left) gem_wait (write-wait)
[513.498638] Starting subtest: write-wait
[513.500951] Starting dynamic subtest: all-engines
[514.647796] Dynamic subtest all-engines: SUCCESS (1.149s)
[514.664355] Starting dynamic subtest: rcs0
[515.705837] Dynamic subtest rcs0: SUCCESS (1.055s)
[515.722993] Starting dynamic subtest: bcs0
[516.764518] Dynamic subtest bcs0: SUCCESS (1.057s)
[516.780620] Starting dynamic subtest: vcs0
[517.818651] Dynamic subtest vcs0: SUCCESS (1.052s)
[517.836263] Starting dynamic subtest: vcs1
[518.874556] Dynamic subtest vcs1: SUCCESS (1.053s)
[518.891277] Starting dynamic subtest: vecs0
[519.926496] Dynamic subtest vecs0: SUCCESS (1.050s)
[519.944199] Starting dynamic subtest: ccs0
[520.984432] Dynamic subtest ccs0: SUCCESS (1.055s)
[520.986910] Subtest write-wait: SUCCESS (7.487s)
[521.206945] [116/132] (501s left) kms_chamelium_edid (dp-edid-read)
[521.604734] Subtest dp-edid-read: SKIP (0.000s)
[522.436440] [117/132] (500s left) gem_exec_balancer (parallel-bb-first)
[522.691089] Starting subtest: parallel-bb-first
[523.578113] Subtest parallel-bb-first: SUCCESS (0.886s)
[524.233328] [118/132] (498s left) kms_pm_rpm (fences)
[526.360740] Starting subtest: fences
[526.365899] Subtest fences: SKIP (0.000s)
[526.752969] [119/132] (496s left) gem_ctx_persistence (legacy-engines-queued)
[526.820637] Starting subtest: legacy-engines-queued
[526.823130] Starting dynamic subtest: render
[526.916782] Dynamic subtest render: SUCCESS (0.095s)
[526.929381] Starting dynamic subtest: bsd1
[528.103706] Dynamic subtest bsd1: SUCCESS (1.183s)
[528.116595] Starting dynamic subtest: bsd2
[530.088630] Dynamic subtest bsd2: SUCCESS (1.981s)
[530.101385] Starting dynamic subtest: blt
[530.125852] Dynamic subtest blt: SUCCESS (0.036s)
[530.135418] Starting dynamic subtest: vebox
[532.072742] Dynamic subtest vebox: SUCCESS (1.943s)
[532.075653] Subtest legacy-engines-queued: SUCCESS (5.252s)
[532.178981] [120/132] (490s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[532.292484] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[532.949545] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.659s)
[533.168175] [121/132] (489s left) gem_mmap_gtt (basic-wc)
[533.230731] Subtest basic-wc: SKIP (0.000s)
[533.302297] [122/132] (489s left) core_auth (getclient-simple)
[533.334917] Starting subtest: getclient-simple
[533.358444] Subtest getclient-simple: SUCCESS (0.025s)
[533.495520] [123/132] (489s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[533.586286] Starting subtest: addfb25-y-tiled-small-legacy
[533.590318] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[533.713176] [124/132] (489s left) perf (invalid-oa-exponent)
[533.816849] Starting subtest: invalid-oa-exponent
[533.829732] Subtest invalid-oa-exponent: SUCCESS (0.011s)
[533.874889] [125/132] (489s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[533.961936] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[533.963810] Starting dynamic subtest: pipe-A-default-mode
[535.079428] Dynamic subtest pipe-A-default-mode: SKIP (1.113s)
[535.083505] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.115s)
[535.274897] [126/132] (487s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[535.377152] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[535.379452] Starting dynamic subtest: A-eDP1
[538.982353] Dynamic subtest A-eDP1: SUCCESS (3.604s)
[538.994316] Starting dynamic subtest: B-eDP1
[542.183440] Dynamic subtest B-eDP1: SUCCESS (3.198s)
[542.193453] Starting dynamic subtest: C-eDP1
[545.283085] Dynamic subtest C-eDP1: SUCCESS (3.099s)
[545.294994] Starting dynamic subtest: D-eDP1
[548.382553] Dynamic subtest D-eDP1: SUCCESS (3.098s)
[548.385106] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.007s)
[549.208731] [127/132] (473s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[549.271109] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[549.273161] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[549.400074] [128/132] (473s left) kms_vrr (max-min)
[549.500941] Starting subtest: max-min
[549.507241] Subtest max-min: SKIP (0.000s)
[549.600035] [129/132] (473s left) kms_rotation_crc (sprite-rotation-90)
[549.695194] Starting subtest: sprite-rotation-90
[549.699536] Subtest sprite-rotation-90: SKIP (0.001s)
[549.790694] [130/132] (473s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[549.886339] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[549.890628] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[550.024206] [131/132] (473s left) kms_cursor_crc (cursor-offscreen-64x64)
[550.196967] Starting subtest: cursor-offscreen-64x64
[550.199298] Starting dynamic subtest: pipe-A-eDP-1
[552.133718] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.935s)
[552.163882] Starting dynamic subtest: pipe-D-eDP-1
[554.693732] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.536s)
[554.696118] Subtest cursor-offscreen-64x64: SUCCESS (4.497s)
[555.580734] [132/132] (467s left) kms_color_pipeline (plane-lut1d-lut1d)
[555.682184] Starting subtest: plane-lut1d-lut1d
[555.695522] Starting dynamic subtest: pipe-A-eDP-1
[556.382696] Dynamic subtest pipe-A-eDP-1: FAIL (0.293s)
[557.032163] Starting dynamic subtest: pipe-B-eDP-1
[557.838505] Dynamic subtest pipe-B-eDP-1: FAIL (0.279s)
[558.365839] Starting dynamic subtest: pipe-C-eDP-1
[559.203115] Dynamic subtest pipe-C-eDP-1: FAIL (0.258s)
[559.703539] Starting dynamic subtest: pipe-D-eDP-1
[560.552869] Dynamic subtest pipe-D-eDP-1: FAIL (0.246s)
[560.555343] Subtest plane-lut1d-lut1d: FAIL (4.540s)
[561.105049] 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.