[59.423201] Initializing watchdogs
[59.423271]   /dev/watchdog0
[59.438779] [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.443329] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.446346] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.449806] [001/132] (960s left) gem_exec_store (cachelines)
[59.541155] Starting subtest: cachelines
[59.543537] Starting dynamic subtest: rcs0
[59.618897] Dynamic subtest rcs0: SUCCESS (0.078s)
[59.627958] Starting dynamic subtest: bcs0
[59.675791] Dynamic subtest bcs0: SUCCESS (0.056s)
[59.685030] Starting dynamic subtest: vcs0
[59.738958] Dynamic subtest vcs0: SUCCESS (0.063s)
[59.747477] Starting dynamic subtest: vcs1
[59.803830] Dynamic subtest vcs1: SUCCESS (0.065s)
[59.813098] Starting dynamic subtest: vecs0
[59.870377] Dynamic subtest vecs0: SUCCESS (0.065s)
[59.879262] Starting dynamic subtest: ccs0
[59.936092] Dynamic subtest ccs0: SUCCESS (0.065s)
[59.938862] Subtest cachelines: SUCCESS (0.395s)
[60.137873] [002/132] (959s left) gem_pread (bench)
[60.199380] Subtest bench: SKIP (0.000s)
[60.263544] [003/132] (959s left) syncobj_wait (invalid-signal-illegal-handle)
[60.324069] Starting subtest: invalid-signal-illegal-handle
[60.326381] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[60.456649] [004/132] (959s left) kms_vblank (query-forked-busy-hang)
[60.558798] Starting subtest: query-forked-busy-hang
[60.560739] Starting dynamic subtest: pipe-A-eDP-1
[86.677945] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.106s)
[86.690669] Starting dynamic subtest: pipe-D-eDP-1
[111.632662] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.950s)
[111.634676] Subtest query-forked-busy-hang: SUCCESS (51.060s)
[112.472659] [005/132] (907s left) kms_psr (fbc-pr-primary-mmap-cpu)
[112.570042] Starting subtest: fbc-pr-primary-mmap-cpu
[112.574281] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[112.664156] [006/132] (907s left) kms_plane_cursor (overlay)
[112.760822] Starting subtest: overlay
[112.765481] Starting dynamic subtest: pipe-A-eDP-1-size-64
[115.193573] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.433s)
[115.251873] Starting dynamic subtest: pipe-A-eDP-1-size-128
[117.639464] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.394s)
[117.698318] Starting dynamic subtest: pipe-A-eDP-1-size-256
[120.163465] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.471s)
[120.515885] Starting dynamic subtest: pipe-B-eDP-1-size-64
[123.676045] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.169s)
[123.733241] Starting dynamic subtest: pipe-B-eDP-1-size-128
[126.154696] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.427s)
[126.212254] Starting dynamic subtest: pipe-B-eDP-1-size-256
[128.610297] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.405s)
[128.958089] Starting dynamic subtest: pipe-C-eDP-1-size-64
[131.717522] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.769s)
[131.753150] Starting dynamic subtest: pipe-C-eDP-1-size-128
[133.773877] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.024s)
[133.811862] Starting dynamic subtest: pipe-C-eDP-1-size-256
[135.820256] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.013s)
[136.144206] Starting dynamic subtest: pipe-D-eDP-1-size-64
[138.893625] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.759s)
[138.930198] Starting dynamic subtest: pipe-D-eDP-1-size-128
[140.983490] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.059s)
[141.018088] Starting dynamic subtest: pipe-D-eDP-1-size-256
[143.061993] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.046s)
[143.380266] Subtest overlay: SUCCESS (30.616s)
[144.220344] [007/132] (875s left) perf (i915-ref-count)
[144.316756] Starting subtest: i915-ref-count
[144.376663] Subtest i915-ref-count: SUCCESS (0.059s)
[144.427582] [008/132] (875s left) gem_exec_schedule (smoketest-all)
[144.501077] Starting subtest: smoketest-all
[176.899802] Subtest smoketest-all: SUCCESS (32.347s)
[178.025569] [009/132] (842s left) gem_lmem_swapping (heavy-random)
[178.065847] Subtest heavy-random: SKIP (0.000s)
[178.105611] [010/132] (842s left) kms_psr (psr-primary-mmap-gtt)
[178.205998] Starting subtest: psr-primary-mmap-gtt
[178.208321] Starting dynamic subtest: eDP-1
[179.593838] Dynamic subtest eDP-1: SKIP (1.385s)
[179.598506] Subtest psr-primary-mmap-gtt: SKIP (1.387s)
[179.772738] [011/132] (840s left) perf (whitelisted-registers-userspace-config)
[179.881055] Starting subtest: whitelisted-registers-userspace-config
[179.883851] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[179.921622] [012/132] (840s left) gem_ppgtt (blt-vs-render-ctx0)
[179.978459] Starting subtest: blt-vs-render-ctx0
[213.040183] Subtest blt-vs-render-ctx0: SUCCESS (33.058s)
[213.250261] [013/132] (807s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[213.394996] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[213.399413] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[213.514069] [014/132] (806s left) gem_mmap_gtt (basic)
[213.573791] Subtest basic: SKIP (0.000s)
[213.631102] [015/132] (806s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[213.693090] Starting subtest: invalid-single-wait-all-unsubmitted
[213.695516] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[213.836156] [016/132] (806s left) gem_ctx_engines (execute-one)
[213.905737] Starting subtest: execute-one
[216.240782] Subtest execute-one: SUCCESS (2.335s)
[216.444580] [017/132] (804s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[216.552597] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[216.556776] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[216.694859] [018/132] (803s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[216.806242] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[216.808490] Starting dynamic subtest: pipe-A-eDP-1
[216.828968] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[216.838250] Starting dynamic subtest: pipe-B-eDP-1
[216.857587] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[216.864077] Starting dynamic subtest: pipe-C-eDP-1
[216.883273] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[216.889934] Starting dynamic subtest: pipe-D-eDP-1
[216.909144] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[216.910996] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.011s)
[219.105239] [019/132] (801s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[219.167979] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[219.229932] [020/132] (801s left) api_intel_allocator (two-level-inception-interruptible)
[219.280075] Starting subtest: two-level-inception-interruptible
[224.687509] Subtest two-level-inception-interruptible: SUCCESS (5.408s)
[224.787655] [021/132] (795s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[229.875792] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[229.878105] Starting dynamic subtest: pipe-A-eDP-1
[236.471378] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.592s)
[236.478707] Starting dynamic subtest: pipe-B-eDP-1
[243.125335] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.654s)
[243.127633] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.250s)
[244.019465] [022/132] (776s left) i915_pciid
[244.210762] [023/132] (776s left) i915_pm_rpm (sysfs-read)
[246.070598] Starting subtest: sysfs-read
[247.237143] Subtest sysfs-read: SUCCESS (1.168s)
[247.633447] [024/132] (773s left) gem_exec_reloc (basic-cpu-noreloc)
[247.698674] Subtest basic-cpu-noreloc: SKIP (0.000s)
[247.765657] [025/132] (773s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[247.868307] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[247.872262] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[248.009844] [026/132] (772s left) sw_sync (timeline_closed_signaled)
[248.047734] Starting subtest: timeline_closed_signaled
[248.050266] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[248.120147] [027/132] (772s left) gem_eio (context-create)
[248.344636] Starting subtest: context-create
[248.685577] Subtest context-create: SUCCESS (0.341s)
[248.850169] [028/132] (772s left) kms_fb_coherency (memset-crc)
[248.932417] Starting subtest: memset-crc
[248.934114] Starting dynamic subtest: mmap-offset-wc
[249.297403] Dynamic subtest mmap-offset-wc: SUCCESS (0.365s)
[249.590173] Starting dynamic subtest: mmap-offset-uc
[255.677335] Dynamic subtest mmap-offset-uc: SUCCESS (6.096s)
[255.975196] Subtest memset-crc: SUCCESS (7.043s)
[256.790513] [029/132] (764s left) gem_ctx_persistence (engines-hostile-preempt)
[256.867875] Starting subtest: engines-hostile-preempt
[256.869884] Starting dynamic subtest: rcs0
[256.953530] Dynamic subtest rcs0: SUCCESS (0.088s)
[256.963357] Starting dynamic subtest: bcs0
[257.021772] Dynamic subtest bcs0: SUCCESS (0.066s)
[257.031725] Starting dynamic subtest: vcs0
[258.897271] Dynamic subtest vcs0: SUCCESS (1.871s)
[258.908276] Starting dynamic subtest: vcs1
[260.868178] Dynamic subtest vcs1: SUCCESS (1.968s)
[260.878356] Starting dynamic subtest: vecs0
[262.915359] Dynamic subtest vecs0: SUCCESS (2.045s)
[262.925212] Starting dynamic subtest: ccs0
[263.020805] Dynamic subtest ccs0: SUCCESS (0.104s)
[263.023285] Subtest engines-hostile-preempt: SUCCESS (6.157s)
[263.122882] [030/132] (757s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[263.227207] Starting subtest: psr-rgb565-draw-pwrite
[270.174084] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.951s)
[270.322778] [031/132] (750s left) gem_render_copy (y-tiled-to-vebox-linear)
[270.394685] Starting subtest: y-tiled-to-vebox-linear
[270.399272] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[270.600574] [032/132] (750s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[270.653134] Starting subtest: multi-wait-for-submit-submitted-signaled
[270.655518] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[270.793490] [033/132] (750s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[270.890589] Starting subtest: x-tiled-64bpp-rotate-90
[270.909661] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[271.080411] [034/132] (750s left) kms_psr (fbc-psr2-cursor-render)
[271.167220] Starting subtest: fbc-psr2-cursor-render
[271.169348] Starting dynamic subtest: eDP-1
[271.173577] Dynamic subtest eDP-1: SKIP (0.000s)
[271.177790] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[271.261048] [035/132] (749s left) kms_getfb (getfb-handle-protection)
[271.352325] Starting subtest: getfb-handle-protection
[271.381316] Subtest getfb-handle-protection: SUCCESS (0.030s)
[271.527436] [036/132] (749s left) sw_sync (sync_merge_invalid)
[271.566119] Starting subtest: sync_merge_invalid
[271.590611] Subtest sync_merge_invalid: SUCCESS (0.025s)
[271.664374] [037/132] (749s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[271.763359] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[276.348567] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.587s)
[277.509473] [038/132] (743s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[277.613526] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[284.899755] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.296s)
[285.068978] [039/132] (736s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[285.191877] Starting subtest: cursorB-vs-flipA-toggle
[285.196440] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[285.351175] [040/132] (736s left) kms_rmfb (rmfb-ioctl)
[285.458307] Starting subtest: rmfb-ioctl
[285.460192] Starting dynamic subtest: pipe-A-eDP-1
[285.562439] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.105s)
[285.575241] Starting dynamic subtest: pipe-B-eDP-1
[286.761356] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.196s)
[286.770941] Starting dynamic subtest: pipe-C-eDP-1
[287.904290] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.141s)
[287.916333] Starting dynamic subtest: pipe-D-eDP-1
[289.031707] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s)
[289.033903] Subtest rmfb-ioctl: SUCCESS (3.576s)
[290.262722] [041/132] (731s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[290.656429] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[291.449615] [042/132] (730s left) sysfs_timeslice_duration (invalid)
[291.510562] Starting subtest: invalid
[291.512931] Starting dynamic subtest: vecs0
[291.515218] Dynamic subtest vecs0: SUCCESS (0.000s)
[291.522892] Starting dynamic subtest: ccs0
[291.525126] Dynamic subtest ccs0: SUCCESS (0.000s)
[291.532559] Starting dynamic subtest: vcs0
[291.534795] Dynamic subtest vcs0: SUCCESS (0.000s)
[291.542327] Starting dynamic subtest: bcs0
[291.544260] Dynamic subtest bcs0: SUCCESS (0.000s)
[291.551671] Starting dynamic subtest: rcs0
[291.553835] Dynamic subtest rcs0: SUCCESS (0.000s)
[291.561228] Starting dynamic subtest: vcs1
[291.563397] Dynamic subtest vcs1: SUCCESS (0.000s)
[291.565617] Subtest invalid: SUCCESS (0.005s)
[291.613882] [043/132] (729s left) syncobj_eventfd (timeline-wait)
[291.667077] Starting subtest: timeline-wait
[291.675892] Subtest timeline-wait: SUCCESS (0.011s)
[291.800979] [044/132] (729s left) kms_pm_backlight (brightness-with-dpms)
[291.890446] Starting subtest: brightness-with-dpms
[291.938722] Starting dynamic subtest: eDP-1
[294.688272] Dynamic subtest eDP-1: SUCCESS (2.749s)
[294.690579] Subtest brightness-with-dpms: SUCCESS (2.800s)
[295.556095] [045/132] (726s left) kms_busy (extended-modeset-hang-oldfb)
[295.662274] Starting subtest: extended-modeset-hang-oldfb
[295.664187] Starting dynamic subtest: pipe-A
[307.123682] Dynamic subtest pipe-A: SUCCESS (11.459s)
[307.133405] Starting dynamic subtest: pipe-D
[319.379191] Dynamic subtest pipe-D: SUCCESS (12.253s)
[319.381500] Subtest extended-modeset-hang-oldfb: SUCCESS (23.716s)
[320.184874] [046/132] (701s left) syncobj_wait (multi-wait-all-signaled)
[320.254558] Starting subtest: multi-wait-all-signaled
[320.256907] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[320.381816] [047/132] (701s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[320.475884] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[320.478237] Starting dynamic subtest: pipe-A-eDP-1
[320.500887] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[320.510524] Starting dynamic subtest: pipe-B-eDP-1
[320.532357] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[320.539955] Starting dynamic subtest: pipe-C-eDP-1
[320.561562] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[320.568835] Starting dynamic subtest: pipe-D-eDP-1
[320.590787] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[320.595230] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[322.763264] [048/132] (698s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[322.868939] Starting subtest: yf-tiled-8bpp-rotate-180
[322.873297] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[322.991323] [049/132] (698s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[323.096726] Starting subtest: psr-rgb565-draw-mmap-cpu
[329.878448] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.784s)
[330.009472] [050/132] (691s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[330.107990] Starting subtest: yf-tiled-16bpp-rotate-90
[330.112324] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[330.257291] [051/132] (691s left) kms_addfb_basic (clobberred-modifier)
[330.319515] Starting subtest: clobberred-modifier
[330.323948] Subtest clobberred-modifier: SKIP (0.000s)
[330.470401] [052/132] (691s left) gem_exec_reloc (basic-write-read-active)
[330.533709] Subtest basic-write-read-active: SKIP (0.000s)
[330.609641] [053/132] (691s left) dmabuf (all-tests)
[330.649110] Starting subtest: all-tests
[330.651370] Starting dynamic subtest: sanitycheck
[330.803473] Dynamic subtest sanitycheck: SUCCESS (0.152s)
[330.815042] Starting dynamic subtest: dma_fence
[331.051494] Dynamic subtest dma_fence: SUCCESS (0.245s)
[331.062774] Starting dynamic subtest: dma_fence_chain
[339.498076] Dynamic subtest dma_fence_chain: SUCCESS (8.442s)
[339.508781] Starting dynamic subtest: dma_fence_unwrap
[339.626098] Dynamic subtest dma_fence_unwrap: SUCCESS (0.127s)
[339.637057] Starting dynamic subtest: dma_resv
[339.774873] Dynamic subtest dma_resv: SUCCESS (0.146s)
[339.777633] Subtest all-tests: SUCCESS (9.125s)
[339.863279] [054/132] (682s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[340.175193] Starting subtest: cursor-rapid-movement-512x170
[340.179991] Subtest cursor-rapid-movement-512x170: SKIP (0.002s)
[340.376697] [055/132] (681s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[340.443655] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[340.445975] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[340.558787] [056/132] (681s left) kms_big_fb (linear-16bpp-rotate-90)
[340.662777] Starting subtest: linear-16bpp-rotate-90
[340.681953] Subtest linear-16bpp-rotate-90: SKIP (0.018s)
[340.838316] [057/132] (681s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[340.934959] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[340.938419] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[341.076711] [058/132] (681s left) gem_exec_schedule (noreorder-priority)
[341.260783] Starting subtest: noreorder-priority
[341.263588] Starting dynamic subtest: rcs0
[342.413063] Dynamic subtest rcs0: SUCCESS (1.153s)
[342.425284] Starting dynamic subtest: vcs0
[343.911598] Dynamic subtest vcs0: SUCCESS (1.495s)
[343.926951] Starting dynamic subtest: vcs1
[345.916359] Dynamic subtest vcs1: SUCCESS (2.002s)
[345.930594] Starting dynamic subtest: vecs0
[347.908053] Dynamic subtest vecs0: SUCCESS (1.989s)
[347.923436] Starting dynamic subtest: ccs0
[349.073817] Dynamic subtest ccs0: SUCCESS (1.165s)
[349.076569] Subtest noreorder-priority: SUCCESS (7.816s)
[349.283361] [059/132] (672s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[349.385929] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[355.975849] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.592s)
[356.136539] [060/132] (666s left) perf (polling-small-buf)
[356.258583] Starting subtest: polling-small-buf
[356.349084] Subtest polling-small-buf: SUCCESS (0.088s)
[356.392706] [061/132] (665s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[356.476147] Starting subtest: bad-pixel-format-y-tiled-ccs
[356.478319] Starting dynamic subtest: pipe-A-eDP-1
[356.501119] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[356.511318] Starting dynamic subtest: pipe-B-eDP-1
[356.533048] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[356.540983] Starting dynamic subtest: pipe-C-eDP-1
[356.562815] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[356.572625] Starting dynamic subtest: pipe-D-eDP-1
[356.594189] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[356.598228] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[358.690282] [062/132] (663s left) i915_drm_fdinfo (busy-hang)
[358.735272] Starting subtest: busy-hang
[358.737603] Starting dynamic subtest: rcs0
[358.771112] Dynamic subtest rcs0: SKIP (0.036s)
[358.781881] Starting dynamic subtest: bcs0
[358.786171] Dynamic subtest bcs0: SKIP (0.001s)
[358.797284] Starting dynamic subtest: vcs0
[358.801763] Dynamic subtest vcs0: SKIP (0.000s)
[358.810848] Starting dynamic subtest: vcs1
[358.814910] Dynamic subtest vcs1: SKIP (0.000s)
[358.822147] Starting dynamic subtest: vecs0
[358.826167] Dynamic subtest vecs0: SKIP (0.000s)
[358.833394] Starting dynamic subtest: ccs0
[358.837430] Dynamic subtest ccs0: SKIP (0.000s)
[358.841368] Subtest busy-hang: SKIP (0.043s)
[358.874381] [063/132] (663s left) kms_cursor_crc (cursor-sliding-max-size)
[359.178749] Starting subtest: cursor-sliding-max-size
[359.185371] Subtest cursor-sliding-max-size: SKIP (0.000s)
[359.329529] [064/132] (663s left) gem_exec_schedule (preempt-queue-contexts-chain)
[359.397035] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[359.712861] [065/132] (662s left) gem_mmap_gtt (big-copy)
[359.773718] Subtest big-copy: SKIP (0.000s)
[359.842566] [066/132] (662s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[359.940492] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[359.942863] Starting dynamic subtest: pipe-A
[360.002983] Dynamic subtest pipe-A: SUCCESS (0.065s)
[360.016416] Starting dynamic subtest: pipe-B
[361.196536] Dynamic subtest pipe-B: SUCCESS (1.192s)
[361.204601] Starting dynamic subtest: pipe-C
[362.307035] Dynamic subtest pipe-C: SUCCESS (1.109s)
[362.322641] Starting dynamic subtest: pipe-D
[363.435909] Dynamic subtest pipe-D: SUCCESS (1.126s)
[363.438351] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.499s)
[364.618915] [067/132] (657s left) kms_chamelium_color (ctm-0-50)
[364.729259] Subtest ctm-0-50: SKIP (0.000s)
[364.783136] [068/132] (657s left) kms_psr (pr-primary-page-flip)
[364.896330] Starting subtest: pr-primary-page-flip
[364.900411] Subtest pr-primary-page-flip: SKIP (0.000s)
[364.997857] [069/132] (657s left) kms_atomic (test-only)
[365.090301] Starting subtest: test-only
[365.138207] Starting dynamic subtest: pipe-A-eDP-1
[367.603735] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.462s)
[367.605626] Subtest test-only: SUCCESS (2.513s)
[368.417135] [070/132] (654s left) gem_spin_batch (spin-each)
[368.476171] Starting subtest: spin-each
[371.837092] Subtest spin-each: SUCCESS (3.362s)
[372.123737] [071/132] (650s left) gem_exec_params (batch-first)
[372.191881] Starting subtest: batch-first
[372.199620] Subtest batch-first: SUCCESS (0.008s)
[372.363030] [072/132] (650s left) gem_busy (semaphore)
[372.556823] Subtest semaphore: SKIP (0.000s)
[372.842888] [073/132] (649s left) kms_psr (pr-primary-blt)
[372.949987] Starting subtest: pr-primary-blt
[372.954386] Subtest pr-primary-blt: SKIP (0.000s)
[373.045715] [074/132] (649s left) kms_dp_link_training (uhbr-mst)
[373.150247] Starting subtest: uhbr-mst
[373.158964] Subtest uhbr-mst: SKIP (0.000s)
[373.249026] [075/132] (649s left) i915_suspend (debugfs-reader)
[373.314154] Starting subtest: debugfs-reader
[391.790359] Subtest debugfs-reader: SUCCESS (6.878s)
[391.960805] [076/132] (630s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[392.063591] Starting subtest: compare-crc-sanitycheck-nv12
[392.065974] Starting dynamic subtest: pipe-A-eDP-1
[392.754901] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.687s)
[392.764773] Starting dynamic subtest: pipe-B-eDP-1
[394.038174] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.276s)
[394.048025] Starting dynamic subtest: pipe-C-eDP-1
[395.212767] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.166s)
[395.222492] Starting dynamic subtest: pipe-D-eDP-1
[396.505710] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.289s)
[396.508394] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.424s)
[397.341066] [077/132] (625s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[397.436754] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[397.440692] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[397.562065] [078/132] (625s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[397.663524] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[397.668167] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[398.768276] [079/132] (624s left) perf_pmu (busy-idle-check-all)
[398.807460] Starting subtest: busy-idle-check-all
[398.809870] Starting dynamic subtest: rcs0
[399.476456] Dynamic subtest rcs0: SUCCESS (0.668s)
[399.488986] Starting dynamic subtest: bcs0
[400.129815] Dynamic subtest bcs0: SUCCESS (0.650s)
[400.142773] Starting dynamic subtest: vcs0
[400.846468] Dynamic subtest vcs0: FAIL (0.641s)
[400.856160] Starting dynamic subtest: vcs1
[401.480700] Dynamic subtest vcs1: FAIL (0.621s)
[401.491150] Starting dynamic subtest: vecs0
[402.105651] Dynamic subtest vecs0: FAIL (0.621s)
[402.116405] Starting dynamic subtest: ccs0
[402.726980] Dynamic subtest ccs0: FAIL (0.621s)
[402.729039] Subtest busy-idle-check-all: FAIL (3.830s)
[403.586114] [080/132] (619s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[403.696968] Starting subtest: psr-2p-pri-indfb-multidraw
[403.701565] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[403.831308] [081/132] (619s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[403.933008] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[403.937397] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[404.049445] [082/132] (619s left) gem_exec_balancer (fairslice)
[404.110406] Starting subtest: fairslice
[406.981927] Subtest fairslice: SUCCESS (2.866s)
[407.580513] [083/132] (615s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[407.644951] Starting subtest: etime-single-wait-all-available-unsubmitted
[407.749726] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.105s)
[407.853344] [084/132] (615s left) gem_exec_fence (syncobj-timeline-chain-engines)
[408.420470] Starting subtest: syncobj-timeline-chain-engines
[408.586746] Subtest syncobj-timeline-chain-engines: SUCCESS (0.166s)
[409.632114] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[409.635016] [085/132] (613s left) gem_pread (display)
[409.700175] Subtest display: SKIP (0.000s)
[409.774757] [086/132] (613s left) gem_sync (basic-all)
[409.850203] Starting subtest: basic-all
[413.476984] Subtest basic-all: SUCCESS (3.631s)
[413.686724] [087/132] (609s left) gem_mmap_offset (isolation)
[413.747535] Starting subtest: isolation
[413.768888] Subtest isolation: SUCCESS (0.015s)
[413.899639] [088/132] (609s left) kms_atomic_interruptible (legacy-dpms)
[414.005090] Starting subtest: legacy-dpms
[414.007396] Starting dynamic subtest: pipe-A-eDP-1
[421.384042] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.374s)
[421.386301] Subtest legacy-dpms: SUCCESS (7.375s)
[422.257793] [089/132] (601s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[422.387953] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[422.392452] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[422.505232] [090/132] (601s left) gem_flink_basic (bad-open)
[422.573889] Starting subtest: bad-open
[422.576245] Subtest bad-open: SUCCESS (0.000s)
[422.709468] [091/132] (600s left) kms_invalid_mode (int-max-clock)
[422.812828] Starting subtest: int-max-clock
[422.815185] Starting dynamic subtest: pipe-A-eDP-1
[423.101934] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.293s)
[423.112261] Starting dynamic subtest: pipe-B-eDP-1
[423.114198] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[423.122559] Starting dynamic subtest: pipe-C-eDP-1
[423.124921] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[423.133571] Starting dynamic subtest: pipe-D-eDP-1
[423.135610] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[423.137597] Subtest int-max-clock: SUCCESS (0.299s)
[423.975925] [092/132] (599s left) gem_exec_params (mmapped)
[424.036498] Starting subtest: mmapped
[424.042023] Subtest mmapped: SUCCESS (0.003s)
[424.192352] [093/132] (599s left) kms_plane_multiple (tiling-x)
[424.297503] Starting subtest: tiling-x
[424.299445] Starting dynamic subtest: pipe-A-eDP-1
[428.113170] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.814s)
[428.123958] Starting dynamic subtest: pipe-B-eDP-1
[432.537566] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.419s)
[432.548223] Starting dynamic subtest: pipe-C-eDP-1
[436.838745] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.300s)
[436.853889] Starting dynamic subtest: pipe-D-eDP-1
[441.166652] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.324s)
[441.168971] Subtest tiling-x: SUCCESS (16.862s)
[442.009373] [094/132] (581s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[442.107353] Starting subtest: fbc-rgb101010-draw-pwrite
[447.447559] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.341s)
[448.616293] [095/132] (575s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[448.723522] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[455.566179] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.842s)
[455.749930] [096/132] (568s left) kms_flip (2x-flip-vs-dpms)
[455.848371] Starting subtest: 2x-flip-vs-dpms
[455.856906] Subtest 2x-flip-vs-dpms: SKIP (0.010s)
[456.005959] [097/132] (567s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[456.097462] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[456.107811] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.010s)
[456.243366] [098/132] (567s left) kms_content_protection (atomic-hdcp14)
[456.392597] Starting subtest: atomic-hdcp14
[456.397059] Subtest atomic-hdcp14: SKIP (0.000s)
[456.549480] [099/132] (567s left) sw_sync (sync_merge)
[456.583778] Starting subtest: sync_merge
[456.585716] Subtest sync_merge: SUCCESS (0.000s)
[456.664540] [100/132] (567s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[456.757841] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[456.759991] Starting dynamic subtest: pipe-A-default-mode
[457.052838] Dynamic subtest pipe-A-default-mode: SKIP (0.292s)
[457.058051] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.293s)
[457.948137] [101/132] (566s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[458.071487] Starting subtest: cursor-vs-flip-atomic-transitions
[487.843304] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (29.759s)
[488.697834] [102/132] (535s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[488.813887] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[488.815772] Starting dynamic subtest: pipe-A-eDP-1
[488.886014] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.070s)
[489.179157] Starting dynamic subtest: pipe-B-eDP-1
[489.959391] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.789s)
[490.258744] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.446s)
[491.135036] [103/132] (532s left) kms_prime (basic-crc-vgem)
[491.225057] Starting subtest: basic-crc-vgem
[491.227434] Starting dynamic subtest: second-to-first
[492.171605] Dynamic subtest second-to-first: SUCCESS (0.951s)
[492.173513] Subtest basic-crc-vgem: SUCCESS (0.952s)
[492.287118] [104/132] (531s left) kms_properties (invalid-properties-legacy)
[492.431465] Starting subtest: invalid-properties-legacy
[492.531775] Subtest invalid-properties-legacy: SUCCESS (0.101s)
[492.625446] [105/132] (531s left) syncobj_timeline (wait-zero-handles)
[492.683130] Starting subtest: wait-zero-handles
[492.685146] Subtest wait-zero-handles: SUCCESS (0.000s)
[492.820794] [106/132] (531s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[497.889992] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[497.894714] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[498.785097] [107/132] (525s left) gem_pxp (reject-modify-context-protection-off-1)
[498.906511] Starting subtest: reject-modify-context-protection-off-1
[498.908700] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[499.053894] [108/132] (525s left) kms_selftest (drm_format_helper)
[499.093731] Starting subtest: drm_format_helper
[499.105785] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[499.212495] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.106s)
[499.224037] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[499.307284] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.095s)
[499.318309] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[499.457425] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.150s)
[499.467619] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[499.608419] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.150s)
[499.617278] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[499.746643] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.136s)
[499.757684] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[499.881056] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.135s)
[499.890279] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[500.003184] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.119s)
[500.013618] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[500.130332] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.126s)
[500.139210] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[500.269997] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.140s)
[500.278809] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[500.410501] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.139s)
[500.419049] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[500.552806] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.142s)
[500.561670] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[500.685048] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.131s)
[500.693876] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[500.838228] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.152s)
[500.848654] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[500.987980] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.148s)
[500.996778] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[501.123250] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.135s)
[501.132793] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[501.257732] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.136s)
[501.267509] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[501.406174] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.146s)
[501.408340] Subtest drm_format_helper: SUCCESS (2.312s)
[501.619937] [109/132] (522s left) gen3_mixed_blits
[501.751253] [110/132] (522s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[501.856689] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[501.861071] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[501.995119] [111/132] (522s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[502.094787] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[508.848707] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.760s)
[508.989595] [112/132] (515s left) gen7_exec_parse (cmd-crossing-page)
[509.056774] Subtest cmd-crossing-page: SKIP (0.000s)
[509.137424] [113/132] (515s left) gem_softpin (overlap)
[509.207438] Starting subtest: overlap
[510.078061] Subtest overlap: SUCCESS (0.872s)
[510.218555] [114/132] (514s left) gem_eio (in-flight-internal-immediate)
[510.450187] Starting subtest: in-flight-internal-immediate
[511.607168] Subtest in-flight-internal-immediate: SUCCESS (1.155s)
[511.784773] [115/132] (512s left) gem_wait (write-wait)
[511.852464] Starting subtest: write-wait
[511.854714] Starting dynamic subtest: all-engines
[512.998829] Dynamic subtest all-engines: SUCCESS (1.149s)
[513.016183] Starting dynamic subtest: rcs0
[514.060703] Dynamic subtest rcs0: SUCCESS (1.060s)
[514.078930] Starting dynamic subtest: bcs0
[515.119748] Dynamic subtest bcs0: SUCCESS (1.057s)
[515.137429] Starting dynamic subtest: vcs0
[516.175899] Dynamic subtest vcs0: SUCCESS (1.053s)
[516.192514] Starting dynamic subtest: vcs1
[517.228184] Dynamic subtest vcs1: SUCCESS (1.051s)
[517.244842] Starting dynamic subtest: vecs0
[518.281858] Dynamic subtest vecs0: SUCCESS (1.051s)
[518.298015] Starting dynamic subtest: ccs0
[519.337285] Dynamic subtest ccs0: SUCCESS (1.054s)
[519.339779] Subtest write-wait: SUCCESS (7.490s)
[519.565215] [116/132] (505s left) kms_chamelium_edid (dp-edid-read)
[519.981232] Subtest dp-edid-read: SKIP (0.000s)
[520.750566] [117/132] (503s left) gem_exec_balancer (parallel-bb-first)
[521.021248] Starting subtest: parallel-bb-first
[521.905596] Subtest parallel-bb-first: SUCCESS (0.885s)
[522.592600] [118/132] (502s left) kms_pm_rpm (fences)
[524.714298] Starting subtest: fences
[524.719240] Subtest fences: SKIP (0.000s)
[525.095944] [119/132] (499s left) gem_ctx_persistence (legacy-engines-queued)
[525.172528] Starting subtest: legacy-engines-queued
[525.174875] Starting dynamic subtest: render
[525.289390] Dynamic subtest render: SUCCESS (0.116s)
[525.301142] Starting dynamic subtest: bsd1
[526.464404] Dynamic subtest bsd1: SUCCESS (1.171s)
[526.477039] Starting dynamic subtest: bsd2
[528.448866] Dynamic subtest bsd2: SUCCESS (1.980s)
[528.460627] Starting dynamic subtest: blt
[528.515376] Dynamic subtest blt: SUCCESS (0.064s)
[528.526808] Starting dynamic subtest: vebox
[530.496405] Dynamic subtest vebox: SUCCESS (1.977s)
[530.499370] Subtest legacy-engines-queued: SUCCESS (5.324s)
[530.600790] [120/132] (494s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[530.716047] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[531.373375] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.659s)
[531.606657] [121/132] (493s left) gem_mmap_gtt (basic-wc)
[531.672418] Subtest basic-wc: SKIP (0.000s)
[531.744406] [122/132] (493s left) core_auth (getclient-simple)
[531.782736] Starting subtest: getclient-simple
[531.810168] Subtest getclient-simple: SUCCESS (0.030s)
[531.965176] [123/132] (493s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[532.068307] Starting subtest: addfb25-y-tiled-small-legacy
[532.072770] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[532.214506] [124/132] (492s left) perf (invalid-oa-exponent)
[532.312799] Starting subtest: invalid-oa-exponent
[532.323198] Subtest invalid-oa-exponent: SUCCESS (0.010s)
[532.368051] [125/132] (492s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[532.467488] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[532.469369] Starting dynamic subtest: pipe-A-default-mode
[533.622961] Dynamic subtest pipe-A-default-mode: SKIP (1.154s)
[533.627408] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.155s)
[533.818540] [126/132] (491s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[533.932451] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[533.934698] Starting dynamic subtest: A-eDP1
[537.510586] Dynamic subtest A-eDP1: SUCCESS (3.576s)
[537.520781] Starting dynamic subtest: B-eDP1
[540.682057] Dynamic subtest B-eDP1: SUCCESS (3.173s)
[540.692318] Starting dynamic subtest: C-eDP1
[543.795693] Dynamic subtest C-eDP1: SUCCESS (3.110s)
[543.806825] Starting dynamic subtest: D-eDP1
[546.916648] Dynamic subtest D-eDP1: SUCCESS (3.122s)
[546.918872] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (12.988s)
[547.736285] [127/132] (477s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[547.794301] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[547.796571] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[547.915687] [128/132] (477s left) kms_vrr (max-min)
[548.018964] Starting subtest: max-min
[548.025243] Subtest max-min: SKIP (0.000s)
[548.119584] [129/132] (477s left) kms_rotation_crc (sprite-rotation-90)
[548.212841] Starting subtest: sprite-rotation-90
[548.216969] Subtest sprite-rotation-90: SKIP (0.001s)
[548.314712] [130/132] (476s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[548.417697] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[548.421748] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[548.561282] [131/132] (476s left) kms_cursor_crc (cursor-offscreen-64x64)
[548.738372] Starting subtest: cursor-offscreen-64x64
[548.742824] Starting dynamic subtest: pipe-A-eDP-1
[550.667067] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.922s)
[550.694339] Starting dynamic subtest: pipe-D-eDP-1
[553.312053] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.623s)
[553.314192] Subtest cursor-offscreen-64x64: SUCCESS (4.571s)
[554.199564] [132/132] (471s left) kms_color_pipeline (plane-lut1d-lut1d)
[554.311041] Starting subtest: plane-lut1d-lut1d
[554.323919] Starting dynamic subtest: pipe-A-eDP-1
[555.044305] Dynamic subtest pipe-A-eDP-1: FAIL (0.315s)
[555.683194] Starting dynamic subtest: pipe-B-eDP-1
[556.485968] Dynamic subtest pipe-B-eDP-1: FAIL (0.290s)
[557.020707] Starting dynamic subtest: pipe-C-eDP-1
[557.851185] Dynamic subtest pipe-C-eDP-1: FAIL (0.254s)
[558.356492] Starting dynamic subtest: pipe-D-eDP-1
[559.214831] Dynamic subtest pipe-D-eDP-1: FAIL (0.268s)
[559.216903] Subtest plane-lut1d-lut1d: FAIL (4.599s)
[559.757100] 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.