[107.480835] Initializing watchdogs [107.480896] /dev/watchdog0 [107.500674] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770] [107.511105] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0 [107.515205] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [107.518169] [001/132] (960s left) gem_spin_batch (spin-each) [107.606080] Starting subtest: spin-each [111.032426] Subtest spin-each: SUCCESS (3.426s) [111.390487] [002/132] (956s left) kms_joiner (basic-ultra-joiner) [111.575651] Subtest basic-ultra-joiner: SKIP (0.000s) [111.727478] [003/132] (956s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-render) [111.917951] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-render [111.919772] Subtest psr-1p-offscreen-pri-shrfb-draw-render: SKIP (0.000s) [112.082698] [004/132] (956s left) gem_exec_schedule (preempt-queue) [112.168585] Subtest preempt-queue: SKIP (0.000s) [112.555836] [005/132] (955s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs) [112.735975] Starting subtest: ccs-on-another-bo-yf-tiled-ccs [112.736958] Starting dynamic subtest: pipe-A-DP-3 [112.745560] Dynamic subtest pipe-A-DP-3: SKIP (0.000s) [112.753653] Starting dynamic subtest: pipe-B-DP-3 [112.762190] Dynamic subtest pipe-B-DP-3: SKIP (0.000s) [112.770245] Starting dynamic subtest: pipe-C-DP-3 [112.778469] Dynamic subtest pipe-C-DP-3: SKIP (0.000s) [112.786170] Starting dynamic subtest: pipe-D-DP-3 [112.795920] Dynamic subtest pipe-D-DP-3: SKIP (0.000s) [112.798033] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.004s) [113.224203] [006/132] (955s left) syncobj_timeline (invalid-signal-bad-pad) [113.293183] Starting subtest: invalid-signal-bad-pad [113.294164] Subtest invalid-signal-bad-pad: SUCCESS (0.000s) [113.470742] [007/132] (955s left) gem_exec_store (dword) [113.549553] Starting subtest: dword [113.550479] Starting dynamic subtest: rcs0 [113.650584] Dynamic subtest rcs0: SUCCESS (0.101s) [113.661938] Starting dynamic subtest: bcs0 [113.694200] Dynamic subtest bcs0: SUCCESS (0.043s) [113.709659] Starting dynamic subtest: vcs0 [113.737728] Dynamic subtest vcs0: SUCCESS (0.042s) [113.771694] Starting dynamic subtest: vcs1 [113.788806] Dynamic subtest vcs1: SUCCESS (0.051s) [113.808493] Starting dynamic subtest: vecs0 [113.832767] Dynamic subtest vecs0: SUCCESS (0.043s) [113.848461] Starting dynamic subtest: vecs1 [113.876374] Dynamic subtest vecs1: SUCCESS (0.043s) [113.898217] Starting dynamic subtest: ccs0 [113.923457] Dynamic subtest ccs0: SUCCESS (0.046s) [113.924955] Subtest dword: SUCCESS (0.374s) [114.205590] [008/132] (954s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-plflip-blt) [114.390891] Starting subtest: psr-2p-primscrn-indfb-plflip-blt [114.392592] Subtest psr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s) [114.573020] [009/132] (954s left) kms_chamelium_hpd (vga-hpd-after-suspend) [114.841714] Subtest vga-hpd-after-suspend: SKIP (0.000s) [114.990372] [010/132] (953s left) gem_exec_reloc (basic-wc-gtt-noreloc) [115.067605] Subtest basic-wc-gtt-noreloc: SKIP (0.000s) [115.145507] [011/132] (953s left) kms_big_fb (x-tiled-16bpp-rotate-180) [115.341921] Starting subtest: x-tiled-16bpp-rotate-180 [117.174787] Subtest x-tiled-16bpp-rotate-180: SUCCESS (1.832s) [117.383539] [012/132] (951s left) api_intel_bb (blit-reloc-purge-cache) [117.453076] Starting subtest: blit-reloc-purge-cache [117.454896] Subtest blit-reloc-purge-cache: SKIP (0.001s) [117.609335] [013/132] (951s left) gen9_exec_parse (bb-start-param) [117.681162] Subtest bb-start-param: SKIP (0.000s) [117.743606] [014/132] (951s left) kms_flip (2x-flip-vs-suspend-interruptible) [117.928518] Starting subtest: 2x-flip-vs-suspend-interruptible [117.944055] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.015s) [118.113486] [015/132] (950s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render) [118.298915] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render [118.300567] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s) [118.484506] [016/132] (950s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted) [118.554984] Starting subtest: invalid-multi-wait-unsubmitted-submitted [118.556199] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s) [118.682988] [017/132] (950s left) kms_ccs (bad-aux-stride-y-tiled-ccs) [118.870258] Starting subtest: bad-aux-stride-y-tiled-ccs [118.871168] Starting dynamic subtest: pipe-A-DP-3 [118.879523] Dynamic subtest pipe-A-DP-3: SKIP (0.000s) [118.887850] Starting dynamic subtest: pipe-B-DP-3 [118.896380] Dynamic subtest pipe-B-DP-3: SKIP (0.000s) [118.904370] Starting dynamic subtest: pipe-C-DP-3 [118.912586] Dynamic subtest pipe-C-DP-3: SKIP (0.000s) [118.920478] Starting dynamic subtest: pipe-D-DP-3 [118.929244] Dynamic subtest pipe-D-DP-3: SKIP (0.000s) [118.930924] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.004s) [119.329055] [018/132] (949s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf) [119.520481] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (0.000s) [119.576925] [019/132] (949s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move) [119.762694] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move [119.764421] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s) [119.948770] [020/132] (949s left) gem_mmap_wc (write-read-distinct) [120.019082] Subtest write-read-distinct: SKIP (0.000s) [120.104463] [021/132] (949s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc) [120.288594] Starting subtest: psr-rgb565-draw-mmap-wc [120.290393] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.000s) [120.463079] [022/132] (948s left) gem_exec_params (invalid-fence-in-submit) [120.537380] Starting subtest: invalid-fence-in-submit [120.540462] Subtest invalid-fence-in-submit: SUCCESS (0.003s) [120.764833] [023/132] (948s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled) [120.835172] Starting subtest: etime-multi-wait-all-submitted-signaled [120.947236] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.105s) [121.080924] [024/132] (948s left) vgem_basic (setversion) [121.188786] Starting subtest: setversion [121.190774] Subtest setversion: SUCCESS (0.000s) [121.315911] [FACT vgem_basic (setversion)] new: kernel.kmod_is_loaded.vgem: true [121.320625] [025/132] (948s left) syncobj_wait (invalid-multi-wait-all-unsubmitted) [121.418237] Starting subtest: invalid-multi-wait-all-unsubmitted [121.428668] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s) [121.589821] [026/132] (948s left) kms_cursor_edge_walk (128x128-right-edge) [121.780510] Starting subtest: 128x128-right-edge [121.782522] Starting dynamic subtest: pipe-A-DP-3 [125.269207] Dynamic subtest pipe-A-DP-3: SUCCESS (3.487s) [125.280766] Starting dynamic subtest: pipe-D-DP-3 [128.600689] Dynamic subtest pipe-D-DP-3: SUCCESS (3.329s) [128.601752] Subtest 128x128-right-edge: SUCCESS (6.819s) [128.886896] [027/132] (940s left) prime_vgem (basic-fence-read) [128.979491] Starting subtest: basic-fence-read [128.985961] Subtest basic-fence-read: SKIP (0.005s) [129.191200] [028/132] (940s left) gem_ctx_engines (execute-one) [129.280433] Starting subtest: execute-one [132.649584] Subtest execute-one: SUCCESS (3.369s) [132.983551] [029/132] (936s left) gem_mmap (basic) [133.064152] Subtest basic: SKIP (0.000s) [133.142204] [030/132] (936s left) kms_lease (lease-invalid-crtc) [133.327636] Starting subtest: lease-invalid-crtc [133.328541] Starting dynamic subtest: pipe-A-DP-3 [133.329416] Dynamic subtest pipe-A-DP-3: SUCCESS (0.000s) [133.337743] Starting dynamic subtest: pipe-B-DP-3 [133.338717] Dynamic subtest pipe-B-DP-3: SUCCESS (0.000s) [133.346978] Starting dynamic subtest: pipe-C-DP-3 [133.348147] Dynamic subtest pipe-C-DP-3: SUCCESS (0.000s) [133.356584] Starting dynamic subtest: pipe-D-DP-3 [133.357477] Dynamic subtest pipe-D-DP-3: SUCCESS (0.000s) [133.358524] Subtest lease-invalid-crtc: SUCCESS (0.003s) [133.490612] [031/132] (936s left) kms_draw_crc (draw-method-mmap-gtt) [133.678069] Starting subtest: draw-method-mmap-gtt [133.679892] Subtest draw-method-mmap-gtt: SKIP (0.000s) [133.871531] [032/132] (936s left) kms_psr (fbc-psr-primary-mmap-cpu) [134.075941] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.000s) [134.132401] [033/132] (936s left) kms_flip (bo-too-big-interruptible) [134.325301] Starting subtest: bo-too-big-interruptible [134.329696] Starting dynamic subtest: A-DP3 [134.637902] Dynamic subtest A-DP3: SUCCESS (0.307s) [134.673664] Starting dynamic subtest: B-DP3 [134.881844] Dynamic subtest B-DP3: SUCCESS (0.242s) [134.909546] Starting dynamic subtest: C-DP3 [135.109984] Dynamic subtest C-DP3: SUCCESS (0.226s) [135.135359] Starting dynamic subtest: D-DP3 [135.344277] Dynamic subtest D-DP3: SUCCESS (0.233s) [135.348524] Subtest bo-too-big-interruptible: SUCCESS (1.023s) [135.762210] [034/132] (934s left) gem_softpin (evict-active-interruptible) [135.847056] Starting subtest: evict-active-interruptible [135.867361] Subtest evict-active-interruptible: SUCCESS (0.021s) [135.994575] [035/132] (934s left) kms_cursor_legacy (cursorb-vs-flipb-varying-size) [136.187052] Starting subtest: cursorB-vs-flipB-varying-size [136.188853] Subtest cursorB-vs-flipB-varying-size: SKIP (0.001s) [136.302016] [036/132] (934s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area) [136.496082] Subtest psr2-overlay-plane-update-sf-dmg-area: SKIP (0.000s) [136.548671] [037/132] (933s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-cpu) [136.748993] Starting subtest: psr-rgb101010-draw-mmap-cpu [136.750763] Subtest psr-rgb101010-draw-mmap-cpu: SKIP (0.000s) [136.906272] [038/132] (933s left) gem_exec_async (concurrent-writes) [136.990443] Starting subtest: concurrent-writes [136.991407] Starting dynamic subtest: rcs0 [137.033271] Dynamic subtest rcs0: SUCCESS (0.043s) [137.044096] Starting dynamic subtest: bcs0 [137.047052] Dynamic subtest bcs0: SUCCESS (0.014s) [137.055896] Starting dynamic subtest: vcs0 [137.058010] Dynamic subtest vcs0: SUCCESS (0.011s) [137.066865] Starting dynamic subtest: vcs1 [137.069202] Dynamic subtest vcs1: SUCCESS (0.011s) [137.077993] Starting dynamic subtest: vecs0 [137.080049] Dynamic subtest vecs0: SUCCESS (0.011s) [137.088694] Starting dynamic subtest: vecs1 [137.091188] Dynamic subtest vecs1: SUCCESS (0.011s) [137.099980] Starting dynamic subtest: ccs0 [137.102056] Dynamic subtest ccs0: SUCCESS (0.010s) [137.103068] Subtest concurrent-writes: SUCCESS (0.112s) [137.424194] [039/132] (933s left) kms_cursor_crc (cursor-rapid-movement-256x85) [137.856427] Starting subtest: cursor-rapid-movement-256x85 [137.860326] Starting dynamic subtest: pipe-A-DP-3 [138.335858] Dynamic subtest pipe-A-DP-3: SUCCESS (0.475s) [138.381254] Starting dynamic subtest: pipe-D-DP-3 [138.762887] Dynamic subtest pipe-D-DP-3: SUCCESS (0.390s) [138.763970] Subtest cursor-rapid-movement-256x85: SUCCESS (0.906s) [139.169317] [040/132] (931s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu) [139.364868] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu [141.451542] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-cpu: SUCCESS (2.088s) [141.644164] [041/132] (929s left) gem_exec_schedule (semaphore-noskip) [141.734107] Starting subtest: semaphore-noskip [142.600296] Subtest semaphore-noskip: SUCCESS (0.867s) [143.028382] [042/132] (927s left) gem_pread (snoop) [143.104039] Subtest snoop: SKIP (0.000s) [143.173271] [043/132] (927s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render) [143.368668] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render [143.370384] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s) [143.563267] [044/132] (927s left) gem_ccs (block-multicopy-compressed) [143.646703] Starting subtest: block-multicopy-compressed [143.647624] Starting dynamic subtest: linear-compressed-compfmt0-smem-lmem0-multicopy [143.692291] Dynamic subtest linear-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.045s) [143.701134] Starting dynamic subtest: linear-compressed-compfmt0-lmem0-lmem0-multicopy [143.782285] Dynamic subtest linear-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.090s) [143.790883] Starting dynamic subtest: xmajor-compressed-compfmt0-smem-lmem0-multicopy [143.799770] Dynamic subtest xmajor-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.017s) [143.808547] Starting dynamic subtest: xmajor-compressed-compfmt0-lmem0-lmem0-multicopy [143.893426] Dynamic subtest xmajor-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.093s) [143.902196] Starting dynamic subtest: tile4-compressed-compfmt0-smem-lmem0-multicopy [143.911379] Dynamic subtest tile4-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.017s) [143.920179] Starting dynamic subtest: tile4-compressed-compfmt0-lmem0-lmem0-multicopy [144.003719] Dynamic subtest tile4-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.092s) [144.012497] Starting dynamic subtest: tile64-compressed-compfmt0-smem-lmem0-multicopy [144.020419] Dynamic subtest tile64-compressed-compfmt0-smem-lmem0-multicopy: SUCCESS (0.016s) [144.029188] Starting dynamic subtest: tile64-compressed-compfmt0-lmem0-lmem0-multicopy [144.106886] Dynamic subtest tile64-compressed-compfmt0-lmem0-lmem0-multicopy: SUCCESS (0.086s) [144.107807] Subtest block-multicopy-compressed: SUCCESS (0.460s) [144.281418] [045/132] (926s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle) [144.471180] Starting subtest: short-busy-flip-before-cursor-toggle [144.473007] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s) [144.602106] [046/132] (926s left) gem_ctx_persistence (engines-cleanup) [144.685148] Starting subtest: engines-cleanup [144.686171] Starting dynamic subtest: rcs0 [144.821420] Dynamic subtest rcs0: SUCCESS (0.137s) [144.836828] Starting dynamic subtest: bcs0 [144.872151] Dynamic subtest bcs0: SUCCESS (0.048s) [144.883721] Starting dynamic subtest: vcs0 [144.919343] Dynamic subtest vcs0: SUCCESS (0.046s) [144.934613] Starting dynamic subtest: vcs1 [144.975286] Dynamic subtest vcs1: SUCCESS (0.055s) [145.011551] Starting dynamic subtest: vecs0 [145.024368] Dynamic subtest vecs0: SUCCESS (0.048s) [145.041573] Starting dynamic subtest: vecs1 [145.065397] Dynamic subtest vecs1: SUCCESS (0.040s) [145.078165] Starting dynamic subtest: ccs0 [145.082376] Dynamic subtest ccs0: SUCCESS (0.016s) [145.083488] Subtest engines-cleanup: SUCCESS (0.399s) [145.234355] [047/132] (925s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite) [145.429812] Starting subtest: fbcpsr-rgb101010-draw-pwrite [145.431444] Subtest fbcpsr-rgb101010-draw-pwrite: SKIP (0.000s) [145.604675] [048/132] (925s left) gem_pxp (create-regular-buffer) [148.205604] Starting subtest: create-regular-buffer [148.207487] Subtest create-regular-buffer: SKIP (0.000s) [148.343831] [049/132] (922s left) kms_plane_scaling (planes-scaler-unity-scaling) [148.532365] Starting subtest: planes-scaler-unity-scaling [148.533217] Starting dynamic subtest: pipe-A [148.615970] Dynamic subtest pipe-A: SUCCESS (0.083s) [148.627543] Starting dynamic subtest: pipe-B [148.830267] Dynamic subtest pipe-B: SUCCESS (0.214s) [148.846086] Starting dynamic subtest: pipe-C [149.047407] Dynamic subtest pipe-C: SUCCESS (0.217s) [149.061925] Starting dynamic subtest: pipe-D [149.263292] Dynamic subtest pipe-D: SUCCESS (0.215s) [149.264340] Subtest planes-scaler-unity-scaling: SUCCESS (0.731s) [149.591954] [050/132] (921s left) kms_psr (pr-primary-mmap-cpu) [149.781079] Subtest pr-primary-mmap-cpu: SKIP (0.000s) [149.838404] [051/132] (921s left) api_intel_bb (simple-bb-ctx) [149.919161] Starting subtest: simple-bb-ctx [149.952337] Subtest simple-bb-ctx: SUCCESS (0.034s) [150.176558] [052/132] (921s left) kms_vblank (wait-idle-hang) [150.368180] Starting subtest: wait-idle-hang [150.369161] Starting dynamic subtest: pipe-A-DP-3 [176.591239] Dynamic subtest pipe-A-DP-3: SUCCESS (26.224s) [176.611695] Starting dynamic subtest: pipe-D-DP-3 [201.560968] Dynamic subtest pipe-D-DP-3: SUCCESS (24.965s) [201.562147] Subtest wait-idle-hang: SUCCESS (51.197s) [201.822007] [053/132] (869s left) gem_pxp (reject-modify-context-protection-off-3) [204.427218] Starting subtest: reject-modify-context-protection-off-3 [204.430011] Subtest reject-modify-context-protection-off-3: SKIP (0.000s) [204.571426] [054/132] (866s left) kms_big_fb (y-tiled-64bpp-rotate-270) [204.772586] Starting subtest: y-tiled-64bpp-rotate-270 [204.774441] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s) [204.974087] [055/132] (866s left) gem_exec_fence (parallel) [205.174888] Starting subtest: parallel [205.175893] Starting dynamic subtest: rcs0 [207.328183] Dynamic subtest rcs0: SUCCESS (2.153s) [207.337167] Starting dynamic subtest: bcs0 [209.484207] Dynamic subtest bcs0: SUCCESS (2.156s) [209.493223] Starting dynamic subtest: vcs0 [211.636423] Dynamic subtest vcs0: SUCCESS (2.152s) [211.645489] Starting dynamic subtest: vcs1 [213.787226] Dynamic subtest vcs1: SUCCESS (2.150s) [213.796185] Starting dynamic subtest: vecs0 [215.941381] Dynamic subtest vecs0: SUCCESS (2.153s) [215.950540] Starting dynamic subtest: vecs1 [218.089387] Dynamic subtest vecs1: SUCCESS (2.148s) [218.098431] Starting dynamic subtest: ccs0 [220.244779] Dynamic subtest ccs0: SUCCESS (2.155s) [220.245634] Subtest parallel: SUCCESS (15.071s) [220.990280] [056/132] (850s left) kms_psr (fbc-pr-dpms) [221.185347] Subtest fbc-pr-dpms: SKIP (0.000s) [221.234449] [057/132] (850s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt) [221.437556] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt [221.439205] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [221.642782] [058/132] (850s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence) [221.832831] Starting subtest: nonblocking-crc-frame-sequence [221.835112] Starting dynamic subtest: pipe-A-DP-3 [222.568608] Dynamic subtest pipe-A-DP-3: SUCCESS (0.734s) [222.579956] Starting dynamic subtest: pipe-B-DP-3 [223.192282] Dynamic subtest pipe-B-DP-3: SUCCESS (0.622s) [223.206667] Starting dynamic subtest: pipe-C-DP-3 [223.857033] Dynamic subtest pipe-C-DP-3: SUCCESS (0.662s) [223.871266] Starting dynamic subtest: pipe-D-DP-3 [224.520043] Dynamic subtest pipe-D-DP-3: SUCCESS (0.661s) [224.521051] Subtest nonblocking-crc-frame-sequence: SUCCESS (2.686s) [224.837335] [059/132] (847s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs) [225.025434] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs [225.026388] Starting dynamic subtest: pipe-A-DP-3 [225.034171] Dynamic subtest pipe-A-DP-3: SUCCESS (0.002s) [225.043259] Starting dynamic subtest: pipe-B-DP-3 [225.051056] Dynamic subtest pipe-B-DP-3: SUCCESS (0.002s) [225.059914] Starting dynamic subtest: pipe-C-DP-3 [225.067618] Dynamic subtest pipe-C-DP-3: SUCCESS (0.002s) [225.076374] Starting dynamic subtest: pipe-D-DP-3 [225.083942] Dynamic subtest pipe-D-DP-3: SUCCESS (0.002s) [225.084815] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SUCCESS (0.014s) [225.489642] [060/132] (846s left) core_hotunplug (unbind-rebind) [225.623916] Starting subtest: unbind-rebind [229.330381] Subtest unbind-rebind: SUCCESS (3.707s) [229.377402] [061/132] (842s left) syncobj_wait (wait-all-snapshot) [229.508177] Starting subtest: wait-all-snapshot [229.592185] Subtest wait-all-snapshot: SUCCESS (0.084s) [229.780623] [062/132] (842s left) i915_drm_fdinfo (context-close-stress) [229.854234] Starting subtest: context-close-stress [240.600525] Subtest context-close-stress: SUCCESS (10.747s) [240.711644] [063/132] (831s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset) [241.282280] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset [241.297560] Subtest 2x-flip-vs-dpms-off-vs-modeset: SKIP (0.015s) [241.448684] [064/132] (830s left) gem_mmap_gtt (big-copy-xy) [241.530124] Subtest big-copy-XY: SKIP (0.000s) [241.608648] [065/132] (830s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite) [241.798849] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite [241.800447] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [241.974367] [066/132] (830s left) gem_wait (wait) [242.064684] Starting subtest: wait [242.065578] Starting dynamic subtest: all-engines [243.175474] Dynamic subtest all-engines: SUCCESS (1.110s) [243.202495] Starting dynamic subtest: rcs0 [244.232759] Dynamic subtest rcs0: SUCCESS (1.055s) [244.247607] Starting dynamic subtest: bcs0 [245.289667] Dynamic subtest bcs0: SUCCESS (1.054s) [245.307336] Starting dynamic subtest: vcs0 [246.349708] Dynamic subtest vcs0: SUCCESS (1.057s) [246.367485] Starting dynamic subtest: vcs1 [247.409525] Dynamic subtest vcs1: SUCCESS (1.057s) [247.426589] Starting dynamic subtest: vecs0 [248.469614] Dynamic subtest vecs0: SUCCESS (1.057s) [248.487139] Starting dynamic subtest: vecs1 [249.527743] Dynamic subtest vecs1: SUCCESS (1.055s) [249.545066] Starting dynamic subtest: ccs0 [250.588290] Dynamic subtest ccs0: SUCCESS (1.058s) [250.590068] Subtest wait: SUCCESS (8.525s) [250.893846] [067/132] (821s left) gem_lmem_swapping (smem-oom) [253.613532] Starting subtest: smem-oom [253.614671] Starting dynamic subtest: lmem0 [282.068117] Dynamic subtest lmem0: SUCCESS (28.456s) [282.069506] Subtest smem-oom: SUCCESS (28.457s) [283.233456] [FACT gem_lmem_swapping (smem-oom)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0 [283.235746] [FACT gem_lmem_swapping (smem-oom)] deleted: kernel.kmod_is_loaded.i915: true [283.238268] [068/132] (789s left) gem_exec_suspend (basic-s3) [284.991436] Starting subtest: basic-S3 [284.992297] Starting dynamic subtest: smem [307.610876] Dynamic subtest smem: SUCCESS (7.185s) [307.623569] Starting dynamic subtest: lmem0 [330.013465] Dynamic subtest lmem0: SUCCESS (7.156s) [330.014587] Subtest basic-S3: SUCCESS (14.343s) [330.235523] [FACT gem_exec_suspend (basic-s3)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0 [330.237819] [FACT gem_exec_suspend (basic-s3)] new: kernel.kmod_is_loaded.i915: true [330.240687] [069/132] (742s left) gem_exec_flush (basic-batch-kernel-default-cmd) [330.322630] Subtest basic-batch-kernel-default-cmd: SKIP (0.000s) [330.406886] [070/132] (742s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc) [330.615608] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc [330.617429] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s) [330.810700] [071/132] (741s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt) [331.007490] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt [331.009287] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [331.162569] [072/132] (741s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [331.360508] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu [331.362271] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [331.542813] [073/132] (741s left) kms_pm_dc (dc5-dpms-negative) [332.752516] Starting subtest: dc5-dpms-negative [336.981853] Subtest dc5-dpms-negative: SUCCESS (4.223s) [337.128216] [074/132] (735s left) kms_content_protection (atomic) [337.389836] Starting subtest: atomic [337.427164] Starting dynamic subtest: pipe-A-DP-3 [458.710541] Dynamic subtest pipe-A-DP-3: FAIL (121.122s) [458.712211] Subtest atomic: FAIL (121.265s) [458.970885] [075/132] (614s left) perf_pmu (busy-idle) [459.040621] Starting subtest: busy-idle [459.041587] Starting dynamic subtest: rcs0 [459.790995] Dynamic subtest rcs0: SUCCESS (0.749s) [459.809589] Starting dynamic subtest: bcs0 [460.460828] Dynamic subtest bcs0: SUCCESS (0.666s) [460.480304] Starting dynamic subtest: vcs0 [461.133061] Dynamic subtest vcs0: SUCCESS (0.669s) [461.152229] Starting dynamic subtest: vcs1 [461.799001] Dynamic subtest vcs1: SUCCESS (0.662s) [461.819860] Starting dynamic subtest: vecs0 [462.470999] Dynamic subtest vecs0: SUCCESS (0.668s) [462.491642] Starting dynamic subtest: vecs1 [463.142850] Dynamic subtest vecs1: SUCCESS (0.668s) [463.163650] Starting dynamic subtest: ccs0 [463.811833] Dynamic subtest ccs0: SUCCESS (0.665s) [463.813945] Subtest busy-idle: SUCCESS (4.772s) [464.006490] [076/132] (609s left) kms_addfb_basic (basic) [464.084860] Starting subtest: basic [464.085786] Subtest basic: SUCCESS (0.000s) [464.389171] [077/132] (608s left) kms_chamelium_edid (dp-edid-resolution-list) [464.663706] Subtest dp-edid-resolution-list: SKIP (0.000s) [464.842504] [078/132] (608s left) template (b) [464.918621] Starting subtest: B [464.919614] Subtest B: SUCCESS (0.000s) [465.097121] [079/132] (608s left) syncobj_wait (signal) [465.175726] Starting subtest: signal [465.176647] Subtest signal: SUCCESS (0.000s) [465.385695] [080/132] (607s left) kms_flip (wf_vblank-ts-check-interruptible) [465.587838] Starting subtest: wf_vblank-ts-check-interruptible [465.592644] Starting dynamic subtest: A-DP3 [473.703590] Dynamic subtest A-DP3: SUCCESS (8.111s) [473.720182] Starting dynamic subtest: B-DP3 [481.737487] Dynamic subtest B-DP3: SUCCESS (8.032s) [481.752369] Starting dynamic subtest: C-DP3 [489.768980] Dynamic subtest C-DP3: SUCCESS (8.030s) [489.785191] Starting dynamic subtest: D-DP3 [497.807327] Dynamic subtest D-DP3: SUCCESS (8.037s) [497.809545] Subtest wf_vblank-ts-check-interruptible: SUCCESS (32.221s) [498.173138] [081/132] (575s left) kms_cursor_crc (cursor-alpha-opaque) [498.366770] Starting subtest: cursor-alpha-opaque [498.367695] Starting dynamic subtest: pipe-A-DP-3 [498.968298] Dynamic subtest pipe-A-DP-3: SUCCESS (0.600s) [498.997847] Starting dynamic subtest: pipe-D-DP-3 [499.480408] Dynamic subtest pipe-D-DP-3: SUCCESS (0.508s) [499.481521] Subtest cursor-alpha-opaque: SUCCESS (1.115s) [500.125505] [082/132] (573s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size) [500.320671] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size [511.311538] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (10.992s) [511.634249] [083/132] (561s left) i915_pm_rpm (system-suspend-devices) [513.762488] Starting subtest: system-suspend-devices [521.438656] Subtest system-suspend-devices: SUCCESS (7.680s) [521.853156] [084/132] (551s left) prime_mmap (test_forked) [521.932335] Starting subtest: test_forked [521.933407] Starting dynamic subtest: test_forked-smem [521.986927] Dynamic subtest test_forked-smem: SUCCESS (0.054s) [522.002993] Starting dynamic subtest: test_forked-lmem0 [522.189051] Dynamic subtest test_forked-lmem0: SUCCESS (0.201s) [522.190121] Subtest test_forked: SUCCESS (0.257s) [522.340241] [085/132] (551s left) gem_mmap (basic-small-bo) [522.423573] Subtest basic-small-bo: SKIP (0.000s) [522.520915] [086/132] (551s left) syncobj_timeline (signal) [522.604845] Starting subtest: signal [522.605743] Subtest signal: SUCCESS (0.000s) [522.763305] [087/132] (551s left) syncobj_wait (invalid-single-wait-all-unsubmitted) [522.843813] Starting subtest: invalid-single-wait-all-unsubmitted [522.844971] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s) [522.994546] [088/132] (550s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-signaled) [523.073787] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-signaled [523.181561] Subtest etime-multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.106s) [523.326837] [089/132] (550s left) i915_selftest (live) [524.588272] Starting subtest: live [524.589405] Starting dynamic subtest: sanitycheck [526.304287] Dynamic subtest sanitycheck: SUCCESS (1.715s) [526.316212] Starting dynamic subtest: uncore [528.002409] Dynamic subtest uncore: SUCCESS (1.697s) [528.016592] Starting dynamic subtest: workarounds [544.690387] Dynamic subtest workarounds: FAIL (16.478s) [544.701232] Starting dynamic subtest: gt_engines [546.945554] Dynamic subtest gt_engines: SUCCESS (2.462s) [546.958028] Starting dynamic subtest: gt_timelines [566.666458] Dynamic subtest gt_timelines: SUCCESS (19.720s) [566.680049] Starting dynamic subtest: gt_contexts [568.379561] Dynamic subtest gt_contexts: SUCCESS (1.712s) [568.392015] Starting dynamic subtest: gt_lrc [574.827770] Dynamic subtest gt_lrc: SUCCESS (6.447s) [574.841707] Starting dynamic subtest: gt_mocs [577.791704] Dynamic subtest gt_mocs: SUCCESS (2.962s) [577.805592] Starting dynamic subtest: gt_pm [582.081377] Dynamic subtest gt_pm: SUCCESS (4.288s) [582.095421] Starting dynamic subtest: gt_heartbeat [583.773272] Dynamic subtest gt_heartbeat: SUCCESS (1.690s) [583.785709] Starting dynamic subtest: gt_tlb [596.515369] Dynamic subtest gt_tlb: SUCCESS (12.741s) [596.526989] Starting dynamic subtest: requests [610.123465] Dynamic subtest requests: SUCCESS (13.607s) [610.135836] Starting dynamic subtest: migrate [615.545221] Dynamic subtest migrate: SUCCESS (5.421s) [615.558984] Starting dynamic subtest: active [617.273350] Dynamic subtest active: SUCCESS (1.726s) [617.286016] Starting dynamic subtest: objects [619.683261] Dynamic subtest objects: SUCCESS (2.409s) [619.695728] Starting dynamic subtest: mman [626.442264] Dynamic subtest mman: SUCCESS (6.758s) [626.454801] Starting dynamic subtest: dmabuf [628.260433] Dynamic subtest dmabuf: SUCCESS (1.817s) [628.273492] Starting dynamic subtest: vma [629.960348] Dynamic subtest vma: SUCCESS (1.699s) [629.972973] Starting dynamic subtest: coherency [633.936272] Dynamic subtest coherency: SUCCESS (3.975s) [633.948950] Starting dynamic subtest: gtt [644.250270] Dynamic subtest gtt: SUCCESS (10.313s) [644.262249] Starting dynamic subtest: gem [646.181635] Dynamic subtest gem: SUCCESS (1.930s) [646.194513] Starting dynamic subtest: evict [649.265403] Dynamic subtest evict: SUCCESS (3.082s) [649.278309] Starting dynamic subtest: hugepages [668.012869] Dynamic subtest hugepages: SUCCESS (18.746s) [668.027258] Starting dynamic subtest: gem_contexts [700.870438] Dynamic subtest gem_contexts: SUCCESS (32.856s) [700.885947] Starting dynamic subtest: client [702.580739] Dynamic subtest client: SUCCESS (1.709s) [702.592901] Starting dynamic subtest: gem_migrate [706.295286] Dynamic subtest gem_migrate: SUCCESS (3.713s) [706.307943] Starting dynamic subtest: reset [708.040481] Dynamic subtest reset: SUCCESS (1.744s) [708.053664] Starting dynamic subtest: memory_region [711.330391] Dynamic subtest memory_region: SUCCESS (3.288s) [711.344983] Starting dynamic subtest: hangcheck [741.225593] Dynamic subtest hangcheck: SUCCESS (29.894s) [741.238167] Starting dynamic subtest: execlists [742.903464] Dynamic subtest execlists: SUCCESS (1.677s) [742.916118] Starting dynamic subtest: ring_submission [744.566614] Dynamic subtest ring_submission: SUCCESS (1.662s) [744.579520] Starting dynamic subtest: perf [746.357461] Dynamic subtest perf: SUCCESS (1.790s) [746.369896] Starting dynamic subtest: slpc [753.657322] Dynamic subtest slpc: SUCCESS (7.299s) [753.669802] Starting dynamic subtest: guc [756.721349] Dynamic subtest guc: SUCCESS (3.063s) [756.732904] Starting dynamic subtest: guc_multi_lrc [758.443562] Dynamic subtest guc_multi_lrc: SUCCESS (1.721s) [758.456139] Starting dynamic subtest: guc_hang [760.949447] Dynamic subtest guc_hang: SUCCESS (2.505s) [760.962108] Starting dynamic subtest: late_gt_pm [762.680243] Dynamic subtest late_gt_pm: SUCCESS (1.730s) [762.681139] Subtest live: FAIL (238.094s) [762.714336] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0 [762.715278] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true [762.717495] [090/132] (311s left) kms_atomic_transition (modeset-transition) [764.429683] Starting subtest: modeset-transition [764.430593] Starting dynamic subtest: 1x-outputs [767.060660] Dynamic subtest 1x-outputs: SUCCESS (2.630s) [767.066584] Subtest modeset-transition: SUCCESS (2.633s) [767.361638] [FACT kms_atomic_transition (modeset-transition)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0 [767.364191] [FACT kms_atomic_transition (modeset-transition)] new: kernel.kmod_is_loaded.i915: true [767.367097] [091/132] (306s left) syncobj_wait (wait-for-submit-complex) [767.445403] Starting subtest: wait-for-submit-complex [767.507125] Subtest wait-for-submit-complex: SUCCESS (0.062s) [767.668971] [092/132] (306s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0) [767.871743] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0 [769.098311] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.226s) [769.387133] [093/132] (304s left) syncobj_timeline (wait-all-for-submit-snapshot) [769.466711] Starting subtest: wait-all-for-submit-snapshot [769.550268] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s) [769.696880] [094/132] (304s left) gem_sync (basic-each) [769.793183] Starting subtest: basic-each [771.940771] Subtest basic-each: SUCCESS (2.149s) [772.245903] [095/132] (302s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip) [772.446813] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip [772.448634] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SKIP (0.000s) [772.653482] [096/132] (301s left) gem_mmap_offset (clear-via-pagefault) [772.740998] Starting subtest: clear-via-pagefault [772.743045] Starting dynamic subtest: lmem0 [797.184840] Dynamic subtest lmem0: SUCCESS (24.438s) [797.198940] Starting dynamic subtest: smem0 [822.875757] Dynamic subtest smem0: SUCCESS (25.693s) [822.876664] Subtest clear-via-pagefault: SUCCESS (50.133s) [823.257043] [097/132] (251s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen) [823.486737] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen [823.488460] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s) [823.667324] [098/132] (250s left) gem_partial_pwrite_pread (write-display) [823.749615] Subtest write-display: SKIP (0.000s) [823.863017] [099/132] (250s left) kms_big_fb (yf-tiled-16bpp-rotate-90) [824.078724] Starting subtest: yf-tiled-16bpp-rotate-90 [824.080572] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s) [824.278254] [100/132] (250s left) kms_ccs (crc-sprite-planes-basic-4-tiled-bmg-ccs) [824.466196] Starting subtest: crc-sprite-planes-basic-4-tiled-bmg-ccs [824.467936] Subtest crc-sprite-planes-basic-4-tiled-bmg-ccs: SKIP (0.000s) [824.795570] [101/132] (250s left) gem_ctx_param (invalid-size-set) [824.874182] Starting subtest: invalid-size-set [824.876575] Subtest invalid-size-set: SUCCESS (0.002s) [825.063651] [102/132] (249s left) kms_flip (dpms-off-confusion-interruptible) [825.258655] Starting subtest: dpms-off-confusion-interruptible [825.263539] Starting dynamic subtest: A-DP3 [833.094958] Dynamic subtest A-DP3: SUCCESS (7.831s) [833.117383] Starting dynamic subtest: B-DP3 [840.849342] Dynamic subtest B-DP3: SUCCESS (7.751s) [840.872173] Starting dynamic subtest: C-DP3 [848.595015] Dynamic subtest C-DP3: SUCCESS (7.740s) [848.613675] Starting dynamic subtest: D-DP3 [856.345786] Dynamic subtest D-DP3: SUCCESS (7.749s) [856.348103] Subtest dpms-off-confusion-interruptible: SUCCESS (31.088s) [856.683113] [103/132] (218s left) gem_mmap (short-mmap) [856.764624] Subtest short-mmap: SKIP (0.000s) [856.842219] [104/132] (218s left) kms_sequence (queue-busy) [857.031912] Starting subtest: queue-busy [857.034513] Starting dynamic subtest: pipe-A-DP-3 [859.618321] Dynamic subtest pipe-A-DP-3: SUCCESS (2.585s) [859.635871] Starting dynamic subtest: pipe-B-DP-3 [862.092530] Dynamic subtest pipe-B-DP-3: SUCCESS (2.471s) [862.112502] Starting dynamic subtest: pipe-C-DP-3 [864.569808] Dynamic subtest pipe-C-DP-3: SUCCESS (2.475s) [864.585772] Starting dynamic subtest: pipe-D-DP-3 [867.045339] Dynamic subtest pipe-D-DP-3: SUCCESS (2.474s) [867.046322] Subtest queue-busy: SUCCESS (10.013s) [867.360682] [105/132] (207s left) kms_frontbuffer_tracking (fbcpsr-stridechange) [867.557807] Starting subtest: fbcpsr-stridechange [867.559530] Subtest fbcpsr-stridechange: SKIP (0.000s) [867.758303] [106/132] (207s left) kms_big_fb (4-tiled-16bpp-rotate-270) [867.968539] Starting subtest: 4-tiled-16bpp-rotate-270 [868.037823] Subtest 4-tiled-16bpp-rotate-270: SKIP (0.068s) [868.243820] [107/132] (207s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite) [868.443772] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite [868.445481] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [868.647187] [108/132] (206s left) kms_psr (fbc-psr2-no-drrs) [868.840543] Subtest fbc-psr2-no-drrs: SKIP (0.000s) [868.901260] [109/132] (206s left) sysfs_timeslice_duration (timeout) [869.001526] Starting subtest: timeout [869.004205] Starting dynamic subtest: vecs0 [870.929733] Dynamic subtest vecs0: SUCCESS (1.927s) [870.944741] Starting dynamic subtest: ccs0 [872.707523] Dynamic subtest ccs0: SUCCESS (1.776s) [872.722670] Starting dynamic subtest: vcs0 [874.415661] Dynamic subtest vcs0: SUCCESS (1.707s) [874.430751] Starting dynamic subtest: bcs0 [875.489688] Dynamic subtest bcs0: SUCCESS (1.073s) [875.505557] Starting dynamic subtest: vecs1 [877.248553] Dynamic subtest vecs1: SUCCESS (1.758s) [877.263077] Starting dynamic subtest: rcs0 [878.943717] Dynamic subtest rcs0: SUCCESS (1.694s) [878.958189] Starting dynamic subtest: vcs1 [880.616646] Dynamic subtest vcs1: SUCCESS (1.671s) [880.617591] Subtest timeout: SUCCESS (11.615s) [880.805688] [110/132] (194s left) gem_render_copy (y-tiled-mc-ccs-to-yf-tiled-ccs) [880.892993] Starting subtest: y-tiled-mc-ccs-to-yf-tiled-ccs [880.894804] Subtest y-tiled-mc-ccs-to-yf-tiled-ccs: SKIP (0.000s) [881.161080] [111/132] (194s left) kms_psr (psr-primary-mmap-gtt) [881.351621] Subtest psr-primary-mmap-gtt: SKIP (0.000s) [881.443403] [112/132] (194s left) kms_addfb_basic (addfb25-y-tiled-small-legacy) [881.640632] Starting subtest: addfb25-y-tiled-small-legacy [881.642410] Subtest addfb25-y-tiled-small-legacy: SKIP (0.000s) [881.859750] [113/132] (193s left) kms_prime (basic-modeset-hybrid) [882.055545] Subtest basic-modeset-hybrid: SKIP (0.000s) [882.274902] [114/132] (193s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt) [882.472840] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt [882.474565] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s) [882.658217] [115/132] (193s left) gem_mmap_gtt (coherency) [882.739654] Subtest coherency: SKIP (0.000s) [882.818934] [116/132] (193s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-cpu) [883.017756] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-cpu [885.388771] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (2.371s) [885.635303] [117/132] (190s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format) [885.824287] Starting subtest: plane-scaler-unity-scaling-with-pixel-format [885.825225] Starting dynamic subtest: pipe-A [887.971183] Dynamic subtest pipe-A: SUCCESS (2.146s) [887.990518] Starting dynamic subtest: pipe-B [890.019685] Dynamic subtest pipe-B: SUCCESS (2.046s) [890.048221] Starting dynamic subtest: pipe-C [890.349541] Dynamic subtest pipe-C: SUCCESS (0.328s) [890.383348] Starting dynamic subtest: pipe-D [890.666022] Dynamic subtest pipe-D: SUCCESS (0.314s) [890.667479] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (4.843s) [891.053366] [118/132] (185s left) sriov_basic (enable-vfs-autoprobe-off) [891.132609] Subtest enable-vfs-autoprobe-off: SKIP (0.000s) [891.211418] [119/132] (185s left) kms_vblank (wait-forked) [891.403833] Starting subtest: wait-forked [891.404770] Starting dynamic subtest: pipe-A-DP-3 [893.770624] Dynamic subtest pipe-A-DP-3: SUCCESS (2.364s) [893.805391] Starting dynamic subtest: pipe-D-DP-3 [896.101871] Dynamic subtest pipe-D-DP-3: SUCCESS (2.320s) [896.102901] Subtest wait-forked: SUCCESS (4.700s) [896.404202] [120/132] (179s left) kms_chamelium_audio (dp-audio-edid) [896.678589] Subtest dp-audio-edid: SKIP (0.000s) [896.870424] [121/132] (179s left) gem_ctx_persistence (process) [896.954878] Starting subtest: process [897.168873] Subtest process: SUCCESS (0.213s) [897.369677] [122/132] (179s left) gem_lmem_swapping (heavy-multi) [900.076706] Starting subtest: heavy-multi [900.077676] Starting dynamic subtest: lmem0 [904.900635] Dynamic subtest lmem0: SUCCESS (4.824s) [904.901610] Subtest heavy-multi: SUCCESS (4.826s) [906.330917] [FACT gem_lmem_swapping (heavy-multi)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0 [906.331883] [FACT gem_lmem_swapping (heavy-multi)] deleted: kernel.kmod_is_loaded.i915: true [906.333908] [123/132] (170s left) syncobj_wait (single-wait-signaled) [907.905650] Starting subtest: single-wait-signaled [907.906470] Subtest single-wait-signaled: SUCCESS (0.000s) [907.986180] [FACT syncobj_wait (single-wait-signaled)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0 [907.988876] [FACT syncobj_wait (single-wait-signaled)] new: kernel.kmod_is_loaded.i915: true [907.991809] [124/132] (168s left) i915_pm_freq_api (freq-reset-multiple) [908.091732] Starting subtest: freq-reset-multiple [908.092689] Starting dynamic subtest: gt0 [924.511507] Dynamic subtest gt0: SUCCESS (16.418s) [924.512535] Subtest freq-reset-multiple: SUCCESS (16.419s) [924.626823] [125/132] (152s left) kms_plane_cursor (overlay) [924.815787] Starting subtest: overlay [924.817568] Starting dynamic subtest: pipe-A-DP-3-size-64 [928.323747] Dynamic subtest pipe-A-DP-3-size-64: SUCCESS (3.507s) [928.439787] Starting dynamic subtest: pipe-A-DP-3-size-128 [931.890765] Dynamic subtest pipe-A-DP-3-size-128: SUCCESS (3.497s) [931.976405] Starting dynamic subtest: pipe-A-DP-3-size-256 [935.441979] Dynamic subtest pipe-A-DP-3-size-256: SUCCESS (3.482s) [935.618812] Starting dynamic subtest: pipe-B-DP-3-size-64 [939.089550] Dynamic subtest pipe-B-DP-3-size-64: SUCCESS (3.488s) [939.154539] Starting dynamic subtest: pipe-B-DP-3-size-128 [942.556462] Dynamic subtest pipe-B-DP-3-size-128: SUCCESS (3.416s) [942.647769] Starting dynamic subtest: pipe-B-DP-3-size-256 [946.123479] Dynamic subtest pipe-B-DP-3-size-256: SUCCESS (3.515s) [946.273555] Starting dynamic subtest: pipe-C-DP-3-size-64 [949.923884] Dynamic subtest pipe-C-DP-3-size-64: SUCCESS (3.670s) [950.020957] Starting dynamic subtest: pipe-C-DP-3-size-128 [953.507414] Dynamic subtest pipe-C-DP-3-size-128: SUCCESS (3.531s) [953.602966] Starting dynamic subtest: pipe-C-DP-3-size-256 [957.024308] Dynamic subtest pipe-C-DP-3-size-256: SUCCESS (3.465s) [957.197714] Starting dynamic subtest: pipe-D-DP-3-size-64 [960.737198] Dynamic subtest pipe-D-DP-3-size-64: SUCCESS (3.563s) [960.803235] Starting dynamic subtest: pipe-D-DP-3-size-128 [964.337492] Dynamic subtest pipe-D-DP-3-size-128: SUCCESS (3.550s) [964.409831] Starting dynamic subtest: pipe-D-DP-3-size-256 [967.904312] Dynamic subtest pipe-D-DP-3-size-256: SUCCESS (3.516s) [968.016413] Subtest overlay: SUCCESS (43.200s) [968.357275] [126/132] (108s left) gem_exec_balancer (parallel-contexts) [968.754477] Starting subtest: parallel-contexts [975.458576] Subtest parallel-contexts: SUCCESS (6.708s) [975.642829] [127/132] (101s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling) [975.833784] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling [975.834688] Starting dynamic subtest: pipe-A-valid-mode [977.623049] Dynamic subtest pipe-A-valid-mode: SUCCESS (1.785s) [977.624393] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (1.788s) [977.931420] [128/132] ( 99s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render) [978.129769] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render [981.179428] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (3.050s) [981.451298] [129/132] ( 95s left) gem_exec_balancer (individual) [981.543487] Starting subtest: individual [983.587581] Subtest individual: SUCCESS (2.044s) [984.013897] [130/132] ( 93s left) perf (non-zero-reason) [984.469342] Starting subtest: non-zero-reason [984.470316] Starting dynamic subtest: 0-rcs0 [987.553914] Dynamic subtest 0-rcs0: FAIL (2.759s) [987.555477] Subtest non-zero-reason: FAIL (2.760s) [987.633738] [131/132] ( 89s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc) [987.823002] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc [987.825540] Starting dynamic subtest: pipe-A-DP-3 [991.232255] Dynamic subtest pipe-A-DP-3: SUCCESS (3.392s) [991.252836] Starting dynamic subtest: pipe-B-DP-3 [991.815427] Dynamic subtest pipe-B-DP-3: SUCCESS (0.575s) [991.849855] Starting dynamic subtest: pipe-C-DP-3 [992.414268] Dynamic subtest pipe-C-DP-3: SUCCESS (0.593s) [992.449963] Starting dynamic subtest: pipe-D-DP-3 [993.016168] Dynamic subtest pipe-D-DP-3: SUCCESS (0.593s) [993.018055] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SUCCESS (5.178s) [993.395530] [132/132] ( 83s left) kms_psr_stress_test (invalidate-primary-flip-overlay) [993.472609] Subtest invalidate-primary-flip-overlay: SKIP (0.000s) [993.556107] Closing watchdogs results: parsing output: 0/ for test: gem_spin_batch results: parsing output: 1/ for test: kms_joiner results: parsing output: 2/ for test: kms_frontbuffer_tracking results: parsing output: 3/ for test: gem_exec_schedule results: parsing output: 4/ for test: kms_ccs results: parsing output: 5/ for test: syncobj_timeline results: parsing output: 6/ for test: gem_exec_store results: parsing output: 7/ for test: kms_frontbuffer_tracking results: parsing output: 8/ for test: kms_chamelium_hpd results: parsing output: 9/ for test: gem_exec_reloc results: parsing output: 10/ for test: kms_big_fb results: parsing output: 11/ for test: api_intel_bb results: parsing output: 12/ for test: gen9_exec_parse results: parsing output: 13/ for test: kms_flip results: parsing output: 14/ for test: kms_frontbuffer_tracking results: parsing output: 15/ for test: syncobj_wait results: parsing output: 16/ for test: kms_ccs results: parsing output: 17/ for test: kms_psr2_sf results: parsing output: 18/ for test: kms_frontbuffer_tracking results: parsing output: 19/ for test: gem_mmap_wc results: parsing output: 20/ for test: kms_frontbuffer_tracking results: parsing output: 21/ for test: gem_exec_params results: parsing output: 22/ for test: syncobj_timeline results: parsing output: 23/ for test: vgem_basic results: parsing output: 24/ for test: syncobj_wait results: parsing output: 25/ for test: kms_cursor_edge_walk results: parsing output: 26/ for test: prime_vgem results: parsing output: 27/ for test: gem_ctx_engines results: parsing output: 28/ for test: gem_mmap results: parsing output: 29/ for test: kms_lease results: parsing output: 30/ for test: kms_draw_crc results: parsing output: 31/ for test: kms_psr results: parsing output: 32/ for test: kms_flip results: parsing output: 33/ for test: gem_softpin results: parsing output: 34/ for test: kms_cursor_legacy results: parsing output: 35/ for test: kms_psr2_sf results: parsing output: 36/ for test: kms_frontbuffer_tracking results: parsing output: 37/ for test: gem_exec_async results: parsing output: 38/ for test: kms_cursor_crc results: parsing output: 39/ for test: kms_frontbuffer_tracking results: parsing output: 40/ for test: gem_exec_schedule results: parsing output: 41/ for test: gem_pread results: parsing output: 42/ for test: kms_frontbuffer_tracking results: parsing output: 43/ for test: gem_ccs results: parsing output: 44/ for test: kms_cursor_legacy results: parsing output: 45/ for test: gem_ctx_persistence results: parsing output: 46/ for test: kms_frontbuffer_tracking results: parsing output: 47/ for test: gem_pxp results: parsing output: 48/ for test: kms_plane_scaling results: parsing output: 49/ for test: kms_psr results: parsing output: 50/ for test: api_intel_bb results: parsing output: 51/ for test: kms_vblank results: parsing output: 52/ for test: gem_pxp results: parsing output: 53/ for test: kms_big_fb results: parsing output: 54/ for test: gem_exec_fence results: parsing output: 55/ for test: kms_psr results: parsing output: 56/ for test: kms_frontbuffer_tracking results: parsing output: 57/ for test: kms_pipe_crc_basic results: parsing output: 58/ for test: kms_ccs results: parsing output: 59/ for test: core_hotunplug results: parsing output: 60/ for test: syncobj_wait results: parsing output: 61/ for test: i915_drm_fdinfo results: parsing output: 62/ for test: kms_flip results: parsing output: 63/ for test: gem_mmap_gtt results: parsing output: 64/ for test: kms_frontbuffer_tracking results: parsing output: 65/ for test: gem_wait results: parsing output: 66/ for test: gem_lmem_swapping results: parsing output: 67/ for test: gem_exec_suspend results: parsing output: 68/ for test: gem_exec_flush results: parsing output: 69/ for test: kms_frontbuffer_tracking results: parsing output: 70/ for test: kms_frontbuffer_tracking results: parsing output: 71/ for test: kms_frontbuffer_tracking results: parsing output: 72/ for test: kms_pm_dc results: parsing output: 73/ for test: kms_content_protection results: parsing output: 74/ for test: perf_pmu results: parsing output: 75/ for test: kms_addfb_basic results: parsing output: 76/ for test: kms_chamelium_edid results: parsing output: 77/ for test: template results: parsing output: 78/ for test: syncobj_wait results: parsing output: 79/ for test: kms_flip results: parsing output: 80/ for test: kms_cursor_crc results: parsing output: 81/ for test: kms_cursor_legacy results: parsing output: 82/ for test: i915_pm_rpm results: parsing output: 83/ for test: prime_mmap results: parsing output: 84/ for test: gem_mmap results: parsing output: 85/ for test: syncobj_timeline results: parsing output: 86/ for test: syncobj_wait results: parsing output: 87/ for test: syncobj_timeline results: parsing output: 88/ for test: i915_selftest results: parsing output: 89/ for test: kms_atomic_transition results: parsing output: 90/ for test: syncobj_wait results: parsing output: 91/ for test: kms_big_fb results: parsing output: 92/ for test: syncobj_timeline results: parsing output: 93/ for test: gem_sync results: parsing output: 94/ for test: kms_big_fb results: parsing output: 95/ for test: gem_mmap_offset results: parsing output: 96/ for test: kms_frontbuffer_tracking results: parsing output: 97/ for test: gem_partial_pwrite_pread results: parsing output: 98/ for test: kms_big_fb results: parsing output: 99/ for test: kms_ccs results: parsing output: 100/ for test: gem_ctx_param results: parsing output: 101/ for test: kms_flip results: parsing output: 102/ for test: gem_mmap results: parsing output: 103/ for test: kms_sequence results: parsing output: 104/ for test: kms_frontbuffer_tracking results: parsing output: 105/ for test: kms_big_fb results: parsing output: 106/ for test: kms_frontbuffer_tracking results: parsing output: 107/ for test: kms_psr results: parsing output: 108/ for test: sysfs_timeslice_duration results: parsing output: 109/ for test: gem_render_copy results: parsing output: 110/ for test: kms_psr results: parsing output: 111/ for test: kms_addfb_basic results: parsing output: 112/ for test: kms_prime results: parsing output: 113/ for test: kms_frontbuffer_tracking results: parsing output: 114/ for test: gem_mmap_gtt results: parsing output: 115/ for test: kms_frontbuffer_tracking results: parsing output: 116/ for test: kms_plane_scaling results: parsing output: 117/ for test: sriov_basic results: parsing output: 118/ for test: kms_vblank results: parsing output: 119/ for test: kms_chamelium_audio results: parsing output: 120/ for test: gem_ctx_persistence results: parsing output: 121/ for test: gem_lmem_swapping results: parsing output: 122/ for test: syncobj_wait results: parsing output: 123/ for test: i915_pm_freq_api results: parsing output: 124/ for test: kms_plane_cursor results: parsing output: 125/ for test: gem_exec_balancer results: parsing output: 126/ for test: kms_flip_scaled_crc results: parsing output: 127/ for test: kms_frontbuffer_tracking results: parsing output: 128/ for test: gem_exec_balancer results: parsing output: 129/ for test: perf results: parsing output: 130/ for test: kms_ccs results: parsing output: 131/ for test: kms_psr_stress_test Done.