[56.166883] Initializing watchdogs
[56.166934]   /dev/watchdog0
[56.174916] [001/130] (960s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[56.291823] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[56.296056] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[56.378860] [002/130] (960s left) kms_big_fb (x-tiled-32bpp-rotate-180)
[56.485515] Starting subtest: x-tiled-32bpp-rotate-180
[57.902994] Subtest x-tiled-32bpp-rotate-180: SUCCESS (1.443s)
[58.023468] [003/130] (958s left) gem_mmap_gtt (basic-write-gtt)
[58.086633] Subtest basic-write-gtt: SKIP (0.000s)
[58.127994] [004/130] (958s left) kms_psr (psr2-dpms)
[58.224583] Starting subtest: psr2-dpms
[58.226465] Starting dynamic subtest: eDP-1
[61.041219] Dynamic subtest eDP-1: SUCCESS (2.849s)
[61.043481] Subtest psr2-dpms: SUCCESS (2.850s)
[61.875371] [005/130] (954s left) kms_color (ctm-red-to-blue)
[61.973852] Starting subtest: ctm-red-to-blue
[61.976136] Starting dynamic subtest: pipe-A-eDP-1
[62.645087] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.675s)
[62.651638] Starting dynamic subtest: pipe-B-eDP-1
[64.037650] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.402s)
[64.044055] Starting dynamic subtest: pipe-C-eDP-1
[65.281435] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.252s)
[65.287720] Starting dynamic subtest: pipe-D-eDP-1
[66.607109] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.333s)
[66.609400] Subtest ctm-red-to-blue: SUCCESS (4.663s)
[67.437355] [006/130] (949s left) gem_ctx_shared (exec-single-timeline)
[67.500614] Starting subtest: exec-single-timeline
[67.503027] Starting dynamic subtest: rcs0
[68.550817] Dynamic subtest rcs0: SUCCESS (1.053s)
[68.560485] Starting dynamic subtest: bcs0
[69.574503] Dynamic subtest bcs0: SUCCESS (1.027s)
[69.582664] Starting dynamic subtest: vcs0
[70.602867] Dynamic subtest vcs0: SUCCESS (1.031s)
[70.613303] Starting dynamic subtest: vcs1
[71.687926] Dynamic subtest vcs1: SUCCESS (1.087s)
[71.699090] Starting dynamic subtest: vecs0
[72.774993] Dynamic subtest vecs0: SUCCESS (1.090s)
[72.781232] Starting dynamic subtest: ccs0
[73.864449] Dynamic subtest ccs0: SUCCESS (1.091s)
[73.867352] Subtest exec-single-timeline: SUCCESS (6.381s)
[74.083184] [007/130] (942s left) kms_big_fb (yf-tiled-64bpp-rotate-270)
[74.203968] Starting subtest: yf-tiled-64bpp-rotate-270
[74.208326] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s)
[74.295793] [008/130] (942s left) kms_psr (psr-primary-mmap-gtt)
[74.384949] Starting subtest: psr-primary-mmap-gtt
[74.387202] Starting dynamic subtest: eDP-1
[75.834035] Dynamic subtest eDP-1: SKIP (1.446s)
[75.838818] Subtest psr-primary-mmap-gtt: SKIP (1.447s)
[76.012941] [009/130] (940s left) gem_mmap_gtt (big-copy-xy)
[76.065203] Subtest big-copy-XY: SKIP (0.000s)
[76.108511] [010/130] (940s left) intel_hwmon (hwmon-read)
[76.165904] Subtest hwmon-read: SKIP (0.000s)
[76.212929] [011/130] (940s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[76.324533] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[76.328701] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[76.419963] [012/130] (940s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi
[76.479857] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted
[76.582718] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.101s)
[76.635386] [013/130] (940s left) kms_prime (basic-modeset-hybrid)
[78.647842] Subtest basic-modeset-hybrid: SKIP (0.000s)
[78.728473] [014/130] (938s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-fullscreen)
[78.843214] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-fullscreen
[78.847185] Subtest fbcpsr-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[78.908772] [015/130] (937s left) kms_psr (pr-no-drrs)
[79.017989] Starting subtest: pr-no-drrs
[79.022575] Subtest pr-no-drrs: SKIP (0.000s)
[79.069309] [016/130] (937s left) kms_plane_alpha_blend (alpha-opaque-fb)
[79.169931] Starting subtest: alpha-opaque-fb
[79.438594] Starting dynamic subtest: pipe-A-eDP-1
[80.016973] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.577s)
[80.025257] Starting dynamic subtest: pipe-D-eDP-1
[81.407412] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.390s)
[81.409660] Subtest alpha-opaque-fb: SUCCESS (2.237s)
[82.187343] [017/130] (934s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[82.288998] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[82.291448] Starting dynamic subtest: pipe-A-eDP-1
[82.315115] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.026s)
[82.323158] Starting dynamic subtest: pipe-B-eDP-1
[82.343711] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.025s)
[82.352652] Starting dynamic subtest: pipe-C-eDP-1
[82.375467] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.032s)
[82.382070] Starting dynamic subtest: pipe-D-eDP-1
[82.404697] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.030s)
[82.407030] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.115s)
[84.634782] [018/130] (932s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-upscaling)
[84.725084] Starting subtest: flip-64bpp-linear-to-32bpp-linear-upscaling
[84.727376] Starting dynamic subtest: pipe-A-default-mode
[87.494280] Dynamic subtest pipe-A-default-mode: SUCCESS (2.766s)
[87.496734] Subtest flip-64bpp-linear-to-32bpp-linear-upscaling: SUCCESS (2.767s)
[88.302780] [019/130] (928s left) kms_pipe_crc_basic (disable-crc-after-crtc)
[88.410178] Starting subtest: disable-crc-after-crtc
[88.412549] Starting dynamic subtest: pipe-A-eDP-1
[90.074209] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.661s)
[90.082961] Starting dynamic subtest: pipe-B-eDP-1
[92.312123] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.235s)
[92.318856] Starting dynamic subtest: pipe-C-eDP-1
[94.551081] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.240s)
[94.559941] Starting dynamic subtest: pipe-D-eDP-1
[96.798274] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.246s)
[96.800731] Subtest disable-crc-after-crtc: SUCCESS (8.387s)
[97.604899] [020/130] (919s left) kms_vblank (query-idle)
[97.696999] Starting subtest: query-idle
[97.699278] Starting dynamic subtest: pipe-A-eDP-1
[99.442162] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.742s)
[99.453408] Starting dynamic subtest: pipe-D-eDP-1
[101.849585] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.406s)
[101.851975] Subtest query-idle: SUCCESS (4.151s)
[102.653167] [021/130] (914s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[102.750981] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[102.753289] Starting dynamic subtest: pipe-A-eDP-1
[102.775971] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[102.783901] Starting dynamic subtest: pipe-B-eDP-1
[102.804714] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[102.811679] Starting dynamic subtest: pipe-C-eDP-1
[102.833291] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[102.840554] Starting dynamic subtest: pipe-D-eDP-1
[102.864026] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[102.868291] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[104.911302] [022/130] (911s left) gem_mmap_gtt (pf-nonblock)
[104.967906] Subtest pf-nonblock: SKIP (0.000s)
[105.001659] [023/130] (911s left) gem_exec_reloc (basic-gtt-read-active)
[105.057868] Subtest basic-gtt-read-active: SKIP (0.000s)
[105.118138] [024/130] (911s left) kms_cursor_crc (cursor-onscreen-32x32)
[105.248422] Starting subtest: cursor-onscreen-32x32
[105.290710] Subtest cursor-onscreen-32x32: SKIP (0.038s)
[105.514239] [025/130] (911s left) i915_hangman (engine-engine-hang)
[105.580889] Starting subtest: engine-engine-hang
[105.583030] Starting dynamic subtest: rcs0
[106.891494] Dynamic subtest rcs0: SUCCESS (1.310s)
[106.902425] Starting dynamic subtest: bcs0
[108.872877] Dynamic subtest bcs0: SUCCESS (1.980s)
[108.883785] Starting dynamic subtest: vcs0
[110.922219] Dynamic subtest vcs0: SUCCESS (2.048s)
[110.932916] Starting dynamic subtest: vcs1
[112.904874] Dynamic subtest vcs1: SUCCESS (1.982s)
[112.914921] Starting dynamic subtest: vecs0
[114.890909] Dynamic subtest vecs0: SUCCESS (1.985s)
[114.901241] Starting dynamic subtest: ccs0
[116.873247] Dynamic subtest ccs0: SUCCESS (1.980s)
[116.875845] Subtest engine-engine-hang: SUCCESS (11.291s)
[116.934297] [026/130] (900s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[117.042656] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[117.055382] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.011s)
[117.131315] [027/130] (899s left) gem_fenced_exec_thrash (2-spare-fences)
[117.187924] Subtest 2-spare-fences: SKIP (0.000s)
[117.227918] [028/130] (899s left) perf (polling-parameterized)
[117.350281] Starting subtest: polling-parameterized
[137.372135] Subtest polling-parameterized: SUCCESS (20.020s)
[137.399203] [029/130] (879s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-plflip-blt)
[137.507927] Starting subtest: fbcpsr-2p-primscrn-indfb-plflip-blt
[137.512333] Subtest fbcpsr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[137.597125] [030/130] (879s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[142.595101] Starting subtest: psr2-overlay-plane-update-continuous-sf
[142.597511] Starting dynamic subtest: pipe-A-eDP-1
[144.353942] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.759s)
[144.361992] Starting dynamic subtest: pipe-B-eDP-1
[146.071720] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.716s)
[146.074199] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (3.479s)
[146.874684] [031/130] (870s left) gem_userptr_blits (nohangcheck)
[146.952630] Starting subtest: nohangcheck
[147.207666] Subtest nohangcheck: SUCCESS (0.253s)
[147.304640] [032/130] (869s left) kms_writeback (writeback-check-output-xrgb2101010)
[147.405205] Subtest writeback-check-output-XRGB2101010: SKIP (0.000s)
[147.430520] [033/130] (869s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[152.343287] Starting subtest: fbc-psr2-cursor-plane-update-sf
[152.345719] Starting dynamic subtest: pipe-A-eDP-1
[153.146035] Dynamic subtest pipe-A-eDP-1: SKIP (0.797s)
[153.153339] Starting dynamic subtest: pipe-B-eDP-1
[154.272181] Dynamic subtest pipe-B-eDP-1: SKIP (1.122s)
[154.276615] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.922s)
[155.423338] [034/130] (861s left) gem_mmap_gtt (big-bo)
[155.486800] Subtest big-bo: SKIP (0.000s)
[155.523320] [035/130] (861s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[155.624756] Starting subtest: fbcpsr-shrfb-scaledprimary
[161.382775] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.761s)
[161.498625] [036/130] (855s left) gem_huc_copy (huc-copy)
[161.563054] Starting subtest: huc-copy
[161.567862] Subtest huc-copy: SUCCESS (0.003s)
[161.686324] [037/130] (855s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[161.785983] Starting subtest: primary-y-tiled-reflect-x-270
[161.790444] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[161.837635] [038/130] (855s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-async-flip)
[161.936414] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip
[162.515910] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-async-flip: SUCCESS (0.581s)
[162.667036] [039/130] (854s left) kms_atomic (plane-primary-overlay-mutable-zpos)
[162.764158] Starting subtest: plane-primary-overlay-mutable-zpos
[166.139920] Subtest plane-primary-overlay-mutable-zpos: SKIP (3.372s)
[167.301184] [040/130] (849s left) gem_vm_create (invalid-destroy)
[167.362738] Starting subtest: invalid-destroy
[167.365240] Subtest invalid-destroy: SUCCESS (0.002s)
[167.451866] [041/130] (849s left) gem_render_copy (yf-tiled-mc-ccs-to-vebox-y-tiled)
[167.507039] Starting subtest: yf-tiled-mc-ccs-to-vebox-y-tiled
[167.511669] Subtest yf-tiled-mc-ccs-to-vebox-y-tiled: SKIP (0.000s)
[167.668120] [042/130] (849s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[167.775565] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[167.779711] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[167.849537] [043/130] (849s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-blt)
[167.950591] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-blt
[172.630195] Subtest fbcpsr-1p-offscren-pri-indfb-draw-blt: SUCCESS (4.682s)
[172.767947] [044/130] (844s left) i915_pm_rps (min-max-config-loaded)
[172.823517] Starting subtest: min-max-config-loaded
[172.828142] Subtest min-max-config-loaded: SKIP (0.001s)
[172.884051] [045/130] (844s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[172.990869] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[177.417373] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.430s)
[177.521495] [046/130] (839s left) gem_userptr_blits (usage-restrictions)
[177.582876] Starting subtest: usage-restrictions
[177.585067] Subtest usage-restrictions: SUCCESS (0.000s)
[177.697838] [047/130] (839s left) kms_chamelium_audio (dp-audio)
[178.081134] Subtest dp-audio: SKIP (0.000s)
[178.909470] [048/130] (838s left) kms_cursor_legacy (cursorb-vs-flipa-legacy)
[179.031698] Starting subtest: cursorB-vs-flipA-legacy
[179.036136] Subtest cursorB-vs-flipA-legacy: SKIP (0.001s)
[179.155717] [049/130] (838s left) kms_feature_discovery (display)
[179.260411] Starting subtest: display
[179.262879] Subtest display: SUCCESS (0.000s)
[179.312879] [050/130] (838s left) kms_atomic_interruptible (universal-setplane-cursor)
[179.422923] Starting subtest: universal-setplane-cursor
[179.425217] Starting dynamic subtest: pipe-A-eDP-1
[186.857700] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.435s)
[186.860056] Subtest universal-setplane-cursor: SUCCESS (7.436s)
[187.686487] [051/130] (829s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180)
[187.802794] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180
[187.806974] Subtest y-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s)
[187.901561] [052/130] (829s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-pwrite)
[187.999327] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-pwrite
[192.882658] Subtest fbcpsr-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (4.885s)
[193.020603] [053/130] (824s left) kms_content_protection (type1)
[193.168730] Starting subtest: type1
[196.565986] Subtest type1: SKIP (3.395s)
[197.723199] [054/130] (819s left) gem_ctx_isolation (nonpriv-switch)
[197.792923] Starting subtest: nonpriv-switch
[197.795210] Starting dynamic subtest: rcs0
[198.066225] Dynamic subtest rcs0: SUCCESS (0.273s)
[198.074694] Starting dynamic subtest: bcs0
[198.360312] Dynamic subtest bcs0: SUCCESS (0.293s)
[198.369736] Starting dynamic subtest: vcs0
[198.643514] Dynamic subtest vcs0: SUCCESS (0.282s)
[198.652674] Starting dynamic subtest: vcs1
[200.160842] Dynamic subtest vcs1: SUCCESS (1.517s)
[200.170072] Starting dynamic subtest: vecs0
[202.179445] Dynamic subtest vecs0: SUCCESS (2.018s)
[202.187819] Starting dynamic subtest: ccs0
[204.297689] Dynamic subtest ccs0: SUCCESS (2.117s)
[204.300112] Subtest nonpriv-switch: SUCCESS (6.505s)
[204.534181] [055/130] (812s left) syncobj_wait (invalid-wait-illegal-handle)
[204.592781] Starting subtest: invalid-wait-illegal-handle
[204.594850] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s)
[204.670367] [056/130] (812s left) kms_flip (2x-flip-vs-fences-interruptible)
[204.773322] Starting subtest: 2x-flip-vs-fences-interruptible
[204.778017] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[204.855165] [057/130] (812s left) kms_pm_backlight (basic-brightness)
[204.953143] Starting subtest: basic-brightness
[205.000517] Starting dynamic subtest: eDP-1
[205.505985] Dynamic subtest eDP-1: SUCCESS (0.505s)
[205.508824] Subtest basic-brightness: SUCCESS (0.555s)
[206.352389] [058/130] (811s left) gem_basic (bad-close)
[206.403030] Starting subtest: bad-close
[206.407259] Subtest bad-close: SUCCESS (0.000s)
[206.497133] [059/130] (811s left) gem_pxp (hw-rejects-pxp-buffer)
[206.610246] Subtest hw-rejects-pxp-buffer: SKIP (0.000s)
[207.709064] [060/130] (809s left) gem_exec_schedule (u-submit-late-slice)
[207.783896] Starting subtest: u-submit-late-slice
[207.786222] Starting dynamic subtest: rcs0
[207.809342] Dynamic subtest rcs0: SUCCESS (0.031s)
[207.817143] Starting dynamic subtest: bcs0
[207.831436] Dynamic subtest bcs0: SUCCESS (0.022s)
[207.838849] Starting dynamic subtest: vcs0
[207.858257] Dynamic subtest vcs0: SUCCESS (0.026s)
[207.865529] Starting dynamic subtest: vcs1
[207.884851] Dynamic subtest vcs1: SUCCESS (0.026s)
[207.892071] Starting dynamic subtest: vecs0
[207.910431] Dynamic subtest vecs0: SUCCESS (0.025s)
[207.917837] Starting dynamic subtest: ccs0
[207.938087] Dynamic subtest ccs0: SUCCESS (0.027s)
[207.940281] Subtest u-submit-late-slice: SUCCESS (0.160s)
[209.007218] [061/130] (808s left) kms_addfb_basic (invalid-get-prop-any)
[209.069936] Starting subtest: invalid-get-prop-any
[209.072280] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[209.186406] [062/130] (808s left) perf (gen8-unprivileged-single-ctx-counters)
[209.287859] Starting subtest: gen8-unprivileged-single-ctx-counters
[209.292453] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[209.306792] [063/130] (808s left) syncobj_eventfd (invalid-illegal-handle)
[209.373614] Starting subtest: invalid-illegal-handle
[209.375941] Subtest invalid-illegal-handle: SUCCESS (0.000s)
[209.455215] [064/130] (808s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-blt)
[209.561138] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-blt
[209.565309] Subtest psr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[209.635959] [065/130] (808s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt)
[209.740976] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt
[209.745160] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[209.809169] [066/130] (807s left) kms_addfb_basic (bad-pitch-999)
[209.861135] Starting subtest: bad-pitch-999
[209.863071] Subtest bad-pitch-999: SUCCESS (0.000s)
[209.998635] [067/130] (807s left) drm_fdinfo (virtual-busy-hang)
[210.038662] Starting subtest: virtual-busy-hang
[210.042322] Subtest virtual-busy-hang: SKIP (0.000s)
[210.085681] [068/130] (807s left) kms_busy (extended-modeset-hang-newfb)
[210.177967] Starting subtest: extended-modeset-hang-newfb
[210.179956] Starting dynamic subtest: pipe-A
[244.828205] Dynamic subtest pipe-A: SUCCESS (34.643s)
[244.836870] Starting dynamic subtest: pipe-D
[267.786385] Dynamic subtest pipe-D: SUCCESS (22.957s)
[267.788600] Subtest extended-modeset-hang-newfb: SUCCESS (57.604s)
[268.553565] [069/130] (749s left) kms_async_flips (invalid-async-flip)
[268.650955] Starting subtest: invalid-async-flip
[268.655301] Subtest invalid-async-flip: SKIP (0.000s)
[269.778297] [070/130] (748s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[269.883446] Starting subtest: fb-8bpc-vs-panel-8bpc
[269.885767] Starting dynamic subtest: pipe-A-eDP-1
[271.253474] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.368s)
[271.255877] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.369s)
[272.026140] [071/130] (745s left) kms_plane_scaling (planes-scaler-unity-scaling)
[272.129184] Starting subtest: planes-scaler-unity-scaling
[272.131459] Starting dynamic subtest: pipe-A
[272.198628] Dynamic subtest pipe-A: SUCCESS (0.069s)
[272.208194] Starting dynamic subtest: pipe-B
[273.370536] Dynamic subtest pipe-B: SUCCESS (1.170s)
[273.379763] Starting dynamic subtest: pipe-C
[274.465867] Dynamic subtest pipe-C: SUCCESS (1.094s)
[274.474797] Starting dynamic subtest: pipe-D
[275.596587] Dynamic subtest pipe-D: SUCCESS (1.130s)
[275.598761] Subtest planes-scaler-unity-scaling: SUCCESS (3.468s)
[276.733825] [072/130] (741s left) gem_exec_store (pages)
[276.799764] Starting subtest: pages
[276.802177] Starting dynamic subtest: rcs0
[276.919705] Dynamic subtest rcs0: SUCCESS (0.120s)
[276.929784] Starting dynamic subtest: bcs0
[276.995413] Dynamic subtest bcs0: SUCCESS (0.073s)
[277.004512] Starting dynamic subtest: vcs0
[277.061875] Dynamic subtest vcs0: SUCCESS (0.066s)
[277.070563] Starting dynamic subtest: vcs1
[277.134958] Dynamic subtest vcs1: SUCCESS (0.072s)
[277.144347] Starting dynamic subtest: vecs0
[277.201160] Dynamic subtest vecs0: SUCCESS (0.065s)
[277.210503] Starting dynamic subtest: ccs0
[277.266139] Dynamic subtest ccs0: SUCCESS (0.064s)
[277.268421] Subtest pages: SUCCESS (0.467s)
[277.431478] [073/130] (740s left) kms_psr (fbc-psr2-sprite-mmap-gtt)
[277.530385] Starting subtest: fbc-psr2-sprite-mmap-gtt
[277.532679] Starting dynamic subtest: eDP-1
[277.537104] Dynamic subtest eDP-1: SKIP (0.000s)
[277.541301] Subtest fbc-psr2-sprite-mmap-gtt: SKIP (0.001s)
[277.583391] [074/130] (740s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[277.765459] Starting subtest: cursor-rapid-movement-128x128
[277.770098] Starting dynamic subtest: pipe-A-eDP-1
[278.308972] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.537s)
[278.334526] Starting dynamic subtest: pipe-D-eDP-1
[279.554622] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.225s)
[279.557080] Subtest cursor-rapid-movement-128x128: SUCCESS (1.788s)
[280.390487] [075/130] (737s left) kms_chamelium_color (ctm-negative)
[280.498245] Subtest ctm-negative: SKIP (0.000s)
[280.521423] [076/130] (737s left) fbdev (unaligned-read)
[280.565457] Starting subtest: unaligned-read
[280.582178] Subtest unaligned-read: SUCCESS (0.017s)
[280.612618] [077/130] (737s left) dmabuf_sync_file (import-multiple-read-write)
[280.655903] Starting subtest: import-multiple-read-write
[280.658311] Subtest import-multiple-read-write: SUCCESS (0.003s)
[280.691999] [078/130] (737s left) gem_exec_fence (invalid-fence-array)
[281.140706] Starting subtest: invalid-fence-array
[281.146541] Subtest invalid-fence-array: SUCCESS (0.004s)
[283.005183] [079/130] (734s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[283.128317] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[283.132654] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[283.221952] [080/130] (734s left) syncobj_timeline (invalid-query-bad-pad)
[283.276629] Starting subtest: invalid-query-bad-pad
[283.278930] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[283.356578] [081/130] (734s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[283.447132] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[283.449457] Starting dynamic subtest: pipe-A-eDP-1
[283.677547] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.198s)
[283.687278] Starting dynamic subtest: pipe-B-eDP-1
[284.968470] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.287s)
[284.978412] Starting dynamic subtest: pipe-C-eDP-1
[286.168432] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.199s)
[286.176876] Starting dynamic subtest: pipe-D-eDP-1
[287.399607] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.230s)
[287.401667] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.920s)
[288.604049] [082/130] (729s left) prime_mmap (test_invalid_sync_flags)
[288.666768] Starting subtest: test_invalid_sync_flags
[288.669068] Starting dynamic subtest: test_invalid_sync_flags-smem
[288.670927] Dynamic subtest test_invalid_sync_flags-smem: SUCCESS (0.000s)
[288.672847] Subtest test_invalid_sync_flags: SUCCESS (0.001s)
[288.741339] [083/130] (729s left) kms_properties (invalid-properties-atomic)
[288.838308] Starting subtest: invalid-properties-atomic
[289.086531] Subtest invalid-properties-atomic: SUCCESS (0.247s)
[289.139665] [084/130] (728s left) gem_userptr_blits (coherency-sync)
[289.214613] Starting subtest: coherency-sync
[289.574609] Subtest coherency-sync: SKIP (0.322s)
[289.661870] [085/130] (728s left) gem_exec_balancer (noheartbeat)
[289.944444] Starting subtest: noheartbeat
[289.948984] Subtest noheartbeat: SKIP (0.000s)
[290.037246] [086/130] (728s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[290.096589] Starting subtest: invalid-bsd2-flag-on-vebox
[290.099080] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[290.175028] [087/130] (727s left) gem_tiling_max_stride
[290.268525] [088/130] (727s left) gem_exec_schedule (preempt-queue-chain)
[290.355654] Subtest preempt-queue-chain: SKIP (0.000s)
[290.622916] [089/130] (727s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-blt)
[290.720830] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-blt
[290.724790] Subtest fbc-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[290.794416] [090/130] (727s left) kms_plane (plane-position-covered)
[290.885112] Starting subtest: plane-position-covered
[291.394921] Starting dynamic subtest: pipe-A-plane-1
[292.679516] Dynamic subtest pipe-A-plane-1: SUCCESS (1.286s)
[292.687603] Starting dynamic subtest: pipe-A-plane-2
[293.903048] Dynamic subtest pipe-A-plane-2: SUCCESS (1.223s)
[293.911519] Starting dynamic subtest: pipe-A-plane-3
[295.135520] Dynamic subtest pipe-A-plane-3: SUCCESS (1.229s)
[295.145301] Starting dynamic subtest: pipe-A-plane-4
[296.362354] Dynamic subtest pipe-A-plane-4: SUCCESS (1.228s)
[296.373171] Starting dynamic subtest: pipe-A-plane-5
[296.387083] Dynamic subtest pipe-A-plane-5: SUCCESS (0.023s)
[297.507782] Starting dynamic subtest: pipe-B-plane-1
[298.743856] Dynamic subtest pipe-B-plane-1: SUCCESS (1.241s)
[298.752456] Starting dynamic subtest: pipe-B-plane-2
[300.040767] Dynamic subtest pipe-B-plane-2: SUCCESS (1.296s)
[300.049544] Starting dynamic subtest: pipe-B-plane-3
[301.269256] Dynamic subtest pipe-B-plane-3: SUCCESS (1.228s)
[301.278113] Starting dynamic subtest: pipe-B-plane-4
[302.484781] Dynamic subtest pipe-B-plane-4: SUCCESS (1.215s)
[302.493275] Starting dynamic subtest: pipe-B-plane-5
[302.509933] Dynamic subtest pipe-B-plane-5: SUCCESS (0.023s)
[302.512103] Subtest plane-position-covered: SUCCESS (11.622s)
[303.273547] [091/130] (714s left) syncobj_timeline (single-wait-available-submitted)
[303.323520] Starting subtest: single-wait-available-submitted
[303.325474] Subtest single-wait-available-submitted: SUCCESS (0.000s)
[303.395287] [092/130] (714s left) gem_workarounds (reset-context)
[303.463273] Starting subtest: reset-context
[303.641786] Subtest reset-context: SUCCESS (0.178s)
[303.748202] [093/130] (714s left) kms_flip (basic-plain-flip)
[303.858501] Starting subtest: basic-plain-flip
[303.860771] Starting dynamic subtest: A-eDP1
[305.589176] Dynamic subtest A-eDP1: SUCCESS (1.732s)
[305.600411] Starting dynamic subtest: B-eDP1
[307.214906] Dynamic subtest B-eDP1: SUCCESS (1.625s)
[307.225066] Starting dynamic subtest: C-eDP1
[308.829063] Dynamic subtest C-eDP1: SUCCESS (1.612s)
[308.837926] Starting dynamic subtest: D-eDP1
[310.459991] Dynamic subtest D-eDP1: SUCCESS (1.629s)
[310.465459] Subtest basic-plain-flip: SUCCESS (6.609s)
[311.607335] [094/130] (706s left) kms_chamelium_hpd (common-hpd-after-suspend)
[311.996235] Subtest common-hpd-after-suspend: SKIP (0.000s)
[312.808401] [095/130] (705s left) gem_mmap_gtt (basic-small-bo-tiledx)
[312.868225] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[312.904948] [096/130] (705s left) gem_cs_tlb (engines)
[312.964521] Starting subtest: engines
[312.966696] Starting dynamic subtest: rcs0
[315.120527] Dynamic subtest rcs0: SUCCESS (2.153s)
[315.128388] Starting dynamic subtest: bcs0
[317.274069] Dynamic subtest bcs0: SUCCESS (2.152s)
[317.283017] Starting dynamic subtest: vcs0
[319.429705] Dynamic subtest vcs0: SUCCESS (2.154s)
[319.438089] Starting dynamic subtest: vcs1
[321.583861] Dynamic subtest vcs1: SUCCESS (2.154s)
[321.592291] Starting dynamic subtest: vecs0
[323.737544] Dynamic subtest vecs0: SUCCESS (2.152s)
[323.746297] Starting dynamic subtest: ccs0
[325.893083] Dynamic subtest ccs0: SUCCESS (2.155s)
[325.895209] Subtest engines: SUCCESS (12.925s)
[325.995507] [097/130] (692s left) gem_exec_reloc (basic-active)
[326.052143] Subtest basic-active: SKIP (0.000s)
[326.118619] [098/130] (692s left) gem_unref_active_buffers
[326.304109] [099/130] (692s left) gen9_exec_parse (bb-start-param)
[326.361526] Subtest bb-start-param: SKIP (0.000s)
[326.419512] [100/130] (691s left) gem_mmap_gtt (basic-read-write)
[326.483168] Subtest basic-read-write: SKIP (0.000s)
[326.520715] [101/130] (691s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[326.628054] Starting subtest: psr-rgb101010-draw-pwrite
[332.198751] Subtest psr-rgb101010-draw-pwrite: SUCCESS (5.572s)
[332.289927] [102/130] (686s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[332.402016] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[332.405964] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[332.493036] [103/130] (685s left) api_intel_bb (destroy-bb)
[332.557587] Starting subtest: destroy-bb
[332.567543] Subtest destroy-bb: SUCCESS (0.009s)
[332.668244] [104/130] (685s left) syncobj_timeline (wait-all-snapshot)
[332.725500] Starting subtest: wait-all-snapshot
[332.807258] Subtest wait-all-snapshot: SUCCESS (0.081s)
[332.862877] [105/130] (685s left) kms_big_fb (x-tiled-addfb)
[332.969919] Starting subtest: x-tiled-addfb
[332.972168] Subtest x-tiled-addfb: SUCCESS (0.000s)
[333.052243] [106/130] (685s left) kms_psr (fbc-pr-sprite-plane-move)
[333.150702] Starting subtest: fbc-pr-sprite-plane-move
[333.154386] Subtest fbc-pr-sprite-plane-move: SKIP (0.000s)
[333.202092] [107/130] (685s left) kms_properties (crtc-properties-atomic)
[333.305784] Starting subtest: crtc-properties-atomic
[333.307854] Starting dynamic subtest: pipe-A-eDP-1
[333.739516] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.430s)
[333.749453] Starting dynamic subtest: pipe-B-eDP-1
[334.869740] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.130s)
[334.879040] Starting dynamic subtest: pipe-C-eDP-1
[335.983372] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.113s)
[335.993530] Starting dynamic subtest: pipe-D-eDP-1
[337.101761] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.116s)
[337.103768] Subtest crtc-properties-atomic: SUCCESS (3.794s)
[337.887113] [108/130] (680s left) kms_sequence (get-busy)
[337.990318] Starting subtest: get-busy
[337.992559] Starting dynamic subtest: pipe-A-eDP-1
[339.923223] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.930s)
[339.932152] Starting dynamic subtest: pipe-B-eDP-1
[342.506608] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.580s)
[342.515577] Starting dynamic subtest: pipe-C-eDP-1
[345.112584] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.604s)
[345.121998] Starting dynamic subtest: pipe-D-eDP-1
[347.640793] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.529s)
[347.643139] Subtest get-busy: SUCCESS (9.649s)
[348.409291] [109/130] (670s left) gem_exec_balancer (bonded-false-hang)
[348.660514] Starting subtest: bonded-false-hang
[348.665735] Subtest bonded-false-hang: SKIP (0.000s)
[348.744925] [110/130] (669s left) gem_exec_flush (basic-wb-rw-default)
[348.816888] Starting subtest: basic-wb-rw-default
[354.299369] Subtest basic-wb-rw-default: SUCCESS (5.482s)
[354.465980] [111/130] (664s left) core_getversion (basic)
[354.512077] Starting subtest: basic
[354.516212] Subtest basic: SUCCESS (0.000s)
[354.556422] [112/130] (664s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-wc)
[354.658106] Starting subtest: fbc-rgb101010-draw-mmap-wc
[358.160091] Subtest fbc-rgb101010-draw-mmap-wc: SUCCESS (3.505s)
[358.244329] [113/130] (660s left) gem_exec_fence (syncobj-invalid-wait)
[358.698979] Starting subtest: syncobj-invalid-wait
[358.701462] Subtest syncobj-invalid-wait: SUCCESS (0.001s)
[359.974980] [114/130] (658s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[360.027314] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[360.029588] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[360.103744] [115/130] (658s left) gem_tiled_swapping (non-threaded)
[360.156619] Subtest non-threaded: SKIP (0.000s)
[360.193206] [116/130] (658s left) perf_pmu (idle)
[360.237591] Starting subtest: idle
[360.239792] Starting dynamic subtest: rcs0
[360.791592] Dynamic subtest rcs0: SUCCESS (0.555s)
[360.802961] Starting dynamic subtest: bcs0
[361.318094] Dynamic subtest bcs0: SUCCESS (0.523s)
[361.329076] Starting dynamic subtest: vcs0
[361.835979] Dynamic subtest vcs0: SUCCESS (0.516s)
[361.847566] Starting dynamic subtest: vcs1
[362.351843] Dynamic subtest vcs1: SUCCESS (0.514s)
[362.361968] Starting dynamic subtest: vecs0
[362.874369] Dynamic subtest vecs0: SUCCESS (0.520s)
[362.883886] Starting dynamic subtest: ccs0
[363.400076] Dynamic subtest ccs0: SUCCESS (0.524s)
[363.402918] Subtest idle: SUCCESS (3.164s)
[363.498646] [117/130] (655s left) kms_pm_rpm (modeset-non-lpsp)
[365.026116] Starting subtest: modeset-non-lpsp
[366.187336] Subtest modeset-non-lpsp: SKIP (1.157s)
[366.519261] [118/130] (652s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot)
[366.648690] Starting subtest: modeset-atomic-cursor-hotspot
[366.653376] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s)
[366.772118] [119/130] (651s left) gem_linear_blits (normal)
[366.925058] Starting subtest: normal
[370.619510] Subtest normal: SUCCESS (3.693s)
[370.744515] [120/130] (647s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-mmap-wc)
[370.882282] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-mmap-wc
[373.728587] Subtest fbc-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (2.847s)
[373.830412] [121/130] (644s left) kms_hdr (static-toggle-dpms)
[373.921296] Starting subtest: static-toggle-dpms
[373.928331] Subtest static-toggle-dpms: SKIP (0.000s)
[373.974212] [122/130] (644s left) gem_exec_reloc (basic-write-gtt-noreloc)
[374.029298] Subtest basic-write-gtt-noreloc: SKIP (0.000s)
[374.098325] [123/130] (644s left) gem_mmap_offset (partial-mmap)
[374.148188] Starting subtest: partial-mmap
[374.150324] Starting dynamic subtest: smem0
[374.152417] Dynamic subtest smem0: SUCCESS (0.004s)
[374.154175] Subtest partial-mmap: SUCCESS (0.004s)
[374.268172] [124/130] (644s left) gem_exec_flush (basic-batch-kernel-default-uc)
[374.324626] Starting subtest: basic-batch-kernel-default-uc
[380.778733] Subtest basic-batch-kernel-default-uc: SUCCESS (6.453s)
[380.946496] [125/130] (637s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[381.048333] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[386.245188] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (5.200s)
[386.372354] [126/130] (632s left) kms_invalid_mode (zero-clock)
[386.484980] Starting subtest: zero-clock
[386.487366] Starting dynamic subtest: pipe-A-eDP-1
[386.771148] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[386.781376] Starting dynamic subtest: pipe-B-eDP-1
[386.783250] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[386.791318] Starting dynamic subtest: pipe-C-eDP-1
[386.793533] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[386.801706] Starting dynamic subtest: pipe-D-eDP-1
[386.803809] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[386.805890] Subtest zero-clock: SUCCESS (0.290s)
[387.616775] [127/130] (631s left) syncobj_wait (wait-for-submit-complex)
[387.681292] Starting subtest: wait-for-submit-complex
[387.743084] Subtest wait-for-submit-complex: SUCCESS (0.061s)
[387.798480] [128/130] (631s left) gem_exec_params (cliprects-invalid)
[387.869595] Starting subtest: cliprects-invalid
[387.871973] Subtest cliprects-invalid: SUCCESS (0.000s)
[387.947960] [129/130] (630s left) gem_ringfill (basic-all)
[393.902506] Starting subtest: basic-all
[395.067599] Subtest basic-all: SUCCESS (1.165s)
[395.171634] [130/130] (623s left) kms_properties (connector-properties-atomic)
[395.279089] Starting subtest: connector-properties-atomic
[395.281309] Starting dynamic subtest: pipe-A-eDP-1
[403.088710] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.807s)
[403.097242] Starting dynamic subtest: pipe-None-DP-1
[403.101543] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[403.109566] Starting dynamic subtest: pipe-None-HDMI-A-1
[403.113652] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[403.122104] Starting dynamic subtest: pipe-None-DP-2
[403.126592] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[403.134756] Starting dynamic subtest: pipe-None-HDMI-A-2
[403.139280] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[403.147535] Starting dynamic subtest: pipe-None-DP-3
[403.152079] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[403.160075] Starting dynamic subtest: pipe-None-DP-4
[403.164566] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[403.166820] Subtest connector-properties-atomic: SUCCESS (7.818s)
[403.926672] Closing watchdogs
Done.