[57.725477] Initializing watchdogs
[57.725573]   /dev/watchdog0
[57.740281] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[57.744548] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.746780] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.750135] [001/132] (960s left) gem_exec_store (cachelines)
[57.836757] Starting subtest: cachelines
[57.839176] Starting dynamic subtest: rcs0
[57.922689] Dynamic subtest rcs0: SUCCESS (0.085s)
[57.931467] Starting dynamic subtest: bcs0
[57.982139] Dynamic subtest bcs0: SUCCESS (0.059s)
[57.991270] Starting dynamic subtest: vcs0
[58.043400] Dynamic subtest vcs0: SUCCESS (0.061s)
[58.050766] Starting dynamic subtest: vcs1
[58.100064] Dynamic subtest vcs1: SUCCESS (0.056s)
[58.106486] Starting dynamic subtest: vecs0
[58.164404] Dynamic subtest vecs0: SUCCESS (0.063s)
[58.171151] Starting dynamic subtest: ccs0
[58.232515] Dynamic subtest ccs0: SUCCESS (0.068s)
[58.234943] Subtest cachelines: SUCCESS (0.396s)
[58.433816] [002/132] (959s left) gem_pread (bench)
[58.494492] Subtest bench: SKIP (0.000s)
[58.561441] [003/132] (959s left) syncobj_wait (invalid-signal-illegal-handle)
[58.626032] Starting subtest: invalid-signal-illegal-handle
[58.628285] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[58.755665] [004/132] (959s left) kms_vblank (query-forked-busy-hang)
[58.853112] Starting subtest: query-forked-busy-hang
[58.855384] Starting dynamic subtest: pipe-A-eDP-1
[85.652611] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.787s)
[85.665788] Starting dynamic subtest: pipe-D-eDP-1
[110.687569] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.031s)
[110.689633] Subtest query-forked-busy-hang: SUCCESS (51.821s)
[111.503350] [005/132] (906s left) kms_psr (fbc-pr-primary-mmap-cpu)
[111.599066] Starting subtest: fbc-pr-primary-mmap-cpu
[111.603275] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[111.694108] [006/132] (906s left) kms_plane_cursor (overlay)
[111.799353] Starting subtest: overlay
[111.804112] Starting dynamic subtest: pipe-A-eDP-1-size-64
[114.292364] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.494s)
[114.350649] Starting dynamic subtest: pipe-A-eDP-1-size-128
[116.793080] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.449s)
[116.851339] Starting dynamic subtest: pipe-A-eDP-1-size-256
[119.327493] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.482s)
[119.692513] Starting dynamic subtest: pipe-B-eDP-1-size-64
[122.827568] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.143s)
[122.885627] Starting dynamic subtest: pipe-B-eDP-1-size-128
[125.273595] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.393s)
[125.332158] Starting dynamic subtest: pipe-B-eDP-1-size-256
[127.752478] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.426s)
[128.100878] Starting dynamic subtest: pipe-C-eDP-1-size-64
[130.929681] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.837s)
[130.965492] Starting dynamic subtest: pipe-C-eDP-1-size-128
[133.008198] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.047s)
[133.043395] Starting dynamic subtest: pipe-C-eDP-1-size-256
[135.108049] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.069s)
[135.432722] Starting dynamic subtest: pipe-D-eDP-1-size-64
[138.211426] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.786s)
[138.246881] Starting dynamic subtest: pipe-D-eDP-1-size-128
[140.299356] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.058s)
[140.336118] Starting dynamic subtest: pipe-D-eDP-1-size-256
[142.432983] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.102s)
[142.753313] Subtest overlay: SUCCESS (30.955s)
[143.636565] [007/132] (874s left) perf (i915-ref-count)
[143.725013] Starting subtest: i915-ref-count
[143.785265] Subtest i915-ref-count: SUCCESS (0.064s)
[143.834524] [008/132] (874s left) gem_exec_schedule (smoketest-all)
[143.906375] Starting subtest: smoketest-all
[176.282990] Subtest smoketest-all: SUCCESS (32.328s)
[178.037445] [009/132] (840s left) gem_lmem_swapping (heavy-random)
[178.095579] Subtest heavy-random: SKIP (0.000s)
[178.136188] [010/132] (840s left) kms_psr (psr-primary-mmap-gtt)
[178.240895] Starting subtest: psr-primary-mmap-gtt
[178.243198] Starting dynamic subtest: eDP-1
[179.718219] Dynamic subtest eDP-1: SKIP (1.475s)
[179.722678] Subtest psr-primary-mmap-gtt: SKIP (1.477s)
[179.920437] [011/132] (838s left) perf (whitelisted-registers-userspace-config)
[180.015053] Starting subtest: whitelisted-registers-userspace-config
[180.017488] Subtest whitelisted-registers-userspace-config: SUCCESS (0.001s)
[180.058080] [012/132] (838s left) gem_ppgtt (blt-vs-render-ctx0)
[180.123729] Starting subtest: blt-vs-render-ctx0
[213.161543] Subtest blt-vs-render-ctx0: SUCCESS (33.034s)
[213.338948] [013/132] (805s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[213.481554] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[213.485852] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[213.613672] [014/132] (805s left) gem_mmap_gtt (basic)
[213.679914] Subtest basic: SKIP (0.000s)
[213.750424] [015/132] (805s left) syncobj_timeline (invalid-single-wait-all-unsubmitted)
[213.820065] Starting subtest: invalid-single-wait-all-unsubmitted
[213.822499] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[213.960317] [016/132] (804s left) gem_ctx_engines (execute-one)
[214.034796] Starting subtest: execute-one
[216.136384] Subtest execute-one: SUCCESS (2.104s)
[216.340969] [017/132] (802s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[216.450626] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[216.454772] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[216.592231] [018/132] (802s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[216.684996] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[216.687266] Starting dynamic subtest: pipe-A-eDP-1
[216.707791] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[216.717334] Starting dynamic subtest: pipe-B-eDP-1
[216.735672] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[216.742490] Starting dynamic subtest: pipe-C-eDP-1
[216.761714] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[216.768518] Starting dynamic subtest: pipe-D-eDP-1
[216.787687] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[216.789579] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.006s)
[218.938454] [019/132] (799s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[218.996861] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[219.063195] [020/132] (799s left) api_intel_allocator (two-level-inception-interruptible)
[219.112384] Starting subtest: two-level-inception-interruptible
[224.515880] Subtest two-level-inception-interruptible: SUCCESS (5.405s)
[224.669950] [021/132] (794s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[229.701432] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[229.703860] Starting dynamic subtest: pipe-A-eDP-1
[236.263482] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.561s)
[236.273414] Starting dynamic subtest: pipe-B-eDP-1
[242.900822] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.632s)
[242.903132] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.197s)
[243.797012] [022/132] (775s left) i915_pciid
[243.986619] [023/132] (775s left) i915_pm_rpm (sysfs-read)
[246.049855] Starting subtest: sysfs-read
[247.223199] Subtest sysfs-read: SUCCESS (1.165s)
[247.602063] [024/132] (771s left) gem_exec_reloc (basic-cpu-noreloc)
[247.665749] Subtest basic-cpu-noreloc: SKIP (0.000s)
[247.742957] [025/132] (771s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[247.847824] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[247.851800] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[247.972393] [026/132] (771s left) sw_sync (timeline_closed_signaled)
[248.009973] Starting subtest: timeline_closed_signaled
[248.012031] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[248.087535] [027/132] (771s left) gem_eio (context-create)
[248.322710] Starting subtest: context-create
[248.650118] Subtest context-create: SUCCESS (0.327s)
[248.823791] [028/132] (770s left) kms_fb_coherency (memset-crc)
[248.923582] Starting subtest: memset-crc
[248.925372] Starting dynamic subtest: mmap-offset-wc
[249.307848] Dynamic subtest mmap-offset-wc: SUCCESS (0.385s)
[249.606808] Starting dynamic subtest: mmap-offset-uc
[255.656255] Dynamic subtest mmap-offset-uc: SUCCESS (6.056s)
[255.940117] Subtest memset-crc: SUCCESS (7.016s)
[256.754845] [029/132] (762s left) gem_ctx_persistence (engines-hostile-preempt)
[256.813575] Starting subtest: engines-hostile-preempt
[256.815777] Starting dynamic subtest: rcs0
[256.908719] Dynamic subtest rcs0: SUCCESS (0.095s)
[256.919277] Starting dynamic subtest: bcs0
[256.989262] Dynamic subtest bcs0: SUCCESS (0.078s)
[256.999706] Starting dynamic subtest: vcs0
[258.911082] Dynamic subtest vcs0: SUCCESS (1.918s)
[258.922420] Starting dynamic subtest: vcs1
[260.867783] Dynamic subtest vcs1: SUCCESS (1.954s)
[260.878240] Starting dynamic subtest: vecs0
[262.916665] Dynamic subtest vecs0: SUCCESS (2.046s)
[262.927766] Starting dynamic subtest: ccs0
[263.024230] Dynamic subtest ccs0: SUCCESS (0.106s)
[263.026841] Subtest engines-hostile-preempt: SUCCESS (6.211s)
[263.122174] [030/132] (756s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[263.223440] Starting subtest: psr-rgb565-draw-pwrite
[270.163558] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.943s)
[270.310217] [031/132] (749s left) gem_render_copy (y-tiled-to-vebox-linear)
[270.365971] Starting subtest: y-tiled-to-vebox-linear
[270.370166] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[270.576817] [032/132] (748s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[270.619504] Starting subtest: multi-wait-for-submit-submitted-signaled
[270.621699] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[270.745289] [033/132] (748s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[270.855805] Starting subtest: x-tiled-64bpp-rotate-90
[270.875164] Subtest x-tiled-64bpp-rotate-90: SKIP (0.019s)
[271.031375] [034/132] (748s left) kms_psr (fbc-psr2-cursor-render)
[271.121764] Starting subtest: fbc-psr2-cursor-render
[271.124067] Starting dynamic subtest: eDP-1
[271.128264] Dynamic subtest eDP-1: SKIP (0.000s)
[271.132498] Subtest fbc-psr2-cursor-render: SKIP (0.001s)
[271.221529] [035/132] (748s left) kms_getfb (getfb-handle-protection)
[271.319186] Starting subtest: getfb-handle-protection
[271.339664] Subtest getfb-handle-protection: SUCCESS (0.022s)
[271.484113] [036/132] (748s left) sw_sync (sync_merge_invalid)
[271.524345] Starting subtest: sync_merge_invalid
[271.547049] Subtest sync_merge_invalid: SUCCESS (0.023s)
[271.626985] [037/132] (747s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[271.728159] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[276.327285] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (4.602s)
[277.478413] [038/132] (742s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render)
[277.581830] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render
[284.843283] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (7.264s)
[285.005263] [039/132] (734s left) kms_cursor_legacy (cursorb-vs-flipa-toggle)
[285.127416] Starting subtest: cursorB-vs-flipA-toggle
[285.131996] Subtest cursorB-vs-flipA-toggle: SKIP (0.001s)
[285.290181] [040/132] (734s left) kms_rmfb (rmfb-ioctl)
[285.390088] Starting subtest: rmfb-ioctl
[285.392345] Starting dynamic subtest: pipe-A-eDP-1
[285.501217] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.110s)
[285.519088] Starting dynamic subtest: pipe-B-eDP-1
[286.725874] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.220s)
[286.738545] Starting dynamic subtest: pipe-C-eDP-1
[287.942663] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.213s)
[287.955662] Starting dynamic subtest: pipe-D-eDP-1
[289.076890] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.132s)
[289.079301] Subtest rmfb-ioctl: SUCCESS (3.689s)
[290.230228] [041/132] (729s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[290.611744] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[291.416970] [042/132] (728s left) sysfs_timeslice_duration (invalid)
[291.473480] Starting subtest: invalid
[291.475820] Starting dynamic subtest: vecs0
[291.478060] Dynamic subtest vecs0: SUCCESS (0.000s)
[291.487307] Starting dynamic subtest: ccs0
[291.489429] Dynamic subtest ccs0: SUCCESS (0.000s)
[291.497061] Starting dynamic subtest: vcs0
[291.499188] Dynamic subtest vcs0: SUCCESS (0.000s)
[291.506376] Starting dynamic subtest: bcs0
[291.508534] Dynamic subtest bcs0: SUCCESS (0.000s)
[291.515698] Starting dynamic subtest: rcs0
[291.517813] Dynamic subtest rcs0: SUCCESS (0.000s)
[291.525148] Starting dynamic subtest: vcs1
[291.527284] Dynamic subtest vcs1: SUCCESS (0.000s)
[291.529446] Subtest invalid: SUCCESS (0.005s)
[291.629817] [043/132] (728s left) syncobj_eventfd (timeline-wait)
[291.693708] Starting subtest: timeline-wait
[291.702143] Subtest timeline-wait: SUCCESS (0.011s)
[291.834943] [044/132] (728s left) kms_pm_backlight (brightness-with-dpms)
[291.935993] Starting subtest: brightness-with-dpms
[291.987140] Starting dynamic subtest: eDP-1
[294.704686] Dynamic subtest eDP-1: SUCCESS (2.717s)
[294.707256] Subtest brightness-with-dpms: SUCCESS (2.771s)
[295.604571] [045/132] (724s left) kms_busy (extended-modeset-hang-oldfb)
[295.706201] Starting subtest: extended-modeset-hang-oldfb
[295.708402] Starting dynamic subtest: pipe-A
[307.673432] Dynamic subtest pipe-A: SUCCESS (11.966s)
[307.683423] Starting dynamic subtest: pipe-D
[319.931088] Dynamic subtest pipe-D: SUCCESS (12.252s)
[319.933388] Subtest extended-modeset-hang-oldfb: SUCCESS (24.223s)
[320.746429] [046/132] (699s left) syncobj_wait (multi-wait-all-signaled)
[320.815479] Starting subtest: multi-wait-all-signaled
[320.817830] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[320.951859] [047/132] (699s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-rc-ccs)
[321.056076] Starting subtest: ccs-on-another-bo-y-tiled-gen12-rc-ccs
[321.058343] Starting dynamic subtest: pipe-A-eDP-1
[321.081151] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[321.089903] Starting dynamic subtest: pipe-B-eDP-1
[321.108086] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[321.115297] Starting dynamic subtest: pipe-C-eDP-1
[321.137553] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[321.144802] Starting dynamic subtest: pipe-D-eDP-1
[321.166594] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[321.170951] Subtest ccs-on-another-bo-y-tiled-gen12-rc-ccs: SKIP (0.004s)
[323.231606] [048/132] (696s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[323.329677] Starting subtest: yf-tiled-8bpp-rotate-180
[323.334247] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[323.450609] [049/132] (696s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu)
[323.545753] Starting subtest: psr-rgb565-draw-mmap-cpu
[330.217862] Subtest psr-rgb565-draw-mmap-cpu: SUCCESS (6.673s)
[330.358134] [050/132] (689s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[330.471838] Starting subtest: yf-tiled-16bpp-rotate-90
[330.476302] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[330.595694] [051/132] (689s left) kms_addfb_basic (clobberred-modifier)
[330.650327] Starting subtest: clobberred-modifier
[330.654566] Subtest clobberred-modifier: SKIP (0.000s)
[330.822107] [052/132] (689s left) gem_exec_reloc (basic-write-read-active)
[330.882517] Subtest basic-write-read-active: SKIP (0.000s)
[330.964705] [053/132] (689s left) dmabuf (all-tests)
[330.999777] Starting subtest: all-tests
[331.002218] Starting dynamic subtest: sanitycheck
[331.180139] Dynamic subtest sanitycheck: SUCCESS (0.179s)
[331.191270] Starting dynamic subtest: dma_fence
[331.458910] Dynamic subtest dma_fence: SUCCESS (0.275s)
[331.469307] Starting dynamic subtest: dma_fence_chain
[339.528715] Dynamic subtest dma_fence_chain: SUCCESS (8.067s)
[339.539879] Starting dynamic subtest: dma_fence_unwrap
[339.670598] Dynamic subtest dma_fence_unwrap: SUCCESS (0.139s)
[339.681569] Starting dynamic subtest: dma_resv
[339.828738] Dynamic subtest dma_resv: SUCCESS (0.156s)
[339.831655] Subtest all-tests: SUCCESS (8.828s)
[339.915481] [054/132] (680s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[340.216378] Starting subtest: cursor-rapid-movement-512x170
[340.220133] Subtest cursor-rapid-movement-512x170: SKIP (0.001s)
[340.398974] [055/132] (679s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[340.454759] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[340.457096] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[340.572533] [056/132] (679s left) kms_big_fb (linear-16bpp-rotate-90)
[340.680576] Starting subtest: linear-16bpp-rotate-90
[340.705171] Subtest linear-16bpp-rotate-90: SKIP (0.023s)
[340.838749] [057/132] (679s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[340.936305] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[340.939693] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[341.056597] [058/132] (679s left) gem_exec_schedule (noreorder-priority)
[341.236670] Starting subtest: noreorder-priority
[341.239574] Starting dynamic subtest: rcs0
[342.392833] Dynamic subtest rcs0: SUCCESS (1.157s)
[342.403640] Starting dynamic subtest: vcs0
[343.869583] Dynamic subtest vcs0: SUCCESS (1.473s)
[343.883940] Starting dynamic subtest: vcs1
[345.960616] Dynamic subtest vcs1: SUCCESS (2.088s)
[345.970500] Starting dynamic subtest: vecs0
[347.939591] Dynamic subtest vecs0: SUCCESS (1.976s)
[347.954254] Starting dynamic subtest: ccs0
[349.110669] Dynamic subtest ccs0: SUCCESS (1.170s)
[349.113059] Subtest noreorder-priority: SUCCESS (7.877s)
[349.322347] [059/132] (671s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc)
[349.448319] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc
[356.007940] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.559s)
[356.177812] [060/132] (664s left) perf (polling-small-buf)
[356.303914] Starting subtest: polling-small-buf
[356.395099] Subtest polling-small-buf: SUCCESS (0.088s)
[356.438636] [061/132] (664s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[356.537217] Starting subtest: bad-pixel-format-y-tiled-ccs
[356.539471] Starting dynamic subtest: pipe-A-eDP-1
[356.561537] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[356.572045] Starting dynamic subtest: pipe-B-eDP-1
[356.592747] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[356.599837] Starting dynamic subtest: pipe-C-eDP-1
[356.620203] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[356.627589] Starting dynamic subtest: pipe-D-eDP-1
[356.649560] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[356.653897] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s)
[358.747841] [062/132] (661s left) i915_drm_fdinfo (busy-hang)
[358.798432] Starting subtest: busy-hang
[358.800767] Starting dynamic subtest: rcs0
[358.837111] Dynamic subtest rcs0: SKIP (0.036s)
[358.846710] Starting dynamic subtest: bcs0
[358.851547] Dynamic subtest bcs0: SKIP (0.001s)
[358.859441] Starting dynamic subtest: vcs0
[358.863898] Dynamic subtest vcs0: SKIP (0.001s)
[358.872091] Starting dynamic subtest: vcs1
[358.876250] Dynamic subtest vcs1: SKIP (0.001s)
[358.883808] Starting dynamic subtest: vecs0
[358.888263] Dynamic subtest vecs0: SKIP (0.001s)
[358.895830] Starting dynamic subtest: ccs0
[358.900260] Dynamic subtest ccs0: SKIP (0.001s)
[358.904773] Subtest busy-hang: SKIP (0.054s)
[358.950481] [063/132] (661s left) kms_cursor_crc (cursor-sliding-max-size)
[359.260408] Starting subtest: cursor-sliding-max-size
[359.267050] Subtest cursor-sliding-max-size: SKIP (0.000s)
[359.419085] [064/132] (661s left) gem_exec_schedule (preempt-queue-contexts-chain)
[359.499674] Subtest preempt-queue-contexts-chain: SKIP (0.000s)
[359.812956] [065/132] (660s left) gem_mmap_gtt (big-copy)
[359.863359] Subtest big-copy: SKIP (0.000s)
[359.917458] [066/132] (660s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-factor-0-25)
[360.012053] Starting subtest: planes-downscale-factor-0-75-upscale-factor-0-25
[360.014193] Starting dynamic subtest: pipe-A
[360.074266] Dynamic subtest pipe-A: SUCCESS (0.065s)
[360.083565] Starting dynamic subtest: pipe-B
[361.253980] Dynamic subtest pipe-B: SUCCESS (1.178s)
[361.267321] Starting dynamic subtest: pipe-C
[362.376179] Dynamic subtest pipe-C: SUCCESS (1.120s)
[362.384692] Starting dynamic subtest: pipe-D
[363.505490] Dynamic subtest pipe-D: SUCCESS (1.128s)
[363.507814] Subtest planes-downscale-factor-0-75-upscale-factor-0-25: SUCCESS (3.497s)
[364.693098] [067/132] (656s left) kms_chamelium_color (ctm-0-50)
[364.782151] Subtest ctm-0-50: SKIP (0.000s)
[364.820890] [068/132] (656s left) kms_psr (pr-primary-page-flip)
[364.923633] Starting subtest: pr-primary-page-flip
[364.927990] Subtest pr-primary-page-flip: SKIP (0.000s)
[365.018933] [069/132] (655s left) kms_atomic (test-only)
[365.106085] Starting subtest: test-only
[365.154145] Starting dynamic subtest: pipe-A-eDP-1
[367.646174] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.494s)
[367.648489] Subtest test-only: SUCCESS (2.542s)
[368.463899] [070/132] (652s left) gem_spin_batch (spin-each)
[368.534701] Starting subtest: spin-each
[371.888685] Subtest spin-each: SUCCESS (3.353s)
[372.168931] [071/132] (648s left) gem_exec_params (batch-first)
[372.242223] Starting subtest: batch-first
[372.254119] Subtest batch-first: SUCCESS (0.010s)
[372.433132] [072/132] (648s left) gem_busy (semaphore)
[372.637062] Subtest semaphore: SKIP (0.000s)
[372.907364] [073/132] (648s left) kms_psr (pr-primary-blt)
[373.005706] Starting subtest: pr-primary-blt
[373.009787] Subtest pr-primary-blt: SKIP (0.000s)
[373.100964] [074/132] (647s left) kms_dp_link_training (uhbr-mst)
[373.200132] Starting subtest: uhbr-mst
[373.208291] Subtest uhbr-mst: SKIP (0.000s)
[373.303122] [075/132] (647s left) i915_suspend (debugfs-reader)
[373.354462] Starting subtest: debugfs-reader
[391.276878] Subtest debugfs-reader: SUCCESS (6.746s)
[391.340695] [076/132] (629s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[391.438385] Starting subtest: compare-crc-sanitycheck-nv12
[391.440713] Starting dynamic subtest: pipe-A-eDP-1
[392.067148] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.625s)
[392.077292] Starting dynamic subtest: pipe-B-eDP-1
[393.317431] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.237s)
[393.326149] Starting dynamic subtest: pipe-C-eDP-1
[394.507332] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.184s)
[394.516599] Starting dynamic subtest: pipe-D-eDP-1
[395.744928] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.227s)
[395.747142] Subtest compare-crc-sanitycheck-nv12: SUCCESS (4.279s)
[396.628445] [077/132] (624s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[396.743397] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[396.747671] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[396.886280] [078/132] (624s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs)
[396.990297] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs
[396.994860] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s)
[398.168010] [079/132] (623s left) perf_pmu (busy-idle-check-all)
[398.206711] Starting subtest: busy-idle-check-all
[398.209049] Starting dynamic subtest: rcs0
[398.883320] Dynamic subtest rcs0: SUCCESS (0.678s)
[398.898373] Starting dynamic subtest: bcs0
[399.538962] Dynamic subtest bcs0: SUCCESS (0.652s)
[399.551222] Starting dynamic subtest: vcs0
[400.254528] Dynamic subtest vcs0: FAIL (0.644s)
[400.264855] Starting dynamic subtest: vcs1
[400.892597] Dynamic subtest vcs1: FAIL (0.620s)
[400.903810] Starting dynamic subtest: vecs0
[401.516434] Dynamic subtest vecs0: FAIL (0.622s)
[401.526838] Starting dynamic subtest: ccs0
[402.143338] Dynamic subtest ccs0: FAIL (0.622s)
[402.145388] Subtest busy-idle-check-all: FAIL (3.846s)
[403.103323] [080/132] (618s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[403.210572] Starting subtest: psr-2p-pri-indfb-multidraw
[403.214744] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[403.352613] [081/132] (618s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[403.458412] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[403.462882] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[403.594844] [082/132] (617s left) gem_exec_balancer (fairslice)
[403.654367] Starting subtest: fairslice
[406.514343] Subtest fairslice: SUCCESS (2.853s)
[407.151736] [083/132] (614s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[407.211667] Starting subtest: etime-single-wait-all-available-unsubmitted
[407.318945] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.107s)
[407.420684] [084/132] (614s left) gem_exec_fence (syncobj-timeline-chain-engines)
[408.013195] Starting subtest: syncobj-timeline-chain-engines
[408.178099] Subtest syncobj-timeline-chain-engines: SUCCESS (0.165s)
[409.166401] [FACT gem_exec_fence (syncobj-timeline-chain-engines)] new: kernel.kmod_is_loaded.vgem: true
[409.169850] [085/132] (612s left) gem_pread (display)
[409.229811] Subtest display: SKIP (0.000s)
[409.291562] [086/132] (612s left) gem_sync (basic-all)
[409.369939] Starting subtest: basic-all
[412.635303] Subtest basic-all: SUCCESS (3.272s)
[412.848734] [087/132] (608s left) gem_mmap_offset (isolation)
[412.906750] Starting subtest: isolation
[412.924979] Subtest isolation: SUCCESS (0.007s)
[413.049354] [088/132] (608s left) kms_atomic_interruptible (legacy-dpms)
[413.140918] Starting subtest: legacy-dpms
[413.143043] Starting dynamic subtest: pipe-A-eDP-1
[420.607858] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.461s)
[420.610108] Subtest legacy-dpms: SUCCESS (7.463s)
[421.466858] [089/132] (600s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[421.579383] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[421.583648] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[421.716765] [090/132] (600s left) gem_flink_basic (bad-open)
[421.766669] Starting subtest: bad-open
[421.768575] Subtest bad-open: SUCCESS (0.000s)
[421.885553] [091/132] (599s left) kms_invalid_mode (int-max-clock)
[421.981705] Starting subtest: int-max-clock
[421.984223] Starting dynamic subtest: pipe-A-eDP-1
[422.267210] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[422.276975] Starting dynamic subtest: pipe-B-eDP-1
[422.279037] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[422.287348] Starting dynamic subtest: pipe-C-eDP-1
[422.289781] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[422.298393] Starting dynamic subtest: pipe-D-eDP-1
[422.300313] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[422.302242] Subtest int-max-clock: SUCCESS (0.292s)
[423.088870] [092/132] (598s left) gem_exec_params (mmapped)
[423.157556] Starting subtest: mmapped
[423.162317] Subtest mmapped: SUCCESS (0.003s)
[423.320635] [093/132] (598s left) kms_plane_multiple (tiling-x)
[423.409273] Starting subtest: tiling-x
[423.411560] Starting dynamic subtest: pipe-A-eDP-1
[427.097375] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.687s)
[427.107072] Starting dynamic subtest: pipe-B-eDP-1
[431.515405] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.414s)
[431.532597] Starting dynamic subtest: pipe-C-eDP-1
[435.847380] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.328s)
[435.857872] Starting dynamic subtest: pipe-D-eDP-1
[440.189097] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.337s)
[440.191467] Subtest tiling-x: SUCCESS (16.771s)
[441.013747] [094/132] (580s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite)
[441.116998] Starting subtest: fbc-rgb101010-draw-pwrite
[446.511240] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (5.398s)
[447.720343] [095/132] (574s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-blt)
[447.824915] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-blt
[454.592246] Subtest psr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.764s)
[454.751025] [096/132] (567s left) kms_flip (2x-flip-vs-dpms)
[454.849492] Starting subtest: 2x-flip-vs-dpms
[454.858280] Subtest 2x-flip-vs-dpms: SKIP (0.011s)
[455.005732] [097/132] (567s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[455.105343] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[455.116311] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.010s)
[455.253264] [098/132] (566s left) kms_content_protection (atomic-hdcp14)
[455.401071] Starting subtest: atomic-hdcp14
[455.405655] Subtest atomic-hdcp14: SKIP (0.000s)
[455.560595] [099/132] (566s left) sw_sync (sync_merge)
[455.595916] Starting subtest: sync_merge
[455.598513] Subtest sync_merge: SUCCESS (0.000s)
[455.682954] [100/132] (566s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[455.787643] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[455.789431] Starting dynamic subtest: pipe-A-default-mode
[456.080469] Dynamic subtest pipe-A-default-mode: SKIP (0.288s)
[456.085226] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.289s)
[456.950477] [101/132] (565s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions)
[457.080305] Starting subtest: cursor-vs-flip-atomic-transitions
[486.951150] Subtest cursor-vs-flip-atomic-transitions: SUCCESS (29.857s)
[487.785489] [102/132] (534s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[487.881665] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[487.883967] Starting dynamic subtest: pipe-A-eDP-1
[487.941621] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.061s)
[488.237111] Starting dynamic subtest: pipe-B-eDP-1
[489.058987] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.832s)
[489.344360] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.466s)
[490.240962] [103/132] (532s left) kms_prime (basic-crc-vgem)
[490.322114] Starting subtest: basic-crc-vgem
[490.325234] Starting dynamic subtest: second-to-first
[491.278639] Dynamic subtest second-to-first: SUCCESS (0.962s)
[491.280571] Subtest basic-crc-vgem: SUCCESS (0.962s)
[491.394079] [104/132] (530s left) kms_properties (invalid-properties-legacy)
[491.539454] Starting subtest: invalid-properties-legacy
[491.639238] Subtest invalid-properties-legacy: SUCCESS (0.101s)
[491.735532] [105/132] (530s left) syncobj_timeline (wait-zero-handles)
[491.791577] Starting subtest: wait-zero-handles
[491.793759] Subtest wait-zero-handles: SUCCESS (0.000s)
[491.926425] [106/132] (530s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area)
[496.889561] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area
[496.894385] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.002s)
[497.766102] [107/132] (524s left) gem_pxp (reject-modify-context-protection-off-1)
[497.894212] Starting subtest: reject-modify-context-protection-off-1
[497.896821] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[498.046232] [108/132] (524s left) kms_selftest (drm_format_helper)
[498.087752] Starting subtest: drm_format_helper
[498.101277] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8
[498.262537] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_gray8: SUCCESS (0.162s)
[498.279957] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332
[498.408055] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb332: SUCCESS (0.143s)
[498.416745] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565
[498.493658] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb565: SUCCESS (0.086s)
[498.503802] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555
[498.619218] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb1555: SUCCESS (0.124s)
[498.627727] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555
[498.751384] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb1555: SUCCESS (0.131s)
[498.761206] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551
[498.901622] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgba5551: SUCCESS (0.150s)
[498.910010] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888
[499.046334] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_rgb888: SUCCESS (0.144s)
[499.055338] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888
[499.195503] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_bgr888: SUCCESS (0.149s)
[499.204371] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888
[499.302584] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb8888: SUCCESS (0.105s)
[499.311453] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010
[499.425884] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xrgb2101010: SUCCESS (0.122s)
[499.434302] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010
[499.571276] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_argb2101010: SUCCESS (0.143s)
[499.580300] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_mono
[499.686566] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_mono: SUCCESS (0.116s)
[499.695374] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_swab
[499.830990] Dynamic subtest drm_format_helper_test-drm_test_fb_swab: SUCCESS (0.143s)
[499.841242] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888
[499.965549] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_xbgr8888: SUCCESS (0.135s)
[499.974291] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888
[500.122313] Dynamic subtest drm_format_helper_test-drm_test_fb_xrgb8888_to_abgr8888: SUCCESS (0.156s)
[500.131409] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_clip_offset
[500.262172] Dynamic subtest drm_format_helper_test-drm_test_fb_clip_offset: SUCCESS (0.139s)
[500.272291] Starting dynamic subtest: drm_format_helper_test-drm_test_fb_memcpy
[500.383486] Dynamic subtest drm_format_helper_test-drm_test_fb_memcpy: SUCCESS (0.119s)
[500.385489] Subtest drm_format_helper: SUCCESS (2.296s)
[500.621361] [109/132] (521s left) gen3_mixed_blits
[500.763518] [110/132] (521s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[500.856437] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[500.860383] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[500.980731] [111/132] (521s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[501.088744] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[507.847066] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.765s)
[507.991675] [112/132] (514s left) gen7_exec_parse (cmd-crossing-page)
[508.054297] Subtest cmd-crossing-page: SKIP (0.000s)
[508.130486] [113/132] (514s left) gem_softpin (overlap)
[508.180104] Starting subtest: overlap
[509.040754] Subtest overlap: SUCCESS (0.859s)
[509.198479] [114/132] (513s left) gem_eio (in-flight-internal-immediate)
[509.429449] Starting subtest: in-flight-internal-immediate
[511.183627] Subtest in-flight-internal-immediate: SUCCESS (1.755s)
[511.360081] [115/132] (511s left) gem_wait (write-wait)
[511.426494] Starting subtest: write-wait
[511.428810] Starting dynamic subtest: all-engines
[512.569857] Dynamic subtest all-engines: SUCCESS (1.143s)
[512.586384] Starting dynamic subtest: rcs0
[513.631200] Dynamic subtest rcs0: SUCCESS (1.059s)
[513.645956] Starting dynamic subtest: bcs0
[514.691444] Dynamic subtest bcs0: SUCCESS (1.058s)
[514.708071] Starting dynamic subtest: vcs0
[515.746214] Dynamic subtest vcs0: SUCCESS (1.053s)
[515.762619] Starting dynamic subtest: vcs1
[516.799361] Dynamic subtest vcs1: SUCCESS (1.051s)
[516.815679] Starting dynamic subtest: vecs0
[517.851306] Dynamic subtest vecs0: SUCCESS (1.050s)
[517.867958] Starting dynamic subtest: ccs0
[518.906372] Dynamic subtest ccs0: SUCCESS (1.053s)
[518.908877] Subtest write-wait: SUCCESS (7.483s)
[519.128583] [116/132] (503s left) kms_chamelium_edid (dp-edid-read)
[519.531441] Subtest dp-edid-read: SKIP (0.000s)
[520.369707] [117/132] (502s left) gem_exec_balancer (parallel-bb-first)
[520.624264] Starting subtest: parallel-bb-first
[521.498999] Subtest parallel-bb-first: SUCCESS (0.875s)
[522.172828] [118/132] (500s left) kms_pm_rpm (fences)
[524.295217] Starting subtest: fences
[524.300214] Subtest fences: SKIP (0.000s)
[524.692291] [119/132] (498s left) gem_ctx_persistence (legacy-engines-queued)
[524.756350] Starting subtest: legacy-engines-queued
[524.758744] Starting dynamic subtest: render
[524.859939] Dynamic subtest render: SUCCESS (0.103s)
[524.875055] Starting dynamic subtest: bsd1
[526.041587] Dynamic subtest bsd1: SUCCESS (1.177s)
[526.054149] Starting dynamic subtest: bsd2
[528.024990] Dynamic subtest bsd2: SUCCESS (1.980s)
[528.037616] Starting dynamic subtest: blt
[528.115192] Dynamic subtest blt: SUCCESS (0.088s)
[528.127108] Starting dynamic subtest: vebox
[530.074184] Dynamic subtest vebox: SUCCESS (1.955s)
[530.076952] Subtest legacy-engines-queued: SUCCESS (5.318s)
[530.175408] [120/132] (492s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[530.280776] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[530.937975] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.659s)
[531.145627] [121/132] (491s left) gem_mmap_gtt (basic-wc)
[531.204658] Subtest basic-wc: SKIP (0.000s)
[531.261355] [122/132] (491s left) core_auth (getclient-simple)
[531.295987] Starting subtest: getclient-simple
[531.319188] Subtest getclient-simple: SUCCESS (0.025s)
[531.449557] [123/132] (491s left) kms_addfb_basic (addfb25-y-tiled-small-legacy)
[531.542191] Starting subtest: addfb25-y-tiled-small-legacy
[531.546237] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s)
[531.669830] [124/132] (491s left) perf (invalid-oa-exponent)
[531.771585] Starting subtest: invalid-oa-exponent
[531.781663] Subtest invalid-oa-exponent: SUCCESS (0.010s)
[531.868841] [125/132] (491s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[531.949666] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[531.951551] Starting dynamic subtest: pipe-A-default-mode
[533.117425] Dynamic subtest pipe-A-default-mode: SKIP (1.166s)
[533.121180] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.167s)
[533.278860] [126/132] (490s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[533.369545] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[533.371866] Starting dynamic subtest: A-eDP1
[537.034653] Dynamic subtest A-eDP1: SUCCESS (3.664s)
[537.045232] Starting dynamic subtest: B-eDP1
[540.460672] Dynamic subtest B-eDP1: SUCCESS (3.424s)
[540.472351] Starting dynamic subtest: C-eDP1
[543.672657] Dynamic subtest C-eDP1: SUCCESS (3.213s)
[543.682830] Starting dynamic subtest: D-eDP1
[546.793187] Dynamic subtest D-eDP1: SUCCESS (3.122s)
[546.795556] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.430s)
[547.620657] [127/132] (475s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted-signaled)
[547.692635] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[547.695165] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[547.827094] [128/132] (475s left) kms_vrr (max-min)
[547.926205] Starting subtest: max-min
[547.932729] Subtest max-min: SKIP (0.000s)
[548.020537] [129/132] (475s left) kms_rotation_crc (sprite-rotation-90)
[548.119346] Starting subtest: sprite-rotation-90
[548.123386] Subtest sprite-rotation-90: SKIP (0.001s)
[548.212082] [130/132] (475s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[548.311687] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[548.316108] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[548.447978] [131/132] (475s left) kms_cursor_crc (cursor-offscreen-64x64)
[548.624103] Starting subtest: cursor-offscreen-64x64
[548.628605] Starting dynamic subtest: pipe-A-eDP-1
[550.561682] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.934s)
[550.602877] Starting dynamic subtest: pipe-D-eDP-1
[553.215955] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.621s)
[553.218196] Subtest cursor-offscreen-64x64: SUCCESS (4.591s)
[554.091477] [132/132] (469s left) kms_color_pipeline (plane-lut1d-lut1d)
[554.200267] Starting subtest: plane-lut1d-lut1d
[554.212269] Starting dynamic subtest: pipe-A-eDP-1
[554.905558] Dynamic subtest pipe-A-eDP-1: FAIL (0.293s)
[555.544229] Starting dynamic subtest: pipe-B-eDP-1
[556.374575] Dynamic subtest pipe-B-eDP-1: FAIL (0.317s)
[556.991492] Starting dynamic subtest: pipe-C-eDP-1
[557.830424] Dynamic subtest pipe-C-eDP-1: FAIL (0.274s)
[558.314837] Starting dynamic subtest: pipe-D-eDP-1
[559.138295] Dynamic subtest pipe-D-eDP-1: FAIL (0.235s)
[559.140463] Subtest plane-lut1d-lut1d: FAIL (4.621s)
[559.715854] 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.