[35.824609] Initializing watchdogs
[35.824636]   /dev/watchdog0
[35.831439] [001/130] (960s left) device_reset (unbind-reset-rebind)
[35.876662] Starting subtest: unbind-reset-rebind
[38.858861] Subtest unbind-reset-rebind: SUCCESS (2.979s)
[38.877069] [002/130] (957s left) gem_spin_batch (user-each)
[38.926117] Starting subtest: user-each
[42.193073] Subtest user-each: SUCCESS (3.259s)
[42.419598] [003/130] (953s left) kms_sequence (queue-idle)
[42.502754] Starting subtest: queue-idle
[42.505348] Starting dynamic subtest: pipe-A-eDP-1
[44.319395] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.813s)
[44.326028] Starting dynamic subtest: pipe-B-eDP-1
[46.844240] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.526s)
[46.852293] Starting dynamic subtest: pipe-C-eDP-1
[49.405278] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.561s)
[49.412289] Starting dynamic subtest: pipe-D-eDP-1
[51.942919] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.536s)
[51.944955] Subtest queue-idle: SUCCESS (9.437s)
[52.821879] [004/130] (943s left) syncobj_timeline (invalid-multi-wait-unsubmitted)
[52.878235] Starting subtest: invalid-multi-wait-unsubmitted
[52.880913] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[52.941927] [005/130] (943s left) kms_flip_event_leak (basic)
[53.033903] Starting subtest: basic
[53.036318] Starting dynamic subtest: pipe-A-eDP-1
[53.445888] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.408s)
[53.451865] Starting dynamic subtest: pipe-B-eDP-1
[54.652404] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.206s)
[54.659988] Starting dynamic subtest: pipe-C-eDP-1
[55.857446] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.205s)
[55.863515] Starting dynamic subtest: pipe-D-eDP-1
[56.997651] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.140s)
[57.000443] Subtest basic: SUCCESS (3.962s)
[57.865914] [006/130] (938s left) syncobj_timeline (multi-wait-available-submitted)
[57.904139] Starting subtest: multi-wait-available-submitted
[57.906268] Subtest multi-wait-available-submitted: SUCCESS (0.000s)
[57.958056] [007/130] (938s left) kms_hdmi_inject (inject-4k)
[58.291864] Starting subtest: inject-4k
[58.580388] Subtest inject-4k: SUCCESS (0.290s)
[59.189885] [008/130] (937s left) kms_writeback (writeback-fb-id)
[59.285098] Subtest writeback-fb-id: SKIP (0.000s)
[59.304631] [009/130] (937s left) kms_prime (basic-crc-vgem)
[60.014574] Starting subtest: basic-crc-vgem
[60.016893] Starting dynamic subtest: second-to-first
[60.894318] Dynamic subtest second-to-first: SUCCESS (0.883s)
[60.896289] Subtest basic-crc-vgem: SUCCESS (0.883s)
[60.950328] [010/130] (935s left) kms_cursor_legacy (basic-flip-after-cursor-varying-size)
[61.091371] Starting subtest: basic-flip-after-cursor-varying-size
[62.014759] Subtest basic-flip-after-cursor-varying-size: SUCCESS (0.924s)
[62.925900] [011/130] (933s left) gem_exec_reloc (basic-gtt-cpu-noreloc)
[62.982040] Subtest basic-gtt-cpu-noreloc: SKIP (0.000s)
[63.026511] [012/130] (933s left) gem_exec_whisper (basic-fds)
[63.074010] Starting subtest: basic-fds
[68.363654] Subtest basic-fds: SUCCESS (5.289s)
[68.517838] [013/130] (927s left) gem_huc_copy (huc-copy)
[68.574167] Starting subtest: huc-copy
[68.578901] Subtest huc-copy: SUCCESS (0.003s)
[68.668549] [014/130] (927s left) kms_atomic (atomic-invalid-params)
[68.758771] Starting subtest: atomic-invalid-params
[68.761156] Starting dynamic subtest: pipe-A-eDP-1
[69.137067] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.374s)
[69.139171] Subtest atomic-invalid-params: SUCCESS (0.375s)
[70.036132] [015/130] (926s left) gem_userptr_blits (unsync-overlap)
[70.099119] Subtest unsync-overlap: SKIP (0.000s)
[70.192316] [016/130] (926s left) gen9_exec_parse (bb-secure)
[70.256762] Subtest bb-secure: SKIP (0.000s)
[70.293480] [017/130] (926s left) gem_create (create-massive)
[70.341816] Starting subtest: create-massive
[70.344047] Subtest create-massive: SUCCESS (0.000s)
[70.502965] [018/130] (926s left) prime_vgem (basic-fence-read)
[70.549413] Starting subtest: basic-fence-read
[70.556664] Subtest basic-fence-read: SKIP (0.007s)
[70.623432] [019/130] (925s left) kms_frontbuffer_tracking (fbc-rgb101010-draw-mmap-cpu)
[70.709286] Starting subtest: fbc-rgb101010-draw-mmap-cpu
[74.280327] Subtest fbc-rgb101010-draw-mmap-cpu: SUCCESS (3.574s)
[74.371078] [020/130] (922s left) syncobj_wait (multi-wait-all-signaled)
[74.427779] Starting subtest: multi-wait-all-signaled
[74.430382] Subtest multi-wait-all-signaled: SUCCESS (0.000s)
[74.494680] [021/130] (922s left) kms_frontbuffer_tracking (fbc-1p-offscren-pri-shrfb-draw-pwrite)
[74.589622] Starting subtest: fbc-1p-offscren-pri-shrfb-draw-pwrite
[79.124780] Subtest fbc-1p-offscren-pri-shrfb-draw-pwrite: SUCCESS (4.538s)
[79.236230] [022/130] (917s left) kms_addfb_basic (bo-too-small-due-to-tiling)
[79.315739] Starting subtest: bo-too-small-due-to-tiling
[79.320244] Subtest bo-too-small-due-to-tiling: SKIP (0.000s)
[79.384069] [023/130] (917s left) kms_flip (2x-single-buffer-flip-vs-dpms-off-vs-modeset)
[79.476020] Starting subtest: 2x-single-buffer-flip-vs-dpms-off-vs-modeset
[79.489701] Subtest 2x-single-buffer-flip-vs-dpms-off-vs-modeset: SKIP (0.013s)
[79.542268] [024/130] (917s left) kms_mmap_write_crc (main)
[79.627081] Starting subtest: main
[79.629038] Starting dynamic subtest: pipe-A-eDP-1
[82.701292] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.072s)
[82.703640] Subtest main: SUCCESS (3.073s)
[83.600252] [025/130] (913s left) kms_joiner (invalid-modeset-force-big-joiner)
[83.692865] Starting subtest: invalid-modeset-force-big-joiner
[83.695221] Starting dynamic subtest: big_joiner_on_last_pipe
[84.012038] Dynamic subtest big_joiner_on_last_pipe: SUCCESS (0.322s)
[84.014567] Subtest invalid-modeset-force-big-joiner: SUCCESS (0.322s)
[84.836659] [026/130] (911s left) kms_flip (dpms-vs-vblank-race)
[84.932080] Starting subtest: dpms-vs-vblank-race
[84.934372] Starting dynamic subtest: A-eDP1
[88.932237] Dynamic subtest A-eDP1: SUCCESS (3.998s)
[88.939409] Starting dynamic subtest: B-eDP1
[92.595672] Dynamic subtest B-eDP1: SUCCESS (3.660s)
[92.603726] Starting dynamic subtest: C-eDP1
[96.218371] Dynamic subtest C-eDP1: SUCCESS (3.623s)
[96.225252] Starting dynamic subtest: D-eDP1
[99.866623] Dynamic subtest D-eDP1: SUCCESS (3.648s)
[99.869533] Subtest dpms-vs-vblank-race: SUCCESS (14.936s)
[100.748604] [027/130] (895s left) syncobj_basic (bad-destroy)
[100.804307] Starting subtest: bad-destroy
[100.806614] Subtest bad-destroy: SUCCESS (0.000s)
[100.867514] [028/130] (895s left) gem_workarounds (suspend-resume-fd)
[100.920606] Starting subtest: suspend-resume-fd
[117.409923] Subtest suspend-resume-fd: SUCCESS (1.125s)
[118.521259] [029/130] (894s left) kms_feature_discovery (dp-mst)
[118.627131] Starting subtest: dp-mst
[118.631120] Subtest dp-mst: SKIP (0.000s)
[118.675967] [030/130] (894s left) kms_rotation_crc (sprite-rotation-90-pos-100-0)
[118.760105] Starting subtest: sprite-rotation-90-pos-100-0
[118.764658] Subtest sprite-rotation-90-pos-100-0: SKIP (0.001s)
[118.810226] [031/130] (894s left) kms_ccs (bad-pixel-format-yf-tiled-ccs)
[118.903065] Starting subtest: bad-pixel-format-yf-tiled-ccs
[118.905465] Starting dynamic subtest: pipe-A-eDP-1
[118.928389] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[118.936579] Starting dynamic subtest: pipe-B-eDP-1
[118.958102] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[118.964120] Starting dynamic subtest: pipe-C-eDP-1
[118.986196] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[118.992798] Starting dynamic subtest: pipe-D-eDP-1
[119.015103] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[119.019633] Subtest bad-pixel-format-yf-tiled-ccs: SKIP (0.002s)
[121.231471] [032/130] (891s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[121.336202] Starting subtest: 2x-long-flip-vs-cursor-legacy
[121.340819] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[121.425232] [033/130] (891s left) kms_chamelium_edid (hdmi-edid-read)
[121.797200] Subtest hdmi-edid-read: SKIP (0.000s)
[122.684239] [034/130] (890s left) kms_frontbuffer_tracking (fbc-1p-indfb-fliptrack-mmap-gtt)
[122.774650] Starting subtest: fbc-1p-indfb-fliptrack-mmap-gtt
[122.779657] Subtest fbc-1p-indfb-fliptrack-mmap-gtt: SKIP (0.000s)
[122.852915] [035/130] (890s left) kms_vblank (wait-forked-busy)
[122.931084] Starting subtest: wait-forked-busy
[122.933390] Starting dynamic subtest: pipe-A-eDP-1
[124.795325] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.862s)
[124.808041] Starting dynamic subtest: pipe-D-eDP-1
[127.471618] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.671s)
[127.474473] Subtest wait-forked-busy: SUCCESS (4.536s)
[128.338338] [036/130] (884s left) gem_exec_schedule (submit-early-slice)
[128.400823] Starting subtest: submit-early-slice
[128.403187] Starting dynamic subtest: rcs0
[128.425307] Dynamic subtest rcs0: SUCCESS (0.036s)
[128.433431] Starting dynamic subtest: bcs0
[128.447908] Dynamic subtest bcs0: SUCCESS (0.023s)
[128.454425] Starting dynamic subtest: vcs0
[128.476508] Dynamic subtest vcs0: SUCCESS (0.028s)
[128.485429] Starting dynamic subtest: vcs1
[128.501950] Dynamic subtest vcs1: SUCCESS (0.025s)
[128.509060] Starting dynamic subtest: vecs0
[128.523606] Dynamic subtest vecs0: SUCCESS (0.022s)
[128.530338] Starting dynamic subtest: ccs0
[128.546208] Dynamic subtest ccs0: SUCCESS (0.023s)
[128.548749] Subtest submit-early-slice: SUCCESS (0.157s)
[129.785147] [037/130] (883s left) gem_shrink (reclaim)
[129.829132] Starting subtest: reclaim
[132.099977] Subtest reclaim: SUCCESS (2.271s)
[132.205028] [038/130] (881s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-blt)
[132.314933] Starting subtest: psr-1p-primscrn-cur-indfb-draw-blt
[136.888207] Subtest psr-1p-primscrn-cur-indfb-draw-blt: SUCCESS (4.576s)
[136.990241] [039/130] (876s left) kms_big_fb (x-tiled-32bpp-rotate-90)
[137.082099] Starting subtest: x-tiled-32bpp-rotate-90
[137.102808] Subtest x-tiled-32bpp-rotate-90: SKIP (0.021s)
[137.192983] [040/130] (876s left) syncobj_wait (multi-wait-for-submit-submitted)
[137.239355] Starting subtest: multi-wait-for-submit-submitted
[137.346724] Subtest multi-wait-for-submit-submitted: SUCCESS (0.105s)
[137.397381] [041/130] (875s left) gem_softpin (evict-prime)
[137.446293] Starting subtest: evict-prime
[137.448922] Starting dynamic subtest: rcs0
[138.585783] Dynamic subtest rcs0: SUCCESS (1.141s)
[138.594187] Starting dynamic subtest: bcs0
[139.719543] Dynamic subtest bcs0: SUCCESS (1.133s)
[139.729958] Starting dynamic subtest: vcs0
[141.758232] Dynamic subtest vcs0: SUCCESS (2.035s)
[141.767116] Starting dynamic subtest: vcs1
[143.741823] Dynamic subtest vcs1: SUCCESS (1.983s)
[143.750346] Starting dynamic subtest: vecs0
[145.799164] Dynamic subtest vecs0: SUCCESS (2.057s)
[145.806942] Starting dynamic subtest: ccs0
[146.937557] Dynamic subtest ccs0: SUCCESS (1.139s)
[146.947726] Starting dynamic subtest: all
[148.732016] Dynamic subtest all: SUCCESS (1.792s)
[148.734864] Subtest evict-prime: SUCCESS (11.285s)
[148.786324] [042/130] (864s left) kms_chamelium_hpd (hdmi-hpd-storm)
[149.168767] Subtest hdmi-hpd-storm: SKIP (0.000s)
[150.012824] [043/130] (863s left) kms_big_fb (y-tiled-addfb-size-overflow)
[150.111628] Starting subtest: y-tiled-addfb-size-overflow
[150.115877] Subtest y-tiled-addfb-size-overflow: SKIP (0.000s)
[150.188193] [044/130] (863s left) prime_vgem (basic-write)
[150.259417] Starting subtest: basic-write
[150.272458] Subtest basic-write: SKIP (0.011s)
[150.370150] [045/130] (862s left) perf (non-system-wide-paranoid)
[150.463526] Starting subtest: non-system-wide-paranoid
[150.485463] Subtest non-system-wide-paranoid: SUCCESS (0.022s)
[150.507885] [046/130] (862s left) syncobj_wait (wait-all-interrupted)
[150.551505] Starting subtest: wait-all-interrupted
[150.651666] Subtest wait-all-interrupted: SUCCESS (0.101s)
[150.706463] [047/130] (862s left) gem_exec_balancer (parallel-contexts)
[150.924639] Starting subtest: parallel-contexts
[152.719456] Subtest parallel-contexts: SUCCESS (1.795s)
[152.972623] [048/130] (860s left) gem_busy (semaphore)
[153.162165] Subtest semaphore: SKIP (0.000s)
[153.365017] [049/130] (860s left) kms_cursor_crc (cursor-rapid-movement-max-size)
[153.624781] Starting subtest: cursor-rapid-movement-max-size
[153.630877] Subtest cursor-rapid-movement-max-size: SKIP (0.000s)
[153.737806] [050/130] (859s left) gem_exec_fair (basic-flow)
[153.808432] Subtest basic-flow: SKIP (0.000s)
[153.850230] [051/130] (859s left) i915_hangman (engine-error-state-capture)
[153.906035] Starting subtest: engine-error-state-capture
[153.908315] Starting dynamic subtest: rcs0
[157.707947] Dynamic subtest rcs0: SUCCESS (3.802s)
[157.718180] Starting dynamic subtest: bcs0
[163.725226] Dynamic subtest bcs0: SUCCESS (6.016s)
[163.735105] Starting dynamic subtest: vcs0
[168.652358] Dynamic subtest vcs0: SUCCESS (4.926s)
[168.661686] Starting dynamic subtest: vcs1
[173.643836] Dynamic subtest vcs1: SUCCESS (4.991s)
[173.653883] Starting dynamic subtest: vecs0
[178.636631] Dynamic subtest vecs0: SUCCESS (4.991s)
[178.646434] Starting dynamic subtest: ccs0
[183.692298] Dynamic subtest ccs0: SUCCESS (5.055s)
[183.694736] Subtest engine-error-state-capture: SUCCESS (29.786s)
[183.748599] [052/130] (829s left) kms_cursor_edge_walk (64x64-top-bottom)
[183.837060] Starting subtest: 64x64-top-bottom
[183.839312] Starting dynamic subtest: pipe-A-eDP-1
[187.369585] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.530s)
[187.378516] Starting dynamic subtest: pipe-D-eDP-1
[190.575497] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.204s)
[190.578146] Subtest 64x64-top-bottom: SUCCESS (6.736s)
[191.439648] [053/130] (822s left) api_intel_allocator (two-level-inception-interruptible)
[191.480307] Starting subtest: two-level-inception-interruptible
[196.873199] Subtest two-level-inception-interruptible: SUCCESS (5.392s)
[196.973620] [054/130] (816s left) syncobj_timeline (etime-multi-wait-all-for-submit-unsubmitted-submitted-signa
[197.027322] Starting subtest: etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled
[197.130023] Subtest etime-multi-wait-all-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.104s)
[197.187373] [055/130] (816s left) gem_mmap_wc (read-write)
[197.236410] Subtest read-write: SKIP (0.000s)
[197.276125] [056/130] (816s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf)
[202.376693] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-fully-sf
[202.381868] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.002s)
[203.280910] [057/130] (810s left) perf (invalid-create-userspace-config)
[203.358490] Starting subtest: invalid-create-userspace-config
[203.361122] Subtest invalid-create-userspace-config: SUCCESS (0.001s)
[203.378517] [058/130] (810s left) kms_rotation_crc (multiplane-rotation-cropping-bottom)
[203.457131] Starting subtest: multiplane-rotation-cropping-bottom
[210.639111] Subtest multiplane-rotation-cropping-bottom: SUCCESS (7.182s)
[210.742550] [059/130] (802s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-indfb-draw-pwrite)
[210.834531] Starting subtest: fbcpsr-1p-offscren-pri-indfb-draw-pwrite
[215.131996] Subtest fbcpsr-1p-offscren-pri-indfb-draw-pwrite: SUCCESS (4.299s)
[215.254993] [060/130] (798s left) prime_mmap (test_invalid_sync_flags)
[215.301945] Starting subtest: test_invalid_sync_flags
[215.303774] Starting dynamic subtest: test_invalid_sync_flags-smem
[215.305623] Dynamic subtest test_invalid_sync_flags-smem: SUCCESS (0.000s)
[215.307560] Subtest test_invalid_sync_flags: SUCCESS (0.000s)
[215.355414] [061/130] (798s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip)
[215.445890] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip
[215.449840] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip: SKIP (0.000s)
[215.510431] [062/130] (798s left) perf_pmu (semaphore-busy)
[215.545651] Starting subtest: semaphore-busy
[215.548057] Starting dynamic subtest: rcs0
[217.199127] Dynamic subtest rcs0: SUCCESS (1.652s)
[217.206957] Starting dynamic subtest: bcs0
[218.824572] Dynamic subtest bcs0: SUCCESS (1.624s)
[218.833465] Starting dynamic subtest: vcs0
[223.274502] Dynamic subtest vcs0: SUCCESS (4.448s)
[223.283515] Starting dynamic subtest: vcs1
[229.228157] Dynamic subtest vcs1: SUCCESS (5.954s)
[229.235946] Starting dynamic subtest: vecs0
[235.241551] Dynamic subtest vecs0: SUCCESS (6.011s)
[235.249891] Starting dynamic subtest: ccs0
[238.313959] Dynamic subtest ccs0: SUCCESS (3.072s)
[238.316805] Subtest semaphore-busy: SUCCESS (22.768s)
[238.410776] [063/130] (775s left) gem_eio (in-flight-10ms)
[238.588272] Starting subtest: in-flight-10ms
[246.011163] Subtest in-flight-10ms: SUCCESS (7.422s)
[246.110533] [064/130] (767s left) kms_cursor_crc (cursor-rapid-movement-128x128)
[246.262038] Starting subtest: cursor-rapid-movement-128x128
[246.264365] Starting dynamic subtest: pipe-A-eDP-1
[246.773389] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.507s)
[246.791819] Starting dynamic subtest: pipe-D-eDP-1
[248.014695] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.227s)
[248.017244] Subtest cursor-rapid-movement-128x128: SUCCESS (1.751s)
[248.876651] [065/130] (764s left) drm_fdinfo (virtual-busy-idle-all)
[248.913978] Starting subtest: virtual-busy-idle-all
[248.918850] Subtest virtual-busy-idle-all: SKIP (0.000s)
[248.963481] [066/130] (764s left) core_getversion (all-cards)
[248.996976] Starting subtest: all-cards
[249.012413] Subtest all-cards: SUCCESS (0.019s)
[249.027646] [067/130] (764s left) kms_psr (fbc-psr-cursor-mmap-cpu)
[249.129619] Starting subtest: fbc-psr-cursor-mmap-cpu
[249.132073] Starting dynamic subtest: eDP-1
[249.136843] Dynamic subtest eDP-1: SKIP (0.000s)
[249.141559] Subtest fbc-psr-cursor-mmap-cpu: SKIP (0.001s)
[249.184875] [068/130] (764s left) i915_pm_freq_api (freq-suspend)
[249.246938] Starting subtest: freq-suspend
[249.250004] Starting dynamic subtest: gt0
[265.405715] Dynamic subtest gt0: SUCCESS (1.147s)
[265.429311] Starting dynamic subtest: gt1
[281.311721] Dynamic subtest gt1: SUCCESS (1.019s)
[281.336883] Subtest freq-suspend: SUCCESS (2.167s)
[282.361789] [069/130] (762s left) gem_mmap_gtt (pf-nonblock)
[282.412779] Subtest pf-nonblock: SKIP (0.000s)
[282.463079] [070/130] (762s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[282.561956] Starting subtest: 2x-absolute-wf_vblank-interruptible
[282.577476] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.013s)
[282.629848] [071/130] (762s left) syncobj_timeline (single-wait-for-submit-available-submitted)
[282.674328] Starting subtest: single-wait-for-submit-available-submitted
[282.676774] Subtest single-wait-for-submit-available-submitted: SUCCESS (0.000s)
[282.751709] [072/130] (761s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-cpu)
[282.850940] Starting subtest: fbc-rgb565-draw-mmap-cpu
[285.598159] Subtest fbc-rgb565-draw-mmap-cpu: SUCCESS (2.747s)
[285.687299] [073/130] (759s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[285.772695] Starting subtest: addfb25-yf-tiled-legacy
[285.774671] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[285.838372] [074/130] (758s left) gem_ctx_shared (detached-shared-gtt)
[285.897243] Starting subtest: detached-shared-gtt
[288.053393] Subtest detached-shared-gtt: SUCCESS (2.153s)
[288.377385] [075/130] (756s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[288.427337] Starting subtest: y-tiled-to-vebox-yf-tiled
[288.431873] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[288.581899] [076/130] (756s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[288.668461] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[293.002367] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (4.335s)
[293.114696] [077/130] (751s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[293.215466] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-mmap-wc
[293.219779] Subtest psr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[293.285263] [078/130] (751s left) kms_psr (fbc-pr-sprite-blt)
[293.379086] Starting subtest: fbc-pr-sprite-blt
[293.383440] Subtest fbc-pr-sprite-blt: SKIP (0.000s)
[293.430144] [079/130] (751s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc)
[293.509035] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc
[293.511429] Starting dynamic subtest: pipe-A-eDP-1
[293.910861] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.369s)
[293.919821] Starting dynamic subtest: pipe-B-eDP-1
[295.350060] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.437s)
[295.361484] Starting dynamic subtest: pipe-C-eDP-1
[296.788386] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.438s)
[296.797024] Starting dynamic subtest: pipe-D-eDP-1
[298.209554] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.419s)
[298.211879] Subtest crc-sprite-planes-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (4.665s)
[299.350715] [080/130] (745s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu)
[299.447976] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu
[299.452617] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[299.537063] [081/130] (745s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render)
[299.627470] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render
[299.631760] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s)
[299.694020] [082/130] (745s left) kms_psr (fbc-psr2-sprite-mmap-cpu)
[299.779107] Starting subtest: fbc-psr2-sprite-mmap-cpu
[299.781514] Starting dynamic subtest: eDP-1
[299.785957] Dynamic subtest eDP-1: SKIP (0.000s)
[299.790434] Subtest fbc-psr2-sprite-mmap-cpu: SKIP (0.001s)
[299.830544] [083/130] (745s left) kms_invalid_mode (zero-clock)
[299.918528] Starting subtest: zero-clock
[299.920897] Starting dynamic subtest: pipe-A-eDP-1
[300.196913] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.280s)
[300.205823] Starting dynamic subtest: pipe-B-eDP-1
[300.207921] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[300.214338] Starting dynamic subtest: pipe-C-eDP-1
[300.216689] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[300.226645] Starting dynamic subtest: pipe-D-eDP-1
[300.229647] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[300.232739] Subtest zero-clock: SUCCESS (0.284s)
[301.079822] [084/130] (743s left) kms_psr (psr2-cursor-render)
[301.154164] Starting subtest: psr2-cursor-render
[301.156468] Starting dynamic subtest: eDP-1
[302.883730] Dynamic subtest eDP-1: SUCCESS (1.728s)
[302.886398] Subtest psr2-cursor-render: SUCCESS (1.728s)
[303.769036] [085/130] (741s left) gem_render_copy (linear-to-vebox-y-tiled)
[303.814541] Starting subtest: linear-to-vebox-y-tiled
[303.819328] Subtest linear-to-vebox-y-tiled: SKIP (0.000s)
[303.971523] [086/130] (740s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[304.056352] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[304.058593] Starting dynamic subtest: pipe-A-eDP-1
[304.772732] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.705s)
[304.781054] Starting dynamic subtest: pipe-B-eDP-1
[306.411815] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.635s)
[306.421223] Starting dynamic subtest: pipe-C-eDP-1
[307.971700] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.560s)
[307.979894] Starting dynamic subtest: pipe-D-eDP-1
[309.505704] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.534s)
[309.507616] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.438s)
[310.745510] [087/130] (734s left) gem_exec_reloc (basic-gtt-cpu)
[310.798537] Subtest basic-gtt-cpu: SKIP (0.000s)
[310.847074] [088/130] (734s left) gem_exec_schedule (deep)
[311.014111] Starting subtest: deep
[311.016665] Starting dynamic subtest: rcs0
[314.108908] Dynamic subtest rcs0: SKIP (3.091s)
[314.126351] Starting dynamic subtest: vcs0
[322.743339] Dynamic subtest vcs0: SUCCESS (8.634s)
[322.751646] Starting dynamic subtest: vcs1
[332.362115] Dynamic subtest vcs1: SUCCESS (9.616s)
[332.373503] Starting dynamic subtest: vecs0
[341.467931] Dynamic subtest vecs0: SUCCESS (9.103s)
[341.478781] Starting dynamic subtest: ccs0
[351.266587] Dynamic subtest ccs0: SUCCESS (9.794s)
[351.269461] Subtest deep: SUCCESS (40.241s)
[352.209927] [089/130] (692s left) syncobj_eventfd (binary-wait-signaled)
[352.257638] Starting subtest: binary-wait-signaled
[352.259982] Subtest binary-wait-signaled: SUCCESS (0.000s)
[352.332924] [090/130] (692s left) kms_busy (extended-modeset-hang-newfb)
[352.431016] Starting subtest: extended-modeset-hang-newfb
[352.433389] Starting dynamic subtest: pipe-A
[387.340005] Dynamic subtest pipe-A: SUCCESS (34.904s)
[387.351660] Starting dynamic subtest: pipe-D
[404.488165] Dynamic subtest pipe-D: SUCCESS (17.146s)
[404.491459] Subtest extended-modeset-hang-newfb: SUCCESS (52.058s)
[405.400785] [091/130] (639s left) kms_big_fb (x-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[405.491244] Starting subtest: x-tiled-max-hw-stride-64bpp-rotate-0-hflip
[406.421836] Subtest x-tiled-max-hw-stride-64bpp-rotate-0-hflip: SUCCESS (0.933s)
[406.534135] [092/130] (638s left) gem_exec_fence (basic-await)
[406.703462] Starting subtest: basic-await
[406.705748] Starting dynamic subtest: rcs0
[406.769465] Dynamic subtest rcs0: SUCCESS (0.067s)
[406.781745] Starting dynamic subtest: bcs0
[406.834057] Dynamic subtest bcs0: SUCCESS (0.064s)
[406.842965] Starting dynamic subtest: vcs0
[406.899600] Dynamic subtest vcs0: SUCCESS (0.065s)
[406.909599] Starting dynamic subtest: vcs1
[406.965053] Dynamic subtest vcs1: SUCCESS (0.064s)
[406.975625] Starting dynamic subtest: vecs0
[407.030466] Dynamic subtest vecs0: SUCCESS (0.064s)
[407.040744] Starting dynamic subtest: ccs0
[407.096661] Dynamic subtest ccs0: SUCCESS (0.065s)
[407.099601] Subtest basic-await: SUCCESS (0.394s)
[408.695264] [093/130] (636s left) gem_mmap_wc (write-prefaulted)
[408.736760] Subtest write-prefaulted: SKIP (0.000s)
[408.780234] [094/130] (636s left) kms_plane_scaling (plane-downscale-factor-0-75-with-rotation)
[408.860124] Starting subtest: plane-downscale-factor-0-75-with-rotation
[408.862493] Starting dynamic subtest: pipe-A
[409.113630] Dynamic subtest pipe-A: SUCCESS (0.253s)
[409.123555] Starting dynamic subtest: pipe-B
[410.499843] Dynamic subtest pipe-B: SUCCESS (1.387s)
[410.507786] Starting dynamic subtest: pipe-C
[411.845667] Dynamic subtest pipe-C: SUCCESS (1.345s)
[411.855956] Starting dynamic subtest: pipe-D
[413.187482] Dynamic subtest pipe-D: SUCCESS (1.342s)
[413.189846] Subtest plane-downscale-factor-0-75-with-rotation: SUCCESS (4.328s)
[414.358501] [095/130] (630s left) i915_pm_rps (waitboost)
[414.404526] Starting subtest: waitboost
[414.483132] Subtest waitboost: FAIL (0.022s)
[414.539760] [096/130] (630s left) kms_content_protection (dp-mst-lic-type-0)
[414.682644] Starting subtest: dp-mst-lic-type-0
[414.686892] Subtest dp-mst-lic-type-0: SKIP (0.000s)
[414.796634] [097/130] (630s left) kms_pm_rpm (fences)
[416.817126] Starting subtest: fences
[416.822973] Subtest fences: SKIP (0.000s)
[417.156719] [098/130] (627s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt)
[417.249467] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt
[417.254116] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s)
[417.319259] [099/130] (627s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-async-flip)
[417.411112] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip
[418.078052] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-async-flip: SUCCESS (0.670s)
[418.215441] [100/130] (626s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-25)
[418.308271] Starting subtest: planes-unity-scaling-downscale-factor-0-25
[418.310726] Starting dynamic subtest: pipe-A
[418.322934] Dynamic subtest pipe-A: SKIP (0.009s)
[418.331646] Starting dynamic subtest: pipe-B
[418.341155] Dynamic subtest pipe-B: SKIP (0.017s)
[418.348517] Starting dynamic subtest: pipe-C
[418.357839] Dynamic subtest pipe-C: SKIP (0.017s)
[418.365224] Starting dynamic subtest: pipe-D
[418.377385] Dynamic subtest pipe-D: SKIP (0.017s)
[418.381130] Subtest planes-unity-scaling-downscale-factor-0-25: SKIP (0.062s)
[418.435746] [101/130] (626s left) kms_chamelium_audio (hdmi-audio)
[418.802696] Subtest hdmi-audio: SKIP (0.000s)
[419.652064] [102/130] (625s left) kms_psr (psr-dpms)
[419.746106] Starting subtest: psr-dpms
[419.748406] Starting dynamic subtest: eDP-1
[422.852366] Dynamic subtest eDP-1: SUCCESS (3.104s)
[422.854760] Subtest psr-dpms: SUCCESS (3.104s)
[423.767876] [103/130] (621s left) gem_softpin (noreloc-interruptible)
[423.821450] Starting subtest: noreloc-interruptible
[429.228262] Subtest noreloc-interruptible: SUCCESS (5.406s)
[429.330878] [104/130] (615s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary)
[429.416735] Starting subtest: fbcpsr-shrfb-scaledprimary
[435.218227] Subtest fbcpsr-shrfb-scaledprimary: SUCCESS (5.804s)
[435.331995] [105/130] (609s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling)
[435.422292] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling
[435.424572] Starting dynamic subtest: pipe-A-default-mode
[435.731560] Dynamic subtest pipe-A-default-mode: SKIP (0.304s)
[435.735524] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.305s)
[436.567471] [106/130] (608s left) kms_flip (plain-flip-ts-check-interruptible)
[436.660408] Starting subtest: plain-flip-ts-check-interruptible
[436.662715] Starting dynamic subtest: A-eDP1
[445.596443] Dynamic subtest A-eDP1: SUCCESS (8.935s)
[445.605458] Starting dynamic subtest: B-eDP1
[454.493356] Dynamic subtest B-eDP1: SUCCESS (8.895s)
[454.503153] Starting dynamic subtest: C-eDP1
[463.378651] Dynamic subtest C-eDP1: SUCCESS (8.884s)
[463.387069] Starting dynamic subtest: D-eDP1
[472.274917] Dynamic subtest D-eDP1: SUCCESS (8.895s)
[472.280492] Subtest plain-flip-ts-check-interruptible: SUCCESS (35.619s)
[473.496970] [107/130] (571s left) kms_flip (2x-dpms-vs-vblank-race)
[473.586238] Starting subtest: 2x-dpms-vs-vblank-race
[473.602356] Subtest 2x-dpms-vs-vblank-race: SKIP (0.015s)
[473.656137] [108/130] (571s left) gem_ctx_param (invalid-param-get)
[473.701780] Starting subtest: invalid-param-get
[473.704075] Subtest invalid-param-get: SUCCESS (0.001s)
[473.776206] [109/130] (571s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-indfb-draw-pwrite)
[473.876722] Starting subtest: psr-2p-scndscrn-pri-indfb-draw-pwrite
[473.880817] Subtest psr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[473.962045] [110/130] (571s left) gem_mmap_gtt (cpuset-big-copy)
[474.016386] Subtest cpuset-big-copy: SKIP (0.000s)
[474.057514] [111/130] (571s left) kms_big_fb (linear-max-hw-stride-32bpp-rotate-180)
[474.161089] Starting subtest: linear-max-hw-stride-32bpp-rotate-180
[475.158461] Subtest linear-max-hw-stride-32bpp-rotate-180: SUCCESS (1.000s)
[475.258095] [112/130] (570s left) kms_fbcon_fbt (psr-suspend)
[475.310160] Starting subtest: psr-suspend
[522.432970] Subtest psr-suspend: SUCCESS (17.622s)
[522.472470] [113/130] (552s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-sf)
[527.579773] Starting subtest: psr2-overlay-plane-move-continuous-sf
[527.582358] Starting dynamic subtest: pipe-A-eDP-1
[534.328382] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.741s)
[534.338979] Starting dynamic subtest: pipe-B-eDP-1
[541.110504] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.776s)
[541.112903] Subtest psr2-overlay-plane-move-continuous-sf: SUCCESS (13.521s)
[542.012208] [114/130] (532s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-pwrite)
[542.102647] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-pwrite
[542.106867] Subtest fbc-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[542.172055] [115/130] (532s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite)
[542.266762] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite
[542.270970] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s)
[542.332593] [116/130] (532s left) perf_pmu (most-busy-check-all)
[542.364409] Starting subtest: most-busy-check-all
[542.366539] Starting dynamic subtest: rcs0
[544.090327] Dynamic subtest rcs0: SUCCESS (1.723s)
[544.099971] Starting dynamic subtest: bcs0
[546.138714] Dynamic subtest bcs0: SUCCESS (2.046s)
[546.147480] Starting dynamic subtest: vcs0
[548.122699] Dynamic subtest vcs0: SUCCESS (1.982s)
[548.132210] Starting dynamic subtest: vcs1
[550.105624] Dynamic subtest vcs1: SUCCESS (1.983s)
[550.115439] Starting dynamic subtest: vecs0
[552.090696] Dynamic subtest vecs0: SUCCESS (1.984s)
[552.101853] Starting dynamic subtest: ccs0
[554.137669] Dynamic subtest ccs0: SUCCESS (2.047s)
[554.140479] Subtest most-busy-check-all: SUCCESS (11.772s)
[554.205545] [117/130] (520s left) syncobj_wait (invalid-multi-wait-unsubmitted-signaled)
[554.254541] Starting subtest: invalid-multi-wait-unsubmitted-signaled
[554.256882] Subtest invalid-multi-wait-unsubmitted-signaled: SUCCESS (0.000s)
[554.327261] [118/130] (520s left) gem_linear_blits (interruptible)
[554.748702] Starting subtest: interruptible
[558.063477] Subtest interruptible: SUCCESS (3.315s)
[558.174557] [119/130] (516s left) vgem_basic (bad-flag)
[558.251784] Starting subtest: bad-flag
[558.253949] Subtest bad-flag: SUCCESS (0.000s)
[558.285262] [120/130] (516s left) kms_plane_lowres (tiling-none)
[558.377880] Starting subtest: tiling-none
[558.380188] Starting dynamic subtest: pipe-A-eDP-1
[558.384630] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[558.667519] Starting dynamic subtest: pipe-B-eDP-1
[558.672680] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[558.680330] Starting dynamic subtest: pipe-C-eDP-1
[558.686228] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[558.693831] Starting dynamic subtest: pipe-D-eDP-1
[558.698628] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[558.703301] Subtest tiling-none: SKIP (0.290s)
[559.548803] [121/130] (515s left) gem_exec_fence (syncobj-unused-fence)
[559.948966] Starting subtest: syncobj-unused-fence
[559.954082] Subtest syncobj-unused-fence: SUCCESS (0.004s)
[561.156167] [122/130] (513s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-cpu)
[561.254571] Starting subtest: fbcpsr-rgb101010-draw-mmap-cpu
[565.964539] Subtest fbcpsr-rgb101010-draw-mmap-cpu: SUCCESS (4.712s)
[566.040777] [123/130] (508s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[566.134518] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[566.138518] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[566.220233] [124/130] (508s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-pwrite)
[566.312304] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-pwrite
[566.317350] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-pwrite: SKIP (0.000s)
[566.382094] [125/130] (508s left) gem_exec_async (forked-writes)
[566.436752] Starting subtest: forked-writes
[566.439071] Starting dynamic subtest: rcs0
[566.459061] Dynamic subtest rcs0: SUCCESS (0.022s)
[566.470216] Starting dynamic subtest: bcs0
[566.479176] Dynamic subtest bcs0: SUCCESS (0.018s)
[566.489847] Starting dynamic subtest: vcs0
[566.494583] Dynamic subtest vcs0: SUCCESS (0.013s)
[566.502568] Starting dynamic subtest: vcs1
[566.506924] Dynamic subtest vcs1: SUCCESS (0.013s)
[566.514558] Starting dynamic subtest: vecs0
[566.519336] Dynamic subtest vecs0: SUCCESS (0.013s)
[566.527167] Starting dynamic subtest: ccs0
[566.535118] Dynamic subtest ccs0: SUCCESS (0.015s)
[566.537466] Subtest forked-writes: SUCCESS (0.098s)
[566.746668] [126/130] (508s left) kms_content_protection (mei-interface)
[566.888449] Starting subtest: mei-interface
[566.900060] Subtest mei-interface: SKIP (0.010s)
[567.012399] [127/130] (508s left) syncobj_wait (reset-during-wait-for-submit)
[567.060288] Starting subtest: reset-during-wait-for-submit
[567.160642] Subtest reset-during-wait-for-submit: SUCCESS (0.102s)
[567.216594] [128/130] (507s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-render)
[567.304456] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-render
[567.308539] Subtest fbc-2p-scndscrn-cur-indfb-draw-render: SKIP (0.000s)
[567.372057] [129/130] (507s left) gem_ctx_persistence (engines-hostile-preempt)
[567.427248] Starting subtest: engines-hostile-preempt
[567.429573] Starting dynamic subtest: rcs0
[567.510653] Dynamic subtest rcs0: SUCCESS (0.085s)
[567.523111] Starting dynamic subtest: bcs0
[567.570658] Dynamic subtest bcs0: SUCCESS (0.059s)
[567.580216] Starting dynamic subtest: vcs0
[569.100570] Dynamic subtest vcs0: SUCCESS (1.527s)
[569.111412] Starting dynamic subtest: vcs1
[571.104689] Dynamic subtest vcs1: SUCCESS (2.003s)
[571.114862] Starting dynamic subtest: vecs0
[573.064733] Dynamic subtest vecs0: SUCCESS (1.958s)
[573.075560] Starting dynamic subtest: ccs0
[573.128150] Dynamic subtest ccs0: SUCCESS (0.064s)
[573.131270] Subtest engines-hostile-preempt: SUCCESS (5.701s)
[573.220320] [130/130] (501s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[573.270960] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[573.306948] Closing watchdogs
Done.