[60.939264] Initializing watchdogs [60.939309] /dev/watchdog0 [60.956339] [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] [60.960912] [FACT before any test] new: hardware.pci.drm_card_at_addr.0000:00:02.0: card0 [60.963870] [FACT before any test] new: kernel.kmod_is_loaded.i915: true [60.967411] [001/156] (960s left) gem_exec_balancer (bonded-false-hang) [61.253817] Starting subtest: bonded-false-hang [61.259066] Subtest bonded-false-hang: SKIP (0.000s) [61.360663] [002/156] (960s left) kms_psr (fbc-pr-sprite-mmap-cpu) [61.468913] Starting subtest: fbc-pr-sprite-mmap-cpu [61.473418] Subtest fbc-pr-sprite-mmap-cpu: SKIP (0.000s) [61.537863] [003/156] (960s left) gem_userptr_blits (sync-unmap-after-close) [61.611535] Starting subtest: sync-unmap-after-close [61.624795] Subtest sync-unmap-after-close: SUCCESS (0.014s) [61.762188] [004/156] (959s left) kms_big_fb (yf-tiled-32bpp-rotate-90) [61.860158] Starting subtest: yf-tiled-32bpp-rotate-90 [61.864450] Subtest yf-tiled-32bpp-rotate-90: SKIP (0.000s) [61.965545] [005/156] (959s left) i915_pm_rc6_residency (rc6-idle) [62.026720] Starting subtest: rc6-idle [62.073712] Starting dynamic subtest: gt0-rcs0 [86.395753] Dynamic subtest gt0-rcs0: SUCCESS (24.305s) [86.404265] Starting dynamic subtest: gt0-bcs0 [110.690210] Dynamic subtest gt0-bcs0: SUCCESS (24.291s) [110.698173] Starting dynamic subtest: gt0-ccs0 [134.978364] Dynamic subtest gt0-ccs0: SUCCESS (24.286s) [134.989231] Starting dynamic subtest: gt1-vcs0 [141.152916] Dynamic subtest gt1-vcs0: SUCCESS (6.171s) [141.164091] Starting dynamic subtest: gt1-vecs0 [147.324582] Dynamic subtest gt1-vecs0: SUCCESS (6.170s) [147.328203] Subtest rc6-idle: SUCCESS (85.280s) [147.400698] [006/156] (874s left) kms_draw_crc (draw-method-mmap-gtt) [147.498979] Starting subtest: draw-method-mmap-gtt [147.503563] Subtest draw-method-mmap-gtt: SKIP (0.000s) [147.593072] [007/156] (874s left) kms_frontbuffer_tracking (pipe-fbc-rte) [147.692306] Starting subtest: pipe-fbc-rte [147.700593] Starting dynamic subtest: pipe-B-eDP-1 [153.236075] Dynamic subtest pipe-B-eDP-1: SUCCESS (5.549s) [153.243661] Subtest pipe-fbc-rte: SUCCESS (5.551s) [155.607216] [008/156] (866s left) gem_mmap_gtt (flink-race) [155.666260] Subtest flink-race: SKIP (0.000s) [155.724551] [009/156] (866s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt) [155.829667] Starting subtest: psrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt [155.834121] Subtest psrhdr-1p-primscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [155.936356] [010/156] (865s left) sysfs_heartbeat_interval (nopreempt) [155.987068] Starting subtest: nopreempt [155.989328] Starting dynamic subtest: vecs0 [165.315864] Dynamic subtest vecs0: SUCCESS (9.327s) [165.327524] Starting dynamic subtest: ccs0 [169.256710] Dynamic subtest ccs0: SUCCESS (3.939s) [169.268192] Starting dynamic subtest: vcs0 [179.329597] Dynamic subtest vcs0: SUCCESS (10.069s) [179.340444] Starting dynamic subtest: bcs0 [183.202910] Dynamic subtest bcs0: SUCCESS (3.874s) [183.212182] Starting dynamic subtest: rcs0 [187.233990] Dynamic subtest rcs0: SUCCESS (4.030s) [187.244423] Starting dynamic subtest: vcs1 [197.309825] Dynamic subtest vcs1: SUCCESS (10.073s) [197.312442] Subtest nopreempt: SUCCESS (41.319s) [197.384898] [011/156] (824s left) syncobj_timeline (invalid-transfer-bad-pad) [197.435048] Starting subtest: invalid-transfer-bad-pad [197.437326] Subtest invalid-transfer-bad-pad: SUCCESS (0.000s) [197.526970] [012/156] (824s left) kms_frontbuffer_tracking (hdr-1p-primscrn-indfb-msflip-blt) [197.628698] Starting subtest: hdr-1p-primscrn-indfb-msflip-blt [197.633134] Subtest hdr-1p-primscrn-indfb-msflip-blt: SKIP (0.000s) [197.736243] [013/156] (824s left) sriov_basic (enable-vfs-bind-unbind-each) [197.786801] Starting subtest: enable-vfs-bind-unbind-each [197.789077] Starting dynamic subtest: numvfs-1 [197.864481] Dynamic subtest numvfs-1: FAIL (0.015s) [197.873902] Starting dynamic subtest: numvfs-2 [197.946061] Dynamic subtest numvfs-2: FAIL (0.004s) [197.956715] Starting dynamic subtest: numvfs-3 [198.029833] Dynamic subtest numvfs-3: FAIL (0.004s) [198.041331] Starting dynamic subtest: numvfs-4 [198.113484] Dynamic subtest numvfs-4: FAIL (0.004s) [198.123823] Starting dynamic subtest: numvfs-5 [198.196374] Dynamic subtest numvfs-5: FAIL (0.004s) [198.206405] Starting dynamic subtest: numvfs-6 [198.280477] Dynamic subtest numvfs-6: FAIL (0.004s) [198.291045] Starting dynamic subtest: numvfs-7 [198.354974] Dynamic subtest numvfs-7: FAIL (0.004s) [198.363284] Starting dynamic subtest: numvfs-random [198.419566] Dynamic subtest numvfs-random: FAIL (0.004s) [198.421270] Subtest enable-vfs-bind-unbind-each: FAIL (0.045s) [198.455479] [014/156] (823s left) gem_ctx_bad_destroy (invalid-ctx) [198.504321] Starting subtest: invalid-ctx [198.506504] Subtest invalid-ctx: SUCCESS (0.000s) [198.590192] [015/156] (823s left) kms_chamelium_edid (dp-edid-stress-resolution-4k) [199.745126] Subtest dp-edid-stress-resolution-4k: SKIP (0.000s) [200.063403] [016/156] (822s left) syncobj_eventfd (timeline-wait) [200.118063] Starting subtest: timeline-wait [200.127080] Subtest timeline-wait: SUCCESS (0.011s) [200.199143] [017/156] (821s left) gem_exec_params (invalid-bsd-ring) [200.254088] Starting subtest: invalid-bsd-ring [200.256264] Subtest invalid-bsd-ring: SUCCESS (0.000s) [200.356838] [018/156] (821s left) kms_big_fb (x-tiled-64bpp-rotate-90) [200.467412] Starting subtest: x-tiled-64bpp-rotate-90 [200.487431] Subtest x-tiled-64bpp-rotate-90: SKIP (0.020s) [200.601788] [019/156] (821s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-pgflip-blt) [200.717355] Starting subtest: fbcpsr-2p-primscrn-shrfb-pgflip-blt [200.721772] Subtest fbcpsr-2p-primscrn-shrfb-pgflip-blt: SKIP (0.000s) [200.808638] [020/156] (821s left) gen9_exec_parse (bb-large) [200.868364] Subtest bb-large: SKIP (0.000s) [200.950202] [021/156] (821s left) kms_rotation_crc (primary-x-tiled-reflect-x-180) [201.035197] Starting subtest: primary-x-tiled-reflect-x-180 [208.141664] Subtest primary-x-tiled-reflect-x-180: SUCCESS (7.108s) [209.338288] [022/156] (812s left) kms_prop_blob (invalid-set-prop-any) [209.400955] Starting subtest: invalid-set-prop-any [209.403038] Subtest invalid-set-prop-any: SUCCESS (0.000s) [209.505880] [023/156] (812s left) gem_exec_schedule (preempt-queue) [209.584431] Subtest preempt-queue: SKIP (0.000s) [209.873997] [024/156] (812s left) kms_ccs (crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc) [209.980482] Starting subtest: crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc [209.982727] Starting dynamic subtest: pipe-A-eDP-1 [210.041394] Dynamic subtest pipe-A-eDP-1: SKIP (0.001s) [210.051030] Starting dynamic subtest: pipe-B-eDP-1 [210.105472] Dynamic subtest pipe-B-eDP-1: SKIP (0.001s) [210.114178] Starting dynamic subtest: pipe-C-eDP-1 [210.168584] Dynamic subtest pipe-C-eDP-1: SKIP (0.001s) [210.176974] Starting dynamic subtest: pipe-D-eDP-1 [210.232777] Dynamic subtest pipe-D-eDP-1: SKIP (0.001s) [210.237126] Subtest crc-sprite-planes-basic-4-tiled-dg2-rc-ccs-cc: SKIP (0.009s) [212.290186] [025/156] (810s left) kms_atomic_transition (plane-all-modeset-transition) [212.378740] Starting subtest: plane-all-modeset-transition [212.382675] Subtest plane-all-modeset-transition: SKIP (0.000s) [212.466235] [026/156] (810s left) i915_hangman (detector) [212.531962] Starting subtest: detector [212.534884] Starting dynamic subtest: rcs0 [224.284060] Dynamic subtest rcs0: SUCCESS (11.751s) [224.296086] Starting dynamic subtest: bcs0 [236.251537] Dynamic subtest bcs0: SUCCESS (11.965s) [236.262885] Starting dynamic subtest: vcs0 [248.219077] Dynamic subtest vcs0: SUCCESS (11.965s) [248.230980] Starting dynamic subtest: vcs1 [260.187046] Dynamic subtest vcs1: SUCCESS (11.966s) [260.199052] Starting dynamic subtest: vecs0 [271.194816] Dynamic subtest vecs0: SUCCESS (11.006s) [271.206607] Starting dynamic subtest: ccs0 [282.267216] Dynamic subtest ccs0: SUCCESS (11.070s) [282.270057] Subtest detector: SUCCESS (69.734s) [282.375950] [027/156] (740s left) kms_frontbuffer_tracking (fbc-2p-indfb-fliptrack-mmap-gtt) [282.474256] Starting subtest: fbc-2p-indfb-fliptrack-mmap-gtt [282.478357] Subtest fbc-2p-indfb-fliptrack-mmap-gtt: SKIP (0.000s) [282.560975] [028/156] (740s left) syncobj_wait (multi-wait-submitted-signaled) [282.621024] Starting subtest: multi-wait-submitted-signaled [282.623253] Subtest multi-wait-submitted-signaled: SUCCESS (0.000s) [282.724836] [029/156] (739s left) kms_frontbuffer_tracking (hdr-2p-primscrn-spr-indfb-draw-blt) [282.823029] Starting subtest: hdr-2p-primscrn-spr-indfb-draw-blt [282.827196] Subtest hdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [282.919141] [030/156] (739s left) kms_plane_scaling (planes-unity-scaling-downscale-factor-0-75) [283.011981] Starting subtest: planes-unity-scaling-downscale-factor-0-75 [283.014374] Starting dynamic subtest: pipe-A [283.074672] Dynamic subtest pipe-A: SUCCESS (0.064s) [283.084118] Starting dynamic subtest: pipe-B [284.209057] Dynamic subtest pipe-B: SUCCESS (1.134s) [284.222735] Starting dynamic subtest: pipe-C [285.323372] Dynamic subtest pipe-C: SUCCESS (1.113s) [285.333536] Starting dynamic subtest: pipe-D [286.470940] Dynamic subtest pipe-D: SUCCESS (1.147s) [286.473492] Subtest planes-unity-scaling-downscale-factor-0-75: SUCCESS (3.461s) [287.618367] [031/156] (735s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt) [287.714978] Starting subtest: fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt [287.719193] Subtest fbcpsrhdr-2p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [287.808851] [032/156] (734s left) gem_exec_schedule (u-independent) [287.870457] Starting subtest: u-independent [287.872297] Starting dynamic subtest: rcs0 [289.279366] Dynamic subtest rcs0: SUCCESS (1.413s) [289.292488] Starting dynamic subtest: vcs0 [291.340364] Dynamic subtest vcs0: SUCCESS (2.060s) [291.351487] Starting dynamic subtest: vcs1 [293.319408] Dynamic subtest vcs1: SUCCESS (1.977s) [293.331822] Starting dynamic subtest: vecs0 [295.303526] Dynamic subtest vecs0: SUCCESS (1.983s) [295.315577] Starting dynamic subtest: ccs0 [297.276696] Dynamic subtest ccs0: SUCCESS (1.972s) [297.279882] Subtest u-independent: SUCCESS (9.411s) [297.577230] [033/156] (725s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt) [297.679891] Starting subtest: fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt [297.684397] Subtest fbcpsr-1p-primscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [297.774015] [034/156] (725s left) kms_plane (pixel-format-y-tiled-ccs-modifier) [297.876445] Starting subtest: pixel-format-y-tiled-ccs-modifier [297.880674] Subtest pixel-format-y-tiled-ccs-modifier: SKIP (0.000s) [297.945780] [035/156] (724s left) kms_frontbuffer_tracking (fbchdr-1p-offscreen-pri-shrfb-draw-mmap-gtt) [298.041737] Starting subtest: fbchdr-1p-offscreen-pri-shrfb-draw-mmap-gtt [298.046086] Subtest fbchdr-1p-offscreen-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [298.136981] [036/156] (724s left) sw_sync (sync_busy) [298.170300] Starting subtest: sync_busy [298.172620] Subtest sync_busy: SUCCESS (0.002s) [298.221265] [037/156] (724s left) gem_fence_thrash (bo-copy) [298.252375] Starting subtest: bo-copy [298.281254] Subtest bo-copy: SKIP (0.026s) [298.336255] [038/156] (724s left) i915_suspend (debugfs-reader) [298.404486] Starting subtest: debugfs-reader [316.892039] Subtest debugfs-reader: SUCCESS (3.226s) [316.950332] [039/156] (706s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-shrfb-msflip-blt) [317.069040] Starting subtest: fbchdr-2p-primscrn-shrfb-msflip-blt [317.073566] Subtest fbchdr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [317.157659] [040/156] (705s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu) [317.258287] Starting subtest: psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu [317.262640] Subtest psrhdr-2p-scndscrn-pri-indfb-draw-mmap-cpu: SKIP (0.000s) [317.353082] [041/156] (705s left) kms_psr (fbc-psr2-dpms) [317.441299] Starting subtest: fbc-psr2-dpms [317.443558] Starting dynamic subtest: eDP-1 [317.448084] Dynamic subtest eDP-1: SKIP (0.000s) [317.452619] Subtest fbc-psr2-dpms: SKIP (0.001s) [317.514104] [042/156] (705s left) gem_exec_reloc (basic-cpu-read-active) [317.565270] Subtest basic-cpu-read-active: SKIP (0.000s) [317.632888] [043/156] (705s left) perf (gen12-mi-rpc) [317.746796] Starting subtest: gen12-mi-rpc [317.749143] Starting dynamic subtest: rcs0 [317.766050] Dynamic subtest rcs0: SUCCESS (0.017s) [317.768055] Subtest gen12-mi-rpc: SUCCESS (0.018s) [317.813215] [044/156] (705s left) kms_flip (2x-wf_vblank-ts-check) [317.958284] Starting subtest: 2x-wf_vblank-ts-check [317.970394] Subtest 2x-wf_vblank-ts-check: SKIP (0.013s) [318.051787] [045/156] (705s left) gem_eio (execbuf) [318.278541] Starting subtest: execbuf [318.618162] Subtest execbuf: SUCCESS (0.336s) [318.786119] [046/156] (704s left) kms_big_fb (y-tiled-8bpp-rotate-270) [318.883467] Starting subtest: y-tiled-8bpp-rotate-270 [318.887126] Subtest y-tiled-8bpp-rotate-270: SKIP (0.000s) [319.016574] [047/156] (704s left) kms_frontbuffer_tracking (psrhdr-2p-primscrn-spr-indfb-draw-render) [319.119686] Starting subtest: psrhdr-2p-primscrn-spr-indfb-draw-render [319.123102] Subtest psrhdr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [319.212855] [048/156] (704s left) gem_spin_batch (legacy-resubmit-new) [319.263655] Starting subtest: legacy-resubmit-new [319.265650] Starting dynamic subtest: default [319.273313] Dynamic subtest default: SUCCESS (0.009s) [319.285695] Starting dynamic subtest: render [319.287594] Dynamic subtest render: SUCCESS (0.003s) [319.297300] Starting dynamic subtest: bsd [319.298926] Dynamic subtest bsd: SUCCESS (0.004s) [319.308527] Starting dynamic subtest: bsd1 [319.310405] Dynamic subtest bsd1: SUCCESS (0.005s) [319.320047] Starting dynamic subtest: bsd2 [319.321889] Dynamic subtest bsd2: SUCCESS (0.002s) [319.331122] Starting dynamic subtest: blt [319.332940] Dynamic subtest blt: SUCCESS (0.004s) [319.342194] Starting dynamic subtest: vebox [319.344032] Dynamic subtest vebox: SUCCESS (0.004s) [319.345850] Subtest legacy-resubmit-new: SUCCESS (0.036s) [320.573242] [049/156] (702s left) i915_query (query-topology-matches-eu-total) [320.642735] Starting subtest: query-topology-matches-eu-total [320.645239] Subtest query-topology-matches-eu-total: SUCCESS (0.000s) [320.788299] [050/156] (702s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc) [320.891667] Starting subtest: fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc [320.896138] Subtest fbcpsrhdr-2p-scndscrn-pri-indfb-draw-mmap-wc: SKIP (0.000s) [320.999619] [051/156] (702s left) syncobj_timeline (multi-wait-submitted-signaled) [321.059741] Starting subtest: multi-wait-submitted-signaled [321.062199] Subtest multi-wait-submitted-signaled: SUCCESS (0.001s) [321.175919] [052/156] (702s left) kms_properties (get_properties-sanity-non-atomic) [321.266121] Starting subtest: get_properties-sanity-non-atomic [321.268447] Subtest get_properties-sanity-non-atomic: SUCCESS (0.001s) [321.349031] [053/156] (702s left) syncobj_timeline (etime-single-wait-for-submit-submitted) [321.404721] Starting subtest: etime-single-wait-for-submit-submitted [321.513092] Subtest etime-single-wait-for-submit-submitted: SUCCESS (0.106s) [321.589845] [054/156] (702s left) gem_create (create-ext-cpu-access-big) [321.763516] Starting subtest: create-ext-cpu-access-big [321.769043] Subtest create-ext-cpu-access-big: SKIP (0.001s) [321.850098] [055/156] (701s left) gem_exec_whisper (basic-contexts-forked) [321.911126] Starting subtest: basic-contexts-forked [346.901903] Subtest basic-contexts-forked: SUCCESS (24.943s) [347.095304] [056/156] (676s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-shrfb-draw-blt) [347.207631] Starting subtest: fbc-2p-primscrn-pri-shrfb-draw-blt [347.212040] Subtest fbc-2p-primscrn-pri-shrfb-draw-blt: SKIP (0.000s) [347.294088] [057/156] (676s left) kms_ccs (crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc) [347.380158] Starting subtest: crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc [347.382458] Starting dynamic subtest: pipe-A-eDP-1 [347.405378] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [347.417694] Starting dynamic subtest: pipe-B-eDP-1 [347.439409] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [347.449655] Starting dynamic subtest: pipe-C-eDP-1 [347.472072] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [347.483915] Starting dynamic subtest: pipe-D-eDP-1 [347.504867] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [347.509179] Subtest crc-primary-rotation-180-y-tiled-gen12-rc-ccs-cc: SKIP (0.005s) [349.647314] [058/156] (674s left) kms_content_protection (dp-mst-lic-type-1) [349.804921] Starting subtest: dp-mst-lic-type-1 [349.809473] Subtest dp-mst-lic-type-1: SKIP (0.000s) [349.908313] [059/156] (674s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-spr-indfb-draw-pwrite) [350.006931] Starting subtest: fbcpsrhdr-1p-primscrn-spr-indfb-draw-pwrite [350.011563] Subtest fbcpsrhdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [350.102873] [060/156] (673s left) gem_mmap (bad-object) [350.152558] Subtest bad-object: SKIP (0.000s) [350.210689] [061/156] (673s left) gem_mmap_gtt (bad-object) [350.257724] Subtest bad-object: SKIP (0.000s) [350.319185] [062/156] (673s left) kms_cursor_crc (cursor-offscreen-128x128) [350.533801] Starting subtest: cursor-offscreen-128x128 [350.536333] Starting dynamic subtest: pipe-A-eDP-1 [352.516141] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.980s) [352.549555] Starting dynamic subtest: pipe-D-eDP-1 [355.193630] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.649s) [355.195632] Subtest cursor-offscreen-128x128: SUCCESS (4.657s) [356.041953] [063/156] (668s left) kms_ccs (bad-aux-stride-y-tiled-gen12-rc-ccs-cc) [356.137588] Starting subtest: bad-aux-stride-y-tiled-gen12-rc-ccs-cc [356.139724] Starting dynamic subtest: pipe-A-eDP-1 [356.161579] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [356.173112] Starting dynamic subtest: pipe-B-eDP-1 [356.194225] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [356.204532] Starting dynamic subtest: pipe-C-eDP-1 [356.226160] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [356.235795] Starting dynamic subtest: pipe-D-eDP-1 [356.258151] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [356.262470] Subtest bad-aux-stride-y-tiled-gen12-rc-ccs-cc: SKIP (0.003s) [358.609861] [064/156] (665s left) kms_getfb (getfb2-into-addfb2) [358.706679] Starting subtest: getfb2-into-addfb2 [358.708904] Subtest getfb2-into-addfb2: SUCCESS (0.000s) [358.822048] [065/156] (665s left) kms_pm_rpm (dpms-lpsp) [360.651362] Starting subtest: dpms-lpsp [360.989419] Subtest dpms-lpsp: SUCCESS (0.339s) [361.093466] [066/156] (663s left) kms_async_flips (alternate-sync-async-flip) [361.194797] Starting subtest: alternate-sync-async-flip [361.571036] Starting dynamic subtest: pipe-A-eDP-1 [364.340673] Dynamic subtest pipe-A-eDP-1: SUCCESS (2.769s) [364.738771] Starting dynamic subtest: pipe-B-eDP-1 [367.491543] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.766s) [367.861016] Starting dynamic subtest: pipe-C-eDP-1 [370.620724] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.768s) [370.991814] Starting dynamic subtest: pipe-D-eDP-1 [373.757467] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.777s) [373.759757] Subtest alternate-sync-async-flip: SUCCESS (12.560s) [374.930333] [067/156] (649s left) gem_eio (wait-10ms) [375.164032] Starting subtest: wait-10ms [375.526455] Subtest wait-10ms: SUCCESS (0.365s) [375.663811] [068/156] (648s left) kms_vblank (wait-idle) [375.752033] Starting subtest: wait-idle [375.754361] Starting dynamic subtest: pipe-A-eDP-1 [377.507902] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.753s) [377.522945] Starting dynamic subtest: pipe-D-eDP-1 [379.962027] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.449s) [379.964175] Subtest wait-idle: SUCCESS (4.207s) [380.779464] [069/156] (643s left) gem_exec_flush (basic-wb-ro-default) [380.837755] Starting subtest: basic-wb-ro-default [386.293664] Subtest basic-wb-ro-default: SUCCESS (5.454s) [386.495026] [070/156] (637s left) kms_frontbuffer_tracking (hdr-2p-primscrn-indfb-plflip-blt) [386.615403] Starting subtest: hdr-2p-primscrn-indfb-plflip-blt [386.619690] Subtest hdr-2p-primscrn-indfb-plflip-blt: SKIP (0.000s) [386.698524] [071/156] (637s left) gem_exec_params (sol-reset-invalid) [386.763151] Starting subtest: sol-reset-invalid [386.765383] Subtest sol-reset-invalid: SUCCESS (0.002s) [388.637265] [072/156] (635s left) kms_frontbuffer_tracking (psr-indfb-scaledprimary) [388.741574] Starting subtest: psr-indfb-scaledprimary [394.534773] Subtest psr-indfb-scaledprimary: SUCCESS (5.795s) [394.660454] [073/156] (629s left) gem_ctx_persistence (engines-mixed-process) [394.722323] Starting subtest: engines-mixed-process [394.724635] Starting dynamic subtest: rcs0 [396.574714] Dynamic subtest rcs0: SUCCESS (1.846s) [396.588694] Starting dynamic subtest: bcs0 [398.559964] Dynamic subtest bcs0: SUCCESS (1.987s) [398.573046] Starting dynamic subtest: vcs0 [400.534996] Dynamic subtest vcs0: SUCCESS (1.973s) [400.546365] Starting dynamic subtest: vcs1 [402.585010] Dynamic subtest vcs1: SUCCESS (2.048s) [402.597487] Starting dynamic subtest: vecs0 [404.581038] Dynamic subtest vecs0: SUCCESS (1.994s) [404.593152] Starting dynamic subtest: ccs0 [406.549835] Dynamic subtest ccs0: SUCCESS (1.966s) [406.552331] Subtest engines-mixed-process: SUCCESS (11.822s) [406.629802] [074/156] (617s left) gem_render_copy (yf-tiled-ccs-to-y-tiled) [406.692761] Starting subtest: yf-tiled-ccs-to-y-tiled [406.696698] Subtest yf-tiled-ccs-to-y-tiled: SKIP (0.000s) [406.894026] [075/156] (617s left) kms_cursor_crc (cursor-tearing-position-change) [407.012135] Starting subtest: cursor-tearing-position-change [407.014438] Starting dynamic subtest: pipe-A-eDP-1 [407.595035] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.578s) [407.607591] Starting dynamic subtest: pipe-D-eDP-1 [408.849514] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.255s) [408.851924] Subtest cursor-tearing-position-change: SUCCESS (1.835s) [409.691935] [076/156] (614s left) kms_frontbuffer_tracking (psrhdr-1p-primscrn-spr-indfb-draw-pwrite) [409.794264] Starting subtest: psrhdr-1p-primscrn-spr-indfb-draw-pwrite [409.798121] Subtest psrhdr-1p-primscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [409.900624] [077/156] (614s left) kms_flip (dpms-vs-vblank-race-interruptible) [409.996587] Starting subtest: dpms-vs-vblank-race-interruptible [409.998810] Starting dynamic subtest: A-eDP1 [414.711577] Dynamic subtest A-eDP1: SUCCESS (4.712s) [414.723874] Starting dynamic subtest: B-eDP1 [417.848027] Dynamic subtest B-eDP1: SUCCESS (3.136s) [417.860333] Starting dynamic subtest: C-eDP1 [421.357228] Dynamic subtest C-eDP1: SUCCESS (3.511s) [421.370569] Starting dynamic subtest: D-eDP1 [425.996475] Dynamic subtest D-eDP1: SUCCESS (4.640s) [426.001419] Subtest dpms-vs-vblank-race-interruptible: SUCCESS (16.015s) [426.874298] [078/156] (597s left) kms_frontbuffer_tracking (fbcpsrhdr-tiling-y) [426.981794] Starting subtest: fbcpsrhdr-tiling-y [426.985851] Subtest fbcpsrhdr-tiling-y: SKIP (0.000s) [427.080720] [079/156] (597s left) gem_exec_fence (basic-busy) [427.250055] Starting subtest: basic-busy [427.252562] Starting dynamic subtest: rcs0 [427.338368] Dynamic subtest rcs0: SUCCESS (0.089s) [427.351142] Starting dynamic subtest: bcs0 [427.403538] Dynamic subtest bcs0: SUCCESS (0.063s) [427.416228] Starting dynamic subtest: vcs0 [427.467349] Dynamic subtest vcs0: SUCCESS (0.062s) [427.479047] Starting dynamic subtest: vcs1 [427.529159] Dynamic subtest vcs1: SUCCESS (0.060s) [427.541633] Starting dynamic subtest: vecs0 [427.593302] Dynamic subtest vecs0: SUCCESS (0.062s) [427.605544] Starting dynamic subtest: ccs0 [427.655394] Dynamic subtest ccs0: SUCCESS (0.060s) [427.657900] Subtest basic-busy: SUCCESS (0.408s) [429.625458] [FACT gem_exec_fence (basic-busy)] new: kernel.kmod_is_loaded.vgem: true [429.629197] [080/156] (595s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-0-hflip) [429.736937] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-0-hflip [430.806417] Subtest 4-tiled-max-hw-stride-32bpp-rotate-0-hflip: SUCCESS (1.070s) [430.931896] [081/156] (593s left) kms_big_fb (linear-64bpp-rotate-180) [431.038215] Starting subtest: linear-64bpp-rotate-180 [432.783844] Subtest linear-64bpp-rotate-180: SUCCESS (1.746s) [432.967440] [082/156] (591s left) kms_addfb_basic (tile-pitch-mismatch) [433.027637] Starting subtest: tile-pitch-mismatch [433.031526] Subtest tile-pitch-mismatch: SKIP (0.000s) [433.182545] [083/156] (591s left) kms_cursor_legacy (forked-move) [433.296343] Starting subtest: forked-move [433.298296] Starting dynamic subtest: pipe-A [438.772389] Dynamic subtest pipe-A: SUCCESS (5.474s) [438.790952] Starting dynamic subtest: pipe-B [444.241086] Dynamic subtest pipe-B: SUCCESS (5.467s) [444.263047] Starting dynamic subtest: pipe-C [449.708307] Dynamic subtest pipe-C: SUCCESS (5.465s) [449.722499] Starting dynamic subtest: pipe-D [455.199651] Dynamic subtest pipe-D: SUCCESS (5.490s) [455.213386] Starting dynamic subtest: all-pipes [460.705590] Dynamic subtest all-pipes: SUCCESS (5.504s) [460.708145] Subtest forked-move: SUCCESS (27.412s) [460.858106] [084/156] (564s left) kms_lease (empty-lease) [460.947429] Starting subtest: empty-lease [460.949572] Starting dynamic subtest: pipe-A-eDP-1 [460.951811] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s) [460.963178] Starting dynamic subtest: pipe-B-eDP-1 [460.965035] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [460.975670] Starting dynamic subtest: pipe-C-eDP-1 [460.977561] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [460.987696] Starting dynamic subtest: pipe-D-eDP-1 [460.989546] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [460.991437] Subtest empty-lease: SUCCESS (0.010s) [461.088667] [085/156] (563s left) kms_plane_multiple (tiling-4) [461.185643] Starting subtest: tiling-4 [461.187853] Starting dynamic subtest: pipe-A-eDP-1 [465.102538] Dynamic subtest pipe-A-eDP-1: SUCCESS (3.916s) [465.114431] Starting dynamic subtest: pipe-B-eDP-1 [469.667459] Dynamic subtest pipe-B-eDP-1: SUCCESS (4.562s) [469.681145] Starting dynamic subtest: pipe-C-eDP-1 [474.114412] Dynamic subtest pipe-C-eDP-1: SUCCESS (4.446s) [474.126570] Starting dynamic subtest: pipe-D-eDP-1 [478.661598] Dynamic subtest pipe-D-eDP-1: SUCCESS (4.546s) [478.663987] Subtest tiling-4: SUCCESS (17.475s) [479.467063] [086/156] (545s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-pwrite) [479.579581] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-pwrite [479.584301] Subtest fbc-2p-scndscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [479.678621] [087/156] (545s left) sw_sync (sync_merge_invalid) [479.713416] Starting subtest: sync_merge_invalid [479.739393] Subtest sync_merge_invalid: SUCCESS (0.027s) [479.820549] [088/156] (545s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-atomic) [479.950359] Starting subtest: flip-vs-cursor-busy-crc-atomic [480.677762] Subtest flip-vs-cursor-busy-crc-atomic: SUCCESS (0.728s) [481.498141] [089/156] (543s left) kms_cursor_legacy (long-nonblocking-modeset-vs-cursor-atomic) [481.623133] Starting subtest: long-nonblocking-modeset-vs-cursor-atomic [499.983565] Subtest long-nonblocking-modeset-vs-cursor-atomic: SUCCESS (18.354s) [500.875713] [090/156] (524s left) kms_async_flips (test-time-stamp) [500.982514] Starting subtest: test-time-stamp [501.354862] Starting dynamic subtest: pipe-A-eDP-1 [502.167410] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.811s) [502.545660] Starting dynamic subtest: pipe-B-eDP-1 [503.397058] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.857s) [503.786713] Starting dynamic subtest: pipe-C-eDP-1 [504.636871] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.858s) [505.002024] Starting dynamic subtest: pipe-D-eDP-1 [505.768918] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.775s) [505.771379] Subtest test-time-stamp: SUCCESS (4.785s) [506.932947] [091/156] (518s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-move) [507.029777] Starting subtest: psr-2p-scndscrn-cur-indfb-move [507.034011] Subtest psr-2p-scndscrn-cur-indfb-move: SKIP (0.000s) [507.122079] [092/156] (518s left) gem_eio (in-flight-contexts-10ms) [507.356965] Starting subtest: in-flight-contexts-10ms [518.570742] Subtest in-flight-contexts-10ms: SUCCESS (11.210s) [518.764467] [093/156] (506s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-render) [518.881772] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-render [523.435382] Subtest fbc-1p-primscrn-pri-indfb-draw-render: SUCCESS (4.558s) [524.587201] [094/156] (500s left) syncobj_timeline (single-wait-for-submit-available-signaled) [524.646622] Starting subtest: single-wait-for-submit-available-signaled [524.648601] Subtest single-wait-for-submit-available-signaled: SUCCESS (0.000s) [524.758601] [095/156] (500s left) kms_addfb_basic (bad-pitch-999) [524.812869] Starting subtest: bad-pitch-999 [524.814908] Subtest bad-pitch-999: SUCCESS (0.000s) [524.957594] [096/156] (500s left) gem_partial_pwrite_pread (write-uncached) [525.019774] Subtest write-uncached: SKIP (0.000s) [525.093700] [097/156] (500s left) kms_frontbuffer_tracking (psr-2p-primscrn-pri-shrfb-draw-pwrite) [525.192160] Starting subtest: psr-2p-primscrn-pri-shrfb-draw-pwrite [525.196428] Subtest psr-2p-primscrn-pri-shrfb-draw-pwrite: SKIP (0.000s) [525.288797] [098/156] (500s left) kms_frontbuffer_tracking (fbc-1p-primscrn-pri-indfb-draw-blt) [525.397770] Starting subtest: fbc-1p-primscrn-pri-indfb-draw-blt [529.837768] Subtest fbc-1p-primscrn-pri-indfb-draw-blt: SUCCESS (4.446s) [531.075232] [099/156] (494s left) kms_psr (fbc-psr-cursor-render) [531.168745] Starting subtest: fbc-psr-cursor-render [531.171033] Starting dynamic subtest: eDP-1 [531.175149] Dynamic subtest eDP-1: SKIP (0.000s) [531.179165] Subtest fbc-psr-cursor-render: SKIP (0.001s) [531.246404] [100/156] (494s left) kms_frontbuffer_tracking (fbchdr-2p-scndscrn-shrfb-pgflip-blt) [531.343058] Starting subtest: fbchdr-2p-scndscrn-shrfb-pgflip-blt [531.347169] Subtest fbchdr-2p-scndscrn-shrfb-pgflip-blt: SKIP (0.000s) [531.449863] [101/156] (494s left) kms_cursor_legacy (cursora-vs-flipb-legacy) [531.572422] Starting subtest: cursorA-vs-flipB-legacy [531.577052] Subtest cursorA-vs-flipB-legacy: SKIP (0.001s) [531.733436] [102/156] (494s left) kms_big_fb (yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [531.833625] Starting subtest: yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip [531.838088] Subtest yf-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s) [531.943814] [103/156] (493s left) kms_frontbuffer_tracking (fbcpsrhdr-2p-scndscrn-spr-indfb-draw-render) [532.048391] Starting subtest: fbcpsrhdr-2p-scndscrn-spr-indfb-draw-render [532.052080] Subtest fbcpsrhdr-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s) [532.156129] [104/156] (493s left) kms_frontbuffer_tracking (psrhdr-2p-scndscrn-spr-indfb-draw-pwrite) [532.258451] Starting subtest: psrhdr-2p-scndscrn-spr-indfb-draw-pwrite [532.261798] Subtest psrhdr-2p-scndscrn-spr-indfb-draw-pwrite: SKIP (0.000s) [532.353056] [105/156] (493s left) kms_big_fb (y-tiled-max-hw-stride-64bpp-rotate-0-async-flip) [532.451185] Starting subtest: y-tiled-max-hw-stride-64bpp-rotate-0-async-flip [532.454882] Subtest y-tiled-max-hw-stride-64bpp-rotate-0-async-flip: SKIP (0.000s) [532.554952] [106/156] (493s left) kms_atomic_interruptible (legacy-setmode) [532.641320] Starting subtest: legacy-setmode [532.643260] Starting dynamic subtest: pipe-A-eDP-1 [539.573124] Dynamic subtest pipe-A-eDP-1: SUCCESS (6.928s) [539.575543] Subtest legacy-setmode: SUCCESS (6.929s) [540.442133] [107/156] (485s left) i915_drm_fdinfo (idle) [540.477235] Starting subtest: idle [540.479229] Starting dynamic subtest: rcs0 [541.046746] Dynamic subtest rcs0: SUCCESS (0.568s) [541.060568] Starting dynamic subtest: bcs0 [541.551369] Dynamic subtest bcs0: SUCCESS (0.503s) [541.567038] Starting dynamic subtest: vcs0 [542.058475] Dynamic subtest vcs0: SUCCESS (0.503s) [542.071289] Starting dynamic subtest: vcs1 [542.564565] Dynamic subtest vcs1: SUCCESS (0.503s) [542.576870] Starting dynamic subtest: vecs0 [543.070571] Dynamic subtest vecs0: SUCCESS (0.503s) [543.084735] Starting dynamic subtest: ccs0 [543.576043] Dynamic subtest ccs0: SUCCESS (0.503s) [543.578926] Subtest idle: SUCCESS (3.101s) [543.654955] [108/156] (482s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-render) [543.754565] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-render [543.758734] Subtest fbc-2p-scndscrn-spr-indfb-draw-render: SKIP (0.000s) [543.862076] [109/156] (482s left) kms_lease (lease-get) [543.947731] Starting subtest: lease-get [543.949935] Starting dynamic subtest: pipe-A-eDP-1 [543.952206] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.001s) [543.965687] Starting dynamic subtest: pipe-B-eDP-1 [543.967841] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.001s) [543.978724] Starting dynamic subtest: pipe-C-eDP-1 [543.980602] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.001s) [543.991352] Starting dynamic subtest: pipe-D-eDP-1 [543.993587] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.001s) [543.995821] Subtest lease-get: SUCCESS (0.009s) [544.053275] [110/156] (482s left) kms_chamelium_audio (dp-audio) [544.429100] Subtest dp-audio: SKIP (0.000s) [545.248171] [111/156] (480s left) kms_frontbuffer_tracking (fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc) [545.355555] Starting subtest: fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc [545.359776] Subtest fbchdr-1p-primscrn-spr-indfb-draw-mmap-wc: SKIP (0.000s) [545.453918] [112/156] (480s left) gem_render_copy (y-tiled-to-vebox-yf-tiled) [545.507584] Starting subtest: y-tiled-to-vebox-yf-tiled [545.512012] Subtest y-tiled-to-vebox-yf-tiled: SKIP (0.000s) [545.716417] [113/156] (480s left) kms_pipe_crc_basic (nonblocking-crc) [545.811148] Starting subtest: nonblocking-crc [545.813485] Starting dynamic subtest: pipe-A-eDP-1 [546.576509] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.764s) [546.588220] Starting dynamic subtest: pipe-B-eDP-1 [548.005038] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.430s) [548.016810] Starting dynamic subtest: pipe-C-eDP-1 [549.389254] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.383s) [549.401021] Starting dynamic subtest: pipe-D-eDP-1 [550.812628] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.422s) [550.815002] Subtest nonblocking-crc: SUCCESS (5.003s) [551.697998] [114/156] (474s left) gem_exec_fence (syncobj-timeline-invalid-wait) [552.275720] Starting subtest: syncobj-timeline-invalid-wait [552.278724] Subtest syncobj-timeline-invalid-wait: SUCCESS (0.004s) [553.643180] [115/156] (472s left) kms_plane_scaling (plane-scaler-with-clipping-clamping-rotation) [553.738439] Starting subtest: plane-scaler-with-clipping-clamping-rotation [553.740827] Starting dynamic subtest: pipe-A [553.975543] Dynamic subtest pipe-A: SUCCESS (0.239s) [553.987188] Starting dynamic subtest: pipe-B [555.312363] Dynamic subtest pipe-B: SUCCESS (1.336s) [555.323500] Starting dynamic subtest: pipe-C [556.632496] Dynamic subtest pipe-C: SUCCESS (1.319s) [556.644289] Starting dynamic subtest: pipe-D [557.957572] Dynamic subtest pipe-D: SUCCESS (1.324s) [557.960036] Subtest plane-scaler-with-clipping-clamping-rotation: SUCCESS (4.223s) [559.151576] [116/156] (467s left) perf_pmu (render-node-busy-idle) [559.242754] Starting subtest: render-node-busy-idle [559.245567] Starting dynamic subtest: rcs0 [559.907300] Dynamic subtest rcs0: SUCCESS (0.667s) [559.921305] Starting dynamic subtest: bcs0 [560.568860] Dynamic subtest bcs0: SUCCESS (0.659s) [560.582716] Starting dynamic subtest: vcs0 [562.516613] Dynamic subtest vcs0: SUCCESS (1.944s) [562.530668] Starting dynamic subtest: vcs1 [564.564603] Dynamic subtest vcs1: SUCCESS (2.046s) [564.576899] Starting dynamic subtest: vecs0 [566.548354] Dynamic subtest vecs0: SUCCESS (1.981s) [566.567564] Starting dynamic subtest: ccs0 [567.223016] Dynamic subtest ccs0: SUCCESS (0.673s) [567.225565] Subtest render-node-busy-idle: SUCCESS (7.987s) [567.318603] [117/156] (459s left) kms_cursor_crc (cursor-rapid-movement-512x512) [567.614867] Starting subtest: cursor-rapid-movement-512x512 [567.618891] Subtest cursor-rapid-movement-512x512: SKIP (0.001s) [567.776047] [118/156] (458s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt) [567.885063] Starting subtest: fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt [567.889209] Subtest fbc-2p-scndscrn-spr-indfb-draw-mmap-gtt: SKIP (0.000s) [567.985844] [119/156] (458s left) gem_ctx_persistence (hang) [568.050486] Starting subtest: hang [568.055066] Subtest hang: SKIP (0.000s) [568.150748] [120/156] (458s left) kms_psr (psr2-cursor-mmap-cpu) [568.243425] Starting subtest: psr2-cursor-mmap-cpu [568.245259] Starting dynamic subtest: eDP-1 [569.932343] Dynamic subtest eDP-1: SUCCESS (1.691s) [569.934624] Subtest psr2-cursor-mmap-cpu: SUCCESS (1.692s) [570.817878] [121/156] (455s left) gem_userptr_blits (sync-overlap) [570.892056] Starting subtest: sync-overlap [570.894297] Subtest sync-overlap: SUCCESS (0.000s) [571.022798] [122/156] (455s left) gem_ccs (block-multicopy-compressed) [571.085397] Starting subtest: block-multicopy-compressed [571.090116] Subtest block-multicopy-compressed: SKIP (0.000s) [571.190495] [123/156] (455s left) kms_psr2_sf (psr2-overlay-plane-move-continuous-sf) [571.286204] Starting subtest: psr2-overlay-plane-move-continuous-sf [571.288303] Starting dynamic subtest: pipe-A-eDP-1 [577.262960] Dynamic subtest pipe-A-eDP-1: SUCCESS (5.978s) [577.276110] Starting dynamic subtest: pipe-B-eDP-1 [584.011465] Dynamic subtest pipe-B-eDP-1: SUCCESS (6.748s) [584.013807] Subtest psr2-overlay-plane-move-continuous-sf: SUCCESS (12.729s) [584.892206] [124/156] (441s left) kms_properties (plane-properties-atomic) [584.997235] Starting subtest: plane-properties-atomic [584.999524] Starting dynamic subtest: pipe-A-eDP-1 [585.540641] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.545s) [585.553188] Starting dynamic subtest: pipe-B-eDP-1 [586.845744] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.304s) [586.858192] Starting dynamic subtest: pipe-C-eDP-1 [588.078252] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.231s) [588.090533] Starting dynamic subtest: pipe-D-eDP-1 [589.316159] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.236s) [589.318493] Subtest plane-properties-atomic: SUCCESS (4.321s) [590.128661] [125/156] (436s left) kms_frontbuffer_tracking (fbchdr-2p-primscrn-spr-indfb-draw-blt) [590.227178] Starting subtest: fbchdr-2p-primscrn-spr-indfb-draw-blt [590.231152] Subtest fbchdr-2p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [590.327069] [126/156] (436s left) kms_frontbuffer_tracking (fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc) [590.422031] Starting subtest: fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc [590.426361] Subtest fbcpsrhdr-1p-primscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [590.516638] [127/156] (436s left) gem_exec_async (forked-writes) [590.572832] Starting subtest: forked-writes [590.575371] Starting dynamic subtest: rcs0 [590.602568] Dynamic subtest rcs0: SUCCESS (0.029s) [590.616376] Starting dynamic subtest: bcs0 [590.625763] Dynamic subtest bcs0: SUCCESS (0.023s) [590.638153] Starting dynamic subtest: vcs0 [590.647929] Dynamic subtest vcs0: SUCCESS (0.021s) [590.659525] Starting dynamic subtest: vcs1 [590.673387] Dynamic subtest vcs1: SUCCESS (0.024s) [590.685086] Starting dynamic subtest: vecs0 [590.696762] Dynamic subtest vecs0: SUCCESS (0.022s) [590.710521] Starting dynamic subtest: ccs0 [590.719128] Dynamic subtest ccs0: SUCCESS (0.022s) [590.721383] Subtest forked-writes: SUCCESS (0.147s) [590.950653] [128/156] (435s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-downscaling) [591.034692] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-downscaling [591.036873] Starting dynamic subtest: pipe-A-default-mode [592.190392] Dynamic subtest pipe-A-default-mode: SKIP (1.154s) [592.194828] Subtest flip-64bpp-4tile-to-32bpp-4tile-downscaling: SKIP (1.155s) [592.334751] [129/156] (434s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move) [592.446082] Starting subtest: fbc-2p-primscrn-spr-indfb-move [592.450212] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s) [592.531332] [130/156] (434s left) kms_vblank (accuracy-idle) [592.617756] Starting subtest: accuracy-idle [592.619930] Starting dynamic subtest: pipe-A-eDP-1 [594.375722] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.756s) [594.392034] Starting dynamic subtest: pipe-D-eDP-1 [596.809175] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.432s) [596.811046] Subtest accuracy-idle: SUCCESS (4.192s) [597.617790] [131/156] (429s left) gem_exec_reloc (basic-gtt) [597.668891] Subtest basic-gtt: SKIP (0.000s) [597.744298] [132/156] (429s left) api_intel_allocator (simple-allocator) [597.800048] Starting subtest: simple-allocator [597.802420] Starting dynamic subtest: basic [597.804705] Dynamic subtest basic: SUCCESS (0.003s) [597.815684] Starting dynamic subtest: parallel-one [597.986075] Dynamic subtest parallel-one: SUCCESS (0.182s) [597.999540] Starting dynamic subtest: print [598.001732] Dynamic subtest print: SUCCESS (0.000s) [598.012174] Starting dynamic subtest: reuse [598.013995] Dynamic subtest reuse: SUCCESS (0.000s) [598.024470] Starting dynamic subtest: reserve [598.026377] Dynamic subtest reserve: SUCCESS (0.000s) [598.036912] Starting dynamic subtest: fork-reopen-allocator [598.038885] Dynamic subtest fork-reopen-allocator: SUCCESS (0.013s) [598.040839] Subtest simple-allocator: SUCCESS (0.206s) [598.128153] [133/156] (428s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-cpu) [598.222725] Starting subtest: fbcpsr-rgb565-draw-mmap-cpu [605.119244] Subtest fbcpsr-rgb565-draw-mmap-cpu: SUCCESS (6.902s) [605.231888] [134/156] (421s left) kms_ccs (crc-primary-basic-4-tiled-mtl-rc-ccs) [605.326380] Starting subtest: crc-primary-basic-4-tiled-mtl-rc-ccs [605.328766] Starting dynamic subtest: pipe-A-eDP-1 [606.331824] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.998s) [606.343383] Starting dynamic subtest: pipe-B-eDP-1 [608.040794] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.706s) [608.053034] Starting dynamic subtest: pipe-C-eDP-1 [609.678614] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.637s) [609.691561] Starting dynamic subtest: pipe-D-eDP-1 [611.298639] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.618s) [611.300714] Subtest crc-primary-basic-4-tiled-mtl-rc-ccs: SUCCESS (5.964s) [612.473150] [135/156] (414s left) drm_read (short-buffer-wakeup) [612.608916] Starting subtest: short-buffer-wakeup [644.829754] Subtest short-buffer-wakeup: SUCCESS (32.216s) [644.964663] [136/156] (382s left) kms_frontbuffer_tracking (fbchdr-rgb565-draw-pwrite) [645.063458] Starting subtest: fbchdr-rgb565-draw-pwrite [645.067947] Subtest fbchdr-rgb565-draw-pwrite: SKIP (0.000s) [645.171688] [137/156] (382s left) kms_color (deep-color) [645.269315] Starting subtest: deep-color [646.350991] Starting dynamic subtest: pipe-A-eDP-1-gamma [647.095096] Dynamic subtest pipe-A-eDP-1-gamma: SUCCESS (0.747s) [647.107222] Starting dynamic subtest: pipe-A-eDP-1-degamma [648.546476] Dynamic subtest pipe-A-eDP-1-degamma: SUCCESS (1.449s) [648.558397] Starting dynamic subtest: pipe-A-eDP-1-ctm [649.995717] Dynamic subtest pipe-A-eDP-1-ctm: SUCCESS (1.446s) [650.840767] Starting dynamic subtest: pipe-B-eDP-1-gamma [651.556426] Dynamic subtest pipe-B-eDP-1-gamma: SUCCESS (0.726s) [651.570130] Starting dynamic subtest: pipe-B-eDP-1-degamma [653.038522] Dynamic subtest pipe-B-eDP-1-degamma: SUCCESS (1.480s) [653.050680] Starting dynamic subtest: pipe-B-eDP-1-ctm [654.443258] Dynamic subtest pipe-B-eDP-1-ctm: SUCCESS (1.405s) [655.213818] Starting dynamic subtest: pipe-C-eDP-1-gamma [655.904991] Dynamic subtest pipe-C-eDP-1-gamma: SUCCESS (0.699s) [655.915418] Starting dynamic subtest: pipe-C-eDP-1-degamma [657.297580] Dynamic subtest pipe-C-eDP-1-degamma: SUCCESS (1.390s) [657.310760] Starting dynamic subtest: pipe-C-eDP-1-ctm [658.713241] Dynamic subtest pipe-C-eDP-1-ctm: SUCCESS (1.417s) [659.513103] Starting dynamic subtest: pipe-D-eDP-1-gamma [660.203828] Dynamic subtest pipe-D-eDP-1-gamma: SUCCESS (0.703s) [660.216507] Starting dynamic subtest: pipe-D-eDP-1-degamma [661.668061] Dynamic subtest pipe-D-eDP-1-degamma: SUCCESS (1.460s) [661.679472] Starting dynamic subtest: pipe-D-eDP-1-ctm [663.092534] Dynamic subtest pipe-D-eDP-1-ctm: SUCCESS (1.426s) [663.094846] Subtest deep-color: SUCCESS (17.823s) [663.947627] [138/156] (363s left) kms_psr_stress_test (flip-primary-invalidate-overlay) [664.040764] Starting subtest: flip-primary-invalidate-overlay [675.676439] Subtest flip-primary-invalidate-overlay: SUCCESS (11.635s) [675.801745] [139/156] (351s left) kms_frontbuffer_tracking (hdr-rgb565-draw-mmap-cpu) [675.900116] Starting subtest: hdr-rgb565-draw-mmap-cpu [675.903454] Subtest hdr-rgb565-draw-mmap-cpu: SKIP (0.000s) [675.994409] [140/156] (351s left) kms_flip (2x-busy-flip) [676.081501] Starting subtest: 2x-busy-flip [676.091240] Subtest 2x-busy-flip: SKIP (0.011s) [676.178003] [141/156] (351s left) kms_frontbuffer_tracking (fbcpsr-1p-offscreen-pri-shrfb-draw-blt) [676.270008] Starting subtest: fbcpsr-1p-offscreen-pri-shrfb-draw-blt [683.169587] Subtest fbcpsr-1p-offscreen-pri-shrfb-draw-blt: SUCCESS (6.899s) [683.309340] [142/156] (344s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-render) [683.407730] Starting subtest: psr-2p-primscrn-cur-indfb-draw-render [683.412209] Subtest psr-2p-primscrn-cur-indfb-draw-render: SKIP (0.000s) [683.502756] [143/156] (344s left) kms_flip_scaled_crc (flip-32bpp-linear-to-64bpp-linear-downscaling) [683.595788] Starting subtest: flip-32bpp-linear-to-64bpp-linear-downscaling [683.598100] Starting dynamic subtest: pipe-A-default-mode [684.751679] Dynamic subtest pipe-A-default-mode: SKIP (1.152s) [684.756489] Subtest flip-32bpp-linear-to-64bpp-linear-downscaling: SKIP (1.154s) [684.898755] [144/156] (342s left) gem_exec_fence (syncobj-timeline-wait) [685.455039] Starting subtest: syncobj-timeline-wait [686.572267] Subtest syncobj-timeline-wait: SUCCESS (1.117s) [688.630718] [145/156] (339s left) kms_frontbuffer_tracking (hdr-2p-scndscrn-pri-indfb-draw-blt) [688.729672] Starting subtest: hdr-2p-scndscrn-pri-indfb-draw-blt [688.733796] Subtest hdr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s) [688.826525] [146/156] (338s left) gem_pxp (dmabuf-shared-protected-dst-is-context-refcounted) [688.945791] Starting subtest: dmabuf-shared-protected-dst-is-context-refcounted [689.078777] Subtest dmabuf-shared-protected-dst-is-context-refcounted: SUCCESS (0.136s) [689.218296] [147/156] (338s left) gem_partial_pwrite_pread (reads-snoop) [689.272157] Subtest reads-snoop: SKIP (0.000s) [689.354194] [148/156] (338s left) kms_chamelium_hpd (dp-hpd-with-enabled-mode) [689.735191] Subtest dp-hpd-with-enabled-mode: SKIP (0.000s) [690.541272] [149/156] (337s left) gem_huc_copy (huc-copy) [690.604523] Starting subtest: huc-copy [690.609054] Subtest huc-copy: SUCCESS (0.003s) [690.738795] [150/156] (337s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-move) [690.842025] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-move [698.313937] Subtest fbcpsr-1p-primscrn-spr-indfb-move: SUCCESS (7.476s) [698.475835] [151/156] (329s left) kms_prop_blob (invalid-get-prop-any) [698.538261] Starting subtest: invalid-get-prop-any [698.540125] Subtest invalid-get-prop-any: SUCCESS (0.000s) [698.627023] [152/156] (329s left) core_auth (getclient-master-drop) [698.662567] Starting subtest: getclient-master-drop [698.672744] Subtest getclient-master-drop: SUCCESS (0.016s) [698.784580] [153/156] (329s left) kms_frontbuffer_tracking (psr-1p-primscrn-pri-indfb-draw-blt) [698.887032] Starting subtest: psr-1p-primscrn-pri-indfb-draw-blt [706.243142] Subtest psr-1p-primscrn-pri-indfb-draw-blt: SUCCESS (7.360s) [706.362546] [154/156] (321s left) gem_userptr_blits (probe) [706.432237] Starting subtest: probe [706.511421] Subtest probe: SUCCESS (0.081s) [706.648292] [155/156] (321s left) kms_psr (psr2-primary-mmap-gtt) [706.737677] Starting subtest: psr2-primary-mmap-gtt [706.739810] Starting dynamic subtest: eDP-1 [708.204368] Dynamic subtest eDP-1: SKIP (1.463s) [708.209077] Subtest psr2-primary-mmap-gtt: SKIP (1.465s) [708.357317] [156/156] (319s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-render) [708.452745] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-render [708.457243] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-render: SKIP (0.000s) [708.555445] Closing watchdogs results: parsing output: 0/ for test: gem_exec_balancer results: parsing output: 1/ for test: kms_psr results: parsing output: 2/ for test: gem_userptr_blits results: parsing output: 3/ for test: kms_big_fb results: parsing output: 4/ for test: i915_pm_rc6_residency results: parsing output: 5/ for test: kms_draw_crc results: parsing output: 6/ for test: kms_frontbuffer_tracking results: parsing output: 7/ for test: gem_mmap_gtt results: parsing output: 8/ for test: kms_frontbuffer_tracking results: parsing output: 9/ for test: sysfs_heartbeat_interval results: parsing output: 10/ for test: syncobj_timeline results: parsing output: 11/ for test: kms_frontbuffer_tracking results: parsing output: 12/ for test: sriov_basic results: parsing output: 13/ for test: gem_ctx_bad_destroy results: parsing output: 14/ for test: kms_chamelium_edid results: parsing output: 15/ for test: syncobj_eventfd results: parsing output: 16/ for test: gem_exec_params results: parsing output: 17/ for test: kms_big_fb results: parsing output: 18/ for test: kms_frontbuffer_tracking results: parsing output: 19/ for test: gen9_exec_parse results: parsing output: 20/ for test: kms_rotation_crc results: parsing output: 21/ for test: kms_prop_blob results: parsing output: 22/ for test: gem_exec_schedule results: parsing output: 23/ for test: kms_ccs results: parsing output: 24/ for test: kms_atomic_transition results: parsing output: 25/ for test: i915_hangman results: parsing output: 26/ for test: kms_frontbuffer_tracking results: parsing output: 27/ for test: syncobj_wait results: parsing output: 28/ for test: kms_frontbuffer_tracking results: parsing output: 29/ for test: kms_plane_scaling results: parsing output: 30/ for test: kms_frontbuffer_tracking results: parsing output: 31/ for test: gem_exec_schedule results: parsing output: 32/ for test: kms_frontbuffer_tracking results: parsing output: 33/ for test: kms_plane results: parsing output: 34/ for test: kms_frontbuffer_tracking results: parsing output: 35/ for test: sw_sync results: parsing output: 36/ for test: gem_fence_thrash results: parsing output: 37/ for test: i915_suspend results: parsing output: 38/ for test: kms_frontbuffer_tracking results: parsing output: 39/ for test: kms_frontbuffer_tracking results: parsing output: 40/ for test: kms_psr results: parsing output: 41/ for test: gem_exec_reloc results: parsing output: 42/ for test: perf results: parsing output: 43/ for test: kms_flip results: parsing output: 44/ for test: gem_eio results: parsing output: 45/ for test: kms_big_fb results: parsing output: 46/ for test: kms_frontbuffer_tracking results: parsing output: 47/ for test: gem_spin_batch results: parsing output: 48/ for test: i915_query results: parsing output: 49/ for test: kms_frontbuffer_tracking results: parsing output: 50/ for test: syncobj_timeline results: parsing output: 51/ for test: kms_properties results: parsing output: 52/ for test: syncobj_timeline results: parsing output: 53/ for test: gem_create results: parsing output: 54/ for test: gem_exec_whisper results: parsing output: 55/ for test: kms_frontbuffer_tracking results: parsing output: 56/ for test: kms_ccs results: parsing output: 57/ for test: kms_content_protection results: parsing output: 58/ for test: kms_frontbuffer_tracking results: parsing output: 59/ for test: gem_mmap results: parsing output: 60/ for test: gem_mmap_gtt results: parsing output: 61/ for test: kms_cursor_crc results: parsing output: 62/ for test: kms_ccs results: parsing output: 63/ for test: kms_getfb results: parsing output: 64/ for test: kms_pm_rpm results: parsing output: 65/ for test: kms_async_flips results: parsing output: 66/ for test: gem_eio results: parsing output: 67/ for test: kms_vblank results: parsing output: 68/ for test: gem_exec_flush results: parsing output: 69/ for test: kms_frontbuffer_tracking results: parsing output: 70/ for test: gem_exec_params results: parsing output: 71/ for test: kms_frontbuffer_tracking results: parsing output: 72/ for test: gem_ctx_persistence results: parsing output: 73/ for test: gem_render_copy results: parsing output: 74/ for test: kms_cursor_crc results: parsing output: 75/ for test: kms_frontbuffer_tracking results: parsing output: 76/ for test: kms_flip results: parsing output: 77/ for test: kms_frontbuffer_tracking results: parsing output: 78/ for test: gem_exec_fence results: parsing output: 79/ for test: kms_big_fb results: parsing output: 80/ for test: kms_big_fb results: parsing output: 81/ for test: kms_addfb_basic results: parsing output: 82/ for test: kms_cursor_legacy results: parsing output: 83/ for test: kms_lease results: parsing output: 84/ for test: kms_plane_multiple results: parsing output: 85/ for test: kms_frontbuffer_tracking results: parsing output: 86/ for test: sw_sync results: parsing output: 87/ for test: kms_cursor_legacy results: parsing output: 88/ for test: kms_cursor_legacy results: parsing output: 89/ for test: kms_async_flips results: parsing output: 90/ for test: kms_frontbuffer_tracking results: parsing output: 91/ for test: gem_eio results: parsing output: 92/ for test: kms_frontbuffer_tracking results: parsing output: 93/ for test: syncobj_timeline results: parsing output: 94/ for test: kms_addfb_basic results: parsing output: 95/ for test: gem_partial_pwrite_pread results: parsing output: 96/ for test: kms_frontbuffer_tracking results: parsing output: 97/ for test: kms_frontbuffer_tracking results: parsing output: 98/ for test: kms_psr results: parsing output: 99/ for test: kms_frontbuffer_tracking results: parsing output: 100/ for test: kms_cursor_legacy results: parsing output: 101/ for test: kms_big_fb results: parsing output: 102/ for test: kms_frontbuffer_tracking results: parsing output: 103/ for test: kms_frontbuffer_tracking results: parsing output: 104/ for test: kms_big_fb results: parsing output: 105/ for test: kms_atomic_interruptible results: parsing output: 106/ for test: i915_drm_fdinfo results: parsing output: 107/ for test: kms_frontbuffer_tracking results: parsing output: 108/ for test: kms_lease results: parsing output: 109/ for test: kms_chamelium_audio results: parsing output: 110/ for test: kms_frontbuffer_tracking results: parsing output: 111/ for test: gem_render_copy results: parsing output: 112/ for test: kms_pipe_crc_basic results: parsing output: 113/ for test: gem_exec_fence results: parsing output: 114/ for test: kms_plane_scaling results: parsing output: 115/ for test: perf_pmu results: parsing output: 116/ for test: kms_cursor_crc results: parsing output: 117/ for test: kms_frontbuffer_tracking results: parsing output: 118/ for test: gem_ctx_persistence results: parsing output: 119/ for test: kms_psr results: parsing output: 120/ for test: gem_userptr_blits results: parsing output: 121/ for test: gem_ccs results: parsing output: 122/ for test: kms_psr2_sf results: parsing output: 123/ for test: kms_properties results: parsing output: 124/ for test: kms_frontbuffer_tracking results: parsing output: 125/ for test: kms_frontbuffer_tracking results: parsing output: 126/ for test: gem_exec_async results: parsing output: 127/ for test: kms_flip_scaled_crc results: parsing output: 128/ for test: kms_frontbuffer_tracking results: parsing output: 129/ for test: kms_vblank results: parsing output: 130/ for test: gem_exec_reloc results: parsing output: 131/ for test: api_intel_allocator results: parsing output: 132/ for test: kms_frontbuffer_tracking results: parsing output: 133/ for test: kms_ccs results: parsing output: 134/ for test: drm_read results: parsing output: 135/ for test: kms_frontbuffer_tracking results: parsing output: 136/ for test: kms_color results: parsing output: 137/ for test: kms_psr_stress_test results: parsing output: 138/ for test: kms_frontbuffer_tracking results: parsing output: 139/ for test: kms_flip results: parsing output: 140/ for test: kms_frontbuffer_tracking results: parsing output: 141/ for test: kms_frontbuffer_tracking results: parsing output: 142/ for test: kms_flip_scaled_crc results: parsing output: 143/ for test: gem_exec_fence results: parsing output: 144/ for test: kms_frontbuffer_tracking results: parsing output: 145/ for test: gem_pxp results: parsing output: 146/ for test: gem_partial_pwrite_pread results: parsing output: 147/ for test: kms_chamelium_hpd results: parsing output: 148/ for test: gem_huc_copy results: parsing output: 149/ for test: kms_frontbuffer_tracking results: parsing output: 150/ for test: kms_prop_blob results: parsing output: 151/ for test: core_auth results: parsing output: 152/ for test: kms_frontbuffer_tracking results: parsing output: 153/ for test: gem_userptr_blits results: parsing output: 154/ for test: kms_psr results: parsing output: 155/ for test: kms_frontbuffer_tracking Done.