[60.387910] Initializing watchdogs
[60.387990]   /dev/watchdog0
[60.404913] [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]
[60.409430] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.411698] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.413724] [001/155] (960s left) gem_userptr_blits (stress-mm-invalidate-close)
[60.484910] Starting subtest: stress-mm-invalidate-close
[62.660945] Subtest stress-mm-invalidate-close: SUCCESS (2.148s)
[62.759372] [002/155] (958s left) gem_mmap_wc (fault-concurrent)
[62.815363] Subtest fault-concurrent: SKIP (0.000s)
[62.864843] [003/155] (958s left) fbdev (unaligned-read)
[62.905943] Starting subtest: unaligned-read
[62.922099] Subtest unaligned-read: SUCCESS (0.016s)
[62.968955] [004/155] (958s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-cur-indfb-onoff)
[63.071866] Starting subtest: fbchdr-1p-primscrn-cur-indfb-onoff
[63.073653] Subtest fbchdr-1p-primscrn-cur-indfb-onoff: SKIP (0.000s)
[63.180121] [005/155] (957s left) kms_lease (lease-unleased-connector)
[63.275227] Starting subtest: lease-unleased-connector
[63.276291] Starting dynamic subtest: pipe-A-eDP-1
[63.281657] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.006s)
[63.288813] Starting dynamic subtest: pipe-B-eDP-1
[63.289656] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.007s)
[63.296549] Starting dynamic subtest: pipe-C-eDP-1
[63.297362] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.007s)
[63.304272] Starting dynamic subtest: pipe-D-eDP-1
[63.305145] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s)
[63.306142] Subtest lease-unleased-connector: SUCCESS (0.030s)
[63.411205] [006/155] (957s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[63.522755] Starting subtest: verify-pxp-key-change-after-suspend-resume
[70.816817] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.323s)
[77.947941] [007/155] (950s left) kms_big_fb (x-tiled-16bpp-rotate-0)
[78.059895] Starting subtest: x-tiled-16bpp-rotate-0
[79.417349] Subtest x-tiled-16bpp-rotate-0: SUCCESS (1.358s)
[79.541679] [008/155] (948s left) gem_set_tiling_vs_gtt
[79.655893] [009/155] (948s left) kms_plane_scaling (planes-downscale-factor-0-5-upscale-20x20)
[79.736629] Starting subtest: planes-downscale-factor-0-5-upscale-20x20
[79.737878] Starting dynamic subtest: pipe-A
[79.756282] Dynamic subtest pipe-A: SKIP (0.019s)
[79.763556] Starting dynamic subtest: pipe-B
[79.794594] Dynamic subtest pipe-B: SKIP (0.038s)
[79.801823] Starting dynamic subtest: pipe-C
[79.832913] Dynamic subtest pipe-C: SKIP (0.038s)
[79.839827] Starting dynamic subtest: pipe-D
[79.871431] Dynamic subtest pipe-D: SKIP (0.038s)
[79.872970] Subtest planes-downscale-factor-0-5-upscale-20x20: SKIP (0.134s)
[79.974029] [010/155] (948s left) kms_frontbuffer_tracking (fbcpsrhdr-indfb-scaledprimary)
[80.071939] Starting subtest: fbcpsrhdr-indfb-scaledprimary
[80.073826] Subtest fbcpsrhdr-indfb-scaledprimary: SKIP (0.000s)
[80.168216] [011/155] (948s left) gem_exec_fence (syncobj-wait)
[80.623541] Starting subtest: syncobj-wait
[82.339633] Subtest syncobj-wait: SUCCESS (1.706s)
[82.669508] [FACT gem_exec_fence (syncobj-wait)] new: kernel.kmod_is_loaded.vgem: true
[82.672013] [012/155] (945s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-render)
[82.765832] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-render
[82.767508] Subtest fbchdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[82.865104] [013/155] (945s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[82.952308] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt
[82.953948] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[83.036026] [014/155] (945s left) gem_create (create-ext-placement-all)
[83.203028] Starting subtest: create-ext-placement-all
[83.204423] Subtest create-ext-placement-all: SUCCESS (0.000s)
[83.284677] [015/155] (945s left) kms_cursor_edge_walk (128x128-left-edge)
[83.362284] Starting subtest: 128x128-left-edge
[83.363376] Starting dynamic subtest: pipe-A-eDP-1
[86.838208] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.476s)
[86.846722] Starting dynamic subtest: pipe-D-eDP-1
[89.973335] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.134s)
[89.974156] Subtest 128x128-left-edge: SUCCESS (6.612s)
[90.791240] [016/155] (937s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[90.891378] Starting subtest: fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[90.893242] Subtest fbchdr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[90.986563] [017/155] (937s left) kms_content_protection (uevent-hdcp14)
[91.125344] Starting subtest: uevent-hdcp14
[91.128041] Subtest uevent-hdcp14: SKIP (0.000s)
[91.246153] [018/155] (937s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[91.339152] Starting subtest: psr-rgb565-draw-pwrite
[98.739305] Subtest psr-rgb565-draw-pwrite: SUCCESS (7.402s)
[98.834629] [019/155] (929s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[98.926631] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[98.928260] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[99.014544] [020/155] (929s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-mmap-cpu)
[99.108108] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-mmap-cpu
[102.715323] Subtest fbc-1p-primscrn-spr-indfb-draw-mmap-cpu: SUCCESS (3.608s)
[103.915447] [021/155] (924s left) kms_plane_multiple (tiling-4)
[104.015376] Starting subtest: tiling-4
[104.016396] Starting dynamic subtest: pipe-A-eDP-1
[107.781930] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.766s)
[107.790472] Starting dynamic subtest: pipe-B-eDP-1
[112.424795] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.640s)
[112.432666] Starting dynamic subtest: pipe-C-eDP-1
[116.951414] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.526s)
[116.960631] Starting dynamic subtest: pipe-D-eDP-1
[121.339936] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.387s)
[121.341042] Subtest tiling-4: SUCCESS (17.326s)
[122.156828] [022/155] (906s left) gem_exec_fence (expired-history)
[122.582572] Starting subtest: expired-history
[130.097920] Subtest expired-history: SUCCESS (7.507s)
[131.594627] [023/155] (897s left) syncobj_wait (single-wait-all-for-submit-signaled)
[131.644967] Starting subtest: single-wait-all-for-submit-signaled
[131.645930] Subtest single-wait-all-for-submit-signaled: SUCCESS (0.000s)
[131.741853] [024/155] (896s left) gem_userptr_blits (huge-split)
[131.801801] Starting subtest: huge-split
[131.811275] Subtest huge-split: SUCCESS (0.011s)
[131.923276] [025/155] (896s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[131.983892] Starting subtest: etime-single-wait-available-unsubmitted
[132.087732] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.103s)
[132.160744] [026/155] (896s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-indfb-draw-pwrite)
[132.262106] Starting subtest: fbcpsrhdr-1p-primscrn-pri-indfb-draw-pwrite
[132.263813] Subtest fbcpsrhdr-1p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[132.358887] [027/155] (896s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[132.447694] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[133.549090] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (1.102s)
[133.664635] [028/155] (895s left) gem_exec_schedule (preempt-hang)
[133.839446] Starting subtest: preempt-hang
[133.841052] Starting dynamic subtest: rcs0
[134.079891] Dynamic subtest rcs0: SUCCESS (0.241s)
[134.089055] Starting dynamic subtest: bcs0
[134.308849] Dynamic subtest bcs0: SUCCESS (0.229s)
[134.317721] Starting dynamic subtest: vcs0
[134.538160] Dynamic subtest vcs0: SUCCESS (0.228s)
[134.546090] Starting dynamic subtest: vcs1
[134.769683] Dynamic subtest vcs1: SUCCESS (0.230s)
[134.777508] Starting dynamic subtest: vecs0
[134.994121] Dynamic subtest vecs0: SUCCESS (0.224s)
[135.004039] Starting dynamic subtest: ccs0
[135.223664] Dynamic subtest ccs0: SUCCESS (0.228s)
[135.224786] Subtest preempt-hang: SUCCESS (1.385s)
[136.461137] [029/155] (892s left) kms_psr (psr-cursor-blt)
[136.542129] Starting subtest: psr-cursor-blt
[136.542980] Starting dynamic subtest: eDP-1
[138.258734] Dynamic subtest eDP-1: SUCCESS (1.715s)
[138.259860] Subtest psr-cursor-blt: SUCCESS (1.715s)
[139.109772] [030/155] (889s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[139.166851] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[139.235730] [031/155] (889s left) kms_plane_lowres (tiling-4)
[139.320289] Starting subtest: tiling-4
[139.321267] Starting dynamic subtest: pipe-A-eDP-1
[139.322841] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[139.617762] Starting dynamic subtest: pipe-B-eDP-1
[139.627565] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[139.635257] Starting dynamic subtest: pipe-C-eDP-1
[139.637291] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[139.645116] Starting dynamic subtest: pipe-D-eDP-1
[139.647069] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[139.649300] Subtest tiling-4: SKIP (0.296s)
[140.428360] [032/155] (888s left) gem_render_copy (y-tiled-to-vebox-y-tiled)
[140.481944] Starting subtest: y-tiled-to-vebox-y-tiled
[140.483995] Subtest y-tiled-to-vebox-y-tiled: SKIP (0.000s)
[140.675743] [033/155] (888s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-render)
[140.777727] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-render
[140.779450] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[140.863862] [034/155] (888s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-linear)
[140.960145] Starting subtest: fbcpsrhdr-tiling-linear
[140.961823] Subtest fbcpsrhdr-tiling-linear: SKIP (0.000s)
[141.056821] [035/155] (887s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs)
[141.136870] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs
[141.137981] Starting dynamic subtest: pipe-A-eDP-1
[141.158298] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.021s)
[141.167406] Starting dynamic subtest: pipe-B-eDP-1
[141.179688] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.020s)
[141.187307] Starting dynamic subtest: pipe-C-eDP-1
[141.201566] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.021s)
[141.210137] Starting dynamic subtest: pipe-D-eDP-1
[141.223343] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.021s)
[141.224324] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.087s)
[143.492971] [036/155] (885s left) kms_fbcon_fbt (fbc-suspend)
[143.538351] Starting subtest: fbc-suspend
[193.261814] Subtest fbc-suspend: SUCCESS (20.033s)
[195.590434] [037/155] (834s left) syncobj_timeline (multi-wait-signaled)
[195.639183] Starting subtest: multi-wait-signaled
[195.639952] Subtest multi-wait-signaled: SUCCESS (0.001s)
[195.729625] [038/155] (834s left) kms_psr2_sf (fbc-psr2-overlay-plane-update-continuous-sf)
[195.815819] Starting subtest: fbc-psr2-overlay-plane-update-continuous-sf
[195.816687] Starting dynamic subtest: pipe-A-eDP-1
[195.916923] Dynamic subtest pipe-A-eDP-1: SKIP (0.101s)
[195.928100] Starting dynamic subtest: pipe-B-eDP-1
[197.045664] Dynamic subtest pipe-B-eDP-1: SKIP (1.140s)
[197.047717] Subtest fbc-psr2-overlay-plane-update-continuous-sf: SKIP (1.243s)
[198.217995] [039/155] (831s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu)
[198.324196] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu
[198.326815] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[198.414863] [040/155] (831s left) i915_drm_fdinfo (busy-idle)
[198.449018] Starting subtest: busy-idle
[198.450652] Starting dynamic subtest: rcs0
[198.478333] Dynamic subtest rcs0: SKIP (0.027s)
[198.489415] Starting dynamic subtest: bcs0
[198.492109] Dynamic subtest bcs0: SKIP (0.001s)
[198.500678] Starting dynamic subtest: vcs0
[198.503012] Dynamic subtest vcs0: SKIP (0.000s)
[198.510578] Starting dynamic subtest: vcs1
[198.512439] Dynamic subtest vcs1: SKIP (0.000s)
[198.519993] Starting dynamic subtest: vecs0
[198.521820] Dynamic subtest vecs0: SKIP (0.000s)
[198.529874] Starting dynamic subtest: ccs0
[198.532819] Dynamic subtest ccs0: SKIP (0.001s)
[198.535115] Subtest busy-idle: SKIP (0.037s)
[198.568691] [041/155] (831s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt)
[198.659048] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt
[198.661586] Subtest fbcpsrhdr-2p-scndscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[198.751995] [042/155] (831s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions)
[198.869058] Starting subtest: short-flip-after-cursor-atomic-transitions
[199.821714] Subtest short-flip-after-cursor-atomic-transitions: SUCCESS (0.958s)
[200.628840] [043/155] (829s left) gem_exec_whisper (basic-contexts)
[200.685707] Starting subtest: basic-contexts
[206.220702] Subtest basic-contexts: SUCCESS (5.559s)
[206.400936] [044/155] (823s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[206.496725] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[213.473347] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.994s)
[213.617232] [045/155] (816s left) i915_hangman (gt-engine-hang)
[213.672681] Starting subtest: gt-engine-hang
[213.674242] Starting dynamic subtest: rcs0
[215.101764] Dynamic subtest rcs0: SUCCESS (1.425s)
[215.117391] Starting dynamic subtest: bcs0
[217.083866] Dynamic subtest bcs0: SUCCESS (1.982s)
[217.098030] Starting dynamic subtest: vcs0
[219.134383] Dynamic subtest vcs0: SUCCESS (2.051s)
[219.146055] Starting dynamic subtest: vcs1
[221.116691] Dynamic subtest vcs1: SUCCESS (1.982s)
[221.128270] Starting dynamic subtest: vecs0
[223.102907] Dynamic subtest vecs0: SUCCESS (1.985s)
[223.120304] Starting dynamic subtest: ccs0
[225.084950] Dynamic subtest ccs0: SUCCESS (1.981s)
[225.086662] Subtest gt-engine-hang: SUCCESS (11.419s)
[225.188725] [046/155] (805s left) kms_pipe_b_c_ivb (disable-pipe-b-enable-pipe-c)
[225.240015] Subtest disable-pipe-B-enable-pipe-C: SKIP (0.000s)
[225.291438] [047/155] (805s left) kms_pm_rpm (modeset-non-lpsp)
[227.323935] Starting subtest: modeset-non-lpsp
[228.494903] Subtest modeset-non-lpsp: SKIP (1.171s)
[228.886223] [048/155] (801s left) kms_ccs (crc-primary-suspend-4-tiled-lnl-ccs)
[228.975378] Starting subtest: crc-primary-suspend-4-tiled-lnl-ccs
[228.978249] Subtest crc-primary-suspend-4-tiled-lnl-ccs: SKIP (0.000s)
[230.102893] [049/155] (800s left) gem_sync (basic-all)
[230.155639] Starting subtest: basic-all
[234.143231] Subtest basic-all: SUCCESS (3.978s)
[234.317338] [050/155] (796s left) kms_invalid_mode (zero-vdisplay)
[234.408792] Starting subtest: zero-vdisplay
[234.409889] Starting dynamic subtest: pipe-A-eDP-1
[234.695767] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.287s)
[234.705192] Starting dynamic subtest: pipe-B-eDP-1
[234.706643] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[234.718341] Starting dynamic subtest: pipe-C-eDP-1
[234.719722] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[234.728666] Starting dynamic subtest: pipe-D-eDP-1
[234.729911] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[234.730901] Subtest zero-vdisplay: SUCCESS (0.293s)
[235.531256] [051/155] (795s left) kms_psr (pr-primary-mmap-cpu)
[235.626816] Starting subtest: pr-primary-mmap-cpu
[235.628954] Subtest pr-primary-mmap-cpu: SKIP (0.000s)
[235.695019] [052/155] (794s left) perf (polling-parameterized)
[235.813174] Starting subtest: polling-parameterized
[255.882387] Subtest polling-parameterized: SUCCESS (20.023s)
[255.918942] [053/155] (774s left) prime_vgem (basic-fence-blt)
[255.979385] Starting subtest: basic-fence-blt
[256.079281] Subtest basic-fence-blt: SUCCESS (0.100s)
[256.197159] [054/155] (774s left) gem_render_tiled_blits (basic)
[256.247997] Subtest basic: SKIP (0.000s)
[256.315120] [055/155] (774s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[256.408021] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[256.410095] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[256.504612] [056/155] (774s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[256.551531] Starting subtest: single-wait-for-submit-available-signaled
[256.552607] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[256.645653] [057/155] (774s left) gem_mmap_offset (isolation)
[256.689138] Starting subtest: isolation
[256.700191] Subtest isolation: SUCCESS (0.006s)
[256.783732] [058/155] (774s left) kms_plane (plane-panning-bottom-right)
[256.866635] Starting subtest: plane-panning-bottom-right
[256.867622] Starting dynamic subtest: pipe-A
[258.588635] Dynamic subtest pipe-A: SUCCESS (1.721s)
[258.601253] Starting dynamic subtest: pipe-B
[261.000045] Dynamic subtest pipe-B: SUCCESS (2.410s)
[261.001135] Subtest plane-panning-bottom-right: SUCCESS (4.134s)
[261.872482] [059/155] (769s left) kms_addfb_basic (legacy-format)
[261.918020] Starting subtest: legacy-format
[261.943401] Subtest legacy-format: SUCCESS (0.027s)
[262.082513] [060/155] (768s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-render)
[262.180978] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-render
[262.183227] Subtest psr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[262.295341] [061/155] (768s left) kms_draw_crc (draw-method-mmap-gtt)
[262.388824] Starting subtest: draw-method-mmap-gtt
[262.391307] Subtest draw-method-mmap-gtt: SKIP (0.000s)
[262.501651] [062/155] (768s left) kms_cursor_crc (cursor-random-128x128)
[262.708366] Starting subtest: cursor-random-128x128
[262.711859] Starting dynamic subtest: pipe-A-eDP-1
[266.111085] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.399s)
[266.144414] Starting dynamic subtest: pipe-D-eDP-1
[270.230951] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.095s)
[270.232265] Subtest cursor-random-128x128: SUCCESS (7.524s)
[271.039617] [063/155] (760s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-sf)
[271.136765] Starting subtest: psr2-overlay-plane-move-continuous-sf
[271.137885] Starting dynamic subtest: pipe-A-eDP-1
[277.140025] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.005s)
[277.151243] Starting dynamic subtest: pipe-B-eDP-1
[283.839249] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.699s)
[283.840418] Subtest psr2-overlay-plane-move-continuous-sf: SUCCESS (12.708s)
[284.699660] [064/155] (746s left) kms_frontbuffer_tracking (hdr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[284.789960] Starting subtest: hdr-1p-primscrn-cur-indfb-draw-mmap-gtt
[284.791833] Subtest hdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[284.880512] [065/155] (746s left) kms_cursor_legacy (cursorb-vs-flipb-toggle)
[284.996707] Starting subtest: cursorB-vs-flipB-toggle
[284.999061] Subtest cursorB-vs-flipB-toggle: SKIP (0.001s)
[285.138809] [066/155] (746s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[285.234181] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[285.236312] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[285.302586] [067/155] (745s left) kms_addfb_basic (addfb25-bad-modifier)
[285.394909] Starting subtest: addfb25-bad-modifier
[285.395947] Subtest addfb25-bad-modifier: SUCCESS (0.000s)
[285.482528] [068/155] (745s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[285.582626] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-wc
[292.816341] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-wc: SUCCESS (7.239s)
[292.935179] [069/155] (738s left) gem_exec_balancer (full)
[292.994952] Starting subtest: full
[298.049270] Subtest full: SUCCESS (5.053s)
[298.378617] [070/155] (732s left) kms_frontbuffer_tracking (fbc-2p-pri-indfb-multidraw)
[298.476001] Starting subtest: fbc-2p-pri-indfb-multidraw
[298.478341] Subtest fbc-2p-pri-indfb-multidraw: SKIP (0.000s)
[298.599663] [071/155] (732s left) gem_mmap_gtt (fault-concurrent-x)
[298.652981] Subtest fault-concurrent-X: SKIP (0.000s)
[298.712331] [072/155] (732s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[298.763020] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[298.764075] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[298.845305] [073/155] (732s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-signa
[298.896387] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-signaled
[299.002055] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-signaled: SUCCESS (0.106s)
[299.088894] [074/155] (732s left) kms_psr2_su (frontbuffer-xrgb8888)
[299.131699] Subtest frontbuffer-XRGB8888: SKIP (0.000s)
[299.197526] [075/155] (732s left) gem_basic (bad-close)
[299.243749] Starting subtest: bad-close
[299.245969] Subtest bad-close: SUCCESS (0.000s)
[299.354741] [076/155] (732s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[299.473688] Starting subtest: cursorB-vs-flipB-atomic
[299.476414] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[299.616803] [077/155] (731s left) kms_atomic_transition (modeset-transition)
[299.708763] Starting subtest: modeset-transition
[299.709922] Starting dynamic subtest: 1x-outputs
[310.857817] Dynamic subtest 1x-outputs: SUCCESS (11.146s)
[310.859153] Subtest modeset-transition: SUCCESS (11.147s)
[311.706467] [078/155] (719s left) kms_frontbuffer_tracking (fbchdr-rgb101010-draw-mmap-gtt)
[311.795515] Starting subtest: fbchdr-rgb101010-draw-mmap-gtt
[311.797424] Subtest fbchdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[311.888514] [079/155] (719s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-indfb-plflip-blt)
[311.979036] Starting subtest: psrhdr-1p-primscrn-indfb-plflip-blt
[311.981268] Subtest psrhdr-1p-primscrn-indfb-plflip-blt: SKIP (0.000s)
[312.067233] [080/155] (719s left) template (b)
[312.113645] Starting subtest: B
[312.114970] Subtest B: SUCCESS (0.000s)
[312.193316] [081/155] (719s left) gem_exec_reloc (basic-gtt-read-noreloc)
[312.238929] Subtest basic-gtt-read-noreloc: SKIP (0.000s)
[312.301800] [082/155] (719s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted)
[312.404740] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted
[312.538714] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.136s)
[312.644229] [083/155] (719s left) i915_pm_rps (engine-order)
[312.695974] Starting subtest: engine-order
[318.944825] Subtest engine-order: SUCCESS (6.242s)
[319.063087] [084/155] (712s left) kms_cursor_crc (cursor-sliding-128x128)
[319.292884] Starting subtest: cursor-sliding-128x128
[319.297286] Starting dynamic subtest: pipe-A-eDP-1
[322.572325] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.276s)
[322.603377] Starting dynamic subtest: pipe-D-eDP-1
[326.511812] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.915s)
[326.512739] Subtest cursor-sliding-128x128: SUCCESS (7.218s)
[327.384077] [085/155] (704s left) gem_exec_create (basic)
[327.440295] Starting subtest: basic
[327.441822] Starting dynamic subtest: smem
[331.104163] Dynamic subtest smem: SUCCESS (3.654s)
[331.105584] Subtest basic: SUCCESS (3.656s)
[331.283532] [086/155] (700s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-fullscreen)
[331.386243] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-fullscreen
[331.388551] Subtest fbcpsr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[331.474395] [087/155] (700s left) gem_exec_schedule (semaphore-user)
[331.539006] Starting subtest: semaphore-user
[331.598821] Subtest semaphore-user: SUCCESS (0.059s)
[333.178166] [088/155] (698s left) syncobj_wait (invalid-reset-one-illegal-handle)
[333.224918] Starting subtest: invalid-reset-one-illegal-handle
[333.226097] Subtest invalid-reset-one-illegal-handle: SUCCESS (0.000s)
[333.332619] [089/155] (698s left) kms_vblank (crtc-id)
[333.421943] Starting subtest: crtc-id
[333.422905] Starting dynamic subtest: pipe-A-eDP-1
[333.863418] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.442s)
[333.877529] Starting dynamic subtest: pipe-D-eDP-1
[335.008017] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.141s)
[335.009420] Subtest crtc-id: SUCCESS (1.587s)
[335.848724] [090/155] (696s left) perf_pmu (busy)
[335.886817] Starting subtest: busy
[335.888157] Starting dynamic subtest: rcs0
[336.471867] Dynamic subtest rcs0: SUCCESS (0.585s)
[336.485410] Starting dynamic subtest: bcs0
[337.043093] Dynamic subtest bcs0: SUCCESS (0.569s)
[337.057271] Starting dynamic subtest: vcs0
[339.167175] Dynamic subtest vcs0: SUCCESS (2.112s)
[339.180269] Starting dynamic subtest: vcs1
[341.151279] Dynamic subtest vcs1: SUCCESS (1.981s)
[341.163886] Starting dynamic subtest: vecs0
[343.135410] Dynamic subtest vecs0: SUCCESS (1.981s)
[343.150233] Starting dynamic subtest: ccs0
[343.695314] Dynamic subtest ccs0: SUCCESS (0.567s)
[343.697118] Subtest busy: SUCCESS (7.811s)
[343.783148] [091/155] (688s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[343.876651] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[343.878597] Subtest psr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[343.968231] [092/155] (688s left) kms_vblank (query-forked)
[344.064841] Starting subtest: query-forked
[344.065937] Starting dynamic subtest: pipe-A-eDP-1
[345.885032] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.819s)
[345.896294] Starting dynamic subtest: pipe-D-eDP-1
[348.381171] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.493s)
[348.382361] Subtest query-forked: SUCCESS (4.316s)
[349.227729] [093/155] (682s left) gem_userptr_blits (map-fixed-invalidate-overlap)
[349.298796] Starting subtest: map-fixed-invalidate-overlap
[349.301478] Subtest map-fixed-invalidate-overlap: SKIP (0.000s)
[349.404819] [094/155] (682s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-indfb-draw-render)
[349.493626] Starting subtest: psrhdr-2p-primscrn-pri-indfb-draw-render
[349.495666] Subtest psrhdr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[349.577493] [095/155] (682s left) i915_selftest (live)
[354.236580] Starting subtest: live
[354.238065] Starting dynamic subtest: sanitycheck
[357.934411] Dynamic subtest sanitycheck: SUCCESS (3.698s)
[357.943664] Starting dynamic subtest: uncore
[361.633306] Dynamic subtest uncore: SUCCESS (3.697s)
[361.643271] Starting dynamic subtest: workarounds
[379.074535] Dynamic subtest workarounds: FAIL (17.406s)
[379.081327] Starting dynamic subtest: gt_engines
[383.096935] Dynamic subtest gt_engines: SUCCESS (4.058s)
[383.106830] Starting dynamic subtest: gt_timelines
[398.570903] Dynamic subtest gt_timelines: SUCCESS (15.476s)
[398.581387] Starting dynamic subtest: gt_contexts
[402.350336] Dynamic subtest gt_contexts: SUCCESS (3.770s)
[402.362020] Starting dynamic subtest: gt_lrc
[407.634126] Dynamic subtest gt_lrc: SUCCESS (5.292s)
[407.643421] Starting dynamic subtest: gt_mocs
[411.328904] Dynamic subtest gt_mocs: SUCCESS (3.700s)
[411.338917] Starting dynamic subtest: gt_pm
[416.798960] Dynamic subtest gt_pm: SUCCESS (5.461s)
[416.808847] Starting dynamic subtest: gt_heartbeat
[421.734447] Dynamic subtest gt_heartbeat: SUCCESS (4.933s)
[421.743342] Starting dynamic subtest: gt_tlb
[432.483914] Dynamic subtest gt_tlb: SUCCESS (10.742s)
[432.493296] Starting dynamic subtest: requests
[445.726642] Dynamic subtest requests: SUCCESS (13.237s)
[445.736700] Starting dynamic subtest: migrate
[451.663921] Dynamic subtest migrate: SUCCESS (5.934s)
[451.673382] Starting dynamic subtest: active
[456.667643] Dynamic subtest active: SUCCESS (5.002s)
[456.677767] Starting dynamic subtest: objects
[460.462839] Dynamic subtest objects: SUCCESS (3.793s)
[460.472100] Starting dynamic subtest: mman
[465.959705] Dynamic subtest mman: SUCCESS (5.496s)
[465.969712] Starting dynamic subtest: dmabuf
[470.924990] Dynamic subtest dmabuf: SUCCESS (4.962s)
[470.932981] Starting dynamic subtest: vma
[475.871871] Dynamic subtest vma: SUCCESS (4.944s)
[475.884669] Starting dynamic subtest: coherency
[480.498321] Dynamic subtest coherency: SUCCESS (4.624s)
[480.508424] Starting dynamic subtest: gtt
[491.690952] Dynamic subtest gtt: SUCCESS (11.189s)
[491.700895] Starting dynamic subtest: gem
[497.856392] Dynamic subtest gem: SUCCESS (6.165s)
[497.866782] Starting dynamic subtest: evict
[503.996368] Dynamic subtest evict: SUCCESS (6.138s)
[504.004688] Starting dynamic subtest: hugepages
[514.066911] Dynamic subtest hugepages: SUCCESS (10.069s)
[514.076799] Starting dynamic subtest: gem_contexts
[542.005981] Dynamic subtest gem_contexts: SUCCESS (27.940s)
[542.016417] Starting dynamic subtest: client
[545.378923] Dynamic subtest client: SUCCESS (3.372s)
[545.389336] Starting dynamic subtest: gem_migrate
[550.239393] Dynamic subtest gem_migrate: SUCCESS (4.859s)
[550.248348] Starting dynamic subtest: reset
[553.565383] Dynamic subtest reset: SUCCESS (3.324s)
[553.575733] Starting dynamic subtest: memory_region
[557.229790] Dynamic subtest memory_region: SUCCESS (3.663s)
[557.245138] Starting dynamic subtest: hangcheck
[571.874699] Dynamic subtest hangcheck: SUCCESS (14.644s)
[571.884896] Starting dynamic subtest: execlists
[576.721391] Dynamic subtest execlists: SUCCESS (4.845s)
[576.731656] Starting dynamic subtest: ring_submission
[579.981726] Dynamic subtest ring_submission: SUCCESS (3.259s)
[579.990662] Starting dynamic subtest: perf
[582.997952] Dynamic subtest perf: SUCCESS (3.014s)
[583.008380] Starting dynamic subtest: slpc
[596.188163] Dynamic subtest slpc: SUCCESS (13.190s)
[596.198235] Starting dynamic subtest: guc
[600.668830] Dynamic subtest guc: SUCCESS (4.481s)
[600.679695] Starting dynamic subtest: guc_multi_lrc
[605.530244] Dynamic subtest guc_multi_lrc: SUCCESS (4.860s)
[605.539103] Starting dynamic subtest: guc_hang
[609.511859] Dynamic subtest guc_hang: SUCCESS (3.979s)
[609.521501] Starting dynamic subtest: late_gt_pm
[612.801759] Dynamic subtest late_gt_pm: SUCCESS (3.288s)
[612.803680] Subtest live: FAIL (258.577s)
[612.826558] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[612.827317] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[612.830046] [096/155] (419s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-rte)
[614.912827] Starting subtest: fbcpsrhdr-1p-rte
[614.915134] Subtest fbcpsrhdr-1p-rte: SKIP (0.000s)
[615.029342] [FACT kms_frontbuffer_tracking (fbcpsrhdr-1p-rte)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[615.030734] [FACT kms_frontbuffer_tracking (fbcpsrhdr-1p-rte)] new: kernel.kmod_is_loaded.i915: true
[615.033836] [097/155] (417s left) gem_exec_params (secure-non-root)
[615.089638] Starting subtest: secure-non-root
[615.091772] Subtest secure-non-root: SKIP (0.000s)
[615.184974] [098/155] (417s left) kms_color (ctm-max)
[615.266234] Starting subtest: ctm-max
[615.267705] Starting dynamic subtest: pipe-A-eDP-1
[616.027820] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.761s)
[616.036498] Starting dynamic subtest: pipe-B-eDP-1
[617.465640] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.438s)
[617.475037] Starting dynamic subtest: pipe-C-eDP-1
[618.853212] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.386s)
[618.866018] Starting dynamic subtest: pipe-D-eDP-1
[620.266912] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.411s)
[620.268001] Subtest ctm-max: SUCCESS (5.002s)
[621.065109] [099/155] (411s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-blt)
[621.151449] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-blt
[621.154092] Subtest hdr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[621.242427] [100/155] (411s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[621.330169] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-mmap-cpu
[621.332025] Subtest hdr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[621.416517] [101/155] (411s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[621.509785] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[629.625900] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (8.119s)
[630.806854] [102/155] (401s left) kms_frontbuffer_tracking (fbchdr-1p-shrfb-fliptrack-mmap-gtt)
[630.903870] Starting subtest: fbchdr-1p-shrfb-fliptrack-mmap-gtt
[630.905851] Subtest fbchdr-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[630.999104] [103/155] (401s left) kms_ccs (bad-rotation-90-yf-tiled-ccs)
[631.077718] Starting subtest: bad-rotation-90-yf-tiled-ccs
[631.078662] Starting dynamic subtest: pipe-A-eDP-1
[631.088218] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[631.098401] Starting dynamic subtest: pipe-B-eDP-1
[631.108531] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[631.118650] Starting dynamic subtest: pipe-C-eDP-1
[631.127965] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[631.136229] Starting dynamic subtest: pipe-D-eDP-1
[631.146302] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[631.147943] Subtest bad-rotation-90-yf-tiled-ccs: SKIP (0.004s)
[633.350839] [104/155] (399s left) kms_properties (connector-properties-legacy)
[633.444990] Starting subtest: connector-properties-legacy
[633.446106] Starting dynamic subtest: pipe-A-eDP-1
[641.486909] Dynamic subtest pipe-A-eDP-1: SUCCESS (8.044s)
[641.496636] Starting dynamic subtest: pipe-None-DP-1
[641.498455] Dynamic subtest pipe-None-DP-1: SUCCESS (0.001s)
[641.506948] Starting dynamic subtest: pipe-None-HDMI-A-1
[641.508825] Dynamic subtest pipe-None-HDMI-A-1: SUCCESS (0.001s)
[641.517986] Starting dynamic subtest: pipe-None-DP-2
[641.519956] Dynamic subtest pipe-None-DP-2: SUCCESS (0.001s)
[641.528604] Starting dynamic subtest: pipe-None-HDMI-A-2
[641.530658] Dynamic subtest pipe-None-HDMI-A-2: SUCCESS (0.001s)
[641.539372] Starting dynamic subtest: pipe-None-DP-3
[641.541404] Dynamic subtest pipe-None-DP-3: SUCCESS (0.001s)
[641.550016] Starting dynamic subtest: pipe-None-DP-4
[641.552066] Dynamic subtest pipe-None-DP-4: SUCCESS (0.001s)
[641.553086] Subtest connector-properties-legacy: SUCCESS (8.056s)
[642.356618] [105/155] (390s left) gem_ctx_sseu (engines)
[642.412015] Subtest engines: SKIP (0.000s)
[642.478872] [106/155] (390s left) kms_psr (fbc-psr-sprite-plane-move)
[642.576767] Starting subtest: fbc-psr-sprite-plane-move
[642.577863] Starting dynamic subtest: eDP-1
[642.579749] Dynamic subtest eDP-1: SKIP (0.000s)
[642.581660] Subtest fbc-psr-sprite-plane-move: SKIP (0.001s)
[642.650123] [107/155] (390s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-cur-indfb-draw-mmap-wc)
[642.744271] Starting subtest: hdr-2p-scndscrn-cur-indfb-draw-mmap-wc
[642.746623] Subtest hdr-2p-scndscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[642.851407] [108/155] (389s left) kms_chamelium_hpd (dp-hpd-after-suspend)
[643.225656] Subtest dp-hpd-after-suspend: SKIP (0.000s)
[644.075545] [109/155] (388s left) kms_chamelium_color (ctm-green-to-red)
[644.171136] Subtest ctm-green-to-red: SKIP (0.000s)
[644.211007] [110/155] (388s left) kms_chamelium_frames (hdmi-frame-dump)
[644.584977] Subtest hdmi-frame-dump: SKIP (0.000s)
[645.400234] [111/155] (387s left) gem_eio (in-flight-suspend)
[645.566953] Starting subtest: in-flight-suspend
[689.330796] Subtest in-flight-suspend: SUCCESS (13.929s)
[695.508025] [112/155] (343s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75)
[695.601088] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75
[695.602000] Starting dynamic subtest: pipe-A
[695.622002] Dynamic subtest pipe-A: SKIP (0.019s)
[695.631905] Starting dynamic subtest: pipe-B
[695.657969] Dynamic subtest pipe-B: SKIP (0.035s)
[695.667654] Starting dynamic subtest: pipe-C
[695.693962] Dynamic subtest pipe-C: SKIP (0.036s)
[695.702977] Starting dynamic subtest: pipe-D
[695.729705] Dynamic subtest pipe-D: SKIP (0.035s)
[695.731419] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.128s)
[695.828364] [113/155] (343s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[695.934155] Starting subtest: y-tiled-16bpp-rotate-0
[695.936159] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[696.046700] [114/155] (342s left) core_auth (getclient-simple)
[696.076347] Starting subtest: getclient-simple
[696.100441] Subtest getclient-simple: SUCCESS (0.025s)
[696.206203] [115/155] (342s left) kms_big_fb (linear-16bpp-rotate-0)
[696.311245] Starting subtest: linear-16bpp-rotate-0
[697.746064] Subtest linear-16bpp-rotate-0: SUCCESS (1.436s)
[697.865504] [116/155] (341s left) gem_softpin (evict-prime-sanity-check)
[697.912174] Starting subtest: evict-prime-sanity-check
[697.913005] Starting dynamic subtest: rcs0
[699.051488] Dynamic subtest rcs0: SUCCESS (1.140s)
[699.063473] Starting dynamic subtest: bcs0
[700.164054] Dynamic subtest bcs0: SUCCESS (1.111s)
[700.180857] Starting dynamic subtest: vcs0
[701.995131] Dynamic subtest vcs0: SUCCESS (1.819s)
[702.006742] Starting dynamic subtest: vcs1
[703.978658] Dynamic subtest vcs1: SUCCESS (1.992s)
[703.992568] Starting dynamic subtest: vecs0
[705.927940] Dynamic subtest vecs0: SUCCESS (1.947s)
[705.939547] Starting dynamic subtest: ccs0
[707.088874] Dynamic subtest ccs0: SUCCESS (1.159s)
[707.109036] Starting dynamic subtest: all
[708.217804] Dynamic subtest all: SUCCESS (1.127s)
[708.218854] Subtest evict-prime-sanity-check: SUCCESS (10.309s)
[708.291327] [117/155] (330s left) gem_close_race (basic-threads)
[708.355656] Starting subtest: basic-threads
[709.836716] Subtest basic-threads: SUCCESS (1.481s)
[710.014739] [118/155] (329s left) kms_flip (absolute-wf_vblank-interruptible)
[710.117827] Starting subtest: absolute-wf_vblank-interruptible
[710.121423] Starting dynamic subtest: A-eDP1
[719.132126] Dynamic subtest A-eDP1: SUCCESS (8.987s)
[719.142911] Starting dynamic subtest: B-eDP1
[727.802524] Dynamic subtest B-eDP1: SUCCESS (8.722s)
[727.814366] Starting dynamic subtest: C-eDP1
[736.476165] Dynamic subtest C-eDP1: SUCCESS (8.715s)
[736.488129] Starting dynamic subtest: D-eDP1
[745.139994] Dynamic subtest D-eDP1: SUCCESS (8.729s)
[745.143429] Subtest absolute-wf_vblank-interruptible: SUCCESS (35.167s)
[746.357076] [119/155] (292s left) kms_psr (fbc-psr2-sprite-mmap-cpu)
[746.442573] Starting subtest: fbc-psr2-sprite-mmap-cpu
[746.443518] Starting dynamic subtest: eDP-1
[746.445429] Dynamic subtest eDP-1: SKIP (0.000s)
[746.447176] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.001s)
[746.516423] [120/155] (292s left) syncobj_timeline (multi-wait-available-submitted)
[746.565978] Starting subtest: multi-wait-available-submitted
[746.567066] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[746.672119] [121/155] (292s left) gem_exec_fence (syncobj-timeline-export)
[747.242975] Starting subtest: syncobj-timeline-export
[747.244008] Subtest syncobj-timeline-export: SUCCESS (0.007s)
[749.060514] [122/155] (290s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-shrfb-plflip-blt)
[749.158149] Starting subtest: fbchdr-2p-scndscrn-shrfb-plflip-blt
[749.159971] Subtest fbchdr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s)
[749.267334] [123/155] (289s left) kms_vblank (wait-idle-hang)
[749.348234] Starting subtest: wait-idle-hang
[749.349176] Starting dynamic subtest: pipe-A-eDP-1
[775.720351] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.406s)
[775.731934] Starting dynamic subtest: pipe-D-eDP-1
[800.756836] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.036s)
[800.758128] Subtest wait-idle-hang: SUCCESS (51.447s)
[801.566544] [124/155] (237s left) kms_busy (extended-modeset-hang-oldfb)
[801.661306] Starting subtest: extended-modeset-hang-oldfb
[801.662221] Starting dynamic subtest: pipe-A
[813.423445] Dynamic subtest pipe-A: SUCCESS (11.760s)
[813.434465] Starting dynamic subtest: pipe-D
[825.996835] Dynamic subtest pipe-D: SUCCESS (12.571s)
[825.997934] Subtest extended-modeset-hang-oldfb: SUCCESS (24.335s)
[826.900730] [125/155] (212s left) kms_psr (psr-primary-page-flip)
[826.991508] Starting subtest: psr-primary-page-flip
[826.992468] Starting dynamic subtest: eDP-1
[828.805634] Dynamic subtest eDP-1: SUCCESS (1.813s)
[828.806509] Subtest psr-primary-page-flip: SUCCESS (1.814s)
[829.655922] [126/155] (209s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[829.749980] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[829.751864] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[829.844406] [127/155] (209s left) kms_frontbuffer_tracking (hdr-rgb101010-draw-pwrite)
[829.938363] Starting subtest: hdr-rgb101010-draw-pwrite
[829.940006] Subtest hdr-rgb101010-draw-pwrite: SKIP (0.000s)
[830.037409] [128/155] (209s left) gen9_exec_parse (bb-start-param)
[830.093137] Subtest bb-start-param: SKIP (0.000s)
[830.151642] [129/155] (209s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-blt)
[830.237918] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-blt
[837.741796] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.506s)
[837.853294] [130/155] (201s left) kms_big_fb (yf-tiled-8bpp-rotate-180)
[837.950667] Starting subtest: yf-tiled-8bpp-rotate-180
[837.952466] Subtest yf-tiled-8bpp-rotate-180: SKIP (0.000s)
[838.042069] [131/155] (201s left) kms_atomic_interruptible (atomic-setmode)
[838.144303] Starting subtest: atomic-setmode
[838.145149] Starting dynamic subtest: pipe-A-eDP-1
[845.050478] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.907s)
[845.051462] Subtest atomic-setmode: SUCCESS (6.908s)
[845.917920] [132/155] (193s left) gem_ctx_persistence (legacy-engines-hang)
[845.971325] Starting subtest: legacy-engines-hang
[845.972409] Starting dynamic subtest: render
[846.057362] Dynamic subtest render: SUCCESS (0.085s)
[846.071506] Starting dynamic subtest: bsd1
[846.161565] Dynamic subtest bsd1: SUCCESS (0.101s)
[846.175057] Starting dynamic subtest: bsd2
[846.249966] Dynamic subtest bsd2: SUCCESS (0.085s)
[846.263567] Starting dynamic subtest: blt
[846.345790] Dynamic subtest blt: SUCCESS (0.093s)
[846.359330] Starting dynamic subtest: vebox
[846.442572] Dynamic subtest vebox: SUCCESS (0.094s)
[846.443958] Subtest legacy-engines-hang: SUCCESS (0.472s)
[846.531017] [133/155] (193s left) gem_ctx_persistence (engines-mixed-process)
[846.592464] Starting subtest: engines-mixed-process
[846.593445] Starting dynamic subtest: rcs0
[847.993905] Dynamic subtest rcs0: SUCCESS (1.401s)
[848.006556] Starting dynamic subtest: bcs0
[849.977972] Dynamic subtest bcs0: SUCCESS (1.982s)
[849.989514] Starting dynamic subtest: vcs0
[852.020698] Dynamic subtest vcs0: SUCCESS (2.042s)
[852.033427] Starting dynamic subtest: vcs1
[854.008510] Dynamic subtest vcs1: SUCCESS (1.986s)
[854.021944] Starting dynamic subtest: vecs0
[855.983792] Dynamic subtest vecs0: SUCCESS (1.974s)
[855.997048] Starting dynamic subtest: ccs0
[857.966135] Dynamic subtest ccs0: SUCCESS (1.980s)
[857.967279] Subtest engines-mixed-process: SUCCESS (11.376s)
[858.056478] [134/155] (181s left) kms_cursor_legacy (short-busy-flip-before-cursor-toggle)
[858.177241] Starting subtest: short-busy-flip-before-cursor-toggle
[858.179335] Subtest short-busy-flip-before-cursor-toggle: SKIP (0.000s)
[858.317844] [135/155] (181s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[858.409135] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt
[858.411073] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[858.501153] [136/155] (181s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[858.586988] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[858.588031] Starting dynamic subtest: pipe-A-eDP-1
[858.596498] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[858.606651] Starting dynamic subtest: pipe-B-eDP-1
[858.614849] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[858.624595] Starting dynamic subtest: pipe-C-eDP-1
[858.632216] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[858.641739] Starting dynamic subtest: pipe-D-eDP-1
[858.649781] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[858.651370] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.006s)
[860.878523] [137/155] (178s left) kms_pipe_crc_basic (bad-source)
[860.965636] Starting subtest: bad-source
[860.966522] Subtest bad-source: SUCCESS (0.000s)
[861.037102] [138/155] (178s left) gem_workarounds (basic-read-context)
[861.112492] Starting subtest: basic-read-context
[861.123173] Subtest basic-read-context: SUCCESS (0.010s)
[861.247157] [139/155] (178s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt)
[861.337132] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt
[868.897248] Subtest psr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.563s)
[869.007996] [140/155] (170s left) kms_flip (2x-flip-vs-panning-interruptible)
[869.095981] Starting subtest: 2x-flip-vs-panning-interruptible
[869.107016] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.011s)
[869.179545] [141/155] (170s left) core_setmaster (master-drop-set-user)
[869.246341] Starting subtest: master-drop-set-user
[869.258550] Subtest master-drop-set-user: SUCCESS (0.013s)
[869.293300] [142/155] (170s left) sw_sync (alloc_merge_fence)
[869.321590] Starting subtest: alloc_merge_fence
[869.322486] Subtest alloc_merge_fence: SUCCESS (0.000s)
[869.378572] [143/155] (170s left) kms_pm_dc (dc5-dpms-negative)
[870.474657] Starting subtest: dc5-dpms-negative
[870.476554] Subtest dc5-dpms-negative: SKIP (0.000s)
[870.554811] [144/155] (169s left) perf (gen8-unprivileged-single-ctx-counters)
[870.682565] Starting subtest: gen8-unprivileged-single-ctx-counters
[870.684297] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[870.720486] [145/155] (169s left) sysfs_heartbeat_interval (idempotent)
[870.769641] Starting subtest: idempotent
[870.770623] Starting dynamic subtest: vecs0
[870.771552] Dynamic subtest vecs0: SUCCESS (0.001s)
[870.785146] Starting dynamic subtest: ccs0
[870.786175] Dynamic subtest ccs0: SUCCESS (0.001s)
[870.795905] Starting dynamic subtest: vcs0
[870.797018] Dynamic subtest vcs0: SUCCESS (0.001s)
[870.806376] Starting dynamic subtest: bcs0
[870.807276] Dynamic subtest bcs0: SUCCESS (0.001s)
[870.817138] Starting dynamic subtest: rcs0
[870.818554] Dynamic subtest rcs0: SUCCESS (0.000s)
[870.828395] Starting dynamic subtest: vcs1
[870.829345] Dynamic subtest vcs1: SUCCESS (0.000s)
[870.830480] Subtest idempotent: SUCCESS (0.008s)
[870.889487] [146/155] (169s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu)
[870.993841] Starting subtest: psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu
[870.995611] Subtest psrhdr-1p-offscreen-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[871.093348] [147/155] (169s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-64bpp-4tile-upscaling)
[871.189033] Starting subtest: flip-32bpp-4tile-to-64bpp-4tile-upscaling
[871.189973] Starting dynamic subtest: pipe-A-default-mode
[873.978194] Dynamic subtest pipe-A-default-mode: SUCCESS (2.789s)
[873.979184] Subtest flip-32bpp-4tile-to-64bpp-4tile-upscaling: SUCCESS (2.791s)
[874.802627] [148/155] (165s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-pri-shrfb-draw-render)
[874.905368] Starting subtest: psrhdr-1p-primscrn-pri-shrfb-draw-render
[874.907789] Subtest psrhdr-1p-primscrn-pri-shrfb-draw-render: SKIP (0.000s)
[874.994708] [149/155] (165s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[875.047368] Starting subtest: invalid-single-wait-all-unsubmitted
[875.048347] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[875.135813] [150/155] (165s left) kms_getfb (getfb-reject-nv12)
[875.216335] Starting subtest: getfb-reject-nv12
[875.217218] Subtest getfb-reject-nv12: SUCCESS (0.001s)
[875.316776] [151/155] (164s left) syncobj_wait (invalid-signal-illegal-handle)
[875.360542] Starting subtest: invalid-signal-illegal-handle
[875.361646] Subtest invalid-signal-illegal-handle: SUCCESS (0.000s)
[875.471653] [152/155] (164s left) api_intel_allocator (reopen-fork)
[875.530142] Starting subtest: reopen-fork
[878.827035] Subtest reopen-fork: SUCCESS (3.289s)
[878.907463] [153/155] (161s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[879.004861] Starting subtest: 4-tiled-32bpp-rotate-270
[879.024534] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[879.151577] [154/155] (161s left) gem_exec_whisper (basic-queues-forked)
[879.208732] Starting subtest: basic-queues-forked
[902.175490] Subtest basic-queues-forked: SUCCESS (22.964s)
[902.350639] [155/155] (138s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[902.444070] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[902.445821] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[902.522859] Closing watchdogs
results: parsing output: 0/ for test: gem_userptr_blits
results: parsing output: 1/ for test: gem_mmap_wc
results: parsing output: 2/ for test: fbdev
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: kms_lease
results: parsing output: 5/ for test: gem_pxp
results: parsing output: 6/ for test: kms_big_fb
results: parsing output: 7/ for test: gem_set_tiling_vs_gtt
results: parsing output: 8/ for test: kms_plane_scaling
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: gem_exec_fence
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: gem_create
results: parsing output: 14/ for test: kms_cursor_edge_walk
results: parsing output: 15/ for test: kms_frontbuffer_tracking
results: parsing output: 16/ for test: kms_content_protection
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: kms_plane_multiple
results: parsing output: 21/ for test: gem_exec_fence
results: parsing output: 22/ for test: syncobj_wait
results: parsing output: 23/ for test: gem_userptr_blits
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_big_fb
results: parsing output: 27/ for test: gem_exec_schedule
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: gem_exec_reloc
results: parsing output: 30/ for test: kms_plane_lowres
results: parsing output: 31/ for test: gem_render_copy
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: kms_ccs
results: parsing output: 35/ for test: kms_fbcon_fbt
results: parsing output: 36/ for test: syncobj_timeline
results: parsing output: 37/ for test: kms_psr2_sf
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: i915_drm_fdinfo
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: kms_cursor_legacy
results: parsing output: 42/ for test: gem_exec_whisper
results: parsing output: 43/ for test: kms_frontbuffer_tracking
results: parsing output: 44/ for test: i915_hangman
results: parsing output: 45/ for test: kms_pipe_b_c_ivb
results: parsing output: 46/ for test: kms_pm_rpm
results: parsing output: 47/ for test: kms_ccs
results: parsing output: 48/ for test: gem_sync
results: parsing output: 49/ for test: kms_invalid_mode
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: perf
results: parsing output: 52/ for test: prime_vgem
results: parsing output: 53/ for test: gem_render_tiled_blits
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: syncobj_timeline
results: parsing output: 56/ for test: gem_mmap_offset
results: parsing output: 57/ for test: kms_plane
results: parsing output: 58/ for test: kms_addfb_basic
results: parsing output: 59/ for test: kms_frontbuffer_tracking
results: parsing output: 60/ for test: kms_draw_crc
results: parsing output: 61/ for test: kms_cursor_crc
results: parsing output: 62/ for test: kms_psr2_sf
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_cursor_legacy
results: parsing output: 65/ for test: kms_flip_scaled_crc
results: parsing output: 66/ for test: kms_addfb_basic
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: gem_exec_balancer
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_mmap_gtt
results: parsing output: 71/ for test: syncobj_wait
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: kms_psr2_su
results: parsing output: 74/ for test: gem_basic
results: parsing output: 75/ for test: kms_cursor_legacy
results: parsing output: 76/ for test: kms_atomic_transition
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: template
results: parsing output: 80/ for test: gem_exec_reloc
results: parsing output: 81/ for test: gem_pxp
results: parsing output: 82/ for test: i915_pm_rps
results: parsing output: 83/ for test: kms_cursor_crc
results: parsing output: 84/ for test: gem_exec_create
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_exec_schedule
results: parsing output: 87/ for test: syncobj_wait
results: parsing output: 88/ for test: kms_vblank
results: parsing output: 89/ for test: perf_pmu
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_vblank
results: parsing output: 92/ for test: gem_userptr_blits
results: parsing output: 93/ for test: kms_frontbuffer_tracking
results: parsing output: 94/ for test: i915_selftest
results: parsing output: 95/ for test: kms_frontbuffer_tracking
results: parsing output: 96/ for test: gem_exec_params
results: parsing output: 97/ for test: kms_color
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_ccs
results: parsing output: 103/ for test: kms_properties
results: parsing output: 104/ for test: gem_ctx_sseu
results: parsing output: 105/ for test: kms_psr
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_chamelium_hpd
results: parsing output: 108/ for test: kms_chamelium_color
results: parsing output: 109/ for test: kms_chamelium_frames
results: parsing output: 110/ for test: gem_eio
results: parsing output: 111/ for test: kms_plane_scaling
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: core_auth
results: parsing output: 114/ for test: kms_big_fb
results: parsing output: 115/ for test: gem_softpin
results: parsing output: 116/ for test: gem_close_race
results: parsing output: 117/ for test: kms_flip
results: parsing output: 118/ for test: kms_psr
results: parsing output: 119/ for test: syncobj_timeline
results: parsing output: 120/ for test: gem_exec_fence
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_vblank
results: parsing output: 123/ for test: kms_busy
results: parsing output: 124/ for test: kms_psr
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: gen9_exec_parse
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: kms_atomic_interruptible
results: parsing output: 131/ for test: gem_ctx_persistence
results: parsing output: 132/ for test: gem_ctx_persistence
results: parsing output: 133/ for test: kms_cursor_legacy
results: parsing output: 134/ for test: kms_frontbuffer_tracking
results: parsing output: 135/ for test: kms_ccs
results: parsing output: 136/ for test: kms_pipe_crc_basic
results: parsing output: 137/ for test: gem_workarounds
results: parsing output: 138/ for test: kms_frontbuffer_tracking
results: parsing output: 139/ for test: kms_flip
results: parsing output: 140/ for test: core_setmaster
results: parsing output: 141/ for test: sw_sync
results: parsing output: 142/ for test: kms_pm_dc
results: parsing output: 143/ for test: perf
results: parsing output: 144/ for test: sysfs_heartbeat_interval
results: parsing output: 145/ for test: kms_frontbuffer_tracking
results: parsing output: 146/ for test: kms_flip_scaled_crc
results: parsing output: 147/ for test: kms_frontbuffer_tracking
results: parsing output: 148/ for test: syncobj_wait
results: parsing output: 149/ for test: kms_getfb
results: parsing output: 150/ for test: syncobj_wait
results: parsing output: 151/ for test: api_intel_allocator
results: parsing output: 152/ for test: kms_big_fb
results: parsing output: 153/ for test: gem_exec_whisper
results: parsing output: 154/ for test: kms_frontbuffer_tracking
Done.