[60.611820] Initializing watchdogs
[60.611964]   /dev/watchdog0
[60.626592] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[60.630643] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.632840] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.636235] [001/131] (960s left) gem_exec_balancer (parallel-ordering)
[60.915536] Starting subtest: parallel-ordering
[61.839244] Subtest parallel-ordering: SUCCESS (0.929s)
[62.005037] [002/131] (959s left) gem_exec_balancer (parallel)
[62.265031] Starting subtest: parallel
[63.143817] Subtest parallel: SUCCESS (0.881s)
[63.955749] [003/131] (957s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[69.027705] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[69.031863] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.003s)
[69.912203] [004/131] (951s left) gem_exec_store (pages)
[69.977658] Starting subtest: pages
[69.979672] Starting dynamic subtest: rcs0
[70.093776] Dynamic subtest rcs0: SUCCESS (0.119s)
[70.103929] Starting dynamic subtest: bcs0
[70.162510] Dynamic subtest bcs0: SUCCESS (0.068s)
[70.169560] Starting dynamic subtest: vcs0
[70.234477] Dynamic subtest vcs0: SUCCESS (0.072s)
[70.241428] Starting dynamic subtest: vcs1
[70.318703] Dynamic subtest vcs1: SUCCESS (0.084s)
[70.325839] Starting dynamic subtest: vecs0
[70.393231] Dynamic subtest vecs0: SUCCESS (0.074s)
[70.400462] Starting dynamic subtest: ccs0
[70.466092] Dynamic subtest ccs0: SUCCESS (0.073s)
[70.468322] Subtest pages: SUCCESS (0.492s)
[70.668819] [005/131] (950s left) kms_psr (pr-primary-mmap-cpu)
[70.773435] Starting subtest: pr-primary-mmap-cpu
[70.777770] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[70.889876] [006/131] (950s left) sysfs_preempt_timeout (invalid)
[70.963807] Starting subtest: invalid
[70.966214] Starting dynamic subtest: vecs0
[70.968404] Dynamic subtest vecs0: SUCCESS (0.000s)
[70.977434] Starting dynamic subtest: ccs0
[70.979557] Dynamic subtest ccs0: SUCCESS (0.000s)
[70.986570] Starting dynamic subtest: vcs0
[70.988642] Dynamic subtest vcs0: SUCCESS (0.000s)
[70.995587] Starting dynamic subtest: bcs0
[70.997666] Dynamic subtest bcs0: SUCCESS (0.000s)
[71.004610] Starting dynamic subtest: rcs0
[71.006768] Dynamic subtest rcs0: SUCCESS (0.000s)
[71.014532] Starting dynamic subtest: vcs1
[71.017047] Dynamic subtest vcs1: SUCCESS (0.000s)
[71.019418] Subtest invalid: SUCCESS (0.003s)
[71.106376] [007/131] (950s left) prime_vgem (sync)
[71.228735] Starting subtest: sync
[71.231171] Starting dynamic subtest: rcs0
[71.289419] Dynamic subtest rcs0: SUCCESS (0.061s)
[71.297161] Starting dynamic subtest: bcs0
[71.349539] Dynamic subtest bcs0: SUCCESS (0.060s)
[71.356983] Starting dynamic subtest: vcs0
[71.395832] Dynamic subtest vcs0: SUCCESS (0.046s)
[71.402922] Starting dynamic subtest: vcs1
[71.440277] Dynamic subtest vcs1: SUCCESS (0.044s)
[71.447553] Starting dynamic subtest: vecs0
[71.486691] Dynamic subtest vecs0: SUCCESS (0.046s)
[71.493872] Starting dynamic subtest: ccs0
[71.530899] Dynamic subtest ccs0: SUCCESS (0.044s)
[71.533094] Subtest sync: SUCCESS (0.303s)
[71.692429] [FACT prime_vgem (sync)] new: kernel.kmod_is_loaded.vgem: true
[71.695698] [008/131] (949s left) kms_chamelium_color (ctm-limited-range)
[71.789063] Subtest ctm-limited-range: SKIP (0.000s)
[71.833146] [009/131] (949s left) i915_suspend (basic-s3-without-i915)
[71.863778] Starting subtest: basic-s3-without-i915
[75.275396] Subtest basic-s3-without-i915: SKIP (3.409s)
[76.866105] [010/131] (944s left) syncobj_timeline (multi-wait-available-signaled)
[76.936189] Starting subtest: multi-wait-available-signaled
[76.938159] Subtest multi-wait-available-signaled: SUCCESS (0.001s)
[77.060794] [011/131] (944s left) gem_exec_flush (basic-wb-rw-before-default)
[77.126180] Starting subtest: basic-wb-rw-before-default
[82.575386] Subtest basic-wb-rw-before-default: SUCCESS (5.451s)
[82.784339] [012/131] (938s left) kms_display_modes (extended-mode-basic)
[82.896305] Starting subtest: extended-mode-basic
[82.900654] Subtest extended-mode-basic: SKIP (0.000s)
[82.941106] [013/131] (938s left) gem_linear_blits (normal)
[83.307196] Starting subtest: normal
[86.945951] Subtest normal: SUCCESS (3.638s)
[87.121728] [014/131] (934s left) syncobj_wait (wait-any-complex)
[87.234368] Starting subtest: wait-any-complex
[87.296915] Subtest wait-any-complex: SUCCESS (0.062s)
[87.398824] [015/131] (934s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc)
[87.515311] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc
[87.519665] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[87.654764] [016/131] (934s left) kms_psr (psr-sprite-plane-onoff)
[87.760963] Starting subtest: psr-sprite-plane-onoff
[87.763480] Starting dynamic subtest: eDP-1
[89.538264] Dynamic subtest eDP-1: SUCCESS (1.776s)
[89.540660] Subtest psr-sprite-plane-onoff: SUCCESS (1.777s)
[90.378578] [017/131] (931s left) gem_render_copy_redux (interruptible)
[90.447959] Starting subtest: interruptible
[90.683636] Subtest interruptible: SUCCESS (0.238s)
[90.844073] [018/131] (930s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-pwrite)
[90.943367] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-pwrite
[90.947739] Subtest fbc-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[91.058663] [019/131] (930s left) kms_vblank (wait-forked-hang)
[91.156676] Starting subtest: wait-forked-hang
[91.159042] Starting dynamic subtest: pipe-A-eDP-1
[117.663229] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.505s)
[117.677692] Starting dynamic subtest: pipe-D-eDP-1
[132.688936] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.025s)
[132.691053] Subtest wait-forked-hang: SUCCESS (41.533s)
[133.537987] [020/131] (888s left) kms_pipe_crc_basic (nonblocking-crc)
[133.629119] Starting subtest: nonblocking-crc
[133.631400] Starting dynamic subtest: pipe-A-eDP-1
[134.429364] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.801s)
[134.439561] Starting dynamic subtest: pipe-B-eDP-1
[135.801682] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.371s)
[135.812185] Starting dynamic subtest: pipe-C-eDP-1
[137.196893] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.394s)
[137.208047] Starting dynamic subtest: pipe-D-eDP-1
[138.532343] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.335s)
[138.534599] Subtest nonblocking-crc: SUCCESS (4.904s)
[139.343728] [021/131] (882s left) perf_pmu (idle)
[139.385750] Starting subtest: idle
[139.388061] Starting dynamic subtest: rcs0
[139.955427] Dynamic subtest rcs0: SUCCESS (0.568s)
[139.965091] Starting dynamic subtest: bcs0
[140.479048] Dynamic subtest bcs0: SUCCESS (0.522s)
[140.488091] Starting dynamic subtest: vcs0
[140.999673] Dynamic subtest vcs0: SUCCESS (0.519s)
[141.009079] Starting dynamic subtest: vcs1
[141.525521] Dynamic subtest vcs1: SUCCESS (0.525s)
[141.535058] Starting dynamic subtest: vecs0
[142.049396] Dynamic subtest vecs0: SUCCESS (0.522s)
[142.063549] Starting dynamic subtest: ccs0
[142.570778] Dynamic subtest ccs0: SUCCESS (0.520s)
[142.573434] Subtest idle: SUCCESS (3.185s)
[142.720922] [022/131] (879s left) prime_mmap (test_reprime)
[142.782547] Starting subtest: test_reprime
[142.784927] Starting dynamic subtest: test_reprime-smem
[142.812956] Dynamic subtest test_reprime-smem: SUCCESS (0.033s)
[142.815312] Subtest test_reprime: SUCCESS (0.033s)
[142.948867] [023/131] (879s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[143.058994] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[143.063181] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[143.193940] [024/131] (878s left) kms_ccs (crc-primary-basic-y-tiled-gen12-mc-ccs)
[143.282126] Starting subtest: crc-primary-basic-y-tiled-gen12-mc-ccs
[143.284461] Starting dynamic subtest: pipe-A-eDP-1
[143.306984] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[143.317175] Starting dynamic subtest: pipe-B-eDP-1
[143.338730] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[143.346399] Starting dynamic subtest: pipe-C-eDP-1
[143.367615] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[143.375030] Starting dynamic subtest: pipe-D-eDP-1
[143.396219] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[143.400496] Subtest crc-primary-basic-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[145.525251] [025/131] (876s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[145.636828] Starting subtest: 4-tiled-32bpp-rotate-180
[146.963577] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.328s)
[147.133017] [026/131] (874s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling)
[147.240030] Starting subtest: flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling
[147.241781] Starting dynamic subtest: pipe-A-default-mode
[147.536269] Dynamic subtest pipe-A-default-mode: SKIP (0.294s)
[147.540716] Subtest flip-32bpp-ytile-to-32bpp-ytilegen12rcccs-upscaling: SKIP (0.295s)
[148.359900] [027/131] (873s left) gem_render_copy_redux (flink)
[148.427345] Starting subtest: flink
[149.198073] Subtest flink: SUCCESS (0.771s)
[149.366587] [028/131] (872s left) syncobj_wait (single-wait-submitted)
[149.415283] Starting subtest: single-wait-submitted
[149.522953] Subtest single-wait-submitted: SUCCESS (0.106s)
[149.624130] [029/131] (872s left) perf (sysctl-defaults)
[149.734539] Starting subtest: sysctl-defaults
[149.737585] Subtest sysctl-defaults: SUCCESS (0.000s)
[149.826997] [030/131] (872s left) gem_tiled_fence_blits (normal)
[149.891610] Subtest normal: SKIP (0.000s)
[149.966822] [031/131] (872s left) kms_plane_scaling (plane-upscale-20x20-with-pixel-format)
[150.064072] Starting subtest: plane-upscale-20x20-with-pixel-format
[150.066443] Starting dynamic subtest: pipe-A
[151.110621] Dynamic subtest pipe-A: SUCCESS (1.049s)
[151.119240] Starting dynamic subtest: pipe-B
[153.245157] Dynamic subtest pipe-B: SUCCESS (2.133s)
[153.254236] Starting dynamic subtest: pipe-C
[154.456057] Dynamic subtest pipe-C: SUCCESS (1.209s)
[154.465504] Starting dynamic subtest: pipe-D
[155.609659] Dynamic subtest pipe-D: SUCCESS (1.152s)
[155.612357] Subtest plane-upscale-20x20-with-pixel-format: SUCCESS (5.548s)
[156.821861] [032/131] (865s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[156.885063] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[156.994297] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.109s)
[157.093013] [033/131] (865s left) kms_big_fb (y-tiled-8bpp-rotate-180)
[157.189606] Starting subtest: y-tiled-8bpp-rotate-180
[157.193935] Subtest y-tiled-8bpp-rotate-180: SKIP (0.000s)
[157.324019] [034/131] (865s left) kms_plane_scaling (plane-downscale-factor-0-5-with-pixel-format)
[157.413789] Starting subtest: plane-downscale-factor-0-5-with-pixel-format
[157.416014] Starting dynamic subtest: pipe-A
[157.433534] Dynamic subtest pipe-A: SKIP (0.015s)
[157.446483] Starting dynamic subtest: pipe-B
[157.462878] Dynamic subtest pipe-B: SKIP (0.029s)
[157.472539] Starting dynamic subtest: pipe-C
[157.492229] Dynamic subtest pipe-C: SKIP (0.029s)
[157.500556] Starting dynamic subtest: pipe-D
[157.519836] Dynamic subtest pipe-D: SKIP (0.029s)
[157.523597] Subtest plane-downscale-factor-0-5-with-pixel-format: SKIP (0.103s)
[157.626799] [035/131] (864s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[157.718561] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc
[157.722976] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[157.850336] [036/131] (864s left) kms_psr2_sf (fbc-psr2-primary-plane-update-sf-dmg-area)
[162.819460] Starting subtest: fbc-psr2-primary-plane-update-sf-dmg-area
[162.821872] Starting dynamic subtest: pipe-A-eDP-1
[163.618561] Dynamic subtest pipe-A-eDP-1: SKIP (0.793s)
[163.632031] Starting dynamic subtest: pipe-B-eDP-1
[164.755082] Dynamic subtest pipe-B-eDP-1: SKIP (1.134s)
[164.766074] Subtest fbc-psr2-primary-plane-update-sf-dmg-area: SKIP (1.931s)
[165.978814] [037/131] (856s left) gem_exec_params (rel-constants-invalid-ring)
[166.046307] Starting subtest: rel-constants-invalid-ring
[166.048611] Subtest rel-constants-invalid-ring: SUCCESS (0.000s)
[166.178275] [038/131] (856s left) syncobj_timeline (reset-unsignaled)
[166.238117] Starting subtest: reset-unsignaled
[166.240314] Subtest reset-unsignaled: SUCCESS (0.000s)
[166.374186] [039/131] (856s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[166.464794] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[166.467059] Starting dynamic subtest: pipe-A-eDP-1
[166.476072] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[166.487078] Starting dynamic subtest: pipe-B-eDP-1
[166.496200] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[166.504939] Starting dynamic subtest: pipe-C-eDP-1
[166.513949] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[166.524180] Starting dynamic subtest: pipe-D-eDP-1
[166.532648] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[166.536781] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.004s)
[168.730270] [040/131] (853s left) gem_render_linear_blits (basic)
[168.794241] Starting subtest: basic
[168.816031] Subtest basic: SUCCESS (0.022s)
[168.917930] [041/131] (853s left) core_auth (many-magics)
[168.969077] Starting subtest: many-magics
[169.713161] Subtest many-magics: SUCCESS (0.747s)
[169.934075] [042/131] (852s left) api_intel_allocator (default-alignment)
[170.004456] Starting subtest: default-alignment
[170.006855] Subtest default-alignment: SUCCESS (0.002s)
[170.139351] [043/131] (852s left) kms_pm_rpm (pc8-residency)
[172.067885] Starting subtest: pc8-residency
[172.072827] Subtest pc8-residency: SKIP (0.000s)
[172.471743] [044/131] (850s left) syncobj_timeline (wait-any-interrupted)
[172.528063] Starting subtest: wait-any-interrupted
[172.628049] Subtest wait-any-interrupted: SUCCESS (0.101s)
[172.725904] [045/131] (850s left) i915_pm_rpm (system-suspend)
[174.252698] Starting subtest: system-suspend
[192.741457] Subtest system-suspend: SUCCESS (6.584s)
[193.146798] [046/131] (829s left) perf_pmu (busy-double-start)
[193.205159] Starting subtest: busy-double-start
[193.207036] Starting dynamic subtest: rcs0
[196.783202] Dynamic subtest rcs0: SUCCESS (3.624s)
[196.794772] Starting dynamic subtest: bcs0
[200.398302] Dynamic subtest bcs0: SUCCESS (3.642s)
[200.409684] Starting dynamic subtest: vcs0
[204.327351] Dynamic subtest vcs0: SUCCESS (3.947s)
[204.338653] Starting dynamic subtest: vcs1
[208.361261] Dynamic subtest vcs1: SUCCESS (4.045s)
[208.372749] Starting dynamic subtest: vecs0
[212.367537] Dynamic subtest vecs0: SUCCESS (4.012s)
[212.379858] Starting dynamic subtest: ccs0
[216.016153] Dynamic subtest ccs0: FAIL (3.596s)
[216.018277] Subtest busy-double-start: FAIL (22.876s)
[216.121194] [047/131] (806s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-blt)
[216.219576] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-blt
[216.223663] Subtest fbc-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s)
[216.334769] [048/131] (806s left) gem_exec_schedule (implicit-boths)
[216.412372] Starting subtest: implicit-boths
[216.414340] Starting dynamic subtest: rcs0
[216.519547] Dynamic subtest rcs0: SUCCESS (0.115s)
[216.531509] Starting dynamic subtest: vcs0
[216.595387] Dynamic subtest vcs0: SUCCESS (0.075s)
[216.608634] Starting dynamic subtest: vcs1
[216.669463] Dynamic subtest vcs1: SUCCESS (0.073s)
[216.684253] Starting dynamic subtest: vecs0
[216.741420] Dynamic subtest vecs0: SUCCESS (0.071s)
[216.754177] Starting dynamic subtest: ccs0
[216.824207] Dynamic subtest ccs0: SUCCESS (0.082s)
[216.826993] Subtest implicit-boths: SUCCESS (0.420s)
[217.889661] [049/131] (805s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[217.988968] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[218.000059] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.010s)
[218.102834] [050/131] (805s left) gem_ccs (block-multicopy-inplace)
[218.169873] Starting subtest: block-multicopy-inplace
[218.174131] Subtest block-multicopy-inplace: SKIP (0.000s)
[218.297762] [051/131] (804s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[218.399969] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[218.403956] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[218.522673] [052/131] (804s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[218.620602] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[225.511853] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (6.898s)
[225.651848] [053/131] (797s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[225.759843] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[232.284637] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (6.526s)
[232.438901] [054/131] (790s left) kms_chamelium_hpd (hdmi-hpd-after-suspend)
[232.827535] Subtest hdmi-hpd-after-suspend: SKIP (0.000s)
[233.637746] [055/131] (789s left) kms_cursor_crc (cursor-random-64x64)
[233.795677] Starting subtest: cursor-random-64x64
[233.797991] Starting dynamic subtest: pipe-A-eDP-1
[237.116326] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.318s)
[237.144825] Starting dynamic subtest: pipe-D-eDP-1
[241.148902] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.010s)
[241.151120] Subtest cursor-random-64x64: SUCCESS (7.353s)
[242.050496] [056/131] (781s left) gem_softpin (evict-prime)
[242.125122] Starting subtest: evict-prime
[242.127480] Starting dynamic subtest: rcs0
[243.294760] Dynamic subtest rcs0: SUCCESS (1.170s)
[243.307520] Starting dynamic subtest: bcs0
[244.445981] Dynamic subtest bcs0: SUCCESS (1.149s)
[244.459124] Starting dynamic subtest: vcs0
[245.819260] Dynamic subtest vcs0: SUCCESS (1.370s)
[245.831430] Starting dynamic subtest: vcs1
[247.781876] Dynamic subtest vcs1: SUCCESS (1.961s)
[247.795797] Starting dynamic subtest: vecs0
[249.775109] Dynamic subtest vecs0: SUCCESS (1.991s)
[249.788037] Starting dynamic subtest: ccs0
[250.930783] Dynamic subtest ccs0: SUCCESS (1.154s)
[250.947326] Starting dynamic subtest: all
[252.787043] Dynamic subtest all: SUCCESS (1.853s)
[252.789596] Subtest evict-prime: SUCCESS (10.665s)
[252.891077] [057/131] (770s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[252.998324] Starting subtest: yf-tiled-64bpp-rotate-0
[253.002689] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[253.141885] [058/131] (770s left) core_getversion (all-cards)
[253.181696] Starting subtest: all-cards
[253.190561] Subtest all-cards: SUCCESS (0.012s)
[253.232508] [059/131] (770s left) kms_psr (psr2-suspend)
[253.322462] Starting subtest: psr2-suspend
[253.324763] Starting dynamic subtest: eDP-1
[271.357827] Dynamic subtest eDP-1: SUCCESS (3.613s)
[271.360145] Subtest psr2-suspend: SUCCESS (3.613s)
[272.173772] [060/131] (751s left) gem_ctx_param (basic)
[272.243895] Starting subtest: basic
[272.246233] Subtest basic: SUCCESS (0.001s)
[272.391311] [061/131] (751s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[272.450538] Subtest writes-after-reads: SKIP (0.000s)
[272.541990] [062/131] (751s left) kms_content_protection (dp-mst-type-1)
[272.689715] Starting subtest: dp-mst-type-1
[272.694276] Subtest dp-mst-type-1: SKIP (0.000s)
[272.861348] [063/131] (750s left) gem_userptr_blits (huge-split)
[272.938601] Starting subtest: huge-split
[272.945695] Subtest huge-split: SUCCESS (0.007s)
[273.121207] [064/131] (750s left) kms_frontbuffer_tracking (psr-rgb101010-draw-mmap-gtt)
[273.212016] Starting subtest: psr-rgb101010-draw-mmap-gtt
[273.215481] Subtest psr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[273.340133] [065/131] (750s left) gem_ctx_shared (q-independent)
[273.413634] Starting subtest: Q-independent
[273.415626] Starting dynamic subtest: rcs0
[273.475876] Dynamic subtest rcs0: SUCCESS (0.063s)
[273.487813] Starting dynamic subtest: vcs0
[273.514455] Dynamic subtest vcs0: SUCCESS (0.038s)
[273.525599] Starting dynamic subtest: vcs1
[273.550074] Dynamic subtest vcs1: SUCCESS (0.035s)
[273.559466] Starting dynamic subtest: vecs0
[273.585854] Dynamic subtest vecs0: SUCCESS (0.036s)
[273.595223] Starting dynamic subtest: ccs0
[273.629728] Dynamic subtest ccs0: SUCCESS (0.044s)
[273.631689] Subtest Q-independent: SUCCESS (0.220s)
[275.401463] [066/131] (748s left) syncobj_wait (single-wait-signaled)
[275.465076] Starting subtest: single-wait-signaled
[275.467483] Subtest single-wait-signaled: SUCCESS (0.000s)
[275.594910] [067/131] (748s left) gem_exec_reloc (basic-wc-noreloc)
[275.656343] Subtest basic-wc-noreloc: SKIP (0.000s)
[275.720977] [068/131] (748s left) kms_psr (fbc-psr2-no-drrs)
[275.822580] Starting subtest: fbc-psr2-no-drrs
[275.824923] Starting dynamic subtest: eDP-1
[275.829249] Dynamic subtest eDP-1: SKIP (0.000s)
[275.833534] Subtest fbc-psr2-no-drrs: SKIP (0.001s)
[275.920688] [069/131] (747s left) kms_hdr (static-swap)
[276.025122] Starting subtest: static-swap
[276.031403] Subtest static-swap: SKIP (0.000s)
[276.122846] [070/131] (747s left) gem_render_copy (y-tiled-to-vebox-linear)
[276.179719] Starting subtest: y-tiled-to-vebox-linear
[276.184221] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[276.384593] [071/131] (747s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs)
[276.477861] Starting subtest: random-ccs-data-4-tiled-lnl-ccs
[276.482430] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s)
[277.646981] [072/131] (746s left) gem_mmap_offset (close-race)
[277.716303] Starting subtest: close-race
[297.828891] Subtest close-race: SUCCESS (20.175s)
[298.021450] [073/131] (726s left) gem_mmap_gtt (big-copy-odd)
[298.093598] Subtest big-copy-odd: SKIP (0.000s)
[298.163571] [074/131] (725s left) kms_chamelium_hpd (common-hpd-after-suspend)
[298.564044] Subtest common-hpd-after-suspend: SKIP (0.000s)
[299.369777] [075/131] (724s left) kms_big_fb (linear-16bpp-rotate-0)
[299.470723] Starting subtest: linear-16bpp-rotate-0
[300.835117] Subtest linear-16bpp-rotate-0: SUCCESS (1.367s)
[301.006847] [076/131] (723s left) gem_exec_schedule (semaphore-power)
[301.201440] Subtest semaphore-power: SKIP (0.000s)
[301.410122] [077/131] (722s left) gem_ctx_persistence (saturated-hostile-nopreempt)
[301.469971] Starting subtest: saturated-hostile-nopreempt
[301.472178] Starting dynamic subtest: rcs0
[301.543442] Dynamic subtest rcs0: SKIP (0.071s)
[301.556176] Starting dynamic subtest: bcs0
[301.560472] Dynamic subtest bcs0: SKIP (0.014s)
[301.569671] Starting dynamic subtest: vcs0
[301.589319] Dynamic subtest vcs0: SKIP (0.027s)
[301.598323] Starting dynamic subtest: vcs1
[301.605366] Dynamic subtest vcs1: SKIP (0.014s)
[301.614221] Starting dynamic subtest: vecs0
[301.629379] Dynamic subtest vecs0: SKIP (0.023s)
[301.638314] Starting dynamic subtest: ccs0
[301.647258] Dynamic subtest ccs0: SKIP (0.016s)
[301.651694] Subtest saturated-hostile-nopreempt: SKIP (0.176s)
[301.746072] [078/131] (722s left) gem_fence_thrash (bo-write-verify-none)
[301.777869] Starting subtest: bo-write-verify-none
[301.802823] Subtest bo-write-verify-none: SKIP (0.021s)
[301.875361] [079/131] (722s left) kms_flip (nonexisting-fb)
[301.963956] Starting subtest: nonexisting-fb
[301.966419] Starting dynamic subtest: A-eDP1
[303.170114] Dynamic subtest A-eDP1: SUCCESS (1.206s)
[303.181314] Starting dynamic subtest: B-eDP1
[304.311745] Dynamic subtest B-eDP1: SUCCESS (1.141s)
[304.324723] Starting dynamic subtest: C-eDP1
[305.453133] Dynamic subtest C-eDP1: SUCCESS (1.140s)
[305.464711] Starting dynamic subtest: D-eDP1
[306.589748] Dynamic subtest D-eDP1: SUCCESS (1.135s)
[306.592090] Subtest nonexisting-fb: SUCCESS (4.631s)
[307.796250] [080/131] (716s left) gem_ctx_persistence (engines-mixed-process)
[307.863902] Starting subtest: engines-mixed-process
[307.865838] Starting dynamic subtest: rcs0
[309.223639] Dynamic subtest rcs0: SUCCESS (1.358s)
[309.234026] Starting dynamic subtest: bcs0
[311.308834] Dynamic subtest bcs0: SUCCESS (2.087s)
[311.318738] Starting dynamic subtest: vcs0
[313.268705] Dynamic subtest vcs0: SUCCESS (1.958s)
[313.279489] Starting dynamic subtest: vcs1
[315.281700] Dynamic subtest vcs1: SUCCESS (2.011s)
[315.294577] Starting dynamic subtest: vecs0
[317.249367] Dynamic subtest vecs0: SUCCESS (1.965s)
[317.261536] Starting dynamic subtest: ccs0
[319.240442] Dynamic subtest ccs0: SUCCESS (1.989s)
[319.242946] Subtest engines-mixed-process: SUCCESS (11.377s)
[319.345208] [081/131] (705s left) kms_getfb (getfb2-into-addfb2)
[319.444754] Starting subtest: getfb2-into-addfb2
[319.447079] Subtest getfb2-into-addfb2: SUCCESS (0.000s)
[319.577766] [082/131] (704s left) kms_plane_cursor (primary)
[319.680984] Starting subtest: primary
[319.685493] Starting dynamic subtest: pipe-A-eDP-1-size-64
[322.267916] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.587s)
[322.299846] Starting dynamic subtest: pipe-A-eDP-1-size-128
[324.877545] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.592s)
[324.905623] Starting dynamic subtest: pipe-A-eDP-1-size-256
[327.445724] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.549s)
[327.763462] Starting dynamic subtest: pipe-B-eDP-1-size-64
[331.033582] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.284s)
[331.065116] Starting dynamic subtest: pipe-B-eDP-1-size-128
[333.590466] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.538s)
[333.614734] Starting dynamic subtest: pipe-B-eDP-1-size-256
[336.201150] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.593s)
[336.518164] Starting dynamic subtest: pipe-C-eDP-1-size-64
[339.414867] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.909s)
[339.443652] Starting dynamic subtest: pipe-C-eDP-1-size-128
[341.582133] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.147s)
[341.610308] Starting dynamic subtest: pipe-C-eDP-1-size-256
[343.748968] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.146s)
[344.067496] Starting dynamic subtest: pipe-D-eDP-1-size-64
[346.886083] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.830s)
[346.913319] Starting dynamic subtest: pipe-D-eDP-1-size-128
[349.097136] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.191s)
[349.127243] Starting dynamic subtest: pipe-D-eDP-1-size-256
[351.295925] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.180s)
[351.604080] Subtest primary: SUCCESS (31.926s)
[352.426583] [083/131] (672s left) kms_lease (empty-lease)
[352.535005] Starting subtest: empty-lease
[352.537222] Starting dynamic subtest: pipe-A-eDP-1
[352.539347] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[352.549789] Starting dynamic subtest: pipe-B-eDP-1
[352.551945] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[352.561388] Starting dynamic subtest: pipe-C-eDP-1
[352.563478] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[352.572834] Starting dynamic subtest: pipe-D-eDP-1
[352.574971] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[352.577273] Subtest empty-lease: SUCCESS (0.009s)
[352.671024] [084/131] (671s left) kms_draw_crc (draw-method-pwrite)
[352.766851] Starting subtest: draw-method-pwrite
[352.768903] Starting dynamic subtest: xrgb8888-untiled
[353.386911] Dynamic subtest xrgb8888-untiled: SUCCESS (0.622s)
[353.397498] Starting dynamic subtest: rgb565-untiled
[353.909361] Dynamic subtest rgb565-untiled: SUCCESS (0.522s)
[353.921595] Starting dynamic subtest: xrgb2101010-untiled
[354.531115] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.621s)
[354.541449] Starting dynamic subtest: xbgr16161616f-untiled
[355.375723] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.842s)
[355.385881] Starting dynamic subtest: xrgb8888-xtiled
[357.508818] Dynamic subtest xrgb8888-xtiled: SUCCESS (2.131s)
[357.520923] Starting dynamic subtest: rgb565-xtiled
[358.886019] Dynamic subtest rgb565-xtiled: SUCCESS (1.377s)
[358.896409] Starting dynamic subtest: xrgb2101010-xtiled
[361.008044] Dynamic subtest xrgb2101010-xtiled: SUCCESS (2.122s)
[361.018905] Starting dynamic subtest: xbgr16161616f-xtiled
[364.729775] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.722s)
[364.742867] Starting dynamic subtest: xrgb8888-4tiled
[366.029413] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.299s)
[366.041745] Starting dynamic subtest: rgb565-4tiled
[366.718933] Dynamic subtest rgb565-4tiled: SUCCESS (0.688s)
[366.729139] Starting dynamic subtest: xrgb2101010-4tiled
[368.029091] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.308s)
[368.039151] Starting dynamic subtest: xbgr16161616f-4tiled
[371.395121] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (3.366s)
[371.397493] Subtest draw-method-pwrite: SUCCESS (18.634s)
[371.536121] [085/131] (653s left) gem_pxp (reject-modify-context-protection-on)
[371.653585] Starting subtest: reject-modify-context-protection-on
[371.656214] Subtest reject-modify-context-protection-on: SUCCESS (0.002s)
[371.794717] [086/131] (652s left) kms_force_connector_basic (force-load-detect)
[371.874870] Starting subtest: force-load-detect
[371.879466] Subtest force-load-detect: SKIP (0.000s)
[372.023042] [087/131] (652s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt)
[372.127275] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt
[376.571591] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.449s)
[377.719027] [088/131] (646s left) kms_flip (busy-flip)
[377.817081] Starting subtest: busy-flip
[377.819389] Starting dynamic subtest: A-eDP1
[379.497710] Dynamic subtest A-eDP1: SUCCESS (1.680s)
[379.509456] Starting dynamic subtest: B-eDP1
[381.137941] Dynamic subtest B-eDP1: SUCCESS (1.639s)
[381.149826] Starting dynamic subtest: C-eDP1
[382.761631] Dynamic subtest C-eDP1: SUCCESS (1.623s)
[382.773955] Starting dynamic subtest: D-eDP1
[384.392902] Dynamic subtest D-eDP1: SUCCESS (1.629s)
[384.398197] Subtest busy-flip: SUCCESS (6.584s)
[385.674478] [089/131] (639s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[385.772708] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[385.775009] Starting dynamic subtest: pipe-A-default-mode
[388.483918] Dynamic subtest pipe-A-default-mode: SUCCESS (2.712s)
[388.486346] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.714s)
[389.372145] [090/131] (635s left) kms_flip (flip-vs-rmfb-interruptible)
[389.474208] Starting subtest: flip-vs-rmfb-interruptible
[389.476643] Starting dynamic subtest: A-eDP1
[398.138239] Dynamic subtest A-eDP1: SUCCESS (8.666s)
[398.150535] Starting dynamic subtest: B-eDP1
[406.767913] Dynamic subtest B-eDP1: SUCCESS (8.631s)
[406.781170] Starting dynamic subtest: C-eDP1
[415.414610] Dynamic subtest C-eDP1: SUCCESS (8.646s)
[415.427830] Starting dynamic subtest: D-eDP1
[424.045806] Dynamic subtest D-eDP1: SUCCESS (8.631s)
[424.050812] Subtest flip-vs-rmfb-interruptible: SUCCESS (34.587s)
[425.323375] [091/131] (599s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[425.430163] Starting subtest: primary-yf-tiled-reflect-x-180
[425.488767] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[425.616820] [092/131] (599s left) kms_ccs (bad-rotation-90-y-tiled-gen12-rc-ccs-cc)
[425.721735] Starting subtest: bad-rotation-90-y-tiled-gen12-rc-ccs-cc
[425.723992] Starting dynamic subtest: pipe-A-eDP-1
[425.747426] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[425.760117] Starting dynamic subtest: pipe-B-eDP-1
[425.781495] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[425.791002] Starting dynamic subtest: pipe-C-eDP-1
[425.812180] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[425.821342] Starting dynamic subtest: pipe-D-eDP-1
[425.842739] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[425.846968] Subtest bad-rotation-90-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[427.898958] [093/131] (597s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-pgflip-blt)
[428.019318] Starting subtest: fbc-1p-primscrn-shrfb-pgflip-blt
[432.480567] Subtest fbc-1p-primscrn-shrfb-pgflip-blt: SUCCESS (4.466s)
[433.699908] [094/131] (591s left) kms_psr (fbc-psr-sprite-blt)
[433.801756] Starting subtest: fbc-psr-sprite-blt
[433.803921] Starting dynamic subtest: eDP-1
[433.807833] Dynamic subtest eDP-1: SKIP (0.000s)
[433.811858] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[433.901069] [095/131] (591s left) kms_async_flips (crc)
[434.009831] Starting subtest: crc
[434.380225] Starting dynamic subtest: pipe-A-eDP-1
[437.283179] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.904s)
[437.676128] Starting dynamic subtest: pipe-B-eDP-1
[440.619325] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.953s)
[441.012568] Starting dynamic subtest: pipe-C-eDP-1
[443.921458] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.922s)
[444.285209] Starting dynamic subtest: pipe-D-eDP-1
[447.165896] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.890s)
[447.168179] Subtest crc: SUCCESS (13.161s)
[448.346662] [096/131] (576s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[448.456016] Starting subtest: fbcpsr-1p-rte
[455.420452] Subtest fbcpsr-1p-rte: SUCCESS (6.971s)
[455.600529] [097/131] (569s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[455.690706] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[455.694593] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[455.814324] [098/131] (569s left) kms_pm_dc (dc6-dpms)
[456.914047] Starting subtest: dc6-dpms
[459.012220] Subtest dc6-dpms: SUCCESS (2.098s)
[459.114111] [099/131] (566s left) api_intel_bb (intel-bb-blit-x)
[459.176366] Starting subtest: intel-bb-blit-x
[462.907288] Subtest intel-bb-blit-x: SUCCESS (3.732s)
[463.061507] [100/131] (562s left) gem_tiled_partial_pwrite_pread (reads)
[463.133976] Subtest reads: SKIP (0.000s)
[463.222548] [101/131] (562s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite)
[463.330953] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite
[470.161410] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.824s)
[470.308486] [102/131] (554s left) i915_getparams_basic (basic-eu-total)
[470.370532] Starting subtest: basic-eu-total
[470.374888] Subtest basic-eu-total: SUCCESS (0.000s)
[470.482984] [103/131] (554s left) gem_exec_fence (syncobj-timeline-export)
[471.053511] Starting subtest: syncobj-timeline-export
[471.059035] Subtest syncobj-timeline-export: SUCCESS (0.007s)
[472.368999] [104/131] (553s left) kms_psr (fbc-pr-no-drrs)
[472.462540] Starting subtest: fbc-pr-no-drrs
[472.466389] Subtest fbc-pr-no-drrs: SKIP (0.000s)
[472.557011] [105/131] (552s left) gem_exec_fence (syncobj-timeline-wait)
[473.121360] Starting subtest: syncobj-timeline-wait
[474.220483] Subtest syncobj-timeline-wait: SUCCESS (1.100s)
[476.297948] [106/131] (549s left) syncobj_wait (single-wait-for-submit-submitted)
[476.363753] Starting subtest: single-wait-for-submit-submitted
[476.465424] Subtest single-wait-for-submit-submitted: SUCCESS (0.103s)
[476.569669] [107/131] (548s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size)
[476.711628] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size
[476.715929] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.003s)
[476.889783] [108/131] (548s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[477.005580] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[477.010091] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[477.124077] [109/131] (548s left) gem_lmem_swapping (heavy-random)
[477.170367] Subtest heavy-random: SKIP (0.000s)
[477.223988] [110/131] (548s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff)
[477.343247] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff
[477.347356] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[477.462521] [111/131] (548s left) syncobj_wait (wait-any-snapshot)
[477.520633] Starting subtest: wait-any-snapshot
[477.600690] Subtest wait-any-snapshot: SUCCESS (0.081s)
[477.700632] [112/131] (548s left) kms_cursor_edge_walk (128x128-top-bottom)
[477.795064] Starting subtest: 128x128-top-bottom
[477.797012] Starting dynamic subtest: pipe-A-eDP-1
[481.229596] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.430s)
[481.243038] Starting dynamic subtest: pipe-D-eDP-1
[484.351842] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.119s)
[484.354193] Subtest 128x128-top-bottom: SUCCESS (6.552s)
[485.175095] [113/131] (540s left) kms_async_flips (test-cursor-atomic)
[485.289218] Starting subtest: test-cursor-atomic
[485.293745] Subtest test-cursor-atomic: SKIP (0.000s)
[486.515608] [114/131] (539s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[486.571319] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[486.648731] [115/131] (539s left) gem_lmem_swapping (parallel-random-verify-ccs)
[486.690733] Subtest parallel-random-verify-ccs: SKIP (0.000s)
[486.731005] [116/131] (539s left) syncobj_timeline (etime-single-wait-all-submitted)
[486.782778] Starting subtest: etime-single-wait-all-submitted
[486.891015] Subtest etime-single-wait-all-submitted: SUCCESS (0.108s)
[486.997734] [117/131] (538s left) kms_cursor_crc (cursor-alpha-transparent)
[487.091197] Starting subtest: cursor-alpha-transparent
[487.093425] Starting dynamic subtest: pipe-A-eDP-1
[487.702860] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.610s)
[487.713452] Starting dynamic subtest: pipe-D-eDP-1
[488.984156] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.281s)
[488.986579] Subtest cursor-alpha-transparent: SUCCESS (1.894s)
[489.882960] [118/131] (536s left) kms_sequence (get-idle)
[489.988565] Starting subtest: get-idle
[489.990414] Starting dynamic subtest: pipe-A-eDP-1
[491.795674] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.804s)
[491.806953] Starting dynamic subtest: pipe-B-eDP-1
[494.244271] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.447s)
[494.254644] Starting dynamic subtest: pipe-C-eDP-1
[496.671938] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.425s)
[496.686349] Starting dynamic subtest: pipe-D-eDP-1
[499.091998] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.417s)
[499.094299] Subtest get-idle: SUCCESS (9.100s)
[499.940817] [119/131] (526s left) gem_ccs (suspend-resume)
[499.991597] Starting subtest: suspend-resume
[499.995984] Subtest suspend-resume: SKIP (0.000s)
[500.125645] [120/131] (525s left) gem_ctx_param (set-priority-invalid-size)
[500.183627] Starting subtest: set-priority-invalid-size
[500.186005] Subtest set-priority-invalid-size: SUCCESS (0.000s)
[500.283216] [121/131] (525s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[500.379502] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[501.048153] Subtest x-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.670s)
[501.267357] [122/131] (524s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[501.370728] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[501.375091] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[501.508816] [123/131] (524s left) gem_userptr_blits (stress-mm-invalidate-close)
[501.577811] Starting subtest: stress-mm-invalidate-close
[503.727679] Subtest stress-mm-invalidate-close: SUCCESS (2.148s)
[503.929908] [124/131] (522s left) prime_mmap (test_errors)
[503.997420] Starting subtest: test_errors
[503.999417] Starting dynamic subtest: test_errors-smem
[504.085501] Dynamic subtest test_errors-smem: SUCCESS (0.090s)
[504.087868] Subtest test_errors: SUCCESS (0.091s)
[504.232089] [125/131] (522s left) gem_mmap_offset (perf)
[504.279869] Starting subtest: perf
[504.281996] Starting dynamic subtest: smem0
[504.589909] Dynamic subtest smem0: SUCCESS (0.309s)
[504.592568] Subtest perf: SUCCESS (0.310s)
[504.744955] [126/131] (521s left) kms_addfb_basic (invalid-get-prop)
[504.789284] Starting subtest: invalid-get-prop
[504.791115] Subtest invalid-get-prop: SUCCESS (0.000s)
[504.958957] [127/131] (521s left) gem_flink_basic (bad-flink)
[505.019333] Starting subtest: bad-flink
[505.020992] Subtest bad-flink: SUCCESS (0.000s)
[505.154088] [128/131] (521s left) gem_exec_reloc (basic-wc-read-noreloc)
[505.208786] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[505.301638] [129/131] (521s left) i915_drm_fdinfo (virtual-busy-all)
[505.338769] Starting subtest: virtual-busy-all
[505.342567] Subtest virtual-busy-all: SKIP (0.000s)
[505.432671] [130/131] (521s left) gen9_exec_parse (batch-zero-length)
[505.482570] Subtest batch-zero-length: SKIP (0.000s)
[505.564642] [131/131] (520s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[505.660041] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[512.593344] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.932s)
[512.784254] Closing watchdogs
results: parsing output: 0/ for test: gem_exec_balancer
results: parsing output: 1/ for test: gem_exec_balancer
results: parsing output: 2/ for test: kms_psr2_sf
results: parsing output: 3/ for test: gem_exec_store
results: parsing output: 4/ for test: kms_psr
results: parsing output: 5/ for test: sysfs_preempt_timeout
results: parsing output: 6/ for test: prime_vgem
results: parsing output: 7/ for test: kms_chamelium_color
results: parsing output: 8/ for test: i915_suspend
results: parsing output: 9/ for test: syncobj_timeline
results: parsing output: 10/ for test: gem_exec_flush
results: parsing output: 11/ for test: kms_display_modes
results: parsing output: 12/ for test: gem_linear_blits
results: parsing output: 13/ for test: syncobj_wait
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: gem_render_copy_redux
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_vblank
results: parsing output: 19/ for test: kms_pipe_crc_basic
results: parsing output: 20/ for test: perf_pmu
results: parsing output: 21/ for test: prime_mmap
results: parsing output: 22/ for test: kms_frontbuffer_tracking
results: parsing output: 23/ for test: kms_ccs
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: kms_flip_scaled_crc
results: parsing output: 26/ for test: gem_render_copy_redux
results: parsing output: 27/ for test: syncobj_wait
results: parsing output: 28/ for test: perf
results: parsing output: 29/ for test: gem_tiled_fence_blits
results: parsing output: 30/ for test: kms_plane_scaling
results: parsing output: 31/ for test: syncobj_timeline
results: parsing output: 32/ for test: kms_big_fb
results: parsing output: 33/ for test: kms_plane_scaling
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: kms_psr2_sf
results: parsing output: 36/ for test: gem_exec_params
results: parsing output: 37/ for test: syncobj_timeline
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: gem_render_linear_blits
results: parsing output: 40/ for test: core_auth
results: parsing output: 41/ for test: api_intel_allocator
results: parsing output: 42/ for test: kms_pm_rpm
results: parsing output: 43/ for test: syncobj_timeline
results: parsing output: 44/ for test: i915_pm_rpm
results: parsing output: 45/ for test: perf_pmu
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: gem_exec_schedule
results: parsing output: 48/ for test: kms_flip
results: parsing output: 49/ for test: gem_ccs
results: parsing output: 50/ for test: kms_big_fb
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: kms_chamelium_hpd
results: parsing output: 54/ for test: kms_cursor_crc
results: parsing output: 55/ for test: gem_softpin
results: parsing output: 56/ for test: kms_big_fb
results: parsing output: 57/ for test: core_getversion
results: parsing output: 58/ for test: kms_psr
results: parsing output: 59/ for test: gem_ctx_param
results: parsing output: 60/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 61/ for test: kms_content_protection
results: parsing output: 62/ for test: gem_userptr_blits
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_ctx_shared
results: parsing output: 65/ for test: syncobj_wait
results: parsing output: 66/ for test: gem_exec_reloc
results: parsing output: 67/ for test: kms_psr
results: parsing output: 68/ for test: kms_hdr
results: parsing output: 69/ for test: gem_render_copy
results: parsing output: 70/ for test: kms_ccs
results: parsing output: 71/ for test: gem_mmap_offset
results: parsing output: 72/ for test: gem_mmap_gtt
results: parsing output: 73/ for test: kms_chamelium_hpd
results: parsing output: 74/ for test: kms_big_fb
results: parsing output: 75/ for test: gem_exec_schedule
results: parsing output: 76/ for test: gem_ctx_persistence
results: parsing output: 77/ for test: gem_fence_thrash
results: parsing output: 78/ for test: kms_flip
results: parsing output: 79/ for test: gem_ctx_persistence
results: parsing output: 80/ for test: kms_getfb
results: parsing output: 81/ for test: kms_plane_cursor
results: parsing output: 82/ for test: kms_lease
results: parsing output: 83/ for test: kms_draw_crc
results: parsing output: 84/ for test: gem_pxp
results: parsing output: 85/ for test: kms_force_connector_basic
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_flip
results: parsing output: 88/ for test: kms_flip_scaled_crc
results: parsing output: 89/ for test: kms_flip
results: parsing output: 90/ for test: kms_rotation_crc
results: parsing output: 91/ for test: kms_ccs
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_psr
results: parsing output: 94/ for test: kms_async_flips
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_pm_dc
results: parsing output: 98/ for test: api_intel_bb
results: parsing output: 99/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: i915_getparams_basic
results: parsing output: 102/ for test: gem_exec_fence
results: parsing output: 103/ for test: kms_psr
results: parsing output: 104/ for test: gem_exec_fence
results: parsing output: 105/ for test: syncobj_wait
results: parsing output: 106/ for test: kms_cursor_legacy
results: parsing output: 107/ for test: kms_big_fb
results: parsing output: 108/ for test: gem_lmem_swapping
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_cursor_edge_walk
results: parsing output: 112/ for test: kms_async_flips
results: parsing output: 113/ for test: gem_exec_reloc
results: parsing output: 114/ for test: gem_lmem_swapping
results: parsing output: 115/ for test: syncobj_timeline
results: parsing output: 116/ for test: kms_cursor_crc
results: parsing output: 117/ for test: kms_sequence
results: parsing output: 118/ for test: gem_ccs
results: parsing output: 119/ for test: gem_ctx_param
results: parsing output: 120/ for test: kms_big_fb
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gem_userptr_blits
results: parsing output: 123/ for test: prime_mmap
results: parsing output: 124/ for test: gem_mmap_offset
results: parsing output: 125/ for test: kms_addfb_basic
results: parsing output: 126/ for test: gem_flink_basic
results: parsing output: 127/ for test: gem_exec_reloc
results: parsing output: 128/ for test: i915_drm_fdinfo
results: parsing output: 129/ for test: gen9_exec_parse
results: parsing output: 130/ for test: kms_frontbuffer_tracking
Done.