[36.649563] Initializing watchdogs
[36.649619]   /dev/watchdog0
[36.656623] [001/143] (960s left) kms_flip (2x-flip-vs-panning-interruptible)
[36.743769] Starting subtest: 2x-flip-vs-panning-interruptible
[36.759060] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.014s)
[36.835976] [002/143] (960s left) gem_exec_reloc (basic-gtt-active)
[36.922996] Subtest basic-gtt-active: SKIP (0.000s)
[36.956338] [003/143] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[37.065457] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[37.069313] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[37.145850] [004/143] (960s left) kms_ccs (pipe-b-ccs-on-another-bo-4-tiled-mtl-rc-ccs)
[37.595373] Starting subtest: pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs
[37.598351] Subtest pipe-B-ccs-on-another-bo-4-tiled-mtl-rc-ccs: SUCCESS (0.000s)
[38.430449] [005/143] (958s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[38.537483] Starting subtest: cursorB-vs-flipB-atomic
[38.542751] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[38.630177] [006/143] (958s left) gem_set_tiling_vs_blt (tiled-to-tiled)
[38.706497] Subtest tiled-to-tiled: SKIP (0.000s)
[38.740729] [007/143] (958s left) kms_ccs (pipe-c-bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[39.178051] Starting subtest: pipe-C-bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[39.184204] Subtest pipe-C-bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[40.046428] [008/143] (957s left) kms_plane_cursor (primary)
[40.143189] Starting subtest: primary
[40.147715] Starting dynamic subtest: pipe-A-eDP-1-size-64
[43.405604] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (3.264s)
[43.431874] Starting dynamic subtest: pipe-A-eDP-1-size-128
[46.640633] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (3.219s)
[46.665546] Starting dynamic subtest: pipe-A-eDP-1-size-256
[49.884708] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (3.229s)
[50.209920] Starting dynamic subtest: pipe-B-eDP-1-size-64
[54.250588] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (4.054s)
[54.276606] Starting dynamic subtest: pipe-B-eDP-1-size-128
[57.517911] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (3.251s)
[57.544867] Starting dynamic subtest: pipe-B-eDP-1-size-256
[60.707529] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (3.174s)
[61.031283] Starting dynamic subtest: pipe-C-eDP-1-size-64
[64.617999] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.601s)
[64.648798] Starting dynamic subtest: pipe-C-eDP-1-size-128
[67.418387] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.782s)
[67.445267] Starting dynamic subtest: pipe-C-eDP-1-size-256
[70.130147] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.694s)
[70.467090] Starting dynamic subtest: pipe-D-eDP-1-size-64
[74.015878] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.561s)
[74.046274] Starting dynamic subtest: pipe-D-eDP-1-size-128
[76.804601] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.769s)
[76.833457] Starting dynamic subtest: pipe-D-eDP-1-size-256
[79.581963] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.759s)
[79.905353] Subtest primary: SUCCESS (39.763s)
[80.827953] [009/143] (916s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render)
[80.923698] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render
[80.927721] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[81.002173] [010/143] (916s left) prime_self_import (basic-with_one_bo_two_files)
[81.058630] Starting subtest: basic-with_one_bo_two_files
[81.074942] Subtest basic-with_one_bo_two_files: SUCCESS (0.015s)
[81.153069] [011/143] (916s left) gem_render_copy (linear-to-vebox-yf-tiled)
[81.209472] Starting subtest: linear-to-vebox-yf-tiled
[81.214284] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[81.394941] [012/143] (916s left) kms_ccs (pipe-d-crc-primary-basic-4-tiled-mtl-rc-ccs)
[81.840425] Starting subtest: pipe-D-crc-primary-basic-4-tiled-mtl-rc-ccs
[82.840605] Subtest pipe-D-crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (1.001s)
[84.019699] [013/143] (913s left) syncobj_wait (multi-wait-submitted)
[84.098136] Starting subtest: multi-wait-submitted
[84.198909] Subtest multi-wait-submitted: SUCCESS (0.103s)
[84.272988] [014/143] (913s left) gem_exec_schedule (thriceslice)
[84.353903] Starting subtest: thriceslice
[84.356361] Starting dynamic subtest: rcs0
[84.374846] Dynamic subtest rcs0: SUCCESS (0.033s)
[84.384939] Starting dynamic subtest: bcs0
[84.420813] Dynamic subtest bcs0: SUCCESS (0.044s)
[84.434054] Starting dynamic subtest: vcs0
[84.485579] Dynamic subtest vcs0: SUCCESS (0.062s)
[84.498946] Starting dynamic subtest: vcs1
[84.525156] Dynamic subtest vcs1: SUCCESS (0.039s)
[84.538498] Starting dynamic subtest: vecs0
[84.586245] Dynamic subtest vecs0: SUCCESS (0.060s)
[84.599325] Starting dynamic subtest: ccs0
[84.627103] Dynamic subtest ccs0: SUCCESS (0.039s)
[84.630056] Subtest thriceslice: SUCCESS (0.286s)
[86.491303] [015/143] (911s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[86.612516] Starting subtest: fbc-psr2-sprite-plane-onoff
[86.614896] Starting dynamic subtest: eDP-1
[86.619597] Dynamic subtest eDP-1: SKIP (0.000s)
[86.624188] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[86.711098] [016/143] (910s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[86.811452] Starting subtest: 4-tiled-16bpp-rotate-90
[86.828270] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.020s)
[86.938497] [017/143] (910s left) i915_query (query-topology-matches-eu-total)
[87.010800] Starting subtest: query-topology-matches-eu-total
[87.013358] Subtest query-topology-matches-eu-total: SUCCESS (0.000s)
[87.083851] [018/143] (910s left) kms_cursor_crc (cursor-suspend)
[87.184798] Starting subtest: cursor-suspend
[87.187832] Starting dynamic subtest: pipe-A-eDP-1
[121.058487] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.092s)
[121.074809] Starting dynamic subtest: pipe-D-eDP-1
[155.021959] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.965s)
[155.024652] Subtest cursor-suspend: SUCCESS (8.063s)
[155.908689] [019/143] (901s left) i915_hangman (detector)
[155.967439] Starting subtest: detector
[155.970042] Starting dynamic subtest: rcs0
[167.183181] Dynamic subtest rcs0: SUCCESS (11.215s)
[167.201671] Starting dynamic subtest: bcs0
[179.216170] Dynamic subtest bcs0: SUCCESS (12.031s)
[179.233813] Starting dynamic subtest: vcs0
[191.183263] Dynamic subtest vcs0: SUCCESS (11.965s)
[191.200857] Starting dynamic subtest: vcs1
[203.151061] Dynamic subtest vcs1: SUCCESS (11.966s)
[203.168346] Starting dynamic subtest: vecs0
[215.183670] Dynamic subtest vecs0: SUCCESS (12.031s)
[215.200167] Starting dynamic subtest: ccs0
[227.215197] Dynamic subtest ccs0: SUCCESS (12.030s)
[227.218432] Subtest detector: SUCCESS (71.249s)
[227.350225] [020/143] (830s left) syncobj_wait (single-wait-all-submitted)
[227.417748] Starting subtest: single-wait-all-submitted
[227.525169] Subtest single-wait-all-submitted: SUCCESS (0.108s)
[227.604098] [021/143] (829s left) gem_mmap_gtt (ptrace)
[227.661546] Subtest ptrace: SKIP (0.000s)
[227.703711] [022/143] (829s left) vc4/vc4_label_bo (set-label)
[227.760648] Subtest set-label: SKIP (0.000s)
[227.804442] [023/143] (829s left) gem_ctx_param (set-priority-range)
[227.870656] Starting subtest: set-priority-range
[227.904942] Subtest set-priority-range: SUCCESS (0.034s)
[228.018798] [024/143] (829s left) kms_ccs (pipe-b-bad-rotation-90-4-tiled-mtl-mc-ccs)
[228.423849] Starting subtest: pipe-B-bad-rotation-90-4-tiled-mtl-mc-ccs
[229.452073] Subtest pipe-B-bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (1.028s)
[230.641483] [025/143] (826s left) gem_eio (in-flight-internal-1us)
[230.836406] Starting subtest: in-flight-internal-1us
[232.379444] Subtest in-flight-internal-1us: SUCCESS (1.543s)
[232.484149] [026/143] (825s left) kms_ccs (pipe-d-bad-pixel-format-4-tiled-mtl-rc-ccs)
[232.898625] Starting subtest: pipe-D-bad-pixel-format-4-tiled-mtl-rc-ccs
[232.901511] Subtest pipe-D-bad-pixel-format-4-tiled-mtl-rc-ccs: SUCCESS (0.000s)
[233.781040] [027/143] (823s left) perf (gen12-group-concurrent-oa-buffer-read)
[233.939031] Starting subtest: gen12-group-concurrent-oa-buffer-read
[243.989664] Subtest gen12-group-concurrent-oa-buffer-read: SUCCESS (10.051s)
[244.025044] [028/143] (813s left) kms_frontbuffer_tracking (psr-rgb101010-draw-blt)
[244.118124] Starting subtest: psr-rgb101010-draw-blt
[249.378083] Subtest psr-rgb101010-draw-blt: SUCCESS (5.265s)
[249.485126] [029/143] (808s left) sw_sync (sync_multi_producer_single_consumer)
[249.525081] Starting subtest: sync_multi_producer_single_consumer
[250.599895] Subtest sync_multi_producer_single_consumer: SUCCESS (1.076s)
[250.641935] [030/143] (807s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[250.719984] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[250.722508] Starting dynamic subtest: pipe-A-eDP-1
[250.738670] Dynamic subtest pipe-A-eDP-1: SKIP (0.013s)
[250.754842] Starting dynamic subtest: pipe-B-eDP-1
[250.759695] Dynamic subtest pipe-B-eDP-1: SKIP (0.020s)
[250.772282] Starting dynamic subtest: pipe-C-eDP-1
[250.777084] Dynamic subtest pipe-C-eDP-1: SKIP (0.020s)
[250.787366] Starting dynamic subtest: pipe-D-eDP-1
[250.799760] Dynamic subtest pipe-D-eDP-1: SKIP (0.020s)
[250.803812] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.079s)
[250.863532] [031/143] (806s left) gem_exec_balancer (nohangcheck)
[251.139434] Starting subtest: nohangcheck
[251.762588] Subtest nohangcheck: SUCCESS (0.624s)
[251.856756] [032/143] (805s left) kms_vblank (query-idle)
[251.961486] Starting subtest: query-idle
[251.964149] Starting dynamic subtest: pipe-A-eDP-1
[253.718555] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.752s)
[253.735027] Starting dynamic subtest: pipe-D-eDP-1
[256.266124] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.544s)
[256.268642] Subtest query-idle: SUCCESS (4.303s)
[257.201189] [033/143] (800s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[257.327941] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[257.330505] Starting dynamic subtest: pipe-A-default-mode
[258.557429] Dynamic subtest pipe-A-default-mode: SKIP (1.224s)
[258.561838] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.226s)
[258.695546] [034/143] (799s left) kms_ccs (pipe-d-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[259.150713] Starting subtest: pipe-D-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[259.156596] Subtest pipe-D-crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.001s)
[260.029658] [035/143] (797s left) kms_addfb_basic (invalid-set-prop)
[260.106901] Starting subtest: invalid-set-prop
[260.109503] Subtest invalid-set-prop: SUCCESS (0.000s)
[260.230615] [036/143] (797s left) syncobj_eventfd (timeline-wait-signaled)
[260.304420] Starting subtest: timeline-wait-signaled
[260.306678] Subtest timeline-wait-signaled: SUCCESS (0.000s)
[260.399908] [037/143] (797s left) drm_fdinfo (context-close-stress)
[260.455761] Starting subtest: context-close-stress
[271.206562] Subtest context-close-stress: SUCCESS (10.753s)
[271.270665] [038/143] (786s left) kms_pm_rpm (legacy-planes)
[272.786750] Starting subtest: legacy-planes
[272.790708] Starting dynamic subtest: plane-40
[297.112605] Dynamic subtest plane-40: SUCCESS (24.326s)
[297.131812] Starting dynamic subtest: plane-49
[321.456773] Dynamic subtest plane-49: SUCCESS (24.342s)
[321.476810] Starting dynamic subtest: plane-58
[345.759373] Dynamic subtest plane-58: SUCCESS (24.300s)
[345.777323] Starting dynamic subtest: plane-67
[369.961795] Dynamic subtest plane-67: SUCCESS (24.201s)
[369.965198] Subtest legacy-planes: SUCCESS (97.178s)
[370.850313] [039/143] (687s left) gem_exec_params (cliprects-invalid)
[370.913719] Starting subtest: cliprects-invalid
[370.916749] Subtest cliprects-invalid: SUCCESS (0.000s)
[370.996683] [040/143] (687s left) gem_exec_parallel (contexts)
[371.092909] Starting subtest: contexts
[371.095379] Starting dynamic subtest: rcs0
[377.756336] Dynamic subtest rcs0: SUCCESS (6.662s)
[377.767255] Starting dynamic subtest: bcs0
[385.080891] Dynamic subtest bcs0: SUCCESS (7.324s)
[385.090391] Starting dynamic subtest: vcs0
[393.720958] Dynamic subtest vcs0: SUCCESS (8.639s)
[393.739037] Starting dynamic subtest: vcs1
[401.724133] Dynamic subtest vcs1: SUCCESS (8.001s)
[401.742059] Starting dynamic subtest: vecs0
[409.907097] Dynamic subtest vecs0: SUCCESS (8.181s)
[409.925337] Starting dynamic subtest: ccs0
[417.795987] Dynamic subtest ccs0: SUCCESS (7.888s)
[417.798239] Subtest contexts: SUCCESS (46.703s)
[417.984882] [041/143] (640s left) gem_watchdog (default-virtual)
[418.067866] Starting subtest: default-virtual
[424.347448] Subtest default-virtual: SUCCESS (6.291s)
[424.433917] [042/143] (633s left) kms_ccs (pipe-d-random-ccs-data-4-tiled-mtl-rc-ccs-cc)
[424.899983] Starting subtest: pipe-D-random-ccs-data-4-tiled-mtl-rc-ccs-cc
[425.692723] Subtest pipe-D-random-ccs-data-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.793s)
[426.944424] [043/143] (631s left) kms_invalid_mode (zero-hdisplay)
[427.048031] Starting subtest: zero-hdisplay
[427.050897] Starting dynamic subtest: pipe-A-eDP-1
[427.329537] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[427.347377] Starting dynamic subtest: pipe-B-eDP-1
[427.349372] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[427.360071] Starting dynamic subtest: pipe-C-eDP-1
[427.362477] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[427.370849] Starting dynamic subtest: pipe-D-eDP-1
[427.373216] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[427.375609] Subtest zero-hdisplay: SUCCESS (0.297s)
[428.209316] [044/143] (629s left) gem_fenced_exec_thrash (too-many-fences)
[428.297791] Subtest too-many-fences: SKIP (0.000s)
[428.354382] [045/143] (629s left) kms_setmode (invalid-clone-single-crtc)
[428.411224] Starting subtest: invalid-clone-single-crtc
[428.419421] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[428.481760] [046/143] (629s left) gem_exec_params (invalid-ring2)
[428.557554] Starting subtest: invalid-ring2
[428.560065] Subtest invalid-ring2: SUCCESS (0.000s)
[428.655783] [047/143] (629s left) gem_softpin (softpin)
[428.733756] Starting subtest: softpin
[461.005562] Subtest softpin: SUCCESS (32.268s)
[461.109276] [048/143] (597s left) drm_fdinfo (virtual-busy-all)
[461.185721] Starting subtest: virtual-busy-all
[461.190356] Subtest virtual-busy-all: SKIP (0.000s)
[461.262498] [049/143] (597s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt)
[461.360008] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt
[461.363969] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[461.457262] [050/143] (596s left) kms_big_fb (yf-tiled-addfb-size-offset-overflow)
[461.542638] Starting subtest: yf-tiled-addfb-size-offset-overflow
[461.547232] Subtest yf-tiled-addfb-size-offset-overflow: SKIP (0.000s)
[461.622128] [051/143] (596s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[461.852912] Starting subtest: cursor-rapid-movement-128x42
[461.883844] Subtest cursor-rapid-movement-128x42: SKIP (0.026s)
[462.121334] [052/143] (596s left) gem_ringfill (legacy-basic)
[468.214696] Starting subtest: legacy-basic
[468.217675] Starting dynamic subtest: default
[468.275261] Dynamic subtest default: SUCCESS (0.062s)
[468.294722] Starting dynamic subtest: render
[468.320591] Dynamic subtest render: SUCCESS (0.043s)
[468.338889] Starting dynamic subtest: bsd
[468.370945] Dynamic subtest bsd: SUCCESS (0.048s)
[468.389962] Starting dynamic subtest: bsd1
[468.414216] Dynamic subtest bsd1: SUCCESS (0.041s)
[468.432139] Starting dynamic subtest: bsd2
[468.466742] Dynamic subtest bsd2: SUCCESS (0.050s)
[468.485400] Starting dynamic subtest: blt
[468.520714] Dynamic subtest blt: SUCCESS (0.052s)
[468.538880] Starting dynamic subtest: vebox
[468.571721] Dynamic subtest vebox: SUCCESS (0.049s)
[468.574957] Subtest legacy-basic: SUCCESS (0.361s)
[468.655023] [053/143] (589s left) kms_chamelium_color (ctm-0-50)
[468.759849] Subtest ctm-0-50: SKIP (0.000s)
[468.791701] [054/143] (589s left) gem_spin_batch (legacy-resubmit)
[468.876213] Starting subtest: legacy-resubmit
[468.878800] Starting dynamic subtest: default
[468.885539] Dynamic subtest default: SUCCESS (0.008s)
[468.904227] Starting dynamic subtest: render
[468.906956] Dynamic subtest render: SUCCESS (0.001s)
[468.920715] Starting dynamic subtest: bsd
[468.923060] Dynamic subtest bsd: SUCCESS (0.003s)
[468.932467] Starting dynamic subtest: bsd1
[468.934621] Dynamic subtest bsd1: SUCCESS (0.004s)
[468.942259] Starting dynamic subtest: bsd2
[468.944353] Dynamic subtest bsd2: SUCCESS (0.001s)
[468.951707] Starting dynamic subtest: blt
[468.953870] Dynamic subtest blt: SUCCESS (0.003s)
[468.960238] Starting dynamic subtest: vebox
[468.962348] Dynamic subtest vebox: SUCCESS (0.003s)
[468.964408] Subtest legacy-resubmit: SUCCESS (0.028s)
[469.125628] [055/143] (589s left) kms_ccs (pipe-c-random-ccs-data-4-tiled-mtl-rc-ccs)
[469.548002] Starting subtest: pipe-C-random-ccs-data-4-tiled-mtl-rc-ccs
[470.354643] Subtest pipe-C-random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (0.807s)
[471.620113] [056/143] (586s left) gem_ctx_isolation (dirty-switch)
[471.680455] Starting subtest: dirty-switch
[471.682847] Starting dynamic subtest: rcs0
[471.855587] Dynamic subtest rcs0: SUCCESS (0.175s)
[471.873985] Starting dynamic subtest: bcs0
[472.075941] Dynamic subtest bcs0: SUCCESS (0.220s)
[472.082812] Starting dynamic subtest: vcs0
[472.320860] Dynamic subtest vcs0: SUCCESS (0.244s)
[472.339630] Starting dynamic subtest: vcs1
[473.404866] Dynamic subtest vcs1: SUCCESS (1.084s)
[473.423202] Starting dynamic subtest: vecs0
[475.523970] Dynamic subtest vecs0: SUCCESS (2.119s)
[475.542219] Starting dynamic subtest: ccs0
[477.516765] Dynamic subtest ccs0: SUCCESS (1.991s)
[477.519511] Subtest dirty-switch: SUCCESS (5.836s)
[477.744699] [057/143] (580s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-pwrite)
[477.825693] Starting subtest: fbcpsr-rgb101010-draw-pwrite
[482.656345] Subtest fbcpsr-rgb101010-draw-pwrite: SUCCESS (4.834s)
[482.777529] [058/143] (575s left) gem_ctx_shared (q-in-order)
[482.860590] Starting subtest: Q-in-order
[482.863539] Starting dynamic subtest: rcs0
[482.997270] Dynamic subtest rcs0: SUCCESS (0.135s)
[483.023249] Starting dynamic subtest: vcs0
[483.100853] Dynamic subtest vcs0: SUCCESS (0.104s)
[483.126172] Starting dynamic subtest: vcs1
[483.201032] Dynamic subtest vcs1: SUCCESS (0.099s)
[483.225186] Starting dynamic subtest: vecs0
[483.332394] Dynamic subtest vecs0: SUCCESS (0.129s)
[483.362112] Starting dynamic subtest: ccs0
[483.452501] Dynamic subtest ccs0: SUCCESS (0.119s)
[483.455625] Subtest Q-in-order: SUCCESS (0.592s)
[484.293158] [059/143] (574s left) gem_exec_create (madvise)
[484.382987] Starting subtest: madvise
[484.385755] Starting dynamic subtest: smem
[506.375005] Dynamic subtest smem: SUCCESS (21.990s)
[506.377701] Subtest madvise: SUCCESS (21.991s)
[507.715718] [060/143] (550s left) kms_pm_rpm (universal-planes-dpms)
[509.347682] Starting subtest: universal-planes-dpms
[509.352354] Starting dynamic subtest: plane-31
[533.522312] Dynamic subtest plane-31: SUCCESS (24.171s)
[533.540897] Starting dynamic subtest: plane-40
[557.866340] Dynamic subtest plane-40: SUCCESS (24.345s)
[557.884979] Starting dynamic subtest: plane-49
[582.166564] Dynamic subtest plane-49: SUCCESS (24.298s)
[582.189340] Starting dynamic subtest: plane-58
[606.494891] Dynamic subtest plane-58: SUCCESS (24.327s)
[606.514952] Starting dynamic subtest: plane-67
[630.817290] Dynamic subtest plane-67: SUCCESS (24.316s)
[630.837379] Starting dynamic subtest: plane-76
[655.107271] Dynamic subtest plane-76: SUCCESS (24.290s)
[655.110460] Subtest universal-planes-dpms: SUCCESS (145.760s)
[656.160931] [061/143] (402s left) kms_flip (2x-nonexisting-fb)
[656.250668] Starting subtest: 2x-nonexisting-fb
[656.262018] Subtest 2x-nonexisting-fb: SKIP (0.014s)
[656.334182] [062/143] (402s left) syncobj_wait (wait-all-for-submit-complex)
[656.404139] Starting subtest: wait-all-for-submit-complex
[656.624881] Subtest wait-all-for-submit-complex: SUCCESS (0.219s)
[656.704572] [063/143] (402s left) syncobj_timeline (wait-all-complex)
[656.789646] Starting subtest: wait-all-complex
[657.008424] Subtest wait-all-complex: SUCCESS (0.218s)
[657.088690] [064/143] (401s left) kms_ccs (pipe-c-crc-primary-basic-4-tiled-dg2-rc-ccs-cc)
[657.506063] Starting subtest: pipe-C-crc-primary-basic-4-tiled-dg2-rc-ccs-cc
[657.511812] Subtest pipe-C-crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.000s)
[658.367971] [065/143] (400s left) kms_cursor_crc (cursor-onscreen-256x85)
[658.711108] Starting subtest: cursor-onscreen-256x85
[658.745324] Subtest cursor-onscreen-256x85: SKIP (0.026s)
[658.925514] [066/143] (399s left) api_intel_allocator (open-vm)
[658.999942] Starting subtest: open-vm
[659.002864] Subtest open-vm: SUCCESS (0.005s)
[659.101998] [067/143] (399s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-modifiers)
[659.196432] Starting subtest: plane-scaler-with-clipping-clamping-modifiers
[659.198463] Starting dynamic subtest: pipe-A-eDP-1
[660.093852] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.898s)
[660.114895] Starting dynamic subtest: pipe-B-eDP-1
[662.170550] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.074s)
[662.192035] Starting dynamic subtest: pipe-C-eDP-1
[664.117527] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.940s)
[664.135771] Starting dynamic subtest: pipe-D-eDP-1
[666.133618] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.015s)
[666.136234] Subtest plane-scaler-with-clipping-clamping-modifiers: SUCCESS (6.939s)
[667.394536] [068/143] (391s left) gem_exec_balancer (bonded-pair)
[667.479108] Starting subtest: bonded-pair
[667.483979] Subtest bonded-pair: SKIP (0.000s)
[667.753057] [069/143] (391s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[667.841093] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[668.865484] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.025s)
[668.988542] [070/143] (390s left) kms_ccs (pipe-a-ccs-on-another-bo-y-tiled-ccs)
[669.364267] Starting subtest: pipe-A-ccs-on-another-bo-y-tiled-ccs
[669.369545] Subtest pipe-A-ccs-on-another-bo-y-tiled-ccs: SKIP (0.000s)
[670.260165] [071/143] (388s left) v3d/v3d_submit_cl (simple-flush-cache)
[670.366267] Subtest simple-flush-cache: SKIP (0.000s)
[670.422399] [072/143] (388s left) kms_psr (pr-basic)
[670.523727] Starting subtest: pr-basic
[670.528519] Subtest pr-basic: SKIP (0.000s)
[670.668003] [073/143] (388s left) kms_cursor_legacy (basic-busy-flip-before-cursor-atomic)
[670.767497] Starting subtest: basic-busy-flip-before-cursor-atomic
[670.772307] Subtest basic-busy-flip-before-cursor-atomic: SKIP (0.000s)
[670.896997] [074/143] (388s left) gem_exec_fair (basic-flow)
[670.999832] Subtest basic-flow: SKIP (0.000s)
[671.035285] [075/143] (388s left) v3d/v3d_submit_cl (bad-multisync-out-sync)
[671.108624] Subtest bad-multisync-out-sync: SKIP (0.000s)
[671.163883] [076/143] (388s left) kms_rotation_crc (primary-yf-tiled-reflect-x-90)
[671.238930] Starting subtest: primary-yf-tiled-reflect-x-90
[671.243134] Subtest primary-yf-tiled-reflect-x-90: SKIP (0.002s)
[671.312118] [077/143] (387s left) kms_big_fb (x-tiled-16bpp-rotate-270)
[671.396286] Starting subtest: x-tiled-16bpp-rotate-270
[671.414428] Subtest x-tiled-16bpp-rotate-270: SKIP (0.019s)
[671.515313] [078/143] (387s left) gem_bad_reloc (negative-reloc-bltcopy)
[671.581059] Subtest negative-reloc-bltcopy: SKIP (0.000s)
[671.642443] [079/143] (387s left) core_setmaster (master-drop-set-root)
[672.221584] Starting subtest: master-drop-set-root
[672.226864] Subtest master-drop-set-root: SUCCESS (0.002s)
[672.252725] [080/143] (387s left) i915_suspend (fence-restore-untiled)
[672.332024] Starting subtest: fence-restore-untiled
[672.337643] Subtest fence-restore-untiled: SKIP (0.000s)
[672.420456] [081/143] (386s left) kms_big_fb (x-tiled-64bpp-rotate-90)
[672.545258] Starting subtest: x-tiled-64bpp-rotate-90
[672.567403] Subtest x-tiled-64bpp-rotate-90: SKIP (0.025s)
[672.666082] [082/143] (386s left) i915_pm_freq_api (freq-suspend)
[672.722082] Starting subtest: freq-suspend
[672.724437] Starting dynamic subtest: gt0
[688.521128] Dynamic subtest gt0: SUCCESS (1.264s)
[688.528665] Starting dynamic subtest: gt1
[704.502181] Dynamic subtest gt1: SUCCESS (1.229s)
[704.504761] Subtest freq-suspend: SUCCESS (2.497s)
[704.569033] [083/143] (384s left) kms_big_joiner (invalid-modeset)
[704.663624] Subtest invalid-modeset: SKIP (0.000s)
[704.694914] [084/143] (384s left) sysfs_preempt_timeout (invalid)
[704.754518] Starting subtest: invalid
[704.757118] Starting dynamic subtest: vecs0
[704.759792] Dynamic subtest vecs0: SUCCESS (0.000s)
[704.767204] Starting dynamic subtest: ccs0
[704.769695] Dynamic subtest ccs0: SUCCESS (0.000s)
[704.777020] Starting dynamic subtest: vcs0
[704.779511] Dynamic subtest vcs0: SUCCESS (0.000s)
[704.786760] Starting dynamic subtest: bcs0
[704.788703] Dynamic subtest bcs0: SUCCESS (0.000s)
[704.794743] Starting dynamic subtest: rcs0
[704.796725] Dynamic subtest rcs0: SUCCESS (0.000s)
[704.815723] Starting dynamic subtest: vcs1
[704.818652] Dynamic subtest vcs1: SUCCESS (0.000s)
[704.821463] Subtest invalid: SUCCESS (0.003s)
[704.848332] [085/143] (383s left) kms_ccs (pipe-a-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc)
[705.260389] Starting subtest: pipe-A-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc
[705.265611] Subtest pipe-A-crc-sprite-planes-basic-y-tiled-gen12-rc-ccs-cc: SKIP (0.001s)
[706.147958] [086/143] (382s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-msflip-blt)
[706.250741] Starting subtest: psr-2p-scndscrn-indfb-msflip-blt
[706.255618] Subtest psr-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s)
[706.332485] [087/143] (382s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-blt)
[706.439294] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-blt
[711.299647] Subtest fbcpsr-1p-offscren-pri-indfb-draw-blt: SUCCESS (4.862s)
[711.438150] [088/143] (377s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[711.527001] Subtest no-spare-fences-interruptible: SKIP (0.000s)
[711.583361] [089/143] (377s left) gem_ctx_param (invalid-set-no-zeromap)
[711.648995] Starting subtest: invalid-set-no-zeromap
[711.651491] Subtest invalid-set-no-zeromap: SUCCESS (0.000s)
[711.710773] [090/143] (377s left) fbdev (write)
[711.752036] Starting subtest: write
[712.113016] Subtest write: SUCCESS (0.360s)
[712.168328] [091/143] (376s left) gem_userptr_blits (usage-restrictions)
[712.240259] Starting subtest: usage-restrictions
[712.242516] Subtest usage-restrictions: SUCCESS (0.000s)
[712.356160] [092/143] (376s left) kms_ccs (pipe-b-crc-primary-rotation-180-4-tiled-dg2-rc-ccs)
[712.761438] Starting subtest: pipe-B-crc-primary-rotation-180-4-tiled-dg2-rc-ccs
[712.767162] Subtest pipe-B-crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SKIP (0.000s)
[713.685437] [093/143] (375s left) gem_userptr_blits (relocations)
[713.771258] Starting subtest: relocations
[713.776185] Subtest relocations: SKIP (0.001s)
[713.890481] [094/143] (375s left) vc4/vc4_tiling (get-after-free)
[714.003514] Subtest get-after-free: SKIP (0.000s)
[714.049986] [095/143] (375s left) gem_exec_params (rel-constants-invalid-ring)
[714.123364] Starting subtest: rel-constants-invalid-ring
[714.126247] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[714.219958] [096/143] (374s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-pwrite)
[714.304204] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-pwrite
[720.261434] Subtest psr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (5.963s)
[720.358051] [097/143] (368s left) gem_exec_schedule (pi-userfault)
[720.591524] Starting subtest: pi-userfault
[720.593710] Starting dynamic subtest: rcs0
[720.609480] Dynamic subtest rcs0: SUCCESS (0.020s)
[720.631440] Starting dynamic subtest: bcs0
[720.634113] Dynamic subtest bcs0: SUCCESS (0.016s)
[720.653772] Starting dynamic subtest: vcs0
[720.655836] Dynamic subtest vcs0: SUCCESS (0.019s)
[720.668599] Starting dynamic subtest: vcs1
[720.670918] Dynamic subtest vcs1: SUCCESS (0.014s)
[720.680131] Starting dynamic subtest: vecs0
[720.682131] Dynamic subtest vecs0: SUCCESS (0.011s)
[720.699132] Starting dynamic subtest: ccs0
[720.701541] Dynamic subtest ccs0: SUCCESS (0.009s)
[720.703919] Subtest pi-userfault: SUCCESS (0.098s)
[720.876623] [098/143] (368s left) gem_exec_params (mmapped)
[720.946996] Starting subtest: mmapped
[720.955917] Subtest mmapped: SUCCESS (0.004s)
[721.053343] [099/143] (368s left) syncobj_timeline (etime-single-wait-all-available-unsubmitted)
[721.120643] Starting subtest: etime-single-wait-all-available-unsubmitted
[721.222302] Subtest etime-single-wait-all-available-unsubmitted: SUCCESS (0.102s)
[721.301618] [100/143] (367s left) kms_dsc (dsc-with-output-formats-with-bpc)
[721.371296] Starting subtest: dsc-with-output-formats-with-bpc
[721.375958] Subtest dsc-with-output-formats-with-bpc: SKIP (0.001s)
[721.437999] [101/143] (367s left) syncobj_wait (invalid-wait-bad-flags)
[721.524423] Starting subtest: invalid-wait-bad-flags
[721.526673] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[721.605497] [102/143] (367s left) kms_cursor_crc (cursor-alpha-transparent)
[721.666452] Starting subtest: cursor-alpha-transparent
[721.668978] Starting dynamic subtest: pipe-A-eDP-1
[722.369937] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.703s)
[722.387019] Starting dynamic subtest: pipe-D-eDP-1
[723.811884] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.437s)
[723.814903] Subtest cursor-alpha-transparent: SUCCESS (2.146s)
[724.700799] [103/143] (364s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[724.817610] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[724.822385] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[724.909361] [104/143] (364s left) syncobj_wait (multi-wait-all-submitted)
[724.991649] Starting subtest: multi-wait-all-submitted
[725.102183] Subtest multi-wait-all-submitted: SUCCESS (0.109s)
[725.181814] [105/143] (364s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[725.295194] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[725.299537] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[725.382679] [106/143] (364s left) gem_exec_store (pages)
[725.478383] Starting subtest: pages
[725.480906] Starting dynamic subtest: rcs0
[725.588587] Dynamic subtest rcs0: SUCCESS (0.110s)
[725.607625] Starting dynamic subtest: bcs0
[725.663163] Dynamic subtest bcs0: SUCCESS (0.073s)
[725.682446] Starting dynamic subtest: vcs0
[725.747036] Dynamic subtest vcs0: SUCCESS (0.082s)
[725.765407] Starting dynamic subtest: vcs1
[725.832944] Dynamic subtest vcs1: SUCCESS (0.084s)
[725.851822] Starting dynamic subtest: vecs0
[725.922327] Dynamic subtest vecs0: SUCCESS (0.088s)
[725.941170] Starting dynamic subtest: ccs0
[726.021610] Dynamic subtest ccs0: SUCCESS (0.098s)
[726.024412] Subtest pages: SUCCESS (0.544s)
[726.204418] [107/143] (363s left) kms_psr (psr2-basic)
[726.336429] Starting subtest: psr2-basic
[726.339131] Starting dynamic subtest: eDP-1
[728.058730] Dynamic subtest eDP-1: SUCCESS (1.723s)
[728.061476] Subtest psr2-basic: SUCCESS (1.726s)
[728.977443] [108/143] (360s left) gem_ctx_param (root-set)
[729.038315] Starting subtest: root-set
[729.041021] Subtest root-set: SUCCESS (0.000s)
[729.120567] [109/143] (360s left) kms_psr (fbc-pr-sprite-mmap-gtt)
[729.198926] Starting subtest: fbc-pr-sprite-mmap-gtt
[729.204079] Subtest fbc-pr-sprite-mmap-gtt: SKIP (0.000s)
[729.298129] [110/143] (360s left) gem_busy (close-race)
[729.366929] Starting subtest: close-race
[754.514035] Subtest close-race: SUCCESS (25.145s)
[754.897010] [111/143] (334s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[755.058546] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[759.662094] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (4.608s)
[759.769276] [112/143] (329s left) gen9_exec_parse (allowed-all)
[759.854928] Subtest allowed-all: SKIP (0.000s)
[759.911601] [113/143] (329s left) gem_mmap_gtt (hang)
[759.992676] Subtest hang: SKIP (0.000s)
[760.043433] [114/143] (329s left) kms_ccs (pipe-a-crc-primary-basic-4-tiled-mtl-mc-ccs)
[760.410134] Starting subtest: pipe-A-crc-primary-basic-4-tiled-mtl-mc-ccs
[762.985888] Subtest pipe-A-crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (2.574s)
[764.200204] [115/143] (325s left) kms_vrr (flipline)
[764.316276] Starting subtest: flipline
[764.321032] Subtest flipline: SKIP (0.000s)
[764.388354] [116/143] (325s left) kms_flip (flip-vs-suspend-interruptible)
[764.489665] Starting subtest: flip-vs-suspend-interruptible
[764.492029] Starting dynamic subtest: A-eDP1
[813.514992] Dynamic subtest A-eDP1: SUCCESS (4.646s)
[813.529613] Starting dynamic subtest: B-eDP1
[862.528777] Dynamic subtest B-eDP1: SUCCESS (4.661s)
[862.547951] Starting dynamic subtest: C-eDP1
[911.504883] Dynamic subtest C-eDP1: SUCCESS (4.644s)
[911.517544] Starting dynamic subtest: D-eDP1
[960.542788] Dynamic subtest D-eDP1: SUCCESS (4.623s)
[960.551497] Subtest flip-vs-suspend-interruptible: SUCCESS (18.591s)
[961.834690] [117/143] (305s left) gem_pread (exhaustion)
[961.923092] Subtest exhaustion: SKIP (0.000s)
[961.980310] [118/143] (305s left) perf_pmu (init-busy)
[962.064804] Starting subtest: init-busy
[962.067462] Starting dynamic subtest: rcs0
[962.070087] Dynamic subtest rcs0: SUCCESS (0.003s)
[962.090262] Starting dynamic subtest: bcs0
[962.092746] Dynamic subtest bcs0: SUCCESS (0.001s)
[962.107302] Starting dynamic subtest: vcs0
[962.109938] Dynamic subtest vcs0: SUCCESS (0.001s)
[962.120947] Starting dynamic subtest: vcs1
[962.123039] Dynamic subtest vcs1: SUCCESS (0.001s)
[962.138819] Starting dynamic subtest: vecs0
[962.140957] Dynamic subtest vecs0: SUCCESS (0.001s)
[962.160008] Starting dynamic subtest: ccs0
[962.162588] Dynamic subtest ccs0: SUCCESS (0.001s)
[962.164866] Subtest init-busy: SUCCESS (0.012s)
[962.197756] [119/143] (305s left) kms_legacy_colorkey (basic)
[962.308639] Starting subtest: basic
[962.311032] Starting dynamic subtest: pipe-A
[962.349365] Dynamic subtest pipe-A: SUCCESS (0.045s)
[962.367276] Starting dynamic subtest: pipe-B
[962.370372] Dynamic subtest pipe-B: SUCCESS (0.001s)
[962.382530] Starting dynamic subtest: pipe-C
[962.385582] Dynamic subtest pipe-C: SUCCESS (0.001s)
[962.395357] Starting dynamic subtest: pipe-D
[962.397772] Dynamic subtest pipe-D: SUCCESS (0.001s)
[962.400363] Subtest basic: SUCCESS (0.053s)
[962.428976] [120/143] (304s left) kms_psr_stress_test (flip-primary-invalidate-overlay)
[962.518941] Starting subtest: flip-primary-invalidate-overlay
[974.146279] Subtest flip-primary-invalidate-overlay: SUCCESS (11.629s)
[974.344472] [121/143] (293s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[974.696952] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[975.604209] [122/143] (291s left) kms_ccs (pipe-c-bad-aux-stride-4-tiled-mtl-mc-ccs)
[976.036267] Starting subtest: pipe-C-bad-aux-stride-4-tiled-mtl-mc-ccs
[976.107916] Subtest pipe-C-bad-aux-stride-4-tiled-mtl-mc-ccs: SUCCESS (0.072s)
[976.875588] [123/143] (290s left) gem_lmem_swapping (parallel-multi)
[976.955991] Subtest parallel-multi: SKIP (0.000s)
[976.978314] [124/143] (290s left) gem_exec_whisper (basic-sync-all)
[977.052629] Starting subtest: basic-sync-all
[987.153289] Subtest basic-sync-all: SUCCESS (10.100s)
[987.345614] [125/143] (280s left) prime_vgem (shrink)
[987.440572] Starting subtest: shrink
[987.460038] Subtest shrink: SUCCESS (0.018s)
[987.548581] [126/143] (279s left) gem_exec_fence (basic-busy)
[987.738776] Starting subtest: basic-busy
[987.741590] Starting dynamic subtest: rcs0
[987.805346] Dynamic subtest rcs0: SUCCESS (0.067s)
[987.826772] Starting dynamic subtest: bcs0
[987.868112] Dynamic subtest bcs0: SUCCESS (0.061s)
[987.888515] Starting dynamic subtest: vcs0
[987.922436] Dynamic subtest vcs0: SUCCESS (0.053s)
[987.941059] Starting dynamic subtest: vcs1
[987.985070] Dynamic subtest vcs1: SUCCESS (0.061s)
[988.005690] Starting dynamic subtest: vecs0
[988.044349] Dynamic subtest vecs0: SUCCESS (0.058s)
[988.064029] Starting dynamic subtest: ccs0
[988.108118] Dynamic subtest ccs0: SUCCESS (0.062s)
[988.111450] Subtest basic-busy: SUCCESS (0.371s)
[990.058358] [127/143] (277s left) syncobj_timeline (invalid-signal-bad-pad)
[990.143490] Starting subtest: invalid-signal-bad-pad
[990.145908] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[990.228484] [128/143] (277s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[990.590999] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[991.472198] [129/143] (276s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180)
[991.581135] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180
[991.585856] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[991.674623] [130/143] (275s left) gem_render_copy_redux (flink)
[991.759865] Starting subtest: flink
[992.740554] Subtest flink: SUCCESS (0.981s)
[992.848199] [131/143] (274s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[992.959561] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[998.825902] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (5.866s)
[998.939038] [132/143] (268s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[999.018980] Starting subtest: fbcpsr-modesetfrombusy
[1003.308416] Subtest fbcpsr-modesetfrombusy: SUCCESS (4.298s)
[1003.438946] [133/143] (264s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[1003.516868] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[1003.520470] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[1003.626005] [134/143] (264s left) kms_frontbuffer_tracking (fbc-2p-shrfb-fliptrack-mmap-gtt)
[1003.719623] Starting subtest: fbc-2p-shrfb-fliptrack-mmap-gtt
[1003.723817] Subtest fbc-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[1003.803005] [135/143] (263s left) gem_mmap_gtt (medium-copy-odd)
[1003.885884] Subtest medium-copy-odd: SKIP (0.000s)
[1003.937867] [136/143] (263s left) kms_frontbuffer_tracking (psr-2p-pri-indfb-multidraw)
[1004.058720] Starting subtest: psr-2p-pri-indfb-multidraw
[1004.062255] Subtest psr-2p-pri-indfb-multidraw: SKIP (0.000s)
[1004.142003] [137/143] (263s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[1004.225670] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[1004.229120] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[1004.316348] [138/143] (263s left) i915_suspend (forcewake)
[1004.385422] Starting subtest: forcewake
[1036.525094] Subtest forcewake: SUCCESS (2.248s)
[1036.583767] [139/143] (261s left) kms_flip (flip-vs-panning-interruptible)
[1036.688048] Starting subtest: flip-vs-panning-interruptible
[1036.694358] Starting dynamic subtest: A-eDP1
[1045.531570] Dynamic subtest A-eDP1: SUCCESS (8.841s)
[1045.551119] Starting dynamic subtest: B-eDP1
[1054.363245] Dynamic subtest B-eDP1: SUCCESS (8.830s)
[1054.385102] Starting dynamic subtest: C-eDP1
[1063.187055] Dynamic subtest C-eDP1: SUCCESS (8.822s)
[1063.204539] Starting dynamic subtest: D-eDP1
[1072.017864] Dynamic subtest D-eDP1: SUCCESS (8.829s)
[1072.026684] Subtest flip-vs-panning-interruptible: SUCCESS (35.339s)
[1073.303915] [140/143] (224s left) kms_psr2_sf (fbc-overlay-primary-update-sf-dmg-area)
[1078.562611] Starting subtest: fbc-overlay-primary-update-sf-dmg-area
[1078.565803] Starting dynamic subtest: pipe-A-eDP-1
[1079.395875] Dynamic subtest pipe-A-eDP-1: SKIP (0.832s)
[1079.412734] Starting dynamic subtest: pipe-B-eDP-1
[1080.680348] Dynamic subtest pipe-B-eDP-1: SKIP (1.280s)
[1080.686451] Subtest fbc-overlay-primary-update-sf-dmg-area: SKIP (2.120s)
[1081.957408] [141/143] (215s left) syncobj_timeline (multi-wait-signaled)
[1082.018956] Starting subtest: multi-wait-signaled
[1082.021370] Subtest multi-wait-signaled: SUCCESS (0.001s)
[1082.088453] [142/143] (215s left) kms_hdr (invalid-metadata-sizes)
[1082.145002] Starting subtest: invalid-metadata-sizes
[1082.148829] Subtest invalid-metadata-sizes: SKIP (0.000s)
[1082.200324] [143/143] (215s left) kms_psr (psr2-primary-page-flip)
[1082.291273] Starting subtest: psr2-primary-page-flip
[1082.293694] Starting dynamic subtest: eDP-1
[1084.101132] Dynamic subtest eDP-1: SUCCESS (1.809s)
[1084.103823] Subtest psr2-primary-page-flip: SUCCESS (1.810s)
[1085.009885] Closing watchdogs
Done.