[55.133392] Initializing watchdogs
[55.133438]   /dev/watchdog0
[55.147194] [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]
[55.152094] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[55.154289] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[55.157639] [001/131] (960s left) kms_psr2_sf (psr2-primary-plane-update-sf-dmg-area)
[60.254808] Starting subtest: psr2-primary-plane-update-sf-dmg-area
[60.257069] Starting dynamic subtest: pipe-A-eDP-1
[66.855506] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.572s)
[66.861729] Starting dynamic subtest: pipe-B-eDP-1
[73.474508] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.604s)
[73.476940] Subtest psr2-primary-plane-update-sf-dmg-area: SUCCESS (13.178s)
[74.340956] [002/131] (941s left) kms_flip (flip-vs-suspend-interruptible)
[74.440841] Starting subtest: flip-vs-suspend-interruptible
[74.443211] Starting dynamic subtest: A-eDP1
[123.588109] Dynamic subtest A-eDP1: SUCCESS (4.546s)
[123.595663] Starting dynamic subtest: B-eDP1
[172.570253] Dynamic subtest B-eDP1: SUCCESS (4.310s)
[172.578005] Starting dynamic subtest: C-eDP1
[221.588475] Dynamic subtest C-eDP1: SUCCESS (4.289s)
[221.596358] Starting dynamic subtest: D-eDP1
[270.576138] Dynamic subtest D-eDP1: SUCCESS (4.290s)
[270.597215] Subtest flip-vs-suspend-interruptible: SUCCESS (17.444s)
[271.724859] [003/131] (743s left) kms_vrr (flip-dpms)
[271.828957] Starting subtest: flip-dpms
[271.836973] Subtest flip-dpms: SKIP (0.000s)
[271.897342] [004/131] (743s left) kms_psr2_su (page_flip-p010)
[271.952131] Subtest page_flip-P010: SKIP (0.000s)
[272.008764] [005/131] (743s left) i915_pm_freq_api (freq-reset)
[272.072767] Starting subtest: freq-reset
[272.075772] Starting dynamic subtest: gt0
[272.269143] Dynamic subtest gt0: SUCCESS (0.192s)
[272.276462] Starting dynamic subtest: gt1
[272.419721] Dynamic subtest gt1: SUCCESS (0.149s)
[272.422314] Subtest freq-reset: SUCCESS (0.342s)
[272.492203] [006/131] (743s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[272.598596] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[280.020433] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.366s)
[280.164590] [007/131] (735s left) gem_exec_fence (syncobj-timeline-repeat)
[280.721175] Starting subtest: syncobj-timeline-repeat
[280.939440] Subtest syncobj-timeline-repeat: SUCCESS (0.217s)
[282.533796] [FACT gem_exec_fence (syncobj-timeline-repeat)] new: kernel.kmod_is_loaded.vgem: true
[282.537312] [008/131] (733s left) vgem_basic (unload)
[282.572669] Starting subtest: unload
[311.779249] Subtest unload: SUCCESS (29.139s)
[311.876297] [009/131] (704s left) gem_exec_async (forked-writes)
[311.936948] Starting subtest: forked-writes
[311.939760] Starting dynamic subtest: rcs0
[311.973665] Dynamic subtest rcs0: SUCCESS (0.036s)
[311.983118] Starting dynamic subtest: bcs0
[311.999634] Dynamic subtest bcs0: SUCCESS (0.026s)
[312.008691] Starting dynamic subtest: vcs0
[312.019232] Dynamic subtest vcs0: SUCCESS (0.020s)
[312.027515] Starting dynamic subtest: vcs1
[312.043931] Dynamic subtest vcs1: SUCCESS (0.024s)
[312.053089] Starting dynamic subtest: vecs0
[312.065851] Dynamic subtest vecs0: SUCCESS (0.021s)
[312.076482] Starting dynamic subtest: ccs0
[312.087294] Dynamic subtest ccs0: SUCCESS (0.022s)
[312.090125] Subtest forked-writes: SUCCESS (0.150s)
[312.335345] [010/131] (703s left) i915_pm_rpm (gem-pread)
[314.590738] Starting subtest: gem-pread
[316.615644] Subtest gem-pread: SUCCESS (2.021s)
[316.733647] [011/131] (699s left) i915_drm_fdinfo (most-busy-check-all)
[316.771091] Starting subtest: most-busy-check-all
[316.773847] Starting dynamic subtest: rcs0
[316.778955] Dynamic subtest rcs0: SKIP (0.004s)
[316.788098] Starting dynamic subtest: bcs0
[316.792450] Dynamic subtest bcs0: SKIP (0.000s)
[316.800458] Starting dynamic subtest: vcs0
[316.806002] Dynamic subtest vcs0: SKIP (0.000s)
[316.813578] Starting dynamic subtest: vcs1
[316.819473] Dynamic subtest vcs1: SKIP (0.000s)
[316.827490] Starting dynamic subtest: vecs0
[316.833211] Dynamic subtest vecs0: SKIP (0.000s)
[316.841263] Starting dynamic subtest: ccs0
[316.846316] Dynamic subtest ccs0: SKIP (0.000s)
[316.851957] Subtest most-busy-check-all: SKIP (0.007s)
[316.889780] [012/131] (699s left) kms_cursor_legacy (single-bo)
[317.050451] Starting subtest: single-bo
[317.052682] Starting dynamic subtest: pipe-A
[322.435864] Dynamic subtest pipe-A: SUCCESS (5.379s)
[322.446338] Starting dynamic subtest: pipe-B
[327.819731] Dynamic subtest pipe-B: SUCCESS (5.380s)
[327.827967] Starting dynamic subtest: pipe-C
[333.207160] Dynamic subtest pipe-C: SUCCESS (5.381s)
[333.218129] Starting dynamic subtest: pipe-D
[338.586006] Dynamic subtest pipe-D: SUCCESS (5.380s)
[338.596213] Starting dynamic subtest: all-pipes
[343.972544] Dynamic subtest all-pipes: SUCCESS (5.386s)
[343.974895] Subtest single-bo: SUCCESS (26.912s)
[344.115455] [013/131] (671s left) syncobj_timeline (wait-all-for-submit-snapshot)
[344.164049] Starting subtest: wait-all-for-submit-snapshot
[344.244251] Subtest wait-all-for-submit-snapshot: SUCCESS (0.082s)
[344.317939] [014/131] (671s left) kms_writeback (writeback-invalid-parameters)
[344.416806] Subtest writeback-invalid-parameters: SKIP (0.000s)
[344.461640] [015/131] (671s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[344.514010] Subtest writes-after-reads: SKIP (0.000s)
[344.588708] [016/131] (671s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[344.672234] Starting subtest: primary-4-tiled-reflect-x-180
[352.053612] Subtest primary-4-tiled-reflect-x-180: SUCCESS (7.381s)
[353.229463] [017/131] (663s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-mmap-cpu)
[353.340398] Starting subtest: psr-1p-primscrn-pri-indfb-draw-mmap-cpu
[360.312388] Subtest psr-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (6.974s)
[360.415164] [018/131] (655s left) kms_big_fb (4-tiled-32bpp-rotate-270)
[360.528441] Starting subtest: 4-tiled-32bpp-rotate-270
[360.548413] Subtest 4-tiled-32bpp-rotate-270: SKIP (0.019s)
[360.680752] [019/131] (655s left) kms_flip (2x-flip-vs-panning)
[360.782044] Starting subtest: 2x-flip-vs-panning
[360.793167] Subtest 2x-flip-vs-panning: SKIP (0.010s)
[360.907018] [020/131] (655s left) kms_psr2_sf (pr-cursor-plane-move-continuous-exceed-sf)
[365.901218] Starting subtest: pr-cursor-plane-move-continuous-exceed-sf
[365.906699] Subtest pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.002s)
[366.751708] [021/131] (649s left) syncobj_timeline (etime-single-wait-for-submit-unsubmitted)
[366.816398] Starting subtest: etime-single-wait-for-submit-unsubmitted
[366.919735] Subtest etime-single-wait-for-submit-unsubmitted: SUCCESS (0.103s)
[366.999386] [022/131] (649s left) sysfs_heartbeat_interval (precise)
[367.055887] Starting subtest: precise
[367.058270] Starting dynamic subtest: vecs0
[374.479444] Dynamic subtest vecs0: SUCCESS (7.418s)
[374.490773] Starting dynamic subtest: ccs0
[375.687203] Dynamic subtest ccs0: SUCCESS (1.208s)
[375.698188] Starting dynamic subtest: vcs0
[383.430415] Dynamic subtest vcs0: SUCCESS (7.740s)
[383.442129] Starting dynamic subtest: bcs0
[384.583819] Dynamic subtest bcs0: SUCCESS (1.151s)
[384.596322] Starting dynamic subtest: rcs0
[385.693898] Dynamic subtest rcs0: SUCCESS (1.106s)
[385.704935] Starting dynamic subtest: vcs1
[393.506394] Dynamic subtest vcs1: SUCCESS (7.810s)
[393.509415] Subtest precise: SUCCESS (26.442s)
[393.584911] [023/131] (622s left) kms_big_fb (linear-8bpp-rotate-180)
[393.690527] Starting subtest: linear-8bpp-rotate-180
[395.198328] Subtest linear-8bpp-rotate-180: FAIL (0.832s)
[395.238342] [024/131] (621s left) kms_cursor_crc (cursor-onscreen-256x256)
[395.469788] Starting subtest: cursor-onscreen-256x256
[395.474809] Starting dynamic subtest: pipe-A-eDP-1
[397.561630] Dynamic subtest pipe-A-eDP-1: FAIL (1.454s)
[397.571572] Starting dynamic subtest: pipe-D-eDP-1
[401.501652] Dynamic subtest pipe-D-eDP-1: FAIL (4.003s)
[401.503698] Subtest cursor-onscreen-256x256: FAIL (5.488s)
[402.199887] [025/131] (614s left) gem_mmap_wc (read-write-distinct)
[402.261572] Subtest read-write-distinct: SKIP (0.000s)
[402.337647] [026/131] (614s left) kms_psr (fbc-psr2-suspend)
[402.432208] Starting subtest: fbc-psr2-suspend
[402.435162] Starting dynamic subtest: eDP-1
[402.440237] Dynamic subtest eDP-1: SKIP (0.000s)
[402.445440] Subtest fbc-psr2-suspend: SKIP (0.001s)
[402.502242] [027/131] (614s left) kms_frontbuffer_tracking (fbc-suspend)
[402.599652] Starting subtest: fbc-suspend
[439.837809] Subtest fbc-suspend: SUCCESS (7.136s)
[441.037280] [028/131] (575s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-pwrite)
[441.163623] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-pwrite
[448.116082] Subtest fbcpsr-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (6.884s)
[448.252685] [029/131] (568s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[448.371496] Starting subtest: cursorA-vs-flipB-legacy
[448.376157] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[448.513141] [030/131] (568s left) syncobj_wait (invalid-single-wait-unsubmitted)
[448.574059] Starting subtest: invalid-single-wait-unsubmitted
[448.576023] Subtest invalid-single-wait-unsubmitted: SUCCESS (0.000s)
[448.653637] [031/131] (568s left) gem_exec_schedule (u-submit-late-slice)
[448.728570] Starting subtest: u-submit-late-slice
[448.730870] Starting dynamic subtest: rcs0
[448.756823] Dynamic subtest rcs0: SUCCESS (0.037s)
[448.769783] Starting dynamic subtest: bcs0
[448.784126] Dynamic subtest bcs0: SUCCESS (0.026s)
[448.792612] Starting dynamic subtest: vcs0
[448.817127] Dynamic subtest vcs0: SUCCESS (0.032s)
[448.825920] Starting dynamic subtest: vcs1
[448.847147] Dynamic subtest vcs1: SUCCESS (0.029s)
[448.856105] Starting dynamic subtest: vecs0
[448.877862] Dynamic subtest vecs0: SUCCESS (0.030s)
[448.886718] Starting dynamic subtest: ccs0
[448.907223] Dynamic subtest ccs0: SUCCESS (0.029s)
[448.909537] Subtest u-submit-late-slice: SUCCESS (0.187s)
[450.606983] [032/131] (566s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs-cc)
[450.700006] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs-cc
[450.702136] Starting dynamic subtest: pipe-A-eDP-1
[450.725190] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[450.735641] Starting dynamic subtest: pipe-B-eDP-1
[450.756980] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[450.765686] Starting dynamic subtest: pipe-C-eDP-1
[450.787043] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[450.796577] Starting dynamic subtest: pipe-D-eDP-1
[450.818653] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[450.822817] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s)
[452.933133] [033/131] (563s left) gem_exec_params (invalid-ring2)
[452.990444] Starting subtest: invalid-ring2
[452.992902] Subtest invalid-ring2: SUCCESS (0.000s)
[453.108268] [034/131] (563s left) kms_chamelium_edid (dp-edid-stress-resolution-4k)
[453.505872] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s)
[454.338849] [035/131] (562s left) gem_exec_nop (basic-parallel)
[454.404269] Starting subtest: basic-parallel
[470.431001] Subtest basic-parallel: SUCCESS (16.017s)
[470.625854] [036/131] (546s left) kms_plane (plane-panning-top-left)
[470.715814] Starting subtest: plane-panning-top-left
[470.718120] Starting dynamic subtest: pipe-A
[472.419374] Dynamic subtest pipe-A: SUCCESS (1.702s)
[472.432513] Starting dynamic subtest: pipe-B
[474.665490] Dynamic subtest pipe-B: SUCCESS (2.248s)
[474.668020] Subtest plane-panning-top-left: SUCCESS (3.952s)
[475.473767] [037/131] (541s left) gem_exec_reloc (basic-wc-read-noreloc)
[475.542580] Subtest basic-wc-read-noreloc: SKIP (0.000s)
[475.616190] [038/131] (541s left) gem_exec_schedule (submit-early-slice)
[475.692200] Starting subtest: submit-early-slice
[475.694519] Starting dynamic subtest: rcs0
[475.720145] Dynamic subtest rcs0: SUCCESS (0.038s)
[475.731016] Starting dynamic subtest: bcs0
[475.754949] Dynamic subtest bcs0: SUCCESS (0.034s)
[475.764573] Starting dynamic subtest: vcs0
[475.791256] Dynamic subtest vcs0: SUCCESS (0.036s)
[475.799156] Starting dynamic subtest: vcs1
[475.830523] Dynamic subtest vcs1: SUCCESS (0.039s)
[475.840365] Starting dynamic subtest: vecs0
[475.862848] Dynamic subtest vecs0: SUCCESS (0.032s)
[475.872078] Starting dynamic subtest: ccs0
[475.897044] Dynamic subtest ccs0: SUCCESS (0.034s)
[475.899335] Subtest submit-early-slice: SUCCESS (0.215s)
[477.580640] [039/131] (539s left) gem_pxp (reject-modify-context-protection-off-1)
[477.706191] Starting subtest: reject-modify-context-protection-off-1
[477.708650] Subtest reject-modify-context-protection-off-1: SUCCESS (0.002s)
[477.818388] [040/131] (539s left) gem_exec_params (secure-non-master)
[477.881159] Starting subtest: secure-non-master
[477.885761] Subtest secure-non-master: SKIP (0.000s)
[477.997151] [041/131] (539s left) kms_getfb (getfb2-handle-zero)
[478.096718] Starting subtest: getfb2-handle-zero
[478.099003] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[478.206696] [042/131] (539s left) kms_big_fb (yf-tiled-32bpp-rotate-180)
[478.308469] Starting subtest: yf-tiled-32bpp-rotate-180
[478.312975] Subtest yf-tiled-32bpp-rotate-180: SKIP (0.000s)
[478.419814] [043/131] (538s left) core_sysfs (read-all-entries)
[478.480035] Starting subtest: read-all-entries
[478.484756] Subtest read-all-entries: SUCCESS (0.004s)
[478.600539] [044/131] (538s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[478.693336] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[479.758001] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.066s)
[479.888531] [045/131] (537s left) gem_ccs (block-multicopy-inplace)
[479.938776] Starting subtest: block-multicopy-inplace
[479.943260] Subtest block-multicopy-inplace: SKIP (0.000s)
[480.034914] [046/131] (537s left) perf_pmu (invalid-init)
[480.070307] Starting subtest: invalid-init
[480.072295] Subtest invalid-init: SUCCESS (0.000s)
[480.171947] [047/131] (537s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-blt)
[480.265639] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-blt
[486.931774] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (6.671s)
[487.067603] [048/131] (530s left) kms_psr (psr2-sprite-plane-onoff)
[487.169625] Starting subtest: psr2-sprite-plane-onoff
[487.171851] Starting dynamic subtest: eDP-1
[488.953282] Dynamic subtest eDP-1: SUCCESS (1.785s)
[488.955550] Subtest psr2-sprite-plane-onoff: SUCCESS (1.786s)
[489.805194] [049/131] (527s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-wc)
[489.910850] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-wc
[489.914983] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[490.001390] [050/131] (527s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[490.091045] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[490.093229] Starting dynamic subtest: pipe-A-default-mode
[490.397813] Dynamic subtest pipe-A-default-mode: SKIP (0.296s)
[490.402617] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.297s)
[491.233441] [051/131] (526s left) kms_pm_backlight (fade-with-dpms)
[491.329255] Starting subtest: fade-with-dpms
[493.509689] Starting dynamic subtest: eDP-1
[496.194845] Dynamic subtest eDP-1: SUCCESS (2.684s)
[496.197260] Subtest fade-with-dpms: SUCCESS (4.865s)
[496.986967] [052/131] (520s left) kms_lease (lease-invalid-connector)
[497.081838] Starting subtest: lease-invalid-connector
[497.084080] Starting dynamic subtest: pipe-A-eDP-1
[497.086357] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[497.095373] Starting dynamic subtest: pipe-B-eDP-1
[497.097222] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[497.105462] Starting dynamic subtest: pipe-C-eDP-1
[497.107599] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[497.115760] Starting dynamic subtest: pipe-D-eDP-1
[497.117569] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[497.119371] Subtest lease-invalid-connector: SUCCESS (0.004s)
[497.156035] [053/131] (520s left) gem_ctx_persistence (engines-queued)
[497.209933] Starting subtest: engines-queued
[497.212246] Starting dynamic subtest: rcs0
[497.305251] Dynamic subtest rcs0: SUCCESS (0.095s)
[497.315175] Starting dynamic subtest: bcs0
[497.328020] Dynamic subtest bcs0: SUCCESS (0.021s)
[497.335868] Starting dynamic subtest: vcs0
[498.494027] Dynamic subtest vcs0: SUCCESS (1.163s)
[498.510813] Starting dynamic subtest: vcs1
[500.475547] Dynamic subtest vcs1: SUCCESS (1.978s)
[500.487222] Starting dynamic subtest: vecs0
[502.457781] Dynamic subtest vecs0: SUCCESS (1.980s)
[502.469218] Starting dynamic subtest: ccs0
[502.664547] Dynamic subtest ccs0: SUCCESS (0.206s)
[502.666905] Subtest engines-queued: SUCCESS (5.455s)
[502.811220] [054/131] (514s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[502.942068] Starting subtest: basic-flip-after-cursor-varying-size
[503.878878] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.937s)
[504.708948] [055/131] (513s left) kms_ccs (ccs-on-another-bo-yf-tiled-ccs)
[504.806483] Starting subtest: ccs-on-another-bo-yf-tiled-ccs
[504.808775] Starting dynamic subtest: pipe-A-eDP-1
[504.830755] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[504.840908] Starting dynamic subtest: pipe-B-eDP-1
[504.862192] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[504.869969] Starting dynamic subtest: pipe-C-eDP-1
[504.891543] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[504.899176] Starting dynamic subtest: pipe-D-eDP-1
[504.921375] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[504.925810] Subtest ccs-on-another-bo-yf-tiled-ccs: SKIP (0.004s)
[507.054820] [056/131] (510s left) kms_cursor_crc (cursor-sliding-32x10)
[507.215425] Starting subtest: cursor-sliding-32x10
[507.256897] Subtest cursor-sliding-32x10: SKIP (0.037s)
[507.534208] [057/131] (510s left) kms_pipe_crc_basic (read-crc-frame-sequence)
[507.624935] Starting subtest: read-crc-frame-sequence
[507.627230] Starting dynamic subtest: pipe-A-eDP-1
[508.305909] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.679s)
[508.314185] Starting dynamic subtest: pipe-B-eDP-1
[509.661507] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.351s)
[509.670149] Starting dynamic subtest: pipe-C-eDP-1
[510.998443] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.338s)
[511.007578] Starting dynamic subtest: pipe-D-eDP-1
[512.323916] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.324s)
[512.326311] Subtest read-crc-frame-sequence: SUCCESS (4.698s)
[513.167573] [058/131] (504s left) gem_spin_batch (spin-each)
[513.223668] Starting subtest: spin-each
[516.499675] Subtest spin-each: SUCCESS (3.266s)
[518.532566] [059/131] (499s left) gem_mmap_gtt (big-bo)
[518.586395] Subtest big-bo: SKIP (0.000s)
[518.646351] [060/131] (499s left) kms_psr (psr2-no-drrs)
[518.746034] Starting subtest: psr2-no-drrs
[518.748297] Starting dynamic subtest: eDP-1
[520.317813] Dynamic subtest eDP-1: SUCCESS (1.573s)
[520.320201] Subtest psr2-no-drrs: SUCCESS (1.574s)
[521.106581] [061/131] (496s left) kms_ccs (random-ccs-data-4-tiled-dg2-rc-ccs-cc)
[521.217147] Starting subtest: random-ccs-data-4-tiled-dg2-rc-ccs-cc
[521.219706] Starting dynamic subtest: pipe-A-eDP-1
[521.244797] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[521.254915] Starting dynamic subtest: pipe-B-eDP-1
[521.277790] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[521.285930] Starting dynamic subtest: pipe-C-eDP-1
[521.309202] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[521.316854] Starting dynamic subtest: pipe-D-eDP-1
[521.339824] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[521.343998] Subtest random-ccs-data-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[523.357152] [062/131] (494s left) gen9_exec_parse (batch-invalid-length)
[523.430140] Subtest batch-invalid-length: SKIP (0.000s)
[523.512178] [063/131] (494s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-render)
[523.610782] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-render
[523.615033] Subtest fbc-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[523.720676] [064/131] (494s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[523.821615] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[523.825698] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[523.915096] [065/131] (494s left) kms_color (ctm-blue-to-red)
[524.006559] Starting subtest: ctm-blue-to-red
[524.008925] Starting dynamic subtest: pipe-A-eDP-1
[524.706171] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.696s)
[524.721782] Starting dynamic subtest: pipe-B-eDP-1
[526.054304] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.349s)
[526.064041] Starting dynamic subtest: pipe-C-eDP-1
[527.404157] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.346s)
[527.413932] Starting dynamic subtest: pipe-D-eDP-1
[528.722987] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.322s)
[528.725273] Subtest ctm-blue-to-red: SUCCESS (4.715s)
[529.551421] [066/131] (488s left) gem_exec_reloc (basic-wc-cpu-active)
[529.609030] Subtest basic-wc-cpu-active: SKIP (0.000s)
[529.669843] [067/131] (488s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-wc)
[529.778109] Starting subtest: psr-rgb565-draw-mmap-wc
[536.403125] Subtest psr-rgb565-draw-mmap-wc: SUCCESS (6.630s)
[536.515141] [068/131] (481s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[536.577458] Starting subtest: multi-wait-available-unsubmitted-signaled
[536.579761] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.001s)
[536.710051] [069/131] (481s left) gem_ctx_shared (q-out-order)
[536.769362] Starting subtest: Q-out-order
[536.771753] Starting dynamic subtest: rcs0
[536.873022] Dynamic subtest rcs0: SUCCESS (0.103s)
[536.889802] Starting dynamic subtest: vcs0
[536.960778] Dynamic subtest vcs0: SUCCESS (0.086s)
[536.976626] Starting dynamic subtest: vcs1
[537.046584] Dynamic subtest vcs1: SUCCESS (0.084s)
[537.063903] Starting dynamic subtest: vecs0
[537.118564] Dynamic subtest vecs0: SUCCESS (0.070s)
[537.135555] Starting dynamic subtest: ccs0
[537.202832] Dynamic subtest ccs0: SUCCESS (0.084s)
[537.205417] Subtest Q-out-order: SUCCESS (0.434s)
[538.612929] [070/131] (479s left) kms_addfb_basic (size-max)
[538.704011] Starting subtest: size-max
[538.706317] Subtest size-max: SUCCESS (0.000s)
[538.797642] [071/131] (479s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-gtt)
[538.896976] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-gtt
[538.901154] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[539.000129] [072/131] (479s left) kms_pm_rpm (drm-resources-equal)
[540.634079] Starting subtest: drm-resources-equal
[545.922772] Subtest drm-resources-equal: SUCCESS (5.290s)
[546.013326] [073/131] (472s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted)
[546.069037] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted
[546.177701] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted: SUCCESS (0.109s)
[546.259160] [074/131] (472s left) gem_lmem_evict (dontneed-evict-race)
[546.297286] Subtest dontneed-evict-race: SKIP (0.000s)
[546.338026] [075/131] (472s left) i915_selftest (mock)
[549.889518] Starting subtest: mock
[549.892418] Starting dynamic subtest: sanitycheck
[550.581624] Dynamic subtest sanitycheck: SUCCESS (0.692s)
[550.591177] Starting dynamic subtest: shmem
[551.234474] Dynamic subtest shmem: SUCCESS (0.651s)
[551.244051] Starting dynamic subtest: fence
[558.668068] Dynamic subtest fence: SUCCESS (7.432s)
[558.677635] Starting dynamic subtest: scatterlist
[560.338827] Dynamic subtest scatterlist: SUCCESS (1.669s)
[560.348409] Starting dynamic subtest: syncmap
[563.595607] Dynamic subtest syncmap: SUCCESS (3.255s)
[563.610445] Starting dynamic subtest: uncore
[564.292444] Dynamic subtest uncore: SUCCESS (0.695s)
[564.302209] Starting dynamic subtest: ring
[564.999573] Dynamic subtest ring: SUCCESS (0.705s)
[565.009564] Starting dynamic subtest: engine
[565.645467] Dynamic subtest engine: SUCCESS (0.644s)
[565.654432] Starting dynamic subtest: timelines
[569.419042] Dynamic subtest timelines: SUCCESS (3.771s)
[569.432657] Starting dynamic subtest: requests
[573.101672] Dynamic subtest requests: SUCCESS (3.681s)
[573.109554] Starting dynamic subtest: objects
[573.860438] Dynamic subtest objects: SUCCESS (0.756s)
[573.874579] Starting dynamic subtest: phys
[574.696797] Dynamic subtest phys: SUCCESS (0.834s)
[574.706412] Starting dynamic subtest: dmabuf
[575.503046] Dynamic subtest dmabuf: SUCCESS (0.805s)
[575.512336] Starting dynamic subtest: vma
[580.996718] Dynamic subtest vma: SUCCESS (5.491s)
[581.005646] Starting dynamic subtest: evict
[582.421181] Dynamic subtest evict: SUCCESS (1.422s)
[582.430357] Starting dynamic subtest: gtt
[585.705785] Dynamic subtest gtt: SUCCESS (3.283s)
[585.715220] Starting dynamic subtest: hugepages
[586.632817] Dynamic subtest hugepages: SUCCESS (0.925s)
[586.642297] Starting dynamic subtest: memory_region
[590.655928] Dynamic subtest memory_region: SUCCESS (4.021s)
[590.658818] Subtest mock: SUCCESS (40.765s)
[590.686530] [FACT i915_selftest (mock)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[590.687183] [FACT i915_selftest (mock)] deleted: kernel.kmod_is_loaded.i915: true
[590.690228] [076/131] (427s left) syncobj_timeline (invalid-signal-zero-handles)
[592.099673] Starting subtest: invalid-signal-zero-handles
[592.102047] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[592.148511] [FACT syncobj_timeline (invalid-signal-zero-handles)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[592.150064] [FACT syncobj_timeline (invalid-signal-zero-handles)] new: kernel.kmod_is_loaded.i915: true
[592.153915] [077/131] (426s left) gem_exec_capture (userptr)
[592.230159] Starting subtest: userptr
[593.026141] Subtest userptr: SUCCESS (0.793s)
[593.176088] [078/131] (425s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[593.285809] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[593.289896] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[593.387563] [079/131] (425s left) gem_exec_balancer (parallel-ordering)
[593.645223] Starting subtest: parallel-ordering
[594.557247] Subtest parallel-ordering: SUCCESS (0.911s)
[594.676146] [080/131] (424s left) kms_busy (basic)
[594.762832] Starting subtest: basic
[594.765156] Starting dynamic subtest: flip
[595.402355] Dynamic subtest flip: SUCCESS (0.638s)
[595.411278] Starting dynamic subtest: modeset
[599.019469] Dynamic subtest modeset: SUCCESS (3.615s)
[599.021937] Subtest basic: SUCCESS (4.255s)
[599.822701] [081/131] (418s left) kms_big_fb (y-tiled-16bpp-rotate-0)
[599.939819] Starting subtest: y-tiled-16bpp-rotate-0
[599.944207] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s)
[600.034537] [082/131] (418s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[600.137944] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[601.017205] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SUCCESS (0.881s)
[601.213588] [083/131] (417s left) kms_plane (plane-position-covered)
[601.311353] Starting subtest: plane-position-covered
[601.824416] Starting dynamic subtest: pipe-A-plane-1
[603.095419] Dynamic subtest pipe-A-plane-1: SUCCESS (1.271s)
[603.105253] Starting dynamic subtest: pipe-A-plane-2
[604.293407] Dynamic subtest pipe-A-plane-2: SUCCESS (1.195s)
[604.302373] Starting dynamic subtest: pipe-A-plane-3
[605.525943] Dynamic subtest pipe-A-plane-3: SUCCESS (1.233s)
[605.535298] Starting dynamic subtest: pipe-A-plane-4
[606.740278] Dynamic subtest pipe-A-plane-4: SUCCESS (1.213s)
[606.749281] Starting dynamic subtest: pipe-A-plane-5
[606.779025] Dynamic subtest pipe-A-plane-5: SUCCESS (0.037s)
[607.899358] Starting dynamic subtest: pipe-B-plane-1
[609.223799] Dynamic subtest pipe-B-plane-1: SUCCESS (1.328s)
[609.232686] Starting dynamic subtest: pipe-B-plane-2
[610.509880] Dynamic subtest pipe-B-plane-2: SUCCESS (1.287s)
[610.519133] Starting dynamic subtest: pipe-B-plane-3
[611.740529] Dynamic subtest pipe-B-plane-3: SUCCESS (1.230s)
[611.749621] Starting dynamic subtest: pipe-B-plane-4
[612.964467] Dynamic subtest pipe-B-plane-4: SUCCESS (1.223s)
[612.976379] Starting dynamic subtest: pipe-B-plane-5
[613.009595] Dynamic subtest pipe-B-plane-5: SUCCESS (0.044s)
[613.011685] Subtest plane-position-covered: SUCCESS (11.697s)
[613.766070] [084/131] (405s left) kms_prop_blob (blob-prop-validate)
[613.827367] Starting subtest: blob-prop-validate
[613.829633] Subtest blob-prop-validate: SUCCESS (0.000s)
[613.925671] [085/131] (405s left) kms_psr (pr-cursor-mmap-gtt)
[614.026043] Starting subtest: pr-cursor-mmap-gtt
[614.030463] Subtest pr-cursor-mmap-gtt: SKIP (0.000s)
[614.098796] [086/131] (404s left) gem_ringfill (legacy-basic)
[619.489462] Starting subtest: legacy-basic
[619.492013] Starting dynamic subtest: default
[619.570437] Dynamic subtest default: SUCCESS (0.080s)
[619.582356] Starting dynamic subtest: render
[619.612583] Dynamic subtest render: SUCCESS (0.040s)
[619.623520] Starting dynamic subtest: bsd
[619.655173] Dynamic subtest bsd: SUCCESS (0.040s)
[619.667469] Starting dynamic subtest: bsd1
[619.699138] Dynamic subtest bsd1: SUCCESS (0.041s)
[619.711316] Starting dynamic subtest: bsd2
[619.745035] Dynamic subtest bsd2: SUCCESS (0.043s)
[619.754775] Starting dynamic subtest: blt
[619.790492] Dynamic subtest blt: SUCCESS (0.043s)
[619.802691] Starting dynamic subtest: vebox
[619.834916] Dynamic subtest vebox: SUCCESS (0.042s)
[619.837489] Subtest legacy-basic: SUCCESS (0.348s)
[619.914257] [087/131] (399s left) api_intel_allocator (simple-allocator)
[619.975252] Starting subtest: simple-allocator
[619.977574] Starting dynamic subtest: basic
[619.979878] Dynamic subtest basic: SUCCESS (0.006s)
[619.989670] Starting dynamic subtest: parallel-one
[620.155336] Dynamic subtest parallel-one: SUCCESS (0.174s)
[620.164520] Starting dynamic subtest: print
[620.166368] Dynamic subtest print: SUCCESS (0.000s)
[620.177662] Starting dynamic subtest: reuse
[620.179926] Dynamic subtest reuse: SUCCESS (0.000s)
[620.187891] Starting dynamic subtest: reserve
[620.190096] Dynamic subtest reserve: SUCCESS (0.000s)
[620.197878] Starting dynamic subtest: fork-reopen-allocator
[620.200086] Dynamic subtest fork-reopen-allocator: SUCCESS (0.013s)
[620.202347] Subtest simple-allocator: SUCCESS (0.197s)
[620.300186] [088/131] (398s left) kms_ccs (crc-primary-suspend-y-tiled-ccs)
[620.398443] Starting subtest: crc-primary-suspend-y-tiled-ccs
[620.400710] Starting dynamic subtest: pipe-A-eDP-1
[620.409373] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[620.417986] Starting dynamic subtest: pipe-B-eDP-1
[620.426626] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[620.435176] Starting dynamic subtest: pipe-C-eDP-1
[620.443780] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[620.451654] Starting dynamic subtest: pipe-D-eDP-1
[620.460452] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[620.464932] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.004s)
[622.511672] [089/131] (396s left) gem_mmap_wc (pf-nonblock)
[622.564444] Subtest pf-nonblock: SKIP (0.000s)
[622.629239] [090/131] (396s left) gen9_exec_parse (bb-start-param)
[622.678027] Subtest bb-start-param: SKIP (0.000s)
[622.767122] [091/131] (396s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[622.860291] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[622.864310] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[622.947687] [092/131] (396s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-render)
[623.043042] Starting subtest: fbcpsr-rgb565-draw-render
[629.928542] Subtest fbcpsr-rgb565-draw-render: SUCCESS (6.886s)
[630.103718] [093/131] (389s left) gem_ctx_isolation (dirty-create)
[630.178101] Starting subtest: dirty-create
[630.180522] Starting dynamic subtest: rcs0
[630.604900] Dynamic subtest rcs0: SUCCESS (0.427s)
[630.615807] Starting dynamic subtest: bcs0
[631.059351] Dynamic subtest bcs0: SUCCESS (0.454s)
[631.069482] Starting dynamic subtest: vcs0
[631.530044] Dynamic subtest vcs0: SUCCESS (0.470s)
[631.540565] Starting dynamic subtest: vcs1
[632.924542] Dynamic subtest vcs1: SUCCESS (1.393s)
[632.935546] Starting dynamic subtest: vecs0
[634.982035] Dynamic subtest vecs0: SUCCESS (2.056s)
[634.993353] Starting dynamic subtest: ccs0
[636.936042] Dynamic subtest ccs0: SUCCESS (1.953s)
[636.938444] Subtest dirty-create: SUCCESS (6.757s)
[637.198613] [094/131] (382s left) kms_flip (2x-blocking-absolute-wf_vblank)
[637.293711] Starting subtest: 2x-blocking-absolute-wf_vblank
[637.304890] Subtest 2x-blocking-absolute-wf_vblank: SKIP (0.011s)
[637.413964] [095/131] (381s left) gem_exec_fence (keep-in-fence)
[637.592762] Starting subtest: keep-in-fence
[637.595136] Starting dynamic subtest: rcs0
[637.662282] Dynamic subtest rcs0: SUCCESS (0.070s)
[637.677600] Starting dynamic subtest: bcs0
[637.717333] Dynamic subtest bcs0: SUCCESS (0.053s)
[637.728574] Starting dynamic subtest: vcs0
[639.454068] Dynamic subtest vcs0: SUCCESS (1.732s)
[639.465731] Starting dynamic subtest: vcs1
[641.501467] Dynamic subtest vcs1: SUCCESS (2.044s)
[641.513951] Starting dynamic subtest: vecs0
[643.485679] Dynamic subtest vecs0: SUCCESS (1.982s)
[643.496525] Starting dynamic subtest: ccs0
[643.722116] Dynamic subtest ccs0: SUCCESS (0.236s)
[643.724766] Subtest keep-in-fence: SUCCESS (6.129s)
[645.560270] [096/131] (373s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-32bpp-xtile-downscaling)
[645.657830] Starting subtest: flip-64bpp-xtile-to-32bpp-xtile-downscaling
[645.660219] Starting dynamic subtest: pipe-A-default-mode
[646.775101] Dynamic subtest pipe-A-default-mode: SKIP (1.114s)
[646.779640] Subtest flip-64bpp-xtile-to-32bpp-xtile-downscaling: SKIP (1.116s)
[646.922025] [097/131] (372s left) prime_udl
[647.001131] [098/131] (372s left) fbdev (info)
[647.030023] Starting subtest: info
[647.032156] Subtest info: SUCCESS (0.000s)
[647.093527] [099/131] (372s left) kms_flip (flip-vs-absolute-wf_vblank-interruptible)
[647.184349] Starting subtest: flip-vs-absolute-wf_vblank-interruptible
[647.186614] Starting dynamic subtest: A-eDP1
[656.080934] Dynamic subtest A-eDP1: SUCCESS (8.893s)
[656.090878] Starting dynamic subtest: B-eDP1
[665.007771] Dynamic subtest B-eDP1: SUCCESS (8.926s)
[665.017504] Starting dynamic subtest: C-eDP1
[673.918171] Dynamic subtest C-eDP1: SUCCESS (8.911s)
[673.929433] Starting dynamic subtest: D-eDP1
[682.839636] Dynamic subtest D-eDP1: SUCCESS (8.922s)
[682.845027] Subtest flip-vs-absolute-wf_vblank-interruptible: SUCCESS (35.662s)
[684.061627] [100/131] (335s left) gem_mmap_gtt (bad-object)
[684.129488] Subtest bad-object: SKIP (0.000s)
[684.191840] [101/131] (335s left) gem_exec_schedule (noreorder-corked)
[684.375559] Starting subtest: noreorder-corked
[684.378303] Starting dynamic subtest: rcs0
[685.538291] Dynamic subtest rcs0: SUCCESS (1.164s)
[685.547858] Starting dynamic subtest: vcs0
[687.529111] Dynamic subtest vcs0: SUCCESS (1.987s)
[687.538623] Starting dynamic subtest: vcs1
[689.510999] Dynamic subtest vcs1: SUCCESS (1.980s)
[689.522476] Starting dynamic subtest: vecs0
[691.495783] Dynamic subtest vecs0: SUCCESS (1.983s)
[691.507657] Starting dynamic subtest: ccs0
[692.663668] Dynamic subtest ccs0: SUCCESS (1.168s)
[692.666152] Subtest noreorder-corked: SUCCESS (8.291s)
[692.845218] [102/131] (326s left) gem_exec_flush (basic-wb-prw-default)
[692.906955] Starting subtest: basic-wb-prw-default
[698.371285] Subtest basic-wb-prw-default: SUCCESS (5.466s)
[698.557679] [103/131] (321s left) kms_properties (plane-properties-atomic)
[698.653787] Starting subtest: plane-properties-atomic
[698.655974] Starting dynamic subtest: pipe-A-eDP-1
[699.228118] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.572s)
[699.236384] Starting dynamic subtest: pipe-B-eDP-1
[700.517748] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.286s)
[700.527700] Starting dynamic subtest: pipe-C-eDP-1
[701.739463] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.222s)
[701.749175] Starting dynamic subtest: pipe-D-eDP-1
[702.968265] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.226s)
[702.970492] Subtest plane-properties-atomic: SUCCESS (4.313s)
[703.755375] [104/131] (315s left) kms_invalid_mode (zero-hdisplay)
[703.857972] Starting subtest: zero-hdisplay
[703.860332] Starting dynamic subtest: pipe-A-eDP-1
[704.142037] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.284s)
[704.153324] Starting dynamic subtest: pipe-B-eDP-1
[704.155703] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[704.165711] Starting dynamic subtest: pipe-C-eDP-1
[704.167686] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[704.175401] Starting dynamic subtest: pipe-D-eDP-1
[704.177333] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[704.179269] Subtest zero-hdisplay: SUCCESS (0.289s)
[704.980807] [105/131] (314s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[705.082751] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[705.087107] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[705.173634] [106/131] (314s left) gem_exec_create (forked)
[705.232187] Starting subtest: forked
[705.234503] Starting dynamic subtest: smem
[726.493840] Dynamic subtest smem: SUCCESS (21.259s)
[726.496642] Subtest forked: SUCCESS (21.261s)
[726.683649] [107/131] (293s left) vgem_basic (debugfs)
[726.731622] Starting subtest: debugfs
[726.734009] Subtest debugfs: SUCCESS (0.001s)
[726.788058] [108/131] (293s left) gem_flink_race (flink_close)
[726.818898] Starting subtest: flink_close
[731.912423] Subtest flink_close: SUCCESS (5.092s)
[731.987677] [109/131] (287s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[732.090953] Starting subtest: async-flip-with-page-flip-events-tiled
[732.477055] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[735.254421] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.776s)
[735.263469] Starting dynamic subtest: pipe-A-eDP-1-4
[737.541791] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.285s)
[737.553671] Starting dynamic subtest: pipe-A-eDP-1-4
[739.551156] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.009s)
[739.560329] Starting dynamic subtest: pipe-A-eDP-1-x
[741.841208] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.289s)
[742.218379] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[744.980563] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.767s)
[744.992556] Starting dynamic subtest: pipe-B-eDP-1-4
[747.267334] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.286s)
[747.277300] Starting dynamic subtest: pipe-B-eDP-1-4
[749.276896] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[749.285888] Starting dynamic subtest: pipe-B-eDP-1-x
[751.578806] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.301s)
[751.945364] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[754.699971] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.764s)
[754.712283] Starting dynamic subtest: pipe-C-eDP-1-4
[756.985244] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.284s)
[756.994046] Starting dynamic subtest: pipe-C-eDP-1-4
[758.995919] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[759.007376] Starting dynamic subtest: pipe-C-eDP-1-x
[761.285838] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.289s)
[761.645501] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[764.420721] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.780s)
[764.432554] Starting dynamic subtest: pipe-D-eDP-1-4
[766.707170] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.286s)
[766.718842] Starting dynamic subtest: pipe-D-eDP-1-4
[768.716930] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[768.729583] Starting dynamic subtest: pipe-D-eDP-1-x
[771.007965] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.290s)
[771.010319] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.912s)
[772.203648] [110/131] (247s left) kms_flip (dpms-vs-vblank-race-interruptible)
[772.309392] Starting subtest: dpms-vs-vblank-race-interruptible
[772.311710] Starting dynamic subtest: A-eDP1
[777.276972] Dynamic subtest A-eDP1: SUCCESS (4.963s)
[777.286155] Starting dynamic subtest: B-eDP1
[781.879968] Dynamic subtest B-eDP1: SUCCESS (4.601s)
[781.888985] Starting dynamic subtest: C-eDP1
[786.372659] Dynamic subtest C-eDP1: SUCCESS (4.491s)
[786.381841] Starting dynamic subtest: D-eDP1
[790.791006] Dynamic subtest D-eDP1: SUCCESS (4.415s)
[790.793246] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (18.481s)
[791.662583] [111/131] (228s left) gem_exec_flush (basic-batch-kernel-default-wb)
[791.726088] Starting subtest: basic-batch-kernel-default-wb
[798.597016] Subtest basic-batch-kernel-default-wb: SUCCESS (6.871s)
[798.780565] [112/131] (221s left) kms_ccs (crc-primary-rotation-180-y-tiled-ccs)
[798.881991] Starting subtest: crc-primary-rotation-180-y-tiled-ccs
[798.884243] Starting dynamic subtest: pipe-A-eDP-1
[798.906250] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[798.916516] Starting dynamic subtest: pipe-B-eDP-1
[798.937802] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[798.946346] Starting dynamic subtest: pipe-C-eDP-1
[798.967957] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[798.976396] Starting dynamic subtest: pipe-D-eDP-1
[798.997998] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[799.001924] Subtest crc-primary-rotation-180-y-tiled-ccs: SKIP (0.005s)
[801.023127] [113/131] (219s left) kms_content_protection (dp-mst-lic-type-1)
[801.204116] Starting subtest: dp-mst-lic-type-1
[801.208444] Subtest dp-mst-lic-type-1: SKIP (0.000s)
[801.340098] [114/131] (218s left) kms_pipe_crc_basic (bad-source)
[801.429812] Starting subtest: bad-source
[801.431676] Subtest bad-source: SUCCESS (0.000s)
[801.501851] [115/131] (218s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[801.590022] Starting subtest: fbc-dirtyfb-ioctl
[801.592356] Starting dynamic subtest: A-eDP-1
[804.223462] Dynamic subtest A-eDP-1: SUCCESS (2.631s)
[804.225827] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.632s)
[805.077306] [116/131] (215s left) syncobj_eventfd (binary-wait-before-signal)
[805.134685] Starting subtest: binary-wait-before-signal
[805.164570] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[805.238850] [117/131] (214s left) kms_addfb_basic (invalid-set-prop)
[805.301252] Starting subtest: invalid-set-prop
[805.303720] Subtest invalid-set-prop: SUCCESS (0.000s)
[805.435288] [118/131] (214s left) gem_exec_params (invalid-bsd2-flag-on-render)
[805.482062] Starting subtest: invalid-bsd2-flag-on-render
[805.484309] Subtest invalid-bsd2-flag-on-render: SUCCESS (0.000s)
[805.595982] [119/131] (214s left) gem_exec_whisper (basic-fds-forked-all)
[805.652896] Starting subtest: basic-fds-forked-all
[825.930553] Subtest basic-fds-forked-all: SUCCESS (20.269s)
[826.111354] [120/131] (194s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-indfb-msflip-blt)
[826.214244] Starting subtest: fbcpsr-2p-primscrn-indfb-msflip-blt
[826.218433] Subtest fbcpsr-2p-primscrn-indfb-msflip-blt: SKIP (0.000s)
[826.299787] [121/131] (194s left) perf_pmu (semaphore-wait)
[826.336292] Starting subtest: semaphore-wait
[826.339494] Starting dynamic subtest: rcs0
[826.851225] Dynamic subtest rcs0: SUCCESS (0.514s)
[826.863287] Starting dynamic subtest: bcs0
[827.364017] Dynamic subtest bcs0: SUCCESS (0.510s)
[827.375965] Starting dynamic subtest: vcs0
[827.877488] Dynamic subtest vcs0: SUCCESS (0.511s)
[827.889797] Starting dynamic subtest: vcs1
[828.390304] Dynamic subtest vcs1: SUCCESS (0.511s)
[828.402582] Starting dynamic subtest: vecs0
[828.905179] Dynamic subtest vecs0: SUCCESS (0.511s)
[828.916816] Starting dynamic subtest: ccs0
[829.419156] Dynamic subtest ccs0: SUCCESS (0.512s)
[829.421548] Subtest semaphore-wait: SUCCESS (3.083s)
[830.503409] [122/131] (189s left) gem_pxp (verify-pxp-stale-buf-optout-execution)
[830.630126] Starting subtest: verify-pxp-stale-buf-optout-execution
[831.691515] Subtest verify-pxp-stale-buf-optout-execution: SUCCESS (1.064s)
[831.838045] [123/131] (188s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-render)
[831.949188] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-render
[831.953520] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[832.050881] [124/131] (188s left) syncobj_wait (invalid-signal-bad-pad)
[832.105723] Starting subtest: invalid-signal-bad-pad
[832.107991] Subtest invalid-signal-bad-pad: SUCCESS (0.000s)
[832.211024] [125/131] (188s left) gem_set_tiling_vs_blt (untiled-to-tiled)
[832.277159] Subtest untiled-to-tiled: SKIP (0.000s)
[832.357032] [126/131] (188s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[832.421059] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[832.423403] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[832.535015] [127/131] (188s left) gem_exec_reloc (basic-gtt-wc-active)
[832.599869] Subtest basic-gtt-wc-active: SKIP (0.000s)
[832.686938] [128/131] (187s left) kms_vblank (query-busy)
[832.784469] Starting subtest: query-busy
[832.786459] Starting dynamic subtest: pipe-A-eDP-1
[834.685224] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.895s)
[834.707161] Starting dynamic subtest: pipe-D-eDP-1
[837.234898] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.549s)
[837.237328] Subtest query-busy: SUCCESS (4.448s)
[838.030866] [129/131] (182s left) gem_fenced_exec_thrash (no-spare-fences-interruptible)
[838.085253] Subtest no-spare-fences-interruptible: SKIP (0.000s)
[838.159161] [130/131] (182s left) kms_chamelium_hpd (dp-hpd-storm)
[838.546373] Subtest dp-hpd-storm: SKIP (0.000s)
[839.337664] [131/131] (181s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[839.440022] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[839.444117] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[839.558142] Closing watchdogs
results: parsing output: 0/ for test: kms_psr2_sf
results: parsing output: 1/ for test: kms_flip
results: parsing output: 2/ for test: kms_vrr
results: parsing output: 3/ for test: kms_psr2_su
results: parsing output: 4/ for test: i915_pm_freq_api
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: gem_exec_fence
results: parsing output: 7/ for test: vgem_basic
results: parsing output: 8/ for test: gem_exec_async
results: parsing output: 9/ for test: i915_pm_rpm
results: parsing output: 10/ for test: i915_drm_fdinfo
results: parsing output: 11/ for test: kms_cursor_legacy
results: parsing output: 12/ for test: syncobj_timeline
results: parsing output: 13/ for test: kms_writeback
results: parsing output: 14/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 15/ for test: kms_rotation_crc
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_flip
results: parsing output: 19/ for test: kms_psr2_sf
results: parsing output: 20/ for test: syncobj_timeline
results: parsing output: 21/ for test: sysfs_heartbeat_interval
results: parsing output: 22/ for test: kms_big_fb
results: parsing output: 23/ for test: kms_cursor_crc
results: parsing output: 24/ for test: gem_mmap_wc
results: parsing output: 25/ for test: kms_psr
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_frontbuffer_tracking
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: syncobj_wait
results: parsing output: 30/ for test: gem_exec_schedule
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: gem_exec_params
results: parsing output: 33/ for test: kms_chamelium_edid
results: parsing output: 34/ for test: gem_exec_nop
results: parsing output: 35/ for test: kms_plane
results: parsing output: 36/ for test: gem_exec_reloc
results: parsing output: 37/ for test: gem_exec_schedule
results: parsing output: 38/ for test: gem_pxp
results: parsing output: 39/ for test: gem_exec_params
results: parsing output: 40/ for test: kms_getfb
results: parsing output: 41/ for test: kms_big_fb
results: parsing output: 42/ for test: core_sysfs
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: gem_ccs
results: parsing output: 45/ for test: perf_pmu
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: kms_psr
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_flip_scaled_crc
results: parsing output: 50/ for test: kms_pm_backlight
results: parsing output: 51/ for test: kms_lease
results: parsing output: 52/ for test: gem_ctx_persistence
results: parsing output: 53/ for test: kms_cursor_legacy
results: parsing output: 54/ for test: kms_ccs
results: parsing output: 55/ for test: kms_cursor_crc
results: parsing output: 56/ for test: kms_pipe_crc_basic
results: parsing output: 57/ for test: gem_spin_batch
results: parsing output: 58/ for test: gem_mmap_gtt
results: parsing output: 59/ for test: kms_psr
results: parsing output: 60/ for test: kms_ccs
results: parsing output: 61/ for test: gen9_exec_parse
results: parsing output: 62/ for test: kms_frontbuffer_tracking
results: parsing output: 63/ for test: kms_frontbuffer_tracking
results: parsing output: 64/ for test: kms_color
results: parsing output: 65/ for test: gem_exec_reloc
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: syncobj_timeline
results: parsing output: 68/ for test: gem_ctx_shared
results: parsing output: 69/ for test: kms_addfb_basic
results: parsing output: 70/ for test: kms_frontbuffer_tracking
results: parsing output: 71/ for test: kms_pm_rpm
results: parsing output: 72/ for test: syncobj_timeline
results: parsing output: 73/ for test: gem_lmem_evict
results: parsing output: 74/ for test: i915_selftest
results: parsing output: 75/ for test: syncobj_timeline
results: parsing output: 76/ for test: gem_exec_capture
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_exec_balancer
results: parsing output: 79/ for test: kms_busy
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: kms_big_fb
results: parsing output: 82/ for test: kms_plane
results: parsing output: 83/ for test: kms_prop_blob
results: parsing output: 84/ for test: kms_psr
results: parsing output: 85/ for test: gem_ringfill
results: parsing output: 86/ for test: api_intel_allocator
results: parsing output: 87/ for test: kms_ccs
results: parsing output: 88/ for test: gem_mmap_wc
results: parsing output: 89/ for test: gen9_exec_parse
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_ctx_isolation
results: parsing output: 93/ for test: kms_flip
results: parsing output: 94/ for test: gem_exec_fence
results: parsing output: 95/ for test: kms_flip_scaled_crc
results: parsing output: 96/ for test: prime_udl
results: parsing output: 97/ for test: fbdev
results: parsing output: 98/ for test: kms_flip
results: parsing output: 99/ for test: gem_mmap_gtt
results: parsing output: 100/ for test: gem_exec_schedule
results: parsing output: 101/ for test: gem_exec_flush
results: parsing output: 102/ for test: kms_properties
results: parsing output: 103/ for test: kms_invalid_mode
results: parsing output: 104/ for test: kms_frontbuffer_tracking
results: parsing output: 105/ for test: gem_exec_create
results: parsing output: 106/ for test: vgem_basic
results: parsing output: 107/ for test: gem_flink_race
results: parsing output: 108/ for test: kms_async_flips
results: parsing output: 109/ for test: kms_flip
results: parsing output: 110/ for test: gem_exec_flush
results: parsing output: 111/ for test: kms_ccs
results: parsing output: 112/ for test: kms_content_protection
results: parsing output: 113/ for test: kms_pipe_crc_basic
results: parsing output: 114/ for test: kms_dirtyfb
results: parsing output: 115/ for test: syncobj_eventfd
results: parsing output: 116/ for test: kms_addfb_basic
results: parsing output: 117/ for test: gem_exec_params
results: parsing output: 118/ for test: gem_exec_whisper
results: parsing output: 119/ for test: kms_frontbuffer_tracking
results: parsing output: 120/ for test: perf_pmu
results: parsing output: 121/ for test: gem_pxp
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: syncobj_wait
results: parsing output: 124/ for test: gem_set_tiling_vs_blt
results: parsing output: 125/ for test: syncobj_wait
results: parsing output: 126/ for test: gem_exec_reloc
results: parsing output: 127/ for test: kms_vblank
results: parsing output: 128/ for test: gem_fenced_exec_thrash
results: parsing output: 129/ for test: kms_chamelium_hpd
results: parsing output: 130/ for test: kms_frontbuffer_tracking
Done.