[36.469508] Initializing watchdogs [36.469547] /dev/watchdog0 [36.475100] [001/140] (960s left) gem_ctx_bad_destroy (invalid-pad) [36.515210] Starting subtest: invalid-pad [36.518050] Subtest invalid-pad: SUCCESS (0.000s) [36.580185] [002/140] (960s left) fbdev (write) [36.651048] Starting subtest: write [37.112491] Subtest write: SUCCESS (0.460s) [37.165947] [003/140] (959s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render) [37.232500] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render [40.446487] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (3.221s) [40.557798] [004/140] (956s left) v3d/v3d_submit_cl (single-in-sync) [40.664467] Subtest single-in-sync: SKIP (0.000s) [40.712962] [005/140] (956s left) kms_writeback (writeback-pixel-formats) [40.805471] Subtest writeback-pixel-formats: SKIP (0.000s) [40.820172] [006/140] (956s left) kms_pm_lpsp (screens-disabled) [41.910255] Starting subtest: screens-disabled [41.915090] Subtest screens-disabled: SKIP (0.000s) [41.971561] [007/140] (955s left) gem_userptr_blits (readonly-unsync) [42.046694] Subtest readonly-unsync: SKIP (0.000s) [42.146579] [008/140] (954s left) syncobj_timeline (etime-single-wait-submitted) [42.209033] Starting subtest: etime-single-wait-submitted [42.319324] Subtest etime-single-wait-submitted: SUCCESS (0.109s) [42.390498] [009/140] (954s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c) [42.441182] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s) [42.455119] [010/140] (954s left) gem_ctx_param (basic) [42.509603] Starting subtest: basic [42.512228] Subtest basic: SUCCESS (0.000s) [42.562410] [011/140] (954s left) kms_rotation_crc (multiplane-rotation-cropping-top) [42.642730] Starting subtest: multiplane-rotation-cropping-top [50.487505] Subtest multiplane-rotation-cropping-top: SUCCESS (7.848s) [50.585218] [012/140] (946s left) kms_ccs (pipe-d-crc-primary-basic-y-tiled-ccs) [50.993589] Starting subtest: pipe-D-crc-primary-basic-y-tiled-ccs [50.998725] Subtest pipe-D-crc-primary-basic-y-tiled-ccs: SKIP (0.000s) [51.878384] [013/140] (945s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-mmap-gtt) [51.956379] Starting subtest: psr-1p-offscren-pri-shrfb-draw-mmap-gtt [51.961134] Subtest psr-1p-offscren-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [52.050242] [014/140] (945s left) kms_atomic_transition (plane-toggle-modeset-transition) [52.153663] Starting subtest: plane-toggle-modeset-transition [52.156260] Starting dynamic subtest: pipe-A-eDP-1 [66.556051] Dynamic subtest pipe-A-eDP-1: SUCCESS (14.401s) [66.573964] Starting dynamic subtest: pipe-B-eDP-1 [80.497162] Dynamic subtest pipe-B-eDP-1: SUCCESS (13.935s) [80.499866] Subtest plane-toggle-modeset-transition: SUCCESS (28.345s) [81.381653] [015/140] (915s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc) [81.458845] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc [83.947315] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (2.494s) [84.054617] [016/140] (913s left) kms_ccs (pipe-b-ccs-on-another-bo-4-tiled-mtl-rc-ccs) [84.430366] Starting subtest: pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs [84.433529] Subtest pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.000s) [85.287728] [017/140] (912s left) vc4/vc4_perfmon (get-values-invalid-pointer) [85.394490] Subtest get-values-invalid-pointer: SKIP (0.000s) [85.446452] [018/140] (911s left) kms_flip (absolute-wf_vblank) [85.520605] Starting subtest: absolute-wf_vblank [85.523105] Starting dynamic subtest: A-eDP1 [94.529108] Dynamic subtest A-eDP1: SUCCESS (8.974s) [94.545157] Starting dynamic subtest: B-eDP1 [103.359705] Dynamic subtest B-eDP1: SUCCESS (8.831s) [103.365605] Starting dynamic subtest: C-eDP1 [112.134847] Dynamic subtest C-eDP1: SUCCESS (8.808s) [112.154228] Starting dynamic subtest: D-eDP1 [121.024395] Dynamic subtest D-eDP1: SUCCESS (8.843s) [121.026746] Subtest absolute-wf_vblank: SUCCESS (35.471s) [122.276135] [019/140] (875s left) gem_wait (write-busy) [122.347996] Starting subtest: write-busy [122.350362] Starting dynamic subtest: all-engines [122.932852] Dynamic subtest all-engines: SUCCESS (0.584s) [122.947446] Starting dynamic subtest: rcs0 [123.492670] Dynamic subtest rcs0: SUCCESS (0.550s) [123.506849] Starting dynamic subtest: bcs0 [124.041373] Dynamic subtest bcs0: SUCCESS (0.547s) [124.054660] Starting dynamic subtest: vcs0 [125.418150] Dynamic subtest vcs0: SUCCESS (1.384s) [125.444270] Starting dynamic subtest: vcs1 [127.466478] Dynamic subtest vcs1: SUCCESS (2.047s) [127.490680] Starting dynamic subtest: vecs0 [129.450229] Dynamic subtest vecs0: SUCCESS (1.983s) [129.478767] Starting dynamic subtest: ccs0 [130.008822] Dynamic subtest ccs0: SUCCESS (0.559s) [130.011573] Subtest write-busy: SUCCESS (7.660s) [130.218817] [020/140] (867s left) kms_prop_blob (invalid-get-prop-any) [130.275549] Starting subtest: invalid-get-prop-any [130.278242] Subtest invalid-get-prop-any: SUCCESS (0.000s) [130.347750] [021/140] (867s left) gem_softpin (safe-alignment) [130.414808] Starting subtest: safe-alignment [130.464404] Subtest safe-alignment: SUCCESS (0.049s) [132.497204] [022/140] (865s left) kms_flip (blocking-wf_vblank) [132.623546] Starting subtest: blocking-wf_vblank [132.626097] Starting dynamic subtest: A-eDP1 [141.684117] Dynamic subtest A-eDP1: SUCCESS (9.027s) [141.691849] Starting dynamic subtest: B-eDP1 [150.705840] Dynamic subtest B-eDP1: SUCCESS (9.021s) [150.721592] Starting dynamic subtest: C-eDP1 [159.686449] Dynamic subtest C-eDP1: SUCCESS (9.013s) [159.703866] Starting dynamic subtest: D-eDP1 [168.771666] Dynamic subtest D-eDP1: SUCCESS (9.085s) [168.780287] Subtest blocking-wf_vblank: SUCCESS (36.161s) [170.023506] [023/140] (827s left) syncobj_wait (wait-all-for-submit-complex) [170.064405] Starting subtest: wait-all-for-submit-complex [170.279657] Subtest wait-all-for-submit-complex: SUCCESS (0.215s) [170.352273] [024/140] (827s left) kms_hdr (invalid-metadata-sizes) [170.421991] Starting subtest: invalid-metadata-sizes [170.429026] Subtest invalid-metadata-sizes: SKIP (0.000s) [170.484166] [025/140] (827s left) kms_flip (flip-vs-suspend-interruptible) [170.545275] Starting subtest: flip-vs-suspend-interruptible [170.548134] Starting dynamic subtest: A-eDP1 [219.983915] Dynamic subtest A-eDP1: SUCCESS (4.621s) [220.000397] Starting dynamic subtest: B-eDP1 [268.968414] Dynamic subtest B-eDP1: SUCCESS (4.563s) [268.982470] Starting dynamic subtest: C-eDP1 [317.963349] Dynamic subtest C-eDP1: SUCCESS (4.602s) [317.975343] Starting dynamic subtest: D-eDP1 [366.962709] Dynamic subtest D-eDP1: SUCCESS (4.533s) [366.970702] Subtest flip-vs-suspend-interruptible: SUCCESS (18.335s) [368.211835] [026/140] (807s left) kms_cursor_legacy (cursor-vs-flip-varying-size) [368.335369] Starting subtest: cursor-vs-flip-varying-size [397.208900] Subtest cursor-vs-flip-varying-size: SUCCESS (28.873s) [398.090867] [027/140] (777s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-upscaling) [398.156622] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-upscaling [398.159063] Starting dynamic subtest: pipe-A-default-mode [401.142735] Dynamic subtest pipe-A-default-mode: SUCCESS (2.988s) [401.145191] Subtest flip-64bpp-xtile-to-16bpp-xtile-upscaling: SUCCESS (2.990s) [402.068369] [028/140] (773s left) kms_ccs (pipe-c-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs) [402.469000] Starting subtest: pipe-C-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs [402.474725] Subtest pipe-C-crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.001s) [403.347745] [029/140] (772s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-pgflip-blt) [403.427399] Starting subtest: psr-2p-scndscrn-shrfb-pgflip-blt [403.432277] Subtest psr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s) [403.527030] [030/140] (772s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-pwrite) [403.603325] Starting subtest: fbc-rgb101010-draw-pwrite [407.773852] Subtest fbc-rgb101010-draw-pwrite: SUCCESS (4.174s) [407.884487] [031/140] (767s left) gem_exec_reloc (basic-write-gtt) [407.950535] Subtest basic-write-gtt: SKIP (0.000s) [408.011838] [032/140] (767s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [408.101426] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip [408.106260] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s) [408.172977] [033/140] (767s left) kms_frontbuffer_tracking (fbcpsr-tiling-linear) [408.244526] Starting subtest: fbcpsr-tiling-linear [412.840266] Subtest fbcpsr-tiling-linear: SUCCESS (4.598s) [412.950219] [034/140] (763s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff) [413.006753] Starting subtest: psr-2p-primscrn-spr-indfb-onoff [413.010892] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s) [413.087159] [035/140] (762s left) kms_big_fb (y-tiled-64bpp-rotate-0) [413.159175] Starting subtest: y-tiled-64bpp-rotate-0 [413.162990] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s) [413.245304] [036/140] (762s left) gem_exec_create (basic) [413.320336] Starting subtest: basic [413.322603] Starting dynamic subtest: smem [416.536989] Dynamic subtest smem: SUCCESS (3.216s) [416.539712] Subtest basic: SUCCESS (3.218s) [416.720000] [037/140] (759s left) kms_lease (lease-get) [416.807060] Starting subtest: lease-get [416.810345] Starting dynamic subtest: pipe-A-eDP-1 [416.812781] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [416.818978] Starting dynamic subtest: pipe-B-eDP-1 [416.821338] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [416.827344] Starting dynamic subtest: pipe-C-eDP-1 [416.829658] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [416.835727] Starting dynamic subtest: pipe-D-eDP-1 [416.837773] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [416.839747] Subtest lease-get: SUCCESS (0.005s) [416.867715] [038/140] (759s left) kms_ccs (pipe-b-missing-ccs-buffer-4-tiled-mtl-mc-ccs) [417.256661] Starting subtest: pipe-B-missing-ccs-buffer-4-tiled-mtl-mc-ccs [417.281096] Subtest pipe-B-missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.021s) [418.108873] [039/140] (757s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-y-tiled) [418.183585] Starting subtest: y-tiled-mc-ccs-to-vebox-y-tiled [418.188339] Subtest y-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s) [418.367400] [040/140] (757s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size) [418.459333] Starting subtest: basic-busy-flip-before-cursor-varying-size [418.464823] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s) [418.555454] [041/140] (757s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25) [418.612287] Starting subtest: planes-unity-scaling-downscale-factor-0-25 [418.615448] Starting dynamic subtest: pipe-A-eDP-1 [418.625441] Dynamic subtest pipe-A-eDP-1: SKIP (0.007s) [418.644601] Starting dynamic subtest: pipe-B-eDP-1 [418.650075] Dynamic subtest pipe-B-eDP-1: SKIP (0.015s) [418.664144] Starting dynamic subtest: pipe-C-eDP-1 [418.670023] Dynamic subtest pipe-C-eDP-1: SKIP (0.015s) [418.678126] Starting dynamic subtest: pipe-D-eDP-1 [418.682319] Dynamic subtest pipe-D-eDP-1: SKIP (0.015s) [418.686320] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (0.056s) [418.737693] [042/140] (757s left) v3d/v3d_wait_bo (bad-pad) [418.833890] Subtest bad-pad: SKIP (0.000s) [418.896877] [043/140] (757s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [419.006970] Starting subtest: basic-flip-after-cursor-legacy [419.952388] Subtest basic-flip-after-cursor-legacy: SUCCESS (0.942s) [420.886969] [044/140] (755s left) kms_ccs (pipe-c-bad-rotation-90-4-tiled-mtl-rc-ccs-cc) [421.291344] Starting subtest: pipe-C-bad-rotation-90-4-tiled-mtl-rc-ccs-cc [422.109799] Subtest pipe-C-bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.817s) [423.281915] [045/140] (752s left) kms_vblank (query-forked-hang) [423.358603] Starting subtest: query-forked-hang [423.361098] Starting dynamic subtest: pipe-A-eDP-1 [437.436821] Dynamic subtest pipe-A-eDP-1: SUCCESS (14.075s) [437.456191] Starting dynamic subtest: pipe-D-eDP-1 [452.353209] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.908s) [452.355655] Subtest query-forked-hang: SUCCESS (28.998s) [453.269833] [046/140] (723s left) kms_big_fb (4-tiled-addfb-size-overflow) [453.360751] Starting subtest: 4-tiled-addfb-size-overflow [453.362735] Subtest 4-tiled-addfb-size-overflow: SUCCESS (0.000s) [453.443748] [047/140] (722s left) v3d/v3d_job_submission (array-job-submission) [453.556963] Subtest array-job-submission: SKIP (0.000s) [453.612830] [048/140] (722s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff) [453.676283] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff [456.606877] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (2.936s) [456.752841] [049/140] (719s left) kms_pipe_crc_basic (suspend-read-crc) [456.824415] Starting subtest: suspend-read-crc [456.826371] Starting dynamic subtest: pipe-A-eDP-1 [474.848025] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.567s) [474.863617] Starting dynamic subtest: pipe-B-eDP-1 [493.929141] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.317s) [493.949037] Starting dynamic subtest: pipe-C-eDP-1 [512.814400] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.131s) [512.828408] Starting dynamic subtest: pipe-D-eDP-1 [530.824674] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.166s) [530.827408] Subtest suspend-read-crc: SUCCESS (12.197s) [531.712225] [050/140] (706s left) kms_ccs (pipe-d-random-ccs-data-y-tiled-gen12-rc-ccs-cc) [532.118557] Starting subtest: pipe-D-random-ccs-data-y-tiled-gen12-rc-ccs-cc [532.128537] Subtest pipe-D-random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [532.946113] [051/140] (705s left) kms_content_protection (lic) [533.067816] Starting subtest: lic [536.592015] Subtest lic: SKIP (3.522s) [537.843750] [052/140] (700s left) kms_big_fb (yf-tiled-32bpp-rotate-270) [537.936315] Starting subtest: yf-tiled-32bpp-rotate-270 [537.941028] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s) [538.033483] [053/140] (700s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc) [538.153259] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc [538.157962] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [538.239243] [054/140] (700s left) kms_cursor_crc (cursor-random-512x170) [538.436219] Starting subtest: cursor-random-512x170 [538.441177] Subtest cursor-random-512x170: SKIP (0.001s) [538.559873] [055/140] (699s left) gem_exec_schedule (preempt-other-chain) [538.653784] Starting subtest: preempt-other-chain [538.655832] Starting dynamic subtest: rcs0 [538.769589] Dynamic subtest rcs0: SUCCESS (0.126s) [538.787552] Starting dynamic subtest: bcs0 [538.874368] Dynamic subtest bcs0: SUCCESS (0.105s) [538.881056] Starting dynamic subtest: vcs0 [538.989382] Dynamic subtest vcs0: SUCCESS (0.115s) [538.996154] Starting dynamic subtest: vcs1 [539.131620] Dynamic subtest vcs1: SUCCESS (0.141s) [539.138399] Starting dynamic subtest: vecs0 [539.262803] Dynamic subtest vecs0: SUCCESS (0.130s) [539.269181] Starting dynamic subtest: ccs0 [539.392968] Dynamic subtest ccs0: SUCCESS (0.130s) [539.395081] Subtest preempt-other-chain: SUCCESS (0.750s) [540.440701] [056/140] (697s left) api_intel_bb (object-reloc-purge-cache) [540.498159] Starting subtest: object-reloc-purge-cache [540.503125] Subtest object-reloc-purge-cache: SKIP (0.003s) [540.586358] [057/140] (697s left) perf_pmu (busy-start) [540.658145] Starting subtest: busy-start [540.660821] Starting dynamic subtest: rcs0 [543.215129] Dynamic subtest rcs0: SUCCESS (2.556s) [543.235233] Starting dynamic subtest: bcs0 [545.766881] Dynamic subtest bcs0: SUCCESS (2.550s) [545.786545] Starting dynamic subtest: vcs0 [549.312772] Dynamic subtest vcs0: SUCCESS (3.543s) [549.332405] Starting dynamic subtest: vcs1 [553.345908] Dynamic subtest vcs1: SUCCESS (4.030s) [553.365886] Starting dynamic subtest: vecs0 [557.312129] Dynamic subtest vecs0: SUCCESS (3.966s) [557.332513] Starting dynamic subtest: ccs0 [559.883859] Dynamic subtest ccs0: SUCCESS (2.550s) [559.886553] Subtest busy-start: SUCCESS (19.206s) [559.945933] [058/140] (678s left) kms_cursor_legacy (flip-vs-cursor-toggle) [560.029631] Starting subtest: flip-vs-cursor-toggle [566.223356] Subtest flip-vs-cursor-toggle: SUCCESS (6.193s) [567.174968] [059/140] (671s left) kms_ccs (pipe-a-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc) [567.557783] Starting subtest: pipe-A-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc [567.562920] Subtest pipe-A-missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [568.436845] [060/140] (670s left) kms_psr (pr_sprite_plane_onoff) [568.522812] Starting subtest: pr_sprite_plane_onoff [568.527103] Subtest pr_sprite_plane_onoff: SKIP (0.000s) [568.648111] [061/140] (669s left) kms_chamelium_hpd (common-hpd-after-suspend) [569.017429] Subtest common-hpd-after-suspend: SKIP (0.000s) [569.889398] [062/140] (668s left) gem_partial_pwrite_pread (write-uncached) [569.949374] Subtest write-uncached: SKIP (0.000s) [569.995915] [063/140] (668s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions) [570.104157] Starting subtest: cursorA-vs-flipA-atomic-transitions [581.305036] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.200s) [582.197259] [064/140] (656s left) kms_psr (psr_cursor_mmap_cpu) [582.268605] Starting subtest: psr_cursor_mmap_cpu [582.271225] Starting dynamic subtest: eDP-1 [584.100285] Dynamic subtest eDP-1: SUCCESS (1.829s) [584.103026] Subtest psr_cursor_mmap_cpu: SUCCESS (1.831s) [585.023041] [065/140] (653s left) kms_dither (fb-8bpc-vs-panel-8bpc) [585.116213] Starting subtest: fb-8bpc-vs-panel-8bpc [585.118767] Starting dynamic subtest: pipe-A-eDP-1 [586.582366] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.463s) [586.585009] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.466s) [587.521268] [066/140] (651s left) kms_ccs (pipe-a-bad-rotation-90-4-tiled-mtl-rc-ccs) [587.899683] Starting subtest: pipe-A-bad-rotation-90-4-tiled-mtl-rc-ccs [588.742112] Subtest pipe-A-bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.846s) [589.929898] [067/140] (648s left) kms_ccs (pipe-a-bad-aux-stride-y-tiled-gen12-rc-ccs-cc) [590.309038] Starting subtest: pipe-A-bad-aux-stride-y-tiled-gen12-rc-ccs-cc [590.314137] Subtest pipe-A-bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [591.167942] [068/140] (647s left) gen7_exec_parse (chained-batch) [591.213655] Subtest chained-batch: SKIP (0.000s) [591.256703] [069/140] (647s left) gem_ctx_persistence (legacy-engines-mixed) [591.295245] Starting subtest: legacy-engines-mixed [591.297756] Starting dynamic subtest: render [591.341667] Dynamic subtest render: SUCCESS (0.047s) [591.364423] Starting dynamic subtest: bsd1 [591.396933] Dynamic subtest bsd1: SUCCESS (0.053s) [591.416986] Starting dynamic subtest: bsd2 [591.446949] Dynamic subtest bsd2: SUCCESS (0.048s) [591.468056] Starting dynamic subtest: blt [591.494188] Dynamic subtest blt: SUCCESS (0.045s) [591.513837] Starting dynamic subtest: vebox [591.541463] Dynamic subtest vebox: SUCCESS (0.045s) [591.544311] Subtest legacy-engines-mixed: SUCCESS (0.247s) [591.616559] [070/140] (647s left) kms_ccs (pipe-d-ccs-on-another-bo-y-tiled-ccs) [592.040045] Starting subtest: pipe-D-ccs-on-another-bo-y-tiled-ccs [592.045681] Subtest pipe-D-ccs-on-another-bo-y-tiled-ccs: SKIP (0.000s) [592.894180] [071/140] (645s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite) [592.966199] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite [597.497040] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.531s) [597.607747] [072/140] (641s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu) [597.668886] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu [602.299246] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (4.636s) [602.425359] [073/140] (636s left) gem_ctx_persistence (engines-hostile) [602.473204] Starting subtest: engines-hostile [602.475489] Starting dynamic subtest: rcs0 [602.570370] Dynamic subtest rcs0: SUCCESS (0.098s) [602.589818] Starting dynamic subtest: bcs0 [602.652887] Dynamic subtest bcs0: SUCCESS (0.080s) [602.668743] Starting dynamic subtest: vcs0 [602.738057] Dynamic subtest vcs0: SUCCESS (0.082s) [602.757545] Starting dynamic subtest: vcs1 [602.822352] Dynamic subtest vcs1: SUCCESS (0.084s) [602.842508] Starting dynamic subtest: vecs0 [602.906250] Dynamic subtest vecs0: SUCCESS (0.082s) [602.926084] Starting dynamic subtest: ccs0 [602.976687] Dynamic subtest ccs0: SUCCESS (0.069s) [602.979871] Subtest engines-hostile: SUCCESS (0.504s) [603.084327] [074/140] (635s left) gem_exercise_blt (fast-copy) [603.140970] Starting subtest: fast-copy [603.143879] Starting dynamic subtest: linear-smem-smem [603.161705] Dynamic subtest linear-smem-smem: SUCCESS (0.023s) [603.172919] Starting dynamic subtest: xmajor-smem-smem [603.185771] Dynamic subtest xmajor-smem-smem: SUCCESS (0.024s) [603.193496] Starting dynamic subtest: tile4-smem-smem [603.223288] Dynamic subtest tile4-smem-smem: SUCCESS (0.036s) [603.235798] Starting dynamic subtest: tile64-smem-smem [603.242156] Dynamic subtest tile64-smem-smem: SUCCESS (0.018s) [603.244442] Subtest fast-copy: SUCCESS (0.104s) [603.341673] [075/140] (635s left) i915_pm_rpm (system-suspend) [605.494693] Starting subtest: system-suspend [627.967428] Subtest system-suspend: SUCCESS (7.814s) [628.771842] [076/140] (624s left) syncobj_timeline (wait-all-delayed-signal) [628.824481] Starting subtest: wait-all-delayed-signal [628.925661] Subtest wait-all-delayed-signal: SUCCESS (0.102s) [629.001553] [077/140] (624s left) kms_atomic (plane-cursor-legacy) [629.093125] Starting subtest: plane-cursor-legacy [629.095810] Starting dynamic subtest: pipe-A-eDP-1 [629.491360] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.399s) [629.494092] Subtest plane-cursor-legacy: SUCCESS (0.402s) [630.403909] [078/140] (623s left) api_intel_allocator (open-vm) [630.469582] Starting subtest: open-vm [630.475972] Subtest open-vm: SUCCESS (0.007s) [630.581394] [079/140] (623s left) gem_exec_fence (syncobj-wait) [631.052753] Starting subtest: syncobj-wait [632.988919] Subtest syncobj-wait: SUCCESS (1.935s) [635.051631] [080/140] (618s left) kms_cursor_crc (cursor-sliding-32x32) [635.164813] Starting subtest: cursor-sliding-32x32 [635.198749] Subtest cursor-sliding-32x32: SKIP (0.028s) [635.429473] [081/140] (618s left) perf (disabled-read-error) [635.569171] Starting subtest: disabled-read-error [635.585684] Subtest disabled-read-error: SUCCESS (0.017s) [635.616133] [082/140] (618s left) syncobj_wait (invalid-single-wait-all-unsubmitted) [635.655765] Starting subtest: invalid-single-wait-all-unsubmitted [635.658171] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s) [635.706347] [083/140] (618s left) kms_fence_pin_leak [635.809095] [084/140] (618s left) gem_exec_suspend (basic-s0) [635.880773] Starting subtest: basic-S0 [635.883411] Starting dynamic subtest: smem [656.867537] Dynamic subtest smem: SUCCESS (7.067s) [656.870873] Subtest basic-S0: SUCCESS (7.068s) [657.057639] [085/140] (610s left) perf (invalid-remove-userspace-config) [657.164647] Starting subtest: invalid-remove-userspace-config [657.186667] Subtest invalid-remove-userspace-config: SUCCESS (0.022s) [657.222632] [086/140] (610s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-mmap-wc) [657.327179] Starting subtest: psr-1p-primscrn-spr-indfb-draw-mmap-wc [661.903394] Subtest psr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (4.580s) [662.015262] [087/140] (605s left) gem_userptr_blits (create-destroy-unsync) [662.094820] Subtest create-destroy-unsync: SKIP (0.000s) [662.203009] [088/140] (605s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt) [662.304573] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt [662.309071] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s) [662.394751] [089/140] (605s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted) [662.432862] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted [662.533646] Subtest etime-multi-wait-all-for-submit-available-unsubmitted: SUCCESS (0.103s) [662.612976] [090/140] (605s left) kms_flip (plain-flip-interruptible) [662.690571] Starting subtest: plain-flip-interruptible [662.696721] Starting dynamic subtest: A-eDP1 [664.440703] Dynamic subtest A-eDP1: SUCCESS (1.744s) [664.461733] Starting dynamic subtest: B-eDP1 [666.170065] Dynamic subtest B-eDP1: SUCCESS (1.727s) [666.190708] Starting dynamic subtest: C-eDP1 [667.949176] Dynamic subtest C-eDP1: SUCCESS (1.778s) [667.970861] Starting dynamic subtest: D-eDP1 [669.691162] Dynamic subtest D-eDP1: SUCCESS (1.740s) [669.698696] Subtest plain-flip-interruptible: SUCCESS (7.008s) [670.948126] [091/140] (597s left) kms_ccs (pipe-c-bad-aux-stride-yf-tiled-ccs) [671.374657] Starting subtest: pipe-C-bad-aux-stride-yf-tiled-ccs [671.380690] Subtest pipe-C-bad-aux-stride-yf-tiled-ccs: SKIP (0.000s) [672.188612] [092/140] (595s left) perf (low-oa-exponent-permissions) [672.308578] Starting subtest: low-oa-exponent-permissions [672.364422] Subtest low-oa-exponent-permissions: SUCCESS (0.056s) [672.413845] [093/140] (595s left) kms_ccs (pipe-c-crc-primary-basic-4-tiled-dg2-rc-ccs-cc) [672.829934] Starting subtest: pipe-C-crc-primary-basic-4-tiled-dg2-rc-ccs-cc [672.835984] Subtest pipe-C-crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s) [673.696506] [094/140] (594s left) gem_mmap_offset (close-race) [673.757041] Starting subtest: close-race [693.922941] Subtest close-race: SUCCESS (20.114s) [693.967745] [095/140] (574s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu) [694.078396] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu [694.083431] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [694.151713] [096/140] (574s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180) [694.226040] Starting subtest: linear-max-hw-stride-32bpp-rotate-180 [695.305934] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.081s) [695.429391] [097/140] (572s left) i915_query (query-regions-garbage-items) [695.503384] Starting subtest: query-regions-garbage-items [695.505458] Subtest query-regions-garbage-items: SUCCESS (0.001s) [695.577537] [098/140] (572s left) gem_pread (self) [695.640015] Subtest self: SKIP (0.000s) [695.681698] [099/140] (572s left) kms_atomic_transition (plane-all-modeset-transition-internal-panels) [695.723878] Starting subtest: plane-all-modeset-transition-internal-panels [695.726225] Starting dynamic subtest: pipe-A-eDP-1 [697.425667] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.701s) [697.767972] Starting dynamic subtest: pipe-B-eDP-1 [699.141186] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.391s) [699.452529] Subtest plane-all-modeset-transition-internal-panels: SUCCESS (3.729s) [700.387707] [100/140] (567s left) kms_frontbuffer_tracking (fbc-2p-rte) [700.443886] Starting subtest: fbc-2p-rte [700.448026] Subtest fbc-2p-rte: SKIP (0.000s) [700.520031] [101/140] (567s left) kms_async_flips (test-time-stamp) [700.602040] Starting subtest: test-time-stamp [700.997837] Starting dynamic subtest: pipe-A-eDP-1 [701.841665] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.841s) [702.249950] Starting dynamic subtest: pipe-B-eDP-1 [703.113905] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.881s) [703.493928] Starting dynamic subtest: pipe-C-eDP-1 [704.329071] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.847s) [704.722868] Starting dynamic subtest: pipe-D-eDP-1 [705.608696] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.899s) [705.611365] Subtest test-time-stamp: SUCCESS (5.007s) [706.916770] [102/140] (561s left) perf_pmu (render-node-busy) [706.993203] Starting subtest: render-node-busy [706.995649] Starting dynamic subtest: rcs0 [707.574448] Dynamic subtest rcs0: SUCCESS (0.583s) [707.595291] Starting dynamic subtest: bcs0 [708.131879] Dynamic subtest bcs0: SUCCESS (0.556s) [708.152959] Starting dynamic subtest: vcs0 [709.921746] Dynamic subtest vcs0: SUCCESS (1.788s) [709.943632] Starting dynamic subtest: vcs1 [711.906551] Dynamic subtest vcs1: SUCCESS (1.982s) [711.927713] Starting dynamic subtest: vecs0 [713.892912] Dynamic subtest vecs0: SUCCESS (1.982s) [713.917985] Starting dynamic subtest: ccs0 [714.458404] Dynamic subtest ccs0: SUCCESS (0.565s) [714.461529] Subtest render-node-busy: SUCCESS (7.468s) [714.530191] [103/140] (553s left) gem_render_copy (yf-tiled) [714.597844] Starting subtest: yf-tiled [714.602710] Subtest yf-tiled: SKIP (0.000s) [714.785861] [104/140] (553s left) kms_ccs (pipe-b-bad-rotation-90-4-tiled-mtl-rc-ccs) [715.167858] Starting subtest: pipe-B-bad-rotation-90-4-tiled-mtl-rc-ccs [716.009083] Subtest pipe-B-bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.841s) [717.204528] [105/140] (551s left) kms_big_fb (x-tiled-8bpp-rotate-0) [717.301203] Starting subtest: x-tiled-8bpp-rotate-0 [718.356791] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.054s) [718.451337] [106/140] (550s left) kms_chamelium_color (ctm-0-75) [718.536523] Subtest ctm-0-75: SKIP (0.000s) [718.571899] [107/140] (549s left) gem_ctx_sseu (mmap-args) [718.641270] Subtest mmap-args: SKIP (0.000s) [718.671116] [108/140] (549s left) kms_plane_lowres (tiling-x) [718.757460] Starting subtest: tiling-x [718.759602] Starting dynamic subtest: pipe-A-eDP-1 [718.763810] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [719.067359] Starting dynamic subtest: pipe-B-eDP-1 [719.072709] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [719.088023] Starting dynamic subtest: pipe-C-eDP-1 [719.093114] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [719.104027] Starting dynamic subtest: pipe-D-eDP-1 [719.108570] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [719.113188] Subtest tiling-x: SKIP (0.301s) [719.970316] [109/140] (548s left) gem_unfence_active_buffers [720.116708] [110/140] (548s left) drm_fdinfo (virtual-busy-hang-all) [720.156554] Starting subtest: virtual-busy-hang-all [720.161649] Subtest virtual-busy-hang-all: SKIP (0.000s) [720.216180] [111/140] (548s left) kms_ccs (pipe-a-crc-primary-basic-y-tiled-gen12-rc-ccs) [720.566810] Starting subtest: pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs [720.572004] Subtest pipe-A-crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.001s) [721.443617] [112/140] (547s left) perf_pmu (interrupts) [721.506693] Starting subtest: interrupts [722.582588] Subtest interrupts: SUCCESS (1.077s) [722.691258] [113/140] (546s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu) [722.771602] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu [722.775955] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [722.859026] [114/140] (545s left) gem_ctx_exec (basic) [722.908709] Starting subtest: basic [722.914158] Subtest basic: SUCCESS (0.007s) [723.023573] [115/140] (545s left) kms_color (ctm-0-75) [723.094827] Starting subtest: ctm-0-75 [723.096939] Starting dynamic subtest: pipe-A [728.274869] Dynamic subtest pipe-A: SUCCESS (5.184s) [728.295439] Starting dynamic subtest: pipe-B [734.251957] Dynamic subtest pipe-B: SUCCESS (5.975s) [734.268760] Starting dynamic subtest: pipe-C [739.955775] Dynamic subtest pipe-C: SUCCESS (5.701s) [739.968996] Starting dynamic subtest: pipe-D [745.709888] Dynamic subtest pipe-D: SUCCESS (5.750s) [745.713091] Subtest ctm-0-75: SUCCESS (22.617s) [746.601008] [116/140] (522s left) gem_lmem_swapping (smem-oom) [746.685806] Subtest smem-oom: SKIP (0.000s) [746.717071] [117/140] (522s left) syncobj_basic (test-valid-cycle) [746.787999] Starting subtest: test-valid-cycle [746.790680] Subtest test-valid-cycle: SUCCESS (0.000s) [746.864580] [118/140] (522s left) gem_exec_schedule (deep) [747.092841] Starting subtest: deep [747.095625] Starting dynamic subtest: rcs0 [750.985809] Dynamic subtest rcs0: SKIP (3.890s) [751.009742] Starting dynamic subtest: vcs0 [761.122749] Dynamic subtest vcs0: SUCCESS (10.137s) [761.136845] Starting dynamic subtest: vcs1 [771.613875] Dynamic subtest vcs1: SUCCESS (10.490s) [771.629181] Starting dynamic subtest: vecs0 [782.212919] Dynamic subtest vecs0: SUCCESS (10.599s) [782.223074] Starting dynamic subtest: ccs0 [792.625552] Dynamic subtest ccs0: SUCCESS (10.411s) [792.628050] Subtest deep: SUCCESS (45.533s) [793.338686] [119/140] (475s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted) [793.382126] Starting subtest: multi-wait-all-for-submit-available-submitted [793.384900] Subtest multi-wait-all-for-submit-available-submitted: SUCCESS (0.000s) [793.453080] [120/140] (475s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt) [793.551399] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt [798.269027] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.720s) [798.431907] [121/140] (470s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt) [798.504770] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt [798.508799] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s) [798.590077] [122/140] (470s left) device_reset (cold-reset-bound) [798.710097] Subtest cold-reset-bound: SKIP (0.000s) [798.780827] [123/140] (470s left) vc4/vc4_wait_bo (unused-bo-1ns) [798.832652] Subtest unused-bo-1ns: SKIP (0.000s) [798.877036] [124/140] (470s left) syncobj_wait (multi-wait-all-signaled) [798.915854] Starting subtest: multi-wait-all-signaled [798.919830] Subtest multi-wait-all-signaled: SUCCESS (0.000s) [798.961027] [125/140] (470s left) kms_plane_scaling (plane-downscale-factor-0-75-with-rotation) [799.029187] Starting subtest: plane-downscale-factor-0-75-with-rotation [799.031550] Starting dynamic subtest: pipe-A-eDP-1 [799.535792] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.509s) [799.548967] Starting dynamic subtest: pipe-B-eDP-1 [801.217695] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.679s) [801.232434] Starting dynamic subtest: pipe-C-eDP-1 [802.860234] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.640s) [802.880755] Starting dynamic subtest: pipe-D-eDP-1 [804.547696] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.684s) [804.550704] Subtest plane-downscale-factor-0-75-with-rotation: SUCCESS (5.521s) [805.797302] [126/140] (463s left) syncobj_timeline (signal-array) [805.858729] Starting subtest: signal-array [805.861351] Subtest signal-array: SUCCESS (0.000s) [805.927548] [127/140] (463s left) gem_sync (basic-all) [806.012762] Starting subtest: basic-all [809.890555] Subtest basic-all: SUCCESS (3.884s) [810.083908] [128/140] (459s left) gem_ctx_engines (none) [810.145569] Starting subtest: none [810.148284] Subtest none: SUCCESS (0.001s) [810.326894] [129/140] (458s left) kms_psr (pr_basic) [810.411401] Starting subtest: pr_basic [810.416225] Subtest pr_basic: SKIP (0.000s) [810.509059] [130/140] (458s left) i915_module_load (reload) [810.568437] Starting subtest: reload [839.216707] Subtest reload: SUCCESS (28.651s) [839.364640] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1668 [max: 8192] direct dependencies: 16902 [max: 32768] indirect dependencies: 95667 all direct dependencies: 317202 dependency chains: 23723 [max: 131072] dependency chain hlocks used: 99319 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 207 in-softirq chains: 568 in-process chains: 22948 stack-trace entries: 192057 [max: 1048576] number of stack traces: 9611 number of stack hash chains: 7248 combined max dependencies: 2716060048 hardirq-safe locks: 92 hardirq-unsafe locks: 1001 softirq-safe locks: 195 softirq-unsafe locks: 912 irq-safe locks: 208 irq-unsafe locks: 1001 hardirq-read-safe locks: 3 hardirq-read-unsafe locks: 298 softirq-read-safe locks: 7 softirq-read-unsafe locks: 293 irq-read-safe locks: 7 irq-read-unsafe locks: 298 uncategorized locks: 276 unused locks: 1 max locking depth: 17 max bfs queue depth: 437 max lock class index: 1888 chain lookup misses: 31940 chain lookup hits: 384672827 cyclic checks: 32118 redundant checks: 0 redundant links: 0 find-mask forwards checks: 5401 find-mask backwards checks: 2840 hardirq on events: 216167465 hardirq off events: 216167444 redundant hardirq ons: 176 redundant hardirq offs: 35 softirq on events: 639745 softirq off events: 639745 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 225 zapped lock chains: 7734 large chain blocks: 1 [839.367043] Closing watchdogs Done.