[95.354306] Initializing watchdogs
[95.354509]   /dev/watchdog0
[95.393960] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3184 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 605]
[95.408336] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[95.414353] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[95.419249] [001/156] (960s left) kms_frontbuffer_tracking (fbc-tiling-linear)
[95.636643] Starting subtest: fbc-tiling-linear
[98.416065] Subtest fbc-tiling-linear: SUCCESS (2.778s)
[98.721445] [002/156] (957s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[98.819594] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[98.913929] [003/156] (957s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[99.103170] Starting subtest: psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[99.105320] Subtest psrhdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[99.256482] [004/156] (956s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[99.437661] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[99.439424] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[99.574461] [005/156] (956s left) kms_force_connector_basic (prune-stale-modes)
[99.825840] Starting subtest: prune-stale-modes
[99.980553] Subtest prune-stale-modes: SUCCESS (0.156s)
[100.298780] [006/156] (956s left) perf_pmu (most-busy-check-all)
[100.413340] Starting subtest: most-busy-check-all
[100.414451] Starting dynamic subtest: rcs0
[100.944199] Dynamic subtest rcs0: SUCCESS (0.530s)
[100.966619] Starting dynamic subtest: bcs0
[101.467273] Dynamic subtest bcs0: SUCCESS (0.523s)
[101.489049] Starting dynamic subtest: vcs0
[101.991075] Dynamic subtest vcs0: SUCCESS (0.523s)
[102.014790] Starting dynamic subtest: vecs0
[102.521880] Dynamic subtest vecs0: SUCCESS (0.529s)
[102.523069] Subtest most-busy-check-all: SUCCESS (2.109s)
[102.660414] [007/156] (953s left) gem_ccs (large-ctrl-surf-copy)
[102.769785] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[102.870418] [008/156] (953s left) kms_plane_alpha_blend (coverage-7efc)
[103.035597] Starting subtest: coverage-7efc
[103.323850] Starting dynamic subtest: pipe-A-HDMI-A-1
[104.264217] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.933s)
[104.335507] Starting dynamic subtest: pipe-C-HDMI-A-1
[105.243625] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.926s)
[105.244521] Subtest coverage-7efc: SUCCESS (2.206s)
[105.602724] [009/156] (950s left) gem_mmap_gtt (fault-concurrent)
[105.701319] Starting subtest: fault-concurrent
[109.313156] Subtest fault-concurrent: SUCCESS (3.611s)
[109.604167] [010/156] (947s left) api_intel_allocator (gem-pool)
[109.699131] Starting subtest: gem-pool
[109.720593] Subtest gem-pool: SUCCESS (0.019s)
[109.870811] [011/156] (946s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-spr-indfb-fullscreen)
[110.053973] Starting subtest: fbchdr-2p-scndscrn-spr-indfb-fullscreen
[111.208970] Subtest fbchdr-2p-scndscrn-spr-indfb-fullscreen: SUCCESS (1.153s)
[111.416646] [012/156] (945s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-modifier)
[111.586720] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-modifier
[111.588674] Subtest pixel-format-4-tiled-dg2-rc-ccs-modifier: SKIP (0.000s)
[111.715581] [013/156] (945s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[111.886848] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[111.888063] Starting dynamic subtest: pipe-A-HDMI-A-1
[112.640879] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.751s)
[112.659896] Starting dynamic subtest: pipe-A-HDMI-A-2
[113.108782] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.466s)
[113.129584] Starting dynamic subtest: pipe-B-HDMI-A-1
[113.602853] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.490s)
[113.621875] Starting dynamic subtest: pipe-B-HDMI-A-2
[114.035204] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.431s)
[114.053441] Starting dynamic subtest: pipe-C-HDMI-A-1
[114.062635] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.002s)
[114.081232] Starting dynamic subtest: pipe-C-HDMI-A-2
[114.090138] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.002s)
[114.091280] Subtest crc-primary-rotation-180-yf-tiled-ccs: SUCCESS (2.157s)
[114.850422] [014/156] (942s left) kms_ccs (ccs-on-another-bo-y-tiled-gen12-mc-ccs)
[115.020929] Starting subtest: ccs-on-another-bo-y-tiled-gen12-mc-ccs
[115.021944] Starting dynamic subtest: pipe-A-HDMI-A-1
[115.031271] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[115.050455] Starting dynamic subtest: pipe-A-HDMI-A-2
[115.059486] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s)
[115.077936] Starting dynamic subtest: pipe-B-HDMI-A-1
[115.087298] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[115.107623] Starting dynamic subtest: pipe-B-HDMI-A-2
[115.117250] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[115.137533] Starting dynamic subtest: pipe-C-HDMI-A-1
[115.145929] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[115.163423] Starting dynamic subtest: pipe-C-HDMI-A-2
[115.172193] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[115.173902] Subtest ccs-on-another-bo-y-tiled-gen12-mc-ccs: SKIP (0.019s)
[115.972287] [015/156] (941s left) kms_frontbuffer_tracking (hdr-rgb565-draw-blt)
[116.158390] Starting subtest: hdr-rgb565-draw-blt
[116.938615] Subtest hdr-rgb565-draw-blt: SUCCESS (0.781s)
[117.222816] [016/156] (939s left) kms_pm_rpm (legacy-planes-dpms)
[119.011032] Starting subtest: legacy-planes-dpms
[119.012208] Starting dynamic subtest: plane-45
[120.397272] Dynamic subtest plane-45: SUCCESS (1.385s)
[120.416614] Starting dynamic subtest: plane-54
[121.855476] Dynamic subtest plane-54: SUCCESS (1.456s)
[121.880563] Starting dynamic subtest: plane-63
[123.300956] Dynamic subtest plane-63: SUCCESS (1.443s)
[123.302765] Subtest legacy-planes-dpms: SUCCESS (4.292s)
[123.674836] [017/156] (933s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[123.796037] Starting subtest: x-tiled-to-vebox-y-tiled
[123.798147] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[124.041986] [018/156] (933s left) sysfs_heartbeat_interval (invalid)
[124.154050] Starting subtest: invalid
[124.155505] Starting dynamic subtest: vecs0
[124.156769] Dynamic subtest vecs0: SUCCESS (0.001s)
[124.177396] Starting dynamic subtest: vcs0
[124.178555] Dynamic subtest vcs0: SUCCESS (0.001s)
[124.196617] Starting dynamic subtest: bcs0
[124.197809] Dynamic subtest bcs0: SUCCESS (0.001s)
[124.217375] Starting dynamic subtest: rcs0
[124.218636] Dynamic subtest rcs0: SUCCESS (0.001s)
[124.219860] Subtest invalid: SUCCESS (0.013s)
[124.302712] [019/156] (933s left) kms_frontbuffer_tracking (fbc-stridechange)
[124.489899] Starting subtest: fbc-stridechange
[126.939706] Subtest fbc-stridechange: SUCCESS (2.449s)
[127.234939] [020/156] (930s left) gem_ctx_param (basic-default)
[127.332317] Starting subtest: basic-default
[127.333311] Subtest basic-default: SUCCESS (0.000s)
[127.502940] [021/156] (930s left) kms_psr (fbc-pr-primary-mmap-gtt)
[127.676267] Subtest fbc-pr-primary-mmap-gtt: SKIP (0.000s)
[127.778365] [022/156] (929s left) i915_pm_freq_mult (media-freq)
[127.878116] Starting subtest: media-freq
[127.879166] Starting dynamic subtest: gt0
[127.881059] Dynamic subtest gt0: SKIP (0.000s)
[127.882932] Subtest media-freq: SKIP (0.002s)
[128.002879] [023/156] (929s left) prime_busy (before-wait)
[128.109739] Starting subtest: before-wait
[128.120582] Starting dynamic subtest: rcs0
[128.139873] Dynamic subtest rcs0: SUCCESS (0.019s)
[128.160129] Starting dynamic subtest: bcs0
[128.161205] Dynamic subtest bcs0: SUCCESS (0.019s)
[128.180727] Starting dynamic subtest: vcs0
[128.181700] Dynamic subtest vcs0: SUCCESS (0.015s)
[128.200511] Starting dynamic subtest: vecs0
[128.201483] Dynamic subtest vecs0: SUCCESS (0.023s)
[128.312458] Subtest before-wait: SUCCESS (0.204s)
[128.449287] [024/156] (929s left) gem_exec_gttfill (engines)
[128.567179] Starting subtest: engines
[128.568446] Starting dynamic subtest: rcs0
[162.399773] Dynamic subtest rcs0: SUCCESS (33.831s)
[162.426254] Starting dynamic subtest: bcs0
[199.585219] Dynamic subtest bcs0: SUCCESS (37.185s)
[199.608207] Starting dynamic subtest: vcs0
[234.394271] Dynamic subtest vcs0: SUCCESS (34.806s)
[234.417249] Starting dynamic subtest: vecs0
[269.006059] Dynamic subtest vecs0: SUCCESS (34.611s)
[269.008249] Subtest engines: SUCCESS (140.438s)
[269.259179] [025/156] (788s left) syncobj_timeline (etime-multi-wait-all-submitted)
[269.436614] Starting subtest: etime-multi-wait-all-submitted
[269.541194] Subtest etime-multi-wait-all-submitted: SUCCESS (0.104s)
[269.670068] [026/156] (788s left) gem_ctx_param (get-priority-new-ctx)
[269.772464] Starting subtest: get-priority-new-ctx
[269.774444] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[269.902325] [027/156] (788s left) kms_big_fb (linear-32bpp-rotate-90)
[270.109475] Starting subtest: linear-32bpp-rotate-90
[270.401204] Subtest linear-32bpp-rotate-90: SKIP (0.289s)
[270.652650] [028/156] (787s left) gem_lmem_swapping (heavy-verify-multi-ccs)
[270.763018] Subtest heavy-verify-multi-ccs: SKIP (0.000s)
[270.859660] [029/156] (787s left) gem_ctx_shared (disjoint-timelines)
[270.978201] Starting subtest: disjoint-timelines
[271.055334] Subtest disjoint-timelines: SUCCESS (0.074s)
[271.343662] [FACT gem_ctx_shared (disjoint-timelines)] new: kernel.kmod_is_loaded.vgem: true
[271.348515] [030/156] (786s left) kms_psr (fbc-psr2-cursor-plane-move)
[271.524394] Subtest fbc-psr2-cursor-plane-move: SKIP (0.000s)
[271.620534] [031/156] (786s left) gem_bad_reloc (negative-reloc-bltcopy)
[271.728068] Starting subtest: negative-reloc-bltcopy
[274.610493] Subtest negative-reloc-bltcopy: SUCCESS (2.882s)
[274.964453] [032/156] (783s left) kms_chamelium_hpd (hdmi-hpd)
[275.510700] Subtest hdmi-hpd: SKIP (0.000s)
[275.800344] [033/156] (782s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-fully-sf)
[275.971984] Subtest pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[276.080788] [034/156] (782s left) gem_eio (wait-10ms)
[276.205808] Starting subtest: wait-10ms
[276.294320] Subtest wait-10ms: SUCCESS (0.093s)
[276.551622] [035/156] (782s left) kms_content_protection (legacy-hdcp14)
[276.796777] Starting subtest: legacy-hdcp14
[276.811143] Subtest legacy-hdcp14: SKIP (0.012s)
[276.968812] [036/156] (781s left) syncobj_wait (reset-signaled)
[277.068894] Starting subtest: reset-signaled
[277.069954] Subtest reset-signaled: SUCCESS (0.001s)
[277.203848] [037/156] (781s left) gem_ctx_isolation (dirty-switch)
[277.325190] Starting subtest: dirty-switch
[277.326340] Starting dynamic subtest: rcs0
[278.936680] Dynamic subtest rcs0: SUCCESS (1.612s)
[278.961139] Starting dynamic subtest: bcs0
[280.809921] Dynamic subtest bcs0: SUCCESS (1.871s)
[280.835337] Starting dynamic subtest: vcs0
[282.303471] Dynamic subtest vcs0: SUCCESS (1.490s)
[282.325541] Starting dynamic subtest: vecs0
[283.805319] Dynamic subtest vecs0: SUCCESS (1.500s)
[283.808226] Subtest dirty-switch: SUCCESS (6.481s)
[284.055482] [038/156] (774s left) gem_flink_basic (bad-open)
[284.152472] Starting subtest: bad-open
[284.153519] Subtest bad-open: SUCCESS (0.000s)
[284.288482] [039/156] (774s left) gem_caching (writes)
[284.401750] Starting subtest: writes
[297.143294] Subtest writes: SUCCESS (12.745s)
[297.320945] [040/156] (761s left) kms_plane_scaling (plane-scaler-unity-scaling-with-modifiers)
[297.490388] Starting subtest: plane-scaler-unity-scaling-with-modifiers
[297.491402] Starting dynamic subtest: pipe-A
[298.560944] Dynamic subtest pipe-A: SUCCESS (1.070s)
[298.587689] Starting dynamic subtest: pipe-B
[299.657406] Dynamic subtest pipe-B: SUCCESS (1.094s)
[299.684224] Starting dynamic subtest: pipe-C
[300.753655] Dynamic subtest pipe-C: SUCCESS (1.094s)
[300.754792] Subtest plane-scaler-unity-scaling-with-modifiers: SUCCESS (3.264s)
[301.272743] [041/156] (758s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[301.375070] Starting subtest: single-wait-all-for-submit-available-submitted
[301.376194] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.001s)
[301.521700] [042/156] (757s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-indfb-draw-render)
[301.707745] Starting subtest: fbchdr-2p-primscrn-pri-indfb-draw-render
[302.877523] Subtest fbchdr-2p-primscrn-pri-indfb-draw-render: SUCCESS (1.170s)
[303.097771] [043/156] (756s left) kms_pm_rpm (system-suspend-idle)
[305.025572] Starting subtest: system-suspend-idle
[322.010415] Subtest system-suspend-idle: SUCCESS (2.690s)
[331.429074] [044/156] (737s left) kms_atomic_transition (modeset-transition)
[331.611624] Starting subtest: modeset-transition
[331.612728] Starting dynamic subtest: 1x-outputs
[335.513097] Dynamic subtest 1x-outputs: SUCCESS (3.909s)
[335.539087] Starting dynamic subtest: 2x-outputs
[343.199688] Dynamic subtest 2x-outputs: SUCCESS (7.695s)
[343.202381] Subtest modeset-transition: SUCCESS (11.609s)
[343.534663] [045/156] (725s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[343.714593] Starting subtest: pipe-fbc-rte
[343.719118] Subtest pipe-fbc-rte: SKIP (0.001s)
[343.885155] [046/156] (724s left) gem_spin_batch (resubmit-all)
[344.002671] Starting subtest: resubmit-all
[344.003839] Starting dynamic subtest: rcs0
[344.013993] Dynamic subtest rcs0: SUCCESS (0.010s)
[344.048773] Starting dynamic subtest: bcs0
[344.049932] Dynamic subtest bcs0: SUCCESS (0.007s)
[344.073865] Starting dynamic subtest: vcs0
[344.074836] Dynamic subtest vcs0: SUCCESS (0.006s)
[344.098565] Starting dynamic subtest: vecs0
[344.099563] Dynamic subtest vecs0: SUCCESS (0.006s)
[344.100630] Subtest resubmit-all: SUCCESS (0.042s)
[344.333738] [047/156] (724s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff)
[344.518307] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff
[344.520257] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[344.683922] [048/156] (724s left) syncobj_basic (illegal-fd-to-handle)
[344.782890] Starting subtest: illegal-fd-to-handle
[344.783943] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[344.917106] [049/156] (724s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[345.105555] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[347.949096] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SUCCESS (2.844s)
[348.176647] [050/156] (721s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[348.367557] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[350.212984] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.846s)
[350.523880] [051/156] (718s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[350.629053] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[350.731533] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[350.907034] [052/156] (718s left) api_intel_bb (misplaced-blitter)
[351.006720] Starting subtest: misplaced-blitter
[351.018091] Subtest misplaced-blitter: SUCCESS (0.012s)
[351.205960] [053/156] (718s left) kms_atomic_interruptible (universal-setplane-cursor)
[351.381812] Starting subtest: universal-setplane-cursor
[351.383202] Starting dynamic subtest: pipe-A-HDMI-A-1
[358.150693] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (6.765s)
[358.151999] Subtest universal-setplane-cursor: SUCCESS (6.771s)
[358.482383] [054/156] (711s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[358.673990] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[358.676946] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[358.814128] [055/156] (710s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[358.998994] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[359.000809] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[359.162057] [056/156] (710s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[359.333906] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[359.334991] Starting dynamic subtest: pipe-A-valid-mode
[360.309430] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.973s)
[360.310405] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SUCCESS (0.977s)
[360.654387] [057/156] (709s left) gem_pxp (create-protected-buffer)
[363.295171] Starting subtest: create-protected-buffer
[363.297503] Subtest create-protected-buffer: SKIP (0.000s)
[363.436888] [058/156] (706s left) gem_fence_thrash (bo-write-verify-threaded-x)
[363.534108] Starting subtest: bo-write-verify-threaded-x
[368.346118] Subtest bo-write-verify-threaded-x: SUCCESS (4.814s)
[368.484304] [059/156] (701s left) gen9_exec_parse (allowed-single)
[368.605678] Starting subtest: allowed-single
[368.613872] Subtest allowed-single: SUCCESS (0.009s)
[368.899379] [060/156] (701s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[369.085296] Starting subtest: psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[369.087306] Subtest psrhdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[369.250488] [061/156] (701s left) gem_exec_balancer (bonded-semaphore)
[369.372100] Starting subtest: bonded-semaphore
[373.435792] Subtest bonded-semaphore: SUCCESS (4.065s)
[373.715125] [062/156] (696s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping)
[373.885307] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping
[373.887344] Subtest pixel-format-y-tiled-gen12-rc-ccs-modifier-source-clamping: SKIP (0.000s)
[374.030535] [063/156] (696s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-wc)
[374.215562] Starting subtest: fbc-rgb565-draw-mmap-wc
[376.936847] Subtest fbc-rgb565-draw-mmap-wc: SUCCESS (2.720s)
[377.214294] [064/156] (693s left) kms_pwrite_crc (basic)
[377.387806] Starting subtest: basic
[378.319129] Subtest basic: SUCCESS (0.928s)
[378.646391] [065/156] (691s left) gem_flink_basic (bad-flink)
[378.743885] Starting subtest: bad-flink
[378.745025] Subtest bad-flink: SUCCESS (0.000s)
[378.879592] [066/156] (691s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-move)
[379.067595] Starting subtest: hdr-2p-primscrn-spr-indfb-move
[380.267023] Subtest hdr-2p-primscrn-spr-indfb-move: SUCCESS (1.198s)
[380.496002] [067/156] (690s left) gem_ctx_persistence (legacy-engines-persistence)
[380.606234] Starting subtest: legacy-engines-persistence
[380.607777] Starting dynamic subtest: render
[381.404513] Dynamic subtest render: SUCCESS (0.797s)
[381.431554] Starting dynamic subtest: bsd
[382.174715] Dynamic subtest bsd: SUCCESS (0.765s)
[382.203591] Starting dynamic subtest: blt
[382.939265] Dynamic subtest blt: SUCCESS (0.760s)
[382.967894] Starting dynamic subtest: vebox
[383.702177] Dynamic subtest vebox: SUCCESS (0.758s)
[383.707510] Subtest legacy-engines-persistence: SUCCESS (3.100s)
[383.862046] [068/156] (687s left) gen7_exec_parse (basic-allowed)
[383.973065] Subtest basic-allowed: SKIP (0.000s)
[384.086712] [069/156] (686s left) gem_exec_schedule (reorder-wide)
[384.337411] Starting subtest: reorder-wide
[384.338710] Starting dynamic subtest: rcs0
[384.509594] Dynamic subtest rcs0: SUCCESS (0.167s)
[384.538513] Starting dynamic subtest: bcs0
[384.819345] Dynamic subtest bcs0: SUCCESS (0.309s)
[384.861513] Starting dynamic subtest: vcs0
[384.978797] Dynamic subtest vcs0: SUCCESS (0.153s)
[385.010633] Starting dynamic subtest: vecs0
[385.125231] Dynamic subtest vecs0: SUCCESS (0.144s)
[385.126731] Subtest reorder-wide: SUCCESS (0.788s)
[385.429639] [070/156] (685s left) kms_frontbuffer_tracking (psrhdr-slowdraw)
[385.616365] Starting subtest: psrhdr-slowdraw
[385.618519] Subtest psrhdr-slowdraw: SKIP (0.000s)
[385.806112] [071/156] (685s left) perf (non-zero-reason)
[386.056037] Starting subtest: non-zero-reason
[386.057051] Starting dynamic subtest: 0-rcs0
[434.502968] Dynamic subtest 0-rcs0: SUCCESS (48.456s)
[434.506674] Subtest non-zero-reason: SUCCESS (48.459s)
[434.625230] [072/156] (636s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-pwrite)
[434.814449] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-pwrite
[436.067726] Subtest fbchdr-2p-primscrn-spr-indfb-draw-pwrite: SUCCESS (1.252s)
[436.300741] [073/156] (635s left) i915_drm_fdinfo (all-busy-check-all)
[436.409138] Starting subtest: all-busy-check-all
[436.926909] Subtest all-busy-check-all: SUCCESS (0.520s)
[437.076539] [074/156] (634s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[437.261193] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[437.263382] Subtest psrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[437.446049] [075/156] (634s left) kms_pipe_crc_basic (compare-crc-sanitycheck-nv12)
[437.615870] Starting subtest: compare-crc-sanitycheck-nv12
[437.620085] Starting dynamic subtest: pipe-A-HDMI-A-1
[438.779542] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (1.161s)
[438.813521] Starting dynamic subtest: pipe-B-HDMI-A-2
[439.444541] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.655s)
[439.474040] Starting dynamic subtest: pipe-C-HDMI-A-1
[440.115532] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.667s)
[440.120374] Subtest compare-crc-sanitycheck-nv12: SUCCESS (2.505s)
[440.438026] [076/156] (631s left) gem_exec_reloc (basic-write-gtt)
[440.553338] Starting subtest: basic-write-gtt
[440.569182] Subtest basic-write-gtt: SUCCESS (0.015s)
[440.785340] [077/156] (631s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[440.977863] Starting subtest: cursor-rapid-movement-max-size
[440.981130] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[441.136823] [078/156] (630s left) kms_color_pipeline (plane-lut1d)
[441.309566] Starting subtest: plane-lut1d
[442.836175] Subtest plane-lut1d: SKIP (1.524s)
[443.145049] [079/156] (628s left) kms_big_fb (yf-tiled-16bpp-rotate-180)
[443.341771] Starting subtest: yf-tiled-16bpp-rotate-180
[447.149357] Subtest yf-tiled-16bpp-rotate-180: SUCCESS (3.808s)
[447.596371] [080/156] (624s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[447.787769] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[447.789737] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[447.928005] [081/156] (624s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[448.115770] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt
[448.117994] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[448.280039] [082/156] (624s left) gem_exec_reloc (basic-wc-read)
[448.390046] Starting subtest: basic-wc-read
[448.405355] Subtest basic-wc-read: SUCCESS (0.016s)
[448.628846] [083/156] (623s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[448.800577] Starting subtest: short-busy-flip-before-cursor-toggle
[448.802668] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[448.946074] [084/156] (623s left) kms_flip (2x-plain-flip-fb-recreate-interruptible)
[449.130079] Starting subtest: 2x-plain-flip-fb-recreate-interruptible
[449.134028] Starting dynamic subtest: AB-HDMI-A1-HDMI-A2
[460.160120] Dynamic subtest AB-HDMI-A1-HDMI-A2: SUCCESS (11.023s)
[460.188597] Starting dynamic subtest: AC-HDMI-A1-HDMI-A2
[471.145608] Dynamic subtest AC-HDMI-A1-HDMI-A2: SUCCESS (10.980s)
[471.176004] Starting dynamic subtest: BC-HDMI-A1-HDMI-A2
[482.125305] Dynamic subtest BC-HDMI-A1-HDMI-A2: SUCCESS (10.974s)
[482.129073] Subtest 2x-plain-flip-fb-recreate-interruptible: SUCCESS (32.996s)
[482.722394] [085/156] (589s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[482.907398] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[482.909571] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[483.065883] [086/156] (589s left) kms_draw_crc (draw-method-render)
[483.237772] Starting subtest: draw-method-render
[483.242055] Starting dynamic subtest: xrgb8888-untiled
[483.788876] Dynamic subtest xrgb8888-untiled: SUCCESS (0.548s)
[483.818933] Starting dynamic subtest: rgb565-untiled
[484.122621] Dynamic subtest rgb565-untiled: SUCCESS (0.329s)
[484.150045] Starting dynamic subtest: xrgb2101010-untiled
[484.472783] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.347s)
[484.501705] Starting dynamic subtest: xbgr16161616f-untiled
[484.872031] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.397s)
[484.903687] Starting dynamic subtest: xrgb8888-xtiled
[485.104975] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.230s)
[485.134222] Starting dynamic subtest: rgb565-xtiled
[485.305671] Dynamic subtest rgb565-xtiled: SUCCESS (0.196s)
[485.336698] Starting dynamic subtest: xrgb2101010-xtiled
[485.505318] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.196s)
[485.533542] Starting dynamic subtest: xbgr16161616f-xtiled
[485.739645] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.229s)
[485.765814] Starting dynamic subtest: xrgb8888-ytiled
[485.955192] Dynamic subtest xrgb8888-ytiled: SUCCESS (0.213s)
[485.983965] Starting dynamic subtest: rgb565-ytiled
[486.155850] Dynamic subtest rgb565-ytiled: SUCCESS (0.196s)
[486.187717] Starting dynamic subtest: xrgb2101010-ytiled
[486.354853] Dynamic subtest xrgb2101010-ytiled: SUCCESS (0.196s)
[486.384828] Starting dynamic subtest: xbgr16161616f-ytiled
[486.588009] Dynamic subtest xbgr16161616f-ytiled: SUCCESS (0.229s)
[486.590003] Subtest draw-method-render: SUCCESS (3.351s)
[486.865700] [087/156] (585s left) kms_cursor_crc (cursor-sliding-256x85)
[487.052427] Starting subtest: cursor-sliding-256x85
[487.089936] Starting dynamic subtest: pipe-A-HDMI-A-1
[491.139620] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (4.048s)
[491.226002] Starting dynamic subtest: pipe-C-HDMI-A-1
[494.919747] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (3.715s)
[494.923933] Subtest cursor-sliding-256x85: SUCCESS (7.866s)
[495.257831] [088/156] (577s left) kms_cursor_crc (cursor-onscreen-256x256)
[495.446330] Starting subtest: cursor-onscreen-256x256
[495.487038] Starting dynamic subtest: pipe-A-HDMI-A-1
[498.171157] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.683s)
[498.259969] Starting dynamic subtest: pipe-C-HDMI-A-1
[500.636648] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.405s)
[500.637912] Subtest cursor-onscreen-256x256: SUCCESS (5.191s)
[500.993109] [089/156] (572s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render)
[501.182718] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render
[501.184928] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[501.339988] [090/156] (571s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[501.528091] Starting subtest: fbcpsr-slowdraw
[501.530172] Subtest fbcpsr-slowdraw: SKIP (0.000s)
[501.671927] [091/156] (571s left) kms_lease (cursor-implicit-plane)
[501.843392] Starting subtest: cursor-implicit-plane
[501.844615] Starting dynamic subtest: pipe-A-HDMI-A-1
[502.506537] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.661s)
[502.539560] Starting dynamic subtest: pipe-A-HDMI-A-2
[502.930594] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.417s)
[502.958414] Starting dynamic subtest: pipe-B-HDMI-A-1
[503.332233] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.395s)
[503.361191] Starting dynamic subtest: pipe-B-HDMI-A-2
[503.731619] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.397s)
[503.761654] Starting dynamic subtest: pipe-C-HDMI-A-1
[504.140118] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (0.401s)
[504.170461] Starting dynamic subtest: pipe-C-HDMI-A-2
[504.545622] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.401s)
[504.551808] Subtest cursor-implicit-plane: SUCCESS (2.706s)
[504.884419] [092/156] (568s left) syncobj_wait (invalid-reset-zero-handles)
[504.984148] Starting subtest: invalid-reset-zero-handles
[504.985328] Subtest invalid-reset-zero-handles: SUCCESS (0.000s)
[505.132642] [093/156] (568s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[505.317840] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[507.649236] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SUCCESS (2.330s)
[507.879058] [094/156] (565s left) kms_big_fb (yf-tiled-64bpp-rotate-90)
[508.070431] Starting subtest: yf-tiled-64bpp-rotate-90
[508.072418] Subtest yf-tiled-64bpp-rotate-90: SKIP (0.000s)
[508.225641] [095/156] (565s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc)
[508.409563] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc
[509.163963] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SUCCESS (0.753s)
[509.468926] [096/156] (564s left) gem_exec_fence (long-history)
[509.985535] Starting subtest: long-history
[515.710105] Subtest long-history: SUCCESS (5.723s)
[516.335164] [097/156] (557s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-msflip-blt)
[516.520579] Starting subtest: psr-1p-primscrn-indfb-msflip-blt
[516.522698] Subtest psr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[516.688926] [098/156] (557s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[516.862883] Starting subtest: bad-rotation-90-yf-tiled-ccs
[516.863945] Starting dynamic subtest: pipe-A-HDMI-A-1
[517.029158] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (0.151s)
[517.056239] Starting dynamic subtest: pipe-A-HDMI-A-2
[517.078751] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.053s)
[517.106279] Starting dynamic subtest: pipe-B-HDMI-A-1
[517.139250] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (0.052s)
[517.167124] Starting dynamic subtest: pipe-B-HDMI-A-2
[517.188255] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.046s)
[517.217172] Starting dynamic subtest: pipe-C-HDMI-A-1
[517.248188] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[517.274936] Starting dynamic subtest: pipe-C-HDMI-A-2
[517.295944] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[517.296904] Subtest bad-rotation-90-yf-tiled-ccs: SUCCESS (0.334s)
[518.175978] [099/156] (555s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic)
[518.347301] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic
[524.146715] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (5.801s)
[524.478331] [100/156] (549s left) kms_chamelium_audio (dp-audio-edid)
[525.039135] Subtest dp-audio-edid: SKIP (0.000s)
[525.332890] [101/156] (548s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[525.507586] Starting subtest: addfb25-yf-tiled-legacy
[525.508585] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[525.669005] [102/156] (548s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-pgflip-blt)
[525.857724] Starting subtest: hdr-1p-primscrn-indfb-pgflip-blt
[526.700363] Subtest hdr-1p-primscrn-indfb-pgflip-blt: SUCCESS (0.843s)
[527.001841] [103/156] (547s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-onoff)
[527.187673] Starting subtest: psr-2p-primscrn-cur-indfb-onoff
[527.189777] Subtest psr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[527.355809] [104/156] (547s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[527.541511] Starting subtest: fbc-2p-pri-indfb-multidraw
[544.841327] Subtest fbc-2p-pri-indfb-multidraw: SUCCESS (17.301s)
[545.069315] [105/156] (529s left) kms_sequence (queue-busy)
[545.244814] Starting subtest: queue-busy
[545.247626] Starting dynamic subtest: pipe-A-HDMI-A-1
[548.125927] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.880s)
[548.152357] Starting dynamic subtest: pipe-A-HDMI-A-2
[550.745937] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.617s)
[550.776318] Starting dynamic subtest: pipe-B-HDMI-A-1
[553.346845] Dynamic subtest pipe-B-HDMI-A-1: SUCCESS (2.597s)
[553.377951] Starting dynamic subtest: pipe-B-HDMI-A-2
[555.946800] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.593s)
[555.974810] Starting dynamic subtest: pipe-C-HDMI-A-1
[558.547737] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.597s)
[558.577347] Starting dynamic subtest: pipe-C-HDMI-A-2
[561.157774] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.601s)
[561.158899] Subtest queue-busy: SUCCESS (15.915s)
[561.485878] [106/156] (513s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[561.660646] Starting subtest: plane-upscale-20x20-with-rotation
[561.661732] Starting dynamic subtest: pipe-A
[565.323193] Dynamic subtest pipe-A: SKIP (3.659s)
[565.349087] Starting dynamic subtest: pipe-B
[569.052940] Dynamic subtest pipe-B: SKIP (3.725s)
[569.079473] Starting dynamic subtest: pipe-C
[572.750691] Dynamic subtest pipe-C: SKIP (3.695s)
[572.752625] Subtest plane-upscale-20x20-with-rotation: SKIP (11.087s)
[573.268880] [107/156] (501s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[573.507170] Starting subtest: psrhdr-1p-primscrn-pri-indfb-draw-pwrite
[573.509932] Subtest psrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[573.652115] [108/156] (501s left) gem_exec_reloc (basic-write-wc)
[573.762896] Starting subtest: basic-write-wc
[573.780327] Subtest basic-write-wc: SUCCESS (0.016s)
[574.002950] [109/156] (500s left) syncobj_timeline (reset-signaled)
[574.109613] Starting subtest: reset-signaled
[574.110802] Subtest reset-signaled: SUCCESS (0.001s)
[574.254993] [110/156] (500s left) kms_psr (pr-cursor-plane-move)
[574.426446] Subtest pr-cursor-plane-move: SKIP (0.000s)
[574.530718] [111/156] (500s left) syncobj_wait (invalid-wait-illegal-handle)
[574.631006] Starting subtest: invalid-wait-illegal-handle
[574.633200] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[574.771748] [112/156] (500s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[574.957761] Starting subtest: extended-pageflip-modeset-hang-oldfb
[574.960652] Starting dynamic subtest: pipe-A
[579.201989] Dynamic subtest pipe-A: SUCCESS (4.239s)
[579.239710] Starting dynamic subtest: pipe-C
[583.154585] Dynamic subtest pipe-C: SUCCESS (3.936s)
[583.155604] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (8.198s)
[583.518285] [113/156] (491s left) kms_psr (psr2-primary-blt)
[583.693715] Subtest psr2-primary-blt: SKIP (0.000s)
[583.809308] [114/156] (491s left) gem_softpin (evict-snoop-interruptible)
[583.924371] Starting subtest: evict-snoop-interruptible
[583.927012] Subtest evict-snoop-interruptible: SKIP (0.000s)
[584.085126] [115/156] (491s left) kms_force_connector_basic (force-load-detect)
[584.340488] Starting subtest: force-load-detect
[584.342293] Subtest force-load-detect: SKIP (0.000s)
[584.667883] [116/156] (490s left) kms_chamelium_edid (hdmi-mode-timings)
[585.205098] Subtest hdmi-mode-timings: SKIP (0.000s)
[585.497684] [117/156] (490s left) syncobj_timeline (invalid-signal-zero-handles)
[585.598732] Starting subtest: invalid-signal-zero-handles
[585.599978] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[585.752890] [118/156] (490s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt)
[585.938594] Starting subtest: fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt
[585.940611] Subtest fbcpsrhdr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[586.102692] [119/156] (489s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[586.289155] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt
[586.291315] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[586.451809] [120/156] (489s left) gem_mmap_gtt (fault-concurrent-x)
[586.550623] Starting subtest: fault-concurrent-X
[590.108962] Subtest fault-concurrent-X: SUCCESS (3.559s)
[590.318261] [121/156] (485s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[590.435138] Starting subtest: y-tiled-to-vebox-y-tiled
[590.436998] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[590.686205] [122/156] (485s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-shrfb-plflip-blt)
[590.873265] Starting subtest: psrhdr-2p-scndscrn-shrfb-plflip-blt
[590.875293] Subtest psrhdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[591.035889] [123/156] (485s left) vgem_basic (debugfs)
[591.146619] Starting subtest: debugfs
[591.148832] Subtest debugfs: SUCCESS (0.001s)
[591.268992] [124/156] (485s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[591.442882] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[591.446635] Starting dynamic subtest: pipe-A-valid-mode
[592.357826] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.909s)
[592.361366] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SUCCESS (0.913s)
[592.690590] [125/156] (483s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[592.878027] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[596.345206] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (3.468s)
[596.543225] [126/156] (480s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[596.730319] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu
[596.732361] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[596.891561] [127/156] (479s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[597.082240] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[597.085641] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[597.256758] [128/156] (479s left) kms_vblank (wait-forked-hang)
[597.429723] Starting subtest: wait-forked-hang
[597.432600] Starting dynamic subtest: pipe-A-HDMI-A-1
[605.196553] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (7.761s)
[605.225365] Starting dynamic subtest: pipe-A-HDMI-A-2
[612.237593] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (7.035s)
[612.272110] Starting dynamic subtest: pipe-C-HDMI-A-1
[619.194658] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (6.950s)
[619.225033] Starting dynamic subtest: pipe-C-HDMI-A-2
[626.176625] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (6.974s)
[626.178203] Subtest wait-forked-hang: SUCCESS (28.747s)
[626.507262] [129/156] (450s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[626.678940] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[626.680070] Starting dynamic subtest: pipe-A-valid-mode
[627.504650] Dynamic subtest pipe-A-valid-mode: SKIP (0.822s)
[627.509182] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (0.827s)
[628.047200] [130/156] (448s left) gem_ctx_exec (basic-invalid-context)
[628.153621] Starting subtest: basic-invalid-context
[628.159937] Subtest basic-invalid-context: SUCCESS (0.006s)
[628.330483] [131/156] (448s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-indfb-msflip-blt)
[628.519599] Starting subtest: fbcpsrhdr-1p-primscrn-indfb-msflip-blt
[628.522774] Subtest fbcpsrhdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[628.682207] [132/156] (448s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[628.855053] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (0.000s)
[628.975283] [133/156] (448s left) gem_ppgtt (blt-vs-render-ctxn)
[629.085235] Starting subtest: blt-vs-render-ctxN
[662.105996] Subtest blt-vs-render-ctxN: SUCCESS (33.024s)
[662.285141] [134/156] (415s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[662.497944] Starting subtest: basic-gtt-cpu-noreloc
[662.508211] Subtest basic-gtt-cpu-noreloc: SUCCESS (0.010s)
[662.728869] [135/156] (414s left) gem_softpin (invalid)
[662.837882] Starting subtest: invalid
[662.848336] Subtest invalid: SUCCESS (0.011s)
[663.029794] [136/156] (414s left) gem_exec_capture (capture)
[663.139732] Starting subtest: capture
[663.140668] Starting dynamic subtest: rcs0-smem
[664.431669] Dynamic subtest rcs0-smem: SUCCESS (1.292s)
[664.460053] Starting dynamic subtest: bcs0-smem
[665.712568] Dynamic subtest bcs0-smem: SUCCESS (1.279s)
[665.740736] Starting dynamic subtest: vcs0-smem
[666.995859] Dynamic subtest vcs0-smem: SUCCESS (1.279s)
[667.023945] Starting dynamic subtest: vecs0-smem
[668.282736] Dynamic subtest vecs0-smem: SUCCESS (1.282s)
[668.283766] Subtest capture: SUCCESS (5.144s)
[668.461895] [137/156] (409s left) gem_tiled_blits (basic)
[668.663728] Starting subtest: basic
[668.699615] Subtest basic: SUCCESS (0.040s)
[668.911595] [138/156] (408s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[669.220103] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[669.222008] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[669.380070] [139/156] (408s left) gem_mmap_offset (partial-remap)
[669.477834] Starting subtest: partial-remap
[669.478787] Starting dynamic subtest: smem0
[669.492745] Dynamic subtest smem0: SUCCESS (0.010s)
[669.493890] Subtest partial-remap: SUCCESS (0.013s)
[669.629727] [140/156] (408s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-blt)
[669.819177] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-blt
[670.653408] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (0.836s)
[670.940423] [141/156] (407s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-blt)
[671.128573] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-blt
[672.203787] Subtest hdr-2p-scndscrn-cur-indfb-draw-blt: SUCCESS (1.076s)
[672.410227] [142/156] (405s left) i915_power (sanity)
[672.509186] Starting subtest: sanity
[676.535052] Subtest sanity: SUCCESS (4.025s)
[676.711261] [143/156] (401s left) gem_exec_schedule (preempt-queue)
[676.856872] Starting subtest: preempt-queue
[676.857853] Starting dynamic subtest: rcs0
[677.192182] Dynamic subtest rcs0: SUCCESS (0.334s)
[677.225570] Starting dynamic subtest: bcs0
[677.531993] Dynamic subtest bcs0: SUCCESS (0.339s)
[677.568675] Starting dynamic subtest: vcs0
[677.861722] Dynamic subtest vcs0: SUCCESS (0.323s)
[677.892562] Starting dynamic subtest: vecs0
[678.181960] Dynamic subtest vecs0: SUCCESS (0.320s)
[678.186831] Subtest preempt-queue: SUCCESS (1.331s)
[678.578071] [144/156] (399s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[678.750815] Starting subtest: cursorB-vs-flipA-legacy
[690.414585] Subtest cursorB-vs-flipA-legacy: SUCCESS (11.661s)
[690.749298] [145/156] (387s left) i915_pm_rps (reset)
[690.865052] Starting subtest: reset
[695.436068] Subtest reset: SUCCESS (4.573s)
[695.582404] [146/156] (383s left) kms_psr (fbc-psr-cursor-plane-onoff)
[695.757159] Subtest fbc-psr-cursor-plane-onoff: SKIP (0.000s)
[695.872515] [147/156] (382s left) kms_setmode (clone-exclusive-crtc)
[695.969343] Starting subtest: clone-exclusive-crtc
[696.430294] Starting dynamic subtest: pipe-A-HDMI-A-1-pipe-B-HDMI-A-2
[696.738086] Dynamic subtest pipe-A-HDMI-A-1-pipe-B-HDMI-A-2: SUCCESS (0.308s)
[697.230725] Starting dynamic subtest: pipe-B-HDMI-A-1-pipe-A-HDMI-A-2
[697.518944] Dynamic subtest pipe-B-HDMI-A-1-pipe-A-HDMI-A-2: SUCCESS (0.309s)
[697.948677] Subtest clone-exclusive-crtc: SUCCESS (1.979s)
[698.295156] [148/156] (380s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[698.484820] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[698.486779] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[698.644889] [149/156] (380s left) kms_flip (flip-vs-rmfb)
[698.815427] Starting subtest: flip-vs-rmfb
[698.819189] Starting dynamic subtest: A-HDMI-A1
[704.433399] Dynamic subtest A-HDMI-A1: SUCCESS (5.615s)
[704.463892] Starting dynamic subtest: B-HDMI-A1
[709.867685] Dynamic subtest B-HDMI-A1: SUCCESS (5.430s)
[709.900160] Starting dynamic subtest: C-HDMI-A1
[715.300149] Dynamic subtest C-HDMI-A1: SUCCESS (5.428s)
[715.329231] Starting dynamic subtest: A-HDMI-A2
[720.681503] Dynamic subtest A-HDMI-A2: SUCCESS (5.377s)
[720.711565] Starting dynamic subtest: B-HDMI-A2
[726.069082] Dynamic subtest B-HDMI-A2: SUCCESS (5.381s)
[726.097512] Starting dynamic subtest: C-HDMI-A2
[731.500637] Dynamic subtest C-HDMI-A2: SUCCESS (5.429s)
[731.505311] Subtest flip-vs-rmfb: SUCCESS (32.694s)
[732.006077] [150/156] (346s left) gem_exec_whisper (basic-contexts-all)
[732.130614] Starting subtest: basic-contexts-all
[738.996330] Subtest basic-contexts-all: SUCCESS (6.866s)
[739.254049] [151/156] (339s left) dmabuf_sync_file (export-basic)
[739.364111] Starting subtest: export-basic
[739.367718] Subtest export-basic: SUCCESS (0.003s)
[739.520339] [152/156] (339s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[739.708067] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[740.822421] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (1.112s)
[741.015062] [153/156] (338s left) gem_sync (basic-each)
[741.137825] Starting subtest: basic-each
[743.359276] Subtest basic-each: SUCCESS (2.221s)
[743.617832] [154/156] (335s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[743.786791] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[743.787873] Starting dynamic subtest: pipe-A-HDMI-A-1
[743.797768] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.000s)
[743.824576] Starting dynamic subtest: pipe-A-HDMI-A-2
[743.833599] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s)
[743.859740] Starting dynamic subtest: pipe-B-HDMI-A-1
[743.868656] Dynamic subtest pipe-B-HDMI-A-1: SKIP (0.000s)
[743.894712] Starting dynamic subtest: pipe-B-HDMI-A-2
[743.904084] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.001s)
[743.929684] Starting dynamic subtest: pipe-C-HDMI-A-1
[743.938235] Dynamic subtest pipe-C-HDMI-A-1: SKIP (0.000s)
[743.963932] Starting dynamic subtest: pipe-C-HDMI-A-2
[743.973876] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s)
[743.975481] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.031s)
[744.758363] [155/156] (334s left) syncobj_wait (multi-wait-for-submit-submitted)
[744.865758] Starting subtest: multi-wait-for-submit-submitted
[744.971585] Subtest multi-wait-for-submit-submitted: SUCCESS (0.107s)
[745.123679] [156/156] (334s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt)
[745.305506] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt
[745.307589] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[745.452942] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: kms_pipe_b_c_ivb
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_force_connector_basic
results: parsing output: 5/ for test: perf_pmu
results: parsing output: 6/ for test: gem_ccs
results: parsing output: 7/ for test: kms_plane_alpha_blend
results: parsing output: 8/ for test: gem_mmap_gtt
results: parsing output: 9/ for test: api_intel_allocator
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_plane
results: parsing output: 12/ for test: kms_ccs
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_pm_rpm
results: parsing output: 16/ for test: gem_render_copy
results: parsing output: 17/ for test: sysfs_heartbeat_interval
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: gem_ctx_param
results: parsing output: 20/ for test: kms_psr
results: parsing output: 21/ for test: i915_pm_freq_mult
results: parsing output: 22/ for test: prime_busy
results: parsing output: 23/ for test: gem_exec_gttfill
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: gem_ctx_param
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_lmem_swapping
results: parsing output: 28/ for test: gem_ctx_shared
results: parsing output: 29/ for test: kms_psr
results: parsing output: 30/ for test: gem_bad_reloc
results: parsing output: 31/ for test: kms_chamelium_hpd
results: parsing output: 32/ for test: kms_psr2_sf
results: parsing output: 33/ for test: gem_eio
results: parsing output: 34/ for test: kms_content_protection
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: gem_ctx_isolation
results: parsing output: 37/ for test: gem_flink_basic
results: parsing output: 38/ for test: gem_caching
results: parsing output: 39/ for test: kms_plane_scaling
results: parsing output: 40/ for test: syncobj_timeline
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_pm_rpm
results: parsing output: 43/ for test: kms_atomic_transition
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: gem_spin_batch
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: syncobj_basic
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_big_fb
results: parsing output: 50/ for test: syncobj_timeline
results: parsing output: 51/ for test: api_intel_bb
results: parsing output: 52/ for test: kms_atomic_interruptible
results: parsing output: 53/ for test: kms_big_fb
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_flip_scaled_crc
results: parsing output: 56/ for test: gem_pxp
results: parsing output: 57/ for test: gem_fence_thrash
results: parsing output: 58/ for test: gen9_exec_parse
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: gem_exec_balancer
results: parsing output: 61/ for test: kms_plane
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_pwrite_crc
results: parsing output: 64/ for test: gem_flink_basic
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: gem_ctx_persistence
results: parsing output: 67/ for test: gen7_exec_parse
results: parsing output: 68/ for test: gem_exec_schedule
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: perf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: i915_drm_fdinfo
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_pipe_crc_basic
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: kms_cursor_crc
results: parsing output: 77/ for test: kms_color_pipeline
results: parsing output: 78/ for test: kms_big_fb
results: parsing output: 79/ for test: kms_big_fb
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_exec_reloc
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_draw_crc
results: parsing output: 86/ for test: kms_cursor_crc
results: parsing output: 87/ for test: kms_cursor_crc
results: parsing output: 88/ for test: kms_frontbuffer_tracking
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: kms_lease
results: parsing output: 91/ for test: syncobj_wait
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_exec_fence
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: kms_ccs
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_chamelium_audio
results: parsing output: 100/ for test: kms_addfb_basic
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_sequence
results: parsing output: 105/ for test: kms_plane_scaling
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: gem_exec_reloc
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_psr
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_busy
results: parsing output: 112/ for test: kms_psr
results: parsing output: 113/ for test: gem_softpin
results: parsing output: 114/ for test: kms_force_connector_basic
results: parsing output: 115/ for test: kms_chamelium_edid
results: parsing output: 116/ for test: syncobj_timeline
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_mmap_gtt
results: parsing output: 120/ for test: gem_render_copy
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: vgem_basic
results: parsing output: 123/ for test: kms_flip_scaled_crc
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_vblank
results: parsing output: 128/ for test: kms_flip_scaled_crc
results: parsing output: 129/ for test: gem_ctx_exec
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_psr2_sf
results: parsing output: 132/ for test: gem_ppgtt
results: parsing output: 133/ for test: gem_exec_reloc
results: parsing output: 134/ for test: gem_softpin
results: parsing output: 135/ for test: gem_exec_capture
results: parsing output: 136/ for test: gem_tiled_blits
results: parsing output: 137/ for test: kms_frontbuffer_tracking
results: parsing output: 138/ for test: gem_mmap_offset
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: kms_frontbuffer_tracking
results: parsing output: 141/ for test: i915_power
results: parsing output: 142/ for test: gem_exec_schedule
results: parsing output: 143/ for test: kms_cursor_legacy
results: parsing output: 144/ for test: i915_pm_rps
results: parsing output: 145/ for test: kms_psr
results: parsing output: 146/ for test: kms_setmode
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: kms_flip
results: parsing output: 149/ for test: gem_exec_whisper
results: parsing output: 150/ for test: dmabuf_sync_file
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: gem_sync
results: parsing output: 153/ for test: kms_ccs
results: parsing output: 154/ for test: syncobj_wait
results: parsing output: 155/ for test: kms_frontbuffer_tracking
Done.