0s autopkgtest [04:19:00]: starting date and time: 2025-11-04 04:19:00+0000 0s autopkgtest [04:19:00]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [04:19:00]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.l57ssqnt/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:golang-yaml.v2 --apt-upgrade golang-github-canonical-go-dqlite --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=golang-yaml.v2/2.4.3-1 -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-arm64-7.secgroup --name adt-resolute-arm64-golang-github-canonical-go-dqlite-20251104-041900-juju-7f2275-prod-proposed-migration-environment-15-ba19df67-a927-4897-a363-af7819399e02 --image adt/ubuntu-resolute-arm64-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-arm64-golang-github-canonical-go-dqlite-20251104-041900-juju-7f2275-prod-proposed-migration-environment-15-ba19df67-a927-4897-a363-af7819399e02 from image adt/ubuntu-resolute-arm64-server-20251104.img (UUID 2e2491ad-9a1f-4837-a9e6-434abe3bdc09)... 68s autopkgtest [04:20:08]: testbed dpkg architecture: arm64 69s autopkgtest [04:20:09]: testbed apt version: 3.1.11 69s autopkgtest [04:20:09]: @@@@@@@@@@@@@@@@@@@@ test bed setup 69s autopkgtest [04:20:09]: testbed release detected to be: None 70s autopkgtest [04:20:10]: updating testbed package index (apt update) 71s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 71s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 71s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 71s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 71s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [9848 B] 71s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1057 kB] 72s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [83.5 kB] 72s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.0 kB] 72s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 Packages [132 kB] 72s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main arm64 c-n-f Metadata [3104 B] 72s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 Packages [107 kB] 72s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/restricted arm64 c-n-f Metadata [324 B] 72s Get:13 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 Packages [669 kB] 73s Get:14 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 c-n-f Metadata [16.0 kB] 73s Get:15 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 Packages [14.7 kB] 73s Get:16 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse arm64 c-n-f Metadata [516 B] 74s Fetched 2205 kB in 2s (923 kB/s) 75s Reading package lists... 76s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 76s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 76s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 76s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 77s Reading package lists... 77s Reading package lists... 78s Building dependency tree... 78s Reading state information... 78s Calculating upgrade... 79s The following packages will be upgraded: 79s bash-completion iputils-tracepath 79s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 79s Need to get 229 kB of archives. 79s After this operation, 1024 B of additional disk space will be used. 79s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 bash-completion all 1:2.16.0-8 [214 kB] 79s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 iputils-tracepath arm64 3:20250605-1ubuntu1 [14.7 kB] 80s dpkg-preconfigure: unable to re-open stdin: No such file or directory 80s Fetched 229 kB in 1s (417 kB/s) 80s (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 ... 83477 files and directories currently installed.) 80s Preparing to unpack .../bash-completion_1%3a2.16.0-8_all.deb ... 80s Unpacking bash-completion (1:2.16.0-8) over (1:2.16.0-7) ... 81s Preparing to unpack .../iputils-tracepath_3%3a20250605-1ubuntu1_arm64.deb ... 81s Unpacking iputils-tracepath (3:20250605-1ubuntu1) over (3:20240905-3ubuntu3) ... 81s Setting up bash-completion (1:2.16.0-8) ... 81s Setting up iputils-tracepath (3:20250605-1ubuntu1) ... 81s Processing triggers for man-db (2.13.1-1) ... 82s autopkgtest [04:20:22]: upgrading testbed (apt dist-upgrade and autopurge) 82s Reading package lists... 83s Building dependency tree... 83s Reading state information... 83s Calculating upgrade... 84s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 85s Reading package lists... 85s Building dependency tree... 85s Reading state information... 86s Solving dependencies... 87s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 90s autopkgtest [04:20:30]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 09:50:31 UTC 2025 90s autopkgtest [04:20:30]: @@@@@@@@@@@@@@@@@@@@ apt-source golang-github-canonical-go-dqlite 92s Get:1 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (dsc) [2750 B] 92s Get:2 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (tar) [94.6 kB] 92s Get:3 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (diff) [4532 B] 93s gpgv: Signature made Mon Sep 1 19:07:08 2025 UTC 93s gpgv: using RSA key D41A7AD07DF6C5FCA7489F1C29EEE2D6ECF442F9 93s gpgv: issuer "gibmat@debian.org" 93s gpgv: Can't check signature: No public key 93s dpkg-source: warning: cannot verify inline signature for ./golang-github-canonical-go-dqlite_2.0.1-1.dsc: no acceptable signature found 93s autopkgtest [04:20:33]: testing package golang-github-canonical-go-dqlite version 2.0.1-1 93s autopkgtest [04:20:33]: build not needed 94s autopkgtest [04:20:34]: test dh-golang-autopkgtest: preparing testbed 94s Reading package lists... 94s Building dependency tree... 94s Reading state information... 95s Solving dependencies... 95s The following NEW packages will be installed: 95s autoconf automake autopoint autotools-dev binutils-gold 95s binutils-gold-aarch64-linux-gnu build-essential cpp cpp-15 95s cpp-15-aarch64-linux-gnu cpp-aarch64-linux-gnu debhelper debugedit 95s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-15 95s g++-15-aarch64-linux-gnu g++-aarch64-linux-gnu gcc gcc-15 95s gcc-15-aarch64-linux-gnu gcc-aarch64-linux-gnu gettext go-dqlite 95s golang-1.25-go golang-1.25-src golang-any golang-github-burntsushi-toml-dev 95s golang-github-canonical-go-dqlite-dev 95s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 95s golang-github-fsnotify-fsnotify-dev golang-github-google-renameio-dev 95s golang-github-hashicorp-hcl-dev golang-github-kr-fs-dev 95s golang-github-magiconair-properties-dev golang-github-mattn-go-runewidth-dev 95s golang-github-mattn-go-sqlite3-dev golang-github-mitchellh-mapstructure-dev 95s golang-github-pelletier-go-toml-dev golang-github-pelletier-go-toml.v2-dev 95s golang-github-peterh-liner-dev golang-github-pkg-errors-dev 95s golang-github-pkg-sftp-dev golang-github-pmezard-go-difflib-dev 95s golang-github-rican7-retry-dev golang-github-rivo-uniseg-dev 95s golang-github-russross-blackfriday-v2-dev 95s golang-github-shurcool-sanitized-anchor-name-dev 95s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 95s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 95s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 95s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 95s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 95s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 95s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 95s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 95s libasan8 libcc1-0 libdebhelper-perl libdqlite-dev libdqlite0 95s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libhwasan0 libisl23 95s libitm1 liblsan0 liblz4-dev libmpc3 libsqlite3-dev libstdc++-15-dev libtool 95s libtsan2 libubsan1 libuv1-dev libxxhash-dev m4 po-debconf tzdata-legacy 96s 0 upgraded, 97 newly installed, 0 to remove and 0 not upgraded. 96s Need to get 126 MB of archives. 96s After this operation, 555 MB of additional disk space will be used. 96s Get:1 http://ftpmaster.internal/ubuntu resolute/main arm64 m4 arm64 1.4.20-2 [213 kB] 96s Get:2 http://ftpmaster.internal/ubuntu resolute/main arm64 autoconf all 2.72-3.1ubuntu1 [384 kB] 96s Get:3 http://ftpmaster.internal/ubuntu resolute/main arm64 autotools-dev all 20240727.1 [43.4 kB] 96s Get:4 http://ftpmaster.internal/ubuntu resolute/main arm64 automake all 1:1.18.1-2 [581 kB] 97s Get:5 http://ftpmaster.internal/ubuntu resolute/main arm64 autopoint all 0.23.1-2build2 [619 kB] 97s Get:6 http://ftpmaster.internal/ubuntu resolute/main arm64 binutils-gold-aarch64-linux-gnu arm64 2.44-3 [2138 kB] 99s Get:7 http://ftpmaster.internal/ubuntu resolute/main arm64 binutils-gold arm64 2.44-3 [2172 B] 99s Get:8 http://ftpmaster.internal/ubuntu resolute/main arm64 libisl23 arm64 0.27-1 [676 kB] 100s Get:9 http://ftpmaster.internal/ubuntu resolute/main arm64 libmpc3 arm64 1.3.1-1build3 [58.9 kB] 100s Get:10 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp-15-aarch64-linux-gnu arm64 15.2.0-7ubuntu1 [11.7 MB] 107s Get:11 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp-15 arm64 15.2.0-7ubuntu1 [1026 B] 107s Get:12 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp-aarch64-linux-gnu arm64 4:15.2.0-4ubuntu1 [5736 B] 108s Get:13 http://ftpmaster.internal/ubuntu resolute/main arm64 cpp arm64 4:15.2.0-4ubuntu1 [22.4 kB] 108s Get:14 http://ftpmaster.internal/ubuntu resolute/main arm64 libcc1-0 arm64 15.2.0-7ubuntu1 [49.0 kB] 108s Get:15 http://ftpmaster.internal/ubuntu resolute/main arm64 libgomp1 arm64 15.2.0-7ubuntu1 [147 kB] 108s Get:16 http://ftpmaster.internal/ubuntu resolute/main arm64 libitm1 arm64 15.2.0-7ubuntu1 [27.9 kB] 108s Get:17 http://ftpmaster.internal/ubuntu resolute/main arm64 libasan8 arm64 15.2.0-7ubuntu1 [2923 kB] 110s Get:18 http://ftpmaster.internal/ubuntu resolute/main arm64 liblsan0 arm64 15.2.0-7ubuntu1 [1316 kB] 110s Get:19 http://ftpmaster.internal/ubuntu resolute/main arm64 libtsan2 arm64 15.2.0-7ubuntu1 [2689 kB] 112s Get:20 http://ftpmaster.internal/ubuntu resolute/main arm64 libubsan1 arm64 15.2.0-7ubuntu1 [1176 kB] 113s Get:21 http://ftpmaster.internal/ubuntu resolute/main arm64 libhwasan0 arm64 15.2.0-7ubuntu1 [1638 kB] 114s Get:22 http://ftpmaster.internal/ubuntu resolute/main arm64 libgcc-15-dev arm64 15.2.0-7ubuntu1 [2600 kB] 115s Get:23 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc-15-aarch64-linux-gnu arm64 15.2.0-7ubuntu1 [23.1 MB] 128s Get:24 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc-15 arm64 15.2.0-7ubuntu1 [513 kB] 128s Get:25 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc-aarch64-linux-gnu arm64 4:15.2.0-4ubuntu1 [1206 B] 128s Get:26 http://ftpmaster.internal/ubuntu resolute/main arm64 gcc arm64 4:15.2.0-4ubuntu1 [5016 B] 128s Get:27 http://ftpmaster.internal/ubuntu resolute/main arm64 libstdc++-15-dev arm64 15.2.0-7ubuntu1 [2546 kB] 129s Get:28 http://ftpmaster.internal/ubuntu resolute/main arm64 g++-15-aarch64-linux-gnu arm64 15.2.0-7ubuntu1 [13.2 MB] 140s Get:29 http://ftpmaster.internal/ubuntu resolute/main arm64 g++-15 arm64 15.2.0-7ubuntu1 [23.7 kB] 140s Get:30 http://ftpmaster.internal/ubuntu resolute/main arm64 g++-aarch64-linux-gnu arm64 4:15.2.0-4ubuntu1 [956 B] 140s Get:31 http://ftpmaster.internal/ubuntu resolute/main arm64 g++ arm64 4:15.2.0-4ubuntu1 [1080 B] 140s Get:32 http://ftpmaster.internal/ubuntu resolute/main arm64 build-essential arm64 12.12ubuntu1 [5082 B] 140s Get:33 http://ftpmaster.internal/ubuntu resolute/main arm64 libdebhelper-perl all 13.24.2ubuntu1 [95.7 kB] 140s Get:34 http://ftpmaster.internal/ubuntu resolute/main arm64 libtool all 2.5.4-4build1 [169 kB] 140s Get:35 http://ftpmaster.internal/ubuntu resolute/main arm64 dh-autoreconf all 21 [12.5 kB] 140s Get:36 http://ftpmaster.internal/ubuntu resolute/main arm64 libarchive-zip-perl all 1.68-1 [90.2 kB] 140s Get:37 http://ftpmaster.internal/ubuntu resolute/main arm64 libfile-stripnondeterminism-perl all 1.15.0-1 [20.5 kB] 140s Get:38 http://ftpmaster.internal/ubuntu resolute/main arm64 dh-strip-nondeterminism all 1.15.0-1 [5090 B] 140s Get:39 http://ftpmaster.internal/ubuntu resolute/main arm64 debugedit arm64 1:5.2-3 [49.1 kB] 141s Get:40 http://ftpmaster.internal/ubuntu resolute/main arm64 dwz arm64 0.16-2 [113 kB] 141s Get:41 http://ftpmaster.internal/ubuntu resolute/main arm64 gettext arm64 0.23.1-2build2 [1026 kB] 142s Get:42 http://ftpmaster.internal/ubuntu resolute/main arm64 intltool-debian all 0.35.0+20060710.6 [23.2 kB] 142s Get:43 http://ftpmaster.internal/ubuntu resolute/main arm64 po-debconf all 1.0.21+nmu1 [233 kB] 142s Get:44 http://ftpmaster.internal/ubuntu resolute/main arm64 debhelper all 13.24.2ubuntu1 [896 kB] 143s Get:45 http://ftpmaster.internal/ubuntu resolute/universe arm64 libdqlite0 arm64 1.18.2-3 [142 kB] 143s Get:46 http://ftpmaster.internal/ubuntu resolute/universe arm64 go-dqlite arm64 2.0.1-1 [4886 kB] 146s Get:47 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 159s Get:48 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-1.25-go arm64 1.25.0-0ubuntu1 [16.0 MB] 173s Get:49 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-src all 2:1.25~1 [5206 B] 173s Get:50 http://ftpmaster.internal/ubuntu resolute/main arm64 golang-go arm64 2:1.25~1 [44.1 kB] 173s Get:51 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-any arm64 2:1.25~1 [2838 B] 173s Get:52 http://ftpmaster.internal/ubuntu resolute/main arm64 libxxhash-dev arm64 0.8.3-2 [81.7 kB] 173s Get:53 http://ftpmaster.internal/ubuntu resolute/main arm64 liblz4-dev arm64 1.10.0-4build1 [104 kB] 173s Get:54 http://ftpmaster.internal/ubuntu resolute/main arm64 libuv1-dev arm64 1.51.0-2ubuntu1 [139 kB] 173s Get:55 http://ftpmaster.internal/ubuntu resolute/main arm64 libsqlite3-dev arm64 3.46.1-8 [932 kB] 174s Get:56 http://ftpmaster.internal/ubuntu resolute/universe arm64 libdqlite-dev arm64 1.18.2-3 [230 kB] 174s Get:57 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-google-renameio-dev all 2.0.0-2 [10.3 kB] 174s Get:58 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-mattn-go-sqlite3-dev arm64 1.14.32~ds1-1 [84.6 kB] 174s Get:59 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-rivo-uniseg-dev all 0.4.7-1 [258 kB] 174s Get:60 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-mattn-go-runewidth-dev all 0.0.16-2 [17.6 kB] 174s Get:61 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-peterh-liner-dev all 1.2.2-1 [21.3 kB] 174s Get:62 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-pkg-errors-dev all 0.9.1-3 [13.2 kB] 174s Get:63 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-rican7-retry-dev all 0.3.1-2 [7740 B] 174s Get:64 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-pmezard-go-difflib-dev all 1.0.0-3 [12.4 kB] 174s Get:65 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-shurcool-sanitized-anchor-name-dev all 1.0.0-3 [3590 B] 174s Get:66 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-russross-blackfriday-v2-dev all 2.1.0-1 [79.6 kB] 174s Get:67 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-cpuguy83-go-md2man-v2-dev all 2.0.4-1 [11.0 kB] 174s Get:68 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-spf13-pflag-dev all 1.0.10-1 [50.1 kB] 174s Get:69 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-golang-x-sys-dev all 0.22.0-1 [457 kB] 175s Get:70 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-fsnotify-fsnotify-dev all 1.9.0-1 [57.3 kB] 175s Get:71 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-davecgh-go-spew-dev all 1.1.1-3 [31.1 kB] 175s Get:72 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-hashicorp-hcl-dev all 1.0.0-1.1 [58.9 kB] 175s Get:73 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-magiconair-properties-dev all 1.8.7-1 [27.3 kB] 175s Get:74 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-mitchellh-mapstructure-dev all 1.5.0+git20231216.8508981-1 [28.3 kB] 175s Get:75 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-burntsushi-toml-dev all 1.4.0-1 [119 kB] 175s Get:76 http://ftpmaster.internal/ubuntu resolute-proposed/universe arm64 golang-gopkg-yaml.v2-dev all 2.4.3-1 [62.7 kB] 175s Get:77 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-pelletier-go-toml-dev all 1.9.5-1build1 [79.6 kB] 175s Get:78 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-pelletier-go-toml.v2-dev all 2.2.4-1 [878 kB] 175s Get:79 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-kr-fs-dev all 0.1.0-2 [6344 B] 175s Get:80 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-golang-x-term-dev all 0.22.0-1 [14.6 kB] 176s Get:81 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-golang-x-text-dev all 0.22.0-1 [4616 kB] 179s Get:82 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-golang-x-crypto-dev all 1:0.25.0-1 [1724 kB] 181s Get:83 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-pkg-sftp-dev all 1.13.9-1 [108 kB] 181s Get:84 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-spf13-afero-dev all 1.15.0-1 [49.8 kB] 181s Get:85 http://ftpmaster.internal/ubuntu resolute/main arm64 tzdata-legacy all 2025b-5ubuntu1 [99.3 kB] 181s Get:86 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-spf13-cast-dev all 1.10.0-2 [20.5 kB] 181s Get:87 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-stretchr-objx-dev all 0.5.2-1 [26.5 kB] 181s Get:88 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-gopkg-yaml.v3-dev all 3.0.1-3 [76.1 kB] 181s Get:89 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-stretchr-testify-dev all 1.10.0-1 [79.6 kB] 182s Get:90 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-spf13-jwalterweatherman-dev all 1.1.0+really1.1.0-2 [6828 B] 182s Get:91 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-subosito-gotenv-dev all 1.3.0-1 [8808 B] 182s Get:92 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-gopkg-ini.v1-dev all 1.67.0-1 [40.8 kB] 182s Get:93 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-spf13-viper-dev all 1.12.0-1 [71.0 kB] 182s Get:94 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-spf13-cobra-dev all 1.8.1-1 [76.2 kB] 182s Get:95 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-golang-x-sync-dev all 0.7.0-1 [19.5 kB] 182s Get:96 http://ftpmaster.internal/ubuntu resolute/universe arm64 golang-github-canonical-go-dqlite-dev all 2.0.1-1 [71.9 kB] 182s Get:97 http://ftpmaster.internal/ubuntu resolute/main arm64 dh-golang all 1.63 [24.9 kB] 183s Fetched 126 MB in 1min 27s (1450 kB/s) 183s Selecting previously unselected package m4. 183s (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 ... 83477 files and directories currently installed.) 183s Preparing to unpack .../00-m4_1.4.20-2_arm64.deb ... 183s Unpacking m4 (1.4.20-2) ... 183s Selecting previously unselected package autoconf. 183s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ... 183s Unpacking autoconf (2.72-3.1ubuntu1) ... 183s Selecting previously unselected package autotools-dev. 183s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ... 183s Unpacking autotools-dev (20240727.1) ... 183s Selecting previously unselected package automake. 183s Preparing to unpack .../03-automake_1%3a1.18.1-2_all.deb ... 183s Unpacking automake (1:1.18.1-2) ... 183s Selecting previously unselected package autopoint. 183s Preparing to unpack .../04-autopoint_0.23.1-2build2_all.deb ... 183s Unpacking autopoint (0.23.1-2build2) ... 183s Selecting previously unselected package binutils-gold-aarch64-linux-gnu. 183s Preparing to unpack .../05-binutils-gold-aarch64-linux-gnu_2.44-3_arm64.deb ... 183s Unpacking binutils-gold-aarch64-linux-gnu (2.44-3) ... 184s Selecting previously unselected package binutils-gold. 184s Preparing to unpack .../06-binutils-gold_2.44-3_arm64.deb ... 184s Unpacking binutils-gold (2.44-3) ... 184s Selecting previously unselected package libisl23:arm64. 184s Preparing to unpack .../07-libisl23_0.27-1_arm64.deb ... 184s Unpacking libisl23:arm64 (0.27-1) ... 184s Selecting previously unselected package libmpc3:arm64. 184s Preparing to unpack .../08-libmpc3_1.3.1-1build3_arm64.deb ... 184s Unpacking libmpc3:arm64 (1.3.1-1build3) ... 184s Selecting previously unselected package cpp-15-aarch64-linux-gnu. 184s Preparing to unpack .../09-cpp-15-aarch64-linux-gnu_15.2.0-7ubuntu1_arm64.deb ... 184s Unpacking cpp-15-aarch64-linux-gnu (15.2.0-7ubuntu1) ... 184s Selecting previously unselected package cpp-15. 184s Preparing to unpack .../10-cpp-15_15.2.0-7ubuntu1_arm64.deb ... 184s Unpacking cpp-15 (15.2.0-7ubuntu1) ... 184s Selecting previously unselected package cpp-aarch64-linux-gnu. 184s Preparing to unpack .../11-cpp-aarch64-linux-gnu_4%3a15.2.0-4ubuntu1_arm64.deb ... 184s Unpacking cpp-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 184s Selecting previously unselected package cpp. 184s Preparing to unpack .../12-cpp_4%3a15.2.0-4ubuntu1_arm64.deb ... 184s Unpacking cpp (4:15.2.0-4ubuntu1) ... 184s Selecting previously unselected package libcc1-0:arm64. 184s Preparing to unpack .../13-libcc1-0_15.2.0-7ubuntu1_arm64.deb ... 184s Unpacking libcc1-0:arm64 (15.2.0-7ubuntu1) ... 184s Selecting previously unselected package libgomp1:arm64. 184s Preparing to unpack .../14-libgomp1_15.2.0-7ubuntu1_arm64.deb ... 184s Unpacking libgomp1:arm64 (15.2.0-7ubuntu1) ... 184s Selecting previously unselected package libitm1:arm64. 184s Preparing to unpack .../15-libitm1_15.2.0-7ubuntu1_arm64.deb ... 184s Unpacking libitm1:arm64 (15.2.0-7ubuntu1) ... 184s Selecting previously unselected package libasan8:arm64. 184s Preparing to unpack .../16-libasan8_15.2.0-7ubuntu1_arm64.deb ... 184s Unpacking libasan8:arm64 (15.2.0-7ubuntu1) ... 184s Selecting previously unselected package liblsan0:arm64. 184s Preparing to unpack .../17-liblsan0_15.2.0-7ubuntu1_arm64.deb ... 184s Unpacking liblsan0:arm64 (15.2.0-7ubuntu1) ... 184s Selecting previously unselected package libtsan2:arm64. 184s Preparing to unpack .../18-libtsan2_15.2.0-7ubuntu1_arm64.deb ... 184s Unpacking libtsan2:arm64 (15.2.0-7ubuntu1) ... 184s Selecting previously unselected package libubsan1:arm64. 184s Preparing to unpack .../19-libubsan1_15.2.0-7ubuntu1_arm64.deb ... 184s Unpacking libubsan1:arm64 (15.2.0-7ubuntu1) ... 185s Selecting previously unselected package libhwasan0:arm64. 185s Preparing to unpack .../20-libhwasan0_15.2.0-7ubuntu1_arm64.deb ... 185s Unpacking libhwasan0:arm64 (15.2.0-7ubuntu1) ... 185s Selecting previously unselected package libgcc-15-dev:arm64. 185s Preparing to unpack .../21-libgcc-15-dev_15.2.0-7ubuntu1_arm64.deb ... 185s Unpacking libgcc-15-dev:arm64 (15.2.0-7ubuntu1) ... 185s Selecting previously unselected package gcc-15-aarch64-linux-gnu. 185s Preparing to unpack .../22-gcc-15-aarch64-linux-gnu_15.2.0-7ubuntu1_arm64.deb ... 185s Unpacking gcc-15-aarch64-linux-gnu (15.2.0-7ubuntu1) ... 185s Selecting previously unselected package gcc-15. 185s Preparing to unpack .../23-gcc-15_15.2.0-7ubuntu1_arm64.deb ... 185s Unpacking gcc-15 (15.2.0-7ubuntu1) ... 185s Selecting previously unselected package gcc-aarch64-linux-gnu. 185s Preparing to unpack .../24-gcc-aarch64-linux-gnu_4%3a15.2.0-4ubuntu1_arm64.deb ... 185s Unpacking gcc-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 185s Selecting previously unselected package gcc. 185s Preparing to unpack .../25-gcc_4%3a15.2.0-4ubuntu1_arm64.deb ... 185s Unpacking gcc (4:15.2.0-4ubuntu1) ... 185s Selecting previously unselected package libstdc++-15-dev:arm64. 185s Preparing to unpack .../26-libstdc++-15-dev_15.2.0-7ubuntu1_arm64.deb ... 185s Unpacking libstdc++-15-dev:arm64 (15.2.0-7ubuntu1) ... 186s Selecting previously unselected package g++-15-aarch64-linux-gnu. 186s Preparing to unpack .../27-g++-15-aarch64-linux-gnu_15.2.0-7ubuntu1_arm64.deb ... 186s Unpacking g++-15-aarch64-linux-gnu (15.2.0-7ubuntu1) ... 186s Selecting previously unselected package g++-15. 186s Preparing to unpack .../28-g++-15_15.2.0-7ubuntu1_arm64.deb ... 186s Unpacking g++-15 (15.2.0-7ubuntu1) ... 186s Selecting previously unselected package g++-aarch64-linux-gnu. 186s Preparing to unpack .../29-g++-aarch64-linux-gnu_4%3a15.2.0-4ubuntu1_arm64.deb ... 186s Unpacking g++-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 186s Selecting previously unselected package g++. 186s Preparing to unpack .../30-g++_4%3a15.2.0-4ubuntu1_arm64.deb ... 186s Unpacking g++ (4:15.2.0-4ubuntu1) ... 186s Selecting previously unselected package build-essential. 186s Preparing to unpack .../31-build-essential_12.12ubuntu1_arm64.deb ... 186s Unpacking build-essential (12.12ubuntu1) ... 186s Selecting previously unselected package libdebhelper-perl. 186s Preparing to unpack .../32-libdebhelper-perl_13.24.2ubuntu1_all.deb ... 186s Unpacking libdebhelper-perl (13.24.2ubuntu1) ... 186s Selecting previously unselected package libtool. 186s Preparing to unpack .../33-libtool_2.5.4-4build1_all.deb ... 186s Unpacking libtool (2.5.4-4build1) ... 186s Selecting previously unselected package dh-autoreconf. 186s Preparing to unpack .../34-dh-autoreconf_21_all.deb ... 186s Unpacking dh-autoreconf (21) ... 186s Selecting previously unselected package libarchive-zip-perl. 186s Preparing to unpack .../35-libarchive-zip-perl_1.68-1_all.deb ... 186s Unpacking libarchive-zip-perl (1.68-1) ... 186s Selecting previously unselected package libfile-stripnondeterminism-perl. 186s Preparing to unpack .../36-libfile-stripnondeterminism-perl_1.15.0-1_all.deb ... 186s Unpacking libfile-stripnondeterminism-perl (1.15.0-1) ... 186s Selecting previously unselected package dh-strip-nondeterminism. 186s Preparing to unpack .../37-dh-strip-nondeterminism_1.15.0-1_all.deb ... 186s Unpacking dh-strip-nondeterminism (1.15.0-1) ... 186s Selecting previously unselected package debugedit. 186s Preparing to unpack .../38-debugedit_1%3a5.2-3_arm64.deb ... 186s Unpacking debugedit (1:5.2-3) ... 186s Selecting previously unselected package dwz. 186s Preparing to unpack .../39-dwz_0.16-2_arm64.deb ... 186s Unpacking dwz (0.16-2) ... 186s Selecting previously unselected package gettext. 186s Preparing to unpack .../40-gettext_0.23.1-2build2_arm64.deb ... 186s Unpacking gettext (0.23.1-2build2) ... 187s Selecting previously unselected package intltool-debian. 187s Preparing to unpack .../41-intltool-debian_0.35.0+20060710.6_all.deb ... 187s Unpacking intltool-debian (0.35.0+20060710.6) ... 187s Selecting previously unselected package po-debconf. 187s Preparing to unpack .../42-po-debconf_1.0.21+nmu1_all.deb ... 187s Unpacking po-debconf (1.0.21+nmu1) ... 187s Selecting previously unselected package debhelper. 187s Preparing to unpack .../43-debhelper_13.24.2ubuntu1_all.deb ... 187s Unpacking debhelper (13.24.2ubuntu1) ... 187s Selecting previously unselected package libdqlite0:arm64. 187s Preparing to unpack .../44-libdqlite0_1.18.2-3_arm64.deb ... 187s Unpacking libdqlite0:arm64 (1.18.2-3) ... 187s Selecting previously unselected package go-dqlite. 187s Preparing to unpack .../45-go-dqlite_2.0.1-1_arm64.deb ... 187s Unpacking go-dqlite (2.0.1-1) ... 187s Selecting previously unselected package golang-1.25-src. 187s Preparing to unpack .../46-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 187s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 188s Selecting previously unselected package golang-1.25-go. 189s Preparing to unpack .../47-golang-1.25-go_1.25.0-0ubuntu1_arm64.deb ... 189s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 189s Selecting previously unselected package golang-src. 189s Preparing to unpack .../48-golang-src_2%3a1.25~1_all.deb ... 189s Unpacking golang-src (2:1.25~1) ... 189s Selecting previously unselected package golang-go:arm64. 189s Preparing to unpack .../49-golang-go_2%3a1.25~1_arm64.deb ... 189s Unpacking golang-go:arm64 (2:1.25~1) ... 189s Selecting previously unselected package golang-any:arm64. 189s Preparing to unpack .../50-golang-any_2%3a1.25~1_arm64.deb ... 189s Unpacking golang-any:arm64 (2:1.25~1) ... 189s Selecting previously unselected package libxxhash-dev:arm64. 189s Preparing to unpack .../51-libxxhash-dev_0.8.3-2_arm64.deb ... 189s Unpacking libxxhash-dev:arm64 (0.8.3-2) ... 189s Selecting previously unselected package liblz4-dev:arm64. 189s Preparing to unpack .../52-liblz4-dev_1.10.0-4build1_arm64.deb ... 189s Unpacking liblz4-dev:arm64 (1.10.0-4build1) ... 189s Selecting previously unselected package libuv1-dev:arm64. 189s Preparing to unpack .../53-libuv1-dev_1.51.0-2ubuntu1_arm64.deb ... 189s Unpacking libuv1-dev:arm64 (1.51.0-2ubuntu1) ... 189s Selecting previously unselected package libsqlite3-dev:arm64. 189s Preparing to unpack .../54-libsqlite3-dev_3.46.1-8_arm64.deb ... 189s Unpacking libsqlite3-dev:arm64 (3.46.1-8) ... 189s Selecting previously unselected package libdqlite-dev:arm64. 189s Preparing to unpack .../55-libdqlite-dev_1.18.2-3_arm64.deb ... 189s Unpacking libdqlite-dev:arm64 (1.18.2-3) ... 189s Selecting previously unselected package golang-github-google-renameio-dev. 189s Preparing to unpack .../56-golang-github-google-renameio-dev_2.0.0-2_all.deb ... 189s Unpacking golang-github-google-renameio-dev (2.0.0-2) ... 189s Selecting previously unselected package golang-github-mattn-go-sqlite3-dev:arm64. 189s Preparing to unpack .../57-golang-github-mattn-go-sqlite3-dev_1.14.32~ds1-1_arm64.deb ... 189s Unpacking golang-github-mattn-go-sqlite3-dev:arm64 (1.14.32~ds1-1) ... 189s Selecting previously unselected package golang-github-rivo-uniseg-dev. 189s Preparing to unpack .../58-golang-github-rivo-uniseg-dev_0.4.7-1_all.deb ... 189s Unpacking golang-github-rivo-uniseg-dev (0.4.7-1) ... 189s Selecting previously unselected package golang-github-mattn-go-runewidth-dev. 190s Preparing to unpack .../59-golang-github-mattn-go-runewidth-dev_0.0.16-2_all.deb ... 190s Unpacking golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 190s Selecting previously unselected package golang-github-peterh-liner-dev. 190s Preparing to unpack .../60-golang-github-peterh-liner-dev_1.2.2-1_all.deb ... 190s Unpacking golang-github-peterh-liner-dev (1.2.2-1) ... 190s Selecting previously unselected package golang-github-pkg-errors-dev. 190s Preparing to unpack .../61-golang-github-pkg-errors-dev_0.9.1-3_all.deb ... 190s Unpacking golang-github-pkg-errors-dev (0.9.1-3) ... 190s Selecting previously unselected package golang-github-rican7-retry-dev. 190s Preparing to unpack .../62-golang-github-rican7-retry-dev_0.3.1-2_all.deb ... 190s Unpacking golang-github-rican7-retry-dev (0.3.1-2) ... 190s Selecting previously unselected package golang-github-pmezard-go-difflib-dev. 190s Preparing to unpack .../63-golang-github-pmezard-go-difflib-dev_1.0.0-3_all.deb ... 190s Unpacking golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 190s Selecting previously unselected package golang-github-shurcool-sanitized-anchor-name-dev. 190s Preparing to unpack .../64-golang-github-shurcool-sanitized-anchor-name-dev_1.0.0-3_all.deb ... 190s Unpacking golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 190s Selecting previously unselected package golang-github-russross-blackfriday-v2-dev. 190s Preparing to unpack .../65-golang-github-russross-blackfriday-v2-dev_2.1.0-1_all.deb ... 190s Unpacking golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 190s Selecting previously unselected package golang-github-cpuguy83-go-md2man-v2-dev. 190s Preparing to unpack .../66-golang-github-cpuguy83-go-md2man-v2-dev_2.0.4-1_all.deb ... 190s Unpacking golang-github-cpuguy83-go-md2man-v2-dev (2.0.4-1) ... 190s Selecting previously unselected package golang-github-spf13-pflag-dev. 190s Preparing to unpack .../67-golang-github-spf13-pflag-dev_1.0.10-1_all.deb ... 190s Unpacking golang-github-spf13-pflag-dev (1.0.10-1) ... 190s Selecting previously unselected package golang-golang-x-sys-dev. 190s Preparing to unpack .../68-golang-golang-x-sys-dev_0.22.0-1_all.deb ... 190s Unpacking golang-golang-x-sys-dev (0.22.0-1) ... 190s Selecting previously unselected package golang-github-fsnotify-fsnotify-dev. 190s Preparing to unpack .../69-golang-github-fsnotify-fsnotify-dev_1.9.0-1_all.deb ... 190s Unpacking golang-github-fsnotify-fsnotify-dev (1.9.0-1) ... 190s Selecting previously unselected package golang-github-davecgh-go-spew-dev. 190s Preparing to unpack .../70-golang-github-davecgh-go-spew-dev_1.1.1-3_all.deb ... 190s Unpacking golang-github-davecgh-go-spew-dev (1.1.1-3) ... 190s Selecting previously unselected package golang-github-hashicorp-hcl-dev. 190s Preparing to unpack .../71-golang-github-hashicorp-hcl-dev_1.0.0-1.1_all.deb ... 190s Unpacking golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 190s Selecting previously unselected package golang-github-magiconair-properties-dev. 190s Preparing to unpack .../72-golang-github-magiconair-properties-dev_1.8.7-1_all.deb ... 190s Unpacking golang-github-magiconair-properties-dev (1.8.7-1) ... 190s Selecting previously unselected package golang-github-mitchellh-mapstructure-dev. 190s Preparing to unpack .../73-golang-github-mitchellh-mapstructure-dev_1.5.0+git20231216.8508981-1_all.deb ... 190s Unpacking golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 190s Selecting previously unselected package golang-github-burntsushi-toml-dev. 190s Preparing to unpack .../74-golang-github-burntsushi-toml-dev_1.4.0-1_all.deb ... 190s Unpacking golang-github-burntsushi-toml-dev (1.4.0-1) ... 190s Selecting previously unselected package golang-gopkg-yaml.v2-dev. 190s Preparing to unpack .../75-golang-gopkg-yaml.v2-dev_2.4.3-1_all.deb ... 190s Unpacking golang-gopkg-yaml.v2-dev (2.4.3-1) ... 190s Selecting previously unselected package golang-github-pelletier-go-toml-dev. 190s Preparing to unpack .../76-golang-github-pelletier-go-toml-dev_1.9.5-1build1_all.deb ... 190s Unpacking golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 190s Selecting previously unselected package golang-github-pelletier-go-toml.v2-dev. 190s Preparing to unpack .../77-golang-github-pelletier-go-toml.v2-dev_2.2.4-1_all.deb ... 190s Unpacking golang-github-pelletier-go-toml.v2-dev (2.2.4-1) ... 190s Selecting previously unselected package golang-github-kr-fs-dev. 191s Preparing to unpack .../78-golang-github-kr-fs-dev_0.1.0-2_all.deb ... 191s Unpacking golang-github-kr-fs-dev (0.1.0-2) ... 191s Selecting previously unselected package golang-golang-x-term-dev. 191s Preparing to unpack .../79-golang-golang-x-term-dev_0.22.0-1_all.deb ... 191s Unpacking golang-golang-x-term-dev (0.22.0-1) ... 191s Selecting previously unselected package golang-golang-x-text-dev. 191s Preparing to unpack .../80-golang-golang-x-text-dev_0.22.0-1_all.deb ... 191s Unpacking golang-golang-x-text-dev (0.22.0-1) ... 191s Selecting previously unselected package golang-golang-x-crypto-dev. 191s Preparing to unpack .../81-golang-golang-x-crypto-dev_1%3a0.25.0-1_all.deb ... 191s Unpacking golang-golang-x-crypto-dev (1:0.25.0-1) ... 191s Selecting previously unselected package golang-github-pkg-sftp-dev. 191s Preparing to unpack .../82-golang-github-pkg-sftp-dev_1.13.9-1_all.deb ... 191s Unpacking golang-github-pkg-sftp-dev (1.13.9-1) ... 191s Selecting previously unselected package golang-github-spf13-afero-dev. 191s Preparing to unpack .../83-golang-github-spf13-afero-dev_1.15.0-1_all.deb ... 191s Unpacking golang-github-spf13-afero-dev (1.15.0-1) ... 191s Selecting previously unselected package tzdata-legacy. 191s Preparing to unpack .../84-tzdata-legacy_2025b-5ubuntu1_all.deb ... 191s Unpacking tzdata-legacy (2025b-5ubuntu1) ... 191s Selecting previously unselected package golang-github-spf13-cast-dev. 191s Preparing to unpack .../85-golang-github-spf13-cast-dev_1.10.0-2_all.deb ... 191s Unpacking golang-github-spf13-cast-dev (1.10.0-2) ... 191s Selecting previously unselected package golang-github-stretchr-objx-dev. 191s Preparing to unpack .../86-golang-github-stretchr-objx-dev_0.5.2-1_all.deb ... 191s Unpacking golang-github-stretchr-objx-dev (0.5.2-1) ... 191s Selecting previously unselected package golang-gopkg-yaml.v3-dev. 191s Preparing to unpack .../87-golang-gopkg-yaml.v3-dev_3.0.1-3_all.deb ... 191s Unpacking golang-gopkg-yaml.v3-dev (3.0.1-3) ... 191s Selecting previously unselected package golang-github-stretchr-testify-dev. 191s Preparing to unpack .../88-golang-github-stretchr-testify-dev_1.10.0-1_all.deb ... 191s Unpacking golang-github-stretchr-testify-dev (1.10.0-1) ... 191s Selecting previously unselected package golang-github-spf13-jwalterweatherman-dev. 191s Preparing to unpack .../89-golang-github-spf13-jwalterweatherman-dev_1.1.0+really1.1.0-2_all.deb ... 191s Unpacking golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 191s Selecting previously unselected package golang-github-subosito-gotenv-dev. 191s Preparing to unpack .../90-golang-github-subosito-gotenv-dev_1.3.0-1_all.deb ... 191s Unpacking golang-github-subosito-gotenv-dev (1.3.0-1) ... 191s Selecting previously unselected package golang-gopkg-ini.v1-dev. 191s Preparing to unpack .../91-golang-gopkg-ini.v1-dev_1.67.0-1_all.deb ... 191s Unpacking golang-gopkg-ini.v1-dev (1.67.0-1) ... 191s Selecting previously unselected package golang-github-spf13-viper-dev. 191s Preparing to unpack .../92-golang-github-spf13-viper-dev_1.12.0-1_all.deb ... 191s Unpacking golang-github-spf13-viper-dev (1.12.0-1) ... 191s Selecting previously unselected package golang-github-spf13-cobra-dev. 191s Preparing to unpack .../93-golang-github-spf13-cobra-dev_1.8.1-1_all.deb ... 191s Unpacking golang-github-spf13-cobra-dev (1.8.1-1) ... 192s Selecting previously unselected package golang-golang-x-sync-dev. 192s Preparing to unpack .../94-golang-golang-x-sync-dev_0.7.0-1_all.deb ... 192s Unpacking golang-golang-x-sync-dev (0.7.0-1) ... 192s Selecting previously unselected package golang-github-canonical-go-dqlite-dev. 192s Preparing to unpack .../95-golang-github-canonical-go-dqlite-dev_2.0.1-1_all.deb ... 192s Unpacking golang-github-canonical-go-dqlite-dev (2.0.1-1) ... 192s Selecting previously unselected package dh-golang. 192s Preparing to unpack .../96-dh-golang_1.63_all.deb ... 192s Unpacking dh-golang (1.63) ... 192s Setting up golang-github-pelletier-go-toml.v2-dev (2.2.4-1) ... 192s Setting up libuv1-dev:arm64 (1.51.0-2ubuntu1) ... 192s Setting up golang-gopkg-yaml.v2-dev (2.4.3-1) ... 192s Setting up golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 192s Setting up golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 192s Setting up golang-github-stretchr-objx-dev (0.5.2-1) ... 192s Setting up golang-github-pkg-errors-dev (0.9.1-3) ... 192s Setting up libarchive-zip-perl (1.68-1) ... 192s Setting up golang-gopkg-yaml.v3-dev (3.0.1-3) ... 192s Setting up libdebhelper-perl (13.24.2ubuntu1) ... 192s Setting up golang-github-burntsushi-toml-dev (1.4.0-1) ... 192s Setting up golang-golang-x-sys-dev (0.22.0-1) ... 192s Setting up golang-github-kr-fs-dev (0.1.0-2) ... 192s Setting up golang-github-subosito-gotenv-dev (1.3.0-1) ... 192s Setting up golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 192s Setting up m4 (1.4.20-2) ... 192s Setting up binutils-gold-aarch64-linux-gnu (2.44-3) ... 192s Setting up golang-golang-x-term-dev (0.22.0-1) ... 192s Setting up libgomp1:arm64 (15.2.0-7ubuntu1) ... 192s Setting up tzdata-legacy (2025b-5ubuntu1) ... 192s Setting up golang-github-google-renameio-dev (2.0.0-2) ... 192s Setting up golang-github-spf13-pflag-dev (1.0.10-1) ... 192s Setting up golang-github-davecgh-go-spew-dev (1.1.1-3) ... 192s Setting up autotools-dev (20240727.1) ... 192s Setting up libsqlite3-dev:arm64 (3.46.1-8) ... 192s Setting up golang-github-stretchr-testify-dev (1.10.0-1) ... 192s Setting up golang-gopkg-ini.v1-dev (1.67.0-1) ... 192s Setting up libmpc3:arm64 (1.3.1-1build3) ... 192s Setting up golang-golang-x-sync-dev (0.7.0-1) ... 192s Setting up autopoint (0.23.1-2build2) ... 192s Setting up golang-github-magiconair-properties-dev (1.8.7-1) ... 192s Setting up golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 192s Setting up golang-github-rican7-retry-dev (0.3.1-2) ... 192s Setting up autoconf (2.72-3.1ubuntu1) ... 192s Setting up libxxhash-dev:arm64 (0.8.3-2) ... 192s Setting up golang-github-rivo-uniseg-dev (0.4.7-1) ... 192s Setting up libubsan1:arm64 (15.2.0-7ubuntu1) ... 192s Setting up dwz (0.16-2) ... 192s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 192s Setting up golang-golang-x-text-dev (0.22.0-1) ... 192s Setting up libhwasan0:arm64 (15.2.0-7ubuntu1) ... 192s Setting up libasan8:arm64 (15.2.0-7ubuntu1) ... 192s Setting up golang-github-spf13-cast-dev (1.10.0-2) ... 192s Setting up golang-github-fsnotify-fsnotify-dev (1.9.0-1) ... 192s Setting up debugedit (1:5.2-3) ... 192s Setting up golang-golang-x-crypto-dev (1:0.25.0-1) ... 192s Setting up golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 192s Setting up golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 192s Setting up libtsan2:arm64 (15.2.0-7ubuntu1) ... 192s Setting up libdqlite0:arm64 (1.18.2-3) ... 192s Setting up libisl23:arm64 (0.27-1) ... 192s Setting up golang-src (2:1.25~1) ... 192s Setting up libcc1-0:arm64 (15.2.0-7ubuntu1) ... 192s Setting up liblsan0:arm64 (15.2.0-7ubuntu1) ... 192s Setting up libitm1:arm64 (15.2.0-7ubuntu1) ... 192s Setting up automake (1:1.18.1-2) ... 192s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 192s Setting up libfile-stripnondeterminism-perl (1.15.0-1) ... 192s Setting up cpp-15-aarch64-linux-gnu (15.2.0-7ubuntu1) ... 192s Setting up golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 192s Setting up gettext (0.23.1-2build2) ... 192s Setting up libgcc-15-dev:arm64 (15.2.0-7ubuntu1) ... 192s Setting up golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 192s Setting up binutils-gold (2.44-3) ... 192s Setting up golang-github-pkg-sftp-dev (1.13.9-1) ... 192s Setting up intltool-debian (0.35.0+20060710.6) ... 192s Setting up golang-github-peterh-liner-dev (1.2.2-1) ... 192s Setting up golang-github-mattn-go-sqlite3-dev:arm64 (1.14.32~ds1-1) ... 192s Setting up go-dqlite (2.0.1-1) ... 192s Setting up cpp-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 192s Setting up libstdc++-15-dev:arm64 (15.2.0-7ubuntu1) ... 192s Setting up liblz4-dev:arm64 (1.10.0-4build1) ... 192s Setting up dh-strip-nondeterminism (1.15.0-1) ... 192s Setting up cpp-15 (15.2.0-7ubuntu1) ... 192s Setting up cpp (4:15.2.0-4ubuntu1) ... 192s Setting up golang-github-spf13-afero-dev (1.15.0-1) ... 192s Setting up libdqlite-dev:arm64 (1.18.2-3) ... 192s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 192s Setting up gcc-15-aarch64-linux-gnu (15.2.0-7ubuntu1) ... 192s Setting up golang-github-cpuguy83-go-md2man-v2-dev (2.0.4-1) ... 192s Setting up golang-go:arm64 (2:1.25~1) ... 192s Setting up po-debconf (1.0.21+nmu1) ... 192s Setting up golang-any:arm64 (2:1.25~1) ... 192s Setting up gcc-15 (15.2.0-7ubuntu1) ... 192s Setting up g++-15-aarch64-linux-gnu (15.2.0-7ubuntu1) ... 192s Setting up golang-github-spf13-viper-dev (1.12.0-1) ... 192s Setting up g++-15 (15.2.0-7ubuntu1) ... 192s Setting up gcc-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 192s Setting up libtool (2.5.4-4build1) ... 192s Setting up gcc (4:15.2.0-4ubuntu1) ... 192s Setting up dh-autoreconf (21) ... 192s Setting up golang-github-spf13-cobra-dev (1.8.1-1) ... 192s Setting up g++-aarch64-linux-gnu (4:15.2.0-4ubuntu1) ... 192s Setting up debhelper (13.24.2ubuntu1) ... 192s Setting up golang-github-canonical-go-dqlite-dev (2.0.1-1) ... 192s Setting up g++ (4:15.2.0-4ubuntu1) ... 192s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 192s Setting up build-essential (12.12ubuntu1) ... 192s Setting up dh-golang (1.63) ... 192s Processing triggers for man-db (2.13.1-1) ... 193s Processing triggers for install-info (7.2-5) ... 193s Processing triggers for libc-bin (2.42-0ubuntu3) ... 195s autopkgtest [04:22:15]: test dh-golang-autopkgtest: /usr/bin/dh_golang_autopkgtest 195s autopkgtest [04:22:15]: test dh-golang-autopkgtest: [----------------------- 195s [info] Testing github.com/canonical/go-dqlite... 195s [info] Source code installed by binary package, overriding dh_auto_configure... 195s dh build --builddirectory=_build --buildsystem=golang --with=golang 195s dh_update_autotools_config -O--builddirectory=_build -O--buildsystem=golang 195s dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang 196s debian/rules override_dh_auto_configure 196s make[1]: Entering directory '/tmp/autopkgtest.hMGXfC/autopkgtest_tmp' 196s mkdir -p "_build" 196s # remove existing symlinks, since "cp -a" won't convert them 196s find "_build" -type l -exec rm "{}" \; 196s cp -a /usr/share/gocode/src "_build" 196s make[1]: Leaving directory '/tmp/autopkgtest.hMGXfC/autopkgtest_tmp' 196s dh_auto_build -O--builddirectory=_build -O--buildsystem=golang 197s 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 197s internal/goarch 197s internal/unsafeheader 197s internal/cpu 197s internal/abi 197s internal/bytealg 197s internal/byteorder 197s internal/chacha8rand 197s internal/coverage/rtcov 197s internal/godebugs 197s internal/goexperiment 197s internal/goos 197s internal/profilerecord 197s internal/runtime/atomic 197s internal/runtime/math 197s internal/runtime/strconv 197s internal/runtime/syscall 197s internal/runtime/exithook 197s internal/runtime/gc 197s internal/runtime/cgroup 197s internal/asan 197s internal/msan 197s internal/race 197s internal/runtime/sys 197s internal/stringslite 197s internal/trace/tracev2 197s internal/runtime/maps 197s sync/atomic 197s internal/sync 197s internal/synctest 197s math/bits 197s internal/itoa 197s cmp 197s runtime 197s math 198s unicode/utf8 198s unicode 198s container/list 198s crypto/internal/fips140/alias 198s crypto/internal/fips140deps/byteorder 198s crypto/internal/fips140deps/cpu 198s crypto/internal/fips140/subtle 198s crypto/internal/boring/sig 198s unicode/utf16 198s vendor/golang.org/x/crypto/cryptobyte/asn1 198s vendor/golang.org/x/crypto/internal/alias 198s internal/nettrace 198s encoding 198s log/internal 198s container/ring 198s github.com/rivo/uniseg 202s internal/reflectlite 202s sync 202s errors 202s internal/oserror 202s iter 202s internal/bisect 202s slices 202s syscall 202s internal/godebug 202s strconv 203s reflect 203s time 204s context 204s io 204s path 204s io/fs 204s internal/filepathlite 204s internal/syscall/unix 205s internal/fmtsort 205s internal/poll 205s internal/syscall/execenv 205s internal/testlog 205s bytes 205s hash 205s crypto 205s os 205s crypto/internal/fips140deps/godebug 205s strings 205s crypto/internal/fips140 205s crypto/internal/impl 205s crypto/internal/fips140/sha256 205s crypto/internal/fips140/sha3 206s crypto/internal/fips140/sha512 206s crypto/internal/fips140/hmac 206s crypto/internal/fips140/check 206s crypto/internal/fips140/aes 206s fmt 206s crypto/internal/sysrand 206s crypto/internal/entropy 206s math/rand/v2 206s crypto/internal/randutil 206s crypto/internal/fips140/drbg 206s crypto/internal/fips140/aes/gcm 206s crypto/internal/fips140only 207s crypto/subtle 207s crypto/internal/fips140/nistec/fiat 207s crypto/cipher 207s crypto/internal/boring 207s crypto/aes 207s crypto/des 207s crypto/internal/fips140/edwards25519/field 207s math/rand 207s math/big 207s crypto/internal/fips140/nistec 207s crypto/internal/fips140/ecdh 207s crypto/ecdh 207s crypto/elliptic 208s crypto/internal/boring/bbig 208s crypto/internal/fips140/bigmod 208s weak 208s crypto/internal/fips140cache 208s crypto/sha3 208s crypto/internal/fips140hash 208s crypto/sha512 208s crypto/internal/fips140/ecdsa 208s encoding/asn1 208s crypto/internal/fips140/edwards25519 208s vendor/golang.org/x/crypto/cryptobyte 208s crypto/internal/fips140/ed25519 208s crypto/rand 208s crypto/ed25519 208s crypto/ecdsa 208s crypto/internal/fips140/hkdf 208s crypto/hkdf 209s crypto/hmac 209s crypto/internal/fips140/mlkem 209s crypto/internal/fips140/tls12 209s crypto/internal/fips140/tls13 209s encoding/binary 209s crypto/md5 209s crypto/rc4 209s crypto/internal/fips140/rsa 209s vendor/golang.org/x/crypto/chacha20 209s vendor/golang.org/x/crypto/internal/poly1305 209s crypto/rsa 209s vendor/golang.org/x/crypto/chacha20poly1305 209s crypto/internal/hpke 209s crypto/sha1 209s crypto/sha256 209s crypto/fips140 209s crypto/tls/internal/fips140tls 209s crypto/dsa 209s encoding/hex 210s crypto/x509/pkix 210s encoding/base64 210s maps 210s encoding/pem 210s vendor/golang.org/x/net/dns/dnsmessage 210s internal/singleflight 210s unique 210s net/netip 210s runtime/cgo 210s net/url 211s path/filepath 211s sort 211s database/sql/driver 211s database/sql 213s github.com/Rican7/retry/backoff 213s github.com/Rican7/retry/jitter 213s github.com/Rican7/retry/strategy 213s github.com/Rican7/retry 213s flag 213s net 213s bufio 213s internal/sysinfo 213s runtime/debug 213s runtime/trace 213s testing 213s github.com/canonical/go-dqlite/logging 213s github.com/pkg/errors 213s golang.org/x/sync/semaphore 213s io/ioutil 213s github.com/google/renameio 213s github.com/mattn/go-sqlite3 215s crypto/x509 215s crypto/tls 217s github.com/canonical/go-dqlite/internal/protocol 218s regexp/syntax 218s regexp 218s gopkg.in/yaml.v2 218s github.com/canonical/go-dqlite/internal/bindings 219s github.com/canonical/go-dqlite/client 219s github.com/canonical/go-dqlite/tracing 219s github.com/canonical/go-dqlite/driver 219s golang.org/x/sys/unix 220s github.com/canonical/go-dqlite 220s log 220s encoding/json 220s github.com/canonical/go-dqlite/app 220s text/tabwriter 221s github.com/canonical/go-dqlite/internal/shell 221s github.com/mattn/go-runewidth 221s github.com/canonical/go-dqlite/benchmark 221s os/signal 221s github.com/peterh/liner 221s encoding/csv 221s github.com/spf13/pflag 221s text/template/parse 221s text/template 222s compress/flate 222s github.com/spf13/cobra 222s hash/crc32 222s compress/gzip 222s vendor/golang.org/x/text/transform 222s vendor/golang.org/x/text/unicode/bidi 222s github.com/canonical/go-dqlite/cmd/dqlite 223s github.com/canonical/go-dqlite/cmd/dqlite-benchmark 224s vendor/golang.org/x/text/secure/bidirule 224s vendor/golang.org/x/text/unicode/norm 224s net/textproto 224s vendor/golang.org/x/net/http2/hpack 224s mime 224s vendor/golang.org/x/net/idna 224s vendor/golang.org/x/net/http/httpguts 225s vendor/golang.org/x/net/http/httpproxy 225s mime/quotedprintable 225s mime/multipart 225s net/http/httptrace 225s net/http/internal 225s net/http/internal/ascii 225s net/http/internal/httpcommon 225s net/http 227s github.com/canonical/go-dqlite/cmd/dqlite-demo 228s create-stamp debian/debhelper-build-stamp 229s dh_auto_test -O--builddirectory=_build -O--buildsystem=golang 229s 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 252s === RUN ExampleLastEntryInfo 252s --- PASS: ExampleLastEntryInfo (0.00s) 252s PASS 252s ok github.com/canonical/go-dqlite 0.013s 424s === RUN TestNew_PristineDefault 424s --- PASS: TestNew_PristineDefault (0.06s) 424s === RUN TestNew_PristineJoiner 424s app_test.go:1207: 04:23:11.700 - 2: DEBUG: new connection from 127.0.0.1:59374 424s app_test.go:1207: 04:23:11.702 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.707 - 2: DEBUG: new connection from 127.0.0.1:59386 424s app_test.go:1207: 04:23:11.742 - 2: DEBUG: new connection from 127.0.0.1:59390 424s app_test.go:1207: 04:23:11.745 - 3: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.760 - 3: DEBUG: new connection from 127.0.0.1:34128 424s app_test.go:1207: 04:23:11.760 - 2: DEBUG: new connection from 127.0.0.1:59400 424s app_test.go:1207: 04:23:11.762 - 2: DEBUG: new connection from 127.0.0.1:59414 424s app_test.go:1207: 04:23:11.764 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s --- PASS: TestNew_PristineJoiner (0.13s) 424s === RUN TestNew_JoinerRestart 424s app_test.go:1207: 04:23:11.822 - 4: DEBUG: new connection from 127.0.0.1:59416 424s app_test.go:1207: 04:23:11.824 - 4: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.826 - 4: DEBUG: new connection from 127.0.0.1:59418 424s app_test.go:1207: 04:23:11.867 - 4: DEBUG: new connection from 127.0.0.1:59422 424s app_test.go:1207: 04:23:11.870 - 5: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.885 - 4: DEBUG: new connection from 127.0.0.1:59428 424s app_test.go:1207: 04:23:11.885 - 5: DEBUG: new connection from 127.0.0.1:34132 424s app_test.go:1207: 04:23:11.910 - 6: DEBUG: new connection from 127.0.0.1:34148 424s app_test.go:1207: 04:23:11.910 - 4: DEBUG: new connection from 127.0.0.1:59434 424s app_test.go:1207: 04:23:11.912 - 6: WARN: attempt 1: server 127.0.0.1:9002: no known leader 424s app_test.go:1207: 04:23:11.913 - 6: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.918 - 4: DEBUG: new connection from 127.0.0.1:59442 424s app_test.go:1207: 04:23:11.918 - 6: DEBUG: new connection from 127.0.0.1:34164 424s --- PASS: TestNew_JoinerRestart (0.15s) 424s === RUN TestNew_SecondJoiner 424s app_test.go:1207: 04:23:11.983 - 7: DEBUG: new connection from 127.0.0.1:59456 424s app_test.go:1207: 04:23:11.985 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.992 - 7: DEBUG: new connection from 127.0.0.1:59472 424s app_test.go:1207: 04:23:11.017 - 7: DEBUG: new connection from 127.0.0.1:59478 424s app_test.go:1207: 04:23:11.019 - 8: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.032 - 8: DEBUG: new connection from 127.0.0.1:34166 424s app_test.go:1207: 04:23:11.034 - 7: DEBUG: new connection from 127.0.0.1:59482 424s app_test.go:1207: 04:23:11.065 - 7: DEBUG: new connection from 127.0.0.1:59498 424s app_test.go:1207: 04:23:11.067 - 9: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.072 - 7: DEBUG: new connection from 127.0.0.1:59504 424s app_test.go:1207: 04:23:11.072 - 8: DEBUG: new connection from 127.0.0.1:34170 424s app_test.go:1207: 04:23:11.074 - 9: DEBUG: new connection from 127.0.0.1:35650 424s app_test.go:1207: 04:23:11.077 - 9: DEBUG: new connection from 127.0.0.1:35662 424s app_test.go:1207: 04:23:11.083 - 7: DEBUG: new connection from 127.0.0.1:59518 424s app_test.go:1207: 04:23:11.961 - 8: DEBUG: new connection from 127.0.0.1:51164 424s app_test.go:1207: 04:23:11.970 - 7: DEBUG: new connection from 127.0.0.1:47080 424s app_test.go:1207: 04:23:11.964 - 7: DEBUG: new connection from 127.0.0.1:47094 424s app_test.go:1207: 04:23:11.967 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s --- PASS: TestNew_SecondJoiner (2.07s) 424s === RUN TestNew_ThirdJoiner 424s app_test.go:1207: 04:23:11.058 - 10: DEBUG: new connection from 127.0.0.1:47098 424s app_test.go:1207: 04:23:11.063 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.072 - 10: DEBUG: new connection from 127.0.0.1:47112 424s app_test.go:1207: 04:23:11.122 - 10: DEBUG: new connection from 127.0.0.1:47114 424s app_test.go:1207: 04:23:11.126 - 11: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.140 - 10: DEBUG: new connection from 127.0.0.1:47120 424s app_test.go:1207: 04:23:11.140 - 11: DEBUG: new connection from 127.0.0.1:51170 424s app_test.go:1207: 04:23:11.189 - 10: DEBUG: new connection from 127.0.0.1:47130 424s app_test.go:1207: 04:23:11.193 - 12: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.200 - 10: DEBUG: new connection from 127.0.0.1:47142 424s app_test.go:1207: 04:23:11.201 - 11: DEBUG: new connection from 127.0.0.1:51178 424s app_test.go:1207: 04:23:11.202 - 12: DEBUG: new connection from 127.0.0.1:56986 424s app_test.go:1207: 04:23:11.206 - 12: DEBUG: new connection from 127.0.0.1:56988 424s app_test.go:1207: 04:23:11.213 - 10: DEBUG: new connection from 127.0.0.1:47144 424s app_test.go:1207: 04:23:11.034 - 11: DEBUG: new connection from 127.0.0.1:51194 424s app_test.go:1207: 04:23:11.041 - 10: DEBUG: new connection from 127.0.0.1:47160 424s app_test.go:1207: 04:23:11.068 - 10: DEBUG: new connection from 127.0.0.1:47176 424s app_test.go:1207: 04:23:11.071 - 13: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.079 - 11: DEBUG: new connection from 127.0.0.1:51202 424s app_test.go:1207: 04:23:11.079 - 10: DEBUG: new connection from 127.0.0.1:47188 424s app_test.go:1207: 04:23:11.081 - 12: DEBUG: new connection from 127.0.0.1:57002 424s app_test.go:1207: 04:23:11.082 - 13: DEBUG: new connection from 127.0.0.1:58660 424s app_test.go:1207: 04:23:11.086 - 13: DEBUG: new connection from 127.0.0.1:58664 424s app_test.go:1207: 04:23:11.088 - 10: DEBUG: new connection from 127.0.0.1:47190 424s app_test.go:1207: 04:23:11.092 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s --- PASS: TestNew_ThirdJoiner (2.14s) 424s === RUN TestNew_FourthJoiner 424s app_test.go:1207: 04:23:11.176 - 14: DEBUG: new connection from 127.0.0.1:47202 424s app_test.go:1207: 04:23:11.179 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.183 - 14: DEBUG: new connection from 127.0.0.1:47216 424s app_test.go:1207: 04:23:11.223 - 14: DEBUG: new connection from 127.0.0.1:47232 424s app_test.go:1207: 04:23:11.227 - 15: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.242 - 15: DEBUG: new connection from 127.0.0.1:51214 424s app_test.go:1207: 04:23:11.242 - 14: DEBUG: new connection from 127.0.0.1:47234 424s app_test.go:1207: 04:23:11.283 - 14: DEBUG: new connection from 127.0.0.1:47246 424s app_test.go:1207: 04:23:11.285 - 16: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.295 - 14: DEBUG: new connection from 127.0.0.1:47262 424s app_test.go:1207: 04:23:11.295 - 15: DEBUG: new connection from 127.0.0.1:51228 424s app_test.go:1207: 04:23:11.297 - 16: DEBUG: new connection from 127.0.0.1:57018 424s app_test.go:1207: 04:23:11.301 - 16: DEBUG: new connection from 127.0.0.1:57034 424s app_test.go:1207: 04:23:11.314 - 14: DEBUG: new connection from 127.0.0.1:47266 424s app_test.go:1207: 04:23:11.160 - 15: DEBUG: new connection from 127.0.0.1:51232 424s app_test.go:1207: 04:23:11.168 - 14: DEBUG: new connection from 127.0.0.1:47280 424s app_test.go:1207: 04:23:11.203 - 14: DEBUG: new connection from 127.0.0.1:47288 424s app_test.go:1207: 04:23:11.206 - 17: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.214 - 14: DEBUG: new connection from 127.0.0.1:47292 424s app_test.go:1207: 04:23:11.215 - 15: DEBUG: new connection from 127.0.0.1:51246 424s app_test.go:1207: 04:23:11.217 - 16: DEBUG: new connection from 127.0.0.1:57036 424s app_test.go:1207: 04:23:11.221 - 17: DEBUG: new connection from 127.0.0.1:58676 424s app_test.go:1207: 04:23:11.225 - 17: DEBUG: new connection from 127.0.0.1:58686 424s app_test.go:1207: 04:23:11.243 - 14: DEBUG: new connection from 127.0.0.1:47306 424s app_test.go:1207: 04:23:11.267 - 14: DEBUG: new connection from 127.0.0.1:47312 424s app_test.go:1207: 04:23:11.271 - 18: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.280 - 14: DEBUG: new connection from 127.0.0.1:47324 424s app_test.go:1207: 04:23:11.280 - 15: DEBUG: new connection from 127.0.0.1:51254 424s app_test.go:1207: 04:23:11.282 - 16: DEBUG: new connection from 127.0.0.1:57040 424s app_test.go:1207: 04:23:11.284 - 17: DEBUG: new connection from 127.0.0.1:58696 424s app_test.go:1207: 04:23:11.286 - 18: DEBUG: new connection from 127.0.0.1:54918 424s app_test.go:1207: 04:23:11.291 - 18: DEBUG: new connection from 127.0.0.1:54934 424s app_test.go:1207: 04:23:11.293 - 14: DEBUG: new connection from 127.0.0.1:47334 424s app_test.go:1207: 04:23:11.298 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s --- PASS: TestNew_FourthJoiner (2.20s) 424s === RUN TestNew_FifthJoiner 424s app_test.go:1207: 04:23:11.391 - 19: DEBUG: new connection from 127.0.0.1:47340 424s app_test.go:1207: 04:23:11.394 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.402 - 19: DEBUG: new connection from 127.0.0.1:47356 424s app_test.go:1207: 04:23:11.439 - 19: DEBUG: new connection from 127.0.0.1:47362 424s app_test.go:1207: 04:23:11.442 - 20: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.458 - 20: DEBUG: new connection from 127.0.0.1:51262 424s app_test.go:1207: 04:23:11.458 - 19: DEBUG: new connection from 127.0.0.1:47370 424s app_test.go:1207: 04:23:11.494 - 19: DEBUG: new connection from 127.0.0.1:47382 424s app_test.go:1207: 04:23:11.498 - 21: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.505 - 19: DEBUG: new connection from 127.0.0.1:47388 424s app_test.go:1207: 04:23:11.505 - 20: DEBUG: new connection from 127.0.0.1:51266 424s app_test.go:1207: 04:23:11.509 - 21: DEBUG: new connection from 127.0.0.1:57042 424s app_test.go:1207: 04:23:11.512 - 21: DEBUG: new connection from 127.0.0.1:57052 424s app_test.go:1207: 04:23:11.521 - 19: DEBUG: new connection from 127.0.0.1:47404 424s app_test.go:1207: 04:23:11.361 - 20: DEBUG: new connection from 127.0.0.1:51276 424s app_test.go:1207: 04:23:11.369 - 19: DEBUG: new connection from 127.0.0.1:47406 424s app_test.go:1207: 04:23:11.389 - 19: DEBUG: new connection from 127.0.0.1:47416 424s app_test.go:1207: 04:23:11.391 - 22: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.397 - 20: DEBUG: new connection from 127.0.0.1:51288 424s app_test.go:1207: 04:23:11.397 - 19: DEBUG: new connection from 127.0.0.1:47422 424s app_test.go:1207: 04:23:11.398 - 21: DEBUG: new connection from 127.0.0.1:57054 424s app_test.go:1207: 04:23:11.399 - 22: DEBUG: new connection from 127.0.0.1:58702 424s app_test.go:1207: 04:23:11.401 - 22: DEBUG: new connection from 127.0.0.1:58704 424s app_test.go:1207: 04:23:11.418 - 19: DEBUG: new connection from 127.0.0.1:47430 424s app_test.go:1207: 04:23:11.436 - 19: DEBUG: new connection from 127.0.0.1:47446 424s app_test.go:1207: 04:23:11.438 - 23: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.444 - 20: DEBUG: new connection from 127.0.0.1:51290 424s app_test.go:1207: 04:23:11.444 - 19: DEBUG: new connection from 127.0.0.1:47460 424s app_test.go:1207: 04:23:11.446 - 21: DEBUG: new connection from 127.0.0.1:57064 424s app_test.go:1207: 04:23:11.447 - 22: DEBUG: new connection from 127.0.0.1:58714 424s app_test.go:1207: 04:23:11.448 - 23: DEBUG: new connection from 127.0.0.1:54944 424s app_test.go:1207: 04:23:11.451 - 23: DEBUG: new connection from 127.0.0.1:54950 424s app_test.go:1207: 04:23:11.475 - 19: DEBUG: new connection from 127.0.0.1:47468 424s app_test.go:1207: 04:23:11.492 - 19: DEBUG: new connection from 127.0.0.1:47472 424s app_test.go:1207: 04:23:11.494 - 24: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.505 - 20: DEBUG: new connection from 127.0.0.1:51298 424s app_test.go:1207: 04:23:11.506 - 19: DEBUG: new connection from 127.0.0.1:47480 424s app_test.go:1207: 04:23:11.507 - 21: DEBUG: new connection from 127.0.0.1:57072 424s app_test.go:1207: 04:23:11.508 - 22: DEBUG: new connection from 127.0.0.1:58722 424s app_test.go:1207: 04:23:11.510 - 23: DEBUG: new connection from 127.0.0.1:54960 424s app_test.go:1207: 04:23:11.510 - 24: DEBUG: new connection from 127.0.0.1:58904 424s app_test.go:1207: 04:23:11.514 - 24: DEBUG: new connection from 127.0.0.1:58910 424s app_test.go:1207: 04:23:11.515 - 19: DEBUG: new connection from 127.0.0.1:47496 424s app_test.go:1207: 04:23:11.518 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s --- PASS: TestNew_FifthJoiner (2.22s) 424s === RUN TestNew_SixthJoiner 424s app_test.go:1207: 04:23:11.603 - 25: DEBUG: new connection from 127.0.0.1:47498 424s app_test.go:1207: 04:23:11.605 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.609 - 25: DEBUG: new connection from 127.0.0.1:47502 424s app_test.go:1207: 04:23:11.646 - 25: DEBUG: new connection from 127.0.0.1:47504 424s app_test.go:1207: 04:23:11.648 - 26: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.658 - 26: DEBUG: new connection from 127.0.0.1:51312 424s app_test.go:1207: 04:23:11.659 - 25: DEBUG: new connection from 127.0.0.1:47514 424s app_test.go:1207: 04:23:11.687 - 25: DEBUG: new connection from 127.0.0.1:47520 424s app_test.go:1207: 04:23:11.689 - 27: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.694 - 25: DEBUG: new connection from 127.0.0.1:47530 424s app_test.go:1207: 04:23:11.695 - 26: DEBUG: new connection from 127.0.0.1:51324 424s app_test.go:1207: 04:23:11.697 - 27: DEBUG: new connection from 127.0.0.1:57086 424s app_test.go:1207: 04:23:11.700 - 27: DEBUG: new connection from 127.0.0.1:57096 424s app_test.go:1207: 04:23:11.706 - 25: DEBUG: new connection from 127.0.0.1:47538 424s app_test.go:1207: 04:23:11.573 - 26: DEBUG: new connection from 127.0.0.1:51340 424s app_test.go:1207: 04:23:11.583 - 25: DEBUG: new connection from 127.0.0.1:47540 424s app_test.go:1207: 04:23:11.630 - 25: DEBUG: new connection from 127.0.0.1:55206 424s app_test.go:1207: 04:23:11.632 - 28: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.638 - 25: DEBUG: new connection from 127.0.0.1:55216 424s app_test.go:1207: 04:23:11.638 - 26: DEBUG: new connection from 127.0.0.1:59338 424s app_test.go:1207: 04:23:11.640 - 27: DEBUG: new connection from 127.0.0.1:35270 424s app_test.go:1207: 04:23:11.643 - 28: DEBUG: new connection from 127.0.0.1:38082 424s app_test.go:1207: 04:23:11.645 - 28: DEBUG: new connection from 127.0.0.1:38084 424s app_test.go:1207: 04:23:11.664 - 25: DEBUG: new connection from 127.0.0.1:55228 424s app_test.go:1207: 04:23:11.675 - 25: DEBUG: new connection from 127.0.0.1:55230 424s app_test.go:1207: 04:23:11.677 - 29: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.683 - 26: DEBUG: new connection from 127.0.0.1:59354 424s app_test.go:1207: 04:23:11.683 - 25: DEBUG: new connection from 127.0.0.1:55246 424s app_test.go:1207: 04:23:11.684 - 27: DEBUG: new connection from 127.0.0.1:35282 424s app_test.go:1207: 04:23:11.685 - 28: DEBUG: new connection from 127.0.0.1:38086 424s app_test.go:1207: 04:23:11.687 - 29: DEBUG: new connection from 127.0.0.1:35512 424s app_test.go:1207: 04:23:11.690 - 29: DEBUG: new connection from 127.0.0.1:35528 424s app_test.go:1207: 04:23:11.704 - 25: DEBUG: new connection from 127.0.0.1:55248 424s app_test.go:1207: 04:23:11.724 - 25: DEBUG: new connection from 127.0.0.1:55250 424s app_test.go:1207: 04:23:11.727 - 30: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.732 - 25: DEBUG: new connection from 127.0.0.1:55264 424s app_test.go:1207: 04:23:11.732 - 26: DEBUG: new connection from 127.0.0.1:59356 424s app_test.go:1207: 04:23:11.734 - 27: DEBUG: new connection from 127.0.0.1:35284 424s app_test.go:1207: 04:23:11.737 - 29: DEBUG: new connection from 127.0.0.1:35542 424s app_test.go:1207: 04:23:11.738 - 28: DEBUG: new connection from 127.0.0.1:38094 424s app_test.go:1207: 04:23:11.740 - 30: DEBUG: new connection from 127.0.0.1:59934 424s app_test.go:1207: 04:23:11.743 - 30: DEBUG: new connection from 127.0.0.1:59948 424s app_test.go:1207: 04:23:11.762 - 25: DEBUG: new connection from 127.0.0.1:55278 424s app_test.go:1207: 04:23:11.771 - 25: DEBUG: new connection from 127.0.0.1:55282 424s app_test.go:1207: 04:23:11.773 - 31: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.780 - 25: DEBUG: new connection from 127.0.0.1:55286 424s app_test.go:1207: 04:23:11.780 - 26: DEBUG: new connection from 127.0.0.1:59370 424s app_test.go:1207: 04:23:11.781 - 27: DEBUG: new connection from 127.0.0.1:35296 424s app_test.go:1207: 04:23:11.783 - 28: DEBUG: new connection from 127.0.0.1:38100 424s app_test.go:1207: 04:23:11.784 - 29: DEBUG: new connection from 127.0.0.1:35554 424s app_test.go:1207: 04:23:11.785 - 30: DEBUG: new connection from 127.0.0.1:59952 424s app_test.go:1207: 04:23:11.786 - 31: DEBUG: new connection from 127.0.0.1:42894 424s app_test.go:1207: 04:23:11.790 - 25: DEBUG: new connection from 127.0.0.1:55300 424s app_test.go:1207: 04:23:11.792 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s --- PASS: TestNew_SixthJoiner (2.27s) 424s === RUN TestHandover_Voter 424s app_test.go:1207: 04:23:11.874 - 32: DEBUG: new connection from 127.0.0.1:55302 424s app_test.go:1207: 04:23:11.876 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.880 - 32: DEBUG: new connection from 127.0.0.1:55316 424s app_test.go:1207: 04:23:11.910 - 32: DEBUG: new connection from 127.0.0.1:55330 424s app_test.go:1207: 04:23:11.912 - 33: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.925 - 32: DEBUG: new connection from 127.0.0.1:55332 424s app_test.go:1207: 04:23:11.927 - 33: DEBUG: new connection from 127.0.0.1:59378 424s app_test.go:1207: 04:23:11.971 - 32: DEBUG: new connection from 127.0.0.1:55338 424s app_test.go:1207: 04:23:11.973 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.979 - 33: DEBUG: new connection from 127.0.0.1:59380 424s app_test.go:1207: 04:23:11.979 - 32: DEBUG: new connection from 127.0.0.1:55340 424s app_test.go:1207: 04:23:11.981 - 34: DEBUG: new connection from 127.0.0.1:35302 424s app_test.go:1207: 04:23:11.983 - 34: DEBUG: new connection from 127.0.0.1:35308 424s app_test.go:1207: 04:23:11.990 - 32: DEBUG: new connection from 127.0.0.1:55352 424s app_test.go:1207: 04:23:11.844 - 33: DEBUG: new connection from 127.0.0.1:59390 424s app_test.go:1207: 04:23:11.848 - 32: DEBUG: new connection from 127.0.0.1:55360 424s app_test.go:1207: 04:23:11.878 - 32: DEBUG: new connection from 127.0.0.1:55376 424s app_test.go:1207: 04:23:11.881 - 35: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.889 - 33: DEBUG: new connection from 127.0.0.1:59400 424s app_test.go:1207: 04:23:11.889 - 32: DEBUG: new connection from 127.0.0.1:55386 424s app_test.go:1207: 04:23:11.892 - 34: DEBUG: new connection from 127.0.0.1:35312 424s app_test.go:1207: 04:23:11.894 - 35: DEBUG: new connection from 127.0.0.1:38114 424s app_test.go:1207: 04:23:11.898 - 35: DEBUG: new connection from 127.0.0.1:38126 424s app_test.go:1207: 04:23:11.901 - 32: DEBUG: new connection from 127.0.0.1:55390 424s app_test.go:1207: 04:23:11.903 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.903 - 32: DEBUG: new connection from 127.0.0.1:55400 424s app_test.go:1207: 04:23:11.904 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.905 - 32: DEBUG: new connection from 127.0.0.1:55414 424s app_test.go:1207: 04:23:11.906 - 33: DEBUG: new connection from 127.0.0.1:59416 424s app_test.go:1207: 04:23:11.908 - 32: DEBUG: new connection from 127.0.0.1:55426 424s app_test.go:1207: 04:23:11.910 - 34: DEBUG: new connection from 127.0.0.1:35324 424s app_test.go:1207: 04:23:11.910 - 35: DEBUG: new connection from 127.0.0.1:38134 424s app_test.go:1207: 04:23:11.852 - 34: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 424s --- PASS: TestHandover_Voter (3.09s) 424s === RUN TestHandover_TwoNodes 424s app_test.go:1207: 04:23:11.955 - 36: DEBUG: new connection from 127.0.0.1:55442 424s app_test.go:1207: 04:23:11.957 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.961 - 36: DEBUG: new connection from 127.0.0.1:55456 424s app_test.go:1207: 04:23:11.991 - 36: DEBUG: new connection from 127.0.0.1:55464 424s app_test.go:1207: 04:23:11.993 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.007 - 36: DEBUG: new connection from 127.0.0.1:55474 424s app_test.go:1207: 04:23:11.007 - 37: DEBUG: new connection from 127.0.0.1:59418 424s app_test.go:1207: 04:23:11.011 - 36: DEBUG: new connection from 127.0.0.1:55488 424s app_test.go:1207: 04:23:11.012 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.013 - 37: DEBUG: new connection from 127.0.0.1:59426 424s app_test.go:1207: 04:23:11.013 - 36: DEBUG: new connection from 127.0.0.1:55500 424s app_test.go:1207: 04:23:11.015 - 37: DEBUG: new connection from 127.0.0.1:59432 424s app_test.go:1207: 04:23:11.023 - 36: DEBUG: new connection from 127.0.0.1:55504 424s app_test.go:1207: 04:23:11.940 - 36: DEBUG: promoted 127.0.0.1:9002 from spare to voter 424s app_test.go:1207: 04:23:11.940 - 36: DEBUG: new connection from 127.0.0.1:55506 424s app_test.go:1207: 04:23:11.940 - 37: DEBUG: new connection from 127.0.0.1:59448 424s app_test.go:1207: 04:23:11.949 - 36: DEBUG: new connection from 127.0.0.1:55514 424s app_test.go:1207: 04:23:11.950 - 36: DEBUG: new connection from 127.0.0.1:55528 424s app_test.go:1207: 04:23:11.952 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 424s app_test.go:1207: 04:23:11.952 - 37: DEBUG: new connection from 127.0.0.1:59462 424s app_test.go:1207: 04:23:11.954 - 36: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 424s app_test.go:1207: 04:23:11.955 - 36: DEBUG: new connection from 127.0.0.1:55534 424s app_test.go:1207: 04:23:11.955 - 37: DEBUG: new connection from 127.0.0.1:59478 424s app_test.go:1207: 04:23:11.958 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 424s app_test.go:1207: 04:23:11.958 - 37: WARN: attempt 1: server 127.0.0.1:9002: context canceled 424s app_test.go:1207: 04:23:11.958 - 37: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 424s --- PASS: TestHandover_TwoNodes (1.06s) 424s === RUN TestHandover_VoterHonorFailureDomain 424s app_test.go:1207: 04:23:11.016 - 38: DEBUG: new connection from 127.0.0.1:55542 424s app_test.go:1207: 04:23:11.018 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.022 - 38: DEBUG: new connection from 127.0.0.1:55546 424s app_test.go:1207: 04:23:11.067 - 38: DEBUG: new connection from 127.0.0.1:55558 424s app_test.go:1207: 04:23:11.069 - 39: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.082 - 39: DEBUG: new connection from 127.0.0.1:59482 424s app_test.go:1207: 04:23:11.082 - 38: DEBUG: new connection from 127.0.0.1:55568 424s app_test.go:1207: 04:23:11.112 - 38: DEBUG: new connection from 127.0.0.1:55584 424s app_test.go:1207: 04:23:11.114 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.119 - 39: DEBUG: new connection from 127.0.0.1:59492 424s app_test.go:1207: 04:23:11.119 - 38: DEBUG: new connection from 127.0.0.1:55598 424s app_test.go:1207: 04:23:11.122 - 40: DEBUG: new connection from 127.0.0.1:35328 424s app_test.go:1207: 04:23:11.125 - 40: DEBUG: new connection from 127.0.0.1:35334 424s app_test.go:1207: 04:23:11.133 - 38: DEBUG: new connection from 127.0.0.1:55600 424s app_test.go:1207: 04:23:11.004 - 39: DEBUG: new connection from 127.0.0.1:59494 424s app_test.go:1207: 04:23:11.008 - 38: DEBUG: new connection from 127.0.0.1:55610 424s app_test.go:1207: 04:23:11.049 - 38: DEBUG: new connection from 127.0.0.1:55612 424s app_test.go:1207: 04:23:11.053 - 41: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.067 - 38: DEBUG: new connection from 127.0.0.1:55620 424s app_test.go:1207: 04:23:11.068 - 39: DEBUG: new connection from 127.0.0.1:59496 424s app_test.go:1207: 04:23:11.069 - 40: DEBUG: new connection from 127.0.0.1:35348 424s app_test.go:1207: 04:23:11.070 - 41: DEBUG: new connection from 127.0.0.1:38140 424s app_test.go:1207: 04:23:11.073 - 41: DEBUG: new connection from 127.0.0.1:38154 424s app_test.go:1207: 04:23:11.090 - 38: DEBUG: new connection from 127.0.0.1:55634 424s app_test.go:1207: 04:23:11.113 - 38: DEBUG: new connection from 127.0.0.1:55650 424s app_test.go:1207: 04:23:11.116 - 42: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.122 - 39: DEBUG: new connection from 127.0.0.1:59510 424s app_test.go:1207: 04:23:11.122 - 38: DEBUG: new connection from 127.0.0.1:55658 424s app_test.go:1207: 04:23:11.124 - 40: DEBUG: new connection from 127.0.0.1:35358 424s app_test.go:1207: 04:23:11.125 - 41: DEBUG: new connection from 127.0.0.1:38170 424s app_test.go:1207: 04:23:11.127 - 42: DEBUG: new connection from 127.0.0.1:35568 424s app_test.go:1207: 04:23:11.130 - 42: DEBUG: new connection from 127.0.0.1:35580 424s app_test.go:1207: 04:23:11.144 - 38: DEBUG: new connection from 127.0.0.1:55672 424s app_test.go:1207: 04:23:11.170 - 38: DEBUG: new connection from 127.0.0.1:55678 424s app_test.go:1207: 04:23:11.172 - 43: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.178 - 39: DEBUG: new connection from 127.0.0.1:59524 424s app_test.go:1207: 04:23:11.178 - 38: DEBUG: new connection from 127.0.0.1:55692 424s app_test.go:1207: 04:23:11.180 - 40: DEBUG: new connection from 127.0.0.1:35370 424s app_test.go:1207: 04:23:11.182 - 41: DEBUG: new connection from 127.0.0.1:38182 424s app_test.go:1207: 04:23:11.184 - 42: DEBUG: new connection from 127.0.0.1:35594 424s app_test.go:1207: 04:23:11.186 - 43: DEBUG: new connection from 127.0.0.1:59962 424s app_test.go:1207: 04:23:11.188 - 43: DEBUG: new connection from 127.0.0.1:59964 424s app_test.go:1207: 04:23:11.190 - 38: DEBUG: new connection from 127.0.0.1:55696 424s app_test.go:1207: 04:23:11.192 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.193 - 38: DEBUG: new connection from 127.0.0.1:55698 424s app_test.go:1207: 04:23:11.194 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.194 - 38: DEBUG: new connection from 127.0.0.1:55710 424s app_test.go:1207: 04:23:11.195 - 39: DEBUG: new connection from 127.0.0.1:59536 424s app_test.go:1207: 04:23:11.197 - 38: DEBUG: new connection from 127.0.0.1:55724 424s app_test.go:1207: 04:23:11.198 - 40: DEBUG: new connection from 127.0.0.1:35380 424s app_test.go:1207: 04:23:11.201 - 41: DEBUG: new connection from 127.0.0.1:38186 424s app_test.go:1207: 04:23:11.203 - 42: DEBUG: new connection from 127.0.0.1:35606 424s app_test.go:1207: 04:23:11.204 - 43: DEBUG: new connection from 127.0.0.1:59978 424s app_test.go:1207: 04:23:11.007 - 40: DEBUG: promoted 127.0.0.1:9006 from stand-by to voter 424s --- PASS: TestHandover_VoterHonorFailureDomain (3.12s) 424s === RUN TestHandover_SingleNode 424s --- PASS: TestHandover_SingleNode (0.05s) 424s === RUN TestHandover_GracefulShutdown 424s app_test.go:406: 04:23:11.184 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:406: 04:23:11.232 - 1: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:406: 04:23:11.284 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:406: 04:23:11.174 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:406: 04:23:11.178 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:406: 04:23:11.190 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 424s app_test.go:406: 04:23:11.190 - 0: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 424s app_test.go:406: 04:23:11.205 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 424s app_test.go:406: 04:23:11.205 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 424s app_test.go:406: 04:23:11.210 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 424s app_test.go:406: 04:23:11.210 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9003 424s app_test.go:406: 04:23:11.211 - 1: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 424s app_test.go:406: 04:23:11.225 - 2: WARN: attempt 1: server 127.0.0.1:9002: dial: dial tcp 127.0.0.1:9002: connect: connection refused 424s app_test.go:406: 04:23:11.225 - 2: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 424s app_test.go:406: 04:23:11.225 - 2: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 424s --- PASS: TestHandover_GracefulShutdown (2.08s) 424s === RUN TestHandover_StandBy 424s app_test.go:1207: 04:23:11.282 - 44: DEBUG: new connection from 127.0.0.1:56026 424s app_test.go:1207: 04:23:11.285 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.289 - 44: DEBUG: new connection from 127.0.0.1:56040 424s app_test.go:1207: 04:23:11.325 - 44: DEBUG: new connection from 127.0.0.1:56042 424s app_test.go:1207: 04:23:11.328 - 45: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.341 - 44: DEBUG: new connection from 127.0.0.1:56052 424s app_test.go:1207: 04:23:11.341 - 45: DEBUG: new connection from 127.0.0.1:59686 424s app_test.go:1207: 04:23:11.375 - 44: DEBUG: new connection from 127.0.0.1:56054 424s app_test.go:1207: 04:23:11.377 - 46: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.381 - 45: DEBUG: new connection from 127.0.0.1:59694 424s app_test.go:1207: 04:23:11.381 - 44: DEBUG: new connection from 127.0.0.1:56060 424s app_test.go:1207: 04:23:11.382 - 46: DEBUG: new connection from 127.0.0.1:35484 424s app_test.go:1207: 04:23:11.384 - 46: DEBUG: new connection from 127.0.0.1:35492 424s app_test.go:1207: 04:23:11.389 - 44: DEBUG: new connection from 127.0.0.1:56072 424s app_test.go:1207: 04:23:11.263 - 45: DEBUG: new connection from 127.0.0.1:43254 424s app_test.go:1207: 04:23:11.267 - 44: DEBUG: new connection from 127.0.0.1:37668 424s app_test.go:1207: 04:23:11.291 - 44: DEBUG: new connection from 127.0.0.1:37680 424s app_test.go:1207: 04:23:11.293 - 47: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.297 - 44: DEBUG: new connection from 127.0.0.1:37690 424s app_test.go:1207: 04:23:11.298 - 45: DEBUG: new connection from 127.0.0.1:43264 424s app_test.go:1207: 04:23:11.300 - 46: DEBUG: new connection from 127.0.0.1:33778 424s app_test.go:1207: 04:23:11.301 - 47: DEBUG: new connection from 127.0.0.1:58636 424s app_test.go:1207: 04:23:11.304 - 47: DEBUG: new connection from 127.0.0.1:58640 424s app_test.go:1207: 04:23:11.309 - 44: DEBUG: new connection from 127.0.0.1:37698 424s app_test.go:1207: 04:23:11.330 - 44: DEBUG: new connection from 127.0.0.1:37702 424s app_test.go:1207: 04:23:11.332 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.335 - 45: DEBUG: new connection from 127.0.0.1:43272 424s app_test.go:1207: 04:23:11.336 - 44: DEBUG: new connection from 127.0.0.1:37716 424s app_test.go:1207: 04:23:11.337 - 46: DEBUG: new connection from 127.0.0.1:33788 424s app_test.go:1207: 04:23:11.339 - 47: DEBUG: new connection from 127.0.0.1:58642 424s app_test.go:1207: 04:23:11.341 - 48: DEBUG: new connection from 127.0.0.1:48388 424s app_test.go:1207: 04:23:11.343 - 48: DEBUG: new connection from 127.0.0.1:48400 424s app_test.go:1207: 04:23:11.349 - 44: DEBUG: new connection from 127.0.0.1:37722 424s app_test.go:1207: 04:23:11.374 - 44: DEBUG: new connection from 127.0.0.1:37738 424s app_test.go:1207: 04:23:11.376 - 49: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.379 - 45: DEBUG: new connection from 127.0.0.1:43276 424s app_test.go:1207: 04:23:11.380 - 44: DEBUG: new connection from 127.0.0.1:37748 424s app_test.go:1207: 04:23:11.381 - 46: DEBUG: new connection from 127.0.0.1:33790 424s app_test.go:1207: 04:23:11.382 - 47: DEBUG: new connection from 127.0.0.1:58652 424s app_test.go:1207: 04:23:11.385 - 48: DEBUG: new connection from 127.0.0.1:48406 424s app_test.go:1207: 04:23:11.386 - 49: DEBUG: new connection from 127.0.0.1:45062 424s app_test.go:1207: 04:23:11.388 - 49: DEBUG: new connection from 127.0.0.1:45064 424s app_test.go:1207: 04:23:11.397 - 44: DEBUG: new connection from 127.0.0.1:37752 424s app_test.go:1207: 04:23:11.419 - 44: DEBUG: new connection from 127.0.0.1:37762 424s app_test.go:1207: 04:23:11.421 - 50: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.425 - 44: DEBUG: new connection from 127.0.0.1:37774 424s app_test.go:1207: 04:23:11.426 - 45: DEBUG: new connection from 127.0.0.1:43278 424s app_test.go:1207: 04:23:11.426 - 46: DEBUG: new connection from 127.0.0.1:33800 424s app_test.go:1207: 04:23:11.429 - 47: DEBUG: new connection from 127.0.0.1:58662 424s app_test.go:1207: 04:23:11.430 - 48: DEBUG: new connection from 127.0.0.1:48422 424s app_test.go:1207: 04:23:11.432 - 49: DEBUG: new connection from 127.0.0.1:45076 424s app_test.go:1207: 04:23:11.432 - 50: DEBUG: new connection from 127.0.0.1:54848 424s app_test.go:1207: 04:23:11.434 - 44: DEBUG: new connection from 127.0.0.1:37790 424s app_test.go:1207: 04:23:11.436 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.437 - 44: DEBUG: new connection from 127.0.0.1:37806 424s app_test.go:1207: 04:23:11.438 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.439 - 45: DEBUG: new connection from 127.0.0.1:43284 424s app_test.go:1207: 04:23:11.439 - 44: DEBUG: new connection from 127.0.0.1:37808 424s app_test.go:1207: 04:23:11.440 - 46: DEBUG: new connection from 127.0.0.1:33812 424s app_test.go:1207: 04:23:11.441 - 47: DEBUG: new connection from 127.0.0.1:58664 424s app_test.go:1207: 04:23:11.443 - 48: DEBUG: new connection from 127.0.0.1:48424 424s app_test.go:1207: 04:23:11.443 - 49: DEBUG: new connection from 127.0.0.1:45086 424s app_test.go:1207: 04:23:11.446 - 50: DEBUG: new connection from 127.0.0.1:54850 424s app_test.go:1207: 04:23:11.448 - 50: DEBUG: new connection from 127.0.0.1:54862 424s app_test.go:1207: 04:23:11.451 - 48: DEBUG: promoted 127.0.0.1:9007 from spare to stand-by 424s --- PASS: TestHandover_StandBy (2.25s) 424s === RUN TestHandover_TransferLeadership 424s app_test.go:1207: 04:23:11.522 - 51: DEBUG: new connection from 127.0.0.1:37824 424s app_test.go:1207: 04:23:11.524 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.527 - 51: DEBUG: new connection from 127.0.0.1:37840 424s app_test.go:1207: 04:23:11.552 - 51: DEBUG: new connection from 127.0.0.1:37856 424s app_test.go:1207: 04:23:11.554 - 52: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.562 - 51: DEBUG: new connection from 127.0.0.1:37858 424s app_test.go:1207: 04:23:11.563 - 52: DEBUG: new connection from 127.0.0.1:43294 424s app_test.go:1207: 04:23:11.602 - 51: DEBUG: new connection from 127.0.0.1:37872 424s app_test.go:1207: 04:23:11.604 - 53: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.607 - 52: DEBUG: new connection from 127.0.0.1:43296 424s app_test.go:1207: 04:23:11.608 - 51: DEBUG: new connection from 127.0.0.1:37886 424s app_test.go:1207: 04:23:11.610 - 53: DEBUG: new connection from 127.0.0.1:33824 424s app_test.go:1207: 04:23:11.614 - 53: DEBUG: new connection from 127.0.0.1:33830 424s app_test.go:1207: 04:23:11.618 - 51: DEBUG: new connection from 127.0.0.1:37900 424s app_test.go:1207: 04:23:11.506 - 52: DEBUG: new connection from 127.0.0.1:43302 424s app_test.go:1207: 04:23:11.510 - 51: DEBUG: new connection from 127.0.0.1:37912 424s app_test.go:1207: 04:23:11.547 - 51: DEBUG: new connection from 127.0.0.1:37916 424s app_test.go:1207: 04:23:11.549 - 54: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.553 - 51: DEBUG: new connection from 127.0.0.1:37928 424s app_test.go:1207: 04:23:11.554 - 52: DEBUG: new connection from 127.0.0.1:43314 424s app_test.go:1207: 04:23:11.555 - 53: DEBUG: new connection from 127.0.0.1:33838 424s app_test.go:1207: 04:23:11.556 - 54: DEBUG: new connection from 127.0.0.1:58668 424s app_test.go:1207: 04:23:11.558 - 54: DEBUG: new connection from 127.0.0.1:58682 424s app_test.go:1207: 04:23:11.561 - 51: DEBUG: new connection from 127.0.0.1:37940 424s app_test.go:1207: 04:23:11.564 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.564 - 51: DEBUG: new connection from 127.0.0.1:37950 424s app_test.go:1207: 04:23:11.565 - 51: DEBUG: new connection from 127.0.0.1:37964 424s app_test.go:1207: 04:23:11.566 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.568 - 51: DEBUG: new connection from 127.0.0.1:37968 424s app_test.go:1207: 04:23:11.568 - 52: DEBUG: new connection from 127.0.0.1:43326 424s app_test.go:1207: 04:23:11.571 - 53: DEBUG: new connection from 127.0.0.1:33850 424s app_test.go:1207: 04:23:11.573 - 54: DEBUG: new connection from 127.0.0.1:58692 424s app_test.go:1207: 04:23:11.511 - 51: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 424s app_test.go:1207: 04:23:11.511 - 52: DEBUG: new connection from 127.0.0.1:43332 424s app_test.go:1207: 04:23:11.512 - 51: DEBUG: new connection from 127.0.0.1:37970 424s app_test.go:1207: 04:23:11.514 - 53: DEBUG: new connection from 127.0.0.1:33860 424s app_test.go:1207: 04:23:11.514 - 54: DEBUG: new connection from 127.0.0.1:58700 424s app_test.go:1207: 04:23:11.520 - 53: DEBUG: new connection from 127.0.0.1:33874 424s app_test.go:1207: 04:23:11.522 - 54: DEBUG: new connection from 127.0.0.1:58716 424s app_test.go:1207: 04:23:11.526 - 52: DEBUG: new connection from 127.0.0.1:43344 424s app_test.go:1207: 04:23:11.528 - 52: DEBUG: new connection from 127.0.0.1:43352 424s app_test.go:1207: 04:23:11.530 - 51: DEBUG: new connection from 127.0.0.1:37984 424s app_test.go:1207: 04:23:11.532 - 51: DEBUG: new connection from 127.0.0.1:37990 424s app_test.go:1207: 04:23:11.533 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 424s app_test.go:1207: 04:23:11.533 - 52: DEBUG: new connection from 127.0.0.1:43358 424s app_test.go:1207: 04:23:11.535 - 51: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 424s app_test.go:1207: 04:23:11.536 - 51: DEBUG: new connection from 127.0.0.1:38004 424s app_test.go:1207: 04:23:11.539 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 424s app_test.go:1207: 04:23:11.539 - 52: DEBUG: new connection from 127.0.0.1:43372 424s app_test.go:1207: 04:23:11.542 - 51: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 424s --- PASS: TestHandover_TransferLeadership (3.09s) 424s === RUN TestRolesAdjustment_ReplaceVoter 424s app_test.go:1207: 04:23:11.621 - 55: DEBUG: new connection from 127.0.0.1:38006 424s app_test.go:1207: 04:23:11.623 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.625 - 55: DEBUG: new connection from 127.0.0.1:38016 424s app_test.go:1207: 04:23:11.648 - 55: DEBUG: new connection from 127.0.0.1:38020 424s app_test.go:1207: 04:23:11.651 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.660 - 56: DEBUG: new connection from 127.0.0.1:43378 424s app_test.go:1207: 04:23:11.660 - 55: DEBUG: new connection from 127.0.0.1:38022 424s app_test.go:1207: 04:23:11.702 - 55: DEBUG: new connection from 127.0.0.1:38038 424s app_test.go:1207: 04:23:11.704 - 57: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.707 - 55: DEBUG: new connection from 127.0.0.1:38050 424s app_test.go:1207: 04:23:11.707 - 56: DEBUG: new connection from 127.0.0.1:43394 424s app_test.go:1207: 04:23:11.709 - 57: DEBUG: new connection from 127.0.0.1:33880 424s app_test.go:1207: 04:23:11.711 - 57: DEBUG: new connection from 127.0.0.1:33884 424s app_test.go:1207: 04:23:11.716 - 55: DEBUG: new connection from 127.0.0.1:38062 424s app_test.go:1207: 04:23:11.603 - 56: DEBUG: new connection from 127.0.0.1:43402 424s app_test.go:1207: 04:23:11.608 - 55: DEBUG: new connection from 127.0.0.1:38078 424s app_test.go:1207: 04:23:11.622 - 55: DEBUG: new connection from 127.0.0.1:38094 424s app_test.go:1207: 04:23:11.624 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.626 - 55: DEBUG: new connection from 127.0.0.1:38102 424s app_test.go:1207: 04:23:11.629 - 56: DEBUG: new connection from 127.0.0.1:43406 424s app_test.go:1207: 04:23:11.629 - 55: DEBUG: new connection from 127.0.0.1:38114 424s app_test.go:1207: 04:23:11.630 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.631 - 57: DEBUG: new connection from 127.0.0.1:33896 424s app_test.go:1207: 04:23:11.632 - 58: DEBUG: new connection from 127.0.0.1:58730 424s app_test.go:1207: 04:23:11.634 - 58: DEBUG: new connection from 127.0.0.1:58740 424s app_test.go:1207: 04:23:11.635 - 55: DEBUG: new connection from 127.0.0.1:38130 424s app_test.go:1207: 04:23:11.636 - 56: DEBUG: new connection from 127.0.0.1:43408 424s app_test.go:1207: 04:23:11.641 - 58: DEBUG: new connection from 127.0.0.1:58754 424s app_test.go:1207: 04:23:11.643 - 55: DEBUG: new connection from 127.0.0.1:38132 424s app_test.go:1207: 04:23:11.663 - 55: DEBUG: new connection from 127.0.0.1:38136 424s app_test.go:1207: 04:23:11.664 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.602 - 55: DEBUG: new connection from 127.0.0.1:38148 424s app_test.go:1207: 04:23:11.602 - 56: DEBUG: new connection from 127.0.0.1:43412 424s app_test.go:1207: 04:23:11.605 - 58: DEBUG: new connection from 127.0.0.1:58756 424s app_test.go:1207: 04:23:11.609 - 55: DEBUG: new connection from 127.0.0.1:38162 424s app_test.go:1207: 04:23:11.609 - 56: DEBUG: new connection from 127.0.0.1:43418 424s app_test.go:1207: 04:23:11.613 - 58: DEBUG: new connection from 127.0.0.1:58758 424s app_test.go:1207: 04:23:11.639 - 55: DEBUG: new connection from 127.0.0.1:38166 424s app_test.go:1207: 04:23:11.641 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.668 - 55: DEBUG: new connection from 127.0.0.1:38178 424s app_test.go:1207: 04:23:11.669 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.616 - 55: DEBUG: new connection from 127.0.0.1:59980 424s app_test.go:1207: 04:23:11.618 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.624 - 55: DEBUG: new connection from 127.0.0.1:59996 424s app_test.go:1207: 04:23:11.624 - 56: DEBUG: new connection from 127.0.0.1:53208 424s app_test.go:1207: 04:23:11.626 - 58: DEBUG: new connection from 127.0.0.1:37616 424s app_test.go:1207: 04:23:11.645 - 55: DEBUG: new connection from 127.0.0.1:60002 424s app_test.go:1207: 04:23:11.647 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.673 - 55: DEBUG: new connection from 127.0.0.1:60004 424s app_test.go:1207: 04:23:11.674 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.629 - 55: DEBUG: new connection from 127.0.0.1:60016 424s app_test.go:1207: 04:23:11.632 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.636 - 55: DEBUG: new connection from 127.0.0.1:60026 424s app_test.go:1207: 04:23:11.636 - 56: DEBUG: new connection from 127.0.0.1:53214 424s app_test.go:1207: 04:23:11.638 - 58: DEBUG: new connection from 127.0.0.1:37618 424s app_test.go:1207: 04:23:11.654 - 55: DEBUG: new connection from 127.0.0.1:60036 424s app_test.go:1207: 04:23:11.657 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.679 - 55: DEBUG: new connection from 127.0.0.1:60042 424s app_test.go:1207: 04:23:11.680 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.640 - 55: DEBUG: new connection from 127.0.0.1:60050 424s app_test.go:1207: 04:23:11.642 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.647 - 55: DEBUG: new connection from 127.0.0.1:60064 424s app_test.go:1207: 04:23:11.647 - 56: DEBUG: new connection from 127.0.0.1:53224 424s app_test.go:1207: 04:23:11.649 - 58: DEBUG: new connection from 127.0.0.1:37624 424s app_test.go:1207: 04:23:11.652 - 58: DEBUG: new connection from 127.0.0.1:37632 424s app_test.go:1207: 04:23:11.653 - 55: DEBUG: new connection from 127.0.0.1:60078 424s app_test.go:1207: 04:23:11.653 - 55: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 424s app_test.go:1207: 04:23:11.653 - 56: DEBUG: new connection from 127.0.0.1:53234 424s app_test.go:1207: 04:23:11.656 - 55: DEBUG: attempt 1: server 127.0.0.1:9004: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:23:11.656 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.656 - 55: DEBUG: new connection from 127.0.0.1:60086 424s app_test.go:1207: 04:23:11.657 - 55: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:23:11.657 - 55: WARN: attempt 1: server 127.0.0.1:9001: context canceled 424s --- PASS: TestRolesAdjustment_ReplaceVoter (10.11s) 424s === RUN TestRolesAdjustment_ReplaceVoterHonorFailureDomain 424s app_test.go:1207: 04:23:11.727 - 59: DEBUG: new connection from 127.0.0.1:60098 424s app_test.go:1207: 04:23:11.729 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.732 - 59: DEBUG: new connection from 127.0.0.1:60108 424s app_test.go:1207: 04:23:11.763 - 59: DEBUG: new connection from 127.0.0.1:60110 424s app_test.go:1207: 04:23:11.765 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.773 - 59: DEBUG: new connection from 127.0.0.1:60114 424s app_test.go:1207: 04:23:11.773 - 60: DEBUG: new connection from 127.0.0.1:53250 424s app_test.go:1207: 04:23:11.811 - 59: DEBUG: new connection from 127.0.0.1:60130 424s app_test.go:1207: 04:23:11.813 - 61: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.816 - 59: DEBUG: new connection from 127.0.0.1:60134 424s app_test.go:1207: 04:23:11.817 - 60: DEBUG: new connection from 127.0.0.1:53264 424s app_test.go:1207: 04:23:11.822 - 61: DEBUG: new connection from 127.0.0.1:55258 424s app_test.go:1207: 04:23:11.824 - 61: DEBUG: new connection from 127.0.0.1:55266 424s app_test.go:1207: 04:23:11.830 - 59: DEBUG: new connection from 127.0.0.1:60148 424s app_test.go:1207: 04:23:11.711 - 60: DEBUG: new connection from 127.0.0.1:53268 424s app_test.go:1207: 04:23:11.715 - 59: DEBUG: new connection from 127.0.0.1:60164 424s app_test.go:1207: 04:23:11.750 - 59: DEBUG: new connection from 127.0.0.1:60170 424s app_test.go:1207: 04:23:11.752 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.756 - 59: DEBUG: new connection from 127.0.0.1:60182 424s app_test.go:1207: 04:23:11.756 - 60: DEBUG: new connection from 127.0.0.1:53284 424s app_test.go:1207: 04:23:11.758 - 61: DEBUG: new connection from 127.0.0.1:55280 424s app_test.go:1207: 04:23:11.759 - 62: DEBUG: new connection from 127.0.0.1:37644 424s app_test.go:1207: 04:23:11.763 - 62: DEBUG: new connection from 127.0.0.1:37646 424s app_test.go:1207: 04:23:11.774 - 59: DEBUG: new connection from 127.0.0.1:60188 424s app_test.go:1207: 04:23:11.797 - 59: DEBUG: new connection from 127.0.0.1:60204 424s app_test.go:1207: 04:23:11.799 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.804 - 59: DEBUG: new connection from 127.0.0.1:60208 424s app_test.go:1207: 04:23:11.804 - 60: DEBUG: new connection from 127.0.0.1:53298 424s app_test.go:1207: 04:23:11.805 - 61: DEBUG: new connection from 127.0.0.1:55286 424s app_test.go:1207: 04:23:11.806 - 62: DEBUG: new connection from 127.0.0.1:37658 424s app_test.go:1207: 04:23:11.808 - 63: DEBUG: new connection from 127.0.0.1:55312 424s app_test.go:1207: 04:23:11.812 - 63: DEBUG: new connection from 127.0.0.1:55314 424s app_test.go:1207: 04:23:11.823 - 59: DEBUG: new connection from 127.0.0.1:60224 424s app_test.go:1207: 04:23:11.841 - 59: DEBUG: new connection from 127.0.0.1:60236 424s app_test.go:1207: 04:23:11.843 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:23:11.847 - 59: DEBUG: new connection from 127.0.0.1:60246 424s app_test.go:1207: 04:23:11.847 - 60: DEBUG: new connection from 127.0.0.1:53302 424s app_test.go:1207: 04:23:11.849 - 61: DEBUG: new connection from 127.0.0.1:55302 424s app_test.go:1207: 04:23:11.851 - 62: DEBUG: new connection from 127.0.0.1:37670 424s app_test.go:1207: 04:23:11.853 - 63: DEBUG: new connection from 127.0.0.1:55320 424s app_test.go:1207: 04:23:11.854 - 64: DEBUG: new connection from 127.0.0.1:58946 424s app_test.go:1207: 04:23:11.857 - 64: DEBUG: new connection from 127.0.0.1:58950 424s app_test.go:1207: 04:23:11.870 - 59: DEBUG: new connection from 127.0.0.1:60260 424s app_test.go:1207: 04:23:11.734 - 59: DEBUG: new connection from 127.0.0.1:60268 424s app_test.go:1207: 04:23:11.736 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:23:11.740 - 59: DEBUG: new connection from 127.0.0.1:60282 424s app_test.go:1207: 04:23:11.740 - 60: DEBUG: new connection from 127.0.0.1:53304 424s app_test.go:1207: 04:23:11.742 - 62: DEBUG: new connection from 127.0.0.1:37674 424s app_test.go:1207: 04:23:11.742 - 63: DEBUG: new connection from 127.0.0.1:55332 424s app_test.go:1207: 04:23:11.745 - 64: DEBUG: new connection from 127.0.0.1:58960 424s app_test.go:1207: 04:23:11.748 - 59: DEBUG: new connection from 127.0.0.1:60296 424s app_test.go:1207: 04:23:11.749 - 60: DEBUG: new connection from 127.0.0.1:53318 424s app_test.go:1207: 04:23:11.751 - 63: DEBUG: new connection from 127.0.0.1:55342 424s app_test.go:1207: 04:23:11.751 - 62: DEBUG: new connection from 127.0.0.1:37682 424s app_test.go:1207: 04:23:11.753 - 64: DEBUG: new connection from 127.0.0.1:58964 424s app_test.go:1207: 04:23:11.756 - 59: DEBUG: new connection from 127.0.0.1:60306 424s app_test.go:1207: 04:23:11.757 - 60: DEBUG: new connection from 127.0.0.1:53322 424s app_test.go:1207: 04:23:11.759 - 62: DEBUG: new connection from 127.0.0.1:37688 424s app_test.go:1207: 04:23:11.759 - 63: DEBUG: new connection from 127.0.0.1:55344 424s app_test.go:1207: 04:23:11.761 - 64: DEBUG: new connection from 127.0.0.1:58978 424s app_test.go:1207: 04:23:11.777 - 59: DEBUG: new connection from 127.0.0.1:60320 424s app_test.go:1207: 04:23:11.779 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.764 - 59: DEBUG: new connection from 127.0.0.1:33500 424s app_test.go:1207: 04:24:11.766 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.814 - 59: DEBUG: new connection from 127.0.0.1:33516 424s app_test.go:1207: 04:24:11.815 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.860 - 59: DEBUG: new connection from 127.0.0.1:33530 424s app_test.go:1207: 04:24:11.861 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.764 - 59: DEBUG: new connection from 127.0.0.1:33538 424s app_test.go:1207: 04:24:11.766 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.769 - 59: DEBUG: new connection from 127.0.0.1:33550 424s app_test.go:1207: 04:24:11.769 - 60: DEBUG: new connection from 127.0.0.1:53540 424s app_test.go:1207: 04:24:11.770 - 62: DEBUG: new connection from 127.0.0.1:48248 424s app_test.go:1207: 04:24:11.771 - 63: DEBUG: new connection from 127.0.0.1:55030 424s app_test.go:1207: 04:24:11.774 - 64: DEBUG: new connection from 127.0.0.1:52674 424s app_test.go:1207: 04:24:11.783 - 59: DEBUG: new connection from 127.0.0.1:33564 424s app_test.go:1207: 04:24:11.785 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.770 - 59: DEBUG: new connection from 127.0.0.1:33570 424s app_test.go:1207: 04:24:11.772 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.819 - 59: DEBUG: new connection from 127.0.0.1:33572 424s app_test.go:1207: 04:24:11.820 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.866 - 59: DEBUG: new connection from 127.0.0.1:33578 424s app_test.go:1207: 04:24:11.867 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.777 - 59: DEBUG: new connection from 127.0.0.1:33586 424s app_test.go:1207: 04:24:11.779 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.783 - 59: DEBUG: new connection from 127.0.0.1:33596 424s app_test.go:1207: 04:24:11.784 - 60: DEBUG: new connection from 127.0.0.1:53544 424s app_test.go:1207: 04:24:11.786 - 62: DEBUG: new connection from 127.0.0.1:48264 424s app_test.go:1207: 04:24:11.786 - 63: DEBUG: new connection from 127.0.0.1:55040 424s app_test.go:1207: 04:24:11.788 - 64: DEBUG: new connection from 127.0.0.1:52688 424s app_test.go:1207: 04:24:11.789 - 59: DEBUG: new connection from 127.0.0.1:33606 424s app_test.go:1207: 04:24:11.791 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.775 - 59: DEBUG: new connection from 127.0.0.1:33612 424s app_test.go:1207: 04:24:11.777 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.824 - 59: DEBUG: new connection from 127.0.0.1:33616 424s app_test.go:1207: 04:24:11.825 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.871 - 59: DEBUG: new connection from 127.0.0.1:33630 424s app_test.go:1207: 04:24:11.873 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.793 - 59: DEBUG: new connection from 127.0.0.1:44822 424s app_test.go:1207: 04:24:11.795 - 59: DEBUG: new connection from 127.0.0.1:44834 424s app_test.go:1207: 04:24:11.796 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.797 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.801 - 59: DEBUG: new connection from 127.0.0.1:44848 424s app_test.go:1207: 04:24:11.801 - 60: DEBUG: new connection from 127.0.0.1:45946 424s app_test.go:1207: 04:24:11.803 - 63: DEBUG: new connection from 127.0.0.1:38930 424s app_test.go:1207: 04:24:11.803 - 62: DEBUG: new connection from 127.0.0.1:49426 424s app_test.go:1207: 04:24:11.807 - 64: DEBUG: new connection from 127.0.0.1:40076 424s app_test.go:1207: 04:24:11.781 - 59: DEBUG: new connection from 127.0.0.1:44852 424s app_test.go:1207: 04:24:11.782 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.829 - 59: DEBUG: new connection from 127.0.0.1:44856 424s app_test.go:1207: 04:24:11.830 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.878 - 59: DEBUG: new connection from 127.0.0.1:44862 424s app_test.go:1207: 04:24:11.880 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.800 - 59: DEBUG: new connection from 127.0.0.1:44878 424s app_test.go:1207: 04:24:11.802 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.810 - 59: DEBUG: new connection from 127.0.0.1:44882 424s app_test.go:1207: 04:24:11.812 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.817 - 59: DEBUG: new connection from 127.0.0.1:44894 424s app_test.go:1207: 04:24:11.817 - 60: DEBUG: new connection from 127.0.0.1:45956 424s app_test.go:1207: 04:24:11.819 - 62: DEBUG: new connection from 127.0.0.1:49432 424s app_test.go:1207: 04:24:11.820 - 63: DEBUG: new connection from 127.0.0.1:38934 424s app_test.go:1207: 04:24:11.823 - 64: DEBUG: new connection from 127.0.0.1:40088 424s app_test.go:1207: 04:24:11.875 - 59: DEBUG: new connection from 127.0.0.1:44896 424s app_test.go:1207: 04:24:11.875 - 63: DEBUG: new connection from 127.0.0.1:38944 424s app_test.go:1207: 04:24:11.875 - 62: DEBUG: new connection from 127.0.0.1:49442 424s app_test.go:1207: 04:24:11.876 - 64: DEBUG: new connection from 127.0.0.1:40104 424s app_test.go:1207: 04:24:11.876 - 60: DEBUG: new connection from 127.0.0.1:45964 424s app_test.go:1207: 04:24:11.876 - 59: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 424s app_test.go:1207: 04:24:11.879 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.879 - 59: WARN: attempt 1: server 127.0.0.1:9004: no known leader 424s app_test.go:1207: 04:24:11.879 - 59: WARN: attempt 1: server 127.0.0.1:9005: no known leader 424s app_test.go:1207: 04:24:11.880 - 59: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:24:11.880 - 59: WARN: attempt 1: server 127.0.0.1:9001: context canceled 424s app_test.go:1207: 04:24:11.880 - 59: DEBUG: attempt 1: server 127.0.0.1:9006: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:24:11.880 - 59: WARN: attempt 1: server 127.0.0.1:9001: context canceled 424s --- PASS: TestRolesAdjustment_ReplaceVoterHonorFailureDomain (20.24s) 424s === RUN TestRolesAdjustment_ImbalancedFailureDomain 424s app_test.go:1207: 04:24:11.970 - 65: DEBUG: new connection from 127.0.0.1:44906 424s app_test.go:1207: 04:24:11.973 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.975 - 65: DEBUG: new connection from 127.0.0.1:44920 424s app_test.go:1207: 04:24:11.997 - 65: DEBUG: new connection from 127.0.0.1:44930 424s app_test.go:1207: 04:24:11.999 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.006 - 66: DEBUG: new connection from 127.0.0.1:45978 424s app_test.go:1207: 04:24:11.007 - 65: DEBUG: new connection from 127.0.0.1:44946 424s app_test.go:1207: 04:24:11.052 - 65: DEBUG: new connection from 127.0.0.1:44950 424s app_test.go:1207: 04:24:11.055 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.058 - 65: DEBUG: new connection from 127.0.0.1:44956 424s app_test.go:1207: 04:24:11.058 - 66: DEBUG: new connection from 127.0.0.1:45980 424s app_test.go:1207: 04:24:11.065 - 67: DEBUG: new connection from 127.0.0.1:55050 424s app_test.go:1207: 04:24:11.068 - 67: DEBUG: new connection from 127.0.0.1:55054 424s app_test.go:1207: 04:24:11.073 - 65: DEBUG: new connection from 127.0.0.1:44968 424s app_test.go:1207: 04:24:11.958 - 66: DEBUG: new connection from 127.0.0.1:45994 424s app_test.go:1207: 04:24:11.964 - 65: DEBUG: new connection from 127.0.0.1:44972 424s app_test.go:1207: 04:24:11.993 - 65: DEBUG: new connection from 127.0.0.1:44974 424s app_test.go:1207: 04:24:11.996 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.000 - 65: DEBUG: new connection from 127.0.0.1:44976 424s app_test.go:1207: 04:24:11.001 - 66: DEBUG: new connection from 127.0.0.1:46008 424s app_test.go:1207: 04:24:11.006 - 67: DEBUG: new connection from 127.0.0.1:55068 424s app_test.go:1207: 04:24:11.007 - 68: DEBUG: new connection from 127.0.0.1:49448 424s app_test.go:1207: 04:24:11.009 - 68: DEBUG: new connection from 127.0.0.1:49464 424s app_test.go:1207: 04:24:11.017 - 65: DEBUG: new connection from 127.0.0.1:44978 424s app_test.go:1207: 04:24:11.047 - 65: DEBUG: new connection from 127.0.0.1:44992 424s app_test.go:1207: 04:24:11.049 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.055 - 66: DEBUG: new connection from 127.0.0.1:46022 424s app_test.go:1207: 04:24:11.055 - 65: DEBUG: new connection from 127.0.0.1:45004 424s app_test.go:1207: 04:24:11.057 - 67: DEBUG: new connection from 127.0.0.1:55070 424s app_test.go:1207: 04:24:11.058 - 68: DEBUG: new connection from 127.0.0.1:49480 424s app_test.go:1207: 04:24:11.061 - 69: DEBUG: new connection from 127.0.0.1:38946 424s app_test.go:1207: 04:24:11.063 - 69: DEBUG: new connection from 127.0.0.1:38952 424s app_test.go:1207: 04:24:11.076 - 65: DEBUG: new connection from 127.0.0.1:45016 424s app_test.go:1207: 04:24:11.089 - 65: DEBUG: new connection from 127.0.0.1:45018 424s app_test.go:1207: 04:24:11.091 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.096 - 65: DEBUG: new connection from 127.0.0.1:45028 424s app_test.go:1207: 04:24:11.096 - 66: DEBUG: new connection from 127.0.0.1:46026 424s app_test.go:1207: 04:24:11.097 - 67: DEBUG: new connection from 127.0.0.1:55072 424s app_test.go:1207: 04:24:11.099 - 68: DEBUG: new connection from 127.0.0.1:49486 424s app_test.go:1207: 04:24:11.102 - 69: DEBUG: new connection from 127.0.0.1:38968 424s app_test.go:1207: 04:24:11.103 - 70: DEBUG: new connection from 127.0.0.1:40106 424s app_test.go:1207: 04:24:11.106 - 70: DEBUG: new connection from 127.0.0.1:40110 424s app_test.go:1207: 04:24:11.118 - 65: DEBUG: new connection from 127.0.0.1:45034 424s app_test.go:1207: 04:24:11.131 - 65: DEBUG: new connection from 127.0.0.1:45038 424s app_test.go:1207: 04:24:11.133 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.137 - 65: DEBUG: new connection from 127.0.0.1:45046 424s app_test.go:1207: 04:24:11.137 - 66: DEBUG: new connection from 127.0.0.1:46038 424s app_test.go:1207: 04:24:11.138 - 67: DEBUG: new connection from 127.0.0.1:55088 424s app_test.go:1207: 04:24:11.140 - 68: DEBUG: new connection from 127.0.0.1:49488 424s app_test.go:1207: 04:24:11.141 - 69: DEBUG: new connection from 127.0.0.1:38972 424s app_test.go:1207: 04:24:11.143 - 70: DEBUG: new connection from 127.0.0.1:40124 424s app_test.go:1207: 04:24:11.145 - 71: DEBUG: new connection from 127.0.0.1:45072 424s app_test.go:1207: 04:24:11.170 - 65: DEBUG: new connection from 127.0.0.1:45050 424s app_test.go:1207: 04:24:11.172 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.176 - 65: DEBUG: new connection from 127.0.0.1:45066 424s app_test.go:1207: 04:24:11.178 - 66: DEBUG: new connection from 127.0.0.1:46046 424s app_test.go:1207: 04:24:11.178 - 67: DEBUG: new connection from 127.0.0.1:55100 424s app_test.go:1207: 04:24:11.180 - 68: DEBUG: new connection from 127.0.0.1:49504 424s app_test.go:1207: 04:24:11.181 - 69: DEBUG: new connection from 127.0.0.1:38982 424s app_test.go:1207: 04:24:11.183 - 70: DEBUG: new connection from 127.0.0.1:40138 424s app_test.go:1207: 04:24:11.183 - 71: DEBUG: new connection from 127.0.0.1:45076 424s app_test.go:1207: 04:24:11.185 - 72: DEBUG: new connection from 127.0.0.1:48230 424s app_test.go:1207: 04:24:11.977 - 65: DEBUG: new connection from 127.0.0.1:45078 424s app_test.go:1207: 04:24:11.980 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.984 - 65: DEBUG: new connection from 127.0.0.1:45086 424s app_test.go:1207: 04:24:11.984 - 66: DEBUG: new connection from 127.0.0.1:46054 424s app_test.go:1207: 04:24:11.987 - 67: DEBUG: new connection from 127.0.0.1:55106 424s app_test.go:1207: 04:24:11.987 - 68: DEBUG: new connection from 127.0.0.1:49518 424s app_test.go:1207: 04:24:11.989 - 69: DEBUG: new connection from 127.0.0.1:38996 424s app_test.go:1207: 04:24:11.989 - 70: DEBUG: new connection from 127.0.0.1:40146 424s app_test.go:1207: 04:24:11.991 - 71: DEBUG: new connection from 127.0.0.1:45088 424s app_test.go:1207: 04:24:11.993 - 72: DEBUG: new connection from 127.0.0.1:48238 424s app_test.go:1207: 04:24:11.995 - 72: DEBUG: new connection from 127.0.0.1:48250 424s app_test.go:1207: 04:24:11.999 - 65: DEBUG: new connection from 127.0.0.1:45096 424s app_test.go:1207: 04:24:11.010 - 65: DEBUG: new connection from 127.0.0.1:45110 424s app_test.go:1207: 04:24:11.012 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.967 - 66: DEBUG: new connection from 127.0.0.1:36700 424s app_test.go:1207: 04:24:11.967 - 65: DEBUG: new connection from 127.0.0.1:52486 424s app_test.go:1207: 04:24:11.970 - 68: DEBUG: new connection from 127.0.0.1:42822 424s app_test.go:1207: 04:24:11.970 - 67: DEBUG: new connection from 127.0.0.1:48940 424s app_test.go:1207: 04:24:11.972 - 69: DEBUG: new connection from 127.0.0.1:52398 424s app_test.go:1207: 04:24:11.972 - 70: DEBUG: new connection from 127.0.0.1:35036 424s app_test.go:1207: 04:24:11.975 - 71: DEBUG: new connection from 127.0.0.1:44066 424s app_test.go:1207: 04:24:11.975 - 72: DEBUG: new connection from 127.0.0.1:50940 424s app_test.go:1207: 04:24:11.979 - 65: DEBUG: new connection from 127.0.0.1:52498 424s app_test.go:1207: 04:24:11.979 - 66: DEBUG: new connection from 127.0.0.1:36702 424s app_test.go:1207: 04:24:11.981 - 67: DEBUG: new connection from 127.0.0.1:48948 424s app_test.go:1207: 04:24:11.981 - 68: DEBUG: new connection from 127.0.0.1:42826 424s app_test.go:1207: 04:24:11.985 - 69: DEBUG: new connection from 127.0.0.1:52402 424s app_test.go:1207: 04:24:11.985 - 70: DEBUG: new connection from 127.0.0.1:35044 424s app_test.go:1207: 04:24:11.987 - 71: DEBUG: new connection from 127.0.0.1:44070 424s app_test.go:1207: 04:24:11.988 - 72: DEBUG: new connection from 127.0.0.1:50954 424s app_test.go:1207: 04:24:11.957 - 65: DEBUG: new connection from 127.0.0.1:52504 424s app_test.go:1207: 04:24:11.958 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.013 - 65: DEBUG: new connection from 127.0.0.1:52506 424s app_test.go:1207: 04:24:11.014 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.066 - 65: DEBUG: new connection from 127.0.0.1:52512 424s app_test.go:1207: 04:24:11.067 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.109 - 65: DEBUG: new connection from 127.0.0.1:52528 424s app_test.go:1207: 04:24:11.111 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.148 - 65: DEBUG: new connection from 127.0.0.1:52540 424s app_test.go:1207: 04:24:11.150 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.187 - 65: DEBUG: new connection from 127.0.0.1:52544 424s app_test.go:1207: 04:24:11.189 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.016 - 65: DEBUG: new connection from 127.0.0.1:52554 424s app_test.go:1207: 04:24:11.017 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.990 - 65: DEBUG: new connection from 127.0.0.1:52562 424s app_test.go:1207: 04:24:11.993 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.997 - 65: DEBUG: new connection from 127.0.0.1:52574 424s app_test.go:1207: 04:24:11.997 - 66: DEBUG: new connection from 127.0.0.1:36704 424s app_test.go:1207: 04:24:11.998 - 67: DEBUG: new connection from 127.0.0.1:48952 424s app_test.go:1207: 04:24:11.999 - 68: DEBUG: new connection from 127.0.0.1:42842 424s app_test.go:1207: 04:24:11.001 - 69: DEBUG: new connection from 127.0.0.1:52418 424s app_test.go:1207: 04:24:11.002 - 70: DEBUG: new connection from 127.0.0.1:35054 424s app_test.go:1207: 04:24:11.003 - 71: DEBUG: new connection from 127.0.0.1:44082 424s app_test.go:1207: 04:24:11.004 - 72: DEBUG: new connection from 127.0.0.1:50958 424s app_test.go:1207: 04:24:11.962 - 65: DEBUG: new connection from 127.0.0.1:52584 424s app_test.go:1207: 04:24:11.963 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.994 - 67: DEBUG: new connection from 127.0.0.1:48960 424s app_test.go:1207: 04:24:11.995 - 72: DEBUG: new connection from 127.0.0.1:50964 424s app_test.go:1207: 04:24:11.999 - 66: DEBUG: new connection from 127.0.0.1:36716 424s app_test.go:1207: 04:24:11.999 - 66: DEBUG: new connection from 127.0.0.1:36728 424s app_test.go:1207: 04:24:11.019 - 65: DEBUG: new connection from 127.0.0.1:52598 424s app_test.go:1207: 04:24:11.020 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.071 - 65: DEBUG: new connection from 127.0.0.1:52614 424s app_test.go:1207: 04:24:11.072 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.115 - 65: DEBUG: new connection from 127.0.0.1:52624 424s app_test.go:1207: 04:24:11.116 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.153 - 65: DEBUG: new connection from 127.0.0.1:52628 424s app_test.go:1207: 04:24:11.155 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.192 - 65: DEBUG: new connection from 127.0.0.1:52644 424s app_test.go:1207: 04:24:11.195 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.022 - 65: DEBUG: new connection from 127.0.0.1:52658 424s app_test.go:1207: 04:24:11.024 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.007 - 65: DEBUG: new connection from 127.0.0.1:52670 424s app_test.go:1207: 04:24:11.009 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.013 - 65: DEBUG: new connection from 127.0.0.1:52676 424s app_test.go:1207: 04:24:11.013 - 66: DEBUG: new connection from 127.0.0.1:36740 424s app_test.go:1207: 04:24:11.014 - 67: DEBUG: new connection from 127.0.0.1:48968 424s app_test.go:1207: 04:24:11.015 - 68: DEBUG: new connection from 127.0.0.1:42852 424s app_test.go:1207: 04:24:11.020 - 69: DEBUG: new connection from 127.0.0.1:52422 424s app_test.go:1207: 04:24:11.020 - 70: DEBUG: new connection from 127.0.0.1:35064 424s app_test.go:1207: 04:24:11.023 - 71: DEBUG: new connection from 127.0.0.1:44086 424s app_test.go:1207: 04:24:11.024 - 72: DEBUG: new connection from 127.0.0.1:50968 424s app_test.go:1207: 04:24:11.967 - 65: DEBUG: new connection from 127.0.0.1:52680 424s app_test.go:1207: 04:24:11.969 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.024 - 65: DEBUG: new connection from 127.0.0.1:52684 424s app_test.go:1207: 04:24:11.025 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.077 - 65: DEBUG: new connection from 127.0.0.1:52700 424s app_test.go:1207: 04:24:11.078 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.121 - 65: DEBUG: new connection from 127.0.0.1:52704 424s app_test.go:1207: 04:24:11.122 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.159 - 65: DEBUG: new connection from 127.0.0.1:52710 424s app_test.go:1207: 04:24:11.160 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.198 - 65: DEBUG: new connection from 127.0.0.1:52720 424s app_test.go:1207: 04:24:11.200 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.028 - 65: DEBUG: new connection from 127.0.0.1:59298 424s app_test.go:1207: 04:24:11.029 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.028 - 65: DEBUG: new connection from 127.0.0.1:59312 424s app_test.go:1207: 04:24:11.030 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.034 - 65: DEBUG: new connection from 127.0.0.1:59314 424s app_test.go:1207: 04:24:11.034 - 66: DEBUG: new connection from 127.0.0.1:50938 424s app_test.go:1207: 04:24:11.035 - 67: DEBUG: new connection from 127.0.0.1:46374 424s app_test.go:1207: 04:24:11.037 - 68: DEBUG: new connection from 127.0.0.1:60574 424s app_test.go:1207: 04:24:11.038 - 69: DEBUG: new connection from 127.0.0.1:57548 424s app_test.go:1207: 04:24:11.040 - 70: DEBUG: new connection from 127.0.0.1:39570 424s app_test.go:1207: 04:24:11.041 - 71: DEBUG: new connection from 127.0.0.1:60420 424s app_test.go:1207: 04:24:11.044 - 72: DEBUG: new connection from 127.0.0.1:39748 424s app_test.go:1207: 04:24:11.973 - 65: DEBUG: new connection from 127.0.0.1:59330 424s app_test.go:1207: 04:24:11.975 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.029 - 65: DEBUG: new connection from 127.0.0.1:59332 424s app_test.go:1207: 04:24:11.030 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.082 - 65: DEBUG: new connection from 127.0.0.1:59346 424s app_test.go:1207: 04:24:11.084 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.126 - 65: DEBUG: new connection from 127.0.0.1:59354 424s app_test.go:1207: 04:24:11.128 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.165 - 65: DEBUG: new connection from 127.0.0.1:59370 424s app_test.go:1207: 04:24:11.167 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.203 - 65: DEBUG: new connection from 127.0.0.1:59378 424s app_test.go:1207: 04:24:11.205 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.033 - 65: DEBUG: new connection from 127.0.0.1:59384 424s app_test.go:1207: 04:24:11.034 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.189 - 71: DEBUG: new connection from 127.0.0.1:60432 424s app_test.go:1207: 04:24:11.189 - 65: DEBUG: new connection from 127.0.0.1:59396 424s app_test.go:1207: 04:24:11.189 - 67: DEBUG: new connection from 127.0.0.1:46378 424s app_test.go:1207: 04:24:11.190 - 66: DEBUG: new connection from 127.0.0.1:50942 424s app_test.go:1207: 04:24:11.190 - 70: DEBUG: new connection from 127.0.0.1:39578 424s app_test.go:1207: 04:24:11.191 - 69: DEBUG: new connection from 127.0.0.1:57554 424s app_test.go:1207: 04:24:11.191 - 68: DEBUG: new connection from 127.0.0.1:60588 424s app_test.go:1207: 04:24:11.191 - 72: DEBUG: new connection from 127.0.0.1:39756 424s app_test.go:1207: 04:24:11.194 - 65: WARN: attempt 1: server 127.0.0.1:9007: no known leader 424s app_test.go:1207: 04:24:11.199 - 65: WARN: attempt 1: server 127.0.0.1:9006: no known leader 424s app_test.go:1207: 04:24:11.199 - 65: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:24:11.200 - 65: DEBUG: new connection from 127.0.0.1:59406 424s app_test.go:1207: 04:24:11.200 - 65: WARN: attempt 1: server 127.0.0.1:9004: no known leader 424s app_test.go:1207: 04:24:11.201 - 65: WARN: attempt 1: server 127.0.0.1:9002: no known leader 424s app_test.go:1207: 04:24:11.201 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.202 - 65: DEBUG: attempt 1: server 127.0.0.1:9008: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:24:11.202 - 65: WARN: attempt 1: server 127.0.0.1:9001: context canceled 424s app_test.go:1207: 04:24:11.202 - 65: WARN: attempt 1: server 127.0.0.1:9005: no known leader 424s --- PASS: TestRolesAdjustment_ImbalancedFailureDomain (20.34s) 424s === RUN TestRolesAdjustment_ReplaceVoterHonorWeight 424s app_test.go:1207: 04:24:11.307 - 73: DEBUG: new connection from 127.0.0.1:59408 424s app_test.go:1207: 04:24:11.309 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.312 - 73: DEBUG: new connection from 127.0.0.1:59416 424s app_test.go:1207: 04:24:11.356 - 73: DEBUG: new connection from 127.0.0.1:59432 424s app_test.go:1207: 04:24:11.358 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.368 - 73: DEBUG: new connection from 127.0.0.1:59436 424s app_test.go:1207: 04:24:11.368 - 74: DEBUG: new connection from 127.0.0.1:50948 424s app_test.go:1207: 04:24:11.394 - 73: DEBUG: new connection from 127.0.0.1:59448 424s app_test.go:1207: 04:24:11.396 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.402 - 74: DEBUG: new connection from 127.0.0.1:50956 424s app_test.go:1207: 04:24:11.403 - 73: DEBUG: new connection from 127.0.0.1:59450 424s app_test.go:1207: 04:24:11.404 - 75: DEBUG: new connection from 127.0.0.1:46380 424s app_test.go:1207: 04:24:11.406 - 75: DEBUG: new connection from 127.0.0.1:46390 424s app_test.go:1207: 04:24:11.410 - 73: DEBUG: new connection from 127.0.0.1:59466 424s app_test.go:1207: 04:24:11.297 - 74: DEBUG: new connection from 127.0.0.1:50962 424s app_test.go:1207: 04:24:11.302 - 73: DEBUG: new connection from 127.0.0.1:59470 424s app_test.go:1207: 04:24:11.322 - 73: DEBUG: new connection from 127.0.0.1:59482 424s app_test.go:1207: 04:24:11.325 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.330 - 74: DEBUG: new connection from 127.0.0.1:50964 424s app_test.go:1207: 04:24:11.330 - 73: DEBUG: new connection from 127.0.0.1:59496 424s app_test.go:1207: 04:24:11.332 - 75: DEBUG: new connection from 127.0.0.1:46402 424s app_test.go:1207: 04:24:11.334 - 76: DEBUG: new connection from 127.0.0.1:60604 424s app_test.go:1207: 04:24:11.337 - 76: DEBUG: new connection from 127.0.0.1:60610 424s app_test.go:1207: 04:24:11.355 - 73: DEBUG: new connection from 127.0.0.1:59504 424s app_test.go:1207: 04:24:11.373 - 73: DEBUG: new connection from 127.0.0.1:59506 424s app_test.go:1207: 04:24:11.375 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.380 - 74: DEBUG: new connection from 127.0.0.1:50980 424s app_test.go:1207: 04:24:11.380 - 73: DEBUG: new connection from 127.0.0.1:59510 424s app_test.go:1207: 04:24:11.382 - 75: DEBUG: new connection from 127.0.0.1:46418 424s app_test.go:1207: 04:24:11.382 - 76: DEBUG: new connection from 127.0.0.1:60626 424s app_test.go:1207: 04:24:11.385 - 77: DEBUG: new connection from 127.0.0.1:57570 424s app_test.go:1207: 04:24:11.388 - 77: DEBUG: new connection from 127.0.0.1:57572 424s app_test.go:1207: 04:24:11.394 - 73: DEBUG: new connection from 127.0.0.1:59516 424s app_test.go:1207: 04:24:11.419 - 73: DEBUG: new connection from 127.0.0.1:59522 424s app_test.go:1207: 04:24:11.421 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.426 - 74: DEBUG: new connection from 127.0.0.1:50996 424s app_test.go:1207: 04:24:11.426 - 73: DEBUG: new connection from 127.0.0.1:59528 424s app_test.go:1207: 04:24:11.428 - 75: DEBUG: new connection from 127.0.0.1:46432 424s app_test.go:1207: 04:24:11.428 - 76: DEBUG: new connection from 127.0.0.1:60642 424s app_test.go:1207: 04:24:11.431 - 78: DEBUG: new connection from 127.0.0.1:39588 424s app_test.go:1207: 04:24:11.431 - 77: DEBUG: new connection from 127.0.0.1:57588 424s app_test.go:1207: 04:24:11.435 - 78: DEBUG: new connection from 127.0.0.1:39592 424s app_test.go:1207: 04:24:11.441 - 73: DEBUG: new connection from 127.0.0.1:59532 424s app_test.go:1207: 04:24:11.314 - 73: DEBUG: new connection from 127.0.0.1:59540 424s app_test.go:1207: 04:24:11.317 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.322 - 73: DEBUG: new connection from 127.0.0.1:59548 424s app_test.go:1207: 04:24:11.323 - 74: DEBUG: new connection from 127.0.0.1:51004 424s app_test.go:1207: 04:24:11.324 - 76: DEBUG: new connection from 127.0.0.1:60652 424s app_test.go:1207: 04:24:11.325 - 77: DEBUG: new connection from 127.0.0.1:57604 424s app_test.go:1207: 04:24:11.327 - 78: DEBUG: new connection from 127.0.0.1:39602 424s app_test.go:1207: 04:24:11.330 - 74: DEBUG: new connection from 127.0.0.1:51006 424s app_test.go:1207: 04:24:11.331 - 73: DEBUG: new connection from 127.0.0.1:59550 424s app_test.go:1207: 04:24:11.334 - 76: DEBUG: new connection from 127.0.0.1:60668 424s app_test.go:1207: 04:24:11.335 - 77: DEBUG: new connection from 127.0.0.1:57608 424s app_test.go:1207: 04:24:11.337 - 78: DEBUG: new connection from 127.0.0.1:39618 424s app_test.go:1207: 04:24:11.341 - 74: DEBUG: new connection from 127.0.0.1:51022 424s app_test.go:1207: 04:24:11.341 - 73: DEBUG: new connection from 127.0.0.1:59564 424s app_test.go:1207: 04:24:11.343 - 76: DEBUG: new connection from 127.0.0.1:60670 424s app_test.go:1207: 04:24:11.343 - 77: DEBUG: new connection from 127.0.0.1:57622 424s app_test.go:1207: 04:24:11.345 - 78: DEBUG: new connection from 127.0.0.1:39630 424s app_test.go:1207: 04:24:11.373 - 73: DEBUG: new connection from 127.0.0.1:59580 424s app_test.go:1207: 04:24:11.374 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.340 - 73: DEBUG: new connection from 127.0.0.1:35068 424s app_test.go:1207: 04:24:11.341 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.391 - 73: DEBUG: new connection from 127.0.0.1:35078 424s app_test.go:1207: 04:24:11.392 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.438 - 73: DEBUG: new connection from 127.0.0.1:35082 424s app_test.go:1207: 04:24:11.439 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.348 - 73: DEBUG: new connection from 127.0.0.1:35092 424s app_test.go:1207: 04:24:11.351 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.356 - 73: DEBUG: new connection from 127.0.0.1:35094 424s app_test.go:1207: 04:24:11.357 - 74: DEBUG: new connection from 127.0.0.1:53378 424s app_test.go:1207: 04:24:11.357 - 76: DEBUG: new connection from 127.0.0.1:48156 424s app_test.go:1207: 04:24:11.359 - 77: DEBUG: new connection from 127.0.0.1:59212 424s app_test.go:1207: 04:24:11.360 - 78: DEBUG: new connection from 127.0.0.1:52350 424s app_test.go:1207: 04:24:11.379 - 73: DEBUG: new connection from 127.0.0.1:35104 424s app_test.go:1207: 04:24:11.381 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.347 - 73: DEBUG: new connection from 127.0.0.1:35106 424s app_test.go:1207: 04:24:11.349 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.397 - 73: DEBUG: new connection from 127.0.0.1:35108 424s app_test.go:1207: 04:24:11.398 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.443 - 73: DEBUG: new connection from 127.0.0.1:35114 424s app_test.go:1207: 04:24:11.445 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.362 - 73: DEBUG: new connection from 127.0.0.1:35122 424s app_test.go:1207: 04:24:11.364 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.369 - 73: DEBUG: new connection from 127.0.0.1:35124 424s app_test.go:1207: 04:24:11.369 - 74: DEBUG: new connection from 127.0.0.1:53384 424s app_test.go:1207: 04:24:11.371 - 76: DEBUG: new connection from 127.0.0.1:48166 424s app_test.go:1207: 04:24:11.373 - 77: DEBUG: new connection from 127.0.0.1:59224 424s app_test.go:1207: 04:24:11.373 - 78: DEBUG: new connection from 127.0.0.1:52354 424s app_test.go:1207: 04:24:11.386 - 73: DEBUG: new connection from 127.0.0.1:35136 424s app_test.go:1207: 04:24:11.388 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.354 - 73: DEBUG: new connection from 127.0.0.1:35146 424s app_test.go:1207: 04:24:11.356 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.402 - 73: DEBUG: new connection from 127.0.0.1:35158 424s app_test.go:1207: 04:24:11.403 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.449 - 73: DEBUG: new connection from 127.0.0.1:35170 424s app_test.go:1207: 04:24:11.451 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.375 - 73: DEBUG: new connection from 127.0.0.1:45760 424s app_test.go:1207: 04:24:11.378 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.382 - 73: DEBUG: new connection from 127.0.0.1:45768 424s app_test.go:1207: 04:24:11.382 - 74: DEBUG: new connection from 127.0.0.1:33018 424s app_test.go:1207: 04:24:11.384 - 76: DEBUG: new connection from 127.0.0.1:51882 424s app_test.go:1207: 04:24:11.384 - 77: DEBUG: new connection from 127.0.0.1:52230 424s app_test.go:1207: 04:24:11.386 - 78: DEBUG: new connection from 127.0.0.1:50830 424s app_test.go:1207: 04:24:11.392 - 73: DEBUG: new connection from 127.0.0.1:45784 424s app_test.go:1207: 04:24:11.395 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.360 - 73: DEBUG: new connection from 127.0.0.1:45790 424s app_test.go:1207: 04:24:11.362 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.406 - 73: DEBUG: new connection from 127.0.0.1:45804 424s app_test.go:1207: 04:24:11.408 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.455 - 73: DEBUG: new connection from 127.0.0.1:45808 424s app_test.go:1207: 04:24:11.456 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.389 - 73: DEBUG: new connection from 127.0.0.1:45824 424s app_test.go:1207: 04:24:11.391 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.394 - 74: DEBUG: new connection from 127.0.0.1:33034 424s app_test.go:1207: 04:24:11.394 - 73: DEBUG: new connection from 127.0.0.1:45832 424s app_test.go:1207: 04:24:11.396 - 76: DEBUG: new connection from 127.0.0.1:51886 424s app_test.go:1207: 04:24:11.397 - 77: DEBUG: new connection from 127.0.0.1:52234 424s app_test.go:1207: 04:24:11.398 - 78: DEBUG: new connection from 127.0.0.1:50838 424s app_test.go:1207: 04:24:11.401 - 73: DEBUG: new connection from 127.0.0.1:45848 424s app_test.go:1207: 04:24:11.403 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:24:11.450 - 73: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 424s app_test.go:1207: 04:24:11.450 - 73: DEBUG: new connection from 127.0.0.1:45850 424s app_test.go:1207: 04:24:11.450 - 78: DEBUG: new connection from 127.0.0.1:50840 424s app_test.go:1207: 04:24:11.451 - 76: DEBUG: new connection from 127.0.0.1:51896 424s app_test.go:1207: 04:24:11.451 - 77: DEBUG: new connection from 127.0.0.1:52238 424s app_test.go:1207: 04:24:11.452 - 74: DEBUG: new connection from 127.0.0.1:33042 424s app_test.go:1207: 04:24:11.454 - 73: WARN: attempt 1: server 127.0.0.1:9006: no known leader 424s app_test.go:1207: 04:24:11.455 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.455 - 73: WARN: attempt 1: server 127.0.0.1:9004: no known leader 424s app_test.go:1207: 04:24:11.455 - 73: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:24:11.455 - 73: WARN: attempt 1: server 127.0.0.1:9001: context canceled 424s app_test.go:1207: 04:24:11.455 - 73: DEBUG: attempt 1: server 127.0.0.1:9005: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:24:11.455 - 73: WARN: attempt 1: server 127.0.0.1:9001: context canceled 424s --- PASS: TestRolesAdjustment_ReplaceVoterHonorWeight (20.25s) 424s === RUN TestRolesAdjustment_CantReplaceVoter 424s app_test.go:1207: 04:24:11.568 - 79: DEBUG: new connection from 127.0.0.1:45854 424s app_test.go:1207: 04:24:11.570 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.572 - 79: DEBUG: new connection from 127.0.0.1:45870 424s app_test.go:1207: 04:24:11.608 - 79: DEBUG: new connection from 127.0.0.1:45880 424s app_test.go:1207: 04:24:11.610 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.619 - 80: DEBUG: new connection from 127.0.0.1:33050 424s app_test.go:1207: 04:24:11.619 - 79: DEBUG: new connection from 127.0.0.1:45894 424s app_test.go:1207: 04:24:11.645 - 79: DEBUG: new connection from 127.0.0.1:45910 424s app_test.go:1207: 04:24:11.647 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.650 - 80: DEBUG: new connection from 127.0.0.1:33064 424s app_test.go:1207: 04:24:11.650 - 79: DEBUG: new connection from 127.0.0.1:45912 424s app_test.go:1207: 04:24:11.652 - 81: DEBUG: new connection from 127.0.0.1:56250 424s app_test.go:1207: 04:24:11.654 - 81: DEBUG: new connection from 127.0.0.1:56262 424s app_test.go:1207: 04:24:11.658 - 79: DEBUG: new connection from 127.0.0.1:45928 424s app_test.go:1207: 04:24:11.546 - 80: DEBUG: new connection from 127.0.0.1:33074 424s app_test.go:1207: 04:24:11.550 - 79: DEBUG: new connection from 127.0.0.1:45936 424s app_test.go:1207: 04:24:11.584 - 79: DEBUG: new connection from 127.0.0.1:45940 424s app_test.go:1207: 04:24:11.587 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:24:11.591 - 80: DEBUG: new connection from 127.0.0.1:33078 424s app_test.go:1207: 04:24:11.591 - 79: DEBUG: new connection from 127.0.0.1:45944 424s app_test.go:1207: 04:24:11.592 - 81: DEBUG: new connection from 127.0.0.1:56272 424s app_test.go:1207: 04:24:11.593 - 82: DEBUG: new connection from 127.0.0.1:51908 424s app_test.go:1207: 04:24:11.595 - 82: DEBUG: new connection from 127.0.0.1:51924 424s app_test.go:1207: 04:25:11.574 - 79: DEBUG: new connection from 127.0.0.1:49210 424s app_test.go:1207: 04:25:11.576 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.580 - 80: DEBUG: new connection from 127.0.0.1:56804 424s app_test.go:1207: 04:25:11.580 - 79: DEBUG: new connection from 127.0.0.1:49224 424s app_test.go:1207: 04:25:11.622 - 79: DEBUG: new connection from 127.0.0.1:49226 424s app_test.go:1207: 04:25:11.626 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.584 - 79: DEBUG: new connection from 127.0.0.1:49234 424s app_test.go:1207: 04:25:11.588 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.596 - 79: DEBUG: new connection from 127.0.0.1:49240 424s app_test.go:1207: 04:25:11.596 - 80: DEBUG: new connection from 127.0.0.1:56814 424s app_test.go:1207: 04:25:11.635 - 79: DEBUG: new connection from 127.0.0.1:49252 424s app_test.go:1207: 04:25:11.637 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.601 - 79: DEBUG: new connection from 127.0.0.1:49256 424s app_test.go:1207: 04:25:11.604 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.612 - 79: DEBUG: new connection from 127.0.0.1:49258 424s app_test.go:1207: 04:25:11.612 - 80: DEBUG: new connection from 127.0.0.1:56820 424s app_test.go:1207: 04:25:11.644 - 79: DEBUG: new connection from 127.0.0.1:49262 424s app_test.go:1207: 04:25:11.646 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.612 - 79: DEBUG: new connection from 127.0.0.1:50150 424s app_test.go:1207: 04:25:11.612 - 79: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 424s app_test.go:1207: 04:25:11.613 - 80: DEBUG: new connection from 127.0.0.1:34188 424s app_test.go:1207: 04:25:11.613 - 79: WARN: attempt 1: server 127.0.0.1:9004: dial: dial tcp 127.0.0.1:9004: connect: connection refused 424s app_test.go:1207: 04:25:11.615 - 79: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:25:11.615 - 79: DEBUG: new connection from 127.0.0.1:50152 424s app_test.go:1207: 04:25:11.618 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s --- PASS: TestRolesAdjustment_CantReplaceVoter (14.11s) 424s === RUN TestRolesAdjustment_ReplaceStandBy 424s app_test.go:1207: 04:25:11.670 - 83: DEBUG: new connection from 127.0.0.1:50168 424s app_test.go:1207: 04:25:11.674 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.678 - 83: DEBUG: new connection from 127.0.0.1:50184 424s app_test.go:1207: 04:25:11.722 - 83: DEBUG: new connection from 127.0.0.1:50196 424s app_test.go:1207: 04:25:11.726 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.741 - 83: DEBUG: new connection from 127.0.0.1:50208 424s app_test.go:1207: 04:25:11.743 - 84: DEBUG: new connection from 127.0.0.1:34198 424s app_test.go:1207: 04:25:11.792 - 83: DEBUG: new connection from 127.0.0.1:50224 424s app_test.go:1207: 04:25:11.797 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.805 - 84: DEBUG: new connection from 127.0.0.1:34208 424s app_test.go:1207: 04:25:11.805 - 83: DEBUG: new connection from 127.0.0.1:50232 424s app_test.go:1207: 04:25:11.812 - 85: DEBUG: new connection from 127.0.0.1:47930 424s app_test.go:1207: 04:25:11.819 - 85: DEBUG: new connection from 127.0.0.1:47940 424s app_test.go:1207: 04:25:11.836 - 83: DEBUG: new connection from 127.0.0.1:50238 424s app_test.go:1207: 04:25:11.660 - 84: DEBUG: new connection from 127.0.0.1:34218 424s app_test.go:1207: 04:25:11.670 - 83: DEBUG: new connection from 127.0.0.1:50246 424s app_test.go:1207: 04:25:11.697 - 83: DEBUG: new connection from 127.0.0.1:50254 424s app_test.go:1207: 04:25:11.700 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.709 - 83: DEBUG: new connection from 127.0.0.1:50270 424s app_test.go:1207: 04:25:11.710 - 84: DEBUG: new connection from 127.0.0.1:34224 424s app_test.go:1207: 04:25:11.711 - 85: DEBUG: new connection from 127.0.0.1:47942 424s app_test.go:1207: 04:25:11.714 - 86: DEBUG: new connection from 127.0.0.1:43354 424s app_test.go:1207: 04:25:11.739 - 86: DEBUG: new connection from 127.0.0.1:43356 424s app_test.go:1207: 04:25:11.742 - 83: DEBUG: new connection from 127.0.0.1:50284 424s app_test.go:1207: 04:25:11.745 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.746 - 83: DEBUG: new connection from 127.0.0.1:50296 424s app_test.go:1207: 04:25:11.754 - 83: DEBUG: new connection from 127.0.0.1:50306 424s app_test.go:1207: 04:25:11.755 - 84: DEBUG: new connection from 127.0.0.1:34228 424s app_test.go:1207: 04:25:11.757 - 85: DEBUG: new connection from 127.0.0.1:47956 424s app_test.go:1207: 04:25:11.760 - 86: DEBUG: new connection from 127.0.0.1:43364 424s app_test.go:1207: 04:25:11.761 - 87: DEBUG: new connection from 127.0.0.1:56062 424s app_test.go:1207: 04:25:11.770 - 87: DEBUG: new connection from 127.0.0.1:56068 424s app_test.go:1207: 04:25:11.780 - 83: DEBUG: new connection from 127.0.0.1:50308 424s app_test.go:1207: 04:25:11.797 - 83: DEBUG: new connection from 127.0.0.1:50316 424s app_test.go:1207: 04:25:11.800 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.806 - 83: DEBUG: new connection from 127.0.0.1:50320 424s app_test.go:1207: 04:25:11.807 - 84: DEBUG: new connection from 127.0.0.1:34240 424s app_test.go:1207: 04:25:11.808 - 85: DEBUG: new connection from 127.0.0.1:47970 424s app_test.go:1207: 04:25:11.811 - 86: DEBUG: new connection from 127.0.0.1:43368 424s app_test.go:1207: 04:25:11.813 - 87: DEBUG: new connection from 127.0.0.1:56084 424s app_test.go:1207: 04:25:11.814 - 88: DEBUG: new connection from 127.0.0.1:50830 424s app_test.go:1207: 04:25:11.821 - 88: DEBUG: new connection from 127.0.0.1:50840 424s app_test.go:1207: 04:25:11.843 - 83: DEBUG: new connection from 127.0.0.1:50324 424s app_test.go:1207: 04:25:11.846 - 83: DEBUG: new connection from 127.0.0.1:50328 424s app_test.go:1207: 04:25:11.849 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.855 - 83: DEBUG: new connection from 127.0.0.1:50332 424s app_test.go:1207: 04:25:11.856 - 84: DEBUG: new connection from 127.0.0.1:34246 424s app_test.go:1207: 04:25:11.857 - 85: DEBUG: new connection from 127.0.0.1:47982 424s app_test.go:1207: 04:25:11.860 - 86: DEBUG: new connection from 127.0.0.1:43372 424s app_test.go:1207: 04:25:11.862 - 87: DEBUG: new connection from 127.0.0.1:56090 424s app_test.go:1207: 04:25:11.863 - 88: DEBUG: new connection from 127.0.0.1:50854 424s app_test.go:1207: 04:25:11.866 - 89: DEBUG: new connection from 127.0.0.1:52156 424s app_test.go:1207: 04:25:11.684 - 83: DEBUG: new connection from 127.0.0.1:50338 424s app_test.go:1207: 04:25:11.687 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.696 - 83: DEBUG: new connection from 127.0.0.1:50352 424s app_test.go:1207: 04:25:11.696 - 84: DEBUG: new connection from 127.0.0.1:34258 424s app_test.go:1207: 04:25:11.698 - 85: DEBUG: new connection from 127.0.0.1:47996 424s app_test.go:1207: 04:25:11.699 - 86: DEBUG: new connection from 127.0.0.1:43388 424s app_test.go:1207: 04:25:11.701 - 88: DEBUG: new connection from 127.0.0.1:50858 424s app_test.go:1207: 04:25:11.701 - 89: DEBUG: new connection from 127.0.0.1:52162 424s app_test.go:1207: 04:25:11.703 - 89: DEBUG: new connection from 127.0.0.1:52174 424s app_test.go:1207: 04:25:11.706 - 83: DEBUG: new connection from 127.0.0.1:50362 424s app_test.go:1207: 04:25:11.706 - 84: DEBUG: new connection from 127.0.0.1:34264 424s app_test.go:1207: 04:25:11.711 - 85: DEBUG: new connection from 127.0.0.1:48006 424s app_test.go:1207: 04:25:11.713 - 83: DEBUG: new connection from 127.0.0.1:50378 424s app_test.go:1207: 04:25:11.713 - 86: DEBUG: new connection from 127.0.0.1:43394 424s app_test.go:1207: 04:25:11.716 - 88: DEBUG: new connection from 127.0.0.1:50862 424s app_test.go:1207: 04:25:11.718 - 89: DEBUG: new connection from 127.0.0.1:52176 424s app_test.go:1207: 04:25:11.723 - 83: DEBUG: new connection from 127.0.0.1:50390 424s app_test.go:1207: 04:25:11.724 - 84: DEBUG: new connection from 127.0.0.1:34270 424s app_test.go:1207: 04:25:11.726 - 85: DEBUG: new connection from 127.0.0.1:48008 424s app_test.go:1207: 04:25:11.726 - 86: DEBUG: new connection from 127.0.0.1:43406 424s app_test.go:1207: 04:25:11.728 - 88: DEBUG: new connection from 127.0.0.1:50878 424s app_test.go:1207: 04:25:11.729 - 89: DEBUG: new connection from 127.0.0.1:52190 424s app_test.go:1207: 04:25:11.749 - 83: DEBUG: new connection from 127.0.0.1:50406 424s app_test.go:1207: 04:25:11.752 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.658 - 83: DEBUG: new connection from 127.0.0.1:50420 424s app_test.go:1207: 04:25:11.660 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.720 - 83: DEBUG: new connection from 127.0.0.1:50428 424s app_test.go:1207: 04:25:11.722 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.823 - 83: DEBUG: new connection from 127.0.0.1:50438 424s app_test.go:1207: 04:25:11.824 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.871 - 83: DEBUG: new connection from 127.0.0.1:50446 424s app_test.go:1207: 04:25:11.872 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.733 - 83: DEBUG: new connection from 127.0.0.1:50910 424s app_test.go:1207: 04:25:11.735 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.741 - 83: DEBUG: new connection from 127.0.0.1:50924 424s app_test.go:1207: 04:25:11.741 - 84: DEBUG: new connection from 127.0.0.1:59340 424s app_test.go:1207: 04:25:11.742 - 85: DEBUG: new connection from 127.0.0.1:33696 424s app_test.go:1207: 04:25:11.744 - 86: DEBUG: new connection from 127.0.0.1:39194 424s app_test.go:1207: 04:25:11.745 - 88: DEBUG: new connection from 127.0.0.1:37312 424s app_test.go:1207: 04:25:11.746 - 89: DEBUG: new connection from 127.0.0.1:43264 424s app_test.go:1207: 04:25:11.756 - 83: DEBUG: new connection from 127.0.0.1:50928 424s app_test.go:1207: 04:25:11.757 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.664 - 83: DEBUG: new connection from 127.0.0.1:50932 424s app_test.go:1207: 04:25:11.665 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.726 - 83: DEBUG: new connection from 127.0.0.1:50940 424s app_test.go:1207: 04:25:11.727 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.830 - 83: DEBUG: new connection from 127.0.0.1:50952 424s app_test.go:1207: 04:25:11.832 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.877 - 83: DEBUG: new connection from 127.0.0.1:50964 424s app_test.go:1207: 04:25:11.878 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.750 - 83: DEBUG: new connection from 127.0.0.1:50970 424s app_test.go:1207: 04:25:11.752 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.756 - 83: DEBUG: new connection from 127.0.0.1:50976 424s app_test.go:1207: 04:25:11.756 - 84: DEBUG: new connection from 127.0.0.1:59346 424s app_test.go:1207: 04:25:11.758 - 85: DEBUG: new connection from 127.0.0.1:33704 424s app_test.go:1207: 04:25:11.758 - 86: DEBUG: new connection from 127.0.0.1:39196 424s app_test.go:1207: 04:25:11.760 - 88: DEBUG: new connection from 127.0.0.1:37326 424s app_test.go:1207: 04:25:11.761 - 89: DEBUG: new connection from 127.0.0.1:43268 424s app_test.go:1207: 04:25:11.762 - 83: DEBUG: new connection from 127.0.0.1:50992 424s app_test.go:1207: 04:25:11.764 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.671 - 83: DEBUG: new connection from 127.0.0.1:51004 424s app_test.go:1207: 04:25:11.673 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.731 - 83: DEBUG: new connection from 127.0.0.1:51018 424s app_test.go:1207: 04:25:11.734 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.836 - 83: DEBUG: new connection from 127.0.0.1:51030 424s app_test.go:1207: 04:25:11.839 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.882 - 83: DEBUG: new connection from 127.0.0.1:51038 424s app_test.go:1207: 04:25:11.884 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.765 - 83: DEBUG: new connection from 127.0.0.1:53550 424s app_test.go:1207: 04:25:11.767 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.775 - 83: DEBUG: new connection from 127.0.0.1:53558 424s app_test.go:1207: 04:25:11.776 - 83: DEBUG: new connection from 127.0.0.1:53566 424s app_test.go:1207: 04:25:11.776 - 84: DEBUG: new connection from 127.0.0.1:36168 424s app_test.go:1207: 04:25:11.777 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.778 - 85: DEBUG: new connection from 127.0.0.1:47406 424s app_test.go:1207: 04:25:11.780 - 86: DEBUG: new connection from 127.0.0.1:35158 424s app_test.go:1207: 04:25:11.783 - 88: DEBUG: new connection from 127.0.0.1:45470 424s app_test.go:1207: 04:25:11.783 - 89: DEBUG: new connection from 127.0.0.1:43826 424s app_test.go:1207: 04:25:11.679 - 83: DEBUG: new connection from 127.0.0.1:53568 424s app_test.go:1207: 04:25:11.682 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.738 - 83: DEBUG: new connection from 127.0.0.1:53578 424s app_test.go:1207: 04:25:11.740 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.845 - 83: DEBUG: new connection from 127.0.0.1:53584 424s app_test.go:1207: 04:25:11.846 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.873 - 85: DEBUG: new connection from 127.0.0.1:47418 424s app_test.go:1207: 04:25:11.873 - 84: DEBUG: new connection from 127.0.0.1:36184 424s app_test.go:1207: 04:25:11.874 - 86: DEBUG: new connection from 127.0.0.1:35170 424s app_test.go:1207: 04:25:11.874 - 89: DEBUG: new connection from 127.0.0.1:43828 424s app_test.go:1207: 04:25:11.874 - 83: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 424s app_test.go:1207: 04:25:11.874 - 83: DEBUG: new connection from 127.0.0.1:53590 424s app_test.go:1207: 04:25:11.874 - 88: DEBUG: new connection from 127.0.0.1:45480 424s app_test.go:1207: 04:25:11.878 - 83: WARN: attempt 1: server 127.0.0.1:9006: no known leader 424s app_test.go:1207: 04:25:11.878 - 83: WARN: attempt 1: server 127.0.0.1:9007: no known leader 424s app_test.go:1207: 04:25:11.879 - 83: WARN: attempt 1: server 127.0.0.1:9004: no known leader 424s app_test.go:1207: 04:25:11.879 - 83: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:25:11.879 - 83: DEBUG: new connection from 127.0.0.1:53598 424s app_test.go:1207: 04:25:11.880 - 83: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:25:11.880 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.880 - 83: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: operation was canceled 424s app_test.go:1207: 04:25:11.880 - 83: DEBUG: new connection from 127.0.0.1:53600 424s --- PASS: TestRolesAdjustment_ReplaceStandBy (22.31s) 424s === RUN TestRolesAdjustment_ReplaceStandByHonorFailureDomains 424s app_test.go:1207: 04:25:11.981 - 90: DEBUG: new connection from 127.0.0.1:53612 424s app_test.go:1207: 04:25:11.984 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.987 - 90: DEBUG: new connection from 127.0.0.1:53618 424s app_test.go:1207: 04:25:11.030 - 90: DEBUG: new connection from 127.0.0.1:53620 424s app_test.go:1207: 04:25:11.032 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.041 - 90: DEBUG: new connection from 127.0.0.1:53632 424s app_test.go:1207: 04:25:11.041 - 91: DEBUG: new connection from 127.0.0.1:36186 424s app_test.go:1207: 04:25:11.066 - 90: DEBUG: new connection from 127.0.0.1:53648 424s app_test.go:1207: 04:25:11.068 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.073 - 91: DEBUG: new connection from 127.0.0.1:36194 424s app_test.go:1207: 04:25:11.073 - 90: DEBUG: new connection from 127.0.0.1:53656 424s app_test.go:1207: 04:25:11.075 - 92: DEBUG: new connection from 127.0.0.1:47422 424s app_test.go:1207: 04:25:11.077 - 92: DEBUG: new connection from 127.0.0.1:47428 424s app_test.go:1207: 04:25:11.082 - 90: DEBUG: new connection from 127.0.0.1:53660 424s app_test.go:1207: 04:25:11.970 - 91: DEBUG: new connection from 127.0.0.1:36196 424s app_test.go:1207: 04:25:11.976 - 90: DEBUG: new connection from 127.0.0.1:53664 424s app_test.go:1207: 04:25:11.013 - 90: DEBUG: new connection from 127.0.0.1:53670 424s app_test.go:1207: 04:25:11.017 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.023 - 90: DEBUG: new connection from 127.0.0.1:53686 424s app_test.go:1207: 04:25:11.025 - 91: DEBUG: new connection from 127.0.0.1:36200 424s app_test.go:1207: 04:25:11.027 - 92: DEBUG: new connection from 127.0.0.1:47442 424s app_test.go:1207: 04:25:11.028 - 93: DEBUG: new connection from 127.0.0.1:35184 424s app_test.go:1207: 04:25:11.034 - 93: DEBUG: new connection from 127.0.0.1:35192 424s app_test.go:1207: 04:25:11.051 - 90: DEBUG: new connection from 127.0.0.1:53690 424s app_test.go:1207: 04:25:11.070 - 90: DEBUG: new connection from 127.0.0.1:53702 424s app_test.go:1207: 04:25:11.074 - 94: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.081 - 90: DEBUG: new connection from 127.0.0.1:53718 424s app_test.go:1207: 04:25:11.082 - 91: DEBUG: new connection from 127.0.0.1:36216 424s app_test.go:1207: 04:25:11.087 - 93: DEBUG: new connection from 127.0.0.1:35200 424s app_test.go:1207: 04:25:11.087 - 92: DEBUG: new connection from 127.0.0.1:47456 424s app_test.go:1207: 04:25:11.092 - 94: DEBUG: new connection from 127.0.0.1:34862 424s app_test.go:1207: 04:25:11.096 - 94: DEBUG: new connection from 127.0.0.1:34864 424s app_test.go:1207: 04:25:11.106 - 90: DEBUG: new connection from 127.0.0.1:53730 424s app_test.go:1207: 04:25:11.127 - 90: DEBUG: new connection from 127.0.0.1:53732 424s app_test.go:1207: 04:25:11.131 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.137 - 90: DEBUG: new connection from 127.0.0.1:53744 424s app_test.go:1207: 04:25:11.137 - 91: DEBUG: new connection from 127.0.0.1:36230 424s app_test.go:1207: 04:25:11.141 - 92: DEBUG: new connection from 127.0.0.1:47466 424s app_test.go:1207: 04:25:11.145 - 93: DEBUG: new connection from 127.0.0.1:35208 424s app_test.go:1207: 04:25:11.147 - 94: DEBUG: new connection from 127.0.0.1:34872 424s app_test.go:1207: 04:25:11.152 - 95: DEBUG: new connection from 127.0.0.1:45496 424s app_test.go:1207: 04:25:11.156 - 95: DEBUG: new connection from 127.0.0.1:45500 424s app_test.go:1207: 04:25:11.175 - 90: DEBUG: new connection from 127.0.0.1:53750 424s app_test.go:1207: 04:25:11.180 - 90: DEBUG: new connection from 127.0.0.1:53760 424s app_test.go:1207: 04:25:11.184 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.189 - 91: DEBUG: new connection from 127.0.0.1:36234 424s app_test.go:1207: 04:25:11.190 - 90: DEBUG: new connection from 127.0.0.1:53764 424s app_test.go:1207: 04:25:11.192 - 92: DEBUG: new connection from 127.0.0.1:47478 424s app_test.go:1207: 04:25:11.193 - 93: DEBUG: new connection from 127.0.0.1:35216 424s app_test.go:1207: 04:25:11.199 - 95: DEBUG: new connection from 127.0.0.1:45512 424s app_test.go:1207: 04:25:11.200 - 94: DEBUG: new connection from 127.0.0.1:34888 424s app_test.go:1207: 04:25:11.204 - 96: DEBUG: new connection from 127.0.0.1:43830 424s app_test.go:1207: 04:25:11.241 - 90: DEBUG: new connection from 127.0.0.1:53774 424s app_test.go:1207: 04:25:11.245 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.251 - 91: DEBUG: new connection from 127.0.0.1:36246 424s app_test.go:1207: 04:25:11.251 - 90: DEBUG: new connection from 127.0.0.1:53782 424s app_test.go:1207: 04:25:11.253 - 92: DEBUG: new connection from 127.0.0.1:47490 424s app_test.go:1207: 04:25:11.254 - 93: DEBUG: new connection from 127.0.0.1:35224 424s app_test.go:1207: 04:25:11.258 - 94: DEBUG: new connection from 127.0.0.1:34890 424s app_test.go:1207: 04:25:11.258 - 95: DEBUG: new connection from 127.0.0.1:45524 424s app_test.go:1207: 04:25:11.261 - 96: DEBUG: new connection from 127.0.0.1:43838 424s app_test.go:1207: 04:25:11.262 - 97: DEBUG: new connection from 127.0.0.1:52178 424s app_test.go:1207: 04:25:11.289 - 90: DEBUG: new connection from 127.0.0.1:53790 424s app_test.go:1207: 04:25:11.294 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.300 - 90: DEBUG: new connection from 127.0.0.1:53800 424s app_test.go:1207: 04:25:11.301 - 91: DEBUG: new connection from 127.0.0.1:36258 424s app_test.go:1207: 04:25:11.304 - 92: DEBUG: new connection from 127.0.0.1:47494 424s app_test.go:1207: 04:25:11.306 - 93: DEBUG: new connection from 127.0.0.1:35236 424s app_test.go:1207: 04:25:11.311 - 94: DEBUG: new connection from 127.0.0.1:34892 424s app_test.go:1207: 04:25:11.312 - 95: DEBUG: new connection from 127.0.0.1:45538 424s app_test.go:1207: 04:25:11.315 - 96: DEBUG: new connection from 127.0.0.1:43848 424s app_test.go:1207: 04:25:11.317 - 97: DEBUG: new connection from 127.0.0.1:52192 424s app_test.go:1207: 04:25:11.320 - 98: DEBUG: new connection from 127.0.0.1:52614 424s app_test.go:1207: 04:25:11.990 - 90: DEBUG: new connection from 127.0.0.1:53802 424s app_test.go:1207: 04:25:11.992 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.996 - 90: DEBUG: new connection from 127.0.0.1:53806 424s app_test.go:1207: 04:25:11.996 - 91: DEBUG: new connection from 127.0.0.1:36274 424s app_test.go:1207: 04:25:11.999 - 92: DEBUG: new connection from 127.0.0.1:47502 424s app_test.go:1207: 04:25:11.000 - 93: DEBUG: new connection from 127.0.0.1:35246 424s app_test.go:1207: 04:25:11.002 - 95: DEBUG: new connection from 127.0.0.1:45550 424s app_test.go:1207: 04:25:11.002 - 96: DEBUG: new connection from 127.0.0.1:43864 424s app_test.go:1207: 04:25:11.005 - 97: DEBUG: new connection from 127.0.0.1:52208 424s app_test.go:1207: 04:25:11.005 - 98: DEBUG: new connection from 127.0.0.1:52628 424s app_test.go:1207: 04:25:11.007 - 97: DEBUG: new connection from 127.0.0.1:52216 424s app_test.go:1207: 04:25:11.009 - 90: DEBUG: new connection from 127.0.0.1:53820 424s app_test.go:1207: 04:25:11.009 - 91: DEBUG: new connection from 127.0.0.1:36278 424s app_test.go:1207: 04:25:11.013 - 92: DEBUG: new connection from 127.0.0.1:47510 424s app_test.go:1207: 04:25:11.016 - 93: DEBUG: new connection from 127.0.0.1:35250 424s app_test.go:1207: 04:25:11.018 - 95: DEBUG: new connection from 127.0.0.1:45566 424s app_test.go:1207: 04:25:11.019 - 90: DEBUG: new connection from 127.0.0.1:53822 424s app_test.go:1207: 04:25:11.021 - 96: DEBUG: new connection from 127.0.0.1:43874 424s app_test.go:1207: 04:25:11.022 - 97: DEBUG: new connection from 127.0.0.1:52228 424s app_test.go:1207: 04:25:11.023 - 98: DEBUG: new connection from 127.0.0.1:52644 424s app_test.go:1207: 04:25:11.026 - 90: DEBUG: new connection from 127.0.0.1:53830 424s app_test.go:1207: 04:25:11.027 - 91: DEBUG: new connection from 127.0.0.1:36282 424s app_test.go:1207: 04:25:11.029 - 92: DEBUG: new connection from 127.0.0.1:47516 424s app_test.go:1207: 04:25:11.030 - 93: DEBUG: new connection from 127.0.0.1:35260 424s app_test.go:1207: 04:25:11.032 - 95: DEBUG: new connection from 127.0.0.1:45568 424s app_test.go:1207: 04:25:11.033 - 96: DEBUG: new connection from 127.0.0.1:43890 424s app_test.go:1207: 04:25:11.036 - 97: DEBUG: new connection from 127.0.0.1:52242 424s app_test.go:1207: 04:25:11.037 - 98: DEBUG: new connection from 127.0.0.1:52658 424s app_test.go:1207: 04:25:11.045 - 90: DEBUG: new connection from 127.0.0.1:53838 424s app_test.go:1207: 04:25:11.046 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.974 - 90: DEBUG: new connection from 127.0.0.1:53844 424s app_test.go:1207: 04:25:11.975 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.037 - 90: DEBUG: new connection from 127.0.0.1:53860 424s app_test.go:1207: 04:25:11.038 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.159 - 90: DEBUG: new connection from 127.0.0.1:53868 424s app_test.go:1207: 04:25:11.160 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.208 - 90: DEBUG: new connection from 127.0.0.1:53870 424s app_test.go:1207: 04:25:11.211 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.266 - 90: DEBUG: new connection from 127.0.0.1:53872 424s app_test.go:1207: 04:25:11.267 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.323 - 90: DEBUG: new connection from 127.0.0.1:53874 424s app_test.go:1207: 04:25:11.326 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.040 - 90: DEBUG: new connection from 127.0.0.1:51854 424s app_test.go:1207: 04:25:11.042 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.047 - 91: DEBUG: new connection from 127.0.0.1:51198 424s app_test.go:1207: 04:25:11.047 - 90: DEBUG: new connection from 127.0.0.1:51866 424s app_test.go:1207: 04:25:11.050 - 90: DEBUG: new connection from 127.0.0.1:51882 424s app_test.go:1207: 04:25:11.051 - 92: DEBUG: new connection from 127.0.0.1:55720 424s app_test.go:1207: 04:25:11.052 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.053 - 93: DEBUG: new connection from 127.0.0.1:38328 424s app_test.go:1207: 04:25:11.055 - 95: DEBUG: new connection from 127.0.0.1:37056 424s app_test.go:1207: 04:25:11.056 - 96: DEBUG: new connection from 127.0.0.1:55102 424s app_test.go:1207: 04:25:11.058 - 97: DEBUG: new connection from 127.0.0.1:54588 424s app_test.go:1207: 04:25:11.058 - 98: DEBUG: new connection from 127.0.0.1:46040 424s app_test.go:1207: 04:25:11.981 - 90: DEBUG: new connection from 127.0.0.1:51884 424s app_test.go:1207: 04:25:11.983 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.042 - 90: DEBUG: new connection from 127.0.0.1:51892 424s app_test.go:1207: 04:25:11.043 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.164 - 90: DEBUG: new connection from 127.0.0.1:51906 424s app_test.go:1207: 04:25:11.165 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.215 - 90: DEBUG: new connection from 127.0.0.1:51922 424s app_test.go:1207: 04:25:11.216 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.271 - 90: DEBUG: new connection from 127.0.0.1:51928 424s app_test.go:1207: 04:25:11.274 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.329 - 90: DEBUG: new connection from 127.0.0.1:51934 424s app_test.go:1207: 04:25:11.331 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.056 - 90: DEBUG: new connection from 127.0.0.1:51936 424s app_test.go:1207: 04:25:11.058 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.064 - 90: DEBUG: new connection from 127.0.0.1:51946 424s app_test.go:1207: 04:25:11.066 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.070 - 90: DEBUG: new connection from 127.0.0.1:51948 424s app_test.go:1207: 04:25:11.070 - 91: DEBUG: new connection from 127.0.0.1:51204 424s app_test.go:1207: 04:25:11.072 - 92: DEBUG: new connection from 127.0.0.1:55724 424s app_test.go:1207: 04:25:11.073 - 93: DEBUG: new connection from 127.0.0.1:38334 424s app_test.go:1207: 04:25:11.074 - 95: DEBUG: new connection from 127.0.0.1:37070 424s app_test.go:1207: 04:25:11.076 - 96: DEBUG: new connection from 127.0.0.1:55118 424s app_test.go:1207: 04:25:11.077 - 97: DEBUG: new connection from 127.0.0.1:54600 424s app_test.go:1207: 04:25:11.080 - 98: DEBUG: new connection from 127.0.0.1:46048 424s app_test.go:1207: 04:25:11.990 - 90: DEBUG: new connection from 127.0.0.1:51950 424s app_test.go:1207: 04:25:11.991 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.049 - 90: DEBUG: new connection from 127.0.0.1:51958 424s app_test.go:1207: 04:25:11.050 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.170 - 90: DEBUG: new connection from 127.0.0.1:51964 424s app_test.go:1207: 04:25:11.171 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.219 - 90: DEBUG: new connection from 127.0.0.1:51966 424s app_test.go:1207: 04:25:11.221 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.278 - 90: DEBUG: new connection from 127.0.0.1:51982 424s app_test.go:1207: 04:25:11.279 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.335 - 90: DEBUG: new connection from 127.0.0.1:51988 424s app_test.go:1207: 04:25:11.336 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.065 - 90: DEBUG: new connection from 127.0.0.1:36626 424s app_test.go:1207: 04:25:11.066 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.082 - 90: DEBUG: new connection from 127.0.0.1:36628 424s app_test.go:1207: 04:25:11.085 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.091 - 90: DEBUG: new connection from 127.0.0.1:36632 424s app_test.go:1207: 04:25:11.091 - 91: DEBUG: new connection from 127.0.0.1:40568 424s app_test.go:1207: 04:25:11.092 - 92: DEBUG: new connection from 127.0.0.1:53684 424s app_test.go:1207: 04:25:11.095 - 93: DEBUG: new connection from 127.0.0.1:39086 424s app_test.go:1207: 04:25:11.100 - 95: DEBUG: new connection from 127.0.0.1:34884 424s app_test.go:1207: 04:25:11.101 - 96: DEBUG: new connection from 127.0.0.1:40574 424s app_test.go:1207: 04:25:11.104 - 97: DEBUG: new connection from 127.0.0.1:36640 424s app_test.go:1207: 04:25:11.105 - 98: DEBUG: new connection from 127.0.0.1:33910 424s app_test.go:1207: 04:25:11.995 - 90: DEBUG: new connection from 127.0.0.1:36634 424s app_test.go:1207: 04:25:11.997 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.055 - 90: DEBUG: new connection from 127.0.0.1:36644 424s app_test.go:1207: 04:25:11.057 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.176 - 90: DEBUG: new connection from 127.0.0.1:36656 424s app_test.go:1207: 04:25:11.178 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.224 - 90: DEBUG: new connection from 127.0.0.1:36670 424s app_test.go:1207: 04:25:11.226 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.284 - 90: DEBUG: new connection from 127.0.0.1:36674 424s app_test.go:1207: 04:25:11.285 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 424s app_test.go:1207: 04:25:11.326 - 98: DEBUG: new connection from 127.0.0.1:33916 424s app_test.go:1207: 04:25:11.326 - 96: DEBUG: new connection from 127.0.0.1:40576 424s app_test.go:1207: 04:25:11.326 - 90: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 424s app_test.go:1207: 04:25:11.326 - 95: DEBUG: new connection from 127.0.0.1:34900 424s app_test.go:1207: 04:25:11.326 - 97: DEBUG: new connection from 127.0.0.1:36656 424s app_test.go:1207: 04:25:11.327 - 90: DEBUG: new connection from 127.0.0.1:36676 424s app_test.go:1207: 04:25:11.327 - 93: DEBUG: new connection from 127.0.0.1:39100 424s app_test.go:1207: 04:25:11.327 - 92: DEBUG: new connection from 127.0.0.1:53690 424s app_test.go:1207: 04:25:11.328 - 91: DEBUG: new connection from 127.0.0.1:40578 424s app_test.go:1207: 04:25:11.332 - 90: WARN: attempt 1: server 127.0.0.1:9008: no known leader 424s app_test.go:1207: 04:25:11.333 - 90: WARN: attempt 1: server 127.0.0.1:9007: no known leader 424s app_test.go:1207: 04:25:11.334 - 90: WARN: attempt 1: server 127.0.0.1:9006: no known leader 424s app_test.go:1207: 04:25:11.334 - 90: WARN: attempt 1: server 127.0.0.1:9004: no known leader 424s app_test.go:1207: 04:25:11.335 - 90: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:25:11.335 - 90: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 424s app_test.go:1207: 04:25:11.335 - 90: WARN: attempt 1: server 127.0.0.1:9009: no known leader 424s app_test.go:1207: 04:25:11.335 - 90: DEBUG: new connection from 127.0.0.1:36692 424s app_test.go:1207: 04:25:11.335 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1207: 04:25:11.335 - 90: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: operation was canceled 424s app_test.go:1207: 04:25:11.335 - 90: DEBUG: new connection from 127.0.0.1:36694 424s --- PASS: TestRolesAdjustment_ReplaceStandByHonorFailureDomains (22.44s) 424s === RUN TestOpen 424s app_test.go:1207: 04:25:11.423 - 99: DEBUG: new connection from 127.0.0.1:50608 424s app_test.go:1207: 04:25:11.424 - 99: DEBUG: new connection from 127.0.0.1:50606 424s app_test.go:1207: 04:25:11.426 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 424s app_test.go:1207: 04:25:11.427 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 424s app_test.go:1207: 04:25:11.430 - 99: DEBUG: new connection from 127.0.0.1:50626 424s --- PASS: TestOpen (0.06s) 424s === RUN TestOpenDisk 424s app_test.go:1207: 04:25:11.476 - 100: DEBUG: new connection from 127.0.0.1:50638 424s app_test.go:1207: 04:25:11.476 - 100: DEBUG: new connection from 127.0.0.1:50640 424s app_test.go:1207: 04:25:11.478 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 424s app_test.go:1207: 04:25:11.479 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 424s app_test.go:1207: 04:25:11.483 - 100: DEBUG: new connection from 127.0.0.1:50648 424s --- PASS: TestOpenDisk (0.05s) 424s === RUN TestOptions 424s --- PASS: TestOptions (0.04s) 424s === RUN TestProxy_Error 424s app_test.go:1207: 04:25:11.577 - 102: DEBUG: new connection from 127.0.0.1:50650 424s app_test.go:1207: 04:25:11.578 - 102: DEBUG: new connection from 127.0.0.1:50666 424s app_test.go:1207: 04:25:11.580 - 102: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 424s app_test.go:1207: 04:25:11.582 - 102: DEBUG: new connection from 127.0.0.1:50676 424s --- PASS: TestProxy_Error (0.39s) 424s === RUN TestReady_Cancel 424s app_test.go:1207: 04:25:11.964 - 103: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 424s --- PASS: TestReady_Cancel (0.23s) 424s === RUN TestExternalConnWithTCP 424s app_test.go:1232: 04:25:11.202 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 424s app_test.go:1232: 04:25:11.226 - 105: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 424s app_test.go:1232: 04:25:11.251 - 106: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 424s app_test.go:1232: 04:25:11.181 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 424s --- PASS: TestExternalConnWithTCP (2.05s) 424s === RUN TestExternalConnWithPipe 424s app_test.go:1232: 04:25:11.246 - 107: DEBUG: attempt 1: server first: connected on fallback path 424s app_test.go:1232: 04:25:11.284 - 108: DEBUG: attempt 1: server first: connected on fallback path 424s app_test.go:1232: 04:25:11.315 - 109: DEBUG: attempt 1: server first: connected on fallback path 424s app_test.go:1232: 04:26:11.229 - 107: DEBUG: attempt 1: server first: connected on fallback path 424s --- PASS: TestExternalConnWithPipe (2.05s) 424s === RUN TestParallelNewApp 424s === PAUSE TestParallelNewApp 424s === RUN Test_TxRowsAffected 424s app_test.go:1232: 04:26:11.289 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s app_test.go:1232: 04:26:11.294 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 424s --- PASS: Test_TxRowsAffected (0.06s) 424s === CONT TestParallelNewApp 424s === RUN TestParallelNewApp/run-0 424s === PAUSE TestParallelNewApp/run-0 424s === RUN TestParallelNewApp/run-1 424s === PAUSE TestParallelNewApp/run-1 424s === RUN TestParallelNewApp/run-2 424s === PAUSE TestParallelNewApp/run-2 424s === RUN TestParallelNewApp/run-3 424s === PAUSE TestParallelNewApp/run-3 424s === RUN TestParallelNewApp/run-4 424s === PAUSE TestParallelNewApp/run-4 424s === RUN TestParallelNewApp/run-5 424s === PAUSE TestParallelNewApp/run-5 424s === RUN TestParallelNewApp/run-6 424s === PAUSE TestParallelNewApp/run-6 424s === RUN TestParallelNewApp/run-7 424s === PAUSE TestParallelNewApp/run-7 424s === RUN TestParallelNewApp/run-8 424s === PAUSE TestParallelNewApp/run-8 424s === RUN TestParallelNewApp/run-9 424s === PAUSE TestParallelNewApp/run-9 424s === RUN TestParallelNewApp/run-10 424s === PAUSE TestParallelNewApp/run-10 424s === RUN TestParallelNewApp/run-11 424s === PAUSE TestParallelNewApp/run-11 424s === RUN TestParallelNewApp/run-12 424s === PAUSE TestParallelNewApp/run-12 424s === RUN TestParallelNewApp/run-13 424s === PAUSE TestParallelNewApp/run-13 424s === RUN TestParallelNewApp/run-14 424s === PAUSE TestParallelNewApp/run-14 424s === RUN TestParallelNewApp/run-15 424s === PAUSE TestParallelNewApp/run-15 424s === RUN TestParallelNewApp/run-16 424s === PAUSE TestParallelNewApp/run-16 424s === RUN TestParallelNewApp/run-17 424s === PAUSE TestParallelNewApp/run-17 424s === RUN TestParallelNewApp/run-18 424s === PAUSE TestParallelNewApp/run-18 424s === RUN TestParallelNewApp/run-19 424s === PAUSE TestParallelNewApp/run-19 424s === RUN TestParallelNewApp/run-20 424s === PAUSE TestParallelNewApp/run-20 424s === RUN TestParallelNewApp/run-21 424s === PAUSE TestParallelNewApp/run-21 424s === RUN TestParallelNewApp/run-22 424s === PAUSE TestParallelNewApp/run-22 424s === RUN TestParallelNewApp/run-23 424s === PAUSE TestParallelNewApp/run-23 424s === RUN TestParallelNewApp/run-24 424s === PAUSE TestParallelNewApp/run-24 424s === RUN TestParallelNewApp/run-25 424s === PAUSE TestParallelNewApp/run-25 424s === RUN TestParallelNewApp/run-26 424s === PAUSE TestParallelNewApp/run-26 424s === RUN TestParallelNewApp/run-27 424s === PAUSE TestParallelNewApp/run-27 424s === RUN TestParallelNewApp/run-28 424s === PAUSE TestParallelNewApp/run-28 424s === RUN TestParallelNewApp/run-29 424s === PAUSE TestParallelNewApp/run-29 424s === RUN TestParallelNewApp/run-30 424s === PAUSE TestParallelNewApp/run-30 424s === RUN TestParallelNewApp/run-31 424s === PAUSE TestParallelNewApp/run-31 424s === RUN TestParallelNewApp/run-32 424s === PAUSE TestParallelNewApp/run-32 424s === RUN TestParallelNewApp/run-33 424s === PAUSE TestParallelNewApp/run-33 424s === RUN TestParallelNewApp/run-34 424s === PAUSE TestParallelNewApp/run-34 424s === RUN TestParallelNewApp/run-35 424s === PAUSE TestParallelNewApp/run-35 424s === RUN TestParallelNewApp/run-36 424s === PAUSE TestParallelNewApp/run-36 424s === RUN TestParallelNewApp/run-37 424s === PAUSE TestParallelNewApp/run-37 424s === RUN TestParallelNewApp/run-38 424s === PAUSE TestParallelNewApp/run-38 424s === RUN TestParallelNewApp/run-39 424s === PAUSE TestParallelNewApp/run-39 424s === RUN TestParallelNewApp/run-40 424s === PAUSE TestParallelNewApp/run-40 424s === RUN TestParallelNewApp/run-41 424s === PAUSE TestParallelNewApp/run-41 424s === RUN TestParallelNewApp/run-42 424s === PAUSE TestParallelNewApp/run-42 424s === RUN TestParallelNewApp/run-43 424s === PAUSE TestParallelNewApp/run-43 424s === RUN TestParallelNewApp/run-44 424s === PAUSE TestParallelNewApp/run-44 424s === RUN TestParallelNewApp/run-45 424s === PAUSE TestParallelNewApp/run-45 424s === RUN TestParallelNewApp/run-46 424s === PAUSE TestParallelNewApp/run-46 424s === RUN TestParallelNewApp/run-47 424s === PAUSE TestParallelNewApp/run-47 424s === RUN TestParallelNewApp/run-48 424s === PAUSE TestParallelNewApp/run-48 424s === RUN TestParallelNewApp/run-49 424s === PAUSE TestParallelNewApp/run-49 424s === RUN TestParallelNewApp/run-50 424s === PAUSE TestParallelNewApp/run-50 424s === RUN TestParallelNewApp/run-51 424s === PAUSE TestParallelNewApp/run-51 424s === RUN TestParallelNewApp/run-52 424s === PAUSE TestParallelNewApp/run-52 424s === RUN TestParallelNewApp/run-53 424s === PAUSE TestParallelNewApp/run-53 424s === RUN TestParallelNewApp/run-54 424s === PAUSE TestParallelNewApp/run-54 424s === RUN TestParallelNewApp/run-55 424s === PAUSE TestParallelNewApp/run-55 424s === RUN TestParallelNewApp/run-56 424s === PAUSE TestParallelNewApp/run-56 424s === RUN TestParallelNewApp/run-57 424s === PAUSE TestParallelNewApp/run-57 424s === RUN TestParallelNewApp/run-58 424s === PAUSE TestParallelNewApp/run-58 424s === RUN TestParallelNewApp/run-59 424s === PAUSE TestParallelNewApp/run-59 424s === RUN TestParallelNewApp/run-60 424s === PAUSE TestParallelNewApp/run-60 424s === RUN TestParallelNewApp/run-61 424s === PAUSE TestParallelNewApp/run-61 424s === RUN TestParallelNewApp/run-62 424s === PAUSE TestParallelNewApp/run-62 424s === RUN TestParallelNewApp/run-63 424s === PAUSE TestParallelNewApp/run-63 424s === RUN TestParallelNewApp/run-64 424s === PAUSE TestParallelNewApp/run-64 424s === RUN TestParallelNewApp/run-65 424s === PAUSE TestParallelNewApp/run-65 424s === RUN TestParallelNewApp/run-66 424s === PAUSE TestParallelNewApp/run-66 424s === RUN TestParallelNewApp/run-67 424s === PAUSE TestParallelNewApp/run-67 424s === RUN TestParallelNewApp/run-68 424s === PAUSE TestParallelNewApp/run-68 424s === RUN TestParallelNewApp/run-69 424s === PAUSE TestParallelNewApp/run-69 424s === RUN TestParallelNewApp/run-70 424s === PAUSE TestParallelNewApp/run-70 424s === RUN TestParallelNewApp/run-71 424s === PAUSE TestParallelNewApp/run-71 424s === RUN TestParallelNewApp/run-72 424s === PAUSE TestParallelNewApp/run-72 424s === RUN TestParallelNewApp/run-73 424s === PAUSE TestParallelNewApp/run-73 424s === RUN TestParallelNewApp/run-74 424s === PAUSE TestParallelNewApp/run-74 424s === RUN TestParallelNewApp/run-75 424s === PAUSE TestParallelNewApp/run-75 424s === RUN TestParallelNewApp/run-76 424s === PAUSE TestParallelNewApp/run-76 424s === RUN TestParallelNewApp/run-77 424s === PAUSE TestParallelNewApp/run-77 424s === RUN TestParallelNewApp/run-78 424s === PAUSE TestParallelNewApp/run-78 424s === RUN TestParallelNewApp/run-79 424s === PAUSE TestParallelNewApp/run-79 424s === RUN TestParallelNewApp/run-80 424s === PAUSE TestParallelNewApp/run-80 424s === RUN TestParallelNewApp/run-81 424s === PAUSE TestParallelNewApp/run-81 424s === RUN TestParallelNewApp/run-82 424s === PAUSE TestParallelNewApp/run-82 424s === RUN TestParallelNewApp/run-83 424s === PAUSE TestParallelNewApp/run-83 424s === RUN TestParallelNewApp/run-84 424s === PAUSE TestParallelNewApp/run-84 424s === RUN TestParallelNewApp/run-85 424s === PAUSE TestParallelNewApp/run-85 424s === RUN TestParallelNewApp/run-86 424s === PAUSE TestParallelNewApp/run-86 424s === RUN TestParallelNewApp/run-87 424s === PAUSE TestParallelNewApp/run-87 424s === RUN TestParallelNewApp/run-88 424s === PAUSE TestParallelNewApp/run-88 424s === RUN TestParallelNewApp/run-89 424s === PAUSE TestParallelNewApp/run-89 424s === RUN TestParallelNewApp/run-90 424s === PAUSE TestParallelNewApp/run-90 424s === RUN TestParallelNewApp/run-91 424s === PAUSE TestParallelNewApp/run-91 424s === RUN TestParallelNewApp/run-92 424s === PAUSE TestParallelNewApp/run-92 424s === RUN TestParallelNewApp/run-93 424s === PAUSE TestParallelNewApp/run-93 424s === RUN TestParallelNewApp/run-94 424s === PAUSE TestParallelNewApp/run-94 424s === RUN TestParallelNewApp/run-95 424s === PAUSE TestParallelNewApp/run-95 424s === RUN TestParallelNewApp/run-96 424s === PAUSE TestParallelNewApp/run-96 424s === RUN TestParallelNewApp/run-97 424s === PAUSE TestParallelNewApp/run-97 424s === RUN TestParallelNewApp/run-98 424s === PAUSE TestParallelNewApp/run-98 424s === RUN TestParallelNewApp/run-99 424s === PAUSE TestParallelNewApp/run-99 424s === CONT TestParallelNewApp/run-0 424s === CONT TestParallelNewApp/run-50 424s === CONT TestParallelNewApp/run-48 424s === CONT TestParallelNewApp/run-49 424s === CONT TestParallelNewApp/run-47 424s === CONT TestParallelNewApp/run-46 424s === CONT TestParallelNewApp/run-45 424s === CONT TestParallelNewApp/run-44 424s === CONT TestParallelNewApp/run-43 424s === CONT TestParallelNewApp/run-42 424s === CONT TestParallelNewApp/run-41 424s === CONT TestParallelNewApp/run-40 424s === CONT TestParallelNewApp/run-39 424s === CONT TestParallelNewApp/run-38 424s === CONT TestParallelNewApp/run-37 424s === CONT TestParallelNewApp/run-36 424s === CONT TestParallelNewApp/run-35 424s === CONT TestParallelNewApp/run-34 424s === CONT TestParallelNewApp/run-33 424s === CONT TestParallelNewApp/run-26 424s === CONT TestParallelNewApp/run-32 424s === CONT TestParallelNewApp/run-31 424s === CONT TestParallelNewApp/run-30 424s === CONT TestParallelNewApp/run-99 424s === CONT TestParallelNewApp/run-98 424s === CONT TestParallelNewApp/run-97 424s === CONT TestParallelNewApp/run-96 424s === CONT TestParallelNewApp/run-95 424s === CONT TestParallelNewApp/run-94 424s === CONT TestParallelNewApp/run-93 424s === CONT TestParallelNewApp/run-92 424s === CONT TestParallelNewApp/run-91 424s === CONT TestParallelNewApp/run-90 424s === CONT TestParallelNewApp/run-89 424s === CONT TestParallelNewApp/run-88 424s === CONT TestParallelNewApp/run-87 424s === CONT TestParallelNewApp/run-86 424s === CONT TestParallelNewApp/run-85 424s === CONT TestParallelNewApp/run-84 424s === CONT TestParallelNewApp/run-83 424s === CONT TestParallelNewApp/run-82 424s === CONT TestParallelNewApp/run-81 424s === CONT TestParallelNewApp/run-80 424s === CONT TestParallelNewApp/run-79 424s === CONT TestParallelNewApp/run-78 424s === CONT TestParallelNewApp/run-77 424s === CONT TestParallelNewApp/run-76 424s === CONT TestParallelNewApp/run-75 424s === CONT TestParallelNewApp/run-74 424s === CONT TestParallelNewApp/run-73 424s === CONT TestParallelNewApp/run-72 424s === CONT TestParallelNewApp/run-71 424s === CONT TestParallelNewApp/run-70 424s === CONT TestParallelNewApp/run-69 424s === CONT TestParallelNewApp/run-68 424s === CONT TestParallelNewApp/run-67 424s === CONT TestParallelNewApp/run-66 424s === CONT TestParallelNewApp/run-65 424s === CONT TestParallelNewApp/run-64 424s === CONT TestParallelNewApp/run-63 424s === CONT TestParallelNewApp/run-62 424s === CONT TestParallelNewApp/run-61 424s === CONT TestParallelNewApp/run-60 424s === CONT TestParallelNewApp/run-59 424s === CONT TestParallelNewApp/run-58 424s === CONT TestParallelNewApp/run-57 424s === CONT TestParallelNewApp/run-56 424s === CONT TestParallelNewApp/run-55 424s === CONT TestParallelNewApp/run-54 424s === CONT TestParallelNewApp/run-53 424s === CONT TestParallelNewApp/run-52 424s === CONT TestParallelNewApp/run-51 424s === CONT TestParallelNewApp/run-15 424s === CONT TestParallelNewApp/run-29 424s === CONT TestParallelNewApp/run-28 424s === CONT TestParallelNewApp/run-27 424s === CONT TestParallelNewApp/run-25 424s === CONT TestParallelNewApp/run-24 424s === CONT TestParallelNewApp/run-23 424s === CONT TestParallelNewApp/run-22 424s === CONT TestParallelNewApp/run-21 424s === CONT TestParallelNewApp/run-20 424s === CONT TestParallelNewApp/run-19 424s === CONT TestParallelNewApp/run-18 424s === CONT TestParallelNewApp/run-17 424s === CONT TestParallelNewApp/run-16 424s === CONT TestParallelNewApp/run-8 424s === CONT TestParallelNewApp/run-14 424s === CONT TestParallelNewApp/run-13 424s === CONT TestParallelNewApp/run-12 424s === CONT TestParallelNewApp/run-11 424s === CONT TestParallelNewApp/run-10 424s === CONT TestParallelNewApp/run-9 424s === CONT TestParallelNewApp/run-4 424s === CONT TestParallelNewApp/run-7 424s === CONT TestParallelNewApp/run-6 424s === CONT TestParallelNewApp/run-5 424s === CONT TestParallelNewApp/run-2 424s === CONT TestParallelNewApp/run-3 424s === CONT TestParallelNewApp/run-1 424s --- PASS: TestParallelNewApp (0.00s) 424s --- PASS: TestParallelNewApp/run-50 (0.04s) 424s --- PASS: TestParallelNewApp/run-0 (0.04s) 424s --- PASS: TestParallelNewApp/run-48 (0.04s) 424s --- PASS: TestParallelNewApp/run-49 (0.05s) 424s --- PASS: TestParallelNewApp/run-47 (0.05s) 424s --- PASS: TestParallelNewApp/run-46 (0.04s) 424s --- PASS: TestParallelNewApp/run-45 (0.03s) 424s --- PASS: TestParallelNewApp/run-44 (0.04s) 424s --- PASS: TestParallelNewApp/run-43 (0.04s) 424s --- PASS: TestParallelNewApp/run-42 (0.03s) 424s --- PASS: TestParallelNewApp/run-40 (0.04s) 424s --- PASS: TestParallelNewApp/run-41 (0.25s) 424s --- PASS: TestParallelNewApp/run-39 (0.23s) 424s --- PASS: TestParallelNewApp/run-38 (0.04s) 424s --- PASS: TestParallelNewApp/run-37 (0.04s) 424s --- PASS: TestParallelNewApp/run-36 (0.04s) 424s --- PASS: TestParallelNewApp/run-35 (0.02s) 424s --- PASS: TestParallelNewApp/run-34 (0.03s) 424s --- PASS: TestParallelNewApp/run-33 (0.02s) 424s --- PASS: TestParallelNewApp/run-26 (0.02s) 424s --- PASS: TestParallelNewApp/run-32 (0.02s) 424s --- PASS: TestParallelNewApp/run-31 (0.02s) 424s --- PASS: TestParallelNewApp/run-30 (0.02s) 424s --- PASS: TestParallelNewApp/run-98 (0.03s) 424s --- PASS: TestParallelNewApp/run-99 (0.22s) 424s --- PASS: TestParallelNewApp/run-96 (0.03s) 424s --- PASS: TestParallelNewApp/run-97 (0.23s) 424s --- PASS: TestParallelNewApp/run-95 (0.03s) 424s --- PASS: TestParallelNewApp/run-94 (0.02s) 424s --- PASS: TestParallelNewApp/run-93 (0.22s) 424s --- PASS: TestParallelNewApp/run-92 (0.23s) 424s --- PASS: TestParallelNewApp/run-91 (0.03s) 424s --- PASS: TestParallelNewApp/run-90 (0.04s) 424s --- PASS: TestParallelNewApp/run-89 (0.04s) 424s --- PASS: TestParallelNewApp/run-88 (0.02s) 424s --- PASS: TestParallelNewApp/run-87 (0.02s) 424s --- PASS: TestParallelNewApp/run-86 (0.22s) 424s --- PASS: TestParallelNewApp/run-85 (0.23s) 424s --- PASS: TestParallelNewApp/run-83 (0.03s) 424s --- PASS: TestParallelNewApp/run-84 (0.23s) 424s --- PASS: TestParallelNewApp/run-81 (0.03s) 424s --- PASS: TestParallelNewApp/run-82 (0.23s) 424s --- PASS: TestParallelNewApp/run-80 (0.03s) 424s --- PASS: TestParallelNewApp/run-79 (0.02s) 424s --- PASS: TestParallelNewApp/run-78 (0.02s) 424s --- PASS: TestParallelNewApp/run-77 (0.02s) 424s --- PASS: TestParallelNewApp/run-76 (0.02s) 424s --- PASS: TestParallelNewApp/run-74 (0.03s) 424s --- PASS: TestParallelNewApp/run-75 (0.22s) 424s --- PASS: TestParallelNewApp/run-73 (0.43s) 424s --- PASS: TestParallelNewApp/run-72 (0.43s) 424s --- PASS: TestParallelNewApp/run-70 (0.03s) 424s --- PASS: TestParallelNewApp/run-71 (0.23s) 424s --- PASS: TestParallelNewApp/run-69 (0.03s) 424s --- PASS: TestParallelNewApp/run-68 (0.02s) 424s --- PASS: TestParallelNewApp/run-67 (0.02s) 424s --- PASS: TestParallelNewApp/run-66 (0.22s) 424s --- PASS: TestParallelNewApp/run-65 (0.23s) 424s --- PASS: TestParallelNewApp/run-64 (0.23s) 424s --- PASS: TestParallelNewApp/run-63 (0.22s) 424s --- PASS: TestParallelNewApp/run-62 (0.02s) 424s --- PASS: TestParallelNewApp/run-61 (0.22s) 424s --- PASS: TestParallelNewApp/run-60 (0.23s) 424s --- PASS: TestParallelNewApp/run-59 (0.03s) 424s --- PASS: TestParallelNewApp/run-58 (0.03s) 424s --- PASS: TestParallelNewApp/run-57 (0.02s) 424s --- PASS: TestParallelNewApp/run-56 (0.02s) 424s --- PASS: TestParallelNewApp/run-55 (0.02s) 424s --- PASS: TestParallelNewApp/run-54 (0.02s) 424s --- PASS: TestParallelNewApp/run-53 (0.02s) 424s --- PASS: TestParallelNewApp/run-52 (0.02s) 424s --- PASS: TestParallelNewApp/run-51 (0.02s) 424s --- PASS: TestParallelNewApp/run-15 (0.02s) 424s --- PASS: TestParallelNewApp/run-29 (0.02s) 424s --- PASS: TestParallelNewApp/run-28 (0.02s) 424s --- PASS: TestParallelNewApp/run-27 (0.02s) 424s --- PASS: TestParallelNewApp/run-25 (0.02s) 424s --- PASS: TestParallelNewApp/run-24 (0.02s) 424s --- PASS: TestParallelNewApp/run-23 (0.02s) 424s --- PASS: TestParallelNewApp/run-22 (0.02s) 424s --- PASS: TestParallelNewApp/run-21 (0.02s) 424s --- PASS: TestParallelNewApp/run-20 (0.02s) 424s --- PASS: TestParallelNewApp/run-19 (0.02s) 424s --- PASS: TestParallelNewApp/run-18 (0.02s) 424s --- PASS: TestParallelNewApp/run-17 (0.02s) 424s --- PASS: TestParallelNewApp/run-16 (0.02s) 424s --- PASS: TestParallelNewApp/run-8 (0.02s) 424s --- PASS: TestParallelNewApp/run-14 (0.02s) 424s --- PASS: TestParallelNewApp/run-13 (0.02s) 424s --- PASS: TestParallelNewApp/run-12 (0.02s) 424s --- PASS: TestParallelNewApp/run-10 (0.02s) 424s --- PASS: TestParallelNewApp/run-11 (0.22s) 424s --- PASS: TestParallelNewApp/run-4 (0.04s) 424s --- PASS: TestParallelNewApp/run-9 (0.24s) 424s --- PASS: TestParallelNewApp/run-6 (0.03s) 424s --- PASS: TestParallelNewApp/run-5 (0.03s) 424s --- PASS: TestParallelNewApp/run-2 (0.04s) 424s --- PASS: TestParallelNewApp/run-3 (0.04s) 424s --- PASS: TestParallelNewApp/run-1 (0.03s) 424s --- PASS: TestParallelNewApp/run-7 (0.22s) 424s === RUN Example 424s --- PASS: Example (0.06s) 424s PASS 424s ok github.com/canonical/go-dqlite/app 164.702s 433s === RUN TestNew_Default 433s Benchmark done. Results available here: 433s /tmp/dqlite-app-test-3971034970/results 433s --- PASS: TestNew_Default (1.07s) 433s === RUN TestNew_KvReadWrite 433s Benchmark done. Results available here: 433s /tmp/dqlite-app-test-2171140375/results 433s --- PASS: TestNew_KvReadWrite (1.09s) 433s === RUN TestNew_ClusteredKvReadWrite 433s Benchmark done. Results available here: 433s /tmp/dqlite-app-test-1985273437/results 433s --- PASS: TestNew_ClusteredKvReadWrite (4.05s) 433s === RUN TestNew_ClusteredTimeout 433s --- PASS: TestNew_ClusteredTimeout (2.08s) 433s PASS 433s ok github.com/canonical/go-dqlite/benchmark 8.323s 436s === RUN TestClient_Leader 436s --- PASS: TestClient_Leader (0.04s) 436s === RUN TestClient_Cluster 436s --- PASS: TestClient_Cluster (0.02s) 436s === RUN TestClient_Transfer 436s --- PASS: TestClient_Transfer (1.03s) 436s === RUN TestClient_Describe 436s --- PASS: TestClient_Describe (0.02s) 436s === RUN TestMembership 436s --- PASS: TestMembership (1.03s) 436s === RUN TestDefaultNodeStore 436s --- PASS: TestDefaultNodeStore (0.00s) 436s === RUN TestConfigMultiThread 436s --- PASS: TestConfigMultiThread (0.00s) 436s PASS 436s ok github.com/canonical/go-dqlite/client 2.157s 436s ? github.com/canonical/go-dqlite/cmd/dqlite [no test files] 436s ? github.com/canonical/go-dqlite/cmd/dqlite-benchmark [no test files] 436s ? github.com/canonical/go-dqlite/cmd/dqlite-demo [no test files] 464s === RUN TestDriver_Open 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestDriver_Open (0.04s) 464s === RUN TestDriver_Prepare 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestDriver_Prepare (0.02s) 464s === RUN TestConn_Exec 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestConn_Exec (0.02s) 464s === RUN TestConn_Query 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestConn_Query (0.02s) 464s === RUN TestConn_QueryRow 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestConn_QueryRow (0.02s) 464s === RUN TestConn_InterruptQuery 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestConn_InterruptQuery (0.21s) 464s === RUN TestConn_QueryBlob 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestConn_QueryBlob (0.03s) 464s === RUN TestStmt_Exec 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestStmt_Exec (0.02s) 464s === RUN TestStmt_ExecManyParams 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestStmt_ExecManyParams (0.02s) 464s === RUN TestStmt_Query 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestStmt_Query (0.02s) 464s === RUN TestStmt_QueryManyParams 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestStmt_QueryManyParams (0.02s) 464s === RUN TestConn_QueryParams 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestConn_QueryParams (0.02s) 464s === RUN TestConn_QueryManyParams 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestConn_QueryManyParams (0.03s) 464s === RUN TestConn_ExecManyParams 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestConn_ExecManyParams (0.02s) 464s === RUN Test_ColumnTypesEmpty 464s driver_test.go:472: this currently fails if the result set is empty, is dqlite skipping the header if empty set? 464s --- SKIP: Test_ColumnTypesEmpty (0.00s) 464s === RUN Test_ColumnTypesExists 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: Test_ColumnTypesExists (0.02s) 464s === RUN Test_ColumnTypesEnd 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: Test_ColumnTypesEnd (0.02s) 464s === RUN Test_ZeroColumns 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: Test_ZeroColumns (0.04s) 464s === RUN Test_DescribeLastEntry 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: Test_DescribeLastEntry (0.71s) 464s === RUN Test_Dump 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: Test_Dump (0.05s) 464s === RUN TestIntegration_DatabaseSQL 464s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 1: server @1: context canceled 464s --- PASS: TestIntegration_DatabaseSQL (2.05s) 464s === RUN TestIntegration_ConstraintError 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 1: server @1: context canceled 464s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 464s func.go:15: WARN: attempt 1: server @1: context canceled 464s --- PASS: TestIntegration_ConstraintError (2.05s) 464s === RUN TestIntegration_ExecBindError 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s integration_test.go:116: 464s Error Trace: /tmp/autopkgtest.hMGXfC/autopkgtest_tmp/_build/src/github.com/canonical/go-dqlite/driver/integration_test.go:116 464s Error: An error is expected but got nil. 464s Test: TestIntegration_ExecBindError 464s --- FAIL: TestIntegration_ExecBindError (0.06s) 464s === RUN TestIntegration_QueryBindError 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestIntegration_QueryBindError (0.02s) 464s === RUN TestIntegration_LargeQuery 464s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 1: server @1: context canceled 464s --- PASS: TestIntegration_LargeQuery (2.08s) 464s === RUN TestIntegration_Recover 464s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 464s func.go:15: DEBUG: attempt 1: server @1: connected on fast path 464s --- PASS: TestIntegration_Recover (1.12s) 464s === RUN TestIntegration_PingOnlyWorksOnceLeaderElected 464s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: WARN: attempt 1: server @1: no known leader 464s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 1: server @1: reported leader server is not the leader 464s func.go:15: WARN: attempt 2: server @1: no known leader 464s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 2: server @1: reported leader server is not the leader 464s func.go:15: WARN: attempt 3: server @1: no known leader 464s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 3: server @1: reported leader server is not the leader 464s func.go:15: DEBUG: attempt 4: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 4: server @1: no known leader 464s func.go:15: WARN: attempt 4: server @1: reported leader server is not the leader 464s func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 5: server @1: no known leader 464s func.go:15: WARN: attempt 5: server @1: reported leader server is not the leader 464s func.go:15: DEBUG: attempt 6: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 6: server @1: no known leader 464s func.go:15: WARN: attempt 6: server @1: reported leader server is not the leader 464s func.go:15: WARN: attempt 7: server @2: no known leader 464s func.go:15: WARN: attempt 7: server @1: no known leader 464s func.go:15: WARN: attempt 8: server @2: no known leader 464s func.go:15: WARN: attempt 8: server @1: no known leader 464s func.go:15: DEBUG: attempt 9: server @2: connect to reported leader @1 464s func.go:15: DEBUG: attempt 9: server @1: connected on fallback path 464s --- PASS: TestIntegration_PingOnlyWorksOnceLeaderElected (8.87s) 464s === RUN TestIntegration_HighAvailability 464s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 464s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 464s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: WARN: attempt 1: server @3: no known leader 464s func.go:15: WARN: attempt 1: server @2: no known leader 464s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: WARN: attempt 2: server @2: no known leader 464s func.go:15: WARN: attempt 2: server @3: no known leader 464s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: WARN: attempt 3: server @3: no known leader 464s func.go:15: WARN: attempt 3: server @2: no known leader 464s func.go:15: WARN: attempt 4: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: WARN: attempt 4: server @2: no known leader 464s func.go:15: WARN: attempt 4: server @3: no known leader 464s func.go:15: WARN: attempt 5: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: WARN: attempt 5: server @3: no known leader 464s func.go:15: WARN: attempt 5: server @2: no known leader 464s func.go:15: WARN: attempt 6: server @1: dial: dial unix @1: connect: connection refused 464s func.go:15: DEBUG: attempt 6: server @2: connect to reported leader @3 464s func.go:15: DEBUG: attempt 6: server @3: connected on fallback path 464s --- PASS: TestIntegration_HighAvailability (7.53s) 464s === RUN TestIntegration_LeadershipTransfer 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 464s func.go:15: WARN: attempt 1: server @1: context canceled 464s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 1: server @1: context canceled 464s func.go:15: DEBUG: leadership lost (10250 - not leader) 464s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 464s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 464s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 464s func.go:15: WARN: attempt 1: server @2: context canceled 464s --- PASS: TestIntegration_LeadershipTransfer (2.05s) 464s === RUN TestIntegration_LeadershipTransfer_Tx 464s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 464s func.go:15: WARN: attempt 1: server @1: context canceled 464s func.go:15: WARN: attempt 1: server @3: no known leader 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s func.go:15: DEBUG: leadership lost (10250 - not leader) 464s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 464s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 464s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 464s --- PASS: TestIntegration_LeadershipTransfer_Tx (2.06s) 464s === RUN TestOptions 464s --- PASS: TestOptions (0.00s) 464s === RUN TestIntegration_ColumnTypeName 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestIntegration_ColumnTypeName (0.04s) 464s === RUN TestIntegration_SqlNullTime 464s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 464s --- PASS: TestIntegration_SqlNullTime (0.04s) 464s FAIL 464s FAIL github.com/canonical/go-dqlite/driver 29.358s 464s === RUN TestNode_Create 464s --- PASS: TestNode_Create (0.03s) 464s === RUN TestNode_Start 464s --- PASS: TestNode_Start (0.02s) 464s === RUN TestNode_Restart 464s --- PASS: TestNode_Restart (0.04s) 464s === RUN TestNode_Start_Inet 464s --- PASS: TestNode_Start_Inet (0.03s) 464s === RUN TestNode_Leader 464s --- PASS: TestNode_Leader (0.03s) 464s === RUN TestNode_Autorecovery 464s --- PASS: TestNode_Autorecovery (0.01s) 464s PASS 464s ok github.com/canonical/go-dqlite/internal/bindings 0.170s 464s === RUN TestMessage_StaticBytesAlignment 464s --- PASS: TestMessage_StaticBytesAlignment (0.00s) 464s === RUN TestMessage_putBlob 464s === RUN TestMessage_putBlob/16 464s === RUN TestMessage_putBlob/16#01 464s === RUN TestMessage_putBlob/24 464s --- PASS: TestMessage_putBlob (0.00s) 464s --- PASS: TestMessage_putBlob/16 (0.00s) 464s --- PASS: TestMessage_putBlob/16#01 (0.00s) 464s --- PASS: TestMessage_putBlob/24 (0.00s) 464s === RUN TestMessage_putString 464s === RUN TestMessage_putString/hello 464s === RUN TestMessage_putString/hello!! 464s === RUN TestMessage_putString/hello_world 464s --- PASS: TestMessage_putString (0.00s) 464s --- PASS: TestMessage_putString/hello (0.00s) 464s --- PASS: TestMessage_putString/hello!! (0.00s) 464s --- PASS: TestMessage_putString/hello_world (0.00s) 464s === RUN TestMessage_putUint8 464s --- PASS: TestMessage_putUint8 (0.00s) 464s === RUN TestMessage_putUint16 464s --- PASS: TestMessage_putUint16 (0.00s) 464s === RUN TestMessage_putUint32 464s --- PASS: TestMessage_putUint32 (0.00s) 464s === RUN TestMessage_putUint64 464s --- PASS: TestMessage_putUint64 (0.00s) 464s === RUN TestMessage_putNamedValues 464s --- PASS: TestMessage_putNamedValues (0.00s) 464s === RUN TestMessage_putNamedValues32 464s --- PASS: TestMessage_putNamedValues32 (0.00s) 464s === RUN TestMessage_putHeader 464s --- PASS: TestMessage_putHeader (0.00s) 464s === RUN TestMessage_getString 464s === RUN TestMessage_getString/hello 464s === RUN TestMessage_getString/hello!! 464s === RUN TestMessage_getString/hello!!! 464s === RUN TestMessage_getString/hello_world 464s --- PASS: TestMessage_getString (0.00s) 464s --- PASS: TestMessage_getString/hello (0.00s) 464s --- PASS: TestMessage_getString/hello!! (0.00s) 464s --- PASS: TestMessage_getString/hello!!! (0.00s) 464s --- PASS: TestMessage_getString/hello_world (0.00s) 464s === RUN TestMessage_getBlob 464s === RUN TestMessage_getBlob/16 464s === RUN TestMessage_getBlob/16#01 464s === RUN TestMessage_getBlob/24 464s --- PASS: TestMessage_getBlob (0.00s) 464s --- PASS: TestMessage_getBlob/16 (0.00s) 464s --- PASS: TestMessage_getBlob/16#01 (0.00s) 464s --- PASS: TestMessage_getBlob/24 (0.00s) 464s === RUN TestMessage_getString_Overflow_WordBoundary 464s --- PASS: TestMessage_getString_Overflow_WordBoundary (0.00s) 464s === RUN TestConnector_Success 464s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 464s --- PASS: TestConnector_Success (0.04s) 464s === RUN TestConnector_LeaderTracker 464s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 464s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 464s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 464s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 464s connector_test.go:398: DEBUG: reusing shared connection to @test-0 464s connector_test.go:398: DEBUG: discarding shared connection to @test-0 464s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 464s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 464s connector_test.go:398: DEBUG: reusing shared connection to @test-0 464s connector_test.go:398: DEBUG: discarding shared connection to @test-0 464s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 464s --- PASS: TestConnector_LeaderTracker (0.83s) 464s === RUN TestConnector_LimitRetries 464s connector_test.go:398: WARN: attempt 1: server @test-123: dial: dial unix @test-123: connect: connection refused 464s connector_test.go:398: WARN: attempt 2: server @test-123: dial: dial unix @test-123: connect: connection refused 464s connector_test.go:398: WARN: attempt 3: server @test-123: dial: dial unix @test-123: connect: connection refused 464s --- PASS: TestConnector_LimitRetries (0.60s) 464s === RUN TestConnector_DialTimeout 464s connector_test.go:158: Skipping network test 464s --- SKIP: TestConnector_DialTimeout (0.00s) 464s === RUN TestConnector_EmptyNodeStore 464s --- PASS: TestConnector_EmptyNodeStore (0.20s) 464s === RUN TestConnector_ContextCanceled 464s connector_test.go:193: Skipping network test 464s --- SKIP: TestConnector_ContextCanceled (0.00s) 464s === RUN TestConnector_AttemptTimeout 464s func.go:15: WARN: attempt 1: server @1234: call leader (budget 99.912119ms): receive: header: read unix @->@1234: i/o timeout 464s func.go:15: WARN: attempt 2: server @1234: call leader (budget 99.878079ms): receive: header: read unix @->@1234: i/o timeout 464s --- PASS: TestConnector_AttemptTimeout (0.40s) 464s === RUN TestProtocol_RequestWithDynamicBuffer 464s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 464s --- PASS: TestProtocol_RequestWithDynamicBuffer (0.07s) 464s === RUN TestProtocol_Prepare 464s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 464s --- PASS: TestProtocol_Prepare (0.03s) 464s PASS 464s ok github.com/canonical/go-dqlite/internal/protocol 2.186s 464s ? github.com/canonical/go-dqlite/internal/shell [no test files] 464s === RUN Test_TestFunc 464s func.go:15: INFO: hello 464s --- PASS: Test_TestFunc (0.00s) 464s === RUN TestLevel_String 464s --- PASS: TestLevel_String (0.00s) 464s PASS 464s ok github.com/canonical/go-dqlite/logging 0.013s 464s ? github.com/canonical/go-dqlite/tracing [no test files] 464s FAIL 464s 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 464s make: *** [debian/rules:11: autopkgtest] Error 25 464s autopkgtest [04:26:44]: test dh-golang-autopkgtest: -----------------------] 465s autopkgtest [04:26:45]: test dh-golang-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 465s dh-golang-autopkgtest FLAKY non-zero exit status 2 465s autopkgtest [04:26:45]: @@@@@@@@@@@@@@@@@@@@ summary 465s dh-golang-autopkgtest FLAKY non-zero exit status 2