[56.894612] Initializing watchdogs
[56.894733]   /dev/watchdog0
[56.908664] [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]
[56.912549] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[56.914727] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[56.918176] [001/131] (960s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[57.080856] Starting subtest: x-tiled-32bpp-rotate-90
[57.112200] Subtest x-tiled-32bpp-rotate-90: SKIP (0.029s)
[57.237198] [002/131] (960s left) kms_cursor_crc (cursor-size-hints)
[57.355792] Starting subtest: cursor-size-hints
[57.358165] Starting dynamic subtest: pipe-A-eDP-1
[58.006145] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.638s)
[58.014920] Starting dynamic subtest: pipe-D-eDP-1
[59.318294] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.293s)
[59.320174] Subtest cursor-size-hints: SUCCESS (1.933s)
[60.198663] [003/131] (957s left) kms_pm_rpm (fences)
[62.035280] Starting subtest: fences
[62.040586] Subtest fences: SKIP (0.000s)
[62.458982] [004/131] (955s left) gem_mmap_wc (write-read)
[62.523539] Subtest write-read: SKIP (0.000s)
[62.577523] [005/131] (955s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-pwrite)
[62.674704] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-pwrite
[62.679249] Subtest fbc-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[62.761878] [006/131] (954s left) gem_mmap_gtt (cpuset-big-copy-odd)
[62.822472] Subtest cpuset-big-copy-odd: SKIP (0.000s)
[62.872502] [007/131] (954s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[62.931067] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[62.933475] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.001s)
[63.045337] [008/131] (954s left) gem_exercise_blt (fast-copy-emit)
[63.110097] Starting subtest: fast-copy-emit
[63.112134] Starting dynamic subtest: linear-smem-smem-emit
[63.129581] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.022s)
[63.138437] Starting dynamic subtest: xmajor-smem-smem-emit
[63.148052] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.018s)
[63.154230] Starting dynamic subtest: tile4-smem-smem-emit
[63.165891] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.017s)
[63.172267] Starting dynamic subtest: tile64-smem-smem-emit
[63.184124] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.018s)
[63.186516] Subtest fast-copy-emit: SUCCESS (0.076s)
[63.313959] [009/131] (954s left) gem_tiled_fence_blits (basic)
[63.379520] Subtest basic: SKIP (0.000s)
[63.447286] [010/131] (954s left) kms_lease (implicit-plane-lease)
[63.546554] Starting subtest: implicit-plane-lease
[63.551027] Subtest implicit-plane-lease: SUCCESS (0.002s)
[63.657210] [011/131] (954s left) syncobj_wait (invalid-reset-bad-pad)
[63.704397] Starting subtest: invalid-reset-bad-pad
[63.706458] Subtest invalid-reset-bad-pad: SUCCESS (0.000s)
[63.788189] [012/131] (954s left) gem_wait (write-wait)
[63.848670] Starting subtest: write-wait
[63.850819] Starting dynamic subtest: all-engines
[64.976881] Dynamic subtest all-engines: SUCCESS (1.120s)
[64.991251] Starting dynamic subtest: rcs0
[66.030772] Dynamic subtest rcs0: SUCCESS (1.047s)
[66.043807] Starting dynamic subtest: bcs0
[67.086796] Dynamic subtest bcs0: SUCCESS (1.051s)
[67.100080] Starting dynamic subtest: vcs0
[68.140440] Dynamic subtest vcs0: SUCCESS (1.049s)
[68.153061] Starting dynamic subtest: vcs1
[69.193121] Dynamic subtest vcs1: SUCCESS (1.049s)
[69.206835] Starting dynamic subtest: vecs0
[70.246679] Dynamic subtest vecs0: SUCCESS (1.049s)
[70.259476] Starting dynamic subtest: ccs0
[71.352269] Dynamic subtest ccs0: SUCCESS (1.102s)
[71.354846] Subtest write-wait: SUCCESS (7.470s)
[71.588787] [FACT gem_wait (write-wait)] new: kernel.kmod_is_loaded.vgem: true
[71.592389] [013/131] (946s left) gem_ctx_persistence (userptr)
[71.703116] Starting subtest: userptr
[72.138101] Subtest userptr: SUCCESS (0.434s)
[72.216177] [014/131] (945s left) perf_pmu (busy-accuracy-2)
[72.256052] Starting subtest: busy-accuracy-2
[72.258393] Starting dynamic subtest: rcs0
[75.316708] Dynamic subtest rcs0: SUCCESS (3.052s)
[75.325986] Starting dynamic subtest: bcs0
[78.380513] Dynamic subtest bcs0: SUCCESS (3.060s)
[78.389833] Starting dynamic subtest: vcs0
[81.428785] Dynamic subtest vcs0: SUCCESS (3.045s)
[81.435980] Starting dynamic subtest: vcs1
[84.481706] Dynamic subtest vcs1: SUCCESS (3.051s)
[84.491263] Starting dynamic subtest: vecs0
[87.532520] Dynamic subtest vecs0: SUCCESS (3.048s)
[87.541462] Starting dynamic subtest: ccs0
[90.596768] Dynamic subtest ccs0: SUCCESS (3.062s)
[90.598799] Subtest busy-accuracy-2: SUCCESS (18.320s)
[90.737270] [015/131] (927s left) gem_exec_fence (syncobj-timeline-invalid-flags)
[91.294909] Starting subtest: syncobj-timeline-invalid-flags
[91.297159] Subtest syncobj-timeline-invalid-flags: SUCCESS (0.000s)
[93.016323] [016/131] (925s left) kms_psr (fbc-psr2-cursor-mmap-cpu)
[93.109958] Starting subtest: fbc-psr2-cursor-mmap-cpu
[93.112236] Starting dynamic subtest: eDP-1
[93.116221] Dynamic subtest eDP-1: SKIP (0.000s)
[93.120197] Subtest fbc-psr2-cursor-mmap-cpu: SKIP (0.000s)
[93.176361] [017/131] (924s left) kms_plane_cursor (viewport)
[93.267909] Starting subtest: viewport
[93.272722] Starting dynamic subtest: pipe-A-eDP-1-size-64
[96.081123] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.810s)
[96.139708] Starting dynamic subtest: pipe-A-eDP-1-size-128
[98.951075] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.817s)
[99.007840] Starting dynamic subtest: pipe-A-eDP-1-size-256
[101.840280] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.837s)
[102.189471] Starting dynamic subtest: pipe-B-eDP-1-size-64
[105.788579] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.607s)
[105.847075] Starting dynamic subtest: pipe-B-eDP-1-size-128
[108.713148] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.873s)
[108.771259] Starting dynamic subtest: pipe-B-eDP-1-size-256
[111.569299] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.803s)
[111.919198] Starting dynamic subtest: pipe-C-eDP-1-size-64
[115.078947] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (3.169s)
[115.115597] Starting dynamic subtest: pipe-C-eDP-1-size-128
[117.535628] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.424s)
[117.572601] Starting dynamic subtest: pipe-C-eDP-1-size-256
[119.991421] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.423s)
[120.315655] Starting dynamic subtest: pipe-D-eDP-1-size-64
[123.462346] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (3.155s)
[123.498095] Starting dynamic subtest: pipe-D-eDP-1-size-128
[125.918317] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.424s)
[125.954790] Starting dynamic subtest: pipe-D-eDP-1-size-256
[128.374396] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.424s)
[128.691726] Subtest viewport: SUCCESS (35.413s)
[129.516297] [018/131] (888s left) kms_fence_pin_leak
[129.705196] [019/131] (888s left) debugfs_test (read_all_entries_display_on)
[129.791096] Starting subtest: read_all_entries_display_on
[130.198844] Subtest read_all_entries_display_on: SUCCESS (0.405s)
[130.321894] [020/131] (887s left) kms_ccs (random-ccs-data-y-tiled-gen12-rc-ccs-cc)
[130.421130] Starting subtest: random-ccs-data-y-tiled-gen12-rc-ccs-cc
[130.423466] Starting dynamic subtest: pipe-A-eDP-1
[130.449152] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[130.458067] Starting dynamic subtest: pipe-B-eDP-1
[130.477813] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[130.484703] Starting dynamic subtest: pipe-C-eDP-1
[130.504955] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[130.512638] Starting dynamic subtest: pipe-D-eDP-1
[130.532511] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[130.536076] Subtest random-ccs-data-y-tiled-gen12-rc-ccs-cc: SKIP (0.004s)
[132.578787] [021/131] (885s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-render)
[132.681353] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-render
[137.853258] Subtest psr-1p-primscrn-pri-shrfb-draw-render: SUCCESS (5.175s)
[137.958693] [022/131] (880s left) kms_rotation_crc (primary-4-tiled-reflect-x-180)
[138.051760] Starting subtest: primary-4-tiled-reflect-x-180
[144.976126] Subtest primary-4-tiled-reflect-x-180: SUCCESS (6.924s)
[146.171715] [023/131] (872s left) gem_render_copy (yf-tiled-to-vebox-linear)
[146.233733] Starting subtest: yf-tiled-to-vebox-linear
[146.238369] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[146.412240] [024/131] (871s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt)
[146.513459] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt
[146.517594] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[146.596878] [025/131] (871s left) kms_ccs (bad-aux-stride-y-tiled-gen12-mc-ccs)
[146.691297] Starting subtest: bad-aux-stride-y-tiled-gen12-mc-ccs
[146.693718] Starting dynamic subtest: pipe-A-eDP-1
[146.717132] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[146.726089] Starting dynamic subtest: pipe-B-eDP-1
[146.747329] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[146.754753] Starting dynamic subtest: pipe-C-eDP-1
[146.775959] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[146.783243] Starting dynamic subtest: pipe-D-eDP-1
[146.805339] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[146.809337] Subtest bad-aux-stride-y-tiled-gen12-mc-ccs: SKIP (0.004s)
[148.824057] [026/131] (869s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[148.931459] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[154.238663] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (5.311s)
[154.390905] [027/131] (864s left) kms_ccs (crc-primary-suspend-4-tiled-bmg-ccs)
[154.495240] Starting subtest: crc-primary-suspend-4-tiled-bmg-ccs
[154.499716] Subtest crc-primary-suspend-4-tiled-bmg-ccs: SKIP (0.000s)
[155.593178] [028/131] (862s left) gem_exec_schedule (manyslice)
[155.669432] Starting subtest: manyslice
[155.671730] Starting dynamic subtest: rcs0
[160.274519] Dynamic subtest rcs0: SUCCESS (4.614s)
[160.287304] Starting dynamic subtest: bcs0
[166.382749] Dynamic subtest bcs0: SUCCESS (6.107s)
[166.395687] Starting dynamic subtest: vcs0
[171.453750] Dynamic subtest vcs0: SUCCESS (5.069s)
[171.461511] Starting dynamic subtest: vcs1
[176.518409] Dynamic subtest vcs1: SUCCESS (5.062s)
[176.530867] Starting dynamic subtest: vecs0
[181.082285] Dynamic subtest vecs0: SUCCESS (4.562s)
[181.094510] Starting dynamic subtest: ccs0
[187.757200] Dynamic subtest ccs0: SUCCESS (6.673s)
[187.759765] Subtest manyslice: SUCCESS (32.096s)
[188.047913] [029/131] (830s left) syncobj_timeline (invalid-wait-bad-flags)
[188.106909] Starting subtest: invalid-wait-bad-flags
[188.109174] Subtest invalid-wait-bad-flags: SUCCESS (0.000s)
[188.223629] [030/131] (830s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-indfb-draw-render)
[188.338622] Starting subtest: fbc-1p-offscren-pri-indfb-draw-render
[191.477254] Subtest fbc-1p-offscren-pri-indfb-draw-render: SUCCESS (3.141s)
[191.606653] [031/131] (826s left) kms_flip_scaled_crc (flip-32bpp-ytile-to-64bpp-ytile-downscaling)
[191.694736] Starting subtest: flip-32bpp-ytile-to-64bpp-ytile-downscaling
[191.696708] Starting dynamic subtest: pipe-A-default-mode
[191.980855] Dynamic subtest pipe-A-default-mode: SKIP (0.281s)
[191.985305] Subtest flip-32bpp-ytile-to-64bpp-ytile-downscaling: SKIP (0.282s)
[192.850354] [032/131] (825s left) gem_exec_reloc (basic-range-active)
[192.910685] Subtest basic-range-active: SKIP (0.000s)
[192.980406] [033/131] (825s left) kms_cursor_crc (cursor-offscreen-512x512)
[193.206956] Starting subtest: cursor-offscreen-512x512
[193.211491] Subtest cursor-offscreen-512x512: SKIP (0.001s)
[193.345679] [034/131] (825s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0)
[193.446176] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0
[194.423234] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (0.977s)
[194.550787] [035/131] (824s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[194.658802] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[199.867074] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SUCCESS (5.211s)
[199.993362] [036/131] (818s left) syncobj_timeline (single-wait-for-submit-available-signaled)
[200.055040] Starting subtest: single-wait-for-submit-available-signaled
[200.057364] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s)
[200.152427] [037/131] (818s left) gem_exec_fence (basic-busy)
[200.336575] Starting subtest: basic-busy
[200.338986] Starting dynamic subtest: rcs0
[200.446430] Dynamic subtest rcs0: SUCCESS (0.109s)
[200.454080] Starting dynamic subtest: bcs0
[200.508508] Dynamic subtest bcs0: SUCCESS (0.060s)
[200.517194] Starting dynamic subtest: vcs0
[200.560189] Dynamic subtest vcs0: SUCCESS (0.049s)
[200.569125] Starting dynamic subtest: vcs1
[200.621153] Dynamic subtest vcs1: SUCCESS (0.059s)
[200.630245] Starting dynamic subtest: vecs0
[200.673026] Dynamic subtest vecs0: SUCCESS (0.050s)
[200.682220] Starting dynamic subtest: ccs0
[200.728249] Dynamic subtest ccs0: SUCCESS (0.053s)
[200.730671] Subtest basic-busy: SUCCESS (0.392s)
[203.031550] [038/131] (815s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-rc-ccs-cc)
[203.137313] Starting subtest: crc-primary-suspend-4-tiled-mtl-rc-ccs-cc
[203.139621] Starting dynamic subtest: pipe-A-eDP-1
[219.651525] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.407s)
[219.660497] Starting dynamic subtest: pipe-B-eDP-1
[236.563345] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.310s)
[236.573991] Starting dynamic subtest: pipe-C-eDP-1
[253.551456] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.327s)
[253.560745] Starting dynamic subtest: pipe-D-eDP-1
[270.543045] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.209s)
[270.544966] Subtest crc-primary-suspend-4-tiled-mtl-rc-ccs-cc: SUCCESS (8.258s)
[271.789669] [039/131] (806s left) syncobj_wait (invalid-single-wait-all-unsubmitted)
[271.847008] Starting subtest: invalid-single-wait-all-unsubmitted
[271.849157] Subtest invalid-single-wait-all-unsubmitted: SUCCESS (0.000s)
[271.956382] [040/131] (806s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-pwrite)
[272.059761] Starting subtest: fbcpsr-rgb565-draw-pwrite
[276.939762] Subtest fbcpsr-rgb565-draw-pwrite: SUCCESS (4.784s)
[277.059222] [041/131] (801s left) gem_softpin (full)
[277.116302] Starting subtest: full
[277.123424] Subtest full: SUCCESS (0.007s)
[277.255584] [042/131] (800s left) gem_exec_reloc (basic-gtt-cpu-active)
[277.320485] Subtest basic-gtt-cpu-active: SKIP (0.000s)
[277.395533] [043/131] (800s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-fullscreen)
[277.508697] Starting subtest: psr-1p-primscrn-spr-indfb-fullscreen
[282.019948] Subtest psr-1p-primscrn-spr-indfb-fullscreen: SUCCESS (4.468s)
[282.142863] [044/131] (796s left) kms_big_fb (y-tiled-16bpp-rotate-180)
[282.244745] Starting subtest: y-tiled-16bpp-rotate-180
[282.249181] Subtest y-tiled-16bpp-rotate-180: SKIP (0.000s)
[282.347143] [045/131] (796s left) prime_self_import (export-vs-gem_close-race)
[282.384303] Starting subtest: export-vs-gem_close-race
[287.470774] Subtest export-vs-gem_close-race: SUCCESS (5.060s)
[287.542376] [046/131] (790s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[287.597671] Starting subtest: single-wait-all-for-submit-available-submitted
[287.600096] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[287.719408] [047/131] (790s left) sysfs_timeslice_duration (duration)
[287.780828] Starting subtest: duration
[287.783080] Starting dynamic subtest: vecs0
[301.779599] Dynamic subtest vecs0: SUCCESS (13.969s)
[301.798036] Starting dynamic subtest: ccs0
[314.900347] Dynamic subtest ccs0: SUCCESS (13.124s)
[314.910498] Starting dynamic subtest: vcs0
[328.787754] Dynamic subtest vcs0: SUCCESS (13.882s)
[328.797695] Starting dynamic subtest: bcs0
[341.953427] Dynamic subtest bcs0: SUCCESS (13.161s)
[341.965455] Starting dynamic subtest: rcs0
[355.100147] Dynamic subtest rcs0: SUCCESS (13.140s)
[355.110528] Starting dynamic subtest: vcs1
[368.852182] Dynamic subtest vcs1: SUCCESS (13.746s)
[368.855185] Subtest duration: SUCCESS (81.034s)
[368.923825] [048/131] (709s left) kms_cursor_crc (cursor-random-64x64)
[369.085324] Starting subtest: cursor-random-64x64
[369.087678] Starting dynamic subtest: pipe-A-eDP-1
[372.690032] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.603s)
[372.715809] Starting dynamic subtest: pipe-D-eDP-1
[377.040013] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.331s)
[377.042497] Subtest cursor-random-64x64: SUCCESS (7.957s)
[377.873936] [049/131] (700s left) kms_psr (psr2-suspend)
[377.970595] Starting subtest: psr2-suspend
[377.972968] Starting dynamic subtest: eDP-1
[395.863354] Dynamic subtest eDP-1: SUCCESS (2.857s)
[395.865927] Subtest psr2-suspend: SUCCESS (2.858s)
[396.760174] [050/131] (696s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite)
[396.863632] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite
[396.868950] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[396.975225] [051/131] (696s left) kms_writeback (writeback-pixel-formats)
[397.077268] Subtest writeback-pixel-formats: SKIP (0.000s)
[397.118759] [052/131] (696s left) kms_cursor_legacy (2x-long-nonblocking-modeset-vs-cursor-atomic)
[397.252254] Starting subtest: 2x-long-nonblocking-modeset-vs-cursor-atomic
[397.258069] Subtest 2x-long-nonblocking-modeset-vs-cursor-atomic: SKIP (0.001s)
[397.387268] [053/131] (696s left) kms_chamelium_color (ctm-green-to-red)
[397.492680] Subtest ctm-green-to-red: SKIP (0.000s)
[397.532626] [054/131] (696s left) gem_exec_suspend (basic-s3)
[397.600036] Starting subtest: basic-S3
[397.602950] Starting dynamic subtest: smem
[417.432477] Dynamic subtest smem: SUCCESS (6.207s)
[417.435667] Subtest basic-S3: SUCCESS (6.208s)
[417.618917] [055/131] (689s left) gem_exec_nop (basic-sequential)
[417.696498] Starting subtest: basic-sequential
[434.515022] Subtest basic-sequential: SUCCESS (16.652s)
[434.741171] [056/131] (672s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[434.850294] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[434.852468] Starting dynamic subtest: pipe-A-eDP-1
[436.945614] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.090s)
[436.953445] Starting dynamic subtest: pipe-B-eDP-1
[439.004923] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.054s)
[439.013147] Starting dynamic subtest: pipe-C-eDP-1
[440.995459] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.987s)
[441.004468] Starting dynamic subtest: pipe-D-eDP-1
[443.023036] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.021s)
[443.025684] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.157s)
[444.163842] [057/131] (663s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-render)
[444.271475] Starting subtest: psr-2p-primscrn-pri-indfb-draw-render
[444.276436] Subtest psr-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[444.375124] [058/131] (663s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[444.445679] Starting subtest: y-tiled-to-vebox-yf-tiled
[444.451310] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[444.624288] [059/131] (663s left) gem_mmap_offset (clear)
[444.676670] Starting subtest: clear
[444.679903] Starting dynamic subtest: smem0
[471.141305] Dynamic subtest smem0: SUCCESS (26.453s)
[471.144440] Subtest clear: SUCCESS (26.455s)
[471.433564] [060/131] (636s left) api_intel_allocator (alloc-simple)
[471.546155] Starting subtest: alloc-simple
[471.550481] Subtest alloc-simple: SUCCESS (0.007s)
[471.675378] [061/131] (636s left) i915_pm_freq_api (freq-suspend)
[471.734522] Starting subtest: freq-suspend
[471.737304] Starting dynamic subtest: gt0
[487.455208] Dynamic subtest gt0: SUCCESS (1.245s)
[487.464486] Starting dynamic subtest: gt1
[503.398467] Dynamic subtest gt1: SUCCESS (1.155s)
[503.401227] Subtest freq-suspend: SUCCESS (2.402s)
[503.466897] [062/131] (633s left) gem_workarounds (basic-read-context)
[503.535275] Starting subtest: basic-read-context
[503.546770] Subtest basic-read-context: SUCCESS (0.010s)
[503.674523] [063/131] (633s left) kms_joiner (switch-modeset-ultra-joiner-big-joiner)
[503.791311] Starting subtest: switch-modeset-ultra-joiner-big-joiner
[503.795422] Subtest switch-modeset-ultra-joiner-big-joiner: SKIP (0.000s)
[503.849290] [064/131] (633s left) kms_dsc (dsc-with-bpc-formats)
[503.949393] Starting subtest: dsc-with-bpc-formats
[504.063604] Subtest dsc-with-bpc-formats: SKIP (0.002s)
[504.100167] [065/131] (633s left) kms_atomic_interruptible (atomic-setmode)
[504.204553] Starting subtest: atomic-setmode
[504.206824] Starting dynamic subtest: pipe-A-eDP-1
[511.057103] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.846s)
[511.059913] Subtest atomic-setmode: SUCCESS (6.847s)
[511.841633] [066/131] (625s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[511.949863] Starting subtest: yf-tiled-16bpp-rotate-0
[511.954253] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[512.047104] [067/131] (625s left) gem_exec_schedule (u-semaphore-resolve)
[512.126244] Starting subtest: u-semaphore-resolve
[512.156154] Subtest u-semaphore-resolve: SUCCESS (0.033s)
[512.506485] [068/131] (624s left) kms_pm_dc (dc5-dpms-negative)
[513.613537] Starting subtest: dc5-dpms-negative
[513.617961] Subtest dc5-dpms-negative: SKIP (0.000s)
[513.680387] [069/131] (623s left) kms_prop_blob (invalid-get-prop-any)
[513.747130] Starting subtest: invalid-get-prop-any
[513.749446] Subtest invalid-get-prop-any: SUCCESS (0.000s)
[513.845001] [070/131] (623s left) kms_cursor_legacy (short-busy-flip-before-cursor-atomic-transitions)
[513.953395] Starting subtest: short-busy-flip-before-cursor-atomic-transitions
[513.957708] Subtest short-busy-flip-before-cursor-atomic-transitions: SKIP (0.000s)
[514.048961] [071/131] (623s left) kms_chamelium_hpd (common-hpd-after-suspend)
[514.426217] Subtest common-hpd-after-suspend: SKIP (0.000s)
[515.247121] [072/131] (622s left) kms_frontbuffer_tracking (psr-rgb101010-draw-pwrite)
[515.368848] Starting subtest: psr-rgb101010-draw-pwrite
[520.808743] Subtest psr-rgb101010-draw-pwrite: SUCCESS (5.440s)
[520.913842] [073/131] (616s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-wc)
[521.011448] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-wc
[521.015618] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[521.106800] [074/131] (616s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-gtt)
[521.207069] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-gtt
[521.211134] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[521.306301] [075/131] (616s left) kms_vblank (query-idle)
[521.399312] Starting subtest: query-idle
[521.401659] Starting dynamic subtest: pipe-A-eDP-1
[523.152430] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.749s)
[523.166869] Starting dynamic subtest: pipe-D-eDP-1
[525.579598] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.423s)
[525.581992] Subtest query-idle: SUCCESS (4.176s)
[526.397951] [076/131] (611s left) prime_self_import (basic-with_two_bos)
[526.435701] Starting subtest: basic-with_two_bos
[526.449366] Subtest basic-with_two_bos: SUCCESS (0.016s)
[526.549944] [077/131] (611s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-pgflip-blt)
[526.655943] Starting subtest: psr-2p-scndscrn-indfb-pgflip-blt
[526.660130] Subtest psr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[526.766172] [078/131] (611s left) gem_exec_balancer (persistence)
[527.016337] Starting subtest: persistence
[527.730427] Subtest persistence: SUCCESS (0.714s)
[527.878500] [079/131] (609s left) kms_fbcon_fbt (psr-suspend)
[527.947922] Starting subtest: psr-suspend
[575.540485] Subtest psr-suspend: SUCCESS (17.457s)
[575.598486] [080/131] (592s left) kms_addfb_basic (basic-x-tiled-legacy)
[575.669565] Starting subtest: basic-x-tiled-legacy
[575.674537] Subtest basic-x-tiled-legacy: SKIP (0.000s)
[575.819869] [081/131] (592s left) api_intel_allocator (reserve-simple)
[575.883895] Starting subtest: reserve-simple
[575.886901] Subtest reserve-simple: SUCCESS (0.003s)
[576.020170] [082/131] (592s left) kms_flip (2x-modeset-vs-vblank-race)
[576.122438] Starting subtest: 2x-modeset-vs-vblank-race
[576.133499] Subtest 2x-modeset-vs-vblank-race: SKIP (0.010s)
[576.206255] [083/131] (591s left) kms_async_flips (async-flip-with-page-flip-events-atomic)
[576.291521] Starting subtest: async-flip-with-page-flip-events-atomic
[576.666627] Starting dynamic subtest: pipe-A-eDP-1-4-mc-ccs
[577.446149] Dynamic subtest pipe-A-eDP-1-4-mc-ccs: SKIP (0.764s)
[577.454915] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[579.693351] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.221s)
[579.705593] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs-cc
[579.965379] Dynamic subtest pipe-A-eDP-1-4-rc-ccs-cc: SKIP (0.263s)
[579.975421] Starting dynamic subtest: pipe-A-eDP-1-4
[582.223359] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.242s)
[582.232880] Starting dynamic subtest: pipe-A-eDP-1-x
[584.510007] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.270s)
[584.525883] Starting dynamic subtest: pipe-A-eDP-1-linear
[586.825317] Dynamic subtest pipe-A-eDP-1-linear: SUCCESS (2.302s)
[587.196174] Starting dynamic subtest: pipe-B-eDP-1-4-mc-ccs
[587.954655] Dynamic subtest pipe-B-eDP-1-4-mc-ccs: SKIP (0.759s)
[587.964616] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[590.209677] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.249s)
[590.218869] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs-cc
[590.473862] Dynamic subtest pipe-B-eDP-1-4-rc-ccs-cc: SKIP (0.259s)
[590.483160] Starting dynamic subtest: pipe-B-eDP-1-4
[592.762312] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.286s)
[592.778840] Starting dynamic subtest: pipe-B-eDP-1-x
[595.058417] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.289s)
[595.070020] Starting dynamic subtest: pipe-B-eDP-1-linear
[597.373635] Dynamic subtest pipe-B-eDP-1-linear: SUCCESS (2.312s)
[597.739545] Starting dynamic subtest: pipe-C-eDP-1-4-mc-ccs
[598.501834] Dynamic subtest pipe-C-eDP-1-4-mc-ccs: SKIP (0.767s)
[598.510730] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[600.743877] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.242s)
[600.752690] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs-cc
[601.010667] Dynamic subtest pipe-C-eDP-1-4-rc-ccs-cc: SKIP (0.263s)
[601.019381] Starting dynamic subtest: pipe-C-eDP-1-4
[603.302516] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.292s)
[603.313417] Starting dynamic subtest: pipe-C-eDP-1-x
[605.580583] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.277s)
[605.589297] Starting dynamic subtest: pipe-C-eDP-1-linear
[607.871391] Dynamic subtest pipe-C-eDP-1-linear: SUCCESS (2.288s)
[608.234692] Starting dynamic subtest: pipe-D-eDP-1-4-mc-ccs
[609.038766] Dynamic subtest pipe-D-eDP-1-4-mc-ccs: SKIP (0.804s)
[609.057484] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[611.289746] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.255s)
[611.299421] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs-cc
[611.553630] Dynamic subtest pipe-D-eDP-1-4-rc-ccs-cc: SKIP (0.262s)
[611.562066] Starting dynamic subtest: pipe-D-eDP-1-4
[613.824871] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.271s)
[613.837047] Starting dynamic subtest: pipe-D-eDP-1-x
[616.124886] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.299s)
[616.136361] Starting dynamic subtest: pipe-D-eDP-1-linear
[618.426673] Dynamic subtest pipe-D-eDP-1-linear: SUCCESS (2.299s)
[618.429621] Subtest async-flip-with-page-flip-events-atomic: SUCCESS (41.997s)
[619.617282] [084/131] (548s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[619.737574] Starting subtest: cursor-vs-flip-legacy
[649.162376] Subtest cursor-vs-flip-legacy: SUCCESS (29.410s)
[650.035197] [085/131] (518s left) kms_big_fb (4-tiled-16bpp-rotate-90)
[650.135670] Starting subtest: 4-tiled-16bpp-rotate-90
[650.154727] Subtest 4-tiled-16bpp-rotate-90: SKIP (0.019s)
[650.291649] [086/131] (518s left) syncobj_wait (multi-wait-all-for-submit-submitted)
[650.352851] Starting subtest: multi-wait-all-for-submit-submitted
[650.461244] Subtest multi-wait-all-for-submit-submitted: SUCCESS (0.109s)
[650.533093] [087/131] (517s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format)
[650.643576] Starting subtest: plane-downscale-factor-0-75-with-pixel-format
[650.646195] Starting dynamic subtest: pipe-A
[652.350544] Dynamic subtest pipe-A: SUCCESS (1.710s)
[652.361185] Starting dynamic subtest: pipe-B
[655.067847] Dynamic subtest pipe-B: SUCCESS (2.715s)
[655.076869] Starting dynamic subtest: pipe-C
[656.260772] Dynamic subtest pipe-C: SUCCESS (1.191s)
[656.270478] Starting dynamic subtest: pipe-D
[657.508644] Dynamic subtest pipe-D: SUCCESS (1.247s)
[657.511368] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (6.866s)
[658.677200] [088/131] (509s left) gem_ctx_param (basic)
[658.753845] Starting subtest: basic
[658.756855] Subtest basic: SUCCESS (0.001s)
[658.866338] [089/131] (509s left) syncobj_eventfd (timeline-wait-signaled)
[658.933644] Starting subtest: timeline-wait-signaled
[658.936347] Subtest timeline-wait-signaled: SUCCESS (0.000s)
[659.027063] [090/131] (509s left) sw_sync (sync_random_merge)
[659.066591] Starting subtest: sync_random_merge
[659.095532] Subtest sync_random_merge: SUCCESS (0.035s)
[659.157301] [091/131] (509s left) syncobj_wait (wait-all-delayed-signal)
[659.222050] Starting subtest: wait-all-delayed-signal
[659.326069] Subtest wait-all-delayed-signal: SUCCESS (0.103s)
[659.396597] [092/131] (509s left) perf_pmu (init-sema)
[659.444538] Starting subtest: init-sema
[659.447192] Starting dynamic subtest: rcs0
[659.449706] Dynamic subtest rcs0: SUCCESS (0.003s)
[659.459084] Starting dynamic subtest: bcs0
[659.461576] Dynamic subtest bcs0: SUCCESS (0.000s)
[659.469846] Starting dynamic subtest: vcs0
[659.472341] Dynamic subtest vcs0: SUCCESS (0.000s)
[659.480424] Starting dynamic subtest: vcs1
[659.482697] Dynamic subtest vcs1: SUCCESS (0.001s)
[659.490652] Starting dynamic subtest: vecs0
[659.493105] Dynamic subtest vecs0: SUCCESS (0.001s)
[659.501293] Starting dynamic subtest: ccs0
[659.503603] Dynamic subtest ccs0: SUCCESS (0.001s)
[659.505871] Subtest init-sema: SUCCESS (0.009s)
[659.565929] [093/131] (509s left) gem_exec_whisper (basic-contexts-forked-all)
[659.621640] Starting subtest: basic-contexts-forked-all
[682.171123] Subtest basic-contexts-forked-all: SUCCESS (22.551s)
[682.353059] [094/131] (486s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf)
[687.398179] Starting subtest: fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf
[687.401235] Starting dynamic subtest: pipe-A-eDP-1
[688.198270] Dynamic subtest pipe-A-eDP-1: SKIP (0.796s)
[688.207891] Starting dynamic subtest: pipe-B-eDP-1
[689.321850] Dynamic subtest pipe-B-eDP-1: SKIP (1.121s)
[689.327774] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-fully-sf: SKIP (1.921s)
[690.500249] [095/131] (478s left) kms_color (degamma)
[690.597420] Starting subtest: degamma
[690.599852] Starting dynamic subtest: pipe-A-eDP-1
[691.311214] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.713s)
[691.321845] Starting dynamic subtest: pipe-B-eDP-1
[692.715660] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.402s)
[692.725490] Starting dynamic subtest: pipe-C-eDP-1
[694.079060] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.364s)
[694.089299] Starting dynamic subtest: pipe-D-eDP-1
[695.404586] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.324s)
[695.406887] Subtest degamma: SUCCESS (4.806s)
[696.206393] [096/131] (472s left) gem_ctx_persistence (heartbeat-close)
[696.259584] Starting subtest: heartbeat-close
[696.265610] Subtest heartbeat-close: SKIP (0.000s)
[696.365356] [097/131] (472s left) gem_pxp (display-protected-crc)
[696.495785] Starting subtest: display-protected-crc
[698.472898] Subtest display-protected-crc: SUCCESS (1.981s)
[698.607247] [098/131] (470s left) kms_psr (psr2-sprite-blt)
[698.709335] Starting subtest: psr2-sprite-blt
[698.711716] Starting dynamic subtest: eDP-1
[700.391675] Dynamic subtest eDP-1: SUCCESS (1.680s)
[700.393901] Subtest psr2-sprite-blt: SUCCESS (1.681s)
[701.253596] [099/131] (467s left) gem_eio (wait-immediate)
[701.469720] Starting subtest: wait-immediate
[701.823634] Subtest wait-immediate: SUCCESS (0.356s)
[701.935294] [100/131] (466s left) fbdev (read)
[701.981626] Starting subtest: read
[702.511564] Subtest read: SUCCESS (0.528s)
[702.570998] [101/131] (466s left) prime_vgem (fence-wait)
[702.662674] Starting subtest: fence-wait
[702.665660] Starting dynamic subtest: rcs0
[703.743131] Dynamic subtest rcs0: SUCCESS (1.083s)
[703.754538] Starting dynamic subtest: bcs0
[704.841236] Dynamic subtest bcs0: SUCCESS (1.097s)
[704.852928] Starting dynamic subtest: vcs0
[705.897483] Dynamic subtest vcs0: SUCCESS (1.055s)
[705.909118] Starting dynamic subtest: vcs1
[706.941872] Dynamic subtest vcs1: SUCCESS (1.043s)
[706.954151] Starting dynamic subtest: vecs0
[707.984440] Dynamic subtest vecs0: SUCCESS (1.041s)
[707.996963] Starting dynamic subtest: ccs0
[709.029594] Dynamic subtest ccs0: SUCCESS (1.044s)
[709.032690] Subtest fence-wait: SUCCESS (6.370s)
[709.182686] [102/131] (459s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-fullscreen)
[709.285289] Starting subtest: fbc-1p-primscrn-spr-indfb-fullscreen
[712.248295] Subtest fbc-1p-primscrn-spr-indfb-fullscreen: SUCCESS (2.967s)
[712.371168] [103/131] (456s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-onoff)
[712.466556] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-onoff
[712.470759] Subtest fbcpsr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[712.565367] [104/131] (456s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions-varying-size)
[712.685017] Starting subtest: cursorA-vs-flipB-atomic-transitions-varying-size
[712.689373] Subtest cursorA-vs-flipB-atomic-transitions-varying-size: SKIP (0.001s)
[712.780536] [105/131] (456s left) kms_flip (flip-vs-suspend)
[712.872522] Starting subtest: flip-vs-suspend
[712.874636] Starting dynamic subtest: A-eDP1
[762.389749] Dynamic subtest A-eDP1: SUCCESS (4.403s)
[762.404937] Starting dynamic subtest: B-eDP1
[811.413967] Dynamic subtest B-eDP1: SUCCESS (4.300s)
[811.423957] Starting dynamic subtest: C-eDP1
[860.391914] Dynamic subtest C-eDP1: SUCCESS (4.294s)
[860.412367] Starting dynamic subtest: D-eDP1
[909.404402] Dynamic subtest D-eDP1: SUCCESS (4.275s)
[909.406846] Subtest flip-vs-suspend: SUCCESS (17.283s)
[910.620078] [106/131] (437s left) kms_fbcon_fbt (fbc-suspend)
[910.688530] Starting subtest: fbc-suspend
[957.561456] Subtest fbc-suspend: SUCCESS (17.643s)
[957.627307] [107/131] (420s left) kms_frontbuffer_tracking (pipe-fbc-rte)
[957.745933] Starting subtest: pipe-fbc-rte
[957.753920] Starting dynamic subtest: pipe-B-eDP-1
[962.340922] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.606s)
[962.349532] Subtest pipe-fbc-rte: SUCCESS (4.608s)
[963.537312] [108/131] (414s left) kms_psr (fbc-psr-sprite-blt)
[963.637707] Starting subtest: fbc-psr-sprite-blt
[963.640621] Starting dynamic subtest: eDP-1
[963.645645] Dynamic subtest eDP-1: SKIP (0.000s)
[963.650577] Subtest fbc-psr-sprite-blt: SKIP (0.001s)
[963.706791] [109/131] (414s left) gem_exec_reloc (basic-wc-noreloc)
[963.771750] Subtest basic-wc-noreloc: SKIP (0.000s)
[963.859431] [110/131] (413s left) gem_eio (create-ext)
[964.068697] Starting subtest: create-ext
[964.386761] Subtest create-ext: SUCCESS (0.319s)
[964.536299] [111/131] (413s left) fbdev (write)
[964.579585] Starting subtest: write
[964.940980] Subtest write: SUCCESS (0.359s)
[965.003762] [112/131] (412s left) i915_pm_rps (waitboost)
[965.064888] Starting subtest: waitboost
[965.158130] Subtest waitboost: FAIL (0.027s)
[965.236044] [113/131] (412s left) gem_partial_pwrite_pread (reads-snoop)
[965.295726] Subtest reads-snoop: SKIP (0.000s)
[965.364739] [114/131] (412s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-render)
[965.471657] Starting subtest: fbc-rgb101010-draw-render
[969.019310] Subtest fbc-rgb101010-draw-render: SUCCESS (3.555s)
[969.151480] [115/131] (408s left) sw_sync (sync_busy)
[969.189355] Starting subtest: sync_busy
[969.191712] Subtest sync_busy: SUCCESS (0.002s)
[969.238153] [116/131] (408s left) kms_flip (flip-vs-absolute-wf_vblank)
[969.335782] Starting subtest: flip-vs-absolute-wf_vblank
[969.338546] Starting dynamic subtest: A-eDP1
[978.294624] Dynamic subtest A-eDP1: SUCCESS (8.964s)
[978.304311] Starting dynamic subtest: B-eDP1
[987.218662] Dynamic subtest B-eDP1: SUCCESS (8.926s)
[987.227749] Starting dynamic subtest: C-eDP1
[996.134504] Dynamic subtest C-eDP1: SUCCESS (8.909s)
[996.145786] Starting dynamic subtest: D-eDP1
[1005.066330] Dynamic subtest D-eDP1: SUCCESS (8.929s)
[1005.068873] Subtest flip-vs-absolute-wf_vblank: SUCCESS (35.739s)
[1006.285119] [117/131] (371s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[1011.330484] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[1011.333283] Starting dynamic subtest: pipe-A-eDP-1
[1017.941681] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.611s)
[1017.952547] Starting dynamic subtest: pipe-B-eDP-1
[1024.572207] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.632s)
[1024.574723] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (13.247s)
[1025.450249] [118/131] (352s left) kms_flip (2x-blocking-absolute-wf_vblank-interruptible)
[1025.555707] Starting subtest: 2x-blocking-absolute-wf_vblank-interruptible
[1025.567122] Subtest 2x-blocking-absolute-wf_vblank-interruptible: SKIP (0.010s)
[1025.638277] [119/131] (352s left) gem_exec_reloc (basic-cpu-gtt-noreloc)
[1025.707934] Subtest basic-cpu-gtt-noreloc: SKIP (0.000s)
[1025.769889] [120/131] (352s left) kms_plane_multiple (tiling-yf)
[1025.865557] Starting subtest: tiling-yf
[1025.870733] Subtest tiling-yf: SKIP (0.000s)
[1025.933327] [121/131] (352s left) kms_big_fb (yf-tiled-16bpp-rotate-90)
[1026.033588] Starting subtest: yf-tiled-16bpp-rotate-90
[1026.038609] Subtest yf-tiled-16bpp-rotate-90: SKIP (0.000s)
[1026.110356] [122/131] (352s left) kms_psr (fbc-psr-basic)
[1026.200497] Starting subtest: fbc-psr-basic
[1026.202934] Starting dynamic subtest: eDP-1
[1026.207920] Dynamic subtest eDP-1: SKIP (0.000s)
[1026.212956] Subtest fbc-psr-basic: SKIP (0.001s)
[1026.268901] [123/131] (351s left) gem_exec_capture (userptr)
[1026.325130] Starting subtest: userptr
[1027.164322] Subtest userptr: SUCCESS (0.840s)
[1027.292347] [124/131] (350s left) gen9_exec_parse (batch-invalid-length)
[1027.353588] Subtest batch-invalid-length: SKIP (0.000s)
[1027.430079] [125/131] (350s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[1027.482681] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[1027.485341] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[1027.582669] [126/131] (350s left) gem_ctx_isolation (clean)
[1027.645894] Starting subtest: clean
[1027.648734] Starting dynamic subtest: rcs0
[1027.728346] Dynamic subtest rcs0: SUCCESS (0.082s)
[1027.738451] Starting dynamic subtest: bcs0
[1027.838179] Dynamic subtest bcs0: SUCCESS (0.109s)
[1027.849906] Starting dynamic subtest: vcs0
[1027.943087] Dynamic subtest vcs0: SUCCESS (0.104s)
[1027.953046] Starting dynamic subtest: vcs1
[1030.004905] Dynamic subtest vcs1: SUCCESS (2.062s)
[1030.014536] Starting dynamic subtest: vecs0
[1032.029911] Dynamic subtest vecs0: SUCCESS (2.025s)
[1032.039612] Starting dynamic subtest: ccs0
[1034.046431] Dynamic subtest ccs0: SUCCESS (2.016s)
[1034.049106] Subtest clean: SUCCESS (6.403s)
[1034.290813] [127/131] (344s left) gem_exec_schedule (preempt-other-chain)
[1034.363625] Starting subtest: preempt-other-chain
[1034.366472] Starting dynamic subtest: rcs0
[1034.498788] Dynamic subtest rcs0: SUCCESS (0.144s)
[1034.511390] Starting dynamic subtest: bcs0
[1034.626694] Dynamic subtest bcs0: SUCCESS (0.128s)
[1034.639685] Starting dynamic subtest: vcs0
[1034.758805] Dynamic subtest vcs0: SUCCESS (0.131s)
[1034.771765] Starting dynamic subtest: vcs1
[1034.891109] Dynamic subtest vcs1: SUCCESS (0.132s)
[1034.901532] Starting dynamic subtest: vecs0
[1035.022291] Dynamic subtest vecs0: SUCCESS (0.131s)
[1035.034444] Starting dynamic subtest: ccs0
[1035.151580] Dynamic subtest ccs0: SUCCESS (0.129s)
[1035.154234] Subtest preempt-other-chain: SUCCESS (0.798s)
[1036.040821] [128/131] (342s left) dmabuf_sync_file (export-basic)
[1036.102108] Starting subtest: export-basic
[1036.104491] Subtest export-basic: SUCCESS (0.001s)
[1036.156142] [129/131] (342s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt)
[1036.259724] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt
[1036.265095] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[1036.350460] [130/131] (342s left) kms_busy (extended-modeset-hang-newfb)
[1036.457837] Starting subtest: extended-modeset-hang-newfb
[1036.460623] Starting dynamic subtest: pipe-A
[1070.831930] Dynamic subtest pipe-A: SUCCESS (34.368s)
[1070.842991] Starting dynamic subtest: pipe-D
[1093.798775] Dynamic subtest pipe-D: SUCCESS (22.964s)
[1093.801676] Subtest extended-modeset-hang-newfb: SUCCESS (57.338s)
[1094.579998] [131/131] (283s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[1094.694868] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[1094.700570] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[1094.796983] Closing watchdogs
results: parsing output: 0/ for test: kms_big_fb
results: parsing output: 1/ for test: kms_cursor_crc
results: parsing output: 2/ for test: kms_pm_rpm
results: parsing output: 3/ for test: gem_mmap_wc
results: parsing output: 4/ for test: kms_frontbuffer_tracking
results: parsing output: 5/ for test: gem_mmap_gtt
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: gem_exercise_blt
results: parsing output: 8/ for test: gem_tiled_fence_blits
results: parsing output: 9/ for test: kms_lease
results: parsing output: 10/ for test: syncobj_wait
results: parsing output: 11/ for test: gem_wait
results: parsing output: 12/ for test: gem_ctx_persistence
results: parsing output: 13/ for test: perf_pmu
results: parsing output: 14/ for test: gem_exec_fence
results: parsing output: 15/ for test: kms_psr
results: parsing output: 16/ for test: kms_plane_cursor
results: parsing output: 17/ for test: kms_fence_pin_leak
results: parsing output: 18/ for test: debugfs_test
results: parsing output: 19/ for test: kms_ccs
results: parsing output: 20/ for test: kms_frontbuffer_tracking
results: parsing output: 21/ for test: kms_rotation_crc
results: parsing output: 22/ for test: gem_render_copy
results: parsing output: 23/ for test: kms_frontbuffer_tracking
results: parsing output: 24/ for test: kms_ccs
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_ccs
results: parsing output: 27/ for test: gem_exec_schedule
results: parsing output: 28/ for test: syncobj_timeline
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: kms_flip_scaled_crc
results: parsing output: 31/ for test: gem_exec_reloc
results: parsing output: 32/ for test: kms_cursor_crc
results: parsing output: 33/ for test: kms_big_fb
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: syncobj_timeline
results: parsing output: 36/ for test: gem_exec_fence
results: parsing output: 37/ for test: kms_ccs
results: parsing output: 38/ for test: syncobj_wait
results: parsing output: 39/ for test: kms_frontbuffer_tracking
results: parsing output: 40/ for test: gem_softpin
results: parsing output: 41/ for test: gem_exec_reloc
results: parsing output: 42/ for test: kms_frontbuffer_tracking
results: parsing output: 43/ for test: kms_big_fb
results: parsing output: 44/ for test: prime_self_import
results: parsing output: 45/ for test: syncobj_timeline
results: parsing output: 46/ for test: sysfs_timeslice_duration
results: parsing output: 47/ for test: kms_cursor_crc
results: parsing output: 48/ for test: kms_psr
results: parsing output: 49/ for test: kms_frontbuffer_tracking
results: parsing output: 50/ for test: kms_writeback
results: parsing output: 51/ for test: kms_cursor_legacy
results: parsing output: 52/ for test: kms_chamelium_color
results: parsing output: 53/ for test: gem_exec_suspend
results: parsing output: 54/ for test: gem_exec_nop
results: parsing output: 55/ for test: kms_ccs
results: parsing output: 56/ for test: kms_frontbuffer_tracking
results: parsing output: 57/ for test: gem_render_copy
results: parsing output: 58/ for test: gem_mmap_offset
results: parsing output: 59/ for test: api_intel_allocator
results: parsing output: 60/ for test: i915_pm_freq_api
results: parsing output: 61/ for test: gem_workarounds
results: parsing output: 62/ for test: kms_joiner
results: parsing output: 63/ for test: kms_dsc
results: parsing output: 64/ for test: kms_atomic_interruptible
results: parsing output: 65/ for test: kms_big_fb
results: parsing output: 66/ for test: gem_exec_schedule
results: parsing output: 67/ for test: kms_pm_dc
results: parsing output: 68/ for test: kms_prop_blob
results: parsing output: 69/ for test: kms_cursor_legacy
results: parsing output: 70/ for test: kms_chamelium_hpd
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_vblank
results: parsing output: 75/ for test: prime_self_import
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_exec_balancer
results: parsing output: 78/ for test: kms_fbcon_fbt
results: parsing output: 79/ for test: kms_addfb_basic
results: parsing output: 80/ for test: api_intel_allocator
results: parsing output: 81/ for test: kms_flip
results: parsing output: 82/ for test: kms_async_flips
results: parsing output: 83/ for test: kms_cursor_legacy
results: parsing output: 84/ for test: kms_big_fb
results: parsing output: 85/ for test: syncobj_wait
results: parsing output: 86/ for test: kms_plane_scaling
results: parsing output: 87/ for test: gem_ctx_param
results: parsing output: 88/ for test: syncobj_eventfd
results: parsing output: 89/ for test: sw_sync
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: perf_pmu
results: parsing output: 92/ for test: gem_exec_whisper
results: parsing output: 93/ for test: kms_psr2_sf
results: parsing output: 94/ for test: kms_color
results: parsing output: 95/ for test: gem_ctx_persistence
results: parsing output: 96/ for test: gem_pxp
results: parsing output: 97/ for test: kms_psr
results: parsing output: 98/ for test: gem_eio
results: parsing output: 99/ for test: fbdev
results: parsing output: 100/ for test: prime_vgem
results: parsing output: 101/ for test: kms_frontbuffer_tracking
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: kms_cursor_legacy
results: parsing output: 104/ for test: kms_flip
results: parsing output: 105/ for test: kms_fbcon_fbt
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_psr
results: parsing output: 108/ for test: gem_exec_reloc
results: parsing output: 109/ for test: gem_eio
results: parsing output: 110/ for test: fbdev
results: parsing output: 111/ for test: i915_pm_rps
results: parsing output: 112/ for test: gem_partial_pwrite_pread
results: parsing output: 113/ for test: kms_frontbuffer_tracking
results: parsing output: 114/ for test: sw_sync
results: parsing output: 115/ for test: kms_flip
results: parsing output: 116/ for test: kms_psr2_sf
results: parsing output: 117/ for test: kms_flip
results: parsing output: 118/ for test: gem_exec_reloc
results: parsing output: 119/ for test: kms_plane_multiple
results: parsing output: 120/ for test: kms_big_fb
results: parsing output: 121/ for test: kms_psr
results: parsing output: 122/ for test: gem_exec_capture
results: parsing output: 123/ for test: gen9_exec_parse
results: parsing output: 124/ for test: syncobj_wait
results: parsing output: 125/ for test: gem_ctx_isolation
results: parsing output: 126/ for test: gem_exec_schedule
results: parsing output: 127/ for test: dmabuf_sync_file
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: kms_busy
results: parsing output: 130/ for test: kms_frontbuffer_tracking
Done.