[54.252371] Initializing watchdogs
[54.252484]   /dev/watchdog0
[54.267604] [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]
[54.272134] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.274334] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.277553] [001/130] (960s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[54.391696] Starting subtest: plane-upscale-20x20-with-rotation
[54.393935] Starting dynamic subtest: pipe-A
[54.622721] Dynamic subtest pipe-A: SUCCESS (0.235s)
[54.631127] Starting dynamic subtest: pipe-B
[55.869335] Dynamic subtest pipe-B: SUCCESS (1.254s)
[55.877648] Starting dynamic subtest: pipe-C
[57.196888] Dynamic subtest pipe-C: SUCCESS (1.335s)
[57.205351] Starting dynamic subtest: pipe-D
[58.533278] Dynamic subtest pipe-D: SUCCESS (1.342s)
[58.535559] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.166s)
[59.710993] [002/130] (955s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[59.806296] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[59.810571] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[59.940250] [003/130] (954s left) kms_big_fb (linear-8bpp-rotate-180)
[60.036976] Starting subtest: linear-8bpp-rotate-180
[61.179332] Subtest linear-8bpp-rotate-180: SUCCESS (1.145s)
[61.333449] [004/130] (953s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[61.424436] Starting subtest: blocking-absolute-wf_vblank-interruptible
[61.426889] Starting dynamic subtest: A-eDP1
[70.139418] Dynamic subtest A-eDP1: SUCCESS (8.728s)
[70.146946] Starting dynamic subtest: B-eDP1
[78.853257] Dynamic subtest B-eDP1: SUCCESS (8.718s)
[78.863194] Starting dynamic subtest: C-eDP1
[87.566491] Dynamic subtest C-eDP1: SUCCESS (8.714s)
[87.575181] Starting dynamic subtest: D-eDP1
[96.295582] Dynamic subtest D-eDP1: SUCCESS (8.729s)
[96.297882] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.893s)
[97.527679] [005/130] (917s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[97.636659] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[97.640736] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[97.737790] [006/130] (917s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[97.836192] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[104.475843] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (6.642s)
[104.603333] [007/130] (910s left) gem_ctx_exec (basic-norecovery)
[104.659713] Starting subtest: basic-norecovery
[104.949117] Subtest basic-norecovery: SUCCESS (0.287s)
[105.106169] [008/130] (909s left) kms_psr (psr-primary-page-flip)
[105.204653] Starting subtest: psr-primary-page-flip
[105.206874] Starting dynamic subtest: eDP-1
[106.973696] Dynamic subtest eDP-1: SUCCESS (1.768s)
[106.975580] Subtest psr-primary-page-flip: SUCCESS (1.768s)
[107.843596] [009/130] (907s left) core_getstats
[108.013655] [010/130] (907s left) i915_hangman (detector)
[108.082653] Starting subtest: detector
[108.084836] Starting dynamic subtest: rcs0
[118.901995] Dynamic subtest rcs0: SUCCESS (10.818s)
[118.913343] Starting dynamic subtest: bcs0
[130.803458] Dynamic subtest bcs0: SUCCESS (11.900s)
[130.813501] Starting dynamic subtest: vcs0
[141.811820] Dynamic subtest vcs0: SUCCESS (11.008s)
[141.821785] Starting dynamic subtest: vcs1
[152.883407] Dynamic subtest vcs1: SUCCESS (11.069s)
[152.892674] Starting dynamic subtest: vecs0
[163.890972] Dynamic subtest vecs0: SUCCESS (11.003s)
[163.902348] Starting dynamic subtest: ccs0
[175.860598] Dynamic subtest ccs0: SUCCESS (11.965s)
[175.863603] Subtest detector: SUCCESS (67.769s)
[175.945633] [011/130] (839s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[176.052717] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[176.054978] Starting dynamic subtest: pipe-A-eDP-1
[178.046985] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.994s)
[178.054705] Starting dynamic subtest: pipe-B-eDP-1
[180.076308] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.028s)
[180.084730] Starting dynamic subtest: pipe-C-eDP-1
[182.095187] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.017s)
[182.103065] Starting dynamic subtest: pipe-D-eDP-1
[184.113928] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.016s)
[184.116307] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.059s)
[185.278697] [012/130] (829s left) gem_lmem_swapping (parallel-random-engines)
[185.326440] Subtest parallel-random-engines: SKIP (0.000s)
[185.367420] [013/130] (829s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[185.470617] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[185.474644] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[185.576925] [014/130] (829s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[185.681032] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[185.685226] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[185.789698] [015/130] (829s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[185.884316] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[185.888267] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[186.002323] [016/130] (829s left) gem_ctx_persistence (legacy-engines-hostile)
[186.067303] Starting subtest: legacy-engines-hostile
[186.069542] Starting dynamic subtest: render
[186.168580] Dynamic subtest render: SUCCESS (0.101s)
[186.178120] Starting dynamic subtest: bsd1
[186.283051] Dynamic subtest bsd1: SUCCESS (0.113s)
[186.293213] Starting dynamic subtest: bsd2
[186.391860] Dynamic subtest bsd2: SUCCESS (0.106s)
[186.401773] Starting dynamic subtest: blt
[186.510548] Dynamic subtest blt: SUCCESS (0.117s)
[186.520138] Starting dynamic subtest: vebox
[186.647588] Dynamic subtest vebox: SUCCESS (0.135s)
[186.649730] Subtest legacy-engines-hostile: SUCCESS (0.581s)
[186.734572] [017/130] (828s left) prime_vgem (shrink)
[186.843752] Starting subtest: shrink
[186.857782] Subtest shrink: SUCCESS (0.013s)
[187.000155] [FACT prime_vgem (shrink)] new: kernel.kmod_is_loaded.vgem: true
[187.003092] [018/130] (828s left) gem_exec_schedule (preempt-queue-chain)
[187.064714] Subtest preempt-queue-chain: SKIP (0.000s)
[187.373069] [019/130] (828s left) kms_flip (flip-vs-panning-vs-hang)
[187.464678] Starting subtest: flip-vs-panning-vs-hang
[187.467089] Starting dynamic subtest: A-eDP1
[238.399684] Dynamic subtest A-eDP1: SUCCESS (50.930s)
[238.408330] Starting dynamic subtest: D-eDP1
[288.380068] Dynamic subtest D-eDP1: SUCCESS (49.977s)
[288.385325] Subtest flip-vs-panning-vs-hang: SUCCESS (100.912s)
[289.580554] [020/130] (725s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[289.822326] Starting subtest: cursor-rapid-movement-256x256
[289.827322] Starting dynamic subtest: pipe-A-eDP-1
[290.356200] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.528s)
[290.378405] Starting dynamic subtest: pipe-D-eDP-1
[291.581455] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.210s)
[291.583796] Subtest cursor-rapid-movement-256x256: SUCCESS (1.758s)
[292.460581] [021/130] (723s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[292.557614] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[292.562036] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[292.684926] [022/130] (722s left) gem_exec_balancer (sliced)
[292.738931] Starting subtest: sliced
[292.743662] Subtest sliced: SKIP (0.000s)
[293.058189] [023/130] (722s left) kms_psr (fbc-psr-suspend)
[293.150099] Starting subtest: fbc-psr-suspend
[293.151973] Starting dynamic subtest: eDP-1
[293.155964] Dynamic subtest eDP-1: SKIP (0.000s)
[293.159997] Subtest fbc-psr-suspend: SKIP (0.001s)
[293.230981] [024/130] (722s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[293.325708] Starting subtest: fbcpsr-stridechange
[299.459786] Subtest fbcpsr-stridechange: SUCCESS (6.136s)
[299.624853] [025/130] (716s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[299.727377] Starting subtest: 4-tiled-32bpp-rotate-180
[301.050089] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.324s)
[301.218188] [026/130] (714s left) gem_mmap_gtt (basic-read-write-distinct)
[301.281605] Subtest basic-read-write-distinct: SKIP (0.000s)
[301.346348] [027/130] (714s left) perf_pmu (busy-accuracy-2)
[301.383817] Starting subtest: busy-accuracy-2
[301.385345] Starting dynamic subtest: rcs0
[304.426207] Dynamic subtest rcs0: SUCCESS (3.040s)
[304.436518] Starting dynamic subtest: bcs0
[307.480271] Dynamic subtest bcs0: SUCCESS (3.052s)
[307.489278] Starting dynamic subtest: vcs0
[310.522909] Dynamic subtest vcs0: SUCCESS (3.042s)
[310.533368] Starting dynamic subtest: vcs1
[313.569490] Dynamic subtest vcs1: SUCCESS (3.045s)
[313.577600] Starting dynamic subtest: vecs0
[316.617977] Dynamic subtest vecs0: SUCCESS (3.047s)
[316.626472] Starting dynamic subtest: ccs0
[319.678364] Dynamic subtest ccs0: SUCCESS (3.058s)
[319.680864] Subtest busy-accuracy-2: SUCCESS (18.292s)
[319.830361] [028/130] (695s left) gem_create (create-ext-set-pat)
[320.003736] Starting subtest: create-ext-set-pat
[320.006579] Subtest create-ext-set-pat: SUCCESS (0.001s)
[320.129026] [029/130] (695s left) gem_exec_reloc (basic-wc-cpu-active)
[320.200926] Subtest basic-wc-cpu-active: SKIP (0.000s)
[320.268951] [030/130] (695s left) gem_ctx_persistence (userptr)
[320.329854] Starting subtest: userptr
[320.529440] Subtest userptr: SUCCESS (0.199s)
[320.655598] [031/130] (695s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[320.705329] Starting subtest: multi-wait-all-for-submit-unsubmitted
[320.809077] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.103s)
[320.906843] [032/130] (695s left) gem_busy (close-race)
[320.965537] Starting subtest: close-race
[346.836889] Subtest close-race: SUCCESS (25.868s)
[347.249204] [033/130] (668s left) kms_addfb_basic (small-bo)
[347.387057] Starting subtest: small-bo
[347.389285] Subtest small-bo: SUCCESS (0.000s)
[347.498861] [034/130] (668s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[347.612779] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[352.062641] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.455s)
[353.287019] [035/130] (662s left) syncobj_timeline (invalid-transfer-non-existent-point)
[353.362743] Starting subtest: invalid-transfer-non-existent-point
[353.365193] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[353.493214] [036/130] (662s left) kms_lease (lease-revoke)
[353.587067] Starting subtest: lease-revoke
[353.589329] Starting dynamic subtest: pipe-A-eDP-1
[353.623084] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.034s)
[353.636577] Starting dynamic subtest: pipe-B-eDP-1
[353.648041] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.025s)
[353.657302] Starting dynamic subtest: pipe-C-eDP-1
[353.674628] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.028s)
[353.688633] Starting dynamic subtest: pipe-D-eDP-1
[353.710083] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.031s)
[353.711876] Subtest lease-revoke: SUCCESS (0.123s)
[353.847237] [037/130] (662s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[353.945831] Starting subtest: psr-rgb101010-draw-pwrite
[361.500189] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.557s)
[361.628335] [038/130] (654s left) gem_mmap_offset (partial-remap)
[361.676390] Starting subtest: partial-remap
[361.678701] Starting dynamic subtest: smem0
[361.687336] Dynamic subtest smem0: SUCCESS (0.003s)
[361.689567] Subtest partial-remap: SUCCESS (0.004s)
[361.817211] [039/130] (654s left) gem_exec_whisper (basic-fds-priority)
[361.871400] Starting subtest: basic-fds-priority
[384.813317] Subtest basic-fds-priority: SUCCESS (22.937s)
[384.994708] [040/130] (631s left) gem_softpin (allocator-nopin)
[385.071080] Starting subtest: allocator-nopin
[385.184942] Subtest allocator-nopin: SUCCESS (0.112s)
[385.352419] [041/130] (630s left) gem_exec_reloc (basic-concurrent16)
[385.420580] Subtest basic-concurrent16: SKIP (0.000s)
[385.488971] [042/130] (630s left) kms_vrr (negative-basic)
[385.586085] Starting subtest: negative-basic
[385.592768] Starting dynamic subtest: pipe-A-eDP-1
[391.010173] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.413s)
[391.295830] Subtest negative-basic: SUCCESS (5.708s)
[392.185237] [043/130] (624s left) syncobj_timeline (single-wait-all-signaled)
[392.236583] Starting subtest: single-wait-all-signaled
[392.238934] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[392.359633] [044/130] (624s left) kms_psr (fbc-psr2-primary-page-flip)
[392.449066] Starting subtest: fbc-psr2-primary-page-flip
[392.451205] Starting dynamic subtest: eDP-1
[392.455192] Dynamic subtest eDP-1: SKIP (0.000s)
[392.459120] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[392.542879] [045/130] (623s left) kms_feature_discovery (display-4x)
[392.628090] Starting subtest: display-4x
[392.632476] Subtest display-4x: SKIP (0.000s)
[392.711281] [046/130] (623s left) kms_pm_rpm (universal-planes)
[394.254481] Starting subtest: universal-planes
[394.257062] Starting dynamic subtest: plane-33
[402.512471] Dynamic subtest plane-33: SUCCESS (8.256s)
[402.524026] Starting dynamic subtest: plane-43
[410.817331] Dynamic subtest plane-43: SUCCESS (8.302s)
[410.830461] Starting dynamic subtest: plane-53
[419.207466] Dynamic subtest plane-53: SUCCESS (8.387s)
[419.219968] Starting dynamic subtest: plane-63
[427.517684] Dynamic subtest plane-63: SUCCESS (8.307s)
[427.529717] Starting dynamic subtest: plane-73
[435.784712] Dynamic subtest plane-73: SUCCESS (8.265s)
[435.796813] Starting dynamic subtest: plane-83
[444.157372] Dynamic subtest plane-83: SUCCESS (8.370s)
[444.159900] Subtest universal-planes: SUCCESS (49.901s)
[444.543208] [047/130] (572s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[444.659345] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[445.729571] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.070s)
[445.885695] [048/130] (570s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[445.941076] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[446.009195] [049/130] (570s left) kms_scaling_modes (scaling-mode-full-aspect)
[446.102711] Starting subtest: scaling-mode-full-aspect
[446.104333] Starting dynamic subtest: pipe-A-eDP-1
[446.196098] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.095s)
[446.211083] Starting dynamic subtest: pipe-B-eDP-1
[447.350456] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.153s)
[447.359847] Starting dynamic subtest: pipe-C-eDP-1
[448.475709] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.124s)
[448.485732] Starting dynamic subtest: pipe-D-eDP-1
[449.613844] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s)
[449.616226] Subtest scaling-mode-full-aspect: SUCCESS (3.513s)
[450.802278] [050/130] (565s left) kms_color (degamma)
[450.906885] Starting subtest: degamma
[450.908981] Starting dynamic subtest: pipe-A-eDP-1
[451.624715] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.720s)
[451.635291] Starting dynamic subtest: pipe-B-eDP-1
[453.039892] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.416s)
[453.051351] Starting dynamic subtest: pipe-C-eDP-1
[454.355659] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.314s)
[454.364598] Starting dynamic subtest: pipe-D-eDP-1
[455.657732] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.301s)
[455.660141] Subtest degamma: SUCCESS (4.754s)
[456.522517] [051/130] (560s left) kms_cursor_crc (cursor-offscreen-256x85)
[456.788914] Starting subtest: cursor-offscreen-256x85
[456.831166] Subtest cursor-offscreen-256x85: SKIP (0.034s)
[457.001313] [052/130] (559s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[457.110516] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[457.115131] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[457.273213] [053/130] (559s left) perf_pmu (busy-start)
[457.315607] Starting subtest: busy-start
[457.317785] Starting dynamic subtest: rcs0
[459.867072] Dynamic subtest rcs0: SUCCESS (2.552s)
[459.878958] Starting dynamic subtest: bcs0
[462.416064] Dynamic subtest bcs0: SUCCESS (2.547s)
[462.427644] Starting dynamic subtest: vcs0
[465.940344] Dynamic subtest vcs0: SUCCESS (3.521s)
[465.951949] Starting dynamic subtest: vcs1
[469.908485] Dynamic subtest vcs1: SUCCESS (3.966s)
[469.920981] Starting dynamic subtest: vecs0
[473.940065] Dynamic subtest vecs0: SUCCESS (4.029s)
[473.951600] Starting dynamic subtest: ccs0
[476.489250] Dynamic subtest ccs0: SUCCESS (2.549s)
[476.491909] Subtest busy-start: SUCCESS (19.176s)
[476.625782] [054/130] (540s left) gem_spin_batch (spin-each)
[476.692803] Starting subtest: spin-each
[479.963777] Subtest spin-each: SUCCESS (3.263s)
[480.974332] [055/130] (535s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[481.087978] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[481.092427] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[481.234406] [056/130] (535s left) kms_chamelium_frames (hdmi-crc-multiple)
[481.625523] Subtest hdmi-crc-multiple: SKIP (0.000s)
[482.426124] [057/130] (534s left) gen9_exec_parse (bb-start-out)
[482.492071] Subtest bb-start-out: SKIP (0.000s)
[482.561281] [058/130] (534s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[482.678692] Starting subtest: cursorB-vs-flipA-atomic-transitions
[482.683293] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[482.846457] [059/130] (534s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[482.942747] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[482.946722] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[483.088251] [060/130] (534s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[483.189208] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[483.191456] Starting dynamic subtest: pipe-A-eDP-1
[483.213711] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[483.224997] Starting dynamic subtest: pipe-B-eDP-1
[483.245721] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[483.254105] Starting dynamic subtest: pipe-C-eDP-1
[483.275256] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[483.283814] Starting dynamic subtest: pipe-D-eDP-1
[483.305761] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[483.309832] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[485.358741] [061/130] (531s left) kms_flip (absolute-wf_vblank)
[485.453197] Starting subtest: absolute-wf_vblank
[485.455529] Starting dynamic subtest: A-eDP1
[494.195524] Dynamic subtest A-eDP1: SUCCESS (8.741s)
[494.205126] Starting dynamic subtest: B-eDP1
[502.923876] Dynamic subtest B-eDP1: SUCCESS (8.726s)
[502.934158] Starting dynamic subtest: C-eDP1
[511.645376] Dynamic subtest C-eDP1: SUCCESS (8.720s)
[511.655818] Starting dynamic subtest: D-eDP1
[520.384539] Dynamic subtest D-eDP1: SUCCESS (8.735s)
[520.387020] Subtest absolute-wf_vblank: SUCCESS (34.932s)
[521.605059] [062/130] (495s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[521.669844] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[521.777349] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.107s)
[521.878363] [063/130] (495s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[521.942093] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[521.944349] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[522.067249] [064/130] (495s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[522.157197] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[522.168516] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.011s)
[522.256485] [065/130] (495s left) kms_cursor_crc (cursor-onscreen-128x42)
[522.472942] Starting subtest: cursor-onscreen-128x42
[522.515224] Subtest cursor-onscreen-128x42: SKIP (0.036s)
[522.753101] [066/130] (494s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[522.845147] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[522.847087] Starting dynamic subtest: pipe-A-eDP-1
[522.895668] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[522.905808] Starting dynamic subtest: pipe-B-eDP-1
[522.954183] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[522.964635] Starting dynamic subtest: pipe-C-eDP-1
[523.013521] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[523.023278] Starting dynamic subtest: pipe-D-eDP-1
[523.068977] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[523.072209] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[525.081830] [067/130] (492s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[525.177093] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[525.179358] Starting dynamic subtest: pipe-A-eDP-1
[525.202737] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[525.212174] Starting dynamic subtest: pipe-B-eDP-1
[525.233785] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[525.242581] Starting dynamic subtest: pipe-C-eDP-1
[525.264367] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[525.273352] Starting dynamic subtest: pipe-D-eDP-1
[525.295408] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[525.299511] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[527.346928] [068/130] (490s left) kms_vblank (query-idle)
[527.443033] Starting subtest: query-idle
[527.444897] Starting dynamic subtest: pipe-A-eDP-1
[529.193562] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.751s)
[529.208466] Starting dynamic subtest: pipe-D-eDP-1
[531.680654] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.485s)
[531.682987] Subtest query-idle: SUCCESS (4.239s)
[532.547911] [069/130] (484s left) gem_exec_reloc (basic-cpu-read-active)
[532.609344] Subtest basic-cpu-read-active: SKIP (0.000s)
[532.682315] [070/130] (484s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[532.779237] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[532.783474] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[532.886827] [071/130] (484s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[537.899223] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[537.901452] Starting dynamic subtest: pipe-A-eDP-1
[538.711539] Dynamic subtest pipe-A-eDP-1: SKIP (0.806s)
[538.721766] Starting dynamic subtest: pipe-B-eDP-1
[539.839587] Dynamic subtest pipe-B-eDP-1: SKIP (1.126s)
[539.844266] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.936s)
[541.064448] [072/130] (476s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[541.164780] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[547.401392] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.241s)
[548.561325] [073/130] (469s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[548.659711] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[548.663830] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[548.800138] [074/130] (468s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[548.902036] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[556.277288] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.378s)
[556.429900] [075/130] (461s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[556.525254] Starting subtest: fbc-modesetfrombusy
[560.262973] Subtest fbc-modesetfrombusy: SUCCESS (3.741s)
[561.440875] [076/130] (456s left) gem_tiled_blits (basic)
[561.506357] Subtest basic: SKIP (0.000s)
[561.596402] [077/130] (456s left) gem_eio (context-create)
[561.819736] Starting subtest: context-create
[562.143363] Subtest context-create: SUCCESS (0.324s)
[562.310564] [078/130] (455s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[562.373283] Starting subtest: x-tiled-to-vebox-y-tiled
[562.377023] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[562.568534] [079/130] (455s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[562.683548] Starting subtest: basic-busy-flip-before-cursor-varying-size
[562.688131] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[562.839745] [080/130] (455s left) kms_plane_alpha_blend (alpha-7efc)
[562.944199] Starting subtest: alpha-7efc
[563.232811] Starting dynamic subtest: pipe-A-eDP-1
[563.808282] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.574s)
[563.832031] Starting dynamic subtest: pipe-D-eDP-1
[565.092244] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.268s)
[565.094545] Subtest alpha-7efc: SUCCESS (2.150s)
[565.906739] [081/130] (452s left) i915_selftest (perf)
[567.840209] Starting subtest: perf
[567.842945] Starting dynamic subtest: engine_cs
[572.259025] Dynamic subtest engine_cs: SUCCESS (4.418s)
[572.268192] Starting dynamic subtest: request
[579.528541] Dynamic subtest request: SUCCESS (7.268s)
[579.537310] Starting dynamic subtest: migrate
[583.877983] Dynamic subtest migrate: SUCCESS (4.348s)
[583.887416] Starting dynamic subtest: region
[589.663572] Dynamic subtest region: SUCCESS (5.784s)
[589.665976] Subtest perf: SUCCESS (21.824s)
[589.694436] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[589.695131] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[589.698155] [082/130] (428s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[591.130582] Starting subtest: short-flip-before-cursor-toggle
[592.065590] Subtest short-flip-before-cursor-toggle: SUCCESS (0.936s)
[592.969469] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[592.970713] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: kernel.kmod_is_loaded.i915: true
[592.975024] [083/130] (425s left) kms_pm_rpm (modeset-lpsp-stress)
[595.094285] Starting subtest: modeset-lpsp-stress
[610.884400] Subtest modeset-lpsp-stress: SUCCESS (15.790s)
[610.995755] [084/130] (407s left) gem_exec_flush (basic-wb-rw-before-default)
[611.071071] Starting subtest: basic-wb-rw-before-default
[616.523437] Subtest basic-wb-rw-before-default: SUCCESS (5.453s)
[616.723242] [085/130] (401s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[616.833656] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[617.591608] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.759s)
[617.776640] [086/130] (400s left) i915_query (query-regions-unallocated)
[617.829814] Starting subtest: query-regions-unallocated
[617.831620] Subtest query-regions-unallocated: SUCCESS (0.000s)
[617.943505] [087/130] (400s left) gem_exec_flush (basic-batch-kernel-default-wb)
[617.995619] Starting subtest: basic-batch-kernel-default-wb
[624.822738] Subtest basic-batch-kernel-default-wb: SUCCESS (6.828s)
[625.020997] [088/130] (393s left) syncobj_wait (multi-wait-all-signaled)
[625.082500] Starting subtest: multi-wait-all-signaled
[625.084817] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[625.211499] [089/130] (393s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[625.316302] Starting subtest: fbc-dirtyfb-ioctl
[625.318624] Starting dynamic subtest: A-eDP-1
[627.917040] Dynamic subtest A-eDP-1: SUCCESS (2.601s)
[627.919534] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.602s)
[628.729643] [090/130] (389s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[633.714052] Starting subtest: psr2-overlay-plane-update-continuous-sf
[633.716423] Starting dynamic subtest: pipe-A-eDP-1
[635.455759] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.741s)
[635.464494] Starting dynamic subtest: pipe-B-eDP-1
[637.193705] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.736s)
[637.196055] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (3.481s)
[638.029885] [091/130] (380s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[638.131756] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[638.136264] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[638.243813] [092/130] (380s left) kms_fbcon_fbt (fbc-suspend)
[638.292760] Starting subtest: fbc-suspend
[688.336038] Subtest fbc-suspend: SUCCESS (19.861s)
[689.423093] [093/130] (329s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[689.528875] Starting subtest: y-tiled-8bpp-rotate-270
[689.533120] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[689.659987] [094/130] (328s left) gem_exec_schedule (u-submit-early-slice)
[689.738643] Starting subtest: u-submit-early-slice
[689.740916] Starting dynamic subtest: rcs0
[689.777508] Dynamic subtest rcs0: SUCCESS (0.043s)
[689.789938] Starting dynamic subtest: bcs0
[689.811507] Dynamic subtest bcs0: SUCCESS (0.034s)
[689.821377] Starting dynamic subtest: vcs0
[689.850338] Dynamic subtest vcs0: SUCCESS (0.038s)
[689.860019] Starting dynamic subtest: vcs1
[689.886547] Dynamic subtest vcs1: SUCCESS (0.036s)
[689.896591] Starting dynamic subtest: vecs0
[689.921327] Dynamic subtest vecs0: SUCCESS (0.034s)
[689.930392] Starting dynamic subtest: ccs0
[689.959175] Dynamic subtest ccs0: SUCCESS (0.037s)
[689.961422] Subtest u-submit-early-slice: SUCCESS (0.225s)
[691.166448] [095/130] (327s left) vgem_basic (debugfs)
[691.203892] Starting subtest: debugfs
[691.206104] Subtest debugfs: SUCCESS (0.001s)
[691.285425] [096/130] (327s left) gen7_exec_parse (batch-without-end)
[691.342844] Subtest batch-without-end: SKIP (0.000s)
[691.416875] [097/130] (327s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[691.519109] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[713.456955] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (21.999s)
[713.637582] [098/130] (305s left) dmabuf_sync_file (export-basic)
[713.678877] Starting subtest: export-basic
[713.680547] Subtest export-basic: SUCCESS (0.001s)
[713.747693] [099/130] (304s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[713.837570] Starting subtest: random-ccs-data-y-tiled-ccs
[713.839561] Starting dynamic subtest: pipe-A-eDP-1
[713.860798] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[713.871654] Starting dynamic subtest: pipe-B-eDP-1
[713.890928] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[713.900174] Starting dynamic subtest: pipe-C-eDP-1
[713.919664] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[713.928561] Starting dynamic subtest: pipe-D-eDP-1
[713.949418] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[713.952873] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[716.059445] [100/130] (302s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[716.173592] Starting subtest: cursorB-vs-flipB-atomic
[716.178174] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[716.332899] [101/130] (302s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[716.391835] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[716.457061] [102/130] (302s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[716.577199] Starting subtest: protected-encrypted-src-copy-not-readible
[716.688616] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.113s)
[716.842281] [103/130] (302s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[716.934903] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[716.937206] Starting dynamic subtest: pipe-A-default-mode
[718.107118] Dynamic subtest pipe-A-default-mode: SKIP (1.170s)
[718.111552] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.171s)
[718.271852] [104/130] (300s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[718.375465] Starting subtest: fbcpsr-tiling-y
[718.379526] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[718.493044] [105/130] (300s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[719.033392] Starting subtest: syncobj-timeline-invalid-flags
[719.036058] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[720.202226] [106/130] (298s left) gem_basic (create-close)
[720.263492] Starting subtest: create-close
[720.267918] Subtest create-close: SUCCESS (0.000s)
[720.385386] [107/130] (298s left) api_intel_allocator (reloc-allocator)
[720.433652] Starting subtest: reloc-allocator
[720.436116] Starting dynamic subtest: basic
[720.438045] Dynamic subtest basic: SUCCESS (0.004s)
[720.450229] Starting dynamic subtest: parallel-one
[720.452467] Dynamic subtest parallel-one: SUCCESS (0.009s)
[720.462953] Starting dynamic subtest: print
[720.465158] Dynamic subtest print: SUCCESS (0.000s)
[720.474766] Starting dynamic subtest: reuse
[720.476983] Dynamic subtest reuse: SUCCESS (0.000s)
[720.486407] Starting dynamic subtest: fork-reopen-allocator
[720.488657] Dynamic subtest fork-reopen-allocator: SUCCESS (0.010s)
[720.490902] Subtest reloc-allocator: SUCCESS (0.025s)
[720.628585] [108/130] (298s left) kms_psr (pr-primary-blt)
[720.720057] Starting subtest: pr-primary-blt
[720.724125] Subtest pr-primary-blt: SKIP (0.000s)
[720.809156] [109/130] (298s left) gem_ctx_bad_destroy (double-destroy)
[720.855947] Starting subtest: double-destroy
[720.858314] Subtest double-destroy: SUCCESS (0.000s)
[720.943500] [110/130] (298s left) core_getclient
[721.111438] [111/130] (298s left) syncobj_wait (wait-all-delayed-signal)
[721.171943] Starting subtest: wait-all-delayed-signal
[721.276651] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[721.369753] [112/130] (297s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[721.466594] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[721.470521] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[721.593589] [113/130] (297s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[721.691482] Starting subtest: yf-tiled-16bpp-rotate-0
[721.695897] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[721.823790] [114/130] (297s left) kms_async_flips (test-time-stamp)
[721.928083] Starting subtest: test-time-stamp
[722.299465] Starting dynamic subtest: pipe-A-eDP-1
[723.083320] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.781s)
[723.449617] Starting dynamic subtest: pipe-B-eDP-1
[724.221278] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.778s)
[724.594507] Starting dynamic subtest: pipe-C-eDP-1
[725.354193] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.765s)
[725.728802] Starting dynamic subtest: pipe-D-eDP-1
[726.490909] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.770s)
[726.493548] Subtest test-time-stamp: SUCCESS (4.561s)
[727.681325] [115/130] (291s left) kms_invalid_mode (bad-vsync-end)
[727.789703] Starting subtest: bad-vsync-end
[727.792073] Starting dynamic subtest: pipe-A-eDP-1
[728.072728] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.285s)
[728.084783] Starting dynamic subtest: pipe-B-eDP-1
[728.087145] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[728.096383] Starting dynamic subtest: pipe-C-eDP-1
[728.098268] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[728.107171] Starting dynamic subtest: pipe-D-eDP-1
[728.109303] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[728.111184] Subtest bad-vsync-end: SUCCESS (0.290s)
[728.904125] [116/130] (290s left) gem_exec_suspend (basic)
[728.978118] Starting subtest: basic
[728.980520] Starting dynamic subtest: smem
[731.068903] Dynamic subtest smem: SUCCESS (2.088s)
[731.071809] Subtest basic: SUCCESS (2.090s)
[731.276203] [117/130] (288s left) api_intel_bb (blit-noreloc-purge-cache)
[731.330409] Starting subtest: blit-noreloc-purge-cache
[731.338923] Subtest blit-noreloc-purge-cache: SUCCESS (0.011s)
[731.504193] [118/130] (287s left) kms_plane (pixel-format-source-clamping)
[731.604926] Starting subtest: pixel-format-source-clamping
[731.632327] Starting dynamic subtest: pipe-A-plane-0
[736.487999] Dynamic subtest pipe-A-plane-0: SUCCESS (4.855s)
[736.499463] Starting dynamic subtest: pipe-A-plane-3
[738.743433] Dynamic subtest pipe-A-plane-3: SUCCESS (2.255s)
[738.753482] Starting dynamic subtest: pipe-A-plane-5
[738.755504] Dynamic subtest pipe-A-plane-5: SUCCESS (0.000s)
[739.869150] Starting dynamic subtest: pipe-B-plane-0
[744.581431] Dynamic subtest pipe-B-plane-0: SUCCESS (4.722s)
[744.591324] Starting dynamic subtest: pipe-B-plane-3
[746.859232] Dynamic subtest pipe-B-plane-3: SUCCESS (2.277s)
[746.868994] Starting dynamic subtest: pipe-B-plane-5
[746.871172] Dynamic subtest pipe-B-plane-5: SUCCESS (0.000s)
[747.164059] Subtest pixel-format-source-clamping: SUCCESS (15.560s)
[748.067584] [119/130] (271s left) i915_query (query-topology-kernel-writes)
[748.130764] Starting subtest: query-topology-kernel-writes
[748.132838] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[748.225449] [120/130] (271s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[748.602335] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[749.449437] [121/130] (270s left) gem_exec_reloc (basic-cpu-active)
[749.527884] Subtest basic-cpu-active: SKIP (0.000s)
[749.613541] [122/130] (270s left) gem_exec_fence (concurrent)
[749.796224] Starting subtest: concurrent
[749.800417] Subtest concurrent: SKIP (0.000s)
[751.209210] [123/130] (268s left) perf (short-reads)
[751.349079] Starting subtest: short-reads
[751.362239] Subtest short-reads: SUCCESS (0.016s)
[751.404402] [124/130] (268s left) dumb_buffer (create-clear)
[751.453596] Starting subtest: create-clear
[785.782874] Subtest create-clear: SUCCESS (34.339s)
[785.919269] [125/130] (233s left) kms_psr2_su (page_flip-p010)
[785.990053] Subtest page_flip-P010: SKIP (0.000s)
[786.048436] [126/130] (233s left) i915_pm_rc6_residency (rc6-idle)
[786.108180] Starting subtest: rc6-idle
[786.154618] Starting dynamic subtest: gt0-rcs0
[810.576001] Dynamic subtest gt0-rcs0: SUCCESS (24.415s)
[810.587608] Starting dynamic subtest: gt0-bcs0
[834.781416] Dynamic subtest gt0-bcs0: SUCCESS (24.205s)
[834.793267] Starting dynamic subtest: gt0-ccs0
[859.128700] Dynamic subtest gt0-ccs0: SUCCESS (24.349s)
[859.138585] Starting dynamic subtest: gt1-vcs0
[865.303642] Dynamic subtest gt1-vcs0: SUCCESS (6.172s)
[865.316665] Starting dynamic subtest: gt1-vecs0
[871.475719] Dynamic subtest gt1-vecs0: SUCCESS (6.171s)
[871.478926] Subtest rc6-idle: SUCCESS (85.371s)
[871.576786] [127/130] (148s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[871.672877] Starting subtest: plane-scaler-unity-scaling-with-rotation
[871.674830] Starting dynamic subtest: pipe-A
[871.898860] Dynamic subtest pipe-A: SUCCESS (0.228s)
[871.910961] Starting dynamic subtest: pipe-B
[873.230680] Dynamic subtest pipe-B: SUCCESS (1.331s)
[873.240918] Starting dynamic subtest: pipe-C
[874.561525] Dynamic subtest pipe-C: SUCCESS (1.331s)
[874.572352] Starting dynamic subtest: pipe-D
[875.891637] Dynamic subtest pipe-D: SUCCESS (1.330s)
[875.894065] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.222s)
[877.099224] [128/130] (142s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[877.193587] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[877.197680] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[877.337680] [129/130] (142s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[877.452733] Starting subtest: cursor-vs-flip-legacy
[906.690628] Subtest cursor-vs-flip-legacy: SUCCESS (29.246s)
[907.533848] [130/130] (112s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[907.588137] Starting subtest: multi-wait-available-submitted-signaled
[907.590314] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[907.691369] 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.