[64.082958] Initializing watchdogs
[64.083010]   /dev/watchdog0
[64.096599] [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]
[64.101186] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[64.103401] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[64.106620] [001/134] (960s left) kms_vblank (ts-continuation-modeset-rpm)
[64.234523] Starting subtest: ts-continuation-modeset-rpm
[64.236328] Starting dynamic subtest: pipe-A-eDP-1
[66.825064] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.591s)
[66.838383] Starting dynamic subtest: pipe-D-eDP-1
[69.063101] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.235s)
[69.065442] Subtest ts-continuation-modeset-rpm: SUCCESS (4.829s)
[69.960416] [002/134] (954s left) gem_exec_reloc (basic-wc-read-noreloc)
[70.020249] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[70.080741] [003/134] (954s left) kms_psr (fbc-psr2-no-drrs)
[70.181125] Starting subtest: fbc-psr2-no-drrs
[70.183075] Starting dynamic subtest: eDP-1
[70.187126] Dynamic subtest eDP-1: SKIP (0.000s)
[70.191110] Subtest fbc-psr2-no-drrs: SKIP (0.000s)
[70.262924] [004/134] (954s left) kms_psr (psr-sprite-plane-onoff)
[70.366814] Starting subtest: psr-sprite-plane-onoff
[70.369093] Starting dynamic subtest: eDP-1
[72.092628] Dynamic subtest eDP-1: SUCCESS (1.736s)
[72.095059] Subtest psr-sprite-plane-onoff: SUCCESS (1.737s)
[72.935670] [005/134] (951s left) kms_chamelium_color (ctm-max)
[73.037613] Subtest ctm-max: SKIP (0.000s)
[73.081380] [006/134] (951s left) gem_reg_read (timestamp-monotonic)
[73.148383] Starting subtest: timestamp-monotonic
[77.232072] Subtest timestamp-monotonic: SUCCESS (4.083s)
[77.310037] [007/134] (947s left) kms_plane_cursor (viewport)
[77.412766] Starting subtest: viewport
[77.417083] Starting dynamic subtest: pipe-A-eDP-1-size-64
[80.022802] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.611s)
[80.079082] Starting dynamic subtest: pipe-A-eDP-1-size-128
[82.647279] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.572s)
[82.705428] Starting dynamic subtest: pipe-A-eDP-1-size-256
[85.348900] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.649s)
[85.702244] Starting dynamic subtest: pipe-B-eDP-1-size-64
[88.968052] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.274s)
[89.025659] Starting dynamic subtest: pipe-B-eDP-1-size-128
[91.691504] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.672s)
[91.751351] Starting dynamic subtest: pipe-B-eDP-1-size-256
[94.359187] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.616s)
[94.706409] Starting dynamic subtest: pipe-C-eDP-1-size-64
[97.659268] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.962s)
[97.701320] Starting dynamic subtest: pipe-C-eDP-1-size-128
[99.926561] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.235s)
[99.963246] Starting dynamic subtest: pipe-C-eDP-1-size-256
[102.148285] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.191s)
[102.476410] Starting dynamic subtest: pipe-D-eDP-1-size-64
[105.420875] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.952s)
[105.463298] Starting dynamic subtest: pipe-D-eDP-1-size-128
[107.665241] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.213s)
[107.701189] Starting dynamic subtest: pipe-D-eDP-1-size-256
[109.909326] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.214s)
[110.227223] Subtest viewport: SUCCESS (32.814s)
[111.057677] [008/134] (913s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[111.170326] Starting subtest: y-tiled-64bpp-rotate-0
[111.174389] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[111.287432] [009/134] (913s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[111.409899] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[111.414114] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[111.511757] [010/134] (913s left) kms_addfb_basic (invalid-get-prop-any)
[111.557112] Starting subtest: invalid-get-prop-any
[111.559241] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[111.716141] [011/134] (913s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[111.813056] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[111.815261] Starting dynamic subtest: pipe-A
[111.833742] Dynamic subtest pipe-A: SKIP (0.017s)
[111.841082] Starting dynamic subtest: pipe-B
[111.867571] Dynamic subtest pipe-B: SKIP (0.032s)
[111.874653] Starting dynamic subtest: pipe-C
[111.901066] Dynamic subtest pipe-C: SKIP (0.032s)
[111.909725] Starting dynamic subtest: pipe-D
[111.935305] Dynamic subtest pipe-D: SKIP (0.034s)
[111.939248] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.119s)
[112.067153] [012/134] (912s left) gem_exec_params (invalid-fence-in-submit)
[112.139912] Starting subtest: invalid-fence-in-submit
[112.141945] Subtest invalid-fence-in-submit: SUCCESS (0.003s)
[112.289967] [013/134] (912s left) kms_big_fb (4-tiled-64bpp-rotate-270)
[112.402965] Starting subtest: 4-tiled-64bpp-rotate-270
[112.422244] Subtest 4-tiled-64bpp-rotate-270: SKIP (0.019s)
[112.568432] [014/134] (912s left) dmabuf_sync_file (import-multiple-read-only)
[112.666644] Starting subtest: import-multiple-read-only
[112.672441] Subtest import-multiple-read-only: SUCCESS (0.006s)
[112.733711] [FACT dmabuf_sync_file (import-multiple-read-only)] new: kernel.kmod_is_loaded.vgem: true
[112.736645] [015/134] (912s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[112.830155] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[112.833878] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[112.932799] [016/134] (912s left) gem_exec_reloc (basic-cpu)
[112.998805] Subtest basic-cpu: SKIP (0.000s)
[113.087060] [017/134] (912s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[113.185630] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[113.189185] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[113.304829] [018/134] (911s left) kms_frontbuffer_tracking (psr-abgr161616f-draw-render)
[113.409946] Starting subtest: psr-abgr161616f-draw-render
[113.413628] Subtest psr-abgr161616f-draw-render: SKIP (0.000s)
[113.540387] [019/134] (911s left) gem_mmap_offset (bad-object)
[113.604077] Starting subtest: bad-object
[113.606063] Subtest bad-object: SUCCESS (0.001s)
[113.741957] [020/134] (911s left) gem_render_tiled_blits (basic)
[113.809041] Subtest basic: SKIP (0.000s)
[113.874350] [021/134] (911s left) sw_sync (sync_multi_timeline_wait)
[113.910215] Starting subtest: sync_multi_timeline_wait
[113.912087] Subtest sync_multi_timeline_wait: SUCCESS (0.000s)
[113.974954] [022/134] (911s left) dumb_buffer (map-valid)
[114.035873] Starting subtest: map-valid
[114.037866] Subtest map-valid: SUCCESS (0.000s)
[114.133626] [023/134] (911s left) gem_userptr_blits (unsync-unmap-after-close)
[114.201169] Subtest unsync-unmap-after-close: SKIP (0.000s)
[114.345533] [024/134] (911s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[114.483535] Starting subtest: modeset-atomic-cursor-hotspot
[114.487318] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[114.636624] [025/134] (910s left) kms_psr2_su (frontbuffer-xrgb8888)
[114.702137] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[114.768035] [026/134] (910s left) kms_color (legacy-gamma-reset)
[114.865333] Starting subtest: legacy-gamma-reset
[114.867073] Starting dynamic subtest: pipe-A-eDP-1
[115.242653] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.378s)
[115.250104] Starting dynamic subtest: pipe-B-eDP-1
[115.255055] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.012s)
[115.262097] Starting dynamic subtest: pipe-C-eDP-1
[115.266896] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.012s)
[115.273948] Starting dynamic subtest: pipe-D-eDP-1
[115.280848] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.013s)
[115.283199] Subtest legacy-gamma-reset: SUCCESS (0.418s)
[116.062770] [027/134] (909s left) gem_exec_params (dr4-dirt)
[116.135100] Starting subtest: DR4-dirt
[116.137412] Subtest DR4-dirt: SUCCESS (0.000s)
[116.298424] [028/134] (909s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[116.370078] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[116.450190] [029/134] (909s left) gem_exec_reloc (basic-gtt-wc-active)
[116.517560] Subtest basic-gtt-wc-active: SKIP (0.000s)
[116.605458] [030/134] (909s left) gem_mmap_wc (pf-nonblock)
[116.664041] Subtest pf-nonblock: SKIP (0.000s)
[116.732980] [031/134] (909s left) gem_exec_params (rsvd2-dirt)
[116.789479] Starting subtest: rsvd2-dirt
[116.793448] Subtest rsvd2-dirt: SKIP (0.000s)
[116.964010] [032/134] (908s left) kms_flip (flip-vs-expired-vblank-interruptible)
[117.061038] Starting subtest: flip-vs-expired-vblank-interruptible
[117.065201] Starting dynamic subtest: A-eDP1
[125.783309] Dynamic subtest A-eDP1: SUCCESS (8.685s)
[125.792959] Starting dynamic subtest: B-eDP1
[134.422547] Dynamic subtest B-eDP1: SUCCESS (8.637s)
[134.429574] Starting dynamic subtest: C-eDP1
[143.052243] Dynamic subtest C-eDP1: SUCCESS (8.618s)
[143.061424] Starting dynamic subtest: D-eDP1
[151.672314] Dynamic subtest D-eDP1: SUCCESS (8.617s)
[151.674311] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.567s)
[152.818330] [033/134] (873s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[152.915865] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[152.917861] Starting dynamic subtest: pipe-A-eDP-1
[152.938743] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.021s)
[152.947335] Starting dynamic subtest: pipe-B-eDP-1
[152.967330] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.022s)
[152.974630] Starting dynamic subtest: pipe-C-eDP-1
[152.993941] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.021s)
[153.001061] Starting dynamic subtest: pipe-D-eDP-1
[153.021368] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.020s)
[153.023955] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.093s)
[155.280407] [034/134] (870s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[155.388422] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip
[156.512468] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SUCCESS (1.123s)
[156.656881] [035/134] (869s left) i915_pm_rps (waitboost)
[156.712212] Starting subtest: waitboost
[162.789978] Subtest waitboost: SUCCESS (6.076s)
[162.940423] [036/134] (863s left) gem_spin_batch (legacy-resubmit)
[162.998466] Starting subtest: legacy-resubmit
[163.000808] Starting dynamic subtest: default
[163.008656] Dynamic subtest default: SUCCESS (0.010s)
[163.018888] Starting dynamic subtest: render
[163.021201] Dynamic subtest render: SUCCESS (0.002s)
[163.028375] Starting dynamic subtest: bsd
[163.030289] Dynamic subtest bsd: SUCCESS (0.003s)
[163.037747] Starting dynamic subtest: bsd1
[163.039673] Dynamic subtest bsd1: SUCCESS (0.003s)
[163.046463] Starting dynamic subtest: bsd2
[163.048389] Dynamic subtest bsd2: SUCCESS (0.001s)
[163.055156] Starting dynamic subtest: blt
[163.057084] Dynamic subtest blt: SUCCESS (0.003s)
[163.063762] Starting dynamic subtest: vebox
[163.065736] Dynamic subtest vebox: SUCCESS (0.005s)
[163.067657] Subtest legacy-resubmit: SUCCESS (0.033s)
[163.308797] [037/134] (862s left) gem_render_copy (yf-tiled-to-vebox-linear)
[163.384430] Starting subtest: yf-tiled-to-vebox-linear
[163.388993] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[163.586361] [038/134] (862s left) gem_pxp (reject-modify-context-protection-off-2)
[163.716143] Starting subtest: reject-modify-context-protection-off-2
[163.718580] Subtest reject-modify-context-protection-off-2: SUCCESS (0.002s)
[163.863413] [039/134] (862s left) gem_ctx_persistence (legacy-engines-hostile)
[163.933599] Starting subtest: legacy-engines-hostile
[163.935932] Starting dynamic subtest: render
[164.057965] Dynamic subtest render: SUCCESS (0.123s)
[164.068374] Starting dynamic subtest: bsd1
[164.175661] Dynamic subtest bsd1: SUCCESS (0.115s)
[164.188410] Starting dynamic subtest: bsd2
[164.302940] Dynamic subtest bsd2: SUCCESS (0.124s)
[164.313077] Starting dynamic subtest: blt
[164.386721] Dynamic subtest blt: SUCCESS (0.081s)
[164.394837] Starting dynamic subtest: vebox
[164.515311] Dynamic subtest vebox: SUCCESS (0.127s)
[164.517250] Subtest legacy-engines-hostile: SUCCESS (0.583s)
[164.664659] [040/134] (861s left) prime_busy (hang)
[164.724077] Starting subtest: hang
[164.725958] Starting dynamic subtest: rcs0
[190.460669] Dynamic subtest rcs0: SUCCESS (25.724s)
[190.467826] Starting dynamic subtest: bcs0
[197.542055] Dynamic subtest bcs0: SUCCESS (7.086s)
[197.549474] Starting dynamic subtest: vcs0
[204.582298] Dynamic subtest vcs0: SUCCESS (7.042s)
[204.589969] Starting dynamic subtest: vcs1
[211.556857] Dynamic subtest vcs1: SUCCESS (6.969s)
[211.565750] Starting dynamic subtest: vecs0
[218.604635] Dynamic subtest vecs0: SUCCESS (7.037s)
[218.614344] Starting dynamic subtest: ccs0
[232.431083] Dynamic subtest ccs0: SUCCESS (13.836s)
[232.433489] Subtest hang: SUCCESS (67.703s)
[232.618643] [041/134] (793s left) sysfs_preempt_timeout (timeout)
[232.678982] Starting subtest: timeout
[232.681310] Starting dynamic subtest: vecs0
[241.878099] Dynamic subtest vecs0: SUCCESS (9.195s)
[241.887525] Starting dynamic subtest: ccs0
[243.137844] Dynamic subtest ccs0: SUCCESS (1.261s)
[243.147761] Starting dynamic subtest: vcs0
[252.904695] Dynamic subtest vcs0: SUCCESS (9.761s)
[252.915021] Starting dynamic subtest: bcs0
[254.192567] Dynamic subtest bcs0: SUCCESS (1.286s)
[254.203479] Starting dynamic subtest: rcs0
[255.550224] Dynamic subtest rcs0: SUCCESS (1.355s)
[255.560544] Starting dynamic subtest: vcs1
[264.939751] Dynamic subtest vcs1: SUCCESS (9.387s)
[264.942269] Subtest timeout: SUCCESS (32.259s)
[265.033239] [042/134] (761s left) syncobj_timeline (single-wait-all-for-submit-available-signaled)
[265.093842] Starting subtest: single-wait-all-for-submit-available-signaled
[265.096326] Subtest single-wait-all-for-submit-available-signaled: SUCCESS (0.000s)
[265.203947] [043/134] (761s left) gem_exec_nop (basic-sequential)
[265.267303] Starting subtest: basic-sequential
[280.410554] Subtest basic-sequential: SUCCESS (15.128s)
[280.633590] [044/134] (745s left) gem_exec_fence (keep-in-fence)
[280.815674] Starting subtest: keep-in-fence
[280.818232] Starting dynamic subtest: rcs0
[280.918070] Dynamic subtest rcs0: SUCCESS (0.103s)
[280.930349] Starting dynamic subtest: bcs0
[280.975475] Dynamic subtest bcs0: SUCCESS (0.056s)
[280.985646] Starting dynamic subtest: vcs0
[282.903625] Dynamic subtest vcs0: SUCCESS (1.925s)
[282.915120] Starting dynamic subtest: vcs1
[284.885665] Dynamic subtest vcs1: SUCCESS (1.979s)
[284.895818] Starting dynamic subtest: vecs0
[286.870793] Dynamic subtest vecs0: SUCCESS (1.982s)
[286.880897] Starting dynamic subtest: ccs0
[287.109541] Dynamic subtest ccs0: SUCCESS (0.238s)
[287.112238] Subtest keep-in-fence: SUCCESS (6.297s)
[289.033316] [045/134] (737s left) gem_exec_whisper (basic-fds-forked-all)
[289.118100] Starting subtest: basic-fds-forked-all
[310.840513] Subtest basic-fds-forked-all: SUCCESS (21.718s)
[311.041907] [046/134] (715s left) kms_ccs (crc-primary-basic-y-tiled-ccs)
[311.145758] Starting subtest: crc-primary-basic-y-tiled-ccs
[311.147946] Starting dynamic subtest: pipe-A-eDP-1
[311.169351] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[311.178518] Starting dynamic subtest: pipe-B-eDP-1
[311.199336] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[311.207260] Starting dynamic subtest: pipe-C-eDP-1
[311.227862] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[311.236158] Starting dynamic subtest: pipe-D-eDP-1
[311.257166] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[311.261355] Subtest crc-primary-basic-y-tiled-ccs: SKIP (0.006s)
[313.408832] [047/134] (713s left) kms_cursor_crc (cursor-random-max-size)
[313.710894] Starting subtest: cursor-random-max-size
[313.717596] Subtest cursor-random-max-size: SKIP (0.000s)
[313.855987] [048/134] (712s left) gem_caching (reads)
[313.922290] Subtest reads: SKIP (0.000s)
[314.015518] [049/134] (712s left) gem_eio (in-flight-internal-10ms)
[314.244647] Starting subtest: in-flight-internal-10ms
[315.998327] Subtest in-flight-internal-10ms: SUCCESS (1.753s)
[316.161745] [050/134] (710s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[316.262289] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[316.266596] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[316.375795] [051/134] (710s left) kms_content_protection (mei-interface)
[316.518473] Starting subtest: mei-interface
[316.523032] Subtest mei-interface: SKIP (0.001s)
[316.664748] [052/134] (710s left) gem_exec_schedule (preempt-contexts)
[316.731600] Starting subtest: preempt-contexts
[316.733731] Starting dynamic subtest: rcs0
[316.780155] Dynamic subtest rcs0: SUCCESS (0.053s)
[316.790552] Starting dynamic subtest: bcs0
[316.828803] Dynamic subtest bcs0: SUCCESS (0.051s)
[316.838172] Starting dynamic subtest: vcs0
[316.876796] Dynamic subtest vcs0: SUCCESS (0.047s)
[316.885918] Starting dynamic subtest: vcs1
[316.921094] Dynamic subtest vcs1: SUCCESS (0.043s)
[316.929757] Starting dynamic subtest: vecs0
[316.962554] Dynamic subtest vecs0: SUCCESS (0.041s)
[316.971991] Starting dynamic subtest: ccs0
[317.013857] Dynamic subtest ccs0: SUCCESS (0.050s)
[317.015820] Subtest preempt-contexts: SUCCESS (0.291s)
[318.037122] [053/134] (708s left) kms_joiner (basic-big-joiner)
[318.144466] Starting subtest: basic-big-joiner
[318.148475] Subtest basic-big-joiner: SKIP (0.000s)
[318.236123] [054/134] (708s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-blt)
[318.347107] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-blt
[318.351276] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[318.466146] [055/134] (708s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-move)
[318.570104] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-move
[318.574402] Subtest fbcpsr-2p-scndscrn-spr-indfb-move: SKIP (0.000s)
[318.670867] [056/134] (708s left) gem_userptr_blits (sync-overlap)
[318.726029] Starting subtest: sync-overlap
[318.728030] Subtest sync-overlap: SUCCESS (0.000s)
[318.864538] [057/134] (708s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff)
[318.950809] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff
[318.954818] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[319.085878] [058/134] (707s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[319.168938] Starting subtest: async-flip-with-page-flip-events-linear
[319.541919] Starting dynamic subtest: pipe-A-eDP-1
[322.323418] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.729s)
[322.631854] Starting dynamic subtest: pipe-B-eDP-1
[325.449039] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.771s)
[325.766189] Starting dynamic subtest: pipe-C-eDP-1
[328.573252] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.760s)
[328.892704] Starting dynamic subtest: pipe-D-eDP-1
[331.710323] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.773s)
[331.712845] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.490s)
[332.851134] [059/134] (694s left) kms_flip (2x-flip-vs-panning-vs-hang)
[332.952825] Starting subtest: 2x-flip-vs-panning-vs-hang
[332.964153] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.011s)
[333.070358] [060/134] (693s left) kms_cursor_crc (cursor-onscreen-32x10)
[333.230154] Starting subtest: cursor-onscreen-32x10
[333.280159] Subtest cursor-onscreen-32x10: SKIP (0.044s)
[333.577130] [061/134] (693s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0)
[333.681312] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0
[333.685220] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SKIP (0.000s)
[333.796214] [062/134] (693s left) kms_selftest (drm_format)
[333.834218] Starting subtest: drm_format
[333.918839] Starting dynamic subtest: drm_test_format_block_width_invalid
[334.063776] Dynamic subtest drm_test_format_block_width_invalid: SUCCESS (0.144s)
[334.073812] Starting dynamic subtest: drm_test_format_block_width_one_plane
[334.218800] Dynamic subtest drm_test_format_block_width_one_plane: SUCCESS (0.154s)
[334.229081] Starting dynamic subtest: drm_test_format_block_width_two_plane
[334.377643] Dynamic subtest drm_test_format_block_width_two_plane: SUCCESS (0.159s)
[334.392293] Starting dynamic subtest: drm_test_format_block_width_three_plane
[334.523324] Dynamic subtest drm_test_format_block_width_three_plane: SUCCESS (0.144s)
[334.533633] Starting dynamic subtest: drm_test_format_block_width_tiled
[334.676871] Dynamic subtest drm_test_format_block_width_tiled: SUCCESS (0.153s)
[334.686793] Starting dynamic subtest: drm_test_format_block_height_invalid
[334.837652] Dynamic subtest drm_test_format_block_height_invalid: SUCCESS (0.161s)
[334.847559] Starting dynamic subtest: drm_test_format_block_height_one_plane
[334.990200] Dynamic subtest drm_test_format_block_height_one_plane: SUCCESS (0.151s)
[335.005090] Starting dynamic subtest: drm_test_format_block_height_two_plane
[335.180893] Dynamic subtest drm_test_format_block_height_two_plane: SUCCESS (0.190s)
[335.191429] Starting dynamic subtest: drm_test_format_block_height_three_plane
[335.333223] Dynamic subtest drm_test_format_block_height_three_plane: SUCCESS (0.151s)
[335.342202] Starting dynamic subtest: drm_test_format_block_height_tiled
[335.438578] Dynamic subtest drm_test_format_block_height_tiled: SUCCESS (0.107s)
[335.446847] Starting dynamic subtest: drm_test_format_min_pitch_invalid
[335.577867] Dynamic subtest drm_test_format_min_pitch_invalid: SUCCESS (0.135s)
[335.587923] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_8bpp
[335.664266] Dynamic subtest drm_test_format_min_pitch_one_plane_8bpp: SUCCESS (0.084s)
[335.674132] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_16bpp
[335.802947] Dynamic subtest drm_test_format_min_pitch_one_plane_16bpp: SUCCESS (0.137s)
[335.812952] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_24bpp
[335.960992] Dynamic subtest drm_test_format_min_pitch_one_plane_24bpp: SUCCESS (0.156s)
[335.970907] Starting dynamic subtest: drm_test_format_min_pitch_one_plane_32bpp
[336.111875] Dynamic subtest drm_test_format_min_pitch_one_plane_32bpp: SUCCESS (0.151s)
[336.121644] Starting dynamic subtest: drm_test_format_min_pitch_two_plane
[336.213115] Dynamic subtest drm_test_format_min_pitch_two_plane: SUCCESS (0.101s)
[336.222465] Starting dynamic subtest: drm_test_format_min_pitch_three_plane_8bpp
[336.341398] Dynamic subtest drm_test_format_min_pitch_three_plane_8bpp: SUCCESS (0.126s)
[336.351333] Starting dynamic subtest: drm_test_format_min_pitch_tiled
[336.485054] Dynamic subtest drm_test_format_min_pitch_tiled: SUCCESS (0.143s)
[336.487085] Subtest drm_format: SUCCESS (2.647s)
[336.680307] [063/134] (690s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[336.807430] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[368.173537] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (31.363s)
[369.008662] [064/134] (658s left) kms_fb_coherency (memset-crc)
[369.120207] Starting subtest: memset-crc
[369.122106] Starting dynamic subtest: mmap-offset-wc
[369.530865] Dynamic subtest mmap-offset-wc: SUCCESS (0.410s)
[369.829296] Starting dynamic subtest: mmap-offset-uc
[376.042900] Dynamic subtest mmap-offset-uc: SUCCESS (6.221s)
[376.333777] Subtest memset-crc: SUCCESS (7.215s)
[377.163600] [065/134] (650s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted-signa
[377.228638] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted-signaled
[377.230592] Subtest multi-wait-for-submit-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[377.352216] [066/134] (649s left) gem_ctx_param (invalid-get-ringsize)
[377.407949] Starting subtest: invalid-get-ringsize
[377.410326] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[377.544212] [067/134] (649s left) api_intel_allocator (execbuf-with-allocator)
[377.600928] Starting subtest: execbuf-with-allocator
[377.603468] Subtest execbuf-with-allocator: SUCCESS (0.005s)
[377.760866] [068/134] (649s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[377.855960] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[385.038093] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (7.184s)
[385.210378] [069/134] (642s left) kms_psr (psr2-cursor-render)
[385.306679] Starting subtest: psr2-cursor-render
[385.308922] Starting dynamic subtest: eDP-1
[386.966265] Dynamic subtest eDP-1: SUCCESS (1.659s)
[386.968610] Subtest psr2-cursor-render: SUCCESS (1.660s)
[387.795124] [070/134] (639s left) kms_plane (pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping)
[387.902947] Starting subtest: pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping
[387.929979] Starting dynamic subtest: pipe-A-plane-0
[387.934651] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[387.945326] Starting dynamic subtest: pipe-A-plane-3
[387.949904] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[387.958689] Starting dynamic subtest: pipe-A-plane-5
[387.963268] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[389.054689] Starting dynamic subtest: pipe-B-plane-0
[389.059246] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[389.067903] Starting dynamic subtest: pipe-B-plane-3
[389.072575] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[389.081232] Starting dynamic subtest: pipe-B-plane-5
[389.085384] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[389.376060] Subtest pixel-format-4-tiled-dg2-mc-ccs-modifier-source-clamping: SKIP (1.470s)
[390.239805] [071/134] (637s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite)
[390.357417] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite
[397.199449] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.846s)
[397.327270] [072/134] (630s left) kms_pm_rpm (drm-resources-equal)
[399.053826] Starting subtest: drm-resources-equal
[404.233868] Subtest drm-resources-equal: SUCCESS (5.181s)
[404.336499] [073/134] (623s left) kms_cursor_edge_walk (64x64-top-edge)
[404.435019] Starting subtest: 64x64-top-edge
[404.437340] Starting dynamic subtest: pipe-A-eDP-1
[407.921307] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.485s)
[407.931337] Starting dynamic subtest: pipe-D-eDP-1
[411.038208] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.116s)
[411.040481] Subtest 64x64-top-edge: SUCCESS (6.603s)
[411.864870] [074/134] (615s left) syncobj_wait (invalid-single-wait-unsubmitted)
[411.933286] Starting subtest: invalid-single-wait-unsubmitted
[411.935654] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[412.067789] [075/134] (615s left) kms_psr (fbc-psr-cursor-plane-move)
[412.165504] Starting subtest: fbc-psr-cursor-plane-move
[412.167641] Starting dynamic subtest: eDP-1
[412.171593] Dynamic subtest eDP-1: SKIP (0.000s)
[412.175585] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[412.251513] [076/134] (615s left) gen9_exec_parse (valid-registers)
[412.300251] Subtest valid-registers: SKIP (0.000s)
[412.367671] [077/134] (615s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc)
[412.466898] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc
[412.469088] Starting dynamic subtest: pipe-A-eDP-1
[413.329496] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.856s)
[413.338895] Starting dynamic subtest: pipe-B-eDP-1
[414.989262] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.655s)
[414.999369] Starting dynamic subtest: pipe-C-eDP-1
[416.479177] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.490s)
[416.490423] Starting dynamic subtest: pipe-D-eDP-1
[418.037304] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.557s)
[418.039464] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.563s)
[419.310445] [078/134] (608s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[419.412207] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[426.310966] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.900s)
[426.473951] [079/134] (601s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-cc-modifier)
[426.560077] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-cc-modifier
[426.585714] Starting dynamic subtest: pipe-A-plane-0
[426.874046] Dynamic subtest pipe-A-plane-0: SUCCESS (0.288s)
[426.883310] Starting dynamic subtest: pipe-A-plane-3
[427.106767] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[427.116286] Starting dynamic subtest: pipe-A-plane-5
[427.121874] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[428.189407] Starting dynamic subtest: pipe-B-plane-0
[428.491438] Dynamic subtest pipe-B-plane-0: SUCCESS (0.311s)
[428.500231] Starting dynamic subtest: pipe-B-plane-3
[428.724835] Dynamic subtest pipe-B-plane-3: SUCCESS (0.233s)
[428.733727] Starting dynamic subtest: pipe-B-plane-5
[428.737833] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[429.043330] Subtest pixel-format-4-tiled-mtl-rc-ccs-cc-modifier: SUCCESS (2.486s)
[429.887929] [080/134] (597s left) syncobj_timeline (invalid-signal-one-illegal-handle)
[429.950680] Starting subtest: invalid-signal-one-illegal-handle
[429.952998] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[430.088574] [081/134] (597s left) gem_lmem_swapping (heavy-verify-multi)
[430.128494] Subtest heavy-verify-multi: SKIP (0.000s)
[430.171573] [082/134] (597s left) i915_pm_freq_api (freq-reset-multiple)
[430.243941] Starting subtest: freq-reset-multiple
[430.246276] Starting dynamic subtest: gt0
[440.250092] Dynamic subtest gt0: SUCCESS (10.003s)
[440.260013] Starting dynamic subtest: gt1
[448.817306] Dynamic subtest gt1: SUCCESS (8.563s)
[448.820139] Subtest freq-reset-multiple: SUCCESS (18.568s)
[448.935467] [083/134] (579s left) gem_exec_reloc (basic-softpin)
[449.000558] Subtest basic-softpin: SKIP (0.000s)
[449.085284] [084/134] (578s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[449.202407] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[449.206399] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[449.334517] [085/134] (578s left) syncobj_basic (bad-destroy-pad)
[449.404848] Starting subtest: bad-destroy-pad
[449.406843] Subtest bad-destroy-pad: SUCCESS (0.000s)
[449.517875] [086/134] (578s left) kms_flip (basic-flip-vs-dpms)
[449.613260] Starting subtest: basic-flip-vs-dpms
[449.615569] Starting dynamic subtest: A-eDP1
[452.158996] Dynamic subtest A-eDP1: SUCCESS (2.543s)
[452.169924] Starting dynamic subtest: B-eDP1
[454.333271] Dynamic subtest B-eDP1: SUCCESS (2.177s)
[454.344462] Starting dynamic subtest: C-eDP1
[456.397776] Dynamic subtest C-eDP1: SUCCESS (2.062s)
[456.407312] Starting dynamic subtest: D-eDP1
[458.486592] Dynamic subtest D-eDP1: SUCCESS (2.088s)
[458.488955] Subtest basic-flip-vs-dpms: SUCCESS (8.878s)
[459.300138] [087/134] (568s left) gem_ctx_sseu (invalid-sseu)
[459.367395] Subtest invalid-sseu: SKIP (0.000s)
[459.431382] [088/134] (568s left) perf_pmu (busy-hang)
[459.467520] Starting subtest: busy-hang
[459.469700] Starting dynamic subtest: rcs0
[460.736246] Dynamic subtest rcs0: SUCCESS (1.269s)
[460.747495] Starting dynamic subtest: bcs0
[461.979033] Dynamic subtest bcs0: SUCCESS (1.241s)
[461.990828] Starting dynamic subtest: vcs0
[464.409241] Dynamic subtest vcs0: SUCCESS (2.428s)
[464.423773] Starting dynamic subtest: vcs1
[466.391030] Dynamic subtest vcs1: SUCCESS (1.979s)
[466.401826] Starting dynamic subtest: vecs0
[468.440207] Dynamic subtest vecs0: SUCCESS (2.047s)
[468.451544] Starting dynamic subtest: ccs0
[469.793245] Dynamic subtest ccs0: SUCCESS (1.348s)
[469.795541] Subtest busy-hang: SUCCESS (10.324s)
[469.914775] [089/134] (558s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[469.975811] Starting subtest: invalid-multi-wait-all-unsubmitted
[469.978604] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[470.077128] [090/134] (558s left) gen9_exec_parse (bb-start-out)
[470.145074] Subtest bb-start-out: SKIP (0.000s)
[470.226354] [091/134] (558s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[470.332640] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[477.702051] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.372s)
[477.892561] [092/134] (550s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-onoff)
[478.012330] Starting subtest: fbc-1p-primscrn-spr-indfb-onoff
[482.055304] Subtest fbc-1p-primscrn-spr-indfb-onoff: SUCCESS (4.047s)
[483.242459] [093/134] (545s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[483.337337] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[483.339306] Starting dynamic subtest: pipe-A-eDP-1
[483.787604] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.450s)
[483.799060] Starting dynamic subtest: pipe-B-eDP-1
[483.819828] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[483.829108] Starting dynamic subtest: pipe-C-eDP-1
[483.848925] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[483.857885] Starting dynamic subtest: pipe-D-eDP-1
[483.877734] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[483.879943] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.465s)
[486.084850] [094/134] (542s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[491.066251] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[491.070677] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.003s)
[491.932425] [095/134] (536s left) i915_query (query-topology-known-pci-ids)
[491.997037] Starting subtest: query-topology-known-pci-ids
[492.001636] Subtest query-topology-known-pci-ids: SKIP (0.000s)
[492.136939] [096/134] (536s left) gem_mmap_gtt (cpuset-basic-small-copy)
[492.187818] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[492.254519] [097/134] (536s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[492.647377] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[493.428192] [098/134] (535s left) kms_chamelium_frames (vga-frame-dump)
[493.810432] Subtest vga-frame-dump: SKIP (0.000s)
[494.654778] [099/134] (534s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[494.773934] Starting subtest: cursorA-vs-flipB-atomic-transitions
[494.778859] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[494.944492] [100/134] (533s left) kms_flip (plain-flip-interruptible)
[495.043719] Starting subtest: plain-flip-interruptible
[495.046025] Starting dynamic subtest: A-eDP1
[496.680531] Dynamic subtest A-eDP1: SUCCESS (1.635s)
[496.697975] Starting dynamic subtest: B-eDP1
[498.307235] Dynamic subtest B-eDP1: SUCCESS (1.624s)
[498.321253] Starting dynamic subtest: C-eDP1
[499.934722] Dynamic subtest C-eDP1: SUCCESS (1.624s)
[499.946133] Starting dynamic subtest: D-eDP1
[501.558392] Dynamic subtest D-eDP1: SUCCESS (1.622s)
[501.563818] Subtest plain-flip-interruptible: SUCCESS (6.518s)
[502.753969] [101/134] (526s left) kms_frontbuffer_tracking (fbcpsr-1p-rte)
[502.860531] Starting subtest: fbcpsr-1p-rte
[509.919672] Subtest fbcpsr-1p-rte: SUCCESS (7.062s)
[510.077416] [102/134] (518s left) kms_atomic (plane-primary-legacy)
[510.176788] Starting subtest: plane-primary-legacy
[510.179090] Starting dynamic subtest: pipe-A-eDP-1
[510.618876] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.441s)
[510.621264] Subtest plane-primary-legacy: SUCCESS (0.443s)
[511.436622] [103/134] (517s left) kms_frontbuffer_tracking (fbc-1p-rte)
[511.541602] Starting subtest: fbc-1p-rte
[517.005871] Subtest fbc-1p-rte: SUCCESS (5.468s)
[518.243083] [104/134] (510s left) kms_pm_rpm (modeset-non-lpsp-stress-no-wait)
[520.072666] Starting subtest: modeset-non-lpsp-stress-no-wait
[520.109205] Subtest modeset-non-lpsp-stress-no-wait: SKIP (0.039s)
[520.542130] [105/134] (508s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[520.646651] Starting subtest: psr-rgb101010-draw-pwrite
[528.318243] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.673s)
[528.453012] [106/134] (500s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[528.551706] Starting subtest: x-tiled-16bpp-rotate-90
[528.571110] Subtest x-tiled-16bpp-rotate-90: SKIP (0.019s)
[528.710938] [107/134] (500s left) gem_userptr_blits (userfault)
[528.784764] Starting subtest: userfault
[528.787072] Subtest userfault: SUCCESS (0.007s)
[528.927601] [108/134] (500s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[533.930838] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[533.933357] Starting dynamic subtest: pipe-A-eDP-1
[534.734383] Dynamic subtest pipe-A-eDP-1: SKIP (0.797s)
[534.744806] Starting dynamic subtest: pipe-B-eDP-1
[535.866442] Dynamic subtest pipe-B-eDP-1: SKIP (1.134s)
[535.870747] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.936s)
[537.062214] [109/134] (492s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[537.199733] Starting subtest: flip-vs-cursor-crc-atomic
[537.903070] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.701s)
[538.786401] [110/134] (490s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-16bpp-ytile-downscaling)
[538.882998] Starting subtest: flip-64bpp-ytile-to-16bpp-ytile-downscaling
[538.887293] Subtest flip-64bpp-ytile-to-16bpp-ytile-downscaling: SKIP (0.000s)
[538.978798] [111/134] (490s left) gem_exec_schedule (semaphore-resolve)
[539.060597] Starting subtest: semaphore-resolve
[539.090892] Subtest semaphore-resolve: SUCCESS (0.038s)
[539.456461] [112/134] (489s left) perf (oa-exponents)
[539.594575] Starting subtest: oa-exponents
[539.596798] Starting dynamic subtest: 0-rcs0
[541.354730] Dynamic subtest 0-rcs0: SUCCESS (1.759s)
[541.364291] Starting dynamic subtest: 1-vcs1
[543.184122] Dynamic subtest 1-vcs1: SUCCESS (1.828s)
[543.186772] Subtest oa-exponents: SUCCESS (3.589s)
[543.233213] [113/134] (486s left) kms_frontbuffer_tracking (psr-suspend)
[543.335076] Starting subtest: psr-suspend
[582.362745] Subtest psr-suspend: SUCCESS (8.192s)
[582.504457] [114/134] (446s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[582.616771] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[582.621327] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[582.730686] [115/134] (446s left) i915_pm_rpm (debugfs-read)
[584.858460] Starting subtest: debugfs-read
[586.016297] Subtest debugfs-read: SUCCESS (1.157s)
[586.405452] [116/134] (443s left) gem_exec_balancer (individual)
[586.467591] Starting subtest: individual
[587.699912] Subtest individual: SUCCESS (1.232s)
[588.047687] [117/134] (441s left) kms_psr (pr-primary-blt)
[588.137934] Starting subtest: pr-primary-blt
[588.142338] Subtest pr-primary-blt: SKIP (0.000s)
[588.227378] [118/134] (441s left) kms_cursor_legacy (basic-flip-after-cursor-atomic)
[588.350678] Starting subtest: basic-flip-after-cursor-atomic
[589.275075] Subtest basic-flip-after-cursor-atomic: SUCCESS (0.921s)
[590.141425] [119/134] (439s left) gem_mmap_wc (read-write-distinct)
[590.210384] Subtest read-write-distinct: SKIP (0.000s)
[590.284512] [120/134] (439s left) kms_lease (lease-uevent)
[590.389503] Starting subtest: lease-uevent
[591.404352] Subtest lease-uevent: SUCCESS (1.015s)
[591.534974] [121/134] (438s left) gem_tiled_swapping (non-threaded)
[591.604626] Subtest non-threaded: SKIP (0.000s)
[591.672014] [122/134] (438s left) syncobj_timeline (etime-multi-wait-all-submitted)
[591.740194] Starting subtest: etime-multi-wait-all-submitted
[591.842683] Subtest etime-multi-wait-all-submitted: SUCCESS (0.103s)
[591.931287] [123/134] (437s left) core_getstats (basic)
[591.986194] Starting subtest: basic
[591.988107] Subtest basic: SUCCESS (0.000s)
[592.110159] [124/134] (437s left) kms_cursor_legacy (cursorb-vs-flipb-legacy)
[592.239972] Starting subtest: cursorB-vs-flipB-legacy
[592.244409] Subtest cursorB-vs-flipB-legacy: SKIP (0.001s)
[592.421009] [125/134] (437s left) kms_properties (get_properties-sanity-atomic)
[592.533232] Starting subtest: get_properties-sanity-atomic
[592.535433] Subtest get_properties-sanity-atomic: SUCCESS (0.002s)
[592.620441] [126/134] (437s left) kms_pm_backlight (bad-brightness)
[592.718140] Starting subtest: bad-brightness
[592.801874] Starting dynamic subtest: eDP-1
[593.111304] Dynamic subtest eDP-1: SUCCESS (0.311s)
[593.113597] Subtest bad-brightness: SUCCESS (0.396s)
[594.000292] [127/134] (435s left) syncobj_timeline (etime-single-wait-all-for-submit-submitted)
[594.062140] Starting subtest: etime-single-wait-all-for-submit-submitted
[594.170968] Subtest etime-single-wait-all-for-submit-submitted: SUCCESS (0.108s)
[594.256111] [128/134] (435s left) gem_exec_balancer (sliced)
[594.319560] Starting subtest: sliced
[594.324190] Subtest sliced: SKIP (0.000s)
[594.644816] [129/134] (435s left) kms_big_fb (linear-16bpp-rotate-90)
[594.750000] Starting subtest: linear-16bpp-rotate-90
[594.771740] Subtest linear-16bpp-rotate-90: SKIP (0.022s)
[594.912186] [130/134] (435s left) perf_pmu (rc6-suspend)
[594.952903] Starting subtest: rc6-suspend
[616.968554] Subtest rc6-suspend: SUCCESS (7.505s)
[617.836475] [131/134] (412s left) perf_pmu (all-busy-idle-check-all)
[617.880671] Starting subtest: all-busy-idle-check-all
[618.583282] Subtest all-busy-idle-check-all: FAIL (0.639s)
[619.338600] [132/134] (410s left) gem_ccs (large-ctrl-surf-copy)
[619.407870] Starting subtest: large-ctrl-surf-copy
[619.411800] Subtest large-ctrl-surf-copy: SKIP (0.000s)
[619.556765] [133/134] (410s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[619.664871] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[619.668767] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[619.782686] [134/134] (410s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[619.887724] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[619.889926] Starting dynamic subtest: pipe-A-default-mode
[622.607588] Dynamic subtest pipe-A-default-mode: SUCCESS (2.715s)
[622.609708] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.716s)
[623.486675] Closing watchdogs
results: parsing output: 0/ for test: kms_vblank
results: parsing output: 1/ for test: gem_exec_reloc
results: parsing output: 2/ for test: kms_psr
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: kms_chamelium_color
results: parsing output: 5/ for test: gem_reg_read
results: parsing output: 6/ for test: kms_plane_cursor
results: parsing output: 7/ for test: kms_big_fb
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: kms_addfb_basic
results: parsing output: 10/ for test: kms_plane_scaling
results: parsing output: 11/ for test: gem_exec_params
results: parsing output: 12/ for test: kms_big_fb
results: parsing output: 13/ for test: dmabuf_sync_file
results: parsing output: 14/ for test: kms_flip_scaled_crc
results: parsing output: 15/ for test: gem_exec_reloc
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: gem_mmap_offset
results: parsing output: 19/ for test: gem_render_tiled_blits
results: parsing output: 20/ for test: sw_sync
results: parsing output: 21/ for test: dumb_buffer
results: parsing output: 22/ for test: gem_userptr_blits
results: parsing output: 23/ for test: kms_cursor_legacy
results: parsing output: 24/ for test: kms_psr2_su
results: parsing output: 25/ for test: kms_color
results: parsing output: 26/ for test: gem_exec_params
results: parsing output: 27/ for test: gem_fenced_exec_thrash
results: parsing output: 28/ for test: gem_exec_reloc
results: parsing output: 29/ for test: gem_mmap_wc
results: parsing output: 30/ for test: gem_exec_params
results: parsing output: 31/ for test: kms_flip
results: parsing output: 32/ for test: kms_ccs
results: parsing output: 33/ for test: kms_big_fb
results: parsing output: 34/ for test: i915_pm_rps
results: parsing output: 35/ for test: gem_spin_batch
results: parsing output: 36/ for test: gem_render_copy
results: parsing output: 37/ for test: gem_pxp
results: parsing output: 38/ for test: gem_ctx_persistence
results: parsing output: 39/ for test: prime_busy
results: parsing output: 40/ for test: sysfs_preempt_timeout
results: parsing output: 41/ for test: syncobj_timeline
results: parsing output: 42/ for test: gem_exec_nop
results: parsing output: 43/ for test: gem_exec_fence
results: parsing output: 44/ for test: gem_exec_whisper
results: parsing output: 45/ for test: kms_ccs
results: parsing output: 46/ for test: kms_cursor_crc
results: parsing output: 47/ for test: gem_caching
results: parsing output: 48/ for test: gem_eio
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_content_protection
results: parsing output: 51/ for test: gem_exec_schedule
results: parsing output: 52/ for test: kms_joiner
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: gem_userptr_blits
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_async_flips
results: parsing output: 58/ for test: kms_flip
results: parsing output: 59/ for test: kms_cursor_crc
results: parsing output: 60/ for test: kms_big_fb
results: parsing output: 61/ for test: kms_selftest
results: parsing output: 62/ for test: kms_cursor_legacy
results: parsing output: 63/ for test: kms_fb_coherency
results: parsing output: 64/ for test: syncobj_timeline
results: parsing output: 65/ for test: gem_ctx_param
results: parsing output: 66/ for test: api_intel_allocator
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: kms_plane
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_pm_rpm
results: parsing output: 72/ for test: kms_cursor_edge_walk
results: parsing output: 73/ for test: syncobj_wait
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: gen9_exec_parse
results: parsing output: 76/ for test: kms_ccs
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_plane
results: parsing output: 79/ for test: syncobj_timeline
results: parsing output: 80/ for test: gem_lmem_swapping
results: parsing output: 81/ for test: i915_pm_freq_api
results: parsing output: 82/ for test: gem_exec_reloc
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: syncobj_basic
results: parsing output: 85/ for test: kms_flip
results: parsing output: 86/ for test: gem_ctx_sseu
results: parsing output: 87/ for test: perf_pmu
results: parsing output: 88/ for test: syncobj_wait
results: parsing output: 89/ for test: gen9_exec_parse
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_ccs
results: parsing output: 93/ for test: kms_psr2_sf
results: parsing output: 94/ for test: i915_query
results: parsing output: 95/ for test: gem_mmap_gtt
results: parsing output: 96/ for test: kms_chamelium_edid
results: parsing output: 97/ for test: kms_chamelium_frames
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_atomic
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_pm_rpm
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: gem_userptr_blits
results: parsing output: 107/ for test: kms_psr2_sf
results: parsing output: 108/ for test: kms_cursor_legacy
results: parsing output: 109/ for test: kms_flip_scaled_crc
results: parsing output: 110/ for test: gem_exec_schedule
results: parsing output: 111/ for test: perf
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: i915_pm_rpm
results: parsing output: 115/ for test: gem_exec_balancer
results: parsing output: 116/ for test: kms_psr
results: parsing output: 117/ for test: kms_cursor_legacy
results: parsing output: 118/ for test: gem_mmap_wc
results: parsing output: 119/ for test: kms_lease
results: parsing output: 120/ for test: gem_tiled_swapping
results: parsing output: 121/ for test: syncobj_timeline
results: parsing output: 122/ for test: core_getstats
results: parsing output: 123/ for test: kms_cursor_legacy
results: parsing output: 124/ for test: kms_properties
results: parsing output: 125/ for test: kms_pm_backlight
results: parsing output: 126/ for test: syncobj_timeline
results: parsing output: 127/ for test: gem_exec_balancer
results: parsing output: 128/ for test: kms_big_fb
results: parsing output: 129/ for test: perf_pmu
results: parsing output: 130/ for test: perf_pmu
results: parsing output: 131/ for test: gem_ccs
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_flip_scaled_crc
Done.