0s autopkgtest [16:12:39]: starting date and time: 2024-03-19 16:12:39+0000 0s autopkgtest [16:12:39]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [16:12:39]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.i0piitpw/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:sqlite3,src:readline --apt-upgrade golang-github-cowsql-go-cowsql --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=sqlite3/3.45.1-1ubuntu1 readline/8.2-3.1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-s390x-2.secgroup --name adt-noble-s390x-golang-github-cowsql-go-cowsql-20240319-161238-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://ftpmaster.internal/ubuntu/ 97s autopkgtest [16:14:16]: testbed dpkg architecture: s390x 97s autopkgtest [16:14:16]: testbed apt version: 2.7.12 97s autopkgtest [16:14:16]: @@@@@@@@@@@@@@@@@@@@ test bed setup 99s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 100s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [486 kB] 100s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [52.7 kB] 100s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3784 kB] 101s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 101s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main s390x Packages [642 kB] 101s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main s390x c-n-f Metadata [3032 B] 101s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x Packages [1372 B] 101s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted s390x c-n-f Metadata [116 B] 101s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x Packages [3968 kB] 102s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe s390x c-n-f Metadata [7292 B] 102s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x Packages [34.4 kB] 102s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse s390x c-n-f Metadata [116 B] 104s Fetched 9102 kB in 5s (1883 kB/s) 104s Reading package lists... 108s Reading package lists... 108s Building dependency tree... 108s Reading state information... 108s Calculating upgrade... 109s The following packages will be upgraded: 109s libsqlite3-0 readline-common 110s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 110s Need to get 804 kB of archives. 110s After this operation, 0 B of additional disk space will be used. 110s Get:1 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libsqlite3-0 s390x 3.45.1-1ubuntu1 [747 kB] 110s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main s390x readline-common all 8.2-3.1 [56.4 kB] 111s Fetched 804 kB in 2s (423 kB/s) 111s (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 ... 52171 files and directories currently installed.) 111s Preparing to unpack .../libsqlite3-0_3.45.1-1ubuntu1_s390x.deb ... 111s Unpacking libsqlite3-0:s390x (3.45.1-1ubuntu1) over (3.45.1-1) ... 111s Preparing to unpack .../readline-common_8.2-3.1_all.deb ... 111s Unpacking readline-common (8.2-3.1) over (8.2-3) ... 111s Setting up libsqlite3-0:s390x (3.45.1-1ubuntu1) ... 111s Setting up readline-common (8.2-3.1) ... 111s Processing triggers for man-db (2.12.0-3) ... 111s Processing triggers for install-info (7.1-3) ... 111s Processing triggers for libc-bin (2.39-0ubuntu2) ... 111s Reading package lists... 112s Building dependency tree... 112s Reading state information... 112s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 113s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 113s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 113s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 113s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 115s Reading package lists... 115s Reading package lists... 115s Building dependency tree... 115s Reading state information... 115s Calculating upgrade... 116s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 116s Reading package lists... 116s Building dependency tree... 116s Reading state information... 116s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 119s autopkgtest [16:14:38]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Tue Feb 13 23:45:46 UTC 2024 120s autopkgtest [16:14:39]: @@@@@@@@@@@@@@@@@@@@ apt-source golang-github-cowsql-go-cowsql 123s Get:1 http://ftpmaster.internal/ubuntu noble/universe golang-github-cowsql-go-cowsql 1.22.0-2 (dsc) [2726 B] 123s Get:2 http://ftpmaster.internal/ubuntu noble/universe golang-github-cowsql-go-cowsql 1.22.0-2 (tar) [107 kB] 123s Get:3 http://ftpmaster.internal/ubuntu noble/universe golang-github-cowsql-go-cowsql 1.22.0-2 (diff) [3800 B] 123s gpgv: Signature made Thu Dec 28 01:43:17 2023 UTC 123s gpgv: using RSA key D41A7AD07DF6C5FCA7489F1C29EEE2D6ECF442F9 123s gpgv: issuer "gibmat@debian.org" 123s gpgv: Can't check signature: No public key 123s dpkg-source: warning: cannot verify inline signature for ./golang-github-cowsql-go-cowsql_1.22.0-2.dsc: no acceptable signature found 123s autopkgtest [16:14:42]: testing package golang-github-cowsql-go-cowsql version 1.22.0-2 125s autopkgtest [16:14:44]: build not needed 130s autopkgtest [16:14:49]: test dh-golang-autopkgtest: preparing testbed 135s Reading package lists... 136s Building dependency tree... 136s Reading state information... 136s Starting pkgProblemResolver with broken count: 0 136s Starting 2 pkgProblemResolver with broken count: 0 136s Done 137s The following additional packages will be installed: 137s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 137s cpp-13-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit dh-autoreconf 137s dh-golang dh-strip-nondeterminism dwz g++ g++-13 g++-13-s390x-linux-gnu 137s g++-s390x-linux-gnu gcc gcc-13 gcc-13-s390x-linux-gnu gcc-s390x-linux-gnu 137s gettext go-cowsql golang-1.22-go golang-1.22-src golang-any 137s golang-github-burntsushi-toml-dev golang-github-cowsql-go-cowsql-dev 137s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 137s golang-github-fsnotify-fsnotify-dev golang-github-ghodss-yaml-dev 137s golang-github-google-renameio-dev golang-github-hashicorp-hcl-dev 137s golang-github-kr-fs-dev golang-github-magiconair-properties-dev 137s golang-github-mattn-go-runewidth-dev golang-github-mattn-go-sqlite3-dev 137s golang-github-mitchellh-mapstructure-dev golang-github-pelletier-go-toml-dev 137s golang-github-pelletier-go-toml.v2-dev golang-github-peterh-liner-dev 137s golang-github-pkg-errors-dev golang-github-pkg-sftp-dev 137s golang-github-pmezard-go-difflib-dev golang-github-rican7-retry-dev 137s golang-github-rivo-uniseg-dev golang-github-russross-blackfriday-v2-dev 137s golang-github-shurcool-sanitized-anchor-name-dev 137s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 137s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 137s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 137s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 137s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 137s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 137s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 137s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 137s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcowsql-dev libcowsql0 137s libcrypt-dev libdebhelper-perl libdw1 libfile-stripnondeterminism-perl 137s libgcc-13-dev libgomp1 libisl23 libitm1 libmpc3 libnsl-dev libraft-dev 137s libraft0 libsqlite3-dev libstdc++-13-dev libsub-override-perl libtirpc-dev 137s libtool libubsan1 linux-libc-dev m4 po-debconf rpcsvc-proto 137s Suggested packages: 137s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 137s cpp-13-doc dh-make g++-multilib g++-13-multilib gcc-13-doc gcc-multilib 137s manpages-dev flex bison gdb gcc-doc gcc-13-multilib gdb-s390x-linux-gnu 137s gettext-doc libasprintf-dev libgettextpo-dev bzr | brz git mercurial 137s subversion glibc-doc sqlite3-doc libstdc++-13-doc libtool-doc gfortran 137s | fortran95-compiler gcj-jdk m4-doc libmail-box-perl 137s Recommended packages: 137s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 137s libltdl-dev libmail-sendmail-perl 137s The following NEW packages will be installed: 137s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 137s cpp cpp-13 cpp-13-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit 137s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 137s g++-13-s390x-linux-gnu g++-s390x-linux-gnu gcc gcc-13 gcc-13-s390x-linux-gnu 137s gcc-s390x-linux-gnu gettext go-cowsql golang-1.22-go golang-1.22-src 137s golang-any golang-github-burntsushi-toml-dev 137s golang-github-cowsql-go-cowsql-dev golang-github-cpuguy83-go-md2man-v2-dev 137s golang-github-davecgh-go-spew-dev golang-github-fsnotify-fsnotify-dev 137s golang-github-ghodss-yaml-dev golang-github-google-renameio-dev 137s golang-github-hashicorp-hcl-dev golang-github-kr-fs-dev 137s golang-github-magiconair-properties-dev golang-github-mattn-go-runewidth-dev 137s golang-github-mattn-go-sqlite3-dev golang-github-mitchellh-mapstructure-dev 137s golang-github-pelletier-go-toml-dev golang-github-pelletier-go-toml.v2-dev 137s golang-github-peterh-liner-dev golang-github-pkg-errors-dev 137s golang-github-pkg-sftp-dev golang-github-pmezard-go-difflib-dev 137s golang-github-rican7-retry-dev golang-github-rivo-uniseg-dev 137s golang-github-russross-blackfriday-v2-dev 137s golang-github-shurcool-sanitized-anchor-name-dev 137s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 137s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 137s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 137s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 137s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 137s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 137s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 137s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 137s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcowsql-dev libcowsql0 137s libcrypt-dev libdebhelper-perl libdw1 libfile-stripnondeterminism-perl 137s libgcc-13-dev libgomp1 libisl23 libitm1 libmpc3 libnsl-dev libraft-dev 137s libraft0 libsqlite3-dev libstdc++-13-dev libsub-override-perl libtirpc-dev 137s libtool libubsan1 linux-libc-dev m4 po-debconf rpcsvc-proto 137s 0 upgraded, 102 newly installed, 0 to remove and 0 not upgraded. 137s Need to get 122 MB/122 MB of archives. 137s After this operation, 526 MB of additional disk space will be used. 137s Get:1 /tmp/autopkgtest.qqsKmC/1-autopkgtest-satdep.deb autopkgtest-satdep s390x 0 [888 B] 137s Get:2 http://ftpmaster.internal/ubuntu noble/main s390x m4 s390x 1.4.19-4 [255 kB] 138s Get:3 http://ftpmaster.internal/ubuntu noble/main s390x autoconf all 2.71-3 [339 kB] 139s Get:4 http://ftpmaster.internal/ubuntu noble/main s390x autotools-dev all 20220109.1 [44.9 kB] 139s Get:5 http://ftpmaster.internal/ubuntu noble/main s390x automake all 1:1.16.5-1.3ubuntu1 [558 kB] 140s Get:6 http://ftpmaster.internal/ubuntu noble/main s390x autopoint all 0.21-14ubuntu1 [422 kB] 140s Get:7 http://ftpmaster.internal/ubuntu noble/main s390x libc-dev-bin s390x 2.39-0ubuntu2 [20.2 kB] 140s Get:8 http://ftpmaster.internal/ubuntu noble/main s390x linux-libc-dev s390x 6.8.0-11.11 [1590 kB] 141s Get:9 http://ftpmaster.internal/ubuntu noble/main s390x libcrypt-dev s390x 1:4.4.36-4 [135 kB] 141s Get:10 http://ftpmaster.internal/ubuntu noble/main s390x libtirpc-dev s390x 1.3.4+ds-1build1 [225 kB] 141s Get:11 http://ftpmaster.internal/ubuntu noble/main s390x libnsl-dev s390x 1.3.0-3 [73.5 kB] 141s Get:12 http://ftpmaster.internal/ubuntu noble/main s390x rpcsvc-proto s390x 1.4.2-0ubuntu6 [64.7 kB] 141s Get:13 http://ftpmaster.internal/ubuntu noble/main s390x libc6-dev s390x 2.39-0ubuntu2 [1629 kB] 141s Get:14 http://ftpmaster.internal/ubuntu noble/main s390x libisl23 s390x 0.26-3 [722 kB] 142s Get:15 http://ftpmaster.internal/ubuntu noble/main s390x libmpc3 s390x 1.3.1-1 [54.9 kB] 142s Get:16 http://ftpmaster.internal/ubuntu noble/main s390x cpp-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [9929 kB] 143s Get:17 http://ftpmaster.internal/ubuntu noble/main s390x cpp-13 s390x 13.2.0-17ubuntu2 [1026 B] 143s Get:18 http://ftpmaster.internal/ubuntu noble/main s390x cpp-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [5308 B] 143s Get:19 http://ftpmaster.internal/ubuntu noble/main s390x cpp s390x 4:13.2.0-7ubuntu1 [22.4 kB] 143s Get:20 http://ftpmaster.internal/ubuntu noble/main s390x libcc1-0 s390x 14-20240303-1ubuntu1 [49.9 kB] 143s Get:21 http://ftpmaster.internal/ubuntu noble/main s390x libgomp1 s390x 14-20240303-1ubuntu1 [151 kB] 143s Get:22 http://ftpmaster.internal/ubuntu noble/main s390x libitm1 s390x 14-20240303-1ubuntu1 [31.1 kB] 143s Get:23 http://ftpmaster.internal/ubuntu noble/main s390x libatomic1 s390x 14-20240303-1ubuntu1 [9392 B] 143s Get:24 http://ftpmaster.internal/ubuntu noble/main s390x libasan8 s390x 14-20240303-1ubuntu1 [2998 kB] 143s Get:25 http://ftpmaster.internal/ubuntu noble/main s390x libubsan1 s390x 14-20240303-1ubuntu1 [1186 kB] 143s Get:26 http://ftpmaster.internal/ubuntu noble/main s390x libgcc-13-dev s390x 13.2.0-17ubuntu2 [1003 kB] 143s Get:27 http://ftpmaster.internal/ubuntu noble/main s390x gcc-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [19.1 MB] 145s Get:28 http://ftpmaster.internal/ubuntu noble/main s390x gcc-13 s390x 13.2.0-17ubuntu2 [467 kB] 145s Get:29 http://ftpmaster.internal/ubuntu noble/main s390x gcc-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [1208 B] 145s Get:30 http://ftpmaster.internal/ubuntu noble/main s390x gcc s390x 4:13.2.0-7ubuntu1 [5014 B] 145s Get:31 http://ftpmaster.internal/ubuntu noble/main s390x libstdc++-13-dev s390x 13.2.0-17ubuntu2 [2430 kB] 145s Get:32 http://ftpmaster.internal/ubuntu noble/main s390x g++-13-s390x-linux-gnu s390x 13.2.0-17ubuntu2 [11.3 MB] 145s Get:33 http://ftpmaster.internal/ubuntu noble/main s390x g++-13 s390x 13.2.0-17ubuntu2 [14.4 kB] 145s Get:34 http://ftpmaster.internal/ubuntu noble/main s390x g++-s390x-linux-gnu s390x 4:13.2.0-7ubuntu1 [956 B] 145s Get:35 http://ftpmaster.internal/ubuntu noble/main s390x g++ s390x 4:13.2.0-7ubuntu1 [1096 B] 145s Get:36 http://ftpmaster.internal/ubuntu noble/main s390x build-essential s390x 12.10ubuntu1 [4930 B] 145s Get:37 http://ftpmaster.internal/ubuntu noble/main s390x libdebhelper-perl all 13.14.1ubuntu1 [89.5 kB] 145s Get:38 http://ftpmaster.internal/ubuntu noble/main s390x libtool all 2.4.7-7 [166 kB] 145s Get:39 http://ftpmaster.internal/ubuntu noble/main s390x dh-autoreconf all 20 [16.1 kB] 145s Get:40 http://ftpmaster.internal/ubuntu noble/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 145s Get:41 http://ftpmaster.internal/ubuntu noble/main s390x libsub-override-perl all 0.10-1 [10.0 kB] 145s Get:42 http://ftpmaster.internal/ubuntu noble/main s390x libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 145s Get:43 http://ftpmaster.internal/ubuntu noble/main s390x dh-strip-nondeterminism all 1.13.1-1 [5362 B] 145s Get:44 http://ftpmaster.internal/ubuntu noble/main s390x libdw1 s390x 0.190-1 [282 kB] 145s Get:45 http://ftpmaster.internal/ubuntu noble/main s390x debugedit s390x 1:5.0-5 [47.5 kB] 145s Get:46 http://ftpmaster.internal/ubuntu noble/main s390x dwz s390x 0.15-1 [108 kB] 145s Get:47 http://ftpmaster.internal/ubuntu noble/main s390x gettext s390x 0.21-14ubuntu1 [917 kB] 145s Get:48 http://ftpmaster.internal/ubuntu noble/main s390x intltool-debian all 0.35.0+20060710.6 [23.2 kB] 145s Get:49 http://ftpmaster.internal/ubuntu noble/main s390x po-debconf all 1.0.21+nmu1 [233 kB] 145s Get:50 http://ftpmaster.internal/ubuntu noble/main s390x debhelper all 13.14.1ubuntu1 [869 kB] 145s Get:51 http://ftpmaster.internal/ubuntu noble/universe s390x libraft0 s390x 0.22.0-1 [101 kB] 145s Get:52 http://ftpmaster.internal/ubuntu noble/universe s390x libcowsql0 s390x 1.15.4-1 [79.1 kB] 145s Get:53 http://ftpmaster.internal/ubuntu noble/universe s390x go-cowsql s390x 1.22.0-2 [8600 kB] 146s Get:54 http://ftpmaster.internal/ubuntu noble/main s390x golang-1.22-src all 1.22.1-1 [19.7 MB] 147s Get:55 http://ftpmaster.internal/ubuntu noble/main s390x golang-1.22-go s390x 1.22.1-1 [24.7 MB] 149s Get:56 http://ftpmaster.internal/ubuntu noble/main s390x golang-src all 2:1.22~2 [4992 B] 149s Get:57 http://ftpmaster.internal/ubuntu noble/main s390x golang-go s390x 2:1.22~2 [43.9 kB] 149s Get:58 http://ftpmaster.internal/ubuntu noble/universe s390x golang-any s390x 2:1.22~2 [2842 B] 149s Get:59 http://ftpmaster.internal/ubuntu noble-proposed/main s390x libsqlite3-dev s390x 3.45.1-1ubuntu1 [973 kB] 149s Get:60 http://ftpmaster.internal/ubuntu noble/universe s390x libcowsql-dev s390x 1.15.4-1 [106 kB] 149s Get:61 http://ftpmaster.internal/ubuntu noble/universe s390x libraft-dev s390x 0.22.0-1 [154 kB] 149s Get:62 http://ftpmaster.internal/ubuntu noble/universe s390x golang-gopkg-yaml.v2-dev all 2.4.0-4 [62.6 kB] 149s Get:63 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-ghodss-yaml-dev all 1.0.0+git20220118.d8423dc-2 [16.6 kB] 149s Get:64 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-google-renameio-dev all 2.0.0-2 [10.3 kB] 149s Get:65 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-mattn-go-sqlite3-dev s390x 1.14.19~ds1-1 [85.5 kB] 149s Get:66 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-rivo-uniseg-dev all 0.4.4-1 [256 kB] 149s Get:67 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-mattn-go-runewidth-dev all 0.0.15-2 [17.2 kB] 149s Get:68 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-peterh-liner-dev all 1.2.2-1 [21.3 kB] 149s Get:69 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-pkg-errors-dev all 0.9.1-3 [13.2 kB] 149s Get:70 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-rican7-retry-dev all 0.3.1-2 [7740 B] 149s Get:71 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-pmezard-go-difflib-dev all 1.0.0-3 [12.4 kB] 149s Get:72 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-shurcool-sanitized-anchor-name-dev all 1.0.0-3 [3590 B] 149s Get:73 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-russross-blackfriday-v2-dev all 2.1.0-1 [79.6 kB] 149s Get:74 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-cpuguy83-go-md2man-v2-dev all 2.0.3+ds1-1 [10.4 kB] 149s Get:75 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-pflag-dev all 1.0.6~git20210604-d5e0c0615ace-1 [43.6 kB] 149s Get:76 http://ftpmaster.internal/ubuntu noble/universe s390x golang-golang-x-sys-dev all 0.17.0-1 [424 kB] 149s Get:77 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-fsnotify-fsnotify-dev all 1.7.0-1 [39.1 kB] 149s Get:78 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-davecgh-go-spew-dev all 1.1.1-3 [31.1 kB] 149s Get:79 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-hashicorp-hcl-dev all 1.0.0-1.1 [58.9 kB] 149s Get:80 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-magiconair-properties-dev all 1.8.7-1 [27.3 kB] 149s Get:81 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-mitchellh-mapstructure-dev all 1.5.0+git20231216.8508981-1 [28.3 kB] 149s Get:82 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-burntsushi-toml-dev all 1.3.2-2 [115 kB] 149s Get:83 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-pelletier-go-toml-dev all 1.9.5-1build1 [79.6 kB] 149s Get:84 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-pelletier-go-toml.v2-dev all 2.1.1-1 [876 kB] 149s Get:85 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-kr-fs-dev all 0.1.0-2 [6344 B] 149s Get:86 http://ftpmaster.internal/ubuntu noble/universe s390x golang-golang-x-term-dev all 0.17.0-1 [14.7 kB] 149s Get:87 http://ftpmaster.internal/ubuntu noble/universe s390x golang-golang-x-text-dev all 0.14.0-1 [4646 kB] 150s Get:88 http://ftpmaster.internal/ubuntu noble/universe s390x golang-golang-x-crypto-dev all 1:0.19.0-1 [1712 kB] 150s Get:89 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-pkg-sftp-dev all 1.13.6-1 [104 kB] 150s Get:90 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-afero-dev all 1.11.0-1 [59.6 kB] 150s Get:91 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-cast-dev all 1.6.0-1 [14.3 kB] 150s Get:92 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-stretchr-objx-dev all 0.5.0-1 [26.5 kB] 150s Get:93 http://ftpmaster.internal/ubuntu noble/universe s390x golang-gopkg-yaml.v3-dev all 3.0.1-3 [76.1 kB] 150s Get:94 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-stretchr-testify-dev all 1.8.4-1 [72.8 kB] 150s Get:95 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-jwalterweatherman-dev all 1.1.0+really1.1.0-2 [6828 B] 150s Get:96 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-subosito-gotenv-dev all 1.3.0-1 [8808 B] 150s Get:97 http://ftpmaster.internal/ubuntu noble/universe s390x golang-gopkg-ini.v1-dev all 1.67.0-1 [40.8 kB] 150s Get:98 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-viper-dev all 1.12.0-1 [71.0 kB] 150s Get:99 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-spf13-cobra-dev all 1.8.0-1 [74.5 kB] 150s Get:100 http://ftpmaster.internal/ubuntu noble/universe s390x golang-golang-x-sync-dev all 0.6.0-1 [18.9 kB] 150s Get:101 http://ftpmaster.internal/ubuntu noble/universe s390x golang-github-cowsql-go-cowsql-dev all 1.22.0-2 [97.2 kB] 150s Get:102 http://ftpmaster.internal/ubuntu noble/main s390x dh-golang all 1.62 [25.2 kB] 151s Fetched 122 MB in 14s (8968 kB/s) 151s Selecting previously unselected package m4. 151s (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 ... 52171 files and directories currently installed.) 151s Preparing to unpack .../000-m4_1.4.19-4_s390x.deb ... 151s Unpacking m4 (1.4.19-4) ... 151s Selecting previously unselected package autoconf. 151s Preparing to unpack .../001-autoconf_2.71-3_all.deb ... 151s Unpacking autoconf (2.71-3) ... 151s Selecting previously unselected package autotools-dev. 151s Preparing to unpack .../002-autotools-dev_20220109.1_all.deb ... 151s Unpacking autotools-dev (20220109.1) ... 151s Selecting previously unselected package automake. 151s Preparing to unpack .../003-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 151s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 151s Selecting previously unselected package autopoint. 151s Preparing to unpack .../004-autopoint_0.21-14ubuntu1_all.deb ... 151s Unpacking autopoint (0.21-14ubuntu1) ... 151s Selecting previously unselected package libc-dev-bin. 151s Preparing to unpack .../005-libc-dev-bin_2.39-0ubuntu2_s390x.deb ... 151s Unpacking libc-dev-bin (2.39-0ubuntu2) ... 151s Selecting previously unselected package linux-libc-dev:s390x. 151s Preparing to unpack .../006-linux-libc-dev_6.8.0-11.11_s390x.deb ... 151s Unpacking linux-libc-dev:s390x (6.8.0-11.11) ... 151s Selecting previously unselected package libcrypt-dev:s390x. 151s Preparing to unpack .../007-libcrypt-dev_1%3a4.4.36-4_s390x.deb ... 152s Unpacking libcrypt-dev:s390x (1:4.4.36-4) ... 152s Selecting previously unselected package libtirpc-dev:s390x. 152s Preparing to unpack .../008-libtirpc-dev_1.3.4+ds-1build1_s390x.deb ... 152s Unpacking libtirpc-dev:s390x (1.3.4+ds-1build1) ... 152s Selecting previously unselected package libnsl-dev:s390x. 152s Preparing to unpack .../009-libnsl-dev_1.3.0-3_s390x.deb ... 152s Unpacking libnsl-dev:s390x (1.3.0-3) ... 152s Selecting previously unselected package rpcsvc-proto. 152s Preparing to unpack .../010-rpcsvc-proto_1.4.2-0ubuntu6_s390x.deb ... 152s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 152s Selecting previously unselected package libc6-dev:s390x. 152s Preparing to unpack .../011-libc6-dev_2.39-0ubuntu2_s390x.deb ... 152s Unpacking libc6-dev:s390x (2.39-0ubuntu2) ... 152s Selecting previously unselected package libisl23:s390x. 152s Preparing to unpack .../012-libisl23_0.26-3_s390x.deb ... 152s Unpacking libisl23:s390x (0.26-3) ... 152s Selecting previously unselected package libmpc3:s390x. 152s Preparing to unpack .../013-libmpc3_1.3.1-1_s390x.deb ... 152s Unpacking libmpc3:s390x (1.3.1-1) ... 152s Selecting previously unselected package cpp-13-s390x-linux-gnu. 152s Preparing to unpack .../014-cpp-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 152s Unpacking cpp-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 152s Selecting previously unselected package cpp-13. 152s Preparing to unpack .../015-cpp-13_13.2.0-17ubuntu2_s390x.deb ... 152s Unpacking cpp-13 (13.2.0-17ubuntu2) ... 152s Selecting previously unselected package cpp-s390x-linux-gnu. 152s Preparing to unpack .../016-cpp-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 152s Unpacking cpp-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 152s Selecting previously unselected package cpp. 152s Preparing to unpack .../017-cpp_4%3a13.2.0-7ubuntu1_s390x.deb ... 152s Unpacking cpp (4:13.2.0-7ubuntu1) ... 152s Selecting previously unselected package libcc1-0:s390x. 152s Preparing to unpack .../018-libcc1-0_14-20240303-1ubuntu1_s390x.deb ... 152s Unpacking libcc1-0:s390x (14-20240303-1ubuntu1) ... 152s Selecting previously unselected package libgomp1:s390x. 152s Preparing to unpack .../019-libgomp1_14-20240303-1ubuntu1_s390x.deb ... 152s Unpacking libgomp1:s390x (14-20240303-1ubuntu1) ... 152s Selecting previously unselected package libitm1:s390x. 152s Preparing to unpack .../020-libitm1_14-20240303-1ubuntu1_s390x.deb ... 152s Unpacking libitm1:s390x (14-20240303-1ubuntu1) ... 152s Selecting previously unselected package libatomic1:s390x. 152s Preparing to unpack .../021-libatomic1_14-20240303-1ubuntu1_s390x.deb ... 152s Unpacking libatomic1:s390x (14-20240303-1ubuntu1) ... 152s Selecting previously unselected package libasan8:s390x. 152s Preparing to unpack .../022-libasan8_14-20240303-1ubuntu1_s390x.deb ... 152s Unpacking libasan8:s390x (14-20240303-1ubuntu1) ... 152s Selecting previously unselected package libubsan1:s390x. 152s Preparing to unpack .../023-libubsan1_14-20240303-1ubuntu1_s390x.deb ... 152s Unpacking libubsan1:s390x (14-20240303-1ubuntu1) ... 152s Selecting previously unselected package libgcc-13-dev:s390x. 152s Preparing to unpack .../024-libgcc-13-dev_13.2.0-17ubuntu2_s390x.deb ... 152s Unpacking libgcc-13-dev:s390x (13.2.0-17ubuntu2) ... 152s Selecting previously unselected package gcc-13-s390x-linux-gnu. 152s Preparing to unpack .../025-gcc-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 152s Unpacking gcc-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 153s Selecting previously unselected package gcc-13. 153s Preparing to unpack .../026-gcc-13_13.2.0-17ubuntu2_s390x.deb ... 153s Unpacking gcc-13 (13.2.0-17ubuntu2) ... 153s Selecting previously unselected package gcc-s390x-linux-gnu. 153s Preparing to unpack .../027-gcc-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 153s Unpacking gcc-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 153s Selecting previously unselected package gcc. 153s Preparing to unpack .../028-gcc_4%3a13.2.0-7ubuntu1_s390x.deb ... 153s Unpacking gcc (4:13.2.0-7ubuntu1) ... 153s Selecting previously unselected package libstdc++-13-dev:s390x. 153s Preparing to unpack .../029-libstdc++-13-dev_13.2.0-17ubuntu2_s390x.deb ... 153s Unpacking libstdc++-13-dev:s390x (13.2.0-17ubuntu2) ... 153s Selecting previously unselected package g++-13-s390x-linux-gnu. 153s Preparing to unpack .../030-g++-13-s390x-linux-gnu_13.2.0-17ubuntu2_s390x.deb ... 153s Unpacking g++-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 153s Selecting previously unselected package g++-13. 153s Preparing to unpack .../031-g++-13_13.2.0-17ubuntu2_s390x.deb ... 153s Unpacking g++-13 (13.2.0-17ubuntu2) ... 153s Selecting previously unselected package g++-s390x-linux-gnu. 154s Preparing to unpack .../032-g++-s390x-linux-gnu_4%3a13.2.0-7ubuntu1_s390x.deb ... 154s Unpacking g++-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 154s Selecting previously unselected package g++. 154s Preparing to unpack .../033-g++_4%3a13.2.0-7ubuntu1_s390x.deb ... 154s Unpacking g++ (4:13.2.0-7ubuntu1) ... 154s Selecting previously unselected package build-essential. 154s Preparing to unpack .../034-build-essential_12.10ubuntu1_s390x.deb ... 154s Unpacking build-essential (12.10ubuntu1) ... 154s Selecting previously unselected package libdebhelper-perl. 154s Preparing to unpack .../035-libdebhelper-perl_13.14.1ubuntu1_all.deb ... 154s Unpacking libdebhelper-perl (13.14.1ubuntu1) ... 154s Selecting previously unselected package libtool. 154s Preparing to unpack .../036-libtool_2.4.7-7_all.deb ... 154s Unpacking libtool (2.4.7-7) ... 154s Selecting previously unselected package dh-autoreconf. 154s Preparing to unpack .../037-dh-autoreconf_20_all.deb ... 154s Unpacking dh-autoreconf (20) ... 154s Selecting previously unselected package libarchive-zip-perl. 154s Preparing to unpack .../038-libarchive-zip-perl_1.68-1_all.deb ... 154s Unpacking libarchive-zip-perl (1.68-1) ... 154s Selecting previously unselected package libsub-override-perl. 154s Preparing to unpack .../039-libsub-override-perl_0.10-1_all.deb ... 154s Unpacking libsub-override-perl (0.10-1) ... 154s Selecting previously unselected package libfile-stripnondeterminism-perl. 154s Preparing to unpack .../040-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 154s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 154s Selecting previously unselected package dh-strip-nondeterminism. 154s Preparing to unpack .../041-dh-strip-nondeterminism_1.13.1-1_all.deb ... 154s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 154s Selecting previously unselected package libdw1:s390x. 154s Preparing to unpack .../042-libdw1_0.190-1_s390x.deb ... 154s Unpacking libdw1:s390x (0.190-1) ... 154s Selecting previously unselected package debugedit. 154s Preparing to unpack .../043-debugedit_1%3a5.0-5_s390x.deb ... 154s Unpacking debugedit (1:5.0-5) ... 154s Selecting previously unselected package dwz. 154s Preparing to unpack .../044-dwz_0.15-1_s390x.deb ... 154s Unpacking dwz (0.15-1) ... 154s Selecting previously unselected package gettext. 154s Preparing to unpack .../045-gettext_0.21-14ubuntu1_s390x.deb ... 154s Unpacking gettext (0.21-14ubuntu1) ... 154s Selecting previously unselected package intltool-debian. 154s Preparing to unpack .../046-intltool-debian_0.35.0+20060710.6_all.deb ... 154s Unpacking intltool-debian (0.35.0+20060710.6) ... 154s Selecting previously unselected package po-debconf. 154s Preparing to unpack .../047-po-debconf_1.0.21+nmu1_all.deb ... 154s Unpacking po-debconf (1.0.21+nmu1) ... 154s Selecting previously unselected package debhelper. 154s Preparing to unpack .../048-debhelper_13.14.1ubuntu1_all.deb ... 154s Unpacking debhelper (13.14.1ubuntu1) ... 154s Selecting previously unselected package libraft0:s390x. 154s Preparing to unpack .../049-libraft0_0.22.0-1_s390x.deb ... 154s Unpacking libraft0:s390x (0.22.0-1) ... 154s Selecting previously unselected package libcowsql0:s390x. 154s Preparing to unpack .../050-libcowsql0_1.15.4-1_s390x.deb ... 154s Unpacking libcowsql0:s390x (1.15.4-1) ... 154s Selecting previously unselected package go-cowsql. 154s Preparing to unpack .../051-go-cowsql_1.22.0-2_s390x.deb ... 154s Unpacking go-cowsql (1.22.0-2) ... 154s Selecting previously unselected package golang-1.22-src. 154s Preparing to unpack .../052-golang-1.22-src_1.22.1-1_all.deb ... 154s Unpacking golang-1.22-src (1.22.1-1) ... 157s Selecting previously unselected package golang-1.22-go. 157s Preparing to unpack .../053-golang-1.22-go_1.22.1-1_s390x.deb ... 157s Unpacking golang-1.22-go (1.22.1-1) ... 158s Selecting previously unselected package golang-src. 158s Preparing to unpack .../054-golang-src_2%3a1.22~2_all.deb ... 158s Unpacking golang-src (2:1.22~2) ... 158s Selecting previously unselected package golang-go:s390x. 158s Preparing to unpack .../055-golang-go_2%3a1.22~2_s390x.deb ... 158s Unpacking golang-go:s390x (2:1.22~2) ... 158s Selecting previously unselected package golang-any:s390x. 158s Preparing to unpack .../056-golang-any_2%3a1.22~2_s390x.deb ... 158s Unpacking golang-any:s390x (2:1.22~2) ... 158s Selecting previously unselected package libsqlite3-dev:s390x. 158s Preparing to unpack .../057-libsqlite3-dev_3.45.1-1ubuntu1_s390x.deb ... 158s Unpacking libsqlite3-dev:s390x (3.45.1-1ubuntu1) ... 158s Selecting previously unselected package libcowsql-dev:s390x. 158s Preparing to unpack .../058-libcowsql-dev_1.15.4-1_s390x.deb ... 158s Unpacking libcowsql-dev:s390x (1.15.4-1) ... 158s Selecting previously unselected package libraft-dev:s390x. 158s Preparing to unpack .../059-libraft-dev_0.22.0-1_s390x.deb ... 159s Unpacking libraft-dev:s390x (0.22.0-1) ... 159s Selecting previously unselected package golang-gopkg-yaml.v2-dev. 159s Preparing to unpack .../060-golang-gopkg-yaml.v2-dev_2.4.0-4_all.deb ... 159s Unpacking golang-gopkg-yaml.v2-dev (2.4.0-4) ... 159s Selecting previously unselected package golang-github-ghodss-yaml-dev. 159s Preparing to unpack .../061-golang-github-ghodss-yaml-dev_1.0.0+git20220118.d8423dc-2_all.deb ... 159s Unpacking golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ... 159s Selecting previously unselected package golang-github-google-renameio-dev. 159s Preparing to unpack .../062-golang-github-google-renameio-dev_2.0.0-2_all.deb ... 159s Unpacking golang-github-google-renameio-dev (2.0.0-2) ... 159s Selecting previously unselected package golang-github-mattn-go-sqlite3-dev:s390x. 159s Preparing to unpack .../063-golang-github-mattn-go-sqlite3-dev_1.14.19~ds1-1_s390x.deb ... 159s Unpacking golang-github-mattn-go-sqlite3-dev:s390x (1.14.19~ds1-1) ... 159s Selecting previously unselected package golang-github-rivo-uniseg-dev. 159s Preparing to unpack .../064-golang-github-rivo-uniseg-dev_0.4.4-1_all.deb ... 159s Unpacking golang-github-rivo-uniseg-dev (0.4.4-1) ... 159s Selecting previously unselected package golang-github-mattn-go-runewidth-dev. 159s Preparing to unpack .../065-golang-github-mattn-go-runewidth-dev_0.0.15-2_all.deb ... 159s Unpacking golang-github-mattn-go-runewidth-dev (0.0.15-2) ... 159s Selecting previously unselected package golang-github-peterh-liner-dev. 159s Preparing to unpack .../066-golang-github-peterh-liner-dev_1.2.2-1_all.deb ... 159s Unpacking golang-github-peterh-liner-dev (1.2.2-1) ... 159s Selecting previously unselected package golang-github-pkg-errors-dev. 159s Preparing to unpack .../067-golang-github-pkg-errors-dev_0.9.1-3_all.deb ... 159s Unpacking golang-github-pkg-errors-dev (0.9.1-3) ... 159s Selecting previously unselected package golang-github-rican7-retry-dev. 159s Preparing to unpack .../068-golang-github-rican7-retry-dev_0.3.1-2_all.deb ... 159s Unpacking golang-github-rican7-retry-dev (0.3.1-2) ... 159s Selecting previously unselected package golang-github-pmezard-go-difflib-dev. 159s Preparing to unpack .../069-golang-github-pmezard-go-difflib-dev_1.0.0-3_all.deb ... 159s Unpacking golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 159s Selecting previously unselected package golang-github-shurcool-sanitized-anchor-name-dev. 159s Preparing to unpack .../070-golang-github-shurcool-sanitized-anchor-name-dev_1.0.0-3_all.deb ... 159s Unpacking golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 159s Selecting previously unselected package golang-github-russross-blackfriday-v2-dev. 159s Preparing to unpack .../071-golang-github-russross-blackfriday-v2-dev_2.1.0-1_all.deb ... 159s Unpacking golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 159s Selecting previously unselected package golang-github-cpuguy83-go-md2man-v2-dev. 159s Preparing to unpack .../072-golang-github-cpuguy83-go-md2man-v2-dev_2.0.3+ds1-1_all.deb ... 159s Unpacking golang-github-cpuguy83-go-md2man-v2-dev (2.0.3+ds1-1) ... 159s Selecting previously unselected package golang-github-spf13-pflag-dev. 159s Preparing to unpack .../073-golang-github-spf13-pflag-dev_1.0.6~git20210604-d5e0c0615ace-1_all.deb ... 159s Unpacking golang-github-spf13-pflag-dev (1.0.6~git20210604-d5e0c0615ace-1) ... 159s Selecting previously unselected package golang-golang-x-sys-dev. 159s Preparing to unpack .../074-golang-golang-x-sys-dev_0.17.0-1_all.deb ... 159s Unpacking golang-golang-x-sys-dev (0.17.0-1) ... 159s Selecting previously unselected package golang-github-fsnotify-fsnotify-dev. 159s Preparing to unpack .../075-golang-github-fsnotify-fsnotify-dev_1.7.0-1_all.deb ... 159s Unpacking golang-github-fsnotify-fsnotify-dev (1.7.0-1) ... 159s Selecting previously unselected package golang-github-davecgh-go-spew-dev. 159s Preparing to unpack .../076-golang-github-davecgh-go-spew-dev_1.1.1-3_all.deb ... 159s Unpacking golang-github-davecgh-go-spew-dev (1.1.1-3) ... 159s Selecting previously unselected package golang-github-hashicorp-hcl-dev. 159s Preparing to unpack .../077-golang-github-hashicorp-hcl-dev_1.0.0-1.1_all.deb ... 159s Unpacking golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 159s Selecting previously unselected package golang-github-magiconair-properties-dev. 159s Preparing to unpack .../078-golang-github-magiconair-properties-dev_1.8.7-1_all.deb ... 159s Unpacking golang-github-magiconair-properties-dev (1.8.7-1) ... 159s Selecting previously unselected package golang-github-mitchellh-mapstructure-dev. 159s Preparing to unpack .../079-golang-github-mitchellh-mapstructure-dev_1.5.0+git20231216.8508981-1_all.deb ... 159s Unpacking golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 159s Selecting previously unselected package golang-github-burntsushi-toml-dev. 159s Preparing to unpack .../080-golang-github-burntsushi-toml-dev_1.3.2-2_all.deb ... 159s Unpacking golang-github-burntsushi-toml-dev (1.3.2-2) ... 159s Selecting previously unselected package golang-github-pelletier-go-toml-dev. 159s Preparing to unpack .../081-golang-github-pelletier-go-toml-dev_1.9.5-1build1_all.deb ... 159s Unpacking golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 159s Selecting previously unselected package golang-github-pelletier-go-toml.v2-dev. 159s Preparing to unpack .../082-golang-github-pelletier-go-toml.v2-dev_2.1.1-1_all.deb ... 159s Unpacking golang-github-pelletier-go-toml.v2-dev (2.1.1-1) ... 159s Selecting previously unselected package golang-github-kr-fs-dev. 159s Preparing to unpack .../083-golang-github-kr-fs-dev_0.1.0-2_all.deb ... 159s Unpacking golang-github-kr-fs-dev (0.1.0-2) ... 160s Selecting previously unselected package golang-golang-x-term-dev. 160s Preparing to unpack .../084-golang-golang-x-term-dev_0.17.0-1_all.deb ... 160s Unpacking golang-golang-x-term-dev (0.17.0-1) ... 160s Selecting previously unselected package golang-golang-x-text-dev. 160s Preparing to unpack .../085-golang-golang-x-text-dev_0.14.0-1_all.deb ... 160s Unpacking golang-golang-x-text-dev (0.14.0-1) ... 160s Selecting previously unselected package golang-golang-x-crypto-dev. 160s Preparing to unpack .../086-golang-golang-x-crypto-dev_1%3a0.19.0-1_all.deb ... 160s Unpacking golang-golang-x-crypto-dev (1:0.19.0-1) ... 160s Selecting previously unselected package golang-github-pkg-sftp-dev. 160s Preparing to unpack .../087-golang-github-pkg-sftp-dev_1.13.6-1_all.deb ... 160s Unpacking golang-github-pkg-sftp-dev (1.13.6-1) ... 160s Selecting previously unselected package golang-github-spf13-afero-dev. 160s Preparing to unpack .../088-golang-github-spf13-afero-dev_1.11.0-1_all.deb ... 160s Unpacking golang-github-spf13-afero-dev (1.11.0-1) ... 160s Selecting previously unselected package golang-github-spf13-cast-dev. 160s Preparing to unpack .../089-golang-github-spf13-cast-dev_1.6.0-1_all.deb ... 160s Unpacking golang-github-spf13-cast-dev (1.6.0-1) ... 160s Selecting previously unselected package golang-github-stretchr-objx-dev. 160s Preparing to unpack .../090-golang-github-stretchr-objx-dev_0.5.0-1_all.deb ... 160s Unpacking golang-github-stretchr-objx-dev (0.5.0-1) ... 160s Selecting previously unselected package golang-gopkg-yaml.v3-dev. 160s Preparing to unpack .../091-golang-gopkg-yaml.v3-dev_3.0.1-3_all.deb ... 160s Unpacking golang-gopkg-yaml.v3-dev (3.0.1-3) ... 160s Selecting previously unselected package golang-github-stretchr-testify-dev. 160s Preparing to unpack .../092-golang-github-stretchr-testify-dev_1.8.4-1_all.deb ... 160s Unpacking golang-github-stretchr-testify-dev (1.8.4-1) ... 160s Selecting previously unselected package golang-github-spf13-jwalterweatherman-dev. 160s Preparing to unpack .../093-golang-github-spf13-jwalterweatherman-dev_1.1.0+really1.1.0-2_all.deb ... 160s Unpacking golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 160s Selecting previously unselected package golang-github-subosito-gotenv-dev. 160s Preparing to unpack .../094-golang-github-subosito-gotenv-dev_1.3.0-1_all.deb ... 160s Unpacking golang-github-subosito-gotenv-dev (1.3.0-1) ... 160s Selecting previously unselected package golang-gopkg-ini.v1-dev. 160s Preparing to unpack .../095-golang-gopkg-ini.v1-dev_1.67.0-1_all.deb ... 160s Unpacking golang-gopkg-ini.v1-dev (1.67.0-1) ... 160s Selecting previously unselected package golang-github-spf13-viper-dev. 160s Preparing to unpack .../096-golang-github-spf13-viper-dev_1.12.0-1_all.deb ... 160s Unpacking golang-github-spf13-viper-dev (1.12.0-1) ... 160s Selecting previously unselected package golang-github-spf13-cobra-dev. 160s Preparing to unpack .../097-golang-github-spf13-cobra-dev_1.8.0-1_all.deb ... 160s Unpacking golang-github-spf13-cobra-dev (1.8.0-1) ... 160s Selecting previously unselected package golang-golang-x-sync-dev. 160s Preparing to unpack .../098-golang-golang-x-sync-dev_0.6.0-1_all.deb ... 160s Unpacking golang-golang-x-sync-dev (0.6.0-1) ... 160s Selecting previously unselected package golang-github-cowsql-go-cowsql-dev. 160s Preparing to unpack .../099-golang-github-cowsql-go-cowsql-dev_1.22.0-2_all.deb ... 160s Unpacking golang-github-cowsql-go-cowsql-dev (1.22.0-2) ... 160s Selecting previously unselected package dh-golang. 160s Preparing to unpack .../100-dh-golang_1.62_all.deb ... 160s Unpacking dh-golang (1.62) ... 160s Selecting previously unselected package autopkgtest-satdep. 160s Preparing to unpack .../101-1-autopkgtest-satdep.deb ... 160s Unpacking autopkgtest-satdep (0) ... 160s Setting up golang-github-pelletier-go-toml.v2-dev (2.1.1-1) ... 160s Setting up golang-gopkg-yaml.v2-dev (2.4.0-4) ... 160s Setting up libdw1:s390x (0.190-1) ... 160s Setting up golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 160s Setting up golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 160s Setting up golang-github-stretchr-objx-dev (0.5.0-1) ... 161s Setting up golang-github-pkg-errors-dev (0.9.1-3) ... 161s Setting up libarchive-zip-perl (1.68-1) ... 161s Setting up golang-gopkg-yaml.v3-dev (3.0.1-3) ... 161s Setting up libdebhelper-perl (13.14.1ubuntu1) ... 161s Setting up golang-github-burntsushi-toml-dev (1.3.2-2) ... 161s Setting up golang-golang-x-sys-dev (0.17.0-1) ... 161s Setting up golang-github-kr-fs-dev (0.1.0-2) ... 161s Setting up golang-github-subosito-gotenv-dev (1.3.0-1) ... 161s Setting up linux-libc-dev:s390x (6.8.0-11.11) ... 161s Setting up golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 161s Setting up m4 (1.4.19-4) ... 161s Setting up golang-golang-x-term-dev (0.17.0-1) ... 161s Setting up libgomp1:s390x (14-20240303-1ubuntu1) ... 161s Setting up golang-github-google-renameio-dev (2.0.0-2) ... 161s Setting up golang-github-spf13-pflag-dev (1.0.6~git20210604-d5e0c0615ace-1) ... 161s Setting up golang-github-davecgh-go-spew-dev (1.1.1-3) ... 161s Setting up autotools-dev (20220109.1) ... 161s Setting up libtirpc-dev:s390x (1.3.4+ds-1build1) ... 161s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 161s Setting up libraft0:s390x (0.22.0-1) ... 161s Setting up golang-github-stretchr-testify-dev (1.8.4-1) ... 161s Setting up golang-1.22-src (1.22.1-1) ... 161s Setting up libraft-dev:s390x (0.22.0-1) ... 161s Setting up golang-gopkg-ini.v1-dev (1.67.0-1) ... 161s Setting up libmpc3:s390x (1.3.1-1) ... 161s Setting up libatomic1:s390x (14-20240303-1ubuntu1) ... 161s Setting up golang-golang-x-sync-dev (0.6.0-1) ... 161s Setting up autopoint (0.21-14ubuntu1) ... 161s Setting up golang-github-magiconair-properties-dev (1.8.7-1) ... 161s Setting up golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 161s Setting up golang-github-rican7-retry-dev (0.3.1-2) ... 161s Setting up autoconf (2.71-3) ... 161s Setting up golang-github-rivo-uniseg-dev (0.4.4-1) ... 161s Setting up libubsan1:s390x (14-20240303-1ubuntu1) ... 161s Setting up dwz (0.15-1) ... 161s Setting up libnsl-dev:s390x (1.3.0-3) ... 161s Setting up golang-golang-x-text-dev (0.14.0-1) ... 161s Setting up libcrypt-dev:s390x (1:4.4.36-4) ... 161s Setting up libasan8:s390x (14-20240303-1ubuntu1) ... 161s Setting up golang-github-spf13-cast-dev (1.6.0-1) ... 161s Setting up golang-github-fsnotify-fsnotify-dev (1.7.0-1) ... 161s Setting up debugedit (1:5.0-5) ... 161s Setting up libsub-override-perl (0.10-1) ... 161s Setting up golang-golang-x-crypto-dev (1:0.19.0-1) ... 161s Setting up golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 161s Setting up golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 161s Setting up libisl23:s390x (0.26-3) ... 161s Setting up golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ... 161s Setting up libc-dev-bin (2.39-0ubuntu2) ... 161s Setting up golang-src (2:1.22~2) ... 161s Setting up libcc1-0:s390x (14-20240303-1ubuntu1) ... 161s Setting up libitm1:s390x (14-20240303-1ubuntu1) ... 161s Setting up automake (1:1.16.5-1.3ubuntu1) ... 161s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 161s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 161s Setting up golang-github-mattn-go-runewidth-dev (0.0.15-2) ... 161s Setting up gettext (0.21-14ubuntu1) ... 161s Setting up golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 161s Setting up golang-github-pkg-sftp-dev (1.13.6-1) ... 161s Setting up libcowsql0:s390x (1.15.4-1) ... 161s Setting up golang-1.22-go (1.22.1-1) ... 161s Setting up intltool-debian (0.35.0+20060710.6) ... 161s Setting up golang-github-peterh-liner-dev (1.2.2-1) ... 161s Setting up cpp-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 161s Setting up dh-strip-nondeterminism (1.13.1-1) ... 161s Setting up libgcc-13-dev:s390x (13.2.0-17ubuntu2) ... 161s Setting up libc6-dev:s390x (2.39-0ubuntu2) ... 161s Setting up golang-github-spf13-afero-dev (1.11.0-1) ... 161s Setting up libstdc++-13-dev:s390x (13.2.0-17ubuntu2) ... 161s Setting up golang-github-cpuguy83-go-md2man-v2-dev (2.0.3+ds1-1) ... 161s Setting up cpp-13 (13.2.0-17ubuntu2) ... 161s Setting up cpp-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 161s Setting up golang-go:s390x (2:1.22~2) ... 161s Setting up po-debconf (1.0.21+nmu1) ... 161s Setting up go-cowsql (1.22.0-2) ... 161s Setting up golang-any:s390x (2:1.22~2) ... 161s Setting up gcc-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 161s Setting up gcc-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 161s Setting up libsqlite3-dev:s390x (3.45.1-1ubuntu1) ... 161s Setting up g++-13-s390x-linux-gnu (13.2.0-17ubuntu2) ... 161s Setting up gcc-13 (13.2.0-17ubuntu2) ... 161s Setting up golang-github-spf13-viper-dev (1.12.0-1) ... 161s Setting up cpp (4:13.2.0-7ubuntu1) ... 161s Setting up g++-13 (13.2.0-17ubuntu2) ... 161s Setting up libtool (2.4.7-7) ... 161s Setting up g++-s390x-linux-gnu (4:13.2.0-7ubuntu1) ... 161s Setting up libcowsql-dev:s390x (1.15.4-1) ... 161s Setting up gcc (4:13.2.0-7ubuntu1) ... 161s Setting up dh-autoreconf (20) ... 161s Setting up golang-github-mattn-go-sqlite3-dev:s390x (1.14.19~ds1-1) ... 161s Setting up golang-github-spf13-cobra-dev (1.8.0-1) ... 161s Setting up g++ (4:13.2.0-7ubuntu1) ... 161s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 161s Setting up build-essential (12.10ubuntu1) ... 161s Setting up golang-github-cowsql-go-cowsql-dev (1.22.0-2) ... 161s Setting up debhelper (13.14.1ubuntu1) ... 161s Setting up dh-golang (1.62) ... 161s Setting up autopkgtest-satdep (0) ... 161s Processing triggers for man-db (2.12.0-3) ... 162s Processing triggers for install-info (7.1-3) ... 162s Processing triggers for libc-bin (2.39-0ubuntu2) ... 168s (Reading database ... 74139 files and directories currently installed.) 168s Removing autopkgtest-satdep (0) ... 169s autopkgtest [16:15:28]: test dh-golang-autopkgtest: /usr/bin/dh_golang_autopkgtest 169s autopkgtest [16:15:28]: test dh-golang-autopkgtest: [----------------------- 169s [info] Testing github.com/cowsql/go-cowsql... 169s [info] Source code installed by binary package, overriding dh_auto_configure... 170s -e: warning: LTO optimize is enable in buildflags. But cgo doesn't support it. LTO flags will be stripped in cgo. 170s dh build --builddirectory=_build --buildsystem=golang --with=golang 170s dh_update_autotools_config -O--builddirectory=_build -O--buildsystem=golang 170s dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang 170s debian/rules override_dh_auto_configure 170s make[1]: Entering directory '/tmp/autopkgtest.qqsKmC/autopkgtest_tmp' 170s -e: warning: LTO optimize is enable in buildflags. But cgo doesn't support it. LTO flags will be stripped in cgo. 170s mkdir -p "_build" 170s # remove existing symlinks, since "cp -a" won't convert them 170s find "_build" -type l -exec rm "{}" \; 170s cp -a /usr/share/gocode/src "_build" 170s make[1]: Leaving directory '/tmp/autopkgtest.qqsKmC/autopkgtest_tmp' 170s dh_auto_build -O--builddirectory=_build -O--buildsystem=golang 170s dh_auto_build: warning: LTO optimize is enable in buildflags. But cgo doesn't support it. LTO flags will be stripped in cgo. 171s cd _build && go install -trimpath -v -p 1 github.com/cowsql/go-cowsql github.com/cowsql/go-cowsql/app github.com/cowsql/go-cowsql/benchmark github.com/cowsql/go-cowsql/client github.com/cowsql/go-cowsql/cmd/cowsql github.com/cowsql/go-cowsql/cmd/cowsql-benchmark github.com/cowsql/go-cowsql/cmd/cowsql-demo github.com/cowsql/go-cowsql/driver github.com/cowsql/go-cowsql/internal/bindings github.com/cowsql/go-cowsql/internal/protocol github.com/cowsql/go-cowsql/internal/shell github.com/cowsql/go-cowsql/logging 171s internal/goarch 171s internal/abi 171s internal/unsafeheader 171s internal/cpu 171s internal/bytealg 171s internal/chacha8rand 171s internal/coverage/rtcov 171s internal/godebugs 171s internal/goexperiment 171s internal/goos 171s runtime/internal/atomic 171s runtime/internal/math 171s runtime/internal/sys 171s runtime/internal/syscall 171s runtime 178s internal/reflectlite 178s errors 178s internal/race 178s sync/atomic 178s sync 178s internal/itoa 178s internal/oserror 178s syscall 179s time 180s context 180s math/bits 180s math 180s unicode/utf8 180s strconv 181s unicode 181s reflect 183s cmp 183s slices 183s sort 183s internal/fmtsort 183s io 184s internal/syscall/unix 184s internal/poll 184s internal/safefilepath 184s internal/syscall/execenv 184s internal/testlog 184s path 185s io/fs 185s os 185s fmt 186s bytes 186s container/list 186s hash 186s crypto 186s crypto/internal/alias 186s crypto/subtle 186s encoding/binary 186s crypto/cipher 186s crypto/internal/boring/sig 186s crypto/internal/boring 186s crypto/aes 187s crypto/des 187s crypto/internal/edwards25519/field 187s crypto/internal/nistec/fiat 188s embed 188s crypto/internal/nistec 188s crypto/internal/randutil 188s crypto/ecdh 188s internal/bisect 188s internal/godebug 189s math/rand 189s strings 189s math/big 190s crypto/elliptic 190s crypto/internal/bigmod 190s crypto/internal/boring/bbig 190s crypto/sha512 190s unicode/utf16 191s encoding/asn1 191s vendor/golang.org/x/crypto/cryptobyte/asn1 191s vendor/golang.org/x/crypto/cryptobyte 191s crypto/ecdsa 191s crypto/internal/edwards25519 192s crypto/rand 192s crypto/ed25519 192s crypto/hmac 192s crypto/md5 192s crypto/rc4 192s crypto/rsa 192s crypto/sha1 192s crypto/sha256 192s crypto/dsa 192s encoding/hex 192s crypto/x509/pkix 192s encoding/base64 192s encoding/pem 192s vendor/golang.org/x/net/dns/dnsmessage 193s internal/nettrace 193s internal/singleflight 193s internal/intern 193s net/netip 193s runtime/cgo 194s net 197s net/url 197s path/filepath 197s crypto/x509 198s vendor/golang.org/x/crypto/internal/alias 198s vendor/golang.org/x/sys/cpu 198s vendor/golang.org/x/crypto/chacha20 198s vendor/golang.org/x/crypto/internal/poly1305 198s vendor/golang.org/x/crypto/chacha20poly1305 198s vendor/golang.org/x/crypto/hkdf 198s crypto/tls 200s database/sql/driver 200s database/sql 201s github.com/Rican7/retry/backoff 201s github.com/Rican7/retry/jitter 201s github.com/Rican7/retry/strategy 201s github.com/Rican7/retry 201s encoding 201s flag 201s bufio 201s internal/sysinfo 201s runtime/debug 201s runtime/trace 201s testing 202s github.com/cowsql/go-cowsql/logging 202s github.com/pkg/errors 202s github.com/cowsql/go-cowsql/internal/protocol 202s io/ioutil 202s github.com/google/renameio 202s github.com/mattn/go-sqlite3 207s regexp/syntax 207s regexp 207s gopkg.in/yaml.v2 208s github.com/cowsql/go-cowsql/client 208s github.com/cowsql/go-cowsql/internal/bindings 209s github.com/cowsql/go-cowsql 209s github.com/cowsql/go-cowsql/driver 210s golang.org/x/sync/semaphore 210s golang.org/x/sys/unix 211s log/internal 211s log 211s github.com/cowsql/go-cowsql/app 211s github.com/cowsql/go-cowsql/benchmark 211s encoding/json 212s github.com/cowsql/go-cowsql/internal/shell 212s container/ring 212s github.com/rivo/uniseg 212s github.com/mattn/go-runewidth 212s os/signal 212s github.com/peterh/liner 213s encoding/csv 213s github.com/spf13/pflag 214s text/template/parse 214s text/template 214s github.com/spf13/cobra 215s github.com/cowsql/go-cowsql/cmd/cowsql 216s github.com/cowsql/go-cowsql/cmd/cowsql-benchmark 218s compress/flate 218s hash/crc32 218s compress/gzip 218s vendor/golang.org/x/text/transform 218s vendor/golang.org/x/text/unicode/bidi 218s vendor/golang.org/x/text/secure/bidirule 218s vendor/golang.org/x/text/unicode/norm 219s vendor/golang.org/x/net/idna 219s net/textproto 219s vendor/golang.org/x/net/http/httpguts 219s vendor/golang.org/x/net/http/httpproxy 219s vendor/golang.org/x/net/http2/hpack 219s mime 219s mime/quotedprintable 220s mime/multipart 220s net/http/httptrace 220s net/http/internal 220s net/http/internal/ascii 220s net/http 223s github.com/cowsql/go-cowsql/cmd/cowsql-demo 225s create-stamp debian/debhelper-build-stamp 225s -e: warning: LTO optimize is enable in buildflags. But cgo doesn't support it. LTO flags will be stripped in cgo. 225s dh_auto_test -O--builddirectory=_build -O--buildsystem=golang 225s dh_auto_test: warning: LTO optimize is enable in buildflags. But cgo doesn't support it. LTO flags will be stripped in cgo. 225s cd _build && go test -vet=off -v -p 1 github.com/cowsql/go-cowsql github.com/cowsql/go-cowsql/app github.com/cowsql/go-cowsql/benchmark github.com/cowsql/go-cowsql/client github.com/cowsql/go-cowsql/cmd/cowsql github.com/cowsql/go-cowsql/cmd/cowsql-benchmark github.com/cowsql/go-cowsql/cmd/cowsql-demo github.com/cowsql/go-cowsql/driver github.com/cowsql/go-cowsql/internal/bindings github.com/cowsql/go-cowsql/internal/protocol github.com/cowsql/go-cowsql/internal/shell github.com/cowsql/go-cowsql/logging 263s ? github.com/cowsql/go-cowsql [no test files] 283s === RUN TestNew_PristineDefault 283s --- PASS: TestNew_PristineDefault (0.06s) 283s === RUN TestNew_PristineJoiner 283s app_test.go:1117: 16:17:03.639 - 2: DEBUG: new connection from 127.0.0.1:36748 283s app_test.go:1117: 16:17:03.642 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected 283s app_test.go:1117: 16:17:03.645 - 2: DEBUG: new connection from 127.0.0.1:36750 283s app_test.go:1117: 16:17:03.688 - 2: DEBUG: new connection from 127.0.0.1:36754 283s app_test.go:1117: 16:17:03.695 - 3: DEBUG: attempt 1: server 127.0.0.1:9001: connected 283s app_test.go:1117: 16:17:03.699 - 2: DEBUG: new connection from 127.0.0.1:36764 283s app_test.go:1117: 16:17:03.705 - 3: DEBUG: new connection from 127.0.0.1:35926 283s app_test.go:1117: 16:17:03.712 - 2: DEBUG: new connection from 127.0.0.1:36776 283s app_test.go:1117: 16:17:03.718 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected 283s --- PASS: TestNew_PristineJoiner (0.21s) 283s === RUN TestNew_JoinerRestart 283s app_test.go:1117: 16:17:03.859 - 4: DEBUG: new connection from 127.0.0.1:36786 283s app_test.go:1117: 16:17:03.864 - 4: DEBUG: attempt 1: server 127.0.0.1:9001: connected 283s app_test.go:1117: 16:17:03.867 - 4: DEBUG: new connection from 127.0.0.1:36802 283s app_test.go:1117: 16:17:03.927 - 4: DEBUG: new connection from 127.0.0.1:36810 283s app_test.go:1117: 16:17:03.929 - 5: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1117: 16:17:03.931 - 4: DEBUG: new connection from 127.0.0.1:36812 284s app_test.go:1117: 16:17:03.933 - 5: DEBUG: new connection from 127.0.0.1:35934 284s app_test.go:1117: 16:17:03.018 - 4: DEBUG: new connection from 127.0.0.1:36820 284s app_test.go:1117: 16:17:03.020 - 6: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1117: 16:17:03.021 - 4: DEBUG: new connection from 127.0.0.1:36824 284s app_test.go:1117: 16:17:03.024 - 6: DEBUG: new connection from 127.0.0.1:35942 284s --- PASS: TestNew_JoinerRestart (0.31s) 284s === RUN TestNew_SecondJoiner 284s app_test.go:1117: 16:17:03.168 - 7: DEBUG: new connection from 127.0.0.1:36830 284s app_test.go:1117: 16:17:03.171 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1117: 16:17:03.172 - 7: DEBUG: new connection from 127.0.0.1:36844 284s app_test.go:1117: 16:17:03.228 - 7: DEBUG: new connection from 127.0.0.1:36856 284s app_test.go:1117: 16:17:03.231 - 8: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1117: 16:17:03.234 - 7: DEBUG: new connection from 127.0.0.1:36862 284s app_test.go:1117: 16:17:03.236 - 8: DEBUG: new connection from 127.0.0.1:35946 284s app_test.go:1117: 16:17:03.298 - 7: DEBUG: new connection from 127.0.0.1:36874 284s app_test.go:1117: 16:17:03.301 - 9: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1117: 16:17:03.303 - 7: DEBUG: new connection from 127.0.0.1:36878 284s app_test.go:1117: 16:17:03.307 - 8: DEBUG: new connection from 127.0.0.1:35958 284s app_test.go:1117: 16:17:03.311 - 9: DEBUG: new connection from 127.0.0.1:38546 284s app_test.go:1117: 16:17:03.314 - 9: DEBUG: new connection from 127.0.0.1:38558 284s app_test.go:1117: 16:17:03.318 - 7: DEBUG: new connection from 127.0.0.1:36886 284s app_test.go:1117: 16:17:03.351 - 8: DEBUG: new connection from 127.0.0.1:35964 284s app_test.go:1117: 16:17:03.356 - 7: DEBUG: new connection from 127.0.0.1:36898 284s app_test.go:1117: 16:17:03.360 - 7: DEBUG: new connection from 127.0.0.1:36906 284s app_test.go:1117: 16:17:03.362 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s --- PASS: TestNew_SecondJoiner (0.43s) 284s === RUN TestNew_ThirdJoiner 284s app_test.go:1117: 16:17:03.600 - 10: DEBUG: new connection from 127.0.0.1:36912 284s app_test.go:1117: 16:17:03.602 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1117: 16:17:03.604 - 10: DEBUG: new connection from 127.0.0.1:36928 284s app_test.go:1117: 16:17:03.668 - 10: DEBUG: new connection from 127.0.0.1:36934 284s app_test.go:1117: 16:17:03.670 - 11: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1117: 16:17:03.673 - 10: DEBUG: new connection from 127.0.0.1:36946 284s app_test.go:1117: 16:17:03.676 - 11: DEBUG: new connection from 127.0.0.1:35968 284s app_test.go:1117: 16:17:03.738 - 10: DEBUG: new connection from 127.0.0.1:36958 284s app_test.go:1117: 16:17:03.740 - 12: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1117: 16:17:03.742 - 10: DEBUG: new connection from 127.0.0.1:36966 284s app_test.go:1117: 16:17:03.746 - 11: DEBUG: new connection from 127.0.0.1:35982 284s app_test.go:1117: 16:17:03.749 - 12: DEBUG: new connection from 127.0.0.1:38574 284s app_test.go:1117: 16:17:03.751 - 12: DEBUG: new connection from 127.0.0.1:38584 284s app_test.go:1117: 16:17:03.755 - 10: DEBUG: new connection from 127.0.0.1:36980 284s app_test.go:1117: 16:17:03.760 - 11: DEBUG: new connection from 127.0.0.1:35988 284s app_test.go:1117: 16:17:03.764 - 10: DEBUG: new connection from 127.0.0.1:36990 284s app_test.go:1117: 16:17:03.818 - 10: DEBUG: new connection from 127.0.0.1:36998 284s app_test.go:1117: 16:17:03.820 - 13: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1117: 16:17:03.822 - 10: DEBUG: new connection from 127.0.0.1:37006 284s app_test.go:1117: 16:17:03.825 - 11: DEBUG: new connection from 127.0.0.1:35990 284s app_test.go:1117: 16:17:03.827 - 12: DEBUG: new connection from 127.0.0.1:38598 284s app_test.go:1117: 16:17:03.830 - 13: DEBUG: new connection from 127.0.0.1:37492 284s app_test.go:1117: 16:17:03.833 - 13: DEBUG: new connection from 127.0.0.1:37500 284s app_test.go:1117: 16:17:03.836 - 10: DEBUG: new connection from 127.0.0.1:37010 284s app_test.go:1117: 16:17:03.838 - 10: DEBUG: new connection from 127.0.0.1:37022 284s app_test.go:1117: 16:17:03.841 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected 284s app_test.go:1117: 16:17:03.842 - 10: DEBUG: new connection from 127.0.0.1:37036 285s --- PASS: TestNew_ThirdJoiner (0.52s) 285s === RUN TestNew_FourthJoiner 285s app_test.go:1117: 16:17:03.100 - 14: DEBUG: new connection from 127.0.0.1:37044 285s app_test.go:1117: 16:17:03.102 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected 285s app_test.go:1117: 16:17:03.104 - 14: DEBUG: new connection from 127.0.0.1:37060 285s app_test.go:1117: 16:17:03.158 - 14: DEBUG: new connection from 127.0.0.1:37072 285s app_test.go:1117: 16:17:03.161 - 15: DEBUG: attempt 1: server 127.0.0.1:9001: connected 285s app_test.go:1117: 16:17:03.164 - 14: DEBUG: new connection from 127.0.0.1:37080 285s app_test.go:1117: 16:17:03.167 - 15: DEBUG: new connection from 127.0.0.1:36004 285s app_test.go:1117: 16:17:03.208 - 14: DEBUG: new connection from 127.0.0.1:37090 285s app_test.go:1117: 16:17:03.211 - 16: DEBUG: attempt 1: server 127.0.0.1:9001: connected 285s app_test.go:1117: 16:17:03.213 - 14: DEBUG: new connection from 127.0.0.1:37096 285s app_test.go:1117: 16:17:03.216 - 15: DEBUG: new connection from 127.0.0.1:36018 285s app_test.go:1117: 16:17:03.219 - 16: DEBUG: new connection from 127.0.0.1:38602 285s app_test.go:1117: 16:17:03.222 - 16: DEBUG: new connection from 127.0.0.1:38612 285s app_test.go:1117: 16:17:03.226 - 14: DEBUG: new connection from 127.0.0.1:37108 285s app_test.go:1117: 16:17:03.234 - 15: DEBUG: new connection from 127.0.0.1:36032 285s app_test.go:1117: 16:17:03.240 - 14: DEBUG: new connection from 127.0.0.1:37122 285s app_test.go:1117: 16:17:03.328 - 14: DEBUG: new connection from 127.0.0.1:37136 285s app_test.go:1117: 16:17:03.331 - 17: DEBUG: attempt 1: server 127.0.0.1:9001: connected 285s app_test.go:1117: 16:17:03.335 - 14: DEBUG: new connection from 127.0.0.1:37146 285s app_test.go:1117: 16:17:03.338 - 15: DEBUG: new connection from 127.0.0.1:36036 285s app_test.go:1117: 16:17:03.341 - 16: DEBUG: new connection from 127.0.0.1:38626 285s app_test.go:1117: 16:17:03.344 - 17: DEBUG: new connection from 127.0.0.1:37512 285s app_test.go:1117: 16:17:03.349 - 17: DEBUG: new connection from 127.0.0.1:37522 285s app_test.go:1117: 16:17:03.357 - 14: DEBUG: new connection from 127.0.0.1:37148 285s app_test.go:1117: 16:17:03.408 - 14: DEBUG: new connection from 127.0.0.1:37160 285s app_test.go:1117: 16:17:03.409 - 18: DEBUG: attempt 1: server 127.0.0.1:9001: connected 285s app_test.go:1117: 16:17:03.412 - 14: DEBUG: new connection from 127.0.0.1:37172 285s app_test.go:1117: 16:17:03.415 - 15: DEBUG: new connection from 127.0.0.1:36050 285s app_test.go:1117: 16:17:03.417 - 16: DEBUG: new connection from 127.0.0.1:38642 285s app_test.go:1117: 16:17:03.420 - 17: DEBUG: new connection from 127.0.0.1:37538 285s app_test.go:1117: 16:17:03.422 - 18: DEBUG: new connection from 127.0.0.1:51508 285s app_test.go:1117: 16:17:03.426 - 18: DEBUG: new connection from 127.0.0.1:51522 285s app_test.go:1117: 16:17:03.428 - 14: DEBUG: new connection from 127.0.0.1:37186 285s app_test.go:1117: 16:17:03.430 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected 285s --- PASS: TestNew_FourthJoiner (0.62s) 285s === RUN TestNew_FifthJoiner 285s app_test.go:1117: 16:17:03.739 - 19: DEBUG: new connection from 127.0.0.1:37202 285s app_test.go:1117: 16:17:03.741 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected 285s app_test.go:1117: 16:17:03.742 - 19: DEBUG: new connection from 127.0.0.1:37206 285s app_test.go:1117: 16:17:03.807 - 19: DEBUG: new connection from 127.0.0.1:37220 285s app_test.go:1117: 16:17:03.809 - 20: DEBUG: attempt 1: server 127.0.0.1:9001: connected 285s app_test.go:1117: 16:17:03.811 - 19: DEBUG: new connection from 127.0.0.1:37236 285s app_test.go:1117: 16:17:03.814 - 20: DEBUG: new connection from 127.0.0.1:36062 285s app_test.go:1117: 16:17:03.878 - 19: DEBUG: new connection from 127.0.0.1:37250 286s app_test.go:1117: 16:17:03.880 - 21: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s app_test.go:1117: 16:17:03.883 - 19: DEBUG: new connection from 127.0.0.1:37266 286s app_test.go:1117: 16:17:03.886 - 20: DEBUG: new connection from 127.0.0.1:36074 286s app_test.go:1117: 16:17:03.890 - 21: DEBUG: new connection from 127.0.0.1:38654 286s app_test.go:1117: 16:17:03.892 - 21: DEBUG: new connection from 127.0.0.1:38666 286s app_test.go:1117: 16:17:03.898 - 19: DEBUG: new connection from 127.0.0.1:37268 286s app_test.go:1117: 16:17:03.903 - 20: DEBUG: new connection from 127.0.0.1:36082 286s app_test.go:1117: 16:17:03.908 - 19: DEBUG: new connection from 127.0.0.1:37284 286s app_test.go:1117: 16:17:03.978 - 19: DEBUG: new connection from 127.0.0.1:37292 286s app_test.go:1117: 16:17:03.981 - 22: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s app_test.go:1117: 16:17:03.984 - 19: DEBUG: new connection from 127.0.0.1:37298 286s app_test.go:1117: 16:17:03.988 - 20: DEBUG: new connection from 127.0.0.1:36090 286s app_test.go:1117: 16:17:03.991 - 21: DEBUG: new connection from 127.0.0.1:38680 286s app_test.go:1117: 16:17:03.995 - 22: DEBUG: new connection from 127.0.0.1:37542 286s app_test.go:1117: 16:17:03.000 - 22: DEBUG: new connection from 127.0.0.1:37546 286s app_test.go:1117: 16:17:03.008 - 19: DEBUG: new connection from 127.0.0.1:37306 286s app_test.go:1117: 16:17:03.059 - 19: DEBUG: new connection from 127.0.0.1:37322 286s app_test.go:1117: 16:17:03.061 - 23: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s app_test.go:1117: 16:17:03.063 - 19: DEBUG: new connection from 127.0.0.1:37336 286s app_test.go:1117: 16:17:03.066 - 20: DEBUG: new connection from 127.0.0.1:36100 286s app_test.go:1117: 16:17:03.071 - 21: DEBUG: new connection from 127.0.0.1:38696 286s app_test.go:1117: 16:17:03.074 - 22: DEBUG: new connection from 127.0.0.1:37562 286s app_test.go:1117: 16:17:03.076 - 23: DEBUG: new connection from 127.0.0.1:51526 286s app_test.go:1117: 16:17:03.078 - 23: DEBUG: new connection from 127.0.0.1:51534 286s app_test.go:1117: 16:17:03.084 - 19: DEBUG: new connection from 127.0.0.1:37338 286s app_test.go:1117: 16:17:03.138 - 19: DEBUG: new connection from 127.0.0.1:37348 286s app_test.go:1117: 16:17:03.140 - 24: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s app_test.go:1117: 16:17:03.144 - 19: DEBUG: new connection from 127.0.0.1:37358 286s app_test.go:1117: 16:17:03.147 - 20: DEBUG: new connection from 127.0.0.1:36104 286s app_test.go:1117: 16:17:03.149 - 21: DEBUG: new connection from 127.0.0.1:38712 286s app_test.go:1117: 16:17:03.152 - 22: DEBUG: new connection from 127.0.0.1:37578 286s app_test.go:1117: 16:17:03.157 - 23: DEBUG: new connection from 127.0.0.1:51550 286s app_test.go:1117: 16:17:03.160 - 24: DEBUG: new connection from 127.0.0.1:53216 286s app_test.go:1117: 16:17:03.165 - 24: DEBUG: new connection from 127.0.0.1:53230 286s app_test.go:1117: 16:17:03.168 - 19: DEBUG: new connection from 127.0.0.1:37372 286s app_test.go:1117: 16:17:03.171 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s --- PASS: TestNew_FifthJoiner (0.80s) 286s === RUN TestNew_SixthJoiner 286s app_test.go:1117: 16:17:03.530 - 25: DEBUG: new connection from 127.0.0.1:37384 286s app_test.go:1117: 16:17:03.533 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s app_test.go:1117: 16:17:03.535 - 25: DEBUG: new connection from 127.0.0.1:37392 286s app_test.go:1117: 16:17:03.598 - 25: DEBUG: new connection from 127.0.0.1:37404 286s app_test.go:1117: 16:17:03.600 - 26: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s app_test.go:1117: 16:17:03.603 - 25: DEBUG: new connection from 127.0.0.1:37420 286s app_test.go:1117: 16:17:03.605 - 26: DEBUG: new connection from 127.0.0.1:36114 286s app_test.go:1117: 16:17:03.668 - 25: DEBUG: new connection from 127.0.0.1:37428 286s app_test.go:1117: 16:17:03.671 - 27: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s app_test.go:1117: 16:17:03.673 - 25: DEBUG: new connection from 127.0.0.1:37442 286s app_test.go:1117: 16:17:03.678 - 26: DEBUG: new connection from 127.0.0.1:36126 286s app_test.go:1117: 16:17:03.682 - 27: DEBUG: new connection from 127.0.0.1:38714 286s app_test.go:1117: 16:17:03.688 - 27: DEBUG: new connection from 127.0.0.1:38724 286s app_test.go:1117: 16:17:03.694 - 25: DEBUG: new connection from 127.0.0.1:37444 286s app_test.go:1117: 16:17:03.701 - 26: DEBUG: new connection from 127.0.0.1:36128 286s app_test.go:1117: 16:17:03.706 - 25: DEBUG: new connection from 127.0.0.1:37458 286s app_test.go:1117: 16:17:03.769 - 25: DEBUG: new connection from 127.0.0.1:37464 286s app_test.go:1117: 16:17:03.775 - 28: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s app_test.go:1117: 16:17:03.780 - 25: DEBUG: new connection from 127.0.0.1:37472 286s app_test.go:1117: 16:17:03.786 - 26: DEBUG: new connection from 127.0.0.1:36138 286s app_test.go:1117: 16:17:03.789 - 27: DEBUG: new connection from 127.0.0.1:38730 286s app_test.go:1117: 16:17:03.792 - 28: DEBUG: new connection from 127.0.0.1:37592 286s app_test.go:1117: 16:17:03.795 - 28: DEBUG: new connection from 127.0.0.1:37602 286s app_test.go:1117: 16:17:03.802 - 25: DEBUG: new connection from 127.0.0.1:37484 286s app_test.go:1117: 16:17:03.858 - 25: DEBUG: new connection from 127.0.0.1:37492 286s app_test.go:1117: 16:17:03.860 - 29: DEBUG: attempt 1: server 127.0.0.1:9001: connected 286s app_test.go:1117: 16:17:03.862 - 25: DEBUG: new connection from 127.0.0.1:37502 286s app_test.go:1117: 16:17:03.866 - 26: DEBUG: new connection from 127.0.0.1:36142 286s app_test.go:1117: 16:17:03.869 - 27: DEBUG: new connection from 127.0.0.1:38744 286s app_test.go:1117: 16:17:03.872 - 28: DEBUG: new connection from 127.0.0.1:37612 286s app_test.go:1117: 16:17:03.876 - 29: DEBUG: new connection from 127.0.0.1:51554 286s app_test.go:1117: 16:17:03.882 - 29: DEBUG: new connection from 127.0.0.1:51568 286s app_test.go:1117: 16:17:03.889 - 25: DEBUG: new connection from 127.0.0.1:37508 287s app_test.go:1117: 16:17:03.938 - 25: DEBUG: new connection from 127.0.0.1:37512 287s app_test.go:1117: 16:17:03.941 - 30: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1117: 16:17:03.945 - 25: DEBUG: new connection from 127.0.0.1:37522 287s app_test.go:1117: 16:17:03.950 - 26: DEBUG: new connection from 127.0.0.1:36144 287s app_test.go:1117: 16:17:03.953 - 27: DEBUG: new connection from 127.0.0.1:38758 287s app_test.go:1117: 16:17:03.956 - 28: DEBUG: new connection from 127.0.0.1:37628 287s app_test.go:1117: 16:17:03.959 - 29: DEBUG: new connection from 127.0.0.1:51570 287s app_test.go:1117: 16:17:03.963 - 30: DEBUG: new connection from 127.0.0.1:53238 287s app_test.go:1117: 16:17:03.966 - 30: DEBUG: new connection from 127.0.0.1:53254 287s app_test.go:1117: 16:17:03.973 - 25: DEBUG: new connection from 127.0.0.1:37530 287s app_test.go:1117: 16:17:03.028 - 25: DEBUG: new connection from 127.0.0.1:37546 287s app_test.go:1117: 16:17:03.030 - 31: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1117: 16:17:03.032 - 25: DEBUG: new connection from 127.0.0.1:37550 287s app_test.go:1117: 16:17:03.035 - 26: DEBUG: new connection from 127.0.0.1:36158 287s app_test.go:1117: 16:17:03.038 - 27: DEBUG: new connection from 127.0.0.1:38768 287s app_test.go:1117: 16:17:03.040 - 28: DEBUG: new connection from 127.0.0.1:37634 287s app_test.go:1117: 16:17:03.042 - 29: DEBUG: new connection from 127.0.0.1:51586 287s app_test.go:1117: 16:17:03.045 - 30: DEBUG: new connection from 127.0.0.1:53262 287s app_test.go:1117: 16:17:03.047 - 31: DEBUG: new connection from 127.0.0.1:59404 287s app_test.go:1117: 16:17:03.050 - 25: DEBUG: new connection from 127.0.0.1:37556 287s app_test.go:1117: 16:17:03.052 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s --- PASS: TestNew_SixthJoiner (0.94s) 287s === RUN TestHandover_Voter 287s app_test.go:1117: 16:17:03.470 - 32: DEBUG: new connection from 127.0.0.1:37560 287s app_test.go:1117: 16:17:03.473 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1117: 16:17:03.476 - 32: DEBUG: new connection from 127.0.0.1:37568 287s app_test.go:1117: 16:17:03.539 - 32: DEBUG: new connection from 127.0.0.1:37584 287s app_test.go:1117: 16:17:03.541 - 33: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1117: 16:17:03.544 - 32: DEBUG: new connection from 127.0.0.1:37592 287s app_test.go:1117: 16:17:03.549 - 33: DEBUG: new connection from 127.0.0.1:36170 287s app_test.go:1117: 16:17:03.618 - 32: DEBUG: new connection from 127.0.0.1:37606 287s app_test.go:1117: 16:17:03.621 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1117: 16:17:03.625 - 32: DEBUG: new connection from 127.0.0.1:37610 287s app_test.go:1117: 16:17:03.628 - 33: DEBUG: new connection from 127.0.0.1:36182 287s app_test.go:1117: 16:17:03.631 - 34: DEBUG: new connection from 127.0.0.1:38782 287s app_test.go:1117: 16:17:03.636 - 34: DEBUG: new connection from 127.0.0.1:38792 287s app_test.go:1117: 16:17:03.641 - 32: DEBUG: new connection from 127.0.0.1:37612 287s app_test.go:1117: 16:17:03.645 - 33: DEBUG: new connection from 127.0.0.1:36190 287s app_test.go:1117: 16:17:03.650 - 32: DEBUG: new connection from 127.0.0.1:37614 287s app_test.go:1117: 16:17:03.728 - 32: DEBUG: new connection from 127.0.0.1:37626 287s app_test.go:1117: 16:17:03.730 - 35: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1117: 16:17:03.733 - 32: DEBUG: new connection from 127.0.0.1:37642 287s app_test.go:1117: 16:17:03.737 - 33: DEBUG: new connection from 127.0.0.1:36200 287s app_test.go:1117: 16:17:03.741 - 34: DEBUG: new connection from 127.0.0.1:38802 287s app_test.go:1117: 16:17:03.746 - 35: DEBUG: new connection from 127.0.0.1:37646 287s app_test.go:1117: 16:17:03.751 - 35: DEBUG: new connection from 127.0.0.1:37652 287s app_test.go:1117: 16:17:03.754 - 32: DEBUG: new connection from 127.0.0.1:37658 287s app_test.go:1117: 16:17:03.756 - 32: DEBUG: new connection from 127.0.0.1:37674 287s app_test.go:1117: 16:17:03.760 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1117: 16:17:03.761 - 32: DEBUG: new connection from 127.0.0.1:37676 287s app_test.go:1117: 16:17:03.763 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected 287s app_test.go:1117: 16:17:03.763 - 32: DEBUG: new connection from 127.0.0.1:37692 287s app_test.go:1117: 16:17:03.766 - 33: DEBUG: new connection from 127.0.0.1:36206 287s app_test.go:1117: 16:17:03.769 - 34: DEBUG: new connection from 127.0.0.1:38808 287s app_test.go:1117: 16:17:03.772 - 35: DEBUG: new connection from 127.0.0.1:37658 287s app_test.go:1117: 16:17:03.775 - 34: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 288s --- PASS: TestHandover_Voter (0.61s) 288s === RUN TestHandover_TwoNodes 288s app_test.go:1117: 16:17:03.080 - 36: DEBUG: new connection from 127.0.0.1:37706 288s app_test.go:1117: 16:17:03.083 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.084 - 36: DEBUG: new connection from 127.0.0.1:37714 288s app_test.go:1117: 16:17:03.147 - 36: DEBUG: new connection from 127.0.0.1:37728 288s app_test.go:1117: 16:17:03.150 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.155 - 36: DEBUG: new connection from 127.0.0.1:37734 288s app_test.go:1117: 16:17:03.159 - 37: DEBUG: new connection from 127.0.0.1:36208 288s app_test.go:1117: 16:17:03.162 - 36: DEBUG: new connection from 127.0.0.1:37748 288s app_test.go:1117: 16:17:03.165 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.165 - 36: DEBUG: new connection from 127.0.0.1:37752 288s app_test.go:1117: 16:17:03.168 - 37: DEBUG: new connection from 127.0.0.1:36222 288s app_test.go:1117: 16:17:03.171 - 37: DEBUG: new connection from 127.0.0.1:36226 288s app_test.go:1117: 16:17:03.176 - 36: DEBUG: new connection from 127.0.0.1:37760 288s app_test.go:1117: 16:17:03.180 - 36: DEBUG: promoted 127.0.0.1:9002 from spare to voter 288s app_test.go:1117: 16:17:03.181 - 36: DEBUG: new connection from 127.0.0.1:37776 288s app_test.go:1117: 16:17:03.183 - 37: DEBUG: new connection from 127.0.0.1:36228 288s app_test.go:1117: 16:17:03.190 - 36: DEBUG: new connection from 127.0.0.1:37786 288s app_test.go:1117: 16:17:03.193 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 288s app_test.go:1117: 16:17:03.193 - 37: DEBUG: new connection from 127.0.0.1:36232 288s app_test.go:1117: 16:17:03.197 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.198 - 36: DEBUG: new connection from 127.0.0.1:37790 288s app_test.go:1117: 16:17:03.201 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 288s app_test.go:1117: 16:17:03.201 - 37: DEBUG: new connection from 127.0.0.1:36234 288s app_test.go:1117: 16:17:03.205 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s --- PASS: TestHandover_TwoNodes (0.32s) 288s === RUN TestHandover_VoterHonorFailureDomain 288s app_test.go:1117: 16:17:03.389 - 38: DEBUG: new connection from 127.0.0.1:37800 288s app_test.go:1117: 16:17:03.392 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.393 - 38: DEBUG: new connection from 127.0.0.1:37802 288s app_test.go:1117: 16:17:03.448 - 38: DEBUG: new connection from 127.0.0.1:37816 288s app_test.go:1117: 16:17:03.450 - 39: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.453 - 38: DEBUG: new connection from 127.0.0.1:37826 288s app_test.go:1117: 16:17:03.455 - 39: DEBUG: new connection from 127.0.0.1:36250 288s app_test.go:1117: 16:17:03.507 - 38: DEBUG: new connection from 127.0.0.1:37836 288s app_test.go:1117: 16:17:03.510 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.512 - 38: DEBUG: new connection from 127.0.0.1:37840 288s app_test.go:1117: 16:17:03.516 - 39: DEBUG: new connection from 127.0.0.1:36256 288s app_test.go:1117: 16:17:03.519 - 40: DEBUG: new connection from 127.0.0.1:38822 288s app_test.go:1117: 16:17:03.523 - 40: DEBUG: new connection from 127.0.0.1:38832 288s app_test.go:1117: 16:17:03.529 - 38: DEBUG: new connection from 127.0.0.1:37846 288s app_test.go:1117: 16:17:03.534 - 39: DEBUG: new connection from 127.0.0.1:36262 288s app_test.go:1117: 16:17:03.538 - 38: DEBUG: new connection from 127.0.0.1:37858 288s app_test.go:1117: 16:17:03.577 - 38: DEBUG: new connection from 127.0.0.1:37870 288s app_test.go:1117: 16:17:03.579 - 41: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.581 - 38: DEBUG: new connection from 127.0.0.1:37874 288s app_test.go:1117: 16:17:03.584 - 39: DEBUG: new connection from 127.0.0.1:36272 288s app_test.go:1117: 16:17:03.587 - 40: DEBUG: new connection from 127.0.0.1:38834 288s app_test.go:1117: 16:17:03.590 - 41: DEBUG: new connection from 127.0.0.1:37664 288s app_test.go:1117: 16:17:03.593 - 41: DEBUG: new connection from 127.0.0.1:37676 288s app_test.go:1117: 16:17:03.600 - 38: DEBUG: new connection from 127.0.0.1:37884 288s app_test.go:1117: 16:17:03.648 - 38: DEBUG: new connection from 127.0.0.1:37890 288s app_test.go:1117: 16:17:03.650 - 42: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.652 - 38: DEBUG: new connection from 127.0.0.1:37906 288s app_test.go:1117: 16:17:03.655 - 39: DEBUG: new connection from 127.0.0.1:36278 288s app_test.go:1117: 16:17:03.658 - 40: DEBUG: new connection from 127.0.0.1:38846 288s app_test.go:1117: 16:17:03.660 - 41: DEBUG: new connection from 127.0.0.1:37678 288s app_test.go:1117: 16:17:03.663 - 42: DEBUG: new connection from 127.0.0.1:51596 288s app_test.go:1117: 16:17:03.668 - 42: DEBUG: new connection from 127.0.0.1:51598 288s app_test.go:1117: 16:17:03.673 - 38: DEBUG: new connection from 127.0.0.1:37908 288s app_test.go:1117: 16:17:03.708 - 38: DEBUG: new connection from 127.0.0.1:37918 288s app_test.go:1117: 16:17:03.710 - 43: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.712 - 38: DEBUG: new connection from 127.0.0.1:37930 288s app_test.go:1117: 16:17:03.715 - 39: DEBUG: new connection from 127.0.0.1:36286 288s app_test.go:1117: 16:17:03.717 - 40: DEBUG: new connection from 127.0.0.1:38860 288s app_test.go:1117: 16:17:03.720 - 41: DEBUG: new connection from 127.0.0.1:37684 288s app_test.go:1117: 16:17:03.722 - 42: DEBUG: new connection from 127.0.0.1:51608 288s app_test.go:1117: 16:17:03.724 - 43: DEBUG: new connection from 127.0.0.1:53276 288s app_test.go:1117: 16:17:03.727 - 43: DEBUG: new connection from 127.0.0.1:53284 288s app_test.go:1117: 16:17:03.730 - 38: DEBUG: new connection from 127.0.0.1:37940 288s app_test.go:1117: 16:17:03.732 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.734 - 38: DEBUG: new connection from 127.0.0.1:37948 288s app_test.go:1117: 16:17:03.735 - 38: DEBUG: new connection from 127.0.0.1:37964 288s app_test.go:1117: 16:17:03.740 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected 288s app_test.go:1117: 16:17:03.741 - 38: DEBUG: new connection from 127.0.0.1:37972 288s app_test.go:1117: 16:17:03.743 - 39: DEBUG: new connection from 127.0.0.1:36294 288s app_test.go:1117: 16:17:03.745 - 40: DEBUG: new connection from 127.0.0.1:38876 288s app_test.go:1117: 16:17:03.748 - 41: DEBUG: new connection from 127.0.0.1:37698 288s app_test.go:1117: 16:17:03.750 - 42: DEBUG: new connection from 127.0.0.1:51624 288s app_test.go:1117: 16:17:03.752 - 43: DEBUG: new connection from 127.0.0.1:53288 288s app_test.go:1117: 16:17:03.755 - 40: DEBUG: promoted 127.0.0.1:9006 from stand-by to voter 289s --- PASS: TestHandover_VoterHonorFailureDomain (0.74s) 289s === RUN TestHandover_SingleNode 289s --- PASS: TestHandover_SingleNode (0.06s) 289s === RUN TestHandover_GracefulShutdown 289s --- PASS: TestHandover_GracefulShutdown (0.39s) 289s === RUN TestHandover_StandBy 289s app_test.go:1117: 16:17:03.599 - 44: DEBUG: new connection from 127.0.0.1:38210 289s app_test.go:1117: 16:17:03.602 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected 289s app_test.go:1117: 16:17:03.604 - 44: DEBUG: new connection from 127.0.0.1:38226 289s app_test.go:1117: 16:17:03.668 - 44: DEBUG: new connection from 127.0.0.1:38240 289s app_test.go:1117: 16:17:03.671 - 45: DEBUG: attempt 1: server 127.0.0.1:9001: connected 289s app_test.go:1117: 16:17:03.674 - 44: DEBUG: new connection from 127.0.0.1:38256 289s app_test.go:1117: 16:17:03.677 - 45: DEBUG: new connection from 127.0.0.1:36472 289s app_test.go:1117: 16:17:03.739 - 44: DEBUG: new connection from 127.0.0.1:38260 289s app_test.go:1117: 16:17:03.744 - 46: DEBUG: attempt 1: server 127.0.0.1:9001: connected 289s app_test.go:1117: 16:17:03.748 - 44: DEBUG: new connection from 127.0.0.1:38276 289s app_test.go:1117: 16:17:03.755 - 45: DEBUG: new connection from 127.0.0.1:36476 289s app_test.go:1117: 16:17:03.760 - 46: DEBUG: new connection from 127.0.0.1:38980 289s app_test.go:1117: 16:17:03.764 - 46: DEBUG: new connection from 127.0.0.1:38994 289s app_test.go:1117: 16:17:03.771 - 44: DEBUG: new connection from 127.0.0.1:38290 289s app_test.go:1117: 16:17:03.776 - 45: DEBUG: new connection from 127.0.0.1:36486 289s app_test.go:1117: 16:17:03.780 - 44: DEBUG: new connection from 127.0.0.1:38306 289s app_test.go:1117: 16:17:03.848 - 44: DEBUG: new connection from 127.0.0.1:38312 289s app_test.go:1117: 16:17:03.851 - 47: DEBUG: attempt 1: server 127.0.0.1:9001: connected 289s app_test.go:1117: 16:17:03.854 - 44: DEBUG: new connection from 127.0.0.1:38316 289s app_test.go:1117: 16:17:03.858 - 45: DEBUG: new connection from 127.0.0.1:36496 289s app_test.go:1117: 16:17:03.861 - 46: DEBUG: new connection from 127.0.0.1:39010 289s app_test.go:1117: 16:17:03.865 - 47: DEBUG: new connection from 127.0.0.1:37712 289s app_test.go:1117: 16:17:03.870 - 47: DEBUG: new connection from 127.0.0.1:37722 289s app_test.go:1117: 16:17:03.876 - 44: DEBUG: new connection from 127.0.0.1:38326 289s app_test.go:1117: 16:17:03.928 - 44: DEBUG: new connection from 127.0.0.1:38336 289s app_test.go:1117: 16:17:03.930 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected 289s app_test.go:1117: 16:17:03.935 - 44: DEBUG: new connection from 127.0.0.1:38344 289s app_test.go:1117: 16:17:03.941 - 45: DEBUG: new connection from 127.0.0.1:36506 289s app_test.go:1117: 16:17:03.945 - 46: DEBUG: new connection from 127.0.0.1:39018 289s app_test.go:1117: 16:17:03.950 - 47: DEBUG: new connection from 127.0.0.1:37726 289s app_test.go:1117: 16:17:03.955 - 48: DEBUG: new connection from 127.0.0.1:51636 289s app_test.go:1117: 16:17:03.960 - 48: DEBUG: new connection from 127.0.0.1:51640 290s app_test.go:1117: 16:17:03.968 - 44: DEBUG: new connection from 127.0.0.1:38348 290s app_test.go:1117: 16:17:03.017 - 44: DEBUG: new connection from 127.0.0.1:38356 290s app_test.go:1117: 16:17:03.021 - 49: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.023 - 44: DEBUG: new connection from 127.0.0.1:38362 290s app_test.go:1117: 16:17:03.027 - 45: DEBUG: new connection from 127.0.0.1:36510 290s app_test.go:1117: 16:17:03.033 - 46: DEBUG: new connection from 127.0.0.1:39020 290s app_test.go:1117: 16:17:03.038 - 47: DEBUG: new connection from 127.0.0.1:37734 290s app_test.go:1117: 16:17:03.041 - 48: DEBUG: new connection from 127.0.0.1:51642 290s app_test.go:1117: 16:17:03.045 - 49: DEBUG: new connection from 127.0.0.1:53304 290s app_test.go:1117: 16:17:03.050 - 49: DEBUG: new connection from 127.0.0.1:53312 290s app_test.go:1117: 16:17:03.057 - 44: DEBUG: new connection from 127.0.0.1:38366 290s app_test.go:1117: 16:17:03.117 - 44: DEBUG: new connection from 127.0.0.1:38368 290s app_test.go:1117: 16:17:03.119 - 50: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.121 - 44: DEBUG: new connection from 127.0.0.1:38382 290s app_test.go:1117: 16:17:03.124 - 45: DEBUG: new connection from 127.0.0.1:36512 290s app_test.go:1117: 16:17:03.127 - 46: DEBUG: new connection from 127.0.0.1:39028 290s app_test.go:1117: 16:17:03.129 - 47: DEBUG: new connection from 127.0.0.1:37742 290s app_test.go:1117: 16:17:03.132 - 48: DEBUG: new connection from 127.0.0.1:51654 290s app_test.go:1117: 16:17:03.134 - 49: DEBUG: new connection from 127.0.0.1:53324 290s app_test.go:1117: 16:17:03.139 - 50: DEBUG: new connection from 127.0.0.1:59418 290s app_test.go:1117: 16:17:03.141 - 44: DEBUG: new connection from 127.0.0.1:38394 290s app_test.go:1117: 16:17:03.143 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.144 - 44: DEBUG: new connection from 127.0.0.1:38400 290s app_test.go:1117: 16:17:03.146 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.146 - 44: DEBUG: new connection from 127.0.0.1:38404 290s app_test.go:1117: 16:17:03.148 - 45: DEBUG: new connection from 127.0.0.1:36514 290s app_test.go:1117: 16:17:03.150 - 46: DEBUG: new connection from 127.0.0.1:39044 290s app_test.go:1117: 16:17:03.153 - 47: DEBUG: new connection from 127.0.0.1:37744 290s app_test.go:1117: 16:17:03.155 - 48: DEBUG: new connection from 127.0.0.1:51658 290s app_test.go:1117: 16:17:03.158 - 49: DEBUG: new connection from 127.0.0.1:53332 290s app_test.go:1117: 16:17:03.160 - 50: DEBUG: new connection from 127.0.0.1:59424 290s app_test.go:1117: 16:17:03.164 - 50: DEBUG: new connection from 127.0.0.1:59440 290s app_test.go:1117: 16:17:03.165 - 48: DEBUG: promoted 127.0.0.1:9007 from spare to stand-by 290s --- PASS: TestHandover_StandBy (0.99s) 290s === RUN TestHandover_TransferLeadership 290s app_test.go:1117: 16:17:03.579 - 51: DEBUG: new connection from 127.0.0.1:38408 290s app_test.go:1117: 16:17:03.582 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.584 - 51: DEBUG: new connection from 127.0.0.1:38422 290s app_test.go:1117: 16:17:03.647 - 51: DEBUG: new connection from 127.0.0.1:38438 290s app_test.go:1117: 16:17:03.650 - 52: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.652 - 51: DEBUG: new connection from 127.0.0.1:38448 290s app_test.go:1117: 16:17:03.654 - 52: DEBUG: new connection from 127.0.0.1:36520 290s app_test.go:1117: 16:17:03.717 - 51: DEBUG: new connection from 127.0.0.1:38464 290s app_test.go:1117: 16:17:03.719 - 53: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.720 - 51: DEBUG: new connection from 127.0.0.1:38470 290s app_test.go:1117: 16:17:03.724 - 52: DEBUG: new connection from 127.0.0.1:36532 290s app_test.go:1117: 16:17:03.727 - 53: DEBUG: new connection from 127.0.0.1:39054 290s app_test.go:1117: 16:17:03.730 - 53: DEBUG: new connection from 127.0.0.1:39060 290s app_test.go:1117: 16:17:03.734 - 51: DEBUG: new connection from 127.0.0.1:38476 290s app_test.go:1117: 16:17:03.738 - 52: DEBUG: new connection from 127.0.0.1:36544 290s app_test.go:1117: 16:17:03.741 - 51: DEBUG: new connection from 127.0.0.1:38480 290s app_test.go:1117: 16:17:03.797 - 51: DEBUG: new connection from 127.0.0.1:38484 290s app_test.go:1117: 16:17:03.799 - 54: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.801 - 51: DEBUG: new connection from 127.0.0.1:38496 290s app_test.go:1117: 16:17:03.803 - 52: DEBUG: new connection from 127.0.0.1:36556 290s app_test.go:1117: 16:17:03.805 - 53: DEBUG: new connection from 127.0.0.1:39066 290s app_test.go:1117: 16:17:03.807 - 54: DEBUG: new connection from 127.0.0.1:37760 290s app_test.go:1117: 16:17:03.810 - 54: DEBUG: new connection from 127.0.0.1:37774 290s app_test.go:1117: 16:17:03.812 - 51: DEBUG: new connection from 127.0.0.1:38502 290s app_test.go:1117: 16:17:03.814 - 51: DEBUG: new connection from 127.0.0.1:38508 290s app_test.go:1117: 16:17:03.819 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.820 - 51: DEBUG: new connection from 127.0.0.1:38514 290s app_test.go:1117: 16:17:03.822 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.822 - 51: DEBUG: new connection from 127.0.0.1:38526 290s app_test.go:1117: 16:17:03.825 - 52: DEBUG: new connection from 127.0.0.1:36566 290s app_test.go:1117: 16:17:03.827 - 53: DEBUG: new connection from 127.0.0.1:39076 290s app_test.go:1117: 16:17:03.829 - 54: DEBUG: new connection from 127.0.0.1:37780 290s app_test.go:1117: 16:17:03.832 - 51: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 290s app_test.go:1117: 16:17:03.832 - 51: DEBUG: new connection from 127.0.0.1:38542 290s app_test.go:1117: 16:17:03.835 - 52: DEBUG: new connection from 127.0.0.1:36568 290s app_test.go:1117: 16:17:03.837 - 53: DEBUG: new connection from 127.0.0.1:39092 290s app_test.go:1117: 16:17:03.839 - 54: DEBUG: new connection from 127.0.0.1:37788 290s app_test.go:1117: 16:17:03.842 - 53: DEBUG: new connection from 127.0.0.1:39100 290s app_test.go:1117: 16:17:03.843 - 54: DEBUG: new connection from 127.0.0.1:37796 290s app_test.go:1117: 16:17:03.848 - 52: DEBUG: new connection from 127.0.0.1:36574 290s app_test.go:1117: 16:17:03.849 - 52: DEBUG: new connection from 127.0.0.1:36590 290s app_test.go:1117: 16:17:03.854 - 51: DEBUG: new connection from 127.0.0.1:38554 290s app_test.go:1117: 16:17:03.857 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 290s app_test.go:1117: 16:17:03.857 - 52: DEBUG: new connection from 127.0.0.1:36594 290s app_test.go:1117: 16:17:03.859 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 290s app_test.go:1117: 16:17:03.860 - 51: DEBUG: new connection from 127.0.0.1:38560 290s app_test.go:1117: 16:17:03.862 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 290s app_test.go:1117: 16:17:03.862 - 52: DEBUG: new connection from 127.0.0.1:36608 290s app_test.go:1117: 16:17:03.864 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 291s --- PASS: TestHandover_TransferLeadership (0.56s) 291s === RUN TestRolesAdjustment_ReplaceVoter 291s app_test.go:1117: 16:17:03.159 - 55: DEBUG: new connection from 127.0.0.1:38562 291s app_test.go:1117: 16:17:03.164 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 291s app_test.go:1117: 16:17:03.165 - 55: DEBUG: new connection from 127.0.0.1:38566 291s app_test.go:1117: 16:17:03.229 - 55: DEBUG: new connection from 127.0.0.1:38578 291s app_test.go:1117: 16:17:03.234 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 291s app_test.go:1117: 16:17:03.239 - 55: DEBUG: new connection from 127.0.0.1:38592 291s app_test.go:1117: 16:17:03.245 - 56: DEBUG: new connection from 127.0.0.1:36614 291s app_test.go:1117: 16:17:03.308 - 55: DEBUG: new connection from 127.0.0.1:38596 291s app_test.go:1117: 16:17:03.310 - 57: DEBUG: attempt 1: server 127.0.0.1:9001: connected 291s app_test.go:1117: 16:17:03.312 - 55: DEBUG: new connection from 127.0.0.1:38606 291s app_test.go:1117: 16:17:03.316 - 56: DEBUG: new connection from 127.0.0.1:36630 291s app_test.go:1117: 16:17:03.319 - 57: DEBUG: new connection from 127.0.0.1:39102 291s app_test.go:1117: 16:17:03.323 - 57: DEBUG: new connection from 127.0.0.1:39106 291s app_test.go:1117: 16:17:03.329 - 55: DEBUG: new connection from 127.0.0.1:38608 291s app_test.go:1117: 16:17:03.332 - 56: DEBUG: new connection from 127.0.0.1:36638 291s app_test.go:1117: 16:17:03.337 - 55: DEBUG: new connection from 127.0.0.1:38612 291s app_test.go:1117: 16:17:03.397 - 55: DEBUG: new connection from 127.0.0.1:38622 291s app_test.go:1117: 16:17:03.400 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 291s app_test.go:1117: 16:17:03.403 - 55: DEBUG: new connection from 127.0.0.1:38630 291s app_test.go:1117: 16:17:03.406 - 56: DEBUG: new connection from 127.0.0.1:36642 291s app_test.go:1117: 16:17:03.408 - 57: DEBUG: new connection from 127.0.0.1:39112 291s app_test.go:1117: 16:17:03.411 - 58: DEBUG: new connection from 127.0.0.1:37798 291s app_test.go:1117: 16:17:03.414 - 58: DEBUG: new connection from 127.0.0.1:37808 291s app_test.go:1117: 16:17:03.419 - 55: DEBUG: new connection from 127.0.0.1:38638 293s app_test.go:1117: 16:17:03.168 - 55: DEBUG: new connection from 127.0.0.1:38644 293s app_test.go:1117: 16:17:03.171 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 293s app_test.go:1117: 16:17:03.172 - 55: DEBUG: new connection from 127.0.0.1:38648 293s app_test.go:1117: 16:17:03.175 - 56: DEBUG: new connection from 127.0.0.1:36648 293s app_test.go:1117: 16:17:03.178 - 58: DEBUG: new connection from 127.0.0.1:37816 293s app_test.go:1117: 16:17:03.253 - 55: DEBUG: new connection from 127.0.0.1:58054 293s app_test.go:1117: 16:17:03.256 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 293s app_test.go:1117: 16:17:03.418 - 55: DEBUG: new connection from 127.0.0.1:58056 293s app_test.go:1117: 16:17:03.420 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 293s app_test.go:1117: 16:17:03.601 - 55: DEBUG: new connection from 127.0.0.1:58070 293s app_test.go:1117: 16:17:03.603 - 56: DEBUG: new connection from 127.0.0.1:44196 293s app_test.go:1117: 16:17:03.606 - 58: DEBUG: new connection from 127.0.0.1:32810 293s app_test.go:1117: 16:17:03.609 - 55: DEBUG: new connection from 127.0.0.1:58076 293s app_test.go:1117: 16:17:03.611 - 56: DEBUG: new connection from 127.0.0.1:44212 293s app_test.go:1117: 16:17:03.614 - 58: DEBUG: new connection from 127.0.0.1:32824 295s app_test.go:1117: 16:17:03.260 - 55: DEBUG: new connection from 127.0.0.1:58086 295s app_test.go:1117: 16:17:03.262 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 295s app_test.go:1117: 16:17:03.423 - 55: DEBUG: new connection from 127.0.0.1:58090 295s app_test.go:1117: 16:17:03.425 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 295s app_test.go:1117: 16:17:03.617 - 55: DEBUG: new connection from 127.0.0.1:58100 295s app_test.go:1117: 16:17:03.619 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 295s app_test.go:1117: 16:17:03.621 - 55: DEBUG: new connection from 127.0.0.1:58112 295s app_test.go:1117: 16:17:03.624 - 56: DEBUG: new connection from 127.0.0.1:44226 295s app_test.go:1117: 16:17:03.627 - 58: DEBUG: new connection from 127.0.0.1:32826 297s app_test.go:1117: 16:17:03.266 - 55: DEBUG: new connection from 127.0.0.1:58128 297s app_test.go:1117: 16:17:03.275 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 297s app_test.go:1117: 16:17:03.430 - 55: DEBUG: new connection from 127.0.0.1:58142 297s app_test.go:1117: 16:17:03.432 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 297s app_test.go:1117: 16:17:03.630 - 55: DEBUG: new connection from 127.0.0.1:58156 297s app_test.go:1117: 16:17:03.633 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 297s app_test.go:1117: 16:17:03.636 - 55: DEBUG: new connection from 127.0.0.1:58170 297s app_test.go:1117: 16:17:03.640 - 56: DEBUG: new connection from 127.0.0.1:44232 297s app_test.go:1117: 16:17:03.642 - 58: DEBUG: new connection from 127.0.0.1:32832 299s app_test.go:1117: 16:17:03.279 - 55: DEBUG: new connection from 127.0.0.1:58186 299s app_test.go:1117: 16:17:03.285 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s app_test.go:1117: 16:17:03.435 - 55: DEBUG: new connection from 127.0.0.1:58194 299s app_test.go:1117: 16:17:03.438 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s app_test.go:1117: 16:17:03.477 - 55: DEBUG: new connection from 127.0.0.1:58208 299s app_test.go:1117: 16:17:03.479 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s --- PASS: TestRolesAdjustment_ReplaceVoter (8.59s) 299s === RUN TestRolesAdjustment_ReplaceVoterHonorFailureDomain 299s app_test.go:1117: 16:17:03.749 - 59: DEBUG: new connection from 127.0.0.1:58216 299s app_test.go:1117: 16:17:03.753 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s app_test.go:1117: 16:17:03.755 - 59: DEBUG: new connection from 127.0.0.1:58232 299s app_test.go:1117: 16:17:03.817 - 59: DEBUG: new connection from 127.0.0.1:58238 299s app_test.go:1117: 16:17:03.819 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s app_test.go:1117: 16:17:03.821 - 59: DEBUG: new connection from 127.0.0.1:58252 299s app_test.go:1117: 16:17:03.824 - 60: DEBUG: new connection from 127.0.0.1:44236 299s app_test.go:1117: 16:17:03.887 - 59: DEBUG: new connection from 127.0.0.1:58268 299s app_test.go:1117: 16:17:03.891 - 61: DEBUG: attempt 1: server 127.0.0.1:9001: connected 299s app_test.go:1117: 16:17:03.894 - 59: DEBUG: new connection from 127.0.0.1:58276 299s app_test.go:1117: 16:17:03.897 - 60: DEBUG: new connection from 127.0.0.1:44248 299s app_test.go:1117: 16:17:03.899 - 61: DEBUG: new connection from 127.0.0.1:58154 299s app_test.go:1117: 16:17:03.902 - 61: DEBUG: new connection from 127.0.0.1:58166 299s app_test.go:1117: 16:17:03.906 - 59: DEBUG: new connection from 127.0.0.1:58286 299s app_test.go:1117: 16:17:03.912 - 60: DEBUG: new connection from 127.0.0.1:44258 299s app_test.go:1117: 16:17:03.916 - 59: DEBUG: new connection from 127.0.0.1:58298 300s app_test.go:1117: 16:17:03.967 - 59: DEBUG: new connection from 127.0.0.1:58304 300s app_test.go:1117: 16:17:03.970 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 300s app_test.go:1117: 16:17:03.971 - 59: DEBUG: new connection from 127.0.0.1:58308 300s app_test.go:1117: 16:17:03.974 - 60: DEBUG: new connection from 127.0.0.1:44268 300s app_test.go:1117: 16:17:03.976 - 61: DEBUG: new connection from 127.0.0.1:58170 300s app_test.go:1117: 16:17:03.978 - 62: DEBUG: new connection from 127.0.0.1:32844 300s app_test.go:1117: 16:17:03.981 - 62: DEBUG: new connection from 127.0.0.1:32856 300s app_test.go:1117: 16:17:03.986 - 59: DEBUG: new connection from 127.0.0.1:58324 300s app_test.go:1117: 16:17:03.047 - 59: DEBUG: new connection from 127.0.0.1:58340 300s app_test.go:1117: 16:17:03.049 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 300s app_test.go:1117: 16:17:03.052 - 59: DEBUG: new connection from 127.0.0.1:58348 300s app_test.go:1117: 16:17:03.056 - 60: DEBUG: new connection from 127.0.0.1:44274 300s app_test.go:1117: 16:17:03.063 - 61: DEBUG: new connection from 127.0.0.1:58186 300s app_test.go:1117: 16:17:03.068 - 62: DEBUG: new connection from 127.0.0.1:32862 300s app_test.go:1117: 16:17:03.072 - 63: DEBUG: new connection from 127.0.0.1:39752 300s app_test.go:1117: 16:17:03.076 - 63: DEBUG: new connection from 127.0.0.1:39762 300s app_test.go:1117: 16:17:03.083 - 59: DEBUG: new connection from 127.0.0.1:58364 300s app_test.go:1117: 16:17:03.139 - 59: DEBUG: new connection from 127.0.0.1:58372 300s app_test.go:1117: 16:17:03.142 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 300s app_test.go:1117: 16:17:03.146 - 59: DEBUG: new connection from 127.0.0.1:58384 300s app_test.go:1117: 16:17:03.150 - 60: DEBUG: new connection from 127.0.0.1:44280 300s app_test.go:1117: 16:17:03.154 - 61: DEBUG: new connection from 127.0.0.1:58196 300s app_test.go:1117: 16:17:03.159 - 62: DEBUG: new connection from 127.0.0.1:32868 300s app_test.go:1117: 16:17:03.163 - 63: DEBUG: new connection from 127.0.0.1:39768 300s app_test.go:1117: 16:17:03.169 - 64: DEBUG: new connection from 127.0.0.1:33692 300s app_test.go:1117: 16:17:03.177 - 64: DEBUG: new connection from 127.0.0.1:33702 300s app_test.go:1117: 16:17:03.186 - 59: DEBUG: new connection from 127.0.0.1:58388 303s app_test.go:1117: 16:17:03.759 - 59: DEBUG: new connection from 127.0.0.1:52422 303s app_test.go:1117: 16:17:03.762 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 303s app_test.go:1117: 16:17:03.764 - 59: DEBUG: new connection from 127.0.0.1:52438 303s app_test.go:1117: 16:17:03.767 - 60: DEBUG: new connection from 127.0.0.1:56886 303s app_test.go:1117: 16:17:03.769 - 62: DEBUG: new connection from 127.0.0.1:56350 303s app_test.go:1117: 16:17:03.771 - 63: DEBUG: new connection from 127.0.0.1:34178 303s app_test.go:1117: 16:17:03.775 - 64: DEBUG: new connection from 127.0.0.1:51438 303s app_test.go:1117: 16:17:03.826 - 59: DEBUG: new connection from 127.0.0.1:52450 303s app_test.go:1117: 16:17:03.828 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 304s app_test.go:1117: 16:17:03.984 - 59: DEBUG: new connection from 127.0.0.1:52456 304s app_test.go:1117: 16:17:03.988 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 304s app_test.go:1117: 16:17:03.081 - 59: DEBUG: new connection from 127.0.0.1:52466 304s app_test.go:1117: 16:17:03.083 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 304s app_test.go:1117: 16:17:03.183 - 59: DEBUG: new connection from 127.0.0.1:52470 304s app_test.go:1117: 16:17:03.187 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 304s app_test.go:1117: 16:17:03.203 - 59: DEBUG: new connection from 127.0.0.1:52480 304s app_test.go:1117: 16:17:03.207 - 60: DEBUG: new connection from 127.0.0.1:56902 304s app_test.go:1117: 16:17:03.211 - 62: DEBUG: new connection from 127.0.0.1:56366 304s app_test.go:1117: 16:17:03.213 - 63: DEBUG: new connection from 127.0.0.1:34190 304s app_test.go:1117: 16:17:03.216 - 64: DEBUG: new connection from 127.0.0.1:51444 304s app_test.go:1117: 16:17:03.219 - 59: DEBUG: new connection from 127.0.0.1:52496 304s app_test.go:1117: 16:17:03.221 - 60: DEBUG: new connection from 127.0.0.1:56910 304s app_test.go:1117: 16:17:03.224 - 62: DEBUG: new connection from 127.0.0.1:56378 304s app_test.go:1117: 16:17:03.227 - 63: DEBUG: new connection from 127.0.0.1:34196 304s app_test.go:1117: 16:17:03.230 - 64: DEBUG: new connection from 127.0.0.1:51452 307s app_test.go:1117: 16:17:03.831 - 59: DEBUG: new connection from 127.0.0.1:52510 307s app_test.go:1117: 16:17:03.834 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 308s app_test.go:1117: 16:17:03.991 - 59: DEBUG: new connection from 127.0.0.1:52518 308s app_test.go:1117: 16:17:03.993 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 308s app_test.go:1117: 16:17:03.086 - 59: DEBUG: new connection from 127.0.0.1:52528 308s app_test.go:1117: 16:17:03.088 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 308s app_test.go:1117: 16:17:03.190 - 59: DEBUG: new connection from 127.0.0.1:52538 308s app_test.go:1117: 16:17:03.192 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 308s app_test.go:1117: 16:17:03.233 - 59: DEBUG: new connection from 127.0.0.1:52540 308s app_test.go:1117: 16:17:03.235 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 308s app_test.go:1117: 16:17:03.237 - 59: DEBUG: new connection from 127.0.0.1:52548 308s app_test.go:1117: 16:17:03.241 - 60: DEBUG: new connection from 127.0.0.1:56914 308s app_test.go:1117: 16:17:03.244 - 62: DEBUG: new connection from 127.0.0.1:56392 308s app_test.go:1117: 16:17:03.247 - 63: DEBUG: new connection from 127.0.0.1:34206 308s app_test.go:1117: 16:17:03.250 - 64: DEBUG: new connection from 127.0.0.1:51464 311s app_test.go:1117: 16:17:03.837 - 59: DEBUG: new connection from 127.0.0.1:52550 311s app_test.go:1117: 16:17:03.839 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 312s app_test.go:1117: 16:17:03.996 - 59: DEBUG: new connection from 127.0.0.1:52558 312s app_test.go:1117: 16:17:03.998 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 312s app_test.go:1117: 16:17:03.091 - 59: DEBUG: new connection from 127.0.0.1:52574 312s app_test.go:1117: 16:17:03.093 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 312s app_test.go:1117: 16:17:03.194 - 59: DEBUG: new connection from 127.0.0.1:52576 312s app_test.go:1117: 16:17:03.196 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 312s app_test.go:1117: 16:17:03.253 - 59: DEBUG: new connection from 127.0.0.1:52588 312s app_test.go:1117: 16:17:03.257 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 312s app_test.go:1117: 16:17:03.261 - 59: DEBUG: new connection from 127.0.0.1:52602 312s app_test.go:1117: 16:17:03.267 - 60: DEBUG: new connection from 127.0.0.1:56924 312s app_test.go:1117: 16:17:03.272 - 62: DEBUG: new connection from 127.0.0.1:56400 312s app_test.go:1117: 16:17:03.278 - 63: DEBUG: new connection from 127.0.0.1:34216 312s app_test.go:1117: 16:17:03.281 - 64: DEBUG: new connection from 127.0.0.1:51478 315s app_test.go:1117: 16:17:03.842 - 59: DEBUG: new connection from 127.0.0.1:39994 315s app_test.go:1117: 16:17:03.845 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 316s app_test.go:1117: 16:17:03.001 - 59: DEBUG: new connection from 127.0.0.1:40008 316s app_test.go:1117: 16:17:03.006 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 316s app_test.go:1117: 16:17:03.095 - 59: DEBUG: new connection from 127.0.0.1:40018 316s app_test.go:1117: 16:17:03.097 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 316s app_test.go:1117: 16:17:03.199 - 59: DEBUG: new connection from 127.0.0.1:40022 316s app_test.go:1117: 16:17:03.201 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 316s app_test.go:1117: 16:17:03.286 - 59: DEBUG: new connection from 127.0.0.1:40032 316s app_test.go:1117: 16:17:03.289 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 316s app_test.go:1117: 16:17:03.291 - 59: DEBUG: new connection from 127.0.0.1:40046 316s app_test.go:1117: 16:17:03.294 - 60: DEBUG: new connection from 127.0.0.1:50496 316s app_test.go:1117: 16:17:03.296 - 62: DEBUG: new connection from 127.0.0.1:49218 316s app_test.go:1117: 16:17:03.298 - 63: DEBUG: new connection from 127.0.0.1:59384 316s app_test.go:1117: 16:17:03.301 - 64: DEBUG: new connection from 127.0.0.1:47906 318s app_test.go:1117: 16:17:03.227 - 59: DEBUG: new connection from 127.0.0.1:40054 318s app_test.go:1117: 16:17:03.230 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s --- PASS: TestRolesAdjustment_ReplaceVoterHonorFailureDomain (18.87s) 318s === RUN TestRolesAdjustment_ReplaceVoterHonorWeight 318s app_test.go:1117: 16:17:03.608 - 65: DEBUG: new connection from 127.0.0.1:40064 318s app_test.go:1117: 16:17:03.611 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1117: 16:17:03.612 - 65: DEBUG: new connection from 127.0.0.1:40068 318s app_test.go:1117: 16:17:03.677 - 65: DEBUG: new connection from 127.0.0.1:40080 318s app_test.go:1117: 16:17:03.679 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1117: 16:17:03.682 - 65: DEBUG: new connection from 127.0.0.1:40082 318s app_test.go:1117: 16:17:03.684 - 66: DEBUG: new connection from 127.0.0.1:50498 318s app_test.go:1117: 16:17:03.748 - 65: DEBUG: new connection from 127.0.0.1:40096 318s app_test.go:1117: 16:17:03.751 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1117: 16:17:03.753 - 65: DEBUG: new connection from 127.0.0.1:40100 318s app_test.go:1117: 16:17:03.757 - 66: DEBUG: new connection from 127.0.0.1:50510 318s app_test.go:1117: 16:17:03.759 - 67: DEBUG: new connection from 127.0.0.1:55478 318s app_test.go:1117: 16:17:03.762 - 67: DEBUG: new connection from 127.0.0.1:55490 318s app_test.go:1117: 16:17:03.767 - 65: DEBUG: new connection from 127.0.0.1:40112 318s app_test.go:1117: 16:17:03.772 - 66: DEBUG: new connection from 127.0.0.1:50514 318s app_test.go:1117: 16:17:03.775 - 65: DEBUG: new connection from 127.0.0.1:40116 318s app_test.go:1117: 16:17:03.828 - 65: DEBUG: new connection from 127.0.0.1:40132 318s app_test.go:1117: 16:17:03.831 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1117: 16:17:03.835 - 65: DEBUG: new connection from 127.0.0.1:40134 318s app_test.go:1117: 16:17:03.838 - 66: DEBUG: new connection from 127.0.0.1:50524 318s app_test.go:1117: 16:17:03.841 - 67: DEBUG: new connection from 127.0.0.1:55506 318s app_test.go:1117: 16:17:03.844 - 68: DEBUG: new connection from 127.0.0.1:49220 318s app_test.go:1117: 16:17:03.848 - 68: DEBUG: new connection from 127.0.0.1:49230 318s app_test.go:1117: 16:17:03.856 - 65: DEBUG: new connection from 127.0.0.1:40142 318s app_test.go:1117: 16:17:03.907 - 65: DEBUG: new connection from 127.0.0.1:40156 318s app_test.go:1117: 16:17:03.910 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 318s app_test.go:1117: 16:17:03.913 - 65: DEBUG: new connection from 127.0.0.1:40168 318s app_test.go:1117: 16:17:03.915 - 66: DEBUG: new connection from 127.0.0.1:50532 318s app_test.go:1117: 16:17:03.918 - 67: DEBUG: new connection from 127.0.0.1:55516 318s app_test.go:1117: 16:17:03.920 - 68: DEBUG: new connection from 127.0.0.1:49234 318s app_test.go:1117: 16:17:03.922 - 69: DEBUG: new connection from 127.0.0.1:59390 318s app_test.go:1117: 16:17:03.925 - 69: DEBUG: new connection from 127.0.0.1:59400 319s app_test.go:1117: 16:17:03.929 - 65: DEBUG: new connection from 127.0.0.1:40174 319s app_test.go:1117: 16:17:03.977 - 65: DEBUG: new connection from 127.0.0.1:40182 319s app_test.go:1117: 16:17:03.980 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 319s app_test.go:1117: 16:17:03.982 - 65: DEBUG: new connection from 127.0.0.1:40192 319s app_test.go:1117: 16:17:03.986 - 66: DEBUG: new connection from 127.0.0.1:50536 319s app_test.go:1117: 16:17:03.989 - 67: DEBUG: new connection from 127.0.0.1:55520 319s app_test.go:1117: 16:17:03.996 - 68: DEBUG: new connection from 127.0.0.1:49236 319s app_test.go:1117: 16:17:03.999 - 69: DEBUG: new connection from 127.0.0.1:59416 319s app_test.go:1117: 16:17:03.002 - 70: DEBUG: new connection from 127.0.0.1:47912 319s app_test.go:1117: 16:17:03.006 - 70: DEBUG: new connection from 127.0.0.1:47914 319s app_test.go:1117: 16:17:03.011 - 65: DEBUG: new connection from 127.0.0.1:40202 322s app_test.go:1117: 16:18:03.616 - 65: DEBUG: new connection from 127.0.0.1:40206 322s app_test.go:1117: 16:18:03.618 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 322s app_test.go:1117: 16:18:03.620 - 65: DEBUG: new connection from 127.0.0.1:40216 322s app_test.go:1117: 16:18:03.622 - 66: DEBUG: new connection from 127.0.0.1:50552 322s app_test.go:1117: 16:18:03.624 - 68: DEBUG: new connection from 127.0.0.1:49248 322s app_test.go:1117: 16:18:03.626 - 69: DEBUG: new connection from 127.0.0.1:59420 322s app_test.go:1117: 16:18:03.628 - 70: DEBUG: new connection from 127.0.0.1:47920 322s app_test.go:1117: 16:18:03.686 - 65: DEBUG: new connection from 127.0.0.1:40226 322s app_test.go:1117: 16:18:03.691 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 322s app_test.go:1117: 16:18:03.853 - 65: DEBUG: new connection from 127.0.0.1:40238 322s app_test.go:1117: 16:18:03.856 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 323s app_test.go:1117: 16:18:03.928 - 65: DEBUG: new connection from 127.0.0.1:40246 323s app_test.go:1117: 16:18:03.930 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 323s app_test.go:1117: 16:18:03.009 - 65: DEBUG: new connection from 127.0.0.1:40248 323s app_test.go:1117: 16:18:03.011 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 323s app_test.go:1117: 16:18:03.054 - 65: DEBUG: new connection from 127.0.0.1:40264 323s app_test.go:1117: 16:18:03.058 - 66: DEBUG: new connection from 127.0.0.1:50558 323s app_test.go:1117: 16:18:03.061 - 68: DEBUG: new connection from 127.0.0.1:49264 323s app_test.go:1117: 16:18:03.064 - 69: DEBUG: new connection from 127.0.0.1:59430 323s app_test.go:1117: 16:18:03.068 - 70: DEBUG: new connection from 127.0.0.1:47932 323s app_test.go:1117: 16:18:03.072 - 65: DEBUG: new connection from 127.0.0.1:40274 323s app_test.go:1117: 16:18:03.074 - 66: DEBUG: new connection from 127.0.0.1:50560 323s app_test.go:1117: 16:18:03.077 - 68: DEBUG: new connection from 127.0.0.1:49276 323s app_test.go:1117: 16:18:03.080 - 69: DEBUG: new connection from 127.0.0.1:59442 323s app_test.go:1117: 16:18:03.082 - 70: DEBUG: new connection from 127.0.0.1:47936 326s app_test.go:1117: 16:18:03.693 - 65: DEBUG: new connection from 127.0.0.1:56482 326s app_test.go:1117: 16:18:03.697 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 326s app_test.go:1117: 16:18:03.860 - 65: DEBUG: new connection from 127.0.0.1:56486 327s app_test.go:1117: 16:18:03.864 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 327s app_test.go:1117: 16:18:03.932 - 65: DEBUG: new connection from 127.0.0.1:56490 327s app_test.go:1117: 16:18:03.934 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 327s app_test.go:1117: 16:18:03.014 - 65: DEBUG: new connection from 127.0.0.1:56492 327s app_test.go:1117: 16:18:03.017 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 327s app_test.go:1117: 16:18:03.085 - 65: DEBUG: new connection from 127.0.0.1:56504 327s app_test.go:1117: 16:18:03.088 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 327s app_test.go:1117: 16:18:03.090 - 65: DEBUG: new connection from 127.0.0.1:56514 327s app_test.go:1117: 16:18:03.093 - 66: DEBUG: new connection from 127.0.0.1:49106 327s app_test.go:1117: 16:18:03.097 - 68: DEBUG: new connection from 127.0.0.1:35236 327s app_test.go:1117: 16:18:03.100 - 69: DEBUG: new connection from 127.0.0.1:51672 327s app_test.go:1117: 16:18:03.103 - 70: DEBUG: new connection from 127.0.0.1:39990 330s app_test.go:1117: 16:18:03.700 - 65: DEBUG: new connection from 127.0.0.1:56530 330s app_test.go:1117: 16:18:03.702 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 330s app_test.go:1117: 16:18:03.868 - 65: DEBUG: new connection from 127.0.0.1:56540 330s app_test.go:1117: 16:18:03.871 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 330s app_test.go:1117: 16:18:03.937 - 65: DEBUG: new connection from 127.0.0.1:56552 330s app_test.go:1117: 16:18:03.939 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 331s app_test.go:1117: 16:18:03.019 - 65: DEBUG: new connection from 127.0.0.1:56560 331s app_test.go:1117: 16:18:03.022 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 331s app_test.go:1117: 16:18:03.107 - 65: DEBUG: new connection from 127.0.0.1:56574 331s app_test.go:1117: 16:18:03.110 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 331s app_test.go:1117: 16:18:03.113 - 65: DEBUG: new connection from 127.0.0.1:56582 331s app_test.go:1117: 16:18:03.116 - 66: DEBUG: new connection from 127.0.0.1:49118 331s app_test.go:1117: 16:18:03.119 - 68: DEBUG: new connection from 127.0.0.1:35238 331s app_test.go:1117: 16:18:03.121 - 69: DEBUG: new connection from 127.0.0.1:51684 331s app_test.go:1117: 16:18:03.124 - 70: DEBUG: new connection from 127.0.0.1:39994 334s app_test.go:1117: 16:18:03.705 - 65: DEBUG: new connection from 127.0.0.1:53486 334s app_test.go:1117: 16:18:03.710 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 334s app_test.go:1117: 16:18:03.874 - 65: DEBUG: new connection from 127.0.0.1:53490 334s app_test.go:1117: 16:18:03.876 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 334s app_test.go:1117: 16:18:03.942 - 65: DEBUG: new connection from 127.0.0.1:53498 334s app_test.go:1117: 16:18:03.944 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 335s app_test.go:1117: 16:18:03.025 - 65: DEBUG: new connection from 127.0.0.1:53502 335s app_test.go:1117: 16:18:03.027 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 335s app_test.go:1117: 16:18:03.127 - 65: DEBUG: new connection from 127.0.0.1:53518 335s app_test.go:1117: 16:18:03.129 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 335s app_test.go:1117: 16:18:03.130 - 65: DEBUG: new connection from 127.0.0.1:53526 335s app_test.go:1117: 16:18:03.132 - 66: DEBUG: new connection from 127.0.0.1:32856 335s app_test.go:1117: 16:18:03.134 - 68: DEBUG: new connection from 127.0.0.1:35484 335s app_test.go:1117: 16:18:03.136 - 69: DEBUG: new connection from 127.0.0.1:35132 335s app_test.go:1117: 16:18:03.140 - 70: DEBUG: new connection from 127.0.0.1:54818 337s app_test.go:1117: 16:18:03.067 - 65: DEBUG: new connection from 127.0.0.1:53542 337s app_test.go:1117: 16:18:03.069 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 337s --- PASS: TestRolesAdjustment_ReplaceVoterHonorWeight (18.79s) 337s === RUN TestRolesAdjustment_CantReplaceVoter 337s app_test.go:1117: 16:18:03.380 - 71: DEBUG: new connection from 127.0.0.1:53552 337s app_test.go:1117: 16:18:03.383 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 337s app_test.go:1117: 16:18:03.385 - 71: DEBUG: new connection from 127.0.0.1:53554 337s app_test.go:1117: 16:18:03.447 - 71: DEBUG: new connection from 127.0.0.1:53570 337s app_test.go:1117: 16:18:03.449 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 337s app_test.go:1117: 16:18:03.452 - 71: DEBUG: new connection from 127.0.0.1:53578 337s app_test.go:1117: 16:18:03.455 - 72: DEBUG: new connection from 127.0.0.1:32860 337s app_test.go:1117: 16:18:03.518 - 71: DEBUG: new connection from 127.0.0.1:53582 337s app_test.go:1117: 16:18:03.520 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 337s app_test.go:1117: 16:18:03.522 - 71: DEBUG: new connection from 127.0.0.1:53586 337s app_test.go:1117: 16:18:03.525 - 72: DEBUG: new connection from 127.0.0.1:32866 337s app_test.go:1117: 16:18:03.528 - 73: DEBUG: new connection from 127.0.0.1:51104 337s app_test.go:1117: 16:18:03.531 - 73: DEBUG: new connection from 127.0.0.1:51120 337s app_test.go:1117: 16:18:03.534 - 71: DEBUG: new connection from 127.0.0.1:53592 337s app_test.go:1117: 16:18:03.539 - 72: DEBUG: new connection from 127.0.0.1:32872 337s app_test.go:1117: 16:18:03.542 - 71: DEBUG: new connection from 127.0.0.1:53598 337s app_test.go:1117: 16:18:03.597 - 71: DEBUG: new connection from 127.0.0.1:53600 337s app_test.go:1117: 16:18:03.600 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 337s app_test.go:1117: 16:18:03.601 - 71: DEBUG: new connection from 127.0.0.1:53602 337s app_test.go:1117: 16:18:03.605 - 72: DEBUG: new connection from 127.0.0.1:32888 337s app_test.go:1117: 16:18:03.608 - 73: DEBUG: new connection from 127.0.0.1:51128 337s app_test.go:1117: 16:18:03.611 - 74: DEBUG: new connection from 127.0.0.1:35498 337s app_test.go:1117: 16:18:03.614 - 74: DEBUG: new connection from 127.0.0.1:35508 341s app_test.go:1117: 16:18:03.390 - 71: DEBUG: new connection from 127.0.0.1:53614 341s app_test.go:1117: 16:18:03.393 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 341s app_test.go:1117: 16:18:03.395 - 71: DEBUG: new connection from 127.0.0.1:53630 341s app_test.go:1117: 16:18:03.397 - 72: DEBUG: new connection from 127.0.0.1:32896 341s app_test.go:1117: 16:18:03.458 - 71: DEBUG: new connection from 127.0.0.1:53640 341s app_test.go:1117: 16:18:03.459 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 345s app_test.go:1117: 16:18:03.400 - 71: DEBUG: new connection from 127.0.0.1:59478 345s app_test.go:1117: 16:18:03.402 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 345s app_test.go:1117: 16:18:03.404 - 71: DEBUG: new connection from 127.0.0.1:59482 345s app_test.go:1117: 16:18:03.406 - 72: DEBUG: new connection from 127.0.0.1:41668 345s app_test.go:1117: 16:18:03.461 - 71: DEBUG: new connection from 127.0.0.1:59494 345s app_test.go:1117: 16:18:03.463 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1117: 16:18:03.410 - 71: DEBUG: new connection from 127.0.0.1:59504 349s app_test.go:1117: 16:18:03.414 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1117: 16:18:03.416 - 71: DEBUG: new connection from 127.0.0.1:59510 349s app_test.go:1117: 16:18:03.419 - 72: DEBUG: new connection from 127.0.0.1:41678 349s app_test.go:1117: 16:18:03.465 - 71: DEBUG: new connection from 127.0.0.1:59524 349s app_test.go:1117: 16:18:03.467 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1117: 16:18:03.686 - 71: DEBUG: new connection from 127.0.0.1:59532 349s app_test.go:1117: 16:18:03.689 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s --- PASS: TestRolesAdjustment_CantReplaceVoter (12.49s) 349s === RUN TestRolesAdjustment_ReplaceStandBy 349s app_test.go:1117: 16:18:03.878 - 75: DEBUG: new connection from 127.0.0.1:59536 349s app_test.go:1117: 16:18:03.881 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1117: 16:18:03.882 - 75: DEBUG: new connection from 127.0.0.1:59548 349s app_test.go:1117: 16:18:03.947 - 75: DEBUG: new connection from 127.0.0.1:59556 349s app_test.go:1117: 16:18:03.949 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 349s app_test.go:1117: 16:18:03.951 - 75: DEBUG: new connection from 127.0.0.1:59568 349s app_test.go:1117: 16:18:03.953 - 76: DEBUG: new connection from 127.0.0.1:41684 350s app_test.go:1117: 16:18:03.017 - 75: DEBUG: new connection from 127.0.0.1:59580 350s app_test.go:1117: 16:18:03.019 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 350s app_test.go:1117: 16:18:03.020 - 75: DEBUG: new connection from 127.0.0.1:59594 350s app_test.go:1117: 16:18:03.023 - 76: DEBUG: new connection from 127.0.0.1:41696 350s app_test.go:1117: 16:18:03.025 - 77: DEBUG: new connection from 127.0.0.1:53220 350s app_test.go:1117: 16:18:03.027 - 77: DEBUG: new connection from 127.0.0.1:53230 350s app_test.go:1117: 16:18:03.032 - 75: DEBUG: new connection from 127.0.0.1:59610 350s app_test.go:1117: 16:18:03.035 - 76: DEBUG: new connection from 127.0.0.1:41710 350s app_test.go:1117: 16:18:03.039 - 75: DEBUG: new connection from 127.0.0.1:59614 350s app_test.go:1117: 16:18:03.097 - 75: DEBUG: new connection from 127.0.0.1:59624 350s app_test.go:1117: 16:18:03.099 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 350s app_test.go:1117: 16:18:03.101 - 75: DEBUG: new connection from 127.0.0.1:59626 350s app_test.go:1117: 16:18:03.103 - 76: DEBUG: new connection from 127.0.0.1:41722 350s app_test.go:1117: 16:18:03.105 - 77: DEBUG: new connection from 127.0.0.1:53234 350s app_test.go:1117: 16:18:03.107 - 78: DEBUG: new connection from 127.0.0.1:45152 350s app_test.go:1117: 16:18:03.109 - 78: DEBUG: new connection from 127.0.0.1:45162 350s app_test.go:1117: 16:18:03.113 - 75: DEBUG: new connection from 127.0.0.1:59642 350s app_test.go:1117: 16:18:03.177 - 75: DEBUG: new connection from 127.0.0.1:59652 350s app_test.go:1117: 16:18:03.180 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 350s app_test.go:1117: 16:18:03.181 - 75: DEBUG: new connection from 127.0.0.1:59658 350s app_test.go:1117: 16:18:03.183 - 76: DEBUG: new connection from 127.0.0.1:41738 350s app_test.go:1117: 16:18:03.186 - 77: DEBUG: new connection from 127.0.0.1:53242 350s app_test.go:1117: 16:18:03.188 - 78: DEBUG: new connection from 127.0.0.1:45166 350s app_test.go:1117: 16:18:03.190 - 79: DEBUG: new connection from 127.0.0.1:38210 350s app_test.go:1117: 16:18:03.194 - 79: DEBUG: new connection from 127.0.0.1:38220 350s app_test.go:1117: 16:18:03.199 - 75: DEBUG: new connection from 127.0.0.1:59660 350s app_test.go:1117: 16:18:03.258 - 75: DEBUG: new connection from 127.0.0.1:59664 350s app_test.go:1117: 16:18:03.260 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 350s app_test.go:1117: 16:18:03.262 - 75: DEBUG: new connection from 127.0.0.1:59670 350s app_test.go:1117: 16:18:03.264 - 76: DEBUG: new connection from 127.0.0.1:41744 350s app_test.go:1117: 16:18:03.266 - 77: DEBUG: new connection from 127.0.0.1:53246 350s app_test.go:1117: 16:18:03.269 - 78: DEBUG: new connection from 127.0.0.1:45176 350s app_test.go:1117: 16:18:03.272 - 79: DEBUG: new connection from 127.0.0.1:38234 350s app_test.go:1117: 16:18:03.274 - 80: DEBUG: new connection from 127.0.0.1:46274 350s app_test.go:1117: 16:18:03.278 - 80: DEBUG: new connection from 127.0.0.1:46278 350s app_test.go:1117: 16:18:03.284 - 75: DEBUG: new connection from 127.0.0.1:59674 350s app_test.go:1117: 16:18:03.328 - 75: DEBUG: new connection from 127.0.0.1:59684 350s app_test.go:1117: 16:18:03.330 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected 350s app_test.go:1117: 16:18:03.333 - 75: DEBUG: new connection from 127.0.0.1:59696 350s app_test.go:1117: 16:18:03.335 - 76: DEBUG: new connection from 127.0.0.1:41754 350s app_test.go:1117: 16:18:03.337 - 77: DEBUG: new connection from 127.0.0.1:53254 350s app_test.go:1117: 16:18:03.339 - 78: DEBUG: new connection from 127.0.0.1:45192 350s app_test.go:1117: 16:18:03.342 - 79: DEBUG: new connection from 127.0.0.1:38248 350s app_test.go:1117: 16:18:03.344 - 80: DEBUG: new connection from 127.0.0.1:46292 350s app_test.go:1117: 16:18:03.347 - 81: DEBUG: new connection from 127.0.0.1:46680 354s app_test.go:1117: 16:18:03.886 - 75: DEBUG: new connection from 127.0.0.1:56340 354s app_test.go:1117: 16:18:03.889 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected 354s app_test.go:1117: 16:18:03.891 - 75: DEBUG: new connection from 127.0.0.1:56342 354s app_test.go:1117: 16:18:03.893 - 76: DEBUG: new connection from 127.0.0.1:43464 355s app_test.go:1117: 16:18:03.896 - 77: DEBUG: new connection from 127.0.0.1:57062 355s app_test.go:1117: 16:18:03.898 - 78: DEBUG: new connection from 127.0.0.1:40976 355s app_test.go:1117: 16:18:03.900 - 80: DEBUG: new connection from 127.0.0.1:46274 355s app_test.go:1117: 16:18:03.902 - 81: DEBUG: new connection from 127.0.0.1:57868 355s app_test.go:1117: 16:18:03.906 - 81: DEBUG: new connection from 127.0.0.1:57880 355s app_test.go:1117: 16:18:03.910 - 75: DEBUG: new connection from 127.0.0.1:56350 355s app_test.go:1117: 16:18:03.956 - 75: DEBUG: new connection from 127.0.0.1:56366 355s app_test.go:1117: 16:18:03.958 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 355s app_test.go:1117: 16:18:03.043 - 75: DEBUG: new connection from 127.0.0.1:56376 355s app_test.go:1117: 16:18:03.047 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 355s app_test.go:1117: 16:18:03.112 - 75: DEBUG: new connection from 127.0.0.1:56378 355s app_test.go:1117: 16:18:03.115 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 355s app_test.go:1117: 16:18:03.278 - 75: DEBUG: new connection from 127.0.0.1:56386 355s app_test.go:1117: 16:18:03.280 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 355s app_test.go:1117: 16:18:03.335 - 75: DEBUG: new connection from 127.0.0.1:56392 355s app_test.go:1117: 16:18:03.337 - 76: DEBUG: new connection from 127.0.0.1:43470 355s app_test.go:1117: 16:18:03.340 - 77: DEBUG: new connection from 127.0.0.1:57076 355s app_test.go:1117: 16:18:03.342 - 78: DEBUG: new connection from 127.0.0.1:40984 355s app_test.go:1117: 16:18:03.345 - 80: DEBUG: new connection from 127.0.0.1:46290 355s app_test.go:1117: 16:18:03.348 - 81: DEBUG: new connection from 127.0.0.1:57884 355s app_test.go:1117: 16:18:03.349 - 75: DEBUG: new connection from 127.0.0.1:56404 355s app_test.go:1117: 16:18:03.356 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected 355s app_test.go:1117: 16:18:03.358 - 75: DEBUG: new connection from 127.0.0.1:56408 355s app_test.go:1117: 16:18:03.361 - 76: DEBUG: new connection from 127.0.0.1:43480 355s app_test.go:1117: 16:18:03.363 - 77: DEBUG: new connection from 127.0.0.1:57092 355s app_test.go:1117: 16:18:03.365 - 78: DEBUG: new connection from 127.0.0.1:40988 355s app_test.go:1117: 16:18:03.368 - 80: DEBUG: new connection from 127.0.0.1:46304 355s app_test.go:1117: 16:18:03.370 - 81: DEBUG: new connection from 127.0.0.1:57886 359s app_test.go:1117: 16:18:03.961 - 75: DEBUG: new connection from 127.0.0.1:56422 359s app_test.go:1117: 16:18:03.964 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 360s app_test.go:1117: 16:18:03.050 - 75: DEBUG: new connection from 127.0.0.1:56426 360s app_test.go:1117: 16:18:03.052 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 360s app_test.go:1117: 16:18:03.117 - 75: DEBUG: new connection from 127.0.0.1:56432 360s app_test.go:1117: 16:18:03.119 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 360s app_test.go:1117: 16:18:03.283 - 75: DEBUG: new connection from 127.0.0.1:56448 360s app_test.go:1117: 16:18:03.287 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 360s app_test.go:1117: 16:18:03.361 - 75: DEBUG: new connection from 127.0.0.1:56460 360s app_test.go:1117: 16:18:03.363 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected 360s app_test.go:1117: 16:18:03.373 - 75: DEBUG: new connection from 127.0.0.1:56468 360s app_test.go:1117: 16:18:03.377 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected 360s app_test.go:1117: 16:18:03.380 - 75: DEBUG: new connection from 127.0.0.1:56470 360s app_test.go:1117: 16:18:03.382 - 76: DEBUG: new connection from 127.0.0.1:43496 360s app_test.go:1117: 16:18:03.385 - 77: DEBUG: new connection from 127.0.0.1:57094 360s app_test.go:1117: 16:18:03.388 - 78: DEBUG: new connection from 127.0.0.1:41000 360s app_test.go:1117: 16:18:03.391 - 80: DEBUG: new connection from 127.0.0.1:46310 360s app_test.go:1117: 16:18:03.393 - 81: DEBUG: new connection from 127.0.0.1:57902 364s app_test.go:1117: 16:18:03.966 - 75: DEBUG: new connection from 127.0.0.1:38360 364s app_test.go:1117: 16:18:03.971 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 365s app_test.go:1117: 16:18:03.054 - 75: DEBUG: new connection from 127.0.0.1:38370 365s app_test.go:1117: 16:18:03.056 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 365s app_test.go:1117: 16:18:03.121 - 75: DEBUG: new connection from 127.0.0.1:38378 365s app_test.go:1117: 16:18:03.123 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 365s app_test.go:1117: 16:18:03.290 - 75: DEBUG: new connection from 127.0.0.1:38382 365s app_test.go:1117: 16:18:03.293 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 365s app_test.go:1117: 16:18:03.364 - 75: DEBUG: new connection from 127.0.0.1:38392 365s app_test.go:1117: 16:18:03.366 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected 365s app_test.go:1117: 16:18:03.397 - 75: DEBUG: new connection from 127.0.0.1:38398 365s app_test.go:1117: 16:18:03.400 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected 365s app_test.go:1117: 16:18:03.402 - 75: DEBUG: new connection from 127.0.0.1:38414 365s app_test.go:1117: 16:18:03.405 - 76: DEBUG: new connection from 127.0.0.1:38718 365s app_test.go:1117: 16:18:03.407 - 77: DEBUG: new connection from 127.0.0.1:33318 365s app_test.go:1117: 16:18:03.409 - 78: DEBUG: new connection from 127.0.0.1:50752 365s app_test.go:1117: 16:18:03.411 - 80: DEBUG: new connection from 127.0.0.1:33406 365s app_test.go:1117: 16:18:03.413 - 81: DEBUG: new connection from 127.0.0.1:42102 370s app_test.go:1117: 16:18:03.976 - 75: DEBUG: new connection from 127.0.0.1:38420 370s app_test.go:1117: 16:18:03.978 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 370s app_test.go:1117: 16:18:03.060 - 75: DEBUG: new connection from 127.0.0.1:38430 370s app_test.go:1117: 16:18:03.062 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 370s app_test.go:1117: 16:18:03.125 - 75: DEBUG: new connection from 127.0.0.1:38438 370s app_test.go:1117: 16:18:03.127 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 370s app_test.go:1117: 16:18:03.295 - 75: DEBUG: new connection from 127.0.0.1:38440 370s app_test.go:1117: 16:18:03.297 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 370s app_test.go:1117: 16:18:03.369 - 75: DEBUG: new connection from 127.0.0.1:38448 370s app_test.go:1117: 16:18:03.371 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected 370s app_test.go:1117: 16:18:03.416 - 75: DEBUG: new connection from 127.0.0.1:38458 370s app_test.go:1117: 16:18:03.416 - 75: DEBUG: new connection from 127.0.0.1:38464 370s app_test.go:1117: 16:18:03.420 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected 370s app_test.go:1117: 16:18:03.420 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected 370s app_test.go:1117: 16:18:03.422 - 75: DEBUG: new connection from 127.0.0.1:38466 370s app_test.go:1117: 16:18:03.425 - 76: DEBUG: new connection from 127.0.0.1:38724 370s app_test.go:1117: 16:18:03.427 - 77: DEBUG: new connection from 127.0.0.1:33328 370s app_test.go:1117: 16:18:03.429 - 78: DEBUG: new connection from 127.0.0.1:50760 370s app_test.go:1117: 16:18:03.432 - 80: DEBUG: new connection from 127.0.0.1:33408 370s --- PASS: TestRolesAdjustment_ReplaceStandBy (20.90s) 370s === RUN TestRolesAdjustment_ReplaceStandByHonorFailureDomains 370s app_test.go:1117: 16:18:03.788 - 82: DEBUG: new connection from 127.0.0.1:38476 370s app_test.go:1117: 16:18:03.792 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected 370s app_test.go:1117: 16:18:03.794 - 82: DEBUG: new connection from 127.0.0.1:38478 370s app_test.go:1117: 16:18:03.897 - 82: DEBUG: new connection from 127.0.0.1:38488 370s app_test.go:1117: 16:18:03.906 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 370s app_test.go:1117: 16:18:03.909 - 82: DEBUG: new connection from 127.0.0.1:38492 370s app_test.go:1117: 16:18:03.911 - 83: DEBUG: new connection from 127.0.0.1:38738 371s app_test.go:1117: 16:18:03.977 - 82: DEBUG: new connection from 127.0.0.1:38500 371s app_test.go:1117: 16:18:03.980 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 371s app_test.go:1117: 16:18:03.982 - 82: DEBUG: new connection from 127.0.0.1:38504 371s app_test.go:1117: 16:18:03.984 - 83: DEBUG: new connection from 127.0.0.1:38744 371s app_test.go:1117: 16:18:03.987 - 84: DEBUG: new connection from 127.0.0.1:33332 371s app_test.go:1117: 16:18:03.989 - 84: DEBUG: new connection from 127.0.0.1:33334 371s app_test.go:1117: 16:18:03.994 - 82: DEBUG: new connection from 127.0.0.1:38512 371s app_test.go:1117: 16:18:03.998 - 83: DEBUG: new connection from 127.0.0.1:38756 371s app_test.go:1117: 16:18:03.002 - 82: DEBUG: new connection from 127.0.0.1:38514 371s app_test.go:1117: 16:18:03.067 - 82: DEBUG: new connection from 127.0.0.1:38530 371s app_test.go:1117: 16:18:03.070 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 371s app_test.go:1117: 16:18:03.072 - 82: DEBUG: new connection from 127.0.0.1:38538 371s app_test.go:1117: 16:18:03.075 - 83: DEBUG: new connection from 127.0.0.1:38758 371s app_test.go:1117: 16:18:03.078 - 84: DEBUG: new connection from 127.0.0.1:33336 371s app_test.go:1117: 16:18:03.080 - 85: DEBUG: new connection from 127.0.0.1:50774 371s app_test.go:1117: 16:18:03.085 - 85: DEBUG: new connection from 127.0.0.1:50780 371s app_test.go:1117: 16:18:03.092 - 82: DEBUG: new connection from 127.0.0.1:38548 371s app_test.go:1117: 16:18:03.157 - 82: DEBUG: new connection from 127.0.0.1:38564 371s app_test.go:1117: 16:18:03.160 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 371s app_test.go:1117: 16:18:03.162 - 82: DEBUG: new connection from 127.0.0.1:38572 371s app_test.go:1117: 16:18:03.166 - 83: DEBUG: new connection from 127.0.0.1:38770 371s app_test.go:1117: 16:18:03.169 - 84: DEBUG: new connection from 127.0.0.1:33348 371s app_test.go:1117: 16:18:03.172 - 85: DEBUG: new connection from 127.0.0.1:50792 371s app_test.go:1117: 16:18:03.175 - 86: DEBUG: new connection from 127.0.0.1:51880 371s app_test.go:1117: 16:18:03.178 - 86: DEBUG: new connection from 127.0.0.1:51882 371s app_test.go:1117: 16:18:03.184 - 82: DEBUG: new connection from 127.0.0.1:38574 371s app_test.go:1117: 16:18:03.227 - 82: DEBUG: new connection from 127.0.0.1:38580 371s app_test.go:1117: 16:18:03.231 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected 371s app_test.go:1117: 16:18:03.236 - 82: DEBUG: new connection from 127.0.0.1:38596 371s app_test.go:1117: 16:18:03.238 - 83: DEBUG: new connection from 127.0.0.1:38778 371s app_test.go:1117: 16:18:03.241 - 84: DEBUG: new connection from 127.0.0.1:33362 371s app_test.go:1117: 16:18:03.243 - 85: DEBUG: new connection from 127.0.0.1:50802 371s app_test.go:1117: 16:18:03.245 - 86: DEBUG: new connection from 127.0.0.1:51890 371s app_test.go:1117: 16:18:03.248 - 87: DEBUG: new connection from 127.0.0.1:33410 371s app_test.go:1117: 16:18:03.251 - 87: DEBUG: new connection from 127.0.0.1:33416 371s app_test.go:1117: 16:18:03.258 - 82: DEBUG: new connection from 127.0.0.1:38608 371s app_test.go:1117: 16:18:03.317 - 82: DEBUG: new connection from 127.0.0.1:38616 371s app_test.go:1117: 16:18:03.319 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 371s app_test.go:1117: 16:18:03.322 - 82: DEBUG: new connection from 127.0.0.1:38630 371s app_test.go:1117: 16:18:03.324 - 83: DEBUG: new connection from 127.0.0.1:38786 371s app_test.go:1117: 16:18:03.326 - 84: DEBUG: new connection from 127.0.0.1:33372 371s app_test.go:1117: 16:18:03.329 - 85: DEBUG: new connection from 127.0.0.1:50814 371s app_test.go:1117: 16:18:03.332 - 86: DEBUG: new connection from 127.0.0.1:51892 371s app_test.go:1117: 16:18:03.335 - 87: DEBUG: new connection from 127.0.0.1:33420 371s app_test.go:1117: 16:18:03.338 - 88: DEBUG: new connection from 127.0.0.1:42116 371s app_test.go:1117: 16:18:03.397 - 82: DEBUG: new connection from 127.0.0.1:38638 371s app_test.go:1117: 16:18:03.400 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 371s app_test.go:1117: 16:18:03.402 - 82: DEBUG: new connection from 127.0.0.1:38646 371s app_test.go:1117: 16:18:03.405 - 83: DEBUG: new connection from 127.0.0.1:38792 371s app_test.go:1117: 16:18:03.407 - 84: DEBUG: new connection from 127.0.0.1:33374 371s app_test.go:1117: 16:18:03.410 - 85: DEBUG: new connection from 127.0.0.1:50816 371s app_test.go:1117: 16:18:03.412 - 86: DEBUG: new connection from 127.0.0.1:51904 371s app_test.go:1117: 16:18:03.414 - 87: DEBUG: new connection from 127.0.0.1:33422 371s app_test.go:1117: 16:18:03.416 - 88: DEBUG: new connection from 127.0.0.1:42120 371s app_test.go:1117: 16:18:03.419 - 89: DEBUG: new connection from 127.0.0.1:32824 371s app_test.go:1117: 16:18:03.478 - 82: DEBUG: new connection from 127.0.0.1:38662 371s app_test.go:1117: 16:18:03.480 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 371s app_test.go:1117: 16:18:03.483 - 82: DEBUG: new connection from 127.0.0.1:38672 371s app_test.go:1117: 16:18:03.486 - 83: DEBUG: new connection from 127.0.0.1:38798 371s app_test.go:1117: 16:18:03.488 - 84: DEBUG: new connection from 127.0.0.1:33380 371s app_test.go:1117: 16:18:03.490 - 85: DEBUG: new connection from 127.0.0.1:50826 371s app_test.go:1117: 16:18:03.493 - 86: DEBUG: new connection from 127.0.0.1:51908 371s app_test.go:1117: 16:18:03.496 - 87: DEBUG: new connection from 127.0.0.1:33430 371s app_test.go:1117: 16:18:03.498 - 88: DEBUG: new connection from 127.0.0.1:42122 371s app_test.go:1117: 16:18:03.500 - 89: DEBUG: new connection from 127.0.0.1:32838 371s app_test.go:1117: 16:18:03.503 - 90: DEBUG: new connection from 127.0.0.1:45346 375s app_test.go:1117: 16:18:03.798 - 82: DEBUG: new connection from 127.0.0.1:57288 375s app_test.go:1117: 16:18:03.800 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected 375s app_test.go:1117: 16:18:03.802 - 82: DEBUG: new connection from 127.0.0.1:57294 375s app_test.go:1117: 16:18:03.804 - 83: DEBUG: new connection from 127.0.0.1:59940 375s app_test.go:1117: 16:18:03.806 - 84: DEBUG: new connection from 127.0.0.1:51230 375s app_test.go:1117: 16:18:03.808 - 85: DEBUG: new connection from 127.0.0.1:40680 375s app_test.go:1117: 16:18:03.811 - 87: DEBUG: new connection from 127.0.0.1:48846 375s app_test.go:1117: 16:18:03.813 - 88: DEBUG: new connection from 127.0.0.1:56106 375s app_test.go:1117: 16:18:03.815 - 89: DEBUG: new connection from 127.0.0.1:56472 375s app_test.go:1117: 16:18:03.817 - 90: DEBUG: new connection from 127.0.0.1:41446 375s app_test.go:1117: 16:18:03.819 - 89: DEBUG: new connection from 127.0.0.1:56480 375s app_test.go:1117: 16:18:03.822 - 82: DEBUG: new connection from 127.0.0.1:57296 375s app_test.go:1117: 16:18:03.914 - 82: DEBUG: new connection from 127.0.0.1:57300 375s app_test.go:1117: 16:18:03.918 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 376s app_test.go:1117: 16:18:03.007 - 82: DEBUG: new connection from 127.0.0.1:57304 376s app_test.go:1117: 16:18:03.009 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 376s app_test.go:1117: 16:18:03.090 - 82: DEBUG: new connection from 127.0.0.1:57312 376s app_test.go:1117: 16:18:03.092 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 376s app_test.go:1117: 16:18:03.250 - 82: DEBUG: new connection from 127.0.0.1:57316 376s app_test.go:1117: 16:18:03.252 - 83: DEBUG: new connection from 127.0.0.1:59954 376s app_test.go:1117: 16:18:03.253 - 82: DEBUG: new connection from 127.0.0.1:57332 376s app_test.go:1117: 16:18:03.256 - 84: DEBUG: new connection from 127.0.0.1:51232 376s app_test.go:1117: 16:18:03.258 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected 376s app_test.go:1117: 16:18:03.258 - 85: DEBUG: new connection from 127.0.0.1:40684 376s app_test.go:1117: 16:18:03.261 - 87: DEBUG: new connection from 127.0.0.1:48854 376s app_test.go:1117: 16:18:03.263 - 88: DEBUG: new connection from 127.0.0.1:56116 376s app_test.go:1117: 16:18:03.265 - 89: DEBUG: new connection from 127.0.0.1:56482 376s app_test.go:1117: 16:18:03.268 - 90: DEBUG: new connection from 127.0.0.1:41460 376s app_test.go:1117: 16:18:03.270 - 82: DEBUG: new connection from 127.0.0.1:57340 376s app_test.go:1117: 16:18:03.272 - 83: DEBUG: new connection from 127.0.0.1:59960 376s app_test.go:1117: 16:18:03.275 - 84: DEBUG: new connection from 127.0.0.1:51236 376s app_test.go:1117: 16:18:03.277 - 85: DEBUG: new connection from 127.0.0.1:40700 376s app_test.go:1117: 16:18:03.279 - 87: DEBUG: new connection from 127.0.0.1:48856 376s app_test.go:1117: 16:18:03.282 - 88: DEBUG: new connection from 127.0.0.1:56122 376s app_test.go:1117: 16:18:03.285 - 89: DEBUG: new connection from 127.0.0.1:56484 376s app_test.go:1117: 16:18:03.287 - 90: DEBUG: new connection from 127.0.0.1:41476 376s app_test.go:1117: 16:18:03.341 - 82: DEBUG: new connection from 127.0.0.1:57342 376s app_test.go:1117: 16:18:03.343 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 376s app_test.go:1117: 16:18:03.421 - 82: DEBUG: new connection from 127.0.0.1:57356 376s app_test.go:1117: 16:18:03.423 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 376s app_test.go:1117: 16:18:03.506 - 82: DEBUG: new connection from 127.0.0.1:57360 376s app_test.go:1117: 16:18:03.508 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 380s app_test.go:1117: 16:18:03.921 - 82: DEBUG: new connection from 127.0.0.1:57362 380s app_test.go:1117: 16:18:03.923 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 381s app_test.go:1117: 16:19:03.011 - 82: DEBUG: new connection from 127.0.0.1:57374 381s app_test.go:1117: 16:19:03.013 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 381s app_test.go:1117: 16:19:03.093 - 82: DEBUG: new connection from 127.0.0.1:57376 381s app_test.go:1117: 16:19:03.096 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 381s app_test.go:1117: 16:19:03.264 - 82: DEBUG: new connection from 127.0.0.1:57382 381s app_test.go:1117: 16:19:03.267 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected 381s app_test.go:1117: 16:19:03.291 - 82: DEBUG: new connection from 127.0.0.1:57396 381s app_test.go:1117: 16:19:03.295 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected 381s app_test.go:1117: 16:19:03.297 - 82: DEBUG: new connection from 127.0.0.1:57398 381s app_test.go:1117: 16:19:03.300 - 83: DEBUG: new connection from 127.0.0.1:59964 381s app_test.go:1117: 16:19:03.302 - 84: DEBUG: new connection from 127.0.0.1:51244 381s app_test.go:1117: 16:19:03.305 - 85: DEBUG: new connection from 127.0.0.1:40714 381s app_test.go:1117: 16:19:03.308 - 87: DEBUG: new connection from 127.0.0.1:48862 381s app_test.go:1117: 16:19:03.312 - 88: DEBUG: new connection from 127.0.0.1:56128 381s app_test.go:1117: 16:19:03.316 - 89: DEBUG: new connection from 127.0.0.1:56498 381s app_test.go:1117: 16:19:03.320 - 90: DEBUG: new connection from 127.0.0.1:41490 381s app_test.go:1117: 16:19:03.344 - 82: DEBUG: new connection from 127.0.0.1:57400 381s app_test.go:1117: 16:19:03.346 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 381s app_test.go:1117: 16:19:03.426 - 82: DEBUG: new connection from 127.0.0.1:57412 381s app_test.go:1117: 16:19:03.428 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 381s app_test.go:1117: 16:19:03.510 - 82: DEBUG: new connection from 127.0.0.1:57428 381s app_test.go:1117: 16:19:03.513 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 385s app_test.go:1117: 16:19:03.926 - 82: DEBUG: new connection from 127.0.0.1:38904 385s app_test.go:1117: 16:19:03.929 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 386s app_test.go:1117: 16:19:03.015 - 82: DEBUG: new connection from 127.0.0.1:38912 386s app_test.go:1117: 16:19:03.018 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 386s app_test.go:1117: 16:19:03.099 - 82: DEBUG: new connection from 127.0.0.1:38916 386s app_test.go:1117: 16:19:03.101 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 386s app_test.go:1117: 16:19:03.269 - 82: DEBUG: new connection from 127.0.0.1:38918 386s app_test.go:1117: 16:19:03.271 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected 386s app_test.go:1117: 16:19:03.322 - 82: DEBUG: new connection from 127.0.0.1:38934 386s app_test.go:1117: 16:19:03.324 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected 386s app_test.go:1117: 16:19:03.326 - 82: DEBUG: new connection from 127.0.0.1:38950 386s app_test.go:1117: 16:19:03.329 - 83: DEBUG: new connection from 127.0.0.1:51244 386s app_test.go:1117: 16:19:03.331 - 84: DEBUG: new connection from 127.0.0.1:42104 386s app_test.go:1117: 16:19:03.335 - 85: DEBUG: new connection from 127.0.0.1:47470 386s app_test.go:1117: 16:19:03.339 - 87: DEBUG: new connection from 127.0.0.1:53908 386s app_test.go:1117: 16:19:03.341 - 88: DEBUG: new connection from 127.0.0.1:51416 386s app_test.go:1117: 16:19:03.343 - 89: DEBUG: new connection from 127.0.0.1:50654 386s app_test.go:1117: 16:19:03.345 - 90: DEBUG: new connection from 127.0.0.1:59654 386s app_test.go:1117: 16:19:03.348 - 82: DEBUG: new connection from 127.0.0.1:38964 386s app_test.go:1117: 16:19:03.350 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 386s app_test.go:1117: 16:19:03.431 - 82: DEBUG: new connection from 127.0.0.1:38970 386s app_test.go:1117: 16:19:03.433 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 386s app_test.go:1117: 16:19:03.516 - 82: DEBUG: new connection from 127.0.0.1:38982 386s app_test.go:1117: 16:19:03.519 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 390s app_test.go:1117: 16:19:03.931 - 82: DEBUG: new connection from 127.0.0.1:38986 390s app_test.go:1117: 16:19:03.934 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 391s app_test.go:1117: 16:19:03.021 - 82: DEBUG: new connection from 127.0.0.1:38988 391s app_test.go:1117: 16:19:03.023 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 391s app_test.go:1117: 16:19:03.104 - 82: DEBUG: new connection from 127.0.0.1:39004 391s app_test.go:1117: 16:19:03.110 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 391s app_test.go:1117: 16:19:03.274 - 82: DEBUG: new connection from 127.0.0.1:39006 391s app_test.go:1117: 16:19:03.277 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected 391s app_test.go:1117: 16:19:03.351 - 82: DEBUG: new connection from 127.0.0.1:39022 391s app_test.go:1117: 16:19:03.352 - 82: DEBUG: new connection from 127.0.0.1:39026 391s app_test.go:1117: 16:19:03.354 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected 391s app_test.go:1117: 16:19:03.356 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 391s app_test.go:1117: 16:19:03.359 - 82: DEBUG: new connection from 127.0.0.1:39042 391s app_test.go:1117: 16:19:03.363 - 83: DEBUG: new connection from 127.0.0.1:51256 391s app_test.go:1117: 16:19:03.368 - 84: DEBUG: new connection from 127.0.0.1:42108 391s app_test.go:1117: 16:19:03.371 - 85: DEBUG: new connection from 127.0.0.1:47472 391s app_test.go:1117: 16:19:03.374 - 87: DEBUG: new connection from 127.0.0.1:53910 391s app_test.go:1117: 16:19:03.376 - 88: DEBUG: new connection from 127.0.0.1:51428 391s app_test.go:1117: 16:19:03.378 - 89: DEBUG: new connection from 127.0.0.1:50658 391s app_test.go:1117: 16:19:03.381 - 90: DEBUG: new connection from 127.0.0.1:59670 391s app_test.go:1117: 16:19:03.434 - 82: DEBUG: new connection from 127.0.0.1:39050 391s app_test.go:1117: 16:19:03.437 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 391s app_test.go:1117: 16:19:03.522 - 82: DEBUG: new connection from 127.0.0.1:39058 391s app_test.go:1117: 16:19:03.524 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 391s app_test.go:1117: 16:19:03.556 - 82: DEBUG: new connection from 127.0.0.1:39066 391s app_test.go:1117: 16:19:03.558 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected 391s --- PASS: TestRolesAdjustment_ReplaceStandByHonorFailureDomains (21.18s) 391s === RUN TestOpen 392s app_test.go:1117: 16:19:03.978 - 91: DEBUG: new connection from 127.0.0.1:49278 392s app_test.go:1117: 16:19:03.979 - 91: DEBUG: new connection from 127.0.0.1:49286 392s app_test.go:1117: 16:19:03.982 - 91: DEBUG: attempt 1: server 127.0.0.1:9000: connected 392s app_test.go:1117: 16:19:03.983 - 91: DEBUG: attempt 1: server 127.0.0.1:9000: connected 392s app_test.go:1117: 16:19:03.986 - 91: DEBUG: new connection from 127.0.0.1:49296 392s --- PASS: TestOpen (0.15s) 392s === RUN TestOptions 392s --- PASS: TestOptions (0.08s) 392s === RUN TestProxy_Error 392s app_test.go:1117: 16:19:03.198 - 93: DEBUG: new connection from 127.0.0.1:49304 392s app_test.go:1117: 16:19:03.198 - 93: DEBUG: new connection from 127.0.0.1:49308 392s app_test.go:1117: 16:19:03.200 - 93: DEBUG: attempt 1: server 127.0.0.1:9000: connected 392s app_test.go:1117: 16:19:03.202 - 93: DEBUG: new connection from 127.0.0.1:49314 392s --- PASS: TestProxy_Error (0.43s) 392s === RUN TestReady_Cancel 392s app_test.go:1117: 16:19:03.627 - 94: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 392s --- PASS: TestReady_Cancel (0.27s) 392s === RUN TestExternalConnWithTCP 392s app_test.go:1142: 16:19:03.908 - 95: DEBUG: attempt 1: server 127.0.0.1:9191: connected 392s app_test.go:1142: 16:19:03.967 - 96: DEBUG: attempt 1: server 127.0.0.1:9191: connected 393s app_test.go:1142: 16:19:03.027 - 97: DEBUG: attempt 1: server 127.0.0.1:9191: connected 393s app_test.go:1142: 16:19:03.037 - 95: DEBUG: attempt 1: server 127.0.0.1:9191: connected 393s --- PASS: TestExternalConnWithTCP (0.37s) 393s === RUN TestExternalConnWithPipe 393s app_test.go:1142: 16:19:03.269 - 98: DEBUG: attempt 1: server first: connected 393s app_test.go:1142: 16:19:03.327 - 99: DEBUG: attempt 1: server first: connected 393s app_test.go:1142: 16:19:03.387 - 100: DEBUG: attempt 1: server first: connected 393s app_test.go:1142: 16:19:03.395 - 98: DEBUG: attempt 1: server first: connected 393s --- PASS: TestExternalConnWithPipe (0.37s) 393s === RUN TestParallelNewApp 393s === PAUSE TestParallelNewApp 393s === RUN Test_TxRowsAffected 393s app_test.go:1142: 16:19:03.639 - 101: DEBUG: attempt 1: server 127.0.0.1:9001: connected 393s app_test.go:1142: 16:19:03.640 - 101: DEBUG: attempt 1: server 127.0.0.1:9001: connected 393s --- PASS: Test_TxRowsAffected (0.13s) 393s === CONT TestParallelNewApp 393s === RUN TestParallelNewApp/run-0 393s === PAUSE TestParallelNewApp/run-0 393s === RUN TestParallelNewApp/run-1 393s === PAUSE TestParallelNewApp/run-1 393s === RUN TestParallelNewApp/run-2 393s === PAUSE TestParallelNewApp/run-2 393s === RUN TestParallelNewApp/run-3 393s === PAUSE TestParallelNewApp/run-3 393s === RUN TestParallelNewApp/run-4 393s === PAUSE TestParallelNewApp/run-4 393s === RUN TestParallelNewApp/run-5 393s === PAUSE TestParallelNewApp/run-5 393s === RUN TestParallelNewApp/run-6 393s === PAUSE TestParallelNewApp/run-6 393s === RUN TestParallelNewApp/run-7 393s === PAUSE TestParallelNewApp/run-7 393s === RUN TestParallelNewApp/run-8 393s === PAUSE TestParallelNewApp/run-8 393s === RUN TestParallelNewApp/run-9 393s === PAUSE TestParallelNewApp/run-9 393s === RUN TestParallelNewApp/run-10 393s === PAUSE TestParallelNewApp/run-10 393s === RUN TestParallelNewApp/run-11 393s === PAUSE TestParallelNewApp/run-11 393s === RUN TestParallelNewApp/run-12 393s === PAUSE TestParallelNewApp/run-12 393s === RUN TestParallelNewApp/run-13 393s === PAUSE TestParallelNewApp/run-13 393s === RUN TestParallelNewApp/run-14 393s === PAUSE TestParallelNewApp/run-14 393s === RUN TestParallelNewApp/run-15 393s === PAUSE TestParallelNewApp/run-15 393s === RUN TestParallelNewApp/run-16 393s === PAUSE TestParallelNewApp/run-16 393s === RUN TestParallelNewApp/run-17 393s === PAUSE TestParallelNewApp/run-17 393s === RUN TestParallelNewApp/run-18 393s === PAUSE TestParallelNewApp/run-18 393s === RUN TestParallelNewApp/run-19 393s === PAUSE TestParallelNewApp/run-19 393s === RUN TestParallelNewApp/run-20 393s === PAUSE TestParallelNewApp/run-20 393s === RUN TestParallelNewApp/run-21 393s === PAUSE TestParallelNewApp/run-21 393s === RUN TestParallelNewApp/run-22 393s === PAUSE TestParallelNewApp/run-22 393s === RUN TestParallelNewApp/run-23 393s === PAUSE TestParallelNewApp/run-23 393s === RUN TestParallelNewApp/run-24 393s === PAUSE TestParallelNewApp/run-24 393s === RUN TestParallelNewApp/run-25 393s === PAUSE TestParallelNewApp/run-25 393s === RUN TestParallelNewApp/run-26 393s === PAUSE TestParallelNewApp/run-26 393s === RUN TestParallelNewApp/run-27 393s === PAUSE TestParallelNewApp/run-27 393s === RUN TestParallelNewApp/run-28 393s === PAUSE TestParallelNewApp/run-28 393s === RUN TestParallelNewApp/run-29 393s === PAUSE TestParallelNewApp/run-29 393s === RUN TestParallelNewApp/run-30 393s === PAUSE TestParallelNewApp/run-30 393s === RUN TestParallelNewApp/run-31 393s === PAUSE TestParallelNewApp/run-31 393s === RUN TestParallelNewApp/run-32 393s === PAUSE TestParallelNewApp/run-32 393s === RUN TestParallelNewApp/run-33 393s === PAUSE TestParallelNewApp/run-33 393s === RUN TestParallelNewApp/run-34 393s === PAUSE TestParallelNewApp/run-34 393s === RUN TestParallelNewApp/run-35 393s === PAUSE TestParallelNewApp/run-35 393s === RUN TestParallelNewApp/run-36 393s === PAUSE TestParallelNewApp/run-36 393s === RUN TestParallelNewApp/run-37 393s === PAUSE TestParallelNewApp/run-37 393s === RUN TestParallelNewApp/run-38 393s === PAUSE TestParallelNewApp/run-38 393s === RUN TestParallelNewApp/run-39 393s === PAUSE TestParallelNewApp/run-39 393s === RUN TestParallelNewApp/run-40 393s === PAUSE TestParallelNewApp/run-40 393s === RUN TestParallelNewApp/run-41 393s === PAUSE TestParallelNewApp/run-41 393s === RUN TestParallelNewApp/run-42 393s === PAUSE TestParallelNewApp/run-42 393s === RUN TestParallelNewApp/run-43 393s === PAUSE TestParallelNewApp/run-43 393s === RUN TestParallelNewApp/run-44 393s === PAUSE TestParallelNewApp/run-44 393s === RUN TestParallelNewApp/run-45 393s === PAUSE TestParallelNewApp/run-45 393s === RUN TestParallelNewApp/run-46 393s === PAUSE TestParallelNewApp/run-46 393s === RUN TestParallelNewApp/run-47 393s === PAUSE TestParallelNewApp/run-47 393s === RUN TestParallelNewApp/run-48 393s === PAUSE TestParallelNewApp/run-48 393s === RUN TestParallelNewApp/run-49 393s === PAUSE TestParallelNewApp/run-49 393s === RUN TestParallelNewApp/run-50 393s === PAUSE TestParallelNewApp/run-50 393s === RUN TestParallelNewApp/run-51 393s === PAUSE TestParallelNewApp/run-51 393s === RUN TestParallelNewApp/run-52 393s === PAUSE TestParallelNewApp/run-52 393s === RUN TestParallelNewApp/run-53 393s === PAUSE TestParallelNewApp/run-53 393s === RUN TestParallelNewApp/run-54 393s === PAUSE TestParallelNewApp/run-54 393s === RUN TestParallelNewApp/run-55 393s === PAUSE TestParallelNewApp/run-55 393s === RUN TestParallelNewApp/run-56 393s === PAUSE TestParallelNewApp/run-56 393s === RUN TestParallelNewApp/run-57 393s === PAUSE TestParallelNewApp/run-57 393s === RUN TestParallelNewApp/run-58 393s === PAUSE TestParallelNewApp/run-58 393s === RUN TestParallelNewApp/run-59 393s === PAUSE TestParallelNewApp/run-59 393s === RUN TestParallelNewApp/run-60 393s === PAUSE TestParallelNewApp/run-60 393s === RUN TestParallelNewApp/run-61 393s === PAUSE TestParallelNewApp/run-61 393s === RUN TestParallelNewApp/run-62 393s === PAUSE TestParallelNewApp/run-62 393s === RUN TestParallelNewApp/run-63 393s === PAUSE TestParallelNewApp/run-63 393s === RUN TestParallelNewApp/run-64 393s === PAUSE TestParallelNewApp/run-64 393s === RUN TestParallelNewApp/run-65 393s === PAUSE TestParallelNewApp/run-65 393s === RUN TestParallelNewApp/run-66 393s === PAUSE TestParallelNewApp/run-66 393s === RUN TestParallelNewApp/run-67 393s === PAUSE TestParallelNewApp/run-67 393s === RUN TestParallelNewApp/run-68 393s === PAUSE TestParallelNewApp/run-68 393s === RUN TestParallelNewApp/run-69 393s === PAUSE TestParallelNewApp/run-69 393s === RUN TestParallelNewApp/run-70 393s === PAUSE TestParallelNewApp/run-70 393s === RUN TestParallelNewApp/run-71 393s === PAUSE TestParallelNewApp/run-71 393s === RUN TestParallelNewApp/run-72 393s === PAUSE TestParallelNewApp/run-72 393s === RUN TestParallelNewApp/run-73 393s === PAUSE TestParallelNewApp/run-73 393s === RUN TestParallelNewApp/run-74 393s === PAUSE TestParallelNewApp/run-74 393s === RUN TestParallelNewApp/run-75 393s === PAUSE TestParallelNewApp/run-75 393s === RUN TestParallelNewApp/run-76 393s === PAUSE TestParallelNewApp/run-76 393s === RUN TestParallelNewApp/run-77 393s === PAUSE TestParallelNewApp/run-77 393s === RUN TestParallelNewApp/run-78 393s === PAUSE TestParallelNewApp/run-78 393s === RUN TestParallelNewApp/run-79 393s === PAUSE TestParallelNewApp/run-79 393s === RUN TestParallelNewApp/run-80 393s === PAUSE TestParallelNewApp/run-80 393s === RUN TestParallelNewApp/run-81 393s === PAUSE TestParallelNewApp/run-81 393s === RUN TestParallelNewApp/run-82 393s === PAUSE TestParallelNewApp/run-82 393s === RUN TestParallelNewApp/run-83 393s === PAUSE TestParallelNewApp/run-83 393s === RUN TestParallelNewApp/run-84 393s === PAUSE TestParallelNewApp/run-84 393s === RUN TestParallelNewApp/run-85 393s === PAUSE TestParallelNewApp/run-85 393s === RUN TestParallelNewApp/run-86 393s === PAUSE TestParallelNewApp/run-86 393s === RUN TestParallelNewApp/run-87 393s === PAUSE TestParallelNewApp/run-87 393s === RUN TestParallelNewApp/run-88 393s === PAUSE TestParallelNewApp/run-88 393s === RUN TestParallelNewApp/run-89 393s === PAUSE TestParallelNewApp/run-89 393s === RUN TestParallelNewApp/run-90 393s === PAUSE TestParallelNewApp/run-90 393s === RUN TestParallelNewApp/run-91 393s === PAUSE TestParallelNewApp/run-91 393s === RUN TestParallelNewApp/run-92 393s === PAUSE TestParallelNewApp/run-92 393s === RUN TestParallelNewApp/run-93 393s === PAUSE TestParallelNewApp/run-93 393s === RUN TestParallelNewApp/run-94 393s === PAUSE TestParallelNewApp/run-94 393s === RUN TestParallelNewApp/run-95 393s === PAUSE TestParallelNewApp/run-95 393s === RUN TestParallelNewApp/run-96 393s === PAUSE TestParallelNewApp/run-96 393s === RUN TestParallelNewApp/run-97 393s === PAUSE TestParallelNewApp/run-97 393s === RUN TestParallelNewApp/run-98 393s === PAUSE TestParallelNewApp/run-98 393s === RUN TestParallelNewApp/run-99 393s === PAUSE TestParallelNewApp/run-99 393s === CONT TestParallelNewApp/run-0 393s === CONT TestParallelNewApp/run-99 393s === CONT TestParallelNewApp/run-98 393s === CONT TestParallelNewApp/run-97 394s === CONT TestParallelNewApp/run-96 394s === CONT TestParallelNewApp/run-95 394s === CONT TestParallelNewApp/run-94 394s === CONT TestParallelNewApp/run-93 394s === CONT TestParallelNewApp/run-92 394s === CONT TestParallelNewApp/run-91 394s === CONT TestParallelNewApp/run-90 394s === CONT TestParallelNewApp/run-89 394s === CONT TestParallelNewApp/run-88 394s === CONT TestParallelNewApp/run-87 394s === CONT TestParallelNewApp/run-86 394s === CONT TestParallelNewApp/run-85 394s === CONT TestParallelNewApp/run-84 395s === CONT TestParallelNewApp/run-83 395s === CONT TestParallelNewApp/run-82 395s === CONT TestParallelNewApp/run-81 395s === CONT TestParallelNewApp/run-80 395s === CONT TestParallelNewApp/run-79 395s === CONT TestParallelNewApp/run-78 395s === CONT TestParallelNewApp/run-77 395s === CONT TestParallelNewApp/run-76 395s === CONT TestParallelNewApp/run-75 395s === CONT TestParallelNewApp/run-74 395s === CONT TestParallelNewApp/run-73 395s === CONT TestParallelNewApp/run-72 395s === CONT TestParallelNewApp/run-71 396s === CONT TestParallelNewApp/run-70 396s === CONT TestParallelNewApp/run-69 396s === CONT TestParallelNewApp/run-68 396s === CONT TestParallelNewApp/run-67 396s === CONT TestParallelNewApp/run-66 396s === CONT TestParallelNewApp/run-65 396s === CONT TestParallelNewApp/run-64 396s === CONT TestParallelNewApp/run-63 396s === CONT TestParallelNewApp/run-62 396s === CONT TestParallelNewApp/run-61 396s === CONT TestParallelNewApp/run-60 396s === CONT TestParallelNewApp/run-59 396s === CONT TestParallelNewApp/run-58 397s === CONT TestParallelNewApp/run-57 397s === CONT TestParallelNewApp/run-56 397s === CONT TestParallelNewApp/run-55 397s === CONT TestParallelNewApp/run-54 397s === CONT TestParallelNewApp/run-53 397s === CONT TestParallelNewApp/run-52 397s === CONT TestParallelNewApp/run-51 397s === CONT TestParallelNewApp/run-50 397s === CONT TestParallelNewApp/run-49 397s === CONT TestParallelNewApp/run-48 397s === CONT TestParallelNewApp/run-47 397s === CONT TestParallelNewApp/run-46 397s === CONT TestParallelNewApp/run-45 398s === CONT TestParallelNewApp/run-44 398s === CONT TestParallelNewApp/run-43 398s === CONT TestParallelNewApp/run-42 398s === CONT TestParallelNewApp/run-41 398s === CONT TestParallelNewApp/run-40 398s === CONT TestParallelNewApp/run-39 398s === CONT TestParallelNewApp/run-38 398s === CONT TestParallelNewApp/run-37 398s === CONT TestParallelNewApp/run-36 398s === CONT TestParallelNewApp/run-35 398s === CONT TestParallelNewApp/run-34 398s === CONT TestParallelNewApp/run-33 399s === CONT TestParallelNewApp/run-32 399s === CONT TestParallelNewApp/run-31 399s === CONT TestParallelNewApp/run-30 399s === CONT TestParallelNewApp/run-29 399s === CONT TestParallelNewApp/run-28 399s === CONT TestParallelNewApp/run-27 399s === CONT TestParallelNewApp/run-26 399s === CONT TestParallelNewApp/run-25 399s === CONT TestParallelNewApp/run-24 399s === CONT TestParallelNewApp/run-23 399s === CONT TestParallelNewApp/run-22 399s === CONT TestParallelNewApp/run-21 399s === CONT TestParallelNewApp/run-20 400s === CONT TestParallelNewApp/run-19 400s === CONT TestParallelNewApp/run-18 400s === CONT TestParallelNewApp/run-17 400s === CONT TestParallelNewApp/run-16 400s === CONT TestParallelNewApp/run-15 400s === CONT TestParallelNewApp/run-14 400s === CONT TestParallelNewApp/run-13 400s === CONT TestParallelNewApp/run-12 400s === CONT TestParallelNewApp/run-11 400s === CONT TestParallelNewApp/run-10 400s === CONT TestParallelNewApp/run-9 400s === CONT TestParallelNewApp/run-8 400s === CONT TestParallelNewApp/run-7 401s === CONT TestParallelNewApp/run-6 401s === CONT TestParallelNewApp/run-5 401s === CONT TestParallelNewApp/run-4 401s === CONT TestParallelNewApp/run-3 401s === CONT TestParallelNewApp/run-2 401s === CONT TestParallelNewApp/run-1 401s --- PASS: TestParallelNewApp (0.00s) 401s --- PASS: TestParallelNewApp/run-0 (0.07s) 401s --- PASS: TestParallelNewApp/run-99 (0.07s) 401s --- PASS: TestParallelNewApp/run-98 (0.07s) 401s --- PASS: TestParallelNewApp/run-97 (0.07s) 401s --- PASS: TestParallelNewApp/run-96 (0.08s) 401s --- PASS: TestParallelNewApp/run-95 (0.08s) 401s --- PASS: TestParallelNewApp/run-94 (0.08s) 401s --- PASS: TestParallelNewApp/run-93 (0.08s) 401s --- PASS: TestParallelNewApp/run-92 (0.08s) 401s --- PASS: TestParallelNewApp/run-91 (0.07s) 401s --- PASS: TestParallelNewApp/run-90 (0.08s) 401s --- PASS: TestParallelNewApp/run-89 (0.08s) 401s --- PASS: TestParallelNewApp/run-88 (0.08s) 401s --- PASS: TestParallelNewApp/run-87 (0.07s) 401s --- PASS: TestParallelNewApp/run-86 (0.08s) 401s --- PASS: TestParallelNewApp/run-85 (0.07s) 401s --- PASS: TestParallelNewApp/run-84 (0.07s) 401s --- PASS: TestParallelNewApp/run-83 (0.07s) 401s --- PASS: TestParallelNewApp/run-82 (0.09s) 401s --- PASS: TestParallelNewApp/run-81 (0.06s) 401s --- PASS: TestParallelNewApp/run-80 (0.08s) 401s --- PASS: TestParallelNewApp/run-79 (0.09s) 401s --- PASS: TestParallelNewApp/run-78 (0.07s) 401s --- PASS: TestParallelNewApp/run-77 (0.08s) 401s --- PASS: TestParallelNewApp/run-76 (0.09s) 401s --- PASS: TestParallelNewApp/run-75 (0.07s) 401s --- PASS: TestParallelNewApp/run-74 (0.07s) 401s --- PASS: TestParallelNewApp/run-73 (0.08s) 401s --- PASS: TestParallelNewApp/run-72 (0.07s) 401s --- PASS: TestParallelNewApp/run-71 (0.07s) 401s --- PASS: TestParallelNewApp/run-70 (0.08s) 401s --- PASS: TestParallelNewApp/run-69 (0.07s) 401s --- PASS: TestParallelNewApp/run-68 (0.07s) 401s --- PASS: TestParallelNewApp/run-67 (0.08s) 401s --- PASS: TestParallelNewApp/run-66 (0.08s) 401s --- PASS: TestParallelNewApp/run-65 (0.07s) 401s --- PASS: TestParallelNewApp/run-64 (0.08s) 401s --- PASS: TestParallelNewApp/run-63 (0.08s) 401s --- PASS: TestParallelNewApp/run-62 (0.08s) 401s --- PASS: TestParallelNewApp/run-61 (0.09s) 401s --- PASS: TestParallelNewApp/run-60 (0.07s) 401s --- PASS: TestParallelNewApp/run-59 (0.08s) 401s --- PASS: TestParallelNewApp/run-58 (0.07s) 401s --- PASS: TestParallelNewApp/run-57 (0.08s) 401s --- PASS: TestParallelNewApp/run-56 (0.08s) 401s --- PASS: TestParallelNewApp/run-55 (0.07s) 401s --- PASS: TestParallelNewApp/run-54 (0.07s) 401s --- PASS: TestParallelNewApp/run-53 (0.08s) 401s --- PASS: TestParallelNewApp/run-52 (0.07s) 401s --- PASS: TestParallelNewApp/run-51 (0.07s) 401s --- PASS: TestParallelNewApp/run-50 (0.08s) 401s --- PASS: TestParallelNewApp/run-49 (0.07s) 401s --- PASS: TestParallelNewApp/run-48 (0.08s) 401s --- PASS: TestParallelNewApp/run-47 (0.07s) 401s --- PASS: TestParallelNewApp/run-46 (0.08s) 401s --- PASS: TestParallelNewApp/run-45 (0.08s) 401s --- PASS: TestParallelNewApp/run-44 (0.08s) 401s --- PASS: TestParallelNewApp/run-43 (0.08s) 401s --- PASS: TestParallelNewApp/run-42 (0.08s) 401s --- PASS: TestParallelNewApp/run-41 (0.08s) 401s --- PASS: TestParallelNewApp/run-40 (0.08s) 401s --- PASS: TestParallelNewApp/run-39 (0.08s) 401s --- PASS: TestParallelNewApp/run-38 (0.08s) 401s --- PASS: TestParallelNewApp/run-37 (0.08s) 401s --- PASS: TestParallelNewApp/run-36 (0.08s) 401s --- PASS: TestParallelNewApp/run-35 (0.08s) 401s --- PASS: TestParallelNewApp/run-34 (0.07s) 401s --- PASS: TestParallelNewApp/run-33 (0.09s) 401s --- PASS: TestParallelNewApp/run-32 (0.06s) 401s --- PASS: TestParallelNewApp/run-31 (0.08s) 401s --- PASS: TestParallelNewApp/run-30 (0.07s) 401s --- PASS: TestParallelNewApp/run-29 (0.08s) 401s --- PASS: TestParallelNewApp/run-28 (0.09s) 401s --- PASS: TestParallelNewApp/run-27 (0.07s) 401s --- PASS: TestParallelNewApp/run-26 (0.07s) 401s --- PASS: TestParallelNewApp/run-25 (0.08s) 401s --- PASS: TestParallelNewApp/run-24 (0.07s) 401s --- PASS: TestParallelNewApp/run-23 (0.08s) 401s --- PASS: TestParallelNewApp/run-22 (0.08s) 401s --- PASS: TestParallelNewApp/run-21 (0.08s) 401s --- PASS: TestParallelNewApp/run-20 (0.07s) 401s --- PASS: TestParallelNewApp/run-19 (0.09s) 401s --- PASS: TestParallelNewApp/run-18 (0.07s) 401s --- PASS: TestParallelNewApp/run-17 (0.08s) 401s --- PASS: TestParallelNewApp/run-16 (0.08s) 401s --- PASS: TestParallelNewApp/run-15 (0.08s) 401s --- PASS: TestParallelNewApp/run-14 (0.06s) 401s --- PASS: TestParallelNewApp/run-13 (0.07s) 401s --- PASS: TestParallelNewApp/run-12 (0.07s) 401s --- PASS: TestParallelNewApp/run-11 (0.07s) 401s --- PASS: TestParallelNewApp/run-10 (0.08s) 401s --- PASS: TestParallelNewApp/run-9 (0.08s) 401s --- PASS: TestParallelNewApp/run-8 (0.08s) 401s --- PASS: TestParallelNewApp/run-7 (0.08s) 401s --- PASS: TestParallelNewApp/run-6 (0.08s) 401s --- PASS: TestParallelNewApp/run-5 (0.08s) 401s --- PASS: TestParallelNewApp/run-4 (0.11s) 401s --- PASS: TestParallelNewApp/run-3 (0.08s) 401s --- PASS: TestParallelNewApp/run-2 (0.08s) 401s --- PASS: TestParallelNewApp/run-1 (0.10s) 401s === RUN Example 401s --- PASS: Example (0.14s) 401s PASS 401s ok github.com/cowsql/go-cowsql/app 118.153s 402s === RUN TestNew_Default 403s Benchmark done. Results available here: 403s /tmp/cowsql-app-test-164880831/results 403s --- PASS: TestNew_Default (1.13s) 403s === RUN TestNew_KvReadWrite 404s Benchmark done. Results available here: 404s /tmp/cowsql-app-test-1835353392/results 404s --- PASS: TestNew_KvReadWrite (1.15s) 404s === RUN TestNew_ClusteredKvReadWrite 406s Benchmark done. Results available here: 406s /tmp/cowsql-app-test-1526848138/results 407s --- PASS: TestNew_ClusteredKvReadWrite (2.29s) 407s === RUN TestNew_ClusteredTimeout 409s --- PASS: TestNew_ClusteredTimeout (2.11s) 409s PASS 409s ok github.com/cowsql/go-cowsql/benchmark 6.756s 409s === RUN TestClient_Leader 410s --- PASS: TestClient_Leader (0.06s) 410s === RUN TestClient_Dump 410s --- PASS: TestClient_Dump (0.11s) 410s === RUN TestClient_Cluster 410s --- PASS: TestClient_Cluster (0.07s) 410s === RUN TestClient_Transfer 410s --- PASS: TestClient_Transfer (0.25s) 410s === RUN TestClient_Describe 410s --- PASS: TestClient_Describe (0.10s) 410s === RUN TestMembership 411s --- PASS: TestMembership (0.32s) 411s === RUN TestDefaultNodeStore 411s --- PASS: TestDefaultNodeStore (0.00s) 411s === RUN TestConfigMultiThread 411s --- PASS: TestConfigMultiThread (0.00s) 411s PASS 411s ok github.com/cowsql/go-cowsql/client 0.941s 415s ? github.com/cowsql/go-cowsql/cmd/cowsql [no test files] 415s ? github.com/cowsql/go-cowsql/cmd/cowsql-benchmark [no test files] 415s ? github.com/cowsql/go-cowsql/cmd/cowsql-demo [no test files] 415s === RUN TestDriver_Open 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestDriver_Open (0.06s) 415s === RUN TestDriver_Prepare 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestDriver_Prepare (0.08s) 415s === RUN TestConn_Exec 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestConn_Exec (0.08s) 415s === RUN TestConn_Query 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestConn_Query (0.08s) 415s === RUN TestConn_QueryRow 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestConn_QueryRow (0.08s) 415s === RUN TestConn_QueryBlob 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestConn_QueryBlob (0.07s) 415s === RUN TestStmt_Exec 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestStmt_Exec (0.08s) 415s === RUN TestStmt_ExecManyParams 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestStmt_ExecManyParams (0.08s) 415s === RUN TestStmt_Query 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestStmt_Query (0.07s) 415s === RUN TestStmt_QueryManyParams 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestStmt_QueryManyParams (0.07s) 415s === RUN TestConn_QueryParams 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestConn_QueryParams (0.08s) 415s === RUN TestConn_QueryManyParams 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestConn_QueryManyParams (0.08s) 415s === RUN TestConn_ExecManyParams 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: TestConn_ExecManyParams (0.08s) 415s === RUN Test_ColumnTypesEmpty 415s driver_test.go:439: this currently fails if the result set is empty, is cowsql skipping the header if empty set? 415s --- SKIP: Test_ColumnTypesEmpty (0.00s) 415s === RUN Test_ColumnTypesExists 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: Test_ColumnTypesExists (0.06s) 415s === RUN Test_ColumnTypesEnd 415s func.go:15: DEBUG: attempt 1: server @1: connected 415s --- PASS: Test_ColumnTypesEnd (0.07s) 415s === RUN Test_ZeroColumns 416s func.go:15: DEBUG: attempt 1: server @1: connected 416s --- PASS: Test_ZeroColumns (0.13s) 416s === RUN TestIntegration_DatabaseSQL 416s func.go:15: DEBUG: attempt 1: server @1: connected 416s --- PASS: TestIntegration_DatabaseSQL (0.35s) 416s === RUN TestIntegration_ConstraintError 416s func.go:15: DEBUG: attempt 1: server @1: connected 416s --- PASS: TestIntegration_ConstraintError (0.40s) 416s === RUN TestIntegration_ExecBindError 416s func.go:15: DEBUG: attempt 1: server @1: connected 416s --- PASS: TestIntegration_ExecBindError (0.13s) 416s === RUN TestIntegration_QueryBindError 416s func.go:15: DEBUG: attempt 1: server @1: connected 416s --- PASS: TestIntegration_QueryBindError (0.07s) 416s === RUN TestIntegration_LargeQuery 417s func.go:15: DEBUG: attempt 1: server @1: connected 417s --- PASS: TestIntegration_LargeQuery (0.39s) 417s === RUN TestIntegration_Recover 417s func.go:15: DEBUG: attempt 1: server @1: connected 417s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 417s func.go:15: DEBUG: attempt 1: server @1: connected 417s --- PASS: TestIntegration_Recover (0.35s) 417s === RUN TestIntegration_PingOnlyWorksOnceLeaderElected 417s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 417s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 417s func.go:15: WARN: attempt 1: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused 418s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 418s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 418s func.go:15: WARN: attempt 2: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused 418s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 418s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 418s func.go:15: WARN: attempt 3: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused 419s func.go:15: WARN: attempt 1: server @1: no known leader 419s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 419s func.go:15: WARN: attempt 1: server @2: reported leader server is not the leader 419s func.go:15: WARN: attempt 2: server @1: no known leader 419s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 419s func.go:15: WARN: attempt 2: server @2: reported leader server is not the leader 419s func.go:15: WARN: attempt 3: server @1: no known leader 419s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 419s func.go:15: WARN: attempt 3: server @2: reported leader server is not the leader 420s func.go:15: WARN: attempt 4: server @1: no known leader 420s func.go:15: DEBUG: attempt 4: server @2: connect to reported leader @1 420s func.go:15: WARN: attempt 4: server @2: reported leader server is not the leader 421s func.go:15: WARN: attempt 5: server @1: no known leader 421s func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @1 421s func.go:15: WARN: attempt 5: server @2: reported leader server is not the leader 422s func.go:15: WARN: attempt 6: server @1: no known leader 422s func.go:15: WARN: attempt 6: server @2: no known leader 423s func.go:15: WARN: attempt 7: server @1: no known leader 423s func.go:15: WARN: attempt 7: server @2: no known leader 424s func.go:15: DEBUG: attempt 8: server @1: connected 425s --- PASS: TestIntegration_PingOnlyWorksOnceLeaderElected (7.19s) 425s === RUN TestIntegration_HighAvailability 425s func.go:15: DEBUG: attempt 1: server @1: connected 427s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 427s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 427s func.go:15: WARN: attempt 1: server @2: no known leader 427s func.go:15: WARN: attempt 1: server @3: no known leader 427s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 427s func.go:15: WARN: attempt 2: server @2: no known leader 427s func.go:15: WARN: attempt 2: server @3: no known leader 427s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 427s func.go:15: WARN: attempt 3: server @2: no known leader 427s func.go:15: WARN: attempt 3: server @3: no known leader 428s func.go:15: WARN: attempt 4: server @1: dial: dial unix @1: connect: connection refused 428s func.go:15: WARN: attempt 4: server @2: no known leader 428s func.go:15: WARN: attempt 4: server @3: no known leader 429s func.go:15: WARN: attempt 5: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: WARN: attempt 5: server @2: no known leader 429s func.go:15: WARN: attempt 5: server @3: no known leader 430s func.go:15: WARN: attempt 6: server @1: dial: dial unix @1: connect: connection refused 430s func.go:15: DEBUG: attempt 6: server @2: connected 430s --- PASS: TestIntegration_HighAvailability (6.02s) 430s === RUN TestIntegration_LeadershipTransfer 431s func.go:15: DEBUG: attempt 1: server @1: connected 431s func.go:15: DEBUG: leadership lost (10250 - not leader) 431s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 431s func.go:15: DEBUG: attempt 1: server @1: connected 431s --- PASS: TestIntegration_LeadershipTransfer (0.40s) 431s === RUN TestIntegration_LeadershipTransfer_Tx 431s func.go:15: DEBUG: attempt 1: server @1: connected 431s func.go:15: DEBUG: leadership lost (10250 - not leader) 431s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 431s func.go:15: DEBUG: attempt 1: server @1: connected 431s --- PASS: TestIntegration_LeadershipTransfer_Tx (0.36s) 431s === RUN TestOptions 431s --- PASS: TestOptions (0.00s) 431s === RUN TestIntegration_ColumnTypeName 431s func.go:15: DEBUG: attempt 1: server @1: connected 431s --- PASS: TestIntegration_ColumnTypeName (0.13s) 431s === RUN TestIntegration_SqlNullTime 431s func.go:15: DEBUG: attempt 1: server @1: connected 431s --- PASS: TestIntegration_SqlNullTime (0.12s) 431s PASS 431s ok github.com/cowsql/go-cowsql/driver 17.187s 432s === RUN TestNode_Create 432s --- PASS: TestNode_Create (0.05s) 432s === RUN TestNode_Start 432s --- PASS: TestNode_Start (0.07s) 432s === RUN TestNode_Restart 432s --- PASS: TestNode_Restart (0.15s) 432s === RUN TestNode_Start_Inet 432s --- PASS: TestNode_Start_Inet (0.06s) 432s === RUN TestNode_Leader 432s --- PASS: TestNode_Leader (0.08s) 432s PASS 432s ok github.com/cowsql/go-cowsql/internal/bindings 0.436s 434s === RUN TestMessage_StaticBytesAlignment 434s --- PASS: TestMessage_StaticBytesAlignment (0.00s) 434s === RUN TestMessage_putBlob 434s === RUN TestMessage_putBlob/16 434s === RUN TestMessage_putBlob/16#01 434s === RUN TestMessage_putBlob/24 434s --- PASS: TestMessage_putBlob (0.00s) 434s --- PASS: TestMessage_putBlob/16 (0.00s) 434s --- PASS: TestMessage_putBlob/16#01 (0.00s) 434s --- PASS: TestMessage_putBlob/24 (0.00s) 434s === RUN TestMessage_putString 434s === RUN TestMessage_putString/hello 434s === RUN TestMessage_putString/hello!! 434s === RUN TestMessage_putString/hello_world 434s --- PASS: TestMessage_putString (0.00s) 434s --- PASS: TestMessage_putString/hello (0.00s) 434s --- PASS: TestMessage_putString/hello!! (0.00s) 434s --- PASS: TestMessage_putString/hello_world (0.00s) 434s === RUN TestMessage_putUint8 434s --- PASS: TestMessage_putUint8 (0.00s) 434s === RUN TestMessage_putUint16 434s --- PASS: TestMessage_putUint16 (0.00s) 434s === RUN TestMessage_putUint32 434s --- PASS: TestMessage_putUint32 (0.00s) 434s === RUN TestMessage_putUint64 434s --- PASS: TestMessage_putUint64 (0.00s) 434s === RUN TestMessage_putNamedValues 434s --- PASS: TestMessage_putNamedValues (0.00s) 434s === RUN TestMessage_putNamedValues32 434s --- PASS: TestMessage_putNamedValues32 (0.00s) 434s === RUN TestMessage_putHeader 434s --- PASS: TestMessage_putHeader (0.00s) 434s === RUN TestMessage_getString 434s === RUN TestMessage_getString/hello 434s === RUN TestMessage_getString/hello!! 434s === RUN TestMessage_getString/hello!!! 434s === RUN TestMessage_getString/hello_world 434s --- PASS: TestMessage_getString (0.00s) 434s --- PASS: TestMessage_getString/hello (0.00s) 434s --- PASS: TestMessage_getString/hello!! (0.00s) 434s --- PASS: TestMessage_getString/hello!!! (0.00s) 434s --- PASS: TestMessage_getString/hello_world (0.00s) 434s === RUN TestMessage_getBlob 434s === RUN TestMessage_getBlob/16 434s === RUN TestMessage_getBlob/16#01 434s === RUN TestMessage_getBlob/24 434s --- PASS: TestMessage_getBlob (0.00s) 434s --- PASS: TestMessage_getBlob/16 (0.00s) 434s --- PASS: TestMessage_getBlob/16#01 (0.00s) 434s --- PASS: TestMessage_getBlob/24 (0.00s) 434s === RUN TestMessage_getString_Overflow_WordBoundary 434s --- PASS: TestMessage_getString_Overflow_WordBoundary (0.00s) 434s === RUN TestConnector_Success 434s connector_test.go:304: DEBUG: attempt 1: server @test-0: connected 434s --- PASS: TestConnector_Success (0.05s) 434s === RUN TestConnector_LimitRetries 434s connector_test.go:304: WARN: attempt 1: server @test-123: dial: dial unix @test-123: connect: connection refused 434s connector_test.go:304: WARN: attempt 2: server @test-123: dial: dial unix @test-123: connect: connection refused 435s connector_test.go:304: WARN: attempt 3: server @test-123: dial: dial unix @test-123: connect: connection refused 435s --- PASS: TestConnector_LimitRetries (0.60s) 435s === RUN TestConnector_DialTimeout 435s connector_test.go:64: Skipping network test 435s --- SKIP: TestConnector_DialTimeout (0.00s) 435s === RUN TestConnector_EmptyNodeStore 435s --- PASS: TestConnector_EmptyNodeStore (0.20s) 435s === RUN TestConnector_ContextCanceled 435s connector_test.go:99: Skipping network test 435s --- SKIP: TestConnector_ContextCanceled (0.00s) 435s === RUN TestConnector_AttemptTimeout 435s func.go:15: WARN: attempt 1: server @1234: call leader (budget 99.952105ms): receive: header: read unix @->@1234: i/o timeout 435s func.go:15: WARN: attempt 2: server @1234: call leader (budget 99.917274ms): receive: header: read unix @->@1234: i/o timeout 435s --- PASS: TestConnector_AttemptTimeout (0.40s) 435s === RUN TestProtocol_RequestWithDynamicBuffer 435s func.go:15: DEBUG: attempt 1: server @test-0: connected 435s --- PASS: TestProtocol_RequestWithDynamicBuffer (0.11s) 435s === RUN TestProtocol_Prepare 435s func.go:15: DEBUG: attempt 1: server @test-0: connected 435s --- PASS: TestProtocol_Prepare (0.06s) 436s PASS 436s ok github.com/cowsql/go-cowsql/internal/protocol 1.429s 436s ? github.com/cowsql/go-cowsql/internal/shell [no test files] 436s === RUN Test_TestFunc 436s func.go:15: INFO: hello 436s --- PASS: Test_TestFunc (0.00s) 436s === RUN TestLevel_String 436s --- PASS: TestLevel_String (0.00s) 436s PASS 436s ok github.com/cowsql/go-cowsql/logging 0.003s 436s autopkgtest [16:19:55]: test dh-golang-autopkgtest: -----------------------] 439s dh-golang-autopkgtest PASS 439s autopkgtest [16:19:58]: test dh-golang-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 440s autopkgtest [16:19:59]: @@@@@@@@@@@@@@@@@@@@ summary 440s dh-golang-autopkgtest PASS 454s Creating nova instance adt-noble-s390x-golang-github-cowsql-go-cowsql-20240319-161238-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-s390x-server-20240319.img (UUID e548347a-8530-49a1-9caa-86a7013f2b8b)...