[62.436443] Initializing watchdogs
[62.436487]   /dev/watchdog0
[62.450389] [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]
[62.454701] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[62.456898] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[62.460217] [001/134] (960s left) kms_frontbuffer_tracking (fbc-1p-primscrn-shrfb-msflip-blt)
[62.588952] Starting subtest: fbc-1p-primscrn-shrfb-msflip-blt
[67.225024] Subtest fbc-1p-primscrn-shrfb-msflip-blt: SUCCESS (4.630s)
[68.363729] [002/134] (954s left) gem_ccs (block-multicopy-inplace)
[68.434724] Starting subtest: block-multicopy-inplace
[68.438932] Subtest block-multicopy-inplace: SKIP (0.000s)
[68.562664] [003/134] (954s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip)
[68.663089] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip
[68.667290] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s)
[68.771148] [004/134] (954s left) gem_exec_schedule (preempt-other-chain)
[68.845537] Starting subtest: preempt-other-chain
[68.847551] Starting dynamic subtest: rcs0
[68.921730] Dynamic subtest rcs0: SUCCESS (0.082s)
[68.929514] Starting dynamic subtest: bcs0
[69.008271] Dynamic subtest bcs0: SUCCESS (0.086s)
[69.020117] Starting dynamic subtest: vcs0
[69.088039] Dynamic subtest vcs0: SUCCESS (0.079s)
[69.099548] Starting dynamic subtest: vcs1
[69.174796] Dynamic subtest vcs1: SUCCESS (0.087s)
[69.183212] Starting dynamic subtest: vecs0
[69.249628] Dynamic subtest vecs0: SUCCESS (0.074s)
[69.261154] Starting dynamic subtest: ccs0
[69.323853] Dynamic subtest ccs0: SUCCESS (0.074s)
[69.326489] Subtest preempt-other-chain: SUCCESS (0.485s)
[71.030010] [005/134] (952s left) kms_cursor_crc (cursor-random-128x128)
[71.224997] Starting subtest: cursor-random-128x128
[71.227394] Starting dynamic subtest: pipe-A-eDP-1
[74.573410] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.345s)
[74.606156] Starting dynamic subtest: pipe-D-eDP-1
[78.541477] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.939s)
[78.543535] Subtest cursor-random-128x128: SUCCESS (7.313s)
[79.424212] [006/134] (943s left) gem_userptr_blits (userfault)
[79.494577] Starting subtest: userfault
[79.496864] Subtest userfault: SUCCESS (0.005s)
[79.645990] [007/134] (943s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted)
[79.702687] Starting subtest: etime-single-wait-all-for-submit-unsubmitted
[79.810057] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.106s)
[79.891795] [008/134] (943s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-msflip-blt)
[80.013691] Starting subtest: psr-2p-scndscrn-shrfb-msflip-blt
[80.017961] Subtest psr-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[80.123224] [009/134] (943s left) gem_exec_capture (userptr)
[80.183823] Starting subtest: userptr
[81.022086] Subtest userptr: SUCCESS (0.836s)
[81.163200] [010/134] (942s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc)
[81.259350] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc
[81.263683] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s)
[81.365385] [011/134] (941s left) sysfs_timeslice_duration (timeout)
[81.426909] Starting subtest: timeout
[81.429278] Starting dynamic subtest: vecs0
[90.917452] Dynamic subtest vecs0: SUCCESS (9.486s)
[90.928527] Starting dynamic subtest: ccs0
[92.328376] Dynamic subtest ccs0: SUCCESS (1.412s)
[92.338796] Starting dynamic subtest: vcs0
[101.926965] Dynamic subtest vcs0: SUCCESS (9.594s)
[101.936962] Starting dynamic subtest: bcs0
[102.548729] Dynamic subtest bcs0: SUCCESS (0.621s)
[102.558540] Starting dynamic subtest: rcs0
[103.822949] Dynamic subtest rcs0: SUCCESS (1.274s)
[103.832791] Starting dynamic subtest: vcs1
[112.939256] Dynamic subtest vcs1: SUCCESS (9.113s)
[112.942071] Subtest timeout: SUCCESS (31.506s)
[113.025518] [012/134] (910s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs-cc)
[113.117063] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs-cc
[113.119473] Starting dynamic subtest: pipe-A-eDP-1
[113.143592] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[113.153301] Starting dynamic subtest: pipe-B-eDP-1
[113.174621] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[113.181781] Starting dynamic subtest: pipe-C-eDP-1
[113.203740] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[113.210719] Starting dynamic subtest: pipe-D-eDP-1
[113.233425] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[113.237530] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s)
[115.379237] [013/134] (908s left) kms_chamelium_frames (hdmi-crc-multiple)
[115.761257] Subtest hdmi-crc-multiple: SKIP (0.000s)
[116.582903] [014/134] (906s left) gem_exec_fence (syncobj-timeline-wait)
[117.148821] Starting subtest: syncobj-timeline-wait
[118.975671] Subtest syncobj-timeline-wait: SUCCESS (1.827s)
[121.039066] [FACT gem_exec_fence (syncobj-timeline-wait)] new: kernel.kmod_is_loaded.vgem: true
[121.041978] [015/134] (902s left) kms_psr (pr-primary-render)
[121.146207] Starting subtest: pr-primary-render
[121.150716] Subtest pr-primary-render: SKIP (0.000s)
[121.229808] [016/134] (902s left) kms_big_fb (y-tiled-64bpp-rotate-180)
[121.353771] Starting subtest: y-tiled-64bpp-rotate-180
[121.358309] Subtest y-tiled-64bpp-rotate-180: SKIP (0.000s)
[121.483057] [017/134] (902s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-shrfb-draw-mmap-wc)
[121.577891] Starting subtest: psr-1p-offscreen-pri-shrfb-draw-mmap-wc
[128.205764] Subtest psr-1p-offscreen-pri-shrfb-draw-mmap-wc: SUCCESS (6.630s)
[128.344395] [018/134] (895s left) kms_big_fb (4-tiled-addfb)
[128.449840] Starting subtest: 4-tiled-addfb
[128.452236] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[128.571207] [019/134] (895s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[128.666446] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[128.670533] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[128.790412] [020/134] (894s left) kms_plane_cursor (primary)
[128.882315] Starting subtest: primary
[128.886812] Starting dynamic subtest: pipe-A-eDP-1-size-64
[131.430882] Dynamic subtest pipe-A-eDP-1-size-64: SUCCESS (2.551s)
[131.454208] Starting dynamic subtest: pipe-A-eDP-1-size-128
[134.065292] Dynamic subtest pipe-A-eDP-1-size-128: SUCCESS (2.616s)
[134.089445] Starting dynamic subtest: pipe-A-eDP-1-size-256
[136.632802] Dynamic subtest pipe-A-eDP-1-size-256: SUCCESS (2.550s)
[136.960153] Starting dynamic subtest: pipe-B-eDP-1-size-64
[140.228943] Dynamic subtest pipe-B-eDP-1-size-64: SUCCESS (3.281s)
[140.251836] Starting dynamic subtest: pipe-B-eDP-1-size-128
[142.787938] Dynamic subtest pipe-B-eDP-1-size-128: SUCCESS (2.538s)
[142.808962] Starting dynamic subtest: pipe-B-eDP-1-size-256
[145.343992] Dynamic subtest pipe-B-eDP-1-size-256: SUCCESS (2.538s)
[145.662277] Starting dynamic subtest: pipe-C-eDP-1-size-64
[148.586008] Dynamic subtest pipe-C-eDP-1-size-64: SUCCESS (2.934s)
[148.612758] Starting dynamic subtest: pipe-C-eDP-1-size-128
[150.785228] Dynamic subtest pipe-C-eDP-1-size-128: SUCCESS (2.180s)
[150.810714] Starting dynamic subtest: pipe-C-eDP-1-size-256
[152.985162] Dynamic subtest pipe-C-eDP-1-size-256: SUCCESS (2.181s)
[153.299024] Starting dynamic subtest: pipe-D-eDP-1-size-64
[156.192114] Dynamic subtest pipe-D-eDP-1-size-64: SUCCESS (2.902s)
[156.216859] Starting dynamic subtest: pipe-D-eDP-1-size-128
[158.403516] Dynamic subtest pipe-D-eDP-1-size-128: SUCCESS (2.192s)
[158.428892] Starting dynamic subtest: pipe-D-eDP-1-size-256
[160.603457] Dynamic subtest pipe-D-eDP-1-size-256: SUCCESS (2.180s)
[160.913238] Subtest primary: SUCCESS (32.030s)
[161.770951] [021/134] (861s left) sw_sync (sync_merge)
[161.804447] Starting subtest: sync_merge
[161.806753] Subtest sync_merge: SUCCESS (0.000s)
[161.870453] [022/134] (861s left) drm_read (invalid-buffer)
[162.015164] Starting subtest: invalid-buffer
[162.017567] Subtest invalid-buffer: SUCCESS (0.000s)
[162.173226] [023/134] (861s left) gem_exec_flush (basic-uc-prw-default)
[162.237957] Starting subtest: basic-uc-prw-default
[167.699756] Subtest basic-uc-prw-default: SUCCESS (5.463s)
[167.895383] [024/134] (855s left) gem_cs_tlb (engines)
[167.985405] Starting subtest: engines
[167.987359] Starting dynamic subtest: rcs0
[170.141723] Dynamic subtest rcs0: SUCCESS (2.155s)
[170.151893] Starting dynamic subtest: bcs0
[172.296235] Dynamic subtest bcs0: SUCCESS (2.152s)
[172.306685] Starting dynamic subtest: vcs0
[174.449954] Dynamic subtest vcs0: SUCCESS (2.151s)
[174.459868] Starting dynamic subtest: vcs1
[176.603900] Dynamic subtest vcs1: SUCCESS (2.153s)
[176.614147] Starting dynamic subtest: vecs0
[178.757819] Dynamic subtest vecs0: SUCCESS (2.152s)
[178.768024] Starting dynamic subtest: ccs0
[180.912755] Dynamic subtest ccs0: SUCCESS (2.154s)
[180.915047] Subtest engines: SUCCESS (12.925s)
[181.100901] [025/134] (842s left) kms_big_fb (linear-16bpp-rotate-270)
[181.210918] Starting subtest: linear-16bpp-rotate-270
[181.230681] Subtest linear-16bpp-rotate-270: SKIP (0.019s)
[181.387282] [026/134] (842s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-onoff)
[181.500631] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-onoff
[181.505069] Subtest fbcpsr-2p-primscrn-spr-indfb-onoff: SKIP (0.000s)
[181.635904] [027/134] (842s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu)
[181.748282] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu
[181.752636] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s)
[181.876502] [028/134] (842s left) kms_psr2_sf (psr2-overlay-plane-update-sf-dmg-area)
[186.829161] Starting subtest: psr2-overlay-plane-update-sf-dmg-area
[186.831608] Starting dynamic subtest: pipe-A-eDP-1
[193.472563] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.642s)
[193.481058] Starting dynamic subtest: pipe-B-eDP-1
[200.108298] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.632s)
[200.110881] Subtest psr2-overlay-plane-update-sf-dmg-area: SUCCESS (13.278s)
[200.993881] [029/134] (823s left) kms_chamelium_audio (hdmi-audio-edid)
[201.392700] Subtest hdmi-audio-edid: SKIP (0.000s)
[202.196230] [030/134] (821s left) gem_tiled_wb
[202.333908] [031/134] (821s left) kms_plane_scaling (plane-upscale-20x20-with-rotation)
[202.426263] Starting subtest: plane-upscale-20x20-with-rotation
[202.428600] Starting dynamic subtest: pipe-A
[202.650862] Dynamic subtest pipe-A: SUCCESS (0.227s)
[202.659195] Starting dynamic subtest: pipe-B
[203.900170] Dynamic subtest pipe-B: SUCCESS (1.249s)
[203.907170] Starting dynamic subtest: pipe-C
[205.237741] Dynamic subtest pipe-C: SUCCESS (1.337s)
[205.245822] Starting dynamic subtest: pipe-D
[206.573310] Dynamic subtest pipe-D: SUCCESS (1.334s)
[206.575714] Subtest plane-upscale-20x20-with-rotation: SUCCESS (4.150s)
[207.764769] [032/134] (816s left) gem_ctx_persistence (engines-hostile-preempt)
[207.830631] Starting subtest: engines-hostile-preempt
[207.833064] Starting dynamic subtest: rcs0
[207.922646] Dynamic subtest rcs0: SUCCESS (0.092s)
[207.933406] Starting dynamic subtest: bcs0
[207.979047] Dynamic subtest bcs0: SUCCESS (0.054s)
[207.988607] Starting dynamic subtest: vcs0
[209.892164] Dynamic subtest vcs0: SUCCESS (1.910s)
[209.903491] Starting dynamic subtest: vcs1
[211.908638] Dynamic subtest vcs1: SUCCESS (2.013s)
[211.919162] Starting dynamic subtest: vecs0
[213.924721] Dynamic subtest vecs0: SUCCESS (2.014s)
[213.934247] Starting dynamic subtest: ccs0
[213.999323] Dynamic subtest ccs0: SUCCESS (0.073s)
[214.002026] Subtest engines-hostile-preempt: SUCCESS (6.169s)
[214.093123] [033/134] (810s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-pwrite)
[214.196146] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-pwrite
[214.200059] Subtest fbc-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[214.324677] [034/134] (809s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-sf)
[219.340698] Starting subtest: fbc-pr-cursor-plane-move-continuous-exceed-sf
[219.345288] Subtest fbc-pr-cursor-plane-move-continuous-exceed-sf: SKIP (0.003s)
[220.203553] [035/134] (804s left) vgem_basic (debugfs)
[220.248289] Starting subtest: debugfs
[220.250499] Subtest debugfs: SUCCESS (0.000s)
[220.317295] [036/134] (804s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-blt)
[220.420797] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-blt
[224.520696] Subtest fbc-1p-offscreen-pri-indfb-draw-blt: SUCCESS (4.103s)
[225.703591] [037/134] (798s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[225.830173] Starting subtest: flip-vs-cursor-legacy
[232.864668] Subtest flip-vs-cursor-legacy: SUCCESS (7.035s)
[233.724895] [038/134] (790s left) kms_cursor_legacy (cursora-vs-flipb-toggle)
[233.856939] Starting subtest: cursorA-vs-flipB-toggle
[233.861611] Subtest cursorA-vs-flipB-toggle: SKIP (0.001s)
[234.021657] [039/134] (790s left) kms_ccs (crc-primary-basic-yf-tiled-ccs)
[234.126249] Starting subtest: crc-primary-basic-yf-tiled-ccs
[234.128538] Starting dynamic subtest: pipe-A-eDP-1
[234.151287] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[234.161577] Starting dynamic subtest: pipe-B-eDP-1
[234.182508] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[234.189773] Starting dynamic subtest: pipe-C-eDP-1
[234.211209] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[234.218517] Starting dynamic subtest: pipe-D-eDP-1
[234.240294] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[234.244350] Subtest crc-primary-basic-yf-tiled-ccs: SKIP (0.006s)
[236.377730] [040/134] (788s left) gem_lmem_swapping (parallel-random-engines)
[236.428604] Subtest parallel-random-engines: SKIP (0.000s)
[236.476320] [041/134] (788s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted-signaled)
[236.542516] Starting subtest: multi-wait-for-submit-unsubmitted-submitted-signaled
[236.544860] Subtest multi-wait-for-submit-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[236.670373] [042/134] (787s left) kms_flip (2x-flip-vs-fences-interruptible)
[236.767533] Starting subtest: 2x-flip-vs-fences-interruptible
[236.772051] Subtest 2x-flip-vs-fences-interruptible: SKIP (0.000s)
[236.890307] [043/134] (787s left) syncobj_basic (illegal-fd-to-handle)
[236.943930] Starting subtest: illegal-fd-to-handle
[236.946174] Subtest illegal-fd-to-handle: SUCCESS (0.000s)
[237.071181] [044/134] (787s left) kms_pm_rpm (dpms-lpsp)
[239.094023] Starting subtest: dpms-lpsp
[239.398618] Subtest dpms-lpsp: SUCCESS (0.304s)
[239.506667] [045/134] (785s left) perf_pmu (interrupts-sync)
[239.552882] Starting subtest: interrupts-sync
[240.666292] Subtest interrupts-sync: SUCCESS (1.112s)
[240.801911] [046/134] (783s left) kms_plane (pixel-format-yf-tiled-ccs-modifier)
[240.909245] Starting subtest: pixel-format-yf-tiled-ccs-modifier
[240.936055] Starting dynamic subtest: pipe-A-plane-0
[240.940432] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[240.948892] Starting dynamic subtest: pipe-A-plane-3
[240.953191] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[240.960567] Starting dynamic subtest: pipe-A-plane-5
[240.965038] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[242.038583] Starting dynamic subtest: pipe-B-plane-0
[242.042653] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[242.050542] Starting dynamic subtest: pipe-B-plane-3
[242.055177] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[242.062997] Starting dynamic subtest: pipe-B-plane-5
[242.067031] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[242.343349] Subtest pixel-format-yf-tiled-ccs-modifier: SKIP (1.433s)
[243.220871] [047/134] (781s left) kms_psr (fbc-psr2-primary-render)
[243.322302] Starting subtest: fbc-psr2-primary-render
[243.324559] Starting dynamic subtest: eDP-1
[243.329025] Dynamic subtest eDP-1: SKIP (0.000s)
[243.333452] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[243.406224] [048/134] (781s left) kms_cursor_legacy (cursor-vs-flip-atomic-transitions-varying-size)
[243.534187] Starting subtest: cursor-vs-flip-atomic-transitions-varying-size
[274.705490] Subtest cursor-vs-flip-atomic-transitions-varying-size: SUCCESS (31.168s)
[275.548332] [049/134] (749s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen)
[275.637616] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen
[275.641993] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s)
[275.754082] [050/134] (749s left) kms_pm_rpm (modeset-non-lpsp)
[278.086413] Starting subtest: modeset-non-lpsp
[279.242861] Subtest modeset-non-lpsp: SKIP (1.157s)
[279.631280] [051/134] (745s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc)
[279.719958] Starting subtest: missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc
[279.722240] Starting dynamic subtest: pipe-A-eDP-1
[279.743384] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[279.752858] Starting dynamic subtest: pipe-B-eDP-1
[279.772464] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[279.780321] Starting dynamic subtest: pipe-C-eDP-1
[279.799695] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[279.807217] Starting dynamic subtest: pipe-D-eDP-1
[279.826602] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[279.828783] Subtest missing-ccs-buffer-4-tiled-mtl-rc-ccs-cc: SUCCESS (0.006s)
[281.973713] [052/134] (742s left) syncobj_wait (wait-all-delayed-signal)
[282.036499] Starting subtest: wait-all-delayed-signal
[282.140382] Subtest wait-all-delayed-signal: SUCCESS (0.102s)
[282.230451] [053/134] (742s left) perf_pmu (idle-no-semaphores)
[282.272541] Starting subtest: idle-no-semaphores
[282.274854] Starting dynamic subtest: rcs0
[282.822616] Dynamic subtest rcs0: SUCCESS (0.553s)
[282.834258] Starting dynamic subtest: bcs0
[283.350769] Dynamic subtest bcs0: SUCCESS (0.525s)
[283.361723] Starting dynamic subtest: vcs0
[283.881617] Dynamic subtest vcs0: SUCCESS (0.528s)
[283.891605] Starting dynamic subtest: vcs1
[284.411399] Dynamic subtest vcs1: SUCCESS (0.527s)
[284.421833] Starting dynamic subtest: vecs0
[284.941863] Dynamic subtest vecs0: SUCCESS (0.527s)
[284.952943] Starting dynamic subtest: ccs0
[285.473062] Dynamic subtest ccs0: SUCCESS (0.529s)
[285.476236] Subtest idle-no-semaphores: SUCCESS (3.205s)
[285.600280] [054/134] (739s left) i915_pm_rps (thresholds-idle)
[285.662056] Starting subtest: thresholds-idle
[285.666024] Subtest thresholds-idle: SKIP (0.002s)
[285.797716] [055/134] (739s left) kms_psr (psr-primary-blt)
[285.906683] Starting subtest: psr-primary-blt
[285.908954] Starting dynamic subtest: eDP-1
[287.673992] Dynamic subtest eDP-1: SUCCESS (1.768s)
[287.676174] Subtest psr-primary-blt: SUCCESS (1.769s)
[288.554088] [056/134] (736s left) gem_tiled_blits (interruptible)
[288.609958] Subtest interruptible: SKIP (0.000s)
[288.700034] [057/134] (736s left) i915_drm_fdinfo (busy-hang)
[288.746774] Starting subtest: busy-hang
[288.748942] Starting dynamic subtest: rcs0
[288.787920] Dynamic subtest rcs0: SKIP (0.043s)
[288.798435] Starting dynamic subtest: bcs0
[288.802385] Dynamic subtest bcs0: SKIP (0.001s)
[288.811282] Starting dynamic subtest: vcs0
[288.815581] Dynamic subtest vcs0: SKIP (0.000s)
[288.823290] Starting dynamic subtest: vcs1
[288.827349] Dynamic subtest vcs1: SKIP (0.000s)
[288.834490] Starting dynamic subtest: vecs0
[288.838589] Dynamic subtest vecs0: SKIP (0.000s)
[288.845809] Starting dynamic subtest: ccs0
[288.849922] Dynamic subtest ccs0: SKIP (0.000s)
[288.853971] Subtest busy-hang: SKIP (0.051s)
[288.889199] [058/134] (736s left) kms_color (ctm-0-50)
[288.984125] Starting subtest: ctm-0-50
[288.986671] Starting dynamic subtest: pipe-A-eDP-1
[292.459160] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.475s)
[292.467226] Starting dynamic subtest: pipe-B-eDP-1
[296.588457] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.127s)
[296.597072] Starting dynamic subtest: pipe-C-eDP-1
[300.636861] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.050s)
[300.645314] Starting dynamic subtest: pipe-D-eDP-1
[304.617896] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.977s)
[304.619895] Subtest ctm-0-50: SUCCESS (15.631s)
[305.449379] [059/134] (719s left) gem_render_copy (linear)
[305.523255] Starting subtest: linear
[305.525613] Starting dynamic subtest: smem
[305.565879] Dynamic subtest smem: SUCCESS (0.042s)
[305.568285] Subtest linear: SUCCESS (0.043s)
[305.770585] [060/134] (719s left) api_intel_allocator (two-level-inception-interruptible)
[305.828611] Starting subtest: two-level-inception-interruptible
[311.234148] Subtest two-level-inception-interruptible: SUCCESS (5.406s)
[311.380265] [061/134] (713s left) kms_flip_scaled_crc (flip-64bpp-xtile-to-16bpp-xtile-downscaling)
[311.486805] Starting subtest: flip-64bpp-xtile-to-16bpp-xtile-downscaling
[311.488928] Starting dynamic subtest: pipe-A-default-mode
[312.597803] Dynamic subtest pipe-A-default-mode: SKIP (1.108s)
[312.602251] Subtest flip-64bpp-xtile-to-16bpp-xtile-downscaling: SKIP (1.110s)
[312.786308] [062/134] (712s left) gem_ctx_shared (exec-shared-gtt)
[312.860678] Starting subtest: exec-shared-gtt
[312.863043] Starting dynamic subtest: rcs0
[312.872161] Dynamic subtest rcs0: SUCCESS (0.011s)
[312.882243] Starting dynamic subtest: bcs0
[312.884426] Dynamic subtest bcs0: SUCCESS (0.007s)
[312.892564] Starting dynamic subtest: vcs0
[312.894454] Dynamic subtest vcs0: SUCCESS (0.006s)
[312.903255] Starting dynamic subtest: vcs1
[312.905486] Dynamic subtest vcs1: SUCCESS (0.009s)
[312.914653] Starting dynamic subtest: vecs0
[312.916965] Dynamic subtest vecs0: SUCCESS (0.009s)
[312.924849] Starting dynamic subtest: ccs0
[312.926979] Dynamic subtest ccs0: SUCCESS (0.006s)
[312.929089] Subtest exec-shared-gtt: SUCCESS (0.054s)
[313.121814] [063/134] (712s left) gem_pxp (regular-baseline-src-copy-readible)
[313.246280] Starting subtest: regular-baseline-src-copy-readible
[313.307148] Subtest regular-baseline-src-copy-readible: SUCCESS (0.064s)
[313.467723] [064/134] (711s left) kms_big_fb (x-tiled-32bpp-rotate-270)
[313.581175] Starting subtest: x-tiled-32bpp-rotate-270
[313.601680] Subtest x-tiled-32bpp-rotate-270: SKIP (0.019s)
[313.747426] [065/134] (711s left) core_getstats (basic)
[313.806601] Starting subtest: basic
[313.808642] Subtest basic: SUCCESS (0.000s)
[313.946872] [066/134] (711s left) kms_joiner (invalid-modeset-big-joiner)
[314.056340] Starting subtest: invalid-modeset-big-joiner
[314.059979] Subtest invalid-modeset-big-joiner: SKIP (0.000s)
[314.133838] [067/134] (711s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-render)
[314.232795] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-render
[321.145400] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-render: SUCCESS (6.914s)
[321.332068] [068/134] (704s left) gem_exec_schedule (u-semaphore-noskip)
[321.420199] Starting subtest: u-semaphore-noskip
[321.827312] Subtest u-semaphore-noskip: SUCCESS (0.413s)
[322.196420] [069/134] (703s left) gem_exec_flush (basic-uc-rw-default)
[322.264822] Starting subtest: basic-uc-rw-default
[327.716159] Subtest basic-uc-rw-default: SUCCESS (5.452s)
[327.912174] [070/134] (697s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-blt)
[328.017668] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-blt
[328.021851] Subtest psr-2p-scndscrn-cur-indfb-draw-blt: SKIP (0.000s)
[328.139630] [071/134] (697s left) gem_mmap_gtt (basic-read)
[328.207870] Subtest basic-read: SKIP (0.000s)
[328.278555] [072/134] (697s left) kms_async_flips (invalid-async-flip-atomic)
[328.377113] Starting subtest: invalid-async-flip-atomic
[328.753496] Starting dynamic subtest: pipe-A-eDP-1
[329.584063] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.831s)
[329.955605] Starting dynamic subtest: pipe-B-eDP-1
[330.729486] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.787s)
[331.083879] Starting dynamic subtest: pipe-C-eDP-1
[331.852404] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.775s)
[332.210698] Starting dynamic subtest: pipe-D-eDP-1
[332.984299] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.780s)
[332.986667] Subtest invalid-async-flip-atomic: SUCCESS (4.610s)
[334.142049] [073/134] (691s left) gen7_exec_parse (basic-rejected)
[334.209379] Subtest basic-rejected: SKIP (0.000s)
[334.295452] [074/134] (691s left) syncobj_timeline (multi-wait-all-for-submit-signaled)
[334.359554] Starting subtest: multi-wait-all-for-submit-signaled
[334.361981] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.001s)
[334.486752] [075/134] (691s left) kms_busy (basic-hang)
[334.590193] Starting subtest: basic-hang
[334.592441] Starting dynamic subtest: flip-pipe-A
[335.216451] Dynamic subtest flip-pipe-A: SUCCESS (0.627s)
[335.224666] Starting dynamic subtest: modeset-pipe-A
[338.659950] Dynamic subtest modeset-pipe-A: SUCCESS (3.442s)
[338.672930] Starting dynamic subtest: flip-pipe-D
[339.857768] Dynamic subtest flip-pipe-D: SUCCESS (1.193s)
[339.866901] Starting dynamic subtest: modeset-pipe-D
[343.301873] Dynamic subtest modeset-pipe-D: SUCCESS (3.444s)
[343.304091] Subtest basic-hang: SUCCESS (8.713s)
[344.121357] [076/134] (681s left) kms_cursor_legacy (flip-vs-cursor-toggle)
[344.243318] Starting subtest: flip-vs-cursor-toggle
[352.668926] Subtest flip-vs-cursor-toggle: SUCCESS (8.425s)
[353.536179] [077/134] (672s left) kms_tiled_display (basic-test-pattern)
[353.646151] Subtest basic-test-pattern: SKIP (0.000s)
[353.692897] [078/134] (672s left) kms_plane (planar-pixel-format-settings)
[353.787906] Starting subtest: planar-pixel-format-settings
[353.850432] Subtest planar-pixel-format-settings: SUCCESS (0.065s)
[353.997500] [079/134] (672s left) gem_exec_create (basic)
[354.056341] Starting subtest: basic
[354.058739] Starting dynamic subtest: smem
[356.886369] Dynamic subtest smem: SUCCESS (2.827s)
[356.889054] Subtest basic: SUCCESS (2.829s)
[357.087526] [080/134] (668s left) gem_exec_fence (syncobj-channel)
[357.685415] Starting subtest: syncobj-channel
[358.717962] Subtest syncobj-channel: SUCCESS (1.032s)
[359.100439] [081/134] (667s left) gem_ctx_persistence (legacy-engines-persistence)
[359.170890] Starting subtest: legacy-engines-persistence
[359.173219] Starting dynamic subtest: render
[359.992370] Dynamic subtest render: SUCCESS (0.820s)
[360.004152] Starting dynamic subtest: bsd1
[360.798994] Dynamic subtest bsd1: SUCCESS (0.804s)
[360.815947] Starting dynamic subtest: bsd2
[361.574366] Dynamic subtest bsd2: SUCCESS (0.772s)
[361.586041] Starting dynamic subtest: blt
[362.330033] Dynamic subtest blt: SUCCESS (0.753s)
[362.341535] Starting dynamic subtest: vebox
[363.111060] Dynamic subtest vebox: SUCCESS (0.778s)
[363.113919] Subtest legacy-engines-persistence: SUCCESS (3.941s)
[363.214767] [082/134] (662s left) sw_sync (sync_merge_same)
[363.254140] Starting subtest: sync_merge_same
[363.256479] Subtest sync_merge_same: SUCCESS (0.000s)
[363.317379] [083/134] (662s left) kms_cursor_legacy (cursora-vs-flipa-atomic-transitions)
[363.441679] Starting subtest: cursorA-vs-flipA-atomic-transitions
[374.616638] Subtest cursorA-vs-flipA-atomic-transitions: SUCCESS (11.175s)
[375.465437] [084/134] (650s left) kms_pipe_b_c_ivb (pipe-b-double-modeset-then-modeset-pipe-c)
[375.533873] Subtest pipe-B-double-modeset-then-modeset-pipe-C: SKIP (0.000s)
[375.599940] [085/134] (650s left) kms_frontbuffer_tracking (psr-1p-rte)
[375.690989] Starting subtest: psr-1p-rte
[381.075475] Subtest psr-1p-rte: SUCCESS (5.388s)
[381.236830] [086/134] (645s left) syncobj_timeline (wait-all-complex)
[381.299639] Starting subtest: wait-all-complex
[381.513241] Subtest wait-all-complex: SUCCESS (0.213s)
[381.599505] [087/134] (644s left) gem_render_copy (y-tiled-to-vebox-yf-tiled)
[381.673912] Starting subtest: y-tiled-to-vebox-yf-tiled
[381.678746] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s)
[381.866585] [088/134] (644s left) gem_exec_params (dr1-dirt)
[381.926646] Starting subtest: DR1-dirt
[381.929142] Subtest DR1-dirt: SUCCESS (0.000s)
[382.056630] [089/134] (644s left) kms_addfb_basic (bad-pitch-1024)
[382.111981] Starting subtest: bad-pitch-1024
[382.114402] Subtest bad-pitch-1024: SUCCESS (0.000s)
[382.250147] [090/134] (644s left) prime_vgem (basic-fence-flip)
[382.330384] Starting subtest: basic-fence-flip
[382.616571] Subtest basic-fence-flip: SUCCESS (0.287s)
[382.713540] [091/134] (643s left) gem_ctx_param (non-root-set)
[382.776531] Starting subtest: non-root-set
[382.799824] Subtest non-root-set: SUCCESS (0.026s)
[382.909351] [092/134] (643s left) gem_mmap_gtt (ptrace)
[382.969334] Subtest ptrace: SKIP (0.000s)
[383.048063] [093/134] (643s left) gem_exec_capture (capture-invisible)
[383.105117] Starting subtest: capture-invisible
[383.107409] Starting dynamic subtest: smem0
[383.111723] Dynamic subtest smem0: SKIP (0.000s)
[383.116143] Subtest capture-invisible: SKIP (0.001s)
[383.240379] [094/134] (643s left) syncobj_timeline (etime-multi-wait-all-submitted)
[383.307646] Starting subtest: etime-multi-wait-all-submitted
[383.409431] Subtest etime-multi-wait-all-submitted: SUCCESS (0.102s)
[383.501557] [095/134] (643s left) syncobj_wait (wait-any-snapshot)
[383.560151] Starting subtest: wait-any-snapshot
[383.638678] Subtest wait-any-snapshot: SUCCESS (0.081s)
[383.727654] [096/134] (643s left) syncobj_timeline (multi-wait-for-submit-available-signaled)
[383.790717] Starting subtest: multi-wait-for-submit-available-signaled
[383.792653] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s)
[383.909854] [097/134] (642s left) kms_draw_crc (draw-method-mmap-wc)
[384.011613] Starting subtest: draw-method-mmap-wc
[384.013528] Starting dynamic subtest: xrgb8888-untiled
[384.275536] Dynamic subtest xrgb8888-untiled: SUCCESS (0.266s)
[384.283721] Starting dynamic subtest: rgb565-untiled
[384.475581] Dynamic subtest rgb565-untiled: SUCCESS (0.199s)
[384.483732] Starting dynamic subtest: xrgb2101010-untiled
[384.687032] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.210s)
[384.695175] Starting dynamic subtest: xbgr16161616f-untiled
[384.898424] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[384.912145] Starting dynamic subtest: xrgb8888-xtiled
[385.042075] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.143s)
[385.049747] Starting dynamic subtest: rgb565-xtiled
[385.164894] Dynamic subtest rgb565-xtiled: SUCCESS (0.122s)
[385.173161] Starting dynamic subtest: xrgb2101010-xtiled
[385.286737] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.121s)
[385.294779] Starting dynamic subtest: xbgr16161616f-xtiled
[385.420038] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[385.428188] Starting dynamic subtest: xrgb8888-4tiled
[385.575788] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.155s)
[385.583398] Starting dynamic subtest: rgb565-4tiled
[385.719952] Dynamic subtest rgb565-4tiled: SUCCESS (0.143s)
[385.727592] Starting dynamic subtest: xrgb2101010-4tiled
[385.864357] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.144s)
[385.872437] Starting dynamic subtest: xbgr16161616f-4tiled
[386.032171] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.166s)
[386.033967] Subtest draw-method-mmap-wc: SUCCESS (2.023s)
[386.147419] [098/134] (640s left) kms_vrr (negative-basic)
[386.256802] Starting subtest: negative-basic
[386.262532] Starting dynamic subtest: pipe-A-eDP-1
[391.677160] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.413s)
[391.959630] Subtest negative-basic: SUCCESS (5.702s)
[392.801432] [099/134] (634s left) kms_setmode (basic)
[392.869731] Starting subtest: basic
[393.205075] Starting dynamic subtest: pipe-A-eDP-1
[395.309060] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.099s)
[395.647991] Starting dynamic subtest: pipe-B-eDP-1
[397.734833] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.089s)
[398.048743] Subtest basic: SUCCESS (5.178s)
[398.887229] [100/134] (627s left) gem_madvise (dontneed-before-mmap)
[398.921179] Starting subtest: dontneed-before-mmap
[398.945565] Subtest dontneed-before-mmap: SUCCESS (0.024s)
[399.057151] [101/134] (627s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-fully-sf)
[404.154516] Starting subtest: psr2-overlay-plane-move-continuous-exceed-fully-sf
[404.156523] Starting dynamic subtest: pipe-A-eDP-1
[421.353667] Dynamic subtest pipe-A-eDP-1: SUCCESS (17.199s)
[421.362861] Starting dynamic subtest: pipe-B-eDP-1
[438.493797] Dynamic subtest pipe-B-eDP-1: SUCCESS (17.135s)
[438.496110] Subtest psr2-overlay-plane-move-continuous-exceed-fully-sf: SUCCESS (34.338s)
[439.304466] [102/134] (587s left) syncobj_wait (single-wait-for-submit-submitted)
[439.371759] Starting subtest: single-wait-for-submit-submitted
[439.473331] Subtest single-wait-for-submit-submitted: SUCCESS (0.102s)
[439.568194] [103/134] (587s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-pwrite)
[439.677675] Starting subtest: psr-1p-primscrn-cur-indfb-draw-pwrite
[446.465282] Subtest psr-1p-primscrn-cur-indfb-draw-pwrite: SUCCESS (6.789s)
[446.613383] [104/134] (580s left) gem_mmap_offset (perf)
[446.669502] Starting subtest: perf
[446.671776] Starting dynamic subtest: smem0
[447.030841] Dynamic subtest smem0: SUCCESS (0.360s)
[447.033736] Subtest perf: SUCCESS (0.361s)
[447.170447] [105/134] (579s left) gem_lmem_swapping (massive-random)
[447.216139] Subtest massive-random: SKIP (0.000s)
[447.251203] [106/134] (579s left) kms_cursor_legacy (torture-move)
[447.364769] Starting subtest: torture-move
[447.366594] Starting dynamic subtest: pipe-A
[453.313139] Dynamic subtest pipe-A: SUCCESS (5.946s)
[453.323551] Starting dynamic subtest: pipe-B
[459.269474] Dynamic subtest pipe-B: SUCCESS (5.953s)
[459.279734] Starting dynamic subtest: pipe-C
[465.562076] Dynamic subtest pipe-C: SUCCESS (6.291s)
[465.573884] Starting dynamic subtest: pipe-D
[471.769259] Dynamic subtest pipe-D: SUCCESS (6.203s)
[471.779706] Starting dynamic subtest: all-pipes
[478.588501] Dynamic subtest all-pipes: SUCCESS (6.817s)
[478.591138] Subtest torture-move: SUCCESS (31.222s)
[478.767595] [107/134] (548s left) prime_busy (after)
[478.836138] Starting subtest: after
[478.841248] Starting dynamic subtest: rcs0
[478.879339] Dynamic subtest rcs0: SUCCESS (0.041s)
[478.889768] Starting dynamic subtest: bcs0
[478.933310] Dynamic subtest bcs0: SUCCESS (0.053s)
[478.942119] Starting dynamic subtest: vcs0
[478.973045] Dynamic subtest vcs0: SUCCESS (0.039s)
[478.981395] Starting dynamic subtest: vcs1
[479.013078] Dynamic subtest vcs1: SUCCESS (0.040s)
[479.021270] Starting dynamic subtest: vecs0
[479.054162] Dynamic subtest vecs0: SUCCESS (0.040s)
[479.062310] Starting dynamic subtest: ccs0
[479.094301] Dynamic subtest ccs0: SUCCESS (0.040s)
[479.203197] Subtest after: SUCCESS (0.366s)
[479.291478] [108/134] (547s left) kms_vrr (flip-basic)
[479.371428] Starting subtest: flip-basic
[479.378061] Subtest flip-basic: SKIP (0.000s)
[479.466017] [109/134] (547s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180)
[479.568806] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180
[480.581937] Subtest x-tiled-max-hw-stride-32bpp-rotate-180: SUCCESS (1.013s)
[480.730874] [110/134] (546s left) prime_vgem (basic-write)
[480.821200] Starting subtest: basic-write
[480.829349] Subtest basic-write: SKIP (0.007s)
[480.956426] [111/134] (546s left) kms_cursor_crc (cursor-onscreen-512x512)
[481.215045] Starting subtest: cursor-onscreen-512x512
[481.218837] Subtest cursor-onscreen-512x512: SKIP (0.001s)
[481.390983] [112/134] (545s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt)
[481.491234] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt
[481.494657] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s)
[481.628974] [113/134] (545s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-upscaling)
[481.726657] Starting subtest: flip-32bpp-linear-to-64bpp-linear-upscaling
[481.728561] Starting dynamic subtest: pipe-A-default-mode
[484.507825] Dynamic subtest pipe-A-default-mode: SUCCESS (2.778s)
[484.510282] Subtest flip-32bpp-linear-to-64bpp-linear-upscaling: SUCCESS (2.782s)
[485.366153] [114/134] (542s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[485.758322] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[486.556492] [115/134] (540s left) kms_hdr (static-swap)
[486.645458] Starting subtest: static-swap
[486.651796] Subtest static-swap: SKIP (0.000s)
[486.732472] [116/134] (540s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu)
[486.840992] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu
[491.158865] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (4.320s)
[492.302688] [117/134] (535s left) api_intel_bb (render)
[492.365893] Starting subtest: render
[492.368256] Starting dynamic subtest: render-none-512
[492.464507] Dynamic subtest render-none-512: SUCCESS (0.100s)
[492.474525] Starting dynamic subtest: render-none-1024
[492.833660] Dynamic subtest render-none-1024: SUCCESS (0.367s)
[492.844210] Starting dynamic subtest: render-x-512
[492.931306] Dynamic subtest render-x-512: SUCCESS (0.098s)
[492.941673] Starting dynamic subtest: render-x-1024
[493.497583] Dynamic subtest render-x-1024: SUCCESS (0.564s)
[493.507923] Starting dynamic subtest: render-y-512
[493.595945] Dynamic subtest render-y-512: SUCCESS (0.098s)
[493.606624] Starting dynamic subtest: render-y-1024
[493.926536] Dynamic subtest render-y-1024: SUCCESS (0.329s)
[493.929149] Subtest render: SUCCESS (1.562s)
[494.071324] [118/134] (533s left) kms_selftest (drm_damage_helper)
[494.112723] Starting subtest: drm_damage_helper
[494.125883] Starting dynamic subtest: drm_test_damage_iter_no_damage
[494.285774] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.159s)
[494.295751] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[494.430991] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.144s)
[494.441471] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[494.576625] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.145s)
[494.590682] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[494.708330] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.131s)
[494.718426] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[494.851341] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.143s)
[494.860932] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[494.977822] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.124s)
[494.987657] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[495.123255] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.144s)
[495.132323] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[495.241130] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.117s)
[495.249785] Starting dynamic subtest: drm_test_damage_iter_single_damage
[495.379711] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.137s)
[495.388697] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[495.503664] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.122s)
[495.513546] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[495.629862] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.128s)
[495.639481] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[495.773958] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.142s)
[495.783658] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[495.903101] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.130s)
[495.913078] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[496.045745] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.141s)
[496.055382] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[496.166149] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.120s)
[496.174208] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[496.249476] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.082s)
[496.263465] Starting dynamic subtest: drm_test_damage_iter_damage
[496.388297] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.137s)
[496.398140] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[496.489917] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.102s)
[496.498811] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[496.633094] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.141s)
[496.642798] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[496.763126] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.130s)
[496.772744] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[496.900121] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.135s)
[496.902172] Subtest drm_damage_helper: SUCCESS (2.787s)
[497.129865] [119/134] (530s left) kms_ccs (crc-primary-suspend-4-tiled-mtl-mc-ccs)
[497.232954] Starting subtest: crc-primary-suspend-4-tiled-mtl-mc-ccs
[497.235246] Starting dynamic subtest: pipe-A-eDP-1
[529.176860] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.048s)
[529.186462] Starting dynamic subtest: pipe-B-eDP-1
[562.107388] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.639s)
[562.119142] Starting dynamic subtest: pipe-C-eDP-1
[595.072468] Dynamic subtest pipe-C-eDP-1: SUCCESS (3.609s)
[595.082532] Starting dynamic subtest: pipe-D-eDP-1
[628.112606] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.687s)
[628.115006] Subtest crc-primary-suspend-4-tiled-mtl-mc-ccs: SUCCESS (13.991s)
[629.370564] [120/134] (398s left) kms_lease (empty-lease)
[629.478752] Starting subtest: empty-lease
[629.481207] Starting dynamic subtest: pipe-A-eDP-1
[629.483522] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s)
[629.493697] Starting dynamic subtest: pipe-B-eDP-1
[629.496004] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[629.505444] Starting dynamic subtest: pipe-C-eDP-1
[629.507739] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s)
[629.516896] Starting dynamic subtest: pipe-D-eDP-1
[629.519164] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s)
[629.521416] Subtest empty-lease: SUCCESS (0.016s)
[629.629056] [121/134] (398s left) kms_content_protection (atomic-dpms-hdcp14)
[629.771876] Starting subtest: atomic-dpms-hdcp14
[629.776563] Subtest atomic-dpms-hdcp14: SKIP (0.000s)
[629.940775] [122/134] (397s left) syncobj_wait (invalid-multi-wait-unsubmitted)
[629.988602] Starting subtest: invalid-multi-wait-unsubmitted
[629.991055] Subtest invalid-multi-wait-unsubmitted: SUCCESS (0.000s)
[630.124984] [123/134] (397s left) gem_exec_store (dword)
[630.199666] Starting subtest: dword
[630.202068] Starting dynamic subtest: rcs0
[630.305518] Dynamic subtest rcs0: SUCCESS (0.105s)
[630.317891] Starting dynamic subtest: bcs0
[630.348530] Dynamic subtest bcs0: SUCCESS (0.041s)
[630.357817] Starting dynamic subtest: vcs0
[630.391320] Dynamic subtest vcs0: SUCCESS (0.040s)
[630.401227] Starting dynamic subtest: vcs1
[630.433211] Dynamic subtest vcs1: SUCCESS (0.039s)
[630.445844] Starting dynamic subtest: vecs0
[630.476052] Dynamic subtest vecs0: SUCCESS (0.040s)
[630.487714] Starting dynamic subtest: ccs0
[630.519388] Dynamic subtest ccs0: SUCCESS (0.040s)
[630.521462] Subtest dword: SUCCESS (0.317s)
[630.733637] [124/134] (397s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier)
[630.823416] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier
[630.850804] Starting dynamic subtest: pipe-A-plane-0
[630.855532] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[630.866410] Starting dynamic subtest: pipe-A-plane-3
[630.871092] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[630.879991] Starting dynamic subtest: pipe-A-plane-5
[630.884836] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[631.987387] Starting dynamic subtest: pipe-B-plane-0
[631.991874] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[632.000736] Starting dynamic subtest: pipe-B-plane-3
[632.005576] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[632.017030] Starting dynamic subtest: pipe-B-plane-5
[632.021708] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[632.307230] Subtest pixel-format-4-tiled-bmg-ccs-modifier: SKIP (1.461s)
[633.192311] [125/134] (394s left) gem_exec_balancer (nop)
[633.256630] Starting subtest: nop
[680.019385] Subtest nop: SUCCESS (46.668s)
[680.321254] [126/134] (347s left) i915_drm_fdinfo (virtual-busy-idle)
[680.357890] Starting subtest: virtual-busy-idle
[680.362183] Subtest virtual-busy-idle: SKIP (0.000s)
[680.440804] [127/134] (347s left) i915_pm_rpm (gem-idle)
[682.779921] Starting subtest: gem-idle
[688.987136] Subtest gem-idle: SUCCESS (6.211s)
[689.329446] [128/134] (338s left) kms_fbcon_fbt (psr)
[690.563069] Starting subtest: psr
[697.842126] Subtest psr: SUCCESS (7.275s)
[697.919218] [129/134] (330s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move)
[698.029866] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move
[705.617779] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.589s)
[705.802154] [130/134] (322s left) gem_softpin (full)
[705.865761] Starting subtest: full
[705.874186] Subtest full: SUCCESS (0.008s)
[706.007249] [131/134] (322s left) kms_psr (fbc-psr-basic)
[706.113434] Starting subtest: fbc-psr-basic
[706.115722] Starting dynamic subtest: eDP-1
[706.120108] Dynamic subtest eDP-1: SKIP (0.000s)
[706.124570] Subtest fbc-psr-basic: SKIP (0.001s)
[706.199916] [132/134] (322s left) kms_async_flips (alternate-sync-async-flip-atomic)
[706.303033] Starting subtest: alternate-sync-async-flip-atomic
[707.450904] Starting dynamic subtest: pipe-A-eDP-1
[709.913452] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.464s)
[710.283248] Starting dynamic subtest: pipe-B-eDP-1
[713.045352] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.771s)
[713.427528] Starting dynamic subtest: pipe-C-eDP-1
[716.175609] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.755s)
[716.555361] Starting dynamic subtest: pipe-D-eDP-1
[719.314159] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.766s)
[719.316479] Subtest alternate-sync-async-flip-atomic: SUCCESS (13.011s)
[720.494577] [133/134] (307s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-cpu)
[720.600926] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-cpu
[720.605081] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[720.738933] [134/134] (307s left) kms_cursor_crc (cursor-sliding-max-size)
[721.045555] Starting subtest: cursor-sliding-max-size
[721.051969] Subtest cursor-sliding-max-size: SKIP (0.000s)
[721.216724] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_ccs
results: parsing output: 2/ for test: kms_big_fb
results: parsing output: 3/ for test: gem_exec_schedule
results: parsing output: 4/ for test: kms_cursor_crc
results: parsing output: 5/ for test: gem_userptr_blits
results: parsing output: 6/ for test: syncobj_timeline
results: parsing output: 7/ for test: kms_frontbuffer_tracking
results: parsing output: 8/ for test: gem_exec_capture
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: sysfs_timeslice_duration
results: parsing output: 11/ for test: kms_ccs
results: parsing output: 12/ for test: kms_chamelium_frames
results: parsing output: 13/ for test: gem_exec_fence
results: parsing output: 14/ for test: kms_psr
results: parsing output: 15/ for test: kms_big_fb
results: parsing output: 16/ for test: kms_frontbuffer_tracking
results: parsing output: 17/ for test: kms_big_fb
results: parsing output: 18/ for test: kms_frontbuffer_tracking
results: parsing output: 19/ for test: kms_plane_cursor
results: parsing output: 20/ for test: sw_sync
results: parsing output: 21/ for test: drm_read
results: parsing output: 22/ for test: gem_exec_flush
results: parsing output: 23/ for test: gem_cs_tlb
results: parsing output: 24/ for test: kms_big_fb
results: parsing output: 25/ for test: kms_frontbuffer_tracking
results: parsing output: 26/ for test: kms_frontbuffer_tracking
results: parsing output: 27/ for test: kms_psr2_sf
results: parsing output: 28/ for test: kms_chamelium_audio
results: parsing output: 29/ for test: gem_tiled_wb
results: parsing output: 30/ for test: kms_plane_scaling
results: parsing output: 31/ for test: gem_ctx_persistence
results: parsing output: 32/ for test: kms_frontbuffer_tracking
results: parsing output: 33/ for test: kms_psr2_sf
results: parsing output: 34/ for test: vgem_basic
results: parsing output: 35/ for test: kms_frontbuffer_tracking
results: parsing output: 36/ for test: kms_cursor_legacy
results: parsing output: 37/ for test: kms_cursor_legacy
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: gem_lmem_swapping
results: parsing output: 40/ for test: syncobj_wait
results: parsing output: 41/ for test: kms_flip
results: parsing output: 42/ for test: syncobj_basic
results: parsing output: 43/ for test: kms_pm_rpm
results: parsing output: 44/ for test: perf_pmu
results: parsing output: 45/ for test: kms_plane
results: parsing output: 46/ for test: kms_psr
results: parsing output: 47/ for test: kms_cursor_legacy
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: kms_pm_rpm
results: parsing output: 50/ for test: kms_ccs
results: parsing output: 51/ for test: syncobj_wait
results: parsing output: 52/ for test: perf_pmu
results: parsing output: 53/ for test: i915_pm_rps
results: parsing output: 54/ for test: kms_psr
results: parsing output: 55/ for test: gem_tiled_blits
results: parsing output: 56/ for test: i915_drm_fdinfo
results: parsing output: 57/ for test: kms_color
results: parsing output: 58/ for test: gem_render_copy
results: parsing output: 59/ for test: api_intel_allocator
results: parsing output: 60/ for test: kms_flip_scaled_crc
results: parsing output: 61/ for test: gem_ctx_shared
results: parsing output: 62/ for test: gem_pxp
results: parsing output: 63/ for test: kms_big_fb
results: parsing output: 64/ for test: core_getstats
results: parsing output: 65/ for test: kms_joiner
results: parsing output: 66/ for test: kms_frontbuffer_tracking
results: parsing output: 67/ for test: gem_exec_schedule
results: parsing output: 68/ for test: gem_exec_flush
results: parsing output: 69/ for test: kms_frontbuffer_tracking
results: parsing output: 70/ for test: gem_mmap_gtt
results: parsing output: 71/ for test: kms_async_flips
results: parsing output: 72/ for test: gen7_exec_parse
results: parsing output: 73/ for test: syncobj_timeline
results: parsing output: 74/ for test: kms_busy
results: parsing output: 75/ for test: kms_cursor_legacy
results: parsing output: 76/ for test: kms_tiled_display
results: parsing output: 77/ for test: kms_plane
results: parsing output: 78/ for test: gem_exec_create
results: parsing output: 79/ for test: gem_exec_fence
results: parsing output: 80/ for test: gem_ctx_persistence
results: parsing output: 81/ for test: sw_sync
results: parsing output: 82/ for test: kms_cursor_legacy
results: parsing output: 83/ for test: kms_pipe_b_c_ivb
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: syncobj_timeline
results: parsing output: 86/ for test: gem_render_copy
results: parsing output: 87/ for test: gem_exec_params
results: parsing output: 88/ for test: kms_addfb_basic
results: parsing output: 89/ for test: prime_vgem
results: parsing output: 90/ for test: gem_ctx_param
results: parsing output: 91/ for test: gem_mmap_gtt
results: parsing output: 92/ for test: gem_exec_capture
results: parsing output: 93/ for test: syncobj_timeline
results: parsing output: 94/ for test: syncobj_wait
results: parsing output: 95/ for test: syncobj_timeline
results: parsing output: 96/ for test: kms_draw_crc
results: parsing output: 97/ for test: kms_vrr
results: parsing output: 98/ for test: kms_setmode
results: parsing output: 99/ for test: gem_madvise
results: parsing output: 100/ for test: kms_psr2_sf
results: parsing output: 101/ for test: syncobj_wait
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gem_mmap_offset
results: parsing output: 104/ for test: gem_lmem_swapping
results: parsing output: 105/ for test: kms_cursor_legacy
results: parsing output: 106/ for test: prime_busy
results: parsing output: 107/ for test: kms_vrr
results: parsing output: 108/ for test: kms_big_fb
results: parsing output: 109/ for test: prime_vgem
results: parsing output: 110/ for test: kms_cursor_crc
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: kms_flip_scaled_crc
results: parsing output: 113/ for test: kms_chamelium_hpd
results: parsing output: 114/ for test: kms_hdr
results: parsing output: 115/ for test: kms_frontbuffer_tracking
results: parsing output: 116/ for test: api_intel_bb
results: parsing output: 117/ for test: kms_selftest
results: parsing output: 118/ for test: kms_ccs
results: parsing output: 119/ for test: kms_lease
results: parsing output: 120/ for test: kms_content_protection
results: parsing output: 121/ for test: syncobj_wait
results: parsing output: 122/ for test: gem_exec_store
results: parsing output: 123/ for test: kms_plane
results: parsing output: 124/ for test: gem_exec_balancer
results: parsing output: 125/ for test: i915_drm_fdinfo
results: parsing output: 126/ for test: i915_pm_rpm
results: parsing output: 127/ for test: kms_fbcon_fbt
results: parsing output: 128/ for test: kms_frontbuffer_tracking
results: parsing output: 129/ for test: gem_softpin
results: parsing output: 130/ for test: kms_psr
results: parsing output: 131/ for test: kms_async_flips
results: parsing output: 132/ for test: kms_frontbuffer_tracking
results: parsing output: 133/ for test: kms_cursor_crc
Done.