[60.904880] Initializing watchdogs
[60.904925]   /dev/watchdog0
[60.918828] [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.923268] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[60.925715] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[60.928833] [001/133] (960s left) kms_cursor_crc (cursor-offscreen-256x85)
[61.210614] Starting subtest: cursor-offscreen-256x85
[61.260526] Subtest cursor-offscreen-256x85: SKIP (0.044s)
[61.411617] [002/133] (960s left) kms_ccs (crc-sprite-planes-basic-y-tiled-gen12-mc-ccs)
[61.493226] Starting subtest: crc-sprite-planes-basic-y-tiled-gen12-mc-ccs
[61.495573] Starting dynamic subtest: pipe-A-eDP-1
[61.555248] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[61.563717] Starting dynamic subtest: pipe-B-eDP-1
[61.623897] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[61.632602] Starting dynamic subtest: pipe-C-eDP-1
[61.689845] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[61.698278] Starting dynamic subtest: pipe-D-eDP-1
[61.746844] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[61.750235] Subtest crc-sprite-planes-basic-y-tiled-gen12-mc-ccs: SKIP (0.007s)
[63.640717] [003/133] (957s left) sysfs_timeslice_duration (duration)
[63.718749] Starting subtest: duration
[63.721278] Starting dynamic subtest: vecs0
[76.884192] Dynamic subtest vecs0: SUCCESS (13.164s)
[76.894562] Starting dynamic subtest: ccs0
[90.026472] Dynamic subtest ccs0: SUCCESS (13.141s)
[90.035937] Starting dynamic subtest: vcs0
[103.795774] Dynamic subtest vcs0: SUCCESS (13.766s)
[103.805630] Starting dynamic subtest: bcs0
[116.940113] Dynamic subtest bcs0: SUCCESS (13.144s)
[116.948825] Starting dynamic subtest: rcs0
[130.101027] Dynamic subtest rcs0: SUCCESS (13.158s)
[130.110897] Starting dynamic subtest: vcs1
[143.796053] Dynamic subtest vcs1: SUCCESS (13.691s)
[143.799850] Subtest duration: SUCCESS (80.072s)
[143.870430] [004/133] (877s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs)
[143.979755] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs
[143.981823] Starting dynamic subtest: pipe-A-eDP-1
[160.455725] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.542s)
[160.464364] Starting dynamic subtest: pipe-B-eDP-1
[177.385032] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.371s)
[177.399031] Starting dynamic subtest: pipe-C-eDP-1
[194.360643] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.332s)
[194.372435] Starting dynamic subtest: pipe-D-eDP-1
[211.407573] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.378s)
[211.409871] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs: SUCCESS (8.627s)
[212.614538] [005/133] (808s left) drm_buddy (drm_buddy)
[212.663488] Starting subtest: drm_buddy
[212.668311] Subtest drm_buddy: SKIP (0.000s)
[212.721418] [006/133] (808s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[212.834575] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[213.658612] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.825s)
[213.808623] [007/133] (807s left) kms_ccs (random-ccs-data-4-tiled-mtl-rc-ccs)
[213.901743] Starting subtest: random-ccs-data-4-tiled-mtl-rc-ccs
[213.904262] Starting dynamic subtest: pipe-A-eDP-1
[214.621090] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.709s)
[214.629259] Starting dynamic subtest: pipe-B-eDP-1
[216.444095] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.821s)
[216.451597] Starting dynamic subtest: pipe-C-eDP-1
[218.245144] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.799s)
[218.257311] Starting dynamic subtest: pipe-D-eDP-1
[219.996798] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.751s)
[219.999046] Subtest random-ccs-data-4-tiled-mtl-rc-ccs: SUCCESS (6.084s)
[221.136591] [008/133] (800s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[221.247358] Starting subtest: bad-pixel-format-yf-tiled-ccs
[221.249754] Starting dynamic subtest: pipe-A-eDP-1
[221.273458] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[221.283752] Starting dynamic subtest: pipe-B-eDP-1
[221.305368] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[221.312986] Starting dynamic subtest: pipe-C-eDP-1
[221.334671] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[221.342264] Starting dynamic subtest: pipe-D-eDP-1
[221.364350] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[221.368755] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.006s)
[223.503012] [009/133] (798s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted)
[223.556973] Starting subtest: multi-wait-available-unsubmitted-submitted
[223.559360] Subtest multi-wait-available-unsubmitted-submitted: SUCCESS (0.001s)
[223.671737] [010/133] (798s left) perf_pmu (event-wait)
[223.711639] Starting subtest: event-wait
[223.714115] Starting dynamic subtest: rcs0
[223.718619] Dynamic subtest rcs0: SKIP (0.000s)
[223.723062] Subtest event-wait: SKIP (0.000s)
[223.827476] [011/133] (797s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt)
[223.919349] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt
[223.923845] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[224.017169] [012/133] (797s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[224.107922] Starting subtest: psr-rgb565-draw-mmap-gtt
[224.112071] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[224.195409] [013/133] (797s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[224.298058] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[224.300433] Starting dynamic subtest: pipe-A-eDP-1
[230.488885] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.193s)
[230.498633] Starting dynamic subtest: pipe-B-eDP-1
[237.146601] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.657s)
[237.148807] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (12.852s)
[238.017899] [014/133] (783s left) kms_psr (fbc-psr2-dpms)
[238.127757] Starting subtest: fbc-psr2-dpms
[238.129980] Starting dynamic subtest: eDP-1
[238.133964] Dynamic subtest eDP-1: SKIP (0.000s)
[238.137919] Subtest fbc-psr2-dpms: SKIP (0.001s)
[238.197926] [015/133] (783s left) gem_exec_balancer (parallel-ordering)
[238.463567] Starting subtest: parallel-ordering
[239.385627] Subtest parallel-ordering: SUCCESS (0.922s)
[239.520840] [016/133] (782s left) kms_joiner (basic-big-joiner)
[239.620264] Starting subtest: basic-big-joiner
[239.624563] Subtest basic-big-joiner: SKIP (0.000s)
[239.688393] [017/133] (782s left) kms_cursor_legacy (flip-vs-cursor-crc-atomic)
[239.799159] Starting subtest: flip-vs-cursor-crc-atomic
[240.440980] Subtest flip-vs-cursor-crc-atomic: SUCCESS (0.640s)
[241.243305] [018/133] (780s left) gem_wait (wait)
[241.307748] Starting subtest: wait
[241.310081] Starting dynamic subtest: all-engines
[242.690721] Dynamic subtest all-engines: SUCCESS (1.381s)
[242.700850] Starting dynamic subtest: rcs0
[243.734470] Dynamic subtest rcs0: SUCCESS (1.043s)
[243.744048] Starting dynamic subtest: bcs0
[244.781036] Dynamic subtest bcs0: SUCCESS (1.045s)
[244.791522] Starting dynamic subtest: vcs0
[245.824777] Dynamic subtest vcs0: SUCCESS (1.042s)
[245.834563] Starting dynamic subtest: vcs1
[246.869303] Dynamic subtest vcs1: SUCCESS (1.043s)
[246.879702] Starting dynamic subtest: vecs0
[247.913274] Dynamic subtest vecs0: SUCCESS (1.042s)
[247.923975] Starting dynamic subtest: ccs0
[248.959929] Dynamic subtest ccs0: SUCCESS (1.045s)
[248.962730] Subtest wait: SUCCESS (7.651s)
[249.169981] [019/133] (772s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-wc)
[249.275031] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-wc
[249.279107] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[249.354614] [020/133] (772s left) kms_flip (2x-flip-vs-expired-vblank)
[249.444185] Starting subtest: 2x-flip-vs-expired-vblank
[249.452082] Subtest 2x-flip-vs-expired-vblank: SKIP (0.010s)
[249.530202] [021/133] (772s left) kms_lease (page-flip-implicit-plane)
[249.610050] Starting subtest: page-flip-implicit-plane
[249.612429] Starting dynamic subtest: pipe-A-eDP-1
[250.093116] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.480s)
[250.104195] Starting dynamic subtest: pipe-B-eDP-1
[251.193301] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.098s)
[251.201220] Starting dynamic subtest: pipe-C-eDP-1
[252.306962] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.114s)
[252.320818] Starting dynamic subtest: pipe-D-eDP-1
[253.444946] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.135s)
[253.447297] Subtest page-flip-implicit-plane: SUCCESS (3.832s)
[254.284995] [022/133] (767s left) gem_exec_params (rsvd2-dirt)
[254.340864] Starting subtest: rsvd2-dirt
[254.345037] Subtest rsvd2-dirt: SKIP (0.000s)
[254.428637] [023/133] (767s left) api_intel_bb (blit-reloc-purge-cache)
[254.476899] Starting subtest: blit-reloc-purge-cache
[254.481037] Subtest blit-reloc-purge-cache: SKIP (0.001s)
[254.591965] [024/133] (767s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary)
[254.689475] Starting subtest: fbc-shrfb-scaledprimary
[257.654165] Subtest fbc-shrfb-scaledprimary: SUCCESS (2.968s)
[258.802573] [025/133] (763s left) i915_pm_rpm (gem-idle)
[260.932813] Starting subtest: gem-idle
[267.143455] Subtest gem-idle: SUCCESS (6.212s)
[267.473395] [026/133] (754s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[268.693380] Starting subtest: x-tiled-8bpp-rotate-0
[269.863766] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.170s)
[269.961516] [027/133] (752s left) gem_exec_schedule (preempt-contexts)
[270.033733] Starting subtest: preempt-contexts
[270.035954] Starting dynamic subtest: rcs0
[270.083721] Dynamic subtest rcs0: SUCCESS (0.060s)
[270.092522] Starting dynamic subtest: bcs0
[270.134956] Dynamic subtest bcs0: SUCCESS (0.051s)
[270.143276] Starting dynamic subtest: vcs0
[270.178999] Dynamic subtest vcs0: SUCCESS (0.044s)
[270.186946] Starting dynamic subtest: vcs1
[270.221622] Dynamic subtest vcs1: SUCCESS (0.042s)
[270.230652] Starting dynamic subtest: vecs0
[270.263863] Dynamic subtest vecs0: SUCCESS (0.042s)
[270.271883] Starting dynamic subtest: ccs0
[270.305718] Dynamic subtest ccs0: SUCCESS (0.041s)
[270.307738] Subtest preempt-contexts: SUCCESS (0.282s)
[271.785793] [028/133] (750s left) gem_exec_flush (basic-uc-rw-default)
[271.865746] Starting subtest: basic-uc-rw-default
[277.319166] Subtest basic-uc-rw-default: SUCCESS (5.454s)
[277.499323] [029/133] (744s left) kms_color_pipeline (plane-lut1d)
[277.605156] Starting subtest: plane-lut1d
[277.731291] Starting dynamic subtest: pipe-A-plane-0
[278.072979] Dynamic subtest pipe-A-plane-0: FAIL (0.174s)
[278.082836] Starting dynamic subtest: pipe-A-plane-1
[278.300530] Dynamic subtest pipe-A-plane-1: FAIL (0.155s)
[278.312355] Starting dynamic subtest: pipe-A-plane-2
[278.542291] Dynamic subtest pipe-A-plane-2: FAIL (0.166s)
[279.512706] Starting dynamic subtest: pipe-B-plane-0
[279.913593] Dynamic subtest pipe-B-plane-0: FAIL (0.142s)
[279.923729] Starting dynamic subtest: pipe-B-plane-1
[280.227242] Dynamic subtest pipe-B-plane-1: FAIL (0.154s)
[280.235112] Starting dynamic subtest: pipe-B-plane-2
[280.528840] Dynamic subtest pipe-B-plane-2: FAIL (0.167s)
[281.208905] Starting dynamic subtest: pipe-C-plane-0
[281.680696] Dynamic subtest pipe-C-plane-0: FAIL (0.129s)
[281.697229] Starting dynamic subtest: pipe-C-plane-1
[282.084166] Dynamic subtest pipe-C-plane-1: FAIL (0.132s)
[282.094670] Starting dynamic subtest: pipe-C-plane-2
[282.437041] Dynamic subtest pipe-C-plane-2: FAIL (0.610s)
[283.240375] Starting dynamic subtest: pipe-D-plane-0
[283.828068] Dynamic subtest pipe-D-plane-0: FAIL (0.152s)
[283.839006] Starting dynamic subtest: pipe-D-plane-1
[284.275475] Dynamic subtest pipe-D-plane-1: FAIL (0.606s)
[284.285079] Starting dynamic subtest: pipe-D-plane-2
[284.701727] Dynamic subtest pipe-D-plane-2: FAIL (0.188s)
[284.871040] Subtest plane-lut1d: FAIL (7.262s)
[285.718404] [030/133] (736s left) gem_exec_schedule (deep)
[285.905588] Starting subtest: deep
[285.908365] Starting dynamic subtest: rcs0
[291.010963] Dynamic subtest rcs0: SKIP (5.100s)
[291.023476] Starting dynamic subtest: vcs0
[301.136032] Dynamic subtest vcs0: SUCCESS (10.124s)
[301.155555] Starting dynamic subtest: vcs1
[312.995769] Dynamic subtest vcs1: SUCCESS (11.860s)
[313.010540] Starting dynamic subtest: vecs0
[323.732489] Dynamic subtest vecs0: SUCCESS (10.739s)
[323.745181] Starting dynamic subtest: ccs0
[336.334626] Dynamic subtest ccs0: SUCCESS (12.604s)
[336.337711] Subtest deep: SUCCESS (50.431s)
[337.423651] [FACT gem_exec_schedule (deep)] new: kernel.kmod_is_loaded.vgem: true
[337.427686] [031/133] (685s left) kms_cursor_crc (cursor-suspend)
[337.569975] Starting subtest: cursor-suspend
[337.572180] Starting dynamic subtest: pipe-A-eDP-1
[370.666680] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.130s)
[370.676676] Starting dynamic subtest: pipe-D-eDP-1
[403.679875] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.776s)
[403.681803] Subtest cursor-suspend: SUCCESS (6.909s)
[404.541280] [032/133] (618s left) perf (gen8-unprivileged-single-ctx-counters)
[404.684880] Starting subtest: gen8-unprivileged-single-ctx-counters
[404.689314] Subtest gen8-unprivileged-single-ctx-counters: SKIP (0.000s)
[404.730511] [033/133] (617s left) api_intel_bb (intel-bb-blit-y)
[404.781669] Starting subtest: intel-bb-blit-y
[408.198558] Subtest intel-bb-blit-y: SUCCESS (3.412s)
[408.318330] [034/133] (614s left) prime_mmap (test_refcounting)
[408.384149] Starting subtest: test_refcounting
[408.386487] Starting dynamic subtest: test_refcounting-smem
[408.413474] Dynamic subtest test_refcounting-smem: SUCCESS (0.031s)
[408.415904] Subtest test_refcounting: SUCCESS (0.032s)
[408.536740] [035/133] (614s left) kms_atomic_transition (modeset-transition)
[408.633558] Starting subtest: modeset-transition
[408.635494] Starting dynamic subtest: 1x-outputs
[419.461219] Dynamic subtest 1x-outputs: SUCCESS (10.819s)
[419.463716] Subtest modeset-transition: SUCCESS (10.821s)
[420.312085] [036/133] (602s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[420.412559] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[420.415032] Starting dynamic subtest: pipe-A
[420.431934] Dynamic subtest pipe-A: SKIP (0.016s)
[420.441944] Starting dynamic subtest: pipe-B
[420.461683] Dynamic subtest pipe-B: SKIP (0.028s)
[420.470721] Starting dynamic subtest: pipe-C
[420.490269] Dynamic subtest pipe-C: SKIP (0.029s)
[420.498798] Starting dynamic subtest: pipe-D
[420.518635] Dynamic subtest pipe-D: SKIP (0.029s)
[420.522732] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.105s)
[420.631771] [037/133] (602s left) kms_frontbuffer_tracking (fbc-suspend)
[420.739727] Starting subtest: fbc-suspend
[458.704102] Subtest fbc-suspend: SUCCESS (7.422s)
[459.897220] [038/133] (563s left) gem_pxp (display-protected-crc)
[460.021163] Starting subtest: display-protected-crc
[461.672643] Subtest display-protected-crc: SUCCESS (1.650s)
[461.780045] [039/133] (561s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-wc)
[461.890298] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-wc
[461.895499] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[461.971125] [040/133] (561s left) gem_userptr_blits (dmabuf-unsync)
[462.059118] Subtest dmabuf-unsync: SKIP (0.000s)
[462.193936] [041/133] (560s left) gem_eio (context-create)
[462.455625] Starting subtest: context-create
[462.777362] Subtest context-create: SUCCESS (0.323s)
[462.889487] [042/133] (560s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt)
[462.986379] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt
[469.890803] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SUCCESS (6.901s)
[470.026943] [043/133] (553s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[470.131691] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt
[470.137083] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[470.226453] [044/133] (552s left) kms_selftest (drm_dp_mst_helper)
[470.270053] Starting subtest: drm_dp_mst_helper
[470.330412] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[470.436877] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.105s)
[470.447242] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[470.534004] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.097s)
[470.542704] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[470.647124] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.113s)
[470.649244] Subtest drm_dp_mst_helper: SUCCESS (0.377s)
[470.799389] [045/133] (552s left) gem_spin_batch (resubmit-new)
[470.863968] Starting subtest: resubmit-new
[470.866255] Starting dynamic subtest: rcs0
[470.876110] Dynamic subtest rcs0: SUCCESS (0.012s)
[470.886170] Starting dynamic subtest: bcs0
[470.888195] Dynamic subtest bcs0: SUCCESS (0.004s)
[470.896826] Starting dynamic subtest: vcs0
[470.898850] Dynamic subtest vcs0: SUCCESS (0.004s)
[470.907734] Starting dynamic subtest: vcs1
[470.909572] Dynamic subtest vcs1: SUCCESS (0.004s)
[470.918059] Starting dynamic subtest: vecs0
[470.920031] Dynamic subtest vecs0: SUCCESS (0.005s)
[470.928323] Starting dynamic subtest: ccs0
[470.930286] Dynamic subtest ccs0: SUCCESS (0.005s)
[470.932303] Subtest resubmit-new: SUCCESS (0.041s)
[472.497174] [046/133] (550s left) testdisplay
[472.798965] [047/133] (550s left) kms_psr2_sf (fbc-psr2-overlay-primary-update-sf-dmg-area)
[472.899893] Starting subtest: fbc-psr2-overlay-primary-update-sf-dmg-area
[472.902336] Starting dynamic subtest: pipe-A-eDP-1
[473.030311] Dynamic subtest pipe-A-eDP-1: SKIP (0.132s)
[473.040975] Starting dynamic subtest: pipe-B-eDP-1
[474.196621] Dynamic subtest pipe-B-eDP-1: SKIP (1.163s)
[474.202738] Subtest fbc-psr2-overlay-primary-update-sf-dmg-area: SKIP (1.298s)
[475.384229] [048/133] (547s left) i915_selftest (live)
[478.662549] Starting subtest: live
[478.665215] Starting dynamic subtest: sanitycheck
[482.217173] Dynamic subtest sanitycheck: SUCCESS (3.552s)
[482.227330] Starting dynamic subtest: uncore
[485.684218] Dynamic subtest uncore: SUCCESS (3.464s)
[485.694034] Starting dynamic subtest: workarounds
[489.584475] Dynamic subtest workarounds: SUCCESS (3.900s)
[489.593824] Starting dynamic subtest: gt_engines
[493.420695] Dynamic subtest gt_engines: SUCCESS (3.838s)
[493.430634] Starting dynamic subtest: gt_timelines
[509.432281] Dynamic subtest gt_timelines: SUCCESS (16.014s)
[509.441976] Starting dynamic subtest: gt_contexts
[513.053526] Dynamic subtest gt_contexts: SUCCESS (3.619s)
[513.063921] Starting dynamic subtest: gt_lrc
[518.163563] Dynamic subtest gt_lrc: SUCCESS (5.108s)
[518.173074] Starting dynamic subtest: gt_mocs
[521.888738] Dynamic subtest gt_mocs: SUCCESS (3.723s)
[521.896524] Starting dynamic subtest: gt_pm
[527.445323] Dynamic subtest gt_pm: SUCCESS (5.553s)
[527.453722] Starting dynamic subtest: gt_heartbeat
[530.938129] Dynamic subtest gt_heartbeat: SUCCESS (3.490s)
[530.946995] Starting dynamic subtest: gt_tlb
[541.177636] Dynamic subtest gt_tlb: SUCCESS (10.234s)
[541.187209] Starting dynamic subtest: requests
[553.567924] Dynamic subtest requests: SUCCESS (12.386s)
[553.577601] Starting dynamic subtest: migrate
[560.144685] Dynamic subtest migrate: SUCCESS (6.577s)
[560.154399] Starting dynamic subtest: active
[563.774293] Dynamic subtest active: SUCCESS (3.629s)
[563.783990] Starting dynamic subtest: objects
[567.965689] Dynamic subtest objects: SUCCESS (4.190s)
[567.977940] Starting dynamic subtest: mman
[572.087100] Dynamic subtest mman: SUCCESS (4.120s)
[572.097094] Starting dynamic subtest: dmabuf
[575.618835] Dynamic subtest dmabuf: SUCCESS (3.530s)
[575.628139] Starting dynamic subtest: vma
[579.165019] Dynamic subtest vma: SUCCESS (3.546s)
[579.180108] Starting dynamic subtest: coherency
[584.903649] Dynamic subtest coherency: SUCCESS (5.737s)
[584.912998] Starting dynamic subtest: gtt
[595.461253] Dynamic subtest gtt: SUCCESS (10.555s)
[595.477110] Starting dynamic subtest: gem
[602.084833] Dynamic subtest gem: SUCCESS (6.621s)
[602.094290] Starting dynamic subtest: evict
[606.950009] Dynamic subtest evict: SUCCESS (4.864s)
[606.958642] Starting dynamic subtest: hugepages
[616.563551] Dynamic subtest hugepages: SUCCESS (9.609s)
[616.573128] Starting dynamic subtest: gem_contexts
[644.026240] Dynamic subtest gem_contexts: SUCCESS (27.456s)
[644.038538] Starting dynamic subtest: client
[649.190638] Dynamic subtest client: SUCCESS (5.161s)
[649.200845] Starting dynamic subtest: gem_migrate
[652.679625] Dynamic subtest gem_migrate: SUCCESS (3.486s)
[652.689950] Starting dynamic subtest: reset
[657.847460] Dynamic subtest reset: SUCCESS (5.165s)
[657.860957] Starting dynamic subtest: memory_region
[662.979374] Dynamic subtest memory_region: SUCCESS (5.129s)
[662.989564] Starting dynamic subtest: hangcheck
[676.660393] Dynamic subtest hangcheck: SUCCESS (13.676s)
[676.668929] Starting dynamic subtest: execlists
[680.124564] Dynamic subtest execlists: SUCCESS (3.461s)
[680.134569] Starting dynamic subtest: ring_submission
[683.628633] Dynamic subtest ring_submission: SUCCESS (3.503s)
[683.638491] Starting dynamic subtest: perf
[687.243701] Dynamic subtest perf: SUCCESS (3.615s)
[687.255010] Starting dynamic subtest: slpc
[700.069637] Dynamic subtest slpc: SUCCESS (12.826s)
[700.079413] Starting dynamic subtest: guc
[704.800079] Dynamic subtest guc: SUCCESS (4.728s)
[704.810227] Starting dynamic subtest: guc_multi_lrc
[708.355650] Dynamic subtest guc_multi_lrc: SUCCESS (3.555s)
[708.368000] Starting dynamic subtest: guc_hang
[712.569374] Dynamic subtest guc_hang: SUCCESS (4.213s)
[712.578643] Starting dynamic subtest: late_gt_pm
[716.090446] Dynamic subtest late_gt_pm: SUCCESS (3.519s)
[716.093555] Subtest live: SUCCESS (237.413s)
[716.124187] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[716.124948] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[716.128520] [049/133] (307s left) gem_partial_pwrite_pread (writes-after-reads-uncached)
[718.472568] Subtest writes-after-reads-uncached: SKIP (0.000s)
[718.554508] [FACT gem_partial_pwrite_pread (writes-after-reads-uncached)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[718.556158] [FACT gem_partial_pwrite_pread (writes-after-reads-uncached)] new: kernel.kmod_is_loaded.i915: true
[718.560282] [050/133] (304s left) kms_getfb (getfb-handle-not-fb)
[718.655246] Starting subtest: getfb-handle-not-fb
[718.657555] Subtest getfb-handle-not-fb: SUCCESS (0.000s)
[718.756223] [051/133] (304s left) kms_psr (fbc-psr-sprite-plane-onoff)
[718.845547] Starting subtest: fbc-psr-sprite-plane-onoff
[718.848008] Starting dynamic subtest: eDP-1
[718.852729] Dynamic subtest eDP-1: SKIP (0.000s)
[718.857736] Subtest fbc-psr-sprite-plane-onoff: SKIP (0.001s)
[718.915893] [052/133] (304s left) i915_hangman (gt-error-state-capture)
[718.974704] Starting subtest: gt-error-state-capture
[718.977078] Starting dynamic subtest: rcs0
[730.406532] Dynamic subtest rcs0: SUCCESS (11.430s)
[730.419692] Starting dynamic subtest: bcs0
[742.435718] Dynamic subtest bcs0: SUCCESS (12.027s)
[742.448072] Starting dynamic subtest: vcs0
[754.341958] Dynamic subtest vcs0: SUCCESS (11.902s)
[754.354345] Starting dynamic subtest: vcs1
[765.347685] Dynamic subtest vcs1: SUCCESS (11.003s)
[765.359542] Starting dynamic subtest: vecs0
[776.356631] Dynamic subtest vecs0: SUCCESS (11.005s)
[776.369212] Starting dynamic subtest: ccs0
[786.405044] Dynamic subtest ccs0: SUCCESS (10.044s)
[786.407976] Subtest gt-error-state-capture: SUCCESS (67.427s)
[786.489599] [053/133] (237s left) perf (invalid-open-flags)
[786.649525] Starting subtest: invalid-open-flags
[786.652540] Subtest invalid-open-flags: SUCCESS (0.000s)
[786.700569] [054/133] (236s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[786.803483] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[786.808516] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[786.902474] [055/133] (236s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[787.003144] Starting subtest: async-flip-with-page-flip-events-linear
[787.378624] Starting dynamic subtest: pipe-A-eDP-1
[790.113140] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.735s)
[790.483360] Starting dynamic subtest: pipe-B-eDP-1
[793.250019] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.773s)
[793.618020] Starting dynamic subtest: pipe-C-eDP-1
[796.379710] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.769s)
[796.778204] Starting dynamic subtest: pipe-D-eDP-1
[799.519840] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.759s)
[799.522399] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.518s)
[800.702249] [056/133] (222s left) sw_sync (alloc_fence)
[800.740338] Starting subtest: alloc_fence
[800.742765] Subtest alloc_fence: SUCCESS (0.000s)
[800.802418] [057/133] (222s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[800.891713] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[800.894452] Starting dynamic subtest: pipe-A-default-mode
[802.069460] Dynamic subtest pipe-A-default-mode: SKIP (1.174s)
[802.074473] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.175s)
[802.202110] [058/133] (221s left) gem_ctx_persistence (heartbeat-close)
[802.254719] Starting subtest: heartbeat-close
[802.259586] Subtest heartbeat-close: SKIP (0.000s)
[802.351343] [059/133] (221s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip)
[802.465547] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip
[802.470460] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.000s)
[802.562438] [060/133] (221s left) kms_flip (2x-flip-vs-modeset)
[802.652458] Starting subtest: 2x-flip-vs-modeset
[802.663986] Subtest 2x-flip-vs-modeset: SKIP (0.010s)
[802.748112] [061/133] (221s left) sw_sync (alloc_timeline)
[802.781978] Starting subtest: alloc_timeline
[802.784590] Subtest alloc_timeline: SUCCESS (0.000s)
[802.835691] [062/133] (221s left) gem_exec_fence (syncobj-timeline-chain-engines)
[803.344051] Starting subtest: syncobj-timeline-chain-engines
[803.474406] Subtest syncobj-timeline-chain-engines: SUCCESS (0.131s)
[804.516240] [063/133] (219s left) kms_plane (pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping)
[804.614867] Starting subtest: pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping
[804.619781] Subtest pixel-format-y-tiled-gen12-rc-ccs-cc-modifier-source-clamping: SKIP (0.000s)
[804.714044] [064/133] (219s left) perf_pmu (all-busy-check-all)
[804.755554] Starting subtest: all-busy-check-all
[806.470634] Subtest all-busy-check-all: SUCCESS (1.715s)
[806.579102] [065/133] (217s left) kms_chamelium_frames (hdmi-crc-single)
[806.974456] Subtest hdmi-crc-single: SKIP (0.000s)
[807.750536] [066/133] (216s left) kms_draw_crc (draw-method-mmap-wc)
[807.844690] Starting subtest: draw-method-mmap-wc
[807.847005] Starting dynamic subtest: xrgb8888-untiled
[808.087970] Dynamic subtest xrgb8888-untiled: SUCCESS (0.244s)
[808.097706] Starting dynamic subtest: rgb565-untiled
[808.319526] Dynamic subtest rgb565-untiled: SUCCESS (0.232s)
[808.328315] Starting dynamic subtest: xrgb2101010-untiled
[808.530531] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.209s)
[808.540019] Starting dynamic subtest: xbgr16161616f-untiled
[808.730068] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.199s)
[808.741996] Starting dynamic subtest: xrgb8888-xtiled
[808.864116] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.133s)
[808.873307] Starting dynamic subtest: rgb565-xtiled
[808.997072] Dynamic subtest rgb565-xtiled: SUCCESS (0.133s)
[809.006550] Starting dynamic subtest: xrgb2101010-xtiled
[809.119179] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.122s)
[809.127783] Starting dynamic subtest: xbgr16161616f-xtiled
[809.241331] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.121s)
[809.250484] Starting dynamic subtest: xrgb8888-4tiled
[809.408226] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.166s)
[809.417109] Starting dynamic subtest: rgb565-4tiled
[809.553066] Dynamic subtest rgb565-4tiled: SUCCESS (0.144s)
[809.562048] Starting dynamic subtest: xrgb2101010-4tiled
[809.708509] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.154s)
[809.718046] Starting dynamic subtest: xbgr16161616f-4tiled
[809.886425] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.177s)
[809.888502] Subtest draw-method-mmap-wc: SUCCESS (2.046s)
[809.979623] [067/133] (214s left) kms_content_protection (atomic)
[810.121559] Starting subtest: atomic
[810.126204] Subtest atomic: SKIP (0.000s)
[810.234589] [068/133] (213s left) kms_ccs (ccs-on-another-bo-y-tiled-ccs)
[810.321282] Starting subtest: ccs-on-another-bo-y-tiled-ccs
[810.323367] Starting dynamic subtest: pipe-A-eDP-1
[810.343092] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[810.356565] Starting dynamic subtest: pipe-B-eDP-1
[810.376461] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[810.384813] Starting dynamic subtest: pipe-C-eDP-1
[810.406219] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[810.414428] Starting dynamic subtest: pipe-D-eDP-1
[810.435996] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[810.440465] Subtest ccs-on-another-bo-y-tiled-ccs: SKIP (0.005s)
[812.551097] [069/133] (211s left) kms_psr (pr-primary-render)
[812.650424] Starting subtest: pr-primary-render
[812.655632] Subtest pr-primary-render: SKIP (0.000s)
[812.727960] [070/133] (211s left) gem_exec_flush (basic-uc-ro-default)
[812.787275] Starting subtest: basic-uc-ro-default
[818.248715] Subtest basic-uc-ro-default: SUCCESS (5.461s)
[818.442646] [071/133] (205s left) kms_dither (fb-8bpc-vs-panel-8bpc)
[818.548676] Starting subtest: fb-8bpc-vs-panel-8bpc
[818.551200] Starting dynamic subtest: pipe-A-eDP-1
[819.918407] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.367s)
[819.920603] Subtest fb-8bpc-vs-panel-8bpc: SUCCESS (1.369s)
[820.714101] [072/133] (203s left) gem_madvise (dontneed-after-mmap)
[820.758463] Starting subtest: dontneed-after-mmap
[820.786977] Subtest dontneed-after-mmap: SUCCESS (0.029s)
[820.883085] [073/133] (203s left) kms_plane_alpha_blend (constant-alpha-mid)
[820.979001] Starting subtest: constant-alpha-mid
[821.265630] Starting dynamic subtest: pipe-A-eDP-1
[822.133991] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.866s)
[822.144455] Starting dynamic subtest: pipe-D-eDP-1
[823.595710] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.463s)
[823.598464] Subtest constant-alpha-mid: SUCCESS (2.619s)
[824.489277] [074/133] (199s left) kms_pipe_b_c_ivb (enable-pipe-c-while-b-has-3-lanes)
[824.548496] Subtest enable-pipe-C-while-B-has-3-lanes: SKIP (0.000s)
[824.605576] [075/133] (199s left) kms_flip (2x-flip-vs-wf_vblank)
[824.693233] Starting subtest: 2x-flip-vs-wf_vblank
[824.701312] Subtest 2x-flip-vs-wf_vblank: SKIP (0.011s)
[824.783171] [076/133] (199s left) kms_cursor_crc (cursor-sliding-128x42)
[825.030412] Starting subtest: cursor-sliding-128x42
[825.078300] Subtest cursor-sliding-128x42: SKIP (0.046s)
[825.279068] [077/133] (199s left) kms_psr (psr2-suspend)
[825.369872] Starting subtest: psr2-suspend
[825.372285] Starting dynamic subtest: eDP-1
[843.633331] Dynamic subtest eDP-1: SUCCESS (3.600s)
[843.636268] Subtest psr2-suspend: SUCCESS (3.602s)
[844.423439] [078/133] (180s left) gem_mmap_offset (open-flood)
[844.501364] Starting subtest: open-flood
[865.954250] Subtest open-flood: SUCCESS (21.489s)
[866.055435] [079/133] (158s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[866.154100] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[866.158325] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[866.243472] [080/133] (158s left) gem_exec_nop (basic-sequential)
[866.308933] Starting subtest: basic-sequential
[880.578479] Subtest basic-sequential: SUCCESS (14.279s)
[880.821263] [081/133] (143s left) kms_flip_scaled_crc (flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[880.931248] Starting subtest: flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[880.936243] Subtest flip-32bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s)
[881.005300] [082/133] (143s left) gem_mmap_gtt (basic-small-bo-tiledy)
[881.058250] Subtest basic-small-bo-tiledY: SKIP (0.000s)
[881.114052] [083/133] (143s left) kms_big_fb (y-tiled-64bpp-rotate-90)
[881.201233] Starting subtest: y-tiled-64bpp-rotate-90
[881.206256] Subtest y-tiled-64bpp-rotate-90: SKIP (0.000s)
[881.283829] [084/133] (143s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite)
[881.379154] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite
[889.963432] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (8.582s)
[890.079033] [085/133] (134s left) gem_exec_alignment (single)
[890.146001] Starting subtest: single
[890.152153] Subtest single: SUCCESS (0.004s)
[890.274862] [086/133] (134s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[890.377973] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[890.383149] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[890.474806] [087/133] (134s left) syncobj_timeline (etime-single-wait-submitted)
[890.534433] Starting subtest: etime-single-wait-submitted
[890.639432] Subtest etime-single-wait-submitted: SUCCESS (0.105s)
[890.717760] [088/133] (134s left) i915_drm_fdinfo (virtual-busy-all)
[890.766347] Starting subtest: virtual-busy-all
[890.772230] Subtest virtual-busy-all: SKIP (0.000s)
[890.842635] [089/133] (134s left) gem_ctx_exec (basic)
[890.900248] Starting subtest: basic
[890.903235] Subtest basic: SUCCESS (0.009s)
[891.029784] [090/133] (134s left) gem_busy (parallel)
[891.241442] Starting subtest: parallel
[891.244368] Starting dynamic subtest: rcs0
[893.132600] Dynamic subtest rcs0: SUCCESS (1.892s)
[893.146841] Starting dynamic subtest: bcs0
[895.116270] Dynamic subtest bcs0: SUCCESS (1.980s)
[895.130485] Starting dynamic subtest: vcs0
[897.100370] Dynamic subtest vcs0: SUCCESS (1.981s)
[897.115991] Starting dynamic subtest: vcs1
[899.084333] Dynamic subtest vcs1: SUCCESS (1.981s)
[899.097595] Starting dynamic subtest: vecs0
[901.068481] Dynamic subtest vecs0: SUCCESS (1.981s)
[901.081503] Starting dynamic subtest: ccs0
[903.114943] Dynamic subtest ccs0: SUCCESS (2.044s)
[903.118053] Subtest parallel: SUCCESS (11.875s)
[903.348623] [091/133] (121s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled)
[903.413246] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled
[903.416066] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s)
[903.502609] [092/133] (121s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytile-downscaling)
[903.595204] Starting subtest: flip-64bpp-ytile-to-32bpp-ytile-downscaling
[903.599723] Subtest flip-64bpp-ytile-to-32bpp-ytile-downscaling: SKIP (0.000s)
[903.669243] [093/133] (121s left) kms_vblank (ts-continuation-dpms-rpm)
[903.766839] Starting subtest: ts-continuation-dpms-rpm
[903.769348] Starting dynamic subtest: pipe-A-eDP-1
[906.245453] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.475s)
[906.260674] Starting dynamic subtest: pipe-D-eDP-1
[908.483835] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.238s)
[908.486848] Subtest ts-continuation-dpms-rpm: SUCCESS (4.716s)
[909.292568] [094/133] (115s left) sw_sync (timeline_closed_signaled)
[909.333349] Starting subtest: timeline_closed_signaled
[909.336607] Subtest timeline_closed_signaled: SUCCESS (0.000s)
[909.384342] [095/133] (115s left) gem_lmem_swapping (verify)
[909.430432] Subtest verify: SKIP (0.000s)
[909.472664] [096/133] (115s left) syncobj_wait (invalid-signal-zero-handles)
[909.530106] Starting subtest: invalid-signal-zero-handles
[909.532441] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[909.644832] [097/133] (115s left) kms_pm_rpm (i2c)
[911.171280] Starting subtest: i2c
[916.888747] Subtest i2c: SUCCESS (5.719s)
[916.976321] [098/133] (108s left) vgem_basic (setversion)
[917.030103] Starting subtest: setversion
[917.035104] Subtest setversion: SUCCESS (0.000s)
[917.104186] [099/133] (108s left) gem_exec_reloc (basic-write-gtt)
[917.166454] Subtest basic-write-gtt: SKIP (0.000s)
[917.237724] [100/133] (108s left) kms_scaling_modes (scaling-mode-center)
[917.330244] Starting subtest: scaling-mode-center
[917.332876] Starting dynamic subtest: pipe-A-eDP-1
[917.447974] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.116s)
[917.463626] Starting dynamic subtest: pipe-B-eDP-1
[918.589818] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.140s)
[918.601739] Starting dynamic subtest: pipe-C-eDP-1
[919.705237] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.114s)
[919.716123] Starting dynamic subtest: pipe-D-eDP-1
[920.839477] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.134s)
[920.842298] Subtest scaling-mode-center: SUCCESS (3.511s)
[922.017765] [101/133] (103s left) perf_pmu (frequency)
[922.068429] Starting subtest: frequency
[922.074005] Starting dynamic subtest: gt0
[924.186007] Dynamic subtest gt0: SUCCESS (2.108s)
[924.197974] Starting dynamic subtest: idle-gt0
[924.690265] Dynamic subtest idle-gt0: SUCCESS (0.504s)
[924.702712] Starting dynamic subtest: gt1
[929.098299] Dynamic subtest gt1: SUCCESS (4.402s)
[929.110491] Starting dynamic subtest: idle-gt1
[929.601604] Dynamic subtest idle-gt1: SUCCESS (0.504s)
[929.604633] Subtest frequency: SUCCESS (7.536s)
[929.716575] [102/133] ( 95s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[929.828194] Starting subtest: 4-tiled-32bpp-rotate-180
[931.195462] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.369s)
[931.319207] [103/133] ( 94s left) kms_hdr (bpc-switch-dpms)
[931.411290] Starting subtest: bpc-switch-dpms
[932.475350] Starting dynamic subtest: pipe-A-eDP-1
[936.908473] Dynamic subtest pipe-A-eDP-1: SUCCESS (4.435s)
[936.911256] Subtest bpc-switch-dpms: SUCCESS (5.501s)
[937.023095] [104/133] ( 88s left) syncobj_timeline (wait-any-interrupted)
[937.082729] Starting subtest: wait-any-interrupted
[937.182448] Subtest wait-any-interrupted: SUCCESS (0.102s)
[937.270408] [105/133] ( 88s left) gem_exec_parallel (contexts)
[937.334049] Starting subtest: contexts
[937.336067] Starting dynamic subtest: rcs0
[940.801251] Dynamic subtest rcs0: SUCCESS (3.468s)
[940.812815] Starting dynamic subtest: bcs0
[944.952919] Dynamic subtest bcs0: SUCCESS (4.149s)
[944.965082] Starting dynamic subtest: vcs0
[949.668681] Dynamic subtest vcs0: SUCCESS (4.713s)
[949.678736] Starting dynamic subtest: vcs1
[955.400724] Dynamic subtest vcs1: SUCCESS (5.729s)
[955.423081] Starting dynamic subtest: vecs0
[961.438457] Dynamic subtest vecs0: SUCCESS (6.034s)
[961.449503] Starting dynamic subtest: ccs0
[966.233649] Dynamic subtest ccs0: SUCCESS (4.792s)
[966.236613] Subtest contexts: SUCCESS (28.898s)
[966.454171] [106/133] ( 59s left) gem_pxp (verify-pxp-key-change-after-suspend-resume)
[966.588728] Starting subtest: verify-pxp-key-change-after-suspend-resume
[973.766015] Subtest verify-pxp-key-change-after-suspend-resume: SUCCESS (7.181s)
[973.889422] [107/133] ( 51s left) gem_create (create-ext-placement-each)
[974.051022] Starting subtest: create-ext-placement-each
[974.054273] Subtest create-ext-placement-each: SUCCESS (0.000s)
[974.154507] [108/133] ( 51s left) kms_ccs (bad-aux-stride-4-tiled-mtl-rc-ccs)
[974.240547] Starting subtest: bad-aux-stride-4-tiled-mtl-rc-ccs
[974.243377] Starting dynamic subtest: pipe-A-eDP-1
[974.268455] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[974.280042] Starting dynamic subtest: pipe-B-eDP-1
[974.303133] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[974.312955] Starting dynamic subtest: pipe-C-eDP-1
[974.336179] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[974.346242] Starting dynamic subtest: pipe-D-eDP-1
[974.370094] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[974.372286] Subtest bad-aux-stride-4-tiled-mtl-rc-ccs: SUCCESS (0.007s)
[976.413527] [109/133] ( 49s left) gem_close_race (basic-process)
[976.483797] Starting subtest: basic-process
[976.525740] Subtest basic-process: SUCCESS (0.042s)
[976.720833] [110/133] ( 49s left) gem_exec_schedule (u-independent)
[976.809383] Starting subtest: u-independent
[976.812258] Starting dynamic subtest: rcs0
[978.126426] Dynamic subtest rcs0: SUCCESS (1.328s)
[978.143219] Starting dynamic subtest: vcs0
[980.113306] Dynamic subtest vcs0: SUCCESS (1.984s)
[980.126477] Starting dynamic subtest: vcs1
[982.098909] Dynamic subtest vcs1: SUCCESS (1.984s)
[982.112497] Starting dynamic subtest: vecs0
[984.087435] Dynamic subtest vecs0: SUCCESS (1.985s)
[984.101133] Starting dynamic subtest: ccs0
[986.123311] Dynamic subtest ccs0: SUCCESS (2.033s)
[986.126213] Subtest u-independent: SUCCESS (9.326s)
[986.427971] [111/133] ( 39s left) gen9_exec_parse (batch-without-end)
[986.490320] Subtest batch-without-end: SKIP (0.000s)
[986.560622] [112/133] ( 39s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[988.290867] Starting subtest: dpms-mode-unset-lpsp
[992.338039] Subtest dpms-mode-unset-lpsp: SUCCESS (4.047s)
[992.716349] [113/133] ( 33s left) kms_chamelium_hpd (vga-hpd)
[993.094221] Subtest vga-hpd: SKIP (0.000s)
[993.896943] [114/133] ( 32s left) gem_lmem_swapping (heavy-multi)
[993.942358] Subtest heavy-multi: SKIP (0.000s)
[993.982046] [115/133] ( 32s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-exceed-fully-sf)
[994.088004] Starting subtest: fbc-pr-overlay-plane-move-continuous-exceed-fully-sf
[994.092699] Subtest fbc-pr-overlay-plane-move-continuous-exceed-fully-sf: SKIP (0.000s)
[994.157489] [116/133] ( 32s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-fullscreen)
[994.260279] Starting subtest: fbc-2p-scndscrn-spr-indfb-fullscreen
[994.265118] Subtest fbc-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s)
[994.355594] [117/133] ( 31s left) kms_frontbuffer_tracking (fbc-1p-primscrn-indfb-pgflip-blt)
[994.457312] Starting subtest: fbc-1p-primscrn-indfb-pgflip-blt
[998.915249] Subtest fbc-1p-primscrn-indfb-pgflip-blt: SUCCESS (4.463s)
[1000.047367] [118/133] ( 26s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-render)
[1000.150451] Starting subtest: psr-1p-primscrn-pri-indfb-draw-render
[1007.390027] Subtest psr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.243s)
[1007.481320] [119/133] ( 18s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt)
[1007.588077] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt
[1007.593162] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[1007.688844] [120/133] ( 18s left) gem_exec_balancer (noheartbeat)
[1007.947757] Starting subtest: noheartbeat
[1007.952254] Subtest noheartbeat: SKIP (0.000s)
[1008.060959] [121/133] ( 18s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[1008.111920] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[1008.214212] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.103s)
[1008.295282] [122/133] ( 18s left) kms_big_fb (linear-addfb)
[1008.399991] Starting subtest: linear-addfb
[1008.402478] Subtest linear-addfb: SUCCESS (0.000s)
[1008.496698] [123/133] ( 18s left) perf_pmu (busy)
[1008.539999] Starting subtest: busy
[1008.543012] Starting dynamic subtest: rcs0
[1009.131837] Dynamic subtest rcs0: SUCCESS (0.593s)
[1009.145332] Starting dynamic subtest: bcs0
[1009.694678] Dynamic subtest bcs0: SUCCESS (0.561s)
[1009.708233] Starting dynamic subtest: vcs0
[1011.083778] Dynamic subtest vcs0: SUCCESS (1.385s)
[1011.097455] Starting dynamic subtest: vcs1
[1013.067030] Dynamic subtest vcs1: SUCCESS (1.980s)
[1013.080774] Starting dynamic subtest: vecs0
[1015.116310] Dynamic subtest vecs0: SUCCESS (2.046s)
[1015.138690] Starting dynamic subtest: ccs0
[1015.689860] Dynamic subtest ccs0: SUCCESS (0.571s)
[1015.692947] Subtest busy: SUCCESS (7.152s)
[1015.807716] [124/133] ( 10s left) kms_force_connector_basic (force-load-detect)
[1015.880268] Starting subtest: force-load-detect
[1015.885490] Subtest force-load-detect: SKIP (0.000s)
[1016.010859] [125/133] ( 10s left) gem_ctx_param (invalid-get-ringsize)
[1016.069802] Starting subtest: invalid-get-ringsize
[1016.073008] Subtest invalid-get-ringsize: SUCCESS (0.001s)
[1016.186898] [126/133] ( 10s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-pgflip-blt)
[1016.289200] Starting subtest: psr-1p-primscrn-shrfb-pgflip-blt
[1023.563715] Subtest psr-1p-primscrn-shrfb-pgflip-blt: SUCCESS (7.280s)
[1023.687968] [127/133] (  3s left) kms_cursor_legacy (cursorb-vs-flipa-varying-size)
[1023.804636] Starting subtest: cursorB-vs-flipA-varying-size
[1023.809731] Subtest cursorB-vs-flipA-varying-size: SKIP (0.001s)
[1023.962964] [128/133] (  2s left) kms_flip (2x-blocking-wf_vblank)
[1024.057053] Starting subtest: 2x-blocking-wf_vblank
[1024.068220] Subtest 2x-blocking-wf_vblank: SKIP (0.010s)
[1024.145953] [129/133] (  2s left) kms_plane_scaling (planes-downscale-factor-0-75)
[1024.234870] Starting subtest: planes-downscale-factor-0-75
[1024.237419] Starting dynamic subtest: pipe-A
[1024.256271] Dynamic subtest pipe-A: SKIP (0.017s)
[1024.272203] Starting dynamic subtest: pipe-B
[1024.287145] Dynamic subtest pipe-B: SKIP (0.032s)
[1024.298442] Starting dynamic subtest: pipe-C
[1024.320159] Dynamic subtest pipe-C: SKIP (0.032s)
[1024.330226] Starting dynamic subtest: pipe-D
[1024.349385] Dynamic subtest pipe-D: SKIP (0.029s)
[1024.353908] Subtest planes-downscale-factor-0-75: SKIP (0.113s)
[1024.455024] [130/133] (  2s left) gem_exec_whisper (basic-fds-forked-all)
[1024.514842] Starting subtest: basic-fds-forked-all
[1046.903469] Subtest basic-fds-forked-all: SUCCESS (22.384s)
[1047.085869] Overall timeout time exceeded, stopping.
[1047.102168] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_crc
results: parsing output: 1/ for test: kms_ccs
results: parsing output: 2/ for test: sysfs_timeslice_duration
results: parsing output: 3/ for test: kms_ccs
results: parsing output: 4/ for test: drm_buddy
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: kms_ccs
results: parsing output: 7/ for test: kms_ccs
results: parsing output: 8/ for test: syncobj_timeline
results: parsing output: 9/ for test: perf_pmu
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_psr
results: parsing output: 14/ for test: gem_exec_balancer
results: parsing output: 15/ for test: kms_joiner
results: parsing output: 16/ for test: kms_cursor_legacy
results: parsing output: 17/ for test: gem_wait
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_flip
results: parsing output: 20/ for test: kms_lease
results: parsing output: 21/ for test: gem_exec_params
results: parsing output: 22/ for test: api_intel_bb
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: i915_pm_rpm
results: parsing output: 25/ for test: kms_big_fb
results: parsing output: 26/ for test: gem_exec_schedule
results: parsing output: 27/ for test: gem_exec_flush
results: parsing output: 28/ for test: kms_color_pipeline
results: parsing output: 29/ for test: gem_exec_schedule
results: parsing output: 30/ for test: kms_cursor_crc
results: parsing output: 31/ for test: perf
results: parsing output: 32/ for test: api_intel_bb
results: parsing output: 33/ for test: prime_mmap
results: parsing output: 34/ for test: kms_atomic_transition
results: parsing output: 35/ for test: kms_plane_scaling
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_pxp
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: gem_userptr_blits
results: parsing output: 40/ for test: gem_eio
results: parsing output: 41/ for test: kms_frontbuffer_tracking
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_selftest
results: parsing output: 44/ for test: gem_spin_batch
results: parsing output: 45/ for test: testdisplay
results: parsing output: 46/ for test: kms_psr2_sf
results: parsing output: 47/ for test: i915_selftest
results: parsing output: 48/ for test: gem_partial_pwrite_pread
results: parsing output: 49/ for test: kms_getfb
results: parsing output: 50/ for test: kms_psr
results: parsing output: 51/ for test: i915_hangman
results: parsing output: 52/ for test: perf
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: kms_async_flips
results: parsing output: 55/ for test: sw_sync
results: parsing output: 56/ for test: kms_flip_scaled_crc
results: parsing output: 57/ for test: gem_ctx_persistence
results: parsing output: 58/ for test: kms_big_fb
results: parsing output: 59/ for test: kms_flip
results: parsing output: 60/ for test: sw_sync
results: parsing output: 61/ for test: gem_exec_fence
results: parsing output: 62/ for test: kms_plane
results: parsing output: 63/ for test: perf_pmu
results: parsing output: 64/ for test: kms_chamelium_frames
results: parsing output: 65/ for test: kms_draw_crc
results: parsing output: 66/ for test: kms_content_protection
results: parsing output: 67/ for test: kms_ccs
results: parsing output: 68/ for test: kms_psr
results: parsing output: 69/ for test: gem_exec_flush
results: parsing output: 70/ for test: kms_dither
results: parsing output: 71/ for test: gem_madvise
results: parsing output: 72/ for test: kms_plane_alpha_blend
results: parsing output: 73/ for test: kms_pipe_b_c_ivb
results: parsing output: 74/ for test: kms_flip
results: parsing output: 75/ for test: kms_cursor_crc
results: parsing output: 76/ for test: kms_psr
results: parsing output: 77/ for test: gem_mmap_offset
results: parsing output: 78/ for test: kms_frontbuffer_tracking
results: parsing output: 79/ for test: gem_exec_nop
results: parsing output: 80/ for test: kms_flip_scaled_crc
results: parsing output: 81/ for test: gem_mmap_gtt
results: parsing output: 82/ for test: kms_big_fb
results: parsing output: 83/ for test: kms_frontbuffer_tracking
results: parsing output: 84/ for test: gem_exec_alignment
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: syncobj_timeline
results: parsing output: 87/ for test: i915_drm_fdinfo
results: parsing output: 88/ for test: gem_ctx_exec
results: parsing output: 89/ for test: gem_busy
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_flip_scaled_crc
results: parsing output: 92/ for test: kms_vblank
results: parsing output: 93/ for test: sw_sync
results: parsing output: 94/ for test: gem_lmem_swapping
results: parsing output: 95/ for test: syncobj_wait
results: parsing output: 96/ for test: kms_pm_rpm
results: parsing output: 97/ for test: vgem_basic
results: parsing output: 98/ for test: gem_exec_reloc
results: parsing output: 99/ for test: kms_scaling_modes
results: parsing output: 100/ for test: perf_pmu
results: parsing output: 101/ for test: kms_big_fb
results: parsing output: 102/ for test: kms_hdr
results: parsing output: 103/ for test: syncobj_timeline
results: parsing output: 104/ for test: gem_exec_parallel
results: parsing output: 105/ for test: gem_pxp
results: parsing output: 106/ for test: gem_create
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: gem_close_race
results: parsing output: 109/ for test: gem_exec_schedule
results: parsing output: 110/ for test: gen9_exec_parse
results: parsing output: 111/ for test: kms_pm_rpm
results: parsing output: 112/ for test: kms_chamelium_hpd
results: parsing output: 113/ for test: gem_lmem_swapping
results: parsing output: 114/ for test: kms_psr2_sf
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: kms_frontbuffer_tracking
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: gem_exec_balancer
results: parsing output: 120/ for test: syncobj_timeline
results: parsing output: 121/ for test: kms_big_fb
results: parsing output: 122/ for test: perf_pmu
results: parsing output: 123/ for test: kms_force_connector_basic
results: parsing output: 124/ for test: gem_ctx_param
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_cursor_legacy
results: parsing output: 127/ for test: kms_flip
results: parsing output: 128/ for test: kms_plane_scaling
results: parsing output: 129/ for test: gem_exec_whisper
results: parsing output: 130/ for test: kms_plane_lowres
results: no output, setting notrun
results: parsing output: 131/ for test: gem_render_copy
results: no output, setting notrun
results: parsing output: 132/ for test: prime_vgem
results: no output, setting notrun
Done.