0s autopkgtest [08:41:33]: starting date and time: 2025-11-04 08:41:33+0000 0s autopkgtest [08:41:33]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [08:41:33]: host juju-7f2275-prod-proposed-migration-environment-20; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.g0_mt7oo/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 builder-cpu2-ram4-disk20 --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-20@bos03-2.secgroup --name adt-resolute-amd64-golang-github-canonical-go-dqlite-20251104-084132-juju-7f2275-prod-proposed-migration-environment-20-b195fe2b-38ce-4588-af7a-d9d0828370c7 --image adt/ubuntu-resolute-amd64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-20 --net-id=net_prod-proposed-migration-amd64 -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-amd64-golang-github-canonical-go-dqlite-20251104-084132-juju-7f2275-prod-proposed-migration-environment-20-b195fe2b-38ce-4588-af7a-d9d0828370c7 from image adt/ubuntu-resolute-amd64-server-20251104.img (UUID a177e2be-26f1-4ac8-94e1-a27e687e1c0c)... 76s autopkgtest [08:42:49]: testbed dpkg architecture: amd64 77s autopkgtest [08:42:50]: testbed apt version: 3.1.11 77s autopkgtest [08:42:50]: @@@@@@@@@@@@@@@@@@@@ test bed setup 77s autopkgtest [08:42:50]: testbed release detected to be: None 78s autopkgtest [08:42:51]: updating testbed package index (apt update) 78s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 79s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 79s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 79s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 79s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [9848 B] 79s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [82.4 kB] 79s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1026 kB] 79s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.1 kB] 79s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main i386 Packages [88.8 kB] 79s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 Packages [132 kB] 79s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/main amd64 c-n-f Metadata [3020 B] 79s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted i386 Packages [3744 B] 79s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 Packages [64.6 kB] 79s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/restricted amd64 c-n-f Metadata [336 B] 79s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 Packages [648 kB] 80s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/universe i386 Packages [253 kB] 80s Get:17 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 c-n-f Metadata [19.2 kB] 80s Get:18 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse i386 Packages [7180 B] 80s Get:19 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 Packages [15.2 kB] 80s Get:20 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse amd64 c-n-f Metadata [652 B] 81s Fetched 2465 kB in 1s (1861 kB/s) 82s Reading package lists... 83s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 84s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 84s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 84s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 85s Reading package lists... 85s Reading package lists... 86s Building dependency tree... 86s Reading state information... 86s Calculating upgrade... 86s The following packages will be upgraded: 86s base-passwd 87s 1 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 87s Need to get 54.9 kB of archives. 87s After this operation, 9216 B of additional disk space will be used. 87s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 base-passwd amd64 3.6.8 [54.9 kB] 87s dpkg-preconfigure: unable to re-open stdin: No such file or directory 87s Fetched 54.9 kB in 0s (178 kB/s) 88s (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 ... 78551 files and directories currently installed.) 88s Preparing to unpack .../base-passwd_3.6.8_amd64.deb ... 88s Unpacking base-passwd (3.6.8) over (3.6.7) ... 88s Setting up base-passwd (3.6.8) ... 88s Processing triggers for man-db (2.13.1-1) ... 89s autopkgtest [08:43:02]: upgrading testbed (apt dist-upgrade and autopurge) 89s Reading package lists... 90s Building dependency tree... 90s Reading state information... 90s Calculating upgrade... 90s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 90s Reading package lists... 91s Building dependency tree... 91s Reading state information... 91s Solving dependencies... 91s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 94s autopkgtest [08:43:07]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:00:33 UTC 2025 94s autopkgtest [08:43:07]: @@@@@@@@@@@@@@@@@@@@ apt-source golang-github-canonical-go-dqlite 96s Get:1 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (dsc) [2750 B] 96s Get:2 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (tar) [94.6 kB] 96s Get:3 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (diff) [4532 B] 97s gpgv: Signature made Mon Sep 1 19:07:08 2025 UTC 97s gpgv: using RSA key D41A7AD07DF6C5FCA7489F1C29EEE2D6ECF442F9 97s gpgv: issuer "gibmat@debian.org" 97s gpgv: Can't check signature: No public key 97s dpkg-source: warning: cannot verify inline signature for ./golang-github-canonical-go-dqlite_2.0.1-1.dsc: no acceptable signature found 97s autopkgtest [08:43:10]: testing package golang-github-canonical-go-dqlite version 2.0.1-1 97s autopkgtest [08:43:10]: build not needed 98s autopkgtest [08:43:11]: test dh-golang-autopkgtest: preparing testbed 99s Reading package lists... 99s Building dependency tree... 99s Reading state information... 99s Solving dependencies... 100s The following NEW packages will be installed: 100s autoconf automake autopoint autotools-dev build-essential cpp cpp-15 100s cpp-15-x86-64-linux-gnu cpp-x86-64-linux-gnu debhelper debugedit 100s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-15 100s g++-15-x86-64-linux-gnu g++-x86-64-linux-gnu gcc gcc-15 100s gcc-15-x86-64-linux-gnu gcc-x86-64-linux-gnu gettext go-dqlite 100s golang-1.25-go golang-1.25-src golang-any golang-github-burntsushi-toml-dev 100s golang-github-canonical-go-dqlite-dev 100s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 100s golang-github-fsnotify-fsnotify-dev golang-github-google-renameio-dev 100s golang-github-hashicorp-hcl-dev golang-github-kr-fs-dev 100s golang-github-magiconair-properties-dev golang-github-mattn-go-runewidth-dev 100s golang-github-mattn-go-sqlite3-dev golang-github-mitchellh-mapstructure-dev 100s golang-github-pelletier-go-toml-dev golang-github-pelletier-go-toml.v2-dev 100s golang-github-peterh-liner-dev golang-github-pkg-errors-dev 100s golang-github-pkg-sftp-dev golang-github-pmezard-go-difflib-dev 100s golang-github-rican7-retry-dev golang-github-rivo-uniseg-dev 100s golang-github-russross-blackfriday-v2-dev 100s golang-github-shurcool-sanitized-anchor-name-dev 100s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 100s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 100s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 100s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 100s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 100s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 100s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 100s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 100s libasan8 libcc1-0 libdebhelper-perl libdqlite-dev libdqlite0 100s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libhwasan0 libisl23 100s libitm1 liblsan0 liblz4-dev libmpc3 libquadmath0 libsqlite3-dev 100s libstdc++-15-dev libtool libtsan2 libubsan1 libuv1-dev libxxhash-dev m4 100s po-debconf tzdata-legacy 100s 0 upgraded, 96 newly installed, 0 to remove and 0 not upgraded. 100s Need to get 133 MB of archives. 100s After this operation, 564 MB of additional disk space will be used. 100s Get:1 http://ftpmaster.internal/ubuntu resolute/main amd64 m4 amd64 1.4.20-2 [217 kB] 100s Get:2 http://ftpmaster.internal/ubuntu resolute/main amd64 autoconf all 2.72-3.1ubuntu1 [384 kB] 100s Get:3 http://ftpmaster.internal/ubuntu resolute/main amd64 autotools-dev all 20240727.1 [43.4 kB] 100s Get:4 http://ftpmaster.internal/ubuntu resolute/main amd64 automake all 1:1.18.1-2 [581 kB] 101s Get:5 http://ftpmaster.internal/ubuntu resolute/main amd64 autopoint all 0.23.1-2build2 [619 kB] 101s Get:6 http://ftpmaster.internal/ubuntu resolute/main amd64 libisl23 amd64 0.27-1 [685 kB] 101s Get:7 http://ftpmaster.internal/ubuntu resolute/main amd64 libmpc3 amd64 1.3.1-1build3 [57.0 kB] 101s Get:8 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp-15-x86-64-linux-gnu amd64 15.2.0-7ubuntu1 [12.9 MB] 104s Get:9 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp-15 amd64 15.2.0-7ubuntu1 [1026 B] 104s Get:10 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp-x86-64-linux-gnu amd64 4:15.2.0-4ubuntu1 [5746 B] 104s Get:11 http://ftpmaster.internal/ubuntu resolute/main amd64 cpp amd64 4:15.2.0-4ubuntu1 [22.4 kB] 104s Get:12 http://ftpmaster.internal/ubuntu resolute/main amd64 libcc1-0 amd64 15.2.0-7ubuntu1 [47.4 kB] 104s Get:13 http://ftpmaster.internal/ubuntu resolute/main amd64 libgomp1 amd64 15.2.0-7ubuntu1 [151 kB] 104s Get:14 http://ftpmaster.internal/ubuntu resolute/main amd64 libitm1 amd64 15.2.0-7ubuntu1 [29.7 kB] 104s Get:15 http://ftpmaster.internal/ubuntu resolute/main amd64 libasan8 amd64 15.2.0-7ubuntu1 [3071 kB] 105s Get:16 http://ftpmaster.internal/ubuntu resolute/main amd64 liblsan0 amd64 15.2.0-7ubuntu1 [1360 kB] 105s Get:17 http://ftpmaster.internal/ubuntu resolute/main amd64 libtsan2 amd64 15.2.0-7ubuntu1 [2757 kB] 106s Get:18 http://ftpmaster.internal/ubuntu resolute/main amd64 libubsan1 amd64 15.2.0-7ubuntu1 [1210 kB] 106s Get:19 http://ftpmaster.internal/ubuntu resolute/main amd64 libhwasan0 amd64 15.2.0-7ubuntu1 [1685 kB] 107s Get:20 http://ftpmaster.internal/ubuntu resolute/main amd64 libquadmath0 amd64 15.2.0-7ubuntu1 [153 kB] 107s Get:21 http://ftpmaster.internal/ubuntu resolute/main amd64 libgcc-15-dev amd64 15.2.0-7ubuntu1 [2864 kB] 108s Get:22 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc-15-x86-64-linux-gnu amd64 15.2.0-7ubuntu1 [25.4 MB] 123s Get:23 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc-15 amd64 15.2.0-7ubuntu1 [524 kB] 123s Get:24 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc-x86-64-linux-gnu amd64 4:15.2.0-4ubuntu1 [1208 B] 123s Get:25 http://ftpmaster.internal/ubuntu resolute/main amd64 gcc amd64 4:15.2.0-4ubuntu1 [5024 B] 123s Get:26 http://ftpmaster.internal/ubuntu resolute/main amd64 libstdc++-15-dev amd64 15.2.0-7ubuntu1 [2573 kB] 125s Get:27 http://ftpmaster.internal/ubuntu resolute/main amd64 g++-15-x86-64-linux-gnu amd64 15.2.0-7ubuntu1 [14.4 MB] 133s Get:28 http://ftpmaster.internal/ubuntu resolute/main amd64 g++-15 amd64 15.2.0-7ubuntu1 [23.7 kB] 134s Get:29 http://ftpmaster.internal/ubuntu resolute/main amd64 g++-x86-64-linux-gnu amd64 4:15.2.0-4ubuntu1 [966 B] 134s Get:30 http://ftpmaster.internal/ubuntu resolute/main amd64 g++ amd64 4:15.2.0-4ubuntu1 [1100 B] 134s Get:31 http://ftpmaster.internal/ubuntu resolute/main amd64 build-essential amd64 12.12ubuntu1 [5080 B] 134s Get:32 http://ftpmaster.internal/ubuntu resolute/main amd64 libdebhelper-perl all 13.24.2ubuntu1 [95.7 kB] 134s Get:33 http://ftpmaster.internal/ubuntu resolute/main amd64 libtool all 2.5.4-4build1 [169 kB] 134s Get:34 http://ftpmaster.internal/ubuntu resolute/main amd64 dh-autoreconf all 21 [12.5 kB] 134s Get:35 http://ftpmaster.internal/ubuntu resolute/main amd64 libarchive-zip-perl all 1.68-1 [90.2 kB] 134s Get:36 http://ftpmaster.internal/ubuntu resolute/main amd64 libfile-stripnondeterminism-perl all 1.15.0-1 [20.5 kB] 134s Get:37 http://ftpmaster.internal/ubuntu resolute/main amd64 dh-strip-nondeterminism all 1.15.0-1 [5090 B] 134s Get:38 http://ftpmaster.internal/ubuntu resolute/main amd64 debugedit amd64 1:5.2-3 [49.9 kB] 134s Get:39 http://ftpmaster.internal/ubuntu resolute/main amd64 dwz amd64 0.16-2 [115 kB] 134s Get:40 http://ftpmaster.internal/ubuntu resolute/main amd64 gettext amd64 0.23.1-2build2 [1043 kB] 134s Get:41 http://ftpmaster.internal/ubuntu resolute/main amd64 intltool-debian all 0.35.0+20060710.6 [23.2 kB] 134s Get:42 http://ftpmaster.internal/ubuntu resolute/main amd64 po-debconf all 1.0.21+nmu1 [233 kB] 134s Get:43 http://ftpmaster.internal/ubuntu resolute/main amd64 debhelper all 13.24.2ubuntu1 [896 kB] 134s Get:44 http://ftpmaster.internal/ubuntu resolute/universe amd64 libdqlite0 amd64 1.18.2-3 [143 kB] 135s Get:45 http://ftpmaster.internal/ubuntu resolute/universe amd64 go-dqlite amd64 2.0.1-1 [6363 kB] 137s Get:46 http://ftpmaster.internal/ubuntu resolute/main amd64 golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 144s Get:47 http://ftpmaster.internal/ubuntu resolute/main amd64 golang-1.25-go amd64 1.25.0-0ubuntu1 [18.0 MB] 151s Get:48 http://ftpmaster.internal/ubuntu resolute/main amd64 golang-src all 2:1.25~1 [5206 B] 151s Get:49 http://ftpmaster.internal/ubuntu resolute/main amd64 golang-go amd64 2:1.25~1 [44.1 kB] 151s Get:50 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-any amd64 2:1.25~1 [2836 B] 151s Get:51 http://ftpmaster.internal/ubuntu resolute/main amd64 libxxhash-dev amd64 0.8.3-2 [80.6 kB] 151s Get:52 http://ftpmaster.internal/ubuntu resolute/main amd64 liblz4-dev amd64 1.10.0-4build1 [101 kB] 152s Get:53 http://ftpmaster.internal/ubuntu resolute/main amd64 libuv1-dev amd64 1.51.0-2ubuntu1 [140 kB] 152s Get:54 http://ftpmaster.internal/ubuntu resolute/main amd64 libsqlite3-dev amd64 3.46.1-8 [932 kB] 152s Get:55 http://ftpmaster.internal/ubuntu resolute/universe amd64 libdqlite-dev amd64 1.18.2-3 [228 kB] 152s Get:56 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-google-renameio-dev all 2.0.0-2 [10.3 kB] 152s Get:57 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-mattn-go-sqlite3-dev amd64 1.14.32~ds1-1 [84.6 kB] 152s Get:58 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-rivo-uniseg-dev all 0.4.7-1 [258 kB] 152s Get:59 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-mattn-go-runewidth-dev all 0.0.16-2 [17.6 kB] 152s Get:60 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-peterh-liner-dev all 1.2.2-1 [21.3 kB] 152s Get:61 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-pkg-errors-dev all 0.9.1-3 [13.2 kB] 152s Get:62 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-rican7-retry-dev all 0.3.1-2 [7740 B] 152s Get:63 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-pmezard-go-difflib-dev all 1.0.0-3 [12.4 kB] 152s Get:64 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-shurcool-sanitized-anchor-name-dev all 1.0.0-3 [3590 B] 152s Get:65 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-russross-blackfriday-v2-dev all 2.1.0-1 [79.6 kB] 152s Get:66 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-cpuguy83-go-md2man-v2-dev all 2.0.4-1 [11.0 kB] 152s Get:67 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-spf13-pflag-dev all 1.0.10-1 [50.1 kB] 152s Get:68 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-golang-x-sys-dev all 0.22.0-1 [457 kB] 152s Get:69 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-fsnotify-fsnotify-dev all 1.9.0-1 [57.3 kB] 152s Get:70 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-davecgh-go-spew-dev all 1.1.1-3 [31.1 kB] 152s Get:71 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-hashicorp-hcl-dev all 1.0.0-1.1 [58.9 kB] 152s Get:72 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-magiconair-properties-dev all 1.8.7-1 [27.3 kB] 152s Get:73 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-mitchellh-mapstructure-dev all 1.5.0+git20231216.8508981-1 [28.3 kB] 152s Get:74 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-burntsushi-toml-dev all 1.4.0-1 [119 kB] 152s Get:75 http://ftpmaster.internal/ubuntu resolute-proposed/universe amd64 golang-gopkg-yaml.v2-dev all 2.4.3-1 [62.7 kB] 153s Get:76 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-pelletier-go-toml-dev all 1.9.5-1build1 [79.6 kB] 153s Get:77 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-pelletier-go-toml.v2-dev all 2.2.4-1 [878 kB] 153s Get:78 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-kr-fs-dev all 0.1.0-2 [6344 B] 153s Get:79 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-golang-x-term-dev all 0.22.0-1 [14.6 kB] 153s Get:80 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-golang-x-text-dev all 0.22.0-1 [4616 kB] 155s Get:81 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-golang-x-crypto-dev all 1:0.25.0-1 [1724 kB] 155s Get:82 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-pkg-sftp-dev all 1.13.9-1 [108 kB] 155s Get:83 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-spf13-afero-dev all 1.15.0-1 [49.8 kB] 155s Get:84 http://ftpmaster.internal/ubuntu resolute/main amd64 tzdata-legacy all 2025b-5ubuntu1 [99.3 kB] 155s Get:85 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-spf13-cast-dev all 1.10.0-2 [20.5 kB] 155s Get:86 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-stretchr-objx-dev all 0.5.2-1 [26.5 kB] 155s Get:87 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-gopkg-yaml.v3-dev all 3.0.1-3 [76.1 kB] 155s Get:88 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-stretchr-testify-dev all 1.10.0-1 [79.6 kB] 156s Get:89 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-spf13-jwalterweatherman-dev all 1.1.0+really1.1.0-2 [6828 B] 156s Get:90 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-subosito-gotenv-dev all 1.3.0-1 [8808 B] 156s Get:91 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-gopkg-ini.v1-dev all 1.67.0-1 [40.8 kB] 156s Get:92 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-spf13-viper-dev all 1.12.0-1 [71.0 kB] 156s Get:93 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-spf13-cobra-dev all 1.8.1-1 [76.2 kB] 156s Get:94 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-golang-x-sync-dev all 0.7.0-1 [19.5 kB] 156s Get:95 http://ftpmaster.internal/ubuntu resolute/universe amd64 golang-github-canonical-go-dqlite-dev all 2.0.1-1 [71.9 kB] 156s Get:96 http://ftpmaster.internal/ubuntu resolute/main amd64 dh-golang all 1.63 [24.9 kB] 156s Fetched 133 MB in 56s (2377 kB/s) 156s Selecting previously unselected package m4. 156s (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 ... 78551 files and directories currently installed.) 156s Preparing to unpack .../00-m4_1.4.20-2_amd64.deb ... 156s Unpacking m4 (1.4.20-2) ... 156s Selecting previously unselected package autoconf. 156s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ... 156s Unpacking autoconf (2.72-3.1ubuntu1) ... 156s Selecting previously unselected package autotools-dev. 156s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ... 156s Unpacking autotools-dev (20240727.1) ... 156s Selecting previously unselected package automake. 156s Preparing to unpack .../03-automake_1%3a1.18.1-2_all.deb ... 156s Unpacking automake (1:1.18.1-2) ... 156s Selecting previously unselected package autopoint. 156s Preparing to unpack .../04-autopoint_0.23.1-2build2_all.deb ... 156s Unpacking autopoint (0.23.1-2build2) ... 156s Selecting previously unselected package libisl23:amd64. 156s Preparing to unpack .../05-libisl23_0.27-1_amd64.deb ... 156s Unpacking libisl23:amd64 (0.27-1) ... 156s Selecting previously unselected package libmpc3:amd64. 157s Preparing to unpack .../06-libmpc3_1.3.1-1build3_amd64.deb ... 157s Unpacking libmpc3:amd64 (1.3.1-1build3) ... 157s Selecting previously unselected package cpp-15-x86-64-linux-gnu. 157s Preparing to unpack .../07-cpp-15-x86-64-linux-gnu_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking cpp-15-x86-64-linux-gnu (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package cpp-15. 157s Preparing to unpack .../08-cpp-15_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking cpp-15 (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package cpp-x86-64-linux-gnu. 157s Preparing to unpack .../09-cpp-x86-64-linux-gnu_4%3a15.2.0-4ubuntu1_amd64.deb ... 157s Unpacking cpp-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 157s Selecting previously unselected package cpp. 157s Preparing to unpack .../10-cpp_4%3a15.2.0-4ubuntu1_amd64.deb ... 157s Unpacking cpp (4:15.2.0-4ubuntu1) ... 157s Selecting previously unselected package libcc1-0:amd64. 157s Preparing to unpack .../11-libcc1-0_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking libcc1-0:amd64 (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package libgomp1:amd64. 157s Preparing to unpack .../12-libgomp1_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking libgomp1:amd64 (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package libitm1:amd64. 157s Preparing to unpack .../13-libitm1_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking libitm1:amd64 (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package libasan8:amd64. 157s Preparing to unpack .../14-libasan8_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking libasan8:amd64 (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package liblsan0:amd64. 157s Preparing to unpack .../15-liblsan0_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking liblsan0:amd64 (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package libtsan2:amd64. 157s Preparing to unpack .../16-libtsan2_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking libtsan2:amd64 (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package libubsan1:amd64. 157s Preparing to unpack .../17-libubsan1_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking libubsan1:amd64 (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package libhwasan0:amd64. 157s Preparing to unpack .../18-libhwasan0_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking libhwasan0:amd64 (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package libquadmath0:amd64. 157s Preparing to unpack .../19-libquadmath0_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking libquadmath0:amd64 (15.2.0-7ubuntu1) ... 157s Selecting previously unselected package libgcc-15-dev:amd64. 157s Preparing to unpack .../20-libgcc-15-dev_15.2.0-7ubuntu1_amd64.deb ... 157s Unpacking libgcc-15-dev:amd64 (15.2.0-7ubuntu1) ... 158s Selecting previously unselected package gcc-15-x86-64-linux-gnu. 158s Preparing to unpack .../21-gcc-15-x86-64-linux-gnu_15.2.0-7ubuntu1_amd64.deb ... 158s Unpacking gcc-15-x86-64-linux-gnu (15.2.0-7ubuntu1) ... 158s Selecting previously unselected package gcc-15. 158s Preparing to unpack .../22-gcc-15_15.2.0-7ubuntu1_amd64.deb ... 158s Unpacking gcc-15 (15.2.0-7ubuntu1) ... 158s Selecting previously unselected package gcc-x86-64-linux-gnu. 158s Preparing to unpack .../23-gcc-x86-64-linux-gnu_4%3a15.2.0-4ubuntu1_amd64.deb ... 158s Unpacking gcc-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 158s Selecting previously unselected package gcc. 158s Preparing to unpack .../24-gcc_4%3a15.2.0-4ubuntu1_amd64.deb ... 158s Unpacking gcc (4:15.2.0-4ubuntu1) ... 158s Selecting previously unselected package libstdc++-15-dev:amd64. 158s Preparing to unpack .../25-libstdc++-15-dev_15.2.0-7ubuntu1_amd64.deb ... 158s Unpacking libstdc++-15-dev:amd64 (15.2.0-7ubuntu1) ... 158s Selecting previously unselected package g++-15-x86-64-linux-gnu. 158s Preparing to unpack .../26-g++-15-x86-64-linux-gnu_15.2.0-7ubuntu1_amd64.deb ... 158s Unpacking g++-15-x86-64-linux-gnu (15.2.0-7ubuntu1) ... 159s Selecting previously unselected package g++-15. 159s Preparing to unpack .../27-g++-15_15.2.0-7ubuntu1_amd64.deb ... 159s Unpacking g++-15 (15.2.0-7ubuntu1) ... 159s Selecting previously unselected package g++-x86-64-linux-gnu. 159s Preparing to unpack .../28-g++-x86-64-linux-gnu_4%3a15.2.0-4ubuntu1_amd64.deb ... 159s Unpacking g++-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 159s Selecting previously unselected package g++. 159s Preparing to unpack .../29-g++_4%3a15.2.0-4ubuntu1_amd64.deb ... 159s Unpacking g++ (4:15.2.0-4ubuntu1) ... 159s Selecting previously unselected package build-essential. 159s Preparing to unpack .../30-build-essential_12.12ubuntu1_amd64.deb ... 159s Unpacking build-essential (12.12ubuntu1) ... 159s Selecting previously unselected package libdebhelper-perl. 159s Preparing to unpack .../31-libdebhelper-perl_13.24.2ubuntu1_all.deb ... 159s Unpacking libdebhelper-perl (13.24.2ubuntu1) ... 159s Selecting previously unselected package libtool. 159s Preparing to unpack .../32-libtool_2.5.4-4build1_all.deb ... 159s Unpacking libtool (2.5.4-4build1) ... 159s Selecting previously unselected package dh-autoreconf. 159s Preparing to unpack .../33-dh-autoreconf_21_all.deb ... 159s Unpacking dh-autoreconf (21) ... 159s Selecting previously unselected package libarchive-zip-perl. 159s Preparing to unpack .../34-libarchive-zip-perl_1.68-1_all.deb ... 159s Unpacking libarchive-zip-perl (1.68-1) ... 159s Selecting previously unselected package libfile-stripnondeterminism-perl. 159s Preparing to unpack .../35-libfile-stripnondeterminism-perl_1.15.0-1_all.deb ... 159s Unpacking libfile-stripnondeterminism-perl (1.15.0-1) ... 159s Selecting previously unselected package dh-strip-nondeterminism. 159s Preparing to unpack .../36-dh-strip-nondeterminism_1.15.0-1_all.deb ... 159s Unpacking dh-strip-nondeterminism (1.15.0-1) ... 159s Selecting previously unselected package debugedit. 159s Preparing to unpack .../37-debugedit_1%3a5.2-3_amd64.deb ... 159s Unpacking debugedit (1:5.2-3) ... 159s Selecting previously unselected package dwz. 159s Preparing to unpack .../38-dwz_0.16-2_amd64.deb ... 159s Unpacking dwz (0.16-2) ... 159s Selecting previously unselected package gettext. 159s Preparing to unpack .../39-gettext_0.23.1-2build2_amd64.deb ... 159s Unpacking gettext (0.23.1-2build2) ... 159s Selecting previously unselected package intltool-debian. 159s Preparing to unpack .../40-intltool-debian_0.35.0+20060710.6_all.deb ... 159s Unpacking intltool-debian (0.35.0+20060710.6) ... 159s Selecting previously unselected package po-debconf. 159s Preparing to unpack .../41-po-debconf_1.0.21+nmu1_all.deb ... 159s Unpacking po-debconf (1.0.21+nmu1) ... 159s Selecting previously unselected package debhelper. 159s Preparing to unpack .../42-debhelper_13.24.2ubuntu1_all.deb ... 159s Unpacking debhelper (13.24.2ubuntu1) ... 159s Selecting previously unselected package libdqlite0:amd64. 159s Preparing to unpack .../43-libdqlite0_1.18.2-3_amd64.deb ... 159s Unpacking libdqlite0:amd64 (1.18.2-3) ... 159s Selecting previously unselected package go-dqlite. 159s Preparing to unpack .../44-go-dqlite_2.0.1-1_amd64.deb ... 159s Unpacking go-dqlite (2.0.1-1) ... 160s Selecting previously unselected package golang-1.25-src. 160s Preparing to unpack .../45-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 160s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 161s Selecting previously unselected package golang-1.25-go. 161s Preparing to unpack .../46-golang-1.25-go_1.25.0-0ubuntu1_amd64.deb ... 161s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 162s Selecting previously unselected package golang-src. 162s Preparing to unpack .../47-golang-src_2%3a1.25~1_all.deb ... 162s Unpacking golang-src (2:1.25~1) ... 162s Selecting previously unselected package golang-go:amd64. 162s Preparing to unpack .../48-golang-go_2%3a1.25~1_amd64.deb ... 162s Unpacking golang-go:amd64 (2:1.25~1) ... 162s Selecting previously unselected package golang-any:amd64. 162s Preparing to unpack .../49-golang-any_2%3a1.25~1_amd64.deb ... 162s Unpacking golang-any:amd64 (2:1.25~1) ... 162s Selecting previously unselected package libxxhash-dev:amd64. 162s Preparing to unpack .../50-libxxhash-dev_0.8.3-2_amd64.deb ... 162s Unpacking libxxhash-dev:amd64 (0.8.3-2) ... 162s Selecting previously unselected package liblz4-dev:amd64. 162s Preparing to unpack .../51-liblz4-dev_1.10.0-4build1_amd64.deb ... 162s Unpacking liblz4-dev:amd64 (1.10.0-4build1) ... 162s Selecting previously unselected package libuv1-dev:amd64. 162s Preparing to unpack .../52-libuv1-dev_1.51.0-2ubuntu1_amd64.deb ... 162s Unpacking libuv1-dev:amd64 (1.51.0-2ubuntu1) ... 162s Selecting previously unselected package libsqlite3-dev:amd64. 162s Preparing to unpack .../53-libsqlite3-dev_3.46.1-8_amd64.deb ... 162s Unpacking libsqlite3-dev:amd64 (3.46.1-8) ... 162s Selecting previously unselected package libdqlite-dev:amd64. 162s Preparing to unpack .../54-libdqlite-dev_1.18.2-3_amd64.deb ... 162s Unpacking libdqlite-dev:amd64 (1.18.2-3) ... 162s Selecting previously unselected package golang-github-google-renameio-dev. 162s Preparing to unpack .../55-golang-github-google-renameio-dev_2.0.0-2_all.deb ... 162s Unpacking golang-github-google-renameio-dev (2.0.0-2) ... 162s Selecting previously unselected package golang-github-mattn-go-sqlite3-dev:amd64. 162s Preparing to unpack .../56-golang-github-mattn-go-sqlite3-dev_1.14.32~ds1-1_amd64.deb ... 162s Unpacking golang-github-mattn-go-sqlite3-dev:amd64 (1.14.32~ds1-1) ... 162s Selecting previously unselected package golang-github-rivo-uniseg-dev. 162s Preparing to unpack .../57-golang-github-rivo-uniseg-dev_0.4.7-1_all.deb ... 162s Unpacking golang-github-rivo-uniseg-dev (0.4.7-1) ... 162s Selecting previously unselected package golang-github-mattn-go-runewidth-dev. 162s Preparing to unpack .../58-golang-github-mattn-go-runewidth-dev_0.0.16-2_all.deb ... 162s Unpacking golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 162s Selecting previously unselected package golang-github-peterh-liner-dev. 162s Preparing to unpack .../59-golang-github-peterh-liner-dev_1.2.2-1_all.deb ... 162s Unpacking golang-github-peterh-liner-dev (1.2.2-1) ... 162s Selecting previously unselected package golang-github-pkg-errors-dev. 162s Preparing to unpack .../60-golang-github-pkg-errors-dev_0.9.1-3_all.deb ... 162s Unpacking golang-github-pkg-errors-dev (0.9.1-3) ... 162s Selecting previously unselected package golang-github-rican7-retry-dev. 162s Preparing to unpack .../61-golang-github-rican7-retry-dev_0.3.1-2_all.deb ... 162s Unpacking golang-github-rican7-retry-dev (0.3.1-2) ... 162s Selecting previously unselected package golang-github-pmezard-go-difflib-dev. 162s Preparing to unpack .../62-golang-github-pmezard-go-difflib-dev_1.0.0-3_all.deb ... 162s Unpacking golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 162s Selecting previously unselected package golang-github-shurcool-sanitized-anchor-name-dev. 162s Preparing to unpack .../63-golang-github-shurcool-sanitized-anchor-name-dev_1.0.0-3_all.deb ... 162s Unpacking golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 162s Selecting previously unselected package golang-github-russross-blackfriday-v2-dev. 162s Preparing to unpack .../64-golang-github-russross-blackfriday-v2-dev_2.1.0-1_all.deb ... 162s Unpacking golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 162s Selecting previously unselected package golang-github-cpuguy83-go-md2man-v2-dev. 162s Preparing to unpack .../65-golang-github-cpuguy83-go-md2man-v2-dev_2.0.4-1_all.deb ... 162s Unpacking golang-github-cpuguy83-go-md2man-v2-dev (2.0.4-1) ... 162s Selecting previously unselected package golang-github-spf13-pflag-dev. 162s Preparing to unpack .../66-golang-github-spf13-pflag-dev_1.0.10-1_all.deb ... 162s Unpacking golang-github-spf13-pflag-dev (1.0.10-1) ... 162s Selecting previously unselected package golang-golang-x-sys-dev. 162s Preparing to unpack .../67-golang-golang-x-sys-dev_0.22.0-1_all.deb ... 162s Unpacking golang-golang-x-sys-dev (0.22.0-1) ... 163s Selecting previously unselected package golang-github-fsnotify-fsnotify-dev. 163s Preparing to unpack .../68-golang-github-fsnotify-fsnotify-dev_1.9.0-1_all.deb ... 163s Unpacking golang-github-fsnotify-fsnotify-dev (1.9.0-1) ... 163s Selecting previously unselected package golang-github-davecgh-go-spew-dev. 163s Preparing to unpack .../69-golang-github-davecgh-go-spew-dev_1.1.1-3_all.deb ... 163s Unpacking golang-github-davecgh-go-spew-dev (1.1.1-3) ... 163s Selecting previously unselected package golang-github-hashicorp-hcl-dev. 163s Preparing to unpack .../70-golang-github-hashicorp-hcl-dev_1.0.0-1.1_all.deb ... 163s Unpacking golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 163s Selecting previously unselected package golang-github-magiconair-properties-dev. 163s Preparing to unpack .../71-golang-github-magiconair-properties-dev_1.8.7-1_all.deb ... 163s Unpacking golang-github-magiconair-properties-dev (1.8.7-1) ... 163s Selecting previously unselected package golang-github-mitchellh-mapstructure-dev. 163s Preparing to unpack .../72-golang-github-mitchellh-mapstructure-dev_1.5.0+git20231216.8508981-1_all.deb ... 163s Unpacking golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 163s Selecting previously unselected package golang-github-burntsushi-toml-dev. 163s Preparing to unpack .../73-golang-github-burntsushi-toml-dev_1.4.0-1_all.deb ... 163s Unpacking golang-github-burntsushi-toml-dev (1.4.0-1) ... 163s Selecting previously unselected package golang-gopkg-yaml.v2-dev. 163s Preparing to unpack .../74-golang-gopkg-yaml.v2-dev_2.4.3-1_all.deb ... 163s Unpacking golang-gopkg-yaml.v2-dev (2.4.3-1) ... 163s Selecting previously unselected package golang-github-pelletier-go-toml-dev. 163s Preparing to unpack .../75-golang-github-pelletier-go-toml-dev_1.9.5-1build1_all.deb ... 163s Unpacking golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 163s Selecting previously unselected package golang-github-pelletier-go-toml.v2-dev. 163s Preparing to unpack .../76-golang-github-pelletier-go-toml.v2-dev_2.2.4-1_all.deb ... 163s Unpacking golang-github-pelletier-go-toml.v2-dev (2.2.4-1) ... 163s Selecting previously unselected package golang-github-kr-fs-dev. 163s Preparing to unpack .../77-golang-github-kr-fs-dev_0.1.0-2_all.deb ... 163s Unpacking golang-github-kr-fs-dev (0.1.0-2) ... 163s Selecting previously unselected package golang-golang-x-term-dev. 163s Preparing to unpack .../78-golang-golang-x-term-dev_0.22.0-1_all.deb ... 163s Unpacking golang-golang-x-term-dev (0.22.0-1) ... 163s Selecting previously unselected package golang-golang-x-text-dev. 163s Preparing to unpack .../79-golang-golang-x-text-dev_0.22.0-1_all.deb ... 163s Unpacking golang-golang-x-text-dev (0.22.0-1) ... 163s Selecting previously unselected package golang-golang-x-crypto-dev. 163s Preparing to unpack .../80-golang-golang-x-crypto-dev_1%3a0.25.0-1_all.deb ... 163s Unpacking golang-golang-x-crypto-dev (1:0.25.0-1) ... 163s Selecting previously unselected package golang-github-pkg-sftp-dev. 163s Preparing to unpack .../81-golang-github-pkg-sftp-dev_1.13.9-1_all.deb ... 163s Unpacking golang-github-pkg-sftp-dev (1.13.9-1) ... 163s Selecting previously unselected package golang-github-spf13-afero-dev. 163s Preparing to unpack .../82-golang-github-spf13-afero-dev_1.15.0-1_all.deb ... 163s Unpacking golang-github-spf13-afero-dev (1.15.0-1) ... 164s Selecting previously unselected package tzdata-legacy. 164s Preparing to unpack .../83-tzdata-legacy_2025b-5ubuntu1_all.deb ... 164s Unpacking tzdata-legacy (2025b-5ubuntu1) ... 164s Selecting previously unselected package golang-github-spf13-cast-dev. 164s Preparing to unpack .../84-golang-github-spf13-cast-dev_1.10.0-2_all.deb ... 164s Unpacking golang-github-spf13-cast-dev (1.10.0-2) ... 164s Selecting previously unselected package golang-github-stretchr-objx-dev. 164s Preparing to unpack .../85-golang-github-stretchr-objx-dev_0.5.2-1_all.deb ... 164s Unpacking golang-github-stretchr-objx-dev (0.5.2-1) ... 164s Selecting previously unselected package golang-gopkg-yaml.v3-dev. 164s Preparing to unpack .../86-golang-gopkg-yaml.v3-dev_3.0.1-3_all.deb ... 164s Unpacking golang-gopkg-yaml.v3-dev (3.0.1-3) ... 164s Selecting previously unselected package golang-github-stretchr-testify-dev. 164s Preparing to unpack .../87-golang-github-stretchr-testify-dev_1.10.0-1_all.deb ... 164s Unpacking golang-github-stretchr-testify-dev (1.10.0-1) ... 164s Selecting previously unselected package golang-github-spf13-jwalterweatherman-dev. 164s Preparing to unpack .../88-golang-github-spf13-jwalterweatherman-dev_1.1.0+really1.1.0-2_all.deb ... 164s Unpacking golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 164s Selecting previously unselected package golang-github-subosito-gotenv-dev. 164s Preparing to unpack .../89-golang-github-subosito-gotenv-dev_1.3.0-1_all.deb ... 164s Unpacking golang-github-subosito-gotenv-dev (1.3.0-1) ... 164s Selecting previously unselected package golang-gopkg-ini.v1-dev. 164s Preparing to unpack .../90-golang-gopkg-ini.v1-dev_1.67.0-1_all.deb ... 164s Unpacking golang-gopkg-ini.v1-dev (1.67.0-1) ... 164s Selecting previously unselected package golang-github-spf13-viper-dev. 164s Preparing to unpack .../91-golang-github-spf13-viper-dev_1.12.0-1_all.deb ... 164s Unpacking golang-github-spf13-viper-dev (1.12.0-1) ... 164s Selecting previously unselected package golang-github-spf13-cobra-dev. 164s Preparing to unpack .../92-golang-github-spf13-cobra-dev_1.8.1-1_all.deb ... 164s Unpacking golang-github-spf13-cobra-dev (1.8.1-1) ... 164s Selecting previously unselected package golang-golang-x-sync-dev. 164s Preparing to unpack .../93-golang-golang-x-sync-dev_0.7.0-1_all.deb ... 164s Unpacking golang-golang-x-sync-dev (0.7.0-1) ... 164s Selecting previously unselected package golang-github-canonical-go-dqlite-dev. 164s Preparing to unpack .../94-golang-github-canonical-go-dqlite-dev_2.0.1-1_all.deb ... 164s Unpacking golang-github-canonical-go-dqlite-dev (2.0.1-1) ... 164s Selecting previously unselected package dh-golang. 164s Preparing to unpack .../95-dh-golang_1.63_all.deb ... 164s Unpacking dh-golang (1.63) ... 164s Setting up golang-github-pelletier-go-toml.v2-dev (2.2.4-1) ... 164s Setting up libuv1-dev:amd64 (1.51.0-2ubuntu1) ... 164s Setting up golang-gopkg-yaml.v2-dev (2.4.3-1) ... 164s Setting up golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 164s Setting up golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 164s Setting up golang-github-stretchr-objx-dev (0.5.2-1) ... 164s Setting up golang-github-pkg-errors-dev (0.9.1-3) ... 164s Setting up libarchive-zip-perl (1.68-1) ... 164s Setting up golang-gopkg-yaml.v3-dev (3.0.1-3) ... 164s Setting up libdebhelper-perl (13.24.2ubuntu1) ... 164s Setting up golang-github-burntsushi-toml-dev (1.4.0-1) ... 164s Setting up golang-golang-x-sys-dev (0.22.0-1) ... 164s Setting up golang-github-kr-fs-dev (0.1.0-2) ... 164s Setting up golang-github-subosito-gotenv-dev (1.3.0-1) ... 164s Setting up golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 164s Setting up m4 (1.4.20-2) ... 164s Setting up golang-golang-x-term-dev (0.22.0-1) ... 164s Setting up libgomp1:amd64 (15.2.0-7ubuntu1) ... 164s Setting up tzdata-legacy (2025b-5ubuntu1) ... 164s Setting up golang-github-google-renameio-dev (2.0.0-2) ... 164s Setting up golang-github-spf13-pflag-dev (1.0.10-1) ... 164s Setting up golang-github-davecgh-go-spew-dev (1.1.1-3) ... 164s Setting up autotools-dev (20240727.1) ... 164s Setting up libsqlite3-dev:amd64 (3.46.1-8) ... 164s Setting up golang-github-stretchr-testify-dev (1.10.0-1) ... 164s Setting up libquadmath0:amd64 (15.2.0-7ubuntu1) ... 164s Setting up golang-gopkg-ini.v1-dev (1.67.0-1) ... 164s Setting up libmpc3:amd64 (1.3.1-1build3) ... 164s Setting up golang-golang-x-sync-dev (0.7.0-1) ... 164s Setting up autopoint (0.23.1-2build2) ... 164s Setting up golang-github-magiconair-properties-dev (1.8.7-1) ... 164s Setting up golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 164s Setting up golang-github-rican7-retry-dev (0.3.1-2) ... 164s Setting up autoconf (2.72-3.1ubuntu1) ... 164s Setting up libxxhash-dev:amd64 (0.8.3-2) ... 164s Setting up golang-github-rivo-uniseg-dev (0.4.7-1) ... 164s Setting up libubsan1:amd64 (15.2.0-7ubuntu1) ... 164s Setting up dwz (0.16-2) ... 164s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 164s Setting up golang-golang-x-text-dev (0.22.0-1) ... 164s Setting up libhwasan0:amd64 (15.2.0-7ubuntu1) ... 164s Setting up libasan8:amd64 (15.2.0-7ubuntu1) ... 164s Setting up golang-github-spf13-cast-dev (1.10.0-2) ... 164s Setting up golang-github-fsnotify-fsnotify-dev (1.9.0-1) ... 164s Setting up debugedit (1:5.2-3) ... 164s Setting up golang-golang-x-crypto-dev (1:0.25.0-1) ... 164s Setting up golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 164s Setting up golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 164s Setting up libtsan2:amd64 (15.2.0-7ubuntu1) ... 164s Setting up libdqlite0:amd64 (1.18.2-3) ... 164s Setting up libisl23:amd64 (0.27-1) ... 164s Setting up golang-src (2:1.25~1) ... 164s Setting up libcc1-0:amd64 (15.2.0-7ubuntu1) ... 164s Setting up liblsan0:amd64 (15.2.0-7ubuntu1) ... 164s Setting up libitm1:amd64 (15.2.0-7ubuntu1) ... 164s Setting up automake (1:1.18.1-2) ... 164s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 164s Setting up libfile-stripnondeterminism-perl (1.15.0-1) ... 164s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 164s Setting up golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 164s Setting up gettext (0.23.1-2build2) ... 164s Setting up libgcc-15-dev:amd64 (15.2.0-7ubuntu1) ... 164s Setting up golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 164s Setting up golang-go:amd64 (2:1.25~1) ... 164s Setting up golang-github-pkg-sftp-dev (1.13.9-1) ... 164s Setting up cpp-15-x86-64-linux-gnu (15.2.0-7ubuntu1) ... 164s Setting up golang-any:amd64 (2:1.25~1) ... 164s Setting up intltool-debian (0.35.0+20060710.6) ... 164s Setting up gcc-15-x86-64-linux-gnu (15.2.0-7ubuntu1) ... 164s Setting up golang-github-peterh-liner-dev (1.2.2-1) ... 164s Setting up golang-github-mattn-go-sqlite3-dev:amd64 (1.14.32~ds1-1) ... 164s Setting up go-dqlite (2.0.1-1) ... 164s Setting up libstdc++-15-dev:amd64 (15.2.0-7ubuntu1) ... 164s Setting up liblz4-dev:amd64 (1.10.0-4build1) ... 164s Setting up dh-strip-nondeterminism (1.15.0-1) ... 164s Setting up cpp-15 (15.2.0-7ubuntu1) ... 164s Setting up golang-github-spf13-afero-dev (1.15.0-1) ... 164s Setting up cpp-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 164s Setting up libdqlite-dev:amd64 (1.18.2-3) ... 164s Setting up g++-15-x86-64-linux-gnu (15.2.0-7ubuntu1) ... 164s Setting up golang-github-cpuguy83-go-md2man-v2-dev (2.0.4-1) ... 164s Setting up gcc-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 164s Setting up po-debconf (1.0.21+nmu1) ... 164s Setting up gcc-15 (15.2.0-7ubuntu1) ... 164s Setting up golang-github-spf13-viper-dev (1.12.0-1) ... 164s Setting up cpp (4:15.2.0-4ubuntu1) ... 164s Setting up g++-x86-64-linux-gnu (4:15.2.0-4ubuntu1) ... 164s Setting up g++-15 (15.2.0-7ubuntu1) ... 164s Setting up libtool (2.5.4-4build1) ... 164s Setting up gcc (4:15.2.0-4ubuntu1) ... 164s Setting up dh-autoreconf (21) ... 164s Setting up golang-github-spf13-cobra-dev (1.8.1-1) ... 164s Setting up g++ (4:15.2.0-4ubuntu1) ... 164s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 164s Setting up build-essential (12.12ubuntu1) ... 164s Setting up debhelper (13.24.2ubuntu1) ... 164s Setting up golang-github-canonical-go-dqlite-dev (2.0.1-1) ... 164s Setting up dh-golang (1.63) ... 164s Processing triggers for man-db (2.13.1-1) ... 166s Processing triggers for install-info (7.2-5) ... 166s Processing triggers for libc-bin (2.42-0ubuntu3) ... 167s autopkgtest [08:44:20]: test dh-golang-autopkgtest: /usr/bin/dh_golang_autopkgtest 167s autopkgtest [08:44:20]: test dh-golang-autopkgtest: [----------------------- 167s [info] Testing github.com/canonical/go-dqlite... 167s [info] Source code installed by binary package, overriding dh_auto_configure... 168s dh build --builddirectory=_build --buildsystem=golang --with=golang 168s dh_update_autotools_config -O--builddirectory=_build -O--buildsystem=golang 168s dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang 168s debian/rules override_dh_auto_configure 168s make[1]: Entering directory '/tmp/autopkgtest.vcFgEA/autopkgtest_tmp' 168s mkdir -p "_build" 168s # remove existing symlinks, since "cp -a" won't convert them 168s find "_build" -type l -exec rm "{}" \; 168s cp -a /usr/share/gocode/src "_build" 169s make[1]: Leaving directory '/tmp/autopkgtest.vcFgEA/autopkgtest_tmp' 169s dh_auto_build -O--builddirectory=_build -O--buildsystem=golang 169s 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 169s internal/goarch 169s internal/unsafeheader 169s internal/cpu 169s internal/abi 169s internal/bytealg 169s internal/byteorder 169s internal/chacha8rand 169s internal/coverage/rtcov 169s internal/godebugs 169s internal/goexperiment 169s internal/goos 169s internal/profilerecord 169s internal/runtime/atomic 169s internal/runtime/math 170s internal/runtime/strconv 170s internal/runtime/syscall 170s internal/runtime/cgroup 170s internal/runtime/exithook 170s internal/runtime/gc 170s internal/asan 170s internal/msan 170s internal/race 170s internal/runtime/sys 170s internal/stringslite 170s internal/runtime/maps 170s internal/trace/tracev2 170s sync/atomic 170s internal/sync 170s internal/synctest 170s math/bits 170s internal/itoa 170s cmp 170s math 170s runtime 170s unicode/utf8 170s unicode 170s container/list 171s crypto/internal/fips140/alias 171s crypto/internal/fips140deps/byteorder 171s crypto/internal/fips140deps/cpu 171s crypto/internal/fips140/subtle 171s crypto/internal/boring/sig 171s unicode/utf16 171s vendor/golang.org/x/crypto/cryptobyte/asn1 171s vendor/golang.org/x/crypto/internal/alias 171s internal/nettrace 171s encoding 171s log/internal 171s container/ring 171s github.com/rivo/uniseg 175s internal/reflectlite 175s sync 175s errors 175s internal/oserror 175s iter 175s internal/bisect 175s slices 175s syscall 175s internal/godebug 175s strconv 176s reflect 176s time 177s context 177s io 177s path 177s io/fs 177s internal/filepathlite 177s internal/syscall/unix 177s internal/poll 177s internal/fmtsort 178s internal/syscall/execenv 178s internal/testlog 178s bytes 178s os 178s hash 178s crypto 178s crypto/internal/fips140deps/godebug 178s strings 178s crypto/internal/fips140 178s crypto/internal/impl 178s crypto/internal/fips140/sha256 178s crypto/internal/fips140/sha3 179s fmt 179s crypto/internal/fips140/sha512 179s crypto/internal/fips140/hmac 179s crypto/internal/fips140/check 179s crypto/internal/fips140/aes 179s crypto/internal/sysrand 179s crypto/internal/entropy 179s math/rand/v2 179s crypto/internal/randutil 179s crypto/subtle 179s crypto/internal/fips140/drbg 179s crypto/internal/fips140/nistec/fiat 179s crypto/internal/fips140/aes/gcm 179s crypto/internal/fips140only 179s crypto/cipher 179s crypto/internal/boring 180s crypto/aes 180s crypto/des 180s crypto/internal/fips140/edwards25519/field 180s math/rand 180s crypto/internal/fips140/nistec 180s math/big 181s crypto/internal/fips140/ecdh 181s crypto/ecdh 181s crypto/internal/fips140/bigmod 181s crypto/elliptic 181s crypto/internal/boring/bbig 181s crypto/internal/fips140/ecdsa 181s weak 181s crypto/internal/fips140cache 181s crypto/sha3 181s crypto/internal/fips140hash 181s crypto/sha512 181s encoding/asn1 181s crypto/internal/fips140/edwards25519 182s crypto/internal/fips140/ed25519 182s vendor/golang.org/x/crypto/cryptobyte 182s crypto/rand 182s crypto/ed25519 182s crypto/ecdsa 182s crypto/internal/fips140/hkdf 182s crypto/hkdf 182s crypto/hmac 182s crypto/internal/fips140/mlkem 182s crypto/internal/fips140/tls12 182s crypto/internal/fips140/tls13 182s encoding/binary 183s vendor/golang.org/x/sys/cpu 183s crypto/md5 183s vendor/golang.org/x/crypto/chacha20 183s vendor/golang.org/x/crypto/internal/poly1305 183s crypto/rc4 183s vendor/golang.org/x/crypto/chacha20poly1305 183s crypto/internal/fips140/rsa 183s crypto/internal/hpke 183s crypto/sha1 183s crypto/sha256 183s crypto/fips140 183s crypto/rsa 183s crypto/tls/internal/fips140tls 183s crypto/dsa 183s encoding/hex 183s crypto/x509/pkix 183s encoding/base64 183s maps 183s vendor/golang.org/x/net/dns/dnsmessage 183s encoding/pem 183s internal/singleflight 183s unique 184s net/netip 184s runtime/cgo 184s net/url 184s path/filepath 185s sort 185s database/sql/driver 185s database/sql 185s net 185s github.com/Rican7/retry/backoff 186s github.com/Rican7/retry/jitter 186s github.com/Rican7/retry/strategy 186s github.com/Rican7/retry 186s flag 186s bufio 186s internal/sysinfo 186s runtime/debug 186s runtime/trace 186s testing 187s github.com/canonical/go-dqlite/logging 187s github.com/pkg/errors 187s golang.org/x/sync/semaphore 187s io/ioutil 187s github.com/google/renameio 188s github.com/mattn/go-sqlite3 189s crypto/x509 190s crypto/tls 192s github.com/canonical/go-dqlite/internal/protocol 192s regexp/syntax 193s regexp 193s github.com/canonical/go-dqlite/internal/bindings 193s gopkg.in/yaml.v2 194s github.com/canonical/go-dqlite/client 194s github.com/canonical/go-dqlite/tracing 194s github.com/canonical/go-dqlite/driver 195s golang.org/x/sys/unix 195s github.com/canonical/go-dqlite 195s log 195s encoding/json 196s text/tabwriter 196s github.com/canonical/go-dqlite/internal/shell 196s github.com/mattn/go-runewidth 196s github.com/canonical/go-dqlite/app 196s os/signal 196s github.com/peterh/liner 196s github.com/canonical/go-dqlite/benchmark 196s encoding/csv 196s github.com/spf13/pflag 196s text/template/parse 197s text/template 197s compress/flate 198s github.com/spf13/cobra 198s hash/crc32 198s compress/gzip 198s vendor/golang.org/x/text/transform 198s vendor/golang.org/x/text/unicode/bidi 198s vendor/golang.org/x/text/secure/bidirule 198s vendor/golang.org/x/text/unicode/norm 198s github.com/canonical/go-dqlite/cmd/dqlite 199s github.com/canonical/go-dqlite/cmd/dqlite-benchmark 200s vendor/golang.org/x/net/idna 200s net/textproto 200s vendor/golang.org/x/net/http/httpproxy 200s vendor/golang.org/x/net/http/httpguts 200s vendor/golang.org/x/net/http2/hpack 200s mime 200s mime/quotedprintable 201s net/http/httptrace 201s net/http/internal 201s net/http/internal/ascii 201s net/http/internal/httpcommon 201s mime/multipart 201s net/http 204s github.com/canonical/go-dqlite/cmd/dqlite-demo 206s create-stamp debian/debhelper-build-stamp 206s dh_auto_test -O--builddirectory=_build -O--buildsystem=golang 207s 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 236s === RUN ExampleLastEntryInfo 236s --- PASS: ExampleLastEntryInfo (0.00s) 236s PASS 236s ok github.com/canonical/go-dqlite 0.005s 412s === RUN TestNew_PristineDefault 412s --- PASS: TestNew_PristineDefault (0.06s) 412s === RUN TestNew_PristineJoiner 412s app_test.go:1207: 08:45:11.156 - 2: DEBUG: new connection from 127.0.0.1:52570 412s app_test.go:1207: 08:45:11.159 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.167 - 2: DEBUG: new connection from 127.0.0.1:52574 412s app_test.go:1207: 08:45:11.188 - 2: DEBUG: new connection from 127.0.0.1:52586 412s app_test.go:1207: 08:45:11.190 - 3: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.204 - 3: DEBUG: new connection from 127.0.0.1:39864 412s app_test.go:1207: 08:45:11.204 - 2: DEBUG: new connection from 127.0.0.1:52590 412s app_test.go:1207: 08:45:11.206 - 2: DEBUG: new connection from 127.0.0.1:52602 412s app_test.go:1207: 08:45:11.207 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s --- PASS: TestNew_PristineJoiner (0.10s) 412s === RUN TestNew_JoinerRestart 412s app_test.go:1207: 08:45:11.270 - 4: DEBUG: new connection from 127.0.0.1:52606 412s app_test.go:1207: 08:45:11.272 - 4: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.277 - 4: DEBUG: new connection from 127.0.0.1:52616 412s app_test.go:1207: 08:45:11.316 - 4: DEBUG: new connection from 127.0.0.1:52620 412s app_test.go:1207: 08:45:11.318 - 5: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.332 - 4: DEBUG: new connection from 127.0.0.1:52630 412s app_test.go:1207: 08:45:11.332 - 5: DEBUG: new connection from 127.0.0.1:39874 412s app_test.go:1207: 08:45:11.361 - 6: DEBUG: new connection from 127.0.0.1:39884 412s app_test.go:1207: 08:45:11.362 - 4: DEBUG: new connection from 127.0.0.1:52636 412s app_test.go:1207: 08:45:11.364 - 6: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.364 - 6: WARN: attempt 1: server 127.0.0.1:9002: no known leader 412s app_test.go:1207: 08:45:11.369 - 6: DEBUG: new connection from 127.0.0.1:39888 412s app_test.go:1207: 08:45:11.370 - 4: DEBUG: new connection from 127.0.0.1:52650 412s --- PASS: TestNew_JoinerRestart (0.19s) 412s === RUN TestNew_SecondJoiner 412s app_test.go:1207: 08:45:11.435 - 7: DEBUG: new connection from 127.0.0.1:52664 412s app_test.go:1207: 08:45:11.437 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.443 - 7: DEBUG: new connection from 127.0.0.1:52678 412s app_test.go:1207: 08:45:11.474 - 7: DEBUG: new connection from 127.0.0.1:52690 412s app_test.go:1207: 08:45:11.476 - 8: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.489 - 8: DEBUG: new connection from 127.0.0.1:39894 412s app_test.go:1207: 08:45:11.489 - 7: DEBUG: new connection from 127.0.0.1:52694 412s app_test.go:1207: 08:45:11.524 - 7: DEBUG: new connection from 127.0.0.1:52696 412s app_test.go:1207: 08:45:11.526 - 9: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.532 - 7: DEBUG: new connection from 127.0.0.1:52698 412s app_test.go:1207: 08:45:11.533 - 8: DEBUG: new connection from 127.0.0.1:39906 412s app_test.go:1207: 08:45:11.535 - 9: DEBUG: new connection from 127.0.0.1:41640 412s app_test.go:1207: 08:45:11.537 - 9: DEBUG: new connection from 127.0.0.1:41648 412s app_test.go:1207: 08:45:11.544 - 7: DEBUG: new connection from 127.0.0.1:52708 412s app_test.go:1207: 08:45:11.425 - 8: DEBUG: new connection from 127.0.0.1:39922 412s app_test.go:1207: 08:45:11.435 - 7: DEBUG: new connection from 127.0.0.1:52716 412s app_test.go:1207: 08:45:11.427 - 7: DEBUG: new connection from 127.0.0.1:52722 412s app_test.go:1207: 08:45:11.431 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s --- PASS: TestNew_SecondJoiner (2.06s) 412s === RUN TestNew_ThirdJoiner 412s app_test.go:1207: 08:45:11.512 - 10: DEBUG: new connection from 127.0.0.1:52732 412s app_test.go:1207: 08:45:11.515 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.520 - 10: DEBUG: new connection from 127.0.0.1:52734 412s app_test.go:1207: 08:45:11.569 - 10: DEBUG: new connection from 127.0.0.1:52746 412s app_test.go:1207: 08:45:11.571 - 11: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.582 - 11: DEBUG: new connection from 127.0.0.1:39930 412s app_test.go:1207: 08:45:11.583 - 10: DEBUG: new connection from 127.0.0.1:52758 412s app_test.go:1207: 08:45:11.623 - 10: DEBUG: new connection from 127.0.0.1:52764 412s app_test.go:1207: 08:45:11.625 - 12: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.631 - 11: DEBUG: new connection from 127.0.0.1:39934 412s app_test.go:1207: 08:45:11.631 - 10: DEBUG: new connection from 127.0.0.1:52772 412s app_test.go:1207: 08:45:11.632 - 12: DEBUG: new connection from 127.0.0.1:41662 412s app_test.go:1207: 08:45:11.634 - 12: DEBUG: new connection from 127.0.0.1:41678 412s app_test.go:1207: 08:45:11.641 - 10: DEBUG: new connection from 127.0.0.1:52776 412s app_test.go:1207: 08:45:11.490 - 11: DEBUG: new connection from 127.0.0.1:39950 412s app_test.go:1207: 08:45:11.496 - 10: DEBUG: new connection from 127.0.0.1:52780 412s app_test.go:1207: 08:45:11.531 - 10: DEBUG: new connection from 127.0.0.1:52796 412s app_test.go:1207: 08:45:11.533 - 13: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.539 - 11: DEBUG: new connection from 127.0.0.1:39966 412s app_test.go:1207: 08:45:11.539 - 10: DEBUG: new connection from 127.0.0.1:52812 412s app_test.go:1207: 08:45:11.541 - 12: DEBUG: new connection from 127.0.0.1:41692 412s app_test.go:1207: 08:45:11.542 - 13: DEBUG: new connection from 127.0.0.1:38374 412s app_test.go:1207: 08:45:11.544 - 13: DEBUG: new connection from 127.0.0.1:38380 412s app_test.go:1207: 08:45:11.547 - 10: DEBUG: new connection from 127.0.0.1:52814 412s app_test.go:1207: 08:45:11.549 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s --- PASS: TestNew_ThirdJoiner (2.12s) 412s === RUN TestNew_FourthJoiner 412s app_test.go:1207: 08:45:11.639 - 14: DEBUG: new connection from 127.0.0.1:52828 412s app_test.go:1207: 08:45:11.642 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.650 - 14: DEBUG: new connection from 127.0.0.1:52830 412s app_test.go:1207: 08:45:11.696 - 14: DEBUG: new connection from 127.0.0.1:52846 412s app_test.go:1207: 08:45:11.700 - 15: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.712 - 15: DEBUG: new connection from 127.0.0.1:39978 412s app_test.go:1207: 08:45:11.713 - 14: DEBUG: new connection from 127.0.0.1:52858 412s app_test.go:1207: 08:45:11.751 - 14: DEBUG: new connection from 127.0.0.1:52872 412s app_test.go:1207: 08:45:11.753 - 16: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.759 - 14: DEBUG: new connection from 127.0.0.1:52888 412s app_test.go:1207: 08:45:11.759 - 15: DEBUG: new connection from 127.0.0.1:39986 412s app_test.go:1207: 08:45:11.760 - 16: DEBUG: new connection from 127.0.0.1:41696 412s app_test.go:1207: 08:45:11.762 - 16: DEBUG: new connection from 127.0.0.1:41702 412s app_test.go:1207: 08:45:11.768 - 14: DEBUG: new connection from 127.0.0.1:52890 412s app_test.go:1207: 08:45:11.608 - 15: DEBUG: new connection from 127.0.0.1:39988 412s app_test.go:1207: 08:45:11.613 - 14: DEBUG: new connection from 127.0.0.1:52892 412s app_test.go:1207: 08:45:11.648 - 14: DEBUG: new connection from 127.0.0.1:52908 412s app_test.go:1207: 08:45:11.650 - 17: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.657 - 14: DEBUG: new connection from 127.0.0.1:52910 412s app_test.go:1207: 08:45:11.658 - 15: DEBUG: new connection from 127.0.0.1:39994 412s app_test.go:1207: 08:45:11.660 - 16: DEBUG: new connection from 127.0.0.1:41704 412s app_test.go:1207: 08:45:11.661 - 17: DEBUG: new connection from 127.0.0.1:38390 412s app_test.go:1207: 08:45:11.664 - 17: DEBUG: new connection from 127.0.0.1:38404 412s app_test.go:1207: 08:45:11.678 - 14: DEBUG: new connection from 127.0.0.1:52924 412s app_test.go:1207: 08:45:11.704 - 14: DEBUG: new connection from 127.0.0.1:52928 412s app_test.go:1207: 08:45:11.706 - 18: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.712 - 14: DEBUG: new connection from 127.0.0.1:52942 412s app_test.go:1207: 08:45:11.713 - 15: DEBUG: new connection from 127.0.0.1:39996 412s app_test.go:1207: 08:45:11.715 - 16: DEBUG: new connection from 127.0.0.1:41712 412s app_test.go:1207: 08:45:11.716 - 17: DEBUG: new connection from 127.0.0.1:38406 412s app_test.go:1207: 08:45:11.718 - 18: DEBUG: new connection from 127.0.0.1:43046 412s app_test.go:1207: 08:45:11.721 - 18: DEBUG: new connection from 127.0.0.1:43054 412s app_test.go:1207: 08:45:11.723 - 14: DEBUG: new connection from 127.0.0.1:52946 412s app_test.go:1207: 08:45:11.727 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.727 - 14: DEBUG: new connection from 127.0.0.1:52956 412s --- PASS: TestNew_FourthJoiner (2.17s) 412s === RUN TestNew_FifthJoiner 412s app_test.go:1207: 08:45:11.810 - 19: DEBUG: new connection from 127.0.0.1:52958 412s app_test.go:1207: 08:45:11.811 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.816 - 19: DEBUG: new connection from 127.0.0.1:52966 412s app_test.go:1207: 08:45:11.865 - 19: DEBUG: new connection from 127.0.0.1:52976 412s app_test.go:1207: 08:45:11.867 - 20: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.885 - 19: DEBUG: new connection from 127.0.0.1:52978 412s app_test.go:1207: 08:45:11.885 - 20: DEBUG: new connection from 127.0.0.1:40012 412s app_test.go:1207: 08:45:11.923 - 19: DEBUG: new connection from 127.0.0.1:52986 412s app_test.go:1207: 08:45:11.927 - 21: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.934 - 20: DEBUG: new connection from 127.0.0.1:40016 412s app_test.go:1207: 08:45:11.935 - 19: DEBUG: new connection from 127.0.0.1:52994 412s app_test.go:1207: 08:45:11.937 - 21: DEBUG: new connection from 127.0.0.1:41724 412s app_test.go:1207: 08:45:11.940 - 21: DEBUG: new connection from 127.0.0.1:41728 412s app_test.go:1207: 08:45:11.951 - 19: DEBUG: new connection from 127.0.0.1:53010 412s app_test.go:1207: 08:45:11.777 - 20: DEBUG: new connection from 127.0.0.1:40022 412s app_test.go:1207: 08:45:11.781 - 19: DEBUG: new connection from 127.0.0.1:53020 412s app_test.go:1207: 08:45:11.809 - 19: DEBUG: new connection from 127.0.0.1:53266 412s app_test.go:1207: 08:45:11.811 - 22: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.818 - 19: DEBUG: new connection from 127.0.0.1:53270 412s app_test.go:1207: 08:45:11.818 - 20: DEBUG: new connection from 127.0.0.1:51754 412s app_test.go:1207: 08:45:11.820 - 21: DEBUG: new connection from 127.0.0.1:54016 412s app_test.go:1207: 08:45:11.820 - 22: DEBUG: new connection from 127.0.0.1:49510 412s app_test.go:1207: 08:45:11.822 - 22: DEBUG: new connection from 127.0.0.1:49526 412s app_test.go:1207: 08:45:11.850 - 19: DEBUG: new connection from 127.0.0.1:53286 412s app_test.go:1207: 08:45:11.858 - 19: DEBUG: new connection from 127.0.0.1:53302 412s app_test.go:1207: 08:45:11.860 - 23: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.867 - 20: DEBUG: new connection from 127.0.0.1:51768 412s app_test.go:1207: 08:45:11.867 - 19: DEBUG: new connection from 127.0.0.1:53310 412s app_test.go:1207: 08:45:11.869 - 21: DEBUG: new connection from 127.0.0.1:54028 412s app_test.go:1207: 08:45:11.869 - 22: DEBUG: new connection from 127.0.0.1:49534 412s app_test.go:1207: 08:45:11.870 - 23: DEBUG: new connection from 127.0.0.1:55948 412s app_test.go:1207: 08:45:11.873 - 23: DEBUG: new connection from 127.0.0.1:55960 412s app_test.go:1207: 08:45:11.879 - 19: DEBUG: new connection from 127.0.0.1:53322 412s app_test.go:1207: 08:45:11.921 - 19: DEBUG: new connection from 127.0.0.1:53326 412s app_test.go:1207: 08:45:11.927 - 24: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.933 - 19: DEBUG: new connection from 127.0.0.1:53340 412s app_test.go:1207: 08:45:11.933 - 20: DEBUG: new connection from 127.0.0.1:51770 412s app_test.go:1207: 08:45:11.935 - 21: DEBUG: new connection from 127.0.0.1:54038 412s app_test.go:1207: 08:45:11.936 - 22: DEBUG: new connection from 127.0.0.1:49544 412s app_test.go:1207: 08:45:11.937 - 23: DEBUG: new connection from 127.0.0.1:55972 412s app_test.go:1207: 08:45:11.939 - 24: DEBUG: new connection from 127.0.0.1:51710 412s app_test.go:1207: 08:45:11.941 - 24: DEBUG: new connection from 127.0.0.1:51722 412s app_test.go:1207: 08:45:11.942 - 19: DEBUG: new connection from 127.0.0.1:53348 412s app_test.go:1207: 08:45:11.944 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s --- PASS: TestNew_FifthJoiner (2.23s) 412s === RUN TestNew_SixthJoiner 412s app_test.go:1207: 08:45:11.030 - 25: DEBUG: new connection from 127.0.0.1:53350 412s app_test.go:1207: 08:45:11.034 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.039 - 25: DEBUG: new connection from 127.0.0.1:53356 412s app_test.go:1207: 08:45:11.070 - 25: DEBUG: new connection from 127.0.0.1:53364 412s app_test.go:1207: 08:45:11.072 - 26: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.087 - 26: DEBUG: new connection from 127.0.0.1:51780 412s app_test.go:1207: 08:45:11.087 - 25: DEBUG: new connection from 127.0.0.1:53378 412s app_test.go:1207: 08:45:11.120 - 25: DEBUG: new connection from 127.0.0.1:53382 412s app_test.go:1207: 08:45:11.122 - 27: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.129 - 25: DEBUG: new connection from 127.0.0.1:53394 412s app_test.go:1207: 08:45:11.129 - 26: DEBUG: new connection from 127.0.0.1:51796 412s app_test.go:1207: 08:45:11.132 - 27: DEBUG: new connection from 127.0.0.1:54040 412s app_test.go:1207: 08:45:11.134 - 27: DEBUG: new connection from 127.0.0.1:54056 412s app_test.go:1207: 08:45:11.140 - 25: DEBUG: new connection from 127.0.0.1:53398 412s app_test.go:1207: 08:45:11.006 - 26: DEBUG: new connection from 127.0.0.1:51802 412s app_test.go:1207: 08:45:11.011 - 25: DEBUG: new connection from 127.0.0.1:53404 412s app_test.go:1207: 08:45:11.052 - 25: DEBUG: new connection from 127.0.0.1:53410 412s app_test.go:1207: 08:45:11.055 - 28: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.061 - 25: DEBUG: new connection from 127.0.0.1:53424 412s app_test.go:1207: 08:45:11.062 - 26: DEBUG: new connection from 127.0.0.1:51814 412s app_test.go:1207: 08:45:11.062 - 27: DEBUG: new connection from 127.0.0.1:54060 412s app_test.go:1207: 08:45:11.064 - 28: DEBUG: new connection from 127.0.0.1:49550 412s app_test.go:1207: 08:45:11.066 - 28: DEBUG: new connection from 127.0.0.1:49562 412s app_test.go:1207: 08:45:11.073 - 25: DEBUG: new connection from 127.0.0.1:53430 412s app_test.go:1207: 08:45:11.100 - 25: DEBUG: new connection from 127.0.0.1:53432 412s app_test.go:1207: 08:45:11.102 - 29: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.107 - 26: DEBUG: new connection from 127.0.0.1:51830 412s app_test.go:1207: 08:45:11.107 - 25: DEBUG: new connection from 127.0.0.1:53448 412s app_test.go:1207: 08:45:11.108 - 27: DEBUG: new connection from 127.0.0.1:54068 412s app_test.go:1207: 08:45:11.110 - 28: DEBUG: new connection from 127.0.0.1:49578 412s app_test.go:1207: 08:45:11.113 - 29: DEBUG: new connection from 127.0.0.1:55978 412s app_test.go:1207: 08:45:11.119 - 29: DEBUG: new connection from 127.0.0.1:55982 412s app_test.go:1207: 08:45:11.139 - 25: DEBUG: new connection from 127.0.0.1:53460 412s app_test.go:1207: 08:45:11.148 - 25: DEBUG: new connection from 127.0.0.1:53472 412s app_test.go:1207: 08:45:11.149 - 30: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.153 - 25: DEBUG: new connection from 127.0.0.1:53486 412s app_test.go:1207: 08:45:11.153 - 26: DEBUG: new connection from 127.0.0.1:51846 412s app_test.go:1207: 08:45:11.154 - 27: DEBUG: new connection from 127.0.0.1:54082 412s app_test.go:1207: 08:45:11.155 - 28: DEBUG: new connection from 127.0.0.1:49586 412s app_test.go:1207: 08:45:11.157 - 29: DEBUG: new connection from 127.0.0.1:55988 412s app_test.go:1207: 08:45:11.158 - 30: DEBUG: new connection from 127.0.0.1:51734 412s app_test.go:1207: 08:45:11.160 - 30: DEBUG: new connection from 127.0.0.1:51738 412s app_test.go:1207: 08:45:11.177 - 25: DEBUG: new connection from 127.0.0.1:53494 412s app_test.go:1207: 08:45:11.193 - 25: DEBUG: new connection from 127.0.0.1:53508 412s app_test.go:1207: 08:45:11.195 - 31: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.200 - 25: DEBUG: new connection from 127.0.0.1:53520 412s app_test.go:1207: 08:45:11.200 - 26: DEBUG: new connection from 127.0.0.1:51856 412s app_test.go:1207: 08:45:11.202 - 27: DEBUG: new connection from 127.0.0.1:54090 412s app_test.go:1207: 08:45:11.203 - 28: DEBUG: new connection from 127.0.0.1:49596 412s app_test.go:1207: 08:45:11.205 - 29: DEBUG: new connection from 127.0.0.1:55992 412s app_test.go:1207: 08:45:11.206 - 30: DEBUG: new connection from 127.0.0.1:51740 412s app_test.go:1207: 08:45:11.207 - 31: DEBUG: new connection from 127.0.0.1:57462 412s app_test.go:1207: 08:45:11.209 - 25: DEBUG: new connection from 127.0.0.1:53534 412s app_test.go:1207: 08:45:11.211 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s --- PASS: TestNew_SixthJoiner (2.27s) 412s === RUN TestHandover_Voter 412s app_test.go:1207: 08:45:11.292 - 32: DEBUG: new connection from 127.0.0.1:53544 412s app_test.go:1207: 08:45:11.294 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.297 - 32: DEBUG: new connection from 127.0.0.1:53558 412s app_test.go:1207: 08:45:11.333 - 32: DEBUG: new connection from 127.0.0.1:53564 412s app_test.go:1207: 08:45:11.334 - 33: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.343 - 33: DEBUG: new connection from 127.0.0.1:51868 412s app_test.go:1207: 08:45:11.344 - 32: DEBUG: new connection from 127.0.0.1:53580 412s app_test.go:1207: 08:45:11.399 - 32: DEBUG: new connection from 127.0.0.1:53592 412s app_test.go:1207: 08:45:11.401 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.408 - 32: DEBUG: new connection from 127.0.0.1:53594 412s app_test.go:1207: 08:45:11.408 - 33: DEBUG: new connection from 127.0.0.1:51882 412s app_test.go:1207: 08:45:11.410 - 34: DEBUG: new connection from 127.0.0.1:54096 412s app_test.go:1207: 08:45:11.412 - 34: DEBUG: new connection from 127.0.0.1:54106 412s app_test.go:1207: 08:45:11.416 - 32: DEBUG: new connection from 127.0.0.1:53598 412s app_test.go:1207: 08:45:11.273 - 33: DEBUG: new connection from 127.0.0.1:51892 412s app_test.go:1207: 08:45:11.277 - 32: DEBUG: new connection from 127.0.0.1:53608 412s app_test.go:1207: 08:45:11.318 - 32: DEBUG: new connection from 127.0.0.1:53614 412s app_test.go:1207: 08:45:11.320 - 35: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.326 - 32: DEBUG: new connection from 127.0.0.1:53626 412s app_test.go:1207: 08:45:11.326 - 33: DEBUG: new connection from 127.0.0.1:51900 412s app_test.go:1207: 08:45:11.329 - 34: DEBUG: new connection from 127.0.0.1:54118 412s app_test.go:1207: 08:45:11.331 - 35: DEBUG: new connection from 127.0.0.1:49602 412s app_test.go:1207: 08:45:11.334 - 35: DEBUG: new connection from 127.0.0.1:49604 412s app_test.go:1207: 08:45:11.336 - 32: DEBUG: new connection from 127.0.0.1:53632 412s app_test.go:1207: 08:45:11.340 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.341 - 32: DEBUG: new connection from 127.0.0.1:53646 412s app_test.go:1207: 08:45:11.341 - 32: DEBUG: new connection from 127.0.0.1:53654 412s app_test.go:1207: 08:45:11.344 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:45:11.346 - 32: DEBUG: new connection from 127.0.0.1:53662 412s app_test.go:1207: 08:45:11.346 - 33: DEBUG: new connection from 127.0.0.1:51904 412s app_test.go:1207: 08:45:11.349 - 34: DEBUG: new connection from 127.0.0.1:54124 412s app_test.go:1207: 08:45:11.351 - 35: DEBUG: new connection from 127.0.0.1:49620 412s app_test.go:1207: 08:45:11.275 - 34: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 412s --- PASS: TestHandover_Voter (3.10s) 412s === RUN TestHandover_TwoNodes 412s app_test.go:1207: 08:45:11.391 - 36: DEBUG: new connection from 127.0.0.1:53670 412s app_test.go:1207: 08:45:11.393 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.396 - 36: DEBUG: new connection from 127.0.0.1:53684 412s app_test.go:1207: 08:45:11.435 - 36: DEBUG: new connection from 127.0.0.1:53686 412s app_test.go:1207: 08:45:11.438 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.446 - 37: DEBUG: new connection from 127.0.0.1:51918 412s app_test.go:1207: 08:45:11.446 - 36: DEBUG: new connection from 127.0.0.1:53690 412s app_test.go:1207: 08:45:11.450 - 36: DEBUG: new connection from 127.0.0.1:53694 412s app_test.go:1207: 08:45:11.452 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:45:11.453 - 37: DEBUG: new connection from 127.0.0.1:51932 412s app_test.go:1207: 08:45:11.453 - 36: DEBUG: new connection from 127.0.0.1:53708 412s app_test.go:1207: 08:45:11.455 - 37: DEBUG: new connection from 127.0.0.1:51946 412s app_test.go:1207: 08:45:11.461 - 36: DEBUG: new connection from 127.0.0.1:53722 412s app_test.go:1207: 08:45:11.372 - 36: DEBUG: promoted 127.0.0.1:9002 from spare to voter 412s app_test.go:1207: 08:45:11.373 - 37: DEBUG: new connection from 127.0.0.1:51958 412s app_test.go:1207: 08:45:11.373 - 36: DEBUG: new connection from 127.0.0.1:53734 412s app_test.go:1207: 08:45:11.383 - 36: DEBUG: new connection from 127.0.0.1:53738 412s app_test.go:1207: 08:45:11.385 - 36: DEBUG: new connection from 127.0.0.1:53742 412s app_test.go:1207: 08:45:11.386 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 412s app_test.go:1207: 08:45:11.386 - 37: DEBUG: new connection from 127.0.0.1:51962 412s app_test.go:1207: 08:45:11.389 - 36: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 412s app_test.go:1207: 08:45:11.390 - 36: DEBUG: new connection from 127.0.0.1:53752 412s app_test.go:1207: 08:45:11.391 - 37: DEBUG: new connection from 127.0.0.1:51978 412s app_test.go:1207: 08:45:11.394 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 412s app_test.go:1207: 08:45:11.394 - 37: DEBUG: new connection from 127.0.0.1:51994 412s app_test.go:1207: 08:45:11.394 - 37: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 412s --- PASS: TestHandover_TwoNodes (1.08s) 412s === RUN TestHandover_VoterHonorFailureDomain 412s app_test.go:1207: 08:45:11.474 - 38: DEBUG: new connection from 127.0.0.1:53762 412s app_test.go:1207: 08:45:11.476 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.479 - 38: DEBUG: new connection from 127.0.0.1:53766 412s app_test.go:1207: 08:45:11.510 - 38: DEBUG: new connection from 127.0.0.1:53776 412s app_test.go:1207: 08:45:11.512 - 39: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.520 - 38: DEBUG: new connection from 127.0.0.1:53788 412s app_test.go:1207: 08:45:11.521 - 39: DEBUG: new connection from 127.0.0.1:52008 412s app_test.go:1207: 08:45:11.565 - 38: DEBUG: new connection from 127.0.0.1:53802 412s app_test.go:1207: 08:45:11.568 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.572 - 38: DEBUG: new connection from 127.0.0.1:53812 412s app_test.go:1207: 08:45:11.572 - 39: DEBUG: new connection from 127.0.0.1:52022 412s app_test.go:1207: 08:45:11.575 - 40: DEBUG: new connection from 127.0.0.1:54132 412s app_test.go:1207: 08:45:11.576 - 40: DEBUG: new connection from 127.0.0.1:54144 412s app_test.go:1207: 08:45:11.580 - 38: DEBUG: new connection from 127.0.0.1:53822 412s app_test.go:1207: 08:45:11.449 - 39: DEBUG: new connection from 127.0.0.1:52030 412s app_test.go:1207: 08:45:11.454 - 38: DEBUG: new connection from 127.0.0.1:53838 412s app_test.go:1207: 08:45:11.489 - 38: DEBUG: new connection from 127.0.0.1:53844 412s app_test.go:1207: 08:45:11.491 - 41: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.495 - 39: DEBUG: new connection from 127.0.0.1:52032 412s app_test.go:1207: 08:45:11.496 - 38: DEBUG: new connection from 127.0.0.1:53850 412s app_test.go:1207: 08:45:11.498 - 40: DEBUG: new connection from 127.0.0.1:54146 412s app_test.go:1207: 08:45:11.498 - 41: DEBUG: new connection from 127.0.0.1:49634 412s app_test.go:1207: 08:45:11.503 - 41: DEBUG: new connection from 127.0.0.1:49642 412s app_test.go:1207: 08:45:11.512 - 38: DEBUG: new connection from 127.0.0.1:53852 412s app_test.go:1207: 08:45:11.534 - 38: DEBUG: new connection from 127.0.0.1:53862 412s app_test.go:1207: 08:45:11.537 - 42: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.541 - 38: DEBUG: new connection from 127.0.0.1:53872 412s app_test.go:1207: 08:45:11.541 - 39: DEBUG: new connection from 127.0.0.1:52040 412s app_test.go:1207: 08:45:11.543 - 40: DEBUG: new connection from 127.0.0.1:54148 412s app_test.go:1207: 08:45:11.545 - 41: DEBUG: new connection from 127.0.0.1:49646 412s app_test.go:1207: 08:45:11.545 - 42: DEBUG: new connection from 127.0.0.1:55998 412s app_test.go:1207: 08:45:11.550 - 42: DEBUG: new connection from 127.0.0.1:56000 412s app_test.go:1207: 08:45:11.556 - 38: DEBUG: new connection from 127.0.0.1:53874 412s app_test.go:1207: 08:45:11.577 - 38: DEBUG: new connection from 127.0.0.1:53888 412s app_test.go:1207: 08:45:11.579 - 43: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.584 - 39: DEBUG: new connection from 127.0.0.1:52048 412s app_test.go:1207: 08:45:11.584 - 38: DEBUG: new connection from 127.0.0.1:53890 412s app_test.go:1207: 08:45:11.586 - 40: DEBUG: new connection from 127.0.0.1:54164 412s app_test.go:1207: 08:45:11.586 - 41: DEBUG: new connection from 127.0.0.1:49652 412s app_test.go:1207: 08:45:11.589 - 42: DEBUG: new connection from 127.0.0.1:56014 412s app_test.go:1207: 08:45:11.591 - 43: DEBUG: new connection from 127.0.0.1:51742 412s app_test.go:1207: 08:45:11.594 - 43: DEBUG: new connection from 127.0.0.1:51752 412s app_test.go:1207: 08:45:11.595 - 38: DEBUG: new connection from 127.0.0.1:53906 412s app_test.go:1207: 08:45:11.597 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.597 - 38: DEBUG: new connection from 127.0.0.1:53908 412s app_test.go:1207: 08:45:11.598 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:45:11.599 - 38: DEBUG: new connection from 127.0.0.1:53920 412s app_test.go:1207: 08:45:11.599 - 39: DEBUG: new connection from 127.0.0.1:52060 412s app_test.go:1207: 08:45:11.601 - 40: DEBUG: new connection from 127.0.0.1:54176 412s app_test.go:1207: 08:45:11.602 - 38: DEBUG: new connection from 127.0.0.1:53922 412s app_test.go:1207: 08:45:11.604 - 41: DEBUG: new connection from 127.0.0.1:49658 412s app_test.go:1207: 08:45:11.607 - 42: DEBUG: new connection from 127.0.0.1:56020 412s app_test.go:1207: 08:45:11.609 - 43: DEBUG: new connection from 127.0.0.1:51756 412s app_test.go:1207: 08:45:11.455 - 40: DEBUG: promoted 127.0.0.1:9006 from stand-by to voter 412s --- PASS: TestHandover_VoterHonorFailureDomain (3.10s) 412s === RUN TestHandover_SingleNode 412s --- PASS: TestHandover_SingleNode (0.05s) 412s === RUN TestHandover_GracefulShutdown 412s app_test.go:406: 08:45:11.618 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:406: 08:45:11.652 - 1: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:406: 08:45:11.697 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:406: 08:45:11.600 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:406: 08:45:11.605 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:406: 08:45:11.615 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 412s app_test.go:406: 08:45:11.615 - 0: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 412s app_test.go:406: 08:45:11.630 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 412s app_test.go:406: 08:45:11.631 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 412s app_test.go:406: 08:45:11.637 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 412s app_test.go:406: 08:45:11.638 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9003 412s app_test.go:406: 08:45:11.638 - 1: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 412s app_test.go:406: 08:45:11.649 - 2: WARN: attempt 1: server 127.0.0.1:9002: dial: dial tcp 127.0.0.1:9002: connect: connection refused 412s app_test.go:406: 08:45:11.649 - 2: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 412s app_test.go:406: 08:45:11.649 - 2: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 412s --- PASS: TestHandover_GracefulShutdown (2.08s) 412s === RUN TestHandover_StandBy 412s app_test.go:1207: 08:45:11.699 - 44: DEBUG: new connection from 127.0.0.1:41388 412s app_test.go:1207: 08:45:11.702 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.706 - 44: DEBUG: new connection from 127.0.0.1:41394 412s app_test.go:1207: 08:45:11.733 - 44: DEBUG: new connection from 127.0.0.1:41404 412s app_test.go:1207: 08:45:11.735 - 45: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.745 - 44: DEBUG: new connection from 127.0.0.1:41418 412s app_test.go:1207: 08:45:11.745 - 45: DEBUG: new connection from 127.0.0.1:51116 412s app_test.go:1207: 08:45:11.778 - 44: DEBUG: new connection from 127.0.0.1:41420 412s app_test.go:1207: 08:45:11.780 - 46: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:45:11.784 - 45: DEBUG: new connection from 127.0.0.1:51128 412s app_test.go:1207: 08:45:11.785 - 44: DEBUG: new connection from 127.0.0.1:41422 412s app_test.go:1207: 08:45:11.786 - 46: DEBUG: new connection from 127.0.0.1:51156 412s app_test.go:1207: 08:45:11.789 - 46: DEBUG: new connection from 127.0.0.1:51158 412s app_test.go:1207: 08:45:11.794 - 44: DEBUG: new connection from 127.0.0.1:41424 412s app_test.go:1207: 08:46:11.685 - 45: DEBUG: new connection from 127.0.0.1:51138 412s app_test.go:1207: 08:46:11.689 - 44: DEBUG: new connection from 127.0.0.1:41438 412s app_test.go:1207: 08:46:11.735 - 44: DEBUG: new connection from 127.0.0.1:41444 412s app_test.go:1207: 08:46:11.738 - 47: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.744 - 45: DEBUG: new connection from 127.0.0.1:51140 412s app_test.go:1207: 08:46:11.745 - 44: DEBUG: new connection from 127.0.0.1:41450 412s app_test.go:1207: 08:46:11.747 - 46: DEBUG: new connection from 127.0.0.1:51166 412s app_test.go:1207: 08:46:11.749 - 47: DEBUG: new connection from 127.0.0.1:43732 412s app_test.go:1207: 08:46:11.753 - 47: DEBUG: new connection from 127.0.0.1:43740 412s app_test.go:1207: 08:46:11.761 - 44: DEBUG: new connection from 127.0.0.1:41456 412s app_test.go:1207: 08:46:11.779 - 44: DEBUG: new connection from 127.0.0.1:41470 412s app_test.go:1207: 08:46:11.782 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.786 - 44: DEBUG: new connection from 127.0.0.1:41478 412s app_test.go:1207: 08:46:11.786 - 45: DEBUG: new connection from 127.0.0.1:51156 412s app_test.go:1207: 08:46:11.788 - 46: DEBUG: new connection from 127.0.0.1:51180 412s app_test.go:1207: 08:46:11.788 - 47: DEBUG: new connection from 127.0.0.1:43752 412s app_test.go:1207: 08:46:11.791 - 48: DEBUG: new connection from 127.0.0.1:38964 412s app_test.go:1207: 08:46:11.793 - 48: DEBUG: new connection from 127.0.0.1:38968 412s app_test.go:1207: 08:46:11.802 - 44: DEBUG: new connection from 127.0.0.1:41494 412s app_test.go:1207: 08:46:11.823 - 44: DEBUG: new connection from 127.0.0.1:41496 412s app_test.go:1207: 08:46:11.825 - 49: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.829 - 45: DEBUG: new connection from 127.0.0.1:51172 412s app_test.go:1207: 08:46:11.829 - 44: DEBUG: new connection from 127.0.0.1:41510 412s app_test.go:1207: 08:46:11.831 - 46: DEBUG: new connection from 127.0.0.1:51190 412s app_test.go:1207: 08:46:11.832 - 47: DEBUG: new connection from 127.0.0.1:43762 412s app_test.go:1207: 08:46:11.835 - 48: DEBUG: new connection from 127.0.0.1:38976 412s app_test.go:1207: 08:46:11.836 - 49: DEBUG: new connection from 127.0.0.1:58190 412s app_test.go:1207: 08:46:11.838 - 49: DEBUG: new connection from 127.0.0.1:58206 412s app_test.go:1207: 08:46:11.845 - 44: DEBUG: new connection from 127.0.0.1:41522 412s app_test.go:1207: 08:46:11.869 - 44: DEBUG: new connection from 127.0.0.1:41538 412s app_test.go:1207: 08:46:11.872 - 50: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.877 - 45: DEBUG: new connection from 127.0.0.1:51188 412s app_test.go:1207: 08:46:11.877 - 44: DEBUG: new connection from 127.0.0.1:41554 412s app_test.go:1207: 08:46:11.878 - 46: DEBUG: new connection from 127.0.0.1:51204 412s app_test.go:1207: 08:46:11.881 - 47: DEBUG: new connection from 127.0.0.1:43764 412s app_test.go:1207: 08:46:11.882 - 48: DEBUG: new connection from 127.0.0.1:38982 412s app_test.go:1207: 08:46:11.884 - 49: DEBUG: new connection from 127.0.0.1:58210 412s app_test.go:1207: 08:46:11.885 - 50: DEBUG: new connection from 127.0.0.1:47246 412s app_test.go:1207: 08:46:11.887 - 44: DEBUG: new connection from 127.0.0.1:41562 412s app_test.go:1207: 08:46:11.889 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.890 - 44: DEBUG: new connection from 127.0.0.1:41564 412s app_test.go:1207: 08:46:11.891 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.891 - 45: DEBUG: new connection from 127.0.0.1:51192 412s app_test.go:1207: 08:46:11.892 - 44: DEBUG: new connection from 127.0.0.1:41576 412s app_test.go:1207: 08:46:11.893 - 46: DEBUG: new connection from 127.0.0.1:51218 412s app_test.go:1207: 08:46:11.893 - 47: DEBUG: new connection from 127.0.0.1:43776 412s app_test.go:1207: 08:46:11.895 - 48: DEBUG: new connection from 127.0.0.1:38990 412s app_test.go:1207: 08:46:11.895 - 49: DEBUG: new connection from 127.0.0.1:58224 412s app_test.go:1207: 08:46:11.898 - 50: DEBUG: new connection from 127.0.0.1:47256 412s app_test.go:1207: 08:46:11.900 - 50: DEBUG: new connection from 127.0.0.1:47268 412s app_test.go:1207: 08:46:11.902 - 48: DEBUG: promoted 127.0.0.1:9007 from spare to stand-by 412s --- PASS: TestHandover_StandBy (2.28s) 412s === RUN TestHandover_TransferLeadership 412s app_test.go:1207: 08:46:11.972 - 51: DEBUG: new connection from 127.0.0.1:41592 412s app_test.go:1207: 08:46:11.975 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.978 - 51: DEBUG: new connection from 127.0.0.1:41598 412s app_test.go:1207: 08:46:11.004 - 51: DEBUG: new connection from 127.0.0.1:41610 412s app_test.go:1207: 08:46:11.006 - 52: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.016 - 52: DEBUG: new connection from 127.0.0.1:51204 412s app_test.go:1207: 08:46:11.016 - 51: DEBUG: new connection from 127.0.0.1:41624 412s app_test.go:1207: 08:46:11.063 - 51: DEBUG: new connection from 127.0.0.1:41628 412s app_test.go:1207: 08:46:11.065 - 53: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.070 - 51: DEBUG: new connection from 127.0.0.1:41642 412s app_test.go:1207: 08:46:11.070 - 52: DEBUG: new connection from 127.0.0.1:51216 412s app_test.go:1207: 08:46:11.073 - 53: DEBUG: new connection from 127.0.0.1:51230 412s app_test.go:1207: 08:46:11.076 - 53: DEBUG: new connection from 127.0.0.1:51246 412s app_test.go:1207: 08:46:11.081 - 51: DEBUG: new connection from 127.0.0.1:41654 412s app_test.go:1207: 08:46:11.962 - 52: DEBUG: new connection from 127.0.0.1:51222 412s app_test.go:1207: 08:46:11.967 - 51: DEBUG: new connection from 127.0.0.1:41670 412s app_test.go:1207: 08:46:11.982 - 51: DEBUG: new connection from 127.0.0.1:41686 412s app_test.go:1207: 08:46:11.984 - 54: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.989 - 52: DEBUG: new connection from 127.0.0.1:51226 412s app_test.go:1207: 08:46:11.990 - 51: DEBUG: new connection from 127.0.0.1:41700 412s app_test.go:1207: 08:46:11.991 - 53: DEBUG: new connection from 127.0.0.1:51262 412s app_test.go:1207: 08:46:11.992 - 54: DEBUG: new connection from 127.0.0.1:43792 412s app_test.go:1207: 08:46:11.994 - 54: DEBUG: new connection from 127.0.0.1:43806 412s app_test.go:1207: 08:46:11.996 - 51: DEBUG: new connection from 127.0.0.1:41714 412s app_test.go:1207: 08:46:11.998 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.999 - 51: DEBUG: new connection from 127.0.0.1:41716 412s app_test.go:1207: 08:46:11.013 - 51: DEBUG: new connection from 127.0.0.1:41726 412s app_test.go:1207: 08:46:11.014 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.015 - 51: DEBUG: new connection from 127.0.0.1:41732 412s app_test.go:1207: 08:46:11.015 - 52: DEBUG: new connection from 127.0.0.1:51240 412s app_test.go:1207: 08:46:11.017 - 53: DEBUG: new connection from 127.0.0.1:51266 412s app_test.go:1207: 08:46:11.018 - 54: DEBUG: new connection from 127.0.0.1:43814 412s app_test.go:1207: 08:46:11.962 - 51: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 412s app_test.go:1207: 08:46:11.963 - 52: DEBUG: new connection from 127.0.0.1:51250 412s app_test.go:1207: 08:46:11.964 - 51: DEBUG: new connection from 127.0.0.1:41748 412s app_test.go:1207: 08:46:11.967 - 53: DEBUG: new connection from 127.0.0.1:51270 412s app_test.go:1207: 08:46:11.967 - 54: DEBUG: new connection from 127.0.0.1:43828 412s app_test.go:1207: 08:46:11.975 - 53: DEBUG: new connection from 127.0.0.1:51286 412s app_test.go:1207: 08:46:11.977 - 54: DEBUG: new connection from 127.0.0.1:43830 412s app_test.go:1207: 08:46:11.986 - 52: DEBUG: new connection from 127.0.0.1:51262 412s app_test.go:1207: 08:46:11.989 - 52: DEBUG: new connection from 127.0.0.1:51264 412s app_test.go:1207: 08:46:11.990 - 51: DEBUG: new connection from 127.0.0.1:41750 412s app_test.go:1207: 08:46:11.992 - 51: DEBUG: new connection from 127.0.0.1:41762 412s app_test.go:1207: 08:46:11.995 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 412s app_test.go:1207: 08:46:11.996 - 52: DEBUG: new connection from 127.0.0.1:51270 412s app_test.go:1207: 08:46:11.998 - 51: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 412s app_test.go:1207: 08:46:11.000 - 51: DEBUG: new connection from 127.0.0.1:41778 412s app_test.go:1207: 08:46:11.002 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 412s app_test.go:1207: 08:46:11.002 - 52: DEBUG: new connection from 127.0.0.1:51272 412s app_test.go:1207: 08:46:11.004 - 51: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 412s --- PASS: TestHandover_TransferLeadership (3.11s) 412s === RUN TestRolesAdjustment_ReplaceVoter 412s app_test.go:1207: 08:46:11.090 - 55: DEBUG: new connection from 127.0.0.1:41786 412s app_test.go:1207: 08:46:11.093 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.097 - 55: DEBUG: new connection from 127.0.0.1:41792 412s app_test.go:1207: 08:46:11.121 - 55: DEBUG: new connection from 127.0.0.1:41804 412s app_test.go:1207: 08:46:11.124 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.134 - 55: DEBUG: new connection from 127.0.0.1:41814 412s app_test.go:1207: 08:46:11.135 - 56: DEBUG: new connection from 127.0.0.1:51284 412s app_test.go:1207: 08:46:11.165 - 55: DEBUG: new connection from 127.0.0.1:41816 412s app_test.go:1207: 08:46:11.167 - 57: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.173 - 55: DEBUG: new connection from 127.0.0.1:41820 412s app_test.go:1207: 08:46:11.173 - 56: DEBUG: new connection from 127.0.0.1:51288 412s app_test.go:1207: 08:46:11.174 - 57: DEBUG: new connection from 127.0.0.1:51296 412s app_test.go:1207: 08:46:11.178 - 57: DEBUG: new connection from 127.0.0.1:51306 412s app_test.go:1207: 08:46:11.182 - 55: DEBUG: new connection from 127.0.0.1:41830 412s app_test.go:1207: 08:46:11.065 - 56: DEBUG: new connection from 127.0.0.1:51294 412s app_test.go:1207: 08:46:11.070 - 55: DEBUG: new connection from 127.0.0.1:41832 412s app_test.go:1207: 08:46:11.098 - 55: DEBUG: new connection from 127.0.0.1:41836 412s app_test.go:1207: 08:46:11.099 - 55: DEBUG: new connection from 127.0.0.1:41840 412s app_test.go:1207: 08:46:11.100 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.100 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.103 - 55: DEBUG: new connection from 127.0.0.1:41856 412s app_test.go:1207: 08:46:11.104 - 56: DEBUG: new connection from 127.0.0.1:51302 412s app_test.go:1207: 08:46:11.105 - 57: DEBUG: new connection from 127.0.0.1:51318 412s app_test.go:1207: 08:46:11.107 - 55: DEBUG: new connection from 127.0.0.1:41868 412s app_test.go:1207: 08:46:11.107 - 56: DEBUG: new connection from 127.0.0.1:51314 412s app_test.go:1207: 08:46:11.108 - 58: DEBUG: new connection from 127.0.0.1:43836 412s app_test.go:1207: 08:46:11.109 - 57: DEBUG: new connection from 127.0.0.1:51334 412s app_test.go:1207: 08:46:11.110 - 58: DEBUG: new connection from 127.0.0.1:43848 412s app_test.go:1207: 08:46:11.112 - 58: DEBUG: new connection from 127.0.0.1:43858 412s app_test.go:1207: 08:46:11.113 - 58: WARN: assign stand-by role to ourselves: a configuration change is already in progress (5) 412s app_test.go:1207: 08:46:11.114 - 55: DEBUG: new connection from 127.0.0.1:41872 412s app_test.go:1207: 08:46:11.114 - 56: DEBUG: new connection from 127.0.0.1:51330 412s app_test.go:1207: 08:46:11.117 - 55: DEBUG: new connection from 127.0.0.1:41876 412s app_test.go:1207: 08:46:11.118 - 57: DEBUG: new connection from 127.0.0.1:51338 412s app_test.go:1207: 08:46:11.120 - 58: DEBUG: new connection from 127.0.0.1:43864 412s app_test.go:1207: 08:46:11.138 - 55: DEBUG: new connection from 127.0.0.1:41886 412s app_test.go:1207: 08:46:11.139 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.114 - 55: DEBUG: new connection from 127.0.0.1:57004 412s app_test.go:1207: 08:46:11.116 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.119 - 56: DEBUG: new connection from 127.0.0.1:46466 412s app_test.go:1207: 08:46:11.119 - 55: DEBUG: new connection from 127.0.0.1:57008 412s app_test.go:1207: 08:46:11.121 - 57: DEBUG: new connection from 127.0.0.1:52448 412s app_test.go:1207: 08:46:11.123 - 58: DEBUG: new connection from 127.0.0.1:35686 412s app_test.go:1207: 08:46:11.123 - 55: DEBUG: new connection from 127.0.0.1:57010 412s app_test.go:1207: 08:46:11.127 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.134 - 55: DEBUG: new connection from 127.0.0.1:57020 412s app_test.go:1207: 08:46:11.135 - 56: DEBUG: new connection from 127.0.0.1:46474 412s app_test.go:1207: 08:46:11.136 - 58: DEBUG: new connection from 127.0.0.1:35698 412s app_test.go:1207: 08:46:11.141 - 55: DEBUG: new connection from 127.0.0.1:57026 412s app_test.go:1207: 08:46:11.141 - 56: DEBUG: new connection from 127.0.0.1:46486 412s app_test.go:1207: 08:46:11.142 - 55: DEBUG: new connection from 127.0.0.1:57032 412s app_test.go:1207: 08:46:11.144 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.144 - 58: DEBUG: new connection from 127.0.0.1:35706 412s app_test.go:1207: 08:46:11.148 - 55: DEBUG: new connection from 127.0.0.1:57044 412s app_test.go:1207: 08:46:11.149 - 56: DEBUG: new connection from 127.0.0.1:46498 412s app_test.go:1207: 08:46:11.151 - 58: DEBUG: new connection from 127.0.0.1:35708 412s app_test.go:1207: 08:46:11.126 - 55: DEBUG: new connection from 127.0.0.1:57050 412s app_test.go:1207: 08:46:11.130 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.149 - 55: DEBUG: new connection from 127.0.0.1:57054 412s app_test.go:1207: 08:46:11.150 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.157 - 55: DEBUG: new connection from 127.0.0.1:57056 412s app_test.go:1207: 08:46:11.160 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.164 - 55: DEBUG: new connection from 127.0.0.1:57060 412s app_test.go:1207: 08:46:11.165 - 56: DEBUG: new connection from 127.0.0.1:46504 412s app_test.go:1207: 08:46:11.166 - 58: DEBUG: new connection from 127.0.0.1:35714 412s app_test.go:1207: 08:46:11.135 - 55: DEBUG: new connection from 127.0.0.1:57068 412s app_test.go:1207: 08:46:11.136 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.159 - 55: DEBUG: new connection from 127.0.0.1:57076 412s app_test.go:1207: 08:46:11.160 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.170 - 55: DEBUG: new connection from 127.0.0.1:57080 412s app_test.go:1207: 08:46:11.173 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.177 - 55: DEBUG: new connection from 127.0.0.1:57096 412s app_test.go:1207: 08:46:11.177 - 56: DEBUG: new connection from 127.0.0.1:46512 412s app_test.go:1207: 08:46:11.180 - 58: DEBUG: new connection from 127.0.0.1:35728 412s app_test.go:1207: 08:46:11.141 - 55: DEBUG: new connection from 127.0.0.1:57112 412s app_test.go:1207: 08:46:11.143 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.164 - 55: DEBUG: new connection from 127.0.0.1:57116 412s app_test.go:1207: 08:46:11.165 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.184 - 55: DEBUG: new connection from 127.0.0.1:57122 412s app_test.go:1207: 08:46:11.187 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.191 - 56: DEBUG: new connection from 127.0.0.1:46524 412s app_test.go:1207: 08:46:11.191 - 55: DEBUG: new connection from 127.0.0.1:57136 412s app_test.go:1207: 08:46:11.195 - 58: DEBUG: new connection from 127.0.0.1:35730 412s app_test.go:1207: 08:46:11.141 - 55: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 412s app_test.go:1207: 08:46:11.142 - 58: DEBUG: new connection from 127.0.0.1:35740 412s app_test.go:1207: 08:46:11.142 - 56: DEBUG: new connection from 127.0.0.1:46526 412s app_test.go:1207: 08:46:11.142 - 55: DEBUG: new connection from 127.0.0.1:57152 412s app_test.go:1207: 08:46:11.143 - 55: DEBUG: attempt 1: server 127.0.0.1:9004: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:46:11.144 - 55: DEBUG: new connection from 127.0.0.1:57160 412s app_test.go:1207: 08:46:11.148 - 55: DEBUG: new connection from 127.0.0.1:57168 412s app_test.go:1207: 08:46:11.148 - 55: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:46:11.148 - 55: DEBUG: new connection from 127.0.0.1:57180 412s app_test.go:1207: 08:46:11.149 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.150 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s --- PASS: TestRolesAdjustment_ReplaceVoter (11.14s) 412s === RUN TestRolesAdjustment_ReplaceVoterHonorFailureDomain 412s app_test.go:1207: 08:46:11.238 - 59: DEBUG: new connection from 127.0.0.1:57182 412s app_test.go:1207: 08:46:11.240 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.243 - 59: DEBUG: new connection from 127.0.0.1:57188 412s app_test.go:1207: 08:46:11.271 - 59: DEBUG: new connection from 127.0.0.1:57196 412s app_test.go:1207: 08:46:11.273 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.282 - 59: DEBUG: new connection from 127.0.0.1:57208 412s app_test.go:1207: 08:46:11.283 - 60: DEBUG: new connection from 127.0.0.1:46532 412s app_test.go:1207: 08:46:11.323 - 59: DEBUG: new connection from 127.0.0.1:57214 412s app_test.go:1207: 08:46:11.325 - 61: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.330 - 60: DEBUG: new connection from 127.0.0.1:46534 412s app_test.go:1207: 08:46:11.330 - 59: DEBUG: new connection from 127.0.0.1:57228 412s app_test.go:1207: 08:46:11.331 - 61: DEBUG: new connection from 127.0.0.1:52612 412s app_test.go:1207: 08:46:11.333 - 61: DEBUG: new connection from 127.0.0.1:52624 412s app_test.go:1207: 08:46:11.339 - 59: DEBUG: new connection from 127.0.0.1:57238 412s app_test.go:1207: 08:46:11.206 - 60: DEBUG: new connection from 127.0.0.1:46540 412s app_test.go:1207: 08:46:11.213 - 59: DEBUG: new connection from 127.0.0.1:57246 412s app_test.go:1207: 08:46:11.235 - 59: DEBUG: new connection from 127.0.0.1:33624 412s app_test.go:1207: 08:46:11.236 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.241 - 59: DEBUG: new connection from 127.0.0.1:33632 412s app_test.go:1207: 08:46:11.242 - 60: DEBUG: new connection from 127.0.0.1:58654 412s app_test.go:1207: 08:46:11.243 - 61: DEBUG: new connection from 127.0.0.1:43418 412s app_test.go:1207: 08:46:11.245 - 62: DEBUG: new connection from 127.0.0.1:33796 412s app_test.go:1207: 08:46:11.248 - 62: DEBUG: new connection from 127.0.0.1:33808 412s app_test.go:1207: 08:46:11.268 - 59: DEBUG: new connection from 127.0.0.1:33644 412s app_test.go:1207: 08:46:11.273 - 59: DEBUG: new connection from 127.0.0.1:33650 412s app_test.go:1207: 08:46:11.275 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.281 - 60: DEBUG: new connection from 127.0.0.1:58660 412s app_test.go:1207: 08:46:11.282 - 59: DEBUG: new connection from 127.0.0.1:33660 412s app_test.go:1207: 08:46:11.284 - 62: DEBUG: new connection from 127.0.0.1:33816 412s app_test.go:1207: 08:46:11.284 - 61: DEBUG: new connection from 127.0.0.1:43420 412s app_test.go:1207: 08:46:11.286 - 63: DEBUG: new connection from 127.0.0.1:40046 412s app_test.go:1207: 08:46:11.288 - 63: DEBUG: new connection from 127.0.0.1:40058 412s app_test.go:1207: 08:46:11.295 - 59: DEBUG: new connection from 127.0.0.1:33662 412s app_test.go:1207: 08:46:11.324 - 59: DEBUG: new connection from 127.0.0.1:33666 412s app_test.go:1207: 08:46:11.326 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.333 - 60: DEBUG: new connection from 127.0.0.1:58676 412s app_test.go:1207: 08:46:11.333 - 59: DEBUG: new connection from 127.0.0.1:33680 412s app_test.go:1207: 08:46:11.334 - 61: DEBUG: new connection from 127.0.0.1:43424 412s app_test.go:1207: 08:46:11.335 - 62: DEBUG: new connection from 127.0.0.1:33826 412s app_test.go:1207: 08:46:11.337 - 63: DEBUG: new connection from 127.0.0.1:40060 412s app_test.go:1207: 08:46:11.338 - 64: DEBUG: new connection from 127.0.0.1:46526 412s app_test.go:1207: 08:46:11.342 - 64: DEBUG: new connection from 127.0.0.1:46542 412s app_test.go:1207: 08:46:11.358 - 59: DEBUG: new connection from 127.0.0.1:33686 412s app_test.go:1207: 08:46:11.245 - 59: DEBUG: new connection from 127.0.0.1:33696 412s app_test.go:1207: 08:46:11.248 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.253 - 59: DEBUG: new connection from 127.0.0.1:33710 412s app_test.go:1207: 08:46:11.254 - 60: DEBUG: new connection from 127.0.0.1:58684 412s app_test.go:1207: 08:46:11.256 - 62: DEBUG: new connection from 127.0.0.1:33836 412s app_test.go:1207: 08:46:11.256 - 63: DEBUG: new connection from 127.0.0.1:40068 412s app_test.go:1207: 08:46:11.259 - 64: DEBUG: new connection from 127.0.0.1:46546 412s app_test.go:1207: 08:46:11.266 - 60: DEBUG: new connection from 127.0.0.1:58690 412s app_test.go:1207: 08:46:11.266 - 59: DEBUG: new connection from 127.0.0.1:33726 412s app_test.go:1207: 08:46:11.268 - 62: DEBUG: new connection from 127.0.0.1:33838 412s app_test.go:1207: 08:46:11.269 - 63: DEBUG: new connection from 127.0.0.1:40074 412s app_test.go:1207: 08:46:11.271 - 64: DEBUG: new connection from 127.0.0.1:46552 412s app_test.go:1207: 08:46:11.275 - 60: DEBUG: new connection from 127.0.0.1:58698 412s app_test.go:1207: 08:46:11.275 - 59: DEBUG: new connection from 127.0.0.1:33738 412s app_test.go:1207: 08:46:11.277 - 62: DEBUG: new connection from 127.0.0.1:33844 412s app_test.go:1207: 08:46:11.278 - 63: DEBUG: new connection from 127.0.0.1:40080 412s app_test.go:1207: 08:46:11.281 - 64: DEBUG: new connection from 127.0.0.1:46554 412s app_test.go:1207: 08:46:11.285 - 59: DEBUG: new connection from 127.0.0.1:33744 412s app_test.go:1207: 08:46:11.287 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.251 - 59: DEBUG: new connection from 127.0.0.1:33756 412s app_test.go:1207: 08:46:11.253 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.292 - 59: DEBUG: new connection from 127.0.0.1:33766 412s app_test.go:1207: 08:46:11.296 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.344 - 59: DEBUG: new connection from 127.0.0.1:33782 412s app_test.go:1207: 08:46:11.345 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.284 - 59: DEBUG: new connection from 127.0.0.1:33792 412s app_test.go:1207: 08:46:11.286 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.290 - 60: DEBUG: new connection from 127.0.0.1:58706 412s app_test.go:1207: 08:46:11.290 - 59: DEBUG: new connection from 127.0.0.1:33796 412s app_test.go:1207: 08:46:11.291 - 59: DEBUG: new connection from 127.0.0.1:33802 412s app_test.go:1207: 08:46:11.293 - 62: DEBUG: new connection from 127.0.0.1:33858 412s app_test.go:1207: 08:46:11.294 - 63: DEBUG: new connection from 127.0.0.1:40094 412s app_test.go:1207: 08:46:11.296 - 64: DEBUG: new connection from 127.0.0.1:46562 412s app_test.go:1207: 08:46:11.297 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.258 - 59: DEBUG: new connection from 127.0.0.1:33810 412s app_test.go:1207: 08:46:11.260 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.300 - 59: DEBUG: new connection from 127.0.0.1:33826 412s app_test.go:1207: 08:46:11.302 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.350 - 59: DEBUG: new connection from 127.0.0.1:33840 412s app_test.go:1207: 08:46:11.352 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.299 - 59: DEBUG: new connection from 127.0.0.1:53322 412s app_test.go:1207: 08:46:11.300 - 59: DEBUG: new connection from 127.0.0.1:53328 412s app_test.go:1207: 08:46:11.301 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.302 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.305 - 59: DEBUG: new connection from 127.0.0.1:53338 412s app_test.go:1207: 08:46:11.305 - 60: DEBUG: new connection from 127.0.0.1:37720 412s app_test.go:1207: 08:46:11.306 - 62: DEBUG: new connection from 127.0.0.1:45508 412s app_test.go:1207: 08:46:11.309 - 64: DEBUG: new connection from 127.0.0.1:34078 412s app_test.go:1207: 08:46:11.310 - 63: DEBUG: new connection from 127.0.0.1:48342 412s app_test.go:1207: 08:46:11.265 - 59: DEBUG: new connection from 127.0.0.1:53340 412s app_test.go:1207: 08:46:11.267 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.308 - 59: DEBUG: new connection from 127.0.0.1:53350 412s app_test.go:1207: 08:46:11.309 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.356 - 59: DEBUG: new connection from 127.0.0.1:53354 412s app_test.go:1207: 08:46:11.357 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.306 - 59: DEBUG: new connection from 127.0.0.1:53362 412s app_test.go:1207: 08:46:11.309 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.312 - 59: DEBUG: new connection from 127.0.0.1:53368 412s app_test.go:1207: 08:46:11.314 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.317 - 60: DEBUG: new connection from 127.0.0.1:37734 412s app_test.go:1207: 08:46:11.317 - 59: DEBUG: new connection from 127.0.0.1:53376 412s app_test.go:1207: 08:46:11.319 - 62: DEBUG: new connection from 127.0.0.1:45524 412s app_test.go:1207: 08:46:11.319 - 63: DEBUG: new connection from 127.0.0.1:48348 412s app_test.go:1207: 08:46:11.323 - 64: DEBUG: new connection from 127.0.0.1:34094 412s app_test.go:1207: 08:46:11.272 - 59: DEBUG: new connection from 127.0.0.1:53382 412s app_test.go:1207: 08:46:11.274 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.313 - 59: DEBUG: new connection from 127.0.0.1:53396 412s app_test.go:1207: 08:46:11.314 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.364 - 59: DEBUG: new connection from 127.0.0.1:53410 412s app_test.go:1207: 08:46:11.366 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.313 - 59: DEBUG: new connection from 127.0.0.1:53426 412s app_test.go:1207: 08:46:11.315 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.326 - 59: DEBUG: new connection from 127.0.0.1:53432 412s app_test.go:1207: 08:46:11.328 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.331 - 60: DEBUG: new connection from 127.0.0.1:37744 412s app_test.go:1207: 08:46:11.331 - 59: DEBUG: new connection from 127.0.0.1:53448 412s app_test.go:1207: 08:46:11.333 - 62: DEBUG: new connection from 127.0.0.1:45540 412s app_test.go:1207: 08:46:11.334 - 63: DEBUG: new connection from 127.0.0.1:48350 412s app_test.go:1207: 08:46:11.335 - 64: DEBUG: new connection from 127.0.0.1:34110 412s app_test.go:1207: 08:46:11.359 - 63: DEBUG: new connection from 127.0.0.1:48356 412s app_test.go:1207: 08:46:11.359 - 62: DEBUG: new connection from 127.0.0.1:45546 412s app_test.go:1207: 08:46:11.359 - 64: DEBUG: new connection from 127.0.0.1:34112 412s app_test.go:1207: 08:46:11.359 - 60: DEBUG: new connection from 127.0.0.1:37750 412s app_test.go:1207: 08:46:11.360 - 59: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 412s app_test.go:1207: 08:46:11.360 - 59: DEBUG: new connection from 127.0.0.1:53464 412s app_test.go:1207: 08:46:11.363 - 59: DEBUG: attempt 1: server 127.0.0.1:9006: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:46:11.363 - 59: WARN: attempt 1: server 127.0.0.1:9004: no known leader 412s app_test.go:1207: 08:46:11.363 - 59: DEBUG: new connection from 127.0.0.1:53474 412s app_test.go:1207: 08:46:11.363 - 59: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:46:11.363 - 59: DEBUG: new connection from 127.0.0.1:53478 412s app_test.go:1207: 08:46:11.364 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.364 - 59: WARN: attempt 1: server 127.0.0.1:9005: no known leader 412s --- PASS: TestRolesAdjustment_ReplaceVoterHonorFailureDomain (20.23s) 412s === RUN TestRolesAdjustment_ImbalancedFailureDomain 412s app_test.go:1207: 08:46:11.450 - 65: DEBUG: new connection from 127.0.0.1:53492 412s app_test.go:1207: 08:46:11.453 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.458 - 65: DEBUG: new connection from 127.0.0.1:53502 412s app_test.go:1207: 08:46:11.489 - 65: DEBUG: new connection from 127.0.0.1:53516 412s app_test.go:1207: 08:46:11.492 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.500 - 65: DEBUG: new connection from 127.0.0.1:53524 412s app_test.go:1207: 08:46:11.501 - 66: DEBUG: new connection from 127.0.0.1:37766 412s app_test.go:1207: 08:46:11.546 - 65: DEBUG: new connection from 127.0.0.1:53536 412s app_test.go:1207: 08:46:11.548 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.552 - 66: DEBUG: new connection from 127.0.0.1:37778 412s app_test.go:1207: 08:46:11.552 - 65: DEBUG: new connection from 127.0.0.1:53546 412s app_test.go:1207: 08:46:11.554 - 67: DEBUG: new connection from 127.0.0.1:47948 412s app_test.go:1207: 08:46:11.557 - 67: DEBUG: new connection from 127.0.0.1:47960 412s app_test.go:1207: 08:46:11.561 - 65: DEBUG: new connection from 127.0.0.1:53552 412s app_test.go:1207: 08:46:11.436 - 66: DEBUG: new connection from 127.0.0.1:39726 412s app_test.go:1207: 08:46:11.440 - 65: DEBUG: new connection from 127.0.0.1:40728 412s app_test.go:1207: 08:46:11.470 - 65: DEBUG: new connection from 127.0.0.1:40736 412s app_test.go:1207: 08:46:11.473 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.478 - 66: DEBUG: new connection from 127.0.0.1:39738 412s app_test.go:1207: 08:46:11.478 - 65: DEBUG: new connection from 127.0.0.1:40752 412s app_test.go:1207: 08:46:11.480 - 67: DEBUG: new connection from 127.0.0.1:56858 412s app_test.go:1207: 08:46:11.480 - 68: DEBUG: new connection from 127.0.0.1:52640 412s app_test.go:1207: 08:46:11.484 - 68: DEBUG: new connection from 127.0.0.1:52654 412s app_test.go:1207: 08:46:11.492 - 65: DEBUG: new connection from 127.0.0.1:40766 412s app_test.go:1207: 08:46:11.513 - 65: DEBUG: new connection from 127.0.0.1:40772 412s app_test.go:1207: 08:46:11.515 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.520 - 66: DEBUG: new connection from 127.0.0.1:39740 412s app_test.go:1207: 08:46:11.520 - 65: DEBUG: new connection from 127.0.0.1:40786 412s app_test.go:1207: 08:46:11.522 - 67: DEBUG: new connection from 127.0.0.1:56870 412s app_test.go:1207: 08:46:11.524 - 68: DEBUG: new connection from 127.0.0.1:52656 412s app_test.go:1207: 08:46:11.524 - 69: DEBUG: new connection from 127.0.0.1:38908 412s app_test.go:1207: 08:46:11.527 - 69: DEBUG: new connection from 127.0.0.1:38920 412s app_test.go:1207: 08:46:11.532 - 65: DEBUG: new connection from 127.0.0.1:40802 412s app_test.go:1207: 08:46:11.561 - 65: DEBUG: new connection from 127.0.0.1:40816 412s app_test.go:1207: 08:46:11.563 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.567 - 65: DEBUG: new connection from 127.0.0.1:40822 412s app_test.go:1207: 08:46:11.567 - 66: DEBUG: new connection from 127.0.0.1:39748 412s app_test.go:1207: 08:46:11.571 - 67: DEBUG: new connection from 127.0.0.1:56886 412s app_test.go:1207: 08:46:11.571 - 68: DEBUG: new connection from 127.0.0.1:52668 412s app_test.go:1207: 08:46:11.573 - 69: DEBUG: new connection from 127.0.0.1:38932 412s app_test.go:1207: 08:46:11.573 - 70: DEBUG: new connection from 127.0.0.1:50060 412s app_test.go:1207: 08:46:11.576 - 70: DEBUG: new connection from 127.0.0.1:50064 412s app_test.go:1207: 08:46:11.581 - 65: DEBUG: new connection from 127.0.0.1:40830 412s app_test.go:1207: 08:46:11.602 - 65: DEBUG: new connection from 127.0.0.1:40836 412s app_test.go:1207: 08:46:11.603 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.608 - 65: DEBUG: new connection from 127.0.0.1:40846 412s app_test.go:1207: 08:46:11.609 - 66: DEBUG: new connection from 127.0.0.1:39760 412s app_test.go:1207: 08:46:11.611 - 67: DEBUG: new connection from 127.0.0.1:56896 412s app_test.go:1207: 08:46:11.616 - 68: DEBUG: new connection from 127.0.0.1:52674 412s app_test.go:1207: 08:46:11.617 - 69: DEBUG: new connection from 127.0.0.1:38942 412s app_test.go:1207: 08:46:11.618 - 70: DEBUG: new connection from 127.0.0.1:50066 412s app_test.go:1207: 08:46:11.619 - 71: DEBUG: new connection from 127.0.0.1:35402 412s app_test.go:1207: 08:46:11.658 - 65: DEBUG: new connection from 127.0.0.1:40860 412s app_test.go:1207: 08:46:11.661 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.665 - 66: DEBUG: new connection from 127.0.0.1:39774 412s app_test.go:1207: 08:46:11.666 - 65: DEBUG: new connection from 127.0.0.1:40874 412s app_test.go:1207: 08:46:11.668 - 67: DEBUG: new connection from 127.0.0.1:56910 412s app_test.go:1207: 08:46:11.669 - 68: DEBUG: new connection from 127.0.0.1:52684 412s app_test.go:1207: 08:46:11.670 - 69: DEBUG: new connection from 127.0.0.1:38950 412s app_test.go:1207: 08:46:11.671 - 70: DEBUG: new connection from 127.0.0.1:50080 412s app_test.go:1207: 08:46:11.675 - 71: DEBUG: new connection from 127.0.0.1:35406 412s app_test.go:1207: 08:46:11.676 - 72: DEBUG: new connection from 127.0.0.1:51472 412s app_test.go:1207: 08:46:11.460 - 65: DEBUG: new connection from 127.0.0.1:40882 412s app_test.go:1207: 08:46:11.463 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.471 - 66: DEBUG: new connection from 127.0.0.1:39778 412s app_test.go:1207: 08:46:11.471 - 65: DEBUG: new connection from 127.0.0.1:40886 412s app_test.go:1207: 08:46:11.475 - 67: DEBUG: new connection from 127.0.0.1:56916 412s app_test.go:1207: 08:46:11.477 - 68: DEBUG: new connection from 127.0.0.1:52692 412s app_test.go:1207: 08:46:11.478 - 69: DEBUG: new connection from 127.0.0.1:38952 412s app_test.go:1207: 08:46:11.479 - 70: DEBUG: new connection from 127.0.0.1:50090 412s app_test.go:1207: 08:46:11.480 - 71: DEBUG: new connection from 127.0.0.1:35422 412s app_test.go:1207: 08:46:11.481 - 72: DEBUG: new connection from 127.0.0.1:51476 412s app_test.go:1207: 08:46:11.483 - 72: DEBUG: new connection from 127.0.0.1:51490 412s app_test.go:1207: 08:46:11.490 - 65: DEBUG: new connection from 127.0.0.1:40888 412s app_test.go:1207: 08:46:11.504 - 65: DEBUG: new connection from 127.0.0.1:40902 412s app_test.go:1207: 08:46:11.506 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.448 - 65: DEBUG: new connection from 127.0.0.1:40916 412s app_test.go:1207: 08:46:11.448 - 66: DEBUG: new connection from 127.0.0.1:39794 412s app_test.go:1207: 08:46:11.451 - 67: DEBUG: new connection from 127.0.0.1:56926 412s app_test.go:1207: 08:46:11.452 - 68: DEBUG: new connection from 127.0.0.1:52700 412s app_test.go:1207: 08:46:11.454 - 69: DEBUG: new connection from 127.0.0.1:38956 412s app_test.go:1207: 08:46:11.455 - 70: DEBUG: new connection from 127.0.0.1:50094 412s app_test.go:1207: 08:46:11.457 - 71: DEBUG: new connection from 127.0.0.1:35430 412s app_test.go:1207: 08:46:11.457 - 72: DEBUG: new connection from 127.0.0.1:51494 412s app_test.go:1207: 08:46:11.461 - 65: DEBUG: new connection from 127.0.0.1:40932 412s app_test.go:1207: 08:46:11.461 - 66: DEBUG: new connection from 127.0.0.1:39810 412s app_test.go:1207: 08:46:11.466 - 67: DEBUG: new connection from 127.0.0.1:56930 412s app_test.go:1207: 08:46:11.467 - 68: DEBUG: new connection from 127.0.0.1:52716 412s app_test.go:1207: 08:46:11.467 - 69: DEBUG: new connection from 127.0.0.1:38968 412s app_test.go:1207: 08:46:11.469 - 70: DEBUG: new connection from 127.0.0.1:50098 412s app_test.go:1207: 08:46:11.470 - 72: DEBUG: new connection from 127.0.0.1:51498 412s app_test.go:1207: 08:46:11.470 - 71: DEBUG: new connection from 127.0.0.1:35434 412s app_test.go:1207: 08:46:11.442 - 65: DEBUG: new connection from 127.0.0.1:40940 412s app_test.go:1207: 08:46:11.444 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.486 - 65: DEBUG: new connection from 127.0.0.1:40954 412s app_test.go:1207: 08:46:11.487 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.528 - 65: DEBUG: new connection from 127.0.0.1:40968 412s app_test.go:1207: 08:46:11.529 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.577 - 65: DEBUG: new connection from 127.0.0.1:40978 412s app_test.go:1207: 08:46:11.579 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.622 - 65: DEBUG: new connection from 127.0.0.1:40988 412s app_test.go:1207: 08:46:11.626 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.681 - 65: DEBUG: new connection from 127.0.0.1:40992 412s app_test.go:1207: 08:46:11.682 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.510 - 65: DEBUG: new connection from 127.0.0.1:40998 412s app_test.go:1207: 08:46:11.511 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.473 - 65: DEBUG: new connection from 127.0.0.1:41012 412s app_test.go:1207: 08:46:11.476 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.480 - 65: DEBUG: new connection from 127.0.0.1:41022 412s app_test.go:1207: 08:46:11.480 - 66: DEBUG: new connection from 127.0.0.1:39818 412s app_test.go:1207: 08:46:11.481 - 67: DEBUG: new connection from 127.0.0.1:56940 412s app_test.go:1207: 08:46:11.482 - 68: DEBUG: new connection from 127.0.0.1:52718 412s app_test.go:1207: 08:46:11.484 - 69: DEBUG: new connection from 127.0.0.1:38982 412s app_test.go:1207: 08:46:11.484 - 70: DEBUG: new connection from 127.0.0.1:50102 412s app_test.go:1207: 08:46:11.487 - 71: DEBUG: new connection from 127.0.0.1:35442 412s app_test.go:1207: 08:46:11.488 - 72: DEBUG: new connection from 127.0.0.1:51500 412s app_test.go:1207: 08:46:11.448 - 65: DEBUG: new connection from 127.0.0.1:41034 412s app_test.go:1207: 08:46:11.451 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.493 - 65: DEBUG: new connection from 127.0.0.1:41044 412s app_test.go:1207: 08:46:11.494 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.534 - 65: DEBUG: new connection from 127.0.0.1:41056 412s app_test.go:1207: 08:46:11.535 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.583 - 65: DEBUG: new connection from 127.0.0.1:41060 412s app_test.go:1207: 08:46:11.584 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.632 - 65: DEBUG: new connection from 127.0.0.1:41064 412s app_test.go:1207: 08:46:11.633 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.687 - 65: DEBUG: new connection from 127.0.0.1:41072 412s app_test.go:1207: 08:46:11.689 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.984 - 67: DEBUG: new connection from 127.0.0.1:56950 412s app_test.go:1207: 08:46:11.986 - 72: DEBUG: new connection from 127.0.0.1:51510 412s app_test.go:1207: 08:46:11.989 - 66: DEBUG: new connection from 127.0.0.1:39830 412s app_test.go:1207: 08:46:11.991 - 66: DEBUG: new connection from 127.0.0.1:39832 412s app_test.go:1207: 08:46:11.518 - 65: DEBUG: new connection from 127.0.0.1:38316 412s app_test.go:1207: 08:46:11.522 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.492 - 65: DEBUG: new connection from 127.0.0.1:38330 412s app_test.go:1207: 08:46:11.493 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.498 - 66: DEBUG: new connection from 127.0.0.1:41850 412s app_test.go:1207: 08:46:11.499 - 65: DEBUG: new connection from 127.0.0.1:38340 412s app_test.go:1207: 08:46:11.500 - 67: DEBUG: new connection from 127.0.0.1:42506 412s app_test.go:1207: 08:46:11.500 - 68: DEBUG: new connection from 127.0.0.1:47570 412s app_test.go:1207: 08:46:11.502 - 69: DEBUG: new connection from 127.0.0.1:48836 412s app_test.go:1207: 08:46:11.503 - 70: DEBUG: new connection from 127.0.0.1:33482 412s app_test.go:1207: 08:46:11.504 - 71: DEBUG: new connection from 127.0.0.1:45342 412s app_test.go:1207: 08:46:11.505 - 72: DEBUG: new connection from 127.0.0.1:35840 412s app_test.go:1207: 08:46:11.456 - 65: DEBUG: new connection from 127.0.0.1:38344 412s app_test.go:1207: 08:46:11.461 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.499 - 65: DEBUG: new connection from 127.0.0.1:38350 412s app_test.go:1207: 08:46:11.500 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.540 - 65: DEBUG: new connection from 127.0.0.1:38366 412s app_test.go:1207: 08:46:11.542 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.591 - 65: DEBUG: new connection from 127.0.0.1:38372 412s app_test.go:1207: 08:46:11.592 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.639 - 65: DEBUG: new connection from 127.0.0.1:38382 412s app_test.go:1207: 08:46:11.644 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.694 - 65: DEBUG: new connection from 127.0.0.1:38390 412s app_test.go:1207: 08:46:11.697 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.527 - 65: DEBUG: new connection from 127.0.0.1:38396 412s app_test.go:1207: 08:46:11.528 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.508 - 65: DEBUG: new connection from 127.0.0.1:38402 412s app_test.go:1207: 08:46:11.510 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.517 - 65: DEBUG: new connection from 127.0.0.1:38410 412s app_test.go:1207: 08:46:11.517 - 66: DEBUG: new connection from 127.0.0.1:41862 412s app_test.go:1207: 08:46:11.519 - 67: DEBUG: new connection from 127.0.0.1:42516 412s app_test.go:1207: 08:46:11.519 - 68: DEBUG: new connection from 127.0.0.1:47576 412s app_test.go:1207: 08:46:11.521 - 69: DEBUG: new connection from 127.0.0.1:48844 412s app_test.go:1207: 08:46:11.522 - 70: DEBUG: new connection from 127.0.0.1:33498 412s app_test.go:1207: 08:46:11.523 - 71: DEBUG: new connection from 127.0.0.1:45346 412s app_test.go:1207: 08:46:11.524 - 72: DEBUG: new connection from 127.0.0.1:35848 412s app_test.go:1207: 08:46:11.469 - 65: DEBUG: new connection from 127.0.0.1:38422 412s app_test.go:1207: 08:46:11.471 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.505 - 65: DEBUG: new connection from 127.0.0.1:38426 412s app_test.go:1207: 08:46:11.507 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.547 - 65: DEBUG: new connection from 127.0.0.1:38438 412s app_test.go:1207: 08:46:11.550 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.598 - 65: DEBUG: new connection from 127.0.0.1:38444 412s app_test.go:1207: 08:46:11.600 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.648 - 65: DEBUG: new connection from 127.0.0.1:38448 412s app_test.go:1207: 08:46:11.650 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.703 - 65: DEBUG: new connection from 127.0.0.1:38454 412s app_test.go:1207: 08:46:11.704 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.533 - 65: DEBUG: new connection from 127.0.0.1:38464 412s app_test.go:1207: 08:46:11.534 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:46:11.682 - 66: DEBUG: new connection from 127.0.0.1:41876 412s app_test.go:1207: 08:46:11.682 - 67: DEBUG: new connection from 127.0.0.1:42524 412s app_test.go:1207: 08:46:11.683 - 72: DEBUG: new connection from 127.0.0.1:35852 412s app_test.go:1207: 08:46:11.683 - 65: DEBUG: new connection from 127.0.0.1:38468 412s app_test.go:1207: 08:46:11.683 - 70: DEBUG: new connection from 127.0.0.1:33510 412s app_test.go:1207: 08:46:11.683 - 69: DEBUG: new connection from 127.0.0.1:48848 412s app_test.go:1207: 08:46:11.684 - 68: DEBUG: new connection from 127.0.0.1:47586 412s app_test.go:1207: 08:46:11.684 - 71: DEBUG: new connection from 127.0.0.1:45362 412s app_test.go:1207: 08:46:11.688 - 65: WARN: attempt 1: server 127.0.0.1:9002: no known leader 412s app_test.go:1207: 08:46:11.688 - 65: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:46:11.689 - 65: WARN: attempt 1: server 127.0.0.1:9007: no known leader 412s app_test.go:1207: 08:46:11.689 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.689 - 65: WARN: attempt 1: server 127.0.0.1:9005: no known leader 412s app_test.go:1207: 08:46:11.689 - 65: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: operation was canceled 412s app_test.go:1207: 08:46:11.689 - 65: DEBUG: new connection from 127.0.0.1:38484 412s app_test.go:1207: 08:46:11.688 - 65: WARN: attempt 1: server 127.0.0.1:9004: no known leader 412s app_test.go:1207: 08:46:11.690 - 65: DEBUG: attempt 1: server 127.0.0.1:9008: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:46:11.690 - 65: WARN: attempt 1: server 127.0.0.1:9001: context canceled 412s app_test.go:1207: 08:46:11.690 - 65: WARN: attempt 1: server 127.0.0.1:9006: no known leader 412s --- PASS: TestRolesAdjustment_ImbalancedFailureDomain (20.35s) 412s === RUN TestRolesAdjustment_ReplaceVoterHonorWeight 412s app_test.go:1207: 08:46:11.807 - 73: DEBUG: new connection from 127.0.0.1:38488 412s app_test.go:1207: 08:46:11.810 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.813 - 73: DEBUG: new connection from 127.0.0.1:38492 412s app_test.go:1207: 08:46:11.840 - 73: DEBUG: new connection from 127.0.0.1:38506 412s app_test.go:1207: 08:46:11.842 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.852 - 74: DEBUG: new connection from 127.0.0.1:41878 412s app_test.go:1207: 08:46:11.853 - 73: DEBUG: new connection from 127.0.0.1:38510 412s app_test.go:1207: 08:46:11.892 - 73: DEBUG: new connection from 127.0.0.1:38512 412s app_test.go:1207: 08:46:11.895 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.899 - 73: DEBUG: new connection from 127.0.0.1:38520 412s app_test.go:1207: 08:46:11.900 - 74: DEBUG: new connection from 127.0.0.1:41888 412s app_test.go:1207: 08:46:11.901 - 75: DEBUG: new connection from 127.0.0.1:42538 412s app_test.go:1207: 08:46:11.903 - 75: DEBUG: new connection from 127.0.0.1:42552 412s app_test.go:1207: 08:46:11.909 - 73: DEBUG: new connection from 127.0.0.1:38536 412s app_test.go:1207: 08:46:11.789 - 74: DEBUG: new connection from 127.0.0.1:38386 412s app_test.go:1207: 08:46:11.795 - 73: DEBUG: new connection from 127.0.0.1:42412 412s app_test.go:1207: 08:46:11.813 - 73: DEBUG: new connection from 127.0.0.1:42418 412s app_test.go:1207: 08:46:11.815 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.819 - 73: DEBUG: new connection from 127.0.0.1:42424 412s app_test.go:1207: 08:46:11.819 - 74: DEBUG: new connection from 127.0.0.1:38400 412s app_test.go:1207: 08:46:11.821 - 75: DEBUG: new connection from 127.0.0.1:33276 412s app_test.go:1207: 08:46:11.823 - 76: DEBUG: new connection from 127.0.0.1:57288 412s app_test.go:1207: 08:46:11.825 - 76: DEBUG: new connection from 127.0.0.1:57302 412s app_test.go:1207: 08:46:11.844 - 73: DEBUG: new connection from 127.0.0.1:42440 412s app_test.go:1207: 08:46:11.852 - 73: DEBUG: new connection from 127.0.0.1:42448 412s app_test.go:1207: 08:46:11.854 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.859 - 74: DEBUG: new connection from 127.0.0.1:38406 412s app_test.go:1207: 08:46:11.859 - 73: DEBUG: new connection from 127.0.0.1:42456 412s app_test.go:1207: 08:46:11.863 - 75: DEBUG: new connection from 127.0.0.1:33282 412s app_test.go:1207: 08:46:11.863 - 76: DEBUG: new connection from 127.0.0.1:57308 412s app_test.go:1207: 08:46:11.865 - 77: DEBUG: new connection from 127.0.0.1:39542 412s app_test.go:1207: 08:46:11.868 - 77: DEBUG: new connection from 127.0.0.1:39546 412s app_test.go:1207: 08:46:11.881 - 73: DEBUG: new connection from 127.0.0.1:42464 412s app_test.go:1207: 08:46:11.901 - 73: DEBUG: new connection from 127.0.0.1:42478 412s app_test.go:1207: 08:46:11.905 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:46:11.911 - 74: DEBUG: new connection from 127.0.0.1:38408 412s app_test.go:1207: 08:46:11.911 - 73: DEBUG: new connection from 127.0.0.1:42486 412s app_test.go:1207: 08:46:11.913 - 75: DEBUG: new connection from 127.0.0.1:33288 412s app_test.go:1207: 08:46:11.914 - 76: DEBUG: new connection from 127.0.0.1:57320 412s app_test.go:1207: 08:46:11.915 - 77: DEBUG: new connection from 127.0.0.1:39560 412s app_test.go:1207: 08:46:11.916 - 78: DEBUG: new connection from 127.0.0.1:52616 412s app_test.go:1207: 08:46:11.918 - 78: DEBUG: new connection from 127.0.0.1:52626 412s app_test.go:1207: 08:46:11.926 - 73: DEBUG: new connection from 127.0.0.1:42488 412s app_test.go:1207: 08:47:11.817 - 73: DEBUG: new connection from 127.0.0.1:42490 412s app_test.go:1207: 08:47:11.820 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.825 - 74: DEBUG: new connection from 127.0.0.1:38416 412s app_test.go:1207: 08:47:11.825 - 73: DEBUG: new connection from 127.0.0.1:42496 412s app_test.go:1207: 08:47:11.826 - 76: DEBUG: new connection from 127.0.0.1:57332 412s app_test.go:1207: 08:47:11.827 - 77: DEBUG: new connection from 127.0.0.1:39564 412s app_test.go:1207: 08:47:11.829 - 78: DEBUG: new connection from 127.0.0.1:52636 412s app_test.go:1207: 08:47:11.834 - 73: DEBUG: new connection from 127.0.0.1:42498 412s app_test.go:1207: 08:47:11.834 - 74: DEBUG: new connection from 127.0.0.1:38426 412s app_test.go:1207: 08:47:11.836 - 76: DEBUG: new connection from 127.0.0.1:57344 412s app_test.go:1207: 08:47:11.837 - 77: DEBUG: new connection from 127.0.0.1:39570 412s app_test.go:1207: 08:47:11.838 - 78: DEBUG: new connection from 127.0.0.1:52652 412s app_test.go:1207: 08:47:11.842 - 73: DEBUG: new connection from 127.0.0.1:42508 412s app_test.go:1207: 08:47:11.843 - 74: DEBUG: new connection from 127.0.0.1:38432 412s app_test.go:1207: 08:47:11.846 - 76: DEBUG: new connection from 127.0.0.1:57356 412s app_test.go:1207: 08:47:11.846 - 77: DEBUG: new connection from 127.0.0.1:39582 412s app_test.go:1207: 08:47:11.850 - 78: DEBUG: new connection from 127.0.0.1:52664 412s app_test.go:1207: 08:47:11.856 - 73: DEBUG: new connection from 127.0.0.1:42510 412s app_test.go:1207: 08:47:11.857 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.829 - 73: DEBUG: new connection from 127.0.0.1:42518 412s app_test.go:1207: 08:47:11.831 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.870 - 73: DEBUG: new connection from 127.0.0.1:42530 412s app_test.go:1207: 08:47:11.872 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.921 - 73: DEBUG: new connection from 127.0.0.1:42542 412s app_test.go:1207: 08:47:11.923 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.853 - 73: DEBUG: new connection from 127.0.0.1:42548 412s app_test.go:1207: 08:47:11.854 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.859 - 73: DEBUG: new connection from 127.0.0.1:42560 412s app_test.go:1207: 08:47:11.859 - 74: DEBUG: new connection from 127.0.0.1:38436 412s app_test.go:1207: 08:47:11.861 - 76: DEBUG: new connection from 127.0.0.1:57362 412s app_test.go:1207: 08:47:11.863 - 73: DEBUG: new connection from 127.0.0.1:42562 412s app_test.go:1207: 08:47:11.866 - 77: DEBUG: new connection from 127.0.0.1:39596 412s app_test.go:1207: 08:47:11.866 - 78: DEBUG: new connection from 127.0.0.1:52676 412s app_test.go:1207: 08:47:11.867 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.837 - 73: DEBUG: new connection from 127.0.0.1:42574 412s app_test.go:1207: 08:47:11.838 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.877 - 73: DEBUG: new connection from 127.0.0.1:42582 412s app_test.go:1207: 08:47:11.879 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.928 - 73: DEBUG: new connection from 127.0.0.1:42592 412s app_test.go:1207: 08:47:11.930 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.871 - 73: DEBUG: new connection from 127.0.0.1:37600 412s app_test.go:1207: 08:47:11.871 - 73: DEBUG: new connection from 127.0.0.1:37612 412s app_test.go:1207: 08:47:11.872 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.873 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.876 - 73: DEBUG: new connection from 127.0.0.1:37620 412s app_test.go:1207: 08:47:11.876 - 74: DEBUG: new connection from 127.0.0.1:43756 412s app_test.go:1207: 08:47:11.878 - 76: DEBUG: new connection from 127.0.0.1:59512 412s app_test.go:1207: 08:47:11.878 - 77: DEBUG: new connection from 127.0.0.1:44034 412s app_test.go:1207: 08:47:11.881 - 78: DEBUG: new connection from 127.0.0.1:49052 412s app_test.go:1207: 08:47:11.844 - 73: DEBUG: new connection from 127.0.0.1:37632 412s app_test.go:1207: 08:47:11.845 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.884 - 73: DEBUG: new connection from 127.0.0.1:37646 412s app_test.go:1207: 08:47:11.886 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.937 - 73: DEBUG: new connection from 127.0.0.1:37648 412s app_test.go:1207: 08:47:11.939 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.876 - 73: DEBUG: new connection from 127.0.0.1:37654 412s app_test.go:1207: 08:47:11.878 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.884 - 73: DEBUG: new connection from 127.0.0.1:37656 412s app_test.go:1207: 08:47:11.886 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.890 - 73: DEBUG: new connection from 127.0.0.1:37664 412s app_test.go:1207: 08:47:11.890 - 74: DEBUG: new connection from 127.0.0.1:43772 412s app_test.go:1207: 08:47:11.892 - 76: DEBUG: new connection from 127.0.0.1:59526 412s app_test.go:1207: 08:47:11.893 - 77: DEBUG: new connection from 127.0.0.1:44044 412s app_test.go:1207: 08:47:11.894 - 78: DEBUG: new connection from 127.0.0.1:49068 412s app_test.go:1207: 08:47:11.850 - 73: DEBUG: new connection from 127.0.0.1:37672 412s app_test.go:1207: 08:47:11.853 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.890 - 73: DEBUG: new connection from 127.0.0.1:37674 412s app_test.go:1207: 08:47:11.892 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.945 - 73: DEBUG: new connection from 127.0.0.1:37688 412s app_test.go:1207: 08:47:11.946 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.884 - 73: DEBUG: new connection from 127.0.0.1:37696 412s app_test.go:1207: 08:47:11.885 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.897 - 73: DEBUG: new connection from 127.0.0.1:37700 412s app_test.go:1207: 08:47:11.898 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.902 - 74: DEBUG: new connection from 127.0.0.1:43788 412s app_test.go:1207: 08:47:11.902 - 73: DEBUG: new connection from 127.0.0.1:37706 412s app_test.go:1207: 08:47:11.904 - 76: DEBUG: new connection from 127.0.0.1:59538 412s app_test.go:1207: 08:47:11.904 - 77: DEBUG: new connection from 127.0.0.1:44052 412s app_test.go:1207: 08:47:11.906 - 78: DEBUG: new connection from 127.0.0.1:49072 412s app_test.go:1207: 08:47:11.930 - 77: DEBUG: new connection from 127.0.0.1:44056 412s app_test.go:1207: 08:47:11.931 - 78: DEBUG: new connection from 127.0.0.1:49082 412s app_test.go:1207: 08:47:11.931 - 76: DEBUG: new connection from 127.0.0.1:59548 412s app_test.go:1207: 08:47:11.931 - 74: DEBUG: new connection from 127.0.0.1:43796 412s app_test.go:1207: 08:47:11.931 - 73: DEBUG: new connection from 127.0.0.1:37718 412s app_test.go:1207: 08:47:11.931 - 73: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 412s app_test.go:1207: 08:47:11.933 - 73: WARN: attempt 1: server 127.0.0.1:9006: no known leader 412s app_test.go:1207: 08:47:11.934 - 73: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:47:11.934 - 73: DEBUG: new connection from 127.0.0.1:37734 412s app_test.go:1207: 08:47:11.934 - 73: DEBUG: attempt 1: server 127.0.0.1:9005: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:47:11.934 - 73: WARN: attempt 1: server 127.0.0.1:9004: no known leader 412s app_test.go:1207: 08:47:11.935 - 73: DEBUG: new connection from 127.0.0.1:37744 412s app_test.go:1207: 08:47:11.935 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s --- PASS: TestRolesAdjustment_ReplaceVoterHonorWeight (20.23s) 412s === RUN TestRolesAdjustment_CantReplaceVoter 412s app_test.go:1207: 08:47:11.035 - 79: DEBUG: new connection from 127.0.0.1:37754 412s app_test.go:1207: 08:47:11.037 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.040 - 79: DEBUG: new connection from 127.0.0.1:37758 412s app_test.go:1207: 08:47:11.068 - 79: DEBUG: new connection from 127.0.0.1:37774 412s app_test.go:1207: 08:47:11.070 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.083 - 79: DEBUG: new connection from 127.0.0.1:37780 412s app_test.go:1207: 08:47:11.083 - 80: DEBUG: new connection from 127.0.0.1:43798 412s app_test.go:1207: 08:47:11.112 - 79: DEBUG: new connection from 127.0.0.1:37788 412s app_test.go:1207: 08:47:11.114 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.119 - 79: DEBUG: new connection from 127.0.0.1:37790 412s app_test.go:1207: 08:47:11.120 - 80: DEBUG: new connection from 127.0.0.1:43812 412s app_test.go:1207: 08:47:11.121 - 81: DEBUG: new connection from 127.0.0.1:45450 412s app_test.go:1207: 08:47:11.123 - 81: DEBUG: new connection from 127.0.0.1:45458 412s app_test.go:1207: 08:47:11.128 - 79: DEBUG: new connection from 127.0.0.1:37792 412s app_test.go:1207: 08:47:11.019 - 80: DEBUG: new connection from 127.0.0.1:51078 412s app_test.go:1207: 08:47:11.025 - 79: DEBUG: new connection from 127.0.0.1:51694 412s app_test.go:1207: 08:47:11.055 - 79: DEBUG: new connection from 127.0.0.1:51698 412s app_test.go:1207: 08:47:11.058 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.062 - 79: DEBUG: new connection from 127.0.0.1:51708 412s app_test.go:1207: 08:47:11.062 - 80: DEBUG: new connection from 127.0.0.1:51090 412s app_test.go:1207: 08:47:11.063 - 81: DEBUG: new connection from 127.0.0.1:53724 412s app_test.go:1207: 08:47:11.064 - 82: DEBUG: new connection from 127.0.0.1:37188 412s app_test.go:1207: 08:47:11.066 - 82: DEBUG: new connection from 127.0.0.1:37194 412s app_test.go:1207: 08:47:11.044 - 79: DEBUG: new connection from 127.0.0.1:51714 412s app_test.go:1207: 08:47:11.046 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.050 - 80: DEBUG: new connection from 127.0.0.1:51106 412s app_test.go:1207: 08:47:11.051 - 79: DEBUG: new connection from 127.0.0.1:51716 412s app_test.go:1207: 08:47:11.086 - 79: DEBUG: new connection from 127.0.0.1:51720 412s app_test.go:1207: 08:47:11.088 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.055 - 79: DEBUG: new connection from 127.0.0.1:51722 412s app_test.go:1207: 08:47:11.058 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.061 - 80: DEBUG: new connection from 127.0.0.1:51118 412s app_test.go:1207: 08:47:11.061 - 79: DEBUG: new connection from 127.0.0.1:51736 412s app_test.go:1207: 08:47:11.093 - 79: DEBUG: new connection from 127.0.0.1:51746 412s app_test.go:1207: 08:47:11.095 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.065 - 79: DEBUG: new connection from 127.0.0.1:60002 412s app_test.go:1207: 08:47:11.066 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.071 - 79: DEBUG: new connection from 127.0.0.1:60012 412s app_test.go:1207: 08:47:11.071 - 80: DEBUG: new connection from 127.0.0.1:50100 412s app_test.go:1207: 08:47:11.102 - 79: DEBUG: new connection from 127.0.0.1:60016 412s app_test.go:1207: 08:47:11.103 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.079 - 79: WARN: attempt 1: server 127.0.0.1:9004: dial: dial tcp 127.0.0.1:9004: connect: connection refused 412s app_test.go:1207: 08:47:11.079 - 79: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 412s app_test.go:1207: 08:47:11.080 - 80: DEBUG: new connection from 127.0.0.1:50108 412s app_test.go:1207: 08:47:11.080 - 79: DEBUG: new connection from 127.0.0.1:60028 412s app_test.go:1207: 08:47:11.086 - 79: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:47:11.086 - 79: DEBUG: new connection from 127.0.0.1:60036 412s app_test.go:1207: 08:47:11.087 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s --- PASS: TestRolesAdjustment_CantReplaceVoter (14.13s) 412s === RUN TestRolesAdjustment_ReplaceStandBy 412s app_test.go:1207: 08:47:11.176 - 83: DEBUG: new connection from 127.0.0.1:60042 412s app_test.go:1207: 08:47:11.179 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.182 - 83: DEBUG: new connection from 127.0.0.1:60056 412s app_test.go:1207: 08:47:11.222 - 83: DEBUG: new connection from 127.0.0.1:60060 412s app_test.go:1207: 08:47:11.224 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.235 - 84: DEBUG: new connection from 127.0.0.1:50116 412s app_test.go:1207: 08:47:11.235 - 83: DEBUG: new connection from 127.0.0.1:60062 412s app_test.go:1207: 08:47:11.264 - 83: DEBUG: new connection from 127.0.0.1:60078 412s app_test.go:1207: 08:47:11.267 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.272 - 84: DEBUG: new connection from 127.0.0.1:50126 412s app_test.go:1207: 08:47:11.272 - 83: DEBUG: new connection from 127.0.0.1:60094 412s app_test.go:1207: 08:47:11.274 - 85: DEBUG: new connection from 127.0.0.1:39368 412s app_test.go:1207: 08:47:11.277 - 85: DEBUG: new connection from 127.0.0.1:39382 412s app_test.go:1207: 08:47:11.282 - 83: DEBUG: new connection from 127.0.0.1:60104 412s app_test.go:1207: 08:47:11.144 - 84: DEBUG: new connection from 127.0.0.1:50130 412s app_test.go:1207: 08:47:11.149 - 83: DEBUG: new connection from 127.0.0.1:60118 412s app_test.go:1207: 08:47:11.177 - 83: DEBUG: new connection from 127.0.0.1:60130 412s app_test.go:1207: 08:47:11.179 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.183 - 83: DEBUG: new connection from 127.0.0.1:60132 412s app_test.go:1207: 08:47:11.184 - 84: DEBUG: new connection from 127.0.0.1:50136 412s app_test.go:1207: 08:47:11.186 - 85: DEBUG: new connection from 127.0.0.1:39392 412s app_test.go:1207: 08:47:11.186 - 86: DEBUG: new connection from 127.0.0.1:41388 412s app_test.go:1207: 08:47:11.189 - 86: DEBUG: new connection from 127.0.0.1:41396 412s app_test.go:1207: 08:47:11.213 - 83: DEBUG: new connection from 127.0.0.1:60144 412s app_test.go:1207: 08:47:11.223 - 83: DEBUG: new connection from 127.0.0.1:60156 412s app_test.go:1207: 08:47:11.226 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.231 - 83: DEBUG: new connection from 127.0.0.1:60164 412s app_test.go:1207: 08:47:11.231 - 84: DEBUG: new connection from 127.0.0.1:50142 412s app_test.go:1207: 08:47:11.233 - 85: DEBUG: new connection from 127.0.0.1:39398 412s app_test.go:1207: 08:47:11.235 - 86: DEBUG: new connection from 127.0.0.1:41400 412s app_test.go:1207: 08:47:11.236 - 87: DEBUG: new connection from 127.0.0.1:44720 412s app_test.go:1207: 08:47:11.238 - 87: DEBUG: new connection from 127.0.0.1:44726 412s app_test.go:1207: 08:47:11.246 - 83: DEBUG: new connection from 127.0.0.1:60180 412s app_test.go:1207: 08:47:11.268 - 83: DEBUG: new connection from 127.0.0.1:60188 412s app_test.go:1207: 08:47:11.270 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.278 - 83: DEBUG: new connection from 127.0.0.1:60190 412s app_test.go:1207: 08:47:11.278 - 84: DEBUG: new connection from 127.0.0.1:50154 412s app_test.go:1207: 08:47:11.280 - 85: DEBUG: new connection from 127.0.0.1:39402 412s app_test.go:1207: 08:47:11.281 - 86: DEBUG: new connection from 127.0.0.1:41414 412s app_test.go:1207: 08:47:11.282 - 87: DEBUG: new connection from 127.0.0.1:44732 412s app_test.go:1207: 08:47:11.283 - 88: DEBUG: new connection from 127.0.0.1:53332 412s app_test.go:1207: 08:47:11.288 - 88: DEBUG: new connection from 127.0.0.1:53346 412s app_test.go:1207: 08:47:11.314 - 83: DEBUG: new connection from 127.0.0.1:60194 412s app_test.go:1207: 08:47:11.325 - 83: DEBUG: new connection from 127.0.0.1:60200 412s app_test.go:1207: 08:47:11.327 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.334 - 83: DEBUG: new connection from 127.0.0.1:60204 412s app_test.go:1207: 08:47:11.334 - 84: DEBUG: new connection from 127.0.0.1:50158 412s app_test.go:1207: 08:47:11.336 - 85: DEBUG: new connection from 127.0.0.1:39410 412s app_test.go:1207: 08:47:11.337 - 86: DEBUG: new connection from 127.0.0.1:41424 412s app_test.go:1207: 08:47:11.338 - 87: DEBUG: new connection from 127.0.0.1:44748 412s app_test.go:1207: 08:47:11.339 - 88: DEBUG: new connection from 127.0.0.1:53348 412s app_test.go:1207: 08:47:11.342 - 89: DEBUG: new connection from 127.0.0.1:49754 412s app_test.go:1207: 08:47:11.185 - 83: DEBUG: new connection from 127.0.0.1:60208 412s app_test.go:1207: 08:47:11.186 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.189 - 84: DEBUG: new connection from 127.0.0.1:50168 412s app_test.go:1207: 08:47:11.190 - 83: DEBUG: new connection from 127.0.0.1:60224 412s app_test.go:1207: 08:47:11.191 - 85: DEBUG: new connection from 127.0.0.1:39418 412s app_test.go:1207: 08:47:11.192 - 86: DEBUG: new connection from 127.0.0.1:41432 412s app_test.go:1207: 08:47:11.193 - 88: DEBUG: new connection from 127.0.0.1:53364 412s app_test.go:1207: 08:47:11.196 - 89: DEBUG: new connection from 127.0.0.1:49764 412s app_test.go:1207: 08:47:11.198 - 89: DEBUG: new connection from 127.0.0.1:49772 412s app_test.go:1207: 08:47:11.200 - 83: DEBUG: new connection from 127.0.0.1:60230 412s app_test.go:1207: 08:47:11.201 - 84: DEBUG: new connection from 127.0.0.1:50178 412s app_test.go:1207: 08:47:11.202 - 85: DEBUG: new connection from 127.0.0.1:39432 412s app_test.go:1207: 08:47:11.204 - 86: DEBUG: new connection from 127.0.0.1:41438 412s app_test.go:1207: 08:47:11.205 - 83: DEBUG: new connection from 127.0.0.1:60246 412s app_test.go:1207: 08:47:11.209 - 88: DEBUG: new connection from 127.0.0.1:53370 412s app_test.go:1207: 08:47:11.209 - 89: DEBUG: new connection from 127.0.0.1:49784 412s app_test.go:1207: 08:47:11.213 - 83: DEBUG: new connection from 127.0.0.1:60252 412s app_test.go:1207: 08:47:11.215 - 84: DEBUG: new connection from 127.0.0.1:50186 412s app_test.go:1207: 08:47:11.217 - 85: DEBUG: new connection from 127.0.0.1:39438 412s app_test.go:1207: 08:47:11.218 - 86: DEBUG: new connection from 127.0.0.1:41444 412s app_test.go:1207: 08:47:11.219 - 88: DEBUG: new connection from 127.0.0.1:53386 412s app_test.go:1207: 08:47:11.220 - 89: DEBUG: new connection from 127.0.0.1:49796 412s app_test.go:1207: 08:47:11.238 - 83: DEBUG: new connection from 127.0.0.1:60260 412s app_test.go:1207: 08:47:11.239 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.149 - 83: DEBUG: new connection from 127.0.0.1:42654 412s app_test.go:1207: 08:47:11.151 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.194 - 83: DEBUG: new connection from 127.0.0.1:42668 412s app_test.go:1207: 08:47:11.197 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.291 - 83: DEBUG: new connection from 127.0.0.1:42670 412s app_test.go:1207: 08:47:11.293 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.345 - 83: DEBUG: new connection from 127.0.0.1:42682 412s app_test.go:1207: 08:47:11.346 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.223 - 83: DEBUG: new connection from 127.0.0.1:42684 412s app_test.go:1207: 08:47:11.225 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.230 - 84: DEBUG: new connection from 127.0.0.1:34154 412s app_test.go:1207: 08:47:11.230 - 83: DEBUG: new connection from 127.0.0.1:42700 412s app_test.go:1207: 08:47:11.232 - 85: DEBUG: new connection from 127.0.0.1:48586 412s app_test.go:1207: 08:47:11.232 - 86: DEBUG: new connection from 127.0.0.1:59638 412s app_test.go:1207: 08:47:11.236 - 88: DEBUG: new connection from 127.0.0.1:42430 412s app_test.go:1207: 08:47:11.237 - 89: DEBUG: new connection from 127.0.0.1:38872 412s app_test.go:1207: 08:47:11.245 - 83: DEBUG: new connection from 127.0.0.1:42706 412s app_test.go:1207: 08:47:11.246 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.155 - 83: DEBUG: new connection from 127.0.0.1:42718 412s app_test.go:1207: 08:47:11.159 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.204 - 83: DEBUG: new connection from 127.0.0.1:42730 412s app_test.go:1207: 08:47:11.208 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.300 - 83: DEBUG: new connection from 127.0.0.1:42736 412s app_test.go:1207: 08:47:11.301 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.350 - 83: DEBUG: new connection from 127.0.0.1:42752 412s app_test.go:1207: 08:47:11.352 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.239 - 83: DEBUG: new connection from 127.0.0.1:42762 412s app_test.go:1207: 08:47:11.242 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.247 - 83: DEBUG: new connection from 127.0.0.1:42768 412s app_test.go:1207: 08:47:11.247 - 84: DEBUG: new connection from 127.0.0.1:34162 412s app_test.go:1207: 08:47:11.250 - 85: DEBUG: new connection from 127.0.0.1:48596 412s app_test.go:1207: 08:47:11.251 - 83: DEBUG: new connection from 127.0.0.1:42780 412s app_test.go:1207: 08:47:11.252 - 86: DEBUG: new connection from 127.0.0.1:59640 412s app_test.go:1207: 08:47:11.253 - 88: DEBUG: new connection from 127.0.0.1:42434 412s app_test.go:1207: 08:47:11.254 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.254 - 89: DEBUG: new connection from 127.0.0.1:38878 412s app_test.go:1207: 08:47:11.163 - 83: DEBUG: new connection from 127.0.0.1:34810 412s app_test.go:1207: 08:47:11.165 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.213 - 83: DEBUG: new connection from 127.0.0.1:34822 412s app_test.go:1207: 08:47:11.215 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.305 - 83: DEBUG: new connection from 127.0.0.1:34838 412s app_test.go:1207: 08:47:11.308 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.360 - 83: DEBUG: new connection from 127.0.0.1:34842 412s app_test.go:1207: 08:47:11.361 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.258 - 83: DEBUG: new connection from 127.0.0.1:34854 412s app_test.go:1207: 08:47:11.259 - 83: DEBUG: new connection from 127.0.0.1:34858 412s app_test.go:1207: 08:47:11.262 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.262 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.267 - 83: DEBUG: new connection from 127.0.0.1:34866 412s app_test.go:1207: 08:47:11.267 - 84: DEBUG: new connection from 127.0.0.1:60460 412s app_test.go:1207: 08:47:11.268 - 85: DEBUG: new connection from 127.0.0.1:55960 412s app_test.go:1207: 08:47:11.270 - 86: DEBUG: new connection from 127.0.0.1:60336 412s app_test.go:1207: 08:47:11.272 - 88: DEBUG: new connection from 127.0.0.1:33410 412s app_test.go:1207: 08:47:11.272 - 89: DEBUG: new connection from 127.0.0.1:39902 412s app_test.go:1207: 08:47:11.169 - 83: DEBUG: new connection from 127.0.0.1:34882 412s app_test.go:1207: 08:47:11.171 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.218 - 83: DEBUG: new connection from 127.0.0.1:34886 412s app_test.go:1207: 08:47:11.220 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.313 - 83: DEBUG: new connection from 127.0.0.1:34888 412s app_test.go:1207: 08:47:11.316 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.346 - 89: DEBUG: new connection from 127.0.0.1:39904 412s app_test.go:1207: 08:47:11.346 - 84: DEBUG: new connection from 127.0.0.1:60470 412s app_test.go:1207: 08:47:11.346 - 88: DEBUG: new connection from 127.0.0.1:33416 412s app_test.go:1207: 08:47:11.347 - 83: DEBUG: new connection from 127.0.0.1:34896 412s app_test.go:1207: 08:47:11.347 - 86: DEBUG: new connection from 127.0.0.1:60344 412s app_test.go:1207: 08:47:11.347 - 85: DEBUG: new connection from 127.0.0.1:55968 412s app_test.go:1207: 08:47:11.347 - 83: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 412s app_test.go:1207: 08:47:11.349 - 83: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:47:11.350 - 83: DEBUG: new connection from 127.0.0.1:34910 412s app_test.go:1207: 08:47:11.351 - 83: WARN: attempt 1: server 127.0.0.1:9007: no known leader 412s app_test.go:1207: 08:47:11.352 - 83: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:47:11.352 - 83: WARN: attempt 1: server 127.0.0.1:9006: no known leader 412s app_test.go:1207: 08:47:11.352 - 83: DEBUG: new connection from 127.0.0.1:34916 412s app_test.go:1207: 08:47:11.352 - 83: WARN: attempt 1: server 127.0.0.1:9004: no known leader 412s app_test.go:1207: 08:47:11.353 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s --- PASS: TestRolesAdjustment_ReplaceStandBy (22.32s) 412s === RUN TestRolesAdjustment_ReplaceStandByHonorFailureDomains 412s app_test.go:1207: 08:47:11.487 - 90: DEBUG: new connection from 127.0.0.1:34926 412s app_test.go:1207: 08:47:11.489 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.493 - 90: DEBUG: new connection from 127.0.0.1:34930 412s app_test.go:1207: 08:47:11.538 - 90: DEBUG: new connection from 127.0.0.1:34944 412s app_test.go:1207: 08:47:11.540 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.549 - 90: DEBUG: new connection from 127.0.0.1:34948 412s app_test.go:1207: 08:47:11.551 - 91: DEBUG: new connection from 127.0.0.1:60472 412s app_test.go:1207: 08:47:11.583 - 90: DEBUG: new connection from 127.0.0.1:34964 412s app_test.go:1207: 08:47:11.586 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.590 - 90: DEBUG: new connection from 127.0.0.1:34966 412s app_test.go:1207: 08:47:11.591 - 91: DEBUG: new connection from 127.0.0.1:60476 412s app_test.go:1207: 08:47:11.592 - 92: DEBUG: new connection from 127.0.0.1:55980 412s app_test.go:1207: 08:47:11.595 - 92: DEBUG: new connection from 127.0.0.1:55994 412s app_test.go:1207: 08:47:11.599 - 90: DEBUG: new connection from 127.0.0.1:34974 412s app_test.go:1207: 08:47:11.467 - 91: DEBUG: new connection from 127.0.0.1:60480 412s app_test.go:1207: 08:47:11.472 - 90: DEBUG: new connection from 127.0.0.1:34982 412s app_test.go:1207: 08:47:11.490 - 90: DEBUG: new connection from 127.0.0.1:34996 412s app_test.go:1207: 08:47:11.493 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.502 - 91: DEBUG: new connection from 127.0.0.1:60496 412s app_test.go:1207: 08:47:11.502 - 90: DEBUG: new connection from 127.0.0.1:35004 412s app_test.go:1207: 08:47:11.504 - 92: DEBUG: new connection from 127.0.0.1:56010 412s app_test.go:1207: 08:47:11.505 - 93: DEBUG: new connection from 127.0.0.1:60352 412s app_test.go:1207: 08:47:11.509 - 93: DEBUG: new connection from 127.0.0.1:60354 412s app_test.go:1207: 08:47:11.517 - 90: DEBUG: new connection from 127.0.0.1:35010 412s app_test.go:1207: 08:47:11.537 - 90: DEBUG: new connection from 127.0.0.1:35022 412s app_test.go:1207: 08:47:11.539 - 94: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.544 - 91: DEBUG: new connection from 127.0.0.1:60506 412s app_test.go:1207: 08:47:11.544 - 90: DEBUG: new connection from 127.0.0.1:35028 412s app_test.go:1207: 08:47:11.546 - 92: DEBUG: new connection from 127.0.0.1:56022 412s app_test.go:1207: 08:47:11.547 - 93: DEBUG: new connection from 127.0.0.1:60368 412s app_test.go:1207: 08:47:11.548 - 94: DEBUG: new connection from 127.0.0.1:43730 412s app_test.go:1207: 08:47:11.551 - 94: DEBUG: new connection from 127.0.0.1:43746 412s app_test.go:1207: 08:47:11.561 - 90: DEBUG: new connection from 127.0.0.1:35032 412s app_test.go:1207: 08:47:11.588 - 90: DEBUG: new connection from 127.0.0.1:35048 412s app_test.go:1207: 08:47:11.590 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.596 - 90: DEBUG: new connection from 127.0.0.1:35062 412s app_test.go:1207: 08:47:11.596 - 91: DEBUG: new connection from 127.0.0.1:60508 412s app_test.go:1207: 08:47:11.597 - 92: DEBUG: new connection from 127.0.0.1:56026 412s app_test.go:1207: 08:47:11.598 - 93: DEBUG: new connection from 127.0.0.1:60380 412s app_test.go:1207: 08:47:11.599 - 94: DEBUG: new connection from 127.0.0.1:43756 412s app_test.go:1207: 08:47:11.600 - 95: DEBUG: new connection from 127.0.0.1:33424 412s app_test.go:1207: 08:47:11.603 - 95: DEBUG: new connection from 127.0.0.1:33426 412s app_test.go:1207: 08:47:11.616 - 90: DEBUG: new connection from 127.0.0.1:35076 412s app_test.go:1207: 08:47:11.645 - 90: DEBUG: new connection from 127.0.0.1:35078 412s app_test.go:1207: 08:47:11.646 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.653 - 91: DEBUG: new connection from 127.0.0.1:60510 412s app_test.go:1207: 08:47:11.653 - 90: DEBUG: new connection from 127.0.0.1:35086 412s app_test.go:1207: 08:47:11.655 - 92: DEBUG: new connection from 127.0.0.1:56040 412s app_test.go:1207: 08:47:11.655 - 93: DEBUG: new connection from 127.0.0.1:60388 412s app_test.go:1207: 08:47:11.657 - 94: DEBUG: new connection from 127.0.0.1:43764 412s app_test.go:1207: 08:47:11.660 - 95: DEBUG: new connection from 127.0.0.1:33428 412s app_test.go:1207: 08:47:11.660 - 96: DEBUG: new connection from 127.0.0.1:39912 412s app_test.go:1207: 08:47:11.688 - 90: DEBUG: new connection from 127.0.0.1:35102 412s app_test.go:1207: 08:47:11.691 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.696 - 91: DEBUG: new connection from 127.0.0.1:60512 412s app_test.go:1207: 08:47:11.696 - 90: DEBUG: new connection from 127.0.0.1:35116 412s app_test.go:1207: 08:47:11.719 - 93: DEBUG: new connection from 127.0.0.1:60392 412s app_test.go:1207: 08:47:11.719 - 92: DEBUG: new connection from 127.0.0.1:56044 412s app_test.go:1207: 08:47:11.721 - 94: DEBUG: new connection from 127.0.0.1:43768 412s app_test.go:1207: 08:47:11.721 - 95: DEBUG: new connection from 127.0.0.1:33438 412s app_test.go:1207: 08:47:11.723 - 96: DEBUG: new connection from 127.0.0.1:39924 412s app_test.go:1207: 08:47:11.724 - 97: DEBUG: new connection from 127.0.0.1:55366 412s app_test.go:1207: 08:47:11.765 - 90: DEBUG: new connection from 127.0.0.1:35132 412s app_test.go:1207: 08:47:11.770 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:47:11.775 - 91: DEBUG: new connection from 127.0.0.1:60526 412s app_test.go:1207: 08:47:11.775 - 90: DEBUG: new connection from 127.0.0.1:35140 412s app_test.go:1207: 08:47:11.777 - 92: DEBUG: new connection from 127.0.0.1:56056 412s app_test.go:1207: 08:47:11.777 - 93: DEBUG: new connection from 127.0.0.1:60396 412s app_test.go:1207: 08:47:11.779 - 94: DEBUG: new connection from 127.0.0.1:43780 412s app_test.go:1207: 08:47:11.779 - 95: DEBUG: new connection from 127.0.0.1:33440 412s app_test.go:1207: 08:47:11.781 - 96: DEBUG: new connection from 127.0.0.1:39936 412s app_test.go:1207: 08:47:11.781 - 97: DEBUG: new connection from 127.0.0.1:55374 412s app_test.go:1207: 08:47:11.782 - 98: DEBUG: new connection from 127.0.0.1:54672 412s app_test.go:1207: 08:47:11.496 - 90: DEBUG: new connection from 127.0.0.1:37922 412s app_test.go:1207: 08:47:11.502 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:47:11.506 - 91: DEBUG: new connection from 127.0.0.1:45378 412s app_test.go:1207: 08:47:11.506 - 90: DEBUG: new connection from 127.0.0.1:37938 412s app_test.go:1207: 08:47:11.508 - 92: DEBUG: new connection from 127.0.0.1:57168 412s app_test.go:1207: 08:47:11.508 - 93: DEBUG: new connection from 127.0.0.1:55522 412s app_test.go:1207: 08:47:11.510 - 95: DEBUG: new connection from 127.0.0.1:37446 412s app_test.go:1207: 08:47:11.511 - 96: DEBUG: new connection from 127.0.0.1:37570 412s app_test.go:1207: 08:47:11.512 - 97: DEBUG: new connection from 127.0.0.1:54382 412s app_test.go:1207: 08:47:11.513 - 98: DEBUG: new connection from 127.0.0.1:50332 412s app_test.go:1207: 08:47:11.518 - 97: DEBUG: new connection from 127.0.0.1:54392 412s app_test.go:1207: 08:47:11.521 - 90: DEBUG: new connection from 127.0.0.1:37940 412s app_test.go:1207: 08:47:11.522 - 91: DEBUG: new connection from 127.0.0.1:45392 412s app_test.go:1207: 08:47:11.525 - 92: DEBUG: new connection from 127.0.0.1:57180 412s app_test.go:1207: 08:47:11.527 - 93: DEBUG: new connection from 127.0.0.1:55536 412s app_test.go:1207: 08:47:11.529 - 95: DEBUG: new connection from 127.0.0.1:37448 412s app_test.go:1207: 08:47:11.531 - 96: DEBUG: new connection from 127.0.0.1:37578 412s app_test.go:1207: 08:47:11.532 - 97: DEBUG: new connection from 127.0.0.1:54400 412s app_test.go:1207: 08:47:11.534 - 98: DEBUG: new connection from 127.0.0.1:50342 412s app_test.go:1207: 08:47:11.537 - 90: DEBUG: new connection from 127.0.0.1:37944 412s app_test.go:1207: 08:47:11.542 - 91: DEBUG: new connection from 127.0.0.1:45408 412s app_test.go:1207: 08:47:11.542 - 90: DEBUG: new connection from 127.0.0.1:37954 412s app_test.go:1207: 08:47:11.544 - 92: DEBUG: new connection from 127.0.0.1:57190 412s app_test.go:1207: 08:47:11.544 - 93: DEBUG: new connection from 127.0.0.1:55540 412s app_test.go:1207: 08:47:11.546 - 95: DEBUG: new connection from 127.0.0.1:37462 412s app_test.go:1207: 08:47:11.546 - 96: DEBUG: new connection from 127.0.0.1:37582 412s app_test.go:1207: 08:47:11.547 - 97: DEBUG: new connection from 127.0.0.1:54414 412s app_test.go:1207: 08:47:11.548 - 98: DEBUG: new connection from 127.0.0.1:50344 412s app_test.go:1207: 08:47:11.554 - 90: DEBUG: new connection from 127.0.0.1:37970 412s app_test.go:1207: 08:47:11.556 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.465 - 90: DEBUG: new connection from 127.0.0.1:37984 412s app_test.go:1207: 08:48:11.467 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.510 - 90: DEBUG: new connection from 127.0.0.1:37998 412s app_test.go:1207: 08:48:11.512 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.606 - 90: DEBUG: new connection from 127.0.0.1:38002 412s app_test.go:1207: 08:48:11.607 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.663 - 90: DEBUG: new connection from 127.0.0.1:38012 412s app_test.go:1207: 08:48:11.666 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.727 - 90: DEBUG: new connection from 127.0.0.1:38020 412s app_test.go:1207: 08:48:11.728 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.785 - 90: DEBUG: new connection from 127.0.0.1:38036 412s app_test.go:1207: 08:48:11.788 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.551 - 90: DEBUG: new connection from 127.0.0.1:38052 412s app_test.go:1207: 08:48:11.553 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.557 - 90: DEBUG: new connection from 127.0.0.1:38068 412s app_test.go:1207: 08:48:11.557 - 91: DEBUG: new connection from 127.0.0.1:45422 412s app_test.go:1207: 08:48:11.559 - 92: DEBUG: new connection from 127.0.0.1:57202 412s app_test.go:1207: 08:48:11.560 - 90: DEBUG: new connection from 127.0.0.1:38084 412s app_test.go:1207: 08:48:11.561 - 93: DEBUG: new connection from 127.0.0.1:55546 412s app_test.go:1207: 08:48:11.562 - 95: DEBUG: new connection from 127.0.0.1:37474 412s app_test.go:1207: 08:48:11.564 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.566 - 96: DEBUG: new connection from 127.0.0.1:37588 412s app_test.go:1207: 08:48:11.566 - 97: DEBUG: new connection from 127.0.0.1:54430 412s app_test.go:1207: 08:48:11.568 - 98: DEBUG: new connection from 127.0.0.1:50346 412s app_test.go:1207: 08:48:11.473 - 90: DEBUG: new connection from 127.0.0.1:38086 412s app_test.go:1207: 08:48:11.474 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.518 - 90: DEBUG: new connection from 127.0.0.1:38102 412s app_test.go:1207: 08:48:11.520 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.612 - 90: DEBUG: new connection from 127.0.0.1:38104 412s app_test.go:1207: 08:48:11.614 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.672 - 90: DEBUG: new connection from 127.0.0.1:38108 412s app_test.go:1207: 08:48:11.673 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.734 - 90: DEBUG: new connection from 127.0.0.1:38124 412s app_test.go:1207: 08:48:11.736 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.793 - 90: DEBUG: new connection from 127.0.0.1:38130 412s app_test.go:1207: 08:48:11.794 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.569 - 90: DEBUG: new connection from 127.0.0.1:59846 412s app_test.go:1207: 08:48:11.570 - 90: DEBUG: new connection from 127.0.0.1:59856 412s app_test.go:1207: 08:48:11.571 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.572 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.577 - 91: DEBUG: new connection from 127.0.0.1:40676 412s app_test.go:1207: 08:48:11.577 - 90: DEBUG: new connection from 127.0.0.1:59858 412s app_test.go:1207: 08:48:11.578 - 92: DEBUG: new connection from 127.0.0.1:43664 412s app_test.go:1207: 08:48:11.579 - 93: DEBUG: new connection from 127.0.0.1:44272 412s app_test.go:1207: 08:48:11.581 - 95: DEBUG: new connection from 127.0.0.1:45250 412s app_test.go:1207: 08:48:11.581 - 96: DEBUG: new connection from 127.0.0.1:34590 412s app_test.go:1207: 08:48:11.582 - 97: DEBUG: new connection from 127.0.0.1:52824 412s app_test.go:1207: 08:48:11.583 - 98: DEBUG: new connection from 127.0.0.1:47102 412s app_test.go:1207: 08:48:11.479 - 90: DEBUG: new connection from 127.0.0.1:59868 412s app_test.go:1207: 08:48:11.481 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.526 - 90: DEBUG: new connection from 127.0.0.1:59872 412s app_test.go:1207: 08:48:11.528 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.618 - 90: DEBUG: new connection from 127.0.0.1:59888 412s app_test.go:1207: 08:48:11.619 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.678 - 90: DEBUG: new connection from 127.0.0.1:59896 412s app_test.go:1207: 08:48:11.679 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.739 - 90: DEBUG: new connection from 127.0.0.1:59898 412s app_test.go:1207: 08:48:11.741 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.797 - 90: DEBUG: new connection from 127.0.0.1:59910 412s app_test.go:1207: 08:48:11.799 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.577 - 90: DEBUG: new connection from 127.0.0.1:59914 412s app_test.go:1207: 08:48:11.578 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.588 - 90: DEBUG: new connection from 127.0.0.1:59930 412s app_test.go:1207: 08:48:11.590 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.595 - 91: DEBUG: new connection from 127.0.0.1:40688 412s app_test.go:1207: 08:48:11.595 - 90: DEBUG: new connection from 127.0.0.1:59942 412s app_test.go:1207: 08:48:11.596 - 92: DEBUG: new connection from 127.0.0.1:43668 412s app_test.go:1207: 08:48:11.597 - 93: DEBUG: new connection from 127.0.0.1:44288 412s app_test.go:1207: 08:48:11.598 - 95: DEBUG: new connection from 127.0.0.1:45254 412s app_test.go:1207: 08:48:11.599 - 96: DEBUG: new connection from 127.0.0.1:34602 412s app_test.go:1207: 08:48:11.601 - 97: DEBUG: new connection from 127.0.0.1:52826 412s app_test.go:1207: 08:48:11.602 - 98: DEBUG: new connection from 127.0.0.1:47114 412s app_test.go:1207: 08:48:11.485 - 90: DEBUG: new connection from 127.0.0.1:59954 412s app_test.go:1207: 08:48:11.487 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.532 - 90: DEBUG: new connection from 127.0.0.1:59962 412s app_test.go:1207: 08:48:11.534 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.624 - 90: DEBUG: new connection from 127.0.0.1:59974 412s app_test.go:1207: 08:48:11.626 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.684 - 90: DEBUG: new connection from 127.0.0.1:59980 412s app_test.go:1207: 08:48:11.686 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.747 - 90: DEBUG: new connection from 127.0.0.1:59984 412s app_test.go:1207: 08:48:11.749 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 412s app_test.go:1207: 08:48:11.786 - 98: DEBUG: new connection from 127.0.0.1:47122 412s app_test.go:1207: 08:48:11.786 - 96: DEBUG: new connection from 127.0.0.1:34616 412s app_test.go:1207: 08:48:11.787 - 92: DEBUG: new connection from 127.0.0.1:43672 412s app_test.go:1207: 08:48:11.787 - 90: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 412s app_test.go:1207: 08:48:11.787 - 91: DEBUG: new connection from 127.0.0.1:40698 412s app_test.go:1207: 08:48:11.787 - 90: DEBUG: new connection from 127.0.0.1:59998 412s app_test.go:1207: 08:48:11.788 - 97: DEBUG: new connection from 127.0.0.1:52830 412s app_test.go:1207: 08:48:11.790 - 90: WARN: attempt 1: server 127.0.0.1:9007: no known leader 412s app_test.go:1207: 08:48:11.790 - 93: DEBUG: new connection from 127.0.0.1:44304 412s app_test.go:1207: 08:48:11.792 - 95: DEBUG: new connection from 127.0.0.1:45266 412s app_test.go:1207: 08:48:11.794 - 90: WARN: attempt 1: server 127.0.0.1:9009: no known leader 412s app_test.go:1207: 08:48:11.794 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1207: 08:48:11.794 - 90: WARN: attempt 1: server 127.0.0.1:9006: no known leader 412s app_test.go:1207: 08:48:11.794 - 90: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:48:11.794 - 90: WARN: attempt 1: server 127.0.0.1:9001: context canceled 412s app_test.go:1207: 08:48:11.795 - 90: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 412s app_test.go:1207: 08:48:11.795 - 90: WARN: attempt 1: server 127.0.0.1:9001: context canceled 412s app_test.go:1207: 08:48:11.796 - 90: WARN: attempt 1: server 127.0.0.1:9004: no known leader 412s app_test.go:1207: 08:48:11.796 - 90: WARN: attempt 1: server 127.0.0.1:9008: no known leader 412s --- PASS: TestRolesAdjustment_ReplaceStandByHonorFailureDomains (22.41s) 412s === RUN TestOpen 412s app_test.go:1207: 08:48:11.893 - 99: DEBUG: new connection from 127.0.0.1:33422 412s app_test.go:1207: 08:48:11.894 - 99: DEBUG: new connection from 127.0.0.1:33428 412s app_test.go:1207: 08:48:11.895 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 412s app_test.go:1207: 08:48:11.897 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 412s app_test.go:1207: 08:48:11.900 - 99: DEBUG: new connection from 127.0.0.1:33434 412s --- PASS: TestOpen (0.05s) 412s === RUN TestOpenDisk 412s app_test.go:1207: 08:48:11.944 - 100: DEBUG: new connection from 127.0.0.1:33436 412s app_test.go:1207: 08:48:11.944 - 100: DEBUG: new connection from 127.0.0.1:33450 412s app_test.go:1207: 08:48:11.946 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 412s app_test.go:1207: 08:48:11.946 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 412s app_test.go:1207: 08:48:11.950 - 100: DEBUG: new connection from 127.0.0.1:33454 412s --- PASS: TestOpenDisk (0.06s) 412s === RUN TestOptions 412s --- PASS: TestOptions (0.04s) 412s === RUN TestProxy_Error 412s app_test.go:1207: 08:48:11.047 - 102: DEBUG: new connection from 127.0.0.1:33458 412s app_test.go:1207: 08:48:11.048 - 102: DEBUG: new connection from 127.0.0.1:33462 412s app_test.go:1207: 08:48:11.050 - 102: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 412s app_test.go:1207: 08:48:11.053 - 102: DEBUG: new connection from 127.0.0.1:33472 412s --- PASS: TestProxy_Error (0.38s) 412s === RUN TestReady_Cancel 412s app_test.go:1207: 08:48:11.430 - 103: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 412s --- PASS: TestReady_Cancel (0.23s) 412s === RUN TestExternalConnWithTCP 412s app_test.go:1232: 08:48:11.667 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 412s app_test.go:1232: 08:48:11.689 - 105: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 412s app_test.go:1232: 08:48:11.722 - 106: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 412s app_test.go:1232: 08:48:11.651 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 412s --- PASS: TestExternalConnWithTCP (2.07s) 412s === RUN TestExternalConnWithPipe 412s app_test.go:1232: 08:48:11.732 - 107: DEBUG: attempt 1: server first: connected on fallback path 412s app_test.go:1232: 08:48:11.761 - 108: DEBUG: attempt 1: server first: connected on fallback path 412s app_test.go:1232: 08:48:11.809 - 109: DEBUG: attempt 1: server first: connected on fallback path 412s app_test.go:1232: 08:48:11.717 - 107: DEBUG: attempt 1: server first: connected on fallback path 412s --- PASS: TestExternalConnWithPipe (2.07s) 412s === RUN TestParallelNewApp 412s === PAUSE TestParallelNewApp 412s === RUN Test_TxRowsAffected 412s app_test.go:1232: 08:48:11.797 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s app_test.go:1232: 08:48:11.802 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 412s --- PASS: Test_TxRowsAffected (0.06s) 412s === CONT TestParallelNewApp 412s === RUN TestParallelNewApp/run-0 412s === PAUSE TestParallelNewApp/run-0 412s === RUN TestParallelNewApp/run-1 412s === PAUSE TestParallelNewApp/run-1 412s === RUN TestParallelNewApp/run-2 412s === PAUSE TestParallelNewApp/run-2 412s === RUN TestParallelNewApp/run-3 412s === PAUSE TestParallelNewApp/run-3 412s === RUN TestParallelNewApp/run-4 412s === PAUSE TestParallelNewApp/run-4 412s === RUN TestParallelNewApp/run-5 412s === PAUSE TestParallelNewApp/run-5 412s === RUN TestParallelNewApp/run-6 412s === PAUSE TestParallelNewApp/run-6 412s === RUN TestParallelNewApp/run-7 412s === PAUSE TestParallelNewApp/run-7 412s === RUN TestParallelNewApp/run-8 412s === PAUSE TestParallelNewApp/run-8 412s === RUN TestParallelNewApp/run-9 412s === PAUSE TestParallelNewApp/run-9 412s === RUN TestParallelNewApp/run-10 412s === PAUSE TestParallelNewApp/run-10 412s === RUN TestParallelNewApp/run-11 412s === PAUSE TestParallelNewApp/run-11 412s === RUN TestParallelNewApp/run-12 412s === PAUSE TestParallelNewApp/run-12 412s === RUN TestParallelNewApp/run-13 412s === PAUSE TestParallelNewApp/run-13 412s === RUN TestParallelNewApp/run-14 412s === PAUSE TestParallelNewApp/run-14 412s === RUN TestParallelNewApp/run-15 412s === PAUSE TestParallelNewApp/run-15 412s === RUN TestParallelNewApp/run-16 412s === PAUSE TestParallelNewApp/run-16 412s === RUN TestParallelNewApp/run-17 412s === PAUSE TestParallelNewApp/run-17 412s === RUN TestParallelNewApp/run-18 412s === PAUSE TestParallelNewApp/run-18 412s === RUN TestParallelNewApp/run-19 412s === PAUSE TestParallelNewApp/run-19 412s === RUN TestParallelNewApp/run-20 412s === PAUSE TestParallelNewApp/run-20 412s === RUN TestParallelNewApp/run-21 412s === PAUSE TestParallelNewApp/run-21 412s === RUN TestParallelNewApp/run-22 412s === PAUSE TestParallelNewApp/run-22 412s === RUN TestParallelNewApp/run-23 412s === PAUSE TestParallelNewApp/run-23 412s === RUN TestParallelNewApp/run-24 412s === PAUSE TestParallelNewApp/run-24 412s === RUN TestParallelNewApp/run-25 412s === PAUSE TestParallelNewApp/run-25 412s === RUN TestParallelNewApp/run-26 412s === PAUSE TestParallelNewApp/run-26 412s === RUN TestParallelNewApp/run-27 412s === PAUSE TestParallelNewApp/run-27 412s === RUN TestParallelNewApp/run-28 412s === PAUSE TestParallelNewApp/run-28 412s === RUN TestParallelNewApp/run-29 412s === PAUSE TestParallelNewApp/run-29 412s === RUN TestParallelNewApp/run-30 412s === PAUSE TestParallelNewApp/run-30 412s === RUN TestParallelNewApp/run-31 412s === PAUSE TestParallelNewApp/run-31 412s === RUN TestParallelNewApp/run-32 412s === PAUSE TestParallelNewApp/run-32 412s === RUN TestParallelNewApp/run-33 412s === PAUSE TestParallelNewApp/run-33 412s === RUN TestParallelNewApp/run-34 412s === PAUSE TestParallelNewApp/run-34 412s === RUN TestParallelNewApp/run-35 412s === PAUSE TestParallelNewApp/run-35 412s === RUN TestParallelNewApp/run-36 412s === PAUSE TestParallelNewApp/run-36 412s === RUN TestParallelNewApp/run-37 412s === PAUSE TestParallelNewApp/run-37 412s === RUN TestParallelNewApp/run-38 412s === PAUSE TestParallelNewApp/run-38 412s === RUN TestParallelNewApp/run-39 412s === PAUSE TestParallelNewApp/run-39 412s === RUN TestParallelNewApp/run-40 412s === PAUSE TestParallelNewApp/run-40 412s === RUN TestParallelNewApp/run-41 412s === PAUSE TestParallelNewApp/run-41 412s === RUN TestParallelNewApp/run-42 412s === PAUSE TestParallelNewApp/run-42 412s === RUN TestParallelNewApp/run-43 412s === PAUSE TestParallelNewApp/run-43 412s === RUN TestParallelNewApp/run-44 412s === PAUSE TestParallelNewApp/run-44 412s === RUN TestParallelNewApp/run-45 412s === PAUSE TestParallelNewApp/run-45 412s === RUN TestParallelNewApp/run-46 412s === PAUSE TestParallelNewApp/run-46 412s === RUN TestParallelNewApp/run-47 412s === PAUSE TestParallelNewApp/run-47 412s === RUN TestParallelNewApp/run-48 412s === PAUSE TestParallelNewApp/run-48 412s === RUN TestParallelNewApp/run-49 412s === PAUSE TestParallelNewApp/run-49 412s === RUN TestParallelNewApp/run-50 412s === PAUSE TestParallelNewApp/run-50 412s === RUN TestParallelNewApp/run-51 412s === PAUSE TestParallelNewApp/run-51 412s === RUN TestParallelNewApp/run-52 412s === PAUSE TestParallelNewApp/run-52 412s === RUN TestParallelNewApp/run-53 412s === PAUSE TestParallelNewApp/run-53 412s === RUN TestParallelNewApp/run-54 412s === PAUSE TestParallelNewApp/run-54 412s === RUN TestParallelNewApp/run-55 412s === PAUSE TestParallelNewApp/run-55 412s === RUN TestParallelNewApp/run-56 412s === PAUSE TestParallelNewApp/run-56 412s === RUN TestParallelNewApp/run-57 412s === PAUSE TestParallelNewApp/run-57 412s === RUN TestParallelNewApp/run-58 412s === PAUSE TestParallelNewApp/run-58 412s === RUN TestParallelNewApp/run-59 412s === PAUSE TestParallelNewApp/run-59 412s === RUN TestParallelNewApp/run-60 412s === PAUSE TestParallelNewApp/run-60 412s === RUN TestParallelNewApp/run-61 412s === PAUSE TestParallelNewApp/run-61 412s === RUN TestParallelNewApp/run-62 412s === PAUSE TestParallelNewApp/run-62 412s === RUN TestParallelNewApp/run-63 412s === PAUSE TestParallelNewApp/run-63 412s === RUN TestParallelNewApp/run-64 412s === PAUSE TestParallelNewApp/run-64 412s === RUN TestParallelNewApp/run-65 412s === PAUSE TestParallelNewApp/run-65 412s === RUN TestParallelNewApp/run-66 412s === PAUSE TestParallelNewApp/run-66 412s === RUN TestParallelNewApp/run-67 412s === PAUSE TestParallelNewApp/run-67 412s === RUN TestParallelNewApp/run-68 412s === PAUSE TestParallelNewApp/run-68 412s === RUN TestParallelNewApp/run-69 412s === PAUSE TestParallelNewApp/run-69 412s === RUN TestParallelNewApp/run-70 412s === PAUSE TestParallelNewApp/run-70 412s === RUN TestParallelNewApp/run-71 412s === PAUSE TestParallelNewApp/run-71 412s === RUN TestParallelNewApp/run-72 412s === PAUSE TestParallelNewApp/run-72 412s === RUN TestParallelNewApp/run-73 412s === PAUSE TestParallelNewApp/run-73 412s === RUN TestParallelNewApp/run-74 412s === PAUSE TestParallelNewApp/run-74 412s === RUN TestParallelNewApp/run-75 412s === PAUSE TestParallelNewApp/run-75 412s === RUN TestParallelNewApp/run-76 412s === PAUSE TestParallelNewApp/run-76 412s === RUN TestParallelNewApp/run-77 412s === PAUSE TestParallelNewApp/run-77 412s === RUN TestParallelNewApp/run-78 412s === PAUSE TestParallelNewApp/run-78 412s === RUN TestParallelNewApp/run-79 412s === PAUSE TestParallelNewApp/run-79 412s === RUN TestParallelNewApp/run-80 412s === PAUSE TestParallelNewApp/run-80 412s === RUN TestParallelNewApp/run-81 412s === PAUSE TestParallelNewApp/run-81 412s === RUN TestParallelNewApp/run-82 412s === PAUSE TestParallelNewApp/run-82 412s === RUN TestParallelNewApp/run-83 412s === PAUSE TestParallelNewApp/run-83 412s === RUN TestParallelNewApp/run-84 412s === PAUSE TestParallelNewApp/run-84 412s === RUN TestParallelNewApp/run-85 412s === PAUSE TestParallelNewApp/run-85 412s === RUN TestParallelNewApp/run-86 412s === PAUSE TestParallelNewApp/run-86 412s === RUN TestParallelNewApp/run-87 412s === PAUSE TestParallelNewApp/run-87 412s === RUN TestParallelNewApp/run-88 412s === PAUSE TestParallelNewApp/run-88 412s === RUN TestParallelNewApp/run-89 412s === PAUSE TestParallelNewApp/run-89 412s === RUN TestParallelNewApp/run-90 412s === PAUSE TestParallelNewApp/run-90 412s === RUN TestParallelNewApp/run-91 412s === PAUSE TestParallelNewApp/run-91 412s === RUN TestParallelNewApp/run-92 412s === PAUSE TestParallelNewApp/run-92 412s === RUN TestParallelNewApp/run-93 412s === PAUSE TestParallelNewApp/run-93 412s === RUN TestParallelNewApp/run-94 412s === PAUSE TestParallelNewApp/run-94 412s === RUN TestParallelNewApp/run-95 412s === PAUSE TestParallelNewApp/run-95 412s === RUN TestParallelNewApp/run-96 412s === PAUSE TestParallelNewApp/run-96 412s === RUN TestParallelNewApp/run-97 412s === PAUSE TestParallelNewApp/run-97 412s === RUN TestParallelNewApp/run-98 412s === PAUSE TestParallelNewApp/run-98 412s === RUN TestParallelNewApp/run-99 412s === PAUSE TestParallelNewApp/run-99 412s === CONT TestParallelNewApp/run-0 412s === CONT TestParallelNewApp/run-50 412s === CONT TestParallelNewApp/run-49 412s === CONT TestParallelNewApp/run-48 412s === CONT TestParallelNewApp/run-47 412s === CONT TestParallelNewApp/run-46 412s === CONT TestParallelNewApp/run-45 412s === CONT TestParallelNewApp/run-44 412s === CONT TestParallelNewApp/run-43 412s === CONT TestParallelNewApp/run-42 412s === CONT TestParallelNewApp/run-41 412s === CONT TestParallelNewApp/run-40 412s === CONT TestParallelNewApp/run-39 412s === CONT TestParallelNewApp/run-38 412s === CONT TestParallelNewApp/run-37 412s === CONT TestParallelNewApp/run-36 412s === CONT TestParallelNewApp/run-35 412s === CONT TestParallelNewApp/run-34 412s === CONT TestParallelNewApp/run-33 412s === CONT TestParallelNewApp/run-32 412s === CONT TestParallelNewApp/run-31 412s === CONT TestParallelNewApp/run-30 412s === CONT TestParallelNewApp/run-29 412s === CONT TestParallelNewApp/run-28 412s === CONT TestParallelNewApp/run-27 412s === CONT TestParallelNewApp/run-26 412s === CONT TestParallelNewApp/run-25 412s === CONT TestParallelNewApp/run-24 412s === CONT TestParallelNewApp/run-23 412s === CONT TestParallelNewApp/run-22 412s === CONT TestParallelNewApp/run-21 412s === CONT TestParallelNewApp/run-20 412s === CONT TestParallelNewApp/run-19 412s === CONT TestParallelNewApp/run-18 412s === CONT TestParallelNewApp/run-17 412s === CONT TestParallelNewApp/run-16 412s === CONT TestParallelNewApp/run-15 412s === CONT TestParallelNewApp/run-14 412s === CONT TestParallelNewApp/run-13 412s === CONT TestParallelNewApp/run-12 412s === CONT TestParallelNewApp/run-11 412s === CONT TestParallelNewApp/run-10 412s === CONT TestParallelNewApp/run-9 412s === CONT TestParallelNewApp/run-8 412s === CONT TestParallelNewApp/run-7 412s === CONT TestParallelNewApp/run-6 412s === CONT TestParallelNewApp/run-5 412s === CONT TestParallelNewApp/run-4 412s === CONT TestParallelNewApp/run-3 412s === CONT TestParallelNewApp/run-2 412s === CONT TestParallelNewApp/run-1 412s === CONT TestParallelNewApp/run-75 412s === CONT TestParallelNewApp/run-99 412s === CONT TestParallelNewApp/run-98 412s === CONT TestParallelNewApp/run-97 412s === CONT TestParallelNewApp/run-96 412s === CONT TestParallelNewApp/run-95 412s === CONT TestParallelNewApp/run-94 412s === CONT TestParallelNewApp/run-93 412s === CONT TestParallelNewApp/run-92 412s === CONT TestParallelNewApp/run-91 412s === CONT TestParallelNewApp/run-90 412s === CONT TestParallelNewApp/run-89 412s === CONT TestParallelNewApp/run-88 412s === CONT TestParallelNewApp/run-87 412s === CONT TestParallelNewApp/run-86 412s === CONT TestParallelNewApp/run-85 412s === CONT TestParallelNewApp/run-84 412s === CONT TestParallelNewApp/run-83 412s === CONT TestParallelNewApp/run-82 412s === CONT TestParallelNewApp/run-81 412s === CONT TestParallelNewApp/run-80 412s === CONT TestParallelNewApp/run-79 412s === CONT TestParallelNewApp/run-78 412s === CONT TestParallelNewApp/run-77 412s === CONT TestParallelNewApp/run-76 412s === CONT TestParallelNewApp/run-63 412s === CONT TestParallelNewApp/run-74 412s === CONT TestParallelNewApp/run-73 412s === CONT TestParallelNewApp/run-72 412s === CONT TestParallelNewApp/run-71 412s === CONT TestParallelNewApp/run-70 412s === CONT TestParallelNewApp/run-69 412s === CONT TestParallelNewApp/run-68 412s === CONT TestParallelNewApp/run-67 412s === CONT TestParallelNewApp/run-66 412s === CONT TestParallelNewApp/run-65 412s === CONT TestParallelNewApp/run-64 412s === CONT TestParallelNewApp/run-57 412s === CONT TestParallelNewApp/run-62 412s === CONT TestParallelNewApp/run-61 412s === CONT TestParallelNewApp/run-60 412s === CONT TestParallelNewApp/run-59 412s === CONT TestParallelNewApp/run-58 412s === CONT TestParallelNewApp/run-54 412s === CONT TestParallelNewApp/run-56 412s === CONT TestParallelNewApp/run-55 412s === CONT TestParallelNewApp/run-52 412s === CONT TestParallelNewApp/run-53 412s === CONT TestParallelNewApp/run-51 412s --- PASS: TestParallelNewApp (0.00s) 412s --- PASS: TestParallelNewApp/run-50 (0.06s) 412s --- PASS: TestParallelNewApp/run-0 (0.06s) 412s --- PASS: TestParallelNewApp/run-49 (0.05s) 412s --- PASS: TestParallelNewApp/run-48 (0.05s) 412s --- PASS: TestParallelNewApp/run-46 (0.04s) 412s --- PASS: TestParallelNewApp/run-47 (0.05s) 412s --- PASS: TestParallelNewApp/run-44 (0.03s) 412s --- PASS: TestParallelNewApp/run-45 (0.04s) 412s --- PASS: TestParallelNewApp/run-42 (0.03s) 412s --- PASS: TestParallelNewApp/run-43 (0.24s) 412s --- PASS: TestParallelNewApp/run-40 (0.02s) 412s --- PASS: TestParallelNewApp/run-41 (0.23s) 412s --- PASS: TestParallelNewApp/run-39 (0.02s) 412s --- PASS: TestParallelNewApp/run-37 (0.03s) 412s --- PASS: TestParallelNewApp/run-36 (0.03s) 412s --- PASS: TestParallelNewApp/run-38 (0.23s) 412s --- PASS: TestParallelNewApp/run-35 (0.23s) 412s --- PASS: TestParallelNewApp/run-33 (0.03s) 412s --- PASS: TestParallelNewApp/run-32 (0.03s) 412s --- PASS: TestParallelNewApp/run-31 (0.05s) 412s --- PASS: TestParallelNewApp/run-30 (0.03s) 412s --- PASS: TestParallelNewApp/run-34 (0.25s) 412s --- PASS: TestParallelNewApp/run-29 (0.02s) 412s --- PASS: TestParallelNewApp/run-28 (0.03s) 412s --- PASS: TestParallelNewApp/run-27 (0.02s) 412s --- PASS: TestParallelNewApp/run-26 (0.03s) 412s --- PASS: TestParallelNewApp/run-25 (0.03s) 412s --- PASS: TestParallelNewApp/run-23 (0.03s) 412s --- PASS: TestParallelNewApp/run-24 (0.04s) 412s --- PASS: TestParallelNewApp/run-22 (0.03s) 412s --- PASS: TestParallelNewApp/run-21 (0.03s) 412s --- PASS: TestParallelNewApp/run-19 (0.03s) 412s --- PASS: TestParallelNewApp/run-20 (0.04s) 412s --- PASS: TestParallelNewApp/run-18 (0.02s) 412s --- PASS: TestParallelNewApp/run-17 (0.04s) 412s --- PASS: TestParallelNewApp/run-16 (0.04s) 412s --- PASS: TestParallelNewApp/run-15 (0.03s) 412s --- PASS: TestParallelNewApp/run-14 (0.02s) 412s --- PASS: TestParallelNewApp/run-13 (0.02s) 412s --- PASS: TestParallelNewApp/run-12 (0.03s) 412s --- PASS: TestParallelNewApp/run-11 (0.02s) 412s --- PASS: TestParallelNewApp/run-10 (0.03s) 412s --- PASS: TestParallelNewApp/run-9 (0.03s) 412s --- PASS: TestParallelNewApp/run-7 (0.04s) 412s --- PASS: TestParallelNewApp/run-8 (0.05s) 412s --- PASS: TestParallelNewApp/run-6 (0.03s) 412s --- PASS: TestParallelNewApp/run-5 (0.04s) 412s --- PASS: TestParallelNewApp/run-4 (0.04s) 412s --- PASS: TestParallelNewApp/run-3 (0.04s) 412s --- PASS: TestParallelNewApp/run-2 (0.04s) 412s --- PASS: TestParallelNewApp/run-1 (0.05s) 412s --- PASS: TestParallelNewApp/run-75 (0.03s) 412s --- PASS: TestParallelNewApp/run-99 (0.03s) 412s --- PASS: TestParallelNewApp/run-98 (0.03s) 412s --- PASS: TestParallelNewApp/run-97 (0.03s) 412s --- PASS: TestParallelNewApp/run-95 (0.03s) 412s --- PASS: TestParallelNewApp/run-96 (0.23s) 412s --- PASS: TestParallelNewApp/run-93 (0.03s) 412s --- PASS: TestParallelNewApp/run-94 (0.24s) 412s --- PASS: TestParallelNewApp/run-92 (0.04s) 412s --- PASS: TestParallelNewApp/run-91 (0.03s) 412s --- PASS: TestParallelNewApp/run-90 (0.03s) 412s --- PASS: TestParallelNewApp/run-88 (0.03s) 412s --- PASS: TestParallelNewApp/run-87 (0.23s) 412s --- PASS: TestParallelNewApp/run-86 (0.05s) 412s --- PASS: TestParallelNewApp/run-85 (0.04s) 412s --- PASS: TestParallelNewApp/run-84 (0.04s) 412s --- PASS: TestParallelNewApp/run-89 (0.43s) 412s --- PASS: TestParallelNewApp/run-83 (0.03s) 412s --- PASS: TestParallelNewApp/run-81 (0.04s) 412s --- PASS: TestParallelNewApp/run-82 (0.05s) 412s --- PASS: TestParallelNewApp/run-80 (0.03s) 412s --- PASS: TestParallelNewApp/run-79 (0.04s) 412s --- PASS: TestParallelNewApp/run-77 (0.03s) 412s --- PASS: TestParallelNewApp/run-78 (0.04s) 412s --- PASS: TestParallelNewApp/run-76 (0.04s) 412s --- PASS: TestParallelNewApp/run-63 (0.04s) 412s --- PASS: TestParallelNewApp/run-73 (0.03s) 412s --- PASS: TestParallelNewApp/run-74 (0.04s) 412s --- PASS: TestParallelNewApp/run-72 (0.03s) 412s --- PASS: TestParallelNewApp/run-70 (0.03s) 412s --- PASS: TestParallelNewApp/run-69 (0.23s) 412s --- PASS: TestParallelNewApp/run-68 (0.03s) 412s --- PASS: TestParallelNewApp/run-71 (0.43s) 412s --- PASS: TestParallelNewApp/run-67 (0.23s) 412s --- PASS: TestParallelNewApp/run-65 (0.03s) 412s --- PASS: TestParallelNewApp/run-66 (0.23s) 412s --- PASS: TestParallelNewApp/run-57 (0.03s) 412s --- PASS: TestParallelNewApp/run-64 (0.23s) 412s --- PASS: TestParallelNewApp/run-61 (0.04s) 412s --- PASS: TestParallelNewApp/run-60 (0.04s) 412s --- PASS: TestParallelNewApp/run-62 (0.24s) 412s --- PASS: TestParallelNewApp/run-59 (0.25s) 412s --- PASS: TestParallelNewApp/run-54 (0.03s) 412s --- PASS: TestParallelNewApp/run-58 (0.24s) 412s --- PASS: TestParallelNewApp/run-56 (0.04s) 412s --- PASS: TestParallelNewApp/run-55 (0.03s) 412s --- PASS: TestParallelNewApp/run-52 (0.03s) 412s --- PASS: TestParallelNewApp/run-53 (0.03s) 412s --- PASS: TestParallelNewApp/run-51 (0.03s) 412s === RUN Example 412s --- PASS: Example (0.45s) 412s PASS 412s ok github.com/canonical/go-dqlite/app 165.878s 421s === RUN TestNew_Default 421s Benchmark done. Results available here: 421s /tmp/dqlite-app-test-2370986006/results 421s --- PASS: TestNew_Default (1.09s) 421s === RUN TestNew_KvReadWrite 421s Benchmark done. Results available here: 421s /tmp/dqlite-app-test-3906423958/results 421s --- PASS: TestNew_KvReadWrite (1.06s) 421s === RUN TestNew_ClusteredKvReadWrite 421s Benchmark done. Results available here: 421s /tmp/dqlite-app-test-1575601802/results 421s --- PASS: TestNew_ClusteredKvReadWrite (4.05s) 421s === RUN TestNew_ClusteredTimeout 421s --- PASS: TestNew_ClusteredTimeout (2.08s) 421s PASS 421s ok github.com/canonical/go-dqlite/benchmark 8.292s 424s === RUN TestClient_Leader 424s --- PASS: TestClient_Leader (0.06s) 424s === RUN TestClient_Cluster 424s --- PASS: TestClient_Cluster (0.03s) 424s === RUN TestClient_Transfer 424s --- PASS: TestClient_Transfer (1.04s) 424s === RUN TestClient_Describe 424s --- PASS: TestClient_Describe (0.03s) 424s === RUN TestMembership 424s --- PASS: TestMembership (1.04s) 424s === RUN TestDefaultNodeStore 424s --- PASS: TestDefaultNodeStore (0.00s) 424s === RUN TestConfigMultiThread 424s --- PASS: TestConfigMultiThread (0.00s) 424s PASS 424s ok github.com/canonical/go-dqlite/client 2.200s 424s ? github.com/canonical/go-dqlite/cmd/dqlite [no test files] 424s ? github.com/canonical/go-dqlite/cmd/dqlite-benchmark [no test files] 424s ? github.com/canonical/go-dqlite/cmd/dqlite-demo [no test files] 450s === RUN TestDriver_Open 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestDriver_Open (0.03s) 450s === RUN TestDriver_Prepare 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestDriver_Prepare (0.03s) 450s === RUN TestConn_Exec 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestConn_Exec (0.03s) 450s === RUN TestConn_Query 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestConn_Query (0.03s) 450s === RUN TestConn_QueryRow 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestConn_QueryRow (0.03s) 450s === RUN TestConn_InterruptQuery 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestConn_InterruptQuery (0.32s) 450s === RUN TestConn_QueryBlob 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestConn_QueryBlob (0.04s) 450s === RUN TestStmt_Exec 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestStmt_Exec (0.03s) 450s === RUN TestStmt_ExecManyParams 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestStmt_ExecManyParams (0.02s) 450s === RUN TestStmt_Query 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestStmt_Query (0.03s) 450s === RUN TestStmt_QueryManyParams 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestStmt_QueryManyParams (0.03s) 450s === RUN TestConn_QueryParams 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestConn_QueryParams (0.03s) 450s === RUN TestConn_QueryManyParams 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestConn_QueryManyParams (0.03s) 450s === RUN TestConn_ExecManyParams 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestConn_ExecManyParams (0.03s) 450s === RUN Test_ColumnTypesEmpty 450s driver_test.go:472: this currently fails if the result set is empty, is dqlite skipping the header if empty set? 450s --- SKIP: Test_ColumnTypesEmpty (0.00s) 450s === RUN Test_ColumnTypesExists 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: Test_ColumnTypesExists (0.02s) 450s === RUN Test_ColumnTypesEnd 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: Test_ColumnTypesEnd (0.03s) 450s === RUN Test_ZeroColumns 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: Test_ZeroColumns (0.05s) 450s === RUN Test_DescribeLastEntry 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: Test_DescribeLastEntry (0.85s) 450s === RUN Test_Dump 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: Test_Dump (0.04s) 450s === RUN TestIntegration_DatabaseSQL 450s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 450s func.go:15: WARN: attempt 1: server @1: context canceled 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 1: server @1: context canceled 450s --- PASS: TestIntegration_DatabaseSQL (2.06s) 450s === RUN TestIntegration_ConstraintError 450s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 1: server @1: context canceled 450s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: operation was canceled 450s --- PASS: TestIntegration_ConstraintError (2.05s) 450s === RUN TestIntegration_ExecBindError 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s integration_test.go:116: 450s Error Trace: /tmp/autopkgtest.vcFgEA/autopkgtest_tmp/_build/src/github.com/canonical/go-dqlite/driver/integration_test.go:116 450s Error: An error is expected but got nil. 450s Test: TestIntegration_ExecBindError 450s --- FAIL: TestIntegration_ExecBindError (0.07s) 450s === RUN TestIntegration_QueryBindError 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestIntegration_QueryBindError (0.03s) 450s === RUN TestIntegration_LargeQuery 450s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 450s func.go:15: WARN: attempt 1: server @1: context canceled 450s --- PASS: TestIntegration_LargeQuery (2.10s) 450s === RUN TestIntegration_Recover 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 1: server @1: context canceled 450s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 450s func.go:15: DEBUG: attempt 1: server @1: connected on fast path 450s --- PASS: TestIntegration_Recover (1.10s) 450s === RUN TestIntegration_PingOnlyWorksOnceLeaderElected 450s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: WARN: attempt 1: server @1: no known leader 450s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 1: server @1: reported leader server is not the leader 450s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 2: server @1: no known leader 450s func.go:15: WARN: attempt 2: server @1: reported leader server is not the leader 450s func.go:15: WARN: attempt 3: server @1: no known leader 450s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 3: server @1: reported leader server is not the leader 450s func.go:15: DEBUG: attempt 4: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 4: server @1: no known leader 450s func.go:15: WARN: attempt 4: server @1: reported leader server is not the leader 450s func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 5: server @1: no known leader 450s func.go:15: WARN: attempt 5: server @1: reported leader server is not the leader 450s func.go:15: DEBUG: attempt 6: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 6: server @1: no known leader 450s func.go:15: WARN: attempt 6: server @1: reported leader server is not the leader 450s func.go:15: WARN: attempt 7: server @2: no known leader 450s func.go:15: WARN: attempt 7: server @1: no known leader 450s func.go:15: DEBUG: attempt 8: server @2: connect to reported leader @1 450s func.go:15: DEBUG: attempt 8: server @1: connected on fallback path 450s --- PASS: TestIntegration_PingOnlyWorksOnceLeaderElected (7.89s) 450s === RUN TestIntegration_HighAvailability 450s func.go:15: WARN: attempt 1: server @3: no known leader 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 1: server @1: context canceled 450s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 450s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: WARN: attempt 1: server @3: no known leader 450s func.go:15: WARN: attempt 1: server @2: no known leader 450s func.go:15: WARN: attempt 2: server @2: no known leader 450s func.go:15: WARN: attempt 2: server @3: no known leader 450s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: WARN: attempt 3: server @3: no known leader 450s func.go:15: WARN: attempt 3: server @2: no known leader 450s func.go:15: WARN: attempt 4: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: WARN: attempt 4: server @2: no known leader 450s func.go:15: WARN: attempt 4: server @3: no known leader 450s func.go:15: WARN: attempt 5: server @1: dial: dial unix @1: connect: connection refused 450s func.go:15: DEBUG: attempt 5: server @3: connect to reported leader @2 450s func.go:15: WARN: attempt 5: server @2: context canceled 450s func.go:15: DEBUG: attempt 5: server @2: connected on fallback path 450s --- PASS: TestIntegration_HighAvailability (6.54s) 450s === RUN TestIntegration_LeadershipTransfer 450s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 450s func.go:15: WARN: attempt 1: server @1: context canceled 450s func.go:15: DEBUG: leadership lost (10250 - not leader) 450s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 450s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 450s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 450s --- PASS: TestIntegration_LeadershipTransfer (2.08s) 450s === RUN TestIntegration_LeadershipTransfer_Tx 450s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 450s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s func.go:15: DEBUG: leadership lost (10250 - not leader) 450s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 450s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 450s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 450s --- PASS: TestIntegration_LeadershipTransfer_Tx (2.06s) 450s === RUN TestOptions 450s --- PASS: TestOptions (0.00s) 450s === RUN TestIntegration_ColumnTypeName 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestIntegration_ColumnTypeName (0.06s) 450s === RUN TestIntegration_SqlNullTime 450s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 450s --- PASS: TestIntegration_SqlNullTime (0.06s) 450s FAIL 450s FAIL github.com/canonical/go-dqlite/driver 27.805s 450s === RUN TestNode_Create 450s --- PASS: TestNode_Create (0.04s) 450s === RUN TestNode_Start 450s --- PASS: TestNode_Start (0.02s) 450s === RUN TestNode_Restart 450s --- PASS: TestNode_Restart (0.04s) 450s === RUN TestNode_Start_Inet 450s --- PASS: TestNode_Start_Inet (0.04s) 450s === RUN TestNode_Leader 450s --- PASS: TestNode_Leader (0.02s) 450s === RUN TestNode_Autorecovery 450s --- PASS: TestNode_Autorecovery (0.03s) 450s PASS 450s ok github.com/canonical/go-dqlite/internal/bindings 0.207s 450s === RUN TestMessage_StaticBytesAlignment 450s --- PASS: TestMessage_StaticBytesAlignment (0.00s) 450s === RUN TestMessage_putBlob 450s === RUN TestMessage_putBlob/16 450s === RUN TestMessage_putBlob/16#01 450s === RUN TestMessage_putBlob/24 450s --- PASS: TestMessage_putBlob (0.00s) 450s --- PASS: TestMessage_putBlob/16 (0.00s) 450s --- PASS: TestMessage_putBlob/16#01 (0.00s) 450s --- PASS: TestMessage_putBlob/24 (0.00s) 450s === RUN TestMessage_putString 450s === RUN TestMessage_putString/hello 450s === RUN TestMessage_putString/hello!! 450s === RUN TestMessage_putString/hello_world 450s --- PASS: TestMessage_putString (0.00s) 450s --- PASS: TestMessage_putString/hello (0.00s) 450s --- PASS: TestMessage_putString/hello!! (0.00s) 450s --- PASS: TestMessage_putString/hello_world (0.00s) 450s === RUN TestMessage_putUint8 450s --- PASS: TestMessage_putUint8 (0.00s) 450s === RUN TestMessage_putUint16 450s --- PASS: TestMessage_putUint16 (0.00s) 450s === RUN TestMessage_putUint32 450s --- PASS: TestMessage_putUint32 (0.00s) 450s === RUN TestMessage_putUint64 450s --- PASS: TestMessage_putUint64 (0.00s) 450s === RUN TestMessage_putNamedValues 450s --- PASS: TestMessage_putNamedValues (0.00s) 450s === RUN TestMessage_putNamedValues32 450s --- PASS: TestMessage_putNamedValues32 (0.00s) 450s === RUN TestMessage_putHeader 450s --- PASS: TestMessage_putHeader (0.00s) 450s === RUN TestMessage_getString 450s === RUN TestMessage_getString/hello 450s === RUN TestMessage_getString/hello!! 450s === RUN TestMessage_getString/hello!!! 450s === RUN TestMessage_getString/hello_world 450s --- PASS: TestMessage_getString (0.00s) 450s --- PASS: TestMessage_getString/hello (0.00s) 450s --- PASS: TestMessage_getString/hello!! (0.00s) 450s --- PASS: TestMessage_getString/hello!!! (0.00s) 450s --- PASS: TestMessage_getString/hello_world (0.00s) 450s === RUN TestMessage_getBlob 450s === RUN TestMessage_getBlob/16 450s === RUN TestMessage_getBlob/16#01 450s === RUN TestMessage_getBlob/24 450s --- PASS: TestMessage_getBlob (0.00s) 450s --- PASS: TestMessage_getBlob/16 (0.00s) 450s --- PASS: TestMessage_getBlob/16#01 (0.00s) 450s --- PASS: TestMessage_getBlob/24 (0.00s) 450s === RUN TestMessage_getString_Overflow_WordBoundary 450s --- PASS: TestMessage_getString_Overflow_WordBoundary (0.00s) 450s === RUN TestConnector_Success 450s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 450s --- PASS: TestConnector_Success (0.04s) 450s === RUN TestConnector_LeaderTracker 450s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 450s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 450s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 450s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 450s connector_test.go:398: DEBUG: reusing shared connection to @test-0 450s connector_test.go:398: DEBUG: discarding shared connection to @test-0 450s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 450s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 450s connector_test.go:398: DEBUG: reusing shared connection to @test-0 450s connector_test.go:398: DEBUG: discarding shared connection to @test-0 450s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 450s --- PASS: TestConnector_LeaderTracker (0.84s) 450s === RUN TestConnector_LimitRetries 450s connector_test.go:398: WARN: attempt 1: server @test-123: dial: dial unix @test-123: connect: connection refused 450s connector_test.go:398: WARN: attempt 2: server @test-123: dial: dial unix @test-123: connect: connection refused 450s connector_test.go:398: WARN: attempt 3: server @test-123: dial: dial unix @test-123: connect: connection refused 450s --- PASS: TestConnector_LimitRetries (0.60s) 450s === RUN TestConnector_DialTimeout 450s connector_test.go:158: Skipping network test 450s --- SKIP: TestConnector_DialTimeout (0.00s) 450s === RUN TestConnector_EmptyNodeStore 450s --- PASS: TestConnector_EmptyNodeStore (0.20s) 450s === RUN TestConnector_ContextCanceled 450s connector_test.go:193: Skipping network test 450s --- SKIP: TestConnector_ContextCanceled (0.00s) 450s === RUN TestConnector_AttemptTimeout 450s func.go:15: WARN: attempt 1: server @1234: call leader (budget 99.92628ms): receive: header: read unix @->@1234: i/o timeout 450s func.go:15: WARN: attempt 2: server @1234: call leader (budget 99.826732ms): receive: header: read unix @->@1234: i/o timeout 450s --- PASS: TestConnector_AttemptTimeout (0.40s) 450s === RUN TestProtocol_RequestWithDynamicBuffer 450s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 450s --- PASS: TestProtocol_RequestWithDynamicBuffer (0.06s) 450s === RUN TestProtocol_Prepare 450s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 450s --- PASS: TestProtocol_Prepare (0.02s) 450s PASS 450s ok github.com/canonical/go-dqlite/internal/protocol 2.168s 450s ? github.com/canonical/go-dqlite/internal/shell [no test files] 450s === RUN Test_TestFunc 450s func.go:15: INFO: hello 450s --- PASS: Test_TestFunc (0.00s) 450s === RUN TestLevel_String 450s --- PASS: TestLevel_String (0.00s) 450s PASS 450s ok github.com/canonical/go-dqlite/logging 0.003s 450s ? github.com/canonical/go-dqlite/tracing [no test files] 450s FAIL 450s 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 450s make: *** [debian/rules:11: autopkgtest] Error 25 451s autopkgtest [08:49:04]: test dh-golang-autopkgtest: -----------------------] 451s dh-golang-autopkgtest FLAKY non-zero exit status 2 451s autopkgtest [08:49:04]: test dh-golang-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 452s autopkgtest [08:49:05]: @@@@@@@@@@@@@@@@@@@@ summary 452s dh-golang-autopkgtest FLAKY non-zero exit status 2