[40.323388] Initializing watchdogs [40.323430] /dev/watchdog0 [40.329500] [001/130] (960s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-onoff) [42.349443] Starting subtest: fbc-2p-primscrn-spr-indfb-onoff [42.350974] Subtest fbc-2p-primscrn-spr-indfb-onoff: SKIP (0.000s) [42.431798] [002/130] (958s left) gem_exec_schedule (pi-ringfull) [42.706730] Starting subtest: pi-ringfull [42.708321] Starting dynamic subtest: rcs0 [42.817402] Dynamic subtest rcs0: FAIL (0.082s) [42.822153] Starting dynamic subtest: bcs0 [42.847742] Dynamic subtest bcs0: FAIL (0.023s) [42.852605] Starting dynamic subtest: vcs0 [42.879413] Dynamic subtest vcs0: FAIL (0.021s) [42.884622] Starting dynamic subtest: vcs1 [42.909980] Dynamic subtest vcs1: FAIL (0.020s) [42.914737] Starting dynamic subtest: vecs0 [42.945590] Dynamic subtest vecs0: FAIL (0.021s) [42.951560] Starting dynamic subtest: vecs1 [42.984609] Dynamic subtest vecs1: FAIL (0.021s) [43.002266] Starting dynamic subtest: ccs0 [43.046121] Dynamic subtest ccs0: FAIL (0.021s) [43.047703] Subtest pi-ringfull: FAIL (0.208s) [53.329132] [003/130] (947s left) gem_exec_params (invalid-batch-start-offset) [53.390096] Starting subtest: invalid-batch-start-offset [53.393743] Subtest invalid-batch-start-offset: SUCCESS (0.003s) [53.496326] [004/130] (947s left) kms_frontbuffer_tracking (fbcpsr-farfromfence-mmap-gtt) [55.494611] Starting subtest: fbcpsr-farfromfence-mmap-gtt [55.496420] Subtest fbcpsr-farfromfence-mmap-gtt: SKIP (0.000s) [55.563798] [005/130] (945s left) perf (enable-disable) [56.035688] Starting subtest: enable-disable [56.036641] Starting dynamic subtest: 0-rcs0 [58.198192] Dynamic subtest 0-rcs0: SUCCESS (2.174s) [58.199011] Subtest enable-disable: SUCCESS (2.175s) [58.225038] [006/130] (942s left) kms_setmode (basic) [58.319773] Starting subtest: basic [58.434904] Starting dynamic subtest: pipe-A-HDMI-A-2 [60.582706] Dynamic subtest pipe-A-HDMI-A-2: FAIL (2.111s) [60.612243] Starting dynamic subtest: pipe-B-HDMI-A-2 [62.763565] Dynamic subtest pipe-B-HDMI-A-2: FAIL (2.121s) [62.765102] Subtest basic: FAIL (4.449s) [62.903184] [007/130] (937s left) kms_atomic_interruptible (universal-setplane-primary) [64.930629] Starting subtest: universal-setplane-primary [64.932438] Starting dynamic subtest: pipe-A-HDMI-A-2 [71.119954] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (6.196s) [71.120739] Subtest universal-setplane-primary: SUCCESS (6.197s) [71.226580] [008/130] (929s left) api_intel_bb (reset-bb) [71.319243] Starting subtest: reset-bb [71.338842] Subtest reset-bb: SUCCESS (0.020s) [71.456407] [009/130] (929s left) gem_lmem_swapping (random-engines) [77.068828] Starting subtest: random-engines [77.069670] Starting dynamic subtest: lmem0 [77.688050] Dynamic subtest lmem0: SUCCESS (0.619s) [77.688887] Subtest random-engines: SUCCESS (0.619s) [79.293900] [010/130] (921s left) kms_frontbuffer_tracking (psr-2p-primscrn-shrfb-msflip-blt) [84.722238] Starting subtest: psr-2p-primscrn-shrfb-msflip-blt [84.723959] Subtest psr-2p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [84.806813] [011/130] (915s left) kms_cursor_legacy (short-flip-after-cursor-atomic-transitions-varying-size) [86.867138] Starting subtest: short-flip-after-cursor-atomic-transitions-varying-size [87.897077] Subtest short-flip-after-cursor-atomic-transitions-varying-size: SUCCESS (1.029s) [88.059461] [012/130] (912s left) gem_mmap_wc (write-prefaulted) [88.122797] Subtest write-prefaulted: SKIP (0.000s) [88.150343] [013/130] (912s left) gem_exec_reloc (basic-wc-read-noreloc) [88.202599] Subtest basic-wc-read-noreloc: SKIP (0.000s) [88.253992] [014/130] (912s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc) [90.267336] Starting subtest: fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc [90.269075] Subtest fbc-2p-scndscrn-pri-shrfb-draw-mmap-wc: SKIP (0.000s) [90.366204] [015/130] (910s left) kms_draw_crc (draw-method-mmap-cpu) [92.446779] Starting subtest: draw-method-mmap-cpu [92.448089] Starting dynamic subtest: xrgb8888-untiled [92.793999] Dynamic subtest xrgb8888-untiled: SUCCESS (0.346s) [92.818887] Starting dynamic subtest: rgb565-untiled [93.127188] Dynamic subtest rgb565-untiled: SUCCESS (0.333s) [93.152939] Starting dynamic subtest: xrgb2101010-untiled [93.426205] Dynamic subtest xrgb2101010-untiled: SUCCESS (0.299s) [93.432819] Starting dynamic subtest: xrgb8888-xtiled [93.578241] Dynamic subtest xrgb8888-xtiled: SUCCESS (0.151s) [93.586139] Starting dynamic subtest: rgb565-xtiled [93.726100] Dynamic subtest rgb565-xtiled: SUCCESS (0.148s) [93.734020] Starting dynamic subtest: xrgb2101010-xtiled [93.877453] Dynamic subtest xrgb2101010-xtiled: SUCCESS (0.150s) [93.903795] Starting dynamic subtest: xrgb8888-4tiled [94.077613] Dynamic subtest xrgb8888-4tiled: SUCCESS (0.200s) [94.086412] Starting dynamic subtest: rgb565-4tiled [94.227640] Dynamic subtest rgb565-4tiled: SUCCESS (0.150s) [94.236293] Starting dynamic subtest: xrgb2101010-4tiled [94.376311] Dynamic subtest xrgb2101010-4tiled: SUCCESS (0.149s) [94.377174] Subtest draw-method-mmap-cpu: SUCCESS (1.930s) [94.475790] [016/130] (906s left) kms_sequence (queue-busy) [96.463016] Starting subtest: queue-busy [96.463967] Starting dynamic subtest: pipe-A-HDMI-A-2 [98.949263] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (2.485s) [98.955455] Starting dynamic subtest: pipe-B-HDMI-A-2 [101.338384] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (2.388s) [101.344772] Starting dynamic subtest: pipe-C-HDMI-A-2 [103.727432] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (2.388s) [103.733913] Starting dynamic subtest: pipe-D-HDMI-A-2 [106.123241] Dynamic subtest pipe-D-HDMI-A-2: SUCCESS (2.394s) [106.124617] Subtest queue-busy: SUCCESS (9.659s) [106.245433] [017/130] (894s left) kms_cursor_crc (cursor-random-max-size) [108.446959] Starting subtest: cursor-random-max-size [108.449723] Subtest cursor-random-max-size: SKIP (0.000s) [108.556270] [018/130] (892s left) syncobj_timeline (multi-wait-for-submit-available-signaled) [108.634973] Starting subtest: multi-wait-for-submit-available-signaled [108.635963] Subtest multi-wait-for-submit-available-signaled: SUCCESS (0.000s) [108.711558] [019/130] (892s left) kms_plane_scaling (planes-downscale-factor-0-25-unity-scaling) [110.709088] Starting subtest: planes-downscale-factor-0-25-unity-scaling [110.710303] Starting dynamic subtest: pipe-A [111.113965] Dynamic subtest pipe-A: SKIP (0.403s) [111.119866] Starting dynamic subtest: pipe-B [111.712522] Dynamic subtest pipe-B: SKIP (0.598s) [111.718523] Starting dynamic subtest: pipe-C [112.308826] Dynamic subtest pipe-C: SKIP (0.596s) [112.314793] Starting dynamic subtest: pipe-D [112.908790] Dynamic subtest pipe-D: SKIP (0.600s) [112.910556] Subtest planes-downscale-factor-0-25-unity-scaling: SKIP (2.198s) [112.973933] [020/130] (887s left) kms_psr2_sf (pr-overlay-plane-move-continuous-exceed-sf) [114.978402] Subtest pr-overlay-plane-move-continuous-exceed-sf: SKIP (0.000s) [115.002431] [021/130] (885s left) kms_cursor_crc (cursor-rapid-movement-64x64) [117.069123] Starting subtest: cursor-rapid-movement-64x64 [117.073178] Starting dynamic subtest: pipe-A-HDMI-A-2 [117.465875] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.393s) [117.495287] Starting dynamic subtest: pipe-D-HDMI-A-2 [117.763582] Dynamic subtest pipe-D-HDMI-A-2: SUCCESS (0.274s) [117.764476] Subtest cursor-rapid-movement-64x64: SUCCESS (0.694s) [118.061746] [022/130] (882s left) gem_exec_whisper (basic-forked) [118.132804] Starting subtest: basic-forked [122.911342] Subtest basic-forked: SUCCESS (4.776s) [123.100901] [023/130] (877s left) kms_frontbuffer_tracking (fbc-suspend) [125.100776] Starting subtest: fbc-suspend [167.339090] Subtest fbc-suspend: SUCCESS (10.431s) [167.499870] [024/130] (865s left) kms_frontbuffer_tracking (psr-2p-primscrn-indfb-pgflip-blt) [169.490448] Starting subtest: psr-2p-primscrn-indfb-pgflip-blt [169.492443] Subtest psr-2p-primscrn-indfb-pgflip-blt: SKIP (0.000s) [169.581473] [025/130] (863s left) gem_wait (write-busy) [169.712152] Starting subtest: write-busy [169.713165] Starting dynamic subtest: all-engines [170.307800] Dynamic subtest all-engines: SUCCESS (0.595s) [170.315956] Starting dynamic subtest: rcs0 [170.848088] Dynamic subtest rcs0: SUCCESS (0.540s) [170.856286] Starting dynamic subtest: bcs0 [171.389010] Dynamic subtest bcs0: SUCCESS (0.540s) [171.397244] Starting dynamic subtest: vcs0 [171.930106] Dynamic subtest vcs0: SUCCESS (0.541s) [171.938439] Starting dynamic subtest: vcs1 [172.469701] Dynamic subtest vcs1: SUCCESS (0.539s) [172.478036] Starting dynamic subtest: vecs0 [173.010058] Dynamic subtest vecs0: SUCCESS (0.540s) [173.018261] Starting dynamic subtest: vecs1 [173.549888] Dynamic subtest vecs1: SUCCESS (0.539s) [173.558210] Starting dynamic subtest: ccs0 [174.089952] Dynamic subtest ccs0: SUCCESS (0.540s) [174.090845] Subtest write-busy: SUCCESS (4.378s) [174.306056] [026/130] (858s left) kms_invalid_mode (clock-too-high) [176.310659] Starting subtest: clock-too-high [176.311454] Starting dynamic subtest: pipe-A-HDMI-A-2 [176.360852] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.050s) [176.371070] Starting dynamic subtest: pipe-B-HDMI-A-2 [176.372678] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.001s) [176.382250] Starting dynamic subtest: pipe-C-HDMI-A-2 [176.384165] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.001s) [176.393805] Starting dynamic subtest: pipe-D-HDMI-A-2 [176.395561] Dynamic subtest pipe-D-HDMI-A-2: SUCCESS (0.001s) [176.396384] Subtest clock-too-high: SUCCESS (0.057s) [176.494148] [027/130] (856s left) kms_vblank (wait-forked-busy-hang) [178.552918] Starting subtest: wait-forked-busy-hang [178.555020] Starting dynamic subtest: pipe-A-HDMI-A-2 [204.705487] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (26.150s) [204.714912] Starting dynamic subtest: pipe-D-HDMI-A-2 [229.716133] Dynamic subtest pipe-D-HDMI-A-2: SUCCESS (25.007s) [229.718137] Subtest wait-forked-busy-hang: SUCCESS (51.162s) [229.839031] [028/130] (802s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-spr-indfb-draw-blt) [231.834238] Starting subtest: fbcpsr-1p-primscrn-spr-indfb-draw-blt [231.835979] Subtest fbcpsr-1p-primscrn-spr-indfb-draw-blt: SKIP (0.000s) [231.912626] [029/130] (800s left) kms_ccs (crc-primary-rotation-180-4-tiled-dg2-rc-ccs) [233.970956] Starting subtest: crc-primary-rotation-180-4-tiled-dg2-rc-ccs [233.971936] Starting dynamic subtest: pipe-A-HDMI-A-2 [237.428446] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (3.452s) [237.440507] Starting dynamic subtest: pipe-B-HDMI-A-2 [238.105232] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.672s) [238.118027] Starting dynamic subtest: pipe-C-HDMI-A-2 [238.765035] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.655s) [238.777196] Starting dynamic subtest: pipe-D-HDMI-A-2 [239.423910] Dynamic subtest pipe-D-HDMI-A-2: SUCCESS (0.653s) [239.425887] Subtest crc-primary-rotation-180-4-tiled-dg2-rc-ccs: SUCCESS (5.447s) [239.627992] [030/130] (793s left) gen7_exec_parse (cmd-crossing-page) [239.688187] Subtest cmd-crossing-page: SKIP (0.000s) [239.732000] [031/130] (793s left) gem_create (create-massive) [239.788845] Starting subtest: create-massive [239.789795] Subtest create-massive: SUCCESS (0.000s) [239.996666] [032/130] (792s left) kms_ccs (crc-sprite-planes-basic-4-tiled-lnl-ccs) [242.008971] Starting subtest: crc-sprite-planes-basic-4-tiled-lnl-ccs [242.010844] Subtest crc-sprite-planes-basic-4-tiled-lnl-ccs: SKIP (0.000s) [242.142871] [033/130] (790s left) kms_psr (fbc-psr2-primary-blt) [244.187206] Subtest fbc-psr2-primary-blt: SKIP (0.000s) [244.216462] [034/130] (788s left) kms_lease (cursor-implicit-plane) [246.206951] Starting subtest: cursor-implicit-plane [246.207921] Starting dynamic subtest: pipe-A-HDMI-A-2 [246.529266] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.321s) [246.559808] Starting dynamic subtest: pipe-B-HDMI-A-2 [246.782997] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.248s) [246.794470] Starting dynamic subtest: pipe-C-HDMI-A-2 [247.010855] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.226s) [247.019409] Starting dynamic subtest: pipe-D-HDMI-A-2 [247.222005] Dynamic subtest pipe-D-HDMI-A-2: SUCCESS (0.210s) [247.222888] Subtest cursor-implicit-plane: SUCCESS (1.015s) [247.372469] [035/130] (785s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-fullscreen) [249.384935] Starting subtest: fbc-2p-primscrn-spr-indfb-fullscreen [249.386462] Subtest fbc-2p-primscrn-spr-indfb-fullscreen: SKIP (0.000s) [249.456053] [036/130] (783s left) kms_prop_blob (blob-multiple) [249.575132] Starting subtest: blob-multiple [249.578603] Subtest blob-multiple: SUCCESS (0.003s) [249.702152] [037/130] (783s left) i915_pm_rps (thresholds-idle-park) [249.784906] Starting subtest: thresholds-idle-park [249.787207] Subtest thresholds-idle-park: SKIP (0.001s) [249.878642] [038/130] (783s left) kms_frontbuffer_tracking (fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt) [251.884905] Starting subtest: fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt [251.886921] Subtest fbc-2p-scndscrn-cur-indfb-draw-mmap-gtt: SKIP (0.000s) [251.958964] [039/130] (781s left) kms_frontbuffer_tracking (fbc-2p-primscrn-spr-indfb-move) [254.032598] Starting subtest: fbc-2p-primscrn-spr-indfb-move [254.034060] Subtest fbc-2p-primscrn-spr-indfb-move: SKIP (0.000s) [254.128746] [040/130] (778s left) kms_big_fb (4-tiled-max-hw-stride-64bpp-rotate-180-hflip) [256.202054] Starting subtest: 4-tiled-max-hw-stride-64bpp-rotate-180-hflip [257.402487] Subtest 4-tiled-max-hw-stride-64bpp-rotate-180-hflip: SUCCESS (1.200s) [257.540237] [041/130] (775s left) kms_frontbuffer_tracking (fbcpsr-1p-primscrn-cur-indfb-draw-render) [259.538838] Starting subtest: fbcpsr-1p-primscrn-cur-indfb-draw-render [259.540933] Subtest fbcpsr-1p-primscrn-cur-indfb-draw-render: SKIP (0.000s) [259.627377] [042/130] (773s left) gem_ctx_persistence (legacy-engines-hostile-preempt) [259.756066] Starting subtest: legacy-engines-hostile-preempt [259.757063] Starting dynamic subtest: render [259.889153] Dynamic subtest render: SUCCESS (0.132s) [259.901804] Starting dynamic subtest: bsd1 [259.933349] Dynamic subtest bsd1: SUCCESS (0.043s) [259.966154] Starting dynamic subtest: bsd2 [260.002461] Dynamic subtest bsd2: SUCCESS (0.067s) [260.029166] Starting dynamic subtest: blt [260.080953] Dynamic subtest blt: SUCCESS (0.076s) [260.116480] Starting dynamic subtest: vebox [260.154635] Dynamic subtest vebox: SUCCESS (0.071s) [260.156251] Subtest legacy-engines-hostile-preempt: SUCCESS (0.399s) [260.289442] [043/130] (772s left) kms_flip (2x-flip-vs-dpms) [262.296816] Starting subtest: 2x-flip-vs-dpms [262.313217] Subtest 2x-flip-vs-dpms: SKIP (0.016s) [262.388305] [044/130] (770s left) kms_big_fb (4-tiled-32bpp-rotate-0) [264.440078] Starting subtest: 4-tiled-32bpp-rotate-0 [265.919315] Subtest 4-tiled-32bpp-rotate-0: SUCCESS (1.479s) [266.073351] [045/130] (767s left) syncobj_wait (reset-during-wait-for-submit) [266.126000] Starting subtest: reset-during-wait-for-submit [266.228321] Subtest reset-during-wait-for-submit: SUCCESS (0.102s) [266.309458] [046/130] (766s left) gem_exec_whisper (basic-contexts-forked-all) [266.381236] Starting subtest: basic-contexts-forked-all [288.623381] Subtest basic-contexts-forked-all: SUCCESS (22.239s) [288.787569] [047/130] (744s left) gem_exec_schedule (smoketest) [288.969825] Starting subtest: smoketest [288.970635] Starting dynamic subtest: rcs0 [294.407362] Dynamic subtest rcs0: SUCCESS (5.432s) [294.415585] Starting dynamic subtest: bcs0 [299.838582] Dynamic subtest bcs0: SUCCESS (5.431s) [299.847007] Starting dynamic subtest: vcs0 [305.270678] Dynamic subtest vcs0: SUCCESS (5.431s) [305.279772] Starting dynamic subtest: vcs1 [310.708418] Dynamic subtest vcs1: SUCCESS (5.437s) [310.718376] Starting dynamic subtest: vecs0 [316.143597] Dynamic subtest vecs0: SUCCESS (5.435s) [316.151899] Starting dynamic subtest: vecs1 [321.580225] Dynamic subtest vecs1: SUCCESS (5.436s) [321.589411] Starting dynamic subtest: ccs0 [327.009532] Dynamic subtest ccs0: SUCCESS (5.429s) [327.010282] Subtest smoketest: SUCCESS (38.035s) [327.253575] [048/130] (706s left) kms_frontbuffer_tracking (fbcpsr-rgb565-draw-mmap-gtt) [329.274717] Starting subtest: fbcpsr-rgb565-draw-mmap-gtt [329.276831] Subtest fbcpsr-rgb565-draw-mmap-gtt: SKIP (0.000s) [329.362431] [049/130] (703s left) kms_cursor_legacy (modeset-atomic-cursor-hotspot) [331.427191] Starting subtest: modeset-atomic-cursor-hotspot [331.429020] Subtest modeset-atomic-cursor-hotspot: SKIP (0.000s) [331.491476] [050/130] (701s left) kms_properties (plane-properties-atomic) [333.497965] Starting subtest: plane-properties-atomic [333.498880] Starting dynamic subtest: pipe-A-HDMI-A-2 [333.928014] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.429s) [333.937900] Starting dynamic subtest: pipe-B-HDMI-A-2 [334.372199] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.443s) [334.382308] Starting dynamic subtest: pipe-C-HDMI-A-2 [334.796384] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.423s) [334.804802] Starting dynamic subtest: pipe-D-HDMI-A-2 [335.239071] Dynamic subtest pipe-D-HDMI-A-2: SUCCESS (0.441s) [335.240081] Subtest plane-properties-atomic: SUCCESS (1.741s) [335.385308] [051/130] (697s left) syncobj_wait (wait-for-submit-snapshot) [335.444733] Starting subtest: wait-for-submit-snapshot [335.527940] Subtest wait-for-submit-snapshot: SUCCESS (0.082s) [335.626208] [052/130] (697s left) kms_psr (pr-dpms) [337.642574] Subtest pr-dpms: SKIP (0.000s) [337.709389] [053/130] (695s left) prime_vgem (wait) [337.842605] Starting subtest: wait [337.843718] Starting dynamic subtest: rcs0 [337.904219] Dynamic subtest rcs0: SUCCESS (0.061s) [337.912590] Starting dynamic subtest: bcs0 [337.947050] Dynamic subtest bcs0: SUCCESS (0.042s) [337.956327] Starting dynamic subtest: vcs0 [337.990091] Dynamic subtest vcs0: SUCCESS (0.042s) [338.000051] Starting dynamic subtest: vcs1 [338.032080] Dynamic subtest vcs1: SUCCESS (0.041s) [338.044122] Starting dynamic subtest: vecs0 [338.072435] Dynamic subtest vecs0: SUCCESS (0.039s) [338.092587] Starting dynamic subtest: vecs1 [338.114050] Dynamic subtest vecs1: SUCCESS (0.040s) [338.134113] Starting dynamic subtest: ccs0 [338.154689] Dynamic subtest ccs0: SUCCESS (0.040s) [338.157737] Subtest wait: SUCCESS (0.312s) [338.292512] [054/130] (695s left) syncobj_wait (single-wait-for-submit-signaled) [338.369944] Starting subtest: single-wait-for-submit-signaled [338.371003] Subtest single-wait-for-submit-signaled: SUCCESS (0.000s) [338.508995] [055/130] (695s left) perf (unprivileged-single-ctx-counters) [338.836135] Starting subtest: unprivileged-single-ctx-counters [338.837925] Subtest unprivileged-single-ctx-counters: SKIP (0.000s) [338.866428] [056/130] (694s left) kms_color (ctm-blue-to-red) [340.904981] Starting subtest: ctm-blue-to-red [340.905896] Starting dynamic subtest: pipe-A-HDMI-A-2 [341.526689] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.621s) [341.538014] Starting dynamic subtest: pipe-B-HDMI-A-2 [341.988980] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.461s) [341.997264] Starting dynamic subtest: pipe-C-HDMI-A-2 [342.495095] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.506s) [342.503288] Starting dynamic subtest: pipe-D-HDMI-A-2 [342.972439] Dynamic subtest pipe-D-HDMI-A-2: SUCCESS (0.477s) [342.973394] Subtest ctm-blue-to-red: SUCCESS (2.067s) [343.159685] [057/130] (690s left) syncobj_timeline (invalid-query-one-illegal-handle) [343.287001] Starting subtest: invalid-query-one-illegal-handle [343.288110] Subtest invalid-query-one-illegal-handle: SUCCESS (0.000s) [343.387499] [058/130] (690s left) kms_frontbuffer_tracking (fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt) [345.439132] Starting subtest: fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt [345.440736] Subtest fbcpsr-2p-primscrn-pri-shrfb-draw-mmap-gtt: SKIP (0.000s) [345.516306] [059/130] (688s left) prime_udl [345.703155] [060/130] (687s left) kms_ccs (bad-rotation-90-4-tiled-mtl-rc-ccs) [347.727951] Starting subtest: bad-rotation-90-4-tiled-mtl-rc-ccs [347.730098] Starting dynamic subtest: pipe-A-HDMI-A-2 [347.738975] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s) [347.747410] Starting dynamic subtest: pipe-B-HDMI-A-2 [347.756210] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [347.764189] Starting dynamic subtest: pipe-C-HDMI-A-2 [347.773751] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s) [347.781918] Starting dynamic subtest: pipe-D-HDMI-A-2 [347.793152] Dynamic subtest pipe-D-HDMI-A-2: SKIP (0.000s) [347.794869] Subtest bad-rotation-90-4-tiled-mtl-rc-ccs: SKIP (0.005s) [347.974056] [061/130] (685s left) gem_render_copy (x-tiled-to-vebox-yf-tiled) [348.105273] Starting subtest: x-tiled-to-vebox-yf-tiled [348.107493] Subtest x-tiled-to-vebox-yf-tiled: SKIP (0.000s) [348.314685] [062/130] (685s left) kms_chamelium_hpd (dp-hpd-fast) [350.401729] Subtest dp-hpd-fast: SKIP (0.000s) [350.463657] [063/130] (683s left) prime_mmap (test_dup) [350.583865] Starting subtest: test_dup [350.584791] Starting dynamic subtest: test_dup-smem [350.616402] Dynamic subtest test_dup-smem: SUCCESS (0.033s) [350.624689] Starting dynamic subtest: test_dup-lmem0 [350.761078] Dynamic subtest test_dup-lmem0: SUCCESS (0.135s) [350.762384] Subtest test_dup: SUCCESS (0.169s) [350.816434] [064/130] (682s left) gem_exec_params (sol-reset-invalid) [350.911328] Starting subtest: sol-reset-invalid [350.913592] Subtest sol-reset-invalid: SUCCESS (0.001s) [351.009029] [065/130] (682s left) kms_psr2_sf (fbc-psr2-cursor-plane-move-continuous-exceed-sf) [353.042938] Subtest fbc-psr2-cursor-plane-move-continuous-exceed-sf: SKIP (0.000s) [353.061067] [066/130] (680s left) gem_render_copy (y-tiled-to-vebox-x-tiled) [353.163186] Starting subtest: y-tiled-to-vebox-x-tiled [353.165452] Subtest y-tiled-to-vebox-x-tiled: SKIP (0.000s) [353.371141] [067/130] (680s left) gem_mmap_gtt (medium-copy-xy) [353.490088] Subtest medium-copy-XY: SKIP (0.000s) [353.527247] [068/130] (680s left) i915_query (query-topology-unsupported) [353.613146] Starting subtest: query-topology-unsupported [353.615362] Subtest query-topology-unsupported: SKIP (0.000s) [353.689030] [069/130] (680s left) perf (non-zero-reason) [353.912341] Starting subtest: non-zero-reason [353.913309] Starting dynamic subtest: 0-rcs0 [356.968311] Dynamic subtest 0-rcs0: FAIL (2.951s) [356.969945] Subtest non-zero-reason: FAIL (2.952s) [357.005446] [070/130] (676s left) kms_big_fb (y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip) [359.053073] Starting subtest: y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip [359.055008] Subtest y-tiled-max-hw-stride-32bpp-rotate-180-hflip-async-flip: SKIP (0.000s) [359.121842] [071/130] (674s left) gem_ctx_sseu (invalid-args) [359.276922] Subtest invalid-args: SKIP (0.000s) [359.333217] [072/130] (674s left) gem_exec_fence (long-history) [359.789559] Starting subtest: long-history [366.516151] Subtest long-history: SUCCESS (6.727s) [367.604281] [073/130] (666s left) kms_big_fb (x-tiled-8bpp-rotate-90) [369.623780] Starting subtest: x-tiled-8bpp-rotate-90 [369.667962] Subtest x-tiled-8bpp-rotate-90: SKIP (0.043s) [369.823279] [074/130] (664s left) gem_exec_balancer (parallel-contexts) [370.141887] Starting subtest: parallel-contexts [374.195963] Subtest parallel-contexts: SUCCESS (4.054s) [374.293662] [075/130] (659s left) sw_sync (sync_busy) [374.390188] Starting subtest: sync_busy [374.391048] Subtest sync_busy: SUCCESS (0.001s) [374.434663] [076/130] (659s left) syncobj_timeline (wait-any-interrupted) [374.521897] Starting subtest: wait-any-interrupted [374.623110] Subtest wait-any-interrupted: SUCCESS (0.101s) [374.719640] [077/130] (659s left) kms_psr (fbc-psr-primary-mmap-cpu) [376.766825] Subtest fbc-psr-primary-mmap-cpu: SKIP (0.000s) [376.796273] [078/130] (657s left) gem_sync (basic-all) [376.908785] Starting subtest: basic-all [380.357502] Subtest basic-all: SUCCESS (3.450s) [380.550266] [079/130] (653s left) syncobj_timeline (host-signal-points) [380.635681] Starting subtest: host-signal-points [380.639516] Subtest host-signal-points: SUCCESS (0.004s) [380.702928] [080/130] (653s left) kms_frontbuffer_tracking (psr-1p-primscrn-shrfb-msflip-blt) [382.732623] Starting subtest: psr-1p-primscrn-shrfb-msflip-blt [382.734613] Subtest psr-1p-primscrn-shrfb-msflip-blt: SKIP (0.000s) [382.828325] [081/130] (651s left) api_intel_bb (blit-reloc-keep-cache) [382.996871] Starting subtest: blit-reloc-keep-cache [382.998553] Subtest blit-reloc-keep-cache: SKIP (0.000s) [383.107219] [082/130] (651s left) vgem_basic (unload) [383.216200] Starting subtest: unload [412.375396] Subtest unload: SUCCESS (29.158s) [412.505127] [083/130] (621s left) gem_mmap_wc (write-read-distinct) [412.587453] Subtest write-read-distinct: SKIP (0.000s) [412.630656] [084/130] (621s left) kms_frontbuffer_tracking (fbcpsr-rgb101010-draw-mmap-wc) [414.660878] Starting subtest: fbcpsr-rgb101010-draw-mmap-wc [414.662921] Subtest fbcpsr-rgb101010-draw-mmap-wc: SKIP (0.000s) [414.746579] [085/130] (619s left) syncobj_eventfd (timeline-wait) [414.899025] Starting subtest: timeline-wait [414.909537] Subtest timeline-wait: SUCCESS (0.011s) [414.974972] [086/130] (619s left) gem_request_retire (retire-vma-not-inactive) [415.068142] Starting subtest: retire-vma-not-inactive [415.141931] Subtest retire-vma-not-inactive: SUCCESS (0.072s) [415.296364] [087/130] (619s left) gem_bad_reloc (negative-reloc-lut) [415.385024] Subtest negative-reloc-lut: SKIP (0.000s) [415.413333] [088/130] (618s left) gem_softpin (softpin) [415.506748] Starting subtest: softpin [447.915698] Subtest softpin: SUCCESS (32.407s) [448.006583] [089/130] (586s left) kms_big_fb (yf-tiled-64bpp-rotate-270) [450.037113] Starting subtest: yf-tiled-64bpp-rotate-270 [450.039171] Subtest yf-tiled-64bpp-rotate-270: SKIP (0.000s) [450.143269] [090/130] (584s left) sw_sync (sync_busy_fork_unixsocket) [450.293401] Starting subtest: sync_busy_fork_unixsocket [450.298887] Subtest sync_busy_fork_unixsocket: SUCCESS (0.006s) [450.337104] [091/130] (584s left) kms_cursor_crc (cursor-offscreen-max-size) [452.575911] Starting subtest: cursor-offscreen-max-size [452.578540] Subtest cursor-offscreen-max-size: SKIP (0.000s) [452.723151] [092/130] (581s left) i915_pciid [452.915648] [093/130] (581s left) kms_psr2_su (page_flip-p010) [453.040684] Subtest page_flip-P010: SKIP (0.000s) [453.082024] [094/130] (581s left) kms_flip_scaled_crc (flip-32bpp-ytileccs-to-64bpp-ytile-upscaling) [455.114020] Starting subtest: flip-32bpp-ytileccs-to-64bpp-ytile-upscaling [455.116069] Starting dynamic subtest: pipe-A-valid-mode [455.161776] Dynamic subtest pipe-A-valid-mode: SKIP (0.045s) [455.163628] Subtest flip-32bpp-ytileccs-to-64bpp-ytile-upscaling: SKIP (0.047s) [455.263894] [095/130] (579s left) kms_flip_scaled_crc (flip-64bpp-4tile-to-32bpp-4tile-upscaling) [457.348999] Starting subtest: flip-64bpp-4tile-to-32bpp-4tile-upscaling [457.351018] Starting dynamic subtest: pipe-A-valid-mode [458.194785] Dynamic subtest pipe-A-valid-mode: SUCCESS (0.843s) [458.195700] Subtest flip-64bpp-4tile-to-32bpp-4tile-upscaling: SUCCESS (0.845s) [458.358160] [096/130] (576s left) kms_properties (plane-properties-legacy) [460.390944] Starting subtest: plane-properties-legacy [460.391841] Starting dynamic subtest: pipe-A-HDMI-A-2 [460.862265] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (0.470s) [460.872878] Starting dynamic subtest: pipe-B-HDMI-A-2 [461.286870] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (0.423s) [461.295652] Starting dynamic subtest: pipe-C-HDMI-A-2 [461.710330] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (0.422s) [461.718736] Starting dynamic subtest: pipe-D-HDMI-A-2 [462.094756] Dynamic subtest pipe-D-HDMI-A-2: SUCCESS (0.384s) [462.095567] Subtest plane-properties-legacy: SUCCESS (1.704s) [462.258347] [097/130] (572s left) kms_flip (flip-vs-panning) [464.290666] Starting subtest: flip-vs-panning [464.295833] Starting dynamic subtest: A-HDMI-A2 [472.041369] Dynamic subtest A-HDMI-A2: SUCCESS (7.745s) [472.052199] Starting dynamic subtest: B-HDMI-A2 [479.723162] Dynamic subtest B-HDMI-A2: SUCCESS (7.681s) [479.733090] Starting dynamic subtest: C-HDMI-A2 [487.424077] Dynamic subtest C-HDMI-A2: SUCCESS (7.699s) [487.457840] Starting dynamic subtest: D-HDMI-A2 [495.137569] Dynamic subtest D-HDMI-A2: SUCCESS (7.711s) [495.139666] Subtest flip-vs-panning: SUCCESS (30.848s) [495.319203] [098/130] (539s left) kms_pipe_crc_basic (hang-read-crc) [497.349005] Starting subtest: hang-read-crc [497.351026] Starting dynamic subtest: pipe-A-HDMI-A-2 [498.677293] Dynamic subtest pipe-A-HDMI-A-2: SUCCESS (1.326s) [498.688217] Starting dynamic subtest: pipe-B-HDMI-A-2 [499.901098] Dynamic subtest pipe-B-HDMI-A-2: SUCCESS (1.222s) [499.910779] Starting dynamic subtest: pipe-C-HDMI-A-2 [501.041115] Dynamic subtest pipe-C-HDMI-A-2: SUCCESS (1.138s) [501.050262] Starting dynamic subtest: pipe-D-HDMI-A-2 [502.215330] Dynamic subtest pipe-D-HDMI-A-2: SUCCESS (1.174s) [502.216175] Subtest hang-read-crc: SUCCESS (4.866s) [502.412286] [099/130] (532s left) kms_ccs (bad-aux-stride-yf-tiled-ccs) [504.436947] Starting subtest: bad-aux-stride-yf-tiled-ccs [504.438924] Starting dynamic subtest: pipe-A-HDMI-A-2 [504.447142] Dynamic subtest pipe-A-HDMI-A-2: SKIP (0.000s) [504.455996] Starting dynamic subtest: pipe-B-HDMI-A-2 [504.464075] Dynamic subtest pipe-B-HDMI-A-2: SKIP (0.000s) [504.472512] Starting dynamic subtest: pipe-C-HDMI-A-2 [504.482311] Dynamic subtest pipe-C-HDMI-A-2: SKIP (0.000s) [504.491120] Starting dynamic subtest: pipe-D-HDMI-A-2 [504.501922] Dynamic subtest pipe-D-HDMI-A-2: SKIP (0.000s) [504.503472] Subtest bad-aux-stride-yf-tiled-ccs: SKIP (0.005s) [504.741602] [100/130] (529s left) gem_ctx_create (basic-files) [504.849628] Starting subtest: basic-files [506.857506] Subtest basic-files: SUCCESS (2.008s) [507.318623] [101/130] (527s left) kms_big_fb (y-tiled-16bpp-rotate-0) [509.348063] Starting subtest: y-tiled-16bpp-rotate-0 [509.349600] Subtest y-tiled-16bpp-rotate-0: SKIP (0.000s) [509.424743] [102/130] (525s left) gem_userptr_blits (unsync-unmap) [509.598223] Subtest unsync-unmap: SKIP (0.000s) [509.740180] [103/130] (525s left) sysfs_heartbeat_interval (nopreempt) [509.862512] Starting subtest: nopreempt [509.863271] Starting dynamic subtest: vecs0 [514.229775] Dynamic subtest vecs0: SUCCESS (4.366s) [514.238874] Starting dynamic subtest: ccs0 [518.518737] Dynamic subtest ccs0: SUCCESS (4.288s) [518.527783] Starting dynamic subtest: vcs0 [522.736851] Dynamic subtest vcs0: SUCCESS (4.217s) [522.747841] Starting dynamic subtest: bcs0 [526.959927] Dynamic subtest bcs0: SUCCESS (4.222s) [526.969116] Starting dynamic subtest: vecs1 [531.138695] Dynamic subtest vecs1: SUCCESS (4.178s) [531.147925] Starting dynamic subtest: rcs0 [535.440301] Dynamic subtest rcs0: SUCCESS (4.301s) [535.452093] Starting dynamic subtest: vcs1 [539.706483] Dynamic subtest vcs1: SUCCESS (4.265s) [539.707663] Subtest nopreempt: SUCCESS (29.844s) [539.854560] [104/130] (494s left) kms_pm_rpm (fences-dpms) [544.280483] Starting subtest: fences-dpms [544.285469] Subtest fences-dpms: SKIP (0.000s) [545.522610] [105/130] (489s left) device_reset (unbind-reset-rebind) [545.659982] Starting subtest: unbind-reset-rebind [550.457996] Subtest unbind-reset-rebind: SUCCESS (4.797s) [550.530140] [106/130] (484s left) kms_flip (basic-flip-vs-modeset) [552.553711] Starting subtest: basic-flip-vs-modeset [552.558963] Starting dynamic subtest: A-HDMI-A2 [553.438102] Dynamic subtest A-HDMI-A2: SUCCESS (0.878s) [553.449231] Starting dynamic subtest: B-HDMI-A2 [554.115699] Dynamic subtest B-HDMI-A2: SUCCESS (0.677s) [554.124930] Starting dynamic subtest: C-HDMI-A2 [554.808981] Dynamic subtest C-HDMI-A2: SUCCESS (0.692s) [554.818388] Starting dynamic subtest: D-HDMI-A2 [555.495883] Dynamic subtest D-HDMI-A2: SUCCESS (0.686s) [555.496762] Subtest basic-flip-vs-modeset: SUCCESS (2.943s) [555.660591] [107/130] (479s left) kms_psr (psr-no-drrs) [557.663813] Subtest psr-no-drrs: SKIP (0.000s) [557.699674] [108/130] (477s left) syncobj_basic (bad-pad-fd-to-handle) [557.765086] Starting subtest: bad-pad-fd-to-handle [557.766095] Subtest bad-pad-fd-to-handle: SUCCESS (0.000s) [557.893118] [109/130] (477s left) gem_exec_reloc (basic-write-read-active) [557.981090] Subtest basic-write-read-active: SKIP (0.000s) [558.016512] [110/130] (477s left) kms_frontbuffer_tracking (psr-2p-scndscrn-shrfb-plflip-blt) [560.021967] Starting subtest: psr-2p-scndscrn-shrfb-plflip-blt [560.023492] Subtest psr-2p-scndscrn-shrfb-plflip-blt: SKIP (0.000s) [560.093527] [111/130] (474s left) gem_exec_fair (basic-pace-share) [560.224055] Subtest basic-pace-share: SKIP (0.000s) [560.269546] [112/130] (474s left) gem_fence_thrash (bo-write-verify-x) [560.320263] Starting subtest: bo-write-verify-x [560.334264] Subtest bo-write-verify-x: SKIP (0.012s) [560.388861] [113/130] (474s left) kms_frontbuffer_tracking (fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu) [562.400579] Starting subtest: fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu [562.402329] Subtest fbcpsr-2p-scndscrn-cur-indfb-draw-mmap-cpu: SKIP (0.000s) [562.504399] [114/130] (472s left) gem_mmap_gtt (basic-write) [562.625560] Subtest basic-write: SKIP (0.000s) [562.666293] [115/130] (472s left) perf_pmu (render-node-busy-idle) [562.731160] Starting subtest: render-node-busy-idle [562.732050] Starting dynamic subtest: rcs0 [563.451415] Dynamic subtest rcs0: SUCCESS (0.719s) [563.465432] Starting dynamic subtest: bcs0 [564.109522] Dynamic subtest bcs0: SUCCESS (0.656s) [564.123354] Starting dynamic subtest: vcs0 [564.774512] Dynamic subtest vcs0: SUCCESS (0.663s) [564.789984] Starting dynamic subtest: vcs1 [565.443367] Dynamic subtest vcs1: SUCCESS (0.666s) [565.457873] Starting dynamic subtest: vecs0 [566.100518] Dynamic subtest vecs0: SUCCESS (0.655s) [566.115503] Starting dynamic subtest: vecs1 [566.767491] Dynamic subtest vecs1: SUCCESS (0.665s) [566.784694] Starting dynamic subtest: ccs0 [567.428681] Dynamic subtest ccs0: SUCCESS (0.659s) [567.430714] Subtest render-node-busy-idle: SUCCESS (4.698s) [567.495957] [116/130] (467s left) kms_cursor_legacy (2x-cursor-vs-flip-legacy) [569.490091] Starting subtest: 2x-cursor-vs-flip-legacy [569.493122] Subtest 2x-cursor-vs-flip-legacy: SKIP (0.002s) [569.568916] [117/130] (465s left) perf (sysctl-defaults) [569.726972] Starting subtest: sysctl-defaults [569.727914] Subtest sysctl-defaults: SUCCESS (0.000s) [569.985972] [118/130] (465s left) kms_chamelium_color (ctm-negative) [572.020855] Subtest ctm-negative: SKIP (0.000s) [572.050644] [119/130] (463s left) kms_addfb_basic (master-rmfb) [572.145996] Starting subtest: master-rmfb [572.148258] Subtest master-rmfb: SUCCESS (0.001s) [574.168885] [120/130] (461s left) dumb_buffer (map-uaf) [574.347636] Starting subtest: map-uaf [574.360556] Subtest map-uaf: SUCCESS (0.013s) [574.474699] [121/130] (460s left) kms_big_fb (4-tiled-64bpp-rotate-180) [576.530948] Starting subtest: 4-tiled-64bpp-rotate-180 [578.089207] Subtest 4-tiled-64bpp-rotate-180: SUCCESS (1.557s) [578.210478] [122/130] (457s left) gem_mmap_offset (bad-extensions) [578.296904] Starting subtest: bad-extensions [578.297715] Subtest bad-extensions: SUCCESS (0.000s) [578.403602] [123/130] (456s left) kms_frontbuffer_tracking (psr-2p-scndscrn-spr-indfb-onoff) [580.453613] Starting subtest: psr-2p-scndscrn-spr-indfb-onoff [580.455115] Subtest psr-2p-scndscrn-spr-indfb-onoff: SKIP (0.000s) [580.531881] [124/130] (454s left) i915_pm_freq_api (freq-reset) [580.693566] Starting subtest: freq-reset [580.694549] Starting dynamic subtest: gt0 [581.068860] Dynamic subtest gt0: SUCCESS (0.374s) [581.069813] Subtest freq-reset: SUCCESS (0.375s) [581.125236] [125/130] (454s left) gem_ctx_persistence (engines-cleanup) [581.212171] Starting subtest: engines-cleanup [581.213131] Starting dynamic subtest: rcs0 [581.312183] Dynamic subtest rcs0: SUCCESS (0.100s) [581.332133] Starting dynamic subtest: bcs0 [581.353346] Dynamic subtest bcs0: SUCCESS (0.040s) [581.366224] Starting dynamic subtest: vcs0 [581.372801] Dynamic subtest vcs0: SUCCESS (0.018s) [581.382784] Starting dynamic subtest: vcs1 [581.418997] Dynamic subtest vcs1: SUCCESS (0.045s) [581.432364] Starting dynamic subtest: vecs0 [581.434754] Dynamic subtest vecs0: SUCCESS (0.015s) [581.447614] Starting dynamic subtest: vecs1 [581.475084] Dynamic subtest vecs1: SUCCESS (0.039s) [581.489702] Starting dynamic subtest: ccs0 [581.521169] Dynamic subtest ccs0: SUCCESS (0.045s) [581.522408] Subtest engines-cleanup: SUCCESS (0.309s) [581.665203] [126/130] (453s left) kms_plane_scaling (plane-upscale-factor-0-25-with-modifiers) [583.704970] Starting subtest: plane-upscale-factor-0-25-with-modifiers [583.705894] Starting dynamic subtest: pipe-A [584.222234] Dynamic subtest pipe-A: SUCCESS (0.517s) [584.252051] Starting dynamic subtest: pipe-B [584.796945] Dynamic subtest pipe-B: SUCCESS (0.573s) [584.825392] Starting dynamic subtest: pipe-C [585.371342] Dynamic subtest pipe-C: SUCCESS (0.573s) [585.399428] Starting dynamic subtest: pipe-D [585.945231] Dynamic subtest pipe-D: SUCCESS (0.572s) [585.946554] Subtest plane-upscale-factor-0-25-with-modifiers: SUCCESS (2.240s) [586.142150] [127/130] (449s left) kms_scaling_modes (scaling-mode-center) [588.176013] Starting subtest: scaling-mode-center [588.179074] Subtest scaling-mode-center: SKIP (0.001s) [588.241342] [128/130] (447s left) kms_chamelium_audio (dp-audio) [590.394215] Subtest dp-audio: SKIP (0.000s) [590.461036] [129/130] (445s left) kms_plane (plane-position-hole-dpms) [592.525978] Starting subtest: plane-position-hole-dpms [592.898108] Starting dynamic subtest: pipe-A-plane-1 [593.433553] Dynamic subtest pipe-A-plane-1: SUCCESS (0.536s) [593.443454] Starting dynamic subtest: pipe-A-plane-2 [593.919071] Dynamic subtest pipe-A-plane-2: SUCCESS (0.484s) [593.932630] Starting dynamic subtest: pipe-A-plane-3 [594.400231] Dynamic subtest pipe-A-plane-3: SUCCESS (0.481s) [594.412627] Starting dynamic subtest: pipe-A-plane-4 [594.869082] Dynamic subtest pipe-A-plane-4: SUCCESS (0.468s) [594.879784] Starting dynamic subtest: pipe-A-plane-5 [594.886024] Dynamic subtest pipe-A-plane-5: SUCCESS (0.017s) [595.168751] Starting dynamic subtest: pipe-B-plane-1 [595.646113] Dynamic subtest pipe-B-plane-1: SUCCESS (0.488s) [595.656237] Starting dynamic subtest: pipe-B-plane-2 [596.096369] Dynamic subtest pipe-B-plane-2: SUCCESS (0.450s) [596.106395] Starting dynamic subtest: pipe-B-plane-3 [596.547720] Dynamic subtest pipe-B-plane-3: SUCCESS (0.450s) [596.558408] Starting dynamic subtest: pipe-B-plane-4 [596.998744] Dynamic subtest pipe-B-plane-4: SUCCESS (0.451s) [597.008936] Starting dynamic subtest: pipe-B-plane-5 [597.012055] Dynamic subtest pipe-B-plane-5: SUCCESS (0.014s) [597.013033] Subtest plane-position-hole-dpms: SUCCESS (4.486s) [597.214501] [130/130] (438s left) gem_ringfill (basic-all) [597.654945] Starting subtest: basic-all [599.271464] Subtest basic-all: SUCCESS (1.617s) [599.427981] Closing watchdogs Done.