0s autopkgtest [04:20:02]: starting date and time: 2025-11-04 04:20:02+0000 0s autopkgtest [04:20:02]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [04:20:02]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.rmba42u1/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-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-8.secgroup --name adt-resolute-s390x-golang-github-canonical-go-dqlite-20251104-042002-juju-7f2275-prod-proposed-migration-environment-2-e3bea7cc-7afa-4d2c-9df4-a7a1843314a6 --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 4s Creating nova instance adt-resolute-s390x-golang-github-canonical-go-dqlite-20251104-042002-juju-7f2275-prod-proposed-migration-environment-2-e3bea7cc-7afa-4d2c-9df4-a7a1843314a6 from image adt/ubuntu-resolute-s390x-server-20251104.img (UUID 80285f34-81f0-4ef3-9458-279742a38e4e)... 75s autopkgtest [04:21:17]: testbed dpkg architecture: s390x 75s autopkgtest [04:21:17]: testbed apt version: 3.1.11 75s autopkgtest [04:21:17]: @@@@@@@@@@@@@@@@@@@@ test bed setup 76s autopkgtest [04:21:18]: testbed release detected to be: None 76s autopkgtest [04:21:18]: updating testbed package index (apt update) 77s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 77s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 77s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 77s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 77s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [24.0 kB] 77s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [9848 B] 77s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [83.5 kB] 77s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1057 kB] 79s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [123 kB] 79s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted s390x Packages [940 B] 79s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [665 kB] 79s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [12.3 kB] 79s Fetched 2063 kB in 3s (741 kB/s) 80s Reading package lists... 81s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 81s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 81s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 81s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 82s Reading package lists... 82s Reading package lists... 82s Building dependency tree... 82s Reading state information... 82s Calculating upgrade... 82s The following packages will be upgraded: 82s bash-completion iputils-tracepath 82s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 82s Need to get 229 kB of archives. 82s After this operation, 1024 B of additional disk space will be used. 82s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x bash-completion all 1:2.16.0-8 [214 kB] 83s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x iputils-tracepath s390x 3:20250605-1ubuntu1 [14.8 kB] 83s dpkg-preconfigure: unable to re-open stdin: No such file or directory 83s Fetched 229 kB in 1s (430 kB/s) 83s (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 ... 56953 files and directories currently installed.) 83s Preparing to unpack .../bash-completion_1%3a2.16.0-8_all.deb ... 83s Unpacking bash-completion (1:2.16.0-8) over (1:2.16.0-7) ... 83s Preparing to unpack .../iputils-tracepath_3%3a20250605-1ubuntu1_s390x.deb ... 83s Unpacking iputils-tracepath (3:20250605-1ubuntu1) over (3:20240905-3ubuntu3) ... 83s Setting up bash-completion (1:2.16.0-8) ... 83s Setting up iputils-tracepath (3:20250605-1ubuntu1) ... 83s Processing triggers for man-db (2.13.1-1) ... 84s autopkgtest [04:21:26]: upgrading testbed (apt dist-upgrade and autopurge) 84s Reading package lists... 84s Building dependency tree... 84s Reading state information... 84s Calculating upgrade... 84s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 84s Reading package lists... 85s Building dependency tree... 85s Reading state information... 85s Solving dependencies... 85s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 87s autopkgtest [04:21:29]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP Mon Sep 22 08:56:47 UTC 2025 87s autopkgtest [04:21:29]: @@@@@@@@@@@@@@@@@@@@ apt-source golang-github-canonical-go-dqlite 90s Get:1 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (dsc) [2750 B] 90s Get:2 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (tar) [94.6 kB] 90s Get:3 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.1-1 (diff) [4532 B] 90s gpgv: Signature made Mon Sep 1 19:07:08 2025 UTC 90s gpgv: using RSA key D41A7AD07DF6C5FCA7489F1C29EEE2D6ECF442F9 90s gpgv: issuer "gibmat@debian.org" 90s gpgv: Can't check signature: No public key 90s dpkg-source: warning: cannot verify inline signature for ./golang-github-canonical-go-dqlite_2.0.1-1.dsc: no acceptable signature found 90s autopkgtest [04:21:32]: testing package golang-github-canonical-go-dqlite version 2.0.1-1 90s autopkgtest [04:21:32]: build not needed 92s autopkgtest [04:21:34]: test dh-golang-autopkgtest: preparing testbed 93s Reading package lists... 93s Building dependency tree... 93s Reading state information... 93s Solving dependencies... 93s The following NEW packages will be installed: 93s autoconf automake autopoint autotools-dev build-essential cpp cpp-15 93s cpp-15-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit dh-autoreconf 93s dh-golang dh-strip-nondeterminism dwz g++ g++-15 g++-15-s390x-linux-gnu 93s g++-s390x-linux-gnu gcc gcc-15 gcc-15-s390x-linux-gnu gcc-s390x-linux-gnu 93s gettext go-dqlite golang-1.25-go golang-1.25-src golang-any 93s golang-github-burntsushi-toml-dev golang-github-canonical-go-dqlite-dev 93s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 93s golang-github-fsnotify-fsnotify-dev golang-github-google-renameio-dev 93s golang-github-hashicorp-hcl-dev golang-github-kr-fs-dev 93s golang-github-magiconair-properties-dev golang-github-mattn-go-runewidth-dev 93s golang-github-mattn-go-sqlite3-dev golang-github-mitchellh-mapstructure-dev 93s golang-github-pelletier-go-toml-dev golang-github-pelletier-go-toml.v2-dev 93s golang-github-peterh-liner-dev golang-github-pkg-errors-dev 93s golang-github-pkg-sftp-dev golang-github-pmezard-go-difflib-dev 93s golang-github-rican7-retry-dev golang-github-rivo-uniseg-dev 93s golang-github-russross-blackfriday-v2-dev 93s golang-github-shurcool-sanitized-anchor-name-dev 93s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 93s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 93s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 93s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 93s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 93s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 93s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 93s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 93s libasan8 libcc1-0 libdebhelper-perl libdqlite-dev libdqlite0 93s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libisl23 libitm1 93s liblz4-dev libmpc3 libsqlite3-dev libstdc++-15-dev libtool libubsan1 93s libuv1-dev libxxhash-dev m4 po-debconf tzdata-legacy 93s 0 upgraded, 92 newly installed, 0 to remove and 0 not upgraded. 93s Need to get 115 MB of archives. 93s After this operation, 497 MB of additional disk space will be used. 93s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x m4 s390x 1.4.20-2 [223 kB] 93s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x autoconf all 2.72-3.1ubuntu1 [384 kB] 94s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x autotools-dev all 20240727.1 [43.4 kB] 94s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x automake all 1:1.18.1-2 [581 kB] 94s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x autopoint all 0.23.1-2build2 [619 kB] 94s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x libisl23 s390x 0.27-1 [704 kB] 94s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x libmpc3 s390x 1.3.1-1build3 [57.6 kB] 94s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-15-s390x-linux-gnu s390x 15.2.0-7ubuntu1 [10.2 MB] 104s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-15 s390x 15.2.0-7ubuntu1 [1022 B] 104s Get:10 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [5746 B] 104s Get:11 http://ftpmaster.internal/ubuntu resolute/main s390x cpp s390x 4:15.2.0-4ubuntu1 [22.4 kB] 104s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x libcc1-0 s390x 15.2.0-7ubuntu1 [50.0 kB] 104s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x libgomp1 s390x 15.2.0-7ubuntu1 [154 kB] 104s Get:14 http://ftpmaster.internal/ubuntu resolute/main s390x libitm1 s390x 15.2.0-7ubuntu1 [30.9 kB] 104s Get:15 http://ftpmaster.internal/ubuntu resolute/main s390x libasan8 s390x 15.2.0-7ubuntu1 [2968 kB] 107s Get:16 http://ftpmaster.internal/ubuntu resolute/main s390x libubsan1 s390x 15.2.0-7ubuntu1 [1211 kB] 108s Get:17 http://ftpmaster.internal/ubuntu resolute/main s390x libgcc-15-dev s390x 15.2.0-7ubuntu1 [1045 kB] 108s Get:18 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15-s390x-linux-gnu s390x 15.2.0-7ubuntu1 [19.9 MB] 119s Get:19 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15 s390x 15.2.0-7ubuntu1 [513 kB] 119s Get:20 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [1208 B] 119s Get:21 http://ftpmaster.internal/ubuntu resolute/main s390x gcc s390x 4:15.2.0-4ubuntu1 [5018 B] 119s Get:22 http://ftpmaster.internal/ubuntu resolute/main s390x libstdc++-15-dev s390x 15.2.0-7ubuntu1 [2659 kB] 121s Get:23 http://ftpmaster.internal/ubuntu resolute/main s390x g++-15-s390x-linux-gnu s390x 15.2.0-7ubuntu1 [11.7 MB] 128s Get:24 http://ftpmaster.internal/ubuntu resolute/main s390x g++-15 s390x 15.2.0-7ubuntu1 [23.7 kB] 128s Get:25 http://ftpmaster.internal/ubuntu resolute/main s390x g++-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [956 B] 128s Get:26 http://ftpmaster.internal/ubuntu resolute/main s390x g++ s390x 4:15.2.0-4ubuntu1 [1078 B] 128s Get:27 http://ftpmaster.internal/ubuntu resolute/main s390x build-essential s390x 12.12ubuntu1 [5090 B] 128s Get:28 http://ftpmaster.internal/ubuntu resolute/main s390x libdebhelper-perl all 13.24.2ubuntu1 [95.7 kB] 128s Get:29 http://ftpmaster.internal/ubuntu resolute/main s390x libtool all 2.5.4-4build1 [169 kB] 128s Get:30 http://ftpmaster.internal/ubuntu resolute/main s390x dh-autoreconf all 21 [12.5 kB] 128s Get:31 http://ftpmaster.internal/ubuntu resolute/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 128s Get:32 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-stripnondeterminism-perl all 1.15.0-1 [20.5 kB] 128s Get:33 http://ftpmaster.internal/ubuntu resolute/main s390x dh-strip-nondeterminism all 1.15.0-1 [5090 B] 128s Get:34 http://ftpmaster.internal/ubuntu resolute/main s390x debugedit s390x 1:5.2-3 [52.8 kB] 128s Get:35 http://ftpmaster.internal/ubuntu resolute/main s390x dwz s390x 0.16-2 [121 kB] 128s Get:36 http://ftpmaster.internal/ubuntu resolute/main s390x gettext s390x 0.23.1-2build2 [1062 kB] 128s Get:37 http://ftpmaster.internal/ubuntu resolute/main s390x intltool-debian all 0.35.0+20060710.6 [23.2 kB] 128s Get:38 http://ftpmaster.internal/ubuntu resolute/main s390x po-debconf all 1.0.21+nmu1 [233 kB] 129s Get:39 http://ftpmaster.internal/ubuntu resolute/main s390x debhelper all 13.24.2ubuntu1 [896 kB] 129s Get:40 http://ftpmaster.internal/ubuntu resolute/universe s390x libdqlite0 s390x 1.18.2-3 [145 kB] 129s Get:41 http://ftpmaster.internal/ubuntu resolute/universe s390x go-dqlite s390x 2.0.1-1 [8007 kB] 133s Get:42 http://ftpmaster.internal/ubuntu resolute/main s390x golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 140s Get:43 http://ftpmaster.internal/ubuntu resolute/main s390x golang-1.25-go s390x 1.25.0-0ubuntu1 [17.1 MB] 143s Get:44 http://ftpmaster.internal/ubuntu resolute/main s390x golang-src all 2:1.25~1 [5206 B] 143s Get:45 http://ftpmaster.internal/ubuntu resolute/main s390x golang-go s390x 2:1.25~1 [44.1 kB] 143s Get:46 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-any s390x 2:1.25~1 [2838 B] 143s Get:47 http://ftpmaster.internal/ubuntu resolute/main s390x libxxhash-dev s390x 0.8.3-2 [83.6 kB] 143s Get:48 http://ftpmaster.internal/ubuntu resolute/main s390x liblz4-dev s390x 1.10.0-4build1 [112 kB] 143s Get:49 http://ftpmaster.internal/ubuntu resolute/main s390x libuv1-dev s390x 1.51.0-2ubuntu1 [136 kB] 143s Get:50 http://ftpmaster.internal/ubuntu resolute/main s390x libsqlite3-dev s390x 3.46.1-8 [970 kB] 143s Get:51 http://ftpmaster.internal/ubuntu resolute/universe s390x libdqlite-dev s390x 1.18.2-3 [220 kB] 143s Get:52 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-google-renameio-dev all 2.0.0-2 [10.3 kB] 143s Get:53 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-mattn-go-sqlite3-dev s390x 1.14.32~ds1-1 [84.6 kB] 143s Get:54 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-rivo-uniseg-dev all 0.4.7-1 [258 kB] 143s Get:55 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-mattn-go-runewidth-dev all 0.0.16-2 [17.6 kB] 143s Get:56 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-peterh-liner-dev all 1.2.2-1 [21.3 kB] 143s Get:57 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-pkg-errors-dev all 0.9.1-3 [13.2 kB] 143s Get:58 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-rican7-retry-dev all 0.3.1-2 [7740 B] 143s Get:59 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-pmezard-go-difflib-dev all 1.0.0-3 [12.4 kB] 143s Get:60 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-shurcool-sanitized-anchor-name-dev all 1.0.0-3 [3590 B] 143s Get:61 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-russross-blackfriday-v2-dev all 2.1.0-1 [79.6 kB] 143s Get:62 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-cpuguy83-go-md2man-v2-dev all 2.0.4-1 [11.0 kB] 143s Get:63 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-spf13-pflag-dev all 1.0.10-1 [50.1 kB] 143s Get:64 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-golang-x-sys-dev all 0.22.0-1 [457 kB] 143s Get:65 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-fsnotify-fsnotify-dev all 1.9.0-1 [57.3 kB] 143s Get:66 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-davecgh-go-spew-dev all 1.1.1-3 [31.1 kB] 143s Get:67 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-hashicorp-hcl-dev all 1.0.0-1.1 [58.9 kB] 143s Get:68 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-magiconair-properties-dev all 1.8.7-1 [27.3 kB] 143s Get:69 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-mitchellh-mapstructure-dev all 1.5.0+git20231216.8508981-1 [28.3 kB] 143s Get:70 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-burntsushi-toml-dev all 1.4.0-1 [119 kB] 143s Get:71 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x golang-gopkg-yaml.v2-dev all 2.4.3-1 [62.7 kB] 143s Get:72 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-pelletier-go-toml-dev all 1.9.5-1build1 [79.6 kB] 143s Get:73 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-pelletier-go-toml.v2-dev all 2.2.4-1 [878 kB] 143s Get:74 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-kr-fs-dev all 0.1.0-2 [6344 B] 143s Get:75 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-golang-x-term-dev all 0.22.0-1 [14.6 kB] 143s Get:76 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-golang-x-text-dev all 0.22.0-1 [4616 kB] 144s Get:77 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-golang-x-crypto-dev all 1:0.25.0-1 [1724 kB] 144s Get:78 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-pkg-sftp-dev all 1.13.9-1 [108 kB] 144s Get:79 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-spf13-afero-dev all 1.15.0-1 [49.8 kB] 144s Get:80 http://ftpmaster.internal/ubuntu resolute/main s390x tzdata-legacy all 2025b-5ubuntu1 [99.3 kB] 144s Get:81 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-spf13-cast-dev all 1.10.0-2 [20.5 kB] 144s Get:82 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-stretchr-objx-dev all 0.5.2-1 [26.5 kB] 144s Get:83 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-gopkg-yaml.v3-dev all 3.0.1-3 [76.1 kB] 144s Get:84 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-stretchr-testify-dev all 1.10.0-1 [79.6 kB] 144s Get:85 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-spf13-jwalterweatherman-dev all 1.1.0+really1.1.0-2 [6828 B] 144s Get:86 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-subosito-gotenv-dev all 1.3.0-1 [8808 B] 144s Get:87 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-gopkg-ini.v1-dev all 1.67.0-1 [40.8 kB] 144s Get:88 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-spf13-viper-dev all 1.12.0-1 [71.0 kB] 144s Get:89 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-spf13-cobra-dev all 1.8.1-1 [76.2 kB] 144s Get:90 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-golang-x-sync-dev all 0.7.0-1 [19.5 kB] 144s Get:91 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-canonical-go-dqlite-dev all 2.0.1-1 [71.9 kB] 144s Get:92 http://ftpmaster.internal/ubuntu resolute/main s390x dh-golang all 1.63 [24.9 kB] 145s Fetched 115 MB in 52s (2234 kB/s) 145s Selecting previously unselected package m4. 145s (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 ... 56953 files and directories currently installed.) 145s Preparing to unpack .../00-m4_1.4.20-2_s390x.deb ... 145s Unpacking m4 (1.4.20-2) ... 145s Selecting previously unselected package autoconf. 145s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ... 145s Unpacking autoconf (2.72-3.1ubuntu1) ... 145s Selecting previously unselected package autotools-dev. 145s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ... 145s Unpacking autotools-dev (20240727.1) ... 145s Selecting previously unselected package automake. 145s Preparing to unpack .../03-automake_1%3a1.18.1-2_all.deb ... 145s Unpacking automake (1:1.18.1-2) ... 145s Selecting previously unselected package autopoint. 145s Preparing to unpack .../04-autopoint_0.23.1-2build2_all.deb ... 145s Unpacking autopoint (0.23.1-2build2) ... 145s Selecting previously unselected package libisl23:s390x. 145s Preparing to unpack .../05-libisl23_0.27-1_s390x.deb ... 145s Unpacking libisl23:s390x (0.27-1) ... 145s Selecting previously unselected package libmpc3:s390x. 145s Preparing to unpack .../06-libmpc3_1.3.1-1build3_s390x.deb ... 145s Unpacking libmpc3:s390x (1.3.1-1build3) ... 145s Selecting previously unselected package cpp-15-s390x-linux-gnu. 145s Preparing to unpack .../07-cpp-15-s390x-linux-gnu_15.2.0-7ubuntu1_s390x.deb ... 145s Unpacking cpp-15-s390x-linux-gnu (15.2.0-7ubuntu1) ... 145s Selecting previously unselected package cpp-15. 145s Preparing to unpack .../08-cpp-15_15.2.0-7ubuntu1_s390x.deb ... 145s Unpacking cpp-15 (15.2.0-7ubuntu1) ... 145s Selecting previously unselected package cpp-s390x-linux-gnu. 145s Preparing to unpack .../09-cpp-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 145s Unpacking cpp-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 145s Selecting previously unselected package cpp. 145s Preparing to unpack .../10-cpp_4%3a15.2.0-4ubuntu1_s390x.deb ... 145s Unpacking cpp (4:15.2.0-4ubuntu1) ... 145s Selecting previously unselected package libcc1-0:s390x. 145s Preparing to unpack .../11-libcc1-0_15.2.0-7ubuntu1_s390x.deb ... 145s Unpacking libcc1-0:s390x (15.2.0-7ubuntu1) ... 145s Selecting previously unselected package libgomp1:s390x. 145s Preparing to unpack .../12-libgomp1_15.2.0-7ubuntu1_s390x.deb ... 145s Unpacking libgomp1:s390x (15.2.0-7ubuntu1) ... 145s Selecting previously unselected package libitm1:s390x. 145s Preparing to unpack .../13-libitm1_15.2.0-7ubuntu1_s390x.deb ... 145s Unpacking libitm1:s390x (15.2.0-7ubuntu1) ... 145s Selecting previously unselected package libasan8:s390x. 145s Preparing to unpack .../14-libasan8_15.2.0-7ubuntu1_s390x.deb ... 145s Unpacking libasan8:s390x (15.2.0-7ubuntu1) ... 145s Selecting previously unselected package libubsan1:s390x. 145s Preparing to unpack .../15-libubsan1_15.2.0-7ubuntu1_s390x.deb ... 145s Unpacking libubsan1:s390x (15.2.0-7ubuntu1) ... 145s Selecting previously unselected package libgcc-15-dev:s390x. 145s Preparing to unpack .../16-libgcc-15-dev_15.2.0-7ubuntu1_s390x.deb ... 145s Unpacking libgcc-15-dev:s390x (15.2.0-7ubuntu1) ... 145s Selecting previously unselected package gcc-15-s390x-linux-gnu. 145s Preparing to unpack .../17-gcc-15-s390x-linux-gnu_15.2.0-7ubuntu1_s390x.deb ... 145s Unpacking gcc-15-s390x-linux-gnu (15.2.0-7ubuntu1) ... 146s Selecting previously unselected package gcc-15. 146s Preparing to unpack .../18-gcc-15_15.2.0-7ubuntu1_s390x.deb ... 146s Unpacking gcc-15 (15.2.0-7ubuntu1) ... 146s Selecting previously unselected package gcc-s390x-linux-gnu. 146s Preparing to unpack .../19-gcc-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 146s Unpacking gcc-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 146s Selecting previously unselected package gcc. 146s Preparing to unpack .../20-gcc_4%3a15.2.0-4ubuntu1_s390x.deb ... 146s Unpacking gcc (4:15.2.0-4ubuntu1) ... 146s Selecting previously unselected package libstdc++-15-dev:s390x. 146s Preparing to unpack .../21-libstdc++-15-dev_15.2.0-7ubuntu1_s390x.deb ... 146s Unpacking libstdc++-15-dev:s390x (15.2.0-7ubuntu1) ... 146s Selecting previously unselected package g++-15-s390x-linux-gnu. 146s Preparing to unpack .../22-g++-15-s390x-linux-gnu_15.2.0-7ubuntu1_s390x.deb ... 146s Unpacking g++-15-s390x-linux-gnu (15.2.0-7ubuntu1) ... 146s Selecting previously unselected package g++-15. 146s Preparing to unpack .../23-g++-15_15.2.0-7ubuntu1_s390x.deb ... 146s Unpacking g++-15 (15.2.0-7ubuntu1) ... 146s Selecting previously unselected package g++-s390x-linux-gnu. 146s Preparing to unpack .../24-g++-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 146s Unpacking g++-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 146s Selecting previously unselected package g++. 146s Preparing to unpack .../25-g++_4%3a15.2.0-4ubuntu1_s390x.deb ... 146s Unpacking g++ (4:15.2.0-4ubuntu1) ... 146s Selecting previously unselected package build-essential. 146s Preparing to unpack .../26-build-essential_12.12ubuntu1_s390x.deb ... 146s Unpacking build-essential (12.12ubuntu1) ... 146s Selecting previously unselected package libdebhelper-perl. 146s Preparing to unpack .../27-libdebhelper-perl_13.24.2ubuntu1_all.deb ... 146s Unpacking libdebhelper-perl (13.24.2ubuntu1) ... 146s Selecting previously unselected package libtool. 146s Preparing to unpack .../28-libtool_2.5.4-4build1_all.deb ... 146s Unpacking libtool (2.5.4-4build1) ... 146s Selecting previously unselected package dh-autoreconf. 146s Preparing to unpack .../29-dh-autoreconf_21_all.deb ... 146s Unpacking dh-autoreconf (21) ... 146s Selecting previously unselected package libarchive-zip-perl. 146s Preparing to unpack .../30-libarchive-zip-perl_1.68-1_all.deb ... 146s Unpacking libarchive-zip-perl (1.68-1) ... 146s Selecting previously unselected package libfile-stripnondeterminism-perl. 146s Preparing to unpack .../31-libfile-stripnondeterminism-perl_1.15.0-1_all.deb ... 146s Unpacking libfile-stripnondeterminism-perl (1.15.0-1) ... 146s Selecting previously unselected package dh-strip-nondeterminism. 146s Preparing to unpack .../32-dh-strip-nondeterminism_1.15.0-1_all.deb ... 146s Unpacking dh-strip-nondeterminism (1.15.0-1) ... 146s Selecting previously unselected package debugedit. 146s Preparing to unpack .../33-debugedit_1%3a5.2-3_s390x.deb ... 146s Unpacking debugedit (1:5.2-3) ... 146s Selecting previously unselected package dwz. 146s Preparing to unpack .../34-dwz_0.16-2_s390x.deb ... 146s Unpacking dwz (0.16-2) ... 146s Selecting previously unselected package gettext. 146s Preparing to unpack .../35-gettext_0.23.1-2build2_s390x.deb ... 146s Unpacking gettext (0.23.1-2build2) ... 146s Selecting previously unselected package intltool-debian. 146s Preparing to unpack .../36-intltool-debian_0.35.0+20060710.6_all.deb ... 146s Unpacking intltool-debian (0.35.0+20060710.6) ... 146s Selecting previously unselected package po-debconf. 146s Preparing to unpack .../37-po-debconf_1.0.21+nmu1_all.deb ... 146s Unpacking po-debconf (1.0.21+nmu1) ... 146s Selecting previously unselected package debhelper. 146s Preparing to unpack .../38-debhelper_13.24.2ubuntu1_all.deb ... 146s Unpacking debhelper (13.24.2ubuntu1) ... 146s Selecting previously unselected package libdqlite0:s390x. 146s Preparing to unpack .../39-libdqlite0_1.18.2-3_s390x.deb ... 146s Unpacking libdqlite0:s390x (1.18.2-3) ... 146s Selecting previously unselected package go-dqlite. 146s Preparing to unpack .../40-go-dqlite_2.0.1-1_s390x.deb ... 146s Unpacking go-dqlite (2.0.1-1) ... 146s Selecting previously unselected package golang-1.25-src. 146s Preparing to unpack .../41-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 146s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 147s Selecting previously unselected package golang-1.25-go. 147s Preparing to unpack .../42-golang-1.25-go_1.25.0-0ubuntu1_s390x.deb ... 147s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 147s Selecting previously unselected package golang-src. 147s Preparing to unpack .../43-golang-src_2%3a1.25~1_all.deb ... 147s Unpacking golang-src (2:1.25~1) ... 147s Selecting previously unselected package golang-go:s390x. 147s Preparing to unpack .../44-golang-go_2%3a1.25~1_s390x.deb ... 147s Unpacking golang-go:s390x (2:1.25~1) ... 147s Selecting previously unselected package golang-any:s390x. 147s Preparing to unpack .../45-golang-any_2%3a1.25~1_s390x.deb ... 147s Unpacking golang-any:s390x (2:1.25~1) ... 147s Selecting previously unselected package libxxhash-dev:s390x. 147s Preparing to unpack .../46-libxxhash-dev_0.8.3-2_s390x.deb ... 147s Unpacking libxxhash-dev:s390x (0.8.3-2) ... 147s Selecting previously unselected package liblz4-dev:s390x. 147s Preparing to unpack .../47-liblz4-dev_1.10.0-4build1_s390x.deb ... 147s Unpacking liblz4-dev:s390x (1.10.0-4build1) ... 147s Selecting previously unselected package libuv1-dev:s390x. 147s Preparing to unpack .../48-libuv1-dev_1.51.0-2ubuntu1_s390x.deb ... 147s Unpacking libuv1-dev:s390x (1.51.0-2ubuntu1) ... 147s Selecting previously unselected package libsqlite3-dev:s390x. 147s Preparing to unpack .../49-libsqlite3-dev_3.46.1-8_s390x.deb ... 147s Unpacking libsqlite3-dev:s390x (3.46.1-8) ... 148s Selecting previously unselected package libdqlite-dev:s390x. 148s Preparing to unpack .../50-libdqlite-dev_1.18.2-3_s390x.deb ... 148s Unpacking libdqlite-dev:s390x (1.18.2-3) ... 148s Selecting previously unselected package golang-github-google-renameio-dev. 148s Preparing to unpack .../51-golang-github-google-renameio-dev_2.0.0-2_all.deb ... 148s Unpacking golang-github-google-renameio-dev (2.0.0-2) ... 148s Selecting previously unselected package golang-github-mattn-go-sqlite3-dev:s390x. 148s Preparing to unpack .../52-golang-github-mattn-go-sqlite3-dev_1.14.32~ds1-1_s390x.deb ... 148s Unpacking golang-github-mattn-go-sqlite3-dev:s390x (1.14.32~ds1-1) ... 148s Selecting previously unselected package golang-github-rivo-uniseg-dev. 148s Preparing to unpack .../53-golang-github-rivo-uniseg-dev_0.4.7-1_all.deb ... 148s Unpacking golang-github-rivo-uniseg-dev (0.4.7-1) ... 148s Selecting previously unselected package golang-github-mattn-go-runewidth-dev. 148s Preparing to unpack .../54-golang-github-mattn-go-runewidth-dev_0.0.16-2_all.deb ... 148s Unpacking golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 148s Selecting previously unselected package golang-github-peterh-liner-dev. 148s Preparing to unpack .../55-golang-github-peterh-liner-dev_1.2.2-1_all.deb ... 148s Unpacking golang-github-peterh-liner-dev (1.2.2-1) ... 148s Selecting previously unselected package golang-github-pkg-errors-dev. 148s Preparing to unpack .../56-golang-github-pkg-errors-dev_0.9.1-3_all.deb ... 148s Unpacking golang-github-pkg-errors-dev (0.9.1-3) ... 148s Selecting previously unselected package golang-github-rican7-retry-dev. 148s Preparing to unpack .../57-golang-github-rican7-retry-dev_0.3.1-2_all.deb ... 148s Unpacking golang-github-rican7-retry-dev (0.3.1-2) ... 148s Selecting previously unselected package golang-github-pmezard-go-difflib-dev. 148s Preparing to unpack .../58-golang-github-pmezard-go-difflib-dev_1.0.0-3_all.deb ... 148s Unpacking golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 148s Selecting previously unselected package golang-github-shurcool-sanitized-anchor-name-dev. 148s Preparing to unpack .../59-golang-github-shurcool-sanitized-anchor-name-dev_1.0.0-3_all.deb ... 148s Unpacking golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 148s Selecting previously unselected package golang-github-russross-blackfriday-v2-dev. 148s Preparing to unpack .../60-golang-github-russross-blackfriday-v2-dev_2.1.0-1_all.deb ... 148s Unpacking golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 148s Selecting previously unselected package golang-github-cpuguy83-go-md2man-v2-dev. 148s Preparing to unpack .../61-golang-github-cpuguy83-go-md2man-v2-dev_2.0.4-1_all.deb ... 148s Unpacking golang-github-cpuguy83-go-md2man-v2-dev (2.0.4-1) ... 148s Selecting previously unselected package golang-github-spf13-pflag-dev. 148s Preparing to unpack .../62-golang-github-spf13-pflag-dev_1.0.10-1_all.deb ... 148s Unpacking golang-github-spf13-pflag-dev (1.0.10-1) ... 148s Selecting previously unselected package golang-golang-x-sys-dev. 148s Preparing to unpack .../63-golang-golang-x-sys-dev_0.22.0-1_all.deb ... 148s Unpacking golang-golang-x-sys-dev (0.22.0-1) ... 148s Selecting previously unselected package golang-github-fsnotify-fsnotify-dev. 148s Preparing to unpack .../64-golang-github-fsnotify-fsnotify-dev_1.9.0-1_all.deb ... 148s Unpacking golang-github-fsnotify-fsnotify-dev (1.9.0-1) ... 148s Selecting previously unselected package golang-github-davecgh-go-spew-dev. 148s Preparing to unpack .../65-golang-github-davecgh-go-spew-dev_1.1.1-3_all.deb ... 148s Unpacking golang-github-davecgh-go-spew-dev (1.1.1-3) ... 148s Selecting previously unselected package golang-github-hashicorp-hcl-dev. 148s Preparing to unpack .../66-golang-github-hashicorp-hcl-dev_1.0.0-1.1_all.deb ... 148s Unpacking golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 148s Selecting previously unselected package golang-github-magiconair-properties-dev. 148s Preparing to unpack .../67-golang-github-magiconair-properties-dev_1.8.7-1_all.deb ... 148s Unpacking golang-github-magiconair-properties-dev (1.8.7-1) ... 148s Selecting previously unselected package golang-github-mitchellh-mapstructure-dev. 148s Preparing to unpack .../68-golang-github-mitchellh-mapstructure-dev_1.5.0+git20231216.8508981-1_all.deb ... 148s Unpacking golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 148s Selecting previously unselected package golang-github-burntsushi-toml-dev. 148s Preparing to unpack .../69-golang-github-burntsushi-toml-dev_1.4.0-1_all.deb ... 148s Unpacking golang-github-burntsushi-toml-dev (1.4.0-1) ... 148s Selecting previously unselected package golang-gopkg-yaml.v2-dev. 148s Preparing to unpack .../70-golang-gopkg-yaml.v2-dev_2.4.3-1_all.deb ... 148s Unpacking golang-gopkg-yaml.v2-dev (2.4.3-1) ... 148s Selecting previously unselected package golang-github-pelletier-go-toml-dev. 148s Preparing to unpack .../71-golang-github-pelletier-go-toml-dev_1.9.5-1build1_all.deb ... 148s Unpacking golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 148s Selecting previously unselected package golang-github-pelletier-go-toml.v2-dev. 148s Preparing to unpack .../72-golang-github-pelletier-go-toml.v2-dev_2.2.4-1_all.deb ... 148s Unpacking golang-github-pelletier-go-toml.v2-dev (2.2.4-1) ... 148s Selecting previously unselected package golang-github-kr-fs-dev. 148s Preparing to unpack .../73-golang-github-kr-fs-dev_0.1.0-2_all.deb ... 148s Unpacking golang-github-kr-fs-dev (0.1.0-2) ... 148s Selecting previously unselected package golang-golang-x-term-dev. 148s Preparing to unpack .../74-golang-golang-x-term-dev_0.22.0-1_all.deb ... 148s Unpacking golang-golang-x-term-dev (0.22.0-1) ... 148s Selecting previously unselected package golang-golang-x-text-dev. 148s Preparing to unpack .../75-golang-golang-x-text-dev_0.22.0-1_all.deb ... 148s Unpacking golang-golang-x-text-dev (0.22.0-1) ... 148s Selecting previously unselected package golang-golang-x-crypto-dev. 148s Preparing to unpack .../76-golang-golang-x-crypto-dev_1%3a0.25.0-1_all.deb ... 148s Unpacking golang-golang-x-crypto-dev (1:0.25.0-1) ... 148s Selecting previously unselected package golang-github-pkg-sftp-dev. 148s Preparing to unpack .../77-golang-github-pkg-sftp-dev_1.13.9-1_all.deb ... 148s Unpacking golang-github-pkg-sftp-dev (1.13.9-1) ... 148s Selecting previously unselected package golang-github-spf13-afero-dev. 148s Preparing to unpack .../78-golang-github-spf13-afero-dev_1.15.0-1_all.deb ... 148s Unpacking golang-github-spf13-afero-dev (1.15.0-1) ... 148s Selecting previously unselected package tzdata-legacy. 148s Preparing to unpack .../79-tzdata-legacy_2025b-5ubuntu1_all.deb ... 148s Unpacking tzdata-legacy (2025b-5ubuntu1) ... 148s Selecting previously unselected package golang-github-spf13-cast-dev. 148s Preparing to unpack .../80-golang-github-spf13-cast-dev_1.10.0-2_all.deb ... 148s Unpacking golang-github-spf13-cast-dev (1.10.0-2) ... 148s Selecting previously unselected package golang-github-stretchr-objx-dev. 148s Preparing to unpack .../81-golang-github-stretchr-objx-dev_0.5.2-1_all.deb ... 148s Unpacking golang-github-stretchr-objx-dev (0.5.2-1) ... 148s Selecting previously unselected package golang-gopkg-yaml.v3-dev. 148s Preparing to unpack .../82-golang-gopkg-yaml.v3-dev_3.0.1-3_all.deb ... 148s Unpacking golang-gopkg-yaml.v3-dev (3.0.1-3) ... 148s Selecting previously unselected package golang-github-stretchr-testify-dev. 148s Preparing to unpack .../83-golang-github-stretchr-testify-dev_1.10.0-1_all.deb ... 148s Unpacking golang-github-stretchr-testify-dev (1.10.0-1) ... 148s Selecting previously unselected package golang-github-spf13-jwalterweatherman-dev. 148s Preparing to unpack .../84-golang-github-spf13-jwalterweatherman-dev_1.1.0+really1.1.0-2_all.deb ... 148s Unpacking golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 148s Selecting previously unselected package golang-github-subosito-gotenv-dev. 148s Preparing to unpack .../85-golang-github-subosito-gotenv-dev_1.3.0-1_all.deb ... 148s Unpacking golang-github-subosito-gotenv-dev (1.3.0-1) ... 148s Selecting previously unselected package golang-gopkg-ini.v1-dev. 148s Preparing to unpack .../86-golang-gopkg-ini.v1-dev_1.67.0-1_all.deb ... 148s Unpacking golang-gopkg-ini.v1-dev (1.67.0-1) ... 148s Selecting previously unselected package golang-github-spf13-viper-dev. 148s Preparing to unpack .../87-golang-github-spf13-viper-dev_1.12.0-1_all.deb ... 148s Unpacking golang-github-spf13-viper-dev (1.12.0-1) ... 148s Selecting previously unselected package golang-github-spf13-cobra-dev. 148s Preparing to unpack .../88-golang-github-spf13-cobra-dev_1.8.1-1_all.deb ... 148s Unpacking golang-github-spf13-cobra-dev (1.8.1-1) ... 148s Selecting previously unselected package golang-golang-x-sync-dev. 148s Preparing to unpack .../89-golang-golang-x-sync-dev_0.7.0-1_all.deb ... 148s Unpacking golang-golang-x-sync-dev (0.7.0-1) ... 148s Selecting previously unselected package golang-github-canonical-go-dqlite-dev. 148s Preparing to unpack .../90-golang-github-canonical-go-dqlite-dev_2.0.1-1_all.deb ... 148s Unpacking golang-github-canonical-go-dqlite-dev (2.0.1-1) ... 148s Selecting previously unselected package dh-golang. 148s Preparing to unpack .../91-dh-golang_1.63_all.deb ... 148s Unpacking dh-golang (1.63) ... 148s Setting up golang-github-pelletier-go-toml.v2-dev (2.2.4-1) ... 148s Setting up libuv1-dev:s390x (1.51.0-2ubuntu1) ... 148s Setting up golang-gopkg-yaml.v2-dev (2.4.3-1) ... 148s Setting up golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 148s Setting up golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 148s Setting up golang-github-stretchr-objx-dev (0.5.2-1) ... 148s Setting up golang-github-pkg-errors-dev (0.9.1-3) ... 148s Setting up libarchive-zip-perl (1.68-1) ... 148s Setting up golang-gopkg-yaml.v3-dev (3.0.1-3) ... 148s Setting up libdebhelper-perl (13.24.2ubuntu1) ... 148s Setting up golang-github-burntsushi-toml-dev (1.4.0-1) ... 148s Setting up golang-golang-x-sys-dev (0.22.0-1) ... 148s Setting up golang-github-kr-fs-dev (0.1.0-2) ... 148s Setting up golang-github-subosito-gotenv-dev (1.3.0-1) ... 148s Setting up golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 148s Setting up m4 (1.4.20-2) ... 148s Setting up golang-golang-x-term-dev (0.22.0-1) ... 148s Setting up libgomp1:s390x (15.2.0-7ubuntu1) ... 148s Setting up tzdata-legacy (2025b-5ubuntu1) ... 148s Setting up golang-github-google-renameio-dev (2.0.0-2) ... 148s Setting up golang-github-spf13-pflag-dev (1.0.10-1) ... 148s Setting up golang-github-davecgh-go-spew-dev (1.1.1-3) ... 148s Setting up autotools-dev (20240727.1) ... 148s Setting up libsqlite3-dev:s390x (3.46.1-8) ... 148s Setting up golang-github-stretchr-testify-dev (1.10.0-1) ... 148s Setting up golang-gopkg-ini.v1-dev (1.67.0-1) ... 148s Setting up libmpc3:s390x (1.3.1-1build3) ... 148s Setting up golang-golang-x-sync-dev (0.7.0-1) ... 148s Setting up autopoint (0.23.1-2build2) ... 148s Setting up golang-github-magiconair-properties-dev (1.8.7-1) ... 148s Setting up golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 148s Setting up golang-github-rican7-retry-dev (0.3.1-2) ... 148s Setting up autoconf (2.72-3.1ubuntu1) ... 148s Setting up libxxhash-dev:s390x (0.8.3-2) ... 148s Setting up golang-github-rivo-uniseg-dev (0.4.7-1) ... 148s Setting up libubsan1:s390x (15.2.0-7ubuntu1) ... 148s Setting up dwz (0.16-2) ... 148s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 148s Setting up golang-golang-x-text-dev (0.22.0-1) ... 148s Setting up libasan8:s390x (15.2.0-7ubuntu1) ... 148s Setting up golang-github-spf13-cast-dev (1.10.0-2) ... 148s Setting up golang-github-fsnotify-fsnotify-dev (1.9.0-1) ... 148s Setting up debugedit (1:5.2-3) ... 148s Setting up golang-golang-x-crypto-dev (1:0.25.0-1) ... 148s Setting up golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 148s Setting up golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 148s Setting up libdqlite0:s390x (1.18.2-3) ... 148s Setting up libisl23:s390x (0.27-1) ... 148s Setting up golang-src (2:1.25~1) ... 148s Setting up libcc1-0:s390x (15.2.0-7ubuntu1) ... 148s Setting up libitm1:s390x (15.2.0-7ubuntu1) ... 148s Setting up automake (1:1.18.1-2) ... 148s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode 148s Setting up libfile-stripnondeterminism-perl (1.15.0-1) ... 148s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 148s Setting up golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 148s Setting up gettext (0.23.1-2build2) ... 148s Setting up libgcc-15-dev:s390x (15.2.0-7ubuntu1) ... 148s Setting up golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 148s Setting up golang-go:s390x (2:1.25~1) ... 148s Setting up golang-github-pkg-sftp-dev (1.13.9-1) ... 148s Setting up golang-any:s390x (2:1.25~1) ... 148s Setting up intltool-debian (0.35.0+20060710.6) ... 148s Setting up golang-github-peterh-liner-dev (1.2.2-1) ... 148s Setting up golang-github-mattn-go-sqlite3-dev:s390x (1.14.32~ds1-1) ... 148s Setting up go-dqlite (2.0.1-1) ... 148s Setting up libstdc++-15-dev:s390x (15.2.0-7ubuntu1) ... 148s Setting up liblz4-dev:s390x (1.10.0-4build1) ... 148s Setting up dh-strip-nondeterminism (1.15.0-1) ... 148s Setting up cpp-15-s390x-linux-gnu (15.2.0-7ubuntu1) ... 148s Setting up golang-github-spf13-afero-dev (1.15.0-1) ... 148s Setting up gcc-15-s390x-linux-gnu (15.2.0-7ubuntu1) ... 148s Setting up g++-15-s390x-linux-gnu (15.2.0-7ubuntu1) ... 148s Setting up libdqlite-dev:s390x (1.18.2-3) ... 148s Setting up golang-github-cpuguy83-go-md2man-v2-dev (2.0.4-1) ... 148s Setting up cpp-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 148s Setting up po-debconf (1.0.21+nmu1) ... 148s Setting up gcc-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 148s Setting up golang-github-spf13-viper-dev (1.12.0-1) ... 148s Setting up cpp-15 (15.2.0-7ubuntu1) ... 148s Setting up cpp (4:15.2.0-4ubuntu1) ... 148s Setting up g++-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 148s Setting up gcc-15 (15.2.0-7ubuntu1) ... 148s Setting up golang-github-spf13-cobra-dev (1.8.1-1) ... 148s Setting up g++-15 (15.2.0-7ubuntu1) ... 148s Setting up libtool (2.5.4-4build1) ... 148s Setting up gcc (4:15.2.0-4ubuntu1) ... 148s Setting up dh-autoreconf (21) ... 148s Setting up golang-github-canonical-go-dqlite-dev (2.0.1-1) ... 148s Setting up g++ (4:15.2.0-4ubuntu1) ... 148s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 148s Setting up build-essential (12.12ubuntu1) ... 148s Setting up debhelper (13.24.2ubuntu1) ... 148s Setting up dh-golang (1.63) ... 148s Processing triggers for man-db (2.13.1-1) ... 149s Processing triggers for install-info (7.2-5) ... 149s Processing triggers for libc-bin (2.42-0ubuntu3) ... 150s autopkgtest [04:22:32]: test dh-golang-autopkgtest: /usr/bin/dh_golang_autopkgtest 150s autopkgtest [04:22:32]: test dh-golang-autopkgtest: [----------------------- 151s [info] Testing github.com/canonical/go-dqlite... 151s [info] Source code installed by binary package, overriding dh_auto_configure... 151s dh build --builddirectory=_build --buildsystem=golang --with=golang 151s dh_update_autotools_config -O--builddirectory=_build -O--buildsystem=golang 151s dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang 151s debian/rules override_dh_auto_configure 151s make[1]: Entering directory '/tmp/autopkgtest.Ks1tT4/autopkgtest_tmp' 151s mkdir -p "_build" 151s # remove existing symlinks, since "cp -a" won't convert them 151s find "_build" -type l -exec rm "{}" \; 151s cp -a /usr/share/gocode/src "_build" 151s make[1]: Leaving directory '/tmp/autopkgtest.Ks1tT4/autopkgtest_tmp' 151s dh_auto_build -O--builddirectory=_build -O--buildsystem=golang 151s 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 151s internal/unsafeheader 151s internal/goarch 151s internal/cpu 151s internal/abi 151s internal/bytealg 151s internal/byteorder 151s internal/chacha8rand 151s internal/coverage/rtcov 151s internal/godebugs 152s internal/goexperiment 152s internal/goos 152s internal/profilerecord 152s internal/runtime/atomic 152s internal/runtime/math 152s internal/runtime/strconv 152s internal/runtime/syscall 152s internal/runtime/exithook 152s internal/runtime/gc 152s internal/asan 152s internal/runtime/cgroup 152s internal/msan 152s internal/race 152s internal/runtime/sys 152s internal/stringslite 152s internal/runtime/maps 152s internal/trace/tracev2 152s sync/atomic 152s internal/sync 152s internal/synctest 152s math/bits 152s internal/itoa 152s cmp 152s math 152s runtime 152s unicode/utf8 152s unicode 152s container/list 152s crypto/internal/fips140/alias 152s crypto/internal/fips140deps/byteorder 152s crypto/internal/fips140deps/cpu 152s crypto/internal/boring/sig 152s unicode/utf16 152s vendor/golang.org/x/crypto/cryptobyte/asn1 152s vendor/golang.org/x/crypto/internal/alias 152s internal/nettrace 152s encoding 152s log/internal 152s container/ring 152s github.com/rivo/uniseg 155s internal/reflectlite 155s sync 155s internal/bisect 155s errors 155s internal/oserror 155s iter 155s slices 155s syscall 155s internal/godebug 155s strconv 155s reflect 155s time 156s context 156s io 156s path 156s io/fs 156s internal/filepathlite 156s internal/syscall/unix 156s internal/poll 156s internal/fmtsort 156s internal/syscall/execenv 156s internal/testlog 156s bytes 156s os 156s hash 156s crypto 156s crypto/internal/fips140deps/godebug 156s strings 157s crypto/internal/fips140 157s crypto/internal/impl 157s crypto/internal/fips140/sha256 157s fmt 157s crypto/internal/fips140/subtle 157s crypto/internal/fips140/sha3 157s crypto/internal/fips140/sha512 157s crypto/internal/fips140/hmac 157s crypto/internal/fips140/check 157s crypto/internal/fips140/aes 157s crypto/internal/sysrand 157s math/rand/v2 157s crypto/internal/entropy 157s crypto/subtle 157s crypto/internal/fips140/nistec/fiat 157s crypto/internal/randutil 157s crypto/internal/fips140/drbg 157s crypto/internal/fips140/aes/gcm 157s crypto/internal/fips140only 157s crypto/cipher 157s crypto/internal/boring 157s crypto/aes 157s crypto/des 157s crypto/internal/fips140/edwards25519/field 158s math/rand 158s crypto/internal/fips140/nistec 158s math/big 158s crypto/internal/fips140/ecdh 158s crypto/elliptic 158s crypto/ecdh 158s crypto/internal/boring/bbig 158s crypto/internal/fips140/bigmod 158s weak 158s crypto/internal/fips140cache 158s crypto/sha3 158s crypto/internal/fips140hash 158s crypto/sha512 158s crypto/internal/fips140/ecdsa 158s encoding/asn1 159s crypto/internal/fips140/edwards25519 159s vendor/golang.org/x/crypto/cryptobyte 159s crypto/internal/fips140/ed25519 159s crypto/rand 159s crypto/ed25519 159s crypto/ecdsa 159s crypto/internal/fips140/hkdf 159s crypto/hkdf 159s crypto/hmac 159s crypto/internal/fips140/mlkem 159s crypto/internal/fips140/tls12 159s crypto/internal/fips140/tls13 159s encoding/binary 160s vendor/golang.org/x/sys/cpu 160s crypto/md5 160s vendor/golang.org/x/crypto/chacha20 160s vendor/golang.org/x/crypto/internal/poly1305 160s crypto/rc4 160s crypto/internal/fips140/rsa 160s vendor/golang.org/x/crypto/chacha20poly1305 160s crypto/internal/hpke 160s crypto/sha1 160s crypto/sha256 160s crypto/fips140 160s crypto/tls/internal/fips140tls 160s crypto/dsa 160s crypto/rsa 160s encoding/hex 160s crypto/x509/pkix 160s encoding/base64 160s maps 160s vendor/golang.org/x/net/dns/dnsmessage 160s encoding/pem 160s internal/singleflight 160s unique 160s net/netip 160s runtime/cgo 160s net/url 161s path/filepath 161s sort 161s database/sql/driver 161s database/sql 161s net 161s github.com/Rican7/retry/backoff 161s github.com/Rican7/retry/jitter 161s github.com/Rican7/retry/strategy 161s github.com/Rican7/retry 161s flag 161s bufio 161s internal/sysinfo 162s runtime/debug 162s runtime/trace 162s testing 162s github.com/canonical/go-dqlite/logging 162s github.com/pkg/errors 162s golang.org/x/sync/semaphore 162s io/ioutil 162s github.com/google/renameio 162s github.com/mattn/go-sqlite3 163s crypto/x509 163s crypto/tls 165s github.com/canonical/go-dqlite/internal/protocol 165s regexp/syntax 165s regexp 166s gopkg.in/yaml.v2 166s github.com/canonical/go-dqlite/internal/bindings 166s github.com/canonical/go-dqlite/client 166s github.com/canonical/go-dqlite/tracing 166s github.com/canonical/go-dqlite/driver 167s golang.org/x/sys/unix 167s github.com/canonical/go-dqlite 167s log 167s encoding/json 167s github.com/canonical/go-dqlite/app 168s github.com/canonical/go-dqlite/benchmark 168s text/tabwriter 168s github.com/mattn/go-runewidth 168s github.com/canonical/go-dqlite/internal/shell 168s os/signal 168s encoding/csv 168s github.com/peterh/liner 168s github.com/spf13/pflag 168s text/template/parse 168s text/template 169s compress/flate 169s github.com/spf13/cobra 169s hash/crc32 169s compress/gzip 169s vendor/golang.org/x/text/transform 169s vendor/golang.org/x/text/unicode/bidi 169s vendor/golang.org/x/text/secure/bidirule 169s github.com/canonical/go-dqlite/cmd/dqlite 169s github.com/canonical/go-dqlite/cmd/dqlite-benchmark 170s vendor/golang.org/x/text/unicode/norm 170s net/textproto 171s vendor/golang.org/x/net/http2/hpack 171s mime 171s vendor/golang.org/x/net/idna 171s vendor/golang.org/x/net/http/httpguts 171s vendor/golang.org/x/net/http/httpproxy 171s mime/quotedprintable 171s mime/multipart 171s net/http/httptrace 171s net/http/internal 171s net/http/internal/ascii 171s net/http/internal/httpcommon 171s net/http 173s github.com/canonical/go-dqlite/cmd/dqlite-demo 174s create-stamp debian/debhelper-build-stamp 174s dh_auto_test -O--builddirectory=_build -O--buildsystem=golang 174s 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 191s === RUN ExampleLastEntryInfo 191s --- PASS: ExampleLastEntryInfo (0.00s) 191s PASS 191s ok github.com/canonical/go-dqlite 0.002s 372s === RUN TestNew_PristineDefault 372s --- PASS: TestNew_PristineDefault (0.09s) 372s === RUN TestNew_PristineJoiner 372s app_test.go:1207: 04:23:11.079 - 2: DEBUG: new connection from 127.0.0.1:48538 372s app_test.go:1207: 04:23:11.081 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.082 - 2: DEBUG: new connection from 127.0.0.1:48550 372s app_test.go:1207: 04:23:11.157 - 2: DEBUG: new connection from 127.0.0.1:48566 372s app_test.go:1207: 04:23:11.158 - 3: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.162 - 2: DEBUG: new connection from 127.0.0.1:48568 372s app_test.go:1207: 04:23:11.163 - 3: DEBUG: new connection from 127.0.0.1:43832 372s app_test.go:1207: 04:23:11.164 - 2: DEBUG: new connection from 127.0.0.1:48580 372s app_test.go:1207: 04:23:11.165 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s --- PASS: TestNew_PristineJoiner (0.25s) 372s === RUN TestNew_JoinerRestart 372s app_test.go:1207: 04:23:11.320 - 4: DEBUG: new connection from 127.0.0.1:48588 372s app_test.go:1207: 04:23:11.321 - 4: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.322 - 4: DEBUG: new connection from 127.0.0.1:48600 372s app_test.go:1207: 04:23:11.408 - 4: DEBUG: new connection from 127.0.0.1:48612 372s app_test.go:1207: 04:23:11.409 - 5: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.416 - 5: DEBUG: new connection from 127.0.0.1:43848 372s app_test.go:1207: 04:23:11.416 - 4: DEBUG: new connection from 127.0.0.1:48626 372s app_test.go:1207: 04:23:11.507 - 4: DEBUG: new connection from 127.0.0.1:48630 372s app_test.go:1207: 04:23:11.507 - 6: DEBUG: new connection from 127.0.0.1:43852 372s app_test.go:1207: 04:23:11.508 - 6: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.508 - 6: WARN: attempt 1: server 127.0.0.1:9002: no known leader 372s app_test.go:1207: 04:23:11.510 - 6: DEBUG: new connection from 127.0.0.1:43856 372s app_test.go:1207: 04:23:11.511 - 4: DEBUG: new connection from 127.0.0.1:48634 372s --- PASS: TestNew_JoinerRestart (0.36s) 372s === RUN TestNew_SecondJoiner 372s app_test.go:1207: 04:23:11.679 - 7: DEBUG: new connection from 127.0.0.1:48650 372s app_test.go:1207: 04:23:11.681 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.683 - 7: DEBUG: new connection from 127.0.0.1:48658 372s app_test.go:1207: 04:23:11.757 - 7: DEBUG: new connection from 127.0.0.1:48664 372s app_test.go:1207: 04:23:11.758 - 8: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.762 - 7: DEBUG: new connection from 127.0.0.1:48680 372s app_test.go:1207: 04:23:11.763 - 8: DEBUG: new connection from 127.0.0.1:43858 372s app_test.go:1207: 04:23:11.847 - 7: DEBUG: new connection from 127.0.0.1:48684 372s app_test.go:1207: 04:23:11.848 - 9: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.850 - 7: DEBUG: new connection from 127.0.0.1:48692 372s app_test.go:1207: 04:23:11.850 - 8: DEBUG: new connection from 127.0.0.1:43872 372s app_test.go:1207: 04:23:11.851 - 9: DEBUG: new connection from 127.0.0.1:41836 372s app_test.go:1207: 04:23:11.852 - 9: DEBUG: new connection from 127.0.0.1:41840 372s app_test.go:1207: 04:23:11.854 - 7: DEBUG: new connection from 127.0.0.1:48700 372s app_test.go:1207: 04:23:11.593 - 8: DEBUG: new connection from 127.0.0.1:43888 372s app_test.go:1207: 04:23:11.620 - 7: DEBUG: new connection from 127.0.0.1:48706 372s app_test.go:1207: 04:23:11.595 - 7: DEBUG: new connection from 127.0.0.1:48708 372s app_test.go:1207: 04:23:11.596 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s --- PASS: TestNew_SecondJoiner (2.26s) 372s === RUN TestNew_ThirdJoiner 372s app_test.go:1207: 04:23:11.940 - 10: DEBUG: new connection from 127.0.0.1:48716 372s app_test.go:1207: 04:23:11.942 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.943 - 10: DEBUG: new connection from 127.0.0.1:48718 372s app_test.go:1207: 04:23:11.028 - 10: DEBUG: new connection from 127.0.0.1:48726 372s app_test.go:1207: 04:23:11.029 - 11: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.034 - 11: DEBUG: new connection from 127.0.0.1:43890 372s app_test.go:1207: 04:23:11.034 - 10: DEBUG: new connection from 127.0.0.1:48738 372s app_test.go:1207: 04:23:11.117 - 10: DEBUG: new connection from 127.0.0.1:48752 372s app_test.go:1207: 04:23:11.118 - 12: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.120 - 10: DEBUG: new connection from 127.0.0.1:48760 372s app_test.go:1207: 04:23:11.120 - 11: DEBUG: new connection from 127.0.0.1:43906 372s app_test.go:1207: 04:23:11.121 - 12: DEBUG: new connection from 127.0.0.1:41850 372s app_test.go:1207: 04:23:11.122 - 12: DEBUG: new connection from 127.0.0.1:41852 372s app_test.go:1207: 04:23:11.125 - 10: DEBUG: new connection from 127.0.0.1:48772 372s app_test.go:1207: 04:23:11.854 - 11: DEBUG: new connection from 127.0.0.1:43916 372s app_test.go:1207: 04:23:11.857 - 10: DEBUG: new connection from 127.0.0.1:48782 372s app_test.go:1207: 04:23:11.947 - 10: DEBUG: new connection from 127.0.0.1:48798 372s app_test.go:1207: 04:23:11.949 - 13: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.951 - 11: DEBUG: new connection from 127.0.0.1:43924 372s app_test.go:1207: 04:23:11.951 - 10: DEBUG: new connection from 127.0.0.1:48804 372s app_test.go:1207: 04:23:11.952 - 12: DEBUG: new connection from 127.0.0.1:41860 372s app_test.go:1207: 04:23:11.952 - 13: DEBUG: new connection from 127.0.0.1:55016 372s app_test.go:1207: 04:23:11.953 - 13: DEBUG: new connection from 127.0.0.1:55026 372s app_test.go:1207: 04:23:11.955 - 10: DEBUG: new connection from 127.0.0.1:48808 372s app_test.go:1207: 04:23:11.956 - 10: DEBUG: new connection from 127.0.0.1:48816 372s app_test.go:1207: 04:23:11.956 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s --- PASS: TestNew_ThirdJoiner (2.32s) 372s === RUN TestNew_FourthJoiner 372s app_test.go:1207: 04:23:11.260 - 14: DEBUG: new connection from 127.0.0.1:48830 372s app_test.go:1207: 04:23:11.262 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.263 - 14: DEBUG: new connection from 127.0.0.1:48846 372s app_test.go:1207: 04:23:11.347 - 14: DEBUG: new connection from 127.0.0.1:48856 372s app_test.go:1207: 04:23:11.348 - 15: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.353 - 14: DEBUG: new connection from 127.0.0.1:48868 372s app_test.go:1207: 04:23:11.353 - 15: DEBUG: new connection from 127.0.0.1:43936 372s app_test.go:1207: 04:23:11.437 - 14: DEBUG: new connection from 127.0.0.1:48880 372s app_test.go:1207: 04:23:11.438 - 16: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.440 - 14: DEBUG: new connection from 127.0.0.1:48892 372s app_test.go:1207: 04:23:11.440 - 15: DEBUG: new connection from 127.0.0.1:43948 372s app_test.go:1207: 04:23:11.441 - 16: DEBUG: new connection from 127.0.0.1:41866 372s app_test.go:1207: 04:23:11.442 - 16: DEBUG: new connection from 127.0.0.1:41872 372s app_test.go:1207: 04:23:11.444 - 14: DEBUG: new connection from 127.0.0.1:48908 372s app_test.go:1207: 04:23:11.173 - 15: DEBUG: new connection from 127.0.0.1:43950 372s app_test.go:1207: 04:23:11.176 - 14: DEBUG: new connection from 127.0.0.1:48912 372s app_test.go:1207: 04:23:11.268 - 14: DEBUG: new connection from 127.0.0.1:48924 372s app_test.go:1207: 04:23:11.270 - 17: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.271 - 15: DEBUG: new connection from 127.0.0.1:43964 372s app_test.go:1207: 04:23:11.272 - 14: DEBUG: new connection from 127.0.0.1:48936 372s app_test.go:1207: 04:23:11.272 - 16: DEBUG: new connection from 127.0.0.1:41888 372s app_test.go:1207: 04:23:11.272 - 17: DEBUG: new connection from 127.0.0.1:55028 372s app_test.go:1207: 04:23:11.273 - 17: DEBUG: new connection from 127.0.0.1:55038 372s app_test.go:1207: 04:23:11.277 - 14: DEBUG: new connection from 127.0.0.1:48938 372s app_test.go:1207: 04:23:11.357 - 14: DEBUG: new connection from 127.0.0.1:48952 372s app_test.go:1207: 04:23:11.358 - 18: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.360 - 15: DEBUG: new connection from 127.0.0.1:43974 372s app_test.go:1207: 04:23:11.360 - 14: DEBUG: new connection from 127.0.0.1:48964 372s app_test.go:1207: 04:23:11.361 - 17: DEBUG: new connection from 127.0.0.1:55054 372s app_test.go:1207: 04:23:11.361 - 16: DEBUG: new connection from 127.0.0.1:41894 372s app_test.go:1207: 04:23:11.363 - 18: DEBUG: new connection from 127.0.0.1:38742 372s app_test.go:1207: 04:23:11.364 - 18: DEBUG: new connection from 127.0.0.1:38748 372s app_test.go:1207: 04:23:11.365 - 14: DEBUG: new connection from 127.0.0.1:48970 372s app_test.go:1207: 04:23:11.366 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s --- PASS: TestNew_FourthJoiner (2.48s) 372s === RUN TestNew_FifthJoiner 372s app_test.go:1207: 04:23:11.729 - 19: DEBUG: new connection from 127.0.0.1:48984 372s app_test.go:1207: 04:23:11.731 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.731 - 19: DEBUG: new connection from 127.0.0.1:48986 372s app_test.go:1207: 04:23:11.807 - 19: DEBUG: new connection from 127.0.0.1:48988 372s app_test.go:1207: 04:23:11.809 - 20: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.813 - 20: DEBUG: new connection from 127.0.0.1:43978 372s app_test.go:1207: 04:23:11.813 - 19: DEBUG: new connection from 127.0.0.1:48994 372s app_test.go:1207: 04:23:11.898 - 19: DEBUG: new connection from 127.0.0.1:49002 372s app_test.go:1207: 04:23:11.899 - 21: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.900 - 20: DEBUG: new connection from 127.0.0.1:43994 372s app_test.go:1207: 04:23:11.900 - 19: DEBUG: new connection from 127.0.0.1:49004 372s app_test.go:1207: 04:23:11.901 - 21: DEBUG: new connection from 127.0.0.1:41902 372s app_test.go:1207: 04:23:11.902 - 21: DEBUG: new connection from 127.0.0.1:41914 372s app_test.go:1207: 04:23:11.904 - 19: DEBUG: new connection from 127.0.0.1:49020 372s app_test.go:1207: 04:23:11.655 - 20: DEBUG: new connection from 127.0.0.1:44004 372s app_test.go:1207: 04:23:11.658 - 19: DEBUG: new connection from 127.0.0.1:49032 372s app_test.go:1207: 04:23:11.747 - 19: DEBUG: new connection from 127.0.0.1:49036 372s app_test.go:1207: 04:23:11.749 - 22: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.751 - 19: DEBUG: new connection from 127.0.0.1:49038 372s app_test.go:1207: 04:23:11.751 - 20: DEBUG: new connection from 127.0.0.1:44012 372s app_test.go:1207: 04:23:11.754 - 21: DEBUG: new connection from 127.0.0.1:41918 372s app_test.go:1207: 04:23:11.755 - 22: DEBUG: new connection from 127.0.0.1:55066 372s app_test.go:1207: 04:23:11.757 - 22: DEBUG: new connection from 127.0.0.1:55068 372s app_test.go:1207: 04:23:11.760 - 19: DEBUG: new connection from 127.0.0.1:49052 372s app_test.go:1207: 04:23:11.837 - 19: DEBUG: new connection from 127.0.0.1:45924 372s app_test.go:1207: 04:23:11.839 - 23: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.844 - 19: DEBUG: new connection from 127.0.0.1:45928 372s app_test.go:1207: 04:23:11.844 - 20: DEBUG: new connection from 127.0.0.1:45012 372s app_test.go:1207: 04:23:11.845 - 21: DEBUG: new connection from 127.0.0.1:60960 372s app_test.go:1207: 04:23:11.845 - 22: DEBUG: new connection from 127.0.0.1:55556 372s app_test.go:1207: 04:23:11.847 - 23: DEBUG: new connection from 127.0.0.1:58534 372s app_test.go:1207: 04:23:11.848 - 23: DEBUG: new connection from 127.0.0.1:58544 372s app_test.go:1207: 04:23:11.851 - 19: DEBUG: new connection from 127.0.0.1:45940 372s app_test.go:1207: 04:23:11.927 - 19: DEBUG: new connection from 127.0.0.1:45956 372s app_test.go:1207: 04:23:11.928 - 24: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.958 - 20: DEBUG: new connection from 127.0.0.1:45014 372s app_test.go:1207: 04:23:11.958 - 19: DEBUG: new connection from 127.0.0.1:45962 372s app_test.go:1207: 04:23:11.960 - 21: DEBUG: new connection from 127.0.0.1:60966 372s app_test.go:1207: 04:23:11.960 - 22: DEBUG: new connection from 127.0.0.1:55566 372s app_test.go:1207: 04:23:11.961 - 23: DEBUG: new connection from 127.0.0.1:58554 372s app_test.go:1207: 04:23:11.961 - 24: DEBUG: new connection from 127.0.0.1:33542 372s app_test.go:1207: 04:23:11.962 - 24: DEBUG: new connection from 127.0.0.1:33558 372s app_test.go:1207: 04:23:11.963 - 19: DEBUG: new connection from 127.0.0.1:45972 372s app_test.go:1207: 04:23:11.964 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s --- PASS: TestNew_FifthJoiner (2.54s) 372s === RUN TestNew_SixthJoiner 372s app_test.go:1207: 04:23:11.279 - 25: DEBUG: new connection from 127.0.0.1:45982 372s app_test.go:1207: 04:23:11.280 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.281 - 25: DEBUG: new connection from 127.0.0.1:45998 372s app_test.go:1207: 04:23:11.357 - 25: DEBUG: new connection from 127.0.0.1:46000 372s app_test.go:1207: 04:23:11.358 - 26: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.361 - 25: DEBUG: new connection from 127.0.0.1:46006 372s app_test.go:1207: 04:23:11.362 - 26: DEBUG: new connection from 127.0.0.1:45020 372s app_test.go:1207: 04:23:11.457 - 25: DEBUG: new connection from 127.0.0.1:46014 372s app_test.go:1207: 04:23:11.458 - 27: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.460 - 26: DEBUG: new connection from 127.0.0.1:45030 372s app_test.go:1207: 04:23:11.460 - 25: DEBUG: new connection from 127.0.0.1:46022 372s app_test.go:1207: 04:23:11.461 - 27: DEBUG: new connection from 127.0.0.1:60976 372s app_test.go:1207: 04:23:11.462 - 27: DEBUG: new connection from 127.0.0.1:60984 372s app_test.go:1207: 04:23:11.464 - 25: DEBUG: new connection from 127.0.0.1:46028 372s app_test.go:1207: 04:23:11.195 - 26: DEBUG: new connection from 127.0.0.1:45044 372s app_test.go:1207: 04:23:11.197 - 25: DEBUG: new connection from 127.0.0.1:46032 372s app_test.go:1207: 04:23:11.278 - 25: DEBUG: new connection from 127.0.0.1:46048 372s app_test.go:1207: 04:23:11.279 - 28: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.281 - 25: DEBUG: new connection from 127.0.0.1:46064 372s app_test.go:1207: 04:23:11.281 - 26: DEBUG: new connection from 127.0.0.1:45046 372s app_test.go:1207: 04:23:11.282 - 27: DEBUG: new connection from 127.0.0.1:60986 372s app_test.go:1207: 04:23:11.283 - 28: DEBUG: new connection from 127.0.0.1:55568 372s app_test.go:1207: 04:23:11.284 - 28: DEBUG: new connection from 127.0.0.1:55572 372s app_test.go:1207: 04:23:11.288 - 25: DEBUG: new connection from 127.0.0.1:46072 372s app_test.go:1207: 04:23:11.368 - 25: DEBUG: new connection from 127.0.0.1:46080 372s app_test.go:1207: 04:23:11.369 - 29: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.371 - 26: DEBUG: new connection from 127.0.0.1:45054 372s app_test.go:1207: 04:23:11.371 - 25: DEBUG: new connection from 127.0.0.1:46084 372s app_test.go:1207: 04:23:11.373 - 27: DEBUG: new connection from 127.0.0.1:60996 372s app_test.go:1207: 04:23:11.374 - 28: DEBUG: new connection from 127.0.0.1:55588 372s app_test.go:1207: 04:23:11.375 - 29: DEBUG: new connection from 127.0.0.1:58562 372s app_test.go:1207: 04:23:11.378 - 29: DEBUG: new connection from 127.0.0.1:58574 372s app_test.go:1207: 04:23:11.381 - 25: DEBUG: new connection from 127.0.0.1:46092 372s app_test.go:1207: 04:23:11.448 - 25: DEBUG: new connection from 127.0.0.1:46102 372s app_test.go:1207: 04:23:11.449 - 30: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.452 - 25: DEBUG: new connection from 127.0.0.1:46106 372s app_test.go:1207: 04:23:11.452 - 26: DEBUG: new connection from 127.0.0.1:45070 372s app_test.go:1207: 04:23:11.453 - 27: DEBUG: new connection from 127.0.0.1:32780 372s app_test.go:1207: 04:23:11.454 - 28: DEBUG: new connection from 127.0.0.1:55594 372s app_test.go:1207: 04:23:11.454 - 29: DEBUG: new connection from 127.0.0.1:58580 372s app_test.go:1207: 04:23:11.455 - 30: DEBUG: new connection from 127.0.0.1:33566 372s app_test.go:1207: 04:23:11.456 - 30: DEBUG: new connection from 127.0.0.1:33572 372s app_test.go:1207: 04:23:11.459 - 25: DEBUG: new connection from 127.0.0.1:46110 372s app_test.go:1207: 04:23:11.538 - 25: DEBUG: new connection from 127.0.0.1:46112 372s app_test.go:1207: 04:23:11.539 - 31: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.543 - 26: DEBUG: new connection from 127.0.0.1:45076 372s app_test.go:1207: 04:23:11.543 - 25: DEBUG: new connection from 127.0.0.1:46116 372s app_test.go:1207: 04:23:11.544 - 27: DEBUG: new connection from 127.0.0.1:32782 372s app_test.go:1207: 04:23:11.545 - 28: DEBUG: new connection from 127.0.0.1:55608 372s app_test.go:1207: 04:23:11.546 - 29: DEBUG: new connection from 127.0.0.1:58594 372s app_test.go:1207: 04:23:11.546 - 30: DEBUG: new connection from 127.0.0.1:33576 372s app_test.go:1207: 04:23:11.547 - 31: DEBUG: new connection from 127.0.0.1:50150 372s app_test.go:1207: 04:23:11.548 - 25: DEBUG: new connection from 127.0.0.1:46132 372s app_test.go:1207: 04:23:11.549 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s --- PASS: TestNew_SixthJoiner (2.60s) 372s === RUN TestHandover_Voter 372s app_test.go:1207: 04:23:11.850 - 32: DEBUG: new connection from 127.0.0.1:46136 372s app_test.go:1207: 04:23:11.851 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.853 - 32: DEBUG: new connection from 127.0.0.1:46152 372s app_test.go:1207: 04:23:11.948 - 32: DEBUG: new connection from 127.0.0.1:46160 372s app_test.go:1207: 04:23:11.949 - 33: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.953 - 32: DEBUG: new connection from 127.0.0.1:46164 372s app_test.go:1207: 04:23:11.953 - 33: DEBUG: new connection from 127.0.0.1:45090 372s app_test.go:1207: 04:23:11.028 - 32: DEBUG: new connection from 127.0.0.1:46172 372s app_test.go:1207: 04:23:11.029 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.031 - 33: DEBUG: new connection from 127.0.0.1:45094 372s app_test.go:1207: 04:23:11.031 - 32: DEBUG: new connection from 127.0.0.1:46184 372s app_test.go:1207: 04:23:11.032 - 34: DEBUG: new connection from 127.0.0.1:32786 372s app_test.go:1207: 04:23:11.033 - 34: DEBUG: new connection from 127.0.0.1:32794 372s app_test.go:1207: 04:23:11.035 - 32: DEBUG: new connection from 127.0.0.1:46186 372s app_test.go:1207: 04:23:11.795 - 33: DEBUG: new connection from 127.0.0.1:45106 372s app_test.go:1207: 04:23:11.797 - 32: DEBUG: new connection from 127.0.0.1:46196 372s app_test.go:1207: 04:23:11.888 - 32: DEBUG: new connection from 127.0.0.1:46212 372s app_test.go:1207: 04:23:11.889 - 35: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.892 - 32: DEBUG: new connection from 127.0.0.1:46216 372s app_test.go:1207: 04:23:11.892 - 33: DEBUG: new connection from 127.0.0.1:45116 372s app_test.go:1207: 04:23:11.894 - 34: DEBUG: new connection from 127.0.0.1:32800 372s app_test.go:1207: 04:23:11.894 - 35: DEBUG: new connection from 127.0.0.1:55620 372s app_test.go:1207: 04:23:11.895 - 35: DEBUG: new connection from 127.0.0.1:55634 372s app_test.go:1207: 04:23:11.897 - 32: DEBUG: new connection from 127.0.0.1:46226 372s app_test.go:1207: 04:23:11.898 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.898 - 32: DEBUG: new connection from 127.0.0.1:46230 372s app_test.go:1207: 04:23:11.899 - 32: DEBUG: new connection from 127.0.0.1:46240 372s app_test.go:1207: 04:23:11.900 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.900 - 33: DEBUG: new connection from 127.0.0.1:45126 372s app_test.go:1207: 04:23:11.900 - 32: DEBUG: new connection from 127.0.0.1:46246 372s app_test.go:1207: 04:23:11.902 - 34: DEBUG: new connection from 127.0.0.1:32808 372s app_test.go:1207: 04:23:11.902 - 35: DEBUG: new connection from 127.0.0.1:55650 372s app_test.go:1207: 04:23:11.798 - 34: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 372s --- PASS: TestHandover_Voter (3.34s) 372s === RUN TestHandover_TwoNodes 372s app_test.go:1207: 04:23:11.219 - 36: DEBUG: new connection from 127.0.0.1:46258 372s app_test.go:1207: 04:23:11.220 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.221 - 36: DEBUG: new connection from 127.0.0.1:46264 372s app_test.go:1207: 04:23:11.297 - 36: DEBUG: new connection from 127.0.0.1:46268 372s app_test.go:1207: 04:23:11.298 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.301 - 36: DEBUG: new connection from 127.0.0.1:46282 372s app_test.go:1207: 04:23:11.301 - 37: DEBUG: new connection from 127.0.0.1:45142 372s app_test.go:1207: 04:23:11.302 - 36: DEBUG: new connection from 127.0.0.1:46286 372s app_test.go:1207: 04:23:11.304 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.304 - 36: DEBUG: new connection from 127.0.0.1:46300 372s app_test.go:1207: 04:23:11.304 - 37: DEBUG: new connection from 127.0.0.1:45152 372s app_test.go:1207: 04:23:11.305 - 37: DEBUG: new connection from 127.0.0.1:45162 372s app_test.go:1207: 04:23:11.307 - 36: DEBUG: new connection from 127.0.0.1:46304 372s app_test.go:1207: 04:23:11.134 - 36: DEBUG: promoted 127.0.0.1:9002 from spare to voter 372s app_test.go:1207: 04:23:11.135 - 37: DEBUG: new connection from 127.0.0.1:45170 372s app_test.go:1207: 04:23:11.135 - 36: DEBUG: new connection from 127.0.0.1:46312 372s app_test.go:1207: 04:23:11.138 - 36: DEBUG: new connection from 127.0.0.1:46314 372s app_test.go:1207: 04:23:11.139 - 36: DEBUG: new connection from 127.0.0.1:46316 372s app_test.go:1207: 04:23:11.140 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 372s app_test.go:1207: 04:23:11.140 - 37: DEBUG: new connection from 127.0.0.1:45186 372s app_test.go:1207: 04:23:11.140 - 36: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 372s app_test.go:1207: 04:23:11.141 - 37: DEBUG: new connection from 127.0.0.1:45188 372s app_test.go:1207: 04:23:11.141 - 36: DEBUG: new connection from 127.0.0.1:46318 372s app_test.go:1207: 04:23:11.142 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 372s app_test.go:1207: 04:23:11.142 - 37: DEBUG: new connection from 127.0.0.1:45204 372s app_test.go:1207: 04:23:11.142 - 37: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 372s --- PASS: TestHandover_TwoNodes (1.20s) 372s === RUN TestHandover_VoterHonorFailureDomain 372s app_test.go:1207: 04:23:11.399 - 38: DEBUG: new connection from 127.0.0.1:46332 372s app_test.go:1207: 04:23:11.400 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.401 - 38: DEBUG: new connection from 127.0.0.1:46334 372s app_test.go:1207: 04:23:11.477 - 38: DEBUG: new connection from 127.0.0.1:46342 372s app_test.go:1207: 04:23:11.478 - 39: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.481 - 39: DEBUG: new connection from 127.0.0.1:45210 372s app_test.go:1207: 04:23:11.481 - 38: DEBUG: new connection from 127.0.0.1:46348 372s app_test.go:1207: 04:23:11.557 - 38: DEBUG: new connection from 127.0.0.1:46356 372s app_test.go:1207: 04:23:11.558 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.560 - 39: DEBUG: new connection from 127.0.0.1:45220 372s app_test.go:1207: 04:23:11.560 - 38: DEBUG: new connection from 127.0.0.1:46370 372s app_test.go:1207: 04:23:11.561 - 40: DEBUG: new connection from 127.0.0.1:32824 372s app_test.go:1207: 04:23:11.562 - 40: DEBUG: new connection from 127.0.0.1:32828 372s app_test.go:1207: 04:23:11.564 - 38: DEBUG: new connection from 127.0.0.1:46376 372s app_test.go:1207: 04:23:11.333 - 39: DEBUG: new connection from 127.0.0.1:45236 372s app_test.go:1207: 04:23:11.335 - 38: DEBUG: new connection from 127.0.0.1:46388 372s app_test.go:1207: 04:23:11.427 - 38: DEBUG: new connection from 127.0.0.1:46392 372s app_test.go:1207: 04:23:11.429 - 41: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.430 - 38: DEBUG: new connection from 127.0.0.1:46398 372s app_test.go:1207: 04:23:11.430 - 39: DEBUG: new connection from 127.0.0.1:45246 372s app_test.go:1207: 04:23:11.431 - 40: DEBUG: new connection from 127.0.0.1:32842 372s app_test.go:1207: 04:23:11.432 - 41: DEBUG: new connection from 127.0.0.1:55658 372s app_test.go:1207: 04:23:11.433 - 41: DEBUG: new connection from 127.0.0.1:55666 372s app_test.go:1207: 04:23:11.436 - 38: DEBUG: new connection from 127.0.0.1:46408 372s app_test.go:1207: 04:23:11.528 - 38: DEBUG: new connection from 127.0.0.1:46410 372s app_test.go:1207: 04:23:11.528 - 42: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.530 - 39: DEBUG: new connection from 127.0.0.1:45262 372s app_test.go:1207: 04:23:11.530 - 38: DEBUG: new connection from 127.0.0.1:46414 372s app_test.go:1207: 04:23:11.532 - 40: DEBUG: new connection from 127.0.0.1:32854 372s app_test.go:1207: 04:23:11.532 - 41: DEBUG: new connection from 127.0.0.1:55672 372s app_test.go:1207: 04:23:11.533 - 42: DEBUG: new connection from 127.0.0.1:58604 372s app_test.go:1207: 04:23:11.534 - 42: DEBUG: new connection from 127.0.0.1:58608 372s app_test.go:1207: 04:23:11.536 - 38: DEBUG: new connection from 127.0.0.1:46416 372s app_test.go:1207: 04:23:11.628 - 38: DEBUG: new connection from 127.0.0.1:46420 372s app_test.go:1207: 04:23:11.629 - 43: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.630 - 39: DEBUG: new connection from 127.0.0.1:45276 372s app_test.go:1207: 04:23:11.631 - 38: DEBUG: new connection from 127.0.0.1:46422 372s app_test.go:1207: 04:23:11.632 - 40: DEBUG: new connection from 127.0.0.1:32862 372s app_test.go:1207: 04:23:11.632 - 41: DEBUG: new connection from 127.0.0.1:55678 372s app_test.go:1207: 04:23:11.633 - 42: DEBUG: new connection from 127.0.0.1:58610 372s app_test.go:1207: 04:23:11.633 - 43: DEBUG: new connection from 127.0.0.1:33580 372s app_test.go:1207: 04:23:11.634 - 43: DEBUG: new connection from 127.0.0.1:33596 372s app_test.go:1207: 04:23:11.635 - 38: DEBUG: new connection from 127.0.0.1:46432 372s app_test.go:1207: 04:23:11.635 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.636 - 38: DEBUG: new connection from 127.0.0.1:46444 372s app_test.go:1207: 04:23:11.636 - 38: DEBUG: new connection from 127.0.0.1:46460 372s app_test.go:1207: 04:23:11.636 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.637 - 38: DEBUG: new connection from 127.0.0.1:46462 372s app_test.go:1207: 04:23:11.637 - 39: DEBUG: new connection from 127.0.0.1:45292 372s app_test.go:1207: 04:23:11.639 - 40: DEBUG: new connection from 127.0.0.1:32864 372s app_test.go:1207: 04:23:11.639 - 41: DEBUG: new connection from 127.0.0.1:55684 372s app_test.go:1207: 04:23:11.640 - 42: DEBUG: new connection from 127.0.0.1:58612 372s app_test.go:1207: 04:23:11.640 - 43: DEBUG: new connection from 127.0.0.1:33612 372s app_test.go:1207: 04:23:11.337 - 40: DEBUG: promoted 127.0.0.1:9006 from stand-by to voter 372s --- PASS: TestHandover_VoterHonorFailureDomain (3.48s) 372s === RUN TestHandover_SingleNode 372s --- PASS: TestHandover_SingleNode (0.06s) 372s === RUN TestHandover_GracefulShutdown 372s app_test.go:406: 04:23:11.969 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:406: 04:23:11.047 - 1: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:406: 04:23:11.127 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:406: 04:23:11.877 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:406: 04:23:11.879 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:406: 04:23:11.882 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 372s app_test.go:406: 04:23:11.882 - 0: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 372s app_test.go:406: 04:23:11.967 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 372s app_test.go:406: 04:23:11.968 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 372s app_test.go:406: 04:23:11.969 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 372s app_test.go:406: 04:23:11.970 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9003 372s app_test.go:406: 04:23:11.970 - 1: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 372s app_test.go:406: 04:23:11.057 - 2: WARN: attempt 1: server 127.0.0.1:9002: dial: dial tcp 127.0.0.1:9002: connect: connection refused 372s app_test.go:406: 04:23:11.057 - 2: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 372s app_test.go:406: 04:23:11.058 - 2: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 372s --- PASS: TestHandover_GracefulShutdown (2.26s) 372s === RUN TestHandover_StandBy 372s app_test.go:1207: 04:23:11.219 - 44: DEBUG: new connection from 127.0.0.1:54644 372s app_test.go:1207: 04:23:11.221 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.222 - 44: DEBUG: new connection from 127.0.0.1:54646 372s app_test.go:1207: 04:23:11.297 - 44: DEBUG: new connection from 127.0.0.1:54660 372s app_test.go:1207: 04:23:11.298 - 45: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.301 - 45: DEBUG: new connection from 127.0.0.1:39104 372s app_test.go:1207: 04:23:11.301 - 44: DEBUG: new connection from 127.0.0.1:54676 372s app_test.go:1207: 04:23:11.397 - 44: DEBUG: new connection from 127.0.0.1:54678 372s app_test.go:1207: 04:23:11.398 - 46: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.399 - 44: DEBUG: new connection from 127.0.0.1:54684 372s app_test.go:1207: 04:23:11.400 - 45: DEBUG: new connection from 127.0.0.1:39114 372s app_test.go:1207: 04:23:11.401 - 46: DEBUG: new connection from 127.0.0.1:35090 372s app_test.go:1207: 04:23:11.402 - 46: DEBUG: new connection from 127.0.0.1:35096 372s app_test.go:1207: 04:23:11.403 - 44: DEBUG: new connection from 127.0.0.1:54686 372s app_test.go:1207: 04:23:11.133 - 45: DEBUG: new connection from 127.0.0.1:39128 372s app_test.go:1207: 04:23:11.137 - 44: DEBUG: new connection from 127.0.0.1:54698 372s app_test.go:1207: 04:23:11.218 - 44: DEBUG: new connection from 127.0.0.1:54706 372s app_test.go:1207: 04:23:11.219 - 47: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.220 - 44: DEBUG: new connection from 127.0.0.1:54716 372s app_test.go:1207: 04:23:11.220 - 45: DEBUG: new connection from 127.0.0.1:39132 372s app_test.go:1207: 04:23:11.221 - 46: DEBUG: new connection from 127.0.0.1:35100 372s app_test.go:1207: 04:23:11.221 - 47: DEBUG: new connection from 127.0.0.1:52086 372s app_test.go:1207: 04:23:11.222 - 47: DEBUG: new connection from 127.0.0.1:52102 372s app_test.go:1207: 04:23:11.224 - 44: DEBUG: new connection from 127.0.0.1:54726 372s app_test.go:1207: 04:23:11.317 - 44: DEBUG: new connection from 127.0.0.1:54742 372s app_test.go:1207: 04:23:11.318 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.320 - 45: DEBUG: new connection from 127.0.0.1:39138 372s app_test.go:1207: 04:23:11.320 - 44: DEBUG: new connection from 127.0.0.1:54746 372s app_test.go:1207: 04:23:11.321 - 46: DEBUG: new connection from 127.0.0.1:35102 372s app_test.go:1207: 04:23:11.321 - 47: DEBUG: new connection from 127.0.0.1:52110 372s app_test.go:1207: 04:23:11.323 - 48: DEBUG: new connection from 127.0.0.1:42290 372s app_test.go:1207: 04:23:11.324 - 48: DEBUG: new connection from 127.0.0.1:42298 372s app_test.go:1207: 04:23:11.327 - 44: DEBUG: new connection from 127.0.0.1:54752 372s app_test.go:1207: 04:23:11.417 - 44: DEBUG: new connection from 127.0.0.1:54768 372s app_test.go:1207: 04:23:11.418 - 49: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.420 - 44: DEBUG: new connection from 127.0.0.1:54774 372s app_test.go:1207: 04:23:11.420 - 45: DEBUG: new connection from 127.0.0.1:39150 372s app_test.go:1207: 04:23:11.421 - 46: DEBUG: new connection from 127.0.0.1:35116 372s app_test.go:1207: 04:23:11.422 - 47: DEBUG: new connection from 127.0.0.1:52112 372s app_test.go:1207: 04:23:11.422 - 48: DEBUG: new connection from 127.0.0.1:42300 372s app_test.go:1207: 04:23:11.424 - 49: DEBUG: new connection from 127.0.0.1:51390 372s app_test.go:1207: 04:23:11.425 - 49: DEBUG: new connection from 127.0.0.1:51392 372s app_test.go:1207: 04:23:11.427 - 44: DEBUG: new connection from 127.0.0.1:54790 372s app_test.go:1207: 04:23:11.517 - 44: DEBUG: new connection from 127.0.0.1:54798 372s app_test.go:1207: 04:23:11.518 - 50: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.520 - 45: DEBUG: new connection from 127.0.0.1:39166 372s app_test.go:1207: 04:23:11.520 - 44: DEBUG: new connection from 127.0.0.1:54810 372s app_test.go:1207: 04:23:11.520 - 46: DEBUG: new connection from 127.0.0.1:35130 372s app_test.go:1207: 04:23:11.522 - 47: DEBUG: new connection from 127.0.0.1:52128 372s app_test.go:1207: 04:23:11.522 - 48: DEBUG: new connection from 127.0.0.1:42314 372s app_test.go:1207: 04:23:11.523 - 49: DEBUG: new connection from 127.0.0.1:51398 372s app_test.go:1207: 04:23:11.523 - 50: DEBUG: new connection from 127.0.0.1:33078 372s app_test.go:1207: 04:23:11.524 - 44: DEBUG: new connection from 127.0.0.1:54820 372s app_test.go:1207: 04:23:11.525 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.525 - 44: DEBUG: new connection from 127.0.0.1:54826 372s app_test.go:1207: 04:23:11.526 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.526 - 45: DEBUG: new connection from 127.0.0.1:39182 372s app_test.go:1207: 04:23:11.526 - 44: DEBUG: new connection from 127.0.0.1:54840 372s app_test.go:1207: 04:23:11.527 - 46: DEBUG: new connection from 127.0.0.1:35142 372s app_test.go:1207: 04:23:11.527 - 47: DEBUG: new connection from 127.0.0.1:52142 372s app_test.go:1207: 04:23:11.528 - 48: DEBUG: new connection from 127.0.0.1:42324 372s app_test.go:1207: 04:23:11.528 - 49: DEBUG: new connection from 127.0.0.1:51404 372s app_test.go:1207: 04:23:11.530 - 50: DEBUG: new connection from 127.0.0.1:33088 372s app_test.go:1207: 04:23:11.531 - 50: DEBUG: new connection from 127.0.0.1:33102 372s app_test.go:1207: 04:23:11.531 - 48: DEBUG: promoted 127.0.0.1:9007 from spare to stand-by 372s --- PASS: TestHandover_StandBy (2.63s) 372s === RUN TestHandover_TransferLeadership 372s app_test.go:1207: 04:23:11.839 - 51: DEBUG: new connection from 127.0.0.1:54854 372s app_test.go:1207: 04:23:11.840 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.841 - 51: DEBUG: new connection from 127.0.0.1:54858 372s app_test.go:1207: 04:23:11.927 - 51: DEBUG: new connection from 127.0.0.1:54860 372s app_test.go:1207: 04:23:11.929 - 52: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.931 - 52: DEBUG: new connection from 127.0.0.1:39196 372s app_test.go:1207: 04:23:11.931 - 51: DEBUG: new connection from 127.0.0.1:54870 372s app_test.go:1207: 04:23:11.997 - 51: DEBUG: new connection from 127.0.0.1:54878 372s app_test.go:1207: 04:23:11.998 - 53: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.999 - 51: DEBUG: new connection from 127.0.0.1:54892 372s app_test.go:1207: 04:23:11.000 - 52: DEBUG: new connection from 127.0.0.1:39198 372s app_test.go:1207: 04:23:11.000 - 53: DEBUG: new connection from 127.0.0.1:35154 372s app_test.go:1207: 04:23:11.002 - 53: DEBUG: new connection from 127.0.0.1:35158 372s app_test.go:1207: 04:23:11.004 - 51: DEBUG: new connection from 127.0.0.1:54902 372s app_test.go:1207: 04:23:11.764 - 52: DEBUG: new connection from 127.0.0.1:39202 372s app_test.go:1207: 04:23:11.765 - 51: DEBUG: new connection from 127.0.0.1:54912 372s app_test.go:1207: 04:23:11.858 - 51: DEBUG: new connection from 127.0.0.1:54918 372s app_test.go:1207: 04:23:11.859 - 54: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.860 - 52: DEBUG: new connection from 127.0.0.1:39214 372s app_test.go:1207: 04:23:11.861 - 51: DEBUG: new connection from 127.0.0.1:54926 372s app_test.go:1207: 04:23:11.861 - 53: DEBUG: new connection from 127.0.0.1:35164 372s app_test.go:1207: 04:23:11.863 - 54: DEBUG: new connection from 127.0.0.1:52152 372s app_test.go:1207: 04:23:11.864 - 54: DEBUG: new connection from 127.0.0.1:52154 372s app_test.go:1207: 04:23:11.866 - 51: DEBUG: new connection from 127.0.0.1:54940 372s app_test.go:1207: 04:23:11.867 - 51: DEBUG: new connection from 127.0.0.1:54942 372s app_test.go:1207: 04:23:11.868 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.868 - 51: DEBUG: new connection from 127.0.0.1:54954 372s app_test.go:1207: 04:23:11.868 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.869 - 51: DEBUG: new connection from 127.0.0.1:54958 372s app_test.go:1207: 04:23:11.869 - 52: DEBUG: new connection from 127.0.0.1:39228 372s app_test.go:1207: 04:23:11.870 - 53: DEBUG: new connection from 127.0.0.1:35170 372s app_test.go:1207: 04:23:11.870 - 54: DEBUG: new connection from 127.0.0.1:52168 372s app_test.go:1207: 04:23:11.765 - 51: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 372s app_test.go:1207: 04:23:11.766 - 51: DEBUG: new connection from 127.0.0.1:54966 372s app_test.go:1207: 04:23:11.766 - 52: DEBUG: new connection from 127.0.0.1:39230 372s app_test.go:1207: 04:23:11.768 - 54: DEBUG: new connection from 127.0.0.1:52170 372s app_test.go:1207: 04:23:11.768 - 53: DEBUG: new connection from 127.0.0.1:35180 372s app_test.go:1207: 04:23:11.770 - 52: DEBUG: new connection from 127.0.0.1:39238 372s app_test.go:1207: 04:23:11.772 - 54: DEBUG: new connection from 127.0.0.1:52184 372s app_test.go:1207: 04:23:11.773 - 53: DEBUG: new connection from 127.0.0.1:35196 372s app_test.go:1207: 04:23:11.774 - 51: DEBUG: new connection from 127.0.0.1:54976 372s app_test.go:1207: 04:23:11.774 - 53: DEBUG: new connection from 127.0.0.1:35210 372s app_test.go:1207: 04:23:11.775 - 51: DEBUG: new connection from 127.0.0.1:54988 372s app_test.go:1207: 04:23:11.776 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9003 372s app_test.go:1207: 04:23:11.776 - 53: DEBUG: new connection from 127.0.0.1:35220 372s app_test.go:1207: 04:23:11.777 - 51: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 372s app_test.go:1207: 04:23:11.778 - 51: DEBUG: new connection from 127.0.0.1:55002 372s app_test.go:1207: 04:23:11.779 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9003 372s app_test.go:1207: 04:23:11.779 - 53: DEBUG: new connection from 127.0.0.1:35224 372s app_test.go:1207: 04:23:11.780 - 51: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 372s --- PASS: TestHandover_TransferLeadership (3.37s) 372s === RUN TestRolesAdjustment_ReplaceVoter 372s app_test.go:1207: 04:23:11.209 - 55: DEBUG: new connection from 127.0.0.1:55006 372s app_test.go:1207: 04:23:11.211 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.212 - 55: DEBUG: new connection from 127.0.0.1:55018 372s app_test.go:1207: 04:23:11.297 - 55: DEBUG: new connection from 127.0.0.1:55028 372s app_test.go:1207: 04:23:11.298 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.301 - 55: DEBUG: new connection from 127.0.0.1:55036 372s app_test.go:1207: 04:23:11.301 - 56: DEBUG: new connection from 127.0.0.1:39240 372s app_test.go:1207: 04:23:11.377 - 55: DEBUG: new connection from 127.0.0.1:55044 372s app_test.go:1207: 04:23:11.378 - 57: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.380 - 56: DEBUG: new connection from 127.0.0.1:39242 372s app_test.go:1207: 04:23:11.380 - 55: DEBUG: new connection from 127.0.0.1:55052 372s app_test.go:1207: 04:23:11.381 - 57: DEBUG: new connection from 127.0.0.1:35234 372s app_test.go:1207: 04:23:11.382 - 57: DEBUG: new connection from 127.0.0.1:35242 372s app_test.go:1207: 04:23:11.384 - 55: DEBUG: new connection from 127.0.0.1:55054 372s app_test.go:1207: 04:23:11.138 - 56: DEBUG: new connection from 127.0.0.1:51990 372s app_test.go:1207: 04:23:11.140 - 55: DEBUG: new connection from 127.0.0.1:44642 372s app_test.go:1207: 04:23:11.213 - 55: DEBUG: new connection from 127.0.0.1:44656 372s app_test.go:1207: 04:23:11.214 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.215 - 55: DEBUG: new connection from 127.0.0.1:44668 372s app_test.go:1207: 04:23:11.215 - 56: DEBUG: new connection from 127.0.0.1:51992 372s app_test.go:1207: 04:23:11.216 - 57: DEBUG: new connection from 127.0.0.1:34806 372s app_test.go:1207: 04:23:11.217 - 55: DEBUG: new connection from 127.0.0.1:44674 372s app_test.go:1207: 04:23:11.219 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.220 - 56: DEBUG: new connection from 127.0.0.1:52006 372s app_test.go:1207: 04:23:11.220 - 55: DEBUG: new connection from 127.0.0.1:44690 372s app_test.go:1207: 04:23:11.221 - 57: DEBUG: new connection from 127.0.0.1:34814 372s app_test.go:1207: 04:23:11.221 - 58: DEBUG: new connection from 127.0.0.1:40868 372s app_test.go:1207: 04:23:11.222 - 58: DEBUG: new connection from 127.0.0.1:40872 372s app_test.go:1207: 04:23:11.224 - 55: DEBUG: new connection from 127.0.0.1:44692 372s app_test.go:1207: 04:23:11.304 - 55: DEBUG: new connection from 127.0.0.1:44700 372s app_test.go:1207: 04:23:11.304 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.218 - 55: DEBUG: new connection from 127.0.0.1:44704 372s app_test.go:1207: 04:23:11.219 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.221 - 55: DEBUG: new connection from 127.0.0.1:44706 372s app_test.go:1207: 04:23:11.222 - 56: DEBUG: new connection from 127.0.0.1:52018 372s app_test.go:1207: 04:23:11.223 - 58: DEBUG: new connection from 127.0.0.1:40886 372s app_test.go:1207: 04:23:11.224 - 55: DEBUG: new connection from 127.0.0.1:44716 372s app_test.go:1207: 04:23:11.224 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.225 - 55: DEBUG: new connection from 127.0.0.1:44730 372s app_test.go:1207: 04:23:11.225 - 56: DEBUG: new connection from 127.0.0.1:52028 372s app_test.go:1207: 04:23:11.226 - 58: DEBUG: new connection from 127.0.0.1:40902 372s app_test.go:1207: 04:23:11.227 - 55: DEBUG: new connection from 127.0.0.1:44734 372s app_test.go:1207: 04:23:11.227 - 56: DEBUG: new connection from 127.0.0.1:52032 372s app_test.go:1207: 04:23:11.228 - 58: DEBUG: new connection from 127.0.0.1:40908 372s app_test.go:1207: 04:23:11.305 - 55: DEBUG: new connection from 127.0.0.1:44746 372s app_test.go:1207: 04:23:11.307 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.226 - 55: DEBUG: new connection from 127.0.0.1:44752 372s app_test.go:1207: 04:23:11.228 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.230 - 55: DEBUG: new connection from 127.0.0.1:44766 372s app_test.go:1207: 04:23:11.231 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.232 - 55: DEBUG: new connection from 127.0.0.1:44782 372s app_test.go:1207: 04:23:11.232 - 56: DEBUG: new connection from 127.0.0.1:52034 372s app_test.go:1207: 04:23:11.234 - 58: DEBUG: new connection from 127.0.0.1:40916 372s app_test.go:1207: 04:23:11.308 - 55: DEBUG: new connection from 127.0.0.1:44786 372s app_test.go:1207: 04:23:11.309 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.230 - 55: DEBUG: new connection from 127.0.0.1:44794 372s app_test.go:1207: 04:23:11.231 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.235 - 55: DEBUG: new connection from 127.0.0.1:44806 372s app_test.go:1207: 04:23:11.236 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.238 - 56: DEBUG: new connection from 127.0.0.1:52038 372s app_test.go:1207: 04:23:11.239 - 55: DEBUG: new connection from 127.0.0.1:44812 372s app_test.go:1207: 04:23:11.239 - 58: DEBUG: new connection from 127.0.0.1:40924 372s app_test.go:1207: 04:23:11.311 - 55: DEBUG: new connection from 127.0.0.1:44820 372s app_test.go:1207: 04:23:11.312 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.233 - 55: DEBUG: new connection from 127.0.0.1:44836 372s app_test.go:1207: 04:23:11.234 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.242 - 55: DEBUG: new connection from 127.0.0.1:44844 372s app_test.go:1207: 04:23:11.243 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:23:11.244 - 56: DEBUG: new connection from 127.0.0.1:52044 372s app_test.go:1207: 04:23:11.244 - 55: DEBUG: new connection from 127.0.0.1:44858 372s app_test.go:1207: 04:23:11.245 - 58: DEBUG: new connection from 127.0.0.1:40926 372s app_test.go:1207: 04:23:11.298 - 56: DEBUG: new connection from 127.0.0.1:52060 372s app_test.go:1207: 04:23:11.298 - 58: DEBUG: new connection from 127.0.0.1:40934 372s app_test.go:1207: 04:23:11.298 - 55: DEBUG: new connection from 127.0.0.1:44864 372s app_test.go:1207: 04:23:11.298 - 55: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 372s app_test.go:1207: 04:23:11.300 - 55: DEBUG: attempt 1: server 127.0.0.1:9004: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:23:11.300 - 55: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:23:11.300 - 55: DEBUG: new connection from 127.0.0.1:44868 372s app_test.go:1207: 04:23:11.300 - 55: DEBUG: new connection from 127.0.0.1:44874 372s app_test.go:1207: 04:23:11.301 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.314 - 55: DEBUG: new connection from 127.0.0.1:44876 372s app_test.go:1207: 04:23:11.314 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s --- PASS: TestRolesAdjustment_ReplaceVoter (10.41s) 372s === RUN TestRolesAdjustment_ReplaceVoterHonorFailureDomain 372s app_test.go:1207: 04:23:11.619 - 59: DEBUG: new connection from 127.0.0.1:44884 372s app_test.go:1207: 04:23:11.620 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.621 - 59: DEBUG: new connection from 127.0.0.1:44898 372s app_test.go:1207: 04:23:11.697 - 59: DEBUG: new connection from 127.0.0.1:44904 372s app_test.go:1207: 04:23:11.698 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.701 - 59: DEBUG: new connection from 127.0.0.1:44912 372s app_test.go:1207: 04:23:11.701 - 60: DEBUG: new connection from 127.0.0.1:52074 372s app_test.go:1207: 04:23:11.778 - 59: DEBUG: new connection from 127.0.0.1:53686 372s app_test.go:1207: 04:23:11.778 - 61: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:23:11.780 - 59: DEBUG: new connection from 127.0.0.1:53690 372s app_test.go:1207: 04:23:11.780 - 60: DEBUG: new connection from 127.0.0.1:54022 372s app_test.go:1207: 04:23:11.781 - 61: DEBUG: new connection from 127.0.0.1:50924 372s app_test.go:1207: 04:23:11.782 - 61: DEBUG: new connection from 127.0.0.1:50938 372s app_test.go:1207: 04:23:11.784 - 59: DEBUG: new connection from 127.0.0.1:53698 372s app_test.go:1207: 04:23:11.543 - 60: DEBUG: new connection from 127.0.0.1:54026 372s app_test.go:1207: 04:23:11.545 - 59: DEBUG: new connection from 127.0.0.1:53714 372s app_test.go:1207: 04:24:11.607 - 59: DEBUG: new connection from 127.0.0.1:53722 372s app_test.go:1207: 04:24:11.609 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.610 - 60: DEBUG: new connection from 127.0.0.1:54034 372s app_test.go:1207: 04:24:11.610 - 59: DEBUG: new connection from 127.0.0.1:53726 372s app_test.go:1207: 04:24:11.612 - 61: DEBUG: new connection from 127.0.0.1:50954 372s app_test.go:1207: 04:24:11.612 - 62: DEBUG: new connection from 127.0.0.1:48774 372s app_test.go:1207: 04:24:11.613 - 62: DEBUG: new connection from 127.0.0.1:48780 372s app_test.go:1207: 04:24:11.615 - 59: DEBUG: new connection from 127.0.0.1:53736 372s app_test.go:1207: 04:24:11.677 - 59: DEBUG: new connection from 127.0.0.1:53740 372s app_test.go:1207: 04:24:11.678 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.679 - 60: DEBUG: new connection from 127.0.0.1:54048 372s app_test.go:1207: 04:24:11.679 - 59: DEBUG: new connection from 127.0.0.1:53742 372s app_test.go:1207: 04:24:11.681 - 61: DEBUG: new connection from 127.0.0.1:50970 372s app_test.go:1207: 04:24:11.681 - 62: DEBUG: new connection from 127.0.0.1:48782 372s app_test.go:1207: 04:24:11.682 - 63: DEBUG: new connection from 127.0.0.1:43278 372s app_test.go:1207: 04:24:11.683 - 63: DEBUG: new connection from 127.0.0.1:43292 372s app_test.go:1207: 04:24:11.686 - 59: DEBUG: new connection from 127.0.0.1:53756 372s app_test.go:1207: 04:24:11.777 - 59: DEBUG: new connection from 127.0.0.1:53772 372s app_test.go:1207: 04:24:11.778 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.779 - 59: DEBUG: new connection from 127.0.0.1:53782 372s app_test.go:1207: 04:24:11.779 - 60: DEBUG: new connection from 127.0.0.1:54064 372s app_test.go:1207: 04:24:11.780 - 61: DEBUG: new connection from 127.0.0.1:50974 372s app_test.go:1207: 04:24:11.781 - 62: DEBUG: new connection from 127.0.0.1:48786 372s app_test.go:1207: 04:24:11.782 - 63: DEBUG: new connection from 127.0.0.1:43304 372s app_test.go:1207: 04:24:11.783 - 64: DEBUG: new connection from 127.0.0.1:38546 372s app_test.go:1207: 04:24:11.783 - 64: DEBUG: new connection from 127.0.0.1:38558 372s app_test.go:1207: 04:24:11.786 - 59: DEBUG: new connection from 127.0.0.1:53794 372s app_test.go:1207: 04:24:11.623 - 59: DEBUG: new connection from 127.0.0.1:53806 372s app_test.go:1207: 04:24:11.624 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.625 - 59: DEBUG: new connection from 127.0.0.1:53820 372s app_test.go:1207: 04:24:11.625 - 60: DEBUG: new connection from 127.0.0.1:54080 372s app_test.go:1207: 04:24:11.626 - 62: DEBUG: new connection from 127.0.0.1:48794 372s app_test.go:1207: 04:24:11.626 - 63: DEBUG: new connection from 127.0.0.1:43320 372s app_test.go:1207: 04:24:11.628 - 64: DEBUG: new connection from 127.0.0.1:38562 372s app_test.go:1207: 04:24:11.630 - 60: DEBUG: new connection from 127.0.0.1:54090 372s app_test.go:1207: 04:24:11.630 - 59: DEBUG: new connection from 127.0.0.1:53836 372s app_test.go:1207: 04:24:11.631 - 62: DEBUG: new connection from 127.0.0.1:48806 372s app_test.go:1207: 04:24:11.632 - 63: DEBUG: new connection from 127.0.0.1:43332 372s app_test.go:1207: 04:24:11.632 - 64: DEBUG: new connection from 127.0.0.1:38566 372s app_test.go:1207: 04:24:11.634 - 60: DEBUG: new connection from 127.0.0.1:54100 372s app_test.go:1207: 04:24:11.634 - 59: DEBUG: new connection from 127.0.0.1:53848 372s app_test.go:1207: 04:24:11.634 - 62: DEBUG: new connection from 127.0.0.1:48820 372s app_test.go:1207: 04:24:11.635 - 63: DEBUG: new connection from 127.0.0.1:43338 372s app_test.go:1207: 04:24:11.635 - 64: DEBUG: new connection from 127.0.0.1:38578 372s app_test.go:1207: 04:24:11.703 - 59: DEBUG: new connection from 127.0.0.1:53862 372s app_test.go:1207: 04:24:11.704 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.614 - 59: DEBUG: new connection from 127.0.0.1:53864 372s app_test.go:1207: 04:24:11.615 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.684 - 59: DEBUG: new connection from 127.0.0.1:53866 372s app_test.go:1207: 04:24:11.685 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.785 - 59: DEBUG: new connection from 127.0.0.1:53880 372s app_test.go:1207: 04:24:11.786 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.638 - 59: DEBUG: new connection from 127.0.0.1:53894 372s app_test.go:1207: 04:24:11.639 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.640 - 60: DEBUG: new connection from 127.0.0.1:54110 372s app_test.go:1207: 04:24:11.640 - 59: DEBUG: new connection from 127.0.0.1:53904 372s app_test.go:1207: 04:24:11.641 - 62: DEBUG: new connection from 127.0.0.1:48824 372s app_test.go:1207: 04:24:11.642 - 63: DEBUG: new connection from 127.0.0.1:43354 372s app_test.go:1207: 04:24:11.643 - 64: DEBUG: new connection from 127.0.0.1:38594 372s app_test.go:1207: 04:24:11.705 - 59: DEBUG: new connection from 127.0.0.1:53906 372s app_test.go:1207: 04:24:11.706 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.618 - 59: DEBUG: new connection from 127.0.0.1:53918 372s app_test.go:1207: 04:24:11.619 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.686 - 59: DEBUG: new connection from 127.0.0.1:53924 372s app_test.go:1207: 04:24:11.687 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.788 - 59: DEBUG: new connection from 127.0.0.1:49760 372s app_test.go:1207: 04:24:11.789 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.645 - 59: DEBUG: new connection from 127.0.0.1:49770 372s app_test.go:1207: 04:24:11.646 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.648 - 60: DEBUG: new connection from 127.0.0.1:43608 372s app_test.go:1207: 04:24:11.648 - 59: DEBUG: new connection from 127.0.0.1:49776 372s app_test.go:1207: 04:24:11.649 - 62: DEBUG: new connection from 127.0.0.1:40070 372s app_test.go:1207: 04:24:11.650 - 63: DEBUG: new connection from 127.0.0.1:48452 372s app_test.go:1207: 04:24:11.651 - 64: DEBUG: new connection from 127.0.0.1:47312 372s app_test.go:1207: 04:24:11.708 - 59: DEBUG: new connection from 127.0.0.1:49790 372s app_test.go:1207: 04:24:11.709 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.621 - 59: DEBUG: new connection from 127.0.0.1:49800 372s app_test.go:1207: 04:24:11.622 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.689 - 59: DEBUG: new connection from 127.0.0.1:49810 372s app_test.go:1207: 04:24:11.690 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.791 - 59: DEBUG: new connection from 127.0.0.1:49826 372s app_test.go:1207: 04:24:11.792 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.654 - 59: DEBUG: new connection from 127.0.0.1:49834 372s app_test.go:1207: 04:24:11.656 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.658 - 60: DEBUG: new connection from 127.0.0.1:43610 372s app_test.go:1207: 04:24:11.659 - 59: DEBUG: new connection from 127.0.0.1:49842 372s app_test.go:1207: 04:24:11.660 - 62: DEBUG: new connection from 127.0.0.1:40076 372s app_test.go:1207: 04:24:11.661 - 63: DEBUG: new connection from 127.0.0.1:48456 372s app_test.go:1207: 04:24:11.661 - 64: DEBUG: new connection from 127.0.0.1:47324 372s app_test.go:1207: 04:24:11.710 - 59: DEBUG: new connection from 127.0.0.1:49844 372s app_test.go:1207: 04:24:11.711 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.624 - 59: DEBUG: new connection from 127.0.0.1:49850 372s app_test.go:1207: 04:24:11.625 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.692 - 59: DEBUG: new connection from 127.0.0.1:49860 372s app_test.go:1207: 04:24:11.692 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.794 - 59: DEBUG: new connection from 127.0.0.1:49874 372s app_test.go:1207: 04:24:11.795 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.664 - 59: DEBUG: new connection from 127.0.0.1:49882 372s app_test.go:1207: 04:24:11.665 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.667 - 60: DEBUG: new connection from 127.0.0.1:43626 372s app_test.go:1207: 04:24:11.668 - 59: DEBUG: new connection from 127.0.0.1:49892 372s app_test.go:1207: 04:24:11.668 - 62: DEBUG: new connection from 127.0.0.1:40084 372s app_test.go:1207: 04:24:11.669 - 63: DEBUG: new connection from 127.0.0.1:48466 372s app_test.go:1207: 04:24:11.669 - 64: DEBUG: new connection from 127.0.0.1:47328 372s app_test.go:1207: 04:24:11.713 - 59: DEBUG: new connection from 127.0.0.1:49898 372s app_test.go:1207: 04:24:11.713 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.848 - 63: DEBUG: new connection from 127.0.0.1:38988 372s app_test.go:1207: 04:24:11.849 - 59: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 372s app_test.go:1207: 04:24:11.849 - 60: DEBUG: new connection from 127.0.0.1:58748 372s app_test.go:1207: 04:24:11.849 - 59: DEBUG: new connection from 127.0.0.1:54616 372s app_test.go:1207: 04:24:11.849 - 62: DEBUG: new connection from 127.0.0.1:33810 372s app_test.go:1207: 04:24:11.849 - 64: DEBUG: new connection from 127.0.0.1:48036 372s app_test.go:1207: 04:24:11.851 - 59: WARN: attempt 1: server 127.0.0.1:9005: no known leader 372s app_test.go:1207: 04:24:11.851 - 59: WARN: attempt 1: server 127.0.0.1:9004: no known leader 372s app_test.go:1207: 04:24:11.852 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.852 - 59: DEBUG: attempt 1: server 127.0.0.1:9006: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:24:11.852 - 59: WARN: attempt 1: server 127.0.0.1:9001: context canceled 372s app_test.go:1207: 04:24:11.852 - 59: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:24:11.852 - 59: WARN: attempt 1: server 127.0.0.1:9001: context canceled 372s --- PASS: TestRolesAdjustment_ReplaceVoterHonorFailureDomain (20.71s) 372s === RUN TestRolesAdjustment_ImbalancedFailureDomain 372s app_test.go:1207: 04:24:11.339 - 65: DEBUG: new connection from 127.0.0.1:54632 372s app_test.go:1207: 04:24:11.341 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.342 - 65: DEBUG: new connection from 127.0.0.1:54646 372s app_test.go:1207: 04:24:11.417 - 65: DEBUG: new connection from 127.0.0.1:54650 372s app_test.go:1207: 04:24:11.419 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.421 - 65: DEBUG: new connection from 127.0.0.1:54658 372s app_test.go:1207: 04:24:11.422 - 66: DEBUG: new connection from 127.0.0.1:58754 372s app_test.go:1207: 04:24:11.497 - 65: DEBUG: new connection from 127.0.0.1:54664 372s app_test.go:1207: 04:24:11.499 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.500 - 65: DEBUG: new connection from 127.0.0.1:54676 372s app_test.go:1207: 04:24:11.500 - 66: DEBUG: new connection from 127.0.0.1:58768 372s app_test.go:1207: 04:24:11.501 - 67: DEBUG: new connection from 127.0.0.1:49534 372s app_test.go:1207: 04:24:11.502 - 67: DEBUG: new connection from 127.0.0.1:49536 372s app_test.go:1207: 04:24:11.503 - 65: DEBUG: new connection from 127.0.0.1:54686 372s app_test.go:1207: 04:24:11.253 - 66: DEBUG: new connection from 127.0.0.1:58782 372s app_test.go:1207: 04:24:11.256 - 65: DEBUG: new connection from 127.0.0.1:54692 372s app_test.go:1207: 04:24:11.338 - 65: DEBUG: new connection from 127.0.0.1:54700 372s app_test.go:1207: 04:24:11.341 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.343 - 65: DEBUG: new connection from 127.0.0.1:54712 372s app_test.go:1207: 04:24:11.343 - 66: DEBUG: new connection from 127.0.0.1:58796 372s app_test.go:1207: 04:24:11.344 - 67: DEBUG: new connection from 127.0.0.1:49544 372s app_test.go:1207: 04:24:11.344 - 68: DEBUG: new connection from 127.0.0.1:33832 372s app_test.go:1207: 04:24:11.345 - 68: DEBUG: new connection from 127.0.0.1:33840 372s app_test.go:1207: 04:24:11.348 - 65: DEBUG: new connection from 127.0.0.1:54728 372s app_test.go:1207: 04:24:11.438 - 65: DEBUG: new connection from 127.0.0.1:54730 372s app_test.go:1207: 04:24:11.440 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.442 - 66: DEBUG: new connection from 127.0.0.1:58804 372s app_test.go:1207: 04:24:11.442 - 65: DEBUG: new connection from 127.0.0.1:54738 372s app_test.go:1207: 04:24:11.443 - 67: DEBUG: new connection from 127.0.0.1:49554 372s app_test.go:1207: 04:24:11.444 - 68: DEBUG: new connection from 127.0.0.1:33846 372s app_test.go:1207: 04:24:11.445 - 69: DEBUG: new connection from 127.0.0.1:39014 372s app_test.go:1207: 04:24:11.446 - 69: DEBUG: new connection from 127.0.0.1:39022 372s app_test.go:1207: 04:24:11.449 - 65: DEBUG: new connection from 127.0.0.1:54750 372s app_test.go:1207: 04:24:11.538 - 65: DEBUG: new connection from 127.0.0.1:54754 372s app_test.go:1207: 04:24:11.539 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.541 - 65: DEBUG: new connection from 127.0.0.1:54762 372s app_test.go:1207: 04:24:11.541 - 66: DEBUG: new connection from 127.0.0.1:58810 372s app_test.go:1207: 04:24:11.542 - 67: DEBUG: new connection from 127.0.0.1:49568 372s app_test.go:1207: 04:24:11.542 - 68: DEBUG: new connection from 127.0.0.1:33856 372s app_test.go:1207: 04:24:11.544 - 69: DEBUG: new connection from 127.0.0.1:39038 372s app_test.go:1207: 04:24:11.545 - 70: DEBUG: new connection from 127.0.0.1:48040 372s app_test.go:1207: 04:24:11.546 - 70: DEBUG: new connection from 127.0.0.1:48056 372s app_test.go:1207: 04:24:11.549 - 65: DEBUG: new connection from 127.0.0.1:54770 372s app_test.go:1207: 04:24:11.618 - 65: DEBUG: new connection from 127.0.0.1:54784 372s app_test.go:1207: 04:24:11.619 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.624 - 65: DEBUG: new connection from 127.0.0.1:54796 372s app_test.go:1207: 04:24:11.624 - 66: DEBUG: new connection from 127.0.0.1:58824 372s app_test.go:1207: 04:24:11.625 - 67: DEBUG: new connection from 127.0.0.1:49582 372s app_test.go:1207: 04:24:11.627 - 68: DEBUG: new connection from 127.0.0.1:33864 372s app_test.go:1207: 04:24:11.628 - 69: DEBUG: new connection from 127.0.0.1:39048 372s app_test.go:1207: 04:24:11.630 - 70: DEBUG: new connection from 127.0.0.1:48058 372s app_test.go:1207: 04:24:11.630 - 71: DEBUG: new connection from 127.0.0.1:41786 372s app_test.go:1207: 04:24:11.708 - 65: DEBUG: new connection from 127.0.0.1:54812 372s app_test.go:1207: 04:24:11.709 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.711 - 65: DEBUG: new connection from 127.0.0.1:54826 372s app_test.go:1207: 04:24:11.711 - 66: DEBUG: new connection from 127.0.0.1:58828 372s app_test.go:1207: 04:24:11.712 - 67: DEBUG: new connection from 127.0.0.1:49590 372s app_test.go:1207: 04:24:11.712 - 68: DEBUG: new connection from 127.0.0.1:33876 372s app_test.go:1207: 04:24:11.713 - 69: DEBUG: new connection from 127.0.0.1:39052 372s app_test.go:1207: 04:24:11.714 - 70: DEBUG: new connection from 127.0.0.1:48064 372s app_test.go:1207: 04:24:11.715 - 71: DEBUG: new connection from 127.0.0.1:41802 372s app_test.go:1207: 04:24:11.716 - 72: DEBUG: new connection from 127.0.0.1:45522 372s app_test.go:1207: 04:24:11.343 - 65: DEBUG: new connection from 127.0.0.1:54830 372s app_test.go:1207: 04:24:11.344 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.346 - 65: DEBUG: new connection from 127.0.0.1:54842 372s app_test.go:1207: 04:24:11.346 - 66: DEBUG: new connection from 127.0.0.1:58838 372s app_test.go:1207: 04:24:11.347 - 67: DEBUG: new connection from 127.0.0.1:49600 372s app_test.go:1207: 04:24:11.347 - 68: DEBUG: new connection from 127.0.0.1:33888 372s app_test.go:1207: 04:24:11.348 - 69: DEBUG: new connection from 127.0.0.1:39068 372s app_test.go:1207: 04:24:11.348 - 70: DEBUG: new connection from 127.0.0.1:48078 372s app_test.go:1207: 04:24:11.349 - 71: DEBUG: new connection from 127.0.0.1:41806 372s app_test.go:1207: 04:24:11.349 - 72: DEBUG: new connection from 127.0.0.1:45528 372s app_test.go:1207: 04:24:11.350 - 72: DEBUG: new connection from 127.0.0.1:45538 372s app_test.go:1207: 04:24:11.352 - 65: DEBUG: new connection from 127.0.0.1:54844 372s app_test.go:1207: 04:24:11.423 - 65: DEBUG: new connection from 127.0.0.1:54860 372s app_test.go:1207: 04:24:11.424 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.258 - 65: DEBUG: new connection from 127.0.0.1:54874 372s app_test.go:1207: 04:24:11.258 - 66: DEBUG: new connection from 127.0.0.1:58848 372s app_test.go:1207: 04:24:11.259 - 67: DEBUG: new connection from 127.0.0.1:49614 372s app_test.go:1207: 04:24:11.260 - 68: DEBUG: new connection from 127.0.0.1:33892 372s app_test.go:1207: 04:24:11.260 - 70: DEBUG: new connection from 127.0.0.1:48090 372s app_test.go:1207: 04:24:11.260 - 69: DEBUG: new connection from 127.0.0.1:39082 372s app_test.go:1207: 04:24:11.262 - 71: DEBUG: new connection from 127.0.0.1:41818 372s app_test.go:1207: 04:24:11.262 - 72: DEBUG: new connection from 127.0.0.1:45546 372s app_test.go:1207: 04:24:11.264 - 65: DEBUG: new connection from 127.0.0.1:54888 372s app_test.go:1207: 04:24:11.264 - 66: DEBUG: new connection from 127.0.0.1:58858 372s app_test.go:1207: 04:24:11.265 - 67: DEBUG: new connection from 127.0.0.1:49626 372s app_test.go:1207: 04:24:11.266 - 68: DEBUG: new connection from 127.0.0.1:33902 372s app_test.go:1207: 04:24:11.267 - 69: DEBUG: new connection from 127.0.0.1:39092 372s app_test.go:1207: 04:24:11.268 - 70: DEBUG: new connection from 127.0.0.1:48106 372s app_test.go:1207: 04:24:11.269 - 71: DEBUG: new connection from 127.0.0.1:41834 372s app_test.go:1207: 04:24:11.269 - 72: DEBUG: new connection from 127.0.0.1:45550 372s app_test.go:1207: 04:24:11.256 - 65: DEBUG: new connection from 127.0.0.1:54892 372s app_test.go:1207: 04:24:11.257 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.347 - 65: DEBUG: new connection from 127.0.0.1:54902 372s app_test.go:1207: 04:24:11.348 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.448 - 65: DEBUG: new connection from 127.0.0.1:54908 372s app_test.go:1207: 04:24:11.449 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.548 - 65: DEBUG: new connection from 127.0.0.1:54920 372s app_test.go:1207: 04:24:11.550 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.631 - 65: DEBUG: new connection from 127.0.0.1:54930 372s app_test.go:1207: 04:24:11.633 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.717 - 65: DEBUG: new connection from 127.0.0.1:54942 372s app_test.go:1207: 04:24:11.718 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.427 - 65: DEBUG: new connection from 127.0.0.1:54948 372s app_test.go:1207: 04:24:11.428 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.270 - 65: DEBUG: new connection from 127.0.0.1:54954 372s app_test.go:1207: 04:24:11.272 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.273 - 66: DEBUG: new connection from 127.0.0.1:58874 372s app_test.go:1207: 04:24:11.273 - 65: DEBUG: new connection from 127.0.0.1:54964 372s app_test.go:1207: 04:24:11.274 - 67: DEBUG: new connection from 127.0.0.1:49632 372s app_test.go:1207: 04:24:11.274 - 68: DEBUG: new connection from 127.0.0.1:33912 372s app_test.go:1207: 04:24:11.275 - 69: DEBUG: new connection from 127.0.0.1:39108 372s app_test.go:1207: 04:24:11.276 - 70: DEBUG: new connection from 127.0.0.1:48122 372s app_test.go:1207: 04:24:11.276 - 71: DEBUG: new connection from 127.0.0.1:41848 372s app_test.go:1207: 04:24:11.277 - 72: DEBUG: new connection from 127.0.0.1:45554 372s app_test.go:1207: 04:24:11.259 - 65: DEBUG: new connection from 127.0.0.1:40328 372s app_test.go:1207: 04:24:11.261 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.349 - 65: DEBUG: new connection from 127.0.0.1:40336 372s app_test.go:1207: 04:24:11.350 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.451 - 65: DEBUG: new connection from 127.0.0.1:40346 372s app_test.go:1207: 04:24:11.452 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.553 - 65: DEBUG: new connection from 127.0.0.1:40348 372s app_test.go:1207: 04:24:11.554 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.634 - 65: DEBUG: new connection from 127.0.0.1:40358 372s app_test.go:1207: 04:24:11.635 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.720 - 65: DEBUG: new connection from 127.0.0.1:40374 372s app_test.go:1207: 04:24:11.720 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.853 - 67: DEBUG: new connection from 127.0.0.1:35960 372s app_test.go:1207: 04:24:11.854 - 72: DEBUG: new connection from 127.0.0.1:59416 372s app_test.go:1207: 04:24:11.855 - 66: DEBUG: new connection from 127.0.0.1:58590 372s app_test.go:1207: 04:24:11.856 - 66: DEBUG: new connection from 127.0.0.1:58602 372s app_test.go:1207: 04:24:11.430 - 65: DEBUG: new connection from 127.0.0.1:40390 372s app_test.go:1207: 04:24:11.432 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.279 - 65: DEBUG: new connection from 127.0.0.1:40406 372s app_test.go:1207: 04:24:11.281 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.287 - 65: DEBUG: new connection from 127.0.0.1:40408 372s app_test.go:1207: 04:24:11.288 - 66: DEBUG: new connection from 127.0.0.1:58616 372s app_test.go:1207: 04:24:11.289 - 67: DEBUG: new connection from 127.0.0.1:35966 372s app_test.go:1207: 04:24:11.290 - 68: DEBUG: new connection from 127.0.0.1:38180 372s app_test.go:1207: 04:24:11.290 - 69: DEBUG: new connection from 127.0.0.1:38286 372s app_test.go:1207: 04:24:11.291 - 70: DEBUG: new connection from 127.0.0.1:38638 372s app_test.go:1207: 04:24:11.292 - 71: DEBUG: new connection from 127.0.0.1:49930 372s app_test.go:1207: 04:24:11.294 - 72: DEBUG: new connection from 127.0.0.1:59430 372s app_test.go:1207: 04:24:11.263 - 65: DEBUG: new connection from 127.0.0.1:40414 372s app_test.go:1207: 04:24:11.264 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.352 - 65: DEBUG: new connection from 127.0.0.1:40416 372s app_test.go:1207: 04:24:11.353 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.454 - 65: DEBUG: new connection from 127.0.0.1:40426 372s app_test.go:1207: 04:24:11.455 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.556 - 65: DEBUG: new connection from 127.0.0.1:40434 372s app_test.go:1207: 04:24:11.557 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.637 - 65: DEBUG: new connection from 127.0.0.1:40446 372s app_test.go:1207: 04:24:11.638 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.722 - 65: DEBUG: new connection from 127.0.0.1:40460 372s app_test.go:1207: 04:24:11.724 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.434 - 65: DEBUG: new connection from 127.0.0.1:40472 372s app_test.go:1207: 04:24:11.435 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.296 - 65: DEBUG: new connection from 127.0.0.1:40486 372s app_test.go:1207: 04:24:11.297 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.299 - 65: DEBUG: new connection from 127.0.0.1:40502 372s app_test.go:1207: 04:24:11.299 - 66: DEBUG: new connection from 127.0.0.1:58624 372s app_test.go:1207: 04:24:11.301 - 67: DEBUG: new connection from 127.0.0.1:35982 372s app_test.go:1207: 04:24:11.301 - 68: DEBUG: new connection from 127.0.0.1:38186 372s app_test.go:1207: 04:24:11.302 - 69: DEBUG: new connection from 127.0.0.1:38290 372s app_test.go:1207: 04:24:11.303 - 70: DEBUG: new connection from 127.0.0.1:38654 372s app_test.go:1207: 04:24:11.303 - 71: DEBUG: new connection from 127.0.0.1:49932 372s app_test.go:1207: 04:24:11.304 - 72: DEBUG: new connection from 127.0.0.1:59442 372s app_test.go:1207: 04:24:11.266 - 65: DEBUG: new connection from 127.0.0.1:40510 372s app_test.go:1207: 04:24:11.267 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.354 - 65: DEBUG: new connection from 127.0.0.1:40520 372s app_test.go:1207: 04:24:11.355 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.458 - 65: DEBUG: new connection from 127.0.0.1:40526 372s app_test.go:1207: 04:24:11.459 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.558 - 65: DEBUG: new connection from 127.0.0.1:40542 372s app_test.go:1207: 04:24:11.559 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.639 - 65: DEBUG: new connection from 127.0.0.1:40550 372s app_test.go:1207: 04:24:11.640 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.726 - 65: DEBUG: new connection from 127.0.0.1:40552 372s app_test.go:1207: 04:24:11.727 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.438 - 65: DEBUG: new connection from 127.0.0.1:59216 372s app_test.go:1207: 04:24:11.439 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.717 - 66: DEBUG: new connection from 127.0.0.1:42088 372s app_test.go:1207: 04:24:11.717 - 71: DEBUG: new connection from 127.0.0.1:58450 372s app_test.go:1207: 04:24:11.718 - 67: DEBUG: new connection from 127.0.0.1:38822 372s app_test.go:1207: 04:24:11.718 - 70: DEBUG: new connection from 127.0.0.1:33784 372s app_test.go:1207: 04:24:11.718 - 68: DEBUG: new connection from 127.0.0.1:57176 372s app_test.go:1207: 04:24:11.718 - 72: DEBUG: new connection from 127.0.0.1:45478 372s app_test.go:1207: 04:24:11.718 - 65: DEBUG: new connection from 127.0.0.1:59218 372s app_test.go:1207: 04:24:11.718 - 69: DEBUG: new connection from 127.0.0.1:52866 372s app_test.go:1207: 04:24:11.721 - 65: WARN: attempt 1: server 127.0.0.1:9005: no known leader 372s app_test.go:1207: 04:24:11.721 - 65: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:24:11.722 - 65: WARN: attempt 1: server 127.0.0.1:9007: no known leader 372s app_test.go:1207: 04:24:11.722 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.722 - 65: DEBUG: new connection from 127.0.0.1:59234 372s app_test.go:1207: 04:24:11.722 - 65: WARN: attempt 1: server 127.0.0.1:9006: no known leader 372s app_test.go:1207: 04:24:11.722 - 65: WARN: attempt 1: server 127.0.0.1:9004: no known leader 372s app_test.go:1207: 04:24:11.722 - 65: DEBUG: attempt 1: server 127.0.0.1:9008: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:24:11.722 - 65: WARN: attempt 1: server 127.0.0.1:9001: context canceled 372s app_test.go:1207: 04:24:11.722 - 65: WARN: attempt 1: server 127.0.0.1:9002: no known leader 372s --- PASS: TestRolesAdjustment_ImbalancedFailureDomain (21.06s) 372s === RUN TestRolesAdjustment_ReplaceVoterHonorWeight 372s app_test.go:1207: 04:24:11.400 - 73: DEBUG: new connection from 127.0.0.1:59240 372s app_test.go:1207: 04:24:11.401 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.402 - 73: DEBUG: new connection from 127.0.0.1:59246 372s app_test.go:1207: 04:24:11.478 - 73: DEBUG: new connection from 127.0.0.1:59248 372s app_test.go:1207: 04:24:11.479 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.482 - 74: DEBUG: new connection from 127.0.0.1:42090 372s app_test.go:1207: 04:24:11.482 - 73: DEBUG: new connection from 127.0.0.1:59252 372s app_test.go:1207: 04:24:11.557 - 73: DEBUG: new connection from 127.0.0.1:59266 372s app_test.go:1207: 04:24:11.559 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.560 - 74: DEBUG: new connection from 127.0.0.1:42104 372s app_test.go:1207: 04:24:11.560 - 73: DEBUG: new connection from 127.0.0.1:59276 372s app_test.go:1207: 04:24:11.561 - 75: DEBUG: new connection from 127.0.0.1:38826 372s app_test.go:1207: 04:24:11.562 - 75: DEBUG: new connection from 127.0.0.1:38828 372s app_test.go:1207: 04:24:11.564 - 73: DEBUG: new connection from 127.0.0.1:59284 372s app_test.go:1207: 04:24:11.314 - 74: DEBUG: new connection from 127.0.0.1:42110 372s app_test.go:1207: 04:24:11.316 - 73: DEBUG: new connection from 127.0.0.1:59288 372s app_test.go:1207: 04:24:11.408 - 73: DEBUG: new connection from 127.0.0.1:59298 372s app_test.go:1207: 04:24:11.409 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.410 - 74: DEBUG: new connection from 127.0.0.1:42112 372s app_test.go:1207: 04:24:11.410 - 73: DEBUG: new connection from 127.0.0.1:59304 372s app_test.go:1207: 04:24:11.411 - 75: DEBUG: new connection from 127.0.0.1:38834 372s app_test.go:1207: 04:24:11.411 - 76: DEBUG: new connection from 127.0.0.1:57182 372s app_test.go:1207: 04:24:11.412 - 76: DEBUG: new connection from 127.0.0.1:57186 372s app_test.go:1207: 04:24:11.414 - 73: DEBUG: new connection from 127.0.0.1:59306 372s app_test.go:1207: 04:24:11.488 - 73: DEBUG: new connection from 127.0.0.1:59312 372s app_test.go:1207: 04:24:11.489 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.490 - 74: DEBUG: new connection from 127.0.0.1:42126 372s app_test.go:1207: 04:24:11.491 - 73: DEBUG: new connection from 127.0.0.1:59316 372s app_test.go:1207: 04:24:11.493 - 75: DEBUG: new connection from 127.0.0.1:38846 372s app_test.go:1207: 04:24:11.493 - 76: DEBUG: new connection from 127.0.0.1:57202 372s app_test.go:1207: 04:24:11.494 - 77: DEBUG: new connection from 127.0.0.1:52878 372s app_test.go:1207: 04:24:11.495 - 77: DEBUG: new connection from 127.0.0.1:52888 372s app_test.go:1207: 04:24:11.500 - 73: DEBUG: new connection from 127.0.0.1:59320 372s app_test.go:1207: 04:24:11.578 - 73: DEBUG: new connection from 127.0.0.1:59326 372s app_test.go:1207: 04:24:11.579 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:24:11.580 - 74: DEBUG: new connection from 127.0.0.1:42140 372s app_test.go:1207: 04:24:11.580 - 73: DEBUG: new connection from 127.0.0.1:59340 372s app_test.go:1207: 04:24:11.581 - 75: DEBUG: new connection from 127.0.0.1:38858 372s app_test.go:1207: 04:24:11.582 - 76: DEBUG: new connection from 127.0.0.1:57208 372s app_test.go:1207: 04:24:11.583 - 77: DEBUG: new connection from 127.0.0.1:52904 372s app_test.go:1207: 04:24:11.583 - 78: DEBUG: new connection from 127.0.0.1:33796 372s app_test.go:1207: 04:24:11.584 - 78: DEBUG: new connection from 127.0.0.1:33804 372s app_test.go:1207: 04:24:11.586 - 73: DEBUG: new connection from 127.0.0.1:59346 372s app_test.go:1207: 04:24:11.404 - 73: DEBUG: new connection from 127.0.0.1:59352 372s app_test.go:1207: 04:24:11.405 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.407 - 74: DEBUG: new connection from 127.0.0.1:42156 372s app_test.go:1207: 04:24:11.407 - 73: DEBUG: new connection from 127.0.0.1:59368 372s app_test.go:1207: 04:24:11.408 - 76: DEBUG: new connection from 127.0.0.1:57224 372s app_test.go:1207: 04:24:11.408 - 77: DEBUG: new connection from 127.0.0.1:52914 372s app_test.go:1207: 04:24:11.410 - 78: DEBUG: new connection from 127.0.0.1:33818 372s app_test.go:1207: 04:24:11.411 - 73: DEBUG: new connection from 127.0.0.1:59376 372s app_test.go:1207: 04:24:11.411 - 74: DEBUG: new connection from 127.0.0.1:42160 372s app_test.go:1207: 04:24:11.413 - 76: DEBUG: new connection from 127.0.0.1:57236 372s app_test.go:1207: 04:24:11.414 - 77: DEBUG: new connection from 127.0.0.1:52922 372s app_test.go:1207: 04:24:11.415 - 78: DEBUG: new connection from 127.0.0.1:33828 372s app_test.go:1207: 04:24:11.416 - 73: DEBUG: new connection from 127.0.0.1:59392 372s app_test.go:1207: 04:24:11.417 - 74: DEBUG: new connection from 127.0.0.1:42170 372s app_test.go:1207: 04:24:11.418 - 76: DEBUG: new connection from 127.0.0.1:57246 372s app_test.go:1207: 04:24:11.418 - 77: DEBUG: new connection from 127.0.0.1:52932 372s app_test.go:1207: 04:24:11.419 - 78: DEBUG: new connection from 127.0.0.1:33840 372s app_test.go:1207: 04:24:11.484 - 73: DEBUG: new connection from 127.0.0.1:59404 372s app_test.go:1207: 04:24:11.485 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.413 - 73: DEBUG: new connection from 127.0.0.1:59410 372s app_test.go:1207: 04:24:11.414 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.497 - 73: DEBUG: new connection from 127.0.0.1:59426 372s app_test.go:1207: 04:24:11.497 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.585 - 73: DEBUG: new connection from 127.0.0.1:59434 372s app_test.go:1207: 04:24:11.585 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.421 - 73: DEBUG: new connection from 127.0.0.1:59442 372s app_test.go:1207: 04:24:11.422 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.424 - 74: DEBUG: new connection from 127.0.0.1:42174 372s app_test.go:1207: 04:24:11.424 - 73: DEBUG: new connection from 127.0.0.1:59446 372s app_test.go:1207: 04:24:11.425 - 76: DEBUG: new connection from 127.0.0.1:57252 372s app_test.go:1207: 04:24:11.426 - 77: DEBUG: new connection from 127.0.0.1:52938 372s app_test.go:1207: 04:24:11.426 - 78: DEBUG: new connection from 127.0.0.1:33850 372s app_test.go:1207: 04:24:11.490 - 73: DEBUG: new connection from 127.0.0.1:59448 372s app_test.go:1207: 04:24:11.491 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.416 - 73: DEBUG: new connection from 127.0.0.1:41434 372s app_test.go:1207: 04:24:11.417 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.499 - 73: DEBUG: new connection from 127.0.0.1:41438 372s app_test.go:1207: 04:24:11.499 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.587 - 73: DEBUG: new connection from 127.0.0.1:41440 372s app_test.go:1207: 04:24:11.588 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.429 - 73: DEBUG: new connection from 127.0.0.1:41446 372s app_test.go:1207: 04:24:11.430 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.432 - 73: DEBUG: new connection from 127.0.0.1:41460 372s app_test.go:1207: 04:24:11.432 - 74: DEBUG: new connection from 127.0.0.1:39954 372s app_test.go:1207: 04:24:11.433 - 76: DEBUG: new connection from 127.0.0.1:40964 372s app_test.go:1207: 04:24:11.433 - 77: DEBUG: new connection from 127.0.0.1:34478 372s app_test.go:1207: 04:24:11.434 - 78: DEBUG: new connection from 127.0.0.1:41090 372s app_test.go:1207: 04:24:11.493 - 73: DEBUG: new connection from 127.0.0.1:41470 372s app_test.go:1207: 04:24:11.494 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.419 - 73: DEBUG: new connection from 127.0.0.1:41476 372s app_test.go:1207: 04:24:11.420 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.501 - 73: DEBUG: new connection from 127.0.0.1:41478 372s app_test.go:1207: 04:24:11.502 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.589 - 73: DEBUG: new connection from 127.0.0.1:41482 372s app_test.go:1207: 04:24:11.590 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.435 - 73: DEBUG: new connection from 127.0.0.1:41486 372s app_test.go:1207: 04:24:11.437 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.439 - 73: DEBUG: new connection from 127.0.0.1:41498 372s app_test.go:1207: 04:24:11.439 - 74: DEBUG: new connection from 127.0.0.1:39970 372s app_test.go:1207: 04:24:11.440 - 76: DEBUG: new connection from 127.0.0.1:40978 372s app_test.go:1207: 04:24:11.440 - 77: DEBUG: new connection from 127.0.0.1:34480 372s app_test.go:1207: 04:24:11.441 - 78: DEBUG: new connection from 127.0.0.1:41106 372s app_test.go:1207: 04:24:11.496 - 73: DEBUG: new connection from 127.0.0.1:41506 372s app_test.go:1207: 04:24:11.497 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.422 - 73: DEBUG: new connection from 127.0.0.1:41522 372s app_test.go:1207: 04:24:11.423 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.504 - 73: DEBUG: new connection from 127.0.0.1:41536 372s app_test.go:1207: 04:24:11.504 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:24:11.592 - 73: DEBUG: new connection from 127.0.0.1:41548 372s app_test.go:1207: 04:24:11.592 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.443 - 73: DEBUG: new connection from 127.0.0.1:47004 372s app_test.go:1207: 04:25:11.445 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.447 - 74: DEBUG: new connection from 127.0.0.1:43298 372s app_test.go:1207: 04:25:11.447 - 73: DEBUG: new connection from 127.0.0.1:47008 372s app_test.go:1207: 04:25:11.448 - 76: DEBUG: new connection from 127.0.0.1:46826 372s app_test.go:1207: 04:25:11.448 - 77: DEBUG: new connection from 127.0.0.1:58412 372s app_test.go:1207: 04:25:11.449 - 78: DEBUG: new connection from 127.0.0.1:49314 372s app_test.go:1207: 04:25:11.498 - 73: DEBUG: new connection from 127.0.0.1:47018 372s app_test.go:1207: 04:25:11.499 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.649 - 74: DEBUG: new connection from 127.0.0.1:43308 372s app_test.go:1207: 04:25:11.649 - 78: DEBUG: new connection from 127.0.0.1:49330 372s app_test.go:1207: 04:25:11.649 - 76: DEBUG: new connection from 127.0.0.1:46832 372s app_test.go:1207: 04:25:11.649 - 77: DEBUG: new connection from 127.0.0.1:58424 372s app_test.go:1207: 04:25:11.650 - 73: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 372s app_test.go:1207: 04:25:11.650 - 73: DEBUG: new connection from 127.0.0.1:47028 372s app_test.go:1207: 04:25:11.651 - 73: WARN: attempt 1: server 127.0.0.1:9004: no known leader 372s app_test.go:1207: 04:25:11.651 - 73: DEBUG: attempt 1: server 127.0.0.1:9005: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:25:11.651 - 73: WARN: attempt 1: server 127.0.0.1:9006: no known leader 372s app_test.go:1207: 04:25:11.652 - 73: DEBUG: new connection from 127.0.0.1:47044 372s app_test.go:1207: 04:25:11.652 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.652 - 73: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:25:11.652 - 73: WARN: attempt 1: server 127.0.0.1:9001: context canceled 372s --- PASS: TestRolesAdjustment_ReplaceVoterHonorWeight (20.79s) 372s === RUN TestRolesAdjustment_CantReplaceVoter 372s app_test.go:1207: 04:25:11.181 - 79: DEBUG: new connection from 127.0.0.1:47052 372s app_test.go:1207: 04:25:11.183 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.184 - 79: DEBUG: new connection from 127.0.0.1:47054 372s app_test.go:1207: 04:25:11.278 - 79: DEBUG: new connection from 127.0.0.1:47058 372s app_test.go:1207: 04:25:11.279 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.284 - 79: DEBUG: new connection from 127.0.0.1:47060 372s app_test.go:1207: 04:25:11.284 - 80: DEBUG: new connection from 127.0.0.1:43312 372s app_test.go:1207: 04:25:11.378 - 79: DEBUG: new connection from 127.0.0.1:47070 372s app_test.go:1207: 04:25:11.379 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.382 - 80: DEBUG: new connection from 127.0.0.1:43320 372s app_test.go:1207: 04:25:11.382 - 79: DEBUG: new connection from 127.0.0.1:47076 372s app_test.go:1207: 04:25:11.383 - 81: DEBUG: new connection from 127.0.0.1:58410 372s app_test.go:1207: 04:25:11.384 - 81: DEBUG: new connection from 127.0.0.1:58416 372s app_test.go:1207: 04:25:11.385 - 79: DEBUG: new connection from 127.0.0.1:47090 372s app_test.go:1207: 04:25:11.104 - 80: DEBUG: new connection from 127.0.0.1:43328 372s app_test.go:1207: 04:25:11.107 - 79: DEBUG: new connection from 127.0.0.1:47096 372s app_test.go:1207: 04:25:11.187 - 79: DEBUG: new connection from 127.0.0.1:47100 372s app_test.go:1207: 04:25:11.189 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.190 - 80: DEBUG: new connection from 127.0.0.1:43344 372s app_test.go:1207: 04:25:11.190 - 79: DEBUG: new connection from 127.0.0.1:47102 372s app_test.go:1207: 04:25:11.191 - 81: DEBUG: new connection from 127.0.0.1:58422 372s app_test.go:1207: 04:25:11.191 - 82: DEBUG: new connection from 127.0.0.1:46840 372s app_test.go:1207: 04:25:11.192 - 82: DEBUG: new connection from 127.0.0.1:46844 372s app_test.go:1207: 04:25:11.186 - 79: DEBUG: new connection from 127.0.0.1:47112 372s app_test.go:1207: 04:25:11.187 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.189 - 80: DEBUG: new connection from 127.0.0.1:43348 372s app_test.go:1207: 04:25:11.189 - 79: DEBUG: new connection from 127.0.0.1:47120 372s app_test.go:1207: 04:25:11.287 - 79: DEBUG: new connection from 127.0.0.1:47134 372s app_test.go:1207: 04:25:11.288 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.191 - 79: DEBUG: new connection from 127.0.0.1:42520 372s app_test.go:1207: 04:25:11.192 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.194 - 79: DEBUG: new connection from 127.0.0.1:42532 372s app_test.go:1207: 04:25:11.194 - 80: DEBUG: new connection from 127.0.0.1:32870 372s app_test.go:1207: 04:25:11.290 - 79: DEBUG: new connection from 127.0.0.1:42548 372s app_test.go:1207: 04:25:11.291 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.196 - 79: DEBUG: new connection from 127.0.0.1:42556 372s app_test.go:1207: 04:25:11.197 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.199 - 80: DEBUG: new connection from 127.0.0.1:32880 372s app_test.go:1207: 04:25:11.199 - 79: DEBUG: new connection from 127.0.0.1:42564 372s app_test.go:1207: 04:25:11.293 - 79: DEBUG: new connection from 127.0.0.1:42576 372s app_test.go:1207: 04:25:11.293 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.258 - 79: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 372s app_test.go:1207: 04:25:11.258 - 79: DEBUG: new connection from 127.0.0.1:42592 372s app_test.go:1207: 04:25:11.259 - 80: DEBUG: new connection from 127.0.0.1:32886 372s app_test.go:1207: 04:25:11.259 - 79: WARN: attempt 1: server 127.0.0.1:9004: dial: dial tcp 127.0.0.1:9004: connect: connection refused 372s app_test.go:1207: 04:25:11.260 - 79: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:25:11.260 - 79: DEBUG: new connection from 127.0.0.1:42608 372s app_test.go:1207: 04:25:11.261 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s --- PASS: TestRolesAdjustment_CantReplaceVoter (14.32s) 372s === RUN TestRolesAdjustment_ReplaceStandBy 372s app_test.go:1207: 04:25:11.529 - 83: DEBUG: new connection from 127.0.0.1:42618 372s app_test.go:1207: 04:25:11.530 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.531 - 83: DEBUG: new connection from 127.0.0.1:42628 372s app_test.go:1207: 04:25:11.607 - 83: DEBUG: new connection from 127.0.0.1:42642 372s app_test.go:1207: 04:25:11.608 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.610 - 83: DEBUG: new connection from 127.0.0.1:42652 372s app_test.go:1207: 04:25:11.610 - 84: DEBUG: new connection from 127.0.0.1:32888 372s app_test.go:1207: 04:25:11.697 - 83: DEBUG: new connection from 127.0.0.1:42664 372s app_test.go:1207: 04:25:11.698 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.700 - 84: DEBUG: new connection from 127.0.0.1:32896 372s app_test.go:1207: 04:25:11.700 - 83: DEBUG: new connection from 127.0.0.1:42666 372s app_test.go:1207: 04:25:11.701 - 85: DEBUG: new connection from 127.0.0.1:49470 372s app_test.go:1207: 04:25:11.702 - 85: DEBUG: new connection from 127.0.0.1:49472 372s app_test.go:1207: 04:25:11.704 - 83: DEBUG: new connection from 127.0.0.1:42676 372s app_test.go:1207: 04:25:11.423 - 84: DEBUG: new connection from 127.0.0.1:32898 372s app_test.go:1207: 04:25:11.425 - 83: DEBUG: new connection from 127.0.0.1:42682 372s app_test.go:1207: 04:25:11.507 - 83: DEBUG: new connection from 127.0.0.1:42688 372s app_test.go:1207: 04:25:11.509 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.510 - 84: DEBUG: new connection from 127.0.0.1:32914 372s app_test.go:1207: 04:25:11.510 - 83: DEBUG: new connection from 127.0.0.1:42692 372s app_test.go:1207: 04:25:11.511 - 85: DEBUG: new connection from 127.0.0.1:49474 372s app_test.go:1207: 04:25:11.512 - 86: DEBUG: new connection from 127.0.0.1:35340 372s app_test.go:1207: 04:25:11.513 - 86: DEBUG: new connection from 127.0.0.1:35350 372s app_test.go:1207: 04:25:11.516 - 83: DEBUG: new connection from 127.0.0.1:42694 372s app_test.go:1207: 04:25:11.607 - 83: DEBUG: new connection from 127.0.0.1:42698 372s app_test.go:1207: 04:25:11.608 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.609 - 84: DEBUG: new connection from 127.0.0.1:32930 372s app_test.go:1207: 04:25:11.609 - 83: DEBUG: new connection from 127.0.0.1:42714 372s app_test.go:1207: 04:25:11.610 - 85: DEBUG: new connection from 127.0.0.1:49476 372s app_test.go:1207: 04:25:11.610 - 86: DEBUG: new connection from 127.0.0.1:35356 372s app_test.go:1207: 04:25:11.611 - 87: DEBUG: new connection from 127.0.0.1:35290 372s app_test.go:1207: 04:25:11.613 - 87: DEBUG: new connection from 127.0.0.1:35302 372s app_test.go:1207: 04:25:11.615 - 83: DEBUG: new connection from 127.0.0.1:42724 372s app_test.go:1207: 04:25:11.707 - 83: DEBUG: new connection from 127.0.0.1:42730 372s app_test.go:1207: 04:25:11.708 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.709 - 83: DEBUG: new connection from 127.0.0.1:42742 372s app_test.go:1207: 04:25:11.710 - 84: DEBUG: new connection from 127.0.0.1:32934 372s app_test.go:1207: 04:25:11.710 - 85: DEBUG: new connection from 127.0.0.1:49488 372s app_test.go:1207: 04:25:11.711 - 86: DEBUG: new connection from 127.0.0.1:35360 372s app_test.go:1207: 04:25:11.712 - 87: DEBUG: new connection from 127.0.0.1:35318 372s app_test.go:1207: 04:25:11.712 - 88: DEBUG: new connection from 127.0.0.1:40248 372s app_test.go:1207: 04:25:11.714 - 88: DEBUG: new connection from 127.0.0.1:40250 372s app_test.go:1207: 04:25:11.716 - 83: DEBUG: new connection from 127.0.0.1:42746 372s app_test.go:1207: 04:25:11.787 - 83: DEBUG: new connection from 127.0.0.1:42750 372s app_test.go:1207: 04:25:11.788 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.789 - 84: DEBUG: new connection from 127.0.0.1:32948 372s app_test.go:1207: 04:25:11.790 - 83: DEBUG: new connection from 127.0.0.1:42754 372s app_test.go:1207: 04:25:11.791 - 85: DEBUG: new connection from 127.0.0.1:49496 372s app_test.go:1207: 04:25:11.791 - 86: DEBUG: new connection from 127.0.0.1:35368 372s app_test.go:1207: 04:25:11.792 - 87: DEBUG: new connection from 127.0.0.1:35320 372s app_test.go:1207: 04:25:11.792 - 88: DEBUG: new connection from 127.0.0.1:40264 372s app_test.go:1207: 04:25:11.793 - 89: DEBUG: new connection from 127.0.0.1:53368 372s app_test.go:1207: 04:25:11.533 - 83: DEBUG: new connection from 127.0.0.1:48674 372s app_test.go:1207: 04:25:11.537 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.539 - 84: DEBUG: new connection from 127.0.0.1:34336 372s app_test.go:1207: 04:25:11.539 - 83: DEBUG: new connection from 127.0.0.1:48676 372s app_test.go:1207: 04:25:11.540 - 85: DEBUG: new connection from 127.0.0.1:42480 372s app_test.go:1207: 04:25:11.540 - 86: DEBUG: new connection from 127.0.0.1:48958 372s app_test.go:1207: 04:25:11.541 - 88: DEBUG: new connection from 127.0.0.1:54358 372s app_test.go:1207: 04:25:11.542 - 89: DEBUG: new connection from 127.0.0.1:60020 372s app_test.go:1207: 04:25:11.543 - 89: DEBUG: new connection from 127.0.0.1:60028 372s app_test.go:1207: 04:25:11.544 - 83: DEBUG: new connection from 127.0.0.1:48678 372s app_test.go:1207: 04:25:11.544 - 84: DEBUG: new connection from 127.0.0.1:34346 372s app_test.go:1207: 04:25:11.545 - 83: DEBUG: new connection from 127.0.0.1:48690 372s app_test.go:1207: 04:25:11.546 - 85: DEBUG: new connection from 127.0.0.1:42494 372s app_test.go:1207: 04:25:11.547 - 86: DEBUG: new connection from 127.0.0.1:48974 372s app_test.go:1207: 04:25:11.548 - 88: DEBUG: new connection from 127.0.0.1:54364 372s app_test.go:1207: 04:25:11.548 - 89: DEBUG: new connection from 127.0.0.1:60034 372s app_test.go:1207: 04:25:11.549 - 83: DEBUG: new connection from 127.0.0.1:48700 372s app_test.go:1207: 04:25:11.549 - 84: DEBUG: new connection from 127.0.0.1:34356 372s app_test.go:1207: 04:25:11.550 - 85: DEBUG: new connection from 127.0.0.1:42502 372s app_test.go:1207: 04:25:11.550 - 86: DEBUG: new connection from 127.0.0.1:48978 372s app_test.go:1207: 04:25:11.551 - 88: DEBUG: new connection from 127.0.0.1:54366 372s app_test.go:1207: 04:25:11.551 - 89: DEBUG: new connection from 127.0.0.1:60046 372s app_test.go:1207: 04:25:11.612 - 83: DEBUG: new connection from 127.0.0.1:48714 372s app_test.go:1207: 04:25:11.613 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.426 - 83: DEBUG: new connection from 127.0.0.1:48724 372s app_test.go:1207: 04:25:11.427 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.514 - 83: DEBUG: new connection from 127.0.0.1:48734 372s app_test.go:1207: 04:25:11.515 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.715 - 83: DEBUG: new connection from 127.0.0.1:48750 372s app_test.go:1207: 04:25:11.716 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.795 - 83: DEBUG: new connection from 127.0.0.1:48762 372s app_test.go:1207: 04:25:11.796 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.554 - 83: DEBUG: new connection from 127.0.0.1:48766 372s app_test.go:1207: 04:25:11.555 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.557 - 84: DEBUG: new connection from 127.0.0.1:34358 372s app_test.go:1207: 04:25:11.557 - 83: DEBUG: new connection from 127.0.0.1:48770 372s app_test.go:1207: 04:25:11.559 - 85: DEBUG: new connection from 127.0.0.1:42510 372s app_test.go:1207: 04:25:11.559 - 86: DEBUG: new connection from 127.0.0.1:48992 372s app_test.go:1207: 04:25:11.561 - 89: DEBUG: new connection from 127.0.0.1:60062 372s app_test.go:1207: 04:25:11.561 - 88: DEBUG: new connection from 127.0.0.1:54380 372s app_test.go:1207: 04:25:11.615 - 83: DEBUG: new connection from 127.0.0.1:48784 372s app_test.go:1207: 04:25:11.616 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.429 - 83: DEBUG: new connection from 127.0.0.1:48800 372s app_test.go:1207: 04:25:11.431 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.517 - 83: DEBUG: new connection from 127.0.0.1:48804 372s app_test.go:1207: 04:25:11.518 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.718 - 83: DEBUG: new connection from 127.0.0.1:48814 372s app_test.go:1207: 04:25:11.718 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.797 - 83: DEBUG: new connection from 127.0.0.1:48822 372s app_test.go:1207: 04:25:11.798 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.564 - 83: DEBUG: new connection from 127.0.0.1:37884 372s app_test.go:1207: 04:25:11.565 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.567 - 83: DEBUG: new connection from 127.0.0.1:37888 372s app_test.go:1207: 04:25:11.567 - 84: DEBUG: new connection from 127.0.0.1:55124 372s app_test.go:1207: 04:25:11.568 - 85: DEBUG: new connection from 127.0.0.1:47226 372s app_test.go:1207: 04:25:11.569 - 86: DEBUG: new connection from 127.0.0.1:39738 372s app_test.go:1207: 04:25:11.570 - 88: DEBUG: new connection from 127.0.0.1:38622 372s app_test.go:1207: 04:25:11.570 - 89: DEBUG: new connection from 127.0.0.1:38546 372s app_test.go:1207: 04:25:11.617 - 83: DEBUG: new connection from 127.0.0.1:37892 372s app_test.go:1207: 04:25:11.618 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.432 - 83: DEBUG: new connection from 127.0.0.1:37906 372s app_test.go:1207: 04:25:11.433 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.520 - 83: DEBUG: new connection from 127.0.0.1:37916 372s app_test.go:1207: 04:25:11.521 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.721 - 83: DEBUG: new connection from 127.0.0.1:37918 372s app_test.go:1207: 04:25:11.722 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.800 - 83: DEBUG: new connection from 127.0.0.1:37920 372s app_test.go:1207: 04:25:11.800 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.573 - 83: DEBUG: new connection from 127.0.0.1:37928 372s app_test.go:1207: 04:25:11.574 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.576 - 84: DEBUG: new connection from 127.0.0.1:55138 372s app_test.go:1207: 04:25:11.576 - 83: DEBUG: new connection from 127.0.0.1:37936 372s app_test.go:1207: 04:25:11.577 - 85: DEBUG: new connection from 127.0.0.1:47238 372s app_test.go:1207: 04:25:11.578 - 86: DEBUG: new connection from 127.0.0.1:39744 372s app_test.go:1207: 04:25:11.579 - 88: DEBUG: new connection from 127.0.0.1:38630 372s app_test.go:1207: 04:25:11.579 - 89: DEBUG: new connection from 127.0.0.1:38556 372s app_test.go:1207: 04:25:11.619 - 83: DEBUG: new connection from 127.0.0.1:37952 372s app_test.go:1207: 04:25:11.620 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.436 - 83: DEBUG: new connection from 127.0.0.1:37954 372s app_test.go:1207: 04:25:11.437 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.523 - 83: DEBUG: new connection from 127.0.0.1:37960 372s app_test.go:1207: 04:25:11.524 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.724 - 83: DEBUG: new connection from 127.0.0.1:37964 372s app_test.go:1207: 04:25:11.725 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.796 - 86: DEBUG: new connection from 127.0.0.1:39750 372s app_test.go:1207: 04:25:11.796 - 83: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 372s app_test.go:1207: 04:25:11.796 - 89: DEBUG: new connection from 127.0.0.1:38564 372s app_test.go:1207: 04:25:11.797 - 84: DEBUG: new connection from 127.0.0.1:55146 372s app_test.go:1207: 04:25:11.797 - 83: DEBUG: new connection from 127.0.0.1:37972 372s app_test.go:1207: 04:25:11.797 - 88: DEBUG: new connection from 127.0.0.1:38636 372s app_test.go:1207: 04:25:11.796 - 85: DEBUG: new connection from 127.0.0.1:47244 372s app_test.go:1207: 04:25:11.800 - 83: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:25:11.800 - 83: WARN: attempt 1: server 127.0.0.1:9004: no known leader 372s app_test.go:1207: 04:25:11.800 - 83: WARN: attempt 1: server 127.0.0.1:9006: no known leader 372s app_test.go:1207: 04:25:11.800 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.800 - 83: DEBUG: new connection from 127.0.0.1:37978 372s app_test.go:1207: 04:25:11.800 - 83: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:25:11.800 - 83: WARN: attempt 1: server 127.0.0.1:9001: context canceled 372s app_test.go:1207: 04:25:11.801 - 83: WARN: attempt 1: server 127.0.0.1:9007: no known leader 372s app_test.go:1207: 04:25:11.802 - 83: DEBUG: new connection from 127.0.0.1:37994 372s app_test.go:1207: 04:25:11.803 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s --- PASS: TestRolesAdjustment_ReplaceStandBy (22.93s) 372s === RUN TestRolesAdjustment_ReplaceStandByHonorFailureDomains 372s app_test.go:1207: 04:25:11.449 - 90: DEBUG: new connection from 127.0.0.1:38006 372s app_test.go:1207: 04:25:11.450 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.451 - 90: DEBUG: new connection from 127.0.0.1:38016 372s app_test.go:1207: 04:25:11.527 - 90: DEBUG: new connection from 127.0.0.1:38020 372s app_test.go:1207: 04:25:11.528 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.531 - 91: DEBUG: new connection from 127.0.0.1:55156 372s app_test.go:1207: 04:25:11.531 - 90: DEBUG: new connection from 127.0.0.1:38030 372s app_test.go:1207: 04:25:11.607 - 90: DEBUG: new connection from 127.0.0.1:38044 372s app_test.go:1207: 04:25:11.608 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.610 - 90: DEBUG: new connection from 127.0.0.1:38058 372s app_test.go:1207: 04:25:11.610 - 91: DEBUG: new connection from 127.0.0.1:55166 372s app_test.go:1207: 04:25:11.611 - 92: DEBUG: new connection from 127.0.0.1:47254 372s app_test.go:1207: 04:25:11.614 - 92: DEBUG: new connection from 127.0.0.1:47268 372s app_test.go:1207: 04:25:11.616 - 90: DEBUG: new connection from 127.0.0.1:38066 372s app_test.go:1207: 04:25:11.353 - 91: DEBUG: new connection from 127.0.0.1:38218 372s app_test.go:1207: 04:25:11.355 - 90: DEBUG: new connection from 127.0.0.1:48876 372s app_test.go:1207: 04:25:11.448 - 90: DEBUG: new connection from 127.0.0.1:48890 372s app_test.go:1207: 04:25:11.449 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.450 - 91: DEBUG: new connection from 127.0.0.1:38232 372s app_test.go:1207: 04:25:11.450 - 90: DEBUG: new connection from 127.0.0.1:48900 372s app_test.go:1207: 04:25:11.452 - 92: DEBUG: new connection from 127.0.0.1:56582 372s app_test.go:1207: 04:25:11.454 - 93: DEBUG: new connection from 127.0.0.1:41402 372s app_test.go:1207: 04:25:11.455 - 93: DEBUG: new connection from 127.0.0.1:41416 372s app_test.go:1207: 04:25:11.458 - 90: DEBUG: new connection from 127.0.0.1:48912 372s app_test.go:1207: 04:25:11.558 - 90: DEBUG: new connection from 127.0.0.1:48928 372s app_test.go:1207: 04:25:11.560 - 94: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.561 - 91: DEBUG: new connection from 127.0.0.1:38234 372s app_test.go:1207: 04:25:11.561 - 90: DEBUG: new connection from 127.0.0.1:48942 372s app_test.go:1207: 04:25:11.562 - 92: DEBUG: new connection from 127.0.0.1:56584 372s app_test.go:1207: 04:25:11.563 - 93: DEBUG: new connection from 127.0.0.1:41422 372s app_test.go:1207: 04:25:11.565 - 94: DEBUG: new connection from 127.0.0.1:53664 372s app_test.go:1207: 04:25:11.566 - 94: DEBUG: new connection from 127.0.0.1:53666 372s app_test.go:1207: 04:25:11.569 - 90: DEBUG: new connection from 127.0.0.1:48944 372s app_test.go:1207: 04:25:11.647 - 90: DEBUG: new connection from 127.0.0.1:48946 372s app_test.go:1207: 04:25:11.649 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.651 - 91: DEBUG: new connection from 127.0.0.1:38240 372s app_test.go:1207: 04:25:11.652 - 90: DEBUG: new connection from 127.0.0.1:48956 372s app_test.go:1207: 04:25:11.652 - 92: DEBUG: new connection from 127.0.0.1:56596 372s app_test.go:1207: 04:25:11.653 - 93: DEBUG: new connection from 127.0.0.1:41428 372s app_test.go:1207: 04:25:11.654 - 94: DEBUG: new connection from 127.0.0.1:53680 372s app_test.go:1207: 04:25:11.654 - 95: DEBUG: new connection from 127.0.0.1:52334 372s app_test.go:1207: 04:25:11.657 - 95: DEBUG: new connection from 127.0.0.1:52346 372s app_test.go:1207: 04:25:11.661 - 90: DEBUG: new connection from 127.0.0.1:48958 372s app_test.go:1207: 04:25:11.717 - 90: DEBUG: new connection from 127.0.0.1:48970 372s app_test.go:1207: 04:25:11.719 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.720 - 90: DEBUG: new connection from 127.0.0.1:48982 372s app_test.go:1207: 04:25:11.721 - 91: DEBUG: new connection from 127.0.0.1:38252 372s app_test.go:1207: 04:25:11.721 - 92: DEBUG: new connection from 127.0.0.1:56598 372s app_test.go:1207: 04:25:11.722 - 93: DEBUG: new connection from 127.0.0.1:41444 372s app_test.go:1207: 04:25:11.722 - 94: DEBUG: new connection from 127.0.0.1:53688 372s app_test.go:1207: 04:25:11.723 - 95: DEBUG: new connection from 127.0.0.1:52360 372s app_test.go:1207: 04:25:11.724 - 96: DEBUG: new connection from 127.0.0.1:43100 372s app_test.go:1207: 04:25:11.808 - 90: DEBUG: new connection from 127.0.0.1:48996 372s app_test.go:1207: 04:25:11.811 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.814 - 91: DEBUG: new connection from 127.0.0.1:38264 372s app_test.go:1207: 04:25:11.814 - 90: DEBUG: new connection from 127.0.0.1:49000 372s app_test.go:1207: 04:25:11.815 - 92: DEBUG: new connection from 127.0.0.1:56608 372s app_test.go:1207: 04:25:11.816 - 93: DEBUG: new connection from 127.0.0.1:41452 372s app_test.go:1207: 04:25:11.817 - 94: DEBUG: new connection from 127.0.0.1:53694 372s app_test.go:1207: 04:25:11.818 - 96: DEBUG: new connection from 127.0.0.1:43102 372s app_test.go:1207: 04:25:11.818 - 95: DEBUG: new connection from 127.0.0.1:52366 372s app_test.go:1207: 04:25:11.820 - 97: DEBUG: new connection from 127.0.0.1:38140 372s app_test.go:1207: 04:25:11.888 - 90: DEBUG: new connection from 127.0.0.1:49006 372s app_test.go:1207: 04:25:11.889 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1207: 04:25:11.891 - 91: DEBUG: new connection from 127.0.0.1:38272 372s app_test.go:1207: 04:25:11.891 - 90: DEBUG: new connection from 127.0.0.1:49012 372s app_test.go:1207: 04:25:11.892 - 92: DEBUG: new connection from 127.0.0.1:56612 372s app_test.go:1207: 04:25:11.892 - 93: DEBUG: new connection from 127.0.0.1:41466 372s app_test.go:1207: 04:25:11.894 - 94: DEBUG: new connection from 127.0.0.1:53704 372s app_test.go:1207: 04:25:11.895 - 95: DEBUG: new connection from 127.0.0.1:52378 372s app_test.go:1207: 04:25:11.895 - 96: DEBUG: new connection from 127.0.0.1:43104 372s app_test.go:1207: 04:25:11.896 - 97: DEBUG: new connection from 127.0.0.1:38146 372s app_test.go:1207: 04:25:11.896 - 98: DEBUG: new connection from 127.0.0.1:42220 372s app_test.go:1207: 04:25:11.452 - 90: DEBUG: new connection from 127.0.0.1:49024 372s app_test.go:1207: 04:25:11.454 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.456 - 90: DEBUG: new connection from 127.0.0.1:49028 372s app_test.go:1207: 04:25:11.456 - 91: DEBUG: new connection from 127.0.0.1:38286 372s app_test.go:1207: 04:25:11.457 - 92: DEBUG: new connection from 127.0.0.1:56616 372s app_test.go:1207: 04:25:11.461 - 93: DEBUG: new connection from 127.0.0.1:41472 372s app_test.go:1207: 04:25:11.462 - 95: DEBUG: new connection from 127.0.0.1:52384 372s app_test.go:1207: 04:25:11.462 - 96: DEBUG: new connection from 127.0.0.1:43108 372s app_test.go:1207: 04:25:11.463 - 97: DEBUG: new connection from 127.0.0.1:38148 372s app_test.go:1207: 04:25:11.464 - 98: DEBUG: new connection from 127.0.0.1:42234 372s app_test.go:1207: 04:25:11.465 - 97: DEBUG: new connection from 127.0.0.1:38156 372s app_test.go:1207: 04:25:11.466 - 90: DEBUG: new connection from 127.0.0.1:49034 372s app_test.go:1207: 04:25:11.466 - 91: DEBUG: new connection from 127.0.0.1:38300 372s app_test.go:1207: 04:25:11.467 - 92: DEBUG: new connection from 127.0.0.1:56624 372s app_test.go:1207: 04:25:11.467 - 93: DEBUG: new connection from 127.0.0.1:41482 372s app_test.go:1207: 04:25:11.468 - 90: DEBUG: new connection from 127.0.0.1:49038 372s app_test.go:1207: 04:25:11.469 - 95: DEBUG: new connection from 127.0.0.1:52400 372s app_test.go:1207: 04:25:11.471 - 96: DEBUG: new connection from 127.0.0.1:43122 372s app_test.go:1207: 04:25:11.472 - 97: DEBUG: new connection from 127.0.0.1:38160 372s app_test.go:1207: 04:25:11.472 - 98: DEBUG: new connection from 127.0.0.1:42246 372s app_test.go:1207: 04:25:11.474 - 91: DEBUG: new connection from 127.0.0.1:38314 372s app_test.go:1207: 04:25:11.474 - 90: DEBUG: new connection from 127.0.0.1:49048 372s app_test.go:1207: 04:25:11.475 - 92: DEBUG: new connection from 127.0.0.1:56638 372s app_test.go:1207: 04:25:11.475 - 93: DEBUG: new connection from 127.0.0.1:41486 372s app_test.go:1207: 04:25:11.476 - 95: DEBUG: new connection from 127.0.0.1:52404 372s app_test.go:1207: 04:25:11.476 - 96: DEBUG: new connection from 127.0.0.1:43132 372s app_test.go:1207: 04:25:11.477 - 97: DEBUG: new connection from 127.0.0.1:38166 372s app_test.go:1207: 04:25:11.478 - 98: DEBUG: new connection from 127.0.0.1:42260 372s app_test.go:1207: 04:25:11.533 - 90: DEBUG: new connection from 127.0.0.1:49062 372s app_test.go:1207: 04:25:11.534 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.358 - 90: DEBUG: new connection from 127.0.0.1:49076 372s app_test.go:1207: 04:25:11.359 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.456 - 90: DEBUG: new connection from 127.0.0.1:49084 372s app_test.go:1207: 04:25:11.457 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.659 - 90: DEBUG: new connection from 127.0.0.1:49090 372s app_test.go:1207: 04:25:11.660 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.725 - 90: DEBUG: new connection from 127.0.0.1:49098 372s app_test.go:1207: 04:25:11.727 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.822 - 90: DEBUG: new connection from 127.0.0.1:49114 372s app_test.go:1207: 04:25:11.823 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.899 - 90: DEBUG: new connection from 127.0.0.1:49128 372s app_test.go:1207: 04:25:11.900 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.480 - 90: DEBUG: new connection from 127.0.0.1:49144 372s app_test.go:1207: 04:25:11.481 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.487 - 91: DEBUG: new connection from 127.0.0.1:38316 372s app_test.go:1207: 04:25:11.487 - 90: DEBUG: new connection from 127.0.0.1:49146 372s app_test.go:1207: 04:25:11.489 - 92: DEBUG: new connection from 127.0.0.1:56654 372s app_test.go:1207: 04:25:11.489 - 93: DEBUG: new connection from 127.0.0.1:41496 372s app_test.go:1207: 04:25:11.490 - 95: DEBUG: new connection from 127.0.0.1:52414 372s app_test.go:1207: 04:25:11.490 - 96: DEBUG: new connection from 127.0.0.1:43138 372s app_test.go:1207: 04:25:11.491 - 97: DEBUG: new connection from 127.0.0.1:38174 372s app_test.go:1207: 04:25:11.491 - 98: DEBUG: new connection from 127.0.0.1:42276 372s app_test.go:1207: 04:25:11.536 - 90: DEBUG: new connection from 127.0.0.1:49154 372s app_test.go:1207: 04:25:11.537 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.361 - 90: DEBUG: new connection from 127.0.0.1:41404 372s app_test.go:1207: 04:25:11.363 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.458 - 90: DEBUG: new connection from 127.0.0.1:41416 372s app_test.go:1207: 04:25:11.459 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.662 - 90: DEBUG: new connection from 127.0.0.1:41422 372s app_test.go:1207: 04:25:11.662 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.728 - 90: DEBUG: new connection from 127.0.0.1:41430 372s app_test.go:1207: 04:25:11.729 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.825 - 90: DEBUG: new connection from 127.0.0.1:41434 372s app_test.go:1207: 04:25:11.825 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.902 - 90: DEBUG: new connection from 127.0.0.1:41450 372s app_test.go:1207: 04:25:11.903 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.493 - 90: DEBUG: new connection from 127.0.0.1:41454 372s app_test.go:1207: 04:25:11.494 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.496 - 90: DEBUG: new connection from 127.0.0.1:41462 372s app_test.go:1207: 04:25:11.496 - 91: DEBUG: new connection from 127.0.0.1:52860 372s app_test.go:1207: 04:25:11.497 - 92: DEBUG: new connection from 127.0.0.1:57680 372s app_test.go:1207: 04:25:11.498 - 93: DEBUG: new connection from 127.0.0.1:44276 372s app_test.go:1207: 04:25:11.498 - 95: DEBUG: new connection from 127.0.0.1:53882 372s app_test.go:1207: 04:25:11.500 - 97: DEBUG: new connection from 127.0.0.1:43454 372s app_test.go:1207: 04:25:11.500 - 96: DEBUG: new connection from 127.0.0.1:55606 372s app_test.go:1207: 04:25:11.501 - 98: DEBUG: new connection from 127.0.0.1:38172 372s app_test.go:1207: 04:25:11.539 - 90: DEBUG: new connection from 127.0.0.1:41472 372s app_test.go:1207: 04:25:11.539 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.365 - 90: DEBUG: new connection from 127.0.0.1:41480 372s app_test.go:1207: 04:25:11.366 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.461 - 90: DEBUG: new connection from 127.0.0.1:41494 372s app_test.go:1207: 04:25:11.462 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.665 - 90: DEBUG: new connection from 127.0.0.1:41510 372s app_test.go:1207: 04:25:11.665 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.731 - 90: DEBUG: new connection from 127.0.0.1:41518 372s app_test.go:1207: 04:25:11.732 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.827 - 90: DEBUG: new connection from 127.0.0.1:41522 372s app_test.go:1207: 04:25:11.828 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.904 - 90: DEBUG: new connection from 127.0.0.1:41534 372s app_test.go:1207: 04:25:11.905 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.503 - 90: DEBUG: new connection from 127.0.0.1:41548 372s app_test.go:1207: 04:25:11.505 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:25:11.506 - 90: DEBUG: new connection from 127.0.0.1:41564 372s app_test.go:1207: 04:25:11.506 - 91: DEBUG: new connection from 127.0.0.1:52876 372s app_test.go:1207: 04:25:11.508 - 92: DEBUG: new connection from 127.0.0.1:57692 372s app_test.go:1207: 04:25:11.509 - 93: DEBUG: new connection from 127.0.0.1:44288 372s app_test.go:1207: 04:25:11.510 - 95: DEBUG: new connection from 127.0.0.1:53886 372s app_test.go:1207: 04:25:11.510 - 96: DEBUG: new connection from 127.0.0.1:55616 372s app_test.go:1207: 04:25:11.511 - 97: DEBUG: new connection from 127.0.0.1:43456 372s app_test.go:1207: 04:25:11.511 - 98: DEBUG: new connection from 127.0.0.1:38182 372s app_test.go:1207: 04:25:11.542 - 90: DEBUG: new connection from 127.0.0.1:41568 372s app_test.go:1207: 04:25:11.542 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:26:11.367 - 90: DEBUG: new connection from 127.0.0.1:34786 372s app_test.go:1207: 04:26:11.369 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:26:11.465 - 90: DEBUG: new connection from 127.0.0.1:34796 372s app_test.go:1207: 04:26:11.465 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:26:11.668 - 90: DEBUG: new connection from 127.0.0.1:34806 372s app_test.go:1207: 04:26:11.669 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:26:11.733 - 90: DEBUG: new connection from 127.0.0.1:34820 372s app_test.go:1207: 04:26:11.734 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:26:11.829 - 90: DEBUG: new connection from 127.0.0.1:34824 372s app_test.go:1207: 04:26:11.830 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 372s app_test.go:1207: 04:26:11.898 - 95: DEBUG: new connection from 127.0.0.1:42854 372s app_test.go:1207: 04:26:11.899 - 96: DEBUG: new connection from 127.0.0.1:33598 372s app_test.go:1207: 04:26:11.900 - 93: DEBUG: new connection from 127.0.0.1:41174 372s app_test.go:1207: 04:26:11.900 - 90: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 372s app_test.go:1207: 04:26:11.900 - 97: DEBUG: new connection from 127.0.0.1:58496 372s app_test.go:1207: 04:26:11.900 - 98: DEBUG: new connection from 127.0.0.1:38710 372s app_test.go:1207: 04:26:11.901 - 92: DEBUG: new connection from 127.0.0.1:57272 372s app_test.go:1207: 04:26:11.901 - 91: DEBUG: new connection from 127.0.0.1:39648 372s app_test.go:1207: 04:26:11.901 - 90: DEBUG: new connection from 127.0.0.1:34830 372s app_test.go:1207: 04:26:11.903 - 90: WARN: attempt 1: server 127.0.0.1:9009: no known leader 372s app_test.go:1207: 04:26:11.903 - 90: WARN: attempt 1: server 127.0.0.1:9008: no known leader 372s app_test.go:1207: 04:26:11.903 - 90: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:26:11.903 - 90: WARN: attempt 1: server 127.0.0.1:9006: no known leader 372s app_test.go:1207: 04:26:11.904 - 90: WARN: attempt 1: server 127.0.0.1:9007: no known leader 372s app_test.go:1207: 04:26:11.904 - 90: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 372s app_test.go:1207: 04:26:11.904 - 90: WARN: attempt 1: server 127.0.0.1:9004: no known leader 372s app_test.go:1207: 04:26:11.904 - 90: DEBUG: new connection from 127.0.0.1:34832 372s app_test.go:1207: 04:26:11.904 - 90: DEBUG: new connection from 127.0.0.1:34840 372s app_test.go:1207: 04:26:11.904 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s --- PASS: TestRolesAdjustment_ReplaceStandByHonorFailureDomains (23.05s) 372s === RUN TestOpen 372s app_test.go:1207: 04:26:11.500 - 99: DEBUG: new connection from 127.0.0.1:44436 372s app_test.go:1207: 04:26:11.501 - 99: DEBUG: new connection from 127.0.0.1:44452 372s app_test.go:1207: 04:26:11.502 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 372s app_test.go:1207: 04:26:11.502 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 372s app_test.go:1207: 04:26:11.504 - 99: DEBUG: new connection from 127.0.0.1:44464 372s --- PASS: TestOpen (0.18s) 372s === RUN TestOpenDisk 372s app_test.go:1207: 04:26:11.670 - 100: DEBUG: new connection from 127.0.0.1:44482 372s app_test.go:1207: 04:26:11.670 - 100: DEBUG: new connection from 127.0.0.1:44478 372s app_test.go:1207: 04:26:11.671 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 372s app_test.go:1207: 04:26:11.671 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 372s app_test.go:1207: 04:26:11.672 - 100: DEBUG: new connection from 127.0.0.1:44496 372s --- PASS: TestOpenDisk (0.17s) 372s === RUN TestOptions 372s app_test.go:1207: 04:26:11.839 - 101: WARN: attempt 1: server 127.0.0.1:9000: context canceled 372s --- PASS: TestOptions (0.29s) 372s === RUN TestProxy_Error 372s app_test.go:1207: 04:26:11.119 - 102: DEBUG: new connection from 127.0.0.1:44508 372s app_test.go:1207: 04:26:11.119 - 102: DEBUG: new connection from 127.0.0.1:44522 372s app_test.go:1207: 04:26:11.121 - 102: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 372s app_test.go:1207: 04:26:11.121 - 102: DEBUG: new connection from 127.0.0.1:44534 372s --- PASS: TestProxy_Error (0.43s) 372s === RUN TestReady_Cancel 372s app_test.go:1207: 04:26:11.568 - 103: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 372s --- PASS: TestReady_Cancel (0.30s) 372s === RUN TestExternalConnWithTCP 372s app_test.go:1232: 04:26:11.839 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 372s app_test.go:1232: 04:26:11.917 - 105: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 372s app_test.go:1232: 04:26:11.997 - 106: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 372s app_test.go:1232: 04:26:11.776 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 372s --- PASS: TestExternalConnWithTCP (2.22s) 372s === RUN TestExternalConnWithPipe 372s app_test.go:1232: 04:26:11.069 - 107: DEBUG: attempt 1: server first: connected on fallback path 372s app_test.go:1232: 04:26:11.147 - 108: DEBUG: attempt 1: server first: connected on fallback path 372s app_test.go:1232: 04:26:11.227 - 109: DEBUG: attempt 1: server first: connected on fallback path 372s app_test.go:1232: 04:26:11.994 - 107: DEBUG: attempt 1: server first: connected on fallback path 372s --- PASS: TestExternalConnWithPipe (2.25s) 372s === RUN TestParallelNewApp 372s === PAUSE TestParallelNewApp 372s === RUN Test_TxRowsAffected 372s app_test.go:1232: 04:26:11.319 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s app_test.go:1232: 04:26:11.320 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 372s --- PASS: Test_TxRowsAffected (0.16s) 372s === CONT TestParallelNewApp 372s === RUN TestParallelNewApp/run-0 372s === PAUSE TestParallelNewApp/run-0 372s === RUN TestParallelNewApp/run-1 372s === PAUSE TestParallelNewApp/run-1 372s === RUN TestParallelNewApp/run-2 372s === PAUSE TestParallelNewApp/run-2 372s === RUN TestParallelNewApp/run-3 372s === PAUSE TestParallelNewApp/run-3 372s === RUN TestParallelNewApp/run-4 372s === PAUSE TestParallelNewApp/run-4 372s === RUN TestParallelNewApp/run-5 372s === PAUSE TestParallelNewApp/run-5 372s === RUN TestParallelNewApp/run-6 372s === PAUSE TestParallelNewApp/run-6 372s === RUN TestParallelNewApp/run-7 372s === PAUSE TestParallelNewApp/run-7 372s === RUN TestParallelNewApp/run-8 372s === PAUSE TestParallelNewApp/run-8 372s === RUN TestParallelNewApp/run-9 372s === PAUSE TestParallelNewApp/run-9 372s === RUN TestParallelNewApp/run-10 372s === PAUSE TestParallelNewApp/run-10 372s === RUN TestParallelNewApp/run-11 372s === PAUSE TestParallelNewApp/run-11 372s === RUN TestParallelNewApp/run-12 372s === PAUSE TestParallelNewApp/run-12 372s === RUN TestParallelNewApp/run-13 372s === PAUSE TestParallelNewApp/run-13 372s === RUN TestParallelNewApp/run-14 372s === PAUSE TestParallelNewApp/run-14 372s === RUN TestParallelNewApp/run-15 372s === PAUSE TestParallelNewApp/run-15 372s === RUN TestParallelNewApp/run-16 372s === PAUSE TestParallelNewApp/run-16 372s === RUN TestParallelNewApp/run-17 372s === PAUSE TestParallelNewApp/run-17 372s === RUN TestParallelNewApp/run-18 372s === PAUSE TestParallelNewApp/run-18 372s === RUN TestParallelNewApp/run-19 372s === PAUSE TestParallelNewApp/run-19 372s === RUN TestParallelNewApp/run-20 372s === PAUSE TestParallelNewApp/run-20 372s === RUN TestParallelNewApp/run-21 372s === PAUSE TestParallelNewApp/run-21 372s === RUN TestParallelNewApp/run-22 372s === PAUSE TestParallelNewApp/run-22 372s === RUN TestParallelNewApp/run-23 372s === PAUSE TestParallelNewApp/run-23 372s === RUN TestParallelNewApp/run-24 372s === PAUSE TestParallelNewApp/run-24 372s === RUN TestParallelNewApp/run-25 372s === PAUSE TestParallelNewApp/run-25 372s === RUN TestParallelNewApp/run-26 372s === PAUSE TestParallelNewApp/run-26 372s === RUN TestParallelNewApp/run-27 372s === PAUSE TestParallelNewApp/run-27 372s === RUN TestParallelNewApp/run-28 372s === PAUSE TestParallelNewApp/run-28 372s === RUN TestParallelNewApp/run-29 372s === PAUSE TestParallelNewApp/run-29 372s === RUN TestParallelNewApp/run-30 372s === PAUSE TestParallelNewApp/run-30 372s === RUN TestParallelNewApp/run-31 372s === PAUSE TestParallelNewApp/run-31 372s === RUN TestParallelNewApp/run-32 372s === PAUSE TestParallelNewApp/run-32 372s === RUN TestParallelNewApp/run-33 372s === PAUSE TestParallelNewApp/run-33 372s === RUN TestParallelNewApp/run-34 372s === PAUSE TestParallelNewApp/run-34 372s === RUN TestParallelNewApp/run-35 372s === PAUSE TestParallelNewApp/run-35 372s === RUN TestParallelNewApp/run-36 372s === PAUSE TestParallelNewApp/run-36 372s === RUN TestParallelNewApp/run-37 372s === PAUSE TestParallelNewApp/run-37 372s === RUN TestParallelNewApp/run-38 372s === PAUSE TestParallelNewApp/run-38 372s === RUN TestParallelNewApp/run-39 372s === PAUSE TestParallelNewApp/run-39 372s === RUN TestParallelNewApp/run-40 372s === PAUSE TestParallelNewApp/run-40 372s === RUN TestParallelNewApp/run-41 372s === PAUSE TestParallelNewApp/run-41 372s === RUN TestParallelNewApp/run-42 372s === PAUSE TestParallelNewApp/run-42 372s === RUN TestParallelNewApp/run-43 372s === PAUSE TestParallelNewApp/run-43 372s === RUN TestParallelNewApp/run-44 372s === PAUSE TestParallelNewApp/run-44 372s === RUN TestParallelNewApp/run-45 372s === PAUSE TestParallelNewApp/run-45 372s === RUN TestParallelNewApp/run-46 372s === PAUSE TestParallelNewApp/run-46 372s === RUN TestParallelNewApp/run-47 372s === PAUSE TestParallelNewApp/run-47 372s === RUN TestParallelNewApp/run-48 372s === PAUSE TestParallelNewApp/run-48 372s === RUN TestParallelNewApp/run-49 372s === PAUSE TestParallelNewApp/run-49 372s === RUN TestParallelNewApp/run-50 372s === PAUSE TestParallelNewApp/run-50 372s === RUN TestParallelNewApp/run-51 372s === PAUSE TestParallelNewApp/run-51 372s === RUN TestParallelNewApp/run-52 372s === PAUSE TestParallelNewApp/run-52 372s === RUN TestParallelNewApp/run-53 372s === PAUSE TestParallelNewApp/run-53 372s === RUN TestParallelNewApp/run-54 372s === PAUSE TestParallelNewApp/run-54 372s === RUN TestParallelNewApp/run-55 372s === PAUSE TestParallelNewApp/run-55 372s === RUN TestParallelNewApp/run-56 372s === PAUSE TestParallelNewApp/run-56 372s === RUN TestParallelNewApp/run-57 372s === PAUSE TestParallelNewApp/run-57 372s === RUN TestParallelNewApp/run-58 372s === PAUSE TestParallelNewApp/run-58 372s === RUN TestParallelNewApp/run-59 372s === PAUSE TestParallelNewApp/run-59 372s === RUN TestParallelNewApp/run-60 372s === PAUSE TestParallelNewApp/run-60 372s === RUN TestParallelNewApp/run-61 372s === PAUSE TestParallelNewApp/run-61 372s === RUN TestParallelNewApp/run-62 372s === PAUSE TestParallelNewApp/run-62 372s === RUN TestParallelNewApp/run-63 372s === PAUSE TestParallelNewApp/run-63 372s === RUN TestParallelNewApp/run-64 372s === PAUSE TestParallelNewApp/run-64 372s === RUN TestParallelNewApp/run-65 372s === PAUSE TestParallelNewApp/run-65 372s === RUN TestParallelNewApp/run-66 372s === PAUSE TestParallelNewApp/run-66 372s === RUN TestParallelNewApp/run-67 372s === PAUSE TestParallelNewApp/run-67 372s === RUN TestParallelNewApp/run-68 372s === PAUSE TestParallelNewApp/run-68 372s === RUN TestParallelNewApp/run-69 372s === PAUSE TestParallelNewApp/run-69 372s === RUN TestParallelNewApp/run-70 372s === PAUSE TestParallelNewApp/run-70 372s === RUN TestParallelNewApp/run-71 372s === PAUSE TestParallelNewApp/run-71 372s === RUN TestParallelNewApp/run-72 372s === PAUSE TestParallelNewApp/run-72 372s === RUN TestParallelNewApp/run-73 372s === PAUSE TestParallelNewApp/run-73 372s === RUN TestParallelNewApp/run-74 372s === PAUSE TestParallelNewApp/run-74 372s === RUN TestParallelNewApp/run-75 372s === PAUSE TestParallelNewApp/run-75 372s === RUN TestParallelNewApp/run-76 372s === PAUSE TestParallelNewApp/run-76 372s === RUN TestParallelNewApp/run-77 372s === PAUSE TestParallelNewApp/run-77 372s === RUN TestParallelNewApp/run-78 372s === PAUSE TestParallelNewApp/run-78 372s === RUN TestParallelNewApp/run-79 372s === PAUSE TestParallelNewApp/run-79 372s === RUN TestParallelNewApp/run-80 372s === PAUSE TestParallelNewApp/run-80 372s === RUN TestParallelNewApp/run-81 372s === PAUSE TestParallelNewApp/run-81 372s === RUN TestParallelNewApp/run-82 372s === PAUSE TestParallelNewApp/run-82 372s === RUN TestParallelNewApp/run-83 372s === PAUSE TestParallelNewApp/run-83 372s === RUN TestParallelNewApp/run-84 372s === PAUSE TestParallelNewApp/run-84 372s === RUN TestParallelNewApp/run-85 372s === PAUSE TestParallelNewApp/run-85 372s === RUN TestParallelNewApp/run-86 372s === PAUSE TestParallelNewApp/run-86 372s === RUN TestParallelNewApp/run-87 372s === PAUSE TestParallelNewApp/run-87 372s === RUN TestParallelNewApp/run-88 372s === PAUSE TestParallelNewApp/run-88 372s === RUN TestParallelNewApp/run-89 372s === PAUSE TestParallelNewApp/run-89 372s === RUN TestParallelNewApp/run-90 372s === PAUSE TestParallelNewApp/run-90 372s === RUN TestParallelNewApp/run-91 372s === PAUSE TestParallelNewApp/run-91 372s === RUN TestParallelNewApp/run-92 372s === PAUSE TestParallelNewApp/run-92 372s === RUN TestParallelNewApp/run-93 372s === PAUSE TestParallelNewApp/run-93 372s === RUN TestParallelNewApp/run-94 372s === PAUSE TestParallelNewApp/run-94 372s === RUN TestParallelNewApp/run-95 372s === PAUSE TestParallelNewApp/run-95 372s === RUN TestParallelNewApp/run-96 372s === PAUSE TestParallelNewApp/run-96 372s === RUN TestParallelNewApp/run-97 372s === PAUSE TestParallelNewApp/run-97 372s === RUN TestParallelNewApp/run-98 372s === PAUSE TestParallelNewApp/run-98 372s === RUN TestParallelNewApp/run-99 372s === PAUSE TestParallelNewApp/run-99 372s === CONT TestParallelNewApp/run-0 372s === CONT TestParallelNewApp/run-50 372s === CONT TestParallelNewApp/run-49 372s === CONT TestParallelNewApp/run-48 372s === CONT TestParallelNewApp/run-47 372s === CONT TestParallelNewApp/run-46 372s === CONT TestParallelNewApp/run-45 372s === CONT TestParallelNewApp/run-44 372s === CONT TestParallelNewApp/run-43 372s === CONT TestParallelNewApp/run-42 372s === CONT TestParallelNewApp/run-41 372s === CONT TestParallelNewApp/run-40 372s === CONT TestParallelNewApp/run-39 372s === CONT TestParallelNewApp/run-38 372s === CONT TestParallelNewApp/run-37 372s === CONT TestParallelNewApp/run-36 372s === CONT TestParallelNewApp/run-35 372s === CONT TestParallelNewApp/run-34 372s === CONT TestParallelNewApp/run-33 372s === CONT TestParallelNewApp/run-32 372s === CONT TestParallelNewApp/run-31 372s === CONT TestParallelNewApp/run-30 372s === CONT TestParallelNewApp/run-29 372s === CONT TestParallelNewApp/run-28 372s === CONT TestParallelNewApp/run-27 372s === CONT TestParallelNewApp/run-26 372s === CONT TestParallelNewApp/run-25 372s === CONT TestParallelNewApp/run-24 372s === CONT TestParallelNewApp/run-23 372s === CONT TestParallelNewApp/run-22 372s === CONT TestParallelNewApp/run-21 372s === CONT TestParallelNewApp/run-20 372s === CONT TestParallelNewApp/run-19 372s === CONT TestParallelNewApp/run-18 372s === CONT TestParallelNewApp/run-17 372s === CONT TestParallelNewApp/run-16 372s === CONT TestParallelNewApp/run-15 372s === CONT TestParallelNewApp/run-14 372s === CONT TestParallelNewApp/run-13 372s === CONT TestParallelNewApp/run-12 372s === CONT TestParallelNewApp/run-11 372s === CONT TestParallelNewApp/run-10 372s === CONT TestParallelNewApp/run-9 372s === CONT TestParallelNewApp/run-8 372s === CONT TestParallelNewApp/run-7 372s === CONT TestParallelNewApp/run-6 372s === CONT TestParallelNewApp/run-5 372s === CONT TestParallelNewApp/run-4 372s === CONT TestParallelNewApp/run-3 372s === CONT TestParallelNewApp/run-2 372s === CONT TestParallelNewApp/run-1 372s === CONT TestParallelNewApp/run-75 372s === CONT TestParallelNewApp/run-99 372s === CONT TestParallelNewApp/run-98 372s === CONT TestParallelNewApp/run-97 372s === CONT TestParallelNewApp/run-96 372s === CONT TestParallelNewApp/run-95 372s === CONT TestParallelNewApp/run-94 372s === CONT TestParallelNewApp/run-93 372s === CONT TestParallelNewApp/run-92 372s === CONT TestParallelNewApp/run-91 372s === CONT TestParallelNewApp/run-90 372s === CONT TestParallelNewApp/run-89 372s === CONT TestParallelNewApp/run-88 372s === CONT TestParallelNewApp/run-87 372s === CONT TestParallelNewApp/run-86 372s === CONT TestParallelNewApp/run-85 372s === CONT TestParallelNewApp/run-84 372s === CONT TestParallelNewApp/run-83 372s === CONT TestParallelNewApp/run-82 372s === CONT TestParallelNewApp/run-81 372s === CONT TestParallelNewApp/run-80 372s === CONT TestParallelNewApp/run-79 372s === CONT TestParallelNewApp/run-78 372s === CONT TestParallelNewApp/run-77 372s === CONT TestParallelNewApp/run-76 372s === CONT TestParallelNewApp/run-63 372s === CONT TestParallelNewApp/run-74 372s === CONT TestParallelNewApp/run-73 372s === CONT TestParallelNewApp/run-72 372s === CONT TestParallelNewApp/run-71 372s === CONT TestParallelNewApp/run-70 372s === CONT TestParallelNewApp/run-69 372s === CONT TestParallelNewApp/run-68 372s === CONT TestParallelNewApp/run-67 372s === CONT TestParallelNewApp/run-66 372s === CONT TestParallelNewApp/run-65 372s === CONT TestParallelNewApp/run-64 372s === CONT TestParallelNewApp/run-57 372s === CONT TestParallelNewApp/run-62 372s === CONT TestParallelNewApp/run-61 372s === CONT TestParallelNewApp/run-60 372s === CONT TestParallelNewApp/run-59 372s === CONT TestParallelNewApp/run-58 372s === CONT TestParallelNewApp/run-54 372s === CONT TestParallelNewApp/run-56 372s === CONT TestParallelNewApp/run-55 372s === CONT TestParallelNewApp/run-52 372s === CONT TestParallelNewApp/run-53 372s === CONT TestParallelNewApp/run-51 372s --- PASS: TestParallelNewApp (0.00s) 372s --- PASS: TestParallelNewApp/run-0 (0.08s) 372s --- PASS: TestParallelNewApp/run-50 (0.08s) 372s --- PASS: TestParallelNewApp/run-48 (0.10s) 372s --- PASS: TestParallelNewApp/run-49 (0.30s) 372s --- PASS: TestParallelNewApp/run-46 (0.08s) 372s --- PASS: TestParallelNewApp/run-47 (0.30s) 372s --- PASS: TestParallelNewApp/run-44 (0.06s) 372s --- PASS: TestParallelNewApp/run-45 (0.10s) 372s --- PASS: TestParallelNewApp/run-43 (0.07s) 372s --- PASS: TestParallelNewApp/run-42 (0.10s) 372s --- PASS: TestParallelNewApp/run-41 (0.10s) 372s --- PASS: TestParallelNewApp/run-40 (0.11s) 372s --- PASS: TestParallelNewApp/run-39 (0.07s) 372s --- PASS: TestParallelNewApp/run-37 (0.06s) 372s --- PASS: TestParallelNewApp/run-36 (0.08s) 372s --- PASS: TestParallelNewApp/run-35 (0.09s) 372s --- PASS: TestParallelNewApp/run-38 (0.29s) 372s --- PASS: TestParallelNewApp/run-34 (0.10s) 372s --- PASS: TestParallelNewApp/run-32 (0.08s) 372s --- PASS: TestParallelNewApp/run-31 (0.28s) 372s --- PASS: TestParallelNewApp/run-30 (0.08s) 372s --- PASS: TestParallelNewApp/run-33 (0.66s) 372s --- PASS: TestParallelNewApp/run-29 (0.28s) 372s --- PASS: TestParallelNewApp/run-27 (0.09s) 372s --- PASS: TestParallelNewApp/run-28 (0.26s) 372s --- PASS: TestParallelNewApp/run-26 (0.09s) 372s --- PASS: TestParallelNewApp/run-25 (0.08s) 372s --- PASS: TestParallelNewApp/run-24 (0.10s) 372s --- PASS: TestParallelNewApp/run-23 (0.10s) 372s --- PASS: TestParallelNewApp/run-22 (0.09s) 372s --- PASS: TestParallelNewApp/run-21 (0.09s) 372s --- PASS: TestParallelNewApp/run-20 (0.10s) 372s --- PASS: TestParallelNewApp/run-19 (0.10s) 372s --- PASS: TestParallelNewApp/run-18 (0.10s) 372s --- PASS: TestParallelNewApp/run-17 (0.10s) 372s --- PASS: TestParallelNewApp/run-16 (0.06s) 372s --- PASS: TestParallelNewApp/run-15 (0.08s) 372s --- PASS: TestParallelNewApp/run-14 (0.09s) 372s --- PASS: TestParallelNewApp/run-13 (0.09s) 372s --- PASS: TestParallelNewApp/run-12 (0.09s) 372s --- PASS: TestParallelNewApp/run-11 (0.07s) 372s --- PASS: TestParallelNewApp/run-10 (0.07s) 372s --- PASS: TestParallelNewApp/run-8 (0.08s) 372s --- PASS: TestParallelNewApp/run-9 (0.10s) 372s --- PASS: TestParallelNewApp/run-6 (0.10s) 372s --- PASS: TestParallelNewApp/run-7 (0.10s) 372s --- PASS: TestParallelNewApp/run-5 (0.08s) 372s --- PASS: TestParallelNewApp/run-3 (0.08s) 372s --- PASS: TestParallelNewApp/run-2 (0.08s) 372s --- PASS: TestParallelNewApp/run-4 (0.30s) 372s --- PASS: TestParallelNewApp/run-75 (0.08s) 372s --- PASS: TestParallelNewApp/run-1 (0.29s) 372s --- PASS: TestParallelNewApp/run-98 (0.09s) 372s --- PASS: TestParallelNewApp/run-99 (0.27s) 372s --- PASS: TestParallelNewApp/run-97 (0.09s) 372s --- PASS: TestParallelNewApp/run-96 (0.12s) 372s --- PASS: TestParallelNewApp/run-95 (0.07s) 372s --- PASS: TestParallelNewApp/run-93 (0.06s) 372s --- PASS: TestParallelNewApp/run-94 (0.09s) 372s --- PASS: TestParallelNewApp/run-92 (0.07s) 372s --- PASS: TestParallelNewApp/run-90 (0.09s) 372s --- PASS: TestParallelNewApp/run-89 (0.08s) 372s --- PASS: TestParallelNewApp/run-88 (0.08s) 372s --- PASS: TestParallelNewApp/run-91 (0.30s) 372s --- PASS: TestParallelNewApp/run-87 (0.09s) 372s --- PASS: TestParallelNewApp/run-86 (0.09s) 372s --- PASS: TestParallelNewApp/run-85 (0.07s) 372s --- PASS: TestParallelNewApp/run-83 (0.07s) 372s --- PASS: TestParallelNewApp/run-82 (0.09s) 372s --- PASS: TestParallelNewApp/run-81 (0.09s) 372s --- PASS: TestParallelNewApp/run-80 (0.09s) 372s --- PASS: TestParallelNewApp/run-79 (0.08s) 372s --- PASS: TestParallelNewApp/run-84 (0.49s) 372s --- PASS: TestParallelNewApp/run-78 (0.08s) 372s --- PASS: TestParallelNewApp/run-77 (0.08s) 372s --- PASS: TestParallelNewApp/run-63 (0.08s) 372s --- PASS: TestParallelNewApp/run-76 (0.08s) 372s --- PASS: TestParallelNewApp/run-74 (0.09s) 372s --- PASS: TestParallelNewApp/run-72 (0.11s) 372s --- PASS: TestParallelNewApp/run-73 (0.29s) 372s --- PASS: TestParallelNewApp/run-71 (0.28s) 372s --- PASS: TestParallelNewApp/run-70 (0.29s) 372s --- PASS: TestParallelNewApp/run-69 (0.29s) 372s --- PASS: TestParallelNewApp/run-67 (0.09s) 372s --- PASS: TestParallelNewApp/run-68 (0.29s) 372s --- PASS: TestParallelNewApp/run-66 (0.08s) 372s --- PASS: TestParallelNewApp/run-65 (0.09s) 372s --- PASS: TestParallelNewApp/run-64 (0.07s) 372s --- PASS: TestParallelNewApp/run-57 (0.08s) 372s --- PASS: TestParallelNewApp/run-62 (0.08s) 372s --- PASS: TestParallelNewApp/run-61 (0.08s) 372s --- PASS: TestParallelNewApp/run-60 (0.07s) 372s --- PASS: TestParallelNewApp/run-59 (0.09s) 372s --- PASS: TestParallelNewApp/run-58 (0.10s) 372s --- PASS: TestParallelNewApp/run-54 (0.11s) 372s --- PASS: TestParallelNewApp/run-56 (0.07s) 372s --- PASS: TestParallelNewApp/run-55 (0.11s) 372s --- PASS: TestParallelNewApp/run-53 (0.10s) 372s --- PASS: TestParallelNewApp/run-52 (0.28s) 372s --- PASS: TestParallelNewApp/run-51 (0.09s) 372s === RUN Example 372s --- PASS: Example (0.38s) 372s PASS 372s ok github.com/canonical/go-dqlite/app 175.300s 381s === RUN TestNew_Default 381s Benchmark done. Results available here: 381s /tmp/dqlite-app-test-3932869536/results 381s --- PASS: TestNew_Default (1.22s) 381s === RUN TestNew_KvReadWrite 381s Benchmark done. Results available here: 381s /tmp/dqlite-app-test-2177189464/results 381s --- PASS: TestNew_KvReadWrite (1.17s) 381s === RUN TestNew_ClusteredKvReadWrite 381s Benchmark done. Results available here: 381s /tmp/dqlite-app-test-4045409439/results 381s --- PASS: TestNew_ClusteredKvReadWrite (4.11s) 381s === RUN TestNew_ClusteredTimeout 381s --- PASS: TestNew_ClusteredTimeout (2.18s) 381s PASS 381s ok github.com/canonical/go-dqlite/benchmark 8.791s 384s === RUN TestClient_Leader 384s --- PASS: TestClient_Leader (0.11s) 384s === RUN TestClient_Cluster 384s --- PASS: TestClient_Cluster (0.08s) 384s === RUN TestClient_Transfer 384s --- PASS: TestClient_Transfer (1.16s) 384s === RUN TestClient_Describe 384s --- PASS: TestClient_Describe (0.07s) 384s === RUN TestMembership 384s --- PASS: TestMembership (1.15s) 384s === RUN TestDefaultNodeStore 384s --- PASS: TestDefaultNodeStore (0.00s) 384s === RUN TestConfigMultiThread 384s --- PASS: TestConfigMultiThread (0.00s) 384s PASS 384s ok github.com/canonical/go-dqlite/client 2.571s 384s ? github.com/canonical/go-dqlite/cmd/dqlite [no test files] 384s ? github.com/canonical/go-dqlite/cmd/dqlite-benchmark [no test files] 384s ? github.com/canonical/go-dqlite/cmd/dqlite-demo [no test files] 411s === RUN TestDriver_Open 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestDriver_Open (0.09s) 411s === RUN TestDriver_Prepare 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestDriver_Prepare (0.09s) 411s === RUN TestConn_Exec 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestConn_Exec (0.08s) 411s === RUN TestConn_Query 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestConn_Query (0.10s) 411s === RUN TestConn_QueryRow 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestConn_QueryRow (0.09s) 411s === RUN TestConn_InterruptQuery 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestConn_InterruptQuery (0.14s) 411s === RUN TestConn_QueryBlob 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestConn_QueryBlob (0.07s) 411s === RUN TestStmt_Exec 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestStmt_Exec (0.07s) 411s === RUN TestStmt_ExecManyParams 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestStmt_ExecManyParams (0.06s) 411s === RUN TestStmt_Query 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestStmt_Query (0.09s) 411s === RUN TestStmt_QueryManyParams 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestStmt_QueryManyParams (0.08s) 411s === RUN TestConn_QueryParams 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestConn_QueryParams (0.09s) 411s === RUN TestConn_QueryManyParams 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestConn_QueryManyParams (0.09s) 411s === RUN TestConn_ExecManyParams 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestConn_ExecManyParams (0.07s) 411s === RUN Test_ColumnTypesEmpty 411s driver_test.go:472: this currently fails if the result set is empty, is dqlite skipping the header if empty set? 411s --- SKIP: Test_ColumnTypesEmpty (0.00s) 411s === RUN Test_ColumnTypesExists 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: Test_ColumnTypesExists (0.09s) 411s === RUN Test_ColumnTypesEnd 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: Test_ColumnTypesEnd (0.08s) 411s === RUN Test_ZeroColumns 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: Test_ZeroColumns (0.17s) 411s === RUN Test_DescribeLastEntry 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: Test_DescribeLastEntry (0.41s) 411s === RUN Test_Dump 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: Test_Dump (0.16s) 411s === RUN TestIntegration_DatabaseSQL 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s func.go:15: WARN: attempt 1: server @2: dial: dial unix @2: operation was canceled 411s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 411s func.go:15: WARN: attempt 1: server @1: context canceled 411s --- PASS: TestIntegration_DatabaseSQL (2.24s) 411s === RUN TestIntegration_ConstraintError 411s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 1: server @1: context canceled 411s --- PASS: TestIntegration_ConstraintError (2.27s) 411s === RUN TestIntegration_ExecBindError 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s integration_test.go:116: 411s Error Trace: /tmp/autopkgtest.Ks1tT4/autopkgtest_tmp/_build/src/github.com/canonical/go-dqlite/driver/integration_test.go:116 411s Error: An error is expected but got nil. 411s Test: TestIntegration_ExecBindError 411s --- FAIL: TestIntegration_ExecBindError (0.19s) 411s === RUN TestIntegration_QueryBindError 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestIntegration_QueryBindError (0.07s) 411s === RUN TestIntegration_LargeQuery 411s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 411s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestIntegration_LargeQuery (2.25s) 411s === RUN TestIntegration_Recover 411s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 411s func.go:15: DEBUG: attempt 1: server @1: connected on fast path 411s --- PASS: TestIntegration_Recover (1.40s) 411s === RUN TestIntegration_PingOnlyWorksOnceLeaderElected 411s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 1: server @1: no known leader 411s func.go:15: WARN: attempt 1: server @1: reported leader server is not the leader 411s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 2: server @1: no known leader 411s func.go:15: WARN: attempt 2: server @1: reported leader server is not the leader 411s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 3: server @1: no known leader 411s func.go:15: WARN: attempt 3: server @1: reported leader server is not the leader 411s func.go:15: WARN: attempt 4: server @1: no known leader 411s func.go:15: DEBUG: attempt 4: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 4: server @1: reported leader server is not the leader 411s func.go:15: WARN: attempt 5: server @1: no known leader 411s func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 5: server @1: reported leader server is not the leader 411s func.go:15: WARN: attempt 6: server @2: no known leader 411s func.go:15: WARN: attempt 6: server @1: no known leader 411s func.go:15: DEBUG: attempt 7: server @2: connect to reported leader @1 411s func.go:15: DEBUG: attempt 7: server @1: connected on fallback path 411s --- PASS: TestIntegration_PingOnlyWorksOnceLeaderElected (7.11s) 411s === RUN TestIntegration_HighAvailability 411s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 1: server @1: context canceled 411s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 411s func.go:15: WARN: attempt 1: server @1: context canceled 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 411s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: WARN: attempt 1: server @3: no known leader 411s func.go:15: WARN: attempt 1: server @2: no known leader 411s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: WARN: attempt 2: server @3: no known leader 411s func.go:15: WARN: attempt 2: server @2: no known leader 411s func.go:15: WARN: attempt 3: server @2: no known leader 411s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: WARN: attempt 3: server @3: no known leader 411s func.go:15: WARN: attempt 4: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: WARN: attempt 4: server @3: no known leader 411s func.go:15: WARN: attempt 4: server @2: no known leader 411s func.go:15: WARN: attempt 5: server @1: dial: dial unix @1: connect: connection refused 411s func.go:15: DEBUG: attempt 5: server @3: connect to reported leader @2 411s func.go:15: DEBUG: attempt 5: server @2: connected on fallback path 411s --- PASS: TestIntegration_HighAvailability (6.99s) 411s === RUN TestIntegration_LeadershipTransfer 411s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 411s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s func.go:15: DEBUG: leadership lost (10250 - not leader) 411s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 411s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 411s func.go:15: WARN: attempt 1: server @2: context canceled 411s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 411s func.go:15: WARN: attempt 1: server @2: context canceled 411s --- PASS: TestIntegration_LeadershipTransfer (2.25s) 411s === RUN TestIntegration_LeadershipTransfer_Tx 411s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 411s func.go:15: WARN: attempt 1: server @1: context canceled 411s func.go:15: DEBUG: leadership lost (10250 - not leader) 411s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 411s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 411s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 411s func.go:15: WARN: attempt 1: server @2: context canceled 411s --- PASS: TestIntegration_LeadershipTransfer_Tx (2.26s) 411s === RUN TestOptions 411s --- PASS: TestOptions (0.00s) 411s === RUN TestIntegration_ColumnTypeName 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestIntegration_ColumnTypeName (0.16s) 411s === RUN TestIntegration_SqlNullTime 411s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 411s --- PASS: TestIntegration_SqlNullTime (0.17s) 411s FAIL 411s FAIL github.com/canonical/go-dqlite/driver 29.472s 411s === RUN TestNode_Create 411s --- PASS: TestNode_Create (0.07s) 411s === RUN TestNode_Start 411s --- PASS: TestNode_Start (0.08s) 411s === RUN TestNode_Restart 411s --- PASS: TestNode_Restart (0.17s) 411s === RUN TestNode_Start_Inet 411s --- PASS: TestNode_Start_Inet (0.08s) 411s === RUN TestNode_Leader 411s --- PASS: TestNode_Leader (0.09s) 411s === RUN TestNode_Autorecovery 411s --- PASS: TestNode_Autorecovery (0.09s) 411s PASS 411s ok github.com/canonical/go-dqlite/internal/bindings 0.577s 411s === RUN TestMessage_StaticBytesAlignment 411s --- PASS: TestMessage_StaticBytesAlignment (0.00s) 411s === RUN TestMessage_putBlob 411s === RUN TestMessage_putBlob/16 411s === RUN TestMessage_putBlob/16#01 411s === RUN TestMessage_putBlob/24 411s --- PASS: TestMessage_putBlob (0.00s) 411s --- PASS: TestMessage_putBlob/16 (0.00s) 411s --- PASS: TestMessage_putBlob/16#01 (0.00s) 411s --- PASS: TestMessage_putBlob/24 (0.00s) 411s === RUN TestMessage_putString 411s === RUN TestMessage_putString/hello 411s === RUN TestMessage_putString/hello!! 411s === RUN TestMessage_putString/hello_world 411s --- PASS: TestMessage_putString (0.00s) 411s --- PASS: TestMessage_putString/hello (0.00s) 411s --- PASS: TestMessage_putString/hello!! (0.00s) 411s --- PASS: TestMessage_putString/hello_world (0.00s) 411s === RUN TestMessage_putUint8 411s --- PASS: TestMessage_putUint8 (0.00s) 411s === RUN TestMessage_putUint16 411s --- PASS: TestMessage_putUint16 (0.00s) 411s === RUN TestMessage_putUint32 411s --- PASS: TestMessage_putUint32 (0.00s) 411s === RUN TestMessage_putUint64 411s --- PASS: TestMessage_putUint64 (0.00s) 411s === RUN TestMessage_putNamedValues 411s --- PASS: TestMessage_putNamedValues (0.00s) 411s === RUN TestMessage_putNamedValues32 411s --- PASS: TestMessage_putNamedValues32 (0.00s) 411s === RUN TestMessage_putHeader 411s --- PASS: TestMessage_putHeader (0.00s) 411s === RUN TestMessage_getString 411s === RUN TestMessage_getString/hello 411s === RUN TestMessage_getString/hello!! 411s === RUN TestMessage_getString/hello!!! 411s === RUN TestMessage_getString/hello_world 411s --- PASS: TestMessage_getString (0.00s) 411s --- PASS: TestMessage_getString/hello (0.00s) 411s --- PASS: TestMessage_getString/hello!! (0.00s) 411s --- PASS: TestMessage_getString/hello!!! (0.00s) 411s --- PASS: TestMessage_getString/hello_world (0.00s) 411s === RUN TestMessage_getBlob 411s === RUN TestMessage_getBlob/16 411s === RUN TestMessage_getBlob/16#01 411s === RUN TestMessage_getBlob/24 411s --- PASS: TestMessage_getBlob (0.00s) 411s --- PASS: TestMessage_getBlob/16 (0.00s) 411s --- PASS: TestMessage_getBlob/16#01 (0.00s) 411s --- PASS: TestMessage_getBlob/24 (0.00s) 411s === RUN TestMessage_getString_Overflow_WordBoundary 411s --- PASS: TestMessage_getString_Overflow_WordBoundary (0.00s) 411s === RUN TestConnector_Success 411s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 411s --- PASS: TestConnector_Success (0.07s) 411s === RUN TestConnector_LeaderTracker 411s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 411s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 411s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 411s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 411s connector_test.go:398: DEBUG: reusing shared connection to @test-0 411s connector_test.go:398: DEBUG: discarding shared connection to @test-0 411s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 411s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 411s connector_test.go:398: DEBUG: reusing shared connection to @test-0 411s connector_test.go:398: DEBUG: discarding shared connection to @test-0 411s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 411s --- PASS: TestConnector_LeaderTracker (0.89s) 411s === RUN TestConnector_LimitRetries 411s connector_test.go:398: WARN: attempt 1: server @test-123: dial: dial unix @test-123: connect: connection refused 411s connector_test.go:398: WARN: attempt 2: server @test-123: dial: dial unix @test-123: connect: connection refused 411s connector_test.go:398: WARN: attempt 3: server @test-123: dial: dial unix @test-123: connect: connection refused 411s --- PASS: TestConnector_LimitRetries (0.60s) 411s === RUN TestConnector_DialTimeout 411s connector_test.go:158: Skipping network test 411s --- SKIP: TestConnector_DialTimeout (0.00s) 411s === RUN TestConnector_EmptyNodeStore 411s --- PASS: TestConnector_EmptyNodeStore (0.20s) 411s === RUN TestConnector_ContextCanceled 411s connector_test.go:193: Skipping network test 411s --- SKIP: TestConnector_ContextCanceled (0.00s) 411s === RUN TestConnector_AttemptTimeout 411s func.go:15: WARN: attempt 1: server @1234: call leader (budget 99.93503ms): receive: header: read unix @->@1234: i/o timeout 411s func.go:15: WARN: attempt 2: server @1234: call leader (budget 99.7916ms): receive: header: read unix @->@1234: i/o timeout 411s --- PASS: TestConnector_AttemptTimeout (0.40s) 411s === RUN TestProtocol_RequestWithDynamicBuffer 411s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 411s --- PASS: TestProtocol_RequestWithDynamicBuffer (0.17s) 411s === RUN TestProtocol_Prepare 411s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 411s --- PASS: TestProtocol_Prepare (0.09s) 411s PASS 411s ok github.com/canonical/go-dqlite/internal/protocol 2.434s 411s ? github.com/canonical/go-dqlite/internal/shell [no test files] 411s === RUN Test_TestFunc 411s func.go:15: INFO: hello 411s --- PASS: Test_TestFunc (0.00s) 411s === RUN TestLevel_String 411s --- PASS: TestLevel_String (0.00s) 411s PASS 411s ok github.com/canonical/go-dqlite/logging 0.002s 411s ? github.com/canonical/go-dqlite/tracing [no test files] 411s FAIL 411s 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 411s make: *** [debian/rules:11: autopkgtest] Error 25 412s autopkgtest [04:26:54]: test dh-golang-autopkgtest: -----------------------] 412s autopkgtest [04:26:54]: test dh-golang-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 412s dh-golang-autopkgtest FLAKY non-zero exit status 2 413s autopkgtest [04:26:55]: @@@@@@@@@@@@@@@@@@@@ summary 413s dh-golang-autopkgtest FLAKY non-zero exit status 2