[35.649032] Initializing watchdogs
[35.649063]   /dev/watchdog0
[35.655691] [001/130] (960s left) kms_big_fb (x-tiled-32bpp-rotate-0)
[35.763271] Starting subtest: x-tiled-32bpp-rotate-0
[37.081376] Subtest x-tiled-32bpp-rotate-0: SUCCESS (1.319s)
[37.203182] [002/130] (958s left) gem_ctx_persistence (hostile)
[37.267691] Starting subtest: hostile
[38.798743] Subtest hostile: FAIL (1.478s)
[38.841121] [003/130] (957s left) gem_exec_fence (syncobj-timeline-wait)
[39.368028] Starting subtest: syncobj-timeline-wait
[40.397740] Subtest syncobj-timeline-wait: SUCCESS (1.029s)
[40.603802] [004/130] (955s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-blt)
[40.706712] Starting subtest: psr-1p-primscrn-spr-indfb-draw-blt
[45.210420] Subtest psr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (4.507s)
[45.331113] [005/130] (950s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[45.415169] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[45.417522] Starting dynamic subtest: pipe-A-default-mode
[48.242399] Dynamic subtest pipe-A-default-mode: SUCCESS (2.825s)
[48.245459] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.826s)
[49.099974] [006/130] (947s left) kms_universal_plane (disable-primary-vs-flip)
[49.195031] Starting subtest: disable-primary-vs-flip
[49.197500] Starting dynamic subtest: pipe-A-eDP-1
[50.767391] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.571s)
[50.775070] Starting dynamic subtest: pipe-B-eDP-1
[53.061952] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.294s)
[53.068726] Starting dynamic subtest: pipe-C-eDP-1
[55.374348] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.311s)
[55.383199] Starting dynamic subtest: pipe-D-eDP-1
[57.679840] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.304s)
[57.682072] Subtest disable-primary-vs-flip: SUCCESS (8.483s)
[58.572873] [007/130] (937s left) kms_chamelium_audio (hdmi-audio-edid)
[58.960789] Subtest hdmi-audio-edid: SKIP (0.000s)
[59.836243] [008/130] (936s left) template (b)
[59.888245] Starting subtest: B
[59.890513] Subtest B: SUCCESS (0.000s)
[59.949224] [009/130] (936s left) kms_big_fb (linear-64bpp-rotate-0)
[60.050889] Starting subtest: linear-64bpp-rotate-0
[61.470024] Subtest linear-64bpp-rotate-0: SUCCESS (1.420s)
[61.614982] [010/130] (934s left) gem_exec_flush (basic-wb-ro-default)
[61.674980] Starting subtest: basic-wb-ro-default
[67.149538] Subtest basic-wb-ro-default: SUCCESS (5.421s)
[67.252712] [011/130] (929s left) gem_partial_pwrite_pread (writes-after-reads-display)
[67.309686] Subtest writes-after-reads-display: SKIP (0.000s)
[67.352356] [012/130] (928s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size)
[67.460866] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size
[68.411652] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (0.949s)
[69.320449] [013/130] (927s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt)
[69.413835] Starting subtest: fbcpsr-rgb565-draw-blt
[74.146907] Subtest fbcpsr-rgb565-draw-blt: SUCCESS (4.736s)
[74.257383] [014/130] (922s left) syncobj_timeline (multi-wait-submitted-signaled)
[74.299071] Starting subtest: multi-wait-submitted-signaled
[74.301515] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s)
[74.382241] [015/130] (921s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[74.476188] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[77.153305] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (2.682s)
[77.275308] [016/130] (919s left) syncobj_basic (bad-handle-to-fd)
[77.318400] Starting subtest: bad-handle-to-fd
[77.321140] Subtest bad-handle-to-fd: SUCCESS (0.000s)
[77.363214] [017/130] (919s left) gem_eio (create)
[77.555653] Starting subtest: create
[77.842363] Subtest create: SUCCESS (0.286s)
[77.938470] [018/130] (918s left) sw_sync (sync_busy)
[77.965767] Starting subtest: sync_busy
[77.967801] Subtest sync_busy: SUCCESS (0.001s)
[77.994618] [019/130] (918s left) kms_psr (fbc-pr-primary-render)
[78.079831] Starting subtest: fbc-pr-primary-render
[78.083767] Subtest fbc-pr-primary-render: SKIP (0.000s)
[78.128147] [020/130] (918s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[78.220645] Starting subtest: extended-modeset-hang-oldfb-with-reset
[78.222700] Starting dynamic subtest: pipe-A
[90.060638] Dynamic subtest pipe-A: SUCCESS (11.838s)
[90.068349] Starting dynamic subtest: pipe-D
[102.863191] Dynamic subtest pipe-D: SUCCESS (12.798s)
[102.866452] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.641s)
[103.757838] [021/130] (892s left) gem_tiled_pread_basic
[103.850605] [022/130] (892s left) kms_pm_rpm (modeset-lpsp)
[105.574348] Starting subtest: modeset-lpsp
[109.964860] Subtest modeset-lpsp: SUCCESS (4.391s)
[110.063150] [023/130] (886s left) api_intel_bb (intel-bb-blit-x)
[110.121713] Starting subtest: intel-bb-blit-x
[113.598091] Subtest intel-bb-blit-x: SUCCESS (3.477s)
[113.692062] [024/130] (882s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff)
[113.792859] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff
[113.797246] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[113.851356] [025/130] (882s left) kms_cursor_crc (cursor-offscreen-32x10)
[113.977929] Starting subtest: cursor-offscreen-32x10
[114.011984] Subtest cursor-offscreen-32x10: SKIP (0.030s)
[114.218660] [026/130] (882s left) kms_cursor_legacy (basic-flip-before-cursor-atomic)
[114.334907] Starting subtest: basic-flip-before-cursor-atomic
[115.252078] Subtest basic-flip-before-cursor-atomic: SUCCESS (0.914s)
[116.170613] [027/130] (880s left) kms_ccs (crc-primary-basic-y-tiled-gen12-rc-ccs)
[116.259770] Starting subtest: crc-primary-basic-y-tiled-gen12-rc-ccs
[116.262107] Starting dynamic subtest: pipe-A-eDP-1
[116.284521] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[116.291452] Starting dynamic subtest: pipe-B-eDP-1
[116.315030] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[116.322127] Starting dynamic subtest: pipe-C-eDP-1
[116.344035] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[116.350266] Starting dynamic subtest: pipe-D-eDP-1
[116.372384] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[116.376793] Subtest crc-primary-basic-y-tiled-gen12-rc-ccs: SKIP (0.003s)
[116.391063] [028/130] (880s left) gem_exec_flush (basic-uc-ro-default)
[116.439553] Starting subtest: basic-uc-ro-default
[121.915787] Subtest basic-uc-ro-default: SUCCESS (5.418s)
[122.013433] [029/130] (874s left) kms_frontbuffer_tracking (fbcpsr-1p-indfb-fliptrack-mmap-gtt)
[122.110989] Starting subtest: fbcpsr-1p-indfb-fliptrack-mmap-gtt
[122.115242] Subtest fbcpsr-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[122.176604] [030/130] (874s left) gem_create (create-ext-placement-sanity-check)
[122.332109] Starting subtest: create-ext-placement-sanity-check
[122.338475] Subtest create-ext-placement-sanity-check: SUCCESS (0.004s)
[122.404154] [031/130] (874s left) kms_lease (master-vs-lease)
[122.484900] Starting subtest: master-vs-lease
[122.487463] Subtest master-vs-lease: SUCCESS (0.001s)
[122.552386] [032/130] (874s left) kms_writeback (writeback-invalid-parameters)
[122.640075] Subtest writeback-invalid-parameters: SKIP (0.000s)
[122.662353] [033/130] (873s left) syncobj_timeline (etime-multi-wait-all-available-unsubmitted)
[122.710922] Starting subtest: etime-multi-wait-all-available-unsubmitted
[122.813865] Subtest etime-multi-wait-all-available-unsubmitted: SUCCESS (0.103s)
[122.865476] [034/130] (873s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[125.443951] Subtest verify-pxp-key-change-after-suspend-resume: SKIP (0.000s)
[125.488585] [035/130] (871s left) tools_test (tools_test)
[125.531280] Starting subtest: tools_test
[125.564164] Subtest tools_test: SUCCESS (0.050s)
[125.597848] [036/130] (871s left) perf (short-reads)
[125.715652] Starting subtest: short-reads
[125.728603] Subtest short-reads: SUCCESS (0.014s)
[125.743823] [037/130] (870s left) gem_exec_schedule (preempt-queue)
[125.820779] Subtest preempt-queue: SKIP (0.000s)
[126.085507] [038/130] (870s left) kms_flip (flip-vs-absolute-wf_vblank)
[126.184159] Starting subtest: flip-vs-absolute-wf_vblank
[126.186395] Starting dynamic subtest: A-eDP1
[135.178696] Dynamic subtest A-eDP1: SUCCESS (8.993s)
[135.187044] Starting dynamic subtest: B-eDP1
[144.202585] Dynamic subtest B-eDP1: SUCCESS (9.024s)
[144.212999] Starting dynamic subtest: C-eDP1
[153.215062] Dynamic subtest C-eDP1: SUCCESS (9.010s)
[153.222728] Starting dynamic subtest: D-eDP1
[162.176061] Dynamic subtest D-eDP1: SUCCESS (8.961s)
[162.178916] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.995s)
[163.406503] [039/130] (833s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-sf)
[168.555769] Starting subtest: psr2-cursor-plane-move-continuous-sf
[168.558241] Starting dynamic subtest: pipe-A-eDP-1
[179.691967] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.136s)
[179.700632] Starting dynamic subtest: pipe-B-eDP-1
[190.832208] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.137s)
[190.834572] Subtest psr2-cursor-plane-move-continuous-sf: SUCCESS (22.275s)
[191.690638] [040/130] (805s left) kms_color (legacy-gamma-reset)
[191.782946] Starting subtest: legacy-gamma-reset
[191.785377] Starting dynamic subtest: pipe-A-eDP-1
[192.132616] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.346s)
[192.139995] Starting dynamic subtest: pipe-B-eDP-1
[192.142398] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.008s)
[192.149038] Starting dynamic subtest: pipe-C-eDP-1
[192.151626] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.008s)
[192.158202] Starting dynamic subtest: pipe-D-eDP-1
[192.160360] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.008s)
[192.162607] Subtest legacy-gamma-reset: SUCCESS (0.374s)
[193.035082] [041/130] (803s left) kms_cursor_crc (cursor-sliding-256x256)
[193.232516] Starting subtest: cursor-sliding-256x256
[193.234829] Starting dynamic subtest: pipe-A-eDP-1
[196.408846] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.174s)
[196.427370] Starting dynamic subtest: pipe-D-eDP-1
[200.370229] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.948s)
[200.373233] Subtest cursor-sliding-256x256: SUCCESS (7.140s)
[201.240102] [042/130] (795s left) prime_vgem (fence-wait)
[201.312552] Starting subtest: fence-wait
[201.315057] Starting dynamic subtest: rcs0
[202.360221] Dynamic subtest rcs0: SUCCESS (1.047s)
[202.369440] Starting dynamic subtest: bcs0
[203.417196] Dynamic subtest bcs0: SUCCESS (1.056s)
[203.425346] Starting dynamic subtest: vcs0
[204.464400] Dynamic subtest vcs0: SUCCESS (1.046s)
[204.472079] Starting dynamic subtest: vcs1
[205.503688] Dynamic subtest vcs1: SUCCESS (1.039s)
[205.511258] Starting dynamic subtest: vecs0
[206.544479] Dynamic subtest vecs0: SUCCESS (1.039s)
[206.552727] Starting dynamic subtest: ccs0
[207.586592] Dynamic subtest ccs0: SUCCESS (1.040s)
[207.588815] Subtest fence-wait: SUCCESS (6.272s)
[207.703409] [043/130] (789s left) kms_flip (modeset-vs-vblank-race)
[207.799042] Starting subtest: modeset-vs-vblank-race
[207.801315] Starting dynamic subtest: A-eDP1
[212.703048] Dynamic subtest A-eDP1: SUCCESS (4.902s)
[212.713355] Starting dynamic subtest: B-eDP1
[217.631342] Dynamic subtest B-eDP1: SUCCESS (4.927s)
[217.640319] Starting dynamic subtest: C-eDP1
[222.535053] Dynamic subtest C-eDP1: SUCCESS (4.903s)
[222.544217] Starting dynamic subtest: D-eDP1
[227.401427] Dynamic subtest D-eDP1: SUCCESS (4.865s)
[227.407033] Subtest modeset-vs-vblank-race: SUCCESS (19.607s)
[228.683535] [044/130] (768s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[228.726594] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[228.729268] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[228.795149] [045/130] (767s left) gem_userptr_blits (vma-merge)
[228.858374] Starting subtest: vma-merge
[228.882892] Subtest vma-merge: SUCCESS (0.024s)
[228.985768] [046/130] (767s left) core_setmaster_vs_auth
[229.109034] [047/130] (767s left) kms_joiner (basic-big-joiner)
[229.212099] Starting subtest: basic-big-joiner
[229.216814] Subtest basic-big-joiner: SKIP (0.000s)
[229.256037] [048/130] (767s left) kms_flip (2x-flip-vs-wf_vblank)
[229.345144] Starting subtest: 2x-flip-vs-wf_vblank
[229.359211] Subtest 2x-flip-vs-wf_vblank: SKIP (0.014s)
[229.408255] [049/130] (767s left) prime_udl
[229.463451] [050/130] (767s left) kms_fb_coherency (memset-crc)
[229.548129] Starting subtest: memset-crc
[229.549967] Starting dynamic subtest: mmap-offset-wc
[229.884316] Dynamic subtest mmap-offset-wc: SUCCESS (0.338s)
[230.171282] Starting dynamic subtest: mmap-offset-uc
[236.354816] Dynamic subtest mmap-offset-uc: SUCCESS (6.192s)
[236.646867] Subtest memset-crc: SUCCESS (7.100s)
[237.517267] [051/130] (759s left) kms_cursor_crc (cursor-onscreen-max-size)
[237.768344] Starting subtest: cursor-onscreen-max-size
[237.775089] Subtest cursor-onscreen-max-size: SKIP (0.000s)
[237.874735] [052/130] (759s left) gem_ppgtt (shrink-vs-evict-any)
[237.937213] Starting subtest: shrink-vs-evict-any
[243.411403] Subtest shrink-vs-evict-any: SUCCESS (5.471s)
[243.449361] [053/130] (753s left) gem_mmap_wc (read-write)
[243.502121] Subtest read-write: SKIP (0.000s)
[243.541175] [054/130] (753s left) vgem_basic (create)
[243.576386] Starting subtest: create
[243.578941] Subtest create: SUCCESS (0.000s)
[243.608587] [055/130] (753s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0)
[243.713630] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0
[244.970928] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0: FAIL (1.098s)
[244.986164] [056/130] (751s left) gem_exec_balancer (hog)
[245.042096] Starting subtest: hog
[245.045875] Subtest hog: SKIP (0.000s)
[245.278485] [057/130] (751s left) kms_plane_scaling (planes-upscale-20x20)
[245.368893] Starting subtest: planes-upscale-20x20
[245.371286] Starting dynamic subtest: pipe-A
[245.430592] Dynamic subtest pipe-A: SUCCESS (0.061s)
[245.439445] Starting dynamic subtest: pipe-B
[246.623688] Dynamic subtest pipe-B: SUCCESS (1.192s)
[246.633219] Starting dynamic subtest: pipe-C
[247.815475] Dynamic subtest pipe-C: SUCCESS (1.191s)
[247.822293] Starting dynamic subtest: pipe-D
[248.970083] Dynamic subtest pipe-D: SUCCESS (1.155s)
[248.972458] Subtest planes-upscale-20x20: SUCCESS (3.601s)
[250.189113] [058/130] (746s left) kms_ccs (crc-sprite-planes-basic-y-tiled-ccs)
[250.281777] Starting subtest: crc-sprite-planes-basic-y-tiled-ccs
[250.283946] Starting dynamic subtest: pipe-A-eDP-1
[250.341882] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[250.350208] Starting dynamic subtest: pipe-B-eDP-1
[250.408238] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[250.416110] Starting dynamic subtest: pipe-C-eDP-1
[250.473680] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[250.481763] Starting dynamic subtest: pipe-D-eDP-1
[250.539465] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[250.543975] Subtest crc-sprite-planes-basic-y-tiled-ccs: SKIP (0.006s)
[250.559694] [059/130] (746s left) gem_ctx_param (invalid-get-ringsize)
[250.620992] Starting subtest: invalid-get-ringsize
[250.623532] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[250.686430] [060/130] (746s left) kms_prime (basic-crc-hybrid)
[251.356049] Subtest basic-crc-hybrid: SKIP (0.000s)
[251.415739] [061/130] (745s left) gem_render_copy (yf-tiled-to-vebox-yf-tiled)
[251.454062] Starting subtest: yf-tiled-to-vebox-yf-tiled
[251.457837] Subtest yf-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[251.611983] [062/130] (745s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-pwrite)
[251.703625] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-pwrite
[251.707682] Subtest psr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[251.773497] [063/130] (745s left) kms_psr (pr-sprite-blt)
[251.857813] Starting subtest: pr-sprite-blt
[251.862115] Subtest pr-sprite-blt: SKIP (0.000s)
[251.909131] [064/130] (745s left) gem_exec_whisper (basic-contexts-forked-all)
[251.971287] Starting subtest: basic-contexts-forked-all
[272.489686] Subtest basic-contexts-forked-all: SUCCESS (20.462s)
[272.592431] [065/130] (724s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[272.700606] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[275.918632] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (3.222s)
[276.029831] [066/130] (721s left) kms_frontbuffer_tracking (psr-1p-primscrn-indfb-plflip-blt)
[276.134047] Starting subtest: psr-1p-primscrn-indfb-plflip-blt
[281.154845] Subtest psr-1p-primscrn-indfb-plflip-blt: SUCCESS (5.021s)
[281.266861] [067/130] (715s left) kms_rotation_crc (multiplane-rotation)
[281.355852] Starting subtest: multiplane-rotation
[288.537835] Subtest multiplane-rotation: SUCCESS (7.182s)
[288.627221] [068/130] (708s left) kms_psr2_sf (fbc-pr-overlay-plane-update-sf-dmg-area)
[293.736325] Starting subtest: fbc-pr-overlay-plane-update-sf-dmg-area
[293.741210] Subtest fbc-pr-overlay-plane-update-sf-dmg-area: SKIP (0.002s)
[294.600633] [069/130] (702s left) prime_self_import (basic-with_two_bos)
[294.628887] Starting subtest: basic-with_two_bos
[294.653527] Subtest basic-with_two_bos: SUCCESS (0.025s)
[294.745228] [070/130] (702s left) kms_big_fb (y-tiled-64bpp-rotate-0)
[294.841044] Starting subtest: y-tiled-64bpp-rotate-0
[294.845183] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s)
[294.900388] [071/130] (702s left) kms_psr (psr-sprite-blt)
[294.978864] Starting subtest: psr-sprite-blt
[294.981850] Starting dynamic subtest: eDP-1
[296.785217] Dynamic subtest eDP-1: SUCCESS (1.804s)
[296.787817] Subtest psr-sprite-blt: SUCCESS (1.805s)
[297.677928] [072/130] (699s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-blt)
[297.774632] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-blt
[300.472730] Subtest fbc-1p-primscrn-spr-indfb-draw-blt: SUCCESS (2.704s)
[300.602261] [073/130] (696s left) i915_suspend (basic-s3-without-i915)
[300.632729] Starting subtest: basic-s3-without-i915
[302.427287] Subtest basic-s3-without-i915: SKIP (1.789s)
[303.410261] [074/130] (693s left) dumb_buffer (map-valid)
[303.467207] Starting subtest: map-valid
[303.469661] Subtest map-valid: SUCCESS (0.000s)
[303.540215] [075/130] (693s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc)
[303.618742] Starting subtest: ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc
[303.620995] Starting dynamic subtest: pipe-A-eDP-1
[303.640423] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[303.647805] Starting dynamic subtest: pipe-B-eDP-1
[303.667272] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[303.673681] Starting dynamic subtest: pipe-C-eDP-1
[303.692273] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[303.697615] Starting dynamic subtest: pipe-D-eDP-1
[303.716169] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[303.718239] Subtest ccs-on-another-bo-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.004s)
[303.731012] [076/130] (693s left) gem_compute (compute-square)
[303.783049] Starting subtest: compute-square
[303.787800] Subtest compute-square: SKIP (0.000s)
[303.845023] [077/130] (693s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-upscaling)
[303.923976] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-upscaling
[303.926460] Starting dynamic subtest: pipe-A-default-mode
[306.772624] Dynamic subtest pipe-A-default-mode: SUCCESS (2.844s)
[306.775329] Subtest flip-64bpp-xtile-to-32bpp-xtile-upscaling: SUCCESS (2.846s)
[307.666107] [078/130] (689s left) gem_mmap (short-mmap)
[307.718246] Subtest short-mmap: SKIP (0.000s)
[307.752126] [079/130] (689s left) gem_exec_fence (syncobj-invalid-wait)
[308.128587] Starting subtest: syncobj-invalid-wait
[308.131585] Subtest syncobj-invalid-wait: SUCCESS (0.000s)
[309.380715] [080/130] (687s left) kms_chamelium_edid (dp-edid-change-during-suspend)
[309.766165] Subtest dp-edid-change-during-suspend: SKIP (0.000s)
[310.646497] [081/130] (686s left) debugfs_test (sysfs)
[310.710449] Starting subtest: sysfs
[310.771678] Subtest sysfs: SUCCESS (0.063s)
[310.865927] [082/130] (686s left) kms_vrr (lobf)
[310.960359] Starting subtest: lobf
[310.964879] Subtest lobf: SKIP (0.000s)
[311.009757] [083/130] (686s left) i915_hangman (detector)
[311.064542] Starting subtest: detector
[311.066804] Starting dynamic subtest: rcs0
[321.353243] Dynamic subtest rcs0: SUCCESS (10.288s)
[321.362591] Starting dynamic subtest: bcs0
[333.321724] Dynamic subtest bcs0: SUCCESS (11.967s)
[333.331761] Starting dynamic subtest: vcs0
[345.352373] Dynamic subtest vcs0: SUCCESS (12.029s)
[345.361851] Starting dynamic subtest: vcs1
[357.319462] Dynamic subtest vcs1: SUCCESS (11.965s)
[357.328061] Starting dynamic subtest: vecs0
[368.265523] Dynamic subtest vecs0: SUCCESS (10.944s)
[368.275565] Starting dynamic subtest: ccs0
[379.337823] Dynamic subtest ccs0: SUCCESS (11.071s)
[379.340750] Subtest detector: SUCCESS (68.271s)
[379.393101] [084/130] (617s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[379.487847] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc
[384.153198] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (4.670s)
[384.253446] [085/130] (613s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted-signaled)
[384.311821] Starting subtest: multi-wait-all-for-submit-unsubmitted-signaled
[384.412966] Subtest multi-wait-all-for-submit-unsubmitted-signaled: SUCCESS (0.102s)
[384.466437] [086/130] (612s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[384.557240] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[384.562504] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[384.616702] [087/130] (612s left) kms_pipe_crc_basic (nonblocking-crc-frame-sequence)
[384.702906] Starting subtest: nonblocking-crc-frame-sequence
[384.705327] Starting dynamic subtest: pipe-A-eDP-1
[385.390557] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.684s)
[385.397788] Starting dynamic subtest: pipe-B-eDP-1
[386.815215] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.423s)
[386.822856] Starting dynamic subtest: pipe-C-eDP-1
[388.265507] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.450s)
[388.273548] Starting dynamic subtest: pipe-D-eDP-1
[389.669911] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.403s)
[389.672598] Subtest nonblocking-crc-frame-sequence: SUCCESS (4.965s)
[390.539849] [088/130] (606s left) kms_plane_scaling (plane-downscale-factor-0-25-with-modifiers)
[390.634915] Starting subtest: plane-downscale-factor-0-25-with-modifiers
[390.637292] Starting dynamic subtest: pipe-A
[390.649739] Dynamic subtest pipe-A: SKIP (0.010s)
[390.658398] Starting dynamic subtest: pipe-B
[390.671009] Dynamic subtest pipe-B: SKIP (0.021s)
[390.679214] Starting dynamic subtest: pipe-C
[390.694847] Dynamic subtest pipe-C: SKIP (0.023s)
[390.702702] Starting dynamic subtest: pipe-D
[390.714751] Dynamic subtest pipe-D: SKIP (0.021s)
[390.718669] Subtest plane-downscale-factor-0-25-with-modifiers: SKIP (0.075s)
[390.783274] [089/130] (606s left) api_intel_allocator (execbuf-with-allocator)
[390.837614] Starting subtest: execbuf-with-allocator
[390.841213] Subtest execbuf-with-allocator: SUCCESS (0.007s)
[390.911702] [090/130] (606s left) gem_exec_reloc (basic-wc-cpu-noreloc)
[390.964538] Subtest basic-wc-cpu-noreloc: SKIP (0.000s)
[391.028353] [091/130] (606s left) gem_eio (wait-1us)
[391.203598] Starting subtest: wait-1us
[391.445040] Subtest wait-1us: SUCCESS (0.243s)
[391.558685] [092/130] (605s left) perf (per-context-mode-unprivileged)
[391.650979] Starting subtest: per-context-mode-unprivileged
[391.654706] Subtest per-context-mode-unprivileged: SKIP (0.000s)
[391.666910] [093/130] (605s left) syncobj_timeline (multi-wait-available-signaled)
[391.708467] Starting subtest: multi-wait-available-signaled
[391.710151] Subtest multi-wait-available-signaled: SUCCESS (0.000s)
[391.766848] [094/130] (605s left) gem_exec_endless (dispatch)
[391.810347] Starting subtest: dispatch
[391.812351] Starting dynamic subtest: rcs0
[391.818160] Dynamic subtest rcs0: SUCCESS (0.005s)
[391.828205] Starting dynamic subtest: bcs0
[391.831605] Dynamic subtest bcs0: SUCCESS (0.003s)
[391.837836] Starting dynamic subtest: vcs0
[391.841306] Dynamic subtest vcs0: SUCCESS (0.003s)
[391.847112] Starting dynamic subtest: vcs1
[391.850587] Dynamic subtest vcs1: SUCCESS (0.003s)
[391.856564] Starting dynamic subtest: vecs0
[391.860191] Dynamic subtest vecs0: SUCCESS (0.003s)
[391.865849] Starting dynamic subtest: ccs0
[391.869485] Dynamic subtest ccs0: SUCCESS (0.004s)
[391.871314] Subtest dispatch: SUCCESS (0.022s)
[393.404219] [095/130] (604s left) gem_userptr_blits (coherency-unsync)
[393.460190] Subtest coherency-unsync: SKIP (0.000s)
[393.566254] [096/130] (603s left) gem_lmem_swapping (heavy-verify-random)
[393.602218] Subtest heavy-verify-random: SKIP (0.000s)
[393.622452] [097/130] (603s left) gem_exec_flush (basic-batch-kernel-default-wb)
[393.674788] Starting subtest: basic-batch-kernel-default-wb
[399.251079] Subtest basic-batch-kernel-default-wb: SUCCESS (5.579s)
[399.413211] [098/130] (598s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[399.515803] Starting subtest: 2x-absolute-wf_vblank-interruptible
[399.532393] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.014s)
[399.582133] [099/130] (598s left) i915_pm_rc6_residency (rc6-fence)
[399.634728] Starting subtest: rc6-fence
[399.656859] Starting dynamic subtest: gt0
[411.974091] Dynamic subtest gt0: SUCCESS (12.316s)
[411.982821] Starting dynamic subtest: gt1
[426.347262] Dynamic subtest gt1: SUCCESS (14.370s)
[426.350190] Subtest rc6-fence: SUCCESS (26.714s)
[426.402260] [100/130] (571s left) kms_plane_scaling (planes-downscale-factor-0-75-upscale-20x20)
[426.498952] Starting subtest: planes-downscale-factor-0-75-upscale-20x20
[426.501698] Starting dynamic subtest: pipe-A
[426.564929] Dynamic subtest pipe-A: SUCCESS (0.065s)
[426.574192] Starting dynamic subtest: pipe-B
[427.809150] Dynamic subtest pipe-B: SUCCESS (1.243s)
[427.818435] Starting dynamic subtest: pipe-C
[429.003515] Dynamic subtest pipe-C: SUCCESS (1.194s)
[429.015328] Starting dynamic subtest: pipe-D
[430.217075] Dynamic subtest pipe-D: SUCCESS (1.213s)
[430.219401] Subtest planes-downscale-factor-0-75-upscale-20x20: SUCCESS (3.719s)
[431.438058] [101/130] (566s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-render)
[431.529944] Starting subtest: fbc-1p-offscren-pri-indfb-draw-render
[434.402751] Subtest fbc-1p-offscren-pri-indfb-draw-render: SUCCESS (2.877s)
[434.518032] [102/130] (563s left) gem_exec_reloc (basic-write-read-noreloc)
[434.568583] Subtest basic-write-read-noreloc: SKIP (0.000s)
[434.609562] [103/130] (563s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-plflip-blt)
[434.707254] Starting subtest: fbc-1p-primscrn-indfb-plflip-blt
[438.025698] Subtest fbc-1p-primscrn-indfb-plflip-blt: SUCCESS (3.319s)
[438.137893] [104/130] (559s left) kms_chamelium_hpd (vga-hpd-enable-disable-mode)
[438.507823] Subtest vga-hpd-enable-disable-mode: SKIP (0.000s)
[439.352908] [105/130] (558s left) i915_query (query-topology-garbage-items)
[439.404086] Starting subtest: query-topology-garbage-items
[439.406845] Subtest query-topology-garbage-items: SUCCESS (0.000s)
[439.461048] [106/130] (558s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc)
[439.560766] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc
[443.885967] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-mmap-wc: SUCCESS (4.328s)
[443.987541] [107/130] (553s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[444.105765] Starting subtest: cursorA-vs-flipA-toggle
[455.220319] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.114s)
[456.143193] [108/130] (541s left) gem_exec_alignment (single)
[456.201979] Starting subtest: single
[456.205203] Subtest single: SUCCESS (0.003s)
[456.305511] [109/130] (541s left) sysfs_heartbeat_interval (mixed)
[456.371582] Starting subtest: mixed
[456.373485] Starting dynamic subtest: vecs0
[461.597076] Dynamic subtest vecs0: SUCCESS (5.226s)
[461.609399] Starting dynamic subtest: ccs0
[466.800786] Dynamic subtest ccs0: SUCCESS (5.203s)
[466.810256] Starting dynamic subtest: vcs0
[471.998258] Dynamic subtest vcs0: SUCCESS (5.196s)
[472.007095] Starting dynamic subtest: bcs0
[477.185320] Dynamic subtest bcs0: SUCCESS (5.186s)
[477.194883] Starting dynamic subtest: rcs0
[482.359217] Dynamic subtest rcs0: SUCCESS (5.173s)
[482.372481] Starting dynamic subtest: vcs1
[487.554716] Dynamic subtest vcs1: SUCCESS (5.194s)
[487.557657] Subtest mixed: SUCCESS (31.184s)
[487.610687] [110/130] (510s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[487.709421] Starting subtest: fbc-rgb101010-draw-render
[491.339212] Subtest fbc-rgb101010-draw-render: SUCCESS (3.630s)
[491.426669] [111/130] (506s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite)
[491.525191] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite
[491.530145] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[491.593992] [112/130] (506s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[491.653491] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[491.687314] [113/130] (506s left) kms_atomic_transition (modeset-transition-nonblocking)
[491.784149] Starting subtest: modeset-transition-nonblocking
[491.787033] Starting dynamic subtest: 1x-outputs
[503.200952] Dynamic subtest 1x-outputs: SUCCESS (11.414s)
[503.465334] Subtest modeset-transition-nonblocking: SUCCESS (11.677s)
[504.331226] [114/130] (493s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-wc)
[504.430623] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-wc
[504.435082] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[504.499672] [115/130] (493s left) gen3_mixed_blits
[504.570933] [116/130] (493s left) perf_pmu (event-wait)
[504.604937] Starting subtest: event-wait
[504.607080] Starting dynamic subtest: rcs0
[504.610940] Dynamic subtest rcs0: SKIP (0.000s)
[504.614804] Subtest event-wait: SKIP (0.000s)
[504.665156] [117/130] (493s left) syncobj_timeline (single-wait-for-submit-signaled)
[504.715671] Starting subtest: single-wait-for-submit-signaled
[504.718117] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s)
[504.778390] [118/130] (493s left) gem_ringfill (engines-basic)
[510.382041] Starting subtest: engines-basic
[510.385056] Starting dynamic subtest: rcs0
[510.428848] Dynamic subtest rcs0: SUCCESS (0.048s)
[510.436653] Starting dynamic subtest: bcs0
[510.472864] Dynamic subtest bcs0: SUCCESS (0.043s)
[510.482252] Starting dynamic subtest: vcs0
[510.516115] Dynamic subtest vcs0: SUCCESS (0.042s)
[510.525486] Starting dynamic subtest: vcs1
[510.559176] Dynamic subtest vcs1: SUCCESS (0.042s)
[510.568883] Starting dynamic subtest: vecs0
[510.601884] Dynamic subtest vecs0: SUCCESS (0.042s)
[510.611602] Starting dynamic subtest: ccs0
[510.647643] Dynamic subtest ccs0: SUCCESS (0.045s)
[510.650779] Subtest engines-basic: SUCCESS (0.268s)
[510.703087] [119/130] (487s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-plflip-blt)
[510.800301] Starting subtest: psr-2p-primscrn-shrfb-plflip-blt
[510.804722] Subtest psr-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[510.867385] [120/130] (487s left) gen7_exec_parse (cmd-crossing-page)
[510.914509] Subtest cmd-crossing-page: SKIP (0.000s)
[510.948246] [121/130] (486s left) kms_psr (fbc-psr2-primary-render)
[511.034885] Starting subtest: fbc-psr2-primary-render
[511.037256] Starting dynamic subtest: eDP-1
[511.041578] Dynamic subtest eDP-1: SKIP (0.000s)
[511.045807] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[511.083080] [122/130] (486s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-pwrite)
[511.180825] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-pwrite
[511.184939] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[511.243844] [123/130] (486s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[511.358698] Starting subtest: short-busy-flip-before-cursor-toggle
[511.363481] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[511.442049] [124/130] (486s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[511.535465] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[511.538941] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[511.593465] [125/130] (486s left) kms_psr (fbc-psr-sprite-render)
[511.670560] Starting subtest: fbc-psr-sprite-render
[511.672654] Starting dynamic subtest: eDP-1
[511.676359] Dynamic subtest eDP-1: SKIP (0.000s)
[511.680125] Subtest fbc-psr-sprite-render: SKIP (0.001s)
[511.720755] [126/130] (486s left) core_auth (many-magics)
[511.778827] Starting subtest: many-magics
[512.506807] Subtest many-magics: SUCCESS (0.730s)
[512.714314] [127/130] (485s left) gem_pipe_control_store_loop (reused-buffer)
[512.765662] Starting subtest: reused-buffer
[514.918845] Subtest reused-buffer: SUCCESS (2.153s)
[515.023198] [128/130] (483s left) gem_exec_schedule (pi-ringfull)
[515.195607] Starting subtest: pi-ringfull
[515.198043] Starting dynamic subtest: rcs0
[515.294435] Dynamic subtest rcs0: FAIL (0.034s)
[515.305784] Starting dynamic subtest: bcs0
[515.379435] Dynamic subtest bcs0: FAIL (0.030s)
[515.386585] Starting dynamic subtest: vcs0
[515.456627] Dynamic subtest vcs0: FAIL (0.027s)
[515.465595] Starting dynamic subtest: vcs1
[515.535365] Dynamic subtest vcs1: FAIL (0.027s)
[515.543850] Starting dynamic subtest: vecs0
[515.612387] Dynamic subtest vecs0: FAIL (0.028s)
[515.631931] Starting dynamic subtest: ccs0
[515.700752] Dynamic subtest ccs0: FAIL (0.026s)
[515.703078] Subtest pi-ringfull: FAIL (0.175s)
[525.521361] [129/130] (472s left) gem_mmap_offset (blt-coherency)
[525.572058] Starting subtest: blt-coherency
[525.576714] Subtest blt-coherency: SUCCESS (0.007s)
[525.681726] [130/130] (472s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[525.780793] Starting subtest: yf-tiled-16bpp-rotate-0
[525.785182] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[525.847903] Closing watchdogs
Done.