[59.455240] Initializing watchdogs
[59.455336]   /dev/watchdog0
[59.471370] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[59.475584] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.477855] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.481314] [001/132] (960s left) gem_exec_store (cachelines)
[59.581299] Starting subtest: cachelines
[59.583776] Starting dynamic subtest: rcs0
[59.667053] Dynamic subtest rcs0: SUCCESS (0.086s)
[59.676140] Starting dynamic subtest: bcs0
[59.734942] Dynamic subtest bcs0: SUCCESS (0.067s)
[59.744732] Starting dynamic subtest: vcs0
[59.800903] Dynamic subtest vcs0: SUCCESS (0.065s)
[59.811737] Starting dynamic subtest: vcs1
[59.867296] Dynamic subtest vcs1: SUCCESS (0.066s)
[59.876250] Starting dynamic subtest: vecs0
[59.928762] Dynamic subtest vecs0: SUCCESS (0.061s)
[59.937325] Starting dynamic subtest: ccs0
[59.990127] Dynamic subtest ccs0: SUCCESS (0.060s)
[59.992574] Subtest cachelines: SUCCESS (0.411s)
[60.197868] [002/132] (959s left) gem_pread (bench)
[60.259302] Subtest bench: SKIP (0.000s)
[60.324683] [003/132] (959s left) syncobj_wait (invalid-signal-illegal-handle)
[60.380065] Starting subtest: invalid-signal-illegal-handle
[60.382446] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[60.524717] [004/132] (959s left) kms_vblank (query-forked-busy-hang)
[60.624075] Starting subtest: query-forked-busy-hang
[60.626367] Starting dynamic subtest: pipe-A-eDP-1
[86.683100] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.057s)
[86.694714] Starting dynamic subtest: pipe-D-eDP-1
[101.663038] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.976s)
[101.665563] Subtest query-forked-busy-hang: SUCCESS (41.036s)
[102.518235] [005/132] (917s left) kms_psr (fbc-pr-primary-mmap-cpu)
[102.626472] Starting subtest: fbc-pr-primary-mmap-cpu
[102.630651] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[102.715952] [006/132] (917s left) kms_plane_cursor (overlay)
[102.819048] Starting subtest: overlay
[102.823720] Starting dynamic subtest: pipe-A-eDP-1-size-64
[105.376133] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.557s)
[105.434392] Starting dynamic subtest: pipe-A-eDP-1-size-128
[107.888138] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.460s)
[107.947732] Starting dynamic subtest: pipe-A-eDP-1-size-256
[110.412333] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.472s)
[110.760832] Starting dynamic subtest: pipe-B-eDP-1-size-64
[113.996833] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.245s)
[114.054593] Starting dynamic subtest: pipe-B-eDP-1-size-128
[116.508532] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.460s)
[116.566763] Starting dynamic subtest: pipe-B-eDP-1-size-256
[119.010099] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.449s)
[119.351100] Starting dynamic subtest: pipe-C-eDP-1-size-64
[122.132902] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.789s)
[122.169432] Starting dynamic subtest: pipe-C-eDP-1-size-128
[124.321732] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.157s)
[124.357824] Starting dynamic subtest: pipe-C-eDP-1-size-256
[126.455571] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.103s)
[126.788421] Starting dynamic subtest: pipe-D-eDP-1-size-64
[129.567144] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.788s)
[129.603461] Starting dynamic subtest: pipe-D-eDP-1-size-128
[131.712231] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.112s)
[131.747636] Starting dynamic subtest: pipe-D-eDP-1-size-256
[133.845862] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.102s)
[134.164128] Subtest overlay: SUCCESS (31.343s)
[135.052433] [007/132] (885s left) perf (i915-ref-count)
[135.135959] Starting subtest: i915-ref-count
[135.196586] Subtest i915-ref-count: SUCCESS (0.059s)
[135.248860] [008/132] (884s left) gem_exec_schedule (smoketest-all)
[135.324738] Starting subtest: smoketest-all
[167.701942] Subtest smoketest-all: SUCCESS (32.334s)
[168.987114] [009/132] (851s left) gem_lmem_swapping (heavy-random)
[169.038500] Subtest heavy-random: SKIP (0.000s)
[169.084286] [010/132] (851s left) kms_psr (psr-primary-mmap-gtt)
[169.199895] Starting subtest: psr-primary-mmap-gtt
[169.202216] Starting dynamic subtest: eDP-1
[170.619714] Dynamic subtest eDP-1: SKIP (1.416s)
[170.624462] Subtest psr-primary-mmap-gtt: SKIP (1.418s)
[170.809841] [011/132] (849s left) perf (whitelisted-registers-userspace-config)
[170.905818] Starting subtest: whitelisted-registers-userspace-config
[170.908324] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[170.949810] [012/132] (849s left) gem_ppgtt (blt-vs-render-ctx0)
[171.026634] Starting subtest: blt-vs-render-ctx0
[204.056594] Subtest blt-vs-render-ctx0: SUCCESS (33.029s)
[204.226197] [013/132] (816s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[204.377123] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[204.381195] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[204.511361] [014/132] (815s left) gem_mmap_gtt (basic)
[204.578617] Subtest basic: SKIP (0.000s)
[204.640323] [015/132] (815s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[204.701881] Starting subtest: invalid-single-wait-all-unsubmitted
[204.704295] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[204.825410] [016/132] (815s left) gem_ctx_engines (execute-one)
[204.887429] Starting subtest: execute-one
[207.114555] Subtest execute-one: SUCCESS (2.227s)
[207.325491] [017/132] (813s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[207.437503] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[207.441519] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[207.573627] [018/132] (813s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[207.671630] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[207.674043] Starting dynamic subtest: pipe-A-eDP-1
[207.694994] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[207.705283] Starting dynamic subtest: pipe-B-eDP-1
[207.724747] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[207.732253] Starting dynamic subtest: pipe-C-eDP-1
[207.751875] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[207.758944] Starting dynamic subtest: pipe-D-eDP-1
[207.778593] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[207.780762] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[209.853086] [019/132] (810s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[209.917148] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[209.982767] [020/132] (810s left) api_intel_allocator (two-level-inception-interruptible)
[210.041455] Starting subtest: two-level-inception-interruptible
[215.447368] Subtest two-level-inception-interruptible: SUCCESS (5.406s)
[215.608479] [021/132] (805s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[220.609696] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[220.611971] Starting dynamic subtest: pipe-A-eDP-1
[227.159065] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.547s)
[227.168504] Starting dynamic subtest: pipe-B-eDP-1
[233.791676] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.632s)
[233.794066] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.183s)
[234.689145] [022/132] (786s left) i915_pciid
[234.886594] [023/132] (785s left) i915_pm_rpm (sysfs-read)
[237.038029] Starting subtest: sysfs-read
[238.215604] Subtest sysfs-read: SUCCESS (1.172s)
[238.591589] [024/132] (782s left) gem_exec_reloc (basic-cpu-noreloc)
[238.673702] Subtest basic-cpu-noreloc: SKIP (0.000s)
[238.759082] [025/132] (782s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[238.852581] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[238.856637] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[238.979637] [026/132] (781s left) sw_sync (timeline_closed_signaled)
[239.021683] Starting subtest: timeline_closed_signaled
[239.023743] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[239.098263] [027/132] (781s left) gem_eio (context-create)
[239.346844] Starting subtest: context-create
[239.689709] Subtest context-create: SUCCESS (0.341s)
[239.824570] [028/132] (781s left) kms_fb_coherency (memset-crc)
[239.921385] Starting subtest: memset-crc
[239.923046] Starting dynamic subtest: mmap-offset-wc
[240.307598] Dynamic subtest mmap-offset-wc: SUCCESS (0.385s)
[240.598384] Starting dynamic subtest: mmap-offset-uc
[246.751755] Dynamic subtest mmap-offset-uc: SUCCESS (6.161s)
[247.042508] Subtest memset-crc: SUCCESS (7.121s)
[247.865159] [029/132] (773s left) gem_ctx_persistence (engines-hostile-preempt)
[247.937657] Starting subtest: engines-hostile-preempt
[247.939662] Starting dynamic subtest: rcs0
[248.047068] Dynamic subtest rcs0: SUCCESS (0.109s)
[248.058027] Starting dynamic subtest: bcs0
[248.122998] Dynamic subtest bcs0: SUCCESS (0.074s)
[248.133702] Starting dynamic subtest: vcs0
[249.967365] Dynamic subtest vcs0: SUCCESS (1.841s)
[249.978841] Starting dynamic subtest: vcs1
[251.908507] Dynamic subtest vcs1: SUCCESS (1.939s)
[251.919883] Starting dynamic subtest: vecs0
[253.923828] Dynamic subtest vecs0: SUCCESS (2.012s)
[253.934821] Starting dynamic subtest: ccs0
[253.994042] Dynamic subtest ccs0: SUCCESS (0.069s)
[253.996915] Subtest engines-hostile-preempt: SUCCESS (6.057s)
[254.107223] [030/132] (767s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[254.219502] Starting subtest: psr-rgb565-draw-pwrite
[261.215200] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.997s)
[261.353411] [031/132] (759s left) gem_render_copy (y-tiled-to-vebox-linear)
[261.421882] Starting subtest: y-tiled-to-vebox-linear
[261.426469] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[261.625441] [032/132] (759s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[261.685654] Starting subtest: multi-wait-for-submit-submitted-signaled
[261.687924] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[261.812977] [033/132] (759s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[261.913778] Starting subtest: x-tiled-64bpp-rotate-90
[261.936067] Subtest x-tiled-64bpp-rotate-90: SKIP (0.022s)
[262.083430] [034/132] (759s left) kms_psr (fbc-psr2-cursor-render)
[262.177922] Starting subtest: fbc-psr2-cursor-render
[262.180227] Starting dynamic subtest: eDP-1
[262.184308] Dynamic subtest eDP-1: SKIP (0.000s)
[262.188347] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[262.275847] [035/132] (759s left) kms_getfb (getfb-handle-protection)
[262.376175] Starting subtest: getfb-handle-protection
[262.389531] Subtest getfb-handle-protection: SUCCESS (0.015s)
[262.543759] [036/132] (758s left) sw_sync (sync_merge_invalid)
[262.582673] Starting subtest: sync_merge_invalid
[262.609933] Subtest sync_merge_invalid: SUCCESS (0.028s)
[262.697972] [037/132] (758s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[262.799660] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[267.376049] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.581s)
[268.597508] [038/132] (752s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[268.694979] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[276.054999] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.362s)
[276.211345] [039/132] (745s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[276.332539] Starting subtest: cursorB-vs-flipA-toggle
[276.337070] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[276.499554] [040/132] (745s left) kms_rmfb (rmfb-ioctl)
[276.597304] Starting subtest: rmfb-ioctl
[276.599223] Starting dynamic subtest: pipe-A-eDP-1
[276.700918] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[276.710428] Starting dynamic subtest: pipe-B-eDP-1
[277.833184] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.131s)
[277.845831] Starting dynamic subtest: pipe-C-eDP-1
[279.049455] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.212s)
[279.059296] Starting dynamic subtest: pipe-D-eDP-1
[280.188784] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.138s)
[280.191220] Subtest rmfb-ioctl: SUCCESS (3.594s)
[281.423387] [041/132] (740s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[281.824277] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[282.613227] [042/132] (738s left) sysfs_timeslice_duration (invalid)
[282.670990] Starting subtest: invalid
[282.673263] Starting dynamic subtest: vecs0
[282.675616] Dynamic subtest vecs0: SUCCESS (0.000s)
[282.685252] Starting dynamic subtest: ccs0
[282.687109] Dynamic subtest ccs0: SUCCESS (0.000s)
[282.695051] Starting dynamic subtest: vcs0
[282.697034] Dynamic subtest vcs0: SUCCESS (0.000s)
[282.704410] Starting dynamic subtest: bcs0
[282.706361] Dynamic subtest bcs0: SUCCESS (0.000s)
[282.713815] Starting dynamic subtest: rcs0
[282.715806] Dynamic subtest rcs0: SUCCESS (0.000s)
[282.723126] Starting dynamic subtest: vcs1
[282.724986] Dynamic subtest vcs1: SUCCESS (0.000s)
[282.726923] Subtest invalid: SUCCESS (0.007s)
[282.806599] [043/132] (738s left) syncobj_eventfd (timeline-wait)
[282.871584] Starting subtest: timeline-wait
[282.879794] Subtest timeline-wait: SUCCESS (0.011s)
[283.009597] [044/132] (738s left) kms_pm_backlight (brightness-with-dpms)
[283.103770] Starting subtest: brightness-with-dpms
[283.153310] Starting dynamic subtest: eDP-1
[285.693655] Dynamic subtest eDP-1: SUCCESS (2.537s)
[285.696023] Subtest brightness-with-dpms: SUCCESS (2.589s)
[286.567388] [045/132] (735s left) kms_busy (extended-modeset-hang-oldfb)
[286.658206] Starting subtest: extended-modeset-hang-oldfb
[286.660507] Starting dynamic subtest: pipe-A
[298.426510] Dynamic subtest pipe-A: SUCCESS (11.765s)
[298.435252] Starting dynamic subtest: pipe-D
[310.702031] Dynamic subtest pipe-D: SUCCESS (12.274s)
[310.704378] Subtest extended-modeset-hang-oldfb: SUCCESS (24.044s)
[311.526880] [046/132] (710s left) syncobj_wait (multi-wait-all-signaled)
[311.593252] Starting subtest: multi-wait-all-signaled
[311.595527] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[311.733278] [047/132] (710s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[311.835966] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[311.838279] Starting dynamic subtest: pipe-A-eDP-1
[311.861509] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[311.872365] Starting dynamic subtest: pipe-B-eDP-1
[311.893486] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[311.901870] Starting dynamic subtest: pipe-C-eDP-1
[311.923304] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[311.931482] Starting dynamic subtest: pipe-D-eDP-1
[311.954478] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[311.958717] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[313.985262] [048/132] (707s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[314.095357] Starting subtest: yf-tiled-8bpp-rotate-180
[314.099418] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[314.211945] [049/132] (707s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[314.307809] Starting subtest: psr-rgb565-draw-mmap-cpu
[321.139933] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.834s)
[321.261534] [050/132] (700s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[321.360209] Starting subtest: yf-tiled-16bpp-rotate-90
[321.364142] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[321.500952] [051/132] (700s left) kms_addfb_basic (clobberred-modifier)
[321.557887] Starting subtest: clobberred-modifier
[321.562352] Subtest clobberred-modifier: SKIP (0.000s)
[321.737496] [052/132] (700s left) gem_exec_reloc (basic-write-read-active)
[321.795384] Subtest basic-write-read-active: SKIP (0.000s)
[321.883696] [053/132] (700s left) dmabuf (all-tests)
[321.921906] Starting subtest: all-tests
[321.924054] Starting dynamic subtest: sanitycheck
[322.096396] Dynamic subtest sanitycheck: SUCCESS (0.175s)
[322.107786] Starting dynamic subtest: dma_fence
[322.353655] Dynamic subtest dma_fence: SUCCESS (0.255s)
[322.368856] Starting dynamic subtest: dma_fence_chain
[330.962693] Dynamic subtest dma_fence_chain: SUCCESS (8.606s)
[330.972100] Starting dynamic subtest: dma_fence_unwrap
[331.103960] Dynamic subtest dma_fence_unwrap: SUCCESS (0.139s)
[331.115174] Starting dynamic subtest: dma_resv
[331.237711] Dynamic subtest dma_resv: SUCCESS (0.131s)
[331.240703] Subtest all-tests: SUCCESS (9.318s)
[331.327094] [054/132] (690s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[331.636684] Starting subtest: cursor-rapid-movement-512x170
[331.640504] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[331.812019] [055/132] (690s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[331.868591] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[331.870977] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[331.985680] [056/132] (690s left) kms_big_fb (linear-16bpp-rotate-90)
[332.080737] Starting subtest: linear-16bpp-rotate-90
[332.100173] Subtest linear-16bpp-rotate-90: SKIP (0.019s)
[332.267325] [057/132] (689s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[332.360055] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[332.363461] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[332.485160] [058/132] (689s left) gem_exec_schedule (noreorder-priority)
[332.655965] Starting subtest: noreorder-priority
[332.658447] Starting dynamic subtest: rcs0
[333.807382] Dynamic subtest rcs0: SUCCESS (1.150s)
[333.817691] Starting dynamic subtest: vcs0
[336.934098] Dynamic subtest vcs0: SUCCESS (3.123s)
[336.945399] Starting dynamic subtest: vcs1
[338.935287] Dynamic subtest vcs1: SUCCESS (1.998s)
[338.946060] Starting dynamic subtest: vecs0
[340.912879] Dynamic subtest vecs0: SUCCESS (1.975s)
[340.921940] Starting dynamic subtest: ccs0
[342.080758] Dynamic subtest ccs0: SUCCESS (1.167s)
[342.083266] Subtest noreorder-priority: SUCCESS (9.426s)
[342.289466] [059/132] (679s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[342.396379] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[349.087377] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.693s)
[349.281066] [060/132] (673s left) perf (polling-small-buf)
[349.403054] Starting subtest: polling-small-buf
[349.493835] Subtest polling-small-buf: SUCCESS (0.088s)
[349.537710] [061/132] (672s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[349.636936] Starting subtest: bad-pixel-format-y-tiled-ccs
[349.639335] Starting dynamic subtest: pipe-A-eDP-1
[349.662927] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[349.671542] Starting dynamic subtest: pipe-B-eDP-1
[349.693071] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[349.700809] Starting dynamic subtest: pipe-C-eDP-1
[349.722408] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[349.730192] Starting dynamic subtest: pipe-D-eDP-1
[349.752053] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[349.757041] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.005s)
[351.808260] [062/132] (670s left) i915_drm_fdinfo (busy-hang)
[351.851687] Starting subtest: busy-hang
[351.854072] Starting dynamic subtest: rcs0
[351.879830] Dynamic subtest rcs0: SKIP (0.026s)
[351.889685] Starting dynamic subtest: bcs0
[351.907887] Dynamic subtest bcs0: SKIP (0.027s)
[351.915950] Starting dynamic subtest: vcs0
[351.919920] Dynamic subtest vcs0: SKIP (0.001s)
[351.928057] Starting dynamic subtest: vcs1
[351.932184] Dynamic subtest vcs1: SKIP (0.000s)
[351.940147] Starting dynamic subtest: vecs0
[351.944137] Dynamic subtest vecs0: SKIP (0.001s)
[351.951585] Starting dynamic subtest: ccs0
[351.955631] Dynamic subtest ccs0: SKIP (0.000s)
[351.959690] Subtest busy-hang: SKIP (0.060s)
[352.006649] [063/132] (670s left) kms_cursor_crc (cursor-sliding-max-size)
[352.329710] Starting subtest: cursor-sliding-max-size
[352.336114] Subtest cursor-sliding-max-size: SKIP (0.000s)
[352.491091] [064/132] (669s left) gem_exec_schedule (preempt-queue-contexts-chain)
[352.575935] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[352.881998] [065/132] (669s left) gem_mmap_gtt (big-copy)
[352.949434] Subtest big-copy: SKIP (0.000s)
[353.017575] [066/132] (669s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[353.121888] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[353.124134] Starting dynamic subtest: pipe-A
[353.184400] Dynamic subtest pipe-A: SUCCESS (0.064s)
[353.194496] Starting dynamic subtest: pipe-B
[354.319020] Dynamic subtest pipe-B: SUCCESS (1.133s)
[354.328837] Starting dynamic subtest: pipe-C
[355.424310] Dynamic subtest pipe-C: SUCCESS (1.104s)
[355.432962] Starting dynamic subtest: pipe-D
[356.555608] Dynamic subtest pipe-D: SUCCESS (1.130s)
[356.558012] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.438s)
[357.749382] [067/132] (664s left) kms_chamelium_color (ctm-0-50)
[357.858918] Subtest ctm-0-50: SKIP (0.000s)
[357.909879] [068/132] (664s left) kms_psr (pr-primary-page-flip)
[358.004861] Starting subtest: pr-primary-page-flip
[358.009213] Subtest pr-primary-page-flip: SKIP (0.000s)
[358.099105] [069/132] (664s left) kms_atomic (test-only)
[358.211287] Starting subtest: test-only
[358.288596] Starting dynamic subtest: pipe-A-eDP-1
[360.804436] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.518s)
[360.806668] Subtest test-only: SUCCESS (2.596s)
[361.645154] [070/132] (661s left) gem_spin_batch (spin-each)
[361.718489] Starting subtest: spin-each
[364.984841] Subtest spin-each: SUCCESS (3.258s)
[366.999965] [071/132] (655s left) gem_exec_params (batch-first)
[367.080999] Starting subtest: batch-first
[367.091854] Subtest batch-first: SUCCESS (0.013s)
[367.256065] [072/132] (655s left) gem_busy (semaphore)
[367.466184] Subtest semaphore: SKIP (0.000s)
[367.741980] [073/132] (655s left) kms_psr (pr-primary-blt)
[367.858102] Starting subtest: pr-primary-blt
[367.862225] Subtest pr-primary-blt: SKIP (0.000s)
[367.952972] [074/132] (654s left) kms_dp_link_training (uhbr-mst)
[368.053332] Starting subtest: uhbr-mst
[368.061874] Subtest uhbr-mst: SKIP (0.000s)
[368.143660] [075/132] (654s left) i915_suspend (debugfs-reader)
[368.202058] Starting subtest: debugfs-reader
[386.750161] Subtest debugfs-reader: SUCCESS (6.942s)
[386.799614] [076/132] (636s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[386.909244] Starting subtest: compare-crc-sanitycheck-nv12
[386.911592] Starting dynamic subtest: pipe-A-eDP-1
[387.575956] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.663s)
[387.590671] Starting dynamic subtest: pipe-B-eDP-1
[388.839596] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.262s)
[388.849080] Starting dynamic subtest: pipe-C-eDP-1
[390.087886] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.245s)
[390.097227] Starting dynamic subtest: pipe-D-eDP-1
[391.330819] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.242s)
[391.333227] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.419s)
[392.187204] [077/132] (630s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[392.297706] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[392.301701] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[392.438054] [078/132] (630s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[392.545198] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[392.549757] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[393.722613] [079/132] (629s left) perf_pmu (busy-idle-check-all)
[393.764905] Starting subtest: busy-idle-check-all
[393.767106] Starting dynamic subtest: rcs0
[394.434589] Dynamic subtest rcs0: SUCCESS (0.669s)
[394.446758] Starting dynamic subtest: bcs0
[395.087624] Dynamic subtest bcs0: SUCCESS (0.651s)
[395.099398] Starting dynamic subtest: vcs0
[395.798053] Dynamic subtest vcs0: FAIL (0.644s)
[395.809042] Starting dynamic subtest: vcs1
[396.438060] Dynamic subtest vcs1: FAIL (0.621s)
[396.448874] Starting dynamic subtest: vecs0
[397.062409] Dynamic subtest vecs0: FAIL (0.622s)
[397.072854] Starting dynamic subtest: ccs0
[397.686481] Dynamic subtest ccs0: FAIL (0.624s)
[397.688549] Subtest busy-idle-check-all: FAIL (3.839s)
[398.604880] [080/132] (624s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[398.709808] Starting subtest: psr-2p-pri-indfb-multidraw
[398.713899] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[398.844616] [081/132] (624s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[398.943924] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[398.948037] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[399.096560] [082/132] (624s left) gem_exec_balancer (fairslice)
[399.146760] Starting subtest: fairslice
[402.070819] Subtest fairslice: SUCCESS (2.921s)
[402.592084] [083/132] (620s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[402.638625] Starting subtest: etime-single-wait-all-available-unsubmitted
[402.744659] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.105s)
[402.848928] [084/132] (620s left) gem_exec_fence (syncobj-timeline-chain-engines)
[403.424516] Starting subtest: syncobj-timeline-chain-engines
[403.594394] Subtest syncobj-timeline-chain-engines: SUCCESS (0.170s)
[404.696887] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[404.700818] [085/132] (618s left) gem_pread (display)
[404.753215] Subtest display: SKIP (0.000s)
[404.818708] [086/132] (618s left) gem_sync (basic-all)
[404.898471] Starting subtest: basic-all
[408.019835] Subtest basic-all: SUCCESS (3.131s)
[408.229570] [087/132] (615s left) gem_mmap_offset (isolation)
[408.288984] Starting subtest: isolation
[408.305407] Subtest isolation: SUCCESS (0.007s)
[408.435897] [088/132] (614s left) kms_atomic_interruptible (legacy-dpms)
[408.543011] Starting subtest: legacy-dpms
[408.545253] Starting dynamic subtest: pipe-A-eDP-1
[415.946242] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.401s)
[415.948470] Subtest legacy-dpms: SUCCESS (7.403s)
[416.787738] [089/132] (606s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[416.877877] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[416.882381] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[417.015829] [090/132] (606s left) gem_flink_basic (bad-open)
[417.080648] Starting subtest: bad-open
[417.083117] Subtest bad-open: SUCCESS (0.000s)
[417.227157] [091/132] (606s left) kms_invalid_mode (int-max-clock)
[417.332716] Starting subtest: int-max-clock
[417.334953] Starting dynamic subtest: pipe-A-eDP-1
[417.622674] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[417.632904] Starting dynamic subtest: pipe-B-eDP-1
[417.635033] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[417.644771] Starting dynamic subtest: pipe-C-eDP-1
[417.646782] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[417.657459] Starting dynamic subtest: pipe-D-eDP-1
[417.659270] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[417.661184] Subtest int-max-clock: SUCCESS (0.293s)
[418.524983] [092/132] (605s left) gem_exec_params (mmapped)
[418.598791] Starting subtest: mmapped
[418.600691] Subtest mmapped: SUCCESS (0.004s)
[418.767642] [093/132] (604s left) kms_plane_multiple (tiling-x)
[418.873350] Starting subtest: tiling-x
[418.875667] Starting dynamic subtest: pipe-A-eDP-1
[422.521383] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.648s)
[422.532514] Starting dynamic subtest: pipe-B-eDP-1
[426.957762] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.433s)
[426.967994] Starting dynamic subtest: pipe-C-eDP-1
[431.271503] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.311s)
[431.281309] Starting dynamic subtest: pipe-D-eDP-1
[435.620992] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.345s)
[435.623395] Subtest tiling-x: SUCCESS (16.742s)
[436.457239] [094/132] (587s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[436.572963] Starting subtest: fbc-rgb101010-draw-pwrite
[441.911553] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.341s)
[443.070655] [095/132] (580s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[443.178754] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[450.158858] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.982s)
[450.332569] [096/132] (573s left) kms_flip (2x-flip-vs-dpms)
[450.433519] Starting subtest: 2x-flip-vs-dpms
[450.445400] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[450.547435] [097/132] (573s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[450.638670] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[450.649103] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[450.753189] [098/132] (573s left) kms_content_protection (atomic-hdcp14)
[450.891609] Starting subtest: atomic-hdcp14
[450.896009] Subtest atomic-hdcp14: SKIP (0.000s)
[451.042708] [099/132] (572s left) sw_sync (sync_merge)
[451.076784] Starting subtest: sync_merge
[451.079237] Subtest sync_merge: SUCCESS (0.000s)
[451.168685] [100/132] (572s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[451.260463] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[451.262296] Starting dynamic subtest: pipe-A-default-mode
[451.548776] Dynamic subtest pipe-A-default-mode: SKIP (0.285s)
[451.553406] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.287s)
[452.410682] [101/132] (571s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[452.536181] Starting subtest: cursor-vs-flip-atomic-transitions
[482.473531] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (29.932s)
[483.354914] [102/132] (540s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[483.446258] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[483.448561] Starting dynamic subtest: pipe-A-eDP-1
[483.506964] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.062s)
[483.814380] Starting dynamic subtest: pipe-B-eDP-1
[484.626914] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.822s)
[484.915672] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.471s)
[485.810647] [103/132] (538s left) kms_prime (basic-crc-vgem)
[485.898708] Starting subtest: basic-crc-vgem
[485.901115] Starting dynamic subtest: second-to-first
[486.825795] Dynamic subtest second-to-first: SUCCESS (0.931s)
[486.827773] Subtest basic-crc-vgem: SUCCESS (0.932s)
[486.944579] [104/132] (537s left) kms_properties (invalid-properties-legacy)
[487.091169] Starting subtest: invalid-properties-legacy
[487.197720] Subtest invalid-properties-legacy: SUCCESS (0.106s)
[487.296778] [105/132] (536s left) syncobj_timeline (wait-zero-handles)
[487.357075] Starting subtest: wait-zero-handles
[487.359450] Subtest wait-zero-handles: SUCCESS (0.000s)
[487.486838] [106/132] (536s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[492.479216] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[492.483636] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[493.335672] [107/132] (530s left) gem_pxp (reject-modify-context-protection-off-1)
[493.470186] Starting subtest: reject-modify-context-protection-off-1
[493.472625] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[493.615034] [108/132] (530s left) kms_selftest (drm_format_helper)
[493.656123] Starting subtest: drm_format_helper
[493.667827] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[493.800576] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.131s)
[493.810761] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[493.951446] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.149s)
[493.961257] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[494.104681] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.152s)
[494.113742] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[494.247385] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.141s)
[494.267847] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[494.391255] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.144s)
[494.401059] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[494.525050] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.133s)
[494.541097] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[494.676928] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.150s)
[494.688320] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[494.822567] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.145s)
[494.833671] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[494.971914] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.149s)
[494.981188] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[495.114619] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.141s)
[495.125982] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[495.261535] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.146s)
[495.272075] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[495.389714] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.128s)
[495.398565] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[495.516422] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.126s)
[495.526939] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[495.649394] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.132s)
[495.659826] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[495.791802] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.141s)
[495.803024] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[495.922281] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.129s)
[495.931477] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[496.068980] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.146s)
[496.070892] Subtest drm_format_helper: SUCCESS (2.411s)
[496.312355] [109/132] (528s left) gen3_mixed_blits
[496.440132] [110/132] (527s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[496.556387] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[496.560313] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[496.686950] [111/132] (527s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[496.786124] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[503.628617] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.846s)
[503.780798] [112/132] (520s left) gen7_exec_parse (cmd-crossing-page)
[503.832523] Subtest cmd-crossing-page: SKIP (0.000s)
[503.899973] [113/132] (520s left) gem_softpin (overlap)
[503.964923] Starting subtest: overlap
[504.895701] Subtest overlap: SUCCESS (0.928s)
[505.061747] [114/132] (519s left) gem_eio (in-flight-internal-immediate)
[505.300168] Starting subtest: in-flight-internal-immediate
[506.637003] Subtest in-flight-internal-immediate: SUCCESS (1.335s)
[506.807404] [115/132] (517s left) gem_wait (write-wait)
[506.876944] Starting subtest: write-wait
[506.879488] Starting dynamic subtest: all-engines
[507.986908] Dynamic subtest all-engines: SUCCESS (1.109s)
[508.004355] Starting dynamic subtest: rcs0
[509.047091] Dynamic subtest rcs0: SUCCESS (1.058s)
[509.064382] Starting dynamic subtest: bcs0
[510.106798] Dynamic subtest bcs0: SUCCESS (1.058s)
[510.124452] Starting dynamic subtest: vcs0
[511.163385] Dynamic subtest vcs0: SUCCESS (1.053s)
[511.180351] Starting dynamic subtest: vcs1
[512.216390] Dynamic subtest vcs1: SUCCESS (1.051s)
[512.233538] Starting dynamic subtest: vecs0
[513.269975] Dynamic subtest vecs0: SUCCESS (1.051s)
[513.287260] Starting dynamic subtest: ccs0
[514.327678] Dynamic subtest ccs0: SUCCESS (1.056s)
[514.330164] Subtest write-wait: SUCCESS (7.451s)
[514.548918] [116/132] (510s left) kms_chamelium_edid (dp-edid-read)
[514.943559] Subtest dp-edid-read: SKIP (0.000s)
[515.739634] [117/132] (508s left) gem_exec_balancer (parallel-bb-first)
[516.002634] Starting subtest: parallel-bb-first
[516.917375] Subtest parallel-bb-first: SUCCESS (0.915s)
[517.610991] [118/132] (507s left) kms_pm_rpm (fences)
[519.636824] Starting subtest: fences
[519.641521] Subtest fences: SKIP (0.000s)
[520.031389] [119/132] (504s left) gem_ctx_persistence (legacy-engines-queued)
[520.097020] Starting subtest: legacy-engines-queued
[520.099662] Starting dynamic subtest: render
[520.182028] Dynamic subtest render: SUCCESS (0.084s)
[520.194786] Starting dynamic subtest: bsd1
[521.482359] Dynamic subtest bsd1: SUCCESS (1.296s)
[521.497868] Starting dynamic subtest: bsd2
[523.465188] Dynamic subtest bsd2: SUCCESS (1.978s)
[523.477013] Starting dynamic subtest: blt
[523.536669] Dynamic subtest blt: SUCCESS (0.069s)
[523.548770] Starting dynamic subtest: vebox
[525.515091] Dynamic subtest vebox: SUCCESS (1.973s)
[525.517841] Subtest legacy-engines-queued: SUCCESS (5.417s)
[525.620716] [120/132] (499s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[525.732921] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[526.401171] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.670s)
[526.630660] [121/132] (498s left) gem_mmap_gtt (basic-wc)
[526.696251] Subtest basic-wc: SKIP (0.000s)
[526.760220] [122/132] (498s left) core_auth (getclient-simple)
[526.795484] Starting subtest: getclient-simple
[526.812752] Subtest getclient-simple: SUCCESS (0.015s)
[526.954085] [123/132] (497s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[527.045483] Starting subtest: addfb25-y-tiled-small-legacy
[527.049853] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[527.156675] [124/132] (497s left) perf (invalid-oa-exponent)
[527.261750] Starting subtest: invalid-oa-exponent
[527.274888] Subtest invalid-oa-exponent: SUCCESS (0.010s)
[527.320830] [125/132] (497s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[527.413146] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[527.415069] Starting dynamic subtest: pipe-A-default-mode
[528.556308] Dynamic subtest pipe-A-default-mode: SKIP (1.140s)
[528.560884] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.141s)
[528.761270] [126/132] (496s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[528.863596] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[528.866064] Starting dynamic subtest: A-eDP1
[532.463138] Dynamic subtest A-eDP1: SUCCESS (3.597s)
[532.473990] Starting dynamic subtest: B-eDP1
[535.656748] Dynamic subtest B-eDP1: SUCCESS (3.190s)
[535.666925] Starting dynamic subtest: C-eDP1
[538.764302] Dynamic subtest C-eDP1: SUCCESS (3.108s)
[538.775083] Starting dynamic subtest: D-eDP1
[541.871983] Dynamic subtest D-eDP1: SUCCESS (3.105s)
[541.874270] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.007s)
[542.692543] [127/132] (482s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[542.759815] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[542.761967] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[542.880043] [128/132] (482s left) kms_vrr (max-min)
[542.967489] Starting subtest: max-min
[542.973649] Subtest max-min: SKIP (0.000s)
[543.064666] [129/132] (482s left) kms_rotation_crc (sprite-rotation-90)
[543.166007] Starting subtest: sprite-rotation-90
[543.170351] Subtest sprite-rotation-90: SKIP (0.001s)
[543.264588] [130/132] (481s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[543.369658] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[543.374081] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[543.507196] [131/132] (481s left) kms_cursor_crc (cursor-offscreen-64x64)
[543.690589] Starting subtest: cursor-offscreen-64x64
[543.695081] Starting dynamic subtest: pipe-A-eDP-1
[545.646509] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.952s)
[545.675490] Starting dynamic subtest: pipe-D-eDP-1
[548.278898] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.610s)
[548.281200] Subtest cursor-offscreen-64x64: SUCCESS (4.586s)
[549.144235] [132/132] (476s left) kms_color_pipeline (plane-lut1d-lut1d)
[549.249994] Starting subtest: plane-lut1d-lut1d
[549.263240] Starting dynamic subtest: pipe-A-eDP-1
[549.959165] Dynamic subtest pipe-A-eDP-1: FAIL (0.294s)
[550.602804] Starting dynamic subtest: pipe-B-eDP-1
[551.414065] Dynamic subtest pipe-B-eDP-1: FAIL (0.290s)
[551.936548] Starting dynamic subtest: pipe-C-eDP-1
[552.757822] Dynamic subtest pipe-C-eDP-1: FAIL (0.235s)
[553.263058] Starting dynamic subtest: pipe-D-eDP-1
[554.085385] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s)
[554.087390] Subtest plane-lut1d-lut1d: FAIL (4.540s)
[554.668744] 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.