[66.777430] Initializing watchdogs [66.777551] /dev/watchdog0 [66.791536] [001/142] (960s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-render) [66.945069] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-render [66.950456] Subtest psr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [67.030036] [002/142] (960s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt) [67.171962] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt [67.178127] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [67.257609] [003/142] (960s left) sw_sync (sync_busy) [67.345808] Starting subtest: sync_busy [67.348910] Subtest sync_busy: SUCCESS (0.003s) [67.414785] [004/142] (959s left) kms_cursor_legacy (torture-bo) [67.568641] Starting subtest: torture-bo [67.571037] Starting dynamic subtest: pipe-A [74.277758] Dynamic subtest pipe-A: SUCCESS (6.704s) [74.288520] Starting dynamic subtest: pipe-B [81.017847] Dynamic subtest pipe-B: SUCCESS (6.740s) [81.029338] Starting dynamic subtest: pipe-C [88.125757] Dynamic subtest pipe-C: SUCCESS (7.108s) [88.136728] Starting dynamic subtest: pipe-D [95.512729] Dynamic subtest pipe-D: SUCCESS (7.385s) [95.524662] Starting dynamic subtest: all-pipes [102.993562] Dynamic subtest all-pipes: SUCCESS (7.483s) [102.996419] Subtest torture-bo: SUCCESS (35.421s) [103.145486] [005/142] (924s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-move) [103.282384] Starting subtest: psr-1p-primscrn-cur-indfb-move [108.997577] Subtest psr-1p-primscrn-cur-indfb-move: SUCCESS (5.719s) [109.121281] [006/142] (918s left) kms_lease (lease_invalid_connector) [109.246614] Starting subtest: lease_invalid_connector [109.249243] Starting dynamic subtest: pipe-A-eDP-1 [109.251822] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s) [109.262255] Starting dynamic subtest: pipe-B-eDP-1 [109.264908] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [109.275196] Starting dynamic subtest: pipe-C-eDP-1 [109.278041] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [109.288667] Starting dynamic subtest: pipe-D-eDP-1 [109.291328] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [109.294043] Subtest lease_invalid_connector: SUCCESS (0.006s) [109.315631] [007/142] (918s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-wc) [109.447048] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-wc [112.781358] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (3.340s) [112.910063] [008/142] (914s left) kms_vblank (pipe-d-query-idle) [113.031417] Starting subtest: pipe-D-query-idle [115.951141] Subtest pipe-D-query-idle: SUCCESS (2.918s) [116.897184] [009/142] (910s left) i915_hangman (gt-engine-error) [117.007164] Starting subtest: gt-engine-error [117.009823] Starting dynamic subtest: rcs0 [118.398336] Dynamic subtest rcs0: SUCCESS (1.390s) [118.410110] Starting dynamic subtest: bcs0 [120.378288] Dynamic subtest bcs0: SUCCESS (1.980s) [120.390803] Starting dynamic subtest: vcs0 [122.363865] Dynamic subtest vcs0: SUCCESS (1.985s) [122.376312] Starting dynamic subtest: vcs1 [124.411187] Dynamic subtest vcs1: SUCCESS (2.046s) [124.423657] Starting dynamic subtest: vecs0 [126.394468] Dynamic subtest vecs0: SUCCESS (1.983s) [126.406963] Starting dynamic subtest: ccs0 [128.379078] Dynamic subtest ccs0: SUCCESS (1.984s) [128.382360] Subtest gt-engine-error: SUCCESS (11.371s) [128.499471] [010/142] (899s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip) [128.645615] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip [128.650281] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s) [128.730281] [011/142] (898s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt) [128.859131] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt [128.864131] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s) [128.942668] [012/142] (898s left) gem_cs_tlb (engines) [129.032364] Starting subtest: engines [129.035099] Starting dynamic subtest: rcs0 [131.190624] Dynamic subtest rcs0: SUCCESS (2.154s) [131.201477] Starting dynamic subtest: bcs0 [133.341472] Dynamic subtest bcs0: SUCCESS (2.150s) [133.353545] Starting dynamic subtest: vcs0 [135.493829] Dynamic subtest vcs0: SUCCESS (2.153s) [135.499747] Starting dynamic subtest: vcs1 [137.644149] Dynamic subtest vcs1: SUCCESS (2.150s) [137.655931] Starting dynamic subtest: vecs0 [139.796273] Dynamic subtest vecs0: SUCCESS (2.150s) [139.807984] Starting dynamic subtest: ccs0 [141.946726] Dynamic subtest ccs0: SUCCESS (2.151s) [141.949450] Subtest engines: SUCCESS (12.910s) [142.044294] [013/142] (885s left) syncobj_wait (multi-wait-all-submitted-signaled) [142.131641] Starting subtest: multi-wait-all-submitted-signaled [142.241235] Subtest multi-wait-all-submitted-signaled: SUCCESS (0.107s) [142.312701] [014/142] (885s left) gem_softpin (allocator-nopin-reserve) [142.407305] Starting subtest: allocator-nopin-reserve [142.584763] Subtest allocator-nopin-reserve: SUCCESS (0.177s) [142.694655] [015/142] (884s left) api_intel_bb (render) [142.781447] Starting subtest: render [142.784256] Starting dynamic subtest: render-none-512 [142.987774] Dynamic subtest render-none-512: SUCCESS (0.207s) [142.998758] Starting dynamic subtest: render-none-1024 [143.662829] Dynamic subtest render-none-1024: SUCCESS (0.675s) [143.674278] Starting dynamic subtest: render-x-512 [143.817800] Dynamic subtest render-x-512: SUCCESS (0.155s) [143.829059] Starting dynamic subtest: render-x-1024 [144.440642] Dynamic subtest render-x-1024: SUCCESS (0.622s) [144.451766] Starting dynamic subtest: render-y-512 [144.608162] Dynamic subtest render-y-512: SUCCESS (0.167s) [144.619072] Starting dynamic subtest: render-y-1024 [145.251102] Dynamic subtest render-y-1024: SUCCESS (0.643s) [145.254089] Subtest render: SUCCESS (2.470s) [145.329754] [016/142] (882s left) gem_exec_whisper (basic-forked) [145.434489] Starting subtest: basic-forked [167.388591] Subtest basic-forked: SUCCESS (21.951s) [167.570340] [017/142] (860s left) kms_ccs (pipe-b-crc-primary-basic-4_tiled_dg2_rc_ccs) [168.057050] Starting subtest: pipe-B-crc-primary-basic-4_tiled_dg2_rc_ccs [168.063958] Subtest pipe-B-crc-primary-basic-4_tiled_dg2_rc_ccs: SKIP (0.001s) [168.898449] [018/142] (858s left) gem_exec_balancer (parallel-keep-in-fence) [169.310306] Starting subtest: parallel-keep-in-fence [170.753878] Subtest parallel-keep-in-fence: SUCCESS (1.444s) [170.810136] [019/142] (856s left) i915_query (query-topology-unsupported) [170.873085] Starting subtest: query-topology-unsupported [170.877990] Subtest query-topology-unsupported: SKIP (0.000s) [170.929497] [020/142] (856s left) gem_userptr_blits (sync-overlap) [171.025474] Starting subtest: sync-overlap [171.028122] Subtest sync-overlap: SUCCESS (0.000s) [171.135237] [021/142] (856s left) kms_ccs (pipe-d-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc) [171.712218] Starting subtest: pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc [171.718658] Subtest pipe-D-crc-sprite-planes-basic-y_tiled_gen12_rc_ccs_cc: SKIP (0.004s) [172.434837] [022/142] (855s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [172.588128] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [173.873060] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (1.288s) [174.114566] [023/142] (853s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen) [174.247033] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen [174.252205] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s) [174.336020] [024/142] (853s left) gem_ppgtt (shrink-vs-evict-any) [174.426757] Starting subtest: shrink-vs-evict-any [182.428096] Subtest shrink-vs-evict-any: SUCCESS (8.001s) [182.490195] [025/142] (845s left) gem_mmap_gtt (basic-write-cpu-read-gtt) [182.579949] Subtest basic-write-cpu-read-gtt: SKIP (0.000s) [182.616207] [026/142] (845s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt) [182.757458] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt [186.950539] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.199s) [187.079878] [027/142] (840s left) gem_tiled_wc [187.217164] [028/142] (840s left) kms_cursor_crc (cursor-rapid-movement-256x256) [187.487301] Starting subtest: cursor-rapid-movement-256x256 [187.496934] Starting dynamic subtest: pipe-A-eDP-1 [188.175940] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.678s) [188.235183] Starting dynamic subtest: pipe-D-eDP-1 [189.532723] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.307s) [189.535556] Subtest cursor-rapid-movement-256x256: SUCCESS (2.043s) [190.431190] [029/142] (837s left) kms_ccs (pipe-c-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc) [190.955887] Starting subtest: pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc [190.962469] Subtest pipe-C-crc-primary-rotation-180-y_tiled_gen12_rc_ccs_cc: SKIP (0.001s) [191.749166] [030/142] (836s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-plflip-blt) [191.876952] Starting subtest: fbcpsr-2p-primscrn-shrfb-plflip-blt [191.881666] Subtest fbcpsr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [191.961919] [031/142] (836s left) kms_ccs (pipe-a-crc-primary-rotation-180-4_tiled_mtl_mc_ccs) [192.411801] Starting subtest: pipe-A-crc-primary-rotation-180-4_tiled_mtl_mc_ccs [195.801586] Subtest pipe-A-crc-primary-rotation-180-4_tiled_mtl_mc_ccs: SUCCESS (3.389s) [197.023910] [032/142] (831s left) gem_exec_reloc (basic-cpu) [197.118080] Subtest basic-cpu: SKIP (0.000s) [197.180682] [033/142] (830s left) gem_lmem_swapping (verify-ccs) [197.272831] Subtest verify-ccs: SKIP (0.000s) [197.311169] [034/142] (830s left) gem_exec_reloc (basic-write-read) [197.400204] Subtest basic-write-read: SKIP (0.000s) [197.453018] [035/142] (830s left) gem_ctx_param (vm) [197.535457] Starting subtest: vm [197.576442] Subtest vm: SUCCESS (0.043s) [197.687565] [036/142] (830s left) gem_caching (read-writes) [197.780212] Subtest read-writes: SKIP (0.000s) [197.819307] [037/142] (830s left) gem_exec_fair (basic-none-vip) [197.923256] Subtest basic-none-vip: SKIP (0.000s) [197.977579] [038/142] (830s left) i915_query (engine-info) [198.059937] Starting subtest: engine-info [198.062434] Subtest engine-info: SUCCESS (0.005s) [198.129403] [039/142] (830s left) gem_mmap_wc (read-write) [198.212777] Subtest read-write: SKIP (0.000s) [198.252635] [040/142] (830s left) syncobj_wait (multi-wait-all-signaled) [198.333995] Starting subtest: multi-wait-all-signaled [198.336321] Subtest multi-wait-all-signaled: SUCCESS (0.001s) [198.399749] [041/142] (829s left) vgem_slow (nohang) [198.547717] Starting subtest: nohang [209.018700] Subtest nohang: SUCCESS (10.471s) [209.081172] [042/142] (819s left) gem_fence_thrash (bo-write-verify-threaded-y) [209.164869] Starting subtest: bo-write-verify-threaded-y [209.172664] Subtest bo-write-verify-threaded-y: SKIP (0.003s) [209.210866] [043/142] (819s left) gem_ctx_shared (q-promotion) [209.309880] Starting subtest: Q-promotion [209.312725] Starting dynamic subtest: rcs0 [209.528768] Dynamic subtest rcs0: SUCCESS (0.216s) [209.554299] Starting dynamic subtest: bcs0 [209.685445] Dynamic subtest bcs0: SUCCESS (0.156s) [209.711756] Starting dynamic subtest: vcs0 [209.810640] Dynamic subtest vcs0: SUCCESS (0.125s) [209.837756] Starting dynamic subtest: vcs1 [209.942826] Dynamic subtest vcs1: SUCCESS (0.130s) [209.969196] Starting dynamic subtest: vecs0 [210.071949] Dynamic subtest vecs0: SUCCESS (0.129s) [210.097946] Starting dynamic subtest: ccs0 [210.244938] Dynamic subtest ccs0: SUCCESS (0.172s) [210.248478] Subtest Q-promotion: SUCCESS (0.934s) [211.511755] [044/142] (816s left) kms_addfb_basic (size-max) [211.643807] Starting subtest: size-max [211.646440] Subtest size-max: SUCCESS (0.000s) [211.738264] [045/142] (816s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [211.897961] Starting subtest: basic-flip-after-cursor-legacy [212.925056] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.025s) [213.861801] [046/142] (814s left) perf_pmu (busy-accuracy-98) [213.953776] Starting subtest: busy-accuracy-98 [213.956362] Starting dynamic subtest: rcs0 [217.080495] Dynamic subtest rcs0: FAIL (3.081s) [217.093728] Starting dynamic subtest: bcs0 [220.163055] Dynamic subtest bcs0: FAIL (3.069s) [220.176941] Starting dynamic subtest: vcs0 [223.157485] Dynamic subtest vcs0: SUCCESS (3.049s) [223.172223] Starting dynamic subtest: vcs1 [226.208338] Dynamic subtest vcs1: SUCCESS (3.050s) [226.223637] Starting dynamic subtest: vecs0 [229.259924] Dynamic subtest vecs0: SUCCESS (3.051s) [229.274534] Starting dynamic subtest: ccs0 [232.389729] Dynamic subtest ccs0: FAIL (3.073s) [232.392345] Subtest busy-accuracy-98: FAIL (18.376s) [232.443614] [047/142] (796s left) dumb_buffer (map-valid) [232.533114] Starting subtest: map-valid [232.535764] Subtest map-valid: SUCCESS (0.000s) [232.613520] [048/142] (795s left) kms_cursor_crc (cursor-size-change) [232.740536] Starting subtest: cursor-size-change [232.743479] Starting dynamic subtest: pipe-A-eDP-1 [233.687420] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.947s) [233.703048] Starting dynamic subtest: pipe-D-eDP-1 [235.340478] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.647s) [235.343403] Subtest cursor-size-change: SUCCESS (2.599s) [236.239734] [049/142] (792s left) gem_mmap_wc (write-cpu-read-wc) [236.329183] Subtest write-cpu-read-wc: SKIP (0.000s) [236.365242] [050/142] (792s left) kms_big_joiner (invalid-modeset) [236.486416] Subtest invalid-modeset: SKIP (0.000s) [236.525154] [051/142] (792s left) kms_psr2_sf (primary-plane-update-sf-dmg-area-big-fb) [241.925760] Starting subtest: primary-plane-update-sf-dmg-area-big-fb [241.928403] Starting dynamic subtest: pipe-A-eDP-1 [252.158914] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.230s) [252.174264] Starting dynamic subtest: pipe-B-eDP-1 [262.952117] Dynamic subtest pipe-B-eDP-1: SUCCESS (10.792s) [262.954785] Subtest primary-plane-update-sf-dmg-area-big-fb: SUCCESS (21.027s) [263.844471] [052/142] (764s left) perf_pmu (render-node-busy) [263.938922] Starting subtest: render-node-busy [263.941937] Starting dynamic subtest: rcs0 [264.542059] Dynamic subtest rcs0: FAIL (0.552s) [264.555942] Starting dynamic subtest: bcs0 [265.107988] Dynamic subtest bcs0: FAIL (0.557s) [265.121365] Starting dynamic subtest: vcs0 [266.425998] Dynamic subtest vcs0: SUCCESS (1.374s) [266.440982] Starting dynamic subtest: vcs1 [268.410047] Dynamic subtest vcs1: SUCCESS (1.982s) [268.424763] Starting dynamic subtest: vecs0 [270.394316] Dynamic subtest vecs0: SUCCESS (1.983s) [270.412072] Starting dynamic subtest: ccs0 [270.983712] Dynamic subtest ccs0: FAIL (0.536s) [270.986346] Subtest render-node-busy: FAIL (6.991s) [271.009577] [053/142] (757s left) kms_ccs (pipe-c-random-ccs-data-4_tiled_dg2_rc_ccs_cc) [271.558857] Starting subtest: pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs_cc [271.567216] Subtest pipe-C-random-ccs-data-4_tiled_dg2_rc_ccs_cc: SKIP (0.000s) [272.355104] [054/142] (756s left) kms_cursor_legacy (short-flip-before-cursor-toggle) [272.513620] Starting subtest: short-flip-before-cursor-toggle [273.531771] Subtest short-flip-before-cursor-toggle: SUCCESS (1.016s) [274.429522] [055/142] (754s left) gen3_render_mixed_blits [274.557295] [056/142] (754s left) gem_partial_pwrite_pread (write) [274.652060] Subtest write: SKIP (0.000s) [274.710832] [057/142] (754s left) i915_pm_rpm (cursor-dpms) [276.551556] Starting subtest: cursor-dpms [312.282446] Subtest cursor-dpms: SUCCESS (35.729s) [313.372557] [058/142] (715s left) kms_plane_cursor (viewport) [313.494275] Starting subtest: viewport [313.498977] Starting dynamic subtest: pipe-A-eDP-1-size-64 [317.121042] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (3.624s) [317.180098] Starting dynamic subtest: pipe-A-eDP-1-size-128 [320.746243] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (3.575s) [320.803851] Starting dynamic subtest: pipe-A-eDP-1-size-256 [324.391633] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (3.597s) [324.758851] Starting dynamic subtest: pipe-B-eDP-1-size-64 [329.092620] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (4.349s) [329.155754] Starting dynamic subtest: pipe-B-eDP-1-size-128 [332.771423] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (3.630s) [332.829255] Starting dynamic subtest: pipe-B-eDP-1-size-256 [336.428732] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (3.608s) [336.792099] Starting dynamic subtest: pipe-C-eDP-1-size-64 [340.683116] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.905s) [340.723459] Starting dynamic subtest: pipe-C-eDP-1-size-128 [343.894545] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (3.183s) [343.933062] Starting dynamic subtest: pipe-C-eDP-1-size-256 [347.095588] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (3.171s) [347.437093] Starting dynamic subtest: pipe-D-eDP-1-size-64 [351.320531] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.897s) [351.360484] Starting dynamic subtest: pipe-D-eDP-1-size-128 [354.565103] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (3.216s) [354.604027] Starting dynamic subtest: pipe-D-eDP-1-size-256 [357.744517] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (3.149s) [358.074486] Subtest viewport: SUCCESS (44.578s) [358.963224] [059/142] (669s left) gem_partial_pwrite_pread (reads) [359.065136] Subtest reads: SKIP (0.000s) [359.133619] [060/142] (669s left) gem_exec_params (batch-first) [359.224137] Starting subtest: batch-first [359.232958] Subtest batch-first: SUCCESS (0.008s) [359.337139] [061/142] (669s left) kms_ccs (pipe-d-ccs-on-another-bo-y_tiled_ccs) [359.933056] Starting subtest: pipe-D-ccs-on-another-bo-y_tiled_ccs [359.939559] Subtest pipe-D-ccs-on-another-bo-y_tiled_ccs: SKIP (0.000s) [360.670417] [062/142] (668s left) kms_lease (page_flip_implicit_plane) [360.795254] Starting subtest: page_flip_implicit_plane [360.797914] Starting dynamic subtest: pipe-A-eDP-1 [361.298973] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.499s) [361.314907] Starting dynamic subtest: pipe-B-eDP-1 [362.518473] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.218s) [362.532766] Starting dynamic subtest: pipe-C-eDP-1 [363.732927] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.212s) [363.746938] Starting dynamic subtest: pipe-D-eDP-1 [364.942173] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.204s) [364.945503] Subtest page_flip_implicit_plane: SUCCESS (4.145s) [365.860386] [063/142] (663s left) kms_chamelium_frames (hdmi-crc-fast) [366.299062] Subtest hdmi-crc-fast: SKIP (0.000s) [367.171912] [064/142] (661s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move) [367.308406] Starting subtest: fbc-1p-primscrn-spr-indfb-move [370.747416] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (3.444s) [370.893216] [065/142] (658s left) kms_flip (flip-vs-suspend) [370.994250] Starting subtest: flip-vs-suspend [371.000214] Starting dynamic subtest: A-eDP1 [405.767567] Dynamic subtest A-eDP1: SUCCESS (4.089s) [405.789643] Starting dynamic subtest: B-eDP1 [439.764093] Dynamic subtest B-eDP1: SUCCESS (3.992s) [439.783469] Starting dynamic subtest: C-eDP1 [473.767999] Dynamic subtest C-eDP1: SUCCESS (3.977s) [473.787859] Starting dynamic subtest: D-eDP1 [507.771713] Dynamic subtest D-eDP1: SUCCESS (4.020s) [507.783644] Subtest flip-vs-suspend: SUCCESS (16.110s) [509.143912] [066/142] (640s left) gem_exec_balancer (individual) [509.245059] Starting subtest: individual [511.084661] Subtest individual: SUCCESS (1.838s) [511.488982] [067/142] (638s left) kms_hdr (bpc-switch) [511.615020] Starting subtest: bpc-switch [512.793872] Starting dynamic subtest: pipe-A-eDP-1 [516.504969] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.710s) [516.507784] Subtest bpc-switch: SUCCESS (4.889s) [516.617519] [068/142] (633s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset) [516.717227] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset [516.724487] Starting dynamic subtest: A-eDP1 [520.726886] Dynamic subtest A-eDP1: SUCCESS (4.001s) [520.744006] Starting dynamic subtest: B-eDP1 [524.313482] Dynamic subtest B-eDP1: SUCCESS (3.583s) [524.333840] Starting dynamic subtest: C-eDP1 [527.808014] Dynamic subtest C-eDP1: SUCCESS (3.495s) [527.825323] Starting dynamic subtest: D-eDP1 [531.267803] Dynamic subtest D-eDP1: SUCCESS (3.456s) [531.276139] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (14.558s) [532.163870] [069/142] (617s left) gem_ctx_persistence (hostile) [532.262564] Starting subtest: hostile [535.921019] Subtest hostile: SUCCESS (3.658s) [536.031562] [070/142] (613s left) gem_watchdog (default-physical) [536.140176] Starting subtest: default-physical [537.151019] Subtest default-physical: SUCCESS (1.020s) [537.227036] [071/142] (612s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render) [537.369332] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render [537.374787] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s) [537.451337] [072/142] (612s left) kms_cursor_crc (cursor-offscreen-256x256) [537.738082] Starting subtest: cursor-offscreen-256x256 [537.750381] Starting dynamic subtest: pipe-A-eDP-1 [539.846131] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.096s) [539.905333] Starting dynamic subtest: pipe-D-eDP-1 [542.628419] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.738s) [542.631230] Subtest cursor-offscreen-256x256: SUCCESS (4.889s) [543.526611] [073/142] (606s left) prime_udl [543.658021] [074/142] (606s left) vc4/vc4_create_bo (create-bo-0) [543.792694] Subtest create-bo-0: SKIP (0.000s) [543.848773] [075/142] (606s left) kms_ccs (pipe-b-missing-ccs-buffer-y_tiled_gen12_rc_ccs) [544.353997] Starting subtest: pipe-B-missing-ccs-buffer-y_tiled_gen12_rc_ccs [544.360937] Subtest pipe-B-missing-ccs-buffer-y_tiled_gen12_rc_ccs: SKIP (0.000s) [545.185049] [076/142] (605s left) kms_frontbuffer_tracking (psr-slowdraw) [545.325339] Starting subtest: psr-slowdraw [569.026605] Subtest psr-slowdraw: SUCCESS (23.706s) [569.156528] [077/142] (581s left) gem_pxp (create-regular-context-2) [571.784020] Subtest create-regular-context-2: SKIP (0.000s) [571.853962] [078/142] (578s left) kms_universal_plane (universal-plane-pageflip-windowed-pipe-a) [571.978947] Starting subtest: universal-plane-pageflip-windowed-pipe-A [572.615040] Subtest universal-plane-pageflip-windowed-pipe-A: SUCCESS (0.636s) [573.516324] [079/142] (576s left) kms_ccs (pipe-a-bad-aux-stride-y_tiled_gen12_mc_ccs) [573.976780] Starting subtest: pipe-A-bad-aux-stride-y_tiled_gen12_mc_ccs [573.982849] Subtest pipe-A-bad-aux-stride-y_tiled_gen12_mc_ccs: SKIP (0.000s) [574.842255] [080/142] (575s left) kms_ccs (pipe-b-bad-rotation-90-4_tiled_dg2_rc_ccs) [575.327554] Starting subtest: pipe-B-bad-rotation-90-4_tiled_dg2_rc_ccs [575.333980] Subtest pipe-B-bad-rotation-90-4_tiled_dg2_rc_ccs: SKIP (0.000s) [576.177607] [081/142] (574s left) syncobj_timeline (wait-all-for-submit-delayed-submit) [576.272025] Starting subtest: wait-all-for-submit-delayed-submit [576.374997] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.104s) [576.452986] [082/142] (573s left) gem_vm_create (create-ext) [576.549453] Starting subtest: create-ext [576.556005] Subtest create-ext: SUCCESS (0.005s) [576.666466] [083/142] (573s left) gem_exec_fence (syncobj-timeline-signal) [577.508535] Starting subtest: syncobj-timeline-signal [577.516426] Subtest syncobj-timeline-signal: SUCCESS (0.009s) [579.212820] [084/142] (571s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-blt) [579.355813] Starting subtest: psr-1p-offscren-pri-shrfb-draw-blt [584.580788] Subtest psr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (5.229s) [584.718855] [085/142] (565s left) gem_mmap_wc (write) [584.805697] Subtest write: SKIP (0.000s) [584.849234] [086/142] (565s left) kms_pwrite_crc [586.709400] [087/142] (563s left) kms_atomic_transition (modeset-transition-nonblocking) [586.835036] Starting subtest: modeset-transition-nonblocking [586.837841] Starting dynamic subtest: 1x-outputs [599.851252] Dynamic subtest 1x-outputs: SUCCESS (13.015s) [600.181171] Subtest modeset-transition-nonblocking: SUCCESS (13.345s) [601.110413] [088/142] (549s left) kms_ccs (pipe-c-bad-rotation-90-4_tiled_dg2_mc_ccs) [601.638591] Starting subtest: pipe-C-bad-rotation-90-4_tiled_dg2_mc_ccs [601.644573] Subtest pipe-C-bad-rotation-90-4_tiled_dg2_mc_ccs: SKIP (0.000s) [602.433156] [089/142] (548s left) v3d/v3d_perfmon (get-values-invalid-pad) [602.567096] Subtest get-values-invalid-pad: SKIP (0.000s) [602.623225] [090/142] (548s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling) [602.746770] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling [602.749455] Starting dynamic subtest: pipe-A-default-mode [603.056162] Dynamic subtest pipe-A-default-mode: SKIP (0.302s) [603.061386] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.306s) [603.986232] [091/142] (546s left) vc4/vc4_tiling (set-bad-handle) [604.120248] Subtest set-bad-handle: SKIP (0.000s) [604.176892] [092/142] (546s left) syncobj_timeline (multi-wait-for-submit-available-signaled) [604.264956] Starting subtest: multi-wait-for-submit-available-signaled [604.267789] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.001s) [604.338490] [093/142] (546s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-pwrite) [604.471202] Starting subtest: psr-2p-primscrn-pri-indfb-draw-pwrite [604.476210] Subtest psr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [604.571363] [094/142] (546s left) gem_exec_reloc (basic-gtt-active) [604.667311] Subtest basic-gtt-active: SKIP (0.000s) [604.734618] [095/142] (546s left) kms_flip (blocking-absolute-wf_vblank-interruptible) [604.838030] Starting subtest: blocking-absolute-wf_vblank-interruptible [604.847887] Starting dynamic subtest: A-eDP1 [613.752949] Dynamic subtest A-eDP1: SUCCESS (8.905s) [613.771541] Starting dynamic subtest: B-eDP1 [622.648084] Dynamic subtest B-eDP1: SUCCESS (8.894s) [622.666195] Starting dynamic subtest: C-eDP1 [631.474832] Dynamic subtest C-eDP1: SUCCESS (8.819s) [631.492696] Starting dynamic subtest: D-eDP1 [640.301185] Dynamic subtest D-eDP1: SUCCESS (8.831s) [640.309849] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (35.472s) [641.619169] [096/142] (509s left) kms_plane_scaling (plane-upscale-with-pixel-format-20x20) [641.744062] Starting subtest: plane-upscale-with-pixel-format-20x20 [641.746707] Starting dynamic subtest: pipe-A-eDP-1 [643.433089] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.689s) [643.453792] Starting dynamic subtest: pipe-B-eDP-1 [646.305044] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.869s) [646.328457] Starting dynamic subtest: pipe-C-eDP-1 [647.674854] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.365s) [647.697200] Starting dynamic subtest: pipe-D-eDP-1 [649.061177] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.384s) [649.063953] Subtest plane-upscale-with-pixel-format-20x20: SUCCESS (7.320s) [650.385414] [097/142] (500s left) kms_chamelium_hpd (hdmi-hpd-fast) [650.814194] Subtest hdmi-hpd-fast: SKIP (0.000s) [651.699630] [098/142] (499s left) gem_exec_schedule (pi-common) [651.935159] Starting subtest: pi-common [651.950631] Starting dynamic subtest: rcs0 [652.023807] Dynamic subtest rcs0: SUCCESS (0.091s) [652.053873] Starting dynamic subtest: bcs0 [652.114296] Dynamic subtest bcs0: SUCCESS (0.089s) [652.148515] Starting dynamic subtest: vcs0 [652.209644] Dynamic subtest vcs0: SUCCESS (0.093s) [652.239129] Starting dynamic subtest: vcs1 [652.303852] Dynamic subtest vcs1: SUCCESS (0.091s) [652.335561] Starting dynamic subtest: vecs0 [652.396646] Dynamic subtest vecs0: SUCCESS (0.091s) [652.427300] Starting dynamic subtest: ccs0 [652.491600] Dynamic subtest ccs0: SUCCESS (0.093s) [652.494455] Subtest pi-common: SUCCESS (0.561s) [652.727479] [099/142] (498s left) vc4/vc4_perfmon (get-values-invalid-pointer) [652.859580] Subtest get-values-invalid-pointer: SKIP (0.000s) [652.916702] [100/142] (498s left) i915_suspend (forcewake) [653.002625] Starting subtest: forcewake [685.762167] Subtest forcewake: SUCCESS (2.614s) [685.838185] [101/142] (495s left) kms_plane_scaling (plane-downscale-with-pixel-format-factor-0-5) [685.960524] Starting subtest: plane-downscale-with-pixel-format-factor-0-5 [685.963286] Starting dynamic subtest: pipe-A-eDP-1 [686.004840] Dynamic subtest pipe-A-eDP-1: SKIP (0.039s) [686.024794] Starting dynamic subtest: pipe-B-eDP-1 [686.076857] Dynamic subtest pipe-B-eDP-1: SKIP (0.069s) [686.093641] Starting dynamic subtest: pipe-C-eDP-1 [686.146753] Dynamic subtest pipe-C-eDP-1: SKIP (0.067s) [686.167307] Starting dynamic subtest: pipe-D-eDP-1 [686.222907] Dynamic subtest pipe-D-eDP-1: SKIP (0.073s) [686.228193] Subtest plane-downscale-with-pixel-format-factor-0-5: SKIP (0.262s) [686.333896] [102/142] (494s left) prime_vgem (basic-blt) [686.450867] Starting subtest: basic-blt [686.539821] Subtest basic-blt: FAIL (0.054s) [686.617208] [103/142] (494s left) kms_psr (suspend) [686.740207] Starting subtest: suspend [705.194171] Subtest suspend: SUCCESS (3.188s) [706.094308] [104/142] (490s left) gem_exec_flush (basic-wb-prw-default) [706.198989] Starting subtest: basic-wb-prw-default [711.908507] Subtest basic-wb-prw-default: SUCCESS (5.709s) [712.101451] [105/142] (484s left) kms_big_fb (x-tiled-addfb-size-overflow) [712.253943] Starting subtest: x-tiled-addfb-size-overflow [712.257133] Subtest x-tiled-addfb-size-overflow: SUCCESS (0.001s) [712.346831] [106/142] (484s left) api_intel_bb (lot-of-buffers) [712.435865] Starting subtest: lot-of-buffers [714.700514] Subtest lot-of-buffers: SUCCESS (2.267s) [714.891815] Aborting: Lockdep not active /proc/lockdep_stats contents: lock-classes: 1840 [max: 8192] direct dependencies: 19214 [max: 32768] indirect dependencies: 151813 all direct dependencies: 407552 dependency chains: 28911 [max: 131072] dependency chain hlocks used: 125228 [max: 655360] dependency chain hlocks lost: 0 in-hardirq chains: 310 in-softirq chains: 657 in-process chains: 27944 stack-trace entries: 183047 [max: 1048576] number of stack traces: 9229 number of stack hash chains: 7028 combined max dependencies: 1423641614 hardirq-safe locks: 114 hardirq-unsafe locks: 1097 softirq-safe locks: 225 softirq-unsafe locks: 1007 irq-safe locks: 240 irq-unsafe locks: 1097 hardirq-read-safe locks: 4 hardirq-read-unsafe locks: 322 softirq-read-safe locks: 7 softirq-read-unsafe locks: 317 irq-read-safe locks: 8 irq-read-unsafe locks: 322 uncategorized locks: 299 unused locks: 1 max locking depth: 16 max bfs queue depth: 423 max lock class index: 1839 chain lookup misses: 29492 chain lookup hits: 277165579 cyclic checks: 29419 redundant checks: 0 redundant links: 0 find-mask forwards checks: 5253 find-mask backwards checks: 2745 hardirq on events: 157139713 hardirq off events: 157139691 redundant hardirq ons: 60 redundant hardirq offs: 27 softirq on events: 1046464 softirq off events: 1046464 redundant softirq ons: 0 redundant softirq offs: 0 debug_locks: 0 zapped classes: 1 zapped lock chains: 98 large chain blocks: 1 [714.895494] Closing watchdogs Done.