[58.075358] Initializing watchdogs
[58.075416]   /dev/watchdog0
[58.089935] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:7d55 Intel Meteorlake (Gen12) Meteor Lake-P [Intel Arc Graphics]
[58.094355] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[58.096569] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[58.099873] [001/134] (960s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-shrfb-draw-blt)
[58.232992] Starting subtest: fbcpsr-1p-primscrn-pri-shrfb-draw-blt
[65.683918] Subtest fbcpsr-1p-primscrn-pri-shrfb-draw-blt: SUCCESS (7.445s)
[65.841125] [002/134] (952s left) gem_exec_params (secure-non-master)
[65.912283] Starting subtest: secure-non-master
[65.916841] Subtest secure-non-master: SKIP (0.000s)
[66.031950] [003/134] (952s left) tools_test (tools_test)
[66.081503] Starting subtest: tools_test
[66.131680] Subtest tools_test: SUCCESS (0.064s)
[66.202693] [004/134] (952s left) kms_selftest (drm_cmdline_parser)
[66.236705] Starting subtest: drm_cmdline_parser
[66.246736] Starting dynamic subtest: drm_test_cmdline_force_d_only
[66.374085] Dynamic subtest drm_test_cmdline_force_d_only: SUCCESS (0.126s)
[66.382584] Starting dynamic subtest: drm_test_cmdline_force_D_only_dvi
[66.507161] Dynamic subtest drm_test_cmdline_force_D_only_dvi: SUCCESS (0.132s)
[66.516114] Starting dynamic subtest: drm_test_cmdline_force_D_only_hdmi
[66.643121] Dynamic subtest drm_test_cmdline_force_D_only_hdmi: SUCCESS (0.135s)
[66.651535] Starting dynamic subtest: drm_test_cmdline_force_D_only_not_digital
[66.909355] Dynamic subtest drm_test_cmdline_force_D_only_not_digital: SUCCESS (0.266s)
[66.918902] Starting dynamic subtest: drm_test_cmdline_force_e_only
[67.504551] Dynamic subtest drm_test_cmdline_force_e_only: SUCCESS (0.591s)
[67.513957] Starting dynamic subtest: drm_test_cmdline_res
[67.862719] Dynamic subtest drm_test_cmdline_res: SUCCESS (0.356s)
[67.871713] Starting dynamic subtest: drm_test_cmdline_res_vesa
[68.212988] Dynamic subtest drm_test_cmdline_res_vesa: SUCCESS (0.350s)
[68.238948] Starting dynamic subtest: drm_test_cmdline_res_vesa_rblank
[68.350080] Dynamic subtest drm_test_cmdline_res_vesa_rblank: SUCCESS (0.137s)
[68.356903] Starting dynamic subtest: drm_test_cmdline_res_rblank
[68.480393] Dynamic subtest drm_test_cmdline_res_rblank: SUCCESS (0.129s)
[68.489273] Starting dynamic subtest: drm_test_cmdline_res_bpp
[68.622833] Dynamic subtest drm_test_cmdline_res_bpp: SUCCESS (0.142s)
[68.630976] Starting dynamic subtest: drm_test_cmdline_res_refresh
[68.702226] Dynamic subtest drm_test_cmdline_res_refresh: SUCCESS (0.080s)
[68.710791] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh
[68.819876] Dynamic subtest drm_test_cmdline_res_bpp_refresh: SUCCESS (0.117s)
[68.828247] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced
[68.952341] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced: SUCCESS (0.132s)
[68.960815] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_margins
[69.087138] Dynamic subtest drm_test_cmdline_res_bpp_refresh_margins: SUCCESS (0.134s)
[69.093831] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_off
[69.190184] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_off: SUCCESS (0.103s)
[69.198553] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on
[69.304064] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on: SUCCESS (0.113s)
[69.310470] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_analog
[69.386132] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_analog: SUCCESS (0.082s)
[69.394859] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_force_on_digital
[69.498440] Dynamic subtest drm_test_cmdline_res_bpp_refresh_force_on_digital: SUCCESS (0.112s)
[69.505751] Starting dynamic subtest: drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on
[69.587371] Dynamic subtest drm_test_cmdline_res_bpp_refresh_interlaced_margins_force_on: SUCCESS (0.088s)
[69.595361] Starting dynamic subtest: drm_test_cmdline_res_margins_force_on
[69.697169] Dynamic subtest drm_test_cmdline_res_margins_force_on: SUCCESS (0.110s)
[69.705972] Starting dynamic subtest: drm_test_cmdline_res_vesa_margins
[69.819217] Dynamic subtest drm_test_cmdline_res_vesa_margins: SUCCESS (0.121s)
[69.828036] Starting dynamic subtest: drm_test_cmdline_name
[69.941371] Dynamic subtest drm_test_cmdline_name: SUCCESS (0.121s)
[69.949925] Starting dynamic subtest: drm_test_cmdline_name_bpp
[70.091212] Dynamic subtest drm_test_cmdline_name_bpp: SUCCESS (0.150s)
[70.099922] Starting dynamic subtest: drm_test_cmdline_name_option
[70.220197] Dynamic subtest drm_test_cmdline_name_option: SUCCESS (0.128s)
[70.228688] Starting dynamic subtest: drm_test_cmdline_name_bpp_option
[70.353411] Dynamic subtest drm_test_cmdline_name_bpp_option: SUCCESS (0.133s)
[70.360282] Starting dynamic subtest: drm_test_cmdline_rotate_0
[70.483204] Dynamic subtest drm_test_cmdline_rotate_0: SUCCESS (0.129s)
[70.490249] Starting dynamic subtest: drm_test_cmdline_rotate_90
[70.615872] Dynamic subtest drm_test_cmdline_rotate_90: SUCCESS (0.133s)
[70.622792] Starting dynamic subtest: drm_test_cmdline_rotate_180
[70.753064] Dynamic subtest drm_test_cmdline_rotate_180: SUCCESS (0.136s)
[70.761383] Starting dynamic subtest: drm_test_cmdline_rotate_270
[70.890073] Dynamic subtest drm_test_cmdline_rotate_270: SUCCESS (0.137s)
[70.897042] Starting dynamic subtest: drm_test_cmdline_hmirror
[71.017296] Dynamic subtest drm_test_cmdline_hmirror: SUCCESS (0.127s)
[71.026060] Starting dynamic subtest: drm_test_cmdline_vmirror
[71.143673] Dynamic subtest drm_test_cmdline_vmirror: SUCCESS (0.125s)
[71.151853] Starting dynamic subtest: drm_test_cmdline_margin_options
[71.228301] Dynamic subtest drm_test_cmdline_margin_options: SUCCESS (0.085s)
[71.236633] Starting dynamic subtest: drm_test_cmdline_multiple_options
[71.359121] Dynamic subtest drm_test_cmdline_multiple_options: SUCCESS (0.130s)
[71.367766] Starting dynamic subtest: drm_test_cmdline_bpp_extra_and_option
[71.497150] Dynamic subtest drm_test_cmdline_bpp_extra_and_option: SUCCESS (0.138s)
[71.504643] Starting dynamic subtest: drm_test_cmdline_extra_and_option
[71.624269] Dynamic subtest drm_test_cmdline_extra_and_option: SUCCESS (0.126s)
[71.631598] Starting dynamic subtest: drm_test_cmdline_freestanding_options
[71.756309] Dynamic subtest drm_test_cmdline_freestanding_options: SUCCESS (0.132s)
[71.763803] Starting dynamic subtest: drm_test_cmdline_freestanding_force_e_and_options
[71.881170] Dynamic subtest drm_test_cmdline_freestanding_force_e_and_options: SUCCESS (0.125s)
[71.887547] Starting dynamic subtest: drm_test_cmdline_panel_orientation
[72.011168] Dynamic subtest drm_test_cmdline_panel_orientation: SUCCESS (0.129s)
[72.018676] Starting dynamic subtest: drm_test_cmdline_invalid
[72.160793] Dynamic subtest drm_test_cmdline_invalid: SUCCESS (0.149s)
[72.169238] Starting dynamic subtest: drm_test_cmdline_tv_options
[72.305996] Dynamic subtest drm_test_cmdline_tv_options: SUCCESS (0.144s)
[72.307929] Subtest drm_cmdline_parser: SUCCESS (6.065s)
[72.519451] [005/134] (946s left) gem_exec_suspend (basic-s0)
[72.585390] Starting subtest: basic-S0
[72.587870] Starting dynamic subtest: smem
[93.052177] Dynamic subtest smem: SUCCESS (6.345s)
[93.055170] Subtest basic-S0: SUCCESS (6.346s)
[102.262356] [006/134] (925s left) kms_content_protection (uevent)
[102.433904] Starting subtest: uevent
[102.438336] Subtest uevent: SKIP (0.000s)
[102.603939] [007/134] (925s left) gem_eio (in-flight-suspend)
[102.855730] Starting subtest: in-flight-suspend
[145.916832] Subtest in-flight-suspend: SUCCESS (13.591s)
[151.048696] [FACT gem_eio (in-flight-suspend)] new: kernel.kmod_is_loaded.vgem: true
[151.105404] [008/134] (881s left) gem_spin_batch (resubmit-all)
[151.188418] Starting subtest: resubmit-all
[151.190766] Starting dynamic subtest: rcs0
[151.206556] Dynamic subtest rcs0: SUCCESS (0.018s)
[151.218486] Starting dynamic subtest: bcs0
[151.220892] Dynamic subtest bcs0: SUCCESS (0.003s)
[151.229261] Starting dynamic subtest: vcs0
[151.231177] Dynamic subtest vcs0: SUCCESS (0.003s)
[151.239240] Starting dynamic subtest: vcs1
[151.241187] Dynamic subtest vcs1: SUCCESS (0.003s)
[151.253293] Starting dynamic subtest: vecs0
[151.255538] Dynamic subtest vecs0: SUCCESS (0.002s)
[151.263672] Starting dynamic subtest: ccs0
[151.265876] Dynamic subtest ccs0: SUCCESS (0.003s)
[151.268104] Subtest resubmit-all: SUCCESS (0.033s)
[152.476169] [009/134] (880s left) kms_psr (psr-cursor-plane-onoff)
[152.578043] Starting subtest: psr-cursor-plane-onoff
[152.580262] Starting dynamic subtest: eDP-1
[154.348747] Dynamic subtest eDP-1: SUCCESS (1.770s)
[154.351198] Subtest psr-cursor-plane-onoff: SUCCESS (1.771s)
[155.231190] [010/134] (877s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-render)
[155.325842] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-render
[162.273917] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-render: SUCCESS (6.942s)
[162.436637] [011/134] (870s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[162.539404] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[162.543688] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[162.660659] [012/134] (870s left) kms_psr2_sf (fbc-psr2-cursor-plane-update-sf)
[167.759530] Starting subtest: fbc-psr2-cursor-plane-update-sf
[167.761419] Starting dynamic subtest: pipe-A-eDP-1
[168.533816] Dynamic subtest pipe-A-eDP-1: SKIP (0.771s)
[168.542340] Starting dynamic subtest: pipe-B-eDP-1
[169.666372] Dynamic subtest pipe-B-eDP-1: SKIP (1.131s)
[169.670984] Subtest fbc-psr2-cursor-plane-update-sf: SKIP (1.905s)
[170.888085] [013/134] (862s left) kms_psr2_sf (psr2-overlay-primary-update-sf-dmg-area)
[176.043083] Starting subtest: psr2-overlay-primary-update-sf-dmg-area
[176.045423] Starting dynamic subtest: pipe-A-eDP-1
[182.666184] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.615s)
[182.676612] Starting dynamic subtest: pipe-B-eDP-1
[189.248164] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.573s)
[189.250386] Subtest psr2-overlay-primary-update-sf-dmg-area: SUCCESS (13.192s)
[190.138092] [014/134] (843s left) kms_pm_rpm (basic-pci-d3-state)
[191.699435] Starting subtest: basic-pci-d3-state
[193.169726] Subtest basic-pci-d3-state: SUCCESS (1.471s)
[193.307281] [015/134] (839s left) gem_ctx_exec (basic-norecovery)
[193.375867] Starting subtest: basic-norecovery
[193.732549] Subtest basic-norecovery: SUCCESS (0.358s)
[193.899049] [016/134] (839s left) gem_pxp (protected-encrypted-src-copy-not-readible)
[194.027649] Starting subtest: protected-encrypted-src-copy-not-readible
[194.174327] Subtest protected-encrypted-src-copy-not-readible: SUCCESS (0.147s)
[194.361739] [017/134] (838s left) kms_flip (absolute-wf_vblank)
[194.458734] Starting subtest: absolute-wf_vblank
[194.461121] Starting dynamic subtest: A-eDP1
[203.232124] Dynamic subtest A-eDP1: SUCCESS (8.728s)
[203.243895] Starting dynamic subtest: B-eDP1
[211.961214] Dynamic subtest B-eDP1: SUCCESS (8.727s)
[211.971809] Starting dynamic subtest: C-eDP1
[220.715360] Dynamic subtest C-eDP1: SUCCESS (8.732s)
[220.724652] Starting dynamic subtest: D-eDP1
[229.453055] Dynamic subtest D-eDP1: SUCCESS (8.725s)
[229.456053] Subtest absolute-wf_vblank: SUCCESS (34.921s)
[230.630045] [018/134] (802s left) prime_mmap (test_refcounting)
[230.682101] Starting subtest: test_refcounting
[230.684063] Starting dynamic subtest: test_refcounting-smem
[230.713236] Dynamic subtest test_refcounting-smem: SUCCESS (0.033s)
[230.715729] Subtest test_refcounting: SUCCESS (0.033s)
[230.855363] [019/134] (802s left) gem_exec_schedule (pi-common)
[231.027626] Starting subtest: pi-common
[231.029845] Starting dynamic subtest: rcs0
[231.054958] Dynamic subtest rcs0: SUCCESS (0.031s)
[231.064420] Starting dynamic subtest: bcs0
[231.088095] Dynamic subtest bcs0: SUCCESS (0.033s)
[231.100374] Starting dynamic subtest: vcs0
[231.118344] Dynamic subtest vcs0: SUCCESS (0.030s)
[231.131931] Starting dynamic subtest: vcs1
[231.146312] Dynamic subtest vcs1: SUCCESS (0.027s)
[231.160015] Starting dynamic subtest: vecs0
[231.172041] Dynamic subtest vecs0: SUCCESS (0.025s)
[231.185845] Starting dynamic subtest: ccs0
[231.198078] Dynamic subtest ccs0: SUCCESS (0.025s)
[231.200430] Subtest pi-common: SUCCESS (0.175s)
[231.462360] [020/134] (801s left) perf (unprivileged-single-ctx-counters)
[231.597830] Starting subtest: unprivileged-single-ctx-counters
[231.602285] Subtest unprivileged-single-ctx-counters: SKIP (0.000s)
[231.644543] [021/134] (801s left) api_intel_allocator (fork-simple-stress-signal)
[231.704233] Starting subtest: fork-simple-stress-signal
[237.165791] Subtest fork-simple-stress-signal: SUCCESS (5.404s)
[237.262203] [022/134] (796s left) kms_flip (flip-vs-panning-vs-hang)
[237.378295] Starting subtest: flip-vs-panning-vs-hang
[237.380308] Starting dynamic subtest: A-eDP1
[288.917568] Dynamic subtest A-eDP1: SUCCESS (51.508s)
[288.928459] Starting dynamic subtest: D-eDP1
[338.966520] Dynamic subtest D-eDP1: SUCCESS (50.019s)
[338.968623] Subtest flip-vs-panning-vs-hang: SUCCESS (101.531s)
[340.176921] [023/134] (693s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-exceed-sf)
[345.157213] Starting subtest: psr2-overlay-plane-move-continuous-exceed-sf
[345.159562] Starting dynamic subtest: pipe-A-eDP-1
[357.171243] Dynamic subtest pipe-A-eDP-1: SUCCESS (12.006s)
[357.181332] Starting dynamic subtest: pipe-B-eDP-1
[369.220506] Dynamic subtest pipe-B-eDP-1: SUCCESS (12.042s)
[369.222818] Subtest psr2-overlay-plane-move-continuous-exceed-sf: SUCCESS (24.053s)
[370.080603] [024/134] (663s left) syncobj_wait (invalid-multi-wait-unsubmitted-submitted)
[370.150472] Starting subtest: invalid-multi-wait-unsubmitted-submitted
[370.152808] Subtest invalid-multi-wait-unsubmitted-submitted: SUCCESS (0.000s)
[370.275149] [025/134] (663s left) gem_exec_create (basic)
[370.331378] Starting subtest: basic
[370.333666] Starting dynamic subtest: smem
[372.454483] Dynamic subtest smem: SUCCESS (2.122s)
[372.456969] Subtest basic: SUCCESS (2.123s)
[372.654031] [026/134] (661s left) gem_ctx_persistence (smoketest)
[372.713331] Starting subtest: smoketest
[413.470849] Subtest smoketest: SUCCESS (40.709s)
[413.538167] [027/134] (620s left) gem_ctx_bad_destroy (invalid-pad)
[413.598251] Starting subtest: invalid-pad
[413.600514] Subtest invalid-pad: SUCCESS (0.000s)
[413.687193] [028/134] (620s left) kms_cursor_legacy (cursora-vs-flipb-legacy)
[413.810292] Starting subtest: cursorA-vs-flipB-legacy
[413.814560] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s)
[413.964848] [029/134] (619s left) kms_atomic_interruptible (legacy-setmode)
[414.070751] Starting subtest: legacy-setmode
[414.072872] Starting dynamic subtest: pipe-A-eDP-1
[420.990801] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.915s)
[420.993146] Subtest legacy-setmode: SUCCESS (6.916s)
[421.876532] [030/134] (611s left) kms_cdclk (mode-transition)
[421.985617] Starting subtest: mode-transition
[421.987867] Starting dynamic subtest: pipe-A-eDP-1
[422.278532] Dynamic subtest pipe-A-eDP-1: SKIP (0.292s)
[422.288226] Starting dynamic subtest: pipe-B-eDP-1
[422.292817] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[422.301852] Starting dynamic subtest: pipe-C-eDP-1
[422.306561] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[422.316140] Starting dynamic subtest: pipe-D-eDP-1
[422.320197] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[422.324242] Subtest mode-transition: SKIP (0.300s)
[423.097411] [031/134] (610s left) kms_big_fb (linear-8bpp-rotate-270)
[423.212170] Starting subtest: linear-8bpp-rotate-270
[423.233839] Subtest linear-8bpp-rotate-270: SKIP (0.021s)
[423.361885] [032/134] (610s left) perf_pmu (faulting-read)
[423.401290] Starting subtest: faulting-read
[423.403491] Starting dynamic subtest: wb
[423.405745] Dynamic subtest wb: SUCCESS (0.002s)
[423.415528] Starting dynamic subtest: wc
[423.417408] Dynamic subtest wc: SUCCESS (0.000s)
[423.426322] Starting dynamic subtest: uc
[423.428494] Dynamic subtest uc: SUCCESS (0.000s)
[423.430663] Subtest faulting-read: SUCCESS (0.006s)
[423.536137] [033/134] (610s left) kms_psr (fbc-psr2-primary-render)
[423.631758] Starting subtest: fbc-psr2-primary-render
[423.633987] Starting dynamic subtest: eDP-1
[423.638259] Dynamic subtest eDP-1: SKIP (0.000s)
[423.642311] Subtest fbc-psr2-primary-render: SKIP (0.001s)
[423.720422] [034/134] (610s left) gem_exec_schedule (deep)
[423.897232] Starting subtest: deep
[423.899715] Starting dynamic subtest: rcs0
[429.071791] Dynamic subtest rcs0: SKIP (5.170s)
[429.083973] Starting dynamic subtest: vcs0
[439.845700] Dynamic subtest vcs0: SUCCESS (10.769s)
[439.858909] Starting dynamic subtest: vcs1
[451.453897] Dynamic subtest vcs1: SUCCESS (11.601s)
[451.467629] Starting dynamic subtest: vecs0
[462.822401] Dynamic subtest vecs0: SUCCESS (11.362s)
[462.835531] Starting dynamic subtest: ccs0
[476.664553] Dynamic subtest ccs0: SUCCESS (13.833s)
[476.668227] Subtest deep: SUCCESS (52.741s)
[478.246193] [035/134] (555s left) kms_selftest (drm_plane_helper)
[478.280852] Starting subtest: drm_plane_helper
[478.329226] Starting dynamic subtest: drm_test_check_plane_state
[478.472649] Dynamic subtest drm_test_check_plane_state: SUCCESS (0.142s)
[478.482307] Starting dynamic subtest: drm_test_check_invalid_plane_state
[478.629669] Dynamic subtest drm_test_check_invalid_plane_state: SUCCESS (0.157s)
[478.631667] Subtest drm_plane_helper: SUCCESS (0.348s)
[478.861098] [036/134] (555s left) syncobj_basic (bad-pad-fd-to-handle)
[478.926055] Starting subtest: bad-pad-fd-to-handle
[478.928340] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s)
[479.071352] [037/134] (555s left) gem_lmem_swapping (parallel-random-engines)
[479.107433] Subtest parallel-random-engines: SKIP (0.000s)
[479.153698] [038/134] (555s left) kms_mmap_write_crc (main)
[479.246156] Starting subtest: main
[479.248437] Starting dynamic subtest: pipe-A-eDP-1
[482.937081] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.684s)
[482.939476] Subtest main: SUCCESS (3.686s)
[483.758782] [039/134] (550s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-pwrite)
[483.873777] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-pwrite
[490.780062] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (6.907s)
[490.923834] [040/134] (543s left) kms_addfb_basic (addfb25-yf-tiled-legacy)
[491.020916] Starting subtest: addfb25-yf-tiled-legacy
[491.022762] Subtest addfb25-yf-tiled-legacy: SUCCESS (0.000s)
[491.123267] [041/134] (543s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc)
[491.221374] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc
[491.225377] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s)
[491.370375] [042/134] (543s left) gem_ctx_exec (basic)
[491.437501] Starting subtest: basic
[491.442355] Subtest basic: SUCCESS (0.006s)
[491.608276] [043/134] (542s left) kms_atomic (test-only)
[491.695885] Starting subtest: test-only
[491.749419] Starting dynamic subtest: pipe-A-eDP-1
[494.330320] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.580s)
[494.332605] Subtest test-only: SUCCESS (2.632s)
[495.213809] [044/134] (539s left) gem_set_tiling_vs_gtt
[495.344192] [045/134] (539s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[495.447931] Starting subtest: yf-tiled-16bpp-rotate-0
[495.452463] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[495.580273] [046/134] (539s left) kms_getfb (getfb2-handle-zero)
[495.685058] Starting subtest: getfb2-handle-zero
[495.687310] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[495.813037] [047/134] (538s left) syncobj_timeline (single-wait-all-for-submit-available-submitted)
[495.873878] Starting subtest: single-wait-all-for-submit-available-submitted
[495.876333] Subtest single-wait-all-for-submit-available-submitted: SUCCESS (0.000s)
[495.981256] [048/134] (538s left) gem_exec_reloc (basic-wc-cpu)
[496.046045] Subtest basic-wc-cpu: SKIP (0.000s)
[496.143042] [049/134] (538s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-onoff)
[496.236482] Starting subtest: psr-1p-primscrn-spr-indfb-onoff
[503.322249] Subtest psr-1p-primscrn-spr-indfb-onoff: SUCCESS (7.081s)
[503.478079] [050/134] (531s left) gem_basic (multigpu-create-close)
[503.536054] Starting subtest: multigpu-create-close
[503.540085] Subtest multigpu-create-close: SKIP (0.000s)
[503.660914] [051/134] (531s left) kms_frontbuffer_tracking (fbc-suspend)
[503.756084] Starting subtest: fbc-suspend
[542.085564] Subtest fbc-suspend: SUCCESS (8.350s)
[549.363380] [052/134] (491s left) gem_wait (wait)
[549.443249] Starting subtest: wait
[549.445636] Starting dynamic subtest: all-engines
[551.424916] Dynamic subtest all-engines: SUCCESS (1.964s)
[551.438509] Starting dynamic subtest: rcs0
[552.472164] Dynamic subtest rcs0: SUCCESS (1.038s)
[552.484223] Starting dynamic subtest: bcs0
[553.520284] Dynamic subtest bcs0: SUCCESS (1.039s)
[553.532595] Starting dynamic subtest: vcs0
[554.566238] Dynamic subtest vcs0: SUCCESS (1.038s)
[554.578499] Starting dynamic subtest: vcs1
[555.610940] Dynamic subtest vcs1: SUCCESS (1.038s)
[555.622461] Starting dynamic subtest: vecs0
[556.655403] Dynamic subtest vecs0: SUCCESS (1.037s)
[556.668313] Starting dynamic subtest: ccs0
[557.701916] Dynamic subtest ccs0: SUCCESS (1.041s)
[557.704748] Subtest wait: SUCCESS (8.210s)
[557.916313] [053/134] (483s left) gem_exec_reloc (basic-wc-active)
[557.981123] Subtest basic-wc-active: SKIP (0.000s)
[558.046251] [054/134] (482s left) kms_pm_rpm (pc8-residency)
[559.572880] Starting subtest: pc8-residency
[559.578450] Subtest pc8-residency: SKIP (0.000s)
[560.366481] [055/134] (480s left) kms_dsc (dsc-with-bpc-formats)
[560.461174] Starting subtest: dsc-with-bpc-formats
[560.569168] Subtest dsc-with-bpc-formats: SKIP (0.003s)
[560.608916] [056/134] (480s left) kms_psr (pr-cursor-plane-move)
[560.698145] Starting subtest: pr-cursor-plane-move
[560.702348] Subtest pr-cursor-plane-move: SKIP (0.000s)
[560.788825] [057/134] (480s left) kms_vblank (ts-continuation-dpms-suspend)
[560.892518] Starting subtest: ts-continuation-dpms-suspend
[560.894552] Starting dynamic subtest: pipe-A-eDP-1
[578.161253] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.253s)
[578.176681] Starting dynamic subtest: pipe-D-eDP-1
[596.149711] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.899s)
[596.151989] Subtest ts-continuation-dpms-suspend: SUCCESS (5.157s)
[606.038113] [058/134] (444s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled)
[606.114264] Starting subtest: multi-wait-available-unsubmitted-signaled
[606.116437] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.001s)
[606.258885] [059/134] (444s left) kms_cursor_legacy (basic-flip-before-cursor-legacy)
[606.380729] Starting subtest: basic-flip-before-cursor-legacy
[607.315767] Subtest basic-flip-before-cursor-legacy: SUCCESS (0.928s)
[608.131471] [060/134] (442s left) kms_chamelium_hpd (dp-hpd-for-each-pipe)
[608.516452] Subtest dp-hpd-for-each-pipe: SKIP (0.000s)
[609.329179] [061/134] (441s left) gem_exec_params (invalid-flag)
[609.384758] Starting subtest: invalid-flag
[609.387070] Subtest invalid-flag: SUCCESS (0.000s)
[609.516795] [062/134] (440s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-pwrite)
[609.609533] Starting subtest: psr-2p-primscrn-spr-indfb-draw-pwrite
[609.613815] Subtest psr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[609.730049] [063/134] (440s left) syncobj_timeline (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[609.775185] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[609.777378] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[609.908663] [064/134] (440s left) prime_vgem (wait)
[609.991292] Starting subtest: wait
[609.993691] Starting dynamic subtest: rcs0
[610.044225] Dynamic subtest rcs0: SUCCESS (0.053s)
[610.053957] Starting dynamic subtest: bcs0
[610.088452] Dynamic subtest bcs0: SUCCESS (0.043s)
[610.098123] Starting dynamic subtest: vcs0
[610.136957] Dynamic subtest vcs0: SUCCESS (0.047s)
[610.146709] Starting dynamic subtest: vcs1
[610.183096] Dynamic subtest vcs1: SUCCESS (0.045s)
[610.192884] Starting dynamic subtest: vecs0
[610.230038] Dynamic subtest vecs0: SUCCESS (0.046s)
[610.239762] Starting dynamic subtest: ccs0
[610.278811] Dynamic subtest ccs0: SUCCESS (0.048s)
[610.280698] Subtest wait: SUCCESS (0.287s)
[610.426076] [065/134] (440s left) gem_exec_capture (capture)
[610.477915] Starting subtest: capture
[610.479701] Starting dynamic subtest: rcs0-smem
[611.296238] Dynamic subtest rcs0-smem: SUCCESS (0.812s)
[611.306013] Starting dynamic subtest: bcs0-smem
[612.112637] Dynamic subtest bcs0-smem: SUCCESS (0.812s)
[612.122474] Starting dynamic subtest: vcs0-smem
[612.926220] Dynamic subtest vcs0-smem: SUCCESS (0.808s)
[612.937024] Starting dynamic subtest: vcs1-smem
[613.785310] Dynamic subtest vcs1-smem: SUCCESS (0.855s)
[613.796515] Starting dynamic subtest: vecs0-smem
[614.679061] Dynamic subtest vecs0-smem: FAIL (0.834s)
[614.690390] Starting dynamic subtest: ccs0-smem
[615.947739] Dynamic subtest ccs0-smem: SUCCESS (1.323s)
[615.950076] Subtest capture: FAIL (5.449s)
[616.090702] [066/134] (434s left) kms_cursor_crc (cursor-rapid-movement-512x512)
[616.373378] Starting subtest: cursor-rapid-movement-512x512
[616.377756] Subtest cursor-rapid-movement-512x512: SKIP (0.001s)
[616.569089] [067/134] (434s left) kms_flip (2x-flip-vs-wf_vblank)
[616.655418] Starting subtest: 2x-flip-vs-wf_vblank
[616.664927] Subtest 2x-flip-vs-wf_vblank: SKIP (0.011s)
[616.814817] [068/134] (433s left) i915_pm_freq_api (freq-suspend)
[616.880267] Starting subtest: freq-suspend
[616.882495] Starting dynamic subtest: gt0
[633.587356] Dynamic subtest gt0: SUCCESS (1.630s)
[633.602841] Starting dynamic subtest: gt1
[650.589908] Dynamic subtest gt1: SUCCESS (1.609s)
[650.594449] Subtest freq-suspend: SUCCESS (3.243s)
[661.722642] [069/134] (400s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[661.846910] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt
[661.851680] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[661.952546] [070/134] (399s left) kms_chamelium_frames (hdmi-aspect-ratio)
[662.346962] Subtest hdmi-aspect-ratio: SKIP (0.000s)
[663.191783] [071/134] (398s left) kms_cursor_crc (cursor-sliding-max-size)
[663.527664] Starting subtest: cursor-sliding-max-size
[663.535130] Subtest cursor-sliding-max-size: SKIP (0.000s)
[663.680297] [072/134] (398s left) kms_color_pipeline (plane-ctm3x4-lut1d)
[663.779813] Starting subtest: plane-ctm3x4-lut1d
[663.796077] Starting dynamic subtest: pipe-A-eDP-1
[664.460866] Dynamic subtest pipe-A-eDP-1: FAIL (0.293s)
[665.195383] Starting dynamic subtest: pipe-B-eDP-1
[666.127304] Dynamic subtest pipe-B-eDP-1: FAIL (0.324s)
[666.645479] Starting dynamic subtest: pipe-C-eDP-1
[667.582177] Dynamic subtest pipe-C-eDP-1: FAIL (0.267s)
[667.977447] Starting dynamic subtest: pipe-D-eDP-1
[668.943675] Dynamic subtest pipe-D-eDP-1: FAIL (0.290s)
[668.945772] Subtest plane-ctm3x4-lut1d: FAIL (4.746s)
[669.386287] [073/134] (392s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw)
[669.481012] Starting subtest: psr-1p-pri-indfb-multidraw
[692.544098] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (23.029s)
[692.704596] [074/134] (369s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-render)
[692.811938] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-render
[692.816994] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s)
[692.921947] [075/134] (369s left) syncobj_timeline (invalid-query-illegal-handle)
[692.971727] Starting subtest: invalid-query-illegal-handle
[692.974226] Subtest invalid-query-illegal-handle: SUCCESS (0.000s)
[693.076584] [076/134] (368s left) kms_ccs (crc-primary-basic-4-tiled-mtl-mc-ccs)
[693.164569] Starting subtest: crc-primary-basic-4-tiled-mtl-mc-ccs
[693.167110] Starting dynamic subtest: pipe-A-eDP-1
[695.299747] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.139s)
[695.311699] Starting dynamic subtest: pipe-B-eDP-1
[697.723448] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.422s)
[697.732995] Starting dynamic subtest: pipe-C-eDP-1
[699.882035] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.160s)
[699.893889] Starting dynamic subtest: pipe-D-eDP-1
[702.042089] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.159s)
[702.044652] Subtest crc-primary-basic-4-tiled-mtl-mc-ccs: SUCCESS (8.885s)
[703.296093] [077/134] (358s left) kms_addfb_basic (too-high)
[703.408177] Starting subtest: too-high
[703.410584] Subtest too-high: SUCCESS (0.001s)
[703.535613] [078/134] (358s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt)
[703.640911] Starting subtest: psr-rgb565-draw-mmap-gtt
[703.645393] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s)
[703.766104] [079/134] (358s left) gem_mmap_offset (mmap-unmap)
[703.815141] Starting subtest: mmap-unmap
[703.817778] Starting dynamic subtest: smem0
[726.936133] Dynamic subtest smem0: SUCCESS (23.070s)
[726.939137] Subtest mmap-unmap: SUCCESS (23.071s)
[727.013846] [080/134] (335s left) gem_pread (exhaustion)
[727.071915] Subtest exhaustion: SKIP (0.000s)
[727.128456] [081/134] (335s left) kms_joiner (invalid-modeset-ultra-joiner)
[727.230532] Starting subtest: invalid-modeset-ultra-joiner
[727.235603] Subtest invalid-modeset-ultra-joiner: SKIP (0.000s)
[727.307845] [082/134] (334s left) i915_drm_fdinfo (basics)
[727.344770] Starting subtest: basics
[727.347622] Subtest basics: SUCCESS (0.000s)
[727.418230] [083/134] (334s left) gem_ccs (ctrl-surf-copy-new-ctx)
[727.470942] Starting subtest: ctrl-surf-copy-new-ctx
[727.476096] Subtest ctrl-surf-copy-new-ctx: SKIP (0.000s)
[727.612057] [084/134] (334s left) kms_plane (pixel-format-x-tiled-modifier-source-clamping)
[727.695822] Starting subtest: pixel-format-x-tiled-modifier-source-clamping
[727.724350] Starting dynamic subtest: pipe-A-plane-0
[728.412610] Dynamic subtest pipe-A-plane-0: SUCCESS (0.688s)
[728.425102] Starting dynamic subtest: pipe-A-plane-3
[728.868199] Dynamic subtest pipe-A-plane-3: SUCCESS (0.454s)
[728.877777] Starting dynamic subtest: pipe-A-plane-5
[728.881804] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[729.980792] Starting dynamic subtest: pipe-B-plane-0
[730.706754] Dynamic subtest pipe-B-plane-0: SUCCESS (0.733s)
[730.716651] Starting dynamic subtest: pipe-B-plane-3
[731.151987] Dynamic subtest pipe-B-plane-3: SUCCESS (0.443s)
[731.161740] Starting dynamic subtest: pipe-B-plane-5
[731.165941] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[731.460204] Subtest pixel-format-x-tiled-modifier-source-clamping: SUCCESS (3.765s)
[732.292243] [085/134] (330s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-onoff)
[732.390020] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-onoff
[732.395328] Subtest fbcpsr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s)
[732.505140] [086/134] (329s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-downscaling)
[732.598694] Starting subtest: flip-64bpp-linear-to-16bpp-linear-downscaling
[732.601093] Starting dynamic subtest: pipe-A-default-mode
[733.720546] Dynamic subtest pipe-A-default-mode: SKIP (1.120s)
[733.725198] Subtest flip-64bpp-linear-to-16bpp-linear-downscaling: SKIP (1.122s)
[733.901254] [087/134] (328s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-msflip-blt)
[734.002196] Starting subtest: fbc-2p-primscrn-shrfb-msflip-blt
[734.006997] Subtest fbc-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s)
[734.110108] [088/134] (328s left) kms_draw_crc (fill-fb)
[734.202132] Starting subtest: fill-fb
[734.699257] Subtest fill-fb: SUCCESS (0.498s)
[734.817541] [089/134] (327s left) kms_big_fb (y-tiled-8bpp-rotate-0)
[734.927590] Starting subtest: y-tiled-8bpp-rotate-0
[734.931858] Subtest y-tiled-8bpp-rotate-0: SKIP (0.000s)
[735.058563] [090/134] (327s left) gem_render_copy (yf-tiled-to-vebox-linear)
[735.118721] Starting subtest: yf-tiled-to-vebox-linear
[735.122996] Subtest yf-tiled-to-vebox-linear: SKIP (0.000s)
[735.316703] [091/134] (327s left) syncobj_wait (invalid-reset-illegal-handle)
[735.384985] Starting subtest: invalid-reset-illegal-handle
[735.387910] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[735.504563] [092/134] (327s left) kms_frontbuffer_tracking (psr-1p-offscreen-pri-indfb-draw-blt)
[735.602979] Starting subtest: psr-1p-offscreen-pri-indfb-draw-blt
[742.555971] Subtest psr-1p-offscreen-pri-indfb-draw-blt: SUCCESS (6.953s)
[742.706880] [093/134] (320s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip)
[742.805920] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip
[742.810143] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0-hflip-async-flip: SKIP (0.000s)
[742.940359] [094/134] (319s left) api_intel_bb (full-batch)
[743.002492] Starting subtest: full-batch
[743.008426] Subtest full-batch: SUCCESS (0.008s)
[743.165770] [095/134] (319s left) kms_psr (fbc-psr-basic)
[743.268169] Starting subtest: fbc-psr-basic
[743.270857] Starting dynamic subtest: eDP-1
[743.275852] Dynamic subtest eDP-1: SKIP (0.000s)
[743.280825] Subtest fbc-psr-basic: SKIP (0.001s)
[743.355549] [096/134] (319s left) gem_exercise_blt (fast-copy-emit)
[743.421230] Starting subtest: fast-copy-emit
[743.424159] Starting dynamic subtest: linear-smem-smem-emit
[743.444177] Dynamic subtest linear-smem-smem-emit: SUCCESS (0.023s)
[743.456595] Starting dynamic subtest: xmajor-smem-smem-emit
[743.461759] Dynamic subtest xmajor-smem-smem-emit: SUCCESS (0.016s)
[743.471186] Starting dynamic subtest: tile4-smem-smem-emit
[743.477537] Dynamic subtest tile4-smem-smem-emit: SUCCESS (0.016s)
[743.487112] Starting dynamic subtest: tile64-smem-smem-emit
[743.493695] Dynamic subtest tile64-smem-smem-emit: SUCCESS (0.016s)
[743.496059] Subtest fast-copy-emit: SUCCESS (0.074s)
[743.648953] [097/134] (319s left) sysfs_heartbeat_interval (mixed)
[743.716058] Starting subtest: mixed
[743.719066] Starting dynamic subtest: vecs0
[748.966227] Dynamic subtest vecs0: SUCCESS (5.247s)
[748.978389] Starting dynamic subtest: ccs0
[754.183976] Dynamic subtest ccs0: SUCCESS (5.213s)
[754.196347] Starting dynamic subtest: vcs0
[759.398172] Dynamic subtest vcs0: SUCCESS (5.213s)
[759.409369] Starting dynamic subtest: bcs0
[764.638840] Dynamic subtest bcs0: SUCCESS (5.241s)
[764.651346] Starting dynamic subtest: rcs0
[769.827460] Dynamic subtest rcs0: SUCCESS (5.188s)
[769.838201] Starting dynamic subtest: vcs1
[775.040773] Dynamic subtest vcs1: SUCCESS (5.213s)
[775.043952] Subtest mixed: SUCCESS (31.328s)
[775.139630] [098/134] (287s left) kms_cursor_legacy (cursor-vs-flip-legacy)
[775.268747] Starting subtest: cursor-vs-flip-legacy
[804.540381] Subtest cursor-vs-flip-legacy: SUCCESS (29.271s)
[805.349439] [099/134] (257s left) gem_exec_schedule (semaphore-resolve)
[805.429929] Starting subtest: semaphore-resolve
[805.463913] Subtest semaphore-resolve: SUCCESS (0.040s)
[805.826517] [100/134] (257s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-render)
[805.920942] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-render
[805.925948] Subtest fbc-2p-primscrn-pri-indfb-draw-render: SKIP (0.000s)
[806.022397] [101/134] (257s left) kms_cursor_legacy (torture-bo)
[806.146882] Starting subtest: torture-bo
[806.149467] Starting dynamic subtest: pipe-A
[812.005897] Dynamic subtest pipe-A: SUCCESS (5.801s)
[812.024125] Starting dynamic subtest: pipe-B
[819.252720] Dynamic subtest pipe-B: SUCCESS (7.296s)
[819.264695] Starting dynamic subtest: pipe-C
[825.693128] Dynamic subtest pipe-C: SUCCESS (6.436s)
[825.704222] Starting dynamic subtest: pipe-D
[832.469383] Dynamic subtest pipe-D: SUCCESS (6.775s)
[832.483181] Starting dynamic subtest: all-pipes
[838.412936] Dynamic subtest all-pipes: SUCCESS (5.941s)
[838.415469] Subtest torture-bo: SUCCESS (32.260s)
[838.592263] [102/134] (224s left) gem_ctx_shared (q-independent)
[838.669126] Starting subtest: Q-independent
[838.672029] Starting dynamic subtest: rcs0
[838.738990] Dynamic subtest rcs0: SUCCESS (0.069s)
[838.751836] Starting dynamic subtest: vcs0
[838.777832] Dynamic subtest vcs0: SUCCESS (0.038s)
[838.788000] Starting dynamic subtest: vcs1
[838.812841] Dynamic subtest vcs1: SUCCESS (0.034s)
[838.822832] Starting dynamic subtest: vecs0
[838.846124] Dynamic subtest vecs0: SUCCESS (0.033s)
[838.855886] Starting dynamic subtest: ccs0
[838.886732] Dynamic subtest ccs0: SUCCESS (0.040s)
[838.889030] Subtest Q-independent: SUCCESS (0.218s)
[840.069167] [103/134] (223s left) gem_exec_fence (syncobj-timeline-invalid-wait)
[840.639902] Starting subtest: syncobj-timeline-invalid-wait
[840.643022] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s)
[842.112795] [104/134] (221s left) gem_render_copy (y-tiled-ccs-to-x-tiled)
[842.182969] Starting subtest: y-tiled-ccs-to-x-tiled
[842.188733] Subtest y-tiled-ccs-to-x-tiled: SKIP (0.000s)
[842.387114] [105/134] (220s left) drm_read (short-buffer-wakeup)
[842.533900] Starting subtest: short-buffer-wakeup
[874.758622] Subtest short-buffer-wakeup: SUCCESS (32.218s)
[874.935273] [106/134] (188s left) kms_atomic_transition (plane-use-after-nonblocking-unbind-fencing)
[875.025002] Starting subtest: plane-use-after-nonblocking-unbind-fencing
[875.027599] Starting dynamic subtest: pipe-A-eDP-1
[875.089213] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.064s)
[875.401340] Starting dynamic subtest: pipe-B-eDP-1
[876.249736] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.858s)
[876.551192] Subtest plane-use-after-nonblocking-unbind-fencing: SUCCESS (1.524s)
[877.401349] [107/134] (185s left) syncobj_timeline (device-submit-unordered)
[877.453207] Starting subtest: device-submit-unordered
[877.455595] Subtest device-submit-unordered: SUCCESS (0.000s)
[877.573581] [108/134] (185s left) kms_ccs (crc-sprite-planes-basic-4-tiled-mtl-mc-ccs)
[877.668889] Starting subtest: crc-sprite-planes-basic-4-tiled-mtl-mc-ccs
[877.671501] Starting dynamic subtest: pipe-A-eDP-1
[877.976754] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.273s)
[877.988041] Starting dynamic subtest: pipe-B-eDP-1
[879.265032] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.286s)
[879.275828] Starting dynamic subtest: pipe-C-eDP-1
[880.465850] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.203s)
[880.476239] Starting dynamic subtest: pipe-D-eDP-1
[881.720467] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.247s)
[881.723191] Subtest crc-sprite-planes-basic-4-tiled-mtl-mc-ccs: SUCCESS (4.015s)
[882.864300] [109/134] (180s left) kms_pipe_crc_basic (nonblocking-crc)
[882.974979] Starting subtest: nonblocking-crc
[882.977189] Starting dynamic subtest: pipe-A-eDP-1
[883.749110] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.773s)
[883.760998] Starting dynamic subtest: pipe-B-eDP-1
[885.166025] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.417s)
[885.176196] Starting dynamic subtest: pipe-C-eDP-1
[886.550192] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.382s)
[886.562328] Starting dynamic subtest: pipe-D-eDP-1
[887.982104] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.429s)
[887.985017] Subtest nonblocking-crc: SUCCESS (5.007s)
[888.787835] [110/134] (174s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt)
[888.890044] Starting subtest: fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt
[888.895124] Subtest fbc-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s)
[889.007232] [111/134] (174s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-draw-mmap-gtt)
[889.122699] Starting subtest: fbc-1p-primscrn-cur-indfb-draw-mmap-gtt
[889.127332] Subtest fbc-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[889.241330] [112/134] (174s left) kms_plane (pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping)
[889.336939] Starting subtest: pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping
[889.365208] Starting dynamic subtest: pipe-A-plane-0
[889.370626] Dynamic subtest pipe-A-plane-0: SKIP (0.000s)
[889.381948] Starting dynamic subtest: pipe-A-plane-3
[889.387018] Dynamic subtest pipe-A-plane-3: SKIP (0.000s)
[889.396970] Starting dynamic subtest: pipe-A-plane-5
[889.402181] Dynamic subtest pipe-A-plane-5: SKIP (0.000s)
[890.463123] Starting dynamic subtest: pipe-B-plane-0
[890.468888] Dynamic subtest pipe-B-plane-0: SKIP (0.000s)
[890.481004] Starting dynamic subtest: pipe-B-plane-3
[890.485936] Dynamic subtest pipe-B-plane-3: SKIP (0.000s)
[890.496690] Starting dynamic subtest: pipe-B-plane-5
[890.501188] Dynamic subtest pipe-B-plane-5: SKIP (0.000s)
[890.763352] Subtest pixel-format-4-tiled-dg2-rc-ccs-cc-modifier-source-clamping: SKIP (1.424s)
[891.643184] [113/134] (171s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation)
[891.737924] Starting subtest: plane-downscale-factor-0-5-with-rotation
[891.740170] Starting dynamic subtest: pipe-A
[891.760121] Dynamic subtest pipe-A: SKIP (0.016s)
[891.770100] Starting dynamic subtest: pipe-B
[891.790852] Dynamic subtest pipe-B: SKIP (0.031s)
[891.800854] Starting dynamic subtest: pipe-C
[891.834055] Dynamic subtest pipe-C: SKIP (0.030s)
[891.843577] Starting dynamic subtest: pipe-D
[891.853423] Dynamic subtest pipe-D: SKIP (0.030s)
[891.858457] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.111s)
[891.980865] [114/134] (171s left) perf_pmu (busy-hang)
[892.022177] Starting subtest: busy-hang
[892.024341] Starting dynamic subtest: rcs0
[893.329816] Dynamic subtest rcs0: SUCCESS (1.295s)
[893.342179] Starting dynamic subtest: bcs0
[894.562658] Dynamic subtest bcs0: SUCCESS (1.245s)
[894.575344] Starting dynamic subtest: vcs0
[896.462816] Dynamic subtest vcs0: SUCCESS (1.898s)
[896.473883] Starting dynamic subtest: vcs1
[898.509970] Dynamic subtest vcs1: SUCCESS (2.042s)
[898.521237] Starting dynamic subtest: vecs0
[900.495005] Dynamic subtest vecs0: SUCCESS (1.986s)
[900.505955] Starting dynamic subtest: ccs0
[901.734302] Dynamic subtest ccs0: SUCCESS (1.235s)
[901.737052] Subtest busy-hang: SUCCESS (9.712s)
[901.839450] [115/134] (161s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-rc-ccs)
[901.947879] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-rc-ccs
[901.950570] Starting dynamic subtest: pipe-A-eDP-1
[902.797934] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.839s)
[902.808114] Starting dynamic subtest: pipe-B-eDP-1
[904.482649] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.681s)
[904.492643] Starting dynamic subtest: pipe-C-eDP-1
[905.973533] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.489s)
[905.984226] Starting dynamic subtest: pipe-D-eDP-1
[907.491343] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.518s)
[907.494030] Subtest crc-primary-rotation-180-4-tiled-mtl-rc-ccs: SUCCESS (5.534s)
[908.671977] [116/134] (155s left) kms_cursor_crc (cursor-offscreen-128x42)
[908.908641] Starting subtest: cursor-offscreen-128x42
[908.957508] Subtest cursor-offscreen-128x42: SKIP (0.042s)
[909.194514] [117/134] (154s left) kms_plane_scaling (planes-upscale-20x20-downscale-factor-0-75)
[909.295418] Starting subtest: planes-upscale-20x20-downscale-factor-0-75
[909.297714] Starting dynamic subtest: pipe-A
[909.317623] Dynamic subtest pipe-A: SKIP (0.018s)
[909.327873] Starting dynamic subtest: pipe-B
[909.352190] Dynamic subtest pipe-B: SKIP (0.032s)
[909.362385] Starting dynamic subtest: pipe-C
[909.385466] Dynamic subtest pipe-C: SKIP (0.032s)
[909.395185] Starting dynamic subtest: pipe-D
[909.418167] Dynamic subtest pipe-D: SKIP (0.032s)
[909.423224] Subtest planes-upscale-20x20-downscale-factor-0-75: SKIP (0.118s)
[909.542443] [118/134] (154s left) kms_big_fb (linear-max-hw-stride-64bpp-rotate-0)
[909.653603] Starting subtest: linear-max-hw-stride-64bpp-rotate-0
[910.688649] Subtest linear-max-hw-stride-64bpp-rotate-0: SUCCESS (1.034s)
[910.845532] [119/134] (153s left) syncobj_basic (bad-pad-handle-to-fd)
[910.913030] Starting subtest: bad-pad-handle-to-fd
[910.915922] Subtest bad-pad-handle-to-fd: SUCCESS (0.000s)
[911.042389] [120/134] (152s left) kms_cursor_legacy (cursora-vs-flipb-atomic-transitions)
[911.159752] Starting subtest: cursorA-vs-flipB-atomic-transitions
[911.163980] Subtest cursorA-vs-flipB-atomic-transitions: SKIP (0.001s)
[911.314532] [121/134] (152s left) kms_frontbuffer_tracking (psr-2p-rte)
[911.418506] Starting subtest: psr-2p-rte
[911.421987] Subtest psr-2p-rte: SKIP (0.000s)
[911.549577] [122/134] (152s left) kms_frontbuffer_tracking (psr-shrfb-scaledprimary)
[911.654984] Starting subtest: psr-shrfb-scaledprimary
[917.489008] Subtest psr-shrfb-scaledprimary: SUCCESS (5.837s)
[917.638459] [123/134] (146s left) gen7_exec_parse (cmd-crossing-page)
[917.703023] Subtest cmd-crossing-page: SKIP (0.000s)
[917.769723] [124/134] (146s left) kms_frontbuffer_tracking (fbc-1p-rte)
[917.867022] Starting subtest: fbc-1p-rte
[923.398228] Subtest fbc-1p-rte: SUCCESS (5.536s)
[924.621543] [125/134] (139s left) gem_mmap_wc (write)
[924.691018] Subtest write: SKIP (0.000s)
[924.780597] [126/134] (139s left) kms_busy (extended-modeset-hang-newfb)
[924.887971] Starting subtest: extended-modeset-hang-newfb
[924.891076] Starting dynamic subtest: pipe-A
[959.601183] Dynamic subtest pipe-A: SUCCESS (34.708s)
[959.613207] Starting dynamic subtest: pipe-D
[981.898881] Dynamic subtest pipe-D: SUCCESS (22.294s)
[981.902007] Subtest extended-modeset-hang-newfb: SUCCESS (57.007s)
[982.711735] [127/134] ( 81s left) i915_pm_rpm (gem-evict-pwrite)
[984.258854] Starting subtest: gem-evict-pwrite
[984.263536] Subtest gem-evict-pwrite: SKIP (0.000s)
[984.984981] [128/134] ( 79s left) kms_ccs (bad-pixel-format-y-tiled-gen12-rc-ccs)
[985.091880] Starting subtest: bad-pixel-format-y-tiled-gen12-rc-ccs
[985.094367] Starting dynamic subtest: pipe-A-eDP-1
[985.119498] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[985.130752] Starting dynamic subtest: pipe-B-eDP-1
[985.152410] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[985.161967] Starting dynamic subtest: pipe-C-eDP-1
[985.187134] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[985.196453] Starting dynamic subtest: pipe-D-eDP-1
[985.221748] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[985.226772] Subtest bad-pixel-format-y-tiled-gen12-rc-ccs: SKIP (0.005s)
[987.432067] [129/134] ( 76s left) i915_suspend (basic-s2idle-without-i915)
[987.486037] Starting subtest: basic-s2idle-without-i915
[1007.060010] Subtest basic-s2idle-without-i915: SUCCESS (4.591s)
[1016.253554] [130/134] ( 57s left) kms_flip_scaled_crc (flip-32bpp-xtile-to-64bpp-xtile-upscaling)
[1016.395777] Starting subtest: flip-32bpp-xtile-to-64bpp-xtile-upscaling
[1016.398062] Starting dynamic subtest: pipe-A-default-mode
[1019.131896] Dynamic subtest pipe-A-default-mode: SUCCESS (2.736s)
[1019.134265] Subtest flip-32bpp-xtile-to-64bpp-xtile-upscaling: SUCCESS (2.738s)
[1019.971647] [131/134] ( 53s left) kms_big_fb (4-tiled-addfb)
[1020.100324] Starting subtest: 4-tiled-addfb
[1020.102511] Subtest 4-tiled-addfb: SUCCESS (0.000s)
[1020.232840] [132/134] ( 53s left) kms_setmode (basic-clone-single-crtc)
[1020.302412] Starting subtest: basic-clone-single-crtc
[1020.308721] Subtest basic-clone-single-crtc: SKIP (0.000s)
[1020.429194] [133/134] ( 53s left) gem_exec_balancer (full-late-pulse)
[1020.494395] Starting subtest: full-late-pulse
[1024.844881] Subtest full-late-pulse: SUCCESS (4.351s)
[1025.200390] [134/134] ( 48s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-render)
[1025.294202] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-render
[1025.298376] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s)
[1025.419499] Closing watchdogs
results: parsing output: 0/ for test: kms_frontbuffer_tracking
results: parsing output: 1/ for test: gem_exec_params
results: parsing output: 2/ for test: tools_test
results: parsing output: 3/ for test: kms_selftest
results: parsing output: 4/ for test: gem_exec_suspend
results: parsing output: 5/ for test: kms_content_protection
results: parsing output: 6/ for test: gem_eio
results: parsing output: 7/ for test: gem_spin_batch
results: parsing output: 8/ for test: kms_psr
results: parsing output: 9/ for test: kms_frontbuffer_tracking
results: parsing output: 10/ for test: kms_frontbuffer_tracking
results: parsing output: 11/ for test: kms_psr2_sf
results: parsing output: 12/ for test: kms_psr2_sf
results: parsing output: 13/ for test: kms_pm_rpm
results: parsing output: 14/ for test: gem_ctx_exec
results: parsing output: 15/ for test: gem_pxp
results: parsing output: 16/ for test: kms_flip
results: parsing output: 17/ for test: prime_mmap
results: parsing output: 18/ for test: gem_exec_schedule
results: parsing output: 19/ for test: perf
results: parsing output: 20/ for test: api_intel_allocator
results: parsing output: 21/ for test: kms_flip
results: parsing output: 22/ for test: kms_psr2_sf
results: parsing output: 23/ for test: syncobj_wait
results: parsing output: 24/ for test: gem_exec_create
results: parsing output: 25/ for test: gem_ctx_persistence
results: parsing output: 26/ for test: gem_ctx_bad_destroy
results: parsing output: 27/ for test: kms_cursor_legacy
results: parsing output: 28/ for test: kms_atomic_interruptible
results: parsing output: 29/ for test: kms_cdclk
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: perf_pmu
results: parsing output: 32/ for test: kms_psr
results: parsing output: 33/ for test: gem_exec_schedule
results: parsing output: 34/ for test: kms_selftest
results: parsing output: 35/ for test: syncobj_basic
results: parsing output: 36/ for test: gem_lmem_swapping
results: parsing output: 37/ for test: kms_mmap_write_crc
results: parsing output: 38/ for test: kms_frontbuffer_tracking
results: parsing output: 39/ for test: kms_addfb_basic
results: parsing output: 40/ for test: kms_frontbuffer_tracking
results: parsing output: 41/ for test: gem_ctx_exec
results: parsing output: 42/ for test: kms_atomic
results: parsing output: 43/ for test: gem_set_tiling_vs_gtt
results: parsing output: 44/ for test: kms_big_fb
results: parsing output: 45/ for test: kms_getfb
results: parsing output: 46/ for test: syncobj_timeline
results: parsing output: 47/ for test: gem_exec_reloc
results: parsing output: 48/ for test: kms_frontbuffer_tracking
results: parsing output: 49/ for test: gem_basic
results: parsing output: 50/ for test: kms_frontbuffer_tracking
results: parsing output: 51/ for test: gem_wait
results: parsing output: 52/ for test: gem_exec_reloc
results: parsing output: 53/ for test: kms_pm_rpm
results: parsing output: 54/ for test: kms_dsc
results: parsing output: 55/ for test: kms_psr
results: parsing output: 56/ for test: kms_vblank
results: parsing output: 57/ for test: syncobj_timeline
results: parsing output: 58/ for test: kms_cursor_legacy
results: parsing output: 59/ for test: kms_chamelium_hpd
results: parsing output: 60/ for test: gem_exec_params
results: parsing output: 61/ for test: kms_frontbuffer_tracking
results: parsing output: 62/ for test: syncobj_timeline
results: parsing output: 63/ for test: prime_vgem
results: parsing output: 64/ for test: gem_exec_capture
results: parsing output: 65/ for test: kms_cursor_crc
results: parsing output: 66/ for test: kms_flip
results: parsing output: 67/ for test: i915_pm_freq_api
results: parsing output: 68/ for test: kms_frontbuffer_tracking
results: parsing output: 69/ for test: kms_chamelium_frames
results: parsing output: 70/ for test: kms_cursor_crc
results: parsing output: 71/ for test: kms_color_pipeline
results: parsing output: 72/ for test: kms_frontbuffer_tracking
results: parsing output: 73/ for test: kms_frontbuffer_tracking
results: parsing output: 74/ for test: syncobj_timeline
results: parsing output: 75/ for test: kms_ccs
results: parsing output: 76/ for test: kms_addfb_basic
results: parsing output: 77/ for test: kms_frontbuffer_tracking
results: parsing output: 78/ for test: gem_mmap_offset
results: parsing output: 79/ for test: gem_pread
results: parsing output: 80/ for test: kms_joiner
results: parsing output: 81/ for test: i915_drm_fdinfo
results: parsing output: 82/ for test: gem_ccs
results: parsing output: 83/ for test: kms_plane
results: parsing output: 84/ for test: kms_frontbuffer_tracking
results: parsing output: 85/ for test: kms_flip_scaled_crc
results: parsing output: 86/ for test: kms_frontbuffer_tracking
results: parsing output: 87/ for test: kms_draw_crc
results: parsing output: 88/ for test: kms_big_fb
results: parsing output: 89/ for test: gem_render_copy
results: parsing output: 90/ for test: syncobj_wait
results: parsing output: 91/ for test: kms_frontbuffer_tracking
results: parsing output: 92/ for test: kms_big_fb
results: parsing output: 93/ for test: api_intel_bb
results: parsing output: 94/ for test: kms_psr
results: parsing output: 95/ for test: gem_exercise_blt
results: parsing output: 96/ for test: sysfs_heartbeat_interval
results: parsing output: 97/ for test: kms_cursor_legacy
results: parsing output: 98/ for test: gem_exec_schedule
results: parsing output: 99/ for test: kms_frontbuffer_tracking
results: parsing output: 100/ for test: kms_cursor_legacy
results: parsing output: 101/ for test: gem_ctx_shared
results: parsing output: 102/ for test: gem_exec_fence
results: parsing output: 103/ for test: gem_render_copy
results: parsing output: 104/ for test: drm_read
results: parsing output: 105/ for test: kms_atomic_transition
results: parsing output: 106/ for test: syncobj_timeline
results: parsing output: 107/ for test: kms_ccs
results: parsing output: 108/ for test: kms_pipe_crc_basic
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: kms_frontbuffer_tracking
results: parsing output: 111/ for test: kms_plane
results: parsing output: 112/ for test: kms_plane_scaling
results: parsing output: 113/ for test: perf_pmu
results: parsing output: 114/ for test: kms_ccs
results: parsing output: 115/ for test: kms_cursor_crc
results: parsing output: 116/ for test: kms_plane_scaling
results: parsing output: 117/ for test: kms_big_fb
results: parsing output: 118/ for test: syncobj_basic
results: parsing output: 119/ for test: kms_cursor_legacy
results: parsing output: 120/ for test: kms_frontbuffer_tracking
results: parsing output: 121/ for test: kms_frontbuffer_tracking
results: parsing output: 122/ for test: gen7_exec_parse
results: parsing output: 123/ for test: kms_frontbuffer_tracking
results: parsing output: 124/ for test: gem_mmap_wc
results: parsing output: 125/ for test: kms_busy
results: parsing output: 126/ for test: i915_pm_rpm
results: parsing output: 127/ for test: kms_ccs
results: parsing output: 128/ for test: i915_suspend
results: parsing output: 129/ for test: kms_flip_scaled_crc
results: parsing output: 130/ for test: kms_big_fb
results: parsing output: 131/ for test: kms_setmode
results: parsing output: 132/ for test: gem_exec_balancer
results: parsing output: 133/ for test: kms_frontbuffer_tracking
Done.