[91.715153] Initializing watchdogs [91.715322] /dev/watchdog0 [91.752800] [FACT before any test] new: hardware.pci.gpu_at_addr.0000:00:02.0: 8086:3185 Intel Geminilake (Gen9) GeminiLake [UHD Graphics 600] [91.767370] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [91.774743] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [91.782696] [001/131] (960s left) kms_cursor_legacy (basic-flip-after-cursor-legacy) [91.970970] Starting subtest: basic-flip-after-cursor-legacy [93.178518] Subtest basic-flip-after-cursor-legacy: SUCCESS (1.208s) [93.388916] [002/131] (958s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-0) [93.582860] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-0 [95.073324] Subtest yf-tiled-max-hw-stride-32bpp-rotate-0: SUCCESS (1.488s) [95.236741] [003/131] (957s left) kms_cursor_legacy (2x-long-cursor-vs-flip-atomic) [95.403707] Starting subtest: 2x-long-cursor-vs-flip-atomic [95.418045] Subtest 2x-long-cursor-vs-flip-atomic: SKIP (0.005s) [95.537707] [004/131] (957s left) i915_pm_rc6_residency (rc6-idle) [95.645215] Starting subtest: rc6-idle [95.667617] Subtest rc6-idle: SKIP (0.018s) [95.811824] [005/131] (956s left) syncobj_timeline (etime-single-wait-all-for-submit-available-unsubmitted) [95.922976] Starting subtest: etime-single-wait-all-for-submit-available-unsubmitted [96.030069] Subtest etime-single-wait-all-for-submit-available-unsubmitted: SUCCESS (0.106s) [96.155908] [006/131] (956s left) i915_getparams_basic (basic-eu-total) [96.263968] Starting subtest: basic-eu-total [96.271327] Subtest basic-eu-total: SUCCESS (0.000s) [96.389276] [007/131] (956s left) kms_cursor_crc (cursor-rapid-movement-32x10) [96.564140] Starting subtest: cursor-rapid-movement-32x10 [96.676335] Subtest cursor-rapid-movement-32x10: SKIP (0.108s) [96.823385] [008/131] (956s left) gem_ctx_persistence (engines-cleanup) [96.992052] Starting subtest: engines-cleanup [96.995332] Starting dynamic subtest: rcs0 [97.009447] Dynamic subtest rcs0: SUCCESS (0.020s) [97.026981] Starting dynamic subtest: bcs0 [97.056417] Dynamic subtest bcs0: SUCCESS (0.047s) [97.078648] Starting dynamic subtest: vcs0 [97.082369] Dynamic subtest vcs0: SUCCESS (0.021s) [97.104572] Starting dynamic subtest: vecs0 [97.116365] Dynamic subtest vecs0: SUCCESS (0.038s) [97.119927] Subtest engines-cleanup: SUCCESS (0.130s) [97.420568] [009/131] (955s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite) [98.004391] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite [98.135541] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-pwrite: SKIP (0.000s) [98.623927] [010/131] (954s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-indfb-draw-mmap-cpu) [98.830414] Starting subtest: psr-2p-primscrn-pri-indfb-draw-mmap-cpu [98.837745] Subtest psr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [98.991709] [011/131] (954s left) core_getversion (basic) [99.102540] Starting subtest: basic [99.111398] Subtest basic: SUCCESS (0.000s) [99.222732] [012/131] (954s left) kms_psr (psr2-cursor-plane-move) [99.387989] Subtest psr2-cursor-plane-move: SKIP (0.000s) [99.484333] [013/131] (954s left) gem_exec_schedule (noreorder-priority) [99.744597] Starting subtest: noreorder-priority [99.747727] Starting dynamic subtest: rcs0 [100.862312] Dynamic subtest rcs0: SUCCESS (1.119s) [100.880371] Starting dynamic subtest: bcs0 [101.982605] Dynamic subtest bcs0: SUCCESS (1.118s) [102.000911] Starting dynamic subtest: vcs0 [103.109103] Dynamic subtest vcs0: SUCCESS (1.125s) [103.127731] Starting dynamic subtest: vecs0 [104.224460] Dynamic subtest vecs0: SUCCESS (1.114s) [104.228588] Subtest noreorder-priority: SUCCESS (4.479s) [104.458618] [014/131] (949s left) kms_plane_scaling (plane-upscale-factor-0-25-with-rotation) [104.618876] Starting subtest: plane-upscale-factor-0-25-with-rotation [104.624457] Starting dynamic subtest: pipe-A [106.408241] Dynamic subtest pipe-A: SKIP (1.781s) [106.441969] Starting dynamic subtest: pipe-B [108.405802] Dynamic subtest pipe-B: SKIP (1.997s) [108.441147] Starting dynamic subtest: pipe-C [110.400316] Dynamic subtest pipe-C: SKIP (1.990s) [110.407892] Subtest plane-upscale-factor-0-25-with-rotation: SKIP (5.771s) [110.806777] [015/131] (943s left) gem_userptr_blits (map-fixed-invalidate-busy) [110.975028] Starting subtest: map-fixed-invalidate-busy [110.978539] Starting dynamic subtest: gtt [114.214590] Dynamic subtest gtt: SUCCESS (3.229s) [114.237098] Starting dynamic subtest: wb [117.305582] Dynamic subtest wb: SUCCESS (3.096s) [117.336171] Starting dynamic subtest: wc [120.498697] Dynamic subtest wc: SUCCESS (3.190s) [120.516309] Starting dynamic subtest: uc [123.761124] Dynamic subtest uc: SUCCESS (3.259s) [123.764391] Subtest map-fixed-invalidate-busy: SUCCESS (12.785s) [123.934791] [016/131] (930s left) kms_cursor_legacy (flip-vs-cursor-atomic-transitions) [124.112576] Starting subtest: flip-vs-cursor-atomic-transitions [138.688284] Subtest flip-vs-cursor-atomic-transitions: FAIL (13.593s) [138.772217] [017/131] (915s left) syncobj_timeline (multi-wait-available-signaled) [138.888283] Starting subtest: multi-wait-available-signaled [138.892563] Subtest multi-wait-available-signaled: SUCCESS (0.003s) [139.021321] [018/131] (915s left) kms_prop_blob (blob-multiple) [139.133558] Starting subtest: blob-multiple [139.137562] Subtest blob-multiple: SUCCESS (0.004s) [139.269798] [019/131] (915s left) kms_vblank (query-forked-busy-hang) [139.440082] Starting subtest: query-forked-busy-hang [139.443250] Starting dynamic subtest: pipe-A-HDMI-A-2 [146.881190] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (7.432s) [146.919824] Starting dynamic subtest: pipe-C-HDMI-A-2 [153.826730] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (6.941s) [153.830975] Subtest query-forked-busy-hang: SUCCESS (14.388s) [154.057649] [020/131] (900s left) syncobj_timeline (multi-wait-available-unsubmitted-signaled) [154.168651] Starting subtest: multi-wait-available-unsubmitted-signaled [154.172272] Subtest multi-wait-available-unsubmitted-signaled: SUCCESS (0.002s) [154.306750] [021/131] (900s left) kms_cursor_crc (cursor-random-128x42) [154.499061] Starting subtest: cursor-random-128x42 [154.520130] Starting dynamic subtest: pipe-A-HDMI-A-2 [158.396990] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (3.885s) [158.496692] Starting dynamic subtest: pipe-C-HDMI-A-2 [162.312829] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (3.849s) [162.316802] Subtest cursor-random-128x42: SUCCESS (7.811s) [162.544299] [022/131] (892s left) kms_frontbuffer_tracking (fbc-tiling-4) [162.737815] Starting subtest: fbc-tiling-4 [162.744025] Subtest fbc-tiling-4: SKIP (0.000s) [162.859093] [023/131] (891s left) i915_query (engine-info-invalid) [162.971132] Starting subtest: engine-info-invalid [162.974353] Subtest engine-info-invalid: SUCCESS (0.000s) [163.108574] [024/131] (891s left) syncobj_timeline (etime-multi-wait-all-for-submit-submitted-signaled) [163.220295] Starting subtest: etime-multi-wait-all-for-submit-submitted-signaled [163.325100] Subtest etime-multi-wait-all-for-submit-submitted-signaled: SUCCESS (0.106s) [163.458136] [025/131] (891s left) kms_frontbuffer_tracking (fbc-1p-pri-indfb-multidraw) [163.650580] Starting subtest: fbc-1p-pri-indfb-multidraw [179.959062] Subtest fbc-1p-pri-indfb-multidraw: SUCCESS (16.313s) [180.142762] [026/131] (874s left) gem_mmap_gtt (basic-small-copy) [180.253229] Starting subtest: basic-small-copy [182.633425] Subtest basic-small-copy: SUCCESS (2.381s) [182.781229] [027/131] (872s left) kms_frontbuffer_tracking (fbcpsr-shrfb-scaledprimary) [183.134616] Starting subtest: fbcpsr-shrfb-scaledprimary [183.140991] Subtest fbcpsr-shrfb-scaledprimary: SKIP (0.000s) [183.275890] [028/131] (871s left) gem_exec_reloc (basic-wc-cpu-noreloc) [183.402977] Starting subtest: basic-wc-cpu-noreloc [183.418315] Subtest basic-wc-cpu-noreloc: SUCCESS (0.012s) [183.608562] [029/131] (871s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf) [183.803885] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s) [183.889231] [030/131] (871s left) perf (invalid-oa-format-id) [184.173934] Starting subtest: invalid-oa-format-id [184.198721] Subtest invalid-oa-format-id: SUCCESS (0.025s) [184.291474] [031/131] (871s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt) [184.479823] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt [184.487051] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [184.609395] [032/131] (870s left) kms_hdmi_inject (inject-4k) [184.930997] Starting subtest: inject-4k [185.227262] Subtest inject-4k: SUCCESS (0.258s) [185.578555] [033/131] (870s left) kms_chamelium_hpd (common-hpd-after-suspend) [185.983937] Subtest common-hpd-after-suspend: SKIP (0.000s) [186.181930] [034/131] (869s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-indfb-msflip-blt) [186.364390] Starting subtest: fbc-2p-scndscrn-indfb-msflip-blt [186.370671] Subtest fbc-2p-scndscrn-indfb-msflip-blt: SKIP (0.000s) [186.497911] [035/131] (869s left) kms_cursor_crc (cursor-alpha-opaque) [186.674757] Starting subtest: cursor-alpha-opaque [186.677882] Starting dynamic subtest: pipe-A-HDMI-A-2 [187.395972] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.716s) [187.431610] Starting dynamic subtest: pipe-C-HDMI-A-2 [187.959051] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.561s) [187.962487] Subtest cursor-alpha-opaque: SUCCESS (1.288s) [188.221441] [036/131] (867s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-shrfb-pgflip-blt) [188.419299] Starting subtest: fbcpsr-2p-scndscrn-shrfb-pgflip-blt [188.425851] Subtest fbcpsr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s) [188.565777] [037/131] (867s left) gem_userptr_blits (stress-mm-invalidate-close) [188.708183] Starting subtest: stress-mm-invalidate-close [190.855997] Subtest stress-mm-invalidate-close: SUCCESS (2.150s) [191.019882] [038/131] (865s left) sw_sync (sync_expired_merge) [191.132563] Starting subtest: sync_expired_merge [192.205272] Subtest sync_expired_merge: SUCCESS (1.075s) [192.320535] [039/131] (863s left) gem_exec_big (single) [192.454104] Starting subtest: single [241.728466] Subtest single: SUCCESS (49.254s) [242.528116] [040/131] (813s left) kms_flip (2x-flip-vs-expired-vblank-interruptible) [242.885239] Starting subtest: 2x-flip-vs-expired-vblank-interruptible [242.893060] Subtest 2x-flip-vs-expired-vblank-interruptible: SKIP (0.002s) [243.039488] [041/131] (813s left) kms_frontbuffer_tracking (psr-1p-primscrn-spr-indfb-draw-pwrite) [243.233592] Starting subtest: psr-1p-primscrn-spr-indfb-draw-pwrite [243.241416] Subtest psr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [243.371830] [042/131] (813s left) gem_mmap_gtt (big-copy-xy) [243.483888] Starting subtest: big-copy-XY [253.873359] Subtest big-copy-XY: SUCCESS (10.382s) [254.031889] [043/131] (802s left) kms_cursor_edge_walk (128x128-right-edge) [254.335226] Starting subtest: 128x128-right-edge [254.360428] Starting dynamic subtest: pipe-A-HDMI-A-2 [258.154052] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (3.816s) [258.198173] Starting dynamic subtest: pipe-C-HDMI-A-2 [261.627976] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (3.466s) [261.656062] Subtest 128x128-right-edge: SUCCESS (7.293s) [261.845175] [044/131] (794s left) kms_frontbuffer_tracking (fbc-2p-primscrn-shrfb-plflip-blt) [262.045887] Starting subtest: fbc-2p-primscrn-shrfb-plflip-blt [262.052405] Subtest fbc-2p-primscrn-shrfb-plflip-blt: SKIP (0.000s) [262.192303] [045/131] (794s left) kms_flip_scaled_crc (flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling) [262.391138] Starting subtest: flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling [262.396825] Starting dynamic subtest: pipe-A-valid-mode [262.634695] Dynamic subtest pipe-A-valid-mode: SKIP (0.266s) [262.641379] Subtest flip-64bpp-ytile-to-32bpp-ytilercccs-downscaling: SKIP (0.271s) [262.862506] [046/131] (794s left) gem_render_copy (y-tiled-ccs-to-y-tiled-mc-ccs) [262.989084] Starting subtest: y-tiled-ccs-to-y-tiled-mc-ccs [262.997155] Subtest y-tiled-ccs-to-y-tiled-mc-ccs: SKIP (0.000s) [263.235285] [047/131] (793s left) gem_exec_balancer (bonded-chain) [263.370576] Starting subtest: bonded-chain [267.695502] Subtest bonded-chain: SUCCESS (4.326s) [267.972143] [048/131] (789s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip) [268.174671] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip [268.186462] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-hflip-async-flip: SKIP (0.004s) [268.337843] [049/131] (788s left) sw_sync (timeline_closed_signaled) [268.468557] Starting subtest: timeline_closed_signaled [268.472117] Subtest timeline_closed_signaled: SUCCESS (0.000s) [268.675570] [050/131] (788s left) kms_feature_discovery (display-3x) [269.192190] Starting subtest: display-3x [269.322615] Subtest display-3x: SKIP (0.000s) [269.744875] [051/131] (788s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs) [270.131525] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs [270.134656] Starting dynamic subtest: pipe-A-HDMI-A-2 [270.236458] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.001s) [270.258068] Starting dynamic subtest: pipe-B-HDMI-A-2 [270.288572] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [270.308883] Starting dynamic subtest: pipe-C-HDMI-A-2 [270.339260] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s) [270.345264] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs: SKIP (0.019s) [270.844105] [052/131] (787s left) kms_psr (pr-cursor-mmap-gtt) [271.012454] Subtest pr-cursor-mmap-gtt: SKIP (0.000s) [271.116946] [053/131] (786s left) gem_pread (bench) [271.222830] Starting subtest: bench [274.349411] Subtest bench: SUCCESS (3.124s) [274.489241] [054/131] (783s left) kms_async_flips (test-time-stamp) [274.659836] Starting subtest: test-time-stamp [274.980820] Starting dynamic subtest: pipe-A-HDMI-A-2 [275.164482] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.179s) [275.547405] Starting dynamic subtest: pipe-B-HDMI-A-2 [275.664519] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.161s) [276.002731] Starting dynamic subtest: pipe-C-HDMI-A-2 [276.154524] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.193s) [276.157699] Subtest test-time-stamp: SUCCESS (1.492s) [276.707812] [055/131] (781s left) gem_softpin (full) [276.832173] Starting subtest: full [276.848523] Subtest full: SUCCESS (0.017s) [276.996364] [056/131] (781s left) api_intel_bb (blit-reloc-keep-cache) [277.104749] Starting subtest: blit-reloc-keep-cache [277.123936] Subtest blit-reloc-keep-cache: SUCCESS (0.022s) [277.297870] [057/131] (781s left) kms_psr2_sf (fbc-pr-cursor-plane-move-continuous-sf) [277.494158] Subtest fbc-pr-cursor-plane-move-continuous-sf: SKIP (0.000s) [277.587114] [058/131] (780s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-shrfb-pgflip-blt) [277.778407] Starting subtest: fbcpsr-1p-primscrn-shrfb-pgflip-blt [277.785654] Subtest fbcpsr-1p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [277.938921] [059/131] (780s left) kms_pm_rpm (fences-dpms) [279.642590] Starting subtest: fences-dpms [282.526458] Subtest fences-dpms: SUCCESS (2.889s) [282.701764] [060/131] (776s left) api_intel_allocator (fork-simple-once) [282.812325] Starting subtest: fork-simple-once [282.845070] Subtest fork-simple-once: SUCCESS (0.036s) [282.985759] [061/131] (775s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu) [283.171281] Starting subtest: fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu [283.177688] Subtest fbcpsr-2p-primscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [283.301618] [062/131] (775s left) gem_ctx_param (set-priority-not-supported) [283.413363] Starting subtest: set-priority-not-supported [283.420466] Subtest set-priority-not-supported: SKIP (0.000s) [283.550111] [063/131] (775s left) gem_ctx_param (get-priority-new-ctx) [283.659825] Starting subtest: get-priority-new-ctx [283.663592] Subtest get-priority-new-ctx: SUCCESS (0.000s) [283.803052] [064/131] (775s left) gem_exec_schedule (u-submit-golden-slice) [283.949676] Starting subtest: u-submit-golden-slice [283.953683] Starting dynamic subtest: rcs0 [283.997738] Dynamic subtest rcs0: SUCCESS (0.050s) [284.019776] Starting dynamic subtest: bcs0 [284.041890] Dynamic subtest bcs0: SUCCESS (0.042s) [284.068826] Starting dynamic subtest: vcs0 [284.091445] Dynamic subtest vcs0: SUCCESS (0.045s) [284.113168] Starting dynamic subtest: vecs0 [284.138868] Dynamic subtest vecs0: SUCCESS (0.046s) [284.143670] Subtest u-submit-golden-slice: SUCCESS (0.196s) [284.519531] [065/131] (774s left) gen9_exec_parse (basic-rejected) [284.648416] Starting subtest: basic-rejected [284.651796] Subtest basic-rejected: SUCCESS (0.004s) [284.917684] [066/131] (774s left) gem_pipe_control_store_loop (reused-buffer) [285.038350] Starting subtest: reused-buffer [287.189428] Subtest reused-buffer: SUCCESS (2.156s) [287.341298] [067/131] (772s left) gem_exec_reloc (basic-cpu-wc-noreloc) [287.464658] Starting subtest: basic-cpu-wc-noreloc [287.477066] Subtest basic-cpu-wc-noreloc: SUCCESS (0.010s) [287.667297] [068/131] (771s left) kms_big_fb (x-tiled-max-hw-stride-32bpp-rotate-180-hflip) [287.858103] Starting subtest: x-tiled-max-hw-stride-32bpp-rotate-180-hflip [287.901877] Subtest x-tiled-max-hw-stride-32bpp-rotate-180-hflip: SKIP (0.039s) [288.051181] [069/131] (771s left) gem_lmem_swapping (verify-random-ccs) [288.176390] Subtest verify-random-ccs: SKIP (0.000s) [288.276183] [070/131] (771s left) gem_exec_params (no-blt) [288.392152] Starting subtest: no-blt [288.396264] Subtest no-blt: SUCCESS (0.003s) [288.533258] [071/131] (771s left) gem_ctx_create (basic) [288.668054] Starting subtest: basic [288.671921] Subtest basic: SUCCESS (0.000s) [288.920831] [072/131] (770s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-pwrite) [289.104684] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-pwrite [289.113123] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [289.233205] [073/131] (770s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-shrfb-msflip-blt) [289.411948] Starting subtest: fbc-2p-scndscrn-shrfb-msflip-blt [289.419126] Subtest fbc-2p-scndscrn-shrfb-msflip-blt: SKIP (0.000s) [289.550839] [074/131] (770s left) fbdev (unaligned-write) [289.665999] Starting subtest: unaligned-write [289.877929] Subtest unaligned-write: SUCCESS (0.213s) [289.999150] [075/131] (770s left) kms_ccs (random-ccs-data-4-tiled-dg2-mc-ccs) [290.174633] Starting subtest: random-ccs-data-4-tiled-dg2-mc-ccs [290.178456] Starting dynamic subtest: pipe-A-HDMI-A-2 [290.281411] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s) [290.304215] Starting dynamic subtest: pipe-B-HDMI-A-2 [290.348970] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [290.369615] Starting dynamic subtest: pipe-C-HDMI-A-2 [290.403519] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.001s) [290.409464] Subtest random-ccs-data-4-tiled-dg2-mc-ccs: SKIP (0.018s) [290.893818] [076/131] (769s left) perf_pmu (render-node-busy-idle) [291.033111] Starting subtest: render-node-busy-idle [291.037058] Starting dynamic subtest: rcs0 [291.668149] Dynamic subtest rcs0: SUCCESS (0.630s) [291.690423] Starting dynamic subtest: bcs0 [292.297449] Dynamic subtest bcs0: SUCCESS (0.629s) [292.320352] Starting dynamic subtest: vcs0 [292.932352] Dynamic subtest vcs0: SUCCESS (0.632s) [292.954704] Starting dynamic subtest: vecs0 [293.563660] Dynamic subtest vecs0: SUCCESS (0.630s) [293.567451] Subtest render-node-busy-idle: SUCCESS (2.535s) [293.694822] [077/131] (766s left) gem_exec_whisper (basic-queues-forked-all) [293.827882] Starting subtest: basic-queues-forked-all [304.342920] Subtest basic-queues-forked-all: SUCCESS (10.514s) [304.613407] [078/131] (755s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-180) [304.804355] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-180 [304.810658] Subtest yf-tiled-max-hw-stride-64bpp-rotate-180: SKIP (0.000s) [304.945795] [079/131] (755s left) kms_flip (flip-vs-absolute-wf_vblank) [305.117473] Starting subtest: flip-vs-absolute-wf_vblank [305.120791] Starting dynamic subtest: A-HDMI-A2 [316.013104] Dynamic subtest A-HDMI-A2: SUCCESS (10.890s) [316.035492] Starting dynamic subtest: B-HDMI-A2 [326.833659] Dynamic subtest B-HDMI-A2: SUCCESS (10.818s) [326.857749] Starting dynamic subtest: C-HDMI-A2 [337.661241] Dynamic subtest C-HDMI-A2: SUCCESS (10.823s) [337.664556] Subtest flip-vs-absolute-wf_vblank: SUCCESS (32.548s) [338.090584] [080/131] (722s left) sysfs_preempt_timeout (idempotent) [338.212102] Starting subtest: idempotent [338.215307] Starting dynamic subtest: vecs0 [338.220338] Dynamic subtest vecs0: SUCCESS (0.003s) [338.242813] Starting dynamic subtest: vcs0 [338.246059] Dynamic subtest vcs0: SUCCESS (0.003s) [338.268623] Starting dynamic subtest: bcs0 [338.272535] Dynamic subtest bcs0: SUCCESS (0.002s) [338.295021] Starting dynamic subtest: rcs0 [338.298213] Dynamic subtest rcs0: SUCCESS (0.002s) [338.301624] Subtest idempotent: SUCCESS (0.026s) [338.389578] [081/131] (722s left) gem_exec_gttfill (engines) [338.523946] Starting subtest: engines [338.527350] Starting dynamic subtest: rcs0 [365.800095] Dynamic subtest rcs0: SUCCESS (27.277s) [365.824902] Starting dynamic subtest: bcs0 [395.882388] Dynamic subtest bcs0: SUCCESS (30.080s) [395.907900] Starting dynamic subtest: vcs0 [422.708111] Dynamic subtest vcs0: SUCCESS (26.820s) [422.730863] Starting dynamic subtest: vecs0 [450.092502] Dynamic subtest vecs0: SUCCESS (27.382s) [450.096244] Subtest engines: SUCCESS (111.573s) [450.371719] [082/131] (610s left) gem_ctx_param (set-priority-invalid-size) [450.610894] Starting subtest: set-priority-invalid-size [450.615523] Subtest set-priority-invalid-size: SUCCESS (0.000s) [450.748124] [083/131] (610s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode) [451.317257] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s) [451.769752] [084/131] (609s left) gem_mmap (short-mmap) [452.325759] Starting subtest: short-mmap [452.391248] Subtest short-mmap: SUCCESS (0.000s) [452.666783] [085/131] (608s left) kms_addfb_basic (bad-pitch-128) [452.788212] Starting subtest: bad-pitch-128 [452.791392] Subtest bad-pitch-128: SUCCESS (0.000s) [452.989344] [086/131] (608s left) gem_softpin (evict-active-interruptible) [453.125052] Starting subtest: evict-active-interruptible [453.132892] Subtest evict-active-interruptible: SUCCESS (0.011s) [453.300648] [087/131] (608s left) kms_plane_lowres (tiling-yf) [453.476816] Starting subtest: tiling-yf [453.480117] Starting dynamic subtest: pipe-A-HDMI-A-2 [460.556206] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (7.079s) [460.830075] Starting dynamic subtest: pipe-B-HDMI-A-2 [467.403045] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (6.577s) [467.601150] Starting dynamic subtest: pipe-C-HDMI-A-2 [474.105258] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (6.526s) [474.309187] Subtest tiling-yf: SUCCESS (20.829s) [474.554649] [088/131] (587s left) kms_frontbuffer_tracking (psr-rgb565-draw-blt) [474.765903] Starting subtest: psr-rgb565-draw-blt [474.772480] Subtest psr-rgb565-draw-blt: SKIP (0.000s) [474.912886] [089/131] (587s left) gem_userptr_blits (sd-probe) [475.043453] Starting subtest: sd-probe [475.047122] Subtest sd-probe: SUCCESS (0.001s) [475.220849] [090/131] (586s left) perf (gen12-invalid-class-instance) [475.480133] Starting subtest: gen12-invalid-class-instance [475.497682] Subtest gen12-invalid-class-instance: SUCCESS (0.014s) [475.613297] [091/131] (586s left) gem_ccs (block-multicopy-inplace) [475.731189] Subtest block-multicopy-inplace: SKIP (0.000s) [475.828714] [092/131] (586s left) gem_exec_reloc (basic-gtt) [475.946550] Starting subtest: basic-gtt [475.970061] Subtest basic-gtt: SUCCESS (0.020s) [476.169716] [093/131] (586s left) kms_atomic (plane-primary-legacy) [476.343262] Starting subtest: plane-primary-legacy [476.354908] Starting dynamic subtest: pipe-A-HDMI-A-2 [476.825238] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.472s) [476.828701] Subtest plane-primary-legacy: SUCCESS (0.479s) [477.049316] [094/131] (585s left) gem_mmap_offset (blt-coherency) [477.164359] Starting subtest: blt-coherency [477.176863] Subtest blt-coherency: SUCCESS (0.018s) [477.332278] [095/131] (585s left) prime_vgem (fence-wait) [477.545103] Starting subtest: fence-wait [477.551060] Starting dynamic subtest: rcs0 [478.575035] Dynamic subtest rcs0: SUCCESS (1.030s) [478.603408] Starting dynamic subtest: vcs0 [479.597107] Dynamic subtest vcs0: SUCCESS (1.016s) [479.623988] Starting dynamic subtest: vecs0 [480.617115] Dynamic subtest vecs0: SUCCESS (1.018s) [480.620519] Subtest fence-wait: SUCCESS (3.077s) [480.770508] [FACT prime_vgem (fence-wait)] new: kernel.kmod_is_loaded.vgem: true [480.778820] [096/131] (581s left) gem_exec_balancer (smoke) [480.911799] Starting subtest: smoke [485.204871] Subtest smoke: SUCCESS (4.294s) [485.492254] [097/131] (577s left) sw_sync (sync_multi_timeline_wait) [485.597107] Starting subtest: sync_multi_timeline_wait [485.600214] Subtest sync_multi_timeline_wait: SUCCESS (0.001s) [485.723857] [098/131] (577s left) kms_frontbuffer_tracking (psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu) [485.908477] Starting subtest: psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu [485.914712] Subtest psr-2p-scndscrn-pri-shrfb-draw-mmap-cpu: SKIP (0.000s) [486.072864] [099/131] (576s left) kms_plane_scaling (plane-downscale-factor-0-75-with-pixel-format) [486.250800] Starting subtest: plane-downscale-factor-0-75-with-pixel-format [486.255698] Starting dynamic subtest: pipe-A [487.160907] Dynamic subtest pipe-A: SUCCESS (0.917s) [487.185516] Starting dynamic subtest: pipe-B [488.328463] Dynamic subtest pipe-B: SUCCESS (1.074s) [488.383252] Starting dynamic subtest: pipe-C [488.718198] Dynamic subtest pipe-C: SUCCESS (0.407s) [488.860628] Subtest plane-downscale-factor-0-75-with-pixel-format: SUCCESS (2.408s) [489.080476] [100/131] (573s left) gem_exec_schedule (submit-golden-slice) [489.224070] Starting subtest: submit-golden-slice [489.227371] Starting dynamic subtest: rcs0 [489.269951] Dynamic subtest rcs0: SUCCESS (0.045s) [489.292995] Starting dynamic subtest: bcs0 [489.322538] Dynamic subtest bcs0: SUCCESS (0.049s) [489.345971] Starting dynamic subtest: vcs0 [489.374453] Dynamic subtest vcs0: SUCCESS (0.047s) [489.398131] Starting dynamic subtest: vecs0 [489.425102] Dynamic subtest vecs0: SUCCESS (0.048s) [489.428391] Subtest submit-golden-slice: SUCCESS (0.206s) [489.826397] [101/131] (573s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-pwrite) [490.016168] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-pwrite [490.023365] Subtest fbc-2p-scndscrn-cur-indfb-draw-pwrite: SKIP (0.000s) [490.160340] [102/131] (573s left) kms_big_fb (x-tiled-32bpp-rotate-270) [490.362782] Starting subtest: x-tiled-32bpp-rotate-270 [490.490138] Subtest x-tiled-32bpp-rotate-270: SKIP (0.123s) [490.662405] [103/131] (572s left) kms_cursor_legacy (2x-flip-vs-cursor-legacy) [490.834672] Starting subtest: 2x-flip-vs-cursor-legacy [490.840940] Subtest 2x-flip-vs-cursor-legacy: SKIP (0.006s) [490.993153] [104/131] (572s left) gen9_exec_parse (secure-batches) [491.121301] Starting subtest: secure-batches [491.125340] Subtest secure-batches: SUCCESS (0.000s) [491.397070] [105/131] (572s left) kms_vblank (ts-continuation-modeset-rpm) [491.565882] Starting subtest: ts-continuation-modeset-rpm [491.569261] Starting dynamic subtest: pipe-A-HDMI-A-2 [493.527416] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.957s) [493.595207] Starting dynamic subtest: pipe-C-HDMI-A-2 [494.351549] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.813s) [494.355161] Subtest ts-continuation-modeset-rpm: SUCCESS (2.785s) [494.596330] [106/131] (569s left) syncobj_timeline (wait-all-delayed-signal) [494.710565] Starting subtest: wait-all-delayed-signal [494.821690] Subtest wait-all-delayed-signal: SUCCESS (0.106s) [494.958266] [107/131] (568s left) gem_workarounds (reset-context) [495.106897] Starting subtest: reset-context [495.128671] Subtest reset-context: SUCCESS (0.027s) [495.305785] [108/131] (568s left) gem_exec_params (invalid-bsd1-flag-on-vebox) [495.428084] Starting subtest: invalid-bsd1-flag-on-vebox [495.431579] Subtest invalid-bsd1-flag-on-vebox: SUCCESS (0.002s) [495.591324] [109/131] (568s left) kms_invalid_mode (bad-htotal) [495.801371] Starting subtest: bad-htotal [495.804590] Starting dynamic subtest: pipe-A-HDMI-A-2 [495.988151] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.213s) [496.018947] Starting dynamic subtest: pipe-B-HDMI-A-2 [496.044703] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.002s) [496.098154] Starting dynamic subtest: pipe-C-HDMI-A-2 [496.102735] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.002s) [496.105847] Subtest bad-htotal: SUCCESS (0.238s) [496.272371] [110/131] (567s left) gem_ctx_isolation (preservation-s3) [496.410596] Starting subtest: preservation-S3 [496.414272] Starting dynamic subtest: rcs0