[59.118114] Initializing watchdogs
[59.118275]   /dev/watchdog0
[59.134660] [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]
[59.139161] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[59.143158] [FACT before any test] new: kernel.kmod_is_loaded.i915: true
[59.145412] [001/133] (960s left) kms_cursor_legacy (torture-bo)
[59.268239] Starting subtest: torture-bo
[59.269470] Starting dynamic subtest: pipe-A
[64.995902] Dynamic subtest pipe-A: SUCCESS (5.719s)
[65.005095] Starting dynamic subtest: pipe-B
[71.776058] Dynamic subtest pipe-B: SUCCESS (6.775s)
[71.785410] Starting dynamic subtest: pipe-C
[78.213826] Dynamic subtest pipe-C: SUCCESS (6.436s)
[78.223667] Starting dynamic subtest: pipe-D
[84.224768] Dynamic subtest pipe-D: SUCCESS (6.009s)
[84.231492] Starting dynamic subtest: all-pipes
[91.677741] Dynamic subtest all-pipes: SUCCESS (7.452s)
[91.678564] Subtest torture-bo: SUCCESS (32.393s)
[91.837573] [002/133] (927s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-sf)
[91.926991] Starting subtest: fbc-psr2-cursor-plane-move-continuous-sf
[91.927900] Starting dynamic subtest: pipe-A-eDP-1
[92.030521] Dynamic subtest pipe-A-eDP-1: SKIP (0.103s)
[92.037289] Starting dynamic subtest: pipe-B-eDP-1
[93.197500] Dynamic subtest pipe-B-eDP-1: SKIP (1.166s)
[93.199152] Subtest fbc-psr2-cursor-plane-move-continuous-sf: SKIP (1.271s)
[94.371953] [003/133] (925s left) kms_cursor_legacy (flip-vs-cursor-legacy)
[94.482935] Starting subtest: flip-vs-cursor-legacy
[102.175441] Subtest flip-vs-cursor-legacy: SUCCESS (7.692s)
[103.000690] [004/133] (916s left) kms_psr (psr-cursor-plane-onoff)
[103.088099] Starting subtest: psr-cursor-plane-onoff
[103.088938] Starting dynamic subtest: eDP-1
[104.807010] Dynamic subtest eDP-1: SUCCESS (1.719s)
[104.808266] Subtest psr-cursor-plane-onoff: SUCCESS (1.720s)
[105.649511] [005/133] (914s left) gem_exec_gttfill (engines)
[105.713496] Starting subtest: engines
[105.714550] Starting dynamic subtest: rcs0
[128.586283] Dynamic subtest rcs0: SUCCESS (22.871s)
[128.596300] Starting dynamic subtest: bcs0
[151.930370] Dynamic subtest bcs0: SUCCESS (23.342s)
[151.936800] Starting dynamic subtest: vcs0
[175.281439] Dynamic subtest vcs0: SUCCESS (23.348s)
[175.290743] Starting dynamic subtest: vcs1
[198.615701] Dynamic subtest vcs1: SUCCESS (23.332s)
[198.625220] Starting dynamic subtest: vecs0
[221.939794] Dynamic subtest vecs0: SUCCESS (23.322s)
[221.948665] Starting dynamic subtest: ccs0
[245.275476] Dynamic subtest ccs0: SUCCESS (23.333s)
[245.277215] Subtest engines: SUCCESS (139.556s)
[245.457802] [006/133] (774s left) kms_ccs (crc-primary-rotation-180-4-tiled-mtl-mc-ccs)
[245.589894] Starting subtest: crc-primary-rotation-180-4-tiled-mtl-mc-ccs
[245.590808] Starting dynamic subtest: pipe-A-eDP-1
[247.823822] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.234s)
[247.831685] Starting dynamic subtest: pipe-B-eDP-1
[250.178525] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.354s)
[250.185928] Starting dynamic subtest: pipe-C-eDP-1
[252.333531] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.154s)
[252.341120] Starting dynamic subtest: pipe-D-eDP-1
[254.444328] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.110s)
[254.445148] Subtest crc-primary-rotation-180-4-tiled-mtl-mc-ccs: SUCCESS (8.855s)
[255.591095] [007/133] (764s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-move)
[255.706615] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-move
[255.708313] Subtest fbcpsr-2p-primscrn-cur-indfb-move: SKIP (0.000s)
[255.795936] [008/133] (764s left) kms_force_connector_basic (force-load-detect)
[255.855485] Starting subtest: force-load-detect
[255.857728] Subtest force-load-detect: SKIP (0.000s)
[255.964456] [009/133] (763s left) kms_cursor_crc (cursor-alpha-transparent)
[256.051428] Starting subtest: cursor-alpha-transparent
[256.052327] Starting dynamic subtest: pipe-A-eDP-1
[256.677964] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.625s)
[256.685216] Starting dynamic subtest: pipe-D-eDP-1
[257.976809] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.298s)
[257.977688] Subtest cursor-alpha-transparent: SUCCESS (1.926s)
[258.846649] [010/133] (761s left) gem_ctx_shared (create-shared-gtt)
[258.922555] Starting subtest: create-shared-gtt
[261.120234] Subtest create-shared-gtt: SUCCESS (2.152s)
[261.452518] [011/133] (758s left) gem_exec_reloc (basic-gtt-read)
[261.500656] Subtest basic-gtt-read: SKIP (0.000s)
[261.561303] [012/133] (758s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu)
[261.652916] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu
[261.654842] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s)
[261.742395] [013/133] (758s left) syncobj_wait (invalid-multi-wait-all-unsubmitted)
[261.795555] Starting subtest: invalid-multi-wait-all-unsubmitted
[261.796539] Subtest invalid-multi-wait-all-unsubmitted: SUCCESS (0.000s)
[261.877697] [014/133] (758s left) kms_big_fb (y-tiled-32bpp-rotate-0)
[261.978906] Starting subtest: y-tiled-32bpp-rotate-0
[261.980860] Subtest y-tiled-32bpp-rotate-0: SKIP (0.000s)
[262.066061] [015/133] (758s left) kms_setmode (invalid-clone-exclusive-crtc)
[262.120389] Starting subtest: invalid-clone-exclusive-crtc
[262.123197] Subtest invalid-clone-exclusive-crtc: SKIP (0.000s)
[262.210653] [016/133] (757s left) syncobj_timeline (multi-wait-for-submit-submitted-signaled)
[262.253151] Starting subtest: multi-wait-for-submit-submitted-signaled
[262.254612] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.001s)
[262.317664] [017/133] (757s left) gem_mmap_offset (perf)
[262.375515] Starting subtest: perf
[262.376437] Starting dynamic subtest: smem0
[262.712484] Dynamic subtest smem0: SUCCESS (0.336s)
[262.713936] Subtest perf: SUCCESS (0.336s)
[262.814244] [018/133] (757s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-5)
[262.904484] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-5
[262.905433] Starting dynamic subtest: pipe-A
[262.923811] Dynamic subtest pipe-A: SKIP (0.018s)
[262.930752] Starting dynamic subtest: pipe-B
[262.957816] Dynamic subtest pipe-B: SKIP (0.034s)
[262.964550] Starting dynamic subtest: pipe-C
[262.990424] Dynamic subtest pipe-C: SKIP (0.032s)
[262.996873] Starting dynamic subtest: pipe-D
[263.022622] Dynamic subtest pipe-D: SKIP (0.032s)
[263.024240] Subtest planes-upscale-factor-0-25-downscale-factor-0-5: SKIP (0.117s)
[263.132771] [019/133] (757s left) syncobj_timeline (etime-single-wait-available-unsubmitted)
[263.187793] Starting subtest: etime-single-wait-available-unsubmitted
[263.291309] Subtest etime-single-wait-available-unsubmitted: SUCCESS (0.104s)
[263.389078] [020/133] (756s left) gem_set_tiling_vs_pwrite
[263.493583] [021/133] (756s left) gem_exec_balancer (sliced)
[263.551564] Starting subtest: sliced
[263.553843] Subtest sliced: SKIP (0.000s)
[263.840263] [022/133] (756s left) kms_selftest (drm_damage_helper)
[263.873920] Starting subtest: drm_damage_helper
[263.917592] Starting dynamic subtest: drm_test_damage_iter_no_damage
[264.007958] Dynamic subtest drm_test_damage_iter_no_damage: SUCCESS (0.090s)
[264.020818] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src
[264.094958] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src: SUCCESS (0.086s)
[264.102726] Starting dynamic subtest: drm_test_damage_iter_no_damage_src_moved
[264.182839] Dynamic subtest drm_test_damage_iter_no_damage_src_moved: SUCCESS (0.089s)
[264.191397] Starting dynamic subtest: drm_test_damage_iter_no_damage_fractional_src_moved
[264.276873] Dynamic subtest drm_test_damage_iter_no_damage_fractional_src_moved: SUCCESS (0.094s)
[264.285360] Starting dynamic subtest: drm_test_damage_iter_no_damage_not_visible
[264.367184] Dynamic subtest drm_test_damage_iter_no_damage_not_visible: SUCCESS (0.089s)
[264.375581] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_crtc
[264.451343] Dynamic subtest drm_test_damage_iter_no_damage_no_crtc: SUCCESS (0.083s)
[264.464333] Starting dynamic subtest: drm_test_damage_iter_no_damage_no_fb
[264.539871] Dynamic subtest drm_test_damage_iter_no_damage_no_fb: SUCCESS (0.087s)
[264.547541] Starting dynamic subtest: drm_test_damage_iter_simple_damage
[264.626719] Dynamic subtest drm_test_damage_iter_simple_damage: SUCCESS (0.088s)
[264.635364] Starting dynamic subtest: drm_test_damage_iter_single_damage
[264.727094] Dynamic subtest drm_test_damage_iter_single_damage: SUCCESS (0.100s)
[264.734462] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_src
[264.821876] Dynamic subtest drm_test_damage_iter_single_damage_intersect_src: SUCCESS (0.094s)
[264.830507] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_src
[264.918864] Dynamic subtest drm_test_damage_iter_single_damage_outside_src: SUCCESS (0.095s)
[264.925264] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src
[265.032735] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src: SUCCESS (0.113s)
[265.041360] Starting dynamic subtest: drm_test_damage_iter_single_damage_intersect_fractional_src
[265.116023] Dynamic subtest drm_test_damage_iter_single_damage_intersect_fractional_src: SUCCESS (0.082s)
[265.124700] Starting dynamic subtest: drm_test_damage_iter_single_damage_outside_fractional_src
[265.197312] Dynamic subtest drm_test_damage_iter_single_damage_outside_fractional_src: SUCCESS (0.080s)
[265.205353] Starting dynamic subtest: drm_test_damage_iter_single_damage_src_moved
[265.279923] Dynamic subtest drm_test_damage_iter_single_damage_src_moved: SUCCESS (0.082s)
[265.288542] Starting dynamic subtest: drm_test_damage_iter_single_damage_fractional_src_moved
[265.373391] Dynamic subtest drm_test_damage_iter_single_damage_fractional_src_moved: SUCCESS (0.092s)
[265.382815] Starting dynamic subtest: drm_test_damage_iter_damage
[265.453910] Dynamic subtest drm_test_damage_iter_damage: SUCCESS (0.080s)
[265.461874] Starting dynamic subtest: drm_test_damage_iter_damage_one_intersect
[265.539447] Dynamic subtest drm_test_damage_iter_damage_one_intersect: SUCCESS (0.084s)
[265.547560] Starting dynamic subtest: drm_test_damage_iter_damage_one_outside
[265.617063] Dynamic subtest drm_test_damage_iter_damage_one_outside: SUCCESS (0.077s)
[265.625161] Starting dynamic subtest: drm_test_damage_iter_damage_src_moved
[265.710103] Dynamic subtest drm_test_damage_iter_damage_src_moved: SUCCESS (0.093s)
[265.718343] Starting dynamic subtest: drm_test_damage_iter_damage_not_visible
[265.789426] Dynamic subtest drm_test_damage_iter_damage_not_visible: SUCCESS (0.078s)
[265.790501] Subtest drm_damage_helper: SUCCESS (1.916s)
[265.939968] [023/133] (754s left) api_intel_bb (object-noreloc-purge-cache-simple)
[265.994456] Starting subtest: object-noreloc-purge-cache-simple
[265.999292] Subtest object-noreloc-purge-cache-simple: SUCCESS (0.006s)
[266.086190] [024/133] (754s left) gem_busy (extended)
[266.277301] Starting subtest: extended
[266.278475] Starting dynamic subtest: rcs0
[266.368427] Dynamic subtest rcs0: SUCCESS (0.095s)
[266.378750] Starting dynamic subtest: bcs0
[266.412361] Dynamic subtest bcs0: SUCCESS (0.043s)
[266.421499] Starting dynamic subtest: vcs0
[267.938332] Dynamic subtest vcs0: SUCCESS (1.514s)
[267.947557] Starting dynamic subtest: vcs1
[269.920558] Dynamic subtest vcs1: SUCCESS (1.980s)
[269.928439] Starting dynamic subtest: vecs0
[271.906381] Dynamic subtest vecs0: SUCCESS (1.984s)
[271.913376] Starting dynamic subtest: ccs0
[271.944627] Dynamic subtest ccs0: SUCCESS (0.046s)
[271.945805] Subtest extended: SUCCESS (5.671s)
[272.174388] [025/133] (748s left) kms_psr (pr-cursor-blt)
[272.277011] Starting subtest: pr-cursor-blt
[272.278613] Subtest pr-cursor-blt: SKIP (0.000s)
[272.342745] [026/133] (748s left) gem_fenced_exec_thrash (no-spare-fences-busy)
[272.398089] Subtest no-spare-fences-busy: SKIP (0.000s)
[272.468427] [027/133] (747s left) gem_mmap_gtt (cpuset-basic-small-copy-odd)
[272.515791] Subtest cpuset-basic-small-copy-odd: SKIP (0.000s)
[272.566490] [028/133] (747s left) kms_cdclk (mode-transition)
[272.650304] Starting subtest: mode-transition
[272.651093] Starting dynamic subtest: pipe-A-eDP-1
[272.937289] Dynamic subtest pipe-A-eDP-1: SKIP (0.286s)
[272.943866] Starting dynamic subtest: pipe-B-eDP-1
[272.945514] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[272.951723] Starting dynamic subtest: pipe-C-eDP-1
[272.953369] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[272.959696] Starting dynamic subtest: pipe-D-eDP-1
[272.961526] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[272.963200] Subtest mode-transition: SKIP (0.293s)
[273.776510] [029/133] (746s left) kms_psr (fbc-pr-sprite-mmap-cpu)
[273.857487] Starting subtest: fbc-pr-sprite-mmap-cpu
[273.859205] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s)
[273.926200] [030/133] (746s left) vgem_basic (mmap)
[273.982790] Starting subtest: mmap
[273.987953] Subtest mmap: SUCCESS (0.006s)
[274.034293] [FACT vgem_basic (mmap)] new: kernel.kmod_is_loaded.vgem: true
[274.036323] [031/133] (746s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180)
[274.132566] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180
[275.111365] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180: SUCCESS (0.979s)
[275.229942] [032/133] (745s left) kms_flip (2x-flip-vs-absolute-wf_vblank-interruptible)
[275.314819] Starting subtest: 2x-flip-vs-absolute-wf_vblank-interruptible
[275.325768] Subtest 2x-flip-vs-absolute-wf_vblank-interruptible: SKIP (0.011s)
[275.411332] [033/133] (745s left) kms_cursor_crc (cursor-sliding-max-size)
[275.725523] Starting subtest: cursor-sliding-max-size
[275.728436] Subtest cursor-sliding-max-size: SKIP (0.000s)
[275.835656] [034/133] (744s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt)
[275.925202] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt
[275.927216] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[276.024645] [035/133] (744s left) gem_pxp (create-protected-buffer)
[276.146327] Starting subtest: create-protected-buffer
[276.147700] Subtest create-protected-buffer: SUCCESS (0.000s)
[276.261996] [036/133] (744s left) kms_plane (pixel-format-4-tiled-bmg-ccs-modifier-source-clamping)
[276.341674] Starting subtest: pixel-format-4-tiled-bmg-ccs-modifier-source-clamping
[276.343700] Subtest pixel-format-4-tiled-bmg-ccs-modifier-source-clamping: SKIP (0.000s)
[276.420597] [037/133] (744s left) gem_exec_fence (syncobj-timeline-export)
[276.960856] Starting subtest: syncobj-timeline-export
[276.962567] Subtest syncobj-timeline-export: SUCCESS (0.007s)
[277.990886] [038/133] (742s left) gem_unfence_active_buffers
[278.121764] [039/133] (742s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc)
[278.212077] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc
[278.213077] Starting dynamic subtest: pipe-A-eDP-1
[278.238740] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s)
[278.251670] Starting dynamic subtest: pipe-B-eDP-1
[278.273124] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s)
[278.282540] Starting dynamic subtest: pipe-C-eDP-1
[278.303246] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s)
[278.309848] Starting dynamic subtest: pipe-D-eDP-1
[278.330267] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s)
[278.332466] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.008s)
[280.456916] [040/133] (740s left) kms_fbcon_fbt (psr-suspend)
[280.517702] Starting subtest: psr-suspend
[330.768732] Subtest psr-suspend: SUCCESS (20.597s)
[334.871740] [041/133] (690s left) kms_pm_rpm (dpms-mode-unset-lpsp)
[336.627949] Starting subtest: dpms-mode-unset-lpsp
[340.723562] Subtest dpms-mode-unset-lpsp: SUCCESS (4.080s)
[341.123690] [042/133] (683s left) i915_drm_fdinfo (busy-check-all)
[341.160409] Starting subtest: busy-check-all
[341.161252] Starting dynamic subtest: rcs0
[341.166233] Dynamic subtest rcs0: SKIP (0.005s)
[341.174636] Starting dynamic subtest: bcs0
[341.176252] Dynamic subtest bcs0: SKIP (0.000s)
[341.183961] Starting dynamic subtest: vcs0
[341.185754] Dynamic subtest vcs0: SKIP (0.000s)
[341.197781] Starting dynamic subtest: vcs1
[341.199445] Dynamic subtest vcs1: SKIP (0.000s)
[341.206753] Starting dynamic subtest: vecs0
[341.208432] Dynamic subtest vecs0: SKIP (0.000s)
[341.215748] Starting dynamic subtest: ccs0
[341.217405] Dynamic subtest ccs0: SKIP (0.000s)
[341.219112] Subtest busy-check-all: SKIP (0.012s)
[341.248251] [043/133] (683s left) kms_psr2_sf (pr-overlay-plane-move-continuous-sf)
[341.362773] Starting subtest: pr-overlay-plane-move-continuous-sf
[341.364452] Subtest pr-overlay-plane-move-continuous-sf: SKIP (0.000s)
[341.429739] [044/133] (683s left) kms_psr (psr-primary-page-flip)
[341.520962] Starting subtest: psr-primary-page-flip
[341.522007] Starting dynamic subtest: eDP-1
[343.321516] Dynamic subtest eDP-1: SUCCESS (1.795s)
[343.322450] Subtest psr-primary-page-flip: SUCCESS (1.796s)
[344.170460] [045/133] (680s left) gem_spin_batch (spin-each)
[344.223520] Starting subtest: spin-each
[347.592474] Subtest spin-each: SUCCESS (3.363s)
[349.586022] [046/133] (675s left) perf_pmu (busy-start)
[349.634193] Starting subtest: busy-start
[349.635157] Starting dynamic subtest: rcs0
[352.204206] Dynamic subtest rcs0: SUCCESS (2.554s)
[352.216793] Starting dynamic subtest: bcs0
[354.754798] Dynamic subtest bcs0: SUCCESS (2.545s)
[354.765471] Starting dynamic subtest: vcs0
[358.427179] Dynamic subtest vcs0: SUCCESS (3.671s)
[358.438194] Starting dynamic subtest: vcs1
[362.460360] Dynamic subtest vcs1: SUCCESS (4.028s)
[362.471003] Starting dynamic subtest: vecs0
[366.491062] Dynamic subtest vecs0: SUCCESS (4.026s)
[366.506278] Starting dynamic subtest: ccs0
[369.030887] Dynamic subtest ccs0: SUCCESS (2.548s)
[369.032273] Subtest busy-start: SUCCESS (19.383s)
[369.123558] [047/133] (656s left) kms_chamelium_frames (hdmi-crc-nonplanar-formats)
[369.506315] Subtest hdmi-crc-nonplanar-formats: SKIP (0.000s)
[370.289656] [048/133] (654s left) kms_feature_discovery (chamelium)
[370.387882] Starting subtest: chamelium
[370.389671] Subtest chamelium: SKIP (0.000s)
[370.459503] [049/133] (654s left) kms_atomic_transition (modeset-transition-nonblocking)
[370.543989] Starting subtest: modeset-transition-nonblocking
[370.544938] Starting dynamic subtest: 1x-outputs
[381.716908] Dynamic subtest 1x-outputs: SUCCESS (11.176s)
[381.719381] Subtest modeset-transition-nonblocking: SUCCESS (11.178s)
[382.561227] [050/133] (642s left) syncobj_eventfd (binary-wait-before-signal)
[382.613907] Starting subtest: binary-wait-before-signal
[382.644442] Subtest binary-wait-before-signal: SUCCESS (0.031s)
[382.717499] [051/133] (642s left) vgem_slow (nohang)
[382.759931] Starting subtest: nohang
[393.245614] Subtest nohang: SUCCESS (10.439s)
[393.275504] [052/133] (632s left) kms_flip (2x-absolute-wf_vblank-interruptible)
[393.373652] Starting subtest: 2x-absolute-wf_vblank-interruptible
[393.384477] Subtest 2x-absolute-wf_vblank-interruptible: SKIP (0.011s)
[393.457715] [053/133] (631s left) i915_pm_rpm (gem-idle)
[395.587547] Starting subtest: gem-idle
[401.794755] Subtest gem-idle: SUCCESS (6.202s)
[402.138671] [054/133] (623s left) dmabuf_sync_file (export-basic)
[402.187097] Starting subtest: export-basic
[402.188097] Subtest export-basic: SUCCESS (0.001s)
[402.247475] [055/133] (623s left) gem_ctx_exec (basic-close-race)
[403.388996] Starting subtest: basic-close-race
[409.245437] Subtest basic-close-race: SUCCESS (5.815s)
[409.325739] [056/133] (616s left) kms_properties (plane-properties-atomic)
[409.418012] Starting subtest: plane-properties-atomic
[409.418935] Starting dynamic subtest: pipe-A-eDP-1
[409.969733] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.550s)
[409.978492] Starting dynamic subtest: pipe-B-eDP-1
[411.201126] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.231s)
[411.210090] Starting dynamic subtest: pipe-C-eDP-1
[412.442706] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.236s)
[412.453865] Starting dynamic subtest: pipe-D-eDP-1
[413.679733] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.239s)
[413.680852] Subtest plane-properties-atomic: SUCCESS (4.260s)
[414.470145] [057/133] (611s left) gem_render_copy (linear-to-vebox-yf-tiled)
[414.523348] Starting subtest: linear-to-vebox-yf-tiled
[414.525319] Subtest linear-to-vebox-yf-tiled: SKIP (0.000s)
[414.700110] [058/133] (610s left) gen9_exec_parse (bb-secure)
[414.761774] Subtest bb-secure: SKIP (0.000s)
[414.863959] [059/133] (610s left) gem_lmem_swapping (heavy-verify-random)
[414.902436] Subtest heavy-verify-random: SKIP (0.000s)
[414.942354] [060/133] (610s left) gem_exec_schedule (wide)
[415.133420] Starting subtest: wide
[415.138572] Starting dynamic subtest: rcs0
[425.885949] Dynamic subtest rcs0: SUCCESS (10.708s)
[425.898273] Starting dynamic subtest: vcs0
[436.695413] Dynamic subtest vcs0: SUCCESS (10.846s)
[436.707622] Starting dynamic subtest: vcs1
[448.481093] Dynamic subtest vcs1: SUCCESS (11.787s)
[448.492489] Starting dynamic subtest: vecs0
[460.118151] Dynamic subtest vecs0: SUCCESS (11.637s)
[460.129624] Starting dynamic subtest: ccs0
[473.860103] Dynamic subtest ccs0: SUCCESS (13.703s)
[473.861849] Subtest wide: SUCCESS (58.687s)
[476.162446] [061/133] (549s left) kms_psr (fbc-pr-basic)
[476.249579] Starting subtest: fbc-pr-basic
[476.251283] Subtest fbc-pr-basic: SKIP (0.000s)
[476.320153] [062/133] (549s left) gem_exec_flush (basic-wb-ro-before-default)
[476.385558] Starting subtest: basic-wb-ro-before-default
[481.868658] Subtest basic-wb-ro-before-default: SUCCESS (5.482s)
[482.067618] [063/133] (543s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs-cc)
[482.158723] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs-cc
[482.159659] Starting dynamic subtest: pipe-A-eDP-1
[483.020984] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.858s)
[483.030120] Starting dynamic subtest: pipe-B-eDP-1
[484.623395] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.601s)
[484.632142] Starting dynamic subtest: pipe-C-eDP-1
[486.126796] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.502s)
[486.135871] Starting dynamic subtest: pipe-D-eDP-1
[487.635654] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.507s)
[487.636412] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs-cc: SUCCESS (5.473s)
[488.811371] [064/133] (537s left) kms_big_fb (4-tiled-8bpp-rotate-90)
[488.906256] Starting subtest: 4-tiled-8bpp-rotate-90
[488.929606] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.022s)
[489.052087] [065/133] (536s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-spr-indfb-draw-blt)
[489.145537] Starting subtest: fbcpsr-2p-scndscrn-spr-indfb-draw-blt
[489.147255] Subtest fbcpsr-2p-scndscrn-spr-indfb-draw-blt: SKIP (0.000s)
[489.242595] [066/133] (536s left) perf (polling)
[489.385697] Starting subtest: polling
[489.386657] Starting dynamic subtest: 0-rcs0
[499.433194] Dynamic subtest 0-rcs0: SUCCESS (10.035s)
[499.444249] Starting dynamic subtest: 1-vcs0
[509.491407] Dynamic subtest 1-vcs0: SUCCESS (10.018s)
[509.492725] Subtest polling: SUCCESS (20.056s)
[509.525598] [067/133] (516s left) kms_plane_alpha_blend (constant-alpha-min)
[509.616004] Starting subtest: constant-alpha-min
[509.915959] Starting dynamic subtest: pipe-A-eDP-1
[510.634740] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.718s)
[510.644154] Starting dynamic subtest: pipe-D-eDP-1
[512.091505] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.455s)
[512.092441] Subtest constant-alpha-min: SUCCESS (2.475s)
[512.969219] [068/133] (513s left) kms_rotation_crc (primary-x-tiled-reflect-x-0)
[513.062373] Starting subtest: primary-x-tiled-reflect-x-0
[520.087206] Subtest primary-x-tiled-reflect-x-0: SUCCESS (7.024s)
[521.244610] [069/133] (504s left) gem_userptr_blits (process-exit-busy)
[521.306396] Starting subtest: process-exit-busy
[521.319503] Subtest process-exit-busy: SUCCESS (0.013s)
[521.433744] [070/133] (504s left) gem_ctx_persistence (engines-queued)
[521.491813] Starting subtest: engines-queued
[521.493048] Starting dynamic subtest: rcs0
[521.556035] Dynamic subtest rcs0: SUCCESS (0.064s)
[521.568736] Starting dynamic subtest: bcs0
[521.576692] Dynamic subtest bcs0: SUCCESS (0.019s)
[521.585316] Starting dynamic subtest: vcs0
[523.450186] Dynamic subtest vcs0: SUCCESS (1.863s)
[523.464220] Starting dynamic subtest: vcs1
[525.432103] Dynamic subtest vcs1: SUCCESS (1.978s)
[525.444815] Starting dynamic subtest: vecs0
[527.481135] Dynamic subtest vecs0: SUCCESS (2.046s)
[527.492717] Starting dynamic subtest: ccs0
[527.638382] Dynamic subtest ccs0: SUCCESS (0.163s)
[527.639747] Subtest engines-queued: SUCCESS (6.145s)
[527.769853] [071/133] (498s left) sriov_basic (enable-vfs-bind-unbind-each)
[527.836482] Starting subtest: enable-vfs-bind-unbind-each
[527.838777] Starting dynamic subtest: numvfs-1
[527.879574] Dynamic subtest numvfs-1: FAIL (0.024s)
[527.889437] Starting dynamic subtest: numvfs-2
[527.916142] Dynamic subtest numvfs-2: FAIL (0.004s)
[527.924596] Starting dynamic subtest: numvfs-3
[527.951969] Dynamic subtest numvfs-3: FAIL (0.004s)
[527.960511] Starting dynamic subtest: numvfs-4
[527.988887] Dynamic subtest numvfs-4: FAIL (0.004s)
[527.998229] Starting dynamic subtest: numvfs-5
[528.026339] Dynamic subtest numvfs-5: FAIL (0.004s)
[528.034599] Starting dynamic subtest: numvfs-6
[528.060306] Dynamic subtest numvfs-6: FAIL (0.004s)
[528.068473] Starting dynamic subtest: numvfs-7
[528.094056] Dynamic subtest numvfs-7: FAIL (0.004s)
[528.102153] Starting dynamic subtest: numvfs-random
[528.127217] Dynamic subtest numvfs-random: FAIL (0.004s)
[528.128035] Subtest enable-vfs-bind-unbind-each: FAIL (0.057s)
[528.160227] [072/133] (497s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-draw-pwrite)
[528.265435] Starting subtest: psr-2p-scndscrn-spr-indfb-draw-pwrite
[528.267051] Subtest psr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[528.361280] [073/133] (497s left) kms_prime (basic-crc-vgem)
[528.436284] Starting subtest: basic-crc-vgem
[528.437129] Starting dynamic subtest: second-to-first
[529.489702] Dynamic subtest second-to-first: SUCCESS (1.054s)
[529.491806] Subtest basic-crc-vgem: SUCCESS (1.055s)
[529.580413] [074/133] (496s left) gem_partial_pwrite_pread (reads-display)
[529.675094] Subtest reads-display: SKIP (0.000s)
[529.737990] [075/133] (496s left) kms_properties (get_properties-sanity-non-atomic)
[529.834035] Starting subtest: get_properties-sanity-non-atomic
[529.836438] Subtest get_properties-sanity-non-atomic: SUCCESS (0.001s)
[529.904322] [076/133] (496s left) gem_exec_reloc (basic-write-wc-active)
[529.952874] Subtest basic-write-wc-active: SKIP (0.000s)
[530.017814] [077/133] (496s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt)
[530.120740] Starting subtest: fbc-rgb565-draw-mmap-gtt
[530.122572] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s)
[530.209974] [078/133] (496s left) gem_exec_schedule (pi-distinct-iova)
[530.388377] Starting subtest: pi-distinct-iova
[530.389425] Starting dynamic subtest: rcs0
[530.465614] Dynamic subtest rcs0: SUCCESS (0.083s)
[530.478002] Starting dynamic subtest: vcs0
[530.526921] Dynamic subtest vcs0: SUCCESS (0.061s)
[530.540418] Starting dynamic subtest: vcs1
[530.588137] Dynamic subtest vcs1: SUCCESS (0.061s)
[530.598723] Starting dynamic subtest: vecs0
[530.654324] Dynamic subtest vecs0: SUCCESS (0.064s)
[530.665931] Starting dynamic subtest: ccs0
[530.718688] Dynamic subtest ccs0: SUCCESS (0.064s)
[530.719612] Subtest pi-distinct-iova: SUCCESS (0.337s)
[532.582980] [079/133] (493s left) kms_flip (2x-flip-vs-suspend-interruptible)
[532.668351] Starting subtest: 2x-flip-vs-suspend-interruptible
[532.679953] Subtest 2x-flip-vs-suspend-interruptible: SKIP (0.011s)
[532.756400] [080/133] (493s left) vgem_basic (dmabuf-fence)
[532.798351] Starting subtest: dmabuf-fence
[532.799230] Subtest dmabuf-fence: SUCCESS (0.000s)
[532.852524] [081/133] (493s left) kms_big_fb (yf-tiled-64bpp-rotate-0)
[532.958329] Starting subtest: yf-tiled-64bpp-rotate-0
[532.960103] Subtest yf-tiled-64bpp-rotate-0: SKIP (0.000s)
[533.055832] [082/133] (493s left) gem_ctx_shared (single-timeline)
[533.114291] Starting subtest: single-timeline
[533.128495] Subtest single-timeline: SUCCESS (0.014s)
[533.349827] [083/133] (493s left) gem_exec_reloc (basic-cpu-noreloc)
[533.407921] Subtest basic-cpu-noreloc: SKIP (0.000s)
[533.468197] [084/133] (493s left) gem_render_copy (y-tiled-ccs-to-yf-tiled)
[533.518812] Starting subtest: y-tiled-ccs-to-yf-tiled
[533.520461] Subtest y-tiled-ccs-to-yf-tiled: SKIP (0.000s)
[533.697033] [085/133] (492s left) gem_ctx_param (get-priority-new-ctx)
[533.752592] Starting subtest: get-priority-new-ctx
[533.753874] Subtest get-priority-new-ctx: SUCCESS (0.001s)
[533.859999] [086/133] (492s left) kms_big_fb (x-tiled-8bpp-rotate-0)
[533.959933] Starting subtest: x-tiled-8bpp-rotate-0
[535.185864] Subtest x-tiled-8bpp-rotate-0: SUCCESS (1.225s)
[535.317406] [087/133] (491s left) gem_mmap_gtt (big-bo-tiledy)
[535.376439] Subtest big-bo-tiledY: SKIP (0.000s)
[535.432336] [088/133] (491s left) syncobj_wait (multi-wait-for-submit-unsubmitted-signaled)
[535.485836] Starting subtest: multi-wait-for-submit-unsubmitted-signaled
[535.486868] Subtest multi-wait-for-submit-unsubmitted-signaled: SUCCESS (0.000s)
[535.596244] [089/133] (491s left) kms_vrr (flip-suspend)
[535.691803] Starting subtest: flip-suspend
[535.694451] Subtest flip-suspend: SKIP (0.000s)
[535.773991] [090/133] (490s left) kms_lease (lease-invalid-crtc)
[535.857830] Starting subtest: lease-invalid-crtc
[535.858638] Starting dynamic subtest: pipe-A-eDP-1
[535.859594] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.000s)
[535.868332] Starting dynamic subtest: pipe-B-eDP-1
[535.869227] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.000s)
[535.877682] Starting dynamic subtest: pipe-C-eDP-1
[535.878509] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[535.886747] Starting dynamic subtest: pipe-D-eDP-1
[535.887559] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[535.888447] Subtest lease-invalid-crtc: SUCCESS (0.004s)
[535.929816] [091/133] (490s left) kms_invalid_mode (bad-htotal)
[536.015208] Starting subtest: bad-htotal
[536.016086] Starting dynamic subtest: pipe-A-eDP-1
[536.302512] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.286s)
[536.311271] Starting dynamic subtest: pipe-B-eDP-1
[536.312135] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s)
[536.320344] Starting dynamic subtest: pipe-C-eDP-1
[536.321245] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.000s)
[536.329779] Starting dynamic subtest: pipe-D-eDP-1
[536.330680] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.000s)
[536.331507] Subtest bad-htotal: SUCCESS (0.291s)
[537.167272] [092/133] (489s left) syncobj_timeline (wait-delayed-signal)
[537.218424] Starting subtest: wait-delayed-signal
[537.322512] Subtest wait-delayed-signal: SUCCESS (0.103s)
[537.406700] [093/133] (489s left) kms_busy (extended-modeset-hang-newfb)
[537.504814] Starting subtest: extended-modeset-hang-newfb
[537.505911] Starting dynamic subtest: pipe-A
[572.482062] Dynamic subtest pipe-A: SUCCESS (34.972s)
[572.496334] Starting dynamic subtest: pipe-D
[595.435813] Dynamic subtest pipe-D: SUCCESS (22.954s)
[595.436722] Subtest extended-modeset-hang-newfb: SUCCESS (57.932s)
[596.230248] [094/133] (430s left) gem_exec_schedule (smoketest)
[596.405766] Starting subtest: smoketest
[596.406960] Starting dynamic subtest: rcs0
[601.962439] Dynamic subtest rcs0: SUCCESS (5.492s)
[601.973350] Starting dynamic subtest: bcs0
[607.513728] Dynamic subtest bcs0: SUCCESS (5.540s)
[607.525973] Starting dynamic subtest: vcs0
[612.995328] Dynamic subtest vcs0: SUCCESS (5.488s)
[613.007378] Starting dynamic subtest: vcs1
[618.449797] Dynamic subtest vcs1: SUCCESS (5.499s)
[618.466516] Starting dynamic subtest: vecs0
[623.997109] Dynamic subtest vecs0: SUCCESS (5.501s)
[624.007761] Starting dynamic subtest: ccs0
[629.516669] Dynamic subtest ccs0: SUCCESS (5.519s)
[629.517530] Subtest smoketest: SUCCESS (33.045s)
[629.636364] [095/133] (397s left) kms_vblank (query-forked-hang)
[629.729899] Starting subtest: query-forked-hang
[629.730765] Starting dynamic subtest: pipe-A-eDP-1
[656.213246] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.480s)
[656.224689] Starting dynamic subtest: pipe-D-eDP-1
[681.229924] Dynamic subtest pipe-D-eDP-1: SUCCESS (25.010s)
[681.230814] Subtest query-forked-hang: SUCCESS (51.495s)
[682.100545] [096/133] (344s left) kms_flip (2x-flip-vs-rmfb-interruptible)
[682.191244] Starting subtest: 2x-flip-vs-rmfb-interruptible
[682.203140] Subtest 2x-flip-vs-rmfb-interruptible: SKIP (0.011s)
[682.279261] [097/133] (344s left) kms_getfb (getfb-repeated-different-handles)
[682.369866] Starting subtest: getfb-repeated-different-handles
[682.370723] Subtest getfb-repeated-different-handles: SUCCESS (0.000s)
[682.468322] [098/133] (344s left) gem_ppgtt (blt-vs-render-ctxn)
[682.530456] Starting subtest: blt-vs-render-ctxN
[715.409177] Subtest blt-vs-render-ctxN: SUCCESS (32.864s)
[715.530015] [099/133] (311s left) syncobj_wait (single-wait-all-signaled)
[715.627935] Starting subtest: single-wait-all-signaled
[715.628954] Subtest single-wait-all-signaled: SUCCESS (0.000s)
[715.723075] [100/133] (311s left) kms_addfb_basic (clobberred-modifier)
[715.775420] Starting subtest: clobberred-modifier
[715.777919] Subtest clobberred-modifier: SKIP (0.000s)
[715.911071] [101/133] (311s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted-signaled)
[715.955920] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted-signaled
[715.956774] Subtest invalid-multi-wait-all-unsubmitted-submitted-signaled: SUCCESS (0.000s)
[716.067701] [102/133] (311s left) kms_cursor_crc (cursor-rapid-movement-256x256)
[716.309723] Starting subtest: cursor-rapid-movement-256x256
[716.313812] Starting dynamic subtest: pipe-A-eDP-1
[716.888934] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.576s)
[716.921069] Starting dynamic subtest: pipe-D-eDP-1
[718.121916] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.208s)
[718.122748] Subtest cursor-rapid-movement-256x256: SUCCESS (1.811s)
[718.962330] [103/133] (308s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt)
[719.052723] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt
[719.054303] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s)
[719.150216] [104/133] (308s left) gem_tiled_pread_basic (basic)
[719.209459] Subtest basic: SKIP (0.000s)
[719.266394] [105/133] (308s left) kms_ccs (bad-pixel-format-y-tiled-ccs)
[719.359472] Starting subtest: bad-pixel-format-y-tiled-ccs
[719.360441] Starting dynamic subtest: pipe-A-eDP-1
[719.369084] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s)
[719.378333] Starting dynamic subtest: pipe-B-eDP-1
[719.386892] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s)
[719.395686] Starting dynamic subtest: pipe-C-eDP-1
[719.403889] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s)
[719.412316] Starting dynamic subtest: pipe-D-eDP-1
[719.420784] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s)
[719.422390] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.005s)
[721.637072] [106/133] (305s left) kms_plane (pixel-format-4-tiled-modifier-source-clamping)
[721.720281] Starting subtest: pixel-format-4-tiled-modifier-source-clamping
[721.749697] Starting dynamic subtest: pipe-A-plane-0
[722.404755] Dynamic subtest pipe-A-plane-0: SUCCESS (0.655s)
[722.413758] Starting dynamic subtest: pipe-A-plane-3
[722.816161] Dynamic subtest pipe-A-plane-3: SUCCESS (0.410s)
[723.924839] Starting dynamic subtest: pipe-B-plane-0
[724.560398] Dynamic subtest pipe-B-plane-0: SUCCESS (0.644s)
[724.570805] Starting dynamic subtest: pipe-B-plane-3
[724.993537] Dynamic subtest pipe-B-plane-3: SUCCESS (0.431s)
[725.314151] Subtest pixel-format-4-tiled-modifier-source-clamping: SUCCESS (3.593s)
[726.110980] [107/133] (301s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-pwrite)
[726.218259] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-pwrite
[726.219981] Subtest fbc-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[726.320165] [108/133] (301s left) kms_frontbuffer_tracking (psr-1p-rte)
[726.416164] Starting subtest: psr-1p-rte
[731.830538] Subtest psr-1p-rte: SUCCESS (5.415s)
[731.958910] [109/133] (295s left) gem_reg_read (timestamp-moving)
[732.011923] Starting subtest: timestamp-moving
[733.022003] Subtest timestamp-moving: SUCCESS (1.001s)
[733.086636] [110/133] (294s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-draw-mmap-gtt)
[733.178884] Starting subtest: psr-1p-primscrn-cur-indfb-draw-mmap-gtt
[733.180812] Subtest psr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s)
[733.263328] [111/133] (294s left) syncobj_timeline (wait-all-interrupted)
[733.314144] Starting subtest: wait-all-interrupted
[733.414985] Subtest wait-all-interrupted: SUCCESS (0.101s)
[733.487348] [112/133] (294s left) kms_frontbuffer_tracking (fbc-1p-offscreen-pri-indfb-draw-mmap-cpu)
[733.577037] Starting subtest: fbc-1p-offscreen-pri-indfb-draw-mmap-cpu
[737.725619] Subtest fbc-1p-offscreen-pri-indfb-draw-mmap-cpu: SUCCESS (4.149s)
[738.883082] [113/133] (288s left) gem_exec_reloc (basic-cpu-wc)
[738.935128] Subtest basic-cpu-wc: SKIP (0.000s)
[739.006170] [114/133] (288s left) kms_psr (fbc-psr-cursor-render)
[739.097662] Starting subtest: fbc-psr-cursor-render
[739.098466] Starting dynamic subtest: eDP-1
[739.100037] Dynamic subtest eDP-1: SKIP (0.000s)
[739.101705] Subtest fbc-psr-cursor-render: SKIP (0.001s)
[739.169553] [115/133] (288s left) i915_pciid
[739.307800] [116/133] (288s left) kms_content_protection (uevent)
[739.444656] Starting subtest: uevent
[739.446352] Subtest uevent: SKIP (0.000s)
[739.565375] [117/133] (288s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-spr-indfb-draw-pwrite)
[739.648522] Starting subtest: fbcpsr-2p-primscrn-spr-indfb-draw-pwrite
[739.650382] Subtest fbcpsr-2p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s)
[739.747027] [118/133] (288s left) syncobj_wait (invalid-reset-illegal-handle)
[739.800429] Starting subtest: invalid-reset-illegal-handle
[739.801397] Subtest invalid-reset-illegal-handle: SUCCESS (0.000s)
[739.919714] [119/133] (287s left) kms_frontbuffer_tracking (psr-2p-scndscrn-indfb-plflip-blt)
[740.021149] Starting subtest: psr-2p-scndscrn-indfb-plflip-blt
[740.022973] Subtest psr-2p-scndscrn-indfb-plflip-blt: SKIP (0.000s)
[740.123680] [120/133] (287s left) kms_getfb (getfb2-handle-zero)
[740.213122] Starting subtest: getfb2-handle-zero
[740.214011] Subtest getfb2-handle-zero: SUCCESS (0.000s)
[740.333852] [121/133] (287s left) i915_selftest (live)
[743.695548] Starting subtest: live
[743.696769] Starting dynamic subtest: sanitycheck
[748.208548] Dynamic subtest sanitycheck: SUCCESS (4.503s)
[748.216931] Starting dynamic subtest: uncore
[752.569401] Dynamic subtest uncore: SUCCESS (4.357s)
[752.579217] Starting dynamic subtest: workarounds
[755.772975] Dynamic subtest workarounds: SUCCESS (3.203s)
[755.782618] Starting dynamic subtest: gt_engines
[760.423433] Dynamic subtest gt_engines: SUCCESS (4.648s)
[760.433121] Starting dynamic subtest: gt_timelines
[778.127012] Dynamic subtest gt_timelines: SUCCESS (17.698s)
[778.135807] Starting dynamic subtest: gt_contexts
[782.526319] Dynamic subtest gt_contexts: SUCCESS (4.397s)
[782.535907] Starting dynamic subtest: gt_lrc
[786.906836] Dynamic subtest gt_lrc: SUCCESS (4.377s)
[786.916391] Starting dynamic subtest: gt_mocs
[791.374384] Dynamic subtest gt_mocs: SUCCESS (4.465s)
[791.384521] Starting dynamic subtest: gt_pm
[798.204182] Dynamic subtest gt_pm: SUCCESS (6.827s)
[798.214127] Starting dynamic subtest: gt_heartbeat
[802.652107] Dynamic subtest gt_heartbeat: SUCCESS (4.445s)
[802.662554] Starting dynamic subtest: gt_tlb
[811.975127] Dynamic subtest gt_tlb: SUCCESS (9.318s)
[811.984594] Starting dynamic subtest: requests
[825.468868] Dynamic subtest requests: SUCCESS (13.491s)
[825.478357] Starting dynamic subtest: migrate
[833.321120] Dynamic subtest migrate: SUCCESS (7.854s)
[833.330824] Starting dynamic subtest: active
[837.685186] Dynamic subtest active: SUCCESS (4.372s)
[837.694473] Starting dynamic subtest: objects
[842.640925] Dynamic subtest objects: SUCCESS (4.946s)
[842.650819] Starting dynamic subtest: mman
[847.491528] Dynamic subtest mman: SUCCESS (4.849s)
[847.502403] Starting dynamic subtest: dmabuf
[851.916544] Dynamic subtest dmabuf: SUCCESS (4.424s)
[851.927001] Starting dynamic subtest: vma
[856.329535] Dynamic subtest vma: SUCCESS (4.411s)
[856.339739] Starting dynamic subtest: coherency
[861.333118] Dynamic subtest coherency: SUCCESS (5.001s)
[861.343513] Starting dynamic subtest: gtt
[871.642405] Dynamic subtest gtt: SUCCESS (10.309s)
[871.652122] Starting dynamic subtest: gem
[879.206038] Dynamic subtest gem: SUCCESS (7.563s)
[879.216835] Starting dynamic subtest: evict
[884.809124] Dynamic subtest evict: SUCCESS (5.600s)
[884.819407] Starting dynamic subtest: hugepages
[893.775909] Dynamic subtest hugepages: SUCCESS (8.965s)
[893.785631] Starting dynamic subtest: gem_contexts
[922.755901] Dynamic subtest gem_contexts: SUCCESS (28.978s)
[922.765849] Starting dynamic subtest: client
[927.189906] Dynamic subtest client: SUCCESS (4.433s)
[927.199819] Starting dynamic subtest: gem_migrate
[931.700260] Dynamic subtest gem_migrate: SUCCESS (4.508s)
[931.709951] Starting dynamic subtest: reset
[936.136387] Dynamic subtest reset: SUCCESS (4.433s)
[936.146052] Starting dynamic subtest: memory_region
[940.552616] Dynamic subtest memory_region: SUCCESS (4.414s)
[940.562133] Starting dynamic subtest: hangcheck
[953.655010] Dynamic subtest hangcheck: SUCCESS (13.101s)
[953.665024] Starting dynamic subtest: execlists
[958.057942] Dynamic subtest execlists: SUCCESS (4.402s)
[958.067385] Starting dynamic subtest: ring_submission
[962.426157] Dynamic subtest ring_submission: SUCCESS (4.365s)
[962.436051] Starting dynamic subtest: perf
[966.872096] Dynamic subtest perf: SUCCESS (4.443s)
[966.881085] Starting dynamic subtest: slpc
[980.993539] Dynamic subtest slpc: SUCCESS (14.120s)
[981.003243] Starting dynamic subtest: guc
[984.954468] Dynamic subtest guc: SUCCESS (3.959s)
[984.962907] Starting dynamic subtest: guc_multi_lrc
[989.369767] Dynamic subtest guc_multi_lrc: SUCCESS (4.412s)
[989.379495] Starting dynamic subtest: guc_hang
[994.288584] Dynamic subtest guc_hang: SUCCESS (4.915s)
[994.298892] Starting dynamic subtest: late_gt_pm
[998.668383] Dynamic subtest late_gt_pm: SUCCESS (4.378s)
[998.669893] Subtest live: SUCCESS (254.952s)
[998.693027] [FACT i915_selftest (live)] deleted: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[998.693767] [FACT i915_selftest (live)] deleted: kernel.kmod_is_loaded.i915: true
[998.695458] [122/133] ( 29s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)
[1000.337841] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling
[1000.340496] Starting dynamic subtest: pipe-A-default-mode
[1001.527140] Dynamic subtest pipe-A-default-mode: SKIP (1.185s)
[1001.528919] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.188s)
[1001.656605] [FACT kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0
[1001.657973] [FACT kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling)] new: kernel.kmod_is_loaded.i915: true
[1001.660823] [123/133] ( 26s left) kms_chamelium_hpd (hdmi-hpd-with-enabled-mode)
[1002.034684] Subtest hdmi-hpd-with-enabled-mode: SKIP (0.000s)
[1002.805081] [124/133] ( 25s left) gem_fence_thrash (bo-copy)
[1002.837116] Starting subtest: bo-copy
[1002.852286] Subtest bo-copy: SKIP (0.013s)
[1002.913373] [125/133] ( 25s left) syncobj_timeline (invalid-transfer-illegal-handle)
[1002.967852] Starting subtest: invalid-transfer-illegal-handle
[1002.969041] Subtest invalid-transfer-illegal-handle: SUCCESS (0.000s)
[1003.059257] [126/133] ( 25s left) kms_big_fb (yf-tiled-16bpp-rotate-0)
[1003.163971] Starting subtest: yf-tiled-16bpp-rotate-0
[1003.165726] Subtest yf-tiled-16bpp-rotate-0: SKIP (0.000s)
[1003.264719] [127/133] ( 24s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-onoff)
[1003.354880] Starting subtest: fbc-2p-scndscrn-spr-indfb-onoff
[1003.356496] Subtest fbc-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s)
[1003.483986] [128/133] ( 24s left) kms_cursor_legacy (2x-long-flip-vs-cursor-legacy)
[1003.592778] Starting subtest: 2x-long-flip-vs-cursor-legacy
[1003.595696] Subtest 2x-long-flip-vs-cursor-legacy: SKIP (0.001s)
[1003.736644] [129/133] ( 24s left) kms_color_pipeline (plane-lut1d-post-ctm3x4)
[1003.822418] Starting subtest: plane-lut1d-post-ctm3x4
[1003.993397] Starting dynamic subtest: pipe-A-plane-0
[1004.159722] Dynamic subtest pipe-A-plane-0: SUCCESS (0.166s)
[1004.168695] Starting dynamic subtest: pipe-A-plane-1
[1004.326361] Dynamic subtest pipe-A-plane-1: SUCCESS (0.166s)
[1004.335753] Starting dynamic subtest: pipe-A-plane-2
[1004.493073] Dynamic subtest pipe-A-plane-2: SUCCESS (0.166s)
[1005.769428] Starting dynamic subtest: pipe-B-plane-0
[1005.961535] Dynamic subtest pipe-B-plane-0: SUCCESS (0.200s)
[1005.970392] Starting dynamic subtest: pipe-B-plane-1
[1006.128080] Dynamic subtest pipe-B-plane-1: SUCCESS (0.166s)
[1006.136994] Starting dynamic subtest: pipe-B-plane-2
[1006.294717] Dynamic subtest pipe-B-plane-2: SUCCESS (0.166s)
[1007.462522] Starting dynamic subtest: pipe-C-plane-0
[1007.598722] Dynamic subtest pipe-C-plane-0: SUCCESS (0.144s)
[1007.607675] Starting dynamic subtest: pipe-C-plane-1
[1007.743326] Dynamic subtest pipe-C-plane-1: SUCCESS (0.144s)
[1007.752062] Starting dynamic subtest: pipe-C-plane-2
[1007.887744] Dynamic subtest pipe-C-plane-2: SUCCESS (0.143s)
[1009.097002] Starting dynamic subtest: pipe-D-plane-0
[1009.231915] Dynamic subtest pipe-D-plane-0: SUCCESS (0.144s)
[1009.242889] Starting dynamic subtest: pipe-D-plane-1
[1009.375818] Dynamic subtest pipe-D-plane-1: SUCCESS (0.143s)
[1009.384432] Starting dynamic subtest: pipe-D-plane-2
[1009.531505] Dynamic subtest pipe-D-plane-2: SUCCESS (0.155s)
[1009.817578] Subtest plane-lut1d-post-ctm3x4: SUCCESS (5.994s)
[1010.674222] [130/133] ( 17s left) kms_addfb_basic (addfb25-y-tiled-legacy)
[1010.767008] Starting subtest: addfb25-y-tiled-legacy
[1010.767829] Subtest addfb25-y-tiled-legacy: SUCCESS (0.000s)
[1010.862490] [131/133] ( 17s left) kms_plane_scaling (plane-downscale-factor-0-5-with-modifiers)
[1010.956951] Starting subtest: plane-downscale-factor-0-5-with-modifiers
[1010.957905] Starting dynamic subtest: pipe-A
[1010.974598] Dynamic subtest pipe-A: SKIP (0.017s)
[1010.983318] Starting dynamic subtest: pipe-B
[1011.011809] Dynamic subtest pipe-B: SKIP (0.035s)
[1011.021354] Starting dynamic subtest: pipe-C
[1011.059922] Dynamic subtest pipe-C: SKIP (0.048s)
[1011.069860] Starting dynamic subtest: pipe-D
[1011.096553] Dynamic subtest pipe-D: SKIP (0.037s)
[1011.098243] Subtest plane-downscale-factor-0-5-with-modifiers: SKIP (0.140s)
[1011.203681] [132/133] ( 17s left) api_intel_allocator (standalone)
[1011.257742] Starting subtest: standalone
[1011.276521] Subtest standalone: SUCCESS (0.020s)
[1011.390320] [133/133] ( 16s left) gem_exec_balancer (full-late-pulse)
[1011.455168] Starting subtest: full-late-pulse
[1016.484786] Subtest full-late-pulse: SUCCESS (5.026s)
[1016.807043] Closing watchdogs
results: parsing output: 0/ for test: kms_cursor_legacy
results: parsing output: 1/ for test: kms_psr2_sf
results: parsing output: 2/ for test: kms_cursor_legacy
results: parsing output: 3/ for test: kms_psr
results: parsing output: 4/ for test: gem_exec_gttfill
results: parsing output: 5/ for test: kms_ccs
results: parsing output: 6/ for test: kms_frontbuffer_tracking
results: parsing output: 7/ for test: kms_force_connector_basic
results: parsing output: 8/ for test: kms_cursor_crc
results: parsing output: 9/ for test: gem_ctx_shared
results: parsing output: 10/ for test: gem_exec_reloc
results: parsing output: 11/ for test: kms_frontbuffer_tracking
results: parsing output: 12/ for test: syncobj_wait
results: parsing output: 13/ for test: kms_big_fb
results: parsing output: 14/ for test: kms_setmode
results: parsing output: 15/ for test: syncobj_timeline
results: parsing output: 16/ for test: gem_mmap_offset
results: parsing output: 17/ for test: kms_plane_scaling
results: parsing output: 18/ for test: syncobj_timeline
results: parsing output: 19/ for test: gem_set_tiling_vs_pwrite
results: parsing output: 20/ for test: gem_exec_balancer
results: parsing output: 21/ for test: kms_selftest
results: parsing output: 22/ for test: api_intel_bb
results: parsing output: 23/ for test: gem_busy
results: parsing output: 24/ for test: kms_psr
results: parsing output: 25/ for test: gem_fenced_exec_thrash
results: parsing output: 26/ for test: gem_mmap_gtt
results: parsing output: 27/ for test: kms_cdclk
results: parsing output: 28/ for test: kms_psr
results: parsing output: 29/ for test: vgem_basic
results: parsing output: 30/ for test: kms_big_fb
results: parsing output: 31/ for test: kms_flip
results: parsing output: 32/ for test: kms_cursor_crc
results: parsing output: 33/ for test: kms_frontbuffer_tracking
results: parsing output: 34/ for test: gem_pxp
results: parsing output: 35/ for test: kms_plane
results: parsing output: 36/ for test: gem_exec_fence
results: parsing output: 37/ for test: gem_unfence_active_buffers
results: parsing output: 38/ for test: kms_ccs
results: parsing output: 39/ for test: kms_fbcon_fbt
results: parsing output: 40/ for test: kms_pm_rpm
results: parsing output: 41/ for test: i915_drm_fdinfo
results: parsing output: 42/ for test: kms_psr2_sf
results: parsing output: 43/ for test: kms_psr
results: parsing output: 44/ for test: gem_spin_batch
results: parsing output: 45/ for test: perf_pmu
results: parsing output: 46/ for test: kms_chamelium_frames
results: parsing output: 47/ for test: kms_feature_discovery
results: parsing output: 48/ for test: kms_atomic_transition
results: parsing output: 49/ for test: syncobj_eventfd
results: parsing output: 50/ for test: vgem_slow
results: parsing output: 51/ for test: kms_flip
results: parsing output: 52/ for test: i915_pm_rpm
results: parsing output: 53/ for test: dmabuf_sync_file
results: parsing output: 54/ for test: gem_ctx_exec
results: parsing output: 55/ for test: kms_properties
results: parsing output: 56/ for test: gem_render_copy
results: parsing output: 57/ for test: gen9_exec_parse
results: parsing output: 58/ for test: gem_lmem_swapping
results: parsing output: 59/ for test: gem_exec_schedule
results: parsing output: 60/ for test: kms_psr
results: parsing output: 61/ for test: gem_exec_flush
results: parsing output: 62/ for test: kms_ccs
results: parsing output: 63/ for test: kms_big_fb
results: parsing output: 64/ for test: kms_frontbuffer_tracking
results: parsing output: 65/ for test: perf
results: parsing output: 66/ for test: kms_plane_alpha_blend
results: parsing output: 67/ for test: kms_rotation_crc
results: parsing output: 68/ for test: gem_userptr_blits
results: parsing output: 69/ for test: gem_ctx_persistence
results: parsing output: 70/ for test: sriov_basic
results: parsing output: 71/ for test: kms_frontbuffer_tracking
results: parsing output: 72/ for test: kms_prime
results: parsing output: 73/ for test: gem_partial_pwrite_pread
results: parsing output: 74/ for test: kms_properties
results: parsing output: 75/ for test: gem_exec_reloc
results: parsing output: 76/ for test: kms_frontbuffer_tracking
results: parsing output: 77/ for test: gem_exec_schedule
results: parsing output: 78/ for test: kms_flip
results: parsing output: 79/ for test: vgem_basic
results: parsing output: 80/ for test: kms_big_fb
results: parsing output: 81/ for test: gem_ctx_shared
results: parsing output: 82/ for test: gem_exec_reloc
results: parsing output: 83/ for test: gem_render_copy
results: parsing output: 84/ for test: gem_ctx_param
results: parsing output: 85/ for test: kms_big_fb
results: parsing output: 86/ for test: gem_mmap_gtt
results: parsing output: 87/ for test: syncobj_wait
results: parsing output: 88/ for test: kms_vrr
results: parsing output: 89/ for test: kms_lease
results: parsing output: 90/ for test: kms_invalid_mode
results: parsing output: 91/ for test: syncobj_timeline
results: parsing output: 92/ for test: kms_busy
results: parsing output: 93/ for test: gem_exec_schedule
results: parsing output: 94/ for test: kms_vblank
results: parsing output: 95/ for test: kms_flip
results: parsing output: 96/ for test: kms_getfb
results: parsing output: 97/ for test: gem_ppgtt
results: parsing output: 98/ for test: syncobj_wait
results: parsing output: 99/ for test: kms_addfb_basic
results: parsing output: 100/ for test: syncobj_wait
results: parsing output: 101/ for test: kms_cursor_crc
results: parsing output: 102/ for test: kms_frontbuffer_tracking
results: parsing output: 103/ for test: gem_tiled_pread_basic
results: parsing output: 104/ for test: kms_ccs
results: parsing output: 105/ for test: kms_plane
results: parsing output: 106/ for test: kms_frontbuffer_tracking
results: parsing output: 107/ for test: kms_frontbuffer_tracking
results: parsing output: 108/ for test: gem_reg_read
results: parsing output: 109/ for test: kms_frontbuffer_tracking
results: parsing output: 110/ for test: syncobj_timeline
results: parsing output: 111/ for test: kms_frontbuffer_tracking
results: parsing output: 112/ for test: gem_exec_reloc
results: parsing output: 113/ for test: kms_psr
results: parsing output: 114/ for test: i915_pciid
results: parsing output: 115/ for test: kms_content_protection
results: parsing output: 116/ for test: kms_frontbuffer_tracking
results: parsing output: 117/ for test: syncobj_wait
results: parsing output: 118/ for test: kms_frontbuffer_tracking
results: parsing output: 119/ for test: kms_getfb
results: parsing output: 120/ for test: i915_selftest
results: parsing output: 121/ for test: kms_flip_scaled_crc
results: parsing output: 122/ for test: kms_chamelium_hpd
results: parsing output: 123/ for test: gem_fence_thrash
results: parsing output: 124/ for test: syncobj_timeline
results: parsing output: 125/ for test: kms_big_fb
results: parsing output: 126/ for test: kms_frontbuffer_tracking
results: parsing output: 127/ for test: kms_cursor_legacy
results: parsing output: 128/ for test: kms_color_pipeline
results: parsing output: 129/ for test: kms_addfb_basic
results: parsing output: 130/ for test: kms_plane_scaling
results: parsing output: 131/ for test: api_intel_allocator
results: parsing output: 132/ for test: gem_exec_balancer
Done.