[58.683300] Initializing watchdogs [58.683350] /dev/watchdog0 [58.699350] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics] [58.704418] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [58.706992] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [58.710871] [001/156] (960s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-pwrite) [58.852791] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-pwrite [58.857319] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [58.945860] [002/156] (960s left) kms_big_fb (yf-tiled-16bpp-rotate-270) [59.046854] Starting subtest: yf-tiled-16bpp-rotate-270 [59.050947] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s) [59.151191] [003/156] (960s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs) [59.242317] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs [59.244635] Starting dynamic subtest: pipe-A-eDP-1 [59.266785] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [59.275523] Starting dynamic subtest: pipe-B-eDP-1 [59.297611] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [59.304797] Starting dynamic subtest: pipe-C-eDP-1 [59.326707] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [59.333592] Starting dynamic subtest: pipe-D-eDP-1 [59.355618] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [59.360525] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.004s) [61.581483] [004/156] (957s left) kms_plane_multiple (tiling-yf) [61.679194] Starting subtest: tiling-yf [61.683863] Subtest tiling-yf: SKIP (0.000s) [61.751503] [005/156] (957s left) sw_sync (sync_busy_fork_unixsocket) [61.787422] Starting subtest: sync_busy_fork_unixsocket [61.796577] Subtest sync_busy_fork_unixsocket: SUCCESS (0.010s) [61.854465] [006/156] (957s left) kms_pm_backlight (fade-with-suspend) [61.951872] Starting subtest: fade-with-suspend [78.720873] Starting dynamic subtest: eDP-1 [81.746309] Dynamic subtest eDP-1: SUCCESS (3.015s) [81.748987] Subtest fade-with-suspend: SUCCESS (5.493s) [89.572821] [007/156] (936s left) perf (polling) [89.723910] Starting subtest: polling [89.726313] Starting dynamic subtest: 0-rcs0 [99.750015] Dynamic subtest 0-rcs0: SUCCESS (9.887s) [99.758801] Starting dynamic subtest: 1-vecs0 [109.762380] Dynamic subtest 1-vecs0: SUCCESS (9.969s) [109.764790] Subtest polling: SUCCESS (19.856s) [109.810526] [008/156] (916s left) gem_exec_fence (basic-busy-all) [109.879673] Starting subtest: basic-busy-all [109.967658] Subtest basic-busy-all: SUCCESS (0.089s) [111.634362] [FACT gem_exec_fence (basic-busy-all)] new: kernel.kmod_is_loaded.vgem: true [111.637444] [009/156] (914s left) gem_userptr_blits (sync-unmap-after-close) [111.704637] Starting subtest: sync-unmap-after-close [111.721128] Subtest sync-unmap-after-close: SUCCESS (0.018s) [111.848551] [010/156] (914s left) api_intel_bb (blit-noreloc-keep-cache) [111.895403] Starting subtest: blit-noreloc-keep-cache [111.911954] Subtest blit-noreloc-keep-cache: SUCCESS (0.019s) [112.007649] [011/156] (914s left) fbdev (info) [112.044251] Starting subtest: info [112.046899] Subtest info: SUCCESS (0.000s) [112.108456] [012/156] (914s left) kms_psr (pr-cursor-mmap-cpu) [112.208128] Starting subtest: pr-cursor-mmap-cpu [112.213563] Subtest pr-cursor-mmap-cpu: SKIP (0.000s) [112.280384] [013/156] (914s left) gem_partial_pwrite_pread (write-uncached) [112.336162] Subtest write-uncached: SKIP (0.000s) [112.407537] [014/156] (914s left) kms_invalid_mode (clock-too-high) [112.507002] Starting subtest: clock-too-high [112.509715] Starting dynamic subtest: pipe-A-eDP-1 [112.791295] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s) [112.802366] Starting dynamic subtest: pipe-B-eDP-1 [112.807201] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s) [112.817115] Starting dynamic subtest: pipe-C-eDP-1 [112.822301] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s) [112.831130] Starting dynamic subtest: pipe-D-eDP-1 [112.836444] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [112.839125] Subtest clock-too-high: SUCCESS (0.293s) [113.596520] [015/156] (913s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [113.691316] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu [113.696465] Subtest hdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [113.798759] [016/156] (913s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-blt) [113.909226] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-blt [113.914633] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s) [113.996982] [017/156] (912s left) i915_suspend (fence-restore-tiled2untiled) [114.052230] Starting subtest: fence-restore-tiled2untiled [114.057413] Subtest fence-restore-tiled2untiled: SKIP (0.000s) [114.150151] [018/156] (912s left) gem_userptr_blits (stress-mm-invalidate-close-overlap) [114.222438] Starting subtest: stress-mm-invalidate-close-overlap [116.365198] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s) [116.483098] [019/156] (910s left) gem_pxp (protected-encrypted-src-copy-not-readible) [116.597534] Starting subtest: protected-encrypted-src-copy-not-readible [116.717788] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.122s) [116.833229] [020/156] (910s left) kms_cursor_crc (cursor-rapid-movement-512x512) [117.094750] Starting subtest: cursor-rapid-movement-512x512 [117.099898] Subtest cursor-rapid-movement-512x512: SKIP (0.001s) [117.247793] [021/156] (909s left) kms_cursor_crc (cursor-alpha-transparent) [117.338113] Starting subtest: cursor-alpha-transparent [117.340425] Starting dynamic subtest: pipe-A-eDP-1 [117.980144] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.643s) [117.990074] Starting dynamic subtest: pipe-D-eDP-1 [119.285832] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.302s) [119.288512] Subtest cursor-alpha-transparent: SUCCESS (1.947s) [120.178378] [022/156] (906s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs-cc) [120.278676] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs-cc [120.281154] Starting dynamic subtest: pipe-A-eDP-1 [121.025399] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.737s) [121.035352] Starting dynamic subtest: pipe-B-eDP-1 [122.798366] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.771s) [122.808390] Starting dynamic subtest: pipe-C-eDP-1 [124.638301] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.841s) [124.648385] Starting dynamic subtest: pipe-D-eDP-1 [126.476435] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.835s) [126.478600] Subtest random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (6.187s) [127.641827] [023/156] (899s left) prime_busy (before) [127.710024] Starting subtest: before [127.712414] Starting dynamic subtest: rcs0 [127.738083] Dynamic subtest rcs0: SUCCESS (0.026s) [127.749986] Starting dynamic subtest: bcs0 [127.780110] Dynamic subtest bcs0: SUCCESS (0.042s) [127.790662] Starting dynamic subtest: vcs0 [127.822653] Dynamic subtest vcs0: SUCCESS (0.042s) [127.833410] Starting dynamic subtest: vcs1 [127.865665] Dynamic subtest vcs1: SUCCESS (0.043s) [127.875864] Starting dynamic subtest: vecs0 [127.907310] Dynamic subtest vecs0: SUCCESS (0.041s) [127.916937] Starting dynamic subtest: ccs0 [127.949104] Dynamic subtest ccs0: SUCCESS (0.041s) [128.056905] Subtest before: SUCCESS (0.347s) [128.135364] [024/156] (899s left) gem_exec_balancer (hang) [128.391835] Starting subtest: hang [130.046221] Subtest hang: SUCCESS (1.657s) [130.158778] [025/156] (897s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-pwrite) [130.259351] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-pwrite [130.264943] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [130.355380] [026/156] (896s left) kms_frontbuffer_tracking (pipe-fbc-rte) [130.454467] Starting subtest: pipe-fbc-rte [130.461998] Starting dynamic subtest: pipe-B-eDP-1 [135.895698] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.445s) [135.903520] Subtest pipe-fbc-rte: SUCCESS (5.447s) [138.169647] [027/156] (889s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt) [138.264620] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt [138.269196] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s) [138.359954] [028/156] (888s left) gem_gpgpu_fill (basic) [138.417880] Starting subtest: basic [138.420066] Starting dynamic subtest: smem [138.425042] Dynamic subtest smem: SUCCESS (0.007s) [138.427372] Subtest basic: SUCCESS (0.007s) [138.557248] [029/156] (888s left) kms_dirtyfb (psr-dirtyfb-ioctl) [138.654625] Starting subtest: psr-dirtyfb-ioctl [138.657257] Starting dynamic subtest: A-eDP-1 [142.584968] Dynamic subtest A-eDP-1: SUCCESS (3.929s) [142.587189] Subtest psr-dirtyfb-ioctl: SUCCESS (3.930s) [143.467539] [030/156] (883s left) kms_plane_alpha_blend (alpha-opaque-fb) [143.562391] Starting subtest: alpha-opaque-fb [143.869383] Starting dynamic subtest: pipe-A-eDP-1 [144.584015] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.711s) [144.594412] Starting dynamic subtest: pipe-D-eDP-1 [145.925303] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.342s) [145.928242] Subtest alpha-opaque-fb: SUCCESS (2.361s) [146.746326] [031/156] (880s left) gem_exec_schedule (wide) [146.925092] Starting subtest: wide [146.931382] Starting dynamic subtest: rcs0 [157.450451] Dynamic subtest rcs0: SUCCESS (10.522s) [157.468099] Starting dynamic subtest: vcs0 [168.959081] Dynamic subtest vcs0: SUCCESS (11.503s) [168.970885] Starting dynamic subtest: vcs1 [179.791045] Dynamic subtest vcs1: SUCCESS (10.830s) [179.803110] Starting dynamic subtest: vecs0 [191.091944] Dynamic subtest vecs0: SUCCESS (11.303s) [191.103157] Starting dynamic subtest: ccs0 [206.120538] Dynamic subtest ccs0: SUCCESS (15.028s) [206.124082] Subtest wide: SUCCESS (59.189s) [207.791095] [032/156] (819s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt) [207.898213] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt [215.185881] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.296s) [215.311647] [033/156] (812s left) gem_mmap_offset (mmap-boundaries) [215.358124] Starting subtest: mmap-boundaries [215.360776] Starting dynamic subtest: smem0 [215.637401] Dynamic subtest smem0: SUCCESS (0.281s) [215.640377] Subtest mmap-boundaries: SUCCESS (0.282s) [215.765830] [034/156] (811s left) gem_exec_fence (submit-chain) [215.933773] Starting subtest: submit-chain [215.958098] Subtest submit-chain: SUCCESS (0.024s) [217.640309] [035/156] (810s left) kms_rotation_crc (primary-y-tiled-reflect-x-0) [217.722292] Starting subtest: primary-y-tiled-reflect-x-0 [217.781719] Subtest primary-y-tiled-reflect-x-0: SKIP (0.060s) [217.887374] [036/156] (809s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc) [217.981127] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc [217.986365] Subtest psrhdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [218.090518] [037/156] (809s left) gem_exec_params (invalid-ring2) [218.146128] Starting subtest: invalid-ring2 [218.148565] Subtest invalid-ring2: SUCCESS (0.000s) [218.238918] [038/156] (809s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-onoff) [218.335632] Starting subtest: hdr-1p-primscrn-cur-indfb-onoff [218.340770] Subtest hdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s) [218.426148] [039/156] (809s left) gem_userptr_blits (sync-overlap) [218.494987] Starting subtest: sync-overlap [218.497535] Subtest sync-overlap: SUCCESS (0.000s) [218.626356] [040/156] (809s left) kms_flip (flip-vs-panning-vs-hang) [218.720388] Starting subtest: flip-vs-panning-vs-hang [218.722612] Starting dynamic subtest: A-eDP1 [270.099014] Dynamic subtest A-eDP1: SUCCESS (51.368s) [270.110050] Starting dynamic subtest: D-eDP1 [310.091674] Dynamic subtest D-eDP1: SUCCESS (39.982s) [310.094570] Subtest flip-vs-panning-vs-hang: SUCCESS (91.355s) [311.294051] [041/156] (716s left) i915_pm_freq_api (freq-reset-multiple) [311.347149] Starting subtest: freq-reset-multiple [311.350055] Starting dynamic subtest: gt0 [319.540915] Dynamic subtest gt0: SUCCESS (8.195s) [319.551170] Starting dynamic subtest: gt1 [327.827398] Dynamic subtest gt1: SUCCESS (8.287s) [327.830390] Subtest freq-reset-multiple: SUCCESS (16.485s) [327.910147] [042/156] (700s left) gem_exec_fence (syncobj-timeline-wait) [328.451061] Starting subtest: syncobj-timeline-wait [329.637962] Subtest syncobj-timeline-wait: SUCCESS (1.189s) [329.857930] [043/156] (698s left) kms_plane (pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping) [329.962297] Starting subtest: pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping [329.967260] Subtest pixel-format-y-tiled-gen12-mc-ccs-modifier-source-clamping: SKIP (0.000s) [330.035645] [044/156] (698s left) syncobj_timeline (invalid-query-illegal-handle) [330.081544] Starting subtest: invalid-query-illegal-handle [330.084174] Subtest invalid-query-illegal-handle: SUCCESS (0.000s) [330.180066] [045/156] (697s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-render) [330.279220] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-render [330.283857] Subtest fbchdr-1p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [330.379075] [046/156] (697s left) vgem_basic (mmap) [330.422195] Starting subtest: mmap [330.464205] Subtest mmap: SUCCESS (0.044s) [330.525524] [047/156] (697s left) kms_flip (bo-too-big-interruptible) [330.619006] Starting subtest: bo-too-big-interruptible [330.621512] Starting dynamic subtest: A-eDP1 [331.935246] Dynamic subtest A-eDP1: SUCCESS (1.316s) [331.948273] Starting dynamic subtest: B-eDP1 [333.271320] Dynamic subtest B-eDP1: SUCCESS (1.334s) [333.283760] Starting dynamic subtest: C-eDP1 [334.611025] Dynamic subtest C-eDP1: SUCCESS (1.339s) [334.622631] Starting dynamic subtest: D-eDP1 [335.939508] Dynamic subtest D-eDP1: SUCCESS (1.327s) [335.942146] Subtest bo-too-big-interruptible: SUCCESS (5.326s) [337.150622] [048/156] (691s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt) [337.255128] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt [337.260081] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s) [337.342552] [049/156] (690s left) gem_ctx_isolation (dirty-switch) [337.393541] Starting subtest: dirty-switch [337.396210] Starting dynamic subtest: rcs0 [337.606419] Dynamic subtest rcs0: SUCCESS (0.213s) [337.618809] Starting dynamic subtest: bcs0 [337.820916] Dynamic subtest bcs0: SUCCESS (0.213s) [337.833385] Starting dynamic subtest: vcs0 [338.035945] Dynamic subtest vcs0: SUCCESS (0.214s) [338.048444] Starting dynamic subtest: vcs1 [339.796783] Dynamic subtest vcs1: SUCCESS (1.760s) [339.808905] Starting dynamic subtest: vecs0 [341.818661] Dynamic subtest vecs0: SUCCESS (2.021s) [341.830995] Starting dynamic subtest: ccs0 [343.816035] Dynamic subtest ccs0: SUCCESS (1.996s) [343.818946] Subtest dirty-switch: SUCCESS (6.423s) [344.053649] [050/156] (684s left) api_intel_bb (reset-bb) [344.111263] Starting subtest: reset-bb [344.117018] Subtest reset-bb: SUCCESS (0.006s) [344.231969] [051/156] (684s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs) [344.331983] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs [344.334324] Starting dynamic subtest: pipe-A-eDP-1 [344.398668] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [344.408220] Starting dynamic subtest: pipe-B-eDP-1 [344.474807] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [344.485823] Starting dynamic subtest: pipe-C-eDP-1 [344.553579] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [344.564999] Starting dynamic subtest: pipe-D-eDP-1 [344.632110] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [344.637369] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.008s) [346.615905] [052/156] (681s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt) [346.711262] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt [346.716161] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [346.791691] [053/156] (681s left) kms_flip (busy-flip) [346.884987] Starting subtest: busy-flip [346.887651] Starting dynamic subtest: A-eDP1 [348.628423] Dynamic subtest A-eDP1: SUCCESS (1.745s) [348.641239] Starting dynamic subtest: B-eDP1 [350.355320] Dynamic subtest B-eDP1: SUCCESS (1.725s) [350.367037] Starting dynamic subtest: C-eDP1 [352.079020] Dynamic subtest C-eDP1: SUCCESS (1.723s) [352.089246] Starting dynamic subtest: D-eDP1 [353.725285] Dynamic subtest D-eDP1: SUCCESS (1.645s) [353.728294] Subtest busy-flip: SUCCESS (6.846s) [354.896491] [054/156] (673s left) kms_lease (setcrtc-implicit-plane) [354.994046] Starting subtest: setcrtc-implicit-plane [354.996420] Starting dynamic subtest: pipe-A-eDP-1 [355.454617] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.457s) [355.465249] Starting dynamic subtest: pipe-B-eDP-1 [356.626396] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.169s) [356.637611] Starting dynamic subtest: pipe-C-eDP-1 [357.741845] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.112s) [357.755277] Starting dynamic subtest: pipe-D-eDP-1 [358.865523] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.125s) [358.868226] Subtest setcrtc-implicit-plane: SUCCESS (3.871s) [359.710000] [055/156] (668s left) gem_ctx_engines (independent) [359.772305] Starting subtest: independent [359.775120] Starting dynamic subtest: rcs0 [359.917416] Dynamic subtest rcs0: SUCCESS (0.144s) [359.927318] Starting dynamic subtest: bcs0 [360.063602] Dynamic subtest bcs0: SUCCESS (0.145s) [360.073506] Starting dynamic subtest: vcs0 [360.198694] Dynamic subtest vcs0: SUCCESS (0.135s) [360.208400] Starting dynamic subtest: vcs1 [360.324681] Dynamic subtest vcs1: SUCCESS (0.125s) [360.337356] Starting dynamic subtest: vecs0 [360.450276] Dynamic subtest vecs0: SUCCESS (0.124s) [360.463295] Starting dynamic subtest: ccs0 [360.592695] Dynamic subtest ccs0: SUCCESS (0.142s) [360.602115] Starting dynamic subtest: all-engines [361.248742] Dynamic subtest all-engines: SUCCESS (0.656s) [361.251006] Subtest independent: SUCCESS (1.476s) [361.764637] [056/156] (666s left) kms_flip (flip-vs-rmfb-interruptible) [361.855179] Starting subtest: flip-vs-rmfb-interruptible [361.858012] Starting dynamic subtest: A-eDP1 [370.497415] Dynamic subtest A-eDP1: SUCCESS (8.642s) [370.508306] Starting dynamic subtest: B-eDP1 [379.130825] Dynamic subtest B-eDP1: SUCCESS (8.631s) [379.144880] Starting dynamic subtest: C-eDP1 [387.747524] Dynamic subtest C-eDP1: SUCCESS (8.613s) [387.760174] Starting dynamic subtest: D-eDP1 [396.381192] Dynamic subtest D-eDP1: SUCCESS (8.631s) [396.386023] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.530s) [397.571344] [057/156] (631s left) gem_madvise (dontneed-before-pwrite) [397.622294] Starting subtest: dontneed-before-pwrite [397.641258] Subtest dontneed-before-pwrite: SKIP (0.014s) [397.710704] [058/156] (630s left) gem_exec_schedule (preempt) [397.774800] Starting subtest: preempt [397.777140] Starting dynamic subtest: rcs0 [397.794850] Dynamic subtest rcs0: SUCCESS (0.028s) [397.804859] Starting dynamic subtest: bcs0 [397.815728] Dynamic subtest bcs0: SUCCESS (0.020s) [397.826106] Starting dynamic subtest: vcs0 [397.837274] Dynamic subtest vcs0: SUCCESS (0.021s) [397.847481] Starting dynamic subtest: vcs1 [397.859634] Dynamic subtest vcs1: SUCCESS (0.022s) [397.869245] Starting dynamic subtest: vecs0 [397.880782] Dynamic subtest vecs0: SUCCESS (0.020s) [397.891550] Starting dynamic subtest: ccs0 [397.903411] Dynamic subtest ccs0: SUCCESS (0.022s) [397.906290] Subtest preempt: SUCCESS (0.137s) [399.660081] [059/156] (629s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling) [399.749016] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling [399.751413] Starting dynamic subtest: pipe-A-default-mode [400.941807] Dynamic subtest pipe-A-default-mode: SKIP (1.189s) [400.946385] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.190s) [401.079571] [060/156] (627s left) i915_pm_rc6_residency (rc6-fence) [401.130832] Starting subtest: rc6-fence [401.147359] Starting dynamic subtest: gt0 [413.470282] Dynamic subtest gt0: SUCCESS (12.321s) [413.483262] Starting dynamic subtest: gt1 [428.577323] Dynamic subtest gt1: SUCCESS (15.100s) [428.580162] Subtest rc6-fence: SUCCESS (27.445s) [428.661082] [061/156] (600s left) tools_test (tools_test) [428.716940] Starting subtest: tools_test [428.772945] Subtest tools_test: SUCCESS (0.075s) [428.844321] [062/156] (599s left) kms_psr (pr-no-drrs) [428.936742] Starting subtest: pr-no-drrs [428.941923] Subtest pr-no-drrs: SKIP (0.000s) [429.011803] [063/156] (599s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf) [429.104314] Starting subtest: psr2-overlay-plane-update-continuous-sf [429.107263] Starting dynamic subtest: pipe-A-eDP-1 [430.241268] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.140s) [430.253321] Starting dynamic subtest: pipe-B-eDP-1 [432.061657] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.818s) [432.064427] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (2.961s) [432.877108] [064/156] (596s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-signaled) [432.933147] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled [432.935918] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s) [433.042773] [065/156] (595s left) kms_big_fb (y-tiled-64bpp-rotate-270) [433.141776] Starting subtest: y-tiled-64bpp-rotate-270 [433.146920] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s) [433.238585] [066/156] (595s left) kms_scaling_modes (scaling-mode-center) [433.319823] Starting subtest: scaling-mode-center [433.322001] Starting dynamic subtest: pipe-A-eDP-1 [433.417275] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.099s) [433.431671] Starting dynamic subtest: pipe-B-eDP-1 [434.589783] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.169s) [434.601311] Starting dynamic subtest: pipe-C-eDP-1 [435.713961] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.123s) [435.724938] Starting dynamic subtest: pipe-D-eDP-1 [436.860211] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.145s) [436.862897] Subtest scaling-mode-center: SUCCESS (3.543s) [438.019001] [067/156] (591s left) kms_psr (fbc-pr-cursor-mmap-cpu) [438.112120] Starting subtest: fbc-pr-cursor-mmap-cpu [438.117245] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s) [438.185738] [068/156] (590s left) kms_big_fb (x-tiled-8bpp-rotate-180) [438.289034] Starting subtest: x-tiled-8bpp-rotate-180 [439.548039] Subtest x-tiled-8bpp-rotate-180: SUCCESS (1.259s) [439.661353] [069/156] (589s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-blt) [439.754184] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-blt [439.759277] Subtest psrhdr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [439.846804] [070/156] (589s left) kms_frontbuffer_tracking (hdr-1p-primscrn-shrfb-plflip-blt) [439.958144] Starting subtest: hdr-1p-primscrn-shrfb-plflip-blt [439.962126] Subtest hdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [440.068572] [071/156] (589s left) gem_mmap_gtt (big-copy-odd) [440.124324] Subtest big-copy-odd: SKIP (0.000s) [440.192372] [072/156] (589s left) kms_async_flips (alternate-sync-async-flip-atomic) [440.281913] Starting subtest: alternate-sync-async-flip-atomic [440.662516] Starting dynamic subtest: pipe-A-eDP-1 [443.425666] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.763s) [443.802909] Starting dynamic subtest: pipe-B-eDP-1 [446.559270] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.764s) [446.931288] Starting dynamic subtest: pipe-C-eDP-1 [449.694200] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.775s) [450.063657] Starting dynamic subtest: pipe-D-eDP-1 [452.827847] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.774s) [452.829755] Subtest alternate-sync-async-flip-atomic: SUCCESS (12.547s) [454.016601] [073/156] (575s left) kms_ccs (crc-primary-basic-yf-tiled-ccs) [454.106943] Starting subtest: crc-primary-basic-yf-tiled-ccs [454.109406] Starting dynamic subtest: pipe-A-eDP-1 [454.133359] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [454.145872] Starting dynamic subtest: pipe-B-eDP-1 [454.168021] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [454.177875] Starting dynamic subtest: pipe-C-eDP-1 [454.200812] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [454.210143] Starting dynamic subtest: pipe-D-eDP-1 [454.232561] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [454.237084] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.008s) [456.383341] [074/156] (572s left) gem_softpin (allocator-evict) [456.442966] Starting subtest: allocator-evict [456.447452] Starting dynamic subtest: rcs0 [480.551011] Dynamic subtest rcs0: SUCCESS (24.101s) [480.562369] Starting dynamic subtest: bcs0 [504.630739] Dynamic subtest bcs0: SUCCESS (24.072s) [504.642787] Starting dynamic subtest: vcs0 [528.420710] Dynamic subtest vcs0: SUCCESS (23.781s) [528.433380] Starting dynamic subtest: vcs1 [552.609586] Dynamic subtest vcs1: SUCCESS (24.180s) [552.626337] Starting dynamic subtest: vecs0 [576.202065] Dynamic subtest vecs0: SUCCESS (23.600s) [576.213579] Starting dynamic subtest: ccs0 [600.128850] Dynamic subtest ccs0: SUCCESS (23.936s) [600.131205] Subtest allocator-evict: SUCCESS (143.681s) [600.212082] [075/156] (429s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-fullscreen) [600.373956] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-fullscreen [606.998194] Subtest fbcpsr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.632s) [607.143816] [076/156] (422s left) gem_mmap_wc (write-wc-read-gtt) [607.198987] Subtest write-wc-read-gtt: SKIP (0.000s) [607.253902] [077/156] (422s left) kms_chamelium_hpd (vga-hpd) [607.634769] Subtest vga-hpd: SKIP (0.000s) [608.492880] [078/156] (421s left) kms_fb_coherency (memset-crc) [608.583912] Starting subtest: memset-crc [608.586540] Starting dynamic subtest: mmap-offset-wc [608.997919] Dynamic subtest mmap-offset-wc: SUCCESS (0.414s) [609.306646] Starting dynamic subtest: mmap-offset-uc [615.439156] Dynamic subtest mmap-offset-uc: SUCCESS (6.143s) [615.733570] Subtest memset-crc: SUCCESS (7.152s) [616.587973] [079/156] (412s left) kms_vblank (wait-busy) [616.689305] Starting subtest: wait-busy [616.692169] Starting dynamic subtest: pipe-A-eDP-1 [618.568097] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.878s) [618.588255] Starting dynamic subtest: pipe-D-eDP-1 [621.160102] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.592s) [621.162808] Subtest wait-busy: SUCCESS (4.474s) [621.971532] [080/156] (407s left) sw_sync (sync_busy) [622.013646] Starting subtest: sync_busy [622.015979] Subtest sync_busy: SUCCESS (0.002s) [622.068650] [081/156] (407s left) kms_big_fb (4-tiled-32bpp-rotate-270) [622.171246] Starting subtest: 4-tiled-32bpp-rotate-270 [622.191390] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s) [622.306434] [082/156] (407s left) kms_getfb (getfb-handle-valid) [622.397022] Starting subtest: getfb-handle-valid [622.399550] Subtest getfb-handle-valid: SUCCESS (0.000s) [622.503718] [083/156] (407s left) kms_psr (fbc-pr-sprite-render) [622.603140] Starting subtest: fbc-pr-sprite-render [622.607861] Subtest fbc-pr-sprite-render: SKIP (0.000s) [622.678763] [084/156] (407s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled) [622.730192] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled [622.838250] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.108s) [622.921134] [085/156] (406s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180) [623.013205] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180 [623.018152] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s) [623.117498] [086/156] (406s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-indfb-draw-blt) [623.215386] Starting subtest: fbchdr-1p-primscrn-pri-indfb-draw-blt [623.220752] Subtest fbchdr-1p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [623.310786] [087/156] (406s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip) [623.403922] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip [623.409177] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s) [623.501743] [088/156] (406s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu) [623.606811] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu [627.146779] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.548s) [628.384364] [089/156] (401s left) kms_flip (nonexisting-fb) [628.483712] Starting subtest: nonexisting-fb [628.486316] Starting dynamic subtest: A-eDP1 [629.661086] Dynamic subtest A-eDP1: SUCCESS (1.177s) [629.673634] Starting dynamic subtest: B-eDP1 [630.808724] Dynamic subtest B-eDP1: SUCCESS (1.146s) [630.821244] Starting dynamic subtest: C-eDP1 [631.934644] Dynamic subtest C-eDP1: SUCCESS (1.123s) [631.946248] Starting dynamic subtest: D-eDP1 [633.072038] Dynamic subtest D-eDP1: SUCCESS (1.136s) [633.074572] Subtest nonexisting-fb: SUCCESS (4.593s) [634.246069] [090/156] (395s left) kms_big_fb (linear-32bpp-rotate-90) [634.349155] Starting subtest: linear-32bpp-rotate-90 [634.368271] Subtest linear-32bpp-rotate-90: SKIP (0.019s) [634.495286] [091/156] (395s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-indfb-draw-blt) [634.586204] Starting subtest: fbchdr-1p-offscreen-pri-indfb-draw-blt [634.591029] Subtest fbchdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s) [634.683912] [092/156] (395s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt) [634.786367] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt [634.791326] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [634.880378] [093/156] (395s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc) [634.983364] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc [634.988145] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [635.066674] [094/156] (395s left) kms_frontbuffer_tracking (psrhdr-rgb101010-draw-pwrite) [635.163643] Starting subtest: psrhdr-rgb101010-draw-pwrite [635.167780] Subtest psrhdr-rgb101010-draw-pwrite: SKIP (0.000s) [635.249588] [095/156] (395s left) kms_plane_scaling (planes-downscale-factor-0-75) [635.330292] Starting subtest: planes-downscale-factor-0-75 [635.332761] Starting dynamic subtest: pipe-A [635.353354] Dynamic subtest pipe-A: SKIP (0.019s) [635.364821] Starting dynamic subtest: pipe-B [635.387990] Dynamic subtest pipe-B: SKIP (0.033s) [635.399041] Starting dynamic subtest: pipe-C [635.419507] Dynamic subtest pipe-C: SKIP (0.033s) [635.430293] Starting dynamic subtest: pipe-D [635.453407] Dynamic subtest pipe-D: SKIP (0.033s) [635.457174] Subtest planes-downscale-factor-0-75: SKIP (0.120s) [635.544726] [096/156] (394s left) gem_softpin (evict-active-interruptible) [635.617870] Starting subtest: evict-active-interruptible [635.624215] Subtest evict-active-interruptible: SUCCESS (0.007s) [635.741137] [097/156] (394s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite) [635.841863] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite [644.628040] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.791s) [644.744075] [098/156] (385s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf) [644.841176] Starting subtest: fbc-pr-cursor-plane-update-sf [644.846377] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.000s) [644.910738] [099/156] (385s left) kms_pm_rpm (package-g7) [646.734393] Starting subtest: package-g7 [646.740098] Subtest package-g7: SKIP (0.000s) [647.155613] [100/156] (383s left) kms_cursor_crc (cursor-tearing-framebuffer-change) [647.273094] Starting subtest: cursor-tearing-framebuffer-change [647.275238] Starting dynamic subtest: pipe-A-eDP-1 [647.849519] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.578s) [647.860891] Starting dynamic subtest: pipe-D-eDP-1 [649.082216] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.231s) [649.085113] Subtest cursor-tearing-framebuffer-change: SUCCESS (1.812s) [649.915797] [101/156] (380s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc) [650.025053] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc [653.593227] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SUCCESS (3.576s) [654.822115] [102/156] (375s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-pwrite) [654.919037] Starting subtest: fbchdr-rgb101010-draw-pwrite [654.924217] Subtest fbchdr-rgb101010-draw-pwrite: SKIP (0.000s) [655.010327] [103/156] (375s left) kms_flip (2x-plain-flip-fb-recreate-interruptible) [655.107668] Starting subtest: 2x-plain-flip-fb-recreate-interruptible [655.119733] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s) [655.232893] [104/156] (375s left) gem_exec_flush (basic-wb-prw-default) [655.295142] Starting subtest: basic-wb-prw-default [660.757297] Subtest basic-wb-prw-default: SUCCESS (5.461s) [660.946437] [105/156] (369s left) gem_mmap_wc (read) [661.004240] Subtest read: SKIP (0.000s) [661.066582] [106/156] (369s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt) [661.164877] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt [661.169592] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s) [661.269389] [107/156] (369s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-blt) [661.369832] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-blt [661.374484] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s) [661.461978] [108/156] (369s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render) [661.560785] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render [661.566161] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [661.642692] [109/156] (369s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-rte) [661.741119] Starting subtest: fbcpsrhdr-2p-rte [661.746294] Subtest fbcpsrhdr-2p-rte: SKIP (0.000s) [661.819224] [110/156] (369s left) gem_exec_reloc (basic-cpu-wc) [661.875014] Subtest basic-cpu-wc: SKIP (0.000s) [661.945871] [111/156] (369s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled) [661.995931] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled [661.998250] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s) [662.110392] [112/156] (368s left) i915_drm_fdinfo (virtual-busy) [662.149300] Starting subtest: virtual-busy [662.154242] Subtest virtual-busy: SKIP (0.000s) [662.224273] [113/156] (368s left) gem_mmap_gtt (cpuset-medium-copy-xy) [662.278658] Subtest cpuset-medium-copy-XY: SKIP (0.000s) [662.346690] [114/156] (368s left) kms_flip (nonblocking-read) [662.437477] Starting subtest: nonblocking-read [662.439358] Subtest nonblocking-read: SUCCESS (0.000s) [662.541598] [115/156] (368s left) kms_color (invalid-ctm-matrix-sizes) [662.625988] Starting subtest: invalid-ctm-matrix-sizes [662.627980] Starting dynamic subtest: pipe-A [662.629910] Dynamic subtest pipe-A: SUCCESS (0.002s) [662.641203] Starting dynamic subtest: pipe-B [662.643394] Dynamic subtest pipe-B: SUCCESS (0.003s) [662.654185] Starting dynamic subtest: pipe-C [662.656162] Dynamic subtest pipe-C: SUCCESS (0.003s) [662.667267] Starting dynamic subtest: pipe-D [662.669660] Dynamic subtest pipe-D: SUCCESS (0.004s) [662.671856] Subtest invalid-ctm-matrix-sizes: SUCCESS (0.014s) [662.715068] [116/156] (368s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt) [662.816920] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt [662.821663] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [662.910918] [117/156] (368s left) gem_workarounds (basic-read-fd) [662.982949] Starting subtest: basic-read-fd [662.992991] Subtest basic-read-fd: SUCCESS (0.013s) [663.113683] [118/156] (368s left) kms_pm_dc (dc5-retention-flops) [664.209283] Starting subtest: dc5-retention-flops [664.214760] Subtest dc5-retention-flops: SKIP (0.000s) [664.287954] [119/156] (367s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite) [664.395764] Starting subtest: fbcpsr-rgb101010-draw-pwrite [669.513965] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (5.124s) [669.616574] [120/156] (361s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt) [669.725186] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt [673.394406] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.676s) [674.611571] [121/156] (356s left) sysfs_heartbeat_interval (invalid) [674.667679] Starting subtest: invalid [674.670353] Starting dynamic subtest: vecs0 [674.673230] Dynamic subtest vecs0: SUCCESS (0.000s) [674.684816] Starting dynamic subtest: ccs0 [674.687144] Dynamic subtest ccs0: SUCCESS (0.000s) [674.698071] Starting dynamic subtest: vcs0 [674.700356] Dynamic subtest vcs0: SUCCESS (0.000s) [674.712607] Starting dynamic subtest: bcs0 [674.715138] Dynamic subtest bcs0: SUCCESS (0.000s) [674.726958] Starting dynamic subtest: rcs0 [674.729183] Dynamic subtest rcs0: SUCCESS (0.000s) [674.740399] Starting dynamic subtest: vcs1 [674.742769] Dynamic subtest vcs1: SUCCESS (0.000s) [674.744972] Subtest invalid: SUCCESS (0.005s) [674.785614] [122/156] (356s left) gem_render_copy (yf-tiled-to-vebox-linear) [674.842391] Starting subtest: yf-tiled-to-vebox-linear [674.847308] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s) [675.041184] [123/156] (356s left) gem_exec_balancer (bonded-chain) [675.103373] Starting subtest: bonded-chain [682.153695] Subtest bonded-chain: SUCCESS (7.048s) [682.577538] [124/156] (348s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-blt) [682.687353] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-blt [682.691863] Subtest psrhdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [682.773522] [125/156] (348s left) drm_mm (drm_mm) [682.812122] Starting subtest: drm_mm [682.873283] Starting dynamic subtest: drm_test_mm_init [682.946922] Dynamic subtest drm_test_mm_init: SUCCESS (0.075s) [682.960913] Starting dynamic subtest: drm_test_mm_debug [683.044686] Dynamic subtest drm_test_mm_debug: SUCCESS (0.098s) [683.058519] Starting dynamic subtest: drm_test_mm_align32 [683.129413] Dynamic subtest drm_test_mm_align32: SUCCESS (0.081s) [683.153178] Starting dynamic subtest: drm_test_mm_align64 [683.226283] Dynamic subtest drm_test_mm_align64: SUCCESS (0.097s) [683.240283] Starting dynamic subtest: drm_test_mm_lowest [683.344393] Dynamic subtest drm_test_mm_lowest: SUCCESS (0.118s) [683.362440] Starting dynamic subtest: drm_test_mm_highest [683.429384] Dynamic subtest drm_test_mm_highest: SUCCESS (0.085s) [683.432118] Subtest drm_mm: SUCCESS (0.619s) [683.593858] [126/156] (348s left) syncobj_timeline (wait-all-for-submit-delayed-submit) [683.648862] Starting subtest: wait-all-for-submit-delayed-submit [683.751706] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s) [683.839729] [127/156] (347s left) kms_content_protection (content-type-change) [683.978073] Starting subtest: content-type-change [683.983229] Subtest content-type-change: SKIP (0.000s) [684.099376] [128/156] (347s left) syncobj_wait (single-wait-submitted) [684.150212] Starting subtest: single-wait-submitted [684.253655] Subtest single-wait-submitted: SUCCESS (0.104s) [684.346610] [129/156] (347s left) kms_atomic (plane-invalid-params) [684.435239] Starting subtest: plane-invalid-params [684.437823] Starting dynamic subtest: pipe-A-eDP-1 [684.845948] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.410s) [684.848254] Subtest plane-invalid-params: SUCCESS (0.411s) [685.744343] [130/156] (346s left) gem_exec_schedule (smoketest) [685.908742] Starting subtest: smoketest [685.911055] Starting dynamic subtest: rcs0 [691.481816] Dynamic subtest rcs0: SUCCESS (5.503s) [691.494503] Starting dynamic subtest: bcs0 [697.023892] Dynamic subtest bcs0: SUCCESS (5.539s) [697.038944] Starting dynamic subtest: vcs0 [702.563059] Dynamic subtest vcs0: SUCCESS (5.536s) [702.575735] Starting dynamic subtest: vcs1 [708.099698] Dynamic subtest vcs1: SUCCESS (5.550s) [708.115037] Starting dynamic subtest: vecs0 [713.633159] Dynamic subtest vecs0: SUCCESS (5.520s) [713.648285] Starting dynamic subtest: ccs0 [719.153893] Dynamic subtest ccs0: SUCCESS (5.528s) [719.156383] Subtest smoketest: SUCCESS (33.186s) [719.336209] [131/156] (312s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt) [719.442191] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt [719.447278] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [719.543145] [132/156] (312s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt) [719.640377] Starting subtest: fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt [719.645410] Subtest fbcpsrhdr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [719.737437] [133/156] (312s left) gem_exec_balancer (parallel-ordering) [720.004305] Starting subtest: parallel-ordering [721.004898] Subtest parallel-ordering: SUCCESS (1.004s) [721.120577] [134/156] (310s left) syncobj_timeline (single-wait-all-available-submitted) [721.180221] Starting subtest: single-wait-all-available-submitted [721.182945] Subtest single-wait-all-available-submitted: SUCCESS (0.000s) [721.272576] [135/156] (310s left) kms_psr (psr-cursor-plane-onoff) [721.360869] Starting subtest: psr-cursor-plane-onoff [721.363029] Starting dynamic subtest: eDP-1 [723.120907] Dynamic subtest eDP-1: SUCCESS (1.758s) [723.123163] Subtest psr-cursor-plane-onoff: SUCCESS (1.759s) [723.979246] [136/156] (308s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-pwrite) [724.079275] Starting subtest: hdr-rgb101010-draw-pwrite [724.084137] Subtest hdr-rgb101010-draw-pwrite: SKIP (0.000s) [724.178986] [137/156] (307s left) kms_selftest (drm_plane_helper) [724.217386] Starting subtest: drm_plane_helper [724.263363] Starting dynamic subtest: drm_test_check_plane_state [724.360910] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.096s) [724.373044] Starting dynamic subtest: drm_test_check_invalid_plane_state [724.450207] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.090s) [724.452970] Subtest drm_plane_helper: SUCCESS (0.231s) [724.608644] [138/156] (307s left) gem_ctx_persistence (legacy-engines-hang) [724.663371] Starting subtest: legacy-engines-hang [724.666370] Starting dynamic subtest: render [724.720677] Dynamic subtest render: SUCCESS (0.057s) [724.734572] Starting dynamic subtest: bsd1 [724.813763] Dynamic subtest bsd1: SUCCESS (0.091s) [724.831902] Starting dynamic subtest: bsd2 [724.906231] Dynamic subtest bsd2: SUCCESS (0.089s) [724.920791] Starting dynamic subtest: blt [725.012396] Dynamic subtest blt: SUCCESS (0.103s) [725.028180] Starting dynamic subtest: vebox [725.107375] Dynamic subtest vebox: SUCCESS (0.092s) [725.110072] Subtest legacy-engines-hang: SUCCESS (0.446s) [725.188977] [139/156] (307s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc) [725.281120] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc [725.283830] Starting dynamic subtest: pipe-A-eDP-1 [725.432156] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.143s) [725.446204] Starting dynamic subtest: pipe-B-eDP-1 [725.464805] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.012s) [725.476097] Starting dynamic subtest: pipe-C-eDP-1 [725.494756] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.011s) [725.505122] Starting dynamic subtest: pipe-D-eDP-1 [725.524674] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.012s) [725.526676] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.183s) [727.743025] [140/156] (304s left) prime_mmap_coherency (read) [727.803014] Starting subtest: read [733.177698] Subtest read: SUCCESS (5.374s) [733.297345] [141/156] (299s left) kms_frontbuffer_tracking (fbc-2p-rte) [733.400126] Starting subtest: fbc-2p-rte [733.405222] Subtest fbc-2p-rte: SKIP (0.000s) [733.493751] [142/156] (298s left) perf_pmu (idle-no-semaphores) [733.532869] Starting subtest: idle-no-semaphores [733.535316] Starting dynamic subtest: rcs0 [734.081576] Dynamic subtest rcs0: SUCCESS (0.548s) [734.095736] Starting dynamic subtest: bcs0 [734.608889] Dynamic subtest bcs0: SUCCESS (0.524s) [734.624383] Starting dynamic subtest: vcs0 [735.136870] Dynamic subtest vcs0: SUCCESS (0.524s) [735.150780] Starting dynamic subtest: vcs1 [735.658921] Dynamic subtest vcs1: SUCCESS (0.519s) [735.673824] Starting dynamic subtest: vecs0 [736.177846] Dynamic subtest vecs0: SUCCESS (0.515s) [736.192186] Starting dynamic subtest: ccs0 [736.696422] Dynamic subtest ccs0: SUCCESS (0.516s) [736.699242] Subtest idle-no-semaphores: SUCCESS (3.166s) [736.833675] [143/156] (295s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-pwrite) [736.933204] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-pwrite [736.938205] Subtest hdr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [737.034804] [144/156] (295s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-move) [737.126942] Starting subtest: fbc-1p-primscrn-cur-indfb-move [741.195702] Subtest fbc-1p-primscrn-cur-indfb-move: SUCCESS (4.073s) [742.359159] [145/156] (290s left) perf_pmu (busy-double-start) [742.403428] Starting subtest: busy-double-start [742.406200] Starting dynamic subtest: rcs0 [746.056415] Dynamic subtest rcs0: FAIL (3.588s) [746.069404] Starting dynamic subtest: bcs0 [749.551225] Dynamic subtest bcs0: SUCCESS (3.561s) [749.564452] Starting dynamic subtest: vcs0 [754.143997] Dynamic subtest vcs0: SUCCESS (4.591s) [754.159407] Starting dynamic subtest: vcs1 [758.126821] Dynamic subtest vcs1: SUCCESS (3.980s) [758.143724] Starting dynamic subtest: vecs0 [762.144599] Dynamic subtest vecs0: SUCCESS (4.015s) [762.157431] Starting dynamic subtest: ccs0 [765.792096] Dynamic subtest ccs0: FAIL (3.568s) [765.794845] Subtest busy-double-start: FAIL (23.314s) [765.841724] [146/156] (266s left) kms_chamelium_frames (hdmi-crc-fast) [766.238607] Subtest hdmi-crc-fast: SKIP (0.000s) [767.073641] [147/156] (265s left) gem_exec_reloc (basic-wc-read-active) [767.137168] Subtest basic-wc-read-active: SKIP (0.000s) [767.202897] [148/156] (265s left) kms_cursor_legacy (cursora-vs-flipa-varying-size) [767.324033] Starting subtest: cursorA-vs-flipA-varying-size [778.477134] Subtest cursorA-vs-flipA-varying-size: SUCCESS (11.149s) [779.335967] [149/156] (253s left) gem_userptr_blits (mmap-offset-banned) [779.399550] Starting subtest: mmap-offset-banned [779.402226] Starting dynamic subtest: wb [779.404929] Dynamic subtest wb: SUCCESS (0.000s) [779.417225] Starting dynamic subtest: wc [779.420049] Dynamic subtest wc: SUCCESS (0.000s) [779.431201] Starting dynamic subtest: uc [779.433978] Dynamic subtest uc: SUCCESS (0.000s) [779.436429] Subtest mmap-offset-banned: SUCCESS (0.007s) [779.528674] [150/156] (253s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-onoff) [779.630129] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-onoff [779.635369] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-onoff: SKIP (0.000s) [779.744711] [151/156] (253s left) kms_atomic_transition (plane-all-modeset-transition-fencing-internal-panels) [779.834030] Starting subtest: plane-all-modeset-transition-fencing-internal-panels [779.836209] Starting dynamic subtest: pipe-A-eDP-1 [781.171571] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.337s) [781.451374] Starting dynamic subtest: pipe-B-eDP-1 [782.535186] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.095s) [782.805129] Subtest plane-all-modeset-transition-fencing-internal-panels: SUCCESS (2.971s) [783.624986] [152/156] (249s left) gen9_exec_parse (batch-without-end) [783.678131] Subtest batch-without-end: SKIP (0.000s) [783.746111] [153/156] (249s left) kms_frontbuffer_tracking (fbcpsrhdr-suspend) [783.843205] Starting subtest: fbcpsrhdr-suspend [783.848074] Subtest fbcpsrhdr-suspend: SKIP (0.000s) [783.934421] [154/156] (248s left) kms_flip_scaled_crc (flip-32bpp-yftileccs-to-64bpp-yftile-upscaling) [784.024139] Starting subtest: flip-32bpp-yftileccs-to-64bpp-yftile-upscaling [784.029129] Subtest flip-32bpp-yftileccs-to-64bpp-yftile-upscaling: SKIP (0.000s) [784.113232] [155/156] (248s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu) [784.209667] Starting subtest: fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu [784.214420] Subtest fbchdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [784.299106] [156/156] (248s left) kms_invalid_mode (bad-hsync-end) [784.392298] Starting subtest: bad-hsync-end [784.395103] Starting dynamic subtest: pipe-A-eDP-1 [784.688171] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.297s) [784.701202] Starting dynamic subtest: pipe-B-eDP-1 [784.703633] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [784.715104] Starting dynamic subtest: pipe-C-eDP-1 [784.717728] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s) [784.729142] Starting dynamic subtest: pipe-D-eDP-1 [784.731512] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s) [784.733971] Subtest bad-hsync-end: SUCCESS (0.303s) [785.578437] Closing watchdogs results: parsing output: 0/ for test: kms_frontbuffer_tracking results: parsing output: 1/ for test: kms_big_fb results: parsing output: 2/ for test: kms_ccs results: parsing output: 3/ for test: kms_plane_multiple results: parsing output: 4/ for test: sw_sync results: parsing output: 5/ for test: kms_pm_backlight results: parsing output: 6/ for test: perf results: parsing output: 7/ for test: gem_exec_fence results: parsing output: 8/ for test: gem_userptr_blits results: parsing output: 9/ for test: api_intel_bb results: parsing output: 10/ for test: fbdev results: parsing output: 11/ for test: kms_psr results: parsing output: 12/ for test: gem_partial_pwrite_pread results: parsing output: 13/ for test: kms_invalid_mode results: parsing output: 14/ for test: kms_frontbuffer_tracking results: parsing output: 15/ for test: kms_frontbuffer_tracking results: parsing output: 16/ for test: i915_suspend results: parsing output: 17/ for test: gem_userptr_blits results: parsing output: 18/ for test: gem_pxp results: parsing output: 19/ for test: kms_cursor_crc results: parsing output: 20/ for test: kms_cursor_crc results: parsing output: 21/ for test: kms_ccs results: parsing output: 22/ for test: prime_busy results: parsing output: 23/ for test: gem_exec_balancer results: parsing output: 24/ for test: kms_frontbuffer_tracking results: parsing output: 25/ for test: kms_frontbuffer_tracking results: parsing output: 26/ for test: kms_frontbuffer_tracking results: parsing output: 27/ for test: gem_gpgpu_fill results: parsing output: 28/ for test: kms_dirtyfb results: parsing output: 29/ for test: kms_plane_alpha_blend results: parsing output: 30/ for test: gem_exec_schedule results: parsing output: 31/ for test: kms_frontbuffer_tracking results: parsing output: 32/ for test: gem_mmap_offset results: parsing output: 33/ for test: gem_exec_fence results: parsing output: 34/ for test: kms_rotation_crc results: parsing output: 35/ for test: kms_frontbuffer_tracking results: parsing output: 36/ for test: gem_exec_params results: parsing output: 37/ for test: kms_frontbuffer_tracking results: parsing output: 38/ for test: gem_userptr_blits results: parsing output: 39/ for test: kms_flip results: parsing output: 40/ for test: i915_pm_freq_api results: parsing output: 41/ for test: gem_exec_fence results: parsing output: 42/ for test: kms_plane results: parsing output: 43/ for test: syncobj_timeline results: parsing output: 44/ for test: kms_frontbuffer_tracking results: parsing output: 45/ for test: vgem_basic results: parsing output: 46/ for test: kms_flip results: parsing output: 47/ for test: kms_frontbuffer_tracking results: parsing output: 48/ for test: gem_ctx_isolation results: parsing output: 49/ for test: api_intel_bb results: parsing output: 50/ for test: kms_ccs results: parsing output: 51/ for test: kms_frontbuffer_tracking results: parsing output: 52/ for test: kms_flip results: parsing output: 53/ for test: kms_lease results: parsing output: 54/ for test: gem_ctx_engines results: parsing output: 55/ for test: kms_flip results: parsing output: 56/ for test: gem_madvise results: parsing output: 57/ for test: gem_exec_schedule results: parsing output: 58/ for test: kms_flip_scaled_crc results: parsing output: 59/ for test: i915_pm_rc6_residency results: parsing output: 60/ for test: tools_test results: parsing output: 61/ for test: kms_psr results: parsing output: 62/ for test: kms_psr2_sf results: parsing output: 63/ for test: syncobj_wait results: parsing output: 64/ for test: kms_big_fb results: parsing output: 65/ for test: kms_scaling_modes results: parsing output: 66/ for test: kms_psr results: parsing output: 67/ for test: kms_big_fb results: parsing output: 68/ for test: kms_frontbuffer_tracking results: parsing output: 69/ for test: kms_frontbuffer_tracking results: parsing output: 70/ for test: gem_mmap_gtt results: parsing output: 71/ for test: kms_async_flips results: parsing output: 72/ for test: kms_ccs results: parsing output: 73/ for test: gem_softpin results: parsing output: 74/ for test: kms_frontbuffer_tracking results: parsing output: 75/ for test: gem_mmap_wc results: parsing output: 76/ for test: kms_chamelium_hpd results: parsing output: 77/ for test: kms_fb_coherency results: parsing output: 78/ for test: kms_vblank results: parsing output: 79/ for test: sw_sync results: parsing output: 80/ for test: kms_big_fb results: parsing output: 81/ for test: kms_getfb results: parsing output: 82/ for test: kms_psr results: parsing output: 83/ for test: syncobj_timeline results: parsing output: 84/ for test: kms_big_fb results: parsing output: 85/ for test: kms_frontbuffer_tracking results: parsing output: 86/ for test: kms_big_fb results: parsing output: 87/ for test: kms_frontbuffer_tracking results: parsing output: 88/ for test: kms_flip results: parsing output: 89/ for test: kms_big_fb results: parsing output: 90/ for test: kms_frontbuffer_tracking results: parsing output: 91/ for test: kms_frontbuffer_tracking results: parsing output: 92/ for test: kms_frontbuffer_tracking results: parsing output: 93/ for test: kms_frontbuffer_tracking results: parsing output: 94/ for test: kms_plane_scaling results: parsing output: 95/ for test: gem_softpin results: parsing output: 96/ for test: kms_frontbuffer_tracking results: parsing output: 97/ for test: kms_psr2_sf results: parsing output: 98/ for test: kms_pm_rpm results: parsing output: 99/ for test: kms_cursor_crc results: parsing output: 100/ for test: kms_frontbuffer_tracking results: parsing output: 101/ for test: kms_frontbuffer_tracking results: parsing output: 102/ for test: kms_flip results: parsing output: 103/ for test: gem_exec_flush results: parsing output: 104/ for test: gem_mmap_wc results: parsing output: 105/ for test: kms_frontbuffer_tracking results: parsing output: 106/ for test: kms_frontbuffer_tracking results: parsing output: 107/ for test: kms_frontbuffer_tracking results: parsing output: 108/ for test: kms_frontbuffer_tracking results: parsing output: 109/ for test: gem_exec_reloc results: parsing output: 110/ for test: syncobj_timeline results: parsing output: 111/ for test: i915_drm_fdinfo results: parsing output: 112/ for test: gem_mmap_gtt results: parsing output: 113/ for test: kms_flip results: parsing output: 114/ for test: kms_color results: parsing output: 115/ for test: kms_frontbuffer_tracking results: parsing output: 116/ for test: gem_workarounds results: parsing output: 117/ for test: kms_pm_dc results: parsing output: 118/ for test: kms_frontbuffer_tracking results: parsing output: 119/ for test: kms_frontbuffer_tracking results: parsing output: 120/ for test: sysfs_heartbeat_interval results: parsing output: 121/ for test: gem_render_copy results: parsing output: 122/ for test: gem_exec_balancer results: parsing output: 123/ for test: kms_frontbuffer_tracking results: parsing output: 124/ for test: drm_mm results: parsing output: 125/ for test: syncobj_timeline results: parsing output: 126/ for test: kms_content_protection results: parsing output: 127/ for test: syncobj_wait results: parsing output: 128/ for test: kms_atomic results: parsing output: 129/ for test: gem_exec_schedule results: parsing output: 130/ for test: kms_frontbuffer_tracking results: parsing output: 131/ for test: kms_frontbuffer_tracking results: parsing output: 132/ for test: gem_exec_balancer results: parsing output: 133/ for test: syncobj_timeline results: parsing output: 134/ for test: kms_psr results: parsing output: 135/ for test: kms_frontbuffer_tracking results: parsing output: 136/ for test: kms_selftest results: parsing output: 137/ for test: gem_ctx_persistence results: parsing output: 138/ for test: kms_ccs results: parsing output: 139/ for test: prime_mmap_coherency results: parsing output: 140/ for test: kms_frontbuffer_tracking results: parsing output: 141/ for test: perf_pmu results: parsing output: 142/ for test: kms_frontbuffer_tracking results: parsing output: 143/ for test: kms_frontbuffer_tracking results: parsing output: 144/ for test: perf_pmu results: parsing output: 145/ for test: kms_chamelium_frames results: parsing output: 146/ for test: gem_exec_reloc results: parsing output: 147/ for test: kms_cursor_legacy results: parsing output: 148/ for test: gem_userptr_blits results: parsing output: 149/ for test: kms_frontbuffer_tracking results: parsing output: 150/ for test: kms_atomic_transition results: parsing output: 151/ for test: gen9_exec_parse results: parsing output: 152/ for test: kms_frontbuffer_tracking results: parsing output: 153/ for test: kms_flip_scaled_crc results: parsing output: 154/ for test: kms_frontbuffer_tracking results: parsing output: 155/ for test: kms_invalid_mode Done.