[57.735343] Initializing watchdogs
[57.735444]   /dev/watchdog0
[57.752791] [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]
[57.757229] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[57.759439] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[57.761311] [001/131] (960s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-blt)
[57.862083] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-blt
[57.863836] Subtest fbc-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[57.993763] [002/131] (960s left) prime_vgem (sync)
[58.084686] Starting subtest: sync
[58.085811] Starting dynamic subtest: rcs0
[58.119286] Dynamic subtest rcs0: SUCCESS (0.035s)
[58.125281] Starting dynamic subtest: bcs0
[58.180916] Dynamic subtest bcs0: SUCCESS (0.061s)
[58.186901] Starting dynamic subtest: vcs0
[58.227536] Dynamic subtest vcs0: SUCCESS (0.047s)
[58.232895] Starting dynamic subtest: vcs1
[58.272953] Dynamic subtest vcs1: SUCCESS (0.045s)
[58.278377] Starting dynamic subtest: vecs0
[58.319458] Dynamic subtest vecs0: SUCCESS (0.046s)
[58.325068] Starting dynamic subtest: ccs0
[58.365484] Dynamic subtest ccs0: SUCCESS (0.046s)
[58.366488] Subtest sync: SUCCESS (0.282s)
[58.512342] [FACT prime_vgem (sync)] new: kernel.kmod_is_loaded.vgem: true
[58.514141] [003/131] (959s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-pgflip-blt)
[58.605978] Starting subtest: psr-2p-primscrn-shrfb-pgflip-blt
[58.607792] Subtest psr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s)
[58.716227] [004/131] (959s left) i915_suspend (fence-restore-tiled2untiled)
[58.760212] Starting subtest: fence-restore-tiled2untiled
[58.762080] Subtest fence-restore-tiled2untiled: SKIP (0.000s)
[58.886142] [005/131] (959s left) kms_atomic_transition (plane-all-transition)
[58.964079] Starting subtest: plane-all-transition
[58.964959] Starting dynamic subtest: pipe-A-eDP-1
[61.494043] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.535s)
[61.797843] Starting dynamic subtest: pipe-B-eDP-1
[64.995174] Dynamic subtest pipe-B-eDP-1: SUCCESS (3.206s)
[65.290429] Subtest plane-all-transition: SUCCESS (6.336s)
[66.141885] [006/131] (952s left) syncobj_wait (multi-wait-for-submit-unsubmitted-submitted)
[66.202550] Starting subtest: multi-wait-for-submit-unsubmitted-submitted
[66.308346] Subtest multi-wait-for-submit-unsubmitted-submitted: SUCCESS (0.103s)
[66.393993] [007/131] (952s left) kms_color (ctm-negative)
[66.481375] Starting subtest: ctm-negative
[66.482257] Starting dynamic subtest: pipe-A-eDP-1
[67.196656] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.715s)
[67.202705] Starting dynamic subtest: pipe-B-eDP-1
[68.602981] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.408s)
[68.610487] Starting dynamic subtest: pipe-C-eDP-1
[69.996183] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.394s)
[70.001528] Starting dynamic subtest: pipe-D-eDP-1
[71.312786] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.315s)
[71.313760] Subtest ctm-negative: SUCCESS (4.835s)
[72.156534] [008/131] (946s left) syncobj_timeline (wait-for-submit-snapshot)
[72.219127] Starting subtest: wait-for-submit-snapshot
[72.301391] Subtest wait-for-submit-snapshot: SUCCESS (0.082s)
[72.390101] [009/131] (946s left) kms_frontbuffer_tracking (fbcpsr-slowdraw)
[72.491942] Starting subtest: fbcpsr-slowdraw
[97.775387] Subtest fbcpsr-slowdraw: SUCCESS (25.285s)
[97.949612] [010/131] (920s left) kms_joiner (basic-max-non-joiner)
[98.049119] Starting subtest: basic-max-non-joiner
[98.050958] Subtest basic-max-non-joiner: SKIP (0.000s)
[98.134325] [011/131] (920s left) kms_psr (pr-cursor-mmap-cpu)
[98.231793] Starting subtest: pr-cursor-mmap-cpu
[98.233769] Subtest pr-cursor-mmap-cpu: SKIP (0.000s)
[98.320609] [012/131] (920s left) vgem_basic (create)
[98.367277] Starting subtest: create
[98.368145] Subtest create: SUCCESS (0.000s)
[98.441690] [013/131] (920s left) kms_frontbuffer_tracking (fbcpsr-2p-shrfb-fliptrack-mmap-gtt)
[98.546324] Starting subtest: fbcpsr-2p-shrfb-fliptrack-mmap-gtt
[98.548030] Subtest fbcpsr-2p-shrfb-fliptrack-mmap-gtt: SKIP (0.000s)
[98.675252] [014/131] (919s left) kms_ccs (crc-primary-rotation-180-4-tiled-lnl-ccs)
[98.763049] Starting subtest: crc-primary-rotation-180-4-tiled-lnl-ccs
[98.764707] Subtest crc-primary-rotation-180-4-tiled-lnl-ccs: SKIP (0.000s)
[99.925245] [015/131] (918s left) kms_selftest (drm_dp_mst_helper)
[99.962423] Starting subtest: drm_dp_mst_helper
[99.968266] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode
[100.108739] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.139s)
[100.116061] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div
[100.223549] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.115s)
[100.230125] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode
[100.361068] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.136s)
[100.361926] Subtest drm_dp_mst_helper: SUCCESS (0.399s)
[100.548436] [016/131] (918s left) kms_lease (lease-uevent)
[100.637458] Starting subtest: lease-uevent
[101.649758] Subtest lease-uevent: SUCCESS (1.010s)
[101.737806] [017/131] (917s left) gem_exec_fence (invalid-timeline-fence-array)
[102.263946] Starting subtest: invalid-timeline-fence-array
[102.268675] Subtest invalid-timeline-fence-array: SUCCESS (0.007s)
[104.011768] [018/131] (914s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-mmap-wc)
[104.101885] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-mmap-wc
[104.103612] Subtest fbc-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s)
[104.219342] [019/131] (914s left) kms_feature_discovery (display-4x)
[104.322596] Starting subtest: display-4x
[104.324511] Subtest display-4x: SKIP (0.000s)
[104.413201] [020/131] (914s left) perf (oa-formats)
[104.552700] Starting subtest: oa-formats
[104.553580] Starting dynamic subtest: 0-rcs0
[104.568859] Dynamic subtest 0-rcs0: SUCCESS (0.016s)
[104.576100] Starting dynamic subtest: 1-vecs0
[104.592841] Dynamic subtest 1-vecs0: SUCCESS (0.023s)
[104.593799] Subtest oa-formats: SUCCESS (0.041s)
[104.632955] [021/131] (914s left) api_intel_allocator (execbuf-with-allocator)
[104.693537] Starting subtest: execbuf-with-allocator
[104.699463] Subtest execbuf-with-allocator: SUCCESS (0.007s)
[104.841472] [022/131] (914s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-msflip-blt)
[104.929969] Starting subtest: fbcpsr-1p-primscrn-shrfb-msflip-blt
[112.253081] Subtest fbcpsr-1p-primscrn-shrfb-msflip-blt: SUCCESS (7.323s)
[112.397688] [023/131] (906s left) kms_cursor_edge_walk (256x256-left-edge)
[112.487911] Starting subtest: 256x256-left-edge
[112.488769] Starting dynamic subtest: pipe-A-eDP-1
[115.910837] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.423s)
[115.918068] Starting dynamic subtest: pipe-D-eDP-1
[119.035512] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.123s)
[119.036477] Subtest 256x256-left-edge: SUCCESS (6.548s)
[119.879129] [024/131] (899s left) gem_exec_whisper (basic-fds-priority)
[119.937404] Starting subtest: basic-fds-priority
[143.525693] Subtest basic-fds-priority: SUCCESS (23.583s)
[143.726747] [025/131] (875s left) kms_sequence (get-forked-busy)
[143.835091] Starting subtest: get-forked-busy
[143.836020] Starting dynamic subtest: pipe-A-eDP-1
[145.770357] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.934s)
[145.776900] Starting dynamic subtest: pipe-B-eDP-1
[148.351403] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.581s)
[148.357875] Starting dynamic subtest: pipe-C-eDP-1
[150.965085] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.612s)
[150.971195] Starting dynamic subtest: pipe-D-eDP-1
[153.597975] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.632s)
[153.598814] Subtest get-forked-busy: SUCCESS (9.763s)
[154.482463] [026/131] (864s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling)
[154.565270] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling
[154.566123] Starting dynamic subtest: pipe-A-default-mode
[154.872532] Dynamic subtest pipe-A-default-mode: SKIP (0.305s)
[154.874237] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.307s)
[155.719066] [027/131] (863s left) kms_draw_crc (draw-method-mmap-wc)
[155.816117] Starting subtest: draw-method-mmap-wc
[155.817023] Starting dynamic subtest: xrgb8888-untiled
[156.027119] Dynamic subtest xrgb8888-untiled: SUCCESS (0.211s)
[156.034559] Starting dynamic subtest: rgb565-untiled
[156.227056] Dynamic subtest rgb565-untiled: SUCCESS (0.198s)
[156.233516] Starting dynamic subtest: xrgb2101010-untiled
[156.416049] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.188s)
[156.422709] Starting dynamic subtest: xbgr16161616f-untiled
[156.627154] Dynamic subtest xbgr16161616f-untiled: SUCCESS (0.210s)
[156.633690] Starting dynamic subtest: xrgb8888-xtiled
[156.771689] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.144s)
[156.779052] Starting dynamic subtest: rgb565-xtiled
[156.916062] Dynamic subtest rgb565-xtiled: SUCCESS (0.142s)
[156.922389] Starting dynamic subtest: xrgb2101010-xtiled
[157.049341] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.133s)
[157.055835] Starting dynamic subtest: xbgr16161616f-xtiled
[157.182696] Dynamic subtest xbgr16161616f-xtiled: SUCCESS (0.132s)
[157.189105] Starting dynamic subtest: xrgb8888-4tiled
[157.338302] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.155s)
[157.345174] Starting dynamic subtest: rgb565-4tiled
[157.482792] Dynamic subtest rgb565-4tiled: SUCCESS (0.143s)
[157.489370] Starting dynamic subtest: xrgb2101010-4tiled
[157.616249] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.133s)
[157.629060] Starting dynamic subtest: xbgr16161616f-4tiled
[157.793876] Dynamic subtest xbgr16161616f-4tiled: SUCCESS (0.177s)
[157.794803] Subtest draw-method-mmap-wc: SUCCESS (1.978s)
[157.940381] [028/131] (861s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation)
[158.027413] Starting subtest: plane-upscale-factor-0-25-with-rotation
[158.028404] Starting dynamic subtest: pipe-A
[158.253615] Dynamic subtest pipe-A: SUCCESS (0.227s)
[158.260231] Starting dynamic subtest: pipe-B
[159.585933] Dynamic subtest pipe-B: SUCCESS (1.332s)
[159.592889] Starting dynamic subtest: pipe-C
[160.925255] Dynamic subtest pipe-C: SUCCESS (1.338s)
[160.932978] Starting dynamic subtest: pipe-D
[162.254243] Dynamic subtest pipe-D: SUCCESS (1.327s)
[162.255226] Subtest plane-upscale-factor-0-25-with-rotation: SUCCESS (4.228s)
[163.460041] [029/131] (855s left) kms_rotation_crc (primary-y-tiled-reflect-x-270)
[163.548986] Starting subtest: primary-y-tiled-reflect-x-270
[163.550550] Subtest primary-y-tiled-reflect-x-270: SKIP (0.001s)
[163.640634] [030/131] (855s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-render)
[163.725252] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-render
[163.726899] Subtest psr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[163.848938] [031/131] (855s left) gem_tiled_partial_pwrite_pread (writes-after-reads)
[163.906042] Subtest writes-after-reads: SKIP (0.000s)
[163.983950] [032/131] (855s left) gem_mmap_gtt (isolation)
[164.033638] Subtest isolation: SKIP (0.000s)
[164.098040] [033/131] (855s left) gem_mmap_offset (pf-nonblock)
[164.151221] Starting subtest: pf-nonblock
[164.161029] Subtest pf-nonblock: SUCCESS (0.010s)
[164.267926] [034/131] (855s left) prime_self_import (basic-with_one_bo)
[164.301762] Starting subtest: basic-with_one_bo
[164.324945] Subtest basic-with_one_bo: SUCCESS (0.024s)
[164.462422] [035/131] (854s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-draw-render)
[164.547773] Starting subtest: fbc-2p-primscrn-spr-indfb-draw-render
[164.549708] Subtest fbc-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[164.659916] [036/131] (854s left) syncobj_wait (single-wait-signaled)
[164.712402] Starting subtest: single-wait-signaled
[164.713270] Subtest single-wait-signaled: SUCCESS (0.000s)
[164.840380] [037/131] (854s left) kms_psr2_sf (pr-cursor-plane-update-sf)
[169.830582] Starting subtest: pr-cursor-plane-update-sf
[169.833686] Subtest pr-cursor-plane-update-sf: SKIP (0.002s)
[170.689345] [038/131] (848s left) kms_cursor_crc (cursor-onscreen-256x256)
[170.914094] Starting subtest: cursor-onscreen-256x256
[170.917682] Starting dynamic subtest: pipe-A-eDP-1
[173.043898] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.126s)
[173.068706] Starting dynamic subtest: pipe-D-eDP-1
[175.858832] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.796s)
[175.859763] Subtest cursor-onscreen-256x256: SUCCESS (4.946s)
[176.671843] [039/131] (842s left) kms_vrr (flipline)
[176.760149] Starting subtest: flipline
[176.762850] Subtest flipline: SKIP (0.000s)
[176.848605] [040/131] (842s left) kms_chamelium_color (ctm-0-50)
[176.939256] Subtest ctm-0-50: SKIP (0.000s)
[176.979329] [041/131] (842s left) gem_mmap_wc (fault-concurrent)
[177.038329] Subtest fault-concurrent: SKIP (0.000s)
[177.085949] [042/131] (842s left) kms_cursor_legacy (cursora-vs-flipb-varying-size)
[177.194465] Starting subtest: cursorA-vs-flipB-varying-size
[177.196189] Subtest cursorA-vs-flipB-varying-size: SKIP (0.001s)
[177.354117] [043/131] (842s left) gem_exec_schedule (fairslice)
[177.415355] Starting subtest: fairslice
[177.416365] Starting dynamic subtest: rcs0
[179.437209] Dynamic subtest rcs0: SUCCESS (2.020s)
[179.444405] Starting dynamic subtest: bcs0
[181.454098] Dynamic subtest bcs0: SUCCESS (2.017s)
[181.460450] Starting dynamic subtest: vcs0
[183.475769] Dynamic subtest vcs0: SUCCESS (2.020s)
[183.482944] Starting dynamic subtest: vcs1
[185.494149] Dynamic subtest vcs1: SUCCESS (2.016s)
[185.501035] Starting dynamic subtest: vecs0
[187.511754] Dynamic subtest vecs0: SUCCESS (2.017s)
[187.519044] Starting dynamic subtest: ccs0
[189.529848] Dynamic subtest ccs0: SUCCESS (2.019s)
[189.530878] Subtest fairslice: SUCCESS (12.114s)
[189.848905] [044/131] (829s left) syncobj_wait (signal)
[189.902987] Starting subtest: signal
[189.904133] Subtest signal: SUCCESS (0.000s)
[190.004447] [045/131] (829s left) gem_exec_schedule (thriceslice)
[190.058439] Starting subtest: thriceslice
[190.059325] Starting dynamic subtest: rcs0
[190.115855] Dynamic subtest rcs0: SUCCESS (0.057s)
[190.125354] Starting dynamic subtest: bcs0
[190.172476] Dynamic subtest bcs0: SUCCESS (0.055s)
[190.181997] Starting dynamic subtest: vcs0
[190.204479] Dynamic subtest vcs0: SUCCESS (0.031s)
[190.211051] Starting dynamic subtest: vcs1
[190.235209] Dynamic subtest vcs1: SUCCESS (0.030s)
[190.242347] Starting dynamic subtest: vecs0
[190.287369] Dynamic subtest vecs0: SUCCESS (0.051s)
[190.297331] Starting dynamic subtest: ccs0
[190.344069] Dynamic subtest ccs0: SUCCESS (0.055s)
[190.345263] Subtest thriceslice: SUCCESS (0.287s)
[190.666683] [046/131] (828s left) gem_exec_balancer (hog)
[190.735932] Starting subtest: hog
[190.737762] Subtest hog: SKIP (0.000s)
[191.076527] [047/131] (828s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-plflip-blt)
[191.179864] Starting subtest: fbc-2p-scndscrn-indfb-plflip-blt
[191.181614] Subtest fbc-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[191.323752] [048/131] (828s left) gem_partial_pwrite_pread (write)
[191.375589] Subtest write: SKIP (0.000s)
[191.426087] [049/131] (828s left) kms_pm_rpm (modeset-lpsp-stress-no-wait)
[193.041715] Starting subtest: modeset-lpsp-stress-no-wait
[202.913396] Subtest modeset-lpsp-stress-no-wait: SUCCESS (9.873s)
[203.011348] [050/131] (816s left) kms_plane_multiple (tiling-4)
[203.091115] Starting subtest: tiling-4
[203.091981] Starting dynamic subtest: pipe-A-eDP-1
[206.825450] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.734s)
[206.836839] Starting dynamic subtest: pipe-B-eDP-1
[211.187162] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.359s)
[211.193639] Starting dynamic subtest: pipe-C-eDP-1
[215.530675] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.342s)
[215.537224] Starting dynamic subtest: pipe-D-eDP-1
[219.867156] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.335s)
[219.868060] Subtest tiling-4: SUCCESS (16.777s)
[220.695173] [051/131] (799s left) kms_ccs (bad-rotation-90-y-tiled-gen12-mc-ccs)
[220.780467] Starting subtest: bad-rotation-90-y-tiled-gen12-mc-ccs
[220.781409] Starting dynamic subtest: pipe-A-eDP-1
[220.790066] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[220.801199] Starting dynamic subtest: pipe-B-eDP-1
[220.810819] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[220.820103] Starting dynamic subtest: pipe-C-eDP-1
[220.828716] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[220.835053] Starting dynamic subtest: pipe-D-eDP-1
[220.843764] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[220.845469] Subtest bad-rotation-90-y-tiled-gen12-mc-ccs: SKIP (0.005s)
[222.954917] [052/131] (796s left) kms_big_fb (linear-32bpp-rotate-270)
[223.060985] Starting subtest: linear-32bpp-rotate-270
[223.080617] Subtest linear-32bpp-rotate-270: SKIP (0.019s)
[223.235559] [053/131] (796s left) kms_flip_scaled_crc (flip-64bpp-linear-to-32bpp-linear-downscaling)
[223.324388] Starting subtest: flip-64bpp-linear-to-32bpp-linear-downscaling
[223.325251] Starting dynamic subtest: pipe-A-default-mode
[224.519528] Dynamic subtest pipe-A-default-mode: SKIP (1.194s)
[224.521249] Subtest flip-64bpp-linear-to-32bpp-linear-downscaling: SKIP (1.195s)
[224.714433] [054/131] (795s left) kms_frontbuffer_tracking (fbcpsr-indfb-scaledprimary)
[224.816514] Starting subtest: fbcpsr-indfb-scaledprimary
[230.560261] Subtest fbcpsr-indfb-scaledprimary: SUCCESS (5.744s)
[230.734512] [055/131] (789s left) gem_exec_flush (basic-wb-ro-default)
[230.806509] Starting subtest: basic-wb-ro-default
[236.261721] Subtest basic-wb-ro-default: SUCCESS (5.455s)
[236.465543] [056/131] (783s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-hflip)
[236.572461] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-hflip
[236.574422] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-hflip: SKIP (0.000s)
[236.693424] [057/131] (783s left) kms_flip (2x-blocking-wf_vblank)
[236.787688] Starting subtest: 2x-blocking-wf_vblank
[236.799564] Subtest 2x-blocking-wf_vblank: SKIP (0.011s)
[236.897952] [058/131] (783s left) core_setmaster (master-drop-set-root)
[236.975773] Starting subtest: master-drop-set-root
[236.987146] Subtest master-drop-set-root: SUCCESS (0.012s)
[237.028323] [059/131] (783s left) gem_partial_pwrite_pread (reads)
[237.078373] Subtest reads: SKIP (0.000s)
[237.153794] [060/131] (782s left) kms_pm_rpm (fences-dpms)
[239.065522] Starting subtest: fences-dpms
[239.067840] Subtest fences-dpms: SKIP (0.000s)
[239.459200] [061/131] (780s left) syncobj_wait (multi-wait-all-for-submit-signaled)
[239.510512] Starting subtest: multi-wait-all-for-submit-signaled
[239.511311] Subtest multi-wait-all-for-submit-signaled: SUCCESS (0.000s)
[239.626732] [062/131] (780s left) kms_chamelium_edid (dp-edid-resolution-list)
[240.001520] Subtest dp-edid-resolution-list: SKIP (0.000s)
[240.833914] [063/131] (779s left) gem_mmap_gtt (bad-object)
[240.883717] Subtest bad-object: SKIP (0.000s)
[240.940919] [064/131] (779s left) kms_async_flips (async-flip-with-page-flip-events-tiled)
[241.037497] Starting subtest: async-flip-with-page-flip-events-tiled
[241.422819] Starting dynamic subtest: pipe-A-eDP-1-4-rc-ccs
[244.153635] Dynamic subtest pipe-A-eDP-1-4-rc-ccs: SUCCESS (2.729s)
[244.160790] Starting dynamic subtest: pipe-A-eDP-1-4
[246.343783] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.186s)
[246.360077] Starting dynamic subtest: pipe-A-eDP-1-4
[248.350804] Dynamic subtest pipe-A-eDP-1-4: SUCCESS (2.008s)
[248.358924] Starting dynamic subtest: pipe-A-eDP-1-x
[250.551847] Dynamic subtest pipe-A-eDP-1-x: SUCCESS (2.200s)
[250.914624] Starting dynamic subtest: pipe-B-eDP-1-4-rc-ccs
[253.677729] Dynamic subtest pipe-B-eDP-1-4-rc-ccs: SUCCESS (2.767s)
[253.687844] Starting dynamic subtest: pipe-B-eDP-1-4
[255.863878] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.185s)
[255.871027] Starting dynamic subtest: pipe-B-eDP-1-4
[257.872968] Dynamic subtest pipe-B-eDP-1-4: SUCCESS (2.009s)
[257.880071] Starting dynamic subtest: pipe-B-eDP-1-x
[260.076134] Dynamic subtest pipe-B-eDP-1-x: SUCCESS (2.201s)
[260.446791] Starting dynamic subtest: pipe-C-eDP-1-4-rc-ccs
[263.203165] Dynamic subtest pipe-C-eDP-1-4-rc-ccs: SUCCESS (2.761s)
[263.217648] Starting dynamic subtest: pipe-C-eDP-1-4
[265.389405] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.185s)
[265.398835] Starting dynamic subtest: pipe-C-eDP-1-4
[267.397194] Dynamic subtest pipe-C-eDP-1-4: SUCCESS (2.009s)
[267.405145] Starting dynamic subtest: pipe-C-eDP-1-x
[269.578493] Dynamic subtest pipe-C-eDP-1-x: SUCCESS (2.177s)
[269.945041] Starting dynamic subtest: pipe-D-eDP-1-4-rc-ccs
[272.733433] Dynamic subtest pipe-D-eDP-1-4-rc-ccs: SUCCESS (2.791s)
[272.740823] Starting dynamic subtest: pipe-D-eDP-1-4
[274.908263] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.173s)
[274.915250] Starting dynamic subtest: pipe-D-eDP-1-4
[276.918823] Dynamic subtest pipe-D-eDP-1-4: SUCCESS (2.009s)
[276.928084] Starting dynamic subtest: pipe-D-eDP-1-x
[279.108467] Dynamic subtest pipe-D-eDP-1-x: SUCCESS (2.189s)
[279.109502] Subtest async-flip-with-page-flip-events-tiled: SUCCESS (38.069s)
[280.329278] [065/131] (739s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-render)
[280.415590] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-render
[280.417295] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-render: SKIP (0.000s)
[280.519814] [066/131] (739s left) gem_exec_alignment (many)
[280.575787] Starting subtest: many
[303.692097] Subtest many: SUCCESS (23.114s)
[305.798245] [067/131] (714s left) kms_psr (psr-dpms)
[305.933049] Starting subtest: psr-dpms
[305.933996] Starting dynamic subtest: eDP-1
[308.940044] Dynamic subtest eDP-1: SUCCESS (3.006s)
[308.940885] Subtest psr-dpms: SUCCESS (3.007s)
[309.834049] [068/131] (710s left) gem_ctx_persistence (engines-mixed-process)
[309.896813] Starting subtest: engines-mixed-process
[309.897991] Starting dynamic subtest: rcs0
[311.900446] Dynamic subtest rcs0: SUCCESS (2.006s)
[311.910571] Starting dynamic subtest: bcs0
[313.950255] Dynamic subtest bcs0: SUCCESS (2.048s)
[313.961026] Starting dynamic subtest: vcs0
[315.911562] Dynamic subtest vcs0: SUCCESS (1.958s)
[315.919563] Starting dynamic subtest: vcs1
[317.940701] Dynamic subtest vcs1: SUCCESS (2.028s)
[317.951889] Starting dynamic subtest: vecs0
[319.917632] Dynamic subtest vecs0: SUCCESS (1.975s)
[319.928438] Starting dynamic subtest: ccs0
[321.882427] Dynamic subtest ccs0: SUCCESS (1.963s)
[321.883615] Subtest engines-mixed-process: SUCCESS (11.988s)
[321.981517] [069/131] (698s left) kms_big_fb (4-tiled-32bpp-rotate-180)
[322.088015] Starting subtest: 4-tiled-32bpp-rotate-180
[323.433259] Subtest 4-tiled-32bpp-rotate-180: SUCCESS (1.345s)
[323.606378] [070/131] (696s left) prime_vgem (coherency-gtt)
[323.670243] Starting subtest: coherency-gtt
[323.672206] Subtest coherency-gtt: SKIP (0.000s)
[323.787918] [071/131] (696s left) kms_cursor_crc (cursor-rapid-movement-128x42)
[323.999735] Starting subtest: cursor-rapid-movement-128x42
[324.039513] Subtest cursor-rapid-movement-128x42: SKIP (0.037s)
[324.288233] [072/131] (696s left) gem_exec_balancer (waits)
[324.353457] Starting subtest: waits
[330.841085] Subtest waits: SUCCESS (6.485s)
[331.181453] [073/131] (689s left) sysfs_preempt_timeout (invalid)
[331.232484] Starting subtest: invalid
[331.233410] Starting dynamic subtest: vecs0
[331.234339] Dynamic subtest vecs0: SUCCESS (0.000s)
[331.242600] Starting dynamic subtest: ccs0
[331.243425] Dynamic subtest ccs0: SUCCESS (0.000s)
[331.250452] Starting dynamic subtest: vcs0
[331.251288] Dynamic subtest vcs0: SUCCESS (0.000s)
[331.258364] Starting dynamic subtest: bcs0
[331.259215] Dynamic subtest bcs0: SUCCESS (0.000s)
[331.266218] Starting dynamic subtest: rcs0
[331.267033] Dynamic subtest rcs0: SUCCESS (0.000s)
[331.273995] Starting dynamic subtest: vcs1
[331.274894] Dynamic subtest vcs1: SUCCESS (0.000s)
[331.275746] Subtest invalid: SUCCESS (0.007s)
[331.370831] [074/131] (689s left) gem_spin_batch (resubmit-all)
[331.420341] Starting subtest: resubmit-all
[331.421186] Starting dynamic subtest: rcs0
[331.433289] Dynamic subtest rcs0: SUCCESS (0.014s)
[331.443321] Starting dynamic subtest: bcs0
[331.444136] Dynamic subtest bcs0: SUCCESS (0.003s)
[331.451855] Starting dynamic subtest: vcs0
[331.452712] Dynamic subtest vcs0: SUCCESS (0.002s)
[331.459763] Starting dynamic subtest: vcs1
[331.460580] Dynamic subtest vcs1: SUCCESS (0.004s)
[331.467568] Starting dynamic subtest: vecs0
[331.468370] Dynamic subtest vecs0: SUCCESS (0.003s)
[331.475293] Starting dynamic subtest: ccs0
[331.476093] Dynamic subtest ccs0: SUCCESS (0.003s)
[331.477105] Subtest resubmit-all: SUCCESS (0.034s)
[332.968262] [075/131] (687s left) gem_exec_gttfill (basic)
[333.036758] Starting subtest: basic
[336.248081] Subtest basic: SUCCESS (3.210s)
[336.454207] [076/131] (684s left) kms_big_fb (yf-tiled-16bpp-rotate-270)
[336.590379] Starting subtest: yf-tiled-16bpp-rotate-270
[336.592190] Subtest yf-tiled-16bpp-rotate-270: SKIP (0.000s)
[336.734499] [077/131] (683s left) gem_ctx_persistence (file)
[336.795799] Starting subtest: file
[336.947397] Subtest file: SUCCESS (0.151s)
[337.084620] [078/131] (683s left) gem_fenced_exec_thrash (too-many-fences)
[337.138847] Subtest too-many-fences: SKIP (0.000s)
[337.216031] [079/131] (683s left) api_intel_bb (misplaced-blitter)
[337.271841] Starting subtest: misplaced-blitter
[337.279457] Subtest misplaced-blitter: SUCCESS (0.008s)
[337.427560] [080/131] (683s left) gen7_exec_parse (basic-rejected)
[337.487265] Subtest basic-rejected: SKIP (0.000s)
[337.562298] [081/131] (683s left) gem_pxp (hw-rejects-pxp-context)
[337.684241] Starting subtest: hw-rejects-pxp-context
[337.686541] Subtest hw-rejects-pxp-context: SKIP (0.000s)
[337.826603] [082/131] (683s left) gem_softpin (evict-active-interruptible)
[337.899044] Starting subtest: evict-active-interruptible
[337.906135] Subtest evict-active-interruptible: SUCCESS (0.007s)
[338.059738] [083/131] (682s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-cpu)
[338.145558] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-cpu
[338.147411] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s)
[338.280358] [084/131] (682s left) syncobj_timeline (invalid-signal-zero-handles)
[338.350942] Starting subtest: invalid-signal-zero-handles
[338.351931] Subtest invalid-signal-zero-handles: SUCCESS (0.000s)
[338.465822] [085/131] (682s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt)
[338.562892] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt
[338.564635] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s)
[338.693450] [086/131] (682s left) kms_big_fb (x-tiled-8bpp-rotate-270)
[338.787855] Starting subtest: x-tiled-8bpp-rotate-270
[338.809988] Subtest x-tiled-8bpp-rotate-270: SKIP (0.021s)
[338.933934] [087/131] (682s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0)
[339.027889] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0
[339.029717] Subtest y-tiled-max-hw-stride-64bpp-rotate-0: SKIP (0.000s)
[339.146783] [088/131] (681s left) i915_pm_freq_api (freq-reset)
[339.205377] Starting subtest: freq-reset
[339.206430] Starting dynamic subtest: gt0
[339.396515] Dynamic subtest gt0: SUCCESS (0.192s)
[339.403777] Starting dynamic subtest: gt1
[339.565463] Dynamic subtest gt1: SUCCESS (0.166s)
[339.566775] Subtest freq-reset: SUCCESS (0.361s)
[339.657874] [089/131] (681s left) gem_unfence_active_buffers
[339.825928] [090/131] (681s left) syncobj_timeline (reset-unsignaled)
[339.874117] Starting subtest: reset-unsignaled
[339.875050] Subtest reset-unsignaled: SUCCESS (0.000s)
[339.985314] [091/131] (681s left) gem_render_copy (y-tiled)
[340.038702] Starting subtest: y-tiled
[340.040576] Subtest y-tiled: SKIP (0.000s)
[340.239393] [092/131] (680s left) gem_wait (busy)
[340.306288] Starting subtest: busy
[340.307289] Starting dynamic subtest: all-engines
[341.912089] Dynamic subtest all-engines: SUCCESS (1.603s)
[341.922859] Starting dynamic subtest: rcs0
[342.453630] Dynamic subtest rcs0: SUCCESS (0.541s)
[342.464029] Starting dynamic subtest: bcs0
[342.994553] Dynamic subtest bcs0: SUCCESS (0.540s)
[343.004212] Starting dynamic subtest: vcs0
[344.918541] Dynamic subtest vcs0: SUCCESS (1.923s)
[344.930474] Starting dynamic subtest: vcs1
[346.902419] Dynamic subtest vcs1: SUCCESS (1.981s)
[346.913601] Starting dynamic subtest: vecs0
[348.886675] Dynamic subtest vecs0: SUCCESS (1.982s)
[348.897911] Starting dynamic subtest: ccs0
[349.430177] Dynamic subtest ccs0: SUCCESS (0.542s)
[349.431552] Subtest busy: SUCCESS (9.124s)
[349.655903] [093/131] (671s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-async-flip)
[349.757796] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-async-flip
[349.759723] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-async-flip: SKIP (0.000s)
[349.872739] [094/131] (671s left) i915_drm_fdinfo (busy-check-all)
[349.906500] Starting subtest: busy-check-all
[349.908068] Starting dynamic subtest: rcs0
[349.912261] Dynamic subtest rcs0: SKIP (0.005s)
[349.921070] Starting dynamic subtest: bcs0
[349.922834] Dynamic subtest bcs0: SKIP (0.000s)
[349.930502] Starting dynamic subtest: vcs0
[349.932282] Dynamic subtest vcs0: SKIP (0.000s)
[349.939647] Starting dynamic subtest: vcs1
[349.941460] Dynamic subtest vcs1: SKIP (0.000s)
[349.948639] Starting dynamic subtest: vecs0
[349.950471] Dynamic subtest vecs0: SKIP (0.000s)
[349.957598] Starting dynamic subtest: ccs0
[349.959404] Dynamic subtest ccs0: SKIP (0.000s)
[349.961203] Subtest busy-check-all: SKIP (0.010s)
[350.005801] [095/131] (671s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[350.146234] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[357.019432] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.872s)
[357.176889] [096/131] (664s left) gem_gtt_cpu_tlb
[357.301739] [097/131] (664s left) gem_eio (wait-wedge-10ms)
[357.507385] Starting subtest: wait-wedge-10ms
[358.924796] Subtest wait-wedge-10ms: SUCCESS (1.417s)
[359.109894] [098/131] (662s left) core_setmaster_vs_auth
[359.297056] [099/131] (662s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions-varying-size)
[359.412270] Starting subtest: flip-vs-cursor-atomic-transitions-varying-size
[368.507863] Subtest flip-vs-cursor-atomic-transitions-varying-size: SUCCESS (9.095s)
[369.380412] [100/131] (652s left) kms_flip (2x-absolute-wf_vblank)
[369.473153] Starting subtest: 2x-absolute-wf_vblank
[369.483916] Subtest 2x-absolute-wf_vblank: SKIP (0.011s)
[369.582083] [101/131] (651s left) kms_ccs (ccs-on-another-bo-4-tiled-mtl-mc-ccs)
[369.681476] Starting subtest: ccs-on-another-bo-4-tiled-mtl-mc-ccs
[369.682353] Starting dynamic subtest: pipe-A-eDP-1
[369.704466] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.023s)
[369.713207] Starting dynamic subtest: pipe-B-eDP-1
[369.727962] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.022s)
[369.736516] Starting dynamic subtest: pipe-C-eDP-1
[369.750381] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.021s)
[369.759042] Starting dynamic subtest: pipe-D-eDP-1
[369.773277] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s)
[369.774102] Subtest ccs-on-another-bo-4-tiled-mtl-mc-ccs: SUCCESS (0.093s)
[372.047049] [102/131] (649s left) i915_pm_rps (engine-order)
[372.105919] Starting subtest: engine-order
[378.351647] Subtest engine-order: SUCCESS (6.245s)
[378.522621] [103/131] (643s left) kms_rotation_crc (bad-pixel-format)
[378.611105] Starting subtest: bad-pixel-format
[378.612971] Subtest bad-pixel-format: SKIP (0.001s)
[378.701461] [104/131] (642s left) perf_pmu (module-unload)
[378.784936] Starting subtest: module-unload
[385.526685] Subtest module-unload: FAIL (6.706s)
[385.545617] [FACT perf_pmu (module-unload)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[385.546309] [FACT perf_pmu (module-unload)] deleted: kernel.kmod_is_loaded.i915: true
[385.547937] [105/131] (636s left) gem_render_copy (y-tiled-to-vebox-linear)
[386.996882] Starting subtest: y-tiled-to-vebox-linear
[386.998707] Subtest y-tiled-to-vebox-linear: SKIP (0.000s)
[387.136546] [FACT gem_render_copy (y-tiled-to-vebox-linear)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[387.137766] [FACT gem_render_copy (y-tiled-to-vebox-linear)] new: kernel.kmod_is_loaded.i915: true
[387.139944] [106/131] (634s left) gem_cs_tlb (engines)
[387.201024] Starting subtest: engines
[387.202046] Starting dynamic subtest: rcs0
[389.359490] Dynamic subtest rcs0: SUCCESS (2.155s)
[389.369445] Starting dynamic subtest: bcs0
[391.511969] Dynamic subtest bcs0: SUCCESS (2.151s)
[391.539520] Starting dynamic subtest: vcs0
[393.665564] Dynamic subtest vcs0: SUCCESS (2.152s)
[393.672194] Starting dynamic subtest: vcs1
[395.820050] Dynamic subtest vcs1: SUCCESS (2.153s)
[395.827379] Starting dynamic subtest: vecs0
[397.973299] Dynamic subtest vecs0: SUCCESS (2.153s)
[397.979816] Starting dynamic subtest: ccs0
[400.128475] Dynamic subtest ccs0: SUCCESS (2.154s)
[400.130069] Subtest engines: SUCCESS (12.924s)
[400.278846] [107/131] (621s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-draw-mmap-gtt)
[400.380771] Starting subtest: psr-2p-scndscrn-cur-indfb-draw-mmap-gtt
[400.382619] Subtest psr-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[400.493041] [108/131] (621s left) syncobj_timeline (invalid-multi-wait-unsubmitted-submitted-signaled)
[400.550202] Starting subtest: invalid-multi-wait-unsubmitted-submitted-signaled
[400.551229] Subtest invalid-multi-wait-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[400.687161] [109/131] (621s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-indfb-pgflip-blt)
[400.788015] Starting subtest: fbcpsr-1p-primscrn-indfb-pgflip-blt
[408.022969] Subtest fbcpsr-1p-primscrn-indfb-pgflip-blt: SUCCESS (7.236s)
[408.189696] [110/131] (613s left) kms_rotation_crc (exhaust-fences)
[408.290299] Starting subtest: exhaust-fences
[408.291930] Subtest exhaust-fences: SKIP (0.000s)
[408.383254] [111/131] (613s left) kms_atomic (plane-overlay-legacy)
[408.472257] Starting subtest: plane-overlay-legacy
[408.473051] Starting dynamic subtest: pipe-A-eDP-1
[408.901126] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.428s)
[408.902029] Subtest plane-overlay-legacy: SUCCESS (0.430s)
[409.722984] [112/131] (612s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[409.816413] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[409.817441] Starting dynamic subtest: pipe-A-eDP-1
[410.033493] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.206s)
[410.040455] Starting dynamic subtest: pipe-B-eDP-1
[411.251198] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.217s)
[411.257874] Starting dynamic subtest: pipe-C-eDP-1
[412.455214] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.202s)
[412.461957] Starting dynamic subtest: pipe-D-eDP-1
[413.686831] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.230s)
[413.687683] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (3.861s)
[414.858088] [113/131] (607s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-move)
[414.971104] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-move
[414.972822] Subtest fbcpsr-2p-primscrn-spr-indfb-move: SKIP (0.000s)
[415.111020] [114/131] (606s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode)
[415.518196] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s)
[416.345468] [115/131] (605s left) kms_psr (fbc-psr2-sprite-plane-onoff)
[416.436377] Starting subtest: fbc-psr2-sprite-plane-onoff
[416.437288] Starting dynamic subtest: eDP-1
[416.438953] Dynamic subtest eDP-1: SKIP (0.000s)
[416.440604] Subtest fbc-psr2-sprite-plane-onoff: SKIP (0.001s)
[416.533858] [116/131] (605s left) kms_psr (pr-primary-page-flip)
[416.616349] Starting subtest: pr-primary-page-flip
[416.617995] Subtest pr-primary-page-flip: SKIP (0.000s)
[416.707086] [117/131] (605s left) gem_exec_reloc (basic-wc-gtt)
[416.761486] Subtest basic-wc-gtt: SKIP (0.000s)
[416.821371] [118/131] (605s left) kms_plane_scaling (intel-max-src-size)
[416.920472] Starting subtest: intel-max-src-size
[416.923261] Subtest intel-max-src-size: SKIP (0.000s)
[417.038918] [119/131] (605s left) gem_eio (in-flight-1us)
[417.257146] Starting subtest: in-flight-1us
[425.923087] Subtest in-flight-1us: SUCCESS (8.666s)
[426.134719] [120/131] (595s left) kms_psr (fbc-pr-dpms)
[426.242609] Starting subtest: fbc-pr-dpms
[426.244448] Subtest fbc-pr-dpms: SKIP (0.000s)
[426.333555] [121/131] (595s left) gem_exec_schedule (noreorder)
[426.519333] Starting subtest: noreorder
[426.520500] Starting dynamic subtest: rcs0
[426.599729] Dynamic subtest rcs0: SUCCESS (0.081s)
[426.610502] Starting dynamic subtest: vcs0
[427.906122] Dynamic subtest vcs0: SUCCESS (1.302s)
[427.916142] Starting dynamic subtest: vcs1
[429.951324] Dynamic subtest vcs1: SUCCESS (2.042s)
[429.961829] Starting dynamic subtest: vecs0
[431.924034] Dynamic subtest vecs0: SUCCESS (1.970s)
[431.940989] Starting dynamic subtest: ccs0
[432.034981] Dynamic subtest ccs0: SUCCESS (0.110s)
[432.036409] Subtest noreorder: SUCCESS (5.518s)
[432.243294] [122/131] (589s left) gem_ccs (block-multicopy-inplace)
[432.303401] Starting subtest: block-multicopy-inplace
[432.305198] Subtest block-multicopy-inplace: SKIP (0.000s)
[432.439464] [123/131] (589s left) gem_exec_whisper (basic-queues-priority)
[432.500791] Starting subtest: basic-queues-priority
[456.899848] Subtest basic-queues-priority: SUCCESS (24.395s)
[457.097996] [124/131] (565s left) sw_sync (sync_merge)
[457.135194] Starting subtest: sync_merge
[457.136032] Subtest sync_merge: SUCCESS (0.000s)
[457.213570] [125/131] (565s left) kms_legacy_colorkey (invalid-plane)
[457.294955] Starting subtest: invalid-plane
[457.295835] Starting dynamic subtest: zero-id
[457.296669] Dynamic subtest zero-id: SUCCESS (0.000s)
[457.303970] Starting dynamic subtest: outrange-id
[457.304821] Dynamic subtest outrange-id: SUCCESS (0.000s)
[457.305655] Subtest invalid-plane: SUCCESS (0.001s)
[457.389572] [126/131] (564s left) kms_dirtyfb (default-dirtyfb-ioctl)
[457.484430] Starting subtest: default-dirtyfb-ioctl
[457.485277] Starting dynamic subtest: A-eDP-1
[459.050259] Dynamic subtest A-eDP-1: SUCCESS (1.565s)
[459.051169] Subtest default-dirtyfb-ioctl: SUCCESS (1.566s)
[459.919779] [127/131] (562s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf)
[464.994944] Starting subtest: fbc-pr-cursor-plane-move-continuous-sf
[464.998063] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.002s)
[465.848467] [128/131] (556s left) prime_mmap (test_map_unmap)
[465.901084] Starting subtest: test_map_unmap
[465.902054] Starting dynamic subtest: test_map_unmap-smem
[465.931121] Dynamic subtest test_map_unmap-smem: SUCCESS (0.031s)
[465.932190] Subtest test_map_unmap: SUCCESS (0.032s)
[466.057811] [129/131] (556s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted)
[466.116899] Starting subtest: etime-multi-wait-all-for-submit-submitted
[466.219055] Subtest etime-multi-wait-all-for-submit-submitted: SUCCESS (0.101s)
[466.311907] [130/131] (556s left) gem_userptr_blits (process-exit)
[466.383660] Starting subtest: process-exit
[466.389749] Subtest process-exit: SUCCESS (0.008s)
[466.553457] [131/131] (555s left) perf_pmu (busy-idle-check-all)
[466.584410] Starting subtest: busy-idle-check-all
[466.585401] Starting dynamic subtest: rcs0
[467.258523] Dynamic subtest rcs0: SUCCESS (0.674s)
[467.269890] Starting dynamic subtest: bcs0
[467.911543] Dynamic subtest bcs0: SUCCESS (0.651s)
[467.922610] Starting dynamic subtest: vcs0
[469.912345] Dynamic subtest vcs0: SUCCESS (1.996s)
[469.923186] Starting dynamic subtest: vcs1
[471.895580] Dynamic subtest vcs1: SUCCESS (1.981s)
[471.908233] Starting dynamic subtest: vecs0
[473.944368] Dynamic subtest vecs0: SUCCESS (2.046s)
[473.954660] Starting dynamic subtest: ccs0
[474.599069] Dynamic subtest ccs0: SUCCESS (0.654s)
[474.602154] Subtest busy-idle-check-all: SUCCESS (8.016s)
[474.741691] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: prime_vgem
results: parsing output: 2/ for test: kms_frontbuffer_tracking
results: parsing output: 3/ for test: i915_suspend
results: parsing output: 4/ for test: kms_atomic_transition
results: parsing output: 5/ for test: syncobj_wait
results: parsing output: 6/ for test: kms_color
results: parsing output: 7/ for test: syncobj_timeline
results: parsing output: 8/ for test: kms_frontbuffer_tracking
results: parsing output: 9/ for test: kms_joiner
results: parsing output: 10/ for test: kms_psr
results: parsing output: 11/ for test: vgem_basic
results: parsing output: 12/ for test: kms_frontbuffer_tracking
results: parsing output: 13/ for test: kms_ccs
results: parsing output: 14/ for test: kms_selftest
results: parsing output: 15/ for test: kms_lease
results: parsing output: 16/ for test: gem_exec_fence
results: parsing output: 17/ for test: kms_frontbuffer_tracking
results: parsing output: 18/ for test: kms_feature_discovery
results: parsing output: 19/ for test: perf
results: parsing output: 20/ for test: api_intel_allocator
results: parsing output: 21/ for test: kms_frontbuffer_tracking
results: parsing output: 22/ for test: kms_cursor_edge_walk
results: parsing output: 23/ for test: gem_exec_whisper
results: parsing output: 24/ for test: kms_sequence
results: parsing output: 25/ for test: kms_flip_scaled_crc
results: parsing output: 26/ for test: kms_draw_crc
results: parsing output: 27/ for test: kms_plane_scaling
results: parsing output: 28/ for test: kms_rotation_crc
results: parsing output: 29/ for test: kms_frontbuffer_tracking
results: parsing output: 30/ for test: gem_tiled_partial_pwrite_pread
results: parsing output: 31/ for test: gem_mmap_gtt
results: parsing output: 32/ for test: gem_mmap_offset
results: parsing output: 33/ for test: prime_self_import
results: parsing output: 34/ for test: kms_frontbuffer_tracking
results: parsing output: 35/ for test: syncobj_wait
results: parsing output: 36/ for test: kms_psr2_sf
results: parsing output: 37/ for test: kms_cursor_crc
results: parsing output: 38/ for test: kms_vrr
results: parsing output: 39/ for test: kms_chamelium_color
results: parsing output: 40/ for test: gem_mmap_wc
results: parsing output: 41/ for test: kms_cursor_legacy
results: parsing output: 42/ for test: gem_exec_schedule
results: parsing output: 43/ for test: syncobj_wait
results: parsing output: 44/ for test: gem_exec_schedule
results: parsing output: 45/ for test: gem_exec_balancer
results: parsing output: 46/ for test: kms_frontbuffer_tracking
results: parsing output: 47/ for test: gem_partial_pwrite_pread
results: parsing output: 48/ for test: kms_pm_rpm
results: parsing output: 49/ for test: kms_plane_multiple
results: parsing output: 50/ for test: kms_ccs
results: parsing output: 51/ for test: kms_big_fb
results: parsing output: 52/ for test: kms_flip_scaled_crc
results: parsing output: 53/ for test: kms_frontbuffer_tracking
results: parsing output: 54/ for test: gem_exec_flush
results: parsing output: 55/ for test: kms_big_fb
results: parsing output: 56/ for test: kms_flip
results: parsing output: 57/ for test: core_setmaster
results: parsing output: 58/ for test: gem_partial_pwrite_pread
results: parsing output: 59/ for test: kms_pm_rpm
results: parsing output: 60/ for test: syncobj_wait
results: parsing output: 61/ for test: kms_chamelium_edid
results: parsing output: 62/ for test: gem_mmap_gtt
results: parsing output: 63/ for test: kms_async_flips
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: gem_exec_alignment
results: parsing output: 66/ for test: kms_psr
results: parsing output: 67/ for test: gem_ctx_persistence
results: parsing output: 68/ for test: kms_big_fb
results: parsing output: 69/ for test: prime_vgem
results: parsing output: 70/ for test: kms_cursor_crc
results: parsing output: 71/ for test: gem_exec_balancer
results: parsing output: 72/ for test: sysfs_preempt_timeout
results: parsing output: 73/ for test: gem_spin_batch
results: parsing output: 74/ for test: gem_exec_gttfill
results: parsing output: 75/ for test: kms_big_fb
results: parsing output: 76/ for test: gem_ctx_persistence
results: parsing output: 77/ for test: gem_fenced_exec_thrash
results: parsing output: 78/ for test: api_intel_bb
results: parsing output: 79/ for test: gen7_exec_parse
results: parsing output: 80/ for test: gem_pxp
results: parsing output: 81/ for test: gem_softpin
results: parsing output: 82/ for test: kms_frontbuffer_tracking
results: parsing output: 83/ for test: syncobj_timeline
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: kms_big_fb
results: parsing output: 87/ for test: i915_pm_freq_api
results: parsing output: 88/ for test: gem_unfence_active_buffers
results: parsing output: 89/ for test: syncobj_timeline
results: parsing output: 90/ for test: gem_render_copy
results: parsing output: 91/ for test: gem_wait
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: i915_drm_fdinfo
results: parsing output: 94/ for test: kms_frontbuffer_tracking
results: parsing output: 95/ for test: gem_gtt_cpu_tlb
results: parsing output: 96/ for test: gem_eio
results: parsing output: 97/ for test: core_setmaster_vs_auth
results: parsing output: 98/ for test: kms_cursor_legacy
results: parsing output: 99/ for test: kms_flip
results: parsing output: 100/ for test: kms_ccs
results: parsing output: 101/ for test: i915_pm_rps
results: parsing output: 102/ for test: kms_rotation_crc
results: parsing output: 103/ for test: perf_pmu
results: parsing output: 104/ for test: gem_render_copy
results: parsing output: 105/ for test: gem_cs_tlb
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: syncobj_timeline
results: parsing output: 108/ for test: kms_frontbuffer_tracking
results: parsing output: 109/ for test: kms_rotation_crc
results: parsing output: 110/ for test: kms_atomic
results: parsing output: 111/ for test: kms_ccs
results: parsing output: 112/ for test: kms_frontbuffer_tracking
results: parsing output: 113/ for test: kms_chamelium_hpd
results: parsing output: 114/ for test: kms_psr
results: parsing output: 115/ for test: kms_psr
results: parsing output: 116/ for test: gem_exec_reloc
results: parsing output: 117/ for test: kms_plane_scaling
results: parsing output: 118/ for test: gem_eio
results: parsing output: 119/ for test: kms_psr
results: parsing output: 120/ for test: gem_exec_schedule
results: parsing output: 121/ for test: gem_ccs
results: parsing output: 122/ for test: gem_exec_whisper
results: parsing output: 123/ for test: sw_sync
results: parsing output: 124/ for test: kms_legacy_colorkey
results: parsing output: 125/ for test: kms_dirtyfb
results: parsing output: 126/ for test: kms_psr2_sf
results: parsing output: 127/ for test: prime_mmap
results: parsing output: 128/ for test: syncobj_timeline
results: parsing output: 129/ for test: gem_userptr_blits
results: parsing output: 130/ for test: perf_pmu
Done.