[60.329268] Initializing watchdogs
[60.329318]   /dev/watchdog0
[60.343713] [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]
[60.348224] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.350712] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.353646] [001/132] (960s left) gem_exec_store (cachelines)
[60.442782] Starting subtest: cachelines
[60.445180] Starting dynamic subtest: rcs0
[60.552559] Dynamic subtest rcs0: SUCCESS (0.111s)
[60.562048] Starting dynamic subtest: bcs0
[60.622097] Dynamic subtest bcs0: SUCCESS (0.069s)
[60.630987] Starting dynamic subtest: vcs0
[60.682308] Dynamic subtest vcs0: SUCCESS (0.060s)
[60.691421] Starting dynamic subtest: vcs1
[60.746693] Dynamic subtest vcs1: SUCCESS (0.064s)
[60.755425] Starting dynamic subtest: vecs0
[60.809186] Dynamic subtest vecs0: SUCCESS (0.063s)
[60.817819] Starting dynamic subtest: ccs0
[60.877663] Dynamic subtest ccs0: SUCCESS (0.069s)
[60.880070] Subtest cachelines: SUCCESS (0.438s)
[61.080157] [002/132] (959s left) gem_pread (bench)
[61.133720] Subtest bench: SKIP (0.000s)
[61.197446] [003/132] (959s left) syncobj_wait (invalid-signal-illegal-handle)
[61.266142] Starting subtest: invalid-signal-illegal-handle
[61.268548] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[61.399392] [004/132] (959s left) kms_vblank (query-forked-busy-hang)
[61.503872] Starting subtest: query-forked-busy-hang
[61.505929] Starting dynamic subtest: pipe-A-eDP-1
[87.704166] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.234s)
[87.717360] Starting dynamic subtest: pipe-D-eDP-1
[102.678061] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.969s)
[102.680361] Subtest query-forked-busy-hang: SUCCESS (41.206s)
[103.519343] [005/132] (917s left) kms_psr (fbc-pr-primary-mmap-cpu)
[103.628788] Starting subtest: fbc-pr-primary-mmap-cpu
[103.633281] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[103.723734] [006/132] (917s left) kms_plane_cursor (overlay)
[103.829620] Starting subtest: overlay
[103.834087] Starting dynamic subtest: pipe-A-eDP-1-size-64
[106.373214] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.544s)
[106.433524] Starting dynamic subtest: pipe-A-eDP-1-size-128
[108.975401] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.549s)
[109.035045] Starting dynamic subtest: pipe-A-eDP-1-size-256
[111.577124] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.549s)
[111.924901] Starting dynamic subtest: pipe-B-eDP-1-size-64
[115.071747] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.159s)
[115.130950] Starting dynamic subtest: pipe-B-eDP-1-size-128
[117.650425] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.526s)
[117.708405] Starting dynamic subtest: pipe-B-eDP-1-size-256
[120.173953] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.471s)
[120.519514] Starting dynamic subtest: pipe-C-eDP-1-size-64
[123.359409] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.847s)
[123.396585] Starting dynamic subtest: pipe-C-eDP-1-size-128
[125.603122] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.213s)
[125.638965] Starting dynamic subtest: pipe-C-eDP-1-size-256
[127.726643] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.091s)
[128.052598] Starting dynamic subtest: pipe-D-eDP-1-size-64
[130.922900] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.877s)
[130.958347] Starting dynamic subtest: pipe-D-eDP-1-size-128
[133.055147] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.102s)
[133.091758] Starting dynamic subtest: pipe-D-eDP-1-size-256
[135.189151] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.101s)
[135.509469] Subtest overlay: SUCCESS (31.678s)
[136.324600] [007/132] (884s left) perf (i915-ref-count)
[136.414949] Starting subtest: i915-ref-count
[136.476607] Subtest i915-ref-count: SUCCESS (0.060s)
[136.529056] [008/132] (884s left) gem_exec_schedule (smoketest-all)
[136.601870] Starting subtest: smoketest-all
[168.955719] Subtest smoketest-all: SUCCESS (32.312s)
[170.027882] [009/132] (851s left) gem_lmem_swapping (heavy-random)
[170.073357] Subtest heavy-random: SKIP (0.000s)
[170.119982] [010/132] (851s left) kms_psr (psr-primary-mmap-gtt)
[170.224939] Starting subtest: psr-primary-mmap-gtt
[170.227178] Starting dynamic subtest: eDP-1
[171.702294] Dynamic subtest eDP-1: SKIP (1.474s)
[171.707214] Subtest psr-primary-mmap-gtt: SKIP (1.477s)
[171.905102] [011/132] (849s left) perf (whitelisted-registers-userspace-config)
[172.010824] Starting subtest: whitelisted-registers-userspace-config
[172.013614] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[172.054550] [012/132] (849s left) gem_ppgtt (blt-vs-render-ctx0)
[172.117401] Starting subtest: blt-vs-render-ctx0
[205.175424] Subtest blt-vs-render-ctx0: SUCCESS (33.054s)
[205.349309] [013/132] (815s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[205.506114] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[205.510072] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[205.636864] [014/132] (815s left) gem_mmap_gtt (basic)
[205.713808] Subtest basic: SKIP (0.000s)
[205.780543] [015/132] (815s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[205.852534] Starting subtest: invalid-single-wait-all-unsubmitted
[205.854923] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[205.985587] [016/132] (815s left) gem_ctx_engines (execute-one)
[206.052207] Starting subtest: execute-one
[208.411992] Subtest execute-one: SUCCESS (2.360s)
[208.636683] [017/132] (812s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[208.737742] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[208.741980] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[208.864527] [018/132] (812s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[208.961949] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[208.964770] Starting dynamic subtest: pipe-A-eDP-1
[208.985805] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[208.999419] Starting dynamic subtest: pipe-B-eDP-1
[209.018997] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[209.028736] Starting dynamic subtest: pipe-C-eDP-1
[209.047917] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[209.056227] Starting dynamic subtest: pipe-D-eDP-1
[209.075521] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[209.077717] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[211.153504] [019/132] (810s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[211.212100] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[211.285475] [020/132] (810s left) api_intel_allocator (two-level-inception-interruptible)
[211.346749] Starting subtest: two-level-inception-interruptible
[216.750676] Subtest two-level-inception-interruptible: SUCCESS (5.405s)
[216.905198] [021/132] (804s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[221.902257] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[221.904172] Starting dynamic subtest: pipe-A-eDP-1
[228.566959] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.664s)
[228.575520] Starting dynamic subtest: pipe-B-eDP-1
[235.212461] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.642s)
[235.214690] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.310s)
[236.107322] [022/132] (785s left) i915_pciid
[236.279596] [023/132] (785s left) i915_pm_rpm (sysfs-read)
[238.029740] Starting subtest: sysfs-read
[239.184494] Subtest sysfs-read: SUCCESS (1.154s)
[239.596824] [024/132] (782s left) gem_exec_reloc (basic-cpu-noreloc)
[239.673443] Subtest basic-cpu-noreloc: SKIP (0.000s)
[239.759708] [025/132] (782s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[239.856621] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[239.861047] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[239.975041] [026/132] (781s left) sw_sync (timeline_closed_signaled)
[240.016302] Starting subtest: timeline_closed_signaled
[240.018528] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[240.099134] [027/132] (781s left) gem_eio (context-create)
[240.346379] Starting subtest: context-create
[240.683319] Subtest context-create: SUCCESS (0.336s)
[240.828949] [028/132] (781s left) kms_fb_coherency (memset-crc)
[240.917319] Starting subtest: memset-crc
[240.919102] Starting dynamic subtest: mmap-offset-wc
[241.278657] Dynamic subtest mmap-offset-wc: SUCCESS (0.362s)
[241.567290] Starting dynamic subtest: mmap-offset-uc
[247.623876] Dynamic subtest mmap-offset-uc: SUCCESS (6.062s)
[247.912255] Subtest memset-crc: SUCCESS (6.996s)
[248.739229] [029/132] (773s left) gem_ctx_persistence (engines-hostile-preempt)
[248.804460] Starting subtest: engines-hostile-preempt
[248.806727] Starting dynamic subtest: rcs0
[248.878427] Dynamic subtest rcs0: SUCCESS (0.074s)
[248.889126] Starting dynamic subtest: bcs0
[248.955189] Dynamic subtest bcs0: SUCCESS (0.075s)
[248.966081] Starting dynamic subtest: vcs0
[250.884410] Dynamic subtest vcs0: SUCCESS (1.926s)
[250.894334] Starting dynamic subtest: vcs1
[252.882711] Dynamic subtest vcs1: SUCCESS (1.996s)
[252.893253] Starting dynamic subtest: vecs0
[254.914536] Dynamic subtest vecs0: SUCCESS (2.029s)
[254.925070] Starting dynamic subtest: ccs0
[255.033406] Dynamic subtest ccs0: SUCCESS (0.116s)
[255.036331] Subtest engines-hostile-preempt: SUCCESS (6.229s)
[255.144415] [030/132] (766s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[255.252621] Starting subtest: psr-rgb565-draw-pwrite
[262.206418] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.956s)
[262.344364] [031/132] (759s left) gem_render_copy (y-tiled-to-vebox-linear)
[262.417184] Starting subtest: y-tiled-to-vebox-linear
[262.421642] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[262.622128] [032/132] (759s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[262.683843] Starting subtest: multi-wait-for-submit-submitted-signaled
[262.686326] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[262.823691] [033/132] (759s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[262.933997] Starting subtest: x-tiled-64bpp-rotate-90
[262.954319] Subtest x-tiled-64bpp-rotate-90: SKIP (0.020s)
[263.110828] [034/132] (759s left) kms_psr (fbc-psr2-cursor-render)
[263.215070] Starting subtest: fbc-psr2-cursor-render
[263.217327] Starting dynamic subtest: eDP-1
[263.221672] Dynamic subtest eDP-1: SKIP (0.000s)
[263.226085] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[263.308167] [035/132] (758s left) kms_getfb (getfb-handle-protection)
[263.404164] Starting subtest: getfb-handle-protection
[263.425586] Subtest getfb-handle-protection: SUCCESS (0.022s)
[263.560393] [036/132] (758s left) sw_sync (sync_merge_invalid)
[263.594623] Starting subtest: sync_merge_invalid
[263.623148] Subtest sync_merge_invalid: SUCCESS (0.030s)
[263.710502] [037/132] (758s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[263.796832] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[268.389732] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.594s)
[269.578018] [038/132] (752s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[269.683580] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[277.019197] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.340s)
[277.142910] [039/132] (745s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[277.260263] Starting subtest: cursorB-vs-flipA-toggle
[277.264602] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[277.425959] [040/132] (744s left) kms_rmfb (rmfb-ioctl)
[277.532954] Starting subtest: rmfb-ioctl
[277.534790] Starting dynamic subtest: pipe-A-eDP-1
[277.637573] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[277.648329] Starting dynamic subtest: pipe-B-eDP-1
[278.833671] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.194s)
[278.847728] Starting dynamic subtest: pipe-C-eDP-1
[279.966769] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.130s)
[279.978345] Starting dynamic subtest: pipe-D-eDP-1
[281.096498] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.126s)
[281.098880] Subtest rmfb-ioctl: SUCCESS (3.566s)
[282.340319] [041/132] (740s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[282.726490] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[283.521500] [042/132] (738s left) sysfs_timeslice_duration (invalid)
[283.581671] Starting subtest: invalid
[283.584097] Starting dynamic subtest: vecs0
[283.586453] Dynamic subtest vecs0: SUCCESS (0.000s)
[283.595867] Starting dynamic subtest: ccs0
[283.598172] Dynamic subtest ccs0: SUCCESS (0.000s)
[283.606136] Starting dynamic subtest: vcs0
[283.608376] Dynamic subtest vcs0: SUCCESS (0.000s)
[283.616542] Starting dynamic subtest: bcs0
[283.618850] Dynamic subtest bcs0: SUCCESS (0.000s)
[283.626885] Starting dynamic subtest: rcs0
[283.629145] Dynamic subtest rcs0: SUCCESS (0.000s)
[283.637420] Starting dynamic subtest: vcs1
[283.639704] Dynamic subtest vcs1: SUCCESS (0.000s)
[283.642033] Subtest invalid: SUCCESS (0.006s)
[283.766162] [043/132] (738s left) syncobj_eventfd (timeline-wait)
[283.829301] Starting subtest: timeline-wait
[283.836854] Subtest timeline-wait: SUCCESS (0.011s)
[283.969456] [044/132] (738s left) kms_pm_backlight (brightness-with-dpms)
[284.070162] Starting subtest: brightness-with-dpms
[284.146431] Starting dynamic subtest: eDP-1
[286.678866] Dynamic subtest eDP-1: SUCCESS (2.536s)
[286.681258] Subtest brightness-with-dpms: SUCCESS (2.611s)
[287.560910] [045/132] (735s left) kms_busy (extended-modeset-hang-oldfb)
[287.664852] Starting subtest: extended-modeset-hang-oldfb
[287.667071] Starting dynamic subtest: pipe-A
[299.428596] Dynamic subtest pipe-A: SUCCESS (11.763s)
[299.437992] Starting dynamic subtest: pipe-D
[311.796650] Dynamic subtest pipe-D: SUCCESS (12.364s)
[311.799092] Subtest extended-modeset-hang-oldfb: SUCCESS (24.132s)
[312.622597] [046/132] (710s left) syncobj_wait (multi-wait-all-signaled)
[312.672288] Starting subtest: multi-wait-all-signaled
[312.674510] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[312.803084] [047/132] (709s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[312.899178] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[312.901913] Starting dynamic subtest: pipe-A-eDP-1
[312.926692] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[312.937669] Starting dynamic subtest: pipe-B-eDP-1
[312.959882] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[312.967796] Starting dynamic subtest: pipe-C-eDP-1
[312.989933] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[312.997505] Starting dynamic subtest: pipe-D-eDP-1
[313.020034] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[313.024453] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[315.177282] [048/132] (707s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[315.285756] Starting subtest: yf-tiled-8bpp-rotate-180
[315.290485] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[315.416045] [049/132] (707s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[315.524932] Starting subtest: psr-rgb565-draw-mmap-cpu
[322.466316] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.944s)
[322.614794] [050/132] (700s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[322.714483] Starting subtest: yf-tiled-16bpp-rotate-90
[322.718542] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[322.831854] [051/132] (700s left) kms_addfb_basic (clobberred-modifier)
[322.891547] Starting subtest: clobberred-modifier
[322.895713] Subtest clobberred-modifier: SKIP (0.000s)
[323.057090] [052/132] (699s left) gem_exec_reloc (basic-write-read-active)
[323.126774] Subtest basic-write-read-active: SKIP (0.000s)
[323.197981] [053/132] (699s left) dmabuf (all-tests)
[323.236998] Starting subtest: all-tests
[323.239383] Starting dynamic subtest: sanitycheck
[323.367533] Dynamic subtest sanitycheck: SUCCESS (0.130s)
[323.378991] Starting dynamic subtest: dma_fence
[323.636391] Dynamic subtest dma_fence: SUCCESS (0.265s)
[323.647069] Starting dynamic subtest: dma_fence_chain
[331.995452] Dynamic subtest dma_fence_chain: SUCCESS (8.356s)
[332.007259] Starting dynamic subtest: dma_fence_unwrap
[332.102248] Dynamic subtest dma_fence_unwrap: SUCCESS (0.105s)
[332.113996] Starting dynamic subtest: dma_resv
[332.236246] Dynamic subtest dma_resv: SUCCESS (0.130s)
[332.239021] Subtest all-tests: SUCCESS (8.999s)
[332.326657] [054/132] (690s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[332.641492] Starting subtest: cursor-rapid-movement-512x170
[332.645638] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[332.825815] [055/132] (690s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[332.890593] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[332.892709] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[333.015999] [056/132] (690s left) kms_big_fb (linear-16bpp-rotate-90)
[333.116528] Starting subtest: linear-16bpp-rotate-90
[333.136205] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[333.292552] [057/132] (689s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[333.401124] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[333.404468] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[333.538660] [058/132] (689s left) gem_exec_schedule (noreorder-priority)
[333.726676] Starting subtest: noreorder-priority
[333.729181] Starting dynamic subtest: rcs0
[334.884076] Dynamic subtest rcs0: SUCCESS (1.158s)
[334.894063] Starting dynamic subtest: vcs0
[336.934245] Dynamic subtest vcs0: SUCCESS (2.047s)
[336.945896] Starting dynamic subtest: vcs1
[338.926459] Dynamic subtest vcs1: SUCCESS (1.989s)
[338.938058] Starting dynamic subtest: vecs0
[340.866685] Dynamic subtest vecs0: SUCCESS (1.938s)
[340.877499] Starting dynamic subtest: ccs0
[342.011806] Dynamic subtest ccs0: SUCCESS (1.144s)
[342.014491] Subtest noreorder-priority: SUCCESS (8.287s)
[342.223473] [059/132] (680s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[342.322693] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[348.968952] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.648s)
[349.142338] [060/132] (674s left) perf (polling-small-buf)
[349.269191] Starting subtest: polling-small-buf
[349.359061] Subtest polling-small-buf: SUCCESS (0.087s)
[349.403589] [061/132] (673s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[349.512053] Starting subtest: bad-pixel-format-y-tiled-ccs
[349.514307] Starting dynamic subtest: pipe-A-eDP-1
[349.536914] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[349.548042] Starting dynamic subtest: pipe-B-eDP-1
[349.569352] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[349.577163] Starting dynamic subtest: pipe-C-eDP-1
[349.598272] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[349.605858] Starting dynamic subtest: pipe-D-eDP-1
[349.627742] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[349.632146] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.005s)
[351.798453] [062/132] (671s left) i915_drm_fdinfo (busy-hang)
[351.841597] Starting subtest: busy-hang
[351.843757] Starting dynamic subtest: rcs0
[351.878833] Dynamic subtest rcs0: SKIP (0.036s)
[351.889579] Starting dynamic subtest: bcs0
[351.893777] Dynamic subtest bcs0: SKIP (0.001s)
[351.902420] Starting dynamic subtest: vcs0
[351.906379] Dynamic subtest vcs0: SKIP (0.001s)
[351.914631] Starting dynamic subtest: vcs1
[351.918840] Dynamic subtest vcs1: SKIP (0.001s)
[351.926939] Starting dynamic subtest: vecs0
[351.931169] Dynamic subtest vecs0: SKIP (0.000s)
[351.939063] Starting dynamic subtest: ccs0
[351.943189] Dynamic subtest ccs0: SKIP (0.000s)
[351.947452] Subtest busy-hang: SKIP (0.046s)
[351.981560] [063/132] (671s left) kms_cursor_crc (cursor-sliding-max-size)
[352.293358] Starting subtest: cursor-sliding-max-size
[352.299771] Subtest cursor-sliding-max-size: SKIP (0.000s)
[352.444818] [064/132] (670s left) gem_exec_schedule (preempt-queue-contexts-chain)
[352.528119] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[352.838786] [065/132] (670s left) gem_mmap_gtt (big-copy)
[352.904266] Subtest big-copy: SKIP (0.000s)
[352.966477] [066/132] (670s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[353.068130] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[353.070349] Starting dynamic subtest: pipe-A
[353.131346] Dynamic subtest pipe-A: SUCCESS (0.065s)
[353.141324] Starting dynamic subtest: pipe-B
[354.292638] Dynamic subtest pipe-B: SUCCESS (1.160s)
[354.302653] Starting dynamic subtest: pipe-C
[355.396484] Dynamic subtest pipe-C: SUCCESS (1.103s)
[355.405218] Starting dynamic subtest: pipe-D
[356.519955] Dynamic subtest pipe-D: SUCCESS (1.123s)
[356.522324] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.454s)
[357.695513] [067/132] (665s left) kms_chamelium_color (ctm-0-50)
[357.799278] Subtest ctm-0-50: SKIP (0.000s)
[357.842336] [068/132] (665s left) kms_psr (pr-primary-page-flip)
[357.940870] Starting subtest: pr-primary-page-flip
[357.945284] Subtest pr-primary-page-flip: SKIP (0.000s)
[358.034841] [069/132] (665s left) kms_atomic (test-only)
[358.134280] Starting subtest: test-only
[358.180298] Starting dynamic subtest: pipe-A-eDP-1
[360.702229] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.519s)
[360.704584] Subtest test-only: SUCCESS (2.569s)
[361.517894] [070/132] (662s left) gem_spin_batch (spin-each)
[361.586046] Starting subtest: spin-each
[364.857916] Subtest spin-each: SUCCESS (3.263s)
[365.058252] [071/132] (658s left) gem_exec_params (batch-first)
[365.116844] Starting subtest: batch-first
[365.127355] Subtest batch-first: SUCCESS (0.011s)
[365.304210] [072/132] (658s left) gem_busy (semaphore)
[365.500157] Subtest semaphore: SKIP (0.000s)
[365.781112] [073/132] (657s left) kms_psr (pr-primary-blt)
[365.879950] Starting subtest: pr-primary-blt
[365.884575] Subtest pr-primary-blt: SKIP (0.000s)
[365.976971] [074/132] (657s left) kms_dp_link_training (uhbr-mst)
[366.060665] Starting subtest: uhbr-mst
[366.069151] Subtest uhbr-mst: SKIP (0.000s)
[366.152064] [075/132] (657s left) i915_suspend (debugfs-reader)
[366.211495] Starting subtest: debugfs-reader
[384.911285] Subtest debugfs-reader: SUCCESS (7.769s)
[385.081098] [076/132] (638s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[385.183650] Starting subtest: compare-crc-sanitycheck-nv12
[385.185737] Starting dynamic subtest: pipe-A-eDP-1
[385.877745] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.723s)
[385.889631] Starting dynamic subtest: pipe-B-eDP-1
[387.186662] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.352s)
[387.195728] Starting dynamic subtest: pipe-C-eDP-1
[388.383311] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.228s)
[388.392159] Starting dynamic subtest: pipe-D-eDP-1
[389.608788] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.255s)
[389.611036] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.568s)
[390.431060] [077/132] (633s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[390.532649] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[390.536651] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[390.656270] [078/132] (633s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[390.743584] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[390.747964] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[391.832598] [079/132] (632s left) perf_pmu (busy-idle-check-all)
[391.876935] Starting subtest: busy-idle-check-all
[391.878850] Starting dynamic subtest: rcs0
[392.545045] Dynamic subtest rcs0: SUCCESS (0.681s)
[392.556353] Starting dynamic subtest: bcs0
[393.194978] Dynamic subtest bcs0: SUCCESS (0.658s)
[393.206910] Starting dynamic subtest: vcs0
[393.901683] Dynamic subtest vcs0: FAIL (0.651s)
[393.912385] Starting dynamic subtest: vcs1
[394.539806] Dynamic subtest vcs1: FAIL (0.629s)
[394.550672] Starting dynamic subtest: vecs0
[395.161282] Dynamic subtest vecs0: FAIL (0.628s)
[395.171975] Starting dynamic subtest: ccs0
[395.785795] Dynamic subtest ccs0: FAIL (0.630s)
[395.788086] Subtest busy-idle-check-all: FAIL (3.884s)
[396.932963] [080/132] (627s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[397.041676] Starting subtest: psr-2p-pri-indfb-multidraw
[397.046127] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[397.186134] [081/132] (626s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[397.282454] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[397.286783] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[397.413024] [082/132] (626s left) gem_exec_balancer (fairslice)
[397.473800] Starting subtest: fairslice
[400.362784] Subtest fairslice: SUCCESS (2.907s)
[400.949487] [083/132] (623s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[401.017713] Starting subtest: etime-single-wait-all-available-unsubmitted
[401.118654] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.102s)
[401.227030] [084/132] (623s left) gem_exec_fence (syncobj-timeline-chain-engines)
[401.808757] Starting subtest: syncobj-timeline-chain-engines
[401.991492] Subtest syncobj-timeline-chain-engines: SUCCESS (0.183s)
[402.977489] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[402.980809] [085/132] (621s left) gem_pread (display)
[403.054792] Subtest display: SKIP (0.000s)
[403.123215] [086/132] (621s left) gem_sync (basic-all)
[403.199859] Starting subtest: basic-all
[406.288472] Subtest basic-all: SUCCESS (3.107s)
[406.500049] [087/132] (617s left) gem_mmap_offset (isolation)
[406.558890] Starting subtest: isolation
[406.576772] Subtest isolation: SUCCESS (0.006s)
[406.711547] [088/132] (617s left) kms_atomic_interruptible (legacy-dpms)
[406.840081] Starting subtest: legacy-dpms
[406.842345] Starting dynamic subtest: pipe-A-eDP-1
[414.230716] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.403s)
[414.233158] Subtest legacy-dpms: SUCCESS (7.405s)
[415.068699] [089/132] (609s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[415.176567] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[415.181092] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[415.278676] [090/132] (609s left) gem_flink_basic (bad-open)
[415.342561] Starting subtest: bad-open
[415.344711] Subtest bad-open: SUCCESS (0.000s)
[415.485747] [091/132] (609s left) kms_invalid_mode (int-max-clock)
[415.591328] Starting subtest: int-max-clock
[415.593608] Starting dynamic subtest: pipe-A-eDP-1
[415.874806] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.283s)
[415.884856] Starting dynamic subtest: pipe-B-eDP-1
[415.887185] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[415.896727] Starting dynamic subtest: pipe-C-eDP-1
[415.899111] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[415.908302] Starting dynamic subtest: pipe-D-eDP-1
[415.910645] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[415.912998] Subtest int-max-clock: SUCCESS (0.289s)
[416.703223] [092/132] (607s left) gem_exec_params (mmapped)
[416.778382] Starting subtest: mmapped
[416.780417] Subtest mmapped: SUCCESS (0.005s)
[416.940155] [093/132] (607s left) kms_plane_multiple (tiling-x)
[417.039975] Starting subtest: tiling-x
[417.042252] Starting dynamic subtest: pipe-A-eDP-1
[420.705159] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.663s)
[420.714951] Starting dynamic subtest: pipe-B-eDP-1
[425.051036] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.342s)
[425.064505] Starting dynamic subtest: pipe-C-eDP-1
[429.375629] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.320s)
[429.386244] Starting dynamic subtest: pipe-D-eDP-1
[433.735201] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.359s)
[433.737461] Subtest tiling-x: SUCCESS (16.688s)
[434.565433] [094/132] (590s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[434.673347] Starting subtest: fbc-rgb101010-draw-pwrite
[440.022122] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.352s)
[441.176971] [095/132] (583s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[441.281699] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[448.132770] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.854s)
[448.338422] [096/132] (576s left) kms_flip (2x-flip-vs-dpms)
[448.437171] Starting subtest: 2x-flip-vs-dpms
[448.449675] Subtest 2x-flip-vs-dpms: SKIP (0.014s)
[448.551172] [097/132] (576s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[448.650392] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[448.662083] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[448.760067] [098/132] (576s left) kms_content_protection (atomic-hdcp14)
[448.899132] Starting subtest: atomic-hdcp14
[448.903822] Subtest atomic-hdcp14: SKIP (0.000s)
[449.055490] [099/132] (575s left) sw_sync (sync_merge)
[449.093642] Starting subtest: sync_merge
[449.095626] Subtest sync_merge: SUCCESS (0.000s)
[449.179683] [100/132] (575s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[449.288609] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[449.290636] Starting dynamic subtest: pipe-A-default-mode
[449.591958] Dynamic subtest pipe-A-default-mode: SKIP (0.300s)
[449.596684] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.301s)
[450.423644] [101/132] (574s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[450.545856] Starting subtest: cursor-vs-flip-atomic-transitions
[480.394408] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (29.855s)
[481.259040] [102/132] (543s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[481.351708] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[481.353981] Starting dynamic subtest: pipe-A-eDP-1
[481.413559] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.062s)
[481.724844] Starting dynamic subtest: pipe-B-eDP-1
[482.510320] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.795s)
[482.796260] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.445s)
[483.697342] [103/132] (541s left) kms_prime (basic-crc-vgem)
[483.787326] Starting subtest: basic-crc-vgem
[483.789714] Starting dynamic subtest: second-to-first
[484.726239] Dynamic subtest second-to-first: SUCCESS (0.943s)
[484.728182] Subtest basic-crc-vgem: SUCCESS (0.943s)
[484.842160] [104/132] (540s left) kms_properties (invalid-properties-legacy)
[484.976541] Starting subtest: invalid-properties-legacy
[485.084873] Subtest invalid-properties-legacy: SUCCESS (0.108s)
[485.186279] [105/132] (539s left) syncobj_timeline (wait-zero-handles)
[485.247076] Starting subtest: wait-zero-handles
[485.249349] Subtest wait-zero-handles: SUCCESS (0.000s)
[485.370365] [106/132] (539s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[490.354238] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[490.358721] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.003s)
[491.216295] [107/132] (534s left) gem_pxp (reject-modify-context-protection-off-1)
[491.352544] Starting subtest: reject-modify-context-protection-off-1
[491.354812] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[491.482256] [108/132] (533s left) kms_selftest (drm_format_helper)
[491.526400] Starting subtest: drm_format_helper
[491.537534] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[491.648334] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.109s)
[491.659386] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[491.760223] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.112s)
[491.771959] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[491.921094] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.159s)
[491.931800] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[492.062167] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.140s)
[492.073852] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[492.216236] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.153s)
[492.227139] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[492.368179] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.152s)
[492.379504] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[492.486118] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.116s)
[492.497015] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[492.603030] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.115s)
[492.612369] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[492.727172] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.124s)
[492.738178] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[492.835188] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.107s)
[492.846290] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[492.952659] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.117s)
[492.963918] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[493.071155] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.115s)
[493.080439] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[493.217246] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.146s)
[493.228246] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[493.346187] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.129s)
[493.357431] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[493.495926] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.126s)
[493.507379] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[493.625385] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.150s)
[493.634895] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[493.752098] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.126s)
[493.754408] Subtest drm_format_helper: SUCCESS (2.225s)
[493.988825] [109/132] (531s left) gen3_mixed_blits
[494.139807] [110/132] (531s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[494.237433] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[494.241495] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[494.375650] [111/132] (531s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[494.469268] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[501.401271] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.934s)
[501.564091] [112/132] (523s left) gen7_exec_parse (cmd-crossing-page)
[501.635560] Subtest cmd-crossing-page: SKIP (0.000s)
[501.722183] [113/132] (523s left) gem_softpin (overlap)
[501.785418] Starting subtest: overlap
[502.671679] Subtest overlap: SUCCESS (0.884s)
[502.836909] [114/132] (522s left) gem_eio (in-flight-internal-immediate)
[503.087342] Starting subtest: in-flight-internal-immediate
[504.959693] Subtest in-flight-internal-immediate: SUCCESS (1.873s)
[505.143427] [115/132] (520s left) gem_wait (write-wait)
[505.208677] Starting subtest: write-wait
[505.211128] Starting dynamic subtest: all-engines
[506.343496] Dynamic subtest all-engines: SUCCESS (1.133s)
[506.362711] Starting dynamic subtest: rcs0
[507.403565] Dynamic subtest rcs0: SUCCESS (1.058s)
[507.420575] Starting dynamic subtest: bcs0
[508.462140] Dynamic subtest bcs0: SUCCESS (1.056s)
[508.478499] Starting dynamic subtest: vcs0
[509.517835] Dynamic subtest vcs0: SUCCESS (1.053s)
[509.535104] Starting dynamic subtest: vcs1
[510.571381] Dynamic subtest vcs1: SUCCESS (1.052s)
[510.588883] Starting dynamic subtest: vecs0
[511.623682] Dynamic subtest vecs0: SUCCESS (1.050s)
[511.640772] Starting dynamic subtest: ccs0
[512.679297] Dynamic subtest ccs0: SUCCESS (1.054s)
[512.682328] Subtest write-wait: SUCCESS (7.471s)
[512.902696] [116/132] (512s left) kms_chamelium_edid (dp-edid-read)
[513.295390] Subtest dp-edid-read: SKIP (0.000s)
[514.117848] [117/132] (511s left) gem_exec_balancer (parallel-bb-first)
[514.399248] Starting subtest: parallel-bb-first
[515.317631] Subtest parallel-bb-first: SUCCESS (0.919s)
[515.937379] [118/132] (509s left) kms_pm_rpm (fences)
[517.967899] Starting subtest: fences
[517.972855] Subtest fences: SKIP (0.000s)
[518.347123] [119/132] (507s left) gem_ctx_persistence (legacy-engines-queued)
[518.414117] Starting subtest: legacy-engines-queued
[518.416549] Starting dynamic subtest: render
[518.504922] Dynamic subtest render: SUCCESS (0.090s)
[518.517759] Starting dynamic subtest: bsd1
[519.810045] Dynamic subtest bsd1: SUCCESS (1.301s)
[519.822893] Starting dynamic subtest: bsd2
[521.855691] Dynamic subtest bsd2: SUCCESS (2.042s)
[521.867670] Starting dynamic subtest: blt
[521.899474] Dynamic subtest blt: SUCCESS (0.042s)
[521.909990] Starting dynamic subtest: vebox
[523.841770] Dynamic subtest vebox: SUCCESS (1.939s)
[523.844793] Subtest legacy-engines-queued: SUCCESS (5.428s)
[523.949076] [120/132] (501s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[524.063349] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[524.709644] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.648s)
[524.926253] [121/132] (500s left) gem_mmap_gtt (basic-wc)
[524.991936] Subtest basic-wc: SKIP (0.000s)
[525.062627] [122/132] (500s left) core_auth (getclient-simple)
[525.101775] Starting subtest: getclient-simple
[525.129202] Subtest getclient-simple: SUCCESS (0.029s)
[525.272299] [123/132] (500s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[525.367968] Starting subtest: addfb25-y-tiled-small-legacy
[525.372401] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[525.501195] [124/132] (500s left) perf (invalid-oa-exponent)
[525.606051] Starting subtest: invalid-oa-exponent
[525.618259] Subtest invalid-oa-exponent: SUCCESS (0.010s)
[525.661139] [125/132] (500s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[525.771376] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[525.773213] Starting dynamic subtest: pipe-A-default-mode
[526.898223] Dynamic subtest pipe-A-default-mode: SKIP (1.123s)
[526.902364] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.125s)
[527.096119] [126/132] (498s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[527.205729] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[527.208018] Starting dynamic subtest: A-eDP1
[530.797826] Dynamic subtest A-eDP1: SUCCESS (3.591s)
[530.809216] Starting dynamic subtest: B-eDP1
[533.998136] Dynamic subtest B-eDP1: SUCCESS (3.197s)
[534.008969] Starting dynamic subtest: C-eDP1
[537.204383] Dynamic subtest C-eDP1: SUCCESS (3.206s)
[537.215051] Starting dynamic subtest: D-eDP1
[540.322500] Dynamic subtest D-eDP1: SUCCESS (3.118s)
[540.324358] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.120s)
[541.146555] [127/132] (484s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[541.217893] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[541.220163] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[541.348045] [128/132] (484s left) kms_vrr (max-min)
[541.449169] Starting subtest: max-min
[541.455771] Subtest max-min: SKIP (0.000s)
[541.551979] [129/132] (484s left) kms_rotation_crc (sprite-rotation-90)
[541.652797] Starting subtest: sprite-rotation-90
[541.657122] Subtest sprite-rotation-90: SKIP (0.001s)
[541.752840] [130/132] (484s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[541.851351] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[541.855605] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[541.990112] [131/132] (484s left) kms_cursor_crc (cursor-offscreen-64x64)
[542.176698] Starting subtest: cursor-offscreen-64x64
[542.178579] Starting dynamic subtest: pipe-A-eDP-1
[544.130365] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.951s)
[544.160710] Starting dynamic subtest: pipe-D-eDP-1
[546.729101] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.578s)
[546.731648] Subtest cursor-offscreen-64x64: SUCCESS (4.555s)
[547.595139] [132/132] (478s left) kms_color_pipeline (plane-lut1d-lut1d)
[547.713297] Starting subtest: plane-lut1d-lut1d
[547.726311] Starting dynamic subtest: pipe-A-eDP-1
[548.437044] Dynamic subtest pipe-A-eDP-1: FAIL (0.304s)
[549.058875] Starting dynamic subtest: pipe-B-eDP-1
[549.894789] Dynamic subtest pipe-B-eDP-1: FAIL (0.324s)
[550.483925] Starting dynamic subtest: pipe-C-eDP-1
[551.348345] Dynamic subtest pipe-C-eDP-1: FAIL (0.268s)
[551.823036] Starting dynamic subtest: pipe-D-eDP-1
[552.701990] Dynamic subtest pipe-D-eDP-1: FAIL (0.301s)
[552.704263] Subtest plane-lut1d-lut1d: FAIL (4.691s)
[553.227159] 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.