[60.860549] Initializing watchdogs
[60.860616]   /dev/watchdog0
[60.880402] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[60.891412] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[60.895422] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.897794] [001/134] (960s left) kms_cursor_crc (cursor-offscreen-256x85)
[61.352442] Starting subtest: cursor-offscreen-256x85
[61.368068] Starting dynamic subtest: pipe-A-DP-3
[63.920673] Dynamic subtest pipe-A-DP-3: SUCCESS (2.555s)
[63.962126] Starting dynamic subtest: pipe-D-DP-3
[66.374299] Dynamic subtest pipe-D-DP-3: SUCCESS (2.421s)
[66.375208] Subtest cursor-offscreen-256x85: SUCCESS (5.026s)
[66.725505] [002/134] (954s left) gem_mmap_gtt (cpuset-big-copy-odd)
[66.858007] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[66.945578] [003/134] (954s left) gem_ctx_engines (execute-allforone)
[67.287046] Starting subtest: execute-allforone
[67.358667] Subtest execute-allforone: SUCCESS (0.072s)
[67.924403] [004/134] (953s left) perf_pmu (busy-idle)
[67.991234] Starting subtest: busy-idle
[67.992165] Starting dynamic subtest: rcs0
[68.725574] Dynamic subtest rcs0: SUCCESS (0.734s)
[68.736860] Starting dynamic subtest: bcs0
[69.390778] Dynamic subtest bcs0: SUCCESS (0.664s)
[69.403055] Starting dynamic subtest: vcs0
[70.055105] Dynamic subtest vcs0: SUCCESS (0.663s)
[70.067637] Starting dynamic subtest: vcs1
[70.716387] Dynamic subtest vcs1: SUCCESS (0.661s)
[70.751676] Starting dynamic subtest: vecs0
[71.400570] Dynamic subtest vecs0: SUCCESS (0.683s)
[71.413168] Starting dynamic subtest: vecs1
[72.066578] Dynamic subtest vecs1: SUCCESS (0.665s)
[72.078821] Starting dynamic subtest: ccs0
[72.742436] Dynamic subtest ccs0: SUCCESS (0.675s)
[72.744245] Subtest busy-idle: SUCCESS (4.754s)
[72.944930] [005/134] (948s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[73.136920] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[73.852463] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.716s)
[74.085612] [006/134] (947s left) api_intel_bb (lot-of-buffers)
[74.159170] Starting subtest: lot-of-buffers
[77.401368] Subtest lot-of-buffers: SUCCESS (3.243s)
[77.649495] [007/134] (944s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[77.727362] Starting subtest: yf-tiled-to-vebox-x-tiled
[77.729510] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[78.007701] [008/134] (943s left) kms_plane (plane-panning-top-left)
[78.194561] Starting subtest: plane-panning-top-left
[78.196382] Starting dynamic subtest: pipe-A
[78.966925] Dynamic subtest pipe-A: SUCCESS (0.771s)
[78.975635] Starting dynamic subtest: pipe-B
[79.628223] Dynamic subtest pipe-B: SUCCESS (0.660s)
[79.629141] Subtest plane-panning-top-left: SUCCESS (1.434s)
[79.959976] [009/134] (941s left) kms_psr (fbc-psr-cursor-plane-onoff)
[80.140008] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[80.186415] [010/134] (941s left) kms_cursor_crc (cursor-tearing-position-change)
[80.414113] Starting subtest: cursor-tearing-position-change
[80.415963] Starting dynamic subtest: pipe-A-DP-3
[80.890202] Dynamic subtest pipe-A-DP-3: SUCCESS (0.475s)
[80.898992] Starting dynamic subtest: pipe-D-DP-3
[81.311234] Dynamic subtest pipe-D-DP-3: SUCCESS (0.420s)
[81.312116] Subtest cursor-tearing-position-change: SUCCESS (0.897s)
[81.893140] [011/134] (940s left) kms_frontbuffer_tracking (basic)
[82.074652] Starting subtest: basic
[84.827279] Subtest basic: SUCCESS (2.754s)
[85.053611] [012/134] (937s left) gem_spin_batch (spin-each)
[85.136371] Starting subtest: spin-each
[88.465231] Subtest spin-each: SUCCESS (3.329s)
[88.809911] [013/134] (933s left) gem_exec_reloc (basic-gtt-cpu)
[88.886377] Subtest basic-gtt-cpu: SKIP (0.000s)
[88.947500] [014/134] (933s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[89.128157] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[89.158426] Starting dynamic subtest: pipe-A-plane-0
[89.160457] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[89.169610] Starting dynamic subtest: pipe-A-plane-3
[89.171843] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[89.180601] Starting dynamic subtest: pipe-A-plane-5
[89.182390] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[89.389049] Starting dynamic subtest: pipe-B-plane-0
[89.391243] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[89.407395] Starting dynamic subtest: pipe-B-plane-3
[89.409522] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[89.423828] Starting dynamic subtest: pipe-B-plane-5
[89.425740] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[89.483177] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SKIP (0.353s)
[89.759784] [015/134] (932s left) gem_render_copy (y-tiled-ccs-to-linear)
[89.835527] Starting subtest: y-tiled-ccs-to-linear
[89.837640] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[90.073118] [016/134] (932s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[90.274180] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[90.275906] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[90.425384] [017/134] (932s left) kms_psr (pr-sprite-render)
[90.605148] Subtest pr-sprite-render: SKIP (0.000s)
[90.651824] [018/134] (931s left) gen9_exec_parse (shadow-peek)
[90.731343] Subtest shadow-peek: SKIP (0.000s)
[90.825866] [019/134] (931s left) perf (invalid-remove-userspace-config)
[91.237309] Starting subtest: invalid-remove-userspace-config
[91.246481] Subtest invalid-remove-userspace-config: SUCCESS (0.010s)
[91.304637] [020/134] (931s left) gem_ctx_param (invalid-size-set)
[91.372376] Starting subtest: invalid-size-set
[91.374171] Subtest invalid-size-set: SUCCESS (0.001s)
[91.509239] [021/134] (931s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[91.701446] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[91.702453] Starting dynamic subtest: pipe-A-DP-3
[91.711314] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[91.720368] Starting dynamic subtest: pipe-B-DP-3
[91.728733] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[91.737518] Starting dynamic subtest: pipe-C-DP-3
[91.745742] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[91.754424] Starting dynamic subtest: pipe-D-DP-3
[91.762896] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[91.765123] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[92.156687] [022/134] (930s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[92.230528] Starting subtest: invalid-bsd2-flag-on-blt
[92.231426] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[92.439681] [023/134] (930s left) gem_render_copy (yf-tiled-ccs-to-linear)
[92.519486] Starting subtest: yf-tiled-ccs-to-linear
[92.521584] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[92.754948] [024/134] (930s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[92.826190] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[92.933897] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.106s)
[93.070657] [025/134] (929s left) gem_exec_fence (invalid-timeline-fence-array)
[93.779336] Starting subtest: invalid-timeline-fence-array
[93.799736] Subtest invalid-timeline-fence-array: SUCCESS (0.022s)
[94.141787] [FACT gem_exec_fence (invalid-timeline-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[94.143514] [026/134] (928s left) kms_chamelium_edid (dp-edid-read)
[94.404178] Subtest dp-edid-read: SKIP (0.000s)
[94.549716] [027/134] (928s left) gem_exec_params (invalid-bsd1-flag-on-render)
[94.635377] Starting subtest: invalid-bsd1-flag-on-render
[94.636261] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[94.827752] [028/134] (928s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[95.021183] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[96.278932] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.257s)
[96.510503] [029/134] (926s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[96.695485] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[96.696397] Starting dynamic subtest: pipe-A-DP-3
[96.704918] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[96.714171] Starting dynamic subtest: pipe-B-DP-3
[96.722546] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[96.731620] Starting dynamic subtest: pipe-C-DP-3
[96.739820] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[96.748991] Starting dynamic subtest: pipe-D-DP-3
[96.759381] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[96.761230] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.006s)
[97.175282] [030/134] (926s left) gem_eio (kms)
[97.597233] Starting subtest: kms
[128.974402] Subtest kms: SUCCESS (31.379s)
[129.348842] [031/134] (893s left) kms_cursor_legacy (torture-move)
[129.537953] Starting subtest: torture-move
[129.538786] Starting dynamic subtest: pipe-A
[135.172369] Dynamic subtest pipe-A: SUCCESS (5.634s)
[135.182229] Starting dynamic subtest: pipe-B
[141.380902] Dynamic subtest pipe-B: SUCCESS (6.209s)
[141.391651] Starting dynamic subtest: pipe-C
[147.598451] Dynamic subtest pipe-C: SUCCESS (6.217s)
[147.611049] Starting dynamic subtest: pipe-D
[153.613450] Dynamic subtest pipe-D: SUCCESS (6.015s)
[153.623849] Starting dynamic subtest: all-pipes
[159.107304] Dynamic subtest all-pipes: SUCCESS (5.494s)
[159.108209] Subtest torture-move: SUCCESS (29.571s)
[159.269730] [032/134] (864s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[159.473252] Starting subtest: bad-pixel-format-yf-tiled-ccs
[159.474165] Starting dynamic subtest: pipe-A-DP-3
[159.482582] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[159.492432] Starting dynamic subtest: pipe-B-DP-3
[159.500861] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[159.510519] Starting dynamic subtest: pipe-C-DP-3
[159.518723] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[159.528500] Starting dynamic subtest: pipe-D-DP-3
[159.538353] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[159.540333] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.004s)
[159.934176] [033/134] (863s left) gem_exec_capture (pi)
[160.023541] Starting subtest: pi
[160.024463] Starting dynamic subtest: rcs0
[165.862829] Dynamic subtest rcs0: SUCCESS (5.838s)
[165.873321] Starting dynamic subtest: bcs0
[171.651073] Dynamic subtest bcs0: SUCCESS (5.789s)
[171.661434] Starting dynamic subtest: vcs0
[177.347227] Dynamic subtest vcs0: SUCCESS (5.696s)
[177.357613] Starting dynamic subtest: vcs1
[183.082471] Dynamic subtest vcs1: SUCCESS (5.735s)
[183.093056] Starting dynamic subtest: vecs0
[188.779676] Dynamic subtest vecs0: SUCCESS (5.697s)
[188.789937] Starting dynamic subtest: vecs1
[194.522166] Dynamic subtest vecs1: SUCCESS (5.742s)
[194.532495] Starting dynamic subtest: ccs0
[200.316982] Dynamic subtest ccs0: SUCCESS (5.795s)
[200.317940] Subtest pi: SUCCESS (40.294s)
[200.491813] [034/134] (823s left) syncobj_timeline (wait-all-snapshot)
[200.620353] Starting subtest: wait-all-snapshot
[200.703350] Subtest wait-all-snapshot: SUCCESS (0.082s)
[200.864113] [035/134] (822s left) kms_async_flips (test-cursor-atomic)
[201.058067] Starting subtest: test-cursor-atomic
[201.289722] Starting dynamic subtest: pipe-A-DP-3
[201.600744] Dynamic subtest pipe-A-DP-3: SUCCESS (0.311s)
[201.844497] Starting dynamic subtest: pipe-B-DP-3
[201.950414] Dynamic subtest pipe-B-DP-3: SUCCESS (0.117s)
[202.157375] Starting dynamic subtest: pipe-C-DP-3
[202.254600] Dynamic subtest pipe-C-DP-3: SUCCESS (0.109s)
[202.464337] Starting dynamic subtest: pipe-D-DP-3
[202.578549] Dynamic subtest pipe-D-DP-3: SUCCESS (0.124s)
[202.579496] Subtest test-cursor-atomic: SUCCESS (1.522s)
[203.081370] [036/134] (820s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[203.280918] Starting subtest: x-tiled-32bpp-rotate-180
[205.096809] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.815s)
[205.361490] [037/134] (818s left) i915_pm_freq_api (freq-reset)
[205.448574] Starting subtest: freq-reset
[205.449501] Starting dynamic subtest: gt0
[205.916437] Dynamic subtest gt0: SUCCESS (0.467s)
[205.917293] Subtest freq-reset: SUCCESS (0.467s)
[206.055738] [038/134] (817s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[206.253714] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[206.255269] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[206.407586] [039/134] (817s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[206.608081] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[206.608984] Starting dynamic subtest: pipe-A-DP-3
[206.618207] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[206.628640] Starting dynamic subtest: pipe-B-DP-3
[206.637810] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[206.648115] Starting dynamic subtest: pipe-C-DP-3
[206.657571] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[206.668101] Starting dynamic subtest: pipe-D-DP-3
[206.679766] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[206.681845] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[207.078100] [040/134] (816s left) gem_mmap (short-mmap)
[207.156612] Subtest short-mmap: SKIP (0.000s)
[207.232775] [041/134] (816s left) vgem_basic (dmabuf-export)
[207.290180] Starting subtest: dmabuf-export
[207.332233] Subtest dmabuf-export: SUCCESS (0.043s)
[207.460805] [042/134] (816s left) gem_render_copy (y-tiled)
[207.546567] Starting subtest: y-tiled
[207.548800] Subtest y-tiled: SKIP (0.000s)
[207.821938] [043/134] (816s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[208.009551] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[208.011259] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[208.179887] [044/134] (816s left) gem_exec_schedule (implicit-write-read)
[208.269257] Starting subtest: implicit-write-read
[208.270207] Starting dynamic subtest: rcs0
[208.568852] Dynamic subtest rcs0: SUCCESS (0.290s)
[208.579493] Starting dynamic subtest: bcs0
[208.764752] Dynamic subtest bcs0: SUCCESS (0.204s)
[208.775503] Starting dynamic subtest: vcs0
[208.977668] Dynamic subtest vcs0: SUCCESS (0.212s)
[208.998215] Starting dynamic subtest: vcs1
[209.205943] Dynamic subtest vcs1: SUCCESS (0.227s)
[209.216667] Starting dynamic subtest: vecs0
[209.418187] Dynamic subtest vecs0: SUCCESS (0.212s)
[209.428807] Starting dynamic subtest: vecs1
[209.629222] Dynamic subtest vecs1: SUCCESS (0.210s)
[209.640323] Starting dynamic subtest: ccs0
[209.842968] Dynamic subtest ccs0: SUCCESS (0.214s)
[209.843827] Subtest implicit-write-read: SUCCESS (1.574s)
[210.228745] [045/134] (814s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[210.433265] Starting subtest: 4-tiled-64bpp-rotate-90
[210.490501] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.057s)
[210.666412] [046/134] (813s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[210.859172] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[210.863602] Starting dynamic subtest: A-DP3
[218.961450] Dynamic subtest A-DP3: SUCCESS (8.098s)
[218.974741] Starting dynamic subtest: B-DP3
[226.990336] Dynamic subtest B-DP3: SUCCESS (8.028s)
[227.001603] Starting dynamic subtest: C-DP3
[235.027845] Dynamic subtest C-DP3: SUCCESS (8.036s)
[235.042357] Starting dynamic subtest: D-DP3
[243.054178] Dynamic subtest D-DP3: SUCCESS (8.023s)
[243.056333] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (32.197s)
[243.404047] [047/134] (781s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[243.596846] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[243.598440] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[243.804340] [048/134] (780s left) kms_color_pipeline (plane-lut1d)
[244.000047] Starting subtest: plane-lut1d
[244.033807] Starting dynamic subtest: pipe-A-DP-3
[244.433367] Dynamic subtest pipe-A-DP-3: SUCCESS (0.399s)
[244.632232] Starting dynamic subtest: pipe-B-DP-3
[244.971030] Dynamic subtest pipe-B-DP-3: SUCCESS (0.349s)
[245.177498] Starting dynamic subtest: pipe-C-DP-3
[245.515600] Dynamic subtest pipe-C-DP-3: SUCCESS (0.348s)
[245.692038] Starting dynamic subtest: pipe-D-DP-3
[246.047925] Dynamic subtest pipe-D-DP-3: SUCCESS (0.366s)
[246.154331] Subtest plane-lut1d: SUCCESS (2.155s)
[246.477454] [049/134] (778s left) i915_pm_rc6_residency (rc6-accuracy)
[246.553305] Starting subtest: rc6-accuracy
[246.554209] Starting dynamic subtest: gt0
[249.768357] Dynamic subtest gt0: FAIL (3.194s)
[249.769325] Subtest rc6-accuracy: FAIL (3.195s)
[249.890314] [050/134] (774s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[250.076273] Starting subtest: basic-flip-before-cursor-atomic
[251.115939] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.040s)
[251.431621] [051/134] (773s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[251.621650] Starting subtest: psr-2p-primscrn-spr-indfb-move
[251.623263] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[251.778045] [052/134] (773s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[251.966663] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[251.968300] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[252.182791] [053/134] (772s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[252.376289] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[252.439507] [054/134] (772s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[252.519492] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[252.620831] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.101s)
[252.776178] [055/134] (772s left) gem_mmap_wc (bad-size)
[252.857140] Subtest bad-size: SKIP (0.000s)
[252.922896] [056/134] (772s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[253.120909] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[253.122722] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[253.281120] [057/134] (771s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[253.470007] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[253.471744] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[253.679868] [058/134] (771s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[253.866090] Starting subtest: cursorA-vs-flipB-atomic
[253.867811] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[254.034769] [059/134] (771s left) sysfs_heartbeat_interval (nopreempt)
[254.120746] Starting subtest: nopreempt
[254.121603] Starting dynamic subtest: vecs0
[258.426304] Dynamic subtest vecs0: SUCCESS (4.304s)
[258.437241] Starting dynamic subtest: ccs0
[262.677385] Dynamic subtest ccs0: SUCCESS (4.250s)
[262.688389] Starting dynamic subtest: vcs0
[266.880313] Dynamic subtest vcs0: SUCCESS (4.202s)
[266.892077] Starting dynamic subtest: bcs0
[271.101290] Dynamic subtest bcs0: SUCCESS (4.220s)
[271.113386] Starting dynamic subtest: vecs1
[275.304194] Dynamic subtest vecs1: SUCCESS (4.202s)
[275.315348] Starting dynamic subtest: rcs0
[279.641758] Dynamic subtest rcs0: SUCCESS (4.337s)
[279.655311] Starting dynamic subtest: vcs1
[283.855312] Dynamic subtest vcs1: SUCCESS (4.212s)
[283.856322] Subtest nopreempt: SUCCESS (29.734s)
[284.042789] [060/134] (741s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[284.240429] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[284.242168] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[284.444523] [061/134] (741s left) gem_wait (busy)
[284.537390] Starting subtest: busy
[284.538267] Starting dynamic subtest: all-engines
[285.126160] Dynamic subtest all-engines: SUCCESS (0.588s)
[285.137960] Starting dynamic subtest: rcs0
[285.668103] Dynamic subtest rcs0: SUCCESS (0.541s)
[285.678584] Starting dynamic subtest: bcs0
[286.207057] Dynamic subtest bcs0: SUCCESS (0.538s)
[286.217922] Starting dynamic subtest: vcs0
[286.746078] Dynamic subtest vcs0: SUCCESS (0.538s)
[286.757148] Starting dynamic subtest: vcs1
[287.284949] Dynamic subtest vcs1: SUCCESS (0.538s)
[287.296136] Starting dynamic subtest: vecs0
[287.824073] Dynamic subtest vecs0: SUCCESS (0.538s)
[287.835126] Starting dynamic subtest: vecs1
[288.363221] Dynamic subtest vecs1: SUCCESS (0.538s)
[288.374131] Starting dynamic subtest: ccs0
[288.902067] Dynamic subtest ccs0: SUCCESS (0.538s)
[288.903075] Subtest busy: SUCCESS (4.365s)
[289.197538] [062/134] (736s left) kms_setmode (clone-exclusive-crtc)
[289.274412] Starting subtest: clone-exclusive-crtc
[289.277551] Subtest clone-exclusive-crtc: SKIP (0.000s)
[289.464323] [063/134] (736s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[289.658314] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[289.708826] [064/134] (736s left) sw_sync (sync_merge_same)
[289.759187] Starting subtest: sync_merge_same
[289.760080] Subtest sync_merge_same: SUCCESS (0.000s)
[289.885561] [065/134] (735s left) kms_hdr (static-toggle)
[290.072776] Starting subtest: static-toggle
[290.658455] Starting dynamic subtest: pipe-A-DP-3
[291.724298] Dynamic subtest pipe-A-DP-3: SUCCESS (1.066s)
[291.725733] Subtest static-toggle: SUCCESS (1.653s)
[292.088629] [066/134] (733s left) gem_exec_await (wide-contexts)
[292.374550] Starting subtest: wide-contexts
[314.396609] Subtest wide-contexts: SUCCESS (22.022s)
[314.655602] [067/134] (711s left) kms_invalid_mode (uint-max-clock)
[314.873803] Starting subtest: uint-max-clock
[314.874621] Starting dynamic subtest: pipe-A-DP-3
[314.946192] Dynamic subtest pipe-A-DP-3: SUCCESS (0.072s)
[314.959176] Starting dynamic subtest: pipe-B-DP-3
[314.960345] Dynamic subtest pipe-B-DP-3: SUCCESS (0.001s)
[314.971712] Starting dynamic subtest: pipe-C-DP-3
[314.972839] Dynamic subtest pipe-C-DP-3: SUCCESS (0.001s)
[314.983716] Starting dynamic subtest: pipe-D-DP-3
[314.984553] Dynamic subtest pipe-D-DP-3: SUCCESS (0.001s)
[314.985432] Subtest uint-max-clock: SUCCESS (0.081s)
[315.226782] [068/134] (710s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[315.424123] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[318.851738] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.428s)
[319.100868] [069/134] (707s left) kms_pm_rpm (modeset-non-lpsp)
[321.058143] Starting subtest: modeset-non-lpsp
[321.682340] Subtest modeset-non-lpsp: SUCCESS (0.629s)
[321.918974] [070/134] (704s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[322.107332] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[322.173755] [071/134] (704s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[322.364056] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[322.365705] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[322.532876] [072/134] (703s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[322.734995] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[322.736755] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[322.898779] [073/134] (703s left) i915_pm_rps (thresholds-idle)
[322.994611] Starting subtest: thresholds-idle
[322.996379] Subtest thresholds-idle: SKIP (0.000s)
[323.200750] [074/134] (703s left) kms_vblank (query-idle-hang)
[323.397409] Starting subtest: query-idle-hang
[323.398305] Starting dynamic subtest: pipe-A-DP-3
[349.572153] Dynamic subtest pipe-A-DP-3: SUCCESS (26.173s)
[349.584955] Starting dynamic subtest: pipe-D-DP-3
[374.580983] Dynamic subtest pipe-D-DP-3: SUCCESS (25.006s)
[374.581940] Subtest query-idle-hang: SUCCESS (51.185s)
[374.904331] [075/134] (651s left) perf_pmu (most-busy-idle-check-all)
[374.995201] Starting subtest: most-busy-idle-check-all
[374.996177] Starting dynamic subtest: rcs0
[375.697391] Dynamic subtest rcs0: SUCCESS (0.702s)
[375.717587] Starting dynamic subtest: bcs0
[376.357717] Dynamic subtest bcs0: SUCCESS (0.658s)
[376.373141] Starting dynamic subtest: vcs0
[377.015613] Dynamic subtest vcs0: SUCCESS (0.653s)
[377.034704] Starting dynamic subtest: vcs1
[377.680685] Dynamic subtest vcs1: SUCCESS (0.660s)
[377.700651] Starting dynamic subtest: vecs0
[378.343656] Dynamic subtest vecs0: SUCCESS (0.658s)
[378.362773] Starting dynamic subtest: vecs1
[379.004400] Dynamic subtest vecs1: SUCCESS (0.656s)
[379.021274] Starting dynamic subtest: ccs0
[379.666688] Dynamic subtest ccs0: SUCCESS (0.657s)
[379.672473] Subtest most-busy-idle-check-all: SUCCESS (4.673s)
[379.869492] [076/134] (646s left) kms_atomic_interruptible (universal-setplane-cursor)
[380.059712] Starting subtest: universal-setplane-cursor
[380.062163] Starting dynamic subtest: pipe-A-DP-3
[386.405984] Dynamic subtest pipe-A-DP-3: SUCCESS (6.344s)
[386.406973] Subtest universal-setplane-cursor: SUCCESS (6.346s)
[386.702070] [077/134] (640s left) gem_userptr_blits (process-exit-busy)
[386.796703] Starting subtest: process-exit-busy
[386.811885] Subtest process-exit-busy: SUCCESS (0.015s)
[387.015816] [078/134] (639s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[387.208230] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[387.209869] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[387.401371] [079/134] (639s left) gem_lmem_swapping (heavy-verify-multi)
[390.168604] Starting subtest: heavy-verify-multi
[390.169937] Starting dynamic subtest: lmem0
[404.247506] Dynamic subtest lmem0: SUCCESS (14.080s)
[404.248451] Subtest heavy-verify-multi: SUCCESS (14.081s)
[406.104775] [FACT gem_lmem_swapping (heavy-verify-multi)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[406.105813] [FACT gem_lmem_swapping (heavy-verify-multi)] deleted: kernel.kmod_is_loaded.i915: true
[406.107877] [080/134] (620s left) gen9_exec_parse (bb-start-cmd)
[407.728463] Subtest bb-start-cmd: SKIP (0.000s)
[407.777766] [FACT gen9_exec_parse (bb-start-cmd)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[407.780542] [FACT gen9_exec_parse (bb-start-cmd)] new: kernel.kmod_is_loaded.i915: true
[407.783440] [081/134] (619s left) fbdev (unaligned-read)
[407.839451] Starting subtest: unaligned-read
[407.851116] Subtest unaligned-read: SUCCESS (0.013s)
[408.000698] [082/134] (619s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[408.284384] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[408.438460] [083/134] (618s left) gem_ppgtt (blt-vs-render-ctx0)
[408.524357] Starting subtest: blt-vs-render-ctx0
[441.253948] Subtest blt-vs-render-ctx0: SUCCESS (32.730s)
[441.476371] [084/134] (585s left) core_setmaster_vs_auth
[441.829256] [085/134] (585s left) prime_busy (hang)
[441.915477] Starting subtest: hang
[441.917808] Starting dynamic subtest: rcs0
[469.475676] Dynamic subtest rcs0: SUCCESS (27.557s)
[469.486160] Starting dynamic subtest: bcs0
[476.629609] Dynamic subtest bcs0: SUCCESS (7.154s)
[476.640529] Starting dynamic subtest: vcs0
[483.620223] Dynamic subtest vcs0: SUCCESS (6.989s)
[483.630852] Starting dynamic subtest: vcs1
[490.661950] Dynamic subtest vcs1: SUCCESS (7.040s)
[490.672868] Starting dynamic subtest: vecs0
[497.632939] Dynamic subtest vecs0: SUCCESS (6.969s)
[497.643617] Starting dynamic subtest: vecs1
[504.611055] Dynamic subtest vecs1: SUCCESS (6.976s)
[504.623186] Starting dynamic subtest: ccs0
[518.505831] Dynamic subtest ccs0: SUCCESS (13.892s)
[518.508268] Subtest hang: SUCCESS (76.590s)
[518.683489] [086/134] (508s left) gem_eio (unwedge-stress)
[518.923445] Starting subtest: unwedge-stress
[518.924376] Starting dynamic subtest: render
[534.941228] Dynamic subtest render: SUCCESS (16.015s)
[534.952917] Starting dynamic subtest: bsd1
[552.927149] Dynamic subtest bsd1: SUCCESS (17.983s)
[552.939212] Starting dynamic subtest: bsd2
[566.948798] Dynamic subtest bsd2: SUCCESS (14.019s)
[566.965674] Starting dynamic subtest: blt
[582.949294] Dynamic subtest blt: SUCCESS (15.998s)
[582.960925] Starting dynamic subtest: vebox
[599.680797] Dynamic subtest vebox: SUCCESS (16.729s)
[599.681759] Subtest unwedge-stress: SUCCESS (80.753s)
[600.015743] [087/134] (427s left) kms_cursor_crc (cursor-random-256x256)
[600.442293] Starting subtest: cursor-random-256x256
[600.446025] Starting dynamic subtest: pipe-A-DP-3
[604.996385] Dynamic subtest pipe-A-DP-3: SUCCESS (4.549s)
[605.048654] Starting dynamic subtest: pipe-D-DP-3
[609.578540] Dynamic subtest pipe-D-DP-3: SUCCESS (4.540s)
[609.579545] Subtest cursor-random-256x256: SUCCESS (9.137s)
[609.980228] [088/134] (417s left) syncobj_timeline (multi-wait-submitted-signaled)
[610.068495] Starting subtest: multi-wait-submitted-signaled
[610.069429] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[610.240345] [089/134] (417s left) kms_dsc (dsc-with-formats)
[610.430880] Starting subtest: dsc-with-formats
[610.446428] Subtest dsc-with-formats: SKIP (0.002s)
[610.553302] [090/134] (417s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[610.747349] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[610.749085] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[610.905199] [091/134] (416s left) api_intel_allocator (reopen-fork)
[610.983448] Starting subtest: reopen-fork
[614.407384] Subtest reopen-fork: SUCCESS (3.424s)
[614.605037] [092/134] (413s left) gem_exec_whisper (basic-fds-priority-all)
[614.697799] Starting subtest: basic-fds-priority-all
[637.487641] Subtest basic-fds-priority-all: SUCCESS (22.787s)
[637.737637] [093/134] (390s left) gem_exec_fence (basic-wait-all)
[637.825488] Starting subtest: basic-wait-all
[637.953016] Subtest basic-wait-all: SUCCESS (0.127s)
[638.859513] [094/134] (389s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[639.045957] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[639.048203] Starting dynamic subtest: pipe-A-DP-3
[639.069685] Dynamic subtest pipe-A-DP-3: SKIP (0.002s)
[639.081914] Starting dynamic subtest: pipe-B-DP-3
[639.102978] Dynamic subtest pipe-B-DP-3: SKIP (0.002s)
[639.114880] Starting dynamic subtest: pipe-C-DP-3
[639.140970] Dynamic subtest pipe-C-DP-3: SKIP (0.002s)
[639.153706] Starting dynamic subtest: pipe-D-DP-3
[639.175027] Dynamic subtest pipe-D-DP-3: SKIP (0.002s)
[639.176584] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.015s)
[639.550546] [095/134] (388s left) kms_psr (fbc-psr2-no-drrs)
[639.739173] Subtest fbc-psr2-no-drrs: SKIP (0.000s)
[639.794894] [096/134] (388s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[639.994833] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[639.996466] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[640.183951] [097/134] (388s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[640.377267] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[640.378976] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[640.567385] [098/134] (387s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[640.762205] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[644.601120] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.839s)
[644.839457] [099/134] (383s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[645.026539] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[645.027435] Starting dynamic subtest: pipe-A
[645.133537] Dynamic subtest pipe-A: SUCCESS (0.106s)
[645.162082] Starting dynamic subtest: pipe-B
[645.345774] Dynamic subtest pipe-B: SUCCESS (0.210s)
[645.368061] Starting dynamic subtest: pipe-C
[645.559064] Dynamic subtest pipe-C: SUCCESS (0.211s)
[645.581023] Starting dynamic subtest: pipe-D
[645.771833] Dynamic subtest pipe-D: SUCCESS (0.211s)
[645.772865] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SUCCESS (0.746s)
[646.139058] [100/134] (382s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[646.333285] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[646.334959] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[646.475303] [101/134] (382s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[646.663433] Starting subtest: flip-vs-cursor-busy-crc-atomic
[647.435147] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.770s)
[647.769316] [102/134] (380s left) core_getstats (basic)
[647.853536] Starting subtest: basic
[647.854384] Subtest basic: SUCCESS (0.000s)
[648.034347] [103/134] (380s left) gem_mmap_gtt (hang-busy)
[648.112185] Subtest hang-busy: SKIP (0.000s)
[648.178790] [104/134] (380s left) kms_flip (flip-vs-expired-vblank-interruptible)
[648.369587] Starting subtest: flip-vs-expired-vblank-interruptible
[648.374469] Starting dynamic subtest: A-DP3
[656.208493] Dynamic subtest A-DP3: SUCCESS (7.834s)
[656.224725] Starting dynamic subtest: B-DP3
[663.953995] Dynamic subtest B-DP3: SUCCESS (7.743s)
[663.993868] Starting dynamic subtest: C-DP3
[671.709743] Dynamic subtest C-DP3: SUCCESS (7.749s)
[671.753418] Starting dynamic subtest: D-DP3
[679.496248] Dynamic subtest D-DP3: SUCCESS (7.781s)
[679.498496] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (31.128s)
[679.885930] [105/134] (349s left) gem_exec_schedule (preempt-queue-contexts)
[679.980794] Subtest preempt-queue-contexts: SKIP (0.000s)
[680.411648] [106/134] (348s left) perf (oa-formats)
[680.821310] Starting subtest: oa-formats
[680.822191] Starting dynamic subtest: 0-rcs0
[680.845598] Dynamic subtest 0-rcs0: SUCCESS (0.024s)
[680.846509] Subtest oa-formats: SUCCESS (0.025s)
[680.909117] [107/134] (348s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[681.102046] Starting subtest: fbcpsr-modesetfrombusy
[681.103774] Subtest fbcpsr-modesetfrombusy: SKIP (0.000s)
[681.296618] [108/134] (347s left) kms_flip (2x-dpms-vs-vblank-race)
[681.482673] Starting subtest: 2x-dpms-vs-vblank-race
[681.497986] Subtest 2x-dpms-vs-vblank-race: SKIP (0.014s)
[681.631364] [109/134] (347s left) gem_exec_params (readonly)
[681.718389] Starting subtest: readonly
[681.719290] Subtest readonly: SUCCESS (0.001s)
[681.930495] [110/134] (347s left) kms_vblank (invalid)
[682.116566] Starting subtest: invalid
[682.118998] Starting dynamic subtest: pipe-A-DP-3
[682.452548] Dynamic subtest pipe-A-DP-3: SUCCESS (0.335s)
[682.453528] Subtest invalid: SUCCESS (0.337s)
[682.743165] [111/134] (346s left) gem_exec_reloc (basic-cpu-read-noreloc)
[682.828818] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[682.924333] [112/134] (346s left) gem_ctx_persistence (engines-hostile)
[683.011620] Starting subtest: engines-hostile
[683.012506] Starting dynamic subtest: rcs0
[683.133534] Dynamic subtest rcs0: SUCCESS (0.121s)
[683.161098] Starting dynamic subtest: bcs0
[683.240429] Dynamic subtest bcs0: SUCCESS (0.105s)
[683.275570] Starting dynamic subtest: vcs0
[683.317224] Dynamic subtest vcs0: SUCCESS (0.075s)
[683.331177] Starting dynamic subtest: vcs1
[683.431371] Dynamic subtest vcs1: SUCCESS (0.112s)
[683.452377] Starting dynamic subtest: vecs0
[683.535047] Dynamic subtest vecs0: SUCCESS (0.098s)
[683.581594] Starting dynamic subtest: vecs1
[683.610431] Dynamic subtest vecs1: SUCCESS (0.072s)
[683.629576] Starting dynamic subtest: ccs0
[683.712112] Dynamic subtest ccs0: SUCCESS (0.099s)
[683.718524] Subtest engines-hostile: SUCCESS (0.702s)
[683.942183] [113/134] (345s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[684.131913] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[684.133511] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[684.326505] [114/134] (345s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[684.518984] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[684.520698] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[684.710606] [115/134] (344s left) gem_caching (writes)
[684.796374] Starting subtest: writes
[689.067774] Subtest writes: SUCCESS (4.271s)
[689.272500] [116/134] (340s left) kms_pm_backlight (fade-with-suspend)
[689.457249] Subtest fade-with-suspend: SKIP (0.000s)
[689.520958] [117/134] (340s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[689.707827] Starting subtest: coverage-vs-premult-vs-constant
[689.974262] Starting dynamic subtest: pipe-A-DP-3
[690.437279] Dynamic subtest pipe-A-DP-3: SUCCESS (0.462s)
[690.463620] Starting dynamic subtest: pipe-D-DP-3
[690.912864] Dynamic subtest pipe-D-DP-3: SUCCESS (0.470s)
[690.913700] Subtest coverage-vs-premult-vs-constant: SUCCESS (1.205s)
[691.211420] [118/134] (338s left) kms_lease (master-vs-lease)
[691.396932] Starting subtest: master-vs-lease
[691.399415] Subtest master-vs-lease: SUCCESS (0.002s)
[691.606403] [119/134] (338s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[691.798273] Starting subtest: psr-rgb565-draw-mmap-wc
[691.799925] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.000s)
[691.991116] [120/134] (338s left) kms_addfb_basic (addfb25-4-tiled)
[692.178267] Starting subtest: addfb25-4-tiled
[692.179166] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[692.343932] [121/134] (337s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[692.531901] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[692.533571] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[692.693013] [122/134] (337s left) kms_plane_multiple (2x-tiling-x)
[692.881578] Starting subtest: 2x-tiling-x
[692.883270] Subtest 2x-tiling-x: SKIP (0.000s)
[693.010289] [123/134] (337s left) kms_psr (psr2-primary-render)
[693.193249] Subtest psr2-primary-render: SKIP (0.000s)
[693.251713] [124/134] (337s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[693.336516] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[693.448706] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.107s)
[693.573462] [125/134] (336s left) gem_exec_balancer (full)
[693.928592] Starting subtest: full
[696.170193] Subtest full: SUCCESS (2.242s)
[696.611117] [126/134] (333s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[696.800148] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[696.801896] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[697.007710] [127/134] (333s left) gem_mmap_wc (write-read)
[697.088177] Subtest write-read: SKIP (0.000s)
[697.168097] [128/134] (333s left) gem_ctx_persistence (process)
[697.256592] Starting subtest: process
[697.472100] Subtest process: SUCCESS (0.216s)
[697.658391] [129/134] (333s left) gem_ctx_param (non-root-set)
[697.738807] Starting subtest: non-root-set
[697.758279] Subtest non-root-set: SUCCESS (0.019s)
[697.927010] [130/134] (332s left) sw_sync (timeline_closed)
[697.975131] Starting subtest: timeline_closed
[697.976059] Subtest timeline_closed: SUCCESS (0.000s)
[698.074687] [131/134] (332s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[698.263276] Starting subtest: primary-4-tiled-reflect-x-180
[708.629066] Subtest primary-4-tiled-reflect-x-180: SUCCESS (10.366s)
[709.046283] [132/134] (321s left) gem_exec_parallel (basic)
[709.137233] Starting subtest: basic
[709.138167] Starting dynamic subtest: rcs0
[710.746406] Dynamic subtest rcs0: SUCCESS (1.609s)
[710.762523] Starting dynamic subtest: bcs0
[712.224967] Dynamic subtest bcs0: SUCCESS (1.477s)
[712.239587] Starting dynamic subtest: vcs0
[713.717549] Dynamic subtest vcs0: SUCCESS (1.491s)
[713.733300] Starting dynamic subtest: vcs1
[715.227560] Dynamic subtest vcs1: SUCCESS (1.509s)
[715.245123] Starting dynamic subtest: vecs0
[716.729446] Dynamic subtest vecs0: SUCCESS (1.500s)
[716.743228] Starting dynamic subtest: vecs1
[718.175228] Dynamic subtest vecs1: SUCCESS (1.444s)
[718.191443] Starting dynamic subtest: ccs0
[719.652377] Dynamic subtest ccs0: SUCCESS (1.476s)
[719.653572] Subtest basic: SUCCESS (10.516s)
[719.967005] [133/134] (311s left) kms_content_protection (uevent-hdcp14)
[720.219593] Starting subtest: uevent-hdcp14
[720.257413] Starting dynamic subtest: pipe-A-DP-3
[754.521849] Dynamic subtest pipe-A-DP-3: FAIL (34.215s)
[754.582125] Subtest uevent-hdcp14: FAIL (34.361s)
[754.885158] [134/134] (276s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[754.964445] Starting subtest: multi-wait-all-for-submit-available-signaled
[754.965365] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.001s)
[755.102650] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: gem_mmap_gtt
results: parsing output: 2/ for test: gem_ctx_engines
results: parsing output: 3/ for test: perf_pmu
results: parsing output: 4/ for test: kms_big_fb
results: parsing output: 5/ for test: api_intel_bb
results: parsing output: 6/ for test: gem_render_copy
results: parsing output: 7/ for test: kms_plane
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: kms_cursor_crc
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: gem_spin_batch
results: parsing output: 12/ for test: gem_exec_reloc
results: parsing output: 13/ for test: kms_plane
results: parsing output: 14/ for test: gem_render_copy
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_psr
results: parsing output: 17/ for test: gen9_exec_parse
results: parsing output: 18/ for test: perf
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_ccs
results: parsing output: 21/ for test: gem_exec_params
results: parsing output: 22/ for test: gem_render_copy
results: parsing output: 23/ for test: syncobj_timeline
results: parsing output: 24/ for test: gem_exec_fence
results: parsing output: 25/ for test: kms_chamelium_edid
results: parsing output: 26/ for test: gem_exec_params
results: parsing output: 27/ for test: kms_big_fb
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: gem_eio
results: parsing output: 30/ for test: kms_cursor_legacy
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: gem_exec_capture
results: parsing output: 33/ for test: syncobj_timeline
results: parsing output: 34/ for test: kms_async_flips
results: parsing output: 35/ for test: kms_big_fb
results: parsing output: 36/ for test: i915_pm_freq_api
results: parsing output: 37/ for test: kms_frontbuffer_tracking
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: gem_mmap
results: parsing output: 40/ for test: vgem_basic
results: parsing output: 41/ for test: gem_render_copy
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: gem_exec_schedule
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: kms_flip
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_color_pipeline
results: parsing output: 48/ for test: i915_pm_rc6_residency
results: parsing output: 49/ for test: kms_cursor_legacy
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_psr2_sf
results: parsing output: 53/ for test: syncobj_timeline
results: parsing output: 54/ for test: gem_mmap_wc
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_cursor_legacy
results: parsing output: 58/ for test: sysfs_heartbeat_interval
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: gem_wait
results: parsing output: 61/ for test: kms_setmode
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: sw_sync
results: parsing output: 64/ for test: kms_hdr
results: parsing output: 65/ for test: gem_exec_await
results: parsing output: 66/ for test: kms_invalid_mode
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_pm_rpm
results: parsing output: 69/ for test: kms_psr
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_pm_rps
results: parsing output: 73/ for test: kms_vblank
results: parsing output: 74/ for test: perf_pmu
results: parsing output: 75/ for test: kms_atomic_interruptible
results: parsing output: 76/ for test: gem_userptr_blits
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_lmem_swapping
results: parsing output: 79/ for test: gen9_exec_parse
results: parsing output: 80/ for test: fbdev
results: parsing output: 81/ for test: kms_chamelium_hpd
results: parsing output: 82/ for test: gem_ppgtt
results: parsing output: 83/ for test: core_setmaster_vs_auth
results: parsing output: 84/ for test: prime_busy
results: parsing output: 85/ for test: gem_eio
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: syncobj_timeline
results: parsing output: 88/ for test: kms_dsc
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: api_intel_allocator
results: parsing output: 91/ for test: gem_exec_whisper
results: parsing output: 92/ for test: gem_exec_fence
results: parsing output: 93/ for test: kms_ccs
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_plane_scaling
results: parsing output: 99/ for test: kms_flip_scaled_crc
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: core_getstats
results: parsing output: 102/ for test: gem_mmap_gtt
results: parsing output: 103/ for test: kms_flip
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: perf
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_flip
results: parsing output: 108/ for test: gem_exec_params
results: parsing output: 109/ for test: kms_vblank
results: parsing output: 110/ for test: gem_exec_reloc
results: parsing output: 111/ for test: gem_ctx_persistence
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: gem_caching
results: parsing output: 115/ for test: kms_pm_backlight
results: parsing output: 116/ for test: kms_plane_alpha_blend
results: parsing output: 117/ for test: kms_lease
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_addfb_basic
results: parsing output: 120/ for test: kms_flip_scaled_crc
results: parsing output: 121/ for test: kms_plane_multiple
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: syncobj_wait
results: parsing output: 124/ for test: gem_exec_balancer
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: gem_mmap_wc
results: parsing output: 127/ for test: gem_ctx_persistence
results: parsing output: 128/ for test: gem_ctx_param
results: parsing output: 129/ for test: sw_sync
results: parsing output: 130/ for test: kms_rotation_crc
results: parsing output: 131/ for test: gem_exec_parallel
results: parsing output: 132/ for test: kms_content_protection
results: parsing output: 133/ for test: syncobj_timeline
Done.