[41.204126] Initializing watchdogs [41.204307] /dev/watchdog0 [41.219493] [001/137] (960s left) gem_ctx_persistence (engines-queued) [41.379476] Starting subtest: engines-queued [41.382946] Starting dynamic subtest: rcs0 [41.425240] Dynamic subtest rcs0: SUCCESS (0.045s) [41.440197] Starting dynamic subtest: bcs0 [41.533336] Dynamic subtest bcs0: SUCCESS (0.100s) [41.557555] Starting dynamic subtest: vcs0 [41.585618] Dynamic subtest vcs0: SUCCESS (0.060s) [41.599896] Starting dynamic subtest: vecs0 [41.629794] Dynamic subtest vecs0: SUCCESS (0.044s) [41.634070] Subtest engines-queued: SUCCESS (0.250s) [41.729320] [002/137] (960s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs) [42.350618] Starting subtest: pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs [42.360719] Subtest pipe-A-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs: SKIP (0.007s) [42.646978] [003/137] (959s left) prime_vgem (fence-wait) [42.899066] Starting subtest: fence-wait [42.902243] Starting dynamic subtest: rcs0 [43.919129] Dynamic subtest rcs0: SUCCESS (1.021s) [43.930269] Starting dynamic subtest: vcs0 [44.939276] Dynamic subtest vcs0: SUCCESS (1.019s) [44.950728] Starting dynamic subtest: vecs0 [45.962503] Dynamic subtest vecs0: SUCCESS (1.021s) [45.965750] Subtest fence-wait: SUCCESS (3.063s) [46.047636] [004/137] (955s left) kms_atomic (plane-immutable-zpos) [46.584883] Starting subtest: plane-immutable-zpos [47.031780] Subtest plane-immutable-zpos: SUCCESS (0.447s) [47.417552] [005/137] (954s left) kms_flip (nonexisting-fb-interruptible) [47.631405] Starting subtest: nonexisting-fb-interruptible [47.637791] Starting dynamic subtest: A-HDMI-A1 [48.234757] Dynamic subtest A-HDMI-A1: SUCCESS (0.596s) [48.255988] Starting dynamic subtest: B-HDMI-A1 [48.545431] Dynamic subtest B-HDMI-A1: SUCCESS (0.313s) [48.560757] Starting dynamic subtest: C-HDMI-A1 [48.859028] Dynamic subtest C-HDMI-A1: SUCCESS (0.312s) [48.876849] Starting dynamic subtest: A-HDMI-A2 [49.298717] Dynamic subtest A-HDMI-A2: SUCCESS (0.329s) [49.363196] Starting dynamic subtest: B-HDMI-A2 [49.623545] Dynamic subtest B-HDMI-A2: SUCCESS (0.345s) [49.689976] Starting dynamic subtest: C-HDMI-A2 [50.022802] Dynamic subtest C-HDMI-A2: SUCCESS (0.412s) [50.090391] Subtest nonexisting-fb-interruptible: SUCCESS (2.316s) [50.534346] [006/137] (951s left) kms_vblank (pipe-d-accuracy-idle) [50.796637] Subtest pipe-D-accuracy-idle: SKIP (0.000s) [50.879121] [007/137] (951s left) gem_userptr_blits (map-fixed-invalidate-busy) [51.202143] Starting subtest: map-fixed-invalidate-busy [51.205308] Starting dynamic subtest: gtt [54.313144] Dynamic subtest gtt: SUCCESS (3.115s) [54.326451] Starting dynamic subtest: wb [57.458313] Dynamic subtest wb: SUCCESS (3.144s) [57.479098] Starting dynamic subtest: wc [60.488634] Dynamic subtest wc: SUCCESS (3.029s) [60.501916] Starting dynamic subtest: uc [63.356323] Dynamic subtest uc: SUCCESS (2.867s) [63.359367] Subtest map-fixed-invalidate-busy: SUCCESS (12.158s) [63.477878] [008/137] (938s left) kms_setmode (invalid-clone-single-crtc-stealing) [63.611240] Starting subtest: invalid-clone-single-crtc-stealing [64.042953] Starting dynamic subtest: pipe-A-HDMI-A-1-HDMI-A-2 [64.374643] Dynamic subtest pipe-A-HDMI-A-1-HDMI-A-2: SUCCESS (0.330s) [64.649731] Starting dynamic subtest: pipe-B-HDMI-A-1-HDMI-A-2 [64.982311] Dynamic subtest pipe-B-HDMI-A-1-HDMI-A-2: SUCCESS (0.337s) [65.207387] Subtest invalid-clone-single-crtc-stealing: SUCCESS (1.589s) [65.419994] [009/137] (936s left) i915_suspend (basic-s3-without-i915) [65.557426] Starting subtest: basic-s3-without-i915 [84.601906] Subtest basic-s3-without-i915: SUCCESS (3.358s) [89.749040] [010/137] (932s left) perf_pmu (all-busy-idle-check-all) [89.900658] Starting subtest: all-busy-idle-check-all [90.532449] Subtest all-busy-idle-check-all: SUCCESS (0.629s) [90.612031] [011/137] (932s left) gem_ctx_shared (q-promotion) [90.760075] Starting subtest: Q-promotion [90.763719] Starting dynamic subtest: rcs0 [90.849233] Dynamic subtest rcs0: SUCCESS (0.086s) [90.867859] Starting dynamic subtest: bcs0 [90.956639] Dynamic subtest bcs0: SUCCESS (0.102s) [90.974570] Starting dynamic subtest: vcs0 [91.038515] Dynamic subtest vcs0: SUCCESS (0.080s) [91.057073] Starting dynamic subtest: vecs0 [91.106370] Dynamic subtest vecs0: SUCCESS (0.076s) [91.109970] Subtest Q-promotion: SUCCESS (0.345s) [91.312910] [012/137] (931s left) i915_pm_backlight (fade) [91.519242] Subtest fade: SKIP (0.000s) [91.566696] [013/137] (931s left) kms_ccs (pipe-c-crc-primary-rotation-180-4_tiled_dg2_mc_ccs) [92.256289] Starting subtest: pipe-C-crc-primary-rotation-180-4_tiled_dg2_mc_ccs [92.262038] Subtest pipe-C-crc-primary-rotation-180-4_tiled_dg2_mc_ccs: SKIP (0.001s) [92.490630] [014/137] (930s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc) [92.732093] Starting subtest: fbc-rgb101010-draw-mmap-wc [95.656061] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (2.933s) [95.986045] [015/137] (926s left) gem_exec_balancer (indices) [96.139990] Starting subtest: indices [98.948956] Subtest indices: SUCCESS (2.808s) [101.935710] [016/137] (920s left) gem_userptr_blits (sync-unmap-after-close) [102.089985] Starting subtest: sync-unmap-after-close [102.128937] Subtest sync-unmap-after-close: SUCCESS (0.034s) [102.235791] [017/137] (920s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-0-25) [102.446560] Starting subtest: planes-downscale-factor-0-75-upscale-0-25 [102.449714] Starting dynamic subtest: pipe-A-HDMI-A-1 [102.814817] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.368s) [102.842106] Starting dynamic subtest: pipe-B-HDMI-A-1 [103.148977] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.330s) [103.174953] Starting dynamic subtest: pipe-C-HDMI-A-1 [103.277147] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.109s) [103.281002] Subtest planes-downscale-factor-0-75-upscale-0-25: SUCCESS (0.826s) [103.600901] [018/137] (919s left) kms_big_fb (linear-8bpp-rotate-90) [103.833428] Starting subtest: linear-8bpp-rotate-90 [104.125950] Subtest linear-8bpp-rotate-90: SKIP (0.290s) [104.433727] [019/137] (918s left) kms_properties (get_properties-sanity-atomic) [104.651546] Starting subtest: get_properties-sanity-atomic [104.658577] Subtest get_properties-sanity-atomic: SUCCESS (0.008s) [104.746382] [020/137] (918s left) kms_big_fb (x-tiled-8bpp-rotate-90) [104.976657] Starting subtest: x-tiled-8bpp-rotate-90 [105.260930] Subtest x-tiled-8bpp-rotate-90: SKIP (0.282s) [105.560997] [021/137] (917s left) sw_sync (sync_busy_fork) [105.698548] Starting subtest: sync_busy_fork [106.721930] Subtest sync_busy_fork: SUCCESS (1.021s) [106.777597] [022/137] (916s left) gem_exec_schedule (u-semaphore-noskip) [106.944285] Starting subtest: u-semaphore-noskip [107.159355] Subtest u-semaphore-noskip: SUCCESS (0.220s) [107.477726] [023/137] (915s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_dg2_rc_ccs) [108.172919] Starting subtest: pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs [108.182085] Subtest pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs: SKIP (0.000s) [108.425130] [024/137] (914s left) kms_cursor_crc (cursor-rapid-movement-512x512) [108.660938] Starting subtest: cursor-rapid-movement-512x512 [108.667439] Subtest cursor-rapid-movement-512x512: SKIP (0.003s) [108.757250] [025/137] (914s left) kms_chamelium_frames (dp-frame-dump) [109.327557] Subtest dp-frame-dump: SKIP (0.000s) [109.506587] [026/137] (913s left) gem_exec_params (secure-non-master) [109.648691] Starting subtest: secure-non-master [109.655040] Subtest secure-non-master: SKIP (0.000s) [109.740941] [027/137] (913s left) gem_mmap_gtt (medium-copy) [109.876406] Starting subtest: medium-copy [114.713811] Subtest medium-copy: SUCCESS (4.836s) [114.801555] [028/137] (908s left) gem_ctx_isolation (preservation-s3) [115.049262] Starting subtest: preservation-S3 [115.052572] Starting dynamic subtest: rcs0 [132.991360] Dynamic subtest rcs0: SUCCESS (1.739s) [133.096691] Starting dynamic subtest: bcs0 [151.101816] Dynamic subtest bcs0: SUCCESS (2.659s) [151.121674] Starting dynamic subtest: vcs0 [168.955611] Dynamic subtest vcs0: SUCCESS (1.697s) [168.974431] Starting dynamic subtest: vecs0 [187.029591] Dynamic subtest vecs0: SUCCESS (1.865s) [187.032852] Subtest preservation-S3: SUCCESS (7.964s) [194.253401] [029/137] (900s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs) [194.404331] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs [194.411448] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s) [194.610734] [030/137] (899s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc) [194.854939] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc [197.316590] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (2.471s) [197.473495] [031/137] (896s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render) [197.704508] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render [197.710090] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [197.787850] [032/137] (896s left) kms_vblank (pipe-a-query-forked) [198.004630] Starting subtest: pipe-A-query-forked [203.139001] Subtest pipe-A-query-forked: SUCCESS (5.133s) [203.366469] [033/137] (891s left) kms_rotation_crc (primary-4-tiled-reflect-x-180) [203.590533] Starting subtest: primary-4-tiled-reflect-x-180 [203.597647] Subtest primary-4-tiled-reflect-x-180: SKIP (0.003s) [203.681910] [034/137] (890s left) gem_create (create-ext-cpu-access-sanity-check) [203.936066] Starting subtest: create-ext-cpu-access-sanity-check [203.942226] Subtest create-ext-cpu-access-sanity-check: SKIP (0.002s) [204.015604] [035/137] (890s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt) [204.258526] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt [206.595261] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SUCCESS (2.346s) [206.949243] [036/137] (887s left) kms_ccs (pipe-a-crc-primary-basic-y_tiled_gen12_rc_ccs) [207.540985] Starting subtest: pipe-A-crc-primary-basic-y_tiled_gen12_rc_ccs [207.546996] Subtest pipe-A-crc-primary-basic-y_tiled_gen12_rc_ccs: SKIP (0.002s) [207.888163] [037/137] (886s left) kms_ccs (pipe-d-random-ccs-data-y_tiled_gen12_rc_ccs_cc) [208.612407] Subtest pipe-D-random-ccs-data-y_tiled_gen12_rc_ccs_cc: SKIP (0.000s) [208.839783] [038/137] (885s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen) [209.078184] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen [209.083836] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [209.167916] [039/137] (885s left) api_intel_bb (object-noreloc-purge-cache-simple) [209.309205] Starting subtest: object-noreloc-purge-cache-simple [209.316816] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.010s) [209.404912] [040/137] (885s left) perf_pmu (busy-idle-no-semaphores) [209.559699] Starting subtest: busy-idle-no-semaphores [209.563212] Starting dynamic subtest: rcs0 [210.192392] Dynamic subtest rcs0: SUCCESS (0.622s) [210.213296] Starting dynamic subtest: bcs0 [210.821379] Dynamic subtest bcs0: SUCCESS (0.626s) [210.840608] Starting dynamic subtest: vcs0 [211.436990] Dynamic subtest vcs0: SUCCESS (0.625s) [211.456207] Starting dynamic subtest: vecs0 [212.053664] Dynamic subtest vecs0: SUCCESS (0.616s) [212.057987] Subtest busy-idle-no-semaphores: SUCCESS (2.493s) [212.159294] [041/137] (882s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy) [212.382703] Starting subtest: 2x-flip-vs-cursor-legacy [213.319343] Subtest 2x-flip-vs-cursor-legacy: SUCCESS (0.934s) [213.546600] [042/137] (881s left) drm_fdinfo (most-busy-idle-check-all) [213.690303] Starting subtest: most-busy-idle-check-all [213.693605] Starting dynamic subtest: rcs0 [214.312255] Dynamic subtest rcs0: SUCCESS (0.619s) [214.331797] Starting dynamic subtest: bcs0 [214.940045] Dynamic subtest bcs0: SUCCESS (0.626s) [214.958251] Starting dynamic subtest: vcs0 [215.556075] Dynamic subtest vcs0: SUCCESS (0.616s) [215.573586] Starting dynamic subtest: vecs0 [216.174915] Dynamic subtest vecs0: SUCCESS (0.617s) [216.178844] Subtest most-busy-idle-check-all: SUCCESS (2.481s) [216.261185] [043/137] (878s left) syncobj_timeline (invalid-signal-bad-pad) [216.407007] Starting subtest: invalid-signal-bad-pad [216.410509] Subtest invalid-signal-bad-pad: SUCCESS (0.000s) [216.494591] [044/137] (878s left) kms_pipe_crc_basic (suspend-read-crc) [216.704265] Starting subtest: suspend-read-crc [216.707466] Starting dynamic subtest: pipe-A-HDMI-A-1 [235.495719] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.829s) [235.524525] Starting dynamic subtest: pipe-B-HDMI-A-2 [254.614132] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.781s) [254.641047] Starting dynamic subtest: pipe-C-HDMI-A-1 [273.658210] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.054s) [273.661198] Subtest suspend-read-crc: SUCCESS (8.674s) [277.902455] [045/137] (869s left) kms_ccs (pipe-a-bad-pixel-format-4_tiled_dg2_mc_ccs) [278.123753] Starting subtest: pipe-A-bad-pixel-format-4_tiled_dg2_mc_ccs [278.130665] Subtest pipe-A-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s) [278.814128] [046/137] (868s left) gem_exec_whisper (basic-forked) [278.969476] Starting subtest: basic-forked [285.270717] Subtest basic-forked: SUCCESS (6.300s) [285.465349] [047/137] (861s left) gem_mmap_gtt (basic-small-copy) [285.598697] Starting subtest: basic-small-copy [287.848900] Subtest basic-small-copy: SUCCESS (2.253s) [287.931898] [048/137] (859s left) gem_tiled_partial_pwrite_pread (writes-after-reads) [288.174971] Starting subtest: writes-after-reads [291.158043] Subtest writes-after-reads: SUCCESS (2.983s) [291.265337] [049/137] (856s left) gem_render_copy (yf-tiled-ccs-to-yf-tiled-ccs) [291.414361] Starting subtest: yf-tiled-ccs-to-yf-tiled-ccs [291.417668] Starting dynamic subtest: smem [291.814463] Dynamic subtest smem: SUCCESS (0.385s) [291.817707] Subtest yf-tiled-ccs-to-yf-tiled-ccs: SUCCESS (0.386s) [291.998757] [050/137] (855s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling) [292.228549] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling [292.232062] Starting dynamic subtest: pipe-A-valid-mode [293.108348] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.875s) [293.112037] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (0.879s) [293.364370] [051/137] (854s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-upscaling) [293.587403] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-upscaling [293.590454] Starting dynamic subtest: pipe-A-valid-mode [294.157265] Dynamic subtest pipe-A-valid-mode: SKIP (0.564s) [294.163569] Subtest flip-64bpp-ytile-to-16bpp-ytile-upscaling: SKIP (0.567s) [294.503939] [052/137] (852s left) gem_pxp (hw-rejects-pxp-context) [297.164182] Starting subtest: hw-rejects-pxp-context [297.167296] Subtest hw-rejects-pxp-context: SUCCESS (0.000s) [297.237168] [053/137] (850s left) kms_concurrent (pipe-a) [297.470712] Starting subtest: pipe-A [297.477160] Starting dynamic subtest: HDMI-A-1 [298.507158] Dynamic subtest HDMI-A-1: SUCCESS (1.031s) [298.535060] Starting dynamic subtest: HDMI-A-2 [299.396449] Dynamic subtest HDMI-A-2: SUCCESS (0.776s) [299.460988] Subtest pipe-A: SUCCESS (1.816s) [299.950235] [054/137] (847s left) v3d/v3d_submit_csd (single-in-sync) [300.744695] Subtest single-in-sync: SKIP (0.000s) [300.776313] [055/137] (846s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt) [301.023855] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt [301.030855] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [301.121980] [056/137] (846s left) gem_eio (in-flight-immediate) [301.298061] Starting subtest: in-flight-immediate [302.316320] Subtest in-flight-immediate: SUCCESS (1.019s) [302.476493] [057/137] (845s left) gem_exec_balancer (bonded-dual) [302.641920] Starting subtest: bonded-dual [305.494428] Subtest bonded-dual: SUCCESS (2.851s) [308.490314] [058/137] (839s left) kms_ccs (pipe-d-crc-primary-rotation-180-y_tiled_gen12_rc_ccs) [309.230107] Subtest pipe-D-crc-primary-rotation-180-y_tiled_gen12_rc_ccs: SKIP (0.000s) [309.468550] [059/137] (838s left) kms_flip (2x-plain-flip-ts-check-interruptible) [309.694202] Starting subtest: 2x-plain-flip-ts-check-interruptible [309.701320] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2 [320.948959] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.245s) [320.980348] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2 [331.848467] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.891s) [331.878481] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2 [342.751587] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.910s) [342.754755] Subtest 2x-plain-flip-ts-check-interruptible: SUCCESS (33.057s) [343.289021] [060/137] (804s left) kms_addfb_basic (addfb25-modifier-no-flag) [343.513706] Starting subtest: addfb25-modifier-no-flag [343.516825] Subtest addfb25-modifier-no-flag: SUCCESS (0.000s) [343.774892] [061/137] (804s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt) [344.009423] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt [344.015687] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [344.090616] [062/137] (803s left) i915_pm_rpm (debugfs-forcewake-user) [346.069846] Starting subtest: debugfs-forcewake-user [356.743123] Subtest debugfs-forcewake-user: SUCCESS (10.673s) [357.162219] [063/137] (790s left) kms_ccs (pipe-b-bad-pixel-format-y_tiled_gen12_rc_ccs) [357.829518] Starting subtest: pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs [357.835732] Subtest pipe-B-bad-pixel-format-y_tiled_gen12_rc_ccs: SKIP (0.000s) [358.176360] [064/137] (789s left) kms_vblank (pipe-b-ts-continuation-dpms-suspend) [358.682224] Starting subtest: pipe-B-ts-continuation-dpms-suspend [394.989327] Subtest pipe-B-ts-continuation-dpms-suspend: SUCCESS (4.434s) [401.231713] [065/137] (784s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0) [401.474928] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0 [401.481507] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s) [401.558092] [066/137] (784s left) v3d/v3d_submit_cl (bad-extension) [401.752706] Subtest bad-extension: SKIP (0.000s) [401.822611] [067/137] (784s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite) [402.065390] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite [402.071276] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [402.154379] [068/137] (784s left) i915_hangman (engine-engine-error) [402.323838] Starting subtest: engine-engine-error [402.326947] Starting dynamic subtest: rcs0 [402.399227] Dynamic subtest rcs0: SUCCESS (0.074s) [402.433101] Starting dynamic subtest: bcs0 [402.438762] Dynamic subtest bcs0: SKIP (0.012s) [402.470548] Starting dynamic subtest: vcs0 [402.484437] Dynamic subtest vcs0: SUCCESS (0.070s) [402.509440] Starting dynamic subtest: vecs0 [402.546715] Dynamic subtest vecs0: SUCCESS (0.059s) [402.550262] Subtest engine-engine-error: SUCCESS (0.222s) [402.658703] [069/137] (783s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled) [402.798411] Starting subtest: multi-wait-for-submit-submitted-signaled [402.801709] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.002s) [402.892222] [070/137] (783s left) gem_userptr_blits (unsync-unmap-cycles) [403.048893] Subtest unsync-unmap-cycles: SKIP (0.000s) [403.175491] [071/137] (783s left) kms_plane_scaling (i915-max-src-size) [403.396917] Starting subtest: i915-max-src-size [403.400152] Starting dynamic subtest: pipe-A-HDMI-A-2 [403.403162] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.006s) [403.406167] Subtest i915-max-src-size: SUCCESS (0.007s) [403.504377] [072/137] (782s left) gem_mmap_gtt (cpuset-big-copy-odd) [403.642661] Starting subtest: cpuset-big-copy-odd [422.458863] Subtest cpuset-big-copy-odd: SUCCESS (18.815s) [422.626916] [073/137] (763s left) gem_exec_fence (basic-busy) [423.006054] Starting subtest: basic-busy [423.009876] Starting dynamic subtest: rcs0 [423.025784] Dynamic subtest rcs0: SUCCESS (0.016s) [423.045646] Starting dynamic subtest: bcs0 [423.062958] Dynamic subtest bcs0: SUCCESS (0.017s) [423.083722] Starting dynamic subtest: vcs0 [423.091541] Dynamic subtest vcs0: SUCCESS (0.016s) [423.110527] Starting dynamic subtest: vecs0 [423.117604] Dynamic subtest vecs0: SUCCESS (0.013s) [423.122232] Subtest basic-busy: SUCCESS (0.067s) [423.658363] [074/137] (762s left) v3d/v3d_submit_csd (bad-multisync-in-sync) [423.872367] Subtest bad-multisync-in-sync: SKIP (0.000s) [423.943180] [075/137] (762s left) kms_atomic (plane-cursor-legacy) [424.847610] Starting subtest: plane-cursor-legacy [425.114834] Subtest plane-cursor-legacy: SUCCESS (0.266s) [425.563359] [076/137] (760s left) kms_chamelium_hpd (vga-hpd-after-suspend) [426.168909] Subtest vga-hpd-after-suspend: SKIP (0.000s) [426.387570] [077/137] (760s left) gem_softpin (evict-active-interruptible) [426.535790] Starting subtest: evict-active-interruptible [426.548420] Subtest evict-active-interruptible: SUCCESS (0.012s) [426.652889] [078/137] (759s left) i915_pm_rpm (gem-idle) [428.612541] Starting subtest: gem-idle [434.196895] Subtest gem-idle: SUCCESS (5.586s) [434.550899] [079/137] (752s left) kms_flip (2x-flip-vs-wf_vblank-interruptible) [434.788228] Starting subtest: 2x-flip-vs-wf_vblank-interruptible [434.791428] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2 [436.469844] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (1.673s) [436.502221] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2 [438.053817] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (1.580s) [438.084035] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2 [439.624176] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (1.569s) [439.627217] Subtest 2x-flip-vs-wf_vblank-interruptible: SUCCESS (4.839s) [440.157479] [080/137] (746s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary) [440.413511] Starting subtest: fbcpsr-indfb-scaledprimary [440.420210] Subtest fbcpsr-indfb-scaledprimary: SKIP (0.000s) [440.499259] [081/137] (746s left) gem_exec_schedule (preempt-queue-contexts-chain) [440.664522] Starting subtest: preempt-queue-contexts-chain [440.667619] Starting dynamic subtest: rcs0 [441.348124] Dynamic subtest rcs0: SUCCESS (0.686s) [441.380621] Starting dynamic subtest: bcs0 [441.955849] Dynamic subtest bcs0: SUCCESS (0.607s) [441.976609] Starting dynamic subtest: vcs0 [442.531790] Dynamic subtest vcs0: SUCCESS (0.574s) [442.552477] Starting dynamic subtest: vecs0 [443.134684] Dynamic subtest vecs0: SUCCESS (0.601s) [443.137762] Subtest preempt-queue-contexts-chain: SUCCESS (2.476s) [443.473457] [082/137] (743s left) gem_mmap_wc (write-gtt-read-wc) [443.609540] Starting subtest: write-gtt-read-wc [444.109020] Subtest write-gtt-read-wc: SUCCESS (0.502s) [444.205456] [083/137] (742s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite) [444.440739] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite [444.446980] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [444.521877] [084/137] (742s left) dmabuf (all-tests) [444.659235] Starting subtest: all-tests [444.666582] Starting dynamic subtest: sanitycheck [444.688026] Dynamic subtest sanitycheck: SUCCESS (0.023s) [444.713276] Starting dynamic subtest: dma_fence [444.848197] Dynamic subtest dma_fence: SUCCESS (0.163s) [444.876904] Starting dynamic subtest: dma_fence_chain [462.519289] Dynamic subtest dma_fence_chain: SUCCESS (17.670s) [462.548703] Starting dynamic subtest: dma_fence_unwrap [462.554125] Dynamic subtest dma_fence_unwrap: SUCCESS (0.027s) [462.581484] Starting dynamic subtest: dma_resv [462.584764] Dynamic subtest dma_resv: SUCCESS (0.023s) [462.587843] Subtest all-tests: SUCCESS (17.912s) [462.637903] [085/137] (724s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size) [462.853588] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size [500.440575] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (37.581s) [500.621920] [086/137] (686s left) dumb_buffer (create-clear) [500.759864] Starting subtest: create-clear [536.220376] Subtest create-clear: SUCCESS (35.455s) [536.302672] [087/137] (650s left) syncobj_timeline (invalid-multi-wait-all-available-unsubmitted-submitted) [536.436483] Starting subtest: invalid-multi-wait-all-available-unsubmitted-submitted [536.439692] Subtest invalid-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.001s) [536.520539] [088/137] (650s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw) [536.763231] Starting subtest: fbc-1p-pri-indfb-multidraw [551.997484] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (15.241s) [552.337918] [089/137] (634s left) gem_lmem_swapping (massive) [552.486351] Subtest massive: SKIP (0.000s) [552.542586] [090/137] (634s left) gem_render_copy (y-tiled-ccs-to-linear) [552.689550] Starting subtest: y-tiled-ccs-to-linear [552.692879] Starting dynamic subtest: smem [552.883761] Dynamic subtest smem: SUCCESS (0.192s) [552.888465] Subtest y-tiled-ccs-to-linear: SUCCESS (0.194s) [553.088935] [091/137] (633s left) kms_vblank (pipe-a-query-forked-hang) [553.307538] Starting subtest: pipe-A-query-forked-hang [567.615863] Subtest pipe-A-query-forked-hang: SUCCESS (14.307s) [567.845493] [092/137] (619s left) gem_userptr_blits (process-exit) [568.010749] Starting subtest: process-exit [568.034623] Subtest process-exit: SUCCESS (0.023s) [568.144656] [093/137] (618s left) gem_fenced_exec_thrash (too-many-fences) [568.286586] Starting subtest: too-many-fences [570.467053] Subtest too-many-fences: SUCCESS (2.180s) [570.596178] [094/137] (616s left) drm_read (empty-block) [571.138143] Starting subtest: empty-block [572.132526] Subtest empty-block: SUCCESS (1.001s) [572.465286] [095/137] (614s left) syncobj_wait (invalid-wait-illegal-handle) [572.607015] Starting subtest: invalid-wait-illegal-handle [572.610242] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [572.695839] [096/137] (614s left) gem_vm_create (isolation) [572.840341] Starting subtest: isolation [572.852041] Subtest isolation: SUCCESS (0.012s) [572.945896] [097/137] (614s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc) [573.184682] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc [573.190854] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [573.262692] [098/137] (613s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size) [573.484004] Starting subtest: basic-busy-flip-before-cursor-varying-size [573.490304] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s) [573.563011] [099/137] (613s left) kms_psr (primary_mmap_cpu) [573.704876] Subtest primary_mmap_cpu: SKIP (0.000s) [573.751242] [100/137] (613s left) vc4/vc4_wait_bo (used-bo-1ns) [573.929450] Subtest used-bo-1ns: SKIP (0.000s) [573.995924] [101/137] (613s left) kms_psr2_su (page_flip-p010) [574.129752] Subtest page_flip-P010: SKIP (0.000s) [574.184764] [102/137] (613s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-move) [574.424165] Starting subtest: psr-2p-primscrn-cur-indfb-move [574.429844] Subtest psr-2p-primscrn-cur-indfb-move: SKIP (0.000s) [574.512142] [103/137] (612s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip) [574.753656] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip [576.089431] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (1.336s) [576.463479] [104/137] (611s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180) [576.691322] Starting subtest: primary-yf-tiled-reflect-x-180 [576.697522] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.003s) [576.778756] [105/137] (610s left) gem_tiled_blits (interruptible) [578.180225] Starting subtest: interruptible [632.052400] Subtest interruptible: SUCCESS (53.873s) [632.146263] [106/137] (555s left) vc4/vc4_mmap (mmap-bo) [632.460486] Subtest mmap-bo: SKIP (0.000s) [632.529541] [107/137] (555s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_dg2_rc_ccs_cc) [633.167931] Starting subtest: pipe-B-crc-primary-basic-4_tiled_dg2_rc_ccs_cc [633.173905] Subtest pipe-B-crc-primary-basic-4_tiled_dg2_rc_ccs_cc: SKIP (0.001s) [633.494055] [108/137] (554s left) kms_getfb (getfb2-into-addfb2) [633.633354] Starting subtest: getfb2-into-addfb2 [633.636708] Subtest getfb2-into-addfb2: SUCCESS (0.000s) [633.715904] [109/137] (553s left) kms_big_fb (y-tiled-32bpp-rotate-270) [633.961666] Starting subtest: y-tiled-32bpp-rotate-270 [642.054742] Subtest y-tiled-32bpp-rotate-270: SUCCESS (8.092s) [642.581204] [110/137] (545s left) kms_flip (dpms-off-confusion-interruptible) [642.804199] Starting subtest: dpms-off-confusion-interruptible [642.811513] Starting dynamic subtest: A-HDMI-A1 [648.437953] Dynamic subtest A-HDMI-A1: SUCCESS (5.619s) [648.465768] Starting dynamic subtest: B-HDMI-A1 [653.739221] Dynamic subtest B-HDMI-A1: SUCCESS (5.299s) [653.772054] Starting dynamic subtest: C-HDMI-A1 [659.048857] Dynamic subtest C-HDMI-A1: SUCCESS (5.310s) [659.069811] Starting dynamic subtest: A-HDMI-A2 [664.413135] Dynamic subtest A-HDMI-A2: SUCCESS (5.365s) [664.432836] Starting dynamic subtest: B-HDMI-A2 [669.787768] Dynamic subtest B-HDMI-A2: SUCCESS (5.371s) [669.820578] Starting dynamic subtest: C-HDMI-A2 [675.168221] Dynamic subtest C-HDMI-A2: SUCCESS (5.385s) [675.171230] Subtest dpms-off-confusion-interruptible: SUCCESS (32.364s) [675.612251] [111/137] (512s left) kms_cursor_crc (cursor-offscreen-512x170) [675.863147] Starting subtest: cursor-offscreen-512x170 [675.869549] Subtest cursor-offscreen-512x170: SKIP (0.003s) [675.960605] [112/137] (511s left) gem_ctx_param (set-priority-range) [676.098936] Starting subtest: set-priority-range [676.154290] Subtest set-priority-range: SUCCESS (0.058s) [676.245582] [113/137] (511s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75) [676.469474] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75 [676.472650] Starting dynamic subtest: pipe-A-HDMI-A-1 [676.947322] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.380s) [677.011850] Starting dynamic subtest: pipe-B-HDMI-A-1 [677.207911] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.282s) [677.273420] Starting dynamic subtest: pipe-C-HDMI-A-1 [677.403674] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.098s) [677.468883] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SUCCESS (0.777s) [677.832021] [114/137] (510s left) kms_vblank (pipe-c-ts-continuation-modeset-rpm) [678.314364] Starting subtest: pipe-C-ts-continuation-modeset-rpm [683.263490] Subtest pipe-C-ts-continuation-modeset-rpm: SUCCESS (4.945s) [683.695936] [115/137] (504s left) syncobj_timeline (single-wait-all-signaled) [683.844191] Starting subtest: single-wait-all-signaled [683.847316] Subtest single-wait-all-signaled: SUCCESS (0.001s) [683.927803] [116/137] (504s left) kms_content_protection (dp-mst-lic-type-1) [684.173750] Starting subtest: dp-mst-lic-type-1 [684.184534] Subtest dp-mst-lic-type-1: SKIP (0.001s) [684.278263] [117/137] (503s left) perf_pmu (idle-no-semaphores) [684.418137] Starting subtest: idle-no-semaphores [684.421332] Starting dynamic subtest: rcs0 [684.932473] Dynamic subtest rcs0: SUCCESS (0.505s) [684.951545] Starting dynamic subtest: bcs0 [685.442442] Dynamic subtest bcs0: SUCCESS (0.504s) [685.461559] Starting dynamic subtest: vcs0 [685.942441] Dynamic subtest vcs0: SUCCESS (0.504s) [685.961573] Starting dynamic subtest: vecs0 [686.452456] Dynamic subtest vecs0: SUCCESS (0.504s) [686.457803] Subtest idle-no-semaphores: SUCCESS (2.020s) [686.527784] [118/137] (501s left) perf (blocking-parameterized) [686.756163] Starting subtest: blocking-parameterized [706.821770] Subtest blocking-parameterized: SUCCESS (20.063s) [706.875773] [119/137] (481s left) gem_exec_params (invalid-fence-in-submit) [707.017205] Starting subtest: invalid-fence-in-submit [707.020566] Subtest invalid-fence-in-submit: SUCCESS (0.004s) [707.113215] [120/137] (481s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff) [707.347934] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff [707.354151] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [707.441511] [121/137] (480s left) kms_ccs (pipe-c-bad-pixel-format-4_tiled_dg2_mc_ccs) [708.099243] Starting subtest: pipe-C-bad-pixel-format-4_tiled_dg2_mc_ccs [708.105234] Subtest pipe-C-bad-pixel-format-4_tiled_dg2_mc_ccs: SKIP (0.000s) [708.402775] [122/137] (480s left) kms_lease (lease_get) [708.621621] Starting subtest: lease_get [708.626500] Starting dynamic subtest: pipe-A-HDMI-A-1 [708.650877] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.012s) [708.699214] Starting dynamic subtest: pipe-A-HDMI-A-2 [708.702553] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.001s) [708.721787] Starting dynamic subtest: pipe-B-HDMI-A-1 [708.725537] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.001s) [708.744819] Starting dynamic subtest: pipe-B-HDMI-A-2 [708.747892] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.001s) [708.766154] Starting dynamic subtest: pipe-C-HDMI-A-1 [708.769068] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.001s) [708.787814] Starting dynamic subtest: pipe-C-HDMI-A-2 [708.790827] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.001s) [708.794074] Subtest lease_get: SUCCESS (0.043s) [708.828031] [123/137] (479s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-downscaling) [709.052719] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-downscaling [709.056262] Starting dynamic subtest: pipe-A-valid-mode [709.807503] Dynamic subtest pipe-A-valid-mode: SKIP (0.750s) [709.813266] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-downscaling: SKIP (0.753s) [710.211795] [124/137] (478s left) gem_exec_params (rsvd2-dirt) [710.353845] Starting subtest: rsvd2-dirt [710.360187] Subtest rsvd2-dirt: SKIP (0.000s) [710.444423] [125/137] (478s left) syncobj_wait (wait-any-snapshot) [710.581952] Starting subtest: wait-any-snapshot [710.667320] Subtest wait-any-snapshot: SUCCESS (0.083s) [710.766073] [126/137] (477s left) gem_mmap_wc (write-wc-read-gtt) [710.900790] Starting subtest: write-wc-read-gtt [711.384439] Subtest write-wc-read-gtt: SUCCESS (0.481s) [711.479065] [127/137] (477s left) kms_cursor_crc (cursor-sliding-256x256) [711.724909] Starting subtest: cursor-sliding-256x256 [711.786265] Starting dynamic subtest: pipe-A-HDMI-A-1 [715.763641] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (3.973s) [715.865419] Starting dynamic subtest: pipe-B-HDMI-A-2 [719.705606] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (3.862s) [719.808992] Starting dynamic subtest: pipe-C-HDMI-A-1 [723.913220] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (4.023s) [723.977990] Subtest cursor-sliding-256x256: SUCCESS (12.075s) [724.272848] [128/137] (464s left) gem_readwrite (new-obj) [724.764926] Starting subtest: new-obj [724.897518] Subtest new-obj: SUCCESS (0.000s) [725.140758] [129/137] (463s left) gem_exec_fence (syncobj-export) [725.759537] Starting subtest: syncobj-export [725.762810] Subtest syncobj-export: SUCCESS (0.006s) [726.087179] [130/137] (462s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu) [726.311230] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu [726.317391] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [726.388399] [131/137] (462s left) kms_ccs (pipe-d-bad-rotation-90-4_tiled_dg2_rc_ccs) [727.143846] Subtest pipe-D-bad-rotation-90-4_tiled_dg2_rc_ccs: SKIP (0.000s) [727.389782] [132/137] (461s left) kms_addfb_basic (unused-modifier) [727.531494] Starting subtest: unused-modifier [727.534740] Subtest unused-modifier: SUCCESS (0.000s) [727.884307] [133/137] (461s left) gem_spin_batch (legacy-resubmit) [728.038078] Starting subtest: legacy-resubmit [728.041367] Starting dynamic subtest: default [728.048640] Dynamic subtest default: SUCCESS (0.008s) [728.087068] Starting dynamic subtest: render [728.090207] Dynamic subtest render: SUCCESS (0.013s) [728.119201] Starting dynamic subtest: bsd [728.122293] Dynamic subtest bsd: SUCCESS (0.007s) [728.141161] Starting dynamic subtest: bsd1 [728.144299] Dynamic subtest bsd1: SUCCESS (0.008s) [728.163043] Starting dynamic subtest: blt [728.166034] Dynamic subtest blt: SUCCESS (0.012s) [728.184577] Starting dynamic subtest: vebox [728.187655] Dynamic subtest vebox: SUCCESS (0.008s) [728.190738] Subtest legacy-resubmit: SUCCESS (0.071s) [728.323043] [134/137] (460s left) sysfs_preempt_timeout (idempotent) [728.467402] Starting subtest: idempotent [728.470586] Starting dynamic subtest: vecs0 [728.473721] Dynamic subtest vecs0: SUCCESS (0.002s) [728.494040] Starting dynamic subtest: vcs0 [728.499133] Dynamic subtest vcs0: SUCCESS (0.001s) [728.520383] Starting dynamic subtest: bcs0 [728.523403] Dynamic subtest bcs0: SUCCESS (0.001s) [728.555636] Starting dynamic subtest: rcs0 [728.558914] Dynamic subtest rcs0: SUCCESS (0.001s) [728.562013] Subtest idempotent: SUCCESS (0.012s) [728.596171] [135/137] (460s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [728.832223] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip [729.795387] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.964s) [730.216852] [136/137] (459s left) gem_exec_reloc (basic-gtt-wc-noreloc) [730.362247] Starting subtest: basic-gtt-wc-noreloc [730.372843] Subtest basic-gtt-wc-noreloc: SUCCESS (0.011s) [730.547833] [137/137] (458s left) sw_sync (sync_multi_timeline_wait) [730.680217] Starting subtest: sync_multi_timeline_wait [730.683554] Subtest sync_multi_timeline_wait: SUCCESS (0.001s) [730.740669] Closing watchdogs Done.