[58.633136] Initializing watchdogs [58.633185] /dev/watchdog0 [58.641215] [001/130] (960s left) syncobj_basic (bad-flags-fd-to-handle) [58.693104] Starting subtest: bad-flags-fd-to-handle [58.695420] Subtest bad-flags-fd-to-handle: SUCCESS (0.000s) [58.774820] [002/130] (960s left) gem_exec_parallel (fds) [58.842260] Starting subtest: fds [58.844556] Starting dynamic subtest: rcs0 [61.961774] Dynamic subtest rcs0: SUCCESS (3.110s) [61.968332] Starting dynamic subtest: bcs0 [64.902352] Dynamic subtest bcs0: SUCCESS (2.934s) [64.913083] Starting dynamic subtest: vcs0 [69.087095] Dynamic subtest vcs0: SUCCESS (4.182s) [69.093366] Starting dynamic subtest: vcs1 [73.271235] Dynamic subtest vcs1: SUCCESS (4.180s) [73.278290] Starting dynamic subtest: vecs0 [77.123620] Dynamic subtest vecs0: SUCCESS (3.850s) [77.132673] Starting dynamic subtest: ccs0 [80.364448] Dynamic subtest ccs0: SUCCESS (3.241s) [80.367358] Subtest fds: SUCCESS (21.499s) [80.530788] [003/130] (938s left) api_intel_allocator (simple-allocator) [80.589209] Starting subtest: simple-allocator [80.591457] Starting dynamic subtest: basic [80.595730] Dynamic subtest basic: SUCCESS (0.007s) [80.602054] Starting dynamic subtest: parallel-one [80.773702] Dynamic subtest parallel-one: SUCCESS (0.178s) [80.779936] Starting dynamic subtest: print [80.781874] Dynamic subtest print: SUCCESS (0.000s) [80.787758] Starting dynamic subtest: reuse [80.790084] Dynamic subtest reuse: SUCCESS (0.000s) [80.795809] Starting dynamic subtest: reserve [80.797687] Dynamic subtest reserve: SUCCESS (0.000s) [80.803204] Starting dynamic subtest: fork-reopen-allocator [80.805137] Dynamic subtest fork-reopen-allocator: SUCCESS (0.015s) [80.807044] Subtest simple-allocator: SUCCESS (0.201s) [80.917776] [004/130] (938s left) gen9_exec_parse (bb-start-cmd) [80.989691] Subtest bb-start-cmd: SKIP (0.000s) [81.040776] [005/130] (938s left) perf_pmu (semaphore-wait-idle) [81.080127] Starting subtest: semaphore-wait-idle [81.082474] Starting dynamic subtest: rcs0 [81.595141] Dynamic subtest rcs0: SUCCESS (0.513s) [81.603169] Starting dynamic subtest: bcs0 [82.110867] Dynamic subtest bcs0: SUCCESS (0.515s) [82.120588] Starting dynamic subtest: vcs0 [82.627298] Dynamic subtest vcs0: SUCCESS (0.515s) [82.635958] Starting dynamic subtest: vcs1 [83.137093] Dynamic subtest vcs1: SUCCESS (0.512s) [83.147562] Starting dynamic subtest: vecs0 [83.652101] Dynamic subtest vecs0: SUCCESS (0.513s) [83.662101] Starting dynamic subtest: ccs0 [84.166891] Dynamic subtest ccs0: SUCCESS (0.515s) [84.172492] Subtest semaphore-wait-idle: SUCCESS (3.085s) [84.287658] [006/130] (934s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-indfb-pgflip-blt) [84.395696] Starting subtest: fbcpsr-2p-scndscrn-indfb-pgflip-blt [84.399834] Subtest fbcpsr-2p-scndscrn-indfb-pgflip-blt: SKIP (0.000s) [84.477811] [007/130] (934s left) kms_psr (fbc-psr2-no-drrs) [84.577097] Starting subtest: fbc-psr2-no-drrs [84.579528] Starting dynamic subtest: eDP-1 [84.584097] Dynamic subtest eDP-1: SKIP (0.000s) [84.588644] Subtest fbc-psr2-no-drrs: SKIP (0.000s) [84.628257] [008/130] (934s left) kms_lease (multimaster-lease) [84.726158] Starting subtest: multimaster-lease [84.731334] Subtest multimaster-lease: SUCCESS (0.003s) [84.825844] [009/130] (934s left) gem_mmap_offset (open-flood) [84.878904] Starting subtest: open-flood [106.369175] Subtest open-flood: SUCCESS (21.493s) [106.457561] [010/130] (912s left) kms_cursor_legacy (cursorb-vs-flipa-atomic-transitions-varying-size) [106.605929] Starting subtest: cursorB-vs-flipA-atomic-transitions-varying-size [106.609660] Subtest cursorB-vs-flipA-atomic-transitions-varying-size: SKIP (0.001s) [106.693826] [011/130] (912s left) gem_exec_balancer (invalid-bonds) [106.763978] Starting subtest: invalid-bonds [106.767687] Subtest invalid-bonds: SKIP (0.000s) [107.045811] [012/130] (912s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-16bpp-4tile-downscaling) [107.146058] Starting subtest: flip-64bpp-4tile-to-16bpp-4tile-downscaling [107.148472] Starting dynamic subtest: pipe-A-default-mode [108.279052] Dynamic subtest pipe-A-default-mode: SKIP (1.128s) [108.287233] Subtest flip-64bpp-4tile-to-16bpp-4tile-downscaling: SKIP (1.129s) [108.398733] [013/130] (910s left) gem_userptr_blits (map-fixed-invalidate-overlap-busy) [108.469721] Starting subtest: map-fixed-invalidate-overlap-busy [108.474406] Subtest map-fixed-invalidate-overlap-busy: SKIP (0.000s) [108.569987] [014/130] (910s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-render) [108.679305] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-render [108.683618] Subtest psr-2p-primscrn-pri-shrfb-draw-render: SKIP (0.000s) [108.749692] [015/130] (910s left) perf_pmu (interrupts) [108.790412] Starting subtest: interrupts [109.922458] Subtest interrupts: SUCCESS (1.132s) [110.027609] [016/130] (909s left) kms_frontbuffer_tracking (fbcpsr-2p-indfb-fliptrack-mmap-gtt) [110.137254] Starting subtest: fbcpsr-2p-indfb-fliptrack-mmap-gtt [110.141265] Subtest fbcpsr-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [110.217864] [017/130] (909s left) gem_watchdog (default-virtual) [110.294328] Starting subtest: default-virtual [116.563139] Subtest default-virtual: SUCCESS (6.280s) [116.647950] [018/130] (902s left) kms_cursor_legacy (flip-vs-cursor-toggle) [116.776659] Starting subtest: flip-vs-cursor-toggle [124.370166] Subtest flip-vs-cursor-toggle: SUCCESS (7.593s) [125.142360] [019/130] (894s left) syncobj_timeline (invalid-signal-illegal-point) [125.200546] Starting subtest: invalid-signal-illegal-point [125.202809] Subtest invalid-signal-illegal-point: SUCCESS (0.000s) [125.280656] [020/130] (894s left) gem_create (create-ext-placement-each) [125.461421] Starting subtest: create-ext-placement-each [125.464488] Subtest create-ext-placement-each: SUCCESS (0.000s) [125.558129] [021/130] (893s left) gem_exec_schedule (preempt-contexts) [125.635215] Starting subtest: preempt-contexts [125.637241] Starting dynamic subtest: rcs0 [125.682042] Dynamic subtest rcs0: SUCCESS (0.051s) [125.695790] Starting dynamic subtest: bcs0 [125.732040] Dynamic subtest bcs0: SUCCESS (0.050s) [125.740547] Starting dynamic subtest: vcs0 [125.772949] Dynamic subtest vcs0: SUCCESS (0.040s) [125.780387] Starting dynamic subtest: vcs1 [125.820786] Dynamic subtest vcs1: SUCCESS (0.044s) [125.829541] Starting dynamic subtest: vecs0 [125.852345] Dynamic subtest vecs0: SUCCESS (0.035s) [125.859714] Starting dynamic subtest: ccs0 [125.899454] Dynamic subtest ccs0: SUCCESS (0.045s) [125.901789] Subtest preempt-contexts: SUCCESS (0.268s) [127.050415] [022/130] (892s left) kms_cursor_edge_walk (64x64-right-edge) [127.146987] Starting subtest: 64x64-right-edge [127.149178] Starting dynamic subtest: pipe-A-eDP-1 [130.616885] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.465s) [130.629560] Starting dynamic subtest: pipe-D-eDP-1 [133.732366] Dynamic subtest pipe-D-eDP-1: SUCCESS (3.117s) [133.734809] Subtest 64x64-right-edge: SUCCESS (6.585s) [134.522225] [023/130] (885s left) syncobj_timeline (signal) [134.572874] Starting subtest: signal [134.575091] Subtest signal: SUCCESS (0.001s) [134.636978] [024/130] (884s left) kms_big_fb (4-tiled-8bpp-rotate-90) [134.732671] Starting subtest: 4-tiled-8bpp-rotate-90 [134.752812] Subtest 4-tiled-8bpp-rotate-90: SKIP (0.020s) [134.858764] [025/130] (884s left) gem_ctx_isolation (nonpriv) [134.925044] Starting subtest: nonpriv [134.927471] Starting dynamic subtest: rcs0 [135.169502] Dynamic subtest rcs0: SUCCESS (0.244s) [135.180048] Starting dynamic subtest: bcs0 [135.427922] Dynamic subtest bcs0: SUCCESS (0.258s) [135.437192] Starting dynamic subtest: vcs0 [135.674087] Dynamic subtest vcs0: SUCCESS (0.246s) [135.683198] Starting dynamic subtest: vcs1 [137.150535] Dynamic subtest vcs1: SUCCESS (1.475s) [137.158278] Starting dynamic subtest: vecs0 [139.168403] Dynamic subtest vecs0: SUCCESS (2.017s) [139.175463] Starting dynamic subtest: ccs0 [141.216275] Dynamic subtest ccs0: SUCCESS (2.047s) [141.218619] Subtest nonpriv: SUCCESS (6.291s) [141.441546] [026/130] (878s left) gem_busy (busy) [141.505262] Starting subtest: busy [141.507647] Starting dynamic subtest: all-engines [141.593508] Dynamic subtest all-engines: SUCCESS (0.088s) [141.601575] Starting dynamic subtest: rcs0 [142.897660] Dynamic subtest rcs0: SUCCESS (1.303s) [142.905802] Starting dynamic subtest: bcs0 [142.971503] Dynamic subtest bcs0: SUCCESS (0.072s) [142.980618] Starting dynamic subtest: vcs0 [143.011888] Dynamic subtest vcs0: SUCCESS (0.039s) [143.020866] Starting dynamic subtest: vcs1 [144.942993] Dynamic subtest vcs1: SUCCESS (1.929s) [144.952286] Starting dynamic subtest: vecs0 [146.928070] Dynamic subtest vecs0: SUCCESS (1.983s) [146.937245] Starting dynamic subtest: ccs0 [148.913352] Dynamic subtest ccs0: SUCCESS (1.985s) [148.915463] Subtest busy: SUCCESS (7.404s) [149.257760] [027/130] (870s left) kms_big_fb (x-tiled-8bpp-rotate-180) [149.371404] Starting subtest: x-tiled-8bpp-rotate-180 [150.317119] Subtest x-tiled-8bpp-rotate-180: SUCCESS (0.946s) [150.441481] [028/130] (869s left) kms_frontbuffer_tracking (psr-1p-offscren-pri-shrfb-draw-render) [150.552645] Starting subtest: psr-1p-offscren-pri-shrfb-draw-render [155.256345] Subtest psr-1p-offscren-pri-shrfb-draw-render: SUCCESS (4.706s) [155.393927] [029/130] (864s left) kms_frontbuffer_tracking (fbc-rgb565-draw-blt) [155.506070] Starting subtest: fbc-rgb565-draw-blt [158.662777] Subtest fbc-rgb565-draw-blt: SUCCESS (3.160s) [158.800890] [030/130] (860s left) kms_content_protection (lic-type-0) [158.938720] Starting subtest: lic-type-0 [162.346256] Subtest lic-type-0: SKIP (3.404s) [163.499564] [031/130] (856s left) gem_softpin (allocator-nopin-reserve) [163.554680] Starting subtest: allocator-nopin-reserve [163.624716] Subtest allocator-nopin-reserve: SUCCESS (0.070s) [163.727441] [032/130] (855s left) gem_mmap (bad-object) [163.790262] Subtest bad-object: SKIP (0.000s) [163.833391] [033/130] (855s left) kms_fence_pin_leak [163.983641] [034/130] (855s left) kms_cursor_legacy (basic-flip-before-cursor-varying-size) [164.097453] Starting subtest: basic-flip-before-cursor-varying-size [165.031694] Subtest basic-flip-before-cursor-varying-size: SUCCESS (0.935s) [165.823412] [035/130] (853s left) kms_flip (2x-flip-vs-dpms) [165.924150] Starting subtest: 2x-flip-vs-dpms [165.935093] Subtest 2x-flip-vs-dpms: SKIP (0.010s) [165.992836] [036/130] (853s left) core_getstats [166.116335] [037/130] (853s left) kms_big_fb (linear-32bpp-rotate-270) [166.215340] Starting subtest: linear-32bpp-rotate-270 [166.234387] Subtest linear-32bpp-rotate-270: SKIP (0.019s) [166.326130] [038/130] (853s left) kms_async_flips (crc) [166.421058] Starting subtest: crc [166.784462] Starting dynamic subtest: pipe-A-eDP-1 [169.621738] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.838s) [169.997241] Starting dynamic subtest: pipe-B-eDP-1 [172.851493] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.863s) [173.215671] Starting dynamic subtest: pipe-C-eDP-1 [176.043585] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.834s) [176.404929] Starting dynamic subtest: pipe-D-eDP-1 [179.263532] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.865s) [179.265714] Subtest crc: SUCCESS (12.840s) [180.439142] [039/130] (839s left) kms_pm_rpm (modeset-lpsp) [182.071327] Starting subtest: modeset-lpsp [183.497665] Subtest modeset-lpsp: SUCCESS (1.425s) [183.573061] [040/130] (836s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff) [183.681808] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff [183.685966] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [183.757994] [041/130] (836s left) kms_atomic (plane-invalid-params-fence) [183.850309] Starting subtest: plane-invalid-params-fence [183.852652] Starting dynamic subtest: pipe-A-eDP-1 [184.202832] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.349s) [184.204865] Subtest plane-invalid-params-fence: SUCCESS (0.351s) [185.048572] [042/130] (834s left) gem_exec_reloc (basic-wc-read-active) [185.115762] Subtest basic-wc-read-active: SKIP (0.000s) [185.178211] [043/130] (834s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75) [185.279073] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75 [185.281252] Starting dynamic subtest: pipe-A [185.299249] Dynamic subtest pipe-A: SKIP (0.013s) [185.307209] Starting dynamic subtest: pipe-B [185.323296] Dynamic subtest pipe-B: SKIP (0.024s) [185.330974] Starting dynamic subtest: pipe-C [185.351657] Dynamic subtest pipe-C: SKIP (0.028s) [185.358920] Starting dynamic subtest: pipe-D [185.375377] Dynamic subtest pipe-D: SKIP (0.024s) [185.379225] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.092s) [185.449432] [044/130] (834s left) gem_exec_balancer (bonded-semaphore) [185.506070] Starting subtest: bonded-semaphore [185.510240] Subtest bonded-semaphore: SKIP (0.000s) [185.784400] [045/130] (834s left) core_setmaster (master-drop-set-root) [187.705865] Starting subtest: master-drop-set-root [187.717059] Subtest master-drop-set-root: SUCCESS (0.012s) [187.742798] [046/130] (832s left) gem_set_tiling_vs_blt (tiled-to-untiled) [187.802592] Subtest tiled-to-untiled: SKIP (0.000s) [187.850080] [047/130] (832s left) kms_vblank (crtc-id) [187.937201] Starting subtest: crtc-id [187.939545] Starting dynamic subtest: pipe-A-eDP-1 [188.368086] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.428s) [188.381211] Starting dynamic subtest: pipe-D-eDP-1 [189.485601] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.115s) [189.487978] Subtest crtc-id: SUCCESS (1.547s) [190.295650] [048/130] (829s left) kms_psr2_sf (pr-primary-plane-update-sf-dmg-area) [195.247795] Starting subtest: pr-primary-plane-update-sf-dmg-area [195.252567] Subtest pr-primary-plane-update-sf-dmg-area: SKIP (0.002s) [196.088025] [049/130] (823s left) kms_frontbuffer_tracking (fbc-shrfb-scaledprimary) [196.198655] Starting subtest: fbc-shrfb-scaledprimary [200.276769] Subtest fbc-shrfb-scaledprimary: SUCCESS (4.081s) [200.415205] [050/130] (819s left) gem_exec_suspend (basic-s3-devices) [200.480021] Starting subtest: basic-S3-devices [200.482481] Starting dynamic subtest: smem [210.921747] Dynamic subtest smem: SUCCESS (10.439s) [210.924896] Subtest basic-S3-devices: SUCCESS (10.440s) [211.084315] [051/130] (808s left) kms_cursor_crc (cursor-size-hints) [211.198811] Starting subtest: cursor-size-hints [211.201161] Starting dynamic subtest: pipe-A-eDP-1 [211.802732] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.602s) [211.810804] Starting dynamic subtest: pipe-D-eDP-1 [213.023889] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.219s) [213.026237] Subtest cursor-size-hints: SUCCESS (1.823s) [213.813611] [052/130] (806s left) kms_frontbuffer_tracking (pipe-fbc-rte) [213.922290] Starting subtest: pipe-fbc-rte [213.928582] Starting dynamic subtest: pipe-B-eDP-1 [218.347144] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.429s) [218.355057] Subtest pipe-fbc-rte: SUCCESS (4.431s) [219.532190] [053/130] (800s left) kms_feature_discovery (dp-mst) [219.634076] Starting subtest: dp-mst [219.638462] Subtest dp-mst: SKIP (0.000s) [219.684448] [054/130] (800s left) gem_exec_whisper (basic-fds) [219.752440] Starting subtest: basic-fds [226.890993] Subtest basic-fds: SUCCESS (7.134s) [227.052473] [055/130] (793s left) kms_plane_lowres (tiling-x) [227.152041] Starting subtest: tiling-x [227.154203] Starting dynamic subtest: pipe-A-eDP-1 [227.158564] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [227.458447] Starting dynamic subtest: pipe-B-eDP-1 [227.463162] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [227.470734] Starting dynamic subtest: pipe-C-eDP-1 [227.475459] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [227.484432] Starting dynamic subtest: pipe-D-eDP-1 [227.488618] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [227.492783] Subtest tiling-x: SKIP (0.305s) [228.264280] [056/130] (791s left) gem_render_copy (yf-tiled) [228.325448] Starting subtest: yf-tiled [228.329564] Subtest yf-tiled: SKIP (0.000s) [228.486737] [057/130] (791s left) kms_addfb_basic (addfb25-x-tiled-legacy) [228.555617] Subtest addfb25-x-tiled-legacy: SKIP (0.000s) [228.689300] [058/130] (791s left) kms_rotation_crc (primary-rotation-90) [228.780065] Starting subtest: primary-rotation-90 [228.784280] Subtest primary-rotation-90: SKIP (0.001s) [228.833709] [059/130] (791s left) gem_softpin (zero) [228.894579] Starting subtest: zero [228.899061] Subtest zero: SUCCESS (0.004s) [229.000137] [060/130] (791s left) kms_ccs (crc-primary-basic-4-tiled-dg2-rc-ccs-cc) [229.101751] Starting subtest: crc-primary-basic-4-tiled-dg2-rc-ccs-cc [229.104057] Starting dynamic subtest: pipe-A-eDP-1 [229.126424] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [229.134317] Starting dynamic subtest: pipe-B-eDP-1 [229.155353] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [229.162633] Starting dynamic subtest: pipe-C-eDP-1 [229.183613] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [229.190820] Starting dynamic subtest: pipe-D-eDP-1 [229.212628] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [229.216959] Subtest crc-primary-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.005s) [231.210231] [061/130] (789s left) kms_vblank (accuracy-idle) [231.313070] Starting subtest: accuracy-idle [231.315355] Starting dynamic subtest: pipe-A-eDP-1 [233.067186] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.752s) [233.080860] Starting dynamic subtest: pipe-D-eDP-1 [235.460072] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.390s) [235.462459] Subtest accuracy-idle: SUCCESS (4.145s) [236.258949] [062/130] (784s left) gem_ctx_param (invalid-param-set) [236.316752] Starting subtest: invalid-param-set [236.319090] Subtest invalid-param-set: SUCCESS (0.000s) [236.359418] [063/130] (783s left) gem_mmap_wc (write-read) [236.421826] Subtest write-read: SKIP (0.000s) [236.477649] [064/130] (783s left) kms_big_fb (yf-tiled-32bpp-rotate-0) [236.571305] Starting subtest: yf-tiled-32bpp-rotate-0 [236.575242] Subtest yf-tiled-32bpp-rotate-0: SKIP (0.000s) [236.665516] [065/130] (783s left) gem_softpin (evict-active) [236.725943] Starting subtest: evict-active [236.732197] Subtest evict-active: SUCCESS (0.006s) [236.832737] [066/130] (783s left) gem_mmap_gtt (basic-read-write-distinct) [236.899546] Subtest basic-read-write-distinct: SKIP (0.000s) [236.943584] [067/130] (783s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-onoff) [237.049270] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-onoff [237.053383] Subtest fbcpsr-2p-primscrn-cur-indfb-onoff: SKIP (0.000s) [237.124133] [068/130] (783s left) kms_rotation_crc (bad-pixel-format) [237.223654] Starting subtest: bad-pixel-format [237.227469] Subtest bad-pixel-format: SKIP (0.001s) [237.277395] [069/130] (783s left) syncobj_timeline (etime-multi-wait-all-submitted-signaled) [237.330932] Starting subtest: etime-multi-wait-all-submitted-signaled [237.438401] Subtest etime-multi-wait-all-submitted-signaled: SUCCESS (0.107s) [237.493852] [070/130] (782s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt) [237.602418] Starting subtest: psr-rgb565-draw-mmap-gtt [237.606344] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s) [237.673628] [071/130] (782s left) i915_hangman (engine-engine-error) [237.744670] Starting subtest: engine-engine-error [237.746993] Starting dynamic subtest: rcs0 [238.920231] Dynamic subtest rcs0: SUCCESS (1.176s) [238.931772] Starting dynamic subtest: bcs0 [240.905500] Dynamic subtest bcs0: SUCCESS (1.983s) [240.916589] Starting dynamic subtest: vcs0 [242.886849] Dynamic subtest vcs0: SUCCESS (1.980s) [242.901629] Starting dynamic subtest: vcs1 [244.871874] Dynamic subtest vcs1: SUCCESS (1.982s) [244.883233] Starting dynamic subtest: vecs0 [246.922972] Dynamic subtest vecs0: SUCCESS (2.048s) [246.934374] Starting dynamic subtest: ccs0 [248.905122] Dynamic subtest ccs0: SUCCESS (1.979s) [248.908304] Subtest engine-engine-error: SUCCESS (11.161s) [248.969581] [072/130] (771s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-180-hflip) [249.069326] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-180-hflip [249.073804] Subtest y-tiled-max-hw-stride-64bpp-rotate-180-hflip: SKIP (0.000s) [249.158536] [073/130] (771s left) kms_chamelium_edid (dp-edid-change-during-suspend) [249.545206] Subtest dp-edid-change-during-suspend: SKIP (0.000s) [250.361270] [074/130] (770s left) gem_exec_schedule (submit-golden-slice) [250.441895] Starting subtest: submit-golden-slice [250.444157] Starting dynamic subtest: rcs0 [250.465278] Dynamic subtest rcs0: SUCCESS (0.030s) [250.474891] Starting dynamic subtest: bcs0 [250.490886] Dynamic subtest bcs0: SUCCESS (0.025s) [250.499189] Starting dynamic subtest: vcs0 [250.518174] Dynamic subtest vcs0: SUCCESS (0.027s) [250.526034] Starting dynamic subtest: vcs1 [250.542520] Dynamic subtest vcs1: SUCCESS (0.024s) [250.550541] Starting dynamic subtest: vecs0 [250.567341] Dynamic subtest vecs0: SUCCESS (0.024s) [250.580984] Starting dynamic subtest: ccs0 [250.596791] Dynamic subtest ccs0: SUCCESS (0.029s) [250.599249] Subtest submit-golden-slice: SUCCESS (0.162s) [252.003938] [075/130] (768s left) syncobj_wait (multi-wait-for-submit-submitted-signaled) [252.072207] Starting subtest: multi-wait-for-submit-submitted-signaled [252.074722] Subtest multi-wait-for-submit-submitted-signaled: SUCCESS (0.000s) [252.158142] [076/130] (768s left) kms_lease (atomic-implicit-crtc) [252.259013] Starting subtest: atomic-implicit-crtc [252.261307] Starting dynamic subtest: pipe-A-eDP-1 [252.272658] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s) [252.281185] Starting dynamic subtest: pipe-B-eDP-1 [252.292127] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.006s) [252.300301] Starting dynamic subtest: pipe-C-eDP-1 [252.311070] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.010s) [252.318976] Starting dynamic subtest: pipe-D-eDP-1 [252.329390] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.007s) [252.331537] Subtest atomic-implicit-crtc: SUCCESS (0.028s) [252.380057] [077/130] (768s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-mmap-wc) [252.485024] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-mmap-wc [255.635311] Subtest fbc-1p-primscrn-pri-indfb-draw-mmap-wc: SUCCESS (3.154s) [255.750487] [078/130] (764s left) kms_vblank (query-forked-hang) [255.839573] Starting subtest: query-forked-hang [255.841478] Starting dynamic subtest: pipe-A-eDP-1 [282.721450] Dynamic subtest pipe-A-eDP-1: SUCCESS (26.878s) [282.735744] Starting dynamic subtest: pipe-D-eDP-1 [297.691102] Dynamic subtest pipe-D-eDP-1: SUCCESS (14.966s) [297.693473] Subtest query-forked-hang: SUCCESS (41.849s) [298.547892] [079/130] (722s left) gem_mmap_gtt (hang-user) [298.618096] Subtest hang-user: SKIP (0.000s) [298.660426] [080/130] (721s left) gem_exec_reloc (basic-wc-active) [298.730181] Subtest basic-wc-active: SKIP (0.000s) [298.773493] [081/130] (721s left) gem_exec_gttfill (all-engines) [298.836785] Starting subtest: all-engines [321.856584] Subtest all-engines: SUCCESS (23.019s) [322.023078] [082/130] (698s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs-cc) [322.136075] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs-cc [322.138276] Starting dynamic subtest: pipe-A-eDP-1 [322.161243] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [322.170100] Starting dynamic subtest: pipe-B-eDP-1 [322.194683] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [322.203081] Starting dynamic subtest: pipe-C-eDP-1 [322.224892] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [322.234579] Starting dynamic subtest: pipe-D-eDP-1 [322.256221] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [322.260241] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s) [324.295232] [083/130] (696s left) gem_mmap_gtt (medium-copy-xy) [324.355137] Subtest medium-copy-XY: SKIP (0.000s) [324.390341] [084/130] (696s left) kms_ccs (crc-primary-suspend-y-tiled-ccs) [324.493181] Starting subtest: crc-primary-suspend-y-tiled-ccs [324.495529] Starting dynamic subtest: pipe-A-eDP-1 [324.504861] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [324.514593] Starting dynamic subtest: pipe-B-eDP-1 [324.523391] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [324.531758] Starting dynamic subtest: pipe-C-eDP-1 [324.540252] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [324.548409] Starting dynamic subtest: pipe-D-eDP-1 [324.556901] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [324.561055] Subtest crc-primary-suspend-y-tiled-ccs: SKIP (0.004s) [326.648390] [085/130] (694s left) kms_vrr (max-min) [326.747079] Starting subtest: max-min [326.753600] Subtest max-min: SKIP (0.000s) [326.801586] [086/130] (693s left) drm_fdinfo (virtual-busy-idle-all) [326.842257] Starting subtest: virtual-busy-idle-all [326.846990] Subtest virtual-busy-idle-all: SKIP (0.000s) [326.887409] [087/130] (693s left) gem_lmem_swapping (massive-random) [326.936690] Subtest massive-random: SKIP (0.000s) [326.954279] [088/130] (693s left) gem_exec_balancer (semaphore) [327.018799] Starting subtest: semaphore [328.951360] Subtest semaphore: SUCCESS (1.931s) [329.250396] [089/130] (691s left) kms_ccs (bad-pixel-format-y-tiled-ccs) [329.348026] Starting subtest: bad-pixel-format-y-tiled-ccs [329.350308] Starting dynamic subtest: pipe-A-eDP-1 [329.375918] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [329.385466] Starting dynamic subtest: pipe-B-eDP-1 [329.407239] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [329.415495] Starting dynamic subtest: pipe-C-eDP-1 [329.437207] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [329.445953] Starting dynamic subtest: pipe-D-eDP-1 [329.469506] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [329.473952] Subtest bad-pixel-format-y-tiled-ccs: SKIP (0.004s) [331.491635] [090/130] (689s left) perf (low-oa-exponent-permissions) [331.621364] Starting subtest: low-oa-exponent-permissions [331.662535] Subtest low-oa-exponent-permissions: SUCCESS (0.041s) [331.690685] [091/130] (689s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render) [331.800738] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render [331.804802] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s) [331.902772] [092/130] (688s left) gem_softpin (invalid) [331.966188] Starting subtest: invalid [331.973861] Subtest invalid: SUCCESS (0.005s) [332.099142] [093/130] (688s left) kms_frontbuffer_tracking (fbc-1p-primscrn-spr-indfb-draw-pwrite) [332.211273] Starting subtest: fbc-1p-primscrn-spr-indfb-draw-pwrite [335.455156] Subtest fbc-1p-primscrn-spr-indfb-draw-pwrite: SUCCESS (3.248s) [335.573878] [094/130] (685s left) kms_plane (planar-pixel-format-settings) [335.690025] Starting subtest: planar-pixel-format-settings [335.723964] Subtest planar-pixel-format-settings: SUCCESS (0.033s) [335.821160] [095/130] (685s left) kms_psr (psr2-cursor-mmap-cpu) [335.908178] Starting subtest: psr2-cursor-mmap-cpu [335.910168] Starting dynamic subtest: eDP-1 [337.591103] Dynamic subtest eDP-1: SUCCESS (1.681s) [337.593286] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.682s) [338.359656] [096/130] (682s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt) [338.468547] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt [338.472800] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [338.546559] [097/130] (682s left) perf_pmu (init-sema) [338.589924] Starting subtest: init-sema [338.592396] Starting dynamic subtest: rcs0 [338.594823] Dynamic subtest rcs0: SUCCESS (0.002s) [338.605944] Starting dynamic subtest: bcs0 [338.607851] Dynamic subtest bcs0: SUCCESS (0.001s) [338.616028] Starting dynamic subtest: vcs0 [338.618236] Dynamic subtest vcs0: SUCCESS (0.001s) [338.626101] Starting dynamic subtest: vcs1 [338.628530] Dynamic subtest vcs1: SUCCESS (0.001s) [338.636375] Starting dynamic subtest: vecs0 [338.638298] Dynamic subtest vecs0: SUCCESS (0.001s) [338.646265] Starting dynamic subtest: ccs0 [338.648455] Dynamic subtest ccs0: SUCCESS (0.001s) [338.650667] Subtest init-sema: SUCCESS (0.008s) [338.668312] [098/130] (682s left) prime_vgem (fence-read-hang) [338.745390] Subtest fence-read-hang: SKIP (0.000s) [338.827136] [099/130] (682s left) kms_pm_backlight (bad-brightness) [338.925243] Starting subtest: bad-brightness [338.991202] Starting dynamic subtest: eDP-1 [339.277615] Dynamic subtest eDP-1: SUCCESS (0.284s) [339.280103] Subtest bad-brightness: SUCCESS (0.353s) [340.086144] [100/130] (680s left) gem_eio (wait-wedge-immediate) [340.320100] Starting subtest: wait-wedge-immediate [341.992608] Subtest wait-wedge-immediate: SUCCESS (1.673s) [342.129256] [101/130] (678s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc) [342.236190] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc [342.240268] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [342.334704] [102/130] (678s left) syncobj_timeline (invalid-wait-illegal-handle) [342.407855] Starting subtest: invalid-wait-illegal-handle [342.410238] Subtest invalid-wait-illegal-handle: SUCCESS (0.000s) [342.509229] [103/130] (678s left) gem_ctx_bad_destroy (invalid-default-ctx) [342.565824] Starting subtest: invalid-default-ctx [342.568175] Subtest invalid-default-ctx: SUCCESS (0.000s) [342.619411] [104/130] (678s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf) [347.551568] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf [347.554080] Starting dynamic subtest: pipe-A-eDP-1 [358.985018] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.429s) [358.993704] Starting dynamic subtest: pipe-B-eDP-1 [370.397178] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.409s) [370.399591] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (22.843s) [371.231530] [105/130] (649s left) kms_psr (pr-cursor-render) [371.329322] Starting subtest: pr-cursor-render [371.334029] Subtest pr-cursor-render: SKIP (0.000s) [371.384883] [106/130] (649s left) kms_draw_crc (draw-method-mmap-gtt) [371.484966] Starting subtest: draw-method-mmap-gtt [371.489080] Subtest draw-method-mmap-gtt: SKIP (0.000s) [371.567285] [107/130] (649s left) gem_exec_schedule (smoketest-all) [371.648718] Starting subtest: smoketest-all [404.045296] Subtest smoketest-all: SUCCESS (32.357s) [405.006044] [108/130] (616s left) gem_pread (bench) [405.063052] Subtest bench: SKIP (0.000s) [405.096036] [109/130] (616s left) kms_draw_crc (fill-fb) [405.195086] Starting subtest: fill-fb [405.602872] Subtest fill-fb: SUCCESS (0.410s) [405.700156] [110/130] (615s left) gem_ctx_persistence (legacy-engines-persistence) [405.768098] Starting subtest: legacy-engines-persistence [405.770394] Starting dynamic subtest: render [406.578299] Dynamic subtest render: SUCCESS (0.809s) [406.590296] Starting dynamic subtest: bsd1 [407.370285] Dynamic subtest bsd1: SUCCESS (0.789s) [407.382403] Starting dynamic subtest: bsd2 [408.151125] Dynamic subtest bsd2: SUCCESS (0.779s) [408.162026] Starting dynamic subtest: blt [408.962077] Dynamic subtest blt: SUCCESS (0.809s) [408.978073] Starting dynamic subtest: vebox [409.784829] Dynamic subtest vebox: SUCCESS (0.819s) [409.787448] Subtest legacy-engines-persistence: SUCCESS (4.017s) [409.846451] [111/130] (611s left) kms_frontbuffer_tracking (psr-1p-primscrn-cur-indfb-onoff) [409.948502] Starting subtest: psr-1p-primscrn-cur-indfb-onoff [414.601234] Subtest psr-1p-primscrn-cur-indfb-onoff: SUCCESS (4.657s) [414.717176] [112/130] (606s left) kms_color (ctm-signed) [414.824148] Starting subtest: ctm-signed [414.826378] Starting dynamic subtest: pipe-A-eDP-1 [416.806151] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.981s) [416.815467] Starting dynamic subtest: pipe-B-eDP-1 [419.480604] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.672s) [419.490234] Starting dynamic subtest: pipe-C-eDP-1 [422.055641] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.575s) [422.064519] Starting dynamic subtest: pipe-D-eDP-1 [424.621534] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.566s) [424.623856] Subtest ctm-signed: SUCCESS (9.796s) [425.462931] [113/130] (595s left) gem_exec_params (invalid-bsd-ring) [425.527785] Starting subtest: invalid-bsd-ring [425.530163] Subtest invalid-bsd-ring: SUCCESS (0.000s) [425.628272] [114/130] (595s left) syncobj_eventfd (binary-wait-before-signal) [425.701612] Starting subtest: binary-wait-before-signal [425.733048] Subtest binary-wait-before-signal: SUCCESS (0.031s) [425.789179] [115/130] (595s left) kms_frontbuffer_tracking (fbcpsr-modesetfrombusy) [425.900774] Starting subtest: fbcpsr-modesetfrombusy [429.953098] Subtest fbcpsr-modesetfrombusy: SUCCESS (4.057s) [430.092309] [116/130] (591s left) kms_psr (psr-cursor-plane-onoff) [430.196949] Starting subtest: psr-cursor-plane-onoff [430.198986] Starting dynamic subtest: eDP-1 [431.828845] Dynamic subtest eDP-1: SUCCESS (1.630s) [431.831194] Subtest psr-cursor-plane-onoff: SUCCESS (1.630s) [432.672151] [117/130] (588s left) sw_sync (timeline_closed_signaled) [432.711339] Starting subtest: timeline_closed_signaled [432.713243] Subtest timeline_closed_signaled: SUCCESS (0.000s) [432.745473] [118/130] (588s left) kms_flip (2x-flip-vs-panning-interruptible) [432.845924] Starting subtest: 2x-flip-vs-panning-interruptible [432.857727] Subtest 2x-flip-vs-panning-interruptible: SKIP (0.010s) [432.915533] [119/130] (588s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-render) [433.025644] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-render [438.062195] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-render: SUCCESS (5.040s) [438.190112] [120/130] (583s left) kms_ccs (random-ccs-data-y-tiled-gen12-mc-ccs) [438.289128] Starting subtest: random-ccs-data-y-tiled-gen12-mc-ccs [438.291424] Starting dynamic subtest: pipe-A-eDP-1 [438.316400] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [438.325085] Starting dynamic subtest: pipe-B-eDP-1 [438.347747] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [438.356303] Starting dynamic subtest: pipe-C-eDP-1 [438.380186] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [438.389188] Starting dynamic subtest: pipe-D-eDP-1 [438.413623] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [438.417717] Subtest random-ccs-data-y-tiled-gen12-mc-ccs: SKIP (0.004s) [440.481933] [121/130] (580s left) kms_chamelium_hpd (dp-hpd-storm-disable) [440.871363] Subtest dp-hpd-storm-disable: SKIP (0.000s) [441.688179] [122/130] (579s left) kms_psr2_sf (fbc-pr-overlay-primary-update-sf-dmg-area) [446.560758] Starting subtest: fbc-pr-overlay-primary-update-sf-dmg-area [446.565541] Subtest fbc-pr-overlay-primary-update-sf-dmg-area: SKIP (0.003s) [447.411169] [123/130] (574s left) kms_cursor_crc (cursor-onscreen-256x256) [447.638064] Starting subtest: cursor-onscreen-256x256 [447.643212] Starting dynamic subtest: pipe-A-eDP-1 [449.750838] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.107s) [449.775328] Starting dynamic subtest: pipe-D-eDP-1 [452.479607] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.714s) [452.481556] Subtest cursor-onscreen-256x256: SUCCESS (4.840s) [453.257645] [124/130] (568s left) sysfs_preempt_timeout (idempotent) [453.328712] Starting subtest: idempotent [453.331411] Starting dynamic subtest: vecs0 [453.333907] Dynamic subtest vecs0: SUCCESS (0.000s) [453.344142] Starting dynamic subtest: ccs0 [453.346436] Dynamic subtest ccs0: SUCCESS (0.000s) [453.354786] Starting dynamic subtest: vcs0 [453.356980] Dynamic subtest vcs0: SUCCESS (0.000s) [453.365205] Starting dynamic subtest: bcs0 [453.367399] Dynamic subtest bcs0: SUCCESS (0.000s) [453.375588] Starting dynamic subtest: rcs0 [453.377842] Dynamic subtest rcs0: SUCCESS (0.000s) [453.386034] Starting dynamic subtest: vcs1 [453.388253] Dynamic subtest vcs1: SUCCESS (0.000s) [453.390478] Subtest idempotent: SUCCESS (0.006s) [453.431695] [125/130] (568s left) i915_pm_rpm (system-suspend-execbuf) [455.164556] Starting subtest: system-suspend-execbuf [513.704960] Subtest system-suspend-execbuf: SUCCESS (43.833s) [514.039083] [126/130] (522s left) gem_exec_schedule (fairslice) [514.115525] Starting subtest: fairslice [514.117567] Starting dynamic subtest: rcs0 [516.127349] Dynamic subtest rcs0: SUCCESS (2.016s) [516.138026] Starting dynamic subtest: bcs0 [518.147976] Dynamic subtest bcs0: SUCCESS (2.017s) [518.158475] Starting dynamic subtest: vcs0 [520.168359] Dynamic subtest vcs0: SUCCESS (2.017s) [520.177202] Starting dynamic subtest: vcs1 [522.183902] Dynamic subtest vcs1: SUCCESS (2.015s) [522.192910] Starting dynamic subtest: vecs0 [524.204624] Dynamic subtest vecs0: SUCCESS (2.018s) [524.215187] Starting dynamic subtest: ccs0 [526.221851] Dynamic subtest ccs0: SUCCESS (2.017s) [526.224221] Subtest fairslice: SUCCESS (12.104s) [526.508430] [127/130] (509s left) kms_psr (fbc-psr-primary-blt) [526.605776] Starting subtest: fbc-psr-primary-blt [526.608126] Starting dynamic subtest: eDP-1 [526.612199] Dynamic subtest eDP-1: SKIP (0.000s) [526.616342] Subtest fbc-psr-primary-blt: SKIP (0.001s) [526.659777] [128/130] (509s left) kms_flip (2x-flip-vs-panning-vs-hang) [526.755718] Starting subtest: 2x-flip-vs-panning-vs-hang [526.766725] Subtest 2x-flip-vs-panning-vs-hang: SKIP (0.010s) [526.827542] [129/130] (509s left) kms_big_fb (yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [526.934922] Starting subtest: yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [526.939422] Subtest yf-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [527.016934] [130/130] (509s left) dmabuf_sync_file (import-multiple-read-only) [527.068738] Starting subtest: import-multiple-read-only [527.071062] Subtest import-multiple-read-only: SUCCESS (0.003s) [527.099947] Closing watchdogs Done.