[60.274785] Initializing watchdogs
[60.274871]   /dev/watchdog0
[60.294822] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:03:00.0: 8086:56a0 Intel Dg2 (Gen12) DG2 [Arc A770]
[60.305790] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[60.309715] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.312067] [001/134] (960s left) kms_cursor_crc (cursor-offscreen-256x85)
[60.752109] Starting subtest: cursor-offscreen-256x85
[60.767206] Starting dynamic subtest: pipe-A-DP-3
[63.332411] Dynamic subtest pipe-A-DP-3: SUCCESS (2.554s)
[63.382056] Starting dynamic subtest: pipe-D-DP-3
[65.873701] Dynamic subtest pipe-D-DP-3: SUCCESS (2.491s)
[65.874707] Subtest cursor-offscreen-256x85: SUCCESS (5.102s)
[66.259838] [002/134] (954s left) gem_mmap_gtt (cpuset-big-copy-odd)
[66.329179] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[66.401250] [003/134] (954s left) gem_ctx_engines (execute-allforone)
[66.484587] Starting subtest: execute-allforone
[66.529414] Subtest execute-allforone: SUCCESS (0.045s)
[66.831829] [004/134] (954s left) perf_pmu (busy-idle)
[66.917513] Starting subtest: busy-idle
[66.918433] Starting dynamic subtest: rcs0
[68.004686] Dynamic subtest rcs0: SUCCESS (1.084s)
[68.016678] Starting dynamic subtest: bcs0
[68.669549] Dynamic subtest bcs0: SUCCESS (0.662s)
[68.704214] Starting dynamic subtest: vcs0
[69.358544] Dynamic subtest vcs0: SUCCESS (0.687s)
[69.371468] Starting dynamic subtest: vcs1
[70.023410] Dynamic subtest vcs1: SUCCESS (0.663s)
[70.035824] Starting dynamic subtest: vecs0
[70.688523] Dynamic subtest vecs0: SUCCESS (0.663s)
[70.701578] Starting dynamic subtest: vecs1
[71.356739] Dynamic subtest vecs1: SUCCESS (0.666s)
[71.369601] Starting dynamic subtest: ccs0
[72.020252] Dynamic subtest ccs0: SUCCESS (0.662s)
[72.021151] Subtest busy-idle: SUCCESS (5.094s)
[72.180941] [005/134] (948s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[72.375568] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[73.071999] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SUCCESS (0.695s)
[73.352628] [006/134] (947s left) api_intel_bb (lot-of-buffers)
[73.424289] Starting subtest: lot-of-buffers
[76.654031] Subtest lot-of-buffers: SUCCESS (3.227s)
[76.877046] [007/134] (944s left) gem_render_copy (yf-tiled-to-vebox-x-tiled)
[76.955386] Starting subtest: yf-tiled-to-vebox-x-tiled
[76.957550] Subtest yf-tiled-to-vebox-x-tiled: SKIP (0.000s)
[77.183613] [008/134] (944s left) kms_plane (plane-panning-top-left)
[77.365746] Starting subtest: plane-panning-top-left
[77.366596] Starting dynamic subtest: pipe-A
[78.145457] Dynamic subtest pipe-A: SUCCESS (0.777s)
[78.155798] Starting dynamic subtest: pipe-B
[78.811297] Dynamic subtest pipe-B: SUCCESS (0.664s)
[78.812207] Subtest plane-panning-top-left: SUCCESS (1.443s)
[79.105261] [009/134] (942s left) kms_psr (fbc-psr-cursor-plane-onoff)
[79.288424] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[79.351293] [010/134] (942s left) kms_cursor_crc (cursor-tearing-position-change)
[79.576205] Starting subtest: cursor-tearing-position-change
[79.578139] Starting dynamic subtest: pipe-A-DP-3
[80.073827] Dynamic subtest pipe-A-DP-3: SUCCESS (0.495s)
[80.095053] Starting dynamic subtest: pipe-D-DP-3
[80.481851] Dynamic subtest pipe-D-DP-3: SUCCESS (0.405s)
[80.482804] Subtest cursor-tearing-position-change: SUCCESS (0.904s)
[81.095973] [011/134] (940s left) kms_frontbuffer_tracking (basic)
[81.280662] Starting subtest: basic
[84.000822] Subtest basic: SUCCESS (2.718s)
[84.245936] [012/134] (937s left) gem_spin_batch (spin-each)
[84.328336] Starting subtest: spin-each
[87.763037] Subtest spin-each: SUCCESS (3.431s)
[88.098777] [013/134] (933s left) gem_exec_reloc (basic-gtt-cpu)
[88.172401] Subtest basic-gtt-cpu: SKIP (0.000s)
[88.259660] [014/134] (933s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[88.437423] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[88.468986] Starting dynamic subtest: pipe-A-plane-0
[88.471167] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[88.480201] Starting dynamic subtest: pipe-A-plane-3
[88.482602] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[88.491293] Starting dynamic subtest: pipe-A-plane-5
[88.493127] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[88.695265] Starting dynamic subtest: pipe-B-plane-0
[88.697532] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[88.713465] Starting dynamic subtest: pipe-B-plane-3
[88.715529] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[88.731284] Starting dynamic subtest: pipe-B-plane-5
[88.733446] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[88.776360] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SKIP (0.338s)
[89.075757] [015/134] (932s left) gem_render_copy (y-tiled-ccs-to-linear)
[89.155409] Starting subtest: y-tiled-ccs-to-linear
[89.157491] Subtest y-tiled-ccs-to-linear: SKIP (0.000s)
[89.438611] [016/134] (932s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[89.626277] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[89.628273] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[89.818893] [017/134] (932s left) kms_psr (pr-sprite-render)
[90.029756] Subtest pr-sprite-render: SKIP (0.000s)
[90.079633] [018/134] (931s left) gen9_exec_parse (shadow-peek)
[90.157067] Subtest shadow-peek: SKIP (0.000s)
[90.234264] [019/134] (931s left) perf (invalid-remove-userspace-config)
[90.651807] Starting subtest: invalid-remove-userspace-config
[90.660853] Subtest invalid-remove-userspace-config: SUCCESS (0.009s)
[90.715685] [020/134] (931s left) gem_ctx_param (invalid-size-set)
[90.787395] Starting subtest: invalid-size-set
[90.789248] Subtest invalid-size-set: SUCCESS (0.001s)
[90.953906] [021/134] (931s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[91.131988] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[91.132983] Starting dynamic subtest: pipe-A-DP-3
[91.141219] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[91.150205] Starting dynamic subtest: pipe-B-DP-3
[91.158695] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[91.167469] Starting dynamic subtest: pipe-C-DP-3
[91.175558] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[91.184228] Starting dynamic subtest: pipe-D-DP-3
[91.194981] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[91.196980] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[91.579111] [022/134] (930s left) gem_exec_params (invalid-bsd2-flag-on-blt)
[91.655416] Starting subtest: invalid-bsd2-flag-on-blt
[91.656516] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s)
[91.831959] [023/134] (930s left) gem_render_copy (yf-tiled-ccs-to-linear)
[91.908487] Starting subtest: yf-tiled-ccs-to-linear
[91.910638] Subtest yf-tiled-ccs-to-linear: SKIP (0.000s)
[92.164989] [024/134] (930s left) syncobj_timeline (etime-multi-wait-for-submit-unsubmitted)
[92.237408] Starting subtest: etime-multi-wait-for-submit-unsubmitted
[92.341313] Subtest etime-multi-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[92.465115] [025/134] (929s left) gem_exec_fence (invalid-timeline-fence-array)
[93.193053] Starting subtest: invalid-timeline-fence-array
[93.210523] Subtest invalid-timeline-fence-array: SUCCESS (0.019s)
[93.503468] [FACT gem_exec_fence (invalid-timeline-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[93.505467] [026/134] (928s left) kms_chamelium_edid (dp-edid-read)
[93.764984] Subtest dp-edid-read: SKIP (0.000s)
[93.968641] [027/134] (928s left) gem_exec_params (invalid-bsd1-flag-on-render)
[94.061969] Starting subtest: invalid-bsd1-flag-on-render
[94.062885] Subtest invalid-bsd1-flag-on-render: SUCCESS (0.000s)
[94.221140] [028/134] (928s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[94.416373] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[95.641966] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (1.224s)
[95.874291] [029/134] (926s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[96.063844] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[96.064622] Starting dynamic subtest: pipe-A-DP-3
[96.073031] Dynamic subtest pipe-A-DP-3: SKIP (0.001s)
[96.082169] Starting dynamic subtest: pipe-B-DP-3
[96.090296] Dynamic subtest pipe-B-DP-3: SKIP (0.001s)
[96.099175] Starting dynamic subtest: pipe-C-DP-3
[96.107400] Dynamic subtest pipe-C-DP-3: SKIP (0.001s)
[96.116177] Starting dynamic subtest: pipe-D-DP-3
[96.126199] Dynamic subtest pipe-D-DP-3: SKIP (0.001s)
[96.128331] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.006s)
[96.534870] [030/134] (926s left) gem_eio (kms)
[96.916925] Starting subtest: kms
[127.966660] Subtest kms: SUCCESS (31.043s)
[128.362050] [031/134] (894s left) kms_cursor_legacy (torture-move)
[128.550786] Starting subtest: torture-move
[128.551670] Starting dynamic subtest: pipe-A
[134.077258] Dynamic subtest pipe-A: SUCCESS (5.524s)
[134.087430] Starting dynamic subtest: pipe-B
[139.648660] Dynamic subtest pipe-B: SUCCESS (5.571s)
[139.659508] Starting dynamic subtest: pipe-C
[145.109495] Dynamic subtest pipe-C: SUCCESS (5.455s)
[145.119956] Starting dynamic subtest: pipe-D
[150.570374] Dynamic subtest pipe-D: SUCCESS (5.467s)
[150.580684] Starting dynamic subtest: all-pipes
[156.024336] Dynamic subtest all-pipes: SUCCESS (5.454s)
[156.025417] Subtest torture-move: SUCCESS (27.475s)
[156.232846] [032/134] (866s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[156.424427] Starting subtest: bad-pixel-format-yf-tiled-ccs
[156.425364] Starting dynamic subtest: pipe-A-DP-3
[156.433814] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[156.443676] Starting dynamic subtest: pipe-B-DP-3
[156.452182] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[156.461860] Starting dynamic subtest: pipe-C-DP-3
[156.469828] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[156.479483] Starting dynamic subtest: pipe-D-DP-3
[156.490334] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[156.492366] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.004s)
[156.913370] [033/134] (866s left) gem_exec_capture (pi)
[156.996995] Starting subtest: pi
[156.997980] Starting dynamic subtest: rcs0
[162.910166] Dynamic subtest rcs0: SUCCESS (5.914s)
[162.920504] Starting dynamic subtest: bcs0
[168.717044] Dynamic subtest bcs0: SUCCESS (5.807s)
[168.727429] Starting dynamic subtest: vcs0
[174.421250] Dynamic subtest vcs0: SUCCESS (5.704s)
[174.431555] Starting dynamic subtest: vcs1
[180.136352] Dynamic subtest vcs1: SUCCESS (5.714s)
[180.146866] Starting dynamic subtest: vecs0
[185.856460] Dynamic subtest vecs0: SUCCESS (5.721s)
[185.867527] Starting dynamic subtest: vecs1
[191.574455] Dynamic subtest vecs1: SUCCESS (5.717s)
[191.584900] Starting dynamic subtest: ccs0
[197.320413] Dynamic subtest ccs0: SUCCESS (5.746s)
[197.321376] Subtest pi: SUCCESS (40.327s)
[197.516414] [034/134] (825s left) syncobj_timeline (wait-all-snapshot)
[197.644375] Starting subtest: wait-all-snapshot
[197.727042] Subtest wait-all-snapshot: SUCCESS (0.082s)
[197.894664] [035/134] (825s left) kms_async_flips (test-cursor-atomic)
[198.089379] Starting subtest: test-cursor-atomic
[198.320331] Starting dynamic subtest: pipe-A-DP-3
[198.623371] Dynamic subtest pipe-A-DP-3: SUCCESS (0.303s)
[198.868397] Starting dynamic subtest: pipe-B-DP-3
[198.974518] Dynamic subtest pipe-B-DP-3: SUCCESS (0.117s)
[199.187957] Starting dynamic subtest: pipe-C-DP-3
[199.302706] Dynamic subtest pipe-C-DP-3: SUCCESS (0.124s)
[199.512532] Starting dynamic subtest: pipe-D-DP-3
[199.633383] Dynamic subtest pipe-D-DP-3: SUCCESS (0.131s)
[199.634293] Subtest test-cursor-atomic: SUCCESS (1.544s)
[200.137190] [036/134] (823s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[200.338571] Starting subtest: x-tiled-32bpp-rotate-180
[202.270410] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.931s)
[202.470085] [037/134] (820s left) i915_pm_freq_api (freq-reset)
[202.558583] Starting subtest: freq-reset
[202.559501] Starting dynamic subtest: gt0
[202.967462] Dynamic subtest gt0: SUCCESS (0.408s)
[202.968466] Subtest freq-reset: SUCCESS (0.408s)
[203.110404] [038/134] (820s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc)
[203.306538] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc
[203.308211] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[203.474733] [039/134] (820s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[203.659177] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[203.660053] Starting dynamic subtest: pipe-A-DP-3
[203.669254] Dynamic subtest pipe-A-DP-3: SKIP (0.000s)
[203.679568] Starting dynamic subtest: pipe-B-DP-3
[203.688820] Dynamic subtest pipe-B-DP-3: SKIP (0.000s)
[203.698928] Starting dynamic subtest: pipe-C-DP-3
[203.707872] Dynamic subtest pipe-C-DP-3: SKIP (0.000s)
[203.718052] Starting dynamic subtest: pipe-D-DP-3
[203.728673] Dynamic subtest pipe-D-DP-3: SKIP (0.000s)
[203.730562] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[204.165792] [040/134] (819s left) gem_mmap (short-mmap)
[204.243977] Subtest short-mmap: SKIP (0.000s)
[204.309729] [041/134] (819s left) vgem_basic (dmabuf-export)
[204.367365] Starting subtest: dmabuf-export
[204.407593] Subtest dmabuf-export: SUCCESS (0.041s)
[204.599424] [042/134] (819s left) gem_render_copy (y-tiled)
[204.682441] Starting subtest: y-tiled
[204.684514] Subtest y-tiled: SKIP (0.000s)
[204.900209] [043/134] (818s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[205.092622] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[205.094302] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[205.285266] [044/134] (818s left) gem_exec_schedule (implicit-write-read)
[205.375578] Starting subtest: implicit-write-read
[205.376452] Starting dynamic subtest: rcs0
[205.674939] Dynamic subtest rcs0: SUCCESS (0.299s)
[205.685602] Starting dynamic subtest: bcs0
[205.880474] Dynamic subtest bcs0: SUCCESS (0.205s)
[205.891265] Starting dynamic subtest: vcs0
[206.093701] Dynamic subtest vcs0: SUCCESS (0.212s)
[206.113691] Starting dynamic subtest: vcs1
[206.318138] Dynamic subtest vcs1: SUCCESS (0.223s)
[206.331960] Starting dynamic subtest: vecs0
[206.531182] Dynamic subtest vecs0: SUCCESS (0.212s)
[206.541751] Starting dynamic subtest: vecs1
[206.741426] Dynamic subtest vecs1: SUCCESS (0.210s)
[206.752227] Starting dynamic subtest: ccs0
[206.994524] Dynamic subtest ccs0: SUCCESS (0.252s)
[206.995405] Subtest implicit-write-read: SUCCESS (1.618s)
[207.438125] [045/134] (816s left) kms_big_fb (4-tiled-64bpp-rotate-90)
[207.635715] Starting subtest: 4-tiled-64bpp-rotate-90
[207.701221] Subtest 4-tiled-64bpp-rotate-90: SKIP (0.065s)
[207.932424] [046/134] (816s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[208.125902] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[208.130382] Starting dynamic subtest: A-DP3
[216.217965] Dynamic subtest A-DP3: SUCCESS (8.087s)
[216.230660] Starting dynamic subtest: B-DP3
[224.254974] Dynamic subtest B-DP3: SUCCESS (8.036s)
[224.266382] Starting dynamic subtest: C-DP3
[232.294195] Dynamic subtest C-DP3: SUCCESS (8.031s)
[232.305532] Starting dynamic subtest: D-DP3
[240.315193] Dynamic subtest D-DP3: SUCCESS (8.027s)
[240.317201] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (32.190s)
[240.682229] [047/134] (783s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-gtt)
[240.877692] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-gtt
[240.879388] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[241.079085] [048/134] (783s left) kms_color_pipeline (plane-lut1d)
[241.272156] Starting subtest: plane-lut1d
[241.294609] Starting dynamic subtest: pipe-A-DP-3
[241.677776] Dynamic subtest pipe-A-DP-3: SUCCESS (0.383s)
[241.843568] Starting dynamic subtest: pipe-B-DP-3
[242.178862] Dynamic subtest pipe-B-DP-3: SUCCESS (0.348s)
[242.355575] Starting dynamic subtest: pipe-C-DP-3
[242.691762] Dynamic subtest pipe-C-DP-3: SUCCESS (0.348s)
[242.875664] Starting dynamic subtest: pipe-D-DP-3
[243.213589] Dynamic subtest pipe-D-DP-3: SUCCESS (0.348s)
[243.288174] Subtest plane-lut1d: SUCCESS (2.016s)
[243.572819] [049/134] (780s left) i915_pm_rc6_residency (rc6-accuracy)
[243.652272] Starting subtest: rc6-accuracy
[243.653185] Starting dynamic subtest: gt0
[246.872853] Dynamic subtest gt0: FAIL (3.200s)
[246.873930] Subtest rc6-accuracy: FAIL (3.201s)
[247.004884] [050/134] (777s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[247.198849] Starting subtest: basic-flip-before-cursor-atomic
[248.247449] Subtest basic-flip-before-cursor-atomic: SUCCESS (1.048s)
[248.578282] [051/134] (775s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-move)
[248.769534] Starting subtest: psr-2p-primscrn-spr-indfb-move
[248.771212] Subtest psr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[248.965659] [052/134] (775s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[249.157032] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu
[249.158868] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[249.366744] [053/134] (775s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[249.566413] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[249.654355] [054/134] (775s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[249.732336] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[249.837522] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.104s)
[249.980209] [055/134] (774s left) gem_mmap_wc (bad-size)
[250.068131] Subtest bad-size: SKIP (0.000s)
[250.140585] [056/134] (774s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[250.340407] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[250.342175] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[250.492930] [057/134] (774s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[250.683911] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[250.686577] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[250.854554] [058/134] (774s left) kms_cursor_legacy (cursora-vs-flipb-atomic)
[251.038720] Starting subtest: cursorA-vs-flipB-atomic
[251.041753] Subtest cursorA-vs-flipB-atomic: SKIP (0.001s)
[251.194520] [059/134] (773s left) sysfs_heartbeat_interval (nopreempt)
[251.283241] Starting subtest: nopreempt
[251.284260] Starting dynamic subtest: vecs0
[255.605144] Dynamic subtest vecs0: SUCCESS (4.322s)
[255.615949] Starting dynamic subtest: ccs0
[259.898813] Dynamic subtest ccs0: SUCCESS (4.293s)
[259.912516] Starting dynamic subtest: vcs0
[264.062205] Dynamic subtest vcs0: SUCCESS (4.162s)
[264.072988] Starting dynamic subtest: bcs0
[268.217034] Dynamic subtest bcs0: SUCCESS (4.154s)
[268.227546] Starting dynamic subtest: vecs1
[272.385256] Dynamic subtest vecs1: SUCCESS (4.167s)
[272.396801] Starting dynamic subtest: rcs0
[276.698690] Dynamic subtest rcs0: SUCCESS (4.312s)
[276.709935] Starting dynamic subtest: vcs1
[280.922249] Dynamic subtest vcs1: SUCCESS (4.222s)
[280.923241] Subtest nopreempt: SUCCESS (29.639s)
[281.156813] [060/134] (743s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[281.356429] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[281.358214] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[281.520792] [061/134] (743s left) gem_wait (busy)
[281.615288] Starting subtest: busy
[281.616222] Starting dynamic subtest: all-engines
[282.212398] Dynamic subtest all-engines: SUCCESS (0.597s)
[282.226748] Starting dynamic subtest: rcs0
[282.754016] Dynamic subtest rcs0: SUCCESS (0.540s)
[282.764634] Starting dynamic subtest: bcs0
[283.293973] Dynamic subtest bcs0: SUCCESS (0.539s)
[283.304958] Starting dynamic subtest: vcs0
[283.833130] Dynamic subtest vcs0: SUCCESS (0.538s)
[283.844014] Starting dynamic subtest: vcs1
[284.372378] Dynamic subtest vcs1: SUCCESS (0.538s)
[284.383272] Starting dynamic subtest: vecs0
[284.911232] Dynamic subtest vecs0: SUCCESS (0.538s)
[284.922072] Starting dynamic subtest: vecs1
[285.450059] Dynamic subtest vecs1: SUCCESS (0.538s)
[285.460809] Starting dynamic subtest: ccs0
[285.989931] Dynamic subtest ccs0: SUCCESS (0.539s)
[285.990907] Subtest busy: SUCCESS (4.375s)
[286.248106] [062/134] (739s left) kms_setmode (clone-exclusive-crtc)
[286.329443] Starting subtest: clone-exclusive-crtc
[286.332522] Subtest clone-exclusive-crtc: SKIP (0.000s)
[286.515129] [063/134] (738s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-fully-sf)
[286.707222] Subtest pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[286.760347] [064/134] (738s left) sw_sync (sync_merge_same)
[286.809432] Starting subtest: sync_merge_same
[286.810284] Subtest sync_merge_same: SUCCESS (0.000s)
[286.904274] [065/134] (738s left) kms_hdr (static-toggle)
[287.094193] Starting subtest: static-toggle
[287.661098] Starting dynamic subtest: pipe-A-DP-3
[288.747135] Dynamic subtest pipe-A-DP-3: SUCCESS (1.086s)
[288.748497] Subtest static-toggle: SUCCESS (1.655s)
[289.118762] [066/134] (736s left) gem_exec_await (wide-contexts)
[289.391848] Starting subtest: wide-contexts
[311.362561] Subtest wide-contexts: SUCCESS (21.970s)
[311.658083] [067/134] (713s left) kms_invalid_mode (uint-max-clock)
[311.877231] Starting subtest: uint-max-clock
[311.878094] Starting dynamic subtest: pipe-A-DP-3
[311.961294] Dynamic subtest pipe-A-DP-3: SUCCESS (0.083s)
[311.977888] Starting dynamic subtest: pipe-B-DP-3
[311.978811] Dynamic subtest pipe-B-DP-3: SUCCESS (0.001s)
[311.993256] Starting dynamic subtest: pipe-C-DP-3
[311.994262] Dynamic subtest pipe-C-DP-3: SUCCESS (0.001s)
[312.005805] Starting dynamic subtest: pipe-D-DP-3
[312.006703] Dynamic subtest pipe-D-DP-3: SUCCESS (0.002s)
[312.007542] Subtest uint-max-clock: SUCCESS (0.096s)
[312.243488] [068/134] (713s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-blt)
[312.451036] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-blt
[315.711114] Subtest fbc-1p-primscrn-cur-indfb-draw-blt: SUCCESS (3.261s)
[315.978971] [069/134] (709s left) kms_pm_rpm (modeset-non-lpsp)
[318.025202] Starting subtest: modeset-non-lpsp
[318.687896] Subtest modeset-non-lpsp: SUCCESS (0.665s)
[318.982417] [070/134] (706s left) kms_psr (fbc-pr-cursor-mmap-cpu)
[319.169338] Subtest fbc-pr-cursor-mmap-cpu: SKIP (0.000s)
[319.227446] [071/134] (706s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-wc)
[319.418257] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-wc
[319.420014] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[319.617317] [072/134] (706s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc)
[319.807168] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc
[319.808933] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[320.001628] [073/134] (706s left) i915_pm_rps (thresholds-idle)
[320.091793] Starting subtest: thresholds-idle
[320.093568] Subtest thresholds-idle: SKIP (0.000s)
[320.220862] [074/134] (705s left) kms_vblank (query-idle-hang)
[320.408501] Starting subtest: query-idle-hang
[320.411106] Starting dynamic subtest: pipe-A-DP-3
[346.576913] Dynamic subtest pipe-A-DP-3: SUCCESS (26.165s)
[346.591345] Starting dynamic subtest: pipe-D-DP-3
[371.570571] Dynamic subtest pipe-D-DP-3: SUCCESS (24.988s)
[371.571468] Subtest query-idle-hang: SUCCESS (51.160s)
[371.872573] [075/134] (654s left) perf_pmu (most-busy-idle-check-all)
[371.943639] Starting subtest: most-busy-idle-check-all
[371.944523] Starting dynamic subtest: rcs0
[372.641249] Dynamic subtest rcs0: SUCCESS (0.697s)
[372.656659] Starting dynamic subtest: bcs0
[373.301750] Dynamic subtest bcs0: SUCCESS (0.658s)
[373.317618] Starting dynamic subtest: vcs0
[373.959589] Dynamic subtest vcs0: SUCCESS (0.654s)
[373.972568] Starting dynamic subtest: vcs1
[374.613630] Dynamic subtest vcs1: SUCCESS (0.653s)
[374.627149] Starting dynamic subtest: vecs0
[375.270654] Dynamic subtest vecs0: SUCCESS (0.656s)
[375.283724] Starting dynamic subtest: vecs1
[375.925690] Dynamic subtest vecs1: SUCCESS (0.653s)
[375.940966] Starting dynamic subtest: ccs0
[376.582690] Dynamic subtest ccs0: SUCCESS (0.653s)
[376.583584] Subtest most-busy-idle-check-all: SUCCESS (4.639s)
[376.810368] [076/134] (649s left) kms_atomic_interruptible (universal-setplane-cursor)
[377.004709] Starting subtest: universal-setplane-cursor
[377.007151] Starting dynamic subtest: pipe-A-DP-3
[383.332562] Dynamic subtest pipe-A-DP-3: SUCCESS (6.323s)
[383.334105] Subtest universal-setplane-cursor: SUCCESS (6.327s)
[383.638417] [077/134] (642s left) gem_userptr_blits (process-exit-busy)
[383.728823] Starting subtest: process-exit-busy
[383.744483] Subtest process-exit-busy: SUCCESS (0.016s)
[383.969604] [078/134] (642s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite)
[384.160014] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite
[384.161784] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[384.318388] [079/134] (642s left) gem_lmem_swapping (heavy-verify-multi)
[387.085727] Starting subtest: heavy-verify-multi
[387.092909] Starting dynamic subtest: lmem0
[400.879946] Dynamic subtest lmem0: SUCCESS (13.793s)
[400.880948] Subtest heavy-verify-multi: SUCCESS (13.794s)
[402.118708] [FACT gem_lmem_swapping (heavy-verify-multi)] deleted: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[402.120035] [FACT gem_lmem_swapping (heavy-verify-multi)] deleted: kernel.kmod_is_loaded.i915: true
[402.122412] [080/134] (624s left) gen9_exec_parse (bb-start-cmd)
[403.722125] Subtest bb-start-cmd: SKIP (0.000s)
[403.772760] [FACT gen9_exec_parse (bb-start-cmd)] new: hardware.pci.drm_card_at_addr.0000:03:00.0: card0
[403.775254] [FACT gen9_exec_parse (bb-start-cmd)] new: kernel.kmod_is_loaded.i915: true
[403.778437] [081/134] (622s left) fbdev (unaligned-read)
[403.847234] Starting subtest: unaligned-read
[403.860782] Subtest unaligned-read: SUCCESS (0.013s)
[404.017459] [082/134] (622s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[404.291398] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[404.447765] [083/134] (622s left) gem_ppgtt (blt-vs-render-ctx0)
[404.539427] Starting subtest: blt-vs-render-ctx0
[437.274463] Subtest blt-vs-render-ctx0: SUCCESS (32.734s)
[437.477320] [084/134] (589s left) core_setmaster_vs_auth
[437.765753] [085/134] (589s left) prime_busy (hang)
[437.849597] Starting subtest: hang
[437.851953] Starting dynamic subtest: rcs0
[464.485781] Dynamic subtest rcs0: SUCCESS (26.631s)
[464.496590] Starting dynamic subtest: bcs0
[471.641944] Dynamic subtest bcs0: SUCCESS (7.154s)
[471.652820] Starting dynamic subtest: vcs0
[478.620986] Dynamic subtest vcs0: SUCCESS (6.979s)
[478.631658] Starting dynamic subtest: vcs1
[485.596947] Dynamic subtest vcs1: SUCCESS (6.974s)
[485.608879] Starting dynamic subtest: vecs0
[492.634080] Dynamic subtest vecs0: SUCCESS (7.034s)
[492.644591] Starting dynamic subtest: vecs1
[499.615984] Dynamic subtest vecs1: SUCCESS (6.982s)
[499.626605] Starting dynamic subtest: ccs0
[513.510149] Dynamic subtest ccs0: SUCCESS (13.892s)
[513.512476] Subtest hang: SUCCESS (75.657s)
[513.669376] [086/134] (513s left) gem_eio (unwedge-stress)
[513.922187] Starting subtest: unwedge-stress
[513.923171] Starting dynamic subtest: render
[528.430132] Dynamic subtest render: SUCCESS (14.506s)
[528.441639] Starting dynamic subtest: bsd1
[545.953341] Dynamic subtest bsd1: SUCCESS (17.523s)
[545.965329] Starting dynamic subtest: bsd2
[560.678501] Dynamic subtest bsd2: SUCCESS (14.724s)
[560.691703] Starting dynamic subtest: blt
[573.908869] Dynamic subtest blt: SUCCESS (13.229s)
[573.920704] Starting dynamic subtest: vebox
[587.956897] Dynamic subtest vebox: SUCCESS (14.047s)
[587.957923] Subtest unwedge-stress: SUCCESS (74.035s)
[588.287365] [087/134] (438s left) kms_cursor_crc (cursor-random-256x256)
[588.710258] Starting subtest: cursor-random-256x256
[588.715298] Starting dynamic subtest: pipe-A-DP-3
[593.276781] Dynamic subtest pipe-A-DP-3: SUCCESS (4.559s)
[593.312700] Starting dynamic subtest: pipe-D-DP-3
[597.727282] Dynamic subtest pipe-D-DP-3: SUCCESS (4.425s)
[597.728292] Subtest cursor-random-256x256: SUCCESS (9.016s)
[598.165011] [088/134] (429s left) syncobj_timeline (multi-wait-submitted-signaled)
[598.254955] Starting subtest: multi-wait-submitted-signaled
[598.255867] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[598.386554] [089/134] (428s left) kms_dsc (dsc-with-formats)
[598.578747] Starting subtest: dsc-with-formats
[598.595469] Subtest dsc-with-formats: SKIP (0.002s)
[598.709686] [090/134] (428s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[598.901317] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[598.903441] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[599.107353] [091/134] (428s left) api_intel_allocator (reopen-fork)
[599.185818] Starting subtest: reopen-fork
[602.585370] Subtest reopen-fork: SUCCESS (3.401s)
[602.723882] [092/134] (424s left) gem_exec_whisper (basic-fds-priority-all)
[602.814748] Starting subtest: basic-fds-priority-all
[625.446742] Subtest basic-fds-priority-all: SUCCESS (22.630s)
[625.718107] [093/134] (401s left) gem_exec_fence (basic-wait-all)
[625.814436] Starting subtest: basic-wait-all
[625.940088] Subtest basic-wait-all: SUCCESS (0.125s)
[626.888353] [094/134] (400s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[627.071974] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[627.074418] Starting dynamic subtest: pipe-A-DP-3
[627.095817] Dynamic subtest pipe-A-DP-3: SKIP (0.002s)
[627.107550] Starting dynamic subtest: pipe-B-DP-3
[627.129271] Dynamic subtest pipe-B-DP-3: SKIP (0.002s)
[627.141219] Starting dynamic subtest: pipe-C-DP-3
[627.167524] Dynamic subtest pipe-C-DP-3: SKIP (0.002s)
[627.179936] Starting dynamic subtest: pipe-D-DP-3
[627.203525] Dynamic subtest pipe-D-DP-3: SKIP (0.002s)
[627.205831] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.015s)
[627.550784] [095/134] (400s left) kms_psr (fbc-psr2-no-drrs)
[627.744116] Subtest fbc-psr2-no-drrs: SKIP (0.000s)
[627.811990] [096/134] (400s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[628.014119] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[628.015796] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[628.220262] [097/134] (399s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite)
[628.418134] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite
[628.419863] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[628.618905] [098/134] (399s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[628.810121] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[631.699991] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (2.890s)
[631.954469] [099/134] (396s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[632.142628] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[632.143502] Starting dynamic subtest: pipe-A
[632.249062] Dynamic subtest pipe-A: SUCCESS (0.106s)
[632.277513] Starting dynamic subtest: pipe-B
[632.463815] Dynamic subtest pipe-B: SUCCESS (0.213s)
[632.485405] Starting dynamic subtest: pipe-C
[632.676816] Dynamic subtest pipe-C: SUCCESS (0.211s)
[632.698291] Starting dynamic subtest: pipe-D
[632.889887] Dynamic subtest pipe-D: SUCCESS (0.211s)
[632.890966] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SUCCESS (0.748s)
[633.206708] [100/134] (394s left) kms_flip_scaled_crc (flip-32bpp-yftile-to-32bpp-yftileccs-upscaling)
[633.397433] Starting subtest: flip-32bpp-yftile-to-32bpp-yftileccs-upscaling
[633.399154] Subtest flip-32bpp-yftile-to-32bpp-yftileccs-upscaling: SKIP (0.000s)
[633.547709] [101/134] (394s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic)
[633.736338] Starting subtest: flip-vs-cursor-busy-crc-atomic
[634.501795] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.766s)
[634.849512] [102/134] (393s left) core_getstats (basic)
[634.928530] Starting subtest: basic
[634.929455] Subtest basic: SUCCESS (0.000s)
[635.071567] [103/134] (393s left) gem_mmap_gtt (hang-busy)
[635.153191] Subtest hang-busy: SKIP (0.000s)
[635.224152] [104/134] (393s left) kms_flip (flip-vs-expired-vblank-interruptible)
[635.418774] Starting subtest: flip-vs-expired-vblank-interruptible
[635.423521] Starting dynamic subtest: A-DP3
[643.264350] Dynamic subtest A-DP3: SUCCESS (7.840s)
[643.280436] Starting dynamic subtest: B-DP3
[651.014477] Dynamic subtest B-DP3: SUCCESS (7.746s)
[651.028862] Starting dynamic subtest: C-DP3
[658.762463] Dynamic subtest C-DP3: SUCCESS (7.746s)
[658.776989] Starting dynamic subtest: D-DP3
[666.540728] Dynamic subtest D-DP3: SUCCESS (7.776s)
[666.549353] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (31.129s)
[666.903073] [105/134] (361s left) gem_exec_schedule (preempt-queue-contexts)
[666.999633] Subtest preempt-queue-contexts: SKIP (0.000s)
[667.422694] [106/134] (361s left) perf (oa-formats)
[667.840554] Starting subtest: oa-formats
[667.841396] Starting dynamic subtest: 0-rcs0
[667.864421] Dynamic subtest 0-rcs0: SUCCESS (0.024s)
[667.865333] Subtest oa-formats: SUCCESS (0.025s)
[667.917459] [107/134] (360s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[668.110175] Starting subtest: fbcpsr-modesetfrombusy
[668.111918] Subtest fbcpsr-modesetfrombusy: SKIP (0.000s)
[668.294234] [108/134] (360s left) kms_flip (2x-dpms-vs-vblank-race)
[668.479160] Starting subtest: 2x-dpms-vs-vblank-race
[668.494493] Subtest 2x-dpms-vs-vblank-race: SKIP (0.015s)
[668.654795] [109/134] (360s left) gem_exec_params (readonly)
[668.742154] Starting subtest: readonly
[668.745777] Subtest readonly: SUCCESS (0.004s)
[668.922570] [110/134] (359s left) kms_vblank (invalid)
[669.113390] Starting subtest: invalid
[669.114273] Starting dynamic subtest: pipe-A-DP-3
[669.435512] Dynamic subtest pipe-A-DP-3: SUCCESS (0.319s)
[669.436701] Subtest invalid: SUCCESS (0.322s)
[669.719362] [111/134] (359s left) gem_exec_reloc (basic-cpu-read-noreloc)
[669.804435] Subtest basic-cpu-read-noreloc: SKIP (0.000s)
[669.895414] [112/134] (359s left) gem_ctx_persistence (engines-hostile)
[669.983948] Starting subtest: engines-hostile
[669.984993] Starting dynamic subtest: rcs0
[670.078510] Dynamic subtest rcs0: SUCCESS (0.094s)
[670.093332] Starting dynamic subtest: bcs0
[670.159874] Dynamic subtest bcs0: SUCCESS (0.079s)
[670.207587] Starting dynamic subtest: vcs0
[670.219245] Dynamic subtest vcs0: SUCCESS (0.056s)
[670.238768] Starting dynamic subtest: vcs1
[670.323794] Dynamic subtest vcs1: SUCCESS (0.102s)
[670.366668] Starting dynamic subtest: vecs0
[670.379247] Dynamic subtest vecs0: SUCCESS (0.052s)
[670.399299] Starting dynamic subtest: vecs1
[670.478140] Dynamic subtest vecs1: SUCCESS (0.096s)
[670.525564] Starting dynamic subtest: ccs0
[670.537252] Dynamic subtest ccs0: SUCCESS (0.055s)
[670.538266] Subtest engines-hostile: SUCCESS (0.554s)
[670.734828] [113/134] (358s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-gtt)
[670.932488] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-gtt
[670.934332] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[671.113495] [114/134] (358s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[671.305127] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[671.306884] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[671.464980] [115/134] (357s left) gem_caching (writes)
[671.551508] Starting subtest: writes
[675.779744] Subtest writes: SUCCESS (4.228s)
[675.996561] [116/134] (353s left) kms_pm_backlight (fade-with-suspend)
[676.184118] Subtest fade-with-suspend: SKIP (0.000s)
[676.246782] [117/134] (353s left) kms_plane_alpha_blend (coverage-vs-premult-vs-constant)
[676.435416] Starting subtest: coverage-vs-premult-vs-constant
[676.696297] Starting dynamic subtest: pipe-A-DP-3
[677.171129] Dynamic subtest pipe-A-DP-3: SUCCESS (0.474s)
[677.184546] Starting dynamic subtest: pipe-D-DP-3
[677.696064] Dynamic subtest pipe-D-DP-3: SUCCESS (0.523s)
[677.696964] Subtest coverage-vs-premult-vs-constant: SUCCESS (1.262s)
[678.016680] [118/134] (351s left) kms_lease (master-vs-lease)
[678.202577] Starting subtest: master-vs-lease
[678.205095] Subtest master-vs-lease: SUCCESS (0.002s)
[678.373868] [119/134] (351s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[678.564371] Starting subtest: psr-rgb565-draw-mmap-wc
[678.566153] Subtest psr-rgb565-draw-mmap-wc: SKIP (0.000s)
[678.759874] [120/134] (350s left) kms_addfb_basic (addfb25-4-tiled)
[678.947399] Starting subtest: addfb25-4-tiled
[678.948269] Subtest addfb25-4-tiled: SUCCESS (0.000s)
[679.159387] [121/134] (350s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling)
[679.347451] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling
[679.349222] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-downscaling: SKIP (0.000s)
[679.510608] [122/134] (350s left) kms_plane_multiple (2x-tiling-x)
[679.700291] Starting subtest: 2x-tiling-x
[679.702054] Subtest 2x-tiling-x: SKIP (0.000s)
[679.859118] [123/134] (350s left) kms_psr (psr2-primary-render)
[680.049534] Subtest psr2-primary-render: SKIP (0.000s)
[680.118165] [124/134] (349s left) syncobj_wait (multi-wait-all-for-submit-submitted-signaled)
[680.200510] Starting subtest: multi-wait-all-for-submit-submitted-signaled
[680.316899] Subtest multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.108s)
[680.474360] [125/134] (349s left) gem_exec_balancer (full)
[680.563313] Starting subtest: full
[682.695177] Subtest full: SUCCESS (2.132s)
[683.174999] [126/134] (347s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render)
[683.367083] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render
[683.368907] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[683.528745] [127/134] (346s left) gem_mmap_wc (write-read)
[683.616396] Subtest write-read: SKIP (0.000s)
[683.703110] [128/134] (346s left) gem_ctx_persistence (process)
[683.790500] Starting subtest: process
[684.009801] Subtest process: SUCCESS (0.219s)
[684.191940] [129/134] (346s left) gem_ctx_param (non-root-set)
[684.273876] Starting subtest: non-root-set
[684.290232] Subtest non-root-set: SUCCESS (0.016s)
[684.444743] [130/134] (346s left) sw_sync (timeline_closed)
[684.494904] Starting subtest: timeline_closed
[684.495837] Subtest timeline_closed: SUCCESS (0.000s)
[684.630934] [131/134] (345s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[684.824414] Starting subtest: primary-4-tiled-reflect-x-180
[694.956405] Subtest primary-4-tiled-reflect-x-180: SUCCESS (10.132s)
[695.337738] [132/134] (335s left) gem_exec_parallel (basic)
[695.427970] Starting subtest: basic
[695.428924] Starting dynamic subtest: rcs0
[696.962280] Dynamic subtest rcs0: SUCCESS (1.534s)
[696.978177] Starting dynamic subtest: bcs0
[698.439361] Dynamic subtest bcs0: SUCCESS (1.476s)
[698.457692] Starting dynamic subtest: vcs0
[699.944555] Dynamic subtest vcs0: SUCCESS (1.504s)
[699.960419] Starting dynamic subtest: vcs1
[701.437179] Dynamic subtest vcs1: SUCCESS (1.491s)
[701.454968] Starting dynamic subtest: vecs0
[702.924169] Dynamic subtest vecs0: SUCCESS (1.486s)
[702.939543] Starting dynamic subtest: vecs1
[704.391491] Dynamic subtest vecs1: SUCCESS (1.466s)
[704.408060] Starting dynamic subtest: ccs0
[705.866363] Dynamic subtest ccs0: SUCCESS (1.474s)
[705.867551] Subtest basic: SUCCESS (10.439s)
[706.180333] [133/134] (324s left) kms_content_protection (uevent-hdcp14)
[706.436210] Starting subtest: uevent-hdcp14
[706.469110] Starting dynamic subtest: pipe-A-DP-3
[740.717144] Dynamic subtest pipe-A-DP-3: FAIL (34.195s)
[740.764109] Subtest uevent-hdcp14: FAIL (34.327s)
[741.071426] [134/134] (289s left) syncobj_timeline (multi-wait-all-for-submit-available-signaled)
[741.163346] Starting subtest: multi-wait-all-for-submit-available-signaled
[741.164263] Subtest multi-wait-all-for-submit-available-signaled: SUCCESS (0.001s)
[741.354102] 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.