0s autopkgtest [03:29:09]: starting date and time: 2026-02-05 03:29:09+0000
  0s autopkgtest [03:29:09]: git checkout: 508d4a25 a-v-ssh wait_for_ssh: demote "ssh connection failed" to a debug message
  0s autopkgtest [03:29:09]: host juju-7f2275-prod-proposed-migration-environment-9; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.hnxueg96/out --timeout-copy=6000 --setup-commands 'ln -s /dev/null /etc/systemd/system/bluetooth.service; printf "http_proxy=http://squid.internal:3128\nhttps_proxy=http://squid.internal:3128\nno_proxy=127.0.0.1,127.0.1.1,localhost,localdomain,internal,login.ubuntu.com,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,keyserver.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com,radosgw.ps5.canonical.com\n" >> /etc/environment' --apt-pocket=proposed=src:glibc --apt-upgrade golang-github-cowsql-go-cowsql --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 --env=ADT_TEST_TRIGGERS=glibc/2.42-2ubuntu5 -- lxd -r lxd-armhf-10.145.243.230 lxd-armhf-10.145.243.230:autopkgtest/ubuntu/resolute/armhf
 27s autopkgtest [03:29:36]: testbed dpkg architecture: armhf
 29s autopkgtest [03:29:38]: testbed apt version: 3.1.14
 33s autopkgtest [03:29:42]: @@@@@@@@@@@@@@@@@@@@ test bed setup
 35s autopkgtest [03:29:44]: testbed release detected to be: None
 44s autopkgtest [03:29:53]: updating testbed package index (apt update)
 46s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [124 kB]
 46s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease
 46s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease
 46s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease
 47s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [35.4 kB]
 47s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [227 kB]
 47s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [1719 kB]
 47s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5260 B]
 47s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf Packages [250 kB]
 47s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/universe armhf Packages [1364 kB]
 47s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse armhf Packages [9664 B]
 47s Fetched 3735 kB in 1s (2793 kB/s)
 49s Reading package lists...
 55s autopkgtest [03:30:04]: upgrading testbed (apt dist-upgrade and autopurge)
 56s Reading package lists...
 57s Building dependency tree...
 57s Reading state information...
 57s Calculating upgrade...
 58s The following packages will be upgraded:
 58s   libc-bin libc-gconv-modules-extra libc6 locales
 59s 4 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 59s Need to get 7850 kB of archives.
 59s After this operation, 0 B of additional disk space will be used.
 59s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libc-gconv-modules-extra armhf 2.42-2ubuntu5 [1417 kB]
 60s Get:2 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libc6 armhf 2.42-2ubuntu5 [1632 kB]
 61s Get:3 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libc-bin armhf 2.42-2ubuntu5 [545 kB]
 62s Get:4 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf locales all 2.42-2ubuntu5 [4255 kB]
 65s Preconfiguring packages ...
 65s Fetched 7850 kB in 7s (1200 kB/s)
 66s (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 ... 68395 files and directories currently installed.)
 66s Preparing to unpack .../libc-gconv-modules-extra_2.42-2ubuntu5_armhf.deb ...
 66s Unpacking libc-gconv-modules-extra:armhf (2.42-2ubuntu5) over (2.42-2ubuntu4) ...
 66s Setting up libc-gconv-modules-extra:armhf (2.42-2ubuntu5) ...
 66s (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 ... 68395 files and directories currently installed.)
 66s Preparing to unpack .../libc6_2.42-2ubuntu5_armhf.deb ...
 66s readlink: /usr/lib/libeatmydata: No such file or directory
 66s Unpacking libc6:armhf (2.42-2ubuntu5) over (2.42-2ubuntu4) ...
 66s Setting up libc6:armhf (2.42-2ubuntu5) ...
 66s (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 ... 68395 files and directories currently installed.)
 66s Preparing to unpack .../libc-bin_2.42-2ubuntu5_armhf.deb ...
 66s Unpacking libc-bin (2.42-2ubuntu5) over (2.42-2ubuntu4) ...
 66s Setting up libc-bin (2.42-2ubuntu5) ...
 67s (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 ... 68395 files and directories currently installed.)
 67s Preparing to unpack .../locales_2.42-2ubuntu5_all.deb ...
 67s Unpacking locales (2.42-2ubuntu5) over (2.42-2ubuntu4) ...
 67s Setting up locales (2.42-2ubuntu5) ...
 68s Generating locales (this might take a while)...
 72s   en_US.UTF-8... done
 72s Generation complete.
 72s Processing triggers for systemd (259-1ubuntu3) ...
 72s Processing triggers for man-db (2.13.1-1) ...
 75s Reading package lists...
 76s Building dependency tree...
 76s Reading state information...
 76s Solving dependencies...
 77s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded.
 79s autopkgtest [03:30:28]: rebooting testbed after setup commands that affected boot
123s autopkgtest [03:31:12]: testbed running kernel: Linux 6.8.0-87-generic #88~22.04.1-Ubuntu SMP PREEMPT_DYNAMIC Tue Oct 14 14:00:09 UTC 2
149s autopkgtest [03:31:38]: @@@@@@@@@@@@@@@@@@@@ apt-source golang-github-cowsql-go-cowsql
164s Get:1 http://ftpmaster.internal/ubuntu resolute/universe golang-github-cowsql-go-cowsql 1.22.0-2 (dsc) [2726 B]
164s Get:2 http://ftpmaster.internal/ubuntu resolute/universe golang-github-cowsql-go-cowsql 1.22.0-2 (tar) [107 kB]
164s Get:3 http://ftpmaster.internal/ubuntu resolute/universe golang-github-cowsql-go-cowsql 1.22.0-2 (diff) [3800 B]
164s gpgv: Signature made Thu Dec 28 01:43:17 2023 UTC
164s gpgv:                using RSA key D41A7AD07DF6C5FCA7489F1C29EEE2D6ECF442F9
164s gpgv:                issuer "gibmat@debian.org"
164s gpgv: Can't check signature: No public key
164s dpkg-source: warning: cannot verify inline signature for ./golang-github-cowsql-go-cowsql_1.22.0-2.dsc: no acceptable signature found
164s autopkgtest [03:31:53]: testing package golang-github-cowsql-go-cowsql version 1.22.0-2
167s autopkgtest [03:31:56]: build not needed
169s autopkgtest [03:31:58]: test dh-golang-autopkgtest: preparing testbed
171s Reading package lists...
171s Building dependency tree...
171s Reading state information...
172s Solving dependencies...
173s The following NEW packages will be installed:
173s   autoconf automake autopoint autotools-dev build-essential cpp cpp-15
173s   cpp-15-arm-linux-gnueabihf cpp-arm-linux-gnueabihf debhelper debugedit
173s   dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-15
173s   g++-15-arm-linux-gnueabihf g++-arm-linux-gnueabihf gcc gcc-15
173s   gcc-15-arm-linux-gnueabihf gcc-arm-linux-gnueabihf gettext go-cowsql
173s   golang-1.25-go golang-1.25-src golang-any golang-github-burntsushi-toml-dev
173s   golang-github-cowsql-go-cowsql-dev golang-github-cpuguy83-go-md2man-v2-dev
173s   golang-github-davecgh-go-spew-dev golang-github-fsnotify-fsnotify-dev
173s   golang-github-ghodss-yaml-dev golang-github-google-renameio-dev
173s   golang-github-hashicorp-hcl-dev golang-github-kr-fs-dev
173s   golang-github-magiconair-properties-dev golang-github-mattn-go-runewidth-dev
173s   golang-github-mattn-go-sqlite3-dev golang-github-mitchellh-mapstructure-dev
173s   golang-github-pelletier-go-toml-dev golang-github-pelletier-go-toml.v2-dev
173s   golang-github-peterh-liner-dev golang-github-pkg-errors-dev
173s   golang-github-pkg-sftp-dev golang-github-pmezard-go-difflib-dev
173s   golang-github-rican7-retry-dev golang-github-rivo-uniseg-dev
173s   golang-github-russross-blackfriday-v2-dev
173s   golang-github-shurcool-sanitized-anchor-name-dev
173s   golang-github-spf13-afero-dev golang-github-spf13-cast-dev
173s   golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev
173s   golang-github-spf13-pflag-dev golang-github-spf13-viper-dev
173s   golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev
173s   golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev
173s   golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev
173s   golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev
173s   golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl
173s   libasan8 libc-dev-bin libc6-dev libcc1-0 libcowsql-dev libcowsql0
173s   libcrypt-dev libdebhelper-perl libdw1t64 libfile-stripnondeterminism-perl
173s   libgcc-15-dev libgomp1 libisl23 libmpc3 libraft-dev libraft0 libsqlite3-dev
173s   libstdc++-15-dev libtool libubsan1 linux-libc-dev m4 po-debconf rpcsvc-proto
173s   tzdata-legacy
173s 0 upgraded, 97 newly installed, 0 to remove and 0 not upgraded.
173s Need to get 117 MB of archives.
173s After this operation, 473 MB of additional disk space will be used.
173s Get:1 http://ftpmaster.internal/ubuntu resolute/main armhf m4 armhf 1.4.20-2 [212 kB]
173s Get:2 http://ftpmaster.internal/ubuntu resolute/main armhf autoconf all 2.72-3.1ubuntu1 [384 kB]
173s Get:3 http://ftpmaster.internal/ubuntu resolute/main armhf autotools-dev all 20240727.1 [43.4 kB]
173s Get:4 http://ftpmaster.internal/ubuntu resolute/main armhf automake all 1:1.18.1-3build1 [582 kB]
174s Get:5 http://ftpmaster.internal/ubuntu resolute/main armhf autopoint all 0.23.2-1 [620 kB]
174s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libc-dev-bin armhf 2.42-2ubuntu5 [21.8 kB]
174s Get:7 http://ftpmaster.internal/ubuntu resolute/main armhf linux-libc-dev armhf 6.18.0-9.9 [1819 kB]
174s Get:8 http://ftpmaster.internal/ubuntu resolute/main armhf libcrypt-dev armhf 1:4.5.1-1 [128 kB]
174s Get:9 http://ftpmaster.internal/ubuntu resolute/main armhf rpcsvc-proto armhf 1.4.3-1build1 [62.6 kB]
174s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/main armhf libc6-dev armhf 2.42-2ubuntu5 [1416 kB]
174s Get:11 http://ftpmaster.internal/ubuntu resolute/main armhf libisl23 armhf 0.27-1build1 [553 kB]
174s Get:12 http://ftpmaster.internal/ubuntu resolute/main armhf libmpc3 armhf 1.3.1-2 [47.0 kB]
174s Get:13 http://ftpmaster.internal/ubuntu resolute/main armhf cpp-15-arm-linux-gnueabihf armhf 15.2.0-12ubuntu1 [10.1 MB]
175s Get:14 http://ftpmaster.internal/ubuntu resolute/main armhf cpp-15 armhf 15.2.0-12ubuntu1 [1032 B]
175s Get:15 http://ftpmaster.internal/ubuntu resolute/main armhf cpp-arm-linux-gnueabihf armhf 4:15.2.0-4ubuntu1 [5756 B]
175s Get:16 http://ftpmaster.internal/ubuntu resolute/main armhf cpp armhf 4:15.2.0-4ubuntu1 [22.4 kB]
175s Get:17 http://ftpmaster.internal/ubuntu resolute/main armhf libcc1-0 armhf 15.2.0-12ubuntu1 [43.5 kB]
175s Get:18 http://ftpmaster.internal/ubuntu resolute/main armhf libgomp1 armhf 15.2.0-12ubuntu1 [129 kB]
175s Get:19 http://ftpmaster.internal/ubuntu resolute/main armhf libasan8 armhf 15.2.0-12ubuntu1 [2949 kB]
175s Get:20 http://ftpmaster.internal/ubuntu resolute/main armhf libubsan1 armhf 15.2.0-12ubuntu1 [1187 kB]
175s Get:21 http://ftpmaster.internal/ubuntu resolute/main armhf libgcc-15-dev armhf 15.2.0-12ubuntu1 [898 kB]
175s Get:22 http://ftpmaster.internal/ubuntu resolute/main armhf gcc-15-arm-linux-gnueabihf armhf 15.2.0-12ubuntu1 [19.5 MB]
176s Get:23 http://ftpmaster.internal/ubuntu resolute/main armhf gcc-15 armhf 15.2.0-12ubuntu1 [499 kB]
176s Get:24 http://ftpmaster.internal/ubuntu resolute/main armhf gcc-arm-linux-gnueabihf armhf 4:15.2.0-4ubuntu1 [1220 B]
176s Get:25 http://ftpmaster.internal/ubuntu resolute/main armhf gcc armhf 4:15.2.0-4ubuntu1 [5022 B]
176s Get:26 http://ftpmaster.internal/ubuntu resolute/main armhf libstdc++-15-dev armhf 15.2.0-12ubuntu1 [2638 kB]
177s Get:27 http://ftpmaster.internal/ubuntu resolute/main armhf g++-15-arm-linux-gnueabihf armhf 15.2.0-12ubuntu1 [11.4 MB]
177s Get:28 http://ftpmaster.internal/ubuntu resolute/main armhf g++-15 armhf 15.2.0-12ubuntu1 [25.3 kB]
177s Get:29 http://ftpmaster.internal/ubuntu resolute/main armhf g++-arm-linux-gnueabihf armhf 4:15.2.0-4ubuntu1 [968 B]
177s Get:30 http://ftpmaster.internal/ubuntu resolute/main armhf g++ armhf 4:15.2.0-4ubuntu1 [1086 B]
177s Get:31 http://ftpmaster.internal/ubuntu resolute/main armhf build-essential armhf 12.12ubuntu2 [5256 B]
177s Get:32 http://ftpmaster.internal/ubuntu resolute/main armhf libdebhelper-perl all 13.28ubuntu1 [97.4 kB]
177s Get:33 http://ftpmaster.internal/ubuntu resolute/main armhf libtool all 2.5.4-9 [169 kB]
177s Get:34 http://ftpmaster.internal/ubuntu resolute/main armhf dh-autoreconf all 21 [12.5 kB]
177s Get:35 http://ftpmaster.internal/ubuntu resolute/main armhf libarchive-zip-perl all 1.68-1 [90.2 kB]
177s Get:36 http://ftpmaster.internal/ubuntu resolute/main armhf libfile-stripnondeterminism-perl all 1.15.0-1build1 [20.6 kB]
177s Get:37 http://ftpmaster.internal/ubuntu resolute/main armhf dh-strip-nondeterminism all 1.15.0-1build1 [5110 B]
177s Get:38 http://ftpmaster.internal/ubuntu resolute/main armhf libdw1t64 armhf 0.194-1 [254 kB]
177s Get:39 http://ftpmaster.internal/ubuntu resolute/main armhf debugedit armhf 1:5.2-3build1 [49.0 kB]
177s Get:40 http://ftpmaster.internal/ubuntu resolute/main armhf dwz armhf 0.16-2 [114 kB]
177s Get:41 http://ftpmaster.internal/ubuntu resolute/main armhf gettext armhf 0.23.2-1 [1059 kB]
177s Get:42 http://ftpmaster.internal/ubuntu resolute/main armhf intltool-debian all 0.35.0+20060710.6build1 [24.1 kB]
177s Get:43 http://ftpmaster.internal/ubuntu resolute/main armhf po-debconf all 1.0.22 [215 kB]
177s Get:44 http://ftpmaster.internal/ubuntu resolute/main armhf debhelper all 13.28ubuntu1 [916 kB]
177s Get:45 http://ftpmaster.internal/ubuntu resolute/universe armhf libraft0 armhf 0.22.1-1build1 [90.3 kB]
177s Get:46 http://ftpmaster.internal/ubuntu resolute/universe armhf libcowsql0 armhf 1.15.9-1 [80.0 kB]
177s Get:47 http://ftpmaster.internal/ubuntu resolute/universe armhf go-cowsql armhf 1.22.0-2 [8286 kB]
178s Get:48 http://ftpmaster.internal/ubuntu resolute/main armhf golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB]
179s Get:49 http://ftpmaster.internal/ubuntu resolute/main armhf golang-1.25-go armhf 1.25.0-0ubuntu1 [16.3 MB]
179s Get:50 http://ftpmaster.internal/ubuntu resolute/main armhf golang-src all 2:1.25~1 [5206 B]
179s Get:51 http://ftpmaster.internal/ubuntu resolute/main armhf golang-go armhf 2:1.25~1 [44.1 kB]
179s Get:52 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-any armhf 2:1.25~1 [2838 B]
179s Get:53 http://ftpmaster.internal/ubuntu resolute/main armhf libsqlite3-dev armhf 3.46.1-9 [836 kB]
179s Get:54 http://ftpmaster.internal/ubuntu resolute/universe armhf libcowsql-dev armhf 1.15.9-1 [109 kB]
179s Get:55 http://ftpmaster.internal/ubuntu resolute/universe armhf libraft-dev armhf 0.22.1-1build1 [174 kB]
179s Get:56 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-gopkg-yaml.v2-dev all 2.4.3-1 [62.7 kB]
179s Get:57 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-ghodss-yaml-dev all 1.0.0+git20220118.d8423dc-2 [16.6 kB]
179s Get:58 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-google-renameio-dev all 2.0.0-2 [10.3 kB]
179s Get:59 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-mattn-go-sqlite3-dev armhf 1.14.32~ds1-1 [84.6 kB]
179s Get:60 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-rivo-uniseg-dev all 0.4.7-1 [258 kB]
179s Get:61 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-mattn-go-runewidth-dev all 0.0.16-2 [17.6 kB]
179s Get:62 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-peterh-liner-dev all 1.2.2-1 [21.3 kB]
179s Get:63 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-pkg-errors-dev all 0.9.1-3 [13.2 kB]
179s Get:64 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-rican7-retry-dev all 0.3.1-2 [7740 B]
179s Get:65 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-pmezard-go-difflib-dev all 1.0.0-3 [12.4 kB]
179s Get:66 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-shurcool-sanitized-anchor-name-dev all 1.0.0-3 [3590 B]
179s Get:67 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-russross-blackfriday-v2-dev all 2.1.0-1build1 [79.1 kB]
179s Get:68 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-cpuguy83-go-md2man-v2-dev all 2.0.4-1 [11.0 kB]
180s Get:69 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-spf13-pflag-dev all 1.0.10-1 [50.1 kB]
180s Get:70 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-golang-x-sys-dev all 0.22.0-1 [457 kB]
180s Get:71 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-fsnotify-fsnotify-dev all 1.9.0-1 [57.3 kB]
180s Get:72 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-davecgh-go-spew-dev all 1.1.1-3 [31.1 kB]
180s Get:73 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-hashicorp-hcl-dev all 1.0.0-1.1 [58.9 kB]
180s Get:74 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-magiconair-properties-dev all 1.8.7-1 [27.3 kB]
180s Get:75 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-mitchellh-mapstructure-dev all 1.5.0+git20231216.8508981-1 [28.3 kB]
180s Get:76 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-burntsushi-toml-dev all 1.5.0-1 [121 kB]
180s Get:77 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-pelletier-go-toml-dev all 1.9.5-1build1 [79.6 kB]
180s Get:78 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-pelletier-go-toml.v2-dev all 2.2.4-1 [878 kB]
180s Get:79 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-kr-fs-dev all 0.1.0-2 [6344 B]
180s Get:80 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-golang-x-term-dev all 0.22.0-1 [14.6 kB]
180s Get:81 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-golang-x-text-dev all 0.31.0-1 [4602 kB]
180s Get:82 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-golang-x-crypto-dev all 1:0.45.0-1 [1993 kB]
180s Get:83 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-pkg-sftp-dev all 1.13.9-1 [108 kB]
180s Get:84 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-spf13-afero-dev all 1.15.0-2 [49.9 kB]
180s Get:85 http://ftpmaster.internal/ubuntu resolute/main armhf tzdata-legacy all 2025c-3ubuntu3 [99.3 kB]
180s Get:86 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-spf13-cast-dev all 1.10.0-2 [20.5 kB]
180s Get:87 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-stretchr-objx-dev all 0.5.2-1 [26.5 kB]
180s Get:88 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-gopkg-yaml.v3-dev all 3.0.4-1 [78.0 kB]
180s Get:89 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-stretchr-testify-dev all 1.10.0-1 [79.6 kB]
180s Get:90 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-spf13-jwalterweatherman-dev all 1.1.0+really1.1.0-2 [6828 B]
180s Get:91 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-subosito-gotenv-dev all 1.3.0-1 [8808 B]
180s Get:92 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-gopkg-ini.v1-dev all 1.67.0-1 [40.8 kB]
180s Get:93 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-spf13-viper-dev all 1.12.0-1 [71.0 kB]
180s Get:94 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-spf13-cobra-dev all 1.8.1-1 [76.2 kB]
180s Get:95 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-golang-x-sync-dev all 0.7.0-1 [19.5 kB]
180s Get:96 http://ftpmaster.internal/ubuntu resolute/universe armhf golang-github-cowsql-go-cowsql-dev all 1.22.0-2 [97.2 kB]
180s Get:97 http://ftpmaster.internal/ubuntu resolute/main armhf dh-golang all 1.63build1 [25.0 kB]
181s Fetched 117 MB in 7s (15.9 MB/s)
181s Selecting previously unselected package m4.
181s (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 ... 68395 files and directories currently installed.)
181s Preparing to unpack .../00-m4_1.4.20-2_armhf.deb ...
181s Unpacking m4 (1.4.20-2) ...
181s Selecting previously unselected package autoconf.
181s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ...
181s Unpacking autoconf (2.72-3.1ubuntu1) ...
181s Selecting previously unselected package autotools-dev.
181s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ...
181s Unpacking autotools-dev (20240727.1) ...
181s Selecting previously unselected package automake.
181s Preparing to unpack .../03-automake_1%3a1.18.1-3build1_all.deb ...
181s Unpacking automake (1:1.18.1-3build1) ...
181s Selecting previously unselected package autopoint.
181s Preparing to unpack .../04-autopoint_0.23.2-1_all.deb ...
181s Unpacking autopoint (0.23.2-1) ...
181s Selecting previously unselected package libc-dev-bin.
181s Preparing to unpack .../05-libc-dev-bin_2.42-2ubuntu5_armhf.deb ...
181s Unpacking libc-dev-bin (2.42-2ubuntu5) ...
181s Selecting previously unselected package linux-libc-dev:armhf.
181s Preparing to unpack .../06-linux-libc-dev_6.18.0-9.9_armhf.deb ...
181s Unpacking linux-libc-dev:armhf (6.18.0-9.9) ...
182s Selecting previously unselected package libcrypt-dev:armhf.
182s Preparing to unpack .../07-libcrypt-dev_1%3a4.5.1-1_armhf.deb ...
182s Unpacking libcrypt-dev:armhf (1:4.5.1-1) ...
182s Selecting previously unselected package rpcsvc-proto.
182s Preparing to unpack .../08-rpcsvc-proto_1.4.3-1build1_armhf.deb ...
182s Unpacking rpcsvc-proto (1.4.3-1build1) ...
182s Selecting previously unselected package libc6-dev:armhf.
182s Preparing to unpack .../09-libc6-dev_2.42-2ubuntu5_armhf.deb ...
182s Unpacking libc6-dev:armhf (2.42-2ubuntu5) ...
182s Selecting previously unselected package libisl23:armhf.
182s Preparing to unpack .../10-libisl23_0.27-1build1_armhf.deb ...
182s Unpacking libisl23:armhf (0.27-1build1) ...
182s Selecting previously unselected package libmpc3:armhf.
182s Preparing to unpack .../11-libmpc3_1.3.1-2_armhf.deb ...
182s Unpacking libmpc3:armhf (1.3.1-2) ...
182s Selecting previously unselected package cpp-15-arm-linux-gnueabihf.
182s Preparing to unpack .../12-cpp-15-arm-linux-gnueabihf_15.2.0-12ubuntu1_armhf.deb ...
182s Unpacking cpp-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ...
182s Selecting previously unselected package cpp-15.
182s Preparing to unpack .../13-cpp-15_15.2.0-12ubuntu1_armhf.deb ...
182s Unpacking cpp-15 (15.2.0-12ubuntu1) ...
182s Selecting previously unselected package cpp-arm-linux-gnueabihf.
182s Preparing to unpack .../14-cpp-arm-linux-gnueabihf_4%3a15.2.0-4ubuntu1_armhf.deb ...
182s Unpacking cpp-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ...
182s Selecting previously unselected package cpp.
182s Preparing to unpack .../15-cpp_4%3a15.2.0-4ubuntu1_armhf.deb ...
182s Unpacking cpp (4:15.2.0-4ubuntu1) ...
182s Selecting previously unselected package libcc1-0:armhf.
182s Preparing to unpack .../16-libcc1-0_15.2.0-12ubuntu1_armhf.deb ...
183s Unpacking libcc1-0:armhf (15.2.0-12ubuntu1) ...
183s Selecting previously unselected package libgomp1:armhf.
183s Preparing to unpack .../17-libgomp1_15.2.0-12ubuntu1_armhf.deb ...
183s Unpacking libgomp1:armhf (15.2.0-12ubuntu1) ...
183s Selecting previously unselected package libasan8:armhf.
183s Preparing to unpack .../18-libasan8_15.2.0-12ubuntu1_armhf.deb ...
183s Unpacking libasan8:armhf (15.2.0-12ubuntu1) ...
183s Selecting previously unselected package libubsan1:armhf.
183s Preparing to unpack .../19-libubsan1_15.2.0-12ubuntu1_armhf.deb ...
183s Unpacking libubsan1:armhf (15.2.0-12ubuntu1) ...
183s Selecting previously unselected package libgcc-15-dev:armhf.
183s Preparing to unpack .../20-libgcc-15-dev_15.2.0-12ubuntu1_armhf.deb ...
183s Unpacking libgcc-15-dev:armhf (15.2.0-12ubuntu1) ...
183s Selecting previously unselected package gcc-15-arm-linux-gnueabihf.
183s Preparing to unpack .../21-gcc-15-arm-linux-gnueabihf_15.2.0-12ubuntu1_armhf.deb ...
183s Unpacking gcc-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ...
183s Selecting previously unselected package gcc-15.
184s Preparing to unpack .../22-gcc-15_15.2.0-12ubuntu1_armhf.deb ...
184s Unpacking gcc-15 (15.2.0-12ubuntu1) ...
184s Selecting previously unselected package gcc-arm-linux-gnueabihf.
184s Preparing to unpack .../23-gcc-arm-linux-gnueabihf_4%3a15.2.0-4ubuntu1_armhf.deb ...
184s Unpacking gcc-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ...
184s Selecting previously unselected package gcc.
184s Preparing to unpack .../24-gcc_4%3a15.2.0-4ubuntu1_armhf.deb ...
184s Unpacking gcc (4:15.2.0-4ubuntu1) ...
184s Selecting previously unselected package libstdc++-15-dev:armhf.
184s Preparing to unpack .../25-libstdc++-15-dev_15.2.0-12ubuntu1_armhf.deb ...
184s Unpacking libstdc++-15-dev:armhf (15.2.0-12ubuntu1) ...
184s Selecting previously unselected package g++-15-arm-linux-gnueabihf.
184s Preparing to unpack .../26-g++-15-arm-linux-gnueabihf_15.2.0-12ubuntu1_armhf.deb ...
184s Unpacking g++-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ...
184s Selecting previously unselected package g++-15.
184s Preparing to unpack .../27-g++-15_15.2.0-12ubuntu1_armhf.deb ...
184s Unpacking g++-15 (15.2.0-12ubuntu1) ...
184s Selecting previously unselected package g++-arm-linux-gnueabihf.
184s Preparing to unpack .../28-g++-arm-linux-gnueabihf_4%3a15.2.0-4ubuntu1_armhf.deb ...
184s Unpacking g++-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ...
184s Selecting previously unselected package g++.
184s Preparing to unpack .../29-g++_4%3a15.2.0-4ubuntu1_armhf.deb ...
184s Unpacking g++ (4:15.2.0-4ubuntu1) ...
184s Selecting previously unselected package build-essential.
184s Preparing to unpack .../30-build-essential_12.12ubuntu2_armhf.deb ...
184s Unpacking build-essential (12.12ubuntu2) ...
184s Selecting previously unselected package libdebhelper-perl.
184s Preparing to unpack .../31-libdebhelper-perl_13.28ubuntu1_all.deb ...
184s Unpacking libdebhelper-perl (13.28ubuntu1) ...
184s Selecting previously unselected package libtool.
185s Preparing to unpack .../32-libtool_2.5.4-9_all.deb ...
185s Unpacking libtool (2.5.4-9) ...
185s Selecting previously unselected package dh-autoreconf.
185s Preparing to unpack .../33-dh-autoreconf_21_all.deb ...
185s Unpacking dh-autoreconf (21) ...
185s Selecting previously unselected package libarchive-zip-perl.
185s Preparing to unpack .../34-libarchive-zip-perl_1.68-1_all.deb ...
185s Unpacking libarchive-zip-perl (1.68-1) ...
185s Selecting previously unselected package libfile-stripnondeterminism-perl.
185s Preparing to unpack .../35-libfile-stripnondeterminism-perl_1.15.0-1build1_all.deb ...
185s Unpacking libfile-stripnondeterminism-perl (1.15.0-1build1) ...
185s Selecting previously unselected package dh-strip-nondeterminism.
185s Preparing to unpack .../36-dh-strip-nondeterminism_1.15.0-1build1_all.deb ...
185s Unpacking dh-strip-nondeterminism (1.15.0-1build1) ...
185s Selecting previously unselected package libdw1t64:armhf.
185s Preparing to unpack .../37-libdw1t64_0.194-1_armhf.deb ...
185s Unpacking libdw1t64:armhf (0.194-1) ...
185s Selecting previously unselected package debugedit.
185s Preparing to unpack .../38-debugedit_1%3a5.2-3build1_armhf.deb ...
185s Unpacking debugedit (1:5.2-3build1) ...
185s Selecting previously unselected package dwz.
185s Preparing to unpack .../39-dwz_0.16-2_armhf.deb ...
185s Unpacking dwz (0.16-2) ...
185s Selecting previously unselected package gettext.
185s Preparing to unpack .../40-gettext_0.23.2-1_armhf.deb ...
185s Unpacking gettext (0.23.2-1) ...
185s Selecting previously unselected package intltool-debian.
185s Preparing to unpack .../41-intltool-debian_0.35.0+20060710.6build1_all.deb ...
185s Unpacking intltool-debian (0.35.0+20060710.6build1) ...
185s Selecting previously unselected package po-debconf.
185s Preparing to unpack .../42-po-debconf_1.0.22_all.deb ...
185s Unpacking po-debconf (1.0.22) ...
185s Selecting previously unselected package debhelper.
185s Preparing to unpack .../43-debhelper_13.28ubuntu1_all.deb ...
185s Unpacking debhelper (13.28ubuntu1) ...
185s Selecting previously unselected package libraft0:armhf.
185s Preparing to unpack .../44-libraft0_0.22.1-1build1_armhf.deb ...
185s Unpacking libraft0:armhf (0.22.1-1build1) ...
185s Selecting previously unselected package libcowsql0:armhf.
185s Preparing to unpack .../45-libcowsql0_1.15.9-1_armhf.deb ...
185s Unpacking libcowsql0:armhf (1.15.9-1) ...
185s Selecting previously unselected package go-cowsql.
185s Preparing to unpack .../46-go-cowsql_1.22.0-2_armhf.deb ...
185s Unpacking go-cowsql (1.22.0-2) ...
185s Selecting previously unselected package golang-1.25-src.
186s Preparing to unpack .../47-golang-1.25-src_1.25.0-0ubuntu1_all.deb ...
186s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ...
188s Selecting previously unselected package golang-1.25-go.
188s Preparing to unpack .../48-golang-1.25-go_1.25.0-0ubuntu1_armhf.deb ...
188s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ...
189s Selecting previously unselected package golang-src.
189s Preparing to unpack .../49-golang-src_2%3a1.25~1_all.deb ...
189s Unpacking golang-src (2:1.25~1) ...
189s Selecting previously unselected package golang-go:armhf.
189s Preparing to unpack .../50-golang-go_2%3a1.25~1_armhf.deb ...
189s Unpacking golang-go:armhf (2:1.25~1) ...
189s Selecting previously unselected package golang-any:armhf.
189s Preparing to unpack .../51-golang-any_2%3a1.25~1_armhf.deb ...
189s Unpacking golang-any:armhf (2:1.25~1) ...
189s Selecting previously unselected package libsqlite3-dev:armhf.
189s Preparing to unpack .../52-libsqlite3-dev_3.46.1-9_armhf.deb ...
189s Unpacking libsqlite3-dev:armhf (3.46.1-9) ...
189s Selecting previously unselected package libcowsql-dev:armhf.
189s Preparing to unpack .../53-libcowsql-dev_1.15.9-1_armhf.deb ...
189s Unpacking libcowsql-dev:armhf (1.15.9-1) ...
189s Selecting previously unselected package libraft-dev:armhf.
189s Preparing to unpack .../54-libraft-dev_0.22.1-1build1_armhf.deb ...
189s Unpacking libraft-dev:armhf (0.22.1-1build1) ...
189s Selecting previously unselected package golang-gopkg-yaml.v2-dev.
189s Preparing to unpack .../55-golang-gopkg-yaml.v2-dev_2.4.3-1_all.deb ...
189s Unpacking golang-gopkg-yaml.v2-dev (2.4.3-1) ...
189s Selecting previously unselected package golang-github-ghodss-yaml-dev.
189s Preparing to unpack .../56-golang-github-ghodss-yaml-dev_1.0.0+git20220118.d8423dc-2_all.deb ...
189s Unpacking golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ...
189s Selecting previously unselected package golang-github-google-renameio-dev.
189s Preparing to unpack .../57-golang-github-google-renameio-dev_2.0.0-2_all.deb ...
189s Unpacking golang-github-google-renameio-dev (2.0.0-2) ...
189s Selecting previously unselected package golang-github-mattn-go-sqlite3-dev:armhf.
190s Preparing to unpack .../58-golang-github-mattn-go-sqlite3-dev_1.14.32~ds1-1_armhf.deb ...
190s Unpacking golang-github-mattn-go-sqlite3-dev:armhf (1.14.32~ds1-1) ...
190s Selecting previously unselected package golang-github-rivo-uniseg-dev.
190s Preparing to unpack .../59-golang-github-rivo-uniseg-dev_0.4.7-1_all.deb ...
190s Unpacking golang-github-rivo-uniseg-dev (0.4.7-1) ...
190s Selecting previously unselected package golang-github-mattn-go-runewidth-dev.
190s Preparing to unpack .../60-golang-github-mattn-go-runewidth-dev_0.0.16-2_all.deb ...
190s Unpacking golang-github-mattn-go-runewidth-dev (0.0.16-2) ...
190s Selecting previously unselected package golang-github-peterh-liner-dev.
190s Preparing to unpack .../61-golang-github-peterh-liner-dev_1.2.2-1_all.deb ...
190s Unpacking golang-github-peterh-liner-dev (1.2.2-1) ...
190s Selecting previously unselected package golang-github-pkg-errors-dev.
190s Preparing to unpack .../62-golang-github-pkg-errors-dev_0.9.1-3_all.deb ...
190s Unpacking golang-github-pkg-errors-dev (0.9.1-3) ...
190s Selecting previously unselected package golang-github-rican7-retry-dev.
190s Preparing to unpack .../63-golang-github-rican7-retry-dev_0.3.1-2_all.deb ...
190s Unpacking golang-github-rican7-retry-dev (0.3.1-2) ...
190s Selecting previously unselected package golang-github-pmezard-go-difflib-dev.
190s Preparing to unpack .../64-golang-github-pmezard-go-difflib-dev_1.0.0-3_all.deb ...
190s Unpacking golang-github-pmezard-go-difflib-dev (1.0.0-3) ...
190s Selecting previously unselected package golang-github-shurcool-sanitized-anchor-name-dev.
190s Preparing to unpack .../65-golang-github-shurcool-sanitized-anchor-name-dev_1.0.0-3_all.deb ...
190s Unpacking golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ...
190s Selecting previously unselected package golang-github-russross-blackfriday-v2-dev.
190s Preparing to unpack .../66-golang-github-russross-blackfriday-v2-dev_2.1.0-1build1_all.deb ...
190s Unpacking golang-github-russross-blackfriday-v2-dev (2.1.0-1build1) ...
190s Selecting previously unselected package golang-github-cpuguy83-go-md2man-v2-dev.
190s Preparing to unpack .../67-golang-github-cpuguy83-go-md2man-v2-dev_2.0.4-1_all.deb ...
190s Unpacking golang-github-cpuguy83-go-md2man-v2-dev (2.0.4-1) ...
190s Selecting previously unselected package golang-github-spf13-pflag-dev.
190s Preparing to unpack .../68-golang-github-spf13-pflag-dev_1.0.10-1_all.deb ...
190s Unpacking golang-github-spf13-pflag-dev (1.0.10-1) ...
190s Selecting previously unselected package golang-golang-x-sys-dev.
190s Preparing to unpack .../69-golang-golang-x-sys-dev_0.22.0-1_all.deb ...
190s Unpacking golang-golang-x-sys-dev (0.22.0-1) ...
190s Selecting previously unselected package golang-github-fsnotify-fsnotify-dev.
190s Preparing to unpack .../70-golang-github-fsnotify-fsnotify-dev_1.9.0-1_all.deb ...
190s Unpacking golang-github-fsnotify-fsnotify-dev (1.9.0-1) ...
190s Selecting previously unselected package golang-github-davecgh-go-spew-dev.
190s Preparing to unpack .../71-golang-github-davecgh-go-spew-dev_1.1.1-3_all.deb ...
190s Unpacking golang-github-davecgh-go-spew-dev (1.1.1-3) ...
190s Selecting previously unselected package golang-github-hashicorp-hcl-dev.
190s Preparing to unpack .../72-golang-github-hashicorp-hcl-dev_1.0.0-1.1_all.deb ...
190s Unpacking golang-github-hashicorp-hcl-dev (1.0.0-1.1) ...
190s Selecting previously unselected package golang-github-magiconair-properties-dev.
191s Preparing to unpack .../73-golang-github-magiconair-properties-dev_1.8.7-1_all.deb ...
191s Unpacking golang-github-magiconair-properties-dev (1.8.7-1) ...
191s Selecting previously unselected package golang-github-mitchellh-mapstructure-dev.
191s Preparing to unpack .../74-golang-github-mitchellh-mapstructure-dev_1.5.0+git20231216.8508981-1_all.deb ...
191s Unpacking golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ...
191s Selecting previously unselected package golang-github-burntsushi-toml-dev.
191s Preparing to unpack .../75-golang-github-burntsushi-toml-dev_1.5.0-1_all.deb ...
191s Unpacking golang-github-burntsushi-toml-dev (1.5.0-1) ...
191s Selecting previously unselected package golang-github-pelletier-go-toml-dev.
191s Preparing to unpack .../76-golang-github-pelletier-go-toml-dev_1.9.5-1build1_all.deb ...
191s Unpacking golang-github-pelletier-go-toml-dev (1.9.5-1build1) ...
191s Selecting previously unselected package golang-github-pelletier-go-toml.v2-dev.
191s Preparing to unpack .../77-golang-github-pelletier-go-toml.v2-dev_2.2.4-1_all.deb ...
191s Unpacking golang-github-pelletier-go-toml.v2-dev (2.2.4-1) ...
191s Selecting previously unselected package golang-github-kr-fs-dev.
191s Preparing to unpack .../78-golang-github-kr-fs-dev_0.1.0-2_all.deb ...
191s Unpacking golang-github-kr-fs-dev (0.1.0-2) ...
191s Selecting previously unselected package golang-golang-x-term-dev.
191s Preparing to unpack .../79-golang-golang-x-term-dev_0.22.0-1_all.deb ...
191s Unpacking golang-golang-x-term-dev (0.22.0-1) ...
191s Selecting previously unselected package golang-golang-x-text-dev.
191s Preparing to unpack .../80-golang-golang-x-text-dev_0.31.0-1_all.deb ...
191s Unpacking golang-golang-x-text-dev (0.31.0-1) ...
192s Selecting previously unselected package golang-golang-x-crypto-dev.
192s Preparing to unpack .../81-golang-golang-x-crypto-dev_1%3a0.45.0-1_all.deb ...
192s Unpacking golang-golang-x-crypto-dev (1:0.45.0-1) ...
192s Selecting previously unselected package golang-github-pkg-sftp-dev.
192s Preparing to unpack .../82-golang-github-pkg-sftp-dev_1.13.9-1_all.deb ...
192s Unpacking golang-github-pkg-sftp-dev (1.13.9-1) ...
192s Selecting previously unselected package golang-github-spf13-afero-dev.
192s Preparing to unpack .../83-golang-github-spf13-afero-dev_1.15.0-2_all.deb ...
192s Unpacking golang-github-spf13-afero-dev (1.15.0-2) ...
192s Selecting previously unselected package tzdata-legacy.
192s Preparing to unpack .../84-tzdata-legacy_2025c-3ubuntu3_all.deb ...
192s Unpacking tzdata-legacy (2025c-3ubuntu3) ...
192s Selecting previously unselected package golang-github-spf13-cast-dev.
192s Preparing to unpack .../85-golang-github-spf13-cast-dev_1.10.0-2_all.deb ...
192s Unpacking golang-github-spf13-cast-dev (1.10.0-2) ...
192s Selecting previously unselected package golang-github-stretchr-objx-dev.
192s Preparing to unpack .../86-golang-github-stretchr-objx-dev_0.5.2-1_all.deb ...
192s Unpacking golang-github-stretchr-objx-dev (0.5.2-1) ...
192s Selecting previously unselected package golang-gopkg-yaml.v3-dev.
192s Preparing to unpack .../87-golang-gopkg-yaml.v3-dev_3.0.4-1_all.deb ...
192s Unpacking golang-gopkg-yaml.v3-dev (3.0.4-1) ...
192s Selecting previously unselected package golang-github-stretchr-testify-dev.
192s Preparing to unpack .../88-golang-github-stretchr-testify-dev_1.10.0-1_all.deb ...
192s Unpacking golang-github-stretchr-testify-dev (1.10.0-1) ...
192s Selecting previously unselected package golang-github-spf13-jwalterweatherman-dev.
192s Preparing to unpack .../89-golang-github-spf13-jwalterweatherman-dev_1.1.0+really1.1.0-2_all.deb ...
192s Unpacking golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ...
192s Selecting previously unselected package golang-github-subosito-gotenv-dev.
193s Preparing to unpack .../90-golang-github-subosito-gotenv-dev_1.3.0-1_all.deb ...
193s Unpacking golang-github-subosito-gotenv-dev (1.3.0-1) ...
193s Selecting previously unselected package golang-gopkg-ini.v1-dev.
193s Preparing to unpack .../91-golang-gopkg-ini.v1-dev_1.67.0-1_all.deb ...
193s Unpacking golang-gopkg-ini.v1-dev (1.67.0-1) ...
193s Selecting previously unselected package golang-github-spf13-viper-dev.
193s Preparing to unpack .../92-golang-github-spf13-viper-dev_1.12.0-1_all.deb ...
193s Unpacking golang-github-spf13-viper-dev (1.12.0-1) ...
193s Selecting previously unselected package golang-github-spf13-cobra-dev.
193s Preparing to unpack .../93-golang-github-spf13-cobra-dev_1.8.1-1_all.deb ...
193s Unpacking golang-github-spf13-cobra-dev (1.8.1-1) ...
193s Selecting previously unselected package golang-golang-x-sync-dev.
193s Preparing to unpack .../94-golang-golang-x-sync-dev_0.7.0-1_all.deb ...
193s Unpacking golang-golang-x-sync-dev (0.7.0-1) ...
193s Selecting previously unselected package golang-github-cowsql-go-cowsql-dev.
193s Preparing to unpack .../95-golang-github-cowsql-go-cowsql-dev_1.22.0-2_all.deb ...
193s Unpacking golang-github-cowsql-go-cowsql-dev (1.22.0-2) ...
193s Selecting previously unselected package dh-golang.
193s Preparing to unpack .../96-dh-golang_1.63build1_all.deb ...
193s Unpacking dh-golang (1.63build1) ...
193s Setting up golang-github-pelletier-go-toml.v2-dev (2.2.4-1) ...
193s Setting up golang-gopkg-yaml.v2-dev (2.4.3-1) ...
193s Setting up golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ...
193s Setting up golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ...
193s Setting up golang-github-stretchr-objx-dev (0.5.2-1) ...
193s Setting up golang-github-pkg-errors-dev (0.9.1-3) ...
193s Setting up libarchive-zip-perl (1.68-1) ...
193s Setting up golang-gopkg-yaml.v3-dev (3.0.4-1) ...
193s Setting up libdebhelper-perl (13.28ubuntu1) ...
193s Setting up golang-github-burntsushi-toml-dev (1.5.0-1) ...
193s Setting up golang-golang-x-sys-dev (0.22.0-1) ...
193s Setting up golang-github-kr-fs-dev (0.1.0-2) ...
193s Setting up golang-github-subosito-gotenv-dev (1.3.0-1) ...
193s Setting up linux-libc-dev:armhf (6.18.0-9.9) ...
193s Setting up golang-github-pmezard-go-difflib-dev (1.0.0-3) ...
193s Setting up m4 (1.4.20-2) ...
193s Setting up golang-golang-x-term-dev (0.22.0-1) ...
193s Setting up libgomp1:armhf (15.2.0-12ubuntu1) ...
193s Setting up tzdata-legacy (2025c-3ubuntu3) ...
193s Setting up libdw1t64:armhf (0.194-1) ...
193s Setting up golang-github-google-renameio-dev (2.0.0-2) ...
193s Setting up golang-github-spf13-pflag-dev (1.0.10-1) ...
193s Setting up golang-github-davecgh-go-spew-dev (1.1.1-3) ...
193s Setting up autotools-dev (20240727.1) ...
193s Setting up rpcsvc-proto (1.4.3-1build1) ...
193s Setting up libraft0:armhf (0.22.1-1build1) ...
193s Setting up golang-github-stretchr-testify-dev (1.10.0-1) ...
193s Setting up libraft-dev:armhf (0.22.1-1build1) ...
193s Setting up golang-gopkg-ini.v1-dev (1.67.0-1) ...
193s Setting up libmpc3:armhf (1.3.1-2) ...
193s Setting up golang-golang-x-sync-dev (0.7.0-1) ...
193s Setting up autopoint (0.23.2-1) ...
193s Setting up golang-github-magiconair-properties-dev (1.8.7-1) ...
193s Setting up golang-github-hashicorp-hcl-dev (1.0.0-1.1) ...
193s Setting up golang-github-rican7-retry-dev (0.3.1-2) ...
193s Setting up autoconf (2.72-3.1ubuntu1) ...
193s Setting up golang-github-rivo-uniseg-dev (0.4.7-1) ...
193s Setting up libubsan1:armhf (15.2.0-12ubuntu1) ...
193s Setting up dwz (0.16-2) ...
193s Setting up golang-1.25-src (1.25.0-0ubuntu1) ...
193s Setting up golang-golang-x-text-dev (0.31.0-1) ...
193s Setting up libcrypt-dev:armhf (1:4.5.1-1) ...
193s Setting up libasan8:armhf (15.2.0-12ubuntu1) ...
193s Setting up golang-github-spf13-cast-dev (1.10.0-2) ...
193s Setting up golang-github-fsnotify-fsnotify-dev (1.9.0-1) ...
193s Setting up debugedit (1:5.2-3build1) ...
193s Setting up golang-golang-x-crypto-dev (1:0.45.0-1) ...
193s Setting up golang-github-pelletier-go-toml-dev (1.9.5-1build1) ...
193s Setting up golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ...
193s Setting up libisl23:armhf (0.27-1build1) ...
193s Setting up golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ...
193s Setting up libc-dev-bin (2.42-2ubuntu5) ...
193s Setting up golang-src (2:1.25~1) ...
193s Setting up cpp-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ...
193s Setting up libcc1-0:armhf (15.2.0-12ubuntu1) ...
193s Setting up cpp-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ...
193s Setting up automake (1:1.18.1-3build1) ...
193s update-alternatives: using /usr/bin/automake-1.18 to provide /usr/bin/automake (automake) in auto mode
193s Setting up libfile-stripnondeterminism-perl (1.15.0-1build1) ...
193s Setting up golang-1.25-go (1.25.0-0ubuntu1) ...
193s Setting up golang-github-mattn-go-runewidth-dev (0.0.16-2) ...
193s Setting up gettext (0.23.2-1) ...
193s Setting up libgcc-15-dev:armhf (15.2.0-12ubuntu1) ...
193s Setting up golang-github-russross-blackfriday-v2-dev (2.1.0-1build1) ...
193s Setting up gcc-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ...
193s Setting up golang-go:armhf (2:1.25~1) ...
193s Setting up golang-github-pkg-sftp-dev (1.13.9-1) ...
193s Setting up libcowsql0:armhf (1.15.9-1) ...
193s Setting up golang-any:armhf (2:1.25~1) ...
193s Setting up intltool-debian (0.35.0+20060710.6build1) ...
193s Setting up golang-github-peterh-liner-dev (1.2.2-1) ...
193s Setting up dh-strip-nondeterminism (1.15.0-1build1) ...
193s Setting up cpp-15 (15.2.0-12ubuntu1) ...
193s Setting up cpp (4:15.2.0-4ubuntu1) ...
193s Setting up libc6-dev:armhf (2.42-2ubuntu5) ...
193s Setting up golang-github-spf13-afero-dev (1.15.0-2) ...
193s Setting up gcc-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ...
193s Setting up golang-github-cpuguy83-go-md2man-v2-dev (2.0.4-1) ...
193s Setting up po-debconf (1.0.22) ...
193s Setting up go-cowsql (1.22.0-2) ...
193s Setting up gcc-15 (15.2.0-12ubuntu1) ...
193s Setting up libstdc++-15-dev:armhf (15.2.0-12ubuntu1) ...
193s Setting up libsqlite3-dev:armhf (3.46.1-9) ...
193s Setting up golang-github-spf13-viper-dev (1.12.0-1) ...
193s Setting up libtool (2.5.4-9) ...
193s Setting up g++-15-arm-linux-gnueabihf (15.2.0-12ubuntu1) ...
193s Setting up libcowsql-dev:armhf (1.15.9-1) ...
193s Setting up gcc (4:15.2.0-4ubuntu1) ...
193s Setting up dh-autoreconf (21) ...
193s Setting up golang-github-mattn-go-sqlite3-dev:armhf (1.14.32~ds1-1) ...
193s Setting up golang-github-spf13-cobra-dev (1.8.1-1) ...
193s Setting up g++-15 (15.2.0-12ubuntu1) ...
193s Setting up golang-github-cowsql-go-cowsql-dev (1.22.0-2) ...
193s Setting up g++-arm-linux-gnueabihf (4:15.2.0-4ubuntu1) ...
193s Setting up debhelper (13.28ubuntu1) ...
193s Setting up g++ (4:15.2.0-4ubuntu1) ...
193s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode
193s Setting up build-essential (12.12ubuntu2) ...
193s Setting up dh-golang (1.63build1) ...
193s Processing triggers for man-db (2.13.1-1) ...
194s Processing triggers for install-info (7.2-5) ...
194s Processing triggers for libc-bin (2.42-2ubuntu5) ...
205s autopkgtest [03:32:34]: test dh-golang-autopkgtest: /usr/bin/dh_golang_autopkgtest
205s autopkgtest [03:32:34]: test dh-golang-autopkgtest: [-----------------------
207s [info] Testing github.com/cowsql/go-cowsql...
207s [info] Source code installed by binary package, overriding dh_auto_configure...
208s dh build --builddirectory=_build --buildsystem=golang --with=golang
208s    dh_update_autotools_config -O--builddirectory=_build -O--buildsystem=golang
208s    dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang
208s    debian/rules override_dh_auto_configure
208s make[1]: Entering directory '/tmp/autopkgtest.uwVASc/autopkgtest_tmp'
208s mkdir -p "_build"
208s # remove existing symlinks, since "cp -a" won't convert them
208s find "_build" -type l -exec rm "{}" \;
208s cp -a /usr/share/gocode/src "_build"
208s make[1]: Leaving directory '/tmp/autopkgtest.uwVASc/autopkgtest_tmp'
208s    dh_auto_build -O--builddirectory=_build -O--buildsystem=golang
209s 	cd _build && go install -trimpath -v -p 8 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
209s internal/goarch
209s internal/unsafeheader
209s internal/byteorder
209s internal/godebugs
209s internal/goos
209s internal/coverage/rtcov
209s internal/goexperiment
209s internal/cpu
209s internal/profilerecord
209s internal/asan
209s internal/msan
209s internal/trace/tracev2
209s internal/runtime/math
209s internal/abi
209s internal/runtime/gc
209s internal/runtime/syscall
209s internal/runtime/sys
209s sync/atomic
209s internal/runtime/strconv
209s math/bits
209s internal/itoa
209s cmp
209s internal/bytealg
209s internal/chacha8rand
209s internal/runtime/atomic
209s unicode/utf8
209s unicode
209s container/list
209s crypto/internal/fips140/alias
209s internal/runtime/cgroup
209s math
209s internal/stringslite
209s crypto/internal/fips140deps/byteorder
209s crypto/internal/fips140deps/cpu
209s crypto/internal/boring/sig
209s unicode/utf16
209s vendor/golang.org/x/crypto/cryptobyte/asn1
209s vendor/golang.org/x/crypto/internal/alias
209s internal/nettrace
209s internal/runtime/exithook
209s encoding
209s log/internal
209s container/ring
209s github.com/rivo/uniseg
209s internal/race
209s internal/synctest
209s internal/sync
209s internal/runtime/maps
210s runtime
214s internal/reflectlite
214s iter
214s crypto/internal/fips140/subtle
214s sync
214s weak
214s slices
214s maps
214s crypto/subtle
215s internal/bisect
215s internal/testlog
215s crypto/internal/fips140cache
215s internal/singleflight
215s unique
215s errors
215s sort
215s runtime/cgo
215s internal/oserror
215s path
215s io
215s strconv
215s syscall
215s internal/godebug
215s bytes
215s hash
215s strings
215s math/rand/v2
215s vendor/golang.org/x/net/dns/dnsmessage
215s crypto/internal/fips140deps/godebug
215s reflect
215s crypto
215s crypto/internal/randutil
215s math/rand
215s encoding/base64
215s net/netip
215s crypto/internal/fips140
215s crypto/internal/impl
215s encoding/pem
215s crypto/internal/fips140/sha256
215s crypto/internal/fips140/sha3
216s crypto/internal/fips140/sha512
216s bufio
216s crypto/sha3
216s crypto/internal/fips140/hmac
216s regexp/syntax
216s hash/crc32
216s crypto/internal/fips140hash
216s time
216s internal/syscall/unix
216s internal/syscall/execenv
216s crypto/internal/fips140/check
216s vendor/golang.org/x/text/transform
216s net/http/internal/ascii
216s crypto/internal/fips140/aes
216s crypto/internal/fips140/nistec/fiat
216s crypto/internal/fips140/edwards25519/field
216s crypto/internal/fips140/bigmod
216s crypto/internal/fips140/hkdf
216s crypto/internal/fips140/edwards25519
216s crypto/internal/fips140/tls12
216s crypto/internal/fips140/tls13
216s crypto/fips140
216s crypto/tls/internal/fips140tls
216s regexp
217s context
217s io/fs
217s internal/poll
217s github.com/Rican7/retry/backoff
217s github.com/Rican7/retry/jitter
217s github.com/Rican7/retry/strategy
217s github.com/Rican7/retry
217s golang.org/x/sync/semaphore
217s internal/filepathlite
217s os
218s internal/fmtsort
218s encoding/binary
218s crypto/internal/fips140/nistec
218s vendor/golang.org/x/crypto/internal/poly1305
218s golang.org/x/sys/unix
218s crypto/internal/sysrand
218s internal/sysinfo
218s path/filepath
218s io/ioutil
218s fmt
218s net
218s crypto/internal/entropy
218s crypto/internal/fips140/drbg
218s github.com/mattn/go-runewidth
218s os/signal
218s crypto/internal/fips140/aes/gcm
218s crypto/internal/fips140only
218s crypto/internal/fips140/ecdh
218s crypto/internal/fips140/ecdsa
219s crypto/internal/fips140/ed25519
219s crypto/hkdf
219s crypto/internal/fips140/mlkem
219s crypto/cipher
219s crypto/md5
219s crypto/rc4
219s crypto/internal/fips140/rsa
219s github.com/google/renameio
219s crypto/internal/boring
219s crypto/des
219s vendor/golang.org/x/crypto/chacha20
219s vendor/golang.org/x/crypto/chacha20poly1305
219s crypto/aes
219s crypto/ecdh
219s crypto/sha512
219s crypto/hmac
219s crypto/sha1
219s math/big
219s crypto/sha256
219s encoding/hex
219s net/url
219s database/sql/driver
219s flag
219s runtime/debug
220s runtime/trace
220s database/sql
220s github.com/pkg/errors
220s log
220s gopkg.in/yaml.v2
220s testing
220s encoding/json
220s github.com/peterh/liner
220s encoding/csv
220s text/template/parse
221s compress/flate
221s github.com/mattn/go-sqlite3
221s text/template
221s compress/gzip
221s crypto/elliptic
221s crypto/internal/boring/bbig
221s encoding/asn1
221s crypto/rand
222s crypto/ed25519
222s crypto/internal/hpke
222s crypto/rsa
222s crypto/dsa
222s vendor/golang.org/x/text/unicode/bidi
222s vendor/golang.org/x/text/unicode/norm
222s vendor/golang.org/x/net/http2/hpack
222s vendor/golang.org/x/crypto/cryptobyte
222s crypto/x509/pkix
222s github.com/cowsql/go-cowsql/logging
222s mime
222s mime/quotedprintable
223s net/http/internal
223s vendor/golang.org/x/text/secure/bidirule
223s crypto/ecdsa
223s vendor/golang.org/x/net/idna
224s vendor/golang.org/x/net/http/httpproxy
224s net/textproto
224s github.com/cowsql/go-cowsql/internal/protocol
224s crypto/x509
224s github.com/spf13/pflag
224s mime/multipart
224s vendor/golang.org/x/net/http/httpguts
224s github.com/cowsql/go-cowsql/internal/bindings
225s crypto/tls
225s github.com/spf13/cobra
227s net/http/httptrace
227s net/http/internal/httpcommon
228s net/http
228s github.com/cowsql/go-cowsql/client
228s github.com/cowsql/go-cowsql
228s github.com/cowsql/go-cowsql/driver
228s github.com/cowsql/go-cowsql/internal/shell
228s github.com/cowsql/go-cowsql/app
229s github.com/cowsql/go-cowsql/benchmark
229s github.com/cowsql/go-cowsql/cmd/cowsql
229s github.com/cowsql/go-cowsql/cmd/cowsql-benchmark
231s github.com/cowsql/go-cowsql/cmd/cowsql-demo
234s    create-stamp debian/debhelper-build-stamp
234s dh_auto_test -O--builddirectory=_build -O--buildsystem=golang
234s 	cd _build && go test -vet=off -v -p 8 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
264s ?   	github.com/cowsql/go-cowsql	[no test files]
386s === RUN   TestNew_PristineDefault
386s --- PASS: TestNew_PristineDefault (0.10s)
386s === RUN   TestNew_PristineJoiner
386s     app_test.go:1117: 03:33:02.740 - 2: DEBUG: new connection from 127.0.0.1:55998
386s     app_test.go:1117: 03:33:02.778 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.792 - 2: DEBUG: new connection from 127.0.0.1:56014
386s     app_test.go:1117: 03:33:02.806 - 2: DEBUG: new connection from 127.0.0.1:56026
386s     app_test.go:1117: 03:33:02.834 - 3: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.861 - 2: DEBUG: new connection from 127.0.0.1:56040
386s     app_test.go:1117: 03:33:02.861 - 3: DEBUG: new connection from 127.0.0.1:56778
386s     app_test.go:1117: 03:33:02.888 - 2: DEBUG: new connection from 127.0.0.1:56042
386s     app_test.go:1117: 03:33:02.917 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestNew_PristineJoiner (0.33s)
386s === RUN   TestNew_JoinerRestart
386s     app_test.go:1117: 03:33:02.032 - 4: DEBUG: new connection from 127.0.0.1:56058
386s     app_test.go:1117: 03:33:02.056 - 4: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.061 - 4: DEBUG: new connection from 127.0.0.1:56070
386s     app_test.go:1117: 03:33:02.142 - 4: DEBUG: new connection from 127.0.0.1:56086
386s     app_test.go:1117: 03:33:02.161 - 5: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.185 - 4: DEBUG: new connection from 127.0.0.1:56096
386s     app_test.go:1117: 03:33:02.186 - 5: DEBUG: new connection from 127.0.0.1:56794
386s     app_test.go:1117: 03:33:02.265 - 4: DEBUG: new connection from 127.0.0.1:56104
386s     app_test.go:1117: 03:33:02.289 - 6: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.314 - 4: DEBUG: new connection from 127.0.0.1:56110
386s     app_test.go:1117: 03:33:02.314 - 6: DEBUG: new connection from 127.0.0.1:56798
386s --- PASS: TestNew_JoinerRestart (0.42s)
386s === RUN   TestNew_SecondJoiner
386s     app_test.go:1117: 03:33:02.450 - 7: DEBUG: new connection from 127.0.0.1:56120
386s     app_test.go:1117: 03:33:02.467 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.475 - 7: DEBUG: new connection from 127.0.0.1:56136
386s     app_test.go:1117: 03:33:02.485 - 7: DEBUG: new connection from 127.0.0.1:56140
386s     app_test.go:1117: 03:33:02.514 - 8: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.529 - 8: DEBUG: new connection from 127.0.0.1:56802
386s     app_test.go:1117: 03:33:02.530 - 7: DEBUG: new connection from 127.0.0.1:56156
386s     app_test.go:1117: 03:33:02.594 - 7: DEBUG: new connection from 127.0.0.1:56172
386s     app_test.go:1117: 03:33:02.621 - 9: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.639 - 7: DEBUG: new connection from 127.0.0.1:56178
386s     app_test.go:1117: 03:33:02.641 - 9: DEBUG: new connection from 127.0.0.1:50724
386s     app_test.go:1117: 03:33:02.642 - 8: DEBUG: new connection from 127.0.0.1:56818
386s     app_test.go:1117: 03:33:02.671 - 9: DEBUG: new connection from 127.0.0.1:50728
386s     app_test.go:1117: 03:33:02.727 - 7: DEBUG: new connection from 127.0.0.1:56192
386s     app_test.go:1117: 03:33:02.768 - 8: DEBUG: new connection from 127.0.0.1:56820
386s     app_test.go:1117: 03:33:02.808 - 7: DEBUG: new connection from 127.0.0.1:56206
386s     app_test.go:1117: 03:33:02.858 - 7: DEBUG: new connection from 127.0.0.1:56220
386s     app_test.go:1117: 03:33:02.877 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestNew_SecondJoiner (0.58s)
386s === RUN   TestNew_ThirdJoiner
386s     app_test.go:1117: 03:33:02.017 - 10: DEBUG: new connection from 127.0.0.1:56232
386s     app_test.go:1117: 03:33:02.041 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.045 - 10: DEBUG: new connection from 127.0.0.1:56244
386s     app_test.go:1117: 03:33:02.131 - 10: DEBUG: new connection from 127.0.0.1:56250
386s     app_test.go:1117: 03:33:02.159 - 11: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.350 - 11: DEBUG: new connection from 127.0.0.1:56824
386s     app_test.go:1117: 03:33:02.350 - 10: DEBUG: new connection from 127.0.0.1:56264
386s     app_test.go:1117: 03:33:02.423 - 10: DEBUG: new connection from 127.0.0.1:56280
386s     app_test.go:1117: 03:33:02.442 - 12: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.468 - 12: DEBUG: new connection from 127.0.0.1:50740
386s     app_test.go:1117: 03:33:02.468 - 11: DEBUG: new connection from 127.0.0.1:56840
386s     app_test.go:1117: 03:33:02.475 - 10: DEBUG: new connection from 127.0.0.1:56294
386s     app_test.go:1117: 03:33:02.506 - 12: DEBUG: new connection from 127.0.0.1:50754
386s     app_test.go:1117: 03:33:02.540 - 10: DEBUG: new connection from 127.0.0.1:56306
386s     app_test.go:1117: 03:33:02.569 - 11: DEBUG: new connection from 127.0.0.1:56844
386s     app_test.go:1117: 03:33:02.604 - 10: DEBUG: new connection from 127.0.0.1:56310
386s     app_test.go:1117: 03:33:02.685 - 10: DEBUG: new connection from 127.0.0.1:56318
386s     app_test.go:1117: 03:33:02.706 - 13: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.733 - 12: DEBUG: new connection from 127.0.0.1:50762
386s     app_test.go:1117: 03:33:02.733 - 11: DEBUG: new connection from 127.0.0.1:56856
386s     app_test.go:1117: 03:33:02.733 - 13: DEBUG: new connection from 127.0.0.1:54440
386s     app_test.go:1117: 03:33:02.734 - 10: DEBUG: new connection from 127.0.0.1:56326
386s     app_test.go:1117: 03:33:02.768 - 13: DEBUG: new connection from 127.0.0.1:54448
386s     app_test.go:1117: 03:33:02.771 - 10: DEBUG: new connection from 127.0.0.1:56330
386s     app_test.go:1117: 03:33:02.795 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestNew_ThirdJoiner (0.97s)
386s === RUN   TestNew_FourthJoiner
386s     app_test.go:1117: 03:33:02.182 - 14: DEBUG: new connection from 127.0.0.1:56344
386s     app_test.go:1117: 03:33:02.197 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.209 - 14: DEBUG: new connection from 127.0.0.1:56350
386s     app_test.go:1117: 03:33:02.285 - 14: DEBUG: new connection from 127.0.0.1:56364
386s     app_test.go:1117: 03:33:02.299 - 15: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.324 - 15: DEBUG: new connection from 127.0.0.1:56872
386s     app_test.go:1117: 03:33:02.325 - 14: DEBUG: new connection from 127.0.0.1:56366
386s     app_test.go:1117: 03:33:02.404 - 14: DEBUG: new connection from 127.0.0.1:56378
386s     app_test.go:1117: 03:33:02.420 - 16: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.451 - 16: DEBUG: new connection from 127.0.0.1:50772
386s     app_test.go:1117: 03:33:02.452 - 15: DEBUG: new connection from 127.0.0.1:56888
386s     app_test.go:1117: 03:33:02.457 - 14: DEBUG: new connection from 127.0.0.1:56386
386s     app_test.go:1117: 03:33:02.474 - 16: DEBUG: new connection from 127.0.0.1:50778
386s     app_test.go:1117: 03:33:02.504 - 14: DEBUG: new connection from 127.0.0.1:56388
386s     app_test.go:1117: 03:33:02.550 - 15: DEBUG: new connection from 127.0.0.1:56898
386s     app_test.go:1117: 03:33:02.584 - 14: DEBUG: new connection from 127.0.0.1:56390
386s     app_test.go:1117: 03:33:02.675 - 14: DEBUG: new connection from 127.0.0.1:56392
386s     app_test.go:1117: 03:33:02.694 - 17: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.716 - 16: DEBUG: new connection from 127.0.0.1:50790
386s     app_test.go:1117: 03:33:02.716 - 14: DEBUG: new connection from 127.0.0.1:56394
386s     app_test.go:1117: 03:33:02.716 - 17: DEBUG: new connection from 127.0.0.1:54462
386s     app_test.go:1117: 03:33:02.718 - 15: DEBUG: new connection from 127.0.0.1:56902
386s     app_test.go:1117: 03:33:02.747 - 17: DEBUG: new connection from 127.0.0.1:54478
386s     app_test.go:1117: 03:33:02.793 - 14: DEBUG: new connection from 127.0.0.1:56410
386s     app_test.go:1117: 03:33:02.817 - 14: DEBUG: new connection from 127.0.0.1:56416
386s     app_test.go:1117: 03:33:02.836 - 18: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.855 - 17: DEBUG: new connection from 127.0.0.1:54488
386s     app_test.go:1117: 03:33:02.856 - 14: DEBUG: new connection from 127.0.0.1:56418
386s     app_test.go:1117: 03:33:02.856 - 16: DEBUG: new connection from 127.0.0.1:50800
386s     app_test.go:1117: 03:33:02.856 - 18: DEBUG: new connection from 127.0.0.1:41762
386s     app_test.go:1117: 03:33:02.858 - 15: DEBUG: new connection from 127.0.0.1:56904
386s     app_test.go:1117: 03:33:02.885 - 18: DEBUG: new connection from 127.0.0.1:41764
386s     app_test.go:1117: 03:33:02.896 - 14: DEBUG: new connection from 127.0.0.1:56422
386s     app_test.go:1117: 03:33:02.913 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestNew_FourthJoiner (1.09s)
386s === RUN   TestNew_FifthJoiner
386s     app_test.go:1117: 03:33:02.060 - 19: DEBUG: new connection from 127.0.0.1:56426
386s     app_test.go:1117: 03:33:02.080 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.086 - 19: DEBUG: new connection from 127.0.0.1:56430
386s     app_test.go:1117: 03:33:02.148 - 19: DEBUG: new connection from 127.0.0.1:56444
386s     app_test.go:1117: 03:33:02.162 - 20: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.173 - 19: DEBUG: new connection from 127.0.0.1:56454
386s     app_test.go:1117: 03:33:02.173 - 20: DEBUG: new connection from 127.0.0.1:56908
386s     app_test.go:1117: 03:33:02.235 - 19: DEBUG: new connection from 127.0.0.1:56466
386s     app_test.go:1117: 03:33:02.254 - 21: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.268 - 20: DEBUG: new connection from 127.0.0.1:56922
386s     app_test.go:1117: 03:33:02.269 - 21: DEBUG: new connection from 127.0.0.1:50810
386s     app_test.go:1117: 03:33:02.269 - 19: DEBUG: new connection from 127.0.0.1:56470
386s     app_test.go:1117: 03:33:02.287 - 21: DEBUG: new connection from 127.0.0.1:50822
386s     app_test.go:1117: 03:33:02.323 - 19: DEBUG: new connection from 127.0.0.1:56486
386s     app_test.go:1117: 03:33:02.363 - 20: DEBUG: new connection from 127.0.0.1:56926
386s     app_test.go:1117: 03:33:02.398 - 19: DEBUG: new connection from 127.0.0.1:56488
386s     app_test.go:1117: 03:33:02.475 - 19: DEBUG: new connection from 127.0.0.1:56492
386s     app_test.go:1117: 03:33:02.487 - 22: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.505 - 20: DEBUG: new connection from 127.0.0.1:56934
386s     app_test.go:1117: 03:33:02.506 - 22: DEBUG: new connection from 127.0.0.1:54490
386s     app_test.go:1117: 03:33:02.506 - 19: DEBUG: new connection from 127.0.0.1:56494
386s     app_test.go:1117: 03:33:02.507 - 21: DEBUG: new connection from 127.0.0.1:50830
386s     app_test.go:1117: 03:33:02.524 - 22: DEBUG: new connection from 127.0.0.1:54498
386s     app_test.go:1117: 03:33:02.577 - 19: DEBUG: new connection from 127.0.0.1:56500
386s     app_test.go:1117: 03:33:02.607 - 19: DEBUG: new connection from 127.0.0.1:56516
386s     app_test.go:1117: 03:33:02.625 - 23: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.641 - 20: DEBUG: new connection from 127.0.0.1:56944
386s     app_test.go:1117: 03:33:02.641 - 19: DEBUG: new connection from 127.0.0.1:56524
386s     app_test.go:1117: 03:33:02.642 - 21: DEBUG: new connection from 127.0.0.1:50836
386s     app_test.go:1117: 03:33:02.642 - 23: DEBUG: new connection from 127.0.0.1:41776
386s     app_test.go:1117: 03:33:02.642 - 22: DEBUG: new connection from 127.0.0.1:54514
386s     app_test.go:1117: 03:33:02.661 - 23: DEBUG: new connection from 127.0.0.1:41780
386s     app_test.go:1117: 03:33:02.708 - 19: DEBUG: new connection from 127.0.0.1:56528
386s     app_test.go:1117: 03:33:02.734 - 19: DEBUG: new connection from 127.0.0.1:56532
386s     app_test.go:1117: 03:33:02.754 - 24: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.788 - 19: DEBUG: new connection from 127.0.0.1:56534
386s     app_test.go:1117: 03:33:02.788 - 20: DEBUG: new connection from 127.0.0.1:56946
386s     app_test.go:1117: 03:33:02.788 - 22: DEBUG: new connection from 127.0.0.1:54526
386s     app_test.go:1117: 03:33:02.788 - 21: DEBUG: new connection from 127.0.0.1:50842
386s     app_test.go:1117: 03:33:02.789 - 24: DEBUG: new connection from 127.0.0.1:34902
386s     app_test.go:1117: 03:33:02.791 - 23: DEBUG: new connection from 127.0.0.1:41790
386s     app_test.go:1117: 03:33:02.819 - 24: DEBUG: new connection from 127.0.0.1:34908
386s     app_test.go:1117: 03:33:02.830 - 19: DEBUG: new connection from 127.0.0.1:56536
386s     app_test.go:1117: 03:33:02.848 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestNew_FifthJoiner (0.95s)
386s === RUN   TestNew_SixthJoiner
386s     app_test.go:1117: 03:33:02.009 - 25: DEBUG: new connection from 127.0.0.1:56552
386s     app_test.go:1117: 03:33:02.026 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.029 - 25: DEBUG: new connection from 127.0.0.1:56560
386s     app_test.go:1117: 03:33:02.072 - 25: DEBUG: new connection from 127.0.0.1:56562
386s     app_test.go:1117: 03:33:02.084 - 26: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.117 - 25: DEBUG: new connection from 127.0.0.1:56576
386s     app_test.go:1117: 03:33:02.117 - 26: DEBUG: new connection from 127.0.0.1:56958
386s     app_test.go:1117: 03:33:02.170 - 25: DEBUG: new connection from 127.0.0.1:56588
386s     app_test.go:1117: 03:33:02.186 - 27: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.197 - 26: DEBUG: new connection from 127.0.0.1:56970
386s     app_test.go:1117: 03:33:02.198 - 25: DEBUG: new connection from 127.0.0.1:56598
386s     app_test.go:1117: 03:33:02.198 - 27: DEBUG: new connection from 127.0.0.1:50854
386s     app_test.go:1117: 03:33:02.214 - 27: DEBUG: new connection from 127.0.0.1:50858
386s     app_test.go:1117: 03:33:02.251 - 25: DEBUG: new connection from 127.0.0.1:56610
386s     app_test.go:1117: 03:33:02.295 - 26: DEBUG: new connection from 127.0.0.1:56982
386s     app_test.go:1117: 03:33:02.317 - 25: DEBUG: new connection from 127.0.0.1:56612
386s     app_test.go:1117: 03:33:02.383 - 25: DEBUG: new connection from 127.0.0.1:56626
386s     app_test.go:1117: 03:33:02.398 - 28: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.414 - 28: DEBUG: new connection from 127.0.0.1:54528
386s     app_test.go:1117: 03:33:02.414 - 27: DEBUG: new connection from 127.0.0.1:50870
386s     app_test.go:1117: 03:33:02.416 - 26: DEBUG: new connection from 127.0.0.1:56984
386s     app_test.go:1117: 03:33:02.418 - 25: DEBUG: new connection from 127.0.0.1:56642
386s     app_test.go:1117: 03:33:02.448 - 28: DEBUG: new connection from 127.0.0.1:54536
386s     app_test.go:1117: 03:33:02.484 - 25: DEBUG: new connection from 127.0.0.1:56650
386s     app_test.go:1117: 03:33:02.502 - 25: DEBUG: new connection from 127.0.0.1:56666
386s     app_test.go:1117: 03:33:02.519 - 29: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.538 - 25: DEBUG: new connection from 127.0.0.1:56678
386s     app_test.go:1117: 03:33:02.539 - 26: DEBUG: new connection from 127.0.0.1:56988
386s     app_test.go:1117: 03:33:02.539 - 27: DEBUG: new connection from 127.0.0.1:50874
386s     app_test.go:1117: 03:33:02.539 - 28: DEBUG: new connection from 127.0.0.1:54538
386s     app_test.go:1117: 03:33:02.541 - 29: DEBUG: new connection from 127.0.0.1:41802
386s     app_test.go:1117: 03:33:02.577 - 29: DEBUG: new connection from 127.0.0.1:41812
386s     app_test.go:1117: 03:33:02.603 - 25: DEBUG: new connection from 127.0.0.1:56690
386s     app_test.go:1117: 03:33:02.630 - 25: DEBUG: new connection from 127.0.0.1:56696
386s     app_test.go:1117: 03:33:02.647 - 30: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.668 - 30: DEBUG: new connection from 127.0.0.1:34920
386s     app_test.go:1117: 03:33:02.669 - 26: DEBUG: new connection from 127.0.0.1:57004
386s     app_test.go:1117: 03:33:02.669 - 29: DEBUG: new connection from 127.0.0.1:41826
386s     app_test.go:1117: 03:33:02.669 - 25: DEBUG: new connection from 127.0.0.1:56712
386s     app_test.go:1117: 03:33:02.671 - 28: DEBUG: new connection from 127.0.0.1:54550
386s     app_test.go:1117: 03:33:02.673 - 27: DEBUG: new connection from 127.0.0.1:50880
386s     app_test.go:1117: 03:33:02.703 - 30: DEBUG: new connection from 127.0.0.1:34936
386s     app_test.go:1117: 03:33:02.746 - 25: DEBUG: new connection from 127.0.0.1:56716
386s     app_test.go:1117: 03:33:02.751 - 25: DEBUG: new connection from 127.0.0.1:56722
386s     app_test.go:1117: 03:33:02.775 - 31: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.791 - 29: DEBUG: new connection from 127.0.0.1:41836
386s     app_test.go:1117: 03:33:02.791 - 25: DEBUG: new connection from 127.0.0.1:56730
386s     app_test.go:1117: 03:33:02.791 - 30: DEBUG: new connection from 127.0.0.1:34946
386s     app_test.go:1117: 03:33:02.792 - 27: DEBUG: new connection from 127.0.0.1:50892
386s     app_test.go:1117: 03:33:02.792 - 28: DEBUG: new connection from 127.0.0.1:54554
386s     app_test.go:1117: 03:33:02.791 - 31: DEBUG: new connection from 127.0.0.1:33020
386s     app_test.go:1117: 03:33:02.798 - 26: DEBUG: new connection from 127.0.0.1:57008
386s     app_test.go:1117: 03:33:02.830 - 25: DEBUG: new connection from 127.0.0.1:56746
386s     app_test.go:1117: 03:33:02.847 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestNew_SixthJoiner (0.99s)
386s === RUN   TestHandover_Voter
386s     app_test.go:1117: 03:33:02.997 - 32: DEBUG: new connection from 127.0.0.1:56752
386s     app_test.go:1117: 03:33:02.014 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.017 - 32: DEBUG: new connection from 127.0.0.1:56768
386s     app_test.go:1117: 03:33:02.068 - 32: DEBUG: new connection from 127.0.0.1:56774
386s     app_test.go:1117: 03:33:02.091 - 33: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.104 - 33: DEBUG: new connection from 127.0.0.1:57018
386s     app_test.go:1117: 03:33:02.104 - 32: DEBUG: new connection from 127.0.0.1:56782
386s     app_test.go:1117: 03:33:02.149 - 32: DEBUG: new connection from 127.0.0.1:56792
386s     app_test.go:1117: 03:33:02.165 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.175 - 32: DEBUG: new connection from 127.0.0.1:56808
386s     app_test.go:1117: 03:33:02.175 - 34: DEBUG: new connection from 127.0.0.1:50894
386s     app_test.go:1117: 03:33:02.175 - 33: DEBUG: new connection from 127.0.0.1:57028
386s     app_test.go:1117: 03:33:02.196 - 34: DEBUG: new connection from 127.0.0.1:50906
386s     app_test.go:1117: 03:33:02.225 - 32: DEBUG: new connection from 127.0.0.1:56822
386s     app_test.go:1117: 03:33:02.253 - 33: DEBUG: new connection from 127.0.0.1:57044
386s     app_test.go:1117: 03:33:02.278 - 32: DEBUG: new connection from 127.0.0.1:56836
386s     app_test.go:1117: 03:33:02.334 - 32: DEBUG: new connection from 127.0.0.1:56844
386s     app_test.go:1117: 03:33:02.348 - 35: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.360 - 35: DEBUG: new connection from 127.0.0.1:54568
386s     app_test.go:1117: 03:33:02.360 - 33: DEBUG: new connection from 127.0.0.1:57058
386s     app_test.go:1117: 03:33:02.361 - 32: DEBUG: new connection from 127.0.0.1:56854
386s     app_test.go:1117: 03:33:02.360 - 34: DEBUG: new connection from 127.0.0.1:50916
386s     app_test.go:1117: 03:33:02.388 - 35: DEBUG: new connection from 127.0.0.1:54576
386s     app_test.go:1117: 03:33:02.399 - 32: DEBUG: new connection from 127.0.0.1:56856
386s     app_test.go:1117: 03:33:02.416 - 32: DEBUG: new connection from 127.0.0.1:56872
386s     app_test.go:1117: 03:33:02.420 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.422 - 32: DEBUG: new connection from 127.0.0.1:56886
386s     app_test.go:1117: 03:33:02.441 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.443 - 32: DEBUG: new connection from 127.0.0.1:56898
386s     app_test.go:1117: 03:33:02.443 - 33: DEBUG: new connection from 127.0.0.1:57068
386s     app_test.go:1117: 03:33:02.443 - 35: DEBUG: new connection from 127.0.0.1:54588
386s     app_test.go:1117: 03:33:02.443 - 34: DEBUG: new connection from 127.0.0.1:50918
386s     app_test.go:1117: 03:33:02.487 - 34: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter
386s --- PASS: TestHandover_Voter (0.60s)
386s === RUN   TestHandover_TwoNodes
386s     app_test.go:1117: 03:33:02.596 - 36: DEBUG: new connection from 127.0.0.1:56910
386s     app_test.go:1117: 03:33:02.612 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.616 - 36: DEBUG: new connection from 127.0.0.1:56918
386s     app_test.go:1117: 03:33:02.673 - 36: DEBUG: new connection from 127.0.0.1:56934
386s     app_test.go:1117: 03:33:02.691 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.704 - 37: DEBUG: new connection from 127.0.0.1:57076
386s     app_test.go:1117: 03:33:02.704 - 36: DEBUG: new connection from 127.0.0.1:56938
386s     app_test.go:1117: 03:33:02.726 - 36: DEBUG: new connection from 127.0.0.1:56950
386s     app_test.go:1117: 03:33:02.740 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.740 - 36: DEBUG: new connection from 127.0.0.1:56956
386s     app_test.go:1117: 03:33:02.741 - 37: DEBUG: new connection from 127.0.0.1:57082
386s     app_test.go:1117: 03:33:02.761 - 37: DEBUG: new connection from 127.0.0.1:57086
386s     app_test.go:1117: 03:33:02.797 - 36: DEBUG: new connection from 127.0.0.1:56966
386s     app_test.go:1117: 03:33:02.827 - 36: DEBUG: promoted 127.0.0.1:9002 from spare to voter
386s     app_test.go:1117: 03:33:02.830 - 36: DEBUG: new connection from 127.0.0.1:56976
386s     app_test.go:1117: 03:33:02.831 - 37: DEBUG: new connection from 127.0.0.1:57092
386s     app_test.go:1117: 03:33:02.862 - 36: DEBUG: new connection from 127.0.0.1:56982
386s     app_test.go:1117: 03:33:02.882 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002
386s     app_test.go:1117: 03:33:02.882 - 37: DEBUG: new connection from 127.0.0.1:57102
386s     app_test.go:1117: 03:33:02.898 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.903 - 36: DEBUG: new connection from 127.0.0.1:56990
386s     app_test.go:1117: 03:33:02.919 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002
386s     app_test.go:1117: 03:33:02.920 - 37: DEBUG: new connection from 127.0.0.1:57108
386s     app_test.go:1117: 03:33:02.933 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestHandover_TwoNodes (0.41s)
386s === RUN   TestHandover_VoterHonorFailureDomain
386s     app_test.go:1117: 03:33:02.012 - 38: DEBUG: new connection from 127.0.0.1:57002
386s     app_test.go:1117: 03:33:02.027 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.035 - 38: DEBUG: new connection from 127.0.0.1:57016
386s     app_test.go:1117: 03:33:02.098 - 38: DEBUG: new connection from 127.0.0.1:57032
386s     app_test.go:1117: 03:33:02.115 - 39: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.129 - 39: DEBUG: new connection from 127.0.0.1:57110
386s     app_test.go:1117: 03:33:02.129 - 38: DEBUG: new connection from 127.0.0.1:57034
386s     app_test.go:1117: 03:33:02.188 - 38: DEBUG: new connection from 127.0.0.1:57048
386s     app_test.go:1117: 03:33:02.203 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.214 - 40: DEBUG: new connection from 127.0.0.1:50934
386s     app_test.go:1117: 03:33:02.213 - 38: DEBUG: new connection from 127.0.0.1:57060
386s     app_test.go:1117: 03:33:02.214 - 39: DEBUG: new connection from 127.0.0.1:57114
386s     app_test.go:1117: 03:33:02.233 - 40: DEBUG: new connection from 127.0.0.1:50936
386s     app_test.go:1117: 03:33:02.261 - 38: DEBUG: new connection from 127.0.0.1:57074
386s     app_test.go:1117: 03:33:02.302 - 39: DEBUG: new connection from 127.0.0.1:57122
386s     app_test.go:1117: 03:33:02.325 - 38: DEBUG: new connection from 127.0.0.1:57082
386s     app_test.go:1117: 03:33:02.384 - 38: DEBUG: new connection from 127.0.0.1:57090
386s     app_test.go:1117: 03:33:02.400 - 41: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.411 - 41: DEBUG: new connection from 127.0.0.1:54590
386s     app_test.go:1117: 03:33:02.412 - 38: DEBUG: new connection from 127.0.0.1:57104
386s     app_test.go:1117: 03:33:02.412 - 39: DEBUG: new connection from 127.0.0.1:57130
386s     app_test.go:1117: 03:33:02.412 - 40: DEBUG: new connection from 127.0.0.1:50944
386s     app_test.go:1117: 03:33:02.439 - 41: DEBUG: new connection from 127.0.0.1:54602
386s     app_test.go:1117: 03:33:02.468 - 38: DEBUG: new connection from 127.0.0.1:57114
386s     app_test.go:1117: 03:33:02.491 - 38: DEBUG: new connection from 127.0.0.1:57118
386s     app_test.go:1117: 03:33:02.503 - 42: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.514 - 39: DEBUG: new connection from 127.0.0.1:57138
386s     app_test.go:1117: 03:33:02.514 - 38: DEBUG: new connection from 127.0.0.1:57124
386s     app_test.go:1117: 03:33:02.514 - 40: DEBUG: new connection from 127.0.0.1:50946
386s     app_test.go:1117: 03:33:02.514 - 42: DEBUG: new connection from 127.0.0.1:41846
386s     app_test.go:1117: 03:33:02.516 - 41: DEBUG: new connection from 127.0.0.1:54610
386s     app_test.go:1117: 03:33:02.535 - 42: DEBUG: new connection from 127.0.0.1:41848
386s     app_test.go:1117: 03:33:02.570 - 38: DEBUG: new connection from 127.0.0.1:57132
386s     app_test.go:1117: 03:33:02.587 - 38: DEBUG: new connection from 127.0.0.1:57148
386s     app_test.go:1117: 03:33:02.605 - 43: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.617 - 42: DEBUG: new connection from 127.0.0.1:41852
386s     app_test.go:1117: 03:33:02.617 - 41: DEBUG: new connection from 127.0.0.1:54620
386s     app_test.go:1117: 03:33:02.617 - 40: DEBUG: new connection from 127.0.0.1:50954
386s     app_test.go:1117: 03:33:02.618 - 39: DEBUG: new connection from 127.0.0.1:57150
386s     app_test.go:1117: 03:33:02.619 - 38: DEBUG: new connection from 127.0.0.1:57164
386s     app_test.go:1117: 03:33:02.620 - 43: DEBUG: new connection from 127.0.0.1:34948
386s     app_test.go:1117: 03:33:02.647 - 43: DEBUG: new connection from 127.0.0.1:34950
386s     app_test.go:1117: 03:33:02.656 - 38: DEBUG: new connection from 127.0.0.1:57176
386s     app_test.go:1117: 03:33:02.668 - 38: DEBUG: new connection from 127.0.0.1:57184
386s     app_test.go:1117: 03:33:02.669 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.674 - 38: DEBUG: new connection from 127.0.0.1:57200
386s     app_test.go:1117: 03:33:02.689 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.690 - 43: DEBUG: new connection from 127.0.0.1:34952
386s     app_test.go:1117: 03:33:02.690 - 38: DEBUG: new connection from 127.0.0.1:57202
386s     app_test.go:1117: 03:33:02.691 - 41: DEBUG: new connection from 127.0.0.1:54622
386s     app_test.go:1117: 03:33:02.691 - 42: DEBUG: new connection from 127.0.0.1:41866
386s     app_test.go:1117: 03:33:02.690 - 39: DEBUG: new connection from 127.0.0.1:57152
386s     app_test.go:1117: 03:33:02.690 - 40: DEBUG: new connection from 127.0.0.1:50958
386s     app_test.go:1117: 03:33:02.733 - 40: DEBUG: promoted 127.0.0.1:9006 from stand-by to voter
386s --- PASS: TestHandover_VoterHonorFailureDomain (0.88s)
386s === RUN   TestHandover_SingleNode
386s --- PASS: TestHandover_SingleNode (0.05s)
386s === RUN   TestHandover_GracefulShutdown
386s --- PASS: TestHandover_GracefulShutdown (0.46s)
386s === RUN   TestHandover_StandBy
386s     app_test.go:1117: 03:33:02.399 - 44: DEBUG: new connection from 127.0.0.1:57440
386s     app_test.go:1117: 03:33:02.420 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.424 - 44: DEBUG: new connection from 127.0.0.1:57456
386s     app_test.go:1117: 03:33:02.472 - 44: DEBUG: new connection from 127.0.0.1:57464
386s     app_test.go:1117: 03:33:02.485 - 45: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.494 - 45: DEBUG: new connection from 127.0.0.1:57288
386s     app_test.go:1117: 03:33:02.494 - 44: DEBUG: new connection from 127.0.0.1:57472
386s     app_test.go:1117: 03:33:02.554 - 44: DEBUG: new connection from 127.0.0.1:57488
386s     app_test.go:1117: 03:33:02.565 - 46: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.572 - 44: DEBUG: new connection from 127.0.0.1:57496
386s     app_test.go:1117: 03:33:02.573 - 45: DEBUG: new connection from 127.0.0.1:57294
386s     app_test.go:1117: 03:33:02.574 - 46: DEBUG: new connection from 127.0.0.1:51066
386s     app_test.go:1117: 03:33:02.598 - 46: DEBUG: new connection from 127.0.0.1:51068
386s     app_test.go:1117: 03:33:02.621 - 44: DEBUG: new connection from 127.0.0.1:57510
386s     app_test.go:1117: 03:33:02.654 - 45: DEBUG: new connection from 127.0.0.1:57304
386s     app_test.go:1117: 03:33:02.678 - 44: DEBUG: new connection from 127.0.0.1:57522
386s     app_test.go:1117: 03:33:02.733 - 44: DEBUG: new connection from 127.0.0.1:57528
386s     app_test.go:1117: 03:33:02.746 - 47: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.759 - 45: DEBUG: new connection from 127.0.0.1:57308
386s     app_test.go:1117: 03:33:02.759 - 44: DEBUG: new connection from 127.0.0.1:57542
386s     app_test.go:1117: 03:33:02.759 - 47: DEBUG: new connection from 127.0.0.1:54624
386s     app_test.go:1117: 03:33:02.759 - 46: DEBUG: new connection from 127.0.0.1:51070
386s     app_test.go:1117: 03:33:02.782 - 47: DEBUG: new connection from 127.0.0.1:54632
386s     app_test.go:1117: 03:33:02.815 - 44: DEBUG: new connection from 127.0.0.1:57552
386s     app_test.go:1117: 03:33:02.828 - 44: DEBUG: new connection from 127.0.0.1:57564
386s     app_test.go:1117: 03:33:02.843 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.855 - 48: DEBUG: new connection from 127.0.0.1:41872
386s     app_test.go:1117: 03:33:02.855 - 45: DEBUG: new connection from 127.0.0.1:57322
386s     app_test.go:1117: 03:33:02.855 - 47: DEBUG: new connection from 127.0.0.1:54646
386s     app_test.go:1117: 03:33:02.855 - 44: DEBUG: new connection from 127.0.0.1:57568
386s     app_test.go:1117: 03:33:02.856 - 46: DEBUG: new connection from 127.0.0.1:51076
386s     app_test.go:1117: 03:33:02.887 - 48: DEBUG: new connection from 127.0.0.1:41884
386s     app_test.go:1117: 03:33:02.918 - 44: DEBUG: new connection from 127.0.0.1:57582
386s     app_test.go:1117: 03:33:02.945 - 44: DEBUG: new connection from 127.0.0.1:57590
386s     app_test.go:1117: 03:33:02.958 - 49: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.976 - 49: DEBUG: new connection from 127.0.0.1:34958
386s     app_test.go:1117: 03:33:02.976 - 48: DEBUG: new connection from 127.0.0.1:41892
386s     app_test.go:1117: 03:33:02.977 - 45: DEBUG: new connection from 127.0.0.1:57326
386s     app_test.go:1117: 03:33:02.977 - 46: DEBUG: new connection from 127.0.0.1:51078
386s     app_test.go:1117: 03:33:02.979 - 47: DEBUG: new connection from 127.0.0.1:54648
386s     app_test.go:1117: 03:33:02.982 - 44: DEBUG: new connection from 127.0.0.1:57594
386s     app_test.go:1117: 03:33:02.009 - 49: DEBUG: new connection from 127.0.0.1:34974
386s     app_test.go:1117: 03:33:02.039 - 44: DEBUG: new connection from 127.0.0.1:57606
386s     app_test.go:1117: 03:33:02.053 - 44: DEBUG: new connection from 127.0.0.1:57618
386s     app_test.go:1117: 03:33:02.069 - 50: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.085 - 46: DEBUG: new connection from 127.0.0.1:51094
386s     app_test.go:1117: 03:33:02.085 - 50: DEBUG: new connection from 127.0.0.1:33030
386s     app_test.go:1117: 03:33:02.085 - 44: DEBUG: new connection from 127.0.0.1:57620
386s     app_test.go:1117: 03:33:02.085 - 47: DEBUG: new connection from 127.0.0.1:54660
386s     app_test.go:1117: 03:33:02.088 - 49: DEBUG: new connection from 127.0.0.1:34980
386s     app_test.go:1117: 03:33:02.097 - 45: DEBUG: new connection from 127.0.0.1:57328
386s     app_test.go:1117: 03:33:02.098 - 48: DEBUG: new connection from 127.0.0.1:41906
386s     app_test.go:1117: 03:33:02.121 - 44: DEBUG: new connection from 127.0.0.1:57630
386s     app_test.go:1117: 03:33:02.139 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.140 - 44: DEBUG: new connection from 127.0.0.1:57644
386s     app_test.go:1117: 03:33:02.152 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.153 - 44: DEBUG: new connection from 127.0.0.1:57654
386s     app_test.go:1117: 03:33:02.153 - 46: DEBUG: new connection from 127.0.0.1:51096
386s     app_test.go:1117: 03:33:02.153 - 48: DEBUG: new connection from 127.0.0.1:41916
386s     app_test.go:1117: 03:33:02.154 - 49: DEBUG: new connection from 127.0.0.1:34986
386s     app_test.go:1117: 03:33:02.154 - 50: DEBUG: new connection from 127.0.0.1:33046
386s     app_test.go:1117: 03:33:02.154 - 45: DEBUG: new connection from 127.0.0.1:57344
386s     app_test.go:1117: 03:33:02.154 - 47: DEBUG: new connection from 127.0.0.1:54662
386s     app_test.go:1117: 03:33:02.195 - 50: DEBUG: new connection from 127.0.0.1:33050
386s     app_test.go:1117: 03:33:02.206 - 48: DEBUG: promoted 127.0.0.1:9007 from spare to stand-by
386s --- PASS: TestHandover_StandBy (0.97s)
386s === RUN   TestHandover_TransferLeadership
386s     app_test.go:1117: 03:33:02.353 - 51: DEBUG: new connection from 127.0.0.1:57666
386s     app_test.go:1117: 03:33:02.369 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.373 - 51: DEBUG: new connection from 127.0.0.1:57670
386s     app_test.go:1117: 03:33:02.417 - 51: DEBUG: new connection from 127.0.0.1:57680
386s     app_test.go:1117: 03:33:02.433 - 52: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.443 - 52: DEBUG: new connection from 127.0.0.1:57354
386s     app_test.go:1117: 03:33:02.443 - 51: DEBUG: new connection from 127.0.0.1:57686
386s     app_test.go:1117: 03:33:02.488 - 51: DEBUG: new connection from 127.0.0.1:57694
386s     app_test.go:1117: 03:33:02.503 - 53: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.512 - 51: DEBUG: new connection from 127.0.0.1:57698
386s     app_test.go:1117: 03:33:02.512 - 53: DEBUG: new connection from 127.0.0.1:51100
386s     app_test.go:1117: 03:33:02.512 - 52: DEBUG: new connection from 127.0.0.1:57366
386s     app_test.go:1117: 03:33:02.530 - 53: DEBUG: new connection from 127.0.0.1:51114
386s     app_test.go:1117: 03:33:02.549 - 51: DEBUG: new connection from 127.0.0.1:57700
386s     app_test.go:1117: 03:33:02.576 - 52: DEBUG: new connection from 127.0.0.1:57368
386s     app_test.go:1117: 03:33:02.749 - 51: DEBUG: new connection from 127.0.0.1:56102
386s     app_test.go:1117: 03:33:02.806 - 51: DEBUG: new connection from 127.0.0.1:56110
386s     app_test.go:1117: 03:33:02.822 - 54: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.834 - 53: DEBUG: new connection from 127.0.0.1:48346
386s     app_test.go:1117: 03:33:02.834 - 52: DEBUG: new connection from 127.0.0.1:40208
386s     app_test.go:1117: 03:33:02.834 - 51: DEBUG: new connection from 127.0.0.1:56126
386s     app_test.go:1117: 03:33:02.835 - 54: DEBUG: new connection from 127.0.0.1:59846
386s     app_test.go:1117: 03:33:02.861 - 54: DEBUG: new connection from 127.0.0.1:59856
386s     app_test.go:1117: 03:33:02.867 - 51: DEBUG: new connection from 127.0.0.1:56140
386s     app_test.go:1117: 03:33:02.878 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.879 - 51: DEBUG: new connection from 127.0.0.1:56142
386s     app_test.go:1117: 03:33:02.880 - 51: DEBUG: new connection from 127.0.0.1:56146
386s     app_test.go:1117: 03:33:02.889 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.890 - 53: DEBUG: new connection from 127.0.0.1:48348
386s     app_test.go:1117: 03:33:02.890 - 54: DEBUG: new connection from 127.0.0.1:59866
386s     app_test.go:1117: 03:33:02.891 - 52: DEBUG: new connection from 127.0.0.1:40220
386s     app_test.go:1117: 03:33:02.891 - 51: DEBUG: new connection from 127.0.0.1:56160
386s     app_test.go:1117: 03:33:02.923 - 51: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter
386s     app_test.go:1117: 03:33:02.924 - 53: DEBUG: new connection from 127.0.0.1:48364
386s     app_test.go:1117: 03:33:02.924 - 54: DEBUG: new connection from 127.0.0.1:59882
386s     app_test.go:1117: 03:33:02.924 - 52: DEBUG: new connection from 127.0.0.1:40226
386s     app_test.go:1117: 03:33:02.924 - 51: DEBUG: new connection from 127.0.0.1:56174
386s     app_test.go:1117: 03:33:02.956 - 53: DEBUG: new connection from 127.0.0.1:48368
386s     app_test.go:1117: 03:33:02.957 - 54: DEBUG: new connection from 127.0.0.1:59890
386s     app_test.go:1117: 03:33:02.973 - 52: DEBUG: new connection from 127.0.0.1:40234
386s     app_test.go:1117: 03:33:02.977 - 52: DEBUG: new connection from 127.0.0.1:40240
386s     app_test.go:1117: 03:33:02.989 - 51: DEBUG: new connection from 127.0.0.1:56178
386s     app_test.go:1117: 03:33:02.007 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002
386s     app_test.go:1117: 03:33:02.007 - 52: DEBUG: new connection from 127.0.0.1:40244
386s     app_test.go:1117: 03:33:02.020 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.027 - 51: DEBUG: new connection from 127.0.0.1:56192
386s     app_test.go:1117: 03:33:02.043 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002
386s     app_test.go:1117: 03:33:02.044 - 52: DEBUG: new connection from 127.0.0.1:40260
386s     app_test.go:1117: 03:33:02.054 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestHandover_TransferLeadership (0.79s)
386s === RUN   TestRolesAdjustment_ReplaceVoter
386s     app_test.go:1117: 03:33:02.142 - 55: DEBUG: new connection from 127.0.0.1:56196
386s     app_test.go:1117: 03:33:02.162 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.166 - 55: DEBUG: new connection from 127.0.0.1:56210
386s     app_test.go:1117: 03:33:02.299 - 55: DEBUG: new connection from 127.0.0.1:56222
386s     app_test.go:1117: 03:33:02.316 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.327 - 56: DEBUG: new connection from 127.0.0.1:40262
386s     app_test.go:1117: 03:33:02.327 - 55: DEBUG: new connection from 127.0.0.1:56238
386s     app_test.go:1117: 03:33:02.371 - 55: DEBUG: new connection from 127.0.0.1:56252
386s     app_test.go:1117: 03:33:02.383 - 57: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.391 - 57: DEBUG: new connection from 127.0.0.1:48380
386s     app_test.go:1117: 03:33:02.391 - 56: DEBUG: new connection from 127.0.0.1:40274
386s     app_test.go:1117: 03:33:02.396 - 55: DEBUG: new connection from 127.0.0.1:56266
386s     app_test.go:1117: 03:33:02.413 - 57: DEBUG: new connection from 127.0.0.1:48382
386s     app_test.go:1117: 03:33:02.433 - 55: DEBUG: new connection from 127.0.0.1:56280
386s     app_test.go:1117: 03:33:02.460 - 56: DEBUG: new connection from 127.0.0.1:40282
386s     app_test.go:1117: 03:33:02.490 - 55: DEBUG: new connection from 127.0.0.1:56290
386s     app_test.go:1117: 03:33:02.558 - 55: DEBUG: new connection from 127.0.0.1:56298
386s     app_test.go:1117: 03:33:02.574 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.583 - 57: DEBUG: new connection from 127.0.0.1:48384
386s     app_test.go:1117: 03:33:02.583 - 55: DEBUG: new connection from 127.0.0.1:56308
386s     app_test.go:1117: 03:33:02.583 - 56: DEBUG: new connection from 127.0.0.1:40288
386s     app_test.go:1117: 03:33:02.584 - 58: DEBUG: new connection from 127.0.0.1:59894
386s     app_test.go:1117: 03:33:02.612 - 58: DEBUG: new connection from 127.0.0.1:59908
386s     app_test.go:1117: 03:33:02.635 - 55: DEBUG: new connection from 127.0.0.1:56324
386s     app_test.go:1117: 03:33:02.178 - 55: DEBUG: new connection from 127.0.0.1:56332
386s     app_test.go:1117: 03:33:02.211 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.250 - 56: DEBUG: new connection from 127.0.0.1:40290
386s     app_test.go:1117: 03:33:02.251 - 55: DEBUG: new connection from 127.0.0.1:56338
386s     app_test.go:1117: 03:33:02.254 - 58: DEBUG: new connection from 127.0.0.1:59920
386s     app_test.go:1117: 03:33:02.341 - 55: DEBUG: new connection from 127.0.0.1:56352
386s     app_test.go:1117: 03:33:02.353 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.622 - 58: DEBUG: new connection from 127.0.0.1:59928
386s     app_test.go:1117: 03:33:02.623 - 55: DEBUG: new connection from 127.0.0.1:56360
386s     app_test.go:1117: 03:33:02.623 - 56: DEBUG: new connection from 127.0.0.1:40294
386s     app_test.go:1117: 03:33:02.624 - 55: DEBUG: new connection from 127.0.0.1:56370
386s     app_test.go:1117: 03:33:02.648 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.655 - 55: DEBUG: new connection from 127.0.0.1:56384
386s     app_test.go:1117: 03:33:02.655 - 56: DEBUG: new connection from 127.0.0.1:40306
386s     app_test.go:1117: 03:33:02.655 - 58: DEBUG: new connection from 127.0.0.1:59940
386s     app_test.go:1117: 03:33:02.360 - 55: DEBUG: new connection from 127.0.0.1:56398
386s     app_test.go:1117: 03:33:02.376 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.655 - 55: DEBUG: new connection from 127.0.0.1:56410
386s     app_test.go:1117: 03:33:02.669 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.677 - 55: DEBUG: new connection from 127.0.0.1:56412
386s     app_test.go:1117: 03:33:02.691 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.700 - 58: DEBUG: new connection from 127.0.0.1:59954
386s     app_test.go:1117: 03:33:02.700 - 56: DEBUG: new connection from 127.0.0.1:40316
386s     app_test.go:1117: 03:33:02.701 - 55: DEBUG: new connection from 127.0.0.1:56426
386s     app_test.go:1117: 03:33:02.393 - 55: DEBUG: new connection from 127.0.0.1:56442
386s     app_test.go:1117: 03:33:02.404 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.677 - 55: DEBUG: new connection from 127.0.0.1:56450
386s     app_test.go:1117: 03:33:02.695 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.717 - 55: DEBUG: new connection from 127.0.0.1:56452
386s     app_test.go:1117: 03:33:02.735 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.742 - 56: DEBUG: new connection from 127.0.0.1:40328
386s     app_test.go:1117: 03:33:02.742 - 55: DEBUG: new connection from 127.0.0.1:56468
386s     app_test.go:1117: 03:33:02.743 - 58: DEBUG: new connection from 127.0.0.1:59970
386s     app_test.go:1117: 03:33:02.411 - 55: DEBUG: new connection from 127.0.0.1:56470
386s     app_test.go:1117: 03:33:02.422 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.646 - 55: DEBUG: new connection from 127.0.0.1:56480
386s     app_test.go:1117: 03:33:02.660 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestRolesAdjustment_ReplaceVoter (8.59s)
386s === RUN   TestRolesAdjustment_ReplaceVoterHonorFailureDomain
386s     app_test.go:1117: 03:33:02.730 - 59: DEBUG: new connection from 127.0.0.1:56482
386s     app_test.go:1117: 03:33:02.746 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.753 - 59: DEBUG: new connection from 127.0.0.1:56494
386s     app_test.go:1117: 03:33:02.816 - 59: DEBUG: new connection from 127.0.0.1:56506
386s     app_test.go:1117: 03:33:02.833 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.844 - 59: DEBUG: new connection from 127.0.0.1:56520
386s     app_test.go:1117: 03:33:02.845 - 60: DEBUG: new connection from 127.0.0.1:40344
386s     app_test.go:1117: 03:33:02.905 - 59: DEBUG: new connection from 127.0.0.1:56524
386s     app_test.go:1117: 03:33:02.919 - 61: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:33:02.927 - 59: DEBUG: new connection from 127.0.0.1:56538
386s     app_test.go:1117: 03:33:02.929 - 60: DEBUG: new connection from 127.0.0.1:40352
386s     app_test.go:1117: 03:33:02.934 - 61: DEBUG: new connection from 127.0.0.1:48516
386s     app_test.go:1117: 03:33:02.950 - 61: DEBUG: new connection from 127.0.0.1:48526
386s     app_test.go:1117: 03:33:02.975 - 59: DEBUG: new connection from 127.0.0.1:56542
386s     app_test.go:1117: 03:34:02.006 - 60: DEBUG: new connection from 127.0.0.1:40354
386s     app_test.go:1117: 03:34:02.030 - 59: DEBUG: new connection from 127.0.0.1:56554
386s     app_test.go:1117: 03:34:02.145 - 59: DEBUG: new connection from 127.0.0.1:56570
386s     app_test.go:1117: 03:34:02.159 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.167 - 62: DEBUG: new connection from 127.0.0.1:59984
386s     app_test.go:1117: 03:34:02.168 - 59: DEBUG: new connection from 127.0.0.1:56580
386s     app_test.go:1117: 03:34:02.168 - 60: DEBUG: new connection from 127.0.0.1:40362
386s     app_test.go:1117: 03:34:02.169 - 61: DEBUG: new connection from 127.0.0.1:48528
386s     app_test.go:1117: 03:34:02.198 - 62: DEBUG: new connection from 127.0.0.1:59998
386s     app_test.go:1117: 03:34:02.220 - 59: DEBUG: new connection from 127.0.0.1:56596
386s     app_test.go:1117: 03:34:02.247 - 59: DEBUG: new connection from 127.0.0.1:56602
386s     app_test.go:1117: 03:34:02.260 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.273 - 60: DEBUG: new connection from 127.0.0.1:40368
386s     app_test.go:1117: 03:34:02.273 - 59: DEBUG: new connection from 127.0.0.1:56606
386s     app_test.go:1117: 03:34:02.274 - 62: DEBUG: new connection from 127.0.0.1:60012
386s     app_test.go:1117: 03:34:02.276 - 61: DEBUG: new connection from 127.0.0.1:48530
386s     app_test.go:1117: 03:34:02.274 - 63: DEBUG: new connection from 127.0.0.1:38976
386s     app_test.go:1117: 03:34:02.311 - 63: DEBUG: new connection from 127.0.0.1:38980
386s     app_test.go:1117: 03:34:02.348 - 59: DEBUG: new connection from 127.0.0.1:56618
386s     app_test.go:1117: 03:34:02.379 - 59: DEBUG: new connection from 127.0.0.1:56626
386s     app_test.go:1117: 03:34:02.400 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.417 - 61: DEBUG: new connection from 127.0.0.1:48540
386s     app_test.go:1117: 03:34:02.418 - 59: DEBUG: new connection from 127.0.0.1:56630
386s     app_test.go:1117: 03:34:02.417 - 63: DEBUG: new connection from 127.0.0.1:38982
386s     app_test.go:1117: 03:34:02.417 - 62: DEBUG: new connection from 127.0.0.1:60014
386s     app_test.go:1117: 03:34:02.423 - 60: DEBUG: new connection from 127.0.0.1:40378
386s     app_test.go:1117: 03:34:02.424 - 64: DEBUG: new connection from 127.0.0.1:45530
386s     app_test.go:1117: 03:34:02.457 - 64: DEBUG: new connection from 127.0.0.1:45534
386s     app_test.go:1117: 03:34:02.486 - 59: DEBUG: new connection from 127.0.0.1:56634
386s     app_test.go:1117: 03:34:02.772 - 59: DEBUG: new connection from 127.0.0.1:43882
386s     app_test.go:1117: 03:34:02.785 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.799 - 63: DEBUG: new connection from 127.0.0.1:60748
386s     app_test.go:1117: 03:34:02.799 - 62: DEBUG: new connection from 127.0.0.1:40310
386s     app_test.go:1117: 03:34:02.803 - 60: DEBUG: new connection from 127.0.0.1:50722
386s     app_test.go:1117: 03:34:02.803 - 59: DEBUG: new connection from 127.0.0.1:43896
386s     app_test.go:1117: 03:34:02.804 - 64: DEBUG: new connection from 127.0.0.1:55972
386s     app_test.go:1117: 03:34:02.866 - 59: DEBUG: new connection from 127.0.0.1:43904
386s     app_test.go:1117: 03:34:02.881 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.215 - 59: DEBUG: new connection from 127.0.0.1:43906
386s     app_test.go:1117: 03:34:02.224 - 64: DEBUG: new connection from 127.0.0.1:55978
386s     app_test.go:1117: 03:34:02.224 - 60: DEBUG: new connection from 127.0.0.1:50736
386s     app_test.go:1117: 03:34:02.224 - 63: DEBUG: new connection from 127.0.0.1:60762
386s     app_test.go:1117: 03:34:02.224 - 59: DEBUG: new connection from 127.0.0.1:43916
386s     app_test.go:1117: 03:34:02.227 - 62: DEBUG: new connection from 127.0.0.1:40312
386s     app_test.go:1117: 03:34:02.239 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.261 - 63: DEBUG: new connection from 127.0.0.1:60766
386s     app_test.go:1117: 03:34:02.261 - 62: DEBUG: new connection from 127.0.0.1:40318
386s     app_test.go:1117: 03:34:02.261 - 60: DEBUG: new connection from 127.0.0.1:50744
386s     app_test.go:1117: 03:34:02.261 - 59: DEBUG: new connection from 127.0.0.1:43926
386s     app_test.go:1117: 03:34:02.262 - 64: DEBUG: new connection from 127.0.0.1:55990
386s     app_test.go:1117: 03:34:02.327 - 59: DEBUG: new connection from 127.0.0.1:43928
386s     app_test.go:1117: 03:34:02.338 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.470 - 59: DEBUG: new connection from 127.0.0.1:43934
386s     app_test.go:1117: 03:34:02.483 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.895 - 59: DEBUG: new connection from 127.0.0.1:43940
386s     app_test.go:1117: 03:34:02.905 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.246 - 59: DEBUG: new connection from 127.0.0.1:43948
386s     app_test.go:1117: 03:34:02.259 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.288 - 59: DEBUG: new connection from 127.0.0.1:43956
386s     app_test.go:1117: 03:34:02.303 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.310 - 62: DEBUG: new connection from 127.0.0.1:40332
386s     app_test.go:1117: 03:34:02.310 - 60: DEBUG: new connection from 127.0.0.1:50758
386s     app_test.go:1117: 03:34:02.310 - 64: DEBUG: new connection from 127.0.0.1:56006
386s     app_test.go:1117: 03:34:02.310 - 59: DEBUG: new connection from 127.0.0.1:43966
386s     app_test.go:1117: 03:34:02.310 - 63: DEBUG: new connection from 127.0.0.1:60778
386s     app_test.go:1117: 03:34:02.346 - 59: DEBUG: new connection from 127.0.0.1:43980
386s     app_test.go:1117: 03:34:02.357 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.581 - 59: DEBUG: new connection from 127.0.0.1:43982
386s     app_test.go:1117: 03:34:02.594 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.925 - 59: DEBUG: new connection from 127.0.0.1:51060
386s     app_test.go:1117: 03:34:02.941 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.270 - 59: DEBUG: new connection from 127.0.0.1:51076
386s     app_test.go:1117: 03:34:02.287 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.340 - 59: DEBUG: new connection from 127.0.0.1:51082
386s     app_test.go:1117: 03:34:02.356 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.361 - 62: DEBUG: new connection from 127.0.0.1:49226
386s     app_test.go:1117: 03:34:02.361 - 64: DEBUG: new connection from 127.0.0.1:39864
386s     app_test.go:1117: 03:34:02.362 - 60: DEBUG: new connection from 127.0.0.1:39878
386s     app_test.go:1117: 03:34:02.362 - 59: DEBUG: new connection from 127.0.0.1:51088
386s     app_test.go:1117: 03:34:02.362 - 63: DEBUG: new connection from 127.0.0.1:46600
386s     app_test.go:1117: 03:34:02.366 - 59: DEBUG: new connection from 127.0.0.1:51092
386s     app_test.go:1117: 03:34:02.390 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.603 - 59: DEBUG: new connection from 127.0.0.1:51094
386s     app_test.go:1117: 03:34:02.618 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.956 - 59: DEBUG: new connection from 127.0.0.1:51098
386s     app_test.go:1117: 03:34:02.970 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.298 - 59: DEBUG: new connection from 127.0.0.1:51114
386s     app_test.go:1117: 03:34:02.309 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.396 - 59: DEBUG: new connection from 127.0.0.1:51124
386s     app_test.go:1117: 03:34:02.410 - 59: DEBUG: new connection from 127.0.0.1:51130
386s     app_test.go:1117: 03:34:02.418 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.426 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.431 - 64: DEBUG: new connection from 127.0.0.1:39866
386s     app_test.go:1117: 03:34:02.431 - 59: DEBUG: new connection from 127.0.0.1:51144
386s     app_test.go:1117: 03:34:02.431 - 60: DEBUG: new connection from 127.0.0.1:39890
386s     app_test.go:1117: 03:34:02.431 - 63: DEBUG: new connection from 127.0.0.1:46606
386s     app_test.go:1117: 03:34:02.431 - 62: DEBUG: new connection from 127.0.0.1:49234
386s     app_test.go:1117: 03:34:02.673 - 59: DEBUG: new connection from 127.0.0.1:51146
386s     app_test.go:1117: 03:34:02.687 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.488 - 59: DEBUG: new connection from 127.0.0.1:51160
386s     app_test.go:1117: 03:34:02.500 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestRolesAdjustment_ReplaceVoterHonorFailureDomain (18.89s)
386s === RUN   TestRolesAdjustment_ReplaceVoterHonorWeight
386s     app_test.go:1117: 03:34:02.625 - 65: DEBUG: new connection from 127.0.0.1:51174
386s     app_test.go:1117: 03:34:02.636 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.642 - 65: DEBUG: new connection from 127.0.0.1:51186
386s     app_test.go:1117: 03:34:02.697 - 65: DEBUG: new connection from 127.0.0.1:51194
386s     app_test.go:1117: 03:34:02.711 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.725 - 66: DEBUG: new connection from 127.0.0.1:39904
386s     app_test.go:1117: 03:34:02.725 - 65: DEBUG: new connection from 127.0.0.1:51196
386s     app_test.go:1117: 03:34:02.792 - 65: DEBUG: new connection from 127.0.0.1:51212
386s     app_test.go:1117: 03:34:02.808 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.814 - 66: DEBUG: new connection from 127.0.0.1:39912
386s     app_test.go:1117: 03:34:02.814 - 65: DEBUG: new connection from 127.0.0.1:51226
386s     app_test.go:1117: 03:34:02.814 - 67: DEBUG: new connection from 127.0.0.1:38822
386s     app_test.go:1117: 03:34:02.833 - 67: DEBUG: new connection from 127.0.0.1:38836
386s     app_test.go:1117: 03:34:02.864 - 65: DEBUG: new connection from 127.0.0.1:51240
386s     app_test.go:1117: 03:34:02.890 - 66: DEBUG: new connection from 127.0.0.1:39928
386s     app_test.go:1117: 03:34:02.912 - 65: DEBUG: new connection from 127.0.0.1:51250
386s     app_test.go:1117: 03:34:02.969 - 65: DEBUG: new connection from 127.0.0.1:51266
386s     app_test.go:1117: 03:34:02.984 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.997 - 66: DEBUG: new connection from 127.0.0.1:39938
386s     app_test.go:1117: 03:34:02.997 - 67: DEBUG: new connection from 127.0.0.1:38838
386s     app_test.go:1117: 03:34:02.997 - 68: DEBUG: new connection from 127.0.0.1:49236
386s     app_test.go:1117: 03:34:02.997 - 65: DEBUG: new connection from 127.0.0.1:51282
386s     app_test.go:1117: 03:34:02.027 - 68: DEBUG: new connection from 127.0.0.1:49238
386s     app_test.go:1117: 03:34:02.056 - 65: DEBUG: new connection from 127.0.0.1:51292
386s     app_test.go:1117: 03:34:02.065 - 65: DEBUG: new connection from 127.0.0.1:51300
386s     app_test.go:1117: 03:34:02.081 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.093 - 69: DEBUG: new connection from 127.0.0.1:46612
386s     app_test.go:1117: 03:34:02.093 - 68: DEBUG: new connection from 127.0.0.1:49244
386s     app_test.go:1117: 03:34:02.093 - 66: DEBUG: new connection from 127.0.0.1:39954
386s     app_test.go:1117: 03:34:02.093 - 67: DEBUG: new connection from 127.0.0.1:38848
386s     app_test.go:1117: 03:34:02.093 - 65: DEBUG: new connection from 127.0.0.1:51314
386s     app_test.go:1117: 03:34:02.127 - 69: DEBUG: new connection from 127.0.0.1:46616
386s     app_test.go:1117: 03:34:02.157 - 65: DEBUG: new connection from 127.0.0.1:51316
386s     app_test.go:1117: 03:34:02.182 - 65: DEBUG: new connection from 127.0.0.1:51320
386s     app_test.go:1117: 03:34:02.198 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.211 - 70: DEBUG: new connection from 127.0.0.1:39878
386s     app_test.go:1117: 03:34:02.211 - 67: DEBUG: new connection from 127.0.0.1:38850
386s     app_test.go:1117: 03:34:02.211 - 69: DEBUG: new connection from 127.0.0.1:46626
386s     app_test.go:1117: 03:34:02.211 - 68: DEBUG: new connection from 127.0.0.1:49258
386s     app_test.go:1117: 03:34:02.211 - 65: DEBUG: new connection from 127.0.0.1:51332
386s     app_test.go:1117: 03:34:02.211 - 66: DEBUG: new connection from 127.0.0.1:39960
386s     app_test.go:1117: 03:34:02.239 - 70: DEBUG: new connection from 127.0.0.1:39894
386s     app_test.go:1117: 03:34:02.262 - 65: DEBUG: new connection from 127.0.0.1:51344
386s     app_test.go:1117: 03:34:02.655 - 65: DEBUG: new connection from 127.0.0.1:36602
386s     app_test.go:1117: 03:34:02.671 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.684 - 70: DEBUG: new connection from 127.0.0.1:37006
386s     app_test.go:1117: 03:34:02.685 - 69: DEBUG: new connection from 127.0.0.1:44196
386s     app_test.go:1117: 03:34:02.685 - 68: DEBUG: new connection from 127.0.0.1:34668
386s     app_test.go:1117: 03:34:02.685 - 66: DEBUG: new connection from 127.0.0.1:57614
386s     app_test.go:1117: 03:34:02.686 - 65: DEBUG: new connection from 127.0.0.1:36618
386s     app_test.go:1117: 03:34:02.744 - 65: DEBUG: new connection from 127.0.0.1:36630
386s     app_test.go:1117: 03:34:02.754 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.036 - 65: DEBUG: new connection from 127.0.0.1:36644
386s     app_test.go:1117: 03:34:02.050 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.107 - 68: DEBUG: new connection from 127.0.0.1:34676
386s     app_test.go:1117: 03:34:02.107 - 65: DEBUG: new connection from 127.0.0.1:36660
386s     app_test.go:1117: 03:34:02.108 - 69: DEBUG: new connection from 127.0.0.1:44204
386s     app_test.go:1117: 03:34:02.108 - 66: DEBUG: new connection from 127.0.0.1:57628
386s     app_test.go:1117: 03:34:02.108 - 70: DEBUG: new connection from 127.0.0.1:37016
386s     app_test.go:1117: 03:34:02.138 - 65: DEBUG: new connection from 127.0.0.1:36668
386s     app_test.go:1117: 03:34:02.145 - 70: DEBUG: new connection from 127.0.0.1:37028
386s     app_test.go:1117: 03:34:02.145 - 69: DEBUG: new connection from 127.0.0.1:44220
386s     app_test.go:1117: 03:34:02.145 - 68: DEBUG: new connection from 127.0.0.1:34690
386s     app_test.go:1117: 03:34:02.146 - 66: DEBUG: new connection from 127.0.0.1:57644
386s     app_test.go:1117: 03:34:02.149 - 65: DEBUG: new connection from 127.0.0.1:36670
386s     app_test.go:1117: 03:34:02.167 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.251 - 65: DEBUG: new connection from 127.0.0.1:36672
386s     app_test.go:1117: 03:34:02.265 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.759 - 65: DEBUG: new connection from 127.0.0.1:36688
386s     app_test.go:1117: 03:34:02.771 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.054 - 65: DEBUG: new connection from 127.0.0.1:36702
386s     app_test.go:1117: 03:34:02.071 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.175 - 65: DEBUG: new connection from 127.0.0.1:36712
386s     app_test.go:1117: 03:34:02.186 - 65: DEBUG: new connection from 127.0.0.1:36714
386s     app_test.go:1117: 03:34:02.191 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.202 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.212 - 68: DEBUG: new connection from 127.0.0.1:34702
386s     app_test.go:1117: 03:34:02.212 - 69: DEBUG: new connection from 127.0.0.1:44232
386s     app_test.go:1117: 03:34:02.212 - 70: DEBUG: new connection from 127.0.0.1:37042
386s     app_test.go:1117: 03:34:02.213 - 65: DEBUG: new connection from 127.0.0.1:36720
386s     app_test.go:1117: 03:34:02.213 - 66: DEBUG: new connection from 127.0.0.1:57658
386s     app_test.go:1117: 03:34:02.272 - 65: DEBUG: new connection from 127.0.0.1:36726
386s     app_test.go:1117: 03:34:02.285 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.779 - 65: DEBUG: new connection from 127.0.0.1:37782
386s     app_test.go:1117: 03:34:02.794 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.079 - 65: DEBUG: new connection from 127.0.0.1:37788
386s     app_test.go:1117: 03:34:02.090 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.203 - 65: DEBUG: new connection from 127.0.0.1:37798
386s     app_test.go:1117: 03:34:02.218 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.241 - 65: DEBUG: new connection from 127.0.0.1:37810
386s     app_test.go:1117: 03:34:02.257 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.265 - 70: DEBUG: new connection from 127.0.0.1:41504
386s     app_test.go:1117: 03:34:02.265 - 65: DEBUG: new connection from 127.0.0.1:37816
386s     app_test.go:1117: 03:34:02.265 - 68: DEBUG: new connection from 127.0.0.1:53358
386s     app_test.go:1117: 03:34:02.265 - 69: DEBUG: new connection from 127.0.0.1:54564
386s     app_test.go:1117: 03:34:02.266 - 66: DEBUG: new connection from 127.0.0.1:51806
386s     app_test.go:1117: 03:34:02.292 - 65: DEBUG: new connection from 127.0.0.1:37832
386s     app_test.go:1117: 03:34:02.303 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.811 - 65: DEBUG: new connection from 127.0.0.1:37842
386s     app_test.go:1117: 03:34:02.821 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.098 - 65: DEBUG: new connection from 127.0.0.1:37844
386s     app_test.go:1117: 03:34:02.109 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.225 - 65: DEBUG: new connection from 127.0.0.1:37858
386s     app_test.go:1117: 03:34:02.235 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.285 - 65: DEBUG: new connection from 127.0.0.1:37874
386s     app_test.go:1117: 03:34:02.296 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.300 - 69: DEBUG: new connection from 127.0.0.1:54572
386s     app_test.go:1117: 03:34:02.301 - 68: DEBUG: new connection from 127.0.0.1:53364
386s     app_test.go:1117: 03:34:02.301 - 66: DEBUG: new connection from 127.0.0.1:51808
386s     app_test.go:1117: 03:34:02.301 - 65: DEBUG: new connection from 127.0.0.1:37876
386s     app_test.go:1117: 03:34:02.302 - 70: DEBUG: new connection from 127.0.0.1:41508
386s     app_test.go:1117: 03:34:02.310 - 65: DEBUG: new connection from 127.0.0.1:37886
386s     app_test.go:1117: 03:34:02.322 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.268 - 65: DEBUG: new connection from 127.0.0.1:37894
386s     app_test.go:1117: 03:34:02.279 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestRolesAdjustment_ReplaceVoterHonorWeight (18.81s)
386s === RUN   TestRolesAdjustment_CantReplaceVoter
386s     app_test.go:1117: 03:34:02.431 - 71: DEBUG: new connection from 127.0.0.1:37910
386s     app_test.go:1117: 03:34:02.441 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.444 - 71: DEBUG: new connection from 127.0.0.1:37918
386s     app_test.go:1117: 03:34:02.487 - 71: DEBUG: new connection from 127.0.0.1:37920
386s     app_test.go:1117: 03:34:02.497 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.505 - 71: DEBUG: new connection from 127.0.0.1:37926
386s     app_test.go:1117: 03:34:02.505 - 72: DEBUG: new connection from 127.0.0.1:51818
386s     app_test.go:1117: 03:34:02.544 - 71: DEBUG: new connection from 127.0.0.1:37934
386s     app_test.go:1117: 03:34:02.555 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.562 - 72: DEBUG: new connection from 127.0.0.1:51828
386s     app_test.go:1117: 03:34:02.562 - 71: DEBUG: new connection from 127.0.0.1:37938
386s     app_test.go:1117: 03:34:02.563 - 73: DEBUG: new connection from 127.0.0.1:52450
386s     app_test.go:1117: 03:34:02.573 - 73: DEBUG: new connection from 127.0.0.1:52464
386s     app_test.go:1117: 03:34:02.596 - 71: DEBUG: new connection from 127.0.0.1:37940
386s     app_test.go:1117: 03:34:02.620 - 72: DEBUG: new connection from 127.0.0.1:51842
386s     app_test.go:1117: 03:34:02.640 - 71: DEBUG: new connection from 127.0.0.1:37952
386s     app_test.go:1117: 03:34:02.691 - 71: DEBUG: new connection from 127.0.0.1:37964
386s     app_test.go:1117: 03:34:02.703 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.712 - 74: DEBUG: new connection from 127.0.0.1:53380
386s     app_test.go:1117: 03:34:02.712 - 73: DEBUG: new connection from 127.0.0.1:52472
386s     app_test.go:1117: 03:34:02.712 - 71: DEBUG: new connection from 127.0.0.1:37976
386s     app_test.go:1117: 03:34:02.713 - 72: DEBUG: new connection from 127.0.0.1:51854
386s     app_test.go:1117: 03:34:02.731 - 74: DEBUG: new connection from 127.0.0.1:53382
386s     app_test.go:1117: 03:34:02.456 - 71: DEBUG: new connection from 127.0.0.1:49212
386s     app_test.go:1117: 03:34:02.467 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.472 - 72: DEBUG: new connection from 127.0.0.1:49804
386s     app_test.go:1117: 03:34:02.473 - 71: DEBUG: new connection from 127.0.0.1:49226
386s     app_test.go:1117: 03:34:02.517 - 71: DEBUG: new connection from 127.0.0.1:49242
386s     app_test.go:1117: 03:34:02.528 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.485 - 71: DEBUG: new connection from 127.0.0.1:49250
386s     app_test.go:1117: 03:34:02.496 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.502 - 72: DEBUG: new connection from 127.0.0.1:49806
386s     app_test.go:1117: 03:34:02.502 - 71: DEBUG: new connection from 127.0.0.1:49264
386s     app_test.go:1117: 03:34:02.534 - 71: DEBUG: new connection from 127.0.0.1:49276
386s     app_test.go:1117: 03:34:02.544 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.515 - 71: DEBUG: new connection from 127.0.0.1:49282
386s     app_test.go:1117: 03:34:02.528 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.536 - 71: DEBUG: new connection from 127.0.0.1:49284
386s     app_test.go:1117: 03:34:02.537 - 72: DEBUG: new connection from 127.0.0.1:49808
386s     app_test.go:1117: 03:34:02.550 - 71: DEBUG: new connection from 127.0.0.1:49288
386s     app_test.go:1117: 03:34:02.561 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.766 - 71: DEBUG: new connection from 127.0.0.1:49298
386s     app_test.go:1117: 03:34:02.776 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestRolesAdjustment_CantReplaceVoter (12.43s)
386s === RUN   TestRolesAdjustment_ReplaceStandBy
386s     app_test.go:1117: 03:34:02.858 - 75: DEBUG: new connection from 127.0.0.1:49308
386s     app_test.go:1117: 03:34:02.870 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.873 - 75: DEBUG: new connection from 127.0.0.1:49310
386s     app_test.go:1117: 03:34:02.918 - 75: DEBUG: new connection from 127.0.0.1:49320
386s     app_test.go:1117: 03:34:02.935 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.951 - 76: DEBUG: new connection from 127.0.0.1:49810
386s     app_test.go:1117: 03:34:02.951 - 75: DEBUG: new connection from 127.0.0.1:49332
386s     app_test.go:1117: 03:34:02.007 - 75: DEBUG: new connection from 127.0.0.1:49334
386s     app_test.go:1117: 03:34:02.018 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.024 - 77: DEBUG: new connection from 127.0.0.1:40766
386s     app_test.go:1117: 03:34:02.024 - 75: DEBUG: new connection from 127.0.0.1:49350
386s     app_test.go:1117: 03:34:02.025 - 76: DEBUG: new connection from 127.0.0.1:49818
386s     app_test.go:1117: 03:34:02.047 - 77: DEBUG: new connection from 127.0.0.1:40776
386s     app_test.go:1117: 03:34:02.075 - 75: DEBUG: new connection from 127.0.0.1:49354
386s     app_test.go:1117: 03:34:02.103 - 76: DEBUG: new connection from 127.0.0.1:49826
386s     app_test.go:1117: 03:34:02.128 - 75: DEBUG: new connection from 127.0.0.1:49356
386s     app_test.go:1117: 03:34:02.201 - 75: DEBUG: new connection from 127.0.0.1:49362
386s     app_test.go:1117: 03:34:02.213 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.227 - 78: DEBUG: new connection from 127.0.0.1:60980
386s     app_test.go:1117: 03:34:02.227 - 75: DEBUG: new connection from 127.0.0.1:49376
386s     app_test.go:1117: 03:34:02.227 - 76: DEBUG: new connection from 127.0.0.1:49832
386s     app_test.go:1117: 03:34:02.230 - 77: DEBUG: new connection from 127.0.0.1:40788
386s     app_test.go:1117: 03:34:02.247 - 78: DEBUG: new connection from 127.0.0.1:60982
386s     app_test.go:1117: 03:34:02.277 - 75: DEBUG: new connection from 127.0.0.1:49380
386s     app_test.go:1117: 03:34:02.311 - 75: DEBUG: new connection from 127.0.0.1:49382
386s     app_test.go:1117: 03:34:02.324 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.337 - 78: DEBUG: new connection from 127.0.0.1:60998
386s     app_test.go:1117: 03:34:02.337 - 79: DEBUG: new connection from 127.0.0.1:42484
386s     app_test.go:1117: 03:34:02.337 - 75: DEBUG: new connection from 127.0.0.1:49386
386s     app_test.go:1117: 03:34:02.337 - 77: DEBUG: new connection from 127.0.0.1:40792
386s     app_test.go:1117: 03:34:02.337 - 76: DEBUG: new connection from 127.0.0.1:49844
386s     app_test.go:1117: 03:34:02.372 - 79: DEBUG: new connection from 127.0.0.1:42498
386s     app_test.go:1117: 03:34:02.398 - 75: DEBUG: new connection from 127.0.0.1:49396
386s     app_test.go:1117: 03:34:02.422 - 75: DEBUG: new connection from 127.0.0.1:49410
386s     app_test.go:1117: 03:34:02.436 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.457 - 76: DEBUG: new connection from 127.0.0.1:49860
386s     app_test.go:1117: 03:34:02.457 - 78: DEBUG: new connection from 127.0.0.1:32776
386s     app_test.go:1117: 03:34:02.457 - 79: DEBUG: new connection from 127.0.0.1:42504
386s     app_test.go:1117: 03:34:02.457 - 75: DEBUG: new connection from 127.0.0.1:49416
386s     app_test.go:1117: 03:34:02.458 - 80: DEBUG: new connection from 127.0.0.1:58438
386s     app_test.go:1117: 03:34:02.458 - 77: DEBUG: new connection from 127.0.0.1:40808
386s     app_test.go:1117: 03:34:02.493 - 80: DEBUG: new connection from 127.0.0.1:58448
386s     app_test.go:1117: 03:34:02.527 - 75: DEBUG: new connection from 127.0.0.1:49428
386s     app_test.go:1117: 03:34:02.553 - 75: DEBUG: new connection from 127.0.0.1:49434
386s     app_test.go:1117: 03:34:02.572 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.587 - 81: DEBUG: new connection from 127.0.0.1:47534
386s     app_test.go:1117: 03:34:02.588 - 76: DEBUG: new connection from 127.0.0.1:49868
386s     app_test.go:1117: 03:34:02.589 - 75: DEBUG: new connection from 127.0.0.1:49436
386s     app_test.go:1117: 03:34:02.591 - 77: DEBUG: new connection from 127.0.0.1:40818
386s     app_test.go:1117: 03:34:02.592 - 80: DEBUG: new connection from 127.0.0.1:58452
386s     app_test.go:1117: 03:34:02.593 - 78: DEBUG: new connection from 127.0.0.1:32778
386s     app_test.go:1117: 03:34:02.593 - 79: DEBUG: new connection from 127.0.0.1:42512
386s     app_test.go:1117: 03:34:02.886 - 75: DEBUG: new connection from 127.0.0.1:50208
386s     app_test.go:1117: 03:34:02.899 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.904 - 81: DEBUG: new connection from 127.0.0.1:46164
386s     app_test.go:1117: 03:34:02.905 - 78: DEBUG: new connection from 127.0.0.1:41552
386s     app_test.go:1117: 03:34:02.906 - 77: DEBUG: new connection from 127.0.0.1:37960
386s     app_test.go:1117: 03:34:02.906 - 76: DEBUG: new connection from 127.0.0.1:41562
386s     app_test.go:1117: 03:34:02.907 - 80: DEBUG: new connection from 127.0.0.1:41590
386s     app_test.go:1117: 03:34:02.907 - 75: DEBUG: new connection from 127.0.0.1:50210
386s     app_test.go:1117: 03:34:02.940 - 81: DEBUG: new connection from 127.0.0.1:46168
386s     app_test.go:1117: 03:34:02.961 - 75: DEBUG: new connection from 127.0.0.1:50218
386s     app_test.go:1117: 03:34:02.972 - 75: DEBUG: new connection from 127.0.0.1:50224
386s     app_test.go:1117: 03:34:02.984 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.163 - 75: DEBUG: new connection from 127.0.0.1:50240
386s     app_test.go:1117: 03:34:02.175 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.263 - 75: DEBUG: new connection from 127.0.0.1:50250
386s     app_test.go:1117: 03:34:02.277 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.354 - 77: DEBUG: new connection from 127.0.0.1:37964
386s     app_test.go:1117: 03:34:02.354 - 80: DEBUG: new connection from 127.0.0.1:41604
386s     app_test.go:1117: 03:34:02.355 - 81: DEBUG: new connection from 127.0.0.1:46172
386s     app_test.go:1117: 03:34:02.356 - 78: DEBUG: new connection from 127.0.0.1:41566
386s     app_test.go:1117: 03:34:02.359 - 75: DEBUG: new connection from 127.0.0.1:50256
386s     app_test.go:1117: 03:34:02.361 - 76: DEBUG: new connection from 127.0.0.1:41574
386s     app_test.go:1117: 03:34:02.390 - 81: DEBUG: new connection from 127.0.0.1:46182
386s     app_test.go:1117: 03:34:02.392 - 75: DEBUG: new connection from 127.0.0.1:50272
386s     app_test.go:1117: 03:34:02.393 - 77: DEBUG: new connection from 127.0.0.1:37970
386s     app_test.go:1117: 03:34:02.393 - 76: DEBUG: new connection from 127.0.0.1:41578
386s     app_test.go:1117: 03:34:02.394 - 78: DEBUG: new connection from 127.0.0.1:41576
386s     app_test.go:1117: 03:34:02.393 - 80: DEBUG: new connection from 127.0.0.1:41608
386s     app_test.go:1117: 03:34:02.507 - 75: DEBUG: new connection from 127.0.0.1:50284
386s     app_test.go:1117: 03:34:02.519 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.636 - 75: DEBUG: new connection from 127.0.0.1:50296
386s     app_test.go:1117: 03:34:02.647 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:34:02.990 - 75: DEBUG: new connection from 127.0.0.1:50300
386s     app_test.go:1117: 03:35:02.003 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.183 - 75: DEBUG: new connection from 127.0.0.1:50316
386s     app_test.go:1117: 03:35:02.194 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.283 - 75: DEBUG: new connection from 127.0.0.1:50332
386s     app_test.go:1117: 03:35:02.294 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.424 - 75: DEBUG: new connection from 127.0.0.1:50344
386s     app_test.go:1117: 03:35:02.436 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.443 - 75: DEBUG: new connection from 127.0.0.1:50358
386s     app_test.go:1117: 03:35:02.443 - 77: DEBUG: new connection from 127.0.0.1:37974
386s     app_test.go:1117: 03:35:02.444 - 80: DEBUG: new connection from 127.0.0.1:41616
386s     app_test.go:1117: 03:35:02.444 - 76: DEBUG: new connection from 127.0.0.1:41584
386s     app_test.go:1117: 03:35:02.446 - 78: DEBUG: new connection from 127.0.0.1:41578
386s     app_test.go:1117: 03:35:02.446 - 81: DEBUG: new connection from 127.0.0.1:46190
386s     app_test.go:1117: 03:35:02.526 - 75: DEBUG: new connection from 127.0.0.1:50368
386s     app_test.go:1117: 03:35:02.536 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.652 - 75: DEBUG: new connection from 127.0.0.1:50380
386s     app_test.go:1117: 03:35:02.663 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.010 - 75: DEBUG: new connection from 127.0.0.1:50660
386s     app_test.go:1117: 03:35:02.024 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.199 - 75: DEBUG: new connection from 127.0.0.1:50666
386s     app_test.go:1117: 03:35:02.214 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.304 - 75: DEBUG: new connection from 127.0.0.1:50680
386s     app_test.go:1117: 03:35:02.316 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.470 - 75: DEBUG: new connection from 127.0.0.1:50682
386s     app_test.go:1117: 03:35:02.483 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.492 - 76: DEBUG: new connection from 127.0.0.1:37308
386s     app_test.go:1117: 03:35:02.492 - 75: DEBUG: new connection from 127.0.0.1:50684
386s     app_test.go:1117: 03:35:02.492 - 78: DEBUG: new connection from 127.0.0.1:38776
386s     app_test.go:1117: 03:35:02.494 - 80: DEBUG: new connection from 127.0.0.1:41594
386s     app_test.go:1117: 03:35:02.495 - 81: DEBUG: new connection from 127.0.0.1:52248
386s     app_test.go:1117: 03:35:02.495 - 77: DEBUG: new connection from 127.0.0.1:54004
386s     app_test.go:1117: 03:35:02.548 - 75: DEBUG: new connection from 127.0.0.1:50690
386s     app_test.go:1117: 03:35:02.560 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.674 - 75: DEBUG: new connection from 127.0.0.1:50692
386s     app_test.go:1117: 03:35:02.687 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.031 - 75: DEBUG: new connection from 127.0.0.1:50708
386s     app_test.go:1117: 03:35:02.051 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.226 - 75: DEBUG: new connection from 127.0.0.1:50720
386s     app_test.go:1117: 03:35:02.242 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.326 - 75: DEBUG: new connection from 127.0.0.1:50734
386s     app_test.go:1117: 03:35:02.338 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.525 - 75: DEBUG: new connection from 127.0.0.1:50748
386s     app_test.go:1117: 03:35:02.535 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.542 - 81: DEBUG: new connection from 127.0.0.1:52252
386s     app_test.go:1117: 03:35:02.542 - 77: DEBUG: new connection from 127.0.0.1:54014
386s     app_test.go:1117: 03:35:02.543 - 80: DEBUG: new connection from 127.0.0.1:41596
386s     app_test.go:1117: 03:35:02.543 - 75: DEBUG: new connection from 127.0.0.1:50760
386s     app_test.go:1117: 03:35:02.543 - 76: DEBUG: new connection from 127.0.0.1:37322
386s     app_test.go:1117: 03:35:02.545 - 78: DEBUG: new connection from 127.0.0.1:38786
386s     app_test.go:1117: 03:35:02.570 - 75: DEBUG: new connection from 127.0.0.1:50772
386s     app_test.go:1117: 03:35:02.590 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.650 - 75: DEBUG: new connection from 127.0.0.1:50776
386s     app_test.go:1117: 03:35:02.661 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestRolesAdjustment_ReplaceStandBy (20.97s)
386s === RUN   TestRolesAdjustment_ReplaceStandByHonorFailureDomains
386s     app_test.go:1117: 03:35:02.830 - 82: DEBUG: new connection from 127.0.0.1:44374
386s     app_test.go:1117: 03:35:02.840 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.844 - 82: DEBUG: new connection from 127.0.0.1:44378
386s     app_test.go:1117: 03:35:02.895 - 82: DEBUG: new connection from 127.0.0.1:44392
386s     app_test.go:1117: 03:35:02.908 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.916 - 83: DEBUG: new connection from 127.0.0.1:37910
386s     app_test.go:1117: 03:35:02.917 - 82: DEBUG: new connection from 127.0.0.1:44394
386s     app_test.go:1117: 03:35:02.983 - 82: DEBUG: new connection from 127.0.0.1:44406
386s     app_test.go:1117: 03:35:02.999 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.008 - 84: DEBUG: new connection from 127.0.0.1:41880
386s     app_test.go:1117: 03:35:02.008 - 82: DEBUG: new connection from 127.0.0.1:44422
386s     app_test.go:1117: 03:35:02.008 - 83: DEBUG: new connection from 127.0.0.1:37918
386s     app_test.go:1117: 03:35:02.025 - 84: DEBUG: new connection from 127.0.0.1:41882
386s     app_test.go:1117: 03:35:02.050 - 82: DEBUG: new connection from 127.0.0.1:44432
386s     app_test.go:1117: 03:35:02.082 - 83: DEBUG: new connection from 127.0.0.1:37930
386s     app_test.go:1117: 03:35:02.112 - 82: DEBUG: new connection from 127.0.0.1:44446
386s     app_test.go:1117: 03:35:02.177 - 82: DEBUG: new connection from 127.0.0.1:44456
386s     app_test.go:1117: 03:35:02.191 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.204 - 84: DEBUG: new connection from 127.0.0.1:41896
386s     app_test.go:1117: 03:35:02.204 - 83: DEBUG: new connection from 127.0.0.1:37942
386s     app_test.go:1117: 03:35:02.204 - 82: DEBUG: new connection from 127.0.0.1:44470
386s     app_test.go:1117: 03:35:02.205 - 85: DEBUG: new connection from 127.0.0.1:59220
386s     app_test.go:1117: 03:35:02.224 - 85: DEBUG: new connection from 127.0.0.1:59232
386s     app_test.go:1117: 03:35:02.258 - 82: DEBUG: new connection from 127.0.0.1:44484
386s     app_test.go:1117: 03:35:02.267 - 82: DEBUG: new connection from 127.0.0.1:44492
386s     app_test.go:1117: 03:35:02.284 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.298 - 86: DEBUG: new connection from 127.0.0.1:34186
386s     app_test.go:1117: 03:35:02.298 - 83: DEBUG: new connection from 127.0.0.1:37954
386s     app_test.go:1117: 03:35:02.299 - 85: DEBUG: new connection from 127.0.0.1:59246
386s     app_test.go:1117: 03:35:02.299 - 84: DEBUG: new connection from 127.0.0.1:41912
386s     app_test.go:1117: 03:35:02.301 - 82: DEBUG: new connection from 127.0.0.1:44506
386s     app_test.go:1117: 03:35:02.325 - 86: DEBUG: new connection from 127.0.0.1:34194
386s     app_test.go:1117: 03:35:02.352 - 82: DEBUG: new connection from 127.0.0.1:44518
386s     app_test.go:1117: 03:35:02.377 - 82: DEBUG: new connection from 127.0.0.1:44532
386s     app_test.go:1117: 03:35:02.391 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.404 - 84: DEBUG: new connection from 127.0.0.1:41926
386s     app_test.go:1117: 03:35:02.404 - 82: DEBUG: new connection from 127.0.0.1:44540
386s     app_test.go:1117: 03:35:02.405 - 83: DEBUG: new connection from 127.0.0.1:37966
386s     app_test.go:1117: 03:35:02.405 - 86: DEBUG: new connection from 127.0.0.1:34202
386s     app_test.go:1117: 03:35:02.408 - 85: DEBUG: new connection from 127.0.0.1:59262
386s     app_test.go:1117: 03:35:02.414 - 87: DEBUG: new connection from 127.0.0.1:46668
386s     app_test.go:1117: 03:35:02.434 - 87: DEBUG: new connection from 127.0.0.1:46684
386s     app_test.go:1117: 03:35:02.463 - 82: DEBUG: new connection from 127.0.0.1:44552
386s     app_test.go:1117: 03:35:02.483 - 82: DEBUG: new connection from 127.0.0.1:44558
386s     app_test.go:1117: 03:35:02.498 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.521 - 83: DEBUG: new connection from 127.0.0.1:37978
386s     app_test.go:1117: 03:35:02.521 - 87: DEBUG: new connection from 127.0.0.1:46688
386s     app_test.go:1117: 03:35:02.526 - 82: DEBUG: new connection from 127.0.0.1:44574
386s     app_test.go:1117: 03:35:02.528 - 84: DEBUG: new connection from 127.0.0.1:41936
386s     app_test.go:1117: 03:35:02.529 - 88: DEBUG: new connection from 127.0.0.1:57544
386s     app_test.go:1117: 03:35:02.528 - 86: DEBUG: new connection from 127.0.0.1:34204
386s     app_test.go:1117: 03:35:02.539 - 85: DEBUG: new connection from 127.0.0.1:59270
386s     app_test.go:1117: 03:35:02.613 - 82: DEBUG: new connection from 127.0.0.1:44586
386s     app_test.go:1117: 03:35:02.627 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.642 - 89: DEBUG: new connection from 127.0.0.1:50632
386s     app_test.go:1117: 03:35:02.642 - 88: DEBUG: new connection from 127.0.0.1:57548
386s     app_test.go:1117: 03:35:02.642 - 84: DEBUG: new connection from 127.0.0.1:41952
386s     app_test.go:1117: 03:35:02.643 - 86: DEBUG: new connection from 127.0.0.1:34206
386s     app_test.go:1117: 03:35:02.643 - 82: DEBUG: new connection from 127.0.0.1:44592
386s     app_test.go:1117: 03:35:02.644 - 83: DEBUG: new connection from 127.0.0.1:37984
386s     app_test.go:1117: 03:35:02.644 - 87: DEBUG: new connection from 127.0.0.1:46702
386s     app_test.go:1117: 03:35:02.646 - 85: DEBUG: new connection from 127.0.0.1:59278
386s     app_test.go:1117: 03:35:02.741 - 82: DEBUG: new connection from 127.0.0.1:44606
386s     app_test.go:1117: 03:35:02.752 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.771 - 89: DEBUG: new connection from 127.0.0.1:50640
386s     app_test.go:1117: 03:35:02.771 - 85: DEBUG: new connection from 127.0.0.1:59286
386s     app_test.go:1117: 03:35:02.771 - 83: DEBUG: new connection from 127.0.0.1:37990
386s     app_test.go:1117: 03:35:02.771 - 87: DEBUG: new connection from 127.0.0.1:46706
386s     app_test.go:1117: 03:35:02.772 - 84: DEBUG: new connection from 127.0.0.1:41958
386s     app_test.go:1117: 03:35:02.772 - 86: DEBUG: new connection from 127.0.0.1:34212
386s     app_test.go:1117: 03:35:02.771 - 88: DEBUG: new connection from 127.0.0.1:57562
386s     app_test.go:1117: 03:35:02.776 - 82: DEBUG: new connection from 127.0.0.1:44620
386s     app_test.go:1117: 03:35:02.791 - 90: DEBUG: new connection from 127.0.0.1:50302
386s     app_test.go:1117: 03:35:02.857 - 82: DEBUG: new connection from 127.0.0.1:44632
386s     app_test.go:1117: 03:35:02.869 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.878 - 88: DEBUG: new connection from 127.0.0.1:57574
386s     app_test.go:1117: 03:35:02.879 - 89: DEBUG: new connection from 127.0.0.1:50648
386s     app_test.go:1117: 03:35:02.880 - 90: DEBUG: new connection from 127.0.0.1:50316
386s     app_test.go:1117: 03:35:02.880 - 87: DEBUG: new connection from 127.0.0.1:46708
386s     app_test.go:1117: 03:35:02.880 - 83: DEBUG: new connection from 127.0.0.1:37998
386s     app_test.go:1117: 03:35:02.881 - 84: DEBUG: new connection from 127.0.0.1:41964
386s     app_test.go:1117: 03:35:02.881 - 82: DEBUG: new connection from 127.0.0.1:44644
386s     app_test.go:1117: 03:35:02.883 - 85: DEBUG: new connection from 127.0.0.1:59298
386s     app_test.go:1117: 03:35:02.914 - 89: DEBUG: new connection from 127.0.0.1:50658
386s     app_test.go:1117: 03:35:02.933 - 82: DEBUG: new connection from 127.0.0.1:44656
386s     app_test.go:1117: 03:35:02.939 - 82: DEBUG: new connection from 127.0.0.1:44666
386s     app_test.go:1117: 03:35:02.955 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.140 - 82: DEBUG: new connection from 127.0.0.1:44682
386s     app_test.go:1117: 03:35:02.157 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.232 - 82: DEBUG: new connection from 127.0.0.1:44688
386s     app_test.go:1117: 03:35:02.246 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.322 - 88: DEBUG: new connection from 127.0.0.1:57578
386s     app_test.go:1117: 03:35:02.322 - 87: DEBUG: new connection from 127.0.0.1:46710
386s     app_test.go:1117: 03:35:02.323 - 85: DEBUG: new connection from 127.0.0.1:59312
386s     app_test.go:1117: 03:35:02.324 - 90: DEBUG: new connection from 127.0.0.1:50324
386s     app_test.go:1117: 03:35:02.324 - 83: DEBUG: new connection from 127.0.0.1:38000
386s     app_test.go:1117: 03:35:02.328 - 82: DEBUG: new connection from 127.0.0.1:44692
386s     app_test.go:1117: 03:35:02.329 - 89: DEBUG: new connection from 127.0.0.1:50666
386s     app_test.go:1117: 03:35:02.335 - 84: DEBUG: new connection from 127.0.0.1:41978
386s     app_test.go:1117: 03:35:02.372 - 85: DEBUG: new connection from 127.0.0.1:59316
386s     app_test.go:1117: 03:35:02.372 - 88: DEBUG: new connection from 127.0.0.1:57588
386s     app_test.go:1117: 03:35:02.378 - 83: DEBUG: new connection from 127.0.0.1:38016
386s     app_test.go:1117: 03:35:02.378 - 89: DEBUG: new connection from 127.0.0.1:50668
386s     app_test.go:1117: 03:35:02.378 - 82: DEBUG: new connection from 127.0.0.1:44700
386s     app_test.go:1117: 03:35:02.378 - 84: DEBUG: new connection from 127.0.0.1:41980
386s     app_test.go:1117: 03:35:02.378 - 90: DEBUG: new connection from 127.0.0.1:50334
386s     app_test.go:1117: 03:35:02.378 - 87: DEBUG: new connection from 127.0.0.1:46714
386s     app_test.go:1117: 03:35:02.444 - 82: DEBUG: new connection from 127.0.0.1:44712
386s     app_test.go:1117: 03:35:02.459 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.575 - 82: DEBUG: new connection from 127.0.0.1:44714
386s     app_test.go:1117: 03:35:02.591 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.688 - 82: DEBUG: new connection from 127.0.0.1:44728
386s     app_test.go:1117: 03:35:02.699 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.812 - 82: DEBUG: new connection from 127.0.0.1:44732
386s     app_test.go:1117: 03:35:02.825 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.968 - 82: DEBUG: new connection from 127.0.0.1:57456
386s     app_test.go:1117: 03:35:02.983 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.165 - 82: DEBUG: new connection from 127.0.0.1:57468
386s     app_test.go:1117: 03:35:02.177 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.255 - 82: DEBUG: new connection from 127.0.0.1:57470
386s     app_test.go:1117: 03:35:02.268 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.420 - 82: DEBUG: new connection from 127.0.0.1:57478
386s     app_test.go:1117: 03:35:02.433 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.442 - 88: DEBUG: new connection from 127.0.0.1:58420
386s     app_test.go:1117: 03:35:02.442 - 85: DEBUG: new connection from 127.0.0.1:33316
386s     app_test.go:1117: 03:35:02.448 - 87: DEBUG: new connection from 127.0.0.1:42792
386s     app_test.go:1117: 03:35:02.456 - 84: DEBUG: new connection from 127.0.0.1:43218
386s     app_test.go:1117: 03:35:02.456 - 90: DEBUG: new connection from 127.0.0.1:56156
386s     app_test.go:1117: 03:35:02.457 - 89: DEBUG: new connection from 127.0.0.1:51946
386s     app_test.go:1117: 03:35:02.457 - 83: DEBUG: new connection from 127.0.0.1:53060
386s     app_test.go:1117: 03:35:02.457 - 82: DEBUG: new connection from 127.0.0.1:57492
386s     app_test.go:1117: 03:35:02.477 - 82: DEBUG: new connection from 127.0.0.1:57500
386s     app_test.go:1117: 03:35:02.489 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.602 - 82: DEBUG: new connection from 127.0.0.1:57508
386s     app_test.go:1117: 03:35:02.612 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.711 - 82: DEBUG: new connection from 127.0.0.1:57518
386s     app_test.go:1117: 03:35:02.725 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.836 - 82: DEBUG: new connection from 127.0.0.1:57528
386s     app_test.go:1117: 03:35:02.848 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.994 - 82: DEBUG: new connection from 127.0.0.1:57538
386s     app_test.go:1117: 03:35:02.012 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.185 - 82: DEBUG: new connection from 127.0.0.1:57540
386s     app_test.go:1117: 03:35:02.196 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.280 - 82: DEBUG: new connection from 127.0.0.1:57550
386s     app_test.go:1117: 03:35:02.292 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.494 - 82: DEBUG: new connection from 127.0.0.1:57564
386s     app_test.go:1117: 03:35:02.497 - 82: DEBUG: new connection from 127.0.0.1:57566
386s     app_test.go:1117: 03:35:02.508 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.515 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.517 - 88: DEBUG: new connection from 127.0.0.1:58436
386s     app_test.go:1117: 03:35:02.518 - 83: DEBUG: new connection from 127.0.0.1:53062
386s     app_test.go:1117: 03:35:02.518 - 87: DEBUG: new connection from 127.0.0.1:42798
386s     app_test.go:1117: 03:35:02.519 - 82: DEBUG: new connection from 127.0.0.1:57582
386s     app_test.go:1117: 03:35:02.520 - 84: DEBUG: new connection from 127.0.0.1:43230
386s     app_test.go:1117: 03:35:02.520 - 89: DEBUG: new connection from 127.0.0.1:51954
386s     app_test.go:1117: 03:35:02.521 - 85: DEBUG: new connection from 127.0.0.1:33320
386s     app_test.go:1117: 03:35:02.525 - 90: DEBUG: new connection from 127.0.0.1:56162
386s     app_test.go:1117: 03:35:02.619 - 82: DEBUG: new connection from 127.0.0.1:57588
386s     app_test.go:1117: 03:35:02.630 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.732 - 82: DEBUG: new connection from 127.0.0.1:57592
386s     app_test.go:1117: 03:35:02.750 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.856 - 82: DEBUG: new connection from 127.0.0.1:57598
386s     app_test.go:1117: 03:35:02.867 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.030 - 82: DEBUG: new connection from 127.0.0.1:43036
386s     app_test.go:1117: 03:35:02.042 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.202 - 82: DEBUG: new connection from 127.0.0.1:43044
386s     app_test.go:1117: 03:35:02.216 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.297 - 82: DEBUG: new connection from 127.0.0.1:43058
386s     app_test.go:1117: 03:35:02.307 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.537 - 82: DEBUG: new connection from 127.0.0.1:43064
386s     app_test.go:1117: 03:35:02.548 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.559 - 82: DEBUG: new connection from 127.0.0.1:43078
386s     app_test.go:1117: 03:35:02.571 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.582 - 82: DEBUG: new connection from 127.0.0.1:43090
386s     app_test.go:1117: 03:35:02.582 - 84: DEBUG: new connection from 127.0.0.1:58514
386s     app_test.go:1117: 03:35:02.583 - 87: DEBUG: new connection from 127.0.0.1:56982
386s     app_test.go:1117: 03:35:02.583 - 88: DEBUG: new connection from 127.0.0.1:53504
386s     app_test.go:1117: 03:35:02.585 - 83: DEBUG: new connection from 127.0.0.1:49288
386s     app_test.go:1117: 03:35:02.585 - 85: DEBUG: new connection from 127.0.0.1:37328
386s     app_test.go:1117: 03:35:02.586 - 90: DEBUG: new connection from 127.0.0.1:39808
386s     app_test.go:1117: 03:35:02.589 - 89: DEBUG: new connection from 127.0.0.1:36464
386s     app_test.go:1117: 03:35:02.636 - 82: DEBUG: new connection from 127.0.0.1:43094
386s     app_test.go:1117: 03:35:02.649 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.756 - 82: DEBUG: new connection from 127.0.0.1:43100
386s     app_test.go:1117: 03:35:02.769 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1117: 03:35:02.829 - 82: DEBUG: new connection from 127.0.0.1:43108
386s     app_test.go:1117: 03:35:02.839 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: TestRolesAdjustment_ReplaceStandByHonorFailureDomains (21.15s)
386s === RUN   TestOpen
386s     app_test.go:1117: 03:35:02.980 - 91: DEBUG: new connection from 127.0.0.1:42656
386s     app_test.go:1117: 03:35:02.981 - 91: DEBUG: new connection from 127.0.0.1:42670
386s     app_test.go:1117: 03:35:02.006 - 91: DEBUG: attempt 1: server 127.0.0.1:9000: connected
386s     app_test.go:1117: 03:35:02.006 - 91: DEBUG: attempt 1: server 127.0.0.1:9000: connected
386s     app_test.go:1117: 03:35:02.017 - 91: DEBUG: new connection from 127.0.0.1:42672
386s --- PASS: TestOpen (0.09s)
386s === RUN   TestOptions
386s --- PASS: TestOptions (0.05s)
386s === RUN   TestProxy_Error
386s     app_test.go:1117: 03:35:02.129 - 93: DEBUG: new connection from 127.0.0.1:42688
386s     app_test.go:1117: 03:35:02.130 - 93: DEBUG: new connection from 127.0.0.1:42696
386s     app_test.go:1117: 03:35:02.150 - 93: DEBUG: attempt 1: server 127.0.0.1:9000: connected
386s     app_test.go:1117: 03:35:02.154 - 93: DEBUG: new connection from 127.0.0.1:42706
386s --- PASS: TestProxy_Error (0.41s)
386s === RUN   TestReady_Cancel
386s     app_test.go:1117: 03:35:02.532 - 94: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused
386s --- PASS: TestReady_Cancel (0.24s)
386s === RUN   TestExternalConnWithTCP
386s     app_test.go:1142: 03:35:02.779 - 95: DEBUG: attempt 1: server 127.0.0.1:9191: connected
386s     app_test.go:1142: 03:35:02.822 - 96: DEBUG: attempt 1: server 127.0.0.1:9191: connected
386s     app_test.go:1142: 03:35:02.873 - 97: DEBUG: attempt 1: server 127.0.0.1:9191: connected
386s     app_test.go:1142: 03:35:02.947 - 95: DEBUG: attempt 1: server 127.0.0.1:9191: connected
386s --- PASS: TestExternalConnWithTCP (0.26s)
386s === RUN   TestExternalConnWithPipe
386s     app_test.go:1142: 03:35:02.044 - 98: DEBUG: attempt 1: server first: connected
386s     app_test.go:1142: 03:35:02.085 - 99: DEBUG: attempt 1: server first: connected
386s     app_test.go:1142: 03:35:02.133 - 100: DEBUG: attempt 1: server first: connected
386s     app_test.go:1142: 03:35:02.205 - 98: DEBUG: attempt 1: server first: connected
386s --- PASS: TestExternalConnWithPipe (0.26s)
386s === RUN   TestParallelNewApp
386s === PAUSE TestParallelNewApp
386s === RUN   Test_TxRowsAffected
386s     app_test.go:1142: 03:35:02.290 - 101: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s     app_test.go:1142: 03:35:02.298 - 101: DEBUG: attempt 1: server 127.0.0.1:9001: connected
386s --- PASS: Test_TxRowsAffected (0.08s)
386s === CONT  TestParallelNewApp
386s === RUN   TestParallelNewApp/run-0
386s === PAUSE TestParallelNewApp/run-0
386s === RUN   TestParallelNewApp/run-1
386s === PAUSE TestParallelNewApp/run-1
386s === RUN   TestParallelNewApp/run-2
386s === PAUSE TestParallelNewApp/run-2
386s === RUN   TestParallelNewApp/run-3
386s === PAUSE TestParallelNewApp/run-3
386s === RUN   TestParallelNewApp/run-4
386s === PAUSE TestParallelNewApp/run-4
386s === RUN   TestParallelNewApp/run-5
386s === PAUSE TestParallelNewApp/run-5
386s === RUN   TestParallelNewApp/run-6
386s === PAUSE TestParallelNewApp/run-6
386s === RUN   TestParallelNewApp/run-7
386s === PAUSE TestParallelNewApp/run-7
386s === RUN   TestParallelNewApp/run-8
386s === PAUSE TestParallelNewApp/run-8
386s === RUN   TestParallelNewApp/run-9
386s === PAUSE TestParallelNewApp/run-9
386s === RUN   TestParallelNewApp/run-10
386s === PAUSE TestParallelNewApp/run-10
386s === RUN   TestParallelNewApp/run-11
386s === PAUSE TestParallelNewApp/run-11
386s === RUN   TestParallelNewApp/run-12
386s === PAUSE TestParallelNewApp/run-12
386s === RUN   TestParallelNewApp/run-13
386s === PAUSE TestParallelNewApp/run-13
386s === RUN   TestParallelNewApp/run-14
386s === PAUSE TestParallelNewApp/run-14
386s === RUN   TestParallelNewApp/run-15
386s === PAUSE TestParallelNewApp/run-15
386s === RUN   TestParallelNewApp/run-16
386s === PAUSE TestParallelNewApp/run-16
386s === RUN   TestParallelNewApp/run-17
386s === PAUSE TestParallelNewApp/run-17
386s === RUN   TestParallelNewApp/run-18
386s === PAUSE TestParallelNewApp/run-18
386s === RUN   TestParallelNewApp/run-19
386s === PAUSE TestParallelNewApp/run-19
386s === RUN   TestParallelNewApp/run-20
386s === PAUSE TestParallelNewApp/run-20
386s === RUN   TestParallelNewApp/run-21
386s === PAUSE TestParallelNewApp/run-21
386s === RUN   TestParallelNewApp/run-22
386s === PAUSE TestParallelNewApp/run-22
386s === RUN   TestParallelNewApp/run-23
386s === PAUSE TestParallelNewApp/run-23
386s === RUN   TestParallelNewApp/run-24
386s === PAUSE TestParallelNewApp/run-24
386s === RUN   TestParallelNewApp/run-25
386s === PAUSE TestParallelNewApp/run-25
386s === RUN   TestParallelNewApp/run-26
386s === PAUSE TestParallelNewApp/run-26
386s === RUN   TestParallelNewApp/run-27
386s === PAUSE TestParallelNewApp/run-27
386s === RUN   TestParallelNewApp/run-28
386s === PAUSE TestParallelNewApp/run-28
386s === RUN   TestParallelNewApp/run-29
386s === PAUSE TestParallelNewApp/run-29
386s === RUN   TestParallelNewApp/run-30
386s === PAUSE TestParallelNewApp/run-30
386s === RUN   TestParallelNewApp/run-31
386s === PAUSE TestParallelNewApp/run-31
386s === RUN   TestParallelNewApp/run-32
386s === PAUSE TestParallelNewApp/run-32
386s === RUN   TestParallelNewApp/run-33
386s === PAUSE TestParallelNewApp/run-33
386s === RUN   TestParallelNewApp/run-34
386s === PAUSE TestParallelNewApp/run-34
386s === RUN   TestParallelNewApp/run-35
386s === PAUSE TestParallelNewApp/run-35
386s === RUN   TestParallelNewApp/run-36
386s === PAUSE TestParallelNewApp/run-36
386s === RUN   TestParallelNewApp/run-37
386s === PAUSE TestParallelNewApp/run-37
386s === RUN   TestParallelNewApp/run-38
386s === PAUSE TestParallelNewApp/run-38
386s === RUN   TestParallelNewApp/run-39
386s === PAUSE TestParallelNewApp/run-39
386s === RUN   TestParallelNewApp/run-40
386s === PAUSE TestParallelNewApp/run-40
386s === RUN   TestParallelNewApp/run-41
386s === PAUSE TestParallelNewApp/run-41
386s === RUN   TestParallelNewApp/run-42
386s === PAUSE TestParallelNewApp/run-42
386s === RUN   TestParallelNewApp/run-43
386s === PAUSE TestParallelNewApp/run-43
386s === RUN   TestParallelNewApp/run-44
386s === PAUSE TestParallelNewApp/run-44
386s === RUN   TestParallelNewApp/run-45
386s === PAUSE TestParallelNewApp/run-45
386s === RUN   TestParallelNewApp/run-46
386s === PAUSE TestParallelNewApp/run-46
386s === RUN   TestParallelNewApp/run-47
386s === PAUSE TestParallelNewApp/run-47
386s === RUN   TestParallelNewApp/run-48
386s === PAUSE TestParallelNewApp/run-48
386s === RUN   TestParallelNewApp/run-49
386s === PAUSE TestParallelNewApp/run-49
386s === RUN   TestParallelNewApp/run-50
386s === PAUSE TestParallelNewApp/run-50
386s === RUN   TestParallelNewApp/run-51
386s === PAUSE TestParallelNewApp/run-51
386s === RUN   TestParallelNewApp/run-52
386s === PAUSE TestParallelNewApp/run-52
386s === RUN   TestParallelNewApp/run-53
386s === PAUSE TestParallelNewApp/run-53
386s === RUN   TestParallelNewApp/run-54
386s === PAUSE TestParallelNewApp/run-54
386s === RUN   TestParallelNewApp/run-55
386s === PAUSE TestParallelNewApp/run-55
386s === RUN   TestParallelNewApp/run-56
386s === PAUSE TestParallelNewApp/run-56
386s === RUN   TestParallelNewApp/run-57
386s === PAUSE TestParallelNewApp/run-57
386s === RUN   TestParallelNewApp/run-58
386s === PAUSE TestParallelNewApp/run-58
386s === RUN   TestParallelNewApp/run-59
386s === PAUSE TestParallelNewApp/run-59
386s === RUN   TestParallelNewApp/run-60
386s === PAUSE TestParallelNewApp/run-60
386s === RUN   TestParallelNewApp/run-61
386s === PAUSE TestParallelNewApp/run-61
386s === RUN   TestParallelNewApp/run-62
386s === PAUSE TestParallelNewApp/run-62
386s === RUN   TestParallelNewApp/run-63
386s === PAUSE TestParallelNewApp/run-63
386s === RUN   TestParallelNewApp/run-64
386s === PAUSE TestParallelNewApp/run-64
386s === RUN   TestParallelNewApp/run-65
386s === PAUSE TestParallelNewApp/run-65
386s === RUN   TestParallelNewApp/run-66
386s === PAUSE TestParallelNewApp/run-66
386s === RUN   TestParallelNewApp/run-67
386s === PAUSE TestParallelNewApp/run-67
386s === RUN   TestParallelNewApp/run-68
386s === PAUSE TestParallelNewApp/run-68
386s === RUN   TestParallelNewApp/run-69
386s === PAUSE TestParallelNewApp/run-69
386s === RUN   TestParallelNewApp/run-70
386s === PAUSE TestParallelNewApp/run-70
386s === RUN   TestParallelNewApp/run-71
386s === PAUSE TestParallelNewApp/run-71
386s === RUN   TestParallelNewApp/run-72
386s === PAUSE TestParallelNewApp/run-72
386s === RUN   TestParallelNewApp/run-73
386s === PAUSE TestParallelNewApp/run-73
386s === RUN   TestParallelNewApp/run-74
386s === PAUSE TestParallelNewApp/run-74
386s === RUN   TestParallelNewApp/run-75
386s === PAUSE TestParallelNewApp/run-75
386s === RUN   TestParallelNewApp/run-76
386s === PAUSE TestParallelNewApp/run-76
386s === RUN   TestParallelNewApp/run-77
386s === PAUSE TestParallelNewApp/run-77
386s === RUN   TestParallelNewApp/run-78
386s === PAUSE TestParallelNewApp/run-78
386s === RUN   TestParallelNewApp/run-79
386s === PAUSE TestParallelNewApp/run-79
386s === RUN   TestParallelNewApp/run-80
386s === PAUSE TestParallelNewApp/run-80
386s === RUN   TestParallelNewApp/run-81
386s === PAUSE TestParallelNewApp/run-81
386s === RUN   TestParallelNewApp/run-82
386s === PAUSE TestParallelNewApp/run-82
386s === RUN   TestParallelNewApp/run-83
386s === PAUSE TestParallelNewApp/run-83
386s === RUN   TestParallelNewApp/run-84
386s === PAUSE TestParallelNewApp/run-84
386s === RUN   TestParallelNewApp/run-85
386s === PAUSE TestParallelNewApp/run-85
386s === RUN   TestParallelNewApp/run-86
386s === PAUSE TestParallelNewApp/run-86
386s === RUN   TestParallelNewApp/run-87
386s === PAUSE TestParallelNewApp/run-87
386s === RUN   TestParallelNewApp/run-88
386s === PAUSE TestParallelNewApp/run-88
386s === RUN   TestParallelNewApp/run-89
386s === PAUSE TestParallelNewApp/run-89
386s === RUN   TestParallelNewApp/run-90
386s === PAUSE TestParallelNewApp/run-90
386s === RUN   TestParallelNewApp/run-91
386s === PAUSE TestParallelNewApp/run-91
386s === RUN   TestParallelNewApp/run-92
386s === PAUSE TestParallelNewApp/run-92
386s === RUN   TestParallelNewApp/run-93
386s === PAUSE TestParallelNewApp/run-93
386s === RUN   TestParallelNewApp/run-94
386s === PAUSE TestParallelNewApp/run-94
386s === RUN   TestParallelNewApp/run-95
386s === PAUSE TestParallelNewApp/run-95
386s === RUN   TestParallelNewApp/run-96
386s === PAUSE TestParallelNewApp/run-96
386s === RUN   TestParallelNewApp/run-97
386s === PAUSE TestParallelNewApp/run-97
386s === RUN   TestParallelNewApp/run-98
386s === PAUSE TestParallelNewApp/run-98
386s === RUN   TestParallelNewApp/run-99
386s === PAUSE TestParallelNewApp/run-99
386s === CONT  TestParallelNewApp/run-0
386s === CONT  TestParallelNewApp/run-50
386s === CONT  TestParallelNewApp/run-49
386s === CONT  TestParallelNewApp/run-13
386s === CONT  TestParallelNewApp/run-7
386s === CONT  TestParallelNewApp/run-19
386s === CONT  TestParallelNewApp/run-48
386s === CONT  TestParallelNewApp/run-25
386s === CONT  TestParallelNewApp/run-18
386s === CONT  TestParallelNewApp/run-17
386s === CONT  TestParallelNewApp/run-16
386s === CONT  TestParallelNewApp/run-15
386s === CONT  TestParallelNewApp/run-14
386s === CONT  TestParallelNewApp/run-10
386s === CONT  TestParallelNewApp/run-12
386s === CONT  TestParallelNewApp/run-11
386s === CONT  TestParallelNewApp/run-37
386s === CONT  TestParallelNewApp/run-47
386s === CONT  TestParallelNewApp/run-46
386s === CONT  TestParallelNewApp/run-45
386s === CONT  TestParallelNewApp/run-44
386s === CONT  TestParallelNewApp/run-43
386s === CONT  TestParallelNewApp/run-42
386s === CONT  TestParallelNewApp/run-41
386s === CONT  TestParallelNewApp/run-40
386s === CONT  TestParallelNewApp/run-39
386s === CONT  TestParallelNewApp/run-38
386s === CONT  TestParallelNewApp/run-22
386s === CONT  TestParallelNewApp/run-24
386s === CONT  TestParallelNewApp/run-23
386s === CONT  TestParallelNewApp/run-9
386s === CONT  TestParallelNewApp/run-4
386s === CONT  TestParallelNewApp/run-6
386s === CONT  TestParallelNewApp/run-5
386s === CONT  TestParallelNewApp/run-31
386s === CONT  TestParallelNewApp/run-36
386s === CONT  TestParallelNewApp/run-35
386s === CONT  TestParallelNewApp/run-34
386s === CONT  TestParallelNewApp/run-33
386s === CONT  TestParallelNewApp/run-32
386s === CONT  TestParallelNewApp/run-28
386s === CONT  TestParallelNewApp/run-30
386s === CONT  TestParallelNewApp/run-29
386s === CONT  TestParallelNewApp/run-2
386s === CONT  TestParallelNewApp/run-3
386s === CONT  TestParallelNewApp/run-21
386s === CONT  TestParallelNewApp/run-20
386s === CONT  TestParallelNewApp/run-88
386s === CONT  TestParallelNewApp/run-99
386s === CONT  TestParallelNewApp/run-98
386s === CONT  TestParallelNewApp/run-97
386s === CONT  TestParallelNewApp/run-96
386s === CONT  TestParallelNewApp/run-95
386s === CONT  TestParallelNewApp/run-94
386s === CONT  TestParallelNewApp/run-93
386s === CONT  TestParallelNewApp/run-92
386s === CONT  TestParallelNewApp/run-91
386s === CONT  TestParallelNewApp/run-90
386s === CONT  TestParallelNewApp/run-89
386s === CONT  TestParallelNewApp/run-63
386s === CONT  TestParallelNewApp/run-74
386s === CONT  TestParallelNewApp/run-73
386s === CONT  TestParallelNewApp/run-72
386s === CONT  TestParallelNewApp/run-71
386s === CONT  TestParallelNewApp/run-70
386s === CONT  TestParallelNewApp/run-69
386s === CONT  TestParallelNewApp/run-68
386s === CONT  TestParallelNewApp/run-67
386s === CONT  TestParallelNewApp/run-66
386s === CONT  TestParallelNewApp/run-65
386s === CONT  TestParallelNewApp/run-64
386s === CONT  TestParallelNewApp/run-8
386s === CONT  TestParallelNewApp/run-27
386s === CONT  TestParallelNewApp/run-26
386s === CONT  TestParallelNewApp/run-57
386s === CONT  TestParallelNewApp/run-62
386s === CONT  TestParallelNewApp/run-61
386s === CONT  TestParallelNewApp/run-60
386s === CONT  TestParallelNewApp/run-59
386s === CONT  TestParallelNewApp/run-58
386s === CONT  TestParallelNewApp/run-82
386s === CONT  TestParallelNewApp/run-87
386s === CONT  TestParallelNewApp/run-86
386s === CONT  TestParallelNewApp/run-85
386s === CONT  TestParallelNewApp/run-84
386s === CONT  TestParallelNewApp/run-83
386s === CONT  TestParallelNewApp/run-79
386s === CONT  TestParallelNewApp/run-81
386s === CONT  TestParallelNewApp/run-80
386s === CONT  TestParallelNewApp/run-1
386s === CONT  TestParallelNewApp/run-54
386s === CONT  TestParallelNewApp/run-56
386s === CONT  TestParallelNewApp/run-55
386s === CONT  TestParallelNewApp/run-77
386s === CONT  TestParallelNewApp/run-78
386s === CONT  TestParallelNewApp/run-52
386s === CONT  TestParallelNewApp/run-53
386s === CONT  TestParallelNewApp/run-51
386s === CONT  TestParallelNewApp/run-76
386s === CONT  TestParallelNewApp/run-75
386s --- PASS: TestParallelNewApp (0.00s)
386s     --- PASS: TestParallelNewApp/run-13 (0.10s)
386s     --- PASS: TestParallelNewApp/run-19 (0.10s)
386s     --- PASS: TestParallelNewApp/run-0 (0.10s)
386s     --- PASS: TestParallelNewApp/run-7 (0.11s)
386s     --- PASS: TestParallelNewApp/run-48 (0.11s)
386s     --- PASS: TestParallelNewApp/run-25 (0.11s)
386s     --- PASS: TestParallelNewApp/run-50 (0.11s)
386s     --- PASS: TestParallelNewApp/run-49 (0.11s)
386s     --- PASS: TestParallelNewApp/run-16 (0.10s)
386s     --- PASS: TestParallelNewApp/run-12 (0.10s)
386s     --- PASS: TestParallelNewApp/run-11 (0.11s)
386s     --- PASS: TestParallelNewApp/run-14 (0.11s)
386s     --- PASS: TestParallelNewApp/run-10 (0.11s)
386s     --- PASS: TestParallelNewApp/run-15 (0.12s)
386s     --- PASS: TestParallelNewApp/run-37 (0.06s)
386s     --- PASS: TestParallelNewApp/run-47 (0.06s)
386s     --- PASS: TestParallelNewApp/run-46 (0.10s)
386s     --- PASS: TestParallelNewApp/run-44 (0.10s)
386s     --- PASS: TestParallelNewApp/run-43 (0.10s)
386s     --- PASS: TestParallelNewApp/run-45 (0.10s)
386s     --- PASS: TestParallelNewApp/run-41 (0.07s)
386s     --- PASS: TestParallelNewApp/run-42 (0.09s)
386s     --- PASS: TestParallelNewApp/run-18 (0.28s)
386s     --- PASS: TestParallelNewApp/run-17 (0.36s)
386s     --- PASS: TestParallelNewApp/run-39 (0.19s)
386s     --- PASS: TestParallelNewApp/run-40 (0.20s)
386s     --- PASS: TestParallelNewApp/run-22 (0.20s)
386s     --- PASS: TestParallelNewApp/run-38 (0.20s)
386s     --- PASS: TestParallelNewApp/run-23 (0.19s)
386s     --- PASS: TestParallelNewApp/run-24 (0.19s)
386s     --- PASS: TestParallelNewApp/run-9 (0.18s)
386s     --- PASS: TestParallelNewApp/run-4 (0.12s)
386s     --- PASS: TestParallelNewApp/run-6 (0.10s)
386s     --- PASS: TestParallelNewApp/run-36 (0.11s)
386s     --- PASS: TestParallelNewApp/run-34 (0.09s)
386s     --- PASS: TestParallelNewApp/run-5 (0.12s)
386s     --- PASS: TestParallelNewApp/run-31 (0.11s)
386s     --- PASS: TestParallelNewApp/run-35 (0.12s)
386s     --- PASS: TestParallelNewApp/run-32 (0.08s)
386s     --- PASS: TestParallelNewApp/run-33 (0.11s)
386s     --- PASS: TestParallelNewApp/run-29 (0.08s)
386s     --- PASS: TestParallelNewApp/run-28 (0.10s)
386s     --- PASS: TestParallelNewApp/run-3 (0.11s)
386s     --- PASS: TestParallelNewApp/run-2 (0.12s)
386s     --- PASS: TestParallelNewApp/run-30 (0.12s)
386s     --- PASS: TestParallelNewApp/run-21 (0.10s)
386s     --- PASS: TestParallelNewApp/run-20 (0.10s)
386s     --- PASS: TestParallelNewApp/run-88 (0.10s)
386s     --- PASS: TestParallelNewApp/run-99 (0.10s)
386s     --- PASS: TestParallelNewApp/run-98 (0.10s)
386s     --- PASS: TestParallelNewApp/run-97 (0.11s)
386s     --- PASS: TestParallelNewApp/run-93 (0.10s)
386s     --- PASS: TestParallelNewApp/run-94 (0.11s)
386s     --- PASS: TestParallelNewApp/run-96 (0.12s)
386s     --- PASS: TestParallelNewApp/run-92 (0.09s)
386s     --- PASS: TestParallelNewApp/run-95 (0.11s)
386s     --- PASS: TestParallelNewApp/run-91 (0.08s)
386s     --- PASS: TestParallelNewApp/run-90 (0.09s)
386s     --- PASS: TestParallelNewApp/run-70 (0.06s)
386s     --- PASS: TestParallelNewApp/run-73 (0.11s)
386s     --- PASS: TestParallelNewApp/run-71 (0.11s)
386s     --- PASS: TestParallelNewApp/run-63 (0.12s)
386s     --- PASS: TestParallelNewApp/run-89 (0.13s)
386s     --- PASS: TestParallelNewApp/run-74 (0.12s)
386s     --- PASS: TestParallelNewApp/run-72 (0.12s)
386s     --- PASS: TestParallelNewApp/run-69 (0.10s)
386s     --- PASS: TestParallelNewApp/run-68 (0.07s)
386s     --- PASS: TestParallelNewApp/run-66 (0.09s)
386s     --- PASS: TestParallelNewApp/run-67 (0.10s)
386s     --- PASS: TestParallelNewApp/run-8 (0.09s)
386s     --- PASS: TestParallelNewApp/run-27 (0.11s)
386s     --- PASS: TestParallelNewApp/run-65 (0.13s)
386s     --- PASS: TestParallelNewApp/run-64 (0.12s)
386s     --- PASS: TestParallelNewApp/run-26 (0.10s)
386s     --- PASS: TestParallelNewApp/run-57 (0.11s)
386s     --- PASS: TestParallelNewApp/run-62 (0.09s)
386s     --- PASS: TestParallelNewApp/run-60 (0.10s)
386s     --- PASS: TestParallelNewApp/run-61 (0.12s)
386s     --- PASS: TestParallelNewApp/run-59 (0.12s)
386s     --- PASS: TestParallelNewApp/run-58 (0.12s)
386s     --- PASS: TestParallelNewApp/run-86 (0.09s)
386s     --- PASS: TestParallelNewApp/run-84 (0.09s)
386s     --- PASS: TestParallelNewApp/run-83 (0.09s)
386s     --- PASS: TestParallelNewApp/run-79 (0.08s)
386s     --- PASS: TestParallelNewApp/run-80 (0.08s)
386s     --- PASS: TestParallelNewApp/run-81 (0.09s)
386s     --- PASS: TestParallelNewApp/run-54 (0.07s)
386s     --- PASS: TestParallelNewApp/run-56 (0.06s)
386s     --- PASS: TestParallelNewApp/run-77 (0.08s)
386s     --- PASS: TestParallelNewApp/run-55 (0.09s)
386s     --- PASS: TestParallelNewApp/run-78 (0.06s)
386s     --- PASS: TestParallelNewApp/run-87 (0.32s)
386s     --- PASS: TestParallelNewApp/run-82 (0.33s)
386s     --- PASS: TestParallelNewApp/run-52 (0.07s)
386s     --- PASS: TestParallelNewApp/run-85 (0.30s)
386s     --- PASS: TestParallelNewApp/run-53 (0.08s)
386s     --- PASS: TestParallelNewApp/run-76 (0.08s)
386s     --- PASS: TestParallelNewApp/run-51 (0.09s)
386s     --- PASS: TestParallelNewApp/run-75 (0.07s)
386s     --- PASS: TestParallelNewApp/run-1 (0.27s)
386s === RUN   Example
386s --- PASS: Example (0.26s)
386s PASS
386s ok  	github.com/cowsql/go-cowsql/app	113.704s
386s === RUN   TestNew_Default
386s Benchmark done. Results available here:
386s /tmp/cowsql-app-test-2910331762/results
386s --- PASS: TestNew_Default (1.13s)
386s === RUN   TestNew_KvReadWrite
386s Benchmark done. Results available here:
386s /tmp/cowsql-app-test-1997619129/results
386s --- PASS: TestNew_KvReadWrite (1.12s)
386s === RUN   TestNew_ClusteredKvReadWrite
386s Benchmark done. Results available here:
386s /tmp/cowsql-app-test-2619010629/results
386s --- PASS: TestNew_ClusteredKvReadWrite (2.52s)
386s === RUN   TestNew_ClusteredTimeout
386s --- PASS: TestNew_ClusteredTimeout (2.08s)
386s PASS
386s ok  	github.com/cowsql/go-cowsql/benchmark	6.943s
386s === RUN   TestClient_Leader
386s --- PASS: TestClient_Leader (0.08s)
386s === RUN   TestClient_Dump
386s --- PASS: TestClient_Dump (0.12s)
386s === RUN   TestClient_Cluster
386s --- PASS: TestClient_Cluster (0.10s)
386s === RUN   TestClient_Transfer
386s --- PASS: TestClient_Transfer (0.22s)
386s === RUN   TestClient_Describe
386s --- PASS: TestClient_Describe (0.05s)
386s === RUN   TestMembership
386s --- PASS: TestMembership (0.27s)
386s === RUN   TestDefaultNodeStore
386s --- PASS: TestDefaultNodeStore (0.01s)
386s === RUN   TestConfigMultiThread
386s --- PASS: TestConfigMultiThread (0.00s)
386s PASS
386s ok  	github.com/cowsql/go-cowsql/client	0.912s
386s ?   	github.com/cowsql/go-cowsql/cmd/cowsql	[no test files]
386s ?   	github.com/cowsql/go-cowsql/cmd/cowsql-benchmark	[no test files]
386s ?   	github.com/cowsql/go-cowsql/cmd/cowsql-demo	[no test files]
386s === RUN   TestDriver_Open
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestDriver_Open (0.08s)
386s === RUN   TestDriver_Prepare
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestDriver_Prepare (0.08s)
386s === RUN   TestConn_Exec
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestConn_Exec (0.06s)
386s === RUN   TestConn_Query
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestConn_Query (0.09s)
386s === RUN   TestConn_QueryRow
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestConn_QueryRow (0.09s)
386s === RUN   TestConn_QueryBlob
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestConn_QueryBlob (0.07s)
386s === RUN   TestStmt_Exec
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestStmt_Exec (0.07s)
386s === RUN   TestStmt_ExecManyParams
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestStmt_ExecManyParams (0.04s)
386s === RUN   TestStmt_Query
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestStmt_Query (0.05s)
386s === RUN   TestStmt_QueryManyParams
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestStmt_QueryManyParams (0.05s)
386s === RUN   TestConn_QueryParams
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestConn_QueryParams (0.06s)
386s === RUN   TestConn_QueryManyParams
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestConn_QueryManyParams (0.06s)
386s === RUN   TestConn_ExecManyParams
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestConn_ExecManyParams (0.05s)
386s === RUN   Test_ColumnTypesEmpty
386s     driver_test.go:439: this currently fails if the result set is empty, is cowsql skipping the header if empty set?
386s --- SKIP: Test_ColumnTypesEmpty (0.00s)
386s === RUN   Test_ColumnTypesExists
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: Test_ColumnTypesExists (0.06s)
386s === RUN   Test_ColumnTypesEnd
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: Test_ColumnTypesEnd (0.05s)
386s === RUN   Test_ZeroColumns
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: Test_ZeroColumns (0.08s)
386s === RUN   TestIntegration_DatabaseSQL
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestIntegration_DatabaseSQL (0.25s)
386s === RUN   TestIntegration_ConstraintError
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestIntegration_ConstraintError (0.31s)
386s === RUN   TestIntegration_ExecBindError
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestIntegration_ExecBindError (0.25s)
386s === RUN   TestIntegration_QueryBindError
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestIntegration_QueryBindError (0.04s)
386s === RUN   TestIntegration_LargeQuery
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestIntegration_LargeQuery (0.34s)
386s === RUN   TestIntegration_Recover
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s     func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestIntegration_Recover (0.53s)
386s === RUN   TestIntegration_PingOnlyWorksOnceLeaderElected
386s     func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused
386s     func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1
386s     func.go:15: WARN: attempt 1: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused
386s     func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused
386s     func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1
386s     func.go:15: WARN: attempt 2: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused
386s     func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused
386s     func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1
386s     func.go:15: WARN: attempt 3: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused
386s     func.go:15: WARN: attempt 1: server @1: no known leader
386s     func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1
386s     func.go:15: WARN: attempt 1: server @2: reported leader server is not the leader
386s     func.go:15: WARN: attempt 2: server @1: no known leader
386s     func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1
386s     func.go:15: WARN: attempt 2: server @2: reported leader server is not the leader
386s     func.go:15: WARN: attempt 3: server @1: no known leader
386s     func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1
386s     func.go:15: WARN: attempt 3: server @2: reported leader server is not the leader
386s     func.go:15: WARN: attempt 4: server @1: no known leader
386s     func.go:15: DEBUG: attempt 4: server @2: connect to reported leader @1
386s     func.go:15: WARN: attempt 4: server @2: reported leader server is not the leader
386s     func.go:15: WARN: attempt 5: server @1: no known leader
386s     func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @1
386s     func.go:15: WARN: attempt 5: server @2: reported leader server is not the leader
386s     func.go:15: WARN: attempt 6: server @1: no known leader
386s     func.go:15: DEBUG: attempt 6: server @2: connect to reported leader @1
386s     func.go:15: WARN: attempt 6: server @2: reported leader server is not the leader
386s     func.go:15: WARN: attempt 7: server @1: no known leader
386s     func.go:15: DEBUG: attempt 7: server @2: connect to reported leader @1
386s     func.go:15: WARN: attempt 7: server @2: reported leader server is not the leader
386s     func.go:15: DEBUG: attempt 8: server @1: connect to reported leader @2
386s     func.go:15: DEBUG: attempt 8: server @1: connected
386s --- PASS: TestIntegration_PingOnlyWorksOnceLeaderElected (7.05s)
386s === RUN   TestIntegration_HighAvailability
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s     func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe
386s     func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused
386s     func.go:15: WARN: attempt 1: server @2: no known leader
386s     func.go:15: WARN: attempt 1: server @3: no known leader
386s     func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused
386s     func.go:15: WARN: attempt 2: server @2: no known leader
386s     func.go:15: WARN: attempt 2: server @3: no known leader
386s     func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused
386s     func.go:15: WARN: attempt 3: server @2: no known leader
386s     func.go:15: WARN: attempt 3: server @3: no known leader
386s     func.go:15: WARN: attempt 4: server @1: dial: dial unix @1: connect: connection refused
386s     func.go:15: WARN: attempt 4: server @2: no known leader
386s     func.go:15: WARN: attempt 4: server @3: no known leader
386s     func.go:15: WARN: attempt 5: server @1: dial: dial unix @1: connect: connection refused
386s     func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @3
386s     func.go:15: DEBUG: attempt 5: server @2: connected
386s --- PASS: TestIntegration_HighAvailability (4.69s)
386s === RUN   TestIntegration_LeadershipTransfer
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s     func.go:15: DEBUG: leadership lost (10250 - not leader)
386s     func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestIntegration_LeadershipTransfer (0.20s)
386s === RUN   TestIntegration_LeadershipTransfer_Tx
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s     func.go:15: DEBUG: leadership lost (10250 - not leader)
386s     func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestIntegration_LeadershipTransfer_Tx (0.21s)
386s === RUN   TestOptions
386s --- PASS: TestOptions (0.00s)
386s === RUN   TestIntegration_ColumnTypeName
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestIntegration_ColumnTypeName (0.06s)
386s === RUN   TestIntegration_SqlNullTime
386s     func.go:15: DEBUG: attempt 1: server @1: connected
386s --- PASS: TestIntegration_SqlNullTime (0.06s)
386s PASS
386s ok  	github.com/cowsql/go-cowsql/driver	15.105s
386s === RUN   TestNode_Create
386s --- PASS: TestNode_Create (0.18s)
386s === RUN   TestNode_Start
386s --- PASS: TestNode_Start (0.04s)
386s === RUN   TestNode_Restart
386s --- PASS: TestNode_Restart (0.07s)
386s === RUN   TestNode_Start_Inet
386s --- PASS: TestNode_Start_Inet (0.06s)
386s === RUN   TestNode_Leader
386s --- PASS: TestNode_Leader (0.04s)
386s PASS
386s ok  	github.com/cowsql/go-cowsql/internal/bindings	0.433s
386s === RUN   TestMessage_StaticBytesAlignment
386s --- PASS: TestMessage_StaticBytesAlignment (0.00s)
386s === RUN   TestMessage_putBlob
386s === RUN   TestMessage_putBlob/16
386s === RUN   TestMessage_putBlob/16#01
386s === RUN   TestMessage_putBlob/24
386s --- PASS: TestMessage_putBlob (0.00s)
386s     --- PASS: TestMessage_putBlob/16 (0.00s)
386s     --- PASS: TestMessage_putBlob/16#01 (0.00s)
386s     --- PASS: TestMessage_putBlob/24 (0.00s)
386s === RUN   TestMessage_putString
386s === RUN   TestMessage_putString/hello
386s === RUN   TestMessage_putString/hello!!
386s === RUN   TestMessage_putString/hello_world
386s --- PASS: TestMessage_putString (0.00s)
386s     --- PASS: TestMessage_putString/hello (0.00s)
386s     --- PASS: TestMessage_putString/hello!! (0.00s)
386s     --- PASS: TestMessage_putString/hello_world (0.00s)
386s === RUN   TestMessage_putUint8
386s --- PASS: TestMessage_putUint8 (0.00s)
386s === RUN   TestMessage_putUint16
386s --- PASS: TestMessage_putUint16 (0.00s)
386s === RUN   TestMessage_putUint32
386s --- PASS: TestMessage_putUint32 (0.00s)
386s === RUN   TestMessage_putUint64
386s --- PASS: TestMessage_putUint64 (0.00s)
386s === RUN   TestMessage_putNamedValues
386s --- PASS: TestMessage_putNamedValues (0.00s)
386s === RUN   TestMessage_putNamedValues32
386s --- PASS: TestMessage_putNamedValues32 (0.00s)
386s === RUN   TestMessage_putHeader
386s --- PASS: TestMessage_putHeader (0.00s)
386s === RUN   TestMessage_getString
386s === RUN   TestMessage_getString/hello
386s === RUN   TestMessage_getString/hello!!
386s === RUN   TestMessage_getString/hello!!!
386s === RUN   TestMessage_getString/hello_world
386s --- PASS: TestMessage_getString (0.00s)
386s     --- PASS: TestMessage_getString/hello (0.00s)
386s     --- PASS: TestMessage_getString/hello!! (0.00s)
386s     --- PASS: TestMessage_getString/hello!!! (0.00s)
386s     --- PASS: TestMessage_getString/hello_world (0.00s)
386s === RUN   TestMessage_getBlob
386s === RUN   TestMessage_getBlob/16
386s === RUN   TestMessage_getBlob/16#01
386s === RUN   TestMessage_getBlob/24
386s --- PASS: TestMessage_getBlob (0.00s)
386s     --- PASS: TestMessage_getBlob/16 (0.00s)
386s     --- PASS: TestMessage_getBlob/16#01 (0.00s)
386s     --- PASS: TestMessage_getBlob/24 (0.00s)
386s === RUN   TestMessage_getString_Overflow_WordBoundary
386s --- PASS: TestMessage_getString_Overflow_WordBoundary (0.00s)
386s === RUN   TestConnector_Success
386s     connector_test.go:304: DEBUG: attempt 1: server @test-0: connected
386s --- PASS: TestConnector_Success (0.05s)
386s === RUN   TestConnector_LimitRetries
386s     connector_test.go:304: WARN: attempt 1: server @test-123: dial: dial unix @test-123: connect: connection refused
386s     connector_test.go:304: WARN: attempt 2: server @test-123: dial: dial unix @test-123: connect: connection refused
386s     connector_test.go:304: WARN: attempt 3: server @test-123: dial: dial unix @test-123: connect: connection refused
386s --- PASS: TestConnector_LimitRetries (0.60s)
386s === RUN   TestConnector_DialTimeout
386s     connector_test.go:64: Skipping network test
386s --- SKIP: TestConnector_DialTimeout (0.00s)
386s === RUN   TestConnector_EmptyNodeStore
386s --- PASS: TestConnector_EmptyNodeStore (0.20s)
386s === RUN   TestConnector_ContextCanceled
386s     connector_test.go:99: Skipping network test
386s --- SKIP: TestConnector_ContextCanceled (0.00s)
386s === RUN   TestConnector_AttemptTimeout
386s     func.go:15: WARN: attempt 1: server @1234: call leader (budget 99.945199ms): receive: header: read unix @->@1234: i/o timeout
386s     func.go:15: WARN: attempt 2: server @1234: call leader (budget 99.869198ms): receive: header: read unix @->@1234: i/o timeout
386s --- PASS: TestConnector_AttemptTimeout (0.41s)
386s === RUN   TestProtocol_RequestWithDynamicBuffer
386s     func.go:15: DEBUG: attempt 1: server @test-0: connected
386s --- PASS: TestProtocol_RequestWithDynamicBuffer (0.10s)
386s === RUN   TestProtocol_Prepare
386s     func.go:15: DEBUG: attempt 1: server @test-0: connected
386s --- PASS: TestProtocol_Prepare (0.05s)
386s PASS
386s ok  	github.com/cowsql/go-cowsql/internal/protocol	1.429s
386s ?   	github.com/cowsql/go-cowsql/internal/shell	[no test files]
386s === RUN   Test_TestFunc
386s     func.go:15: INFO: hello
386s --- PASS: Test_TestFunc (0.00s)
386s === RUN   TestLevel_String
386s --- PASS: TestLevel_String (0.00s)
386s PASS
386s ok  	github.com/cowsql/go-cowsql/logging	0.021s
386s autopkgtest [03:35:35]: test dh-golang-autopkgtest: -----------------------]
390s dh-golang-autopkgtest PASS
390s autopkgtest [03:35:39]: test dh-golang-autopkgtest:  - - - - - - - - - - results - - - - - - - - - -
394s autopkgtest [03:35:43]: @@@@@@@@@@@@@@@@@@@@ summary
394s dh-golang-autopkgtest PASS