0s autopkgtest [07:02:06]: starting date and time: 2025-11-04 07:02:06+0000 0s autopkgtest [07:02:06]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [07:02:06]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.87k1ub0p/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:golang-yaml.v2 --apt-upgrade golang-github-canonical-go-dqlite --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=golang-yaml.v2/2.4.3-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-ppc64el-3.secgroup --name adt-resolute-ppc64el-golang-github-canonical-go-dqlite-20251104-070205-juju-7f2275-prod-proposed-migration-environment-20-befeff3a-96e3-42b9-8688-69df2cb1d47d --image adt/ubuntu-resolute-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-ppc64el-golang-github-canonical-go-dqlite-20251104-070205-juju-7f2275-prod-proposed-migration-environment-20-befeff3a-96e3-42b9-8688-69df2cb1d47d from image adt/ubuntu-resolute-ppc64el-server-20251104.img (UUID 3b85278a-e278-4751-897d-5e4a1759ee05)... 53s autopkgtest [07:02:59]: testbed dpkg architecture: ppc64el 54s autopkgtest [07:03:00]: testbed apt version: 3.1.11 54s autopkgtest [07:03:00]: @@@@@@@@@@@@@@@@@@@@ test bed setup 54s autopkgtest [07:03:00]: testbed release detected to be: None 55s autopkgtest [07:03:01]: updating testbed package index (apt update) 55s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 55s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 55s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 56s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 56s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [82.2 kB] 56s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1038 kB] 56s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [9848 B] 56s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [23.4 kB] 56s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [123 kB] 56s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted ppc64el Packages [940 B] 56s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [638 kB] 56s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [12.8 kB] 56s Fetched 2016 kB in 1s (1642 kB/s) 57s Reading package lists... 58s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 58s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 58s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 58s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 59s Reading package lists... 59s Reading package lists... 59s Building dependency tree... 59s Reading state information... 59s Calculating upgrade... 59s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 59s autopkgtest [07:03:05]: upgrading testbed (apt dist-upgrade and autopurge) 59s Reading package lists... 60s Building dependency tree... 60s Reading state information... 60s Calculating upgrade... 60s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 60s Reading package lists... 60s Building dependency tree... 60s Reading state information... 60s Solving dependencies... 60s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 63s autopkgtest [07:03:09]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:02:41 UTC 2025 63s autopkgtest [07:03:09]: @@@@@@@@@@@@@@@@@@@@ apt-source golang-github-canonical-go-dqlite 65s Get:1 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (dsc) [2750 B] 65s Get:2 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (tar) [94.6 kB] 65s Get:3 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (diff) [4532 B] 65s gpgv: Signature made Mon Sep 1 19:07:08 2025 UTC 65s gpgv: using RSA key D41A7AD07DF6C5FCA7489F1C29EEE2D6ECF442F9 65s gpgv: issuer "gibmat@debian.org" 65s gpgv: Can't check signature: No public key 65s dpkg-source: warning: cannot verify inline signature for ./golang-github-canonical-go-dqlite_2.0.1-1.dsc: no acceptable signature found 65s autopkgtest [07:03:11]: testing package golang-github-canonical-go-dqlite version 2.0.1-1 66s autopkgtest [07:03:12]: build not needed 66s autopkgtest [07:03:12]: test dh-golang-autopkgtest: preparing testbed 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Solving dependencies... 67s The following NEW packages will be installed: 67s autoconf automake autopoint autotools-dev build-essential cpp cpp-15 67s cpp-15-powerpc64le-linux-gnu cpp-powerpc64le-linux-gnu debhelper debugedit 67s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-15 67s g++-15-powerpc64le-linux-gnu g++-powerpc64le-linux-gnu gcc gcc-15 67s gcc-15-powerpc64le-linux-gnu gcc-powerpc64le-linux-gnu gettext go-dqlite 67s golang-1.25-go golang-1.25-src golang-any golang-github-burntsushi-toml-dev 67s golang-github-canonical-go-dqlite-dev 67s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 67s golang-github-fsnotify-fsnotify-dev golang-github-google-renameio-dev 67s golang-github-hashicorp-hcl-dev golang-github-kr-fs-dev 67s golang-github-magiconair-properties-dev golang-github-mattn-go-runewidth-dev 67s golang-github-mattn-go-sqlite3-dev golang-github-mitchellh-mapstructure-dev 67s golang-github-pelletier-go-toml-dev golang-github-pelletier-go-toml.v2-dev 67s golang-github-peterh-liner-dev golang-github-pkg-errors-dev 67s golang-github-pkg-sftp-dev golang-github-pmezard-go-difflib-dev 67s golang-github-rican7-retry-dev golang-github-rivo-uniseg-dev 67s golang-github-russross-blackfriday-v2-dev 67s golang-github-shurcool-sanitized-anchor-name-dev 67s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 67s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 67s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 67s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 67s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 67s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 67s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 67s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 67s libasan8 libcc1-0 libdebhelper-perl libdqlite-dev libdqlite0 67s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libisl23 libitm1 67s liblsan0 liblz4-dev libmpc3 libquadmath0 libsqlite3-dev libstdc++-15-dev 67s libtool libtsan2 libubsan1 libuv1-dev libxxhash-dev m4 po-debconf 67s tzdata-legacy 67s 0 upgraded, 95 newly installed, 0 to remove and 0 not upgraded. 67s Need to get 122 MB of archives. 67s After this operation, 549 MB of additional disk space will be used. 67s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el m4 ppc64el 1.4.20-2 [236 kB] 67s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el autoconf all 2.72-3.1ubuntu1 [384 kB] 67s Get:3 http://ftpmaster.internal/ubuntu resolute/main ppc64el autotools-dev all 20240727.1 [43.4 kB] 67s Get:4 http://ftpmaster.internal/ubuntu resolute/main ppc64el automake all 1:1.18.1-2 [581 kB] 67s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el autopoint all 0.23.1-2build2 [619 kB] 68s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el libisl23 ppc64el 0.27-1 [882 kB] 68s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el libmpc3 ppc64el 1.3.1-1build3 [62.6 kB] 68s Get:8 http://ftpmaster.internal/ubuntu resolute/main ppc64el cpp-15-powerpc64le-linux-gnu ppc64el 15.2.0-7ubuntu1 [11.4 MB] 68s Get:9 http://ftpmaster.internal/ubuntu resolute/main ppc64el cpp-15 ppc64el 15.2.0-7ubuntu1 [1032 B] 68s Get:10 http://ftpmaster.internal/ubuntu resolute/main ppc64el cpp-powerpc64le-linux-gnu ppc64el 4:15.2.0-4ubuntu1 [5746 B] 68s Get:11 http://ftpmaster.internal/ubuntu resolute/main ppc64el cpp ppc64el 4:15.2.0-4ubuntu1 [22.4 kB] 68s Get:12 http://ftpmaster.internal/ubuntu resolute/main ppc64el libcc1-0 ppc64el 15.2.0-7ubuntu1 [49.0 kB] 68s Get:13 http://ftpmaster.internal/ubuntu resolute/main ppc64el libgomp1 ppc64el 15.2.0-7ubuntu1 [169 kB] 68s Get:14 http://ftpmaster.internal/ubuntu resolute/main ppc64el libitm1 ppc64el 15.2.0-7ubuntu1 [32.3 kB] 68s Get:15 http://ftpmaster.internal/ubuntu resolute/main ppc64el libasan8 ppc64el 15.2.0-7ubuntu1 [3006 kB] 68s Get:16 http://ftpmaster.internal/ubuntu resolute/main ppc64el liblsan0 ppc64el 15.2.0-7ubuntu1 [1374 kB] 68s Get:17 http://ftpmaster.internal/ubuntu resolute/main ppc64el libtsan2 ppc64el 15.2.0-7ubuntu1 [2728 kB] 68s Get:18 http://ftpmaster.internal/ubuntu resolute/main ppc64el libubsan1 ppc64el 15.2.0-7ubuntu1 [1231 kB] 69s Get:19 http://ftpmaster.internal/ubuntu resolute/main ppc64el libquadmath0 ppc64el 15.2.0-7ubuntu1 [160 kB] 69s Get:20 http://ftpmaster.internal/ubuntu resolute/main ppc64el libgcc-15-dev ppc64el 15.2.0-7ubuntu1 [1670 kB] 69s Get:21 http://ftpmaster.internal/ubuntu resolute/main ppc64el gcc-15-powerpc64le-linux-gnu ppc64el 15.2.0-7ubuntu1 [22.4 MB] 70s Get:22 http://ftpmaster.internal/ubuntu resolute/main ppc64el gcc-15 ppc64el 15.2.0-7ubuntu1 [524 kB] 70s Get:23 http://ftpmaster.internal/ubuntu resolute/main ppc64el gcc-powerpc64le-linux-gnu ppc64el 4:15.2.0-4ubuntu1 [1220 B] 70s Get:24 http://ftpmaster.internal/ubuntu resolute/main ppc64el gcc ppc64el 4:15.2.0-4ubuntu1 [5032 B] 70s Get:25 http://ftpmaster.internal/ubuntu resolute/main ppc64el libstdc++-15-dev ppc64el 15.2.0-7ubuntu1 [2744 kB] 70s Get:26 http://ftpmaster.internal/ubuntu resolute/main ppc64el g++-15-powerpc64le-linux-gnu ppc64el 15.2.0-7ubuntu1 [13.0 MB] 72s Get:27 http://ftpmaster.internal/ubuntu resolute/main ppc64el g++-15 ppc64el 15.2.0-7ubuntu1 [23.7 kB] 72s Get:28 http://ftpmaster.internal/ubuntu resolute/main ppc64el g++-powerpc64le-linux-gnu ppc64el 4:15.2.0-4ubuntu1 [970 B] 72s Get:29 http://ftpmaster.internal/ubuntu resolute/main ppc64el g++ ppc64el 4:15.2.0-4ubuntu1 [1092 B] 72s Get:30 http://ftpmaster.internal/ubuntu resolute/main ppc64el build-essential ppc64el 12.12ubuntu1 [5094 B] 72s Get:31 http://ftpmaster.internal/ubuntu resolute/main ppc64el libdebhelper-perl all 13.24.2ubuntu1 [95.7 kB] 72s Get:32 http://ftpmaster.internal/ubuntu resolute/main ppc64el libtool all 2.5.4-4build1 [169 kB] 72s Get:33 http://ftpmaster.internal/ubuntu resolute/main ppc64el dh-autoreconf all 21 [12.5 kB] 72s Get:34 http://ftpmaster.internal/ubuntu resolute/main ppc64el libarchive-zip-perl all 1.68-1 [90.2 kB] 72s Get:35 http://ftpmaster.internal/ubuntu resolute/main ppc64el libfile-stripnondeterminism-perl all 1.15.0-1 [20.5 kB] 72s Get:36 http://ftpmaster.internal/ubuntu resolute/main ppc64el dh-strip-nondeterminism all 1.15.0-1 [5090 B] 72s Get:37 http://ftpmaster.internal/ubuntu resolute/main ppc64el debugedit ppc64el 1:5.2-3 [57.3 kB] 72s Get:38 http://ftpmaster.internal/ubuntu resolute/main ppc64el dwz ppc64el 0.16-2 [142 kB] 72s Get:39 http://ftpmaster.internal/ubuntu resolute/main ppc64el gettext ppc64el 0.23.1-2build2 [1177 kB] 72s Get:40 http://ftpmaster.internal/ubuntu resolute/main ppc64el intltool-debian all 0.35.0+20060710.6 [23.2 kB] 72s Get:41 http://ftpmaster.internal/ubuntu resolute/main ppc64el po-debconf all 1.0.21+nmu1 [233 kB] 72s Get:42 http://ftpmaster.internal/ubuntu resolute/main ppc64el debhelper all 13.24.2ubuntu1 [896 kB] 72s Get:43 http://ftpmaster.internal/ubuntu resolute/universe ppc64el libdqlite0 ppc64el 1.18.2-3 [176 kB] 72s Get:44 http://ftpmaster.internal/ubuntu resolute/universe ppc64el go-dqlite ppc64el 2.0.1-1 [5631 kB] 73s Get:45 http://ftpmaster.internal/ubuntu resolute/main ppc64el golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 76s Get:46 http://ftpmaster.internal/ubuntu resolute/main ppc64el golang-1.25-go ppc64el 1.25.0-0ubuntu1 [16.1 MB] 79s Get:47 http://ftpmaster.internal/ubuntu resolute/main ppc64el golang-src all 2:1.25~1 [5206 B] 79s Get:48 http://ftpmaster.internal/ubuntu resolute/main ppc64el golang-go ppc64el 2:1.25~1 [44.1 kB] 79s Get:49 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-any ppc64el 2:1.25~1 [2840 B] 79s Get:50 http://ftpmaster.internal/ubuntu resolute/main ppc64el libxxhash-dev ppc64el 0.8.3-2 [91.7 kB] 79s Get:51 http://ftpmaster.internal/ubuntu resolute/main ppc64el liblz4-dev ppc64el 1.10.0-4build1 [118 kB] 79s Get:52 http://ftpmaster.internal/ubuntu resolute/main ppc64el libuv1-dev ppc64el 1.51.0-2ubuntu1 [158 kB] 79s Get:53 http://ftpmaster.internal/ubuntu resolute/main ppc64el libsqlite3-dev ppc64el 3.46.1-8 [1053 kB] 79s Get:54 http://ftpmaster.internal/ubuntu resolute/universe ppc64el libdqlite-dev ppc64el 1.18.2-3 [270 kB] 79s Get:55 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-google-renameio-dev all 2.0.0-2 [10.3 kB] 79s Get:56 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-mattn-go-sqlite3-dev ppc64el 1.14.32~ds1-1 [84.6 kB] 80s Get:57 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-rivo-uniseg-dev all 0.4.7-1 [258 kB] 80s Get:58 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-mattn-go-runewidth-dev all 0.0.16-2 [17.6 kB] 80s Get:59 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-peterh-liner-dev all 1.2.2-1 [21.3 kB] 80s Get:60 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-pkg-errors-dev all 0.9.1-3 [13.2 kB] 80s Get:61 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-rican7-retry-dev all 0.3.1-2 [7740 B] 80s Get:62 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-pmezard-go-difflib-dev all 1.0.0-3 [12.4 kB] 80s Get:63 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-shurcool-sanitized-anchor-name-dev all 1.0.0-3 [3590 B] 80s Get:64 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-russross-blackfriday-v2-dev all 2.1.0-1 [79.6 kB] 80s Get:65 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-cpuguy83-go-md2man-v2-dev all 2.0.4-1 [11.0 kB] 80s Get:66 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-spf13-pflag-dev all 1.0.10-1 [50.1 kB] 80s Get:67 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-golang-x-sys-dev all 0.22.0-1 [457 kB] 80s Get:68 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-fsnotify-fsnotify-dev all 1.9.0-1 [57.3 kB] 80s Get:69 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-davecgh-go-spew-dev all 1.1.1-3 [31.1 kB] 80s Get:70 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-hashicorp-hcl-dev all 1.0.0-1.1 [58.9 kB] 80s Get:71 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-magiconair-properties-dev all 1.8.7-1 [27.3 kB] 80s Get:72 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-mitchellh-mapstructure-dev all 1.5.0+git20231216.8508981-1 [28.3 kB] 80s Get:73 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-burntsushi-toml-dev all 1.4.0-1 [119 kB] 80s Get:74 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el golang-gopkg-yaml.v2-dev all 2.4.3-1 [62.7 kB] 80s Get:75 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-pelletier-go-toml-dev all 1.9.5-1build1 [79.6 kB] 80s Get:76 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-pelletier-go-toml.v2-dev all 2.2.4-1 [878 kB] 80s Get:77 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-kr-fs-dev all 0.1.0-2 [6344 B] 80s Get:78 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-golang-x-term-dev all 0.22.0-1 [14.6 kB] 80s Get:79 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-golang-x-text-dev all 0.22.0-1 [4616 kB] 83s Get:80 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-golang-x-crypto-dev all 1:0.25.0-1 [1724 kB] 84s Get:81 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-pkg-sftp-dev all 1.13.9-1 [108 kB] 84s Get:82 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-spf13-afero-dev all 1.15.0-1 [49.8 kB] 84s Get:83 http://ftpmaster.internal/ubuntu resolute/main ppc64el tzdata-legacy all 2025b-5ubuntu1 [99.3 kB] 84s Get:84 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-spf13-cast-dev all 1.10.0-2 [20.5 kB] 84s Get:85 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-stretchr-objx-dev all 0.5.2-1 [26.5 kB] 84s Get:86 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-gopkg-yaml.v3-dev all 3.0.1-3 [76.1 kB] 84s Get:87 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-stretchr-testify-dev all 1.10.0-1 [79.6 kB] 84s Get:88 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-spf13-jwalterweatherman-dev all 1.1.0+really1.1.0-2 [6828 B] 84s Get:89 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-subosito-gotenv-dev all 1.3.0-1 [8808 B] 84s Get:90 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-gopkg-ini.v1-dev all 1.67.0-1 [40.8 kB] 84s Get:91 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-spf13-viper-dev all 1.12.0-1 [71.0 kB] 84s Get:92 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-spf13-cobra-dev all 1.8.1-1 [76.2 kB] 84s Get:93 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-golang-x-sync-dev all 0.7.0-1 [19.5 kB] 84s Get:94 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-canonical-go-dqlite-dev all 2.0.1-1 [71.9 kB] 84s Get:95 http://ftpmaster.internal/ubuntu resolute/main ppc64el dh-golang all 1.63 [24.9 kB] 85s Fetched 122 MB in 18s (6972 kB/s) 85s Selecting previously unselected package m4. 85s (Reading database ... (Reading database ... 5% (Reading database ... 10% (Reading database ... 15% (Reading database ... 20% (Reading database ... 25% (Reading database ... 30% (Reading database ... 35% (Reading database ... 40% (Reading database ... 45% (Reading database ... 50% (Reading database ... 55% (Reading database ... 60% (Reading database ... 65% (Reading database ... 70% (Reading database ... 75% (Reading database ... 80% (Reading database ... 85% (Reading database ... 90% (Reading database ... 95% (Reading database ... 100% (Reading database ... 76579 files and directories currently installed.) 85s Preparing to unpack .../00-m4_1.4.20-2_ppc64el.deb ... 85s Unpacking m4 (1.4.20-2) ... 85s Selecting previously unselected package autoconf. 85s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ... 85s Unpacking autoconf (2.72-3.1ubuntu1) ... 85s Selecting previously unselected package autotools-dev. 85s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ... 85s Unpacking autotools-dev (20240727.1) ... 85s Selecting previously unselected package automake. 85s Preparing to unpack .../03-automake_1%3a1.18.1-2_all.deb ... 85s Unpacking automake (1:1.18.1-2) ... 85s Selecting previously unselected package autopoint. 85s Preparing to unpack .../04-autopoint_0.23.1-2build2_all.deb ... 85s Unpacking autopoint (0.23.1-2build2) ... 85s Selecting previously unselected package libisl23:ppc64el. 85s Preparing to unpack .../05-libisl23_0.27-1_ppc64el.deb ... 85s Unpacking libisl23:ppc64el (0.27-1) ... 85s Selecting previously unselected package libmpc3:ppc64el. 85s Preparing to unpack .../06-libmpc3_1.3.1-1build3_ppc64el.deb ... 85s Unpacking libmpc3:ppc64el (1.3.1-1build3) ... 85s Selecting previously unselected package cpp-15-powerpc64le-linux-gnu. 85s Preparing to unpack .../07-cpp-15-powerpc64le-linux-gnu_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking cpp-15-powerpc64le-linux-gnu (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package cpp-15. 85s Preparing to unpack .../08-cpp-15_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking cpp-15 (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package cpp-powerpc64le-linux-gnu. 85s Preparing to unpack .../09-cpp-powerpc64le-linux-gnu_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 85s Unpacking cpp-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 85s Selecting previously unselected package cpp. 85s Preparing to unpack .../10-cpp_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 85s Unpacking cpp (4:15.2.0-4ubuntu1) ... 85s Selecting previously unselected package libcc1-0:ppc64el. 85s Preparing to unpack .../11-libcc1-0_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking libcc1-0:ppc64el (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package libgomp1:ppc64el. 85s Preparing to unpack .../12-libgomp1_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking libgomp1:ppc64el (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package libitm1:ppc64el. 85s Preparing to unpack .../13-libitm1_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking libitm1:ppc64el (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package libasan8:ppc64el. 85s Preparing to unpack .../14-libasan8_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking libasan8:ppc64el (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package liblsan0:ppc64el. 85s Preparing to unpack .../15-liblsan0_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking liblsan0:ppc64el (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package libtsan2:ppc64el. 85s Preparing to unpack .../16-libtsan2_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking libtsan2:ppc64el (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package libubsan1:ppc64el. 85s Preparing to unpack .../17-libubsan1_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking libubsan1:ppc64el (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package libquadmath0:ppc64el. 85s Preparing to unpack .../18-libquadmath0_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking libquadmath0:ppc64el (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package libgcc-15-dev:ppc64el. 85s Preparing to unpack .../19-libgcc-15-dev_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking libgcc-15-dev:ppc64el (15.2.0-7ubuntu1) ... 85s Selecting previously unselected package gcc-15-powerpc64le-linux-gnu. 85s Preparing to unpack .../20-gcc-15-powerpc64le-linux-gnu_15.2.0-7ubuntu1_ppc64el.deb ... 85s Unpacking gcc-15-powerpc64le-linux-gnu (15.2.0-7ubuntu1) ... 86s Selecting previously unselected package gcc-15. 86s Preparing to unpack .../21-gcc-15_15.2.0-7ubuntu1_ppc64el.deb ... 86s Unpacking gcc-15 (15.2.0-7ubuntu1) ... 86s Selecting previously unselected package gcc-powerpc64le-linux-gnu. 86s Preparing to unpack .../22-gcc-powerpc64le-linux-gnu_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 86s Unpacking gcc-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 86s Selecting previously unselected package gcc. 86s Preparing to unpack .../23-gcc_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 86s Unpacking gcc (4:15.2.0-4ubuntu1) ... 86s Selecting previously unselected package libstdc++-15-dev:ppc64el. 86s Preparing to unpack .../24-libstdc++-15-dev_15.2.0-7ubuntu1_ppc64el.deb ... 86s Unpacking libstdc++-15-dev:ppc64el (15.2.0-7ubuntu1) ... 86s Selecting previously unselected package g++-15-powerpc64le-linux-gnu. 86s Preparing to unpack .../25-g++-15-powerpc64le-linux-gnu_15.2.0-7ubuntu1_ppc64el.deb ... 86s Unpacking g++-15-powerpc64le-linux-gnu (15.2.0-7ubuntu1) ... 86s Selecting previously unselected package g++-15. 86s Preparing to unpack .../26-g++-15_15.2.0-7ubuntu1_ppc64el.deb ... 86s Unpacking g++-15 (15.2.0-7ubuntu1) ... 86s Selecting previously unselected package g++-powerpc64le-linux-gnu. 86s Preparing to unpack .../27-g++-powerpc64le-linux-gnu_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 86s Unpacking g++-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 86s Selecting previously unselected package g++. 86s Preparing to unpack .../28-g++_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 86s Unpacking g++ (4:15.2.0-4ubuntu1) ... 86s Selecting previously unselected package build-essential. 86s Preparing to unpack .../29-build-essential_12.12ubuntu1_ppc64el.deb ... 86s Unpacking build-essential (12.12ubuntu1) ... 86s Selecting previously unselected package libdebhelper-perl. 86s Preparing to unpack .../30-libdebhelper-perl_13.24.2ubuntu1_all.deb ... 86s Unpacking libdebhelper-perl (13.24.2ubuntu1) ... 86s Selecting previously unselected package libtool. 86s Preparing to unpack .../31-libtool_2.5.4-4build1_all.deb ... 86s Unpacking libtool (2.5.4-4build1) ... 86s Selecting previously unselected package dh-autoreconf. 86s Preparing to unpack .../32-dh-autoreconf_21_all.deb ... 86s Unpacking dh-autoreconf (21) ... 86s Selecting previously unselected package libarchive-zip-perl. 86s Preparing to unpack .../33-libarchive-zip-perl_1.68-1_all.deb ... 86s Unpacking libarchive-zip-perl (1.68-1) ... 86s Selecting previously unselected package libfile-stripnondeterminism-perl. 86s Preparing to unpack .../34-libfile-stripnondeterminism-perl_1.15.0-1_all.deb ... 86s Unpacking libfile-stripnondeterminism-perl (1.15.0-1) ... 86s Selecting previously unselected package dh-strip-nondeterminism. 86s Preparing to unpack .../35-dh-strip-nondeterminism_1.15.0-1_all.deb ... 86s Unpacking dh-strip-nondeterminism (1.15.0-1) ... 86s Selecting previously unselected package debugedit. 86s Preparing to unpack .../36-debugedit_1%3a5.2-3_ppc64el.deb ... 86s Unpacking debugedit (1:5.2-3) ... 86s Selecting previously unselected package dwz. 86s Preparing to unpack .../37-dwz_0.16-2_ppc64el.deb ... 86s Unpacking dwz (0.16-2) ... 86s Selecting previously unselected package gettext. 86s Preparing to unpack .../38-gettext_0.23.1-2build2_ppc64el.deb ... 86s Unpacking gettext (0.23.1-2build2) ... 86s Selecting previously unselected package intltool-debian. 86s Preparing to unpack .../39-intltool-debian_0.35.0+20060710.6_all.deb ... 86s Unpacking intltool-debian (0.35.0+20060710.6) ... 86s Selecting previously unselected package po-debconf. 86s Preparing to unpack .../40-po-debconf_1.0.21+nmu1_all.deb ... 86s Unpacking po-debconf (1.0.21+nmu1) ... 86s Selecting previously unselected package debhelper. 86s Preparing to unpack .../41-debhelper_13.24.2ubuntu1_all.deb ... 86s Unpacking debhelper (13.24.2ubuntu1) ... 86s Selecting previously unselected package libdqlite0:ppc64el. 86s Preparing to unpack .../42-libdqlite0_1.18.2-3_ppc64el.deb ... 86s Unpacking libdqlite0:ppc64el (1.18.2-3) ... 86s Selecting previously unselected package go-dqlite. 86s Preparing to unpack .../43-go-dqlite_2.0.1-1_ppc64el.deb ... 86s Unpacking go-dqlite (2.0.1-1) ... 87s Selecting previously unselected package golang-1.25-src. 87s Preparing to unpack .../44-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 87s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 88s Selecting previously unselected package golang-1.25-go. 88s Preparing to unpack .../45-golang-1.25-go_1.25.0-0ubuntu1_ppc64el.deb ... 88s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 89s Selecting previously unselected package golang-src. 89s Preparing to unpack .../46-golang-src_2%3a1.25~1_all.deb ... 89s Unpacking golang-src (2:1.25~1) ... 89s Selecting previously unselected package golang-go:ppc64el. 89s Preparing to unpack .../47-golang-go_2%3a1.25~1_ppc64el.deb ... 89s Unpacking golang-go:ppc64el (2:1.25~1) ... 89s Selecting previously unselected package golang-any:ppc64el. 89s Preparing to unpack .../48-golang-any_2%3a1.25~1_ppc64el.deb ... 89s Unpacking golang-any:ppc64el (2:1.25~1) ... 89s Selecting previously unselected package libxxhash-dev:ppc64el. 89s Preparing to unpack .../49-libxxhash-dev_0.8.3-2_ppc64el.deb ... 89s Unpacking libxxhash-dev:ppc64el (0.8.3-2) ... 89s Selecting previously unselected package liblz4-dev:ppc64el. 89s Preparing to unpack .../50-liblz4-dev_1.10.0-4build1_ppc64el.deb ... 89s Unpacking liblz4-dev:ppc64el (1.10.0-4build1) ... 89s Selecting previously unselected package libuv1-dev:ppc64el. 89s Preparing to unpack .../51-libuv1-dev_1.51.0-2ubuntu1_ppc64el.deb ... 89s Unpacking libuv1-dev:ppc64el (1.51.0-2ubuntu1) ... 89s Selecting previously unselected package libsqlite3-dev:ppc64el. 89s Preparing to unpack .../52-libsqlite3-dev_3.46.1-8_ppc64el.deb ... 89s Unpacking libsqlite3-dev:ppc64el (3.46.1-8) ... 89s Selecting previously unselected package libdqlite-dev:ppc64el. 89s Preparing to unpack .../53-libdqlite-dev_1.18.2-3_ppc64el.deb ... 89s Unpacking libdqlite-dev:ppc64el (1.18.2-3) ... 89s Selecting previously unselected package golang-github-google-renameio-dev. 89s Preparing to unpack .../54-golang-github-google-renameio-dev_2.0.0-2_all.deb ... 89s Unpacking golang-github-google-renameio-dev (2.0.0-2) ... 89s Selecting previously unselected package golang-github-mattn-go-sqlite3-dev:ppc64el. 89s Preparing to unpack .../55-golang-github-mattn-go-sqlite3-dev_1.14.32~ds1-1_ppc64el.deb ... 89s Unpacking golang-github-mattn-go-sqlite3-dev:ppc64el (1.14.32~ds1-1) ... 89s Selecting previously unselected package golang-github-rivo-uniseg-dev. 89s Preparing to unpack .../56-golang-github-rivo-uniseg-dev_0.4.7-1_all.deb ... 89s Unpacking golang-github-rivo-uniseg-dev (0.4.7-1) ... 89s Selecting previously unselected package golang-github-mattn-go-runewidth-dev. 89s Preparing to unpack .../57-golang-github-mattn-go-runewidth-dev_0.0.16-2_all.deb ... 89s Unpacking golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 89s Selecting previously unselected package golang-github-peterh-liner-dev. 89s Preparing to unpack .../58-golang-github-peterh-liner-dev_1.2.2-1_all.deb ... 89s Unpacking golang-github-peterh-liner-dev (1.2.2-1) ... 89s Selecting previously unselected package golang-github-pkg-errors-dev. 89s Preparing to unpack .../59-golang-github-pkg-errors-dev_0.9.1-3_all.deb ... 89s Unpacking golang-github-pkg-errors-dev (0.9.1-3) ... 89s Selecting previously unselected package golang-github-rican7-retry-dev. 89s Preparing to unpack .../60-golang-github-rican7-retry-dev_0.3.1-2_all.deb ... 89s Unpacking golang-github-rican7-retry-dev (0.3.1-2) ... 89s Selecting previously unselected package golang-github-pmezard-go-difflib-dev. 89s Preparing to unpack .../61-golang-github-pmezard-go-difflib-dev_1.0.0-3_all.deb ... 89s Unpacking golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 89s Selecting previously unselected package golang-github-shurcool-sanitized-anchor-name-dev. 89s Preparing to unpack .../62-golang-github-shurcool-sanitized-anchor-name-dev_1.0.0-3_all.deb ... 89s Unpacking golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 89s Selecting previously unselected package golang-github-russross-blackfriday-v2-dev. 89s Preparing to unpack .../63-golang-github-russross-blackfriday-v2-dev_2.1.0-1_all.deb ... 89s Unpacking golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 89s Selecting previously unselected package golang-github-cpuguy83-go-md2man-v2-dev. 89s Preparing to unpack .../64-golang-github-cpuguy83-go-md2man-v2-dev_2.0.4-1_all.deb ... 89s Unpacking golang-github-cpuguy83-go-md2man-v2-dev (2.0.4-1) ... 89s Selecting previously unselected package golang-github-spf13-pflag-dev. 89s Preparing to unpack .../65-golang-github-spf13-pflag-dev_1.0.10-1_all.deb ... 89s Unpacking golang-github-spf13-pflag-dev (1.0.10-1) ... 89s Selecting previously unselected package golang-golang-x-sys-dev. 89s Preparing to unpack .../66-golang-golang-x-sys-dev_0.22.0-1_all.deb ... 89s Unpacking golang-golang-x-sys-dev (0.22.0-1) ... 89s Selecting previously unselected package golang-github-fsnotify-fsnotify-dev. 89s Preparing to unpack .../67-golang-github-fsnotify-fsnotify-dev_1.9.0-1_all.deb ... 89s Unpacking golang-github-fsnotify-fsnotify-dev (1.9.0-1) ... 89s Selecting previously unselected package golang-github-davecgh-go-spew-dev. 89s Preparing to unpack .../68-golang-github-davecgh-go-spew-dev_1.1.1-3_all.deb ... 89s Unpacking golang-github-davecgh-go-spew-dev (1.1.1-3) ... 89s Selecting previously unselected package golang-github-hashicorp-hcl-dev. 89s Preparing to unpack .../69-golang-github-hashicorp-hcl-dev_1.0.0-1.1_all.deb ... 89s Unpacking golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 89s Selecting previously unselected package golang-github-magiconair-properties-dev. 89s Preparing to unpack .../70-golang-github-magiconair-properties-dev_1.8.7-1_all.deb ... 89s Unpacking golang-github-magiconair-properties-dev (1.8.7-1) ... 89s Selecting previously unselected package golang-github-mitchellh-mapstructure-dev. 89s Preparing to unpack .../71-golang-github-mitchellh-mapstructure-dev_1.5.0+git20231216.8508981-1_all.deb ... 89s Unpacking golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 89s Selecting previously unselected package golang-github-burntsushi-toml-dev. 89s Preparing to unpack .../72-golang-github-burntsushi-toml-dev_1.4.0-1_all.deb ... 89s Unpacking golang-github-burntsushi-toml-dev (1.4.0-1) ... 89s Selecting previously unselected package golang-gopkg-yaml.v2-dev. 89s Preparing to unpack .../73-golang-gopkg-yaml.v2-dev_2.4.3-1_all.deb ... 89s Unpacking golang-gopkg-yaml.v2-dev (2.4.3-1) ... 89s Selecting previously unselected package golang-github-pelletier-go-toml-dev. 89s Preparing to unpack .../74-golang-github-pelletier-go-toml-dev_1.9.5-1build1_all.deb ... 89s Unpacking golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 89s Selecting previously unselected package golang-github-pelletier-go-toml.v2-dev. 89s Preparing to unpack .../75-golang-github-pelletier-go-toml.v2-dev_2.2.4-1_all.deb ... 89s Unpacking golang-github-pelletier-go-toml.v2-dev (2.2.4-1) ... 89s Selecting previously unselected package golang-github-kr-fs-dev. 89s Preparing to unpack .../76-golang-github-kr-fs-dev_0.1.0-2_all.deb ... 89s Unpacking golang-github-kr-fs-dev (0.1.0-2) ... 89s Selecting previously unselected package golang-golang-x-term-dev. 89s Preparing to unpack .../77-golang-golang-x-term-dev_0.22.0-1_all.deb ... 89s Unpacking golang-golang-x-term-dev (0.22.0-1) ... 89s Selecting previously unselected package golang-golang-x-text-dev. 89s Preparing to unpack .../78-golang-golang-x-text-dev_0.22.0-1_all.deb ... 89s Unpacking golang-golang-x-text-dev (0.22.0-1) ... 90s Selecting previously unselected package golang-golang-x-crypto-dev. 90s Preparing to unpack .../79-golang-golang-x-crypto-dev_1%3a0.25.0-1_all.deb ... 90s Unpacking golang-golang-x-crypto-dev (1:0.25.0-1) ... 90s Selecting previously unselected package golang-github-pkg-sftp-dev. 90s Preparing to unpack .../80-golang-github-pkg-sftp-dev_1.13.9-1_all.deb ... 90s Unpacking golang-github-pkg-sftp-dev (1.13.9-1) ... 90s Selecting previously unselected package golang-github-spf13-afero-dev. 90s Preparing to unpack .../81-golang-github-spf13-afero-dev_1.15.0-1_all.deb ... 90s Unpacking golang-github-spf13-afero-dev (1.15.0-1) ... 90s Selecting previously unselected package tzdata-legacy. 90s Preparing to unpack .../82-tzdata-legacy_2025b-5ubuntu1_all.deb ... 90s Unpacking tzdata-legacy (2025b-5ubuntu1) ... 90s Selecting previously unselected package golang-github-spf13-cast-dev. 90s Preparing to unpack .../83-golang-github-spf13-cast-dev_1.10.0-2_all.deb ... 90s Unpacking golang-github-spf13-cast-dev (1.10.0-2) ... 90s Selecting previously unselected package golang-github-stretchr-objx-dev. 90s Preparing to unpack .../84-golang-github-stretchr-objx-dev_0.5.2-1_all.deb ... 90s Unpacking golang-github-stretchr-objx-dev (0.5.2-1) ... 90s Selecting previously unselected package golang-gopkg-yaml.v3-dev. 90s Preparing to unpack .../85-golang-gopkg-yaml.v3-dev_3.0.1-3_all.deb ... 90s Unpacking golang-gopkg-yaml.v3-dev (3.0.1-3) ... 90s Selecting previously unselected package golang-github-stretchr-testify-dev. 90s Preparing to unpack .../86-golang-github-stretchr-testify-dev_1.10.0-1_all.deb ... 90s Unpacking golang-github-stretchr-testify-dev (1.10.0-1) ... 90s Selecting previously unselected package golang-github-spf13-jwalterweatherman-dev. 90s Preparing to unpack .../87-golang-github-spf13-jwalterweatherman-dev_1.1.0+really1.1.0-2_all.deb ... 90s Unpacking golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 90s Selecting previously unselected package golang-github-subosito-gotenv-dev. 90s Preparing to unpack .../88-golang-github-subosito-gotenv-dev_1.3.0-1_all.deb ... 90s Unpacking golang-github-subosito-gotenv-dev (1.3.0-1) ... 90s Selecting previously unselected package golang-gopkg-ini.v1-dev. 90s Preparing to unpack .../89-golang-gopkg-ini.v1-dev_1.67.0-1_all.deb ... 90s Unpacking golang-gopkg-ini.v1-dev (1.67.0-1) ... 90s Selecting previously unselected package golang-github-spf13-viper-dev. 90s Preparing to unpack .../90-golang-github-spf13-viper-dev_1.12.0-1_all.deb ... 90s Unpacking golang-github-spf13-viper-dev (1.12.0-1) ... 90s Selecting previously unselected package golang-github-spf13-cobra-dev. 90s Preparing to unpack .../91-golang-github-spf13-cobra-dev_1.8.1-1_all.deb ... 90s Unpacking golang-github-spf13-cobra-dev (1.8.1-1) ... 90s Selecting previously unselected package golang-golang-x-sync-dev. 90s Preparing to unpack .../92-golang-golang-x-sync-dev_0.7.0-1_all.deb ... 90s Unpacking golang-golang-x-sync-dev (0.7.0-1) ... 90s Selecting previously unselected package golang-github-canonical-go-dqlite-dev. 90s Preparing to unpack .../93-golang-github-canonical-go-dqlite-dev_2.0.1-1_all.deb ... 90s Unpacking golang-github-canonical-go-dqlite-dev (2.0.1-1) ... 90s Selecting previously unselected package dh-golang. 90s Preparing to unpack .../94-dh-golang_1.63_all.deb ... 90s Unpacking dh-golang (1.63) ... 90s Setting up golang-github-pelletier-go-toml.v2-dev (2.2.4-1) ... 90s Setting up libuv1-dev:ppc64el (1.51.0-2ubuntu1) ... 90s Setting up golang-gopkg-yaml.v2-dev (2.4.3-1) ... 90s Setting up golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 90s Setting up golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 90s Setting up golang-github-stretchr-objx-dev (0.5.2-1) ... 90s Setting up golang-github-pkg-errors-dev (0.9.1-3) ... 90s Setting up libarchive-zip-perl (1.68-1) ... 90s Setting up golang-gopkg-yaml.v3-dev (3.0.1-3) ... 90s Setting up libdebhelper-perl (13.24.2ubuntu1) ... 90s Setting up golang-github-burntsushi-toml-dev (1.4.0-1) ... 90s Setting up golang-golang-x-sys-dev (0.22.0-1) ... 90s Setting up golang-github-kr-fs-dev (0.1.0-2) ... 90s Setting up golang-github-subosito-gotenv-dev (1.3.0-1) ... 90s Setting up golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 90s Setting up m4 (1.4.20-2) ... 90s Setting up golang-golang-x-term-dev (0.22.0-1) ... 90s Setting up libgomp1:ppc64el (15.2.0-7ubuntu1) ... 90s Setting up tzdata-legacy (2025b-5ubuntu1) ... 90s Setting up golang-github-google-renameio-dev (2.0.0-2) ... 90s Setting up golang-github-spf13-pflag-dev (1.0.10-1) ... 90s Setting up golang-github-davecgh-go-spew-dev (1.1.1-3) ... 90s Setting up autotools-dev (20240727.1) ... 90s Setting up libsqlite3-dev:ppc64el (3.46.1-8) ... 90s Setting up golang-github-stretchr-testify-dev (1.10.0-1) ... 90s Setting up libquadmath0:ppc64el (15.2.0-7ubuntu1) ... 90s Setting up golang-gopkg-ini.v1-dev (1.67.0-1) ... 90s Setting up libmpc3:ppc64el (1.3.1-1build3) ... 90s Setting up golang-golang-x-sync-dev (0.7.0-1) ... 90s Setting up autopoint (0.23.1-2build2) ... 90s Setting up golang-github-magiconair-properties-dev (1.8.7-1) ... 90s Setting up golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 90s Setting up golang-github-rican7-retry-dev (0.3.1-2) ... 90s Setting up autoconf (2.72-3.1ubuntu1) ... 90s Setting up libxxhash-dev:ppc64el (0.8.3-2) ... 90s Setting up golang-github-rivo-uniseg-dev (0.4.7-1) ... 90s Setting up libubsan1:ppc64el (15.2.0-7ubuntu1) ... 90s Setting up dwz (0.16-2) ... 90s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 90s Setting up golang-golang-x-text-dev (0.22.0-1) ... 90s Setting up libasan8:ppc64el (15.2.0-7ubuntu1) ... 90s Setting up golang-github-spf13-cast-dev (1.10.0-2) ... 90s Setting up golang-github-fsnotify-fsnotify-dev (1.9.0-1) ... 90s Setting up debugedit (1:5.2-3) ... 90s Setting up golang-golang-x-crypto-dev (1:0.25.0-1) ... 90s Setting up golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 90s Setting up golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 90s Setting up libtsan2:ppc64el (15.2.0-7ubuntu1) ... 90s Setting up libdqlite0:ppc64el (1.18.2-3) ... 90s Setting up libisl23:ppc64el (0.27-1) ... 90s Setting up golang-src (2:1.25~1) ... 90s Setting up libcc1-0:ppc64el (15.2.0-7ubuntu1) ... 90s Setting up liblsan0:ppc64el (15.2.0-7ubuntu1) ... 90s Setting up libitm1:ppc64el (15.2.0-7ubuntu1) ... 90s Setting up automake (1:1.18.1-2) ... 90s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 90s Setting up libfile-stripnondeterminism-perl (1.15.0-1) ... 90s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 90s Setting up golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 90s Setting up gettext (0.23.1-2build2) ... 90s Setting up libgcc-15-dev:ppc64el (15.2.0-7ubuntu1) ... 90s Setting up golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 90s Setting up golang-go:ppc64el (2:1.25~1) ... 90s Setting up golang-github-pkg-sftp-dev (1.13.9-1) ... 90s Setting up golang-any:ppc64el (2:1.25~1) ... 90s Setting up intltool-debian (0.35.0+20060710.6) ... 90s Setting up golang-github-peterh-liner-dev (1.2.2-1) ... 90s Setting up golang-github-mattn-go-sqlite3-dev:ppc64el (1.14.32~ds1-1) ... 90s Setting up go-dqlite (2.0.1-1) ... 90s Setting up cpp-15-powerpc64le-linux-gnu (15.2.0-7ubuntu1) ... 90s Setting up libstdc++-15-dev:ppc64el (15.2.0-7ubuntu1) ... 90s Setting up liblz4-dev:ppc64el (1.10.0-4build1) ... 90s Setting up dh-strip-nondeterminism (1.15.0-1) ... 90s Setting up cpp-15 (15.2.0-7ubuntu1) ... 90s Setting up golang-github-spf13-afero-dev (1.15.0-1) ... 90s Setting up cpp-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 90s Setting up libdqlite-dev:ppc64el (1.18.2-3) ... 90s Setting up golang-github-cpuguy83-go-md2man-v2-dev (2.0.4-1) ... 90s Setting up gcc-15-powerpc64le-linux-gnu (15.2.0-7ubuntu1) ... 90s Setting up po-debconf (1.0.21+nmu1) ... 90s Setting up gcc-15 (15.2.0-7ubuntu1) ... 90s Setting up gcc-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 90s Setting up golang-github-spf13-viper-dev (1.12.0-1) ... 90s Setting up cpp (4:15.2.0-4ubuntu1) ... 90s Setting up g++-15-powerpc64le-linux-gnu (15.2.0-7ubuntu1) ... 90s Setting up libtool (2.5.4-4build1) ... 90s Setting up gcc (4:15.2.0-4ubuntu1) ... 90s Setting up dh-autoreconf (21) ... 90s Setting up golang-github-spf13-cobra-dev (1.8.1-1) ... 90s Setting up g++-15 (15.2.0-7ubuntu1) ... 90s Setting up g++-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 90s Setting up debhelper (13.24.2ubuntu1) ... 90s Setting up golang-github-canonical-go-dqlite-dev (2.0.1-1) ... 90s Setting up g++ (4:15.2.0-4ubuntu1) ... 90s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 90s Setting up build-essential (12.12ubuntu1) ... 90s Setting up dh-golang (1.63) ... 90s Processing triggers for man-db (2.13.1-1) ... 91s Processing triggers for install-info (7.2-5) ... 91s Processing triggers for libc-bin (2.42-0ubuntu3) ... 93s autopkgtest [07:03:39]: test dh-golang-autopkgtest: /usr/bin/dh_golang_autopkgtest 93s autopkgtest [07:03:39]: test dh-golang-autopkgtest: [----------------------- 93s [info] Testing github.com/canonical/go-dqlite... 93s [info] Source code installed by binary package, overriding dh_auto_configure... 93s dh build --builddirectory=_build --buildsystem=golang --with=golang 93s dh_update_autotools_config -O--builddirectory=_build -O--buildsystem=golang 93s dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang 93s debian/rules override_dh_auto_configure 93s make[1]: Entering directory '/tmp/autopkgtest.S1Ydmg/autopkgtest_tmp' 93s mkdir -p "_build" 93s # remove existing symlinks, since "cp -a" won't convert them 93s find "_build" -type l -exec rm "{}" \; 93s cp -a /usr/share/gocode/src "_build" 94s make[1]: Leaving directory '/tmp/autopkgtest.S1Ydmg/autopkgtest_tmp' 94s dh_auto_build -O--builddirectory=_build -O--buildsystem=golang 94s cd _build && go install -trimpath -v -p 2 github.com/canonical/go-dqlite github.com/canonical/go-dqlite/app github.com/canonical/go-dqlite/benchmark github.com/canonical/go-dqlite/client github.com/canonical/go-dqlite/cmd/dqlite github.com/canonical/go-dqlite/cmd/dqlite-benchmark github.com/canonical/go-dqlite/cmd/dqlite-demo github.com/canonical/go-dqlite/driver github.com/canonical/go-dqlite/internal/bindings github.com/canonical/go-dqlite/internal/protocol github.com/canonical/go-dqlite/internal/shell github.com/canonical/go-dqlite/logging github.com/canonical/go-dqlite/tracing 94s internal/goarch 94s internal/unsafeheader 94s internal/cpu 94s internal/abi 94s internal/bytealg 94s internal/byteorder 94s internal/chacha8rand 94s internal/coverage/rtcov 94s internal/godebugs 94s internal/goexperiment 94s internal/goos 94s internal/profilerecord 94s internal/runtime/atomic 94s internal/runtime/math 94s internal/runtime/strconv 94s internal/runtime/syscall 94s internal/runtime/exithook 94s internal/runtime/gc 94s internal/runtime/cgroup 94s internal/asan 94s internal/msan 94s internal/race 94s internal/runtime/sys 94s internal/stringslite 94s internal/runtime/maps 94s internal/trace/tracev2 94s sync/atomic 95s internal/sync 95s internal/synctest 95s math/bits 95s internal/itoa 95s cmp 95s math 95s runtime 95s unicode/utf8 95s unicode 95s container/list 95s crypto/internal/fips140/alias 95s crypto/internal/fips140deps/byteorder 95s crypto/internal/fips140/subtle 95s crypto/internal/fips140deps/cpu 95s crypto/internal/boring/sig 95s unicode/utf16 95s vendor/golang.org/x/crypto/cryptobyte/asn1 95s vendor/golang.org/x/crypto/internal/alias 95s internal/nettrace 95s encoding 95s log/internal 95s container/ring 95s github.com/rivo/uniseg 98s internal/reflectlite 98s sync 98s errors 98s internal/oserror 98s internal/bisect 98s iter 98s slices 98s syscall 99s internal/godebug 99s strconv 99s reflect 99s time 99s context 100s io 100s path 100s io/fs 100s internal/filepathlite 100s internal/syscall/unix 100s internal/poll 100s internal/syscall/execenv 100s internal/testlog 100s os 100s internal/fmtsort 100s bytes 100s hash 100s crypto 101s crypto/internal/fips140deps/godebug 101s strings 101s crypto/internal/fips140 101s crypto/internal/impl 101s crypto/internal/fips140/sha256 101s crypto/internal/fips140/sha3 101s crypto/internal/fips140/sha512 101s crypto/internal/fips140/hmac 101s fmt 101s crypto/internal/fips140/check 101s crypto/internal/fips140/aes 101s crypto/internal/sysrand 101s crypto/internal/entropy 101s math/rand/v2 101s crypto/internal/randutil 101s crypto/internal/fips140/drbg 101s crypto/subtle 101s crypto/internal/fips140/aes/gcm 101s crypto/internal/fips140only 101s crypto/internal/fips140/nistec/fiat 101s crypto/cipher 102s crypto/internal/boring 102s crypto/aes 102s crypto/des 102s crypto/internal/fips140/edwards25519/field 102s math/rand 102s math/big 102s crypto/internal/fips140/nistec 102s crypto/internal/boring/bbig 103s crypto/internal/fips140/bigmod 103s crypto/internal/fips140/ecdh 103s crypto/ecdh 103s crypto/elliptic 103s crypto/internal/fips140/ecdsa 103s weak 103s crypto/internal/fips140cache 103s crypto/sha3 103s crypto/internal/fips140hash 103s crypto/sha512 103s encoding/asn1 103s crypto/internal/fips140/edwards25519 103s crypto/internal/fips140/ed25519 103s crypto/rand 103s vendor/golang.org/x/crypto/cryptobyte 103s crypto/ed25519 103s crypto/ecdsa 103s crypto/internal/fips140/hkdf 103s crypto/hkdf 103s crypto/hmac 103s crypto/internal/fips140/mlkem 103s crypto/internal/fips140/tls12 103s crypto/internal/fips140/tls13 103s encoding/binary 104s vendor/golang.org/x/crypto/chacha20 104s vendor/golang.org/x/crypto/internal/poly1305 104s crypto/md5 104s vendor/golang.org/x/crypto/chacha20poly1305 104s crypto/rc4 104s crypto/internal/hpke 104s crypto/internal/fips140/rsa 104s crypto/sha1 104s crypto/sha256 104s crypto/fips140 104s crypto/tls/internal/fips140tls 104s crypto/dsa 104s crypto/rsa 104s encoding/hex 104s crypto/x509/pkix 104s encoding/base64 104s maps 104s vendor/golang.org/x/net/dns/dnsmessage 104s encoding/pem 104s internal/singleflight 104s unique 104s net/netip 105s runtime/cgo 105s net/url 105s path/filepath 105s sort 105s database/sql/driver 105s database/sql 105s net 106s github.com/Rican7/retry/backoff 106s github.com/Rican7/retry/jitter 106s github.com/Rican7/retry/strategy 106s github.com/Rican7/retry 106s flag 106s bufio 106s internal/sysinfo 106s runtime/debug 106s runtime/trace 106s testing 107s github.com/canonical/go-dqlite/logging 107s github.com/pkg/errors 107s golang.org/x/sync/semaphore 107s io/ioutil 107s github.com/google/renameio 107s github.com/mattn/go-sqlite3 108s crypto/x509 109s crypto/tls 110s github.com/canonical/go-dqlite/internal/protocol 111s regexp/syntax 111s regexp 111s gopkg.in/yaml.v2 112s github.com/canonical/go-dqlite/internal/bindings 112s github.com/canonical/go-dqlite/client 113s github.com/canonical/go-dqlite/tracing 113s github.com/canonical/go-dqlite/driver 113s golang.org/x/sys/unix 113s github.com/canonical/go-dqlite 113s log 113s encoding/json 114s github.com/canonical/go-dqlite/app 114s text/tabwriter 114s github.com/canonical/go-dqlite/internal/shell 114s github.com/mattn/go-runewidth 114s os/signal 114s github.com/peterh/liner 114s github.com/canonical/go-dqlite/benchmark 114s encoding/csv 114s text/template/parse 114s github.com/spf13/pflag 115s text/template 115s compress/flate 115s github.com/spf13/cobra 115s hash/crc32 115s compress/gzip 116s vendor/golang.org/x/text/transform 116s vendor/golang.org/x/text/unicode/bidi 116s github.com/canonical/go-dqlite/cmd/dqlite 116s github.com/canonical/go-dqlite/cmd/dqlite-benchmark 117s vendor/golang.org/x/text/secure/bidirule 117s vendor/golang.org/x/text/unicode/norm 117s net/textproto 117s vendor/golang.org/x/net/http2/hpack 117s mime 117s vendor/golang.org/x/net/idna 118s vendor/golang.org/x/net/http/httpguts 118s vendor/golang.org/x/net/http/httpproxy 118s mime/quotedprintable 118s mime/multipart 118s net/http/httptrace 118s net/http/internal 118s net/http/internal/ascii 118s net/http/internal/httpcommon 118s net/http 120s github.com/canonical/go-dqlite/cmd/dqlite-demo 121s create-stamp debian/debhelper-build-stamp 121s dh_auto_test -O--builddirectory=_build -O--buildsystem=golang 122s cd _build && go test -vet=off -v -p 2 github.com/canonical/go-dqlite github.com/canonical/go-dqlite/app github.com/canonical/go-dqlite/benchmark github.com/canonical/go-dqlite/client github.com/canonical/go-dqlite/cmd/dqlite github.com/canonical/go-dqlite/cmd/dqlite-benchmark github.com/canonical/go-dqlite/cmd/dqlite-demo github.com/canonical/go-dqlite/driver github.com/canonical/go-dqlite/internal/bindings github.com/canonical/go-dqlite/internal/protocol github.com/canonical/go-dqlite/internal/shell github.com/canonical/go-dqlite/logging github.com/canonical/go-dqlite/tracing 143s === RUN ExampleLastEntryInfo 143s --- PASS: ExampleLastEntryInfo (0.00s) 143s PASS 143s ok github.com/canonical/go-dqlite 0.006s 318s === RUN TestNew_PristineDefault 318s app_test.go:1207: 07:04:11.051 - 1: WARN: attempt 1: server 127.0.0.1:9000: context canceled 318s --- PASS: TestNew_PristineDefault (0.25s) 318s === RUN TestNew_PristineJoiner 318s app_test.go:1207: 07:04:11.311 - 2: DEBUG: new connection from 127.0.0.1:48332 318s app_test.go:1207: 07:04:11.313 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.316 - 2: DEBUG: new connection from 127.0.0.1:48346 318s app_test.go:1207: 07:04:11.360 - 2: DEBUG: new connection from 127.0.0.1:48350 318s app_test.go:1207: 07:04:11.362 - 3: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.368 - 2: DEBUG: new connection from 127.0.0.1:48360 318s app_test.go:1207: 07:04:11.368 - 3: DEBUG: new connection from 127.0.0.1:36894 318s app_test.go:1207: 07:04:11.370 - 2: DEBUG: new connection from 127.0.0.1:48372 318s app_test.go:1207: 07:04:11.371 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s --- PASS: TestNew_PristineJoiner (0.16s) 318s === RUN TestNew_JoinerRestart 318s app_test.go:1207: 07:04:11.467 - 4: DEBUG: new connection from 127.0.0.1:48378 318s app_test.go:1207: 07:04:11.469 - 4: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.472 - 4: DEBUG: new connection from 127.0.0.1:48386 318s app_test.go:1207: 07:04:11.508 - 4: DEBUG: new connection from 127.0.0.1:48400 318s app_test.go:1207: 07:04:11.510 - 5: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.517 - 5: DEBUG: new connection from 127.0.0.1:36902 318s app_test.go:1207: 07:04:11.517 - 4: DEBUG: new connection from 127.0.0.1:48404 318s app_test.go:1207: 07:04:11.568 - 6: DEBUG: new connection from 127.0.0.1:36910 318s app_test.go:1207: 07:04:11.568 - 4: DEBUG: new connection from 127.0.0.1:48406 318s app_test.go:1207: 07:04:11.569 - 6: WARN: attempt 1: server 127.0.0.1:9002: no known leader 318s app_test.go:1207: 07:04:11.570 - 6: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.573 - 6: DEBUG: new connection from 127.0.0.1:36916 318s app_test.go:1207: 07:04:11.576 - 4: DEBUG: new connection from 127.0.0.1:48410 318s --- PASS: TestNew_JoinerRestart (0.20s) 318s === RUN TestNew_SecondJoiner 318s app_test.go:1207: 07:04:11.673 - 7: DEBUG: new connection from 127.0.0.1:48416 318s app_test.go:1207: 07:04:11.675 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.677 - 7: DEBUG: new connection from 127.0.0.1:48432 318s app_test.go:1207: 07:04:11.728 - 7: DEBUG: new connection from 127.0.0.1:48438 318s app_test.go:1207: 07:04:11.730 - 8: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.738 - 7: DEBUG: new connection from 127.0.0.1:48450 318s app_test.go:1207: 07:04:11.738 - 8: DEBUG: new connection from 127.0.0.1:36928 318s app_test.go:1207: 07:04:11.792 - 7: DEBUG: new connection from 127.0.0.1:48458 318s app_test.go:1207: 07:04:11.794 - 9: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.796 - 8: DEBUG: new connection from 127.0.0.1:36936 318s app_test.go:1207: 07:04:11.798 - 9: DEBUG: new connection from 127.0.0.1:38642 318s app_test.go:1207: 07:04:11.799 - 7: DEBUG: new connection from 127.0.0.1:48470 318s app_test.go:1207: 07:04:11.804 - 9: DEBUG: new connection from 127.0.0.1:38644 318s app_test.go:1207: 07:04:11.808 - 7: DEBUG: new connection from 127.0.0.1:48474 318s app_test.go:1207: 07:04:11.629 - 8: DEBUG: new connection from 127.0.0.1:36946 318s app_test.go:1207: 07:04:11.659 - 7: DEBUG: new connection from 127.0.0.1:48488 318s app_test.go:1207: 07:04:11.632 - 7: DEBUG: new connection from 127.0.0.1:48498 318s app_test.go:1207: 07:04:11.634 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s --- PASS: TestNew_SecondJoiner (2.13s) 318s === RUN TestNew_ThirdJoiner 318s app_test.go:1207: 07:04:11.792 - 10: DEBUG: new connection from 127.0.0.1:48502 318s app_test.go:1207: 07:04:11.794 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.796 - 10: DEBUG: new connection from 127.0.0.1:48518 318s app_test.go:1207: 07:04:11.840 - 10: DEBUG: new connection from 127.0.0.1:48534 318s app_test.go:1207: 07:04:11.842 - 11: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.851 - 10: DEBUG: new connection from 127.0.0.1:48540 318s app_test.go:1207: 07:04:11.851 - 11: DEBUG: new connection from 127.0.0.1:36960 318s app_test.go:1207: 07:04:11.896 - 10: DEBUG: new connection from 127.0.0.1:48542 318s app_test.go:1207: 07:04:11.898 - 12: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.901 - 10: DEBUG: new connection from 127.0.0.1:48550 318s app_test.go:1207: 07:04:11.901 - 11: DEBUG: new connection from 127.0.0.1:36972 318s app_test.go:1207: 07:04:11.902 - 12: DEBUG: new connection from 127.0.0.1:38646 318s app_test.go:1207: 07:04:11.905 - 12: DEBUG: new connection from 127.0.0.1:38652 318s app_test.go:1207: 07:04:11.908 - 10: DEBUG: new connection from 127.0.0.1:48556 318s app_test.go:1207: 07:04:11.765 - 11: DEBUG: new connection from 127.0.0.1:36974 318s app_test.go:1207: 07:04:11.772 - 10: DEBUG: new connection from 127.0.0.1:48560 318s app_test.go:1207: 07:04:11.812 - 10: DEBUG: new connection from 127.0.0.1:48564 318s app_test.go:1207: 07:04:11.814 - 13: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.822 - 10: DEBUG: new connection from 127.0.0.1:48568 318s app_test.go:1207: 07:04:11.822 - 11: DEBUG: new connection from 127.0.0.1:36976 318s app_test.go:1207: 07:04:11.824 - 12: DEBUG: new connection from 127.0.0.1:38656 318s app_test.go:1207: 07:04:11.824 - 13: DEBUG: new connection from 127.0.0.1:46720 318s app_test.go:1207: 07:04:11.826 - 13: DEBUG: new connection from 127.0.0.1:46734 318s app_test.go:1207: 07:04:11.827 - 10: DEBUG: new connection from 127.0.0.1:48582 318s app_test.go:1207: 07:04:11.831 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s --- PASS: TestNew_ThirdJoiner (2.20s) 318s === RUN TestNew_FourthJoiner 318s app_test.go:1207: 07:04:11.994 - 14: DEBUG: new connection from 127.0.0.1:48590 318s app_test.go:1207: 07:04:11.996 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.000 - 14: DEBUG: new connection from 127.0.0.1:48600 318s app_test.go:1207: 07:04:11.044 - 14: DEBUG: new connection from 127.0.0.1:48612 318s app_test.go:1207: 07:04:11.047 - 15: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.054 - 15: DEBUG: new connection from 127.0.0.1:36988 318s app_test.go:1207: 07:04:11.054 - 14: DEBUG: new connection from 127.0.0.1:48622 318s app_test.go:1207: 07:04:11.104 - 14: DEBUG: new connection from 127.0.0.1:48624 318s app_test.go:1207: 07:04:11.106 - 16: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.109 - 15: DEBUG: new connection from 127.0.0.1:37004 318s app_test.go:1207: 07:04:11.109 - 14: DEBUG: new connection from 127.0.0.1:48636 318s app_test.go:1207: 07:04:11.110 - 16: DEBUG: new connection from 127.0.0.1:38668 318s app_test.go:1207: 07:04:11.112 - 16: DEBUG: new connection from 127.0.0.1:38678 318s app_test.go:1207: 07:04:11.117 - 14: DEBUG: new connection from 127.0.0.1:48642 318s app_test.go:1207: 07:04:11.962 - 15: DEBUG: new connection from 127.0.0.1:37010 318s app_test.go:1207: 07:04:11.966 - 14: DEBUG: new connection from 127.0.0.1:48654 318s app_test.go:1207: 07:04:11.008 - 14: DEBUG: new connection from 127.0.0.1:48662 318s app_test.go:1207: 07:04:11.010 - 17: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.013 - 15: DEBUG: new connection from 127.0.0.1:37014 318s app_test.go:1207: 07:04:11.013 - 14: DEBUG: new connection from 127.0.0.1:48666 318s app_test.go:1207: 07:04:11.016 - 16: DEBUG: new connection from 127.0.0.1:38680 318s app_test.go:1207: 07:04:11.016 - 17: DEBUG: new connection from 127.0.0.1:46750 318s app_test.go:1207: 07:04:11.018 - 17: DEBUG: new connection from 127.0.0.1:46752 318s app_test.go:1207: 07:04:11.025 - 14: DEBUG: new connection from 127.0.0.1:48682 318s app_test.go:1207: 07:04:11.064 - 14: DEBUG: new connection from 127.0.0.1:48684 318s app_test.go:1207: 07:04:11.066 - 18: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.069 - 14: DEBUG: new connection from 127.0.0.1:48698 318s app_test.go:1207: 07:04:11.070 - 15: DEBUG: new connection from 127.0.0.1:37020 318s app_test.go:1207: 07:04:11.070 - 16: DEBUG: new connection from 127.0.0.1:38684 318s app_test.go:1207: 07:04:11.072 - 17: DEBUG: new connection from 127.0.0.1:46760 318s app_test.go:1207: 07:04:11.072 - 18: DEBUG: new connection from 127.0.0.1:43724 318s app_test.go:1207: 07:04:11.076 - 18: DEBUG: new connection from 127.0.0.1:43734 318s app_test.go:1207: 07:04:11.077 - 14: DEBUG: new connection from 127.0.0.1:48712 318s app_test.go:1207: 07:04:11.079 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s --- PASS: TestNew_FourthJoiner (2.26s) 318s === RUN TestNew_FifthJoiner 318s app_test.go:1207: 07:04:11.262 - 19: DEBUG: new connection from 127.0.0.1:48726 318s app_test.go:1207: 07:04:11.264 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.266 - 19: DEBUG: new connection from 127.0.0.1:48742 318s app_test.go:1207: 07:04:11.316 - 19: DEBUG: new connection from 127.0.0.1:48758 318s app_test.go:1207: 07:04:11.318 - 20: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.328 - 20: DEBUG: new connection from 127.0.0.1:37022 318s app_test.go:1207: 07:04:11.328 - 19: DEBUG: new connection from 127.0.0.1:48772 318s app_test.go:1207: 07:04:11.385 - 19: DEBUG: new connection from 127.0.0.1:48778 318s app_test.go:1207: 07:04:11.388 - 21: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.393 - 20: DEBUG: new connection from 127.0.0.1:37026 318s app_test.go:1207: 07:04:11.394 - 19: DEBUG: new connection from 127.0.0.1:48784 318s app_test.go:1207: 07:04:11.394 - 21: DEBUG: new connection from 127.0.0.1:38690 318s app_test.go:1207: 07:04:11.396 - 21: DEBUG: new connection from 127.0.0.1:38702 318s app_test.go:1207: 07:04:11.405 - 19: DEBUG: new connection from 127.0.0.1:48796 318s app_test.go:1207: 07:04:11.221 - 20: DEBUG: new connection from 127.0.0.1:37042 318s app_test.go:1207: 07:04:11.225 - 19: DEBUG: new connection from 127.0.0.1:48800 318s app_test.go:1207: 07:04:11.280 - 19: DEBUG: new connection from 127.0.0.1:37816 318s app_test.go:1207: 07:04:11.282 - 22: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.286 - 20: DEBUG: new connection from 127.0.0.1:48754 318s app_test.go:1207: 07:04:11.286 - 19: DEBUG: new connection from 127.0.0.1:37832 318s app_test.go:1207: 07:04:11.287 - 21: DEBUG: new connection from 127.0.0.1:59376 318s app_test.go:1207: 07:04:11.288 - 22: DEBUG: new connection from 127.0.0.1:51934 318s app_test.go:1207: 07:04:11.290 - 22: DEBUG: new connection from 127.0.0.1:51938 318s app_test.go:1207: 07:04:11.300 - 19: DEBUG: new connection from 127.0.0.1:37846 318s app_test.go:1207: 07:04:11.352 - 19: DEBUG: new connection from 127.0.0.1:37854 318s app_test.go:1207: 07:04:11.354 - 23: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.359 - 20: DEBUG: new connection from 127.0.0.1:48768 318s app_test.go:1207: 07:04:11.359 - 19: DEBUG: new connection from 127.0.0.1:37866 318s app_test.go:1207: 07:04:11.360 - 21: DEBUG: new connection from 127.0.0.1:59388 318s app_test.go:1207: 07:04:11.361 - 22: DEBUG: new connection from 127.0.0.1:51948 318s app_test.go:1207: 07:04:11.362 - 23: DEBUG: new connection from 127.0.0.1:53296 318s app_test.go:1207: 07:04:11.364 - 23: DEBUG: new connection from 127.0.0.1:53312 318s app_test.go:1207: 07:04:11.370 - 19: DEBUG: new connection from 127.0.0.1:37868 318s app_test.go:1207: 07:04:11.413 - 19: DEBUG: new connection from 127.0.0.1:37878 318s app_test.go:1207: 07:04:11.417 - 24: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.421 - 19: DEBUG: new connection from 127.0.0.1:37884 318s app_test.go:1207: 07:04:11.421 - 20: DEBUG: new connection from 127.0.0.1:48782 318s app_test.go:1207: 07:04:11.423 - 21: DEBUG: new connection from 127.0.0.1:59394 318s app_test.go:1207: 07:04:11.429 - 23: DEBUG: new connection from 127.0.0.1:53322 318s app_test.go:1207: 07:04:11.431 - 24: DEBUG: new connection from 127.0.0.1:39332 318s app_test.go:1207: 07:04:11.440 - 22: DEBUG: new connection from 127.0.0.1:51950 318s app_test.go:1207: 07:04:11.441 - 24: DEBUG: new connection from 127.0.0.1:39338 318s app_test.go:1207: 07:04:11.443 - 19: DEBUG: new connection from 127.0.0.1:37890 318s app_test.go:1207: 07:04:11.444 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s --- PASS: TestNew_FifthJoiner (2.37s) 318s === RUN TestNew_SixthJoiner 318s app_test.go:1207: 07:04:11.644 - 25: DEBUG: new connection from 127.0.0.1:37896 318s app_test.go:1207: 07:04:11.646 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.651 - 25: DEBUG: new connection from 127.0.0.1:37898 318s app_test.go:1207: 07:04:11.692 - 25: DEBUG: new connection from 127.0.0.1:37906 318s app_test.go:1207: 07:04:11.694 - 26: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.706 - 25: DEBUG: new connection from 127.0.0.1:37920 318s app_test.go:1207: 07:04:11.706 - 26: DEBUG: new connection from 127.0.0.1:48794 318s app_test.go:1207: 07:04:11.756 - 25: DEBUG: new connection from 127.0.0.1:37936 318s app_test.go:1207: 07:04:11.758 - 27: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.761 - 25: DEBUG: new connection from 127.0.0.1:37938 318s app_test.go:1207: 07:04:11.761 - 26: DEBUG: new connection from 127.0.0.1:48798 318s app_test.go:1207: 07:04:11.763 - 27: DEBUG: new connection from 127.0.0.1:59404 318s app_test.go:1207: 07:04:11.764 - 27: DEBUG: new connection from 127.0.0.1:59408 318s app_test.go:1207: 07:04:11.768 - 25: DEBUG: new connection from 127.0.0.1:37946 318s app_test.go:1207: 07:04:11.591 - 26: DEBUG: new connection from 127.0.0.1:48812 318s app_test.go:1207: 07:04:11.596 - 25: DEBUG: new connection from 127.0.0.1:37960 318s app_test.go:1207: 07:04:11.636 - 25: DEBUG: new connection from 127.0.0.1:37968 318s app_test.go:1207: 07:04:11.639 - 28: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.642 - 25: DEBUG: new connection from 127.0.0.1:37980 318s app_test.go:1207: 07:04:11.642 - 26: DEBUG: new connection from 127.0.0.1:48824 318s app_test.go:1207: 07:04:11.644 - 27: DEBUG: new connection from 127.0.0.1:59416 318s app_test.go:1207: 07:04:11.644 - 28: DEBUG: new connection from 127.0.0.1:51962 318s app_test.go:1207: 07:04:11.646 - 28: DEBUG: new connection from 127.0.0.1:51976 318s app_test.go:1207: 07:04:11.654 - 25: DEBUG: new connection from 127.0.0.1:37990 318s app_test.go:1207: 07:04:11.692 - 25: DEBUG: new connection from 127.0.0.1:38000 318s app_test.go:1207: 07:04:11.693 - 29: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.697 - 25: DEBUG: new connection from 127.0.0.1:38004 318s app_test.go:1207: 07:04:11.697 - 26: DEBUG: new connection from 127.0.0.1:48836 318s app_test.go:1207: 07:04:11.698 - 27: DEBUG: new connection from 127.0.0.1:59430 318s app_test.go:1207: 07:04:11.699 - 28: DEBUG: new connection from 127.0.0.1:51978 318s app_test.go:1207: 07:04:11.700 - 29: DEBUG: new connection from 127.0.0.1:53332 318s app_test.go:1207: 07:04:11.703 - 29: DEBUG: new connection from 127.0.0.1:53336 318s app_test.go:1207: 07:04:11.711 - 25: DEBUG: new connection from 127.0.0.1:38016 318s app_test.go:1207: 07:04:11.782 - 25: DEBUG: new connection from 127.0.0.1:38032 318s app_test.go:1207: 07:04:11.784 - 30: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.790 - 26: DEBUG: new connection from 127.0.0.1:48852 318s app_test.go:1207: 07:04:11.790 - 25: DEBUG: new connection from 127.0.0.1:38044 318s app_test.go:1207: 07:04:11.791 - 27: DEBUG: new connection from 127.0.0.1:59436 318s app_test.go:1207: 07:04:11.792 - 28: DEBUG: new connection from 127.0.0.1:51982 318s app_test.go:1207: 07:04:11.794 - 29: DEBUG: new connection from 127.0.0.1:53348 318s app_test.go:1207: 07:04:11.795 - 30: DEBUG: new connection from 127.0.0.1:39340 318s app_test.go:1207: 07:04:11.797 - 30: DEBUG: new connection from 127.0.0.1:39348 318s app_test.go:1207: 07:04:11.806 - 25: DEBUG: new connection from 127.0.0.1:38056 318s app_test.go:1207: 07:04:11.850 - 25: DEBUG: new connection from 127.0.0.1:38068 318s app_test.go:1207: 07:04:11.851 - 31: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.861 - 26: DEBUG: new connection from 127.0.0.1:48854 318s app_test.go:1207: 07:04:11.862 - 25: DEBUG: new connection from 127.0.0.1:38080 318s app_test.go:1207: 07:04:11.863 - 27: DEBUG: new connection from 127.0.0.1:59438 318s app_test.go:1207: 07:04:11.865 - 28: DEBUG: new connection from 127.0.0.1:51994 318s app_test.go:1207: 07:04:11.868 - 29: DEBUG: new connection from 127.0.0.1:53358 318s app_test.go:1207: 07:04:11.869 - 30: DEBUG: new connection from 127.0.0.1:39362 318s app_test.go:1207: 07:04:11.870 - 31: DEBUG: new connection from 127.0.0.1:38180 318s app_test.go:1207: 07:04:11.872 - 25: DEBUG: new connection from 127.0.0.1:38088 318s app_test.go:1207: 07:04:11.873 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s --- PASS: TestNew_SixthJoiner (2.40s) 318s === RUN TestHandover_Voter 318s app_test.go:1207: 07:04:11.019 - 32: DEBUG: new connection from 127.0.0.1:38098 318s app_test.go:1207: 07:04:11.020 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.023 - 32: DEBUG: new connection from 127.0.0.1:38102 318s app_test.go:1207: 07:04:11.068 - 32: DEBUG: new connection from 127.0.0.1:38104 318s app_test.go:1207: 07:04:11.070 - 33: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.077 - 33: DEBUG: new connection from 127.0.0.1:48868 318s app_test.go:1207: 07:04:11.077 - 32: DEBUG: new connection from 127.0.0.1:38120 318s app_test.go:1207: 07:04:11.140 - 32: DEBUG: new connection from 127.0.0.1:38124 318s app_test.go:1207: 07:04:11.142 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.147 - 32: DEBUG: new connection from 127.0.0.1:38132 318s app_test.go:1207: 07:04:11.147 - 33: DEBUG: new connection from 127.0.0.1:48884 318s app_test.go:1207: 07:04:11.149 - 34: DEBUG: new connection from 127.0.0.1:59448 318s app_test.go:1207: 07:04:11.151 - 34: DEBUG: new connection from 127.0.0.1:59460 318s app_test.go:1207: 07:04:11.157 - 32: DEBUG: new connection from 127.0.0.1:38134 318s app_test.go:1207: 07:04:11.988 - 33: DEBUG: new connection from 127.0.0.1:48896 318s app_test.go:1207: 07:04:11.992 - 32: DEBUG: new connection from 127.0.0.1:38136 318s app_test.go:1207: 07:04:11.036 - 32: DEBUG: new connection from 127.0.0.1:38142 318s app_test.go:1207: 07:04:11.038 - 35: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.042 - 32: DEBUG: new connection from 127.0.0.1:38150 318s app_test.go:1207: 07:04:11.042 - 33: DEBUG: new connection from 127.0.0.1:48906 318s app_test.go:1207: 07:04:11.044 - 34: DEBUG: new connection from 127.0.0.1:59468 318s app_test.go:1207: 07:04:11.046 - 35: DEBUG: new connection from 127.0.0.1:51998 318s app_test.go:1207: 07:04:11.049 - 35: DEBUG: new connection from 127.0.0.1:52014 318s app_test.go:1207: 07:04:11.050 - 32: DEBUG: new connection from 127.0.0.1:38154 318s app_test.go:1207: 07:04:11.052 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.052 - 32: DEBUG: new connection from 127.0.0.1:38170 318s app_test.go:1207: 07:04:11.053 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:04:11.053 - 32: DEBUG: new connection from 127.0.0.1:38176 318s app_test.go:1207: 07:04:11.054 - 33: DEBUG: new connection from 127.0.0.1:48914 318s app_test.go:1207: 07:04:11.054 - 34: DEBUG: new connection from 127.0.0.1:59480 318s app_test.go:1207: 07:04:11.055 - 35: DEBUG: new connection from 127.0.0.1:52020 318s app_test.go:1207: 07:04:11.057 - 32: DEBUG: new connection from 127.0.0.1:38178 318s app_test.go:1207: 07:04:11.992 - 34: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 318s --- PASS: TestHandover_Voter (3.20s) 318s === RUN TestHandover_TwoNodes 318s app_test.go:1207: 07:04:11.250 - 36: DEBUG: new connection from 127.0.0.1:38180 318s app_test.go:1207: 07:04:11.251 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.254 - 36: DEBUG: new connection from 127.0.0.1:38194 318s app_test.go:1207: 07:04:11.296 - 36: DEBUG: new connection from 127.0.0.1:38202 318s app_test.go:1207: 07:04:11.299 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.317 - 37: DEBUG: new connection from 127.0.0.1:48930 318s app_test.go:1207: 07:04:11.317 - 36: DEBUG: new connection from 127.0.0.1:38210 318s app_test.go:1207: 07:04:11.319 - 36: DEBUG: new connection from 127.0.0.1:38226 318s app_test.go:1207: 07:04:11.320 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:04:11.320 - 37: DEBUG: new connection from 127.0.0.1:48944 318s app_test.go:1207: 07:04:11.320 - 36: DEBUG: new connection from 127.0.0.1:38228 318s app_test.go:1207: 07:04:11.322 - 37: DEBUG: new connection from 127.0.0.1:48956 318s app_test.go:1207: 07:04:11.334 - 36: DEBUG: new connection from 127.0.0.1:38240 318s app_test.go:1207: 07:04:11.196 - 36: DEBUG: promoted 127.0.0.1:9002 from spare to voter 318s app_test.go:1207: 07:04:11.197 - 36: DEBUG: new connection from 127.0.0.1:38250 318s app_test.go:1207: 07:04:11.197 - 37: DEBUG: new connection from 127.0.0.1:48960 318s app_test.go:1207: 07:04:11.208 - 36: DEBUG: new connection from 127.0.0.1:38266 318s app_test.go:1207: 07:04:11.209 - 36: DEBUG: new connection from 127.0.0.1:38280 318s app_test.go:1207: 07:04:11.210 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 318s app_test.go:1207: 07:04:11.210 - 37: DEBUG: new connection from 127.0.0.1:48972 318s app_test.go:1207: 07:04:11.212 - 36: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 318s app_test.go:1207: 07:04:11.213 - 36: DEBUG: new connection from 127.0.0.1:38282 318s app_test.go:1207: 07:04:11.214 - 37: DEBUG: new connection from 127.0.0.1:48986 318s app_test.go:1207: 07:04:11.214 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 318s app_test.go:1207: 07:04:11.214 - 37: DEBUG: new connection from 127.0.0.1:48988 318s app_test.go:1207: 07:04:11.216 - 37: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 318s --- PASS: TestHandover_TwoNodes (1.12s) 318s === RUN TestHandover_VoterHonorFailureDomain 318s app_test.go:1207: 07:04:11.353 - 38: DEBUG: new connection from 127.0.0.1:38298 318s app_test.go:1207: 07:04:11.354 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.359 - 38: DEBUG: new connection from 127.0.0.1:38302 318s app_test.go:1207: 07:04:11.408 - 38: DEBUG: new connection from 127.0.0.1:38318 318s app_test.go:1207: 07:04:11.410 - 39: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.421 - 38: DEBUG: new connection from 127.0.0.1:38330 318s app_test.go:1207: 07:04:11.421 - 39: DEBUG: new connection from 127.0.0.1:48992 318s app_test.go:1207: 07:04:11.469 - 38: DEBUG: new connection from 127.0.0.1:38332 318s app_test.go:1207: 07:04:11.470 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.475 - 39: DEBUG: new connection from 127.0.0.1:49004 318s app_test.go:1207: 07:04:11.475 - 38: DEBUG: new connection from 127.0.0.1:38338 318s app_test.go:1207: 07:04:11.476 - 40: DEBUG: new connection from 127.0.0.1:59492 318s app_test.go:1207: 07:04:11.478 - 40: DEBUG: new connection from 127.0.0.1:59504 318s app_test.go:1207: 07:04:11.488 - 38: DEBUG: new connection from 127.0.0.1:38348 318s app_test.go:1207: 07:04:11.314 - 39: DEBUG: new connection from 127.0.0.1:49008 318s app_test.go:1207: 07:04:11.318 - 38: DEBUG: new connection from 127.0.0.1:38360 318s app_test.go:1207: 07:04:11.356 - 38: DEBUG: new connection from 127.0.0.1:38366 318s app_test.go:1207: 07:04:11.358 - 41: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.361 - 39: DEBUG: new connection from 127.0.0.1:49018 318s app_test.go:1207: 07:04:11.362 - 38: DEBUG: new connection from 127.0.0.1:38380 318s app_test.go:1207: 07:04:11.362 - 40: DEBUG: new connection from 127.0.0.1:59516 318s app_test.go:1207: 07:04:11.363 - 41: DEBUG: new connection from 127.0.0.1:52022 318s app_test.go:1207: 07:04:11.365 - 41: DEBUG: new connection from 127.0.0.1:52036 318s app_test.go:1207: 07:04:11.375 - 38: DEBUG: new connection from 127.0.0.1:38392 318s app_test.go:1207: 07:04:11.432 - 38: DEBUG: new connection from 127.0.0.1:38396 318s app_test.go:1207: 07:04:11.434 - 42: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.438 - 39: DEBUG: new connection from 127.0.0.1:49028 318s app_test.go:1207: 07:04:11.439 - 38: DEBUG: new connection from 127.0.0.1:38402 318s app_test.go:1207: 07:04:11.441 - 41: DEBUG: new connection from 127.0.0.1:52048 318s app_test.go:1207: 07:04:11.441 - 40: DEBUG: new connection from 127.0.0.1:59522 318s app_test.go:1207: 07:04:11.443 - 42: DEBUG: new connection from 127.0.0.1:53362 318s app_test.go:1207: 07:04:11.445 - 42: DEBUG: new connection from 127.0.0.1:53376 318s app_test.go:1207: 07:04:11.464 - 38: DEBUG: new connection from 127.0.0.1:38404 318s app_test.go:1207: 07:04:11.496 - 38: DEBUG: new connection from 127.0.0.1:38420 318s app_test.go:1207: 07:04:11.499 - 43: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.505 - 39: DEBUG: new connection from 127.0.0.1:49032 318s app_test.go:1207: 07:04:11.505 - 38: DEBUG: new connection from 127.0.0.1:38436 318s app_test.go:1207: 07:04:11.506 - 40: DEBUG: new connection from 127.0.0.1:59536 318s app_test.go:1207: 07:04:11.507 - 41: DEBUG: new connection from 127.0.0.1:52062 318s app_test.go:1207: 07:04:11.508 - 42: DEBUG: new connection from 127.0.0.1:53378 318s app_test.go:1207: 07:04:11.508 - 43: DEBUG: new connection from 127.0.0.1:39364 318s app_test.go:1207: 07:04:11.511 - 43: DEBUG: new connection from 127.0.0.1:39374 318s app_test.go:1207: 07:04:11.512 - 38: DEBUG: new connection from 127.0.0.1:38448 318s app_test.go:1207: 07:04:11.515 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.516 - 38: DEBUG: new connection from 127.0.0.1:38458 318s app_test.go:1207: 07:04:11.516 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:04:11.517 - 38: DEBUG: new connection from 127.0.0.1:38460 318s app_test.go:1207: 07:04:11.517 - 39: DEBUG: new connection from 127.0.0.1:49038 318s app_test.go:1207: 07:04:11.526 - 38: DEBUG: new connection from 127.0.0.1:38474 318s app_test.go:1207: 07:04:11.527 - 41: DEBUG: new connection from 127.0.0.1:52068 318s app_test.go:1207: 07:04:11.528 - 40: DEBUG: new connection from 127.0.0.1:59542 318s app_test.go:1207: 07:04:11.531 - 42: DEBUG: new connection from 127.0.0.1:53390 318s app_test.go:1207: 07:04:11.531 - 43: DEBUG: new connection from 127.0.0.1:39376 318s app_test.go:1207: 07:04:11.322 - 40: DEBUG: promoted 127.0.0.1:9006 from stand-by to voter 318s --- PASS: TestHandover_VoterHonorFailureDomain (3.27s) 318s === RUN TestHandover_SingleNode 318s --- PASS: TestHandover_SingleNode (0.06s) 318s === RUN TestHandover_GracefulShutdown 318s app_test.go:406: 07:04:11.687 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:406: 07:04:11.732 - 1: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:406: 07:04:11.788 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:406: 07:04:11.642 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:406: 07:04:11.645 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:406: 07:04:11.657 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 318s app_test.go:406: 07:04:11.658 - 0: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 318s app_test.go:406: 07:04:11.696 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 318s app_test.go:406: 07:04:11.696 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 318s app_test.go:406: 07:04:11.703 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 318s app_test.go:406: 07:04:11.703 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9003 318s app_test.go:406: 07:04:11.703 - 1: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 318s app_test.go:406: 07:04:11.732 - 2: WARN: attempt 1: server 127.0.0.1:9002: dial: dial tcp 127.0.0.1:9002: connect: connection refused 318s app_test.go:406: 07:04:11.732 - 2: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 318s app_test.go:406: 07:04:11.732 - 2: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 318s --- PASS: TestHandover_GracefulShutdown (2.14s) 318s === RUN TestHandover_StandBy 318s app_test.go:1207: 07:04:11.837 - 44: DEBUG: new connection from 127.0.0.1:49362 318s app_test.go:1207: 07:04:11.839 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.841 - 44: DEBUG: new connection from 127.0.0.1:49372 318s app_test.go:1207: 07:04:11.892 - 44: DEBUG: new connection from 127.0.0.1:49376 318s app_test.go:1207: 07:04:11.894 - 45: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.904 - 45: DEBUG: new connection from 127.0.0.1:53668 318s app_test.go:1207: 07:04:11.904 - 44: DEBUG: new connection from 127.0.0.1:49390 318s app_test.go:1207: 07:04:11.956 - 44: DEBUG: new connection from 127.0.0.1:49392 318s app_test.go:1207: 07:04:11.958 - 46: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.961 - 45: DEBUG: new connection from 127.0.0.1:53684 318s app_test.go:1207: 07:04:11.961 - 44: DEBUG: new connection from 127.0.0.1:49400 318s app_test.go:1207: 07:04:11.962 - 46: DEBUG: new connection from 127.0.0.1:47854 318s app_test.go:1207: 07:04:11.964 - 46: DEBUG: new connection from 127.0.0.1:47866 318s app_test.go:1207: 07:04:11.969 - 44: DEBUG: new connection from 127.0.0.1:49408 318s app_test.go:1207: 07:04:11.784 - 45: DEBUG: new connection from 127.0.0.1:53698 318s app_test.go:1207: 07:04:11.789 - 44: DEBUG: new connection from 127.0.0.1:49424 318s app_test.go:1207: 07:04:11.845 - 44: DEBUG: new connection from 127.0.0.1:49428 318s app_test.go:1207: 07:04:11.848 - 47: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.856 - 45: DEBUG: new connection from 127.0.0.1:53708 318s app_test.go:1207: 07:04:11.856 - 44: DEBUG: new connection from 127.0.0.1:49444 318s app_test.go:1207: 07:04:11.858 - 46: DEBUG: new connection from 127.0.0.1:47870 318s app_test.go:1207: 07:04:11.860 - 47: DEBUG: new connection from 127.0.0.1:48970 318s app_test.go:1207: 07:04:11.861 - 47: DEBUG: new connection from 127.0.0.1:48986 318s app_test.go:1207: 07:04:11.876 - 44: DEBUG: new connection from 127.0.0.1:49454 318s app_test.go:1207: 07:04:11.908 - 44: DEBUG: new connection from 127.0.0.1:49468 318s app_test.go:1207: 07:04:11.910 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.917 - 44: DEBUG: new connection from 127.0.0.1:49480 318s app_test.go:1207: 07:04:11.918 - 45: DEBUG: new connection from 127.0.0.1:53722 318s app_test.go:1207: 07:04:11.919 - 46: DEBUG: new connection from 127.0.0.1:47872 318s app_test.go:1207: 07:04:11.920 - 47: DEBUG: new connection from 127.0.0.1:48988 318s app_test.go:1207: 07:04:11.921 - 48: DEBUG: new connection from 127.0.0.1:53066 318s app_test.go:1207: 07:04:11.923 - 48: DEBUG: new connection from 127.0.0.1:53074 318s app_test.go:1207: 07:04:11.929 - 44: DEBUG: new connection from 127.0.0.1:49496 318s app_test.go:1207: 07:04:11.968 - 44: DEBUG: new connection from 127.0.0.1:49498 318s app_test.go:1207: 07:04:11.971 - 49: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:04:11.980 - 45: DEBUG: new connection from 127.0.0.1:53734 318s app_test.go:1207: 07:04:11.980 - 44: DEBUG: new connection from 127.0.0.1:49512 318s app_test.go:1207: 07:04:11.981 - 46: DEBUG: new connection from 127.0.0.1:47888 318s app_test.go:1207: 07:04:11.982 - 47: DEBUG: new connection from 127.0.0.1:48998 318s app_test.go:1207: 07:04:11.983 - 48: DEBUG: new connection from 127.0.0.1:53082 318s app_test.go:1207: 07:04:11.984 - 49: DEBUG: new connection from 127.0.0.1:45376 318s app_test.go:1207: 07:04:11.987 - 49: DEBUG: new connection from 127.0.0.1:45380 318s app_test.go:1207: 07:05:11.001 - 44: DEBUG: new connection from 127.0.0.1:49528 318s app_test.go:1207: 07:05:11.044 - 44: DEBUG: new connection from 127.0.0.1:49544 318s app_test.go:1207: 07:05:11.046 - 50: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.051 - 44: DEBUG: new connection from 127.0.0.1:49546 318s app_test.go:1207: 07:05:11.051 - 45: DEBUG: new connection from 127.0.0.1:53750 318s app_test.go:1207: 07:05:11.052 - 46: DEBUG: new connection from 127.0.0.1:47900 318s app_test.go:1207: 07:05:11.053 - 47: DEBUG: new connection from 127.0.0.1:49012 318s app_test.go:1207: 07:05:11.054 - 48: DEBUG: new connection from 127.0.0.1:53092 318s app_test.go:1207: 07:05:11.056 - 49: DEBUG: new connection from 127.0.0.1:45388 318s app_test.go:1207: 07:05:11.057 - 50: DEBUG: new connection from 127.0.0.1:46828 318s app_test.go:1207: 07:05:11.059 - 44: DEBUG: new connection from 127.0.0.1:49548 318s app_test.go:1207: 07:05:11.060 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.060 - 44: DEBUG: new connection from 127.0.0.1:49550 318s app_test.go:1207: 07:05:11.061 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.062 - 45: DEBUG: new connection from 127.0.0.1:53764 318s app_test.go:1207: 07:05:11.062 - 44: DEBUG: new connection from 127.0.0.1:49560 318s app_test.go:1207: 07:05:11.062 - 46: DEBUG: new connection from 127.0.0.1:47912 318s app_test.go:1207: 07:05:11.063 - 47: DEBUG: new connection from 127.0.0.1:49026 318s app_test.go:1207: 07:05:11.064 - 48: DEBUG: new connection from 127.0.0.1:53096 318s app_test.go:1207: 07:05:11.065 - 49: DEBUG: new connection from 127.0.0.1:45390 318s app_test.go:1207: 07:05:11.066 - 50: DEBUG: new connection from 127.0.0.1:46830 318s app_test.go:1207: 07:05:11.069 - 50: DEBUG: new connection from 127.0.0.1:46842 318s app_test.go:1207: 07:05:11.071 - 48: DEBUG: promoted 127.0.0.1:9007 from spare to stand-by 318s --- PASS: TestHandover_StandBy (2.41s) 318s === RUN TestHandover_TransferLeadership 318s app_test.go:1207: 07:05:11.228 - 51: DEBUG: new connection from 127.0.0.1:49568 318s app_test.go:1207: 07:05:11.230 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.231 - 51: DEBUG: new connection from 127.0.0.1:49574 318s app_test.go:1207: 07:05:11.272 - 51: DEBUG: new connection from 127.0.0.1:49576 318s app_test.go:1207: 07:05:11.273 - 52: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.281 - 51: DEBUG: new connection from 127.0.0.1:49580 318s app_test.go:1207: 07:05:11.281 - 52: DEBUG: new connection from 127.0.0.1:53772 318s app_test.go:1207: 07:05:11.324 - 51: DEBUG: new connection from 127.0.0.1:49584 318s app_test.go:1207: 07:05:11.325 - 53: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.330 - 51: DEBUG: new connection from 127.0.0.1:49598 318s app_test.go:1207: 07:05:11.330 - 52: DEBUG: new connection from 127.0.0.1:53786 318s app_test.go:1207: 07:05:11.334 - 53: DEBUG: new connection from 127.0.0.1:47918 318s app_test.go:1207: 07:05:11.336 - 53: DEBUG: new connection from 127.0.0.1:47928 318s app_test.go:1207: 07:05:11.343 - 51: DEBUG: new connection from 127.0.0.1:49606 318s app_test.go:1207: 07:05:11.193 - 52: DEBUG: new connection from 127.0.0.1:53800 318s app_test.go:1207: 07:05:11.197 - 51: DEBUG: new connection from 127.0.0.1:49616 318s app_test.go:1207: 07:05:11.240 - 51: DEBUG: new connection from 127.0.0.1:49618 318s app_test.go:1207: 07:05:11.242 - 54: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.246 - 51: DEBUG: new connection from 127.0.0.1:49630 318s app_test.go:1207: 07:05:11.247 - 52: DEBUG: new connection from 127.0.0.1:53816 318s app_test.go:1207: 07:05:11.248 - 53: DEBUG: new connection from 127.0.0.1:47936 318s app_test.go:1207: 07:05:11.252 - 54: DEBUG: new connection from 127.0.0.1:49032 318s app_test.go:1207: 07:05:11.254 - 54: DEBUG: new connection from 127.0.0.1:49038 318s app_test.go:1207: 07:05:11.256 - 51: DEBUG: new connection from 127.0.0.1:49642 318s app_test.go:1207: 07:05:11.257 - 51: DEBUG: new connection from 127.0.0.1:49650 318s app_test.go:1207: 07:05:11.258 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.259 - 51: DEBUG: new connection from 127.0.0.1:49658 318s app_test.go:1207: 07:05:11.259 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.260 - 51: DEBUG: new connection from 127.0.0.1:49668 318s app_test.go:1207: 07:05:11.260 - 52: DEBUG: new connection from 127.0.0.1:53820 318s app_test.go:1207: 07:05:11.261 - 53: DEBUG: new connection from 127.0.0.1:47944 318s app_test.go:1207: 07:05:11.263 - 54: DEBUG: new connection from 127.0.0.1:49050 318s app_test.go:1207: 07:05:11.195 - 51: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 318s app_test.go:1207: 07:05:11.195 - 51: DEBUG: new connection from 127.0.0.1:49676 318s app_test.go:1207: 07:05:11.196 - 52: DEBUG: new connection from 127.0.0.1:53836 318s app_test.go:1207: 07:05:11.199 - 53: DEBUG: new connection from 127.0.0.1:47946 318s app_test.go:1207: 07:05:11.199 - 54: DEBUG: new connection from 127.0.0.1:49054 318s app_test.go:1207: 07:05:11.202 - 53: DEBUG: new connection from 127.0.0.1:47962 318s app_test.go:1207: 07:05:11.203 - 54: DEBUG: new connection from 127.0.0.1:49060 318s app_test.go:1207: 07:05:11.209 - 52: DEBUG: new connection from 127.0.0.1:53850 318s app_test.go:1207: 07:05:11.211 - 52: DEBUG: new connection from 127.0.0.1:53860 318s app_test.go:1207: 07:05:11.212 - 51: DEBUG: new connection from 127.0.0.1:49680 318s app_test.go:1207: 07:05:11.214 - 51: DEBUG: new connection from 127.0.0.1:49696 318s app_test.go:1207: 07:05:11.215 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 318s app_test.go:1207: 07:05:11.216 - 52: DEBUG: new connection from 127.0.0.1:53868 318s app_test.go:1207: 07:05:11.217 - 51: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 318s app_test.go:1207: 07:05:11.218 - 51: DEBUG: new connection from 127.0.0.1:49706 318s app_test.go:1207: 07:05:11.220 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 318s app_test.go:1207: 07:05:11.220 - 52: DEBUG: new connection from 127.0.0.1:53872 318s app_test.go:1207: 07:05:11.221 - 51: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 318s --- PASS: TestHandover_TransferLeadership (3.20s) 318s === RUN TestRolesAdjustment_ReplaceVoter 318s app_test.go:1207: 07:05:11.434 - 55: DEBUG: new connection from 127.0.0.1:49710 318s app_test.go:1207: 07:05:11.435 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.437 - 55: DEBUG: new connection from 127.0.0.1:49724 318s app_test.go:1207: 07:05:11.488 - 55: DEBUG: new connection from 127.0.0.1:49726 318s app_test.go:1207: 07:05:11.489 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.497 - 55: DEBUG: new connection from 127.0.0.1:49738 318s app_test.go:1207: 07:05:11.497 - 56: DEBUG: new connection from 127.0.0.1:53874 318s app_test.go:1207: 07:05:11.540 - 55: DEBUG: new connection from 127.0.0.1:49740 318s app_test.go:1207: 07:05:11.542 - 57: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.547 - 56: DEBUG: new connection from 127.0.0.1:53880 318s app_test.go:1207: 07:05:11.547 - 55: DEBUG: new connection from 127.0.0.1:49752 318s app_test.go:1207: 07:05:11.548 - 57: DEBUG: new connection from 127.0.0.1:47972 318s app_test.go:1207: 07:05:11.550 - 57: DEBUG: new connection from 127.0.0.1:47988 318s app_test.go:1207: 07:05:11.557 - 55: DEBUG: new connection from 127.0.0.1:49766 318s app_test.go:1207: 07:05:11.400 - 56: DEBUG: new connection from 127.0.0.1:53890 318s app_test.go:1207: 07:05:11.407 - 55: DEBUG: new connection from 127.0.0.1:49772 318s app_test.go:1207: 07:05:11.438 - 55: DEBUG: new connection from 127.0.0.1:51016 318s app_test.go:1207: 07:05:11.440 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.443 - 56: DEBUG: new connection from 127.0.0.1:41556 318s app_test.go:1207: 07:05:11.443 - 55: DEBUG: new connection from 127.0.0.1:51020 318s app_test.go:1207: 07:05:11.444 - 57: DEBUG: new connection from 127.0.0.1:51898 318s app_test.go:1207: 07:05:11.445 - 55: DEBUG: new connection from 127.0.0.1:51022 318s app_test.go:1207: 07:05:11.448 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.450 - 56: DEBUG: new connection from 127.0.0.1:41570 318s app_test.go:1207: 07:05:11.450 - 55: DEBUG: new connection from 127.0.0.1:51032 318s app_test.go:1207: 07:05:11.452 - 57: DEBUG: new connection from 127.0.0.1:51914 318s app_test.go:1207: 07:05:11.452 - 58: DEBUG: new connection from 127.0.0.1:32896 318s app_test.go:1207: 07:05:11.459 - 58: DEBUG: new connection from 127.0.0.1:32898 318s app_test.go:1207: 07:05:11.486 - 55: DEBUG: new connection from 127.0.0.1:51038 318s app_test.go:1207: 07:05:11.499 - 55: DEBUG: new connection from 127.0.0.1:51046 318s app_test.go:1207: 07:05:11.500 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.447 - 55: DEBUG: new connection from 127.0.0.1:51062 318s app_test.go:1207: 07:05:11.449 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.453 - 56: DEBUG: new connection from 127.0.0.1:41572 318s app_test.go:1207: 07:05:11.453 - 55: DEBUG: new connection from 127.0.0.1:51078 318s app_test.go:1207: 07:05:11.455 - 58: DEBUG: new connection from 127.0.0.1:32908 318s app_test.go:1207: 07:05:11.459 - 56: DEBUG: new connection from 127.0.0.1:41584 318s app_test.go:1207: 07:05:11.459 - 55: DEBUG: new connection from 127.0.0.1:51094 318s app_test.go:1207: 07:05:11.461 - 58: DEBUG: new connection from 127.0.0.1:32924 318s app_test.go:1207: 07:05:11.465 - 56: DEBUG: new connection from 127.0.0.1:41590 318s app_test.go:1207: 07:05:11.465 - 55: DEBUG: new connection from 127.0.0.1:51096 318s app_test.go:1207: 07:05:11.466 - 58: DEBUG: new connection from 127.0.0.1:32936 318s app_test.go:1207: 07:05:11.482 - 55: DEBUG: new connection from 127.0.0.1:51108 318s app_test.go:1207: 07:05:11.482 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.505 - 55: DEBUG: new connection from 127.0.0.1:51124 318s app_test.go:1207: 07:05:11.507 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.469 - 55: DEBUG: new connection from 127.0.0.1:51140 318s app_test.go:1207: 07:05:11.471 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.472 - 55: DEBUG: new connection from 127.0.0.1:51150 318s app_test.go:1207: 07:05:11.472 - 56: DEBUG: new connection from 127.0.0.1:41596 318s app_test.go:1207: 07:05:11.474 - 58: DEBUG: new connection from 127.0.0.1:32938 318s app_test.go:1207: 07:05:11.486 - 55: DEBUG: new connection from 127.0.0.1:51156 318s app_test.go:1207: 07:05:11.487 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.510 - 55: DEBUG: new connection from 127.0.0.1:51164 318s app_test.go:1207: 07:05:11.511 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.476 - 55: DEBUG: new connection from 127.0.0.1:51178 318s app_test.go:1207: 07:05:11.478 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.483 - 56: DEBUG: new connection from 127.0.0.1:41600 318s app_test.go:1207: 07:05:11.483 - 55: DEBUG: new connection from 127.0.0.1:51180 318s app_test.go:1207: 07:05:11.484 - 58: DEBUG: new connection from 127.0.0.1:32954 318s app_test.go:1207: 07:05:11.489 - 55: DEBUG: new connection from 127.0.0.1:51196 318s app_test.go:1207: 07:05:11.490 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.514 - 55: DEBUG: new connection from 127.0.0.1:51198 318s app_test.go:1207: 07:05:11.515 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.487 - 55: DEBUG: new connection from 127.0.0.1:51204 318s app_test.go:1207: 07:05:11.489 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.491 - 55: DEBUG: new connection from 127.0.0.1:51218 318s app_test.go:1207: 07:05:11.491 - 56: DEBUG: new connection from 127.0.0.1:41610 318s app_test.go:1207: 07:05:11.492 - 58: DEBUG: new connection from 127.0.0.1:32960 318s app_test.go:1207: 07:05:11.492 - 55: DEBUG: new connection from 127.0.0.1:51224 318s app_test.go:1207: 07:05:11.493 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.519 - 55: DEBUG: new connection from 127.0.0.1:51230 318s app_test.go:1207: 07:05:11.519 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.521 - 58: DEBUG: new connection from 127.0.0.1:32966 318s app_test.go:1207: 07:05:11.521 - 56: DEBUG: new connection from 127.0.0.1:41626 318s app_test.go:1207: 07:05:11.521 - 55: DEBUG: new connection from 127.0.0.1:51234 318s app_test.go:1207: 07:05:11.521 - 55: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 318s app_test.go:1207: 07:05:11.524 - 55: DEBUG: attempt 1: server 127.0.0.1:9004: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:05:11.524 - 55: DEBUG: new connection from 127.0.0.1:51248 318s app_test.go:1207: 07:05:11.525 - 55: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:05:11.525 - 55: DEBUG: new connection from 127.0.0.1:51250 318s app_test.go:1207: 07:05:11.526 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s --- PASS: TestRolesAdjustment_ReplaceVoter (10.25s) 318s === RUN TestRolesAdjustment_ReplaceVoterHonorFailureDomain 318s app_test.go:1207: 07:05:11.696 - 59: DEBUG: new connection from 127.0.0.1:51262 318s app_test.go:1207: 07:05:11.698 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.700 - 59: DEBUG: new connection from 127.0.0.1:51276 318s app_test.go:1207: 07:05:11.756 - 59: DEBUG: new connection from 127.0.0.1:51282 318s app_test.go:1207: 07:05:11.758 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.764 - 59: DEBUG: new connection from 127.0.0.1:51294 318s app_test.go:1207: 07:05:11.764 - 60: DEBUG: new connection from 127.0.0.1:41642 318s app_test.go:1207: 07:05:11.812 - 59: DEBUG: new connection from 127.0.0.1:51308 318s app_test.go:1207: 07:05:11.814 - 61: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.817 - 59: DEBUG: new connection from 127.0.0.1:51322 318s app_test.go:1207: 07:05:11.819 - 61: DEBUG: new connection from 127.0.0.1:52064 318s app_test.go:1207: 07:05:11.819 - 60: DEBUG: new connection from 127.0.0.1:41646 318s app_test.go:1207: 07:05:11.821 - 61: DEBUG: new connection from 127.0.0.1:52078 318s app_test.go:1207: 07:05:11.824 - 59: DEBUG: new connection from 127.0.0.1:51326 318s app_test.go:1207: 07:05:11.646 - 60: DEBUG: new connection from 127.0.0.1:41648 318s app_test.go:1207: 07:05:11.651 - 59: DEBUG: new connection from 127.0.0.1:51332 318s app_test.go:1207: 07:05:11.692 - 59: DEBUG: new connection from 127.0.0.1:46020 318s app_test.go:1207: 07:05:11.694 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.701 - 59: DEBUG: new connection from 127.0.0.1:46028 318s app_test.go:1207: 07:05:11.701 - 60: DEBUG: new connection from 127.0.0.1:49308 318s app_test.go:1207: 07:05:11.702 - 61: DEBUG: new connection from 127.0.0.1:55828 318s app_test.go:1207: 07:05:11.704 - 62: DEBUG: new connection from 127.0.0.1:37752 318s app_test.go:1207: 07:05:11.708 - 62: DEBUG: new connection from 127.0.0.1:37764 318s app_test.go:1207: 07:05:11.723 - 59: DEBUG: new connection from 127.0.0.1:46034 318s app_test.go:1207: 07:05:11.764 - 59: DEBUG: new connection from 127.0.0.1:46048 318s app_test.go:1207: 07:05:11.766 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.769 - 59: DEBUG: new connection from 127.0.0.1:46062 318s app_test.go:1207: 07:05:11.769 - 60: DEBUG: new connection from 127.0.0.1:49318 318s app_test.go:1207: 07:05:11.770 - 61: DEBUG: new connection from 127.0.0.1:55838 318s app_test.go:1207: 07:05:11.772 - 62: DEBUG: new connection from 127.0.0.1:37776 318s app_test.go:1207: 07:05:11.772 - 63: DEBUG: new connection from 127.0.0.1:35270 318s app_test.go:1207: 07:05:11.774 - 63: DEBUG: new connection from 127.0.0.1:35282 318s app_test.go:1207: 07:05:11.785 - 59: DEBUG: new connection from 127.0.0.1:46074 318s app_test.go:1207: 07:05:11.821 - 59: DEBUG: new connection from 127.0.0.1:46090 318s app_test.go:1207: 07:05:11.823 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.826 - 59: DEBUG: new connection from 127.0.0.1:46094 318s app_test.go:1207: 07:05:11.827 - 60: DEBUG: new connection from 127.0.0.1:49332 318s app_test.go:1207: 07:05:11.827 - 61: DEBUG: new connection from 127.0.0.1:55842 318s app_test.go:1207: 07:05:11.829 - 62: DEBUG: new connection from 127.0.0.1:37784 318s app_test.go:1207: 07:05:11.830 - 63: DEBUG: new connection from 127.0.0.1:35298 318s app_test.go:1207: 07:05:11.831 - 64: DEBUG: new connection from 127.0.0.1:57196 318s app_test.go:1207: 07:05:11.834 - 64: DEBUG: new connection from 127.0.0.1:57212 318s app_test.go:1207: 07:05:11.838 - 59: DEBUG: new connection from 127.0.0.1:46098 318s app_test.go:1207: 07:05:11.702 - 59: DEBUG: new connection from 127.0.0.1:46114 318s app_test.go:1207: 07:05:11.704 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.710 - 60: DEBUG: new connection from 127.0.0.1:49344 318s app_test.go:1207: 07:05:11.710 - 59: DEBUG: new connection from 127.0.0.1:46120 318s app_test.go:1207: 07:05:11.711 - 62: DEBUG: new connection from 127.0.0.1:37788 318s app_test.go:1207: 07:05:11.712 - 63: DEBUG: new connection from 127.0.0.1:35302 318s app_test.go:1207: 07:05:11.713 - 64: DEBUG: new connection from 127.0.0.1:57218 318s app_test.go:1207: 07:05:11.721 - 60: DEBUG: new connection from 127.0.0.1:49354 318s app_test.go:1207: 07:05:11.721 - 59: DEBUG: new connection from 127.0.0.1:46132 318s app_test.go:1207: 07:05:11.723 - 62: DEBUG: new connection from 127.0.0.1:37798 318s app_test.go:1207: 07:05:11.723 - 63: DEBUG: new connection from 127.0.0.1:35310 318s app_test.go:1207: 07:05:11.725 - 64: DEBUG: new connection from 127.0.0.1:57228 318s app_test.go:1207: 07:05:11.728 - 60: DEBUG: new connection from 127.0.0.1:49356 318s app_test.go:1207: 07:05:11.728 - 59: DEBUG: new connection from 127.0.0.1:46134 318s app_test.go:1207: 07:05:11.730 - 62: DEBUG: new connection from 127.0.0.1:37812 318s app_test.go:1207: 07:05:11.730 - 63: DEBUG: new connection from 127.0.0.1:35318 318s app_test.go:1207: 07:05:11.732 - 64: DEBUG: new connection from 127.0.0.1:57242 318s app_test.go:1207: 07:05:11.767 - 59: DEBUG: new connection from 127.0.0.1:46140 318s app_test.go:1207: 07:05:11.768 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.711 - 59: DEBUG: new connection from 127.0.0.1:46154 318s app_test.go:1207: 07:05:11.714 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.776 - 59: DEBUG: new connection from 127.0.0.1:46164 318s app_test.go:1207: 07:05:11.779 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.836 - 59: DEBUG: new connection from 127.0.0.1:46178 318s app_test.go:1207: 07:05:11.837 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.735 - 59: DEBUG: new connection from 127.0.0.1:46186 318s app_test.go:1207: 07:05:11.738 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.744 - 60: DEBUG: new connection from 127.0.0.1:49364 318s app_test.go:1207: 07:05:11.744 - 59: DEBUG: new connection from 127.0.0.1:46200 318s app_test.go:1207: 07:05:11.745 - 62: DEBUG: new connection from 127.0.0.1:37826 318s app_test.go:1207: 07:05:11.746 - 63: DEBUG: new connection from 127.0.0.1:35332 318s app_test.go:1207: 07:05:11.747 - 64: DEBUG: new connection from 127.0.0.1:57244 318s app_test.go:1207: 07:05:11.778 - 59: DEBUG: new connection from 127.0.0.1:46208 318s app_test.go:1207: 07:05:11.780 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.723 - 59: DEBUG: new connection from 127.0.0.1:46210 318s app_test.go:1207: 07:05:11.724 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.786 - 59: DEBUG: new connection from 127.0.0.1:46218 318s app_test.go:1207: 07:05:11.788 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.842 - 59: DEBUG: new connection from 127.0.0.1:46220 318s app_test.go:1207: 07:05:11.843 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.752 - 59: DEBUG: new connection from 127.0.0.1:33354 318s app_test.go:1207: 07:05:11.755 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.757 - 59: DEBUG: new connection from 127.0.0.1:33368 318s app_test.go:1207: 07:05:11.757 - 60: DEBUG: new connection from 127.0.0.1:46626 318s app_test.go:1207: 07:05:11.758 - 62: DEBUG: new connection from 127.0.0.1:51528 318s app_test.go:1207: 07:05:11.759 - 63: DEBUG: new connection from 127.0.0.1:51982 318s app_test.go:1207: 07:05:11.760 - 64: DEBUG: new connection from 127.0.0.1:43986 318s app_test.go:1207: 07:05:11.785 - 59: DEBUG: new connection from 127.0.0.1:33378 318s app_test.go:1207: 07:05:11.787 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.731 - 59: DEBUG: new connection from 127.0.0.1:33394 318s app_test.go:1207: 07:05:11.732 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.791 - 59: DEBUG: new connection from 127.0.0.1:33400 318s app_test.go:1207: 07:05:11.792 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.846 - 59: DEBUG: new connection from 127.0.0.1:33412 318s app_test.go:1207: 07:05:11.848 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.763 - 59: DEBUG: new connection from 127.0.0.1:33420 318s app_test.go:1207: 07:05:11.766 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.772 - 59: DEBUG: new connection from 127.0.0.1:33434 318s app_test.go:1207: 07:05:11.772 - 60: DEBUG: new connection from 127.0.0.1:46634 318s app_test.go:1207: 07:05:11.774 - 62: DEBUG: new connection from 127.0.0.1:51536 318s app_test.go:1207: 07:05:11.775 - 63: DEBUG: new connection from 127.0.0.1:51986 318s app_test.go:1207: 07:05:11.776 - 64: DEBUG: new connection from 127.0.0.1:43988 318s app_test.go:1207: 07:05:11.790 - 59: DEBUG: new connection from 127.0.0.1:33442 318s app_test.go:1207: 07:05:11.790 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.735 - 59: DEBUG: new connection from 127.0.0.1:33446 318s app_test.go:1207: 07:05:11.736 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.797 - 59: DEBUG: new connection from 127.0.0.1:33458 318s app_test.go:1207: 07:05:11.798 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.853 - 59: DEBUG: new connection from 127.0.0.1:33464 318s app_test.go:1207: 07:05:11.854 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.780 - 59: DEBUG: new connection from 127.0.0.1:33476 318s app_test.go:1207: 07:05:11.783 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.786 - 60: DEBUG: new connection from 127.0.0.1:46644 318s app_test.go:1207: 07:05:11.787 - 59: DEBUG: new connection from 127.0.0.1:33486 318s app_test.go:1207: 07:05:11.788 - 62: DEBUG: new connection from 127.0.0.1:51548 318s app_test.go:1207: 07:05:11.790 - 63: DEBUG: new connection from 127.0.0.1:51990 318s app_test.go:1207: 07:05:11.792 - 64: DEBUG: new connection from 127.0.0.1:43990 318s app_test.go:1207: 07:05:11.794 - 59: DEBUG: new connection from 127.0.0.1:33494 318s app_test.go:1207: 07:05:11.795 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.873 - 59: DEBUG: new connection from 127.0.0.1:33498 318s app_test.go:1207: 07:05:11.873 - 59: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 318s app_test.go:1207: 07:05:11.873 - 60: DEBUG: new connection from 127.0.0.1:46656 318s app_test.go:1207: 07:05:11.873 - 63: DEBUG: new connection from 127.0.0.1:51994 318s app_test.go:1207: 07:05:11.874 - 62: DEBUG: new connection from 127.0.0.1:51554 318s app_test.go:1207: 07:05:11.874 - 64: DEBUG: new connection from 127.0.0.1:43992 318s app_test.go:1207: 07:05:11.876 - 59: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:05:11.876 - 59: DEBUG: new connection from 127.0.0.1:33508 318s app_test.go:1207: 07:05:11.876 - 59: DEBUG: attempt 1: server 127.0.0.1:9006: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:05:11.878 - 59: WARN: attempt 1: server 127.0.0.1:9005: no known leader 318s app_test.go:1207: 07:05:11.878 - 59: DEBUG: new connection from 127.0.0.1:33524 318s app_test.go:1207: 07:05:11.878 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.878 - 59: WARN: attempt 1: server 127.0.0.1:9004: no known leader 318s --- PASS: TestRolesAdjustment_ReplaceVoterHonorFailureDomain (20.42s) 318s === RUN TestRolesAdjustment_ImbalancedFailureDomain 318s app_test.go:1207: 07:05:11.100 - 65: DEBUG: new connection from 127.0.0.1:33526 318s app_test.go:1207: 07:05:11.104 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.106 - 65: DEBUG: new connection from 127.0.0.1:33534 318s app_test.go:1207: 07:05:11.168 - 65: DEBUG: new connection from 127.0.0.1:33550 318s app_test.go:1207: 07:05:11.170 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.184 - 66: DEBUG: new connection from 127.0.0.1:46666 318s app_test.go:1207: 07:05:11.184 - 65: DEBUG: new connection from 127.0.0.1:33566 318s app_test.go:1207: 07:05:11.216 - 65: DEBUG: new connection from 127.0.0.1:33572 318s app_test.go:1207: 07:05:11.218 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.221 - 65: DEBUG: new connection from 127.0.0.1:33586 318s app_test.go:1207: 07:05:11.222 - 66: DEBUG: new connection from 127.0.0.1:46676 318s app_test.go:1207: 07:05:11.222 - 67: DEBUG: new connection from 127.0.0.1:43906 318s app_test.go:1207: 07:05:11.225 - 67: DEBUG: new connection from 127.0.0.1:43910 318s app_test.go:1207: 07:05:11.233 - 65: DEBUG: new connection from 127.0.0.1:33594 318s app_test.go:1207: 07:05:11.081 - 66: DEBUG: new connection from 127.0.0.1:48030 318s app_test.go:1207: 07:05:11.095 - 65: DEBUG: new connection from 127.0.0.1:48574 318s app_test.go:1207: 07:05:11.104 - 65: DEBUG: new connection from 127.0.0.1:48584 318s app_test.go:1207: 07:05:11.106 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.109 - 65: DEBUG: new connection from 127.0.0.1:48592 318s app_test.go:1207: 07:05:11.109 - 66: DEBUG: new connection from 127.0.0.1:48042 318s app_test.go:1207: 07:05:11.110 - 67: DEBUG: new connection from 127.0.0.1:51672 318s app_test.go:1207: 07:05:11.111 - 68: DEBUG: new connection from 127.0.0.1:35780 318s app_test.go:1207: 07:05:11.113 - 68: DEBUG: new connection from 127.0.0.1:35784 318s app_test.go:1207: 07:05:11.117 - 65: DEBUG: new connection from 127.0.0.1:48598 318s app_test.go:1207: 07:05:11.160 - 65: DEBUG: new connection from 127.0.0.1:48600 318s app_test.go:1207: 07:05:11.162 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.165 - 65: DEBUG: new connection from 127.0.0.1:48614 318s app_test.go:1207: 07:05:11.165 - 66: DEBUG: new connection from 127.0.0.1:48056 318s app_test.go:1207: 07:05:11.168 - 67: DEBUG: new connection from 127.0.0.1:51688 318s app_test.go:1207: 07:05:11.168 - 68: DEBUG: new connection from 127.0.0.1:35790 318s app_test.go:1207: 07:05:11.170 - 69: DEBUG: new connection from 127.0.0.1:50244 318s app_test.go:1207: 07:05:11.172 - 69: DEBUG: new connection from 127.0.0.1:50258 318s app_test.go:1207: 07:05:11.175 - 65: DEBUG: new connection from 127.0.0.1:48624 318s app_test.go:1207: 07:05:11.216 - 65: DEBUG: new connection from 127.0.0.1:48638 318s app_test.go:1207: 07:05:11.218 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.221 - 66: DEBUG: new connection from 127.0.0.1:48058 318s app_test.go:1207: 07:05:11.221 - 65: DEBUG: new connection from 127.0.0.1:48648 318s app_test.go:1207: 07:05:11.224 - 67: DEBUG: new connection from 127.0.0.1:51700 318s app_test.go:1207: 07:05:11.225 - 68: DEBUG: new connection from 127.0.0.1:35796 318s app_test.go:1207: 07:05:11.226 - 69: DEBUG: new connection from 127.0.0.1:50260 318s app_test.go:1207: 07:05:11.226 - 70: DEBUG: new connection from 127.0.0.1:42670 318s app_test.go:1207: 07:05:11.228 - 70: DEBUG: new connection from 127.0.0.1:42684 318s app_test.go:1207: 07:05:11.233 - 65: DEBUG: new connection from 127.0.0.1:48658 318s app_test.go:1207: 07:05:11.276 - 65: DEBUG: new connection from 127.0.0.1:48660 318s app_test.go:1207: 07:05:11.278 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.282 - 65: DEBUG: new connection from 127.0.0.1:48664 318s app_test.go:1207: 07:05:11.282 - 66: DEBUG: new connection from 127.0.0.1:48064 318s app_test.go:1207: 07:05:11.283 - 67: DEBUG: new connection from 127.0.0.1:51710 318s app_test.go:1207: 07:05:11.286 - 68: DEBUG: new connection from 127.0.0.1:35800 318s app_test.go:1207: 07:05:11.286 - 69: DEBUG: new connection from 127.0.0.1:50274 318s app_test.go:1207: 07:05:11.288 - 70: DEBUG: new connection from 127.0.0.1:42694 318s app_test.go:1207: 07:05:11.288 - 71: DEBUG: new connection from 127.0.0.1:44268 318s app_test.go:1207: 07:05:11.328 - 65: DEBUG: new connection from 127.0.0.1:48676 318s app_test.go:1207: 07:05:11.330 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.334 - 66: DEBUG: new connection from 127.0.0.1:48072 318s app_test.go:1207: 07:05:11.334 - 65: DEBUG: new connection from 127.0.0.1:48680 318s app_test.go:1207: 07:05:11.336 - 67: DEBUG: new connection from 127.0.0.1:51718 318s app_test.go:1207: 07:05:11.337 - 68: DEBUG: new connection from 127.0.0.1:35808 318s app_test.go:1207: 07:05:11.338 - 69: DEBUG: new connection from 127.0.0.1:50284 318s app_test.go:1207: 07:05:11.340 - 71: DEBUG: new connection from 127.0.0.1:44272 318s app_test.go:1207: 07:05:11.340 - 70: DEBUG: new connection from 127.0.0.1:42706 318s app_test.go:1207: 07:05:11.342 - 72: DEBUG: new connection from 127.0.0.1:40000 318s app_test.go:1207: 07:05:11.108 - 65: DEBUG: new connection from 127.0.0.1:48688 318s app_test.go:1207: 07:05:11.111 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.117 - 66: DEBUG: new connection from 127.0.0.1:48084 318s app_test.go:1207: 07:05:11.117 - 65: DEBUG: new connection from 127.0.0.1:48702 318s app_test.go:1207: 07:05:11.118 - 67: DEBUG: new connection from 127.0.0.1:51726 318s app_test.go:1207: 07:05:11.119 - 68: DEBUG: new connection from 127.0.0.1:35818 318s app_test.go:1207: 07:05:11.120 - 69: DEBUG: new connection from 127.0.0.1:50290 318s app_test.go:1207: 07:05:11.122 - 70: DEBUG: new connection from 127.0.0.1:42714 318s app_test.go:1207: 07:05:11.123 - 71: DEBUG: new connection from 127.0.0.1:44282 318s app_test.go:1207: 07:05:11.124 - 72: DEBUG: new connection from 127.0.0.1:40008 318s app_test.go:1207: 07:05:11.126 - 72: DEBUG: new connection from 127.0.0.1:40022 318s app_test.go:1207: 07:05:11.131 - 65: DEBUG: new connection from 127.0.0.1:48710 318s app_test.go:1207: 07:05:11.186 - 65: DEBUG: new connection from 127.0.0.1:48722 318s app_test.go:1207: 07:05:11.190 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.068 - 66: DEBUG: new connection from 127.0.0.1:48086 318s app_test.go:1207: 07:05:11.068 - 65: DEBUG: new connection from 127.0.0.1:48732 318s app_test.go:1207: 07:05:11.071 - 67: DEBUG: new connection from 127.0.0.1:51742 318s app_test.go:1207: 07:05:11.072 - 68: DEBUG: new connection from 127.0.0.1:35832 318s app_test.go:1207: 07:05:11.073 - 69: DEBUG: new connection from 127.0.0.1:50294 318s app_test.go:1207: 07:05:11.074 - 70: DEBUG: new connection from 127.0.0.1:42730 318s app_test.go:1207: 07:05:11.075 - 71: DEBUG: new connection from 127.0.0.1:44290 318s app_test.go:1207: 07:05:11.076 - 72: DEBUG: new connection from 127.0.0.1:40036 318s app_test.go:1207: 07:05:11.080 - 65: DEBUG: new connection from 127.0.0.1:48736 318s app_test.go:1207: 07:05:11.080 - 66: DEBUG: new connection from 127.0.0.1:48094 318s app_test.go:1207: 07:05:11.083 - 67: DEBUG: new connection from 127.0.0.1:51758 318s app_test.go:1207: 07:05:11.083 - 68: DEBUG: new connection from 127.0.0.1:35846 318s app_test.go:1207: 07:05:11.085 - 69: DEBUG: new connection from 127.0.0.1:50296 318s app_test.go:1207: 07:05:11.085 - 70: DEBUG: new connection from 127.0.0.1:42746 318s app_test.go:1207: 07:05:11.087 - 71: DEBUG: new connection from 127.0.0.1:44300 318s app_test.go:1207: 07:05:11.087 - 72: DEBUG: new connection from 127.0.0.1:40046 318s app_test.go:1207: 07:05:11.067 - 65: DEBUG: new connection from 127.0.0.1:48742 318s app_test.go:1207: 07:05:11.068 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.114 - 65: DEBUG: new connection from 127.0.0.1:48744 318s app_test.go:1207: 07:05:11.115 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.174 - 65: DEBUG: new connection from 127.0.0.1:48750 318s app_test.go:1207: 07:05:11.175 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.229 - 65: DEBUG: new connection from 127.0.0.1:48752 318s app_test.go:1207: 07:05:11.231 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.290 - 65: DEBUG: new connection from 127.0.0.1:48760 318s app_test.go:1207: 07:05:11.292 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.345 - 65: DEBUG: new connection from 127.0.0.1:48776 318s app_test.go:1207: 07:05:11.346 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.196 - 65: DEBUG: new connection from 127.0.0.1:48778 318s app_test.go:1207: 07:05:11.197 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.090 - 65: DEBUG: new connection from 127.0.0.1:48790 318s app_test.go:1207: 07:05:11.092 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.096 - 66: DEBUG: new connection from 127.0.0.1:48110 318s app_test.go:1207: 07:05:11.096 - 65: DEBUG: new connection from 127.0.0.1:48798 318s app_test.go:1207: 07:05:11.097 - 67: DEBUG: new connection from 127.0.0.1:51762 318s app_test.go:1207: 07:05:11.098 - 68: DEBUG: new connection from 127.0.0.1:35858 318s app_test.go:1207: 07:05:11.099 - 69: DEBUG: new connection from 127.0.0.1:50310 318s app_test.go:1207: 07:05:11.101 - 70: DEBUG: new connection from 127.0.0.1:42752 318s app_test.go:1207: 07:05:11.101 - 71: DEBUG: new connection from 127.0.0.1:44302 318s app_test.go:1207: 07:05:11.104 - 72: DEBUG: new connection from 127.0.0.1:40060 318s app_test.go:1207: 07:05:11.072 - 65: DEBUG: new connection from 127.0.0.1:48808 318s app_test.go:1207: 07:05:11.074 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.119 - 67: DEBUG: new connection from 127.0.0.1:51770 318s app_test.go:1207: 07:05:11.119 - 65: DEBUG: new connection from 127.0.0.1:48818 318s app_test.go:1207: 07:05:11.120 - 72: DEBUG: new connection from 127.0.0.1:40076 318s app_test.go:1207: 07:05:11.122 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.122 - 66: DEBUG: new connection from 127.0.0.1:48122 318s app_test.go:1207: 07:05:11.123 - 66: DEBUG: new connection from 127.0.0.1:48126 318s app_test.go:1207: 07:05:11.178 - 65: DEBUG: new connection from 127.0.0.1:48824 318s app_test.go:1207: 07:05:11.179 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.241 - 65: DEBUG: new connection from 127.0.0.1:48840 318s app_test.go:1207: 07:05:11.243 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.295 - 65: DEBUG: new connection from 127.0.0.1:48856 318s app_test.go:1207: 07:05:11.296 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.349 - 65: DEBUG: new connection from 127.0.0.1:48868 318s app_test.go:1207: 07:05:11.351 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.203 - 65: DEBUG: new connection from 127.0.0.1:52016 318s app_test.go:1207: 07:05:11.205 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.107 - 65: DEBUG: new connection from 127.0.0.1:52018 318s app_test.go:1207: 07:05:11.109 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.112 - 65: DEBUG: new connection from 127.0.0.1:52030 318s app_test.go:1207: 07:05:11.112 - 66: DEBUG: new connection from 127.0.0.1:51498 318s app_test.go:1207: 07:05:11.113 - 67: DEBUG: new connection from 127.0.0.1:54348 318s app_test.go:1207: 07:05:11.114 - 68: DEBUG: new connection from 127.0.0.1:41738 318s app_test.go:1207: 07:05:11.115 - 69: DEBUG: new connection from 127.0.0.1:43496 318s app_test.go:1207: 07:05:11.115 - 70: DEBUG: new connection from 127.0.0.1:52684 318s app_test.go:1207: 07:05:11.117 - 71: DEBUG: new connection from 127.0.0.1:42504 318s app_test.go:1207: 07:05:11.117 - 72: DEBUG: new connection from 127.0.0.1:48836 318s app_test.go:1207: 07:05:11.078 - 65: DEBUG: new connection from 127.0.0.1:52044 318s app_test.go:1207: 07:05:11.081 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.130 - 65: DEBUG: new connection from 127.0.0.1:52054 318s app_test.go:1207: 07:05:11.131 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.186 - 65: DEBUG: new connection from 127.0.0.1:52068 318s app_test.go:1207: 07:05:11.186 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.248 - 65: DEBUG: new connection from 127.0.0.1:52084 318s app_test.go:1207: 07:05:11.249 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.303 - 65: DEBUG: new connection from 127.0.0.1:52096 318s app_test.go:1207: 07:05:11.304 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.355 - 65: DEBUG: new connection from 127.0.0.1:52102 318s app_test.go:1207: 07:05:11.356 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.209 - 65: DEBUG: new connection from 127.0.0.1:52106 318s app_test.go:1207: 07:05:11.210 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.119 - 65: DEBUG: new connection from 127.0.0.1:52122 318s app_test.go:1207: 07:05:11.122 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.130 - 66: DEBUG: new connection from 127.0.0.1:51502 318s app_test.go:1207: 07:05:11.130 - 65: DEBUG: new connection from 127.0.0.1:52134 318s app_test.go:1207: 07:05:11.131 - 67: DEBUG: new connection from 127.0.0.1:54360 318s app_test.go:1207: 07:05:11.132 - 68: DEBUG: new connection from 127.0.0.1:41740 318s app_test.go:1207: 07:05:11.133 - 69: DEBUG: new connection from 127.0.0.1:43506 318s app_test.go:1207: 07:05:11.133 - 70: DEBUG: new connection from 127.0.0.1:52692 318s app_test.go:1207: 07:05:11.134 - 71: DEBUG: new connection from 127.0.0.1:42512 318s app_test.go:1207: 07:05:11.135 - 72: DEBUG: new connection from 127.0.0.1:48844 318s app_test.go:1207: 07:05:11.085 - 65: DEBUG: new connection from 127.0.0.1:52144 318s app_test.go:1207: 07:05:11.086 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.134 - 65: DEBUG: new connection from 127.0.0.1:52146 318s app_test.go:1207: 07:05:11.136 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.192 - 65: DEBUG: new connection from 127.0.0.1:52162 318s app_test.go:1207: 07:05:11.194 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.256 - 65: DEBUG: new connection from 127.0.0.1:52174 318s app_test.go:1207: 07:05:11.258 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.309 - 65: DEBUG: new connection from 127.0.0.1:52190 318s app_test.go:1207: 07:05:11.311 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.362 - 65: DEBUG: new connection from 127.0.0.1:52194 318s app_test.go:1207: 07:05:11.363 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.215 - 65: DEBUG: new connection from 127.0.0.1:52208 318s app_test.go:1207: 07:05:11.217 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.347 - 69: DEBUG: new connection from 127.0.0.1:43514 318s app_test.go:1207: 07:05:11.347 - 70: DEBUG: new connection from 127.0.0.1:52704 318s app_test.go:1207: 07:05:11.347 - 68: DEBUG: new connection from 127.0.0.1:41748 318s app_test.go:1207: 07:05:11.348 - 72: DEBUG: new connection from 127.0.0.1:48846 318s app_test.go:1207: 07:05:11.348 - 67: DEBUG: new connection from 127.0.0.1:54370 318s app_test.go:1207: 07:05:11.348 - 65: DEBUG: new connection from 127.0.0.1:52214 318s app_test.go:1207: 07:05:11.348 - 71: DEBUG: new connection from 127.0.0.1:42522 318s app_test.go:1207: 07:05:11.347 - 66: DEBUG: new connection from 127.0.0.1:51514 318s app_test.go:1207: 07:05:11.352 - 65: WARN: attempt 1: server 127.0.0.1:9004: no known leader 318s app_test.go:1207: 07:05:11.352 - 65: WARN: attempt 1: server 127.0.0.1:9002: no known leader 318s app_test.go:1207: 07:05:11.352 - 65: WARN: attempt 1: server 127.0.0.1:9005: no known leader 318s app_test.go:1207: 07:05:11.353 - 65: DEBUG: attempt 1: server 127.0.0.1:9008: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:05:11.353 - 65: WARN: attempt 1: server 127.0.0.1:9006: no known leader 318s app_test.go:1207: 07:05:11.353 - 65: WARN: attempt 1: server 127.0.0.1:9007: no known leader 318s app_test.go:1207: 07:05:11.353 - 65: DEBUG: new connection from 127.0.0.1:52222 318s app_test.go:1207: 07:05:11.354 - 65: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:05:11.354 - 65: DEBUG: new connection from 127.0.0.1:52238 318s app_test.go:1207: 07:05:11.354 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s --- PASS: TestRolesAdjustment_ImbalancedFailureDomain (20.58s) 318s === RUN TestRolesAdjustment_ReplaceVoterHonorWeight 318s app_test.go:1207: 07:05:11.703 - 73: DEBUG: new connection from 127.0.0.1:52244 318s app_test.go:1207: 07:05:11.706 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.711 - 73: DEBUG: new connection from 127.0.0.1:52256 318s app_test.go:1207: 07:05:11.756 - 73: DEBUG: new connection from 127.0.0.1:52262 318s app_test.go:1207: 07:05:11.759 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.766 - 74: DEBUG: new connection from 127.0.0.1:51528 318s app_test.go:1207: 07:05:11.766 - 73: DEBUG: new connection from 127.0.0.1:52264 318s app_test.go:1207: 07:05:11.808 - 73: DEBUG: new connection from 127.0.0.1:52280 318s app_test.go:1207: 07:05:11.811 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.818 - 74: DEBUG: new connection from 127.0.0.1:51544 318s app_test.go:1207: 07:05:11.818 - 73: DEBUG: new connection from 127.0.0.1:52288 318s app_test.go:1207: 07:05:11.819 - 75: DEBUG: new connection from 127.0.0.1:54396 318s app_test.go:1207: 07:05:11.821 - 75: DEBUG: new connection from 127.0.0.1:54406 318s app_test.go:1207: 07:05:11.826 - 73: DEBUG: new connection from 127.0.0.1:52296 318s app_test.go:1207: 07:05:11.653 - 74: DEBUG: new connection from 127.0.0.1:48952 318s app_test.go:1207: 07:05:11.659 - 73: DEBUG: new connection from 127.0.0.1:49724 318s app_test.go:1207: 07:05:11.696 - 73: DEBUG: new connection from 127.0.0.1:49728 318s app_test.go:1207: 07:05:11.698 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.704 - 73: DEBUG: new connection from 127.0.0.1:49730 318s app_test.go:1207: 07:05:11.704 - 74: DEBUG: new connection from 127.0.0.1:48960 318s app_test.go:1207: 07:05:11.706 - 75: DEBUG: new connection from 127.0.0.1:41558 318s app_test.go:1207: 07:05:11.707 - 76: DEBUG: new connection from 127.0.0.1:44604 318s app_test.go:1207: 07:05:11.710 - 76: DEBUG: new connection from 127.0.0.1:44608 318s app_test.go:1207: 07:05:11.721 - 73: DEBUG: new connection from 127.0.0.1:49732 318s app_test.go:1207: 07:05:11.760 - 73: DEBUG: new connection from 127.0.0.1:49736 318s app_test.go:1207: 07:05:11.762 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.765 - 73: DEBUG: new connection from 127.0.0.1:49742 318s app_test.go:1207: 07:05:11.765 - 74: DEBUG: new connection from 127.0.0.1:48962 318s app_test.go:1207: 07:05:11.769 - 75: DEBUG: new connection from 127.0.0.1:41564 318s app_test.go:1207: 07:05:11.770 - 76: DEBUG: new connection from 127.0.0.1:44616 318s app_test.go:1207: 07:05:11.771 - 77: DEBUG: new connection from 127.0.0.1:54652 318s app_test.go:1207: 07:05:11.773 - 77: DEBUG: new connection from 127.0.0.1:54664 318s app_test.go:1207: 07:05:11.789 - 73: DEBUG: new connection from 127.0.0.1:49744 318s app_test.go:1207: 07:05:11.824 - 73: DEBUG: new connection from 127.0.0.1:49754 318s app_test.go:1207: 07:05:11.826 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:05:11.829 - 73: DEBUG: new connection from 127.0.0.1:49764 318s app_test.go:1207: 07:05:11.829 - 74: DEBUG: new connection from 127.0.0.1:48978 318s app_test.go:1207: 07:05:11.831 - 75: DEBUG: new connection from 127.0.0.1:41570 318s app_test.go:1207: 07:05:11.833 - 76: DEBUG: new connection from 127.0.0.1:44620 318s app_test.go:1207: 07:05:11.833 - 77: DEBUG: new connection from 127.0.0.1:54676 318s app_test.go:1207: 07:05:11.834 - 78: DEBUG: new connection from 127.0.0.1:41504 318s app_test.go:1207: 07:05:11.837 - 78: DEBUG: new connection from 127.0.0.1:41508 318s app_test.go:1207: 07:05:11.845 - 73: DEBUG: new connection from 127.0.0.1:49778 318s app_test.go:1207: 07:05:11.712 - 73: DEBUG: new connection from 127.0.0.1:49780 318s app_test.go:1207: 07:05:11.714 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:05:11.717 - 74: DEBUG: new connection from 127.0.0.1:48988 318s app_test.go:1207: 07:05:11.717 - 73: DEBUG: new connection from 127.0.0.1:49792 318s app_test.go:1207: 07:05:11.718 - 76: DEBUG: new connection from 127.0.0.1:44632 318s app_test.go:1207: 07:05:11.726 - 77: DEBUG: new connection from 127.0.0.1:54690 318s app_test.go:1207: 07:05:11.728 - 78: DEBUG: new connection from 127.0.0.1:41524 318s app_test.go:1207: 07:05:11.732 - 74: DEBUG: new connection from 127.0.0.1:49000 318s app_test.go:1207: 07:05:11.733 - 73: DEBUG: new connection from 127.0.0.1:49808 318s app_test.go:1207: 07:05:11.733 - 76: DEBUG: new connection from 127.0.0.1:44638 318s app_test.go:1207: 07:05:11.735 - 77: DEBUG: new connection from 127.0.0.1:54698 318s app_test.go:1207: 07:05:11.737 - 78: DEBUG: new connection from 127.0.0.1:41530 318s app_test.go:1207: 07:05:11.741 - 74: DEBUG: new connection from 127.0.0.1:49016 318s app_test.go:1207: 07:05:11.741 - 73: DEBUG: new connection from 127.0.0.1:49820 318s app_test.go:1207: 07:05:11.742 - 76: DEBUG: new connection from 127.0.0.1:44646 318s app_test.go:1207: 07:05:11.744 - 77: DEBUG: new connection from 127.0.0.1:54706 318s app_test.go:1207: 07:05:11.744 - 78: DEBUG: new connection from 127.0.0.1:41542 318s app_test.go:1207: 07:05:11.769 - 73: DEBUG: new connection from 127.0.0.1:49832 318s app_test.go:1207: 07:05:11.770 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.711 - 73: DEBUG: new connection from 127.0.0.1:49846 318s app_test.go:1207: 07:06:11.712 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.776 - 73: DEBUG: new connection from 127.0.0.1:49856 318s app_test.go:1207: 07:06:11.777 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.840 - 73: DEBUG: new connection from 127.0.0.1:49860 318s app_test.go:1207: 07:06:11.841 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.752 - 73: DEBUG: new connection from 127.0.0.1:49868 318s app_test.go:1207: 07:06:11.754 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.756 - 74: DEBUG: new connection from 127.0.0.1:49032 318s app_test.go:1207: 07:06:11.756 - 73: DEBUG: new connection from 127.0.0.1:49880 318s app_test.go:1207: 07:06:11.757 - 76: DEBUG: new connection from 127.0.0.1:44650 318s app_test.go:1207: 07:06:11.758 - 77: DEBUG: new connection from 127.0.0.1:54716 318s app_test.go:1207: 07:06:11.760 - 78: DEBUG: new connection from 127.0.0.1:41548 318s app_test.go:1207: 07:06:11.772 - 73: DEBUG: new connection from 127.0.0.1:49894 318s app_test.go:1207: 07:06:11.773 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.720 - 73: DEBUG: new connection from 127.0.0.1:49902 318s app_test.go:1207: 07:06:11.721 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.780 - 73: DEBUG: new connection from 127.0.0.1:49910 318s app_test.go:1207: 07:06:11.781 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.847 - 73: DEBUG: new connection from 127.0.0.1:49926 318s app_test.go:1207: 07:06:11.848 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.763 - 73: DEBUG: new connection from 127.0.0.1:41334 318s app_test.go:1207: 07:06:11.765 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.769 - 73: DEBUG: new connection from 127.0.0.1:41350 318s app_test.go:1207: 07:06:11.769 - 74: DEBUG: new connection from 127.0.0.1:48768 318s app_test.go:1207: 07:06:11.770 - 76: DEBUG: new connection from 127.0.0.1:58016 318s app_test.go:1207: 07:06:11.771 - 77: DEBUG: new connection from 127.0.0.1:46918 318s app_test.go:1207: 07:06:11.772 - 78: DEBUG: new connection from 127.0.0.1:58438 318s app_test.go:1207: 07:06:11.778 - 73: DEBUG: new connection from 127.0.0.1:41360 318s app_test.go:1207: 07:06:11.779 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.726 - 73: DEBUG: new connection from 127.0.0.1:41366 318s app_test.go:1207: 07:06:11.727 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.788 - 73: DEBUG: new connection from 127.0.0.1:41370 318s app_test.go:1207: 07:06:11.789 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.851 - 73: DEBUG: new connection from 127.0.0.1:41376 318s app_test.go:1207: 07:06:11.852 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.775 - 73: DEBUG: new connection from 127.0.0.1:41380 318s app_test.go:1207: 07:06:11.777 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.781 - 74: DEBUG: new connection from 127.0.0.1:48784 318s app_test.go:1207: 07:06:11.782 - 73: DEBUG: new connection from 127.0.0.1:41392 318s app_test.go:1207: 07:06:11.782 - 73: DEBUG: new connection from 127.0.0.1:41402 318s app_test.go:1207: 07:06:11.783 - 76: DEBUG: new connection from 127.0.0.1:58026 318s app_test.go:1207: 07:06:11.784 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.784 - 77: DEBUG: new connection from 127.0.0.1:46930 318s app_test.go:1207: 07:06:11.787 - 78: DEBUG: new connection from 127.0.0.1:58446 318s app_test.go:1207: 07:06:11.732 - 73: DEBUG: new connection from 127.0.0.1:41406 318s app_test.go:1207: 07:06:11.737 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.794 - 73: DEBUG: new connection from 127.0.0.1:41414 318s app_test.go:1207: 07:06:11.795 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.859 - 73: DEBUG: new connection from 127.0.0.1:41416 318s app_test.go:1207: 07:06:11.862 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.790 - 73: DEBUG: new connection from 127.0.0.1:41426 318s app_test.go:1207: 07:06:11.790 - 73: DEBUG: new connection from 127.0.0.1:41440 318s app_test.go:1207: 07:06:11.792 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.793 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.802 - 73: DEBUG: new connection from 127.0.0.1:41450 318s app_test.go:1207: 07:06:11.803 - 74: DEBUG: new connection from 127.0.0.1:48800 318s app_test.go:1207: 07:06:11.805 - 76: DEBUG: new connection from 127.0.0.1:58032 318s app_test.go:1207: 07:06:11.806 - 77: DEBUG: new connection from 127.0.0.1:46936 318s app_test.go:1207: 07:06:11.807 - 78: DEBUG: new connection from 127.0.0.1:58460 318s app_test.go:1207: 07:06:11.877 - 77: DEBUG: new connection from 127.0.0.1:46938 318s app_test.go:1207: 07:06:11.877 - 78: DEBUG: new connection from 127.0.0.1:58470 318s app_test.go:1207: 07:06:11.877 - 74: DEBUG: new connection from 127.0.0.1:48802 318s app_test.go:1207: 07:06:11.877 - 76: DEBUG: new connection from 127.0.0.1:58044 318s app_test.go:1207: 07:06:11.878 - 73: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 318s app_test.go:1207: 07:06:11.877 - 73: DEBUG: new connection from 127.0.0.1:41456 318s app_test.go:1207: 07:06:11.880 - 73: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:06:11.881 - 73: DEBUG: new connection from 127.0.0.1:41462 318s app_test.go:1207: 07:06:11.882 - 73: WARN: attempt 1: server 127.0.0.1:9004: no known leader 318s app_test.go:1207: 07:06:11.882 - 73: DEBUG: attempt 1: server 127.0.0.1:9005: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:06:11.882 - 73: DEBUG: new connection from 127.0.0.1:41476 318s app_test.go:1207: 07:06:11.883 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.883 - 73: WARN: attempt 1: server 127.0.0.1:9006: no known leader 318s --- PASS: TestRolesAdjustment_ReplaceVoterHonorWeight (20.43s) 318s === RUN TestRolesAdjustment_CantReplaceVoter 318s app_test.go:1207: 07:06:11.126 - 79: DEBUG: new connection from 127.0.0.1:48038 318s app_test.go:1207: 07:06:11.129 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.132 - 79: DEBUG: new connection from 127.0.0.1:48042 318s app_test.go:1207: 07:06:11.189 - 79: DEBUG: new connection from 127.0.0.1:48054 318s app_test.go:1207: 07:06:11.191 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.202 - 79: DEBUG: new connection from 127.0.0.1:48066 318s app_test.go:1207: 07:06:11.203 - 80: DEBUG: new connection from 127.0.0.1:35820 318s app_test.go:1207: 07:06:11.249 - 79: DEBUG: new connection from 127.0.0.1:48078 318s app_test.go:1207: 07:06:11.251 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.257 - 80: DEBUG: new connection from 127.0.0.1:35822 318s app_test.go:1207: 07:06:11.257 - 79: DEBUG: new connection from 127.0.0.1:48090 318s app_test.go:1207: 07:06:11.259 - 81: DEBUG: new connection from 127.0.0.1:52782 318s app_test.go:1207: 07:06:11.262 - 81: DEBUG: new connection from 127.0.0.1:52784 318s app_test.go:1207: 07:06:11.270 - 79: DEBUG: new connection from 127.0.0.1:48096 318s app_test.go:1207: 07:06:11.083 - 80: DEBUG: new connection from 127.0.0.1:35836 318s app_test.go:1207: 07:06:11.088 - 79: DEBUG: new connection from 127.0.0.1:48102 318s app_test.go:1207: 07:06:11.126 - 79: DEBUG: new connection from 127.0.0.1:48106 318s app_test.go:1207: 07:06:11.132 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.136 - 79: DEBUG: new connection from 127.0.0.1:48122 318s app_test.go:1207: 07:06:11.137 - 80: DEBUG: new connection from 127.0.0.1:35844 318s app_test.go:1207: 07:06:11.139 - 81: DEBUG: new connection from 127.0.0.1:52786 318s app_test.go:1207: 07:06:11.140 - 82: DEBUG: new connection from 127.0.0.1:55428 318s app_test.go:1207: 07:06:11.144 - 82: DEBUG: new connection from 127.0.0.1:55436 318s app_test.go:1207: 07:06:11.135 - 79: DEBUG: new connection from 127.0.0.1:48138 318s app_test.go:1207: 07:06:11.137 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.141 - 79: DEBUG: new connection from 127.0.0.1:48146 318s app_test.go:1207: 07:06:11.141 - 80: DEBUG: new connection from 127.0.0.1:35854 318s app_test.go:1207: 07:06:11.205 - 79: DEBUG: new connection from 127.0.0.1:48154 318s app_test.go:1207: 07:06:11.207 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.144 - 79: DEBUG: new connection from 127.0.0.1:48162 318s app_test.go:1207: 07:06:11.146 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.151 - 79: DEBUG: new connection from 127.0.0.1:48178 318s app_test.go:1207: 07:06:11.151 - 80: DEBUG: new connection from 127.0.0.1:35860 318s app_test.go:1207: 07:06:11.213 - 79: DEBUG: new connection from 127.0.0.1:48190 318s app_test.go:1207: 07:06:11.214 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.153 - 79: DEBUG: new connection from 127.0.0.1:37592 318s app_test.go:1207: 07:06:11.155 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.162 - 80: DEBUG: new connection from 127.0.0.1:46236 318s app_test.go:1207: 07:06:11.162 - 79: DEBUG: new connection from 127.0.0.1:37596 318s app_test.go:1207: 07:06:11.219 - 79: DEBUG: new connection from 127.0.0.1:37606 318s app_test.go:1207: 07:06:11.223 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.181 - 79: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 318s app_test.go:1207: 07:06:11.182 - 80: DEBUG: new connection from 127.0.0.1:46246 318s app_test.go:1207: 07:06:11.182 - 79: DEBUG: new connection from 127.0.0.1:37616 318s app_test.go:1207: 07:06:11.182 - 79: WARN: attempt 1: server 127.0.0.1:9004: dial: dial tcp 127.0.0.1:9004: connect: connection refused 318s app_test.go:1207: 07:06:11.184 - 79: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:06:11.184 - 79: DEBUG: new connection from 127.0.0.1:37630 318s app_test.go:1207: 07:06:11.184 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s --- PASS: TestRolesAdjustment_CantReplaceVoter (14.19s) 318s === RUN TestRolesAdjustment_ReplaceStandBy 318s app_test.go:1207: 07:06:11.301 - 83: DEBUG: new connection from 127.0.0.1:37642 318s app_test.go:1207: 07:06:11.304 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.307 - 83: DEBUG: new connection from 127.0.0.1:37644 318s app_test.go:1207: 07:06:11.357 - 83: DEBUG: new connection from 127.0.0.1:37656 318s app_test.go:1207: 07:06:11.359 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.370 - 83: DEBUG: new connection from 127.0.0.1:37660 318s app_test.go:1207: 07:06:11.370 - 84: DEBUG: new connection from 127.0.0.1:46262 318s app_test.go:1207: 07:06:11.413 - 83: DEBUG: new connection from 127.0.0.1:37676 318s app_test.go:1207: 07:06:11.415 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.424 - 84: DEBUG: new connection from 127.0.0.1:46274 318s app_test.go:1207: 07:06:11.424 - 83: DEBUG: new connection from 127.0.0.1:37688 318s app_test.go:1207: 07:06:11.425 - 85: DEBUG: new connection from 127.0.0.1:48762 318s app_test.go:1207: 07:06:11.427 - 85: DEBUG: new connection from 127.0.0.1:48776 318s app_test.go:1207: 07:06:11.433 - 83: DEBUG: new connection from 127.0.0.1:37692 318s app_test.go:1207: 07:06:11.273 - 84: DEBUG: new connection from 127.0.0.1:46276 318s app_test.go:1207: 07:06:11.278 - 83: DEBUG: new connection from 127.0.0.1:37708 318s app_test.go:1207: 07:06:11.317 - 83: DEBUG: new connection from 127.0.0.1:37714 318s app_test.go:1207: 07:06:11.319 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.327 - 84: DEBUG: new connection from 127.0.0.1:46290 318s app_test.go:1207: 07:06:11.328 - 83: DEBUG: new connection from 127.0.0.1:37726 318s app_test.go:1207: 07:06:11.329 - 85: DEBUG: new connection from 127.0.0.1:48786 318s app_test.go:1207: 07:06:11.329 - 86: DEBUG: new connection from 127.0.0.1:60122 318s app_test.go:1207: 07:06:11.332 - 86: DEBUG: new connection from 127.0.0.1:60138 318s app_test.go:1207: 07:06:11.349 - 83: DEBUG: new connection from 127.0.0.1:37740 318s app_test.go:1207: 07:06:11.380 - 83: DEBUG: new connection from 127.0.0.1:37744 318s app_test.go:1207: 07:06:11.383 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.386 - 84: DEBUG: new connection from 127.0.0.1:46296 318s app_test.go:1207: 07:06:11.386 - 83: DEBUG: new connection from 127.0.0.1:37750 318s app_test.go:1207: 07:06:11.388 - 85: DEBUG: new connection from 127.0.0.1:48802 318s app_test.go:1207: 07:06:11.388 - 86: DEBUG: new connection from 127.0.0.1:60142 318s app_test.go:1207: 07:06:11.390 - 87: DEBUG: new connection from 127.0.0.1:55796 318s app_test.go:1207: 07:06:11.393 - 87: DEBUG: new connection from 127.0.0.1:55806 318s app_test.go:1207: 07:06:11.404 - 83: DEBUG: new connection from 127.0.0.1:37764 318s app_test.go:1207: 07:06:11.444 - 83: DEBUG: new connection from 127.0.0.1:37770 318s app_test.go:1207: 07:06:11.447 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.451 - 84: DEBUG: new connection from 127.0.0.1:46302 318s app_test.go:1207: 07:06:11.452 - 83: DEBUG: new connection from 127.0.0.1:37782 318s app_test.go:1207: 07:06:11.453 - 85: DEBUG: new connection from 127.0.0.1:48804 318s app_test.go:1207: 07:06:11.453 - 86: DEBUG: new connection from 127.0.0.1:60144 318s app_test.go:1207: 07:06:11.455 - 87: DEBUG: new connection from 127.0.0.1:55812 318s app_test.go:1207: 07:06:11.455 - 88: DEBUG: new connection from 127.0.0.1:40716 318s app_test.go:1207: 07:06:11.460 - 88: DEBUG: new connection from 127.0.0.1:40722 318s app_test.go:1207: 07:06:11.475 - 83: DEBUG: new connection from 127.0.0.1:37786 318s app_test.go:1207: 07:06:11.501 - 83: DEBUG: new connection from 127.0.0.1:37792 318s app_test.go:1207: 07:06:11.503 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.507 - 83: DEBUG: new connection from 127.0.0.1:37796 318s app_test.go:1207: 07:06:11.508 - 84: DEBUG: new connection from 127.0.0.1:46312 318s app_test.go:1207: 07:06:11.509 - 85: DEBUG: new connection from 127.0.0.1:48820 318s app_test.go:1207: 07:06:11.510 - 86: DEBUG: new connection from 127.0.0.1:60150 318s app_test.go:1207: 07:06:11.511 - 87: DEBUG: new connection from 127.0.0.1:55828 318s app_test.go:1207: 07:06:11.513 - 88: DEBUG: new connection from 127.0.0.1:40724 318s app_test.go:1207: 07:06:11.514 - 89: DEBUG: new connection from 127.0.0.1:36140 318s app_test.go:1207: 07:06:11.310 - 83: DEBUG: new connection from 127.0.0.1:37804 318s app_test.go:1207: 07:06:11.312 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.315 - 83: DEBUG: new connection from 127.0.0.1:37806 318s app_test.go:1207: 07:06:11.315 - 84: DEBUG: new connection from 127.0.0.1:46324 318s app_test.go:1207: 07:06:11.316 - 85: DEBUG: new connection from 127.0.0.1:48828 318s app_test.go:1207: 07:06:11.317 - 86: DEBUG: new connection from 127.0.0.1:60152 318s app_test.go:1207: 07:06:11.318 - 88: DEBUG: new connection from 127.0.0.1:40728 318s app_test.go:1207: 07:06:11.319 - 89: DEBUG: new connection from 127.0.0.1:36152 318s app_test.go:1207: 07:06:11.325 - 89: DEBUG: new connection from 127.0.0.1:36158 318s app_test.go:1207: 07:06:11.329 - 83: DEBUG: new connection from 127.0.0.1:37820 318s app_test.go:1207: 07:06:11.331 - 84: DEBUG: new connection from 127.0.0.1:46326 318s app_test.go:1207: 07:06:11.332 - 83: DEBUG: new connection from 127.0.0.1:37836 318s app_test.go:1207: 07:06:11.332 - 85: DEBUG: new connection from 127.0.0.1:48834 318s app_test.go:1207: 07:06:11.337 - 86: DEBUG: new connection from 127.0.0.1:60166 318s app_test.go:1207: 07:06:11.337 - 88: DEBUG: new connection from 127.0.0.1:40732 318s app_test.go:1207: 07:06:11.340 - 89: DEBUG: new connection from 127.0.0.1:36164 318s app_test.go:1207: 07:06:11.343 - 83: DEBUG: new connection from 127.0.0.1:37846 318s app_test.go:1207: 07:06:11.344 - 84: DEBUG: new connection from 127.0.0.1:46336 318s app_test.go:1207: 07:06:11.346 - 85: DEBUG: new connection from 127.0.0.1:48842 318s app_test.go:1207: 07:06:11.347 - 86: DEBUG: new connection from 127.0.0.1:60170 318s app_test.go:1207: 07:06:11.348 - 88: DEBUG: new connection from 127.0.0.1:40738 318s app_test.go:1207: 07:06:11.349 - 89: DEBUG: new connection from 127.0.0.1:36180 318s app_test.go:1207: 07:06:11.374 - 83: DEBUG: new connection from 127.0.0.1:37848 318s app_test.go:1207: 07:06:11.376 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.274 - 83: DEBUG: new connection from 127.0.0.1:37040 318s app_test.go:1207: 07:06:11.281 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.335 - 83: DEBUG: new connection from 127.0.0.1:37050 318s app_test.go:1207: 07:06:11.337 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.463 - 83: DEBUG: new connection from 127.0.0.1:37054 318s app_test.go:1207: 07:06:11.464 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.521 - 83: DEBUG: new connection from 127.0.0.1:37062 318s app_test.go:1207: 07:06:11.522 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.353 - 83: DEBUG: new connection from 127.0.0.1:37078 318s app_test.go:1207: 07:06:11.359 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.363 - 83: DEBUG: new connection from 127.0.0.1:37086 318s app_test.go:1207: 07:06:11.363 - 84: DEBUG: new connection from 127.0.0.1:45988 318s app_test.go:1207: 07:06:11.365 - 85: DEBUG: new connection from 127.0.0.1:51502 318s app_test.go:1207: 07:06:11.365 - 86: DEBUG: new connection from 127.0.0.1:35956 318s app_test.go:1207: 07:06:11.367 - 88: DEBUG: new connection from 127.0.0.1:34538 318s app_test.go:1207: 07:06:11.367 - 89: DEBUG: new connection from 127.0.0.1:33622 318s app_test.go:1207: 07:06:11.383 - 83: DEBUG: new connection from 127.0.0.1:37090 318s app_test.go:1207: 07:06:11.384 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.287 - 83: DEBUG: new connection from 127.0.0.1:37092 318s app_test.go:1207: 07:06:11.289 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.342 - 83: DEBUG: new connection from 127.0.0.1:37106 318s app_test.go:1207: 07:06:11.343 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.469 - 83: DEBUG: new connection from 127.0.0.1:37114 318s app_test.go:1207: 07:06:11.471 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.527 - 83: DEBUG: new connection from 127.0.0.1:37122 318s app_test.go:1207: 07:06:11.528 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.370 - 83: DEBUG: new connection from 127.0.0.1:37134 318s app_test.go:1207: 07:06:11.374 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.379 - 83: DEBUG: new connection from 127.0.0.1:37140 318s app_test.go:1207: 07:06:11.379 - 84: DEBUG: new connection from 127.0.0.1:45996 318s app_test.go:1207: 07:06:11.380 - 85: DEBUG: new connection from 127.0.0.1:51504 318s app_test.go:1207: 07:06:11.381 - 86: DEBUG: new connection from 127.0.0.1:35958 318s app_test.go:1207: 07:06:11.382 - 88: DEBUG: new connection from 127.0.0.1:34540 318s app_test.go:1207: 07:06:11.383 - 89: DEBUG: new connection from 127.0.0.1:33628 318s app_test.go:1207: 07:06:11.389 - 83: DEBUG: new connection from 127.0.0.1:37146 318s app_test.go:1207: 07:06:11.389 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.300 - 83: DEBUG: new connection from 127.0.0.1:59692 318s app_test.go:1207: 07:06:11.301 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.354 - 83: DEBUG: new connection from 127.0.0.1:59702 318s app_test.go:1207: 07:06:11.355 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.483 - 83: DEBUG: new connection from 127.0.0.1:59714 318s app_test.go:1207: 07:06:11.484 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.538 - 83: DEBUG: new connection from 127.0.0.1:59730 318s app_test.go:1207: 07:06:11.539 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.387 - 83: DEBUG: new connection from 127.0.0.1:59738 318s app_test.go:1207: 07:06:11.389 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.396 - 83: DEBUG: new connection from 127.0.0.1:59754 318s app_test.go:1207: 07:06:11.397 - 84: DEBUG: new connection from 127.0.0.1:41552 318s app_test.go:1207: 07:06:11.397 - 83: DEBUG: new connection from 127.0.0.1:59768 318s app_test.go:1207: 07:06:11.397 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.399 - 85: DEBUG: new connection from 127.0.0.1:33534 318s app_test.go:1207: 07:06:11.400 - 86: DEBUG: new connection from 127.0.0.1:41266 318s app_test.go:1207: 07:06:11.402 - 88: DEBUG: new connection from 127.0.0.1:46192 318s app_test.go:1207: 07:06:11.403 - 89: DEBUG: new connection from 127.0.0.1:40174 318s app_test.go:1207: 07:06:11.305 - 83: DEBUG: new connection from 127.0.0.1:59784 318s app_test.go:1207: 07:06:11.306 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.359 - 83: DEBUG: new connection from 127.0.0.1:59792 318s app_test.go:1207: 07:06:11.360 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.488 - 83: DEBUG: new connection from 127.0.0.1:59808 318s app_test.go:1207: 07:06:11.490 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.522 - 83: DEBUG: new connection from 127.0.0.1:59812 318s app_test.go:1207: 07:06:11.523 - 89: DEBUG: new connection from 127.0.0.1:40186 318s app_test.go:1207: 07:06:11.524 - 88: DEBUG: new connection from 127.0.0.1:46198 318s app_test.go:1207: 07:06:11.525 - 84: DEBUG: new connection from 127.0.0.1:41564 318s app_test.go:1207: 07:06:11.525 - 86: DEBUG: new connection from 127.0.0.1:41282 318s app_test.go:1207: 07:06:11.525 - 83: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 318s app_test.go:1207: 07:06:11.525 - 85: DEBUG: new connection from 127.0.0.1:33544 318s app_test.go:1207: 07:06:11.529 - 83: WARN: attempt 1: server 127.0.0.1:9007: no known leader 318s app_test.go:1207: 07:06:11.529 - 83: WARN: attempt 1: server 127.0.0.1:9006: no known leader 318s app_test.go:1207: 07:06:11.529 - 83: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:06:11.530 - 83: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:06:11.530 - 83: WARN: attempt 1: server 127.0.0.1:9001: context canceled 318s app_test.go:1207: 07:06:11.530 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.530 - 83: DEBUG: new connection from 127.0.0.1:59822 318s app_test.go:1207: 07:06:11.530 - 83: WARN: attempt 1: server 127.0.0.1:9004: no known leader 318s app_test.go:1207: 07:06:11.530 - 83: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: operation was canceled 318s --- PASS: TestRolesAdjustment_ReplaceStandBy (22.52s) 318s === RUN TestRolesAdjustment_ReplaceStandByHonorFailureDomains 318s app_test.go:1207: 07:06:11.838 - 90: DEBUG: new connection from 127.0.0.1:59832 318s app_test.go:1207: 07:06:11.840 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.842 - 90: DEBUG: new connection from 127.0.0.1:59846 318s app_test.go:1207: 07:06:11.892 - 90: DEBUG: new connection from 127.0.0.1:59852 318s app_test.go:1207: 07:06:11.894 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.901 - 90: DEBUG: new connection from 127.0.0.1:59862 318s app_test.go:1207: 07:06:11.902 - 91: DEBUG: new connection from 127.0.0.1:41574 318s app_test.go:1207: 07:06:11.949 - 90: DEBUG: new connection from 127.0.0.1:59870 318s app_test.go:1207: 07:06:11.952 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.955 - 91: DEBUG: new connection from 127.0.0.1:41576 318s app_test.go:1207: 07:06:11.956 - 90: DEBUG: new connection from 127.0.0.1:59876 318s app_test.go:1207: 07:06:11.957 - 92: DEBUG: new connection from 127.0.0.1:33548 318s app_test.go:1207: 07:06:11.959 - 92: DEBUG: new connection from 127.0.0.1:33564 318s app_test.go:1207: 07:06:11.966 - 90: DEBUG: new connection from 127.0.0.1:59890 318s app_test.go:1207: 07:06:11.805 - 91: DEBUG: new connection from 127.0.0.1:41586 318s app_test.go:1207: 07:06:11.810 - 90: DEBUG: new connection from 127.0.0.1:59892 318s app_test.go:1207: 07:06:11.853 - 90: DEBUG: new connection from 127.0.0.1:59894 318s app_test.go:1207: 07:06:11.855 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.869 - 91: DEBUG: new connection from 127.0.0.1:41594 318s app_test.go:1207: 07:06:11.869 - 90: DEBUG: new connection from 127.0.0.1:59910 318s app_test.go:1207: 07:06:11.870 - 92: DEBUG: new connection from 127.0.0.1:33570 318s app_test.go:1207: 07:06:11.871 - 93: DEBUG: new connection from 127.0.0.1:41294 318s app_test.go:1207: 07:06:11.874 - 93: DEBUG: new connection from 127.0.0.1:41306 318s app_test.go:1207: 07:06:11.878 - 90: DEBUG: new connection from 127.0.0.1:59914 318s app_test.go:1207: 07:06:11.916 - 90: DEBUG: new connection from 127.0.0.1:59928 318s app_test.go:1207: 07:06:11.919 - 94: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.922 - 91: DEBUG: new connection from 127.0.0.1:41598 318s app_test.go:1207: 07:06:11.922 - 90: DEBUG: new connection from 127.0.0.1:59932 318s app_test.go:1207: 07:06:11.923 - 92: DEBUG: new connection from 127.0.0.1:33580 318s app_test.go:1207: 07:06:11.925 - 93: DEBUG: new connection from 127.0.0.1:41314 318s app_test.go:1207: 07:06:11.925 - 94: DEBUG: new connection from 127.0.0.1:53564 318s app_test.go:1207: 07:06:11.927 - 94: DEBUG: new connection from 127.0.0.1:53570 318s app_test.go:1207: 07:06:11.931 - 90: DEBUG: new connection from 127.0.0.1:59940 318s app_test.go:1207: 07:06:11.976 - 90: DEBUG: new connection from 127.0.0.1:59950 318s app_test.go:1207: 07:06:11.978 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.983 - 90: DEBUG: new connection from 127.0.0.1:59956 318s app_test.go:1207: 07:06:11.983 - 91: DEBUG: new connection from 127.0.0.1:41612 318s app_test.go:1207: 07:06:11.984 - 92: DEBUG: new connection from 127.0.0.1:33596 318s app_test.go:1207: 07:06:11.985 - 93: DEBUG: new connection from 127.0.0.1:41320 318s app_test.go:1207: 07:06:11.986 - 94: DEBUG: new connection from 127.0.0.1:53584 318s app_test.go:1207: 07:06:11.987 - 95: DEBUG: new connection from 127.0.0.1:46208 318s app_test.go:1207: 07:06:11.990 - 95: DEBUG: new connection from 127.0.0.1:46224 318s app_test.go:1207: 07:06:11.008 - 90: DEBUG: new connection from 127.0.0.1:59960 318s app_test.go:1207: 07:06:11.032 - 90: DEBUG: new connection from 127.0.0.1:59974 318s app_test.go:1207: 07:06:11.034 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.042 - 91: DEBUG: new connection from 127.0.0.1:41616 318s app_test.go:1207: 07:06:11.042 - 90: DEBUG: new connection from 127.0.0.1:59976 318s app_test.go:1207: 07:06:11.043 - 92: DEBUG: new connection from 127.0.0.1:33606 318s app_test.go:1207: 07:06:11.044 - 93: DEBUG: new connection from 127.0.0.1:41336 318s app_test.go:1207: 07:06:11.045 - 94: DEBUG: new connection from 127.0.0.1:53586 318s app_test.go:1207: 07:06:11.046 - 95: DEBUG: new connection from 127.0.0.1:46230 318s app_test.go:1207: 07:06:11.047 - 96: DEBUG: new connection from 127.0.0.1:40190 318s app_test.go:1207: 07:06:11.092 - 90: DEBUG: new connection from 127.0.0.1:59990 318s app_test.go:1207: 07:06:11.094 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.097 - 90: DEBUG: new connection from 127.0.0.1:59998 318s app_test.go:1207: 07:06:11.097 - 91: DEBUG: new connection from 127.0.0.1:41624 318s app_test.go:1207: 07:06:11.102 - 92: DEBUG: new connection from 127.0.0.1:33620 318s app_test.go:1207: 07:06:11.102 - 93: DEBUG: new connection from 127.0.0.1:41344 318s app_test.go:1207: 07:06:11.104 - 94: DEBUG: new connection from 127.0.0.1:53598 318s app_test.go:1207: 07:06:11.104 - 95: DEBUG: new connection from 127.0.0.1:46234 318s app_test.go:1207: 07:06:11.106 - 96: DEBUG: new connection from 127.0.0.1:40206 318s app_test.go:1207: 07:06:11.106 - 97: DEBUG: new connection from 127.0.0.1:49724 318s app_test.go:1207: 07:06:11.156 - 90: DEBUG: new connection from 127.0.0.1:60000 318s app_test.go:1207: 07:06:11.158 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:06:11.162 - 91: DEBUG: new connection from 127.0.0.1:41626 318s app_test.go:1207: 07:06:11.162 - 90: DEBUG: new connection from 127.0.0.1:60006 318s app_test.go:1207: 07:06:11.163 - 92: DEBUG: new connection from 127.0.0.1:33632 318s app_test.go:1207: 07:06:11.164 - 93: DEBUG: new connection from 127.0.0.1:41346 318s app_test.go:1207: 07:06:11.165 - 94: DEBUG: new connection from 127.0.0.1:53614 318s app_test.go:1207: 07:06:11.167 - 95: DEBUG: new connection from 127.0.0.1:46246 318s app_test.go:1207: 07:06:11.172 - 96: DEBUG: new connection from 127.0.0.1:40214 318s app_test.go:1207: 07:06:11.173 - 97: DEBUG: new connection from 127.0.0.1:49736 318s app_test.go:1207: 07:06:11.174 - 98: DEBUG: new connection from 127.0.0.1:54448 318s app_test.go:1207: 07:06:11.843 - 90: DEBUG: new connection from 127.0.0.1:38002 318s app_test.go:1207: 07:06:11.845 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.848 - 90: DEBUG: new connection from 127.0.0.1:38004 318s app_test.go:1207: 07:06:11.848 - 91: DEBUG: new connection from 127.0.0.1:34792 318s app_test.go:1207: 07:06:11.850 - 92: DEBUG: new connection from 127.0.0.1:40510 318s app_test.go:1207: 07:06:11.851 - 93: DEBUG: new connection from 127.0.0.1:42270 318s app_test.go:1207: 07:06:11.852 - 95: DEBUG: new connection from 127.0.0.1:55224 318s app_test.go:1207: 07:06:11.854 - 96: DEBUG: new connection from 127.0.0.1:56560 318s app_test.go:1207: 07:06:11.856 - 97: DEBUG: new connection from 127.0.0.1:47274 318s app_test.go:1207: 07:06:11.857 - 98: DEBUG: new connection from 127.0.0.1:47350 318s app_test.go:1207: 07:06:11.859 - 97: DEBUG: new connection from 127.0.0.1:47278 318s app_test.go:1207: 07:06:11.861 - 90: DEBUG: new connection from 127.0.0.1:38016 318s app_test.go:1207: 07:06:11.862 - 91: DEBUG: new connection from 127.0.0.1:34804 318s app_test.go:1207: 07:06:11.863 - 92: DEBUG: new connection from 127.0.0.1:40514 318s app_test.go:1207: 07:06:11.865 - 93: DEBUG: new connection from 127.0.0.1:42274 318s app_test.go:1207: 07:06:11.867 - 95: DEBUG: new connection from 127.0.0.1:55234 318s app_test.go:1207: 07:06:11.868 - 90: DEBUG: new connection from 127.0.0.1:38030 318s app_test.go:1207: 07:06:11.869 - 96: DEBUG: new connection from 127.0.0.1:56572 318s app_test.go:1207: 07:06:11.872 - 97: DEBUG: new connection from 127.0.0.1:47286 318s app_test.go:1207: 07:06:11.873 - 98: DEBUG: new connection from 127.0.0.1:47362 318s app_test.go:1207: 07:06:11.878 - 91: DEBUG: new connection from 127.0.0.1:34816 318s app_test.go:1207: 07:06:11.878 - 90: DEBUG: new connection from 127.0.0.1:38034 318s app_test.go:1207: 07:06:11.880 - 92: DEBUG: new connection from 127.0.0.1:40524 318s app_test.go:1207: 07:06:11.880 - 93: DEBUG: new connection from 127.0.0.1:42288 318s app_test.go:1207: 07:06:11.882 - 95: DEBUG: new connection from 127.0.0.1:55238 318s app_test.go:1207: 07:06:11.882 - 96: DEBUG: new connection from 127.0.0.1:56584 318s app_test.go:1207: 07:06:11.884 - 97: DEBUG: new connection from 127.0.0.1:47300 318s app_test.go:1207: 07:06:11.884 - 98: DEBUG: new connection from 127.0.0.1:47368 318s app_test.go:1207: 07:06:11.905 - 90: DEBUG: new connection from 127.0.0.1:38046 318s app_test.go:1207: 07:06:11.906 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.807 - 90: DEBUG: new connection from 127.0.0.1:38060 318s app_test.go:1207: 07:06:11.808 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.875 - 90: DEBUG: new connection from 127.0.0.1:38072 318s app_test.go:1207: 07:06:11.877 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.991 - 90: DEBUG: new connection from 127.0.0.1:38074 318s app_test.go:1207: 07:06:11.992 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.049 - 90: DEBUG: new connection from 127.0.0.1:38088 318s app_test.go:1207: 07:06:11.052 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.109 - 90: DEBUG: new connection from 127.0.0.1:38104 318s app_test.go:1207: 07:06:11.110 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:06:11.177 - 90: DEBUG: new connection from 127.0.0.1:38112 318s app_test.go:1207: 07:06:11.179 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.887 - 90: DEBUG: new connection from 127.0.0.1:38128 318s app_test.go:1207: 07:07:11.889 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.894 - 91: DEBUG: new connection from 127.0.0.1:34830 318s app_test.go:1207: 07:07:11.894 - 90: DEBUG: new connection from 127.0.0.1:38136 318s app_test.go:1207: 07:07:11.897 - 92: DEBUG: new connection from 127.0.0.1:40536 318s app_test.go:1207: 07:07:11.898 - 93: DEBUG: new connection from 127.0.0.1:42298 318s app_test.go:1207: 07:07:11.900 - 95: DEBUG: new connection from 127.0.0.1:55254 318s app_test.go:1207: 07:07:11.900 - 96: DEBUG: new connection from 127.0.0.1:56598 318s app_test.go:1207: 07:07:11.903 - 98: DEBUG: new connection from 127.0.0.1:47380 318s app_test.go:1207: 07:07:11.903 - 97: DEBUG: new connection from 127.0.0.1:47308 318s app_test.go:1207: 07:07:11.909 - 90: DEBUG: new connection from 127.0.0.1:38142 318s app_test.go:1207: 07:07:11.910 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.812 - 90: DEBUG: new connection from 127.0.0.1:38154 318s app_test.go:1207: 07:07:11.813 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.885 - 90: DEBUG: new connection from 127.0.0.1:38166 318s app_test.go:1207: 07:07:11.886 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.003 - 90: DEBUG: new connection from 127.0.0.1:38176 318s app_test.go:1207: 07:07:11.004 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.061 - 90: DEBUG: new connection from 127.0.0.1:38188 318s app_test.go:1207: 07:07:11.062 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.114 - 90: DEBUG: new connection from 127.0.0.1:38196 318s app_test.go:1207: 07:07:11.116 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.183 - 90: DEBUG: new connection from 127.0.0.1:38198 318s app_test.go:1207: 07:07:11.184 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.906 - 90: DEBUG: new connection from 127.0.0.1:44032 318s app_test.go:1207: 07:07:11.909 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.918 - 90: DEBUG: new connection from 127.0.0.1:44036 318s app_test.go:1207: 07:07:11.918 - 90: DEBUG: new connection from 127.0.0.1:44048 318s app_test.go:1207: 07:07:11.919 - 91: DEBUG: new connection from 127.0.0.1:60522 318s app_test.go:1207: 07:07:11.920 - 92: DEBUG: new connection from 127.0.0.1:37722 318s app_test.go:1207: 07:07:11.921 - 93: DEBUG: new connection from 127.0.0.1:38770 318s app_test.go:1207: 07:07:11.921 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.923 - 95: DEBUG: new connection from 127.0.0.1:58624 318s app_test.go:1207: 07:07:11.925 - 96: DEBUG: new connection from 127.0.0.1:48722 318s app_test.go:1207: 07:07:11.927 - 97: DEBUG: new connection from 127.0.0.1:32874 318s app_test.go:1207: 07:07:11.929 - 98: DEBUG: new connection from 127.0.0.1:57766 318s app_test.go:1207: 07:07:11.818 - 90: DEBUG: new connection from 127.0.0.1:44050 318s app_test.go:1207: 07:07:11.819 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.889 - 90: DEBUG: new connection from 127.0.0.1:44060 318s app_test.go:1207: 07:07:11.890 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.008 - 90: DEBUG: new connection from 127.0.0.1:44066 318s app_test.go:1207: 07:07:11.009 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.070 - 90: DEBUG: new connection from 127.0.0.1:44072 318s app_test.go:1207: 07:07:11.071 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.119 - 90: DEBUG: new connection from 127.0.0.1:44084 318s app_test.go:1207: 07:07:11.120 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.188 - 90: DEBUG: new connection from 127.0.0.1:44092 318s app_test.go:1207: 07:07:11.189 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.929 - 90: DEBUG: new connection from 127.0.0.1:44102 318s app_test.go:1207: 07:07:11.931 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.934 - 90: DEBUG: new connection from 127.0.0.1:44112 318s app_test.go:1207: 07:07:11.936 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.938 - 91: DEBUG: new connection from 127.0.0.1:60536 318s app_test.go:1207: 07:07:11.938 - 90: DEBUG: new connection from 127.0.0.1:44114 318s app_test.go:1207: 07:07:11.940 - 92: DEBUG: new connection from 127.0.0.1:37732 318s app_test.go:1207: 07:07:11.942 - 93: DEBUG: new connection from 127.0.0.1:38774 318s app_test.go:1207: 07:07:11.944 - 95: DEBUG: new connection from 127.0.0.1:58634 318s app_test.go:1207: 07:07:11.945 - 96: DEBUG: new connection from 127.0.0.1:48724 318s app_test.go:1207: 07:07:11.946 - 97: DEBUG: new connection from 127.0.0.1:32878 318s app_test.go:1207: 07:07:11.946 - 98: DEBUG: new connection from 127.0.0.1:57770 318s app_test.go:1207: 07:07:11.825 - 90: DEBUG: new connection from 127.0.0.1:44122 318s app_test.go:1207: 07:07:11.826 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.899 - 90: DEBUG: new connection from 127.0.0.1:44134 318s app_test.go:1207: 07:07:11.901 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.014 - 90: DEBUG: new connection from 127.0.0.1:44148 318s app_test.go:1207: 07:07:11.015 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.076 - 90: DEBUG: new connection from 127.0.0.1:44164 318s app_test.go:1207: 07:07:11.077 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.124 - 90: DEBUG: new connection from 127.0.0.1:44170 318s app_test.go:1207: 07:07:11.125 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 318s app_test.go:1207: 07:07:11.177 - 90: DEBUG: new connection from 127.0.0.1:44172 318s app_test.go:1207: 07:07:11.177 - 96: DEBUG: new connection from 127.0.0.1:48728 318s app_test.go:1207: 07:07:11.178 - 90: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 318s app_test.go:1207: 07:07:11.178 - 97: DEBUG: new connection from 127.0.0.1:32892 318s app_test.go:1207: 07:07:11.178 - 95: DEBUG: new connection from 127.0.0.1:58650 318s app_test.go:1207: 07:07:11.179 - 93: DEBUG: new connection from 127.0.0.1:38780 318s app_test.go:1207: 07:07:11.179 - 92: DEBUG: new connection from 127.0.0.1:37740 318s app_test.go:1207: 07:07:11.180 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1207: 07:07:11.180 - 91: DEBUG: new connection from 127.0.0.1:60552 318s app_test.go:1207: 07:07:11.181 - 90: WARN: attempt 1: server 127.0.0.1:9009: dial: dial tcp 127.0.0.1:9009: operation was canceled 318s app_test.go:1207: 07:07:11.181 - 98: DEBUG: new connection from 127.0.0.1:57782 318s app_test.go:1207: 07:07:11.185 - 90: WARN: attempt 1: server 127.0.0.1:9007: no known leader 318s app_test.go:1207: 07:07:11.185 - 90: WARN: attempt 1: server 127.0.0.1:9004: no known leader 318s app_test.go:1207: 07:07:11.186 - 90: WARN: attempt 1: server 127.0.0.1:9008: no known leader 318s app_test.go:1207: 07:07:11.186 - 90: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:07:11.186 - 90: WARN: attempt 1: server 127.0.0.1:9001: context canceled 318s app_test.go:1207: 07:07:11.186 - 90: WARN: attempt 1: server 127.0.0.1:9006: no known leader 318s app_test.go:1207: 07:07:11.186 - 90: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 318s app_test.go:1207: 07:07:11.186 - 90: WARN: attempt 1: server 127.0.0.1:9001: context canceled 318s --- PASS: TestRolesAdjustment_ReplaceStandByHonorFailureDomains (22.65s) 318s === RUN TestOpen 318s app_test.go:1207: 07:07:11.475 - 99: DEBUG: new connection from 127.0.0.1:57638 318s app_test.go:1207: 07:07:11.475 - 99: DEBUG: new connection from 127.0.0.1:57646 318s app_test.go:1207: 07:07:11.478 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 318s app_test.go:1207: 07:07:11.479 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 318s app_test.go:1207: 07:07:11.483 - 99: DEBUG: new connection from 127.0.0.1:57658 318s --- PASS: TestOpen (0.10s) 318s === RUN TestOpenDisk 318s app_test.go:1207: 07:07:11.575 - 100: DEBUG: new connection from 127.0.0.1:57664 318s app_test.go:1207: 07:07:11.575 - 100: DEBUG: new connection from 127.0.0.1:57670 318s app_test.go:1207: 07:07:11.577 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 318s app_test.go:1207: 07:07:11.578 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 318s app_test.go:1207: 07:07:11.580 - 100: DEBUG: new connection from 127.0.0.1:57682 318s --- PASS: TestOpenDisk (0.09s) 318s === RUN TestOptions 318s --- PASS: TestOptions (0.05s) 318s === RUN TestProxy_Error 318s app_test.go:1207: 07:07:11.707 - 102: DEBUG: new connection from 127.0.0.1:57698 318s app_test.go:1207: 07:07:11.707 - 102: DEBUG: new connection from 127.0.0.1:57708 318s app_test.go:1207: 07:07:11.711 - 102: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 318s app_test.go:1207: 07:07:11.712 - 102: DEBUG: new connection from 127.0.0.1:57712 318s --- PASS: TestProxy_Error (0.39s) 318s === RUN TestReady_Cancel 318s app_test.go:1207: 07:07:11.113 - 103: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 318s --- PASS: TestReady_Cancel (0.25s) 318s === RUN TestExternalConnWithTCP 318s app_test.go:1232: 07:07:11.393 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 318s app_test.go:1232: 07:07:11.440 - 105: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 318s app_test.go:1232: 07:07:11.496 - 106: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 318s app_test.go:1232: 07:07:11.341 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 318s --- PASS: TestExternalConnWithTCP (2.14s) 318s === RUN TestExternalConnWithPipe 318s app_test.go:1232: 07:07:11.511 - 107: DEBUG: attempt 1: server first: connected on fallback path 318s app_test.go:1232: 07:07:11.556 - 108: DEBUG: attempt 1: server first: connected on fallback path 318s app_test.go:1232: 07:07:11.597 - 109: DEBUG: attempt 1: server first: connected on fallback path 318s app_test.go:1232: 07:07:11.476 - 107: DEBUG: attempt 1: server first: connected on fallback path 318s --- PASS: TestExternalConnWithPipe (2.14s) 318s === RUN TestParallelNewApp 318s === PAUSE TestParallelNewApp 318s === RUN Test_TxRowsAffected 318s app_test.go:1232: 07:07:11.639 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s app_test.go:1232: 07:07:11.643 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 318s --- PASS: Test_TxRowsAffected (0.10s) 318s === CONT TestParallelNewApp 318s === RUN TestParallelNewApp/run-0 318s === PAUSE TestParallelNewApp/run-0 318s === RUN TestParallelNewApp/run-1 318s === PAUSE TestParallelNewApp/run-1 318s === RUN TestParallelNewApp/run-2 318s === PAUSE TestParallelNewApp/run-2 318s === RUN TestParallelNewApp/run-3 318s === PAUSE TestParallelNewApp/run-3 318s === RUN TestParallelNewApp/run-4 318s === PAUSE TestParallelNewApp/run-4 318s === RUN TestParallelNewApp/run-5 318s === PAUSE TestParallelNewApp/run-5 318s === RUN TestParallelNewApp/run-6 318s === PAUSE TestParallelNewApp/run-6 318s === RUN TestParallelNewApp/run-7 318s === PAUSE TestParallelNewApp/run-7 318s === RUN TestParallelNewApp/run-8 318s === PAUSE TestParallelNewApp/run-8 318s === RUN TestParallelNewApp/run-9 318s === PAUSE TestParallelNewApp/run-9 318s === RUN TestParallelNewApp/run-10 318s === PAUSE TestParallelNewApp/run-10 318s === RUN TestParallelNewApp/run-11 318s === PAUSE TestParallelNewApp/run-11 318s === RUN TestParallelNewApp/run-12 318s === PAUSE TestParallelNewApp/run-12 318s === RUN TestParallelNewApp/run-13 318s === PAUSE TestParallelNewApp/run-13 318s === RUN TestParallelNewApp/run-14 318s === PAUSE TestParallelNewApp/run-14 318s === RUN TestParallelNewApp/run-15 318s === PAUSE TestParallelNewApp/run-15 318s === RUN TestParallelNewApp/run-16 318s === PAUSE TestParallelNewApp/run-16 318s === RUN TestParallelNewApp/run-17 318s === PAUSE TestParallelNewApp/run-17 318s === RUN TestParallelNewApp/run-18 318s === PAUSE TestParallelNewApp/run-18 318s === RUN TestParallelNewApp/run-19 318s === PAUSE TestParallelNewApp/run-19 318s === RUN TestParallelNewApp/run-20 318s === PAUSE TestParallelNewApp/run-20 318s === RUN TestParallelNewApp/run-21 318s === PAUSE TestParallelNewApp/run-21 318s === RUN TestParallelNewApp/run-22 318s === PAUSE TestParallelNewApp/run-22 318s === RUN TestParallelNewApp/run-23 318s === PAUSE TestParallelNewApp/run-23 318s === RUN TestParallelNewApp/run-24 318s === PAUSE TestParallelNewApp/run-24 318s === RUN TestParallelNewApp/run-25 318s === PAUSE TestParallelNewApp/run-25 318s === RUN TestParallelNewApp/run-26 318s === PAUSE TestParallelNewApp/run-26 318s === RUN TestParallelNewApp/run-27 318s === PAUSE TestParallelNewApp/run-27 318s === RUN TestParallelNewApp/run-28 318s === PAUSE TestParallelNewApp/run-28 318s === RUN TestParallelNewApp/run-29 318s === PAUSE TestParallelNewApp/run-29 318s === RUN TestParallelNewApp/run-30 318s === PAUSE TestParallelNewApp/run-30 318s === RUN TestParallelNewApp/run-31 318s === PAUSE TestParallelNewApp/run-31 318s === RUN TestParallelNewApp/run-32 318s === PAUSE TestParallelNewApp/run-32 318s === RUN TestParallelNewApp/run-33 318s === PAUSE TestParallelNewApp/run-33 318s === RUN TestParallelNewApp/run-34 318s === PAUSE TestParallelNewApp/run-34 318s === RUN TestParallelNewApp/run-35 318s === PAUSE TestParallelNewApp/run-35 318s === RUN TestParallelNewApp/run-36 318s === PAUSE TestParallelNewApp/run-36 318s === RUN TestParallelNewApp/run-37 318s === PAUSE TestParallelNewApp/run-37 318s === RUN TestParallelNewApp/run-38 318s === PAUSE TestParallelNewApp/run-38 318s === RUN TestParallelNewApp/run-39 318s === PAUSE TestParallelNewApp/run-39 318s === RUN TestParallelNewApp/run-40 318s === PAUSE TestParallelNewApp/run-40 318s === RUN TestParallelNewApp/run-41 318s === PAUSE TestParallelNewApp/run-41 318s === RUN TestParallelNewApp/run-42 318s === PAUSE TestParallelNewApp/run-42 318s === RUN TestParallelNewApp/run-43 318s === PAUSE TestParallelNewApp/run-43 318s === RUN TestParallelNewApp/run-44 318s === PAUSE TestParallelNewApp/run-44 318s === RUN TestParallelNewApp/run-45 318s === PAUSE TestParallelNewApp/run-45 318s === RUN TestParallelNewApp/run-46 318s === PAUSE TestParallelNewApp/run-46 318s === RUN TestParallelNewApp/run-47 318s === PAUSE TestParallelNewApp/run-47 318s === RUN TestParallelNewApp/run-48 318s === PAUSE TestParallelNewApp/run-48 318s === RUN TestParallelNewApp/run-49 318s === PAUSE TestParallelNewApp/run-49 318s === RUN TestParallelNewApp/run-50 318s === PAUSE TestParallelNewApp/run-50 318s === RUN TestParallelNewApp/run-51 318s === PAUSE TestParallelNewApp/run-51 318s === RUN TestParallelNewApp/run-52 318s === PAUSE TestParallelNewApp/run-52 318s === RUN TestParallelNewApp/run-53 318s === PAUSE TestParallelNewApp/run-53 318s === RUN TestParallelNewApp/run-54 318s === PAUSE TestParallelNewApp/run-54 318s === RUN TestParallelNewApp/run-55 318s === PAUSE TestParallelNewApp/run-55 318s === RUN TestParallelNewApp/run-56 318s === PAUSE TestParallelNewApp/run-56 318s === RUN TestParallelNewApp/run-57 318s === PAUSE TestParallelNewApp/run-57 318s === RUN TestParallelNewApp/run-58 318s === PAUSE TestParallelNewApp/run-58 318s === RUN TestParallelNewApp/run-59 318s === PAUSE TestParallelNewApp/run-59 318s === RUN TestParallelNewApp/run-60 318s === PAUSE TestParallelNewApp/run-60 318s === RUN TestParallelNewApp/run-61 318s === PAUSE TestParallelNewApp/run-61 318s === RUN TestParallelNewApp/run-62 318s === PAUSE TestParallelNewApp/run-62 318s === RUN TestParallelNewApp/run-63 318s === PAUSE TestParallelNewApp/run-63 318s === RUN TestParallelNewApp/run-64 318s === PAUSE TestParallelNewApp/run-64 318s === RUN TestParallelNewApp/run-65 318s === PAUSE TestParallelNewApp/run-65 318s === RUN TestParallelNewApp/run-66 318s === PAUSE TestParallelNewApp/run-66 318s === RUN TestParallelNewApp/run-67 318s === PAUSE TestParallelNewApp/run-67 318s === RUN TestParallelNewApp/run-68 318s === PAUSE TestParallelNewApp/run-68 318s === RUN TestParallelNewApp/run-69 318s === PAUSE TestParallelNewApp/run-69 318s === RUN TestParallelNewApp/run-70 318s === PAUSE TestParallelNewApp/run-70 318s === RUN TestParallelNewApp/run-71 318s === PAUSE TestParallelNewApp/run-71 318s === RUN TestParallelNewApp/run-72 318s === PAUSE TestParallelNewApp/run-72 318s === RUN TestParallelNewApp/run-73 318s === PAUSE TestParallelNewApp/run-73 318s === RUN TestParallelNewApp/run-74 318s === PAUSE TestParallelNewApp/run-74 318s === RUN TestParallelNewApp/run-75 318s === PAUSE TestParallelNewApp/run-75 318s === RUN TestParallelNewApp/run-76 318s === PAUSE TestParallelNewApp/run-76 318s === RUN TestParallelNewApp/run-77 318s === PAUSE TestParallelNewApp/run-77 318s === RUN TestParallelNewApp/run-78 318s === PAUSE TestParallelNewApp/run-78 318s === RUN TestParallelNewApp/run-79 318s === PAUSE TestParallelNewApp/run-79 318s === RUN TestParallelNewApp/run-80 318s === PAUSE TestParallelNewApp/run-80 318s === RUN TestParallelNewApp/run-81 318s === PAUSE TestParallelNewApp/run-81 318s === RUN TestParallelNewApp/run-82 318s === PAUSE TestParallelNewApp/run-82 318s === RUN TestParallelNewApp/run-83 318s === PAUSE TestParallelNewApp/run-83 318s === RUN TestParallelNewApp/run-84 318s === PAUSE TestParallelNewApp/run-84 318s === RUN TestParallelNewApp/run-85 318s === PAUSE TestParallelNewApp/run-85 318s === RUN TestParallelNewApp/run-86 318s === PAUSE TestParallelNewApp/run-86 318s === RUN TestParallelNewApp/run-87 318s === PAUSE TestParallelNewApp/run-87 318s === RUN TestParallelNewApp/run-88 318s === PAUSE TestParallelNewApp/run-88 318s === RUN TestParallelNewApp/run-89 318s === PAUSE TestParallelNewApp/run-89 318s === RUN TestParallelNewApp/run-90 318s === PAUSE TestParallelNewApp/run-90 318s === RUN TestParallelNewApp/run-91 318s === PAUSE TestParallelNewApp/run-91 318s === RUN TestParallelNewApp/run-92 318s === PAUSE TestParallelNewApp/run-92 318s === RUN TestParallelNewApp/run-93 318s === PAUSE TestParallelNewApp/run-93 318s === RUN TestParallelNewApp/run-94 318s === PAUSE TestParallelNewApp/run-94 318s === RUN TestParallelNewApp/run-95 318s === PAUSE TestParallelNewApp/run-95 318s === RUN TestParallelNewApp/run-96 318s === PAUSE TestParallelNewApp/run-96 318s === RUN TestParallelNewApp/run-97 318s === PAUSE TestParallelNewApp/run-97 318s === RUN TestParallelNewApp/run-98 318s === PAUSE TestParallelNewApp/run-98 318s === RUN TestParallelNewApp/run-99 318s === PAUSE TestParallelNewApp/run-99 318s === CONT TestParallelNewApp/run-82 318s === CONT TestParallelNewApp/run-0 318s === CONT TestParallelNewApp/run-81 318s === CONT TestParallelNewApp/run-80 318s === CONT TestParallelNewApp/run-76 318s === CONT TestParallelNewApp/run-79 318s === CONT TestParallelNewApp/run-78 318s === CONT TestParallelNewApp/run-77 318s === CONT TestParallelNewApp/run-75 318s === CONT TestParallelNewApp/run-74 318s === CONT TestParallelNewApp/run-73 318s === CONT TestParallelNewApp/run-72 318s === CONT TestParallelNewApp/run-71 318s === CONT TestParallelNewApp/run-70 318s === CONT TestParallelNewApp/run-69 318s === CONT TestParallelNewApp/run-68 318s === CONT TestParallelNewApp/run-67 318s === CONT TestParallelNewApp/run-66 318s === CONT TestParallelNewApp/run-65 318s === CONT TestParallelNewApp/run-64 318s === CONT TestParallelNewApp/run-63 318s === CONT TestParallelNewApp/run-62 318s === CONT TestParallelNewApp/run-61 318s === CONT TestParallelNewApp/run-60 318s === CONT TestParallelNewApp/run-59 318s === CONT TestParallelNewApp/run-58 318s === CONT TestParallelNewApp/run-57 318s === CONT TestParallelNewApp/run-56 318s === CONT TestParallelNewApp/run-55 318s === CONT TestParallelNewApp/run-54 318s === CONT TestParallelNewApp/run-53 318s === CONT TestParallelNewApp/run-52 318s === CONT TestParallelNewApp/run-51 318s === CONT TestParallelNewApp/run-50 318s === CONT TestParallelNewApp/run-49 318s === CONT TestParallelNewApp/run-99 318s === CONT TestParallelNewApp/run-98 318s === CONT TestParallelNewApp/run-97 318s === CONT TestParallelNewApp/run-96 318s === CONT TestParallelNewApp/run-95 318s === CONT TestParallelNewApp/run-94 318s === CONT TestParallelNewApp/run-93 318s === CONT TestParallelNewApp/run-92 318s === CONT TestParallelNewApp/run-91 318s === CONT TestParallelNewApp/run-90 318s === CONT TestParallelNewApp/run-89 318s === CONT TestParallelNewApp/run-88 318s === CONT TestParallelNewApp/run-87 318s === CONT TestParallelNewApp/run-86 318s === CONT TestParallelNewApp/run-83 318s === CONT TestParallelNewApp/run-85 318s === CONT TestParallelNewApp/run-84 318s === CONT TestParallelNewApp/run-25 318s === CONT TestParallelNewApp/run-48 318s === CONT TestParallelNewApp/run-47 318s === CONT TestParallelNewApp/run-46 318s === CONT TestParallelNewApp/run-45 318s === CONT TestParallelNewApp/run-44 318s === CONT TestParallelNewApp/run-43 318s === CONT TestParallelNewApp/run-42 318s === CONT TestParallelNewApp/run-41 318s === CONT TestParallelNewApp/run-40 318s === CONT TestParallelNewApp/run-39 318s === CONT TestParallelNewApp/run-38 318s === CONT TestParallelNewApp/run-37 318s === CONT TestParallelNewApp/run-36 318s === CONT TestParallelNewApp/run-35 318s === CONT TestParallelNewApp/run-34 318s === CONT TestParallelNewApp/run-33 318s === CONT TestParallelNewApp/run-32 318s === CONT TestParallelNewApp/run-31 318s === CONT TestParallelNewApp/run-30 318s === CONT TestParallelNewApp/run-29 318s === CONT TestParallelNewApp/run-28 318s === CONT TestParallelNewApp/run-27 318s === CONT TestParallelNewApp/run-26 318s === CONT TestParallelNewApp/run-13 318s === CONT TestParallelNewApp/run-24 318s === CONT TestParallelNewApp/run-23 318s === CONT TestParallelNewApp/run-22 318s === CONT TestParallelNewApp/run-21 318s === CONT TestParallelNewApp/run-20 318s === CONT TestParallelNewApp/run-19 318s === CONT TestParallelNewApp/run-18 318s === CONT TestParallelNewApp/run-17 318s === CONT TestParallelNewApp/run-16 318s === CONT TestParallelNewApp/run-15 318s === CONT TestParallelNewApp/run-14 318s === CONT TestParallelNewApp/run-7 318s === CONT TestParallelNewApp/run-12 318s === CONT TestParallelNewApp/run-11 318s === CONT TestParallelNewApp/run-10 318s === CONT TestParallelNewApp/run-9 318s === CONT TestParallelNewApp/run-8 318s === CONT TestParallelNewApp/run-4 318s === CONT TestParallelNewApp/run-6 318s === CONT TestParallelNewApp/run-5 318s === CONT TestParallelNewApp/run-2 318s === CONT TestParallelNewApp/run-3 318s === CONT TestParallelNewApp/run-1 318s --- PASS: TestParallelNewApp (0.00s) 318s --- PASS: TestParallelNewApp/run-82 (0.04s) 318s --- PASS: TestParallelNewApp/run-0 (0.05s) 318s --- PASS: TestParallelNewApp/run-80 (0.06s) 318s --- PASS: TestParallelNewApp/run-76 (0.06s) 318s --- PASS: TestParallelNewApp/run-79 (0.06s) 318s --- PASS: TestParallelNewApp/run-78 (0.06s) 318s --- PASS: TestParallelNewApp/run-81 (0.25s) 318s --- PASS: TestParallelNewApp/run-77 (0.05s) 318s --- PASS: TestParallelNewApp/run-75 (0.05s) 318s --- PASS: TestParallelNewApp/run-73 (0.05s) 318s --- PASS: TestParallelNewApp/run-72 (0.06s) 318s --- PASS: TestParallelNewApp/run-74 (0.26s) 318s --- PASS: TestParallelNewApp/run-71 (0.26s) 318s --- PASS: TestParallelNewApp/run-69 (0.07s) 318s --- PASS: TestParallelNewApp/run-68 (0.05s) 318s --- PASS: TestParallelNewApp/run-70 (0.26s) 318s --- PASS: TestParallelNewApp/run-67 (0.05s) 318s --- PASS: TestParallelNewApp/run-65 (0.05s) 318s --- PASS: TestParallelNewApp/run-64 (0.05s) 318s --- PASS: TestParallelNewApp/run-66 (0.26s) 318s --- PASS: TestParallelNewApp/run-62 (0.06s) 318s --- PASS: TestParallelNewApp/run-61 (0.06s) 318s --- PASS: TestParallelNewApp/run-63 (0.27s) 318s --- PASS: TestParallelNewApp/run-60 (0.07s) 318s --- PASS: TestParallelNewApp/run-59 (0.07s) 318s --- PASS: TestParallelNewApp/run-58 (0.06s) 318s --- PASS: TestParallelNewApp/run-57 (0.26s) 318s --- PASS: TestParallelNewApp/run-56 (0.25s) 318s --- PASS: TestParallelNewApp/run-54 (0.06s) 318s --- PASS: TestParallelNewApp/run-55 (0.07s) 318s --- PASS: TestParallelNewApp/run-52 (0.07s) 318s --- PASS: TestParallelNewApp/run-51 (0.07s) 318s --- PASS: TestParallelNewApp/run-50 (0.06s) 318s --- PASS: TestParallelNewApp/run-53 (0.25s) 318s --- PASS: TestParallelNewApp/run-49 (0.06s) 318s --- PASS: TestParallelNewApp/run-99 (0.04s) 318s --- PASS: TestParallelNewApp/run-98 (0.04s) 318s --- PASS: TestParallelNewApp/run-97 (0.05s) 318s --- PASS: TestParallelNewApp/run-96 (0.05s) 318s --- PASS: TestParallelNewApp/run-95 (0.04s) 318s --- PASS: TestParallelNewApp/run-94 (0.04s) 318s --- PASS: TestParallelNewApp/run-93 (0.04s) 318s --- PASS: TestParallelNewApp/run-91 (0.04s) 318s --- PASS: TestParallelNewApp/run-92 (0.24s) 318s --- PASS: TestParallelNewApp/run-89 (0.05s) 318s --- PASS: TestParallelNewApp/run-90 (0.24s) 318s --- PASS: TestParallelNewApp/run-87 (0.06s) 318s --- PASS: TestParallelNewApp/run-86 (0.05s) 318s --- PASS: TestParallelNewApp/run-83 (0.06s) 318s --- PASS: TestParallelNewApp/run-85 (0.06s) 318s --- PASS: TestParallelNewApp/run-88 (0.25s) 318s --- PASS: TestParallelNewApp/run-84 (0.04s) 318s --- PASS: TestParallelNewApp/run-48 (0.04s) 318s --- PASS: TestParallelNewApp/run-47 (0.05s) 318s --- PASS: TestParallelNewApp/run-46 (0.05s) 318s --- PASS: TestParallelNewApp/run-45 (0.05s) 318s --- PASS: TestParallelNewApp/run-25 (0.26s) 318s --- PASS: TestParallelNewApp/run-44 (0.04s) 318s --- PASS: TestParallelNewApp/run-42 (0.04s) 318s --- PASS: TestParallelNewApp/run-41 (0.06s) 318s --- PASS: TestParallelNewApp/run-40 (0.06s) 318s --- PASS: TestParallelNewApp/run-39 (0.05s) 318s --- PASS: TestParallelNewApp/run-43 (0.25s) 318s --- PASS: TestParallelNewApp/run-38 (0.05s) 318s --- PASS: TestParallelNewApp/run-37 (0.05s) 318s --- PASS: TestParallelNewApp/run-36 (0.05s) 318s --- PASS: TestParallelNewApp/run-35 (0.05s) 318s --- PASS: TestParallelNewApp/run-33 (0.04s) 318s --- PASS: TestParallelNewApp/run-34 (0.25s) 318s --- PASS: TestParallelNewApp/run-31 (0.06s) 318s --- PASS: TestParallelNewApp/run-32 (0.26s) 318s --- PASS: TestParallelNewApp/run-30 (0.05s) 318s --- PASS: TestParallelNewApp/run-29 (0.05s) 318s --- PASS: TestParallelNewApp/run-27 (0.04s) 318s --- PASS: TestParallelNewApp/run-26 (0.05s) 318s --- PASS: TestParallelNewApp/run-13 (0.05s) 318s --- PASS: TestParallelNewApp/run-28 (0.24s) 318s --- PASS: TestParallelNewApp/run-23 (0.05s) 318s --- PASS: TestParallelNewApp/run-22 (0.04s) 318s --- PASS: TestParallelNewApp/run-21 (0.05s) 318s --- PASS: TestParallelNewApp/run-24 (0.25s) 318s --- PASS: TestParallelNewApp/run-20 (0.04s) 318s --- PASS: TestParallelNewApp/run-19 (0.04s) 318s --- PASS: TestParallelNewApp/run-18 (0.05s) 318s --- PASS: TestParallelNewApp/run-16 (0.04s) 318s --- PASS: TestParallelNewApp/run-15 (0.05s) 318s --- PASS: TestParallelNewApp/run-14 (0.04s) 318s --- PASS: TestParallelNewApp/run-7 (0.05s) 318s --- PASS: TestParallelNewApp/run-17 (0.25s) 318s --- PASS: TestParallelNewApp/run-12 (0.05s) 318s --- PASS: TestParallelNewApp/run-10 (0.06s) 318s --- PASS: TestParallelNewApp/run-11 (0.07s) 318s --- PASS: TestParallelNewApp/run-9 (0.06s) 318s --- PASS: TestParallelNewApp/run-4 (0.07s) 318s --- PASS: TestParallelNewApp/run-6 (0.06s) 318s --- PASS: TestParallelNewApp/run-8 (0.26s) 318s --- PASS: TestParallelNewApp/run-5 (0.06s) 318s --- PASS: TestParallelNewApp/run-3 (0.06s) 318s --- PASS: TestParallelNewApp/run-2 (0.07s) 318s --- PASS: TestParallelNewApp/run-1 (0.06s) 318s === RUN Example 318s --- PASS: Example (0.12s) 318s PASS 318s ok github.com/canonical/go-dqlite/app 168.529s 327s === RUN TestNew_Default 327s Benchmark done. Results available here: 327s /tmp/dqlite-app-test-679247085/results 327s --- PASS: TestNew_Default (1.10s) 327s === RUN TestNew_KvReadWrite 327s Benchmark done. Results available here: 327s /tmp/dqlite-app-test-1041118462/results 327s --- PASS: TestNew_KvReadWrite (1.12s) 327s === RUN TestNew_ClusteredKvReadWrite 327s Benchmark done. Results available here: 327s /tmp/dqlite-app-test-658026954/results 327s --- PASS: TestNew_ClusteredKvReadWrite (4.07s) 327s === RUN TestNew_ClusteredTimeout 327s --- PASS: TestNew_ClusteredTimeout (2.12s) 327s PASS 327s ok github.com/canonical/go-dqlite/benchmark 8.442s 330s === RUN TestClient_Leader 330s --- PASS: TestClient_Leader (0.03s) 330s === RUN TestClient_Cluster 330s --- PASS: TestClient_Cluster (0.04s) 330s === RUN TestClient_Transfer 330s --- PASS: TestClient_Transfer (1.07s) 330s === RUN TestClient_Describe 330s --- PASS: TestClient_Describe (0.04s) 330s === RUN TestMembership 330s --- PASS: TestMembership (1.06s) 330s === RUN TestDefaultNodeStore 330s --- PASS: TestDefaultNodeStore (0.00s) 330s === RUN TestConfigMultiThread 330s --- PASS: TestConfigMultiThread (0.00s) 330s PASS 330s ok github.com/canonical/go-dqlite/client 2.260s 330s ? github.com/canonical/go-dqlite/cmd/dqlite [no test files] 330s ? github.com/canonical/go-dqlite/cmd/dqlite-benchmark [no test files] 330s ? github.com/canonical/go-dqlite/cmd/dqlite-demo [no test files] 357s === RUN TestDriver_Open 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestDriver_Open (0.05s) 357s === RUN TestDriver_Prepare 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestDriver_Prepare (0.05s) 357s === RUN TestConn_Exec 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestConn_Exec (0.04s) 357s === RUN TestConn_Query 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestConn_Query (0.04s) 357s === RUN TestConn_QueryRow 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestConn_QueryRow (0.04s) 357s === RUN TestConn_InterruptQuery 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestConn_InterruptQuery (0.51s) 357s === RUN TestConn_QueryBlob 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestConn_QueryBlob (0.06s) 357s === RUN TestStmt_Exec 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestStmt_Exec (0.06s) 357s === RUN TestStmt_ExecManyParams 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestStmt_ExecManyParams (0.06s) 357s === RUN TestStmt_Query 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestStmt_Query (0.04s) 357s === RUN TestStmt_QueryManyParams 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestStmt_QueryManyParams (0.04s) 357s === RUN TestConn_QueryParams 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestConn_QueryParams (0.04s) 357s === RUN TestConn_QueryManyParams 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestConn_QueryManyParams (0.04s) 357s === RUN TestConn_ExecManyParams 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestConn_ExecManyParams (0.04s) 357s === RUN Test_ColumnTypesEmpty 357s driver_test.go:472: this currently fails if the result set is empty, is dqlite skipping the header if empty set? 357s --- SKIP: Test_ColumnTypesEmpty (0.00s) 357s === RUN Test_ColumnTypesExists 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: Test_ColumnTypesExists (0.05s) 357s === RUN Test_ColumnTypesEnd 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: Test_ColumnTypesEnd (0.07s) 357s === RUN Test_ZeroColumns 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: Test_ZeroColumns (0.10s) 357s === RUN Test_DescribeLastEntry 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: Test_DescribeLastEntry (0.73s) 357s === RUN Test_Dump 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: Test_Dump (0.09s) 357s === RUN TestIntegration_DatabaseSQL 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 357s func.go:15: WARN: attempt 1: server @1: context canceled 357s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 357s func.go:15: WARN: attempt 1: server @1: context canceled 357s --- PASS: TestIntegration_DatabaseSQL (2.12s) 357s === RUN TestIntegration_ConstraintError 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 357s func.go:15: WARN: attempt 1: server @1: context canceled 357s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 357s func.go:15: WARN: attempt 1: server @1: context canceled 357s --- PASS: TestIntegration_ConstraintError (2.12s) 357s === RUN TestIntegration_ExecBindError 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s integration_test.go:116: 357s Error Trace: /tmp/autopkgtest.S1Ydmg/autopkgtest_tmp/_build/src/github.com/canonical/go-dqlite/driver/integration_test.go:116 357s Error: An error is expected but got nil. 357s Test: TestIntegration_ExecBindError 357s --- FAIL: TestIntegration_ExecBindError (0.10s) 357s === RUN TestIntegration_QueryBindError 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestIntegration_QueryBindError (0.05s) 357s === RUN TestIntegration_LargeQuery 357s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 357s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s --- PASS: TestIntegration_LargeQuery (2.16s) 357s === RUN TestIntegration_Recover 357s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 357s func.go:15: DEBUG: attempt 1: server @1: connected on fast path 357s --- PASS: TestIntegration_Recover (1.26s) 357s === RUN TestIntegration_PingOnlyWorksOnceLeaderElected 357s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 357s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 357s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 357s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 357s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 357s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 357s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 357s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 357s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 357s func.go:15: WARN: attempt 1: server @1: no known leader 357s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 357s func.go:15: WARN: attempt 1: server @1: reported leader server is not the leader 357s func.go:15: WARN: attempt 2: server @1: no known leader 357s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 357s func.go:15: WARN: attempt 2: server @1: reported leader server is not the leader 357s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 357s func.go:15: WARN: attempt 3: server @1: no known leader 357s func.go:15: WARN: attempt 3: server @1: reported leader server is not the leader 357s func.go:15: DEBUG: attempt 4: server @2: connect to reported leader @1 357s func.go:15: WARN: attempt 4: server @1: no known leader 357s func.go:15: WARN: attempt 4: server @1: reported leader server is not the leader 357s func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @1 357s func.go:15: WARN: attempt 5: server @1: no known leader 357s func.go:15: WARN: attempt 5: server @1: reported leader server is not the leader 357s func.go:15: WARN: attempt 6: server @2: no known leader 357s func.go:15: WARN: attempt 6: server @1: no known leader 357s func.go:15: DEBUG: attempt 7: server @2: connect to reported leader @1 357s func.go:15: DEBUG: attempt 7: server @1: connected on fallback path 357s --- PASS: TestIntegration_PingOnlyWorksOnceLeaderElected (6.98s) 357s === RUN TestIntegration_HighAvailability 357s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 357s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 357s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 357s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 357s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 357s func.go:15: WARN: attempt 1: server @3: no known leader 357s func.go:15: WARN: attempt 1: server @2: no known leader 357s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 357s func.go:15: WARN: attempt 2: server @2: no known leader 357s func.go:15: WARN: attempt 2: server @3: no known leader 357s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 357s func.go:15: WARN: attempt 3: server @3: no known leader 358s func.go:15: WARN: attempt 3: server @2: no known leader 358s func.go:15: WARN: attempt 4: server @1: dial: dial unix @1: connect: connection refused 358s func.go:15: WARN: attempt 4: server @3: no known leader 358s func.go:15: WARN: attempt 4: server @2: no known leader 358s func.go:15: WARN: attempt 5: server @1: dial: dial unix @1: connect: connection refused 358s func.go:15: WARN: attempt 5: server @2: no known leader 358s func.go:15: WARN: attempt 5: server @3: no known leader 358s func.go:15: WARN: attempt 6: server @1: dial: dial unix @1: connect: connection refused 358s func.go:15: DEBUG: attempt 6: server @2: connect to reported leader @3 358s func.go:15: DEBUG: attempt 6: server @3: connected on fallback path 358s --- PASS: TestIntegration_HighAvailability (7.67s) 358s === RUN TestIntegration_LeadershipTransfer 358s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 358s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 358s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 358s func.go:15: DEBUG: leadership lost (10250 - not leader) 358s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 358s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 358s func.go:15: WARN: attempt 1: server @2: context canceled 358s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 358s func.go:15: WARN: attempt 1: server @2: context canceled 358s --- PASS: TestIntegration_LeadershipTransfer (2.16s) 358s === RUN TestIntegration_LeadershipTransfer_Tx 358s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 358s func.go:15: WARN: attempt 1: server @1: context canceled 358s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 358s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 358s func.go:15: WARN: attempt 1: server @1: context canceled 358s func.go:15: DEBUG: leadership lost (10250 - not leader) 358s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 358s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 358s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 358s --- PASS: TestIntegration_LeadershipTransfer_Tx (2.14s) 358s === RUN TestOptions 358s --- PASS: TestOptions (0.00s) 358s === RUN TestIntegration_ColumnTypeName 358s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 358s --- PASS: TestIntegration_ColumnTypeName (0.07s) 358s === RUN TestIntegration_SqlNullTime 358s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 358s --- PASS: TestIntegration_SqlNullTime (0.09s) 358s FAIL 358s FAIL github.com/canonical/go-dqlite/driver 29.081s 358s === RUN TestNode_Create 358s --- PASS: TestNode_Create (0.03s) 358s === RUN TestNode_Start 358s --- PASS: TestNode_Start (0.05s) 358s === RUN TestNode_Restart 358s --- PASS: TestNode_Restart (0.07s) 358s === RUN TestNode_Start_Inet 358s --- PASS: TestNode_Start_Inet (0.04s) 358s === RUN TestNode_Leader 358s --- PASS: TestNode_Leader (0.03s) 358s === RUN TestNode_Autorecovery 358s --- PASS: TestNode_Autorecovery (0.04s) 358s PASS 358s ok github.com/canonical/go-dqlite/internal/bindings 0.256s 358s === RUN TestMessage_StaticBytesAlignment 358s --- PASS: TestMessage_StaticBytesAlignment (0.00s) 358s === RUN TestMessage_putBlob 358s === RUN TestMessage_putBlob/16 358s === RUN TestMessage_putBlob/16#01 358s === RUN TestMessage_putBlob/24 358s --- PASS: TestMessage_putBlob (0.00s) 358s --- PASS: TestMessage_putBlob/16 (0.00s) 358s --- PASS: TestMessage_putBlob/16#01 (0.00s) 358s --- PASS: TestMessage_putBlob/24 (0.00s) 358s === RUN TestMessage_putString 358s === RUN TestMessage_putString/hello 358s === RUN TestMessage_putString/hello!! 358s === RUN TestMessage_putString/hello_world 358s --- PASS: TestMessage_putString (0.00s) 358s --- PASS: TestMessage_putString/hello (0.00s) 358s --- PASS: TestMessage_putString/hello!! (0.00s) 358s --- PASS: TestMessage_putString/hello_world (0.00s) 358s === RUN TestMessage_putUint8 358s --- PASS: TestMessage_putUint8 (0.00s) 358s === RUN TestMessage_putUint16 358s --- PASS: TestMessage_putUint16 (0.00s) 358s === RUN TestMessage_putUint32 358s --- PASS: TestMessage_putUint32 (0.00s) 358s === RUN TestMessage_putUint64 358s --- PASS: TestMessage_putUint64 (0.00s) 358s === RUN TestMessage_putNamedValues 358s --- PASS: TestMessage_putNamedValues (0.00s) 358s === RUN TestMessage_putNamedValues32 358s --- PASS: TestMessage_putNamedValues32 (0.00s) 358s === RUN TestMessage_putHeader 358s --- PASS: TestMessage_putHeader (0.00s) 358s === RUN TestMessage_getString 358s === RUN TestMessage_getString/hello 358s === RUN TestMessage_getString/hello!! 358s === RUN TestMessage_getString/hello!!! 358s === RUN TestMessage_getString/hello_world 358s --- PASS: TestMessage_getString (0.00s) 358s --- PASS: TestMessage_getString/hello (0.00s) 358s --- PASS: TestMessage_getString/hello!! (0.00s) 358s --- PASS: TestMessage_getString/hello!!! (0.00s) 358s --- PASS: TestMessage_getString/hello_world (0.00s) 358s === RUN TestMessage_getBlob 358s === RUN TestMessage_getBlob/16 358s === RUN TestMessage_getBlob/16#01 358s === RUN TestMessage_getBlob/24 358s --- PASS: TestMessage_getBlob (0.00s) 358s --- PASS: TestMessage_getBlob/16 (0.00s) 358s --- PASS: TestMessage_getBlob/16#01 (0.00s) 358s --- PASS: TestMessage_getBlob/24 (0.00s) 358s === RUN TestMessage_getString_Overflow_WordBoundary 358s --- PASS: TestMessage_getString_Overflow_WordBoundary (0.00s) 358s === RUN TestConnector_Success 358s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 358s --- PASS: TestConnector_Success (0.05s) 358s === RUN TestConnector_LeaderTracker 358s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 358s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 358s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 358s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 358s connector_test.go:398: DEBUG: reusing shared connection to @test-0 358s connector_test.go:398: DEBUG: discarding shared connection to @test-0 358s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 358s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 358s connector_test.go:398: DEBUG: reusing shared connection to @test-0 358s connector_test.go:398: DEBUG: discarding shared connection to @test-0 358s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 358s --- PASS: TestConnector_LeaderTracker (0.86s) 358s === RUN TestConnector_LimitRetries 358s connector_test.go:398: WARN: attempt 1: server @test-123: dial: dial unix @test-123: connect: connection refused 358s connector_test.go:398: WARN: attempt 2: server @test-123: dial: dial unix @test-123: connect: connection refused 358s connector_test.go:398: WARN: attempt 3: server @test-123: dial: dial unix @test-123: connect: connection refused 358s --- PASS: TestConnector_LimitRetries (0.60s) 358s === RUN TestConnector_DialTimeout 358s connector_test.go:158: Skipping network test 358s --- SKIP: TestConnector_DialTimeout (0.00s) 358s === RUN TestConnector_EmptyNodeStore 358s --- PASS: TestConnector_EmptyNodeStore (0.20s) 358s === RUN TestConnector_ContextCanceled 358s connector_test.go:193: Skipping network test 358s --- SKIP: TestConnector_ContextCanceled (0.00s) 358s === RUN TestConnector_AttemptTimeout 358s func.go:15: WARN: attempt 1: server @1234: call leader (budget 99.953269ms): receive: header: read unix @->@1234: i/o timeout 358s func.go:15: WARN: attempt 2: server @1234: call leader (budget 99.920079ms): receive: header: read unix @->@1234: i/o timeout 358s --- PASS: TestConnector_AttemptTimeout (0.40s) 358s === RUN TestProtocol_RequestWithDynamicBuffer 358s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 358s --- PASS: TestProtocol_RequestWithDynamicBuffer (0.10s) 358s === RUN TestProtocol_Prepare 358s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 358s --- PASS: TestProtocol_Prepare (0.04s) 358s PASS 358s ok github.com/canonical/go-dqlite/internal/protocol 2.259s 358s ? github.com/canonical/go-dqlite/internal/shell [no test files] 358s === RUN Test_TestFunc 358s func.go:15: INFO: hello 358s --- PASS: Test_TestFunc (0.00s) 358s === RUN TestLevel_String 358s --- PASS: TestLevel_String (0.00s) 358s PASS 358s ok github.com/canonical/go-dqlite/logging 0.003s 358s ? github.com/canonical/go-dqlite/tracing [no test files] 358s FAIL 358s dh_auto_test: error: cd _build && go test -vet=off -v -p 2 github.com/canonical/go-dqlite github.com/canonical/go-dqlite/app github.com/canonical/go-dqlite/benchmark github.com/canonical/go-dqlite/client github.com/canonical/go-dqlite/cmd/dqlite github.com/canonical/go-dqlite/cmd/dqlite-benchmark github.com/canonical/go-dqlite/cmd/dqlite-demo github.com/canonical/go-dqlite/driver github.com/canonical/go-dqlite/internal/bindings github.com/canonical/go-dqlite/internal/protocol github.com/canonical/go-dqlite/internal/shell github.com/canonical/go-dqlite/logging github.com/canonical/go-dqlite/tracing returned exit code 1 358s make: *** [debian/rules:11: autopkgtest] Error 25 358s autopkgtest [07:08:04]: test dh-golang-autopkgtest: -----------------------] 359s autopkgtest [07:08:05]: test dh-golang-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 359s dh-golang-autopkgtest FLAKY non-zero exit status 2 359s autopkgtest [07:08:05]: @@@@@@@@@@@@@@@@@@@@ summary 359s dh-golang-autopkgtest FLAKY non-zero exit status 2