[91.084378] Initializing watchdogs [91.084646] /dev/watchdog0 [91.125786] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600] [91.140651] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [91.147588] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [91.156239] [001/133] (960s left) gem_exec_whisper (basic-sync-all) [91.315875] Starting subtest: basic-sync-all [99.273409] Subtest basic-sync-all: SUCCESS (7.958s) [99.553570] [002/133] (952s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen) [99.765218] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen [101.828453] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SUCCESS (2.067s) [102.062366] [003/133] (949s left) kms_flip (plain-flip-interruptible) [102.254456] Starting subtest: plain-flip-interruptible [102.257863] Starting dynamic subtest: A-HDMI-A1 [103.463985] Dynamic subtest A-HDMI-A1: SUCCESS (1.205s) [103.484517] Starting dynamic subtest: B-HDMI-A1 [104.323767] Dynamic subtest B-HDMI-A1: SUCCESS (0.857s) [104.344863] Starting dynamic subtest: C-HDMI-A1 [105.190308] Dynamic subtest C-HDMI-A1: SUCCESS (0.863s) [105.210668] Starting dynamic subtest: A-HDMI-A2 [106.068812] Dynamic subtest A-HDMI-A2: SUCCESS (0.875s) [106.088875] Starting dynamic subtest: B-HDMI-A2 [106.939058] Dynamic subtest B-HDMI-A2: SUCCESS (0.869s) [106.960327] Starting dynamic subtest: C-HDMI-A2 [107.801099] Dynamic subtest C-HDMI-A2: SUCCESS (0.857s) [107.841779] Subtest plain-flip-interruptible: SUCCESS (5.546s) [108.333444] [004/133] (943s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-exceed-fully-sf) [108.532458] Subtest fbc-pr-cursor-plane-move-continuous-exceed-fully-sf: SKIP (0.000s) [108.632553] [005/133] (943s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-blt) [108.834267] Starting subtest: fbcpsr-rgb565-draw-blt [108.841681] Subtest fbcpsr-rgb565-draw-blt: SKIP (0.000s) [109.001869] [006/133] (943s left) kms_rotation_crc (bad-tiling) [109.187419] Starting subtest: bad-tiling [115.360028] Subtest bad-tiling: SUCCESS (6.175s) [115.637466] [007/133] (936s left) api_intel_allocator (two-level-inception) [115.760995] Starting subtest: two-level-inception [121.338379] Subtest two-level-inception: SUCCESS (5.579s) [121.502556] [008/133] (930s left) gem_exec_reloc (basic-wc-gtt-active) [121.621160] Starting subtest: basic-wc-gtt-active [121.757727] Subtest basic-wc-gtt-active: SUCCESS (0.135s) [122.033792] [009/133] (930s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [122.246259] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [122.253103] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [122.420680] [010/133] (930s left) gem_mmap_gtt (cpuset-basic-small-copy-odd) [122.530201] Starting subtest: cpuset-basic-small-copy-odd [127.523426] Subtest cpuset-basic-small-copy-odd: SUCCESS (4.997s) [127.702098] [011/133] (924s left) gem_mmap_wc (pf-nonblock) [127.949719] Starting subtest: pf-nonblock [127.953112] Subtest pf-nonblock: SUCCESS (0.005s) [128.100851] [012/133] (924s left) gem_exec_create (legacy) [128.240507] Starting subtest: legacy [128.244554] Starting dynamic subtest: smem [130.284255] Dynamic subtest smem: SUCCESS (2.051s) [130.288370] Subtest legacy: SUCCESS (2.052s) [130.572115] [013/133] (922s left) kms_dither (fb-8bpc-vs-panel-6bpc) [130.775136] Starting subtest: fb-8bpc-vs-panel-6bpc [130.778145] Starting dynamic subtest: pipe-A-HDMI-A-1 [131.172640] Dynamic subtest pipe-A-HDMI-A-1: SKIP (0.388s) [131.196360] Starting dynamic subtest: pipe-A-HDMI-A-2 [131.216730] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.004s) [131.222896] Subtest fb-8bpc-vs-panel-6bpc: SKIP (0.400s) [131.497243] [014/133] (921s left) kms_big_fb (x-tiled-8bpp-rotate-0) [131.712853] Starting subtest: x-tiled-8bpp-rotate-0 [134.808808] Subtest x-tiled-8bpp-rotate-0: SUCCESS (3.096s) [135.112312] [015/133] (917s left) kms_cursor_crc (cursor-onscreen-128x42) [135.323124] Starting subtest: cursor-onscreen-128x42 [135.371681] Starting dynamic subtest: pipe-A-HDMI-A-1 [138.069738] Dynamic subtest pipe-A-HDMI-A-1: SUCCESS (2.697s) [138.234885] Starting dynamic subtest: pipe-C-HDMI-A-1 [140.571357] Dynamic subtest pipe-C-HDMI-A-1: SUCCESS (2.431s) [140.574614] Subtest cursor-onscreen-128x42: SUCCESS (5.248s) [140.899775] [016/133] (912s left) syncobj_timeline (etime-single-wait-all-for-submit-unsubmitted) [141.044509] Starting subtest: etime-single-wait-all-for-submit-unsubmitted [141.150051] Subtest etime-single-wait-all-for-submit-unsubmitted: SUCCESS (0.107s) [141.321061] [017/133] (911s left) syncobj_wait (wait-all-interrupted) [141.440741] Starting subtest: wait-all-interrupted [141.549105] Subtest wait-all-interrupted: SUCCESS (0.110s) [141.705049] [018/133] (911s left) kms_busy (extended-modeset-hang-newfb) [141.894282] Starting subtest: extended-modeset-hang-newfb [141.897738] Starting dynamic subtest: pipe-A [149.925594] Dynamic subtest pipe-A: SUCCESS (8.024s) [149.981045] Starting dynamic subtest: pipe-C [157.898577] Dynamic subtest pipe-C: SUCCESS (7.965s) [157.903911] Subtest extended-modeset-hang-newfb: SUCCESS (16.006s) [158.280478] [019/133] (895s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-mmap-cpu) [158.503630] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-mmap-cpu [161.356069] Subtest fbc-2p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (2.857s) [161.573686] [020/133] (891s left) syncobj_timeline (reset-multiple-signaled) [161.687250] Starting subtest: reset-multiple-signaled [161.690578] Subtest reset-multiple-signaled: SUCCESS (0.002s) [161.842935] [021/133] (891s left) gem_exec_schedule (wide) [162.112525] Starting subtest: wide [162.117467] Starting dynamic subtest: rcs0 [182.178069] Dynamic subtest rcs0: SUCCESS (20.054s) [182.244503] Starting dynamic subtest: bcs0 [203.413496] Dynamic subtest bcs0: SUCCESS (21.232s) [203.463050] Starting dynamic subtest: vcs0 [224.270064] Dynamic subtest vcs0: SUCCESS (20.817s) [224.308972] Starting dynamic subtest: vecs0 [245.195092] Dynamic subtest vecs0: SUCCESS (20.954s) [245.200038] Subtest wide: SUCCESS (83.084s) [246.212693] [FACT gem_exec_schedule (wide)] new: kernel.kmod_is_loaded.vgem: true [246.219780] [022/133] (807s left) gem_ctx_engines (none) [246.366948] Starting subtest: none [246.372181] Subtest none: SUCCESS (0.004s) [246.661303] [023/133] (807s left) gen7_exec_parse (batch-without-end) [246.777879] Subtest batch-without-end: SKIP (0.000s) [246.905573] [024/133] (806s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling) [247.085107] Starting subtest: flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling [247.092577] Subtest flip-64bpp-4tile-to-32bpp-4tiledg2rcccs-upscaling: SKIP (0.000s) [247.274713] [025/133] (806s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt) [247.480993] Starting subtest: fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt [247.489648] Subtest fbcpsr-1p-offscreen-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [247.674794] [026/133] (806s left) syncobj_timeline (etime-multi-wait-all-for-submit-available-unsubmitted-submi [247.787558] Starting subtest: etime-multi-wait-all-for-submit-available-unsubmitted-submitted [247.894052] Subtest etime-multi-wait-all-for-submit-available-unsubmitted-submitted: SUCCESS (0.105s) [248.077991] [027/133] (806s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-shrfb-draw-mmap-gtt) [248.297878] Starting subtest: psr-1p-primscrn-pri-shrfb-draw-mmap-gtt [248.304045] Subtest psr-1p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [248.511734] [028/133] (805s left) gem_exec_schedule (promotion) [248.650155] Starting subtest: promotion [248.653293] Starting dynamic subtest: rcs0 [248.777146] Dynamic subtest rcs0: SUCCESS (0.125s) [248.803589] Starting dynamic subtest: bcs0 [248.920543] Dynamic subtest bcs0: SUCCESS (0.145s) [248.944119] Starting dynamic subtest: vcs0 [249.065060] Dynamic subtest vcs0: SUCCESS (0.140s) [249.088225] Starting dynamic subtest: vecs0 [249.201672] Dynamic subtest vecs0: SUCCESS (0.135s) [249.205872] Subtest promotion: SUCCESS (0.556s) [249.623513] [029/133] (804s left) gem_exec_schedule (independent) [249.761042] Starting subtest: independent [249.764335] Starting dynamic subtest: rcs0 [249.919739] Dynamic subtest rcs0: SUCCESS (0.163s) [249.948054] Starting dynamic subtest: bcs0 [250.083142] Dynamic subtest bcs0: SUCCESS (0.159s) [250.121104] Starting dynamic subtest: vcs0 [250.228410] Dynamic subtest vcs0: SUCCESS (0.143s) [250.253300] Starting dynamic subtest: vecs0 [250.371567] Dynamic subtest vecs0: SUCCESS (0.141s) [250.376132] Subtest independent: SUCCESS (0.618s) [250.791991] [030/133] (803s left) prime_self_import (basic-llseek-size) [250.898600] Starting subtest: basic-llseek-size [250.905624] Subtest basic-llseek-size: SUCCESS (0.007s) [251.065601] [031/133] (803s left) gem_exec_reloc (basic-gtt-wc) [251.186404] Starting subtest: basic-gtt-wc [251.203234] Subtest basic-gtt-wc: SUCCESS (0.019s) [251.479277] [032/133] (803s left) i915_hangman (engine-error-state-capture) [251.623913] Starting subtest: engine-error-state-capture [251.627871] Starting dynamic subtest: rcs0 [255.509688] Dynamic subtest rcs0: SUCCESS (3.889s) [255.532779] Starting dynamic subtest: bcs0 [259.483925] Dynamic subtest bcs0: SUCCESS (3.970s) [259.508423] Starting dynamic subtest: vcs0 [263.511181] Dynamic subtest vcs0: SUCCESS (4.022s) [263.535271] Starting dynamic subtest: vecs0 [267.469927] Dynamic subtest vecs0: SUCCESS (3.959s) [267.473714] Subtest engine-error-state-capture: SUCCESS (15.851s) [267.671681] [033/133] (787s left) kms_plane_cursor (overlay) [267.851234] Starting subtest: overlay [267.857777] Starting dynamic subtest: pipe-A-HDMI-A-1-size-64 [270.528050] Dynamic subtest pipe-A-HDMI-A-1-size-64: SUCCESS (2.673s) [270.614954] Starting dynamic subtest: pipe-A-HDMI-A-1-size-128 [273.162095] Dynamic subtest pipe-A-HDMI-A-1-size-128: SUCCESS (2.565s) [273.249062] Starting dynamic subtest: pipe-A-HDMI-A-1-size-256 [275.779051] Dynamic subtest pipe-A-HDMI-A-1-size-256: SUCCESS (2.548s) [276.070709] Starting dynamic subtest: pipe-B-HDMI-A-2-size-64 [278.724321] Dynamic subtest pipe-B-HDMI-A-2-size-64: SUCCESS (2.674s) [278.794772] Starting dynamic subtest: pipe-B-HDMI-A-2-size-128 [281.341073] Dynamic subtest pipe-B-HDMI-A-2-size-128: SUCCESS (2.562s) [281.412738] Starting dynamic subtest: pipe-B-HDMI-A-2-size-256 [284.008826] Dynamic subtest pipe-B-HDMI-A-2-size-256: SUCCESS (2.616s) [284.330347] Starting dynamic subtest: pipe-C-HDMI-A-1-size-64 [286.911058] Dynamic subtest pipe-C-HDMI-A-1-size-64: SUCCESS (2.646s) [286.980603] Starting dynamic subtest: pipe-C-HDMI-A-1-size-128 [289.508546] Dynamic subtest pipe-C-HDMI-A-1-size-128: SUCCESS (2.548s) [289.579700] Starting dynamic subtest: pipe-C-HDMI-A-1-size-256 [292.141415] Dynamic subtest pipe-C-HDMI-A-1-size-256: SUCCESS (2.581s) [292.399301] Subtest overlay: SUCCESS (24.544s) [292.733287] [034/133] (762s left) sysfs_timeslice_duration (timeout) [292.864558] Starting subtest: timeout [292.868897] Starting dynamic subtest: vecs0 [293.717781] Dynamic subtest vecs0: SUCCESS (0.847s) [293.743400] Starting dynamic subtest: vcs0 [294.547568] Dynamic subtest vcs0: SUCCESS (0.829s) [294.573548] Starting dynamic subtest: bcs0 [295.408878] Dynamic subtest bcs0: SUCCESS (0.858s) [295.436626] Starting dynamic subtest: rcs0 [296.269773] Dynamic subtest rcs0: SUCCESS (0.859s) [296.273154] Subtest timeout: SUCCESS (3.407s) [296.467255] [035/133] (758s left) dumb_buffer (invalid-bpp) [296.578544] Starting subtest: invalid-bpp [296.582396] Subtest invalid-bpp: SUCCESS (0.000s) [296.789042] [036/133] (758s left) gem_exec_nop (basic-parallel) [296.928956] Starting subtest: basic-parallel [300.033612] Subtest basic-parallel: SUCCESS (3.119s) [300.316304] [037/133] (755s left) kms_addfb_basic (bad-pitch-256) [300.423420] Starting subtest: bad-pitch-256 [300.426738] Subtest bad-pitch-256: SUCCESS (0.000s) [300.681136] [038/133] (754s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-cpu) [300.881534] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-cpu [303.679761] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-cpu: SUCCESS (2.803s) [304.050847] [039/133] (751s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-fullscreen) [304.285457] Starting subtest: psr-2p-scndscrn-spr-indfb-fullscreen [304.294495] Subtest psr-2p-scndscrn-spr-indfb-fullscreen: SKIP (0.000s) [304.464738] [040/133] (751s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite) [304.671999] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite [307.867416] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SUCCESS (3.197s) [308.096415] [041/133] (747s left) gem_exec_balancer (bonded-sync) [308.226424] Starting subtest: bonded-sync [312.623712] Subtest bonded-sync: SUCCESS (4.397s) [312.901963] [042/133] (742s left) i915_drm_fdinfo (busy) [313.018345] Starting subtest: busy [313.022350] Starting dynamic subtest: rcs0 [313.568066] Dynamic subtest rcs0: SUCCESS (0.546s) [313.591393] Starting dynamic subtest: bcs0 [314.099546] Dynamic subtest bcs0: SUCCESS (0.525s) [314.124661] Starting dynamic subtest: vcs0 [314.634220] Dynamic subtest vcs0: SUCCESS (0.532s) [314.657363] Starting dynamic subtest: vecs0 [315.165404] Dynamic subtest vecs0: SUCCESS (0.526s) [315.169031] Subtest busy: SUCCESS (2.145s) [315.346819] [043/133] (740s left) gem_exec_create (forked) [315.479518] Starting subtest: forked [315.483220] Starting dynamic subtest: smem [335.548083] Dynamic subtest smem: SUCCESS (20.067s) [335.552812] Subtest forked: SUCCESS (20.071s) [335.847925] [044/133] (720s left) kms_pm_rpm (modeset-non-lpsp) [337.869260] Starting subtest: modeset-non-lpsp [338.272835] Subtest modeset-non-lpsp: SUCCESS (0.409s) [338.764067] [045/133] (717s left) gem_render_copy (yf-tiled-ccs-to-x-tiled) [339.008310] Starting subtest: yf-tiled-ccs-to-x-tiled [339.012056] Starting dynamic subtest: smem [339.411779] Dynamic subtest smem: SUCCESS (0.406s) [339.416216] Subtest yf-tiled-ccs-to-x-tiled: SUCCESS (0.415s) [339.744166] [046/133] (716s left) gem_userptr_blits (nohangcheck) [339.921727] Starting subtest: nohangcheck [340.190373] Subtest nohangcheck: SUCCESS (0.273s) [340.392181] [047/133] (716s left) gem_spin_batch (legacy) [340.520081] Starting subtest: legacy [340.523255] Starting dynamic subtest: default [343.763195] Dynamic subtest default: SUCCESS (3.233s) [343.788205] Starting dynamic subtest: render [346.994486] Dynamic subtest render: SUCCESS (3.229s) [347.019895] Starting dynamic subtest: bsd [350.227933] Dynamic subtest bsd: SUCCESS (3.230s) [350.252454] Starting dynamic subtest: bsd1 [353.461103] Dynamic subtest bsd1: SUCCESS (3.228s) [353.484227] Starting dynamic subtest: blt [356.696587] Dynamic subtest blt: SUCCESS (3.230s) [356.720864] Starting dynamic subtest: vebox [359.930630] Dynamic subtest vebox: SUCCESS (3.230s) [359.934578] Subtest legacy: SUCCESS (19.408s) [360.211530] [048/133] (696s left) kms_content_protection (dp-mst-lic-type-0) [360.408957] Starting subtest: dp-mst-lic-type-0 [360.415515] Subtest dp-mst-lic-type-0: SKIP (0.000s) [360.573688] [049/133] (696s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc) [360.796424] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc [360.803158] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [360.960826] [050/133] (695s left) kms_plane (pixel-format-y-tiled-modifier) [361.155105] Starting subtest: pixel-format-y-tiled-modifier [361.390501] Starting dynamic subtest: pipe-A-plane-0 [362.223241] Dynamic subtest pipe-A-plane-0: SUCCESS (0.834s) [362.250180] Starting dynamic subtest: pipe-A-plane-1 [363.039265] Dynamic subtest pipe-A-plane-1: SUCCESS (0.812s) [363.075498] Starting dynamic subtest: pipe-A-plane-2 [363.605794] Dynamic subtest pipe-A-plane-2: SUCCESS (0.563s) [363.650540] Starting dynamic subtest: pipe-A-plane-3 [364.174275] Dynamic subtest pipe-A-plane-3: SUCCESS (0.563s) [364.219986] Starting dynamic subtest: pipe-A-plane-4 [364.229474] Dynamic subtest pipe-A-plane-4: SKIP (0.000s) [364.561449] Starting dynamic subtest: pipe-B-plane-0 [365.325167] Dynamic subtest pipe-B-plane-0: SUCCESS (0.782s) [365.349165] Starting dynamic subtest: pipe-B-plane-1 [366.125306] Dynamic subtest pipe-B-plane-1: SUCCESS (0.796s) [366.148717] Starting dynamic subtest: pipe-B-plane-2 [366.693085] Dynamic subtest pipe-B-plane-2: SUCCESS (0.563s) [366.716958] Starting dynamic subtest: pipe-B-plane-3 [367.259299] Dynamic subtest pipe-B-plane-3: SUCCESS (0.563s) [367.313404] Starting dynamic subtest: pipe-B-plane-4 [367.322727] Dynamic subtest pipe-B-plane-4: SKIP (0.000s) [367.497074] Subtest pixel-format-y-tiled-modifier: SUCCESS (6.342s) [367.833537] [051/133] (689s left) prime_vgem (fence-flip-hang) [367.989739] Subtest fence-flip-hang: SKIP (0.000s) [368.146260] [052/133] (688s left) syncobj_basic (bad-fd-to-handle) [368.256450] Starting subtest: bad-fd-to-handle [368.260468] Subtest bad-fd-to-handle: SUCCESS (0.000s) [368.411351] [053/133] (688s left) kms_chamelium_hpd (vga-hpd) [369.041815] Subtest vga-hpd: SKIP (0.000s) [369.339749] [054/133] (687s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-mmap-cpu) [369.570827] Starting subtest: psr-2p-primscrn-spr-indfb-draw-mmap-cpu [369.577399] Subtest psr-2p-primscrn-spr-indfb-draw-mmap-cpu: SKIP (0.000s) [369.760999] [055/133] (687s left) kms_selftest (drm_dp_mst_helper) [369.887494] Starting subtest: drm_dp_mst_helper [369.912011] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_mode [369.973708] Dynamic subtest drm_test_dp_mst_calc_pbn_mode: SUCCESS (0.060s) [369.997522] Starting dynamic subtest: drm_test_dp_mst_calc_pbn_div [370.032581] Dynamic subtest drm_test_dp_mst_calc_pbn_div: SUCCESS (0.058s) [370.060338] Starting dynamic subtest: drm_test_dp_mst_sideband_msg_req_decode [370.129882] Dynamic subtest drm_test_dp_mst_sideband_msg_req_decode: SUCCESS (0.087s) [370.135554] Subtest drm_dp_mst_helper: SUCCESS (0.244s) [370.342406] [056/133] (687s left) prime_self_import (reimport-vs-gem_close-race) [370.449072] Starting subtest: reimport-vs-gem_close-race [375.461272] Subtest reimport-vs-gem_close-race: SUCCESS (5.019s) [375.631990] [057/133] (681s left) kms_flip (blocking-absolute-wf_vblank)