[56.555351] Initializing watchdogs [56.555391] /dev/watchdog0 [56.563117] [001/130] (960s left) gem_ctx_persistence (legacy-engines-mixed-process) [56.643996] Starting subtest: legacy-engines-mixed-process [56.646391] Starting dynamic subtest: render [57.971848] Dynamic subtest render: SUCCESS (1.327s) [57.980611] Starting dynamic subtest: bsd1 [59.969700] Dynamic subtest bsd1: SUCCESS (1.997s) [59.977860] Starting dynamic subtest: bsd2 [61.963243] Dynamic subtest bsd2: SUCCESS (1.992s) [61.974596] Starting dynamic subtest: blt [63.941833] Dynamic subtest blt: SUCCESS (1.978s) [63.950884] Starting dynamic subtest: vebox [65.909072] Dynamic subtest vebox: SUCCESS (1.965s) [65.913090] Subtest legacy-engines-mixed-process: SUCCESS (9.265s) [65.967393] [002/130] (951s left) core_hotunplug (unbind-rebind) [66.076650] Starting subtest: unbind-rebind [72.279895] Subtest unbind-rebind: SUCCESS (6.201s) [72.296157] [003/130] (944s left) i915_pipe_stress (stress-xrgb8888-ytiled) [73.914264] Starting subtest: stress-xrgb8888-ytiled [73.919060] Subtest stress-xrgb8888-ytiled: SKIP (0.000s) [73.962119] [004/130] (943s left) gem_readwrite (read-write) [74.018153] Subtest read-write: SKIP (0.000s) [74.051721] [005/130] (943s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt) [74.160142] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt [74.164575] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [74.245296] [006/130] (942s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt) [74.349334] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt [74.353529] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s) [74.423188] [007/130] (942s left) gem_exec_fence (basic-await) [74.604442] Starting subtest: basic-await [74.606867] Starting dynamic subtest: rcs0 [74.688709] Dynamic subtest rcs0: SUCCESS (0.084s) [74.699259] Starting dynamic subtest: bcs0 [74.766255] Dynamic subtest bcs0: SUCCESS (0.077s) [74.775819] Starting dynamic subtest: vcs0 [74.844479] Dynamic subtest vcs0: SUCCESS (0.078s) [74.853088] Starting dynamic subtest: vcs1 [74.922974] Dynamic subtest vcs1: SUCCESS (0.079s) [74.932091] Starting dynamic subtest: vecs0 [74.999566] Dynamic subtest vecs0: SUCCESS (0.076s) [75.010345] Starting dynamic subtest: ccs0 [75.076709] Dynamic subtest ccs0: SUCCESS (0.076s) [75.079673] Subtest basic-await: SUCCESS (0.472s) [76.963614] [008/130] (940s left) gem_exec_gttfill (all-engines) [77.020935] Starting subtest: all-engines [100.937097] Subtest all-engines: SUCCESS (23.912s) [101.102283] [009/130] (916s left) syncobj_wait (wait-all-snapshot) [101.200689] Starting subtest: wait-all-snapshot [101.282433] Subtest wait-all-snapshot: SUCCESS (0.081s) [101.339890] [010/130] (915s left) kms_dirtyfb (drrs-dirtyfb-ioctl) [101.443192] Starting subtest: drrs-dirtyfb-ioctl [101.447708] Subtest drrs-dirtyfb-ioctl: SKIP (0.001s) [101.531780] [011/130] (915s left) gem_ctx_sseu (invalid-sseu) [101.607037] Subtest invalid-sseu: SKIP (0.000s) [101.654969] [012/130] (915s left) kms_pipe_crc_basic (suspend-read-crc) [101.750055] Starting subtest: suspend-read-crc [101.752510] Starting dynamic subtest: pipe-A-eDP-1 [119.232810] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.509s) [119.244427] Starting dynamic subtest: pipe-B-eDP-1 [136.950242] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.736s) [136.963189] Starting dynamic subtest: pipe-C-eDP-1 [154.900074] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.662s) [154.909978] Starting dynamic subtest: pipe-D-eDP-1 [172.903123] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.673s) [172.905556] Subtest suspend-read-crc: SUCCESS (10.584s) [173.744639] [013/130] (904s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers) [173.849691] Starting subtest: plane-scaler-unity-scaling-with-modifiers [173.852028] Starting dynamic subtest: pipe-A [174.201947] Dynamic subtest pipe-A: SUCCESS (0.352s) [174.211910] Starting dynamic subtest: pipe-B [175.567151] Dynamic subtest pipe-B: SUCCESS (1.364s) [175.576528] Starting dynamic subtest: pipe-C [176.981319] Dynamic subtest pipe-C: SUCCESS (1.413s) [176.991573] Starting dynamic subtest: pipe-D [178.422341] Dynamic subtest pipe-D: SUCCESS (1.440s) [178.424629] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.568s) [179.538105] [014/130] (898s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions) [179.656204] Starting subtest: cursorA-vs-flipB-atomic-transitions [179.660833] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s) [179.746590] [015/130] (898s left) kms_psr (fbc-pr-no-drrs) [179.837204] Starting subtest: fbc-pr-no-drrs [179.841689] Subtest fbc-pr-no-drrs: SKIP (0.000s) [179.891391] [016/130] (898s left) gen3_mixed_blits [179.989041] [017/130] (897s left) kms_hdr (static-toggle-suspend) [180.080762] Starting subtest: static-toggle-suspend [180.087259] Subtest static-toggle-suspend: SKIP (0.000s) [180.133155] [018/130] (897s left) gem_exec_whisper (basic-queues-priority-all) [180.198536] Starting subtest: basic-queues-priority-all [201.640470] Subtest basic-queues-priority-all: SUCCESS (21.426s) [201.804515] [019/130] (876s left) kms_feature_discovery (display) [201.901880] Starting subtest: display [201.904349] Subtest display: SUCCESS (0.000s) [201.960271] [020/130] (876s left) drm_fdinfo (most-busy-check-all) [201.998328] Starting subtest: most-busy-check-all [202.000660] Starting dynamic subtest: rcs0 [202.005523] Dynamic subtest rcs0: SKIP (0.004s) [202.014480] Starting dynamic subtest: bcs0 [202.019097] Dynamic subtest bcs0: SKIP (0.000s) [202.028228] Starting dynamic subtest: vcs0 [202.031941] Dynamic subtest vcs0: SKIP (0.000s) [202.039879] Starting dynamic subtest: vcs1 [202.044042] Dynamic subtest vcs1: SKIP (0.000s) [202.051946] Starting dynamic subtest: vecs0 [202.056068] Dynamic subtest vecs0: SKIP (0.000s) [202.064282] Starting dynamic subtest: ccs0 [202.068448] Dynamic subtest ccs0: SKIP (0.000s) [202.072590] Subtest most-busy-check-all: SKIP (0.006s) [202.088472] [021/130] (875s left) perf_pmu (pmu-read) [202.130093] Starting subtest: pmu-read [202.132416] Subtest pmu-read: SUCCESS (0.002s) [202.237690] [022/130] (875s left) prime_vgem (basic-fence-read) [202.312263] Starting subtest: basic-fence-read [202.327824] Subtest basic-fence-read: SKIP (0.013s) [202.421715] [023/130] (875s left) kms_flip (wf_vblank-ts-check-interruptible) [202.524421] Starting subtest: wf_vblank-ts-check-interruptible [202.526752] Starting dynamic subtest: A-eDP1 [211.407595] Dynamic subtest A-eDP1: SUCCESS (8.878s) [211.419672] Starting dynamic subtest: B-eDP1 [220.337169] Dynamic subtest B-eDP1: SUCCESS (8.925s) [220.349402] Starting dynamic subtest: C-eDP1 [229.257408] Dynamic subtest C-eDP1: SUCCESS (8.915s) [229.268887] Starting dynamic subtest: D-eDP1 [238.091128] Dynamic subtest D-eDP1: SUCCESS (8.830s) [238.093503] Subtest wf_vblank-ts-check-interruptible: SUCCESS (35.554s) [239.290891] [024/130] (838s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt) [239.393937] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt [239.398267] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [239.477475] [025/130] (838s left) gem_ctx_persistence (file) [239.543287] Starting subtest: file [239.677194] Subtest file: SUCCESS (0.134s) [239.783440] [026/130] (838s left) kms_lease (multimaster-lease) [239.875807] Starting subtest: multimaster-lease [239.880982] Subtest multimaster-lease: SUCCESS (0.004s) [239.991529] [027/130] (838s left) gem_exec_reloc (basic-write-cpu-active) [240.051543] Subtest basic-write-cpu-active: SKIP (0.000s) [240.099413] [028/130] (838s left) gem_exec_balancer (full) [240.168085] Starting subtest: full [244.246400] Subtest full: SUCCESS (4.076s) [244.553581] [029/130] (833s left) gem_ctx_param (invalid-param-get) [244.619783] Starting subtest: invalid-param-get [244.622611] Subtest invalid-param-get: SUCCESS (0.001s) [244.713398] [030/130] (833s left) gem_softpin (overlap) [244.786340] Starting subtest: overlap [245.557963] Subtest overlap: SUCCESS (0.770s) [245.672561] [031/130] (832s left) gem_madvise (dontneed-before-mmap) [245.705963] Starting subtest: dontneed-before-mmap [245.732979] Subtest dontneed-before-mmap: SUCCESS (0.028s) [245.843821] [032/130] (832s left) gem_tiled_swapping (non-threaded) [245.911190] Subtest non-threaded: SKIP (0.000s) [245.955759] [033/130] (832s left) kms_psr (fbc-psr2-primary-mmap-cpu) [246.060869] Starting subtest: fbc-psr2-primary-mmap-cpu [246.063051] Starting dynamic subtest: eDP-1 [246.067677] Dynamic subtest eDP-1: SKIP (0.000s) [246.071883] Subtest fbc-psr2-primary-mmap-cpu: SKIP (0.000s) [246.116335] [034/130] (832s left) kms_color (ctm-negative) [246.212832] Starting subtest: ctm-negative [246.215249] Starting dynamic subtest: pipe-A-eDP-1 [246.868879] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.652s) [246.879320] Starting dynamic subtest: pipe-B-eDP-1 [248.263737] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.397s) [248.274426] Starting dynamic subtest: pipe-C-eDP-1 [249.516846] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.251s) [249.525920] Starting dynamic subtest: pipe-D-eDP-1 [250.757874] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.241s) [250.760363] Subtest ctm-negative: SUCCESS (4.543s) [251.604718] [035/130] (826s left) kms_big_fb (x-tiled-16bpp-rotate-270) [251.710287] Starting subtest: x-tiled-16bpp-rotate-270 [251.729826] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s) [251.823718] [036/130] (826s left) kms_busy (extended-pageflip-hang-newfb) [251.915507] Starting subtest: extended-pageflip-hang-newfb [251.917924] Starting dynamic subtest: pipe-A [278.344910] Dynamic subtest pipe-A: SUCCESS (26.416s) [278.357970] Starting dynamic subtest: pipe-D [293.334168] Dynamic subtest pipe-D: SUCCESS (14.981s) [293.337145] Subtest extended-pageflip-hang-newfb: SUCCESS (41.401s) [294.167827] [037/130] (784s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs) [294.237457] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs [294.242016] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s) [294.404162] [038/130] (784s left) prime_mmap_coherency (write) [294.467770] Starting subtest: write [299.847251] Subtest write: SUCCESS (5.377s) [300.014746] [039/130] (778s left) gem_exec_schedule (preempt-queue-contexts-chain) [300.101142] Subtest preempt-queue-contexts-chain: SKIP (0.000s) [300.379957] [040/130] (778s left) i915_query (engine-info-invalid) [300.440399] Starting subtest: engine-info-invalid [300.442882] Subtest engine-info-invalid: SUCCESS (0.000s) [300.535416] [041/130] (777s left) kms_joiner (basic-force-ultra-joiner) [300.648475] Starting subtest: basic-force-ultra-joiner [300.652628] Subtest basic-force-ultra-joiner: SKIP (0.000s) [300.703341] [042/130] (777s left) gem_pxp (reject-modify-context-protection-on) [300.833557] Starting subtest: reject-modify-context-protection-on [300.835618] Subtest reject-modify-context-protection-on: SUCCESS (0.002s) [301.937049] [043/130] (776s left) kms_flip (2x-modeset-vs-vblank-race) [302.041063] Starting subtest: 2x-modeset-vs-vblank-race [302.051540] Subtest 2x-modeset-vs-vblank-race: SKIP (0.010s) [302.111717] [044/130] (776s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip) [302.220142] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip [302.777439] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.559s) [302.927196] [045/130] (775s left) gem_exec_reloc (basic-gtt-cpu-noreloc) [302.993330] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s) [303.053596] [046/130] (775s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c) [303.111527] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s) [303.156697] [047/130] (775s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc) [303.269071] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc [305.663122] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (2.398s) [305.781215] [048/130] (772s left) gem_softpin (evict-prime) [305.835054] Starting subtest: evict-prime [305.836971] Starting dynamic subtest: rcs0 [307.022827] Dynamic subtest rcs0: SUCCESS (1.184s) [307.033315] Starting dynamic subtest: bcs0 [308.148898] Dynamic subtest bcs0: SUCCESS (1.127s) [308.159056] Starting dynamic subtest: vcs0 [309.465091] Dynamic subtest vcs0: SUCCESS (1.313s) [309.477983] Starting dynamic subtest: vcs1 [311.497145] Dynamic subtest vcs1: SUCCESS (2.030s) [311.513043] Starting dynamic subtest: vecs0 [313.506639] Dynamic subtest vecs0: SUCCESS (2.008s) [313.519617] Starting dynamic subtest: ccs0 [314.673816] Dynamic subtest ccs0: SUCCESS (1.167s) [314.685084] Starting dynamic subtest: all [316.497207] Dynamic subtest all: SUCCESS (1.820s) [316.499684] Subtest evict-prime: SUCCESS (10.656s) [316.557968] [049/130] (762s left) syncobj_timeline (wait-for-submit-snapshot) [316.618172] Starting subtest: wait-for-submit-snapshot [316.698703] Subtest wait-for-submit-snapshot: SUCCESS (0.082s) [316.752546] [050/130] (761s left) kms_psr (pr-sprite-blt) [316.847923] Starting subtest: pr-sprite-blt [316.852508] Subtest pr-sprite-blt: SKIP (0.000s) [316.902010] [051/130] (761s left) gem_exec_gttfill (multigpu-basic) [317.077277] Starting subtest: multigpu-basic [333.728155] Subtest multigpu-basic: SUCCESS (16.644s) [333.755540] [052/130] (744s left) kms_rotation_crc (exhaust-fences) [333.898961] Starting subtest: exhaust-fences [333.903526] Subtest exhaust-fences: SKIP (0.000s) [333.953021] [053/130] (744s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions) [334.075097] Starting subtest: cursorB-vs-flipB-atomic-transitions [334.079496] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s) [334.169321] [054/130] (744s left) kms_cursor_crc (cursor-rapid-movement-64x21) [334.355492] Starting subtest: cursor-rapid-movement-64x21 [334.392142] Subtest cursor-rapid-movement-64x21: SKIP (0.031s) [334.582982] [055/130] (744s left) kms_psr (psr2-sprite-blt) [334.672925] Starting subtest: psr2-sprite-blt [334.675241] Starting dynamic subtest: eDP-1 [336.418949] Dynamic subtest eDP-1: SUCCESS (1.744s) [336.421480] Subtest psr2-sprite-blt: SUCCESS (1.744s) [337.269883] [056/130] (741s left) kms_chamelium_hpd (dp-hpd-after-suspend) [337.669070] Subtest dp-hpd-after-suspend: SKIP (0.000s) [338.478441] [057/130] (740s left) kms_flip (nonexisting-fb-interruptible) [338.584933] Starting subtest: nonexisting-fb-interruptible [338.587353] Starting dynamic subtest: A-eDP1 [339.702292] Dynamic subtest A-eDP1: SUCCESS (1.116s) [339.712357] Starting dynamic subtest: B-eDP1 [340.824512] Dynamic subtest B-eDP1: SUCCESS (1.121s) [340.835813] Starting dynamic subtest: C-eDP1 [341.955260] Dynamic subtest C-eDP1: SUCCESS (1.130s) [341.966124] Starting dynamic subtest: D-eDP1 [343.073482] Dynamic subtest D-eDP1: SUCCESS (1.117s) [343.078661] Subtest nonexisting-fb-interruptible: SUCCESS (4.492s) [344.215819] [058/130] (734s left) kms_sequence (get-forked-busy) [344.314899] Starting subtest: get-forked-busy [344.317280] Starting dynamic subtest: pipe-A-eDP-1 [346.239999] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.921s) [346.252028] Starting dynamic subtest: pipe-B-eDP-1 [348.824387] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.584s) [348.836689] Starting dynamic subtest: pipe-C-eDP-1 [351.446537] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.618s) [351.457837] Starting dynamic subtest: pipe-D-eDP-1 [354.054421] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.608s) [354.056827] Subtest get-forked-busy: SUCCESS (9.736s) [354.827452] [059/130] (724s left) gem_create (create-ext-set-pat) [355.000787] Starting subtest: create-ext-set-pat [355.003771] Subtest create-ext-set-pat: SUCCESS (0.001s) [355.089392] [060/130] (723s left) kms_atomic (plane-overlay-legacy) [355.191959] Starting subtest: plane-overlay-legacy [355.194296] Starting dynamic subtest: pipe-A-eDP-1 [355.603509] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.409s) [355.605992] Subtest plane-overlay-legacy: SUCCESS (0.410s) [356.451535] [061/130] (722s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move) [356.561927] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move [356.566026] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s) [356.664799] [062/130] (722s left) kms_flip (2x-flip-vs-fences-interruptible) [356.767996] Starting subtest: 2x-flip-vs-fences-interruptible [356.772455] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s) [356.865171] [063/130] (722s left) gem_lmem_swapping (heavy-verify-random-ccs) [356.928430] Subtest heavy-verify-random-ccs: SKIP (0.000s) [356.950437] [064/130] (722s left) kms_frontbuffer_tracking (fbc-indfb-scaledprimary) [357.060422] Starting subtest: fbc-indfb-scaledprimary [361.136494] Subtest fbc-indfb-scaledprimary: SUCCESS (4.078s) [361.266950] [065/130] (717s left) kms_ccs (bad-rotation-90-4-tiled-lnl-ccs) [361.371939] Starting subtest: bad-rotation-90-4-tiled-lnl-ccs [361.376484] Subtest bad-rotation-90-4-tiled-lnl-ccs: SKIP (0.000s) [362.498289] [066/130] (716s left) kms_psr (fbc-psr2-sprite-plane-onoff) [362.600029] Starting subtest: fbc-psr2-sprite-plane-onoff [362.602321] Starting dynamic subtest: eDP-1 [362.606568] Dynamic subtest eDP-1: SKIP (0.000s) [362.610807] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s) [362.654635] [067/130] (716s left) kms_cursor_crc (cursor-onscreen-max-size) [362.932781] Starting subtest: cursor-onscreen-max-size [362.940047] Subtest cursor-onscreen-max-size: SKIP (0.000s) [363.064460] [068/130] (716s left) gem_pread (self) [363.115478] Subtest self: SKIP (0.000s) [363.161473] [069/130] (715s left) kms_cursor_edge_walk (128x128-top-edge) [363.264933] Starting subtest: 128x128-top-edge [363.267182] Starting dynamic subtest: pipe-A-eDP-1 [366.671773] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.403s) [366.681307] Starting dynamic subtest: pipe-D-eDP-1 [369.804352] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.129s) [369.806884] Subtest 128x128-top-edge: SUCCESS (6.534s) [370.578951] [070/130] (708s left) kms_vblank (ts-continuation-modeset-hang) [370.677062] Starting subtest: ts-continuation-modeset-hang [370.679335] Starting dynamic subtest: pipe-A-eDP-1 [397.226478] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.540s) [397.241621] Starting dynamic subtest: pipe-D-eDP-1 [412.274796] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.073s) [412.277199] Subtest ts-continuation-modeset-hang: SUCCESS (41.618s) [413.060144] [071/130] (666s left) kms_chamelium_edid (dp-edid-change-during-suspend) [413.445356] Subtest dp-edid-change-during-suspend: SKIP (0.000s) [414.260359] [072/130] (664s left) kms_big_fb (y-tiled-addfb) [414.380765] Starting subtest: y-tiled-addfb [414.385067] Subtest y-tiled-addfb: SKIP (0.000s) [414.475685] [073/130] (664s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt) [414.587632] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt [414.591901] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [414.672760] [074/130] (664s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf) [419.631318] Starting subtest: fbc-psr2-cursor-plane-update-sf [419.633716] Starting dynamic subtest: pipe-A-eDP-1 [420.434272] Dynamic subtest pipe-A-eDP-1: SKIP (0.800s) [420.444926] Starting dynamic subtest: pipe-B-eDP-1 [421.557887] Dynamic subtest pipe-B-eDP-1: SKIP (1.123s) [421.562566] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.926s) [422.714169] [075/130] (656s left) kms_setmode (basic) [422.776392] Starting subtest: basic [423.090344] Starting dynamic subtest: pipe-A-eDP-1 [425.272266] Dynamic subtest pipe-A-eDP-1: FAIL (2.074s) [425.493240] Starting dynamic subtest: pipe-B-eDP-1 [427.691250] Dynamic subtest pipe-B-eDP-1: FAIL (2.086s) [427.870834] Subtest basic: FAIL (5.100s) [428.685477] [076/130] (650s left) perf_pmu (busy-hang) [428.729291] Starting subtest: busy-hang [428.731451] Starting dynamic subtest: rcs0 [429.998053] Dynamic subtest rcs0: SUCCESS (1.267s) [430.009351] Starting dynamic subtest: bcs0 [431.242096] Dynamic subtest bcs0: SUCCESS (1.243s) [431.252585] Starting dynamic subtest: vcs0 [433.031201] Dynamic subtest vcs0: SUCCESS (1.788s) [433.042085] Starting dynamic subtest: vcs1 [433.794785] Dynamic subtest vcs1: FAIL (0.691s) [433.805970] Starting dynamic subtest: vecs0 [436.998400] Dynamic subtest vecs0: SUCCESS (3.276s) [437.009641] Starting dynamic subtest: ccs0 [438.239516] Dynamic subtest ccs0: SUCCESS (1.240s) [438.241397] Subtest busy-hang: FAIL (9.515s) [438.343120] [077/130] (640s left) gem_tiled_blits (normal) [438.403028] Subtest normal: SKIP (0.000s) [438.457335] [078/130] (640s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-wc) [438.554451] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-wc [442.904966] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (4.353s) [443.008569] [079/130] (636s left) api_intel_allocator (fork-simple-stress-signal) [443.057798] Starting subtest: fork-simple-stress-signal [448.467326] Subtest fork-simple-stress-signal: SUCCESS (5.413s) [448.569274] [080/130] (630s left) gem_exec_params (invalid-bsd1-flag-on-blt) [448.632692] Starting subtest: invalid-bsd1-flag-on-blt [448.635058] Subtest invalid-bsd1-flag-on-blt: SUCCESS (0.000s) [448.728151] [081/130] (630s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-render) [448.847413] Starting subtest: psr-1p-primscrn-cur-indfb-draw-render [453.298721] Subtest psr-1p-primscrn-cur-indfb-draw-render: SUCCESS (4.456s) [453.425145] [082/130] (625s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc) [453.531997] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc [453.534284] Starting dynamic subtest: pipe-A-eDP-1 [454.336693] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.795s) [454.346467] Starting dynamic subtest: pipe-B-eDP-1 [455.913766] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.577s) [455.923878] Starting dynamic subtest: pipe-C-eDP-1 [457.384451] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.469s) [457.396421] Starting dynamic subtest: pipe-D-eDP-1 [458.801379] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.418s) [458.803601] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.263s) [459.972692] [083/130] (619s left) kms_ccs (missing-ccs-buffer-yf-tiled-ccs) [460.072917] Starting subtest: missing-ccs-buffer-yf-tiled-ccs [460.075243] Starting dynamic subtest: pipe-A-eDP-1 [460.099003] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [460.110536] Starting dynamic subtest: pipe-B-eDP-1 [460.132206] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [460.142121] Starting dynamic subtest: pipe-C-eDP-1 [460.164609] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [460.175169] Starting dynamic subtest: pipe-D-eDP-1 [460.197623] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [460.201821] Subtest missing-ccs-buffer-yf-tiled-ccs: SKIP (0.004s) [462.216250] [084/130] (617s left) gem_mmap_gtt (basic-wc) [462.271265] Subtest basic-wc: SKIP (0.000s) [462.306867] [085/130] (617s left) kms_pm_backlight (basic-brightness) [462.403808] Starting subtest: basic-brightness [462.448276] Starting dynamic subtest: eDP-1 [462.949478] Dynamic subtest eDP-1: SUCCESS (0.500s) [462.951906] Subtest basic-brightness: SUCCESS (0.548s) [463.737084] [086/130] (615s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip) [463.847053] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip [464.459170] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.614s) [464.610423] [087/130] (614s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip) [464.721870] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip [465.478649] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.759s) [465.630282] [088/130] (613s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite) [465.738651] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite [470.787982] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (5.054s) [470.899741] [089/130] (608s left) syncobj_timeline (single-wait-available-signaled) [470.962730] Starting subtest: single-wait-available-signaled [470.965113] Subtest single-wait-available-signaled: SUCCESS (0.000s) [471.057225] [090/130] (608s left) syncobj_timeline (etime-single-wait-submitted) [471.117021] Starting subtest: etime-single-wait-submitted [471.222411] Subtest etime-single-wait-submitted: SUCCESS (0.105s) [471.279966] [091/130] (608s left) gem_wait (write-wait) [471.353348] Starting subtest: write-wait [471.355819] Starting dynamic subtest: all-engines [472.487550] Dynamic subtest all-engines: SUCCESS (1.134s) [472.500433] Starting dynamic subtest: rcs0 [473.553180] Dynamic subtest rcs0: SUCCESS (1.063s) [473.567560] Starting dynamic subtest: bcs0 [474.618739] Dynamic subtest bcs0: SUCCESS (1.063s) [474.638008] Starting dynamic subtest: vcs0 [475.673322] Dynamic subtest vcs0: SUCCESS (1.053s) [475.690029] Starting dynamic subtest: vcs1 [476.727143] Dynamic subtest vcs1: SUCCESS (1.053s) [476.743658] Starting dynamic subtest: vecs0 [477.783273] Dynamic subtest vecs0: SUCCESS (1.054s) [477.799963] Starting dynamic subtest: ccs0 [478.841311] Dynamic subtest ccs0: SUCCESS (1.056s) [478.843781] Subtest write-wait: SUCCESS (7.490s) [479.029805] [092/130] (600s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt) [479.150747] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt [482.654312] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (3.509s) [482.771656] [093/130] (596s left) sriov_basic (enable-vfs-autoprobe-off) [482.822430] Starting subtest: enable-vfs-autoprobe-off [482.824819] Starting dynamic subtest: numvfs-1 [482.890369] Dynamic subtest numvfs-1: FAIL (0.005s) [482.909660] Starting dynamic subtest: numvfs-2 [482.972648] Dynamic subtest numvfs-2: FAIL (0.003s) [482.983987] Starting dynamic subtest: numvfs-3 [483.043807] Dynamic subtest numvfs-3: FAIL (0.003s) [483.053377] Starting dynamic subtest: numvfs-4 [483.112880] Dynamic subtest numvfs-4: FAIL (0.003s) [483.123396] Starting dynamic subtest: numvfs-5 [483.182434] Dynamic subtest numvfs-5: FAIL (0.003s) [483.192520] Starting dynamic subtest: numvfs-6 [483.252224] Dynamic subtest numvfs-6: FAIL (0.003s) [483.261785] Starting dynamic subtest: numvfs-7 [483.322164] Dynamic subtest numvfs-7: FAIL (0.003s) [483.330831] Starting dynamic subtest: numvfs-random [483.389670] Dynamic subtest numvfs-random: FAIL (0.004s) [483.399875] Starting dynamic subtest: numvfs-all [483.459181] Dynamic subtest numvfs-all: FAIL (0.445s) [483.460885] Subtest enable-vfs-autoprobe-off: FAIL (0.479s) [483.478502] [094/130] (596s left) gen9_exec_parse (cmd-crossing-page) [483.543537] Subtest cmd-crossing-page: SKIP (0.000s) [483.609399] [095/130] (595s left) kms_vrr (flipline) [483.693304] Starting subtest: flipline [483.698967] Subtest flipline: SKIP (0.000s) [483.746644] [096/130] (595s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen) [483.860312] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen [488.265303] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.407s) [488.380873] [097/130] (591s left) kms_async_flips (invalid-async-flip) [488.479357] Starting subtest: invalid-async-flip [488.483860] Subtest invalid-async-flip: SKIP (0.000s) [489.664958] [098/130] (589s left) prime_mmap (test_reprime) [489.727621] Starting subtest: test_reprime [489.729878] Starting dynamic subtest: test_reprime-smem [489.756560] Dynamic subtest test_reprime-smem: SUCCESS (0.032s) [489.758930] Subtest test_reprime: SUCCESS (0.032s) [489.848769] [099/130] (589s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs) [489.947012] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs [489.951335] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s) [491.085019] [100/130] (588s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5) [491.178948] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5 [491.181117] Starting dynamic subtest: pipe-A [491.193369] Dynamic subtest pipe-A: SKIP (0.007s) [491.203896] Starting dynamic subtest: pipe-B [491.213213] Dynamic subtest pipe-B: SKIP (0.015s) [491.223147] Starting dynamic subtest: pipe-C [491.232619] Dynamic subtest pipe-C: SKIP (0.015s) [491.242059] Starting dynamic subtest: pipe-D [491.250905] Dynamic subtest pipe-D: SKIP (0.015s) [491.255259] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.054s) [491.326855] [101/130] (588s left) kms_content_protection (uevent) [491.464319] Starting subtest: uevent [494.840667] Subtest uevent: SKIP (3.374s) [495.998109] [102/130] (583s left) device_reset (cold-reset-bound) [496.042219] Starting subtest: cold-reset-bound [496.046957] Subtest cold-reset-bound: SKIP (0.000s) [496.086533] [103/130] (583s left) sw_sync (sync_busy_fork_unixsocket) [496.125475] Starting subtest: sync_busy_fork_unixsocket [496.131367] Subtest sync_busy_fork_unixsocket: SUCCESS (0.008s) [496.164669] [104/130] (583s left) prime_self_import (basic-with_fd_dup) [496.204121] Starting subtest: basic-with_fd_dup [496.228526] Subtest basic-with_fd_dup: SUCCESS (0.024s) [496.331582] [105/130] (583s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-move) [496.435369] Starting subtest: psr-2p-scndscrn-spr-indfb-move [496.439543] Subtest psr-2p-scndscrn-spr-indfb-move: SKIP (0.000s) [496.532451] [106/130] (583s left) i915_pm_rpm (system-suspend-devices) [498.652310] Starting subtest: system-suspend-devices [506.238811] Subtest system-suspend-devices: SUCCESS (7.587s) [506.578773] [107/130] (573s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset) [506.675088] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset [506.685686] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.010s) [506.745529] [108/130] (573s left) kms_big_fb (y-tiled-16bpp-rotate-0) [506.852025] Starting subtest: y-tiled-16bpp-rotate-0 [506.856496] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s) [506.952198] [109/130] (572s left) syncobj_timeline (multi-wait-for-submit-available-signaled) [507.017480] Starting subtest: multi-wait-for-submit-available-signaled [507.019807] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.001s) [507.110686] [110/130] (572s left) gem_mmap_gtt (isolation) [507.171544] Subtest isolation: SKIP (0.000s) [507.211990] [111/130] (572s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt) [507.315446] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt [507.319878] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s) [507.387751] [112/130] (572s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt) [507.485141] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt [507.489656] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [507.569184] [113/130] (572s left) kms_addfb_basic (basic) [507.628119] Starting subtest: basic [507.630169] Subtest basic: SUCCESS (0.000s) [507.765214] [114/130] (572s left) gem_exec_schedule (u-semaphore-user) [507.844252] Starting subtest: u-semaphore-user [507.901918] Subtest u-semaphore-user: SUCCESS (0.058s) [509.519509] [115/130] (570s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu) [509.619625] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu [509.624112] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [509.706218] [116/130] (570s left) kms_prime (basic-modeset-hybrid) [511.650705] Subtest basic-modeset-hybrid: SKIP (0.000s) [511.714032] [117/130] (568s left) kms_fbcon_fbt (fbc) [511.768605] Starting subtest: fbc [517.028792] Subtest fbc: SUCCESS (5.260s) [517.101068] [118/130] (562s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu) [517.202051] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu [521.993713] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.794s) [522.101102] [119/130] (557s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted) [522.165548] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted [522.270441] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.103s) [522.328143] [120/130] (557s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc) [522.436109] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc [522.440534] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [522.515833] [121/130] (557s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling) [522.617674] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling [522.620103] Starting dynamic subtest: pipe-A-default-mode [522.904464] Dynamic subtest pipe-A-default-mode: SKIP (0.283s) [522.909511] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.284s) [523.674149] [122/130] (556s left) kms_addfb_basic (bad-pitch-63) [523.728654] Starting subtest: bad-pitch-63 [523.731184] Subtest bad-pitch-63: SUCCESS (0.000s) [523.853925] [123/130] (556s left) syncobj_basic (test-valid-cycle) [523.912886] Starting subtest: test-valid-cycle [523.915273] Subtest test-valid-cycle: SUCCESS (0.000s) [523.990896] [124/130] (556s left) gem_exec_alignment (single) [524.055246] Starting subtest: single [524.057642] Subtest single: SUCCESS (0.002s) [524.163023] [125/130] (556s left) gem_mmap_wc (bad-object) [524.223694] Subtest bad-object: SKIP (0.000s) [524.260997] [126/130] (555s left) kms_pm_rpm (legacy-planes-dpms) [525.784455] Starting subtest: legacy-planes-dpms [525.787320] Starting dynamic subtest: plane-41 [534.056647] Dynamic subtest plane-41: SUCCESS (8.270s) [534.069575] Starting dynamic subtest: plane-50 [542.332795] Dynamic subtest plane-50: SUCCESS (8.275s) [542.345563] Starting dynamic subtest: plane-59 [550.598499] Dynamic subtest plane-59: SUCCESS (8.263s) [550.610384] Starting dynamic subtest: plane-68 [559.155825] Dynamic subtest plane-68: SUCCESS (8.555s) [559.158279] Subtest legacy-planes-dpms: SUCCESS (33.371s) [559.480590] [127/130] (520s left) gem_mmap_gtt (basic-write-gtt) [559.545128] Subtest basic-write-gtt: SKIP (0.000s) [559.592159] [128/130] (520s left) kms_psr (psr-cursor-render) [559.687362] Starting subtest: psr-cursor-render [559.689657] Starting dynamic subtest: eDP-1 [561.381832] Dynamic subtest eDP-1: SUCCESS (1.693s) [561.384066] Subtest psr-cursor-render: SUCCESS (1.694s) [562.224568] [129/130] (518s left) gem_ccs (block-copy-uncompressed) [562.284868] Starting subtest: block-copy-uncompressed [562.287313] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem [562.309302] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.023s) [562.321358] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem [562.327311] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s) [562.337091] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem [562.344953] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s) [562.354647] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem [562.361787] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.016s) [562.364452] Subtest block-copy-uncompressed: SUCCESS (0.077s) [562.474312] [130/130] (517s left) gem_madvise (dontneed-before-pwrite) [562.518282] Starting subtest: dontneed-before-pwrite [562.535439] Subtest dontneed-before-pwrite: SKIP (0.014s) [562.571156] Closing watchdogs Done.