[56.848963] Initializing watchdogs [56.849427] /dev/watchdog0 [56.864749] [001/141] (960s left) kms_selftest (all-tests) [57.286492] Starting subtest: all-tests [57.289530] Subtest all-tests: SKIP (0.000s) [57.357860] [002/141] (960s left) drm_fdinfo (busy) [57.580244] Starting subtest: busy [57.581785] Starting dynamic subtest: rcs0 [58.121690] Dynamic subtest rcs0: SUCCESS (0.542s) [58.135798] Starting dynamic subtest: bcs0 [58.658267] Dynamic subtest bcs0: SUCCESS (0.536s) [58.672266] Starting dynamic subtest: vcs0 [59.197166] Dynamic subtest vcs0: SUCCESS (0.538s) [59.210292] Starting dynamic subtest: vecs0 [59.727788] Dynamic subtest vecs0: SUCCESS (0.531s) [59.729443] Subtest busy: SUCCESS (2.148s) [59.805672] [003/141] (957s left) gem_ctx_isolation (dirty-create) [60.030522] Starting subtest: dirty-create [60.032058] Starting dynamic subtest: rcs0 [61.718600] Dynamic subtest rcs0: SUCCESS (1.685s) [61.731170] Starting dynamic subtest: bcs0 [63.892997] Dynamic subtest bcs0: SUCCESS (2.174s) [63.905804] Starting dynamic subtest: vcs0 [65.502697] Dynamic subtest vcs0: SUCCESS (1.610s) [65.516634] Starting dynamic subtest: vecs0 [67.108894] Dynamic subtest vecs0: SUCCESS (1.606s) [67.110339] Subtest dirty-create: SUCCESS (7.077s) [67.309377] [004/141] (950s left) kms_ccs (pipe-d-missing-ccs-buffer-y_tiled_gen12_rc_ccs) [68.028275] Subtest pipe-D-missing-ccs-buffer-y_tiled_gen12_rc_ccs: SKIP (0.000s) [68.343324] [005/141] (949s left) kms_plane_scaling (plane-downscale-with-pixel-format-factor-0-75) [68.642265] Starting subtest: plane-downscale-with-pixel-format-factor-0-75 [68.643620] Starting dynamic subtest: pipe-A-DP-1 [70.994381] Dynamic subtest pipe-A-DP-1: SUCCESS (2.351s) [71.011622] Starting dynamic subtest: pipe-B-DP-1 [73.596809] Dynamic subtest pipe-B-DP-1: SUCCESS (2.599s) [73.612659] Starting dynamic subtest: pipe-C-DP-1 [73.977737] Dynamic subtest pipe-C-DP-1: SUCCESS (0.378s) [73.979309] Subtest plane-downscale-with-pixel-format-factor-0-75: SUCCESS (5.338s) [74.367889] [006/141] (943s left) perf (invalid-oa-metric-set-id) [74.692402] Starting subtest: invalid-oa-metric-set-id [74.748648] Subtest invalid-oa-metric-set-id: SUCCESS (0.055s) [74.795454] [007/141] (942s left) gem_exec_reloc (basic-write-read-active) [75.007726] Starting subtest: basic-write-read-active [75.148251] Subtest basic-write-read-active: SUCCESS (0.139s) [75.331778] [008/141] (942s left) gem_exec_whisper (basic-forked-all) [75.558611] Starting subtest: basic-forked-all [97.652669] Subtest basic-forked-all: SUCCESS (22.094s) [97.851088] [009/141] (919s left) kms_universal_plane (universal-plane-pipe-a-sanity) [98.147531] Starting subtest: universal-plane-pipe-A-sanity [98.561039] Subtest universal-plane-pipe-A-sanity: SUCCESS (0.412s) [98.856979] [010/141] (918s left) syncobj_wait (invalid-single-wait-unsubmitted) [99.058564] Starting subtest: invalid-single-wait-unsubmitted [99.060504] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.001s) [99.140592] [011/141] (918s left) kms_prop_blob (invalid-get-prop-any) [99.338234] Starting subtest: invalid-get-prop-any [99.339750] Subtest invalid-get-prop-any: SUCCESS (0.000s) [99.422574] [012/141] (918s left) gem_mmap_wc (pf-nonblock) [99.621488] Starting subtest: pf-nonblock [99.626338] Subtest pf-nonblock: SUCCESS (0.005s) [99.739435] [013/141] (918s left) kms_ccs (pipe-a-random-ccs-data-y_tiled_ccs) [100.156704] Starting subtest: pipe-A-random-ccs-data-y_tiled_ccs [100.594423] Subtest pipe-A-random-ccs-data-y_tiled_ccs: SUCCESS (0.438s) [101.297965] [014/141] (916s left) kms_cursor_legacy (forked-bo) [101.597168] Starting subtest: forked-bo [101.598704] Starting dynamic subtest: pipe-A [123.165630] Dynamic subtest pipe-A: SUCCESS (21.567s) [123.181313] Starting dynamic subtest: pipe-B [144.755588] Dynamic subtest pipe-B: SUCCESS (21.590s) [144.772234] Starting dynamic subtest: pipe-C [166.328311] Dynamic subtest pipe-C: SUCCESS (21.572s) [166.345504] Starting dynamic subtest: all-pipes [187.886261] Dynamic subtest all-pipes: SUCCESS (21.557s) [187.887733] Subtest forked-bo: SUCCESS (86.287s) [187.964151] [015/141] (829s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-64bpp-yftile-upscaling) [188.261682] Starting subtest: flip-32bpp-yftile-to-64bpp-yftile-upscaling [188.263095] Starting dynamic subtest: pipe-A-valid-mode [188.382134] Dynamic subtest pipe-A-valid-mode: SKIP (0.118s) [188.385279] Subtest flip-32bpp-yftile-to-64bpp-yftile-upscaling: SKIP (0.121s) [188.701922] [016/141] (829s left) kms_psr (dpms) [188.903609] Subtest dpms: SKIP (0.000s) [188.952161] [017/141] (828s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-pwrite) [189.251683] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-pwrite [189.254356] Subtest fbcpsr-1p-offscren-pri-indfb-draw-pwrite: SKIP (0.000s) [189.334902] [018/141] (828s left) gem_spin_batch (resubmit-new) [189.555553] Starting subtest: resubmit-new [189.557376] Starting dynamic subtest: rcs0 [189.567830] Dynamic subtest rcs0: SUCCESS (0.011s) [189.586109] Starting dynamic subtest: bcs0 [189.587400] Dynamic subtest bcs0: SUCCESS (0.013s) [189.604650] Starting dynamic subtest: vcs0 [189.605981] Dynamic subtest vcs0: SUCCESS (0.008s) [189.622059] Starting dynamic subtest: vecs0 [189.623325] Dynamic subtest vecs0: SUCCESS (0.009s) [189.624571] Subtest resubmit-new: SUCCESS (0.043s) [189.836031] [019/141] (828s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_rc_ccs_cc) [190.312122] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs_cc [190.314734] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s) [190.832933] [020/141] (827s left) gem_softpin (noreloc-interruptible) [191.043835] Starting subtest: noreloc-interruptible [196.532405] Subtest noreloc-interruptible: SUCCESS (5.489s) [196.649602] [021/141] (821s left) kms_lease (lease_unleased_crtc) [196.952209] Starting subtest: lease_unleased_crtc [196.953977] Starting dynamic subtest: pipe-A-DP-1 [197.130059] Dynamic subtest pipe-A-DP-1: SUCCESS (0.179s) [197.151484] Starting dynamic subtest: pipe-B-DP-1 [197.238213] Dynamic subtest pipe-B-DP-1: SUCCESS (0.103s) [197.258423] Starting dynamic subtest: pipe-C-DP-1 [197.341081] Dynamic subtest pipe-C-DP-1: SUCCESS (0.099s) [197.342701] Subtest lease_unleased_crtc: SUCCESS (0.393s) [197.632869] [022/141] (820s left) syncobj_wait (single-wait-all-for-submit-signaled) [197.838230] Starting subtest: single-wait-all-for-submit-signaled [197.839627] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.001s) [197.915510] [023/141] (820s left) vgem_basic (bad-fence) [198.181139] Starting subtest: bad-fence [198.182593] Subtest bad-fence: SUCCESS (0.000s) [198.249064] [024/141] (819s left) kms_big_joiner (2x-modeset) [198.554308] Subtest 2x-modeset: SKIP (0.000s) [198.598557] [025/141] (819s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen) [198.901322] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen [198.903902] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [198.999222] [026/141] (819s left) gen9_exec_parse (valid-registers) [199.220893] Starting subtest: valid-registers [199.399080] Subtest valid-registers: SUCCESS (0.176s) [199.615822] [027/141] (818s left) gem_mmap_gtt (close-race) [199.814056] Starting subtest: close-race [219.920293] Subtest close-race: SUCCESS (20.104s) [220.016405] [028/141] (798s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-blt) [220.318725] Starting subtest: psr-2p-primscrn-pri-indfb-draw-blt [220.321256] Subtest psr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [220.416623] [029/141] (797s left) kms_cursor_legacy (short-flip-before-cursor-atomic-transitions-varying-size) [220.714217] Starting subtest: short-flip-before-cursor-atomic-transitions-varying-size [222.049962] Subtest short-flip-before-cursor-atomic-transitions-varying-size: SUCCESS (1.340s) [222.381957] [030/141] (795s left) gem_mmap_gtt (basic-small-copy-odd) [222.578841] Starting subtest: basic-small-copy-odd [226.751610] Subtest basic-small-copy-odd: SUCCESS (4.170s) [226.835967] [031/141] (791s left) kms_vblank (pipe-b-query-forked-hang) [227.247449] Starting subtest: pipe-B-query-forked-hang [234.989351] Subtest pipe-B-query-forked-hang: SUCCESS (7.742s) [235.337554] [032/141] (783s left) perf (invalid-create-userspace-config) [235.698317] Starting subtest: invalid-create-userspace-config [235.703166] Subtest invalid-create-userspace-config: SUCCESS (0.006s) [235.750835] [033/141] (782s left) perf (mi-rpc) [236.032880] Starting subtest: mi-rpc [236.087330] Subtest mi-rpc: SUCCESS (0.054s) [236.136297] [034/141] (782s left) kms_ccs (pipe-a-bad-aux-stride-4_tiled_mtl_rc_ccs_cc) [236.601424] Starting subtest: pipe-A-bad-aux-stride-4_tiled_mtl_rc_ccs_cc [236.603923] Subtest pipe-A-bad-aux-stride-4_tiled_mtl_rc_ccs_cc: SKIP (0.000s) [237.156564] [035/141] (781s left) gem_ccs (block-multicopy-compressed) [237.363933] Subtest block-multicopy-compressed: SKIP (0.000s) [237.423244] [036/141] (781s left) kms_big_fb (y-tiled-8bpp-rotate-0) [237.744542] Starting subtest: y-tiled-8bpp-rotate-0 [240.986211] Subtest y-tiled-8bpp-rotate-0: SUCCESS (3.240s) [241.290057] [037/141] (777s left) feature_discovery (display-4x) [241.572952] Starting subtest: display-4x [241.575575] Subtest display-4x: SKIP (0.000s) [241.655364] [038/141] (776s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render) [241.961144] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render [244.269183] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (2.310s) [244.389303] [039/141] (774s left) api_intel_bb (reset-flags) [244.592770] Starting subtest: reset-flags [244.613570] Subtest reset-flags: SUCCESS (0.022s) [244.721988] [040/141] (773s left) v3d/v3d_submit_cl (single-in-sync) [244.987190] Subtest single-in-sync: SKIP (0.000s) [245.056646] [041/141] (773s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0) [245.384304] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0 [245.387404] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s) [245.489084] [042/141] (773s left) kms_properties (invalid-properties-legacy) [245.789734] Starting subtest: invalid-properties-legacy [245.924436] Subtest invalid-properties-legacy: SUCCESS (0.137s) [246.005972] [043/141] (772s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite) [246.324051] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite [246.326644] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [246.422820] [044/141] (772s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc) [246.734736] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc [246.737488] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [246.839194] [045/141] (772s left) gem_exec_schedule (u-fairslice-all) [247.073109] Starting subtest: u-fairslice-all [249.190193] Subtest u-fairslice-all: SUCCESS (2.118s) [249.556881] [046/141] (769s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move) [249.868239] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move [249.870809] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SKIP (0.000s) [249.972805] [047/141] (768s left) kms_rotation_crc (primary-y-tiled-reflect-x-0) [250.276944] Starting subtest: primary-y-tiled-reflect-x-0 [250.279741] Subtest primary-y-tiled-reflect-x-0: SKIP (0.003s) [250.355915] [048/141] (768s left) kms_chamelium_hpd (hdmi-hpd-storm-disable) [250.753431] Subtest hdmi-hpd-storm-disable: SKIP (0.000s) [251.043737] [049/141] (767s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt) [251.352179] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt [253.685863] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (2.334s) [253.806501] [050/141] (765s left) gem_exec_big (single) [254.021172] Starting subtest: single [292.028619] Subtest single: SUCCESS (38.006s) [294.801299] [051/141] (724s left) kms_flip (2x-flip-vs-panning) [295.194276] Starting subtest: 2x-flip-vs-panning [295.200187] Subtest 2x-flip-vs-panning: SKIP (0.003s) [295.307377] [052/141] (723s left) kms_cursor_crc (cursor-random-128x42) [295.631696] Starting subtest: cursor-random-128x42 [295.643460] Starting dynamic subtest: pipe-A-DP-1 [299.650551] Dynamic subtest pipe-A-DP-1: SUCCESS (4.005s) [299.725963] Starting dynamic subtest: pipe-C-DP-1 [303.819859] Dynamic subtest pipe-C-DP-1: SUCCESS (4.109s) [303.821155] Subtest cursor-random-128x42: SUCCESS (8.187s) [304.171337] [053/141] (715s left) gem_exec_fence (invalid-timeline-fence-array) [304.977387] Starting subtest: invalid-timeline-fence-array [304.986484] Subtest invalid-timeline-fence-array: SUCCESS (0.010s) [305.218487] [054/141] (713s left) kms_frontbuffer_tracking (fbcpsr-1p-shrfb-fliptrack-mmap-gtt) [305.532598] Starting subtest: fbcpsr-1p-shrfb-fliptrack-mmap-gtt [305.535470] Subtest fbcpsr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s) [305.619163] [055/141] (713s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats) [306.011341] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s) [306.291955] [056/141] (713s left) gem_ctx_shared (q-promotion) [306.516038] Starting subtest: Q-promotion [306.517555] Starting dynamic subtest: rcs0 [306.623931] Dynamic subtest rcs0: SUCCESS (0.105s) [306.649479] Starting dynamic subtest: bcs0 [306.740208] Dynamic subtest bcs0: SUCCESS (0.114s) [306.762346] Starting dynamic subtest: vcs0 [306.837214] Dynamic subtest vcs0: SUCCESS (0.096s) [306.859939] Starting dynamic subtest: vecs0 [306.930890] Dynamic subtest vecs0: SUCCESS (0.093s) [306.932378] Subtest Q-promotion: SUCCESS (0.411s) [307.154976] [057/141] (712s left) i915_module_load (reload-no-display) [307.350156] Starting subtest: reload-no-display [311.624189] Subtest reload-no-display: SUCCESS (4.272s) [311.654604] [058/141] (707s left) kms_draw_crc (draw-method-mmap-gtt) [313.361935] Starting subtest: draw-method-mmap-gtt [313.367837] Starting dynamic subtest: xrgb8888-untiled [313.733391] Dynamic subtest xrgb8888-untiled: SUCCESS (0.368s) [313.752220] Starting dynamic subtest: rgb565-untiled [313.996089] Dynamic subtest rgb565-untiled: SUCCESS (0.266s) [314.013056] Starting dynamic subtest: xrgb2101010-untiled [314.262631] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.266s) [314.280141] Starting dynamic subtest: xrgb8888-xtiled [314.395906] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s) [314.412790] Starting dynamic subtest: rgb565-xtiled [314.530352] Dynamic subtest rgb565-xtiled: SUCCESS (0.133s) [314.547260] Starting dynamic subtest: xrgb2101010-xtiled [314.662642] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.132s) [314.679918] Starting dynamic subtest: xrgb8888-ytiled [314.795944] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.133s) [314.813546] Starting dynamic subtest: rgb565-ytiled [314.929257] Dynamic subtest rgb565-ytiled: SUCCESS (0.133s) [314.946633] Starting dynamic subtest: xrgb2101010-ytiled [315.062735] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.133s) [315.064452] Subtest draw-method-mmap-gtt: SUCCESS (1.702s) [315.199710] [059/141] (704s left) i915_pm_rpm (universal-planes) [317.032657] Starting subtest: universal-planes [317.034838] Starting dynamic subtest: plane-31 [319.883676] Dynamic subtest plane-31: SUCCESS (2.850s) [319.904798] Starting dynamic subtest: plane-39 [322.837565] Dynamic subtest plane-39: SUCCESS (2.954s) [322.855669] Starting dynamic subtest: plane-47 [325.662273] Dynamic subtest plane-47: SUCCESS (2.823s) [325.681992] Starting dynamic subtest: plane-55 [328.528681] Dynamic subtest plane-55: SUCCESS (2.867s) [328.530787] Subtest universal-planes: SUCCESS (11.497s) [329.015833] [060/141] (690s left) sw_sync (timeline_closed) [329.217994] Starting subtest: timeline_closed [329.219493] Subtest timeline_closed: SUCCESS (0.000s) [329.279464] [061/141] (690s left) gem_exec_suspend (basic-s3-devices) [329.513458] Starting subtest: basic-S3-devices [329.514815] Starting dynamic subtest: smem [339.427577] Dynamic subtest smem: SUCCESS (9.913s) [339.428880] Subtest basic-S3-devices: SUCCESS (9.915s) [341.638810] [062/141] (679s left) gem_wait (busy) [341.878317] Starting subtest: busy [341.879653] Starting dynamic subtest: all-engines [342.407799] Dynamic subtest all-engines: SUCCESS (0.528s) [342.433992] Starting dynamic subtest: rcs0 [342.930751] Dynamic subtest rcs0: SUCCESS (0.522s) [342.951666] Starting dynamic subtest: bcs0 [343.451647] Dynamic subtest bcs0: SUCCESS (0.520s) [343.473809] Starting dynamic subtest: vcs0 [343.970459] Dynamic subtest vcs0: SUCCESS (0.518s) [343.991609] Starting dynamic subtest: vecs0 [344.484562] Dynamic subtest vecs0: SUCCESS (0.513s) [344.485829] Subtest busy: SUCCESS (2.606s) [344.734609] [063/141] (676s left) kms_writeback (writeback-fb-id) [345.132145] Subtest writeback-fb-id: SKIP (0.000s) [345.183376] [064/141] (676s left) gem_ctx_persistence (legacy-engines-hostile-preempt) [345.391899] Starting subtest: legacy-engines-hostile-preempt [345.395806] Starting dynamic subtest: render [345.497957] Dynamic subtest render: SUCCESS (0.102s) [345.520415] Starting dynamic subtest: bsd [345.595429] Dynamic subtest bsd: SUCCESS (0.094s) [345.616201] Starting dynamic subtest: blt [345.684921] Dynamic subtest blt: SUCCESS (0.089s) [345.706951] Starting dynamic subtest: vebox [345.778447] Dynamic subtest vebox: SUCCESS (0.091s) [345.779751] Subtest legacy-engines-hostile-preempt: SUCCESS (0.388s) [345.868080] [065/141] (675s left) kms_rotation_crc (sprite-rotation-180) [346.172476] Starting subtest: sprite-rotation-180 [350.557236] Subtest sprite-rotation-180: SUCCESS (4.385s) [350.983189] [066/141] (670s left) kms_ccs (pipe-c-ccs-on-another-bo-yf_tiled_ccs) [351.668617] Starting subtest: pipe-C-ccs-on-another-bo-yf_tiled_ccs [351.672170] Subtest pipe-C-ccs-on-another-bo-yf_tiled_ccs: SKIP (0.000s) [352.032461] [067/141] (669s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip) [352.365672] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip [352.373483] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.005s) [352.464811] [068/141] (669s left) gem_pwrite (basic-exhaustion) [352.664643] Starting subtest: basic-exhaustion [373.755311] Subtest basic-exhaustion: SUCCESS (21.089s) [373.870652] [069/141] (647s left) kms_ccs (pipe-d-bad-pixel-format-y_tiled_ccs) [374.595586] Subtest pipe-D-bad-pixel-format-y_tiled_ccs: SKIP (0.000s) [374.897163] [070/141] (646s left) kms_big_fb (x-tiled-32bpp-rotate-0) [375.221399] Starting subtest: x-tiled-32bpp-rotate-0 [380.055271] Subtest x-tiled-32bpp-rotate-0: SUCCESS (4.832s) [380.613720] [071/141] (641s left) gem_workarounds (reset-context) [380.838214] Starting subtest: reset-context [380.868301] Subtest reset-context: SUCCESS (0.030s) [381.080202] [072/141] (640s left) kms_setmode (basic-clone-single-crtc) [381.274573] Starting subtest: basic-clone-single-crtc [381.278923] Subtest basic-clone-single-crtc: SKIP (0.000s) [381.362725] [073/141] (640s left) gem_exec_schedule (pi-common) [381.726276] Starting subtest: pi-common [381.727698] Starting dynamic subtest: rcs0 [381.825814] Dynamic subtest rcs0: SUCCESS (0.097s) [381.849022] Starting dynamic subtest: bcs0 [381.913300] Dynamic subtest bcs0: SUCCESS (0.090s) [381.936906] Starting dynamic subtest: vcs0 [382.006964] Dynamic subtest vcs0: SUCCESS (0.092s) [382.032661] Starting dynamic subtest: vecs0 [382.099948] Dynamic subtest vecs0: SUCCESS (0.091s) [382.101371] Subtest pi-common: SUCCESS (0.375s) [382.295133] [074/141] (639s left) kms_cursor_legacy (cursorb-vs-flipb-legacy) [382.600811] Starting subtest: cursorB-vs-flipB-legacy [382.603828] Subtest cursorB-vs-flipB-legacy: SKIP (0.003s) [382.697731] [075/141] (639s left) kms_vblank (pipe-d-wait-forked) [383.005811] Subtest pipe-D-wait-forked: SKIP (0.000s) [383.097705] [076/141] (638s left) sysfs_heartbeat_interval (precise) [383.308782] Starting subtest: precise [383.310832] Starting dynamic subtest: vecs0 [384.806698] Dynamic subtest vecs0: SUCCESS (1.496s) [384.830088] Starting dynamic subtest: vcs0 [386.301693] Dynamic subtest vcs0: SUCCESS (1.494s) [386.325821] Starting dynamic subtest: bcs0 [387.804836] Dynamic subtest bcs0: SUCCESS (1.499s) [387.831928] Starting dynamic subtest: rcs0 [389.300935] Dynamic subtest rcs0: SUCCESS (1.497s) [389.302399] Subtest precise: SUCCESS (5.992s) [389.398586] [077/141] (632s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt) [389.706939] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt [389.709741] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [389.814943] [078/141] (632s left) vc4/vc4_perfmon (create-single-perfmon) [390.072688] Subtest create-single-perfmon: SKIP (0.000s) [390.147313] [079/141] (632s left) kms_content_protection (type1) [390.486397] Starting subtest: type1 [391.167916] Subtest type1: SKIP (0.679s) [391.579738] [080/141] (630s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-pgflip-blt) [391.884771] Starting subtest: fbc-2p-primscrn-shrfb-pgflip-blt [391.887615] Subtest fbc-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [391.979781] [081/141] (630s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs) [392.199314] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs [392.200731] Starting dynamic subtest: smem [392.534428] Dynamic subtest smem: SUCCESS (0.332s) [392.535801] Subtest yf-tiled-ccs-to-y-tiled-ccs: SUCCESS (0.333s) [392.731340] [082/141] (629s left) kms_vblank (pipe-a-ts-continuation-dpms-suspend) [393.027699] Starting subtest: pipe-A-ts-continuation-dpms-suspend [410.714304] Subtest pipe-A-ts-continuation-dpms-suspend: SUCCESS (1.688s) [417.207147] [083/141] (627s left) kms_atomic_interruptible (legacy-dpms) [417.515713] Starting subtest: legacy-dpms [417.520582] Starting dynamic subtest: DP-1-pipe-A [424.072702] Dynamic subtest DP-1-pipe-A: SUCCESS (6.546s) [424.074065] Subtest legacy-dpms: SUCCESS (6.551s) [424.415163] [084/141] (620s left) kms_chamelium_color (gamma) [424.724577] Subtest gamma: SKIP (0.000s) [424.779868] [085/141] (619s left) syncobj_timeline (reset-multiple-signaled) [424.983374] Starting subtest: reset-multiple-signaled [424.987630] Subtest reset-multiple-signaled: SUCCESS (0.003s) [425.081362] [086/141] (619s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted) [425.295425] Starting subtest: invalid-multi-wait-unsubmitted-submitted [425.300185] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.005s) [425.397849] [087/141] (619s left) perf_pmu (busy-hang) [425.605981] Starting subtest: busy-hang [425.607495] Starting dynamic subtest: rcs0 [426.655437] Dynamic subtest rcs0: SUCCESS (1.050s) [426.681230] Starting dynamic subtest: bcs0 [427.710036] Dynamic subtest bcs0: SUCCESS (1.053s) [427.734585] Starting dynamic subtest: vcs0 [428.755523] Dynamic subtest vcs0: SUCCESS (1.044s) [428.780281] Starting dynamic subtest: vecs0 [429.800876] Dynamic subtest vecs0: SUCCESS (1.043s) [429.802216] Subtest busy-hang: SUCCESS (4.196s) [429.899721] [088/141] (614s left) prime_vgem (wait) [430.121513] Starting subtest: wait [430.122924] Starting dynamic subtest: rcs0 [430.152825] Dynamic subtest rcs0: SUCCESS (0.030s) [430.177905] Starting dynamic subtest: vcs0 [430.179216] Dynamic subtest vcs0: SUCCESS (0.019s) [430.204641] Starting dynamic subtest: vecs0 [430.205983] Dynamic subtest vecs0: SUCCESS (0.016s) [430.207321] Subtest wait: SUCCESS (0.069s) [430.315358] [089/141] (614s left) kms_addfb_basic (basic) [430.517731] Starting subtest: basic [430.519197] Subtest basic: SUCCESS (0.000s) [430.715590] [090/141] (613s left) syncobj_wait (single-wait-for-submit-unsubmitted) [430.919727] Starting subtest: single-wait-for-submit-unsubmitted [431.029048] Subtest single-wait-for-submit-unsubmitted: SUCCESS (0.108s) [431.113998] [091/141] (613s left) gem_wait (invalid-buf) [431.324352] Starting subtest: invalid-buf [431.325791] Subtest invalid-buf: SUCCESS (0.000s) [431.598410] [092/141] (613s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff) [431.914489] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff [431.917795] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s) [432.014474] [093/141] (612s left) perf (disabled-read-error) [432.359483] Starting subtest: disabled-read-error [432.464296] Subtest disabled-read-error: SUCCESS (0.106s) [432.513969] [094/141] (612s left) gem_linear_blits (basic) [433.465237] Starting subtest: basic [433.480673] Subtest basic: SUCCESS (0.017s) [433.629589] [095/141] (611s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc) [434.484842] Starting subtest: pipe-C-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc [434.490803] Subtest pipe-C-crc-sprite-planes-basic-4_tiled_mtl_rc_ccs_cc: SKIP (0.005s) [434.821475] [096/141] (610s left) kms_ccs (pipe-b-random-ccs-data-y_tiled_gen12_rc_ccs_cc) [435.367573] Starting subtest: pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs_cc [435.371542] Subtest pipe-B-random-ccs-data-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s) [435.862578] [097/141] (609s left) kms_big_fb (x-tiled-64bpp-rotate-270) [436.204997] Starting subtest: x-tiled-64bpp-rotate-270 [436.287164] Subtest x-tiled-64bpp-rotate-270: SKIP (0.080s) [436.412513] [098/141] (608s left) kms_ccs (pipe-b-crc-primary-basic-y_tiled_gen12_rc_ccs) [436.938621] Starting subtest: pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs [436.941217] Subtest pipe-B-crc-primary-basic-y_tiled_gen12_rc_ccs: SKIP (0.001s) [437.448364] [099/141] (607s left) kms_addfb_basic (basic-x-tiled-legacy) [437.654338] Starting subtest: basic-x-tiled-legacy [437.655853] Subtest basic-x-tiled-legacy: SUCCESS (0.000s) [437.847714] [100/141] (607s left) kms_ccs (pipe-a-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc) [438.261271] Starting subtest: pipe-A-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc [438.264000] Subtest pipe-A-crc-primary-rotation-180-4_tiled_dg2_rc_ccs_cc: SKIP (0.001s) [438.876308] [101/141] (606s left) kms_ccs (pipe-a-bad-rotation-90-y_tiled_ccs) [439.280872] Starting subtest: pipe-A-bad-rotation-90-y_tiled_ccs [439.974412] Subtest pipe-A-bad-rotation-90-y_tiled_ccs: SUCCESS (0.679s) [440.684975] [102/141] (604s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt) [441.015090] Starting subtest: psr-farfromfence-mmap-gtt [441.017907] Subtest psr-farfromfence-mmap-gtt: SKIP (0.001s) [441.133537] [103/141] (604s left) i915_pm_freq_api (freq-basic-api) [441.346047] Subtest freq-basic-api: SKIP (0.000s) [441.415125] [104/141] (603s left) kms_plane_scaling (plane-upscale-with-rotation-factor-0-25) [441.709154] Starting subtest: plane-upscale-with-rotation-factor-0-25 [441.710581] Starting dynamic subtest: pipe-A-DP-1 [441.772156] Dynamic subtest pipe-A-DP-1: SKIP (0.059s) [441.796414] Starting dynamic subtest: pipe-B-DP-1 [442.122260] Dynamic subtest pipe-B-DP-1: SKIP (0.347s) [442.149296] Starting dynamic subtest: pipe-C-DP-1 [442.465711] Dynamic subtest pipe-C-DP-1: SKIP (0.338s) [442.468436] Subtest plane-upscale-with-rotation-factor-0-25: SKIP (0.757s) [442.857996] [105/141] (602s left) gem_exec_balancer (full-late) [443.085420] Starting subtest: full-late [447.036710] Subtest full-late: SUCCESS (3.951s) [450.908117] [106/141] (594s left) gem_tiling_max_stride [451.275147] [107/141] (594s left) kms_addfb_basic (size-max) [451.586153] Starting subtest: size-max [451.587734] Subtest size-max: SUCCESS (0.000s) [451.691410] [108/141] (593s left) perf (rc6-disable) [452.022660] Starting subtest: rc6-disable [452.259406] Subtest rc6-disable: SUCCESS (0.235s) [452.325966] [109/141] (593s left) kms_atomic (plane-primary-legacy) [452.754373] Starting subtest: plane-primary-legacy [452.946230] Subtest plane-primary-legacy: SUCCESS (0.192s) [453.363427] [110/141] (592s left) syncobj_timeline (multi-wait-for-submit-available-signaled) [453.568805] Starting subtest: multi-wait-for-submit-available-signaled [453.570234] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.002s) [453.661157] [111/141] (591s left) v3d/v3d_perfmon (create-two-perfmon) [453.916311] Subtest create-two-perfmon: SKIP (0.000s) [453.994714] [112/141] (591s left) kms_ccs (pipe-c-crc-primary-rotation-180-y_tiled_gen12_rc_ccs) [454.643578] Starting subtest: pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs [454.646450] Subtest pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.001s) [455.033369] [113/141] (590s left) gem_softpin (allocator-nopin) [455.245229] Starting subtest: allocator-nopin [455.489443] Subtest allocator-nopin: SUCCESS (0.243s) [455.616440] [114/141] (590s left) v3d/v3d_wait_bo (bad-pad) [455.889503] Subtest bad-pad: SKIP (0.000s) [455.966433] [115/141] (589s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt) [456.269686] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt [456.272453] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s) [456.383698] [116/141] (589s left) kms_vblank (pipe-b-wait-forked-busy-hang) [456.685611] Starting subtest: pipe-B-wait-forked-busy-hang [463.974709] Subtest pipe-B-wait-forked-busy-hang: SUCCESS (7.291s) [464.329058] [117/141] (581s left) i915_pm_rps (min-max-config-idle) [464.543339] Starting subtest: min-max-config-idle [464.937624] Subtest min-max-config-idle: SUCCESS (0.397s) [465.029538] [118/141] (580s left) syncobj_basic (test-valid-cycle) [465.234477] Starting subtest: test-valid-cycle [465.235983] Subtest test-valid-cycle: SUCCESS (0.000s) [465.328897] [119/141] (580s left) kms_cursor_crc (cursor-rapid-movement-128x128) [465.644103] Starting subtest: cursor-rapid-movement-128x128 [465.655771] Starting dynamic subtest: pipe-A-DP-1 [466.152470] Dynamic subtest pipe-A-DP-1: SUCCESS (0.491s) [466.234722] Starting dynamic subtest: pipe-C-DP-1 [467.069776] Dynamic subtest pipe-C-DP-1: SUCCESS (0.857s) [467.071125] Subtest cursor-rapid-movement-128x128: SUCCESS (1.423s) [467.421522] [120/141] (578s left) api_intel_bb (bb-with-allocator) [467.626620] Starting subtest: bb-with-allocator [467.637404] Subtest bb-with-allocator: SUCCESS (0.011s) [467.750857] [121/141] (578s left) syncobj_timeline (etime-single-wait-submitted) [467.954255] Starting subtest: etime-single-wait-submitted [468.061577] Subtest etime-single-wait-submitted: SUCCESS (0.108s) [468.150589] [122/141] (577s left) gem_exec_schedule (independent) [468.381763] Starting subtest: independent [468.386404] Starting dynamic subtest: rcs0 [468.572515] Dynamic subtest rcs0: SUCCESS (0.187s) [468.603970] Starting dynamic subtest: bcs0 [468.762670] Dynamic subtest bcs0: SUCCESS (0.189s) [468.800153] Starting dynamic subtest: vcs0 [468.936863] Dynamic subtest vcs0: SUCCESS (0.173s) [468.965091] Starting dynamic subtest: vecs0 [469.113222] Dynamic subtest vecs0: SUCCESS (0.175s) [469.114630] Subtest independent: SUCCESS (0.731s) [469.452101] [123/141] (576s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-fullscreen) [469.759288] Starting subtest: psr-2p-primscrn-spr-indfb-fullscreen [469.762127] Subtest psr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s) [469.885075] [124/141] (576s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_dg2_rc_ccs_cc) [470.611794] Subtest pipe-D-bad-rotation-90-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s) [470.929499] [125/141] (575s left) gem_exec_fence (syncobj-timeline-invalid-flags) [471.721764] Starting subtest: syncobj-timeline-invalid-flags [471.723127] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.001s) [471.947217] [126/141] (574s left) kms_psr (psr2_cursor_blt) [472.151269] Subtest psr2_cursor_blt: SKIP (0.000s) [472.211429] [127/141] (574s left) kms_ccs (pipe-b-missing-ccs-buffer-yf_tiled_ccs) [472.762474] Starting subtest: pipe-B-missing-ccs-buffer-yf_tiled_ccs [472.765026] Subtest pipe-B-missing-ccs-buffer-yf_tiled_ccs: SUCCESS (0.001s) [473.279199] [128/141] (573s left) gem_exec_flush (basic-wb-ro-default) [473.508978] Starting subtest: basic-wb-ro-default [478.998955] Subtest basic-wb-ro-default: SUCCESS (5.490s) [479.212864] [129/141] (567s left) gem_partial_pwrite_pread (reads-uncached) [479.426215] Starting subtest: reads-uncached [486.220031] Subtest reads-uncached: SUCCESS (6.794s) [486.345627] [130/141] (560s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu) [486.662374] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu [486.665223] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [486.779460] [131/141] (559s left) kms_ccs (pipe-d-crc-primary-rotation-180-4_tiled_mtl_mc_ccs) [487.506625] Subtest pipe-D-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SKIP (0.000s) [487.827960] [132/141] (558s left) kms_frontbuffer_tracking (psr-slowdraw) [488.139956] Starting subtest: psr-slowdraw [488.143503] Subtest psr-slowdraw: SKIP (0.000s) [488.244959] [133/141] (558s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-gtt) [488.555764] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-gtt [488.558537] Subtest psr-1p-offscren-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [488.661728] [134/141] (558s left) gem_lmem_swapping (heavy-verify-random) [488.874141] Subtest heavy-verify-random: SKIP (0.000s) [488.943334] [135/141] (557s left) gem_userptr_blits (readonly-unsync) [489.156381] Subtest readonly-unsync: SKIP (0.000s) [489.328368] [136/141] (557s left) syncobj_wait (invalid-wait-bad-flags) [489.533151] Starting subtest: invalid-wait-bad-flags [489.534655] Subtest invalid-wait-bad-flags: SUCCESS (0.000s) [489.627893] [137/141] (557s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite) [489.940712] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite [489.944116] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [490.044499] [138/141] (556s left) kms_plane_alpha_blend (alpha-transparent-fb) [490.343652] Starting subtest: alpha-transparent-fb [490.656047] Starting dynamic subtest: pipe-A-DP-1 [491.430028] Dynamic subtest pipe-A-DP-1: FAIL (0.404s) [491.455247] Starting dynamic subtest: pipe-C-DP-1 [492.097314] Dynamic subtest pipe-C-DP-1: FAIL (0.654s) [492.098748] Subtest alpha-transparent-fb: FAIL (1.374s) [492.144371] [139/141] (554s left) kms_flip (2x-wf_vblank-ts-check-interruptible) [492.440746] Starting subtest: 2x-wf_vblank-ts-check-interruptible [492.446206] Subtest 2x-wf_vblank-ts-check-interruptible: SKIP (0.004s) [492.552038] [140/141] (554s left) kms_plane (plane-position-hole-dpms) [492.857620] Starting subtest: plane-position-hole-dpms [492.861925] Starting dynamic subtest: pipe-A-planes [494.927505] Dynamic subtest pipe-A-planes: SUCCESS (2.065s) [494.955319] Starting dynamic subtest: pipe-B-planes [497.292481] Dynamic subtest pipe-B-planes: SUCCESS (2.362s) [497.293900] Subtest plane-position-hole-dpms: SUCCESS (4.437s) [497.634831] [141/141] (549s left) kms_force_connector_basic (force-connector-state) [497.842532] Subtest force-connector-state: SKIP (0.000s) [497.896739] Closing watchdogs Done.