[57.567422] Initializing watchdogs
[57.567466]   /dev/watchdog0
[57.582342] [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]
[57.586981] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.589676] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.593121] [001/154] (960s left) kms_chamelium_frames (dp-crc-single)
[58.024885] Subtest dp-crc-single: SKIP (0.000s)
[58.822070] [002/154] (959s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[58.929519] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[58.933795] Subtest hdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[59.022819] [003/154] (959s left) kms_big_fb (y-tiled-64bpp-rotate-270)
[59.143033] Starting subtest: y-tiled-64bpp-rotate-270
[59.147393] Subtest y-tiled-64bpp-rotate-270: SKIP (0.000s)
[59.243952] [004/154] (958s left) kms_lease (lease-get)
[59.331090] Starting subtest: lease-get
[59.333509] Starting dynamic subtest: pipe-A-eDP-1
[59.335957] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[59.345724] Starting dynamic subtest: pipe-B-eDP-1
[59.347646] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[59.354444] Starting dynamic subtest: pipe-C-eDP-1
[59.356342] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[59.363308] Starting dynamic subtest: pipe-D-eDP-1
[59.365220] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[59.367083] Subtest lease-get: SUCCESS (0.007s)
[59.442313] [005/154] (958s left) kms_atomic_transition (plane-toggle-modeset-transition)
[59.539882] Starting subtest: plane-toggle-modeset-transition
[59.542123] Starting dynamic subtest: pipe-A-eDP-1
[72.321891] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.782s)
[72.334865] Starting dynamic subtest: pipe-B-eDP-1
[84.794936] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.469s)
[84.797603] Subtest plane-toggle-modeset-transition: SUCCESS (25.259s)
[85.620223] [006/154] (932s left) kms_async_flips (async-flip-dpms)
[85.739218] Starting subtest: async-flip-dpms
[86.172903] Starting dynamic subtest: pipe-A-eDP-1
[90.258659] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.087s)
[90.626457] Starting dynamic subtest: pipe-B-eDP-1
[93.511227] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.890s)
[93.889840] Starting dynamic subtest: pipe-C-eDP-1
[96.680859] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.797s)
[97.064054] Starting dynamic subtest: pipe-D-eDP-1
[99.933776] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.878s)
[99.936324] Subtest async-flip-dpms: SUCCESS (14.196s)
[101.123172] [007/154] (917s left) gem_exec_suspend (basic-s3)
[101.194809] Starting subtest: basic-S3
[101.197185] Starting dynamic subtest: smem
[121.639558] Dynamic subtest smem: SUCCESS (7.097s)
[121.643002] Subtest basic-S3: SUCCESS (7.098s)
[121.820330] [008/154] (896s left) gem_create (create-clear)
[121.885176] Starting subtest: create-clear
[121.888351] Starting dynamic subtest: smem0
[154.454659] Dynamic subtest smem0: SUCCESS (32.561s)
[154.456634] Subtest create-clear: SUCCESS (32.561s)
[154.647084] [009/154] (863s left) gem_mmap (bad-offset)
[154.715212] Subtest bad-offset: SKIP (0.000s)
[154.781934] [010/154] (863s left) kms_cursor_edge_walk (128x128-top-edge)
[154.893915] Starting subtest: 128x128-top-edge
[154.896389] Starting dynamic subtest: pipe-A-eDP-1
[158.341487] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.448s)
[158.351272] Starting dynamic subtest: pipe-D-eDP-1
[161.544202] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.202s)
[161.546336] Subtest 128x128-top-edge: SUCCESS (6.652s)
[162.355477] [011/154] (856s left) gem_exec_store (dword)
[162.417087] Starting subtest: dword
[162.419063] Starting dynamic subtest: rcs0
[162.495644] Dynamic subtest rcs0: SUCCESS (0.080s)
[162.510458] Starting dynamic subtest: bcs0
[162.537006] Dynamic subtest bcs0: SUCCESS (0.041s)
[162.546834] Starting dynamic subtest: vcs0
[162.578471] Dynamic subtest vcs0: SUCCESS (0.041s)
[162.587315] Starting dynamic subtest: vcs1
[162.621124] Dynamic subtest vcs1: SUCCESS (0.041s)
[162.630424] Starting dynamic subtest: vecs0
[162.662093] Dynamic subtest vecs0: SUCCESS (0.040s)
[162.673534] Starting dynamic subtest: ccs0
[162.705287] Dynamic subtest ccs0: SUCCESS (0.042s)
[162.707890] Subtest dword: SUCCESS (0.291s)
[162.898743] [012/154] (855s left) gem_exec_fence (syncobj-timeline-multiple-ext-nodes)
[163.513136] Starting subtest: syncobj-timeline-multiple-ext-nodes
[163.515635] Subtest syncobj-timeline-multiple-ext-nodes: SUCCESS (0.004s)
[164.722668] [FACT gem_exec_fence (syncobj-timeline-multiple-ext-nodes)] new: kernel.kmod_is_loaded.vgem: true
[164.725766] [013/154] (853s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[164.821712] Starting subtest: fbchdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[164.825743] Subtest fbchdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[164.920617] [014/154] (853s left) kms_color (gamma)
[165.011870] Starting subtest: gamma
[165.014165] Starting dynamic subtest: pipe-A-eDP-1
[165.727158] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.712s)
[165.737412] Starting dynamic subtest: pipe-B-eDP-1
[167.171980] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.445s)
[167.182784] Starting dynamic subtest: pipe-C-eDP-1
[168.545344] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.373s)
[168.555725] Starting dynamic subtest: pipe-D-eDP-1
[169.880392] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.334s)
[169.882416] Subtest gamma: SUCCESS (4.866s)
[170.687391] [015/154] (847s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[170.791611] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[170.795811] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[170.902701] [016/154] (847s left) gem_render_tiled_blits (basic)
[170.955311] Subtest basic: SKIP (0.000s)
[171.032935] [017/154] (847s left) kms_content_protection (dp-mst-type-0-suspend-resume)
[171.164198] Starting subtest: dp-mst-type-0-suspend-resume
[171.168872] Subtest dp-mst-type-0-suspend-resume: SKIP (0.000s)
[171.310642] [018/154] (847s left) kms_psr (pr-sprite-blt)
[171.395740] Starting subtest: pr-sprite-blt
[171.399864] Subtest pr-sprite-blt: SKIP (0.000s)
[171.481836] [019/154] (847s left) kms_frontbuffer_tracking (hdr-2p-pri-indfb-multidraw)
[171.579867] Starting subtest: hdr-2p-pri-indfb-multidraw
[171.583861] Subtest hdr-2p-pri-indfb-multidraw: SKIP (0.000s)
[171.654626] [020/154] (847s left) gem_mmap_offset (clear)
[171.710732] Starting subtest: clear
[171.712702] Starting dynamic subtest: smem0
[197.127982] Dynamic subtest smem0: SUCCESS (25.411s)
[197.130020] Subtest clear: SUCCESS (25.413s)
[197.262844] [021/154] (821s left) kms_pm_backlight (fade)
[197.403974] Starting subtest: fade
[197.483860] Starting dynamic subtest: eDP-1
[200.483589] Dynamic subtest eDP-1: SUCCESS (2.997s)
[200.486057] Subtest fade: SUCCESS (3.079s)
[201.311747] [022/154] (817s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-shrfb-plflip-blt)
[201.417648] Starting subtest: fbcpsrhdr-1p-primscrn-shrfb-plflip-blt
[201.422140] Subtest fbcpsrhdr-1p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[201.519600] [023/154] (817s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb565-draw-pwrite)
[201.615468] Starting subtest: fbcpsrhdr-rgb565-draw-pwrite
[201.619928] Subtest fbcpsrhdr-rgb565-draw-pwrite: SKIP (0.000s)
[201.709435] [024/154] (817s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format)
[201.807057] Starting subtest: plane-scaler-unity-scaling-with-pixel-format
[201.809205] Starting dynamic subtest: pipe-A
[202.911218] Dynamic subtest pipe-A: SUCCESS (1.106s)
[202.922030] Starting dynamic subtest: pipe-B
[205.104056] Dynamic subtest pipe-B: SUCCESS (2.192s)
[205.114264] Starting dynamic subtest: pipe-C
[206.310074] Dynamic subtest pipe-C: SUCCESS (1.205s)
[206.321061] Starting dynamic subtest: pipe-D
[207.534102] Dynamic subtest pipe-D: SUCCESS (1.223s)
[207.536565] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (5.729s)
[208.734936] [025/154] (810s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-move)
[208.844746] Starting subtest: psrhdr-2p-primscrn-cur-indfb-move
[208.849005] Subtest psrhdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[208.952505] [026/154] (810s left) gem_request_retire (retire-vma-not-inactive)
[209.019096] Starting subtest: retire-vma-not-inactive
[209.078752] Subtest retire-vma-not-inactive: SUCCESS (0.057s)
[209.208997] [027/154] (809s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-render)
[209.310278] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-render
[209.314394] Subtest psrhdr-1p-offscreen-pri-indfb-draw-render: SKIP (0.000s)
[209.401929] [028/154] (809s left) kms_plane_multiple (2x-tiling-none)
[209.496485] Starting subtest: 2x-tiling-none
[209.500770] Subtest 2x-tiling-none: SKIP (0.000s)
[209.581663] [029/154] (809s left) kms_frontbuffer_tracking (psrhdr-farfromfence-mmap-gtt)
[209.690414] Starting subtest: psrhdr-farfromfence-mmap-gtt
[209.693862] Subtest psrhdr-farfromfence-mmap-gtt: SKIP (0.000s)
[209.797195] [030/154] (809s left) gem_mmap_wc (pf-nonblock)
[209.857137] Subtest pf-nonblock: SKIP (0.000s)
[209.924366] [031/154] (809s left) kms_big_fb (4-tiled-addfb)
[210.043062] Starting subtest: 4-tiled-addfb
[210.044962] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[210.160497] [032/154] (809s left) kms_feature_discovery (display-2x)
[210.259988] Starting subtest: display-2x
[210.263445] Subtest display-2x: SKIP (0.000s)
[210.345955] [033/154] (809s left) kms_vblank (ts-continuation-idle)
[210.442834] Starting subtest: ts-continuation-idle
[210.444365] Starting dynamic subtest: pipe-A-eDP-1
[210.842049] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.399s)
[210.857024] Starting dynamic subtest: pipe-D-eDP-1
[211.928546] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.084s)
[211.930932] Subtest ts-continuation-idle: SUCCESS (1.487s)
[212.801800] [034/154] (806s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-blt)
[212.905154] Starting subtest: fbchdr-rgb101010-draw-blt
[212.909276] Subtest fbchdr-rgb101010-draw-blt: SKIP (0.000s)
[213.004044] [035/154] (806s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[213.071190] Subtest no-spare-fences-busy: SKIP (0.000s)
[213.147965] [036/154] (806s left) kms_flip (nonexisting-fb)
[213.243632] Starting subtest: nonexisting-fb
[213.245949] Starting dynamic subtest: A-eDP1
[214.388418] Dynamic subtest A-eDP1: SUCCESS (1.147s)
[214.400296] Starting dynamic subtest: B-eDP1
[215.533201] Dynamic subtest B-eDP1: SUCCESS (1.144s)
[215.544264] Starting dynamic subtest: C-eDP1
[216.677502] Dynamic subtest C-eDP1: SUCCESS (1.144s)
[216.687682] Starting dynamic subtest: D-eDP1
[217.820494] Dynamic subtest D-eDP1: SUCCESS (1.140s)
[217.823504] Subtest nonexisting-fb: SUCCESS (4.583s)
[219.002412] [037/154] (800s left) vgem_basic (busy-fence)
[219.053135] Starting subtest: busy-fence
[219.055483] Subtest busy-fence: SUCCESS (0.000s)
[219.113679] [038/154] (800s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[219.217658] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[219.221953] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[219.343881] [039/154] (800s left) gem_ctx_param (invalid-ctx-set)
[219.407467] Starting subtest: invalid-ctx-set
[219.409767] Subtest invalid-ctx-set: SUCCESS (0.001s)
[219.514941] [040/154] (800s left) perf_pmu (busy-double-start)
[219.553164] Starting subtest: busy-double-start
[219.555496] Starting dynamic subtest: rcs0
[223.195347] Dynamic subtest rcs0: FAIL (3.590s)
[223.205456] Starting dynamic subtest: bcs0
[226.712651] Dynamic subtest bcs0: SUCCESS (3.569s)
[226.724912] Starting dynamic subtest: vcs0
[231.163320] Dynamic subtest vcs0: SUCCESS (4.449s)
[231.174488] Starting dynamic subtest: vcs1
[235.212255] Dynamic subtest vcs1: SUCCESS (4.047s)
[235.226370] Starting dynamic subtest: vecs0
[239.144462] Dynamic subtest vecs0: SUCCESS (3.930s)
[239.155967] Starting dynamic subtest: ccs0
[242.785267] Dynamic subtest ccs0: FAIL (3.568s)
[242.787138] Subtest busy-double-start: FAIL (23.162s)
[242.866243] [041/154] (776s left) i915_module_load (resize-bar)
[242.899366] Starting subtest: resize-bar
[242.920407] Subtest resize-bar: SKIP (0.019s)
[242.966292] [042/154] (776s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[243.068651] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[251.999910] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.936s)
[252.118250] [043/154] (767s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-blt)
[252.219699] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-blt
[252.224181] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[252.320091] [044/154] (767s left) gem_mmap_wc (read-write-distinct)
[252.371118] Subtest read-write-distinct: SKIP (0.000s)
[252.440682] [045/154] (767s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-blt)
[252.541167] Starting subtest: hdr-rgb101010-draw-blt
[252.545188] Subtest hdr-rgb101010-draw-blt: SKIP (0.000s)
[252.642835] [046/154] (767s left) api_intel_bb (full-batch)
[252.704559] Starting subtest: full-batch
[252.707308] Subtest full-batch: SUCCESS (0.006s)
[252.822156] [047/154] (767s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[252.888174] Subtest tiled-to-tiled: SKIP (0.000s)
[252.968685] [048/154] (767s left) kms_plane (pixel-format-linear-modifier)
[253.065707] Starting subtest: pixel-format-linear-modifier
[253.093272] Starting dynamic subtest: pipe-A-plane-0
[253.648714] Dynamic subtest pipe-A-plane-0: SUCCESS (0.555s)
[253.658540] Starting dynamic subtest: pipe-A-plane-3
[254.082614] Dynamic subtest pipe-A-plane-3: SUCCESS (0.433s)
[254.092689] Starting dynamic subtest: pipe-A-plane-5
[254.292945] Dynamic subtest pipe-A-plane-5: SUCCESS (0.211s)
[255.399285] Starting dynamic subtest: pipe-B-plane-0
[255.967875] Dynamic subtest pipe-B-plane-0: SUCCESS (0.578s)
[255.979086] Starting dynamic subtest: pipe-B-plane-3
[256.401111] Dynamic subtest pipe-B-plane-3: SUCCESS (0.433s)
[256.411713] Starting dynamic subtest: pipe-B-plane-5
[256.600591] Dynamic subtest pipe-B-plane-5: SUCCESS (0.199s)
[256.911241] Subtest pixel-format-linear-modifier: SUCCESS (3.846s)
[257.787966] [049/154] (762s left) perf (missing-sample-flags)
[257.932200] Starting subtest: missing-sample-flags
[257.934547] Subtest missing-sample-flags: SUCCESS (0.001s)
[257.980838] [050/154] (762s left) dumb_buffer (create-valid-dumb)
[258.029786] Starting subtest: create-valid-dumb
[258.032200] Subtest create-valid-dumb: SUCCESS (0.000s)
[258.125957] [051/154] (762s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc)
[258.225600] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc
[258.229533] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[258.332070] [052/154] (761s left) perf (polling)
[258.422242] Starting subtest: polling
[258.424607] Starting dynamic subtest: 0-rcs0
[268.456094] Dynamic subtest 0-rcs0: SUCCESS (10.031s)
[268.467453] Starting dynamic subtest: 1-vcs1
[278.529343] Dynamic subtest 1-vcs1: SUCCESS (10.021s)
[278.532397] Subtest polling: SUCCESS (20.055s)
[278.575727] [053/154] (741s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0)
[278.688290] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0
[279.723121] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.035s)
[279.848863] [054/154] (740s left) kms_cursor_crc (cursor-onscreen-64x64)
[280.025445] Starting subtest: cursor-onscreen-64x64
[280.029902] Starting dynamic subtest: pipe-A-eDP-1
[282.153662] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.124s)
[282.186791] Starting dynamic subtest: pipe-D-eDP-1
[284.971028] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.792s)
[284.973602] Subtest cursor-onscreen-64x64: SUCCESS (4.945s)
[285.837197] [055/154] (734s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[285.935820] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[285.949120] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.011s)
[286.042265] [056/154] (734s left) gem_ctx_isolation (clean)
[286.096131] Starting subtest: clean
[286.098683] Starting dynamic subtest: rcs0
[286.145016] Dynamic subtest rcs0: SUCCESS (0.049s)
[286.158112] Starting dynamic subtest: bcs0
[286.201189] Dynamic subtest bcs0: SUCCESS (0.055s)
[286.212288] Starting dynamic subtest: vcs0
[286.266972] Dynamic subtest vcs0: SUCCESS (0.065s)
[286.279260] Starting dynamic subtest: vcs1
[287.674064] Dynamic subtest vcs1: SUCCESS (1.406s)
[287.687457] Starting dynamic subtest: vecs0
[289.661561] Dynamic subtest vecs0: SUCCESS (1.986s)
[289.672626] Starting dynamic subtest: ccs0
[291.650828] Dynamic subtest ccs0: SUCCESS (1.987s)
[291.653228] Subtest clean: SUCCESS (5.555s)
[291.872983] [057/154] (728s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[291.982703] Starting subtest: fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[291.987664] Subtest fbchdr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[292.089135] [058/154] (728s left) perf_pmu (all-busy-check-all)
[292.130542] Starting subtest: all-busy-check-all
[293.671545] Subtest all-busy-check-all: SUCCESS (1.539s)
[293.772533] [059/154] (726s left) kms_psr (psr-cursor-mmap-gtt)
[293.875449] Starting subtest: psr-cursor-mmap-gtt
[293.877794] Starting dynamic subtest: eDP-1
[295.639250] Dynamic subtest eDP-1: SUCCESS (1.764s)
[295.641638] Subtest psr-cursor-mmap-gtt: SUCCESS (1.765s)
[296.517557] [060/154] (724s left) kms_flip (2x-plain-flip-ts-check-interruptible)
[296.609046] Starting subtest: 2x-plain-flip-ts-check-interruptible
[296.621766] Subtest 2x-plain-flip-ts-check-interruptible: SKIP (0.012s)
[296.700678] [061/154] (723s left) kms_psr (fbc-psr-sprite-mmap-gtt)
[296.798016] Starting subtest: fbc-psr-sprite-mmap-gtt
[296.800347] Starting dynamic subtest: eDP-1
[296.804764] Dynamic subtest eDP-1: SKIP (0.000s)
[296.809166] Subtest fbc-psr-sprite-mmap-gtt: SKIP (0.001s)
[296.873734] [062/154] (723s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[296.965717] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[296.968118] Starting dynamic subtest: pipe-A-default-mode
[298.167388] Dynamic subtest pipe-A-default-mode: SKIP (1.198s)
[298.173104] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.200s)
[298.313419] [063/154] (722s left) gem_exec_schedule (preempt-contexts)
[298.377945] Starting subtest: preempt-contexts
[298.379998] Starting dynamic subtest: rcs0
[298.424162] Dynamic subtest rcs0: SUCCESS (0.054s)
[298.434825] Starting dynamic subtest: bcs0
[298.477857] Dynamic subtest bcs0: SUCCESS (0.053s)
[298.489171] Starting dynamic subtest: vcs0
[298.528585] Dynamic subtest vcs0: SUCCESS (0.049s)
[298.543271] Starting dynamic subtest: vcs1
[298.571267] Dynamic subtest vcs1: SUCCESS (0.042s)
[298.581886] Starting dynamic subtest: vecs0
[298.610142] Dynamic subtest vecs0: SUCCESS (0.038s)
[298.620991] Starting dynamic subtest: ccs0
[298.658019] Dynamic subtest ccs0: SUCCESS (0.046s)
[298.660192] Subtest preempt-contexts: SUCCESS (0.287s)
[299.706162] [064/154] (721s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[299.809988] Starting subtest: fbc-shrfb-scaledprimary
[302.735616] Subtest fbc-shrfb-scaledprimary: SUCCESS (2.931s)
[303.930160] [065/154] (716s left) kms_psr (fbc-pr-primary-mmap-gtt)
[304.035650] Starting subtest: fbc-pr-primary-mmap-gtt
[304.039984] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[304.111159] [066/154] (716s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-downscaling)
[304.195757] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-downscaling
[304.198063] Starting dynamic subtest: pipe-A-default-mode
[305.387175] Dynamic subtest pipe-A-default-mode: SKIP (1.187s)
[305.391620] Subtest flip-32bpp-4tile-to-64bpp-4tile-downscaling: SKIP (1.189s)
[305.532156] [067/154] (715s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[305.636024] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[305.640348] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[305.749759] [068/154] (715s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-render)
[305.863799] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-render
[305.868147] Subtest psrhdr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[305.967403] [069/154] (715s left) gen9_exec_parse (bb-oversize)
[306.031198] Subtest bb-oversize: SKIP (0.000s)
[306.103983] [070/154] (714s left) syncobj_timeline (multi-wait-all-signaled)
[306.153737] Starting subtest: multi-wait-all-signaled
[306.155996] Subtest multi-wait-all-signaled: SUCCESS (0.001s)
[306.257448] [071/154] (714s left) gem_exec_balancer (sliced)
[306.310091] Starting subtest: sliced
[306.314041] Subtest sliced: SKIP (0.000s)
[306.640444] [072/154] (714s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[306.743041] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[306.747129] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[306.861779] [073/154] (714s left) syncobj_wait (wait-delayed-signal)
[306.932111] Starting subtest: wait-delayed-signal
[307.032617] Subtest wait-delayed-signal: SUCCESS (0.102s)
[307.143109] [074/154] (714s left) gem_mmap_gtt (basic-small-bo)
[307.200957] Subtest basic-small-bo: SKIP (0.000s)
[307.268104] [075/154] (714s left) kms_big_fb (linear-addfb)
[307.367546] Starting subtest: linear-addfb
[307.369895] Subtest linear-addfb: SUCCESS (0.000s)
[307.468932] [076/154] (713s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[307.572343] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[308.684041] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.112s)
[308.798881] [077/154] (712s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[308.893301] Starting subtest: psr-shrfb-scaledprimary
[314.648806] Subtest psr-shrfb-scaledprimary: SUCCESS (5.759s)
[314.787672] [078/154] (706s left) gem_softpin (allocator-fork)
[314.839534] Starting subtest: allocator-fork
[317.104557] Subtest allocator-fork: SUCCESS (2.210s)
[317.179493] [079/154] (704s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[317.274430] Starting subtest: random-ccs-data-y-tiled-ccs
[317.276727] Starting dynamic subtest: pipe-A-eDP-1
[317.300847] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[317.312679] Starting dynamic subtest: pipe-B-eDP-1
[317.336157] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[317.346694] Starting dynamic subtest: pipe-C-eDP-1
[317.370711] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[317.383363] Starting dynamic subtest: pipe-D-eDP-1
[317.408024] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[317.412139] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[319.526870] [080/154] (701s left) prime_self_import (export-vs-gem_close-race)
[319.561526] Starting subtest: export-vs-gem_close-race
[324.649963] Subtest export-vs-gem_close-race: SUCCESS (5.068s)
[324.707627] [081/154] (696s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[324.818643] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt
[324.822850] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[324.920467] [082/154] (696s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-sf)
[325.019273] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-sf
[325.023243] Subtest fbc-pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s)
[325.091382] [083/154] (696s left) syncobj_wait (single-wait-all-submitted)
[325.149963] Starting subtest: single-wait-all-submitted
[325.259536] Subtest single-wait-all-submitted: SUCCESS (0.108s)
[325.340463] [084/154] (696s left) gem_softpin (evict-snoop-interruptible)
[325.399668] Starting subtest: evict-snoop-interruptible
[325.404205] Subtest evict-snoop-interruptible: SKIP (0.000s)
[325.527525] [085/154] (696s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-gtt)
[325.627007] Starting subtest: fbcpsr-rgb101010-draw-mmap-gtt
[325.631380] Subtest fbcpsr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[325.726576] [086/154] (696s left) kms_pipe_crc_basic (nonblocking-crc)
[325.813636] Starting subtest: nonblocking-crc
[325.815961] Starting dynamic subtest: pipe-A-eDP-1
[326.560623] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.747s)
[326.572031] Starting dynamic subtest: pipe-B-eDP-1
[327.977108] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.415s)
[327.990252] Starting dynamic subtest: pipe-C-eDP-1
[329.365448] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.387s)
[329.377196] Starting dynamic subtest: pipe-D-eDP-1
[330.781896] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.414s)
[330.784194] Subtest nonblocking-crc: SUCCESS (4.969s)
[331.597268] [087/154] (690s left) kms_cursor_legacy (torture-bo)
[331.722394] Starting subtest: torture-bo
[331.724712] Starting dynamic subtest: pipe-A
[338.270782] Dynamic subtest pipe-A: SUCCESS (6.492s)
[338.287142] Starting dynamic subtest: pipe-B
[345.016331] Dynamic subtest pipe-B: SUCCESS (6.797s)
[345.036242] Starting dynamic subtest: pipe-C
[351.458028] Dynamic subtest pipe-C: SUCCESS (6.439s)
[351.468644] Starting dynamic subtest: pipe-D
[358.938356] Dynamic subtest pipe-D: SUCCESS (7.479s)
[358.956290] Starting dynamic subtest: all-pipes
[365.545280] Dynamic subtest all-pipes: SUCCESS (6.603s)
[365.547815] Subtest torture-bo: SUCCESS (33.819s)
[365.718041] [088/154] (656s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[365.838610] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[365.843038] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[365.977691] [089/154] (655s left) kms_ccs (crc-primary-basic-4-tiled-lnl-ccs)
[366.079122] Starting subtest: crc-primary-basic-4-tiled-lnl-ccs
[366.083701] Subtest crc-primary-basic-4-tiled-lnl-ccs: SKIP (0.000s)
[367.225123] [090/154] (654s left) gem_wait (wait)
[367.287694] Starting subtest: wait
[367.289960] Starting dynamic subtest: all-engines
[368.613871] Dynamic subtest all-engines: SUCCESS (1.324s)
[368.628293] Starting dynamic subtest: rcs0
[369.659646] Dynamic subtest rcs0: SUCCESS (1.044s)
[369.675773] Starting dynamic subtest: bcs0
[370.704869] Dynamic subtest bcs0: SUCCESS (1.043s)
[370.717819] Starting dynamic subtest: vcs0
[371.751219] Dynamic subtest vcs0: SUCCESS (1.044s)
[371.764534] Starting dynamic subtest: vcs1
[372.797319] Dynamic subtest vcs1: SUCCESS (1.044s)
[372.810772] Starting dynamic subtest: vecs0
[373.843658] Dynamic subtest vecs0: SUCCESS (1.044s)
[373.857274] Starting dynamic subtest: ccs0
[374.891119] Dynamic subtest ccs0: SUCCESS (1.045s)
[374.893822] Subtest wait: SUCCESS (7.604s)
[375.091320] [091/154] (646s left) prime_mmap (test_aperture_limit)
[375.147863] Starting subtest: test_aperture_limit
[375.150043] Starting dynamic subtest: test_aperture_limit-smem
[375.156757] Dynamic subtest test_aperture_limit-smem: SKIP (0.008s)
[375.161261] Subtest test_aperture_limit: SKIP (0.009s)
[375.232369] [092/154] (646s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[375.289712] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[375.394718] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.103s)
[375.474659] [093/154] (646s left) gem_ctx_persistence (legacy-engines-persistence)
[375.529887] Starting subtest: legacy-engines-persistence
[375.532192] Starting dynamic subtest: render
[376.374654] Dynamic subtest render: SUCCESS (0.843s)
[376.389788] Starting dynamic subtest: bsd1
[377.131318] Dynamic subtest bsd1: SUCCESS (0.754s)
[377.147006] Starting dynamic subtest: bsd2
[377.894329] Dynamic subtest bsd2: SUCCESS (0.760s)
[377.910216] Starting dynamic subtest: blt
[378.641700] Dynamic subtest blt: SUCCESS (0.744s)
[378.655295] Starting dynamic subtest: vebox
[379.446664] Dynamic subtest vebox: SUCCESS (0.803s)
[379.449401] Subtest legacy-engines-persistence: SUCCESS (3.918s)
[379.547449] [094/154] (642s left) gem_exec_reloc (basic-softpin)
[379.614090] Subtest basic-softpin: SKIP (0.000s)
[379.698522] [095/154] (642s left) kms_psr2_sf (pr-overlay-plane-move-continuous-sf)
[379.805049] Starting subtest: pr-overlay-plane-move-continuous-sf
[379.809395] Subtest pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[379.876866] [096/154] (642s left) gem_barrier_race (remote-request)
[379.934601] Starting subtest: remote-request
[379.936774] Starting dynamic subtest: rcs0
[385.094646] Dynamic subtest rcs0: SUCCESS (5.161s)
[385.100310] Subtest remote-request: SUCCESS (5.163s)
[385.252603] [097/154] (636s left) i915_selftest (perf)
[389.839894] Starting subtest: perf
[389.842445] Starting dynamic subtest: engine_cs
[392.694485] Dynamic subtest engine_cs: SUCCESS (2.853s)
[392.705672] Starting dynamic subtest: request
[400.031205] Dynamic subtest request: SUCCESS (7.334s)
[400.040649] Starting dynamic subtest: migrate
[404.407522] Dynamic subtest migrate: SUCCESS (4.374s)
[404.417506] Starting dynamic subtest: region
[410.281356] Dynamic subtest region: SUCCESS (5.871s)
[410.283925] Subtest perf: SUCCESS (20.441s)
[410.313657] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[410.314456] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[410.317817] [098/154] (611s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)
[411.921133] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt
[411.925073] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[412.052900] [FACT kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[412.054201] [FACT kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt)] new: kernel.kmod_is_loaded.i915: true
[412.058812] [099/154] (610s left) syncobj_wait (invalid-signal-one-illegal-handle)
[412.121727] Starting subtest: invalid-signal-one-illegal-handle
[412.123721] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[412.227999] [100/154] (610s left) kms_selftest (drm_plane_helper)
[412.273341] Starting subtest: drm_plane_helper
[412.278745] Starting dynamic subtest: drm_test_check_plane_state
[412.396831] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.116s)
[412.408378] Starting dynamic subtest: drm_test_check_invalid_plane_state
[412.496664] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.101s)
[412.499114] Subtest drm_plane_helper: SUCCESS (0.225s)
[412.686305] [101/154] (609s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[412.785767] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu
[412.789879] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[412.893542] [102/154] (609s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[412.995116] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[412.999164] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[413.099801] [103/154] (609s left) kms_frontbuffer_tracking (hdr-1p-offscreen-pri-indfb-draw-blt)
[413.204760] Starting subtest: hdr-1p-offscreen-pri-indfb-draw-blt
[413.209279] Subtest hdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[413.293381] [104/154] (609s left) gem_create (create-valid-nonaligned)
[413.345820] Starting subtest: create-valid-nonaligned
[413.348085] Subtest create-valid-nonaligned: SUCCESS (0.001s)
[413.526851] [105/154] (609s left) kms_chamelium_hpd (dp-hpd-storm-disable)
[413.917365] Subtest dp-hpd-storm-disable: SKIP (0.000s)
[414.701818] [106/154] (607s left) gem_render_copy (yf-tiled-ccs-to-linear)
[414.771286] Starting subtest: yf-tiled-ccs-to-linear
[414.775739] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[414.955824] [107/154] (607s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[415.072366] Starting subtest: psr-rgb565-draw-mmap-wc
[422.241593] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (7.173s)
[422.357955] [108/154] (600s left) gem_exec_schedule (fairslice)
[422.436299] Starting subtest: fairslice
[422.438254] Starting dynamic subtest: rcs0
[424.459426] Dynamic subtest rcs0: SUCCESS (2.026s)
[424.472327] Starting dynamic subtest: bcs0
[426.481623] Dynamic subtest bcs0: SUCCESS (2.023s)
[426.492676] Starting dynamic subtest: vcs0
[428.505637] Dynamic subtest vcs0: SUCCESS (2.022s)
[428.516011] Starting dynamic subtest: vcs1
[430.528536] Dynamic subtest vcs1: SUCCESS (2.020s)
[430.543115] Starting dynamic subtest: vecs0
[432.547545] Dynamic subtest vecs0: SUCCESS (2.019s)
[432.560008] Starting dynamic subtest: ccs0
[434.571632] Dynamic subtest ccs0: SUCCESS (2.024s)
[434.573598] Subtest fairslice: SUCCESS (12.141s)
[434.872870] [109/154] (587s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[434.984001] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[434.988106] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[435.102194] [110/154] (587s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt)
[435.194705] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt
[435.199090] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[435.301886] [111/154] (587s left) gem_ringfill (legacy-basic)
[440.618998] Starting subtest: legacy-basic
[440.621969] Starting dynamic subtest: default
[440.680368] Dynamic subtest default: SUCCESS (0.061s)
[440.694232] Starting dynamic subtest: render
[440.723827] Dynamic subtest render: SUCCESS (0.041s)
[440.737219] Starting dynamic subtest: bsd
[440.766945] Dynamic subtest bsd: SUCCESS (0.040s)
[440.778760] Starting dynamic subtest: bsd1
[440.809613] Dynamic subtest bsd1: SUCCESS (0.040s)
[440.820753] Starting dynamic subtest: bsd2
[440.849464] Dynamic subtest bsd2: SUCCESS (0.037s)
[440.862703] Starting dynamic subtest: blt
[440.899192] Dynamic subtest blt: SUCCESS (0.047s)
[440.912640] Starting dynamic subtest: vebox
[440.943301] Dynamic subtest vebox: SUCCESS (0.042s)
[440.945640] Subtest legacy-basic: SUCCESS (0.327s)
[441.040689] [112/154] (581s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-indfb-pgflip-blt)
[441.141543] Starting subtest: psrhdr-2p-primscrn-indfb-pgflip-blt
[441.145739] Subtest psrhdr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[441.242651] [113/154] (581s left) gem_eio (create-ext)
[441.467742] Starting subtest: create-ext
[441.785241] Subtest create-ext: SUCCESS (0.319s)
[441.924380] [114/154] (581s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[442.009033] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[442.010856] Starting dynamic subtest: pipe-A-eDP-1
[442.059174] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[442.069511] Starting dynamic subtest: pipe-B-eDP-1
[442.116422] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[442.126720] Starting dynamic subtest: pipe-C-eDP-1
[442.173939] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[442.184200] Starting dynamic subtest: pipe-D-eDP-1
[442.232299] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[442.237010] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.010s)
[444.345365] [115/154] (578s left) gem_eio (in-flight-contexts-10ms)
[444.558334] Starting subtest: in-flight-contexts-10ms
[458.687679] Subtest in-flight-contexts-10ms: SUCCESS (14.129s)
[458.820682] [116/154] (564s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[458.876795] Starting subtest: multi-wait-for-submit-submitted-signaled
[458.879229] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s)
[458.977857] [117/154] (564s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc)
[459.062819] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc
[459.065090] Starting dynamic subtest: pipe-A-eDP-1
[459.088294] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[459.101633] Starting dynamic subtest: pipe-B-eDP-1
[459.122864] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[459.133897] Starting dynamic subtest: pipe-C-eDP-1
[459.155255] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[459.166218] Starting dynamic subtest: pipe-D-eDP-1
[459.189451] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[459.193835] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[461.394535] [118/154] (561s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[461.517199] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[472.698633] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (11.179s)
[473.527417] [119/154] (549s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[473.642692] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[473.647234] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s)
[473.757783] [120/154] (549s left) prime_vgem (busy)
[473.831541] Starting subtest: busy
[473.833962] Starting dynamic subtest: rcs0
[473.887741] Dynamic subtest rcs0: SUCCESS (0.056s)
[473.898276] Starting dynamic subtest: bcs0
[473.937119] Dynamic subtest bcs0: SUCCESS (0.048s)
[473.947812] Starting dynamic subtest: vcs0
[473.981417] Dynamic subtest vcs0: SUCCESS (0.043s)
[473.992593] Starting dynamic subtest: vcs1
[474.030060] Dynamic subtest vcs1: SUCCESS (0.048s)
[474.040884] Starting dynamic subtest: vecs0
[474.073706] Dynamic subtest vecs0: SUCCESS (0.043s)
[474.084629] Starting dynamic subtest: ccs0
[474.119457] Dynamic subtest ccs0: SUCCESS (0.045s)
[474.121735] Subtest busy: SUCCESS (0.289s)
[474.244157] [121/154] (549s left) i915_drm_fdinfo (virtual-busy-hang-all)
[474.283406] Starting subtest: virtual-busy-hang-all
[474.287916] Subtest virtual-busy-hang-all: SKIP (0.000s)
[474.363211] [122/154] (548s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[474.462504] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[474.466546] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[474.566197] [123/154] (548s left) gem_sync (basic-many-each)
[474.641720] Starting subtest: basic-many-each
[476.922434] Subtest basic-many-each: SUCCESS (2.288s)
[477.113688] [124/154] (546s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-pri-indfb-multidraw)
[477.221535] Starting subtest: fbcpsrhdr-1p-pri-indfb-multidraw
[477.225811] Subtest fbcpsrhdr-1p-pri-indfb-multidraw: SKIP (0.000s)
[477.330529] [125/154] (546s left) kms_atomic (crtc-invalid-params-fence)
[477.432367] Starting subtest: crtc-invalid-params-fence
[477.434691] Starting dynamic subtest: pipe-A-eDP-1
[477.843954] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.411s)
[477.846368] Subtest crtc-invalid-params-fence: SUCCESS (0.412s)
[478.654414] [126/154] (544s left) gem_exec_params (rel-constants-invalid)
[478.723163] Starting subtest: rel-constants-invalid
[478.725071] Subtest rel-constants-invalid: SUCCESS (0.000s)
[478.838813] [127/154] (544s left) api_intel_allocator (two-level-inception-interruptible)
[478.897967] Starting subtest: two-level-inception-interruptible
[484.359461] Subtest two-level-inception-interruptible: SUCCESS (5.410s)
[484.457672] [128/154] (539s left) syncobj_wait (wait-all-for-submit-delayed-submit)
[484.511514] Starting subtest: wait-all-for-submit-delayed-submit
[484.612126] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.102s)
[484.699853] [129/154] (538s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[484.769895] Starting subtest: invalid-bsd2-flag-on-vebox
[484.774513] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[484.896258] [130/154] (538s left) syncobj_timeline (etime-single-wait-for-submit-submitted)
[484.949158] Starting subtest: etime-single-wait-for-submit-submitted
[485.064278] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.113s)
[485.151002] [131/154] (538s left) prime_self_import (basic-with_two_bos)
[485.192445] Starting subtest: basic-with_two_bos
[485.207934] Subtest basic-with_two_bos: SUCCESS (0.019s)
[485.349787] [132/154] (538s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[485.480066] Starting subtest: cursorB-vs-flipB-atomic-transitions
[485.484664] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[485.632737] [133/154] (538s left) gem_exec_balancer (noheartbeat)
[485.930474] Starting subtest: noheartbeat
[485.935628] Subtest noheartbeat: SKIP (0.000s)
[486.066498] [134/154] (537s left) kms_lease (cursor-implicit-plane)
[486.172248] Starting subtest: cursor-implicit-plane
[486.174585] Starting dynamic subtest: pipe-A-eDP-1
[486.630129] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.456s)
[486.642725] Starting dynamic subtest: pipe-B-eDP-1
[487.760232] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.131s)
[487.774937] Starting dynamic subtest: pipe-C-eDP-1
[488.865129] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.102s)
[488.877060] Starting dynamic subtest: pipe-D-eDP-1
[490.008223] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.142s)
[490.010580] Subtest cursor-implicit-plane: SUCCESS (3.837s)
[490.856266] [135/154] (533s left) gem_exec_schedule (u-submit-early-slice)
[490.925220] Starting subtest: u-submit-early-slice
[490.927401] Starting dynamic subtest: rcs0
[490.960899] Dynamic subtest rcs0: SUCCESS (0.042s)
[490.975795] Starting dynamic subtest: bcs0
[491.001969] Dynamic subtest bcs0: SUCCESS (0.040s)
[491.015424] Starting dynamic subtest: vcs0
[491.051274] Dynamic subtest vcs0: SUCCESS (0.047s)
[491.064068] Starting dynamic subtest: vcs1
[491.094383] Dynamic subtest vcs1: SUCCESS (0.042s)
[491.106656] Starting dynamic subtest: vecs0
[491.133558] Dynamic subtest vecs0: SUCCESS (0.038s)
[491.146625] Starting dynamic subtest: ccs0
[491.179258] Dynamic subtest ccs0: SUCCESS (0.045s)
[491.181648] Subtest u-submit-early-slice: SUCCESS (0.261s)
[492.727790] [136/154] (531s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-rc-ccs)
[492.827852] Starting subtest: crc-primary-suspend-4-tiled-dg2-rc-ccs
[492.830251] Starting dynamic subtest: pipe-A-eDP-1
[492.839356] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[492.851559] Starting dynamic subtest: pipe-B-eDP-1
[492.860111] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[492.871034] Starting dynamic subtest: pipe-C-eDP-1
[492.879229] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[492.889620] Starting dynamic subtest: pipe-D-eDP-1
[492.897830] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[492.901712] Subtest crc-primary-suspend-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[495.116737] [137/154] (528s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-move)
[495.213359] Starting subtest: fbc-1p-primscrn-spr-indfb-move
[499.364039] Subtest fbc-1p-primscrn-spr-indfb-move: SUCCESS (4.155s)
[500.538467] [138/154] (523s left) gem_exec_whisper (basic-queues-priority)
[500.605461] Starting subtest: basic-queues-priority
[524.694531] Subtest basic-queues-priority: SUCCESS (24.032s)
[524.846745] [139/154] (499s left) kms_psr (fbc-psr2-primary-page-flip)
[524.951387] Starting subtest: fbc-psr2-primary-page-flip
[524.953599] Starting dynamic subtest: eDP-1
[524.957629] Dynamic subtest eDP-1: SKIP (0.000s)
[524.961633] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[525.030186] [140/154] (499s left) kms_scaling_modes (scaling-mode-full-aspect)
[525.126343] Starting subtest: scaling-mode-full-aspect
[525.128624] Starting dynamic subtest: pipe-A-eDP-1
[525.223039] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.097s)
[525.243222] Starting dynamic subtest: pipe-B-eDP-1
[526.448001] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.222s)
[526.460374] Starting dynamic subtest: pipe-C-eDP-1
[527.662403] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.213s)
[527.675851] Starting dynamic subtest: pipe-D-eDP-1
[528.803256] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.139s)
[528.805957] Subtest scaling-mode-full-aspect: SUCCESS (3.678s)
[530.066965] [141/154] (494s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt)
[530.179965] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt
[530.184163] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[530.294260] [142/154] (493s left) i915_pm_rps (thresholds)
[530.349826] Starting subtest: thresholds
[530.354526] Subtest thresholds: SKIP (0.001s)
[530.458633] [143/154] (493s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-indfb-draw-blt)
[530.562759] Starting subtest: hdr-2p-primscrn-pri-indfb-draw-blt
[530.567180] Subtest hdr-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s)
[530.680811] [144/154] (493s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt)
[530.772047] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt
[538.115961] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.348s)
[538.235499] [145/154] (486s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-plflip-blt)
[538.331549] Starting subtest: fbchdr-1p-primscrn-indfb-plflip-blt
[538.335848] Subtest fbchdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[538.443249] [146/154] (486s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-pwrite)
[538.531900] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-pwrite
[538.536466] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[538.643152] [147/154] (485s left) kms_cursor_crc (cursor-sliding-128x42)
[538.882320] Starting subtest: cursor-sliding-128x42
[538.931688] Subtest cursor-sliding-128x42: SKIP (0.046s)
[539.145549] [148/154] (485s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[539.247365] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[539.251069] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[539.366746] [149/154] (485s left) kms_rotation_crc (primary-rotation-180)
[539.452091] Starting subtest: primary-rotation-180
[546.419547] Subtest primary-rotation-180: SUCCESS (6.968s)
[547.691119] [150/154] (476s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[547.737820] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[547.740160] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[547.866148] [151/154] (476s left) perf_pmu (render-node-busy)
[547.933420] Starting subtest: render-node-busy
[547.935881] Starting dynamic subtest: rcs0
[548.513012] Dynamic subtest rcs0: SUCCESS (0.578s)
[548.526158] Starting dynamic subtest: bcs0
[549.070796] Dynamic subtest bcs0: SUCCESS (0.555s)
[549.084048] Starting dynamic subtest: vcs0
[550.630622] Dynamic subtest vcs0: SUCCESS (1.556s)
[550.645099] Starting dynamic subtest: vcs1
[552.614368] Dynamic subtest vcs1: SUCCESS (1.980s)
[552.627421] Starting dynamic subtest: vecs0
[554.662662] Dynamic subtest vecs0: SUCCESS (2.045s)
[554.677499] Starting dynamic subtest: ccs0
[555.237657] Dynamic subtest ccs0: SUCCESS (0.573s)
[555.240813] Subtest render-node-busy: SUCCESS (7.305s)
[555.317396] [152/154] (469s left) kms_getfb (getfb2-into-addfb2)
[555.412318] Starting subtest: getfb2-into-addfb2
[555.414642] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[555.521752] [153/154] (469s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[555.631313] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-gtt
[555.635631] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[555.731145] [154/154] (469s left) syncobj_timeline (wait-any-interrupted)
[555.787380] Starting subtest: wait-any-interrupted
[555.889346] Subtest wait-any-interrupted: SUCCESS (0.103s)
[555.964599] Closing watchdogs
results: parsing output: 0/ for test: kms_chamelium_frames
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: kms_lease
results: parsing output: 4/ for test: kms_atomic_transition
results: parsing output: 5/ for test: kms_async_flips
results: parsing output: 6/ for test: gem_exec_suspend
results: parsing output: 7/ for test: gem_create
results: parsing output: 8/ for test: gem_mmap
results: parsing output: 9/ for test: kms_cursor_edge_walk
results: parsing output: 10/ for test: gem_exec_store
results: parsing output: 11/ for test: gem_exec_fence
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_color
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_render_tiled_blits
results: parsing output: 16/ for test: kms_content_protection
results: parsing output: 17/ for test: kms_psr
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_mmap_offset
results: parsing output: 20/ for test: kms_pm_backlight
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_plane_scaling
results: parsing output: 24/ for test: kms_frontbuffer_tracking
results: parsing output: 25/ for test: gem_request_retire
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_plane_multiple
results: parsing output: 28/ for test: kms_frontbuffer_tracking
results: parsing output: 29/ for test: gem_mmap_wc
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: kms_feature_discovery
results: parsing output: 32/ for test: kms_vblank
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_fenced_exec_thrash
results: parsing output: 35/ for test: kms_flip
results: parsing output: 36/ for test: vgem_basic
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: gem_ctx_param
results: parsing output: 39/ for test: perf_pmu
results: parsing output: 40/ for test: i915_module_load
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_mmap_wc
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: api_intel_bb
results: parsing output: 46/ for test: gem_set_tiling_vs_blt
results: parsing output: 47/ for test: kms_plane
results: parsing output: 48/ for test: perf
results: parsing output: 49/ for test: dumb_buffer
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: perf
results: parsing output: 52/ for test: kms_big_fb
results: parsing output: 53/ for test: kms_cursor_crc
results: parsing output: 54/ for test: kms_flip
results: parsing output: 55/ for test: gem_ctx_isolation
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: perf_pmu
results: parsing output: 58/ for test: kms_psr
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: kms_flip_scaled_crc
results: parsing output: 62/ for test: gem_exec_schedule
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_psr
results: parsing output: 65/ for test: kms_flip_scaled_crc
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gen9_exec_parse
results: parsing output: 69/ for test: syncobj_timeline
results: parsing output: 70/ for test: gem_exec_balancer
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: syncobj_wait
results: parsing output: 73/ for test: gem_mmap_gtt
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_softpin
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: prime_self_import
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: kms_psr2_sf
results: parsing output: 82/ for test: syncobj_wait
results: parsing output: 83/ for test: gem_softpin
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_pipe_crc_basic
results: parsing output: 86/ for test: kms_cursor_legacy
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: kms_ccs
results: parsing output: 89/ for test: gem_wait
results: parsing output: 90/ for test: prime_mmap
results: parsing output: 91/ for test: syncobj_timeline
results: parsing output: 92/ for test: gem_ctx_persistence
results: parsing output: 93/ for test: gem_exec_reloc
results: parsing output: 94/ for test: kms_psr2_sf
results: parsing output: 95/ for test: gem_barrier_race
results: parsing output: 96/ for test: i915_selftest
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: syncobj_wait
results: parsing output: 99/ for test: kms_selftest
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_frontbuffer_tracking
results: parsing output: 103/ for test: gem_create
results: parsing output: 104/ for test: kms_chamelium_hpd
results: parsing output: 105/ for test: gem_render_copy
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_schedule
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: gem_ringfill
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: gem_eio
results: parsing output: 113/ for test: kms_ccs
results: parsing output: 114/ for test: gem_eio
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: kms_ccs
results: parsing output: 117/ for test: kms_cursor_legacy
results: parsing output: 118/ for test: kms_big_fb
results: parsing output: 119/ for test: prime_vgem
results: parsing output: 120/ for test: i915_drm_fdinfo
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gem_sync
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_atomic
results: parsing output: 125/ for test: gem_exec_params
results: parsing output: 126/ for test: api_intel_allocator
results: parsing output: 127/ for test: syncobj_wait
results: parsing output: 128/ for test: gem_exec_params
results: parsing output: 129/ for test: syncobj_timeline
results: parsing output: 130/ for test: prime_self_import
results: parsing output: 131/ for test: kms_cursor_legacy
results: parsing output: 132/ for test: gem_exec_balancer
results: parsing output: 133/ for test: kms_lease
results: parsing output: 134/ for test: gem_exec_schedule
results: parsing output: 135/ for test: kms_ccs
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: gem_exec_whisper
results: parsing output: 138/ for test: kms_psr
results: parsing output: 139/ for test: kms_scaling_modes
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: i915_pm_rps
results: parsing output: 142/ for test: kms_frontbuffer_tracking
results: parsing output: 143/ for test: kms_frontbuffer_tracking
results: parsing output: 144/ for test: kms_frontbuffer_tracking
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_cursor_crc
results: parsing output: 147/ for test: kms_big_fb
results: parsing output: 148/ for test: kms_rotation_crc
results: parsing output: 149/ for test: syncobj_wait
results: parsing output: 150/ for test: perf_pmu
results: parsing output: 151/ for test: kms_getfb
results: parsing output: 152/ for test: kms_frontbuffer_tracking
results: parsing output: 153/ for test: syncobj_timeline
Done.