[57.167194] Initializing watchdogs
[57.167243]   /dev/watchdog0
[57.182584] [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]
[57.187196] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.189529] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.193447] [001/133] (960s left) api_intel_bb (offset-control)
[57.276704] Starting subtest: offset-control
[57.289012] Subtest offset-control: SUCCESS (0.013s)
[57.430730] [002/133] (960s left) kms_plane_lowres (tiling-yf)
[57.534794] Starting subtest: tiling-yf
[57.539077] Subtest tiling-yf: SKIP (0.000s)
[57.601755] [003/133] (960s left) core_auth (basic-auth)
[57.673891] Starting subtest: basic-auth
[57.676220] Subtest basic-auth: SUCCESS (0.001s)
[57.771326] [004/133] (960s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[57.870411] Starting subtest: fbc-psr2-cursor-plane-update-sf
[57.872712] Starting dynamic subtest: pipe-A-eDP-1
[57.969619] Dynamic subtest pipe-A-eDP-1: SKIP (0.103s)
[57.979035] Starting dynamic subtest: pipe-B-eDP-1
[59.120800] Dynamic subtest pipe-B-eDP-1: SKIP (1.151s)
[59.125435] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.256s)
[60.295816] [005/133] (957s left) perf_pmu (render-node-busy-idle)
[60.375632] Starting subtest: render-node-busy-idle
[60.377992] Starting dynamic subtest: rcs0
[61.072526] Dynamic subtest rcs0: SUCCESS (0.697s)
[61.083929] Starting dynamic subtest: bcs0
[61.741442] Dynamic subtest bcs0: SUCCESS (0.669s)
[61.750674] Starting dynamic subtest: vcs0
[62.938810] Dynamic subtest vcs0: SUCCESS (1.195s)
[62.949041] Starting dynamic subtest: vcs1
[64.923029] Dynamic subtest vcs1: SUCCESS (1.984s)
[64.933242] Starting dynamic subtest: vecs0
[66.905967] Dynamic subtest vecs0: SUCCESS (1.983s)
[66.916110] Starting dynamic subtest: ccs0
[67.663729] Dynamic subtest ccs0: SUCCESS (0.759s)
[67.666188] Subtest render-node-busy-idle: SUCCESS (7.294s)
[67.765460] [006/133] (950s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[67.952847] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[67.955321] Starting dynamic subtest: pipe-A-default-mode
[70.707902] Dynamic subtest pipe-A-default-mode: SUCCESS (2.755s)
[70.710297] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.757s)
[71.565911] [007/133] (946s left) syncobj_timeline (invalid-transfer-non-existent-point)
[71.626320] Starting subtest: invalid-transfer-non-existent-point
[71.628588] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[71.708662] [008/133] (946s left) kms_chamelium_hpd (vga-hpd-without-ddc)
[72.101787] Subtest vga-hpd-without-ddc: SKIP (0.000s)
[72.879504] [009/133] (945s left) gem_unfence_active_buffers
[73.040764] [010/133] (945s left) gem_exec_reloc (basic-concurrent16)
[73.105022] Subtest basic-concurrent16: SKIP (0.000s)
[73.172605] [011/133] (944s left) kms_rotation_crc (primary-yf-tiled-reflect-x-180)
[73.268927] Starting subtest: primary-yf-tiled-reflect-x-180
[73.328744] Subtest primary-yf-tiled-reflect-x-180: SKIP (0.059s)
[73.431395] [012/133] (944s left) kms_pipe_crc_basic (nonblocking-crc)
[73.534795] Starting subtest: nonblocking-crc
[73.537076] Starting dynamic subtest: pipe-A-eDP-1
[74.278595] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.744s)
[74.286651] Starting dynamic subtest: pipe-B-eDP-1
[75.711735] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.434s)
[75.719797] Starting dynamic subtest: pipe-C-eDP-1
[77.080190] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.367s)
[77.090539] Starting dynamic subtest: pipe-D-eDP-1
[78.416809] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.335s)
[78.418888] Subtest nonblocking-crc: SUCCESS (4.884s)
[79.236671] [013/133] (938s left) prime_mmap_kms (buffer-sharing)
[79.330478] Starting subtest: buffer-sharing
[79.775839] Subtest buffer-sharing: SUCCESS (0.445s)
[80.653340] [014/133] (937s left) gem_render_copy (mixed-tiled-to-yf-tiled-ccs)
[80.711527] Starting subtest: mixed-tiled-to-yf-tiled-ccs
[80.715955] Subtest mixed-tiled-to-yf-tiled-ccs: SKIP (0.000s)
[80.887827] [015/133] (937s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[81.001224] Starting subtest: 4-tiled-64bpp-rotate-270
[81.021024] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.019s)
[81.150814] [016/133] (937s left) gem_busy (extended)
[81.335941] Starting subtest: extended
[81.338525] Starting dynamic subtest: rcs0
[81.410317] Dynamic subtest rcs0: SUCCESS (0.077s)
[81.423155] Starting dynamic subtest: bcs0
[81.451622] Dynamic subtest bcs0: SUCCESS (0.041s)
[81.461705] Starting dynamic subtest: vcs0
[82.905619] Dynamic subtest vcs0: SUCCESS (1.451s)
[82.916468] Starting dynamic subtest: vcs1
[84.889757] Dynamic subtest vcs1: SUCCESS (1.982s)
[84.900891] Starting dynamic subtest: vecs0
[86.937028] Dynamic subtest vecs0: SUCCESS (2.046s)
[86.947412] Starting dynamic subtest: ccs0
[86.984929] Dynamic subtest ccs0: SUCCESS (0.048s)
[86.987424] Subtest extended: SUCCESS (5.653s)
[87.249331] [017/133] (931s left) kms_joiner (basic-force-big-joiner)
[87.370154] Starting subtest: basic-force-big-joiner
[87.374209] Subtest basic-force-big-joiner: SKIP (0.000s)
[87.444848] [018/133] (930s left) kms_psr (pr-sprite-plane-onoff)
[87.545797] Starting subtest: pr-sprite-plane-onoff
[87.550315] Subtest pr-sprite-plane-onoff: SKIP (0.000s)
[87.617968] [019/133] (930s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[87.710778] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[87.715156] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.000s)
[87.787372] [020/133] (930s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[87.883664] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[87.888339] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[87.980565] [021/133] (930s left) gem_eio (in-flight-contexts-1us)
[88.197215] Starting subtest: in-flight-contexts-1us
[99.942825] Subtest in-flight-contexts-1us: SUCCESS (11.744s)
[100.061785] [022/133] (918s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[100.182013] Starting subtest: cursor-vs-flip-legacy
[129.576952] Subtest cursor-vs-flip-legacy: SUCCESS (29.392s)
[130.375474] [023/133] (888s left) perf_pmu (busy-accuracy-2)
[130.435025] Starting subtest: busy-accuracy-2
[130.437151] Starting dynamic subtest: rcs0
[133.482188] Dynamic subtest rcs0: SUCCESS (3.046s)
[133.490573] Starting dynamic subtest: bcs0
[136.543072] Dynamic subtest bcs0: SUCCESS (3.059s)
[136.553216] Starting dynamic subtest: vcs0
[139.591949] Dynamic subtest vcs0: SUCCESS (3.047s)
[139.602546] Starting dynamic subtest: vcs1
[142.641062] Dynamic subtest vcs1: SUCCESS (3.047s)
[142.651139] Starting dynamic subtest: vecs0
[145.682062] Dynamic subtest vecs0: SUCCESS (3.041s)
[145.692943] Starting dynamic subtest: ccs0
[148.738734] Dynamic subtest ccs0: SUCCESS (3.056s)
[148.741016] Subtest busy-accuracy-2: SUCCESS (18.303s)
[148.852748] [024/133] (869s left) kms_psr (fbc-psr2-cursor-plane-onoff)
[148.951619] Starting subtest: fbc-psr2-cursor-plane-onoff
[148.953803] Starting dynamic subtest: eDP-1
[148.958118] Dynamic subtest eDP-1: SKIP (0.000s)
[148.962360] Subtest fbc-psr2-cursor-plane-onoff: SKIP (0.001s)
[149.022677] [025/133] (869s left) kms_properties (plane-properties-atomic)
[149.130155] Starting subtest: plane-properties-atomic
[149.132570] Starting dynamic subtest: pipe-A-eDP-1
[149.699710] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.568s)
[149.709575] Starting dynamic subtest: pipe-B-eDP-1
[150.936070] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.236s)
[150.945203] Starting dynamic subtest: pipe-C-eDP-1
[152.171851] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.236s)
[152.182356] Starting dynamic subtest: pipe-D-eDP-1
[153.409677] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.235s)
[153.411630] Subtest plane-properties-atomic: SUCCESS (4.279s)
[154.210500] [026/133] (864s left) gem_softpin (evict-active-interruptible)
[154.279846] Starting subtest: evict-active-interruptible
[154.285716] Subtest evict-active-interruptible: SUCCESS (0.006s)
[154.370973] [027/133] (864s left) kms_vblank (crtc-id)
[154.455272] Starting subtest: crtc-id
[154.457362] Starting dynamic subtest: pipe-A-eDP-1
[154.897141] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.442s)
[154.911701] Starting dynamic subtest: pipe-D-eDP-1
[156.036940] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.135s)
[156.038935] Subtest crtc-id: SUCCESS (1.581s)
[156.869410] [028/133] (861s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[156.940860] Starting subtest: y-tiled-ccs-to-x-tiled
[156.945709] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[157.123422] [029/133] (861s left) kms_ccs (crc-primary-suspend-y-tiled-gen12-rc-ccs-cc)
[157.209058] Starting subtest: crc-primary-suspend-y-tiled-gen12-rc-ccs-cc
[157.211393] Starting dynamic subtest: pipe-A-eDP-1
[157.220671] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[157.230083] Starting dynamic subtest: pipe-B-eDP-1
[157.239413] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[157.247642] Starting dynamic subtest: pipe-C-eDP-1
[157.256370] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[157.264306] Starting dynamic subtest: pipe-D-eDP-1
[157.272910] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[157.277084] Subtest crc-primary-suspend-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[159.443231] [030/133] (859s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[159.540627] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[164.571283] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (5.033s)
[165.763539] [031/133] (853s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[165.862476] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[165.866759] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[165.963612] [032/133] (852s left) kms_vblank (query-busy)
[166.054567] Starting subtest: query-busy
[166.056729] Starting dynamic subtest: pipe-A-eDP-1
[167.935555] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.880s)
[167.948937] Starting dynamic subtest: pipe-D-eDP-1
[170.524593] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.585s)
[170.526533] Subtest query-busy: SUCCESS (4.469s)
[171.331609] [033/133] (847s left) kms_psr_stress_test (invalidate-primary-flip-overlay)
[171.419886] Starting subtest: invalidate-primary-flip-overlay
[182.177689] Subtest invalidate-primary-flip-overlay: SUCCESS (10.760s)
[182.282421] [034/133] (836s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[182.375950] Starting subtest: psr-rgb565-draw-mmap-wc
[189.182885] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.808s)
[189.291131] [035/133] (829s left) gem_exec_fence (invalid-fence-array)
[189.747733] Starting subtest: invalid-fence-array
[189.750129] Subtest invalid-fence-array: SUCCESS (0.003s)
[190.952816] [FACT gem_exec_fence (invalid-fence-array)] new: kernel.kmod_is_loaded.vgem: true
[190.956426] [036/133] (828s left) kms_addfb_basic (bad-pitch-1024)
[191.026212] Starting subtest: bad-pitch-1024
[191.028575] Subtest bad-pitch-1024: SUCCESS (0.000s)
[191.156691] [037/133] (827s left) gem_ctx_persistence (process)
[191.215876] Starting subtest: process
[191.379624] Subtest process: SUCCESS (0.166s)
[191.469867] [038/133] (827s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[191.564850] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[191.568915] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[191.667387] [039/133] (827s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[191.770790] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[195.493643] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (3.727s)
[196.647765] [040/133] (822s left) gem_exec_reloc (basic-range)
[196.711408] Subtest basic-range: SKIP (0.000s)
[196.770479] [041/133] (822s left) kms_hdr (invalid-metadata-sizes)
[196.863362] Starting subtest: invalid-metadata-sizes
[196.869644] Subtest invalid-metadata-sizes: SKIP (0.000s)
[196.936038] [042/133] (822s left) gem_exec_balancer (parallel-keep-in-fence)
[197.208492] Starting subtest: parallel-keep-in-fence
[198.447702] Subtest parallel-keep-in-fence: SUCCESS (1.239s)
[198.539806] [043/133] (820s left) kms_content_protection (type1)
[198.672898] Starting subtest: type1
[198.677187] Subtest type1: SKIP (0.000s)
[198.800841] [044/133] (820s left) kms_chamelium_color (ctm-0-25)
[198.909658] Subtest ctm-0-25: SKIP (0.000s)
[198.957285] [045/133] (820s left) gem_exec_store (basic)
[199.025968] Starting subtest: basic
[199.139029] Subtest basic: SUCCESS (0.113s)
[199.329052] [046/133] (820s left) kms_async_flips (async-flip-hang)
[199.425310] Starting subtest: async-flip-hang
[199.800515] Starting dynamic subtest: pipe-A-eDP-1
[215.354859] Dynamic subtest pipe-A-eDP-1: SUCCESS (15.553s)
[215.759177] Starting dynamic subtest: pipe-B-eDP-1
[231.347881] Dynamic subtest pipe-B-eDP-1: SUCCESS (15.592s)
[231.749982] Starting dynamic subtest: pipe-C-eDP-1
[247.343326] Dynamic subtest pipe-C-eDP-1: SUCCESS (15.597s)
[247.735473] Starting dynamic subtest: pipe-D-eDP-1
[263.343573] Dynamic subtest pipe-D-eDP-1: SUCCESS (15.613s)
[263.345739] Subtest async-flip-hang: SUCCESS (63.918s)
[264.500749] [047/133] (755s left) i915_query (query-regions-sanity-check)
[264.565834] Starting subtest: query-regions-sanity-check
[264.575656] Subtest query-regions-sanity-check: SUCCESS (0.011s)
[264.694482] [048/133] (754s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf)
[264.794115] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf
[264.796382] Starting dynamic subtest: pipe-A-eDP-1
[275.487127] Dynamic subtest pipe-A-eDP-1: SUCCESS (10.694s)
[275.497132] Starting dynamic subtest: pipe-B-eDP-1
[286.904760] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.414s)
[286.906674] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.111s)
[287.760900] [049/133] (731s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[287.863857] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[287.868075] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[287.957017] [050/133] (731s left) kms_plane (pixel-format-4-tiled-lnl-ccs-modifier-source-clamping)
[288.048125] Starting subtest: pixel-format-4-tiled-lnl-ccs-modifier-source-clamping
[288.052215] Subtest pixel-format-4-tiled-lnl-ccs-modifier-source-clamping: SKIP (0.000s)
[288.120186] [051/133] (731s left) kms_cursor_crc (cursor-random-256x256)
[288.365775] Starting subtest: cursor-random-256x256
[288.368066] Starting dynamic subtest: pipe-A-eDP-1
[291.690899] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.322s)
[291.720543] Starting dynamic subtest: pipe-D-eDP-1
[295.652069] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.940s)
[295.654459] Subtest cursor-random-256x256: SUCCESS (7.288s)
[296.516349] [052/133] (723s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[296.610598] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[296.615089] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[296.711300] [053/133] (723s left) gem_ctx_param (invalid-size-set)
[296.773406] Starting subtest: invalid-size-set
[296.775754] Subtest invalid-size-set: SUCCESS (0.001s)
[296.882978] [054/133] (722s left) kms_properties (get_properties-sanity-non-atomic)
[296.979419] Starting subtest: get_properties-sanity-non-atomic
[296.981773] Subtest get_properties-sanity-non-atomic: SUCCESS (0.002s)
[297.051811] [055/133] (722s left) gem_mmap_gtt (cpuset-basic-small-copy-xy)
[297.109221] Subtest cpuset-basic-small-copy-XY: SKIP (0.000s)
[297.176170] [056/133] (722s left) kms_cursor_legacy (2x-long-cursor-vs-flip-legacy)
[297.290227] Starting subtest: 2x-long-cursor-vs-flip-legacy
[297.294649] Subtest 2x-long-cursor-vs-flip-legacy: SKIP (0.001s)
[297.436226] [057/133] (722s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[297.530306] Starting subtest: x-tiled-32bpp-rotate-180
[299.030033] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.500s)
[299.164832] [058/133] (720s left) gem_exec_schedule (preempt-user)
[299.235467] Starting subtest: preempt-user
[299.237104] Starting dynamic subtest: rcs0
[299.254746] Dynamic subtest rcs0: SUCCESS (0.023s)
[299.264525] Starting dynamic subtest: bcs0
[299.273748] Dynamic subtest bcs0: SUCCESS (0.019s)
[299.282292] Starting dynamic subtest: vcs0
[299.292901] Dynamic subtest vcs0: SUCCESS (0.018s)
[299.301614] Starting dynamic subtest: vcs1
[299.313461] Dynamic subtest vcs1: SUCCESS (0.020s)
[299.322048] Starting dynamic subtest: vecs0
[299.333765] Dynamic subtest vecs0: SUCCESS (0.020s)
[299.342195] Starting dynamic subtest: ccs0
[299.359993] Dynamic subtest ccs0: SUCCESS (0.026s)
[299.361812] Subtest preempt-user: SUCCESS (0.129s)
[300.973431] [059/133] (719s left) syncobj_timeline (reset-signaled)
[301.027259] Starting subtest: reset-signaled
[301.029690] Subtest reset-signaled: SUCCESS (0.000s)
[301.124614] [060/133] (718s left) kms_getfb (getfb-handle-closed)
[301.208528] Starting subtest: getfb-handle-closed
[301.210661] Subtest getfb-handle-closed: SUCCESS (0.000s)
[301.305521] [061/133] (718s left) kms_flip (dpms-vs-vblank-race-interruptible)
[301.400138] Starting subtest: dpms-vs-vblank-race-interruptible
[301.402589] Starting dynamic subtest: A-eDP1
[306.320763] Dynamic subtest A-eDP1: SUCCESS (4.918s)
[306.330034] Starting dynamic subtest: B-eDP1
[310.939581] Dynamic subtest B-eDP1: SUCCESS (4.616s)
[310.949623] Starting dynamic subtest: C-eDP1
[315.559457] Dynamic subtest C-eDP1: SUCCESS (4.620s)
[315.570101] Starting dynamic subtest: D-eDP1
[320.200726] Dynamic subtest D-eDP1: SUCCESS (4.638s)
[320.205319] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (18.803s)
[321.082838] [062/133] (699s left) kms_pm_rpm (universal-planes-dpms)
[323.025628] Starting subtest: universal-planes-dpms
[323.027655] Starting dynamic subtest: plane-34
[331.314031] Dynamic subtest plane-34: SUCCESS (8.289s)
[331.325951] Starting dynamic subtest: plane-64
[339.748672] Dynamic subtest plane-64: SUCCESS (8.432s)
[339.760442] Starting dynamic subtest: plane-94
[348.279987] Dynamic subtest plane-94: SUCCESS (8.527s)
[348.292265] Starting dynamic subtest: plane-124
[356.574128] Dynamic subtest plane-124: SUCCESS (8.292s)
[356.585076] Starting dynamic subtest: plane-134
[364.910621] Dynamic subtest plane-134: SUCCESS (8.333s)
[364.922622] Starting dynamic subtest: plane-144
[373.214923] Dynamic subtest plane-144: SUCCESS (8.302s)
[373.217490] Subtest universal-planes-dpms: SUCCESS (50.192s)
[373.583639] [063/133] (646s left) gem_exec_schedule (noreorder-corked)
[373.780811] Starting subtest: noreorder-corked
[373.783453] Starting dynamic subtest: rcs0
[374.921701] Dynamic subtest rcs0: SUCCESS (1.141s)
[374.932075] Starting dynamic subtest: vcs0
[376.917321] Dynamic subtest vcs0: SUCCESS (1.992s)
[376.928563] Starting dynamic subtest: vcs1
[378.901568] Dynamic subtest vcs1: SUCCESS (1.981s)
[378.914300] Starting dynamic subtest: vecs0
[380.891460] Dynamic subtest vecs0: SUCCESS (1.987s)
[380.901919] Starting dynamic subtest: ccs0
[382.043782] Dynamic subtest ccs0: SUCCESS (1.151s)
[382.046243] Subtest noreorder-corked: SUCCESS (8.265s)
[382.232241] [064/133] (638s left) gen9_exec_parse (unaligned-access)
[382.300365] Subtest unaligned-access: SKIP (0.000s)
[382.379894] [065/133] (637s left) kms_cursor_edge_walk (256x256-right-edge)
[382.476576] Starting subtest: 256x256-right-edge
[382.478901] Starting dynamic subtest: pipe-A-eDP-1
[385.944435] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.468s)
[385.954298] Starting dynamic subtest: pipe-D-eDP-1
[389.087531] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.140s)
[389.089848] Subtest 256x256-right-edge: SUCCESS (6.611s)
[389.893982] [066/133] (630s left) kms_getfb (getfb-addfb-different-handles)
[389.991034] Starting subtest: getfb-addfb-different-handles
[389.993501] Subtest getfb-addfb-different-handles: SUCCESS (0.000s)
[390.122070] [067/133] (630s left) kms_atomic_transition (plane-all-transition-fencing)
[390.222064] Starting subtest: plane-all-transition-fencing
[390.224360] Starting dynamic subtest: pipe-A-eDP-1
[392.716082] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.496s)
[393.027177] Starting dynamic subtest: pipe-B-eDP-1
[396.224135] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.205s)
[396.520667] Subtest plane-all-transition-fencing: SUCCESS (6.300s)
[397.362427] [068/133] (623s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[397.452045] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[397.454153] Starting dynamic subtest: pipe-A-eDP-1
[399.700923] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.249s)
[399.709872] Starting dynamic subtest: pipe-B-eDP-1
[402.048434] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.348s)
[402.057069] Starting dynamic subtest: pipe-C-eDP-1
[404.237054] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.187s)
[404.245753] Starting dynamic subtest: pipe-D-eDP-1
[406.341450] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.102s)
[406.343450] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.891s)
[407.496863] [069/133] (612s left) perf (invalid-oa-exponent)
[407.629259] Starting subtest: invalid-oa-exponent
[407.643328] Subtest invalid-oa-exponent: SUCCESS (0.016s)
[407.694984] [070/133] (612s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[407.791379] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[407.795743] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[407.873647] [071/133] (612s left) kms_plane_scaling (planes-scaler-unity-scaling)
[407.965291] Starting subtest: planes-scaler-unity-scaling
[407.967592] Starting dynamic subtest: pipe-A
[408.026917] Dynamic subtest pipe-A: SUCCESS (0.064s)
[408.041606] Starting dynamic subtest: pipe-B
[409.217786] Dynamic subtest pipe-B: SUCCESS (1.188s)
[409.229550] Starting dynamic subtest: pipe-C
[410.319805] Dynamic subtest pipe-C: SUCCESS (1.100s)
[410.330363] Starting dynamic subtest: pipe-D
[411.466852] Dynamic subtest pipe-D: SUCCESS (1.146s)
[411.469670] Subtest planes-scaler-unity-scaling: SUCCESS (3.505s)
[412.616746] [072/133] (607s left) kms_vblank (ts-continuation-idle)
[412.713112] Starting subtest: ts-continuation-idle
[412.714984] Starting dynamic subtest: pipe-A-eDP-1
[413.132093] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.419s)
[413.147160] Starting dynamic subtest: pipe-D-eDP-1
[414.206738] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.071s)
[414.208884] Subtest ts-continuation-idle: SUCCESS (1.494s)
[415.079490] [073/133] (605s left) syncobj_timeline (etime-single-wait-submitted)
[415.133140] Starting subtest: etime-single-wait-submitted
[415.236099] Subtest etime-single-wait-submitted: SUCCESS (0.103s)
[415.325391] [074/133] (605s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[415.427819] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[422.504361] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.079s)
[422.658367] [075/133] (598s left) gem_exec_parallel (basic)
[422.719673] Starting subtest: basic
[422.722049] Starting dynamic subtest: rcs0
[424.146062] Dynamic subtest rcs0: SUCCESS (1.426s)
[424.158437] Starting dynamic subtest: bcs0
[425.617462] Dynamic subtest bcs0: SUCCESS (1.469s)
[425.628342] Starting dynamic subtest: vcs0
[428.888313] Dynamic subtest vcs0: SUCCESS (3.267s)
[428.900180] Starting dynamic subtest: vcs1
[430.872816] Dynamic subtest vcs1: SUCCESS (1.982s)
[430.885460] Starting dynamic subtest: vecs0
[432.857061] Dynamic subtest vecs0: SUCCESS (1.981s)
[432.868668] Starting dynamic subtest: ccs0
[434.336971] Dynamic subtest ccs0: SUCCESS (1.479s)
[434.339536] Subtest basic: SUCCESS (11.619s)
[434.529174] [076/133] (586s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[434.632077] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[434.636113] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[434.737107] [077/133] (586s left) kms_addfb_basic (bad-pitch-128)
[434.801015] Starting subtest: bad-pitch-128
[434.803199] Subtest bad-pitch-128: SUCCESS (0.000s)
[434.936948] [078/133] (585s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-pgflip-blt)
[435.036751] Starting subtest: psr-1p-primscrn-indfb-pgflip-blt
[442.376260] Subtest psr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.342s)
[442.492096] [079/133] (578s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs)
[442.585660] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs
[442.587924] Starting dynamic subtest: pipe-A-eDP-1
[442.611053] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[442.621682] Starting dynamic subtest: pipe-B-eDP-1
[442.643086] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[442.651821] Starting dynamic subtest: pipe-C-eDP-1
[442.672496] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[442.681543] Starting dynamic subtest: pipe-D-eDP-1
[442.703378] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[442.707422] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[444.887880] [080/133] (576s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-msflip-blt)
[444.991670] Starting subtest: fbc-2p-primscrn-indfb-msflip-blt
[444.995739] Subtest fbc-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[445.081893] [081/133] (575s left) gem_lmem_swapping (heavy-verify-multi)
[445.122782] Subtest heavy-verify-multi: SKIP (0.000s)
[445.169734] [082/133] (575s left) kms_color (ctm-red-to-blue)
[445.263360] Starting subtest: ctm-red-to-blue
[445.265556] Starting dynamic subtest: pipe-A-eDP-1
[445.971097] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.709s)
[445.980920] Starting dynamic subtest: pipe-B-eDP-1
[447.408487] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.435s)
[447.418197] Starting dynamic subtest: pipe-C-eDP-1
[448.776118] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.369s)
[448.785812] Starting dynamic subtest: pipe-D-eDP-1
[450.120785] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.341s)
[450.122889] Subtest ctm-red-to-blue: SUCCESS (4.857s)
[450.932371] [083/133] (570s left) gem_mmap_offset (partial-remap)
[450.991932] Starting subtest: partial-remap
[450.994440] Starting dynamic subtest: smem0
[451.003475] Dynamic subtest smem0: SUCCESS (0.004s)
[451.005722] Subtest partial-remap: SUCCESS (0.004s)
[451.113053] [084/133] (569s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu)
[451.219364] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu
[457.804263] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-mmap-cpu: SUCCESS (6.591s)
[457.960354] [085/133] (563s left) gem_userptr_blits (stress-mm-invalidate-close-overlap)
[458.035077] Starting subtest: stress-mm-invalidate-close-overlap
[460.181079] Subtest stress-mm-invalidate-close-overlap: SUCCESS (2.148s)
[460.318375] [086/133] (560s left) gem_ctx_shared (detached-shared-gtt)
[460.391276] Starting subtest: detached-shared-gtt
[462.545730] Subtest detached-shared-gtt: SUCCESS (2.153s)
[462.831830] [087/133] (558s left) kms_addfb_basic (too-wide)
[462.949210] Starting subtest: too-wide
[462.951531] Subtest too-wide: SUCCESS (0.000s)
[463.068699] [088/133] (558s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[463.169422] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[466.739870] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.573s)
[467.958719] [089/133] (553s left) vgem_basic (create)
[468.007070] Starting subtest: create
[468.009219] Subtest create: SUCCESS (0.000s)
[468.064528] [090/133] (553s left) api_intel_allocator (reopen-fork)
[468.112983] Starting subtest: reopen-fork
[471.492719] Subtest reopen-fork: SUCCESS (3.380s)
[471.590406] [091/133] (549s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move)
[471.685821] Starting subtest: psr-2p-scndscrn-cur-indfb-move
[471.690037] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[471.779873] [092/133] (549s left) kms_chamelium_frames (dp-crc-multiple)
[472.173769] Subtest dp-crc-multiple: SKIP (0.000s)
[472.959460] [093/133] (548s left) syncobj_timeline (wait-any-snapshot)
[473.027701] Starting subtest: wait-any-snapshot
[473.108624] Subtest wait-any-snapshot: SUCCESS (0.081s)
[473.188156] [094/133] (548s left) i915_pm_rpm (debugfs-forcewake-user)
[475.020048] Starting subtest: debugfs-forcewake-user
[486.411367] Subtest debugfs-forcewake-user: SUCCESS (11.390s)
[486.789524] [095/133] (534s left) gen9_exec_parse (bb-large)
[486.851920] Subtest bb-large: SKIP (0.000s)
[486.924527] [096/133] (534s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[487.042113] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[487.172300] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.133s)
[487.282170] [097/133] (534s left) gem_eio (unwedge-stress)
[487.514238] Starting subtest: unwedge-stress
[487.516518] Starting dynamic subtest: render
[492.388582] Dynamic subtest render: SUCCESS (4.874s)
[492.405549] Starting dynamic subtest: bsd1
[510.406104] Dynamic subtest bsd1: SUCCESS (18.016s)
[510.421916] Starting dynamic subtest: bsd2
[528.393106] Dynamic subtest bsd2: SUCCESS (17.985s)
[528.409959] Starting dynamic subtest: blt
[528.414731] Dynamic subtest blt: SKIP (0.000s)
[528.423934] Starting dynamic subtest: vebox
[546.368084] Dynamic subtest vebox: SUCCESS (17.973s)
[546.370026] Subtest unwedge-stress: SUCCESS (58.853s)
[547.969829] [098/133] (473s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render)
[548.066985] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render
[548.071042] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[548.161963] [099/133] (473s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0)
[548.280358] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0
[549.336657] Subtest x-tiled-max-hw-stride-64bpp-rotate-0: SUCCESS (1.056s)
[549.460583] [100/133] (472s left) gem_mmap_gtt (medium-copy-xy)
[549.513540] Subtest medium-copy-XY: SKIP (0.000s)
[549.577470] [101/133] (472s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic)
[549.688808] Starting subtest: 2x-long-cursor-vs-flip-atomic
[549.694569] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.003s)
[549.836755] [102/133] (472s left) kms_psr (psr-cursor-render)
[549.926375] Starting subtest: psr-cursor-render
[549.928250] Starting dynamic subtest: eDP-1
[551.662391] Dynamic subtest eDP-1: SUCCESS (1.737s)
[551.664747] Subtest psr-cursor-render: SUCCESS (1.738s)
[552.544913] [103/133] (469s left) kms_psr (fbc-pr-cursor-blt)
[552.640085] Starting subtest: fbc-pr-cursor-blt
[552.644574] Subtest fbc-pr-cursor-blt: SKIP (0.000s)
[552.715856] [104/133] (469s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-blt)
[552.809340] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-blt
[557.285465] Subtest fbc-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (4.479s)
[558.450773] [105/133] (463s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt)
[558.573713] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt
[558.578258] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[558.676130] [106/133] (463s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[558.773660] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[558.777779] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[558.866865] [107/133] (463s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[558.965837] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[558.970124] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[559.049582] [108/133] (463s left) syncobj_eventfd (binary-wait-before-signal)
[559.094843] Starting subtest: binary-wait-before-signal
[559.124366] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[559.211972] [109/133] (462s left) core_setmaster (master-drop-set-shared-fd)
[559.294029] Starting subtest: master-drop-set-shared-fd
[559.315809] Subtest master-drop-set-shared-fd: SUCCESS (0.022s)
[559.357179] [110/133] (462s left) kms_pm_dc (dc3co-vpb-simulation)
[560.465889] Starting subtest: dc3co-vpb-simulation
[568.935693] Subtest dc3co-vpb-simulation: SKIP (8.465s)
[569.079733] [111/133] (453s left) kms_async_flips (invalid-async-flip)
[569.173570] Starting subtest: invalid-async-flip
[569.566580] Starting dynamic subtest: pipe-A-eDP-1
[570.355764] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.789s)
[570.723612] Starting dynamic subtest: pipe-B-eDP-1
[571.489652] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.777s)
[571.852891] Starting dynamic subtest: pipe-C-eDP-1
[572.614743] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.770s)
[572.984084] Starting dynamic subtest: pipe-D-eDP-1
[573.751898] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.777s)
[573.754227] Subtest invalid-async-flip: SUCCESS (4.581s)
[574.907957] [112/133] (447s left) gem_create (create-ext-cpu-access-sanity-check)
[575.072235] Starting subtest: create-ext-cpu-access-sanity-check
[575.077378] Subtest create-ext-cpu-access-sanity-check: SKIP (0.001s)
[575.148618] [113/133] (447s left) gem_ctx_isolation (dirty-switch)
[575.218369] Starting subtest: dirty-switch
[575.220845] Starting dynamic subtest: rcs0
[575.406454] Dynamic subtest rcs0: SUCCESS (0.188s)
[575.416209] Starting dynamic subtest: bcs0
[575.616698] Dynamic subtest bcs0: SUCCESS (0.208s)
[575.627071] Starting dynamic subtest: vcs0
[575.825109] Dynamic subtest vcs0: SUCCESS (0.208s)
[575.836916] Starting dynamic subtest: vcs1
[577.094036] Dynamic subtest vcs1: SUCCESS (1.268s)
[577.105590] Starting dynamic subtest: vecs0
[579.108065] Dynamic subtest vecs0: SUCCESS (2.013s)
[579.119643] Starting dynamic subtest: ccs0
[581.111354] Dynamic subtest ccs0: SUCCESS (2.002s)
[581.113700] Subtest dirty-switch: SUCCESS (5.893s)
[581.348537] [114/133] (441s left) kms_ccs (crc-primary-suspend-yf-tiled-ccs)
[581.456200] Starting subtest: crc-primary-suspend-yf-tiled-ccs
[581.458545] Starting dynamic subtest: pipe-A-eDP-1
[581.467875] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[581.482066] Starting dynamic subtest: pipe-B-eDP-1
[581.491188] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[581.500316] Starting dynamic subtest: pipe-C-eDP-1
[581.508998] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[581.518280] Starting dynamic subtest: pipe-D-eDP-1
[581.527043] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[581.531299] Subtest crc-primary-suspend-yf-tiled-ccs: SKIP (0.005s)
[583.774979] [115/133] (438s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[583.875814] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[583.880255] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[583.960806] [116/133] (438s left) kms_big_fb (linear-addfb)
[584.074691] Starting subtest: linear-addfb
[584.077051] Subtest linear-addfb: SUCCESS (0.000s)
[584.178988] [117/133] (438s left) gem_render_copy (y-tiled-mc-ccs-to-y-tiled-ccs)
[584.243443] Starting subtest: y-tiled-mc-ccs-to-y-tiled-ccs
[584.247847] Subtest y-tiled-mc-ccs-to-y-tiled-ccs: SKIP (0.000s)
[584.429884] [118/133] (438s left) kms_feature_discovery (psr2)
[584.519092] Starting subtest: psr2
[584.521364] Subtest psr2: SUCCESS (0.000s)
[584.592466] [119/133] (437s left) sysfs_preempt_timeout (invalid)
[584.658981] Starting subtest: invalid
[584.661326] Starting dynamic subtest: vecs0
[584.663647] Dynamic subtest vecs0: SUCCESS (0.000s)
[584.674470] Starting dynamic subtest: ccs0
[584.676357] Dynamic subtest ccs0: SUCCESS (0.000s)
[584.685495] Starting dynamic subtest: vcs0
[584.687678] Dynamic subtest vcs0: SUCCESS (0.000s)
[584.696732] Starting dynamic subtest: bcs0
[584.698614] Dynamic subtest bcs0: SUCCESS (0.000s)
[584.707398] Starting dynamic subtest: rcs0
[584.709271] Dynamic subtest rcs0: SUCCESS (0.000s)
[584.717974] Starting dynamic subtest: vcs1
[584.719890] Dynamic subtest vcs1: SUCCESS (0.000s)
[584.721785] Subtest invalid: SUCCESS (0.006s)
[584.760461] [120/133] (437s left) kms_plane_scaling (plane-scaler-unity-scaling-with-pixel-format)
[584.855567] Starting subtest: plane-scaler-unity-scaling-with-pixel-format
[584.857956] Starting dynamic subtest: pipe-A
[585.959658] Dynamic subtest pipe-A: SUCCESS (1.106s)
[585.971437] Starting dynamic subtest: pipe-B
[588.176729] Dynamic subtest pipe-B: SUCCESS (2.215s)
[588.186103] Starting dynamic subtest: pipe-C
[589.368056] Dynamic subtest pipe-C: SUCCESS (1.190s)
[589.378827] Starting dynamic subtest: pipe-D
[590.520153] Dynamic subtest pipe-D: SUCCESS (1.152s)
[590.522413] Subtest plane-scaler-unity-scaling-with-pixel-format: SUCCESS (5.667s)
[591.727761] [121/133] (430s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[591.853601] Starting subtest: short-flip-before-cursor-toggle
[592.775507] Subtest short-flip-before-cursor-toggle: SUCCESS (0.922s)
[593.577177] [122/133] (429s left) gem_eio (in-flight-internal-immediate)
[593.825153] Starting subtest: in-flight-internal-immediate
[594.979474] Subtest in-flight-internal-immediate: SUCCESS (1.152s)
[595.115995] [123/133] (427s left) kms_psr (fbc-psr-cursor-render)
[595.211785] Starting subtest: fbc-psr-cursor-render
[595.213988] Starting dynamic subtest: eDP-1
[595.217994] Dynamic subtest eDP-1: SKIP (0.000s)
[595.221942] Subtest fbc-psr-cursor-render: SKIP (0.001s)
[595.287358] [124/133] (427s left) gem_userptr_blits (mmap-offset-banned)
[595.350902] Starting subtest: mmap-offset-banned
[595.353063] Starting dynamic subtest: wb
[595.354851] Dynamic subtest wb: SUCCESS (0.000s)
[595.365353] Starting dynamic subtest: wc
[595.367483] Dynamic subtest wc: SUCCESS (0.000s)
[595.376138] Starting dynamic subtest: uc
[595.378202] Dynamic subtest uc: SUCCESS (0.000s)
[595.380257] Subtest mmap-offset-banned: SUCCESS (0.002s)
[595.478719] [125/133] (427s left) kms_cursor_crc (cursor-onscreen-32x32)
[595.623557] Starting subtest: cursor-onscreen-32x32
[595.669834] Subtest cursor-onscreen-32x32: SKIP (0.043s)
[595.971842] [126/133] (426s left) syncobj_wait (single-wait-all-for-submit-submitted)
[596.025389] Starting subtest: single-wait-all-for-submit-submitted
[596.132778] Subtest single-wait-all-for-submit-submitted: SUCCESS (0.106s)
[596.209227] [127/133] (426s left) kms_lease (simple-lease)
[596.316822] Starting subtest: simple-lease
[596.319273] Starting dynamic subtest: pipe-A-eDP-1
[596.826532] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.509s)
[596.839077] Starting dynamic subtest: pipe-B-eDP-1
[598.250106] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.421s)
[598.259418] Starting dynamic subtest: pipe-C-eDP-1
[599.652388] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.401s)
[599.661820] Starting dynamic subtest: pipe-D-eDP-1
[601.057080] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.402s)
[601.059471] Subtest simple-lease: SUCCESS (4.740s)
[601.863944] [128/133] (421s left) perf (polling-small-buf)
[601.962032] Starting subtest: polling-small-buf
[602.054326] Subtest polling-small-buf: SUCCESS (0.089s)
[602.107370] [129/133] (420s left) gem_softpin (allocator-basic)
[602.168382] Starting subtest: allocator-basic
[602.236989] Subtest allocator-basic: SUCCESS (0.067s)
[602.355270] [130/133] (420s left) i915_drm_fdinfo (virtual-busy-idle-all)
[602.401298] Starting subtest: virtual-busy-idle-all
[602.405650] Subtest virtual-busy-idle-all: SKIP (0.000s)
[602.466443] [131/133] (420s left) gem_exec_balancer (nohangcheck)
[602.735631] Starting subtest: nohangcheck
[603.662904] Subtest nohangcheck: SUCCESS (0.928s)
[603.793432] [132/133] (419s left) kms_cursor_legacy (torture-bo)
[603.916706] Starting subtest: torture-bo
[603.918618] Starting dynamic subtest: pipe-A
[610.293626] Dynamic subtest pipe-A: SUCCESS (6.374s)
[610.311300] Starting dynamic subtest: pipe-B
[617.259228] Dynamic subtest pipe-B: SUCCESS (6.964s)
[617.268977] Starting dynamic subtest: pipe-C
[624.858596] Dynamic subtest pipe-C: SUCCESS (7.596s)
[624.874080] Starting dynamic subtest: pipe-D
[631.344497] Dynamic subtest pipe-D: SUCCESS (6.484s)
[631.359556] Starting dynamic subtest: all-pipes
[639.228852] Dynamic subtest all-pipes: SUCCESS (7.881s)
[639.231422] Subtest torture-bo: SUCCESS (35.310s)
[639.387951] [133/133] (383s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[639.511260] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[639.515742] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[639.604112] Closing watchdogs
results: parsing output: 0/ for test: api_intel_bb
results: parsing output: 1/ for test: kms_plane_lowres
results: parsing output: 2/ for test: core_auth
results: parsing output: 3/ for test: kms_psr2_sf
results: parsing output: 4/ for test: perf_pmu
results: parsing output: 5/ for test: kms_flip_scaled_crc
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_chamelium_hpd
results: parsing output: 8/ for test: gem_unfence_active_buffers
results: parsing output: 9/ for test: gem_exec_reloc
results: parsing output: 10/ for test: kms_rotation_crc
results: parsing output: 11/ for test: kms_pipe_crc_basic
results: parsing output: 12/ for test: prime_mmap_kms
results: parsing output: 13/ for test: gem_render_copy
results: parsing output: 14/ for test: kms_big_fb
results: parsing output: 15/ for test: gem_busy
results: parsing output: 16/ for test: kms_joiner
results: parsing output: 17/ for test: kms_psr
results: parsing output: 18/ for test: kms_flip_scaled_crc
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: gem_eio
results: parsing output: 21/ for test: kms_cursor_legacy
results: parsing output: 22/ for test: perf_pmu
results: parsing output: 23/ for test: kms_psr
results: parsing output: 24/ for test: kms_properties
results: parsing output: 25/ for test: gem_softpin
results: parsing output: 26/ for test: kms_vblank
results: parsing output: 27/ for test: gem_render_copy
results: parsing output: 28/ for test: kms_ccs
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_vblank
results: parsing output: 32/ for test: kms_psr_stress_test
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_exec_fence
results: parsing output: 35/ for test: kms_addfb_basic
results: parsing output: 36/ for test: gem_ctx_persistence
results: parsing output: 37/ for test: kms_big_fb
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: gem_exec_reloc
results: parsing output: 40/ for test: kms_hdr
results: parsing output: 41/ for test: gem_exec_balancer
results: parsing output: 42/ for test: kms_content_protection
results: parsing output: 43/ for test: kms_chamelium_color
results: parsing output: 44/ for test: gem_exec_store
results: parsing output: 45/ for test: kms_async_flips
results: parsing output: 46/ for test: i915_query
results: parsing output: 47/ for test: kms_psr2_sf
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_plane
results: parsing output: 50/ for test: kms_cursor_crc
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: gem_ctx_param
results: parsing output: 53/ for test: kms_properties
results: parsing output: 54/ for test: gem_mmap_gtt
results: parsing output: 55/ for test: kms_cursor_legacy
results: parsing output: 56/ for test: kms_big_fb
results: parsing output: 57/ for test: gem_exec_schedule
results: parsing output: 58/ for test: syncobj_timeline
results: parsing output: 59/ for test: kms_getfb
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: kms_pm_rpm
results: parsing output: 62/ for test: gem_exec_schedule
results: parsing output: 63/ for test: gen9_exec_parse
results: parsing output: 64/ for test: kms_cursor_edge_walk
results: parsing output: 65/ for test: kms_getfb
results: parsing output: 66/ for test: kms_atomic_transition
results: parsing output: 67/ for test: kms_ccs
results: parsing output: 68/ for test: perf
results: parsing output: 69/ for test: kms_big_fb
results: parsing output: 70/ for test: kms_plane_scaling
results: parsing output: 71/ for test: kms_vblank
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: gem_exec_parallel
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: kms_addfb_basic
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_ccs
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: gem_lmem_swapping
results: parsing output: 81/ for test: kms_color
results: parsing output: 82/ for test: gem_mmap_offset
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_userptr_blits
results: parsing output: 85/ for test: gem_ctx_shared
results: parsing output: 86/ for test: kms_addfb_basic
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: vgem_basic
results: parsing output: 89/ for test: api_intel_allocator
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_chamelium_frames
results: parsing output: 92/ for test: syncobj_timeline
results: parsing output: 93/ for test: i915_pm_rpm
results: parsing output: 94/ for test: gen9_exec_parse
results: parsing output: 95/ for test: gem_pxp
results: parsing output: 96/ for test: gem_eio
results: parsing output: 97/ for test: kms_frontbuffer_tracking
results: parsing output: 98/ for test: kms_big_fb
results: parsing output: 99/ for test: gem_mmap_gtt
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: kms_psr
results: parsing output: 102/ for test: kms_psr
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: kms_big_fb
results: parsing output: 107/ for test: syncobj_eventfd
results: parsing output: 108/ for test: core_setmaster
results: parsing output: 109/ for test: kms_pm_dc
results: parsing output: 110/ for test: kms_async_flips
results: parsing output: 111/ for test: gem_create
results: parsing output: 112/ for test: gem_ctx_isolation
results: parsing output: 113/ for test: kms_ccs
results: parsing output: 114/ for test: kms_frontbuffer_tracking
results: parsing output: 115/ for test: kms_big_fb
results: parsing output: 116/ for test: gem_render_copy
results: parsing output: 117/ for test: kms_feature_discovery
results: parsing output: 118/ for test: sysfs_preempt_timeout
results: parsing output: 119/ for test: kms_plane_scaling
results: parsing output: 120/ for test: kms_cursor_legacy
results: parsing output: 121/ for test: gem_eio
results: parsing output: 122/ for test: kms_psr
results: parsing output: 123/ for test: gem_userptr_blits
results: parsing output: 124/ for test: kms_cursor_crc
results: parsing output: 125/ for test: syncobj_wait
results: parsing output: 126/ for test: kms_lease
results: parsing output: 127/ for test: perf
results: parsing output: 128/ for test: gem_softpin
results: parsing output: 129/ for test: i915_drm_fdinfo
results: parsing output: 130/ for test: gem_exec_balancer
results: parsing output: 131/ for test: kms_cursor_legacy
results: parsing output: 132/ for test: kms_frontbuffer_tracking
Done.