[59.742840] Initializing watchdogs
[59.742888]   /dev/watchdog0
[59.759888] [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.764245] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.766230] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.768121] [001/132] (960s left) gem_exec_store (cachelines)
[59.842777] Starting subtest: cachelines
[59.843680] Starting dynamic subtest: rcs0
[59.961686] Dynamic subtest rcs0: SUCCESS (0.118s)
[59.969377] Starting dynamic subtest: bcs0
[60.024225] Dynamic subtest bcs0: SUCCESS (0.062s)
[60.031855] Starting dynamic subtest: vcs0
[60.082281] Dynamic subtest vcs0: SUCCESS (0.057s)
[60.090202] Starting dynamic subtest: vcs1
[60.145074] Dynamic subtest vcs1: SUCCESS (0.062s)
[60.152988] Starting dynamic subtest: vecs0
[60.204997] Dynamic subtest vecs0: SUCCESS (0.059s)
[60.213044] Starting dynamic subtest: ccs0
[60.272050] Dynamic subtest ccs0: SUCCESS (0.066s)
[60.273279] Subtest cachelines: SUCCESS (0.429s)
[60.473325] [002/132] (959s left) gem_pread (bench)
[60.526243] Subtest bench: SKIP (0.000s)
[60.580241] [003/132] (959s left) syncobj_wait (invalid-signal-illegal-handle)
[60.636234] Starting subtest: invalid-signal-illegal-handle
[60.637150] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[60.760732] [004/132] (959s left) kms_vblank (query-forked-busy-hang)
[60.863942] Starting subtest: query-forked-busy-hang
[60.864993] Starting dynamic subtest: pipe-A-eDP-1
[87.707390] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.843s)
[87.716201] Starting dynamic subtest: pipe-D-eDP-1
[111.701975] Dynamic subtest pipe-D-eDP-1: SUCCESS (23.992s)
[111.702871] Subtest query-forked-busy-hang: SUCCESS (50.839s)
[112.515583] [005/132] (907s left) kms_psr (fbc-pr-primary-mmap-cpu)
[112.607225] Starting subtest: fbc-pr-primary-mmap-cpu
[112.608972] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[112.692545] [006/132] (907s left) kms_plane_cursor (overlay)
[112.773202] Starting subtest: overlay
[112.775048] Starting dynamic subtest: pipe-A-eDP-1-size-64
[115.197770] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.425s)
[115.257537] Starting dynamic subtest: pipe-A-eDP-1-size-128
[117.665539] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.415s)
[117.724921] Starting dynamic subtest: pipe-A-eDP-1-size-256
[120.211673] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.494s)
[120.558294] Starting dynamic subtest: pipe-B-eDP-1-size-64
[123.748587] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.196s)
[123.808961] Starting dynamic subtest: pipe-B-eDP-1-size-128
[126.261148] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.460s)
[126.320479] Starting dynamic subtest: pipe-B-eDP-1-size-256
[128.773311] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.460s)
[129.118909] Starting dynamic subtest: pipe-C-eDP-1-size-64
[132.007462] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.894s)
[132.045401] Starting dynamic subtest: pipe-C-eDP-1-size-128
[134.051753] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.013s)
[134.088881] Starting dynamic subtest: pipe-C-eDP-1-size-256
[136.096109] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.013s)
[136.419032] Starting dynamic subtest: pipe-D-eDP-1-size-64
[139.351630] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.939s)
[139.388132] Starting dynamic subtest: pipe-D-eDP-1-size-128
[141.629359] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.246s)
[141.666027] Starting dynamic subtest: pipe-D-eDP-1-size-256
[143.696184] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.035s)
[144.012520] Subtest overlay: SUCCESS (31.239s)
[144.850151] [007/132] (875s left) perf (i915-ref-count)
[144.925355] Starting subtest: i915-ref-count
[144.986377] Subtest i915-ref-count: SUCCESS (0.060s)
[145.036577] [008/132] (875s left) gem_exec_schedule (smoketest-all)
[145.094735] Starting subtest: smoketest-all
[177.437227] Subtest smoketest-all: SUCCESS (32.320s)
[179.061441] [009/132] (841s left) gem_lmem_swapping (heavy-random)
[179.095765] Subtest heavy-random: SKIP (0.000s)
[179.133031] [010/132] (841s left) kms_psr (psr-primary-mmap-gtt)
[179.225159] Starting subtest: psr-primary-mmap-gtt
[179.226149] Starting dynamic subtest: eDP-1
[180.634396] Dynamic subtest eDP-1: SKIP (1.406s)
[180.636657] Subtest psr-primary-mmap-gtt: SKIP (1.408s)
[180.829068] [011/132] (839s left) perf (whitelisted-registers-userspace-config)
[180.921453] Starting subtest: whitelisted-registers-userspace-config
[180.922596] Subtest whitelisted-registers-userspace-config: SUCCESS (0.000s)
[180.957789] [012/132] (839s left) gem_ppgtt (blt-vs-render-ctx0)
[181.016368] Starting subtest: blt-vs-render-ctx0
[214.030720] Subtest blt-vs-render-ctx0: SUCCESS (33.011s)
[214.182078] [013/132] (806s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[214.312594] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[214.314427] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[214.434917] [014/132] (806s left) gem_mmap_gtt (basic)
[214.500089] Subtest basic: SKIP (0.000s)
[214.547625] [015/132] (806s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[214.600265] Starting subtest: invalid-single-wait-all-unsubmitted
[214.601367] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[214.709206] [016/132] (805s left) gem_ctx_engines (execute-one)
[214.775229] Starting subtest: execute-one
[216.974795] Subtest execute-one: SUCCESS (2.200s)
[217.171029] [017/132] (803s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[217.263631] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[217.265480] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[217.397654] [018/132] (803s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[217.489364] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[217.490263] Starting dynamic subtest: pipe-A-eDP-1
[217.497622] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[217.504085] Starting dynamic subtest: pipe-B-eDP-1
[217.511570] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[217.517802] Starting dynamic subtest: pipe-C-eDP-1
[217.525297] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[217.531517] Starting dynamic subtest: pipe-D-eDP-1
[217.538687] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[217.539548] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[219.663587] [019/132] (801s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[219.718864] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[219.771948] [020/132] (801s left) api_intel_allocator (two-level-inception-interruptible)
[219.820335] Starting subtest: two-level-inception-interruptible
[225.231008] Subtest two-level-inception-interruptible: SUCCESS (5.410s)
[225.371807] [021/132] (795s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[230.433941] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[230.436116] Starting dynamic subtest: pipe-A-eDP-1
[237.054627] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.618s)
[237.062118] Starting dynamic subtest: pipe-B-eDP-1
[243.700283] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.643s)
[243.701118] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.265s)
[244.594416] [022/132] (776s left) i915_pciid
[244.754447] [023/132] (776s left) i915_pm_rpm (sysfs-read)
[247.091417] Starting subtest: sysfs-read
[248.246828] Subtest sysfs-read: SUCCESS (1.154s)
[248.639436] [024/132] (772s left) gem_exec_reloc (basic-cpu-noreloc)
[248.697026] Subtest basic-cpu-noreloc: SKIP (0.000s)
[248.764878] [025/132] (772s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[248.873306] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[248.875063] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[249.012808] [026/132] (771s left) sw_sync (timeline_closed_signaled)
[249.042419] Starting subtest: timeline_closed_signaled
[249.043407] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[249.112450] [027/132] (771s left) gem_eio (context-create)
[249.331720] Starting subtest: context-create
[249.672969] Subtest context-create: SUCCESS (0.341s)
[249.819769] [028/132] (771s left) kms_fb_coherency (memset-crc)
[249.904550] Starting subtest: memset-crc
[249.905592] Starting dynamic subtest: mmap-offset-wc
[250.258542] Dynamic subtest mmap-offset-wc: SUCCESS (0.354s)
[250.552233] Starting dynamic subtest: mmap-offset-uc
[256.639968] Dynamic subtest mmap-offset-uc: SUCCESS (6.093s)
[256.918874] Subtest memset-crc: SUCCESS (7.014s)
[257.792420] [029/132] (763s left) gem_ctx_persistence (engines-hostile-preempt)
[257.857524] Starting subtest: engines-hostile-preempt
[257.858587] Starting dynamic subtest: rcs0
[258.004613] Dynamic subtest rcs0: SUCCESS (0.146s)
[258.022919] Starting dynamic subtest: bcs0
[258.086950] Dynamic subtest bcs0: SUCCESS (0.079s)
[258.097719] Starting dynamic subtest: vcs0
[259.946921] Dynamic subtest vcs0: SUCCESS (1.855s)
[259.955312] Starting dynamic subtest: vcs1
[261.927974] Dynamic subtest vcs1: SUCCESS (1.979s)
[261.936454] Starting dynamic subtest: vecs0
[263.877436] Dynamic subtest vecs0: SUCCESS (1.947s)
[263.885037] Starting dynamic subtest: ccs0
[263.984928] Dynamic subtest ccs0: SUCCESS (0.107s)
[263.986699] Subtest engines-hostile-preempt: SUCCESS (6.129s)
[264.082148] [030/132] (757s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[264.195279] Starting subtest: psr-rgb565-draw-pwrite
[271.180373] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.986s)
[271.329424] [031/132] (749s left) gem_render_copy (y-tiled-to-vebox-linear)
[271.403960] Starting subtest: y-tiled-to-vebox-linear
[271.405935] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[271.596941] [032/132] (749s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[271.653854] Starting subtest: multi-wait-for-submit-submitted-signaled
[271.655043] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[271.769901] [033/132] (749s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[271.872959] Starting subtest: x-tiled-64bpp-rotate-90
[271.891107] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[272.032824] [034/132] (749s left) kms_psr (fbc-psr2-cursor-render)
[272.126311] Starting subtest: fbc-psr2-cursor-render
[272.127184] Starting dynamic subtest: eDP-1
[272.128926] Dynamic subtest eDP-1: SKIP (0.000s)
[272.135232] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[272.215748] [035/132] (749s left) kms_getfb (getfb-handle-protection)
[272.301493] Starting subtest: getfb-handle-protection
[272.328753] Subtest getfb-handle-protection: SUCCESS (0.027s)
[272.483393] [036/132] (748s left) sw_sync (sync_merge_invalid)
[272.517567] Starting subtest: sync_merge_invalid
[272.544793] Subtest sync_merge_invalid: SUCCESS (0.028s)
[272.626179] [037/132] (748s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[272.735789] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[277.392523] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.658s)
[278.572318] [038/132] (742s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[278.658634] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[285.984555] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.326s)
[286.138423] [039/132] (735s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[286.261576] Starting subtest: cursorB-vs-flipA-toggle
[286.265311] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[286.426119] [040/132] (735s left) kms_rmfb (rmfb-ioctl)
[286.516545] Starting subtest: rmfb-ioctl
[286.517442] Starting dynamic subtest: pipe-A-eDP-1
[286.622178] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[286.631609] Starting dynamic subtest: pipe-B-eDP-1
[287.818358] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.195s)
[287.827806] Starting dynamic subtest: pipe-C-eDP-1
[288.959604] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.139s)
[288.973826] Starting dynamic subtest: pipe-D-eDP-1
[290.105650] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.143s)
[290.106632] Subtest rmfb-ioctl: SUCCESS (3.590s)
[291.337566] [041/132] (730s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[291.716389] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[292.525261] [042/132] (728s left) sysfs_timeslice_duration (invalid)
[292.587334] Starting subtest: invalid
[292.588317] Starting dynamic subtest: vecs0
[292.589224] Dynamic subtest vecs0: SUCCESS (0.000s)
[292.597352] Starting dynamic subtest: ccs0
[292.598247] Dynamic subtest ccs0: SUCCESS (0.000s)
[292.604418] Starting dynamic subtest: vcs0
[292.605294] Dynamic subtest vcs0: SUCCESS (0.000s)
[292.611596] Starting dynamic subtest: bcs0
[292.612454] Dynamic subtest bcs0: SUCCESS (0.000s)
[292.618720] Starting dynamic subtest: rcs0
[292.619571] Dynamic subtest rcs0: SUCCESS (0.000s)
[292.625703] Starting dynamic subtest: vcs1
[292.626550] Dynamic subtest vcs1: SUCCESS (0.000s)
[292.627426] Subtest invalid: SUCCESS (0.006s)
[292.730826] [043/132] (728s left) syncobj_eventfd (timeline-wait)
[292.785020] Starting subtest: timeline-wait
[292.794877] Subtest timeline-wait: SUCCESS (0.011s)
[292.922074] [044/132] (728s left) kms_pm_backlight (brightness-with-dpms)
[293.023369] Starting subtest: brightness-with-dpms
[293.108060] Starting dynamic subtest: eDP-1
[295.762908] Dynamic subtest eDP-1: SUCCESS (2.656s)
[295.763981] Subtest brightness-with-dpms: SUCCESS (2.739s)
[296.634915] [045/132] (724s left) kms_busy (extended-modeset-hang-oldfb)
[296.731385] Starting subtest: extended-modeset-hang-oldfb
[296.732369] Starting dynamic subtest: pipe-A
[308.701043] Dynamic subtest pipe-A: SUCCESS (11.968s)
[308.710962] Starting dynamic subtest: pipe-D
[320.969942] Dynamic subtest pipe-D: SUCCESS (12.266s)
[320.970902] Subtest extended-modeset-hang-oldfb: SUCCESS (24.238s)
[321.783801] [046/132] (699s left) syncobj_wait (multi-wait-all-signaled)
[321.843533] Starting subtest: multi-wait-all-signaled
[321.844620] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[321.971973] [047/132] (699s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[322.060250] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[322.061131] Starting dynamic subtest: pipe-A-eDP-1
[322.069744] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[322.076850] Starting dynamic subtest: pipe-B-eDP-1
[322.085485] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[322.092720] Starting dynamic subtest: pipe-C-eDP-1
[322.102604] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[322.110264] Starting dynamic subtest: pipe-D-eDP-1
[322.118719] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[322.120390] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[324.344546] [048/132] (697s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[324.442894] Starting subtest: yf-tiled-8bpp-rotate-180
[324.444496] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[324.578788] [049/132] (697s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[324.684509] Starting subtest: psr-rgb565-draw-mmap-cpu
[331.405265] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.721s)
[331.556994] [050/132] (690s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[331.657396] Starting subtest: yf-tiled-16bpp-rotate-90
[331.659134] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[331.782063] [051/132] (689s left) kms_addfb_basic (clobberred-modifier)
[331.838043] Starting subtest: clobberred-modifier
[331.839883] Subtest clobberred-modifier: SKIP (0.000s)
[331.997782] [052/132] (689s left) gem_exec_reloc (basic-write-read-active)
[332.049856] Subtest basic-write-read-active: SKIP (0.000s)
[332.118831] [053/132] (689s left) dmabuf (all-tests)
[332.160269] Starting subtest: all-tests
[332.161232] Starting dynamic subtest: sanitycheck
[332.337304] Dynamic subtest sanitycheck: SUCCESS (0.174s)
[332.348127] Starting dynamic subtest: dma_fence
[332.627102] Dynamic subtest dma_fence: SUCCESS (0.287s)
[332.636560] Starting dynamic subtest: dma_fence_chain
[341.268419] Dynamic subtest dma_fence_chain: SUCCESS (8.638s)
[341.278453] Starting dynamic subtest: dma_fence_unwrap
[341.410517] Dynamic subtest dma_fence_unwrap: SUCCESS (0.140s)
[341.420551] Starting dynamic subtest: dma_resv
[341.552308] Dynamic subtest dma_resv: SUCCESS (0.140s)
[341.553506] Subtest all-tests: SUCCESS (9.391s)
[341.632207] [054/132] (680s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[341.916347] Starting subtest: cursor-rapid-movement-512x170
[341.918293] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[342.088074] [055/132] (679s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[342.141195] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[342.142086] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[342.259116] [056/132] (679s left) kms_big_fb (linear-16bpp-rotate-90)
[342.354797] Starting subtest: linear-16bpp-rotate-90
[342.375466] Subtest linear-16bpp-rotate-90: SKIP (0.020s)
[342.524245] [057/132] (679s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[342.618620] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[342.620321] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[342.737088] [058/132] (679s left) gem_exec_schedule (noreorder-priority)
[342.911590] Starting subtest: noreorder-priority
[342.912687] Starting dynamic subtest: rcs0
[344.082040] Dynamic subtest rcs0: SUCCESS (1.169s)
[344.093349] Starting dynamic subtest: vcs0
[345.903879] Dynamic subtest vcs0: SUCCESS (1.817s)
[345.913739] Starting dynamic subtest: vcs1
[347.908964] Dynamic subtest vcs1: SUCCESS (2.002s)
[347.918705] Starting dynamic subtest: vecs0
[349.953702] Dynamic subtest vecs0: SUCCESS (2.043s)
[349.962419] Starting dynamic subtest: ccs0
[351.129219] Dynamic subtest ccs0: SUCCESS (1.175s)
[351.130414] Subtest noreorder-priority: SUCCESS (8.218s)
[351.327326] [059/132] (670s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[351.419816] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[358.202720] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.781s)
[358.365724] [060/132] (663s left) perf (polling-small-buf)
[358.485723] Starting subtest: polling-small-buf
[358.575126] Subtest polling-small-buf: SUCCESS (0.090s)
[358.614485] [061/132] (663s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[358.703339] Starting subtest: bad-pixel-format-y-tiled-ccs
[358.704203] Starting dynamic subtest: pipe-A-eDP-1
[358.713234] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[358.725605] Starting dynamic subtest: pipe-B-eDP-1
[358.734359] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[358.741724] Starting dynamic subtest: pipe-C-eDP-1
[358.749702] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[358.755893] Starting dynamic subtest: pipe-D-eDP-1
[358.763930] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[358.765475] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[360.869101] [062/132] (661s left) i915_drm_fdinfo (busy-hang)
[360.906494] Starting subtest: busy-hang
[360.907379] Starting dynamic subtest: rcs0
[360.943263] Dynamic subtest rcs0: SKIP (0.035s)
[360.952193] Starting dynamic subtest: bcs0
[360.953912] Dynamic subtest bcs0: SKIP (0.001s)
[360.960361] Starting dynamic subtest: vcs0
[360.962079] Dynamic subtest vcs0: SKIP (0.000s)
[360.968470] Starting dynamic subtest: vcs1
[360.970162] Dynamic subtest vcs1: SKIP (0.000s)
[360.976480] Starting dynamic subtest: vecs0
[360.978283] Dynamic subtest vecs0: SKIP (0.000s)
[360.984580] Starting dynamic subtest: ccs0
[360.986360] Dynamic subtest ccs0: SKIP (0.000s)
[360.988036] Subtest busy-hang: SKIP (0.042s)
[361.036418] [063/132] (661s left) kms_cursor_crc (cursor-sliding-max-size)
[361.326321] Starting subtest: cursor-sliding-max-size
[361.329171] Subtest cursor-sliding-max-size: SKIP (0.000s)
[361.489083] [064/132] (660s left) gem_exec_schedule (preempt-queue-contexts-chain)
[361.546085] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[361.855798] [065/132] (660s left) gem_mmap_gtt (big-copy)
[361.909549] Subtest big-copy: SKIP (0.000s)
[361.966814] [066/132] (660s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[362.054859] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[362.055779] Starting dynamic subtest: pipe-A
[362.121058] Dynamic subtest pipe-A: SUCCESS (0.066s)
[362.129626] Starting dynamic subtest: pipe-B
[363.274332] Dynamic subtest pipe-B: SUCCESS (1.152s)
[363.284393] Starting dynamic subtest: pipe-C
[364.375843] Dynamic subtest pipe-C: SUCCESS (1.099s)
[364.390365] Starting dynamic subtest: pipe-D
[365.515904] Dynamic subtest pipe-D: SUCCESS (1.137s)
[365.519294] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.462s)
[366.698025] [067/132] (655s left) kms_chamelium_color (ctm-0-50)
[366.798550] Subtest ctm-0-50: SKIP (0.000s)
[366.836094] [068/132] (655s left) kms_psr (pr-primary-page-flip)
[366.927531] Starting subtest: pr-primary-page-flip
[366.929321] Subtest pr-primary-page-flip: SKIP (0.000s)
[367.018419] [069/132] (655s left) kms_atomic (test-only)
[367.116841] Starting subtest: test-only
[367.170016] Starting dynamic subtest: pipe-A-eDP-1
[369.675213] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.505s)
[369.676071] Subtest test-only: SUCCESS (2.558s)
[370.490865] [070/132] (651s left) gem_spin_batch (spin-each)
[370.554736] Starting subtest: spin-each
[373.814217] Subtest spin-each: SUCCESS (3.259s)
[374.015501] [071/132] (648s left) gem_exec_params (batch-first)
[374.075064] Starting subtest: batch-first
[374.086656] Subtest batch-first: SUCCESS (0.011s)
[374.251360] [072/132] (648s left) gem_busy (semaphore)
[374.443519] Subtest semaphore: SKIP (0.000s)
[374.712996] [073/132] (647s left) kms_psr (pr-primary-blt)
[374.815327] Starting subtest: pr-primary-blt
[374.817083] Subtest pr-primary-blt: SKIP (0.000s)
[374.915556] [074/132] (647s left) kms_dp_link_training (uhbr-mst)
[375.004096] Starting subtest: uhbr-mst
[375.007243] Subtest uhbr-mst: SKIP (0.000s)
[375.093826] [075/132] (647s left) i915_suspend (debugfs-reader)
[375.162472] Starting subtest: debugfs-reader
[394.079257] Subtest debugfs-reader: SUCCESS (7.312s)
[399.270417] [076/132] (628s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[399.376381] Starting subtest: compare-crc-sanitycheck-nv12
[399.378389] Starting dynamic subtest: pipe-A-eDP-1
[400.025908] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.646s)
[400.033756] Starting dynamic subtest: pipe-B-eDP-1
[401.304612] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.276s)
[401.317692] Starting dynamic subtest: pipe-C-eDP-1
[402.502526] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.197s)
[402.509982] Starting dynamic subtest: pipe-D-eDP-1
[403.724023] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.220s)
[403.724997] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.345s)
[404.597416] [077/132] (623s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[404.692064] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[404.693822] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[404.816494] [078/132] (622s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[404.901623] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[404.903290] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[406.030953] [079/132] (621s left) perf_pmu (busy-idle-check-all)
[406.073143] Starting subtest: busy-idle-check-all
[406.074204] Starting dynamic subtest: rcs0
[406.748750] Dynamic subtest rcs0: SUCCESS (0.675s)
[406.760102] Starting dynamic subtest: bcs0
[407.401931] Dynamic subtest bcs0: SUCCESS (0.650s)
[407.413211] Starting dynamic subtest: vcs0
[408.059020] Dynamic subtest vcs0: FAIL (0.631s)
[408.066779] Starting dynamic subtest: vcs1
[408.683871] Dynamic subtest vcs1: FAIL (0.619s)
[408.691597] Starting dynamic subtest: vecs0
[409.308729] Dynamic subtest vecs0: FAIL (0.622s)
[409.317035] Starting dynamic subtest: ccs0
[409.932771] Dynamic subtest ccs0: FAIL (0.623s)
[409.933598] Subtest busy-idle-check-all: FAIL (3.829s)
[410.628201] [080/132] (617s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[410.728524] Starting subtest: psr-2p-pri-indfb-multidraw
[410.730320] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[410.854397] [081/132] (616s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[410.947627] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[410.949321] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[411.058951] [082/132] (616s left) gem_exec_balancer (fairslice)
[411.116814] Starting subtest: fairslice
[414.012120] Subtest fairslice: SUCCESS (2.892s)
[414.617228] [083/132] (613s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[414.670428] Starting subtest: etime-single-wait-all-available-unsubmitted
[414.773542] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.102s)
[414.869338] [084/132] (613s left) gem_exec_fence (syncobj-timeline-chain-engines)
[415.435849] Starting subtest: syncobj-timeline-chain-engines
[415.592281] Subtest syncobj-timeline-chain-engines: SUCCESS (0.157s)
[416.572079] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[416.574088] [085/132] (611s left) gem_pread (display)
[416.636842] Subtest display: SKIP (0.000s)
[416.696978] [086/132] (611s left) gem_sync (basic-all)
[416.770824] Starting subtest: basic-all
[419.800332] Subtest basic-all: SUCCESS (3.028s)
[420.007428] [087/132] (608s left) gem_mmap_offset (isolation)
[420.060875] Starting subtest: isolation
[420.069503] Subtest isolation: SUCCESS (0.006s)
[420.192099] [088/132] (607s left) kms_atomic_interruptible (legacy-dpms)
[420.287223] Starting subtest: legacy-dpms
[420.288082] Starting dynamic subtest: pipe-A-eDP-1
[427.750967] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.461s)
[427.751799] Subtest legacy-dpms: SUCCESS (7.463s)
[428.596532] [089/132] (599s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[428.699132] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[428.701088] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[428.826396] [090/132] (599s left) gem_flink_basic (bad-open)
[428.872402] Starting subtest: bad-open
[428.873298] Subtest bad-open: SUCCESS (0.000s)
[429.001053] [091/132] (599s left) kms_invalid_mode (int-max-clock)
[429.093372] Starting subtest: int-max-clock
[429.094313] Starting dynamic subtest: pipe-A-eDP-1
[429.376346] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.281s)
[429.384813] Starting dynamic subtest: pipe-B-eDP-1
[429.385700] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[429.392812] Starting dynamic subtest: pipe-C-eDP-1
[429.393737] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[429.401017] Starting dynamic subtest: pipe-D-eDP-1
[429.401938] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[429.402841] Subtest int-max-clock: SUCCESS (0.287s)
[430.229029] [092/132] (597s left) gem_exec_params (mmapped)
[430.287990] Starting subtest: mmapped
[430.290878] Subtest mmapped: SUCCESS (0.003s)
[430.451228] [093/132] (597s left) kms_plane_multiple (tiling-x)
[430.551051] Starting subtest: tiling-x
[430.551947] Starting dynamic subtest: pipe-A-eDP-1
[434.268076] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.715s)
[434.276394] Starting dynamic subtest: pipe-B-eDP-1
[438.588643] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.320s)
[438.603100] Starting dynamic subtest: pipe-C-eDP-1
[442.938110] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.346s)
[442.946471] Starting dynamic subtest: pipe-D-eDP-1
[447.479454] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.540s)
[447.480401] Subtest tiling-x: SUCCESS (16.927s)
[448.301759] [094/132] (579s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[448.399438] Starting subtest: fbc-rgb101010-draw-pwrite
[453.734113] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.334s)
[454.897171] [095/132] (573s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[454.998125] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[461.813345] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.812s)
[461.991903] [096/132] (566s left) kms_flip (2x-flip-vs-dpms)
[462.080390] Starting subtest: 2x-flip-vs-dpms
[462.090937] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[462.192298] [097/132] (566s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[462.303771] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[462.321711] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.018s)
[462.417882] [098/132] (565s left) kms_content_protection (atomic-hdcp14)
[462.563121] Starting subtest: atomic-hdcp14
[462.564977] Subtest atomic-hdcp14: SKIP (0.000s)
[462.737842] [099/132] (565s left) sw_sync (sync_merge)
[462.773072] Starting subtest: sync_merge
[462.774164] Subtest sync_merge: SUCCESS (0.000s)
[462.845607] [100/132] (565s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[462.949315] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[462.950337] Starting dynamic subtest: pipe-A-default-mode
[463.235342] Dynamic subtest pipe-A-default-mode: SKIP (0.284s)
[463.237441] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.286s)
[464.122884] [101/132] (564s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[464.230564] Starting subtest: cursor-vs-flip-atomic-transitions
[494.259592] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (30.025s)
[495.159851] [102/132] (533s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[495.243906] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[495.244885] Starting dynamic subtest: pipe-A-eDP-1
[495.306099] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.065s)
[495.599667] Starting dynamic subtest: pipe-B-eDP-1
[496.439925] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.848s)
[496.737510] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.497s)
[497.620086] [103/132] (530s left) kms_prime (basic-crc-vgem)
[497.697042] Starting subtest: basic-crc-vgem
[497.697931] Starting dynamic subtest: second-to-first
[498.592669] Dynamic subtest second-to-first: SUCCESS (0.897s)
[498.593608] Subtest basic-crc-vgem: SUCCESS (0.897s)
[498.693177] [104/132] (529s left) kms_properties (invalid-properties-legacy)
[498.815789] Starting subtest: invalid-properties-legacy
[498.923852] Subtest invalid-properties-legacy: SUCCESS (0.106s)
[499.014412] [105/132] (529s left) syncobj_timeline (wait-zero-handles)
[499.075399] Starting subtest: wait-zero-handles
[499.076455] Subtest wait-zero-handles: SUCCESS (0.000s)
[499.204994] [106/132] (529s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[504.268714] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[504.273308] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[505.145831] [107/132] (523s left) gem_pxp (reject-modify-context-protection-off-1)
[505.262931] Starting subtest: reject-modify-context-protection-off-1
[505.264090] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[505.396009] [108/132] (523s left) kms_selftest (drm_format_helper)
[505.436577] Starting subtest: drm_format_helper
[505.447561] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[505.595533] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.148s)
[505.604546] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[505.739239] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.144s)
[505.747018] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[505.884110] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.143s)
[505.893234] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[506.015204] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.132s)
[506.022916] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[506.160713] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.143s)
[506.169445] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[506.302198] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.142s)
[506.309908] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[506.444324] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.141s)
[506.452185] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[506.601233] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.155s)
[506.619403] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[506.739981] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.138s)
[506.758863] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[506.872625] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.130s)
[506.890695] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[507.037628] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.164s)
[507.052008] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[507.177379] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.138s)
[507.187392] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[507.316346] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.138s)
[507.324638] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[507.462783] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.144s)
[507.471073] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[507.600547] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.137s)
[507.608827] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[507.740223] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.139s)
[507.749687] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[507.897527] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.157s)
[507.898545] Subtest drm_format_helper: SUCCESS (2.462s)
[508.124928] [109/132] (520s left) gen3_mixed_blits
[508.260779] [110/132] (520s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[508.357935] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[508.359777] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[508.474088] [111/132] (520s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[508.571192] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[515.286553] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.716s)
[515.451079] [112/132] (513s left) gen7_exec_parse (cmd-crossing-page)
[515.516699] Subtest cmd-crossing-page: SKIP (0.000s)
[515.588684] [113/132] (513s left) gem_softpin (overlap)
[515.650273] Starting subtest: overlap
[516.558382] Subtest overlap: SUCCESS (0.905s)
[516.702341] [114/132] (512s left) gem_eio (in-flight-internal-immediate)
[516.935428] Starting subtest: in-flight-internal-immediate
[518.590411] Subtest in-flight-internal-immediate: SUCCESS (1.654s)
[518.795896] [115/132] (510s left) gem_wait (write-wait)
[518.861545] Starting subtest: write-wait
[518.862683] Starting dynamic subtest: all-engines
[519.999124] Dynamic subtest all-engines: SUCCESS (1.137s)
[520.025700] Starting dynamic subtest: rcs0
[521.070024] Dynamic subtest rcs0: SUCCESS (1.069s)
[521.086162] Starting dynamic subtest: bcs0
[522.131295] Dynamic subtest bcs0: SUCCESS (1.058s)
[522.148043] Starting dynamic subtest: vcs0
[523.184635] Dynamic subtest vcs0: SUCCESS (1.051s)
[523.206816] Starting dynamic subtest: vcs1
[524.245179] Dynamic subtest vcs1: SUCCESS (1.058s)
[524.261906] Starting dynamic subtest: vecs0
[525.297929] Dynamic subtest vecs0: SUCCESS (1.050s)
[525.314589] Starting dynamic subtest: ccs0
[526.355644] Dynamic subtest ccs0: SUCCESS (1.056s)
[526.358613] Subtest write-wait: SUCCESS (7.495s)
[526.572711] [116/132] (502s left) kms_chamelium_edid (dp-edid-read)
[526.962438] Subtest dp-edid-read: SKIP (0.000s)
[527.732957] [117/132] (501s left) gem_exec_balancer (parallel-bb-first)
[527.986682] Starting subtest: parallel-bb-first
[528.906951] Subtest parallel-bb-first: SUCCESS (0.920s)
[529.569452] [118/132] (499s left) kms_pm_rpm (fences)
[531.700208] Starting subtest: fences
[531.702824] Subtest fences: SKIP (0.000s)
[532.070209] [119/132] (497s left) gem_ctx_persistence (legacy-engines-queued)
[532.137387] Starting subtest: legacy-engines-queued
[532.138797] Starting dynamic subtest: render
[532.240970] Dynamic subtest render: SUCCESS (0.103s)
[532.253181] Starting dynamic subtest: bsd1
[533.451440] Dynamic subtest bsd1: SUCCESS (1.205s)
[533.462676] Starting dynamic subtest: bsd2
[535.496877] Dynamic subtest bsd2: SUCCESS (2.042s)
[535.507963] Starting dynamic subtest: blt
[535.534595] Dynamic subtest blt: SUCCESS (0.037s)
[535.543667] Starting dynamic subtest: vebox
[537.480981] Dynamic subtest vebox: SUCCESS (1.942s)
[537.482736] Subtest legacy-engines-queued: SUCCESS (5.342s)
[537.576976] [120/132] (491s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[537.686189] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[538.344561] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.659s)
[538.546139] [121/132] (490s left) gem_mmap_gtt (basic-wc)
[538.599276] Subtest basic-wc: SKIP (0.000s)
[538.662815] [122/132] (490s left) core_auth (getclient-simple)
[538.693610] Starting subtest: getclient-simple
[538.714045] Subtest getclient-simple: SUCCESS (0.021s)
[538.845724] [123/132] (490s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[538.931958] Starting subtest: addfb25-y-tiled-small-legacy
[538.933768] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[539.040494] [124/132] (490s left) perf (invalid-oa-exponent)
[539.139956] Starting subtest: invalid-oa-exponent
[539.152933] Subtest invalid-oa-exponent: SUCCESS (0.012s)
[539.193921] [125/132] (490s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[539.280431] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[539.281424] Starting dynamic subtest: pipe-A-default-mode
[540.414428] Dynamic subtest pipe-A-default-mode: SKIP (1.133s)
[540.416359] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.135s)
[540.613736] [126/132] (488s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[540.708199] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[540.710414] Starting dynamic subtest: A-eDP1
[544.308942] Dynamic subtest A-eDP1: SUCCESS (3.598s)
[544.317531] Starting dynamic subtest: B-eDP1
[547.528541] Dynamic subtest B-eDP1: SUCCESS (3.218s)
[547.536509] Starting dynamic subtest: C-eDP1
[550.629560] Dynamic subtest C-eDP1: SUCCESS (3.100s)
[550.637815] Starting dynamic subtest: D-eDP1
[553.750857] Dynamic subtest D-eDP1: SUCCESS (3.120s)
[553.753378] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.044s)
[554.573710] [127/132] (474s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[554.640196] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[554.641176] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[554.765499] [128/132] (474s left) kms_vrr (max-min)
[554.859959] Starting subtest: max-min
[554.862718] Subtest max-min: SKIP (0.000s)
[554.951447] [129/132] (474s left) kms_rotation_crc (sprite-rotation-90)
[555.042635] Starting subtest: sprite-rotation-90
[555.044337] Subtest sprite-rotation-90: SKIP (0.001s)
[555.134058] [130/132] (474s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[555.230554] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[555.232358] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[555.371113] [131/132] (474s left) kms_cursor_crc (cursor-offscreen-64x64)
[555.549704] Starting subtest: cursor-offscreen-64x64
[555.553167] Starting dynamic subtest: pipe-A-eDP-1
[557.503259] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.950s)
[557.529474] Starting dynamic subtest: pipe-D-eDP-1
[560.067211] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.544s)
[560.068839] Subtest cursor-offscreen-64x64: SUCCESS (4.516s)
[560.950448] [132/132] (468s left) kms_color_pipeline (plane-lut1d-lut1d)
[561.046080] Starting subtest: plane-lut1d-lut1d
[561.060342] Starting dynamic subtest: pipe-A-eDP-1
[561.496620] Dynamic subtest pipe-A-eDP-1: FAIL (0.294s)
[562.414574] Starting dynamic subtest: pipe-B-eDP-1
[562.891438] Dynamic subtest pipe-B-eDP-1: FAIL (0.291s)
[563.746070] Starting dynamic subtest: pipe-C-eDP-1
[564.200529] Dynamic subtest pipe-C-eDP-1: FAIL (0.235s)
[565.074854] Starting dynamic subtest: pipe-D-eDP-1
[565.517518] Dynamic subtest pipe-D-eDP-1: FAIL (0.236s)
[565.594137] Subtest plane-lut1d-lut1d: FAIL (4.547s)
[566.480313] 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.