[61.829046] Initializing watchdogs
[61.829089]   /dev/watchdog0
[61.842769] [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]
[61.846833] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[61.849128] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[61.852600] [001/132] (960s left) kms_pm_dc (dc9-dpms)
[62.982568] Starting subtest: dc9-dpms
[64.405990] Subtest dc9-dpms: SUCCESS (1.422s)
[64.488956] [002/132] (957s left) gem_exec_schedule (semaphore-resolve)
[64.563807] Starting subtest: semaphore-resolve
[64.597963] Subtest semaphore-resolve: SUCCESS (0.040s)
[64.971180] [003/132] (957s left) gem_exec_params (mmapped)
[65.036647] Starting subtest: mmapped
[65.041479] Subtest mmapped: SUCCESS (0.004s)
[65.182661] [004/132] (957s left) kms_psr (psr-primary-page-flip)
[65.281145] Starting subtest: psr-primary-page-flip
[65.283422] Starting dynamic subtest: eDP-1
[67.060896] Dynamic subtest eDP-1: SUCCESS (1.775s)
[67.063535] Subtest psr-primary-page-flip: SUCCESS (1.776s)
[67.960585] [005/132] (954s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[68.023888] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[68.026240] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[68.177332] [006/132] (954s left) kms_cursor_legacy (cursora-vs-flipa-toggle)
[68.313221] Starting subtest: cursorA-vs-flipA-toggle
[79.509247] Subtest cursorA-vs-flipA-toggle: SUCCESS (11.191s)
[80.369846] [007/132] (942s left) gem_exec_create (forked)
[80.433937] Starting subtest: forked
[80.436203] Starting dynamic subtest: smem
[101.910990] Dynamic subtest smem: SUCCESS (21.471s)
[101.914056] Subtest forked: SUCCESS (21.472s)
[102.140087] [008/132] (920s left) gem_exec_reloc (basic-gtt-cpu)
[102.212673] Subtest basic-gtt-cpu: SKIP (0.000s)
[102.294169] [009/132] (920s left) kms_big_fb (x-tiled-addfb-size-offset-overflow)
[102.398768] Starting subtest: x-tiled-addfb-size-offset-overflow
[102.401090] Subtest x-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[102.509703] [010/132] (920s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-move)
[102.625057] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-move
[110.088245] Subtest fbcpsr-1p-primscrn-cur-indfb-move: SUCCESS (7.466s)
[110.271908] [011/132] (912s left) perf_pmu (faulting-read)
[110.312024] Starting subtest: faulting-read
[110.314461] Starting dynamic subtest: wb
[110.316787] Dynamic subtest wb: SUCCESS (0.001s)
[110.329517] Starting dynamic subtest: wc
[110.331778] Dynamic subtest wc: SUCCESS (0.000s)
[110.341586] Starting dynamic subtest: uc
[110.343758] Dynamic subtest uc: SUCCESS (0.000s)
[110.345934] Subtest faulting-read: SUCCESS (0.002s)
[110.430392] [012/132] (912s left) gem_ctx_persistence (engines-persistence)
[110.492988] Starting subtest: engines-persistence
[110.495451] Starting dynamic subtest: rcs0
[111.344797] Dynamic subtest rcs0: SUCCESS (0.851s)
[111.356863] Starting dynamic subtest: bcs0
[112.158817] Dynamic subtest bcs0: SUCCESS (0.813s)
[112.170299] Starting dynamic subtest: vcs0
[112.939963] Dynamic subtest vcs0: SUCCESS (0.780s)
[112.950953] Starting dynamic subtest: vcs1
[113.776832] Dynamic subtest vcs1: SUCCESS (0.836s)
[113.787541] Starting dynamic subtest: vecs0
[114.607797] Dynamic subtest vecs0: SUCCESS (0.830s)
[114.618578] Starting dynamic subtest: ccs0
[115.416822] Dynamic subtest ccs0: SUCCESS (0.808s)
[115.419348] Subtest engines-persistence: SUCCESS (4.924s)
[115.522375] [013/132] (907s left) gem_mmap_gtt (ptrace)
[115.574914] Subtest ptrace: SKIP (0.000s)
[115.635296] [014/132] (907s left) kms_flip (dpms-off-confusion-interruptible)
[115.735153] Starting subtest: dpms-off-confusion-interruptible
[115.737502] Starting dynamic subtest: A-eDP1
[124.397806] Dynamic subtest A-eDP1: SUCCESS (8.661s)
[124.405634] Starting dynamic subtest: B-eDP1
[133.037270] Dynamic subtest B-eDP1: SUCCESS (8.638s)
[133.047303] Starting dynamic subtest: C-eDP1
[141.658086] Dynamic subtest C-eDP1: SUCCESS (8.619s)
[141.666837] Starting dynamic subtest: D-eDP1
[150.288157] Dynamic subtest D-eDP1: SUCCESS (8.629s)
[150.292699] Subtest dpms-off-confusion-interruptible: SUCCESS (34.557s)
[151.486193] [015/132] (871s left) syncobj_wait (multi-wait-submitted-signaled)
[151.552237] Starting subtest: multi-wait-submitted-signaled
[151.554526] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s)
[151.697567] [016/132] (871s left) kms_cursor_crc (cursor-sliding-512x170)
[151.976209] Starting subtest: cursor-sliding-512x170
[151.980730] Subtest cursor-sliding-512x170: SKIP (0.001s)
[152.155307] [017/132] (870s left) kms_big_fb (linear-8bpp-rotate-270)
[152.258351] Starting subtest: linear-8bpp-rotate-270
[152.278931] Subtest linear-8bpp-rotate-270: SKIP (0.020s)
[152.440106] [018/132] (870s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75)
[152.535889] Starting subtest: planes-unity-scaling-downscale-factor-0-75
[152.537857] Starting dynamic subtest: pipe-A
[152.607493] Dynamic subtest pipe-A: SUCCESS (0.073s)
[152.617224] Starting dynamic subtest: pipe-B
[153.785686] Dynamic subtest pipe-B: SUCCESS (1.176s)
[153.798565] Starting dynamic subtest: pipe-C
[154.891860] Dynamic subtest pipe-C: SUCCESS (1.105s)
[154.903707] Starting dynamic subtest: pipe-D
[156.017449] Dynamic subtest pipe-D: SUCCESS (1.124s)
[156.019907] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.484s)
[157.201599] [019/132] (865s left) kms_psr2_sf (fbc-pr-overlay-plane-move-continuous-sf)
[162.260540] Starting subtest: fbc-pr-overlay-plane-move-continuous-sf
[162.265323] Subtest fbc-pr-overlay-plane-move-continuous-sf: SKIP (0.002s)
[163.134925] [020/132] (860s left) kms_frontbuffer_tracking (psr-rgb565-draw-pwrite)
[163.245179] Starting subtest: psr-rgb565-draw-pwrite
[170.172625] Subtest psr-rgb565-draw-pwrite: SUCCESS (6.931s)
[170.302889] [021/132] (852s left) prime_busy (after-wait)
[170.386834] Starting subtest: after-wait
[170.390249] Starting dynamic subtest: rcs0
[170.443164] Dynamic subtest rcs0: SUCCESS (0.052s)
[170.453156] Starting dynamic subtest: bcs0
[170.527119] Dynamic subtest bcs0: SUCCESS (0.083s)
[170.536944] Starting dynamic subtest: vcs0
[170.567481] Dynamic subtest vcs0: SUCCESS (0.040s)
[170.575458] Starting dynamic subtest: vcs1
[170.608933] Dynamic subtest vcs1: SUCCESS (0.041s)
[170.617710] Starting dynamic subtest: vecs0
[170.652027] Dynamic subtest vecs0: SUCCESS (0.042s)
[170.660971] Starting dynamic subtest: ccs0
[170.692731] Dynamic subtest ccs0: SUCCESS (0.040s)
[170.802843] Subtest after-wait: SUCCESS (0.417s)
[170.898349] [022/132] (852s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[171.193570] Starting subtest: cursor-rapid-movement-max-size
[171.200179] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[171.351112] [023/132] (851s left) gem_exec_balancer (indices)
[171.408258] Starting subtest: indices
[173.877410] Subtest indices: SUCCESS (2.467s)
[174.197470] [024/132] (849s left) syncobj_wait (wait-any-interrupted)
[174.267326] Starting subtest: wait-any-interrupted
[174.370086] Subtest wait-any-interrupted: SUCCESS (0.102s)
[174.473581] [025/132] (848s left) kms_lease (lease-again)
[174.567936] Starting subtest: lease-again
[174.570226] Starting dynamic subtest: pipe-A-eDP-1
[174.572528] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.002s)
[174.581366] Starting dynamic subtest: pipe-B-eDP-1
[174.583222] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[174.590812] Starting dynamic subtest: pipe-C-eDP-1
[174.592705] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[174.600676] Starting dynamic subtest: pipe-D-eDP-1
[174.602779] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[174.604898] Subtest lease-again: SUCCESS (0.013s)
[174.723426] [026/132] (848s left) template (b)
[174.781615] Starting subtest: B
[174.783481] Subtest B: SUCCESS (0.000s)
[174.907639] [027/132] (848s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite)
[175.014126] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite
[175.018253] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[175.128311] [028/132] (848s left) gem_ctx_create (basic)
[175.196929] Starting subtest: basic
[175.198964] Subtest basic: SUCCESS (0.000s)
[175.401109] [029/132] (848s left) gem_reg_read (bad-register)
[175.455581] Starting subtest: bad-register
[175.457581] Subtest bad-register: SUCCESS (0.000s)
[175.587134] [030/132] (847s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[175.650837] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[175.755696] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.103s)
[175.855300] [031/132] (847s left) syncobj_wait (invalid-signal-one-illegal-handle)
[175.910404] Starting subtest: invalid-signal-one-illegal-handle
[175.912434] Subtest invalid-signal-one-illegal-handle: SUCCESS (0.000s)
[176.023165] [032/132] (847s left) kms_plane (plane-panning-bottom-right)
[176.115893] Starting subtest: plane-panning-bottom-right
[176.117753] Starting dynamic subtest: pipe-A
[177.883972] Dynamic subtest pipe-A: SUCCESS (1.768s)
[177.892769] Starting dynamic subtest: pipe-B
[180.216496] Dynamic subtest pipe-B: SUCCESS (2.329s)
[180.218384] Subtest plane-panning-bottom-right: SUCCESS (4.101s)
[181.054828] [033/132] (842s left) kms_dirtyfb (default-dirtyfb-ioctl)
[181.156989] Starting subtest: default-dirtyfb-ioctl
[181.158851] Starting dynamic subtest: A-eDP-1
[182.765066] Dynamic subtest A-eDP-1: SUCCESS (1.607s)
[182.767482] Subtest default-dirtyfb-ioctl: SUCCESS (1.608s)
[183.589911] [034/132] (840s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-mmap-wc)
[183.699573] Starting subtest: psr-1p-offscreen-pri-indfb-draw-mmap-wc
[190.357592] Subtest psr-1p-offscreen-pri-indfb-draw-mmap-wc: SUCCESS (6.660s)
[190.534745] [035/132] (833s left) gen9_exec_parse (unaligned-access)
[190.594336] Subtest unaligned-access: SKIP (0.000s)
[190.675407] [036/132] (833s left) kms_atomic (plane-invalid-params-fence)
[190.778173] Starting subtest: plane-invalid-params-fence
[190.780408] Starting dynamic subtest: pipe-A-eDP-1
[191.134687] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.357s)
[191.136979] Subtest plane-invalid-params-fence: SUCCESS (0.358s)
[191.968705] [037/132] (831s left) gem_exec_schedule (out-order)
[192.041264] Starting subtest: out-order
[192.043459] Starting dynamic subtest: rcs0
[192.139839] Dynamic subtest rcs0: SUCCESS (0.103s)
[192.150895] Starting dynamic subtest: vcs0
[192.226597] Dynamic subtest vcs0: SUCCESS (0.087s)
[192.240575] Starting dynamic subtest: vcs1
[192.301585] Dynamic subtest vcs1: SUCCESS (0.074s)
[192.312517] Starting dynamic subtest: vecs0
[192.375405] Dynamic subtest vecs0: SUCCESS (0.073s)
[192.386816] Starting dynamic subtest: ccs0
[192.457492] Dynamic subtest ccs0: SUCCESS (0.082s)
[192.459936] Subtest out-order: SUCCESS (0.421s)
[194.003104] [038/132] (829s left) gem_ctx_shared (exec-shared-gtt)
[194.055264] Starting subtest: exec-shared-gtt
[194.057512] Starting dynamic subtest: rcs0
[194.062758] Dynamic subtest rcs0: SUCCESS (0.010s)
[194.072655] Starting dynamic subtest: bcs0
[194.074572] Dynamic subtest bcs0: SUCCESS (0.006s)
[194.082830] Starting dynamic subtest: vcs0
[194.085177] Dynamic subtest vcs0: SUCCESS (0.006s)
[194.093734] Starting dynamic subtest: vcs1
[194.096065] Dynamic subtest vcs1: SUCCESS (0.008s)
[194.103973] Starting dynamic subtest: vecs0
[194.106246] Dynamic subtest vecs0: SUCCESS (0.004s)
[194.114331] Starting dynamic subtest: ccs0
[194.116677] Dynamic subtest ccs0: SUCCESS (0.005s)
[194.118999] Subtest exec-shared-gtt: SUCCESS (0.043s)
[194.308334] [039/132] (829s left) kms_getfb (getfb2-handle-zero)
[194.408160] Starting subtest: getfb2-handle-zero
[194.410470] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[194.551106] [040/132] (829s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[194.652050] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[194.654327] Starting dynamic subtest: pipe-A-default-mode
[195.804909] Dynamic subtest pipe-A-default-mode: SKIP (1.151s)
[195.809219] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.152s)
[195.982918] [041/132] (827s left) gem_eio (wait-10ms)
[196.220268] Starting subtest: wait-10ms
[196.552498] Subtest wait-10ms: SUCCESS (0.331s)
[196.727440] [042/132] (827s left) kms_addfb_basic (basic)
[196.792864] Starting subtest: basic
[196.794754] Subtest basic: SUCCESS (0.000s)
[196.954153] [043/132] (827s left) gem_close_race (basic-threads)
[197.028599] Starting subtest: basic-threads
[198.431447] Subtest basic-threads: SUCCESS (1.404s)
[198.636965] [044/132] (825s left) gen9_exec_parse (secure-batches)
[198.707079] Subtest secure-batches: SKIP (0.000s)
[198.775600] [045/132] (825s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu)
[198.884709] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu
[203.120671] Subtest fbc-1p-primscrn-pri-shrfb-draw-mmap-cpu: SUCCESS (4.240s)
[204.293407] [046/132] (819s left) kms_psr (fbc-psr2-sprite-plane-move)
[204.392880] Starting subtest: fbc-psr2-sprite-plane-move
[204.395239] Starting dynamic subtest: eDP-1
[204.399839] Dynamic subtest eDP-1: SKIP (0.000s)
[204.404554] Subtest fbc-psr2-sprite-plane-move: SKIP (0.001s)
[204.486341] [047/132] (819s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[204.581112] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[204.585236] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[204.715871] [048/132] (819s left) api_intel_bb (full-batch)
[204.771965] Starting subtest: full-batch
[204.776783] Subtest full-batch: SUCCESS (0.005s)
[204.919898] [049/132] (819s left) gem_exec_create (basic)
[204.985705] Starting subtest: basic
[204.987817] Starting dynamic subtest: smem
[208.854700] Dynamic subtest smem: SUCCESS (3.869s)
[208.857733] Subtest basic: SUCCESS (3.870s)
[209.064406] [050/132] (815s left) kms_content_protection (suspend-resume)
[209.217984] Starting subtest: suspend-resume
[209.222680] Subtest suspend-resume: SKIP (0.000s)
[209.372817] [051/132] (814s left) kms_vblank (wait-forked)
[209.471043] Starting subtest: wait-forked
[209.472913] Starting dynamic subtest: pipe-A-eDP-1
[211.288450] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.817s)
[211.302655] Starting dynamic subtest: pipe-D-eDP-1
[213.754823] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.463s)
[213.756853] Subtest wait-forked: SUCCESS (4.283s)
[214.651927] [052/132] (809s left) gem_spin_batch (legacy-resubmit-new)
[214.713342] Starting subtest: legacy-resubmit-new
[214.715445] Starting dynamic subtest: default
[214.720051] Dynamic subtest default: SUCCESS (0.008s)
[214.730250] Starting dynamic subtest: render
[214.733202] Dynamic subtest render: SUCCESS (0.003s)
[214.752028] Starting dynamic subtest: bsd
[214.754491] Dynamic subtest bsd: SUCCESS (0.005s)
[214.765349] Starting dynamic subtest: bsd1
[214.767337] Dynamic subtest bsd1: SUCCESS (0.005s)
[214.775916] Starting dynamic subtest: bsd2
[214.777881] Dynamic subtest bsd2: SUCCESS (0.003s)
[214.785394] Starting dynamic subtest: blt
[214.787303] Dynamic subtest blt: SUCCESS (0.004s)
[214.794803] Starting dynamic subtest: vebox
[214.796663] Dynamic subtest vebox: SUCCESS (0.004s)
[214.798562] Subtest legacy-resubmit-new: SUCCESS (0.036s)
[215.956982] [053/132] (808s left) gem_eio (in-flight-contexts-10ms)
[216.176535] Starting subtest: in-flight-contexts-10ms
[229.929967] Subtest in-flight-contexts-10ms: SUCCESS (13.752s)
[230.150166] [054/132] (794s left) gem_exec_await (wide-all)
[230.379972] Starting subtest: wide-all
[253.771767] Subtest wide-all: SUCCESS (23.390s)
[253.988373] [FACT gem_exec_await (wide-all)] new: kernel.kmod_is_loaded.vgem: true
[253.993025] [055/132] (770s left) kms_psr2_su (page_flip-xrgb8888)
[254.090043] Subtest page_flip-XRGB8888: SKIP (0.000s)
[254.156823] [056/132] (770s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs)
[254.253908] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs
[254.256274] Starting dynamic subtest: pipe-A-eDP-1
[254.276828] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[254.287481] Starting dynamic subtest: pipe-B-eDP-1
[254.307105] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[254.315683] Starting dynamic subtest: pipe-C-eDP-1
[254.334399] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[254.342977] Starting dynamic subtest: pipe-D-eDP-1
[254.362313] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[254.364533] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs: SUCCESS (0.005s)
[256.518378] [057/132] (768s left) kms_chamelium_frames (hdmi-crc-single)
[256.893068] Subtest hdmi-crc-single: SKIP (0.000s)
[257.702412] [058/132] (766s left) kms_hdmi_inject (inject-4k)
[258.069744] Starting subtest: inject-4k
[258.383861] Subtest inject-4k: SUCCESS (0.316s)
[258.917764] [059/132] (765s left) core_sysfs (read-all-entries)
[258.989239] Starting subtest: read-all-entries
[258.991318] Subtest read-all-entries: SUCCESS (0.003s)
[259.119187] [060/132] (765s left) kms_color (deep-color)
[259.212987] Starting subtest: deep-color
[260.255608] Starting dynamic subtest: pipe-A-eDP-1-gamma
[260.961825] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.705s)
[260.972020] Starting dynamic subtest: pipe-A-eDP-1-degamma
[262.460627] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.501s)
[262.469787] Starting dynamic subtest: pipe-A-eDP-1-ctm
[263.877448] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.414s)
[264.650950] Starting dynamic subtest: pipe-B-eDP-1-gamma
[265.380982] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.736s)
[265.389859] Starting dynamic subtest: pipe-B-eDP-1-degamma
[266.838075] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.454s)
[266.847476] Starting dynamic subtest: pipe-B-eDP-1-ctm
[268.219036] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.382s)
[269.040033] Starting dynamic subtest: pipe-C-eDP-1-gamma
[269.714459] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.684s)
[269.723341] Starting dynamic subtest: pipe-C-eDP-1-degamma
[271.094146] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.377s)
[271.103958] Starting dynamic subtest: pipe-C-eDP-1-ctm
[272.528787] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.436s)
[273.350066] Starting dynamic subtest: pipe-D-eDP-1-gamma
[274.045202] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.705s)
[274.054158] Starting dynamic subtest: pipe-D-eDP-1-degamma
[275.498496] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.452s)
[275.508622] Starting dynamic subtest: pipe-D-eDP-1-ctm
[276.907690] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.406s)
[276.909687] Subtest deep-color: SUCCESS (17.693s)
[277.822516] [061/132] (746s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[277.923966] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[277.927970] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[278.032737] [062/132] (746s left) gem_exec_create (legacy)
[278.095625] Starting subtest: legacy
[278.097913] Starting dynamic subtest: smem
[280.181779] Dynamic subtest smem: SUCCESS (2.086s)
[280.184339] Subtest legacy: SUCCESS (2.087s)
[280.385919] [063/132] (744s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc)
[280.495804] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc
[280.498092] Starting dynamic subtest: pipe-A-eDP-1
[280.528882] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[280.538582] Starting dynamic subtest: pipe-B-eDP-1
[280.559520] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[280.567479] Starting dynamic subtest: pipe-C-eDP-1
[280.588130] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[280.596319] Starting dynamic subtest: pipe-D-eDP-1
[280.617619] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[280.621957] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.006s)
[282.769236] [064/132] (742s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc)
[282.874655] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc
[282.879171] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[282.998148] [065/132] (741s left) gem_pwrite (basic-self)
[283.064379] Subtest basic-self: SKIP (0.000s)
[283.123411] [066/132] (741s left) kms_ccs (crc-primary-rotation-180-yf-tiled-ccs)
[283.213044] Starting subtest: crc-primary-rotation-180-yf-tiled-ccs
[283.215216] Starting dynamic subtest: pipe-A-eDP-1
[283.237260] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[283.248734] Starting dynamic subtest: pipe-B-eDP-1
[283.269688] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[283.277655] Starting dynamic subtest: pipe-C-eDP-1
[283.298634] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[283.308058] Starting dynamic subtest: pipe-D-eDP-1
[283.329999] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[283.334320] Subtest crc-primary-rotation-180-yf-tiled-ccs: SKIP (0.006s)
[285.435588] [067/132] (739s left) gem_userptr_blits (create-destroy-unsync)
[285.495322] Subtest create-destroy-unsync: SKIP (0.000s)
[285.647813] [068/132] (739s left) kms_plane_cursor (primary)
[285.750826] Starting subtest: primary
[285.754937] Starting dynamic subtest: pipe-A-eDP-1-size-64
[288.323919] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.573s)
[288.349966] Starting dynamic subtest: pipe-A-eDP-1-size-128
[290.926111] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.581s)
[290.949327] Starting dynamic subtest: pipe-A-eDP-1-size-256
[293.503145] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.560s)
[293.819569] Starting dynamic subtest: pipe-B-eDP-1-size-64
[297.107337] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.298s)
[297.131287] Starting dynamic subtest: pipe-B-eDP-1-size-128
[299.664545] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.538s)
[299.689767] Starting dynamic subtest: pipe-B-eDP-1-size-256
[302.243138] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.559s)
[302.563270] Starting dynamic subtest: pipe-C-eDP-1-size-64
[305.409708] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.856s)
[305.435558] Starting dynamic subtest: pipe-C-eDP-1-size-128
[307.564352] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.135s)
[307.592084] Starting dynamic subtest: pipe-C-eDP-1-size-256
[309.732700] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.147s)
[310.051739] Starting dynamic subtest: pipe-D-eDP-1-size-64
[312.953074] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.912s)
[312.979165] Starting dynamic subtest: pipe-D-eDP-1-size-128
[315.175548] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.202s)
[315.202246] Starting dynamic subtest: pipe-D-eDP-1-size-256
[317.364469] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.169s)
[317.673055] Subtest primary: SUCCESS (31.921s)
[318.560259] [069/132] (706s left) syncobj_wait (invalid-reset-bad-pad)
[318.635852] Starting subtest: invalid-reset-bad-pad
[318.638289] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[318.756545] [070/132] (706s left) kms_pm_lpsp (kms-lpsp)
[319.860561] Starting subtest: kms-lpsp
[319.862549] Starting dynamic subtest: pipe-A-eDP-1
[320.016388] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.159s)
[320.315802] Subtest kms-lpsp: SUCCESS (0.459s)
[321.179899] [071/132] (703s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[326.120018] Starting subtest: psr2-overlay-plane-update-continuous-sf
[326.122267] Starting dynamic subtest: pipe-A-eDP-1
[327.879327] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.757s)
[327.889641] Starting dynamic subtest: pipe-B-eDP-1
[329.613591] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.735s)
[329.615880] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (3.497s)
[330.464135] [072/132] (694s left) kms_joiner (basic-force-ultra-joiner)
[330.573843] Starting subtest: basic-force-ultra-joiner
[330.578266] Subtest basic-force-ultra-joiner: SKIP (0.000s)
[330.662639] [073/132] (694s left) syncobj_timeline (device-submit-unordered)
[330.728483] Starting subtest: device-submit-unordered
[330.730690] Subtest device-submit-unordered: SUCCESS (0.000s)
[330.863734] [074/132] (694s left) kms_psr (fbc-psr-primary-blt)
[330.983341] Starting subtest: fbc-psr-primary-blt
[330.985496] Starting dynamic subtest: eDP-1
[330.989710] Dynamic subtest eDP-1: SKIP (0.000s)
[330.994042] Subtest fbc-psr-primary-blt: SKIP (0.001s)
[331.079500] [075/132] (694s left) i915_power (sanity)
[331.143083] Starting subtest: sanity
[335.196489] Subtest sanity: SUCCESS (4.047s)
[336.985387] [076/132] (688s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-gtt)
[337.082535] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-gtt
[337.086630] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[337.212857] [077/132] (688s left) gem_render_copy (yf-tiled-ccs-to-y-tiled-ccs)
[337.273041] Starting subtest: yf-tiled-ccs-to-y-tiled-ccs
[337.277530] Subtest yf-tiled-ccs-to-y-tiled-ccs: SKIP (0.000s)
[337.483256] [078/132] (687s left) perf (enable-disable)
[337.638490] Starting subtest: enable-disable
[337.640896] Starting dynamic subtest: 0-rcs0
[339.901695] Dynamic subtest 0-rcs0: SUCCESS (2.262s)
[339.912070] Starting dynamic subtest: 1-vcs0
[344.365426] Dynamic subtest 1-vcs0: SUCCESS (4.463s)
[344.367939] Subtest enable-disable: SUCCESS (6.727s)
[344.422315] [079/132] (681s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[349.405692] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[349.410362] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[350.287559] [080/132] (675s left) i915_hangman (detector)
[350.361552] Starting subtest: detector
[350.363922] Starting dynamic subtest: rcs0
[361.909363] Dynamic subtest rcs0: SUCCESS (11.545s)
[361.921869] Starting dynamic subtest: bcs0
[373.877574] Dynamic subtest bcs0: SUCCESS (11.965s)
[373.891685] Starting dynamic subtest: vcs0
[384.821522] Dynamic subtest vcs0: SUCCESS (10.940s)
[384.835456] Starting dynamic subtest: vcs1
[395.829229] Dynamic subtest vcs1: SUCCESS (11.005s)
[395.840947] Starting dynamic subtest: vecs0
[407.796431] Dynamic subtest vecs0: SUCCESS (11.964s)
[407.808074] Starting dynamic subtest: ccs0
[418.869317] Dynamic subtest ccs0: SUCCESS (11.070s)
[418.872087] Subtest detector: SUCCESS (68.506s)
[419.049437] [081/132] (606s left) sysfs_preempt_timeout (invalid)
[419.121774] Starting subtest: invalid
[419.124249] Starting dynamic subtest: vecs0
[419.126673] Dynamic subtest vecs0: SUCCESS (0.001s)
[419.149541] Starting dynamic subtest: ccs0
[419.152142] Dynamic subtest ccs0: SUCCESS (0.001s)
[419.171491] Starting dynamic subtest: vcs0
[419.173662] Dynamic subtest vcs0: SUCCESS (0.001s)
[419.191227] Starting dynamic subtest: bcs0
[419.193337] Dynamic subtest bcs0: SUCCESS (0.001s)
[419.211580] Starting dynamic subtest: rcs0
[419.213670] Dynamic subtest rcs0: SUCCESS (0.001s)
[419.233187] Starting dynamic subtest: vcs1
[419.235580] Dynamic subtest vcs1: SUCCESS (0.001s)
[419.237861] Subtest invalid: SUCCESS (0.019s)
[419.313693] [082/132] (606s left) kms_addfb_basic (unused-modifier)
[419.383614] Starting subtest: unused-modifier
[419.385920] Subtest unused-modifier: SUCCESS (0.000s)
[419.557599] [083/132] (606s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite)
[419.669652] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite
[426.408365] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.741s)
[426.556496] [084/132] (599s left) kms_feature_discovery (display)
[426.652733] Starting subtest: display
[426.654923] Subtest display: SUCCESS (0.000s)
[426.758867] [085/132] (599s left) kms_getfb (getfb-handle-closed)
[426.847993] Starting subtest: getfb-handle-closed
[426.850284] Subtest getfb-handle-closed: SUCCESS (0.000s)
[426.967848] [086/132] (598s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt)
[427.066808] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt
[427.070735] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[427.186530] [087/132] (598s left) gem_create (create-size-update)
[427.251489] Starting subtest: create-size-update
[427.253313] Subtest create-size-update: SUCCESS (0.000s)
[427.485913] [088/132] (598s left) gem_userptr_blits (set-cache-level)
[427.555512] Starting subtest: set-cache-level
[427.559854] Subtest set-cache-level: SKIP (0.000s)
[427.711120] [089/132] (598s left) kms_psr (psr2-cursor-blt)
[427.808908] Starting subtest: psr2-cursor-blt
[427.811125] Starting dynamic subtest: eDP-1
[429.495137] Dynamic subtest eDP-1: SUCCESS (1.685s)
[429.497387] Subtest psr2-cursor-blt: SUCCESS (1.686s)
[430.326681] [090/132] (595s left) fbdev (write)
[430.372440] Starting subtest: write
[430.786186] Subtest write: SUCCESS (0.412s)
[430.878886] [091/132] (595s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[430.984456] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[430.988561] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[431.120889] [092/132] (594s left) gem_exec_params (invalid-bsd2-flag-on-vebox)
[431.191424] Starting subtest: invalid-bsd2-flag-on-vebox
[431.193606] Subtest invalid-bsd2-flag-on-vebox: SUCCESS (0.000s)
[431.327975] [093/132] (594s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu)
[431.430919] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu
[431.435156] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[431.557294] [094/132] (594s left) kms_cursor_crc (cursor-onscreen-128x128)
[431.764563] Starting subtest: cursor-onscreen-128x128
[431.769678] Starting dynamic subtest: pipe-A-eDP-1
[433.875914] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.107s)
[433.906959] Starting dynamic subtest: pipe-D-eDP-1
[436.703947] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.804s)
[436.706150] Subtest cursor-onscreen-128x128: SUCCESS (4.938s)
[437.588112] [095/132] (588s left) gem_exercise_blt (fast-copy)
[437.648410] Starting subtest: fast-copy
[437.650621] Starting dynamic subtest: linear-smem-smem
[437.671215] Dynamic subtest linear-smem-smem: SUCCESS (0.023s)
[437.681026] Starting dynamic subtest: xmajor-smem-smem
[437.689049] Dynamic subtest xmajor-smem-smem: SUCCESS (0.017s)
[437.698281] Starting dynamic subtest: tile4-smem-smem
[437.705758] Dynamic subtest tile4-smem-smem: SUCCESS (0.016s)
[437.715299] Starting dynamic subtest: tile64-smem-smem
[437.722824] Dynamic subtest tile64-smem-smem: SUCCESS (0.016s)
[437.725086] Subtest fast-copy: SUCCESS (0.075s)
[437.883488] [096/132] (588s left) kms_big_fb (yf-tiled-64bpp-rotate-180)
[437.994672] Starting subtest: yf-tiled-64bpp-rotate-180
[437.998824] Subtest yf-tiled-64bpp-rotate-180: SKIP (0.000s)
[438.130409] [097/132] (588s left) kms_flip (2x-flip-vs-suspend-interruptible)
[438.232916] Starting subtest: 2x-flip-vs-suspend-interruptible
[438.243536] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.010s)
[438.379713] [098/132] (587s left) kms_flip (modeset-vs-vblank-race)
[438.482532] Starting subtest: modeset-vs-vblank-race
[438.484700] Starting dynamic subtest: A-eDP1
[443.186045] Dynamic subtest A-eDP1: SUCCESS (4.701s)
[443.197695] Starting dynamic subtest: B-eDP1
[447.923172] Dynamic subtest B-eDP1: SUCCESS (4.735s)
[447.934952] Starting dynamic subtest: C-eDP1
[452.460531] Dynamic subtest C-eDP1: SUCCESS (4.535s)
[452.479680] Starting dynamic subtest: D-eDP1
[457.101080] Dynamic subtest D-eDP1: SUCCESS (4.638s)
[457.103386] Subtest modeset-vs-vblank-race: SUCCESS (18.621s)
[458.375882] [099/132] (568s left) syncobj_timeline (multi-wait-for-submit-available-unsubmitted-submitted)
[458.445831] Starting subtest: multi-wait-for-submit-available-unsubmitted-submitted
[458.448419] Subtest multi-wait-for-submit-available-unsubmitted-submitted: SUCCESS (0.000s)
[458.581087] [100/132] (567s left) kms_big_fb (y-tiled-16bpp-rotate-90)
[458.698901] Starting subtest: y-tiled-16bpp-rotate-90
[458.703371] Subtest y-tiled-16bpp-rotate-90: SKIP (0.000s)
[458.839599] [101/132] (567s left) kms_frontbuffer_tracking (psr-modesetfrombusy)
[458.959307] Starting subtest: psr-modesetfrombusy
[465.123880] Subtest psr-modesetfrombusy: SUCCESS (6.170s)
[465.308172] [102/132] (561s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[465.372229] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[465.442461] [103/132] (561s left) gem_ctx_persistence (heartbeat-stop)
[465.505188] Starting subtest: heartbeat-stop
[465.508811] Subtest heartbeat-stop: SKIP (0.000s)
[465.646954] [104/132] (560s left) kms_busy (extended-pageflip-modeset-hang-oldfb)
[465.744904] Starting subtest: extended-pageflip-modeset-hang-oldfb
[465.746486] Starting dynamic subtest: pipe-A
[473.655513] Dynamic subtest pipe-A: SUCCESS (7.907s)
[473.665398] Starting dynamic subtest: pipe-D
[482.238846] Dynamic subtest pipe-D: SUCCESS (8.579s)
[482.241178] Subtest extended-pageflip-modeset-hang-oldfb: SUCCESS (16.491s)
[483.112410] [105/132] (543s left) kms_flip (dpms-vs-vblank-race)
[483.221262] Starting subtest: dpms-vs-vblank-race
[483.223420] Starting dynamic subtest: A-eDP1
[488.154372] Dynamic subtest A-eDP1: SUCCESS (4.932s)
[488.164626] Starting dynamic subtest: B-eDP1
[492.784063] Dynamic subtest B-eDP1: SUCCESS (4.625s)
[492.796282] Starting dynamic subtest: C-eDP1
[497.389459] Dynamic subtest C-eDP1: SUCCESS (4.607s)
[497.400063] Starting dynamic subtest: D-eDP1
[502.045761] Dynamic subtest D-eDP1: SUCCESS (4.652s)
[502.047770] Subtest dpms-vs-vblank-race: SUCCESS (18.824s)
[502.951328] [106/132] (523s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[503.051705] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[503.056075] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[503.182401] [107/132] (523s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[503.281333] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[503.285489] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[503.424987] [108/132] (523s left) kms_frontbuffer_tracking (fbc-1p-shrfb-fliptrack-mmap-gtt)
[503.531278] Starting subtest: fbc-1p-shrfb-fliptrack-mmap-gtt
[503.535608] Subtest fbc-1p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[503.650371] [109/132] (523s left) syncobj_timeline (wait-any-complex)
[503.720774] Starting subtest: wait-any-complex
[503.781043] Subtest wait-any-complex: SUCCESS (0.062s)
[503.882141] [110/132] (522s left) kms_ccs (bad-rotation-90-4-tiled-mtl-mc-ccs)
[503.981355] Starting subtest: bad-rotation-90-4-tiled-mtl-mc-ccs
[503.982883] Starting dynamic subtest: pipe-A-eDP-1
[504.396075] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.416s)
[504.407463] Starting dynamic subtest: pipe-B-eDP-1
[504.424849] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.004s)
[504.434819] Starting dynamic subtest: pipe-C-eDP-1
[504.450541] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.005s)
[504.459225] Starting dynamic subtest: pipe-D-eDP-1
[504.474483] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.004s)
[504.476160] Subtest bad-rotation-90-4-tiled-mtl-mc-ccs: SUCCESS (0.434s)
[506.724151] [111/132] (520s left) api_intel_allocator (fork-simple-once)
[506.788640] Starting subtest: fork-simple-once
[506.802641] Subtest fork-simple-once: SUCCESS (0.015s)
[506.958949] [112/132] (519s left) i915_pm_rc6_residency (rc6-idle)
[507.027702] Starting subtest: rc6-idle
[507.054352] Starting dynamic subtest: gt0-rcs0
[531.261883] Dynamic subtest gt0-rcs0: SUCCESS (24.207s)
[531.270973] Starting dynamic subtest: gt0-bcs0
[555.526072] Dynamic subtest gt0-bcs0: SUCCESS (24.261s)
[555.537512] Starting dynamic subtest: gt0-ccs0
[579.823754] Dynamic subtest gt0-ccs0: SUCCESS (24.295s)
[579.835412] Starting dynamic subtest: gt1-vcs0
[585.997718] Dynamic subtest gt1-vcs0: SUCCESS (6.169s)
[586.010209] Starting dynamic subtest: gt1-vecs0
[592.170211] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[592.172866] Subtest rc6-idle: SUCCESS (85.142s)
[592.273493] [113/132] (434s left) kms_chamelium_color (ctm-max)
[592.383437] Subtest ctm-max: SKIP (0.000s)
[592.430966] [114/132] (434s left) syncobj_timeline (multi-wait-all-available-signaled)
[592.486068] Starting subtest: multi-wait-all-available-signaled
[592.488225] Subtest multi-wait-all-available-signaled: SUCCESS (0.000s)
[592.616167] [115/132] (434s left) gem_close (many-handles-one-vma)
[592.683826] Starting subtest: many-handles-one-vma
[592.707738] Subtest many-handles-one-vma: SUCCESS (0.024s)
[592.874257] [116/132] (434s left) kms_selftest (drm_plane_helper)
[592.914713] Starting subtest: drm_plane_helper
[592.961033] Starting dynamic subtest: drm_test_check_plane_state
[593.115743] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.153s)
[593.126068] Starting dynamic subtest: drm_test_check_invalid_plane_state
[593.217550] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.102s)
[593.219714] Subtest drm_plane_helper: SUCCESS (0.301s)
[593.431889] [117/132] (433s left) kms_vblank (ts-continuation-dpms-rpm)
[593.531063] Starting subtest: ts-continuation-dpms-rpm
[593.533177] Starting dynamic subtest: pipe-A-eDP-1
[595.992817] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.460s)
[596.007365] Starting dynamic subtest: pipe-D-eDP-1
[598.239606] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.246s)
[598.241954] Subtest ts-continuation-dpms-rpm: SUCCESS (4.710s)
[599.062407] [118/132] (428s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[599.180473] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[599.848728] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.670s)
[600.080364] [119/132] (427s left) gem_pxp (verify-pxp-execution-after-suspend-resume)
[600.200510] Starting subtest: verify-pxp-execution-after-suspend-resume
[607.359506] Subtest verify-pxp-execution-after-suspend-resume: SUCCESS (7.157s)
[607.518862] [120/132] (419s left) gem_exec_whisper (basic-contexts-priority-all)
[607.602077] Starting subtest: basic-contexts-priority-all
[629.853818] Subtest basic-contexts-priority-all: SUCCESS (22.249s)
[630.064068] [121/132] (397s left) kms_flip (bo-too-big-interruptible)
[630.165672] Starting subtest: bo-too-big-interruptible
[630.167766] Starting dynamic subtest: A-eDP1
[631.456686] Dynamic subtest A-eDP1: SUCCESS (1.290s)
[631.469248] Starting dynamic subtest: B-eDP1
[632.684581] Dynamic subtest B-eDP1: SUCCESS (1.227s)
[632.697585] Starting dynamic subtest: C-eDP1
[633.939407] Dynamic subtest C-eDP1: SUCCESS (1.254s)
[633.955446] Starting dynamic subtest: D-eDP1
[635.256226] Dynamic subtest D-eDP1: SUCCESS (1.314s)
[635.261404] Subtest bo-too-big-interruptible: SUCCESS (5.096s)
[636.496566] [122/132] (390s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[636.593295] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[636.597362] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[636.738501] [123/132] (390s left) kms_addfb_basic (invalid-get-prop-any)
[636.793667] Starting subtest: invalid-get-prop-any
[636.795820] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[636.974893] [124/132] (390s left) gem_fenced_exec_thrash (no-spare-fences-busy-interruptible)
[637.048659] Subtest no-spare-fences-busy-interruptible: SKIP (0.000s)
[637.135496] [125/132] (390s left) kms_chamelium_hpd (vga-hpd-fast)
[637.524163] Subtest vga-hpd-fast: SKIP (0.000s)
[638.383751] [126/132] (389s left) kms_async_flips (async-flip-with-page-flip-events-linear)
[638.484084] Starting subtest: async-flip-with-page-flip-events-linear
[638.856141] Starting dynamic subtest: pipe-A-eDP-1
[641.592230] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.736s)
[641.965377] Starting dynamic subtest: pipe-B-eDP-1
[644.720999] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.762s)
[645.085282] Starting dynamic subtest: pipe-C-eDP-1
[647.847638] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.771s)
[648.212220] Starting dynamic subtest: pipe-D-eDP-1
[650.966908] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.763s)
[650.969459] Subtest async-flip-with-page-flip-events-linear: SUCCESS (12.484s)
[652.175928] [127/132] (375s left) gem_exec_fence (invalid-timeline-fence-array)
[652.800521] Starting subtest: invalid-timeline-fence-array
[652.802971] Subtest invalid-timeline-fence-array: SUCCESS (0.005s)
[654.043326] [128/132] (373s left) kms_atomic_interruptible (universal-setplane-cursor)
[654.135607] Starting subtest: universal-setplane-cursor
[654.137952] Starting dynamic subtest: pipe-A-eDP-1
[661.554623] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.419s)
[661.557035] Subtest universal-setplane-cursor: SUCCESS (7.421s)
[662.419427] [129/132] (365s left) prime_vgem (basic-fence-read)
[662.509468] Starting subtest: basic-fence-read
[662.517640] Subtest basic-fence-read: SKIP (0.006s)
[662.674257] [130/132] (365s left) gem_eio (in-flight-suspend)
[662.938793] Starting subtest: in-flight-suspend
[706.238507] Subtest in-flight-suspend: SUCCESS (13.995s)
[706.376109] [131/132] (321s left) gem_exec_reloc (basic-wc-noreloc)
[706.458626] Subtest basic-wc-noreloc: SKIP (0.000s)
[706.547804] [132/132] (321s left) kms_properties (get_properties-sanity-non-atomic)
[706.647446] Starting subtest: get_properties-sanity-non-atomic
[706.649679] Subtest get_properties-sanity-non-atomic: SUCCESS (0.001s)
[706.748298] Closing watchdogs
results: parsing output: 0/ for test: kms_pm_dc
results: parsing output: 1/ for test: gem_exec_schedule
results: parsing output: 2/ for test: gem_exec_params
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: syncobj_timeline
results: parsing output: 5/ for test: kms_cursor_legacy
results: parsing output: 6/ for test: gem_exec_create
results: parsing output: 7/ for test: gem_exec_reloc
results: parsing output: 8/ for test: kms_big_fb
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: perf_pmu
results: parsing output: 11/ for test: gem_ctx_persistence
results: parsing output: 12/ for test: gem_mmap_gtt
results: parsing output: 13/ for test: kms_flip
results: parsing output: 14/ for test: syncobj_wait
results: parsing output: 15/ for test: kms_cursor_crc
results: parsing output: 16/ for test: kms_big_fb
results: parsing output: 17/ for test: kms_plane_scaling
results: parsing output: 18/ for test: kms_psr2_sf
results: parsing output: 19/ for test: kms_frontbuffer_tracking
results: parsing output: 20/ for test: prime_busy
results: parsing output: 21/ for test: kms_cursor_crc
results: parsing output: 22/ for test: gem_exec_balancer
results: parsing output: 23/ for test: syncobj_wait
results: parsing output: 24/ for test: kms_lease
results: parsing output: 25/ for test: template
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: gem_ctx_create
results: parsing output: 28/ for test: gem_reg_read
results: parsing output: 29/ for test: syncobj_timeline
results: parsing output: 30/ for test: syncobj_wait
results: parsing output: 31/ for test: kms_plane
results: parsing output: 32/ for test: kms_dirtyfb
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gen9_exec_parse
results: parsing output: 35/ for test: kms_atomic
results: parsing output: 36/ for test: gem_exec_schedule
results: parsing output: 37/ for test: gem_ctx_shared
results: parsing output: 38/ for test: kms_getfb
results: parsing output: 39/ for test: kms_flip_scaled_crc
results: parsing output: 40/ for test: gem_eio
results: parsing output: 41/ for test: kms_addfb_basic
results: parsing output: 42/ for test: gem_close_race
results: parsing output: 43/ for test: gen9_exec_parse
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_psr
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: api_intel_bb
results: parsing output: 48/ for test: gem_exec_create
results: parsing output: 49/ for test: kms_content_protection
results: parsing output: 50/ for test: kms_vblank
results: parsing output: 51/ for test: gem_spin_batch
results: parsing output: 52/ for test: gem_eio
results: parsing output: 53/ for test: gem_exec_await
results: parsing output: 54/ for test: kms_psr2_su
results: parsing output: 55/ for test: kms_ccs
results: parsing output: 56/ for test: kms_chamelium_frames
results: parsing output: 57/ for test: kms_hdmi_inject
results: parsing output: 58/ for test: core_sysfs
results: parsing output: 59/ for test: kms_color
results: parsing output: 60/ for test: kms_frontbuffer_tracking
results: parsing output: 61/ for test: gem_exec_create
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: gem_pwrite
results: parsing output: 65/ for test: kms_ccs
results: parsing output: 66/ for test: gem_userptr_blits
results: parsing output: 67/ for test: kms_plane_cursor
results: parsing output: 68/ for test: syncobj_wait
results: parsing output: 69/ for test: kms_pm_lpsp
results: parsing output: 70/ for test: kms_psr2_sf
results: parsing output: 71/ for test: kms_joiner
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: kms_psr
results: parsing output: 74/ for test: i915_power
results: parsing output: 75/ for test: kms_frontbuffer_tracking
results: parsing output: 76/ for test: gem_render_copy
results: parsing output: 77/ for test: perf
results: parsing output: 78/ for test: kms_psr2_sf
results: parsing output: 79/ for test: i915_hangman
results: parsing output: 80/ for test: sysfs_preempt_timeout
results: parsing output: 81/ for test: kms_addfb_basic
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_feature_discovery
results: parsing output: 84/ for test: kms_getfb
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: gem_create
results: parsing output: 87/ for test: gem_userptr_blits
results: parsing output: 88/ for test: kms_psr
results: parsing output: 89/ for test: fbdev
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: gem_exec_params
results: parsing output: 92/ for test: kms_frontbuffer_tracking
results: parsing output: 93/ for test: kms_cursor_crc
results: parsing output: 94/ for test: gem_exercise_blt
results: parsing output: 95/ for test: kms_big_fb
results: parsing output: 96/ for test: kms_flip
results: parsing output: 97/ for test: kms_flip
results: parsing output: 98/ for test: syncobj_timeline
results: parsing output: 99/ for test: kms_big_fb
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: gem_mmap_gtt
results: parsing output: 102/ for test: gem_ctx_persistence
results: parsing output: 103/ for test: kms_busy
results: parsing output: 104/ for test: kms_flip
results: parsing output: 105/ for test: kms_big_fb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: syncobj_timeline
results: parsing output: 109/ for test: kms_ccs
results: parsing output: 110/ for test: api_intel_allocator
results: parsing output: 111/ for test: i915_pm_rc6_residency
results: parsing output: 112/ for test: kms_chamelium_color
results: parsing output: 113/ for test: syncobj_timeline
results: parsing output: 114/ for test: gem_close
results: parsing output: 115/ for test: kms_selftest
results: parsing output: 116/ for test: kms_vblank
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: gem_pxp
results: parsing output: 119/ for test: gem_exec_whisper
results: parsing output: 120/ for test: kms_flip
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: kms_addfb_basic
results: parsing output: 123/ for test: gem_fenced_exec_thrash
results: parsing output: 124/ for test: kms_chamelium_hpd
results: parsing output: 125/ for test: kms_async_flips
results: parsing output: 126/ for test: gem_exec_fence
results: parsing output: 127/ for test: kms_atomic_interruptible
results: parsing output: 128/ for test: prime_vgem
results: parsing output: 129/ for test: gem_eio
results: parsing output: 130/ for test: gem_exec_reloc
results: parsing output: 131/ for test: kms_properties
Done.