[63.292672] Initializing watchdogs
[63.292720]   /dev/watchdog0
[63.306626] [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]
[63.311129] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[63.313438] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[63.316914] [001/133] (960s left) syncobj_basic (bad-fd-to-handle)
[63.392821] Starting subtest: bad-fd-to-handle
[63.395081] Subtest bad-fd-to-handle: SUCCESS (0.000s)
[63.484139] [002/133] (960s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[63.580621] Starting subtest: fbc-psr2-cursor-mmap-cpu
[63.582734] Starting dynamic subtest: eDP-1
[63.586611] Dynamic subtest eDP-1: SKIP (0.000s)
[63.590590] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[63.665042] [003/133] (960s left) kms_concurrent (multi-plane-atomic-lowres)
[63.759640] Starting subtest: multi-plane-atomic-lowres
[63.761876] Starting dynamic subtest: pipe-A-eDP-1
[64.286374] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.519s)
[64.297681] Starting dynamic subtest: pipe-B-eDP-1
[65.501204] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.207s)
[65.508522] Starting dynamic subtest: pipe-C-eDP-1
[66.744838] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.234s)
[66.753522] Starting dynamic subtest: pipe-D-eDP-1
[67.965712] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.216s)
[67.968087] Subtest multi-plane-atomic-lowres: SUCCESS (4.181s)
[68.797952] [004/133] (955s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-mc-ccs)
[68.893487] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-mc-ccs
[68.895730] Starting dynamic subtest: pipe-A-eDP-1
[68.953048] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[68.959774] Starting dynamic subtest: pipe-B-eDP-1
[69.016124] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[69.024757] Starting dynamic subtest: pipe-C-eDP-1
[69.081293] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[69.090790] Starting dynamic subtest: pipe-D-eDP-1
[69.145599] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[69.149592] Subtest crc-sprite-planes-basic-4-tiled-dg2-mc-ccs: SKIP (0.007s)
[71.153203] [005/133] (952s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[71.277046] Starting subtest: cursorB-vs-flipA-atomic-transitions
[71.282693] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[71.432700] [006/133] (952s left) gem_exec_balancer (semaphore)
[71.499116] Starting subtest: semaphore
[73.318995] Subtest semaphore: SUCCESS (1.810s)
[73.672835] [007/133] (950s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[73.771103] Starting subtest: 4-tiled-32bpp-rotate-270
[73.790697] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[73.917993] [008/133] (950s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy)
[74.030714] Starting subtest: 2x-flip-vs-cursor-legacy
[74.036694] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.001s)
[74.169232] [009/133] (949s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[74.270154] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[74.274096] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[74.370916] [010/133] (949s left) kms_flip (flip-vs-expired-vblank-interruptible)
[74.460571] Starting subtest: flip-vs-expired-vblank-interruptible
[74.462908] Starting dynamic subtest: A-eDP1
[83.196934] Dynamic subtest A-eDP1: SUCCESS (8.692s)
[83.207095] Starting dynamic subtest: B-eDP1
[91.800822] Dynamic subtest B-eDP1: SUCCESS (8.632s)
[91.812456] Starting dynamic subtest: C-eDP1
[100.419739] Dynamic subtest C-eDP1: SUCCESS (8.615s)
[100.429139] Starting dynamic subtest: D-eDP1
[109.095443] Dynamic subtest D-eDP1: SUCCESS (8.627s)
[109.097851] Subtest flip-vs-expired-vblank-interruptible: SUCCESS (34.573s)
[110.213599] [011/133] (913s left) kms_big_fb (y-tiled-32bpp-rotate-270)
[110.313839] Starting subtest: y-tiled-32bpp-rotate-270
[110.318110] Subtest y-tiled-32bpp-rotate-270: SKIP (0.000s)
[110.402176] [012/133] (913s left) kms_invalid_mode (clock-too-high)
[110.488774] Starting subtest: clock-too-high
[110.491069] Starting dynamic subtest: pipe-A-eDP-1
[110.775362] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.289s)
[110.782425] Starting dynamic subtest: pipe-B-eDP-1
[110.786302] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[110.792869] Starting dynamic subtest: pipe-C-eDP-1
[110.797008] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[110.803785] Starting dynamic subtest: pipe-D-eDP-1
[110.808176] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[110.810462] Subtest clock-too-high: SUCCESS (0.293s)
[111.658702] [013/133] (912s left) gem_exec_fence (submit3)
[111.839052] Starting subtest: submit3
[111.843763] Subtest submit3: SKIP (0.000s)
[113.365586] [FACT gem_exec_fence (submit3)] new: kernel.kmod_is_loaded.vgem: true
[113.368798] [014/133] (910s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[113.459576] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[114.473317] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.015s)
[114.584789] [015/133] (909s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[114.682540] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[114.684454] Starting dynamic subtest: pipe-A-eDP-1
[114.703990] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[114.713711] Starting dynamic subtest: pipe-B-eDP-1
[114.731232] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[114.737809] Starting dynamic subtest: pipe-C-eDP-1
[114.755278] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[114.761766] Starting dynamic subtest: pipe-D-eDP-1
[114.780026] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[114.784130] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.004s)
[116.844403] [016/133] (907s left) gem_tiled_wb
[116.963222] [017/133] (907s left) kms_addfb_basic (invalid-set-prop)
[117.021792] Starting subtest: invalid-set-prop
[117.023759] Subtest invalid-set-prop: SUCCESS (0.000s)
[117.155276] [018/133] (907s left) kms_flip (2x-dpms-vs-vblank-race-interruptible)
[117.239472] Starting subtest: 2x-dpms-vs-vblank-race-interruptible
[117.250328] Subtest 2x-dpms-vs-vblank-race-interruptible: SKIP (0.010s)
[117.336772] [019/133] (907s left) gem_ccs (suspend-resume)
[117.384577] Starting subtest: suspend-resume
[117.389601] Subtest suspend-resume: SKIP (0.000s)
[117.491598] [020/133] (907s left) perf (non-sampling-read-error)
[117.614898] Starting subtest: non-sampling-read-error
[117.626615] Subtest non-sampling-read-error: SUCCESS (0.010s)
[117.664715] [021/133] (906s left) gem_ctx_param (invalid-size-set)
[117.727054] Starting subtest: invalid-size-set
[117.729342] Subtest invalid-size-set: SUCCESS (0.001s)
[117.840318] [022/133] (906s left) syncobj_eventfd (timeline-wait)
[117.894051] Starting subtest: timeline-wait
[117.904062] Subtest timeline-wait: SUCCESS (0.011s)
[118.008620] [023/133] (906s left) gem_ccs (block-copy-compressed)
[118.067276] Starting subtest: block-copy-compressed
[118.071127] Subtest block-copy-compressed: SKIP (0.000s)
[118.187483] [024/133] (906s left) i915_suspend (forcewake)
[118.240094] Starting subtest: forcewake
[149.827821] Subtest forcewake: SUCCESS (2.386s)
[150.899393] [025/133] (874s left) syncobj_wait (invalid-reset-illegal-handle)
[150.961620] Starting subtest: invalid-reset-illegal-handle
[150.963700] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[151.043388] [026/133] (874s left) gem_userptr_blits (sync-unmap-cycles)
[151.118730] Starting subtest: sync-unmap-cycles
[156.492203] Subtest sync-unmap-cycles: SUCCESS (5.372s)
[156.614738] [027/133] (869s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[156.716248] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[163.392846] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.679s)
[163.518650] [028/133] (862s left) gem_ctx_persistence (engines-hostile-preempt)
[163.589163] Starting subtest: engines-hostile-preempt
[163.591335] Starting dynamic subtest: rcs0
[163.673595] Dynamic subtest rcs0: SUCCESS (0.082s)
[163.684624] Starting dynamic subtest: bcs0
[163.724409] Dynamic subtest bcs0: SUCCESS (0.050s)
[163.735385] Starting dynamic subtest: vcs0
[165.528019] Dynamic subtest vcs0: SUCCESS (1.803s)
[165.542624] Starting dynamic subtest: vcs1
[167.506254] Dynamic subtest vcs1: SUCCESS (1.976s)
[167.518305] Starting dynamic subtest: vecs0
[169.489068] Dynamic subtest vecs0: SUCCESS (1.981s)
[169.499145] Starting dynamic subtest: ccs0
[169.532864] Dynamic subtest ccs0: SUCCESS (0.044s)
[169.535146] Subtest engines-hostile-preempt: SUCCESS (5.944s)
[169.609839] [029/133] (856s left) kms_universal_plane (universal-plane-functional)
[169.712677] Starting subtest: universal-plane-functional
[169.714914] Starting dynamic subtest: pipe-A-eDP-1
[173.622318] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.841s)
[173.632560] Starting dynamic subtest: pipe-B-eDP-1
[178.357725] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.644s)
[178.367570] Starting dynamic subtest: pipe-C-eDP-1
[182.748961] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.343s)
[182.758520] Starting dynamic subtest: pipe-D-eDP-1
[187.172419] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.394s)
[187.174453] Subtest universal-plane-functional: SUCCESS (17.228s)
[188.040015] [030/133] (837s left) gen9_exec_parse (basic-rejected)
[188.103375] Subtest basic-rejected: SKIP (0.000s)
[188.181812] [031/133] (837s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[188.226878] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[188.229100] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[188.323135] [032/133] (837s left) kms_ccs (bad-pixel-format-y-tiled-gen12-mc-ccs)
[188.428961] Starting subtest: bad-pixel-format-y-tiled-gen12-mc-ccs
[188.431258] Starting dynamic subtest: pipe-A-eDP-1
[188.453453] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[188.463175] Starting dynamic subtest: pipe-B-eDP-1
[188.486564] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[188.500300] Starting dynamic subtest: pipe-C-eDP-1
[188.523912] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[188.534129] Starting dynamic subtest: pipe-D-eDP-1
[188.556360] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[188.560555] Subtest bad-pixel-format-y-tiled-gen12-mc-ccs: SKIP (0.013s)
[190.629258] [033/133] (835s left) gem_eio (in-flight-suspend)
[190.846400] Starting subtest: in-flight-suspend
[234.112642] Subtest in-flight-suspend: SUCCESS (13.665s)
[234.266137] [034/133] (791s left) i915_pm_rps (min-max-config-loaded)
[234.342036] Starting subtest: min-max-config-loaded
[234.346793] Subtest min-max-config-loaded: SKIP (0.000s)
[234.458808] [035/133] (791s left) gem_mmap_gtt (basic-write-gtt)
[234.510090] Subtest basic-write-gtt: SKIP (0.000s)
[234.569715] [036/133] (791s left) kms_pm_rpm (dpms-mode-unset-non-lpsp)
[236.196144] Starting subtest: dpms-mode-unset-non-lpsp
[237.358527] Subtest dpms-mode-unset-non-lpsp: SKIP (1.131s)
[237.745900] [037/133] (788s left) gem_render_copy (linear-to-vebox-y-tiled)
[237.801440] Starting subtest: linear-to-vebox-y-tiled
[237.806034] Subtest linear-to-vebox-y-tiled: SKIP (0.000s)
[238.001819] [038/133] (788s left) gem_vm_create (execbuf)
[238.054457] Starting subtest: execbuf
[238.062435] Subtest execbuf: SUCCESS (0.008s)
[238.195122] [039/133] (788s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[238.287199] Starting subtest: multiplane-rotation-cropping-bottom
[255.899750] Subtest multiplane-rotation-cropping-bottom: SUCCESS (17.471s)
[256.015476] [040/133] (770s left) gem_ctx_param (basic)
[256.067147] Starting subtest: basic
[256.069160] Subtest basic: SUCCESS (0.001s)
[256.187169] [041/133] (770s left) kms_psr (pr-basic)
[256.272400] Starting subtest: pr-basic
[256.276016] Subtest pr-basic: SKIP (0.000s)
[256.343935] [042/133] (770s left) gem_exec_whisper (basic-forked-all)
[256.410839] Starting subtest: basic-forked-all
[270.097575] Subtest basic-forked-all: SUCCESS (13.669s)
[270.280276] [043/133] (756s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[270.395879] Starting subtest: 2x-long-flip-vs-cursor-legacy
[270.400485] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[270.553764] [044/133] (755s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-onoff)
[270.652139] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-onoff
[277.698770] Subtest fbcpsr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.050s)
[277.843173] [045/133] (748s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[277.953130] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[284.425929] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.474s)
[284.538973] [046/133] (742s left) kms_chamelium_audio (hdmi-audio)
[284.918637] Subtest hdmi-audio: SKIP (0.000s)
[285.759298] [047/133] (740s left) gem_ctx_shared (single-timeline)
[285.811053] Starting subtest: single-timeline
[285.831720] Subtest single-timeline: SUCCESS (0.014s)
[286.059440] [048/133] (740s left) kms_big_fb (linear-32bpp-rotate-180)
[286.165806] Starting subtest: linear-32bpp-rotate-180
[287.612276] Subtest linear-32bpp-rotate-180: SUCCESS (1.445s)
[287.765341] [049/133] (738s left) device_reset (cold-reset-bound)
[287.815540] Starting subtest: cold-reset-bound
[287.819363] Subtest cold-reset-bound: SKIP (0.000s)
[287.888262] [050/133] (738s left) gem_exercise_blt (fast-copy)
[287.935927] Starting subtest: fast-copy
[287.937818] Starting dynamic subtest: linear-smem-smem
[287.959841] Dynamic subtest linear-smem-smem: SUCCESS (0.023s)
[287.971664] Starting dynamic subtest: xmajor-smem-smem
[287.977608] Dynamic subtest xmajor-smem-smem: SUCCESS (0.017s)
[287.986783] Starting dynamic subtest: tile4-smem-smem
[287.994695] Dynamic subtest tile4-smem-smem: SUCCESS (0.016s)
[288.003244] Starting dynamic subtest: tile64-smem-smem
[288.012301] Dynamic subtest tile64-smem-smem: SUCCESS (0.017s)
[288.014313] Subtest fast-copy: SUCCESS (0.077s)
[288.133105] [051/133] (738s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[288.235545] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[288.237290] Starting dynamic subtest: pipe-A
[288.298614] Dynamic subtest pipe-A: SUCCESS (0.064s)
[288.308065] Starting dynamic subtest: pipe-B
[289.409385] Dynamic subtest pipe-B: SUCCESS (1.109s)
[289.420676] Starting dynamic subtest: pipe-C
[290.510229] Dynamic subtest pipe-C: SUCCESS (1.099s)
[290.520788] Starting dynamic subtest: pipe-D
[291.644365] Dynamic subtest pipe-D: SUCCESS (1.133s)
[291.646784] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.409s)
[292.807181] [052/133] (734s left) kms_addfb_basic (unused-pitches)
[292.870964] Starting subtest: unused-pitches
[292.872923] Subtest unused-pitches: SUCCESS (0.000s)
[293.034716] [053/133] (733s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[293.135939] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt
[293.140153] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[293.241251] [054/133] (733s left) gem_exec_reloc (basic-write-gtt)
[293.295094] Subtest basic-write-gtt: SKIP (0.000s)
[293.366904] [055/133] (733s left) kms_chamelium_frames (hdmi-crc-multiple)
[293.751882] Subtest hdmi-crc-multiple: SKIP (0.000s)
[294.527669] [056/133] (732s left) gem_mmap_offset (pf-nonblock)
[294.593209] Starting subtest: pf-nonblock
[294.598984] Subtest pf-nonblock: SUCCESS (0.008s)
[294.705704] [057/133] (732s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-pwrite)
[294.799375] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-pwrite
[302.385056] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (7.586s)
[302.502052] [058/133] (724s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[302.590454] Starting subtest: primary-y-tiled-reflect-x-270
[302.594920] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[302.662365] [059/133] (724s left) kms_draw_crc (draw-method-pwrite)
[302.749478] Starting subtest: draw-method-pwrite
[302.751387] Starting dynamic subtest: xrgb8888-untiled
[303.369916] Dynamic subtest xrgb8888-untiled: SUCCESS (0.622s)
[303.380296] Starting dynamic subtest: rgb565-untiled
[303.814998] Dynamic subtest rgb565-untiled: SUCCESS (0.444s)
[303.824713] Starting dynamic subtest: xrgb2101010-untiled
[304.349117] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.531s)
[304.358548] Starting dynamic subtest: xbgr16161616f-untiled
[305.103442] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.755s)
[305.113956] Starting dynamic subtest: xrgb8888-xtiled
[306.905736] Dynamic subtest xrgb8888-xtiled: SUCCESS (1.799s)
[306.915686] Starting dynamic subtest: rgb565-xtiled
[307.993991] Dynamic subtest rgb565-xtiled: SUCCESS (1.086s)
[308.003901] Starting dynamic subtest: xrgb2101010-xtiled
[309.761150] Dynamic subtest xrgb2101010-xtiled: SUCCESS (1.766s)
[309.780480] Starting dynamic subtest: xbgr16161616f-xtiled
[312.952484] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (3.187s)
[312.971401] Starting dynamic subtest: xrgb8888-4tiled
[314.040030] Dynamic subtest xrgb8888-4tiled: SUCCESS (1.086s)
[314.054517] Starting dynamic subtest: rgb565-4tiled
[314.596846] Dynamic subtest rgb565-4tiled: SUCCESS (0.555s)
[314.606603] Starting dynamic subtest: xrgb2101010-4tiled
[315.707636] Dynamic subtest xrgb2101010-4tiled: SUCCESS (1.108s)
[315.717293] Starting dynamic subtest: xbgr16161616f-4tiled
[318.642198] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (2.931s)
[318.644569] Subtest draw-method-pwrite: SUCCESS (15.890s)
[318.753229] [060/133] (708s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[318.843598] Starting subtest: yf-tiled-64bpp-rotate-0
[318.848152] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[318.932003] [061/133] (708s left) kms_addfb_basic (addfb25-x-tiled-legacy)
[319.021546] Subtest addfb25-x-tiled-legacy: SKIP (0.000s)
[319.121487] [062/133] (708s left) kms_cursor_crc (cursor-alpha-opaque)
[319.214618] Starting subtest: cursor-alpha-opaque
[319.216843] Starting dynamic subtest: pipe-A-eDP-1
[319.845415] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.631s)
[319.855544] Starting dynamic subtest: pipe-D-eDP-1
[321.169798] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.321s)
[321.172207] Subtest cursor-alpha-opaque: SUCCESS (1.955s)
[322.030681] [063/133] (705s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[322.123927] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[322.126358] Starting dynamic subtest: pipe-A-eDP-1
[328.024101] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.899s)
[328.034410] Starting dynamic subtest: pipe-B-eDP-1
[334.651211] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.627s)
[334.653692] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.529s)
[335.535909] [064/133] (691s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[335.599089] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[335.601377] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[335.720636] [065/133] (691s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[335.812548] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[335.814816] Starting dynamic subtest: pipe-A-eDP-1
[335.838076] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[335.850132] Starting dynamic subtest: pipe-B-eDP-1
[335.871966] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[335.881601] Starting dynamic subtest: pipe-C-eDP-1
[335.903453] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[335.912360] Starting dynamic subtest: pipe-D-eDP-1
[335.934978] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[335.939825] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.004s)
[338.014822] [066/133] (689s left) prime_mmap_coherency (read)
[338.070131] Starting subtest: read
[343.501845] Subtest read: SUCCESS (5.380s)
[343.565928] [067/133] (683s left) kms_cursor_legacy (forked-bo)
[343.685930] Starting subtest: forked-bo
[343.688268] Starting dynamic subtest: pipe-A
[349.205788] Dynamic subtest pipe-A: SUCCESS (5.463s)
[349.216996] Starting dynamic subtest: pipe-B
[354.669760] Dynamic subtest pipe-B: SUCCESS (5.465s)
[354.681828] Starting dynamic subtest: pipe-C
[360.135568] Dynamic subtest pipe-C: SUCCESS (5.464s)
[360.148817] Starting dynamic subtest: pipe-D
[365.602513] Dynamic subtest pipe-D: SUCCESS (5.463s)
[365.616823] Starting dynamic subtest: all-pipes
[371.064984] Dynamic subtest all-pipes: SUCCESS (5.512s)
[371.067471] Subtest forked-bo: SUCCESS (27.377s)
[371.208642] [068/133] (656s left) kms_cursor_edge_walk (256x256-left-edge)
[371.324534] Starting subtest: 256x256-left-edge
[371.326793] Starting dynamic subtest: pipe-A-eDP-1
[374.768971] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.442s)
[374.779636] Starting dynamic subtest: pipe-D-eDP-1
[377.894877] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.127s)
[377.897103] Subtest 256x256-left-edge: SUCCESS (6.572s)
[378.705711] [069/133] (648s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[378.805460] Starting subtest: psr-rgb565-draw-mmap-gtt
[378.809609] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[378.895785] [070/133] (648s left) gem_flink_basic (bad-open)
[378.956350] Starting subtest: bad-open
[378.958529] Subtest bad-open: SUCCESS (0.000s)
[379.072589] [071/133] (648s left) gem_exec_gttfill (multigpu-basic)
[379.258985] Starting subtest: multigpu-basic
[393.176587] Subtest multigpu-basic: SUCCESS (13.918s)
[393.234599] [072/133] (634s left) perf_pmu (most-busy-check-all)
[393.322064] Starting subtest: most-busy-check-all
[393.324883] Starting dynamic subtest: rcs0
[395.112955] Dynamic subtest rcs0: SUCCESS (1.789s)
[395.126099] Starting dynamic subtest: bcs0
[397.096767] Dynamic subtest bcs0: SUCCESS (1.980s)
[397.110223] Starting dynamic subtest: vcs0
[399.082163] Dynamic subtest vcs0: SUCCESS (1.982s)
[399.095183] Starting dynamic subtest: vcs1
[401.065909] Dynamic subtest vcs1: SUCCESS (1.980s)
[401.079353] Starting dynamic subtest: vecs0
[403.113379] Dynamic subtest vecs0: SUCCESS (2.044s)
[403.126585] Starting dynamic subtest: ccs0
[405.098181] Dynamic subtest ccs0: SUCCESS (1.981s)
[405.101027] Subtest most-busy-check-all: SUCCESS (11.773s)
[405.218936] [073/133] (622s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-pwrite)
[405.317364] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-pwrite
[410.727894] Subtest fbc-1p-primscrn-pri-indfb-draw-pwrite: SUCCESS (5.413s)
[411.928441] [074/133] (615s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[412.027342] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[412.029535] Starting dynamic subtest: pipe-A-default-mode
[414.716784] Dynamic subtest pipe-A-default-mode: SUCCESS (2.689s)
[414.719224] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.691s)
[415.589962] [075/133] (612s left) kms_psr (psr2-sprite-render)
[415.706597] Starting subtest: psr2-sprite-render
[415.708976] Starting dynamic subtest: eDP-1
[417.392705] Dynamic subtest eDP-1: SUCCESS (1.686s)
[417.394942] Subtest psr2-sprite-render: SUCCESS (1.687s)
[418.261229] [076/133] (609s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[418.635166] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[419.467993] [077/133] (608s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-onoff)
[419.562090] Starting subtest: fbc-2p-primscrn-cur-indfb-onoff
[419.566139] Subtest fbc-2p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[419.657761] [078/133] (608s left) kms_psr (fbc-psr-sprite-plane-onoff)
[419.747546] Starting subtest: fbc-psr-sprite-plane-onoff
[419.749784] Starting dynamic subtest: eDP-1
[419.753922] Dynamic subtest eDP-1: SKIP (0.000s)
[419.758062] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[419.822435] [079/133] (608s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy)
[419.920073] Starting subtest: fbcpsr-modesetfrombusy
[426.076405] Subtest fbcpsr-modesetfrombusy: SUCCESS (6.159s)
[426.194269] [080/133] (601s left) gem_eio (in-flight-internal-1us)
[426.412717] Starting subtest: in-flight-internal-1us
[428.126017] Subtest in-flight-internal-1us: SUCCESS (1.714s)
[428.247796] [081/133] (599s left) kms_frontbuffer_tracking (fbcpsr-2p-rte)
[428.339889] Starting subtest: fbcpsr-2p-rte
[428.344180] Subtest fbcpsr-2p-rte: SKIP (0.000s)
[428.421005] [082/133] (599s left) gem_mmap_gtt (cpuset-basic-small-copy)
[428.474971] Subtest cpuset-basic-small-copy: SKIP (0.000s)
[428.540148] [083/133] (599s left) syncobj_timeline (single-wait-signaled)
[428.606052] Starting subtest: single-wait-signaled
[428.608533] Subtest single-wait-signaled: SUCCESS (0.000s)
[428.693611] [084/133] (599s left) gem_render_copy (y-tiled-ccs-to-yf-tiled-mc-ccs)
[428.740261] Starting subtest: y-tiled-ccs-to-yf-tiled-mc-ccs
[428.744914] Subtest y-tiled-ccs-to-yf-tiled-mc-ccs: SKIP (0.000s)
[428.931258] [085/133] (599s left) perf_pmu (semaphore-wait-idle)
[428.982599] Starting subtest: semaphore-wait-idle
[428.984961] Starting dynamic subtest: rcs0
[429.494357] Dynamic subtest rcs0: SUCCESS (0.514s)
[429.507368] Starting dynamic subtest: bcs0
[430.008537] Dynamic subtest bcs0: SUCCESS (0.511s)
[430.021382] Starting dynamic subtest: vcs0
[430.521338] Dynamic subtest vcs0: SUCCESS (0.510s)
[430.534047] Starting dynamic subtest: vcs1
[431.035015] Dynamic subtest vcs1: SUCCESS (0.510s)
[431.048191] Starting dynamic subtest: vecs0
[431.549580] Dynamic subtest vecs0: SUCCESS (0.512s)
[431.562859] Starting dynamic subtest: ccs0
[432.067773] Dynamic subtest ccs0: SUCCESS (0.514s)
[432.070626] Subtest semaphore-wait-idle: SUCCESS (3.088s)
[432.181961] [086/133] (596s left) gem_pipe_control_store_loop (reused-buffer)
[432.251164] Starting subtest: reused-buffer
[434.459589] Subtest reused-buffer: SUCCESS (2.152s)
[434.517087] [087/133] (593s left) kms_getfb (getfb-handle-not-fb)
[434.610443] Starting subtest: getfb-handle-not-fb
[434.612760] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[434.708533] [088/133] (593s left) prime_mmap_coherency (write)
[434.771230] Starting subtest: write
[440.208014] Subtest write: SUCCESS (5.381s)
[440.287253] [089/133] (588s left) gem_exec_params (cliprects-invalid)
[440.340555] Starting subtest: cliprects-invalid
[440.342985] Subtest cliprects-invalid: SUCCESS (0.000s)
[440.449576] [090/133] (588s left) kms_pm_rpm (modeset-pc8-residency-stress)
[442.203918] Starting subtest: modeset-pc8-residency-stress
[442.208730] Subtest modeset-pc8-residency-stress: SKIP (0.000s)
[442.804956] [091/133] (585s left) kms_big_fb (4-tiled-16bpp-rotate-180)
[442.912081] Starting subtest: 4-tiled-16bpp-rotate-180
[444.124155] Subtest 4-tiled-16bpp-rotate-180: SUCCESS (1.212s)
[444.226625] [092/133] (584s left) i915_drm_fdinfo (virtual-busy-idle-all)
[444.270998] Starting subtest: virtual-busy-idle-all
[444.275451] Subtest virtual-busy-idle-all: SKIP (0.000s)
[444.337142] [093/133] (584s left) gem_ctx_persistence (legacy-engines-hostile)
[444.391466] Starting subtest: legacy-engines-hostile
[444.393505] Starting dynamic subtest: render
[444.531482] Dynamic subtest render: SUCCESS (0.139s)
[444.546883] Starting dynamic subtest: bsd1
[444.613619] Dynamic subtest bsd1: SUCCESS (0.079s)
[444.627972] Starting dynamic subtest: bsd2
[444.741698] Dynamic subtest bsd2: SUCCESS (0.125s)
[444.756373] Starting dynamic subtest: blt
[444.837457] Dynamic subtest blt: SUCCESS (0.093s)
[444.852031] Starting dynamic subtest: vebox
[444.936626] Dynamic subtest vebox: SUCCESS (0.096s)
[444.938837] Subtest legacy-engines-hostile: SUCCESS (0.546s)
[445.031540] [094/133] (583s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[445.133511] Starting subtest: x-tiled-32bpp-rotate-270
[445.153420] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[445.269255] [095/133] (583s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[445.375952] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[445.378056] Starting dynamic subtest: pipe-A-eDP-1
[445.493604] Dynamic subtest pipe-A-eDP-1: SKIP (0.122s)
[445.505419] Starting dynamic subtest: pipe-B-eDP-1
[446.666568] Dynamic subtest pipe-B-eDP-1: SKIP (1.170s)
[446.671160] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.295s)
[447.894209] [096/133] (580s left) kms_plane (planar-pixel-format-settings)
[447.986222] Starting subtest: planar-pixel-format-settings
[448.047511] Subtest planar-pixel-format-settings: SUCCESS (0.062s)
[448.158525] [097/133] (580s left) syncobj_wait (multi-wait-submitted-signaled)
[448.223772] Starting subtest: multi-wait-submitted-signaled
[448.225964] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[448.336978] [098/133] (580s left) kms_properties (plane-properties-atomic)
[448.429454] Starting subtest: plane-properties-atomic
[448.431824] Starting dynamic subtest: pipe-A-eDP-1
[448.985537] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.555s)
[448.996551] Starting dynamic subtest: pipe-B-eDP-1
[450.249478] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.262s)
[450.262077] Starting dynamic subtest: pipe-C-eDP-1
[451.555950] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.305s)
[451.567254] Starting dynamic subtest: pipe-D-eDP-1
[452.795326] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.236s)
[452.797223] Subtest plane-properties-atomic: SUCCESS (4.364s)
[453.613568] [099/133] (575s left) prime_vgem (basic-write)
[453.694662] Starting subtest: basic-write
[453.712930] Subtest basic-write: SKIP (0.019s)
[453.827498] [100/133] (575s left) gem_exec_schedule (preemptive-hang)
[454.007636] Starting subtest: preemptive-hang
[454.009786] Starting dynamic subtest: rcs0
[463.195180] Dynamic subtest rcs0: SUCCESS (9.189s)
[463.208791] Starting dynamic subtest: bcs0
[472.214657] Dynamic subtest bcs0: SUCCESS (9.012s)
[472.228888] Starting dynamic subtest: vcs0
[481.232347] Dynamic subtest vcs0: SUCCESS (9.028s)
[481.243620] Starting dynamic subtest: vcs1
[490.195236] Dynamic subtest vcs1: SUCCESS (8.962s)
[490.206154] Starting dynamic subtest: vecs0
[499.285690] Dynamic subtest vecs0: SUCCESS (9.081s)
[499.297034] Starting dynamic subtest: ccs0
[508.184221] Dynamic subtest ccs0: SUCCESS (8.903s)
[508.187407] Subtest preemptive-hang: SUCCESS (54.184s)
[508.428035] [101/133] (520s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[508.536860] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[508.539210] Starting dynamic subtest: pipe-A-eDP-1
[508.610217] Dynamic subtest pipe-A-eDP-1: SKIP (0.077s)
[508.621280] Starting dynamic subtest: pipe-B-eDP-1
[509.754691] Dynamic subtest pipe-B-eDP-1: SKIP (1.142s)
[509.758991] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.221s)
[510.935996] [102/133] (518s left) gem_softpin (overlap)
[510.998426] Starting subtest: overlap
[511.896340] Subtest overlap: SUCCESS (0.896s)
[512.007784] [103/133] (517s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-render)
[512.096360] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-render
[516.043381] Subtest fbc-1p-primscrn-spr-indfb-draw-render: SUCCESS (3.951s)
[517.216326] [104/133] (511s left) kms_flip (single-buffer-flip-vs-dpms-off-vs-modeset)
[517.318508] Starting subtest: single-buffer-flip-vs-dpms-off-vs-modeset
[517.320804] Starting dynamic subtest: A-eDP1
[520.975086] Dynamic subtest A-eDP1: SUCCESS (3.655s)
[520.985318] Starting dynamic subtest: B-eDP1
[524.230755] Dynamic subtest B-eDP1: SUCCESS (3.254s)
[524.242098] Starting dynamic subtest: C-eDP1
[527.337740] Dynamic subtest C-eDP1: SUCCESS (3.105s)
[527.348279] Starting dynamic subtest: D-eDP1
[530.546159] Dynamic subtest D-eDP1: SUCCESS (3.210s)
[530.548500] Subtest single-buffer-flip-vs-dpms-off-vs-modeset: SUCCESS (13.233s)
[531.338889] [105/133] (497s left) gem_exec_schedule (semaphore-codependency)
[531.403351] Starting subtest: semaphore-codependency
[531.409515] Subtest semaphore-codependency: SUCCESS (0.013s)
[531.752974] [106/133] (497s left) kms_flip (flip-vs-expired-vblank)
[531.848906] Starting subtest: flip-vs-expired-vblank
[531.851232] Starting dynamic subtest: A-eDP1
[540.587328] Dynamic subtest A-eDP1: SUCCESS (8.705s)
[540.600744] Starting dynamic subtest: B-eDP1
[549.229952] Dynamic subtest B-eDP1: SUCCESS (8.640s)
[549.242847] Starting dynamic subtest: C-eDP1
[557.962451] Dynamic subtest C-eDP1: SUCCESS (8.717s)
[557.972559] Starting dynamic subtest: D-eDP1
[566.544936] Dynamic subtest D-eDP1: SUCCESS (8.625s)
[566.551088] Subtest flip-vs-expired-vblank: SUCCESS (34.704s)
[567.720468] [107/133] (461s left) gem_mmap_offset (partial-mmap)
[567.777466] Starting subtest: partial-mmap
[567.779715] Starting dynamic subtest: smem0
[567.782049] Dynamic subtest smem0: SUCCESS (0.004s)
[567.784197] Subtest partial-mmap: SUCCESS (0.004s)
[567.878806] [108/133] (461s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-blt)
[567.994624] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-blt
[567.998895] Subtest psr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[568.102905] [109/133] (461s left) perf_pmu (all-busy-check-all)
[568.153382] Starting subtest: all-busy-check-all
[570.088524] Subtest all-busy-check-all: SUCCESS (1.935s)
[570.179683] [110/133] (459s left) kms_color (ctm-negative)
[570.280291] Starting subtest: ctm-negative
[570.282450] Starting dynamic subtest: pipe-A-eDP-1
[570.994053] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.712s)
[571.005064] Starting dynamic subtest: pipe-B-eDP-1
[572.455434] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.462s)
[572.465891] Starting dynamic subtest: pipe-C-eDP-1
[573.813489] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.355s)
[573.824188] Starting dynamic subtest: pipe-D-eDP-1
[575.140862] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.328s)
[575.143267] Subtest ctm-negative: SUCCESS (4.860s)
[575.954592] [111/133] (453s left) kms_lease (lease-unleased-crtc)
[576.055516] Starting subtest: lease-unleased-crtc
[576.057863] Starting dynamic subtest: pipe-A-eDP-1
[576.348140] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.291s)
[576.358594] Starting dynamic subtest: pipe-B-eDP-1
[577.105811] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.758s)
[577.117425] Starting dynamic subtest: pipe-C-eDP-1
[577.119691] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.004s)
[577.129856] Starting dynamic subtest: pipe-D-eDP-1
[577.132037] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[577.134124] Subtest lease-unleased-crtc: SUCCESS (1.063s)
[577.469065] [112/133] (451s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[577.558332] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[577.584490] Starting dynamic subtest: pipe-A-plane-0
[578.273188] Dynamic subtest pipe-A-plane-0: SUCCESS (0.689s)
[578.283121] Starting dynamic subtest: pipe-A-plane-3
[578.695135] Dynamic subtest pipe-A-plane-3: SUCCESS (0.421s)
[579.768444] Starting dynamic subtest: pipe-B-plane-0
[580.437876] Dynamic subtest pipe-B-plane-0: SUCCESS (0.678s)
[580.454780] Starting dynamic subtest: pipe-B-plane-3
[580.837157] Dynamic subtest pipe-B-plane-3: SUCCESS (0.398s)
[581.157497] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.599s)
[581.973321] [113/133] (447s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip)
[582.065870] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip
[582.070035] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[582.173542] [114/133] (447s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[582.264957] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc
[582.269016] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[582.361084] [115/133] (447s left) i915_drm_fdinfo (virtual-busy-all)
[582.401093] Starting subtest: virtual-busy-all
[582.405512] Subtest virtual-busy-all: SKIP (0.000s)
[582.465197] [116/133] (447s left) kms_plane_lowres (tiling-yf)
[582.551752] Starting subtest: tiling-yf
[582.556126] Subtest tiling-yf: SKIP (0.000s)
[582.624531] [117/133] (447s left) perf (low-oa-exponent-permissions)
[582.729100] Starting subtest: low-oa-exponent-permissions
[582.757081] Subtest low-oa-exponent-permissions: SUCCESS (0.031s)
[582.801857] [118/133] (446s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-pwrite)
[582.887611] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-pwrite
[587.751382] Subtest fbc-1p-offscreen-pri-indfb-draw-pwrite: SUCCESS (4.866s)
[588.880871] [119/133] (440s left) kms_pipe_crc_basic (hang-read-crc)
[588.988644] Starting subtest: hang-read-crc
[588.990998] Starting dynamic subtest: pipe-A-eDP-1
[591.043645] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.052s)
[591.055379] Starting dynamic subtest: pipe-B-eDP-1
[593.736538] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.693s)
[593.747913] Starting dynamic subtest: pipe-C-eDP-1
[596.431521] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.694s)
[596.442674] Starting dynamic subtest: pipe-D-eDP-1
[599.090976] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.657s)
[599.093379] Subtest hang-read-crc: SUCCESS (10.103s)
[599.936119] [120/133] (429s left) sysfs_preempt_timeout (invalid)
[600.001471] Starting subtest: invalid
[600.003919] Starting dynamic subtest: vecs0
[600.006358] Dynamic subtest vecs0: SUCCESS (0.000s)
[600.017854] Starting dynamic subtest: ccs0
[600.019848] Dynamic subtest ccs0: SUCCESS (0.000s)
[600.029448] Starting dynamic subtest: vcs0
[600.031771] Dynamic subtest vcs0: SUCCESS (0.000s)
[600.040981] Starting dynamic subtest: bcs0
[600.042955] Dynamic subtest bcs0: SUCCESS (0.000s)
[600.052179] Starting dynamic subtest: rcs0
[600.054181] Dynamic subtest rcs0: SUCCESS (0.000s)
[600.063401] Starting dynamic subtest: vcs1
[600.065343] Dynamic subtest vcs1: SUCCESS (0.000s)
[600.067299] Subtest invalid: SUCCESS (0.006s)
[600.103962] [121/133] (429s left) syncobj_timeline (invalid-signal-illegal-point)
[600.161772] Starting subtest: invalid-signal-illegal-point
[600.163744] Subtest invalid-signal-illegal-point: SUCCESS (0.000s)
[600.265725] [122/133] (429s left) kms_vblank (wait-forked-hang)
[600.381832] Starting subtest: wait-forked-hang
[600.387536] Starting dynamic subtest: pipe-A-eDP-1
[626.851835] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.459s)
[626.867554] Starting dynamic subtest: pipe-D-eDP-1
[651.827370] Dynamic subtest pipe-D-eDP-1: SUCCESS (24.969s)
[651.829732] Subtest wait-forked-hang: SUCCESS (51.435s)
[652.647855] [123/133] (377s left) perf_pmu (busy-check-all)
[652.697040] Starting subtest: busy-check-all
[652.699179] Starting dynamic subtest: rcs0
[653.243217] Dynamic subtest rcs0: SUCCESS (0.548s)
[653.257115] Starting dynamic subtest: bcs0
[653.795343] Dynamic subtest bcs0: SUCCESS (0.550s)
[653.808795] Starting dynamic subtest: vcs0
[655.082043] Dynamic subtest vcs0: SUCCESS (1.282s)
[655.095550] Starting dynamic subtest: vcs1
[657.065407] Dynamic subtest vcs1: SUCCESS (1.980s)
[657.080654] Starting dynamic subtest: vecs0
[659.112674] Dynamic subtest vecs0: SUCCESS (2.044s)
[659.128037] Starting dynamic subtest: ccs0
[659.670119] Dynamic subtest ccs0: SUCCESS (0.555s)
[659.672634] Subtest busy-check-all: SUCCESS (6.976s)
[659.786322] [124/133] (370s left) api_intel_bb (lot-of-buffers)
[659.845398] Starting subtest: lot-of-buffers
[660.832520] Subtest lot-of-buffers: SUCCESS (0.989s)
[661.504248] [125/133] (368s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[661.600406] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[668.867279] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.267s)
[668.999098] [126/133] (361s left) gem_spin_batch (legacy-resubmit)
[669.066912] Starting subtest: legacy-resubmit
[669.069195] Starting dynamic subtest: default
[669.073904] Dynamic subtest default: SUCCESS (0.006s)
[669.085518] Starting dynamic subtest: render
[669.087760] Dynamic subtest render: SUCCESS (0.001s)
[669.097839] Starting dynamic subtest: bsd
[669.099749] Dynamic subtest bsd: SUCCESS (0.002s)
[669.109153] Starting dynamic subtest: bsd1
[669.111094] Dynamic subtest bsd1: SUCCESS (0.001s)
[669.120422] Starting dynamic subtest: bsd2
[669.122363] Dynamic subtest bsd2: SUCCESS (0.002s)
[669.131988] Starting dynamic subtest: blt
[669.133888] Dynamic subtest blt: SUCCESS (0.003s)
[669.143484] Starting dynamic subtest: vebox
[669.145384] Dynamic subtest vebox: SUCCESS (0.004s)
[669.147237] Subtest legacy-resubmit: SUCCESS (0.028s)
[669.316485] [127/133] (360s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[669.419503] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[669.421792] Starting dynamic subtest: pipe-A-default-mode
[672.191964] Dynamic subtest pipe-A-default-mode: SUCCESS (2.772s)
[672.194521] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.773s)
[673.038051] [128/133] (357s left) api_intel_allocator (gem-pool)
[673.109200] Starting subtest: gem-pool
[673.114984] Subtest gem-pool: SUCCESS (0.007s)
[673.204638] [129/133] (356s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[673.293873] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[673.297881] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[673.385684] [130/133] (356s left) syncobj_wait (invalid-wait-bad-flags)
[673.439461] Starting subtest: invalid-wait-bad-flags
[673.441608] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[673.532461] [131/133] (356s left) gem_exec_balancer (bonded-chain)
[673.583354] Starting subtest: bonded-chain
[680.568619] Subtest bonded-chain: SUCCESS (6.982s)
[680.892708] [132/133] (349s left) kms_cursor_crc (cursor-random-max-size)
[681.222223] Starting subtest: cursor-random-max-size
[681.228551] Subtest cursor-random-max-size: SKIP (0.000s)
[681.345492] [133/133] (348s left) prime_mmap_kms (buffer-sharing)
[681.444377] Starting subtest: buffer-sharing
[681.891891] Subtest buffer-sharing: SUCCESS (0.445s)
[682.781553] Closing watchdogs
results: parsing output: 0/ for test: syncobj_basic
results: parsing output: 1/ for test: kms_psr
results: parsing output: 2/ for test: kms_concurrent
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: kms_cursor_legacy
results: parsing output: 5/ for test: gem_exec_balancer
results: parsing output: 6/ for test: kms_big_fb
results: parsing output: 7/ for test: kms_cursor_legacy
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_flip
results: parsing output: 10/ for test: kms_big_fb
results: parsing output: 11/ for test: kms_invalid_mode
results: parsing output: 12/ for test: gem_exec_fence
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: kms_ccs
results: parsing output: 15/ for test: gem_tiled_wb
results: parsing output: 16/ for test: kms_addfb_basic
results: parsing output: 17/ for test: kms_flip
results: parsing output: 18/ for test: gem_ccs
results: parsing output: 19/ for test: perf
results: parsing output: 20/ for test: gem_ctx_param
results: parsing output: 21/ for test: syncobj_eventfd
results: parsing output: 22/ for test: gem_ccs
results: parsing output: 23/ for test: i915_suspend
results: parsing output: 24/ for test: syncobj_wait
results: parsing output: 25/ for test: gem_userptr_blits
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: gem_ctx_persistence
results: parsing output: 28/ for test: kms_universal_plane
results: parsing output: 29/ for test: gen9_exec_parse
results: parsing output: 30/ for test: syncobj_timeline
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: gem_eio
results: parsing output: 33/ for test: i915_pm_rps
results: parsing output: 34/ for test: gem_mmap_gtt
results: parsing output: 35/ for test: kms_pm_rpm
results: parsing output: 36/ for test: gem_render_copy
results: parsing output: 37/ for test: gem_vm_create
results: parsing output: 38/ for test: kms_rotation_crc
results: parsing output: 39/ for test: gem_ctx_param
results: parsing output: 40/ for test: kms_psr
results: parsing output: 41/ for test: gem_exec_whisper
results: parsing output: 42/ for test: kms_cursor_legacy
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_chamelium_audio
results: parsing output: 46/ for test: gem_ctx_shared
results: parsing output: 47/ for test: kms_big_fb
results: parsing output: 48/ for test: device_reset
results: parsing output: 49/ for test: gem_exercise_blt
results: parsing output: 50/ for test: kms_plane_scaling
results: parsing output: 51/ for test: kms_addfb_basic
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: gem_exec_reloc
results: parsing output: 54/ for test: kms_chamelium_frames
results: parsing output: 55/ for test: gem_mmap_offset
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_rotation_crc
results: parsing output: 58/ for test: kms_draw_crc
results: parsing output: 59/ for test: kms_big_fb
results: parsing output: 60/ for test: kms_addfb_basic
results: parsing output: 61/ for test: kms_cursor_crc
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: syncobj_timeline
results: parsing output: 64/ for test: kms_ccs
results: parsing output: 65/ for test: prime_mmap_coherency
results: parsing output: 66/ for test: kms_cursor_legacy
results: parsing output: 67/ for test: kms_cursor_edge_walk
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: gem_flink_basic
results: parsing output: 70/ for test: gem_exec_gttfill
results: parsing output: 71/ for test: perf_pmu
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_flip_scaled_crc
results: parsing output: 74/ for test: kms_psr
results: parsing output: 75/ for test: kms_chamelium_hpd
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: kms_psr
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_eio
results: parsing output: 80/ for test: kms_frontbuffer_tracking
results: parsing output: 81/ for test: gem_mmap_gtt
results: parsing output: 82/ for test: syncobj_timeline
results: parsing output: 83/ for test: gem_render_copy
results: parsing output: 84/ for test: perf_pmu
results: parsing output: 85/ for test: gem_pipe_control_store_loop
results: parsing output: 86/ for test: kms_getfb
results: parsing output: 87/ for test: prime_mmap_coherency
results: parsing output: 88/ for test: gem_exec_params
results: parsing output: 89/ for test: kms_pm_rpm
results: parsing output: 90/ for test: kms_big_fb
results: parsing output: 91/ for test: i915_drm_fdinfo
results: parsing output: 92/ for test: gem_ctx_persistence
results: parsing output: 93/ for test: kms_big_fb
results: parsing output: 94/ for test: kms_psr2_sf
results: parsing output: 95/ for test: kms_plane
results: parsing output: 96/ for test: syncobj_wait
results: parsing output: 97/ for test: kms_properties
results: parsing output: 98/ for test: prime_vgem
results: parsing output: 99/ for test: gem_exec_schedule
results: parsing output: 100/ for test: kms_psr2_sf
results: parsing output: 101/ for test: gem_softpin
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_flip
results: parsing output: 104/ for test: gem_exec_schedule
results: parsing output: 105/ for test: kms_flip
results: parsing output: 106/ for test: gem_mmap_offset
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: perf_pmu
results: parsing output: 109/ for test: kms_color
results: parsing output: 110/ for test: kms_lease
results: parsing output: 111/ for test: kms_plane
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: i915_drm_fdinfo
results: parsing output: 115/ for test: kms_plane_lowres
results: parsing output: 116/ for test: perf
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_pipe_crc_basic
results: parsing output: 119/ for test: sysfs_preempt_timeout
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_vblank
results: parsing output: 122/ for test: perf_pmu
results: parsing output: 123/ for test: api_intel_bb
results: parsing output: 124/ for test: kms_frontbuffer_tracking
results: parsing output: 125/ for test: gem_spin_batch
results: parsing output: 126/ for test: kms_flip_scaled_crc
results: parsing output: 127/ for test: api_intel_allocator
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: syncobj_wait
results: parsing output: 130/ for test: gem_exec_balancer
results: parsing output: 131/ for test: kms_cursor_crc
results: parsing output: 132/ for test: prime_mmap_kms
Done.