[59.640486] Initializing watchdogs
[59.640549]   /dev/watchdog0
[59.659349] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[59.668853] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[59.672715] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.675051] [001/133] (960s left) kms_flip_scaled_crc (flip-64bpp-yftile-to-32bpp-yftile-downscaling)
[59.965583] Starting subtest: flip-64bpp-yftile-to-32bpp-yftile-downscaling
[59.966579] Starting dynamic subtest: pipe-A-valid-mode
[60.056591] Dynamic subtest pipe-A-valid-mode: SKIP (0.089s)
[60.058358] Subtest flip-64bpp-yftile-to-32bpp-yftile-downscaling: SKIP (0.090s)
[60.251088] [002/133] (959s left) gem_exec_schedule (out-order)
[60.349556] Starting subtest: out-order
[60.350666] Starting dynamic subtest: rcs0
[60.624177] Dynamic subtest rcs0: SUCCESS (0.263s)
[60.631513] Starting dynamic subtest: bcs0
[60.812410] Dynamic subtest bcs0: SUCCESS (0.188s)
[60.819627] Starting dynamic subtest: vcs0
[61.007581] Dynamic subtest vcs0: SUCCESS (0.203s)
[61.014720] Starting dynamic subtest: vcs1
[61.206372] Dynamic subtest vcs1: SUCCESS (0.198s)
[61.213426] Starting dynamic subtest: vecs0
[61.407659] Dynamic subtest vecs0: SUCCESS (0.200s)
[61.414801] Starting dynamic subtest: vecs1
[61.607387] Dynamic subtest vecs1: SUCCESS (0.199s)
[61.614552] Starting dynamic subtest: ccs0
[61.804786] Dynamic subtest ccs0: SUCCESS (0.196s)
[61.805891] Subtest out-order: SUCCESS (1.449s)
[62.225086] [003/133] (958s left) kms_flip (dpms-off-confusion)
[62.426026] Starting subtest: dpms-off-confusion
[62.429602] Starting dynamic subtest: A-DP4
[70.292812] Dynamic subtest A-DP4: SUCCESS (7.845s)
[70.302230] Starting dynamic subtest: B-DP4
[78.052238] Dynamic subtest B-DP4: SUCCESS (7.752s)
[78.062638] Starting dynamic subtest: C-DP4
[85.813569] Dynamic subtest C-DP4: SUCCESS (7.754s)
[85.838902] Starting dynamic subtest: D-DP4
[93.608610] Dynamic subtest D-DP4: SUCCESS (7.790s)
[93.609535] Subtest dpms-off-confusion: SUCCESS (31.152s)
[93.939354] [004/133] (926s left) gem_ctx_engines (execute-allforone)
[94.025117] Starting subtest: execute-allforone
[94.060272] Subtest execute-allforone: SUCCESS (0.035s)
[94.359178] [005/133] (926s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[94.555431] Starting subtest: fb-8bpc-vs-panel-8bpc
[94.556341] Starting dynamic subtest: pipe-A-DP-4
[94.981751] Dynamic subtest pipe-A-DP-4: SUCCESS (0.424s)
[94.983680] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (0.425s)
[95.213328] [006/133] (925s left) i915_pm_rc6_residency (rc6-accuracy)
[95.282046] Starting subtest: rc6-accuracy
[95.282907] Starting dynamic subtest: gt0
[98.455888] Dynamic subtest gt0: SUCCESS (3.170s)
[98.456821] Subtest rc6-accuracy: SUCCESS (3.170s)
[98.558648] [007/133] (922s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[98.765919] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (0.000s)
[98.806733] [008/133] (921s left) kms_plane_scaling (planes-upscale-20x20)
[99.007925] Starting subtest: planes-upscale-20x20
[99.008672] Starting dynamic subtest: pipe-A
[99.123813] Dynamic subtest pipe-A: SUCCESS (0.116s)
[99.138561] Starting dynamic subtest: pipe-B
[99.343589] Dynamic subtest pipe-B: SUCCESS (0.220s)
[99.354243] Starting dynamic subtest: pipe-C
[99.565345] Dynamic subtest pipe-C: SUCCESS (0.222s)
[99.573951] Starting dynamic subtest: pipe-D
[99.768420] Dynamic subtest pipe-D: SUCCESS (0.203s)
[99.769290] Subtest planes-upscale-20x20: SUCCESS (0.761s)
[100.064233] [009/133] (920s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[100.279294] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[100.281040] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[100.429473] [010/133] (920s left) kms_chamelium_hpd (hdmi-hpd)
[100.714731] Subtest hdmi-hpd: SKIP (0.000s)
[100.869541] [011/133] (920s left) kms_feature_discovery (display)
[101.067515] Starting subtest: display
[101.068419] Subtest display: SUCCESS (0.000s)
[101.159346] [012/133] (919s left) kms_psr (pr-sprite-blt)
[101.365459] Subtest pr-sprite-blt: SKIP (0.000s)
[101.417909] [013/133] (919s left) gem_exec_whisper (basic-contexts)
[101.502053] Starting subtest: basic-contexts
[111.441896] Subtest basic-contexts: SUCCESS (9.924s)
[111.676597] [014/133] (909s left) gem_exec_schedule (thriceslice)
[111.765452] Starting subtest: thriceslice
[111.766407] Starting dynamic subtest: rcs0
[111.794222] Dynamic subtest rcs0: SUCCESS (0.029s)
[111.802013] Starting dynamic subtest: bcs0
[111.824245] Dynamic subtest bcs0: SUCCESS (0.030s)
[111.833198] Starting dynamic subtest: vcs0
[111.854363] Dynamic subtest vcs0: SUCCESS (0.030s)
[111.867639] Starting dynamic subtest: vcs1
[111.889272] Dynamic subtest vcs1: SUCCESS (0.034s)
[111.907316] Starting dynamic subtest: vecs0
[111.927604] Dynamic subtest vecs0: SUCCESS (0.038s)
[111.945527] Starting dynamic subtest: vecs1
[111.988688] Dynamic subtest vecs1: SUCCESS (0.060s)
[112.018593] Starting dynamic subtest: ccs0
[112.037548] Dynamic subtest ccs0: SUCCESS (0.049s)
[112.038756] Subtest thriceslice: SUCCESS (0.272s)
[112.415746] [015/133] (908s left) gem_exec_gttfill (engines)
[112.500977] Starting subtest: engines
[112.501916] Starting dynamic subtest: rcs0
[138.158716] Dynamic subtest rcs0: SUCCESS (25.654s)
[138.166621] Starting dynamic subtest: bcs0
[163.873181] Dynamic subtest bcs0: SUCCESS (25.714s)
[163.881140] Starting dynamic subtest: vcs0
[189.594782] Dynamic subtest vcs0: SUCCESS (25.721s)
[189.602785] Starting dynamic subtest: vcs1
[215.862061] Dynamic subtest vcs1: SUCCESS (26.266s)
[215.870134] Starting dynamic subtest: vecs0
[241.194926] Dynamic subtest vecs0: SUCCESS (25.331s)
[241.203065] Starting dynamic subtest: vecs1
[266.939631] Dynamic subtest vecs1: SUCCESS (25.743s)
[266.947529] Starting dynamic subtest: ccs0
[292.619914] Dynamic subtest ccs0: SUCCESS (25.680s)
[292.620927] Subtest engines: SUCCESS (180.111s)
[292.874281] [016/133] (728s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[293.111609] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[293.113393] Subtest y-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[293.252086] [017/133] (727s left) kms_getfb (getfb-handle-protection)
[293.334031] Starting subtest: getfb-handle-protection
[293.351732] Subtest getfb-handle-protection: SUCCESS (0.018s)
[293.477349] [018/133] (727s left) vkms/vkms_configfs (enable-plane-no-crtcs)
[293.628210] Subtest enable-plane-no-crtcs: SKIP (0.000s)
[293.690376] [019/133] (727s left) syncobj_wait (single-wait-for-submit-submitted)
[293.767119] Starting subtest: single-wait-for-submit-submitted
[293.869992] Subtest single-wait-for-submit-submitted: SUCCESS (0.102s)
[294.010551] [020/133] (727s left) dumb_buffer (map-uaf)
[294.087034] Starting subtest: map-uaf
[294.110828] Subtest map-uaf: SUCCESS (0.024s)
[294.243309] [021/133] (727s left) kms_psr (fbc-psr-sprite-plane-move)
[294.449491] Subtest fbc-psr-sprite-plane-move: SKIP (0.000s)
[294.502899] [022/133] (726s left) perf_pmu (multi-client)
[294.575478] Starting subtest: multi-client
[294.576427] Starting dynamic subtest: rcs0
[295.652347] Dynamic subtest rcs0: SUCCESS (1.075s)
[295.665250] Starting dynamic subtest: bcs0
[296.702574] Dynamic subtest bcs0: SUCCESS (1.049s)
[296.715819] Starting dynamic subtest: vcs0
[297.754842] Dynamic subtest vcs0: SUCCESS (1.051s)
[297.793676] Starting dynamic subtest: vcs1
[298.805633] Dynamic subtest vcs1: SUCCESS (1.050s)
[298.819128] Starting dynamic subtest: vecs0
[299.856257] Dynamic subtest vecs0: SUCCESS (1.050s)
[299.869240] Starting dynamic subtest: vecs1
[300.906294] Dynamic subtest vecs1: SUCCESS (1.049s)
[300.919206] Starting dynamic subtest: ccs0
[301.955963] Dynamic subtest ccs0: SUCCESS (1.049s)
[301.958056] Subtest multi-client: SUCCESS (7.379s)
[302.115693] [023/133] (719s left) kms_psr (psr-cursor-mmap-cpu)
[302.319522] Subtest psr-cursor-mmap-cpu: SKIP (0.000s)
[302.370071] [024/133] (719s left) gem_basic (create-close)
[302.446967] Starting subtest: create-close
[302.449022] Subtest create-close: SUCCESS (0.000s)
[302.603572] [025/133] (719s left) gem_userptr_blits (readonly-pwrite-unsync)
[302.686864] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[302.888640] [026/133] (718s left) i915_query (hwconfig_table)
[302.968961] Starting subtest: hwconfig_table
[303.157866] Subtest hwconfig_table: SUCCESS (0.001s)
[303.240079] [027/133] (718s left) gem_userptr_blits (map-fixed-invalidate-busy)
[303.339484] Starting subtest: map-fixed-invalidate-busy
[303.341313] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[303.483056] [028/133] (718s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-wc)
[303.695767] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-wc
[303.697527] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[303.836537] [029/133] (718s left) kms_psr2_sf (pr-overlay-plane-move-continuous-sf)
[304.046432] Subtest pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[304.107439] [030/133] (717s left) kms_flip (plain-flip-interruptible)
[304.320640] Starting subtest: plain-flip-interruptible
[304.325759] Starting dynamic subtest: A-DP4
[305.167655] Dynamic subtest A-DP4: SUCCESS (0.841s)
[305.179500] Starting dynamic subtest: B-DP4
[305.899744] Dynamic subtest B-DP4: SUCCESS (0.731s)
[305.909067] Starting dynamic subtest: C-DP4
[306.638756] Dynamic subtest C-DP4: SUCCESS (0.739s)
[306.648628] Starting dynamic subtest: D-DP4
[307.371986] Dynamic subtest D-DP4: SUCCESS (0.732s)
[307.376314] Subtest plain-flip-interruptible: SUCCESS (3.053s)
[307.702406] [031/133] (714s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[307.915228] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[307.917008] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[308.032192] [032/133] (714s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[308.242393] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[308.244124] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[308.339511] [033/133] (713s left) gem_userptr_blits (coherency-unsync)
[308.425277] Subtest coherency-unsync: SKIP (0.000s)
[308.628517] [034/133] (713s left) gem_madvise (dontneed-before-exec)
[308.677416] Starting subtest: dontneed-before-exec
[308.705075] Subtest dontneed-before-exec: SKIP (0.027s)
[308.763339] [035/133] (713s left) syncobj_wait (wait-all-delayed-signal)
[308.839536] Starting subtest: wait-all-delayed-signal
[308.941312] Subtest wait-all-delayed-signal: SUCCESS (0.101s)
[309.045667] [036/133] (713s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs-cc)
[309.249513] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs-cc
[309.250403] Starting dynamic subtest: pipe-A-DP-4
[309.259156] Dynamic subtest pipe-A-DP-4: SKIP (0.000s)
[309.268195] Starting dynamic subtest: pipe-B-DP-4
[309.276885] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[309.285665] Starting dynamic subtest: pipe-C-DP-4
[309.294027] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[309.302480] Starting dynamic subtest: pipe-D-DP-4
[309.311610] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[309.313742] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs-cc: SKIP (0.004s)
[309.697236] [037/133] (712s left) gem_softpin (evict-snoop-interruptible)
[309.784232] Starting subtest: evict-snoop-interruptible
[309.786136] Subtest evict-snoop-interruptible: SKIP (0.000s)
[309.923963] [038/133] (712s left) kms_chamelium_audio (dp-audio-edid)
[310.204570] Subtest dp-audio-edid: SKIP (0.000s)
[310.358970] [039/133] (712s left) kms_psr (fbc-pr-sprite-blt)
[310.569520] Subtest fbc-pr-sprite-blt: SKIP (0.000s)
[310.622724] [040/133] (712s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-yf-tiled)
[310.710435] Starting subtest: yf-tiled-mc-ccs-to-vebox-yf-tiled
[310.712382] Subtest yf-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[310.984364] [041/133] (711s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-plflip-blt)
[311.193672] Starting subtest: fbcpsr-1p-primscrn-indfb-plflip-blt
[311.195298] Subtest fbcpsr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[311.338739] [042/133] (711s left) gem_pxp (display-protected-crc)
[313.959532] Starting subtest: display-protected-crc
[313.962295] Subtest display-protected-crc: SKIP (0.000s)
[314.052179] [043/133] (708s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[314.259964] Starting subtest: basic-flip-after-cursor-varying-size
[315.272568] Subtest basic-flip-after-cursor-varying-size: SUCCESS (1.012s)
[315.548404] [044/133] (707s left) gem_exec_reloc (basic-wc)
[315.632151] Subtest basic-wc: SKIP (0.000s)
[315.690816] [045/133] (707s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling)
[315.900971] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling
[315.901903] Starting dynamic subtest: pipe-A-valid-mode
[318.414989] Dynamic subtest pipe-A-valid-mode: SUCCESS (2.511s)
[318.416748] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-downscaling: SUCCESS (2.513s)
[318.670641] [046/133] (704s left) kms_plane (plane-position-hole)
[318.881159] Starting subtest: plane-position-hole
[319.284370] Starting dynamic subtest: pipe-A-plane-1
[319.755252] Dynamic subtest pipe-A-plane-1: SUCCESS (0.470s)
[319.765654] Starting dynamic subtest: pipe-A-plane-2
[320.196563] Dynamic subtest pipe-A-plane-2: SUCCESS (0.441s)
[320.207867] Starting dynamic subtest: pipe-A-plane-3
[320.638060] Dynamic subtest pipe-A-plane-3: SUCCESS (0.441s)
[320.650478] Starting dynamic subtest: pipe-A-plane-4
[321.069508] Dynamic subtest pipe-A-plane-4: SUCCESS (0.430s)
[321.090262] Starting dynamic subtest: pipe-A-plane-5
[321.099786] Dynamic subtest pipe-A-plane-5: SUCCESS (0.030s)
[321.449497] Starting dynamic subtest: pipe-B-plane-1
[321.851436] Dynamic subtest pipe-B-plane-1: SUCCESS (0.412s)
[321.863079] Starting dynamic subtest: pipe-B-plane-2
[322.291975] Dynamic subtest pipe-B-plane-2: SUCCESS (0.440s)
[322.301235] Starting dynamic subtest: pipe-B-plane-3
[322.732084] Dynamic subtest pipe-B-plane-3: SUCCESS (0.439s)
[322.741408] Starting dynamic subtest: pipe-B-plane-4
[323.166737] Dynamic subtest pipe-B-plane-4: SUCCESS (0.435s)
[323.175997] Starting dynamic subtest: pipe-B-plane-5
[323.182186] Dynamic subtest pipe-B-plane-5: SUCCESS (0.015s)
[323.183074] Subtest plane-position-hole: SUCCESS (4.301s)
[323.435394] [047/133] (699s left) kms_flip (flip-vs-expired-vblank)
[323.645949] Starting subtest: flip-vs-expired-vblank
[323.650612] Starting dynamic subtest: A-DP4
[331.510133] Dynamic subtest A-DP4: SUCCESS (7.858s)
[331.522533] Starting dynamic subtest: B-DP4
[339.265523] Dynamic subtest B-DP4: SUCCESS (7.752s)
[339.303427] Starting dynamic subtest: C-DP4
[347.055580] Dynamic subtest C-DP4: SUCCESS (7.786s)
[347.072817] Starting dynamic subtest: D-DP4
[354.838142] Dynamic subtest D-DP4: SUCCESS (7.780s)
[354.839072] Subtest flip-vs-expired-vblank: SUCCESS (31.193s)
[355.210692] [048/133] (668s left) kms_vrr (max-min)
[355.416663] Starting subtest: max-min
[355.419266] Subtest max-min: SKIP (0.000s)
[355.502401] [049/133] (667s left) kms_color (legacy-gamma)
[355.711687] Starting subtest: legacy-gamma
[355.712574] Starting dynamic subtest: pipe-A-DP-4
[356.401542] Dynamic subtest pipe-A-DP-4: SUCCESS (0.689s)
[356.410641] Starting dynamic subtest: pipe-B-DP-4
[357.007387] Dynamic subtest pipe-B-DP-4: SUCCESS (0.606s)
[357.022746] Starting dynamic subtest: pipe-C-DP-4
[357.610989] Dynamic subtest pipe-C-DP-4: SUCCESS (0.602s)
[357.622062] Starting dynamic subtest: pipe-D-DP-4
[358.226662] Dynamic subtest pipe-D-DP-4: SUCCESS (0.615s)
[358.227667] Subtest legacy-gamma: SUCCESS (2.515s)
[358.518832] [050/133] (664s left) kms_psr (fbc-psr-no-drrs)
[358.723416] Subtest fbc-psr-no-drrs: SKIP (0.000s)
[358.783693] [051/133] (664s left) kms_flip (wf_vblank-ts-check)
[358.989250] Starting subtest: wf_vblank-ts-check
[358.994199] Starting dynamic subtest: A-DP4
[367.105485] Dynamic subtest A-DP4: SUCCESS (8.111s)
[367.116304] Starting dynamic subtest: B-DP4
[375.171565] Dynamic subtest B-DP4: SUCCESS (8.065s)
[375.181326] Starting dynamic subtest: C-DP4
[383.209556] Dynamic subtest C-DP4: SUCCESS (8.037s)
[383.219973] Starting dynamic subtest: D-DP4
[391.248758] Dynamic subtest D-DP4: SUCCESS (8.038s)
[391.249634] Subtest wf_vblank-ts-check: SUCCESS (32.259s)
[391.586902] [052/133] (631s left) gem_exec_fence (basic-wait)
[391.792999] Starting subtest: basic-wait
[391.793972] Starting dynamic subtest: rcs0
[391.905867] Dynamic subtest rcs0: SUCCESS (0.112s)
[391.920186] Starting dynamic subtest: bcs0
[391.958638] Dynamic subtest bcs0: SUCCESS (0.051s)
[391.972181] Starting dynamic subtest: vcs0
[392.015120] Dynamic subtest vcs0: SUCCESS (0.055s)
[392.056230] Starting dynamic subtest: vcs1
[392.074702] Dynamic subtest vcs1: SUCCESS (0.058s)
[392.090320] Starting dynamic subtest: vecs0
[392.129362] Dynamic subtest vecs0: SUCCESS (0.053s)
[392.160227] Starting dynamic subtest: vecs1
[392.182902] Dynamic subtest vecs1: SUCCESS (0.052s)
[392.201847] Starting dynamic subtest: ccs0
[392.233973] Dynamic subtest ccs0: SUCCESS (0.050s)
[392.235369] Subtest basic-wait: SUCCESS (0.441s)
[393.075810] [FACT gem_exec_fence (basic-wait)] new: kernel.kmod_is_loaded.vgem: true
[393.077966] [053/133] (630s left) gem_userptr_blits (mmap-offset-banned)
[393.183232] Starting subtest: mmap-offset-banned
[393.184173] Starting dynamic subtest: fixed
[393.185092] Dynamic subtest fixed: SUCCESS (0.000s)
[393.185952] Subtest mmap-offset-banned: SUCCESS (0.001s)
[393.376971] [054/133] (630s left) kms_legacy_colorkey (invalid-plane)
[393.589164] Starting subtest: invalid-plane
[393.590126] Starting dynamic subtest: zero-id
[393.590988] Dynamic subtest zero-id: SUCCESS (0.000s)
[393.600315] Starting dynamic subtest: outrange-id
[393.601235] Dynamic subtest outrange-id: SUCCESS (0.000s)
[393.602136] Subtest invalid-plane: SUCCESS (0.001s)
[393.681432] [055/133] (630s left) api_intel_allocator (reopen)
[393.767213] Starting subtest: reopen
[393.789908] Subtest reopen: SUCCESS (0.023s)
[393.991337] [056/133] (629s left) gem_ctx_persistence (engines-mixed)
[394.083215] Starting subtest: engines-mixed
[394.084654] Starting dynamic subtest: rcs0
[394.190522] Dynamic subtest rcs0: SUCCESS (0.107s)
[394.202238] Starting dynamic subtest: bcs0
[394.252758] Dynamic subtest bcs0: SUCCESS (0.061s)
[394.274818] Starting dynamic subtest: vcs0
[394.319818] Dynamic subtest vcs0: SUCCESS (0.065s)
[394.359692] Starting dynamic subtest: vcs1
[394.401559] Dynamic subtest vcs1: SUCCESS (0.080s)
[394.423500] Starting dynamic subtest: vecs0
[394.470517] Dynamic subtest vecs0: SUCCESS (0.067s)
[394.513209] Starting dynamic subtest: vecs1
[394.541534] Dynamic subtest vecs1: SUCCESS (0.069s)
[394.557878] Starting dynamic subtest: ccs0
[394.604742] Dynamic subtest ccs0: SUCCESS (0.062s)
[394.606050] Subtest engines-mixed: SUCCESS (0.522s)
[394.790888] [057/133] (629s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-mmap-wc)
[395.009895] Starting subtest: fbc-1p-offscren-pri-indfb-draw-mmap-wc
[395.011644] Subtest fbc-1p-offscren-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[395.160420] [058/133] (628s left) kms_big_fb (linear-8bpp-rotate-90)
[395.387034] Starting subtest: linear-8bpp-rotate-90
[395.435549] Subtest linear-8bpp-rotate-90: SKIP (0.048s)
[395.637527] [059/133] (628s left) kms_addfb_basic (basic)
[395.729415] Starting subtest: basic
[395.730382] Subtest basic: SUCCESS (0.000s)
[396.013007] [060/133] (628s left) perf_pmu (busy-start)
[396.091879] Starting subtest: busy-start
[396.092873] Starting dynamic subtest: rcs0
[398.663024] Dynamic subtest rcs0: SUCCESS (2.570s)
[398.677402] Starting dynamic subtest: bcs0
[401.211988] Dynamic subtest bcs0: SUCCESS (2.546s)
[401.224652] Starting dynamic subtest: vcs0
[403.761098] Dynamic subtest vcs0: SUCCESS (2.546s)
[403.775896] Starting dynamic subtest: vcs1
[406.311055] Dynamic subtest vcs1: SUCCESS (2.547s)
[406.328194] Starting dynamic subtest: vecs0
[408.861118] Dynamic subtest vecs0: SUCCESS (2.547s)
[408.878406] Starting dynamic subtest: vecs1
[411.409911] Dynamic subtest vecs1: SUCCESS (2.546s)
[411.427393] Starting dynamic subtest: ccs0
[413.960053] Dynamic subtest ccs0: SUCCESS (2.547s)
[413.962129] Subtest busy-start: SUCCESS (17.868s)
[414.086974] [061/133] (610s left) kms_psr2_sf (fbc-pr-cursor-plane-update-sf)
[414.312331] Subtest fbc-pr-cursor-plane-update-sf: SKIP (0.000s)
[414.361875] [062/133] (609s left) gem_mmap_gtt (medium-copy-odd)
[414.448182] Subtest medium-copy-odd: SKIP (0.000s)
[414.512909] [063/133] (609s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-pgflip-blt)
[414.732501] Starting subtest: fbcpsr-2p-primscrn-indfb-pgflip-blt
[414.734250] Subtest fbcpsr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[414.869553] [064/133] (609s left) kms_addfb_basic (addfb25-framebuffer-vs-set-tiling)
[415.084975] Subtest addfb25-framebuffer-vs-set-tiling: SKIP (0.000s)
[415.252077] [065/133] (609s left) gen9_exec_parse (batch-zero-length)
[415.346195] Subtest batch-zero-length: SKIP (0.000s)
[415.434025] [066/133] (609s left) kms_content_protection (uevent)
[415.700699] Starting subtest: uevent
[415.737618] Starting dynamic subtest: pipe-A-DP-4
[450.051462] Dynamic subtest pipe-A-DP-4: FAIL (34.260s)
[450.097240] Subtest uevent: FAIL (34.394s)
[450.387906] [067/133] (574s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[450.474796] Starting subtest: etime-single-wait-all-for-submit-submitted
[450.584510] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.107s)
[450.711284] [068/133] (573s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[450.796935] Starting subtest: etime-multi-wait-all-for-submit-submitted
[450.901767] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.104s)
[451.016682] [069/133] (573s left) kms_rotation_crc (primary-x-tiled-reflect-x-180)
[451.232919] Starting subtest: primary-x-tiled-reflect-x-180
[461.687885] Subtest primary-x-tiled-reflect-x-180: SUCCESS (10.454s)
[462.043260] [070/133] (562s left) vkms/vkms_configfs (encoder-default-files)
[462.114239] Subtest encoder-default-files: SKIP (0.000s)
[462.182478] [071/133] (562s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[462.394294] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[462.395206] Starting dynamic subtest: pipe-A-DP-4
[462.403890] Dynamic subtest pipe-A-DP-4: SKIP (0.001s)
[462.413244] Starting dynamic subtest: pipe-B-DP-4
[462.421849] Dynamic subtest pipe-B-DP-4: SKIP (0.001s)
[462.431130] Starting dynamic subtest: pipe-C-DP-4
[462.439684] Dynamic subtest pipe-C-DP-4: SKIP (0.001s)
[462.448780] Starting dynamic subtest: pipe-D-DP-4
[462.458356] Dynamic subtest pipe-D-DP-4: SKIP (0.001s)
[462.460470] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SKIP (0.008s)
[462.845929] [072/133] (562s left) gem_ctx_persistence (legacy-engines-mixed-process)
[462.937394] Starting subtest: legacy-engines-mixed-process
[462.938300] Starting dynamic subtest: render
[464.937825] Dynamic subtest render: SUCCESS (1.999s)
[464.950630] Starting dynamic subtest: bsd1
[466.912095] Dynamic subtest bsd1: SUCCESS (1.972s)
[466.925167] Starting dynamic subtest: bsd2
[468.962184] Dynamic subtest bsd2: SUCCESS (2.048s)
[468.975155] Starting dynamic subtest: blt
[470.947998] Dynamic subtest blt: SUCCESS (1.984s)
[470.960041] Starting dynamic subtest: vebox
[472.927327] Dynamic subtest vebox: SUCCESS (1.978s)
[472.928502] Subtest legacy-engines-mixed-process: SUCCESS (9.990s)
[473.046842] [073/133] (551s left) gem_mmap_gtt (hang-busy)
[473.138528] Subtest hang-busy: SKIP (0.000s)
[473.219795] [074/133] (551s left) kms_flip (2x-nonexisting-fb)
[473.439163] Starting subtest: 2x-nonexisting-fb
[473.451462] Subtest 2x-nonexisting-fb: SKIP (0.013s)
[473.565807] [075/133] (551s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-onoff)
[473.795143] Starting subtest: psr-2p-primscrn-spr-indfb-onoff
[473.796852] Subtest psr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[473.923839] [076/133] (551s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[474.158863] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[474.160584] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[474.293798] [077/133] (550s left) i915_query (query-topology-kernel-writes)
[474.389345] Starting subtest: query-topology-kernel-writes
[474.390297] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[474.566048] [078/133] (550s left) gem_eio (wait-immediate)
[474.872092] Starting subtest: wait-immediate
[475.468190] Subtest wait-immediate: SUCCESS (0.597s)
[475.689490] [079/133] (549s left) gem_linear_blits (basic)
[475.957646] Starting subtest: basic
[475.985940] Subtest basic: SUCCESS (0.030s)
[476.154678] [080/133] (549s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[476.415935] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[476.417750] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[476.571433] [081/133] (548s left) i915_pm_rps (thresholds-idle)
[476.670917] Starting subtest: thresholds-idle
[476.672997] Subtest thresholds-idle: SKIP (0.001s)
[476.775988] [082/133] (548s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[476.999921] Starting subtest: yf-tiled-64bpp-rotate-90
[477.001660] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[477.158972] [083/133] (548s left) kms_chamelium_color (degamma)
[477.372885] Subtest degamma: SKIP (0.000s)
[477.434839] [084/133] (548s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[477.529302] Starting subtest: single-wait-all-for-submit-available-signaled
[477.530261] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[477.634987] [085/133] (548s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt)
[477.851577] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt
[477.853277] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[478.002366] [086/133] (547s left) gem_lmem_swapping (verify-ccs)
[480.768699] Starting subtest: verify-ccs
[480.770875] Starting dynamic subtest: lmem0
[516.616500] Dynamic subtest lmem0: SUCCESS (35.843s)
[516.617429] Subtest verify-ccs: SUCCESS (35.845s)
[518.856022] [FACT gem_lmem_swapping (verify-ccs)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[518.857018] [FACT gem_lmem_swapping (verify-ccs)] deleted: kernel.kmod_is_loaded.i915: true
[518.858968] [087/133] (507s left) kms_vblank (invalid)
[523.285663] Starting subtest: invalid
[523.287904] Starting dynamic subtest: pipe-A-DP-4
[523.595222] Dynamic subtest pipe-A-DP-4: SUCCESS (0.307s)
[523.596401] Subtest invalid: SUCCESS (0.310s)
[523.860439] [FACT kms_vblank (invalid)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[523.864981] [FACT kms_vblank (invalid)] new: kernel.kmod_is_loaded.amdgpu: true
[523.865117] [FACT kms_vblank (invalid)] new: kernel.kmod_is_loaded.i915: true
[523.865969] [FACT kms_vblank (invalid)] new: kernel.kmod_is_loaded.xe: true
[523.868120] [088/133] (502s left) kms_selftest (drm_cmdline_parser)
[523.923193] Starting subtest: drm_cmdline_parser
[523.929956] Starting dynamic subtest: drm_test_cmdline_force_d_only
[524.051135] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.118s)
[524.064745] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[524.150571] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.097s)
[524.188546] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[524.252646] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.098s)
[524.292077] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[524.359736] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.103s)
[524.395741] Starting dynamic subtest: drm_test_cmdline_force_e_only
[524.453570] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.090s)
[524.490062] Starting dynamic subtest: drm_test_cmdline_res
[524.551954] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.095s)
[524.564766] Starting dynamic subtest: drm_test_cmdline_res_vesa
[524.648590] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.092s)
[524.659515] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[524.749614] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.098s)
[524.788345] Starting dynamic subtest: drm_test_cmdline_res_rblank
[524.862516] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.108s)
[524.875322] Starting dynamic subtest: drm_test_cmdline_res_bpp
[524.957743] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.092s)
[524.997393] Starting dynamic subtest: drm_test_cmdline_res_refresh
[525.052541] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.091s)
[525.089906] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[525.150597] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.094s)
[525.188799] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[525.251840] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.097s)
[525.291543] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[525.363691] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.108s)
[525.402550] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[525.453768] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.087s)
[525.490196] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[525.551877] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.094s)
[525.565448] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[525.661758] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.105s)
[525.700022] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[525.742426] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.078s)
[525.771969] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[525.845417] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.101s)
[525.855498] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[525.956570] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.109s)
[525.967710] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[526.065599] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.106s)
[526.077258] Starting dynamic subtest: drm_test_cmdline_name
[526.155727] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.087s)
[526.196093] Starting dynamic subtest: drm_test_cmdline_name_bpp
[526.269280] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.110s)
[526.308194] Starting dynamic subtest: drm_test_cmdline_name_option
[526.383477] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.109s)
[526.422610] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[526.490193] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.104s)
[526.528746] Starting dynamic subtest: drm_test_cmdline_rotate_0
[526.588355] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.093s)
[526.628023] Starting dynamic subtest: drm_test_cmdline_rotate_90
[526.680823] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.088s)
[526.722631] Starting dynamic subtest: drm_test_cmdline_rotate_180
[526.789287] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.105s)
[526.828519] Starting dynamic subtest: drm_test_cmdline_rotate_270
[526.895216] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.102s)
[526.936774] Starting dynamic subtest: drm_test_cmdline_hmirror
[526.995102] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.096s)
[527.033895] Starting dynamic subtest: drm_test_cmdline_vmirror
[527.112081] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.108s)
[527.147940] Starting dynamic subtest: drm_test_cmdline_margin_options
[527.215355] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.100s)
[527.252881] Starting dynamic subtest: drm_test_cmdline_multiple_options
[527.322312] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.107s)
[527.364073] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[527.442196] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.115s)
[527.455180] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[527.549188] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.102s)
[527.592439] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[527.661076] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.108s)
[527.672381] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[527.746121] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.081s)
[527.785789] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[527.854346] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.104s)
[527.896440] Starting dynamic subtest: drm_test_cmdline_invalid
[527.994697] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.132s)
[528.023215] Starting dynamic subtest: drm_test_cmdline_tv_options
[528.122705] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.123s)
[528.124577] Subtest drm_cmdline_parser: SUCCESS (4.196s)
[528.362163] [089/133] (497s left) gem_exec_reloc (basic-cpu-wc-noreloc)
[528.471300] Subtest basic-cpu-wc-noreloc: SKIP (0.000s)
[528.542112] [090/133] (497s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[528.759217] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[528.760971] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[528.877969] [091/133] (497s left) gem_eio (create)
[529.136943] Starting subtest: create
[529.484504] Subtest create: SUCCESS (0.348s)
[529.721630] [092/133] (496s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[529.947401] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[529.949131] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[530.055557] [093/133] (496s left) kms_cursor_edge_walk (256x256-left-edge)
[530.272296] Starting subtest: 256x256-left-edge
[530.273205] Starting dynamic subtest: pipe-A-DP-4
[533.764686] Dynamic subtest pipe-A-DP-4: SUCCESS (3.491s)
[533.780574] Starting dynamic subtest: pipe-D-DP-4
[537.100533] Dynamic subtest pipe-D-DP-4: SUCCESS (3.332s)
[537.101538] Subtest 256x256-left-edge: SUCCESS (6.828s)
[537.355289] [094/133] (489s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[537.578356] Starting subtest: flip-vs-cursor-legacy
[546.977018] Subtest flip-vs-cursor-legacy: SUCCESS (9.398s)
[547.238558] [095/133] (479s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-blt)
[547.455939] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-blt
[547.457748] Subtest fbc-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[547.596480] [096/133] (478s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt)
[547.826064] Starting subtest: psr-rgb565-draw-blt
[547.827829] Subtest psr-rgb565-draw-blt: SKIP (0.000s)
[547.961722] [097/133] (478s left) gem_mmap (pf-nonblock)
[548.045388] Subtest pf-nonblock: SKIP (0.000s)
[548.122613] [098/133] (478s left) gem_tiled_partial_pwrite_pread (reads)
[548.213920] Subtest reads: SKIP (0.000s)
[548.314909] [099/133] (478s left) kms_async_flips (test-cursor-atomic)
[548.531324] Starting subtest: test-cursor-atomic
[548.762557] Starting dynamic subtest: pipe-A-DP-4
[549.047111] Dynamic subtest pipe-A-DP-4: SUCCESS (0.284s)
[549.288148] Starting dynamic subtest: pipe-B-DP-4
[549.393433] Dynamic subtest pipe-B-DP-4: SUCCESS (0.116s)
[549.603257] Starting dynamic subtest: pipe-C-DP-4
[549.715475] Dynamic subtest pipe-C-DP-4: SUCCESS (0.121s)
[549.922713] Starting dynamic subtest: pipe-D-DP-4
[550.046275] Dynamic subtest pipe-D-DP-4: SUCCESS (0.133s)
[550.047338] Subtest test-cursor-atomic: SUCCESS (1.516s)
[550.551051] [100/133] (476s left) gem_exec_reloc (basic-wc-cpu-active)
[550.642743] Subtest basic-wc-cpu-active: SKIP (0.000s)
[550.720167] [101/133] (476s left) syncobj_timeline (multi-wait-available-submitted)
[550.803993] Starting subtest: multi-wait-available-submitted
[550.804952] Subtest multi-wait-available-submitted: SUCCESS (0.001s)
[550.948414] [102/133] (475s left) kms_cursor_crc (cursor-random-128x128)
[551.293531] Starting subtest: cursor-random-128x128
[551.298440] Starting dynamic subtest: pipe-A-DP-4
[555.627077] Dynamic subtest pipe-A-DP-4: SUCCESS (4.328s)
[555.677305] Starting dynamic subtest: pipe-D-DP-4
[560.145341] Dynamic subtest pipe-D-DP-4: SUCCESS (4.478s)
[560.146268] Subtest cursor-random-128x128: SUCCESS (8.852s)
[560.598087] [103/133] (466s left) gem_exec_schedule (implicit-write-read)
[560.698682] Starting subtest: implicit-write-read
[560.699585] Starting dynamic subtest: rcs0
[560.975230] Dynamic subtest rcs0: SUCCESS (0.276s)
[560.985447] Starting dynamic subtest: bcs0
[561.165358] Dynamic subtest bcs0: SUCCESS (0.189s)
[561.175450] Starting dynamic subtest: vcs0
[561.368052] Dynamic subtest vcs0: SUCCESS (0.202s)
[561.378201] Starting dynamic subtest: vcs1
[561.572504] Dynamic subtest vcs1: SUCCESS (0.203s)
[561.582501] Starting dynamic subtest: vecs0
[561.774374] Dynamic subtest vecs0: SUCCESS (0.201s)
[561.784125] Starting dynamic subtest: vecs1
[561.999032] Dynamic subtest vecs1: SUCCESS (0.224s)
[562.009937] Starting dynamic subtest: ccs0
[562.222349] Dynamic subtest ccs0: SUCCESS (0.222s)
[562.223322] Subtest implicit-write-read: SUCCESS (1.524s)
[562.608874] [104/133] (464s left) gem_bad_reloc (negative-reloc-lut)
[562.700196] Subtest negative-reloc-lut: SKIP (0.000s)
[562.781723] [105/133] (464s left) kms_big_fb (x-tiled-addfb-size-offset-overflow)
[563.003689] Starting subtest: x-tiled-addfb-size-offset-overflow
[563.004572] Subtest x-tiled-addfb-size-offset-overflow: SUCCESS (0.001s)
[563.143460] [106/133] (464s left) kms_hdmi_inject (inject-4k)
[563.319865] Starting subtest: inject-4k
[563.487691] Subtest inject-4k: SUCCESS (0.168s)
[563.838472] [107/133] (463s left) kms_pm_backlight (fade)
[564.054081] Subtest fade: SKIP (0.000s)
[564.115882] [108/133] (463s left) prime_vgem (sync)
[564.253154] Starting subtest: sync
[564.254142] Starting dynamic subtest: rcs0
[564.311822] Dynamic subtest rcs0: SUCCESS (0.058s)
[564.322696] Starting dynamic subtest: bcs0
[564.357968] Dynamic subtest bcs0: SUCCESS (0.045s)
[564.370550] Starting dynamic subtest: vcs0
[564.404930] Dynamic subtest vcs0: SUCCESS (0.045s)
[564.429394] Starting dynamic subtest: vcs1
[564.449566] Dynamic subtest vcs1: SUCCESS (0.042s)
[564.474543] Starting dynamic subtest: vecs0
[564.492126] Dynamic subtest vecs0: SUCCESS (0.040s)
[564.512381] Starting dynamic subtest: vecs1
[564.533277] Dynamic subtest vecs1: SUCCESS (0.040s)
[564.548970] Starting dynamic subtest: ccs0
[564.573038] Dynamic subtest ccs0: SUCCESS (0.038s)
[564.574059] Subtest sync: SUCCESS (0.320s)
[564.750783] [109/133] (462s left) kms_pm_rpm (fences)
[567.091137] Starting subtest: fences
[567.093295] Subtest fences: SKIP (0.000s)
[567.475903] [110/133] (459s left) sw_sync (sync_busy_fork_unixsocket)
[567.530204] Starting subtest: sync_busy_fork_unixsocket
[567.539762] Subtest sync_busy_fork_unixsocket: SUCCESS (0.011s)
[567.614774] [111/133] (459s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[567.827987] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[567.830259] Starting dynamic subtest: pipe-A-DP-4
[567.833779] Dynamic subtest pipe-A-DP-4: SKIP (0.000s)
[567.844306] Starting dynamic subtest: pipe-B-DP-4
[567.848161] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[567.858486] Starting dynamic subtest: pipe-C-DP-4
[567.862080] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[567.872145] Starting dynamic subtest: pipe-D-DP-4
[567.875590] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[567.877321] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SKIP (0.007s)
[568.284943] [112/133] (459s left) device_reset (cold-reset-bound)
[568.375525] Starting subtest: cold-reset-bound
[568.377500] Subtest cold-reset-bound: SKIP (0.000s)
[568.470458] [113/133] (459s left) kms_atomic_transition (plane-all-transition-nonblocking-fencing)
[568.688430] Starting subtest: plane-all-transition-nonblocking-fencing
[568.689285] Starting dynamic subtest: pipe-A-DP-4
[572.416545] Dynamic subtest pipe-A-DP-4: SUCCESS (3.726s)
[572.506718] Starting dynamic subtest: pipe-B-DP-4
[576.269705] Dynamic subtest pipe-B-DP-4: SUCCESS (3.775s)
[576.323172] Subtest plane-all-transition-nonblocking-fencing: SUCCESS (7.634s)
[576.573268] [114/133] (451s left) gem_partial_pwrite_pread (write-uncached)
[576.674516] Subtest write-uncached: SKIP (0.000s)
[576.776018] [115/133] (450s left) kms_cursor_crc (cursor-sliding-512x170)
[577.216099] Starting subtest: cursor-sliding-512x170
[577.219261] Subtest cursor-sliding-512x170: SKIP (0.001s)
[577.454153] [116/133] (450s left) kms_invalid_mode (int-max-clock)
[577.679830] Starting subtest: int-max-clock
[577.680702] Starting dynamic subtest: pipe-A-DP-4
[577.757140] Dynamic subtest pipe-A-DP-4: SUCCESS (0.076s)
[577.768080] Starting dynamic subtest: pipe-B-DP-4
[577.769154] Dynamic subtest pipe-B-DP-4: SUCCESS (0.001s)
[577.779711] Starting dynamic subtest: pipe-C-DP-4
[577.781407] Dynamic subtest pipe-C-DP-4: SUCCESS (0.001s)
[577.791961] Starting dynamic subtest: pipe-D-DP-4
[577.793133] Dynamic subtest pipe-D-DP-4: SUCCESS (0.001s)
[577.794125] Subtest int-max-clock: SUCCESS (0.084s)
[578.009737] [117/133] (449s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[578.223544] Starting subtest: disable-crc-after-crtc
[578.224410] Starting dynamic subtest: pipe-A-DP-4
[578.969615] Dynamic subtest pipe-A-DP-4: SUCCESS (0.745s)
[578.989697] Starting dynamic subtest: pipe-B-DP-4
[579.597616] Dynamic subtest pipe-B-DP-4: SUCCESS (0.624s)
[579.610065] Starting dynamic subtest: pipe-C-DP-4
[580.199343] Dynamic subtest pipe-C-DP-4: SUCCESS (0.601s)
[580.213069] Starting dynamic subtest: pipe-D-DP-4
[580.807433] Dynamic subtest pipe-D-DP-4: SUCCESS (0.606s)
[580.808352] Subtest disable-crc-after-crtc: SUCCESS (2.585s)
[581.087517] [118/133] (446s left) gem_mmap_wc (close)
[581.173429] Subtest close: SKIP (0.000s)
[581.237977] [119/133] (446s left) vgem_basic (bad-fence)
[581.328335] Starting subtest: bad-fence
[581.329273] Subtest bad-fence: SUCCESS (0.000s)
[581.416727] [120/133] (446s left) syncobj_timeline (transfer-timeline-point)
[581.504832] Starting subtest: transfer-timeline-point
[581.505813] Subtest transfer-timeline-point: SUCCESS (0.001s)
[581.647493] [121/133] (446s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-ccs)
[581.737383] Starting subtest: y-tiled-ccs-to-yf-tiled-ccs
[581.739446] Subtest y-tiled-ccs-to-yf-tiled-ccs: SKIP (0.000s)
[582.006300] [122/133] (446s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy)
[582.230654] Starting subtest: flip-vs-cursor-busy-crc-legacy
[582.951074] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.717s)
[583.226624] [123/133] (445s left) gem_wait (busy)
[583.329851] Starting subtest: busy
[583.330826] Starting dynamic subtest: all-engines
[583.925297] Dynamic subtest all-engines: SUCCESS (0.595s)
[583.936817] Starting dynamic subtest: rcs0
[584.465282] Dynamic subtest rcs0: SUCCESS (0.539s)
[584.476092] Starting dynamic subtest: bcs0
[585.005376] Dynamic subtest bcs0: SUCCESS (0.539s)
[585.015956] Starting dynamic subtest: vcs0
[585.545377] Dynamic subtest vcs0: SUCCESS (0.539s)
[585.555970] Starting dynamic subtest: vcs1
[586.084410] Dynamic subtest vcs1: SUCCESS (0.538s)
[586.094983] Starting dynamic subtest: vecs0
[586.623266] Dynamic subtest vecs0: SUCCESS (0.538s)
[586.633799] Starting dynamic subtest: vecs1
[587.162191] Dynamic subtest vecs1: SUCCESS (0.538s)
[587.172731] Starting dynamic subtest: ccs0
[587.701384] Dynamic subtest ccs0: SUCCESS (0.538s)
[587.702355] Subtest busy: SUCCESS (4.372s)
[588.012642] [124/133] (440s left) kms_big_fb (linear-16bpp-rotate-270)
[588.234210] Starting subtest: linear-16bpp-rotate-270
[588.286472] Subtest linear-16bpp-rotate-270: SKIP (0.052s)
[588.472474] [125/133] (440s left) gem_exec_reloc (basic-write-read-noreloc)
[588.573325] Subtest basic-write-read-noreloc: SKIP (0.000s)
[588.680293] [126/133] (439s left) gem_exec_balancer (hang)
[589.060795] Starting subtest: hang
[592.320342] Subtest hang: SUCCESS (3.260s)
[592.472077] [127/133] (436s left) gem_ctx_bad_destroy (invalid-ctx)
[592.558726] Starting subtest: invalid-ctx
[592.559641] Subtest invalid-ctx: SUCCESS (0.000s)
[592.681998] [128/133] (436s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[592.902042] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[592.903812] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[593.039978] [129/133] (435s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[593.260161] Starting subtest: 4-tiled-64bpp-rotate-270
[593.319760] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.059s)
[593.532867] [130/133] (435s left) kms_ccs (bad-pixel-format-4-tiled-mtl-rc-ccs-cc)
[593.746428] Starting subtest: bad-pixel-format-4-tiled-mtl-rc-ccs-cc
[593.747298] Starting dynamic subtest: pipe-A-DP-4
[593.756057] Dynamic subtest pipe-A-DP-4: SKIP (0.000s)
[593.766477] Starting dynamic subtest: pipe-B-DP-4
[593.775258] Dynamic subtest pipe-B-DP-4: SKIP (0.000s)
[593.785372] Starting dynamic subtest: pipe-C-DP-4
[593.793837] Dynamic subtest pipe-C-DP-4: SKIP (0.000s)
[593.803761] Starting dynamic subtest: pipe-D-DP-4
[593.814101] Dynamic subtest pipe-D-DP-4: SKIP (0.000s)
[593.816239] Subtest bad-pixel-format-4-tiled-mtl-rc-ccs-cc: SKIP (0.006s)
[594.197872] [131/133] (434s left) gem_render_copy_redux (flink-interruptible)
[594.288352] Starting subtest: flink-interruptible
[597.433259] Subtest flink-interruptible: SUCCESS (3.145s)
[597.614748] [132/133] (431s left) device_reset (unbind-reset-rebind)
[597.702404] Starting subtest: unbind-reset-rebind
[600.136638] Subtest unbind-reset-rebind: SUCCESS (2.433s)
[600.198902] [133/133] (428s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[600.415934] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[600.417577] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[600.554269] Closing watchdogs
results: parsing output: 0/ for test: kms_flip_scaled_crc
results: parsing output: 1/ for test: gem_exec_schedule
results: parsing output: 2/ for test: kms_flip
results: parsing output: 3/ for test: gem_ctx_engines
results: parsing output: 4/ for test: kms_dither
results: parsing output: 5/ for test: i915_pm_rc6_residency
results: parsing output: 6/ for test: kms_psr2_sf
results: parsing output: 7/ for test: kms_plane_scaling
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: kms_chamelium_hpd
results: parsing output: 10/ for test: kms_feature_discovery
results: parsing output: 11/ for test: kms_psr
results: parsing output: 12/ for test: gem_exec_whisper
results: parsing output: 13/ for test: gem_exec_schedule
results: parsing output: 14/ for test: gem_exec_gttfill
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_getfb
results: parsing output: 17/ for test: vkms/vkms_configfs
results: parsing output: 18/ for test: syncobj_wait
results: parsing output: 19/ for test: dumb_buffer
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: perf_pmu
results: parsing output: 22/ for test: kms_psr
results: parsing output: 23/ for test: gem_basic
results: parsing output: 24/ for test: gem_userptr_blits
results: parsing output: 25/ for test: i915_query
results: parsing output: 26/ for test: gem_userptr_blits
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_psr2_sf
results: parsing output: 29/ for test: kms_flip
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_frontbuffer_tracking
results: parsing output: 32/ for test: gem_userptr_blits
results: parsing output: 33/ for test: gem_madvise
results: parsing output: 34/ for test: syncobj_wait
results: parsing output: 35/ for test: kms_ccs
results: parsing output: 36/ for test: gem_softpin
results: parsing output: 37/ for test: kms_chamelium_audio
results: parsing output: 38/ for test: kms_psr
results: parsing output: 39/ for test: gem_render_copy
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: gem_pxp
results: parsing output: 42/ for test: kms_cursor_legacy
results: parsing output: 43/ for test: gem_exec_reloc
results: parsing output: 44/ for test: kms_flip_scaled_crc
results: parsing output: 45/ for test: kms_plane
results: parsing output: 46/ for test: kms_flip
results: parsing output: 47/ for test: kms_vrr
results: parsing output: 48/ for test: kms_color
results: parsing output: 49/ for test: kms_psr
results: parsing output: 50/ for test: kms_flip
results: parsing output: 51/ for test: gem_exec_fence
results: parsing output: 52/ for test: gem_userptr_blits
results: parsing output: 53/ for test: kms_legacy_colorkey
results: parsing output: 54/ for test: api_intel_allocator
results: parsing output: 55/ for test: gem_ctx_persistence
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_big_fb
results: parsing output: 58/ for test: kms_addfb_basic
results: parsing output: 59/ for test: perf_pmu
results: parsing output: 60/ for test: kms_psr2_sf
results: parsing output: 61/ for test: gem_mmap_gtt
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_addfb_basic
results: parsing output: 64/ for test: gen9_exec_parse
results: parsing output: 65/ for test: kms_content_protection
results: parsing output: 66/ for test: syncobj_timeline
results: parsing output: 67/ for test: syncobj_timeline
results: parsing output: 68/ for test: kms_rotation_crc
results: parsing output: 69/ for test: vkms/vkms_configfs
results: parsing output: 70/ for test: kms_ccs
results: parsing output: 71/ for test: gem_ctx_persistence
results: parsing output: 72/ for test: gem_mmap_gtt
results: parsing output: 73/ for test: kms_flip
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: i915_query
results: parsing output: 77/ for test: gem_eio
results: parsing output: 78/ for test: gem_linear_blits
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: i915_pm_rps
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: kms_chamelium_color
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: gem_lmem_swapping
results: parsing output: 86/ for test: kms_vblank
results: parsing output: 87/ for test: kms_selftest
results: parsing output: 88/ for test: gem_exec_reloc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: gem_eio
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_cursor_edge_walk
results: parsing output: 93/ for test: kms_cursor_legacy
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_mmap
results: parsing output: 97/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 98/ for test: kms_async_flips
results: parsing output: 99/ for test: gem_exec_reloc
results: parsing output: 100/ for test: syncobj_timeline
results: parsing output: 101/ for test: kms_cursor_crc
results: parsing output: 102/ for test: gem_exec_schedule
results: parsing output: 103/ for test: gem_bad_reloc
results: parsing output: 104/ for test: kms_big_fb
results: parsing output: 105/ for test: kms_hdmi_inject
results: parsing output: 106/ for test: kms_pm_backlight
results: parsing output: 107/ for test: prime_vgem
results: parsing output: 108/ for test: kms_pm_rpm
results: parsing output: 109/ for test: sw_sync
results: parsing output: 110/ for test: kms_ccs
results: parsing output: 111/ for test: device_reset
results: parsing output: 112/ for test: kms_atomic_transition
results: parsing output: 113/ for test: gem_partial_pwrite_pread
results: parsing output: 114/ for test: kms_cursor_crc
results: parsing output: 115/ for test: kms_invalid_mode
results: parsing output: 116/ for test: kms_pipe_crc_basic
results: parsing output: 117/ for test: gem_mmap_wc
results: parsing output: 118/ for test: vgem_basic
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: gem_render_copy
results: parsing output: 121/ for test: kms_cursor_legacy
results: parsing output: 122/ for test: gem_wait
results: parsing output: 123/ for test: kms_big_fb
results: parsing output: 124/ for test: gem_exec_reloc
results: parsing output: 125/ for test: gem_exec_balancer
results: parsing output: 126/ for test: gem_ctx_bad_destroy
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_big_fb
results: parsing output: 129/ for test: kms_ccs
results: parsing output: 130/ for test: gem_render_copy_redux
results: parsing output: 131/ for test: device_reset
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.