0s autopkgtest [18:23:16]: starting date and time: 2024-03-19 18:23:16+0000 0s autopkgtest [18:23:16]: git checkout: 4a1cd702 l/adt_testbed: don't blame the testbed for unsolvable build deps 0s autopkgtest [18:23:16]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.04pfn7ij/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:dqlite,src:libuv1 --apt-upgrade golang-github-canonical-go-dqlite --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=dqlite/1.16.0-5build1 libuv1/1.48.0-1.1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos02-ppc64el-19.secgroup --name adt-noble-ppc64el-golang-github-canonical-go-dqlite-20240319-182316-juju-7f2275-prod-proposed-migration-environment-2 --image adt/ubuntu-noble-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration -e TERM=linux -e ''"'"'http_proxy=http://squid.internal:3128'"'"'' -e ''"'"'https_proxy=http://squid.internal:3128'"'"'' -e ''"'"'no_proxy=127.0.0.1,127.0.1.1,login.ubuntu.com,localhost,localdomain,novalocal,internal,archive.ubuntu.com,ports.ubuntu.com,security.ubuntu.com,ddebs.ubuntu.com,changelogs.ubuntu.com,launchpadlibrarian.net,launchpadcontent.net,launchpad.net,10.24.0.0/24,keystone.ps5.canonical.com,objectstorage.prodstack5.canonical.com'"'"'' --mirror=http://ftpmaster.internal/ubuntu/ 334s autopkgtest [18:28:50]: testbed dpkg architecture: ppc64el 334s autopkgtest [18:28:50]: testbed apt version: 2.7.12 334s autopkgtest [18:28:50]: @@@@@@@@@@@@@@@@@@@@ test bed setup 336s Get:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease [117 kB] 336s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/universe Sources [3740 kB] 337s Get:3 http://ftpmaster.internal/ubuntu noble-proposed/main Sources [493 kB] 337s Get:4 http://ftpmaster.internal/ubuntu noble-proposed/restricted Sources [6540 B] 337s Get:5 http://ftpmaster.internal/ubuntu noble-proposed/multiverse Sources [52.7 kB] 337s Get:6 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el Packages [648 kB] 337s Get:7 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el c-n-f Metadata [3116 B] 337s Get:8 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el Packages [1372 B] 337s Get:9 http://ftpmaster.internal/ubuntu noble-proposed/restricted ppc64el c-n-f Metadata [116 B] 337s Get:10 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el Packages [4078 kB] 338s Get:11 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el c-n-f Metadata [8652 B] 338s Get:12 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el Packages [47.7 kB] 338s Get:13 http://ftpmaster.internal/ubuntu noble-proposed/multiverse ppc64el c-n-f Metadata [116 B] 342s Fetched 9195 kB in 4s (2428 kB/s) 342s Reading package lists... 345s Reading package lists... 345s Building dependency tree... 345s Reading state information... 345s Calculating upgrade... 345s The following packages will be upgraded: 345s ubuntu-minimal ubuntu-standard 345s 2 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 345s Need to get 21.5 kB of archives. 345s After this operation, 0 B of additional disk space will be used. 345s Get:1 http://ftpmaster.internal/ubuntu noble/main ppc64el ubuntu-minimal ppc64el 1.536build1 [10.7 kB] 345s Get:2 http://ftpmaster.internal/ubuntu noble/main ppc64el ubuntu-standard ppc64el 1.536build1 [10.7 kB] 346s Fetched 21.5 kB in 0s (80.4 kB/s) 346s (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 ... 70095 files and directories currently installed.) 346s Preparing to unpack .../ubuntu-minimal_1.536build1_ppc64el.deb ... 346s Unpacking ubuntu-minimal (1.536build1) over (1.536) ... 346s Preparing to unpack .../ubuntu-standard_1.536build1_ppc64el.deb ... 346s Unpacking ubuntu-standard (1.536build1) over (1.536) ... 346s Setting up ubuntu-minimal (1.536build1) ... 346s Setting up ubuntu-standard (1.536build1) ... 346s Reading package lists... 346s Building dependency tree... 346s Reading state information... 347s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 347s Hit:1 http://ftpmaster.internal/ubuntu noble-proposed InRelease 347s Hit:2 http://ftpmaster.internal/ubuntu noble InRelease 347s Hit:3 http://ftpmaster.internal/ubuntu noble-updates InRelease 347s Hit:4 http://ftpmaster.internal/ubuntu noble-security InRelease 349s Reading package lists... 349s Reading package lists... 349s Building dependency tree... 349s Reading state information... 349s Calculating upgrade... 349s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 349s Reading package lists... 350s Building dependency tree... 350s Reading state information... 350s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 353s autopkgtest [18:29:09]: testbed running kernel: Linux 6.8.0-11-generic #11-Ubuntu SMP Wed Feb 14 00:33:03 UTC 2024 353s autopkgtest [18:29:09]: @@@@@@@@@@@@@@@@@@@@ apt-source golang-github-canonical-go-dqlite 356s Get:1 http://ftpmaster.internal/ubuntu noble/universe golang-github-canonical-go-dqlite 1.21.0-1 (dsc) [2778 B] 356s Get:2 http://ftpmaster.internal/ubuntu noble/universe golang-github-canonical-go-dqlite 1.21.0-1 (tar) [110 kB] 356s Get:3 http://ftpmaster.internal/ubuntu noble/universe golang-github-canonical-go-dqlite 1.21.0-1 (diff) [4388 B] 356s gpgv: Signature made Mon Oct 30 22:11:23 2023 UTC 356s gpgv: using RSA key D41A7AD07DF6C5FCA7489F1C29EEE2D6ECF442F9 356s gpgv: issuer "gibmat@debian.org" 356s gpgv: Can't check signature: No public key 356s dpkg-source: warning: cannot verify inline signature for ./golang-github-canonical-go-dqlite_1.21.0-1.dsc: no acceptable signature found 356s autopkgtest [18:29:12]: testing package golang-github-canonical-go-dqlite version 1.21.0-1 358s autopkgtest [18:29:14]: build not needed 360s autopkgtest [18:29:16]: test dh-golang-autopkgtest: preparing testbed 371s Reading package lists... 371s Building dependency tree... 371s Reading state information... 372s Starting pkgProblemResolver with broken count: 0 372s Starting 2 pkgProblemResolver with broken count: 0 372s Done 372s The following additional packages will be installed: 372s autoconf automake autopoint autotools-dev build-essential cpp cpp-13 372s cpp-13-powerpc64le-linux-gnu cpp-powerpc64le-linux-gnu debhelper debugedit 372s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 372s g++-13-powerpc64le-linux-gnu g++-powerpc64le-linux-gnu gcc gcc-13 372s gcc-13-powerpc64le-linux-gnu gcc-powerpc64le-linux-gnu gettext go-dqlite 372s golang-1.22-go golang-1.22-src golang-any golang-github-burntsushi-toml-dev 372s golang-github-canonical-go-dqlite-dev 372s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 372s golang-github-fsnotify-fsnotify-dev golang-github-ghodss-yaml-dev 372s golang-github-google-renameio-dev golang-github-hashicorp-hcl-dev 372s golang-github-kr-fs-dev golang-github-magiconair-properties-dev 372s golang-github-mattn-go-runewidth-dev golang-github-mattn-go-sqlite3-dev 372s golang-github-mitchellh-mapstructure-dev golang-github-pelletier-go-toml-dev 372s golang-github-pelletier-go-toml.v2-dev golang-github-peterh-liner-dev 372s golang-github-pkg-errors-dev golang-github-pkg-sftp-dev 372s golang-github-pmezard-go-difflib-dev golang-github-rican7-retry-dev 372s golang-github-rivo-uniseg-dev golang-github-russross-blackfriday-v2-dev 372s golang-github-shurcool-sanitized-anchor-name-dev 372s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 372s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 372s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 372s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 372s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 372s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 372s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 372s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 372s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 372s libdebhelper-perl libdqlite-dev libdqlite0 libdw1 372s libfile-stripnondeterminism-perl libgcc-13-dev libgomp1 libisl23 libitm1 372s liblsan0 libmpc3 libnsl-dev libquadmath0 libraft-dev libraft0 libsqlite3-dev 372s libstdc++-13-dev libsub-override-perl libtirpc-dev libtool libtsan2 372s libubsan1 libuv1t64 linux-libc-dev m4 po-debconf rpcsvc-proto 372s Suggested packages: 372s autoconf-archive gnu-standards autoconf-doc cpp-doc gcc-13-locales 372s cpp-13-doc dh-make gcc-13-doc gcc-multilib manpages-dev flex bison gdb 372s gcc-doc gdb-powerpc64le-linux-gnu gettext-doc libasprintf-dev 372s libgettextpo-dev bzr | brz git mercurial subversion glibc-doc sqlite3-doc 372s libstdc++-13-doc libtool-doc gfortran | fortran95-compiler gcj-jdk m4-doc 372s libmail-box-perl 372s Recommended packages: 372s pkg-config manpages manpages-dev libc-devtools libarchive-cpio-perl 372s libltdl-dev libmail-sendmail-perl 372s The following packages will be REMOVED: 372s libuv1 372s The following NEW packages will be installed: 372s autoconf automake autopkgtest-satdep autopoint autotools-dev build-essential 372s cpp cpp-13 cpp-13-powerpc64le-linux-gnu cpp-powerpc64le-linux-gnu debhelper 372s debugedit dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-13 372s g++-13-powerpc64le-linux-gnu g++-powerpc64le-linux-gnu gcc gcc-13 372s gcc-13-powerpc64le-linux-gnu gcc-powerpc64le-linux-gnu gettext go-dqlite 372s golang-1.22-go golang-1.22-src golang-any golang-github-burntsushi-toml-dev 372s golang-github-canonical-go-dqlite-dev 372s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 372s golang-github-fsnotify-fsnotify-dev golang-github-ghodss-yaml-dev 372s golang-github-google-renameio-dev golang-github-hashicorp-hcl-dev 372s golang-github-kr-fs-dev golang-github-magiconair-properties-dev 372s golang-github-mattn-go-runewidth-dev golang-github-mattn-go-sqlite3-dev 372s golang-github-mitchellh-mapstructure-dev golang-github-pelletier-go-toml-dev 372s golang-github-pelletier-go-toml.v2-dev golang-github-peterh-liner-dev 372s golang-github-pkg-errors-dev golang-github-pkg-sftp-dev 372s golang-github-pmezard-go-difflib-dev golang-github-rican7-retry-dev 372s golang-github-rivo-uniseg-dev golang-github-russross-blackfriday-v2-dev 372s golang-github-shurcool-sanitized-anchor-name-dev 372s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 372s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 372s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 372s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 372s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 372s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 372s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 372s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 372s libasan8 libatomic1 libc-dev-bin libc6-dev libcc1-0 libcrypt-dev 372s libdebhelper-perl libdqlite-dev libdqlite0 libdw1 372s libfile-stripnondeterminism-perl libgcc-13-dev libgomp1 libisl23 libitm1 372s liblsan0 libmpc3 libnsl-dev libquadmath0 libraft-dev libraft0 libsqlite3-dev 372s libstdc++-13-dev libsub-override-perl libtirpc-dev libtool libtsan2 372s libubsan1 libuv1t64 linux-libc-dev m4 po-debconf rpcsvc-proto 372s 0 upgraded, 106 newly installed, 1 to remove and 0 not upgraded. 372s Need to get 125 MB/125 MB of archives. 372s After this operation, 581 MB of additional disk space will be used. 372s Get:1 /tmp/autopkgtest.sVMzRF/1-autopkgtest-satdep.deb autopkgtest-satdep ppc64el 0 [900 B] 372s Get:2 http://ftpmaster.internal/ubuntu noble-proposed/main ppc64el libuv1t64 ppc64el 1.48.0-1.1 [117 kB] 373s Get:3 http://ftpmaster.internal/ubuntu noble/main ppc64el m4 ppc64el 1.4.19-4 [275 kB] 373s Get:4 http://ftpmaster.internal/ubuntu noble/main ppc64el autoconf all 2.71-3 [339 kB] 373s Get:5 http://ftpmaster.internal/ubuntu noble/main ppc64el autotools-dev all 20220109.1 [44.9 kB] 373s Get:6 http://ftpmaster.internal/ubuntu noble/main ppc64el automake all 1:1.16.5-1.3ubuntu1 [558 kB] 373s Get:7 http://ftpmaster.internal/ubuntu noble/main ppc64el autopoint all 0.21-14ubuntu1 [422 kB] 373s Get:8 http://ftpmaster.internal/ubuntu noble/main ppc64el libc-dev-bin ppc64el 2.39-0ubuntu2 [21.3 kB] 373s Get:9 http://ftpmaster.internal/ubuntu noble/main ppc64el linux-libc-dev ppc64el 6.8.0-11.11 [1585 kB] 374s Get:10 http://ftpmaster.internal/ubuntu noble/main ppc64el libcrypt-dev ppc64el 1:4.4.36-4 [167 kB] 374s Get:11 http://ftpmaster.internal/ubuntu noble/main ppc64el libtirpc-dev ppc64el 1.3.4+ds-1build1 [262 kB] 374s Get:12 http://ftpmaster.internal/ubuntu noble/main ppc64el libnsl-dev ppc64el 1.3.0-3 [79.2 kB] 374s Get:13 http://ftpmaster.internal/ubuntu noble/main ppc64el rpcsvc-proto ppc64el 1.4.2-0ubuntu6 [82.3 kB] 374s Get:14 http://ftpmaster.internal/ubuntu noble/main ppc64el libc6-dev ppc64el 2.39-0ubuntu2 [2102 kB] 374s Get:15 http://ftpmaster.internal/ubuntu noble/main ppc64el libisl23 ppc64el 0.26-3 [864 kB] 374s Get:16 http://ftpmaster.internal/ubuntu noble/main ppc64el libmpc3 ppc64el 1.3.1-1 [61.2 kB] 374s Get:17 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp-13-powerpc64le-linux-gnu ppc64el 13.2.0-17ubuntu2 [10.7 MB] 376s Get:18 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp-13 ppc64el 13.2.0-17ubuntu2 [1038 B] 376s Get:19 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp-powerpc64le-linux-gnu ppc64el 4:13.2.0-7ubuntu1 [5330 B] 376s Get:20 http://ftpmaster.internal/ubuntu noble/main ppc64el cpp ppc64el 4:13.2.0-7ubuntu1 [22.5 kB] 376s Get:21 http://ftpmaster.internal/ubuntu noble/main ppc64el libcc1-0 ppc64el 14-20240303-1ubuntu1 [48.1 kB] 376s Get:22 http://ftpmaster.internal/ubuntu noble/main ppc64el libgomp1 ppc64el 14-20240303-1ubuntu1 [161 kB] 376s Get:23 http://ftpmaster.internal/ubuntu noble/main ppc64el libitm1 ppc64el 14-20240303-1ubuntu1 [32.4 kB] 376s Get:24 http://ftpmaster.internal/ubuntu noble/main ppc64el libatomic1 ppc64el 14-20240303-1ubuntu1 [10.7 kB] 376s Get:25 http://ftpmaster.internal/ubuntu noble/main ppc64el libasan8 ppc64el 14-20240303-1ubuntu1 [2973 kB] 376s Get:26 http://ftpmaster.internal/ubuntu noble/main ppc64el liblsan0 ppc64el 14-20240303-1ubuntu1 [1325 kB] 376s Get:27 http://ftpmaster.internal/ubuntu noble/main ppc64el libtsan2 ppc64el 14-20240303-1ubuntu1 [2734 kB] 376s Get:28 http://ftpmaster.internal/ubuntu noble/main ppc64el libubsan1 ppc64el 14-20240303-1ubuntu1 [1194 kB] 376s Get:29 http://ftpmaster.internal/ubuntu noble/main ppc64el libquadmath0 ppc64el 14-20240303-1ubuntu1 [158 kB] 376s Get:30 http://ftpmaster.internal/ubuntu noble/main ppc64el libgcc-13-dev ppc64el 13.2.0-17ubuntu2 [1581 kB] 376s Get:31 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc-13-powerpc64le-linux-gnu ppc64el 13.2.0-17ubuntu2 [20.6 MB] 377s Get:32 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc-13 ppc64el 13.2.0-17ubuntu2 [477 kB] 377s Get:33 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc-powerpc64le-linux-gnu ppc64el 4:13.2.0-7ubuntu1 [1224 B] 377s Get:34 http://ftpmaster.internal/ubuntu noble/main ppc64el gcc ppc64el 4:13.2.0-7ubuntu1 [5022 B] 377s Get:35 http://ftpmaster.internal/ubuntu noble/main ppc64el libstdc++-13-dev ppc64el 13.2.0-17ubuntu2 [2445 kB] 377s Get:36 http://ftpmaster.internal/ubuntu noble/main ppc64el g++-13-powerpc64le-linux-gnu ppc64el 13.2.0-17ubuntu2 [12.2 MB] 377s Get:37 http://ftpmaster.internal/ubuntu noble/main ppc64el g++-13 ppc64el 13.2.0-17ubuntu2 [14.5 kB] 377s Get:38 http://ftpmaster.internal/ubuntu noble/main ppc64el g++-powerpc64le-linux-gnu ppc64el 4:13.2.0-7ubuntu1 [968 B] 377s Get:39 http://ftpmaster.internal/ubuntu noble/main ppc64el g++ ppc64el 4:13.2.0-7ubuntu1 [1086 B] 377s Get:40 http://ftpmaster.internal/ubuntu noble/main ppc64el build-essential ppc64el 12.10ubuntu1 [4936 B] 377s Get:41 http://ftpmaster.internal/ubuntu noble/main ppc64el libdebhelper-perl all 13.14.1ubuntu1 [89.5 kB] 377s Get:42 http://ftpmaster.internal/ubuntu noble/main ppc64el libtool all 2.4.7-7 [166 kB] 378s Get:43 http://ftpmaster.internal/ubuntu noble/main ppc64el dh-autoreconf all 20 [16.1 kB] 378s Get:44 http://ftpmaster.internal/ubuntu noble/main ppc64el libarchive-zip-perl all 1.68-1 [90.2 kB] 378s Get:45 http://ftpmaster.internal/ubuntu noble/main ppc64el libsub-override-perl all 0.10-1 [10.0 kB] 378s Get:46 http://ftpmaster.internal/ubuntu noble/main ppc64el libfile-stripnondeterminism-perl all 1.13.1-1 [18.1 kB] 378s Get:47 http://ftpmaster.internal/ubuntu noble/main ppc64el dh-strip-nondeterminism all 1.13.1-1 [5362 B] 378s Get:48 http://ftpmaster.internal/ubuntu noble/main ppc64el libdw1 ppc64el 0.190-1 [294 kB] 378s Get:49 http://ftpmaster.internal/ubuntu noble/main ppc64el debugedit ppc64el 1:5.0-5 [51.1 kB] 378s Get:50 http://ftpmaster.internal/ubuntu noble/main ppc64el dwz ppc64el 0.15-1 [139 kB] 378s Get:51 http://ftpmaster.internal/ubuntu noble/main ppc64el gettext ppc64el 0.21-14ubuntu1 [979 kB] 378s Get:52 http://ftpmaster.internal/ubuntu noble/main ppc64el intltool-debian all 0.35.0+20060710.6 [23.2 kB] 378s Get:53 http://ftpmaster.internal/ubuntu noble/main ppc64el po-debconf all 1.0.21+nmu1 [233 kB] 378s Get:54 http://ftpmaster.internal/ubuntu noble/main ppc64el debhelper all 13.14.1ubuntu1 [869 kB] 378s Get:55 http://ftpmaster.internal/ubuntu noble/universe ppc64el libraft0 ppc64el 0.22.0-1 [116 kB] 378s Get:56 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el libdqlite0 ppc64el 1.16.0-5build1 [97.1 kB] 378s Get:57 http://ftpmaster.internal/ubuntu noble/universe ppc64el go-dqlite ppc64el 1.21.0-1 [3861 kB] 378s Get:58 http://ftpmaster.internal/ubuntu noble/main ppc64el golang-1.22-src all 1.22.1-1 [19.7 MB] 378s Get:59 http://ftpmaster.internal/ubuntu noble/main ppc64el golang-1.22-go ppc64el 1.22.1-1 [23.5 MB] 379s Get:60 http://ftpmaster.internal/ubuntu noble/main ppc64el golang-src all 2:1.22~2 [4992 B] 379s Get:61 http://ftpmaster.internal/ubuntu noble/main ppc64el golang-go ppc64el 2:1.22~2 [43.9 kB] 379s Get:62 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-any ppc64el 2:1.22~2 [2840 B] 379s Get:63 http://ftpmaster.internal/ubuntu noble/main ppc64el libsqlite3-dev ppc64el 3.45.1-1 [1036 kB] 379s Get:64 http://ftpmaster.internal/ubuntu noble-proposed/universe ppc64el libdqlite-dev ppc64el 1.16.0-5build1 [137 kB] 379s Get:65 http://ftpmaster.internal/ubuntu noble/universe ppc64el libraft-dev ppc64el 0.22.0-1 [177 kB] 379s Get:66 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-gopkg-yaml.v2-dev all 2.4.0-4 [62.6 kB] 379s Get:67 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-ghodss-yaml-dev all 1.0.0+git20220118.d8423dc-2 [16.6 kB] 379s Get:68 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-google-renameio-dev all 2.0.0-2 [10.3 kB] 379s Get:69 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-mattn-go-sqlite3-dev ppc64el 1.14.19~ds1-1 [85.5 kB] 379s Get:70 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-rivo-uniseg-dev all 0.4.4-1 [256 kB] 379s Get:71 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-mattn-go-runewidth-dev all 0.0.15-2 [17.2 kB] 379s Get:72 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-peterh-liner-dev all 1.2.2-1 [21.3 kB] 379s Get:73 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-pkg-errors-dev all 0.9.1-3 [13.2 kB] 379s Get:74 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-rican7-retry-dev all 0.3.1-2 [7740 B] 379s Get:75 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-pmezard-go-difflib-dev all 1.0.0-3 [12.4 kB] 379s Get:76 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-shurcool-sanitized-anchor-name-dev all 1.0.0-3 [3590 B] 379s Get:77 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-russross-blackfriday-v2-dev all 2.1.0-1 [79.6 kB] 379s Get:78 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-cpuguy83-go-md2man-v2-dev all 2.0.3+ds1-1 [10.4 kB] 379s Get:79 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-spf13-pflag-dev all 1.0.6~git20210604-d5e0c0615ace-1 [43.6 kB] 379s Get:80 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-golang-x-sys-dev all 0.17.0-1 [424 kB] 379s Get:81 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-fsnotify-fsnotify-dev all 1.7.0-1 [39.1 kB] 379s Get:82 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-davecgh-go-spew-dev all 1.1.1-3 [31.1 kB] 379s Get:83 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-hashicorp-hcl-dev all 1.0.0-1.1 [58.9 kB] 379s Get:84 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-magiconair-properties-dev all 1.8.7-1 [27.3 kB] 379s Get:85 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-mitchellh-mapstructure-dev all 1.5.0+git20231216.8508981-1 [28.3 kB] 379s Get:86 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-burntsushi-toml-dev all 1.3.2-2 [115 kB] 379s Get:87 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-pelletier-go-toml-dev all 1.9.5-1build1 [79.6 kB] 379s Get:88 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-pelletier-go-toml.v2-dev all 2.1.1-1 [876 kB] 379s Get:89 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-kr-fs-dev all 0.1.0-2 [6344 B] 379s Get:90 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-golang-x-term-dev all 0.17.0-1 [14.7 kB] 379s Get:91 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-golang-x-text-dev all 0.14.0-1 [4646 kB] 380s Get:92 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-golang-x-crypto-dev all 1:0.19.0-1 [1712 kB] 380s Get:93 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-pkg-sftp-dev all 1.13.6-1 [104 kB] 380s Get:94 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-spf13-afero-dev all 1.11.0-1 [59.6 kB] 380s Get:95 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-spf13-cast-dev all 1.6.0-1 [14.3 kB] 380s Get:96 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-stretchr-objx-dev all 0.5.0-1 [26.5 kB] 380s Get:97 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-gopkg-yaml.v3-dev all 3.0.1-3 [76.1 kB] 380s Get:98 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-stretchr-testify-dev all 1.8.4-1 [72.8 kB] 380s Get:99 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-spf13-jwalterweatherman-dev all 1.1.0+really1.1.0-2 [6828 B] 380s Get:100 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-subosito-gotenv-dev all 1.3.0-1 [8808 B] 380s Get:101 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-gopkg-ini.v1-dev all 1.67.0-1 [40.8 kB] 380s Get:102 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-spf13-viper-dev all 1.12.0-1 [71.0 kB] 380s Get:103 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-spf13-cobra-dev all 1.8.0-1 [74.5 kB] 380s Get:104 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-golang-x-sync-dev all 0.6.0-1 [18.9 kB] 380s Get:105 http://ftpmaster.internal/ubuntu noble/universe ppc64el golang-github-canonical-go-dqlite-dev all 1.21.0-1 [86.3 kB] 380s Get:106 http://ftpmaster.internal/ubuntu noble/main ppc64el dh-golang all 1.62 [25.2 kB] 381s Fetched 125 MB in 8s (15.6 MB/s) 381s dpkg: libuv1:ppc64el: dependency problems, but removing anyway as you requested: 381s bind9-libs:ppc64el depends on libuv1 (>= 1.40.0). 381s 381s (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 ... 70095 files and directories currently installed.) 381s Removing libuv1:ppc64el (1.48.0-1) ... 381s Selecting previously unselected package libuv1t64:ppc64el. 381s (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 ... 70090 files and directories currently installed.) 381s Preparing to unpack .../000-libuv1t64_1.48.0-1.1_ppc64el.deb ... 381s Unpacking libuv1t64:ppc64el (1.48.0-1.1) ... 381s Selecting previously unselected package m4. 381s Preparing to unpack .../001-m4_1.4.19-4_ppc64el.deb ... 381s Unpacking m4 (1.4.19-4) ... 381s Selecting previously unselected package autoconf. 381s Preparing to unpack .../002-autoconf_2.71-3_all.deb ... 381s Unpacking autoconf (2.71-3) ... 381s Selecting previously unselected package autotools-dev. 381s Preparing to unpack .../003-autotools-dev_20220109.1_all.deb ... 381s Unpacking autotools-dev (20220109.1) ... 381s Selecting previously unselected package automake. 381s Preparing to unpack .../004-automake_1%3a1.16.5-1.3ubuntu1_all.deb ... 381s Unpacking automake (1:1.16.5-1.3ubuntu1) ... 381s Selecting previously unselected package autopoint. 381s Preparing to unpack .../005-autopoint_0.21-14ubuntu1_all.deb ... 381s Unpacking autopoint (0.21-14ubuntu1) ... 381s Selecting previously unselected package libc-dev-bin. 381s Preparing to unpack .../006-libc-dev-bin_2.39-0ubuntu2_ppc64el.deb ... 381s Unpacking libc-dev-bin (2.39-0ubuntu2) ... 381s Selecting previously unselected package linux-libc-dev:ppc64el. 381s Preparing to unpack .../007-linux-libc-dev_6.8.0-11.11_ppc64el.deb ... 381s Unpacking linux-libc-dev:ppc64el (6.8.0-11.11) ... 381s Selecting previously unselected package libcrypt-dev:ppc64el. 381s Preparing to unpack .../008-libcrypt-dev_1%3a4.4.36-4_ppc64el.deb ... 381s Unpacking libcrypt-dev:ppc64el (1:4.4.36-4) ... 381s Selecting previously unselected package libtirpc-dev:ppc64el. 381s Preparing to unpack .../009-libtirpc-dev_1.3.4+ds-1build1_ppc64el.deb ... 381s Unpacking libtirpc-dev:ppc64el (1.3.4+ds-1build1) ... 381s Selecting previously unselected package libnsl-dev:ppc64el. 381s Preparing to unpack .../010-libnsl-dev_1.3.0-3_ppc64el.deb ... 381s Unpacking libnsl-dev:ppc64el (1.3.0-3) ... 381s Selecting previously unselected package rpcsvc-proto. 381s Preparing to unpack .../011-rpcsvc-proto_1.4.2-0ubuntu6_ppc64el.deb ... 381s Unpacking rpcsvc-proto (1.4.2-0ubuntu6) ... 381s Selecting previously unselected package libc6-dev:ppc64el. 381s Preparing to unpack .../012-libc6-dev_2.39-0ubuntu2_ppc64el.deb ... 381s Unpacking libc6-dev:ppc64el (2.39-0ubuntu2) ... 381s Selecting previously unselected package libisl23:ppc64el. 381s Preparing to unpack .../013-libisl23_0.26-3_ppc64el.deb ... 381s Unpacking libisl23:ppc64el (0.26-3) ... 381s Selecting previously unselected package libmpc3:ppc64el. 381s Preparing to unpack .../014-libmpc3_1.3.1-1_ppc64el.deb ... 381s Unpacking libmpc3:ppc64el (1.3.1-1) ... 381s Selecting previously unselected package cpp-13-powerpc64le-linux-gnu. 381s Preparing to unpack .../015-cpp-13-powerpc64le-linux-gnu_13.2.0-17ubuntu2_ppc64el.deb ... 381s Unpacking cpp-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 382s Selecting previously unselected package cpp-13. 382s Preparing to unpack .../016-cpp-13_13.2.0-17ubuntu2_ppc64el.deb ... 382s Unpacking cpp-13 (13.2.0-17ubuntu2) ... 382s Selecting previously unselected package cpp-powerpc64le-linux-gnu. 382s Preparing to unpack .../017-cpp-powerpc64le-linux-gnu_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 382s Unpacking cpp-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 382s Selecting previously unselected package cpp. 382s Preparing to unpack .../018-cpp_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 382s Unpacking cpp (4:13.2.0-7ubuntu1) ... 382s Selecting previously unselected package libcc1-0:ppc64el. 382s Preparing to unpack .../019-libcc1-0_14-20240303-1ubuntu1_ppc64el.deb ... 382s Unpacking libcc1-0:ppc64el (14-20240303-1ubuntu1) ... 382s Selecting previously unselected package libgomp1:ppc64el. 382s Preparing to unpack .../020-libgomp1_14-20240303-1ubuntu1_ppc64el.deb ... 382s Unpacking libgomp1:ppc64el (14-20240303-1ubuntu1) ... 382s Selecting previously unselected package libitm1:ppc64el. 382s Preparing to unpack .../021-libitm1_14-20240303-1ubuntu1_ppc64el.deb ... 382s Unpacking libitm1:ppc64el (14-20240303-1ubuntu1) ... 382s Selecting previously unselected package libatomic1:ppc64el. 382s Preparing to unpack .../022-libatomic1_14-20240303-1ubuntu1_ppc64el.deb ... 382s Unpacking libatomic1:ppc64el (14-20240303-1ubuntu1) ... 382s Selecting previously unselected package libasan8:ppc64el. 382s Preparing to unpack .../023-libasan8_14-20240303-1ubuntu1_ppc64el.deb ... 382s Unpacking libasan8:ppc64el (14-20240303-1ubuntu1) ... 382s Selecting previously unselected package liblsan0:ppc64el. 382s Preparing to unpack .../024-liblsan0_14-20240303-1ubuntu1_ppc64el.deb ... 382s Unpacking liblsan0:ppc64el (14-20240303-1ubuntu1) ... 382s Selecting previously unselected package libtsan2:ppc64el. 382s Preparing to unpack .../025-libtsan2_14-20240303-1ubuntu1_ppc64el.deb ... 382s Unpacking libtsan2:ppc64el (14-20240303-1ubuntu1) ... 382s Selecting previously unselected package libubsan1:ppc64el. 382s Preparing to unpack .../026-libubsan1_14-20240303-1ubuntu1_ppc64el.deb ... 382s Unpacking libubsan1:ppc64el (14-20240303-1ubuntu1) ... 382s Selecting previously unselected package libquadmath0:ppc64el. 382s Preparing to unpack .../027-libquadmath0_14-20240303-1ubuntu1_ppc64el.deb ... 382s Unpacking libquadmath0:ppc64el (14-20240303-1ubuntu1) ... 382s Selecting previously unselected package libgcc-13-dev:ppc64el. 382s Preparing to unpack .../028-libgcc-13-dev_13.2.0-17ubuntu2_ppc64el.deb ... 382s Unpacking libgcc-13-dev:ppc64el (13.2.0-17ubuntu2) ... 382s Selecting previously unselected package gcc-13-powerpc64le-linux-gnu. 382s Preparing to unpack .../029-gcc-13-powerpc64le-linux-gnu_13.2.0-17ubuntu2_ppc64el.deb ... 382s Unpacking gcc-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 383s Selecting previously unselected package gcc-13. 383s Preparing to unpack .../030-gcc-13_13.2.0-17ubuntu2_ppc64el.deb ... 383s Unpacking gcc-13 (13.2.0-17ubuntu2) ... 383s Selecting previously unselected package gcc-powerpc64le-linux-gnu. 383s Preparing to unpack .../031-gcc-powerpc64le-linux-gnu_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 383s Unpacking gcc-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 383s Selecting previously unselected package gcc. 383s Preparing to unpack .../032-gcc_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 383s Unpacking gcc (4:13.2.0-7ubuntu1) ... 383s Selecting previously unselected package libstdc++-13-dev:ppc64el. 383s Preparing to unpack .../033-libstdc++-13-dev_13.2.0-17ubuntu2_ppc64el.deb ... 383s Unpacking libstdc++-13-dev:ppc64el (13.2.0-17ubuntu2) ... 383s Selecting previously unselected package g++-13-powerpc64le-linux-gnu. 383s Preparing to unpack .../034-g++-13-powerpc64le-linux-gnu_13.2.0-17ubuntu2_ppc64el.deb ... 383s Unpacking g++-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 383s Selecting previously unselected package g++-13. 383s Preparing to unpack .../035-g++-13_13.2.0-17ubuntu2_ppc64el.deb ... 383s Unpacking g++-13 (13.2.0-17ubuntu2) ... 383s Selecting previously unselected package g++-powerpc64le-linux-gnu. 383s Preparing to unpack .../036-g++-powerpc64le-linux-gnu_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 383s Unpacking g++-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 383s Selecting previously unselected package g++. 383s Preparing to unpack .../037-g++_4%3a13.2.0-7ubuntu1_ppc64el.deb ... 383s Unpacking g++ (4:13.2.0-7ubuntu1) ... 384s Selecting previously unselected package build-essential. 384s Preparing to unpack .../038-build-essential_12.10ubuntu1_ppc64el.deb ... 384s Unpacking build-essential (12.10ubuntu1) ... 384s Selecting previously unselected package libdebhelper-perl. 384s Preparing to unpack .../039-libdebhelper-perl_13.14.1ubuntu1_all.deb ... 384s Unpacking libdebhelper-perl (13.14.1ubuntu1) ... 384s Selecting previously unselected package libtool. 384s Preparing to unpack .../040-libtool_2.4.7-7_all.deb ... 384s Unpacking libtool (2.4.7-7) ... 384s Selecting previously unselected package dh-autoreconf. 384s Preparing to unpack .../041-dh-autoreconf_20_all.deb ... 384s Unpacking dh-autoreconf (20) ... 384s Selecting previously unselected package libarchive-zip-perl. 384s Preparing to unpack .../042-libarchive-zip-perl_1.68-1_all.deb ... 384s Unpacking libarchive-zip-perl (1.68-1) ... 384s Selecting previously unselected package libsub-override-perl. 384s Preparing to unpack .../043-libsub-override-perl_0.10-1_all.deb ... 384s Unpacking libsub-override-perl (0.10-1) ... 384s Selecting previously unselected package libfile-stripnondeterminism-perl. 384s Preparing to unpack .../044-libfile-stripnondeterminism-perl_1.13.1-1_all.deb ... 384s Unpacking libfile-stripnondeterminism-perl (1.13.1-1) ... 384s Selecting previously unselected package dh-strip-nondeterminism. 384s Preparing to unpack .../045-dh-strip-nondeterminism_1.13.1-1_all.deb ... 384s Unpacking dh-strip-nondeterminism (1.13.1-1) ... 384s Selecting previously unselected package libdw1:ppc64el. 384s Preparing to unpack .../046-libdw1_0.190-1_ppc64el.deb ... 384s Unpacking libdw1:ppc64el (0.190-1) ... 384s Selecting previously unselected package debugedit. 384s Preparing to unpack .../047-debugedit_1%3a5.0-5_ppc64el.deb ... 384s Unpacking debugedit (1:5.0-5) ... 384s Selecting previously unselected package dwz. 384s Preparing to unpack .../048-dwz_0.15-1_ppc64el.deb ... 384s Unpacking dwz (0.15-1) ... 384s Selecting previously unselected package gettext. 384s Preparing to unpack .../049-gettext_0.21-14ubuntu1_ppc64el.deb ... 384s Unpacking gettext (0.21-14ubuntu1) ... 384s Selecting previously unselected package intltool-debian. 384s Preparing to unpack .../050-intltool-debian_0.35.0+20060710.6_all.deb ... 384s Unpacking intltool-debian (0.35.0+20060710.6) ... 384s Selecting previously unselected package po-debconf. 384s Preparing to unpack .../051-po-debconf_1.0.21+nmu1_all.deb ... 384s Unpacking po-debconf (1.0.21+nmu1) ... 384s Selecting previously unselected package debhelper. 384s Preparing to unpack .../052-debhelper_13.14.1ubuntu1_all.deb ... 384s Unpacking debhelper (13.14.1ubuntu1) ... 384s Selecting previously unselected package libraft0:ppc64el. 384s Preparing to unpack .../053-libraft0_0.22.0-1_ppc64el.deb ... 384s Unpacking libraft0:ppc64el (0.22.0-1) ... 384s Selecting previously unselected package libdqlite0:ppc64el. 384s Preparing to unpack .../054-libdqlite0_1.16.0-5build1_ppc64el.deb ... 384s Unpacking libdqlite0:ppc64el (1.16.0-5build1) ... 384s Selecting previously unselected package go-dqlite. 384s Preparing to unpack .../055-go-dqlite_1.21.0-1_ppc64el.deb ... 384s Unpacking go-dqlite (1.21.0-1) ... 384s Selecting previously unselected package golang-1.22-src. 384s Preparing to unpack .../056-golang-1.22-src_1.22.1-1_all.deb ... 384s Unpacking golang-1.22-src (1.22.1-1) ... 387s Selecting previously unselected package golang-1.22-go. 387s Preparing to unpack .../057-golang-1.22-go_1.22.1-1_ppc64el.deb ... 387s Unpacking golang-1.22-go (1.22.1-1) ... 388s Selecting previously unselected package golang-src. 388s Preparing to unpack .../058-golang-src_2%3a1.22~2_all.deb ... 388s Unpacking golang-src (2:1.22~2) ... 388s Selecting previously unselected package golang-go:ppc64el. 388s Preparing to unpack .../059-golang-go_2%3a1.22~2_ppc64el.deb ... 388s Unpacking golang-go:ppc64el (2:1.22~2) ... 388s Selecting previously unselected package golang-any:ppc64el. 388s Preparing to unpack .../060-golang-any_2%3a1.22~2_ppc64el.deb ... 388s Unpacking golang-any:ppc64el (2:1.22~2) ... 388s Selecting previously unselected package libsqlite3-dev:ppc64el. 388s Preparing to unpack .../061-libsqlite3-dev_3.45.1-1_ppc64el.deb ... 388s Unpacking libsqlite3-dev:ppc64el (3.45.1-1) ... 388s Selecting previously unselected package libdqlite-dev:ppc64el. 388s Preparing to unpack .../062-libdqlite-dev_1.16.0-5build1_ppc64el.deb ... 388s Unpacking libdqlite-dev:ppc64el (1.16.0-5build1) ... 388s Selecting previously unselected package libraft-dev:ppc64el. 388s Preparing to unpack .../063-libraft-dev_0.22.0-1_ppc64el.deb ... 388s Unpacking libraft-dev:ppc64el (0.22.0-1) ... 388s Selecting previously unselected package golang-gopkg-yaml.v2-dev. 388s Preparing to unpack .../064-golang-gopkg-yaml.v2-dev_2.4.0-4_all.deb ... 388s Unpacking golang-gopkg-yaml.v2-dev (2.4.0-4) ... 388s Selecting previously unselected package golang-github-ghodss-yaml-dev. 388s Preparing to unpack .../065-golang-github-ghodss-yaml-dev_1.0.0+git20220118.d8423dc-2_all.deb ... 388s Unpacking golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ... 388s Selecting previously unselected package golang-github-google-renameio-dev. 388s Preparing to unpack .../066-golang-github-google-renameio-dev_2.0.0-2_all.deb ... 388s Unpacking golang-github-google-renameio-dev (2.0.0-2) ... 388s Selecting previously unselected package golang-github-mattn-go-sqlite3-dev:ppc64el. 388s Preparing to unpack .../067-golang-github-mattn-go-sqlite3-dev_1.14.19~ds1-1_ppc64el.deb ... 388s Unpacking golang-github-mattn-go-sqlite3-dev:ppc64el (1.14.19~ds1-1) ... 388s Selecting previously unselected package golang-github-rivo-uniseg-dev. 388s Preparing to unpack .../068-golang-github-rivo-uniseg-dev_0.4.4-1_all.deb ... 388s Unpacking golang-github-rivo-uniseg-dev (0.4.4-1) ... 388s Selecting previously unselected package golang-github-mattn-go-runewidth-dev. 388s Preparing to unpack .../069-golang-github-mattn-go-runewidth-dev_0.0.15-2_all.deb ... 388s Unpacking golang-github-mattn-go-runewidth-dev (0.0.15-2) ... 388s Selecting previously unselected package golang-github-peterh-liner-dev. 388s Preparing to unpack .../070-golang-github-peterh-liner-dev_1.2.2-1_all.deb ... 388s Unpacking golang-github-peterh-liner-dev (1.2.2-1) ... 388s Selecting previously unselected package golang-github-pkg-errors-dev. 388s Preparing to unpack .../071-golang-github-pkg-errors-dev_0.9.1-3_all.deb ... 388s Unpacking golang-github-pkg-errors-dev (0.9.1-3) ... 388s Selecting previously unselected package golang-github-rican7-retry-dev. 388s Preparing to unpack .../072-golang-github-rican7-retry-dev_0.3.1-2_all.deb ... 388s Unpacking golang-github-rican7-retry-dev (0.3.1-2) ... 388s Selecting previously unselected package golang-github-pmezard-go-difflib-dev. 388s Preparing to unpack .../073-golang-github-pmezard-go-difflib-dev_1.0.0-3_all.deb ... 388s Unpacking golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 388s Selecting previously unselected package golang-github-shurcool-sanitized-anchor-name-dev. 388s Preparing to unpack .../074-golang-github-shurcool-sanitized-anchor-name-dev_1.0.0-3_all.deb ... 388s Unpacking golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 388s Selecting previously unselected package golang-github-russross-blackfriday-v2-dev. 388s Preparing to unpack .../075-golang-github-russross-blackfriday-v2-dev_2.1.0-1_all.deb ... 388s Unpacking golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 388s Selecting previously unselected package golang-github-cpuguy83-go-md2man-v2-dev. 388s Preparing to unpack .../076-golang-github-cpuguy83-go-md2man-v2-dev_2.0.3+ds1-1_all.deb ... 388s Unpacking golang-github-cpuguy83-go-md2man-v2-dev (2.0.3+ds1-1) ... 388s Selecting previously unselected package golang-github-spf13-pflag-dev. 388s Preparing to unpack .../077-golang-github-spf13-pflag-dev_1.0.6~git20210604-d5e0c0615ace-1_all.deb ... 388s Unpacking golang-github-spf13-pflag-dev (1.0.6~git20210604-d5e0c0615ace-1) ... 388s Selecting previously unselected package golang-golang-x-sys-dev. 388s Preparing to unpack .../078-golang-golang-x-sys-dev_0.17.0-1_all.deb ... 388s Unpacking golang-golang-x-sys-dev (0.17.0-1) ... 388s Selecting previously unselected package golang-github-fsnotify-fsnotify-dev. 388s Preparing to unpack .../079-golang-github-fsnotify-fsnotify-dev_1.7.0-1_all.deb ... 388s Unpacking golang-github-fsnotify-fsnotify-dev (1.7.0-1) ... 388s Selecting previously unselected package golang-github-davecgh-go-spew-dev. 388s Preparing to unpack .../080-golang-github-davecgh-go-spew-dev_1.1.1-3_all.deb ... 388s Unpacking golang-github-davecgh-go-spew-dev (1.1.1-3) ... 389s Selecting previously unselected package golang-github-hashicorp-hcl-dev. 389s Preparing to unpack .../081-golang-github-hashicorp-hcl-dev_1.0.0-1.1_all.deb ... 389s Unpacking golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 389s Selecting previously unselected package golang-github-magiconair-properties-dev. 389s Preparing to unpack .../082-golang-github-magiconair-properties-dev_1.8.7-1_all.deb ... 389s Unpacking golang-github-magiconair-properties-dev (1.8.7-1) ... 389s Selecting previously unselected package golang-github-mitchellh-mapstructure-dev. 389s Preparing to unpack .../083-golang-github-mitchellh-mapstructure-dev_1.5.0+git20231216.8508981-1_all.deb ... 389s Unpacking golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 389s Selecting previously unselected package golang-github-burntsushi-toml-dev. 389s Preparing to unpack .../084-golang-github-burntsushi-toml-dev_1.3.2-2_all.deb ... 389s Unpacking golang-github-burntsushi-toml-dev (1.3.2-2) ... 389s Selecting previously unselected package golang-github-pelletier-go-toml-dev. 389s Preparing to unpack .../085-golang-github-pelletier-go-toml-dev_1.9.5-1build1_all.deb ... 389s Unpacking golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 389s Selecting previously unselected package golang-github-pelletier-go-toml.v2-dev. 389s Preparing to unpack .../086-golang-github-pelletier-go-toml.v2-dev_2.1.1-1_all.deb ... 389s Unpacking golang-github-pelletier-go-toml.v2-dev (2.1.1-1) ... 389s Selecting previously unselected package golang-github-kr-fs-dev. 389s Preparing to unpack .../087-golang-github-kr-fs-dev_0.1.0-2_all.deb ... 389s Unpacking golang-github-kr-fs-dev (0.1.0-2) ... 389s Selecting previously unselected package golang-golang-x-term-dev. 389s Preparing to unpack .../088-golang-golang-x-term-dev_0.17.0-1_all.deb ... 389s Unpacking golang-golang-x-term-dev (0.17.0-1) ... 389s Selecting previously unselected package golang-golang-x-text-dev. 389s Preparing to unpack .../089-golang-golang-x-text-dev_0.14.0-1_all.deb ... 389s Unpacking golang-golang-x-text-dev (0.14.0-1) ... 389s Selecting previously unselected package golang-golang-x-crypto-dev. 389s Preparing to unpack .../090-golang-golang-x-crypto-dev_1%3a0.19.0-1_all.deb ... 389s Unpacking golang-golang-x-crypto-dev (1:0.19.0-1) ... 389s Selecting previously unselected package golang-github-pkg-sftp-dev. 389s Preparing to unpack .../091-golang-github-pkg-sftp-dev_1.13.6-1_all.deb ... 389s Unpacking golang-github-pkg-sftp-dev (1.13.6-1) ... 389s Selecting previously unselected package golang-github-spf13-afero-dev. 389s Preparing to unpack .../092-golang-github-spf13-afero-dev_1.11.0-1_all.deb ... 389s Unpacking golang-github-spf13-afero-dev (1.11.0-1) ... 389s Selecting previously unselected package golang-github-spf13-cast-dev. 389s Preparing to unpack .../093-golang-github-spf13-cast-dev_1.6.0-1_all.deb ... 389s Unpacking golang-github-spf13-cast-dev (1.6.0-1) ... 389s Selecting previously unselected package golang-github-stretchr-objx-dev. 389s Preparing to unpack .../094-golang-github-stretchr-objx-dev_0.5.0-1_all.deb ... 389s Unpacking golang-github-stretchr-objx-dev (0.5.0-1) ... 389s Selecting previously unselected package golang-gopkg-yaml.v3-dev. 389s Preparing to unpack .../095-golang-gopkg-yaml.v3-dev_3.0.1-3_all.deb ... 389s Unpacking golang-gopkg-yaml.v3-dev (3.0.1-3) ... 389s Selecting previously unselected package golang-github-stretchr-testify-dev. 389s Preparing to unpack .../096-golang-github-stretchr-testify-dev_1.8.4-1_all.deb ... 389s Unpacking golang-github-stretchr-testify-dev (1.8.4-1) ... 389s Selecting previously unselected package golang-github-spf13-jwalterweatherman-dev. 389s Preparing to unpack .../097-golang-github-spf13-jwalterweatherman-dev_1.1.0+really1.1.0-2_all.deb ... 389s Unpacking golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 389s Selecting previously unselected package golang-github-subosito-gotenv-dev. 389s Preparing to unpack .../098-golang-github-subosito-gotenv-dev_1.3.0-1_all.deb ... 389s Unpacking golang-github-subosito-gotenv-dev (1.3.0-1) ... 390s Selecting previously unselected package golang-gopkg-ini.v1-dev. 390s Preparing to unpack .../099-golang-gopkg-ini.v1-dev_1.67.0-1_all.deb ... 390s Unpacking golang-gopkg-ini.v1-dev (1.67.0-1) ... 390s Selecting previously unselected package golang-github-spf13-viper-dev. 390s Preparing to unpack .../100-golang-github-spf13-viper-dev_1.12.0-1_all.deb ... 390s Unpacking golang-github-spf13-viper-dev (1.12.0-1) ... 390s Selecting previously unselected package golang-github-spf13-cobra-dev. 390s Preparing to unpack .../101-golang-github-spf13-cobra-dev_1.8.0-1_all.deb ... 390s Unpacking golang-github-spf13-cobra-dev (1.8.0-1) ... 390s Selecting previously unselected package golang-golang-x-sync-dev. 390s Preparing to unpack .../102-golang-golang-x-sync-dev_0.6.0-1_all.deb ... 390s Unpacking golang-golang-x-sync-dev (0.6.0-1) ... 390s Selecting previously unselected package golang-github-canonical-go-dqlite-dev. 390s Preparing to unpack .../103-golang-github-canonical-go-dqlite-dev_1.21.0-1_all.deb ... 390s Unpacking golang-github-canonical-go-dqlite-dev (1.21.0-1) ... 390s Selecting previously unselected package dh-golang. 390s Preparing to unpack .../104-dh-golang_1.62_all.deb ... 390s Unpacking dh-golang (1.62) ... 390s Selecting previously unselected package autopkgtest-satdep. 390s Preparing to unpack .../105-1-autopkgtest-satdep.deb ... 390s Unpacking autopkgtest-satdep (0) ... 390s Setting up golang-github-pelletier-go-toml.v2-dev (2.1.1-1) ... 390s Setting up golang-gopkg-yaml.v2-dev (2.4.0-4) ... 390s Setting up libdw1:ppc64el (0.190-1) ... 390s Setting up golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 390s Setting up golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 390s Setting up golang-github-stretchr-objx-dev (0.5.0-1) ... 390s Setting up golang-github-pkg-errors-dev (0.9.1-3) ... 390s Setting up libarchive-zip-perl (1.68-1) ... 390s Setting up golang-gopkg-yaml.v3-dev (3.0.1-3) ... 390s Setting up libdebhelper-perl (13.14.1ubuntu1) ... 390s Setting up golang-github-burntsushi-toml-dev (1.3.2-2) ... 390s Setting up golang-golang-x-sys-dev (0.17.0-1) ... 390s Setting up golang-github-kr-fs-dev (0.1.0-2) ... 390s Setting up libuv1t64:ppc64el (1.48.0-1.1) ... 390s Setting up golang-github-subosito-gotenv-dev (1.3.0-1) ... 390s Setting up linux-libc-dev:ppc64el (6.8.0-11.11) ... 390s Setting up golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 390s Setting up m4 (1.4.19-4) ... 390s Setting up golang-golang-x-term-dev (0.17.0-1) ... 390s Setting up libgomp1:ppc64el (14-20240303-1ubuntu1) ... 390s Setting up golang-github-google-renameio-dev (2.0.0-2) ... 390s Setting up golang-github-spf13-pflag-dev (1.0.6~git20210604-d5e0c0615ace-1) ... 390s Setting up golang-github-davecgh-go-spew-dev (1.1.1-3) ... 390s Setting up autotools-dev (20220109.1) ... 390s Setting up libtirpc-dev:ppc64el (1.3.4+ds-1build1) ... 390s Setting up rpcsvc-proto (1.4.2-0ubuntu6) ... 390s Setting up libraft0:ppc64el (0.22.0-1) ... 390s Setting up golang-github-stretchr-testify-dev (1.8.4-1) ... 390s Setting up golang-1.22-src (1.22.1-1) ... 390s Setting up libquadmath0:ppc64el (14-20240303-1ubuntu1) ... 390s Setting up libraft-dev:ppc64el (0.22.0-1) ... 390s Setting up golang-gopkg-ini.v1-dev (1.67.0-1) ... 390s Setting up libmpc3:ppc64el (1.3.1-1) ... 390s Setting up libatomic1:ppc64el (14-20240303-1ubuntu1) ... 390s Setting up golang-golang-x-sync-dev (0.6.0-1) ... 390s Setting up autopoint (0.21-14ubuntu1) ... 390s Setting up golang-github-magiconair-properties-dev (1.8.7-1) ... 390s Setting up golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 390s Setting up golang-github-rican7-retry-dev (0.3.1-2) ... 390s Setting up autoconf (2.71-3) ... 390s Setting up golang-github-rivo-uniseg-dev (0.4.4-1) ... 390s Setting up libubsan1:ppc64el (14-20240303-1ubuntu1) ... 390s Setting up dwz (0.15-1) ... 390s Setting up libnsl-dev:ppc64el (1.3.0-3) ... 390s Setting up golang-golang-x-text-dev (0.14.0-1) ... 390s Setting up libcrypt-dev:ppc64el (1:4.4.36-4) ... 390s Setting up libasan8:ppc64el (14-20240303-1ubuntu1) ... 390s Setting up golang-github-spf13-cast-dev (1.6.0-1) ... 390s Setting up golang-github-fsnotify-fsnotify-dev (1.7.0-1) ... 390s Setting up debugedit (1:5.0-5) ... 390s Setting up libsub-override-perl (0.10-1) ... 390s Setting up golang-golang-x-crypto-dev (1:0.19.0-1) ... 390s Setting up golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 390s Setting up golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 390s Setting up libtsan2:ppc64el (14-20240303-1ubuntu1) ... 390s Setting up libdqlite0:ppc64el (1.16.0-5build1) ... 390s Setting up libisl23:ppc64el (0.26-3) ... 390s Setting up golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ... 390s Setting up libc-dev-bin (2.39-0ubuntu2) ... 390s Setting up cpp-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 390s Setting up golang-src (2:1.22~2) ... 390s Setting up libcc1-0:ppc64el (14-20240303-1ubuntu1) ... 390s Setting up liblsan0:ppc64el (14-20240303-1ubuntu1) ... 390s Setting up libitm1:ppc64el (14-20240303-1ubuntu1) ... 390s Setting up cpp-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 390s Setting up automake (1:1.16.5-1.3ubuntu1) ... 390s update-alternatives: using /usr/bin/automake-1.16 to provide /usr/bin/automake (automake) in auto mode 390s Setting up libfile-stripnondeterminism-perl (1.13.1-1) ... 390s Setting up golang-github-mattn-go-runewidth-dev (0.0.15-2) ... 390s Setting up gettext (0.21-14ubuntu1) ... 390s Setting up golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 390s Setting up cpp-13 (13.2.0-17ubuntu2) ... 390s Setting up golang-github-pkg-sftp-dev (1.13.6-1) ... 390s Setting up golang-1.22-go (1.22.1-1) ... 390s Setting up intltool-debian (0.35.0+20060710.6) ... 390s Setting up golang-github-peterh-liner-dev (1.2.2-1) ... 390s Setting up go-dqlite (1.21.0-1) ... 390s Setting up dh-strip-nondeterminism (1.13.1-1) ... 390s Setting up libgcc-13-dev:ppc64el (13.2.0-17ubuntu2) ... 390s Setting up cpp (4:13.2.0-7ubuntu1) ... 390s Setting up libc6-dev:ppc64el (2.39-0ubuntu2) ... 390s Setting up golang-github-spf13-afero-dev (1.11.0-1) ... 390s Setting up libstdc++-13-dev:ppc64el (13.2.0-17ubuntu2) ... 390s Setting up golang-github-cpuguy83-go-md2man-v2-dev (2.0.3+ds1-1) ... 390s Setting up golang-go:ppc64el (2:1.22~2) ... 390s Setting up po-debconf (1.0.21+nmu1) ... 390s Setting up golang-any:ppc64el (2:1.22~2) ... 390s Setting up libsqlite3-dev:ppc64el (3.45.1-1) ... 390s Setting up gcc-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 390s Setting up gcc-13 (13.2.0-17ubuntu2) ... 390s Setting up golang-github-spf13-viper-dev (1.12.0-1) ... 390s Setting up libdqlite-dev:ppc64el (1.16.0-5build1) ... 390s Setting up libtool (2.4.7-7) ... 390s Setting up g++-13-powerpc64le-linux-gnu (13.2.0-17ubuntu2) ... 390s Setting up dh-autoreconf (20) ... 390s Setting up golang-github-mattn-go-sqlite3-dev:ppc64el (1.14.19~ds1-1) ... 390s Setting up gcc-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 390s Setting up golang-github-spf13-cobra-dev (1.8.0-1) ... 390s Setting up g++-powerpc64le-linux-gnu (4:13.2.0-7ubuntu1) ... 390s Setting up g++-13 (13.2.0-17ubuntu2) ... 390s Setting up debhelper (13.14.1ubuntu1) ... 390s Setting up gcc (4:13.2.0-7ubuntu1) ... 390s Setting up golang-github-canonical-go-dqlite-dev (1.21.0-1) ... 390s Setting up g++ (4:13.2.0-7ubuntu1) ... 390s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 390s Setting up build-essential (12.10ubuntu1) ... 390s Setting up dh-golang (1.62) ... 390s Setting up autopkgtest-satdep (0) ... 390s Processing triggers for man-db (2.12.0-3) ... 391s Processing triggers for install-info (7.1-3) ... 391s Processing triggers for libc-bin (2.39-0ubuntu2) ... 395s (Reading database ... 92105 files and directories currently installed.) 395s Removing autopkgtest-satdep (0) ... 396s autopkgtest [18:29:52]: test dh-golang-autopkgtest: /usr/bin/dh_golang_autopkgtest 396s autopkgtest [18:29:52]: test dh-golang-autopkgtest: [----------------------- 396s [info] Testing github.com/canonical/go-dqlite... 396s [info] Source code installed by binary package, overriding dh_auto_configure... 397s dh build --builddirectory=_build --buildsystem=golang --with=golang 397s dh_update_autotools_config -O--builddirectory=_build -O--buildsystem=golang 397s dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang 397s debian/rules override_dh_auto_configure 397s make[1]: Entering directory '/tmp/autopkgtest.sVMzRF/autopkgtest_tmp' 397s mkdir -p "_build" 397s # remove existing symlinks, since "cp -a" won't convert them 397s find "_build" -type l -exec rm "{}" \; 397s cp -a /usr/share/gocode/src "_build" 397s make[1]: Leaving directory '/tmp/autopkgtest.sVMzRF/autopkgtest_tmp' 397s dh_auto_build -O--builddirectory=_build -O--buildsystem=golang 398s cd _build && go install -trimpath -v -p 1 github.com/canonical/go-dqlite github.com/canonical/go-dqlite/app github.com/canonical/go-dqlite/benchmark github.com/canonical/go-dqlite/client github.com/canonical/go-dqlite/cmd/dqlite github.com/canonical/go-dqlite/cmd/dqlite-benchmark github.com/canonical/go-dqlite/cmd/dqlite-demo github.com/canonical/go-dqlite/driver github.com/canonical/go-dqlite/internal/bindings github.com/canonical/go-dqlite/internal/protocol github.com/canonical/go-dqlite/internal/shell github.com/canonical/go-dqlite/logging github.com/canonical/go-dqlite/tracing 398s internal/goarch 398s internal/abi 399s internal/unsafeheader 399s internal/cpu 399s internal/bytealg 399s internal/chacha8rand 399s internal/coverage/rtcov 399s internal/godebugs 399s internal/goexperiment 399s internal/goos 399s runtime/internal/atomic 399s runtime/internal/math 399s runtime/internal/sys 399s runtime/internal/syscall 399s runtime 405s internal/reflectlite 405s errors 405s internal/race 405s sync/atomic 405s sync 405s internal/itoa 405s internal/oserror 405s syscall 406s time 406s context 406s math/bits 406s math 407s unicode/utf8 407s strconv 407s unicode 407s reflect 408s cmp 408s slices 408s sort 408s internal/fmtsort 409s io 409s internal/syscall/unix 409s internal/poll 409s internal/safefilepath 409s internal/syscall/execenv 409s internal/testlog 409s path 409s io/fs 409s os 409s fmt 410s bytes 410s container/list 410s hash 410s crypto 410s crypto/internal/alias 410s crypto/subtle 410s encoding/binary 410s crypto/cipher 410s crypto/internal/boring/sig 410s crypto/internal/boring 410s crypto/aes 410s crypto/des 410s crypto/internal/edwards25519/field 411s crypto/internal/nistec/fiat 411s embed 411s crypto/internal/nistec 411s crypto/internal/randutil 411s crypto/ecdh 412s internal/bisect 412s internal/godebug 412s math/rand 412s strings 412s math/big 413s crypto/elliptic 413s crypto/internal/bigmod 413s crypto/internal/boring/bbig 413s crypto/sha512 413s unicode/utf16 413s encoding/asn1 413s vendor/golang.org/x/crypto/cryptobyte/asn1 413s vendor/golang.org/x/crypto/cryptobyte 414s crypto/ecdsa 414s crypto/internal/edwards25519 414s crypto/rand 414s crypto/ed25519 414s crypto/hmac 414s crypto/md5 414s crypto/rc4 414s crypto/rsa 414s crypto/sha1 414s crypto/sha256 414s crypto/dsa 414s encoding/hex 414s crypto/x509/pkix 414s encoding/base64 414s encoding/pem 414s vendor/golang.org/x/net/dns/dnsmessage 415s internal/nettrace 415s internal/singleflight 415s internal/intern 415s net/netip 415s runtime/cgo 416s net 419s net/url 419s path/filepath 419s crypto/x509 420s vendor/golang.org/x/crypto/internal/alias 420s vendor/golang.org/x/crypto/chacha20 420s vendor/golang.org/x/crypto/internal/poly1305 420s vendor/golang.org/x/crypto/chacha20poly1305 420s vendor/golang.org/x/crypto/hkdf 420s crypto/tls 422s database/sql/driver 422s database/sql 422s github.com/Rican7/retry/backoff 422s github.com/Rican7/retry/jitter 422s github.com/Rican7/retry/strategy 422s github.com/Rican7/retry 422s encoding 422s flag 422s bufio 422s internal/sysinfo 423s runtime/debug 423s runtime/trace 423s testing 423s github.com/canonical/go-dqlite/logging 423s github.com/pkg/errors 423s github.com/canonical/go-dqlite/internal/protocol 423s io/ioutil 423s github.com/google/renameio 424s github.com/mattn/go-sqlite3 428s regexp/syntax 428s regexp 429s gopkg.in/yaml.v2 430s github.com/canonical/go-dqlite/client 430s github.com/canonical/go-dqlite/internal/bindings 431s github.com/canonical/go-dqlite 431s github.com/canonical/go-dqlite/tracing 431s github.com/canonical/go-dqlite/driver 431s golang.org/x/sync/semaphore 431s golang.org/x/sys/unix 432s log/internal 432s log 432s github.com/canonical/go-dqlite/app 433s github.com/canonical/go-dqlite/benchmark 433s encoding/json 433s github.com/canonical/go-dqlite/internal/shell 433s container/ring 433s github.com/rivo/uniseg 434s github.com/mattn/go-runewidth 434s os/signal 434s github.com/peterh/liner 434s encoding/csv 434s github.com/spf13/pflag 435s text/template/parse 435s text/template 436s github.com/spf13/cobra 436s github.com/canonical/go-dqlite/cmd/dqlite 438s github.com/canonical/go-dqlite/cmd/dqlite-benchmark 439s compress/flate 439s hash/crc32 439s compress/gzip 439s vendor/golang.org/x/text/transform 439s vendor/golang.org/x/text/unicode/bidi 440s vendor/golang.org/x/text/secure/bidirule 440s vendor/golang.org/x/text/unicode/norm 440s vendor/golang.org/x/net/idna 440s net/textproto 441s vendor/golang.org/x/net/http/httpguts 441s vendor/golang.org/x/net/http/httpproxy 441s vendor/golang.org/x/net/http2/hpack 441s mime 441s mime/quotedprintable 441s mime/multipart 441s net/http/httptrace 441s net/http/internal 441s net/http/internal/ascii 441s net/http 445s github.com/canonical/go-dqlite/cmd/dqlite-demo 446s create-stamp debian/debhelper-build-stamp 446s dh_auto_test -O--builddirectory=_build -O--buildsystem=golang 447s cd _build && go test -vet=off -v -p 1 github.com/canonical/go-dqlite github.com/canonical/go-dqlite/app github.com/canonical/go-dqlite/benchmark github.com/canonical/go-dqlite/client github.com/canonical/go-dqlite/cmd/dqlite github.com/canonical/go-dqlite/cmd/dqlite-benchmark github.com/canonical/go-dqlite/cmd/dqlite-demo github.com/canonical/go-dqlite/driver github.com/canonical/go-dqlite/internal/bindings github.com/canonical/go-dqlite/internal/protocol github.com/canonical/go-dqlite/internal/shell github.com/canonical/go-dqlite/logging github.com/canonical/go-dqlite/tracing 479s ? github.com/canonical/go-dqlite [no test files] 493s === RUN TestNew_PristineDefault 493s --- PASS: TestNew_PristineDefault (0.04s) 493s === RUN TestNew_PristineJoiner 493s app_test.go:1202: 18:31:03.431 - 2: DEBUG: new connection from 127.0.0.1:47566 493s app_test.go:1202: 18:31:03.435 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.437 - 2: DEBUG: new connection from 127.0.0.1:47578 493s app_test.go:1202: 18:31:03.457 - 2: DEBUG: new connection from 127.0.0.1:47582 493s app_test.go:1202: 18:31:03.460 - 3: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.463 - 2: DEBUG: new connection from 127.0.0.1:47590 493s app_test.go:1202: 18:31:03.466 - 3: DEBUG: new connection from 127.0.0.1:58846 493s app_test.go:1202: 18:31:03.468 - 2: DEBUG: new connection from 127.0.0.1:47596 493s app_test.go:1202: 18:31:03.471 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s --- PASS: TestNew_PristineJoiner (0.09s) 493s === RUN TestNew_JoinerRestart 493s app_test.go:1202: 18:31:03.522 - 4: DEBUG: new connection from 127.0.0.1:47610 493s app_test.go:1202: 18:31:03.524 - 4: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.527 - 4: DEBUG: new connection from 127.0.0.1:47614 493s app_test.go:1202: 18:31:03.560 - 4: DEBUG: new connection from 127.0.0.1:47618 493s app_test.go:1202: 18:31:03.563 - 5: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.565 - 4: DEBUG: new connection from 127.0.0.1:47626 493s app_test.go:1202: 18:31:03.568 - 5: DEBUG: new connection from 127.0.0.1:58862 493s app_test.go:1202: 18:31:03.592 - 4: DEBUG: new connection from 127.0.0.1:47632 493s app_test.go:1202: 18:31:03.594 - 6: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.596 - 4: DEBUG: new connection from 127.0.0.1:47638 493s app_test.go:1202: 18:31:03.598 - 6: DEBUG: new connection from 127.0.0.1:58878 493s --- PASS: TestNew_JoinerRestart (0.13s) 493s === RUN TestNew_SecondJoiner 493s app_test.go:1202: 18:31:03.650 - 7: DEBUG: new connection from 127.0.0.1:47642 493s app_test.go:1202: 18:31:03.652 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.655 - 7: DEBUG: new connection from 127.0.0.1:47656 493s app_test.go:1202: 18:31:03.676 - 7: DEBUG: new connection from 127.0.0.1:47660 493s app_test.go:1202: 18:31:03.678 - 8: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.681 - 7: DEBUG: new connection from 127.0.0.1:47664 493s app_test.go:1202: 18:31:03.685 - 8: DEBUG: new connection from 127.0.0.1:58882 493s app_test.go:1202: 18:31:03.716 - 7: DEBUG: new connection from 127.0.0.1:47680 493s app_test.go:1202: 18:31:03.719 - 9: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.721 - 7: DEBUG: new connection from 127.0.0.1:47682 493s app_test.go:1202: 18:31:03.723 - 8: DEBUG: new connection from 127.0.0.1:58894 493s app_test.go:1202: 18:31:03.726 - 9: DEBUG: new connection from 127.0.0.1:58636 493s app_test.go:1202: 18:31:03.729 - 9: DEBUG: new connection from 127.0.0.1:58650 493s app_test.go:1202: 18:31:03.733 - 7: DEBUG: new connection from 127.0.0.1:47694 493s app_test.go:1202: 18:31:03.738 - 8: DEBUG: new connection from 127.0.0.1:58898 493s app_test.go:1202: 18:31:03.748 - 7: DEBUG: new connection from 127.0.0.1:47708 493s app_test.go:1202: 18:31:03.753 - 7: DEBUG: new connection from 127.0.0.1:47710 493s app_test.go:1202: 18:31:03.755 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s --- PASS: TestNew_SecondJoiner (0.21s) 493s === RUN TestNew_ThirdJoiner 493s app_test.go:1202: 18:31:03.858 - 10: DEBUG: new connection from 127.0.0.1:47712 493s app_test.go:1202: 18:31:03.861 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.862 - 10: DEBUG: new connection from 127.0.0.1:47726 493s app_test.go:1202: 18:31:03.888 - 10: DEBUG: new connection from 127.0.0.1:47736 493s app_test.go:1202: 18:31:03.890 - 11: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.893 - 10: DEBUG: new connection from 127.0.0.1:47740 493s app_test.go:1202: 18:31:03.896 - 11: DEBUG: new connection from 127.0.0.1:58910 493s app_test.go:1202: 18:31:03.928 - 10: DEBUG: new connection from 127.0.0.1:47742 493s app_test.go:1202: 18:31:03.930 - 12: DEBUG: attempt 1: server 127.0.0.1:9001: connected 493s app_test.go:1202: 18:31:03.932 - 10: DEBUG: new connection from 127.0.0.1:47750 493s app_test.go:1202: 18:31:03.935 - 11: DEBUG: new connection from 127.0.0.1:58914 493s app_test.go:1202: 18:31:03.938 - 12: DEBUG: new connection from 127.0.0.1:58664 493s app_test.go:1202: 18:31:03.945 - 12: DEBUG: new connection from 127.0.0.1:58676 493s app_test.go:1202: 18:31:03.949 - 10: DEBUG: new connection from 127.0.0.1:47754 493s app_test.go:1202: 18:31:03.954 - 11: DEBUG: new connection from 127.0.0.1:58926 494s app_test.go:1202: 18:31:03.957 - 10: DEBUG: new connection from 127.0.0.1:47756 494s app_test.go:1202: 18:31:03.992 - 10: DEBUG: new connection from 127.0.0.1:47768 494s app_test.go:1202: 18:31:03.994 - 13: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.997 - 10: DEBUG: new connection from 127.0.0.1:47774 494s app_test.go:1202: 18:31:03.000 - 11: DEBUG: new connection from 127.0.0.1:58934 494s app_test.go:1202: 18:31:03.002 - 12: DEBUG: new connection from 127.0.0.1:58678 494s app_test.go:1202: 18:31:03.004 - 13: DEBUG: new connection from 127.0.0.1:50712 494s app_test.go:1202: 18:31:03.007 - 13: DEBUG: new connection from 127.0.0.1:50726 494s app_test.go:1202: 18:31:03.023 - 10: DEBUG: new connection from 127.0.0.1:47786 494s app_test.go:1202: 18:31:03.027 - 10: DEBUG: new connection from 127.0.0.1:47790 494s app_test.go:1202: 18:31:03.030 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s --- PASS: TestNew_ThirdJoiner (0.30s) 494s === RUN TestNew_FourthJoiner 494s app_test.go:1202: 18:31:03.158 - 14: DEBUG: new connection from 127.0.0.1:47802 494s app_test.go:1202: 18:31:03.161 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.162 - 14: DEBUG: new connection from 127.0.0.1:47818 494s app_test.go:1202: 18:31:03.188 - 14: DEBUG: new connection from 127.0.0.1:47828 494s app_test.go:1202: 18:31:03.190 - 15: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.193 - 14: DEBUG: new connection from 127.0.0.1:47844 494s app_test.go:1202: 18:31:03.197 - 15: DEBUG: new connection from 127.0.0.1:58936 494s app_test.go:1202: 18:31:03.224 - 14: DEBUG: new connection from 127.0.0.1:47850 494s app_test.go:1202: 18:31:03.226 - 16: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.228 - 14: DEBUG: new connection from 127.0.0.1:47860 494s app_test.go:1202: 18:31:03.231 - 15: DEBUG: new connection from 127.0.0.1:58944 494s app_test.go:1202: 18:31:03.233 - 16: DEBUG: new connection from 127.0.0.1:58686 494s app_test.go:1202: 18:31:03.236 - 16: DEBUG: new connection from 127.0.0.1:58696 494s app_test.go:1202: 18:31:03.240 - 14: DEBUG: new connection from 127.0.0.1:47870 494s app_test.go:1202: 18:31:03.244 - 15: DEBUG: new connection from 127.0.0.1:58948 494s app_test.go:1202: 18:31:03.249 - 14: DEBUG: new connection from 127.0.0.1:47884 494s app_test.go:1202: 18:31:03.280 - 14: DEBUG: new connection from 127.0.0.1:47898 494s app_test.go:1202: 18:31:03.282 - 17: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.285 - 14: DEBUG: new connection from 127.0.0.1:47900 494s app_test.go:1202: 18:31:03.287 - 15: DEBUG: new connection from 127.0.0.1:58962 494s app_test.go:1202: 18:31:03.289 - 16: DEBUG: new connection from 127.0.0.1:58700 494s app_test.go:1202: 18:31:03.292 - 17: DEBUG: new connection from 127.0.0.1:50742 494s app_test.go:1202: 18:31:03.294 - 17: DEBUG: new connection from 127.0.0.1:50750 494s app_test.go:1202: 18:31:03.319 - 14: DEBUG: new connection from 127.0.0.1:47904 494s app_test.go:1202: 18:31:03.328 - 14: DEBUG: new connection from 127.0.0.1:47918 494s app_test.go:1202: 18:31:03.330 - 18: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.333 - 14: DEBUG: new connection from 127.0.0.1:47932 494s app_test.go:1202: 18:31:03.337 - 15: DEBUG: new connection from 127.0.0.1:58972 494s app_test.go:1202: 18:31:03.340 - 16: DEBUG: new connection from 127.0.0.1:58702 494s app_test.go:1202: 18:31:03.342 - 17: DEBUG: new connection from 127.0.0.1:50752 494s app_test.go:1202: 18:31:03.345 - 18: DEBUG: new connection from 127.0.0.1:58008 494s app_test.go:1202: 18:31:03.347 - 18: DEBUG: new connection from 127.0.0.1:58020 494s app_test.go:1202: 18:31:03.350 - 14: DEBUG: new connection from 127.0.0.1:47948 494s app_test.go:1202: 18:31:03.353 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s --- PASS: TestNew_FourthJoiner (0.32s) 494s === RUN TestNew_FifthJoiner 494s app_test.go:1202: 18:31:03.478 - 19: DEBUG: new connection from 127.0.0.1:47952 494s app_test.go:1202: 18:31:03.480 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.482 - 19: DEBUG: new connection from 127.0.0.1:47964 494s app_test.go:1202: 18:31:03.508 - 19: DEBUG: new connection from 127.0.0.1:47980 494s app_test.go:1202: 18:31:03.510 - 20: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.513 - 19: DEBUG: new connection from 127.0.0.1:47990 494s app_test.go:1202: 18:31:03.515 - 20: DEBUG: new connection from 127.0.0.1:58986 494s app_test.go:1202: 18:31:03.549 - 19: DEBUG: new connection from 127.0.0.1:48002 494s app_test.go:1202: 18:31:03.552 - 21: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.554 - 19: DEBUG: new connection from 127.0.0.1:48018 494s app_test.go:1202: 18:31:03.557 - 20: DEBUG: new connection from 127.0.0.1:58996 494s app_test.go:1202: 18:31:03.561 - 21: DEBUG: new connection from 127.0.0.1:58716 494s app_test.go:1202: 18:31:03.565 - 21: DEBUG: new connection from 127.0.0.1:58730 494s app_test.go:1202: 18:31:03.570 - 19: DEBUG: new connection from 127.0.0.1:48032 494s app_test.go:1202: 18:31:03.574 - 20: DEBUG: new connection from 127.0.0.1:59000 494s app_test.go:1202: 18:31:03.579 - 19: DEBUG: new connection from 127.0.0.1:48044 494s app_test.go:1202: 18:31:03.614 - 19: DEBUG: new connection from 127.0.0.1:48054 494s app_test.go:1202: 18:31:03.616 - 22: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.619 - 19: DEBUG: new connection from 127.0.0.1:48064 494s app_test.go:1202: 18:31:03.621 - 20: DEBUG: new connection from 127.0.0.1:59002 494s app_test.go:1202: 18:31:03.624 - 21: DEBUG: new connection from 127.0.0.1:58736 494s app_test.go:1202: 18:31:03.626 - 22: DEBUG: new connection from 127.0.0.1:50754 494s app_test.go:1202: 18:31:03.632 - 22: DEBUG: new connection from 127.0.0.1:50768 494s app_test.go:1202: 18:31:03.639 - 19: DEBUG: new connection from 127.0.0.1:48076 494s app_test.go:1202: 18:31:03.668 - 19: DEBUG: new connection from 127.0.0.1:48084 494s app_test.go:1202: 18:31:03.670 - 23: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.673 - 19: DEBUG: new connection from 127.0.0.1:48094 494s app_test.go:1202: 18:31:03.675 - 20: DEBUG: new connection from 127.0.0.1:59004 494s app_test.go:1202: 18:31:03.678 - 21: DEBUG: new connection from 127.0.0.1:58738 494s app_test.go:1202: 18:31:03.680 - 22: DEBUG: new connection from 127.0.0.1:50770 494s app_test.go:1202: 18:31:03.684 - 23: DEBUG: new connection from 127.0.0.1:58022 494s app_test.go:1202: 18:31:03.688 - 23: DEBUG: new connection from 127.0.0.1:58028 494s app_test.go:1202: 18:31:03.695 - 19: DEBUG: new connection from 127.0.0.1:48098 494s app_test.go:1202: 18:31:03.724 - 19: DEBUG: new connection from 127.0.0.1:48106 494s app_test.go:1202: 18:31:03.726 - 24: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.738 - 19: DEBUG: new connection from 127.0.0.1:48118 494s app_test.go:1202: 18:31:03.740 - 20: DEBUG: new connection from 127.0.0.1:59006 494s app_test.go:1202: 18:31:03.743 - 21: DEBUG: new connection from 127.0.0.1:58744 494s app_test.go:1202: 18:31:03.745 - 22: DEBUG: new connection from 127.0.0.1:50780 494s app_test.go:1202: 18:31:03.747 - 23: DEBUG: new connection from 127.0.0.1:58042 494s app_test.go:1202: 18:31:03.749 - 24: DEBUG: new connection from 127.0.0.1:40368 494s app_test.go:1202: 18:31:03.763 - 24: DEBUG: new connection from 127.0.0.1:40380 494s app_test.go:1202: 18:31:03.766 - 19: DEBUG: new connection from 127.0.0.1:48120 494s app_test.go:1202: 18:31:03.769 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.770 - 19: DEBUG: new connection from 127.0.0.1:48130 494s app_test.go:1202: 18:31:03.771 - 19: ERROR: proxy: first: remote -> local: read tcp 127.0.0.1:9001->127.0.0.1:48130: read: connection reset by peer 494s --- PASS: TestNew_FifthJoiner (0.43s) 494s === RUN TestNew_SixthJoiner 494s app_test.go:1202: 18:31:03.906 - 25: DEBUG: new connection from 127.0.0.1:48134 494s app_test.go:1202: 18:31:03.910 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.912 - 25: DEBUG: new connection from 127.0.0.1:48144 494s app_test.go:1202: 18:31:03.940 - 25: DEBUG: new connection from 127.0.0.1:48160 494s app_test.go:1202: 18:31:03.942 - 26: DEBUG: attempt 1: server 127.0.0.1:9001: connected 494s app_test.go:1202: 18:31:03.946 - 25: DEBUG: new connection from 127.0.0.1:48172 494s app_test.go:1202: 18:31:03.948 - 26: DEBUG: new connection from 127.0.0.1:59020 495s app_test.go:1202: 18:31:03.976 - 25: DEBUG: new connection from 127.0.0.1:35000 495s app_test.go:1202: 18:31:03.978 - 27: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.981 - 25: DEBUG: new connection from 127.0.0.1:35008 495s app_test.go:1202: 18:31:03.983 - 26: DEBUG: new connection from 127.0.0.1:42186 495s app_test.go:1202: 18:31:03.986 - 27: DEBUG: new connection from 127.0.0.1:33392 495s app_test.go:1202: 18:31:03.991 - 27: DEBUG: new connection from 127.0.0.1:33396 495s app_test.go:1202: 18:31:03.995 - 25: DEBUG: new connection from 127.0.0.1:35020 495s app_test.go:1202: 18:31:03.000 - 26: DEBUG: new connection from 127.0.0.1:42188 495s app_test.go:1202: 18:31:03.004 - 25: DEBUG: new connection from 127.0.0.1:35034 495s app_test.go:1202: 18:31:03.036 - 25: DEBUG: new connection from 127.0.0.1:35040 495s app_test.go:1202: 18:31:03.039 - 28: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.041 - 25: DEBUG: new connection from 127.0.0.1:35046 495s app_test.go:1202: 18:31:03.044 - 26: DEBUG: new connection from 127.0.0.1:42204 495s app_test.go:1202: 18:31:03.046 - 27: DEBUG: new connection from 127.0.0.1:33400 495s app_test.go:1202: 18:31:03.049 - 28: DEBUG: new connection from 127.0.0.1:46138 495s app_test.go:1202: 18:31:03.053 - 28: DEBUG: new connection from 127.0.0.1:46144 495s app_test.go:1202: 18:31:03.060 - 25: DEBUG: new connection from 127.0.0.1:35060 495s app_test.go:1202: 18:31:03.088 - 25: DEBUG: new connection from 127.0.0.1:35062 495s app_test.go:1202: 18:31:03.090 - 29: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.093 - 25: DEBUG: new connection from 127.0.0.1:35070 495s app_test.go:1202: 18:31:03.095 - 26: DEBUG: new connection from 127.0.0.1:42210 495s app_test.go:1202: 18:31:03.098 - 27: DEBUG: new connection from 127.0.0.1:33412 495s app_test.go:1202: 18:31:03.100 - 28: DEBUG: new connection from 127.0.0.1:46160 495s app_test.go:1202: 18:31:03.102 - 29: DEBUG: new connection from 127.0.0.1:47208 495s app_test.go:1202: 18:31:03.104 - 29: DEBUG: new connection from 127.0.0.1:47214 495s app_test.go:1202: 18:31:03.128 - 25: DEBUG: new connection from 127.0.0.1:35084 495s app_test.go:1202: 18:31:03.140 - 25: DEBUG: new connection from 127.0.0.1:35094 495s app_test.go:1202: 18:31:03.143 - 30: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.146 - 25: DEBUG: new connection from 127.0.0.1:35110 495s app_test.go:1202: 18:31:03.149 - 26: DEBUG: new connection from 127.0.0.1:42222 495s app_test.go:1202: 18:31:03.152 - 27: DEBUG: new connection from 127.0.0.1:33414 495s app_test.go:1202: 18:31:03.154 - 28: DEBUG: new connection from 127.0.0.1:46164 495s app_test.go:1202: 18:31:03.159 - 29: DEBUG: new connection from 127.0.0.1:47218 495s app_test.go:1202: 18:31:03.161 - 30: DEBUG: new connection from 127.0.0.1:44166 495s app_test.go:1202: 18:31:03.166 - 30: DEBUG: new connection from 127.0.0.1:44172 495s app_test.go:1202: 18:31:03.174 - 25: DEBUG: new connection from 127.0.0.1:35112 495s app_test.go:1202: 18:31:03.200 - 25: DEBUG: new connection from 127.0.0.1:35128 495s app_test.go:1202: 18:31:03.202 - 31: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.206 - 25: DEBUG: new connection from 127.0.0.1:35136 495s app_test.go:1202: 18:31:03.208 - 26: DEBUG: new connection from 127.0.0.1:42234 495s app_test.go:1202: 18:31:03.211 - 27: DEBUG: new connection from 127.0.0.1:33430 495s app_test.go:1202: 18:31:03.213 - 28: DEBUG: new connection from 127.0.0.1:46168 495s app_test.go:1202: 18:31:03.215 - 29: DEBUG: new connection from 127.0.0.1:47230 495s app_test.go:1202: 18:31:03.218 - 30: DEBUG: new connection from 127.0.0.1:44176 495s app_test.go:1202: 18:31:03.220 - 31: DEBUG: new connection from 127.0.0.1:38598 495s app_test.go:1202: 18:31:03.224 - 25: DEBUG: new connection from 127.0.0.1:35142 495s app_test.go:1202: 18:31:03.226 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s --- PASS: TestNew_SixthJoiner (0.50s) 495s === RUN TestHandover_Voter 495s app_test.go:1202: 18:31:03.402 - 32: DEBUG: new connection from 127.0.0.1:35156 495s app_test.go:1202: 18:31:03.405 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.406 - 32: DEBUG: new connection from 127.0.0.1:35172 495s app_test.go:1202: 18:31:03.432 - 32: DEBUG: new connection from 127.0.0.1:35174 495s app_test.go:1202: 18:31:03.434 - 33: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.437 - 32: DEBUG: new connection from 127.0.0.1:35188 495s app_test.go:1202: 18:31:03.440 - 33: DEBUG: new connection from 127.0.0.1:42236 495s app_test.go:1202: 18:31:03.472 - 32: DEBUG: new connection from 127.0.0.1:35192 495s app_test.go:1202: 18:31:03.474 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.477 - 32: DEBUG: new connection from 127.0.0.1:35204 495s app_test.go:1202: 18:31:03.479 - 33: DEBUG: new connection from 127.0.0.1:42240 495s app_test.go:1202: 18:31:03.481 - 34: DEBUG: new connection from 127.0.0.1:33444 495s app_test.go:1202: 18:31:03.491 - 34: DEBUG: new connection from 127.0.0.1:33456 495s app_test.go:1202: 18:31:03.496 - 32: DEBUG: new connection from 127.0.0.1:35214 495s app_test.go:1202: 18:31:03.500 - 33: DEBUG: new connection from 127.0.0.1:42248 495s app_test.go:1202: 18:31:03.506 - 32: DEBUG: new connection from 127.0.0.1:35216 495s app_test.go:1202: 18:31:03.540 - 32: DEBUG: new connection from 127.0.0.1:35218 495s app_test.go:1202: 18:31:03.542 - 35: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.545 - 32: DEBUG: new connection from 127.0.0.1:35228 495s app_test.go:1202: 18:31:03.547 - 33: DEBUG: new connection from 127.0.0.1:42262 495s app_test.go:1202: 18:31:03.550 - 34: DEBUG: new connection from 127.0.0.1:33468 495s app_test.go:1202: 18:31:03.552 - 35: DEBUG: new connection from 127.0.0.1:46176 495s app_test.go:1202: 18:31:03.555 - 35: DEBUG: new connection from 127.0.0.1:46186 495s app_test.go:1202: 18:31:03.558 - 32: DEBUG: new connection from 127.0.0.1:35238 495s app_test.go:1202: 18:31:03.571 - 32: DEBUG: new connection from 127.0.0.1:35252 495s app_test.go:1202: 18:31:03.575 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.575 - 32: DEBUG: new connection from 127.0.0.1:35254 495s app_test.go:1202: 18:31:03.578 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.579 - 32: DEBUG: new connection from 127.0.0.1:35258 495s app_test.go:1202: 18:31:03.581 - 33: DEBUG: new connection from 127.0.0.1:42266 495s app_test.go:1202: 18:31:03.583 - 34: DEBUG: new connection from 127.0.0.1:33480 495s app_test.go:1202: 18:31:03.585 - 35: DEBUG: new connection from 127.0.0.1:46188 495s app_test.go:1202: 18:31:03.589 - 34: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 495s --- PASS: TestHandover_Voter (0.31s) 495s === RUN TestHandover_TwoNodes 495s app_test.go:1202: 18:31:03.710 - 36: DEBUG: new connection from 127.0.0.1:35270 495s app_test.go:1202: 18:31:03.714 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.716 - 36: DEBUG: new connection from 127.0.0.1:35282 495s app_test.go:1202: 18:31:03.744 - 36: DEBUG: new connection from 127.0.0.1:35292 495s app_test.go:1202: 18:31:03.746 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.750 - 36: DEBUG: new connection from 127.0.0.1:35308 495s app_test.go:1202: 18:31:03.752 - 37: DEBUG: new connection from 127.0.0.1:42278 495s app_test.go:1202: 18:31:03.754 - 36: DEBUG: new connection from 127.0.0.1:35316 495s app_test.go:1202: 18:31:03.757 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.757 - 36: DEBUG: new connection from 127.0.0.1:35328 495s app_test.go:1202: 18:31:03.759 - 37: DEBUG: new connection from 127.0.0.1:42286 495s app_test.go:1202: 18:31:03.762 - 37: DEBUG: new connection from 127.0.0.1:42292 495s app_test.go:1202: 18:31:03.773 - 36: DEBUG: new connection from 127.0.0.1:35340 495s app_test.go:1202: 18:31:03.777 - 36: DEBUG: promoted 127.0.0.1:9002 from spare to voter 495s app_test.go:1202: 18:31:03.778 - 36: DEBUG: new connection from 127.0.0.1:35348 495s app_test.go:1202: 18:31:03.780 - 37: DEBUG: new connection from 127.0.0.1:42300 495s app_test.go:1202: 18:31:03.785 - 36: DEBUG: new connection from 127.0.0.1:35356 495s app_test.go:1202: 18:31:03.788 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 495s app_test.go:1202: 18:31:03.788 - 37: DEBUG: new connection from 127.0.0.1:42302 495s app_test.go:1202: 18:31:03.790 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.791 - 36: DEBUG: new connection from 127.0.0.1:35370 495s app_test.go:1202: 18:31:03.793 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 495s app_test.go:1202: 18:31:03.794 - 37: DEBUG: new connection from 127.0.0.1:42306 495s app_test.go:1202: 18:31:03.796 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s --- PASS: TestHandover_TwoNodes (0.16s) 495s === RUN TestHandover_VoterHonorFailureDomain 495s app_test.go:1202: 18:31:03.862 - 38: DEBUG: new connection from 127.0.0.1:35372 495s app_test.go:1202: 18:31:03.864 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.866 - 38: DEBUG: new connection from 127.0.0.1:35378 495s app_test.go:1202: 18:31:03.888 - 38: DEBUG: new connection from 127.0.0.1:35382 495s app_test.go:1202: 18:31:03.892 - 39: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.895 - 38: DEBUG: new connection from 127.0.0.1:35396 495s app_test.go:1202: 18:31:03.897 - 39: DEBUG: new connection from 127.0.0.1:42314 495s app_test.go:1202: 18:31:03.920 - 38: DEBUG: new connection from 127.0.0.1:35404 495s app_test.go:1202: 18:31:03.922 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected 495s app_test.go:1202: 18:31:03.925 - 38: DEBUG: new connection from 127.0.0.1:35410 495s app_test.go:1202: 18:31:03.928 - 39: DEBUG: new connection from 127.0.0.1:42326 495s app_test.go:1202: 18:31:03.930 - 40: DEBUG: new connection from 127.0.0.1:33490 495s app_test.go:1202: 18:31:03.935 - 40: DEBUG: new connection from 127.0.0.1:33492 495s app_test.go:1202: 18:31:03.939 - 38: DEBUG: new connection from 127.0.0.1:35422 495s app_test.go:1202: 18:31:03.943 - 39: DEBUG: new connection from 127.0.0.1:42336 495s app_test.go:1202: 18:31:03.947 - 38: DEBUG: new connection from 127.0.0.1:35430 496s app_test.go:1202: 18:31:03.976 - 38: DEBUG: new connection from 127.0.0.1:35446 496s app_test.go:1202: 18:31:03.978 - 41: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.981 - 38: DEBUG: new connection from 127.0.0.1:35450 496s app_test.go:1202: 18:31:03.984 - 39: DEBUG: new connection from 127.0.0.1:42350 496s app_test.go:1202: 18:31:03.986 - 40: DEBUG: new connection from 127.0.0.1:33494 496s app_test.go:1202: 18:31:03.988 - 41: DEBUG: new connection from 127.0.0.1:46194 496s app_test.go:1202: 18:31:03.006 - 41: DEBUG: new connection from 127.0.0.1:46200 496s app_test.go:1202: 18:31:03.013 - 38: DEBUG: new connection from 127.0.0.1:35460 496s app_test.go:1202: 18:31:03.040 - 38: DEBUG: new connection from 127.0.0.1:35464 496s app_test.go:1202: 18:31:03.042 - 42: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.045 - 38: DEBUG: new connection from 127.0.0.1:35476 496s app_test.go:1202: 18:31:03.048 - 39: DEBUG: new connection from 127.0.0.1:42352 496s app_test.go:1202: 18:31:03.050 - 40: DEBUG: new connection from 127.0.0.1:33508 496s app_test.go:1202: 18:31:03.052 - 41: DEBUG: new connection from 127.0.0.1:46212 496s app_test.go:1202: 18:31:03.055 - 42: DEBUG: new connection from 127.0.0.1:47236 496s app_test.go:1202: 18:31:03.057 - 42: DEBUG: new connection from 127.0.0.1:47244 496s app_test.go:1202: 18:31:03.075 - 38: DEBUG: new connection from 127.0.0.1:35488 496s app_test.go:1202: 18:31:03.084 - 38: DEBUG: new connection from 127.0.0.1:35498 496s app_test.go:1202: 18:31:03.086 - 43: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.089 - 38: DEBUG: new connection from 127.0.0.1:35506 496s app_test.go:1202: 18:31:03.091 - 39: DEBUG: new connection from 127.0.0.1:42368 496s app_test.go:1202: 18:31:03.094 - 40: DEBUG: new connection from 127.0.0.1:33514 496s app_test.go:1202: 18:31:03.096 - 41: DEBUG: new connection from 127.0.0.1:46228 496s app_test.go:1202: 18:31:03.099 - 42: DEBUG: new connection from 127.0.0.1:47254 496s app_test.go:1202: 18:31:03.102 - 43: DEBUG: new connection from 127.0.0.1:44180 496s app_test.go:1202: 18:31:03.104 - 43: DEBUG: new connection from 127.0.0.1:44194 496s app_test.go:1202: 18:31:03.108 - 38: DEBUG: new connection from 127.0.0.1:35508 496s app_test.go:1202: 18:31:03.110 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.111 - 38: DEBUG: new connection from 127.0.0.1:35512 496s app_test.go:1202: 18:31:03.113 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.114 - 38: DEBUG: new connection from 127.0.0.1:35522 496s app_test.go:1202: 18:31:03.116 - 38: DEBUG: new connection from 127.0.0.1:35526 496s app_test.go:1202: 18:31:03.119 - 39: DEBUG: new connection from 127.0.0.1:42382 496s app_test.go:1202: 18:31:03.121 - 40: DEBUG: new connection from 127.0.0.1:33516 496s app_test.go:1202: 18:31:03.124 - 41: DEBUG: new connection from 127.0.0.1:46240 496s app_test.go:1202: 18:31:03.126 - 42: DEBUG: new connection from 127.0.0.1:47262 496s app_test.go:1202: 18:31:03.128 - 43: DEBUG: new connection from 127.0.0.1:44196 496s app_test.go:1202: 18:31:03.131 - 40: DEBUG: promoted 127.0.0.1:9006 from stand-by to voter 496s --- PASS: TestHandover_VoterHonorFailureDomain (0.42s) 496s === RUN TestHandover_SingleNode 496s --- PASS: TestHandover_SingleNode (0.03s) 496s === RUN TestHandover_GracefulShutdown 496s --- PASS: TestHandover_GracefulShutdown (0.19s) 496s === RUN TestHandover_StandBy 496s app_test.go:1202: 18:31:03.502 - 44: DEBUG: new connection from 127.0.0.1:35792 496s app_test.go:1202: 18:31:03.504 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.506 - 44: DEBUG: new connection from 127.0.0.1:35804 496s app_test.go:1202: 18:31:03.528 - 44: DEBUG: new connection from 127.0.0.1:35808 496s app_test.go:1202: 18:31:03.530 - 45: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.533 - 44: DEBUG: new connection from 127.0.0.1:35820 496s app_test.go:1202: 18:31:03.536 - 45: DEBUG: new connection from 127.0.0.1:42488 496s app_test.go:1202: 18:31:03.564 - 44: DEBUG: new connection from 127.0.0.1:35824 496s app_test.go:1202: 18:31:03.566 - 46: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.568 - 44: DEBUG: new connection from 127.0.0.1:35840 496s app_test.go:1202: 18:31:03.571 - 45: DEBUG: new connection from 127.0.0.1:42502 496s app_test.go:1202: 18:31:03.573 - 46: DEBUG: new connection from 127.0.0.1:33614 496s app_test.go:1202: 18:31:03.577 - 46: DEBUG: new connection from 127.0.0.1:33616 496s app_test.go:1202: 18:31:03.581 - 44: DEBUG: new connection from 127.0.0.1:35854 496s app_test.go:1202: 18:31:03.585 - 45: DEBUG: new connection from 127.0.0.1:42506 496s app_test.go:1202: 18:31:03.590 - 44: DEBUG: new connection from 127.0.0.1:35858 496s app_test.go:1202: 18:31:03.624 - 44: DEBUG: new connection from 127.0.0.1:35866 496s app_test.go:1202: 18:31:03.627 - 47: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.630 - 44: DEBUG: new connection from 127.0.0.1:35878 496s app_test.go:1202: 18:31:03.634 - 45: DEBUG: new connection from 127.0.0.1:42522 496s app_test.go:1202: 18:31:03.637 - 46: DEBUG: new connection from 127.0.0.1:33622 496s app_test.go:1202: 18:31:03.639 - 47: DEBUG: new connection from 127.0.0.1:46248 496s app_test.go:1202: 18:31:03.642 - 47: DEBUG: new connection from 127.0.0.1:46264 496s app_test.go:1202: 18:31:03.663 - 44: DEBUG: new connection from 127.0.0.1:35884 496s app_test.go:1202: 18:31:03.676 - 44: DEBUG: new connection from 127.0.0.1:35890 496s app_test.go:1202: 18:31:03.678 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.682 - 44: DEBUG: new connection from 127.0.0.1:35902 496s app_test.go:1202: 18:31:03.684 - 45: DEBUG: new connection from 127.0.0.1:42532 496s app_test.go:1202: 18:31:03.687 - 46: DEBUG: new connection from 127.0.0.1:33630 496s app_test.go:1202: 18:31:03.689 - 47: DEBUG: new connection from 127.0.0.1:46278 496s app_test.go:1202: 18:31:03.691 - 48: DEBUG: new connection from 127.0.0.1:47272 496s app_test.go:1202: 18:31:03.709 - 48: DEBUG: new connection from 127.0.0.1:47276 496s app_test.go:1202: 18:31:03.716 - 44: DEBUG: new connection from 127.0.0.1:35904 496s app_test.go:1202: 18:31:03.741 - 44: DEBUG: new connection from 127.0.0.1:35916 496s app_test.go:1202: 18:31:03.744 - 49: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.747 - 44: DEBUG: new connection from 127.0.0.1:35932 496s app_test.go:1202: 18:31:03.750 - 45: DEBUG: new connection from 127.0.0.1:42542 496s app_test.go:1202: 18:31:03.753 - 46: DEBUG: new connection from 127.0.0.1:33646 496s app_test.go:1202: 18:31:03.755 - 47: DEBUG: new connection from 127.0.0.1:46286 496s app_test.go:1202: 18:31:03.757 - 48: DEBUG: new connection from 127.0.0.1:47286 496s app_test.go:1202: 18:31:03.760 - 49: DEBUG: new connection from 127.0.0.1:44212 496s app_test.go:1202: 18:31:03.770 - 49: DEBUG: new connection from 127.0.0.1:44228 496s app_test.go:1202: 18:31:03.777 - 44: DEBUG: new connection from 127.0.0.1:35940 496s app_test.go:1202: 18:31:03.804 - 44: DEBUG: new connection from 127.0.0.1:35950 496s app_test.go:1202: 18:31:03.806 - 50: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.810 - 44: DEBUG: new connection from 127.0.0.1:35966 496s app_test.go:1202: 18:31:03.812 - 45: DEBUG: new connection from 127.0.0.1:42550 496s app_test.go:1202: 18:31:03.816 - 46: DEBUG: new connection from 127.0.0.1:33650 496s app_test.go:1202: 18:31:03.819 - 47: DEBUG: new connection from 127.0.0.1:46302 496s app_test.go:1202: 18:31:03.821 - 48: DEBUG: new connection from 127.0.0.1:47300 496s app_test.go:1202: 18:31:03.824 - 49: DEBUG: new connection from 127.0.0.1:44230 496s app_test.go:1202: 18:31:03.826 - 50: DEBUG: new connection from 127.0.0.1:38608 496s app_test.go:1202: 18:31:03.828 - 44: DEBUG: new connection from 127.0.0.1:35970 496s app_test.go:1202: 18:31:03.830 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.831 - 44: DEBUG: new connection from 127.0.0.1:35982 496s app_test.go:1202: 18:31:03.833 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected 496s app_test.go:1202: 18:31:03.833 - 44: DEBUG: new connection from 127.0.0.1:35988 496s app_test.go:1202: 18:31:03.835 - 45: DEBUG: new connection from 127.0.0.1:42562 496s app_test.go:1202: 18:31:03.837 - 46: DEBUG: new connection from 127.0.0.1:33654 496s app_test.go:1202: 18:31:03.840 - 47: DEBUG: new connection from 127.0.0.1:46318 496s app_test.go:1202: 18:31:03.842 - 48: DEBUG: new connection from 127.0.0.1:47308 496s app_test.go:1202: 18:31:03.844 - 49: DEBUG: new connection from 127.0.0.1:44240 496s app_test.go:1202: 18:31:03.846 - 50: DEBUG: new connection from 127.0.0.1:38622 496s app_test.go:1202: 18:31:03.849 - 50: DEBUG: new connection from 127.0.0.1:38634 496s app_test.go:1202: 18:31:03.852 - 48: DEBUG: promoted 127.0.0.1:9007 from spare to stand-by 497s --- PASS: TestHandover_StandBy (0.52s) 497s === RUN TestHandover_TransferLeadership 497s app_test.go:1202: 18:31:03.030 - 51: DEBUG: new connection from 127.0.0.1:35994 497s app_test.go:1202: 18:31:03.033 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.036 - 51: DEBUG: new connection from 127.0.0.1:36010 497s app_test.go:1202: 18:31:03.068 - 51: DEBUG: new connection from 127.0.0.1:36026 497s app_test.go:1202: 18:31:03.071 - 52: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.074 - 51: DEBUG: new connection from 127.0.0.1:36034 497s app_test.go:1202: 18:31:03.076 - 52: DEBUG: new connection from 127.0.0.1:42566 497s app_test.go:1202: 18:31:03.104 - 51: DEBUG: new connection from 127.0.0.1:36048 497s app_test.go:1202: 18:31:03.106 - 53: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.109 - 51: DEBUG: new connection from 127.0.0.1:36064 497s app_test.go:1202: 18:31:03.111 - 52: DEBUG: new connection from 127.0.0.1:42582 497s app_test.go:1202: 18:31:03.113 - 53: DEBUG: new connection from 127.0.0.1:33666 497s app_test.go:1202: 18:31:03.116 - 53: DEBUG: new connection from 127.0.0.1:33672 497s app_test.go:1202: 18:31:03.120 - 51: DEBUG: new connection from 127.0.0.1:36074 497s app_test.go:1202: 18:31:03.125 - 52: DEBUG: new connection from 127.0.0.1:42596 497s app_test.go:1202: 18:31:03.129 - 51: DEBUG: new connection from 127.0.0.1:36086 497s app_test.go:1202: 18:31:03.160 - 51: DEBUG: new connection from 127.0.0.1:36090 497s app_test.go:1202: 18:31:03.162 - 54: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.165 - 51: DEBUG: new connection from 127.0.0.1:36096 497s app_test.go:1202: 18:31:03.167 - 52: DEBUG: new connection from 127.0.0.1:42602 497s app_test.go:1202: 18:31:03.170 - 53: DEBUG: new connection from 127.0.0.1:33674 497s app_test.go:1202: 18:31:03.172 - 54: DEBUG: new connection from 127.0.0.1:46334 497s app_test.go:1202: 18:31:03.183 - 54: DEBUG: new connection from 127.0.0.1:46342 497s app_test.go:1202: 18:31:03.187 - 51: DEBUG: new connection from 127.0.0.1:36104 497s app_test.go:1202: 18:31:03.189 - 51: DEBUG: new connection from 127.0.0.1:36120 497s app_test.go:1202: 18:31:03.193 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.194 - 51: DEBUG: new connection from 127.0.0.1:36124 497s app_test.go:1202: 18:31:03.196 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.196 - 51: DEBUG: new connection from 127.0.0.1:36130 497s app_test.go:1202: 18:31:03.199 - 52: DEBUG: new connection from 127.0.0.1:42608 497s app_test.go:1202: 18:31:03.201 - 53: DEBUG: new connection from 127.0.0.1:33690 497s app_test.go:1202: 18:31:03.204 - 54: DEBUG: new connection from 127.0.0.1:46348 497s app_test.go:1202: 18:31:03.207 - 51: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 497s app_test.go:1202: 18:31:03.208 - 51: DEBUG: new connection from 127.0.0.1:36134 497s app_test.go:1202: 18:31:03.210 - 52: DEBUG: new connection from 127.0.0.1:42612 497s app_test.go:1202: 18:31:03.212 - 53: DEBUG: new connection from 127.0.0.1:33692 497s app_test.go:1202: 18:31:03.214 - 54: DEBUG: new connection from 127.0.0.1:46350 497s app_test.go:1202: 18:31:03.232 - 53: DEBUG: new connection from 127.0.0.1:33704 497s app_test.go:1202: 18:31:03.234 - 54: DEBUG: new connection from 127.0.0.1:46356 497s app_test.go:1202: 18:31:03.236 - 52: DEBUG: new connection from 127.0.0.1:42618 497s app_test.go:1202: 18:31:03.240 - 52: DEBUG: new connection from 127.0.0.1:42630 497s app_test.go:1202: 18:31:03.245 - 51: DEBUG: new connection from 127.0.0.1:36142 497s app_test.go:1202: 18:31:03.247 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 497s app_test.go:1202: 18:31:03.248 - 52: DEBUG: new connection from 127.0.0.1:42632 497s app_test.go:1202: 18:31:03.250 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.251 - 51: DEBUG: new connection from 127.0.0.1:36148 497s app_test.go:1202: 18:31:03.253 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 497s app_test.go:1202: 18:31:03.254 - 52: DEBUG: new connection from 127.0.0.1:42642 497s app_test.go:1202: 18:31:03.256 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s --- PASS: TestHandover_TransferLeadership (0.34s) 497s === RUN TestRolesAdjustment_ReplaceVoter 497s app_test.go:1202: 18:31:03.371 - 55: DEBUG: new connection from 127.0.0.1:36160 497s app_test.go:1202: 18:31:03.374 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.377 - 55: DEBUG: new connection from 127.0.0.1:36170 497s app_test.go:1202: 18:31:03.404 - 55: DEBUG: new connection from 127.0.0.1:36180 497s app_test.go:1202: 18:31:03.406 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.409 - 55: DEBUG: new connection from 127.0.0.1:36184 497s app_test.go:1202: 18:31:03.411 - 56: DEBUG: new connection from 127.0.0.1:42650 497s app_test.go:1202: 18:31:03.436 - 55: DEBUG: new connection from 127.0.0.1:36200 497s app_test.go:1202: 18:31:03.438 - 57: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.440 - 55: DEBUG: new connection from 127.0.0.1:36212 497s app_test.go:1202: 18:31:03.442 - 56: DEBUG: new connection from 127.0.0.1:42656 497s app_test.go:1202: 18:31:03.445 - 57: DEBUG: new connection from 127.0.0.1:33710 497s app_test.go:1202: 18:31:03.454 - 57: DEBUG: new connection from 127.0.0.1:33718 497s app_test.go:1202: 18:31:03.458 - 55: DEBUG: new connection from 127.0.0.1:36216 497s app_test.go:1202: 18:31:03.463 - 56: DEBUG: new connection from 127.0.0.1:42672 497s app_test.go:1202: 18:31:03.467 - 55: DEBUG: new connection from 127.0.0.1:36222 497s app_test.go:1202: 18:31:03.496 - 55: DEBUG: new connection from 127.0.0.1:36226 497s app_test.go:1202: 18:31:03.498 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 497s app_test.go:1202: 18:31:03.500 - 55: DEBUG: new connection from 127.0.0.1:36228 497s app_test.go:1202: 18:31:03.503 - 56: DEBUG: new connection from 127.0.0.1:42686 497s app_test.go:1202: 18:31:03.505 - 57: DEBUG: new connection from 127.0.0.1:33730 497s app_test.go:1202: 18:31:03.508 - 58: DEBUG: new connection from 127.0.0.1:46358 497s app_test.go:1202: 18:31:03.510 - 58: DEBUG: new connection from 127.0.0.1:46370 497s app_test.go:1202: 18:31:03.530 - 55: DEBUG: new connection from 127.0.0.1:36244 499s app_test.go:1202: 18:31:03.380 - 55: DEBUG: new connection from 127.0.0.1:36252 499s app_test.go:1202: 18:31:03.382 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 499s app_test.go:1202: 18:31:03.384 - 55: DEBUG: new connection from 127.0.0.1:36266 499s app_test.go:1202: 18:31:03.386 - 56: DEBUG: new connection from 127.0.0.1:42698 499s app_test.go:1202: 18:31:03.389 - 58: DEBUG: new connection from 127.0.0.1:46374 499s app_test.go:1202: 18:31:03.414 - 55: DEBUG: new connection from 127.0.0.1:36282 499s app_test.go:1202: 18:31:03.417 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 499s app_test.go:1202: 18:31:03.514 - 55: DEBUG: new connection from 127.0.0.1:36296 499s app_test.go:1202: 18:31:03.516 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 499s app_test.go:1202: 18:31:03.849 - 55: DEBUG: new connection from 127.0.0.1:36302 499s app_test.go:1202: 18:31:03.851 - 56: DEBUG: new connection from 127.0.0.1:42704 499s app_test.go:1202: 18:31:03.853 - 58: DEBUG: new connection from 127.0.0.1:46380 499s app_test.go:1202: 18:31:03.857 - 55: DEBUG: new connection from 127.0.0.1:36314 499s app_test.go:1202: 18:31:03.859 - 56: DEBUG: new connection from 127.0.0.1:42714 499s app_test.go:1202: 18:31:03.861 - 58: DEBUG: new connection from 127.0.0.1:46396 501s app_test.go:1202: 18:31:03.420 - 55: DEBUG: new connection from 127.0.0.1:36324 501s app_test.go:1202: 18:31:03.422 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 501s app_test.go:1202: 18:31:03.519 - 55: DEBUG: new connection from 127.0.0.1:36328 501s app_test.go:1202: 18:31:03.521 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 501s app_test.go:1202: 18:31:03.863 - 55: DEBUG: new connection from 127.0.0.1:36332 501s app_test.go:1202: 18:31:03.866 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 501s app_test.go:1202: 18:31:03.868 - 55: DEBUG: new connection from 127.0.0.1:36338 501s app_test.go:1202: 18:31:03.870 - 56: DEBUG: new connection from 127.0.0.1:42720 501s app_test.go:1202: 18:31:03.873 - 58: DEBUG: new connection from 127.0.0.1:46404 503s app_test.go:1202: 18:31:03.425 - 55: DEBUG: new connection from 127.0.0.1:36352 503s app_test.go:1202: 18:31:03.427 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 503s app_test.go:1202: 18:31:03.524 - 55: DEBUG: new connection from 127.0.0.1:36354 503s app_test.go:1202: 18:31:03.529 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 503s app_test.go:1202: 18:31:03.876 - 55: DEBUG: new connection from 127.0.0.1:36358 503s app_test.go:1202: 18:31:03.878 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 503s app_test.go:1202: 18:31:03.883 - 55: DEBUG: new connection from 127.0.0.1:36360 503s app_test.go:1202: 18:31:03.885 - 56: DEBUG: new connection from 127.0.0.1:42732 503s app_test.go:1202: 18:31:03.888 - 58: DEBUG: new connection from 127.0.0.1:46416 505s app_test.go:1202: 18:31:03.430 - 55: DEBUG: new connection from 127.0.0.1:55084 505s app_test.go:1202: 18:31:03.432 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected 505s app_test.go:1202: 18:31:03.531 - 55: DEBUG: new connection from 127.0.0.1:55098 505s app_test.go:1202: 18:31:03.532 - 55: DEBUG: new connection from 127.0.0.1:55110 505s app_test.go:1202: 18:31:03.535 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected 505s app_test.go:1202: 18:31:03.536 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected 505s --- PASS: TestRolesAdjustment_ReplaceVoter (8.26s) 505s === RUN TestRolesAdjustment_ReplaceVoterHonorFailureDomain 505s app_test.go:1202: 18:31:03.634 - 59: DEBUG: new connection from 127.0.0.1:55120 505s app_test.go:1202: 18:31:03.636 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 505s app_test.go:1202: 18:31:03.637 - 59: DEBUG: new connection from 127.0.0.1:55132 505s app_test.go:1202: 18:31:03.668 - 59: DEBUG: new connection from 127.0.0.1:55136 505s app_test.go:1202: 18:31:03.670 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 505s app_test.go:1202: 18:31:03.673 - 59: DEBUG: new connection from 127.0.0.1:55142 505s app_test.go:1202: 18:31:03.676 - 60: DEBUG: new connection from 127.0.0.1:54780 505s app_test.go:1202: 18:31:03.704 - 59: DEBUG: new connection from 127.0.0.1:55156 505s app_test.go:1202: 18:31:03.706 - 61: DEBUG: attempt 1: server 127.0.0.1:9001: connected 505s app_test.go:1202: 18:31:03.708 - 59: DEBUG: new connection from 127.0.0.1:55160 505s app_test.go:1202: 18:31:03.711 - 60: DEBUG: new connection from 127.0.0.1:54794 505s app_test.go:1202: 18:31:03.715 - 61: DEBUG: new connection from 127.0.0.1:36110 505s app_test.go:1202: 18:31:03.718 - 61: DEBUG: new connection from 127.0.0.1:36122 505s app_test.go:1202: 18:31:03.722 - 59: DEBUG: new connection from 127.0.0.1:55166 505s app_test.go:1202: 18:31:03.726 - 60: DEBUG: new connection from 127.0.0.1:54808 505s app_test.go:1202: 18:31:03.730 - 59: DEBUG: new connection from 127.0.0.1:55168 505s app_test.go:1202: 18:31:03.764 - 59: DEBUG: new connection from 127.0.0.1:55184 505s app_test.go:1202: 18:31:03.766 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 505s app_test.go:1202: 18:31:03.769 - 59: DEBUG: new connection from 127.0.0.1:55196 505s app_test.go:1202: 18:31:03.771 - 60: DEBUG: new connection from 127.0.0.1:54818 505s app_test.go:1202: 18:31:03.773 - 61: DEBUG: new connection from 127.0.0.1:36128 505s app_test.go:1202: 18:31:03.776 - 62: DEBUG: new connection from 127.0.0.1:42804 505s app_test.go:1202: 18:31:03.784 - 62: DEBUG: new connection from 127.0.0.1:42818 505s app_test.go:1202: 18:31:03.791 - 59: DEBUG: new connection from 127.0.0.1:55198 505s app_test.go:1202: 18:31:03.809 - 59: DEBUG: new connection from 127.0.0.1:55212 505s app_test.go:1202: 18:31:03.811 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 505s app_test.go:1202: 18:31:03.814 - 59: DEBUG: new connection from 127.0.0.1:55228 505s app_test.go:1202: 18:31:03.816 - 60: DEBUG: new connection from 127.0.0.1:54822 505s app_test.go:1202: 18:31:03.819 - 61: DEBUG: new connection from 127.0.0.1:36144 505s app_test.go:1202: 18:31:03.822 - 62: DEBUG: new connection from 127.0.0.1:42824 505s app_test.go:1202: 18:31:03.826 - 63: DEBUG: new connection from 127.0.0.1:50302 505s app_test.go:1202: 18:31:03.829 - 63: DEBUG: new connection from 127.0.0.1:50316 505s app_test.go:1202: 18:31:03.846 - 59: DEBUG: new connection from 127.0.0.1:55242 505s app_test.go:1202: 18:31:03.856 - 59: DEBUG: new connection from 127.0.0.1:55244 505s app_test.go:1202: 18:31:03.858 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 505s app_test.go:1202: 18:31:03.861 - 59: DEBUG: new connection from 127.0.0.1:55252 505s app_test.go:1202: 18:31:03.863 - 60: DEBUG: new connection from 127.0.0.1:54836 505s app_test.go:1202: 18:31:03.865 - 61: DEBUG: new connection from 127.0.0.1:36154 505s app_test.go:1202: 18:31:03.868 - 62: DEBUG: new connection from 127.0.0.1:42828 505s app_test.go:1202: 18:31:03.870 - 63: DEBUG: new connection from 127.0.0.1:50318 505s app_test.go:1202: 18:31:03.872 - 64: DEBUG: new connection from 127.0.0.1:51850 505s app_test.go:1202: 18:31:03.886 - 64: DEBUG: new connection from 127.0.0.1:51862 505s app_test.go:1202: 18:31:03.895 - 59: DEBUG: new connection from 127.0.0.1:55260 509s app_test.go:1202: 18:31:03.641 - 59: DEBUG: new connection from 127.0.0.1:55272 509s app_test.go:1202: 18:31:03.643 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 509s app_test.go:1202: 18:31:03.646 - 59: DEBUG: new connection from 127.0.0.1:55274 509s app_test.go:1202: 18:31:03.649 - 60: DEBUG: new connection from 127.0.0.1:54850 509s app_test.go:1202: 18:31:03.651 - 62: DEBUG: new connection from 127.0.0.1:42832 509s app_test.go:1202: 18:31:03.654 - 63: DEBUG: new connection from 127.0.0.1:50328 509s app_test.go:1202: 18:31:03.656 - 64: DEBUG: new connection from 127.0.0.1:51864 509s app_test.go:1202: 18:31:03.678 - 59: DEBUG: new connection from 127.0.0.1:55282 509s app_test.go:1202: 18:31:03.680 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 509s app_test.go:1202: 18:31:03.785 - 59: DEBUG: new connection from 127.0.0.1:55290 509s app_test.go:1202: 18:31:03.788 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 509s app_test.go:1202: 18:31:03.832 - 59: DEBUG: new connection from 127.0.0.1:55302 509s app_test.go:1202: 18:31:03.835 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 509s app_test.go:1202: 18:31:03.892 - 59: DEBUG: new connection from 127.0.0.1:55316 509s app_test.go:1202: 18:31:03.894 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 510s app_test.go:1202: 18:31:03.111 - 59: DEBUG: new connection from 127.0.0.1:55330 510s app_test.go:1202: 18:31:03.115 - 60: DEBUG: new connection from 127.0.0.1:54864 510s app_test.go:1202: 18:31:03.118 - 62: DEBUG: new connection from 127.0.0.1:42844 510s app_test.go:1202: 18:31:03.120 - 63: DEBUG: new connection from 127.0.0.1:50334 510s app_test.go:1202: 18:31:03.122 - 64: DEBUG: new connection from 127.0.0.1:51874 510s app_test.go:1202: 18:31:03.126 - 59: DEBUG: new connection from 127.0.0.1:55334 510s app_test.go:1202: 18:31:03.128 - 60: DEBUG: new connection from 127.0.0.1:54866 510s app_test.go:1202: 18:31:03.131 - 62: DEBUG: new connection from 127.0.0.1:42850 510s app_test.go:1202: 18:31:03.133 - 63: DEBUG: new connection from 127.0.0.1:50350 510s app_test.go:1202: 18:31:03.135 - 64: DEBUG: new connection from 127.0.0.1:51878 513s app_test.go:1202: 18:31:03.684 - 59: DEBUG: new connection from 127.0.0.1:55344 513s app_test.go:1202: 18:31:03.686 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 513s app_test.go:1202: 18:31:03.790 - 59: DEBUG: new connection from 127.0.0.1:55358 513s app_test.go:1202: 18:31:03.793 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 513s app_test.go:1202: 18:31:03.838 - 59: DEBUG: new connection from 127.0.0.1:55370 513s app_test.go:1202: 18:31:03.840 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 513s app_test.go:1202: 18:31:03.897 - 59: DEBUG: new connection from 127.0.0.1:55378 513s app_test.go:1202: 18:31:03.899 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 514s app_test.go:1202: 18:31:03.137 - 59: DEBUG: new connection from 127.0.0.1:55394 514s app_test.go:1202: 18:31:03.140 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 514s app_test.go:1202: 18:31:03.142 - 59: DEBUG: new connection from 127.0.0.1:55396 514s app_test.go:1202: 18:31:03.146 - 60: DEBUG: new connection from 127.0.0.1:54876 514s app_test.go:1202: 18:31:03.149 - 62: DEBUG: new connection from 127.0.0.1:42856 514s app_test.go:1202: 18:31:03.151 - 63: DEBUG: new connection from 127.0.0.1:50360 514s app_test.go:1202: 18:31:03.154 - 64: DEBUG: new connection from 127.0.0.1:51894 517s app_test.go:1202: 18:31:03.689 - 59: DEBUG: new connection from 127.0.0.1:35688 517s app_test.go:1202: 18:31:03.691 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 517s app_test.go:1202: 18:31:03.796 - 59: DEBUG: new connection from 127.0.0.1:35704 517s app_test.go:1202: 18:31:03.798 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 517s app_test.go:1202: 18:31:03.843 - 59: DEBUG: new connection from 127.0.0.1:35720 517s app_test.go:1202: 18:31:03.846 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 517s app_test.go:1202: 18:31:03.902 - 59: DEBUG: new connection from 127.0.0.1:35728 517s app_test.go:1202: 18:31:03.904 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 518s app_test.go:1202: 18:31:03.157 - 59: DEBUG: new connection from 127.0.0.1:35730 518s app_test.go:1202: 18:31:03.159 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 518s app_test.go:1202: 18:31:03.162 - 59: DEBUG: new connection from 127.0.0.1:35738 518s app_test.go:1202: 18:31:03.164 - 60: DEBUG: new connection from 127.0.0.1:35428 518s app_test.go:1202: 18:31:03.166 - 62: DEBUG: new connection from 127.0.0.1:52584 518s app_test.go:1202: 18:31:03.169 - 63: DEBUG: new connection from 127.0.0.1:52010 518s app_test.go:1202: 18:31:03.173 - 64: DEBUG: new connection from 127.0.0.1:51332 521s app_test.go:1202: 18:31:03.694 - 59: DEBUG: new connection from 127.0.0.1:35744 521s app_test.go:1202: 18:31:03.696 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected 521s app_test.go:1202: 18:31:03.800 - 59: DEBUG: new connection from 127.0.0.1:35754 521s app_test.go:1202: 18:31:03.803 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected 521s app_test.go:1202: 18:31:03.849 - 59: DEBUG: new connection from 127.0.0.1:35756 521s app_test.go:1202: 18:31:03.852 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected 521s app_test.go:1202: 18:31:03.907 - 59: DEBUG: new connection from 127.0.0.1:35772 521s app_test.go:1202: 18:31:03.909 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected 522s app_test.go:1202: 18:31:03.177 - 59: DEBUG: new connection from 127.0.0.1:35780 522s app_test.go:1202: 18:31:03.179 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 522s app_test.go:1202: 18:31:03.181 - 59: DEBUG: new connection from 127.0.0.1:35790 522s app_test.go:1202: 18:31:03.184 - 60: DEBUG: new connection from 127.0.0.1:35442 522s app_test.go:1202: 18:31:03.187 - 62: DEBUG: new connection from 127.0.0.1:52600 522s app_test.go:1202: 18:31:03.189 - 63: DEBUG: new connection from 127.0.0.1:52022 522s app_test.go:1202: 18:31:03.192 - 64: DEBUG: new connection from 127.0.0.1:51334 523s app_test.go:1202: 18:31:03.911 - 59: DEBUG: new connection from 127.0.0.1:35800 523s app_test.go:1202: 18:31:03.914 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected 524s --- PASS: TestRolesAdjustment_ReplaceVoterHonorFailureDomain (18.42s) 524s === RUN TestRolesAdjustment_ImbalancedFailureDomain 524s app_test.go:1202: 18:32:03.046 - 65: DEBUG: new connection from 127.0.0.1:35810 524s app_test.go:1202: 18:32:03.050 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 524s app_test.go:1202: 18:32:03.052 - 65: DEBUG: new connection from 127.0.0.1:35818 524s app_test.go:1202: 18:32:03.080 - 65: DEBUG: new connection from 127.0.0.1:35834 524s app_test.go:1202: 18:32:03.083 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 524s app_test.go:1202: 18:32:03.086 - 65: DEBUG: new connection from 127.0.0.1:35840 524s app_test.go:1202: 18:32:03.088 - 66: DEBUG: new connection from 127.0.0.1:35456 524s app_test.go:1202: 18:32:03.112 - 65: DEBUG: new connection from 127.0.0.1:35852 524s app_test.go:1202: 18:32:03.115 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 524s app_test.go:1202: 18:32:03.117 - 65: DEBUG: new connection from 127.0.0.1:35866 524s app_test.go:1202: 18:32:03.120 - 66: DEBUG: new connection from 127.0.0.1:35466 524s app_test.go:1202: 18:32:03.122 - 67: DEBUG: new connection from 127.0.0.1:58268 524s app_test.go:1202: 18:32:03.125 - 67: DEBUG: new connection from 127.0.0.1:58282 524s app_test.go:1202: 18:32:03.129 - 65: DEBUG: new connection from 127.0.0.1:35882 524s app_test.go:1202: 18:32:03.134 - 66: DEBUG: new connection from 127.0.0.1:35472 524s app_test.go:1202: 18:32:03.139 - 65: DEBUG: new connection from 127.0.0.1:35892 524s app_test.go:1202: 18:32:03.172 - 65: DEBUG: new connection from 127.0.0.1:35906 524s app_test.go:1202: 18:32:03.175 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 524s app_test.go:1202: 18:32:03.178 - 65: DEBUG: new connection from 127.0.0.1:35922 524s app_test.go:1202: 18:32:03.180 - 66: DEBUG: new connection from 127.0.0.1:35484 524s app_test.go:1202: 18:32:03.183 - 67: DEBUG: new connection from 127.0.0.1:58294 524s app_test.go:1202: 18:32:03.186 - 68: DEBUG: new connection from 127.0.0.1:52610 524s app_test.go:1202: 18:32:03.194 - 68: DEBUG: new connection from 127.0.0.1:52624 524s app_test.go:1202: 18:32:03.201 - 65: DEBUG: new connection from 127.0.0.1:35938 524s app_test.go:1202: 18:32:03.230 - 65: DEBUG: new connection from 127.0.0.1:35946 524s app_test.go:1202: 18:32:03.232 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 524s app_test.go:1202: 18:32:03.236 - 65: DEBUG: new connection from 127.0.0.1:35960 524s app_test.go:1202: 18:32:03.240 - 66: DEBUG: new connection from 127.0.0.1:35488 524s app_test.go:1202: 18:32:03.243 - 67: DEBUG: new connection from 127.0.0.1:58304 524s app_test.go:1202: 18:32:03.245 - 68: DEBUG: new connection from 127.0.0.1:52636 524s app_test.go:1202: 18:32:03.247 - 69: DEBUG: new connection from 127.0.0.1:52028 524s app_test.go:1202: 18:32:03.256 - 69: DEBUG: new connection from 127.0.0.1:52034 524s app_test.go:1202: 18:32:03.263 - 65: DEBUG: new connection from 127.0.0.1:35966 524s app_test.go:1202: 18:32:03.295 - 65: DEBUG: new connection from 127.0.0.1:35978 524s app_test.go:1202: 18:32:03.297 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 524s app_test.go:1202: 18:32:03.300 - 65: DEBUG: new connection from 127.0.0.1:35990 524s app_test.go:1202: 18:32:03.302 - 66: DEBUG: new connection from 127.0.0.1:35504 524s app_test.go:1202: 18:32:03.306 - 67: DEBUG: new connection from 127.0.0.1:58318 524s app_test.go:1202: 18:32:03.309 - 68: DEBUG: new connection from 127.0.0.1:52646 524s app_test.go:1202: 18:32:03.312 - 69: DEBUG: new connection from 127.0.0.1:52050 524s app_test.go:1202: 18:32:03.314 - 70: DEBUG: new connection from 127.0.0.1:51346 524s app_test.go:1202: 18:32:03.317 - 70: DEBUG: new connection from 127.0.0.1:51358 524s app_test.go:1202: 18:32:03.334 - 65: DEBUG: new connection from 127.0.0.1:35996 524s app_test.go:1202: 18:32:03.360 - 65: DEBUG: new connection from 127.0.0.1:36002 524s app_test.go:1202: 18:32:03.362 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 524s app_test.go:1202: 18:32:03.366 - 65: DEBUG: new connection from 127.0.0.1:36016 524s app_test.go:1202: 18:32:03.368 - 66: DEBUG: new connection from 127.0.0.1:35514 524s app_test.go:1202: 18:32:03.371 - 67: DEBUG: new connection from 127.0.0.1:58320 524s app_test.go:1202: 18:32:03.373 - 68: DEBUG: new connection from 127.0.0.1:52662 524s app_test.go:1202: 18:32:03.375 - 69: DEBUG: new connection from 127.0.0.1:52052 524s app_test.go:1202: 18:32:03.377 - 70: DEBUG: new connection from 127.0.0.1:51368 524s app_test.go:1202: 18:32:03.380 - 71: DEBUG: new connection from 127.0.0.1:37720 524s app_test.go:1202: 18:32:03.412 - 65: DEBUG: new connection from 127.0.0.1:36024 524s app_test.go:1202: 18:32:03.414 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 524s app_test.go:1202: 18:32:03.417 - 65: DEBUG: new connection from 127.0.0.1:36032 524s app_test.go:1202: 18:32:03.420 - 66: DEBUG: new connection from 127.0.0.1:35526 524s app_test.go:1202: 18:32:03.422 - 67: DEBUG: new connection from 127.0.0.1:58328 524s app_test.go:1202: 18:32:03.424 - 68: DEBUG: new connection from 127.0.0.1:52666 524s app_test.go:1202: 18:32:03.427 - 69: DEBUG: new connection from 127.0.0.1:52068 524s app_test.go:1202: 18:32:03.429 - 70: DEBUG: new connection from 127.0.0.1:51372 524s app_test.go:1202: 18:32:03.431 - 71: DEBUG: new connection from 127.0.0.1:37724 524s app_test.go:1202: 18:32:03.433 - 72: DEBUG: new connection from 127.0.0.1:35704 528s app_test.go:1202: 18:32:03.055 - 65: DEBUG: new connection from 127.0.0.1:52870 528s app_test.go:1202: 18:32:03.057 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 528s app_test.go:1202: 18:32:03.060 - 65: DEBUG: new connection from 127.0.0.1:52882 528s app_test.go:1202: 18:32:03.062 - 66: DEBUG: new connection from 127.0.0.1:46428 528s app_test.go:1202: 18:32:03.065 - 67: DEBUG: new connection from 127.0.0.1:58096 528s app_test.go:1202: 18:32:03.067 - 68: DEBUG: new connection from 127.0.0.1:33856 528s app_test.go:1202: 18:32:03.070 - 69: DEBUG: new connection from 127.0.0.1:42604 528s app_test.go:1202: 18:32:03.073 - 70: DEBUG: new connection from 127.0.0.1:54606 528s app_test.go:1202: 18:32:03.077 - 71: DEBUG: new connection from 127.0.0.1:57700 528s app_test.go:1202: 18:32:03.080 - 72: DEBUG: new connection from 127.0.0.1:43420 528s app_test.go:1202: 18:32:03.083 - 72: DEBUG: new connection from 127.0.0.1:43428 528s app_test.go:1202: 18:32:03.092 - 65: DEBUG: new connection from 127.0.0.1:52888 528s app_test.go:1202: 18:32:03.092 - 65: DEBUG: new connection from 127.0.0.1:52894 528s app_test.go:1202: 18:32:03.097 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 528s app_test.go:1202: 18:32:03.145 - 65: DEBUG: new connection from 127.0.0.1:52898 528s app_test.go:1202: 18:32:03.148 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 528s app_test.go:1202: 18:32:03.198 - 65: DEBUG: new connection from 127.0.0.1:52906 528s app_test.go:1202: 18:32:03.200 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 528s app_test.go:1202: 18:32:03.260 - 65: DEBUG: new connection from 127.0.0.1:52908 528s app_test.go:1202: 18:32:03.263 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 528s app_test.go:1202: 18:32:03.330 - 65: DEBUG: new connection from 127.0.0.1:52916 528s app_test.go:1202: 18:32:03.333 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 528s app_test.go:1202: 18:32:03.383 - 65: DEBUG: new connection from 127.0.0.1:52922 528s app_test.go:1202: 18:32:03.386 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 528s app_test.go:1202: 18:32:03.437 - 65: DEBUG: new connection from 127.0.0.1:52930 528s app_test.go:1202: 18:32:03.440 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 528s app_test.go:1202: 18:32:03.536 - 65: DEBUG: new connection from 127.0.0.1:52940 528s app_test.go:1202: 18:32:03.540 - 66: DEBUG: new connection from 127.0.0.1:46434 528s app_test.go:1202: 18:32:03.546 - 67: DEBUG: new connection from 127.0.0.1:58106 528s app_test.go:1202: 18:32:03.550 - 68: DEBUG: new connection from 127.0.0.1:33858 528s app_test.go:1202: 18:32:03.554 - 69: DEBUG: new connection from 127.0.0.1:42612 528s app_test.go:1202: 18:32:03.556 - 70: DEBUG: new connection from 127.0.0.1:54618 528s app_test.go:1202: 18:32:03.560 - 71: DEBUG: new connection from 127.0.0.1:57702 528s app_test.go:1202: 18:32:03.563 - 72: DEBUG: new connection from 127.0.0.1:43442 528s app_test.go:1202: 18:32:03.570 - 65: DEBUG: new connection from 127.0.0.1:52950 528s app_test.go:1202: 18:32:03.572 - 66: DEBUG: new connection from 127.0.0.1:46440 528s app_test.go:1202: 18:32:03.575 - 67: DEBUG: new connection from 127.0.0.1:58116 528s app_test.go:1202: 18:32:03.577 - 68: DEBUG: new connection from 127.0.0.1:33864 528s app_test.go:1202: 18:32:03.580 - 69: DEBUG: new connection from 127.0.0.1:42628 528s app_test.go:1202: 18:32:03.583 - 70: DEBUG: new connection from 127.0.0.1:54626 528s app_test.go:1202: 18:32:03.585 - 71: DEBUG: new connection from 127.0.0.1:57712 528s app_test.go:1202: 18:32:03.588 - 72: DEBUG: new connection from 127.0.0.1:43454 532s app_test.go:1202: 18:32:03.100 - 65: DEBUG: new connection from 127.0.0.1:52958 532s app_test.go:1202: 18:32:03.103 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 532s app_test.go:1202: 18:32:03.152 - 65: DEBUG: new connection from 127.0.0.1:52960 532s app_test.go:1202: 18:32:03.155 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 532s app_test.go:1202: 18:32:03.204 - 65: DEBUG: new connection from 127.0.0.1:52970 532s app_test.go:1202: 18:32:03.208 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 532s app_test.go:1202: 18:32:03.267 - 65: DEBUG: new connection from 127.0.0.1:52986 532s app_test.go:1202: 18:32:03.269 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 532s app_test.go:1202: 18:32:03.337 - 65: DEBUG: new connection from 127.0.0.1:53000 532s app_test.go:1202: 18:32:03.339 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 532s app_test.go:1202: 18:32:03.389 - 65: DEBUG: new connection from 127.0.0.1:53006 532s app_test.go:1202: 18:32:03.392 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 532s app_test.go:1202: 18:32:03.444 - 65: DEBUG: new connection from 127.0.0.1:53008 532s app_test.go:1202: 18:32:03.447 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 532s app_test.go:1202: 18:32:03.591 - 65: DEBUG: new connection from 127.0.0.1:53022 532s app_test.go:1202: 18:32:03.594 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 532s app_test.go:1202: 18:32:03.597 - 65: DEBUG: new connection from 127.0.0.1:53036 532s app_test.go:1202: 18:32:03.600 - 66: DEBUG: new connection from 127.0.0.1:46442 532s app_test.go:1202: 18:32:03.602 - 67: DEBUG: new connection from 127.0.0.1:58122 532s app_test.go:1202: 18:32:03.605 - 68: DEBUG: new connection from 127.0.0.1:33876 532s app_test.go:1202: 18:32:03.608 - 69: DEBUG: new connection from 127.0.0.1:42642 532s app_test.go:1202: 18:32:03.618 - 70: DEBUG: new connection from 127.0.0.1:54630 532s app_test.go:1202: 18:32:03.625 - 71: DEBUG: new connection from 127.0.0.1:57716 532s app_test.go:1202: 18:32:03.627 - 72: DEBUG: new connection from 127.0.0.1:43468 533s app_test.go:1202: 18:32:03.563 - 67: DEBUG: new connection from 127.0.0.1:58132 533s app_test.go:1202: 18:32:03.565 - 72: DEBUG: new connection from 127.0.0.1:43480 533s app_test.go:1202: 18:32:03.569 - 66: DEBUG: new connection from 127.0.0.1:46448 533s app_test.go:1202: 18:32:03.572 - 66: DEBUG: new connection from 127.0.0.1:46460 536s app_test.go:1202: 18:32:03.105 - 65: DEBUG: new connection from 127.0.0.1:57238 536s app_test.go:1202: 18:32:03.108 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 536s app_test.go:1202: 18:32:03.158 - 65: DEBUG: new connection from 127.0.0.1:57244 536s app_test.go:1202: 18:32:03.160 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 536s app_test.go:1202: 18:32:03.212 - 65: DEBUG: new connection from 127.0.0.1:57250 536s app_test.go:1202: 18:32:03.214 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 536s app_test.go:1202: 18:32:03.274 - 65: DEBUG: new connection from 127.0.0.1:57262 536s app_test.go:1202: 18:32:03.277 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 536s app_test.go:1202: 18:32:03.342 - 65: DEBUG: new connection from 127.0.0.1:57276 536s app_test.go:1202: 18:32:03.345 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 536s app_test.go:1202: 18:32:03.395 - 65: DEBUG: new connection from 127.0.0.1:57290 536s app_test.go:1202: 18:32:03.397 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 536s app_test.go:1202: 18:32:03.450 - 65: DEBUG: new connection from 127.0.0.1:57306 536s app_test.go:1202: 18:32:03.453 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 536s app_test.go:1202: 18:32:03.630 - 65: DEBUG: new connection from 127.0.0.1:57320 536s app_test.go:1202: 18:32:03.633 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 536s app_test.go:1202: 18:32:03.636 - 65: DEBUG: new connection from 127.0.0.1:57330 536s app_test.go:1202: 18:32:03.644 - 66: DEBUG: new connection from 127.0.0.1:41846 536s app_test.go:1202: 18:32:03.647 - 67: DEBUG: new connection from 127.0.0.1:50792 536s app_test.go:1202: 18:32:03.650 - 68: DEBUG: new connection from 127.0.0.1:39776 536s app_test.go:1202: 18:32:03.653 - 69: DEBUG: new connection from 127.0.0.1:41738 536s app_test.go:1202: 18:32:03.658 - 70: DEBUG: new connection from 127.0.0.1:56768 536s app_test.go:1202: 18:32:03.662 - 71: DEBUG: new connection from 127.0.0.1:60796 536s app_test.go:1202: 18:32:03.667 - 72: DEBUG: new connection from 127.0.0.1:50414 540s app_test.go:1202: 18:32:03.110 - 65: DEBUG: new connection from 127.0.0.1:57342 540s app_test.go:1202: 18:32:03.113 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected 540s app_test.go:1202: 18:32:03.164 - 65: DEBUG: new connection from 127.0.0.1:57350 540s app_test.go:1202: 18:32:03.166 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected 540s app_test.go:1202: 18:32:03.218 - 65: DEBUG: new connection from 127.0.0.1:57362 540s app_test.go:1202: 18:32:03.221 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected 540s app_test.go:1202: 18:32:03.282 - 65: DEBUG: new connection from 127.0.0.1:57376 540s app_test.go:1202: 18:32:03.284 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected 540s app_test.go:1202: 18:32:03.348 - 65: DEBUG: new connection from 127.0.0.1:57392 540s app_test.go:1202: 18:32:03.351 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected 540s app_test.go:1202: 18:32:03.400 - 65: DEBUG: new connection from 127.0.0.1:57398 540s app_test.go:1202: 18:32:03.403 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected 540s app_test.go:1202: 18:32:03.456 - 65: DEBUG: new connection from 127.0.0.1:57414 540s app_test.go:1202: 18:32:03.458 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected 540s app_test.go:1202: 18:32:03.671 - 65: DEBUG: new connection from 127.0.0.1:57426 540s app_test.go:1202: 18:32:03.674 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 540s app_test.go:1202: 18:32:03.677 - 65: DEBUG: new connection from 127.0.0.1:57436 540s app_test.go:1202: 18:32:03.680 - 66: DEBUG: new connection from 127.0.0.1:41856 540s app_test.go:1202: 18:32:03.684 - 67: DEBUG: new connection from 127.0.0.1:50798 540s app_test.go:1202: 18:32:03.687 - 68: DEBUG: new connection from 127.0.0.1:39778 540s app_test.go:1202: 18:32:03.690 - 69: DEBUG: new connection from 127.0.0.1:41746 540s app_test.go:1202: 18:32:03.692 - 70: DEBUG: new connection from 127.0.0.1:56772 540s app_test.go:1202: 18:32:03.694 - 71: DEBUG: new connection from 127.0.0.1:60808 540s app_test.go:1202: 18:32:03.697 - 72: DEBUG: new connection from 127.0.0.1:50420 542s app_test.go:1202: 18:32:03.437 - 65: DEBUG: new connection from 127.0.0.1:57444 542s app_test.go:1202: 18:32:03.439 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected 542s --- PASS: TestRolesAdjustment_ImbalancedFailureDomain (18.58s) 542s === RUN TestRolesAdjustment_ReplaceVoterHonorWeight 542s app_test.go:1202: 18:32:03.626 - 73: DEBUG: new connection from 127.0.0.1:57458 542s app_test.go:1202: 18:32:03.629 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 542s app_test.go:1202: 18:32:03.631 - 73: DEBUG: new connection from 127.0.0.1:57466 542s app_test.go:1202: 18:32:03.660 - 73: DEBUG: new connection from 127.0.0.1:57470 542s app_test.go:1202: 18:32:03.663 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 542s app_test.go:1202: 18:32:03.666 - 73: DEBUG: new connection from 127.0.0.1:57486 542s app_test.go:1202: 18:32:03.668 - 74: DEBUG: new connection from 127.0.0.1:41860 542s app_test.go:1202: 18:32:03.697 - 73: DEBUG: new connection from 127.0.0.1:57500 542s app_test.go:1202: 18:32:03.700 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected 542s app_test.go:1202: 18:32:03.702 - 73: DEBUG: new connection from 127.0.0.1:57506 542s app_test.go:1202: 18:32:03.705 - 74: DEBUG: new connection from 127.0.0.1:41866 542s app_test.go:1202: 18:32:03.708 - 75: DEBUG: new connection from 127.0.0.1:50820 542s app_test.go:1202: 18:32:03.711 - 75: DEBUG: new connection from 127.0.0.1:50826 542s app_test.go:1202: 18:32:03.717 - 73: DEBUG: new connection from 127.0.0.1:57508 542s app_test.go:1202: 18:32:03.722 - 74: DEBUG: new connection from 127.0.0.1:41880 542s app_test.go:1202: 18:32:03.726 - 73: DEBUG: new connection from 127.0.0.1:57518 542s app_test.go:1202: 18:32:03.760 - 73: DEBUG: new connection from 127.0.0.1:57534 542s app_test.go:1202: 18:32:03.763 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 542s app_test.go:1202: 18:32:03.765 - 73: DEBUG: new connection from 127.0.0.1:57544 542s app_test.go:1202: 18:32:03.768 - 74: DEBUG: new connection from 127.0.0.1:41886 542s app_test.go:1202: 18:32:03.770 - 75: DEBUG: new connection from 127.0.0.1:50838 542s app_test.go:1202: 18:32:03.774 - 76: DEBUG: new connection from 127.0.0.1:39798 542s app_test.go:1202: 18:32:03.778 - 76: DEBUG: new connection from 127.0.0.1:39810 542s app_test.go:1202: 18:32:03.785 - 73: DEBUG: new connection from 127.0.0.1:57558 542s app_test.go:1202: 18:32:03.812 - 73: DEBUG: new connection from 127.0.0.1:57562 542s app_test.go:1202: 18:32:03.815 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 542s app_test.go:1202: 18:32:03.817 - 73: DEBUG: new connection from 127.0.0.1:57568 542s app_test.go:1202: 18:32:03.820 - 74: DEBUG: new connection from 127.0.0.1:41888 542s app_test.go:1202: 18:32:03.823 - 75: DEBUG: new connection from 127.0.0.1:50854 542s app_test.go:1202: 18:32:03.825 - 76: DEBUG: new connection from 127.0.0.1:39818 542s app_test.go:1202: 18:32:03.827 - 77: DEBUG: new connection from 127.0.0.1:41758 542s app_test.go:1202: 18:32:03.843 - 77: DEBUG: new connection from 127.0.0.1:41764 542s app_test.go:1202: 18:32:03.849 - 73: DEBUG: new connection from 127.0.0.1:57574 542s app_test.go:1202: 18:32:03.880 - 73: DEBUG: new connection from 127.0.0.1:57580 542s app_test.go:1202: 18:32:03.884 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 542s app_test.go:1202: 18:32:03.888 - 73: DEBUG: new connection from 127.0.0.1:57596 542s app_test.go:1202: 18:32:03.890 - 74: DEBUG: new connection from 127.0.0.1:41904 542s app_test.go:1202: 18:32:03.893 - 75: DEBUG: new connection from 127.0.0.1:50858 542s app_test.go:1202: 18:32:03.895 - 76: DEBUG: new connection from 127.0.0.1:39832 542s app_test.go:1202: 18:32:03.897 - 77: DEBUG: new connection from 127.0.0.1:41766 542s app_test.go:1202: 18:32:03.900 - 78: DEBUG: new connection from 127.0.0.1:56788 542s app_test.go:1202: 18:32:03.903 - 78: DEBUG: new connection from 127.0.0.1:56804 542s app_test.go:1202: 18:32:03.925 - 73: DEBUG: new connection from 127.0.0.1:57612 546s app_test.go:1202: 18:32:03.633 - 73: DEBUG: new connection from 127.0.0.1:56768 546s app_test.go:1202: 18:32:03.636 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 546s app_test.go:1202: 18:32:03.638 - 73: DEBUG: new connection from 127.0.0.1:56776 546s app_test.go:1202: 18:32:03.641 - 74: DEBUG: new connection from 127.0.0.1:49712 546s app_test.go:1202: 18:32:03.644 - 76: DEBUG: new connection from 127.0.0.1:51170 546s app_test.go:1202: 18:32:03.648 - 77: DEBUG: new connection from 127.0.0.1:50120 546s app_test.go:1202: 18:32:03.650 - 78: DEBUG: new connection from 127.0.0.1:59948 546s app_test.go:1202: 18:32:03.671 - 73: DEBUG: new connection from 127.0.0.1:56780 546s app_test.go:1202: 18:32:03.674 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 546s app_test.go:1202: 18:32:03.781 - 73: DEBUG: new connection from 127.0.0.1:56788 546s app_test.go:1202: 18:32:03.784 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 546s app_test.go:1202: 18:32:03.847 - 73: DEBUG: new connection from 127.0.0.1:56802 546s app_test.go:1202: 18:32:03.850 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 546s app_test.go:1202: 18:32:03.906 - 73: DEBUG: new connection from 127.0.0.1:56814 546s app_test.go:1202: 18:32:03.909 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 547s app_test.go:1202: 18:32:03.107 - 73: DEBUG: new connection from 127.0.0.1:56830 547s app_test.go:1202: 18:32:03.110 - 74: DEBUG: new connection from 127.0.0.1:49714 547s app_test.go:1202: 18:32:03.113 - 76: DEBUG: new connection from 127.0.0.1:51174 547s app_test.go:1202: 18:32:03.117 - 77: DEBUG: new connection from 127.0.0.1:50128 547s app_test.go:1202: 18:32:03.120 - 78: DEBUG: new connection from 127.0.0.1:59952 547s app_test.go:1202: 18:32:03.125 - 73: DEBUG: new connection from 127.0.0.1:56842 547s app_test.go:1202: 18:32:03.127 - 74: DEBUG: new connection from 127.0.0.1:49722 547s app_test.go:1202: 18:32:03.131 - 76: DEBUG: new connection from 127.0.0.1:51182 547s app_test.go:1202: 18:32:03.135 - 77: DEBUG: new connection from 127.0.0.1:50144 547s app_test.go:1202: 18:32:03.137 - 78: DEBUG: new connection from 127.0.0.1:59966 550s app_test.go:1202: 18:32:03.677 - 73: DEBUG: new connection from 127.0.0.1:56856 550s app_test.go:1202: 18:32:03.679 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 550s app_test.go:1202: 18:32:03.787 - 73: DEBUG: new connection from 127.0.0.1:56870 550s app_test.go:1202: 18:32:03.790 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 550s app_test.go:1202: 18:32:03.853 - 73: DEBUG: new connection from 127.0.0.1:56876 550s app_test.go:1202: 18:32:03.855 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 550s app_test.go:1202: 18:32:03.912 - 73: DEBUG: new connection from 127.0.0.1:56886 550s app_test.go:1202: 18:32:03.914 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 551s app_test.go:1202: 18:32:03.140 - 73: DEBUG: new connection from 127.0.0.1:56900 551s app_test.go:1202: 18:32:03.143 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 551s app_test.go:1202: 18:32:03.145 - 73: DEBUG: new connection from 127.0.0.1:56916 551s app_test.go:1202: 18:32:03.148 - 74: DEBUG: new connection from 127.0.0.1:49738 551s app_test.go:1202: 18:32:03.151 - 76: DEBUG: new connection from 127.0.0.1:51192 551s app_test.go:1202: 18:32:03.154 - 77: DEBUG: new connection from 127.0.0.1:50148 551s app_test.go:1202: 18:32:03.156 - 78: DEBUG: new connection from 127.0.0.1:59976 554s app_test.go:1202: 18:32:03.683 - 73: DEBUG: new connection from 127.0.0.1:56924 554s app_test.go:1202: 18:32:03.686 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 554s app_test.go:1202: 18:32:03.793 - 73: DEBUG: new connection from 127.0.0.1:56940 554s app_test.go:1202: 18:32:03.797 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 554s app_test.go:1202: 18:32:03.858 - 73: DEBUG: new connection from 127.0.0.1:56948 554s app_test.go:1202: 18:32:03.861 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 554s app_test.go:1202: 18:32:03.917 - 73: DEBUG: new connection from 127.0.0.1:56952 554s app_test.go:1202: 18:32:03.920 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 555s app_test.go:1202: 18:32:03.161 - 73: DEBUG: new connection from 127.0.0.1:53874 555s app_test.go:1202: 18:32:03.164 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 555s app_test.go:1202: 18:32:03.166 - 73: DEBUG: new connection from 127.0.0.1:53878 555s app_test.go:1202: 18:32:03.168 - 74: DEBUG: new connection from 127.0.0.1:59318 555s app_test.go:1202: 18:32:03.171 - 76: DEBUG: new connection from 127.0.0.1:56794 555s app_test.go:1202: 18:32:03.174 - 77: DEBUG: new connection from 127.0.0.1:57438 555s app_test.go:1202: 18:32:03.176 - 78: DEBUG: new connection from 127.0.0.1:46180 558s app_test.go:1202: 18:32:03.688 - 73: DEBUG: new connection from 127.0.0.1:53884 558s app_test.go:1202: 18:32:03.690 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected 558s app_test.go:1202: 18:32:03.801 - 73: DEBUG: new connection from 127.0.0.1:53888 558s app_test.go:1202: 18:32:03.804 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected 558s app_test.go:1202: 18:32:03.864 - 73: DEBUG: new connection from 127.0.0.1:53904 558s app_test.go:1202: 18:32:03.867 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected 558s app_test.go:1202: 18:32:03.922 - 73: DEBUG: new connection from 127.0.0.1:53916 558s app_test.go:1202: 18:32:03.925 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected 559s app_test.go:1202: 18:32:03.179 - 73: DEBUG: new connection from 127.0.0.1:53920 559s app_test.go:1202: 18:32:03.182 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 559s app_test.go:1202: 18:32:03.184 - 73: DEBUG: new connection from 127.0.0.1:53936 559s app_test.go:1202: 18:32:03.187 - 74: DEBUG: new connection from 127.0.0.1:59328 559s app_test.go:1202: 18:32:03.189 - 76: DEBUG: new connection from 127.0.0.1:56806 559s app_test.go:1202: 18:32:03.192 - 77: DEBUG: new connection from 127.0.0.1:57442 559s app_test.go:1202: 18:32:03.195 - 78: DEBUG: new connection from 127.0.0.1:46194 560s app_test.go:1202: 18:32:03.932 - 73: DEBUG: new connection from 127.0.0.1:53940 560s app_test.go:1202: 18:32:03.935 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected 561s --- PASS: TestRolesAdjustment_ReplaceVoterHonorWeight (18.46s) 561s === RUN TestRolesAdjustment_CantReplaceVoter 561s app_test.go:1202: 18:32:03.082 - 79: DEBUG: new connection from 127.0.0.1:53944 561s app_test.go:1202: 18:32:03.084 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 561s app_test.go:1202: 18:32:03.086 - 79: DEBUG: new connection from 127.0.0.1:53958 561s app_test.go:1202: 18:32:03.122 - 79: DEBUG: new connection from 127.0.0.1:53962 561s app_test.go:1202: 18:32:03.127 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 561s app_test.go:1202: 18:32:03.133 - 79: DEBUG: new connection from 127.0.0.1:53976 561s app_test.go:1202: 18:32:03.137 - 80: DEBUG: new connection from 127.0.0.1:59344 561s app_test.go:1202: 18:32:03.175 - 79: DEBUG: new connection from 127.0.0.1:53992 561s app_test.go:1202: 18:32:03.178 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected 561s app_test.go:1202: 18:32:03.180 - 79: DEBUG: new connection from 127.0.0.1:53998 561s app_test.go:1202: 18:32:03.183 - 80: DEBUG: new connection from 127.0.0.1:59356 561s app_test.go:1202: 18:32:03.186 - 81: DEBUG: new connection from 127.0.0.1:48094 561s app_test.go:1202: 18:32:03.196 - 81: DEBUG: new connection from 127.0.0.1:48108 561s app_test.go:1202: 18:32:03.200 - 79: DEBUG: new connection from 127.0.0.1:54004 561s app_test.go:1202: 18:32:03.208 - 80: DEBUG: new connection from 127.0.0.1:59370 561s app_test.go:1202: 18:32:03.213 - 79: DEBUG: new connection from 127.0.0.1:54006 561s app_test.go:1202: 18:32:03.245 - 79: DEBUG: new connection from 127.0.0.1:54018 561s app_test.go:1202: 18:32:03.247 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected 561s app_test.go:1202: 18:32:03.250 - 79: DEBUG: new connection from 127.0.0.1:54022 561s app_test.go:1202: 18:32:03.252 - 80: DEBUG: new connection from 127.0.0.1:59382 561s app_test.go:1202: 18:32:03.255 - 81: DEBUG: new connection from 127.0.0.1:48120 561s app_test.go:1202: 18:32:03.258 - 82: DEBUG: new connection from 127.0.0.1:56810 561s app_test.go:1202: 18:32:03.261 - 82: DEBUG: new connection from 127.0.0.1:56812 565s app_test.go:1202: 18:32:03.091 - 79: DEBUG: new connection from 127.0.0.1:54296 565s app_test.go:1202: 18:32:03.093 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 565s app_test.go:1202: 18:32:03.095 - 79: DEBUG: new connection from 127.0.0.1:54306 565s app_test.go:1202: 18:32:03.100 - 80: DEBUG: new connection from 127.0.0.1:36650 565s app_test.go:1202: 18:32:03.142 - 79: DEBUG: new connection from 127.0.0.1:54314 565s app_test.go:1202: 18:32:03.145 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 569s app_test.go:1202: 18:32:03.104 - 79: DEBUG: new connection from 127.0.0.1:54316 569s app_test.go:1202: 18:32:03.106 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 569s app_test.go:1202: 18:32:03.108 - 79: DEBUG: new connection from 127.0.0.1:54326 569s app_test.go:1202: 18:32:03.111 - 80: DEBUG: new connection from 127.0.0.1:36664 569s app_test.go:1202: 18:32:03.148 - 79: DEBUG: new connection from 127.0.0.1:54338 569s app_test.go:1202: 18:32:03.150 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s app_test.go:1202: 18:32:03.115 - 79: DEBUG: new connection from 127.0.0.1:54352 573s app_test.go:1202: 18:32:03.117 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s app_test.go:1202: 18:32:03.120 - 79: DEBUG: new connection from 127.0.0.1:54354 573s app_test.go:1202: 18:32:03.123 - 80: DEBUG: new connection from 127.0.0.1:36674 573s app_test.go:1202: 18:32:03.153 - 79: DEBUG: new connection from 127.0.0.1:54364 573s app_test.go:1202: 18:32:03.157 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s app_test.go:1202: 18:32:03.292 - 79: DEBUG: new connection from 127.0.0.1:54366 573s app_test.go:1202: 18:32:03.295 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s --- PASS: TestRolesAdjustment_CantReplaceVoter (12.29s) 573s === RUN TestRolesAdjustment_ReplaceStandBy 573s app_test.go:1202: 18:32:03.368 - 83: DEBUG: new connection from 127.0.0.1:54368 573s app_test.go:1202: 18:32:03.371 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s app_test.go:1202: 18:32:03.373 - 83: DEBUG: new connection from 127.0.0.1:54370 573s app_test.go:1202: 18:32:03.401 - 83: DEBUG: new connection from 127.0.0.1:54372 573s app_test.go:1202: 18:32:03.404 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s app_test.go:1202: 18:32:03.410 - 83: DEBUG: new connection from 127.0.0.1:54376 573s app_test.go:1202: 18:32:03.415 - 84: DEBUG: new connection from 127.0.0.1:36676 573s app_test.go:1202: 18:32:03.448 - 83: DEBUG: new connection from 127.0.0.1:54378 573s app_test.go:1202: 18:32:03.452 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s app_test.go:1202: 18:32:03.455 - 83: DEBUG: new connection from 127.0.0.1:54392 573s app_test.go:1202: 18:32:03.457 - 84: DEBUG: new connection from 127.0.0.1:36690 573s app_test.go:1202: 18:32:03.460 - 85: DEBUG: new connection from 127.0.0.1:54104 573s app_test.go:1202: 18:32:03.463 - 85: DEBUG: new connection from 127.0.0.1:54114 573s app_test.go:1202: 18:32:03.467 - 83: DEBUG: new connection from 127.0.0.1:54394 573s app_test.go:1202: 18:32:03.473 - 84: DEBUG: new connection from 127.0.0.1:36704 573s app_test.go:1202: 18:32:03.478 - 83: DEBUG: new connection from 127.0.0.1:54396 573s app_test.go:1202: 18:32:03.513 - 83: DEBUG: new connection from 127.0.0.1:54408 573s app_test.go:1202: 18:32:03.516 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s app_test.go:1202: 18:32:03.519 - 83: DEBUG: new connection from 127.0.0.1:54418 573s app_test.go:1202: 18:32:03.522 - 84: DEBUG: new connection from 127.0.0.1:36706 573s app_test.go:1202: 18:32:03.525 - 85: DEBUG: new connection from 127.0.0.1:54122 573s app_test.go:1202: 18:32:03.527 - 86: DEBUG: new connection from 127.0.0.1:55816 573s app_test.go:1202: 18:32:03.531 - 86: DEBUG: new connection from 127.0.0.1:55822 573s app_test.go:1202: 18:32:03.540 - 83: DEBUG: new connection from 127.0.0.1:54428 573s app_test.go:1202: 18:32:03.560 - 83: DEBUG: new connection from 127.0.0.1:54444 573s app_test.go:1202: 18:32:03.563 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s app_test.go:1202: 18:32:03.567 - 83: DEBUG: new connection from 127.0.0.1:54454 573s app_test.go:1202: 18:32:03.569 - 84: DEBUG: new connection from 127.0.0.1:36718 573s app_test.go:1202: 18:32:03.572 - 85: DEBUG: new connection from 127.0.0.1:54130 573s app_test.go:1202: 18:32:03.575 - 86: DEBUG: new connection from 127.0.0.1:55832 573s app_test.go:1202: 18:32:03.577 - 87: DEBUG: new connection from 127.0.0.1:38798 573s app_test.go:1202: 18:32:03.580 - 87: DEBUG: new connection from 127.0.0.1:38814 573s app_test.go:1202: 18:32:03.588 - 83: DEBUG: new connection from 127.0.0.1:54464 573s app_test.go:1202: 18:32:03.617 - 83: DEBUG: new connection from 127.0.0.1:54468 573s app_test.go:1202: 18:32:03.620 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s app_test.go:1202: 18:32:03.624 - 83: DEBUG: new connection from 127.0.0.1:54482 573s app_test.go:1202: 18:32:03.627 - 84: DEBUG: new connection from 127.0.0.1:36726 573s app_test.go:1202: 18:32:03.630 - 85: DEBUG: new connection from 127.0.0.1:54132 573s app_test.go:1202: 18:32:03.635 - 86: DEBUG: new connection from 127.0.0.1:55840 573s app_test.go:1202: 18:32:03.637 - 87: DEBUG: new connection from 127.0.0.1:38820 573s app_test.go:1202: 18:32:03.640 - 88: DEBUG: new connection from 127.0.0.1:59656 573s app_test.go:1202: 18:32:03.644 - 88: DEBUG: new connection from 127.0.0.1:59660 573s app_test.go:1202: 18:32:03.651 - 83: DEBUG: new connection from 127.0.0.1:54490 573s app_test.go:1202: 18:32:03.681 - 83: DEBUG: new connection from 127.0.0.1:54496 573s app_test.go:1202: 18:32:03.684 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 573s app_test.go:1202: 18:32:03.688 - 83: DEBUG: new connection from 127.0.0.1:54508 573s app_test.go:1202: 18:32:03.691 - 84: DEBUG: new connection from 127.0.0.1:36736 573s app_test.go:1202: 18:32:03.694 - 85: DEBUG: new connection from 127.0.0.1:54146 573s app_test.go:1202: 18:32:03.697 - 86: DEBUG: new connection from 127.0.0.1:55848 573s app_test.go:1202: 18:32:03.700 - 87: DEBUG: new connection from 127.0.0.1:38836 573s app_test.go:1202: 18:32:03.702 - 88: DEBUG: new connection from 127.0.0.1:59666 573s app_test.go:1202: 18:32:03.706 - 89: DEBUG: new connection from 127.0.0.1:44380 578s app_test.go:1202: 18:32:03.377 - 83: DEBUG: new connection from 127.0.0.1:38162 578s app_test.go:1202: 18:32:03.379 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 578s app_test.go:1202: 18:32:03.382 - 83: DEBUG: new connection from 127.0.0.1:38172 578s app_test.go:1202: 18:32:03.385 - 84: DEBUG: new connection from 127.0.0.1:50660 578s app_test.go:1202: 18:32:03.388 - 85: DEBUG: new connection from 127.0.0.1:45666 578s app_test.go:1202: 18:32:03.391 - 86: DEBUG: new connection from 127.0.0.1:50874 578s app_test.go:1202: 18:32:03.393 - 88: DEBUG: new connection from 127.0.0.1:60120 578s app_test.go:1202: 18:32:03.396 - 89: DEBUG: new connection from 127.0.0.1:59408 578s app_test.go:1202: 18:32:03.411 - 89: DEBUG: new connection from 127.0.0.1:59414 578s app_test.go:1202: 18:32:03.416 - 83: DEBUG: new connection from 127.0.0.1:38180 578s app_test.go:1202: 18:32:03.419 - 83: DEBUG: new connection from 127.0.0.1:38188 578s app_test.go:1202: 18:32:03.422 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 578s app_test.go:1202: 18:32:03.484 - 83: DEBUG: new connection from 127.0.0.1:38202 578s app_test.go:1202: 18:32:03.486 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 578s app_test.go:1202: 18:32:03.533 - 83: DEBUG: new connection from 127.0.0.1:38204 578s app_test.go:1202: 18:32:03.535 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 578s app_test.go:1202: 18:32:03.647 - 83: DEBUG: new connection from 127.0.0.1:38208 578s app_test.go:1202: 18:32:03.649 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 578s app_test.go:1202: 18:32:03.711 - 83: DEBUG: new connection from 127.0.0.1:38218 578s app_test.go:1202: 18:32:03.715 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 578s app_test.go:1202: 18:32:03.851 - 83: DEBUG: new connection from 127.0.0.1:38222 578s app_test.go:1202: 18:32:03.854 - 84: DEBUG: new connection from 127.0.0.1:50666 578s app_test.go:1202: 18:32:03.856 - 85: DEBUG: new connection from 127.0.0.1:45676 578s app_test.go:1202: 18:32:03.858 - 86: DEBUG: new connection from 127.0.0.1:50886 578s app_test.go:1202: 18:32:03.861 - 88: DEBUG: new connection from 127.0.0.1:60136 578s app_test.go:1202: 18:32:03.863 - 89: DEBUG: new connection from 127.0.0.1:59420 578s app_test.go:1202: 18:32:03.868 - 83: DEBUG: new connection from 127.0.0.1:38230 578s app_test.go:1202: 18:32:03.870 - 84: DEBUG: new connection from 127.0.0.1:50676 578s app_test.go:1202: 18:32:03.873 - 85: DEBUG: new connection from 127.0.0.1:45688 578s app_test.go:1202: 18:32:03.875 - 86: DEBUG: new connection from 127.0.0.1:50892 578s app_test.go:1202: 18:32:03.877 - 88: DEBUG: new connection from 127.0.0.1:60152 578s app_test.go:1202: 18:32:03.879 - 89: DEBUG: new connection from 127.0.0.1:59436 583s app_test.go:1202: 18:32:03.426 - 83: DEBUG: new connection from 127.0.0.1:38242 583s app_test.go:1202: 18:32:03.428 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 583s app_test.go:1202: 18:32:03.490 - 83: DEBUG: new connection from 127.0.0.1:38254 583s app_test.go:1202: 18:32:03.492 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 583s app_test.go:1202: 18:32:03.538 - 83: DEBUG: new connection from 127.0.0.1:38264 583s app_test.go:1202: 18:32:03.543 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 583s app_test.go:1202: 18:32:03.652 - 83: DEBUG: new connection from 127.0.0.1:38278 583s app_test.go:1202: 18:32:03.654 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 583s app_test.go:1202: 18:32:03.718 - 83: DEBUG: new connection from 127.0.0.1:38280 583s app_test.go:1202: 18:32:03.721 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 583s app_test.go:1202: 18:32:03.882 - 83: DEBUG: new connection from 127.0.0.1:38284 583s app_test.go:1202: 18:32:03.885 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 583s app_test.go:1202: 18:32:03.887 - 83: DEBUG: new connection from 127.0.0.1:38292 583s app_test.go:1202: 18:32:03.890 - 84: DEBUG: new connection from 127.0.0.1:50688 583s app_test.go:1202: 18:32:03.893 - 85: DEBUG: new connection from 127.0.0.1:45704 583s app_test.go:1202: 18:32:03.895 - 86: DEBUG: new connection from 127.0.0.1:50900 583s app_test.go:1202: 18:32:03.898 - 88: DEBUG: new connection from 127.0.0.1:60154 583s app_test.go:1202: 18:32:03.900 - 89: DEBUG: new connection from 127.0.0.1:59444 588s app_test.go:1202: 18:33:03.430 - 83: DEBUG: new connection from 127.0.0.1:51812 588s app_test.go:1202: 18:33:03.433 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 588s app_test.go:1202: 18:33:03.495 - 83: DEBUG: new connection from 127.0.0.1:51822 588s app_test.go:1202: 18:33:03.498 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 588s app_test.go:1202: 18:33:03.546 - 83: DEBUG: new connection from 127.0.0.1:51830 588s app_test.go:1202: 18:33:03.549 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 588s app_test.go:1202: 18:33:03.657 - 83: DEBUG: new connection from 127.0.0.1:51832 588s app_test.go:1202: 18:33:03.660 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 588s app_test.go:1202: 18:33:03.723 - 83: DEBUG: new connection from 127.0.0.1:51836 588s app_test.go:1202: 18:33:03.728 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 588s app_test.go:1202: 18:33:03.903 - 83: DEBUG: new connection from 127.0.0.1:51844 588s app_test.go:1202: 18:33:03.905 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 588s app_test.go:1202: 18:33:03.907 - 83: DEBUG: new connection from 127.0.0.1:51860 588s app_test.go:1202: 18:33:03.910 - 84: DEBUG: new connection from 127.0.0.1:35360 588s app_test.go:1202: 18:33:03.912 - 85: DEBUG: new connection from 127.0.0.1:44560 588s app_test.go:1202: 18:33:03.914 - 86: DEBUG: new connection from 127.0.0.1:56834 588s app_test.go:1202: 18:33:03.917 - 88: DEBUG: new connection from 127.0.0.1:50586 588s app_test.go:1202: 18:33:03.919 - 89: DEBUG: new connection from 127.0.0.1:47294 593s app_test.go:1202: 18:33:03.436 - 83: DEBUG: new connection from 127.0.0.1:51872 593s app_test.go:1202: 18:33:03.438 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected 593s app_test.go:1202: 18:33:03.500 - 83: DEBUG: new connection from 127.0.0.1:51888 593s app_test.go:1202: 18:33:03.503 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected 593s app_test.go:1202: 18:33:03.551 - 83: DEBUG: new connection from 127.0.0.1:51892 593s app_test.go:1202: 18:33:03.554 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected 593s app_test.go:1202: 18:33:03.662 - 83: DEBUG: new connection from 127.0.0.1:51896 593s app_test.go:1202: 18:33:03.664 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected 593s app_test.go:1202: 18:33:03.731 - 83: DEBUG: new connection from 127.0.0.1:51902 593s app_test.go:1202: 18:33:03.734 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected 593s app_test.go:1202: 18:33:03.736 - 83: DEBUG: new connection from 127.0.0.1:51914 593s app_test.go:1202: 18:33:03.738 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected 593s --- PASS: TestRolesAdjustment_ReplaceStandBy (20.54s) 593s === RUN TestRolesAdjustment_ReplaceStandByHonorFailureDomains 593s app_test.go:1202: 18:33:03.914 - 90: DEBUG: new connection from 127.0.0.1:51928 593s app_test.go:1202: 18:33:03.918 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 593s app_test.go:1202: 18:33:03.920 - 90: DEBUG: new connection from 127.0.0.1:51938 593s app_test.go:1202: 18:33:03.952 - 90: DEBUG: new connection from 127.0.0.1:51954 593s app_test.go:1202: 18:33:03.955 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected 594s app_test.go:1202: 18:33:03.959 - 90: DEBUG: new connection from 127.0.0.1:51964 594s app_test.go:1202: 18:33:03.961 - 91: DEBUG: new connection from 127.0.0.1:35362 594s app_test.go:1202: 18:33:03.988 - 90: DEBUG: new connection from 127.0.0.1:51974 594s app_test.go:1202: 18:33:03.991 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected 594s app_test.go:1202: 18:33:03.993 - 90: DEBUG: new connection from 127.0.0.1:51978 594s app_test.go:1202: 18:33:03.996 - 91: DEBUG: new connection from 127.0.0.1:35378 594s app_test.go:1202: 18:33:03.998 - 92: DEBUG: new connection from 127.0.0.1:44568 594s app_test.go:1202: 18:33:03.001 - 92: DEBUG: new connection from 127.0.0.1:44578 594s app_test.go:1202: 18:33:03.005 - 90: DEBUG: new connection from 127.0.0.1:51980 594s app_test.go:1202: 18:33:03.010 - 91: DEBUG: new connection from 127.0.0.1:35394 594s app_test.go:1202: 18:33:03.014 - 90: DEBUG: new connection from 127.0.0.1:51988 594s app_test.go:1202: 18:33:03.048 - 90: DEBUG: new connection from 127.0.0.1:51998 594s app_test.go:1202: 18:33:03.051 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected 594s app_test.go:1202: 18:33:03.053 - 90: DEBUG: new connection from 127.0.0.1:52010 594s app_test.go:1202: 18:33:03.056 - 91: DEBUG: new connection from 127.0.0.1:35396 594s app_test.go:1202: 18:33:03.058 - 92: DEBUG: new connection from 127.0.0.1:44582 594s app_test.go:1202: 18:33:03.061 - 93: DEBUG: new connection from 127.0.0.1:56850 594s app_test.go:1202: 18:33:03.078 - 93: DEBUG: new connection from 127.0.0.1:56856 594s app_test.go:1202: 18:33:03.087 - 90: DEBUG: new connection from 127.0.0.1:52018 594s app_test.go:1202: 18:33:03.116 - 90: DEBUG: new connection from 127.0.0.1:52022 594s app_test.go:1202: 18:33:03.119 - 94: DEBUG: attempt 1: server 127.0.0.1:9001: connected 594s app_test.go:1202: 18:33:03.122 - 90: DEBUG: new connection from 127.0.0.1:52030 594s app_test.go:1202: 18:33:03.124 - 91: DEBUG: new connection from 127.0.0.1:35408 594s app_test.go:1202: 18:33:03.127 - 92: DEBUG: new connection from 127.0.0.1:44598 594s app_test.go:1202: 18:33:03.129 - 93: DEBUG: new connection from 127.0.0.1:56868 594s app_test.go:1202: 18:33:03.131 - 94: DEBUG: new connection from 127.0.0.1:46508 594s app_test.go:1202: 18:33:03.135 - 94: DEBUG: new connection from 127.0.0.1:46524 594s app_test.go:1202: 18:33:03.141 - 90: DEBUG: new connection from 127.0.0.1:52038 594s app_test.go:1202: 18:33:03.168 - 90: DEBUG: new connection from 127.0.0.1:52054 594s app_test.go:1202: 18:33:03.171 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected 594s app_test.go:1202: 18:33:03.174 - 90: DEBUG: new connection from 127.0.0.1:52070 594s app_test.go:1202: 18:33:03.178 - 91: DEBUG: new connection from 127.0.0.1:35412 594s app_test.go:1202: 18:33:03.181 - 92: DEBUG: new connection from 127.0.0.1:44614 594s app_test.go:1202: 18:33:03.183 - 93: DEBUG: new connection from 127.0.0.1:56870 594s app_test.go:1202: 18:33:03.185 - 94: DEBUG: new connection from 127.0.0.1:46532 594s app_test.go:1202: 18:33:03.188 - 95: DEBUG: new connection from 127.0.0.1:50592 594s app_test.go:1202: 18:33:03.209 - 95: DEBUG: new connection from 127.0.0.1:50602 594s app_test.go:1202: 18:33:03.216 - 90: DEBUG: new connection from 127.0.0.1:52074 594s app_test.go:1202: 18:33:03.244 - 90: DEBUG: new connection from 127.0.0.1:52090 594s app_test.go:1202: 18:33:03.247 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected 594s app_test.go:1202: 18:33:03.252 - 90: DEBUG: new connection from 127.0.0.1:52102 594s app_test.go:1202: 18:33:03.254 - 91: DEBUG: new connection from 127.0.0.1:35422 594s app_test.go:1202: 18:33:03.257 - 92: DEBUG: new connection from 127.0.0.1:44620 594s app_test.go:1202: 18:33:03.259 - 93: DEBUG: new connection from 127.0.0.1:56878 594s app_test.go:1202: 18:33:03.262 - 94: DEBUG: new connection from 127.0.0.1:46544 594s app_test.go:1202: 18:33:03.264 - 95: DEBUG: new connection from 127.0.0.1:50606 594s app_test.go:1202: 18:33:03.268 - 96: DEBUG: new connection from 127.0.0.1:47296 594s app_test.go:1202: 18:33:03.300 - 90: DEBUG: new connection from 127.0.0.1:52110 594s app_test.go:1202: 18:33:03.303 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected 594s app_test.go:1202: 18:33:03.306 - 90: DEBUG: new connection from 127.0.0.1:52116 594s app_test.go:1202: 18:33:03.308 - 91: DEBUG: new connection from 127.0.0.1:35428 594s app_test.go:1202: 18:33:03.311 - 92: DEBUG: new connection from 127.0.0.1:44630 594s app_test.go:1202: 18:33:03.313 - 93: DEBUG: new connection from 127.0.0.1:56894 594s app_test.go:1202: 18:33:03.315 - 94: DEBUG: new connection from 127.0.0.1:46552 594s app_test.go:1202: 18:33:03.317 - 95: DEBUG: new connection from 127.0.0.1:50618 594s app_test.go:1202: 18:33:03.319 - 96: DEBUG: new connection from 127.0.0.1:47310 594s app_test.go:1202: 18:33:03.322 - 97: DEBUG: new connection from 127.0.0.1:50080 594s app_test.go:1202: 18:33:03.352 - 90: DEBUG: new connection from 127.0.0.1:52126 594s app_test.go:1202: 18:33:03.354 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected 594s app_test.go:1202: 18:33:03.358 - 90: DEBUG: new connection from 127.0.0.1:52128 594s app_test.go:1202: 18:33:03.360 - 91: DEBUG: new connection from 127.0.0.1:35432 594s app_test.go:1202: 18:33:03.362 - 92: DEBUG: new connection from 127.0.0.1:44634 594s app_test.go:1202: 18:33:03.365 - 93: DEBUG: new connection from 127.0.0.1:56906 594s app_test.go:1202: 18:33:03.367 - 94: DEBUG: new connection from 127.0.0.1:46564 594s app_test.go:1202: 18:33:03.370 - 95: DEBUG: new connection from 127.0.0.1:50630 594s app_test.go:1202: 18:33:03.374 - 96: DEBUG: new connection from 127.0.0.1:47324 594s app_test.go:1202: 18:33:03.377 - 97: DEBUG: new connection from 127.0.0.1:50086 594s app_test.go:1202: 18:33:03.379 - 98: DEBUG: new connection from 127.0.0.1:49560 598s app_test.go:1202: 18:33:03.923 - 90: DEBUG: new connection from 127.0.0.1:56232 598s app_test.go:1202: 18:33:03.926 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 598s app_test.go:1202: 18:33:03.928 - 90: DEBUG: new connection from 127.0.0.1:56240 598s app_test.go:1202: 18:33:03.931 - 91: DEBUG: new connection from 127.0.0.1:54796 598s app_test.go:1202: 18:33:03.933 - 92: DEBUG: new connection from 127.0.0.1:36398 598s app_test.go:1202: 18:33:03.935 - 93: DEBUG: new connection from 127.0.0.1:37892 598s app_test.go:1202: 18:33:03.938 - 95: DEBUG: new connection from 127.0.0.1:53994 598s app_test.go:1202: 18:33:03.940 - 96: DEBUG: new connection from 127.0.0.1:45078 598s app_test.go:1202: 18:33:03.943 - 97: DEBUG: new connection from 127.0.0.1:43312 598s app_test.go:1202: 18:33:03.945 - 98: DEBUG: new connection from 127.0.0.1:38158 599s app_test.go:1202: 18:33:03.948 - 97: DEBUG: new connection from 127.0.0.1:43314 599s app_test.go:1202: 18:33:03.952 - 90: DEBUG: new connection from 127.0.0.1:56244 599s app_test.go:1202: 18:33:03.965 - 90: DEBUG: new connection from 127.0.0.1:56250 599s app_test.go:1202: 18:33:03.967 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected 599s app_test.go:1202: 18:33:03.019 - 90: DEBUG: new connection from 127.0.0.1:56254 599s app_test.go:1202: 18:33:03.023 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected 599s app_test.go:1202: 18:33:03.084 - 90: DEBUG: new connection from 127.0.0.1:56262 599s app_test.go:1202: 18:33:03.086 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected 599s app_test.go:1202: 18:33:03.214 - 90: DEBUG: new connection from 127.0.0.1:56268 599s app_test.go:1202: 18:33:03.216 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected 599s app_test.go:1202: 18:33:03.272 - 90: DEBUG: new connection from 127.0.0.1:56282 599s app_test.go:1202: 18:33:03.274 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected 599s app_test.go:1202: 18:33:03.325 - 90: DEBUG: new connection from 127.0.0.1:56294 599s app_test.go:1202: 18:33:03.327 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected 599s app_test.go:1202: 18:33:03.382 - 90: DEBUG: new connection from 127.0.0.1:56296 599s app_test.go:1202: 18:33:03.384 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected 599s app_test.go:1202: 18:33:03.395 - 90: DEBUG: new connection from 127.0.0.1:56298 599s app_test.go:1202: 18:33:03.397 - 91: DEBUG: new connection from 127.0.0.1:54812 599s app_test.go:1202: 18:33:03.399 - 92: DEBUG: new connection from 127.0.0.1:36400 599s app_test.go:1202: 18:33:03.402 - 93: DEBUG: new connection from 127.0.0.1:37900 599s app_test.go:1202: 18:33:03.405 - 95: DEBUG: new connection from 127.0.0.1:54010 599s app_test.go:1202: 18:33:03.407 - 96: DEBUG: new connection from 127.0.0.1:45094 599s app_test.go:1202: 18:33:03.409 - 97: DEBUG: new connection from 127.0.0.1:43324 599s app_test.go:1202: 18:33:03.412 - 98: DEBUG: new connection from 127.0.0.1:38166 599s app_test.go:1202: 18:33:03.416 - 90: DEBUG: new connection from 127.0.0.1:56314 599s app_test.go:1202: 18:33:03.418 - 91: DEBUG: new connection from 127.0.0.1:54826 599s app_test.go:1202: 18:33:03.422 - 92: DEBUG: new connection from 127.0.0.1:36406 599s app_test.go:1202: 18:33:03.425 - 93: DEBUG: new connection from 127.0.0.1:37908 599s app_test.go:1202: 18:33:03.428 - 95: DEBUG: new connection from 127.0.0.1:54022 599s app_test.go:1202: 18:33:03.430 - 96: DEBUG: new connection from 127.0.0.1:45108 599s app_test.go:1202: 18:33:03.432 - 97: DEBUG: new connection from 127.0.0.1:43326 599s app_test.go:1202: 18:33:03.435 - 98: DEBUG: new connection from 127.0.0.1:38172 604s app_test.go:1202: 18:33:03.970 - 90: DEBUG: new connection from 127.0.0.1:56328 604s app_test.go:1202: 18:33:03.972 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected 604s app_test.go:1202: 18:33:03.027 - 90: DEBUG: new connection from 127.0.0.1:56340 604s app_test.go:1202: 18:33:03.030 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected 604s app_test.go:1202: 18:33:03.089 - 90: DEBUG: new connection from 127.0.0.1:56342 604s app_test.go:1202: 18:33:03.092 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected 604s app_test.go:1202: 18:33:03.219 - 90: DEBUG: new connection from 127.0.0.1:56348 604s app_test.go:1202: 18:33:03.223 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected 604s app_test.go:1202: 18:33:03.277 - 90: DEBUG: new connection from 127.0.0.1:56364 604s app_test.go:1202: 18:33:03.279 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected 604s app_test.go:1202: 18:33:03.330 - 90: DEBUG: new connection from 127.0.0.1:56374 604s app_test.go:1202: 18:33:03.333 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected 604s app_test.go:1202: 18:33:03.388 - 90: DEBUG: new connection from 127.0.0.1:56390 604s app_test.go:1202: 18:33:03.390 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected 604s app_test.go:1202: 18:33:03.437 - 90: DEBUG: new connection from 127.0.0.1:56402 604s app_test.go:1202: 18:33:03.440 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 604s app_test.go:1202: 18:33:03.442 - 90: DEBUG: new connection from 127.0.0.1:56416 604s app_test.go:1202: 18:33:03.445 - 91: DEBUG: new connection from 127.0.0.1:54832 604s app_test.go:1202: 18:33:03.449 - 92: DEBUG: new connection from 127.0.0.1:36408 604s app_test.go:1202: 18:33:03.451 - 93: DEBUG: new connection from 127.0.0.1:37922 604s app_test.go:1202: 18:33:03.454 - 95: DEBUG: new connection from 127.0.0.1:54038 604s app_test.go:1202: 18:33:03.456 - 96: DEBUG: new connection from 127.0.0.1:45122 604s app_test.go:1202: 18:33:03.458 - 97: DEBUG: new connection from 127.0.0.1:43328 604s app_test.go:1202: 18:33:03.460 - 98: DEBUG: new connection from 127.0.0.1:38182 609s app_test.go:1202: 18:33:03.976 - 90: DEBUG: new connection from 127.0.0.1:47742 609s app_test.go:1202: 18:33:03.982 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected 609s app_test.go:1202: 18:33:03.033 - 90: DEBUG: new connection from 127.0.0.1:47758 609s app_test.go:1202: 18:33:03.036 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected 609s app_test.go:1202: 18:33:03.095 - 90: DEBUG: new connection from 127.0.0.1:47760 609s app_test.go:1202: 18:33:03.098 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected 609s app_test.go:1202: 18:33:03.226 - 90: DEBUG: new connection from 127.0.0.1:47776 609s app_test.go:1202: 18:33:03.228 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected 609s app_test.go:1202: 18:33:03.283 - 90: DEBUG: new connection from 127.0.0.1:47792 609s app_test.go:1202: 18:33:03.285 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected 609s app_test.go:1202: 18:33:03.336 - 90: DEBUG: new connection from 127.0.0.1:47798 609s app_test.go:1202: 18:33:03.339 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected 609s app_test.go:1202: 18:33:03.393 - 90: DEBUG: new connection from 127.0.0.1:47814 609s app_test.go:1202: 18:33:03.396 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected 609s app_test.go:1202: 18:33:03.464 - 90: DEBUG: new connection from 127.0.0.1:47828 609s app_test.go:1202: 18:33:03.467 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 609s app_test.go:1202: 18:33:03.471 - 90: DEBUG: new connection from 127.0.0.1:47836 609s app_test.go:1202: 18:33:03.473 - 91: DEBUG: new connection from 127.0.0.1:35508 609s app_test.go:1202: 18:33:03.476 - 92: DEBUG: new connection from 127.0.0.1:42332 609s app_test.go:1202: 18:33:03.479 - 93: DEBUG: new connection from 127.0.0.1:38790 609s app_test.go:1202: 18:33:03.482 - 95: DEBUG: new connection from 127.0.0.1:58558 609s app_test.go:1202: 18:33:03.484 - 96: DEBUG: new connection from 127.0.0.1:59066 609s app_test.go:1202: 18:33:03.487 - 97: DEBUG: new connection from 127.0.0.1:43990 609s app_test.go:1202: 18:33:03.490 - 98: DEBUG: new connection from 127.0.0.1:58498 614s app_test.go:1202: 18:33:03.988 - 90: DEBUG: new connection from 127.0.0.1:47848 614s app_test.go:1202: 18:33:03.992 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected 614s app_test.go:1202: 18:33:03.040 - 90: DEBUG: new connection from 127.0.0.1:47856 614s app_test.go:1202: 18:33:03.043 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected 614s app_test.go:1202: 18:33:03.101 - 90: DEBUG: new connection from 127.0.0.1:47868 614s app_test.go:1202: 18:33:03.104 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected 614s app_test.go:1202: 18:33:03.231 - 90: DEBUG: new connection from 127.0.0.1:47876 614s app_test.go:1202: 18:33:03.233 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected 614s app_test.go:1202: 18:33:03.288 - 90: DEBUG: new connection from 127.0.0.1:47888 614s app_test.go:1202: 18:33:03.291 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected 614s app_test.go:1202: 18:33:03.341 - 90: DEBUG: new connection from 127.0.0.1:47900 614s app_test.go:1202: 18:33:03.345 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected 614s app_test.go:1202: 18:33:03.400 - 90: DEBUG: new connection from 127.0.0.1:47912 614s app_test.go:1202: 18:33:03.400 - 90: DEBUG: new connection from 127.0.0.1:47928 614s app_test.go:1202: 18:33:03.406 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 614s app_test.go:1202: 18:33:03.406 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected 614s app_test.go:1202: 18:33:03.493 - 90: DEBUG: new connection from 127.0.0.1:47940 614s app_test.go:1202: 18:33:03.496 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected 614s app_test.go:1202: 18:33:03.499 - 90: WARN: adjust roles: failed to send Leader request: call leader (budget 0s): receive: header: EOF 614s --- PASS: TestRolesAdjustment_ReplaceStandByHonorFailureDomains (20.64s) 614s === RUN TestOpen 614s app_test.go:1202: 18:33:03.550 - 99: DEBUG: new connection from 127.0.0.1:53144 614s app_test.go:1202: 18:33:03.551 - 99: DEBUG: new connection from 127.0.0.1:53146 614s app_test.go:1202: 18:33:03.556 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected 614s app_test.go:1202: 18:33:03.558 - 99: DEBUG: new connection from 127.0.0.1:53156 614s app_test.go:1202: 18:33:03.560 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected 614s --- PASS: TestOpen (0.06s) 614s === RUN TestOpenDisk 614s app_test.go:1202: 18:33:03.615 - 100: DEBUG: new connection from 127.0.0.1:53172 614s app_test.go:1202: 18:33:03.616 - 100: DEBUG: new connection from 127.0.0.1:53170 614s app_test.go:1202: 18:33:03.621 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected 614s app_test.go:1202: 18:33:03.621 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected 614s app_test.go:1202: 18:33:03.625 - 100: DEBUG: new connection from 127.0.0.1:53188 614s --- PASS: TestOpenDisk (0.06s) 614s === RUN TestOptions 614s --- PASS: TestOptions (0.03s) 614s === RUN TestProxy_Error 614s app_test.go:1202: 18:33:03.698 - 102: DEBUG: new connection from 127.0.0.1:53196 614s app_test.go:1202: 18:33:03.698 - 102: DEBUG: new connection from 127.0.0.1:53200 614s app_test.go:1202: 18:33:03.702 - 102: DEBUG: attempt 1: server 127.0.0.1:9000: connected 614s app_test.go:1202: 18:33:03.704 - 102: DEBUG: new connection from 127.0.0.1:53210 615s --- PASS: TestProxy_Error (0.38s) 615s === RUN TestReady_Cancel 615s app_test.go:1202: 18:33:03.076 - 103: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 615s --- PASS: TestReady_Cancel (0.22s) 615s === RUN TestExternalConnWithTCP 615s app_test.go:1227: 18:33:03.307 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected 615s app_test.go:1227: 18:33:03.332 - 105: DEBUG: attempt 1: server 127.0.0.1:9191: connected 615s app_test.go:1227: 18:33:03.364 - 106: DEBUG: attempt 1: server 127.0.0.1:9191: connected 615s app_test.go:1227: 18:33:03.378 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected 615s --- PASS: TestExternalConnWithTCP (0.16s) 615s === RUN TestExternalConnWithPipe 615s app_test.go:1227: 18:33:03.463 - 107: DEBUG: attempt 1: server first: connected 615s app_test.go:1227: 18:33:03.484 - 108: DEBUG: attempt 1: server first: connected 615s app_test.go:1227: 18:33:03.512 - 109: DEBUG: attempt 1: server first: connected 615s app_test.go:1227: 18:33:03.523 - 107: DEBUG: attempt 1: server first: connected 615s --- PASS: TestExternalConnWithPipe (0.15s) 615s === RUN TestParallelNewApp 615s === PAUSE TestParallelNewApp 615s === RUN Test_TxRowsAffected 615s app_test.go:1227: 18:33:03.614 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected 615s app_test.go:1227: 18:33:03.616 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected 615s --- PASS: Test_TxRowsAffected (0.05s) 615s === CONT TestParallelNewApp 615s === RUN TestParallelNewApp/run-0 615s === PAUSE TestParallelNewApp/run-0 615s === RUN TestParallelNewApp/run-1 615s === PAUSE TestParallelNewApp/run-1 615s === RUN TestParallelNewApp/run-2 615s === PAUSE TestParallelNewApp/run-2 615s === RUN TestParallelNewApp/run-3 615s === PAUSE TestParallelNewApp/run-3 615s === RUN TestParallelNewApp/run-4 615s === PAUSE TestParallelNewApp/run-4 615s === RUN TestParallelNewApp/run-5 615s === PAUSE TestParallelNewApp/run-5 615s === RUN TestParallelNewApp/run-6 615s === PAUSE TestParallelNewApp/run-6 615s === RUN TestParallelNewApp/run-7 615s === PAUSE TestParallelNewApp/run-7 615s === RUN TestParallelNewApp/run-8 615s === PAUSE TestParallelNewApp/run-8 615s === RUN TestParallelNewApp/run-9 615s === PAUSE TestParallelNewApp/run-9 615s === RUN TestParallelNewApp/run-10 615s === PAUSE TestParallelNewApp/run-10 615s === RUN TestParallelNewApp/run-11 615s === PAUSE TestParallelNewApp/run-11 615s === RUN TestParallelNewApp/run-12 615s === PAUSE TestParallelNewApp/run-12 615s === RUN TestParallelNewApp/run-13 615s === PAUSE TestParallelNewApp/run-13 615s === RUN TestParallelNewApp/run-14 615s === PAUSE TestParallelNewApp/run-14 615s === RUN TestParallelNewApp/run-15 615s === PAUSE TestParallelNewApp/run-15 615s === RUN TestParallelNewApp/run-16 615s === PAUSE TestParallelNewApp/run-16 615s === RUN TestParallelNewApp/run-17 615s === PAUSE TestParallelNewApp/run-17 615s === RUN TestParallelNewApp/run-18 615s === PAUSE TestParallelNewApp/run-18 615s === RUN TestParallelNewApp/run-19 615s === PAUSE TestParallelNewApp/run-19 615s === RUN TestParallelNewApp/run-20 615s === PAUSE TestParallelNewApp/run-20 615s === RUN TestParallelNewApp/run-21 615s === PAUSE TestParallelNewApp/run-21 615s === RUN TestParallelNewApp/run-22 615s === PAUSE TestParallelNewApp/run-22 615s === RUN TestParallelNewApp/run-23 615s === PAUSE TestParallelNewApp/run-23 615s === RUN TestParallelNewApp/run-24 615s === PAUSE TestParallelNewApp/run-24 615s === RUN TestParallelNewApp/run-25 615s === PAUSE TestParallelNewApp/run-25 615s === RUN TestParallelNewApp/run-26 615s === PAUSE TestParallelNewApp/run-26 615s === RUN TestParallelNewApp/run-27 615s === PAUSE TestParallelNewApp/run-27 615s === RUN TestParallelNewApp/run-28 615s === PAUSE TestParallelNewApp/run-28 615s === RUN TestParallelNewApp/run-29 615s === PAUSE TestParallelNewApp/run-29 615s === RUN TestParallelNewApp/run-30 615s === PAUSE TestParallelNewApp/run-30 615s === RUN TestParallelNewApp/run-31 615s === PAUSE TestParallelNewApp/run-31 615s === RUN TestParallelNewApp/run-32 615s === PAUSE TestParallelNewApp/run-32 615s === RUN TestParallelNewApp/run-33 615s === PAUSE TestParallelNewApp/run-33 615s === RUN TestParallelNewApp/run-34 615s === PAUSE TestParallelNewApp/run-34 615s === RUN TestParallelNewApp/run-35 615s === PAUSE TestParallelNewApp/run-35 615s === RUN TestParallelNewApp/run-36 615s === PAUSE TestParallelNewApp/run-36 615s === RUN TestParallelNewApp/run-37 615s === PAUSE TestParallelNewApp/run-37 615s === RUN TestParallelNewApp/run-38 615s === PAUSE TestParallelNewApp/run-38 615s === RUN TestParallelNewApp/run-39 615s === PAUSE TestParallelNewApp/run-39 615s === RUN TestParallelNewApp/run-40 615s === PAUSE TestParallelNewApp/run-40 615s === RUN TestParallelNewApp/run-41 615s === PAUSE TestParallelNewApp/run-41 615s === RUN TestParallelNewApp/run-42 615s === PAUSE TestParallelNewApp/run-42 615s === RUN TestParallelNewApp/run-43 615s === PAUSE TestParallelNewApp/run-43 615s === RUN TestParallelNewApp/run-44 615s === PAUSE TestParallelNewApp/run-44 615s === RUN TestParallelNewApp/run-45 615s === PAUSE TestParallelNewApp/run-45 615s === RUN TestParallelNewApp/run-46 615s === PAUSE TestParallelNewApp/run-46 615s === RUN TestParallelNewApp/run-47 615s === PAUSE TestParallelNewApp/run-47 615s === RUN TestParallelNewApp/run-48 615s === PAUSE TestParallelNewApp/run-48 615s === RUN TestParallelNewApp/run-49 615s === PAUSE TestParallelNewApp/run-49 615s === RUN TestParallelNewApp/run-50 615s === PAUSE TestParallelNewApp/run-50 615s === RUN TestParallelNewApp/run-51 615s === PAUSE TestParallelNewApp/run-51 615s === RUN TestParallelNewApp/run-52 615s === PAUSE TestParallelNewApp/run-52 615s === RUN TestParallelNewApp/run-53 615s === PAUSE TestParallelNewApp/run-53 615s === RUN TestParallelNewApp/run-54 615s === PAUSE TestParallelNewApp/run-54 615s === RUN TestParallelNewApp/run-55 615s === PAUSE TestParallelNewApp/run-55 615s === RUN TestParallelNewApp/run-56 615s === PAUSE TestParallelNewApp/run-56 615s === RUN TestParallelNewApp/run-57 615s === PAUSE TestParallelNewApp/run-57 615s === RUN TestParallelNewApp/run-58 615s === PAUSE TestParallelNewApp/run-58 615s === RUN TestParallelNewApp/run-59 615s === PAUSE TestParallelNewApp/run-59 615s === RUN TestParallelNewApp/run-60 615s === PAUSE TestParallelNewApp/run-60 615s === RUN TestParallelNewApp/run-61 615s === PAUSE TestParallelNewApp/run-61 615s === RUN TestParallelNewApp/run-62 615s === PAUSE TestParallelNewApp/run-62 615s === RUN TestParallelNewApp/run-63 615s === PAUSE TestParallelNewApp/run-63 615s === RUN TestParallelNewApp/run-64 615s === PAUSE TestParallelNewApp/run-64 615s === RUN TestParallelNewApp/run-65 615s === PAUSE TestParallelNewApp/run-65 615s === RUN TestParallelNewApp/run-66 615s === PAUSE TestParallelNewApp/run-66 615s === RUN TestParallelNewApp/run-67 615s === PAUSE TestParallelNewApp/run-67 615s === RUN TestParallelNewApp/run-68 615s === PAUSE TestParallelNewApp/run-68 615s === RUN TestParallelNewApp/run-69 615s === PAUSE TestParallelNewApp/run-69 615s === RUN TestParallelNewApp/run-70 615s === PAUSE TestParallelNewApp/run-70 615s === RUN TestParallelNewApp/run-71 615s === PAUSE TestParallelNewApp/run-71 615s === RUN TestParallelNewApp/run-72 615s === PAUSE TestParallelNewApp/run-72 615s === RUN TestParallelNewApp/run-73 615s === PAUSE TestParallelNewApp/run-73 615s === RUN TestParallelNewApp/run-74 615s === PAUSE TestParallelNewApp/run-74 615s === RUN TestParallelNewApp/run-75 615s === PAUSE TestParallelNewApp/run-75 615s === RUN TestParallelNewApp/run-76 615s === PAUSE TestParallelNewApp/run-76 615s === RUN TestParallelNewApp/run-77 615s === PAUSE TestParallelNewApp/run-77 615s === RUN TestParallelNewApp/run-78 615s === PAUSE TestParallelNewApp/run-78 615s === RUN TestParallelNewApp/run-79 615s === PAUSE TestParallelNewApp/run-79 615s === RUN TestParallelNewApp/run-80 615s === PAUSE TestParallelNewApp/run-80 615s === RUN TestParallelNewApp/run-81 615s === PAUSE TestParallelNewApp/run-81 615s === RUN TestParallelNewApp/run-82 615s === PAUSE TestParallelNewApp/run-82 615s === RUN TestParallelNewApp/run-83 615s === PAUSE TestParallelNewApp/run-83 615s === RUN TestParallelNewApp/run-84 615s === PAUSE TestParallelNewApp/run-84 615s === RUN TestParallelNewApp/run-85 615s === PAUSE TestParallelNewApp/run-85 615s === RUN TestParallelNewApp/run-86 615s === PAUSE TestParallelNewApp/run-86 615s === RUN TestParallelNewApp/run-87 615s === PAUSE TestParallelNewApp/run-87 615s === RUN TestParallelNewApp/run-88 615s === PAUSE TestParallelNewApp/run-88 615s === RUN TestParallelNewApp/run-89 615s === PAUSE TestParallelNewApp/run-89 615s === RUN TestParallelNewApp/run-90 615s === PAUSE TestParallelNewApp/run-90 615s === RUN TestParallelNewApp/run-91 615s === PAUSE TestParallelNewApp/run-91 615s === RUN TestParallelNewApp/run-92 615s === PAUSE TestParallelNewApp/run-92 615s === RUN TestParallelNewApp/run-93 615s === PAUSE TestParallelNewApp/run-93 615s === RUN TestParallelNewApp/run-94 615s === PAUSE TestParallelNewApp/run-94 615s === RUN TestParallelNewApp/run-95 615s === PAUSE TestParallelNewApp/run-95 615s === RUN TestParallelNewApp/run-96 615s === PAUSE TestParallelNewApp/run-96 615s === RUN TestParallelNewApp/run-97 615s === PAUSE TestParallelNewApp/run-97 615s === RUN TestParallelNewApp/run-98 615s === PAUSE TestParallelNewApp/run-98 615s === RUN TestParallelNewApp/run-99 615s === PAUSE TestParallelNewApp/run-99 615s === CONT TestParallelNewApp/run-0 615s === CONT TestParallelNewApp/run-99 615s === CONT TestParallelNewApp/run-98 615s === CONT TestParallelNewApp/run-97 615s === CONT TestParallelNewApp/run-96 615s === CONT TestParallelNewApp/run-95 615s === CONT TestParallelNewApp/run-94 615s === CONT TestParallelNewApp/run-93 615s === CONT TestParallelNewApp/run-92 615s === CONT TestParallelNewApp/run-91 615s === CONT TestParallelNewApp/run-90 615s === CONT TestParallelNewApp/run-89 615s === CONT TestParallelNewApp/run-88 616s === CONT TestParallelNewApp/run-87 616s === CONT TestParallelNewApp/run-86 616s === CONT TestParallelNewApp/run-85 616s === CONT TestParallelNewApp/run-84 616s === CONT TestParallelNewApp/run-83 616s === CONT TestParallelNewApp/run-82 616s === CONT TestParallelNewApp/run-81 616s === CONT TestParallelNewApp/run-80 616s === CONT TestParallelNewApp/run-79 616s === CONT TestParallelNewApp/run-78 616s === CONT TestParallelNewApp/run-77 616s === CONT TestParallelNewApp/run-76 616s === CONT TestParallelNewApp/run-75 616s === CONT TestParallelNewApp/run-74 616s === CONT TestParallelNewApp/run-73 616s === CONT TestParallelNewApp/run-72 616s === CONT TestParallelNewApp/run-71 616s === CONT TestParallelNewApp/run-70 616s === CONT TestParallelNewApp/run-69 616s === CONT TestParallelNewApp/run-68 616s === CONT TestParallelNewApp/run-67 616s === CONT TestParallelNewApp/run-66 616s === CONT TestParallelNewApp/run-65 616s === CONT TestParallelNewApp/run-64 616s === CONT TestParallelNewApp/run-63 616s === CONT TestParallelNewApp/run-62 616s === CONT TestParallelNewApp/run-61 616s === CONT TestParallelNewApp/run-60 616s === CONT TestParallelNewApp/run-59 616s === CONT TestParallelNewApp/run-58 616s === CONT TestParallelNewApp/run-57 616s === CONT TestParallelNewApp/run-56 616s === CONT TestParallelNewApp/run-55 616s === CONT TestParallelNewApp/run-54 616s === CONT TestParallelNewApp/run-53 616s === CONT TestParallelNewApp/run-52 616s === CONT TestParallelNewApp/run-51 616s === CONT TestParallelNewApp/run-50 616s === CONT TestParallelNewApp/run-49 616s === CONT TestParallelNewApp/run-48 616s === CONT TestParallelNewApp/run-47 617s === CONT TestParallelNewApp/run-46 617s === CONT TestParallelNewApp/run-45 617s === CONT TestParallelNewApp/run-44 617s === CONT TestParallelNewApp/run-43 617s === CONT TestParallelNewApp/run-42 617s === CONT TestParallelNewApp/run-41 617s === CONT TestParallelNewApp/run-40 617s === CONT TestParallelNewApp/run-39 617s === CONT TestParallelNewApp/run-38 617s === CONT TestParallelNewApp/run-37 617s === CONT TestParallelNewApp/run-36 617s === CONT TestParallelNewApp/run-35 617s === CONT TestParallelNewApp/run-34 617s === CONT TestParallelNewApp/run-33 617s === CONT TestParallelNewApp/run-32 617s === CONT TestParallelNewApp/run-31 617s === CONT TestParallelNewApp/run-30 617s === CONT TestParallelNewApp/run-29 617s === CONT TestParallelNewApp/run-28 617s === CONT TestParallelNewApp/run-27 617s === CONT TestParallelNewApp/run-26 617s === CONT TestParallelNewApp/run-25 617s === CONT TestParallelNewApp/run-24 617s === CONT TestParallelNewApp/run-23 617s === CONT TestParallelNewApp/run-22 617s === CONT TestParallelNewApp/run-21 617s === CONT TestParallelNewApp/run-20 617s === CONT TestParallelNewApp/run-19 617s === CONT TestParallelNewApp/run-18 617s === CONT TestParallelNewApp/run-17 617s === CONT TestParallelNewApp/run-16 617s === CONT TestParallelNewApp/run-15 617s === CONT TestParallelNewApp/run-14 617s === CONT TestParallelNewApp/run-13 617s === CONT TestParallelNewApp/run-12 617s === CONT TestParallelNewApp/run-11 617s === CONT TestParallelNewApp/run-10 617s === CONT TestParallelNewApp/run-9 617s === CONT TestParallelNewApp/run-8 618s === CONT TestParallelNewApp/run-7 618s === CONT TestParallelNewApp/run-6 618s === CONT TestParallelNewApp/run-5 618s === CONT TestParallelNewApp/run-4 618s === CONT TestParallelNewApp/run-3 618s === CONT TestParallelNewApp/run-2 618s === CONT TestParallelNewApp/run-1 618s --- PASS: TestParallelNewApp (0.00s) 618s --- PASS: TestParallelNewApp/run-0 (0.03s) 618s --- PASS: TestParallelNewApp/run-99 (0.02s) 618s --- PASS: TestParallelNewApp/run-98 (0.03s) 618s --- PASS: TestParallelNewApp/run-97 (0.02s) 618s --- PASS: TestParallelNewApp/run-96 (0.03s) 618s --- PASS: TestParallelNewApp/run-95 (0.02s) 618s --- PASS: TestParallelNewApp/run-94 (0.03s) 618s --- PASS: TestParallelNewApp/run-93 (0.02s) 618s --- PASS: TestParallelNewApp/run-92 (0.02s) 618s --- PASS: TestParallelNewApp/run-91 (0.02s) 618s --- PASS: TestParallelNewApp/run-90 (0.03s) 618s --- PASS: TestParallelNewApp/run-89 (0.02s) 618s --- PASS: TestParallelNewApp/run-88 (0.02s) 618s --- PASS: TestParallelNewApp/run-87 (0.03s) 618s --- PASS: TestParallelNewApp/run-86 (0.02s) 618s --- PASS: TestParallelNewApp/run-85 (0.02s) 618s --- PASS: TestParallelNewApp/run-84 (0.02s) 618s --- PASS: TestParallelNewApp/run-83 (0.02s) 618s --- PASS: TestParallelNewApp/run-82 (0.03s) 618s --- PASS: TestParallelNewApp/run-81 (0.02s) 618s --- PASS: TestParallelNewApp/run-80 (0.03s) 618s --- PASS: TestParallelNewApp/run-79 (0.02s) 618s --- PASS: TestParallelNewApp/run-78 (0.02s) 618s --- PASS: TestParallelNewApp/run-77 (0.03s) 618s --- PASS: TestParallelNewApp/run-76 (0.03s) 618s --- PASS: TestParallelNewApp/run-75 (0.02s) 618s --- PASS: TestParallelNewApp/run-74 (0.02s) 618s --- PASS: TestParallelNewApp/run-73 (0.02s) 618s --- PASS: TestParallelNewApp/run-72 (0.02s) 618s --- PASS: TestParallelNewApp/run-71 (0.02s) 618s --- PASS: TestParallelNewApp/run-70 (0.02s) 618s --- PASS: TestParallelNewApp/run-69 (0.03s) 618s --- PASS: TestParallelNewApp/run-68 (0.02s) 618s --- PASS: TestParallelNewApp/run-67 (0.02s) 618s --- PASS: TestParallelNewApp/run-66 (0.02s) 618s --- PASS: TestParallelNewApp/run-65 (0.02s) 618s --- PASS: TestParallelNewApp/run-64 (0.02s) 618s --- PASS: TestParallelNewApp/run-63 (0.02s) 618s --- PASS: TestParallelNewApp/run-62 (0.02s) 618s --- PASS: TestParallelNewApp/run-61 (0.03s) 618s --- PASS: TestParallelNewApp/run-60 (0.03s) 618s --- PASS: TestParallelNewApp/run-59 (0.02s) 618s --- PASS: TestParallelNewApp/run-58 (0.03s) 618s --- PASS: TestParallelNewApp/run-57 (0.02s) 618s --- PASS: TestParallelNewApp/run-56 (0.02s) 618s --- PASS: TestParallelNewApp/run-55 (0.02s) 618s --- PASS: TestParallelNewApp/run-54 (0.02s) 618s --- PASS: TestParallelNewApp/run-53 (0.02s) 618s --- PASS: TestParallelNewApp/run-52 (0.03s) 618s --- PASS: TestParallelNewApp/run-51 (0.02s) 618s --- PASS: TestParallelNewApp/run-50 (0.02s) 618s --- PASS: TestParallelNewApp/run-49 (0.02s) 618s --- PASS: TestParallelNewApp/run-48 (0.02s) 618s --- PASS: TestParallelNewApp/run-47 (0.03s) 618s --- PASS: TestParallelNewApp/run-46 (0.03s) 618s --- PASS: TestParallelNewApp/run-45 (0.02s) 618s --- PASS: TestParallelNewApp/run-44 (0.03s) 618s --- PASS: TestParallelNewApp/run-43 (0.02s) 618s --- PASS: TestParallelNewApp/run-42 (0.03s) 618s --- PASS: TestParallelNewApp/run-41 (0.02s) 618s --- PASS: TestParallelNewApp/run-40 (0.03s) 618s --- PASS: TestParallelNewApp/run-39 (0.02s) 618s --- PASS: TestParallelNewApp/run-38 (0.03s) 618s --- PASS: TestParallelNewApp/run-37 (0.02s) 618s --- PASS: TestParallelNewApp/run-36 (0.02s) 618s --- PASS: TestParallelNewApp/run-35 (0.02s) 618s --- PASS: TestParallelNewApp/run-34 (0.03s) 618s --- PASS: TestParallelNewApp/run-33 (0.02s) 618s --- PASS: TestParallelNewApp/run-32 (0.03s) 618s --- PASS: TestParallelNewApp/run-31 (0.02s) 618s --- PASS: TestParallelNewApp/run-30 (0.02s) 618s --- PASS: TestParallelNewApp/run-29 (0.02s) 618s --- PASS: TestParallelNewApp/run-28 (0.02s) 618s --- PASS: TestParallelNewApp/run-27 (0.02s) 618s --- PASS: TestParallelNewApp/run-26 (0.03s) 618s --- PASS: TestParallelNewApp/run-25 (0.02s) 618s --- PASS: TestParallelNewApp/run-24 (0.03s) 618s --- PASS: TestParallelNewApp/run-23 (0.02s) 618s --- PASS: TestParallelNewApp/run-22 (0.03s) 618s --- PASS: TestParallelNewApp/run-21 (0.02s) 618s --- PASS: TestParallelNewApp/run-20 (0.03s) 618s --- PASS: TestParallelNewApp/run-19 (0.02s) 618s --- PASS: TestParallelNewApp/run-18 (0.02s) 618s --- PASS: TestParallelNewApp/run-17 (0.02s) 618s --- PASS: TestParallelNewApp/run-16 (0.03s) 618s --- PASS: TestParallelNewApp/run-15 (0.02s) 618s --- PASS: TestParallelNewApp/run-14 (0.02s) 618s --- PASS: TestParallelNewApp/run-13 (0.03s) 618s --- PASS: TestParallelNewApp/run-12 (0.02s) 618s --- PASS: TestParallelNewApp/run-11 (0.03s) 618s --- PASS: TestParallelNewApp/run-10 (0.03s) 618s --- PASS: TestParallelNewApp/run-9 (0.02s) 618s --- PASS: TestParallelNewApp/run-8 (0.02s) 618s --- PASS: TestParallelNewApp/run-7 (0.02s) 618s --- PASS: TestParallelNewApp/run-6 (0.03s) 618s --- PASS: TestParallelNewApp/run-5 (0.03s) 618s --- PASS: TestParallelNewApp/run-4 (0.02s) 618s --- PASS: TestParallelNewApp/run-3 (0.02s) 618s --- PASS: TestParallelNewApp/run-2 (0.02s) 618s --- PASS: TestParallelNewApp/run-1 (0.03s) 618s === RUN Example 618s --- PASS: Example (0.05s) 618s PASS 618s ok github.com/canonical/go-dqlite/app 124.830s 619s === RUN TestNew_Default 620s Benchmark done. Results available here: 620s /tmp/dqlite-app-test-1551767707/results 620s --- PASS: TestNew_Default (1.06s) 620s === RUN TestNew_KvReadWrite 621s Benchmark done. Results available here: 621s /tmp/dqlite-app-test-3064179910/results 621s --- PASS: TestNew_KvReadWrite (1.07s) 621s === RUN TestNew_ClusteredKvReadWrite 623s Benchmark done. Results available here: 623s /tmp/dqlite-app-test-3801503320/results 623s --- PASS: TestNew_ClusteredKvReadWrite (2.14s) 623s === RUN TestNew_ClusteredTimeout 625s --- PASS: TestNew_ClusteredTimeout (2.06s) 625s PASS 625s ok github.com/canonical/go-dqlite/benchmark 6.347s 626s === RUN TestClient_Leader 626s --- PASS: TestClient_Leader (0.03s) 626s === RUN TestClient_Dump 626s --- PASS: TestClient_Dump (0.05s) 626s === RUN TestClient_Cluster 626s --- PASS: TestClient_Cluster (0.03s) 626s === RUN TestClient_Transfer 626s --- PASS: TestClient_Transfer (0.10s) 626s === RUN TestClient_Describe 626s --- PASS: TestClient_Describe (0.03s) 626s === RUN TestMembership 626s --- PASS: TestMembership (0.13s) 626s === RUN TestDefaultNodeStore 626s --- PASS: TestDefaultNodeStore (0.00s) 626s === RUN TestConfigMultiThread 626s --- PASS: TestConfigMultiThread (0.00s) 626s PASS 626s ok github.com/canonical/go-dqlite/client 0.370s 629s ? github.com/canonical/go-dqlite/cmd/dqlite [no test files] 629s ? github.com/canonical/go-dqlite/cmd/dqlite-benchmark [no test files] 629s ? github.com/canonical/go-dqlite/cmd/dqlite-demo [no test files] 630s === RUN TestDriver_Open 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestDriver_Open (0.03s) 630s === RUN TestDriver_Prepare 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestDriver_Prepare (0.03s) 630s === RUN TestConn_Exec 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestConn_Exec (0.03s) 630s === RUN TestConn_Query 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestConn_Query (0.03s) 630s === RUN TestConn_QueryRow 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestConn_QueryRow (0.03s) 630s === RUN TestConn_QueryBlob 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestConn_QueryBlob (0.03s) 630s === RUN TestStmt_Exec 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestStmt_Exec (0.03s) 630s === RUN TestStmt_ExecManyParams 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestStmt_ExecManyParams (0.03s) 630s === RUN TestStmt_Query 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestStmt_Query (0.03s) 630s === RUN TestStmt_QueryManyParams 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestStmt_QueryManyParams (0.03s) 630s === RUN TestConn_QueryParams 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestConn_QueryParams (0.03s) 630s === RUN TestConn_QueryManyParams 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestConn_QueryManyParams (0.03s) 630s === RUN TestConn_ExecManyParams 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestConn_ExecManyParams (0.03s) 630s === RUN Test_ColumnTypesEmpty 630s driver_test.go:439: this currently fails if the result set is empty, is dqlite skipping the header if empty set? 630s --- SKIP: Test_ColumnTypesEmpty (0.00s) 630s === RUN Test_ColumnTypesExists 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: Test_ColumnTypesExists (0.03s) 630s === RUN Test_ColumnTypesEnd 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: Test_ColumnTypesEnd (0.03s) 630s === RUN Test_ZeroColumns 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: Test_ZeroColumns (0.05s) 630s === RUN TestIntegration_DatabaseSQL 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestIntegration_DatabaseSQL (0.16s) 630s === RUN TestIntegration_ConstraintError 630s func.go:15: DEBUG: attempt 1: server @1: connected 630s --- PASS: TestIntegration_ConstraintError (0.15s) 630s === RUN TestIntegration_ExecBindError 631s func.go:15: DEBUG: attempt 1: server @1: connected 631s --- PASS: TestIntegration_ExecBindError (0.05s) 631s === RUN TestIntegration_QueryBindError 631s func.go:15: DEBUG: attempt 1: server @1: connected 631s --- PASS: TestIntegration_QueryBindError (0.03s) 631s === RUN TestIntegration_LargeQuery 631s func.go:15: DEBUG: attempt 1: server @1: connected 631s --- PASS: TestIntegration_LargeQuery (0.18s) 631s === RUN TestIntegration_Recover 631s func.go:15: DEBUG: attempt 1: server @1: connected 631s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 631s func.go:15: DEBUG: attempt 1: server @1: connected 631s --- PASS: TestIntegration_Recover (0.15s) 631s === RUN TestIntegration_PingOnlyWorksOnceLeaderElected 631s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 631s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 631s func.go:15: WARN: attempt 1: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused 631s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 631s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 631s func.go:15: WARN: attempt 2: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused 632s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 632s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 632s func.go:15: WARN: attempt 3: server @2: reported leader unavailable err=dial: dial unix @1: connect: connection refused 633s func.go:15: WARN: attempt 1: server @1: no known leader 633s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 633s func.go:15: WARN: attempt 1: server @2: reported leader server is not the leader 633s func.go:15: WARN: attempt 2: server @1: no known leader 633s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 633s func.go:15: WARN: attempt 2: server @2: reported leader server is not the leader 633s func.go:15: WARN: attempt 3: server @1: no known leader 633s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 633s func.go:15: WARN: attempt 3: server @2: reported leader server is not the leader 634s func.go:15: WARN: attempt 4: server @1: no known leader 634s func.go:15: DEBUG: attempt 4: server @2: connect to reported leader @1 634s func.go:15: WARN: attempt 4: server @2: reported leader server is not the leader 635s func.go:15: WARN: attempt 5: server @1: no known leader 635s func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @1 635s func.go:15: WARN: attempt 5: server @2: reported leader server is not the leader 636s func.go:15: WARN: attempt 6: server @1: no known leader 636s func.go:15: DEBUG: attempt 6: server @2: connect to reported leader @1 636s func.go:15: WARN: attempt 6: server @2: reported leader server is not the leader 637s func.go:15: WARN: attempt 7: server @1: no known leader 637s func.go:15: DEBUG: attempt 7: server @2: connect to reported leader @1 637s func.go:15: WARN: attempt 7: server @2: reported leader server is not the leader 638s func.go:15: DEBUG: attempt 8: server @1: connected 638s --- PASS: TestIntegration_PingOnlyWorksOnceLeaderElected (6.96s) 638s === RUN TestIntegration_HighAvailability 638s func.go:15: DEBUG: attempt 1: server @1: connected 640s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 640s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 640s func.go:15: WARN: attempt 1: server @2: no known leader 640s func.go:15: WARN: attempt 1: server @3: no known leader 640s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 640s func.go:15: WARN: attempt 2: server @2: no known leader 640s func.go:15: WARN: attempt 2: server @3: no known leader 641s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 641s func.go:15: WARN: attempt 3: server @2: no known leader 641s func.go:15: WARN: attempt 3: server @3: no known leader 641s func.go:15: WARN: attempt 4: server @1: dial: dial unix @1: connect: connection refused 641s func.go:15: WARN: attempt 4: server @2: no known leader 641s func.go:15: WARN: attempt 4: server @3: no known leader 642s func.go:15: WARN: attempt 5: server @1: dial: dial unix @1: connect: connection refused 642s func.go:15: WARN: attempt 5: server @2: no known leader 642s func.go:15: WARN: attempt 5: server @3: no known leader 643s func.go:15: WARN: attempt 6: server @1: dial: dial unix @1: connect: connection refused 643s func.go:15: DEBUG: attempt 6: server @2: connected 644s --- PASS: TestIntegration_HighAvailability (5.67s) 644s === RUN TestIntegration_LeadershipTransfer 644s func.go:15: DEBUG: attempt 1: server @1: connected 644s func.go:15: DEBUG: leadership lost (10250 - not leader) 644s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 644s func.go:15: DEBUG: attempt 1: server @1: connected 644s --- PASS: TestIntegration_LeadershipTransfer (0.16s) 644s === RUN TestIntegration_LeadershipTransfer_Tx 644s func.go:15: DEBUG: attempt 1: server @1: connected 644s func.go:15: DEBUG: leadership lost (10250 - not leader) 644s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 644s func.go:15: DEBUG: attempt 1: server @1: connected 644s --- PASS: TestIntegration_LeadershipTransfer_Tx (0.16s) 644s === RUN TestOptions 644s --- PASS: TestOptions (0.00s) 644s === RUN TestIntegration_ColumnTypeName 644s func.go:15: DEBUG: attempt 1: server @1: connected 644s --- PASS: TestIntegration_ColumnTypeName (0.05s) 644s === RUN TestIntegration_SqlNullTime 644s func.go:15: DEBUG: attempt 1: server @1: connected 644s --- PASS: TestIntegration_SqlNullTime (0.05s) 644s PASS 644s ok github.com/canonical/go-dqlite/driver 14.247s 644s === RUN TestNode_Create 644s --- PASS: TestNode_Create (0.02s) 644s === RUN TestNode_Start 644s --- PASS: TestNode_Start (0.03s) 644s === RUN TestNode_Restart 645s --- PASS: TestNode_Restart (0.05s) 645s === RUN TestNode_Start_Inet 645s --- PASS: TestNode_Start_Inet (0.03s) 645s === RUN TestNode_Leader 645s --- PASS: TestNode_Leader (0.03s) 645s === RUN TestNode_Autorecovery 645s --- PASS: TestNode_Autorecovery (0.02s) 645s PASS 645s ok github.com/canonical/go-dqlite/internal/bindings 0.186s 647s === RUN TestMessage_StaticBytesAlignment 647s --- PASS: TestMessage_StaticBytesAlignment (0.00s) 647s === RUN TestMessage_putBlob 647s === RUN TestMessage_putBlob/16 647s === RUN TestMessage_putBlob/16#01 647s === RUN TestMessage_putBlob/24 647s --- PASS: TestMessage_putBlob (0.00s) 647s --- PASS: TestMessage_putBlob/16 (0.00s) 647s --- PASS: TestMessage_putBlob/16#01 (0.00s) 647s --- PASS: TestMessage_putBlob/24 (0.00s) 647s === RUN TestMessage_putString 647s === RUN TestMessage_putString/hello 647s === RUN TestMessage_putString/hello!! 647s === RUN TestMessage_putString/hello_world 647s --- PASS: TestMessage_putString (0.00s) 647s --- PASS: TestMessage_putString/hello (0.00s) 647s --- PASS: TestMessage_putString/hello!! (0.00s) 647s --- PASS: TestMessage_putString/hello_world (0.00s) 647s === RUN TestMessage_putUint8 647s --- PASS: TestMessage_putUint8 (0.00s) 647s === RUN TestMessage_putUint16 647s --- PASS: TestMessage_putUint16 (0.00s) 647s === RUN TestMessage_putUint32 647s --- PASS: TestMessage_putUint32 (0.00s) 647s === RUN TestMessage_putUint64 647s --- PASS: TestMessage_putUint64 (0.00s) 647s === RUN TestMessage_putNamedValues 647s --- PASS: TestMessage_putNamedValues (0.00s) 647s === RUN TestMessage_putNamedValues32 647s --- PASS: TestMessage_putNamedValues32 (0.00s) 647s === RUN TestMessage_putHeader 647s --- PASS: TestMessage_putHeader (0.00s) 647s === RUN TestMessage_getString 647s === RUN TestMessage_getString/hello 647s === RUN TestMessage_getString/hello!! 647s === RUN TestMessage_getString/hello!!! 647s === RUN TestMessage_getString/hello_world 647s --- PASS: TestMessage_getString (0.00s) 647s --- PASS: TestMessage_getString/hello (0.00s) 647s --- PASS: TestMessage_getString/hello!! (0.00s) 647s --- PASS: TestMessage_getString/hello!!! (0.00s) 647s --- PASS: TestMessage_getString/hello_world (0.00s) 647s === RUN TestMessage_getBlob 647s === RUN TestMessage_getBlob/16 647s === RUN TestMessage_getBlob/16#01 647s === RUN TestMessage_getBlob/24 647s --- PASS: TestMessage_getBlob (0.00s) 647s --- PASS: TestMessage_getBlob/16 (0.00s) 647s --- PASS: TestMessage_getBlob/16#01 (0.00s) 647s --- PASS: TestMessage_getBlob/24 (0.00s) 647s === RUN TestMessage_getString_Overflow_WordBoundary 647s --- PASS: TestMessage_getString_Overflow_WordBoundary (0.00s) 647s === RUN TestConnector_Success 647s connector_test.go:304: DEBUG: attempt 1: server @test-0: connected 647s --- PASS: TestConnector_Success (0.03s) 647s === RUN TestConnector_LimitRetries 647s connector_test.go:304: WARN: attempt 1: server @test-123: dial: dial unix @test-123: connect: connection refused 647s connector_test.go:304: WARN: attempt 2: server @test-123: dial: dial unix @test-123: connect: connection refused 647s connector_test.go:304: WARN: attempt 3: server @test-123: dial: dial unix @test-123: connect: connection refused 647s --- PASS: TestConnector_LimitRetries (0.60s) 647s === RUN TestConnector_DialTimeout 647s connector_test.go:64: Skipping network test 647s --- SKIP: TestConnector_DialTimeout (0.00s) 647s === RUN TestConnector_EmptyNodeStore 647s --- PASS: TestConnector_EmptyNodeStore (0.20s) 647s === RUN TestConnector_ContextCanceled 647s connector_test.go:99: Skipping network test 647s --- SKIP: TestConnector_ContextCanceled (0.00s) 647s === RUN TestConnector_AttemptTimeout 647s func.go:15: WARN: attempt 1: server @1234: call leader (budget 99.828247ms): receive: header: read unix @->@1234: i/o timeout 648s func.go:15: WARN: attempt 2: server @1234: call leader (budget 99.91985ms): receive: header: read unix @->@1234: i/o timeout 648s --- PASS: TestConnector_AttemptTimeout (0.40s) 648s === RUN TestProtocol_RequestWithDynamicBuffer 648s func.go:15: DEBUG: attempt 1: server @test-0: connected 648s --- PASS: TestProtocol_RequestWithDynamicBuffer (0.05s) 648s === RUN TestProtocol_Prepare 648s func.go:15: DEBUG: attempt 1: server @test-0: connected 648s --- PASS: TestProtocol_Prepare (0.03s) 648s PASS 648s ok github.com/canonical/go-dqlite/internal/protocol 1.320s 648s ? github.com/canonical/go-dqlite/internal/shell [no test files] 648s === RUN Test_TestFunc 648s func.go:15: INFO: hello 648s --- PASS: Test_TestFunc (0.00s) 648s === RUN TestLevel_String 648s --- PASS: TestLevel_String (0.00s) 648s PASS 648s ok github.com/canonical/go-dqlite/logging 0.002s 648s ? github.com/canonical/go-dqlite/tracing [no test files] 649s autopkgtest [18:34:05]: test dh-golang-autopkgtest: -----------------------] 651s autopkgtest [18:34:07]: test dh-golang-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 651s dh-golang-autopkgtest PASS 651s autopkgtest [18:34:07]: @@@@@@@@@@@@@@@@@@@@ summary 651s dh-golang-autopkgtest PASS 664s Creating nova instance adt-noble-ppc64el-golang-github-canonical-go-dqlite-20240319-182316-juju-7f2275-prod-proposed-migration-environment-2 from image adt/ubuntu-noble-ppc64el-server-20240319.img (UUID 6e7a6c13-d651-45a1-a24f-48d9d59effd9)...