[51.089947] Initializing watchdogs [51.090352] /dev/watchdog0 [51.107285] [001/142] (960s left) kms_universal_plane (universal-plane-pageflip-windowed-pipe-b) [51.443183] Starting subtest: universal-plane-pageflip-windowed-pipe-B [52.328615] Subtest universal-plane-pageflip-windowed-pipe-B: SUCCESS (0.887s) [52.662731] [002/142] (958s left) perf (non-zero-reason) [53.039579] Starting subtest: non-zero-reason [53.042221] Starting dynamic subtest: 0-rcs0 [88.052775] Dynamic subtest 0-rcs0: SUCCESS (35.012s) [88.054939] Subtest non-zero-reason: SUCCESS (35.013s) [88.099485] [003/142] (923s left) kms_flip (2x-flip-vs-expired-vblank-interruptible) [88.404226] Starting subtest: 2x-flip-vs-expired-vblank-interruptible [88.410471] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.003s) [88.501068] [004/142] (923s left) kms_psr (cursor_mmap_gtt) [88.712508] Subtest cursor_mmap_gtt: SKIP (0.000s) [88.765744] [005/142] (922s left) gem_render_copy (yf-tiled-to-vebox-linear) [88.990157] Starting subtest: yf-tiled-to-vebox-linear [88.994583] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s) [89.183314] [006/142] (922s left) gem_cs_tlb (engines) [89.402505] Starting subtest: engines [89.404843] Starting dynamic subtest: rcs0 [91.567504] Dynamic subtest rcs0: SUCCESS (2.163s) [91.582932] Starting dynamic subtest: bcs0 [93.736179] Dynamic subtest bcs0: SUCCESS (2.167s) [93.751085] Starting dynamic subtest: vcs0 [95.899982] Dynamic subtest vcs0: SUCCESS (2.163s) [95.914453] Starting dynamic subtest: vecs0 [98.068059] Dynamic subtest vecs0: SUCCESS (2.166s) [98.070152] Subtest engines: SUCCESS (8.661s) [98.183529] [007/142] (913s left) kms_sysfs_edid_timing [99.633984] [008/142] (912s left) v3d/v3d_wait_bo (used-bo) [99.883147] Subtest used-bo: SKIP (0.000s) [99.951725] [009/142] (911s left) kms_ccs (pipe-b-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc) [100.513657] Starting subtest: pipe-B-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc [100.520324] Subtest pipe-B-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc: SKIP (0.005s) [100.942436] [010/142] (910s left) debugfs_test (sysfs) [101.159168] Starting subtest: sysfs [101.259583] Subtest sysfs: SUCCESS (0.099s) [101.444224] [011/142] (910s left) perf_pmu (semaphore-wait) [101.659346] Starting subtest: semaphore-wait [101.661428] Starting dynamic subtest: rcs0 [102.178661] Dynamic subtest rcs0: SUCCESS (0.518s) [102.196259] Starting dynamic subtest: bcs0 [102.694474] Dynamic subtest bcs0: SUCCESS (0.516s) [102.712587] Starting dynamic subtest: vcs0 [103.210140] Dynamic subtest vcs0: SUCCESS (0.514s) [103.227537] Starting dynamic subtest: vecs0 [103.724185] Dynamic subtest vecs0: SUCCESS (0.514s) [103.726448] Subtest semaphore-wait: SUCCESS (2.064s) [103.828020] [012/142] (908s left) kms_chamelium_color (ctm-0-25) [104.133741] Subtest ctm-0-25: SKIP (0.000s) [104.176173] [013/142] (907s left) gem_mmap_gtt (isolation) [104.381933] Starting subtest: isolation [104.388919] Subtest isolation: SUCCESS (0.004s) [104.476955] [014/142] (907s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling) [104.786425] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling [104.789525] Starting dynamic subtest: pipe-A-valid-mode [105.195136] Dynamic subtest pipe-A-valid-mode: SKIP (0.412s) [105.199080] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SKIP (0.414s) [105.578538] [015/142] (906s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa [105.787384] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled [105.897217] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.110s) [105.978706] [016/142] (906s left) kms_cursor_crc (cursor-offscreen-128x128) [106.290800] Starting subtest: cursor-offscreen-128x128 [106.302117] Starting dynamic subtest: pipe-A-DP-1 [108.956774] Dynamic subtest pipe-A-DP-1: SUCCESS (2.654s) [109.051389] Starting dynamic subtest: pipe-C-DP-1 [111.856721] Dynamic subtest pipe-C-DP-1: SUCCESS (2.820s) [111.858846] Subtest cursor-offscreen-128x128: SUCCESS (5.566s) [112.223077] [017/142] (899s left) gem_pxp (reject-modify-context-protection-on) [114.949855] Subtest reject-modify-context-protection-on: SKIP (0.000s) [115.035255] [018/142] (897s left) vc4/vc4_purgeable_bo (free-purged-bo) [115.282564] Subtest free-purged-bo: SKIP (0.000s) [115.351678] [019/142] (896s left) kms_ccs (pipe-c-missing-ccs-buffer-y_tiled_gen12_mc_ccs) [115.977491] Starting subtest: pipe-C-missing-ccs-buffer-y_tiled_gen12_mc_ccs [115.982619] Subtest pipe-C-missing-ccs-buffer-y_tiled_gen12_mc_ccs: SKIP (0.000s) [116.335192] [020/142] (895s left) kms_psr2_sf (overlay-plane-move-continuous-exceed-fully-sf) [116.542383] Subtest overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s) [116.600591] [021/142] (895s left) kms_big_joiner (2x-modeset) [116.901852] Subtest 2x-modeset: SKIP (0.000s) [116.951498] [022/142] (895s left) gem_exec_schedule (noreorder-corked) [117.325546] Starting subtest: noreorder-corked [117.328294] Starting dynamic subtest: rcs0 [118.450335] Dynamic subtest rcs0: SUCCESS (1.127s) [118.470226] Starting dynamic subtest: bcs0 [119.578081] Dynamic subtest bcs0: SUCCESS (1.126s) [119.596151] Starting dynamic subtest: vcs0 [120.708877] Dynamic subtest vcs0: SUCCESS (1.130s) [120.733267] Starting dynamic subtest: vecs0 [121.838286] Dynamic subtest vecs0: SUCCESS (1.130s) [121.840386] Subtest noreorder-corked: SUCCESS (4.515s) [122.034855] [023/142] (890s left) vc4/vc4_wait_bo (unused-bo-0ns) [122.282493] Subtest unused-bo-0ns: SKIP (0.000s) [122.350472] [024/142] (890s left) kms_plane_scaling (plane-downscale-with-modifiers-factor-0-5) [122.654503] Starting subtest: plane-downscale-with-modifiers-factor-0-5 [122.656693] Starting dynamic subtest: pipe-A-DP-1 [124.055901] Dynamic subtest pipe-A-DP-1: SUCCESS (1.401s) [124.076088] Starting dynamic subtest: pipe-B-DP-1 [125.533337] Dynamic subtest pipe-B-DP-1: SUCCESS (1.472s) [125.550497] Starting dynamic subtest: pipe-C-DP-1 [126.600915] Dynamic subtest pipe-C-DP-1: SUCCESS (1.067s) [126.603529] Subtest plane-downscale-with-modifiers-factor-0-5: SUCCESS (3.948s) [126.991447] [025/142] (885s left) kms_ccs (pipe-c-ccs-on-another-bo-yf_tiled_ccs) [127.642908] Starting subtest: pipe-C-ccs-on-another-bo-yf_tiled_ccs [127.647913] Subtest pipe-C-ccs-on-another-bo-yf_tiled_ccs: SKIP (0.000s) [127.983797] [026/142] (884s left) kms_flip (2x-plain-flip-ts-check) [128.288353] Starting subtest: 2x-plain-flip-ts-check [128.292362] Subtest 2x-plain-flip-ts-check: SKIP (0.003s) [128.384323] [027/142] (884s left) gem_lmem_swapping (parallel-random-verify) [128.598380] Subtest parallel-random-verify: SKIP (0.000s) [128.650293] [028/142] (883s left) kms_plane_scaling (plane-upscale-with-modifiers-20x20) [128.951149] Starting subtest: plane-upscale-with-modifiers-20x20 [128.953243] Starting dynamic subtest: pipe-A-DP-1 [129.510978] Dynamic subtest pipe-A-DP-1: SUCCESS (0.560s) [129.535416] Starting dynamic subtest: pipe-B-DP-1 [130.309934] Dynamic subtest pipe-B-DP-1: SUCCESS (0.796s) [130.329248] Starting dynamic subtest: pipe-C-DP-1 [130.949041] Dynamic subtest pipe-C-DP-1: SUCCESS (0.636s) [130.951147] Subtest plane-upscale-with-modifiers-20x20: SUCCESS (2.002s) [131.362297] [029/142] (881s left) gem_ctx_shared (q-in-order) [131.595673] Starting subtest: Q-in-order [131.597879] Starting dynamic subtest: rcs0 [131.768891] Dynamic subtest rcs0: SUCCESS (0.172s) [131.795085] Starting dynamic subtest: bcs0 [131.879528] Dynamic subtest bcs0: SUCCESS (0.108s) [131.902957] Starting dynamic subtest: vcs0 [131.973104] Dynamic subtest vcs0: SUCCESS (0.094s) [131.994506] Starting dynamic subtest: vecs0 [132.064314] Dynamic subtest vecs0: SUCCESS (0.089s) [132.066532] Subtest Q-in-order: SUCCESS (0.467s) [132.296318] [030/142] (880s left) gem_exec_reloc (basic-write-gtt-noreloc) [132.514654] Starting subtest: basic-write-gtt-noreloc [132.525585] Subtest basic-write-gtt-noreloc: SUCCESS (0.010s) [132.712571] [031/142] (880s left) syncobj_wait (multi-wait-all-submitted) [132.925701] Starting subtest: multi-wait-all-submitted [133.034183] Subtest multi-wait-all-submitted: SUCCESS (0.109s) [133.112537] [032/142] (879s left) kms_frontbuffer_tracking (fbcpsr-suspend) [133.429293] Starting subtest: fbcpsr-suspend [133.433104] Subtest fbcpsr-suspend: SKIP (0.000s) [133.528774] [033/142] (879s left) gem_mmap_wc (write-wc-read-gtt) [133.737934] Starting subtest: write-wc-read-gtt [134.280992] Subtest write-wc-read-gtt: SUCCESS (0.543s) [134.395413] [034/142] (878s left) kms_content_protection (atomic) [134.738214] Starting subtest: atomic [134.775267] Starting dynamic subtest: pipe-A-DP-1 [255.729860] Per-test timeout exceeded. Killing the current test with SIGQUIT. [256.647632] Closing watchdogs [256.655979] Initializing watchdogs [256.656187] /dev/watchdog0 [256.678905] [035/142] (756s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu) [256.993982] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu [256.997941] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [257.096691] [036/142] (755s left) kms_rotation_crc (bad-pixel-format) [257.403934] Starting subtest: bad-pixel-format [259.217139] Subtest bad-pixel-format: SUCCESS (1.818s) [259.643279] [037/142] (753s left) kms_vblank (pipe-c-wait-idle-hang) [259.956282] Starting subtest: pipe-C-wait-idle-hang [267.010962] Subtest pipe-C-wait-idle-hang: SUCCESS (7.056s) [267.355159] [038/142] (745s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu) [267.676118] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu [269.897891] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (2.226s) [270.022182] [039/142] (743s left) kms_ccs (pipe-d-bad-aux-stride-4_tiled_mtl_rc_ccs_cc) [270.692233] Subtest pipe-D-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s) [271.006395] [040/142] (742s left) gem_ppgtt (blt-vs-render-ctxn) [271.254791] Starting subtest: blt-vs-render-ctxN [305.030137] Subtest blt-vs-render-ctxN: SUCCESS (33.775s) [305.144624] [041/142] (708s left) sysfs_heartbeat_interval (precise) [305.479981] Starting subtest: precise [305.482452] Starting dynamic subtest: vecs0 [306.982460] Dynamic subtest vecs0: SUCCESS (1.500s) [307.006427] Starting dynamic subtest: vcs0 [308.479481] Dynamic subtest vcs0: SUCCESS (1.497s) [308.503897] Starting dynamic subtest: bcs0 [309.978115] Dynamic subtest bcs0: SUCCESS (1.496s) [309.998213] Starting dynamic subtest: rcs0 [311.466189] Dynamic subtest rcs0: SUCCESS (1.487s) [311.468256] Subtest precise: SUCCESS (5.988s) [311.556794] [042/142] (701s left) i915_pm_rpm (gem-execbuf-stress-pc8) [313.550628] Starting subtest: gem-execbuf-stress-pc8 [313.596819] Subtest gem-execbuf-stress-pc8: SKIP (0.042s) [314.063877] [043/142] (699s left) gem_flink_basic (flink-lifetime) [314.271536] Starting subtest: flink-lifetime [314.273929] Subtest flink-lifetime: SUCCESS (0.003s) [314.379378] [044/142] (698s left) kms_chamelium_hpd (hdmi-hpd-enable-disable-mode) [314.791334] Subtest hdmi-hpd-enable-disable-mode: SKIP (0.000s) [315.077567] [045/142] (698s left) gem_softpin (allocator-nopin) [315.293517] Starting subtest: allocator-nopin [315.537697] Subtest allocator-nopin: SUCCESS (0.244s) [315.672754] [046/142] (697s left) gem_render_copy (y-tiled-ccs-to-y-tiled) [315.903400] Starting subtest: y-tiled-ccs-to-y-tiled [315.905519] Starting dynamic subtest: smem [316.210065] Dynamic subtest smem: SUCCESS (0.307s) [316.212126] Subtest y-tiled-ccs-to-y-tiled: SUCCESS (0.309s) [316.405766] [047/142] (697s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-cpu) [316.718332] Starting subtest: psr-rgb565-draw-mmap-cpu [316.722215] Subtest psr-rgb565-draw-mmap-cpu: SKIP (0.000s) [316.822547] [048/142] (696s left) api_intel_bb (crc32) [317.030335] Starting subtest: crc32 [317.034389] Subtest crc32: SKIP (0.000s) [317.138865] [049/142] (696s left) gem_spin_batch (resubmit-new) [317.371352] Starting subtest: resubmit-new [317.373513] Starting dynamic subtest: rcs0 [317.382965] Dynamic subtest rcs0: SUCCESS (0.010s) [317.405603] Starting dynamic subtest: bcs0 [317.408079] Dynamic subtest bcs0: SUCCESS (0.012s) [317.428782] Starting dynamic subtest: vcs0 [317.430790] Dynamic subtest vcs0: SUCCESS (0.008s) [317.450066] Starting dynamic subtest: vecs0 [317.451998] Dynamic subtest vecs0: SUCCESS (0.008s) [317.453962] Subtest resubmit-new: SUCCESS (0.046s) [317.655657] [050/142] (695s left) kms_flip (plain-flip-fb-recreate-interruptible) [317.957218] Starting subtest: plain-flip-fb-recreate-interruptible [317.962635] Starting dynamic subtest: A-DP1 [328.767244] Dynamic subtest A-DP1: SUCCESS (10.805s) [328.787310] Starting dynamic subtest: B-DP1 [339.488471] Dynamic subtest B-DP1: SUCCESS (10.719s) [339.510911] Starting dynamic subtest: C-DP1 [350.211485] Dynamic subtest C-DP1: SUCCESS (10.721s) [350.214131] Subtest plain-flip-fb-recreate-interruptible: SUCCESS (32.257s) [350.630052] [051/142] (662s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_gen12_rc_ccs) [351.311270] Subtest pipe-D-bad-pixel-format-y_tiled_gen12_rc_ccs: SKIP (0.000s) [351.628998] [052/142] (661s left) kms_ccs (pipe-b-bad-aux-stride-4_tiled_mtl_rc_ccs_cc) [352.186312] Starting subtest: pipe-B-bad-aux-stride-4_tiled_mtl_rc_ccs_cc [352.190677] Subtest pipe-B-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s) [352.628820] [053/142] (661s left) perf (non-sampling-read-error) [352.985587] Starting subtest: non-sampling-read-error [353.027891] Subtest non-sampling-read-error: SUCCESS (0.043s) [353.077951] [054/142] (660s left) gem_exec_reloc (basic-gtt-cpu) [353.298608] Starting subtest: basic-gtt-cpu [353.321074] Subtest basic-gtt-cpu: SUCCESS (0.022s) [353.513346] [055/142] (660s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt) [353.828032] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt [353.832031] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s) [353.928926] [056/142] (659s left) syncobj_wait (invalid-reset-bad-pad) [354.140813] Starting subtest: invalid-reset-bad-pad [354.143468] Subtest invalid-reset-bad-pad: SUCCESS (0.000s) [354.228559] [057/142] (659s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt) [354.550378] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt [354.554277] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s) [354.662786] [058/142] (659s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-render) [354.978440] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-render [354.982350] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s) [355.078621] [059/142] (658s left) kms_ccs (pipe-d-crc-sprite-planes-basic-yf_tiled_ccs) [355.760072] Subtest pipe-D-crc-sprite-planes-basic-yf_tiled_ccs: SKIP (0.000s) [356.079793] [060/142] (657s left) kms_big_fb (4-tiled-8bpp-rotate-180) [356.420994] Starting subtest: 4-tiled-8bpp-rotate-180 [356.426126] Subtest 4-tiled-8bpp-rotate-180: SKIP (0.000s) [356.529397] [061/142] (657s left) gem_exec_fence (submit-chain) [356.897219] Starting subtest: submit-chain [356.921825] Subtest submit-chain: SUCCESS (0.025s) [357.596624] [062/142] (656s left) kms_async_flips (crc) [357.910073] Starting subtest: crc [358.357086] Starting dynamic subtest: pipe-A-DP-1 [362.226799] Dynamic subtest pipe-A-DP-1: FAIL (3.177s) [362.248614] Starting dynamic subtest: pipe-B-DP-1 [365.162701] Dynamic subtest pipe-B-DP-1: FAIL (2.550s) [365.184269] Starting dynamic subtest: pipe-C-DP-1 [368.059286] Dynamic subtest pipe-C-DP-1: FAIL (2.549s) [368.061382] Subtest crc: FAIL (9.606s) [368.124331] [063/142] (645s left) gem_render_linear_blits (basic) [368.343605] Starting subtest: basic [368.424398] Subtest basic: SUCCESS (0.080s) [368.501432] [064/142] (645s left) gem_create (create-valid-nonaligned) [368.709643] Starting subtest: create-valid-nonaligned [368.711857] Subtest create-valid-nonaligned: SUCCESS (0.000s) [368.936361] [065/142] (645s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move) [369.255040] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move [369.258949] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SKIP (0.000s) [369.352803] [066/142] (644s left) gem_eio (create-ext) [369.595366] Starting subtest: create-ext [369.637302] Subtest create-ext: SUCCESS (0.042s) [369.836785] [067/142] (644s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted) [370.048543] Starting subtest: etime-multi-wait-for-submit-unsubmitted [370.153170] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.104s) [370.251690] [068/142] (644s left) syncobj_timeline (multi-wait-for-submit-available-submitted) [370.463968] Starting subtest: multi-wait-for-submit-available-submitted [370.466128] Subtest multi-wait-for-submit-available-submitted: SUCCESS (0.002s) [370.551264] [069/142] (643s left) kms_big_fb (y-tiled-32bpp-rotate-90) [370.884534] Starting subtest: y-tiled-32bpp-rotate-90 [375.826150] Subtest y-tiled-32bpp-rotate-90: SUCCESS (4.940s) [376.353591] [070/142] (638s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs) [377.055522] Subtest pipe-D-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs: SKIP (0.000s) [377.383772] [071/142] (637s left) gem_exec_schedule (independent) [377.622875] Starting subtest: independent [377.625314] Starting dynamic subtest: rcs0 [377.810971] Dynamic subtest rcs0: SUCCESS (0.187s) [377.839394] Starting dynamic subtest: bcs0 [378.002697] Dynamic subtest bcs0: SUCCESS (0.189s) [378.033121] Starting dynamic subtest: vcs0 [378.183094] Dynamic subtest vcs0: SUCCESS (0.178s) [378.213275] Starting dynamic subtest: vecs0 [378.353869] Dynamic subtest vecs0: SUCCESS (0.168s) [378.356165] Subtest independent: SUCCESS (0.733s) [378.700500] [072/142] (635s left) kms_lease (implicit-plane-lease) [379.007674] Starting subtest: implicit-plane-lease [379.009956] Subtest implicit-plane-lease: SUCCESS (0.004s) [379.099774] [073/142] (635s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt) [379.417401] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt [379.422124] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [379.516433] [074/142] (635s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling) [379.832469] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling [379.834903] Starting dynamic subtest: pipe-A-valid-mode [379.941116] Dynamic subtest pipe-A-valid-mode: SKIP (0.105s) [379.947209] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.110s) [380.273892] [075/142] (634s left) kms_psr_stress_test (flip-primary-invalidate-overlay) [380.485832] Subtest flip-primary-invalidate-overlay: SKIP (0.000s) [380.555537] [076/142] (634s left) i915_pm_backlight (fade-with-suspend) [380.867188] Subtest fade-with-suspend: SKIP (0.000s) [380.923646] [077/142] (633s left) i915_hangman (detector) [381.151395] Starting subtest: detector [381.154227] Starting dynamic subtest: rcs0 [390.367678] Dynamic subtest rcs0: SUCCESS (9.217s) [390.392453] Starting dynamic subtest: bcs0 [400.302679] Dynamic subtest bcs0: SUCCESS (9.932s) [400.328514] Starting dynamic subtest: vcs0 [410.280893] Dynamic subtest vcs0: SUCCESS (9.975s) [410.307620] Starting dynamic subtest: vecs0 [420.265932] Dynamic subtest vecs0: SUCCESS (9.982s) [420.268490] Subtest detector: SUCCESS (39.117s) [420.408218] [078/142] (594s left) kms_big_fb (yf-tiled-16bpp-rotate-0) [420.755255] Starting subtest: yf-tiled-16bpp-rotate-0 [424.345996] Subtest yf-tiled-16bpp-rotate-0: SUCCESS (3.590s) [425.143742] [079/142] (589s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc) [425.471204] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc [425.475139] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [425.574375] [080/142] (589s left) syncobj_timeline (invalid-single-wait-available-unsubmitted) [425.783294] Starting subtest: invalid-single-wait-available-unsubmitted [425.785713] Subtest invalid-single-wait-available-unsubmitted: SUCCESS (0.000s) [425.873601] [081/142] (589s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size) [426.186590] Starting subtest: cursorB-vs-flipA-varying-size [426.191158] Subtest cursorB-vs-flipA-varying-size: SKIP (0.004s) [426.274305] [082/142] (588s left) kms_flip (2x-flip-vs-dpms) [426.556466] Starting subtest: 2x-flip-vs-dpms [426.560454] Subtest 2x-flip-vs-dpms: SKIP (0.003s) [426.703104] [083/142] (588s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_mtl_rc_ccs) [427.012934] Starting subtest: pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs [427.019305] Subtest pipe-A-bad-pixel-format-4_tiled_mtl_rc_ccs: SKIP (0.000s) [427.708484] [084/142] (587s left) kms_ccs (pipe-a-crc-primary-basic-4_tiled_dg2_mc_ccs) [428.110502] Starting subtest: pipe-A-crc-primary-basic-4_tiled_dg2_mc_ccs [428.114464] Subtest pipe-A-crc-primary-basic-4_tiled_dg2_mc_ccs: SKIP (0.001s) [428.693957] [085/142] (586s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0) [429.021326] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0 [430.448143] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.427s) [430.611837] [086/142] (584s left) gem_exec_store (basic) [430.844410] Starting subtest: basic [430.917884] Subtest basic: SUCCESS (0.073s) [431.127698] [087/142] (584s left) v3d/v3d_submit_cl (bad-perfmon) [431.401531] Subtest bad-perfmon: SKIP (0.000s) [431.476916] [088/142] (583s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_mtl_rc_ccs) [431.956783] Starting subtest: pipe-B-bad-rotation-90-4_tiled_mtl_rc_ccs [431.961923] Subtest pipe-B-bad-rotation-90-4_tiled_mtl_rc_ccs: SKIP (0.000s) [432.456993] [089/142] (582s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [432.777678] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu [432.781630] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [432.890360] [090/142] (582s left) gem_exec_balancer (parallel-out-fence) [436.941693] Subtest parallel-out-fence: SKIP (0.000s) [437.040226] [091/142] (578s left) kms_cursor_edge_walk (64x64-right-edge) [437.348152] Starting subtest: 64x64-right-edge [437.350254] Starting dynamic subtest: pipe-A-DP-1 [441.012272] Dynamic subtest pipe-A-DP-1: SUCCESS (3.664s) [441.039293] Starting dynamic subtest: pipe-C-DP-1 [444.487911] Dynamic subtest pipe-C-DP-1: SUCCESS (3.471s) [444.490381] Subtest 64x64-right-edge: SUCCESS (7.146s) [444.820887] [092/142] (570s left) gem_ctx_param (set-priority-range) [445.030026] Starting subtest: set-priority-range [445.097926] Subtest set-priority-range: SUCCESS (0.070s) [445.200397] [093/142] (570s left) gem_mmap_wc (close) [445.407222] Starting subtest: close [445.494650] Subtest close: SUCCESS (0.089s) [445.581841] [094/142] (569s left) kms_properties (get_properties-sanity-non-atomic) [445.891605] Starting subtest: get_properties-sanity-non-atomic [445.894276] Subtest get_properties-sanity-non-atomic: SUCCESS (0.003s) [445.983352] [095/142] (569s left) i915_pm_rpm (drm-resources-equal) [447.784830] Starting subtest: drm-resources-equal [449.393236] Subtest drm-resources-equal: SUCCESS (1.602s) [449.612791] [096/142] (565s left) gem_eio (in-flight-internal-1us) [449.854563] Starting subtest: in-flight-internal-1us [449.951743] Subtest in-flight-internal-1us: SUCCESS (0.103s) [450.177429] [097/142] (565s left) gem_exec_nop (basic-parallel) [450.419599] Starting subtest: basic-parallel [453.570851] Subtest basic-parallel: SUCCESS (3.153s) [453.813549] [098/142] (561s left) syncobj_timeline (single-wait-signaled) [454.022993] Starting subtest: single-wait-signaled [454.025461] Subtest single-wait-signaled: SUCCESS (0.001s) [454.109343] [099/142] (561s left) gem_ctx_param (vm) [454.346308] Starting subtest: vm [454.383939] Subtest vm: SUCCESS (0.066s) [454.509937] [100/142] (561s left) kms_ccs (pipe-a-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs) [454.971627] Starting subtest: pipe-A-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs [454.979153] Subtest pipe-A-crc-sprite-planes-basic-4_tiled_mtl_mc_ccs: SKIP (0.005s) [455.508873] [101/142] (560s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0) [455.832073] Starting subtest: linear-max-hw-stride-64bpp-rotate-0 [457.312919] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.480s) [457.459991] [102/142] (558s left) kms_cursor_legacy (flip-vs-cursor-toggle) [457.771326] Starting subtest: flip-vs-cursor-toggle [477.246919] Subtest flip-vs-cursor-toggle: SUCCESS (19.475s) [477.600845] [103/142] (538s left) kms_draw_crc (draw-method-mmap-cpu) [477.910440] Starting subtest: draw-method-mmap-cpu [477.912517] Starting dynamic subtest: xrgb8888-untiled [478.202564] Dynamic subtest xrgb8888-untiled: SUCCESS (0.294s) [478.228167] Starting dynamic subtest: rgb565-untiled [478.470142] Dynamic subtest rgb565-untiled: SUCCESS (0.264s) [478.491130] Starting dynamic subtest: xrgb2101010-untiled [478.768954] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.297s) [478.793942] Starting dynamic subtest: xrgb8888-xtiled [478.952451] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.181s) [478.976991] Starting dynamic subtest: rgb565-xtiled [479.136177] Dynamic subtest rgb565-xtiled: SUCCESS (0.180s) [479.161846] Starting dynamic subtest: xrgb2101010-xtiled [479.336365] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.197s) [479.361352] Starting dynamic subtest: xrgb8888-ytiled [479.535630] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.197s) [479.559969] Starting dynamic subtest: rgb565-ytiled [479.718951] Dynamic subtest rgb565-ytiled: SUCCESS (0.181s) [479.743928] Starting dynamic subtest: xrgb2101010-ytiled [479.919070] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.197s) [479.921070] Subtest draw-method-mmap-cpu: SUCCESS (2.013s) [480.014625] [104/142] (535s left) gem_exec_store (dword) [480.247644] Starting subtest: dword [480.249836] Starting dynamic subtest: rcs0 [480.302092] Dynamic subtest rcs0: SUCCESS (0.052s) [480.329146] Starting dynamic subtest: bcs0 [480.331341] Dynamic subtest bcs0: SUCCESS (0.025s) [480.354735] Starting dynamic subtest: vcs0 [480.357033] Dynamic subtest vcs0: SUCCESS (0.019s) [480.379729] Starting dynamic subtest: vecs0 [480.381848] Dynamic subtest vecs0: SUCCESS (0.013s) [480.383940] Subtest dword: SUCCESS (0.121s) [480.609647] [105/142] (535s left) gen9_exec_parse (basic-rejected) [480.844166] Starting subtest: basic-rejected [480.849945] Subtest basic-rejected: SUCCESS (0.003s) [481.065708] [106/142] (534s left) kms_cursor_crc (cursor-rapid-movement-max-size) [481.397743] Starting subtest: cursor-rapid-movement-max-size [481.403957] Subtest cursor-rapid-movement-max-size: SKIP (0.000s) [481.515062] [107/142] (534s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats) [481.922396] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s) [482.220896] [108/142] (533s left) drm_fdinfo (most-busy-idle-check-all) [482.434560] Starting subtest: most-busy-idle-check-all [482.437461] Starting dynamic subtest: rcs0 [483.059742] Dynamic subtest rcs0: SUCCESS (0.624s) [483.086824] Starting dynamic subtest: bcs0 [483.689172] Dynamic subtest bcs0: SUCCESS (0.626s) [483.719793] Starting dynamic subtest: vcs0 [484.318369] Dynamic subtest vcs0: SUCCESS (0.626s) [484.347404] Starting dynamic subtest: vecs0 [484.945632] Dynamic subtest vecs0: SUCCESS (0.624s) [484.948194] Subtest most-busy-idle-check-all: SUCCESS (2.513s) [485.029875] [109/142] (531s left) kms_frontbuffer_tracking (fbc-badstride) [485.356392] Starting subtest: fbc-badstride [493.269307] Subtest fbc-badstride: SUCCESS (7.918s) [493.397817] [110/142] (522s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move) [493.718815] Starting subtest: fbc-2p-scndscrn-cur-indfb-move [493.723037] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s) [493.814643] [111/142] (522s left) gem_ccs (block-multicopy-inplace) [494.030594] Subtest block-multicopy-inplace: SKIP (0.000s) [494.096896] [112/142] (522s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-blt) [494.416033] Starting subtest: fbc-1p-offscren-pri-indfb-draw-blt [496.304651] Subtest fbc-1p-offscren-pri-indfb-draw-blt: SUCCESS (1.895s) [496.446807] [113/142] (519s left) gem_exec_reloc (basic-write-gtt-active) [496.665254] Starting subtest: basic-write-gtt-active [496.804949] Subtest basic-write-gtt-active: SUCCESS (0.138s) [496.998108] [114/142] (519s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_mtl_rc_ccs) [497.586561] Starting subtest: pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs [497.591165] Subtest pipe-C-bad-rotation-90-4_tiled_mtl_rc_ccs: SKIP (0.000s) [497.999290] [115/142] (518s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite) [498.313855] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite [498.317624] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [498.416359] [116/142] (518s left) kms_lease (lease_unleased_crtc) [498.790897] Starting subtest: lease_unleased_crtc [498.793188] Starting dynamic subtest: pipe-A-DP-1 [498.954425] Dynamic subtest pipe-A-DP-1: SUCCESS (0.166s) [498.981854] Starting dynamic subtest: pipe-B-DP-1 [499.057185] Dynamic subtest pipe-B-DP-1: SUCCESS (0.099s) [499.085028] Starting dynamic subtest: pipe-C-DP-1 [499.164943] Dynamic subtest pipe-C-DP-1: SUCCESS (0.102s) [499.167716] Subtest lease_unleased_crtc: SUCCESS (0.380s) [499.486785] [117/142] (517s left) kms_psr (psr2_primary_mmap_gtt) [499.701266] Subtest psr2_primary_mmap_gtt: SKIP (0.000s) [499.751653] [118/142] (516s left) i915_pm_rps (reset) [499.964416] Starting subtest: reset [503.541356] Subtest reset: SUCCESS (3.577s) [503.637283] [119/142] (512s left) kms_plane_multiple (tiling-y) [503.943332] Starting subtest: tiling-y [503.945425] Starting dynamic subtest: pipe-A-DP-1 [505.807084] Dynamic subtest pipe-A-DP-1: SUCCESS (1.859s) [505.829975] Starting dynamic subtest: pipe-B-DP-1 [507.875130] Dynamic subtest pipe-B-DP-1: SUCCESS (2.067s) [507.902802] Starting dynamic subtest: pipe-C-DP-1 [509.881430] Dynamic subtest pipe-C-DP-1: SUCCESS (2.001s) [509.883664] Subtest tiling-y: SUCCESS (5.940s) [510.228316] [120/142] (506s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt) [510.545455] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt [512.969314] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (2.424s) [513.111184] [121/142] (503s left) gem_exec_reloc (basic-write-cpu-active) [513.328758] Starting subtest: basic-write-cpu-active [513.468860] Subtest basic-write-cpu-active: SUCCESS (0.139s) [513.661424] [122/142] (503s left) kms_addfb_basic (unused-modifier) [513.872810] Starting subtest: unused-modifier [513.875029] Subtest unused-modifier: SUCCESS (0.000s) [514.078165] [123/142] (502s left) kms_atomic_transition (modeset-transition) [514.387496] Starting subtest: modeset-transition [514.389859] Starting dynamic subtest: 1x-outputs [519.357262] Dynamic subtest 1x-outputs: SUCCESS (4.971s) [519.483537] Subtest modeset-transition: SUCCESS (5.098s) [519.827414] [124/142] (497s left) feature_discovery (display-4x) [520.144901] Starting subtest: display-4x [520.149396] Subtest display-4x: SKIP (0.000s) [520.243306] [125/142] (496s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180) [520.570207] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180 [520.574164] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s) [520.675460] [126/142] (496s left) kms_vblank (pipe-d-wait-forked) [520.982569] Subtest pipe-D-wait-forked: SKIP (0.000s) [521.076228] [127/142] (495s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu) [521.396562] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu [521.400382] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [521.492539] [128/142] (495s left) kms_ccs (pipe-b-random-ccs-data-4_tiled_dg2_rc_ccs_cc) [522.023443] Starting subtest: pipe-B-random-ccs-data-4_tiled_dg2_rc_ccs_cc [522.030241] Subtest pipe-B-random-ccs-data-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s) [522.503045] [129/142] (494s left) v3d/v3d_perfmon (create-perfmon-exceed) [522.793289] Subtest create-perfmon-exceed: SKIP (0.000s) [522.869763] [130/142] (494s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt) [523.194794] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt [523.198805] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s) [523.302870] [131/142] (493s left) sw_sync (sync_multi_consumer_producer) [523.507508] Starting subtest: sync_multi_consumer_producer [524.485606] Subtest sync_multi_consumer_producer: SUCCESS (0.977s) [524.568980] [132/142] (492s left) api_intel_allocator (reopen) [524.774288] Starting subtest: reopen [524.780894] Subtest reopen: SUCCESS (0.006s) [524.902789] [133/142] (492s left) gem_exec_reloc (basic-wc-cpu-active) [525.121209] Starting subtest: basic-wc-cpu-active [525.262124] Subtest basic-wc-cpu-active: SUCCESS (0.140s) [525.453961] [134/142] (491s left) prime_vgem (basic-fence-flip) [525.688157] Starting subtest: basic-fence-flip [526.273204] Subtest basic-fence-flip: SUCCESS (0.585s) [526.403693] [135/142] (490s left) syncobj_wait (single-wait-all-signaled) [526.617684] Starting subtest: single-wait-all-signaled [526.620184] Subtest single-wait-all-signaled: SUCCESS (0.001s) [526.702907] [136/142] (490s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff) [527.024782] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff [527.029327] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [527.136127] [137/142] (490s left) gem_exec_create (legacy) [527.372843] Starting subtest: legacy [527.375258] Starting dynamic subtest: smem [529.439189] Dynamic subtest smem: SUCCESS (2.067s) [529.441243] Subtest legacy: SUCCESS (2.070s) [529.653772] [138/142] (487s left) gem_render_copy_redux (flink-interruptible) [529.896755] Starting subtest: flink-interruptible [534.714539] Subtest flink-interruptible: SUCCESS (4.820s) [534.837342] [139/142] (482s left) kms_getfb (getfb-addfb-different-handles) [535.052958] Starting subtest: getfb-addfb-different-handles [535.055422] Subtest getfb-addfb-different-handles: SUCCESS (0.000s) [535.152691] [140/142] (482s left) gem_mmap_gtt (basic-write-gtt) [535.360725] Starting subtest: basic-write-gtt [536.102868] Subtest basic-write-gtt: SUCCESS (0.743s) [536.218931] [141/142] (481s left) kms_flip (bo-too-big-interruptible) [536.527345] Starting subtest: bo-too-big-interruptible [536.533307] Starting dynamic subtest: A-DP1 [539.540700] Dynamic subtest A-DP1: SUCCESS (3.008s) [539.569202] Starting dynamic subtest: B-DP1 [542.456989] Dynamic subtest B-DP1: SUCCESS (2.912s) [542.483956] Starting dynamic subtest: C-DP1 [545.345860] Dynamic subtest C-DP1: SUCCESS (2.885s) [545.351903] Subtest bo-too-big-interruptible: SUCCESS (8.822s) [545.977483] [142/142] (471s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc) [546.308411] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc [548.130589] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (1.829s) [548.268645] Closing watchdogs Done.