[58.825477] Initializing watchdogs
[58.825523]   /dev/watchdog0
[58.840108] [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]
[58.844430] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.846719] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.850181] [001/156] (960s left) gem_busy (semaphore)
[59.092297] Subtest semaphore: SKIP (0.000s)
[59.323464] [002/156] (960s left) gem_ctx_persistence (engines-hostile-preempt)
[59.377768] Starting subtest: engines-hostile-preempt
[59.380190] Starting dynamic subtest: rcs0
[59.467365] Dynamic subtest rcs0: SUCCESS (0.088s)
[59.478564] Starting dynamic subtest: bcs0
[59.521836] Dynamic subtest bcs0: SUCCESS (0.054s)
[59.531159] Starting dynamic subtest: vcs0
[61.323816] Dynamic subtest vcs0: SUCCESS (1.788s)
[61.332309] Starting dynamic subtest: vcs1
[63.299663] Dynamic subtest vcs1: SUCCESS (1.966s)
[63.309639] Starting dynamic subtest: vecs0
[65.280382] Dynamic subtest vecs0: SUCCESS (1.972s)
[65.289977] Starting dynamic subtest: ccs0
[65.339074] Dynamic subtest ccs0: SUCCESS (0.059s)
[65.341784] Subtest engines-hostile-preempt: SUCCESS (5.933s)
[65.419345] [003/156] (954s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[65.524848] Starting subtest: plane-upscale-factor-0-25-with-rotation
[65.527235] Starting dynamic subtest: pipe-A
[65.762023] Dynamic subtest pipe-A: SUCCESS (0.239s)
[65.769483] Starting dynamic subtest: pipe-B
[67.265483] Dynamic subtest pipe-B: SUCCESS (1.499s)
[67.273141] Starting dynamic subtest: pipe-C
[68.597917] Dynamic subtest pipe-C: SUCCESS (1.328s)
[68.607522] Starting dynamic subtest: pipe-D
[69.932680] Dynamic subtest pipe-D: SUCCESS (1.332s)
[69.935178] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.400s)
[71.123794] [004/156] (948s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-render)
[71.219837] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-render
[71.224308] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[71.318733] [005/156] (948s left) syncobj_eventfd (invalid-illegal-eventfd)
[71.365795] Starting subtest: invalid-illegal-eventfd
[71.368068] Subtest invalid-illegal-eventfd: SUCCESS (0.000s)
[71.455527] [006/156] (948s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180-hflip)
[71.561391] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180-hflip
[71.565698] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s)
[71.655993] [007/156] (947s left) syncobj_wait (reset-multiple-signaled)
[71.701885] Starting subtest: reset-multiple-signaled
[71.704535] Subtest reset-multiple-signaled: SUCCESS (0.000s)
[71.800931] [008/156] (947s left) kms_cursor_crc (cursor-onscreen-64x21)
[71.993434] Starting subtest: cursor-onscreen-64x21
[72.038259] Subtest cursor-onscreen-64x21: SKIP (0.041s)
[72.370142] [009/156] (947s left) kms_addfb_basic (small-bo)
[72.630751] Starting subtest: small-bo
[72.633182] Subtest small-bo: SUCCESS (0.000s)
[72.949655] [010/156] (946s left) kms_psr (fbc-psr-cursor-plane-move)
[73.117065] Starting subtest: fbc-psr-cursor-plane-move
[73.119471] Starting dynamic subtest: eDP-1
[73.123680] Dynamic subtest eDP-1: SKIP (0.000s)
[73.128136] Subtest fbc-psr-cursor-plane-move: SKIP (0.001s)
[73.198969] [011/156] (946s left) kms_frontbuffer_tracking (hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt)
[73.310278] Starting subtest: hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt
[73.314267] Subtest hdr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[73.438906] [012/156] (946s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-pwrite)
[73.615766] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-pwrite
[80.798228] Subtest fbc-1p-offscreen-pri-shrfb-draw-pwrite: SUCCESS (7.183s)
[81.942237] [013/156] (937s left) kms_psr (fbc-pr-cursor-plane-move)
[82.049423] Starting subtest: fbc-pr-cursor-plane-move
[82.054027] Subtest fbc-pr-cursor-plane-move: SKIP (0.000s)
[82.115407] [014/156] (937s left) gem_exec_flush (basic-uc-ro-default)
[82.174441] Starting subtest: basic-uc-ro-default
[87.659076] Subtest basic-uc-ro-default: SUCCESS (5.455s)
[87.810087] [015/156] (932s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[87.927732] Starting subtest: psr-shrfb-scaledprimary
[93.688881] Subtest psr-shrfb-scaledprimary: SUCCESS (5.765s)
[93.821101] [016/156] (926s left) kms_cdclk (plane-scaling)
[93.917914] Starting subtest: plane-scaling
[93.920069] Starting dynamic subtest: pipe-A-eDP-1
[94.225786] Dynamic subtest pipe-A-eDP-1: SKIP (0.305s)
[94.237025] Starting dynamic subtest: pipe-B-eDP-1
[94.241752] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[94.250593] Starting dynamic subtest: pipe-C-eDP-1
[94.255323] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[94.263512] Starting dynamic subtest: pipe-D-eDP-1
[94.268039] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[94.272352] Subtest plane-scaling: SKIP (0.311s)
[95.094317] [017/156] (924s left) kms_frontbuffer_tracking (fbcpsrhdr-stridechange)
[95.210204] Starting subtest: fbcpsrhdr-stridechange
[95.214205] Subtest fbcpsrhdr-stridechange: SKIP (0.000s)
[95.314095] [018/156] (924s left) i915_suspend (forcewake)
[95.361946] Starting subtest: forcewake
[127.053913] Subtest forcewake: SUCCESS (2.315s)
[128.126420] [019/156] (892s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[128.237572] Starting subtest: plane-downscale-factor-0-5-with-rotation
[128.239871] Starting dynamic subtest: pipe-A
[128.258032] Dynamic subtest pipe-A: SKIP (0.017s)
[128.267501] Starting dynamic subtest: pipe-B
[128.286662] Dynamic subtest pipe-B: SKIP (0.029s)
[128.295850] Starting dynamic subtest: pipe-C
[128.317170] Dynamic subtest pipe-C: SKIP (0.030s)
[128.325963] Starting dynamic subtest: pipe-D
[128.349781] Dynamic subtest pipe-D: SKIP (0.032s)
[128.353661] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.110s)
[128.441455] [020/156] (892s left) kms_prop_blob (invalid-set-prop-any)
[128.493035] Starting subtest: invalid-set-prop-any
[128.495004] Subtest invalid-set-prop-any: SUCCESS (0.000s)
[128.580695] [021/156] (892s left) gem_ctx_persistence (idempotent)
[128.638716] Starting subtest: idempotent
[128.641071] Subtest idempotent: SUCCESS (0.000s)
[128.759082] [022/156] (892s left) gem_mmap (bad-object)
[128.808800] Subtest bad-object: SKIP (0.000s)
[128.863826] [023/156] (892s left) kms_chamelium_frames (hdmi-crc-single)
[129.248694] Subtest hdmi-crc-single: SKIP (0.000s)
[130.091731] [024/156] (891s left) kms_flip (2x-flip-vs-fences-interruptible)
[130.193465] Starting subtest: 2x-flip-vs-fences-interruptible
[130.198143] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[130.300584] [025/156] (891s left) syncobj_timeline (multi-wait-available-unsubmitted-submitted-signaled)
[130.357441] Starting subtest: multi-wait-available-unsubmitted-submitted-signaled
[130.359688] Subtest multi-wait-available-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[130.467172] [026/156] (890s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt)
[130.563512] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt
[130.567689] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[130.650312] [027/156] (890s left) kms_plane (pixel-format-4-tiled-mtl-rc-ccs-modifier)
[130.735296] Starting subtest: pixel-format-4-tiled-mtl-rc-ccs-modifier
[130.763631] Starting dynamic subtest: pipe-A-plane-0
[131.056691] Dynamic subtest pipe-A-plane-0: SUCCESS (0.289s)
[131.068549] Starting dynamic subtest: pipe-A-plane-3
[131.291851] Dynamic subtest pipe-A-plane-3: SUCCESS (0.233s)
[131.300685] Starting dynamic subtest: pipe-A-plane-5
[131.304485] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[132.433795] Starting dynamic subtest: pipe-B-plane-0
[132.717826] Dynamic subtest pipe-B-plane-0: SUCCESS (0.289s)
[132.727470] Starting dynamic subtest: pipe-B-plane-3
[132.964082] Dynamic subtest pipe-B-plane-3: SUCCESS (0.244s)
[132.972869] Starting dynamic subtest: pipe-B-plane-5
[132.977377] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[133.280856] Subtest pixel-format-4-tiled-mtl-rc-ccs-modifier: SUCCESS (2.517s)
[134.114187] [028/156] (887s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs)
[134.227645] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs
[134.229786] Starting dynamic subtest: pipe-A-eDP-1
[134.398672] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.163s)
[134.408235] Starting dynamic subtest: pipe-B-eDP-1
[134.429781] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.002s)
[134.440503] Starting dynamic subtest: pipe-C-eDP-1
[134.460387] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.002s)
[134.468968] Starting dynamic subtest: pipe-D-eDP-1
[134.488780] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.002s)
[134.491042] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SUCCESS (0.173s)
[136.698117] [029/156] (884s left) kms_cursor_legacy (cursorb-vs-flipb-atomic-transitions)
[136.822297] Starting subtest: cursorB-vs-flipB-atomic-transitions
[136.827010] Subtest cursorB-vs-flipB-atomic-transitions: SKIP (0.001s)
[136.966315] [030/156] (884s left) kms_async_flips (crc-atomic)
[137.073392] Starting subtest: crc-atomic
[137.457869] Starting dynamic subtest: pipe-A-eDP-1
[140.375411] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.881s)
[140.731589] Starting dynamic subtest: pipe-B-eDP-1
[143.719317] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.963s)
[144.070833] Starting dynamic subtest: pipe-C-eDP-1
[147.052518] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.956s)
[147.390037] Starting dynamic subtest: pipe-D-eDP-1
[150.291491] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.869s)
[150.293751] Subtest crc-atomic: SUCCESS (13.142s)
[151.445103] [031/156] (870s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt)
[151.545778] Starting subtest: fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt
[151.550155] Subtest fbcpsrhdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[151.634689] [032/156] (869s left) kms_ccs (missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc)
[151.728419] Starting subtest: missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc
[151.730850] Starting dynamic subtest: pipe-A-eDP-1
[151.755617] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[151.765049] Starting dynamic subtest: pipe-B-eDP-1
[151.786997] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[151.795954] Starting dynamic subtest: pipe-C-eDP-1
[151.817723] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[151.826345] Starting dynamic subtest: pipe-D-eDP-1
[151.850199] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[151.854457] Subtest missing-ccs-buffer-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[154.018465] [033/156] (867s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[154.074435] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[154.137314] [034/156] (867s left) kms_invalid_mode (uint-max-clock)
[154.237033] Starting subtest: uint-max-clock
[154.239141] Starting dynamic subtest: pipe-A-eDP-1
[154.522208] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.290s)
[154.531931] Starting dynamic subtest: pipe-B-eDP-1
[154.533897] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[154.542155] Starting dynamic subtest: pipe-C-eDP-1
[154.544535] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[154.553056] Starting dynamic subtest: pipe-D-eDP-1
[154.555332] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[154.557599] Subtest uint-max-clock: SUCCESS (0.296s)
[155.338851] [035/156] (866s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[155.430627] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[155.432919] Starting dynamic subtest: pipe-A-eDP-1
[156.324826] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.887s)
[156.336680] Starting dynamic subtest: pipe-B-eDP-1
[157.868695] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.540s)
[157.877911] Starting dynamic subtest: pipe-C-eDP-1
[159.388726] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.520s)
[159.399777] Starting dynamic subtest: pipe-D-eDP-1
[160.909595] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.519s)
[160.911774] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.470s)
[162.088752] [036/156] (859s left) kms_psr (fbc-psr2-cursor-plane-move)
[162.177720] Starting subtest: fbc-psr2-cursor-plane-move
[162.180134] Starting dynamic subtest: eDP-1
[162.184664] Dynamic subtest eDP-1: SKIP (0.000s)
[162.189201] Subtest fbc-psr2-cursor-plane-move: SKIP (0.001s)
[162.247584] [037/156] (859s left) syncobj_wait (multi-wait-all-signaled)
[162.295186] Starting subtest: multi-wait-all-signaled
[162.297500] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[162.385679] [038/156] (859s left) kms_vrr (lobf)
[162.476142] Starting subtest: lobf
[162.480600] Subtest lobf: SKIP (0.000s)
[162.560117] [039/156] (859s left) kms_hdr (invalid-hdr)
[162.655056] Starting subtest: invalid-hdr
[162.657350] Starting dynamic subtest: pipe-A-eDP-1-XRGB2101010
[167.918059] Dynamic subtest pipe-A-eDP-1-XRGB2101010: SUCCESS (5.259s)
[167.935637] Starting dynamic subtest: pipe-A-eDP-1-XRGB16161616F
[172.002816] Dynamic subtest pipe-A-eDP-1-XRGB16161616F: SUCCESS (4.070s)
[172.012064] Subtest invalid-hdr: SUCCESS (9.358s)
[172.140062] [040/156] (849s left) gem_pread (exhaustion)
[172.196237] Subtest exhaustion: SKIP (0.000s)
[172.254177] [041/156] (849s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[172.357473] Starting subtest: 2x-flip-vs-rmfb-interruptible
[172.368726] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.010s)
[172.486631] [042/156] (849s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions-varying-size)
[172.601461] Starting subtest: cursorA-vs-flipA-atomic-transitions-varying-size
[184.843660] Subtest cursorA-vs-flipA-atomic-transitions-varying-size: SUCCESS (12.237s)
[185.666508] [043/156] (836s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[185.729269] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[185.837481] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[185.914358] [044/156] (836s left) gem_exec_create (legacy)
[185.973273] Starting subtest: legacy
[185.975683] Starting dynamic subtest: smem
[188.087348] Dynamic subtest smem: SUCCESS (2.087s)
[188.089450] Subtest legacy: SUCCESS (2.088s)
[188.258628] [045/156] (833s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[188.370309] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[195.371870] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.002s)
[195.506595] [046/156] (826s left) kms_dp_link_training (uhbr-mst)
[195.603233] Starting subtest: uhbr-mst
[195.612200] Subtest uhbr-mst: SKIP (0.000s)
[195.685066] [047/156] (826s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu)
[195.772010] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu
[195.776247] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[195.870329] [048/156] (826s left) gem_mmap_gtt (basic-small-bo-tiledx)
[195.919731] Subtest basic-small-bo-tiledX: SKIP (0.000s)
[195.986040] [049/156] (826s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-render)
[196.079999] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-render
[196.084397] Subtest fbc-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s)
[196.181013] [050/156] (826s left) kms_fbcon_fbt (fbc-suspend)
[196.233837] Starting subtest: fbc-suspend
[244.167691] Subtest fbc-suspend: SUCCESS (18.822s)
[245.552871] [051/156] (776s left) kms_frontbuffer_tracking (fbcpsrhdr-rgb101010-draw-mmap-gtt)
[245.660367] Starting subtest: fbcpsrhdr-rgb101010-draw-mmap-gtt
[245.664838] Subtest fbcpsrhdr-rgb101010-draw-mmap-gtt: SKIP (0.000s)
[245.758372] [052/156] (776s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[245.850514] Starting subtest: psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[245.854750] Subtest psrhdr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[245.936488] [053/156] (776s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-mmap-cpu)
[246.027799] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-mmap-cpu
[246.032257] Subtest fbc-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[246.120263] [054/156] (776s left) syncobj_wait (wait-all-snapshot)
[246.173081] Starting subtest: wait-all-snapshot
[246.253056] Subtest wait-all-snapshot: SUCCESS (0.081s)
[246.328634] [055/156] (776s left) prime_mmap (test_dup)
[246.380988] Starting subtest: test_dup
[246.382961] Starting dynamic subtest: test_dup-smem
[246.410442] Dynamic subtest test_dup-smem: SUCCESS (0.031s)
[246.412819] Subtest test_dup: SUCCESS (0.032s)
[246.482376] [056/156] (776s left) gem_eio (in-flight-immediate)
[246.720282] Starting subtest: in-flight-immediate
[255.564899] Subtest in-flight-immediate: SUCCESS (8.807s)
[255.752320] [FACT gem_eio (in-flight-immediate)] new: kernel.kmod_is_loaded.vgem: true
[255.756432] [057/156] (766s left) kms_frontbuffer_tracking (psr-rgb101010-draw-render)
[255.858656] Starting subtest: psr-rgb101010-draw-render
[263.145404] Subtest psr-rgb101010-draw-render: SUCCESS (7.280s)
[263.285973] [058/156] (759s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-indfb-pgflip-blt)
[263.386605] Starting subtest: fbchdr-1p-primscrn-indfb-pgflip-blt
[263.391005] Subtest fbchdr-1p-primscrn-indfb-pgflip-blt: SKIP (0.000s)
[263.497812] [059/156] (759s left) i915_pm_rpm (gem-mmap-type)
[265.831667] Starting subtest: gem-mmap-type
[265.834322] Starting dynamic subtest: wb-smem0
[269.662913] Dynamic subtest wb-smem0: SUCCESS (3.831s)
[269.673144] Starting dynamic subtest: wc-smem0
[272.568542] Dynamic subtest wc-smem0: SUCCESS (2.903s)
[272.579182] Starting dynamic subtest: uc-smem0
[275.767142] Dynamic subtest uc-smem0: SUCCESS (3.198s)
[275.769419] Subtest gem-mmap-type: SUCCESS (9.937s)
[275.884228] [060/156] (746s left) kms_plane_multiple (tiling-x)
[275.977398] Starting subtest: tiling-x
[275.979707] Starting dynamic subtest: pipe-A-eDP-1
[279.655696] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.678s)
[279.665419] Starting dynamic subtest: pipe-B-eDP-1
[284.181797] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.523s)
[284.192928] Starting dynamic subtest: pipe-C-eDP-1
[288.714280] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.529s)
[288.724788] Starting dynamic subtest: pipe-D-eDP-1
[293.254439] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.540s)
[293.256748] Subtest tiling-x: SUCCESS (17.275s)
[294.065422] [061/156] (728s left) gem_wait (write-busy)
[294.136197] Starting subtest: write-busy
[294.138111] Starting dynamic subtest: all-engines
[294.746079] Dynamic subtest all-engines: SUCCESS (0.611s)
[294.763997] Starting dynamic subtest: rcs0
[295.296873] Dynamic subtest rcs0: SUCCESS (0.549s)
[295.313302] Starting dynamic subtest: bcs0
[295.844504] Dynamic subtest bcs0: SUCCESS (0.547s)
[295.859206] Starting dynamic subtest: vcs0
[297.626415] Dynamic subtest vcs0: SUCCESS (1.777s)
[297.643443] Starting dynamic subtest: vcs1
[299.609101] Dynamic subtest vcs1: SUCCESS (1.979s)
[299.626602] Starting dynamic subtest: vecs0
[301.658100] Dynamic subtest vecs0: SUCCESS (2.045s)
[301.674866] Starting dynamic subtest: ccs0
[302.208197] Dynamic subtest ccs0: SUCCESS (0.549s)
[302.210477] Subtest write-busy: SUCCESS (8.073s)
[302.408460] [062/156] (720s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions)
[302.528378] Starting subtest: cursorB-vs-flipA-atomic-transitions
[302.533011] Subtest cursorB-vs-flipA-atomic-transitions: SKIP (0.001s)
[302.683274] [063/156] (720s left) kms_flip (flip-vs-fences)
[302.781154] Starting subtest: flip-vs-fences
[302.785413] Subtest flip-vs-fences: SKIP (0.000s)
[302.903228] [064/156] (720s left) kms_content_protection (lic-type-0)
[303.042754] Starting subtest: lic-type-0
[303.047229] Subtest lic-type-0: SKIP (0.000s)
[303.179551] [065/156] (719s left) kms_frontbuffer_tracking (hdr-rgb565-draw-render)
[303.273777] Starting subtest: hdr-rgb565-draw-render
[303.278243] Subtest hdr-rgb565-draw-render: SKIP (0.000s)
[303.398002] [066/156] (719s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-pwrite)
[303.491809] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-pwrite
[303.495310] Subtest fbc-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[303.590249] [067/156] (719s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-mc-ccs)
[303.682291] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-mc-ccs
[303.684117] Starting dynamic subtest: pipe-A-eDP-1
[303.703038] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[303.714606] Starting dynamic subtest: pipe-B-eDP-1
[303.731705] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[303.741006] Starting dynamic subtest: pipe-C-eDP-1
[303.758709] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[303.769656] Starting dynamic subtest: pipe-D-eDP-1
[303.788072] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[303.791889] Subtest crc-primary-rotation-180-y-tiled-gen12-mc-ccs: SKIP (0.006s)
[305.931177] [068/156] (717s left) kms_frontbuffer_tracking (hdr-2p-primscrn-cur-indfb-draw-blt)
[306.046997] Starting subtest: hdr-2p-primscrn-cur-indfb-draw-blt
[306.051535] Subtest hdr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[306.153149] [069/156] (716s left) kms_vblank (ts-continuation-dpms-rpm)
[306.246265] Starting subtest: ts-continuation-dpms-rpm
[306.248205] Starting dynamic subtest: pipe-A-eDP-1
[308.726690] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.481s)
[308.743186] Starting dynamic subtest: pipe-D-eDP-1
[310.966409] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.236s)
[310.968835] Subtest ts-continuation-dpms-rpm: SUCCESS (4.721s)
[311.859009] [070/156] (711s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[311.969122] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[311.973664] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[312.068506] [071/156] (711s left) kms_psr (psr-suspend)
[312.165374] Starting subtest: psr-suspend
[312.167741] Starting dynamic subtest: eDP-1
[329.514016] Dynamic subtest eDP-1: SUCCESS (2.871s)
[329.516400] Subtest psr-suspend: SUCCESS (2.871s)
[330.385755] [072/156] (692s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-pwrite)
[330.483771] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-pwrite
[334.967965] Subtest fbc-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (4.437s)
[336.161708] [073/156] (687s left) gen9_exec_parse (bb-start-far)
[336.226715] Subtest bb-start-far: SKIP (0.000s)
[336.306150] [074/156] (687s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[336.411422] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[336.415909] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[336.525850] [075/156] (686s left) gem_exec_whisper (basic-sync-all)
[336.585648] Starting subtest: basic-sync-all
[343.262571] Subtest basic-sync-all: SUCCESS (6.644s)
[343.445630] [076/156] (679s left) perf_pmu (faulting-read)
[343.488641] Starting subtest: faulting-read
[343.491016] Starting dynamic subtest: wb
[343.493357] Dynamic subtest wb: SUCCESS (0.001s)
[343.508998] Starting dynamic subtest: wc
[343.510920] Dynamic subtest wc: SUCCESS (0.000s)
[343.520394] Starting dynamic subtest: uc
[343.522315] Dynamic subtest uc: SUCCESS (0.000s)
[343.524305] Subtest faulting-read: SUCCESS (0.005s)
[343.615717] [077/156] (679s left) kms_pm_rpm (system-suspend-modeset)
[345.246610] Starting subtest: system-suspend-modeset
[364.631415] Subtest system-suspend-modeset: SUCCESS (4.574s)
[365.028074] [078/156] (658s left) gem_basic (create-fd-close)
[365.098570] Starting subtest: create-fd-close
[365.104346] Subtest create-fd-close: SUCCESS (0.000s)
[365.193670] [079/156] (658s left) kms_atomic_transition (plane-all-transition)
[365.278156] Starting subtest: plane-all-transition
[365.280613] Starting dynamic subtest: pipe-A-eDP-1
[367.798181] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.495s)
[368.110391] Starting dynamic subtest: pipe-B-eDP-1
[371.422189] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.299s)
[371.721446] Subtest plane-all-transition: SUCCESS (6.390s)
[372.551383] [080/156] (651s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-blt)
[372.659361] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-blt
[372.664414] Subtest psrhdr-1p-primscrn-cur-indfb-draw-blt: SKIP (0.000s)
[372.741126] [081/156] (650s left) api_intel_bb (simple-bb)
[372.789570] Starting subtest: simple-bb
[372.795076] Subtest simple-bb: SUCCESS (0.006s)
[372.907497] [082/156] (650s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-shrfb-draw-render)
[373.000959] Starting subtest: hdr-2p-scndscrn-pri-shrfb-draw-render
[373.005397] Subtest hdr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[373.099249] [083/156] (650s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-render)
[373.200676] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-render
[376.904759] Subtest fbc-1p-primscrn-cur-indfb-draw-render: SUCCESS (3.698s)
[378.073672] [084/156] (645s left) kms_flip (flip-vs-suspend-interruptible)
[378.162031] Starting subtest: flip-vs-suspend-interruptible
[378.164474] Starting dynamic subtest: A-eDP1
[539.081389] Dynamic subtest A-eDP1: SUCCESS (12.241s)
[539.083630] Subtest flip-vs-suspend-interruptible: SUCCESS (12.245s)
[539.207979] [085/156] (484s left) kms_plane_alpha_blend (alpha-7efc)
[539.310066] Starting subtest: alpha-7efc
[539.611485] Starting dynamic subtest: pipe-A-eDP-1
[540.245830] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.622s)
[540.281700] Starting dynamic subtest: pipe-D-eDP-1
[541.601803] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.311s)
[541.604753] Subtest alpha-7efc: SUCCESS (2.260s)
[542.463830] [086/156] (481s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu)
[542.573236] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu
[549.340940] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (6.715s)
[549.481562] [087/156] (474s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[549.587018] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[549.591877] Subtest psr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[549.685564] [088/156] (474s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu)
[549.778536] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu
[549.782927] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[549.866649] [089/156] (474s left) gem_exec_whisper (basic-contexts)
[549.929542] Starting subtest: basic-contexts
[556.464406] Subtest basic-contexts: SUCCESS (6.511s)
[556.651875] [090/156] (467s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[556.754703] Starting subtest: fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc
[556.759751] Subtest fbchdr-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[556.845499] [091/156] (467s left) syncobj_timeline (invalid-query-bad-pad)
[556.901796] Starting subtest: invalid-query-bad-pad
[556.904499] Subtest invalid-query-bad-pad: SUCCESS (0.000s)
[557.007280] [092/156] (467s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[557.106300] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[563.988581] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.876s)
[564.145771] [093/156] (460s left) gem_create (create-ext-set-pat)
[564.325086] Starting subtest: create-ext-set-pat
[564.327926] Subtest create-ext-set-pat: SUCCESS (0.001s)
[564.428700] [094/156] (459s left) kms_draw_crc (draw-method-render)
[564.520852] Starting subtest: draw-method-render
[564.523644] Starting dynamic subtest: xrgb8888-untiled
[564.775892] Dynamic subtest xrgb8888-untiled: SUCCESS (0.255s)
[564.787405] Starting dynamic subtest: rgb565-untiled
[564.997693] Dynamic subtest rgb565-untiled: SUCCESS (0.221s)
[565.008257] Starting dynamic subtest: xrgb2101010-untiled
[565.207232] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s)
[565.217781] Starting dynamic subtest: xbgr16161616f-untiled
[565.431678] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.220s)
[565.449265] Starting dynamic subtest: xrgb8888-xtiled
[565.552744] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.122s)
[565.564020] Starting dynamic subtest: rgb565-xtiled
[565.663755] Dynamic subtest rgb565-xtiled: SUCCESS (0.110s)
[565.675000] Starting dynamic subtest: xrgb2101010-xtiled
[565.786493] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[565.801363] Starting dynamic subtest: xbgr16161616f-xtiled
[565.897588] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.111s)
[565.908982] Starting dynamic subtest: xrgb8888-4tiled
[566.019598] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s)
[566.030737] Starting dynamic subtest: rgb565-4tiled
[566.130887] Dynamic subtest rgb565-4tiled: SUCCESS (0.110s)
[566.141586] Starting dynamic subtest: xrgb2101010-4tiled
[566.243208] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.110s)
[566.254186] Starting dynamic subtest: xbgr16161616f-4tiled
[566.364508] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.122s)
[566.366548] Subtest draw-method-render: SUCCESS (1.844s)
[566.483423] [095/156] (457s left) kms_flip (nonexisting-fb)
[566.573458] Starting subtest: nonexisting-fb
[566.575664] Starting dynamic subtest: A-eDP1
[567.714557] Dynamic subtest A-eDP1: SUCCESS (1.139s)
[567.725610] Starting dynamic subtest: B-eDP1
[568.855227] Dynamic subtest B-eDP1: SUCCESS (1.139s)
[568.866833] Starting dynamic subtest: C-eDP1
[569.999562] Dynamic subtest C-eDP1: SUCCESS (1.142s)
[570.013041] Starting dynamic subtest: D-eDP1
[571.136577] Dynamic subtest D-eDP1: SUCCESS (1.133s)
[571.141999] Subtest nonexisting-fb: SUCCESS (4.564s)
[572.318626] [096/156] (451s left) gem_exec_schedule (pi-common)
[572.506026] Starting subtest: pi-common
[572.508619] Starting dynamic subtest: rcs0
[572.538723] Dynamic subtest rcs0: SUCCESS (0.034s)
[572.549642] Starting dynamic subtest: bcs0
[572.574215] Dynamic subtest bcs0: SUCCESS (0.034s)
[572.587969] Starting dynamic subtest: vcs0
[572.606029] Dynamic subtest vcs0: SUCCESS (0.031s)
[572.620444] Starting dynamic subtest: vcs1
[572.635884] Dynamic subtest vcs1: SUCCESS (0.029s)
[572.651026] Starting dynamic subtest: vecs0
[572.665886] Dynamic subtest vecs0: SUCCESS (0.029s)
[572.681047] Starting dynamic subtest: ccs0
[572.695321] Dynamic subtest ccs0: SUCCESS (0.029s)
[572.697939] Subtest pi-common: SUCCESS (0.191s)
[572.924860] [097/156] (451s left) kms_plane_scaling (2x-scaler-multi-pipe)
[573.012023] Starting subtest: 2x-scaler-multi-pipe
[573.017815] Subtest 2x-scaler-multi-pipe: SKIP (0.001s)
[573.117502] [098/156] (451s left) sysfs_defaults (readonly)
[573.172712] Starting subtest: readonly
[573.174985] Starting dynamic subtest: vecs0
[573.177504] Dynamic subtest vecs0: SUCCESS (0.000s)
[573.188456] Starting dynamic subtest: ccs0
[573.190770] Dynamic subtest ccs0: SUCCESS (0.000s)
[573.201984] Starting dynamic subtest: vcs0
[573.204282] Dynamic subtest vcs0: SUCCESS (0.000s)
[573.214004] Starting dynamic subtest: bcs0
[573.216227] Dynamic subtest bcs0: SUCCESS (0.000s)
[573.226023] Starting dynamic subtest: rcs0
[573.228256] Dynamic subtest rcs0: SUCCESS (0.000s)
[573.241832] Starting dynamic subtest: vcs1
[573.244581] Dynamic subtest vcs1: SUCCESS (0.000s)
[573.246776] Subtest readonly: SUCCESS (0.006s)
[573.292514] [099/156] (451s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[573.398572] Starting subtest: fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[573.403396] Subtest fbcpsrhdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[573.492721] [100/156] (450s left) gem_softpin (reverse)
[573.548374] Starting subtest: reverse
[573.553606] Subtest reverse: SUCCESS (0.006s)
[573.665975] [101/156] (450s left) kms_frontbuffer_tracking (hdr-1p-primscrn-pri-shrfb-draw-mmap-wc)
[573.761153] Starting subtest: hdr-1p-primscrn-pri-shrfb-draw-mmap-wc
[573.765927] Subtest hdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[573.851245] [102/156] (450s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-pri-shrfb-draw-pwrite)
[573.942770] Starting subtest: fbchdr-1p-primscrn-pri-shrfb-draw-pwrite
[573.946562] Subtest fbchdr-1p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[574.038099] [103/156] (450s left) syncobj_basic (test-valid-cycle)
[574.088765] Starting subtest: test-valid-cycle
[574.090926] Subtest test-valid-cycle: SUCCESS (0.000s)
[574.184597] [104/156] (450s left) prime_busy (after)
[574.239807] Starting subtest: after
[574.244601] Starting dynamic subtest: rcs0
[574.273468] Dynamic subtest rcs0: SUCCESS (0.030s)
[574.285699] Starting dynamic subtest: bcs0
[574.323825] Dynamic subtest bcs0: SUCCESS (0.050s)
[574.335111] Starting dynamic subtest: vcs0
[574.367638] Dynamic subtest vcs0: SUCCESS (0.042s)
[574.379103] Starting dynamic subtest: vcs1
[574.410221] Dynamic subtest vcs1: SUCCESS (0.041s)
[574.421330] Starting dynamic subtest: vecs0
[574.453057] Dynamic subtest vecs0: SUCCESS (0.042s)
[574.473642] Starting dynamic subtest: ccs0
[574.496153] Dynamic subtest ccs0: SUCCESS (0.040s)
[574.605985] Subtest after: SUCCESS (0.364s)
[574.685824] [105/156] (450s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[574.888658] Starting subtest: cursor-rapid-movement-128x128
[574.891232] Starting dynamic subtest: pipe-A-eDP-1
[575.435791] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.546s)
[575.468514] Starting dynamic subtest: pipe-D-eDP-1
[576.623678] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.163s)
[576.626027] Subtest cursor-rapid-movement-128x128: SUCCESS (1.735s)
[577.474914] [106/156] (447s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-blt)
[577.571462] Starting subtest: psr-2p-primscrn-spr-indfb-draw-blt
[577.576235] Subtest psr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s)
[577.667561] [107/156] (447s left) gem_render_copy (y-tiled-mc-ccs-to-vebox-yf-tiled)
[577.720791] Starting subtest: y-tiled-mc-ccs-to-vebox-yf-tiled
[577.725773] Subtest y-tiled-mc-ccs-to-vebox-yf-tiled: SKIP (0.000s)
[577.904546] [108/156] (446s left) kms_rotation_crc (exhaust-fences)
[577.993887] Starting subtest: exhaust-fences
[577.998990] Subtest exhaust-fences: SKIP (0.000s)
[578.070979] [109/156] (446s left) gem_exec_reloc (basic-gtt-read-active)
[578.125017] Subtest basic-gtt-read-active: SKIP (0.000s)
[578.181819] [110/156] (446s left) gem_pxp (hw-rejects-pxp-context)
[578.303669] Starting subtest: hw-rejects-pxp-context
[578.308709] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[578.425285] [111/156] (446s left) kms_psr (pr-sprite-blt)
[578.517731] Starting subtest: pr-sprite-blt
[578.522402] Subtest pr-sprite-blt: SKIP (0.000s)
[578.596287] [112/156] (446s left) kms_pm_lpsp (screens-disabled)
[579.689168] Starting subtest: screens-disabled
[579.693985] Subtest screens-disabled: SKIP (0.000s)
[579.762578] [113/156] (445s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite)
[579.865643] Starting subtest: fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite
[579.870541] Subtest fbcpsrhdr-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s)
[579.960399] [114/156] (445s left) syncobj_basic (bad-destroy-pad)
[580.011669] Starting subtest: bad-destroy-pad
[580.013846] Subtest bad-destroy-pad: SUCCESS (0.000s)
[580.111828] [115/156] (445s left) kms_concurrent (multi-plane-atomic-lowres)
[580.198824] Starting subtest: multi-plane-atomic-lowres
[580.200987] Starting dynamic subtest: pipe-A-eDP-1
[580.714290] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.515s)
[580.725877] Starting dynamic subtest: pipe-B-eDP-1
[582.029877] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.312s)
[582.041586] Starting dynamic subtest: pipe-C-eDP-1
[583.331886] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.300s)
[583.343386] Starting dynamic subtest: pipe-D-eDP-1
[584.572544] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.240s)
[584.575310] Subtest multi-plane-atomic-lowres: SUCCESS (4.372s)
[585.385199] [116/156] (439s left) gem_exec_fence (syncobj-export)
[585.815677] Starting subtest: syncobj-export
[585.822721] Subtest syncobj-export: SUCCESS (0.005s)
[587.594791] [117/156] (437s left) kms_lease (page-flip-implicit-plane)
[587.687982] Starting subtest: page-flip-implicit-plane
[587.690735] Starting dynamic subtest: pipe-A-eDP-1
[588.167642] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.480s)
[588.179907] Starting dynamic subtest: pipe-B-eDP-1
[589.331673] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.161s)
[589.345562] Starting dynamic subtest: pipe-C-eDP-1
[590.448806] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.116s)
[590.462040] Starting dynamic subtest: pipe-D-eDP-1
[591.580791] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.129s)
[591.583612] Subtest page-flip-implicit-plane: SUCCESS (3.892s)
[592.425908] [118/156] (432s left) kms_psr (psr2-sprite-mmap-cpu)
[592.517699] Starting subtest: psr2-sprite-mmap-cpu
[592.520155] Starting dynamic subtest: eDP-1
[594.210659] Dynamic subtest eDP-1: SUCCESS (1.693s)
[594.213148] Subtest psr2-sprite-mmap-cpu: SUCCESS (1.694s)
[595.085420] [119/156] (430s left) gem_wait (invalid-flags)
[595.140389] Starting subtest: invalid-flags
[595.142777] Subtest invalid-flags: SUCCESS (0.000s)
[595.368695] [120/156] (429s left) gem_ctx_sseu (engines)
[595.420902] Subtest engines: SKIP (0.000s)
[595.494123] [121/156] (429s left) api_intel_allocator (default-alignment)
[595.543779] Starting subtest: default-alignment
[595.546498] Subtest default-alignment: SUCCESS (0.004s)
[595.651611] [122/156] (429s left) i915_fb_tiling (basic-x-tiling)
[595.703518] Subtest basic-x-tiling: SKIP (0.000s)
[595.770806] [123/156] (429s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff)
[595.866318] Starting subtest: psr-1p-primscrn-cur-indfb-onoff
[602.911672] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (7.049s)
[603.027817] [124/156] (422s left) kms_async_flips (test-time-stamp)
[603.114756] Starting subtest: test-time-stamp
[603.505029] Starting dynamic subtest: pipe-A-eDP-1
[604.318034] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.811s)
[604.695647] Starting dynamic subtest: pipe-B-eDP-1
[605.541475] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.851s)
[605.911621] Starting dynamic subtest: pipe-C-eDP-1
[606.676362] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.771s)
[607.043824] Starting dynamic subtest: pipe-D-eDP-1
[607.811774] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.774s)
[607.814719] Subtest test-time-stamp: SUCCESS (4.697s)
[608.978131] [125/156] (416s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-0-async-flip)
[609.074921] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip
[609.887939] Subtest 4-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SUCCESS (0.814s)
[610.050178] [126/156] (415s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-move)
[610.150741] Starting subtest: psrhdr-1p-primscrn-spr-indfb-move
[610.155230] Subtest psrhdr-1p-primscrn-spr-indfb-move: SKIP (0.000s)
[610.232551] [127/156] (415s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc)
[610.326809] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc
[610.331758] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[610.423789] [128/156] (415s left) gem_ctx_bad_destroy (invalid-ctx)
[610.478935] Starting subtest: invalid-ctx
[610.481823] Subtest invalid-ctx: SUCCESS (0.000s)
[610.563655] [129/156] (415s left) i915_drm_fdinfo (memory-info-purgeable)
[610.602006] Starting subtest: memory-info-purgeable
[610.604864] Starting dynamic subtest: smem0
[613.195282] Dynamic subtest smem0: SUCCESS (2.595s)
[613.197576] Subtest memory-info-purgeable: SUCCESS (2.596s)
[613.274581] [130/156] (412s left) kms_big_fb (4-tiled-addfb-size-offset-overflow)
[613.369667] Starting subtest: 4-tiled-addfb-size-offset-overflow
[613.372060] Subtest 4-tiled-addfb-size-offset-overflow: SUCCESS (0.000s)
[613.466328] [131/156] (412s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[613.560746] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu
[613.564803] Subtest fbchdr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[613.663416] [132/156] (412s left) kms_busy (extended-modeset-hang-oldfb-with-reset)
[613.764707] Starting subtest: extended-modeset-hang-oldfb-with-reset
[613.766655] Starting dynamic subtest: pipe-A
[625.958351] Dynamic subtest pipe-A: SUCCESS (12.189s)
[625.971229] Starting dynamic subtest: pipe-D
[638.448274] Dynamic subtest pipe-D: SUCCESS (12.485s)
[638.451010] Subtest extended-modeset-hang-oldfb-with-reset: SUCCESS (24.677s)
[639.332711] [133/156] (386s left) kms_selftest (drm_dp_mst_helper)
[639.372840] Starting subtest: drm_dp_mst_helper
[639.393878] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[639.490803] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.096s)
[639.501786] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[639.594864] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.106s)
[639.605835] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[639.692896] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.093s)
[639.695697] Subtest drm_dp_mst_helper: SUCCESS (0.320s)
[639.839408] [134/156] (386s left) device_reset (unbind-cold-reset-rebind)
[639.891995] Starting subtest: unbind-cold-reset-rebind
[639.897753] Subtest unbind-cold-reset-rebind: SKIP (0.000s)
[639.968794] [135/156] (386s left) gem_exec_balancer (invalid-bonds)
[640.029623] Starting subtest: invalid-bonds
[640.034627] Subtest invalid-bonds: SKIP (0.000s)
[640.324453] [136/156] (385s left) syncobj_timeline (wait-any-snapshot)
[640.374837] Starting subtest: wait-any-snapshot
[640.456313] Subtest wait-any-snapshot: SUCCESS (0.083s)
[640.535551] [137/156] (385s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff)
[640.631337] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff
[640.636627] Subtest fbc-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[640.732755] [138/156] (385s left) kms_flip (2x-flip-vs-dpms-off-vs-modeset-interruptible)
[640.820032] Starting subtest: 2x-flip-vs-dpms-off-vs-modeset-interruptible
[640.831111] Subtest 2x-flip-vs-dpms-off-vs-modeset-interruptible: SKIP (0.010s)
[640.922336] [139/156] (385s left) gem_exec_params (mmapped)
[640.976818] Starting subtest: mmapped
[640.979931] Subtest mmapped: SUCCESS (0.003s)
[641.094527] [140/156] (385s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render)
[641.199352] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render
[648.559469] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (7.369s)
[648.697554] [141/156] (377s left) gem_userptr_blits (readonly-pwrite-unsync)
[648.766490] Subtest readonly-pwrite-unsync: SKIP (0.000s)
[648.882036] [142/156] (377s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-32bpp-ytileccs-downscaling)
[648.968831] Starting subtest: flip-32bpp-ytile-to-32bpp-ytileccs-downscaling
[648.974025] Subtest flip-32bpp-ytile-to-32bpp-ytileccs-downscaling: SKIP (0.000s)
[649.050328] [143/156] (377s left) kms_setmode (invalid-clone-single-crtc)
[649.094625] Starting subtest: invalid-clone-single-crtc
[649.102681] Subtest invalid-clone-single-crtc: SKIP (0.000s)
[649.195928] [144/156] (377s left) kms_addfb_basic (bad-pitch-32)
[649.249539] Starting subtest: bad-pitch-32
[649.251713] Subtest bad-pitch-32: SUCCESS (0.000s)
[649.385144] [145/156] (377s left) kms_big_fb (x-tiled-16bpp-rotate-90)
[649.474647] Starting subtest: x-tiled-16bpp-rotate-90
[649.493859] Subtest x-tiled-16bpp-rotate-90: SKIP (0.019s)
[649.599880] [146/156] (376s left) kms_cursor_edge_walk (128x128-top-edge)
[649.699671] Starting subtest: 128x128-top-edge
[649.701621] Starting dynamic subtest: pipe-A-eDP-1
[653.206726] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.506s)
[653.218348] Starting dynamic subtest: pipe-D-eDP-1
[656.343879] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.133s)
[656.346902] Subtest 128x128-top-edge: SUCCESS (6.644s)
[657.154542] [147/156] (369s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[657.540701] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[658.373341] [148/156] (368s left) gem_exec_schedule (submit-early-slice)
[658.433722] Starting subtest: submit-early-slice
[658.435607] Starting dynamic subtest: rcs0
[658.474480] Dynamic subtest rcs0: SUCCESS (0.049s)
[658.486295] Starting dynamic subtest: bcs0
[658.518411] Dynamic subtest bcs0: SUCCESS (0.042s)
[658.530273] Starting dynamic subtest: vcs0
[658.564785] Dynamic subtest vcs0: SUCCESS (0.046s)
[658.576377] Starting dynamic subtest: vcs1
[658.605514] Dynamic subtest vcs1: SUCCESS (0.040s)
[658.616054] Starting dynamic subtest: vecs0
[658.647660] Dynamic subtest vecs0: SUCCESS (0.041s)
[658.658627] Starting dynamic subtest: ccs0
[658.692914] Dynamic subtest ccs0: SUCCESS (0.045s)
[658.695921] Subtest submit-early-slice: SUCCESS (0.268s)
[659.628716] [149/156] (366s left) kms_frontbuffer_tracking (psrhdr-1p-offscreen-pri-indfb-draw-blt)
[659.721570] Starting subtest: psrhdr-1p-offscreen-pri-indfb-draw-blt
[659.726771] Subtest psrhdr-1p-offscreen-pri-indfb-draw-blt: SKIP (0.000s)
[659.813663] [150/156] (366s left) syncobj_wait (wait-all-delayed-signal)
[659.877830] Starting subtest: wait-all-delayed-signal
[659.978351] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[660.059429] [151/156] (366s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc)
[660.151595] Starting subtest: psrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc
[660.156091] Subtest psrhdr-2p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[660.250560] [152/156] (366s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-cur-indfb-move)
[660.346296] Starting subtest: fbchdr-2p-primscrn-cur-indfb-move
[660.351728] Subtest fbchdr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[660.437648] [153/156] (366s left) gem_mmap_offset (perf)
[660.489990] Starting subtest: perf
[660.492464] Starting dynamic subtest: smem0
[660.855023] Dynamic subtest smem0: SUCCESS (0.362s)
[660.857452] Subtest perf: SUCCESS (0.363s)
[660.984247] [154/156] (365s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-0)
[661.083170] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-0
[662.151710] Subtest x-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.068s)
[662.275280] [155/156] (364s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt)
[662.368220] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt
[662.372294] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s)
[662.459056] [156/156] (364s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[662.554210] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[662.556253] Starting dynamic subtest: pipe-A-eDP-1
[679.072009] Dynamic subtest pipe-A-eDP-1: SUCCESS (16.519s)
[679.083792] Starting dynamic subtest: pipe-B-eDP-1
[696.266418] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.193s)
[696.268996] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (33.715s)
[697.141386] Closing watchdogs
results: parsing output: 0/ for test: gem_busy
results: parsing output: 1/ for test: gem_ctx_persistence
results: parsing output: 2/ for test: kms_plane_scaling
results: parsing output: 3/ for test: kms_frontbuffer_tracking
results: parsing output: 4/ for test: syncobj_eventfd
results: parsing output: 5/ for test: kms_big_fb
results: parsing output: 6/ for test: syncobj_wait
results: parsing output: 7/ for test: kms_cursor_crc
results: parsing output: 8/ for test: kms_addfb_basic
results: parsing output: 9/ for test: kms_psr
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: kms_psr
results: parsing output: 13/ for test: gem_exec_flush
results: parsing output: 14/ for test: kms_frontbuffer_tracking
results: parsing output: 15/ for test: kms_cdclk
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: i915_suspend
results: parsing output: 18/ for test: kms_plane_scaling
results: parsing output: 19/ for test: kms_prop_blob
results: parsing output: 20/ for test: gem_ctx_persistence
results: parsing output: 21/ for test: gem_mmap
results: parsing output: 22/ for test: kms_chamelium_frames
results: parsing output: 23/ for test: kms_flip
results: parsing output: 24/ for test: syncobj_timeline
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_plane
results: parsing output: 27/ for test: kms_ccs
results: parsing output: 28/ for test: kms_cursor_legacy
results: parsing output: 29/ for test: kms_async_flips
results: parsing output: 30/ for test: kms_frontbuffer_tracking
results: parsing output: 31/ for test: kms_ccs
results: parsing output: 32/ for test: gem_mmap_gtt
results: parsing output: 33/ for test: kms_invalid_mode
results: parsing output: 34/ for test: kms_ccs
results: parsing output: 35/ for test: kms_psr
results: parsing output: 36/ for test: syncobj_wait
results: parsing output: 37/ for test: kms_vrr
results: parsing output: 38/ for test: kms_hdr
results: parsing output: 39/ for test: gem_pread
results: parsing output: 40/ for test: kms_flip
results: parsing output: 41/ for test: kms_cursor_legacy
results: parsing output: 42/ for test: syncobj_timeline
results: parsing output: 43/ for test: gem_exec_create
results: parsing output: 44/ for test: kms_frontbuffer_tracking
results: parsing output: 45/ for test: kms_dp_link_training
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: gem_mmap_gtt
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_fbcon_fbt
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: kms_frontbuffer_tracking
results: parsing output: 52/ for test: kms_frontbuffer_tracking
results: parsing output: 53/ for test: syncobj_wait
results: parsing output: 54/ for test: prime_mmap
results: parsing output: 55/ for test: gem_eio
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: kms_frontbuffer_tracking
results: parsing output: 58/ for test: i915_pm_rpm
results: parsing output: 59/ for test: kms_plane_multiple
results: parsing output: 60/ for test: gem_wait
results: parsing output: 61/ for test: kms_cursor_legacy
results: parsing output: 62/ for test: kms_flip
results: parsing output: 63/ for test: kms_content_protection
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: kms_frontbuffer_tracking
results: parsing output: 66/ for test: kms_ccs
results: parsing output: 67/ for test: kms_frontbuffer_tracking
results: parsing output: 68/ for test: kms_vblank
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: kms_psr
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: gen9_exec_parse
results: parsing output: 73/ for test: kms_big_fb
results: parsing output: 74/ for test: gem_exec_whisper
results: parsing output: 75/ for test: perf_pmu
results: parsing output: 76/ for test: kms_pm_rpm
results: parsing output: 77/ for test: gem_basic
results: parsing output: 78/ for test: kms_atomic_transition
results: parsing output: 79/ for test: kms_frontbuffer_tracking
results: parsing output: 80/ for test: api_intel_bb
results: parsing output: 81/ for test: kms_frontbuffer_tracking
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: kms_flip
results: parsing output: 84/ for test: kms_plane_alpha_blend
results: parsing output: 85/ for test: kms_frontbuffer_tracking
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_frontbuffer_tracking
results: parsing output: 88/ for test: gem_exec_whisper
results: parsing output: 89/ for test: kms_frontbuffer_tracking
results: parsing output: 90/ for test: syncobj_timeline
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: gem_create
results: parsing output: 93/ for test: kms_draw_crc
results: parsing output: 94/ for test: kms_flip
results: parsing output: 95/ for test: gem_exec_schedule
results: parsing output: 96/ for test: kms_plane_scaling
results: parsing output: 97/ for test: sysfs_defaults
results: parsing output: 98/ for test: kms_frontbuffer_tracking
results: parsing output: 99/ for test: gem_softpin
results: parsing output: 100/ for test: kms_frontbuffer_tracking
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: syncobj_basic
results: parsing output: 103/ for test: prime_busy
results: parsing output: 104/ for test: kms_cursor_crc
results: parsing output: 105/ for test: kms_frontbuffer_tracking
results: parsing output: 106/ for test: gem_render_copy
results: parsing output: 107/ for test: kms_rotation_crc
results: parsing output: 108/ for test: gem_exec_reloc
results: parsing output: 109/ for test: gem_pxp
results: parsing output: 110/ for test: kms_psr
results: parsing output: 111/ for test: kms_pm_lpsp
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: syncobj_basic
results: parsing output: 114/ for test: kms_concurrent
results: parsing output: 115/ for test: gem_exec_fence
results: parsing output: 116/ for test: kms_lease
results: parsing output: 117/ for test: kms_psr
results: parsing output: 118/ for test: gem_wait
results: parsing output: 119/ for test: gem_ctx_sseu
results: parsing output: 120/ for test: api_intel_allocator
results: parsing output: 121/ for test: i915_fb_tiling
results: parsing output: 122/ for test: kms_frontbuffer_tracking
results: parsing output: 123/ for test: kms_async_flips
results: parsing output: 124/ for test: kms_big_fb
results: parsing output: 125/ for test: kms_frontbuffer_tracking
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: gem_ctx_bad_destroy
results: parsing output: 128/ for test: i915_drm_fdinfo
results: parsing output: 129/ for test: kms_big_fb
results: parsing output: 130/ for test: kms_frontbuffer_tracking
results: parsing output: 131/ for test: kms_busy
results: parsing output: 132/ for test: kms_selftest
results: parsing output: 133/ for test: device_reset
results: parsing output: 134/ for test: gem_exec_balancer
results: parsing output: 135/ for test: syncobj_timeline
results: parsing output: 136/ for test: kms_frontbuffer_tracking
results: parsing output: 137/ for test: kms_flip
results: parsing output: 138/ for test: gem_exec_params
results: parsing output: 139/ for test: kms_frontbuffer_tracking
results: parsing output: 140/ for test: gem_userptr_blits
results: parsing output: 141/ for test: kms_flip_scaled_crc
results: parsing output: 142/ for test: kms_setmode
results: parsing output: 143/ for test: kms_addfb_basic
results: parsing output: 144/ for test: kms_big_fb
results: parsing output: 145/ for test: kms_cursor_edge_walk
results: parsing output: 146/ for test: kms_chamelium_hpd
results: parsing output: 147/ for test: gem_exec_schedule
results: parsing output: 148/ for test: kms_frontbuffer_tracking
results: parsing output: 149/ for test: syncobj_wait
results: parsing output: 150/ for test: kms_frontbuffer_tracking
results: parsing output: 151/ for test: kms_frontbuffer_tracking
results: parsing output: 152/ for test: gem_mmap_offset
results: parsing output: 153/ for test: kms_big_fb
results: parsing output: 154/ for test: kms_frontbuffer_tracking
results: parsing output: 155/ for test: kms_psr2_sf
Done.