[38.604828] Initializing watchdogs [38.604983] /dev/watchdog0 [38.619044] [001/143] (960s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu) [38.727415] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu [38.731635] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [38.801059] [002/143] (960s left) gem_exec_params (rs-invalid) [38.873223] Starting subtest: rs-invalid [38.883436] Subtest rs-invalid: SUCCESS (0.009s) [38.975998] [003/143] (960s left) kms_rotation_crc (sprite-rotation-90-pos-100-0) [39.062423] Starting subtest: sprite-rotation-90-pos-100-0 [39.067280] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s) [39.123994] [004/143] (960s left) gem_mmap_wc (bad-object) [39.210499] Subtest bad-object: SKIP (0.000s) [39.241572] [005/143] (959s left) kms_cursor_crc (cursor-onscreen-128x42) [39.449692] Starting subtest: cursor-onscreen-128x42 [39.496368] Subtest cursor-onscreen-128x42: SKIP (0.042s) [39.682216] [006/143] (959s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move) [39.757237] Starting subtest: psr-2p-scndscrn-cur-indfb-move [39.760660] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s) [39.822920] [007/143] (959s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite) [39.919203] Starting subtest: psr-rgb565-draw-pwrite [44.831645] Subtest psr-rgb565-draw-pwrite: SUCCESS (4.916s) [44.931024] [008/143] (954s left) gem_readwrite (beyond-eob) [44.992082] Subtest beyond-EOB: SKIP (0.000s) [45.021089] [009/143] (954s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu) [45.118694] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu [50.115250] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (5.003s) [50.220397] [010/143] (949s left) kms_scaling_modes (scaling-mode-none) [50.332602] Starting subtest: scaling-mode-none [50.334667] Starting dynamic subtest: pipe-A-eDP-1 [50.465168] Dynamic subtest pipe-A-eDP-1: SKIP (0.130s) [50.477450] Starting dynamic subtest: pipe-B-eDP-1 [50.518610] Dynamic subtest pipe-B-eDP-1: SKIP (0.052s) [50.529693] Starting dynamic subtest: pipe-C-eDP-1 [50.549388] Dynamic subtest pipe-C-eDP-1: SKIP (0.031s) [50.561929] Starting dynamic subtest: pipe-D-eDP-1 [50.605432] Dynamic subtest pipe-D-eDP-1: SKIP (0.054s) [50.610111] Subtest scaling-mode-none: SKIP (0.272s) [50.705989] [011/143] (948s left) kms_flip (absolute-wf_vblank-interruptible) [50.810566] Starting subtest: absolute-wf_vblank-interruptible [50.815727] Starting dynamic subtest: A-eDP1 [59.788059] Dynamic subtest A-eDP1: SUCCESS (8.939s) [59.801057] Starting dynamic subtest: B-eDP1 [68.680824] Dynamic subtest B-eDP1: SUCCESS (8.895s) [68.687223] Starting dynamic subtest: C-eDP1 [77.468565] Dynamic subtest C-eDP1: SUCCESS (8.819s) [77.481966] Starting dynamic subtest: D-eDP1 [86.348063] Dynamic subtest D-eDP1: SUCCESS (8.832s) [86.351272] Subtest absolute-wf_vblank-interruptible: SUCCESS (35.499s) [87.549095] [012/143] (911s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt) [87.651383] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt [87.655359] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [87.727463] [013/143] (911s left) syncobj_timeline (wait-all-snapshot) [87.802678] Starting subtest: wait-all-snapshot [87.884216] Subtest wait-all-snapshot: SUCCESS (0.083s) [87.953472] [014/143] (911s left) gem_exec_balancer (busy) [88.052798] Starting subtest: busy [89.436657] Subtest busy: SUCCESS (1.382s) [89.722812] [015/143] (909s left) vc4/vc4_tiling (get-bad-handle) [89.827089] Subtest get-bad-handle: SKIP (0.000s) [89.877289] [016/143] (909s left) kms_psr (pr-sprite-mmap-gtt) [89.982177] Starting subtest: pr-sprite-mmap-gtt [89.986877] Subtest pr-sprite-mmap-gtt: SKIP (0.000s) [90.094389] [017/143] (909s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-indfb-draw-mmap-wc) [90.192134] Starting subtest: psr-1p-offscren-pri-indfb-draw-mmap-wc [94.775475] Subtest psr-1p-offscren-pri-indfb-draw-mmap-wc: SUCCESS (4.586s) [94.887595] [018/143] (904s left) kms_dp_aux_dev [95.114637] [019/143] (904s left) gem_fenced_exec_thrash (no-spare-fences-interruptible) [95.199576] Subtest no-spare-fences-interruptible: SKIP (0.000s) [95.248620] [020/143] (904s left) syncobj_timeline (etime-single-wait-available-unsubmitted) [95.322497] Starting subtest: etime-single-wait-available-unsubmitted [95.424774] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.103s) [95.494466] [021/143] (904s left) kms_prop_blob (basic) [95.558105] Starting subtest: basic [95.560835] Subtest basic: SUCCESS (0.000s) [95.643794] [022/143] (904s left) kms_busy (extended-modeset-hang-oldfb-with-reset) [95.738031] Starting subtest: extended-modeset-hang-oldfb-with-reset [95.740530] Starting dynamic subtest: pipe-A [107.892968] Dynamic subtest pipe-A: SUCCESS (12.152s) [107.911272] Starting dynamic subtest: pipe-D [120.687676] Dynamic subtest pipe-D: SUCCESS (12.783s) [120.690698] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.944s) [121.578786] [023/143] (878s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling) [121.668131] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling [121.670586] Starting dynamic subtest: pipe-A-default-mode [121.961559] Dynamic subtest pipe-A-default-mode: SKIP (0.290s) [121.966715] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.291s) [122.864842] [024/143] (876s left) kms_ccs (pipe-a-random-ccs-data-y-tiled-gen12-rc-ccs-cc) [123.278887] Starting subtest: pipe-A-random-ccs-data-y-tiled-gen12-rc-ccs-cc [123.286826] Subtest pipe-A-random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.000s) [124.147646] [025/143] (875s left) gem_exec_whisper (basic-normal-all) [124.220102] Starting subtest: basic-normal-all [127.099930] Subtest basic-normal-all: SUCCESS (2.877s) [127.273990] [026/143] (872s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [127.357593] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu [127.362151] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [127.451106] [027/143] (872s left) kms_ccs (pipe-b-bad-pixel-format-4-tiled-mtl-rc-ccs-cc) [127.834386] Starting subtest: pipe-B-bad-pixel-format-4-tiled-mtl-rc-ccs-cc [127.837380] Subtest pipe-B-bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.000s) [128.772471] [028/143] (871s left) gem_mmap_gtt (big-bo-tiledy) [128.834745] Subtest big-bo-tiledY: SKIP (0.000s) [128.891723] [029/143] (870s left) perf_pmu (multi-client) [128.948419] Starting subtest: multi-client [128.951162] Starting dynamic subtest: rcs0 [129.999656] Dynamic subtest rcs0: SUCCESS (1.050s) [130.016171] Starting dynamic subtest: bcs0 [131.056996] Dynamic subtest bcs0: SUCCESS (1.056s) [131.073257] Starting dynamic subtest: vcs0 [132.114077] Dynamic subtest vcs0: SUCCESS (1.056s) [132.130385] Starting dynamic subtest: vcs1 [133.492785] Dynamic subtest vcs1: SUCCESS (1.378s) [133.508822] Starting dynamic subtest: vecs0 [135.476037] Dynamic subtest vecs0: SUCCESS (1.983s) [135.492141] Starting dynamic subtest: ccs0 [137.462428] Dynamic subtest ccs0: SUCCESS (1.986s) [137.465487] Subtest multi-client: SUCCESS (8.514s) [137.579836] [030/143] (862s left) gem_mmap (basic) [137.663476] Subtest basic: SKIP (0.000s) [137.713080] [031/143] (862s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt) [137.838331] Starting subtest: fbcpsr-farfromfence-mmap-gtt [137.843927] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s) [137.925906] [032/143] (862s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180) [138.043733] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180 [138.048036] Subtest y-tiled-max-hw-stride-32bpp-rotate-180: SKIP (0.000s) [138.124826] [033/143] (861s left) kms_big_fb (y-tiled-64bpp-rotate-90) [138.228399] Starting subtest: y-tiled-64bpp-rotate-90 [138.232384] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s) [138.300325] [034/143] (861s left) gem_mmap_gtt (zero-extend) [138.375179] Subtest zero-extend: SKIP (0.000s) [138.402376] [035/143] (861s left) kms_chamelium_color (gamma) [138.464346] Subtest gamma: SKIP (0.000s) [138.488972] [036/143] (861s left) kms_ccs (pipe-c-random-ccs-data-4-tiled-dg2-rc-ccs) [138.899293] Starting subtest: pipe-C-random-ccs-data-4-tiled-dg2-rc-ccs [138.907675] Subtest pipe-C-random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.000s) [139.775867] [037/143] (860s left) gem_mmap_gtt (basic-write) [139.847993] Subtest basic-write: SKIP (0.000s) [139.891558] [038/143] (860s left) kms_color (ctm-signed) [139.967450] Starting subtest: ctm-signed [139.969702] Starting dynamic subtest: pipe-A [142.211537] Dynamic subtest pipe-A: SUCCESS (2.242s) [142.222971] Starting dynamic subtest: pipe-B [145.194749] Dynamic subtest pipe-B: SUCCESS (2.984s) [145.211538] Starting dynamic subtest: pipe-C [148.099655] Dynamic subtest pipe-C: SUCCESS (2.900s) [148.114367] Starting dynamic subtest: pipe-D [150.977908] Dynamic subtest pipe-D: SUCCESS (2.881s) [150.980865] Subtest ctm-signed: SUCCESS (11.009s) [151.863429] [039/143] (848s left) kms_rotation_crc (bad-tiling) [151.956508] Starting subtest: bad-tiling [151.961705] Subtest bad-tiling: SKIP (0.002s) [152.029947] [040/143] (848s left) kms_ccs (pipe-a-bad-rotation-90-y-tiled-gen12-mc-ccs) [152.401892] Starting subtest: pipe-A-bad-rotation-90-y-tiled-gen12-mc-ccs [152.406842] Subtest pipe-A-bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.000s) [153.331437] [041/143] (846s left) kms_rotation_crc (sprite-rotation-180) [153.427838] Starting subtest: sprite-rotation-180 [160.665216] Subtest sprite-rotation-180: SUCCESS (7.242s) [161.987084] [042/143] (838s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90) [162.066155] Starting subtest: primary-yf-tiled-reflect-x-90 [162.070869] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.002s) [162.135704] [043/143] (838s left) gem_exec_reloc (basic-wc-gtt) [162.219226] Subtest basic-wc-gtt: SKIP (0.000s) [162.281907] [044/143] (838s left) api_intel_bb (add-remove-objects) [162.352803] Starting subtest: add-remove-objects [162.363914] Subtest add-remove-objects: SUCCESS (0.014s) [162.457032] [045/143] (837s left) kms_big_fb (x-tiled-32bpp-rotate-180) [162.519821] Starting subtest: x-tiled-32bpp-rotate-180 [164.086806] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.570s) [164.265921] [046/143] (836s left) gem_render_copy (yf-tiled-to-vebox-y-tiled) [164.338272] Starting subtest: yf-tiled-to-vebox-y-tiled [164.342642] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s) [164.523331] [047/143] (835s left) kms_psr (fbc-psr-sprite-blt) [164.598623] Starting subtest: fbc-psr-sprite-blt [164.600988] Starting dynamic subtest: eDP-1 [164.605701] Dynamic subtest eDP-1: SKIP (0.000s) [164.610202] Subtest fbc-psr-sprite-blt: SKIP (0.001s) [164.696423] [048/143] (835s left) kms_big_fb (linear-16bpp-rotate-0) [164.794877] Starting subtest: linear-16bpp-rotate-0 [166.311134] Subtest linear-16bpp-rotate-0: SUCCESS (1.518s) [166.433277] [049/143] (833s left) gem_linear_blits (normal) [166.857600] Starting subtest: normal [174.744096] Subtest normal: SUCCESS (7.886s) [174.881442] [050/143] (825s left) kms_ccs (pipe-d-bad-aux-stride-y-tiled-gen12-rc-ccs) [175.407415] Starting subtest: pipe-D-bad-aux-stride-y-tiled-gen12-rc-ccs [175.412797] Subtest pipe-D-bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.000s) [176.259759] [051/143] (824s left) gem_exec_whisper (basic-contexts-priority) [176.345749] Starting subtest: basic-contexts-priority [200.057701] Subtest basic-contexts-priority: SUCCESS (23.707s) [200.241240] [052/143] (800s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite) [200.357301] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite [200.361264] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [200.438262] [053/143] (800s left) perf (gen12-invalid-class-instance) [200.607771] Starting subtest: gen12-invalid-class-instance [200.619435] Subtest gen12-invalid-class-instance: SUCCESS (0.011s) [200.638863] [054/143] (799s left) kms_chamelium_hpd (hdmi-hpd) [201.018061] Subtest hdmi-hpd: SKIP (0.000s) [201.881237] [055/143] (798s left) kms_ccs (pipe-a-crc-primary-rotation-180-4-tiled-dg2-mc-ccs) [202.301306] Starting subtest: pipe-A-crc-primary-rotation-180-4-tiled-dg2-mc-ccs [202.306583] Subtest pipe-A-crc-primary-rotation-180-4-tiled-dg2-mc-ccs: SKIP (0.001s) [203.172176] [056/143] (797s left) kms_vblank (wait-idle-hang) [203.258280] Starting subtest: wait-idle-hang [203.260760] Starting dynamic subtest: pipe-A-eDP-1 [217.349677] Dynamic subtest pipe-A-eDP-1: SUCCESS (14.089s) [217.367828] Starting dynamic subtest: pipe-D-eDP-1 [242.402623] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.048s) [242.405283] Subtest wait-idle-hang: SUCCESS (39.147s) [243.317915] [057/143] (757s left) kms_ccs (pipe-c-bad-aux-stride-y-tiled-gen12-mc-ccs) [243.725587] Starting subtest: pipe-C-bad-aux-stride-y-tiled-gen12-mc-ccs [243.731197] Subtest pipe-C-bad-aux-stride-y-tiled-gen12-mc-ccs: SKIP (0.000s) [244.594463] [058/143] (756s left) v3d/v3d_submit_cl (simple-flush-cache) [244.671482] Subtest simple-flush-cache: SKIP (0.000s) [244.722335] [059/143] (756s left) gem_ctx_persistence (process) [244.788134] Starting subtest: process [244.930604] Subtest process: SUCCESS (0.142s) [245.020252] [060/143] (755s left) drm_fdinfo (most-busy-check-all) [245.061933] Starting subtest: most-busy-check-all [245.064795] Starting dynamic subtest: rcs0 [245.069459] Dynamic subtest rcs0: SKIP (0.004s) [245.080748] Starting dynamic subtest: bcs0 [245.084965] Dynamic subtest bcs0: SKIP (0.000s) [245.091451] Starting dynamic subtest: vcs0 [245.095655] Dynamic subtest vcs0: SKIP (0.000s) [245.102374] Starting dynamic subtest: vcs1 [245.106589] Dynamic subtest vcs1: SKIP (0.000s) [245.113451] Starting dynamic subtest: vecs0 [245.117688] Dynamic subtest vecs0: SKIP (0.000s) [245.124576] Starting dynamic subtest: ccs0 [245.128801] Dynamic subtest ccs0: SKIP (0.000s) [245.133077] Subtest most-busy-check-all: SKIP (0.007s) [245.150648] [061/143] (755s left) gem_partial_pwrite_pread (reads) [245.264131] Subtest reads: SKIP (0.000s) [245.313967] [062/143] (755s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled) [245.384996] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled [245.387611] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s) [245.480590] [063/143] (755s left) kms_cursor_legacy (single-bo) [245.607561] Starting subtest: single-bo [245.609800] Starting dynamic subtest: pipe-A [251.052635] Dynamic subtest pipe-A: SUCCESS (5.387s) [251.060634] Starting dynamic subtest: pipe-B [256.385654] Dynamic subtest pipe-B: SUCCESS (5.386s) [256.392023] Starting dynamic subtest: pipe-C [261.772658] Dynamic subtest pipe-C: SUCCESS (5.385s) [261.778650] Starting dynamic subtest: pipe-D [267.158105] Dynamic subtest pipe-D: SUCCESS (5.385s) [267.176588] Starting dynamic subtest: all-pipes [272.546654] Dynamic subtest all-pipes: SUCCESS (5.388s) [272.549206] Subtest single-bo: SUCCESS (26.936s) [272.700570] [064/143] (728s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling) [272.778104] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling [272.780805] Starting dynamic subtest: pipe-A-default-mode [273.115545] Dynamic subtest pipe-A-default-mode: SKIP (0.334s) [273.120492] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SKIP (0.336s) [273.947792] [065/143] (726s left) gem_exec_schedule (out-order) [274.029485] Starting subtest: out-order [274.032245] Starting dynamic subtest: rcs0 [274.146736] Dynamic subtest rcs0: SUCCESS (0.116s) [274.163596] Starting dynamic subtest: vcs0 [274.300177] Dynamic subtest vcs0: SUCCESS (0.152s) [274.318475] Starting dynamic subtest: vcs1 [274.421965] Dynamic subtest vcs1: SUCCESS (0.121s) [274.439704] Starting dynamic subtest: vecs0 [274.520404] Dynamic subtest vecs0: SUCCESS (0.097s) [274.538522] Starting dynamic subtest: ccs0 [274.608766] Dynamic subtest ccs0: SUCCESS (0.088s) [274.611884] Subtest out-order: SUCCESS (0.579s) [275.550666] [066/143] (725s left) kms_ccs (pipe-c-crc-primary-rotation-180-4-tiled-mtl-mc-ccs) [275.976495] Starting subtest: pipe-C-crc-primary-rotation-180-4-tiled-mtl-mc-ccs [278.563431] Subtest pipe-C-crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (2.587s) [279.817546] [067/143] (721s left) syncobj_basic (bad-destroy) [279.889257] Starting subtest: bad-destroy [279.891739] Subtest bad-destroy: SUCCESS (0.000s) [279.972211] [068/143] (721s left) syncobj_wait (single-wait-submitted) [280.044179] Starting subtest: single-wait-submitted [280.143770] Subtest single-wait-submitted: SUCCESS (0.101s) [280.221045] [069/143] (720s left) kms_ccs (pipe-d-crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc) [280.647935] Starting subtest: pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc [280.652639] Subtest pipe-D-crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s) [281.515715] [070/143] (719s left) sw_sync (sync_merge_same) [281.552901] Starting subtest: sync_merge_same [281.555270] Subtest sync_merge_same: SUCCESS (0.000s) [281.598940] [071/143] (719s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-cpu) [281.697030] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-cpu [286.296662] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (4.605s) [286.394646] [072/143] (714s left) syncobj_timeline (invalid-single-wait-unsubmitted) [286.470458] Starting subtest: invalid-single-wait-unsubmitted [286.473032] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s) [286.545406] [073/143] (714s left) perf (low-oa-exponent-permissions) [286.648708] Starting subtest: low-oa-exponent-permissions [286.699711] Subtest low-oa-exponent-permissions: SUCCESS (0.052s) [286.749249] [074/143] (714s left) api_intel_bb (misplaced-blitter) [286.817830] Starting subtest: misplaced-blitter [286.826353] Subtest misplaced-blitter: SUCCESS (0.012s) [286.934916] [075/143] (714s left) gem_mmap_wc (write-cpu-read-wc) [287.019146] Subtest write-cpu-read-wc: SKIP (0.000s) [287.072720] [076/143] (714s left) syncobj_timeline (device-signal-unordered) [287.163717] Starting subtest: device-signal-unordered [287.166381] Subtest device-signal-unordered: SUCCESS (0.002s) [287.241896] [077/143] (713s left) kms_psr (psr2-sprite-mmap-cpu) [287.361963] Starting subtest: psr2-sprite-mmap-cpu [287.364246] Starting dynamic subtest: eDP-1 [289.197176] Dynamic subtest eDP-1: SUCCESS (1.835s) [289.200095] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.836s) [290.076565] [078/143] (711s left) gem_softpin (evict-prime) [290.144772] Starting subtest: evict-prime [290.147507] Starting dynamic subtest: rcs0 [291.276744] Dynamic subtest rcs0: SUCCESS (1.132s) [291.288675] Starting dynamic subtest: bcs0 [292.389632] Dynamic subtest bcs0: SUCCESS (1.111s) [292.401933] Starting dynamic subtest: vcs0 [294.442581] Dynamic subtest vcs0: SUCCESS (2.051s) [294.464967] Starting dynamic subtest: vcs1 [296.411575] Dynamic subtest vcs1: SUCCESS (1.966s) [296.431494] Starting dynamic subtest: vecs0 [298.418805] Dynamic subtest vecs0: SUCCESS (2.006s) [298.438499] Starting dynamic subtest: ccs0 [299.527669] Dynamic subtest ccs0: SUCCESS (1.109s) [299.538921] Starting dynamic subtest: all [301.491062] Dynamic subtest all: SUCCESS (1.960s) [301.494326] Subtest evict-prime: SUCCESS (11.345s) [301.572359] [079/143] (699s left) gem_ctx_shared (q-smoketest) [301.663863] Starting subtest: Q-smoketest [301.666443] Starting dynamic subtest: rcs0 [307.270527] Dynamic subtest rcs0: SUCCESS (5.550s) [307.283819] Starting dynamic subtest: bcs0 [312.780095] Dynamic subtest bcs0: SUCCESS (5.512s) [312.799818] Starting dynamic subtest: vcs0 [318.259981] Dynamic subtest vcs0: SUCCESS (5.480s) [318.267430] Starting dynamic subtest: vcs1 [323.754306] Dynamic subtest vcs1: SUCCESS (5.491s) [323.777178] Starting dynamic subtest: vecs0 [329.243264] Dynamic subtest vecs0: SUCCESS (5.485s) [329.261195] Starting dynamic subtest: ccs0 [334.862777] Dynamic subtest ccs0: SUCCESS (5.561s) [334.864882] Subtest Q-smoketest: SUCCESS (33.085s) [334.946705] [080/143] (666s left) v3d/v3d_perfmon (get-values-invalid-pointer) [335.038417] Subtest get-values-invalid-pointer: SKIP (0.000s) [335.079667] [081/143] (666s left) kms_pm_lpsp (screens-disabled) [336.200353] Starting subtest: screens-disabled [336.205518] Subtest screens-disabled: SKIP (0.000s) [336.276783] [082/143] (665s left) kms_psr (fbc-pr-sprite-mmap-cpu) [336.402057] Starting subtest: fbc-pr-sprite-mmap-cpu [336.407322] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s) [336.524196] [083/143] (664s left) prime_busy (hang) [336.609733] Starting subtest: hang [336.612757] Starting dynamic subtest: rcs0 [349.983888] Dynamic subtest rcs0: SUCCESS (13.370s) [350.002934] Starting dynamic subtest: bcs0 [357.146979] Dynamic subtest bcs0: SUCCESS (7.163s) [357.153622] Starting dynamic subtest: vcs0 [364.110767] Dynamic subtest vcs0: SUCCESS (6.963s) [364.117397] Starting dynamic subtest: vcs1 [371.092165] Dynamic subtest vcs1: SUCCESS (6.981s) [371.098691] Starting dynamic subtest: vecs0 [378.126594] Dynamic subtest vecs0: SUCCESS (7.033s) [378.132790] Starting dynamic subtest: ccs0 [391.978801] Dynamic subtest ccs0: SUCCESS (13.851s) [391.981779] Subtest hang: SUCCESS (55.367s) [392.085897] [084/143] (609s left) vc4/vc4_perfmon (create-perfmon-exceed) [392.200593] Subtest create-perfmon-exceed: SKIP (0.000s) [392.257885] [085/143] (609s left) gem_exec_params (invalid-ring2) [392.376070] Starting subtest: invalid-ring2 [392.378770] Subtest invalid-ring2: SUCCESS (0.000s) [392.470386] [086/143] (609s left) kms_ccs (pipe-b-ccs-on-another-bo-yf-tiled-ccs) [392.890742] Starting subtest: pipe-B-ccs-on-another-bo-yf-tiled-ccs [392.897037] Subtest pipe-B-ccs-on-another-bo-yf-tiled-ccs: SKIP (0.000s) [393.783779] [087/143] (607s left) kms_pipe_crc_basic (hang-read-crc) [393.870711] Starting subtest: hang-read-crc [393.873111] Starting dynamic subtest: pipe-A-eDP-1 [396.079706] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.208s) [396.098209] Starting dynamic subtest: pipe-B-eDP-1 [399.064852] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.983s) [399.081400] Starting dynamic subtest: pipe-C-eDP-1 [401.862572] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.795s) [401.884810] Starting dynamic subtest: pipe-D-eDP-1 [404.780039] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.913s) [404.782668] Subtest hang-read-crc: SUCCESS (10.912s) [405.679997] [088/143] (595s left) kms_plane_scaling (planes-downscale-factor-0-75) [405.748358] Starting subtest: planes-downscale-factor-0-75 [405.750684] Starting dynamic subtest: pipe-A-eDP-1 [405.779123] Dynamic subtest pipe-A-eDP-1: SKIP (0.026s) [405.789577] Starting dynamic subtest: pipe-B-eDP-1 [405.813092] Dynamic subtest pipe-B-eDP-1: SKIP (0.029s) [405.831854] Starting dynamic subtest: pipe-C-eDP-1 [405.837555] Dynamic subtest pipe-C-eDP-1: SKIP (0.019s) [405.850170] Starting dynamic subtest: pipe-D-eDP-1 [405.855074] Dynamic subtest pipe-D-eDP-1: SKIP (0.020s) [405.859851] Subtest planes-downscale-factor-0-75: SKIP (0.102s) [405.931505] [089/143] (595s left) gem_mmap (bad-object) [405.995160] Subtest bad-object: SKIP (0.000s) [406.037820] [090/143] (595s left) kms_invalid_mode (zero-vdisplay) [406.137118] Starting subtest: zero-vdisplay [406.139629] Starting dynamic subtest: pipe-A-eDP-1 [406.422828] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s) [406.434514] Starting dynamic subtest: pipe-B-eDP-1 [406.437042] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [406.444856] Starting dynamic subtest: pipe-C-eDP-1 [406.447352] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [406.465328] Starting dynamic subtest: pipe-D-eDP-1 [406.468039] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [406.470318] Subtest zero-vdisplay: SUCCESS (0.298s) [407.351302] [091/143] (594s left) gem_exec_schedule (preempt-queue-chain) [407.448741] Subtest preempt-queue-chain: SKIP (0.000s) [407.755679] [092/143] (593s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-cpu) [407.828463] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-cpu [410.629908] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-cpu: SUCCESS (2.805s) [410.741791] [093/143] (590s left) gem_lmem_swapping (parallel-random-verify-ccs) [410.813783] Subtest parallel-random-verify-ccs: SKIP (0.000s) [410.857874] [094/143] (590s left) gem_create (create-ext-set-pat) [411.047912] Starting subtest: create-ext-set-pat [411.051240] Subtest create-ext-set-pat: SUCCESS (0.001s) [411.138905] [095/143] (590s left) i915_pm_sseu (full-enable) [411.215685] Subtest full-enable: SKIP (0.000s) [411.292018] [096/143] (590s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-gtt) [411.354122] Starting subtest: fbc-rgb101010-draw-mmap-gtt [411.358418] Subtest fbc-rgb101010-draw-mmap-gtt: SKIP (0.000s) [411.442580] [097/143] (590s left) gem_ccs (block-copy-uncompressed) [411.535214] Starting subtest: block-copy-uncompressed [411.537800] Starting dynamic subtest: linear-uncompressed-compfmt0-smem-smem [411.571646] Dynamic subtest linear-uncompressed-compfmt0-smem-smem: SUCCESS (0.035s) [411.589684] Starting dynamic subtest: xmajor-uncompressed-compfmt0-smem-smem [411.592294] Dynamic subtest xmajor-uncompressed-compfmt0-smem-smem: SUCCESS (0.019s) [411.602068] Starting dynamic subtest: tile4-uncompressed-compfmt0-smem-smem [411.609471] Dynamic subtest tile4-uncompressed-compfmt0-smem-smem: SUCCESS (0.018s) [411.618243] Starting dynamic subtest: tile64-uncompressed-compfmt0-smem-smem [411.627349] Dynamic subtest tile64-uncompressed-compfmt0-smem-smem: SUCCESS (0.017s) [411.629820] Subtest block-copy-uncompressed: SUCCESS (0.092s) [411.718974] [098/143] (590s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu) [411.806413] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu [411.811162] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [411.870248] [099/143] (590s left) perf (invalid-create-userspace-config) [411.984181] Starting subtest: invalid-create-userspace-config [411.986826] Subtest invalid-create-userspace-config: SUCCESS (0.001s) [412.010324] [100/143] (589s left) kms_vblank (query-idle-hang) [412.109853] Starting subtest: query-idle-hang [412.112101] Starting dynamic subtest: pipe-A-eDP-1 [426.398624] Dynamic subtest pipe-A-eDP-1: SUCCESS (14.285s) [426.413949] Starting dynamic subtest: pipe-D-eDP-1 [451.358161] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.951s) [451.360874] Subtest query-idle-hang: SUCCESS (39.247s) [452.277757] [101/143] (549s left) gem_ctx_isolation (nonpriv-switch) [452.365758] Starting subtest: nonpriv-switch [452.369055] Starting dynamic subtest: rcs0 [452.666543] Dynamic subtest rcs0: SUCCESS (0.302s) [452.675625] Starting dynamic subtest: bcs0 [452.955477] Dynamic subtest bcs0: SUCCESS (0.287s) [452.973394] Starting dynamic subtest: vcs0 [453.257497] Dynamic subtest vcs0: SUCCESS (0.302s) [453.276230] Starting dynamic subtest: vcs1 [454.778207] Dynamic subtest vcs1: SUCCESS (1.520s) [454.798431] Starting dynamic subtest: vecs0 [456.791392] Dynamic subtest vecs0: SUCCESS (2.013s) [456.809745] Starting dynamic subtest: ccs0 [458.869793] Dynamic subtest ccs0: SUCCESS (2.078s) [458.872705] Subtest nonpriv-switch: SUCCESS (6.505s) [459.105303] [102/143] (542s left) kms_cursor_legacy (2x-flip-vs-cursor-atomic) [459.181359] Starting subtest: 2x-flip-vs-cursor-atomic [459.186389] Subtest 2x-flip-vs-cursor-atomic: SKIP (0.001s) [459.276090] [103/143] (542s left) kms_cursor_crc (cursor-sliding-256x85) [459.587964] Starting subtest: cursor-sliding-256x85 [459.621792] Subtest cursor-sliding-256x85: SKIP (0.026s) [459.770706] [104/143] (542s left) gem_mmap_gtt (basic-write-gtt) [459.833892] Subtest basic-write-gtt: SKIP (0.000s) [459.882484] [105/143] (542s left) kms_big_fb (yf-tiled-32bpp-rotate-270) [459.974669] Starting subtest: yf-tiled-32bpp-rotate-270 [459.979903] Subtest yf-tiled-32bpp-rotate-270: SKIP (0.000s) [460.066967] [106/143] (542s left) kms_ccs (pipe-b-missing-ccs-buffer-4-tiled-mtl-rc-ccs) [460.488883] Starting subtest: pipe-B-missing-ccs-buffer-4-tiled-mtl-rc-ccs [460.491962] Subtest pipe-B-missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.000s) [461.368707] [107/143] (540s left) kms_pm_backlight (basic-brightness) [461.435357] Starting subtest: basic-brightness [461.496485] Starting dynamic subtest: eDP-1 [462.022433] Dynamic subtest eDP-1: SUCCESS (0.526s) [462.025198] Subtest basic-brightness: SUCCESS (0.590s) [462.968184] [108/143] (539s left) gem_busy (close-race) [463.043462] Starting subtest: close-race [486.453491] Subtest close-race: SUCCESS (23.408s) [486.823927] [109/143] (515s left) kms_atomic (plane-invalid-params) [487.002076] Starting subtest: plane-invalid-params [487.004940] Starting dynamic subtest: pipe-A-eDP-1 [487.521885] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.518s) [487.524462] Subtest plane-invalid-params: SUCCESS (0.520s) [488.408578] [110/143] (513s left) kms_getfb (getfb-handle-closed) [488.476307] Starting subtest: getfb-handle-closed [488.479235] Subtest getfb-handle-closed: SUCCESS (0.000s) [488.556440] [111/143] (513s left) v3d/v3d_job_submission (array-job-submission) [488.640492] Subtest array-job-submission: SKIP (0.000s) [488.697901] [112/143] (513s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [488.786015] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip [489.851976] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (1.070s) [490.002190] [113/143] (512s left) kms_addfb_basic (bo-too-small) [490.110588] Starting subtest: bo-too-small [490.113085] Subtest bo-too-small: SUCCESS (0.000s) [490.194240] [114/143] (512s left) kms_ccs (pipe-d-crc-primary-basic-4-tiled-mtl-rc-ccs-cc) [490.660318] Starting subtest: pipe-D-crc-primary-basic-4-tiled-mtl-rc-ccs-cc [491.662622] Subtest pipe-D-crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (1.002s) [492.839659] [115/143] (509s left) prime_mmap_coherency (read) [492.923141] Starting subtest: read [498.356402] Subtest read: SUCCESS (5.380s) [498.395409] [116/143] (503s left) gem_sync (basic-store-all) [498.499952] Starting subtest: basic-store-all [501.913986] Subtest basic-store-all: SUCCESS (3.399s) [502.087588] [117/143] (500s left) i915_pciid [502.260339] [118/143] (500s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt) [502.352093] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt [505.635975] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (3.289s) [505.772433] [119/143] (496s left) kms_frontbuffer_tracking (psr-farfromfence-mmap-gtt) [505.895818] Starting subtest: psr-farfromfence-mmap-gtt [505.900360] Subtest psr-farfromfence-mmap-gtt: SKIP (0.000s) [505.978868] [120/143] (496s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-render) [506.063845] Starting subtest: psr-1p-primscrn-spr-indfb-draw-render [510.915809] Subtest psr-1p-primscrn-spr-indfb-draw-render: SUCCESS (4.856s) [511.050835] [121/143] (491s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu) [511.167872] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu [515.928751] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (4.766s) [516.041136] [122/143] (486s left) kms_getfb (getfb-handle-zero) [516.097720] Starting subtest: getfb-handle-zero [516.100946] Subtest getfb-handle-zero: SUCCESS (0.000s) [516.203897] [123/143] (486s left) sw_sync (alloc_merge_fence) [516.261640] Starting subtest: alloc_merge_fence [516.263744] Subtest alloc_merge_fence: SUCCESS (0.000s) [516.323157] [124/143] (486s left) dmabuf_sync_file (export-before-signal) [516.376516] Starting subtest: export-before-signal [516.378731] Subtest export-before-signal: SUCCESS (0.001s) [516.434443] [125/143] (486s left) gen9_exec_parse (secure-batches) [516.506887] Subtest secure-batches: SKIP (0.000s) [516.583404] [126/143] (486s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions) [516.702350] Starting subtest: cursorB-vs-flipB-atomic-transitions [516.706920] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s) [516.794844] [127/143] (485s left) kms_psr2_sf (fbc-overlay-plane-move-continuous-exceed-sf) [522.089251] Starting subtest: fbc-overlay-plane-move-continuous-exceed-sf [522.092188] Starting dynamic subtest: pipe-A-eDP-1 [522.928261] Dynamic subtest pipe-A-eDP-1: SKIP (0.834s) [522.946738] Starting dynamic subtest: pipe-B-eDP-1 [524.164908] Dynamic subtest pipe-B-eDP-1: SKIP (1.233s) [524.169832] Subtest fbc-overlay-plane-move-continuous-exceed-sf: SKIP (2.075s) [525.447887] [128/143] (477s left) gem_exec_fence (invalid-fence-array) [525.914648] Starting subtest: invalid-fence-array [525.917640] Subtest invalid-fence-array: SUCCESS (0.003s) [527.552063] [129/143] (475s left) vgem_basic (second-client) [527.613537] Starting subtest: second-client [527.635681] Subtest second-client: SUCCESS (0.022s) [527.683822] [130/143] (475s left) gem_mmap_gtt (basic-small-copy-xy) [527.764958] Subtest basic-small-copy-XY: SKIP (0.000s) [527.815730] [131/143] (475s left) i915_pm_rpm (system-suspend-devices) [529.651566] Starting subtest: system-suspend-devices [542.340964] Subtest system-suspend-devices: SUCCESS (12.691s) [543.144447] [132/143] (459s left) kms_ccs (pipe-c-bad-rotation-90-4-tiled-mtl-mc-ccs) [543.581174] Starting subtest: pipe-C-bad-rotation-90-4-tiled-mtl-mc-ccs [544.599737] Subtest pipe-C-bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (1.019s) [545.781148] [133/143] (457s left) gem_exec_reloc (basic-wc-gtt-noreloc) [545.872924] Subtest basic-wc-gtt-noreloc: SKIP (0.000s) [545.938683] [134/143] (457s left) gem_userptr_blits (sd-probe) [545.999304] Starting subtest: sd-probe [546.001558] Subtest sd-probe: SUCCESS (0.000s) [546.105970] [135/143] (456s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-render) [546.177376] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-render [550.948326] Subtest fbcpsr-1p-offscren-pri-indfb-draw-render: SUCCESS (4.774s) [551.103233] [136/143] (451s left) kms_ccs (pipe-d-random-ccs-data-4-tiled-dg2-rc-ccs) [551.568764] Starting subtest: pipe-D-random-ccs-data-4-tiled-dg2-rc-ccs [551.576902] Subtest pipe-D-random-ccs-data-4-tiled-dg2-rc-ccs: SKIP (0.000s) [552.436612] [137/143] (450s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted) [552.502265] Starting subtest: multi-wait-available-unsubmitted-submitted [552.504478] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.000s) [552.580325] [138/143] (450s left) kms_addfb_basic (basic-y-tiled-legacy) [552.648992] Starting subtest: basic-y-tiled-legacy [552.654241] Subtest basic-y-tiled-legacy: SKIP (0.000s) [552.765687] [139/143] (450s left) kms_plane (pixel-format-source-clamping) [552.842586] Starting subtest: pixel-format-source-clamping [552.845225] Starting dynamic subtest: pipe-A [563.042227] Dynamic subtest pipe-A: SUCCESS (10.198s) [563.062974] Starting dynamic subtest: pipe-B [574.339077] Dynamic subtest pipe-B: SUCCESS (11.292s) [574.342153] Subtest pixel-format-source-clamping: SUCCESS (21.495s) [575.227865] [140/143] (427s left) kms_psr (fbc-psr2-dpms) [575.311867] Starting subtest: fbc-psr2-dpms [575.314305] Starting dynamic subtest: eDP-1 [575.319051] Dynamic subtest eDP-1: SKIP (0.000s) [575.323790] Subtest fbc-psr2-dpms: SKIP (0.002s) [575.432748] [141/143] (427s left) gem_exec_basic (basic) [575.523943] Starting subtest: basic [575.526881] Starting dynamic subtest: rcs0-smem [575.529742] Dynamic subtest rcs0-smem: SUCCESS (0.005s) [575.540336] Starting dynamic subtest: bcs0-smem [575.542806] Dynamic subtest bcs0-smem: SUCCESS (0.003s) [575.550519] Starting dynamic subtest: vcs0-smem [575.552873] Dynamic subtest vcs0-smem: SUCCESS (0.003s) [575.559949] Starting dynamic subtest: vcs1-smem [575.562379] Dynamic subtest vcs1-smem: SUCCESS (0.002s) [575.569235] Starting dynamic subtest: vecs0-smem [575.571309] Dynamic subtest vecs0-smem: SUCCESS (0.002s) [575.577800] Starting dynamic subtest: ccs0-smem [575.579744] Dynamic subtest ccs0-smem: SUCCESS (0.003s) [575.581824] Subtest basic: SUCCESS (0.023s) [577.505199] [142/143] (425s left) gem_render_copy (yf-tiled) [577.599054] Starting subtest: yf-tiled [577.604293] Subtest yf-tiled: SKIP (0.000s) [577.789095] [143/143] (425s left) kms_cursor_crc (cursor-alpha-opaque) [577.890218] Starting subtest: cursor-alpha-opaque [577.893058] Starting dynamic subtest: pipe-A-eDP-1 [578.604832] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.712s) [578.628756] Starting dynamic subtest: pipe-D-eDP-1 [580.042964] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.431s) [580.046283] Subtest cursor-alpha-opaque: SUCCESS (2.150s) [580.926353] Closing watchdogs Done.