0s autopkgtest [03:07:57]: starting date and time: 2025-10-29 03:07:57+0000 0s autopkgtest [03:07:57]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [03:07:57]: host juju-7f2275-prod-proposed-migration-environment-2; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work.2_m2drjw/out --timeout-copy=6000 --setup-commands /home/ubuntu/autopkgtest-cloud/worker-config-production/setup-canonical.sh --apt-pocket=proposed=src:golang-github-spf13-cobra,src:go-md2man-v2,src:golang-github-spf13-pflag --apt-upgrade golang-github-canonical-go-dqlite --timeout-short=300 --timeout-copy=20000 --timeout-build=20000 '--env=ADT_TEST_TRIGGERS=golang-github-spf13-cobra/1.10.1-1 go-md2man-v2/2.0.7-1 golang-github-spf13-pflag/1.0.10-1' -- ssh -s /home/ubuntu/autopkgtest/ssh-setup/nova -- --flavor autopkgtest-s390x --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-2@bos03-s390x-4.secgroup --name adt-resolute-s390x-golang-github-canonical-go-dqlite-20251029-030757-juju-7f2275-prod-proposed-migration-environment-2-1742520f-4634-4163-b50b-e566d2a1d7d1 --image adt/ubuntu-resolute-s390x-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-2 --net-id=net_prod-proposed-migration-s390x -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-s390x-golang-github-canonical-go-dqlite-20251029-030757-juju-7f2275-prod-proposed-migration-environment-2-1742520f-4634-4163-b50b-e566d2a1d7d1 from image adt/ubuntu-resolute-s390x-server-20251028.img (UUID 222a8a17-8cdf-422e-baeb-91db113eb3ff)... 50s autopkgtest [03:08:47]: testbed dpkg architecture: s390x 50s autopkgtest [03:08:47]: testbed apt version: 3.1.8ubuntu1 51s autopkgtest [03:08:48]: @@@@@@@@@@@@@@@@@@@@ test bed setup 51s autopkgtest [03:08:48]: testbed release detected to be: None 52s autopkgtest [03:08:49]: updating testbed package index (apt update) 52s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 52s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 52s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 52s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 53s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [70.0 kB] 53s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5856 B] 53s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [3625 kB] 54s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [203 kB] 54s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main s390x Packages [247 kB] 54s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted s390x Packages [940 B] 54s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x Packages [1754 kB] 54s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse s390x Packages [20.3 kB] 54s Fetched 6013 kB in 2s (2552 kB/s) 55s Reading package lists... 56s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 56s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 56s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 56s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 57s Reading package lists... 57s Reading package lists... 57s Building dependency tree... 57s Reading state information... 57s Calculating upgrade... 57s The following packages will be upgraded: 57s apt cloud-init cloud-init-base libapt-pkg7.0 python3-blinker 57s python3-lazr.uri 57s 6 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 57s Need to get 3264 kB of archives. 57s After this operation, 124 kB of additional disk space will be used. 57s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x libapt-pkg7.0 s390x 3.1.11 [1150 kB] 58s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x apt s390x 3.1.11 [1462 kB] 59s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x cloud-init-base all 25.4~1gcb12e00e-0ubuntu1 [625 kB] 59s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x cloud-init all 25.4~1gcb12e00e-0ubuntu1 [2114 B] 59s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x python3-blinker all 1.9.0-2 [10.8 kB] 59s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x python3-lazr.uri all 1.0.6-7 [13.8 kB] 59s dpkg-preconfigure: unable to re-open stdin: No such file or directory 59s Fetched 3264 kB in 2s (1662 kB/s) 59s (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 ... 56948 files and directories currently installed.) 59s Preparing to unpack .../0-libapt-pkg7.0_3.1.11_s390x.deb ... 59s Unpacking libapt-pkg7.0:s390x (3.1.11) over (3.1.8ubuntu1) ... 60s Preparing to unpack .../1-apt_3.1.11_s390x.deb ... 60s Unpacking apt (3.1.11) over (3.1.8ubuntu1) ... 60s Preparing to unpack .../2-cloud-init-base_25.4~1gcb12e00e-0ubuntu1_all.deb ... 60s Unpacking cloud-init-base (25.4~1gcb12e00e-0ubuntu1) over (25.3-0ubuntu1) ... 60s Preparing to unpack .../3-cloud-init_25.4~1gcb12e00e-0ubuntu1_all.deb ... 60s Unpacking cloud-init (25.4~1gcb12e00e-0ubuntu1) over (25.3-0ubuntu1) ... 60s Preparing to unpack .../4-python3-blinker_1.9.0-2_all.deb ... 60s Unpacking python3-blinker (1.9.0-2) over (1.9.0-1) ... 60s Preparing to unpack .../5-python3-lazr.uri_1.0.6-7_all.deb ... 60s Unpacking python3-lazr.uri (1.0.6-7) over (1.0.6-6) ... 60s Setting up python3-lazr.uri (1.0.6-7) ... 60s Setting up cloud-init-base (25.4~1gcb12e00e-0ubuntu1) ... 60s Encountered debconf setting for cloud-init-base/datasources. 61s Setting up libapt-pkg7.0:s390x (3.1.11) ... 61s Setting up python3-blinker (1.9.0-2) ... 61s Setting up apt (3.1.11) ... 61s Installing new version of config file /etc/apt/apt.conf.d/01-vendor-ubuntu ... 62s Setting up cloud-init (25.4~1gcb12e00e-0ubuntu1) ... 62s Processing triggers for rsyslog (8.2504.0-1ubuntu2) ... 62s Processing triggers for man-db (2.13.1-1) ... 63s Processing triggers for libc-bin (2.42-0ubuntu3) ... 63s autopkgtest [03:09:00]: upgrading testbed (apt dist-upgrade and autopurge) 65s Reading package lists... 65s Building dependency tree... 65s Reading state information... 65s Calculating upgrade... 65s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Solving dependencies... 66s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 66s autopkgtest [03:09:03]: rebooting testbed after setup commands that affected boot 79s autopkgtest [03:09:16]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP Mon Sep 22 08:56:47 UTC 2025 82s autopkgtest [03:09:19]: @@@@@@@@@@@@@@@@@@@@ apt-source golang-github-canonical-go-dqlite 84s Get:1 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.0-1 (dsc) [2755 B] 84s Get:2 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.0-1 (tar) [96.2 kB] 84s Get:3 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.0-1 (diff) [4496 B] 84s gpgv: Signature made Thu Oct 31 23:23:45 2024 UTC 84s gpgv: using RSA key D41A7AD07DF6C5FCA7489F1C29EEE2D6ECF442F9 84s gpgv: issuer "gibmat@debian.org" 84s gpgv: Can't check signature: No public key 84s dpkg-source: warning: cannot verify inline signature for ./golang-github-canonical-go-dqlite_2.0.0-1.dsc: no acceptable signature found 84s autopkgtest [03:09:21]: testing package golang-github-canonical-go-dqlite version 2.0.0-1 85s autopkgtest [03:09:22]: build not needed 85s autopkgtest [03:09:22]: test dh-golang-autopkgtest: preparing testbed 86s Reading package lists... 86s Building dependency tree... 86s Reading state information... 86s Solving dependencies... 86s The following NEW packages will be installed: 86s autoconf automake autopoint autotools-dev build-essential cpp cpp-15 86s cpp-15-s390x-linux-gnu cpp-s390x-linux-gnu debhelper debugedit dh-autoreconf 86s dh-golang dh-strip-nondeterminism dwz g++ g++-15 g++-15-s390x-linux-gnu 86s g++-s390x-linux-gnu gcc gcc-15 gcc-15-s390x-linux-gnu gcc-s390x-linux-gnu 86s gettext go-dqlite golang-1.25-go golang-1.25-src golang-any 86s golang-github-burntsushi-toml-dev golang-github-canonical-go-dqlite-dev 86s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 86s golang-github-fsnotify-fsnotify-dev golang-github-ghodss-yaml-dev 86s golang-github-google-renameio-dev golang-github-hashicorp-hcl-dev 86s golang-github-kr-fs-dev golang-github-magiconair-properties-dev 86s golang-github-mattn-go-runewidth-dev golang-github-mattn-go-sqlite3-dev 86s golang-github-mitchellh-mapstructure-dev golang-github-pelletier-go-toml-dev 86s golang-github-pelletier-go-toml.v2-dev golang-github-peterh-liner-dev 86s golang-github-pkg-errors-dev golang-github-pkg-sftp-dev 86s golang-github-pmezard-go-difflib-dev golang-github-rican7-retry-dev 86s golang-github-rivo-uniseg-dev golang-github-russross-blackfriday-v2-dev 86s golang-github-shurcool-sanitized-anchor-name-dev 86s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 86s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 86s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 86s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 86s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 86s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 86s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 86s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 86s libasan8 libcc1-0 libdebhelper-perl libdqlite-dev libdqlite0 86s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libisl23 libitm1 86s liblz4-dev libmpc3 libsqlite3-dev libstdc++-15-dev libtool libubsan1 86s libuv1-dev libxxhash-dev m4 po-debconf tzdata-legacy 86s 0 upgraded, 93 newly installed, 0 to remove and 0 not upgraded. 86s Need to get 113 MB of archives. 86s After this operation, 495 MB of additional disk space will be used. 86s Get:1 http://ftpmaster.internal/ubuntu resolute/main s390x m4 s390x 1.4.19-8 [259 kB] 86s Get:2 http://ftpmaster.internal/ubuntu resolute/main s390x autoconf all 2.72-3.1ubuntu1 [384 kB] 87s Get:3 http://ftpmaster.internal/ubuntu resolute/main s390x autotools-dev all 20240727.1 [43.4 kB] 87s Get:4 http://ftpmaster.internal/ubuntu resolute/main s390x automake all 1:1.17-4ubuntu1 [572 kB] 87s Get:5 http://ftpmaster.internal/ubuntu resolute/main s390x autopoint all 0.23.1-2build2 [619 kB] 87s Get:6 http://ftpmaster.internal/ubuntu resolute/main s390x libisl23 s390x 0.27-1 [704 kB] 87s Get:7 http://ftpmaster.internal/ubuntu resolute/main s390x libmpc3 s390x 1.3.1-1build3 [57.6 kB] 87s Get:8 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-15-s390x-linux-gnu s390x 15.2.0-5ubuntu1 [10.2 MB] 89s Get:9 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-15 s390x 15.2.0-5ubuntu1 [1024 B] 89s Get:10 http://ftpmaster.internal/ubuntu resolute/main s390x cpp-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [5746 B] 89s Get:11 http://ftpmaster.internal/ubuntu resolute/main s390x cpp s390x 4:15.2.0-4ubuntu1 [22.4 kB] 89s Get:12 http://ftpmaster.internal/ubuntu resolute/main s390x libcc1-0 s390x 15.2.0-5ubuntu1 [50.0 kB] 89s Get:13 http://ftpmaster.internal/ubuntu resolute/main s390x libgomp1 s390x 15.2.0-5ubuntu1 [154 kB] 89s Get:14 http://ftpmaster.internal/ubuntu resolute/main s390x libitm1 s390x 15.2.0-5ubuntu1 [30.9 kB] 89s Get:15 http://ftpmaster.internal/ubuntu resolute/main s390x libasan8 s390x 15.2.0-5ubuntu1 [2969 kB] 89s Get:16 http://ftpmaster.internal/ubuntu resolute/main s390x libubsan1 s390x 15.2.0-5ubuntu1 [1211 kB] 89s Get:17 http://ftpmaster.internal/ubuntu resolute/main s390x libgcc-15-dev s390x 15.2.0-5ubuntu1 [1045 kB] 89s Get:18 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15-s390x-linux-gnu s390x 15.2.0-5ubuntu1 [19.9 MB] 91s Get:19 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-15 s390x 15.2.0-5ubuntu1 [512 kB] 91s Get:20 http://ftpmaster.internal/ubuntu resolute/main s390x gcc-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [1208 B] 91s Get:21 http://ftpmaster.internal/ubuntu resolute/main s390x gcc s390x 4:15.2.0-4ubuntu1 [5018 B] 91s Get:22 http://ftpmaster.internal/ubuntu resolute/main s390x libstdc++-15-dev s390x 15.2.0-5ubuntu1 [2658 kB] 91s Get:23 http://ftpmaster.internal/ubuntu resolute/main s390x g++-15-s390x-linux-gnu s390x 15.2.0-5ubuntu1 [11.7 MB] 92s Get:24 http://ftpmaster.internal/ubuntu resolute/main s390x g++-15 s390x 15.2.0-5ubuntu1 [23.7 kB] 92s Get:25 http://ftpmaster.internal/ubuntu resolute/main s390x g++-s390x-linux-gnu s390x 4:15.2.0-4ubuntu1 [956 B] 92s Get:26 http://ftpmaster.internal/ubuntu resolute/main s390x g++ s390x 4:15.2.0-4ubuntu1 [1078 B] 92s Get:27 http://ftpmaster.internal/ubuntu resolute/main s390x build-essential s390x 12.12ubuntu1 [5090 B] 92s Get:28 http://ftpmaster.internal/ubuntu resolute/main s390x libdebhelper-perl all 13.24.2ubuntu1 [95.7 kB] 92s Get:29 http://ftpmaster.internal/ubuntu resolute/main s390x libtool all 2.5.4-4build1 [169 kB] 92s Get:30 http://ftpmaster.internal/ubuntu resolute/main s390x dh-autoreconf all 21 [12.5 kB] 92s Get:31 http://ftpmaster.internal/ubuntu resolute/main s390x libarchive-zip-perl all 1.68-1 [90.2 kB] 92s Get:32 http://ftpmaster.internal/ubuntu resolute/main s390x libfile-stripnondeterminism-perl all 1.14.2-1 [20.4 kB] 92s Get:33 http://ftpmaster.internal/ubuntu resolute/main s390x dh-strip-nondeterminism all 1.14.2-1 [5098 B] 92s Get:34 http://ftpmaster.internal/ubuntu resolute/main s390x debugedit s390x 1:5.2-3 [52.8 kB] 92s Get:35 http://ftpmaster.internal/ubuntu resolute/main s390x dwz s390x 0.16-2 [121 kB] 92s Get:36 http://ftpmaster.internal/ubuntu resolute/main s390x gettext s390x 0.23.1-2build2 [1062 kB] 92s Get:37 http://ftpmaster.internal/ubuntu resolute/main s390x intltool-debian all 0.35.0+20060710.6 [23.2 kB] 92s Get:38 http://ftpmaster.internal/ubuntu resolute/main s390x po-debconf all 1.0.21+nmu1 [233 kB] 92s Get:39 http://ftpmaster.internal/ubuntu resolute/main s390x debhelper all 13.24.2ubuntu1 [896 kB] 92s Get:40 http://ftpmaster.internal/ubuntu resolute/universe s390x libdqlite0 s390x 1.18.1-1 [157 kB] 92s Get:41 http://ftpmaster.internal/ubuntu resolute/universe s390x go-dqlite s390x 2.0.0-1 [5438 kB] 92s Get:42 http://ftpmaster.internal/ubuntu resolute/main s390x golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 94s Get:43 http://ftpmaster.internal/ubuntu resolute/main s390x golang-1.25-go s390x 1.25.0-0ubuntu1 [17.1 MB] 96s Get:44 http://ftpmaster.internal/ubuntu resolute/main s390x golang-src all 2:1.25~1 [5206 B] 96s Get:45 http://ftpmaster.internal/ubuntu resolute/main s390x golang-go s390x 2:1.25~1 [44.1 kB] 96s Get:46 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-any s390x 2:1.25~1 [2838 B] 96s Get:47 http://ftpmaster.internal/ubuntu resolute/main s390x libxxhash-dev s390x 0.8.3-2 [83.6 kB] 96s Get:48 http://ftpmaster.internal/ubuntu resolute/main s390x liblz4-dev s390x 1.10.0-4build1 [112 kB] 96s Get:49 http://ftpmaster.internal/ubuntu resolute/main s390x libuv1-dev s390x 1.50.0-2ubuntu1 [137 kB] 96s Get:50 http://ftpmaster.internal/ubuntu resolute/main s390x libsqlite3-dev s390x 3.46.1-8 [970 kB] 96s Get:51 http://ftpmaster.internal/ubuntu resolute/universe s390x libdqlite-dev s390x 1.18.1-1 [245 kB] 96s Get:52 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-gopkg-yaml.v2-dev all 2.4.0-5 [62.0 kB] 96s Get:53 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-ghodss-yaml-dev all 1.0.0+git20220118.d8423dc-2 [16.6 kB] 96s Get:54 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-google-renameio-dev all 2.0.0-2 [10.3 kB] 96s Get:55 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-mattn-go-sqlite3-dev s390x 1.14.27~ds1-1 [86.3 kB] 96s Get:56 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-rivo-uniseg-dev all 0.4.7-1 [258 kB] 96s Get:57 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-mattn-go-runewidth-dev all 0.0.16-2 [17.6 kB] 96s Get:58 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-peterh-liner-dev all 1.2.2-1 [21.3 kB] 96s Get:59 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-pkg-errors-dev all 0.9.1-3 [13.2 kB] 96s Get:60 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-rican7-retry-dev all 0.3.1-2 [7740 B] 96s Get:61 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-pmezard-go-difflib-dev all 1.0.0-3 [12.4 kB] 96s Get:62 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-shurcool-sanitized-anchor-name-dev all 1.0.0-3 [3590 B] 96s Get:63 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-russross-blackfriday-v2-dev all 2.1.0-1 [79.6 kB] 96s Get:64 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x golang-github-cpuguy83-go-md2man-v2-dev all 2.0.7-1 [12.4 kB] 96s Get:65 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x golang-github-spf13-pflag-dev all 1.0.10-1 [50.1 kB] 96s Get:66 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-golang-x-sys-dev all 0.22.0-1 [457 kB] 96s Get:67 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-fsnotify-fsnotify-dev all 1.8.0-3 [55.5 kB] 96s Get:68 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-davecgh-go-spew-dev all 1.1.1-3 [31.1 kB] 96s Get:69 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-hashicorp-hcl-dev all 1.0.0-1.1 [58.9 kB] 96s Get:70 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-magiconair-properties-dev all 1.8.7-1 [27.3 kB] 96s Get:71 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-mitchellh-mapstructure-dev all 1.5.0+git20231216.8508981-1 [28.3 kB] 96s Get:72 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-burntsushi-toml-dev all 1.4.0-1 [119 kB] 96s Get:73 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-pelletier-go-toml-dev all 1.9.5-1build1 [79.6 kB] 96s Get:74 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-pelletier-go-toml.v2-dev all 2.2.1-1 [877 kB] 96s Get:75 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-kr-fs-dev all 0.1.0-2 [6344 B] 96s Get:76 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-golang-x-term-dev all 0.22.0-1 [14.6 kB] 96s Get:77 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-golang-x-text-dev all 0.22.0-1 [4616 kB] 97s Get:78 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-golang-x-crypto-dev all 1:0.25.0-1 [1724 kB] 97s Get:79 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-pkg-sftp-dev all 1.13.6-1 [104 kB] 97s Get:80 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-spf13-afero-dev all 1.11.0-1 [59.6 kB] 97s Get:81 http://ftpmaster.internal/ubuntu resolute/main s390x tzdata-legacy all 2025b-5ubuntu1 [99.3 kB] 97s Get:82 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-spf13-cast-dev all 1.6.0-2 [14.4 kB] 97s Get:83 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-stretchr-objx-dev all 0.5.2-1 [26.5 kB] 97s Get:84 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-gopkg-yaml.v3-dev all 3.0.1-3 [76.1 kB] 97s Get:85 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-stretchr-testify-dev all 1.10.0-1 [79.6 kB] 97s Get:86 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-spf13-jwalterweatherman-dev all 1.1.0+really1.1.0-2 [6828 B] 97s Get:87 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-subosito-gotenv-dev all 1.3.0-1 [8808 B] 97s Get:88 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-gopkg-ini.v1-dev all 1.67.0-1 [40.8 kB] 97s Get:89 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-spf13-viper-dev all 1.12.0-1 [71.0 kB] 97s Get:90 http://ftpmaster.internal/ubuntu resolute-proposed/universe s390x golang-github-spf13-cobra-dev all 1.10.1-1 [81.3 kB] 97s Get:91 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-golang-x-sync-dev all 0.7.0-1 [19.5 kB] 97s Get:92 http://ftpmaster.internal/ubuntu resolute/universe s390x golang-github-canonical-go-dqlite-dev all 2.0.0-1 [72.0 kB] 97s Get:93 http://ftpmaster.internal/ubuntu resolute/main s390x dh-golang all 1.63 [24.9 kB] 97s Fetched 113 MB in 11s (10.3 MB/s) 97s Selecting previously unselected package m4. 97s (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 ... 56950 files and directories currently installed.) 97s Preparing to unpack .../00-m4_1.4.19-8_s390x.deb ... 97s Unpacking m4 (1.4.19-8) ... 97s Selecting previously unselected package autoconf. 97s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ... 97s Unpacking autoconf (2.72-3.1ubuntu1) ... 97s Selecting previously unselected package autotools-dev. 97s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ... 97s Unpacking autotools-dev (20240727.1) ... 97s Selecting previously unselected package automake. 97s Preparing to unpack .../03-automake_1%3a1.17-4ubuntu1_all.deb ... 97s Unpacking automake (1:1.17-4ubuntu1) ... 97s Selecting previously unselected package autopoint. 97s Preparing to unpack .../04-autopoint_0.23.1-2build2_all.deb ... 97s Unpacking autopoint (0.23.1-2build2) ... 97s Selecting previously unselected package libisl23:s390x. 97s Preparing to unpack .../05-libisl23_0.27-1_s390x.deb ... 97s Unpacking libisl23:s390x (0.27-1) ... 98s Selecting previously unselected package libmpc3:s390x. 98s Preparing to unpack .../06-libmpc3_1.3.1-1build3_s390x.deb ... 98s Unpacking libmpc3:s390x (1.3.1-1build3) ... 98s Selecting previously unselected package cpp-15-s390x-linux-gnu. 98s Preparing to unpack .../07-cpp-15-s390x-linux-gnu_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking cpp-15-s390x-linux-gnu (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package cpp-15. 98s Preparing to unpack .../08-cpp-15_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking cpp-15 (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package cpp-s390x-linux-gnu. 98s Preparing to unpack .../09-cpp-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 98s Unpacking cpp-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 98s Selecting previously unselected package cpp. 98s Preparing to unpack .../10-cpp_4%3a15.2.0-4ubuntu1_s390x.deb ... 98s Unpacking cpp (4:15.2.0-4ubuntu1) ... 98s Selecting previously unselected package libcc1-0:s390x. 98s Preparing to unpack .../11-libcc1-0_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking libcc1-0:s390x (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package libgomp1:s390x. 98s Preparing to unpack .../12-libgomp1_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking libgomp1:s390x (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package libitm1:s390x. 98s Preparing to unpack .../13-libitm1_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking libitm1:s390x (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package libasan8:s390x. 98s Preparing to unpack .../14-libasan8_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking libasan8:s390x (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package libubsan1:s390x. 98s Preparing to unpack .../15-libubsan1_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking libubsan1:s390x (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package libgcc-15-dev:s390x. 98s Preparing to unpack .../16-libgcc-15-dev_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking libgcc-15-dev:s390x (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package gcc-15-s390x-linux-gnu. 98s Preparing to unpack .../17-gcc-15-s390x-linux-gnu_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking gcc-15-s390x-linux-gnu (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package gcc-15. 98s Preparing to unpack .../18-gcc-15_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking gcc-15 (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package gcc-s390x-linux-gnu. 98s Preparing to unpack .../19-gcc-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 98s Unpacking gcc-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 98s Selecting previously unselected package gcc. 98s Preparing to unpack .../20-gcc_4%3a15.2.0-4ubuntu1_s390x.deb ... 98s Unpacking gcc (4:15.2.0-4ubuntu1) ... 98s Selecting previously unselected package libstdc++-15-dev:s390x. 98s Preparing to unpack .../21-libstdc++-15-dev_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking libstdc++-15-dev:s390x (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package g++-15-s390x-linux-gnu. 98s Preparing to unpack .../22-g++-15-s390x-linux-gnu_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking g++-15-s390x-linux-gnu (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package g++-15. 98s Preparing to unpack .../23-g++-15_15.2.0-5ubuntu1_s390x.deb ... 98s Unpacking g++-15 (15.2.0-5ubuntu1) ... 98s Selecting previously unselected package g++-s390x-linux-gnu. 98s Preparing to unpack .../24-g++-s390x-linux-gnu_4%3a15.2.0-4ubuntu1_s390x.deb ... 98s Unpacking g++-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 98s Selecting previously unselected package g++. 98s Preparing to unpack .../25-g++_4%3a15.2.0-4ubuntu1_s390x.deb ... 98s Unpacking g++ (4:15.2.0-4ubuntu1) ... 98s Selecting previously unselected package build-essential. 98s Preparing to unpack .../26-build-essential_12.12ubuntu1_s390x.deb ... 98s Unpacking build-essential (12.12ubuntu1) ... 98s Selecting previously unselected package libdebhelper-perl. 98s Preparing to unpack .../27-libdebhelper-perl_13.24.2ubuntu1_all.deb ... 98s Unpacking libdebhelper-perl (13.24.2ubuntu1) ... 98s Selecting previously unselected package libtool. 98s Preparing to unpack .../28-libtool_2.5.4-4build1_all.deb ... 98s Unpacking libtool (2.5.4-4build1) ... 99s Selecting previously unselected package dh-autoreconf. 99s Preparing to unpack .../29-dh-autoreconf_21_all.deb ... 99s Unpacking dh-autoreconf (21) ... 99s Selecting previously unselected package libarchive-zip-perl. 99s Preparing to unpack .../30-libarchive-zip-perl_1.68-1_all.deb ... 99s Unpacking libarchive-zip-perl (1.68-1) ... 99s Selecting previously unselected package libfile-stripnondeterminism-perl. 99s Preparing to unpack .../31-libfile-stripnondeterminism-perl_1.14.2-1_all.deb ... 99s Unpacking libfile-stripnondeterminism-perl (1.14.2-1) ... 99s Selecting previously unselected package dh-strip-nondeterminism. 99s Preparing to unpack .../32-dh-strip-nondeterminism_1.14.2-1_all.deb ... 99s Unpacking dh-strip-nondeterminism (1.14.2-1) ... 99s Selecting previously unselected package debugedit. 99s Preparing to unpack .../33-debugedit_1%3a5.2-3_s390x.deb ... 99s Unpacking debugedit (1:5.2-3) ... 99s Selecting previously unselected package dwz. 99s Preparing to unpack .../34-dwz_0.16-2_s390x.deb ... 99s Unpacking dwz (0.16-2) ... 99s Selecting previously unselected package gettext. 99s Preparing to unpack .../35-gettext_0.23.1-2build2_s390x.deb ... 99s Unpacking gettext (0.23.1-2build2) ... 99s Selecting previously unselected package intltool-debian. 99s Preparing to unpack .../36-intltool-debian_0.35.0+20060710.6_all.deb ... 99s Unpacking intltool-debian (0.35.0+20060710.6) ... 99s Selecting previously unselected package po-debconf. 99s Preparing to unpack .../37-po-debconf_1.0.21+nmu1_all.deb ... 99s Unpacking po-debconf (1.0.21+nmu1) ... 99s Selecting previously unselected package debhelper. 99s Preparing to unpack .../38-debhelper_13.24.2ubuntu1_all.deb ... 99s Unpacking debhelper (13.24.2ubuntu1) ... 99s Selecting previously unselected package libdqlite0:s390x. 99s Preparing to unpack .../39-libdqlite0_1.18.1-1_s390x.deb ... 99s Unpacking libdqlite0:s390x (1.18.1-1) ... 99s Selecting previously unselected package go-dqlite. 99s Preparing to unpack .../40-go-dqlite_2.0.0-1_s390x.deb ... 99s Unpacking go-dqlite (2.0.0-1) ... 99s Selecting previously unselected package golang-1.25-src. 99s Preparing to unpack .../41-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 99s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 100s Selecting previously unselected package golang-1.25-go. 100s Preparing to unpack .../42-golang-1.25-go_1.25.0-0ubuntu1_s390x.deb ... 100s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 100s Selecting previously unselected package golang-src. 100s Preparing to unpack .../43-golang-src_2%3a1.25~1_all.deb ... 100s Unpacking golang-src (2:1.25~1) ... 100s Selecting previously unselected package golang-go:s390x. 100s Preparing to unpack .../44-golang-go_2%3a1.25~1_s390x.deb ... 100s Unpacking golang-go:s390x (2:1.25~1) ... 100s Selecting previously unselected package golang-any:s390x. 100s Preparing to unpack .../45-golang-any_2%3a1.25~1_s390x.deb ... 100s Unpacking golang-any:s390x (2:1.25~1) ... 100s Selecting previously unselected package libxxhash-dev:s390x. 100s Preparing to unpack .../46-libxxhash-dev_0.8.3-2_s390x.deb ... 100s Unpacking libxxhash-dev:s390x (0.8.3-2) ... 100s Selecting previously unselected package liblz4-dev:s390x. 100s Preparing to unpack .../47-liblz4-dev_1.10.0-4build1_s390x.deb ... 100s Unpacking liblz4-dev:s390x (1.10.0-4build1) ... 100s Selecting previously unselected package libuv1-dev:s390x. 100s Preparing to unpack .../48-libuv1-dev_1.50.0-2ubuntu1_s390x.deb ... 100s Unpacking libuv1-dev:s390x (1.50.0-2ubuntu1) ... 100s Selecting previously unselected package libsqlite3-dev:s390x. 100s Preparing to unpack .../49-libsqlite3-dev_3.46.1-8_s390x.deb ... 100s Unpacking libsqlite3-dev:s390x (3.46.1-8) ... 100s Selecting previously unselected package libdqlite-dev:s390x. 100s Preparing to unpack .../50-libdqlite-dev_1.18.1-1_s390x.deb ... 100s Unpacking libdqlite-dev:s390x (1.18.1-1) ... 100s Selecting previously unselected package golang-gopkg-yaml.v2-dev. 100s Preparing to unpack .../51-golang-gopkg-yaml.v2-dev_2.4.0-5_all.deb ... 100s Unpacking golang-gopkg-yaml.v2-dev (2.4.0-5) ... 100s Selecting previously unselected package golang-github-ghodss-yaml-dev. 100s Preparing to unpack .../52-golang-github-ghodss-yaml-dev_1.0.0+git20220118.d8423dc-2_all.deb ... 100s Unpacking golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ... 100s Selecting previously unselected package golang-github-google-renameio-dev. 100s Preparing to unpack .../53-golang-github-google-renameio-dev_2.0.0-2_all.deb ... 100s Unpacking golang-github-google-renameio-dev (2.0.0-2) ... 100s Selecting previously unselected package golang-github-mattn-go-sqlite3-dev:s390x. 100s Preparing to unpack .../54-golang-github-mattn-go-sqlite3-dev_1.14.27~ds1-1_s390x.deb ... 100s Unpacking golang-github-mattn-go-sqlite3-dev:s390x (1.14.27~ds1-1) ... 100s Selecting previously unselected package golang-github-rivo-uniseg-dev. 100s Preparing to unpack .../55-golang-github-rivo-uniseg-dev_0.4.7-1_all.deb ... 100s Unpacking golang-github-rivo-uniseg-dev (0.4.7-1) ... 100s Selecting previously unselected package golang-github-mattn-go-runewidth-dev. 100s Preparing to unpack .../56-golang-github-mattn-go-runewidth-dev_0.0.16-2_all.deb ... 100s Unpacking golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 100s Selecting previously unselected package golang-github-peterh-liner-dev. 100s Preparing to unpack .../57-golang-github-peterh-liner-dev_1.2.2-1_all.deb ... 100s Unpacking golang-github-peterh-liner-dev (1.2.2-1) ... 100s Selecting previously unselected package golang-github-pkg-errors-dev. 100s Preparing to unpack .../58-golang-github-pkg-errors-dev_0.9.1-3_all.deb ... 100s Unpacking golang-github-pkg-errors-dev (0.9.1-3) ... 100s Selecting previously unselected package golang-github-rican7-retry-dev. 100s Preparing to unpack .../59-golang-github-rican7-retry-dev_0.3.1-2_all.deb ... 100s Unpacking golang-github-rican7-retry-dev (0.3.1-2) ... 100s Selecting previously unselected package golang-github-pmezard-go-difflib-dev. 100s Preparing to unpack .../60-golang-github-pmezard-go-difflib-dev_1.0.0-3_all.deb ... 100s Unpacking golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 100s Selecting previously unselected package golang-github-shurcool-sanitized-anchor-name-dev. 100s Preparing to unpack .../61-golang-github-shurcool-sanitized-anchor-name-dev_1.0.0-3_all.deb ... 100s Unpacking golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 100s Selecting previously unselected package golang-github-russross-blackfriday-v2-dev. 100s Preparing to unpack .../62-golang-github-russross-blackfriday-v2-dev_2.1.0-1_all.deb ... 100s Unpacking golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 100s Selecting previously unselected package golang-github-cpuguy83-go-md2man-v2-dev. 100s Preparing to unpack .../63-golang-github-cpuguy83-go-md2man-v2-dev_2.0.7-1_all.deb ... 100s Unpacking golang-github-cpuguy83-go-md2man-v2-dev (2.0.7-1) ... 100s Selecting previously unselected package golang-github-spf13-pflag-dev. 100s Preparing to unpack .../64-golang-github-spf13-pflag-dev_1.0.10-1_all.deb ... 100s Unpacking golang-github-spf13-pflag-dev (1.0.10-1) ... 100s Selecting previously unselected package golang-golang-x-sys-dev. 100s Preparing to unpack .../65-golang-golang-x-sys-dev_0.22.0-1_all.deb ... 100s Unpacking golang-golang-x-sys-dev (0.22.0-1) ... 100s Selecting previously unselected package golang-github-fsnotify-fsnotify-dev. 100s Preparing to unpack .../66-golang-github-fsnotify-fsnotify-dev_1.8.0-3_all.deb ... 100s Unpacking golang-github-fsnotify-fsnotify-dev (1.8.0-3) ... 100s Selecting previously unselected package golang-github-davecgh-go-spew-dev. 100s Preparing to unpack .../67-golang-github-davecgh-go-spew-dev_1.1.1-3_all.deb ... 100s Unpacking golang-github-davecgh-go-spew-dev (1.1.1-3) ... 100s Selecting previously unselected package golang-github-hashicorp-hcl-dev. 100s Preparing to unpack .../68-golang-github-hashicorp-hcl-dev_1.0.0-1.1_all.deb ... 100s Unpacking golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 100s Selecting previously unselected package golang-github-magiconair-properties-dev. 100s Preparing to unpack .../69-golang-github-magiconair-properties-dev_1.8.7-1_all.deb ... 100s Unpacking golang-github-magiconair-properties-dev (1.8.7-1) ... 100s Selecting previously unselected package golang-github-mitchellh-mapstructure-dev. 100s Preparing to unpack .../70-golang-github-mitchellh-mapstructure-dev_1.5.0+git20231216.8508981-1_all.deb ... 100s Unpacking golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 100s Selecting previously unselected package golang-github-burntsushi-toml-dev. 100s Preparing to unpack .../71-golang-github-burntsushi-toml-dev_1.4.0-1_all.deb ... 100s Unpacking golang-github-burntsushi-toml-dev (1.4.0-1) ... 101s Selecting previously unselected package golang-github-pelletier-go-toml-dev. 101s Preparing to unpack .../72-golang-github-pelletier-go-toml-dev_1.9.5-1build1_all.deb ... 101s Unpacking golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 101s Selecting previously unselected package golang-github-pelletier-go-toml.v2-dev. 101s Preparing to unpack .../73-golang-github-pelletier-go-toml.v2-dev_2.2.1-1_all.deb ... 101s Unpacking golang-github-pelletier-go-toml.v2-dev (2.2.1-1) ... 101s Selecting previously unselected package golang-github-kr-fs-dev. 101s Preparing to unpack .../74-golang-github-kr-fs-dev_0.1.0-2_all.deb ... 101s Unpacking golang-github-kr-fs-dev (0.1.0-2) ... 101s Selecting previously unselected package golang-golang-x-term-dev. 101s Preparing to unpack .../75-golang-golang-x-term-dev_0.22.0-1_all.deb ... 101s Unpacking golang-golang-x-term-dev (0.22.0-1) ... 101s Selecting previously unselected package golang-golang-x-text-dev. 101s Preparing to unpack .../76-golang-golang-x-text-dev_0.22.0-1_all.deb ... 101s Unpacking golang-golang-x-text-dev (0.22.0-1) ... 101s Selecting previously unselected package golang-golang-x-crypto-dev. 101s Preparing to unpack .../77-golang-golang-x-crypto-dev_1%3a0.25.0-1_all.deb ... 101s Unpacking golang-golang-x-crypto-dev (1:0.25.0-1) ... 101s Selecting previously unselected package golang-github-pkg-sftp-dev. 101s Preparing to unpack .../78-golang-github-pkg-sftp-dev_1.13.6-1_all.deb ... 101s Unpacking golang-github-pkg-sftp-dev (1.13.6-1) ... 101s Selecting previously unselected package golang-github-spf13-afero-dev. 101s Preparing to unpack .../79-golang-github-spf13-afero-dev_1.11.0-1_all.deb ... 101s Unpacking golang-github-spf13-afero-dev (1.11.0-1) ... 101s Selecting previously unselected package tzdata-legacy. 101s Preparing to unpack .../80-tzdata-legacy_2025b-5ubuntu1_all.deb ... 101s Unpacking tzdata-legacy (2025b-5ubuntu1) ... 101s Selecting previously unselected package golang-github-spf13-cast-dev. 101s Preparing to unpack .../81-golang-github-spf13-cast-dev_1.6.0-2_all.deb ... 101s Unpacking golang-github-spf13-cast-dev (1.6.0-2) ... 101s Selecting previously unselected package golang-github-stretchr-objx-dev. 101s Preparing to unpack .../82-golang-github-stretchr-objx-dev_0.5.2-1_all.deb ... 101s Unpacking golang-github-stretchr-objx-dev (0.5.2-1) ... 101s Selecting previously unselected package golang-gopkg-yaml.v3-dev. 101s Preparing to unpack .../83-golang-gopkg-yaml.v3-dev_3.0.1-3_all.deb ... 101s Unpacking golang-gopkg-yaml.v3-dev (3.0.1-3) ... 101s Selecting previously unselected package golang-github-stretchr-testify-dev. 101s Preparing to unpack .../84-golang-github-stretchr-testify-dev_1.10.0-1_all.deb ... 101s Unpacking golang-github-stretchr-testify-dev (1.10.0-1) ... 101s Selecting previously unselected package golang-github-spf13-jwalterweatherman-dev. 101s Preparing to unpack .../85-golang-github-spf13-jwalterweatherman-dev_1.1.0+really1.1.0-2_all.deb ... 101s Unpacking golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 101s Selecting previously unselected package golang-github-subosito-gotenv-dev. 101s Preparing to unpack .../86-golang-github-subosito-gotenv-dev_1.3.0-1_all.deb ... 101s Unpacking golang-github-subosito-gotenv-dev (1.3.0-1) ... 101s Selecting previously unselected package golang-gopkg-ini.v1-dev. 101s Preparing to unpack .../87-golang-gopkg-ini.v1-dev_1.67.0-1_all.deb ... 101s Unpacking golang-gopkg-ini.v1-dev (1.67.0-1) ... 101s Selecting previously unselected package golang-github-spf13-viper-dev. 101s Preparing to unpack .../88-golang-github-spf13-viper-dev_1.12.0-1_all.deb ... 101s Unpacking golang-github-spf13-viper-dev (1.12.0-1) ... 101s Selecting previously unselected package golang-github-spf13-cobra-dev. 101s Preparing to unpack .../89-golang-github-spf13-cobra-dev_1.10.1-1_all.deb ... 101s Unpacking golang-github-spf13-cobra-dev (1.10.1-1) ... 101s Selecting previously unselected package golang-golang-x-sync-dev. 101s Preparing to unpack .../90-golang-golang-x-sync-dev_0.7.0-1_all.deb ... 101s Unpacking golang-golang-x-sync-dev (0.7.0-1) ... 101s Selecting previously unselected package golang-github-canonical-go-dqlite-dev. 101s Preparing to unpack .../91-golang-github-canonical-go-dqlite-dev_2.0.0-1_all.deb ... 101s Unpacking golang-github-canonical-go-dqlite-dev (2.0.0-1) ... 101s Selecting previously unselected package dh-golang. 101s Preparing to unpack .../92-dh-golang_1.63_all.deb ... 101s Unpacking dh-golang (1.63) ... 101s Setting up golang-github-pelletier-go-toml.v2-dev (2.2.1-1) ... 101s Setting up libuv1-dev:s390x (1.50.0-2ubuntu1) ... 101s Setting up golang-gopkg-yaml.v2-dev (2.4.0-5) ... 101s Setting up golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 101s Setting up golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 101s Setting up golang-github-stretchr-objx-dev (0.5.2-1) ... 101s Setting up golang-github-pkg-errors-dev (0.9.1-3) ... 101s Setting up libarchive-zip-perl (1.68-1) ... 101s Setting up golang-gopkg-yaml.v3-dev (3.0.1-3) ... 101s Setting up libdebhelper-perl (13.24.2ubuntu1) ... 101s Setting up golang-github-burntsushi-toml-dev (1.4.0-1) ... 101s Setting up golang-golang-x-sys-dev (0.22.0-1) ... 101s Setting up golang-github-kr-fs-dev (0.1.0-2) ... 101s Setting up golang-github-subosito-gotenv-dev (1.3.0-1) ... 101s Setting up golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 101s Setting up m4 (1.4.19-8) ... 101s Setting up golang-golang-x-term-dev (0.22.0-1) ... 101s Setting up libgomp1:s390x (15.2.0-5ubuntu1) ... 101s Setting up tzdata-legacy (2025b-5ubuntu1) ... 101s Setting up golang-github-google-renameio-dev (2.0.0-2) ... 101s Setting up golang-github-spf13-pflag-dev (1.0.10-1) ... 101s Setting up golang-github-davecgh-go-spew-dev (1.1.1-3) ... 101s Setting up autotools-dev (20240727.1) ... 101s Setting up libsqlite3-dev:s390x (3.46.1-8) ... 101s Setting up golang-github-stretchr-testify-dev (1.10.0-1) ... 101s Setting up golang-gopkg-ini.v1-dev (1.67.0-1) ... 101s Setting up libmpc3:s390x (1.3.1-1build3) ... 101s Setting up golang-golang-x-sync-dev (0.7.0-1) ... 101s Setting up autopoint (0.23.1-2build2) ... 101s Setting up golang-github-magiconair-properties-dev (1.8.7-1) ... 101s Setting up golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 101s Setting up golang-github-rican7-retry-dev (0.3.1-2) ... 101s Setting up autoconf (2.72-3.1ubuntu1) ... 101s Setting up libxxhash-dev:s390x (0.8.3-2) ... 101s Setting up golang-github-rivo-uniseg-dev (0.4.7-1) ... 101s Setting up libubsan1:s390x (15.2.0-5ubuntu1) ... 101s Setting up dwz (0.16-2) ... 101s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 101s Setting up golang-golang-x-text-dev (0.22.0-1) ... 101s Setting up libasan8:s390x (15.2.0-5ubuntu1) ... 101s Setting up golang-github-spf13-cast-dev (1.6.0-2) ... 101s Setting up golang-github-fsnotify-fsnotify-dev (1.8.0-3) ... 101s Setting up debugedit (1:5.2-3) ... 101s Setting up golang-golang-x-crypto-dev (1:0.25.0-1) ... 101s Setting up golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 101s Setting up golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 101s Setting up libdqlite0:s390x (1.18.1-1) ... 101s Setting up libisl23:s390x (0.27-1) ... 101s Setting up golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ... 101s Setting up golang-src (2:1.25~1) ... 101s Setting up libcc1-0:s390x (15.2.0-5ubuntu1) ... 101s Setting up libitm1:s390x (15.2.0-5ubuntu1) ... 101s Setting up automake (1:1.17-4ubuntu1) ... 101s update-alternatives: using /usr/bin/automake-1.17 to provide /usr/bin/automake (automake) in auto mode 101s Setting up libfile-stripnondeterminism-perl (1.14.2-1) ... 101s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 101s Setting up golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 101s Setting up gettext (0.23.1-2build2) ... 101s Setting up libgcc-15-dev:s390x (15.2.0-5ubuntu1) ... 101s Setting up golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 101s Setting up golang-go:s390x (2:1.25~1) ... 101s Setting up golang-github-pkg-sftp-dev (1.13.6-1) ... 101s Setting up golang-any:s390x (2:1.25~1) ... 101s Setting up intltool-debian (0.35.0+20060710.6) ... 101s Setting up golang-github-peterh-liner-dev (1.2.2-1) ... 101s Setting up golang-github-mattn-go-sqlite3-dev:s390x (1.14.27~ds1-1) ... 101s Setting up go-dqlite (2.0.0-1) ... 101s Setting up libstdc++-15-dev:s390x (15.2.0-5ubuntu1) ... 101s Setting up liblz4-dev:s390x (1.10.0-4build1) ... 101s Setting up dh-strip-nondeterminism (1.14.2-1) ... 101s Setting up cpp-15-s390x-linux-gnu (15.2.0-5ubuntu1) ... 101s Setting up golang-github-spf13-afero-dev (1.11.0-1) ... 101s Setting up gcc-15-s390x-linux-gnu (15.2.0-5ubuntu1) ... 101s Setting up g++-15-s390x-linux-gnu (15.2.0-5ubuntu1) ... 101s Setting up libdqlite-dev:s390x (1.18.1-1) ... 101s Setting up golang-github-cpuguy83-go-md2man-v2-dev (2.0.7-1) ... 101s Setting up cpp-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 101s Setting up po-debconf (1.0.21+nmu1) ... 101s Setting up gcc-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 101s Setting up golang-github-spf13-viper-dev (1.12.0-1) ... 101s Setting up cpp-15 (15.2.0-5ubuntu1) ... 101s Setting up cpp (4:15.2.0-4ubuntu1) ... 101s Setting up g++-s390x-linux-gnu (4:15.2.0-4ubuntu1) ... 101s Setting up gcc-15 (15.2.0-5ubuntu1) ... 101s Setting up golang-github-spf13-cobra-dev (1.10.1-1) ... 101s Setting up g++-15 (15.2.0-5ubuntu1) ... 101s Setting up libtool (2.5.4-4build1) ... 101s Setting up gcc (4:15.2.0-4ubuntu1) ... 101s Setting up dh-autoreconf (21) ... 101s Setting up golang-github-canonical-go-dqlite-dev (2.0.0-1) ... 101s Setting up g++ (4:15.2.0-4ubuntu1) ... 101s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 101s Setting up build-essential (12.12ubuntu1) ... 101s Setting up debhelper (13.24.2ubuntu1) ... 101s Setting up dh-golang (1.63) ... 101s Processing triggers for man-db (2.13.1-1) ... 102s Processing triggers for install-info (7.1.1-1ubuntu1) ... 102s Processing triggers for libc-bin (2.42-0ubuntu3) ... 103s autopkgtest [03:09:40]: test dh-golang-autopkgtest: /usr/bin/dh_golang_autopkgtest 103s autopkgtest [03:09:40]: test dh-golang-autopkgtest: [----------------------- 103s [info] Testing github.com/canonical/go-dqlite... 103s [info] Source code installed by binary package, overriding dh_auto_configure... 103s dh build --builddirectory=_build --buildsystem=golang --with=golang 104s dh_update_autotools_config -O--builddirectory=_build -O--buildsystem=golang 104s dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang 104s debian/rules override_dh_auto_configure 104s make[1]: Entering directory '/tmp/autopkgtest.sUmDjC/autopkgtest_tmp' 104s mkdir -p "_build" 104s # remove existing symlinks, since "cp -a" won't convert them 104s find "_build" -type l -exec rm "{}" \; 104s cp -a /usr/share/gocode/src "_build" 104s make[1]: Leaving directory '/tmp/autopkgtest.sUmDjC/autopkgtest_tmp' 104s dh_auto_build -O--builddirectory=_build -O--buildsystem=golang 104s cd _build && go install -trimpath -v -p 2 github.com/canonical/go-dqlite github.com/canonical/go-dqlite/app github.com/canonical/go-dqlite/benchmark github.com/canonical/go-dqlite/client github.com/canonical/go-dqlite/cmd/dqlite github.com/canonical/go-dqlite/cmd/dqlite-benchmark github.com/canonical/go-dqlite/cmd/dqlite-demo github.com/canonical/go-dqlite/driver github.com/canonical/go-dqlite/internal/bindings github.com/canonical/go-dqlite/internal/protocol github.com/canonical/go-dqlite/internal/shell github.com/canonical/go-dqlite/logging github.com/canonical/go-dqlite/tracing 104s internal/unsafeheader 104s internal/goarch 104s internal/cpu 104s internal/abi 104s internal/bytealg 104s internal/byteorder 104s internal/chacha8rand 104s internal/coverage/rtcov 104s internal/godebugs 104s internal/goexperiment 104s internal/goos 104s internal/profilerecord 104s internal/runtime/atomic 104s internal/runtime/math 104s internal/runtime/strconv 104s internal/runtime/syscall 104s internal/runtime/exithook 104s internal/runtime/cgroup 104s internal/runtime/gc 104s internal/asan 104s internal/msan 104s internal/race 104s internal/runtime/sys 105s internal/stringslite 105s internal/runtime/maps 105s internal/trace/tracev2 105s sync/atomic 105s internal/sync 105s internal/synctest 105s math/bits 105s internal/itoa 105s cmp 105s math 105s runtime 105s unicode/utf8 105s unicode 105s container/list 105s crypto/internal/fips140/alias 105s crypto/internal/fips140deps/byteorder 105s crypto/internal/fips140deps/cpu 105s crypto/internal/boring/sig 105s unicode/utf16 105s vendor/golang.org/x/crypto/cryptobyte/asn1 105s vendor/golang.org/x/crypto/internal/alias 105s internal/nettrace 105s encoding 105s log/internal 105s container/ring 105s github.com/rivo/uniseg 108s internal/reflectlite 108s sync 108s internal/bisect 108s errors 108s internal/oserror 108s iter 108s slices 108s internal/godebug 108s syscall 108s strconv 109s reflect 109s time 109s context 110s io 110s path 110s io/fs 110s internal/filepathlite 110s internal/syscall/unix 110s internal/fmtsort 110s internal/poll 110s internal/syscall/execenv 110s internal/testlog 110s bytes 110s hash 110s crypto 110s crypto/internal/fips140deps/godebug 110s strings 110s os 110s crypto/internal/fips140 110s crypto/internal/impl 110s crypto/internal/fips140/sha256 111s crypto/internal/fips140/subtle 111s crypto/internal/fips140/sha3 111s crypto/internal/fips140/sha512 111s crypto/internal/fips140/hmac 111s crypto/internal/fips140/check 111s crypto/internal/fips140/aes 111s fmt 111s crypto/internal/sysrand 111s crypto/internal/entropy 111s math/rand/v2 111s crypto/internal/randutil 111s crypto/internal/fips140/drbg 111s crypto/subtle 111s crypto/internal/fips140/nistec/fiat 111s crypto/internal/fips140/aes/gcm 111s crypto/internal/fips140only 111s crypto/cipher 111s crypto/internal/boring 111s crypto/aes 111s crypto/des 112s crypto/internal/fips140/edwards25519/field 112s math/rand 112s crypto/internal/fips140/nistec 112s math/big 112s crypto/internal/fips140/ecdh 112s crypto/elliptic 112s crypto/ecdh 113s crypto/internal/boring/bbig 113s crypto/internal/fips140/bigmod 113s weak 113s crypto/internal/fips140cache 113s crypto/sha3 113s crypto/internal/fips140hash 113s crypto/sha512 113s encoding/asn1 113s crypto/internal/fips140/ecdsa 113s crypto/internal/fips140/edwards25519 113s crypto/internal/fips140/ed25519 113s vendor/golang.org/x/crypto/cryptobyte 113s crypto/rand 113s crypto/ed25519 113s crypto/ecdsa 113s crypto/internal/fips140/hkdf 113s crypto/hkdf 113s crypto/hmac 113s crypto/internal/fips140/mlkem 113s crypto/internal/fips140/tls12 113s crypto/internal/fips140/tls13 113s encoding/binary 114s vendor/golang.org/x/sys/cpu 114s crypto/md5 114s vendor/golang.org/x/crypto/chacha20 114s vendor/golang.org/x/crypto/internal/poly1305 114s crypto/rc4 114s crypto/internal/fips140/rsa 114s vendor/golang.org/x/crypto/chacha20poly1305 114s crypto/internal/hpke 114s crypto/sha1 114s crypto/rsa 114s crypto/sha256 114s crypto/fips140 114s crypto/tls/internal/fips140tls 114s crypto/dsa 114s encoding/hex 114s encoding/base64 114s crypto/x509/pkix 114s encoding/pem 114s maps 114s vendor/golang.org/x/net/dns/dnsmessage 114s internal/singleflight 114s unique 115s net/netip 115s runtime/cgo 115s net/url 115s path/filepath 115s sort 115s database/sql/driver 116s net 116s database/sql 116s github.com/Rican7/retry/backoff 116s github.com/Rican7/retry/jitter 116s github.com/Rican7/retry/strategy 116s github.com/Rican7/retry 116s flag 116s bufio 116s internal/sysinfo 116s runtime/debug 116s runtime/trace 116s testing 117s github.com/canonical/go-dqlite/logging 117s github.com/pkg/errors 117s golang.org/x/sync/semaphore 118s io/ioutil 118s github.com/google/renameio 118s github.com/mattn/go-sqlite3 118s crypto/x509 119s crypto/tls 121s github.com/canonical/go-dqlite/internal/protocol 121s regexp/syntax 122s regexp 122s gopkg.in/yaml.v2 122s github.com/canonical/go-dqlite/internal/bindings 123s github.com/canonical/go-dqlite/client 123s github.com/canonical/go-dqlite/tracing 123s github.com/canonical/go-dqlite/driver 123s golang.org/x/sys/unix 124s github.com/canonical/go-dqlite 124s log 124s encoding/json 125s github.com/canonical/go-dqlite/app 125s text/tabwriter 125s github.com/canonical/go-dqlite/internal/shell 125s github.com/mattn/go-runewidth 125s github.com/canonical/go-dqlite/benchmark 125s os/signal 125s github.com/peterh/liner 125s encoding/csv 125s github.com/spf13/pflag 125s text/template/parse 126s text/template 126s compress/flate 126s github.com/spf13/cobra 126s hash/crc32 126s compress/gzip 126s vendor/golang.org/x/text/transform 126s vendor/golang.org/x/text/unicode/bidi 127s vendor/golang.org/x/text/secure/bidirule 127s vendor/golang.org/x/text/unicode/norm 127s github.com/canonical/go-dqlite/cmd/dqlite 127s github.com/canonical/go-dqlite/cmd/dqlite-benchmark 128s vendor/golang.org/x/net/idna 128s net/textproto 128s vendor/golang.org/x/net/http2/hpack 128s vendor/golang.org/x/net/http/httpguts 128s vendor/golang.org/x/net/http/httpproxy 128s mime 128s mime/quotedprintable 128s net/http/httptrace 128s net/http/internal 128s net/http/internal/ascii 128s net/http/internal/httpcommon 129s mime/multipart 129s net/http 131s github.com/canonical/go-dqlite/cmd/dqlite-demo 132s create-stamp debian/debhelper-build-stamp 132s dh_auto_test -O--builddirectory=_build -O--buildsystem=golang 132s cd _build && go test -vet=off -v -p 2 github.com/canonical/go-dqlite github.com/canonical/go-dqlite/app github.com/canonical/go-dqlite/benchmark github.com/canonical/go-dqlite/client github.com/canonical/go-dqlite/cmd/dqlite github.com/canonical/go-dqlite/cmd/dqlite-benchmark github.com/canonical/go-dqlite/cmd/dqlite-demo github.com/canonical/go-dqlite/driver github.com/canonical/go-dqlite/internal/bindings github.com/canonical/go-dqlite/internal/protocol github.com/canonical/go-dqlite/internal/shell github.com/canonical/go-dqlite/logging github.com/canonical/go-dqlite/tracing 154s === RUN ExampleLastEntryInfo 154s --- PASS: ExampleLastEntryInfo (0.00s) 154s PASS 154s ok github.com/canonical/go-dqlite 0.008s 335s === RUN TestNew_PristineDefault 335s --- PASS: TestNew_PristineDefault (0.10s) 335s === RUN TestNew_PristineJoiner 335s app_test.go:1207: 03:10:10.324 - 2: DEBUG: new connection from 127.0.0.1:50774 335s app_test.go:1207: 03:10:10.325 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.327 - 2: DEBUG: new connection from 127.0.0.1:50790 335s app_test.go:1207: 03:10:10.400 - 2: DEBUG: new connection from 127.0.0.1:50796 335s app_test.go:1207: 03:10:10.402 - 3: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.410 - 3: DEBUG: new connection from 127.0.0.1:58492 335s app_test.go:1207: 03:10:10.410 - 2: DEBUG: new connection from 127.0.0.1:50810 335s app_test.go:1207: 03:10:10.412 - 2: DEBUG: new connection from 127.0.0.1:50818 335s app_test.go:1207: 03:10:10.413 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s --- PASS: TestNew_PristineJoiner (0.27s) 335s === RUN TestNew_JoinerRestart 335s app_test.go:1207: 03:10:10.583 - 4: DEBUG: new connection from 127.0.0.1:50824 335s app_test.go:1207: 03:10:10.585 - 4: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.587 - 4: DEBUG: new connection from 127.0.0.1:50840 335s app_test.go:1207: 03:10:10.670 - 4: DEBUG: new connection from 127.0.0.1:50852 335s app_test.go:1207: 03:10:10.671 - 5: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.678 - 5: DEBUG: new connection from 127.0.0.1:58498 335s app_test.go:1207: 03:10:10.678 - 4: DEBUG: new connection from 127.0.0.1:50858 335s app_test.go:1207: 03:10:10.770 - 4: DEBUG: new connection from 127.0.0.1:50862 335s app_test.go:1207: 03:10:10.770 - 6: DEBUG: new connection from 127.0.0.1:58504 335s app_test.go:1207: 03:10:10.771 - 6: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.771 - 6: WARN: attempt 1: server 127.0.0.1:9002: no known leader 335s app_test.go:1207: 03:10:10.775 - 4: DEBUG: new connection from 127.0.0.1:50870 335s app_test.go:1207: 03:10:10.775 - 6: DEBUG: new connection from 127.0.0.1:58506 335s --- PASS: TestNew_JoinerRestart (0.35s) 335s === RUN TestNew_SecondJoiner 335s app_test.go:1207: 03:10:10.933 - 7: DEBUG: new connection from 127.0.0.1:50876 335s app_test.go:1207: 03:10:10.935 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.938 - 7: DEBUG: new connection from 127.0.0.1:50884 335s app_test.go:1207: 03:10:10.020 - 7: DEBUG: new connection from 127.0.0.1:50890 335s app_test.go:1207: 03:10:10.022 - 8: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.026 - 8: DEBUG: new connection from 127.0.0.1:58516 335s app_test.go:1207: 03:10:10.027 - 7: DEBUG: new connection from 127.0.0.1:50896 335s app_test.go:1207: 03:10:10.110 - 7: DEBUG: new connection from 127.0.0.1:50908 335s app_test.go:1207: 03:10:10.111 - 9: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.114 - 7: DEBUG: new connection from 127.0.0.1:50924 335s app_test.go:1207: 03:10:10.114 - 8: DEBUG: new connection from 127.0.0.1:58524 335s app_test.go:1207: 03:10:10.115 - 9: DEBUG: new connection from 127.0.0.1:49422 335s app_test.go:1207: 03:10:10.116 - 9: DEBUG: new connection from 127.0.0.1:49426 335s app_test.go:1207: 03:10:10.120 - 7: DEBUG: new connection from 127.0.0.1:50926 335s app_test.go:1207: 03:10:10.850 - 8: DEBUG: new connection from 127.0.0.1:58538 335s app_test.go:1207: 03:10:10.908 - 7: DEBUG: new connection from 127.0.0.1:50928 335s app_test.go:1207: 03:10:10.850 - 7: DEBUG: new connection from 127.0.0.1:50932 335s app_test.go:1207: 03:10:10.852 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s --- PASS: TestNew_SecondJoiner (2.26s) 335s === RUN TestNew_ThirdJoiner 335s app_test.go:1207: 03:10:10.173 - 10: DEBUG: new connection from 127.0.0.1:50934 335s app_test.go:1207: 03:10:10.175 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.177 - 10: DEBUG: new connection from 127.0.0.1:50940 335s app_test.go:1207: 03:10:10.252 - 10: DEBUG: new connection from 127.0.0.1:50944 335s app_test.go:1207: 03:10:10.253 - 11: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.261 - 10: DEBUG: new connection from 127.0.0.1:50958 335s app_test.go:1207: 03:10:10.261 - 11: DEBUG: new connection from 127.0.0.1:58552 335s app_test.go:1207: 03:10:10.360 - 10: DEBUG: new connection from 127.0.0.1:50960 335s app_test.go:1207: 03:10:10.361 - 12: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.364 - 11: DEBUG: new connection from 127.0.0.1:58560 335s app_test.go:1207: 03:10:10.365 - 10: DEBUG: new connection from 127.0.0.1:50962 335s app_test.go:1207: 03:10:10.365 - 12: DEBUG: new connection from 127.0.0.1:49428 335s app_test.go:1207: 03:10:10.367 - 12: DEBUG: new connection from 127.0.0.1:49432 335s app_test.go:1207: 03:10:10.370 - 10: DEBUG: new connection from 127.0.0.1:50976 335s app_test.go:1207: 03:10:10.109 - 11: DEBUG: new connection from 127.0.0.1:58574 335s app_test.go:1207: 03:10:10.112 - 10: DEBUG: new connection from 127.0.0.1:50980 335s app_test.go:1207: 03:10:10.200 - 10: DEBUG: new connection from 127.0.0.1:50992 335s app_test.go:1207: 03:10:10.201 - 13: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.204 - 11: DEBUG: new connection from 127.0.0.1:58578 335s app_test.go:1207: 03:10:10.204 - 10: DEBUG: new connection from 127.0.0.1:51004 335s app_test.go:1207: 03:10:10.205 - 12: DEBUG: new connection from 127.0.0.1:49434 335s app_test.go:1207: 03:10:10.205 - 13: DEBUG: new connection from 127.0.0.1:33226 335s app_test.go:1207: 03:10:10.207 - 13: DEBUG: new connection from 127.0.0.1:33236 335s app_test.go:1207: 03:10:10.207 - 10: DEBUG: new connection from 127.0.0.1:51018 335s app_test.go:1207: 03:10:10.209 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s --- PASS: TestNew_ThirdJoiner (2.34s) 335s === RUN TestNew_FourthJoiner 335s app_test.go:1207: 03:10:10.533 - 14: DEBUG: new connection from 127.0.0.1:51028 335s app_test.go:1207: 03:10:10.534 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.536 - 14: DEBUG: new connection from 127.0.0.1:51036 335s app_test.go:1207: 03:10:10.630 - 14: DEBUG: new connection from 127.0.0.1:51046 335s app_test.go:1207: 03:10:10.632 - 15: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.637 - 14: DEBUG: new connection from 127.0.0.1:51060 335s app_test.go:1207: 03:10:10.637 - 15: DEBUG: new connection from 127.0.0.1:58592 335s app_test.go:1207: 03:10:10.730 - 14: DEBUG: new connection from 127.0.0.1:51066 335s app_test.go:1207: 03:10:10.731 - 16: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.733 - 15: DEBUG: new connection from 127.0.0.1:58600 335s app_test.go:1207: 03:10:10.733 - 14: DEBUG: new connection from 127.0.0.1:51070 335s app_test.go:1207: 03:10:10.734 - 16: DEBUG: new connection from 127.0.0.1:49444 335s app_test.go:1207: 03:10:10.735 - 16: DEBUG: new connection from 127.0.0.1:49458 335s app_test.go:1207: 03:10:10.738 - 14: DEBUG: new connection from 127.0.0.1:51082 335s app_test.go:1207: 03:10:10.448 - 15: DEBUG: new connection from 127.0.0.1:58614 335s app_test.go:1207: 03:10:10.450 - 14: DEBUG: new connection from 127.0.0.1:51096 335s app_test.go:1207: 03:10:10.540 - 14: DEBUG: new connection from 127.0.0.1:58350 335s app_test.go:1207: 03:10:10.542 - 17: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 335s app_test.go:1207: 03:10:10.544 - 15: DEBUG: new connection from 127.0.0.1:60682 335s app_test.go:1207: 03:10:10.544 - 14: DEBUG: new connection from 127.0.0.1:58352 335s app_test.go:1207: 03:10:10.546 - 16: DEBUG: new connection from 127.0.0.1:44826 335s app_test.go:1207: 03:10:10.547 - 17: DEBUG: new connection from 127.0.0.1:44686 336s app_test.go:1207: 03:10:10.548 - 17: DEBUG: new connection from 127.0.0.1:44688 336s app_test.go:1207: 03:10:10.552 - 14: DEBUG: new connection from 127.0.0.1:58358 336s app_test.go:1207: 03:10:10.630 - 14: DEBUG: new connection from 127.0.0.1:58366 336s app_test.go:1207: 03:10:10.631 - 18: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.634 - 15: DEBUG: new connection from 127.0.0.1:60692 336s app_test.go:1207: 03:10:10.634 - 14: DEBUG: new connection from 127.0.0.1:58372 336s app_test.go:1207: 03:10:10.635 - 16: DEBUG: new connection from 127.0.0.1:44834 336s app_test.go:1207: 03:10:10.635 - 17: DEBUG: new connection from 127.0.0.1:44696 336s app_test.go:1207: 03:10:10.636 - 18: DEBUG: new connection from 127.0.0.1:37886 336s app_test.go:1207: 03:10:10.638 - 18: DEBUG: new connection from 127.0.0.1:37890 336s app_test.go:1207: 03:10:10.639 - 14: DEBUG: new connection from 127.0.0.1:58382 336s app_test.go:1207: 03:10:10.640 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s --- PASS: TestNew_FourthJoiner (2.44s) 336s === RUN TestNew_FifthJoiner 336s app_test.go:1207: 03:10:10.962 - 19: DEBUG: new connection from 127.0.0.1:58398 336s app_test.go:1207: 03:10:10.964 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.965 - 19: DEBUG: new connection from 127.0.0.1:58410 336s app_test.go:1207: 03:10:10.050 - 19: DEBUG: new connection from 127.0.0.1:58420 336s app_test.go:1207: 03:10:10.052 - 20: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.055 - 20: DEBUG: new connection from 127.0.0.1:60708 336s app_test.go:1207: 03:10:10.056 - 19: DEBUG: new connection from 127.0.0.1:58436 336s app_test.go:1207: 03:10:10.140 - 19: DEBUG: new connection from 127.0.0.1:58446 336s app_test.go:1207: 03:10:10.141 - 21: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.144 - 20: DEBUG: new connection from 127.0.0.1:60714 336s app_test.go:1207: 03:10:10.144 - 19: DEBUG: new connection from 127.0.0.1:58452 336s app_test.go:1207: 03:10:10.146 - 21: DEBUG: new connection from 127.0.0.1:44848 336s app_test.go:1207: 03:10:10.147 - 21: DEBUG: new connection from 127.0.0.1:44854 336s app_test.go:1207: 03:10:10.149 - 19: DEBUG: new connection from 127.0.0.1:58454 336s app_test.go:1207: 03:10:10.889 - 20: DEBUG: new connection from 127.0.0.1:60730 336s app_test.go:1207: 03:10:10.891 - 19: DEBUG: new connection from 127.0.0.1:58458 336s app_test.go:1207: 03:10:10.970 - 19: DEBUG: new connection from 127.0.0.1:58462 336s app_test.go:1207: 03:10:10.971 - 22: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.974 - 19: DEBUG: new connection from 127.0.0.1:58464 336s app_test.go:1207: 03:10:10.974 - 20: DEBUG: new connection from 127.0.0.1:60742 336s app_test.go:1207: 03:10:10.975 - 21: DEBUG: new connection from 127.0.0.1:44864 336s app_test.go:1207: 03:10:10.976 - 22: DEBUG: new connection from 127.0.0.1:44702 336s app_test.go:1207: 03:10:10.977 - 22: DEBUG: new connection from 127.0.0.1:44718 336s app_test.go:1207: 03:10:10.982 - 19: DEBUG: new connection from 127.0.0.1:58466 336s app_test.go:1207: 03:10:10.060 - 19: DEBUG: new connection from 127.0.0.1:58482 336s app_test.go:1207: 03:10:10.061 - 23: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.064 - 20: DEBUG: new connection from 127.0.0.1:60756 336s app_test.go:1207: 03:10:10.064 - 19: DEBUG: new connection from 127.0.0.1:58488 336s app_test.go:1207: 03:10:10.065 - 21: DEBUG: new connection from 127.0.0.1:44868 336s app_test.go:1207: 03:10:10.065 - 22: DEBUG: new connection from 127.0.0.1:44722 336s app_test.go:1207: 03:10:10.066 - 23: DEBUG: new connection from 127.0.0.1:37898 336s app_test.go:1207: 03:10:10.067 - 23: DEBUG: new connection from 127.0.0.1:37900 336s app_test.go:1207: 03:10:10.070 - 19: DEBUG: new connection from 127.0.0.1:58496 336s app_test.go:1207: 03:10:10.160 - 19: DEBUG: new connection from 127.0.0.1:58504 336s app_test.go:1207: 03:10:10.179 - 24: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.181 - 20: DEBUG: new connection from 127.0.0.1:60768 336s app_test.go:1207: 03:10:10.182 - 19: DEBUG: new connection from 127.0.0.1:58520 336s app_test.go:1207: 03:10:10.183 - 21: DEBUG: new connection from 127.0.0.1:44876 336s app_test.go:1207: 03:10:10.183 - 22: DEBUG: new connection from 127.0.0.1:44738 336s app_test.go:1207: 03:10:10.184 - 23: DEBUG: new connection from 127.0.0.1:37902 336s app_test.go:1207: 03:10:10.184 - 24: DEBUG: new connection from 127.0.0.1:58818 336s app_test.go:1207: 03:10:10.185 - 24: DEBUG: new connection from 127.0.0.1:58820 336s app_test.go:1207: 03:10:10.186 - 19: DEBUG: new connection from 127.0.0.1:58536 336s app_test.go:1207: 03:10:10.187 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s --- PASS: TestNew_FifthJoiner (2.55s) 336s === RUN TestNew_SixthJoiner 336s app_test.go:1207: 03:10:10.513 - 25: DEBUG: new connection from 127.0.0.1:58548 336s app_test.go:1207: 03:10:10.514 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.516 - 25: DEBUG: new connection from 127.0.0.1:58560 336s app_test.go:1207: 03:10:10.600 - 25: DEBUG: new connection from 127.0.0.1:58564 336s app_test.go:1207: 03:10:10.601 - 26: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.606 - 25: DEBUG: new connection from 127.0.0.1:58576 336s app_test.go:1207: 03:10:10.606 - 26: DEBUG: new connection from 127.0.0.1:60770 336s app_test.go:1207: 03:10:10.690 - 25: DEBUG: new connection from 127.0.0.1:58586 336s app_test.go:1207: 03:10:10.691 - 27: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.694 - 26: DEBUG: new connection from 127.0.0.1:60778 336s app_test.go:1207: 03:10:10.694 - 25: DEBUG: new connection from 127.0.0.1:58594 336s app_test.go:1207: 03:10:10.694 - 27: DEBUG: new connection from 127.0.0.1:44880 336s app_test.go:1207: 03:10:10.695 - 27: DEBUG: new connection from 127.0.0.1:44892 336s app_test.go:1207: 03:10:10.698 - 25: DEBUG: new connection from 127.0.0.1:58598 336s app_test.go:1207: 03:10:10.438 - 26: DEBUG: new connection from 127.0.0.1:60792 336s app_test.go:1207: 03:10:10.441 - 25: DEBUG: new connection from 127.0.0.1:58602 336s app_test.go:1207: 03:10:10.530 - 25: DEBUG: new connection from 127.0.0.1:58614 336s app_test.go:1207: 03:10:10.531 - 28: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.534 - 26: DEBUG: new connection from 127.0.0.1:60806 336s app_test.go:1207: 03:10:10.534 - 25: DEBUG: new connection from 127.0.0.1:58618 336s app_test.go:1207: 03:10:10.535 - 27: DEBUG: new connection from 127.0.0.1:44896 336s app_test.go:1207: 03:10:10.535 - 28: DEBUG: new connection from 127.0.0.1:44748 336s app_test.go:1207: 03:10:10.536 - 28: DEBUG: new connection from 127.0.0.1:44762 336s app_test.go:1207: 03:10:10.540 - 25: DEBUG: new connection from 127.0.0.1:58632 336s app_test.go:1207: 03:10:10.630 - 25: DEBUG: new connection from 127.0.0.1:58644 336s app_test.go:1207: 03:10:10.632 - 29: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.635 - 26: DEBUG: new connection from 127.0.0.1:60812 336s app_test.go:1207: 03:10:10.635 - 25: DEBUG: new connection from 127.0.0.1:58646 336s app_test.go:1207: 03:10:10.637 - 27: DEBUG: new connection from 127.0.0.1:44898 336s app_test.go:1207: 03:10:10.638 - 28: DEBUG: new connection from 127.0.0.1:44766 336s app_test.go:1207: 03:10:10.638 - 29: DEBUG: new connection from 127.0.0.1:37906 336s app_test.go:1207: 03:10:10.640 - 29: DEBUG: new connection from 127.0.0.1:37908 336s app_test.go:1207: 03:10:10.643 - 25: DEBUG: new connection from 127.0.0.1:58654 336s app_test.go:1207: 03:10:10.720 - 25: DEBUG: new connection from 127.0.0.1:58670 336s app_test.go:1207: 03:10:10.722 - 30: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.724 - 26: DEBUG: new connection from 127.0.0.1:60820 336s app_test.go:1207: 03:10:10.724 - 25: DEBUG: new connection from 127.0.0.1:58674 336s app_test.go:1207: 03:10:10.727 - 27: DEBUG: new connection from 127.0.0.1:44908 336s app_test.go:1207: 03:10:10.727 - 28: DEBUG: new connection from 127.0.0.1:44782 336s app_test.go:1207: 03:10:10.728 - 29: DEBUG: new connection from 127.0.0.1:37922 336s app_test.go:1207: 03:10:10.728 - 30: DEBUG: new connection from 127.0.0.1:58830 336s app_test.go:1207: 03:10:10.730 - 30: DEBUG: new connection from 127.0.0.1:58832 336s app_test.go:1207: 03:10:10.732 - 25: DEBUG: new connection from 127.0.0.1:58688 336s app_test.go:1207: 03:10:10.820 - 25: DEBUG: new connection from 127.0.0.1:58704 336s app_test.go:1207: 03:10:10.822 - 31: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.824 - 25: DEBUG: new connection from 127.0.0.1:58712 336s app_test.go:1207: 03:10:10.825 - 26: DEBUG: new connection from 127.0.0.1:60828 336s app_test.go:1207: 03:10:10.826 - 27: DEBUG: new connection from 127.0.0.1:44914 336s app_test.go:1207: 03:10:10.827 - 28: DEBUG: new connection from 127.0.0.1:44786 336s app_test.go:1207: 03:10:10.828 - 29: DEBUG: new connection from 127.0.0.1:37924 336s app_test.go:1207: 03:10:10.829 - 30: DEBUG: new connection from 127.0.0.1:58844 336s app_test.go:1207: 03:10:10.830 - 31: DEBUG: new connection from 127.0.0.1:35052 336s app_test.go:1207: 03:10:10.831 - 25: DEBUG: new connection from 127.0.0.1:58724 336s app_test.go:1207: 03:10:10.832 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s --- PASS: TestNew_SixthJoiner (2.68s) 336s === RUN TestHandover_Voter 336s app_test.go:1207: 03:10:10.203 - 32: DEBUG: new connection from 127.0.0.1:58740 336s app_test.go:1207: 03:10:10.205 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.207 - 32: DEBUG: new connection from 127.0.0.1:58744 336s app_test.go:1207: 03:10:10.290 - 32: DEBUG: new connection from 127.0.0.1:58754 336s app_test.go:1207: 03:10:10.291 - 33: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.295 - 32: DEBUG: new connection from 127.0.0.1:58756 336s app_test.go:1207: 03:10:10.295 - 33: DEBUG: new connection from 127.0.0.1:60844 336s app_test.go:1207: 03:10:10.380 - 32: DEBUG: new connection from 127.0.0.1:58770 336s app_test.go:1207: 03:10:10.381 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.383 - 32: DEBUG: new connection from 127.0.0.1:58776 336s app_test.go:1207: 03:10:10.383 - 33: DEBUG: new connection from 127.0.0.1:60848 336s app_test.go:1207: 03:10:10.385 - 34: DEBUG: new connection from 127.0.0.1:44920 336s app_test.go:1207: 03:10:10.387 - 34: DEBUG: new connection from 127.0.0.1:44928 336s app_test.go:1207: 03:10:10.390 - 32: DEBUG: new connection from 127.0.0.1:58786 336s app_test.go:1207: 03:10:10.119 - 33: DEBUG: new connection from 127.0.0.1:60852 336s app_test.go:1207: 03:10:10.121 - 32: DEBUG: new connection from 127.0.0.1:58792 336s app_test.go:1207: 03:10:10.210 - 32: DEBUG: new connection from 127.0.0.1:58804 336s app_test.go:1207: 03:10:10.213 - 35: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.215 - 32: DEBUG: new connection from 127.0.0.1:58816 336s app_test.go:1207: 03:10:10.215 - 33: DEBUG: new connection from 127.0.0.1:60868 336s app_test.go:1207: 03:10:10.216 - 34: DEBUG: new connection from 127.0.0.1:44944 336s app_test.go:1207: 03:10:10.216 - 35: DEBUG: new connection from 127.0.0.1:44802 336s app_test.go:1207: 03:10:10.218 - 35: DEBUG: new connection from 127.0.0.1:44812 336s app_test.go:1207: 03:10:10.219 - 32: DEBUG: new connection from 127.0.0.1:58826 336s app_test.go:1207: 03:10:10.220 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.220 - 32: DEBUG: new connection from 127.0.0.1:58832 336s app_test.go:1207: 03:10:10.221 - 32: DEBUG: new connection from 127.0.0.1:58840 336s app_test.go:1207: 03:10:10.221 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:10:10.222 - 32: DEBUG: new connection from 127.0.0.1:58856 336s app_test.go:1207: 03:10:10.222 - 33: DEBUG: new connection from 127.0.0.1:60880 336s app_test.go:1207: 03:10:10.223 - 34: DEBUG: new connection from 127.0.0.1:44948 336s app_test.go:1207: 03:10:10.224 - 35: DEBUG: new connection from 127.0.0.1:44818 336s app_test.go:1207: 03:10:10.120 - 34: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 336s --- PASS: TestHandover_Voter (3.35s) 336s === RUN TestHandover_TwoNodes 336s app_test.go:1207: 03:10:10.552 - 36: DEBUG: new connection from 127.0.0.1:58870 336s app_test.go:1207: 03:10:10.554 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.555 - 36: DEBUG: new connection from 127.0.0.1:58880 336s app_test.go:1207: 03:10:10.640 - 36: DEBUG: new connection from 127.0.0.1:58890 336s app_test.go:1207: 03:10:10.642 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.646 - 37: DEBUG: new connection from 127.0.0.1:60884 336s app_test.go:1207: 03:10:10.647 - 36: DEBUG: new connection from 127.0.0.1:58904 336s app_test.go:1207: 03:10:10.648 - 36: DEBUG: new connection from 127.0.0.1:58908 336s app_test.go:1207: 03:10:10.648 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:10:10.649 - 36: DEBUG: new connection from 127.0.0.1:58910 336s app_test.go:1207: 03:10:10.649 - 37: DEBUG: new connection from 127.0.0.1:60888 336s app_test.go:1207: 03:10:10.651 - 37: DEBUG: new connection from 127.0.0.1:60894 336s app_test.go:1207: 03:10:10.653 - 36: DEBUG: new connection from 127.0.0.1:58920 336s app_test.go:1207: 03:10:10.469 - 36: DEBUG: promoted 127.0.0.1:9002 from spare to voter 336s app_test.go:1207: 03:10:10.470 - 37: DEBUG: new connection from 127.0.0.1:47256 336s app_test.go:1207: 03:10:10.470 - 36: DEBUG: new connection from 127.0.0.1:53728 336s app_test.go:1207: 03:10:10.474 - 36: DEBUG: new connection from 127.0.0.1:53730 336s app_test.go:1207: 03:10:10.475 - 36: DEBUG: new connection from 127.0.0.1:53732 336s app_test.go:1207: 03:10:10.476 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 336s app_test.go:1207: 03:10:10.476 - 37: DEBUG: new connection from 127.0.0.1:47266 336s app_test.go:1207: 03:10:10.477 - 36: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 336s app_test.go:1207: 03:10:10.478 - 37: DEBUG: new connection from 127.0.0.1:47270 336s app_test.go:1207: 03:10:10.478 - 36: DEBUG: new connection from 127.0.0.1:53744 336s app_test.go:1207: 03:10:10.478 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 336s app_test.go:1207: 03:10:10.478 - 37: DEBUG: new connection from 127.0.0.1:47282 336s app_test.go:1207: 03:10:10.479 - 37: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 336s --- PASS: TestHandover_TwoNodes (1.18s) 336s === RUN TestHandover_VoterHonorFailureDomain 336s app_test.go:1207: 03:10:10.722 - 38: DEBUG: new connection from 127.0.0.1:53746 336s app_test.go:1207: 03:10:10.724 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.725 - 38: DEBUG: new connection from 127.0.0.1:53756 336s app_test.go:1207: 03:10:10.820 - 38: DEBUG: new connection from 127.0.0.1:53762 336s app_test.go:1207: 03:10:10.823 - 39: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.827 - 39: DEBUG: new connection from 127.0.0.1:47298 336s app_test.go:1207: 03:10:10.827 - 38: DEBUG: new connection from 127.0.0.1:53776 336s app_test.go:1207: 03:10:10.910 - 38: DEBUG: new connection from 127.0.0.1:53782 336s app_test.go:1207: 03:10:10.911 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.914 - 38: DEBUG: new connection from 127.0.0.1:53788 336s app_test.go:1207: 03:10:10.914 - 39: DEBUG: new connection from 127.0.0.1:47304 336s app_test.go:1207: 03:10:10.915 - 40: DEBUG: new connection from 127.0.0.1:47104 336s app_test.go:1207: 03:10:10.916 - 40: DEBUG: new connection from 127.0.0.1:47120 336s app_test.go:1207: 03:10:10.918 - 38: DEBUG: new connection from 127.0.0.1:53794 336s app_test.go:1207: 03:10:10.649 - 39: DEBUG: new connection from 127.0.0.1:47316 336s app_test.go:1207: 03:10:10.651 - 38: DEBUG: new connection from 127.0.0.1:53796 336s app_test.go:1207: 03:10:10.740 - 38: DEBUG: new connection from 127.0.0.1:53808 336s app_test.go:1207: 03:10:10.741 - 41: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.744 - 39: DEBUG: new connection from 127.0.0.1:47322 336s app_test.go:1207: 03:10:10.744 - 38: DEBUG: new connection from 127.0.0.1:53814 336s app_test.go:1207: 03:10:10.746 - 40: DEBUG: new connection from 127.0.0.1:47136 336s app_test.go:1207: 03:10:10.747 - 41: DEBUG: new connection from 127.0.0.1:47412 336s app_test.go:1207: 03:10:10.748 - 41: DEBUG: new connection from 127.0.0.1:47426 336s app_test.go:1207: 03:10:10.751 - 38: DEBUG: new connection from 127.0.0.1:53826 336s app_test.go:1207: 03:10:10.840 - 38: DEBUG: new connection from 127.0.0.1:53830 336s app_test.go:1207: 03:10:10.841 - 42: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.844 - 39: DEBUG: new connection from 127.0.0.1:47332 336s app_test.go:1207: 03:10:10.844 - 38: DEBUG: new connection from 127.0.0.1:53846 336s app_test.go:1207: 03:10:10.845 - 40: DEBUG: new connection from 127.0.0.1:47150 336s app_test.go:1207: 03:10:10.846 - 41: DEBUG: new connection from 127.0.0.1:47428 336s app_test.go:1207: 03:10:10.846 - 42: DEBUG: new connection from 127.0.0.1:34052 336s app_test.go:1207: 03:10:10.849 - 42: DEBUG: new connection from 127.0.0.1:34054 336s app_test.go:1207: 03:10:10.852 - 38: DEBUG: new connection from 127.0.0.1:53852 336s app_test.go:1207: 03:10:10.930 - 38: DEBUG: new connection from 127.0.0.1:53868 336s app_test.go:1207: 03:10:10.932 - 43: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.934 - 39: DEBUG: new connection from 127.0.0.1:47344 336s app_test.go:1207: 03:10:10.934 - 38: DEBUG: new connection from 127.0.0.1:53876 336s app_test.go:1207: 03:10:10.935 - 40: DEBUG: new connection from 127.0.0.1:47160 336s app_test.go:1207: 03:10:10.935 - 41: DEBUG: new connection from 127.0.0.1:47434 336s app_test.go:1207: 03:10:10.938 - 42: DEBUG: new connection from 127.0.0.1:34056 336s app_test.go:1207: 03:10:10.938 - 43: DEBUG: new connection from 127.0.0.1:38424 336s app_test.go:1207: 03:10:10.939 - 43: DEBUG: new connection from 127.0.0.1:38426 336s app_test.go:1207: 03:10:10.941 - 38: DEBUG: new connection from 127.0.0.1:53890 336s app_test.go:1207: 03:10:10.942 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:10:10.942 - 38: DEBUG: new connection from 127.0.0.1:53902 336s app_test.go:1207: 03:10:10.943 - 38: DEBUG: new connection from 127.0.0.1:53906 336s app_test.go:1207: 03:10:10.943 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:10:10.944 - 38: DEBUG: new connection from 127.0.0.1:53916 336s app_test.go:1207: 03:10:10.945 - 39: DEBUG: new connection from 127.0.0.1:47350 336s app_test.go:1207: 03:10:10.946 - 40: DEBUG: new connection from 127.0.0.1:47168 336s app_test.go:1207: 03:10:10.946 - 41: DEBUG: new connection from 127.0.0.1:47440 336s app_test.go:1207: 03:10:10.947 - 42: DEBUG: new connection from 127.0.0.1:34058 336s app_test.go:1207: 03:10:10.948 - 43: DEBUG: new connection from 127.0.0.1:38440 336s app_test.go:1207: 03:10:10.651 - 40: DEBUG: promoted 127.0.0.1:9006 from stand-by to voter 336s --- PASS: TestHandover_VoterHonorFailureDomain (3.53s) 336s === RUN TestHandover_SingleNode 336s --- PASS: TestHandover_SingleNode (0.08s) 336s === RUN TestHandover_GracefulShutdown 336s app_test.go:406: 03:10:10.332 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:406: 03:10:10.410 - 1: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:406: 03:10:10.490 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:406: 03:11:10.252 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:406: 03:11:10.256 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:406: 03:11:10.262 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 336s app_test.go:406: 03:11:10.262 - 0: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 336s app_test.go:406: 03:11:10.350 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 336s app_test.go:406: 03:11:10.350 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 336s app_test.go:406: 03:11:10.352 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 336s app_test.go:406: 03:11:10.352 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9003 336s app_test.go:406: 03:11:10.353 - 1: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 336s app_test.go:406: 03:11:10.409 - 2: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 336s app_test.go:406: 03:11:10.409 - 2: WARN: attempt 1: server 127.0.0.1:9002: dial: dial tcp 127.0.0.1:9002: connect: connection refused 336s app_test.go:406: 03:11:10.409 - 2: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 336s --- PASS: TestHandover_GracefulShutdown (2.27s) 336s === RUN TestHandover_StandBy 336s app_test.go:1207: 03:11:10.602 - 44: DEBUG: new connection from 127.0.0.1:54226 336s app_test.go:1207: 03:11:10.603 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.605 - 44: DEBUG: new connection from 127.0.0.1:54228 336s app_test.go:1207: 03:11:10.690 - 44: DEBUG: new connection from 127.0.0.1:54236 336s app_test.go:1207: 03:11:10.691 - 45: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.695 - 44: DEBUG: new connection from 127.0.0.1:54244 336s app_test.go:1207: 03:11:10.695 - 45: DEBUG: new connection from 127.0.0.1:47514 336s app_test.go:1207: 03:11:10.800 - 44: DEBUG: new connection from 127.0.0.1:54260 336s app_test.go:1207: 03:11:10.801 - 46: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.805 - 45: DEBUG: new connection from 127.0.0.1:47526 336s app_test.go:1207: 03:11:10.805 - 44: DEBUG: new connection from 127.0.0.1:54274 336s app_test.go:1207: 03:11:10.806 - 46: DEBUG: new connection from 127.0.0.1:47268 336s app_test.go:1207: 03:11:10.807 - 46: DEBUG: new connection from 127.0.0.1:47284 336s app_test.go:1207: 03:11:10.809 - 44: DEBUG: new connection from 127.0.0.1:54288 336s app_test.go:1207: 03:11:10.521 - 45: DEBUG: new connection from 127.0.0.1:47542 336s app_test.go:1207: 03:11:10.523 - 44: DEBUG: new connection from 127.0.0.1:54300 336s app_test.go:1207: 03:11:10.600 - 44: DEBUG: new connection from 127.0.0.1:54302 336s app_test.go:1207: 03:11:10.601 - 47: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.603 - 44: DEBUG: new connection from 127.0.0.1:54310 336s app_test.go:1207: 03:11:10.604 - 45: DEBUG: new connection from 127.0.0.1:47546 336s app_test.go:1207: 03:11:10.605 - 46: DEBUG: new connection from 127.0.0.1:47288 336s app_test.go:1207: 03:11:10.606 - 47: DEBUG: new connection from 127.0.0.1:47442 336s app_test.go:1207: 03:11:10.607 - 47: DEBUG: new connection from 127.0.0.1:47446 336s app_test.go:1207: 03:11:10.610 - 44: DEBUG: new connection from 127.0.0.1:54322 336s app_test.go:1207: 03:11:10.690 - 44: DEBUG: new connection from 127.0.0.1:54332 336s app_test.go:1207: 03:11:10.691 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.693 - 44: DEBUG: new connection from 127.0.0.1:54336 336s app_test.go:1207: 03:11:10.693 - 45: DEBUG: new connection from 127.0.0.1:47558 336s app_test.go:1207: 03:11:10.695 - 46: DEBUG: new connection from 127.0.0.1:47294 336s app_test.go:1207: 03:11:10.697 - 47: DEBUG: new connection from 127.0.0.1:47458 336s app_test.go:1207: 03:11:10.697 - 48: DEBUG: new connection from 127.0.0.1:34062 336s app_test.go:1207: 03:11:10.698 - 48: DEBUG: new connection from 127.0.0.1:34066 336s app_test.go:1207: 03:11:10.702 - 44: DEBUG: new connection from 127.0.0.1:54346 336s app_test.go:1207: 03:11:10.780 - 44: DEBUG: new connection from 127.0.0.1:54362 336s app_test.go:1207: 03:11:10.781 - 49: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.784 - 45: DEBUG: new connection from 127.0.0.1:47574 336s app_test.go:1207: 03:11:10.784 - 44: DEBUG: new connection from 127.0.0.1:54366 336s app_test.go:1207: 03:11:10.785 - 46: DEBUG: new connection from 127.0.0.1:47302 336s app_test.go:1207: 03:11:10.785 - 47: DEBUG: new connection from 127.0.0.1:47464 336s app_test.go:1207: 03:11:10.786 - 48: DEBUG: new connection from 127.0.0.1:34070 336s app_test.go:1207: 03:11:10.787 - 49: DEBUG: new connection from 127.0.0.1:38454 336s app_test.go:1207: 03:11:10.788 - 49: DEBUG: new connection from 127.0.0.1:38462 336s app_test.go:1207: 03:11:10.791 - 44: DEBUG: new connection from 127.0.0.1:54372 336s app_test.go:1207: 03:11:10.880 - 44: DEBUG: new connection from 127.0.0.1:54378 336s app_test.go:1207: 03:11:10.882 - 50: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.884 - 45: DEBUG: new connection from 127.0.0.1:47582 336s app_test.go:1207: 03:11:10.884 - 44: DEBUG: new connection from 127.0.0.1:54384 336s app_test.go:1207: 03:11:10.885 - 46: DEBUG: new connection from 127.0.0.1:47304 336s app_test.go:1207: 03:11:10.886 - 47: DEBUG: new connection from 127.0.0.1:47470 336s app_test.go:1207: 03:11:10.886 - 48: DEBUG: new connection from 127.0.0.1:34084 336s app_test.go:1207: 03:11:10.887 - 49: DEBUG: new connection from 127.0.0.1:38472 336s app_test.go:1207: 03:11:10.887 - 50: DEBUG: new connection from 127.0.0.1:57500 336s app_test.go:1207: 03:11:10.888 - 44: DEBUG: new connection from 127.0.0.1:54390 336s app_test.go:1207: 03:11:10.889 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.889 - 44: DEBUG: new connection from 127.0.0.1:54402 336s app_test.go:1207: 03:11:10.899 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.899 - 44: DEBUG: new connection from 127.0.0.1:54416 336s app_test.go:1207: 03:11:10.899 - 45: DEBUG: new connection from 127.0.0.1:47588 336s app_test.go:1207: 03:11:10.901 - 46: DEBUG: new connection from 127.0.0.1:47308 336s app_test.go:1207: 03:11:10.901 - 47: DEBUG: new connection from 127.0.0.1:47486 336s app_test.go:1207: 03:11:10.902 - 48: DEBUG: new connection from 127.0.0.1:34086 336s app_test.go:1207: 03:11:10.904 - 49: DEBUG: new connection from 127.0.0.1:38476 336s app_test.go:1207: 03:11:10.905 - 50: DEBUG: new connection from 127.0.0.1:57516 336s app_test.go:1207: 03:11:10.906 - 50: DEBUG: new connection from 127.0.0.1:57518 336s app_test.go:1207: 03:11:10.907 - 48: DEBUG: promoted 127.0.0.1:9007 from spare to stand-by 336s --- PASS: TestHandover_StandBy (2.62s) 336s === RUN TestHandover_TransferLeadership 336s app_test.go:1207: 03:11:10.212 - 51: DEBUG: new connection from 127.0.0.1:54428 336s app_test.go:1207: 03:11:10.214 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.215 - 51: DEBUG: new connection from 127.0.0.1:54434 336s app_test.go:1207: 03:11:10.310 - 51: DEBUG: new connection from 127.0.0.1:54444 336s app_test.go:1207: 03:11:10.311 - 52: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.315 - 51: DEBUG: new connection from 127.0.0.1:54452 336s app_test.go:1207: 03:11:10.316 - 52: DEBUG: new connection from 127.0.0.1:47590 336s app_test.go:1207: 03:11:10.420 - 51: DEBUG: new connection from 127.0.0.1:54456 336s app_test.go:1207: 03:11:10.421 - 53: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.423 - 51: DEBUG: new connection from 127.0.0.1:54470 336s app_test.go:1207: 03:11:10.423 - 52: DEBUG: new connection from 127.0.0.1:47600 336s app_test.go:1207: 03:11:10.424 - 53: DEBUG: new connection from 127.0.0.1:47312 336s app_test.go:1207: 03:11:10.425 - 53: DEBUG: new connection from 127.0.0.1:47314 336s app_test.go:1207: 03:11:10.427 - 51: DEBUG: new connection from 127.0.0.1:54480 336s app_test.go:1207: 03:11:10.139 - 52: DEBUG: new connection from 127.0.0.1:47614 336s app_test.go:1207: 03:11:10.144 - 51: DEBUG: new connection from 127.0.0.1:54482 336s app_test.go:1207: 03:11:10.230 - 51: DEBUG: new connection from 127.0.0.1:38780 336s app_test.go:1207: 03:11:10.231 - 54: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.233 - 51: DEBUG: new connection from 127.0.0.1:38796 336s app_test.go:1207: 03:11:10.233 - 52: DEBUG: new connection from 127.0.0.1:41446 336s app_test.go:1207: 03:11:10.234 - 53: DEBUG: new connection from 127.0.0.1:34352 336s app_test.go:1207: 03:11:10.234 - 54: DEBUG: new connection from 127.0.0.1:34126 336s app_test.go:1207: 03:11:10.235 - 54: DEBUG: new connection from 127.0.0.1:34140 336s app_test.go:1207: 03:11:10.236 - 51: DEBUG: new connection from 127.0.0.1:38812 336s app_test.go:1207: 03:11:10.237 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.237 - 51: DEBUG: new connection from 127.0.0.1:38814 336s app_test.go:1207: 03:11:10.238 - 51: DEBUG: new connection from 127.0.0.1:38816 336s app_test.go:1207: 03:11:10.239 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.239 - 52: DEBUG: new connection from 127.0.0.1:41462 336s app_test.go:1207: 03:11:10.239 - 51: DEBUG: new connection from 127.0.0.1:38822 336s app_test.go:1207: 03:11:10.240 - 54: DEBUG: new connection from 127.0.0.1:34156 336s app_test.go:1207: 03:11:10.241 - 53: DEBUG: new connection from 127.0.0.1:34368 336s app_test.go:1207: 03:11:10.139 - 51: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 336s app_test.go:1207: 03:11:10.140 - 51: DEBUG: new connection from 127.0.0.1:38838 336s app_test.go:1207: 03:11:10.140 - 52: DEBUG: new connection from 127.0.0.1:41464 336s app_test.go:1207: 03:11:10.142 - 54: DEBUG: new connection from 127.0.0.1:34162 336s app_test.go:1207: 03:11:10.143 - 53: DEBUG: new connection from 127.0.0.1:34378 336s app_test.go:1207: 03:11:10.145 - 53: DEBUG: new connection from 127.0.0.1:34390 336s app_test.go:1207: 03:11:10.145 - 54: DEBUG: new connection from 127.0.0.1:34170 336s app_test.go:1207: 03:11:10.148 - 52: DEBUG: new connection from 127.0.0.1:41468 336s app_test.go:1207: 03:11:10.151 - 52: DEBUG: new connection from 127.0.0.1:41472 336s app_test.go:1207: 03:11:10.152 - 51: DEBUG: new connection from 127.0.0.1:38852 336s app_test.go:1207: 03:11:10.153 - 51: DEBUG: new connection from 127.0.0.1:38860 336s app_test.go:1207: 03:11:10.153 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 336s app_test.go:1207: 03:11:10.153 - 52: DEBUG: new connection from 127.0.0.1:41486 336s app_test.go:1207: 03:11:10.154 - 51: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 336s app_test.go:1207: 03:11:10.155 - 51: DEBUG: new connection from 127.0.0.1:38876 336s app_test.go:1207: 03:11:10.156 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 336s app_test.go:1207: 03:11:10.156 - 52: DEBUG: new connection from 127.0.0.1:41494 336s app_test.go:1207: 03:11:10.157 - 51: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 336s --- PASS: TestHandover_TransferLeadership (3.36s) 336s === RUN TestRolesAdjustment_ReplaceVoter 336s app_test.go:1207: 03:11:10.572 - 55: DEBUG: new connection from 127.0.0.1:38892 336s app_test.go:1207: 03:11:10.574 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.575 - 55: DEBUG: new connection from 127.0.0.1:38894 336s app_test.go:1207: 03:11:10.660 - 55: DEBUG: new connection from 127.0.0.1:38904 336s app_test.go:1207: 03:11:10.661 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.665 - 56: DEBUG: new connection from 127.0.0.1:41508 336s app_test.go:1207: 03:11:10.666 - 55: DEBUG: new connection from 127.0.0.1:38918 336s app_test.go:1207: 03:11:10.750 - 55: DEBUG: new connection from 127.0.0.1:38926 336s app_test.go:1207: 03:11:10.751 - 57: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.753 - 56: DEBUG: new connection from 127.0.0.1:41518 336s app_test.go:1207: 03:11:10.753 - 55: DEBUG: new connection from 127.0.0.1:38934 336s app_test.go:1207: 03:11:10.754 - 57: DEBUG: new connection from 127.0.0.1:34394 336s app_test.go:1207: 03:11:10.755 - 57: DEBUG: new connection from 127.0.0.1:34396 336s app_test.go:1207: 03:11:10.757 - 55: DEBUG: new connection from 127.0.0.1:38946 336s app_test.go:1207: 03:11:10.498 - 56: DEBUG: new connection from 127.0.0.1:41524 336s app_test.go:1207: 03:11:10.500 - 55: DEBUG: new connection from 127.0.0.1:38958 336s app_test.go:1207: 03:11:10.577 - 55: DEBUG: new connection from 127.0.0.1:38970 336s app_test.go:1207: 03:11:10.579 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.581 - 55: DEBUG: new connection from 127.0.0.1:38974 336s app_test.go:1207: 03:11:10.581 - 56: DEBUG: new connection from 127.0.0.1:41530 336s app_test.go:1207: 03:11:10.583 - 57: DEBUG: new connection from 127.0.0.1:34398 336s app_test.go:1207: 03:11:10.589 - 55: DEBUG: new connection from 127.0.0.1:38990 336s app_test.go:1207: 03:11:10.590 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.592 - 55: DEBUG: new connection from 127.0.0.1:38998 336s app_test.go:1207: 03:11:10.592 - 56: DEBUG: new connection from 127.0.0.1:41544 336s app_test.go:1207: 03:11:10.593 - 57: DEBUG: new connection from 127.0.0.1:34400 336s app_test.go:1207: 03:11:10.593 - 58: DEBUG: new connection from 127.0.0.1:34180 336s app_test.go:1207: 03:11:10.595 - 58: DEBUG: new connection from 127.0.0.1:34188 336s app_test.go:1207: 03:11:10.597 - 55: DEBUG: new connection from 127.0.0.1:39006 336s app_test.go:1207: 03:11:10.669 - 55: DEBUG: new connection from 127.0.0.1:39018 336s app_test.go:1207: 03:11:10.669 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.585 - 55: DEBUG: new connection from 127.0.0.1:39026 336s app_test.go:1207: 03:11:10.587 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.589 - 55: DEBUG: new connection from 127.0.0.1:39042 336s app_test.go:1207: 03:11:10.589 - 56: DEBUG: new connection from 127.0.0.1:41554 336s app_test.go:1207: 03:11:10.590 - 58: DEBUG: new connection from 127.0.0.1:34192 336s app_test.go:1207: 03:11:10.591 - 56: DEBUG: new connection from 127.0.0.1:41566 336s app_test.go:1207: 03:11:10.592 - 55: DEBUG: new connection from 127.0.0.1:39044 336s app_test.go:1207: 03:11:10.593 - 58: DEBUG: new connection from 127.0.0.1:34198 336s app_test.go:1207: 03:11:10.595 - 55: DEBUG: new connection from 127.0.0.1:39046 336s app_test.go:1207: 03:11:10.595 - 56: DEBUG: new connection from 127.0.0.1:41582 336s app_test.go:1207: 03:11:10.596 - 58: DEBUG: new connection from 127.0.0.1:34204 336s app_test.go:1207: 03:11:10.596 - 55: DEBUG: new connection from 127.0.0.1:39058 336s app_test.go:1207: 03:11:10.597 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.672 - 55: DEBUG: new connection from 127.0.0.1:39070 336s app_test.go:1207: 03:11:10.673 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.599 - 55: DEBUG: new connection from 127.0.0.1:39076 336s app_test.go:1207: 03:11:10.599 - 55: DEBUG: new connection from 127.0.0.1:39090 336s app_test.go:1207: 03:11:10.600 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.600 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.602 - 55: DEBUG: new connection from 127.0.0.1:39094 336s app_test.go:1207: 03:11:10.602 - 56: DEBUG: new connection from 127.0.0.1:41590 336s app_test.go:1207: 03:11:10.604 - 58: DEBUG: new connection from 127.0.0.1:34214 336s app_test.go:1207: 03:11:10.676 - 55: DEBUG: new connection from 127.0.0.1:39100 336s app_test.go:1207: 03:11:10.677 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.602 - 55: DEBUG: new connection from 127.0.0.1:46596 336s app_test.go:1207: 03:11:10.603 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.605 - 55: DEBUG: new connection from 127.0.0.1:46606 336s app_test.go:1207: 03:11:10.607 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.608 - 55: DEBUG: new connection from 127.0.0.1:46618 336s app_test.go:1207: 03:11:10.608 - 56: DEBUG: new connection from 127.0.0.1:34564 336s app_test.go:1207: 03:11:10.609 - 58: DEBUG: new connection from 127.0.0.1:43314 336s app_test.go:1207: 03:11:10.679 - 55: DEBUG: new connection from 127.0.0.1:46630 336s app_test.go:1207: 03:11:10.680 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.608 - 55: DEBUG: new connection from 127.0.0.1:46644 336s app_test.go:1207: 03:11:10.609 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.612 - 55: DEBUG: new connection from 127.0.0.1:46656 336s app_test.go:1207: 03:11:10.613 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.615 - 56: DEBUG: new connection from 127.0.0.1:34566 336s app_test.go:1207: 03:11:10.616 - 55: DEBUG: new connection from 127.0.0.1:46662 336s app_test.go:1207: 03:11:10.617 - 58: DEBUG: new connection from 127.0.0.1:43320 336s app_test.go:1207: 03:11:10.671 - 55: DEBUG: new connection from 127.0.0.1:46668 336s app_test.go:1207: 03:11:10.671 - 58: DEBUG: new connection from 127.0.0.1:43334 336s app_test.go:1207: 03:11:10.672 - 56: DEBUG: new connection from 127.0.0.1:34580 336s app_test.go:1207: 03:11:10.672 - 55: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 336s app_test.go:1207: 03:11:10.673 - 55: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:11:10.674 - 55: DEBUG: new connection from 127.0.0.1:46674 336s app_test.go:1207: 03:11:10.674 - 55: DEBUG: attempt 1: server 127.0.0.1:9004: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:11:10.674 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.674 - 55: DEBUG: new connection from 127.0.0.1:46688 336s app_test.go:1207: 03:11:10.683 - 55: DEBUG: new connection from 127.0.0.1:46690 336s app_test.go:1207: 03:11:10.684 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s --- PASS: TestRolesAdjustment_ReplaceVoter (10.47s) 336s === RUN TestRolesAdjustment_ReplaceVoterHonorFailureDomain 336s app_test.go:1207: 03:11:10.062 - 59: DEBUG: new connection from 127.0.0.1:46696 336s app_test.go:1207: 03:11:10.063 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.065 - 59: DEBUG: new connection from 127.0.0.1:46706 336s app_test.go:1207: 03:11:10.140 - 59: DEBUG: new connection from 127.0.0.1:46722 336s app_test.go:1207: 03:11:10.141 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.146 - 60: DEBUG: new connection from 127.0.0.1:34588 336s app_test.go:1207: 03:11:10.146 - 59: DEBUG: new connection from 127.0.0.1:46732 336s app_test.go:1207: 03:11:10.230 - 59: DEBUG: new connection from 127.0.0.1:46748 336s app_test.go:1207: 03:11:10.231 - 61: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.233 - 59: DEBUG: new connection from 127.0.0.1:46760 336s app_test.go:1207: 03:11:10.233 - 60: DEBUG: new connection from 127.0.0.1:34604 336s app_test.go:1207: 03:11:10.234 - 61: DEBUG: new connection from 127.0.0.1:34882 336s app_test.go:1207: 03:11:10.236 - 61: DEBUG: new connection from 127.0.0.1:34894 336s app_test.go:1207: 03:11:10.238 - 59: DEBUG: new connection from 127.0.0.1:46774 336s app_test.go:1207: 03:11:10.968 - 60: DEBUG: new connection from 127.0.0.1:34608 336s app_test.go:1207: 03:11:10.971 - 59: DEBUG: new connection from 127.0.0.1:46784 336s app_test.go:1207: 03:11:10.060 - 59: DEBUG: new connection from 127.0.0.1:46794 336s app_test.go:1207: 03:11:10.061 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.063 - 60: DEBUG: new connection from 127.0.0.1:34624 336s app_test.go:1207: 03:11:10.064 - 59: DEBUG: new connection from 127.0.0.1:46796 336s app_test.go:1207: 03:11:10.066 - 61: DEBUG: new connection from 127.0.0.1:34904 336s app_test.go:1207: 03:11:10.066 - 62: DEBUG: new connection from 127.0.0.1:43342 336s app_test.go:1207: 03:11:10.067 - 62: DEBUG: new connection from 127.0.0.1:43352 336s app_test.go:1207: 03:11:10.070 - 59: DEBUG: new connection from 127.0.0.1:46800 336s app_test.go:1207: 03:11:10.140 - 59: DEBUG: new connection from 127.0.0.1:46804 336s app_test.go:1207: 03:11:10.141 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.144 - 60: DEBUG: new connection from 127.0.0.1:34628 336s app_test.go:1207: 03:11:10.145 - 59: DEBUG: new connection from 127.0.0.1:46814 336s app_test.go:1207: 03:11:10.146 - 62: DEBUG: new connection from 127.0.0.1:43360 336s app_test.go:1207: 03:11:10.146 - 61: DEBUG: new connection from 127.0.0.1:34920 336s app_test.go:1207: 03:11:10.147 - 63: DEBUG: new connection from 127.0.0.1:37192 336s app_test.go:1207: 03:11:10.148 - 63: DEBUG: new connection from 127.0.0.1:37196 336s app_test.go:1207: 03:11:10.150 - 59: DEBUG: new connection from 127.0.0.1:46830 336s app_test.go:1207: 03:11:10.230 - 59: DEBUG: new connection from 127.0.0.1:46832 336s app_test.go:1207: 03:11:10.231 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.233 - 59: DEBUG: new connection from 127.0.0.1:46834 336s app_test.go:1207: 03:11:10.233 - 60: DEBUG: new connection from 127.0.0.1:34636 336s app_test.go:1207: 03:11:10.235 - 61: DEBUG: new connection from 127.0.0.1:34928 336s app_test.go:1207: 03:11:10.235 - 62: DEBUG: new connection from 127.0.0.1:43366 336s app_test.go:1207: 03:11:10.236 - 64: DEBUG: new connection from 127.0.0.1:35222 336s app_test.go:1207: 03:11:10.236 - 63: DEBUG: new connection from 127.0.0.1:37200 336s app_test.go:1207: 03:11:10.237 - 64: DEBUG: new connection from 127.0.0.1:35238 336s app_test.go:1207: 03:11:10.241 - 59: DEBUG: new connection from 127.0.0.1:46842 336s app_test.go:1207: 03:11:10.067 - 59: DEBUG: new connection from 127.0.0.1:46846 336s app_test.go:1207: 03:11:10.069 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.070 - 59: DEBUG: new connection from 127.0.0.1:46860 336s app_test.go:1207: 03:11:10.071 - 60: DEBUG: new connection from 127.0.0.1:34644 336s app_test.go:1207: 03:11:10.072 - 62: DEBUG: new connection from 127.0.0.1:43374 336s app_test.go:1207: 03:11:10.073 - 63: DEBUG: new connection from 127.0.0.1:37202 336s app_test.go:1207: 03:11:10.073 - 64: DEBUG: new connection from 127.0.0.1:35252 336s app_test.go:1207: 03:11:10.076 - 60: DEBUG: new connection from 127.0.0.1:34654 336s app_test.go:1207: 03:11:10.076 - 59: DEBUG: new connection from 127.0.0.1:46870 336s app_test.go:1207: 03:11:10.077 - 62: DEBUG: new connection from 127.0.0.1:43382 336s app_test.go:1207: 03:11:10.078 - 63: DEBUG: new connection from 127.0.0.1:37212 336s app_test.go:1207: 03:11:10.078 - 64: DEBUG: new connection from 127.0.0.1:35260 336s app_test.go:1207: 03:11:10.080 - 60: DEBUG: new connection from 127.0.0.1:34658 336s app_test.go:1207: 03:11:10.080 - 59: DEBUG: new connection from 127.0.0.1:46874 336s app_test.go:1207: 03:11:10.081 - 63: DEBUG: new connection from 127.0.0.1:37228 336s app_test.go:1207: 03:11:10.081 - 62: DEBUG: new connection from 127.0.0.1:43394 336s app_test.go:1207: 03:11:10.083 - 64: DEBUG: new connection from 127.0.0.1:35276 336s app_test.go:1207: 03:11:10.149 - 59: DEBUG: new connection from 127.0.0.1:46890 336s app_test.go:1207: 03:11:10.150 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.069 - 59: DEBUG: new connection from 127.0.0.1:46892 336s app_test.go:1207: 03:11:10.070 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.149 - 59: DEBUG: new connection from 127.0.0.1:46896 336s app_test.go:1207: 03:11:10.150 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.240 - 59: DEBUG: new connection from 127.0.0.1:46908 336s app_test.go:1207: 03:11:10.240 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.085 - 59: DEBUG: new connection from 127.0.0.1:49374 336s app_test.go:1207: 03:11:10.086 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.088 - 59: DEBUG: new connection from 127.0.0.1:49384 336s app_test.go:1207: 03:11:10.088 - 60: DEBUG: new connection from 127.0.0.1:38616 336s app_test.go:1207: 03:11:10.089 - 62: DEBUG: new connection from 127.0.0.1:54164 336s app_test.go:1207: 03:11:10.089 - 63: DEBUG: new connection from 127.0.0.1:54712 336s app_test.go:1207: 03:11:10.090 - 64: DEBUG: new connection from 127.0.0.1:37664 336s app_test.go:1207: 03:11:10.152 - 59: DEBUG: new connection from 127.0.0.1:49400 336s app_test.go:1207: 03:11:10.154 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.073 - 59: DEBUG: new connection from 127.0.0.1:49408 336s app_test.go:1207: 03:11:10.074 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.153 - 59: DEBUG: new connection from 127.0.0.1:49412 336s app_test.go:1207: 03:11:10.154 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.244 - 59: DEBUG: new connection from 127.0.0.1:49428 336s app_test.go:1207: 03:11:10.245 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.093 - 59: DEBUG: new connection from 127.0.0.1:49436 336s app_test.go:1207: 03:11:10.095 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.096 - 60: DEBUG: new connection from 127.0.0.1:38630 336s app_test.go:1207: 03:11:10.096 - 59: DEBUG: new connection from 127.0.0.1:49450 336s app_test.go:1207: 03:11:10.097 - 62: DEBUG: new connection from 127.0.0.1:54174 336s app_test.go:1207: 03:11:10.097 - 63: DEBUG: new connection from 127.0.0.1:54728 336s app_test.go:1207: 03:11:10.098 - 64: DEBUG: new connection from 127.0.0.1:37680 336s app_test.go:1207: 03:11:10.155 - 59: DEBUG: new connection from 127.0.0.1:49464 336s app_test.go:1207: 03:11:10.156 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.076 - 59: DEBUG: new connection from 127.0.0.1:49480 336s app_test.go:1207: 03:11:10.077 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.156 - 59: DEBUG: new connection from 127.0.0.1:49494 336s app_test.go:1207: 03:11:10.157 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.247 - 59: DEBUG: new connection from 127.0.0.1:49504 336s app_test.go:1207: 03:11:10.248 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.100 - 59: DEBUG: new connection from 127.0.0.1:49510 336s app_test.go:1207: 03:11:10.102 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.105 - 59: DEBUG: new connection from 127.0.0.1:49516 336s app_test.go:1207: 03:11:10.105 - 60: DEBUG: new connection from 127.0.0.1:38638 336s app_test.go:1207: 03:11:10.105 - 62: DEBUG: new connection from 127.0.0.1:54178 336s app_test.go:1207: 03:11:10.106 - 63: DEBUG: new connection from 127.0.0.1:54738 336s app_test.go:1207: 03:11:10.106 - 64: DEBUG: new connection from 127.0.0.1:37690 336s app_test.go:1207: 03:11:10.159 - 59: DEBUG: new connection from 127.0.0.1:49530 336s app_test.go:1207: 03:11:10.160 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.079 - 59: DEBUG: new connection from 127.0.0.1:55120 336s app_test.go:1207: 03:11:10.080 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.160 - 59: DEBUG: new connection from 127.0.0.1:55130 336s app_test.go:1207: 03:11:10.160 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.250 - 59: DEBUG: new connection from 127.0.0.1:55138 336s app_test.go:1207: 03:11:10.251 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.110 - 59: DEBUG: new connection from 127.0.0.1:55144 336s app_test.go:1207: 03:11:10.111 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.113 - 59: DEBUG: new connection from 127.0.0.1:55160 336s app_test.go:1207: 03:11:10.113 - 60: DEBUG: new connection from 127.0.0.1:39506 336s app_test.go:1207: 03:11:10.114 - 62: DEBUG: new connection from 127.0.0.1:36774 336s app_test.go:1207: 03:11:10.115 - 63: DEBUG: new connection from 127.0.0.1:51320 336s app_test.go:1207: 03:11:10.115 - 64: DEBUG: new connection from 127.0.0.1:51818 336s app_test.go:1207: 03:11:10.162 - 59: DEBUG: new connection from 127.0.0.1:55162 336s app_test.go:1207: 03:11:10.163 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.311 - 60: DEBUG: new connection from 127.0.0.1:39520 336s app_test.go:1207: 03:11:10.312 - 59: DEBUG: new connection from 127.0.0.1:55166 336s app_test.go:1207: 03:11:10.313 - 59: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:11:10.313 - 59: DEBUG: new connection from 127.0.0.1:55180 336s app_test.go:1207: 03:11:10.314 - 64: DEBUG: new connection from 127.0.0.1:51826 336s app_test.go:1207: 03:11:10.314 - 63: DEBUG: new connection from 127.0.0.1:51332 336s app_test.go:1207: 03:11:10.314 - 59: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 336s app_test.go:1207: 03:11:10.315 - 62: DEBUG: new connection from 127.0.0.1:36788 336s app_test.go:1207: 03:11:10.315 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.315 - 59: WARN: attempt 1: server 127.0.0.1:9005: no known leader 336s app_test.go:1207: 03:11:10.316 - 59: WARN: attempt 1: server 127.0.0.1:9004: no known leader 336s app_test.go:1207: 03:11:10.316 - 59: DEBUG: attempt 1: server 127.0.0.1:9006: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:11:10.316 - 59: WARN: attempt 1: server 127.0.0.1:9001: context canceled 336s --- PASS: TestRolesAdjustment_ReplaceVoterHonorFailureDomain (20.79s) 336s === RUN TestRolesAdjustment_ImbalancedFailureDomain 336s app_test.go:1207: 03:11:10.836 - 65: DEBUG: new connection from 127.0.0.1:55192 336s app_test.go:1207: 03:11:10.842 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.844 - 65: DEBUG: new connection from 127.0.0.1:55206 336s app_test.go:1207: 03:11:10.940 - 65: DEBUG: new connection from 127.0.0.1:55214 336s app_test.go:1207: 03:11:10.942 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.951 - 66: DEBUG: new connection from 127.0.0.1:39534 336s app_test.go:1207: 03:11:10.951 - 65: DEBUG: new connection from 127.0.0.1:55228 336s app_test.go:1207: 03:11:10.039 - 65: DEBUG: new connection from 127.0.0.1:55240 336s app_test.go:1207: 03:11:10.041 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.043 - 65: DEBUG: new connection from 127.0.0.1:55254 336s app_test.go:1207: 03:11:10.044 - 66: DEBUG: new connection from 127.0.0.1:39536 336s app_test.go:1207: 03:11:10.044 - 67: DEBUG: new connection from 127.0.0.1:49286 336s app_test.go:1207: 03:11:10.046 - 67: DEBUG: new connection from 127.0.0.1:49300 336s app_test.go:1207: 03:11:10.048 - 65: DEBUG: new connection from 127.0.0.1:55268 336s app_test.go:1207: 03:11:10.756 - 66: DEBUG: new connection from 127.0.0.1:39546 336s app_test.go:1207: 03:11:10.759 - 65: DEBUG: new connection from 127.0.0.1:55280 336s app_test.go:1207: 03:11:10.839 - 65: DEBUG: new connection from 127.0.0.1:55288 336s app_test.go:1207: 03:11:10.841 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.842 - 66: DEBUG: new connection from 127.0.0.1:39556 336s app_test.go:1207: 03:11:10.843 - 65: DEBUG: new connection from 127.0.0.1:55302 336s app_test.go:1207: 03:11:10.844 - 67: DEBUG: new connection from 127.0.0.1:49302 336s app_test.go:1207: 03:11:10.845 - 68: DEBUG: new connection from 127.0.0.1:36808 336s app_test.go:1207: 03:11:10.845 - 68: DEBUG: new connection from 127.0.0.1:36820 336s app_test.go:1207: 03:11:10.849 - 65: DEBUG: new connection from 127.0.0.1:55316 336s app_test.go:1207: 03:11:10.919 - 65: DEBUG: new connection from 127.0.0.1:55322 336s app_test.go:1207: 03:11:10.921 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.923 - 66: DEBUG: new connection from 127.0.0.1:39564 336s app_test.go:1207: 03:11:10.923 - 65: DEBUG: new connection from 127.0.0.1:55334 336s app_test.go:1207: 03:11:10.924 - 67: DEBUG: new connection from 127.0.0.1:49304 336s app_test.go:1207: 03:11:10.924 - 68: DEBUG: new connection from 127.0.0.1:36824 336s app_test.go:1207: 03:11:10.925 - 69: DEBUG: new connection from 127.0.0.1:51354 336s app_test.go:1207: 03:11:10.926 - 69: DEBUG: new connection from 127.0.0.1:51366 336s app_test.go:1207: 03:11:10.929 - 65: DEBUG: new connection from 127.0.0.1:55346 336s app_test.go:1207: 03:11:10.010 - 65: DEBUG: new connection from 127.0.0.1:55348 336s app_test.go:1207: 03:11:10.011 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.012 - 65: DEBUG: new connection from 127.0.0.1:55360 336s app_test.go:1207: 03:11:10.012 - 66: DEBUG: new connection from 127.0.0.1:39570 336s app_test.go:1207: 03:11:10.013 - 67: DEBUG: new connection from 127.0.0.1:49310 336s app_test.go:1207: 03:11:10.014 - 68: DEBUG: new connection from 127.0.0.1:36834 336s app_test.go:1207: 03:11:10.015 - 69: DEBUG: new connection from 127.0.0.1:51372 336s app_test.go:1207: 03:11:10.016 - 70: DEBUG: new connection from 127.0.0.1:51840 336s app_test.go:1207: 03:11:10.017 - 70: DEBUG: new connection from 127.0.0.1:51844 336s app_test.go:1207: 03:11:10.020 - 65: DEBUG: new connection from 127.0.0.1:55376 336s app_test.go:1207: 03:11:10.100 - 65: DEBUG: new connection from 127.0.0.1:55392 336s app_test.go:1207: 03:11:10.101 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.103 - 66: DEBUG: new connection from 127.0.0.1:39584 336s app_test.go:1207: 03:11:10.103 - 65: DEBUG: new connection from 127.0.0.1:55406 336s app_test.go:1207: 03:11:10.104 - 67: DEBUG: new connection from 127.0.0.1:49318 336s app_test.go:1207: 03:11:10.104 - 68: DEBUG: new connection from 127.0.0.1:36844 336s app_test.go:1207: 03:11:10.105 - 69: DEBUG: new connection from 127.0.0.1:51386 336s app_test.go:1207: 03:11:10.106 - 70: DEBUG: new connection from 127.0.0.1:51852 336s app_test.go:1207: 03:11:10.108 - 71: DEBUG: new connection from 127.0.0.1:46812 336s app_test.go:1207: 03:11:10.210 - 65: DEBUG: new connection from 127.0.0.1:55408 336s app_test.go:1207: 03:11:10.211 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.213 - 65: DEBUG: new connection from 127.0.0.1:55414 336s app_test.go:1207: 03:11:10.213 - 66: DEBUG: new connection from 127.0.0.1:39594 336s app_test.go:1207: 03:11:10.214 - 67: DEBUG: new connection from 127.0.0.1:49326 336s app_test.go:1207: 03:11:10.215 - 68: DEBUG: new connection from 127.0.0.1:36846 336s app_test.go:1207: 03:11:10.215 - 69: DEBUG: new connection from 127.0.0.1:51390 336s app_test.go:1207: 03:11:10.216 - 70: DEBUG: new connection from 127.0.0.1:51868 336s app_test.go:1207: 03:11:10.216 - 71: DEBUG: new connection from 127.0.0.1:46826 336s app_test.go:1207: 03:11:10.217 - 72: DEBUG: new connection from 127.0.0.1:49182 336s app_test.go:1207: 03:11:10.845 - 65: DEBUG: new connection from 127.0.0.1:55418 336s app_test.go:1207: 03:11:10.847 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.848 - 65: DEBUG: new connection from 127.0.0.1:55432 336s app_test.go:1207: 03:11:10.848 - 66: DEBUG: new connection from 127.0.0.1:39602 336s app_test.go:1207: 03:11:10.849 - 67: DEBUG: new connection from 127.0.0.1:49328 336s app_test.go:1207: 03:11:10.850 - 68: DEBUG: new connection from 127.0.0.1:36850 336s app_test.go:1207: 03:11:10.850 - 69: DEBUG: new connection from 127.0.0.1:51404 336s app_test.go:1207: 03:11:10.851 - 70: DEBUG: new connection from 127.0.0.1:51872 336s app_test.go:1207: 03:11:10.851 - 71: DEBUG: new connection from 127.0.0.1:46840 336s app_test.go:1207: 03:11:10.852 - 72: DEBUG: new connection from 127.0.0.1:49188 336s app_test.go:1207: 03:11:10.853 - 72: DEBUG: new connection from 127.0.0.1:49190 336s app_test.go:1207: 03:11:10.856 - 65: DEBUG: new connection from 127.0.0.1:55444 336s app_test.go:1207: 03:11:10.953 - 65: DEBUG: new connection from 127.0.0.1:55456 336s app_test.go:1207: 03:11:10.954 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.762 - 65: DEBUG: new connection from 127.0.0.1:55458 336s app_test.go:1207: 03:11:10.763 - 66: DEBUG: new connection from 127.0.0.1:39604 336s app_test.go:1207: 03:11:10.763 - 67: DEBUG: new connection from 127.0.0.1:49330 336s app_test.go:1207: 03:11:10.765 - 68: DEBUG: new connection from 127.0.0.1:36856 336s app_test.go:1207: 03:11:10.766 - 69: DEBUG: new connection from 127.0.0.1:51418 336s app_test.go:1207: 03:11:10.766 - 70: DEBUG: new connection from 127.0.0.1:51886 336s app_test.go:1207: 03:11:10.767 - 71: DEBUG: new connection from 127.0.0.1:46846 336s app_test.go:1207: 03:11:10.767 - 72: DEBUG: new connection from 127.0.0.1:49196 336s app_test.go:1207: 03:11:10.770 - 65: DEBUG: new connection from 127.0.0.1:55464 336s app_test.go:1207: 03:11:10.770 - 66: DEBUG: new connection from 127.0.0.1:39618 336s app_test.go:1207: 03:11:10.771 - 67: DEBUG: new connection from 127.0.0.1:49340 336s app_test.go:1207: 03:11:10.771 - 68: DEBUG: new connection from 127.0.0.1:36872 336s app_test.go:1207: 03:11:10.772 - 69: DEBUG: new connection from 127.0.0.1:51434 336s app_test.go:1207: 03:11:10.772 - 70: DEBUG: new connection from 127.0.0.1:51888 336s app_test.go:1207: 03:11:10.773 - 71: DEBUG: new connection from 127.0.0.1:46848 336s app_test.go:1207: 03:11:10.773 - 72: DEBUG: new connection from 127.0.0.1:49202 336s app_test.go:1207: 03:11:10.757 - 65: DEBUG: new connection from 127.0.0.1:55478 336s app_test.go:1207: 03:11:10.758 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.846 - 65: DEBUG: new connection from 127.0.0.1:55482 336s app_test.go:1207: 03:11:10.847 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.927 - 65: DEBUG: new connection from 127.0.0.1:55488 336s app_test.go:1207: 03:11:10.928 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.018 - 65: DEBUG: new connection from 127.0.0.1:55490 336s app_test.go:1207: 03:11:10.019 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.109 - 65: DEBUG: new connection from 127.0.0.1:55494 336s app_test.go:1207: 03:11:10.110 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.219 - 65: DEBUG: new connection from 127.0.0.1:55496 336s app_test.go:1207: 03:11:10.220 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.957 - 65: DEBUG: new connection from 127.0.0.1:33384 336s app_test.go:1207: 03:11:10.958 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.776 - 65: DEBUG: new connection from 127.0.0.1:33388 336s app_test.go:1207: 03:11:10.778 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.779 - 66: DEBUG: new connection from 127.0.0.1:56976 336s app_test.go:1207: 03:11:10.779 - 65: DEBUG: new connection from 127.0.0.1:33390 336s app_test.go:1207: 03:11:10.780 - 67: DEBUG: new connection from 127.0.0.1:45598 336s app_test.go:1207: 03:11:10.780 - 68: DEBUG: new connection from 127.0.0.1:43192 336s app_test.go:1207: 03:11:10.781 - 69: DEBUG: new connection from 127.0.0.1:42262 336s app_test.go:1207: 03:11:10.781 - 70: DEBUG: new connection from 127.0.0.1:58978 336s app_test.go:1207: 03:11:10.782 - 71: DEBUG: new connection from 127.0.0.1:42226 336s app_test.go:1207: 03:11:10.783 - 72: DEBUG: new connection from 127.0.0.1:42266 336s app_test.go:1207: 03:11:10.761 - 65: DEBUG: new connection from 127.0.0.1:33398 336s app_test.go:1207: 03:11:10.762 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.849 - 65: DEBUG: new connection from 127.0.0.1:33410 336s app_test.go:1207: 03:11:10.850 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.856 - 67: DEBUG: new connection from 127.0.0.1:45610 336s app_test.go:1207: 03:11:10.858 - 72: DEBUG: new connection from 127.0.0.1:42268 336s app_test.go:1207: 03:11:10.859 - 66: DEBUG: new connection from 127.0.0.1:56992 336s app_test.go:1207: 03:11:10.860 - 66: DEBUG: new connection from 127.0.0.1:57008 336s app_test.go:1207: 03:11:10.931 - 65: DEBUG: new connection from 127.0.0.1:33424 336s app_test.go:1207: 03:11:10.933 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.021 - 65: DEBUG: new connection from 127.0.0.1:33440 336s app_test.go:1207: 03:11:10.022 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.113 - 65: DEBUG: new connection from 127.0.0.1:33456 336s app_test.go:1207: 03:11:10.114 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.222 - 65: DEBUG: new connection from 127.0.0.1:33470 336s app_test.go:1207: 03:11:10.224 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.960 - 65: DEBUG: new connection from 127.0.0.1:33478 336s app_test.go:1207: 03:11:10.962 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.786 - 65: DEBUG: new connection from 127.0.0.1:33490 336s app_test.go:1207: 03:11:10.787 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.790 - 65: DEBUG: new connection from 127.0.0.1:33502 336s app_test.go:1207: 03:11:10.790 - 66: DEBUG: new connection from 127.0.0.1:57014 336s app_test.go:1207: 03:11:10.791 - 67: DEBUG: new connection from 127.0.0.1:45616 336s app_test.go:1207: 03:11:10.792 - 68: DEBUG: new connection from 127.0.0.1:43204 336s app_test.go:1207: 03:11:10.793 - 69: DEBUG: new connection from 127.0.0.1:42274 336s app_test.go:1207: 03:11:10.793 - 70: DEBUG: new connection from 127.0.0.1:58992 336s app_test.go:1207: 03:11:10.794 - 71: DEBUG: new connection from 127.0.0.1:42230 336s app_test.go:1207: 03:11:10.794 - 72: DEBUG: new connection from 127.0.0.1:42270 336s app_test.go:1207: 03:11:10.764 - 65: DEBUG: new connection from 127.0.0.1:33508 336s app_test.go:1207: 03:11:10.766 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.852 - 65: DEBUG: new connection from 127.0.0.1:33520 336s app_test.go:1207: 03:11:10.853 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.937 - 65: DEBUG: new connection from 127.0.0.1:33522 336s app_test.go:1207: 03:11:10.938 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.024 - 65: DEBUG: new connection from 127.0.0.1:33534 336s app_test.go:1207: 03:11:10.025 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.116 - 65: DEBUG: new connection from 127.0.0.1:33548 336s app_test.go:1207: 03:11:10.118 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.226 - 65: DEBUG: new connection from 127.0.0.1:33554 336s app_test.go:1207: 03:11:10.227 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.965 - 65: DEBUG: new connection from 127.0.0.1:33568 336s app_test.go:1207: 03:11:10.967 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.796 - 65: DEBUG: new connection from 127.0.0.1:43470 336s app_test.go:1207: 03:11:10.798 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.800 - 65: DEBUG: new connection from 127.0.0.1:43484 336s app_test.go:1207: 03:11:10.800 - 66: DEBUG: new connection from 127.0.0.1:36506 336s app_test.go:1207: 03:11:10.801 - 67: DEBUG: new connection from 127.0.0.1:51442 336s app_test.go:1207: 03:11:10.802 - 68: DEBUG: new connection from 127.0.0.1:60440 336s app_test.go:1207: 03:11:10.803 - 69: DEBUG: new connection from 127.0.0.1:40558 336s app_test.go:1207: 03:11:10.804 - 70: DEBUG: new connection from 127.0.0.1:45954 336s app_test.go:1207: 03:11:10.805 - 71: DEBUG: new connection from 127.0.0.1:58062 336s app_test.go:1207: 03:11:10.806 - 72: DEBUG: new connection from 127.0.0.1:56978 336s app_test.go:1207: 03:11:10.769 - 65: DEBUG: new connection from 127.0.0.1:43492 336s app_test.go:1207: 03:11:10.770 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.856 - 65: DEBUG: new connection from 127.0.0.1:43508 336s app_test.go:1207: 03:11:10.857 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.941 - 65: DEBUG: new connection from 127.0.0.1:43524 336s app_test.go:1207: 03:11:10.942 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.028 - 65: DEBUG: new connection from 127.0.0.1:43528 336s app_test.go:1207: 03:11:10.029 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.121 - 65: DEBUG: new connection from 127.0.0.1:43544 336s app_test.go:1207: 03:11:10.121 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.229 - 65: DEBUG: new connection from 127.0.0.1:43546 336s app_test.go:1207: 03:11:10.230 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.971 - 65: DEBUG: new connection from 127.0.0.1:43554 336s app_test.go:1207: 03:11:10.972 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:11:10.220 - 65: DEBUG: new connection from 127.0.0.1:43564 336s app_test.go:1207: 03:11:10.220 - 71: DEBUG: new connection from 127.0.0.1:58076 336s app_test.go:1207: 03:11:10.220 - 66: DEBUG: new connection from 127.0.0.1:36520 336s app_test.go:1207: 03:11:10.221 - 70: DEBUG: new connection from 127.0.0.1:45958 336s app_test.go:1207: 03:11:10.221 - 69: DEBUG: new connection from 127.0.0.1:40564 336s app_test.go:1207: 03:11:10.221 - 68: DEBUG: new connection from 127.0.0.1:60450 336s app_test.go:1207: 03:11:10.221 - 72: DEBUG: new connection from 127.0.0.1:56988 336s app_test.go:1207: 03:11:10.221 - 67: DEBUG: new connection from 127.0.0.1:51446 336s app_test.go:1207: 03:11:10.224 - 65: WARN: attempt 1: server 127.0.0.1:9007: no known leader 336s app_test.go:1207: 03:11:10.224 - 65: WARN: attempt 1: server 127.0.0.1:9006: no known leader 336s app_test.go:1207: 03:11:10.224 - 65: WARN: attempt 1: server 127.0.0.1:9004: no known leader 336s app_test.go:1207: 03:11:10.224 - 65: DEBUG: attempt 1: server 127.0.0.1:9008: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:11:10.224 - 65: DEBUG: new connection from 127.0.0.1:43576 336s app_test.go:1207: 03:11:10.224 - 65: WARN: attempt 1: server 127.0.0.1:9005: no known leader 336s app_test.go:1207: 03:11:10.224 - 65: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:11:10.225 - 65: DEBUG: new connection from 127.0.0.1:43582 336s app_test.go:1207: 03:11:10.225 - 65: WARN: attempt 1: server 127.0.0.1:9002: no known leader 336s app_test.go:1207: 03:11:10.225 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s --- PASS: TestRolesAdjustment_ImbalancedFailureDomain (21.07s) 336s === RUN TestRolesAdjustment_ReplaceVoterHonorWeight 336s app_test.go:1207: 03:11:10.915 - 73: DEBUG: new connection from 127.0.0.1:43586 336s app_test.go:1207: 03:11:10.916 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:11:10.917 - 73: DEBUG: new connection from 127.0.0.1:43588 336s app_test.go:1207: 03:12:10.000 - 73: DEBUG: new connection from 127.0.0.1:43600 336s app_test.go:1207: 03:12:10.002 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.006 - 73: DEBUG: new connection from 127.0.0.1:43616 336s app_test.go:1207: 03:12:10.006 - 74: DEBUG: new connection from 127.0.0.1:36524 336s app_test.go:1207: 03:12:10.089 - 73: DEBUG: new connection from 127.0.0.1:43628 336s app_test.go:1207: 03:12:10.091 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.093 - 73: DEBUG: new connection from 127.0.0.1:43642 336s app_test.go:1207: 03:12:10.093 - 74: DEBUG: new connection from 127.0.0.1:36540 336s app_test.go:1207: 03:12:10.094 - 75: DEBUG: new connection from 127.0.0.1:51448 336s app_test.go:1207: 03:12:10.096 - 75: DEBUG: new connection from 127.0.0.1:51462 336s app_test.go:1207: 03:12:10.098 - 73: DEBUG: new connection from 127.0.0.1:43654 336s app_test.go:1207: 03:12:10.832 - 74: DEBUG: new connection from 127.0.0.1:36546 336s app_test.go:1207: 03:12:10.835 - 73: DEBUG: new connection from 127.0.0.1:43656 336s app_test.go:1207: 03:12:10.930 - 73: DEBUG: new connection from 127.0.0.1:43666 336s app_test.go:1207: 03:12:10.932 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.934 - 74: DEBUG: new connection from 127.0.0.1:36560 336s app_test.go:1207: 03:12:10.935 - 73: DEBUG: new connection from 127.0.0.1:43674 336s app_test.go:1207: 03:12:10.936 - 75: DEBUG: new connection from 127.0.0.1:51466 336s app_test.go:1207: 03:12:10.936 - 76: DEBUG: new connection from 127.0.0.1:60454 336s app_test.go:1207: 03:12:10.937 - 76: DEBUG: new connection from 127.0.0.1:60460 336s app_test.go:1207: 03:12:10.940 - 73: DEBUG: new connection from 127.0.0.1:43680 336s app_test.go:1207: 03:12:10.030 - 73: DEBUG: new connection from 127.0.0.1:43696 336s app_test.go:1207: 03:12:10.032 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.034 - 74: DEBUG: new connection from 127.0.0.1:36576 336s app_test.go:1207: 03:12:10.034 - 73: DEBUG: new connection from 127.0.0.1:43702 336s app_test.go:1207: 03:12:10.036 - 75: DEBUG: new connection from 127.0.0.1:51482 336s app_test.go:1207: 03:12:10.037 - 76: DEBUG: new connection from 127.0.0.1:60476 336s app_test.go:1207: 03:12:10.038 - 77: DEBUG: new connection from 127.0.0.1:40576 336s app_test.go:1207: 03:12:10.039 - 77: DEBUG: new connection from 127.0.0.1:40592 336s app_test.go:1207: 03:12:10.042 - 73: DEBUG: new connection from 127.0.0.1:43712 336s app_test.go:1207: 03:12:10.120 - 73: DEBUG: new connection from 127.0.0.1:43728 336s app_test.go:1207: 03:12:10.121 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.126 - 74: DEBUG: new connection from 127.0.0.1:36582 336s app_test.go:1207: 03:12:10.126 - 73: DEBUG: new connection from 127.0.0.1:43738 336s app_test.go:1207: 03:12:10.127 - 75: DEBUG: new connection from 127.0.0.1:51494 336s app_test.go:1207: 03:12:10.128 - 76: DEBUG: new connection from 127.0.0.1:60488 336s app_test.go:1207: 03:12:10.130 - 77: DEBUG: new connection from 127.0.0.1:40606 336s app_test.go:1207: 03:12:10.131 - 78: DEBUG: new connection from 127.0.0.1:45960 336s app_test.go:1207: 03:12:10.132 - 78: DEBUG: new connection from 127.0.0.1:45976 336s app_test.go:1207: 03:12:10.135 - 73: DEBUG: new connection from 127.0.0.1:43746 336s app_test.go:1207: 03:12:10.919 - 73: DEBUG: new connection from 127.0.0.1:43762 336s app_test.go:1207: 03:12:10.920 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.922 - 74: DEBUG: new connection from 127.0.0.1:36594 336s app_test.go:1207: 03:12:10.922 - 73: DEBUG: new connection from 127.0.0.1:43774 336s app_test.go:1207: 03:12:10.923 - 76: DEBUG: new connection from 127.0.0.1:60504 336s app_test.go:1207: 03:12:10.923 - 77: DEBUG: new connection from 127.0.0.1:40610 336s app_test.go:1207: 03:12:10.924 - 78: DEBUG: new connection from 127.0.0.1:45986 336s app_test.go:1207: 03:12:10.926 - 73: DEBUG: new connection from 127.0.0.1:43790 336s app_test.go:1207: 03:12:10.926 - 74: DEBUG: new connection from 127.0.0.1:36596 336s app_test.go:1207: 03:12:10.928 - 76: DEBUG: new connection from 127.0.0.1:60508 336s app_test.go:1207: 03:12:10.928 - 77: DEBUG: new connection from 127.0.0.1:40622 336s app_test.go:1207: 03:12:10.929 - 78: DEBUG: new connection from 127.0.0.1:46002 336s app_test.go:1207: 03:12:10.932 - 73: DEBUG: new connection from 127.0.0.1:43802 336s app_test.go:1207: 03:12:10.932 - 74: DEBUG: new connection from 127.0.0.1:36598 336s app_test.go:1207: 03:12:10.933 - 76: DEBUG: new connection from 127.0.0.1:60514 336s app_test.go:1207: 03:12:10.934 - 77: DEBUG: new connection from 127.0.0.1:40628 336s app_test.go:1207: 03:12:10.935 - 78: DEBUG: new connection from 127.0.0.1:46012 336s app_test.go:1207: 03:12:10.008 - 73: DEBUG: new connection from 127.0.0.1:43810 336s app_test.go:1207: 03:12:10.010 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.939 - 73: DEBUG: new connection from 127.0.0.1:33136 336s app_test.go:1207: 03:12:10.940 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.041 - 73: DEBUG: new connection from 127.0.0.1:33144 336s app_test.go:1207: 03:12:10.042 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.134 - 73: DEBUG: new connection from 127.0.0.1:33154 336s app_test.go:1207: 03:12:10.135 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.938 - 73: DEBUG: new connection from 127.0.0.1:33170 336s app_test.go:1207: 03:12:10.940 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.942 - 73: DEBUG: new connection from 127.0.0.1:33176 336s app_test.go:1207: 03:12:10.942 - 74: DEBUG: new connection from 127.0.0.1:34730 336s app_test.go:1207: 03:12:10.943 - 76: DEBUG: new connection from 127.0.0.1:34240 336s app_test.go:1207: 03:12:10.943 - 77: DEBUG: new connection from 127.0.0.1:48740 336s app_test.go:1207: 03:12:10.945 - 78: DEBUG: new connection from 127.0.0.1:46524 336s app_test.go:1207: 03:12:10.013 - 73: DEBUG: new connection from 127.0.0.1:33178 336s app_test.go:1207: 03:12:10.014 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.943 - 73: DEBUG: new connection from 127.0.0.1:33192 336s app_test.go:1207: 03:12:10.944 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.044 - 73: DEBUG: new connection from 127.0.0.1:33194 336s app_test.go:1207: 03:12:10.045 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.137 - 73: DEBUG: new connection from 127.0.0.1:33204 336s app_test.go:1207: 03:12:10.138 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.948 - 73: DEBUG: new connection from 127.0.0.1:33210 336s app_test.go:1207: 03:12:10.950 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.953 - 73: DEBUG: new connection from 127.0.0.1:33220 336s app_test.go:1207: 03:12:10.953 - 74: DEBUG: new connection from 127.0.0.1:34744 336s app_test.go:1207: 03:12:10.955 - 76: DEBUG: new connection from 127.0.0.1:34244 336s app_test.go:1207: 03:12:10.955 - 77: DEBUG: new connection from 127.0.0.1:48750 336s app_test.go:1207: 03:12:10.957 - 78: DEBUG: new connection from 127.0.0.1:46528 336s app_test.go:1207: 03:12:10.016 - 73: DEBUG: new connection from 127.0.0.1:33234 336s app_test.go:1207: 03:12:10.018 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.947 - 73: DEBUG: new connection from 127.0.0.1:33250 336s app_test.go:1207: 03:12:10.949 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.048 - 73: DEBUG: new connection from 127.0.0.1:33258 336s app_test.go:1207: 03:12:10.049 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.141 - 73: DEBUG: new connection from 127.0.0.1:33264 336s app_test.go:1207: 03:12:10.142 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.962 - 73: DEBUG: new connection from 127.0.0.1:40798 336s app_test.go:1207: 03:12:10.964 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.966 - 73: DEBUG: new connection from 127.0.0.1:40802 336s app_test.go:1207: 03:12:10.966 - 74: DEBUG: new connection from 127.0.0.1:58258 336s app_test.go:1207: 03:12:10.967 - 76: DEBUG: new connection from 127.0.0.1:37144 336s app_test.go:1207: 03:12:10.968 - 77: DEBUG: new connection from 127.0.0.1:51968 336s app_test.go:1207: 03:12:10.969 - 78: DEBUG: new connection from 127.0.0.1:48666 336s app_test.go:1207: 03:12:10.021 - 73: DEBUG: new connection from 127.0.0.1:40812 336s app_test.go:1207: 03:12:10.021 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.951 - 73: DEBUG: new connection from 127.0.0.1:40828 336s app_test.go:1207: 03:12:10.952 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.051 - 73: DEBUG: new connection from 127.0.0.1:40836 336s app_test.go:1207: 03:12:10.052 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.144 - 73: DEBUG: new connection from 127.0.0.1:40846 336s app_test.go:1207: 03:12:10.145 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.971 - 73: DEBUG: new connection from 127.0.0.1:40858 336s app_test.go:1207: 03:12:10.972 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.975 - 73: DEBUG: new connection from 127.0.0.1:40860 336s app_test.go:1207: 03:12:10.975 - 74: DEBUG: new connection from 127.0.0.1:58268 336s app_test.go:1207: 03:12:10.976 - 76: DEBUG: new connection from 127.0.0.1:37146 336s app_test.go:1207: 03:12:10.977 - 77: DEBUG: new connection from 127.0.0.1:51982 336s app_test.go:1207: 03:12:10.978 - 78: DEBUG: new connection from 127.0.0.1:48672 336s app_test.go:1207: 03:12:10.023 - 73: DEBUG: new connection from 127.0.0.1:40868 336s app_test.go:1207: 03:12:10.024 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.211 - 74: DEBUG: new connection from 127.0.0.1:58270 336s app_test.go:1207: 03:12:10.211 - 78: DEBUG: new connection from 127.0.0.1:48680 336s app_test.go:1207: 03:12:10.211 - 76: DEBUG: new connection from 127.0.0.1:37154 336s app_test.go:1207: 03:12:10.211 - 73: DEBUG: new connection from 127.0.0.1:40878 336s app_test.go:1207: 03:12:10.211 - 73: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 336s app_test.go:1207: 03:12:10.211 - 77: DEBUG: new connection from 127.0.0.1:51992 336s app_test.go:1207: 03:12:10.213 - 73: DEBUG: attempt 1: server 127.0.0.1:9005: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:12:10.213 - 73: DEBUG: new connection from 127.0.0.1:40894 336s app_test.go:1207: 03:12:10.213 - 73: WARN: attempt 1: server 127.0.0.1:9006: no known leader 336s app_test.go:1207: 03:12:10.213 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.214 - 73: WARN: attempt 1: server 127.0.0.1:9004: no known leader 336s app_test.go:1207: 03:12:10.214 - 73: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:12:10.214 - 73: WARN: attempt 1: server 127.0.0.1:9001: context canceled 336s --- PASS: TestRolesAdjustment_ReplaceVoterHonorWeight (20.81s) 336s === RUN TestRolesAdjustment_CantReplaceVoter 336s app_test.go:1207: 03:12:10.715 - 79: DEBUG: new connection from 127.0.0.1:40898 336s app_test.go:1207: 03:12:10.716 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.718 - 79: DEBUG: new connection from 127.0.0.1:40900 336s app_test.go:1207: 03:12:10.810 - 79: DEBUG: new connection from 127.0.0.1:40906 336s app_test.go:1207: 03:12:10.811 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.816 - 79: DEBUG: new connection from 127.0.0.1:40920 336s app_test.go:1207: 03:12:10.817 - 80: DEBUG: new connection from 127.0.0.1:58282 336s app_test.go:1207: 03:12:10.900 - 79: DEBUG: new connection from 127.0.0.1:40926 336s app_test.go:1207: 03:12:10.901 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.903 - 80: DEBUG: new connection from 127.0.0.1:58292 336s app_test.go:1207: 03:12:10.903 - 79: DEBUG: new connection from 127.0.0.1:40932 336s app_test.go:1207: 03:12:10.903 - 81: DEBUG: new connection from 127.0.0.1:58108 336s app_test.go:1207: 03:12:10.905 - 81: DEBUG: new connection from 127.0.0.1:58114 336s app_test.go:1207: 03:12:10.906 - 79: DEBUG: new connection from 127.0.0.1:40938 336s app_test.go:1207: 03:12:10.639 - 80: DEBUG: new connection from 127.0.0.1:58306 336s app_test.go:1207: 03:12:10.642 - 79: DEBUG: new connection from 127.0.0.1:40946 336s app_test.go:1207: 03:12:10.720 - 79: DEBUG: new connection from 127.0.0.1:40950 336s app_test.go:1207: 03:12:10.721 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.723 - 80: DEBUG: new connection from 127.0.0.1:58312 336s app_test.go:1207: 03:12:10.723 - 79: DEBUG: new connection from 127.0.0.1:40956 336s app_test.go:1207: 03:12:10.724 - 81: DEBUG: new connection from 127.0.0.1:58120 336s app_test.go:1207: 03:12:10.724 - 82: DEBUG: new connection from 127.0.0.1:37166 336s app_test.go:1207: 03:12:10.725 - 82: DEBUG: new connection from 127.0.0.1:37168 336s app_test.go:1207: 03:12:10.719 - 79: DEBUG: new connection from 127.0.0.1:40964 336s app_test.go:1207: 03:12:10.721 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.724 - 79: DEBUG: new connection from 127.0.0.1:40966 336s app_test.go:1207: 03:12:10.724 - 80: DEBUG: new connection from 127.0.0.1:58326 336s app_test.go:1207: 03:12:10.820 - 79: DEBUG: new connection from 127.0.0.1:40976 336s app_test.go:1207: 03:12:10.822 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.727 - 79: DEBUG: new connection from 127.0.0.1:42182 336s app_test.go:1207: 03:12:10.729 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.730 - 79: DEBUG: new connection from 127.0.0.1:42192 336s app_test.go:1207: 03:12:10.730 - 80: DEBUG: new connection from 127.0.0.1:58354 336s app_test.go:1207: 03:12:10.831 - 79: DEBUG: new connection from 127.0.0.1:42196 336s app_test.go:1207: 03:12:10.832 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.732 - 79: DEBUG: new connection from 127.0.0.1:42198 336s app_test.go:1207: 03:12:10.733 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.736 - 79: DEBUG: new connection from 127.0.0.1:42204 336s app_test.go:1207: 03:12:10.736 - 80: DEBUG: new connection from 127.0.0.1:58368 336s app_test.go:1207: 03:12:10.834 - 79: DEBUG: new connection from 127.0.0.1:42206 336s app_test.go:1207: 03:12:10.836 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.780 - 79: DEBUG: new connection from 127.0.0.1:42220 336s app_test.go:1207: 03:12:10.781 - 80: DEBUG: new connection from 127.0.0.1:58376 336s app_test.go:1207: 03:12:10.781 - 79: WARN: attempt 1: server 127.0.0.1:9004: dial: dial tcp 127.0.0.1:9004: connect: connection refused 336s app_test.go:1207: 03:12:10.781 - 79: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 336s app_test.go:1207: 03:12:10.781 - 79: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:12:10.781 - 79: DEBUG: new connection from 127.0.0.1:42236 336s app_test.go:1207: 03:12:10.782 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s --- PASS: TestRolesAdjustment_CantReplaceVoter (14.32s) 336s === RUN TestRolesAdjustment_ReplaceStandBy 336s app_test.go:1207: 03:12:10.062 - 83: DEBUG: new connection from 127.0.0.1:42242 336s app_test.go:1207: 03:12:10.063 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.064 - 83: DEBUG: new connection from 127.0.0.1:42246 336s app_test.go:1207: 03:12:10.139 - 83: DEBUG: new connection from 127.0.0.1:42258 336s app_test.go:1207: 03:12:10.141 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.145 - 83: DEBUG: new connection from 127.0.0.1:42266 336s app_test.go:1207: 03:12:10.145 - 84: DEBUG: new connection from 127.0.0.1:58386 336s app_test.go:1207: 03:12:10.230 - 83: DEBUG: new connection from 127.0.0.1:42272 336s app_test.go:1207: 03:12:10.232 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.233 - 84: DEBUG: new connection from 127.0.0.1:58392 336s app_test.go:1207: 03:12:10.233 - 83: DEBUG: new connection from 127.0.0.1:42274 336s app_test.go:1207: 03:12:10.234 - 85: DEBUG: new connection from 127.0.0.1:57638 336s app_test.go:1207: 03:12:10.235 - 85: DEBUG: new connection from 127.0.0.1:57652 336s app_test.go:1207: 03:12:10.237 - 83: DEBUG: new connection from 127.0.0.1:42286 336s app_test.go:1207: 03:12:10.957 - 84: DEBUG: new connection from 127.0.0.1:51642 336s app_test.go:1207: 03:12:10.969 - 83: DEBUG: new connection from 127.0.0.1:40978 336s app_test.go:1207: 03:12:10.050 - 83: DEBUG: new connection from 127.0.0.1:40992 336s app_test.go:1207: 03:12:10.052 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.063 - 84: DEBUG: new connection from 127.0.0.1:51650 336s app_test.go:1207: 03:12:10.063 - 83: DEBUG: new connection from 127.0.0.1:41008 336s app_test.go:1207: 03:12:10.066 - 86: DEBUG: new connection from 127.0.0.1:46874 336s app_test.go:1207: 03:12:10.066 - 85: DEBUG: new connection from 127.0.0.1:53126 336s app_test.go:1207: 03:12:10.068 - 86: DEBUG: new connection from 127.0.0.1:46890 336s app_test.go:1207: 03:12:10.074 - 83: DEBUG: new connection from 127.0.0.1:41016 336s app_test.go:1207: 03:12:10.129 - 83: DEBUG: new connection from 127.0.0.1:41020 336s app_test.go:1207: 03:12:10.131 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.133 - 83: DEBUG: new connection from 127.0.0.1:41022 336s app_test.go:1207: 03:12:10.133 - 84: DEBUG: new connection from 127.0.0.1:51654 336s app_test.go:1207: 03:12:10.134 - 85: DEBUG: new connection from 127.0.0.1:53142 336s app_test.go:1207: 03:12:10.134 - 86: DEBUG: new connection from 127.0.0.1:46892 336s app_test.go:1207: 03:12:10.137 - 87: DEBUG: new connection from 127.0.0.1:50074 336s app_test.go:1207: 03:12:10.138 - 87: DEBUG: new connection from 127.0.0.1:50084 336s app_test.go:1207: 03:12:10.141 - 83: DEBUG: new connection from 127.0.0.1:41034 336s app_test.go:1207: 03:12:10.220 - 83: DEBUG: new connection from 127.0.0.1:41042 336s app_test.go:1207: 03:12:10.221 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.224 - 83: DEBUG: new connection from 127.0.0.1:41044 336s app_test.go:1207: 03:12:10.224 - 84: DEBUG: new connection from 127.0.0.1:51658 336s app_test.go:1207: 03:12:10.225 - 85: DEBUG: new connection from 127.0.0.1:53154 336s app_test.go:1207: 03:12:10.226 - 86: DEBUG: new connection from 127.0.0.1:46898 336s app_test.go:1207: 03:12:10.228 - 87: DEBUG: new connection from 127.0.0.1:50098 336s app_test.go:1207: 03:12:10.230 - 88: DEBUG: new connection from 127.0.0.1:33778 336s app_test.go:1207: 03:12:10.231 - 88: DEBUG: new connection from 127.0.0.1:33780 336s app_test.go:1207: 03:12:10.235 - 83: DEBUG: new connection from 127.0.0.1:41052 336s app_test.go:1207: 03:12:10.309 - 83: DEBUG: new connection from 127.0.0.1:41064 336s app_test.go:1207: 03:12:10.311 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.313 - 84: DEBUG: new connection from 127.0.0.1:51668 336s app_test.go:1207: 03:12:10.313 - 83: DEBUG: new connection from 127.0.0.1:41068 336s app_test.go:1207: 03:12:10.314 - 85: DEBUG: new connection from 127.0.0.1:53164 336s app_test.go:1207: 03:12:10.315 - 86: DEBUG: new connection from 127.0.0.1:46908 336s app_test.go:1207: 03:12:10.315 - 87: DEBUG: new connection from 127.0.0.1:50110 336s app_test.go:1207: 03:12:10.317 - 89: DEBUG: new connection from 127.0.0.1:34086 336s app_test.go:1207: 03:12:10.317 - 88: DEBUG: new connection from 127.0.0.1:33792 336s app_test.go:1207: 03:12:10.066 - 83: DEBUG: new connection from 127.0.0.1:41074 336s app_test.go:1207: 03:12:10.068 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.070 - 84: DEBUG: new connection from 127.0.0.1:51670 336s app_test.go:1207: 03:12:10.070 - 83: DEBUG: new connection from 127.0.0.1:41078 336s app_test.go:1207: 03:12:10.070 - 85: DEBUG: new connection from 127.0.0.1:53168 336s app_test.go:1207: 03:12:10.071 - 86: DEBUG: new connection from 127.0.0.1:46922 336s app_test.go:1207: 03:12:10.072 - 88: DEBUG: new connection from 127.0.0.1:33806 336s app_test.go:1207: 03:12:10.072 - 89: DEBUG: new connection from 127.0.0.1:34092 336s app_test.go:1207: 03:12:10.073 - 89: DEBUG: new connection from 127.0.0.1:34096 336s app_test.go:1207: 03:12:10.075 - 83: DEBUG: new connection from 127.0.0.1:41094 336s app_test.go:1207: 03:12:10.075 - 84: DEBUG: new connection from 127.0.0.1:51682 336s app_test.go:1207: 03:12:10.076 - 83: DEBUG: new connection from 127.0.0.1:41108 336s app_test.go:1207: 03:12:10.078 - 85: DEBUG: new connection from 127.0.0.1:53184 336s app_test.go:1207: 03:12:10.079 - 86: DEBUG: new connection from 127.0.0.1:46930 336s app_test.go:1207: 03:12:10.080 - 88: DEBUG: new connection from 127.0.0.1:33820 336s app_test.go:1207: 03:12:10.080 - 89: DEBUG: new connection from 127.0.0.1:34102 336s app_test.go:1207: 03:12:10.082 - 83: DEBUG: new connection from 127.0.0.1:41122 336s app_test.go:1207: 03:12:10.082 - 84: DEBUG: new connection from 127.0.0.1:51692 336s app_test.go:1207: 03:12:10.083 - 85: DEBUG: new connection from 127.0.0.1:53198 336s app_test.go:1207: 03:12:10.083 - 86: DEBUG: new connection from 127.0.0.1:46940 336s app_test.go:1207: 03:12:10.084 - 88: DEBUG: new connection from 127.0.0.1:33832 336s app_test.go:1207: 03:12:10.085 - 89: DEBUG: new connection from 127.0.0.1:34114 336s app_test.go:1207: 03:12:10.147 - 83: DEBUG: new connection from 127.0.0.1:41124 336s app_test.go:1207: 03:12:10.148 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.959 - 83: DEBUG: new connection from 127.0.0.1:41126 336s app_test.go:1207: 03:12:10.962 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.070 - 83: DEBUG: new connection from 127.0.0.1:41140 336s app_test.go:1207: 03:12:10.071 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.233 - 83: DEBUG: new connection from 127.0.0.1:41146 336s app_test.go:1207: 03:12:10.234 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.320 - 83: DEBUG: new connection from 127.0.0.1:41156 336s app_test.go:1207: 03:12:10.321 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.087 - 83: DEBUG: new connection from 127.0.0.1:41164 336s app_test.go:1207: 03:12:10.088 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.090 - 84: DEBUG: new connection from 127.0.0.1:51706 336s app_test.go:1207: 03:12:10.090 - 83: DEBUG: new connection from 127.0.0.1:41172 336s app_test.go:1207: 03:12:10.091 - 85: DEBUG: new connection from 127.0.0.1:53208 336s app_test.go:1207: 03:12:10.091 - 86: DEBUG: new connection from 127.0.0.1:46952 336s app_test.go:1207: 03:12:10.092 - 88: DEBUG: new connection from 127.0.0.1:33838 336s app_test.go:1207: 03:12:10.092 - 89: DEBUG: new connection from 127.0.0.1:34126 336s app_test.go:1207: 03:12:10.149 - 83: DEBUG: new connection from 127.0.0.1:41182 336s app_test.go:1207: 03:12:10.150 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.965 - 83: DEBUG: new connection from 127.0.0.1:53356 336s app_test.go:1207: 03:12:10.966 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.083 - 83: DEBUG: new connection from 127.0.0.1:53372 336s app_test.go:1207: 03:12:10.085 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.237 - 83: DEBUG: new connection from 127.0.0.1:53380 336s app_test.go:1207: 03:12:10.239 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.323 - 83: DEBUG: new connection from 127.0.0.1:53392 336s app_test.go:1207: 03:12:10.324 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.095 - 83: DEBUG: new connection from 127.0.0.1:53402 336s app_test.go:1207: 03:12:10.096 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.098 - 84: DEBUG: new connection from 127.0.0.1:48444 336s app_test.go:1207: 03:12:10.098 - 83: DEBUG: new connection from 127.0.0.1:53416 336s app_test.go:1207: 03:12:10.099 - 85: DEBUG: new connection from 127.0.0.1:42868 336s app_test.go:1207: 03:12:10.099 - 86: DEBUG: new connection from 127.0.0.1:34878 336s app_test.go:1207: 03:12:10.100 - 88: DEBUG: new connection from 127.0.0.1:42644 336s app_test.go:1207: 03:12:10.100 - 89: DEBUG: new connection from 127.0.0.1:39590 336s app_test.go:1207: 03:12:10.152 - 83: DEBUG: new connection from 127.0.0.1:53430 336s app_test.go:1207: 03:12:10.153 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.968 - 83: DEBUG: new connection from 127.0.0.1:53446 336s app_test.go:1207: 03:12:10.970 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.087 - 83: DEBUG: new connection from 127.0.0.1:53462 336s app_test.go:1207: 03:12:10.088 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.242 - 83: DEBUG: new connection from 127.0.0.1:53470 336s app_test.go:1207: 03:12:10.242 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.326 - 83: DEBUG: new connection from 127.0.0.1:53480 336s app_test.go:1207: 03:12:10.327 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.103 - 83: DEBUG: new connection from 127.0.0.1:53484 336s app_test.go:1207: 03:12:10.104 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.106 - 83: DEBUG: new connection from 127.0.0.1:53490 336s app_test.go:1207: 03:12:10.106 - 84: DEBUG: new connection from 127.0.0.1:48458 336s app_test.go:1207: 03:12:10.108 - 85: DEBUG: new connection from 127.0.0.1:42878 336s app_test.go:1207: 03:12:10.108 - 86: DEBUG: new connection from 127.0.0.1:34892 336s app_test.go:1207: 03:12:10.109 - 88: DEBUG: new connection from 127.0.0.1:42648 336s app_test.go:1207: 03:12:10.110 - 89: DEBUG: new connection from 127.0.0.1:39606 336s app_test.go:1207: 03:12:10.156 - 83: DEBUG: new connection from 127.0.0.1:53494 336s app_test.go:1207: 03:12:10.156 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.972 - 83: DEBUG: new connection from 127.0.0.1:49294 336s app_test.go:1207: 03:12:10.974 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.090 - 83: DEBUG: new connection from 127.0.0.1:49308 336s app_test.go:1207: 03:12:10.091 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.245 - 83: DEBUG: new connection from 127.0.0.1:49324 336s app_test.go:1207: 03:12:10.247 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:12:10.320 - 84: DEBUG: new connection from 127.0.0.1:42956 336s app_test.go:1207: 03:12:10.320 - 88: DEBUG: new connection from 127.0.0.1:52984 336s app_test.go:1207: 03:12:10.320 - 86: DEBUG: new connection from 127.0.0.1:50406 336s app_test.go:1207: 03:12:10.321 - 85: DEBUG: new connection from 127.0.0.1:53874 336s app_test.go:1207: 03:12:10.321 - 83: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 336s app_test.go:1207: 03:12:10.320 - 83: DEBUG: new connection from 127.0.0.1:49330 336s app_test.go:1207: 03:12:10.320 - 89: DEBUG: new connection from 127.0.0.1:53040 336s app_test.go:1207: 03:12:10.322 - 83: WARN: attempt 1: server 127.0.0.1:9006: no known leader 336s app_test.go:1207: 03:12:10.322 - 83: WARN: attempt 1: server 127.0.0.1:9007: no known leader 336s app_test.go:1207: 03:12:10.323 - 83: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:12:10.323 - 83: DEBUG: new connection from 127.0.0.1:49340 336s app_test.go:1207: 03:12:10.323 - 83: WARN: attempt 1: server 127.0.0.1:9004: no known leader 336s app_test.go:1207: 03:12:10.323 - 83: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:12:10.323 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.323 - 83: DEBUG: new connection from 127.0.0.1:49350 336s --- PASS: TestRolesAdjustment_ReplaceStandBy (22.87s) 336s === RUN TestRolesAdjustment_ReplaceStandByHonorFailureDomains 336s app_test.go:1207: 03:12:10.901 - 90: DEBUG: new connection from 127.0.0.1:49360 336s app_test.go:1207: 03:12:10.903 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.904 - 90: DEBUG: new connection from 127.0.0.1:49364 336s app_test.go:1207: 03:12:10.000 - 90: DEBUG: new connection from 127.0.0.1:49380 336s app_test.go:1207: 03:12:10.001 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.005 - 91: DEBUG: new connection from 127.0.0.1:42964 336s app_test.go:1207: 03:12:10.005 - 90: DEBUG: new connection from 127.0.0.1:49382 336s app_test.go:1207: 03:12:10.080 - 90: DEBUG: new connection from 127.0.0.1:49390 336s app_test.go:1207: 03:12:10.081 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.082 - 91: DEBUG: new connection from 127.0.0.1:42970 336s app_test.go:1207: 03:12:10.083 - 90: DEBUG: new connection from 127.0.0.1:49394 336s app_test.go:1207: 03:12:10.083 - 92: DEBUG: new connection from 127.0.0.1:53886 336s app_test.go:1207: 03:12:10.084 - 92: DEBUG: new connection from 127.0.0.1:53898 336s app_test.go:1207: 03:12:10.086 - 90: DEBUG: new connection from 127.0.0.1:49402 336s app_test.go:1207: 03:12:10.826 - 91: DEBUG: new connection from 127.0.0.1:42984 336s app_test.go:1207: 03:12:10.829 - 90: DEBUG: new connection from 127.0.0.1:49412 336s app_test.go:1207: 03:12:10.910 - 90: DEBUG: new connection from 127.0.0.1:49416 336s app_test.go:1207: 03:12:10.911 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:12:10.913 - 90: DEBUG: new connection from 127.0.0.1:49426 336s app_test.go:1207: 03:12:10.913 - 91: DEBUG: new connection from 127.0.0.1:42996 336s app_test.go:1207: 03:12:10.915 - 92: DEBUG: new connection from 127.0.0.1:53914 336s app_test.go:1207: 03:12:10.915 - 93: DEBUG: new connection from 127.0.0.1:50414 336s app_test.go:1207: 03:12:10.917 - 93: DEBUG: new connection from 127.0.0.1:50416 336s app_test.go:1207: 03:12:10.921 - 90: DEBUG: new connection from 127.0.0.1:49430 336s app_test.go:1207: 03:13:10.000 - 90: DEBUG: new connection from 127.0.0.1:49438 336s app_test.go:1207: 03:13:10.001 - 94: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:13:10.003 - 90: DEBUG: new connection from 127.0.0.1:49454 336s app_test.go:1207: 03:13:10.004 - 91: DEBUG: new connection from 127.0.0.1:43006 336s app_test.go:1207: 03:13:10.004 - 92: DEBUG: new connection from 127.0.0.1:53920 336s app_test.go:1207: 03:13:10.005 - 93: DEBUG: new connection from 127.0.0.1:50430 336s app_test.go:1207: 03:13:10.006 - 94: DEBUG: new connection from 127.0.0.1:52208 336s app_test.go:1207: 03:13:10.009 - 94: DEBUG: new connection from 127.0.0.1:52220 336s app_test.go:1207: 03:13:10.012 - 90: DEBUG: new connection from 127.0.0.1:49460 336s app_test.go:1207: 03:13:10.090 - 90: DEBUG: new connection from 127.0.0.1:49462 336s app_test.go:1207: 03:13:10.091 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:13:10.092 - 90: DEBUG: new connection from 127.0.0.1:49476 336s app_test.go:1207: 03:13:10.093 - 91: DEBUG: new connection from 127.0.0.1:43022 336s app_test.go:1207: 03:13:10.094 - 92: DEBUG: new connection from 127.0.0.1:53930 336s app_test.go:1207: 03:13:10.095 - 93: DEBUG: new connection from 127.0.0.1:50444 336s app_test.go:1207: 03:13:10.097 - 94: DEBUG: new connection from 127.0.0.1:52230 336s app_test.go:1207: 03:13:10.098 - 95: DEBUG: new connection from 127.0.0.1:52994 336s app_test.go:1207: 03:13:10.100 - 95: DEBUG: new connection from 127.0.0.1:53010 336s app_test.go:1207: 03:13:10.103 - 90: DEBUG: new connection from 127.0.0.1:49492 336s app_test.go:1207: 03:13:10.179 - 90: DEBUG: new connection from 127.0.0.1:49496 336s app_test.go:1207: 03:13:10.181 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:13:10.183 - 90: DEBUG: new connection from 127.0.0.1:49498 336s app_test.go:1207: 03:13:10.183 - 91: DEBUG: new connection from 127.0.0.1:43028 336s app_test.go:1207: 03:13:10.184 - 92: DEBUG: new connection from 127.0.0.1:53946 336s app_test.go:1207: 03:13:10.185 - 93: DEBUG: new connection from 127.0.0.1:50454 336s app_test.go:1207: 03:13:10.186 - 94: DEBUG: new connection from 127.0.0.1:52242 336s app_test.go:1207: 03:13:10.188 - 95: DEBUG: new connection from 127.0.0.1:53018 336s app_test.go:1207: 03:13:10.188 - 96: DEBUG: new connection from 127.0.0.1:53064 336s app_test.go:1207: 03:13:10.270 - 90: DEBUG: new connection from 127.0.0.1:49504 336s app_test.go:1207: 03:13:10.271 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:13:10.274 - 90: DEBUG: new connection from 127.0.0.1:49514 336s app_test.go:1207: 03:13:10.274 - 91: DEBUG: new connection from 127.0.0.1:43034 336s app_test.go:1207: 03:13:10.276 - 92: DEBUG: new connection from 127.0.0.1:53952 336s app_test.go:1207: 03:13:10.276 - 93: DEBUG: new connection from 127.0.0.1:50456 336s app_test.go:1207: 03:13:10.278 - 94: DEBUG: new connection from 127.0.0.1:52254 336s app_test.go:1207: 03:13:10.279 - 95: DEBUG: new connection from 127.0.0.1:53022 336s app_test.go:1207: 03:13:10.280 - 96: DEBUG: new connection from 127.0.0.1:53074 336s app_test.go:1207: 03:13:10.280 - 97: DEBUG: new connection from 127.0.0.1:56604 336s app_test.go:1207: 03:13:10.370 - 90: DEBUG: new connection from 127.0.0.1:49528 336s app_test.go:1207: 03:13:10.371 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1207: 03:13:10.373 - 91: DEBUG: new connection from 127.0.0.1:43046 336s app_test.go:1207: 03:13:10.375 - 90: DEBUG: new connection from 127.0.0.1:49530 336s app_test.go:1207: 03:13:10.376 - 92: DEBUG: new connection from 127.0.0.1:53956 336s app_test.go:1207: 03:13:10.378 - 93: DEBUG: new connection from 127.0.0.1:50468 336s app_test.go:1207: 03:13:10.379 - 94: DEBUG: new connection from 127.0.0.1:52256 336s app_test.go:1207: 03:13:10.379 - 95: DEBUG: new connection from 127.0.0.1:53024 336s app_test.go:1207: 03:13:10.381 - 96: DEBUG: new connection from 127.0.0.1:53086 336s app_test.go:1207: 03:13:10.381 - 97: DEBUG: new connection from 127.0.0.1:56612 336s app_test.go:1207: 03:13:10.382 - 98: DEBUG: new connection from 127.0.0.1:35474 336s app_test.go:1207: 03:13:10.906 - 90: DEBUG: new connection from 127.0.0.1:49536 336s app_test.go:1207: 03:13:10.908 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.909 - 91: DEBUG: new connection from 127.0.0.1:43048 336s app_test.go:1207: 03:13:10.909 - 90: DEBUG: new connection from 127.0.0.1:49548 336s app_test.go:1207: 03:13:10.910 - 92: DEBUG: new connection from 127.0.0.1:53962 336s app_test.go:1207: 03:13:10.911 - 93: DEBUG: new connection from 127.0.0.1:50478 336s app_test.go:1207: 03:13:10.912 - 95: DEBUG: new connection from 127.0.0.1:53028 336s app_test.go:1207: 03:13:10.913 - 96: DEBUG: new connection from 127.0.0.1:53102 336s app_test.go:1207: 03:13:10.914 - 97: DEBUG: new connection from 127.0.0.1:56626 336s app_test.go:1207: 03:13:10.914 - 98: DEBUG: new connection from 127.0.0.1:35484 336s app_test.go:1207: 03:13:10.915 - 97: DEBUG: new connection from 127.0.0.1:56634 336s app_test.go:1207: 03:13:10.916 - 90: DEBUG: new connection from 127.0.0.1:49564 336s app_test.go:1207: 03:13:10.916 - 91: DEBUG: new connection from 127.0.0.1:43062 336s app_test.go:1207: 03:13:10.918 - 92: DEBUG: new connection from 127.0.0.1:53964 336s app_test.go:1207: 03:13:10.919 - 93: DEBUG: new connection from 127.0.0.1:50486 336s app_test.go:1207: 03:13:10.920 - 95: DEBUG: new connection from 127.0.0.1:53036 336s app_test.go:1207: 03:13:10.921 - 96: DEBUG: new connection from 127.0.0.1:53118 336s app_test.go:1207: 03:13:10.922 - 97: DEBUG: new connection from 127.0.0.1:56644 336s app_test.go:1207: 03:13:10.922 - 90: DEBUG: new connection from 127.0.0.1:49580 336s app_test.go:1207: 03:13:10.924 - 98: DEBUG: new connection from 127.0.0.1:35488 336s app_test.go:1207: 03:13:10.925 - 91: DEBUG: new connection from 127.0.0.1:43066 336s app_test.go:1207: 03:13:10.925 - 90: DEBUG: new connection from 127.0.0.1:49584 336s app_test.go:1207: 03:13:10.926 - 92: DEBUG: new connection from 127.0.0.1:53968 336s app_test.go:1207: 03:13:10.926 - 93: DEBUG: new connection from 127.0.0.1:50488 336s app_test.go:1207: 03:13:10.927 - 95: DEBUG: new connection from 127.0.0.1:53040 336s app_test.go:1207: 03:13:10.928 - 96: DEBUG: new connection from 127.0.0.1:53124 336s app_test.go:1207: 03:13:10.928 - 97: DEBUG: new connection from 127.0.0.1:56646 336s app_test.go:1207: 03:13:10.929 - 98: DEBUG: new connection from 127.0.0.1:35502 336s app_test.go:1207: 03:13:10.006 - 90: DEBUG: new connection from 127.0.0.1:49596 336s app_test.go:1207: 03:13:10.007 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.829 - 90: DEBUG: new connection from 127.0.0.1:49610 336s app_test.go:1207: 03:13:10.830 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.919 - 90: DEBUG: new connection from 127.0.0.1:49624 336s app_test.go:1207: 03:13:10.920 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.102 - 90: DEBUG: new connection from 127.0.0.1:49638 336s app_test.go:1207: 03:13:10.103 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.192 - 90: DEBUG: new connection from 127.0.0.1:49646 336s app_test.go:1207: 03:13:10.194 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.283 - 90: DEBUG: new connection from 127.0.0.1:49650 336s app_test.go:1207: 03:13:10.284 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.384 - 90: DEBUG: new connection from 127.0.0.1:49654 336s app_test.go:1207: 03:13:10.385 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.930 - 90: DEBUG: new connection from 127.0.0.1:43552 336s app_test.go:1207: 03:13:10.932 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.934 - 90: DEBUG: new connection from 127.0.0.1:43560 336s app_test.go:1207: 03:13:10.934 - 91: DEBUG: new connection from 127.0.0.1:50306 336s app_test.go:1207: 03:13:10.936 - 93: DEBUG: new connection from 127.0.0.1:46492 336s app_test.go:1207: 03:13:10.936 - 92: DEBUG: new connection from 127.0.0.1:58448 336s app_test.go:1207: 03:13:10.937 - 95: DEBUG: new connection from 127.0.0.1:36038 336s app_test.go:1207: 03:13:10.937 - 96: DEBUG: new connection from 127.0.0.1:56266 336s app_test.go:1207: 03:13:10.938 - 97: DEBUG: new connection from 127.0.0.1:45824 336s app_test.go:1207: 03:13:10.938 - 98: DEBUG: new connection from 127.0.0.1:40774 336s app_test.go:1207: 03:13:10.010 - 90: DEBUG: new connection from 127.0.0.1:43564 336s app_test.go:1207: 03:13:10.011 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.832 - 90: DEBUG: new connection from 127.0.0.1:43570 336s app_test.go:1207: 03:13:10.833 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.922 - 90: DEBUG: new connection from 127.0.0.1:43576 336s app_test.go:1207: 03:13:10.923 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.105 - 90: DEBUG: new connection from 127.0.0.1:43586 336s app_test.go:1207: 03:13:10.107 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.196 - 90: DEBUG: new connection from 127.0.0.1:43590 336s app_test.go:1207: 03:13:10.197 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.286 - 90: DEBUG: new connection from 127.0.0.1:43594 336s app_test.go:1207: 03:13:10.287 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.387 - 90: DEBUG: new connection from 127.0.0.1:43598 336s app_test.go:1207: 03:13:10.388 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.940 - 90: DEBUG: new connection from 127.0.0.1:43602 336s app_test.go:1207: 03:13:10.944 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.946 - 90: DEBUG: new connection from 127.0.0.1:43606 336s app_test.go:1207: 03:13:10.946 - 91: DEBUG: new connection from 127.0.0.1:50314 336s app_test.go:1207: 03:13:10.947 - 92: DEBUG: new connection from 127.0.0.1:58454 336s app_test.go:1207: 03:13:10.947 - 93: DEBUG: new connection from 127.0.0.1:46502 336s app_test.go:1207: 03:13:10.948 - 95: DEBUG: new connection from 127.0.0.1:36048 336s app_test.go:1207: 03:13:10.948 - 96: DEBUG: new connection from 127.0.0.1:56282 336s app_test.go:1207: 03:13:10.949 - 97: DEBUG: new connection from 127.0.0.1:45840 336s app_test.go:1207: 03:13:10.949 - 98: DEBUG: new connection from 127.0.0.1:40784 336s app_test.go:1207: 03:13:10.013 - 90: DEBUG: new connection from 127.0.0.1:43614 336s app_test.go:1207: 03:13:10.014 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.835 - 90: DEBUG: new connection from 127.0.0.1:43630 336s app_test.go:1207: 03:13:10.836 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.925 - 90: DEBUG: new connection from 127.0.0.1:43640 336s app_test.go:1207: 03:13:10.926 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.110 - 90: DEBUG: new connection from 127.0.0.1:43656 336s app_test.go:1207: 03:13:10.111 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.199 - 90: DEBUG: new connection from 127.0.0.1:43668 336s app_test.go:1207: 03:13:10.200 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.289 - 90: DEBUG: new connection from 127.0.0.1:43680 336s app_test.go:1207: 03:13:10.290 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.390 - 90: DEBUG: new connection from 127.0.0.1:43690 336s app_test.go:1207: 03:13:10.391 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.952 - 90: DEBUG: new connection from 127.0.0.1:40476 336s app_test.go:1207: 03:13:10.953 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.955 - 91: DEBUG: new connection from 127.0.0.1:52678 336s app_test.go:1207: 03:13:10.955 - 90: DEBUG: new connection from 127.0.0.1:40488 336s app_test.go:1207: 03:13:10.956 - 92: DEBUG: new connection from 127.0.0.1:53366 336s app_test.go:1207: 03:13:10.958 - 93: DEBUG: new connection from 127.0.0.1:51818 336s app_test.go:1207: 03:13:10.960 - 95: DEBUG: new connection from 127.0.0.1:56380 336s app_test.go:1207: 03:13:10.962 - 96: DEBUG: new connection from 127.0.0.1:36860 336s app_test.go:1207: 03:13:10.964 - 97: DEBUG: new connection from 127.0.0.1:41658 336s app_test.go:1207: 03:13:10.965 - 98: DEBUG: new connection from 127.0.0.1:55566 336s app_test.go:1207: 03:13:10.017 - 90: DEBUG: new connection from 127.0.0.1:40494 336s app_test.go:1207: 03:13:10.017 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.838 - 90: DEBUG: new connection from 127.0.0.1:40508 336s app_test.go:1207: 03:13:10.839 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.929 - 90: DEBUG: new connection from 127.0.0.1:40518 336s app_test.go:1207: 03:13:10.930 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.113 - 90: DEBUG: new connection from 127.0.0.1:40520 336s app_test.go:1207: 03:13:10.114 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.202 - 90: DEBUG: new connection from 127.0.0.1:40528 336s app_test.go:1207: 03:13:10.203 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.292 - 90: DEBUG: new connection from 127.0.0.1:40534 336s app_test.go:1207: 03:13:10.293 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 336s app_test.go:1207: 03:13:10.385 - 98: DEBUG: new connection from 127.0.0.1:55572 336s app_test.go:1207: 03:13:10.385 - 97: DEBUG: new connection from 127.0.0.1:41660 336s app_test.go:1207: 03:13:10.385 - 93: DEBUG: new connection from 127.0.0.1:51830 336s app_test.go:1207: 03:13:10.385 - 96: DEBUG: new connection from 127.0.0.1:36868 336s app_test.go:1207: 03:13:10.385 - 90: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 336s app_test.go:1207: 03:13:10.385 - 90: DEBUG: new connection from 127.0.0.1:40550 336s app_test.go:1207: 03:13:10.386 - 95: DEBUG: new connection from 127.0.0.1:56386 336s app_test.go:1207: 03:13:10.386 - 92: DEBUG: new connection from 127.0.0.1:53374 336s app_test.go:1207: 03:13:10.386 - 91: DEBUG: new connection from 127.0.0.1:52694 336s app_test.go:1207: 03:13:10.390 - 90: WARN: attempt 1: server 127.0.0.1:9007: no known leader 336s app_test.go:1207: 03:13:10.391 - 90: WARN: attempt 1: server 127.0.0.1:9009: no known leader 336s app_test.go:1207: 03:13:10.391 - 90: WARN: attempt 1: server 127.0.0.1:9006: no known leader 336s app_test.go:1207: 03:13:10.391 - 90: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:13:10.391 - 90: WARN: attempt 1: server 127.0.0.1:9008: no known leader 336s app_test.go:1207: 03:13:10.391 - 90: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 336s app_test.go:1207: 03:13:10.391 - 90: DEBUG: new connection from 127.0.0.1:40556 336s app_test.go:1207: 03:13:10.391 - 90: DEBUG: new connection from 127.0.0.1:40564 336s app_test.go:1207: 03:13:10.391 - 90: WARN: attempt 1: server 127.0.0.1:9004: no known leader 336s app_test.go:1207: 03:13:10.391 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s --- PASS: TestRolesAdjustment_ReplaceStandByHonorFailureDomains (23.07s) 336s === RUN TestOpen 336s app_test.go:1207: 03:13:10.971 - 99: DEBUG: new connection from 127.0.0.1:41784 336s app_test.go:1207: 03:13:10.971 - 99: DEBUG: new connection from 127.0.0.1:41786 336s app_test.go:1207: 03:13:10.973 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 336s app_test.go:1207: 03:13:10.973 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 336s app_test.go:1207: 03:13:10.974 - 99: DEBUG: new connection from 127.0.0.1:41804 336s --- PASS: TestOpen (0.17s) 336s === RUN TestOpenDisk 336s app_test.go:1207: 03:13:10.143 - 100: DEBUG: new connection from 127.0.0.1:41806 336s app_test.go:1207: 03:13:10.143 - 100: DEBUG: new connection from 127.0.0.1:41808 336s app_test.go:1207: 03:13:10.144 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 336s app_test.go:1207: 03:13:10.144 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 336s app_test.go:1207: 03:13:10.145 - 100: DEBUG: new connection from 127.0.0.1:41820 336s --- PASS: TestOpenDisk (0.16s) 336s === RUN TestOptions 336s --- PASS: TestOptions (0.12s) 336s === RUN TestProxy_Error 336s app_test.go:1207: 03:13:10.423 - 102: DEBUG: new connection from 127.0.0.1:41844 336s app_test.go:1207: 03:13:10.423 - 102: DEBUG: new connection from 127.0.0.1:41828 336s app_test.go:1207: 03:13:10.425 - 102: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 336s app_test.go:1207: 03:13:10.426 - 102: DEBUG: new connection from 127.0.0.1:41854 336s --- PASS: TestProxy_Error (0.43s) 336s === RUN TestReady_Cancel 336s app_test.go:1207: 03:13:10.860 - 103: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 336s --- PASS: TestReady_Cancel (0.28s) 336s === RUN TestExternalConnWithTCP 336s app_test.go:1232: 03:13:10.143 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 336s app_test.go:1232: 03:13:10.230 - 105: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 336s app_test.go:1232: 03:13:10.310 - 106: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 336s app_test.go:1232: 03:13:10.068 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 336s --- PASS: TestExternalConnWithTCP (2.24s) 336s === RUN TestExternalConnWithPipe 336s app_test.go:1232: 03:13:10.383 - 107: DEBUG: attempt 1: server first: connected on fallback path 336s app_test.go:1232: 03:13:10.460 - 108: DEBUG: attempt 1: server first: connected on fallback path 336s app_test.go:1232: 03:13:10.540 - 109: DEBUG: attempt 1: server first: connected on fallback path 336s app_test.go:1232: 03:13:10.309 - 107: DEBUG: attempt 1: server first: connected on fallback path 336s --- PASS: TestExternalConnWithPipe (2.26s) 336s === RUN TestParallelNewApp 336s === PAUSE TestParallelNewApp 336s === RUN Test_TxRowsAffected 336s app_test.go:1232: 03:13:10.642 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s app_test.go:1232: 03:13:10.644 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 336s --- PASS: Test_TxRowsAffected (0.16s) 336s === CONT TestParallelNewApp 336s === RUN TestParallelNewApp/run-0 336s === PAUSE TestParallelNewApp/run-0 336s === RUN TestParallelNewApp/run-1 336s === PAUSE TestParallelNewApp/run-1 336s === RUN TestParallelNewApp/run-2 336s === PAUSE TestParallelNewApp/run-2 336s === RUN TestParallelNewApp/run-3 336s === PAUSE TestParallelNewApp/run-3 336s === RUN TestParallelNewApp/run-4 336s === PAUSE TestParallelNewApp/run-4 336s === RUN TestParallelNewApp/run-5 336s === PAUSE TestParallelNewApp/run-5 336s === RUN TestParallelNewApp/run-6 336s === PAUSE TestParallelNewApp/run-6 336s === RUN TestParallelNewApp/run-7 336s === PAUSE TestParallelNewApp/run-7 336s === RUN TestParallelNewApp/run-8 336s === PAUSE TestParallelNewApp/run-8 336s === RUN TestParallelNewApp/run-9 336s === PAUSE TestParallelNewApp/run-9 336s === RUN TestParallelNewApp/run-10 336s === PAUSE TestParallelNewApp/run-10 336s === RUN TestParallelNewApp/run-11 336s === PAUSE TestParallelNewApp/run-11 336s === RUN TestParallelNewApp/run-12 336s === PAUSE TestParallelNewApp/run-12 336s === RUN TestParallelNewApp/run-13 336s === PAUSE TestParallelNewApp/run-13 336s === RUN TestParallelNewApp/run-14 336s === PAUSE TestParallelNewApp/run-14 336s === RUN TestParallelNewApp/run-15 336s === PAUSE TestParallelNewApp/run-15 336s === RUN TestParallelNewApp/run-16 336s === PAUSE TestParallelNewApp/run-16 336s === RUN TestParallelNewApp/run-17 336s === PAUSE TestParallelNewApp/run-17 336s === RUN TestParallelNewApp/run-18 336s === PAUSE TestParallelNewApp/run-18 336s === RUN TestParallelNewApp/run-19 336s === PAUSE TestParallelNewApp/run-19 336s === RUN TestParallelNewApp/run-20 336s === PAUSE TestParallelNewApp/run-20 336s === RUN TestParallelNewApp/run-21 336s === PAUSE TestParallelNewApp/run-21 336s === RUN TestParallelNewApp/run-22 336s === PAUSE TestParallelNewApp/run-22 336s === RUN TestParallelNewApp/run-23 336s === PAUSE TestParallelNewApp/run-23 336s === RUN TestParallelNewApp/run-24 336s === PAUSE TestParallelNewApp/run-24 336s === RUN TestParallelNewApp/run-25 336s === PAUSE TestParallelNewApp/run-25 336s === RUN TestParallelNewApp/run-26 336s === PAUSE TestParallelNewApp/run-26 336s === RUN TestParallelNewApp/run-27 336s === PAUSE TestParallelNewApp/run-27 336s === RUN TestParallelNewApp/run-28 336s === PAUSE TestParallelNewApp/run-28 336s === RUN TestParallelNewApp/run-29 336s === PAUSE TestParallelNewApp/run-29 336s === RUN TestParallelNewApp/run-30 336s === PAUSE TestParallelNewApp/run-30 336s === RUN TestParallelNewApp/run-31 336s === PAUSE TestParallelNewApp/run-31 336s === RUN TestParallelNewApp/run-32 336s === PAUSE TestParallelNewApp/run-32 336s === RUN TestParallelNewApp/run-33 336s === PAUSE TestParallelNewApp/run-33 336s === RUN TestParallelNewApp/run-34 336s === PAUSE TestParallelNewApp/run-34 336s === RUN TestParallelNewApp/run-35 336s === PAUSE TestParallelNewApp/run-35 336s === RUN TestParallelNewApp/run-36 336s === PAUSE TestParallelNewApp/run-36 336s === RUN TestParallelNewApp/run-37 336s === PAUSE TestParallelNewApp/run-37 336s === RUN TestParallelNewApp/run-38 336s === PAUSE TestParallelNewApp/run-38 336s === RUN TestParallelNewApp/run-39 336s === PAUSE TestParallelNewApp/run-39 336s === RUN TestParallelNewApp/run-40 336s === PAUSE TestParallelNewApp/run-40 336s === RUN TestParallelNewApp/run-41 336s === PAUSE TestParallelNewApp/run-41 336s === RUN TestParallelNewApp/run-42 336s === PAUSE TestParallelNewApp/run-42 336s === RUN TestParallelNewApp/run-43 336s === PAUSE TestParallelNewApp/run-43 336s === RUN TestParallelNewApp/run-44 336s === PAUSE TestParallelNewApp/run-44 336s === RUN TestParallelNewApp/run-45 336s === PAUSE TestParallelNewApp/run-45 336s === RUN TestParallelNewApp/run-46 336s === PAUSE TestParallelNewApp/run-46 336s === RUN TestParallelNewApp/run-47 336s === PAUSE TestParallelNewApp/run-47 336s === RUN TestParallelNewApp/run-48 336s === PAUSE TestParallelNewApp/run-48 336s === RUN TestParallelNewApp/run-49 336s === PAUSE TestParallelNewApp/run-49 336s === RUN TestParallelNewApp/run-50 336s === PAUSE TestParallelNewApp/run-50 336s === RUN TestParallelNewApp/run-51 336s === PAUSE TestParallelNewApp/run-51 336s === RUN TestParallelNewApp/run-52 336s === PAUSE TestParallelNewApp/run-52 336s === RUN TestParallelNewApp/run-53 336s === PAUSE TestParallelNewApp/run-53 336s === RUN TestParallelNewApp/run-54 336s === PAUSE TestParallelNewApp/run-54 336s === RUN TestParallelNewApp/run-55 336s === PAUSE TestParallelNewApp/run-55 336s === RUN TestParallelNewApp/run-56 336s === PAUSE TestParallelNewApp/run-56 336s === RUN TestParallelNewApp/run-57 336s === PAUSE TestParallelNewApp/run-57 336s === RUN TestParallelNewApp/run-58 336s === PAUSE TestParallelNewApp/run-58 336s === RUN TestParallelNewApp/run-59 336s === PAUSE TestParallelNewApp/run-59 336s === RUN TestParallelNewApp/run-60 336s === PAUSE TestParallelNewApp/run-60 336s === RUN TestParallelNewApp/run-61 336s === PAUSE TestParallelNewApp/run-61 336s === RUN TestParallelNewApp/run-62 336s === PAUSE TestParallelNewApp/run-62 336s === RUN TestParallelNewApp/run-63 336s === PAUSE TestParallelNewApp/run-63 336s === RUN TestParallelNewApp/run-64 336s === PAUSE TestParallelNewApp/run-64 336s === RUN TestParallelNewApp/run-65 336s === PAUSE TestParallelNewApp/run-65 336s === RUN TestParallelNewApp/run-66 336s === PAUSE TestParallelNewApp/run-66 336s === RUN TestParallelNewApp/run-67 336s === PAUSE TestParallelNewApp/run-67 336s === RUN TestParallelNewApp/run-68 336s === PAUSE TestParallelNewApp/run-68 336s === RUN TestParallelNewApp/run-69 336s === PAUSE TestParallelNewApp/run-69 336s === RUN TestParallelNewApp/run-70 336s === PAUSE TestParallelNewApp/run-70 336s === RUN TestParallelNewApp/run-71 336s === PAUSE TestParallelNewApp/run-71 336s === RUN TestParallelNewApp/run-72 336s === PAUSE TestParallelNewApp/run-72 336s === RUN TestParallelNewApp/run-73 336s === PAUSE TestParallelNewApp/run-73 336s === RUN TestParallelNewApp/run-74 336s === PAUSE TestParallelNewApp/run-74 336s === RUN TestParallelNewApp/run-75 336s === PAUSE TestParallelNewApp/run-75 336s === RUN TestParallelNewApp/run-76 336s === PAUSE TestParallelNewApp/run-76 336s === RUN TestParallelNewApp/run-77 336s === PAUSE TestParallelNewApp/run-77 336s === RUN TestParallelNewApp/run-78 336s === PAUSE TestParallelNewApp/run-78 336s === RUN TestParallelNewApp/run-79 336s === PAUSE TestParallelNewApp/run-79 336s === RUN TestParallelNewApp/run-80 336s === PAUSE TestParallelNewApp/run-80 336s === RUN TestParallelNewApp/run-81 336s === PAUSE TestParallelNewApp/run-81 336s === RUN TestParallelNewApp/run-82 336s === PAUSE TestParallelNewApp/run-82 336s === RUN TestParallelNewApp/run-83 336s === PAUSE TestParallelNewApp/run-83 336s === RUN TestParallelNewApp/run-84 336s === PAUSE TestParallelNewApp/run-84 336s === RUN TestParallelNewApp/run-85 336s === PAUSE TestParallelNewApp/run-85 336s === RUN TestParallelNewApp/run-86 336s === PAUSE TestParallelNewApp/run-86 336s === RUN TestParallelNewApp/run-87 336s === PAUSE TestParallelNewApp/run-87 336s === RUN TestParallelNewApp/run-88 336s === PAUSE TestParallelNewApp/run-88 336s === RUN TestParallelNewApp/run-89 336s === PAUSE TestParallelNewApp/run-89 336s === RUN TestParallelNewApp/run-90 336s === PAUSE TestParallelNewApp/run-90 336s === RUN TestParallelNewApp/run-91 336s === PAUSE TestParallelNewApp/run-91 336s === RUN TestParallelNewApp/run-92 336s === PAUSE TestParallelNewApp/run-92 336s === RUN TestParallelNewApp/run-93 336s === PAUSE TestParallelNewApp/run-93 336s === RUN TestParallelNewApp/run-94 336s === PAUSE TestParallelNewApp/run-94 336s === RUN TestParallelNewApp/run-95 336s === PAUSE TestParallelNewApp/run-95 336s === RUN TestParallelNewApp/run-96 336s === PAUSE TestParallelNewApp/run-96 336s === RUN TestParallelNewApp/run-97 336s === PAUSE TestParallelNewApp/run-97 336s === RUN TestParallelNewApp/run-98 336s === PAUSE TestParallelNewApp/run-98 336s === RUN TestParallelNewApp/run-99 336s === PAUSE TestParallelNewApp/run-99 336s === CONT TestParallelNewApp/run-0 336s === CONT TestParallelNewApp/run-50 336s === CONT TestParallelNewApp/run-49 336s === CONT TestParallelNewApp/run-48 336s === CONT TestParallelNewApp/run-47 336s === CONT TestParallelNewApp/run-46 336s === CONT TestParallelNewApp/run-45 336s === CONT TestParallelNewApp/run-44 336s === CONT TestParallelNewApp/run-43 336s === CONT TestParallelNewApp/run-42 336s === CONT TestParallelNewApp/run-41 336s === CONT TestParallelNewApp/run-40 336s === CONT TestParallelNewApp/run-39 336s === CONT TestParallelNewApp/run-38 336s === CONT TestParallelNewApp/run-37 336s === CONT TestParallelNewApp/run-36 336s === CONT TestParallelNewApp/run-35 336s === CONT TestParallelNewApp/run-34 336s === CONT TestParallelNewApp/run-33 336s === CONT TestParallelNewApp/run-32 336s === CONT TestParallelNewApp/run-31 336s === CONT TestParallelNewApp/run-30 336s === CONT TestParallelNewApp/run-29 336s === CONT TestParallelNewApp/run-28 336s === CONT TestParallelNewApp/run-27 336s === CONT TestParallelNewApp/run-26 336s === CONT TestParallelNewApp/run-25 336s === CONT TestParallelNewApp/run-24 336s === CONT TestParallelNewApp/run-23 336s === CONT TestParallelNewApp/run-22 336s === CONT TestParallelNewApp/run-21 336s === CONT TestParallelNewApp/run-20 336s === CONT TestParallelNewApp/run-19 336s === CONT TestParallelNewApp/run-18 336s === CONT TestParallelNewApp/run-17 336s === CONT TestParallelNewApp/run-16 336s === CONT TestParallelNewApp/run-15 336s === CONT TestParallelNewApp/run-14 336s === CONT TestParallelNewApp/run-13 336s === CONT TestParallelNewApp/run-12 336s === CONT TestParallelNewApp/run-11 336s === CONT TestParallelNewApp/run-10 336s === CONT TestParallelNewApp/run-9 336s === CONT TestParallelNewApp/run-8 336s === CONT TestParallelNewApp/run-7 336s === CONT TestParallelNewApp/run-6 336s === CONT TestParallelNewApp/run-5 336s === CONT TestParallelNewApp/run-4 336s === CONT TestParallelNewApp/run-3 336s === CONT TestParallelNewApp/run-2 336s === CONT TestParallelNewApp/run-1 336s === CONT TestParallelNewApp/run-75 336s === CONT TestParallelNewApp/run-99 336s === CONT TestParallelNewApp/run-98 336s === CONT TestParallelNewApp/run-97 336s === CONT TestParallelNewApp/run-96 336s === CONT TestParallelNewApp/run-95 336s === CONT TestParallelNewApp/run-94 336s === CONT TestParallelNewApp/run-93 336s === CONT TestParallelNewApp/run-92 336s === CONT TestParallelNewApp/run-91 336s === CONT TestParallelNewApp/run-90 336s === CONT TestParallelNewApp/run-89 336s === CONT TestParallelNewApp/run-88 336s === CONT TestParallelNewApp/run-87 336s === CONT TestParallelNewApp/run-86 336s === CONT TestParallelNewApp/run-85 336s === CONT TestParallelNewApp/run-84 336s === CONT TestParallelNewApp/run-83 336s === CONT TestParallelNewApp/run-82 336s === CONT TestParallelNewApp/run-81 336s === CONT TestParallelNewApp/run-80 336s === CONT TestParallelNewApp/run-79 336s === CONT TestParallelNewApp/run-78 336s === CONT TestParallelNewApp/run-77 336s === CONT TestParallelNewApp/run-76 336s === CONT TestParallelNewApp/run-63 336s === CONT TestParallelNewApp/run-74 336s === CONT TestParallelNewApp/run-73 336s === CONT TestParallelNewApp/run-72 336s === CONT TestParallelNewApp/run-71 336s === CONT TestParallelNewApp/run-70 336s === CONT TestParallelNewApp/run-69 336s === CONT TestParallelNewApp/run-68 336s === CONT TestParallelNewApp/run-67 336s === CONT TestParallelNewApp/run-66 336s === CONT TestParallelNewApp/run-65 336s === CONT TestParallelNewApp/run-64 336s === CONT TestParallelNewApp/run-57 336s === CONT TestParallelNewApp/run-62 336s === CONT TestParallelNewApp/run-61 336s === CONT TestParallelNewApp/run-60 336s === CONT TestParallelNewApp/run-59 336s === CONT TestParallelNewApp/run-58 336s === CONT TestParallelNewApp/run-54 336s === CONT TestParallelNewApp/run-56 336s === CONT TestParallelNewApp/run-55 336s === CONT TestParallelNewApp/run-52 336s === CONT TestParallelNewApp/run-53 336s === CONT TestParallelNewApp/run-51 336s --- PASS: TestParallelNewApp (0.00s) 336s --- PASS: TestParallelNewApp/run-0 (0.10s) 336s --- PASS: TestParallelNewApp/run-50 (0.10s) 336s --- PASS: TestParallelNewApp/run-48 (0.07s) 336s --- PASS: TestParallelNewApp/run-47 (0.10s) 336s --- PASS: TestParallelNewApp/run-46 (0.08s) 336s --- PASS: TestParallelNewApp/run-49 (0.27s) 336s --- PASS: TestParallelNewApp/run-44 (0.08s) 336s --- PASS: TestParallelNewApp/run-45 (0.10s) 336s --- PASS: TestParallelNewApp/run-42 (0.09s) 336s --- PASS: TestParallelNewApp/run-41 (0.09s) 336s --- PASS: TestParallelNewApp/run-40 (0.10s) 336s --- PASS: TestParallelNewApp/run-43 (0.29s) 336s --- PASS: TestParallelNewApp/run-38 (0.09s) 336s --- PASS: TestParallelNewApp/run-37 (0.08s) 336s --- PASS: TestParallelNewApp/run-36 (0.08s) 336s --- PASS: TestParallelNewApp/run-39 (0.26s) 336s --- PASS: TestParallelNewApp/run-35 (0.10s) 336s --- PASS: TestParallelNewApp/run-33 (0.09s) 336s --- PASS: TestParallelNewApp/run-32 (0.09s) 336s --- PASS: TestParallelNewApp/run-34 (0.28s) 336s --- PASS: TestParallelNewApp/run-30 (0.09s) 336s --- PASS: TestParallelNewApp/run-31 (0.09s) 336s --- PASS: TestParallelNewApp/run-28 (0.06s) 336s --- PASS: TestParallelNewApp/run-29 (0.11s) 336s --- PASS: TestParallelNewApp/run-27 (0.10s) 336s --- PASS: TestParallelNewApp/run-26 (0.11s) 336s --- PASS: TestParallelNewApp/run-25 (0.07s) 336s --- PASS: TestParallelNewApp/run-23 (0.09s) 336s --- PASS: TestParallelNewApp/run-22 (0.10s) 336s --- PASS: TestParallelNewApp/run-24 (0.28s) 336s --- PASS: TestParallelNewApp/run-21 (0.09s) 336s --- PASS: TestParallelNewApp/run-20 (0.08s) 336s --- PASS: TestParallelNewApp/run-19 (0.09s) 336s --- PASS: TestParallelNewApp/run-18 (0.07s) 336s --- PASS: TestParallelNewApp/run-17 (0.09s) 336s --- PASS: TestParallelNewApp/run-16 (0.09s) 336s --- PASS: TestParallelNewApp/run-15 (0.11s) 336s --- PASS: TestParallelNewApp/run-14 (0.07s) 336s --- PASS: TestParallelNewApp/run-13 (0.07s) 336s --- PASS: TestParallelNewApp/run-12 (0.08s) 336s --- PASS: TestParallelNewApp/run-11 (0.10s) 336s --- PASS: TestParallelNewApp/run-9 (0.07s) 336s --- PASS: TestParallelNewApp/run-8 (0.07s) 336s --- PASS: TestParallelNewApp/run-10 (0.26s) 336s --- PASS: TestParallelNewApp/run-7 (0.09s) 336s --- PASS: TestParallelNewApp/run-6 (0.10s) 336s --- PASS: TestParallelNewApp/run-5 (0.10s) 336s --- PASS: TestParallelNewApp/run-3 (0.07s) 336s --- PASS: TestParallelNewApp/run-2 (0.09s) 336s --- PASS: TestParallelNewApp/run-1 (0.08s) 336s --- PASS: TestParallelNewApp/run-4 (0.31s) 336s --- PASS: TestParallelNewApp/run-75 (0.07s) 336s --- PASS: TestParallelNewApp/run-99 (0.27s) 336s --- PASS: TestParallelNewApp/run-98 (0.28s) 336s --- PASS: TestParallelNewApp/run-97 (0.08s) 336s --- PASS: TestParallelNewApp/run-96 (0.05s) 336s --- PASS: TestParallelNewApp/run-94 (0.10s) 336s --- PASS: TestParallelNewApp/run-95 (0.29s) 336s --- PASS: TestParallelNewApp/run-93 (0.30s) 336s --- PASS: TestParallelNewApp/run-91 (0.08s) 336s --- PASS: TestParallelNewApp/run-92 (0.29s) 336s --- PASS: TestParallelNewApp/run-90 (0.08s) 336s --- PASS: TestParallelNewApp/run-88 (0.10s) 336s --- PASS: TestParallelNewApp/run-87 (0.09s) 336s --- PASS: TestParallelNewApp/run-86 (0.09s) 336s --- PASS: TestParallelNewApp/run-89 (0.31s) 336s --- PASS: TestParallelNewApp/run-84 (0.08s) 336s --- PASS: TestParallelNewApp/run-85 (0.10s) 336s --- PASS: TestParallelNewApp/run-82 (0.06s) 336s --- PASS: TestParallelNewApp/run-83 (0.10s) 336s --- PASS: TestParallelNewApp/run-81 (0.07s) 336s --- PASS: TestParallelNewApp/run-80 (0.10s) 336s --- PASS: TestParallelNewApp/run-79 (0.07s) 336s --- PASS: TestParallelNewApp/run-78 (0.09s) 336s --- PASS: TestParallelNewApp/run-76 (0.09s) 336s --- PASS: TestParallelNewApp/run-63 (0.09s) 336s --- PASS: TestParallelNewApp/run-77 (0.27s) 336s --- PASS: TestParallelNewApp/run-73 (0.09s) 336s --- PASS: TestParallelNewApp/run-74 (0.09s) 336s --- PASS: TestParallelNewApp/run-72 (0.08s) 336s --- PASS: TestParallelNewApp/run-71 (0.10s) 336s --- PASS: TestParallelNewApp/run-70 (0.07s) 336s --- PASS: TestParallelNewApp/run-69 (0.07s) 336s --- PASS: TestParallelNewApp/run-68 (0.05s) 336s --- PASS: TestParallelNewApp/run-67 (0.08s) 336s --- PASS: TestParallelNewApp/run-66 (0.10s) 336s --- PASS: TestParallelNewApp/run-65 (0.10s) 336s --- PASS: TestParallelNewApp/run-64 (0.10s) 336s --- PASS: TestParallelNewApp/run-57 (0.10s) 336s --- PASS: TestParallelNewApp/run-62 (0.09s) 336s --- PASS: TestParallelNewApp/run-61 (0.09s) 336s --- PASS: TestParallelNewApp/run-60 (0.09s) 336s --- PASS: TestParallelNewApp/run-59 (0.09s) 336s --- PASS: TestParallelNewApp/run-58 (0.08s) 336s --- PASS: TestParallelNewApp/run-54 (0.08s) 336s --- PASS: TestParallelNewApp/run-56 (0.09s) 336s --- PASS: TestParallelNewApp/run-55 (0.09s) 336s --- PASS: TestParallelNewApp/run-52 (0.09s) 336s --- PASS: TestParallelNewApp/run-53 (0.09s) 336s --- PASS: TestParallelNewApp/run-51 (0.28s) 336s === RUN Example 336s --- PASS: Example (0.16s) 336s PASS 336s ok github.com/canonical/go-dqlite/app 174.685s 345s === RUN TestNew_Default 345s Benchmark done. Results available here: 345s /tmp/dqlite-app-test-1576942789/results 345s --- PASS: TestNew_Default (1.14s) 345s === RUN TestNew_KvReadWrite 345s Benchmark done. Results available here: 345s /tmp/dqlite-app-test-1629711837/results 345s --- PASS: TestNew_KvReadWrite (1.16s) 345s === RUN TestNew_ClusteredKvReadWrite 345s Benchmark done. Results available here: 345s /tmp/dqlite-app-test-4034985066/results 345s --- PASS: TestNew_ClusteredKvReadWrite (4.11s) 345s === RUN TestNew_ClusteredTimeout 345s --- PASS: TestNew_ClusteredTimeout (2.17s) 345s PASS 345s ok github.com/canonical/go-dqlite/benchmark 8.687s 347s === RUN TestClient_Leader 347s --- PASS: TestClient_Leader (0.06s) 347s === RUN TestClient_Cluster 347s --- PASS: TestClient_Cluster (0.08s) 347s === RUN TestClient_Transfer 347s --- PASS: TestClient_Transfer (1.15s) 347s === RUN TestClient_Describe 347s --- PASS: TestClient_Describe (0.07s) 347s === RUN TestMembership 347s --- PASS: TestMembership (1.17s) 347s === RUN TestDefaultNodeStore 347s --- PASS: TestDefaultNodeStore (0.00s) 347s === RUN TestConfigMultiThread 347s --- PASS: TestConfigMultiThread (0.00s) 347s PASS 347s ok github.com/canonical/go-dqlite/client 2.540s 347s ? github.com/canonical/go-dqlite/cmd/dqlite [no test files] 347s ? github.com/canonical/go-dqlite/cmd/dqlite-benchmark [no test files] 347s ? github.com/canonical/go-dqlite/cmd/dqlite-demo [no test files] 379s === RUN TestDriver_Open 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestDriver_Open (0.08s) 379s === RUN TestDriver_Prepare 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestDriver_Prepare (0.09s) 379s === RUN TestConn_Exec 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestConn_Exec (0.08s) 379s === RUN TestConn_Query 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestConn_Query (0.09s) 379s === RUN TestConn_QueryRow 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestConn_QueryRow (0.08s) 379s === RUN TestConn_InterruptQuery 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestConn_InterruptQuery (0.14s) 379s === RUN TestConn_QueryBlob 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestConn_QueryBlob (0.09s) 379s === RUN TestStmt_Exec 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestStmt_Exec (0.09s) 379s === RUN TestStmt_ExecManyParams 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestStmt_ExecManyParams (0.09s) 379s === RUN TestStmt_Query 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestStmt_Query (0.06s) 379s === RUN TestStmt_QueryManyParams 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestStmt_QueryManyParams (0.09s) 379s === RUN TestConn_QueryParams 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestConn_QueryParams (0.08s) 379s === RUN TestConn_QueryManyParams 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestConn_QueryManyParams (0.08s) 379s === RUN TestConn_ExecManyParams 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestConn_ExecManyParams (0.08s) 379s === RUN Test_ColumnTypesEmpty 379s driver_test.go:472: this currently fails if the result set is empty, is dqlite skipping the header if empty set? 379s --- SKIP: Test_ColumnTypesEmpty (0.00s) 379s === RUN Test_ColumnTypesExists 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: Test_ColumnTypesExists (0.09s) 379s === RUN Test_ColumnTypesEnd 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: Test_ColumnTypesEnd (0.08s) 379s === RUN Test_ZeroColumns 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: Test_ZeroColumns (0.17s) 379s === RUN Test_DescribeLastEntry 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: Test_DescribeLastEntry (0.52s) 379s === RUN Test_Dump 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: Test_Dump (0.16s) 379s === RUN TestIntegration_DatabaseSQL 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: context canceled 379s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: context canceled 379s --- PASS: TestIntegration_DatabaseSQL (2.30s) 379s === RUN TestIntegration_ConstraintError 379s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: context canceled 379s --- PASS: TestIntegration_ConstraintError (2.20s) 379s === RUN TestIntegration_ExecBindError 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestIntegration_ExecBindError (0.17s) 379s === RUN TestIntegration_QueryBindError 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestIntegration_QueryBindError (0.09s) 379s === RUN TestIntegration_LargeQuery 379s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: context canceled 379s --- PASS: TestIntegration_LargeQuery (2.28s) 379s === RUN TestIntegration_Recover 379s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: context canceled 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 379s func.go:15: DEBUG: attempt 1: server @1: connected on fast path 379s --- PASS: TestIntegration_Recover (1.40s) 379s === RUN TestIntegration_PingOnlyWorksOnceLeaderElected 379s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: WARN: attempt 1: server @1: no known leader 379s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: reported leader server is not the leader 379s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 2: server @1: reported leader server is not the leader 379s func.go:15: WARN: attempt 2: server @1: no known leader 379s func.go:15: WARN: attempt 3: server @1: no known leader 379s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 3: server @1: reported leader server is not the leader 379s func.go:15: DEBUG: attempt 4: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 4: server @1: no known leader 379s func.go:15: WARN: attempt 4: server @1: reported leader server is not the leader 379s func.go:15: WARN: attempt 5: server @1: no known leader 379s func.go:15: WARN: attempt 5: server @2: no known leader 379s func.go:15: WARN: attempt 6: server @1: no known leader 379s func.go:15: WARN: attempt 6: server @2: no known leader 379s func.go:15: WARN: attempt 7: server @2: no known leader 379s func.go:15: WARN: attempt 7: server @1: no known leader 379s func.go:15: WARN: attempt 8: server @1: no known leader 379s func.go:15: WARN: attempt 8: server @2: no known leader 379s func.go:15: DEBUG: attempt 9: server @2: connected on fallback path 379s func.go:15: DEBUG: attempt 9: server @1: connect to reported leader @2 379s func.go:15: WARN: attempt 9: server @2: context canceled 379s --- PASS: TestIntegration_PingOnlyWorksOnceLeaderElected (9.13s) 379s === RUN TestIntegration_HighAvailability 379s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: context canceled 379s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 379s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: WARN: attempt 1: server @2: no known leader 379s func.go:15: WARN: attempt 1: server @3: no known leader 379s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: WARN: attempt 2: server @3: no known leader 379s func.go:15: WARN: attempt 2: server @2: no known leader 379s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: WARN: attempt 3: server @3: no known leader 379s func.go:15: WARN: attempt 3: server @2: no known leader 379s func.go:15: WARN: attempt 4: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: WARN: attempt 4: server @2: no known leader 379s func.go:15: WARN: attempt 4: server @3: no known leader 379s func.go:15: WARN: attempt 5: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: WARN: attempt 5: server @3: no known leader 379s func.go:15: WARN: attempt 5: server @2: no known leader 379s func.go:15: WARN: attempt 6: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: WARN: attempt 6: server @3: no known leader 379s func.go:15: WARN: attempt 6: server @2: no known leader 379s func.go:15: DEBUG: attempt 7: server @3: connected on fallback path 379s func.go:15: WARN: attempt 7: server @1: dial: dial unix @1: connect: connection refused 379s func.go:15: DEBUG: attempt 7: server @2: connect to reported leader @3 379s func.go:15: WARN: attempt 7: server @3: context canceled 379s --- PASS: TestIntegration_HighAvailability (8.98s) 379s === RUN TestIntegration_LeadershipTransfer 379s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: context canceled 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: context canceled 379s func.go:15: DEBUG: leadership lost (10250 - not leader) 379s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 379s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 379s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 379s --- PASS: TestIntegration_LeadershipTransfer (2.27s) 379s === RUN TestIntegration_LeadershipTransfer_Tx 379s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: context canceled 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 379s func.go:15: WARN: attempt 1: server @1: context canceled 379s func.go:15: DEBUG: leadership lost (10250 - not leader) 379s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 379s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 379s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 379s --- PASS: TestIntegration_LeadershipTransfer_Tx (2.25s) 379s === RUN TestOptions 379s --- PASS: TestOptions (0.00s) 379s === RUN TestIntegration_ColumnTypeName 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestIntegration_ColumnTypeName (0.17s) 379s === RUN TestIntegration_SqlNullTime 379s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 379s --- PASS: TestIntegration_SqlNullTime (0.16s) 379s PASS 379s ok github.com/canonical/go-dqlite/driver 33.636s 379s === RUN TestNode_Create 379s --- PASS: TestNode_Create (0.08s) 379s === RUN TestNode_Start 379s --- PASS: TestNode_Start (0.07s) 379s === RUN TestNode_Restart 379s --- PASS: TestNode_Restart (0.15s) 379s === RUN TestNode_Start_Inet 379s --- PASS: TestNode_Start_Inet (0.06s) 379s === RUN TestNode_Leader 379s --- PASS: TestNode_Leader (0.09s) 379s === RUN TestNode_Autorecovery 379s --- PASS: TestNode_Autorecovery (0.09s) 379s PASS 379s ok github.com/canonical/go-dqlite/internal/bindings 0.538s 379s === RUN TestMessage_StaticBytesAlignment 379s --- PASS: TestMessage_StaticBytesAlignment (0.00s) 379s === RUN TestMessage_putBlob 379s === RUN TestMessage_putBlob/16 379s === RUN TestMessage_putBlob/16#01 379s === RUN TestMessage_putBlob/24 379s --- PASS: TestMessage_putBlob (0.00s) 379s --- PASS: TestMessage_putBlob/16 (0.00s) 379s --- PASS: TestMessage_putBlob/16#01 (0.00s) 379s --- PASS: TestMessage_putBlob/24 (0.00s) 379s === RUN TestMessage_putString 379s === RUN TestMessage_putString/hello 379s === RUN TestMessage_putString/hello!! 379s === RUN TestMessage_putString/hello_world 379s --- PASS: TestMessage_putString (0.00s) 379s --- PASS: TestMessage_putString/hello (0.00s) 379s --- PASS: TestMessage_putString/hello!! (0.00s) 379s --- PASS: TestMessage_putString/hello_world (0.00s) 379s === RUN TestMessage_putUint8 379s --- PASS: TestMessage_putUint8 (0.00s) 379s === RUN TestMessage_putUint16 379s --- PASS: TestMessage_putUint16 (0.00s) 379s === RUN TestMessage_putUint32 379s --- PASS: TestMessage_putUint32 (0.00s) 379s === RUN TestMessage_putUint64 379s --- PASS: TestMessage_putUint64 (0.00s) 379s === RUN TestMessage_putNamedValues 379s --- PASS: TestMessage_putNamedValues (0.00s) 379s === RUN TestMessage_putNamedValues32 379s --- PASS: TestMessage_putNamedValues32 (0.00s) 379s === RUN TestMessage_putHeader 379s --- PASS: TestMessage_putHeader (0.00s) 379s === RUN TestMessage_getString 379s === RUN TestMessage_getString/hello 379s === RUN TestMessage_getString/hello!! 379s === RUN TestMessage_getString/hello!!! 379s === RUN TestMessage_getString/hello_world 379s --- PASS: TestMessage_getString (0.00s) 379s --- PASS: TestMessage_getString/hello (0.00s) 379s --- PASS: TestMessage_getString/hello!! (0.00s) 379s --- PASS: TestMessage_getString/hello!!! (0.00s) 379s --- PASS: TestMessage_getString/hello_world (0.00s) 379s === RUN TestMessage_getBlob 379s === RUN TestMessage_getBlob/16 379s === RUN TestMessage_getBlob/16#01 379s === RUN TestMessage_getBlob/24 379s --- PASS: TestMessage_getBlob (0.00s) 379s --- PASS: TestMessage_getBlob/16 (0.00s) 379s --- PASS: TestMessage_getBlob/16#01 (0.00s) 379s --- PASS: TestMessage_getBlob/24 (0.00s) 379s === RUN TestMessage_getString_Overflow_WordBoundary 379s --- PASS: TestMessage_getString_Overflow_WordBoundary (0.00s) 379s === RUN TestConnector_Success 379s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 379s --- PASS: TestConnector_Success (0.07s) 379s === RUN TestConnector_LeaderTracker 379s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 379s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 379s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 379s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 379s connector_test.go:398: DEBUG: reusing shared connection to @test-0 379s connector_test.go:398: DEBUG: discarding shared connection to @test-0 379s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 379s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 379s connector_test.go:398: DEBUG: reusing shared connection to @test-0 379s connector_test.go:398: DEBUG: discarding shared connection to @test-0 379s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 379s --- PASS: TestConnector_LeaderTracker (0.90s) 379s === RUN TestConnector_LimitRetries 379s connector_test.go:398: WARN: attempt 1: server @test-123: dial: dial unix @test-123: connect: connection refused 379s connector_test.go:398: WARN: attempt 2: server @test-123: dial: dial unix @test-123: connect: connection refused 379s connector_test.go:398: WARN: attempt 3: server @test-123: dial: dial unix @test-123: connect: connection refused 379s --- PASS: TestConnector_LimitRetries (0.60s) 379s === RUN TestConnector_DialTimeout 379s connector_test.go:158: Skipping network test 379s --- SKIP: TestConnector_DialTimeout (0.00s) 379s === RUN TestConnector_EmptyNodeStore 379s --- PASS: TestConnector_EmptyNodeStore (0.20s) 379s === RUN TestConnector_ContextCanceled 379s connector_test.go:193: Skipping network test 379s --- SKIP: TestConnector_ContextCanceled (0.00s) 379s === RUN TestConnector_AttemptTimeout 379s func.go:15: WARN: attempt 1: server @1234: call leader (budget 99.940772ms): receive: header: read unix @->@1234: i/o timeout 379s func.go:15: WARN: attempt 2: server @1234: call leader (budget 99.847191ms): receive: header: read unix @->@1234: i/o timeout 379s --- PASS: TestConnector_AttemptTimeout (0.40s) 379s === RUN TestProtocol_RequestWithDynamicBuffer 379s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 379s --- PASS: TestProtocol_RequestWithDynamicBuffer (0.16s) 379s === RUN TestProtocol_Prepare 379s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 379s --- PASS: TestProtocol_Prepare (0.07s) 379s PASS 379s ok github.com/canonical/go-dqlite/internal/protocol 2.415s 379s ? github.com/canonical/go-dqlite/internal/shell [no test files] 379s === RUN Test_TestFunc 379s func.go:15: INFO: hello 379s --- PASS: Test_TestFunc (0.00s) 379s === RUN TestLevel_String 379s --- PASS: TestLevel_String (0.00s) 379s PASS 379s ok github.com/canonical/go-dqlite/logging 0.002s 379s ? github.com/canonical/go-dqlite/tracing [no test files] 379s autopkgtest [03:14:16]: test dh-golang-autopkgtest: -----------------------] 380s dh-golang-autopkgtest PASS 380s autopkgtest [03:14:17]: test dh-golang-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 380s autopkgtest [03:14:17]: @@@@@@@@@@@@@@@@@@@@ summary 380s dh-golang-autopkgtest PASS