[57.073362] Initializing watchdogs
[57.073583]   /dev/watchdog0
[57.100101] [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]
[57.104560] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.107254] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.110643] [001/130] (960s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[57.231729] Starting subtest: plane-upscale-20x20-with-rotation
[57.233995] Starting dynamic subtest: pipe-A
[57.465454] Dynamic subtest pipe-A: SUCCESS (0.235s)
[57.474530] Starting dynamic subtest: pipe-B
[58.806749] Dynamic subtest pipe-B: SUCCESS (1.342s)
[58.813319] Starting dynamic subtest: pipe-C
[60.139142] Dynamic subtest pipe-C: SUCCESS (1.332s)
[60.145898] Starting dynamic subtest: pipe-D
[61.471946] Dynamic subtest pipe-D: SUCCESS (1.332s)
[61.474355] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.242s)
[62.654872] [002/130] (954s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[62.751175] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[62.755519] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[62.873099] [003/130] (954s left) kms_big_fb (linear-8bpp-rotate-180)
[62.973347] Starting subtest: linear-8bpp-rotate-180
[64.131443] Subtest linear-8bpp-rotate-180: SUCCESS (1.158s)
[64.289446] [004/130] (953s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[64.390929] Starting subtest: blocking-absolute-wf_vblank-interruptible
[64.393371] Starting dynamic subtest: A-eDP1
[73.104020] Dynamic subtest A-eDP1: SUCCESS (8.712s)
[73.113725] Starting dynamic subtest: B-eDP1
[81.845243] Dynamic subtest B-eDP1: SUCCESS (8.741s)
[81.856913] Starting dynamic subtest: C-eDP1
[90.571816] Dynamic subtest C-eDP1: SUCCESS (8.732s)
[90.579398] Starting dynamic subtest: D-eDP1
[99.299739] Dynamic subtest D-eDP1: SUCCESS (8.731s)
[99.302104] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.921s)
[100.531061] [005/130] (917s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[100.633163] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[100.637308] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[100.775768] [006/130] (917s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[100.875566] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[107.397953] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (6.528s)
[107.547117] [007/130] (910s left) gem_ctx_exec (basic-norecovery)
[107.603091] Starting subtest: basic-norecovery
[107.872104] Subtest basic-norecovery: SUCCESS (0.270s)
[108.017181] [008/130] (909s left) kms_psr (psr-primary-page-flip)
[108.111839] Starting subtest: psr-primary-page-flip
[108.114189] Starting dynamic subtest: eDP-1
[109.863983] Dynamic subtest eDP-1: SUCCESS (1.753s)
[109.866319] Subtest psr-primary-page-flip: SUCCESS (1.754s)
[110.740105] [009/130] (907s left) core_getstats
[110.903141] [010/130] (907s left) i915_hangman (detector)
[110.981070] Starting subtest: detector
[110.982942] Starting dynamic subtest: rcs0
[122.866484] Dynamic subtest rcs0: SUCCESS (11.887s)
[122.877147] Starting dynamic subtest: bcs0
[134.898747] Dynamic subtest bcs0: SUCCESS (12.032s)
[134.909628] Starting dynamic subtest: vcs0
[146.866130] Dynamic subtest vcs0: SUCCESS (11.967s)
[146.874884] Starting dynamic subtest: vcs1
[158.834455] Dynamic subtest vcs1: SUCCESS (11.961s)
[158.845876] Starting dynamic subtest: vecs0
[170.801894] Dynamic subtest vecs0: SUCCESS (11.962s)
[170.812834] Starting dynamic subtest: ccs0
[179.826839] Dynamic subtest ccs0: SUCCESS (9.021s)
[179.829264] Subtest detector: SUCCESS (68.834s)
[179.916946] [011/130] (838s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[180.021640] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[180.023436] Starting dynamic subtest: pipe-A-eDP-1
[182.089361] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.068s)
[182.099059] Starting dynamic subtest: pipe-B-eDP-1
[184.190675] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.100s)
[184.199087] Starting dynamic subtest: pipe-C-eDP-1
[186.269874] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.076s)
[186.278409] Starting dynamic subtest: pipe-D-eDP-1
[188.293881] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.023s)
[188.296185] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.271s)
[189.476853] [012/130] (828s left) gem_lmem_swapping (parallel-random-engines)
[189.519402] Subtest parallel-random-engines: SKIP (0.000s)
[189.554141] [013/130] (828s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[189.647213] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[189.651152] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[189.760049] [014/130] (828s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[189.863396] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[189.867317] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[189.984322] [015/130] (828s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[190.076999] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[190.081200] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[190.198291] [016/130] (827s left) gem_ctx_persistence (legacy-engines-hostile)
[190.256761] Starting subtest: legacy-engines-hostile
[190.259075] Starting dynamic subtest: render
[190.406698] Dynamic subtest render: SUCCESS (0.149s)
[190.417447] Starting dynamic subtest: bsd1
[190.527599] Dynamic subtest bsd1: SUCCESS (0.119s)
[190.537960] Starting dynamic subtest: bsd2
[190.647695] Dynamic subtest bsd2: SUCCESS (0.118s)
[190.657950] Starting dynamic subtest: blt
[190.768839] Dynamic subtest blt: SUCCESS (0.120s)
[190.779028] Starting dynamic subtest: vebox
[190.882121] Dynamic subtest vebox: SUCCESS (0.112s)
[190.884323] Subtest legacy-engines-hostile: SUCCESS (0.626s)
[190.959988] [017/130] (827s left) prime_vgem (shrink)
[191.078794] Starting subtest: shrink
[191.092144] Subtest shrink: SUCCESS (0.012s)
[191.223689] [FACT prime_vgem (shrink)] new: kernel.kmod_is_loaded.vgem: true
[191.226494] [018/130] (827s left) gem_exec_schedule (preempt-queue-chain)
[191.302427] Subtest preempt-queue-chain: SKIP (0.000s)
[191.604395] [019/130] (826s left) kms_flip (flip-vs-panning-vs-hang)
[191.699807] Starting subtest: flip-vs-panning-vs-hang
[191.702135] Starting dynamic subtest: A-eDP1
[232.379372] Dynamic subtest A-eDP1: SUCCESS (40.674s)
[232.390200] Starting dynamic subtest: D-eDP1
[272.376902] Dynamic subtest D-eDP1: SUCCESS (39.992s)
[272.379064] Subtest flip-vs-panning-vs-hang: SUCCESS (80.670s)
[273.569433] [020/130] (744s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[273.799236] Starting subtest: cursor-rapid-movement-256x256
[273.804674] Starting dynamic subtest: pipe-A-eDP-1
[274.342579] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.539s)
[274.366452] Starting dynamic subtest: pipe-D-eDP-1
[275.571851] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.211s)
[275.574153] Subtest cursor-rapid-movement-256x256: SUCCESS (1.771s)
[276.429854] [021/130] (741s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[276.532253] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[276.536236] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[276.665165] [022/130] (741s left) gem_exec_balancer (sliced)
[276.731732] Starting subtest: sliced
[276.736257] Subtest sliced: SKIP (0.000s)
[277.022097] [023/130] (741s left) kms_psr (fbc-psr-suspend)
[277.115282] Starting subtest: fbc-psr-suspend
[277.117210] Starting dynamic subtest: eDP-1
[277.121297] Dynamic subtest eDP-1: SKIP (0.000s)
[277.125385] Subtest fbc-psr-suspend: SKIP (0.001s)
[277.198872] [024/130] (741s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[277.285929] Starting subtest: fbcpsr-stridechange
[283.350391] Subtest fbcpsr-stridechange: SUCCESS (6.068s)
[283.525230] [025/130] (735s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[283.624571] Starting subtest: 4-tiled-32bpp-rotate-180
[285.057379] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.432s)
[285.198718] [026/130] (733s left) gem_mmap_gtt (basic-read-write-distinct)
[285.254897] Subtest basic-read-write-distinct: SKIP (0.000s)
[285.317775] [027/130] (733s left) perf_pmu (busy-accuracy-2)
[285.352153] Starting subtest: busy-accuracy-2
[285.354300] Starting dynamic subtest: rcs0
[288.398931] Dynamic subtest rcs0: SUCCESS (3.043s)
[288.409213] Starting dynamic subtest: bcs0
[291.457281] Dynamic subtest bcs0: SUCCESS (3.057s)
[291.467601] Starting dynamic subtest: vcs0
[294.500484] Dynamic subtest vcs0: SUCCESS (3.042s)
[294.511175] Starting dynamic subtest: vcs1
[297.547075] Dynamic subtest vcs1: SUCCESS (3.045s)
[297.557456] Starting dynamic subtest: vecs0
[300.595966] Dynamic subtest vecs0: SUCCESS (3.047s)
[300.608065] Starting dynamic subtest: ccs0
[303.655865] Dynamic subtest ccs0: SUCCESS (3.058s)
[303.657962] Subtest busy-accuracy-2: SUCCESS (18.301s)
[303.806369] [028/130] (714s left) gem_create (create-ext-set-pat)
[303.965843] Starting subtest: create-ext-set-pat
[303.968445] Subtest create-ext-set-pat: SUCCESS (0.001s)
[304.096533] [029/130] (714s left) gem_exec_reloc (basic-wc-cpu-active)
[304.165565] Subtest basic-wc-cpu-active: SKIP (0.000s)
[304.228138] [030/130] (714s left) gem_ctx_persistence (userptr)
[304.282142] Starting subtest: userptr
[304.543241] Subtest userptr: SUCCESS (0.260s)
[304.636354] [031/130] (714s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[304.699100] Starting subtest: multi-wait-all-for-submit-unsubmitted
[304.807983] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.107s)
[304.895372] [032/130] (713s left) gem_busy (close-race)
[304.958326] Starting subtest: close-race
[328.915541] Subtest close-race: SUCCESS (23.954s)
[329.299357] [033/130] (689s left) kms_addfb_basic (small-bo)
[329.436106] Starting subtest: small-bo
[329.438019] Subtest small-bo: SUCCESS (0.000s)
[329.569724] [034/130] (689s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[329.666577] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[334.069370] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.408s)
[335.267500] [035/130] (683s left) syncobj_timeline (invalid-transfer-non-existent-point)
[335.324378] Starting subtest: invalid-transfer-non-existent-point
[335.326787] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[335.444132] [036/130] (683s left) kms_lease (lease-revoke)
[335.537888] Starting subtest: lease-revoke
[335.540116] Starting dynamic subtest: pipe-A-eDP-1
[335.572767] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.034s)
[335.584043] Starting dynamic subtest: pipe-B-eDP-1
[335.597174] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.026s)
[335.606655] Starting dynamic subtest: pipe-C-eDP-1
[335.622905] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.025s)
[335.632180] Starting dynamic subtest: pipe-D-eDP-1
[335.648466] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.024s)
[335.650840] Subtest lease-revoke: SUCCESS (0.113s)
[335.814480] [037/130] (683s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[335.920215] Starting subtest: psr-rgb101010-draw-pwrite
[343.474255] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.556s)
[343.611130] [038/130] (675s left) gem_mmap_offset (partial-remap)
[343.684675] Starting subtest: partial-remap
[343.687165] Starting dynamic subtest: smem0
[343.696066] Dynamic subtest smem0: SUCCESS (0.003s)
[343.698339] Subtest partial-remap: SUCCESS (0.004s)
[343.832908] [039/130] (675s left) gem_exec_whisper (basic-fds-priority)
[343.896571] Starting subtest: basic-fds-priority
[366.814240] Subtest basic-fds-priority: SUCCESS (22.913s)
[367.020393] [040/130] (652s left) gem_softpin (allocator-nopin)
[367.076341] Starting subtest: allocator-nopin
[367.183464] Subtest allocator-nopin: SUCCESS (0.105s)
[367.332371] [041/130] (651s left) gem_exec_reloc (basic-concurrent16)
[367.390264] Subtest basic-concurrent16: SKIP (0.000s)
[367.468875] [042/130] (651s left) kms_vrr (negative-basic)
[367.569069] Starting subtest: negative-basic
[367.575938] Starting dynamic subtest: pipe-A-eDP-1
[373.001552] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.422s)
[373.286138] Subtest negative-basic: SUCCESS (5.716s)
[374.125395] [043/130] (645s left) syncobj_timeline (single-wait-all-signaled)
[374.184987] Starting subtest: single-wait-all-signaled
[374.187186] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[374.316871] [044/130] (644s left) kms_psr (fbc-psr2-primary-page-flip)
[374.417937] Starting subtest: fbc-psr2-primary-page-flip
[374.420281] Starting dynamic subtest: eDP-1
[374.424509] Dynamic subtest eDP-1: SKIP (0.000s)
[374.428703] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[374.499219] [045/130] (644s left) kms_feature_discovery (display-4x)
[374.602143] Starting subtest: display-4x
[374.606609] Subtest display-4x: SKIP (0.000s)
[374.697897] [046/130] (644s left) kms_pm_rpm (universal-planes)
[376.241362] Starting subtest: universal-planes
[376.244199] Starting dynamic subtest: plane-33
[384.542700] Dynamic subtest plane-33: SUCCESS (8.300s)
[384.555098] Starting dynamic subtest: plane-43
[392.938344] Dynamic subtest plane-43: SUCCESS (8.392s)
[392.949383] Starting dynamic subtest: plane-53
[401.217665] Dynamic subtest plane-53: SUCCESS (8.277s)
[401.229857] Starting dynamic subtest: plane-63
[409.489713] Dynamic subtest plane-63: SUCCESS (8.270s)
[409.501503] Starting dynamic subtest: plane-73
[417.775063] Dynamic subtest plane-73: SUCCESS (8.283s)
[417.787061] Starting dynamic subtest: plane-83
[426.087415] Dynamic subtest plane-83: SUCCESS (8.310s)
[426.089849] Subtest universal-planes: SUCCESS (49.845s)
[426.478532] [047/130] (592s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[426.585975] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[427.540606] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (0.956s)
[427.723039] [048/130] (591s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[427.791281] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[427.882101] [049/130] (591s left) kms_scaling_modes (scaling-mode-full-aspect)
[427.976046] Starting subtest: scaling-mode-full-aspect
[427.977810] Starting dynamic subtest: pipe-A-eDP-1
[428.095164] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.120s)
[428.105926] Starting dynamic subtest: pipe-B-eDP-1
[429.281808] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.183s)
[429.291080] Starting dynamic subtest: pipe-C-eDP-1
[430.396141] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.113s)
[430.405691] Starting dynamic subtest: pipe-D-eDP-1
[431.534071] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s)
[431.536405] Subtest scaling-mode-full-aspect: SUCCESS (3.559s)
[432.717442] [050/130] (586s left) kms_color (degamma)
[432.810832] Starting subtest: degamma
[432.813115] Starting dynamic subtest: pipe-A-eDP-1
[433.504516] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.690s)
[433.514459] Starting dynamic subtest: pipe-B-eDP-1
[434.902872] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.399s)
[434.913177] Starting dynamic subtest: pipe-C-eDP-1
[436.252749] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.347s)
[436.265355] Starting dynamic subtest: pipe-D-eDP-1
[437.618356] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.366s)
[437.620796] Subtest degamma: SUCCESS (4.806s)
[438.433668] [051/130] (581s left) kms_cursor_crc (cursor-offscreen-256x85)
[438.705928] Starting subtest: cursor-offscreen-256x85
[438.746211] Subtest cursor-offscreen-256x85: SKIP (0.036s)
[438.939418] [052/130] (580s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[439.062966] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[439.067399] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[439.217703] [053/130] (580s left) perf_pmu (busy-start)
[439.255422] Starting subtest: busy-start
[439.257738] Starting dynamic subtest: rcs0
[441.805331] Dynamic subtest rcs0: SUCCESS (2.549s)
[441.817289] Starting dynamic subtest: bcs0
[444.354691] Dynamic subtest bcs0: SUCCESS (2.547s)
[444.366884] Starting dynamic subtest: vcs0
[447.890936] Dynamic subtest vcs0: SUCCESS (3.533s)
[447.901031] Starting dynamic subtest: vcs1
[451.923254] Dynamic subtest vcs1: SUCCESS (4.030s)
[451.934944] Starting dynamic subtest: vecs0
[455.891077] Dynamic subtest vecs0: SUCCESS (3.966s)
[455.901637] Starting dynamic subtest: ccs0
[458.439658] Dynamic subtest ccs0: SUCCESS (2.548s)
[458.442141] Subtest busy-start: SUCCESS (19.183s)
[458.571181] [054/130] (561s left) gem_spin_batch (spin-each)
[458.630353] Starting subtest: spin-each
[462.002698] Subtest spin-each: SUCCESS (3.372s)
[464.000723] [055/130] (555s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[464.114330] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[464.118579] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[464.239214] [056/130] (555s left) kms_chamelium_frames (hdmi-crc-multiple)
[464.623434] Subtest hdmi-crc-multiple: SKIP (0.000s)
[465.429868] [057/130] (554s left) gen9_exec_parse (bb-start-out)
[465.492768] Subtest bb-start-out: SKIP (0.000s)
[465.560808] [058/130] (554s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[465.682412] Starting subtest: cursorB-vs-flipA-atomic-transitions
[465.686840] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[465.851737] [059/130] (554s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[465.951515] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[465.955787] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[466.089085] [060/130] (553s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[466.189064] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[466.191333] Starting dynamic subtest: pipe-A-eDP-1
[466.213907] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[466.224698] Starting dynamic subtest: pipe-B-eDP-1
[466.245681] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[466.253941] Starting dynamic subtest: pipe-C-eDP-1
[466.275221] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[466.283253] Starting dynamic subtest: pipe-D-eDP-1
[466.305035] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[466.309299] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[468.340290] [061/130] (551s left) kms_flip (absolute-wf_vblank)
[468.431446] Starting subtest: absolute-wf_vblank
[468.433669] Starting dynamic subtest: A-eDP1
[477.173336] Dynamic subtest A-eDP1: SUCCESS (8.742s)
[477.184245] Starting dynamic subtest: B-eDP1
[485.906403] Dynamic subtest B-eDP1: SUCCESS (8.732s)
[485.915376] Starting dynamic subtest: C-eDP1
[494.630614] Dynamic subtest C-eDP1: SUCCESS (8.722s)
[494.641362] Starting dynamic subtest: D-eDP1
[503.368912] Dynamic subtest D-eDP1: SUCCESS (8.736s)
[503.371291] Subtest absolute-wf_vblank: SUCCESS (34.939s)
[504.604128] [062/130] (515s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[504.655289] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[504.759741] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.104s)
[504.857432] [063/130] (515s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[504.919541] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[504.921665] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[505.054386] [064/130] (515s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[505.150126] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[505.161252] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.010s)
[505.292290] [065/130] (514s left) kms_cursor_crc (cursor-onscreen-128x42)
[505.515715] Starting subtest: cursor-onscreen-128x42
[505.558126] Subtest cursor-onscreen-128x42: SKIP (0.036s)
[505.781448] [066/130] (514s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[505.880025] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[505.881807] Starting dynamic subtest: pipe-A-eDP-1
[505.928279] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[505.937251] Starting dynamic subtest: pipe-B-eDP-1
[505.986265] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[506.000371] Starting dynamic subtest: pipe-C-eDP-1
[506.049476] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[506.059687] Starting dynamic subtest: pipe-D-eDP-1
[506.107667] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[506.111439] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[508.053615] [067/130] (512s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[508.152676] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[508.154928] Starting dynamic subtest: pipe-A-eDP-1
[508.177421] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[508.187958] Starting dynamic subtest: pipe-B-eDP-1
[508.209161] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[508.218388] Starting dynamic subtest: pipe-C-eDP-1
[508.239639] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[508.249375] Starting dynamic subtest: pipe-D-eDP-1
[508.271388] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[508.275366] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[510.342416] [068/130] (509s left) kms_vblank (query-idle)
[510.437794] Starting subtest: query-idle
[510.440114] Starting dynamic subtest: pipe-A-eDP-1
[512.186708] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.750s)
[512.200924] Starting dynamic subtest: pipe-D-eDP-1
[514.649799] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.462s)
[514.651974] Subtest query-idle: SUCCESS (4.215s)
[515.492158] [069/130] (504s left) gem_exec_reloc (basic-cpu-read-active)
[515.555636] Subtest basic-cpu-read-active: SKIP (0.000s)
[515.627948] [070/130] (504s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[515.725208] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[515.729583] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[515.833254] [071/130] (504s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[520.744875] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[520.747400] Starting dynamic subtest: pipe-A-eDP-1
[521.558920] Dynamic subtest pipe-A-eDP-1: SKIP (0.811s)
[521.569394] Starting dynamic subtest: pipe-B-eDP-1
[522.689607] Dynamic subtest pipe-B-eDP-1: SKIP (1.127s)
[522.694123] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.942s)
[523.894397] [072/130] (496s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[523.994592] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[530.157570] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.166s)
[531.286249] [073/130] (489s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[531.390548] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[531.395046] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[531.518641] [074/130] (488s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[531.614454] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[539.005986] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.393s)
[539.191106] [075/130] (481s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[539.293238] Starting subtest: fbc-modesetfrombusy
[543.108708] Subtest fbc-modesetfrombusy: SUCCESS (3.820s)
[544.290467] [076/130] (476s left) gem_tiled_blits (basic)
[544.357667] Subtest basic: SKIP (0.000s)
[544.428719] [077/130] (476s left) gem_eio (context-create)
[544.647904] Starting subtest: context-create
[544.951949] Subtest context-create: SUCCESS (0.305s)
[545.077130] [078/130] (475s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[545.133833] Starting subtest: x-tiled-to-vebox-y-tiled
[545.138477] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[545.330326] [079/130] (475s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[545.435175] Starting subtest: basic-busy-flip-before-cursor-varying-size
[545.439723] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[545.604959] [080/130] (475s left) kms_plane_alpha_blend (alpha-7efc)
[545.693370] Starting subtest: alpha-7efc
[545.980376] Starting dynamic subtest: pipe-A-eDP-1
[546.569782] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.586s)
[546.592723] Starting dynamic subtest: pipe-D-eDP-1
[547.834688] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.249s)
[547.837033] Subtest alpha-7efc: SUCCESS (2.143s)
[548.659446] [081/130] (472s left) i915_selftest (perf)
[550.643629] Starting subtest: perf
[550.646540] Starting dynamic subtest: engine_cs
[553.549062] Dynamic subtest engine_cs: SUCCESS (2.905s)
[553.557754] Starting dynamic subtest: request
[560.803675] Dynamic subtest request: SUCCESS (7.253s)
[560.812596] Starting dynamic subtest: migrate
[565.147045] Dynamic subtest migrate: SUCCESS (4.342s)
[565.156062] Starting dynamic subtest: region
[571.107867] Dynamic subtest region: SUCCESS (5.959s)
[571.110378] Subtest perf: SUCCESS (20.464s)
[571.137998] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[571.138701] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[571.141668] [082/130] (449s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[572.655751] Starting subtest: short-flip-before-cursor-toggle
[573.603028] Subtest short-flip-before-cursor-toggle: SUCCESS (0.949s)
[574.534684] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[574.535888] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: kernel.kmod_is_loaded.i915: true
[574.539419] [083/130] (446s left) kms_pm_rpm (modeset-lpsp-stress)
[576.065536] Starting subtest: modeset-lpsp-stress
[596.022704] Subtest modeset-lpsp-stress: SUCCESS (19.955s)
[596.120242] [084/130] (424s left) gem_exec_flush (basic-wb-rw-before-default)
[596.187090] Starting subtest: basic-wb-rw-before-default
[601.636992] Subtest basic-wb-rw-before-default: SUCCESS (5.450s)
[601.833873] [085/130] (419s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[601.944481] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[602.712724] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.770s)
[602.877224] [086/130] (418s left) i915_query (query-regions-unallocated)
[602.935752] Starting subtest: query-regions-unallocated
[602.938051] Subtest query-regions-unallocated: SUCCESS (0.000s)
[603.067135] [087/130] (417s left) gem_exec_flush (basic-batch-kernel-default-wb)
[603.134242] Starting subtest: basic-batch-kernel-default-wb
[610.000443] Subtest basic-batch-kernel-default-wb: SUCCESS (6.868s)
[610.209127] [088/130] (410s left) syncobj_wait (multi-wait-all-signaled)
[610.281428] Starting subtest: multi-wait-all-signaled
[610.283633] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[610.404623] [089/130] (410s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[610.501679] Starting subtest: fbc-dirtyfb-ioctl
[610.503681] Starting dynamic subtest: A-eDP-1
[613.062545] Dynamic subtest A-eDP-1: SUCCESS (2.561s)
[613.064870] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.562s)
[613.860400] [090/130] (407s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[618.930712] Starting subtest: psr2-overlay-plane-update-continuous-sf
[618.933185] Starting dynamic subtest: pipe-A-eDP-1
[620.678160] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.746s)
[620.689077] Starting dynamic subtest: pipe-B-eDP-1
[622.401231] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.721s)
[622.403869] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (3.470s)
[623.235870] [091/130] (397s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[623.331496] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[623.335970] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[623.463400] [092/130] (397s left) kms_fbcon_fbt (fbc-suspend)
[623.527646] Starting subtest: fbc-suspend
[672.132054] Subtest fbc-suspend: SUCCESS (18.970s)
[673.283215] [093/130] (347s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[673.383092] Starting subtest: y-tiled-8bpp-rotate-270
[673.388830] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[673.497372] [094/130] (347s left) gem_exec_schedule (u-submit-early-slice)
[673.583566] Starting subtest: u-submit-early-slice
[673.585646] Starting dynamic subtest: rcs0
[673.618961] Dynamic subtest rcs0: SUCCESS (0.045s)
[673.631731] Starting dynamic subtest: bcs0
[673.656479] Dynamic subtest bcs0: SUCCESS (0.034s)
[673.666400] Starting dynamic subtest: vcs0
[673.695458] Dynamic subtest vcs0: SUCCESS (0.041s)
[673.707097] Starting dynamic subtest: vcs1
[673.736496] Dynamic subtest vcs1: SUCCESS (0.040s)
[673.747081] Starting dynamic subtest: vecs0
[673.770914] Dynamic subtest vecs0: SUCCESS (0.034s)
[673.783460] Starting dynamic subtest: ccs0
[673.807773] Dynamic subtest ccs0: SUCCESS (0.036s)
[673.810298] Subtest u-submit-early-slice: SUCCESS (0.233s)
[675.647948] [095/130] (345s left) vgem_basic (debugfs)
[675.693356] Starting subtest: debugfs
[675.695360] Subtest debugfs: SUCCESS (0.000s)
[675.772253] [096/130] (345s left) gen7_exec_parse (batch-without-end)
[675.837667] Subtest batch-without-end: SKIP (0.000s)
[675.911809] [097/130] (345s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[676.008652] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[698.007362] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (21.987s)
[698.175648] [098/130] (323s left) dmabuf_sync_file (export-basic)
[698.218745] Starting subtest: export-basic
[698.221065] Subtest export-basic: SUCCESS (0.001s)
[698.305297] [099/130] (323s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[698.401478] Starting subtest: random-ccs-data-y-tiled-ccs
[698.403452] Starting dynamic subtest: pipe-A-eDP-1
[698.424730] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[698.434780] Starting dynamic subtest: pipe-B-eDP-1
[698.456510] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[698.465682] Starting dynamic subtest: pipe-C-eDP-1
[698.487759] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[698.496446] Starting dynamic subtest: pipe-D-eDP-1
[698.517991] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[698.522108] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[700.589606] [100/130] (320s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[700.710188] Starting subtest: cursorB-vs-flipB-atomic
[700.715096] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[700.878465] [101/130] (320s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[700.933697] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[700.997377] [102/130] (320s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[701.120705] Starting subtest: protected-encrypted-src-copy-not-readible
[701.229814] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.111s)
[701.381565] [103/130] (320s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[701.469433] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[701.471751] Starting dynamic subtest: pipe-A-default-mode
[702.625450] Dynamic subtest pipe-A-default-mode: SKIP (1.154s)
[702.630808] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.156s)
[702.790526] [104/130] (318s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[702.899329] Starting subtest: fbcpsr-tiling-y
[702.903959] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[703.009901] [105/130] (318s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[703.555997] Starting subtest: syncobj-timeline-invalid-flags
[703.558651] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[704.622143] [106/130] (317s left) gem_basic (create-close)
[704.686807] Starting subtest: create-close
[704.691890] Subtest create-close: SUCCESS (0.000s)
[704.779256] [107/130] (317s left) api_intel_allocator (reloc-allocator)
[704.833130] Starting subtest: reloc-allocator
[704.836100] Starting dynamic subtest: basic
[704.838834] Dynamic subtest basic: SUCCESS (0.004s)
[704.850495] Starting dynamic subtest: parallel-one
[704.852879] Dynamic subtest parallel-one: SUCCESS (0.009s)
[704.862881] Starting dynamic subtest: print
[704.865575] Dynamic subtest print: SUCCESS (0.000s)
[704.875387] Starting dynamic subtest: reuse
[704.877858] Dynamic subtest reuse: SUCCESS (0.000s)
[704.887239] Starting dynamic subtest: fork-reopen-allocator
[704.889977] Dynamic subtest fork-reopen-allocator: SUCCESS (0.011s)
[704.892656] Subtest reloc-allocator: SUCCESS (0.027s)
[704.998777] [108/130] (316s left) kms_psr (pr-primary-blt)
[705.097718] Starting subtest: pr-primary-blt
[705.102864] Subtest pr-primary-blt: SKIP (0.000s)
[705.184183] [109/130] (316s left) gem_ctx_bad_destroy (double-destroy)
[705.239473] Starting subtest: double-destroy
[705.242306] Subtest double-destroy: SUCCESS (0.000s)
[705.338301] [110/130] (316s left) core_getclient
[705.511810] [111/130] (316s left) syncobj_wait (wait-all-delayed-signal)
[705.565536] Starting subtest: wait-all-delayed-signal
[705.667671] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[705.756850] [112/130] (316s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[705.851650] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[705.855311] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[705.957504] [113/130] (316s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[706.051332] Starting subtest: yf-tiled-16bpp-rotate-0
[706.055086] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[706.174466] [114/130] (315s left) kms_async_flips (test-time-stamp)
[706.263787] Starting subtest: test-time-stamp
[706.639096] Starting dynamic subtest: pipe-A-eDP-1
[707.402823] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.761s)
[707.770039] Starting dynamic subtest: pipe-B-eDP-1
[708.529856] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.764s)
[708.905700] Starting dynamic subtest: pipe-C-eDP-1
[709.655749] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.755s)
[710.018402] Starting dynamic subtest: pipe-D-eDP-1
[710.782955] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.770s)
[710.785767] Subtest test-time-stamp: SUCCESS (4.519s)
[711.949297] [115/130] (310s left) kms_invalid_mode (bad-vsync-end)
[712.051672] Starting subtest: bad-vsync-end
[712.054062] Starting dynamic subtest: pipe-A-eDP-1
[712.349077] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.300s)
[712.358741] Starting dynamic subtest: pipe-B-eDP-1
[712.361318] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[712.370841] Starting dynamic subtest: pipe-C-eDP-1
[712.373015] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[712.382367] Starting dynamic subtest: pipe-D-eDP-1
[712.384772] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[712.387218] Subtest bad-vsync-end: SUCCESS (0.309s)
[713.192889] [116/130] (309s left) gem_exec_suspend (basic)
[713.259503] Starting subtest: basic
[713.262197] Starting dynamic subtest: smem
[714.489780] Dynamic subtest smem: SUCCESS (1.229s)
[714.492830] Subtest basic: SUCCESS (1.230s)
[714.684943] [117/130] (307s left) api_intel_bb (blit-noreloc-purge-cache)
[714.734998] Starting subtest: blit-noreloc-purge-cache
[714.742571] Subtest blit-noreloc-purge-cache: SUCCESS (0.010s)
[714.840947] [118/130] (307s left) kms_plane (pixel-format-source-clamping)
[714.932805] Starting subtest: pixel-format-source-clamping
[714.961885] Starting dynamic subtest: pipe-A-plane-0
[719.872599] Dynamic subtest pipe-A-plane-0: SUCCESS (4.911s)
[719.881908] Starting dynamic subtest: pipe-A-plane-3
[722.295494] Dynamic subtest pipe-A-plane-3: SUCCESS (2.422s)
[722.305745] Starting dynamic subtest: pipe-A-plane-5
[722.308648] Dynamic subtest pipe-A-plane-5: SUCCESS (0.000s)
[723.365446] Starting dynamic subtest: pipe-B-plane-0
[728.268627] Dynamic subtest pipe-B-plane-0: SUCCESS (4.911s)
[728.278531] Starting dynamic subtest: pipe-B-plane-3
[730.558113] Dynamic subtest pipe-B-plane-3: SUCCESS (2.288s)
[730.567752] Starting dynamic subtest: pipe-B-plane-5
[730.569615] Dynamic subtest pipe-B-plane-5: SUCCESS (0.000s)
[730.868124] Subtest pixel-format-source-clamping: SUCCESS (15.933s)
[731.766837] [119/130] (290s left) i915_query (query-topology-kernel-writes)
[731.832482] Starting subtest: query-topology-kernel-writes
[731.834562] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[731.952819] [120/130] (290s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[732.336771] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[733.164231] [121/130] (289s left) gem_exec_reloc (basic-cpu-active)
[733.220896] Subtest basic-cpu-active: SKIP (0.000s)
[733.302418] [122/130] (289s left) gem_exec_fence (concurrent)
[733.485948] Starting subtest: concurrent
[733.491276] Subtest concurrent: SKIP (0.000s)
[735.593429] [123/130] (286s left) perf (short-reads)
[735.734494] Starting subtest: short-reads
[735.751070] Subtest short-reads: SUCCESS (0.016s)
[735.790301] [124/130] (286s left) dumb_buffer (create-clear)
[735.847098] Starting subtest: create-clear
[769.686649] Subtest create-clear: SUCCESS (33.831s)
[769.812527] [125/130] (252s left) kms_psr2_su (page_flip-p010)
[769.865083] Subtest page_flip-P010: SKIP (0.000s)
[769.927957] [126/130] (252s left) i915_pm_rc6_residency (rc6-idle)
[769.990090] Starting subtest: rc6-idle
[770.007251] Starting dynamic subtest: gt0-rcs0
[794.208983] Dynamic subtest gt0-rcs0: SUCCESS (24.205s)
[794.218915] Starting dynamic subtest: gt0-bcs0
[818.523425] Dynamic subtest gt0-bcs0: SUCCESS (24.310s)
[818.534959] Starting dynamic subtest: gt0-ccs0
[842.785773] Dynamic subtest gt0-ccs0: SUCCESS (24.257s)
[842.795871] Starting dynamic subtest: gt1-vcs0
[848.960496] Dynamic subtest gt1-vcs0: SUCCESS (6.170s)
[848.972905] Starting dynamic subtest: gt1-vecs0
[855.133929] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[855.136889] Subtest rc6-idle: SUCCESS (85.140s)
[855.216738] [127/130] (167s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[855.323484] Starting subtest: plane-scaler-unity-scaling-with-rotation
[855.325710] Starting dynamic subtest: pipe-A
[855.551087] Dynamic subtest pipe-A: SUCCESS (0.230s)
[855.561607] Starting dynamic subtest: pipe-B
[856.864732] Dynamic subtest pipe-B: SUCCESS (1.311s)
[856.877364] Starting dynamic subtest: pipe-C
[858.201004] Dynamic subtest pipe-C: SUCCESS (1.335s)
[858.210846] Starting dynamic subtest: pipe-D
[859.531427] Dynamic subtest pipe-D: SUCCESS (1.330s)
[859.533657] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.210s)
[860.748505] [128/130] (161s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[860.849258] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[860.853579] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[860.964564] [129/130] (161s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[861.075062] Starting subtest: cursor-vs-flip-legacy
[890.312664] Subtest cursor-vs-flip-legacy: SUCCESS (29.229s)
[891.177659] [130/130] (131s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[891.235636] Starting subtest: multi-wait-available-submitted-signaled
[891.238277] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[891.366639] Closing watchdogs
results: parsing output: 0/ for test: kms_plane_scaling
results: parsing output: 1/ for test: kms_frontbuffer_tracking
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: kms_flip
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: kms_frontbuffer_tracking
results: parsing output: 6/ for test: gem_ctx_exec
results: parsing output: 7/ for test: kms_psr
results: parsing output: 8/ for test: core_getstats
results: parsing output: 9/ for test: i915_hangman
results: parsing output: 10/ for test: kms_ccs
results: parsing output: 11/ for test: gem_lmem_swapping
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: gem_ctx_persistence
results: parsing output: 16/ for test: prime_vgem
results: parsing output: 17/ for test: gem_exec_schedule
results: parsing output: 18/ for test: kms_flip
results: parsing output: 19/ for test: kms_cursor_crc
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: gem_exec_balancer
results: parsing output: 22/ for test: kms_psr
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: gem_mmap_gtt
results: parsing output: 26/ for test: perf_pmu
results: parsing output: 27/ for test: gem_create
results: parsing output: 28/ for test: gem_exec_reloc
results: parsing output: 29/ for test: gem_ctx_persistence
results: parsing output: 30/ for test: syncobj_wait
results: parsing output: 31/ for test: gem_busy
results: parsing output: 32/ for test: kms_addfb_basic
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: syncobj_timeline
results: parsing output: 35/ for test: kms_lease
results: parsing output: 36/ for test: kms_frontbuffer_tracking
results: parsing output: 37/ for test: gem_mmap_offset
results: parsing output: 38/ for test: gem_exec_whisper
results: parsing output: 39/ for test: gem_softpin
results: parsing output: 40/ for test: gem_exec_reloc
results: parsing output: 41/ for test: kms_vrr
results: parsing output: 42/ for test: syncobj_timeline
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: kms_feature_discovery
results: parsing output: 45/ for test: kms_pm_rpm
results: parsing output: 46/ for test: kms_big_fb
results: parsing output: 47/ for test: gem_exec_reloc
results: parsing output: 48/ for test: kms_scaling_modes
results: parsing output: 49/ for test: kms_color
results: parsing output: 50/ for test: kms_cursor_crc
results: parsing output: 51/ for test: kms_cursor_legacy
results: parsing output: 52/ for test: perf_pmu
results: parsing output: 53/ for test: gem_spin_batch
results: parsing output: 54/ for test: kms_frontbuffer_tracking
results: parsing output: 55/ for test: kms_chamelium_frames
results: parsing output: 56/ for test: gen9_exec_parse
results: parsing output: 57/ for test: kms_cursor_legacy
results: parsing output: 58/ for test: kms_frontbuffer_tracking
results: parsing output: 59/ for test: kms_ccs
results: parsing output: 60/ for test: kms_flip
results: parsing output: 61/ for test: syncobj_timeline
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: kms_flip
results: parsing output: 64/ for test: kms_cursor_crc
results: parsing output: 65/ for test: kms_ccs
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: kms_vblank
results: parsing output: 68/ for test: gem_exec_reloc
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_psr2_sf
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: kms_frontbuffer_tracking
results: parsing output: 75/ for test: gem_tiled_blits
results: parsing output: 76/ for test: gem_eio
results: parsing output: 77/ for test: gem_render_copy
results: parsing output: 78/ for test: kms_cursor_legacy
results: parsing output: 79/ for test: kms_plane_alpha_blend
results: parsing output: 80/ for test: i915_selftest
results: parsing output: 81/ for test: kms_cursor_legacy
results: parsing output: 82/ for test: kms_pm_rpm
results: parsing output: 83/ for test: gem_exec_flush
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: i915_query
results: parsing output: 86/ for test: gem_exec_flush
results: parsing output: 87/ for test: syncobj_wait
results: parsing output: 88/ for test: kms_dirtyfb
results: parsing output: 89/ for test: kms_psr2_sf
results: parsing output: 90/ for test: kms_frontbuffer_tracking
results: parsing output: 91/ for test: kms_fbcon_fbt
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: vgem_basic
results: parsing output: 95/ for test: gen7_exec_parse
results: parsing output: 96/ for test: kms_frontbuffer_tracking
results: parsing output: 97/ for test: dmabuf_sync_file
results: parsing output: 98/ for test: kms_ccs
results: parsing output: 99/ for test: kms_cursor_legacy
results: parsing output: 100/ for test: gem_mmap_gtt
results: parsing output: 101/ for test: gem_pxp
results: parsing output: 102/ for test: kms_flip_scaled_crc
results: parsing output: 103/ for test: kms_frontbuffer_tracking
results: parsing output: 104/ for test: gem_exec_fence
results: parsing output: 105/ for test: gem_basic
results: parsing output: 106/ for test: api_intel_allocator
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: gem_ctx_bad_destroy
results: parsing output: 109/ for test: core_getclient
results: parsing output: 110/ for test: syncobj_wait
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_big_fb
results: parsing output: 113/ for test: kms_async_flips
results: parsing output: 114/ for test: kms_invalid_mode
results: parsing output: 115/ for test: gem_exec_suspend
results: parsing output: 116/ for test: api_intel_bb
results: parsing output: 117/ for test: kms_plane
results: parsing output: 118/ for test: i915_query
results: parsing output: 119/ for test: kms_chamelium_edid
results: parsing output: 120/ for test: gem_exec_reloc
results: parsing output: 121/ for test: gem_exec_fence
results: parsing output: 122/ for test: perf
results: parsing output: 123/ for test: dumb_buffer
results: parsing output: 124/ for test: kms_psr2_su
results: parsing output: 125/ for test: i915_pm_rc6_residency
results: parsing output: 126/ for test: kms_plane_scaling
results: parsing output: 127/ for test: kms_frontbuffer_tracking
results: parsing output: 128/ for test: kms_cursor_legacy
results: parsing output: 129/ for test: syncobj_timeline
Done.