[54.373127] Initializing watchdogs
[54.373184]   /dev/watchdog0
[54.386518] [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.390691] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[54.392877] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[54.396196] [001/130] (960s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[54.508104] Starting subtest: plane-upscale-20x20-with-rotation
[54.510396] Starting dynamic subtest: pipe-A
[54.741891] Dynamic subtest pipe-A: SUCCESS (0.235s)
[54.750058] Starting dynamic subtest: pipe-B
[56.052253] Dynamic subtest pipe-B: SUCCESS (1.308s)
[56.060919] Starting dynamic subtest: pipe-C
[57.390399] Dynamic subtest pipe-C: SUCCESS (1.335s)
[57.398428] Starting dynamic subtest: pipe-D
[58.715584] Dynamic subtest pipe-D: SUCCESS (1.323s)
[58.718048] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.202s)
[59.911146] [002/130] (955s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-move)
[60.006956] Starting subtest: fbc-2p-scndscrn-cur-indfb-move
[60.011094] Subtest fbc-2p-scndscrn-cur-indfb-move: SKIP (0.000s)
[60.103586] [003/130] (954s left) kms_big_fb (linear-8bpp-rotate-180)
[60.219176] Starting subtest: linear-8bpp-rotate-180
[61.388847] Subtest linear-8bpp-rotate-180: SUCCESS (1.168s)
[61.505995] [004/130] (953s left) kms_flip (blocking-absolute-wf_vblank-interruptible)
[61.612421] Starting subtest: blocking-absolute-wf_vblank-interruptible
[61.614845] Starting dynamic subtest: A-eDP1
[70.411669] Dynamic subtest A-eDP1: SUCCESS (8.757s)
[70.419303] Starting dynamic subtest: B-eDP1
[79.145071] Dynamic subtest B-eDP1: SUCCESS (8.727s)
[79.154670] Starting dynamic subtest: C-eDP1
[87.873313] Dynamic subtest C-eDP1: SUCCESS (8.713s)
[87.882095] Starting dynamic subtest: D-eDP1
[96.604385] Dynamic subtest D-eDP1: SUCCESS (8.727s)
[96.606766] Subtest blocking-absolute-wf_vblank-interruptible: SUCCESS (34.932s)
[97.798101] [005/130] (917s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu)
[97.891013] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu
[97.895281] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s)
[97.989177] [006/130] (917s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu)
[98.093656] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu
[104.646480] Subtest fbcpsr-1p-offscren-pri-indfb-draw-mmap-cpu: SUCCESS (6.555s)
[104.803726] [007/130] (910s left) gem_ctx_exec (basic-norecovery)
[104.864045] Starting subtest: basic-norecovery
[105.137190] Subtest basic-norecovery: SUCCESS (0.273s)
[105.288915] [008/130] (909s left) kms_psr (psr-primary-page-flip)
[105.386142] Starting subtest: psr-primary-page-flip
[105.388433] Starting dynamic subtest: eDP-1
[107.138995] Dynamic subtest eDP-1: SUCCESS (1.753s)
[107.141367] Subtest psr-primary-page-flip: SUCCESS (1.753s)
[108.021303] [009/130] (907s left) core_getstats
[108.191354] [010/130] (907s left) i915_hangman (detector)
[108.254473] Starting subtest: detector
[108.256492] Starting dynamic subtest: rcs0
[119.858045] Dynamic subtest rcs0: SUCCESS (11.603s)
[119.868238] Starting dynamic subtest: bcs0
[130.867723] Dynamic subtest bcs0: SUCCESS (11.007s)
[130.877943] Starting dynamic subtest: vcs0
[142.836377] Dynamic subtest vcs0: SUCCESS (11.967s)
[142.846721] Starting dynamic subtest: vcs1
[154.803326] Dynamic subtest vcs1: SUCCESS (11.965s)
[154.814081] Starting dynamic subtest: vecs0
[165.874987] Dynamic subtest vecs0: SUCCESS (11.071s)
[165.885659] Starting dynamic subtest: ccs0
[177.842738] Dynamic subtest ccs0: SUCCESS (11.966s)
[177.845905] Subtest detector: SUCCESS (69.585s)
[177.929340] [011/130] (837s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[178.021309] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[178.023687] Starting dynamic subtest: pipe-A-eDP-1
[180.046227] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.024s)
[180.053191] Starting dynamic subtest: pipe-B-eDP-1
[182.123393] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.075s)
[182.131030] Starting dynamic subtest: pipe-C-eDP-1
[184.160857] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.036s)
[184.168147] Starting dynamic subtest: pipe-D-eDP-1
[186.178281] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.019s)
[186.180595] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.157s)
[187.335215] [012/130] (827s left) gem_lmem_swapping (parallel-random-engines)
[187.381864] Subtest parallel-random-engines: SKIP (0.000s)
[187.419970] [013/130] (827s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[187.527618] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[187.532074] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[187.651230] [014/130] (827s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[187.758088] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip
[187.762465] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[187.874191] [015/130] (827s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[187.974562] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[187.978683] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[188.084656] [016/130] (827s left) gem_ctx_persistence (legacy-engines-hostile)
[188.148719] Starting subtest: legacy-engines-hostile
[188.151105] Starting dynamic subtest: render
[188.301160] Dynamic subtest render: SUCCESS (0.153s)
[188.311521] Starting dynamic subtest: bsd1
[188.423082] Dynamic subtest bsd1: SUCCESS (0.120s)
[188.433090] Starting dynamic subtest: bsd2
[188.543534] Dynamic subtest bsd2: SUCCESS (0.118s)
[188.553482] Starting dynamic subtest: blt
[188.617149] Dynamic subtest blt: SUCCESS (0.072s)
[188.625973] Starting dynamic subtest: vebox
[188.766513] Dynamic subtest vebox: SUCCESS (0.148s)
[188.768683] Subtest legacy-engines-hostile: SUCCESS (0.619s)
[188.866831] [017/130] (826s left) prime_vgem (shrink)
[188.974136] Starting subtest: shrink
[188.989504] Subtest shrink: SUCCESS (0.014s)
[189.113763] [FACT prime_vgem (shrink)] new: kernel.kmod_is_loaded.vgem: true
[189.116571] [018/130] (826s left) gem_exec_schedule (preempt-queue-chain)
[189.191463] Subtest preempt-queue-chain: SKIP (0.000s)
[189.488844] [019/130] (826s left) kms_flip (flip-vs-panning-vs-hang)
[189.587672] Starting subtest: flip-vs-panning-vs-hang
[189.589862] Starting dynamic subtest: A-eDP1
[240.381028] Dynamic subtest A-eDP1: SUCCESS (50.796s)
[240.389376] Starting dynamic subtest: D-eDP1
[290.366589] Dynamic subtest D-eDP1: SUCCESS (49.975s)
[290.369110] Subtest flip-vs-panning-vs-hang: SUCCESS (100.774s)
[291.551546] [020/130] (724s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[291.785298] Starting subtest: cursor-rapid-movement-256x256
[291.790517] Starting dynamic subtest: pipe-A-eDP-1
[292.350205] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.560s)
[292.374799] Starting dynamic subtest: pipe-D-eDP-1
[293.557255] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.186s)
[293.559683] Subtest cursor-rapid-movement-256x256: SUCCESS (1.769s)
[294.408764] [021/130] (721s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[294.508395] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[294.512456] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[294.645917] [022/130] (721s left) gem_exec_balancer (sliced)
[294.699338] Starting subtest: sliced
[294.703887] Subtest sliced: SKIP (0.000s)
[295.034317] [023/130] (720s left) kms_psr (fbc-psr-suspend)
[295.122402] Starting subtest: fbc-psr-suspend
[295.124796] Starting dynamic subtest: eDP-1
[295.129122] Dynamic subtest eDP-1: SKIP (0.000s)
[295.133437] Subtest fbc-psr-suspend: SKIP (0.001s)
[295.221832] [024/130] (720s left) kms_frontbuffer_tracking (fbcpsr-stridechange)
[295.308493] Starting subtest: fbcpsr-stridechange
[301.399375] Subtest fbcpsr-stridechange: SUCCESS (6.093s)
[301.571621] [025/130] (714s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[301.677756] Starting subtest: 4-tiled-32bpp-rotate-180
[303.122511] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.445s)
[303.307796] [026/130] (712s left) gem_mmap_gtt (basic-read-write-distinct)
[303.360360] Subtest basic-read-write-distinct: SKIP (0.000s)
[303.428874] [027/130] (712s left) perf_pmu (busy-accuracy-2)
[303.465672] Starting subtest: busy-accuracy-2
[303.467678] Starting dynamic subtest: rcs0
[306.511759] Dynamic subtest rcs0: SUCCESS (3.042s)
[306.522197] Starting dynamic subtest: bcs0
[309.621822] Dynamic subtest bcs0: SUCCESS (3.056s)
[309.631553] Starting dynamic subtest: vcs0
[312.618685] Dynamic subtest vcs0: SUCCESS (3.047s)
[312.629993] Starting dynamic subtest: vcs1
[315.714059] Dynamic subtest vcs1: SUCCESS (3.045s)
[315.723696] Starting dynamic subtest: vecs0
[318.760065] Dynamic subtest vecs0: SUCCESS (3.044s)
[318.769429] Starting dynamic subtest: ccs0
[321.824805] Dynamic subtest ccs0: SUCCESS (3.062s)
[321.827185] Subtest busy-accuracy-2: SUCCESS (18.305s)
[321.945487] [028/130] (693s left) gem_create (create-ext-set-pat)
[322.103755] Starting subtest: create-ext-set-pat
[322.106695] Subtest create-ext-set-pat: SUCCESS (0.001s)
[322.223954] [029/130] (693s left) gem_exec_reloc (basic-wc-cpu-active)
[322.280845] Subtest basic-wc-cpu-active: SKIP (0.000s)
[322.366367] [030/130] (693s left) gem_ctx_persistence (userptr)
[322.416075] Starting subtest: userptr
[322.618773] Subtest userptr: SUCCESS (0.202s)
[322.717179] [031/130] (693s left) syncobj_wait (multi-wait-all-for-submit-unsubmitted)
[322.758855] Starting subtest: multi-wait-all-for-submit-unsubmitted
[322.864479] Subtest multi-wait-all-for-submit-unsubmitted: SUCCESS (0.105s)
[322.961205] [032/130] (693s left) gem_busy (close-race)
[323.024973] Starting subtest: close-race
[346.898214] Subtest close-race: SUCCESS (23.870s)
[347.307344] [033/130] (668s left) kms_addfb_basic (small-bo)
[347.464864] Starting subtest: small-bo
[347.467110] Subtest small-bo: SUCCESS (0.000s)
[347.594603] [034/130] (668s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-render)
[347.700836] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-render
[352.126489] Subtest fbc-1p-primscrn-pri-shrfb-draw-render: SUCCESS (4.430s)
[353.325378] [035/130] (662s left) syncobj_timeline (invalid-transfer-non-existent-point)
[353.383536] Starting subtest: invalid-transfer-non-existent-point
[353.385886] Subtest invalid-transfer-non-existent-point: SUCCESS (0.000s)
[353.519413] [036/130] (662s left) kms_lease (lease-revoke)
[353.611212] Starting subtest: lease-revoke
[353.613498] Starting dynamic subtest: pipe-A-eDP-1
[353.649360] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.038s)
[353.658712] Starting dynamic subtest: pipe-B-eDP-1
[353.671677] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.024s)
[353.685421] Starting dynamic subtest: pipe-C-eDP-1
[353.701815] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.029s)
[353.711197] Starting dynamic subtest: pipe-D-eDP-1
[353.732027] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.027s)
[353.734133] Subtest lease-revoke: SUCCESS (0.121s)
[353.901479] [037/130] (662s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[353.993605] Starting subtest: psr-rgb101010-draw-pwrite
[361.522117] Subtest psr-rgb101010-draw-pwrite: SUCCESS (7.532s)
[361.638720] [038/130] (654s left) gem_mmap_offset (partial-remap)
[361.701475] Starting subtest: partial-remap
[361.703736] Starting dynamic subtest: smem0
[361.712607] Dynamic subtest smem0: SUCCESS (0.004s)
[361.714528] Subtest partial-remap: SUCCESS (0.005s)
[361.834698] [039/130] (654s left) gem_exec_whisper (basic-fds-priority)
[361.904096] Starting subtest: basic-fds-priority
[385.605121] Subtest basic-fds-priority: SUCCESS (23.694s)
[385.811065] [040/130] (630s left) gem_softpin (allocator-nopin)
[385.863646] Starting subtest: allocator-nopin
[385.975343] Subtest allocator-nopin: SUCCESS (0.110s)
[386.135431] [041/130] (630s left) gem_exec_reloc (basic-concurrent16)
[386.181602] Subtest basic-concurrent16: SKIP (0.000s)
[386.270035] [042/130] (630s left) kms_vrr (negative-basic)
[386.367878] Starting subtest: negative-basic
[386.374325] Starting dynamic subtest: pipe-A-eDP-1
[391.851497] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.414s)
[392.095022] Subtest negative-basic: SUCCESS (5.726s)
[392.935822] [043/130] (623s left) syncobj_timeline (single-wait-all-signaled)
[392.988850] Starting subtest: single-wait-all-signaled
[392.991140] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[393.117793] [044/130] (623s left) kms_psr (fbc-psr2-primary-page-flip)
[393.205029] Starting subtest: fbc-psr2-primary-page-flip
[393.206905] Starting dynamic subtest: eDP-1
[393.210953] Dynamic subtest eDP-1: SKIP (0.000s)
[393.214977] Subtest fbc-psr2-primary-page-flip: SKIP (0.001s)
[393.288235] [045/130] (623s left) kms_feature_discovery (display-4x)
[393.381780] Starting subtest: display-4x
[393.386137] Subtest display-4x: SKIP (0.000s)
[393.481025] [046/130] (623s left) kms_pm_rpm (universal-planes)
[395.022804] Starting subtest: universal-planes
[395.025448] Starting dynamic subtest: plane-33
[403.297904] Dynamic subtest plane-33: SUCCESS (8.274s)
[403.310701] Starting dynamic subtest: plane-43
[411.590686] Dynamic subtest plane-43: SUCCESS (8.290s)
[411.603588] Starting dynamic subtest: plane-53
[419.865730] Dynamic subtest plane-53: SUCCESS (8.274s)
[419.875501] Starting dynamic subtest: plane-63
[428.248420] Dynamic subtest plane-63: SUCCESS (8.380s)
[428.261038] Starting dynamic subtest: plane-73
[436.638502] Dynamic subtest plane-73: SUCCESS (8.388s)
[436.650793] Starting dynamic subtest: plane-83
[444.918284] Dynamic subtest plane-83: SUCCESS (8.277s)
[444.921346] Subtest universal-planes: SUCCESS (49.893s)
[445.317383] [047/130] (571s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[445.435634] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-180-hflip
[446.436888] Subtest x-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.001s)
[446.583306] [048/130] (570s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[446.646091] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[446.737769] [049/130] (570s left) kms_scaling_modes (scaling-mode-full-aspect)
[446.832745] Starting subtest: scaling-mode-full-aspect
[446.834604] Starting dynamic subtest: pipe-A-eDP-1
[446.929762] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.096s)
[446.941298] Starting dynamic subtest: pipe-B-eDP-1
[448.119468] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.188s)
[448.128649] Starting dynamic subtest: pipe-C-eDP-1
[449.225560] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.105s)
[449.235534] Starting dynamic subtest: pipe-D-eDP-1
[450.363299] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.137s)
[450.365507] Subtest scaling-mode-full-aspect: SUCCESS (3.530s)
[451.566257] [050/130] (565s left) kms_color (degamma)
[451.658123] Starting subtest: degamma
[451.659931] Starting dynamic subtest: pipe-A-eDP-1
[452.341253] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.685s)
[452.350428] Starting dynamic subtest: pipe-B-eDP-1
[453.714989] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.373s)
[453.726535] Starting dynamic subtest: pipe-C-eDP-1
[455.008494] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.292s)
[455.018933] Starting dynamic subtest: pipe-D-eDP-1
[456.338907] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.329s)
[456.341408] Subtest degamma: SUCCESS (4.682s)
[457.179155] [051/130] (559s left) kms_cursor_crc (cursor-offscreen-256x85)
[457.442743] Starting subtest: cursor-offscreen-256x85
[457.489567] Subtest cursor-offscreen-256x85: SKIP (0.040s)
[457.710671] [052/130] (559s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[457.839398] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[457.843931] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[457.995138] [053/130] (558s left) perf_pmu (busy-start)
[458.035844] Starting subtest: busy-start
[458.038221] Starting dynamic subtest: rcs0
[460.588512] Dynamic subtest rcs0: SUCCESS (2.552s)
[460.598761] Starting dynamic subtest: bcs0
[463.159973] Dynamic subtest bcs0: SUCCESS (2.547s)
[463.170692] Starting dynamic subtest: vcs0
[466.899332] Dynamic subtest vcs0: SUCCESS (3.759s)
[466.910651] Starting dynamic subtest: vcs1
[470.931036] Dynamic subtest vcs1: SUCCESS (4.029s)
[470.942812] Starting dynamic subtest: vecs0
[474.898449] Dynamic subtest vecs0: SUCCESS (3.966s)
[474.909908] Starting dynamic subtest: ccs0
[477.469597] Dynamic subtest ccs0: SUCCESS (2.549s)
[477.471688] Subtest busy-start: SUCCESS (19.412s)
[477.589784] [054/130] (539s left) gem_spin_batch (spin-each)
[477.653409] Starting subtest: spin-each
[481.016615] Subtest spin-each: SUCCESS (3.363s)
[482.910648] [055/130] (534s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[483.014497] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[483.018822] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[483.130670] [056/130] (533s left) kms_chamelium_frames (hdmi-crc-multiple)
[483.508932] Subtest hdmi-crc-multiple: SKIP (0.000s)
[484.284228] [057/130] (532s left) gen9_exec_parse (bb-start-out)
[484.352782] Subtest bb-start-out: SKIP (0.000s)
[484.441122] [058/130] (532s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[484.559805] Starting subtest: cursorB-vs-flipA-atomic-transitions
[484.564280] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[484.728285] [059/130] (532s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[484.831544] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[484.835866] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[484.962276] [060/130] (532s left) kms_ccs (bad-pixel-format-4-tiled-dg2-rc-ccs-cc)
[485.044962] Starting subtest: bad-pixel-format-4-tiled-dg2-rc-ccs-cc
[485.047207] Starting dynamic subtest: pipe-A-eDP-1
[485.068850] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[485.079009] Starting dynamic subtest: pipe-B-eDP-1
[485.099764] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[485.108300] Starting dynamic subtest: pipe-C-eDP-1
[485.129326] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[485.137816] Starting dynamic subtest: pipe-D-eDP-1
[485.159764] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[485.164340] Subtest bad-pixel-format-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s)
[487.214501] [061/130] (530s left) kms_flip (absolute-wf_vblank)
[487.323423] Starting subtest: absolute-wf_vblank
[487.325323] Starting dynamic subtest: A-eDP1
[496.106922] Dynamic subtest A-eDP1: SUCCESS (8.748s)
[496.118606] Starting dynamic subtest: B-eDP1
[504.834743] Dynamic subtest B-eDP1: SUCCESS (8.729s)
[504.844647] Starting dynamic subtest: C-eDP1
[513.566209] Dynamic subtest C-eDP1: SUCCESS (8.720s)
[513.575332] Starting dynamic subtest: D-eDP1
[522.295507] Dynamic subtest D-eDP1: SUCCESS (8.724s)
[522.297896] Subtest absolute-wf_vblank: SUCCESS (34.929s)
[523.492308] [062/130] (493s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled)
[523.552480] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled
[523.656303] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.104s)
[523.766369] [063/130] (493s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-signaled)
[523.810547] Starting subtest: invalid-multi-wait-all-unsubmitted-signaled
[523.812829] Subtest invalid-multi-wait-all-unsubmitted-signaled: SUCCESS (0.000s)
[523.926879] [064/130] (493s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible)
[524.024020] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible
[524.034750] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.010s)
[524.137071] [065/130] (493s left) kms_cursor_crc (cursor-onscreen-128x42)
[524.336101] Starting subtest: cursor-onscreen-128x42
[524.377241] Subtest cursor-onscreen-128x42: SKIP (0.036s)
[524.618226] [066/130] (492s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[524.711124] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[524.712669] Starting dynamic subtest: pipe-A-eDP-1
[524.759543] Dynamic subtest pipe-A-eDP-1: SKIP (0.002s)
[524.767998] Starting dynamic subtest: pipe-B-eDP-1
[524.814165] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[524.822687] Starting dynamic subtest: pipe-C-eDP-1
[524.869273] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[524.879278] Starting dynamic subtest: pipe-D-eDP-1
[524.925697] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[524.929165] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.010s)
[527.061638] [067/130] (490s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs)
[527.164742] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs
[527.167201] Starting dynamic subtest: pipe-A-eDP-1
[527.190996] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[527.206624] Starting dynamic subtest: pipe-B-eDP-1
[527.229080] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[527.239604] Starting dynamic subtest: pipe-C-eDP-1
[527.261194] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[527.269898] Starting dynamic subtest: pipe-D-eDP-1
[527.291698] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[527.296107] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs: SKIP (0.005s)
[529.349547] [068/130] (488s left) kms_vblank (query-idle)
[529.438826] Starting subtest: query-idle
[529.441126] Starting dynamic subtest: pipe-A-eDP-1
[531.193860] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.752s)
[531.207180] Starting dynamic subtest: pipe-D-eDP-1
[533.671283] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.474s)
[533.673154] Subtest query-idle: SUCCESS (4.229s)
[534.526232] [069/130] (483s left) gem_exec_reloc (basic-cpu-read-active)
[534.587361] Subtest basic-cpu-read-active: SKIP (0.000s)
[534.664818] [070/130] (482s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[534.766804] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[534.770981] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[534.873345] [071/130] (482s left) kms_psr2_sf (fbc-psr2-plane-move-sf-dmg-area)
[539.883392] Starting subtest: fbc-psr2-plane-move-sf-dmg-area
[539.886052] Starting dynamic subtest: pipe-A-eDP-1
[540.678441] Dynamic subtest pipe-A-eDP-1: SKIP (0.790s)
[540.688163] Starting dynamic subtest: pipe-B-eDP-1
[541.807587] Dynamic subtest pipe-B-eDP-1: SKIP (1.127s)
[541.812213] Subtest fbc-psr2-plane-move-sf-dmg-area: SKIP (1.921s)
[543.011357] [072/130] (474s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-shrfb-draw-pwrite)
[543.101290] Starting subtest: fbc-1p-primscrn-pri-shrfb-draw-pwrite
[549.207988] Subtest fbc-1p-primscrn-pri-shrfb-draw-pwrite: SUCCESS (6.110s)
[550.395682] [073/130] (467s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-blt)
[550.495240] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-blt
[550.499216] Subtest psr-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s)
[550.617643] [074/130] (467s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[550.709928] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[558.044336] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.336s)
[558.202584] [075/130] (459s left) kms_frontbuffer_tracking (fbc-modesetfrombusy)
[558.302136] Starting subtest: fbc-modesetfrombusy
[562.081885] Subtest fbc-modesetfrombusy: SUCCESS (3.785s)
[563.265860] [076/130] (454s left) gem_tiled_blits (basic)
[563.322630] Subtest basic: SKIP (0.000s)
[563.398765] [077/130] (454s left) gem_eio (context-create)
[563.610707] Starting subtest: context-create
[563.914842] Subtest context-create: SUCCESS (0.303s)
[564.081988] [078/130] (453s left) gem_render_copy (x-tiled-to-vebox-y-tiled)
[564.144322] Starting subtest: x-tiled-to-vebox-y-tiled
[564.148953] Subtest x-tiled-to-vebox-y-tiled: SKIP (0.000s)
[564.345687] [079/130] (453s left) kms_cursor_legacy (basic-busy-flip-before-cursor-varying-size)
[564.459086] Starting subtest: basic-busy-flip-before-cursor-varying-size
[564.463810] Subtest basic-busy-flip-before-cursor-varying-size: SKIP (0.000s)
[564.614044] [080/130] (453s left) kms_plane_alpha_blend (alpha-7efc)
[564.719204] Starting subtest: alpha-7efc
[565.001445] Starting dynamic subtest: pipe-A-eDP-1
[565.588096] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.585s)
[565.613455] Starting dynamic subtest: pipe-D-eDP-1
[566.927301] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.323s)
[566.929807] Subtest alpha-7efc: SUCCESS (2.210s)
[567.729144] [081/130] (450s left) i915_selftest (perf)
[571.259200] Starting subtest: perf
[571.261675] Starting dynamic subtest: engine_cs
[575.670846] Dynamic subtest engine_cs: SUCCESS (4.410s)
[575.680164] Starting dynamic subtest: request
[582.973328] Dynamic subtest request: SUCCESS (7.301s)
[582.982630] Starting dynamic subtest: migrate
[587.385406] Dynamic subtest migrate: SUCCESS (4.409s)
[587.394990] Starting dynamic subtest: region
[593.545112] Dynamic subtest region: SUCCESS (6.159s)
[593.547599] Subtest perf: SUCCESS (22.286s)
[593.577574] [FACT i915_selftest (perf)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[593.578304] [FACT i915_selftest (perf)] deleted: kernel.kmod_is_loaded.i915: true
[593.581496] [082/130] (424s left) kms_cursor_legacy (short-flip-before-cursor-toggle)
[595.087825] Starting subtest: short-flip-before-cursor-toggle
[596.040441] Subtest short-flip-before-cursor-toggle: SUCCESS (0.952s)
[596.864577] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[596.865698] [FACT kms_cursor_legacy (short-flip-before-cursor-toggle)] new: kernel.kmod_is_loaded.i915: true
[596.869318] [083/130] (421s left) kms_pm_rpm (modeset-lpsp-stress)
[599.091891] Starting subtest: modeset-lpsp-stress
[619.282257] Subtest modeset-lpsp-stress: SUCCESS (20.191s)
[619.399507] [084/130] (398s left) gem_exec_flush (basic-wb-rw-before-default)
[619.459861] Starting subtest: basic-wb-rw-before-default
[624.945102] Subtest basic-wb-rw-before-default: SUCCESS (5.448s)
[625.108205] [085/130] (393s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip)
[625.219279] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip
[625.988378] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip-async-flip: SUCCESS (0.770s)
[626.198072] [086/130] (392s left) i915_query (query-regions-unallocated)
[626.267879] Starting subtest: query-regions-unallocated
[626.270248] Subtest query-regions-unallocated: SUCCESS (0.000s)
[626.391375] [087/130] (391s left) gem_exec_flush (basic-batch-kernel-default-wb)
[626.447227] Starting subtest: basic-batch-kernel-default-wb
[633.300241] Subtest basic-batch-kernel-default-wb: SUCCESS (6.857s)
[633.500229] [088/130] (384s left) syncobj_wait (multi-wait-all-signaled)
[633.561708] Starting subtest: multi-wait-all-signaled
[633.564151] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[633.676149] [089/130] (384s left) kms_dirtyfb (fbc-dirtyfb-ioctl)
[633.768855] Starting subtest: fbc-dirtyfb-ioctl
[633.771335] Starting dynamic subtest: A-eDP-1
[636.418476] Dynamic subtest A-eDP-1: SUCCESS (2.648s)
[636.420764] Subtest fbc-dirtyfb-ioctl: SUCCESS (2.650s)
[637.309362] [090/130] (381s left) kms_psr2_sf (psr2-overlay-plane-update-continuous-sf)
[642.259656] Starting subtest: psr2-overlay-plane-update-continuous-sf
[642.262126] Starting dynamic subtest: pipe-A-eDP-1
[644.017405] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.756s)
[644.027079] Starting dynamic subtest: pipe-B-eDP-1
[645.752208] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.733s)
[645.754611] Subtest psr2-overlay-plane-update-continuous-sf: SUCCESS (3.493s)
[646.610555] [091/130] (371s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt)
[646.709219] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt
[646.713589] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s)
[646.842635] [092/130] (371s left) kms_fbcon_fbt (fbc-suspend)
[646.916245] Starting subtest: fbc-suspend
[695.724274] Subtest fbc-suspend: SUCCESS (18.940s)
[696.862422] [093/130] (321s left) kms_big_fb (y-tiled-8bpp-rotate-270)
[696.964415] Starting subtest: y-tiled-8bpp-rotate-270
[696.968885] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s)
[697.075193] [094/130] (321s left) gem_exec_schedule (u-submit-early-slice)
[697.151934] Starting subtest: u-submit-early-slice
[697.153810] Starting dynamic subtest: rcs0
[697.192849] Dynamic subtest rcs0: SUCCESS (0.045s)
[697.205829] Starting dynamic subtest: bcs0
[697.230430] Dynamic subtest bcs0: SUCCESS (0.040s)
[697.240713] Starting dynamic subtest: vcs0
[697.272057] Dynamic subtest vcs0: SUCCESS (0.041s)
[697.282723] Starting dynamic subtest: vcs1
[697.307812] Dynamic subtest vcs1: SUCCESS (0.035s)
[697.317099] Starting dynamic subtest: vecs0
[697.346131] Dynamic subtest vecs0: SUCCESS (0.038s)
[697.355673] Starting dynamic subtest: ccs0
[697.386011] Dynamic subtest ccs0: SUCCESS (0.039s)
[697.388321] Subtest u-submit-early-slice: SUCCESS (0.241s)
[698.802060] [095/130] (319s left) vgem_basic (debugfs)
[698.842677] Starting subtest: debugfs
[698.844877] Subtest debugfs: SUCCESS (0.000s)
[698.916200] [096/130] (319s left) gen7_exec_parse (batch-without-end)
[698.978523] Subtest batch-without-end: SKIP (0.000s)
[699.063975] [097/130] (319s left) kms_frontbuffer_tracking (fbcpsr-1p-pri-indfb-multidraw)
[699.164039] Starting subtest: fbcpsr-1p-pri-indfb-multidraw
[721.112862] Subtest fbcpsr-1p-pri-indfb-multidraw: SUCCESS (21.924s)
[721.270267] [098/130] (297s left) dmabuf_sync_file (export-basic)
[721.311620] Starting subtest: export-basic
[721.313268] Subtest export-basic: SUCCESS (0.001s)
[721.394249] [099/130] (297s left) kms_ccs (random-ccs-data-y-tiled-ccs)
[721.488042] Starting subtest: random-ccs-data-y-tiled-ccs
[721.489985] Starting dynamic subtest: pipe-A-eDP-1
[721.510955] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[721.522401] Starting dynamic subtest: pipe-B-eDP-1
[721.542039] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[721.552239] Starting dynamic subtest: pipe-C-eDP-1
[721.572277] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[721.580414] Starting dynamic subtest: pipe-D-eDP-1
[721.600507] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[721.604028] Subtest random-ccs-data-y-tiled-ccs: SKIP (0.004s)
[723.720722] [100/130] (295s left) kms_cursor_legacy (cursorb-vs-flipb-atomic)
[723.909939] Starting subtest: cursorB-vs-flipB-atomic
[723.914781] Subtest cursorB-vs-flipB-atomic: SKIP (0.001s)
[724.120270] [101/130] (294s left) gem_mmap_gtt (cpuset-medium-copy-xy)
[724.194473] Subtest cpuset-medium-copy-XY: SKIP (0.000s)
[724.357645] [102/130] (294s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[724.477341] Starting subtest: protected-encrypted-src-copy-not-readible
[724.588410] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.113s)
[724.731837] [103/130] (294s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling)
[724.831578] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling
[724.833930] Starting dynamic subtest: pipe-A-default-mode
[725.943067] Dynamic subtest pipe-A-default-mode: SKIP (1.109s)
[725.947729] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.111s)
[726.094788] [104/130] (292s left) kms_frontbuffer_tracking (fbcpsr-tiling-y)
[726.189879] Starting subtest: fbcpsr-tiling-y
[726.194315] Subtest fbcpsr-tiling-y: SKIP (0.000s)
[726.320813] [105/130] (292s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[726.856803] Starting subtest: syncobj-timeline-invalid-flags
[726.859107] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[728.816993] [106/130] (290s left) gem_basic (create-close)
[728.872639] Starting subtest: create-close
[728.876984] Subtest create-close: SUCCESS (0.000s)
[729.002545] [107/130] (290s left) api_intel_allocator (reloc-allocator)
[729.062312] Starting subtest: reloc-allocator
[729.064582] Starting dynamic subtest: basic
[729.068728] Dynamic subtest basic: SUCCESS (0.007s)
[729.078453] Starting dynamic subtest: parallel-one
[729.080741] Dynamic subtest parallel-one: SUCCESS (0.008s)
[729.090116] Starting dynamic subtest: print
[729.092687] Dynamic subtest print: SUCCESS (0.000s)
[729.101700] Starting dynamic subtest: reuse
[729.103833] Dynamic subtest reuse: SUCCESS (0.000s)
[729.113537] Starting dynamic subtest: fork-reopen-allocator
[729.115627] Dynamic subtest fork-reopen-allocator: SUCCESS (0.010s)
[729.117698] Subtest reloc-allocator: SUCCESS (0.028s)
[729.260605] [108/130] (289s left) kms_psr (pr-primary-blt)
[729.357188] Starting subtest: pr-primary-blt
[729.361600] Subtest pr-primary-blt: SKIP (0.000s)
[729.442729] [109/130] (289s left) gem_ctx_bad_destroy (double-destroy)
[729.492638] Starting subtest: double-destroy
[729.495200] Subtest double-destroy: SUCCESS (0.000s)
[729.593713] [110/130] (289s left) core_getclient
[729.774151] [111/130] (289s left) syncobj_wait (wait-all-delayed-signal)
[729.831154] Starting subtest: wait-all-delayed-signal
[729.935551] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[730.022926] [112/130] (289s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-wc)
[730.120613] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-wc
[730.125050] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s)
[730.232510] [113/130] (289s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[730.328714] Starting subtest: yf-tiled-16bpp-rotate-0
[730.332777] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[730.461696] [114/130] (288s left) kms_async_flips (test-time-stamp)
[730.562338] Starting subtest: test-time-stamp
[730.940819] Starting dynamic subtest: pipe-A-eDP-1
[731.711065] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.769s)
[732.075740] Starting dynamic subtest: pipe-B-eDP-1
[732.843059] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.774s)
[733.207645] Starting dynamic subtest: pipe-C-eDP-1
[733.969019] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.767s)
[734.334685] Starting dynamic subtest: pipe-D-eDP-1
[735.095417] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.766s)
[735.097934] Subtest test-time-stamp: SUCCESS (4.534s)
[736.263216] [115/130] (283s left) kms_invalid_mode (bad-vsync-end)
[736.362539] Starting subtest: bad-vsync-end
[736.364809] Starting dynamic subtest: pipe-A-eDP-1
[736.653236] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.293s)
[736.663391] Starting dynamic subtest: pipe-B-eDP-1
[736.665887] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[736.675364] Starting dynamic subtest: pipe-C-eDP-1
[736.677307] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[736.687232] Starting dynamic subtest: pipe-D-eDP-1
[736.689104] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[736.690995] Subtest bad-vsync-end: SUCCESS (0.298s)
[737.504830] [116/130] (282s left) gem_exec_suspend (basic)
[737.560254] Starting subtest: basic
[737.562947] Starting dynamic subtest: smem
[738.681022] Dynamic subtest smem: SUCCESS (1.118s)
[738.684016] Subtest basic: SUCCESS (1.120s)
[738.875338] [117/130] (280s left) api_intel_bb (blit-noreloc-purge-cache)
[738.924504] Starting subtest: blit-noreloc-purge-cache
[738.934703] Subtest blit-noreloc-purge-cache: SUCCESS (0.013s)
[739.076691] [118/130] (280s left) kms_plane (pixel-format-source-clamping)
[739.165418] Starting subtest: pixel-format-source-clamping
[739.192854] Starting dynamic subtest: pipe-A-plane-0
[744.103732] Dynamic subtest pipe-A-plane-0: SUCCESS (4.911s)
[744.115980] Starting dynamic subtest: pipe-A-plane-3
[746.414492] Dynamic subtest pipe-A-plane-3: SUCCESS (2.311s)
[746.424759] Starting dynamic subtest: pipe-A-plane-5
[746.426594] Dynamic subtest pipe-A-plane-5: SUCCESS (0.000s)
[747.565643] Starting dynamic subtest: pipe-B-plane-0
[752.432827] Dynamic subtest pipe-B-plane-0: SUCCESS (4.878s)
[752.445457] Starting dynamic subtest: pipe-B-plane-3
[754.733457] Dynamic subtest pipe-B-plane-3: SUCCESS (2.299s)
[754.742680] Starting dynamic subtest: pipe-B-plane-5
[754.744280] Dynamic subtest pipe-B-plane-5: SUCCESS (0.000s)
[755.052816] Subtest pixel-format-source-clamping: SUCCESS (15.885s)
[755.860239] [119/130] (263s left) i915_query (query-topology-kernel-writes)
[755.929397] Starting subtest: query-topology-kernel-writes
[755.931672] Subtest query-topology-kernel-writes: SUCCESS (0.000s)
[756.039395] [120/130] (263s left) kms_chamelium_edid (hdmi-edid-change-during-suspend)
[756.418404] Subtest hdmi-edid-change-during-suspend: SKIP (0.000s)
[757.253165] [121/130] (262s left) gem_exec_reloc (basic-cpu-active)
[757.302620] Subtest basic-cpu-active: SKIP (0.000s)
[757.388662] [122/130] (262s left) gem_exec_fence (concurrent)
[757.565705] Starting subtest: concurrent
[757.569935] Subtest concurrent: SKIP (0.000s)
[759.874751] [123/130] (259s left) perf (short-reads)
[760.020495] Starting subtest: short-reads
[760.035290] Subtest short-reads: SUCCESS (0.016s)
[760.074203] [124/130] (259s left) dumb_buffer (create-clear)
[760.125032] Starting subtest: create-clear
[794.362054] Subtest create-clear: SUCCESS (34.229s)
[794.482977] [125/130] (225s left) kms_psr2_su (page_flip-p010)
[794.542503] Subtest page_flip-P010: SKIP (0.000s)
[794.602503] [126/130] (225s left) i915_pm_rc6_residency (rc6-idle)
[794.657273] Starting subtest: rc6-idle
[794.701674] Starting dynamic subtest: gt0-rcs0
[819.029920] Dynamic subtest gt0-rcs0: SUCCESS (24.330s)
[819.041853] Starting dynamic subtest: gt0-bcs0
[843.304748] Dynamic subtest gt0-bcs0: SUCCESS (24.271s)
[843.314317] Starting dynamic subtest: gt0-ccs0
[867.517550] Dynamic subtest gt0-ccs0: SUCCESS (24.206s)
[867.529800] Starting dynamic subtest: gt1-vcs0
[873.739381] Dynamic subtest gt1-vcs0: SUCCESS (6.170s)
[873.752122] Starting dynamic subtest: gt1-vecs0
[879.917133] Dynamic subtest gt1-vecs0: SUCCESS (6.169s)
[879.919972] Subtest rc6-idle: SUCCESS (85.203s)
[879.963025] [127/130] (140s left) kms_plane_scaling (plane-scaler-unity-scaling-with-rotation)
[880.069795] Starting subtest: plane-scaler-unity-scaling-with-rotation
[880.072070] Starting dynamic subtest: pipe-A
[880.296593] Dynamic subtest pipe-A: SUCCESS (0.229s)
[880.306642] Starting dynamic subtest: pipe-B
[881.557742] Dynamic subtest pipe-B: SUCCESS (1.260s)
[881.570205] Starting dynamic subtest: pipe-C
[882.897203] Dynamic subtest pipe-C: SUCCESS (1.339s)
[882.908693] Starting dynamic subtest: pipe-D
[884.244615] Dynamic subtest pipe-D: SUCCESS (1.346s)
[884.247015] Subtest plane-scaler-unity-scaling-with-rotation: SUCCESS (4.176s)
[885.428072] [128/130] (134s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt)
[885.529078] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt
[885.533160] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[885.633871] [129/130] (134s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[885.745188] Starting subtest: cursor-vs-flip-legacy
[914.962091] Subtest cursor-vs-flip-legacy: SUCCESS (29.210s)
[915.786056] [130/130] (104s left) syncobj_timeline (multi-wait-available-submitted-signaled)
[915.843218] Starting subtest: multi-wait-available-submitted-signaled
[915.845585] Subtest multi-wait-available-submitted-signaled: SUCCESS (0.000s)
[915.978583] 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.