[39.812916] Initializing watchdogs
[39.813095]   /dev/watchdog0
[39.826600] [001/138] (960s left) syncobj_basic (bad-fd-to-handle)
[39.893087] Starting subtest: bad-fd-to-handle
[39.895462] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[39.945147] [002/138] (960s left) syncobj_timeline (multi-wait-all-available-submitted)
[39.999376] Starting subtest: multi-wait-all-available-submitted
[40.001761] Subtest multi-wait-all-available-submitted: SUCCESS (0.001s)
[40.060522] [003/138] (960s left) gem_exec_reloc (basic-wc-gtt)
[40.114362] Subtest basic-wc-gtt: SKIP (0.000s)
[40.158418] [004/138] (960s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation)
[40.225258] Starting subtest: plane-scaler-with-clipping-clamping-rotation
[40.227703] Starting dynamic subtest: pipe-A-eDP-1
[40.686193] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.465s)
[40.695583] Starting dynamic subtest: pipe-B-eDP-1
[42.257910] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.570s)
[42.268238] Starting dynamic subtest: pipe-C-eDP-1
[43.825144] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.565s)
[43.835426] Starting dynamic subtest: pipe-D-eDP-1
[45.406354] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.580s)
[45.408749] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (5.186s)
[46.636252] [005/138] (953s left) gem_lmem_swapping (parallel-random-verify)
[46.717133] Subtest parallel-random-verify: SKIP (0.000s)
[46.751653] [006/138] (953s left) kms_setmode (basic)
[46.820331] Starting subtest: basic
[47.257508] Starting dynamic subtest: pipe-A-eDP-1
[49.289359] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.027s)
[49.626692] Starting dynamic subtest: pipe-B-eDP-1
[51.769743] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.146s)
[52.087179] Subtest basic: SUCCESS (5.266s)
[52.956926] [007/138] (947s left) kms_cursor_crc (cursor-rapid-movement-512x170)
[53.109233] Starting subtest: cursor-rapid-movement-512x170
[53.113982] Subtest cursor-rapid-movement-512x170: SKIP (0.002s)
[53.220880] [008/138] (947s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling)
[53.291040] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling
[53.293443] Starting dynamic subtest: pipe-A-default-mode
[56.177964] Dynamic subtest pipe-A-default-mode: SUCCESS (2.888s)
[56.180804] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (2.889s)
[57.065057] [009/138] (943s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[57.137475] Starting subtest: yf-tiled-64bpp-rotate-180
[57.141433] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[57.216464] [010/138] (943s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[57.276932] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[57.278935] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[57.337229] [011/138] (943s left) kms_ccs (pipe-a-bad-rotation-90-4-tiled-mtl-rc-ccs)
[57.727761] Starting subtest: pipe-A-bad-rotation-90-4-tiled-mtl-rc-ccs
[58.614413] Subtest pipe-A-bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.887s)
[59.819746] [012/138] (940s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs)
[60.228554] Starting subtest: pipe-D-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs
[60.234253] Subtest pipe-D-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs: SKIP (0.001s)
[61.057799] [013/138] (939s left) kms_ccs (pipe-a-crc-primary-basic-y-tiled-gen12-rc-ccs)
[61.437260] Starting subtest: pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs
[61.441984] Subtest pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.001s)
[62.314626] [014/138] (938s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size)
[62.426817] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size
[63.387489] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (0.960s)
[64.286085] [015/138] (936s left) kms_chamelium_hpd (hdmi-hpd-for-each-pipe)
[64.637074] Subtest hdmi-hpd-for-each-pipe: SKIP (0.000s)
[65.541906] [016/138] (935s left) kms_cursor_crc (cursor-offscreen-32x32)
[65.628522] Starting subtest: cursor-offscreen-32x32
[65.658031] Subtest cursor-offscreen-32x32: SKIP (0.024s)
[65.849049] [017/138] (934s left) kms_atomic_transition (plane-all-transition-fencing)
[65.938617] Starting subtest: plane-all-transition-fencing
[65.943564] Starting dynamic subtest: pipe-A-eDP-1
[68.434769] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.497s)
[68.754262] Starting dynamic subtest: pipe-B-eDP-1
[72.090089] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.344s)
[72.394623] Subtest plane-all-transition-fencing: SUCCESS (6.454s)
[73.320521] [018/138] (927s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[73.394589] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-gtt
[73.399055] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[73.473881] [019/138] (927s left) gem_exec_balancer (noheartbeat)
[73.717892] Starting subtest: noheartbeat
[73.722395] Subtest noheartbeat: SKIP (0.000s)
[73.777445] [020/138] (926s left) kms_ccs (pipe-b-random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[74.147751] Starting subtest: pipe-B-random-ccs-data-4-tiled-dg2-rc-ccs-cc
[74.154154] Subtest pipe-B-random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[75.012441] [021/138] (925s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[75.097154] Starting subtest: psr-modesetfrombusy
[79.403829] Subtest psr-modesetfrombusy: SUCCESS (4.314s)
[79.508092] [022/138] (921s left) kms_ccs (pipe-d-random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[79.898286] Starting subtest: pipe-D-random-ccs-data-y-tiled-gen12-rc-ccs-cc
[79.906329] Subtest pipe-D-random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s)
[80.733864] [023/138] (920s left) kms_cursor_edge_walk (128x128-top-edge)
[80.816544] Starting subtest: 128x128-top-edge
[80.818907] Starting dynamic subtest: pipe-A-eDP-1
[84.421431] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.603s)
[84.431198] Starting dynamic subtest: pipe-D-eDP-1
[87.682323] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.258s)
[87.684739] Subtest 128x128-top-edge: SUCCESS (6.866s)
[88.621352] [024/138] (912s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[88.691741] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-gtt
[88.695812] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[88.780862] [025/138] (912s left) kms_ccs (pipe-b-bad-aux-stride-4-tiled-mtl-rc-ccs-cc)
[89.162329] Starting subtest: pipe-B-bad-aux-stride-4-tiled-mtl-rc-ccs-cc
[89.165224] Subtest pipe-B-bad-aux-stride-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.000s)
[90.009595] [026/138] (910s left) gem_ctx_shared (exec-single-timeline)
[90.075610] Starting subtest: exec-single-timeline
[90.080675] Starting dynamic subtest: rcs0
[91.142838] Dynamic subtest rcs0: SUCCESS (1.065s)
[91.154718] Starting dynamic subtest: bcs0
[92.166939] Dynamic subtest bcs0: SUCCESS (1.023s)
[92.179582] Starting dynamic subtest: vcs0
[93.191544] Dynamic subtest vcs0: SUCCESS (1.024s)
[93.203239] Starting dynamic subtest: vcs1
[94.215428] Dynamic subtest vcs1: SUCCESS (1.022s)
[94.227136] Starting dynamic subtest: vecs0
[95.239497] Dynamic subtest vecs0: SUCCESS (1.023s)
[95.251356] Starting dynamic subtest: ccs0
[96.263563] Dynamic subtest ccs0: SUCCESS (1.023s)
[96.266247] Subtest exec-single-timeline: SUCCESS (6.186s)
[96.443911] [027/138] (904s left) kms_addfb_basic (unused-pitches)
[96.497989] Starting subtest: unused-pitches
[96.500550] Subtest unused-pitches: SUCCESS (0.000s)
[96.609173] [028/138] (904s left) gem_compute (compute-square)
[96.672909] Starting subtest: compute-square
[96.677542] Subtest compute-square: SKIP (0.000s)
[96.731852] [029/138] (904s left) gem_request_retire (retire-vma-not-inactive)
[96.777683] Starting subtest: retire-vma-not-inactive
[96.783194] Subtest retire-vma-not-inactive: SUCCESS (0.007s)
[96.871589] [030/138] (904s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[96.960417] Starting subtest: 4-tiled-addfb-size-offset-overflow
[96.962864] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[97.055077] [031/138] (903s left) kms_lease (lease-unleased-connector)
[97.115242] Starting subtest: lease-unleased-connector
[97.117872] Starting dynamic subtest: pipe-A-eDP-1
[97.125225] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.008s)
[97.139138] Starting dynamic subtest: pipe-B-eDP-1
[97.141653] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.014s)
[97.153323] Starting dynamic subtest: pipe-C-eDP-1
[97.155817] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.012s)
[97.163632] Starting dynamic subtest: pipe-D-eDP-1
[97.165572] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.008s)
[97.167505] Subtest lease-unleased-connector: SUCCESS (0.046s)
[97.247555] [032/138] (903s left) gem_pread (uncached)
[97.308985] Subtest uncached: SKIP (0.000s)
[97.341443] [033/138] (903s left) kms_big_fb (x-tiled-addfb-size-offset-overflow)
[97.411637] Starting subtest: x-tiled-addfb-size-offset-overflow
[97.413702] Subtest x-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[97.497257] [034/138] (903s left) syncobj_wait (multi-wait-signaled)
[97.545977] Starting subtest: multi-wait-signaled
[97.547985] Subtest multi-wait-signaled: SUCCESS (0.000s)
[97.608149] [035/138] (903s left) api_intel_bb (reset-bb)
[97.657762] Starting subtest: reset-bb
[97.659911] Subtest reset-bb: SUCCESS (0.001s)
[97.743220] [036/138] (903s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[97.824793] Starting subtest: fbcpsr-2p-rte
[97.828069] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[97.904431] [037/138] (903s left) kms_pm_rpm (i2c)
[99.618233] Starting subtest: i2c
[109.077450] Subtest i2c: SUCCESS (9.458s)
[109.186228] [038/138] (891s left) gem_mmap_wc (fault-concurrent)
[109.258271] Subtest fault-concurrent: SKIP (0.000s)
[109.277727] [039/138] (891s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[109.347500] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[109.351942] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[109.522166] [040/138] (891s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc)
[109.587586] Starting subtest: fbcpsr-rgb565-draw-mmap-wc
[114.408734] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (4.824s)
[114.526741] [041/138] (886s left) gem_exec_schedule (semaphore-resolve)
[114.588505] Starting subtest: semaphore-resolve
[114.615406] Subtest semaphore-resolve: SUCCESS (0.031s)
[114.937909] [042/138] (886s left) vc4/vc4_tiling (set-get)
[115.034940] Subtest set-get: SKIP (0.000s)
[115.080235] [043/138] (886s left) gem_exec_whisper (basic-queues)
[115.162631] Starting subtest: basic-queues
[120.394915] Subtest basic-queues: SUCCESS (5.228s)
[120.570282] [044/138] (880s left) kms_psr (psr2_primary_blt)
[120.659904] Starting subtest: psr2_primary_blt
[122.430704] Subtest psr2_primary_blt: SUCCESS (1.771s)
[123.372336] [045/138] (877s left) gem_exec_params (rel-constants-invalid-ring)
[123.420845] Starting subtest: rel-constants-invalid-ring
[123.423204] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[123.513335] [046/138] (877s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc)
[123.626064] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc
[126.767296] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (3.145s)
[126.894336] [047/138] (874s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[126.980086] Starting subtest: primary-x-tiled-reflect-x-0
[134.582870] Subtest primary-x-tiled-reflect-x-0: SUCCESS (7.603s)
[135.851650] [048/138] (865s left) kms_universal_plane (universal-plane-functional)
[135.927877] Starting subtest: universal-plane-functional
[135.930314] Starting dynamic subtest: pipe-A-eDP-1
[140.049938] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.119s)
[140.067866] Starting dynamic subtest: pipe-B-eDP-1
[144.914409] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.862s)
[144.927327] Starting dynamic subtest: pipe-C-eDP-1
[149.562413] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.644s)
[149.574477] Starting dynamic subtest: pipe-D-eDP-1
[154.236614] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.671s)
[154.239059] Subtest universal-plane-functional: SUCCESS (18.309s)
[155.161021] [049/138] (846s left) gem_mmap_gtt (basic-write-read-distinct)
[155.221965] Subtest basic-write-read-distinct: SKIP (0.000s)
[155.237988] [050/138] (846s left) kms_cursor_legacy (cursor-vs-flip-varying-size)
[155.325282] Starting subtest: cursor-vs-flip-varying-size
[184.040667] Subtest cursor-vs-flip-varying-size: SUCCESS (28.714s)
[184.942551] [051/138] (816s left) i915_pm_rpm (debugfs-forcewake-user)
[186.652405] Starting subtest: debugfs-forcewake-user
[200.514055] Subtest debugfs-forcewake-user: SUCCESS (13.862s)
[200.871116] [052/138] (800s left) kms_color (legacy-gamma)
[200.955369] Starting subtest: legacy-gamma
[200.957843] Starting dynamic subtest: pipe-A
[201.690408] Dynamic subtest pipe-A: SUCCESS (0.735s)
[201.702103] Starting dynamic subtest: pipe-B
[203.186269] Dynamic subtest pipe-B: SUCCESS (1.491s)
[203.197299] Starting dynamic subtest: pipe-C
[204.601303] Dynamic subtest pipe-C: SUCCESS (1.416s)
[204.611924] Starting dynamic subtest: pipe-D
[206.092036] Dynamic subtest pipe-D: SUCCESS (1.491s)
[206.094440] Subtest legacy-gamma: SUCCESS (5.137s)
[207.007773] [053/138] (794s left) kms_writeback (writeback-fb-id-xrgb2101010)
[207.096945] Subtest writeback-fb-id-XRGB2101010: SKIP (0.000s)
[207.112342] [054/138] (794s left) kms_properties (plane-properties-atomic)
[207.176856] Starting subtest: plane-properties-atomic
[210.968579] Subtest plane-properties-atomic: SUCCESS (3.791s)
[211.875819] [055/138] (789s left) gem_exec_fence (basic-await)
[212.079522] Starting subtest: basic-await
[212.082110] Starting dynamic subtest: rcs0
[212.157835] Dynamic subtest rcs0: SUCCESS (0.079s)
[212.170250] Starting dynamic subtest: bcs0
[212.238255] Dynamic subtest bcs0: SUCCESS (0.079s)
[212.251051] Starting dynamic subtest: vcs0
[212.319630] Dynamic subtest vcs0: SUCCESS (0.080s)
[212.332044] Starting dynamic subtest: vcs1
[212.400983] Dynamic subtest vcs1: SUCCESS (0.080s)
[212.414624] Starting dynamic subtest: vecs0
[212.500406] Dynamic subtest vecs0: SUCCESS (0.098s)
[212.513230] Starting dynamic subtest: ccs0
[212.583752] Dynamic subtest ccs0: SUCCESS (0.081s)
[212.586949] Subtest basic-await: SUCCESS (0.506s)
[214.505052] [056/138] (787s left) prime_busy (after)
[214.560605] Starting subtest: after
[214.562976] Starting dynamic subtest: rcs0
[214.592060] Dynamic subtest rcs0: SUCCESS (0.029s)
[214.604249] Starting dynamic subtest: bcs0
[214.631432] Dynamic subtest bcs0: SUCCESS (0.038s)
[214.643898] Starting dynamic subtest: vcs0
[214.671208] Dynamic subtest vcs0: SUCCESS (0.037s)
[214.683271] Starting dynamic subtest: vcs1
[214.689091] Dynamic subtest vcs1: SUCCESS (0.015s)
[214.696869] Starting dynamic subtest: vecs0
[214.708218] Dynamic subtest vecs0: SUCCESS (0.021s)
[214.716380] Starting dynamic subtest: ccs0
[214.727250] Dynamic subtest ccs0: SUCCESS (0.018s)
[214.838703] Subtest after: SUCCESS (0.277s)
[214.904893] [057/138] (786s left) kms_ccs (pipe-c-crc-primary-basic-y-tiled-gen12-rc-ccs)
[215.291149] Starting subtest: pipe-C-crc-primary-basic-y-tiled-gen12-rc-ccs
[215.296172] Subtest pipe-C-crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.000s)
[216.172520] [058/138] (785s left) syncobj_timeline (wait-all-snapshot)
[216.224208] Starting subtest: wait-all-snapshot
[216.304159] Subtest wait-all-snapshot: SUCCESS (0.082s)
[216.371188] [059/138] (785s left) sw_sync (timeline_closed)
[216.420103] Starting subtest: timeline_closed
[216.422587] Subtest timeline_closed: SUCCESS (0.000s)
[216.469232] [060/138] (785s left) gem_ctx_isolation (preservation-s3)
[216.543272] Starting subtest: preservation-S3
[216.545701] Starting dynamic subtest: rcs0
[232.773541] Dynamic subtest rcs0: SUCCESS (1.236s)
[232.787934] Starting dynamic subtest: bcs0
[248.837487] Dynamic subtest bcs0: SUCCESS (1.283s)
[248.851876] Starting dynamic subtest: vcs0
[264.850050] Dynamic subtest vcs0: SUCCESS (2.488s)
[264.866862] Starting dynamic subtest: vcs1
[282.842916] Dynamic subtest vcs1: SUCCESS (4.989s)
[282.859485] Starting dynamic subtest: vecs0
[300.839272] Dynamic subtest vecs0: SUCCESS (4.971s)
[300.853127] Starting dynamic subtest: ccs0
[318.840689] Dynamic subtest ccs0: SUCCESS (3.145s)
[318.843760] Subtest preservation-S3: SUCCESS (18.116s)
[319.057476] [061/138] (766s left) gem_pwrite (basic-exhaustion)
[319.113861] Subtest basic-exhaustion: SKIP (0.000s)
[319.151211] [062/138] (766s left) gen9_exec_parse (bb-start-far)
[319.223192] Subtest bb-start-far: SKIP (0.000s)
[319.252653] [063/138] (766s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[319.332959] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[323.996533] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.669s)
[324.115950] [064/138] (761s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[324.171425] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[324.175665] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[324.257812] [065/138] (761s left) kms_flip (dpms-vs-vblank-race)
[324.332603] Starting subtest: dpms-vs-vblank-race
[324.337872] Starting dynamic subtest: A-eDP1
[328.386585] Dynamic subtest A-eDP1: SUCCESS (4.049s)
[328.401057] Starting dynamic subtest: B-eDP1
[332.099822] Dynamic subtest B-eDP1: SUCCESS (3.712s)
[332.114288] Starting dynamic subtest: C-eDP1
[335.749337] Dynamic subtest C-eDP1: SUCCESS (3.646s)
[335.762057] Starting dynamic subtest: D-eDP1
[339.395594] Dynamic subtest D-eDP1: SUCCESS (3.647s)
[339.406518] Subtest dpms-vs-vblank-race: SUCCESS (15.076s)
[340.286794] [066/138] (745s left) gem_ctx_param (invalid-size-get)
[340.344308] Starting subtest: invalid-size-get
[340.346620] Subtest invalid-size-get: SUCCESS (0.000s)
[340.409200] [067/138] (745s left) gem_exec_balancer (bonded-dual)
[340.470310] Starting subtest: bonded-dual
[340.474876] Subtest bonded-dual: SKIP (0.000s)
[340.711273] [068/138] (745s left) gem_flink_basic (basic)
[340.784768] Starting subtest: basic
[340.787183] Subtest basic: SUCCESS (0.000s)
[340.868460] [069/138] (745s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[340.919634] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[340.921986] Starting dynamic subtest: A-eDP1
[349.924437] Dynamic subtest A-eDP1: SUCCESS (9.004s)
[349.939177] Starting dynamic subtest: B-eDP1
[358.882352] Dynamic subtest B-eDP1: SUCCESS (8.957s)
[358.896353] Starting dynamic subtest: C-eDP1
[367.833349] Dynamic subtest C-eDP1: SUCCESS (8.947s)
[367.849333] Starting dynamic subtest: D-eDP1
[376.791842] Dynamic subtest D-eDP1: SUCCESS (8.958s)
[376.798967] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.882s)
[378.050023] [070/138] (708s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[378.135516] Starting subtest: fbc-rgb565-draw-mmap-wc
[380.997357] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (2.869s)
[381.122364] [071/138] (705s left) gem_mmap_offset (close-race)
[381.188963] Starting subtest: close-race
[401.317075] Subtest close-race: SUCCESS (20.128s)
[401.418098] [072/138] (684s left) kms_ccs (pipe-c-ccs-on-another-bo-yf-tiled-ccs)
[401.849241] Starting subtest: pipe-C-ccs-on-another-bo-yf-tiled-ccs
[401.854796] Subtest pipe-C-ccs-on-another-bo-yf-tiled-ccs: SKIP (0.000s)
[402.742624] [073/138] (683s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[402.807970] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[402.811743] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[402.898881] [074/138] (683s left) gem_mmap_wc (read-write-distinct)
[402.949357] Subtest read-write-distinct: SKIP (0.000s)
[402.971155] [075/138] (683s left) kms_frontbuffer_tracking (psr-slowdraw)
[403.047048] Starting subtest: psr-slowdraw
[426.081763] Subtest psr-slowdraw: SUCCESS (23.037s)
[426.192707] [076/138] (660s left) gem_mmap_gtt (fault-concurrent-x)
[426.236554] Subtest fault-concurrent-X: SKIP (0.000s)
[426.265489] [077/138] (660s left) kms_atomic_transition (modeset-transition)
[426.342067] Starting subtest: modeset-transition
[426.344362] Starting dynamic subtest: 1x-outputs
[438.486659] Dynamic subtest 1x-outputs: SUCCESS (12.143s)
[438.783244] Subtest modeset-transition: SUCCESS (12.440s)
[439.688174] [078/138] (646s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[439.763944] Starting subtest: extended-modeset-hang-oldfb-with-reset
[439.766231] Starting dynamic subtest: pipe-A
[442.826833] Dynamic subtest pipe-A: SUCCESS (3.062s)
[442.847213] Starting dynamic subtest: pipe-D
[446.469329] Dynamic subtest pipe-D: SUCCESS (3.635s)
[446.471802] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (6.706s)
[447.368170] [079/138] (638s left) gem_exec_schedule (fifo)
[447.456941] Starting subtest: fifo
[447.459025] Starting dynamic subtest: rcs0
[447.530779] Dynamic subtest rcs0: SUCCESS (0.077s)
[447.542814] Starting dynamic subtest: vcs0
[447.605375] Dynamic subtest vcs0: SUCCESS (0.074s)
[447.620538] Starting dynamic subtest: vcs1
[447.677494] Dynamic subtest vcs1: SUCCESS (0.071s)
[447.690695] Starting dynamic subtest: vecs0
[447.735619] Dynamic subtest vecs0: SUCCESS (0.056s)
[447.752990] Starting dynamic subtest: ccs0
[447.792244] Dynamic subtest ccs0: SUCCESS (0.056s)
[447.794822] Subtest fifo: SUCCESS (0.340s)
[449.671545] [080/138] (636s left) perf_pmu (gt-awake)
[449.728430] Starting subtest: gt-awake
[458.590175] Subtest gt-awake: SUCCESS (8.860s)
[458.684660] [081/138] (627s left) kms_pm_rpm (pm-tiling)
[460.798731] Starting subtest: pm-tiling
[460.807423] Subtest pm-tiling: SKIP (0.000s)
[461.167520] [082/138] (625s left) gem_ctx_exec (basic)
[461.223427] Starting subtest: basic
[461.226074] Subtest basic: SUCCESS (0.004s)
[461.329678] [083/138] (625s left) drm_fdinfo (basics)
[461.377812] Starting subtest: basics
[461.379723] Subtest basics: SUCCESS (0.000s)
[461.444640] [084/138] (625s left) v3d/v3d_submit_csd (multi-and-single-sync)
[461.536139] Subtest multi-and-single-sync: SKIP (0.000s)
[461.586310] [085/138] (624s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[461.646992] Starting subtest: stress-mm-invalidate-close-overlap
[463.993632] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.349s)
[464.568894] [086/138] (621s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen)
[464.641659] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen
[469.203793] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.566s)
[469.319748] [087/138] (617s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[469.369976] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[469.374597] Starting dynamic subtest: pipe-A-default-mode
[472.355564] Dynamic subtest pipe-A-default-mode: SUCCESS (2.982s)
[472.358283] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.984s)
[473.269807] [088/138] (613s left) vc4/vc4_dmabuf_poll (poll-write-waits-until-write-done)
[473.361563] Subtest poll-write-waits-until-write-done: SKIP (0.000s)
[473.408450] [089/138] (613s left) v3d/v3d_job_submission (array-job-submission)
[473.461181] Subtest array-job-submission: SKIP (0.000s)
[473.500515] [090/138] (613s left) gem_render_copy (y-tiled-ccs-to-y-tiled)
[473.556558] Starting subtest: y-tiled-ccs-to-y-tiled
[473.560667] Subtest y-tiled-ccs-to-y-tiled: SKIP (0.000s)
[473.733575] [091/138] (612s left) kms_prop_blob (invalid-get-prop-any)
[473.793459] Starting subtest: invalid-get-prop-any
[473.795900] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[473.855504] [092/138] (612s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[473.956007] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[473.960601] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[474.043908] [093/138] (612s left) kms_flip (nonexisting-fb-interruptible)
[474.114756] Starting subtest: nonexisting-fb-interruptible
[474.119992] Starting dynamic subtest: A-eDP1
[475.384527] Dynamic subtest A-eDP1: SUCCESS (1.265s)
[475.401768] Starting dynamic subtest: B-eDP1
[476.666430] Dynamic subtest B-eDP1: SUCCESS (1.280s)
[476.682971] Starting dynamic subtest: C-eDP1
[477.909653] Dynamic subtest C-eDP1: SUCCESS (1.242s)
[477.928001] Starting dynamic subtest: D-eDP1
[479.138043] Dynamic subtest D-eDP1: SUCCESS (1.228s)
[479.144661] Subtest nonexisting-fb-interruptible: SUCCESS (5.030s)
[480.392680] [094/138] (606s left) kms_ccs (pipe-d-crc-primary-rotation-180-y-tiled-ccs)
[480.793200] Starting subtest: pipe-D-crc-primary-rotation-180-y-tiled-ccs
[480.798692] Subtest pipe-D-crc-primary-rotation-180-y-tiled-ccs: SKIP (0.000s)
[481.656668] [095/138] (605s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-pwrite)
[481.716973] Starting subtest: psr-1p-primscrn-pri-indfb-draw-pwrite
[486.944717] Subtest psr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.231s)
[487.046131] [096/138] (599s left) kms_lease (page-flip-implicit-plane)
[487.124156] Starting subtest: page-flip-implicit-plane
[487.126497] Starting dynamic subtest: pipe-A-eDP-1
[487.597575] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.472s)
[487.611845] Starting dynamic subtest: pipe-B-eDP-1
[488.841422] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.241s)
[488.852951] Starting dynamic subtest: pipe-C-eDP-1
[490.029812] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.187s)
[490.044358] Starting dynamic subtest: pipe-D-eDP-1
[491.245636] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.213s)
[491.248115] Subtest page-flip-implicit-plane: SUCCESS (4.122s)
[492.164263] [097/138] (594s left) gem_sync (basic-each)
[492.253988] Starting subtest: basic-each
[494.388307] Subtest basic-each: SUCCESS (2.151s)
[494.566483] [098/138] (592s left) i915_query (query-garbage)
[494.630174] Starting subtest: query-garbage
[494.632541] Subtest query-garbage: SUCCESS (0.000s)
[494.692066] [099/138] (592s left) kms_force_connector_basic (force-edid)
[494.750448] Starting subtest: force-edid
[495.076721] Subtest force-edid: SUCCESS (0.323s)
[495.213427] [100/138] (591s left) syncobj_timeline (single-wait-all-available-submitted)
[495.274001] Starting subtest: single-wait-all-available-submitted
[495.276022] Subtest single-wait-all-available-submitted: SUCCESS (0.000s)
[495.337014] [101/138] (591s left) kms_dsc (dsc-with-output-formats-with-bpc)
[495.412702] Starting subtest: dsc-with-output-formats-with-bpc
[495.416656] Subtest dsc-with-output-formats-with-bpc: SKIP (0.001s)
[495.479027] [102/138] (591s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[495.577618] Starting subtest: basic-busy-flip-before-cursor-varying-size
[495.581993] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[495.701072] [103/138] (591s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[495.772794] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[495.774974] Starting dynamic subtest: pipe-A-eDP-1
[495.779012] Dynamic subtest pipe-A-eDP-1: SKIP (0.003s)
[495.794220] Starting dynamic subtest: pipe-B-eDP-1
[495.798284] Dynamic subtest pipe-B-eDP-1: SKIP (0.012s)
[495.811160] Starting dynamic subtest: pipe-C-eDP-1
[495.815202] Dynamic subtest pipe-C-eDP-1: SKIP (0.011s)
[495.824768] Starting dynamic subtest: pipe-D-eDP-1
[495.828056] Dynamic subtest pipe-D-eDP-1: SKIP (0.011s)
[495.831280] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.041s)
[495.896994] [104/138] (591s left) kms_flip (basic-flip-vs-modeset)
[495.961981] Starting subtest: basic-flip-vs-modeset
[495.964420] Starting dynamic subtest: A-eDP1
[498.732839] Dynamic subtest A-eDP1: SUCCESS (2.768s)
[498.747505] Starting dynamic subtest: B-eDP1
[501.036999] Dynamic subtest B-eDP1: SUCCESS (2.303s)
[501.052859] Starting dynamic subtest: C-eDP1
[503.342155] Dynamic subtest C-eDP1: SUCCESS (2.303s)
[503.356722] Starting dynamic subtest: D-eDP1
[505.646535] Dynamic subtest D-eDP1: SUCCESS (2.303s)
[505.651217] Subtest basic-flip-vs-modeset: SUCCESS (9.693s)
[506.553636] [105/138] (580s left) gem_ctx_param (root-set)
[506.624167] Starting subtest: root-set
[506.626607] Subtest root-set: SUCCESS (0.000s)
[506.693062] [106/138] (580s left) kms_vblank (wait-forked-hang)
[506.763103] Starting subtest: wait-forked-hang
[506.765538] Starting dynamic subtest: pipe-A-eDP-1
[520.462649] Dynamic subtest pipe-A-eDP-1: SUCCESS (13.695s)
[520.482426] Starting dynamic subtest: pipe-D-eDP-1
[535.448351] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.979s)
[535.451206] Subtest wait-forked-hang: SUCCESS (28.686s)
[536.390044] [107/138] (550s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[536.452678] Starting subtest: x-tiled-to-vebox-y-tiled
[536.457496] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[536.634542] [108/138] (550s left) gem_mmap_gtt (medium-copy)
[536.683451] Subtest medium-copy: SKIP (0.000s)
[536.704823] [109/138] (550s left) kms_frontbuffer_tracking (fbc-rgb565-draw-pwrite)
[536.786600] Starting subtest: fbc-rgb565-draw-pwrite
[539.975382] Subtest fbc-rgb565-draw-pwrite: SUCCESS (3.197s)
[540.108146] [110/138] (547s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[540.186199] Starting subtest: y-tiled-32bpp-rotate-0
[540.190557] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[540.279645] [111/138] (546s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[540.364088] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[540.368010] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[540.443172] [112/138] (546s left) v3d/v3d_submit_cl (bad-pad)
[540.523606] Subtest bad-pad: SKIP (0.000s)
[540.575529] [113/138] (546s left) syncobj_wait (wait-for-submit-delayed-submit)
[540.630813] Starting subtest: wait-for-submit-delayed-submit
[540.730842] Subtest wait-for-submit-delayed-submit: SUCCESS (0.102s)
[540.804342] [114/138] (546s left) syncobj_timeline (host-signal-ordered)
[540.847812] Starting subtest: host-signal-ordered
[540.849996] Subtest host-signal-ordered: SUCCESS (0.001s)
[540.916631] [115/138] (546s left) kms_hdr (invalid-metadata-sizes)
[540.986833] Starting subtest: invalid-metadata-sizes
[540.990551] Subtest invalid-metadata-sizes: SKIP (0.000s)
[541.041567] [116/138] (546s left) gem_blits (basic)
[541.092371] Starting subtest: basic
[542.331879] Subtest basic: SUCCESS (1.238s)
[542.428811] [117/138] (544s left) kms_ccs (pipe-b-bad-rotation-90-yf-tiled-ccs)
[542.800849] Starting subtest: pipe-B-bad-rotation-90-yf-tiled-ccs
[542.806590] Subtest pipe-B-bad-rotation-90-yf-tiled-ccs: SKIP (0.000s)
[543.673026] [118/138] (543s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[543.744188] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[544.457242] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.715s)
[544.595848] [119/138] (542s left) gem_wait (write-busy)
[544.644370] Starting subtest: write-busy
[544.654570] Starting dynamic subtest: all-engines
[545.195761] Dynamic subtest all-engines: SUCCESS (0.551s)
[545.219423] Starting dynamic subtest: rcs0
[545.745502] Dynamic subtest rcs0: SUCCESS (0.549s)
[545.768753] Starting dynamic subtest: bcs0
[546.294548] Dynamic subtest bcs0: SUCCESS (0.548s)
[546.317520] Starting dynamic subtest: vcs0
[547.612224] Dynamic subtest vcs0: SUCCESS (1.315s)
[547.637093] Starting dynamic subtest: vcs1
[549.596438] Dynamic subtest vcs1: SUCCESS (1.983s)
[549.620609] Starting dynamic subtest: vecs0
[551.580534] Dynamic subtest vecs0: SUCCESS (1.982s)
[551.605913] Starting dynamic subtest: ccs0
[552.135586] Dynamic subtest ccs0: SUCCESS (0.555s)
[552.138290] Subtest write-busy: SUCCESS (7.492s)
[552.341373] [120/138] (535s left) gem_exec_fence (syncobj-timeline-export)
[552.908381] Starting subtest: syncobj-timeline-export
[552.915280] Subtest syncobj-timeline-export: SUCCESS (0.008s)
[554.688536] [121/138] (532s left) kms_ccs (pipe-c-random-ccs-data-4-tiled-dg2-rc-ccs)
[555.095099] Starting subtest: pipe-C-random-ccs-data-4-tiled-dg2-rc-ccs
[555.103155] Subtest pipe-C-random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[555.959968] [122/138] (531s left) gem_spin_batch (spin-all)
[556.024681] Starting subtest: spin-all
[556.032955] Subtest spin-all: SUCCESS (0.006s)
[557.661277] [123/138] (529s left) api_intel_bb (bb-with-allocator)
[557.697303] Starting subtest: bb-with-allocator
[557.702629] Subtest bb-with-allocator: SUCCESS (0.006s)
[557.781722] [124/138] (529s left) kms_plane (pixel-format)
[557.877231] Starting subtest: pixel-format
[557.879773] Starting dynamic subtest: pipe-A
[566.230960] Dynamic subtest pipe-A: SUCCESS (8.350s)
[566.247413] Starting dynamic subtest: pipe-B
[575.676140] Dynamic subtest pipe-B: SUCCESS (9.438s)
[575.678284] Subtest pixel-format: SUCCESS (17.795s)
[576.571067] [125/138] (511s left) kms_ccs (pipe-a-crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[576.936865] Starting subtest: pipe-A-crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[578.031354] Subtest pipe-A-crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (1.092s)
[579.209020] [126/138] (508s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[579.270245] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[580.322600] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.056s)
[580.445249] [127/138] (507s left) kms_chamelium_edid (dp-edid-read)
[580.807062] Subtest dp-edid-read: SKIP (0.000s)
[581.663747] [128/138] (506s left) gem_exec_schedule (noreorder-priority)
[581.858492] Starting subtest: noreorder-priority
[581.861075] Starting dynamic subtest: rcs0
[582.984817] Dynamic subtest rcs0: SUCCESS (1.127s)
[583.001498] Starting dynamic subtest: vcs0
[584.634320] Dynamic subtest vcs0: SUCCESS (1.647s)
[584.651818] Starting dynamic subtest: vcs1
[586.604276] Dynamic subtest vcs1: SUCCESS (1.969s)
[586.620678] Starting dynamic subtest: vecs0
[588.588451] Dynamic subtest vecs0: SUCCESS (1.983s)
[588.605854] Starting dynamic subtest: ccs0
[589.710660] Dynamic subtest ccs0: SUCCESS (1.122s)
[589.713110] Subtest noreorder-priority: SUCCESS (7.855s)
[589.894314] [129/138] (497s left) kms_hdmi_inject (inject-4k)
[590.229389] Starting subtest: inject-4k
[590.511286] Subtest inject-4k: SUCCESS (0.282s)
[591.129854] [130/138] (496s left) kms_ccs (pipe-d-ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[591.524344] Starting subtest: pipe-D-ccs-on-another-bo-4-tiled-mtl-rc-ccs
[591.527138] Subtest pipe-D-ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.000s)
[592.366638] [131/138] (495s left) kms_ccs (pipe-b-crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[592.738281] Starting subtest: pipe-B-crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[592.743032] Subtest pipe-B-crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[593.598966] [132/138] (494s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[593.653033] Subtest addfb25-x-tiled-legacy: SKIP (0.000s)
[593.753113] [133/138] (494s left) gem_exec_flush (basic-uc-ro-default)
[593.834945] Starting subtest: basic-uc-ro-default
[599.293075] Subtest basic-uc-ro-default: SUCCESS (5.456s)
[599.474073] [134/138] (488s left) i915_pm_rps (min-max-config-loaded)
[599.540429] Starting subtest: min-max-config-loaded
[599.545716] Subtest min-max-config-loaded: SKIP (0.001s)
[599.607860] [135/138] (488s left) kms_ccs (pipe-c-bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[599.995836] Starting subtest: pipe-C-bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[600.001233] Subtest pipe-C-bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[600.889873] [136/138] (487s left) gem_ctx_persistence (legacy-engines-hang)
[600.931849] Starting subtest: legacy-engines-hang
[600.934346] Starting dynamic subtest: render
[600.966405] Dynamic subtest render: SUCCESS (0.034s)
[600.980842] Starting dynamic subtest: bsd1
[601.765809] Dynamic subtest bsd1: FAIL (0.739s)
[601.782523] Starting dynamic subtest: bsd2
[603.764721] Dynamic subtest bsd2: SUCCESS (2.055s)
[603.781501] Starting dynamic subtest: blt
[603.841124] Dynamic subtest blt: SUCCESS (0.074s)
[603.858196] Starting dynamic subtest: vebox
[603.889792] Dynamic subtest vebox: SUCCESS (0.047s)
[603.892630] Subtest legacy-engines-hang: FAIL (2.959s)
[603.966512] [137/138] (483s left) prime_mmap (test_aperture_limit)
[604.006384] Starting subtest: test_aperture_limit
[604.008415] Starting dynamic subtest: test_aperture_limit-smem
[604.011579] Dynamic subtest test_aperture_limit-smem: SUCCESS (0.006s)
[604.014183] Subtest test_aperture_limit: SUCCESS (0.007s)
[604.094283] [138/138] (483s left) perf (low-oa-exponent-permissions)
[604.235548] Starting subtest: low-oa-exponent-permissions
[604.282156] Subtest low-oa-exponent-permissions: SUCCESS (0.048s)
[604.319559] Closing watchdogs
Done.