[42.526612] Initializing watchdogs [42.526642] /dev/watchdog0 [42.531353] [001/130] (960s left) syncobj_timeline (wait-all-for-submit-delayed-submit) [42.635688] Starting subtest: wait-all-for-submit-delayed-submit [42.741688] Subtest wait-all-for-submit-delayed-submit: SUCCESS (0.103s) [42.791730] [002/130] (960s left) sysfs_defaults (readonly) [42.847279] Starting subtest: readonly [42.849150] Starting dynamic subtest: vecs0 [42.850505] Dynamic subtest vecs0: SUCCESS (0.000s) [42.855818] Starting dynamic subtest: ccs0 [42.856937] Dynamic subtest ccs0: SUCCESS (0.000s) [42.860458] Starting dynamic subtest: vcs0 [42.861477] Dynamic subtest vcs0: SUCCESS (0.000s) [42.865067] Starting dynamic subtest: bcs0 [42.866077] Dynamic subtest bcs0: SUCCESS (0.000s) [42.869561] Starting dynamic subtest: rcs0 [42.870617] Dynamic subtest rcs0: SUCCESS (0.000s) [42.874176] Starting dynamic subtest: vcs1 [42.875208] Dynamic subtest vcs1: SUCCESS (0.000s) [42.876200] Subtest readonly: SUCCESS (0.001s) [42.905261] [003/130] (960s left) gen9_exec_parse (unaligned-jump) [42.953724] Subtest unaligned-jump: SKIP (0.000s) [42.996961] [004/130] (960s left) gem_mmap_wc (invalid-flags) [43.043907] Subtest invalid-flags: SKIP (0.000s) [43.082611] [005/130] (959s left) kms_properties (crtc-properties-atomic) [43.163554] Starting subtest: crtc-properties-atomic [43.164561] Starting dynamic subtest: pipe-A-eDP-1 [43.622563] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.458s) [43.628253] Starting dynamic subtest: pipe-B-eDP-1 [44.853568] Dynamic subtest pipe-B-eDP-1: SUCCESS (1.230s) [44.861756] Starting dynamic subtest: pipe-C-eDP-1 [46.058241] Dynamic subtest pipe-C-eDP-1: SUCCESS (1.204s) [46.063885] Starting dynamic subtest: pipe-D-eDP-1 [47.271778] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.212s) [47.272970] Subtest crtc-properties-atomic: SUCCESS (4.107s) [48.151894] [006/130] (954s left) gem_ctx_param (invalid-ctx-get) [48.199922] Starting subtest: invalid-ctx-get [48.201744] Subtest invalid-ctx-get: SUCCESS (0.000s) [48.238656] [007/130] (954s left) gem_pxp (fail-invalid-protected-context) [50.813581] Subtest fail-invalid-protected-context: SKIP (0.000s) [50.859294] [008/130] (952s left) syncobj_eventfd (timeline-wait-before-signal) [50.901963] Starting subtest: timeline-wait-before-signal [50.933219] Subtest timeline-wait-before-signal: SUCCESS (0.031s) [50.979138] [009/130] (952s left) kms_frontbuffer_tracking (fbc-2p-primscrn-indfb-pgflip-blt) [51.063759] Starting subtest: fbc-2p-primscrn-indfb-pgflip-blt [51.065507] Subtest fbc-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [51.132195] [010/130] (951s left) gem_exec_reloc (basic-softpin) [51.174321] Subtest basic-softpin: SKIP (0.000s) [51.220823] [011/130] (951s left) kms_rotation_crc (primary-y-tiled-reflect-x-0) [51.305529] Starting subtest: primary-y-tiled-reflect-x-0 [51.367689] Subtest primary-y-tiled-reflect-x-0: SKIP (0.059s) [51.441485] [012/130] (951s left) kms_ccs (bad-pixel-format-4-tiled-dg2-mc-ccs) [51.527543] Starting subtest: bad-pixel-format-4-tiled-dg2-mc-ccs [51.528628] Starting dynamic subtest: pipe-A-eDP-1 [51.537911] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [51.543036] Starting dynamic subtest: pipe-B-eDP-1 [51.551475] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [51.555156] Starting dynamic subtest: pipe-C-eDP-1 [51.563408] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [51.567093] Starting dynamic subtest: pipe-D-eDP-1 [51.575340] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [51.577030] Subtest bad-pixel-format-4-tiled-dg2-mc-ccs: SKIP (0.002s) [53.844378] [013/130] (949s left) gem_madvise (dontneed-before-mmap) [53.875686] Starting subtest: dontneed-before-mmap [53.912085] Subtest dontneed-before-mmap: SUCCESS (0.036s) [53.998606] [014/130] (949s left) kms_frontbuffer_tracking (psr-rgb565-draw-mmap-gtt) [54.099704] Starting subtest: psr-rgb565-draw-mmap-gtt [54.101435] Subtest psr-rgb565-draw-mmap-gtt: SKIP (0.000s) [54.171305] [015/130] (949s left) kms_joiner (basic-big-joiner) [54.259976] Starting subtest: basic-big-joiner [54.261638] Subtest basic-big-joiner: SKIP (0.000s) [54.301357] [016/130] (948s left) gem_exec_schedule (thriceslice) [54.348764] Starting subtest: thriceslice [54.349587] Starting dynamic subtest: rcs0 [54.376184] Dynamic subtest rcs0: SUCCESS (0.032s) [54.380949] Starting dynamic subtest: bcs0 [54.406513] Dynamic subtest bcs0: SUCCESS (0.030s) [54.412476] Starting dynamic subtest: vcs0 [54.456370] Dynamic subtest vcs0: SUCCESS (0.049s) [54.463186] Starting dynamic subtest: vcs1 [54.485896] Dynamic subtest vcs1: SUCCESS (0.030s) [54.491315] Starting dynamic subtest: vecs0 [54.533314] Dynamic subtest vecs0: SUCCESS (0.047s) [54.538945] Starting dynamic subtest: ccs0 [54.562394] Dynamic subtest ccs0: SUCCESS (0.029s) [54.563664] Subtest thriceslice: SUCCESS (0.218s) [56.388477] [017/130] (946s left) kms_psr2_sf (psr2-cursor-plane-move-continuous-exceed-sf) [61.482516] Starting subtest: psr2-cursor-plane-move-continuous-exceed-sf [61.483700] Starting dynamic subtest: pipe-A-eDP-1 [72.893262] Dynamic subtest pipe-A-eDP-1: SUCCESS (11.506s) [72.897804] Starting dynamic subtest: pipe-B-eDP-1 [84.364508] Dynamic subtest pipe-B-eDP-1: SUCCESS (11.490s) [84.365923] Subtest psr2-cursor-plane-move-continuous-exceed-sf: SUCCESS (23.000s) [85.267078] [018/130] (917s left) prime_busy (hang) [85.317268] Starting subtest: hang [85.318760] Starting dynamic subtest: rcs0 [111.883400] Dynamic subtest rcs0: SUCCESS (26.565s) [111.890329] Starting dynamic subtest: bcs0 [119.038061] Dynamic subtest bcs0: SUCCESS (7.155s) [119.044227] Starting dynamic subtest: vcs0 [126.013222] Dynamic subtest vcs0: SUCCESS (6.976s) [126.017388] Starting dynamic subtest: vcs1 [133.057339] Dynamic subtest vcs1: SUCCESS (7.044s) [133.061334] Starting dynamic subtest: vecs0 [140.026435] Dynamic subtest vecs0: SUCCESS (6.968s) [140.031027] Starting dynamic subtest: ccs0 [153.863877] Dynamic subtest ccs0: SUCCESS (13.837s) [153.865293] Subtest hang: SUCCESS (68.545s) [153.976113] [019/130] (849s left) gem_softpin (noreloc-interruptible) [154.018926] Starting subtest: noreloc-interruptible [159.419681] Subtest noreloc-interruptible: SUCCESS (5.398s) [159.568982] [020/130] (843s left) gem_pipe_control_store_loop (reused-buffer) [159.625604] Starting subtest: reused-buffer [161.779523] Subtest reused-buffer: SUCCESS (2.152s) [161.885482] [021/130] (841s left) perf (create-destroy-userspace-config) [162.042060] Starting subtest: create-destroy-userspace-config [162.059074] Subtest create-destroy-userspace-config: SUCCESS (0.017s) [162.080116] [022/130] (840s left) kms_atomic_interruptible (legacy-dpms) [162.170376] Starting subtest: legacy-dpms [162.171556] Starting dynamic subtest: pipe-A-eDP-1 [169.671800] Dynamic subtest pipe-A-eDP-1: SUCCESS (7.500s) [169.672663] Subtest legacy-dpms: SUCCESS (7.501s) [170.522107] [023/130] (832s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-wc) [170.613889] Starting subtest: fbcpsr-rgb565-draw-mmap-wc [175.118140] Subtest fbcpsr-rgb565-draw-mmap-wc: SUCCESS (4.503s) [175.203654] [024/130] (827s left) gem_ctx_freq (sysfs) [175.240807] Starting subtest: sysfs [175.242020] Starting dynamic subtest: gt0 [180.762770] Dynamic subtest gt0: SUCCESS (5.521s) [180.767926] Starting dynamic subtest: gt1 [222.392180] Dynamic subtest gt1: SUCCESS (41.626s) [222.393642] Subtest sysfs: SUCCESS (47.147s) [222.440421] [025/130] (780s left) kms_frontbuffer_tracking (psr-2p-primscrn-cur-indfb-draw-mmap-wc) [222.526475] Starting subtest: psr-2p-primscrn-cur-indfb-draw-mmap-wc [222.528469] Subtest psr-2p-primscrn-cur-indfb-draw-mmap-wc: SKIP (0.000s) [222.591431] [026/130] (780s left) kms_plane_scaling (plane-downscale-factor-0-5-with-rotation) [222.673806] Starting subtest: plane-downscale-factor-0-5-with-rotation [222.675024] Starting dynamic subtest: pipe-A [222.680854] Dynamic subtest pipe-A: SKIP (0.004s) [222.686831] Starting dynamic subtest: pipe-B [222.693851] Dynamic subtest pipe-B: SKIP (0.014s) [222.698135] Starting dynamic subtest: pipe-C [222.707804] Dynamic subtest pipe-C: SKIP (0.014s) [222.713107] Starting dynamic subtest: pipe-D [222.722108] Dynamic subtest pipe-D: SKIP (0.014s) [222.724032] Subtest plane-downscale-factor-0-5-with-rotation: SKIP (0.046s) [222.792968] [027/130] (780s left) gem_ctx_shared (detached-shared-gtt) [222.842390] Starting subtest: detached-shared-gtt [224.996197] Subtest detached-shared-gtt: SUCCESS (2.152s) [225.316776] [028/130] (777s left) kms_cursor_crc (cursor-rapid-movement-64x64) [225.443176] Starting subtest: cursor-rapid-movement-64x64 [225.446222] Starting dynamic subtest: pipe-A-eDP-1 [225.966166] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.517s) [225.983180] Starting dynamic subtest: pipe-D-eDP-1 [227.223114] Dynamic subtest pipe-D-eDP-1: SUCCESS (1.245s) [227.224081] Subtest cursor-rapid-movement-64x64: SUCCESS (1.780s) [228.088404] [029/130] (775s left) kms_addfb_basic (addfb25-x-tiled-legacy) [228.138031] Subtest addfb25-x-tiled-legacy: SKIP (0.000s) [228.261938] [030/130] (774s left) kms_psr (fbc-pr-primary-mmap-cpu) [228.344662] Starting subtest: fbc-pr-primary-mmap-cpu [228.346560] Subtest fbc-pr-primary-mmap-cpu: SKIP (0.000s) [228.388924] [031/130] (774s left) kms_big_fb (y-tiled-64bpp-rotate-0) [228.484102] Starting subtest: y-tiled-64bpp-rotate-0 [228.485839] Subtest y-tiled-64bpp-rotate-0: SKIP (0.000s) [228.546061] [032/130] (774s left) kms_flip (dpms-off-confusion-interruptible) [228.629800] Starting subtest: dpms-off-confusion-interruptible [228.631883] Starting dynamic subtest: A-eDP1 [237.346820] Dynamic subtest A-eDP1: SUCCESS (8.714s) [237.354334] Starting dynamic subtest: B-eDP1 [246.049861] Dynamic subtest B-eDP1: SUCCESS (8.702s) [246.056907] Starting dynamic subtest: C-eDP1 [254.742984] Dynamic subtest C-eDP1: SUCCESS (8.692s) [254.749609] Starting dynamic subtest: D-eDP1 [263.446774] Dynamic subtest D-eDP1: SUCCESS (8.704s) [263.449150] Subtest dpms-off-confusion-interruptible: SUCCESS (34.819s) [264.661152] [033/130] (738s left) perf_pmu (busy-accuracy-50) [264.692448] Starting subtest: busy-accuracy-50 [264.693541] Starting dynamic subtest: rcs0 [266.222487] Dynamic subtest rcs0: SUCCESS (1.529s) [266.229120] Starting dynamic subtest: bcs0 [267.756056] Dynamic subtest bcs0: SUCCESS (1.533s) [267.763215] Starting dynamic subtest: vcs0 [269.292464] Dynamic subtest vcs0: SUCCESS (1.536s) [269.299668] Starting dynamic subtest: vcs1 [270.834420] Dynamic subtest vcs1: SUCCESS (1.542s) [270.841103] Starting dynamic subtest: vecs0 [272.372097] Dynamic subtest vecs0: SUCCESS (1.537s) [272.379230] Starting dynamic subtest: ccs0 [273.914395] Dynamic subtest ccs0: SUCCESS (1.542s) [273.915671] Subtest busy-accuracy-50: SUCCESS (9.221s) [274.002924] [034/130] (729s left) kms_big_fb (yf-tiled-addfb) [274.097939] Starting subtest: yf-tiled-addfb [274.099722] Subtest yf-tiled-addfb: SKIP (0.000s) [274.155489] [035/130] (729s left) kms_frontbuffer_tracking (fbcpsr-1p-offscren-pri-shrfb-draw-blt) [274.245305] Starting subtest: fbcpsr-1p-offscren-pri-shrfb-draw-blt [278.769807] Subtest fbcpsr-1p-offscren-pri-shrfb-draw-blt: SUCCESS (4.523s) [278.891946] [036/130] (724s left) kms_rotation_crc (primary-rotation-270) [278.973453] Starting subtest: primary-rotation-270 [278.975127] Subtest primary-rotation-270: SKIP (0.001s) [279.021480] [037/130] (724s left) kms_big_fb (4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [279.108620] Starting subtest: 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [279.656043] Subtest 4-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SUCCESS (0.547s) [279.777646] [038/130] (723s left) kms_content_protection (dp-mst-lic-type-1) [279.905157] Starting subtest: dp-mst-lic-type-1 [279.907231] Subtest dp-mst-lic-type-1: SKIP (0.000s) [280.021768] [039/130] (723s left) kms_hdr (static-toggle-suspend) [280.106508] Starting subtest: static-toggle-suspend [280.109519] Subtest static-toggle-suspend: SKIP (0.000s) [280.152074] [040/130] (723s left) kms_psr (fbc-psr-suspend) [280.235549] Starting subtest: fbc-psr-suspend [280.236533] Starting dynamic subtest: eDP-1 [280.238370] Dynamic subtest eDP-1: SKIP (0.000s) [280.240109] Subtest fbc-psr-suspend: SKIP (0.000s) [280.282850] [041/130] (722s left) kms_frontbuffer_tracking (psr-1p-pri-indfb-multidraw) [280.370867] Starting subtest: psr-1p-pri-indfb-multidraw [298.918193] Subtest psr-1p-pri-indfb-multidraw: SUCCESS (18.547s) [298.993014] [042/130] (704s left) api_intel_bb (blit-noreloc-purge-cache) [299.038734] Starting subtest: blit-noreloc-purge-cache [299.046315] Subtest blit-noreloc-purge-cache: SUCCESS (0.009s) [299.104589] [043/130] (704s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-indfb-draw-blt) [299.191051] Starting subtest: fbcpsr-2p-scndscrn-pri-indfb-draw-blt [299.192964] Subtest fbcpsr-2p-scndscrn-pri-indfb-draw-blt: SKIP (0.000s) [299.260246] [044/130] (704s left) gem_exec_fence (invalid-fence-array) [299.664710] Starting subtest: invalid-fence-array [299.668253] Subtest invalid-fence-array: SUCCESS (0.002s) [301.401507] [045/130] (701s left) kms_chamelium_hpd (hdmi-hpd) [301.764466] Subtest hdmi-hpd: SKIP (0.000s) [302.655210] [046/130] (700s left) gen9_exec_parse (allowed-single) [302.703181] Subtest allowed-single: SKIP (0.000s) [302.744392] [047/130] (700s left) gem_sync (basic-store-each) [302.796867] Starting subtest: basic-store-each [305.114122] Subtest basic-store-each: SUCCESS (2.320s) [305.269625] [048/130] (698s left) gem_exec_balancer (bonded-true-hang) [305.473778] Starting subtest: bonded-true-hang [305.476021] Subtest bonded-true-hang: SKIP (0.000s) [305.538995] [049/130] (697s left) perf_pmu (render-node-busy) [305.597556] Starting subtest: render-node-busy [305.598919] Starting dynamic subtest: rcs0 [306.169620] Dynamic subtest rcs0: SUCCESS (0.572s) [306.177613] Starting dynamic subtest: bcs0 [306.729801] Dynamic subtest bcs0: SUCCESS (0.559s) [306.738106] Starting dynamic subtest: vcs0 [308.406942] Dynamic subtest vcs0: SUCCESS (1.674s) [308.415307] Starting dynamic subtest: vcs1 [310.391213] Dynamic subtest vcs1: SUCCESS (1.983s) [310.399123] Starting dynamic subtest: vecs0 [312.375531] Dynamic subtest vecs0: SUCCESS (1.983s) [312.383504] Starting dynamic subtest: ccs0 [312.938847] Dynamic subtest ccs0: SUCCESS (0.564s) [312.940788] Subtest render-node-busy: SUCCESS (7.341s) [312.990801] [050/130] (690s left) drm_fdinfo (memory-info-purgeable) [313.017344] Starting subtest: memory-info-purgeable [313.018638] Starting dynamic subtest: smem0 [315.745574] Dynamic subtest smem0: SUCCESS (2.729s) [315.746581] Subtest memory-info-purgeable: SUCCESS (2.730s) [315.799222] [051/130] (687s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu) [315.891648] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu [315.893330] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [315.963187] [052/130] (687s left) core_setmaster (master-drop-set-user) [316.445514] Starting subtest: master-drop-set-user [316.458078] Subtest master-drop-set-user: SUCCESS (0.014s) [316.468072] [053/130] (686s left) gem_ctx_sseu (mmap-args) [316.511111] Subtest mmap-args: SKIP (0.000s) [316.553661] [054/130] (686s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt) [316.634881] Starting subtest: fbcpsr-farfromfence-mmap-gtt [316.636707] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s) [316.698314] [055/130] (686s left) syncobj_timeline (reset-unsignaled) [316.740363] Starting subtest: reset-unsignaled [316.741484] Subtest reset-unsignaled: SUCCESS (0.000s) [316.783323] [056/130] (686s left) i915_pm_rps (fence-order) [316.827501] Starting subtest: fence-order [320.118668] Subtest fence-order: SUCCESS (3.290s) [320.152942] [057/130] (683s left) kms_draw_crc (fill-fb) [320.253441] Starting subtest: fill-fb [320.628313] Subtest fill-fb: SUCCESS (0.375s) [320.711484] [058/130] (682s left) kms_chamelium_color (ctm-max) [320.797471] Subtest ctm-max: SKIP (0.000s) [320.815649] [059/130] (682s left) gem_exec_parallel (userptr) [320.858518] Starting subtest: userptr [320.859582] Starting dynamic subtest: rcs0 [322.243877] Dynamic subtest rcs0: SUCCESS (1.385s) [322.251676] Starting dynamic subtest: bcs0 [323.582751] Dynamic subtest bcs0: SUCCESS (1.338s) [323.590752] Starting dynamic subtest: vcs0 [325.366617] Dynamic subtest vcs0: SUCCESS (1.780s) [325.374384] Starting dynamic subtest: vcs1 [327.415833] Dynamic subtest vcs1: SUCCESS (2.048s) [327.424297] Starting dynamic subtest: vecs0 [329.334743] Dynamic subtest vecs0: SUCCESS (1.918s) [329.341065] Starting dynamic subtest: ccs0 [330.761077] Dynamic subtest ccs0: SUCCESS (1.428s) [330.762529] Subtest userptr: SUCCESS (9.902s) [330.923402] [060/130] (672s left) kms_frontbuffer_tracking (fbc-2p-primscrn-pri-indfb-draw-blt) [331.012823] Starting subtest: fbc-2p-primscrn-pri-indfb-draw-blt [331.015211] Subtest fbc-2p-primscrn-pri-indfb-draw-blt: SKIP (0.000s) [331.091585] [061/130] (672s left) gem_gtt_cpu_tlb [331.161934] [062/130] (672s left) kms_pm_rpm (cursor-dpms) [332.665844] Starting subtest: cursor-dpms [343.588860] Subtest cursor-dpms: SUCCESS (10.923s) [343.924646] [063/130] (659s left) kms_flip (bo-too-big) [344.011423] Starting subtest: bo-too-big [344.013799] Starting dynamic subtest: A-eDP1 [345.374930] Dynamic subtest A-eDP1: SUCCESS (1.360s) [345.382316] Starting dynamic subtest: B-eDP1 [346.706458] Dynamic subtest B-eDP1: SUCCESS (1.331s) [346.713526] Starting dynamic subtest: C-eDP1 [348.066470] Dynamic subtest C-eDP1: SUCCESS (1.360s) [348.072156] Starting dynamic subtest: D-eDP1 [349.405509] Dynamic subtest D-eDP1: SUCCESS (1.338s) [349.409386] Subtest bo-too-big: SUCCESS (5.396s) [350.677755] [064/130] (652s left) gem_lmem_swapping (basic) [350.703598] Subtest basic: SKIP (0.000s) [350.715432] [065/130] (652s left) syncobj_timeline (etime-multi-wait-submitted) [350.756917] Starting subtest: etime-multi-wait-submitted [350.867605] Subtest etime-multi-wait-submitted: SUCCESS (0.108s) [350.916337] [066/130] (652s left) gem_exec_store (cachelines) [350.961953] Starting subtest: cachelines [350.962850] Starting dynamic subtest: rcs0 [351.059513] Dynamic subtest rcs0: SUCCESS (0.097s) [351.066651] Starting dynamic subtest: bcs0 [351.109971] Dynamic subtest bcs0: SUCCESS (0.049s) [351.117605] Starting dynamic subtest: vcs0 [351.160351] Dynamic subtest vcs0: SUCCESS (0.050s) [351.167425] Starting dynamic subtest: vcs1 [351.210106] Dynamic subtest vcs1: SUCCESS (0.049s) [351.217067] Starting dynamic subtest: vecs0 [351.260376] Dynamic subtest vecs0: SUCCESS (0.049s) [351.268074] Starting dynamic subtest: ccs0 [351.314160] Dynamic subtest ccs0: SUCCESS (0.053s) [351.315464] Subtest cachelines: SUCCESS (0.352s) [351.470484] [067/130] (652s left) gem_exec_schedule (preempt-engines) [351.516407] Starting subtest: preempt-engines [351.517508] Starting dynamic subtest: rcs0 [353.798179] Dynamic subtest rcs0: SUCCESS (2.279s) [353.806218] Starting dynamic subtest: bcs0 [356.096947] Dynamic subtest bcs0: SUCCESS (2.298s) [356.105756] Starting dynamic subtest: vcs0 [358.378965] Dynamic subtest vcs0: SUCCESS (2.281s) [358.390087] Starting dynamic subtest: vcs1 [360.666410] Dynamic subtest vcs1: SUCCESS (2.287s) [360.673236] Starting dynamic subtest: vecs0 [362.962373] Dynamic subtest vecs0: SUCCESS (2.295s) [362.969936] Starting dynamic subtest: ccs0 [365.223883] Dynamic subtest ccs0: SUCCESS (2.261s) [365.225052] Subtest preempt-engines: SUCCESS (13.705s) [365.490952] [068/130] (638s left) kms_ccs (bad-rotation-90-4-tiled-dg2-rc-ccs) [365.576596] Starting subtest: bad-rotation-90-4-tiled-dg2-rc-ccs [365.577572] Starting dynamic subtest: pipe-A-eDP-1 [365.586588] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [365.592872] Starting dynamic subtest: pipe-B-eDP-1 [365.601464] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [365.607459] Starting dynamic subtest: pipe-C-eDP-1 [365.615755] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [365.620675] Starting dynamic subtest: pipe-D-eDP-1 [365.629417] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [365.631042] Subtest bad-rotation-90-4-tiled-dg2-rc-ccs: SKIP (0.003s) [367.892781] [069/130] (635s left) gem_pread (bench) [367.941601] Subtest bench: SKIP (0.000s) [367.980238] [070/130] (635s left) kms_frontbuffer_tracking (psr-2p-primscrn-spr-indfb-draw-render) [368.063824] Starting subtest: psr-2p-primscrn-spr-indfb-draw-render [368.065644] Subtest psr-2p-primscrn-spr-indfb-draw-render: SKIP (0.000s) [368.150328] [071/130] (635s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt) [368.241863] Starting subtest: fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt [368.243695] Subtest fbc-2p-scndscrn-pri-indfb-draw-mmap-gtt: SKIP (0.000s) [368.308460] [072/130] (635s left) gem_softpin (full) [368.351807] Starting subtest: full [368.358879] Subtest full: SUCCESS (0.007s) [368.453505] [073/130] (635s left) kms_ccs (random-ccs-data-4-tiled-lnl-ccs) [368.546360] Starting subtest: random-ccs-data-4-tiled-lnl-ccs [368.549351] Subtest random-ccs-data-4-tiled-lnl-ccs: SKIP (0.000s) [369.689122] [074/130] (633s left) kms_legacy_colorkey (invalid-plane) [369.767646] Starting subtest: invalid-plane [369.768717] Starting dynamic subtest: zero-id [369.769749] Dynamic subtest zero-id: SUCCESS (0.000s) [369.775676] Starting dynamic subtest: outrange-id [369.776893] Dynamic subtest outrange-id: SUCCESS (0.000s) [369.778041] Subtest invalid-plane: SUCCESS (0.000s) [369.815199] [075/130] (633s left) kms_cursor_crc (cursor-random-32x10) [369.921998] Starting subtest: cursor-random-32x10 [369.955818] Subtest cursor-random-32x10: SKIP (0.032s) [370.170124] [076/130] (633s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt) [370.273137] Starting subtest: fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt [370.275684] Subtest fbcpsr-2p-scndscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [370.356775] [077/130] (633s left) gem_busy (parallel) [370.521671] Starting subtest: parallel [370.523416] Starting dynamic subtest: rcs0 [372.407776] Dynamic subtest rcs0: SUCCESS (1.884s) [372.419621] Starting dynamic subtest: bcs0 [374.392210] Dynamic subtest bcs0: SUCCESS (1.983s) [374.400701] Starting dynamic subtest: vcs0 [376.376335] Dynamic subtest vcs0: SUCCESS (1.983s) [376.384627] Starting dynamic subtest: vcs1 [378.359303] Dynamic subtest vcs1: SUCCESS (1.982s) [378.368274] Starting dynamic subtest: vecs0 [380.407240] Dynamic subtest vecs0: SUCCESS (2.047s) [380.418022] Starting dynamic subtest: ccs0 [382.391526] Dynamic subtest ccs0: SUCCESS (1.983s) [382.393068] Subtest parallel: SUCCESS (11.868s) [382.606339] [078/130] (621s left) kms_big_fb (y-tiled-16bpp-rotate-270) [382.706926] Starting subtest: y-tiled-16bpp-rotate-270 [382.708648] Subtest y-tiled-16bpp-rotate-270: SKIP (0.000s) [382.793392] [079/130] (620s left) syncobj_timeline (multi-wait-all-for-submit-available-submitted-signaled) [382.836931] Starting subtest: multi-wait-all-for-submit-available-submitted-signaled [382.837789] Subtest multi-wait-all-for-submit-available-submitted-signaled: SUCCESS (0.000s) [382.892514] [080/130] (620s left) kms_sequence (get-busy) [382.975586] Starting subtest: get-busy [382.976577] Starting dynamic subtest: pipe-A-eDP-1 [384.873904] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.897s) [384.880359] Starting dynamic subtest: pipe-B-eDP-1 [387.522097] Dynamic subtest pipe-B-eDP-1: SUCCESS (2.647s) [387.528128] Starting dynamic subtest: pipe-C-eDP-1 [390.210881] Dynamic subtest pipe-C-eDP-1: SUCCESS (2.686s) [390.217066] Starting dynamic subtest: pipe-D-eDP-1 [392.888707] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.677s) [392.890022] Subtest get-busy: SUCCESS (9.911s) [393.750295] [081/130] (609s left) kms_psr (fbc-psr2-cursor-mmap-gtt) [393.831605] Starting subtest: fbc-psr2-cursor-mmap-gtt [393.832536] Starting dynamic subtest: eDP-1 [393.834322] Dynamic subtest eDP-1: SKIP (0.000s) [393.836027] Subtest fbc-psr2-cursor-mmap-gtt: SKIP (0.001s) [393.879927] [082/130] (609s left) gem_userptr_blits (stress-mm) [393.930786] Starting subtest: stress-mm [399.302882] Subtest stress-mm: SUCCESS (5.369s) [399.398204] [083/130] (604s left) gem_lmem_swapping (parallel-random-verify-ccs) [399.424546] Subtest parallel-random-verify-ccs: SKIP (0.000s) [399.445781] [084/130] (604s left) kms_ccs (missing-ccs-buffer-4-tiled-mtl-mc-ccs) [399.531557] Starting subtest: missing-ccs-buffer-4-tiled-mtl-mc-ccs [399.532470] Starting dynamic subtest: pipe-A-eDP-1 [399.556151] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.023s) [399.561623] Starting dynamic subtest: pipe-B-eDP-1 [399.576779] Dynamic subtest pipe-B-eDP-1: SUCCESS (0.020s) [399.582029] Starting dynamic subtest: pipe-C-eDP-1 [399.598766] Dynamic subtest pipe-C-eDP-1: SUCCESS (0.021s) [399.603773] Starting dynamic subtest: pipe-D-eDP-1 [399.621772] Dynamic subtest pipe-D-eDP-1: SUCCESS (0.022s) [399.622662] Subtest missing-ccs-buffer-4-tiled-mtl-mc-ccs: SUCCESS (0.090s) [401.942932] [085/130] (601s left) kms_prop_blob (blob-prop-core) [401.999890] Starting subtest: blob-prop-core [402.001339] Subtest blob-prop-core: SUCCESS (0.000s) [402.065131] [086/130] (601s left) kms_frontbuffer_tracking (fbc-1p-primscrn-cur-indfb-onoff) [402.158528] Starting subtest: fbc-1p-primscrn-cur-indfb-onoff [404.947442] Subtest fbc-1p-primscrn-cur-indfb-onoff: SUCCESS (2.789s) [405.071137] [087/130] (598s left) gem_reg_read (timestamp-monotonic) [405.116817] Starting subtest: timestamp-monotonic [409.403528] Subtest timestamp-monotonic: SUCCESS (4.286s) [409.450234] [088/130] (594s left) kms_frontbuffer_tracking (psr-2p-scndscrn-cur-indfb-onoff) [409.537917] Starting subtest: psr-2p-scndscrn-cur-indfb-onoff [409.539678] Subtest psr-2p-scndscrn-cur-indfb-onoff: SKIP (0.000s) [409.604056] [089/130] (594s left) kms_big_fb (x-tiled-32bpp-rotate-270) [409.689694] Starting subtest: x-tiled-32bpp-rotate-270 [409.708392] Subtest x-tiled-32bpp-rotate-270: SKIP (0.018s) [409.809763] [090/130] (593s left) drm_fdinfo (busy-check-all) [409.833305] Starting subtest: busy-check-all [409.834219] Starting dynamic subtest: rcs0 [409.837398] Dynamic subtest rcs0: SKIP (0.004s) [409.842193] Starting dynamic subtest: bcs0 [409.843976] Dynamic subtest bcs0: SKIP (0.000s) [409.848630] Starting dynamic subtest: vcs0 [409.850473] Dynamic subtest vcs0: SKIP (0.000s) [409.855211] Starting dynamic subtest: vcs1 [409.856984] Dynamic subtest vcs1: SKIP (0.000s) [409.861544] Starting dynamic subtest: vecs0 [409.864083] Dynamic subtest vecs0: SKIP (0.000s) [409.868555] Starting dynamic subtest: ccs0 [409.870361] Dynamic subtest ccs0: SKIP (0.000s) [409.872048] Subtest busy-check-all: SKIP (0.006s) [409.886275] [091/130] (593s left) kms_frontbuffer_tracking (fbc-rgb565-draw-mmap-gtt) [409.999409] Starting subtest: fbc-rgb565-draw-mmap-gtt [410.001084] Subtest fbc-rgb565-draw-mmap-gtt: SKIP (0.000s) [410.059548] [092/130] (593s left) kms_vblank (wait-forked-busy) [410.144143] Starting subtest: wait-forked-busy [410.145691] Starting dynamic subtest: pipe-A-eDP-1 [412.021384] Dynamic subtest pipe-A-eDP-1: SUCCESS (1.876s) [412.031877] Starting dynamic subtest: pipe-D-eDP-1 [414.636595] Dynamic subtest pipe-D-eDP-1: SUCCESS (2.612s) [414.637780] Subtest wait-forked-busy: SUCCESS (4.491s) [415.510862] [093/130] (588s left) gem_exec_params (invalid-bsd2-flag-on-blt) [415.560174] Starting subtest: invalid-bsd2-flag-on-blt [415.561343] Subtest invalid-bsd2-flag-on-blt: SUCCESS (0.000s) [415.630252] [094/130] (588s left) prime_self_import (basic-with_fd_dup) [415.651218] Starting subtest: basic-with_fd_dup [415.667987] Subtest basic-with_fd_dup: SUCCESS (0.017s) [415.762284] [095/130] (588s left) syncobj_wait (invalid-multi-wait-all-unsubmitted-submitted) [415.805435] Starting subtest: invalid-multi-wait-all-unsubmitted-submitted [415.806759] Subtest invalid-multi-wait-all-unsubmitted-submitted: SUCCESS (0.000s) [415.889461] [096/130] (587s left) gem_eio (in-flight-1us) [416.103138] Starting subtest: in-flight-1us [424.156855] Subtest in-flight-1us: SUCCESS (8.054s) [424.303549] [097/130] (579s left) kms_feature_discovery (psr1) [424.384463] Starting subtest: psr1 [424.385486] Subtest psr1: SUCCESS (0.000s) [424.431991] [098/130] (579s left) kms_flip (flip-vs-blocking-wf-vblank) [424.518988] Starting subtest: flip-vs-blocking-wf-vblank [424.520223] Starting dynamic subtest: A-eDP1 [433.495054] Dynamic subtest A-eDP1: SUCCESS (8.975s) [433.502258] Starting dynamic subtest: B-eDP1 [442.517563] Dynamic subtest B-eDP1: SUCCESS (9.020s) [442.527166] Starting dynamic subtest: C-eDP1 [451.532218] Dynamic subtest C-eDP1: SUCCESS (9.013s) [451.538776] Starting dynamic subtest: D-eDP1 [460.489191] Dynamic subtest D-eDP1: SUCCESS (8.955s) [460.491801] Subtest flip-vs-blocking-wf-vblank: SUCCESS (35.973s) [461.718559] [099/130] (542s left) kms_psr (psr2-basic) [461.806082] Starting subtest: psr2-basic [461.807743] Starting dynamic subtest: eDP-1 [463.484470] Dynamic subtest eDP-1: SUCCESS (1.677s) [463.485500] Subtest psr2-basic: SUCCESS (1.678s) [464.343442] [100/130] (539s left) kms_big_fb (linear-64bpp-rotate-90) [464.442977] Starting subtest: linear-64bpp-rotate-90 [464.461162] Subtest linear-64bpp-rotate-90: SKIP (0.018s) [464.550234] [101/130] (539s left) i915_pm_rpm (system-suspend-devices) [466.463818] Starting subtest: system-suspend-devices [473.997541] Subtest system-suspend-devices: SUCCESS (7.531s) [474.325472] [102/130] (529s left) gem_userptr_blits (huge-split) [474.377806] Starting subtest: huge-split [474.384913] Subtest huge-split: SUCCESS (0.007s) [474.484864] [103/130] (529s left) gem_ctx_persistence (heartbeat-many) [474.537120] Starting subtest: heartbeat-many [474.539876] Subtest heartbeat-many: SKIP (0.000s) [474.615794] [104/130] (529s left) gem_mmap_gtt (basic-small-bo-tiledy) [474.655132] Subtest basic-small-bo-tiledY: SKIP (0.000s) [474.688130] [105/130] (529s left) kms_plane (planar-pixel-format-settings) [474.772536] Starting subtest: planar-pixel-format-settings [474.798078] Subtest planar-pixel-format-settings: SUCCESS (0.025s) [474.877665] [106/130] (529s left) gem_render_copy (yf-tiled-to-vebox-y-tiled) [474.926129] Starting subtest: yf-tiled-to-vebox-y-tiled [474.929989] Subtest yf-tiled-to-vebox-y-tiled: SKIP (0.000s) [475.082111] [107/130] (528s left) kms_plane_scaling (planes-upscale-factor-0-25-downscale-factor-0-75) [475.174932] Starting subtest: planes-upscale-factor-0-25-downscale-factor-0-75 [475.176207] Starting dynamic subtest: pipe-A [475.189717] Dynamic subtest pipe-A: SKIP (0.013s) [475.197072] Starting dynamic subtest: pipe-B [475.212437] Dynamic subtest pipe-B: SKIP (0.023s) [475.217915] Starting dynamic subtest: pipe-C [475.237272] Dynamic subtest pipe-C: SKIP (0.024s) [475.242819] Starting dynamic subtest: pipe-D [475.261949] Dynamic subtest pipe-D: SKIP (0.024s) [475.263628] Subtest planes-upscale-factor-0-25-downscale-factor-0-75: SKIP (0.085s) [475.321520] [108/130] (528s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-cur-indfb-draw-blt) [475.402524] Starting subtest: fbcpsr-2p-primscrn-cur-indfb-draw-blt [475.404438] Subtest fbcpsr-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [475.477833] [109/130] (528s left) kms_atomic (plane-invalid-params) [475.552034] Starting subtest: plane-invalid-params [475.553191] Starting dynamic subtest: pipe-A-eDP-1 [475.937824] Dynamic subtest pipe-A-eDP-1: SUCCESS (0.385s) [475.938808] Subtest plane-invalid-params: SUCCESS (0.386s) [476.824154] [110/130] (527s left) kms_psr2_sf (fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf) [481.928086] Starting subtest: fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf [481.929331] Starting dynamic subtest: pipe-A-eDP-1 [482.809221] Dynamic subtest pipe-A-eDP-1: SKIP (0.880s) [482.815566] Starting dynamic subtest: pipe-B-eDP-1 [484.024283] Dynamic subtest pipe-B-eDP-1: SKIP (1.213s) [484.026050] Subtest fbc-psr2-overlay-plane-move-continuous-exceed-fully-sf: SKIP (2.096s) [485.271651] [111/130] (518s left) kms_scaling_modes (scaling-mode-none) [485.357323] Starting subtest: scaling-mode-none [485.358942] Starting dynamic subtest: pipe-A-eDP-1 [485.449126] Dynamic subtest pipe-A-eDP-1: SKIP (0.091s) [485.457324] Starting dynamic subtest: pipe-B-eDP-1 [485.482603] Dynamic subtest pipe-B-eDP-1: SKIP (0.031s) [485.490871] Starting dynamic subtest: pipe-C-eDP-1 [485.512251] Dynamic subtest pipe-C-eDP-1: SKIP (0.029s) [485.517892] Starting dynamic subtest: pipe-D-eDP-1 [485.538397] Dynamic subtest pipe-D-eDP-1: SKIP (0.025s) [485.540057] Subtest scaling-mode-none: SKIP (0.181s) [485.638300] [112/130] (518s left) kms_flip (2x-flip-vs-wf_vblank) [485.722353] Starting subtest: 2x-flip-vs-wf_vblank [485.737185] Subtest 2x-flip-vs-wf_vblank: SKIP (0.014s) [485.790488] [113/130] (518s left) kms_psr (psr2-sprite-mmap-gtt) [485.871690] Starting subtest: psr2-sprite-mmap-gtt [485.872865] Starting dynamic subtest: eDP-1 [487.329045] Dynamic subtest eDP-1: SKIP (1.452s) [487.331400] Subtest psr2-sprite-mmap-gtt: SKIP (1.453s) [487.442717] [114/130] (516s left) gem_mmap_gtt (hang-user) [487.487143] Subtest hang-user: SKIP (0.000s) [487.526422] [115/130] (516s left) kms_frontbuffer_tracking (fbc-2p-primscrn-cur-indfb-draw-blt) [487.617424] Starting subtest: fbc-2p-primscrn-cur-indfb-draw-blt [487.619150] Subtest fbc-2p-primscrn-cur-indfb-draw-blt: SKIP (0.000s) [487.692507] [116/130] (516s left) kms_draw_crc (draw-method-mmap-cpu) [487.774456] Starting subtest: draw-method-mmap-cpu [487.775505] Starting dynamic subtest: xrgb8888-untiled [487.971604] Dynamic subtest xrgb8888-untiled: SUCCESS (0.198s) [487.977601] Starting dynamic subtest: rgb565-untiled [488.171292] Dynamic subtest rgb565-untiled: SUCCESS (0.199s) [488.177538] Starting dynamic subtest: xrgb2101010-untiled [488.349239] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.178s) [488.356222] Starting dynamic subtest: xrgb8888-xtiled [488.459972] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.111s) [488.467513] Starting dynamic subtest: rgb565-xtiled [488.583077] Dynamic subtest rgb565-xtiled: SUCCESS (0.123s) [488.588693] Starting dynamic subtest: xrgb2101010-xtiled [488.682659] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.099s) [488.688721] Starting dynamic subtest: xrgb8888-4tiled [488.804493] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.121s) [488.810783] Starting dynamic subtest: rgb565-4tiled [488.915889] Dynamic subtest rgb565-4tiled: SUCCESS (0.111s) [488.923597] Starting dynamic subtest: xrgb2101010-4tiled [489.015506] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.099s) [489.016438] Subtest draw-method-mmap-cpu: SUCCESS (1.243s) [489.097582] [117/130] (515s left) gem_exec_reloc (basic-wc-gtt-noreloc) [489.146527] Subtest basic-wc-gtt-noreloc: SKIP (0.000s) [489.191036] [118/130] (514s left) kms_cursor_legacy (flip-vs-cursor-busy-crc-legacy) [489.301215] Starting subtest: flip-vs-cursor-busy-crc-legacy [490.116165] Subtest flip-vs-cursor-busy-crc-legacy: SUCCESS (0.814s) [491.033863] [119/130] (513s left) syncobj_wait (invalid-signal-bad-pad) [491.081437] Starting subtest: invalid-signal-bad-pad [491.082553] Subtest invalid-signal-bad-pad: SUCCESS (0.000s) [491.145010] [120/130] (513s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-shrfb-msflip-blt) [491.227788] Starting subtest: fbcpsr-2p-primscrn-shrfb-msflip-blt [491.229490] Subtest fbcpsr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [491.300984] [121/130] (512s left) kms_chamelium_hpd (vga-hpd-with-enabled-mode) [491.686744] Subtest vga-hpd-with-enabled-mode: SKIP (0.000s) [492.544745] [122/130] (511s left) gem_exec_reloc (basic-gtt-active) [492.582751] Subtest basic-gtt-active: SKIP (0.000s) [492.641807] [123/130] (511s left) sw_sync (sync_merge_invalid) [492.662460] Starting subtest: sync_merge_invalid [492.692896] Subtest sync_merge_invalid: SUCCESS (0.030s) [492.729085] [124/130] (511s left) kms_display_modes (mst-extended-mode-negative) [492.802661] Starting subtest: mst-extended-mode-negative [492.804727] Subtest mst-extended-mode-negative: SKIP (0.000s) [492.818438] [125/130] (511s left) kms_flip_scaled_crc (flip-64bpp-linear-to-16bpp-linear-upscaling) [492.895619] Starting subtest: flip-64bpp-linear-to-16bpp-linear-upscaling [492.896619] Starting dynamic subtest: pipe-A-default-mode [495.790800] Dynamic subtest pipe-A-default-mode: SUCCESS (2.893s) [495.791792] Subtest flip-64bpp-linear-to-16bpp-linear-upscaling: SUCCESS (2.894s) [496.662792] [126/130] (507s left) gem_ctx_param (set-priority-range) [496.709482] Starting subtest: set-priority-range [496.720029] Subtest set-priority-range: SUCCESS (0.011s) [496.790189] [127/130] (507s left) gem_exec_fair (basic-flow) [496.842631] Subtest basic-flow: SKIP (0.000s) [496.898105] [128/130] (507s left) gem_mmap_gtt (medium-copy) [496.948071] Subtest medium-copy: SKIP (0.000s) [496.989224] [129/130] (507s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc) [497.075621] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc [497.076667] Starting dynamic subtest: pipe-A-eDP-1 [497.085764] Dynamic subtest pipe-A-eDP-1: SKIP (0.000s) [497.092105] Starting dynamic subtest: pipe-B-eDP-1 [497.100550] Dynamic subtest pipe-B-eDP-1: SKIP (0.000s) [497.106215] Starting dynamic subtest: pipe-C-eDP-1 [497.114428] Dynamic subtest pipe-C-eDP-1: SKIP (0.000s) [497.119827] Starting dynamic subtest: pipe-D-eDP-1 [497.128410] Dynamic subtest pipe-D-eDP-1: SKIP (0.000s) [497.130046] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs-cc: SKIP (0.004s) [499.415966] [130/130] (504s left) gem_exec_reloc (basic-wc-noreloc) [499.472246] Subtest basic-wc-noreloc: SKIP (0.000s) [499.511793] Closing watchdogs Done.