0s autopkgtest [09:47:10]: starting date and time: 2024-03-20 09:47:10+0000 0s autopkgtest [09:47:10]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [09:47:10]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.e20iyhp4/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:dqlite,src:libuv1 --apt-upgrade golang-github-canonical-go-dqlite --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=dqlite/1.16.0-5build1 libuv1/1.48.0-1.1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos01-s390x-7.secgroup --name adt-noble-s390x-golang-github-canonical-go-dqlite-20240320-094710-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://us.ports.ubuntu.com/ubuntu-ports/ 93s autopkgtest [09:48:43]: testbed dpkg architecture: s390x 94s autopkgtest [09:48:44]: testbed apt version: 2.7.12 94s autopkgtest [09:48:44]: @@@@@@@@@@@@@@@@@@@@ test bed setup 94s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 94s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 94s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [52.7 kB] 94s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3810 kB] 95s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [497 kB] 95s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [653 kB] 95s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 95s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 95s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 95s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [4050 kB] 95s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 95s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [34.4 kB] 95s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 97s Fetched 9232 kB in 2s (3887 kB/s) 97s Reading package lists... 100s Reading package lists... 100s Building dependency tree... 100s Reading state information... 101s Calculating upgrade... 101s The following packages will be upgraded: 101s ubuntu-minimal ubuntu-standard 101s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 101s Need to get 21.5 kB of archives. 101s After this operation, 0 B of additional disk space will be used. 101s Get:1 http://ftpmaster.internal/ubuntu noble/main s390x ubuntu-minimal s390x 1.536build1 [10.7 kB] 101s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x ubuntu-standard s390x 1.536build1 [10.7 kB] 101s Fetched 21.5 kB in 0s (101 kB/s) 101s (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 ... 51778 files and directories currently installed.) 101s Preparing to unpack .../ubuntu-minimal_1.536build1_s390x.deb ... 101s Unpacking ubuntu-minimal (1.536build1) over (1.536) ... 101s Preparing to unpack .../ubuntu-standard_1.536build1_s390x.deb ... 101s Unpacking ubuntu-standard (1.536build1) over (1.536) ... 101s Setting up ubuntu-minimal (1.536build1) ... 101s Setting up ubuntu-standard (1.536build1) ... 102s Reading package lists... 102s Building dependency tree... 102s Reading state information... 102s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 103s Hit:1 http://ftpmaster.internal/ubuntu noble InRelease 103s Hit:2 http://ftpmaster.internal/ubuntu noble-updates InRelease 103s Hit:3 http://ftpmaster.internal/ubuntu noble-security InRelease 103s Hit:4 http://ftpmaster.internal/ubuntu noble-proposed InRelease 104s Reading package lists... 104s Reading package lists... 104s Building dependency tree... 104s Reading state information... 104s Calculating upgrade... 105s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 105s Reading package lists... 105s Building dependency tree... 105s Reading state information... 105s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 108s autopkgtest [09:48:58]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 108s autopkgtest [09:48:58]: @@@@@@@@@@@@@@@@@@@@ apt-source golang-github-canonical-go-dqlite 110s Get:1 http://ftpmaster.internal/ubuntu noble/universe golang-github-canonical-go-dqlite 1.21.0-1 (dsc) [2778 B] 110s Get:2 http://ftpmaster.internal/ubuntu noble/universe golang-github-canonical-go-dqlite 1.21.0-1 (tar) [110 kB] 110s Get:3 http://ftpmaster.internal/ubuntu noble/universe golang-github-canonical-go-dqlite 1.21.0-1 (diff) [4388 B] 111s gpgv: Signature made Mon Oct 30 22:11:23 2023 UTC 111s gpgv: using RSA key D41A7AD07DF6C5FCA7489F1C29EEE2D6ECF442F9 111s gpgv: issuer "gibmat@debian.org" 111s gpgv: Can't check signature: No public key 111s dpkg-source: warning: cannot verify inline signature for ./golang-github-canonical-go-dqlite_1.21.0-1.dsc: no acceptable signature found 111s autopkgtest [09:49:01]: testing package golang-github-canonical-go-dqlite version 1.21.0-1 112s autopkgtest [09:49:02]: build not needed 116s autopkgtest [09:49:06]: test dh-golang-autopkgtest: preparing testbed 122s Reading package lists... 122s Building dependency tree... 122s Reading state information... 122s Starting pkgProblemResolver with broken count: 0 122s Starting 2 pkgProblemResolver with broken count: 0 122s Done 122s The following additional packages will be installed: 122s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 122s cpp-13-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit dh-autoreconf 122s dh-golang dh-strip-nondeterminism dwz g++ g++-13 g++-13-s390x-linux-gnu 122s g++-s390x-linux-gnu gcc gcc-13 gcc-13-base gcc-13-s390x-linux-gnu 122s gcc-s390x-linux-gnu gettext go-dqlite golang-1.22-go golang-1.22-src 122s golang-any golang-github-burntsushi-toml-dev 122s golang-github-canonical-go-dqlite-dev 122s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 122s golang-github-fsnotify-fsnotify-dev golang-github-ghodss-yaml-dev 122s golang-github-google-renameio-dev golang-github-hashicorp-hcl-dev 122s golang-github-kr-fs-dev golang-github-magiconair-properties-dev 122s golang-github-mattn-go-runewidth-dev golang-github-mattn-go-sqlite3-dev 122s golang-github-mitchellh-mapstructure-dev golang-github-pelletier-go-toml-dev 122s golang-github-pelletier-go-toml.v2-dev golang-github-peterh-liner-dev 122s golang-github-pkg-errors-dev golang-github-pkg-sftp-dev 122s golang-github-pmezard-go-difflib-dev golang-github-rican7-retry-dev 122s golang-github-rivo-uniseg-dev golang-github-russross-blackfriday-v2-dev 122s golang-github-shurcool-sanitized-anchor-name-dev 122s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 122s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 122s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 122s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 122s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 122s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 122s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 122s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 122s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 122s libdebhelper-perl libdqlite-dev libdqlite0 libdw1 122s libfile-stripnondeterminism-perl libgcc-13-dev libgomp1 libisl23 libitm1 122s libmpc3 libnsl-dev libraft-dev libraft0 libsqlite3-dev libstdc++-13-dev 122s libsub-override-perl libtirpc-dev libtool libubsan1 libuv1t64 linux-libc-dev 122s m4 po-debconf rpcsvc-proto 122s Suggested packages: 122s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 122s cpp-13-doc dh-make g++-multilib g++-13-multilib gcc-13-doc gcc-multilib 122s manpages-dev flex bison gdb gcc-doc gcc-13-multilib gdb-s390x-linux-gnu 122s gettext-doc libasprintf-dev libgettextpo-dev bzr | brz git mercurial 122s subversion glibc-doc sqlite3-doc libstdc++-13-doc libtool-doc gfortran 122s | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 122s Recommended packages: 122s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 122s libltdl-dev libmail-sendmail-perl 122s The following packages will be REMOVED: 122s libuv1 122s The following NEW packages will be installed: 122s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 122s cpp cpp-13 cpp-13-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit 122s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 122s g++-13-s390x-linux-gnu g++-s390x-linux-gnu gcc gcc-13 gcc-13-base 122s gcc-13-s390x-linux-gnu gcc-s390x-linux-gnu gettext go-dqlite golang-1.22-go 122s golang-1.22-src golang-any golang-github-burntsushi-toml-dev 122s golang-github-canonical-go-dqlite-dev 122s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 122s golang-github-fsnotify-fsnotify-dev golang-github-ghodss-yaml-dev 122s golang-github-google-renameio-dev golang-github-hashicorp-hcl-dev 122s golang-github-kr-fs-dev golang-github-magiconair-properties-dev 122s golang-github-mattn-go-runewidth-dev golang-github-mattn-go-sqlite3-dev 122s golang-github-mitchellh-mapstructure-dev golang-github-pelletier-go-toml-dev 122s golang-github-pelletier-go-toml.v2-dev golang-github-peterh-liner-dev 122s golang-github-pkg-errors-dev golang-github-pkg-sftp-dev 122s golang-github-pmezard-go-difflib-dev golang-github-rican7-retry-dev 122s golang-github-rivo-uniseg-dev golang-github-russross-blackfriday-v2-dev 122s golang-github-shurcool-sanitized-anchor-name-dev 122s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 122s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 122s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 122s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 122s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 122s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 122s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 122s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 122s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 122s libdebhelper-perl libdqlite-dev libdqlite0 libdw1 122s libfile-stripnondeterminism-perl libgcc-13-dev libgomp1 libisl23 libitm1 122s libmpc3 libnsl-dev libraft-dev libraft0 libsqlite3-dev libstdc++-13-dev 122s libsub-override-perl libtirpc-dev libtool libubsan1 libuv1t64 linux-libc-dev 122s m4 po-debconf rpcsvc-proto 122s 0 upgraded, 104 newly installed, 1 to remove and 0 not upgraded. 122s Need to get 117 MB/117 MB of archives. 122s After this operation, 526 MB of additional disk space will be used. 122s Get:1 /tmp/autopkgtest.WeEHai/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [904 B] 123s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libuv1t64 s390x 1.48.0-1.1 [101 kB] 123s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x gcc-13-base s390x 13.2.0-17ubuntu2 [47.4 kB] 123s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x m4 s390x 1.4.19-4 [255 kB] 123s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x autoconf all 2.71-3 [339 kB] 123s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x autotools-dev all 20220109.1 [44.9 kB] 123s Get:7 http://ftpmaster.internal/ubuntu noble/main s390x automake all 1:1.16.5-1.3ubuntu1 [558 kB] 123s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x autopoint all 0.21-14ubuntu1 [422 kB] 123s Get:9 http://ftpmaster.internal/ubuntu noble/main s390x libc-dev-bin s390x 2.39-0ubuntu2 [20.2 kB] 123s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x linux-libc-dev s390x 6.8.0-11.11 [1590 kB] 123s Get:11 http://ftpmaster.internal/ubuntu noble/main s390x libcrypt-dev s390x 1:4.4.36-4 [135 kB] 123s Get:12 http://ftpmaster.internal/ubuntu noble/main s390x libtirpc-dev s390x 1.3.4+ds-1build1 [225 kB] 123s Get:13 http://ftpmaster.internal/ubuntu noble/main s390x libnsl-dev s390x 1.3.0-3 [73.5 kB] 123s Get:14 http://ftpmaster.internal/ubuntu noble/main s390x rpcsvc-proto s390x 1.4.2-0ubuntu6 [64.7 kB] 123s Get:15 http://ftpmaster.internal/ubuntu noble/main s390x libc6-dev s390x 2.39-0ubuntu2 [1629 kB] 123s Get:16 http://ftpmaster.internal/ubuntu noble/main s390x libisl23 s390x 0.26-3 [722 kB] 123s Get:17 http://ftpmaster.internal/ubuntu noble/main s390x libmpc3 s390x 1.3.1-1 [54.9 kB] 123s Get:18 http://ftpmaster.internal/ubuntu noble/main s390x cpp-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [9929 kB] 124s Get:19 http://ftpmaster.internal/ubuntu noble/main s390x cpp-13 s390x 13.2.0-17ubuntu2 [1026 B] 124s Get:20 http://ftpmaster.internal/ubuntu noble/main s390x cpp-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [5308 B] 124s Get:21 http://ftpmaster.internal/ubuntu noble/main s390x cpp s390x 4:13.2.0-7ubuntu1 [22.4 kB] 124s Get:22 http://ftpmaster.internal/ubuntu noble/main s390x libcc1-0 s390x 14-20240303-1ubuntu1 [49.9 kB] 124s Get:23 http://ftpmaster.internal/ubuntu noble/main s390x libgomp1 s390x 14-20240303-1ubuntu1 [151 kB] 124s Get:24 http://ftpmaster.internal/ubuntu noble/main s390x libitm1 s390x 14-20240303-1ubuntu1 [31.1 kB] 124s Get:25 http://ftpmaster.internal/ubuntu noble/main s390x libatomic1 s390x 14-20240303-1ubuntu1 [9392 B] 124s Get:26 http://ftpmaster.internal/ubuntu noble/main s390x libasan8 s390x 14-20240303-1ubuntu1 [2998 kB] 124s Get:27 http://ftpmaster.internal/ubuntu noble/main s390x libubsan1 s390x 14-20240303-1ubuntu1 [1186 kB] 124s Get:28 http://ftpmaster.internal/ubuntu noble/main s390x libgcc-13-dev s390x 13.2.0-17ubuntu2 [1003 kB] 124s Get:29 http://ftpmaster.internal/ubuntu noble/main s390x gcc-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [19.1 MB] 124s Get:30 http://ftpmaster.internal/ubuntu noble/main s390x gcc-13 s390x 13.2.0-17ubuntu2 [467 kB] 124s Get:31 http://ftpmaster.internal/ubuntu noble/main s390x gcc-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [1208 B] 124s Get:32 http://ftpmaster.internal/ubuntu noble/main s390x gcc s390x 4:13.2.0-7ubuntu1 [5014 B] 124s Get:33 http://ftpmaster.internal/ubuntu noble/main s390x libstdc++-13-dev s390x 13.2.0-17ubuntu2 [2430 kB] 124s Get:34 http://ftpmaster.internal/ubuntu noble/main s390x g++-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [11.3 MB] 125s Get:35 http://ftpmaster.internal/ubuntu noble/main s390x g++-13 s390x 13.2.0-17ubuntu2 [14.4 kB] 125s Get:36 http://ftpmaster.internal/ubuntu noble/main s390x g++-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [956 B] 125s Get:37 http://ftpmaster.internal/ubuntu noble/main s390x g++ s390x 4:13.2.0-7ubuntu1 [1096 B] 125s Get:38 http://ftpmaster.internal/ubuntu noble/main s390x build-essential s390x 12.10ubuntu1 [4930 B] 125s Get:39 http://ftpmaster.internal/ubuntu noble/main s390x libdebhelper-perl all 13.14.1ubuntu1 [89.5 kB] 125s Get:40 http://ftpmaster.internal/ubuntu noble/main s390x libtool all 2.4.7-7 [166 kB] 125s Get:41 http://ftpmaster.internal/ubuntu noble/main s390x dh-autoreconf all 20 [16.1 kB] 125s Get:42 http://ftpmaster.internal/ubuntu noble/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 125s Get:43 http://ftpmaster.internal/ubuntu noble/main s390x libsub-override-perl all 0.10-1 [10.0 kB] 125s Get:44 http://ftpmaster.internal/ubuntu noble/main s390x libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 125s Get:45 http://ftpmaster.internal/ubuntu noble/main s390x dh-strip-nondeterminism all 1.13.1-1 [5362 B] 125s Get:46 http://ftpmaster.internal/ubuntu noble/main s390x libdw1 s390x 0.190-1 [282 kB] 125s Get:47 http://ftpmaster.internal/ubuntu noble/main s390x debugedit s390x 1:5.0-5 [47.5 kB] 125s Get:48 http://ftpmaster.internal/ubuntu noble/main s390x dwz s390x 0.15-1 [108 kB] 125s Get:49 http://ftpmaster.internal/ubuntu noble/main s390x gettext s390x 0.21-14ubuntu1 [917 kB] 125s Get:50 http://ftpmaster.internal/ubuntu noble/main s390x intltool-debian all 0.35.0+20060710.6 [23.2 kB] 125s Get:51 http://ftpmaster.internal/ubuntu noble/main s390x po-debconf all 1.0.21+nmu1 [233 kB] 125s Get:52 http://ftpmaster.internal/ubuntu noble/main s390x debhelper all 13.14.1ubuntu1 [869 kB] 125s Get:53 http://ftpmaster.internal/ubuntu noble/universe s390x libraft0 s390x 0.22.0-1 [101 kB] 125s Get:54 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x libdqlite0 s390x 1.16.0-5build1 [85.8 kB] 125s Get:55 http://ftpmaster.internal/ubuntu noble/universe s390x go-dqlite s390x 1.21.0-1 [4019 kB] 125s Get:56 http://ftpmaster.internal/ubuntu noble/main s390x golang-1.22-src all 1.22.1-1 [19.7 MB] 125s Get:57 http://ftpmaster.internal/ubuntu noble/main s390x golang-1.22-go s390x 1.22.1-1 [24.7 MB] 126s Get:58 http://ftpmaster.internal/ubuntu noble/main s390x golang-src all 2:1.22~2 [4992 B] 126s Get:59 http://ftpmaster.internal/ubuntu noble/main s390x golang-go s390x 2:1.22~2 [43.9 kB] 126s Get:60 http://ftpmaster.internal/ubuntu noble/universe s390x golang-any s390x 2:1.22~2 [2842 B] 126s Get:61 http://ftpmaster.internal/ubuntu noble/main s390x libsqlite3-dev s390x 3.45.1-1 [973 kB] 126s Get:62 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x libdqlite-dev s390x 1.16.0-5build1 [113 kB] 126s Get:63 http://ftpmaster.internal/ubuntu noble/universe s390x libraft-dev s390x 0.22.0-1 [154 kB] 126s Get:64 http://ftpmaster.internal/ubuntu noble/universe s390x golang-gopkg-yaml.v2-dev all 2.4.0-4 [62.6 kB] 126s Get:65 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-ghodss-yaml-dev all 1.0.0+git20220118.d8423dc-2 [16.6 kB] 126s Get:66 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-google-renameio-dev all 2.0.0-2 [10.3 kB] 126s Get:67 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-mattn-go-sqlite3-dev s390x 1.14.19~ds1-1 [85.5 kB] 126s Get:68 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-rivo-uniseg-dev all 0.4.4-1 [256 kB] 126s Get:69 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-mattn-go-runewidth-dev all 0.0.15-2 [17.2 kB] 126s Get:70 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-peterh-liner-dev all 1.2.2-1 [21.3 kB] 126s Get:71 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-pkg-errors-dev all 0.9.1-3 [13.2 kB] 126s Get:72 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-rican7-retry-dev all 0.3.1-2 [7740 B] 126s Get:73 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-pmezard-go-difflib-dev all 1.0.0-3 [12.4 kB] 126s Get:74 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-shurcool-sanitized-anchor-name-dev all 1.0.0-3 [3590 B] 126s Get:75 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-russross-blackfriday-v2-dev all 2.1.0-1 [79.6 kB] 126s Get:76 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-cpuguy83-go-md2man-v2-dev all 2.0.3+ds1-1 [10.4 kB] 126s Get:77 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-pflag-dev all 1.0.6~git20210604-d5e0c0615ace-1 [43.6 kB] 126s Get:78 http://ftpmaster.internal/ubuntu noble/universe s390x golang-golang-x-sys-dev all 0.17.0-1 [424 kB] 126s Get:79 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-fsnotify-fsnotify-dev all 1.7.0-1 [39.1 kB] 126s Get:80 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-davecgh-go-spew-dev all 1.1.1-3 [31.1 kB] 126s Get:81 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-hashicorp-hcl-dev all 1.0.0-1.1 [58.9 kB] 126s Get:82 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-magiconair-properties-dev all 1.8.7-1 [27.3 kB] 126s Get:83 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-mitchellh-mapstructure-dev all 1.5.0+git20231216.8508981-1 [28.3 kB] 126s Get:84 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-burntsushi-toml-dev all 1.3.2-2 [115 kB] 126s Get:85 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-pelletier-go-toml-dev all 1.9.5-1build1 [79.6 kB] 126s Get:86 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-pelletier-go-toml.v2-dev all 2.1.1-1 [876 kB] 126s Get:87 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-kr-fs-dev all 0.1.0-2 [6344 B] 126s Get:88 http://ftpmaster.internal/ubuntu noble/universe s390x golang-golang-x-term-dev all 0.17.0-1 [14.7 kB] 126s Get:89 http://ftpmaster.internal/ubuntu noble/universe s390x golang-golang-x-text-dev all 0.14.0-1 [4646 kB] 126s Get:90 http://ftpmaster.internal/ubuntu noble/universe s390x golang-golang-x-crypto-dev all 1:0.19.0-1 [1712 kB] 126s Get:91 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-pkg-sftp-dev all 1.13.6-1 [104 kB] 126s Get:92 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-afero-dev all 1.11.0-1 [59.6 kB] 126s Get:93 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-cast-dev all 1.6.0-1 [14.3 kB] 126s Get:94 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-stretchr-objx-dev all 0.5.0-1 [26.5 kB] 126s Get:95 http://ftpmaster.internal/ubuntu noble/universe s390x golang-gopkg-yaml.v3-dev all 3.0.1-3 [76.1 kB] 126s Get:96 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-stretchr-testify-dev all 1.8.4-1 [72.8 kB] 126s Get:97 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-jwalterweatherman-dev all 1.1.0+really1.1.0-2 [6828 B] 126s Get:98 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-subosito-gotenv-dev all 1.3.0-1 [8808 B] 126s Get:99 http://ftpmaster.internal/ubuntu noble/universe s390x golang-gopkg-ini.v1-dev all 1.67.0-1 [40.8 kB] 126s Get:100 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-viper-dev all 1.12.0-1 [71.0 kB] 126s Get:101 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-cobra-dev all 1.8.0-1 [74.5 kB] 126s Get:102 http://ftpmaster.internal/ubuntu noble/universe s390x golang-golang-x-sync-dev all 0.6.0-1 [18.9 kB] 127s Get:103 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-canonical-go-dqlite-dev all 1.21.0-1 [86.3 kB] 127s Get:104 http://ftpmaster.internal/ubuntu noble/main s390x dh-golang all 1.62 [25.2 kB] 128s Fetched 117 MB in 4s (26.9 MB/s) 128s dpkg: libuv1:s390x: dependency problems, but removing anyway as you requested: 128s bind9-libs:s390x depends on libuv1 (>= 1.40.0). 128s 128s (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 ... 51778 files and directories currently installed.) 128s Removing libuv1:s390x (1.48.0-1) ... 128s Selecting previously unselected package libuv1t64:s390x. 128s (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 ... 51773 files and directories currently installed.) 128s Preparing to unpack .../000-libuv1t64_1.48.0-1.1_s390x.deb ... 128s Unpacking libuv1t64:s390x (1.48.0-1.1) ... 128s Selecting previously unselected package gcc-13-base:s390x. 128s Preparing to unpack .../001-gcc-13-base_13.2.0-17ubuntu2_s390x.deb ... 128s Unpacking gcc-13-base:s390x (13.2.0-17ubuntu2) ... 128s Selecting previously unselected package m4. 128s Preparing to unpack .../002-m4_1.4.19-4_s390x.deb ... 128s Unpacking m4 (1.4.19-4) ... 128s Selecting previously unselected package autoconf. 128s Preparing to unpack .../003-autoconf_2.71-3_all.deb ... 128s Unpacking autoconf (2.71-3) ... 128s Selecting previously unselected package autotools-dev. 128s Preparing to unpack .../004-autotools-dev_20220109.1_all.deb ... 128s Unpacking autotools-dev (20220109.1) ... 128s Selecting previously unselected package automake. 128s Preparing to unpack .../005-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 128s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 128s Selecting previously unselected package autopoint. 128s Preparing to unpack .../006-autopoint_0.21-14ubuntu1_all.deb ... 128s Unpacking autopoint (0.21-14ubuntu1) ... 128s Selecting previously unselected package libc-dev-bin. 128s Preparing to unpack .../007-libc-dev-bin_2.39-0ubuntu2_s390x.deb ... 128s Unpacking libc-dev-bin (2.39-0ubuntu2) ... 128s Selecting previously unselected package linux-libc-dev:s390x. 128s Preparing to unpack .../008-linux-libc-dev_6.8.0-11.11_s390x.deb ... 128s Unpacking linux-libc-dev:s390x (6.8.0-11.11) ... 128s Selecting previously unselected package libcrypt-dev:s390x. 128s Preparing to unpack .../009-libcrypt-dev_1%3a4.4.36-4_s390x.deb ... 128s Unpacking libcrypt-dev:s390x (1:4.4.36-4) ... 128s Selecting previously unselected package libtirpc-dev:s390x. 128s Preparing to unpack .../010-libtirpc-dev_1.3.4+ds-1build1_s390x.deb ... 128s Unpacking libtirpc-dev:s390x (1.3.4+ds-1build1) ... 128s Selecting previously unselected package libnsl-dev:s390x. 128s Preparing to unpack .../011-libnsl-dev_1.3.0-3_s390x.deb ... 128s Unpacking libnsl-dev:s390x (1.3.0-3) ... 128s Selecting previously unselected package rpcsvc-proto. 128s Preparing to unpack .../012-rpcsvc-proto_1.4.2-0ubuntu6_s390x.deb ... 128s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 128s Selecting previously unselected package libc6-dev:s390x. 128s Preparing to unpack .../013-libc6-dev_2.39-0ubuntu2_s390x.deb ... 128s Unpacking libc6-dev:s390x (2.39-0ubuntu2) ... 128s Selecting previously unselected package libisl23:s390x. 128s Preparing to unpack .../014-libisl23_0.26-3_s390x.deb ... 128s Unpacking libisl23:s390x (0.26-3) ... 128s Selecting previously unselected package libmpc3:s390x. 128s Preparing to unpack .../015-libmpc3_1.3.1-1_s390x.deb ... 128s Unpacking libmpc3:s390x (1.3.1-1) ... 128s Selecting previously unselected package cpp-13-s390x-linux-gnu. 128s Preparing to unpack .../016-cpp-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 128s Unpacking cpp-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 128s Selecting previously unselected package cpp-13. 128s Preparing to unpack .../017-cpp-13_13.2.0-17ubuntu2_s390x.deb ... 128s Unpacking cpp-13 (13.2.0-17ubuntu2) ... 128s Selecting previously unselected package cpp-s390x-linux-gnu. 128s Preparing to unpack .../018-cpp-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 128s Unpacking cpp-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 128s Selecting previously unselected package cpp. 128s Preparing to unpack .../019-cpp_4%3a13.2.0-7ubuntu1_s390x.deb ... 128s Unpacking cpp (4:13.2.0-7ubuntu1) ... 128s Selecting previously unselected package libcc1-0:s390x. 128s Preparing to unpack .../020-libcc1-0_14-20240303-1ubuntu1_s390x.deb ... 128s Unpacking libcc1-0:s390x (14-20240303-1ubuntu1) ... 128s Selecting previously unselected package libgomp1:s390x. 128s Preparing to unpack .../021-libgomp1_14-20240303-1ubuntu1_s390x.deb ... 128s Unpacking libgomp1:s390x (14-20240303-1ubuntu1) ... 128s Selecting previously unselected package libitm1:s390x. 128s Preparing to unpack .../022-libitm1_14-20240303-1ubuntu1_s390x.deb ... 128s Unpacking libitm1:s390x (14-20240303-1ubuntu1) ... 128s Selecting previously unselected package libatomic1:s390x. 128s Preparing to unpack .../023-libatomic1_14-20240303-1ubuntu1_s390x.deb ... 128s Unpacking libatomic1:s390x (14-20240303-1ubuntu1) ... 128s Selecting previously unselected package libasan8:s390x. 128s Preparing to unpack .../024-libasan8_14-20240303-1ubuntu1_s390x.deb ... 128s Unpacking libasan8:s390x (14-20240303-1ubuntu1) ... 129s Selecting previously unselected package libubsan1:s390x. 129s Preparing to unpack .../025-libubsan1_14-20240303-1ubuntu1_s390x.deb ... 129s Unpacking libubsan1:s390x (14-20240303-1ubuntu1) ... 129s Selecting previously unselected package libgcc-13-dev:s390x. 129s Preparing to unpack .../026-libgcc-13-dev_13.2.0-17ubuntu2_s390x.deb ... 129s Unpacking libgcc-13-dev:s390x (13.2.0-17ubuntu2) ... 129s Selecting previously unselected package gcc-13-s390x-linux-gnu. 129s Preparing to unpack .../027-gcc-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 129s Unpacking gcc-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 129s Selecting previously unselected package gcc-13. 129s Preparing to unpack .../028-gcc-13_13.2.0-17ubuntu2_s390x.deb ... 129s Unpacking gcc-13 (13.2.0-17ubuntu2) ... 129s Selecting previously unselected package gcc-s390x-linux-gnu. 129s Preparing to unpack .../029-gcc-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 129s Unpacking gcc-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 129s Selecting previously unselected package gcc. 129s Preparing to unpack .../030-gcc_4%3a13.2.0-7ubuntu1_s390x.deb ... 129s Unpacking gcc (4:13.2.0-7ubuntu1) ... 129s Selecting previously unselected package libstdc++-13-dev:s390x. 129s Preparing to unpack .../031-libstdc++-13-dev_13.2.0-17ubuntu2_s390x.deb ... 129s Unpacking libstdc++-13-dev:s390x (13.2.0-17ubuntu2) ... 129s Selecting previously unselected package g++-13-s390x-linux-gnu. 129s Preparing to unpack .../032-g++-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 129s Unpacking g++-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 130s Selecting previously unselected package g++-13. 130s Preparing to unpack .../033-g++-13_13.2.0-17ubuntu2_s390x.deb ... 130s Unpacking g++-13 (13.2.0-17ubuntu2) ... 130s Selecting previously unselected package g++-s390x-linux-gnu. 130s Preparing to unpack .../034-g++-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 130s Unpacking g++-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 130s Selecting previously unselected package g++. 130s Preparing to unpack .../035-g++_4%3a13.2.0-7ubuntu1_s390x.deb ... 130s Unpacking g++ (4:13.2.0-7ubuntu1) ... 130s Selecting previously unselected package build-essential. 130s Preparing to unpack .../036-build-essential_12.10ubuntu1_s390x.deb ... 130s Unpacking build-essential (12.10ubuntu1) ... 130s Selecting previously unselected package libdebhelper-perl. 130s Preparing to unpack .../037-libdebhelper-perl_13.14.1ubuntu1_all.deb ... 130s Unpacking libdebhelper-perl (13.14.1ubuntu1) ... 130s Selecting previously unselected package libtool. 130s Preparing to unpack .../038-libtool_2.4.7-7_all.deb ... 130s Unpacking libtool (2.4.7-7) ... 130s Selecting previously unselected package dh-autoreconf. 130s Preparing to unpack .../039-dh-autoreconf_20_all.deb ... 130s Unpacking dh-autoreconf (20) ... 130s Selecting previously unselected package libarchive-zip-perl. 130s Preparing to unpack .../040-libarchive-zip-perl_1.68-1_all.deb ... 130s Unpacking libarchive-zip-perl (1.68-1) ... 130s Selecting previously unselected package libsub-override-perl. 130s Preparing to unpack .../041-libsub-override-perl_0.10-1_all.deb ... 130s Unpacking libsub-override-perl (0.10-1) ... 130s Selecting previously unselected package libfile-stripnondeterminism-perl. 130s Preparing to unpack .../042-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 130s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 130s Selecting previously unselected package dh-strip-nondeterminism. 130s Preparing to unpack .../043-dh-strip-nondeterminism_1.13.1-1_all.deb ... 130s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 130s Selecting previously unselected package libdw1:s390x. 130s Preparing to unpack .../044-libdw1_0.190-1_s390x.deb ... 130s Unpacking libdw1:s390x (0.190-1) ... 130s Selecting previously unselected package debugedit. 130s Preparing to unpack .../045-debugedit_1%3a5.0-5_s390x.deb ... 130s Unpacking debugedit (1:5.0-5) ... 130s Selecting previously unselected package dwz. 130s Preparing to unpack .../046-dwz_0.15-1_s390x.deb ... 130s Unpacking dwz (0.15-1) ... 130s Selecting previously unselected package gettext. 130s Preparing to unpack .../047-gettext_0.21-14ubuntu1_s390x.deb ... 130s Unpacking gettext (0.21-14ubuntu1) ... 130s Selecting previously unselected package intltool-debian. 130s Preparing to unpack .../048-intltool-debian_0.35.0+20060710.6_all.deb ... 130s Unpacking intltool-debian (0.35.0+20060710.6) ... 130s Selecting previously unselected package po-debconf. 130s Preparing to unpack .../049-po-debconf_1.0.21+nmu1_all.deb ... 130s Unpacking po-debconf (1.0.21+nmu1) ... 130s Selecting previously unselected package debhelper. 130s Preparing to unpack .../050-debhelper_13.14.1ubuntu1_all.deb ... 130s Unpacking debhelper (13.14.1ubuntu1) ... 130s Selecting previously unselected package libraft0:s390x. 130s Preparing to unpack .../051-libraft0_0.22.0-1_s390x.deb ... 130s Unpacking libraft0:s390x (0.22.0-1) ... 130s Selecting previously unselected package libdqlite0:s390x. 130s Preparing to unpack .../052-libdqlite0_1.16.0-5build1_s390x.deb ... 130s Unpacking libdqlite0:s390x (1.16.0-5build1) ... 130s Selecting previously unselected package go-dqlite. 130s Preparing to unpack .../053-go-dqlite_1.21.0-1_s390x.deb ... 130s Unpacking go-dqlite (1.21.0-1) ... 130s Selecting previously unselected package golang-1.22-src. 130s Preparing to unpack .../054-golang-1.22-src_1.22.1-1_all.deb ... 130s Unpacking golang-1.22-src (1.22.1-1) ... 132s Selecting previously unselected package golang-1.22-go. 132s Preparing to unpack .../055-golang-1.22-go_1.22.1-1_s390x.deb ... 132s Unpacking golang-1.22-go (1.22.1-1) ... 133s Selecting previously unselected package golang-src. 133s Preparing to unpack .../056-golang-src_2%3a1.22~2_all.deb ... 133s Unpacking golang-src (2:1.22~2) ... 133s Selecting previously unselected package golang-go:s390x. 133s Preparing to unpack .../057-golang-go_2%3a1.22~2_s390x.deb ... 133s Unpacking golang-go:s390x (2:1.22~2) ... 133s Selecting previously unselected package golang-any:s390x. 133s Preparing to unpack .../058-golang-any_2%3a1.22~2_s390x.deb ... 133s Unpacking golang-any:s390x (2:1.22~2) ... 133s Selecting previously unselected package libsqlite3-dev:s390x. 133s Preparing to unpack .../059-libsqlite3-dev_3.45.1-1_s390x.deb ... 133s Unpacking libsqlite3-dev:s390x (3.45.1-1) ... 133s Selecting previously unselected package libdqlite-dev:s390x. 133s Preparing to unpack .../060-libdqlite-dev_1.16.0-5build1_s390x.deb ... 133s Unpacking libdqlite-dev:s390x (1.16.0-5build1) ... 133s Selecting previously unselected package libraft-dev:s390x. 133s Preparing to unpack .../061-libraft-dev_0.22.0-1_s390x.deb ... 133s Unpacking libraft-dev:s390x (0.22.0-1) ... 133s Selecting previously unselected package golang-gopkg-yaml.v2-dev. 133s Preparing to unpack .../062-golang-gopkg-yaml.v2-dev_2.4.0-4_all.deb ... 133s Unpacking golang-gopkg-yaml.v2-dev (2.4.0-4) ... 133s Selecting previously unselected package golang-github-ghodss-yaml-dev. 133s Preparing to unpack .../063-golang-github-ghodss-yaml-dev_1.0.0+git20220118.d8423dc-2_all.deb ... 133s Unpacking golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ... 133s Selecting previously unselected package golang-github-google-renameio-dev. 133s Preparing to unpack .../064-golang-github-google-renameio-dev_2.0.0-2_all.deb ... 133s Unpacking golang-github-google-renameio-dev (2.0.0-2) ... 133s Selecting previously unselected package golang-github-mattn-go-sqlite3-dev:s390x. 133s Preparing to unpack .../065-golang-github-mattn-go-sqlite3-dev_1.14.19~ds1-1_s390x.deb ... 133s Unpacking golang-github-mattn-go-sqlite3-dev:s390x (1.14.19~ds1-1) ... 133s Selecting previously unselected package golang-github-rivo-uniseg-dev. 133s Preparing to unpack .../066-golang-github-rivo-uniseg-dev_0.4.4-1_all.deb ... 133s Unpacking golang-github-rivo-uniseg-dev (0.4.4-1) ... 133s Selecting previously unselected package golang-github-mattn-go-runewidth-dev. 133s Preparing to unpack .../067-golang-github-mattn-go-runewidth-dev_0.0.15-2_all.deb ... 133s Unpacking golang-github-mattn-go-runewidth-dev (0.0.15-2) ... 133s Selecting previously unselected package golang-github-peterh-liner-dev. 133s Preparing to unpack .../068-golang-github-peterh-liner-dev_1.2.2-1_all.deb ... 133s Unpacking golang-github-peterh-liner-dev (1.2.2-1) ... 133s Selecting previously unselected package golang-github-pkg-errors-dev. 133s Preparing to unpack .../069-golang-github-pkg-errors-dev_0.9.1-3_all.deb ... 133s Unpacking golang-github-pkg-errors-dev (0.9.1-3) ... 133s Selecting previously unselected package golang-github-rican7-retry-dev. 133s Preparing to unpack .../070-golang-github-rican7-retry-dev_0.3.1-2_all.deb ... 133s Unpacking golang-github-rican7-retry-dev (0.3.1-2) ... 133s Selecting previously unselected package golang-github-pmezard-go-difflib-dev. 133s Preparing to unpack .../071-golang-github-pmezard-go-difflib-dev_1.0.0-3_all.deb ... 133s Unpacking golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 133s Selecting previously unselected package golang-github-shurcool-sanitized-anchor-name-dev. 133s Preparing to unpack .../072-golang-github-shurcool-sanitized-anchor-name-dev_1.0.0-3_all.deb ... 133s Unpacking golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 133s Selecting previously unselected package golang-github-russross-blackfriday-v2-dev. 133s Preparing to unpack .../073-golang-github-russross-blackfriday-v2-dev_2.1.0-1_all.deb ... 133s Unpacking golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 133s Selecting previously unselected package golang-github-cpuguy83-go-md2man-v2-dev. 133s Preparing to unpack .../074-golang-github-cpuguy83-go-md2man-v2-dev_2.0.3+ds1-1_all.deb ... 133s Unpacking golang-github-cpuguy83-go-md2man-v2-dev (2.0.3+ds1-1) ... 133s Selecting previously unselected package golang-github-spf13-pflag-dev. 133s Preparing to unpack .../075-golang-github-spf13-pflag-dev_1.0.6~git20210604-d5e0c0615ace-1_all.deb ... 133s Unpacking golang-github-spf13-pflag-dev (1.0.6~git20210604-d5e0c0615ace-1) ... 133s Selecting previously unselected package golang-golang-x-sys-dev. 133s Preparing to unpack .../076-golang-golang-x-sys-dev_0.17.0-1_all.deb ... 133s Unpacking golang-golang-x-sys-dev (0.17.0-1) ... 133s Selecting previously unselected package golang-github-fsnotify-fsnotify-dev. 133s Preparing to unpack .../077-golang-github-fsnotify-fsnotify-dev_1.7.0-1_all.deb ... 133s Unpacking golang-github-fsnotify-fsnotify-dev (1.7.0-1) ... 133s Selecting previously unselected package golang-github-davecgh-go-spew-dev. 133s Preparing to unpack .../078-golang-github-davecgh-go-spew-dev_1.1.1-3_all.deb ... 133s Unpacking golang-github-davecgh-go-spew-dev (1.1.1-3) ... 133s Selecting previously unselected package golang-github-hashicorp-hcl-dev. 133s Preparing to unpack .../079-golang-github-hashicorp-hcl-dev_1.0.0-1.1_all.deb ... 133s Unpacking golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 133s Selecting previously unselected package golang-github-magiconair-properties-dev. 133s Preparing to unpack .../080-golang-github-magiconair-properties-dev_1.8.7-1_all.deb ... 133s Unpacking golang-github-magiconair-properties-dev (1.8.7-1) ... 133s Selecting previously unselected package golang-github-mitchellh-mapstructure-dev. 133s Preparing to unpack .../081-golang-github-mitchellh-mapstructure-dev_1.5.0+git20231216.8508981-1_all.deb ... 133s Unpacking golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 133s Selecting previously unselected package golang-github-burntsushi-toml-dev. 133s Preparing to unpack .../082-golang-github-burntsushi-toml-dev_1.3.2-2_all.deb ... 133s Unpacking golang-github-burntsushi-toml-dev (1.3.2-2) ... 134s Selecting previously unselected package golang-github-pelletier-go-toml-dev. 134s Preparing to unpack .../083-golang-github-pelletier-go-toml-dev_1.9.5-1build1_all.deb ... 134s Unpacking golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 134s Selecting previously unselected package golang-github-pelletier-go-toml.v2-dev. 134s Preparing to unpack .../084-golang-github-pelletier-go-toml.v2-dev_2.1.1-1_all.deb ... 134s Unpacking golang-github-pelletier-go-toml.v2-dev (2.1.1-1) ... 134s Selecting previously unselected package golang-github-kr-fs-dev. 134s Preparing to unpack .../085-golang-github-kr-fs-dev_0.1.0-2_all.deb ... 134s Unpacking golang-github-kr-fs-dev (0.1.0-2) ... 134s Selecting previously unselected package golang-golang-x-term-dev. 134s Preparing to unpack .../086-golang-golang-x-term-dev_0.17.0-1_all.deb ... 134s Unpacking golang-golang-x-term-dev (0.17.0-1) ... 134s Selecting previously unselected package golang-golang-x-text-dev. 134s Preparing to unpack .../087-golang-golang-x-text-dev_0.14.0-1_all.deb ... 134s Unpacking golang-golang-x-text-dev (0.14.0-1) ... 134s Selecting previously unselected package golang-golang-x-crypto-dev. 134s Preparing to unpack .../088-golang-golang-x-crypto-dev_1%3a0.19.0-1_all.deb ... 134s Unpacking golang-golang-x-crypto-dev (1:0.19.0-1) ... 134s Selecting previously unselected package golang-github-pkg-sftp-dev. 134s Preparing to unpack .../089-golang-github-pkg-sftp-dev_1.13.6-1_all.deb ... 134s Unpacking golang-github-pkg-sftp-dev (1.13.6-1) ... 134s Selecting previously unselected package golang-github-spf13-afero-dev. 134s Preparing to unpack .../090-golang-github-spf13-afero-dev_1.11.0-1_all.deb ... 134s Unpacking golang-github-spf13-afero-dev (1.11.0-1) ... 134s Selecting previously unselected package golang-github-spf13-cast-dev. 134s Preparing to unpack .../091-golang-github-spf13-cast-dev_1.6.0-1_all.deb ... 134s Unpacking golang-github-spf13-cast-dev (1.6.0-1) ... 134s Selecting previously unselected package golang-github-stretchr-objx-dev. 134s Preparing to unpack .../092-golang-github-stretchr-objx-dev_0.5.0-1_all.deb ... 134s Unpacking golang-github-stretchr-objx-dev (0.5.0-1) ... 134s Selecting previously unselected package golang-gopkg-yaml.v3-dev. 134s Preparing to unpack .../093-golang-gopkg-yaml.v3-dev_3.0.1-3_all.deb ... 134s Unpacking golang-gopkg-yaml.v3-dev (3.0.1-3) ... 134s Selecting previously unselected package golang-github-stretchr-testify-dev. 134s Preparing to unpack .../094-golang-github-stretchr-testify-dev_1.8.4-1_all.deb ... 134s Unpacking golang-github-stretchr-testify-dev (1.8.4-1) ... 134s Selecting previously unselected package golang-github-spf13-jwalterweatherman-dev. 134s Preparing to unpack .../095-golang-github-spf13-jwalterweatherman-dev_1.1.0+really1.1.0-2_all.deb ... 134s Unpacking golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 134s Selecting previously unselected package golang-github-subosito-gotenv-dev. 134s Preparing to unpack .../096-golang-github-subosito-gotenv-dev_1.3.0-1_all.deb ... 134s Unpacking golang-github-subosito-gotenv-dev (1.3.0-1) ... 134s Selecting previously unselected package golang-gopkg-ini.v1-dev. 134s Preparing to unpack .../097-golang-gopkg-ini.v1-dev_1.67.0-1_all.deb ... 134s Unpacking golang-gopkg-ini.v1-dev (1.67.0-1) ... 134s Selecting previously unselected package golang-github-spf13-viper-dev. 134s Preparing to unpack .../098-golang-github-spf13-viper-dev_1.12.0-1_all.deb ... 134s Unpacking golang-github-spf13-viper-dev (1.12.0-1) ... 134s Selecting previously unselected package golang-github-spf13-cobra-dev. 134s Preparing to unpack .../099-golang-github-spf13-cobra-dev_1.8.0-1_all.deb ... 134s Unpacking golang-github-spf13-cobra-dev (1.8.0-1) ... 134s Selecting previously unselected package golang-golang-x-sync-dev. 134s Preparing to unpack .../100-golang-golang-x-sync-dev_0.6.0-1_all.deb ... 134s Unpacking golang-golang-x-sync-dev (0.6.0-1) ... 134s Selecting previously unselected package golang-github-canonical-go-dqlite-dev. 134s Preparing to unpack .../101-golang-github-canonical-go-dqlite-dev_1.21.0-1_all.deb ... 134s Unpacking golang-github-canonical-go-dqlite-dev (1.21.0-1) ... 134s Selecting previously unselected package dh-golang. 134s Preparing to unpack .../102-dh-golang_1.62_all.deb ... 134s Unpacking dh-golang (1.62) ... 134s Selecting previously unselected package autopkgtest-satdep. 134s Preparing to unpack .../103-1-autopkgtest-satdep.deb ... 134s Unpacking autopkgtest-satdep (0) ... 134s Setting up golang-github-pelletier-go-toml.v2-dev (2.1.1-1) ... 134s Setting up golang-gopkg-yaml.v2-dev (2.4.0-4) ... 134s Setting up libdw1:s390x (0.190-1) ... 134s Setting up golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 134s Setting up golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 134s Setting up golang-github-stretchr-objx-dev (0.5.0-1) ... 134s Setting up golang-github-pkg-errors-dev (0.9.1-3) ... 134s Setting up libarchive-zip-perl (1.68-1) ... 134s Setting up golang-gopkg-yaml.v3-dev (3.0.1-3) ... 134s Setting up libdebhelper-perl (13.14.1ubuntu1) ... 134s Setting up golang-github-burntsushi-toml-dev (1.3.2-2) ... 134s Setting up golang-golang-x-sys-dev (0.17.0-1) ... 134s Setting up golang-github-kr-fs-dev (0.1.0-2) ... 134s Setting up libuv1t64:s390x (1.48.0-1.1) ... 134s Setting up golang-github-subosito-gotenv-dev (1.3.0-1) ... 134s Setting up linux-libc-dev:s390x (6.8.0-11.11) ... 134s Setting up golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 134s Setting up m4 (1.4.19-4) ... 134s Setting up golang-golang-x-term-dev (0.17.0-1) ... 134s Setting up libgomp1:s390x (14-20240303-1ubuntu1) ... 134s Setting up golang-github-google-renameio-dev (2.0.0-2) ... 134s Setting up golang-github-spf13-pflag-dev (1.0.6~git20210604-d5e0c0615ace-1) ... 134s Setting up golang-github-davecgh-go-spew-dev (1.1.1-3) ... 134s Setting up autotools-dev (20220109.1) ... 134s Setting up libtirpc-dev:s390x (1.3.4+ds-1build1) ... 134s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 134s Setting up gcc-13-base:s390x (13.2.0-17ubuntu2) ... 134s Setting up libraft0:s390x (0.22.0-1) ... 134s Setting up golang-github-stretchr-testify-dev (1.8.4-1) ... 134s Setting up golang-1.22-src (1.22.1-1) ... 134s Setting up libraft-dev:s390x (0.22.0-1) ... 134s Setting up golang-gopkg-ini.v1-dev (1.67.0-1) ... 134s Setting up libmpc3:s390x (1.3.1-1) ... 134s Setting up libatomic1:s390x (14-20240303-1ubuntu1) ... 134s Setting up golang-golang-x-sync-dev (0.6.0-1) ... 134s Setting up autopoint (0.21-14ubuntu1) ... 134s Setting up golang-github-magiconair-properties-dev (1.8.7-1) ... 134s Setting up golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 134s Setting up golang-github-rican7-retry-dev (0.3.1-2) ... 134s Setting up autoconf (2.71-3) ... 134s Setting up golang-github-rivo-uniseg-dev (0.4.4-1) ... 134s Setting up libubsan1:s390x (14-20240303-1ubuntu1) ... 134s Setting up dwz (0.15-1) ... 134s Setting up libnsl-dev:s390x (1.3.0-3) ... 134s Setting up golang-golang-x-text-dev (0.14.0-1) ... 134s Setting up libcrypt-dev:s390x (1:4.4.36-4) ... 134s Setting up libasan8:s390x (14-20240303-1ubuntu1) ... 134s Setting up golang-github-spf13-cast-dev (1.6.0-1) ... 134s Setting up golang-github-fsnotify-fsnotify-dev (1.7.0-1) ... 134s Setting up debugedit (1:5.0-5) ... 134s Setting up libsub-override-perl (0.10-1) ... 134s Setting up golang-golang-x-crypto-dev (1:0.19.0-1) ... 134s Setting up golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 134s Setting up golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 134s Setting up libdqlite0:s390x (1.16.0-5build1) ... 134s Setting up libisl23:s390x (0.26-3) ... 134s Setting up golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ... 134s Setting up libc-dev-bin (2.39-0ubuntu2) ... 134s Setting up golang-src (2:1.22~2) ... 134s Setting up libcc1-0:s390x (14-20240303-1ubuntu1) ... 134s Setting up libitm1:s390x (14-20240303-1ubuntu1) ... 134s Setting up automake (1:1.16.5-1.3ubuntu1) ... 134s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 134s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 134s Setting up golang-github-mattn-go-runewidth-dev (0.0.15-2) ... 134s Setting up gettext (0.21-14ubuntu1) ... 134s Setting up golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 134s Setting up golang-github-pkg-sftp-dev (1.13.6-1) ... 134s Setting up golang-1.22-go (1.22.1-1) ... 134s Setting up intltool-debian (0.35.0+20060710.6) ... 134s Setting up golang-github-peterh-liner-dev (1.2.2-1) ... 134s Setting up go-dqlite (1.21.0-1) ... 134s Setting up cpp-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 134s Setting up dh-strip-nondeterminism (1.13.1-1) ... 134s Setting up libgcc-13-dev:s390x (13.2.0-17ubuntu2) ... 134s Setting up libc6-dev:s390x (2.39-0ubuntu2) ... 134s Setting up golang-github-spf13-afero-dev (1.11.0-1) ... 134s Setting up libstdc++-13-dev:s390x (13.2.0-17ubuntu2) ... 134s Setting up golang-github-cpuguy83-go-md2man-v2-dev (2.0.3+ds1-1) ... 134s Setting up cpp-13 (13.2.0-17ubuntu2) ... 134s Setting up cpp-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 134s Setting up golang-go:s390x (2:1.22~2) ... 134s Setting up po-debconf (1.0.21+nmu1) ... 134s Setting up golang-any:s390x (2:1.22~2) ... 134s Setting up gcc-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 134s Setting up gcc-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 134s Setting up libsqlite3-dev:s390x (3.45.1-1) ... 134s Setting up g++-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 134s Setting up gcc-13 (13.2.0-17ubuntu2) ... 134s Setting up golang-github-spf13-viper-dev (1.12.0-1) ... 134s Setting up cpp (4:13.2.0-7ubuntu1) ... 134s Setting up libdqlite-dev:s390x (1.16.0-5build1) ... 134s Setting up g++-13 (13.2.0-17ubuntu2) ... 134s Setting up libtool (2.4.7-7) ... 134s Setting up g++-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 134s Setting up gcc (4:13.2.0-7ubuntu1) ... 134s Setting up dh-autoreconf (20) ... 134s Setting up golang-github-mattn-go-sqlite3-dev:s390x (1.14.19~ds1-1) ... 134s Setting up golang-github-spf13-cobra-dev (1.8.0-1) ... 134s Setting up g++ (4:13.2.0-7ubuntu1) ... 134s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 134s Setting up build-essential (12.10ubuntu1) ... 134s Setting up debhelper (13.14.1ubuntu1) ... 134s Setting up golang-github-canonical-go-dqlite-dev (1.21.0-1) ... 134s Setting up dh-golang (1.62) ... 134s Setting up autopkgtest-satdep (0) ... 134s Processing triggers for man-db (2.12.0-3) ... 135s Processing triggers for install-info (7.1-3) ... 135s Processing triggers for libc-bin (2.39-0ubuntu2) ... 140s (Reading database ... 73754 files and directories currently installed.) 140s Removing autopkgtest-satdep (0) ... 140s autopkgtest [09:49:30]: test dh-golang-autopkgtest: /usr/bin/dh_golang_autopkgtest 140s autopkgtest [09:49:30]: test dh-golang-autopkgtest: [----------------------- 141s [info] Testing github.com/canonical/go-dqlite... 141s [info] Source code installed by binary package, overriding dh_auto_configure... 141s dh build --builddirectory=_build --buildsystem=golang --with=golang 141s dh_update_autotools_config -O--builddirectory=_build -O--buildsystem=golang 141s dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang 141s debian/rules override_dh_auto_configure 141s make[1]: Entering directory '/tmp/autopkgtest.WeEHai/autopkgtest_tmp' 141s mkdir -p "_build" 141s # remove existing symlinks, since "cp -a" won't convert them 141s find "_build" -type l -exec rm "{}" \; 141s cp -a /usr/share/gocode/src "_build" 141s make[1]: Leaving directory '/tmp/autopkgtest.WeEHai/autopkgtest_tmp' 141s dh_auto_build -O--builddirectory=_build -O--buildsystem=golang 142s cd _build && go install -trimpath -v -p 1 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 142s internal/goarch 142s internal/abi 142s internal/unsafeheader 142s internal/cpu 142s internal/bytealg 142s internal/chacha8rand 142s internal/coverage/rtcov 142s internal/godebugs 142s internal/goexperiment 142s internal/goos 142s runtime/internal/atomic 142s runtime/internal/math 142s runtime/internal/sys 142s runtime/internal/syscall 142s runtime 147s internal/reflectlite 148s errors 148s internal/race 148s sync/atomic 148s sync 148s internal/itoa 148s internal/oserror 148s syscall 148s time 149s context 149s math/bits 149s math 149s unicode/utf8 149s strconv 150s unicode 150s reflect 151s cmp 151s slices 151s sort 151s internal/fmtsort 151s io 151s internal/syscall/unix 151s internal/poll 151s internal/safefilepath 151s internal/syscall/execenv 152s internal/testlog 152s path 152s io/fs 152s os 152s fmt 153s bytes 153s container/list 153s hash 153s crypto 153s crypto/internal/alias 153s crypto/subtle 153s encoding/binary 153s crypto/cipher 153s crypto/internal/boring/sig 153s crypto/internal/boring 153s crypto/aes 153s crypto/des 153s crypto/internal/edwards25519/field 153s crypto/internal/nistec/fiat 154s embed 154s crypto/internal/nistec 154s crypto/internal/randutil 154s crypto/ecdh 154s internal/bisect 154s internal/godebug 154s math/rand 155s strings 155s math/big 156s crypto/elliptic 156s crypto/internal/bigmod 156s crypto/internal/boring/bbig 156s crypto/sha512 156s unicode/utf16 156s encoding/asn1 156s vendor/golang.org/x/crypto/cryptobyte/asn1 156s vendor/golang.org/x/crypto/cryptobyte 156s crypto/ecdsa 156s crypto/internal/edwards25519 157s crypto/rand 157s crypto/ed25519 157s crypto/hmac 157s crypto/md5 157s crypto/rc4 157s crypto/rsa 157s crypto/sha1 157s crypto/sha256 157s crypto/dsa 157s encoding/hex 157s crypto/x509/pkix 157s encoding/base64 157s encoding/pem 157s vendor/golang.org/x/net/dns/dnsmessage 157s internal/nettrace 157s internal/singleflight 158s internal/intern 158s net/netip 158s runtime/cgo 158s net 161s net/url 161s path/filepath 161s crypto/x509 162s vendor/golang.org/x/crypto/internal/alias 162s vendor/golang.org/x/sys/cpu 162s vendor/golang.org/x/crypto/chacha20 162s vendor/golang.org/x/crypto/internal/poly1305 162s vendor/golang.org/x/crypto/chacha20poly1305 162s vendor/golang.org/x/crypto/hkdf 162s crypto/tls 164s database/sql/driver 164s database/sql 165s github.com/Rican7/retry/backoff 165s github.com/Rican7/retry/jitter 165s github.com/Rican7/retry/strategy 165s github.com/Rican7/retry 165s encoding 165s flag 165s bufio 165s internal/sysinfo 165s runtime/debug 165s runtime/trace 165s testing 165s github.com/canonical/go-dqlite/logging 165s github.com/pkg/errors 166s github.com/canonical/go-dqlite/internal/protocol 166s io/ioutil 166s github.com/google/renameio 166s github.com/mattn/go-sqlite3 169s regexp/syntax 170s regexp 170s gopkg.in/yaml.v2 171s github.com/canonical/go-dqlite/client 171s github.com/canonical/go-dqlite/internal/bindings 173s github.com/canonical/go-dqlite 173s github.com/canonical/go-dqlite/tracing 173s github.com/canonical/go-dqlite/driver 173s golang.org/x/sync/semaphore 173s golang.org/x/sys/unix 174s log/internal 174s log 174s github.com/canonical/go-dqlite/app 174s github.com/canonical/go-dqlite/benchmark 174s encoding/json 175s github.com/canonical/go-dqlite/internal/shell 175s container/ring 175s github.com/rivo/uniseg 175s github.com/mattn/go-runewidth 175s os/signal 176s github.com/peterh/liner 176s encoding/csv 176s github.com/spf13/pflag 177s text/template/parse 177s text/template 178s github.com/spf13/cobra 178s github.com/canonical/go-dqlite/cmd/dqlite 180s github.com/canonical/go-dqlite/cmd/dqlite-benchmark 181s compress/flate 181s hash/crc32 181s compress/gzip 181s vendor/golang.org/x/text/transform 181s vendor/golang.org/x/text/unicode/bidi 182s vendor/golang.org/x/text/secure/bidirule 182s vendor/golang.org/x/text/unicode/norm 182s vendor/golang.org/x/net/idna 182s net/textproto 183s vendor/golang.org/x/net/http/httpguts 183s vendor/golang.org/x/net/http/httpproxy 183s vendor/golang.org/x/net/http2/hpack 183s mime 183s mime/quotedprintable 183s mime/multipart 183s net/http/httptrace 183s net/http/internal 183s net/http/internal/ascii 183s net/http 187s github.com/canonical/go-dqlite/cmd/dqlite-demo 189s create-stamp debian/debhelper-build-stamp 189s dh_auto_test -O--builddirectory=_build -O--buildsystem=golang 189s cd _build && go test -vet=off -v -p 1 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 220s ? github.com/canonical/go-dqlite [no test files] 233s === RUN TestNew_PristineDefault 233s --- PASS: TestNew_PristineDefault (0.08s) 233s === RUN TestNew_PristineJoiner 233s app_test.go:1202: 09:51:03.654 - 2: DEBUG: new connection from 127.0.0.1:33212 233s app_test.go:1202: 09:51:03.656 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected 233s app_test.go:1202: 09:51:03.658 - 2: DEBUG: new connection from 127.0.0.1:33218 233s app_test.go:1202: 09:51:03.723 - 2: DEBUG: new connection from 127.0.0.1:33222 233s app_test.go:1202: 09:51:03.725 - 3: DEBUG: attempt 1: server 127.0.0.1:9001: connected 233s app_test.go:1202: 09:51:03.728 - 2: DEBUG: new connection from 127.0.0.1:33232 233s app_test.go:1202: 09:51:03.730 - 3: DEBUG: new connection from 127.0.0.1:59074 233s app_test.go:1202: 09:51:03.732 - 2: DEBUG: new connection from 127.0.0.1:33236 233s app_test.go:1202: 09:51:03.734 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected 233s --- PASS: TestNew_PristineJoiner (0.21s) 233s === RUN TestNew_JoinerRestart 233s app_test.go:1202: 09:51:03.864 - 4: DEBUG: new connection from 127.0.0.1:33242 233s app_test.go:1202: 09:51:03.866 - 4: DEBUG: attempt 1: server 127.0.0.1:9001: connected 233s app_test.go:1202: 09:51:03.868 - 4: DEBUG: new connection from 127.0.0.1:33258 233s app_test.go:1202: 09:51:03.932 - 4: DEBUG: new connection from 127.0.0.1:50424 233s app_test.go:1202: 09:51:03.934 - 5: DEBUG: attempt 1: server 127.0.0.1:9001: connected 233s app_test.go:1202: 09:51:03.937 - 4: DEBUG: new connection from 127.0.0.1:50440 233s app_test.go:1202: 09:51:03.939 - 5: DEBUG: new connection from 127.0.0.1:53426 234s app_test.go:1202: 09:51:03.012 - 4: DEBUG: new connection from 127.0.0.1:50454 234s app_test.go:1202: 09:51:03.014 - 6: DEBUG: attempt 1: server 127.0.0.1:9001: connected 234s app_test.go:1202: 09:51:03.016 - 4: DEBUG: new connection from 127.0.0.1:50464 234s app_test.go:1202: 09:51:03.018 - 6: DEBUG: new connection from 127.0.0.1:53436 234s --- PASS: TestNew_JoinerRestart (0.29s) 234s === RUN TestNew_SecondJoiner 234s app_test.go:1202: 09:51:03.134 - 7: DEBUG: new connection from 127.0.0.1:50478 234s app_test.go:1202: 09:51:03.136 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected 234s app_test.go:1202: 09:51:03.138 - 7: DEBUG: new connection from 127.0.0.1:50492 234s app_test.go:1202: 09:51:03.202 - 7: DEBUG: new connection from 127.0.0.1:50494 234s app_test.go:1202: 09:51:03.205 - 8: DEBUG: attempt 1: server 127.0.0.1:9001: connected 234s app_test.go:1202: 09:51:03.208 - 7: DEBUG: new connection from 127.0.0.1:50504 234s app_test.go:1202: 09:51:03.210 - 8: DEBUG: new connection from 127.0.0.1:53450 234s app_test.go:1202: 09:51:03.272 - 7: DEBUG: new connection from 127.0.0.1:50506 234s app_test.go:1202: 09:51:03.274 - 9: DEBUG: attempt 1: server 127.0.0.1:9001: connected 234s app_test.go:1202: 09:51:03.276 - 7: DEBUG: new connection from 127.0.0.1:50516 234s app_test.go:1202: 09:51:03.278 - 8: DEBUG: new connection from 127.0.0.1:53462 234s app_test.go:1202: 09:51:03.280 - 9: DEBUG: new connection from 127.0.0.1:41036 234s app_test.go:1202: 09:51:03.283 - 9: DEBUG: new connection from 127.0.0.1:41038 234s app_test.go:1202: 09:51:03.287 - 7: DEBUG: new connection from 127.0.0.1:50518 234s app_test.go:1202: 09:51:03.312 - 8: DEBUG: new connection from 127.0.0.1:53474 234s app_test.go:1202: 09:51:03.316 - 7: DEBUG: new connection from 127.0.0.1:50520 234s app_test.go:1202: 09:51:03.320 - 7: DEBUG: new connection from 127.0.0.1:50534 234s app_test.go:1202: 09:51:03.322 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected 234s --- PASS: TestNew_SecondJoiner (0.42s) 234s === RUN TestNew_ThirdJoiner 234s app_test.go:1202: 09:51:03.544 - 10: DEBUG: new connection from 127.0.0.1:50544 234s app_test.go:1202: 09:51:03.546 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected 234s app_test.go:1202: 09:51:03.547 - 10: DEBUG: new connection from 127.0.0.1:50548 234s app_test.go:1202: 09:51:03.602 - 10: DEBUG: new connection from 127.0.0.1:50564 234s app_test.go:1202: 09:51:03.604 - 11: DEBUG: attempt 1: server 127.0.0.1:9001: connected 234s app_test.go:1202: 09:51:03.607 - 10: DEBUG: new connection from 127.0.0.1:50580 234s app_test.go:1202: 09:51:03.609 - 11: DEBUG: new connection from 127.0.0.1:53482 234s app_test.go:1202: 09:51:03.672 - 10: DEBUG: new connection from 127.0.0.1:50582 234s app_test.go:1202: 09:51:03.674 - 12: DEBUG: attempt 1: server 127.0.0.1:9001: connected 234s app_test.go:1202: 09:51:03.676 - 10: DEBUG: new connection from 127.0.0.1:50588 234s app_test.go:1202: 09:51:03.679 - 11: DEBUG: new connection from 127.0.0.1:53490 234s app_test.go:1202: 09:51:03.682 - 12: DEBUG: new connection from 127.0.0.1:41046 234s app_test.go:1202: 09:51:03.685 - 12: DEBUG: new connection from 127.0.0.1:41048 234s app_test.go:1202: 09:51:03.689 - 10: DEBUG: new connection from 127.0.0.1:50598 234s app_test.go:1202: 09:51:03.692 - 11: DEBUG: new connection from 127.0.0.1:53506 234s app_test.go:1202: 09:51:03.696 - 10: DEBUG: new connection from 127.0.0.1:50606 234s app_test.go:1202: 09:51:03.752 - 10: DEBUG: new connection from 127.0.0.1:50618 234s app_test.go:1202: 09:51:03.755 - 13: DEBUG: attempt 1: server 127.0.0.1:9001: connected 234s app_test.go:1202: 09:51:03.757 - 10: DEBUG: new connection from 127.0.0.1:50632 234s app_test.go:1202: 09:51:03.760 - 11: DEBUG: new connection from 127.0.0.1:53508 234s app_test.go:1202: 09:51:03.762 - 12: DEBUG: new connection from 127.0.0.1:41060 234s app_test.go:1202: 09:51:03.764 - 13: DEBUG: new connection from 127.0.0.1:39854 234s app_test.go:1202: 09:51:03.766 - 13: DEBUG: new connection from 127.0.0.1:39866 234s app_test.go:1202: 09:51:03.769 - 10: DEBUG: new connection from 127.0.0.1:50640 234s app_test.go:1202: 09:51:03.770 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected 234s --- PASS: TestNew_ThirdJoiner (0.44s) 234s === RUN TestNew_FourthJoiner 235s app_test.go:1202: 09:51:03.997 - 14: DEBUG: new connection from 127.0.0.1:50646 235s app_test.go:1202: 09:51:03.999 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s app_test.go:1202: 09:51:03.001 - 14: DEBUG: new connection from 127.0.0.1:50656 235s app_test.go:1202: 09:51:03.063 - 14: DEBUG: new connection from 127.0.0.1:50662 235s app_test.go:1202: 09:51:03.067 - 15: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s app_test.go:1202: 09:51:03.071 - 14: DEBUG: new connection from 127.0.0.1:50670 235s app_test.go:1202: 09:51:03.079 - 15: DEBUG: new connection from 127.0.0.1:53514 235s app_test.go:1202: 09:51:03.142 - 14: DEBUG: new connection from 127.0.0.1:50678 235s app_test.go:1202: 09:51:03.144 - 16: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s app_test.go:1202: 09:51:03.145 - 14: DEBUG: new connection from 127.0.0.1:50682 235s app_test.go:1202: 09:51:03.148 - 15: DEBUG: new connection from 127.0.0.1:53518 235s app_test.go:1202: 09:51:03.150 - 16: DEBUG: new connection from 127.0.0.1:41072 235s app_test.go:1202: 09:51:03.152 - 16: DEBUG: new connection from 127.0.0.1:41088 235s app_test.go:1202: 09:51:03.156 - 14: DEBUG: new connection from 127.0.0.1:50692 235s app_test.go:1202: 09:51:03.160 - 15: DEBUG: new connection from 127.0.0.1:53532 235s app_test.go:1202: 09:51:03.163 - 14: DEBUG: new connection from 127.0.0.1:50704 235s app_test.go:1202: 09:51:03.212 - 14: DEBUG: new connection from 127.0.0.1:50714 235s app_test.go:1202: 09:51:03.214 - 17: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s app_test.go:1202: 09:51:03.216 - 14: DEBUG: new connection from 127.0.0.1:50716 235s app_test.go:1202: 09:51:03.218 - 15: DEBUG: new connection from 127.0.0.1:53544 235s app_test.go:1202: 09:51:03.220 - 16: DEBUG: new connection from 127.0.0.1:41090 235s app_test.go:1202: 09:51:03.223 - 17: DEBUG: new connection from 127.0.0.1:39874 235s app_test.go:1202: 09:51:03.225 - 17: DEBUG: new connection from 127.0.0.1:39888 235s app_test.go:1202: 09:51:03.230 - 14: DEBUG: new connection from 127.0.0.1:50722 235s app_test.go:1202: 09:51:03.292 - 14: DEBUG: new connection from 127.0.0.1:50730 235s app_test.go:1202: 09:51:03.295 - 18: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s app_test.go:1202: 09:51:03.297 - 14: DEBUG: new connection from 127.0.0.1:50746 235s app_test.go:1202: 09:51:03.300 - 15: DEBUG: new connection from 127.0.0.1:53554 235s app_test.go:1202: 09:51:03.302 - 16: DEBUG: new connection from 127.0.0.1:41096 235s app_test.go:1202: 09:51:03.304 - 17: DEBUG: new connection from 127.0.0.1:39890 235s app_test.go:1202: 09:51:03.307 - 18: DEBUG: new connection from 127.0.0.1:42084 235s app_test.go:1202: 09:51:03.309 - 18: DEBUG: new connection from 127.0.0.1:42100 235s app_test.go:1202: 09:51:03.312 - 14: DEBUG: new connection from 127.0.0.1:50754 235s app_test.go:1202: 09:51:03.315 - 14: DEBUG: new connection from 127.0.0.1:50760 235s app_test.go:1202: 09:51:03.317 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s --- PASS: TestNew_FourthJoiner (0.67s) 235s === RUN TestNew_FifthJoiner 235s app_test.go:1202: 09:51:03.653 - 19: DEBUG: new connection from 127.0.0.1:50768 235s app_test.go:1202: 09:51:03.655 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s app_test.go:1202: 09:51:03.656 - 19: DEBUG: new connection from 127.0.0.1:50784 235s app_test.go:1202: 09:51:03.722 - 19: DEBUG: new connection from 127.0.0.1:50788 235s app_test.go:1202: 09:51:03.724 - 20: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s app_test.go:1202: 09:51:03.726 - 19: DEBUG: new connection from 127.0.0.1:50796 235s app_test.go:1202: 09:51:03.729 - 20: DEBUG: new connection from 127.0.0.1:53568 235s app_test.go:1202: 09:51:03.792 - 19: DEBUG: new connection from 127.0.0.1:50808 235s app_test.go:1202: 09:51:03.794 - 21: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s app_test.go:1202: 09:51:03.795 - 19: DEBUG: new connection from 127.0.0.1:50810 235s app_test.go:1202: 09:51:03.798 - 20: DEBUG: new connection from 127.0.0.1:53578 235s app_test.go:1202: 09:51:03.800 - 21: DEBUG: new connection from 127.0.0.1:41110 235s app_test.go:1202: 09:51:03.803 - 21: DEBUG: new connection from 127.0.0.1:41120 235s app_test.go:1202: 09:51:03.806 - 19: DEBUG: new connection from 127.0.0.1:50814 235s app_test.go:1202: 09:51:03.809 - 20: DEBUG: new connection from 127.0.0.1:53588 235s app_test.go:1202: 09:51:03.812 - 19: DEBUG: new connection from 127.0.0.1:50822 235s app_test.go:1202: 09:51:03.862 - 19: DEBUG: new connection from 127.0.0.1:50834 235s app_test.go:1202: 09:51:03.864 - 22: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s app_test.go:1202: 09:51:03.865 - 19: DEBUG: new connection from 127.0.0.1:50844 235s app_test.go:1202: 09:51:03.867 - 20: DEBUG: new connection from 127.0.0.1:53590 235s app_test.go:1202: 09:51:03.870 - 21: DEBUG: new connection from 127.0.0.1:41132 235s app_test.go:1202: 09:51:03.872 - 22: DEBUG: new connection from 127.0.0.1:39894 235s app_test.go:1202: 09:51:03.874 - 22: DEBUG: new connection from 127.0.0.1:39908 235s app_test.go:1202: 09:51:03.879 - 19: DEBUG: new connection from 127.0.0.1:50856 235s app_test.go:1202: 09:51:03.921 - 19: DEBUG: new connection from 127.0.0.1:50862 235s app_test.go:1202: 09:51:03.923 - 23: DEBUG: attempt 1: server 127.0.0.1:9001: connected 235s app_test.go:1202: 09:51:03.925 - 19: DEBUG: new connection from 127.0.0.1:50874 235s app_test.go:1202: 09:51:03.928 - 20: DEBUG: new connection from 127.0.0.1:53596 235s app_test.go:1202: 09:51:03.931 - 21: DEBUG: new connection from 127.0.0.1:41140 235s app_test.go:1202: 09:51:03.933 - 22: DEBUG: new connection from 127.0.0.1:39912 235s app_test.go:1202: 09:51:03.935 - 23: DEBUG: new connection from 127.0.0.1:42104 235s app_test.go:1202: 09:51:03.938 - 23: DEBUG: new connection from 127.0.0.1:42120 235s app_test.go:1202: 09:51:03.943 - 19: DEBUG: new connection from 127.0.0.1:50884 236s app_test.go:1202: 09:51:03.992 - 19: DEBUG: new connection from 127.0.0.1:50894 236s app_test.go:1202: 09:51:03.994 - 24: DEBUG: attempt 1: server 127.0.0.1:9001: connected 236s app_test.go:1202: 09:51:03.996 - 19: DEBUG: new connection from 127.0.0.1:50900 236s app_test.go:1202: 09:51:03.999 - 20: DEBUG: new connection from 127.0.0.1:53606 236s app_test.go:1202: 09:51:03.001 - 21: DEBUG: new connection from 127.0.0.1:41152 236s app_test.go:1202: 09:51:03.003 - 22: DEBUG: new connection from 127.0.0.1:39920 236s app_test.go:1202: 09:51:03.005 - 23: DEBUG: new connection from 127.0.0.1:42128 236s app_test.go:1202: 09:51:03.008 - 24: DEBUG: new connection from 127.0.0.1:35052 236s app_test.go:1202: 09:51:03.011 - 24: DEBUG: new connection from 127.0.0.1:35066 236s app_test.go:1202: 09:51:03.014 - 19: DEBUG: new connection from 127.0.0.1:50904 236s app_test.go:1202: 09:51:03.016 - 19: DEBUG: new connection from 127.0.0.1:50916 236s app_test.go:1202: 09:51:03.019 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected 236s --- PASS: TestNew_FifthJoiner (0.75s) 236s === RUN TestNew_SixthJoiner 236s app_test.go:1202: 09:51:03.423 - 25: DEBUG: new connection from 127.0.0.1:50920 236s app_test.go:1202: 09:51:03.426 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected 236s app_test.go:1202: 09:51:03.427 - 25: DEBUG: new connection from 127.0.0.1:50922 236s app_test.go:1202: 09:51:03.492 - 25: DEBUG: new connection from 127.0.0.1:50930 236s app_test.go:1202: 09:51:03.494 - 26: DEBUG: attempt 1: server 127.0.0.1:9001: connected 236s app_test.go:1202: 09:51:03.496 - 25: DEBUG: new connection from 127.0.0.1:50940 236s app_test.go:1202: 09:51:03.498 - 26: DEBUG: new connection from 127.0.0.1:53620 236s app_test.go:1202: 09:51:03.552 - 25: DEBUG: new connection from 127.0.0.1:50954 236s app_test.go:1202: 09:51:03.554 - 27: DEBUG: attempt 1: server 127.0.0.1:9001: connected 236s app_test.go:1202: 09:51:03.556 - 25: DEBUG: new connection from 127.0.0.1:50968 236s app_test.go:1202: 09:51:03.558 - 26: DEBUG: new connection from 127.0.0.1:53624 236s app_test.go:1202: 09:51:03.562 - 27: DEBUG: new connection from 127.0.0.1:41154 236s app_test.go:1202: 09:51:03.564 - 27: DEBUG: new connection from 127.0.0.1:41156 236s app_test.go:1202: 09:51:03.567 - 25: DEBUG: new connection from 127.0.0.1:50972 236s app_test.go:1202: 09:51:03.571 - 26: DEBUG: new connection from 127.0.0.1:53632 236s app_test.go:1202: 09:51:03.574 - 25: DEBUG: new connection from 127.0.0.1:50978 236s app_test.go:1202: 09:51:03.632 - 25: DEBUG: new connection from 127.0.0.1:50982 236s app_test.go:1202: 09:51:03.634 - 28: DEBUG: attempt 1: server 127.0.0.1:9001: connected 236s app_test.go:1202: 09:51:03.635 - 25: DEBUG: new connection from 127.0.0.1:50986 236s app_test.go:1202: 09:51:03.638 - 26: DEBUG: new connection from 127.0.0.1:53644 236s app_test.go:1202: 09:51:03.640 - 27: DEBUG: new connection from 127.0.0.1:41170 236s app_test.go:1202: 09:51:03.642 - 28: DEBUG: new connection from 127.0.0.1:39930 236s app_test.go:1202: 09:51:03.645 - 28: DEBUG: new connection from 127.0.0.1:39934 236s app_test.go:1202: 09:51:03.650 - 25: DEBUG: new connection from 127.0.0.1:50994 236s app_test.go:1202: 09:51:03.702 - 25: DEBUG: new connection from 127.0.0.1:50998 236s app_test.go:1202: 09:51:03.705 - 29: DEBUG: attempt 1: server 127.0.0.1:9001: connected 236s app_test.go:1202: 09:51:03.707 - 25: DEBUG: new connection from 127.0.0.1:51008 236s app_test.go:1202: 09:51:03.710 - 26: DEBUG: new connection from 127.0.0.1:53650 236s app_test.go:1202: 09:51:03.713 - 27: DEBUG: new connection from 127.0.0.1:41184 236s app_test.go:1202: 09:51:03.716 - 28: DEBUG: new connection from 127.0.0.1:39950 236s app_test.go:1202: 09:51:03.719 - 29: DEBUG: new connection from 127.0.0.1:42138 236s app_test.go:1202: 09:51:03.721 - 29: DEBUG: new connection from 127.0.0.1:42152 236s app_test.go:1202: 09:51:03.727 - 25: DEBUG: new connection from 127.0.0.1:51020 236s app_test.go:1202: 09:51:03.782 - 25: DEBUG: new connection from 127.0.0.1:51026 236s app_test.go:1202: 09:51:03.785 - 30: DEBUG: attempt 1: server 127.0.0.1:9001: connected 236s app_test.go:1202: 09:51:03.787 - 25: DEBUG: new connection from 127.0.0.1:51040 236s app_test.go:1202: 09:51:03.790 - 26: DEBUG: new connection from 127.0.0.1:53664 236s app_test.go:1202: 09:51:03.792 - 27: DEBUG: new connection from 127.0.0.1:41186 236s app_test.go:1202: 09:51:03.795 - 28: DEBUG: new connection from 127.0.0.1:39954 236s app_test.go:1202: 09:51:03.797 - 29: DEBUG: new connection from 127.0.0.1:42168 236s app_test.go:1202: 09:51:03.799 - 30: DEBUG: new connection from 127.0.0.1:35080 236s app_test.go:1202: 09:51:03.803 - 30: DEBUG: new connection from 127.0.0.1:35084 236s app_test.go:1202: 09:51:03.808 - 25: DEBUG: new connection from 127.0.0.1:51056 236s app_test.go:1202: 09:51:03.852 - 25: DEBUG: new connection from 127.0.0.1:51064 236s app_test.go:1202: 09:51:03.854 - 31: DEBUG: attempt 1: server 127.0.0.1:9001: connected 236s app_test.go:1202: 09:51:03.856 - 25: DEBUG: new connection from 127.0.0.1:51068 236s app_test.go:1202: 09:51:03.859 - 26: DEBUG: new connection from 127.0.0.1:53674 236s app_test.go:1202: 09:51:03.861 - 27: DEBUG: new connection from 127.0.0.1:41188 236s app_test.go:1202: 09:51:03.863 - 28: DEBUG: new connection from 127.0.0.1:39966 236s app_test.go:1202: 09:51:03.865 - 29: DEBUG: new connection from 127.0.0.1:42182 236s app_test.go:1202: 09:51:03.867 - 30: DEBUG: new connection from 127.0.0.1:35096 236s app_test.go:1202: 09:51:03.870 - 31: DEBUG: new connection from 127.0.0.1:41196 236s app_test.go:1202: 09:51:03.872 - 25: DEBUG: new connection from 127.0.0.1:51082 236s app_test.go:1202: 09:51:03.874 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s --- PASS: TestNew_SixthJoiner (0.86s) 237s === RUN TestHandover_Voter 237s app_test.go:1202: 09:51:03.274 - 32: DEBUG: new connection from 127.0.0.1:51094 237s app_test.go:1202: 09:51:03.276 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s app_test.go:1202: 09:51:03.277 - 32: DEBUG: new connection from 127.0.0.1:51104 237s app_test.go:1202: 09:51:03.332 - 32: DEBUG: new connection from 127.0.0.1:51112 237s app_test.go:1202: 09:51:03.334 - 33: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s app_test.go:1202: 09:51:03.336 - 32: DEBUG: new connection from 127.0.0.1:51118 237s app_test.go:1202: 09:51:03.341 - 33: DEBUG: new connection from 127.0.0.1:53682 237s app_test.go:1202: 09:51:03.392 - 32: DEBUG: new connection from 127.0.0.1:51134 237s app_test.go:1202: 09:51:03.394 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s app_test.go:1202: 09:51:03.395 - 32: DEBUG: new connection from 127.0.0.1:51142 237s app_test.go:1202: 09:51:03.398 - 33: DEBUG: new connection from 127.0.0.1:53692 237s app_test.go:1202: 09:51:03.400 - 34: DEBUG: new connection from 127.0.0.1:41192 237s app_test.go:1202: 09:51:03.403 - 34: DEBUG: new connection from 127.0.0.1:41204 237s app_test.go:1202: 09:51:03.406 - 32: DEBUG: new connection from 127.0.0.1:51154 237s app_test.go:1202: 09:51:03.410 - 33: DEBUG: new connection from 127.0.0.1:53706 237s app_test.go:1202: 09:51:03.414 - 32: DEBUG: new connection from 127.0.0.1:51170 237s app_test.go:1202: 09:51:03.462 - 32: DEBUG: new connection from 127.0.0.1:51182 237s app_test.go:1202: 09:51:03.464 - 35: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s app_test.go:1202: 09:51:03.466 - 32: DEBUG: new connection from 127.0.0.1:51184 237s app_test.go:1202: 09:51:03.468 - 33: DEBUG: new connection from 127.0.0.1:53718 237s app_test.go:1202: 09:51:03.470 - 34: DEBUG: new connection from 127.0.0.1:41212 237s app_test.go:1202: 09:51:03.473 - 35: DEBUG: new connection from 127.0.0.1:39972 237s app_test.go:1202: 09:51:03.475 - 35: DEBUG: new connection from 127.0.0.1:39982 237s app_test.go:1202: 09:51:03.477 - 32: DEBUG: new connection from 127.0.0.1:51200 237s app_test.go:1202: 09:51:03.479 - 32: DEBUG: new connection from 127.0.0.1:51208 237s app_test.go:1202: 09:51:03.484 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s app_test.go:1202: 09:51:03.484 - 32: DEBUG: new connection from 127.0.0.1:51212 237s app_test.go:1202: 09:51:03.486 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s app_test.go:1202: 09:51:03.486 - 32: DEBUG: new connection from 127.0.0.1:51226 237s app_test.go:1202: 09:51:03.488 - 33: DEBUG: new connection from 127.0.0.1:53732 237s app_test.go:1202: 09:51:03.491 - 34: DEBUG: new connection from 127.0.0.1:41224 237s app_test.go:1202: 09:51:03.493 - 35: DEBUG: new connection from 127.0.0.1:39988 237s app_test.go:1202: 09:51:03.495 - 34: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 237s --- PASS: TestHandover_Voter (0.51s) 237s === RUN TestHandover_TwoNodes 237s app_test.go:1202: 09:51:03.793 - 36: DEBUG: new connection from 127.0.0.1:51232 237s app_test.go:1202: 09:51:03.796 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s app_test.go:1202: 09:51:03.797 - 36: DEBUG: new connection from 127.0.0.1:51248 237s app_test.go:1202: 09:51:03.862 - 36: DEBUG: new connection from 127.0.0.1:51256 237s app_test.go:1202: 09:51:03.864 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s app_test.go:1202: 09:51:03.867 - 36: DEBUG: new connection from 127.0.0.1:51264 237s app_test.go:1202: 09:51:03.869 - 37: DEBUG: new connection from 127.0.0.1:53746 237s app_test.go:1202: 09:51:03.871 - 36: DEBUG: new connection from 127.0.0.1:51268 237s app_test.go:1202: 09:51:03.873 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s app_test.go:1202: 09:51:03.874 - 36: DEBUG: new connection from 127.0.0.1:51270 237s app_test.go:1202: 09:51:03.876 - 37: DEBUG: new connection from 127.0.0.1:53756 237s app_test.go:1202: 09:51:03.878 - 37: DEBUG: new connection from 127.0.0.1:53760 237s app_test.go:1202: 09:51:03.883 - 36: DEBUG: new connection from 127.0.0.1:51284 237s app_test.go:1202: 09:51:03.886 - 36: DEBUG: promoted 127.0.0.1:9002 from spare to voter 237s app_test.go:1202: 09:51:03.886 - 36: DEBUG: new connection from 127.0.0.1:51298 237s app_test.go:1202: 09:51:03.888 - 37: DEBUG: new connection from 127.0.0.1:53776 237s app_test.go:1202: 09:51:03.892 - 36: DEBUG: new connection from 127.0.0.1:51306 237s app_test.go:1202: 09:51:03.894 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 237s app_test.go:1202: 09:51:03.894 - 37: DEBUG: new connection from 127.0.0.1:53790 237s app_test.go:1202: 09:51:03.896 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected 237s app_test.go:1202: 09:51:03.897 - 36: DEBUG: new connection from 127.0.0.1:51316 237s app_test.go:1202: 09:51:03.899 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 237s app_test.go:1202: 09:51:03.899 - 37: DEBUG: new connection from 127.0.0.1:53796 237s app_test.go:1202: 09:51:03.901 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected 238s --- PASS: TestHandover_TwoNodes (0.31s) 238s === RUN TestHandover_VoterHonorFailureDomain 238s app_test.go:1202: 09:51:03.092 - 38: DEBUG: new connection from 127.0.0.1:51318 238s app_test.go:1202: 09:51:03.095 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected 238s app_test.go:1202: 09:51:03.096 - 38: DEBUG: new connection from 127.0.0.1:51324 238s app_test.go:1202: 09:51:03.162 - 38: DEBUG: new connection from 127.0.0.1:51340 238s app_test.go:1202: 09:51:03.164 - 39: DEBUG: attempt 1: server 127.0.0.1:9001: connected 238s app_test.go:1202: 09:51:03.165 - 38: DEBUG: new connection from 127.0.0.1:51352 238s app_test.go:1202: 09:51:03.168 - 39: DEBUG: new connection from 127.0.0.1:53798 238s app_test.go:1202: 09:51:03.231 - 38: DEBUG: new connection from 127.0.0.1:51368 238s app_test.go:1202: 09:51:03.233 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected 238s app_test.go:1202: 09:51:03.235 - 38: DEBUG: new connection from 127.0.0.1:51370 238s app_test.go:1202: 09:51:03.237 - 39: DEBUG: new connection from 127.0.0.1:53804 238s app_test.go:1202: 09:51:03.239 - 40: DEBUG: new connection from 127.0.0.1:41232 238s app_test.go:1202: 09:51:03.242 - 40: DEBUG: new connection from 127.0.0.1:41246 238s app_test.go:1202: 09:51:03.245 - 38: DEBUG: new connection from 127.0.0.1:51384 238s app_test.go:1202: 09:51:03.248 - 39: DEBUG: new connection from 127.0.0.1:53810 238s app_test.go:1202: 09:51:03.252 - 38: DEBUG: new connection from 127.0.0.1:51390 238s app_test.go:1202: 09:51:03.312 - 38: DEBUG: new connection from 127.0.0.1:51398 238s app_test.go:1202: 09:51:03.314 - 41: DEBUG: attempt 1: server 127.0.0.1:9001: connected 238s app_test.go:1202: 09:51:03.316 - 38: DEBUG: new connection from 127.0.0.1:51410 238s app_test.go:1202: 09:51:03.318 - 39: DEBUG: new connection from 127.0.0.1:53826 238s app_test.go:1202: 09:51:03.321 - 40: DEBUG: new connection from 127.0.0.1:41254 238s app_test.go:1202: 09:51:03.323 - 41: DEBUG: new connection from 127.0.0.1:40002 238s app_test.go:1202: 09:51:03.326 - 41: DEBUG: new connection from 127.0.0.1:40006 238s app_test.go:1202: 09:51:03.331 - 38: DEBUG: new connection from 127.0.0.1:51418 238s app_test.go:1202: 09:51:03.392 - 38: DEBUG: new connection from 127.0.0.1:51430 238s app_test.go:1202: 09:51:03.394 - 42: DEBUG: attempt 1: server 127.0.0.1:9001: connected 238s app_test.go:1202: 09:51:03.396 - 38: DEBUG: new connection from 127.0.0.1:51438 238s app_test.go:1202: 09:51:03.398 - 39: DEBUG: new connection from 127.0.0.1:53832 238s app_test.go:1202: 09:51:03.400 - 40: DEBUG: new connection from 127.0.0.1:41258 238s app_test.go:1202: 09:51:03.404 - 41: DEBUG: new connection from 127.0.0.1:40020 238s app_test.go:1202: 09:51:03.406 - 42: DEBUG: new connection from 127.0.0.1:42186 238s app_test.go:1202: 09:51:03.408 - 42: DEBUG: new connection from 127.0.0.1:42190 238s app_test.go:1202: 09:51:03.412 - 38: DEBUG: new connection from 127.0.0.1:51446 238s app_test.go:1202: 09:51:03.472 - 38: DEBUG: new connection from 127.0.0.1:51452 238s app_test.go:1202: 09:51:03.474 - 43: DEBUG: attempt 1: server 127.0.0.1:9001: connected 238s app_test.go:1202: 09:51:03.476 - 38: DEBUG: new connection from 127.0.0.1:51466 238s app_test.go:1202: 09:51:03.479 - 39: DEBUG: new connection from 127.0.0.1:53846 238s app_test.go:1202: 09:51:03.481 - 40: DEBUG: new connection from 127.0.0.1:41274 238s app_test.go:1202: 09:51:03.483 - 41: DEBUG: new connection from 127.0.0.1:40034 238s app_test.go:1202: 09:51:03.485 - 42: DEBUG: new connection from 127.0.0.1:42198 238s app_test.go:1202: 09:51:03.487 - 43: DEBUG: new connection from 127.0.0.1:35098 238s app_test.go:1202: 09:51:03.492 - 38: DEBUG: new connection from 127.0.0.1:51482 238s app_test.go:1202: 09:51:03.494 - 43: DEBUG: new connection from 127.0.0.1:35102 238s app_test.go:1202: 09:51:03.495 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected 238s app_test.go:1202: 09:51:03.496 - 38: DEBUG: new connection from 127.0.0.1:51490 238s app_test.go:1202: 09:51:03.497 - 38: DEBUG: new connection from 127.0.0.1:51494 238s app_test.go:1202: 09:51:03.500 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected 238s app_test.go:1202: 09:51:03.501 - 38: DEBUG: new connection from 127.0.0.1:51498 238s app_test.go:1202: 09:51:03.503 - 39: DEBUG: new connection from 127.0.0.1:53860 238s app_test.go:1202: 09:51:03.505 - 40: DEBUG: new connection from 127.0.0.1:41288 238s app_test.go:1202: 09:51:03.508 - 41: DEBUG: new connection from 127.0.0.1:40036 238s app_test.go:1202: 09:51:03.510 - 42: DEBUG: new connection from 127.0.0.1:42210 238s app_test.go:1202: 09:51:03.512 - 43: DEBUG: new connection from 127.0.0.1:35116 238s app_test.go:1202: 09:51:03.514 - 40: DEBUG: promoted 127.0.0.1:9006 from stand-by to voter 238s --- PASS: TestHandover_VoterHonorFailureDomain (0.83s) 238s === RUN TestHandover_SingleNode 238s --- PASS: TestHandover_SingleNode (0.07s) 238s === RUN TestHandover_GracefulShutdown 239s --- PASS: TestHandover_GracefulShutdown (0.38s) 239s === RUN TestHandover_StandBy 239s app_test.go:1202: 09:51:03.373 - 44: DEBUG: new connection from 127.0.0.1:51726 239s app_test.go:1202: 09:51:03.375 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected 239s app_test.go:1202: 09:51:03.377 - 44: DEBUG: new connection from 127.0.0.1:51740 239s app_test.go:1202: 09:51:03.442 - 44: DEBUG: new connection from 127.0.0.1:51752 239s app_test.go:1202: 09:51:03.445 - 45: DEBUG: attempt 1: server 127.0.0.1:9001: connected 239s app_test.go:1202: 09:51:03.447 - 44: DEBUG: new connection from 127.0.0.1:51758 239s app_test.go:1202: 09:51:03.449 - 45: DEBUG: new connection from 127.0.0.1:53988 239s app_test.go:1202: 09:51:03.512 - 44: DEBUG: new connection from 127.0.0.1:51770 239s app_test.go:1202: 09:51:03.514 - 46: DEBUG: attempt 1: server 127.0.0.1:9001: connected 239s app_test.go:1202: 09:51:03.516 - 44: DEBUG: new connection from 127.0.0.1:51780 239s app_test.go:1202: 09:51:03.518 - 45: DEBUG: new connection from 127.0.0.1:53996 239s app_test.go:1202: 09:51:03.520 - 46: DEBUG: new connection from 127.0.0.1:41418 239s app_test.go:1202: 09:51:03.522 - 46: DEBUG: new connection from 127.0.0.1:41428 239s app_test.go:1202: 09:51:03.526 - 44: DEBUG: new connection from 127.0.0.1:51796 239s app_test.go:1202: 09:51:03.530 - 45: DEBUG: new connection from 127.0.0.1:54000 239s app_test.go:1202: 09:51:03.534 - 44: DEBUG: new connection from 127.0.0.1:51804 239s app_test.go:1202: 09:51:03.592 - 44: DEBUG: new connection from 127.0.0.1:51814 239s app_test.go:1202: 09:51:03.596 - 47: DEBUG: attempt 1: server 127.0.0.1:9001: connected 239s app_test.go:1202: 09:51:03.600 - 44: DEBUG: new connection from 127.0.0.1:51830 239s app_test.go:1202: 09:51:03.602 - 45: DEBUG: new connection from 127.0.0.1:54014 239s app_test.go:1202: 09:51:03.604 - 46: DEBUG: new connection from 127.0.0.1:41436 239s app_test.go:1202: 09:51:03.607 - 47: DEBUG: new connection from 127.0.0.1:40042 239s app_test.go:1202: 09:51:03.609 - 47: DEBUG: new connection from 127.0.0.1:40058 239s app_test.go:1202: 09:51:03.615 - 44: DEBUG: new connection from 127.0.0.1:51836 239s app_test.go:1202: 09:51:03.662 - 44: DEBUG: new connection from 127.0.0.1:51846 239s app_test.go:1202: 09:51:03.666 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected 239s app_test.go:1202: 09:51:03.668 - 44: DEBUG: new connection from 127.0.0.1:51850 239s app_test.go:1202: 09:51:03.670 - 45: DEBUG: new connection from 127.0.0.1:54016 239s app_test.go:1202: 09:51:03.672 - 46: DEBUG: new connection from 127.0.0.1:41446 239s app_test.go:1202: 09:51:03.675 - 47: DEBUG: new connection from 127.0.0.1:40062 239s app_test.go:1202: 09:51:03.677 - 48: DEBUG: new connection from 127.0.0.1:42220 239s app_test.go:1202: 09:51:03.680 - 48: DEBUG: new connection from 127.0.0.1:42234 239s app_test.go:1202: 09:51:03.685 - 44: DEBUG: new connection from 127.0.0.1:51864 239s app_test.go:1202: 09:51:03.742 - 44: DEBUG: new connection from 127.0.0.1:51874 239s app_test.go:1202: 09:51:03.744 - 49: DEBUG: attempt 1: server 127.0.0.1:9001: connected 239s app_test.go:1202: 09:51:03.747 - 44: DEBUG: new connection from 127.0.0.1:51878 239s app_test.go:1202: 09:51:03.750 - 45: DEBUG: new connection from 127.0.0.1:54028 239s app_test.go:1202: 09:51:03.753 - 46: DEBUG: new connection from 127.0.0.1:41454 239s app_test.go:1202: 09:51:03.755 - 47: DEBUG: new connection from 127.0.0.1:40076 239s app_test.go:1202: 09:51:03.758 - 48: DEBUG: new connection from 127.0.0.1:42236 239s app_test.go:1202: 09:51:03.760 - 49: DEBUG: new connection from 127.0.0.1:35124 239s app_test.go:1202: 09:51:03.763 - 49: DEBUG: new connection from 127.0.0.1:35132 239s app_test.go:1202: 09:51:03.769 - 44: DEBUG: new connection from 127.0.0.1:51886 239s app_test.go:1202: 09:51:03.822 - 44: DEBUG: new connection from 127.0.0.1:51890 239s app_test.go:1202: 09:51:03.824 - 50: DEBUG: attempt 1: server 127.0.0.1:9001: connected 239s app_test.go:1202: 09:51:03.826 - 44: DEBUG: new connection from 127.0.0.1:51892 239s app_test.go:1202: 09:51:03.829 - 45: DEBUG: new connection from 127.0.0.1:54032 239s app_test.go:1202: 09:51:03.833 - 46: DEBUG: new connection from 127.0.0.1:41468 239s app_test.go:1202: 09:51:03.836 - 47: DEBUG: new connection from 127.0.0.1:40092 239s app_test.go:1202: 09:51:03.838 - 48: DEBUG: new connection from 127.0.0.1:42246 239s app_test.go:1202: 09:51:03.840 - 49: DEBUG: new connection from 127.0.0.1:35136 239s app_test.go:1202: 09:51:03.842 - 50: DEBUG: new connection from 127.0.0.1:41208 239s app_test.go:1202: 09:51:03.845 - 44: DEBUG: new connection from 127.0.0.1:51906 239s app_test.go:1202: 09:51:03.847 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected 239s app_test.go:1202: 09:51:03.847 - 44: DEBUG: new connection from 127.0.0.1:51918 239s app_test.go:1202: 09:51:03.849 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected 239s app_test.go:1202: 09:51:03.850 - 44: DEBUG: new connection from 127.0.0.1:51932 239s app_test.go:1202: 09:51:03.852 - 45: DEBUG: new connection from 127.0.0.1:54044 239s app_test.go:1202: 09:51:03.854 - 46: DEBUG: new connection from 127.0.0.1:41476 239s app_test.go:1202: 09:51:03.856 - 47: DEBUG: new connection from 127.0.0.1:40102 239s app_test.go:1202: 09:51:03.859 - 48: DEBUG: new connection from 127.0.0.1:42252 239s app_test.go:1202: 09:51:03.861 - 49: DEBUG: new connection from 127.0.0.1:35140 239s app_test.go:1202: 09:51:03.863 - 50: DEBUG: new connection from 127.0.0.1:41214 239s app_test.go:1202: 09:51:03.866 - 50: DEBUG: new connection from 127.0.0.1:41222 239s app_test.go:1202: 09:51:03.868 - 48: DEBUG: promoted 127.0.0.1:9007 from spare to stand-by 240s --- PASS: TestHandover_StandBy (0.95s) 240s === RUN TestHandover_TransferLeadership 240s app_test.go:1202: 09:51:03.314 - 51: DEBUG: new connection from 127.0.0.1:51942 240s app_test.go:1202: 09:51:03.319 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 240s app_test.go:1202: 09:51:03.320 - 51: DEBUG: new connection from 127.0.0.1:51956 240s app_test.go:1202: 09:51:03.382 - 51: DEBUG: new connection from 127.0.0.1:51970 240s app_test.go:1202: 09:51:03.384 - 52: DEBUG: attempt 1: server 127.0.0.1:9001: connected 240s app_test.go:1202: 09:51:03.387 - 51: DEBUG: new connection from 127.0.0.1:51978 240s app_test.go:1202: 09:51:03.389 - 52: DEBUG: new connection from 127.0.0.1:54058 240s app_test.go:1202: 09:51:03.452 - 51: DEBUG: new connection from 127.0.0.1:51988 240s app_test.go:1202: 09:51:03.454 - 53: DEBUG: attempt 1: server 127.0.0.1:9001: connected 240s app_test.go:1202: 09:51:03.456 - 51: DEBUG: new connection from 127.0.0.1:51996 240s app_test.go:1202: 09:51:03.460 - 52: DEBUG: new connection from 127.0.0.1:54064 240s app_test.go:1202: 09:51:03.464 - 53: DEBUG: new connection from 127.0.0.1:41492 240s app_test.go:1202: 09:51:03.466 - 53: DEBUG: new connection from 127.0.0.1:41508 240s app_test.go:1202: 09:51:03.469 - 51: DEBUG: new connection from 127.0.0.1:52000 240s app_test.go:1202: 09:51:03.473 - 52: DEBUG: new connection from 127.0.0.1:54068 240s app_test.go:1202: 09:51:03.477 - 51: DEBUG: new connection from 127.0.0.1:52012 240s app_test.go:1202: 09:51:03.532 - 51: DEBUG: new connection from 127.0.0.1:52026 240s app_test.go:1202: 09:51:03.534 - 54: DEBUG: attempt 1: server 127.0.0.1:9001: connected 240s app_test.go:1202: 09:51:03.536 - 51: DEBUG: new connection from 127.0.0.1:52030 240s app_test.go:1202: 09:51:03.539 - 52: DEBUG: new connection from 127.0.0.1:54082 240s app_test.go:1202: 09:51:03.542 - 53: DEBUG: new connection from 127.0.0.1:41516 240s app_test.go:1202: 09:51:03.546 - 54: DEBUG: new connection from 127.0.0.1:40108 240s app_test.go:1202: 09:51:03.549 - 54: DEBUG: new connection from 127.0.0.1:40116 240s app_test.go:1202: 09:51:03.551 - 51: DEBUG: new connection from 127.0.0.1:52034 240s app_test.go:1202: 09:51:03.553 - 51: DEBUG: new connection from 127.0.0.1:52044 240s app_test.go:1202: 09:51:03.556 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 240s app_test.go:1202: 09:51:03.556 - 51: DEBUG: new connection from 127.0.0.1:52046 240s app_test.go:1202: 09:51:03.559 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 240s app_test.go:1202: 09:51:03.559 - 51: DEBUG: new connection from 127.0.0.1:52060 240s app_test.go:1202: 09:51:03.561 - 52: DEBUG: new connection from 127.0.0.1:54090 240s app_test.go:1202: 09:51:03.563 - 53: DEBUG: new connection from 127.0.0.1:41526 240s app_test.go:1202: 09:51:03.565 - 54: DEBUG: new connection from 127.0.0.1:40122 240s app_test.go:1202: 09:51:03.568 - 51: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 240s app_test.go:1202: 09:51:03.568 - 51: DEBUG: new connection from 127.0.0.1:52072 240s app_test.go:1202: 09:51:03.570 - 52: DEBUG: new connection from 127.0.0.1:54106 240s app_test.go:1202: 09:51:03.572 - 53: DEBUG: new connection from 127.0.0.1:41542 240s app_test.go:1202: 09:51:03.574 - 54: DEBUG: new connection from 127.0.0.1:40126 240s app_test.go:1202: 09:51:03.578 - 53: DEBUG: new connection from 127.0.0.1:41552 240s app_test.go:1202: 09:51:03.580 - 54: DEBUG: new connection from 127.0.0.1:40138 240s app_test.go:1202: 09:51:03.584 - 52: DEBUG: new connection from 127.0.0.1:54108 240s app_test.go:1202: 09:51:03.585 - 52: DEBUG: new connection from 127.0.0.1:54124 240s app_test.go:1202: 09:51:03.588 - 51: DEBUG: new connection from 127.0.0.1:52074 240s app_test.go:1202: 09:51:03.590 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 240s app_test.go:1202: 09:51:03.591 - 52: DEBUG: new connection from 127.0.0.1:54140 240s app_test.go:1202: 09:51:03.592 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 240s app_test.go:1202: 09:51:03.593 - 51: DEBUG: new connection from 127.0.0.1:52082 240s app_test.go:1202: 09:51:03.595 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 240s app_test.go:1202: 09:51:03.595 - 52: DEBUG: new connection from 127.0.0.1:54154 240s app_test.go:1202: 09:51:03.597 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 240s --- PASS: TestHandover_TransferLeadership (0.53s) 240s === RUN TestRolesAdjustment_ReplaceVoter 240s app_test.go:1202: 09:51:03.874 - 55: DEBUG: new connection from 127.0.0.1:52096 240s app_test.go:1202: 09:51:03.876 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 240s app_test.go:1202: 09:51:03.878 - 55: DEBUG: new connection from 127.0.0.1:52106 240s app_test.go:1202: 09:51:03.942 - 55: DEBUG: new connection from 127.0.0.1:52112 240s app_test.go:1202: 09:51:03.944 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 240s app_test.go:1202: 09:51:03.947 - 55: DEBUG: new connection from 127.0.0.1:52114 240s app_test.go:1202: 09:51:03.949 - 56: DEBUG: new connection from 127.0.0.1:54166 241s app_test.go:1202: 09:51:03.012 - 55: DEBUG: new connection from 127.0.0.1:52120 241s app_test.go:1202: 09:51:03.014 - 57: DEBUG: attempt 1: server 127.0.0.1:9001: connected 241s app_test.go:1202: 09:51:03.015 - 55: DEBUG: new connection from 127.0.0.1:52124 241s app_test.go:1202: 09:51:03.018 - 56: DEBUG: new connection from 127.0.0.1:54182 241s app_test.go:1202: 09:51:03.020 - 57: DEBUG: new connection from 127.0.0.1:41556 241s app_test.go:1202: 09:51:03.023 - 57: DEBUG: new connection from 127.0.0.1:41562 241s app_test.go:1202: 09:51:03.027 - 55: DEBUG: new connection from 127.0.0.1:52126 241s app_test.go:1202: 09:51:03.031 - 56: DEBUG: new connection from 127.0.0.1:54186 241s app_test.go:1202: 09:51:03.035 - 55: DEBUG: new connection from 127.0.0.1:52136 241s app_test.go:1202: 09:51:03.092 - 55: DEBUG: new connection from 127.0.0.1:52138 241s app_test.go:1202: 09:51:03.094 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 241s app_test.go:1202: 09:51:03.097 - 55: DEBUG: new connection from 127.0.0.1:52150 241s app_test.go:1202: 09:51:03.099 - 56: DEBUG: new connection from 127.0.0.1:54192 241s app_test.go:1202: 09:51:03.101 - 57: DEBUG: new connection from 127.0.0.1:41570 241s app_test.go:1202: 09:51:03.104 - 58: DEBUG: new connection from 127.0.0.1:40152 241s app_test.go:1202: 09:51:03.107 - 58: DEBUG: new connection from 127.0.0.1:40160 241s app_test.go:1202: 09:51:03.111 - 55: DEBUG: new connection from 127.0.0.1:52152 242s app_test.go:1202: 09:51:03.883 - 55: DEBUG: new connection from 127.0.0.1:52162 242s app_test.go:1202: 09:51:03.886 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 242s app_test.go:1202: 09:51:03.888 - 55: DEBUG: new connection from 127.0.0.1:52168 242s app_test.go:1202: 09:51:03.890 - 56: DEBUG: new connection from 127.0.0.1:54206 242s app_test.go:1202: 09:51:03.893 - 58: DEBUG: new connection from 127.0.0.1:40162 242s app_test.go:1202: 09:51:03.954 - 55: DEBUG: new connection from 127.0.0.1:52172 242s app_test.go:1202: 09:51:03.957 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 243s app_test.go:1202: 09:51:03.107 - 55: DEBUG: new connection from 127.0.0.1:52182 243s app_test.go:1202: 09:51:03.109 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 243s app_test.go:1202: 09:51:03.317 - 55: DEBUG: new connection from 127.0.0.1:52196 243s app_test.go:1202: 09:51:03.319 - 56: DEBUG: new connection from 127.0.0.1:54218 243s app_test.go:1202: 09:51:03.322 - 58: DEBUG: new connection from 127.0.0.1:40176 243s app_test.go:1202: 09:51:03.325 - 55: DEBUG: new connection from 127.0.0.1:52206 243s app_test.go:1202: 09:51:03.327 - 56: DEBUG: new connection from 127.0.0.1:54228 243s app_test.go:1202: 09:51:03.330 - 58: DEBUG: new connection from 127.0.0.1:40180 244s app_test.go:1202: 09:51:03.959 - 55: DEBUG: new connection from 127.0.0.1:35170 244s app_test.go:1202: 09:51:03.962 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 245s app_test.go:1202: 09:51:03.112 - 55: DEBUG: new connection from 127.0.0.1:35180 245s app_test.go:1202: 09:51:03.114 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 245s app_test.go:1202: 09:51:03.333 - 55: DEBUG: new connection from 127.0.0.1:35184 245s app_test.go:1202: 09:51:03.336 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 245s app_test.go:1202: 09:51:03.338 - 55: DEBUG: new connection from 127.0.0.1:35186 245s app_test.go:1202: 09:51:03.340 - 56: DEBUG: new connection from 127.0.0.1:59184 245s app_test.go:1202: 09:51:03.342 - 58: DEBUG: new connection from 127.0.0.1:54490 247s app_test.go:1202: 09:51:03.965 - 55: DEBUG: new connection from 127.0.0.1:35194 247s app_test.go:1202: 09:51:03.968 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 247s app_test.go:1202: 09:51:03.116 - 55: DEBUG: new connection from 127.0.0.1:35198 247s app_test.go:1202: 09:51:03.119 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 247s app_test.go:1202: 09:51:03.345 - 55: DEBUG: new connection from 127.0.0.1:35212 247s app_test.go:1202: 09:51:03.347 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 247s app_test.go:1202: 09:51:03.349 - 55: DEBUG: new connection from 127.0.0.1:35216 247s app_test.go:1202: 09:51:03.351 - 56: DEBUG: new connection from 127.0.0.1:59194 247s app_test.go:1202: 09:51:03.354 - 58: DEBUG: new connection from 127.0.0.1:54492 249s app_test.go:1202: 09:51:03.970 - 55: DEBUG: new connection from 127.0.0.1:35232 249s app_test.go:1202: 09:51:03.973 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 249s app_test.go:1202: 09:51:03.121 - 55: DEBUG: new connection from 127.0.0.1:35242 249s app_test.go:1202: 09:51:03.123 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 249s app_test.go:1202: 09:51:03.161 - 55: DEBUG: new connection from 127.0.0.1:35258 249s app_test.go:1202: 09:51:03.163 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 249s --- PASS: TestRolesAdjustment_ReplaceVoter (8.55s) 249s === RUN TestRolesAdjustment_ReplaceVoterHonorFailureDomain 249s app_test.go:1202: 09:51:03.393 - 59: DEBUG: new connection from 127.0.0.1:35260 249s app_test.go:1202: 09:51:03.395 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 249s app_test.go:1202: 09:51:03.396 - 59: DEBUG: new connection from 127.0.0.1:35262 249s app_test.go:1202: 09:51:03.452 - 59: DEBUG: new connection from 127.0.0.1:35272 249s app_test.go:1202: 09:51:03.454 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 249s app_test.go:1202: 09:51:03.456 - 59: DEBUG: new connection from 127.0.0.1:35276 249s app_test.go:1202: 09:51:03.459 - 60: DEBUG: new connection from 127.0.0.1:59198 249s app_test.go:1202: 09:51:03.512 - 59: DEBUG: new connection from 127.0.0.1:35292 249s app_test.go:1202: 09:51:03.514 - 61: DEBUG: attempt 1: server 127.0.0.1:9001: connected 249s app_test.go:1202: 09:51:03.515 - 59: DEBUG: new connection from 127.0.0.1:35306 249s app_test.go:1202: 09:51:03.517 - 60: DEBUG: new connection from 127.0.0.1:59214 249s app_test.go:1202: 09:51:03.520 - 61: DEBUG: new connection from 127.0.0.1:37924 249s app_test.go:1202: 09:51:03.522 - 61: DEBUG: new connection from 127.0.0.1:37930 249s app_test.go:1202: 09:51:03.526 - 59: DEBUG: new connection from 127.0.0.1:35314 249s app_test.go:1202: 09:51:03.529 - 60: DEBUG: new connection from 127.0.0.1:59216 249s app_test.go:1202: 09:51:03.533 - 59: DEBUG: new connection from 127.0.0.1:35320 249s app_test.go:1202: 09:51:03.581 - 59: DEBUG: new connection from 127.0.0.1:35326 249s app_test.go:1202: 09:51:03.583 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 249s app_test.go:1202: 09:51:03.585 - 59: DEBUG: new connection from 127.0.0.1:35340 249s app_test.go:1202: 09:51:03.587 - 60: DEBUG: new connection from 127.0.0.1:59228 249s app_test.go:1202: 09:51:03.589 - 61: DEBUG: new connection from 127.0.0.1:37932 249s app_test.go:1202: 09:51:03.592 - 62: DEBUG: new connection from 127.0.0.1:54496 249s app_test.go:1202: 09:51:03.595 - 62: DEBUG: new connection from 127.0.0.1:54512 249s app_test.go:1202: 09:51:03.599 - 59: DEBUG: new connection from 127.0.0.1:35342 249s app_test.go:1202: 09:51:03.652 - 59: DEBUG: new connection from 127.0.0.1:35348 249s app_test.go:1202: 09:51:03.654 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 249s app_test.go:1202: 09:51:03.655 - 59: DEBUG: new connection from 127.0.0.1:35356 249s app_test.go:1202: 09:51:03.658 - 60: DEBUG: new connection from 127.0.0.1:59238 249s app_test.go:1202: 09:51:03.660 - 61: DEBUG: new connection from 127.0.0.1:37940 249s app_test.go:1202: 09:51:03.662 - 62: DEBUG: new connection from 127.0.0.1:54516 249s app_test.go:1202: 09:51:03.664 - 63: DEBUG: new connection from 127.0.0.1:42058 249s app_test.go:1202: 09:51:03.667 - 63: DEBUG: new connection from 127.0.0.1:42070 249s app_test.go:1202: 09:51:03.671 - 59: DEBUG: new connection from 127.0.0.1:35360 249s app_test.go:1202: 09:51:03.722 - 59: DEBUG: new connection from 127.0.0.1:35374 249s app_test.go:1202: 09:51:03.725 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 249s app_test.go:1202: 09:51:03.727 - 59: DEBUG: new connection from 127.0.0.1:35376 249s app_test.go:1202: 09:51:03.731 - 60: DEBUG: new connection from 127.0.0.1:59246 249s app_test.go:1202: 09:51:03.733 - 61: DEBUG: new connection from 127.0.0.1:37944 249s app_test.go:1202: 09:51:03.735 - 62: DEBUG: new connection from 127.0.0.1:54532 249s app_test.go:1202: 09:51:03.738 - 63: DEBUG: new connection from 127.0.0.1:42076 249s app_test.go:1202: 09:51:03.740 - 64: DEBUG: new connection from 127.0.0.1:43212 249s app_test.go:1202: 09:51:03.743 - 64: DEBUG: new connection from 127.0.0.1:43228 249s app_test.go:1202: 09:51:03.747 - 59: DEBUG: new connection from 127.0.0.1:35382 253s app_test.go:1202: 09:51:03.400 - 59: DEBUG: new connection from 127.0.0.1:35392 253s app_test.go:1202: 09:51:03.403 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 253s app_test.go:1202: 09:51:03.404 - 59: DEBUG: new connection from 127.0.0.1:35402 253s app_test.go:1202: 09:51:03.407 - 60: DEBUG: new connection from 127.0.0.1:59250 253s app_test.go:1202: 09:51:03.409 - 62: DEBUG: new connection from 127.0.0.1:54546 253s app_test.go:1202: 09:51:03.412 - 63: DEBUG: new connection from 127.0.0.1:42084 253s app_test.go:1202: 09:51:03.415 - 64: DEBUG: new connection from 127.0.0.1:43232 253s app_test.go:1202: 09:51:03.462 - 59: DEBUG: new connection from 127.0.0.1:35416 253s app_test.go:1202: 09:51:03.463 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 253s app_test.go:1202: 09:51:03.598 - 59: DEBUG: new connection from 127.0.0.1:35426 253s app_test.go:1202: 09:51:03.600 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 253s app_test.go:1202: 09:51:03.670 - 59: DEBUG: new connection from 127.0.0.1:35442 253s app_test.go:1202: 09:51:03.672 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 253s app_test.go:1202: 09:51:03.743 - 59: DEBUG: new connection from 127.0.0.1:35452 253s app_test.go:1202: 09:51:03.745 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 253s app_test.go:1202: 09:51:03.857 - 59: DEBUG: new connection from 127.0.0.1:35464 253s app_test.go:1202: 09:51:03.860 - 60: DEBUG: new connection from 127.0.0.1:59252 253s app_test.go:1202: 09:51:03.862 - 62: DEBUG: new connection from 127.0.0.1:54554 253s app_test.go:1202: 09:51:03.864 - 63: DEBUG: new connection from 127.0.0.1:42096 253s app_test.go:1202: 09:51:03.866 - 64: DEBUG: new connection from 127.0.0.1:43244 253s app_test.go:1202: 09:51:03.869 - 59: DEBUG: new connection from 127.0.0.1:35476 253s app_test.go:1202: 09:51:03.871 - 60: DEBUG: new connection from 127.0.0.1:59264 253s app_test.go:1202: 09:51:03.873 - 62: DEBUG: new connection from 127.0.0.1:54566 253s app_test.go:1202: 09:51:03.876 - 63: DEBUG: new connection from 127.0.0.1:42108 253s app_test.go:1202: 09:51:03.879 - 64: DEBUG: new connection from 127.0.0.1:43254 257s app_test.go:1202: 09:51:03.465 - 59: DEBUG: new connection from 127.0.0.1:39488 257s app_test.go:1202: 09:51:03.468 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 257s app_test.go:1202: 09:51:03.602 - 59: DEBUG: new connection from 127.0.0.1:39502 257s app_test.go:1202: 09:51:03.605 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 257s app_test.go:1202: 09:51:03.674 - 59: DEBUG: new connection from 127.0.0.1:39504 257s app_test.go:1202: 09:51:03.677 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 257s app_test.go:1202: 09:51:03.747 - 59: DEBUG: new connection from 127.0.0.1:39512 257s app_test.go:1202: 09:51:03.749 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 257s app_test.go:1202: 09:51:03.882 - 59: DEBUG: new connection from 127.0.0.1:39524 257s app_test.go:1202: 09:51:03.884 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 257s app_test.go:1202: 09:51:03.885 - 59: DEBUG: new connection from 127.0.0.1:39536 257s app_test.go:1202: 09:51:03.888 - 60: DEBUG: new connection from 127.0.0.1:33570 257s app_test.go:1202: 09:51:03.890 - 62: DEBUG: new connection from 127.0.0.1:55022 257s app_test.go:1202: 09:51:03.892 - 63: DEBUG: new connection from 127.0.0.1:34784 257s app_test.go:1202: 09:51:03.894 - 64: DEBUG: new connection from 127.0.0.1:47412 261s app_test.go:1202: 09:51:03.470 - 59: DEBUG: new connection from 127.0.0.1:39540 261s app_test.go:1202: 09:51:03.473 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 261s app_test.go:1202: 09:51:03.608 - 59: DEBUG: new connection from 127.0.0.1:39554 261s app_test.go:1202: 09:51:03.612 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 261s app_test.go:1202: 09:51:03.680 - 59: DEBUG: new connection from 127.0.0.1:39556 261s app_test.go:1202: 09:51:03.682 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 261s app_test.go:1202: 09:51:03.752 - 59: DEBUG: new connection from 127.0.0.1:39560 261s app_test.go:1202: 09:51:03.754 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 261s app_test.go:1202: 09:51:03.897 - 59: DEBUG: new connection from 127.0.0.1:39564 261s app_test.go:1202: 09:51:03.899 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 261s app_test.go:1202: 09:51:03.901 - 59: DEBUG: new connection from 127.0.0.1:39580 261s app_test.go:1202: 09:51:03.904 - 60: DEBUG: new connection from 127.0.0.1:33580 261s app_test.go:1202: 09:51:03.906 - 62: DEBUG: new connection from 127.0.0.1:55030 261s app_test.go:1202: 09:51:03.908 - 63: DEBUG: new connection from 127.0.0.1:34800 261s app_test.go:1202: 09:51:03.910 - 64: DEBUG: new connection from 127.0.0.1:47422 265s app_test.go:1202: 09:51:03.475 - 59: DEBUG: new connection from 127.0.0.1:58484 265s app_test.go:1202: 09:51:03.478 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 265s app_test.go:1202: 09:51:03.615 - 59: DEBUG: new connection from 127.0.0.1:58496 265s app_test.go:1202: 09:51:03.617 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 265s app_test.go:1202: 09:51:03.686 - 59: DEBUG: new connection from 127.0.0.1:58512 265s app_test.go:1202: 09:51:03.688 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 265s app_test.go:1202: 09:51:03.756 - 59: DEBUG: new connection from 127.0.0.1:58518 265s app_test.go:1202: 09:51:03.758 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 265s app_test.go:1202: 09:51:03.913 - 59: DEBUG: new connection from 127.0.0.1:58528 265s app_test.go:1202: 09:51:03.917 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 265s app_test.go:1202: 09:51:03.918 - 59: DEBUG: new connection from 127.0.0.1:58530 265s app_test.go:1202: 09:51:03.920 - 60: DEBUG: new connection from 127.0.0.1:54782 265s app_test.go:1202: 09:51:03.923 - 62: DEBUG: new connection from 127.0.0.1:33632 265s app_test.go:1202: 09:51:03.925 - 63: DEBUG: new connection from 127.0.0.1:40542 265s app_test.go:1202: 09:51:03.927 - 64: DEBUG: new connection from 127.0.0.1:40372 267s app_test.go:1202: 09:51:03.802 - 59: DEBUG: new connection from 127.0.0.1:58536 267s app_test.go:1202: 09:51:03.804 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 268s --- PASS: TestRolesAdjustment_ReplaceVoterHonorFailureDomain (18.73s) 268s === RUN TestRolesAdjustment_ImbalancedFailureDomain 268s app_test.go:1202: 09:51:03.133 - 65: DEBUG: new connection from 127.0.0.1:58550 268s app_test.go:1202: 09:51:03.135 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 268s app_test.go:1202: 09:51:03.136 - 65: DEBUG: new connection from 127.0.0.1:58558 268s app_test.go:1202: 09:51:03.201 - 65: DEBUG: new connection from 127.0.0.1:58574 268s app_test.go:1202: 09:51:03.203 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 268s app_test.go:1202: 09:51:03.206 - 65: DEBUG: new connection from 127.0.0.1:58576 268s app_test.go:1202: 09:51:03.208 - 66: DEBUG: new connection from 127.0.0.1:54792 268s app_test.go:1202: 09:51:03.272 - 65: DEBUG: new connection from 127.0.0.1:58584 268s app_test.go:1202: 09:51:03.275 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 268s app_test.go:1202: 09:51:03.276 - 65: DEBUG: new connection from 127.0.0.1:58600 268s app_test.go:1202: 09:51:03.279 - 66: DEBUG: new connection from 127.0.0.1:54796 268s app_test.go:1202: 09:51:03.281 - 67: DEBUG: new connection from 127.0.0.1:36104 268s app_test.go:1202: 09:51:03.284 - 67: DEBUG: new connection from 127.0.0.1:36116 268s app_test.go:1202: 09:51:03.286 - 65: DEBUG: new connection from 127.0.0.1:58614 268s app_test.go:1202: 09:51:03.291 - 66: DEBUG: new connection from 127.0.0.1:54810 268s app_test.go:1202: 09:51:03.294 - 65: DEBUG: new connection from 127.0.0.1:58620 268s app_test.go:1202: 09:51:03.352 - 65: DEBUG: new connection from 127.0.0.1:58628 268s app_test.go:1202: 09:51:03.354 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 268s app_test.go:1202: 09:51:03.355 - 65: DEBUG: new connection from 127.0.0.1:58632 268s app_test.go:1202: 09:51:03.357 - 66: DEBUG: new connection from 127.0.0.1:54820 268s app_test.go:1202: 09:51:03.360 - 67: DEBUG: new connection from 127.0.0.1:36118 268s app_test.go:1202: 09:51:03.362 - 68: DEBUG: new connection from 127.0.0.1:33638 268s app_test.go:1202: 09:51:03.364 - 68: DEBUG: new connection from 127.0.0.1:33640 268s app_test.go:1202: 09:51:03.368 - 65: DEBUG: new connection from 127.0.0.1:58642 268s app_test.go:1202: 09:51:03.431 - 65: DEBUG: new connection from 127.0.0.1:58646 268s app_test.go:1202: 09:51:03.433 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 268s app_test.go:1202: 09:51:03.435 - 65: DEBUG: new connection from 127.0.0.1:58654 268s app_test.go:1202: 09:51:03.438 - 66: DEBUG: new connection from 127.0.0.1:54822 268s app_test.go:1202: 09:51:03.441 - 67: DEBUG: new connection from 127.0.0.1:36132 268s app_test.go:1202: 09:51:03.443 - 68: DEBUG: new connection from 127.0.0.1:33652 268s app_test.go:1202: 09:51:03.445 - 69: DEBUG: new connection from 127.0.0.1:40552 268s app_test.go:1202: 09:51:03.448 - 69: DEBUG: new connection from 127.0.0.1:40562 268s app_test.go:1202: 09:51:03.453 - 65: DEBUG: new connection from 127.0.0.1:58658 268s app_test.go:1202: 09:51:03.502 - 65: DEBUG: new connection from 127.0.0.1:58672 268s app_test.go:1202: 09:51:03.504 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 268s app_test.go:1202: 09:51:03.505 - 65: DEBUG: new connection from 127.0.0.1:58684 268s app_test.go:1202: 09:51:03.507 - 66: DEBUG: new connection from 127.0.0.1:54826 268s app_test.go:1202: 09:51:03.510 - 67: DEBUG: new connection from 127.0.0.1:36138 268s app_test.go:1202: 09:51:03.511 - 68: DEBUG: new connection from 127.0.0.1:33658 268s app_test.go:1202: 09:51:03.514 - 69: DEBUG: new connection from 127.0.0.1:40566 268s app_test.go:1202: 09:51:03.516 - 70: DEBUG: new connection from 127.0.0.1:40402 268s app_test.go:1202: 09:51:03.519 - 70: DEBUG: new connection from 127.0.0.1:40416 268s app_test.go:1202: 09:51:03.524 - 65: DEBUG: new connection from 127.0.0.1:58700 268s app_test.go:1202: 09:51:03.571 - 65: DEBUG: new connection from 127.0.0.1:58708 268s app_test.go:1202: 09:51:03.573 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 268s app_test.go:1202: 09:51:03.575 - 65: DEBUG: new connection from 127.0.0.1:58712 268s app_test.go:1202: 09:51:03.578 - 66: DEBUG: new connection from 127.0.0.1:54836 268s app_test.go:1202: 09:51:03.580 - 67: DEBUG: new connection from 127.0.0.1:36152 268s app_test.go:1202: 09:51:03.583 - 68: DEBUG: new connection from 127.0.0.1:33672 268s app_test.go:1202: 09:51:03.585 - 69: DEBUG: new connection from 127.0.0.1:40582 268s app_test.go:1202: 09:51:03.588 - 70: DEBUG: new connection from 127.0.0.1:40420 268s app_test.go:1202: 09:51:03.591 - 71: DEBUG: new connection from 127.0.0.1:46334 268s app_test.go:1202: 09:51:03.652 - 65: DEBUG: new connection from 127.0.0.1:58726 268s app_test.go:1202: 09:51:03.654 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 268s app_test.go:1202: 09:51:03.655 - 65: DEBUG: new connection from 127.0.0.1:58730 268s app_test.go:1202: 09:51:03.658 - 66: DEBUG: new connection from 127.0.0.1:54838 268s app_test.go:1202: 09:51:03.661 - 67: DEBUG: new connection from 127.0.0.1:36168 268s app_test.go:1202: 09:51:03.664 - 68: DEBUG: new connection from 127.0.0.1:33684 268s app_test.go:1202: 09:51:03.666 - 69: DEBUG: new connection from 127.0.0.1:40592 268s app_test.go:1202: 09:51:03.668 - 70: DEBUG: new connection from 127.0.0.1:40424 268s app_test.go:1202: 09:51:03.670 - 71: DEBUG: new connection from 127.0.0.1:46338 268s app_test.go:1202: 09:51:03.672 - 72: DEBUG: new connection from 127.0.0.1:39030 272s app_test.go:1202: 09:51:03.139 - 65: DEBUG: new connection from 127.0.0.1:58744 272s app_test.go:1202: 09:51:03.142 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 272s app_test.go:1202: 09:51:03.144 - 65: DEBUG: new connection from 127.0.0.1:58752 272s app_test.go:1202: 09:51:03.147 - 66: DEBUG: new connection from 127.0.0.1:54854 272s app_test.go:1202: 09:51:03.149 - 67: DEBUG: new connection from 127.0.0.1:36180 272s app_test.go:1202: 09:51:03.151 - 68: DEBUG: new connection from 127.0.0.1:33700 272s app_test.go:1202: 09:51:03.153 - 69: DEBUG: new connection from 127.0.0.1:40604 272s app_test.go:1202: 09:51:03.155 - 70: DEBUG: new connection from 127.0.0.1:40440 272s app_test.go:1202: 09:51:03.157 - 71: DEBUG: new connection from 127.0.0.1:46342 272s app_test.go:1202: 09:51:03.160 - 72: DEBUG: new connection from 127.0.0.1:39032 272s app_test.go:1202: 09:51:03.162 - 72: DEBUG: new connection from 127.0.0.1:39038 272s app_test.go:1202: 09:51:03.166 - 65: DEBUG: new connection from 127.0.0.1:58754 272s app_test.go:1202: 09:51:03.210 - 65: DEBUG: new connection from 127.0.0.1:58756 272s app_test.go:1202: 09:51:03.212 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 272s app_test.go:1202: 09:51:03.299 - 65: DEBUG: new connection from 127.0.0.1:58772 272s app_test.go:1202: 09:51:03.301 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 272s app_test.go:1202: 09:51:03.367 - 65: DEBUG: new connection from 127.0.0.1:58778 272s app_test.go:1202: 09:51:03.369 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 272s app_test.go:1202: 09:51:03.451 - 65: DEBUG: new connection from 127.0.0.1:58784 272s app_test.go:1202: 09:51:03.453 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 272s app_test.go:1202: 09:51:03.523 - 65: DEBUG: new connection from 127.0.0.1:58792 272s app_test.go:1202: 09:51:03.525 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 272s app_test.go:1202: 09:51:03.578 - 65: DEBUG: new connection from 127.0.0.1:58798 272s app_test.go:1202: 09:51:03.581 - 66: DEBUG: new connection from 127.0.0.1:54858 272s app_test.go:1202: 09:51:03.583 - 67: DEBUG: new connection from 127.0.0.1:36190 272s app_test.go:1202: 09:51:03.586 - 68: DEBUG: new connection from 127.0.0.1:33712 272s app_test.go:1202: 09:51:03.588 - 69: DEBUG: new connection from 127.0.0.1:40620 272s app_test.go:1202: 09:51:03.590 - 70: DEBUG: new connection from 127.0.0.1:40454 272s app_test.go:1202: 09:51:03.593 - 71: DEBUG: new connection from 127.0.0.1:46344 272s app_test.go:1202: 09:51:03.594 - 65: DEBUG: new connection from 127.0.0.1:58802 272s app_test.go:1202: 09:51:03.595 - 72: DEBUG: new connection from 127.0.0.1:39044 272s app_test.go:1202: 09:51:03.599 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 272s app_test.go:1202: 09:51:03.600 - 65: DEBUG: new connection from 127.0.0.1:58818 272s app_test.go:1202: 09:51:03.605 - 66: DEBUG: new connection from 127.0.0.1:54860 272s app_test.go:1202: 09:51:03.609 - 67: DEBUG: new connection from 127.0.0.1:36204 272s app_test.go:1202: 09:51:03.612 - 68: DEBUG: new connection from 127.0.0.1:33722 272s app_test.go:1202: 09:51:03.615 - 69: DEBUG: new connection from 127.0.0.1:40628 272s app_test.go:1202: 09:51:03.617 - 70: DEBUG: new connection from 127.0.0.1:40468 272s app_test.go:1202: 09:51:03.620 - 71: DEBUG: new connection from 127.0.0.1:46352 272s app_test.go:1202: 09:51:03.622 - 72: DEBUG: new connection from 127.0.0.1:39046 272s app_test.go:1202: 09:51:03.675 - 65: DEBUG: new connection from 127.0.0.1:58828 272s app_test.go:1202: 09:51:03.678 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 276s app_test.go:1202: 09:51:03.215 - 65: DEBUG: new connection from 127.0.0.1:41782 276s app_test.go:1202: 09:51:03.218 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 276s app_test.go:1202: 09:51:03.303 - 65: DEBUG: new connection from 127.0.0.1:41796 276s app_test.go:1202: 09:51:03.306 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 276s app_test.go:1202: 09:51:03.372 - 65: DEBUG: new connection from 127.0.0.1:41806 276s app_test.go:1202: 09:51:03.374 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 276s app_test.go:1202: 09:51:03.455 - 65: DEBUG: new connection from 127.0.0.1:41816 276s app_test.go:1202: 09:51:03.458 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 276s app_test.go:1202: 09:51:03.527 - 65: DEBUG: new connection from 127.0.0.1:41820 276s app_test.go:1202: 09:51:03.529 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 276s app_test.go:1202: 09:51:03.609 - 65: DEBUG: new connection from 127.0.0.1:41826 276s app_test.go:1202: 09:51:03.611 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 276s app_test.go:1202: 09:51:03.626 - 65: DEBUG: new connection from 127.0.0.1:41836 276s app_test.go:1202: 09:51:03.628 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 276s app_test.go:1202: 09:51:03.630 - 65: DEBUG: new connection from 127.0.0.1:41842 276s app_test.go:1202: 09:51:03.633 - 66: DEBUG: new connection from 127.0.0.1:37030 276s app_test.go:1202: 09:51:03.636 - 67: DEBUG: new connection from 127.0.0.1:42018 276s app_test.go:1202: 09:51:03.638 - 68: DEBUG: new connection from 127.0.0.1:40390 276s app_test.go:1202: 09:51:03.641 - 69: DEBUG: new connection from 127.0.0.1:48972 276s app_test.go:1202: 09:51:03.643 - 70: DEBUG: new connection from 127.0.0.1:48198 276s app_test.go:1202: 09:51:03.645 - 71: DEBUG: new connection from 127.0.0.1:46832 276s app_test.go:1202: 09:51:03.647 - 72: DEBUG: new connection from 127.0.0.1:51652 276s app_test.go:1202: 09:51:03.680 - 65: DEBUG: new connection from 127.0.0.1:41844 276s app_test.go:1202: 09:51:03.683 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 278s app_test.go:1202: 09:51:03.652 - 67: DEBUG: new connection from 127.0.0.1:42020 278s app_test.go:1202: 09:51:03.655 - 72: DEBUG: new connection from 127.0.0.1:51660 278s app_test.go:1202: 09:51:03.657 - 66: DEBUG: new connection from 127.0.0.1:37046 278s app_test.go:1202: 09:51:03.658 - 66: DEBUG: new connection from 127.0.0.1:37056 280s app_test.go:1202: 09:51:03.221 - 65: DEBUG: new connection from 127.0.0.1:41846 280s app_test.go:1202: 09:51:03.223 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 280s app_test.go:1202: 09:51:03.309 - 65: DEBUG: new connection from 127.0.0.1:41848 280s app_test.go:1202: 09:51:03.311 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 280s app_test.go:1202: 09:51:03.377 - 65: DEBUG: new connection from 127.0.0.1:41862 280s app_test.go:1202: 09:51:03.380 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 280s app_test.go:1202: 09:51:03.460 - 65: DEBUG: new connection from 127.0.0.1:41876 280s app_test.go:1202: 09:51:03.462 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 280s app_test.go:1202: 09:51:03.532 - 65: DEBUG: new connection from 127.0.0.1:41886 280s app_test.go:1202: 09:51:03.534 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 280s app_test.go:1202: 09:51:03.613 - 65: DEBUG: new connection from 127.0.0.1:41900 280s app_test.go:1202: 09:51:03.615 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 280s app_test.go:1202: 09:51:03.649 - 65: DEBUG: new connection from 127.0.0.1:41916 280s app_test.go:1202: 09:51:03.651 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 280s app_test.go:1202: 09:51:03.653 - 65: DEBUG: new connection from 127.0.0.1:41926 280s app_test.go:1202: 09:51:03.655 - 66: DEBUG: new connection from 127.0.0.1:37062 280s app_test.go:1202: 09:51:03.657 - 67: DEBUG: new connection from 127.0.0.1:42036 280s app_test.go:1202: 09:51:03.659 - 68: DEBUG: new connection from 127.0.0.1:40402 280s app_test.go:1202: 09:51:03.661 - 69: DEBUG: new connection from 127.0.0.1:48976 280s app_test.go:1202: 09:51:03.664 - 70: DEBUG: new connection from 127.0.0.1:48202 280s app_test.go:1202: 09:51:03.666 - 71: DEBUG: new connection from 127.0.0.1:46834 280s app_test.go:1202: 09:51:03.668 - 72: DEBUG: new connection from 127.0.0.1:51668 280s app_test.go:1202: 09:51:03.684 - 65: DEBUG: new connection from 127.0.0.1:41938 280s app_test.go:1202: 09:51:03.686 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1202: 09:51:03.226 - 65: DEBUG: new connection from 127.0.0.1:48274 284s app_test.go:1202: 09:51:03.229 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1202: 09:51:03.313 - 65: DEBUG: new connection from 127.0.0.1:48276 284s app_test.go:1202: 09:51:03.316 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1202: 09:51:03.383 - 65: DEBUG: new connection from 127.0.0.1:48278 284s app_test.go:1202: 09:51:03.385 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1202: 09:51:03.468 - 65: DEBUG: new connection from 127.0.0.1:48288 284s app_test.go:1202: 09:51:03.470 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1202: 09:51:03.536 - 65: DEBUG: new connection from 127.0.0.1:48294 284s app_test.go:1202: 09:51:03.538 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1202: 09:51:03.617 - 65: DEBUG: new connection from 127.0.0.1:48308 284s app_test.go:1202: 09:51:03.621 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1202: 09:51:03.671 - 65: DEBUG: new connection from 127.0.0.1:48314 284s app_test.go:1202: 09:51:03.673 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1202: 09:51:03.675 - 65: DEBUG: new connection from 127.0.0.1:48316 284s app_test.go:1202: 09:51:03.677 - 66: DEBUG: new connection from 127.0.0.1:46518 284s app_test.go:1202: 09:51:03.679 - 67: DEBUG: new connection from 127.0.0.1:39714 284s app_test.go:1202: 09:51:03.681 - 68: DEBUG: new connection from 127.0.0.1:55650 284s app_test.go:1202: 09:51:03.684 - 69: DEBUG: new connection from 127.0.0.1:55270 284s app_test.go:1202: 09:51:03.686 - 70: DEBUG: new connection from 127.0.0.1:56980 284s app_test.go:1202: 09:51:03.688 - 71: DEBUG: new connection from 127.0.0.1:46058 284s app_test.go:1202: 09:51:03.690 - 65: DEBUG: new connection from 127.0.0.1:48330 284s app_test.go:1202: 09:51:03.692 - 72: DEBUG: new connection from 127.0.0.1:56566 284s app_test.go:1202: 09:51:03.694 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s app_test.go:1202: 09:51:03.676 - 65: DEBUG: new connection from 127.0.0.1:48344 286s app_test.go:1202: 09:51:03.679 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s --- PASS: TestRolesAdjustment_ImbalancedFailureDomain (19.01s) 287s === RUN TestRolesAdjustment_ReplaceVoterHonorWeight 287s app_test.go:1202: 09:51:03.143 - 73: DEBUG: new connection from 127.0.0.1:48356 287s app_test.go:1202: 09:51:03.146 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1202: 09:51:03.147 - 73: DEBUG: new connection from 127.0.0.1:48370 287s app_test.go:1202: 09:51:03.212 - 73: DEBUG: new connection from 127.0.0.1:48382 287s app_test.go:1202: 09:51:03.214 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1202: 09:51:03.217 - 73: DEBUG: new connection from 127.0.0.1:48394 287s app_test.go:1202: 09:51:03.219 - 74: DEBUG: new connection from 127.0.0.1:46532 287s app_test.go:1202: 09:51:03.281 - 73: DEBUG: new connection from 127.0.0.1:48402 287s app_test.go:1202: 09:51:03.284 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1202: 09:51:03.285 - 73: DEBUG: new connection from 127.0.0.1:48412 287s app_test.go:1202: 09:51:03.287 - 74: DEBUG: new connection from 127.0.0.1:46542 287s app_test.go:1202: 09:51:03.290 - 75: DEBUG: new connection from 127.0.0.1:39724 287s app_test.go:1202: 09:51:03.293 - 75: DEBUG: new connection from 127.0.0.1:39730 287s app_test.go:1202: 09:51:03.297 - 73: DEBUG: new connection from 127.0.0.1:48428 287s app_test.go:1202: 09:51:03.301 - 74: DEBUG: new connection from 127.0.0.1:46552 287s app_test.go:1202: 09:51:03.304 - 73: DEBUG: new connection from 127.0.0.1:48432 287s app_test.go:1202: 09:51:03.361 - 73: DEBUG: new connection from 127.0.0.1:48438 287s app_test.go:1202: 09:51:03.363 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1202: 09:51:03.365 - 73: DEBUG: new connection from 127.0.0.1:48440 287s app_test.go:1202: 09:51:03.367 - 74: DEBUG: new connection from 127.0.0.1:46568 287s app_test.go:1202: 09:51:03.370 - 75: DEBUG: new connection from 127.0.0.1:39740 287s app_test.go:1202: 09:51:03.372 - 76: DEBUG: new connection from 127.0.0.1:55660 287s app_test.go:1202: 09:51:03.374 - 76: DEBUG: new connection from 127.0.0.1:55666 287s app_test.go:1202: 09:51:03.379 - 73: DEBUG: new connection from 127.0.0.1:48456 287s app_test.go:1202: 09:51:03.442 - 73: DEBUG: new connection from 127.0.0.1:48464 287s app_test.go:1202: 09:51:03.444 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1202: 09:51:03.445 - 73: DEBUG: new connection from 127.0.0.1:48468 287s app_test.go:1202: 09:51:03.448 - 74: DEBUG: new connection from 127.0.0.1:46584 287s app_test.go:1202: 09:51:03.452 - 75: DEBUG: new connection from 127.0.0.1:39756 287s app_test.go:1202: 09:51:03.456 - 76: DEBUG: new connection from 127.0.0.1:55674 287s app_test.go:1202: 09:51:03.459 - 77: DEBUG: new connection from 127.0.0.1:55272 287s app_test.go:1202: 09:51:03.462 - 77: DEBUG: new connection from 127.0.0.1:55282 287s app_test.go:1202: 09:51:03.466 - 73: DEBUG: new connection from 127.0.0.1:48472 287s app_test.go:1202: 09:51:03.512 - 73: DEBUG: new connection from 127.0.0.1:48486 287s app_test.go:1202: 09:51:03.514 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1202: 09:51:03.515 - 73: DEBUG: new connection from 127.0.0.1:48492 287s app_test.go:1202: 09:51:03.518 - 74: DEBUG: new connection from 127.0.0.1:46590 287s app_test.go:1202: 09:51:03.520 - 75: DEBUG: new connection from 127.0.0.1:39760 287s app_test.go:1202: 09:51:03.522 - 76: DEBUG: new connection from 127.0.0.1:55682 287s app_test.go:1202: 09:51:03.524 - 77: DEBUG: new connection from 127.0.0.1:55290 287s app_test.go:1202: 09:51:03.526 - 78: DEBUG: new connection from 127.0.0.1:56992 287s app_test.go:1202: 09:51:03.529 - 78: DEBUG: new connection from 127.0.0.1:56996 287s app_test.go:1202: 09:51:03.536 - 73: DEBUG: new connection from 127.0.0.1:48506 291s app_test.go:1202: 09:52:03.151 - 73: DEBUG: new connection from 127.0.0.1:48516 291s app_test.go:1202: 09:52:03.153 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 291s app_test.go:1202: 09:52:03.155 - 73: DEBUG: new connection from 127.0.0.1:48532 291s app_test.go:1202: 09:52:03.158 - 74: DEBUG: new connection from 127.0.0.1:46606 291s app_test.go:1202: 09:52:03.160 - 76: DEBUG: new connection from 127.0.0.1:55690 291s app_test.go:1202: 09:52:03.162 - 77: DEBUG: new connection from 127.0.0.1:55292 291s app_test.go:1202: 09:52:03.164 - 78: DEBUG: new connection from 127.0.0.1:57000 291s app_test.go:1202: 09:52:03.221 - 73: DEBUG: new connection from 127.0.0.1:48546 291s app_test.go:1202: 09:52:03.223 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 291s app_test.go:1202: 09:52:03.378 - 73: DEBUG: new connection from 127.0.0.1:48560 291s app_test.go:1202: 09:52:03.381 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 291s app_test.go:1202: 09:52:03.465 - 73: DEBUG: new connection from 127.0.0.1:48564 291s app_test.go:1202: 09:52:03.467 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 291s app_test.go:1202: 09:52:03.534 - 73: DEBUG: new connection from 127.0.0.1:48580 291s app_test.go:1202: 09:52:03.536 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 291s app_test.go:1202: 09:52:03.597 - 73: DEBUG: new connection from 127.0.0.1:48584 291s app_test.go:1202: 09:52:03.599 - 74: DEBUG: new connection from 127.0.0.1:46610 291s app_test.go:1202: 09:52:03.602 - 76: DEBUG: new connection from 127.0.0.1:55694 291s app_test.go:1202: 09:52:03.605 - 77: DEBUG: new connection from 127.0.0.1:55298 291s app_test.go:1202: 09:52:03.608 - 78: DEBUG: new connection from 127.0.0.1:57004 291s app_test.go:1202: 09:52:03.611 - 73: DEBUG: new connection from 127.0.0.1:48586 291s app_test.go:1202: 09:52:03.613 - 74: DEBUG: new connection from 127.0.0.1:46624 291s app_test.go:1202: 09:52:03.615 - 76: DEBUG: new connection from 127.0.0.1:55698 291s app_test.go:1202: 09:52:03.617 - 77: DEBUG: new connection from 127.0.0.1:55300 291s app_test.go:1202: 09:52:03.619 - 78: DEBUG: new connection from 127.0.0.1:57012 295s app_test.go:1202: 09:52:03.226 - 73: DEBUG: new connection from 127.0.0.1:57646 295s app_test.go:1202: 09:52:03.228 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 295s app_test.go:1202: 09:52:03.384 - 73: DEBUG: new connection from 127.0.0.1:57662 295s app_test.go:1202: 09:52:03.386 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 295s app_test.go:1202: 09:52:03.469 - 73: DEBUG: new connection from 127.0.0.1:57674 295s app_test.go:1202: 09:52:03.471 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 295s app_test.go:1202: 09:52:03.539 - 73: DEBUG: new connection from 127.0.0.1:57688 295s app_test.go:1202: 09:52:03.541 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 295s app_test.go:1202: 09:52:03.623 - 73: DEBUG: new connection from 127.0.0.1:57698 295s app_test.go:1202: 09:52:03.625 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 295s app_test.go:1202: 09:52:03.626 - 73: DEBUG: new connection from 127.0.0.1:57714 295s app_test.go:1202: 09:52:03.628 - 74: DEBUG: new connection from 127.0.0.1:48188 295s app_test.go:1202: 09:52:03.632 - 76: DEBUG: new connection from 127.0.0.1:43894 295s app_test.go:1202: 09:52:03.635 - 77: DEBUG: new connection from 127.0.0.1:52382 295s app_test.go:1202: 09:52:03.637 - 78: DEBUG: new connection from 127.0.0.1:47372 299s app_test.go:1202: 09:52:03.231 - 73: DEBUG: new connection from 127.0.0.1:57728 299s app_test.go:1202: 09:52:03.234 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s app_test.go:1202: 09:52:03.389 - 73: DEBUG: new connection from 127.0.0.1:57742 299s app_test.go:1202: 09:52:03.391 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s app_test.go:1202: 09:52:03.473 - 73: DEBUG: new connection from 127.0.0.1:57750 299s app_test.go:1202: 09:52:03.475 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s app_test.go:1202: 09:52:03.543 - 73: DEBUG: new connection from 127.0.0.1:57756 299s app_test.go:1202: 09:52:03.546 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s app_test.go:1202: 09:52:03.640 - 73: DEBUG: new connection from 127.0.0.1:57762 299s app_test.go:1202: 09:52:03.642 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s app_test.go:1202: 09:52:03.644 - 73: DEBUG: new connection from 127.0.0.1:57770 299s app_test.go:1202: 09:52:03.646 - 74: DEBUG: new connection from 127.0.0.1:48192 299s app_test.go:1202: 09:52:03.648 - 76: DEBUG: new connection from 127.0.0.1:43906 299s app_test.go:1202: 09:52:03.650 - 77: DEBUG: new connection from 127.0.0.1:52398 299s app_test.go:1202: 09:52:03.653 - 78: DEBUG: new connection from 127.0.0.1:47386 303s app_test.go:1202: 09:52:03.237 - 73: DEBUG: new connection from 127.0.0.1:57780 303s app_test.go:1202: 09:52:03.240 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 303s app_test.go:1202: 09:52:03.393 - 73: DEBUG: new connection from 127.0.0.1:57788 303s app_test.go:1202: 09:52:03.395 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 303s app_test.go:1202: 09:52:03.477 - 73: DEBUG: new connection from 127.0.0.1:57798 303s app_test.go:1202: 09:52:03.481 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 303s app_test.go:1202: 09:52:03.548 - 73: DEBUG: new connection from 127.0.0.1:57804 303s app_test.go:1202: 09:52:03.549 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 303s app_test.go:1202: 09:52:03.657 - 73: DEBUG: new connection from 127.0.0.1:57814 303s app_test.go:1202: 09:52:03.659 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 303s app_test.go:1202: 09:52:03.660 - 73: DEBUG: new connection from 127.0.0.1:57818 303s app_test.go:1202: 09:52:03.662 - 74: DEBUG: new connection from 127.0.0.1:48194 303s app_test.go:1202: 09:52:03.664 - 76: DEBUG: new connection from 127.0.0.1:43912 303s app_test.go:1202: 09:52:03.666 - 77: DEBUG: new connection from 127.0.0.1:52414 303s app_test.go:1202: 09:52:03.669 - 78: DEBUG: new connection from 127.0.0.1:47400 305s app_test.go:1202: 09:52:03.586 - 73: DEBUG: new connection from 127.0.0.1:55242 305s app_test.go:1202: 09:52:03.589 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 305s --- PASS: TestRolesAdjustment_ReplaceVoterHonorWeight (18.79s) 305s === RUN TestRolesAdjustment_CantReplaceVoter 305s app_test.go:1202: 09:52:03.943 - 79: DEBUG: new connection from 127.0.0.1:55254 305s app_test.go:1202: 09:52:03.945 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 305s app_test.go:1202: 09:52:03.946 - 79: DEBUG: new connection from 127.0.0.1:55256 306s app_test.go:1202: 09:52:03.012 - 79: DEBUG: new connection from 127.0.0.1:55264 306s app_test.go:1202: 09:52:03.014 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 306s app_test.go:1202: 09:52:03.016 - 79: DEBUG: new connection from 127.0.0.1:55278 306s app_test.go:1202: 09:52:03.019 - 80: DEBUG: new connection from 127.0.0.1:60728 306s app_test.go:1202: 09:52:03.071 - 79: DEBUG: new connection from 127.0.0.1:55292 306s app_test.go:1202: 09:52:03.073 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected 306s app_test.go:1202: 09:52:03.075 - 79: DEBUG: new connection from 127.0.0.1:55300 306s app_test.go:1202: 09:52:03.077 - 80: DEBUG: new connection from 127.0.0.1:60740 306s app_test.go:1202: 09:52:03.079 - 81: DEBUG: new connection from 127.0.0.1:32768 306s app_test.go:1202: 09:52:03.082 - 81: DEBUG: new connection from 127.0.0.1:32776 306s app_test.go:1202: 09:52:03.085 - 79: DEBUG: new connection from 127.0.0.1:55304 306s app_test.go:1202: 09:52:03.089 - 80: DEBUG: new connection from 127.0.0.1:60756 306s app_test.go:1202: 09:52:03.092 - 79: DEBUG: new connection from 127.0.0.1:55312 306s app_test.go:1202: 09:52:03.142 - 79: DEBUG: new connection from 127.0.0.1:55322 306s app_test.go:1202: 09:52:03.144 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected 306s app_test.go:1202: 09:52:03.145 - 79: DEBUG: new connection from 127.0.0.1:55338 306s app_test.go:1202: 09:52:03.147 - 80: DEBUG: new connection from 127.0.0.1:60764 306s app_test.go:1202: 09:52:03.149 - 81: DEBUG: new connection from 127.0.0.1:32786 306s app_test.go:1202: 09:52:03.151 - 82: DEBUG: new connection from 127.0.0.1:41528 306s app_test.go:1202: 09:52:03.154 - 82: DEBUG: new connection from 127.0.0.1:41534 309s app_test.go:1202: 09:52:03.950 - 79: DEBUG: new connection from 127.0.0.1:55352 309s app_test.go:1202: 09:52:03.954 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 309s app_test.go:1202: 09:52:03.956 - 79: DEBUG: new connection from 127.0.0.1:55368 309s app_test.go:1202: 09:52:03.958 - 80: DEBUG: new connection from 127.0.0.1:60772 310s app_test.go:1202: 09:52:03.023 - 79: DEBUG: new connection from 127.0.0.1:55370 310s app_test.go:1202: 09:52:03.025 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 313s app_test.go:1202: 09:52:03.962 - 79: DEBUG: new connection from 127.0.0.1:53832 313s app_test.go:1202: 09:52:03.964 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 313s app_test.go:1202: 09:52:03.967 - 79: DEBUG: new connection from 127.0.0.1:53836 314s app_test.go:1202: 09:52:03.969 - 80: DEBUG: new connection from 127.0.0.1:47560 314s app_test.go:1202: 09:52:03.028 - 79: DEBUG: new connection from 127.0.0.1:53850 314s app_test.go:1202: 09:52:03.030 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1202: 09:52:03.972 - 79: DEBUG: new connection from 127.0.0.1:53852 318s app_test.go:1202: 09:52:03.974 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1202: 09:52:03.976 - 79: DEBUG: new connection from 127.0.0.1:53866 318s app_test.go:1202: 09:52:03.978 - 80: DEBUG: new connection from 127.0.0.1:47576 318s app_test.go:1202: 09:52:03.032 - 79: DEBUG: new connection from 127.0.0.1:53868 318s app_test.go:1202: 09:52:03.034 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1202: 09:52:03.221 - 79: DEBUG: new connection from 127.0.0.1:53884 318s app_test.go:1202: 09:52:03.223 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s --- PASS: TestRolesAdjustment_CantReplaceVoter (12.48s) 318s === RUN TestRolesAdjustment_ReplaceStandBy 318s app_test.go:1202: 09:52:03.413 - 83: DEBUG: new connection from 127.0.0.1:53888 318s app_test.go:1202: 09:52:03.415 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1202: 09:52:03.417 - 83: DEBUG: new connection from 127.0.0.1:53894 318s app_test.go:1202: 09:52:03.482 - 83: DEBUG: new connection from 127.0.0.1:53900 318s app_test.go:1202: 09:52:03.485 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1202: 09:52:03.488 - 83: DEBUG: new connection from 127.0.0.1:53910 318s app_test.go:1202: 09:52:03.490 - 84: DEBUG: new connection from 127.0.0.1:47578 318s app_test.go:1202: 09:52:03.552 - 83: DEBUG: new connection from 127.0.0.1:53926 318s app_test.go:1202: 09:52:03.554 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1202: 09:52:03.555 - 83: DEBUG: new connection from 127.0.0.1:53942 318s app_test.go:1202: 09:52:03.558 - 84: DEBUG: new connection from 127.0.0.1:47594 318s app_test.go:1202: 09:52:03.560 - 85: DEBUG: new connection from 127.0.0.1:55260 318s app_test.go:1202: 09:52:03.562 - 85: DEBUG: new connection from 127.0.0.1:55262 318s app_test.go:1202: 09:52:03.565 - 83: DEBUG: new connection from 127.0.0.1:53958 318s app_test.go:1202: 09:52:03.569 - 84: DEBUG: new connection from 127.0.0.1:47598 318s app_test.go:1202: 09:52:03.573 - 83: DEBUG: new connection from 127.0.0.1:53960 318s app_test.go:1202: 09:52:03.632 - 83: DEBUG: new connection from 127.0.0.1:53962 318s app_test.go:1202: 09:52:03.634 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1202: 09:52:03.636 - 83: DEBUG: new connection from 127.0.0.1:53964 318s app_test.go:1202: 09:52:03.638 - 84: DEBUG: new connection from 127.0.0.1:47610 318s app_test.go:1202: 09:52:03.640 - 85: DEBUG: new connection from 127.0.0.1:55274 318s app_test.go:1202: 09:52:03.642 - 86: DEBUG: new connection from 127.0.0.1:60154 318s app_test.go:1202: 09:52:03.644 - 86: DEBUG: new connection from 127.0.0.1:60166 318s app_test.go:1202: 09:52:03.652 - 83: DEBUG: new connection from 127.0.0.1:53974 318s app_test.go:1202: 09:52:03.702 - 83: DEBUG: new connection from 127.0.0.1:53990 318s app_test.go:1202: 09:52:03.704 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1202: 09:52:03.705 - 83: DEBUG: new connection from 127.0.0.1:54004 318s app_test.go:1202: 09:52:03.708 - 84: DEBUG: new connection from 127.0.0.1:47626 318s app_test.go:1202: 09:52:03.710 - 85: DEBUG: new connection from 127.0.0.1:55284 318s app_test.go:1202: 09:52:03.712 - 86: DEBUG: new connection from 127.0.0.1:60168 318s app_test.go:1202: 09:52:03.714 - 87: DEBUG: new connection from 127.0.0.1:52546 318s app_test.go:1202: 09:52:03.716 - 87: DEBUG: new connection from 127.0.0.1:52550 318s app_test.go:1202: 09:52:03.721 - 83: DEBUG: new connection from 127.0.0.1:54018 318s app_test.go:1202: 09:52:03.772 - 83: DEBUG: new connection from 127.0.0.1:54026 318s app_test.go:1202: 09:52:03.774 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1202: 09:52:03.776 - 83: DEBUG: new connection from 127.0.0.1:54042 318s app_test.go:1202: 09:52:03.779 - 84: DEBUG: new connection from 127.0.0.1:47638 318s app_test.go:1202: 09:52:03.782 - 85: DEBUG: new connection from 127.0.0.1:55300 318s app_test.go:1202: 09:52:03.785 - 86: DEBUG: new connection from 127.0.0.1:60180 318s app_test.go:1202: 09:52:03.787 - 87: DEBUG: new connection from 127.0.0.1:52558 318s app_test.go:1202: 09:52:03.789 - 88: DEBUG: new connection from 127.0.0.1:33740 318s app_test.go:1202: 09:52:03.791 - 88: DEBUG: new connection from 127.0.0.1:33744 318s app_test.go:1202: 09:52:03.796 - 83: DEBUG: new connection from 127.0.0.1:54052 318s app_test.go:1202: 09:52:03.852 - 83: DEBUG: new connection from 127.0.0.1:54064 318s app_test.go:1202: 09:52:03.854 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1202: 09:52:03.856 - 83: DEBUG: new connection from 127.0.0.1:54066 318s app_test.go:1202: 09:52:03.858 - 84: DEBUG: new connection from 127.0.0.1:47642 318s app_test.go:1202: 09:52:03.860 - 85: DEBUG: new connection from 127.0.0.1:55310 318s app_test.go:1202: 09:52:03.863 - 86: DEBUG: new connection from 127.0.0.1:60182 318s app_test.go:1202: 09:52:03.865 - 87: DEBUG: new connection from 127.0.0.1:52562 318s app_test.go:1202: 09:52:03.867 - 88: DEBUG: new connection from 127.0.0.1:33760 318s app_test.go:1202: 09:52:03.869 - 89: DEBUG: new connection from 127.0.0.1:59366 323s app_test.go:1202: 09:52:03.420 - 83: DEBUG: new connection from 127.0.0.1:54082 323s app_test.go:1202: 09:52:03.425 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 323s app_test.go:1202: 09:52:03.427 - 83: DEBUG: new connection from 127.0.0.1:54086 323s app_test.go:1202: 09:52:03.430 - 84: DEBUG: new connection from 127.0.0.1:47652 323s app_test.go:1202: 09:52:03.432 - 85: DEBUG: new connection from 127.0.0.1:55316 323s app_test.go:1202: 09:52:03.434 - 86: DEBUG: new connection from 127.0.0.1:60192 323s app_test.go:1202: 09:52:03.436 - 88: DEBUG: new connection from 127.0.0.1:33774 323s app_test.go:1202: 09:52:03.438 - 89: DEBUG: new connection from 127.0.0.1:59374 323s app_test.go:1202: 09:52:03.440 - 89: DEBUG: new connection from 127.0.0.1:59390 323s app_test.go:1202: 09:52:03.444 - 83: DEBUG: new connection from 127.0.0.1:54094 323s app_test.go:1202: 09:52:03.493 - 83: DEBUG: new connection from 127.0.0.1:54110 323s app_test.go:1202: 09:52:03.495 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 323s app_test.go:1202: 09:52:03.577 - 83: DEBUG: new connection from 127.0.0.1:54120 323s app_test.go:1202: 09:52:03.579 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 323s app_test.go:1202: 09:52:03.648 - 83: DEBUG: new connection from 127.0.0.1:54136 323s app_test.go:1202: 09:52:03.650 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 323s app_test.go:1202: 09:52:03.794 - 83: DEBUG: new connection from 127.0.0.1:54142 323s app_test.go:1202: 09:52:03.797 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 323s app_test.go:1202: 09:52:03.859 - 83: DEBUG: new connection from 127.0.0.1:54158 323s app_test.go:1202: 09:52:03.862 - 84: DEBUG: new connection from 127.0.0.1:47654 323s app_test.go:1202: 09:52:03.864 - 85: DEBUG: new connection from 127.0.0.1:55328 323s app_test.go:1202: 09:52:03.866 - 86: DEBUG: new connection from 127.0.0.1:60204 323s app_test.go:1202: 09:52:03.868 - 88: DEBUG: new connection from 127.0.0.1:33778 323s app_test.go:1202: 09:52:03.870 - 89: DEBUG: new connection from 127.0.0.1:59396 323s app_test.go:1202: 09:52:03.871 - 83: DEBUG: new connection from 127.0.0.1:54170 323s app_test.go:1202: 09:52:03.875 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 323s app_test.go:1202: 09:52:03.876 - 83: DEBUG: new connection from 127.0.0.1:54182 323s app_test.go:1202: 09:52:03.878 - 84: DEBUG: new connection from 127.0.0.1:47656 323s app_test.go:1202: 09:52:03.880 - 85: DEBUG: new connection from 127.0.0.1:55344 323s app_test.go:1202: 09:52:03.882 - 86: DEBUG: new connection from 127.0.0.1:60208 323s app_test.go:1202: 09:52:03.885 - 88: DEBUG: new connection from 127.0.0.1:33794 323s app_test.go:1202: 09:52:03.887 - 89: DEBUG: new connection from 127.0.0.1:59406 328s app_test.go:1202: 09:52:03.497 - 83: DEBUG: new connection from 127.0.0.1:37682 328s app_test.go:1202: 09:52:03.500 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 328s app_test.go:1202: 09:52:03.581 - 83: DEBUG: new connection from 127.0.0.1:37690 328s app_test.go:1202: 09:52:03.584 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 328s app_test.go:1202: 09:52:03.652 - 83: DEBUG: new connection from 127.0.0.1:37698 328s app_test.go:1202: 09:52:03.654 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 328s app_test.go:1202: 09:52:03.802 - 83: DEBUG: new connection from 127.0.0.1:37700 328s app_test.go:1202: 09:52:03.804 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 328s app_test.go:1202: 09:52:03.879 - 83: DEBUG: new connection from 127.0.0.1:37704 328s app_test.go:1202: 09:52:03.881 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 328s app_test.go:1202: 09:52:03.889 - 83: DEBUG: new connection from 127.0.0.1:37720 328s app_test.go:1202: 09:52:03.891 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 328s app_test.go:1202: 09:52:03.892 - 83: DEBUG: new connection from 127.0.0.1:37726 328s app_test.go:1202: 09:52:03.894 - 84: DEBUG: new connection from 127.0.0.1:57022 328s app_test.go:1202: 09:52:03.897 - 85: DEBUG: new connection from 127.0.0.1:55562 328s app_test.go:1202: 09:52:03.899 - 86: DEBUG: new connection from 127.0.0.1:58708 328s app_test.go:1202: 09:52:03.901 - 88: DEBUG: new connection from 127.0.0.1:50616 328s app_test.go:1202: 09:52:03.904 - 89: DEBUG: new connection from 127.0.0.1:51182 333s app_test.go:1202: 09:52:03.502 - 83: DEBUG: new connection from 127.0.0.1:37734 333s app_test.go:1202: 09:52:03.504 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 333s app_test.go:1202: 09:52:03.586 - 83: DEBUG: new connection from 127.0.0.1:37748 333s app_test.go:1202: 09:52:03.588 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 333s app_test.go:1202: 09:52:03.656 - 83: DEBUG: new connection from 127.0.0.1:37758 333s app_test.go:1202: 09:52:03.658 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 333s app_test.go:1202: 09:52:03.807 - 83: DEBUG: new connection from 127.0.0.1:37774 333s app_test.go:1202: 09:52:03.810 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 333s app_test.go:1202: 09:52:03.882 - 83: DEBUG: new connection from 127.0.0.1:37788 333s app_test.go:1202: 09:52:03.884 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 333s app_test.go:1202: 09:52:03.906 - 83: DEBUG: new connection from 127.0.0.1:37804 333s app_test.go:1202: 09:52:03.908 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 333s app_test.go:1202: 09:52:03.909 - 83: DEBUG: new connection from 127.0.0.1:37818 333s app_test.go:1202: 09:52:03.912 - 84: DEBUG: new connection from 127.0.0.1:57026 333s app_test.go:1202: 09:52:03.914 - 85: DEBUG: new connection from 127.0.0.1:55564 333s app_test.go:1202: 09:52:03.916 - 86: DEBUG: new connection from 127.0.0.1:58720 333s app_test.go:1202: 09:52:03.918 - 88: DEBUG: new connection from 127.0.0.1:50624 333s app_test.go:1202: 09:52:03.920 - 89: DEBUG: new connection from 127.0.0.1:51194 338s app_test.go:1202: 09:52:03.507 - 83: DEBUG: new connection from 127.0.0.1:51936 338s app_test.go:1202: 09:52:03.509 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 338s app_test.go:1202: 09:52:03.590 - 83: DEBUG: new connection from 127.0.0.1:51948 338s app_test.go:1202: 09:52:03.592 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 338s app_test.go:1202: 09:52:03.659 - 83: DEBUG: new connection from 127.0.0.1:51960 338s app_test.go:1202: 09:52:03.662 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 338s app_test.go:1202: 09:52:03.813 - 83: DEBUG: new connection from 127.0.0.1:51970 338s app_test.go:1202: 09:52:03.815 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 338s app_test.go:1202: 09:52:03.886 - 83: DEBUG: new connection from 127.0.0.1:51972 338s app_test.go:1202: 09:52:03.888 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 338s app_test.go:1202: 09:52:03.921 - 83: DEBUG: new connection from 127.0.0.1:51976 338s app_test.go:1202: 09:52:03.923 - 83: DEBUG: new connection from 127.0.0.1:51986 338s app_test.go:1202: 09:52:03.927 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 338s app_test.go:1202: 09:52:03.927 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 338s app_test.go:1202: 09:52:03.930 - 83: DEBUG: new connection from 127.0.0.1:51988 338s app_test.go:1202: 09:52:03.932 - 84: DEBUG: new connection from 127.0.0.1:36648 338s app_test.go:1202: 09:52:03.934 - 85: DEBUG: new connection from 127.0.0.1:35484 338s app_test.go:1202: 09:52:03.937 - 86: DEBUG: new connection from 127.0.0.1:60406 338s app_test.go:1202: 09:52:03.939 - 88: DEBUG: new connection from 127.0.0.1:52292 339s --- PASS: TestRolesAdjustment_ReplaceStandBy (20.92s) 339s === RUN TestRolesAdjustment_ReplaceStandByHonorFailureDomains 339s app_test.go:1202: 09:52:03.332 - 90: DEBUG: new connection from 127.0.0.1:52000 339s app_test.go:1202: 09:52:03.335 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 339s app_test.go:1202: 09:52:03.336 - 90: DEBUG: new connection from 127.0.0.1:52004 339s app_test.go:1202: 09:52:03.402 - 90: DEBUG: new connection from 127.0.0.1:52016 339s app_test.go:1202: 09:52:03.404 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected 339s app_test.go:1202: 09:52:03.406 - 90: DEBUG: new connection from 127.0.0.1:52032 339s app_test.go:1202: 09:52:03.408 - 91: DEBUG: new connection from 127.0.0.1:36656 339s app_test.go:1202: 09:52:03.472 - 90: DEBUG: new connection from 127.0.0.1:52040 339s app_test.go:1202: 09:52:03.474 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected 339s app_test.go:1202: 09:52:03.475 - 90: DEBUG: new connection from 127.0.0.1:52052 339s app_test.go:1202: 09:52:03.477 - 91: DEBUG: new connection from 127.0.0.1:36666 339s app_test.go:1202: 09:52:03.480 - 92: DEBUG: new connection from 127.0.0.1:35496 339s app_test.go:1202: 09:52:03.482 - 92: DEBUG: new connection from 127.0.0.1:35498 339s app_test.go:1202: 09:52:03.487 - 90: DEBUG: new connection from 127.0.0.1:52068 339s app_test.go:1202: 09:52:03.490 - 91: DEBUG: new connection from 127.0.0.1:36676 339s app_test.go:1202: 09:52:03.493 - 90: DEBUG: new connection from 127.0.0.1:52078 339s app_test.go:1202: 09:52:03.542 - 90: DEBUG: new connection from 127.0.0.1:52088 339s app_test.go:1202: 09:52:03.544 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected 339s app_test.go:1202: 09:52:03.545 - 90: DEBUG: new connection from 127.0.0.1:52092 339s app_test.go:1202: 09:52:03.548 - 91: DEBUG: new connection from 127.0.0.1:36682 339s app_test.go:1202: 09:52:03.550 - 92: DEBUG: new connection from 127.0.0.1:35506 339s app_test.go:1202: 09:52:03.552 - 93: DEBUG: new connection from 127.0.0.1:60416 339s app_test.go:1202: 09:52:03.554 - 93: DEBUG: new connection from 127.0.0.1:60420 339s app_test.go:1202: 09:52:03.559 - 90: DEBUG: new connection from 127.0.0.1:52100 339s app_test.go:1202: 09:52:03.601 - 90: DEBUG: new connection from 127.0.0.1:52102 339s app_test.go:1202: 09:52:03.603 - 94: DEBUG: attempt 1: server 127.0.0.1:9001: connected 339s app_test.go:1202: 09:52:03.605 - 90: DEBUG: new connection from 127.0.0.1:52116 339s app_test.go:1202: 09:52:03.607 - 91: DEBUG: new connection from 127.0.0.1:36692 339s app_test.go:1202: 09:52:03.609 - 92: DEBUG: new connection from 127.0.0.1:35520 339s app_test.go:1202: 09:52:03.611 - 93: DEBUG: new connection from 127.0.0.1:60432 339s app_test.go:1202: 09:52:03.615 - 94: DEBUG: new connection from 127.0.0.1:37502 339s app_test.go:1202: 09:52:03.617 - 94: DEBUG: new connection from 127.0.0.1:37510 339s app_test.go:1202: 09:52:03.621 - 90: DEBUG: new connection from 127.0.0.1:52120 339s app_test.go:1202: 09:52:03.672 - 90: DEBUG: new connection from 127.0.0.1:52122 339s app_test.go:1202: 09:52:03.674 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected 339s app_test.go:1202: 09:52:03.675 - 90: DEBUG: new connection from 127.0.0.1:52126 339s app_test.go:1202: 09:52:03.678 - 91: DEBUG: new connection from 127.0.0.1:36700 339s app_test.go:1202: 09:52:03.680 - 92: DEBUG: new connection from 127.0.0.1:35522 339s app_test.go:1202: 09:52:03.682 - 93: DEBUG: new connection from 127.0.0.1:60446 339s app_test.go:1202: 09:52:03.684 - 94: DEBUG: new connection from 127.0.0.1:37522 339s app_test.go:1202: 09:52:03.686 - 95: DEBUG: new connection from 127.0.0.1:52300 339s app_test.go:1202: 09:52:03.689 - 95: DEBUG: new connection from 127.0.0.1:52316 339s app_test.go:1202: 09:52:03.693 - 90: DEBUG: new connection from 127.0.0.1:52136 339s app_test.go:1202: 09:52:03.752 - 90: DEBUG: new connection from 127.0.0.1:52146 339s app_test.go:1202: 09:52:03.756 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected 339s app_test.go:1202: 09:52:03.759 - 90: DEBUG: new connection from 127.0.0.1:52154 339s app_test.go:1202: 09:52:03.761 - 91: DEBUG: new connection from 127.0.0.1:36708 339s app_test.go:1202: 09:52:03.763 - 92: DEBUG: new connection from 127.0.0.1:35532 339s app_test.go:1202: 09:52:03.766 - 93: DEBUG: new connection from 127.0.0.1:60452 339s app_test.go:1202: 09:52:03.768 - 94: DEBUG: new connection from 127.0.0.1:37536 339s app_test.go:1202: 09:52:03.770 - 95: DEBUG: new connection from 127.0.0.1:52320 339s app_test.go:1202: 09:52:03.772 - 96: DEBUG: new connection from 127.0.0.1:48462 339s app_test.go:1202: 09:52:03.832 - 90: DEBUG: new connection from 127.0.0.1:52170 339s app_test.go:1202: 09:52:03.833 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected 339s app_test.go:1202: 09:52:03.835 - 90: DEBUG: new connection from 127.0.0.1:52176 339s app_test.go:1202: 09:52:03.837 - 91: DEBUG: new connection from 127.0.0.1:36724 339s app_test.go:1202: 09:52:03.839 - 92: DEBUG: new connection from 127.0.0.1:35544 339s app_test.go:1202: 09:52:03.841 - 93: DEBUG: new connection from 127.0.0.1:60462 339s app_test.go:1202: 09:52:03.843 - 94: DEBUG: new connection from 127.0.0.1:37550 339s app_test.go:1202: 09:52:03.846 - 95: DEBUG: new connection from 127.0.0.1:52332 339s app_test.go:1202: 09:52:03.848 - 96: DEBUG: new connection from 127.0.0.1:48466 339s app_test.go:1202: 09:52:03.850 - 97: DEBUG: new connection from 127.0.0.1:34032 339s app_test.go:1202: 09:52:03.911 - 90: DEBUG: new connection from 127.0.0.1:52188 339s app_test.go:1202: 09:52:03.913 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected 339s app_test.go:1202: 09:52:03.915 - 90: DEBUG: new connection from 127.0.0.1:52202 339s app_test.go:1202: 09:52:03.917 - 91: DEBUG: new connection from 127.0.0.1:36736 339s app_test.go:1202: 09:52:03.920 - 92: DEBUG: new connection from 127.0.0.1:35556 339s app_test.go:1202: 09:52:03.922 - 93: DEBUG: new connection from 127.0.0.1:60478 339s app_test.go:1202: 09:52:03.924 - 94: DEBUG: new connection from 127.0.0.1:37556 339s app_test.go:1202: 09:52:03.926 - 95: DEBUG: new connection from 127.0.0.1:52342 339s app_test.go:1202: 09:52:03.928 - 96: DEBUG: new connection from 127.0.0.1:48476 339s app_test.go:1202: 09:52:03.930 - 97: DEBUG: new connection from 127.0.0.1:34036 339s app_test.go:1202: 09:52:03.932 - 98: DEBUG: new connection from 127.0.0.1:48638 344s app_test.go:1202: 09:52:03.339 - 90: DEBUG: new connection from 127.0.0.1:47906 344s app_test.go:1202: 09:52:03.342 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 344s app_test.go:1202: 09:52:03.344 - 90: DEBUG: new connection from 127.0.0.1:47912 344s app_test.go:1202: 09:52:03.347 - 91: DEBUG: new connection from 127.0.0.1:33932 344s app_test.go:1202: 09:52:03.349 - 92: DEBUG: new connection from 127.0.0.1:54602 344s app_test.go:1202: 09:52:03.351 - 93: DEBUG: new connection from 127.0.0.1:39364 344s app_test.go:1202: 09:52:03.354 - 95: DEBUG: new connection from 127.0.0.1:60728 344s app_test.go:1202: 09:52:03.356 - 96: DEBUG: new connection from 127.0.0.1:57492 344s app_test.go:1202: 09:52:03.361 - 97: DEBUG: new connection from 127.0.0.1:49388 344s app_test.go:1202: 09:52:03.364 - 98: DEBUG: new connection from 127.0.0.1:48512 344s app_test.go:1202: 09:52:03.367 - 97: DEBUG: new connection from 127.0.0.1:49400 344s app_test.go:1202: 09:52:03.371 - 90: DEBUG: new connection from 127.0.0.1:47922 344s app_test.go:1202: 09:52:03.411 - 90: DEBUG: new connection from 127.0.0.1:47924 344s app_test.go:1202: 09:52:03.413 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected 344s app_test.go:1202: 09:52:03.498 - 90: DEBUG: new connection from 127.0.0.1:47938 344s app_test.go:1202: 09:52:03.500 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected 344s app_test.go:1202: 09:52:03.557 - 90: DEBUG: new connection from 127.0.0.1:47942 344s app_test.go:1202: 09:52:03.559 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected 344s app_test.go:1202: 09:52:03.691 - 90: DEBUG: new connection from 127.0.0.1:47946 344s app_test.go:1202: 09:52:03.693 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected 344s app_test.go:1202: 09:52:03.774 - 90: DEBUG: new connection from 127.0.0.1:47962 344s app_test.go:1202: 09:52:03.776 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected 344s app_test.go:1202: 09:52:03.788 - 90: DEBUG: new connection from 127.0.0.1:47966 344s app_test.go:1202: 09:52:03.790 - 91: DEBUG: new connection from 127.0.0.1:33936 344s app_test.go:1202: 09:52:03.792 - 92: DEBUG: new connection from 127.0.0.1:54604 344s app_test.go:1202: 09:52:03.794 - 93: DEBUG: new connection from 127.0.0.1:39372 344s app_test.go:1202: 09:52:03.797 - 95: DEBUG: new connection from 127.0.0.1:60734 344s app_test.go:1202: 09:52:03.800 - 96: DEBUG: new connection from 127.0.0.1:57494 344s app_test.go:1202: 09:52:03.803 - 97: DEBUG: new connection from 127.0.0.1:49402 344s app_test.go:1202: 09:52:03.805 - 98: DEBUG: new connection from 127.0.0.1:48516 344s app_test.go:1202: 09:52:03.808 - 90: DEBUG: new connection from 127.0.0.1:47968 344s app_test.go:1202: 09:52:03.810 - 91: DEBUG: new connection from 127.0.0.1:33946 344s app_test.go:1202: 09:52:03.812 - 92: DEBUG: new connection from 127.0.0.1:54612 344s app_test.go:1202: 09:52:03.814 - 93: DEBUG: new connection from 127.0.0.1:39374 344s app_test.go:1202: 09:52:03.816 - 95: DEBUG: new connection from 127.0.0.1:60748 344s app_test.go:1202: 09:52:03.818 - 96: DEBUG: new connection from 127.0.0.1:57504 344s app_test.go:1202: 09:52:03.820 - 97: DEBUG: new connection from 127.0.0.1:49418 344s app_test.go:1202: 09:52:03.822 - 98: DEBUG: new connection from 127.0.0.1:48526 344s app_test.go:1202: 09:52:03.854 - 90: DEBUG: new connection from 127.0.0.1:47980 344s app_test.go:1202: 09:52:03.856 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected 344s app_test.go:1202: 09:52:03.934 - 90: DEBUG: new connection from 127.0.0.1:47988 344s app_test.go:1202: 09:52:03.938 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1202: 09:52:03.415 - 90: DEBUG: new connection from 127.0.0.1:47992 349s app_test.go:1202: 09:52:03.417 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1202: 09:52:03.501 - 90: DEBUG: new connection from 127.0.0.1:48002 349s app_test.go:1202: 09:52:03.505 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1202: 09:52:03.562 - 90: DEBUG: new connection from 127.0.0.1:48012 349s app_test.go:1202: 09:52:03.564 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1202: 09:52:03.696 - 90: DEBUG: new connection from 127.0.0.1:48020 349s app_test.go:1202: 09:52:03.698 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1202: 09:52:03.778 - 90: DEBUG: new connection from 127.0.0.1:48026 349s app_test.go:1202: 09:52:03.780 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1202: 09:52:03.825 - 90: DEBUG: new connection from 127.0.0.1:48030 349s app_test.go:1202: 09:52:03.827 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1202: 09:52:03.829 - 90: DEBUG: new connection from 127.0.0.1:48038 349s app_test.go:1202: 09:52:03.831 - 91: DEBUG: new connection from 127.0.0.1:33954 349s app_test.go:1202: 09:52:03.833 - 92: DEBUG: new connection from 127.0.0.1:54628 349s app_test.go:1202: 09:52:03.835 - 93: DEBUG: new connection from 127.0.0.1:39390 349s app_test.go:1202: 09:52:03.837 - 95: DEBUG: new connection from 127.0.0.1:60756 349s app_test.go:1202: 09:52:03.839 - 96: DEBUG: new connection from 127.0.0.1:57508 349s app_test.go:1202: 09:52:03.841 - 97: DEBUG: new connection from 127.0.0.1:49422 349s app_test.go:1202: 09:52:03.844 - 98: DEBUG: new connection from 127.0.0.1:48536 349s app_test.go:1202: 09:52:03.857 - 90: DEBUG: new connection from 127.0.0.1:48052 349s app_test.go:1202: 09:52:03.859 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1202: 09:52:03.940 - 90: DEBUG: new connection from 127.0.0.1:48062 349s app_test.go:1202: 09:52:03.942 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected 354s app_test.go:1202: 09:53:03.420 - 90: DEBUG: new connection from 127.0.0.1:39688 354s app_test.go:1202: 09:53:03.428 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected 354s app_test.go:1202: 09:53:03.508 - 90: DEBUG: new connection from 127.0.0.1:39702 354s app_test.go:1202: 09:53:03.510 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected 354s app_test.go:1202: 09:53:03.566 - 90: DEBUG: new connection from 127.0.0.1:39716 354s app_test.go:1202: 09:53:03.568 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected 354s app_test.go:1202: 09:53:03.699 - 90: DEBUG: new connection from 127.0.0.1:39720 354s app_test.go:1202: 09:53:03.701 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected 354s app_test.go:1202: 09:53:03.783 - 90: DEBUG: new connection from 127.0.0.1:39734 354s app_test.go:1202: 09:53:03.785 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected 354s app_test.go:1202: 09:53:03.846 - 90: DEBUG: new connection from 127.0.0.1:39750 354s app_test.go:1202: 09:53:03.849 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 354s app_test.go:1202: 09:53:03.851 - 90: DEBUG: new connection from 127.0.0.1:39760 354s app_test.go:1202: 09:53:03.853 - 91: DEBUG: new connection from 127.0.0.1:58920 354s app_test.go:1202: 09:53:03.856 - 92: DEBUG: new connection from 127.0.0.1:35990 354s app_test.go:1202: 09:53:03.858 - 93: DEBUG: new connection from 127.0.0.1:40042 354s app_test.go:1202: 09:53:03.861 - 90: DEBUG: new connection from 127.0.0.1:39774 354s app_test.go:1202: 09:53:03.863 - 95: DEBUG: new connection from 127.0.0.1:37090 354s app_test.go:1202: 09:53:03.865 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected 354s app_test.go:1202: 09:53:03.865 - 96: DEBUG: new connection from 127.0.0.1:45072 354s app_test.go:1202: 09:53:03.868 - 97: DEBUG: new connection from 127.0.0.1:47864 354s app_test.go:1202: 09:53:03.870 - 98: DEBUG: new connection from 127.0.0.1:37376 354s app_test.go:1202: 09:53:03.945 - 90: DEBUG: new connection from 127.0.0.1:39786 354s app_test.go:1202: 09:53:03.948 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected 359s app_test.go:1202: 09:53:03.433 - 90: DEBUG: new connection from 127.0.0.1:39800 359s app_test.go:1202: 09:53:03.437 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected 359s app_test.go:1202: 09:53:03.512 - 90: DEBUG: new connection from 127.0.0.1:39810 359s app_test.go:1202: 09:53:03.514 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected 359s app_test.go:1202: 09:53:03.571 - 90: DEBUG: new connection from 127.0.0.1:39818 359s app_test.go:1202: 09:53:03.573 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected 359s app_test.go:1202: 09:53:03.704 - 90: DEBUG: new connection from 127.0.0.1:39826 359s app_test.go:1202: 09:53:03.706 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected 359s app_test.go:1202: 09:53:03.787 - 90: DEBUG: new connection from 127.0.0.1:39842 359s app_test.go:1202: 09:53:03.789 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected 359s app_test.go:1202: 09:53:03.868 - 90: DEBUG: new connection from 127.0.0.1:39846 359s app_test.go:1202: 09:53:03.870 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected 359s app_test.go:1202: 09:53:03.872 - 90: DEBUG: new connection from 127.0.0.1:39856 359s app_test.go:1202: 09:53:03.874 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 359s app_test.go:1202: 09:53:03.875 - 90: DEBUG: new connection from 127.0.0.1:39862 359s app_test.go:1202: 09:53:03.877 - 91: DEBUG: new connection from 127.0.0.1:58932 359s app_test.go:1202: 09:53:03.879 - 92: DEBUG: new connection from 127.0.0.1:36000 359s app_test.go:1202: 09:53:03.881 - 93: DEBUG: new connection from 127.0.0.1:40046 359s app_test.go:1202: 09:53:03.884 - 95: DEBUG: new connection from 127.0.0.1:37106 359s app_test.go:1202: 09:53:03.886 - 96: DEBUG: new connection from 127.0.0.1:45086 359s app_test.go:1202: 09:53:03.888 - 97: DEBUG: new connection from 127.0.0.1:47874 359s app_test.go:1202: 09:53:03.891 - 98: DEBUG: new connection from 127.0.0.1:37386 359s app_test.go:1202: 09:53:03.951 - 90: DEBUG: new connection from 127.0.0.1:39870 359s app_test.go:1202: 09:53:03.954 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected 360s app_test.go:1202: 09:53:03.991 - 90: DEBUG: new connection from 127.0.0.1:39886 360s app_test.go:1202: 09:53:03.993 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 360s --- PASS: TestRolesAdjustment_ReplaceStandByHonorFailureDomains (21.07s) 360s === RUN TestOpen 360s app_test.go:1202: 09:53:03.403 - 99: DEBUG: new connection from 127.0.0.1:39686 360s app_test.go:1202: 09:53:03.404 - 99: DEBUG: new connection from 127.0.0.1:39694 360s app_test.go:1202: 09:53:03.407 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected 360s app_test.go:1202: 09:53:03.407 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected 360s app_test.go:1202: 09:53:03.408 - 99: DEBUG: new connection from 127.0.0.1:39698 360s --- PASS: TestOpen (0.13s) 360s === RUN TestOpenDisk 360s app_test.go:1202: 09:53:03.553 - 100: DEBUG: new connection from 127.0.0.1:39714 360s app_test.go:1202: 09:53:03.553 - 100: DEBUG: new connection from 127.0.0.1:39718 360s app_test.go:1202: 09:53:03.559 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected 360s app_test.go:1202: 09:53:03.559 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected 360s app_test.go:1202: 09:53:03.561 - 100: DEBUG: new connection from 127.0.0.1:39726 360s --- PASS: TestOpenDisk (0.14s) 360s === RUN TestOptions 360s --- PASS: TestOptions (0.07s) 360s === RUN TestProxy_Error 360s app_test.go:1202: 09:53:03.773 - 102: DEBUG: new connection from 127.0.0.1:39736 360s app_test.go:1202: 09:53:03.773 - 102: DEBUG: new connection from 127.0.0.1:39738 360s app_test.go:1202: 09:53:03.777 - 102: DEBUG: attempt 1: server 127.0.0.1:9000: connected 360s app_test.go:1202: 09:53:03.778 - 102: DEBUG: new connection from 127.0.0.1:39754 361s --- PASS: TestProxy_Error (0.44s) 361s === RUN TestReady_Cancel 361s app_test.go:1202: 09:53:03.201 - 103: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 362s --- PASS: TestReady_Cancel (0.28s) 362s === RUN TestExternalConnWithTCP 362s app_test.go:1227: 09:53:03.483 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected 362s app_test.go:1227: 09:53:03.541 - 105: DEBUG: attempt 1: server 127.0.0.1:9191: connected 362s app_test.go:1227: 09:53:03.591 - 106: DEBUG: attempt 1: server 127.0.0.1:9191: connected 362s app_test.go:1227: 09:53:03.599 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected 362s --- PASS: TestExternalConnWithTCP (0.35s) 362s === RUN TestExternalConnWithPipe 362s app_test.go:1227: 09:53:03.833 - 107: DEBUG: attempt 1: server first: connected 362s app_test.go:1227: 09:53:03.891 - 108: DEBUG: attempt 1: server first: connected 362s app_test.go:1227: 09:53:03.951 - 109: DEBUG: attempt 1: server first: connected 362s app_test.go:1227: 09:53:03.959 - 107: DEBUG: attempt 1: server first: connected 362s --- PASS: TestExternalConnWithPipe (0.35s) 362s === RUN TestParallelNewApp 362s === PAUSE TestParallelNewApp 362s === RUN Test_TxRowsAffected 362s app_test.go:1227: 09:53:03.192 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected 362s app_test.go:1227: 09:53:03.194 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected 362s --- PASS: Test_TxRowsAffected (0.14s) 362s === CONT TestParallelNewApp 362s === RUN TestParallelNewApp/run-0 362s === PAUSE TestParallelNewApp/run-0 362s === RUN TestParallelNewApp/run-1 362s === PAUSE TestParallelNewApp/run-1 362s === RUN TestParallelNewApp/run-2 362s === PAUSE TestParallelNewApp/run-2 362s === RUN TestParallelNewApp/run-3 362s === PAUSE TestParallelNewApp/run-3 362s === RUN TestParallelNewApp/run-4 362s === PAUSE TestParallelNewApp/run-4 362s === RUN TestParallelNewApp/run-5 362s === PAUSE TestParallelNewApp/run-5 362s === RUN TestParallelNewApp/run-6 362s === PAUSE TestParallelNewApp/run-6 362s === RUN TestParallelNewApp/run-7 362s === PAUSE TestParallelNewApp/run-7 362s === RUN TestParallelNewApp/run-8 362s === PAUSE TestParallelNewApp/run-8 362s === RUN TestParallelNewApp/run-9 362s === PAUSE TestParallelNewApp/run-9 362s === RUN TestParallelNewApp/run-10 362s === PAUSE TestParallelNewApp/run-10 362s === RUN TestParallelNewApp/run-11 362s === PAUSE TestParallelNewApp/run-11 362s === RUN TestParallelNewApp/run-12 362s === PAUSE TestParallelNewApp/run-12 362s === RUN TestParallelNewApp/run-13 362s === PAUSE TestParallelNewApp/run-13 362s === RUN TestParallelNewApp/run-14 362s === PAUSE TestParallelNewApp/run-14 362s === RUN TestParallelNewApp/run-15 362s === PAUSE TestParallelNewApp/run-15 362s === RUN TestParallelNewApp/run-16 362s === PAUSE TestParallelNewApp/run-16 362s === RUN TestParallelNewApp/run-17 362s === PAUSE TestParallelNewApp/run-17 362s === RUN TestParallelNewApp/run-18 362s === PAUSE TestParallelNewApp/run-18 362s === RUN TestParallelNewApp/run-19 362s === PAUSE TestParallelNewApp/run-19 362s === RUN TestParallelNewApp/run-20 362s === PAUSE TestParallelNewApp/run-20 362s === RUN TestParallelNewApp/run-21 362s === PAUSE TestParallelNewApp/run-21 362s === RUN TestParallelNewApp/run-22 362s === PAUSE TestParallelNewApp/run-22 362s === RUN TestParallelNewApp/run-23 362s === PAUSE TestParallelNewApp/run-23 362s === RUN TestParallelNewApp/run-24 362s === PAUSE TestParallelNewApp/run-24 362s === RUN TestParallelNewApp/run-25 362s === PAUSE TestParallelNewApp/run-25 362s === RUN TestParallelNewApp/run-26 362s === PAUSE TestParallelNewApp/run-26 362s === RUN TestParallelNewApp/run-27 362s === PAUSE TestParallelNewApp/run-27 362s === RUN TestParallelNewApp/run-28 362s === PAUSE TestParallelNewApp/run-28 362s === RUN TestParallelNewApp/run-29 362s === PAUSE TestParallelNewApp/run-29 362s === RUN TestParallelNewApp/run-30 362s === PAUSE TestParallelNewApp/run-30 362s === RUN TestParallelNewApp/run-31 362s === PAUSE TestParallelNewApp/run-31 362s === RUN TestParallelNewApp/run-32 362s === PAUSE TestParallelNewApp/run-32 362s === RUN TestParallelNewApp/run-33 362s === PAUSE TestParallelNewApp/run-33 362s === RUN TestParallelNewApp/run-34 362s === PAUSE TestParallelNewApp/run-34 362s === RUN TestParallelNewApp/run-35 362s === PAUSE TestParallelNewApp/run-35 362s === RUN TestParallelNewApp/run-36 362s === PAUSE TestParallelNewApp/run-36 362s === RUN TestParallelNewApp/run-37 362s === PAUSE TestParallelNewApp/run-37 362s === RUN TestParallelNewApp/run-38 362s === PAUSE TestParallelNewApp/run-38 362s === RUN TestParallelNewApp/run-39 362s === PAUSE TestParallelNewApp/run-39 362s === RUN TestParallelNewApp/run-40 362s === PAUSE TestParallelNewApp/run-40 362s === RUN TestParallelNewApp/run-41 362s === PAUSE TestParallelNewApp/run-41 362s === RUN TestParallelNewApp/run-42 362s === PAUSE TestParallelNewApp/run-42 362s === RUN TestParallelNewApp/run-43 362s === PAUSE TestParallelNewApp/run-43 362s === RUN TestParallelNewApp/run-44 362s === PAUSE TestParallelNewApp/run-44 362s === RUN TestParallelNewApp/run-45 362s === PAUSE TestParallelNewApp/run-45 362s === RUN TestParallelNewApp/run-46 362s === PAUSE TestParallelNewApp/run-46 362s === RUN TestParallelNewApp/run-47 362s === PAUSE TestParallelNewApp/run-47 362s === RUN TestParallelNewApp/run-48 362s === PAUSE TestParallelNewApp/run-48 362s === RUN TestParallelNewApp/run-49 362s === PAUSE TestParallelNewApp/run-49 362s === RUN TestParallelNewApp/run-50 362s === PAUSE TestParallelNewApp/run-50 362s === RUN TestParallelNewApp/run-51 362s === PAUSE TestParallelNewApp/run-51 362s === RUN TestParallelNewApp/run-52 362s === PAUSE TestParallelNewApp/run-52 362s === RUN TestParallelNewApp/run-53 362s === PAUSE TestParallelNewApp/run-53 362s === RUN TestParallelNewApp/run-54 362s === PAUSE TestParallelNewApp/run-54 362s === RUN TestParallelNewApp/run-55 362s === PAUSE TestParallelNewApp/run-55 362s === RUN TestParallelNewApp/run-56 362s === PAUSE TestParallelNewApp/run-56 362s === RUN TestParallelNewApp/run-57 362s === PAUSE TestParallelNewApp/run-57 362s === RUN TestParallelNewApp/run-58 362s === PAUSE TestParallelNewApp/run-58 362s === RUN TestParallelNewApp/run-59 362s === PAUSE TestParallelNewApp/run-59 362s === RUN TestParallelNewApp/run-60 362s === PAUSE TestParallelNewApp/run-60 362s === RUN TestParallelNewApp/run-61 362s === PAUSE TestParallelNewApp/run-61 362s === RUN TestParallelNewApp/run-62 362s === PAUSE TestParallelNewApp/run-62 362s === RUN TestParallelNewApp/run-63 362s === PAUSE TestParallelNewApp/run-63 362s === RUN TestParallelNewApp/run-64 362s === PAUSE TestParallelNewApp/run-64 362s === RUN TestParallelNewApp/run-65 362s === PAUSE TestParallelNewApp/run-65 362s === RUN TestParallelNewApp/run-66 362s === PAUSE TestParallelNewApp/run-66 362s === RUN TestParallelNewApp/run-67 362s === PAUSE TestParallelNewApp/run-67 362s === RUN TestParallelNewApp/run-68 362s === PAUSE TestParallelNewApp/run-68 362s === RUN TestParallelNewApp/run-69 362s === PAUSE TestParallelNewApp/run-69 362s === RUN TestParallelNewApp/run-70 362s === PAUSE TestParallelNewApp/run-70 362s === RUN TestParallelNewApp/run-71 362s === PAUSE TestParallelNewApp/run-71 362s === RUN TestParallelNewApp/run-72 362s === PAUSE TestParallelNewApp/run-72 362s === RUN TestParallelNewApp/run-73 362s === PAUSE TestParallelNewApp/run-73 362s === RUN TestParallelNewApp/run-74 362s === PAUSE TestParallelNewApp/run-74 362s === RUN TestParallelNewApp/run-75 362s === PAUSE TestParallelNewApp/run-75 362s === RUN TestParallelNewApp/run-76 362s === PAUSE TestParallelNewApp/run-76 362s === RUN TestParallelNewApp/run-77 362s === PAUSE TestParallelNewApp/run-77 362s === RUN TestParallelNewApp/run-78 362s === PAUSE TestParallelNewApp/run-78 362s === RUN TestParallelNewApp/run-79 362s === PAUSE TestParallelNewApp/run-79 362s === RUN TestParallelNewApp/run-80 362s === PAUSE TestParallelNewApp/run-80 362s === RUN TestParallelNewApp/run-81 362s === PAUSE TestParallelNewApp/run-81 362s === RUN TestParallelNewApp/run-82 362s === PAUSE TestParallelNewApp/run-82 362s === RUN TestParallelNewApp/run-83 362s === PAUSE TestParallelNewApp/run-83 362s === RUN TestParallelNewApp/run-84 362s === PAUSE TestParallelNewApp/run-84 362s === RUN TestParallelNewApp/run-85 362s === PAUSE TestParallelNewApp/run-85 362s === RUN TestParallelNewApp/run-86 362s === PAUSE TestParallelNewApp/run-86 362s === RUN TestParallelNewApp/run-87 362s === PAUSE TestParallelNewApp/run-87 362s === RUN TestParallelNewApp/run-88 362s === PAUSE TestParallelNewApp/run-88 362s === RUN TestParallelNewApp/run-89 362s === PAUSE TestParallelNewApp/run-89 362s === RUN TestParallelNewApp/run-90 362s === PAUSE TestParallelNewApp/run-90 362s === RUN TestParallelNewApp/run-91 362s === PAUSE TestParallelNewApp/run-91 362s === RUN TestParallelNewApp/run-92 362s === PAUSE TestParallelNewApp/run-92 362s === RUN TestParallelNewApp/run-93 362s === PAUSE TestParallelNewApp/run-93 362s === RUN TestParallelNewApp/run-94 362s === PAUSE TestParallelNewApp/run-94 362s === RUN TestParallelNewApp/run-95 362s === PAUSE TestParallelNewApp/run-95 362s === RUN TestParallelNewApp/run-96 362s === PAUSE TestParallelNewApp/run-96 362s === RUN TestParallelNewApp/run-97 362s === PAUSE TestParallelNewApp/run-97 362s === RUN TestParallelNewApp/run-98 362s === PAUSE TestParallelNewApp/run-98 362s === RUN TestParallelNewApp/run-99 362s === PAUSE TestParallelNewApp/run-99 362s === CONT TestParallelNewApp/run-0 362s === CONT TestParallelNewApp/run-99 362s === CONT TestParallelNewApp/run-98 362s === CONT TestParallelNewApp/run-97 362s === CONT TestParallelNewApp/run-96 362s === CONT TestParallelNewApp/run-95 362s === CONT TestParallelNewApp/run-94 362s === CONT TestParallelNewApp/run-93 362s === CONT TestParallelNewApp/run-92 362s === CONT TestParallelNewApp/run-91 362s === CONT TestParallelNewApp/run-90 363s === CONT TestParallelNewApp/run-89 363s === CONT TestParallelNewApp/run-88 363s === CONT TestParallelNewApp/run-87 363s === CONT TestParallelNewApp/run-86 363s === CONT TestParallelNewApp/run-85 363s === CONT TestParallelNewApp/run-84 363s === CONT TestParallelNewApp/run-83 363s === CONT TestParallelNewApp/run-82 363s === CONT TestParallelNewApp/run-81 363s === CONT TestParallelNewApp/run-80 363s === CONT TestParallelNewApp/run-79 363s === CONT TestParallelNewApp/run-78 363s === CONT TestParallelNewApp/run-77 364s === CONT TestParallelNewApp/run-76 364s === CONT TestParallelNewApp/run-75 364s === CONT TestParallelNewApp/run-74 364s === CONT TestParallelNewApp/run-73 364s === CONT TestParallelNewApp/run-72 364s === CONT TestParallelNewApp/run-71 364s === CONT TestParallelNewApp/run-70 364s === CONT TestParallelNewApp/run-69 364s === CONT TestParallelNewApp/run-68 364s === CONT TestParallelNewApp/run-67 364s === CONT TestParallelNewApp/run-66 364s === CONT TestParallelNewApp/run-65 364s === CONT TestParallelNewApp/run-64 364s === CONT TestParallelNewApp/run-63 365s === CONT TestParallelNewApp/run-62 365s === CONT TestParallelNewApp/run-61 365s === CONT TestParallelNewApp/run-60 365s === CONT TestParallelNewApp/run-59 365s === CONT TestParallelNewApp/run-58 365s === CONT TestParallelNewApp/run-57 365s === CONT TestParallelNewApp/run-56 365s === CONT TestParallelNewApp/run-55 365s === CONT TestParallelNewApp/run-54 365s === CONT TestParallelNewApp/run-53 365s === CONT TestParallelNewApp/run-52 365s === CONT TestParallelNewApp/run-51 365s === CONT TestParallelNewApp/run-50 366s === CONT TestParallelNewApp/run-49 366s === CONT TestParallelNewApp/run-48 366s === CONT TestParallelNewApp/run-47 366s === CONT TestParallelNewApp/run-46 366s === CONT TestParallelNewApp/run-45 366s === CONT TestParallelNewApp/run-44 366s === CONT TestParallelNewApp/run-43 366s === CONT TestParallelNewApp/run-42 366s === CONT TestParallelNewApp/run-41 366s === CONT TestParallelNewApp/run-40 366s === CONT TestParallelNewApp/run-39 366s === CONT TestParallelNewApp/run-38 366s === CONT TestParallelNewApp/run-37 366s === CONT TestParallelNewApp/run-36 367s === CONT TestParallelNewApp/run-35 367s === CONT TestParallelNewApp/run-34 367s === CONT TestParallelNewApp/run-33 367s === CONT TestParallelNewApp/run-32 367s === CONT TestParallelNewApp/run-31 367s === CONT TestParallelNewApp/run-30 367s === CONT TestParallelNewApp/run-29 367s === CONT TestParallelNewApp/run-28 367s === CONT TestParallelNewApp/run-27 367s === CONT TestParallelNewApp/run-26 367s === CONT TestParallelNewApp/run-25 367s === CONT TestParallelNewApp/run-24 367s === CONT TestParallelNewApp/run-23 368s === CONT TestParallelNewApp/run-22 368s === CONT TestParallelNewApp/run-21 368s === CONT TestParallelNewApp/run-20 368s === CONT TestParallelNewApp/run-19 368s === CONT TestParallelNewApp/run-18 368s === CONT TestParallelNewApp/run-17 368s === CONT TestParallelNewApp/run-16 368s === CONT TestParallelNewApp/run-15 368s === CONT TestParallelNewApp/run-14 368s === CONT TestParallelNewApp/run-13 368s === CONT TestParallelNewApp/run-12 368s === CONT TestParallelNewApp/run-11 368s === CONT TestParallelNewApp/run-10 369s === CONT TestParallelNewApp/run-9 369s === CONT TestParallelNewApp/run-8 369s === CONT TestParallelNewApp/run-7 369s === CONT TestParallelNewApp/run-6 369s === CONT TestParallelNewApp/run-5 369s === CONT TestParallelNewApp/run-4 369s === CONT TestParallelNewApp/run-3 369s === CONT TestParallelNewApp/run-2 369s === CONT TestParallelNewApp/run-1 369s --- PASS: TestParallelNewApp (0.00s) 369s --- PASS: TestParallelNewApp/run-0 (0.06s) 369s --- PASS: TestParallelNewApp/run-99 (0.08s) 369s --- PASS: TestParallelNewApp/run-98 (0.05s) 369s --- PASS: TestParallelNewApp/run-97 (0.07s) 369s --- PASS: TestParallelNewApp/run-96 (0.05s) 369s --- PASS: TestParallelNewApp/run-95 (0.06s) 369s --- PASS: TestParallelNewApp/run-94 (0.05s) 369s --- PASS: TestParallelNewApp/run-93 (0.06s) 369s --- PASS: TestParallelNewApp/run-92 (0.08s) 369s --- PASS: TestParallelNewApp/run-91 (0.06s) 369s --- PASS: TestParallelNewApp/run-90 (0.06s) 369s --- PASS: TestParallelNewApp/run-89 (0.09s) 369s --- PASS: TestParallelNewApp/run-88 (0.07s) 369s --- PASS: TestParallelNewApp/run-87 (0.07s) 369s --- PASS: TestParallelNewApp/run-86 (0.08s) 369s --- PASS: TestParallelNewApp/run-85 (0.09s) 369s --- PASS: TestParallelNewApp/run-84 (0.07s) 369s --- PASS: TestParallelNewApp/run-83 (0.08s) 369s --- PASS: TestParallelNewApp/run-82 (0.09s) 369s --- PASS: TestParallelNewApp/run-81 (0.07s) 369s --- PASS: TestParallelNewApp/run-80 (0.09s) 369s --- PASS: TestParallelNewApp/run-79 (0.07s) 369s --- PASS: TestParallelNewApp/run-78 (0.08s) 369s --- PASS: TestParallelNewApp/run-77 (0.08s) 369s --- PASS: TestParallelNewApp/run-76 (0.08s) 369s --- PASS: TestParallelNewApp/run-75 (0.07s) 369s --- PASS: TestParallelNewApp/run-74 (0.08s) 369s --- PASS: TestParallelNewApp/run-73 (0.06s) 369s --- PASS: TestParallelNewApp/run-72 (0.07s) 369s --- PASS: TestParallelNewApp/run-71 (0.09s) 369s --- PASS: TestParallelNewApp/run-70 (0.06s) 369s --- PASS: TestParallelNewApp/run-69 (0.06s) 369s --- PASS: TestParallelNewApp/run-68 (0.06s) 369s --- PASS: TestParallelNewApp/run-67 (0.05s) 369s --- PASS: TestParallelNewApp/run-66 (0.08s) 369s --- PASS: TestParallelNewApp/run-65 (0.08s) 369s --- PASS: TestParallelNewApp/run-64 (0.08s) 369s --- PASS: TestParallelNewApp/run-63 (0.07s) 369s --- PASS: TestParallelNewApp/run-62 (0.08s) 369s --- PASS: TestParallelNewApp/run-61 (0.08s) 369s --- PASS: TestParallelNewApp/run-60 (0.08s) 369s --- PASS: TestParallelNewApp/run-59 (0.07s) 369s --- PASS: TestParallelNewApp/run-58 (0.05s) 369s --- PASS: TestParallelNewApp/run-57 (0.06s) 369s --- PASS: TestParallelNewApp/run-56 (0.05s) 369s --- PASS: TestParallelNewApp/run-55 (0.05s) 369s --- PASS: TestParallelNewApp/run-54 (0.07s) 369s --- PASS: TestParallelNewApp/run-53 (0.09s) 369s --- PASS: TestParallelNewApp/run-52 (0.07s) 369s --- PASS: TestParallelNewApp/run-51 (0.08s) 369s --- PASS: TestParallelNewApp/run-50 (0.09s) 369s --- PASS: TestParallelNewApp/run-49 (0.06s) 369s --- PASS: TestParallelNewApp/run-48 (0.07s) 369s --- PASS: TestParallelNewApp/run-47 (0.08s) 369s --- PASS: TestParallelNewApp/run-46 (0.07s) 369s --- PASS: TestParallelNewApp/run-45 (0.07s) 369s --- PASS: TestParallelNewApp/run-44 (0.08s) 369s --- PASS: TestParallelNewApp/run-43 (0.07s) 369s --- PASS: TestParallelNewApp/run-42 (0.07s) 369s --- PASS: TestParallelNewApp/run-41 (0.07s) 369s --- PASS: TestParallelNewApp/run-40 (0.08s) 369s --- PASS: TestParallelNewApp/run-39 (0.07s) 369s --- PASS: TestParallelNewApp/run-38 (0.08s) 369s --- PASS: TestParallelNewApp/run-37 (0.08s) 369s --- PASS: TestParallelNewApp/run-36 (0.07s) 369s --- PASS: TestParallelNewApp/run-35 (0.08s) 369s --- PASS: TestParallelNewApp/run-34 (0.08s) 369s --- PASS: TestParallelNewApp/run-33 (0.06s) 369s --- PASS: TestParallelNewApp/run-32 (0.06s) 369s --- PASS: TestParallelNewApp/run-31 (0.07s) 369s --- PASS: TestParallelNewApp/run-30 (0.08s) 369s --- PASS: TestParallelNewApp/run-29 (0.07s) 369s --- PASS: TestParallelNewApp/run-28 (0.07s) 369s --- PASS: TestParallelNewApp/run-27 (0.08s) 369s --- PASS: TestParallelNewApp/run-26 (0.06s) 369s --- PASS: TestParallelNewApp/run-25 (0.08s) 369s --- PASS: TestParallelNewApp/run-24 (0.08s) 369s --- PASS: TestParallelNewApp/run-23 (0.08s) 369s --- PASS: TestParallelNewApp/run-22 (0.08s) 369s --- PASS: TestParallelNewApp/run-21 (0.07s) 369s --- PASS: TestParallelNewApp/run-20 (0.08s) 369s --- PASS: TestParallelNewApp/run-19 (0.08s) 369s --- PASS: TestParallelNewApp/run-18 (0.09s) 369s --- PASS: TestParallelNewApp/run-17 (0.07s) 369s --- PASS: TestParallelNewApp/run-16 (0.07s) 369s --- PASS: TestParallelNewApp/run-15 (0.07s) 369s --- PASS: TestParallelNewApp/run-14 (0.07s) 369s --- PASS: TestParallelNewApp/run-13 (0.09s) 369s --- PASS: TestParallelNewApp/run-12 (0.07s) 369s --- PASS: TestParallelNewApp/run-11 (0.08s) 369s --- PASS: TestParallelNewApp/run-10 (0.06s) 369s --- PASS: TestParallelNewApp/run-9 (0.08s) 369s --- PASS: TestParallelNewApp/run-8 (0.07s) 369s --- PASS: TestParallelNewApp/run-7 (0.07s) 369s --- PASS: TestParallelNewApp/run-6 (0.07s) 369s --- PASS: TestParallelNewApp/run-5 (0.07s) 369s --- PASS: TestParallelNewApp/run-4 (0.07s) 369s --- PASS: TestParallelNewApp/run-3 (0.07s) 369s --- PASS: TestParallelNewApp/run-2 (0.07s) 369s --- PASS: TestParallelNewApp/run-1 (0.08s) 369s === RUN Example 369s --- PASS: Example (0.16s) 369s PASS 369s ok github.com/canonical/go-dqlite/app 136.381s 370s === RUN TestNew_Default 371s Benchmark done. Results available here: 371s /tmp/dqlite-app-test-341554564/results 371s --- PASS: TestNew_Default (1.16s) 371s === RUN TestNew_KvReadWrite 372s Benchmark done. Results available here: 372s /tmp/dqlite-app-test-25752353/results 373s --- PASS: TestNew_KvReadWrite (1.26s) 373s === RUN TestNew_ClusteredKvReadWrite 375s Benchmark done. Results available here: 375s /tmp/dqlite-app-test-2227274203/results 375s --- PASS: TestNew_ClusteredKvReadWrite (2.39s) 375s === RUN TestNew_ClusteredTimeout 377s --- PASS: TestNew_ClusteredTimeout (2.48s) 377s PASS 377s ok github.com/canonical/go-dqlite/benchmark 7.321s 378s === RUN TestClient_Leader 378s --- PASS: TestClient_Leader (0.05s) 378s === RUN TestClient_Dump 378s --- PASS: TestClient_Dump (0.14s) 378s === RUN TestClient_Cluster 378s --- PASS: TestClient_Cluster (0.06s) 378s === RUN TestClient_Transfer 379s --- PASS: TestClient_Transfer (0.27s) 379s === RUN TestClient_Describe 379s --- PASS: TestClient_Describe (0.07s) 379s === RUN TestMembership 379s --- PASS: TestMembership (0.30s) 379s === RUN TestDefaultNodeStore 379s --- PASS: TestDefaultNodeStore (0.00s) 379s === RUN TestConfigMultiThread 379s --- PASS: TestConfigMultiThread (0.00s) 379s PASS 379s ok github.com/canonical/go-dqlite/client 0.898s 382s ? github.com/canonical/go-dqlite/cmd/dqlite [no test files] 382s ? github.com/canonical/go-dqlite/cmd/dqlite-benchmark [no test files] 382s ? github.com/canonical/go-dqlite/cmd/dqlite-demo [no test files] 382s === RUN TestDriver_Open 382s func.go:15: DEBUG: attempt 1: server @1: connected 382s --- PASS: TestDriver_Open (0.05s) 382s === RUN TestDriver_Prepare 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestDriver_Prepare (0.07s) 383s === RUN TestConn_Exec 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestConn_Exec (0.08s) 383s === RUN TestConn_Query 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestConn_Query (0.07s) 383s === RUN TestConn_QueryRow 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestConn_QueryRow (0.07s) 383s === RUN TestConn_QueryBlob 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestConn_QueryBlob (0.07s) 383s === RUN TestStmt_Exec 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestStmt_Exec (0.07s) 383s === RUN TestStmt_ExecManyParams 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestStmt_ExecManyParams (0.07s) 383s === RUN TestStmt_Query 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestStmt_Query (0.07s) 383s === RUN TestStmt_QueryManyParams 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestStmt_QueryManyParams (0.08s) 383s === RUN TestConn_QueryParams 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestConn_QueryParams (0.06s) 383s === RUN TestConn_QueryManyParams 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestConn_QueryManyParams (0.07s) 383s === RUN TestConn_ExecManyParams 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: TestConn_ExecManyParams (0.07s) 383s === RUN Test_ColumnTypesEmpty 383s driver_test.go:439: this currently fails if the result set is empty, is dqlite skipping the header if empty set? 383s --- SKIP: Test_ColumnTypesEmpty (0.00s) 383s === RUN Test_ColumnTypesExists 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: Test_ColumnTypesExists (0.07s) 383s === RUN Test_ColumnTypesEnd 383s func.go:15: DEBUG: attempt 1: server @1: connected 383s --- PASS: Test_ColumnTypesEnd (0.06s) 383s === RUN Test_ZeroColumns 384s func.go:15: DEBUG: attempt 1: server @1: connected 384s --- PASS: Test_ZeroColumns (0.13s) 384s === RUN TestIntegration_DatabaseSQL 384s func.go:15: DEBUG: attempt 1: server @1: connected 384s --- PASS: TestIntegration_DatabaseSQL (0.37s) 384s === RUN TestIntegration_ConstraintError 384s func.go:15: DEBUG: attempt 1: server @1: connected 384s --- PASS: TestIntegration_ConstraintError (0.36s) 384s === RUN TestIntegration_ExecBindError 384s func.go:15: DEBUG: attempt 1: server @1: connected 384s --- PASS: TestIntegration_ExecBindError (0.13s) 384s === RUN TestIntegration_QueryBindError 385s func.go:15: DEBUG: attempt 1: server @1: connected 385s --- PASS: TestIntegration_QueryBindError (0.09s) 385s === RUN TestIntegration_LargeQuery 385s func.go:15: DEBUG: attempt 1: server @1: connected 385s --- PASS: TestIntegration_LargeQuery (0.38s) 385s === RUN TestIntegration_Recover 385s func.go:15: DEBUG: attempt 1: server @1: connected 385s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 385s func.go:15: DEBUG: attempt 1: server @1: connected 385s --- PASS: TestIntegration_Recover (0.38s) 385s === RUN TestIntegration_PingOnlyWorksOnceLeaderElected 386s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 386s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 386s func.go:15: WARN: attempt 1: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused 386s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 386s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 386s func.go:15: WARN: attempt 2: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused 386s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 386s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 386s func.go:15: WARN: attempt 3: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused 387s func.go:15: WARN: attempt 1: server @1: no known leader 387s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 387s func.go:15: WARN: attempt 1: server @2: reported leader server is not the leader 387s func.go:15: WARN: attempt 2: server @1: no known leader 387s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 387s func.go:15: WARN: attempt 2: server @2: reported leader server is not the leader 388s func.go:15: WARN: attempt 3: server @1: no known leader 388s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 388s func.go:15: WARN: attempt 3: server @2: reported leader server is not the leader 388s func.go:15: WARN: attempt 4: server @1: no known leader 388s func.go:15: DEBUG: attempt 4: server @2: connect to reported leader @1 388s func.go:15: WARN: attempt 4: server @2: reported leader server is not the leader 389s func.go:15: WARN: attempt 5: server @1: no known leader 389s func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @1 389s func.go:15: WARN: attempt 5: server @2: reported leader server is not the leader 390s func.go:15: WARN: attempt 6: server @1: no known leader 390s func.go:15: WARN: attempt 6: server @2: no known leader 391s func.go:15: WARN: attempt 7: server @1: no known leader 391s func.go:15: WARN: attempt 7: server @2: no known leader 392s func.go:15: WARN: attempt 8: server @1: no known leader 392s func.go:15: WARN: attempt 8: server @2: no known leader 393s func.go:15: DEBUG: attempt 9: server @1: connected 394s --- PASS: TestIntegration_PingOnlyWorksOnceLeaderElected (8.17s) 394s === RUN TestIntegration_HighAvailability 394s func.go:15: DEBUG: attempt 1: server @1: connected 396s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 396s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 396s func.go:15: WARN: attempt 1: server @2: no known leader 396s func.go:15: WARN: attempt 1: server @3: no known leader 396s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 396s func.go:15: WARN: attempt 2: server @2: no known leader 396s func.go:15: WARN: attempt 2: server @3: no known leader 397s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 397s func.go:15: WARN: attempt 3: server @2: no known leader 397s func.go:15: WARN: attempt 3: server @3: no known leader 397s func.go:15: WARN: attempt 4: server @1: dial: dial unix @1: connect: connection refused 397s func.go:15: WARN: attempt 4: server @2: no known leader 397s func.go:15: WARN: attempt 4: server @3: no known leader 398s func.go:15: WARN: attempt 5: server @1: dial: dial unix @1: connect: connection refused 398s func.go:15: WARN: attempt 5: server @2: no known leader 398s func.go:15: WARN: attempt 5: server @3: no known leader 399s func.go:15: WARN: attempt 6: server @1: dial: dial unix @1: connect: connection refused 399s func.go:15: DEBUG: attempt 6: server @2: connected 400s --- PASS: TestIntegration_HighAvailability (6.03s) 400s === RUN TestIntegration_LeadershipTransfer 400s func.go:15: DEBUG: attempt 1: server @1: connected 400s func.go:15: DEBUG: leadership lost (10250 - not leader) 400s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 400s func.go:15: DEBUG: attempt 1: server @1: connected 400s --- PASS: TestIntegration_LeadershipTransfer (0.37s) 400s === RUN TestIntegration_LeadershipTransfer_Tx 400s func.go:15: DEBUG: attempt 1: server @1: connected 400s func.go:15: DEBUG: leadership lost (10250 - not leader) 400s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 400s func.go:15: DEBUG: attempt 1: server @1: connected 400s --- PASS: TestIntegration_LeadershipTransfer_Tx (0.35s) 400s === RUN TestOptions 400s --- PASS: TestOptions (0.00s) 400s === RUN TestIntegration_ColumnTypeName 400s func.go:15: DEBUG: attempt 1: server @1: connected 400s --- PASS: TestIntegration_ColumnTypeName (0.11s) 400s === RUN TestIntegration_SqlNullTime 400s func.go:15: DEBUG: attempt 1: server @1: connected 401s --- PASS: TestIntegration_SqlNullTime (0.13s) 401s PASS 401s ok github.com/canonical/go-dqlite/driver 18.073s 401s === RUN TestNode_Create 401s --- PASS: TestNode_Create (0.05s) 401s === RUN TestNode_Start 401s --- PASS: TestNode_Start (0.08s) 401s === RUN TestNode_Restart 401s --- PASS: TestNode_Restart (0.15s) 401s === RUN TestNode_Start_Inet 401s --- PASS: TestNode_Start_Inet (0.08s) 401s === RUN TestNode_Leader 401s --- PASS: TestNode_Leader (0.08s) 401s === RUN TestNode_Autorecovery 401s --- PASS: TestNode_Autorecovery (0.07s) 401s PASS 401s ok github.com/canonical/go-dqlite/internal/bindings 0.514s 403s === RUN TestMessage_StaticBytesAlignment 403s --- PASS: TestMessage_StaticBytesAlignment (0.00s) 403s === RUN TestMessage_putBlob 403s === RUN TestMessage_putBlob/16 403s === RUN TestMessage_putBlob/16#01 403s === RUN TestMessage_putBlob/24 403s --- PASS: TestMessage_putBlob (0.00s) 403s --- PASS: TestMessage_putBlob/16 (0.00s) 403s --- PASS: TestMessage_putBlob/16#01 (0.00s) 403s --- PASS: TestMessage_putBlob/24 (0.00s) 403s === RUN TestMessage_putString 403s === RUN TestMessage_putString/hello 403s === RUN TestMessage_putString/hello!! 403s === RUN TestMessage_putString/hello_world 403s --- PASS: TestMessage_putString (0.00s) 403s --- PASS: TestMessage_putString/hello (0.00s) 403s --- PASS: TestMessage_putString/hello!! (0.00s) 403s --- PASS: TestMessage_putString/hello_world (0.00s) 403s === RUN TestMessage_putUint8 403s --- PASS: TestMessage_putUint8 (0.00s) 403s === RUN TestMessage_putUint16 403s --- PASS: TestMessage_putUint16 (0.00s) 403s === RUN TestMessage_putUint32 403s --- PASS: TestMessage_putUint32 (0.00s) 403s === RUN TestMessage_putUint64 403s --- PASS: TestMessage_putUint64 (0.00s) 403s === RUN TestMessage_putNamedValues 403s --- PASS: TestMessage_putNamedValues (0.00s) 403s === RUN TestMessage_putNamedValues32 403s --- PASS: TestMessage_putNamedValues32 (0.00s) 403s === RUN TestMessage_putHeader 403s --- PASS: TestMessage_putHeader (0.00s) 403s === RUN TestMessage_getString 403s === RUN TestMessage_getString/hello 403s === RUN TestMessage_getString/hello!! 403s === RUN TestMessage_getString/hello!!! 403s === RUN TestMessage_getString/hello_world 403s --- PASS: TestMessage_getString (0.00s) 403s --- PASS: TestMessage_getString/hello (0.00s) 403s --- PASS: TestMessage_getString/hello!! (0.00s) 403s --- PASS: TestMessage_getString/hello!!! (0.00s) 403s --- PASS: TestMessage_getString/hello_world (0.00s) 403s === RUN TestMessage_getBlob 403s === RUN TestMessage_getBlob/16 403s === RUN TestMessage_getBlob/16#01 403s === RUN TestMessage_getBlob/24 403s --- PASS: TestMessage_getBlob (0.00s) 403s --- PASS: TestMessage_getBlob/16 (0.00s) 403s --- PASS: TestMessage_getBlob/16#01 (0.00s) 403s --- PASS: TestMessage_getBlob/24 (0.00s) 403s === RUN TestMessage_getString_Overflow_WordBoundary 403s --- PASS: TestMessage_getString_Overflow_WordBoundary (0.00s) 403s === RUN TestConnector_Success 403s connector_test.go:304: DEBUG: attempt 1: server @test-0: connected 403s --- PASS: TestConnector_Success (0.05s) 403s === RUN TestConnector_LimitRetries 403s connector_test.go:304: WARN: attempt 1: server @test-123: dial: dial unix @test-123: connect: connection refused 403s connector_test.go:304: WARN: attempt 2: server @test-123: dial: dial unix @test-123: connect: connection refused 404s connector_test.go:304: WARN: attempt 3: server @test-123: dial: dial unix @test-123: connect: connection refused 404s --- PASS: TestConnector_LimitRetries (0.60s) 404s === RUN TestConnector_DialTimeout 404s connector_test.go:64: Skipping network test 404s --- SKIP: TestConnector_DialTimeout (0.00s) 404s === RUN TestConnector_EmptyNodeStore 404s --- PASS: TestConnector_EmptyNodeStore (0.20s) 404s === RUN TestConnector_ContextCanceled 404s connector_test.go:99: Skipping network test 404s --- SKIP: TestConnector_ContextCanceled (0.00s) 404s === RUN TestConnector_AttemptTimeout 404s func.go:15: WARN: attempt 1: server @1234: call leader (budget 99.944905ms): receive: header: read unix @->@1234: i/o timeout 404s func.go:15: WARN: attempt 2: server @1234: call leader (budget 99.906705ms): receive: header: read unix @->@1234: i/o timeout 404s --- PASS: TestConnector_AttemptTimeout (0.40s) 404s === RUN TestProtocol_RequestWithDynamicBuffer 404s func.go:15: DEBUG: attempt 1: server @test-0: connected 404s --- PASS: TestProtocol_RequestWithDynamicBuffer (0.11s) 404s === RUN TestProtocol_Prepare 404s func.go:15: DEBUG: attempt 1: server @test-0: connected 405s --- PASS: TestProtocol_Prepare (0.07s) 405s PASS 405s ok github.com/canonical/go-dqlite/internal/protocol 1.445s 405s ? github.com/canonical/go-dqlite/internal/shell [no test files] 405s === RUN Test_TestFunc 405s func.go:15: INFO: hello 405s --- PASS: Test_TestFunc (0.00s) 405s === RUN TestLevel_String 405s --- PASS: TestLevel_String (0.00s) 405s PASS 405s ok github.com/canonical/go-dqlite/logging 0.003s 405s ? github.com/canonical/go-dqlite/tracing [no test files] 405s autopkgtest [09:53:55]: test dh-golang-autopkgtest: -----------------------] 406s autopkgtest [09:53:56]: test dh-golang-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 406s dh-golang-autopkgtest PASS 407s autopkgtest [09:53:57]: @@@@@@@@@@@@@@@@@@@@ summary 407s dh-golang-autopkgtest PASS 421s Creating nova instance adt-noble-s390x-golang-github-canonical-go-dqlite-20240320-094710-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240319.img (UUID fdb88291-221e-4465-b557-6bcc22878017)...