[62.273134] Initializing watchdogs
[62.273265]   /dev/watchdog0
[62.288860] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[62.293299] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[62.295758] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.297714] [001/133] (960s left) kms_ccs (crc-primary-suspend-4-tiled-dg2-mc-ccs)
[62.408096] Starting subtest: crc-primary-suspend-4-tiled-dg2-mc-ccs
[62.409057] Starting dynamic subtest: pipe-A-eDP-1
[62.412521] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[62.421500] Starting dynamic subtest: pipe-B-eDP-1
[62.425920] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[62.433074] Starting dynamic subtest: pipe-C-eDP-1
[62.436781] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[62.445218] Starting dynamic subtest: pipe-D-eDP-1
[62.448609] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[62.450317] Subtest crc-primary-suspend-4-tiled-dg2-mc-ccs: SKIP (0.003s)
[64.666511] [002/133] (958s left) i915_query (query-topology-known-pci-ids)
[64.721180] Starting subtest: query-topology-known-pci-ids
[64.722862] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[64.839400] [003/133] (958s left) gem_spin_batch (resubmit-new)
[64.917484] Starting subtest: resubmit-new
[64.918450] Starting dynamic subtest: rcs0
[64.931557] Dynamic subtest rcs0: SUCCESS (0.014s)
[64.937727] Starting dynamic subtest: bcs0
[64.938537] Dynamic subtest bcs0: SUCCESS (0.005s)
[64.943915] Starting dynamic subtest: vcs0
[64.944801] Dynamic subtest vcs0: SUCCESS (0.004s)
[64.950763] Starting dynamic subtest: vcs1
[64.951524] Dynamic subtest vcs1: SUCCESS (0.005s)
[64.957320] Starting dynamic subtest: vecs0
[64.958185] Dynamic subtest vecs0: SUCCESS (0.006s)
[64.963586] Starting dynamic subtest: ccs0
[64.964326] Dynamic subtest ccs0: SUCCESS (0.003s)
[64.965060] Subtest resubmit-new: SUCCESS (0.040s)
[66.876098] [004/133] (956s left) kms_draw_crc (draw-method-render)
[67.179071] Starting subtest: draw-method-render
[67.180480] Starting dynamic subtest: xrgb8888-untiled
[67.459465] Dynamic subtest xrgb8888-untiled: SUCCESS (0.280s)
[67.470609] Starting dynamic subtest: rgb565-untiled
[67.704109] Dynamic subtest rgb565-untiled: SUCCESS (0.244s)
[67.712032] Starting dynamic subtest: xrgb2101010-untiled
[67.926723] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.221s)
[67.933277] Starting dynamic subtest: xbgr16161616f-untiled
[68.160145] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.233s)
[68.166552] Starting dynamic subtest: xrgb8888-xtiled
[68.282404] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[68.289598] Starting dynamic subtest: rgb565-xtiled
[68.405084] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[68.412210] Starting dynamic subtest: xrgb2101010-xtiled
[68.516353] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.111s)
[68.525045] Starting dynamic subtest: xbgr16161616f-xtiled
[68.649663] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.133s)
[68.655857] Starting dynamic subtest: xrgb8888-4tiled
[68.771950] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.122s)
[68.777986] Starting dynamic subtest: rgb565-4tiled
[68.883220] Dynamic subtest rgb565-4tiled: SUCCESS (0.111s)
[68.889633] Starting dynamic subtest: xrgb2101010-4tiled
[68.994400] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.111s)
[69.001059] Starting dynamic subtest: xbgr16161616f-4tiled
[69.128229] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.133s)
[69.129683] Subtest draw-method-render: SUCCESS (1.947s)
[69.267983] [005/133] (953s left) gem_exec_schedule (noreorder)
[69.448614] Starting subtest: noreorder
[69.449559] Starting dynamic subtest: rcs0
[69.521532] Dynamic subtest rcs0: SUCCESS (0.077s)
[69.530278] Starting dynamic subtest: vcs0
[70.968858] Dynamic subtest vcs0: SUCCESS (1.435s)
[70.977565] Starting dynamic subtest: vcs1
[72.909788] Dynamic subtest vcs1: SUCCESS (1.938s)
[72.918559] Starting dynamic subtest: vecs0
[74.925632] Dynamic subtest vecs0: SUCCESS (2.014s)
[74.933951] Starting dynamic subtest: ccs0
[75.025464] Dynamic subtest ccs0: SUCCESS (0.109s)
[75.026596] Subtest noreorder: SUCCESS (5.578s)
[75.217111] [006/133] (947s left) gem_ctx_shared (disjoint-timelines)
[75.278998] Starting subtest: disjoint-timelines
[75.328356] Subtest disjoint-timelines: SUCCESS (0.049s)
[75.587846] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[75.589547] [007/133] (947s left) gem_tiled_wb
[75.705302] [008/133] (947s left) gem_partial_pwrite_pread (write-snoop)
[75.764947] Subtest write-snoop: SKIP (0.000s)
[75.839260] [009/133] (947s left) api_intel_allocator (fork-simple-stress-signal)
[75.886134] Starting subtest: fork-simple-stress-signal
[81.341557] Subtest fork-simple-stress-signal: SUCCESS (5.406s)
[81.426974] [010/133] (941s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[81.478124] Starting subtest: invalid-multi-wait-unsubmitted
[81.479121] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[81.598310] [011/133] (941s left) gem_exec_gttfill (multigpu-basic)
[81.786079] Starting subtest: multigpu-basic
[101.005670] Subtest multigpu-basic: SUCCESS (19.214s)
[101.037586] [012/133] (922s left) gem_fence_thrash (bo-write-verify-threaded-y)
[101.113764] Starting subtest: bo-write-verify-threaded-y
[101.129859] Subtest bo-write-verify-threaded-y: SKIP (0.016s)
[101.183847] [013/133] (921s left) kms_pm_dc (dc3co-vpb-simulation)
[102.314027] Starting subtest: dc3co-vpb-simulation
[111.046671] Subtest dc3co-vpb-simulation: SKIP (8.685s)
[111.177013] [014/133] (912s left) i915_drm_fdinfo (busy-idle-check-all)
[111.217013] Starting subtest: busy-idle-check-all
[111.218014] Starting dynamic subtest: rcs0
[111.221965] Dynamic subtest rcs0: SKIP (0.004s)
[111.228812] Starting dynamic subtest: bcs0
[111.230579] Dynamic subtest bcs0: SKIP (0.000s)
[111.236655] Starting dynamic subtest: vcs0
[111.238334] Dynamic subtest vcs0: SKIP (0.000s)
[111.244363] Starting dynamic subtest: vcs1
[111.246109] Dynamic subtest vcs1: SKIP (0.000s)
[111.251964] Starting dynamic subtest: vecs0
[111.253727] Dynamic subtest vecs0: SKIP (0.000s)
[111.259819] Starting dynamic subtest: ccs0
[111.261497] Dynamic subtest ccs0: SKIP (0.000s)
[111.263138] Subtest busy-idle-check-all: SKIP (0.007s)
[111.293220] [015/133] (911s left) kms_psr (pr-cursor-plane-move)
[111.408261] Starting subtest: pr-cursor-plane-move
[111.410118] Subtest pr-cursor-plane-move: SKIP (0.000s)
[111.485600] [016/133] (911s left) perf_pmu (idle-no-semaphores)
[111.524127] Starting subtest: idle-no-semaphores
[111.525032] Starting dynamic subtest: rcs0
[112.072954] Dynamic subtest rcs0: SUCCESS (0.539s)
[112.082338] Starting dynamic subtest: bcs0
[112.597269] Dynamic subtest bcs0: SUCCESS (0.523s)
[112.606903] Starting dynamic subtest: vcs0
[113.124543] Dynamic subtest vcs0: SUCCESS (0.526s)
[113.134245] Starting dynamic subtest: vcs1
[113.643745] Dynamic subtest vcs1: SUCCESS (0.518s)
[113.652839] Starting dynamic subtest: vecs0
[114.162484] Dynamic subtest vecs0: SUCCESS (0.517s)
[114.172092] Starting dynamic subtest: ccs0
[114.674307] Dynamic subtest ccs0: SUCCESS (0.510s)
[114.675774] Subtest idle-no-semaphores: SUCCESS (3.141s)
[114.770514] [017/133] (908s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[114.887730] Starting subtest: basic-busy-flip-before-cursor-varying-size
[114.889710] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[115.036874] [018/133] (908s left) syncobj_timeline (invalid-signal-zero-handles)
[115.094639] Starting subtest: invalid-signal-zero-handles
[115.095759] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[115.202385] [019/133] (908s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[115.288959] Starting subtest: fb-8bpc-vs-panel-8bpc
[115.290049] Starting dynamic subtest: pipe-A-eDP-1
[116.652040] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.362s)
[116.652926] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.363s)
[117.456747] [020/133] (905s left) gem_mmap_offset (basic-uaf)
[117.502147] Starting subtest: basic-uaf
[117.504657] Subtest basic-uaf: SUCCESS (0.003s)
[117.602077] [021/133] (905s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-gtt)
[117.698943] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-gtt
[117.700692] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[117.823269] [022/133] (905s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[117.877891] Starting subtest: multi-wait-all-for-submit-signaled
[117.879243] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[117.976943] [023/133] (905s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted-submitted)
[118.032019] Starting subtest: etime-multi-wait-all-available-unsubmitted-submitted
[118.136957] Subtest etime-multi-wait-all-available-unsubmitted-submitted: SUCCESS (0.104s)
[118.233330] [024/133] (905s left) gem_eio (in-flight-internal-10ms)
[118.456769] Starting subtest: in-flight-internal-10ms
[119.964035] Subtest in-flight-internal-10ms: SUCCESS (1.507s)
[120.130419] [025/133] (903s left) gem_userptr_blits (map-fixed-invalidate-busy)
[120.188476] Starting subtest: map-fixed-invalidate-busy
[120.190207] Subtest map-fixed-invalidate-busy: SKIP (0.000s)
[120.321760] [026/133] (903s left) perf_pmu (busy)
[120.351240] Starting subtest: busy
[120.352275] Starting dynamic subtest: rcs0
[120.942415] Dynamic subtest rcs0: SUCCESS (0.591s)
[120.951959] Starting dynamic subtest: bcs0
[121.504247] Dynamic subtest bcs0: SUCCESS (0.561s)
[121.513997] Starting dynamic subtest: vcs0
[122.909756] Dynamic subtest vcs0: SUCCESS (1.395s)
[122.919857] Starting dynamic subtest: vcs1
[124.893697] Dynamic subtest vcs1: SUCCESS (1.982s)
[124.903793] Starting dynamic subtest: vecs0
[126.941697] Dynamic subtest vecs0: SUCCESS (2.047s)
[126.950725] Starting dynamic subtest: ccs0
[127.501775] Dynamic subtest ccs0: SUCCESS (0.569s)
[127.502874] Subtest busy: SUCCESS (7.150s)
[127.622689] [027/133] (896s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[127.723046] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[127.736557] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.013s)
[127.822602] [028/133] (895s left) gem_exec_fence (expired-history)
[128.240271] Starting subtest: expired-history
[135.669268] Subtest expired-history: SUCCESS (7.420s)
[136.234203] [029/133] (887s left) gem_exec_params (batch-first)
[136.284986] Starting subtest: batch-first
[136.290975] Subtest batch-first: SUCCESS (0.007s)
[136.447010] [030/133] (887s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[136.548678] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[136.560696] Subtest 2x-plain-flip-fb-recreate-interruptible: SKIP (0.011s)
[136.640612] [031/133] (887s left) gem_exec_balancer (hang)
[136.910049] Starting subtest: hang
[138.947355] Subtest hang: SUCCESS (2.038s)
[139.092538] [032/133] (884s left) kms_cursor_crc (cursor-onscreen-256x256)
[139.329335] Starting subtest: cursor-onscreen-256x256
[139.333000] Starting dynamic subtest: pipe-A-eDP-1
[141.507723] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.175s)
[141.536185] Starting dynamic subtest: pipe-D-eDP-1
[144.363500] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.833s)
[144.364404] Subtest cursor-onscreen-256x256: SUCCESS (5.033s)
[145.228201] [033/133] (878s left) gem_userptr_blits (vma-merge)
[145.299140] Starting subtest: vma-merge
[145.341489] Subtest vma-merge: SUCCESS (0.043s)
[145.481398] [034/133] (878s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[145.588311] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[153.109250] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (7.521s)
[153.238535] [035/133] (870s left) gem_ctx_isolation (nonpriv)
[153.306620] Starting subtest: nonpriv
[153.307746] Starting dynamic subtest: rcs0
[153.534162] Dynamic subtest rcs0: SUCCESS (0.227s)
[153.544354] Starting dynamic subtest: bcs0
[153.801015] Dynamic subtest bcs0: SUCCESS (0.266s)
[153.811806] Starting dynamic subtest: vcs0
[154.063531] Dynamic subtest vcs0: SUCCESS (0.262s)
[154.073192] Starting dynamic subtest: vcs1
[156.184530] Dynamic subtest vcs1: SUCCESS (2.120s)
[156.194832] Starting dynamic subtest: vecs0
[158.177809] Dynamic subtest vecs0: SUCCESS (1.993s)
[158.187707] Starting dynamic subtest: ccs0
[160.173626] Dynamic subtest ccs0: SUCCESS (1.996s)
[160.174645] Subtest nonpriv: SUCCESS (6.867s)
[160.427520] [036/133] (863s left) gem_mmap_gtt (cpuset-medium-copy)
[160.475102] Subtest cpuset-medium-copy: SKIP (0.000s)
[160.537613] [037/133] (863s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[160.638341] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-wc
[160.639956] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[160.769059] [038/133] (863s left) gem_exec_schedule (preempt-user)
[160.841672] Starting subtest: preempt-user
[160.842799] Starting dynamic subtest: rcs0
[160.866783] Dynamic subtest rcs0: SUCCESS (0.025s)
[160.874752] Starting dynamic subtest: bcs0
[160.886024] Dynamic subtest bcs0: SUCCESS (0.019s)
[160.893915] Starting dynamic subtest: vcs0
[160.907509] Dynamic subtest vcs0: SUCCESS (0.021s)
[160.914922] Starting dynamic subtest: vcs1
[160.928550] Dynamic subtest vcs1: SUCCESS (0.021s)
[160.935735] Starting dynamic subtest: vecs0
[160.947925] Dynamic subtest vecs0: SUCCESS (0.019s)
[160.955183] Starting dynamic subtest: ccs0
[160.969638] Dynamic subtest ccs0: SUCCESS (0.021s)
[160.970578] Subtest preempt-user: SUCCESS (0.128s)
[163.018228] [039/133] (860s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[163.103422] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[163.105324] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[163.227265] [040/133] (860s left) kms_invalid_mode (bad-vsync-end)
[163.315983] Starting subtest: bad-vsync-end
[163.317098] Starting dynamic subtest: pipe-A-eDP-1
[163.611199] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.295s)
[163.625122] Starting dynamic subtest: pipe-B-eDP-1
[163.625929] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[163.632893] Starting dynamic subtest: pipe-C-eDP-1
[163.633778] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[163.640779] Starting dynamic subtest: pipe-D-eDP-1
[163.641636] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[163.642509] Subtest bad-vsync-end: SUCCESS (0.299s)
[164.469621] [041/133] (859s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling)
[164.559130] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling
[164.560874] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.000s)
[164.635257] [042/133] (859s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[164.693598] Starting subtest: multi-wait-all-for-submit-submitted
[164.800698] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.107s)
[164.891500] [043/133] (859s left) core_getversion (all-cards)
[164.927459] Starting subtest: all-cards
[164.936922] Subtest all-cards: SUCCESS (0.011s)
[164.978291] [044/133] (859s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area-big-fb)
[165.076176] Starting subtest: psr2-primary-plane-update-sf-dmg-area-big-fb
[165.077065] Starting dynamic subtest: pipe-A-eDP-1
[171.895187] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.819s)
[171.902613] Starting dynamic subtest: pipe-B-eDP-1
[179.063726] Dynamic subtest pipe-B-eDP-1: SUCCESS (7.168s)
[179.064651] Subtest psr2-primary-plane-update-sf-dmg-area-big-fb: SUCCESS (13.989s)
[179.922076] [045/133] (844s left) kms_vblank (invalid)
[180.018468] Starting subtest: invalid
[180.019405] Starting dynamic subtest: pipe-A-eDP-1
[180.427962] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.409s)
[180.428982] Subtest invalid: SUCCESS (0.410s)
[181.263409] [046/133] (842s left) gem_exec_schedule (u-submit-golden-slice)
[181.338064] Starting subtest: u-submit-golden-slice
[181.339016] Starting dynamic subtest: rcs0
[181.372170] Dynamic subtest rcs0: SUCCESS (0.034s)
[181.382132] Starting dynamic subtest: bcs0
[181.415047] Dynamic subtest bcs0: SUCCESS (0.042s)
[181.424949] Starting dynamic subtest: vcs0
[181.449909] Dynamic subtest vcs0: SUCCESS (0.034s)
[181.457314] Starting dynamic subtest: vcs1
[181.486328] Dynamic subtest vcs1: SUCCESS (0.036s)
[181.494281] Starting dynamic subtest: vecs0
[181.521405] Dynamic subtest vecs0: SUCCESS (0.035s)
[181.529193] Starting dynamic subtest: ccs0
[181.557956] Dynamic subtest ccs0: SUCCESS (0.036s)
[181.558962] Subtest u-submit-golden-slice: SUCCESS (0.220s)
[183.066670] [047/133] (841s left) i915_suspend (fence-restore-untiled)
[183.141598] Starting subtest: fence-restore-untiled
[183.143581] Subtest fence-restore-untiled: SKIP (0.000s)
[183.262527] [048/133] (841s left) kms_flip (modeset-vs-vblank-race)
[183.364274] Starting subtest: modeset-vs-vblank-race
[183.365350] Starting dynamic subtest: A-eDP1
[188.075099] Dynamic subtest A-eDP1: SUCCESS (4.710s)
[188.085908] Starting dynamic subtest: B-eDP1
[192.806916] Dynamic subtest B-eDP1: SUCCESS (4.730s)
[192.817971] Starting dynamic subtest: C-eDP1
[197.433158] Dynamic subtest C-eDP1: SUCCESS (4.625s)
[197.442016] Starting dynamic subtest: D-eDP1
[202.071762] Dynamic subtest D-eDP1: SUCCESS (4.637s)
[202.074878] Subtest modeset-vs-vblank-race: SUCCESS (18.711s)
[203.246972] [049/133] (821s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[203.355522] Starting subtest: yf-tiled-64bpp-rotate-180
[203.357362] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[203.467547] [050/133] (820s left) fbdev (pan)
[203.494344] Starting subtest: pan
[203.520240] Subtest pan: SUCCESS (0.026s)
[203.596662] [051/133] (820s left) gem_eio (in-flight-contexts-immediate)
[203.830516] Starting subtest: in-flight-contexts-immediate
[217.894584] Subtest in-flight-contexts-immediate: SUCCESS (14.064s)
[218.020502] [052/133] (806s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[218.124955] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[224.938495] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.817s)
[225.093171] [053/133] (799s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[225.185016] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[225.185974] Starting dynamic subtest: pipe-A
[225.524774] Dynamic subtest pipe-A: SUCCESS (0.339s)
[225.533470] Starting dynamic subtest: pipe-B
[226.943571] Dynamic subtest pipe-B: SUCCESS (1.418s)
[226.951068] Starting dynamic subtest: pipe-C
[228.381061] Dynamic subtest pipe-C: SUCCESS (1.437s)
[228.390031] Starting dynamic subtest: pipe-D
[229.860353] Dynamic subtest pipe-D: SUCCESS (1.477s)
[229.861369] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (4.676s)
[230.997081] [054/133] (793s left) prime_mmap_coherency (write)
[231.054343] Starting subtest: write
[236.475885] Subtest write: SUCCESS (5.376s)
[236.601621] [055/133] (787s left) gem_exec_reloc (basic-write-read-noreloc)
[236.661264] Subtest basic-write-read-noreloc: SKIP (0.000s)
[236.737421] [056/133] (787s left) kms_psr (fbc-psr-no-drrs)
[236.841139] Starting subtest: fbc-psr-no-drrs
[236.842060] Starting dynamic subtest: eDP-1
[236.844065] Dynamic subtest eDP-1: SKIP (0.000s)
[236.845841] Subtest fbc-psr-no-drrs: SKIP (0.001s)
[236.922262] [057/133] (787s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180)
[237.009168] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180
[238.099825] Subtest x-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.091s)
[238.264748] [058/133] (786s left) kms_psr2_sf (fbc-pr-overlay-plane-update-continuous-sf)
[238.359698] Starting subtest: fbc-pr-overlay-plane-update-continuous-sf
[238.361556] Subtest fbc-pr-overlay-plane-update-continuous-sf: SKIP (0.000s)
[238.443022] [059/133] (786s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[238.499455] Subtest writes-after-reads-uncached: SKIP (0.000s)
[238.562667] [060/133] (786s left) kms_lease (possible-crtcs-filtering)
[238.658333] Starting subtest: possible-crtcs-filtering
[238.662764] Subtest possible-crtcs-filtering: SUCCESS (0.005s)
[238.781478] [061/133] (785s left) gem_exec_reloc (basic-wc-noreloc)
[238.847973] Subtest basic-wc-noreloc: SKIP (0.000s)
[238.934546] [062/133] (785s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[239.026981] Starting subtest: plane-upscale-factor-0-25-with-rotation
[239.027940] Starting dynamic subtest: pipe-A
[239.254092] Dynamic subtest pipe-A: SUCCESS (0.227s)
[239.261582] Starting dynamic subtest: pipe-B
[240.576960] Dynamic subtest pipe-B: SUCCESS (1.322s)
[240.584526] Starting dynamic subtest: pipe-C
[241.911171] Dynamic subtest pipe-C: SUCCESS (1.333s)
[241.918640] Starting dynamic subtest: pipe-D
[243.248643] Dynamic subtest pipe-D: SUCCESS (1.337s)
[243.249435] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.222s)
[244.454786] [063/133] (780s left) gem_exec_fence (submit-chain)
[244.641073] Starting subtest: submit-chain
[244.653969] Subtest submit-chain: SUCCESS (0.019s)
[246.027324] [064/133] (778s left) i915_pm_rps (engine-order)
[246.084045] Starting subtest: engine-order
[252.502255] Subtest engine-order: SUCCESS (6.410s)
[252.644573] [065/133] (772s left) kms_multipipe_modeset (basic-max-pipe-crc-check)
[252.737605] Starting subtest: basic-max-pipe-crc-check
[252.739503] Subtest basic-max-pipe-crc-check: SKIP (0.000s)
[252.782509] [066/133] (772s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[252.836406] Starting subtest: single-wait-all-for-submit-available-signaled
[252.837419] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[252.945900] [067/133] (772s left) kms_ccs (bad-aux-stride-y-tiled-ccs)
[253.039330] Starting subtest: bad-aux-stride-y-tiled-ccs
[253.040232] Starting dynamic subtest: pipe-A-eDP-1
[253.049018] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[253.056577] Starting dynamic subtest: pipe-B-eDP-1
[253.064969] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[253.073257] Starting dynamic subtest: pipe-C-eDP-1
[253.081519] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[253.088746] Starting dynamic subtest: pipe-D-eDP-1
[253.096819] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[253.098374] Subtest bad-aux-stride-y-tiled-ccs: SKIP (0.005s)
[255.218143] [068/133] (769s left) prime_vgem (basic-fence-flip)
[255.300660] Starting subtest: basic-fence-flip
[255.591670] Subtest basic-fence-flip: SUCCESS (0.291s)
[255.702752] [069/133] (769s left) gen7_exec_parse (oacontrol-tracking)
[255.762483] Subtest oacontrol-tracking: SKIP (0.000s)
[255.840937] [070/133] (769s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[255.941369] Starting subtest: crc-primary-basic-y-tiled-ccs
[255.942286] Starting dynamic subtest: pipe-A-eDP-1
[255.950829] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[255.958964] Starting dynamic subtest: pipe-B-eDP-1
[255.967868] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[255.976736] Starting dynamic subtest: pipe-C-eDP-1
[255.984766] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[255.992082] Starting dynamic subtest: pipe-D-eDP-1
[256.000349] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[256.001901] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.006s)
[258.110880] [071/133] (766s left) kms_psr (psr2-cursor-mmap-gtt)
[258.202517] Starting subtest: psr2-cursor-mmap-gtt
[258.203444] Starting dynamic subtest: eDP-1
[259.881395] Dynamic subtest eDP-1: SUCCESS (1.678s)
[259.882256] Subtest psr2-cursor-mmap-gtt: SUCCESS (1.679s)
[260.677969] [072/133] (764s left) api_intel_bb (lot-of-buffers)
[260.740470] Starting subtest: lot-of-buffers
[261.860227] Subtest lot-of-buffers: SUCCESS (1.110s)
[262.101251] [073/133] (763s left) kms_chamelium_hpd (common-hpd-after-suspend)
[262.483694] Subtest common-hpd-after-suspend: SKIP (0.000s)
[263.267906] [074/133] (761s left) syncobj_eventfd (binary-wait)
[263.314761] Starting subtest: binary-wait
[263.325051] Subtest binary-wait: SUCCESS (0.010s)
[263.403125] [075/133] (761s left) kms_psr (psr-primary-blt)
[263.510499] Starting subtest: psr-primary-blt
[263.511379] Starting dynamic subtest: eDP-1
[265.295734] Dynamic subtest eDP-1: SUCCESS (1.783s)
[265.296823] Subtest psr-primary-blt: SUCCESS (1.784s)
[266.160387] [076/133] (759s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[266.264861] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[266.266574] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[266.363844] [077/133] (758s left) gem_ctx_persistence (legacy-engines-hang)
[266.426590] Starting subtest: legacy-engines-hang
[266.427711] Starting dynamic subtest: render
[266.551761] Dynamic subtest render: SUCCESS (0.124s)
[266.563073] Starting dynamic subtest: bsd1
[266.668826] Dynamic subtest bsd1: SUCCESS (0.114s)
[266.682147] Starting dynamic subtest: bsd2
[266.773866] Dynamic subtest bsd2: SUCCESS (0.102s)
[266.789328] Starting dynamic subtest: blt
[266.893808] Dynamic subtest blt: SUCCESS (0.117s)
[266.905627] Starting dynamic subtest: vebox
[266.972252] Dynamic subtest vebox: SUCCESS (0.076s)
[266.975141] Subtest legacy-engines-hang: SUCCESS (0.547s)
[267.126773] [078/133] (758s left) kms_atomic (atomic-plane-damage)
[267.209706] Starting subtest: atomic-plane-damage
[267.258220] Starting dynamic subtest: pipe-A-eDP-1
[267.785901] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.527s)
[267.786892] Subtest atomic-plane-damage: SUCCESS (0.578s)
[268.602924] [079/133] (756s left) sysfs_timeslice_duration (idempotent)
[268.667294] Starting subtest: idempotent
[268.668289] Starting dynamic subtest: vecs0
[268.669259] Dynamic subtest vecs0: SUCCESS (0.000s)
[268.678470] Starting dynamic subtest: ccs0
[268.679348] Dynamic subtest ccs0: SUCCESS (0.000s)
[268.686858] Starting dynamic subtest: vcs0
[268.687826] Dynamic subtest vcs0: SUCCESS (0.000s)
[268.696100] Starting dynamic subtest: bcs0
[268.696932] Dynamic subtest bcs0: SUCCESS (0.000s)
[268.704069] Starting dynamic subtest: rcs0
[268.704898] Dynamic subtest rcs0: SUCCESS (0.000s)
[268.712132] Starting dynamic subtest: vcs1
[268.712946] Dynamic subtest vcs1: SUCCESS (0.000s)
[268.713972] Subtest idempotent: SUCCESS (0.006s)
[268.801778] [080/133] (756s left) kms_fence_pin_leak
[268.966571] [081/133] (756s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[269.053726] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[272.816769] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.764s)
[274.003495] [082/133] (751s left) gem_exec_params (rel-constants-invalid-ring)
[274.050320] Starting subtest: rel-constants-invalid-ring
[274.051212] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[274.178551] [083/133] (751s left) kms_color (ctm-0-75)
[274.261332] Starting subtest: ctm-0-75
[274.262104] Starting dynamic subtest: pipe-A-eDP-1
[279.243345] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.981s)
[279.251272] Starting dynamic subtest: pipe-B-eDP-1
[284.885793] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.641s)
[284.895059] Starting dynamic subtest: pipe-C-eDP-1
[290.363986] Dynamic subtest pipe-C-eDP-1: SUCCESS (5.475s)
[290.371522] Starting dynamic subtest: pipe-D-eDP-1
[295.677041] Dynamic subtest pipe-D-eDP-1: SUCCESS (5.312s)
[295.677950] Subtest ctm-0-75: SUCCESS (21.414s)
[296.513902] [084/133] (729s left) i915_query (query-topology-unsupported)
[296.577784] Starting subtest: query-topology-unsupported
[296.579755] Subtest query-topology-unsupported: SKIP (0.000s)
[296.702126] [085/133] (728s left) i915_hangman (engine-error-state-capture)
[296.762818] Starting subtest: engine-error-state-capture
[296.763660] Starting dynamic subtest: rcs0
[299.836066] Dynamic subtest rcs0: SUCCESS (3.064s)
[299.846329] Starting dynamic subtest: bcs0
[304.828528] Dynamic subtest bcs0: SUCCESS (4.989s)
[304.838379] Starting dynamic subtest: vcs0
[310.908403] Dynamic subtest vcs0: SUCCESS (6.077s)
[310.918099] Starting dynamic subtest: vcs1
[315.900054] Dynamic subtest vcs1: SUCCESS (4.988s)
[315.910202] Starting dynamic subtest: vecs0
[320.828064] Dynamic subtest vecs0: SUCCESS (4.925s)
[320.841707] Starting dynamic subtest: ccs0
[325.884558] Dynamic subtest ccs0: SUCCESS (5.053s)
[325.885764] Subtest engine-error-state-capture: SUCCESS (29.112s)
[325.965306] [086/133] (699s left) gem_pread (self)
[326.022613] Subtest self: SKIP (0.000s)
[326.091699] [087/133] (699s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[326.197877] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[326.198794] Starting dynamic subtest: pipe-A-eDP-1
[326.297414] Dynamic subtest pipe-A-eDP-1: SKIP (0.100s)
[326.305634] Starting dynamic subtest: pipe-B-eDP-1
[327.473588] Dynamic subtest pipe-B-eDP-1: SKIP (1.174s)
[327.475346] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.277s)
[328.765394] [088/133] (696s left) syncobj_wait (invalid-wait-illegal-handle)
[328.831364] Starting subtest: invalid-wait-illegal-handle
[328.832426] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[328.948989] [089/133] (696s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-pwrite)
[329.044397] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-pwrite
[329.046196] Subtest psr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[329.164256] [090/133] (696s left) gem_userptr_blits (create-destroy-sync)
[329.233722] Starting subtest: create-destroy-sync
[334.288754] Subtest create-destroy-sync: SUCCESS (5.008s)
[334.406463] [091/133] (691s left) kms_addfb_basic (unused-modifier)
[334.464572] Starting subtest: unused-modifier
[334.465394] Subtest unused-modifier: SUCCESS (0.000s)
[334.612164] [092/133] (691s left) kms_properties (connector-properties-atomic)
[334.702221] Starting subtest: connector-properties-atomic
[334.703214] Starting dynamic subtest: pipe-A-eDP-1
[342.656210] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.952s)
[342.663807] Starting dynamic subtest: pipe-None-DP-1
[342.665483] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[342.672567] Starting dynamic subtest: pipe-None-HDMI-A-1
[342.674256] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[342.681174] Starting dynamic subtest: pipe-None-DP-2
[342.682825] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[342.690320] Starting dynamic subtest: pipe-None-HDMI-A-2
[342.692722] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[342.702247] Starting dynamic subtest: pipe-None-DP-3
[342.704253] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[342.711419] Starting dynamic subtest: pipe-None-DP-4
[342.713261] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[342.714212] Subtest connector-properties-atomic: SUCCESS (7.964s)
[343.523662] [093/133] (682s left) kms_vblank (wait-forked)
[343.621269] Starting subtest: wait-forked
[343.622194] Starting dynamic subtest: pipe-A-eDP-1
[345.429677] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.807s)
[345.438597] Starting dynamic subtest: pipe-D-eDP-1
[347.919028] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.486s)
[347.920468] Subtest wait-forked: SUCCESS (4.298s)
[348.787904] [094/133] (677s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[348.881967] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[348.883738] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[349.011711] [095/133] (676s left) kms_content_protection (dp-mst-type-1)
[349.159736] Starting subtest: dp-mst-type-1
[349.161867] Subtest dp-mst-type-1: SKIP (0.000s)
[349.322057] [096/133] (676s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[349.402470] Starting subtest: primary-x-tiled-reflect-x-0
[356.515713] Subtest primary-x-tiled-reflect-x-0: SUCCESS (7.114s)
[357.758140] [097/133] (668s left) kms_big_fb (linear-32bpp-rotate-180)
[357.862278] Starting subtest: linear-32bpp-rotate-180
[359.430977] Subtest linear-32bpp-rotate-180: SUCCESS (1.569s)
[359.573370] [098/133] (666s left) gem_exercise_blt (fast-copy-emit)
[359.636428] Starting subtest: fast-copy-emit
[359.637392] Starting dynamic subtest: linear-smem-smem-emit
[359.658042] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.021s)
[359.667572] Starting dynamic subtest: xmajor-smem-smem-emit
[359.675190] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.017s)
[359.682732] Starting dynamic subtest: tile4-smem-smem-emit
[359.692098] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.016s)
[359.699364] Starting dynamic subtest: tile64-smem-smem-emit
[359.709623] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.017s)
[359.710580] Subtest fast-copy-emit: SUCCESS (0.074s)
[359.863269] [099/133] (666s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[359.961280] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[359.962898] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[360.079976] [100/133] (665s left) kms_plane (pixel-format-yf-tiled-ccs-modifier)
[360.172454] Starting subtest: pixel-format-yf-tiled-ccs-modifier
[360.177467] Subtest pixel-format-yf-tiled-ccs-modifier: SKIP (0.000s)
[360.254260] [101/133] (665s left) kms_psr (fbc-psr2-dpms)
[360.348547] Starting subtest: fbc-psr2-dpms
[360.349596] Starting dynamic subtest: eDP-1
[360.351348] Dynamic subtest eDP-1: SKIP (0.000s)
[360.352964] Subtest fbc-psr2-dpms: SKIP (0.001s)
[360.430239] [102/133] (665s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[360.516155] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[360.517138] Starting dynamic subtest: pipe-A-eDP-1
[360.786897] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.260s)
[360.795195] Starting dynamic subtest: pipe-B-eDP-1
[362.026285] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.237s)
[362.034563] Starting dynamic subtest: pipe-C-eDP-1
[363.239644] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.212s)
[363.247783] Starting dynamic subtest: pipe-D-eDP-1
[364.451941] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.213s)
[364.452671] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.927s)
[365.722320] [103/133] (660s left) perf (i915-ref-count)
[365.808687] Starting subtest: i915-ref-count
[365.866333] Subtest i915-ref-count: SUCCESS (0.057s)
[365.917503] [104/133] (660s left) gem_exec_reloc (basic-wc-cpu-active)
[365.984982] Subtest basic-wc-cpu-active: SKIP (0.000s)
[366.070398] [105/133] (660s left) kms_hdr (invalid-metadata-sizes)
[366.169281] Starting subtest: invalid-metadata-sizes
[366.171796] Subtest invalid-metadata-sizes: SKIP (0.000s)
[366.249313] [106/133] (660s left) vgem_basic (mmap)
[366.294380] Starting subtest: mmap
[366.317457] Subtest mmap: SUCCESS (0.023s)
[366.381017] [107/133] (659s left) kms_busy (extended-modeset-hang-oldfb)
[366.477278] Starting subtest: extended-modeset-hang-oldfb
[366.478117] Starting dynamic subtest: pipe-A
[378.287031] Dynamic subtest pipe-A: SUCCESS (11.807s)
[378.295709] Starting dynamic subtest: pipe-D
[390.558700] Dynamic subtest pipe-D: SUCCESS (12.269s)
[390.559702] Subtest extended-modeset-hang-oldfb: SUCCESS (24.081s)
[391.360951] [108/133] (634s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[391.461743] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[391.463450] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[391.580959] [109/133] (634s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-upscaling)
[391.675172] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-upscaling
[391.676876] Subtest flip-32bpp-ytile-to-64bpp-ytile-upscaling: SKIP (0.000s)
[391.757062] [110/133] (634s left) gem_exec_reloc (basic-gtt-active)
[391.812886] Subtest basic-gtt-active: SKIP (0.000s)
[391.900411] [111/133] (634s left) gem_mmap (short-mmap)
[391.959819] Subtest short-mmap: SKIP (0.000s)
[392.023966] [112/133] (634s left) kms_plane_cursor (primary)
[392.118242] Starting subtest: primary
[392.119906] Starting dynamic subtest: pipe-A-eDP-1-size-64
[394.691151] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.573s)
[394.717135] Starting dynamic subtest: pipe-A-eDP-1-size-128
[397.338610] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.629s)
[397.362634] Starting dynamic subtest: pipe-A-eDP-1-size-256
[399.926720] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.572s)
[400.240228] Starting dynamic subtest: pipe-B-eDP-1-size-64
[403.527436] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.295s)
[403.551817] Starting dynamic subtest: pipe-B-eDP-1-size-128
[406.096885] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.550s)
[406.119987] Starting dynamic subtest: pipe-B-eDP-1-size-256
[408.718339] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.605s)
[409.035495] Starting dynamic subtest: pipe-C-eDP-1-size-64
[411.918806] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.891s)
[411.945890] Starting dynamic subtest: pipe-C-eDP-1-size-128
[414.118545] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.180s)
[414.145995] Starting dynamic subtest: pipe-C-eDP-1-size-256
[416.319061] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.181s)
[416.632815] Starting dynamic subtest: pipe-D-eDP-1-size-64
[419.525667] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.900s)
[419.553081] Starting dynamic subtest: pipe-D-eDP-1-size-128
[421.736944] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.192s)
[421.766238] Starting dynamic subtest: pipe-D-eDP-1-size-256
[423.936894] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.180s)
[424.244650] Subtest primary: SUCCESS (32.125s)
[425.106915] [113/133] (601s left) kms_flip (2x-modeset-vs-vblank-race-interruptible)
[425.217652] Starting subtest: 2x-modeset-vs-vblank-race-interruptible
[425.232191] Subtest 2x-modeset-vs-vblank-race-interruptible: SKIP (0.014s)
[425.330867] [114/133] (601s left) syncobj_timeline (reset-during-wait-for-submit)
[425.390953] Starting subtest: reset-during-wait-for-submit
[425.495166] Subtest reset-during-wait-for-submit: SUCCESS (0.103s)
[425.583524] [115/133] (601s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[425.681952] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu
[432.804924] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (7.124s)
[432.940046] [116/133] (593s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[433.039248] Starting subtest: x-tiled-32bpp-rotate-270
[433.058197] Subtest x-tiled-32bpp-rotate-270: SKIP (0.018s)
[433.191194] [117/133] (593s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[433.256511] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[433.339855] [118/133] (593s left) kms_cursor_crc (cursor-random-64x64)
[433.503354] Starting subtest: cursor-random-64x64
[433.507131] Starting dynamic subtest: pipe-A-eDP-1
[436.850030] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.342s)
[436.878071] Starting dynamic subtest: pipe-D-eDP-1
[440.810152] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.939s)
[440.811015] Subtest cursor-random-64x64: SUCCESS (7.307s)
[441.682727] [119/133] (585s left) gem_create (create-ext-cpu-access-big)
[441.856043] Starting subtest: create-ext-cpu-access-big
[441.858642] Subtest create-ext-cpu-access-big: SKIP (0.000s)
[441.927092] [120/133] (584s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[442.315093] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[443.163380] [121/133] (583s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[443.254907] Starting subtest: random-ccs-data-y-tiled-ccs
[443.255709] Starting dynamic subtest: pipe-A-eDP-1
[443.263995] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[443.271446] Starting dynamic subtest: pipe-B-eDP-1
[443.279410] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[443.286937] Starting dynamic subtest: pipe-C-eDP-1
[443.294898] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[443.302020] Starting dynamic subtest: pipe-D-eDP-1
[443.310393] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[443.311847] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.005s)
[445.554148] [122/133] (581s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[445.658466] Starting subtest: 4-tiled-32bpp-rotate-180
[447.015100] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.357s)
[447.213737] [123/133] (579s left) kms_addfb_basic (addfb25-4-tiled)
[447.311819] Starting subtest: addfb25-4-tiled
[447.312731] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[447.458157] [124/133] (579s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[447.557777] Starting subtest: fbcpsr-indfb-scaledprimary
[453.341969] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.784s)
[453.475640] [125/133] (573s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[453.583571] Starting subtest: cursorB-vs-flipA-varying-size
[453.586564] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[453.735440] [126/133] (573s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-msflip-blt)
[453.832742] Starting subtest: fbc-1p-primscrn-indfb-msflip-blt
[458.355330] Subtest fbc-1p-primscrn-indfb-msflip-blt: SUCCESS (4.521s)
[459.521220] [127/133] (567s left) kms_pm_rpm (package-g7)
[461.046918] Starting subtest: package-g7
[461.049479] Subtest package-g7: SKIP (0.000s)
[461.852902] [128/133] (565s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu)
[461.956341] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu
[468.500793] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (6.545s)
[468.633811] [129/133] (558s left) kms_flip (flip-vs-expired-vblank)
[468.717237] Starting subtest: flip-vs-expired-vblank
[468.719343] Starting dynamic subtest: A-eDP1
[477.455833] Dynamic subtest A-eDP1: SUCCESS (8.710s)
[477.465066] Starting dynamic subtest: B-eDP1
[486.085298] Dynamic subtest B-eDP1: SUCCESS (8.625s)
[486.095753] Starting dynamic subtest: C-eDP1
[494.719842] Dynamic subtest C-eDP1: SUCCESS (8.621s)
[494.728189] Starting dynamic subtest: D-eDP1
[503.344906] Dynamic subtest D-eDP1: SUCCESS (8.622s)
[503.345873] Subtest flip-vs-expired-vblank: SUCCESS (34.592s)
[504.498410] [130/133] (522s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[504.595357] Starting subtest: y-tiled-64bpp-rotate-0
[504.597304] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[504.728901] [131/133] (522s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[504.812048] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[511.265936] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.454s)
[512.422447] [132/133] (514s left) gem_ringfill (legacy-basic)
[517.920289] Starting subtest: legacy-basic
[517.921358] Starting dynamic subtest: default
[517.977634] Dynamic subtest default: SUCCESS (0.065s)
[517.989455] Starting dynamic subtest: render
[518.020233] Dynamic subtest render: SUCCESS (0.040s)
[518.030159] Starting dynamic subtest: bsd
[518.061527] Dynamic subtest bsd: SUCCESS (0.039s)
[518.071136] Starting dynamic subtest: bsd1
[518.101476] Dynamic subtest bsd1: SUCCESS (0.038s)
[518.111635] Starting dynamic subtest: bsd2
[518.145739] Dynamic subtest bsd2: SUCCESS (0.041s)
[518.156263] Starting dynamic subtest: blt
[518.190556] Dynamic subtest blt: SUCCESS (0.042s)
[518.202991] Starting dynamic subtest: vebox
[518.233575] Dynamic subtest vebox: SUCCESS (0.041s)
[518.236035] Subtest legacy-basic: SUCCESS (0.324s)
[518.338832] [133/133] (508s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[518.410060] Starting subtest: x-tiled-to-vebox-y-tiled
[518.412271] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[518.609625] Closing watchdogs
results: parsing output: 0/ for test: kms_ccs
results: parsing output: 1/ for test: i915_query
results: parsing output: 2/ for test: gem_spin_batch
results: parsing output: 3/ for test: kms_draw_crc
results: parsing output: 4/ for test: gem_exec_schedule
results: parsing output: 5/ for test: gem_ctx_shared
results: parsing output: 6/ for test: gem_tiled_wb
results: parsing output: 7/ for test: gem_partial_pwrite_pread
results: parsing output: 8/ for test: api_intel_allocator
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: gem_exec_gttfill
results: parsing output: 11/ for test: gem_fence_thrash
results: parsing output: 12/ for test: kms_pm_dc
results: parsing output: 13/ for test: i915_drm_fdinfo
results: parsing output: 14/ for test: kms_psr
results: parsing output: 15/ for test: perf_pmu
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: syncobj_timeline
results: parsing output: 18/ for test: kms_dither
results: parsing output: 19/ for test: gem_mmap_offset
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: syncobj_wait
results: parsing output: 22/ for test: syncobj_timeline
results: parsing output: 23/ for test: gem_eio
results: parsing output: 24/ for test: gem_userptr_blits
results: parsing output: 25/ for test: perf_pmu
results: parsing output: 26/ for test: kms_flip
results: parsing output: 27/ for test: gem_exec_fence
results: parsing output: 28/ for test: gem_exec_params
results: parsing output: 29/ for test: kms_flip
results: parsing output: 30/ for test: gem_exec_balancer
results: parsing output: 31/ for test: kms_cursor_crc
results: parsing output: 32/ for test: gem_userptr_blits
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_ctx_isolation
results: parsing output: 35/ for test: gem_mmap_gtt
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_exec_schedule
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_invalid_mode
results: parsing output: 40/ for test: kms_flip_scaled_crc
results: parsing output: 41/ for test: syncobj_wait
results: parsing output: 42/ for test: core_getversion
results: parsing output: 43/ for test: kms_psr2_sf
results: parsing output: 44/ for test: kms_vblank
results: parsing output: 45/ for test: gem_exec_schedule
results: parsing output: 46/ for test: i915_suspend
results: parsing output: 47/ for test: kms_flip
results: parsing output: 48/ for test: kms_big_fb
results: parsing output: 49/ for test: fbdev
results: parsing output: 50/ for test: gem_eio
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_plane_scaling
results: parsing output: 53/ for test: prime_mmap_coherency
results: parsing output: 54/ for test: gem_exec_reloc
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: kms_big_fb
results: parsing output: 57/ for test: kms_psr2_sf
results: parsing output: 58/ for test: gem_partial_pwrite_pread
results: parsing output: 59/ for test: kms_lease
results: parsing output: 60/ for test: gem_exec_reloc
results: parsing output: 61/ for test: kms_plane_scaling
results: parsing output: 62/ for test: gem_exec_fence
results: parsing output: 63/ for test: i915_pm_rps
results: parsing output: 64/ for test: kms_multipipe_modeset
results: parsing output: 65/ for test: syncobj_timeline
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: prime_vgem
results: parsing output: 68/ for test: gen7_exec_parse
results: parsing output: 69/ for test: kms_ccs
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: api_intel_bb
results: parsing output: 72/ for test: kms_chamelium_hpd
results: parsing output: 73/ for test: syncobj_eventfd
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_ctx_persistence
results: parsing output: 77/ for test: kms_atomic
results: parsing output: 78/ for test: sysfs_timeslice_duration
results: parsing output: 79/ for test: kms_fence_pin_leak
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_params
results: parsing output: 82/ for test: kms_color
results: parsing output: 83/ for test: i915_query
results: parsing output: 84/ for test: i915_hangman
results: parsing output: 85/ for test: gem_pread
results: parsing output: 86/ for test: kms_psr2_sf
results: parsing output: 87/ for test: syncobj_wait
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: gem_userptr_blits
results: parsing output: 90/ for test: kms_addfb_basic
results: parsing output: 91/ for test: kms_properties
results: parsing output: 92/ for test: kms_vblank
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: kms_content_protection
results: parsing output: 95/ for test: kms_rotation_crc
results: parsing output: 96/ for test: kms_big_fb
results: parsing output: 97/ for test: gem_exercise_blt
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_plane
results: parsing output: 100/ for test: kms_psr
results: parsing output: 101/ for test: kms_ccs
results: parsing output: 102/ for test: perf
results: parsing output: 103/ for test: gem_exec_reloc
results: parsing output: 104/ for test: kms_hdr
results: parsing output: 105/ for test: vgem_basic
results: parsing output: 106/ for test: kms_busy
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: kms_flip_scaled_crc
results: parsing output: 109/ for test: gem_exec_reloc
results: parsing output: 110/ for test: gem_mmap
results: parsing output: 111/ for test: kms_plane_cursor
results: parsing output: 112/ for test: kms_flip
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_big_fb
results: parsing output: 116/ for test: gem_fenced_exec_thrash
results: parsing output: 117/ for test: kms_cursor_crc
results: parsing output: 118/ for test: gem_create
results: parsing output: 119/ for test: kms_chamelium_edid
results: parsing output: 120/ for test: kms_ccs
results: parsing output: 121/ for test: kms_big_fb
results: parsing output: 122/ for test: kms_addfb_basic
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: kms_cursor_legacy
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_pm_rpm
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_flip
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: gem_ringfill
results: parsing output: 132/ for test: gem_render_copy
Done.