[58.267481] Initializing watchdogs
[58.267530]   /dev/watchdog0
[58.280842] [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]
[58.285303] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.287509] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.290792] [001/131] (960s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[58.420102] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[58.482652] [002/131] (960s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[58.582085] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[58.584329] Starting dynamic subtest: pipe-A-default-mode
[58.894623] Dynamic subtest pipe-A-default-mode: SKIP (0.307s)
[58.899381] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.308s)
[59.655149] [003/131] (959s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[59.758249] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[59.762860] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[59.844750] [004/131] (959s left) kms_psr (psr-sprite-plane-move)
[59.941043] Starting subtest: psr-sprite-plane-move
[59.943686] Starting dynamic subtest: eDP-1
[61.700000] Dynamic subtest eDP-1: SUCCESS (1.750s)
[61.702521] Subtest psr-sprite-plane-move: SUCCESS (1.751s)
[62.516876] [005/131] (956s left) kms_pm_backlight (basic-brightness)
[62.620318] Starting subtest: basic-brightness
[62.710752] Starting dynamic subtest: eDP-1
[63.201907] Dynamic subtest eDP-1: SUCCESS (0.488s)
[63.204512] Subtest basic-brightness: SUCCESS (0.578s)
[64.043608] [006/131] (954s left) i915_pm_rpm (gem-pread)
[66.087501] Starting subtest: gem-pread
[68.131633] Subtest gem-pread: SUCCESS (2.038s)
[68.210386] [007/131] (950s left) gem_exec_reloc (basic-write-gtt-active)
[68.285065] Subtest basic-write-gtt-active: SKIP (0.000s)
[68.350597] [008/131] (950s left) kms_legacy_colorkey (invalid-plane)
[68.454982] Starting subtest: invalid-plane
[68.457535] Starting dynamic subtest: zero-id
[68.459854] Dynamic subtest zero-id: SUCCESS (0.000s)
[68.468738] Starting dynamic subtest: outrange-id
[68.471154] Dynamic subtest outrange-id: SUCCESS (0.000s)
[68.473453] Subtest invalid-plane: SUCCESS (0.000s)
[68.521983] [009/131] (950s left) perf (mi-rpc)
[68.634063] Starting subtest: mi-rpc
[68.638434] Subtest mi-rpc: SKIP (0.000s)
[68.666217] [010/131] (950s left) kms_big_fb (yf-tiled-addfb-size-overflow)
[68.780659] Starting subtest: yf-tiled-addfb-size-overflow
[68.784853] Subtest yf-tiled-addfb-size-overflow: SKIP (0.000s)
[68.862385] [011/131] (950s left) gem_lmem_swapping (parallel-random)
[68.906208] Subtest parallel-random: SKIP (0.000s)
[68.937347] [012/131] (950s left) kms_addfb_basic (size-max)
[69.039999] Starting subtest: size-max
[69.042292] Subtest size-max: SUCCESS (0.000s)
[69.117297] [013/131] (950s left) kms_atomic (plane-invalid-params-fence)
[69.213718] Starting subtest: plane-invalid-params-fence
[69.215780] Starting dynamic subtest: pipe-A-eDP-1
[69.579335] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.362s)
[69.581398] Subtest plane-invalid-params-fence: SUCCESS (0.363s)
[70.405711] [014/131] (948s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc)
[70.509798] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc
[70.512055] Starting dynamic subtest: pipe-A-eDP-1
[70.531902] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[70.540477] Starting dynamic subtest: pipe-B-eDP-1
[70.560321] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[70.566593] Starting dynamic subtest: pipe-C-eDP-1
[70.586661] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[70.592986] Starting dynamic subtest: pipe-D-eDP-1
[70.612934] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[70.615173] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.004s)
[72.664875] [015/131] (946s left) kms_vblank (wait-busy-hang)
[72.750649] Starting subtest: wait-busy-hang
[72.753121] Starting dynamic subtest: pipe-A-eDP-1
[99.683586] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.920s)
[99.696268] Starting dynamic subtest: pipe-D-eDP-1
[114.726112] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.037s)
[114.728054] Subtest wait-busy-hang: SUCCESS (41.961s)
[115.522190] [016/131] (903s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-blt)
[115.628309] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-blt
[115.632504] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[115.722985] [017/131] (903s left) drm_buddy (drm_buddy)
[115.764217] Starting subtest: drm_buddy
[115.774968] Starting dynamic subtest: drm_test_buddy_alloc_limit
[115.900459] Dynamic subtest drm_test_buddy_alloc_limit: SUCCESS (0.127s)
[115.906591] Starting dynamic subtest: drm_test_buddy_alloc_optimistic
[116.025327] Dynamic subtest drm_test_buddy_alloc_optimistic: SUCCESS (0.123s)
[116.031482] Starting dynamic subtest: drm_test_buddy_alloc_pessimistic
[116.141100] Dynamic subtest drm_test_buddy_alloc_pessimistic: SUCCESS (0.116s)
[116.147054] Starting dynamic subtest: drm_test_buddy_alloc_pathological
[116.236687] Dynamic subtest drm_test_buddy_alloc_pathological: SUCCESS (0.096s)
[116.244319] Starting dynamic subtest: drm_test_buddy_alloc_contiguous
[116.337750] Dynamic subtest drm_test_buddy_alloc_contiguous: SUCCESS (0.100s)
[116.343920] Starting dynamic subtest: drm_test_buddy_alloc_clear
[116.487528] Dynamic subtest drm_test_buddy_alloc_clear: SUCCESS (0.151s)
[116.493867] Starting dynamic subtest: drm_test_buddy_alloc_range_bias
[116.605330] Dynamic subtest drm_test_buddy_alloc_range_bias: SUCCESS (0.118s)
[116.607710] Subtest drm_buddy: SUCCESS (0.841s)
[116.797714] [018/131] (902s left) gem_bad_reloc (negative-reloc-bltcopy)
[116.862059] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[116.922378] [019/131] (902s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[117.032398] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[118.091271] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.059s)
[118.273275] [020/131] (901s left) kms_cursor_legacy (2x-long-flip-vs-cursor-atomic)
[118.397757] Starting subtest: 2x-long-flip-vs-cursor-atomic
[118.402316] Subtest 2x-long-flip-vs-cursor-atomic: SKIP (0.001s)
[118.496213] [021/131] (901s left) gem_render_copy (y-tiled-to-vebox-x-tiled)
[118.564786] Starting subtest: y-tiled-to-vebox-x-tiled
[118.569113] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s)
[118.739523] [022/131] (900s left) gem_readwrite (new-obj)
[118.801594] Subtest new-obj: SKIP (0.000s)
[118.872650] [023/131] (900s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-gtt)
[118.974680] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-gtt
[118.979206] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[119.085343] [024/131] (900s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[119.140109] Starting subtest: single-wait-all-for-submit-available-signaled
[119.142078] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[119.239651] [025/131] (900s left) gem_exec_schedule (preempt-user)
[119.321451] Starting subtest: preempt-user
[119.323695] Starting dynamic subtest: rcs0
[119.340274] Dynamic subtest rcs0: SUCCESS (0.024s)
[119.349645] Starting dynamic subtest: bcs0
[119.357768] Dynamic subtest bcs0: SUCCESS (0.018s)
[119.364467] Starting dynamic subtest: vcs0
[119.376337] Dynamic subtest vcs0: SUCCESS (0.018s)
[119.382857] Starting dynamic subtest: vcs1
[119.394332] Dynamic subtest vcs1: SUCCESS (0.018s)
[119.400909] Starting dynamic subtest: vecs0
[119.413750] Dynamic subtest vecs0: SUCCESS (0.019s)
[119.420445] Starting dynamic subtest: ccs0
[119.435790] Dynamic subtest ccs0: SUCCESS (0.022s)
[119.438067] Subtest preempt-user: SUCCESS (0.120s)
[121.009442] [026/131] (898s left) gem_exec_params (invalid-ring)
[121.074908] Starting subtest: invalid-ring
[121.077781] Subtest invalid-ring: SUCCESS (0.000s)
[121.171398] [027/131] (898s left) kms_big_fb (linear-64bpp-rotate-180)
[121.273663] Starting subtest: linear-64bpp-rotate-180
[123.542442] Subtest linear-64bpp-rotate-180: SUCCESS (2.269s)
[123.765457] [028/131] (895s left) gem_mmap_wc (bad-size)
[123.829726] Subtest bad-size: SKIP (0.000s)
[123.883359] [029/131] (895s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[123.983754] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[123.994333] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[124.062360] [030/131] (895s left) kms_flip (basic-flip-vs-dpms)
[124.161659] Starting subtest: basic-flip-vs-dpms
[124.164077] Starting dynamic subtest: A-eDP1
[126.707397] Dynamic subtest A-eDP1: SUCCESS (2.543s)
[126.714294] Starting dynamic subtest: B-eDP1
[128.853592] Dynamic subtest B-eDP1: SUCCESS (2.146s)
[128.861991] Starting dynamic subtest: C-eDP1
[131.014663] Dynamic subtest C-eDP1: SUCCESS (2.161s)
[131.022754] Starting dynamic subtest: D-eDP1
[133.178440] Dynamic subtest D-eDP1: SUCCESS (2.164s)
[133.180993] Subtest basic-flip-vs-dpms: SUCCESS (9.020s)
[133.963947] [031/131] (885s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[134.066526] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[134.070866] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[134.158440] [032/131] (885s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt)
[134.261177] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt
[134.265153] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[134.335132] [033/131] (885s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[134.437741] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[134.441956] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[134.523577] [034/131] (885s left) syncobj_timeline (wait-all-for-submit-snapshot)
[134.587053] Starting subtest: wait-all-for-submit-snapshot
[134.666718] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[134.739624] [035/131] (885s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[134.846637] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[138.095482] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (3.253s)
[138.215106] [036/131] (881s left) gem_mmap_gtt (big-bo-tiledy)
[138.273738] Subtest big-bo-tiledY: SKIP (0.000s)
[138.327635] [037/131] (881s left) kms_pipe_crc_basic (read-crc)
[138.432692] Starting subtest: read-crc
[138.434725] Starting dynamic subtest: pipe-A-eDP-1
[139.135816] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.701s)
[139.144785] Starting dynamic subtest: pipe-B-eDP-1
[140.462860] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.325s)
[140.472097] Starting dynamic subtest: pipe-C-eDP-1
[141.719620] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.256s)
[141.727746] Starting dynamic subtest: pipe-D-eDP-1
[143.060322] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.338s)
[143.062410] Subtest read-crc: SUCCESS (4.625s)
[143.888684] [038/131] (876s left) gem_exec_fence (syncobj-export)
[144.355708] Starting subtest: syncobj-export
[144.361776] Subtest syncobj-export: SUCCESS (0.005s)
[146.065261] [FACT gem_exec_fence (syncobj-export)] new: kernel.kmod_is_loaded.vgem: true
[146.068485] [039/131] (874s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[146.137253] Subtest untiled-to-tiled: SKIP (0.000s)
[146.184437] [040/131] (873s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[151.153419] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[151.155704] Starting dynamic subtest: pipe-A-eDP-1
[151.963223] Dynamic subtest pipe-A-eDP-1: SKIP (0.799s)
[151.973287] Starting dynamic subtest: pipe-B-eDP-1
[153.079804] Dynamic subtest pipe-B-eDP-1: SKIP (1.121s)
[153.084162] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.924s)
[154.260194] [041/131] (865s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[154.363482] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[154.367600] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[154.462811] [042/131] (865s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[154.573263] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[154.577236] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[154.683867] [043/131] (865s left) api_intel_bb (purge-bb)
[154.749071] Starting subtest: purge-bb
[154.757266] Subtest purge-bb: SUCCESS (0.004s)
[154.887383] [044/131] (865s left) gem_mmap_wc (write-cpu-read-wc)
[154.948153] Subtest write-cpu-read-wc: SKIP (0.000s)
[155.009418] [045/131] (865s left) gem_ctx_bad_destroy (invalid-default-ctx)
[155.065899] Starting subtest: invalid-default-ctx
[155.068283] Subtest invalid-default-ctx: SUCCESS (0.000s)
[155.126589] [046/131] (865s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs)
[155.226500] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs
[155.228342] Starting dynamic subtest: pipe-A-eDP-1
[155.248415] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[155.255546] Starting dynamic subtest: pipe-B-eDP-1
[155.274301] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[155.280143] Starting dynamic subtest: pipe-C-eDP-1
[155.299482] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[155.305180] Starting dynamic subtest: pipe-D-eDP-1
[155.324948] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[155.328759] Subtest random-ccs-data-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[157.506652] [047/131] (862s left) perf (blocking)
[157.604421] Starting subtest: blocking
[157.606743] Starting dynamic subtest: 0-rcs0
[167.639324] Dynamic subtest 0-rcs0: SUCCESS (10.033s)
[167.646591] Starting dynamic subtest: 1-vcs1
[177.661328] Dynamic subtest 1-vcs1: SUCCESS (10.022s)
[177.664086] Subtest blocking: SUCCESS (20.055s)
[177.703845] [048/131] (842s left) syncobj_wait (reset-multiple-signaled)
[177.765467] Starting subtest: reset-multiple-signaled
[177.767785] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[177.839213] [049/131] (842s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[177.960551] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[177.965035] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[178.068368] [050/131] (842s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[178.455500] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[179.217353] [051/131] (841s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[179.328345] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[179.332642] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[179.437470] [052/131] (841s left) kms_cursor_crc (cursor-offscreen-128x128)
[179.633742] Starting subtest: cursor-offscreen-128x128
[179.638443] Starting dynamic subtest: pipe-A-eDP-1
[181.623733] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.985s)
[181.651762] Starting dynamic subtest: pipe-D-eDP-1
[184.268470] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.620s)
[184.271067] Subtest cursor-offscreen-128x128: SUCCESS (4.633s)
[185.078849] [053/131] (835s left) i915_suspend (fence-restore-tiled2untiled)
[185.137432] Starting subtest: fence-restore-tiled2untiled
[185.141586] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[185.253850] [054/131] (835s left) gem_spin_batch (legacy-resubmit)
[185.316830] Starting subtest: legacy-resubmit
[185.319341] Starting dynamic subtest: default
[185.324585] Dynamic subtest default: SUCCESS (0.005s)
[185.334084] Starting dynamic subtest: render
[185.336445] Dynamic subtest render: SUCCESS (0.001s)
[185.343292] Starting dynamic subtest: bsd
[185.345246] Dynamic subtest bsd: SUCCESS (0.002s)
[185.351545] Starting dynamic subtest: bsd1
[185.353475] Dynamic subtest bsd1: SUCCESS (0.002s)
[185.359693] Starting dynamic subtest: bsd2
[185.361609] Dynamic subtest bsd2: SUCCESS (0.001s)
[185.367796] Starting dynamic subtest: blt
[185.369711] Dynamic subtest blt: SUCCESS (0.002s)
[185.376018] Starting dynamic subtest: vebox
[185.377941] Dynamic subtest vebox: SUCCESS (0.003s)
[185.379907] Subtest legacy-resubmit: SUCCESS (0.020s)
[185.557155] [055/131] (835s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[185.664268] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[186.644085] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (0.978s)
[186.767526] [056/131] (833s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[186.864095] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[186.866302] Starting dynamic subtest: pipe-A-eDP-1
[186.888761] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[186.896217] Starting dynamic subtest: pipe-B-eDP-1
[186.917623] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[186.924478] Starting dynamic subtest: pipe-C-eDP-1
[186.945393] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[186.952216] Starting dynamic subtest: pipe-D-eDP-1
[186.970225] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[186.973581] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[189.027815] [057/131] (831s left) kms_flip (flip-vs-blocking-wf-vblank)
[189.133223] Starting subtest: flip-vs-blocking-wf-vblank
[189.135533] Starting dynamic subtest: A-eDP1
[198.080373] Dynamic subtest A-eDP1: SUCCESS (8.946s)
[198.092959] Starting dynamic subtest: B-eDP1
[207.010110] Dynamic subtest B-eDP1: SUCCESS (8.928s)
[207.018523] Starting dynamic subtest: C-eDP1
[215.919640] Dynamic subtest C-eDP1: SUCCESS (8.909s)
[215.928372] Starting dynamic subtest: D-eDP1
[224.851429] Dynamic subtest D-eDP1: SUCCESS (8.928s)
[224.853458] Subtest flip-vs-blocking-wf-vblank: SUCCESS (35.721s)
[226.068366] [058/131] (794s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt)
[226.187147] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt
[230.973559] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.788s)
[231.151167] [059/131] (789s left) gem_exec_endless (dispatch)
[231.222970] Starting subtest: dispatch
[231.225443] Starting dynamic subtest: rcs0
[231.232601] Dynamic subtest rcs0: SUCCESS (0.005s)
[231.241229] Starting dynamic subtest: bcs0
[231.245465] Dynamic subtest bcs0: SUCCESS (0.004s)
[231.254354] Starting dynamic subtest: vcs0
[231.258900] Dynamic subtest vcs0: SUCCESS (0.005s)
[231.266624] Starting dynamic subtest: vcs1
[231.271765] Dynamic subtest vcs1: SUCCESS (0.004s)
[231.284264] Starting dynamic subtest: vecs0
[231.288542] Dynamic subtest vecs0: SUCCESS (0.005s)
[231.295981] Starting dynamic subtest: ccs0
[231.300304] Dynamic subtest ccs0: SUCCESS (0.005s)
[231.302774] Subtest dispatch: SUCCESS (0.030s)
[232.978276] [060/131] (787s left) gem_eio (wait-10ms)
[233.221032] Starting subtest: wait-10ms
[233.601061] Subtest wait-10ms: SUCCESS (0.381s)
[233.758423] [061/131] (787s left) gen7_exec_parse (oacontrol-tracking)
[233.814342] Subtest oacontrol-tracking: SKIP (0.000s)
[233.894814] [062/131] (787s left) kms_psr (fbc-pr-primary-mmap-cpu)
[234.001418] Starting subtest: fbc-pr-primary-mmap-cpu
[234.004730] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s)
[234.069923] [063/131] (786s left) api_intel_allocator (simple-allocator)
[234.133540] Starting subtest: simple-allocator
[234.135247] Starting dynamic subtest: basic
[234.137234] Dynamic subtest basic: SUCCESS (0.003s)
[234.148971] Starting dynamic subtest: parallel-one
[234.323291] Dynamic subtest parallel-one: SUCCESS (0.186s)
[234.333828] Starting dynamic subtest: print
[234.336229] Dynamic subtest print: SUCCESS (0.000s)
[234.343740] Starting dynamic subtest: reuse
[234.345678] Dynamic subtest reuse: SUCCESS (0.000s)
[234.352462] Starting dynamic subtest: reserve
[234.354352] Dynamic subtest reserve: SUCCESS (0.000s)
[234.361249] Starting dynamic subtest: fork-reopen-allocator
[234.363094] Dynamic subtest fork-reopen-allocator: SUCCESS (0.013s)
[234.364929] Subtest simple-allocator: SUCCESS (0.205s)
[234.466656] [064/131] (786s left) gem_pxp (reject-modify-context-protection-on)
[234.580988] Starting subtest: reject-modify-context-protection-on
[234.583442] Subtest reject-modify-context-protection-on: SUCCESS (0.002s)
[234.678968] [065/131] (786s left) perf_pmu (busy-start)
[234.718816] Starting subtest: busy-start
[234.720704] Starting dynamic subtest: rcs0
[237.271030] Dynamic subtest rcs0: SUCCESS (2.552s)
[237.281853] Starting dynamic subtest: bcs0
[239.818930] Dynamic subtest bcs0: SUCCESS (2.547s)
[239.828211] Starting dynamic subtest: vcs0
[242.926629] Dynamic subtest vcs0: SUCCESS (3.105s)
[242.935812] Starting dynamic subtest: vcs1
[246.958247] Dynamic subtest vcs1: SUCCESS (4.030s)
[246.969842] Starting dynamic subtest: vecs0
[250.925795] Dynamic subtest vecs0: SUCCESS (3.966s)
[250.936103] Starting dynamic subtest: ccs0
[253.476528] Dynamic subtest ccs0: SUCCESS (2.550s)
[253.479894] Subtest busy-start: SUCCESS (18.758s)
[253.588202] [066/131] (767s left) kms_plane_multiple (tiling-y)
[253.697760] Starting subtest: tiling-y
[253.702181] Subtest tiling-y: SKIP (0.000s)
[253.766996] [067/131] (767s left) gem_workarounds (reset-context)
[253.827861] Starting subtest: reset-context
[253.986822] Subtest reset-context: SUCCESS (0.158s)
[254.127194] [068/131] (766s left) gem_ctx_persistence (saturated-hostile)
[254.186726] Starting subtest: saturated-hostile
[254.189245] Starting dynamic subtest: rcs0
[255.024245] Dynamic subtest rcs0: SUCCESS (0.837s)
[255.035667] Starting dynamic subtest: bcs0
[255.817693] Dynamic subtest bcs0: SUCCESS (0.788s)
[255.827713] Starting dynamic subtest: vcs0
[256.630635] Dynamic subtest vcs0: SUCCESS (0.816s)
[256.640353] Starting dynamic subtest: vcs1
[257.439434] Dynamic subtest vcs1: SUCCESS (0.808s)
[257.448697] Starting dynamic subtest: vecs0
[258.257841] Dynamic subtest vecs0: SUCCESS (0.816s)
[258.269241] Starting dynamic subtest: ccs0
[259.116474] Dynamic subtest ccs0: SUCCESS (0.858s)
[259.119144] Subtest saturated-hostile: SUCCESS (4.930s)
[259.191335] [069/131] (761s left) kms_selftest (drm_dp_mst_helper)
[259.231778] Starting subtest: drm_dp_mst_helper
[259.250406] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[259.382461] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.133s)
[259.395173] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[259.530226] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.148s)
[259.538875] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[259.662304] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.131s)
[259.664323] Subtest drm_dp_mst_helper: SUCCESS (0.434s)
[259.851116] [070/131] (761s left) dumb_buffer (map-invalid-size)
[259.910090] Starting subtest: map-invalid-size
[259.912547] Subtest map-invalid-size: SUCCESS (0.000s)
[260.002845] [071/131] (761s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[260.078058] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[260.136900] [072/131] (761s left) gem_ctx_persistence (engines-hostile-preempt)
[260.196045] Starting subtest: engines-hostile-preempt
[260.198340] Starting dynamic subtest: rcs0
[260.276861] Dynamic subtest rcs0: SUCCESS (0.083s)
[260.287455] Starting dynamic subtest: bcs0
[260.344765] Dynamic subtest bcs0: SUCCESS (0.067s)
[260.354999] Starting dynamic subtest: vcs0
[261.943084] Dynamic subtest vcs0: SUCCESS (1.596s)
[261.952738] Starting dynamic subtest: vcs1
[263.946331] Dynamic subtest vcs1: SUCCESS (2.002s)
[263.959235] Starting dynamic subtest: vecs0
[265.879555] Dynamic subtest vecs0: SUCCESS (1.931s)
[265.889827] Starting dynamic subtest: ccs0
[265.972874] Dynamic subtest ccs0: SUCCESS (0.093s)
[265.975825] Subtest engines-hostile-preempt: SUCCESS (5.780s)
[266.048229] [073/131] (755s left) kms_pm_rpm (basic-rte)
[266.084078] Starting subtest: basic-rte
[269.541070] Subtest basic-rte: SUCCESS (3.459s)
[271.036221] [074/131] (750s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-gtt)
[271.137599] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-gtt
[271.142079] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[271.232325] [075/131] (750s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[271.327287] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[271.329605] Starting dynamic subtest: pipe-A-eDP-1
[271.350338] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[271.358958] Starting dynamic subtest: pipe-B-eDP-1
[271.377473] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[271.385351] Starting dynamic subtest: pipe-C-eDP-1
[271.405074] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[271.414736] Starting dynamic subtest: pipe-D-eDP-1
[271.434064] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[271.436206] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.007s)
[273.473365] [076/131] (747s left) gem_exec_async (concurrent-writes)
[273.533960] Starting subtest: concurrent-writes
[273.536533] Starting dynamic subtest: rcs0
[273.548404] Dynamic subtest rcs0: SUCCESS (0.014s)
[273.557062] Starting dynamic subtest: bcs0
[273.559212] Dynamic subtest bcs0: SUCCESS (0.006s)
[273.566894] Starting dynamic subtest: vcs0
[273.569246] Dynamic subtest vcs0: SUCCESS (0.004s)
[273.576988] Starting dynamic subtest: vcs1
[273.579298] Dynamic subtest vcs1: SUCCESS (0.006s)
[273.586781] Starting dynamic subtest: vecs0
[273.588904] Dynamic subtest vecs0: SUCCESS (0.004s)
[273.596328] Starting dynamic subtest: ccs0
[273.598541] Dynamic subtest ccs0: SUCCESS (0.006s)
[273.600674] Subtest concurrent-writes: SUCCESS (0.044s)
[273.815511] [077/131] (747s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-plflip-blt)
[273.917603] Starting subtest: fbc-1p-primscrn-shrfb-plflip-blt
[277.592820] Subtest fbc-1p-primscrn-shrfb-plflip-blt: SUCCESS (3.680s)
[277.725041] [078/131] (743s left) gem_exec_balancer (parallel-ordering)
[277.970679] Starting subtest: parallel-ordering
[278.771597] Subtest parallel-ordering: SUCCESS (0.801s)
[278.904326] [079/131] (742s left) kms_cursor_legacy (cursora-vs-flipa-legacy)
[279.028536] Starting subtest: cursorA-vs-flipA-legacy
[290.182957] Subtest cursorA-vs-flipA-legacy: SUCCESS (11.153s)
[291.016270] [080/131] (730s left) perf (i915-ref-count)
[291.083731] Starting subtest: i915-ref-count
[291.120497] Subtest i915-ref-count: SUCCESS (0.033s)
[291.169256] [081/131] (730s left) kms_psr (fbc-psr2-primary-mmap-cpu)
[291.270626] Starting subtest: fbc-psr2-primary-mmap-cpu
[291.272853] Starting dynamic subtest: eDP-1
[291.277232] Dynamic subtest eDP-1: SKIP (0.000s)
[291.281556] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.001s)
[291.335081] [082/131] (730s left) gen9_exec_parse (valid-registers)
[291.400037] Subtest valid-registers: SKIP (0.000s)
[291.456615] [083/131] (730s left) gem_ctx_engines (execute-one)
[291.508626] Starting subtest: execute-one
[293.622118] Subtest execute-one: SUCCESS (2.114s)
[293.971687] [084/131] (727s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[294.081709] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[294.085725] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[294.181435] [085/131] (727s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[294.240346] Starting subtest: invalid-multi-wait-unsubmitted
[294.242289] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[294.348162] [086/131] (727s left) kms_cursor_crc (cursor-onscreen-512x512)
[294.579818] Starting subtest: cursor-onscreen-512x512
[294.584522] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[294.758678] [087/131] (727s left) gem_madvise (dontneed-before-pwrite)
[294.791043] Starting subtest: dontneed-before-pwrite
[294.820992] Subtest dontneed-before-pwrite: SKIP (0.026s)
[294.884462] [088/131] (726s left) syncobj_timeline (invalid-wait-illegal-handle)
[294.946764] Starting subtest: invalid-wait-illegal-handle
[294.948818] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[295.054184] [089/131] (726s left) gem_eio (hibernate)
[295.293813] Starting subtest: hibernate
[310.137385] Subtest hibernate: SUCCESS (14.841s)
[310.227985] Aborting: Lockdep not active

/proc/lockdep_stats contents:
 lock-classes:                         2377 [max: 8192]
 direct dependencies:                 27780 [max: 524288]
 indirect dependencies:              229568
 all direct dependencies:            599715
 dependency chains:                   42721 [max: 524288]
 dependency chain hlocks used:       185512 [max: 2621440]
 dependency chain hlocks lost:            0
 in-hardirq chains:                     381
 in-softirq chains:                     897
 in-process chains:                   41443
 stack-trace entries:                292392 [max: 524288]
 number of stack traces:              13403
 number of stack hash chains:          9162
 combined max dependencies:      1331882096
 hardirq-safe locks:                    133
 hardirq-unsafe locks:                 1365
 softirq-safe locks:                    261
 softirq-unsafe locks:                 1263
 irq-safe locks:                        286
 irq-unsafe locks:                     1365
 hardirq-read-safe locks:                 5
 hardirq-read-unsafe locks:             463
 softirq-read-safe locks:                 9
 softirq-read-unsafe locks:             458
 irq-read-safe locks:                     9
 irq-read-unsafe locks:                 463
 uncategorized locks:                   392
 unused locks:                            1
 max locking depth:                      16
 max bfs queue depth:                   420
 max lock class index:                 2376
 debug_locks:                             0

 zapped classes:                          3
 zapped lock chains:                    158
 large chain blocks:                      1

[310.242865] Closing watchdogs
Done.