0s autopkgtest [05:59:35]: starting date and time: 2025-10-30 05:59:35+0000 0s autopkgtest [05:59:35]: git checkout: 4b346b80 nova: make wait_reboot return success even when a no-op 0s autopkgtest [05:59:35]: host juju-7f2275-prod-proposed-migration-environment-15; command line: /home/ubuntu/autopkgtest/runner/autopkgtest --output-dir /tmp/autopkgtest-work._cx_bbyu/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-ppc64el --security-groups autopkgtest-juju-7f2275-prod-proposed-migration-environment-15@bos03-ppc64el-8.secgroup --name adt-resolute-ppc64el-golang-github-canonical-go-dqlite-20251030-055934-juju-7f2275-prod-proposed-migration-environment-15-022101fb-4013-4544-9958-298f59a49c62 --image adt/ubuntu-resolute-ppc64el-server --keyname testbed-juju-7f2275-prod-proposed-migration-environment-15 --net-id=net_prod-proposed-migration-ppc64el -e TERM=linux --mirror=http://ftpmaster.internal/ubuntu/ 3s Creating nova instance adt-resolute-ppc64el-golang-github-canonical-go-dqlite-20251030-055934-juju-7f2275-prod-proposed-migration-environment-15-022101fb-4013-4544-9958-298f59a49c62 from image adt/ubuntu-resolute-ppc64el-server-20251029.img (UUID 27271d73-b679-46bf-826f-8c22ef3a1484)... 59s autopkgtest [06:00:34]: testbed dpkg architecture: ppc64el 60s autopkgtest [06:00:35]: testbed apt version: 3.1.11 60s autopkgtest [06:00:35]: @@@@@@@@@@@@@@@@@@@@ test bed setup 60s autopkgtest [06:00:35]: testbed release detected to be: None 61s autopkgtest [06:00:36]: updating testbed package index (apt update) 61s Get:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease [87.8 kB] 61s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 61s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 61s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 62s Get:5 http://ftpmaster.internal/ubuntu resolute-proposed/main Sources [206 kB] 62s Get:6 http://ftpmaster.internal/ubuntu resolute-proposed/universe Sources [3669 kB] 62s Get:7 http://ftpmaster.internal/ubuntu resolute-proposed/restricted Sources [5856 B] 62s Get:8 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse Sources [70.5 kB] 62s Get:9 http://ftpmaster.internal/ubuntu resolute-proposed/main ppc64el Packages [269 kB] 62s Get:10 http://ftpmaster.internal/ubuntu resolute-proposed/restricted ppc64el Packages [940 B] 62s Get:11 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el Packages [2494 kB] 63s Get:12 http://ftpmaster.internal/ubuntu resolute-proposed/multiverse ppc64el Packages [38.5 kB] 63s Fetched 6843 kB in 2s (2991 kB/s) 64s Reading package lists... 65s Failed to check for VM: Permission denied 65s Hit:1 http://ftpmaster.internal/ubuntu resolute-proposed InRelease 65s Hit:2 http://ftpmaster.internal/ubuntu resolute InRelease 65s Hit:3 http://ftpmaster.internal/ubuntu resolute-updates InRelease 65s Hit:4 http://ftpmaster.internal/ubuntu resolute-security InRelease 66s Reading package lists... 66s Reading package lists... 66s Building dependency tree... 66s Reading state information... 66s Calculating upgrade... 66s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 66s autopkgtest [06:00:41]: upgrading testbed (apt dist-upgrade and autopurge) 67s Reading package lists... 67s Building dependency tree... 67s Reading state information... 67s Calculating upgrade... 67s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 67s Reading package lists... 67s Building dependency tree... 67s Reading state information... 67s Solving dependencies... 67s 0 upgraded, 0 newly installed, 0 to remove and 0 not upgraded. 70s autopkgtest [06:00:45]: testbed running kernel: Linux 6.17.0-5-generic #5-Ubuntu SMP PREEMPT_DYNAMIC Mon Sep 22 10:02:41 UTC 2025 71s autopkgtest [06:00:46]: @@@@@@@@@@@@@@@@@@@@ apt-source golang-github-canonical-go-dqlite 73s Get:1 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.0-1 (dsc) [2755 B] 73s Get:2 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.0-1 (tar) [96.2 kB] 73s Get:3 http://ftpmaster.internal/ubuntu resolute/universe golang-github-canonical-go-dqlite 2.0.0-1 (diff) [4496 B] 73s gpgv: Signature made Thu Oct 31 23:23:45 2024 UTC 73s gpgv: using RSA key D41A7AD07DF6C5FCA7489F1C29EEE2D6ECF442F9 73s gpgv: issuer "gibmat@debian.org" 73s gpgv: Can't check signature: No public key 73s dpkg-source: warning: cannot verify inline signature for ./golang-github-canonical-go-dqlite_2.0.0-1.dsc: no acceptable signature found 73s autopkgtest [06:00:48]: testing package golang-github-canonical-go-dqlite version 2.0.0-1 74s autopkgtest [06:00:49]: build not needed 76s autopkgtest [06:00:51]: test dh-golang-autopkgtest: preparing testbed 76s Reading package lists... 76s Building dependency tree... 76s Reading state information... 76s Solving dependencies... 76s The following NEW packages will be installed: 76s autoconf automake autopoint autotools-dev build-essential cpp cpp-15 76s cpp-15-powerpc64le-linux-gnu cpp-powerpc64le-linux-gnu debhelper debugedit 76s dh-autoreconf dh-golang dh-strip-nondeterminism dwz g++ g++-15 76s g++-15-powerpc64le-linux-gnu g++-powerpc64le-linux-gnu gcc gcc-15 76s gcc-15-powerpc64le-linux-gnu gcc-powerpc64le-linux-gnu gettext go-dqlite 76s golang-1.25-go golang-1.25-src golang-any golang-github-burntsushi-toml-dev 76s golang-github-canonical-go-dqlite-dev 76s golang-github-cpuguy83-go-md2man-v2-dev golang-github-davecgh-go-spew-dev 76s golang-github-fsnotify-fsnotify-dev golang-github-ghodss-yaml-dev 76s golang-github-google-renameio-dev golang-github-hashicorp-hcl-dev 76s golang-github-kr-fs-dev golang-github-magiconair-properties-dev 76s golang-github-mattn-go-runewidth-dev golang-github-mattn-go-sqlite3-dev 76s golang-github-mitchellh-mapstructure-dev golang-github-pelletier-go-toml-dev 76s golang-github-pelletier-go-toml.v2-dev golang-github-peterh-liner-dev 76s golang-github-pkg-errors-dev golang-github-pkg-sftp-dev 76s golang-github-pmezard-go-difflib-dev golang-github-rican7-retry-dev 76s golang-github-rivo-uniseg-dev golang-github-russross-blackfriday-v2-dev 76s golang-github-shurcool-sanitized-anchor-name-dev 76s golang-github-spf13-afero-dev golang-github-spf13-cast-dev 76s golang-github-spf13-cobra-dev golang-github-spf13-jwalterweatherman-dev 76s golang-github-spf13-pflag-dev golang-github-spf13-viper-dev 76s golang-github-stretchr-objx-dev golang-github-stretchr-testify-dev 76s golang-github-subosito-gotenv-dev golang-go golang-golang-x-crypto-dev 76s golang-golang-x-sync-dev golang-golang-x-sys-dev golang-golang-x-term-dev 76s golang-golang-x-text-dev golang-gopkg-ini.v1-dev golang-gopkg-yaml.v2-dev 76s golang-gopkg-yaml.v3-dev golang-src intltool-debian libarchive-zip-perl 76s libasan8 libcc1-0 libdebhelper-perl libdqlite-dev libdqlite0 76s libfile-stripnondeterminism-perl libgcc-15-dev libgomp1 libisl23 libitm1 76s liblsan0 liblz4-dev libmpc3 libquadmath0 libsqlite3-dev libstdc++-15-dev 76s libtool libtsan2 libubsan1 libuv1-dev libxxhash-dev m4 po-debconf 76s tzdata-legacy 76s 0 upgraded, 96 newly installed, 0 to remove and 0 not upgraded. 76s Need to get 121 MB of archives. 76s After this operation, 547 MB of additional disk space will be used. 76s Get:1 http://ftpmaster.internal/ubuntu resolute/main ppc64el m4 ppc64el 1.4.19-8 [280 kB] 77s Get:2 http://ftpmaster.internal/ubuntu resolute/main ppc64el autoconf all 2.72-3.1ubuntu1 [384 kB] 77s Get:3 http://ftpmaster.internal/ubuntu resolute/main ppc64el autotools-dev all 20240727.1 [43.4 kB] 77s Get:4 http://ftpmaster.internal/ubuntu resolute/main ppc64el automake all 1:1.17-4ubuntu1 [572 kB] 77s Get:5 http://ftpmaster.internal/ubuntu resolute/main ppc64el autopoint all 0.23.1-2build2 [619 kB] 77s Get:6 http://ftpmaster.internal/ubuntu resolute/main ppc64el libisl23 ppc64el 0.27-1 [882 kB] 77s Get:7 http://ftpmaster.internal/ubuntu resolute/main ppc64el libmpc3 ppc64el 1.3.1-1build3 [62.6 kB] 77s Get:8 http://ftpmaster.internal/ubuntu resolute/main ppc64el cpp-15-powerpc64le-linux-gnu ppc64el 15.2.0-5ubuntu1 [11.4 MB] 78s Get:9 http://ftpmaster.internal/ubuntu resolute/main ppc64el cpp-15 ppc64el 15.2.0-5ubuntu1 [1036 B] 78s Get:10 http://ftpmaster.internal/ubuntu resolute/main ppc64el cpp-powerpc64le-linux-gnu ppc64el 4:15.2.0-4ubuntu1 [5746 B] 78s Get:11 http://ftpmaster.internal/ubuntu resolute/main ppc64el cpp ppc64el 4:15.2.0-4ubuntu1 [22.4 kB] 78s Get:12 http://ftpmaster.internal/ubuntu resolute/main ppc64el libcc1-0 ppc64el 15.2.0-5ubuntu1 [49.0 kB] 78s Get:13 http://ftpmaster.internal/ubuntu resolute/main ppc64el libgomp1 ppc64el 15.2.0-5ubuntu1 [169 kB] 78s Get:14 http://ftpmaster.internal/ubuntu resolute/main ppc64el libitm1 ppc64el 15.2.0-5ubuntu1 [32.3 kB] 78s Get:15 http://ftpmaster.internal/ubuntu resolute/main ppc64el libasan8 ppc64el 15.2.0-5ubuntu1 [3006 kB] 79s Get:16 http://ftpmaster.internal/ubuntu resolute/main ppc64el liblsan0 ppc64el 15.2.0-5ubuntu1 [1374 kB] 79s Get:17 http://ftpmaster.internal/ubuntu resolute/main ppc64el libtsan2 ppc64el 15.2.0-5ubuntu1 [2729 kB] 79s Get:18 http://ftpmaster.internal/ubuntu resolute/main ppc64el libubsan1 ppc64el 15.2.0-5ubuntu1 [1231 kB] 79s Get:19 http://ftpmaster.internal/ubuntu resolute/main ppc64el libquadmath0 ppc64el 15.2.0-5ubuntu1 [160 kB] 80s Get:20 http://ftpmaster.internal/ubuntu resolute/main ppc64el libgcc-15-dev ppc64el 15.2.0-5ubuntu1 [1670 kB] 80s Get:21 http://ftpmaster.internal/ubuntu resolute/main ppc64el gcc-15-powerpc64le-linux-gnu ppc64el 15.2.0-5ubuntu1 [22.4 MB] 85s Get:22 http://ftpmaster.internal/ubuntu resolute/main ppc64el gcc-15 ppc64el 15.2.0-5ubuntu1 [523 kB] 85s Get:23 http://ftpmaster.internal/ubuntu resolute/main ppc64el gcc-powerpc64le-linux-gnu ppc64el 4:15.2.0-4ubuntu1 [1220 B] 85s Get:24 http://ftpmaster.internal/ubuntu resolute/main ppc64el gcc ppc64el 4:15.2.0-4ubuntu1 [5032 B] 85s Get:25 http://ftpmaster.internal/ubuntu resolute/main ppc64el libstdc++-15-dev ppc64el 15.2.0-5ubuntu1 [2744 kB] 87s Get:26 http://ftpmaster.internal/ubuntu resolute/main ppc64el g++-15-powerpc64le-linux-gnu ppc64el 15.2.0-5ubuntu1 [13.0 MB] 96s Get:27 http://ftpmaster.internal/ubuntu resolute/main ppc64el g++-15 ppc64el 15.2.0-5ubuntu1 [23.7 kB] 96s Get:28 http://ftpmaster.internal/ubuntu resolute/main ppc64el g++-powerpc64le-linux-gnu ppc64el 4:15.2.0-4ubuntu1 [970 B] 96s Get:29 http://ftpmaster.internal/ubuntu resolute/main ppc64el g++ ppc64el 4:15.2.0-4ubuntu1 [1092 B] 96s Get:30 http://ftpmaster.internal/ubuntu resolute/main ppc64el build-essential ppc64el 12.12ubuntu1 [5094 B] 96s Get:31 http://ftpmaster.internal/ubuntu resolute/main ppc64el libdebhelper-perl all 13.24.2ubuntu1 [95.7 kB] 96s Get:32 http://ftpmaster.internal/ubuntu resolute/main ppc64el libtool all 2.5.4-4build1 [169 kB] 97s Get:33 http://ftpmaster.internal/ubuntu resolute/main ppc64el dh-autoreconf all 21 [12.5 kB] 97s Get:34 http://ftpmaster.internal/ubuntu resolute/main ppc64el libarchive-zip-perl all 1.68-1 [90.2 kB] 97s Get:35 http://ftpmaster.internal/ubuntu resolute/main ppc64el libfile-stripnondeterminism-perl all 1.14.2-1 [20.4 kB] 97s Get:36 http://ftpmaster.internal/ubuntu resolute/main ppc64el dh-strip-nondeterminism all 1.14.2-1 [5098 B] 97s Get:37 http://ftpmaster.internal/ubuntu resolute/main ppc64el debugedit ppc64el 1:5.2-3 [57.3 kB] 97s Get:38 http://ftpmaster.internal/ubuntu resolute/main ppc64el dwz ppc64el 0.16-2 [142 kB] 97s Get:39 http://ftpmaster.internal/ubuntu resolute/main ppc64el gettext ppc64el 0.23.1-2build2 [1177 kB] 98s Get:40 http://ftpmaster.internal/ubuntu resolute/main ppc64el intltool-debian all 0.35.0+20060710.6 [23.2 kB] 98s Get:41 http://ftpmaster.internal/ubuntu resolute/main ppc64el po-debconf all 1.0.21+nmu1 [233 kB] 98s Get:42 http://ftpmaster.internal/ubuntu resolute/main ppc64el debhelper all 13.24.2ubuntu1 [896 kB] 99s Get:43 http://ftpmaster.internal/ubuntu resolute/universe ppc64el libdqlite0 ppc64el 1.18.1-1 [187 kB] 99s Get:44 http://ftpmaster.internal/ubuntu resolute/universe ppc64el go-dqlite ppc64el 2.0.0-1 [4153 kB] 102s Get:45 http://ftpmaster.internal/ubuntu resolute/main ppc64el golang-1.25-src all 1.25.0-0ubuntu1 [23.0 MB] 123s Get:46 http://ftpmaster.internal/ubuntu resolute/main ppc64el golang-1.25-go ppc64el 1.25.0-0ubuntu1 [16.1 MB] 142s Get:47 http://ftpmaster.internal/ubuntu resolute/main ppc64el golang-src all 2:1.25~1 [5206 B] 142s Get:48 http://ftpmaster.internal/ubuntu resolute/main ppc64el golang-go ppc64el 2:1.25~1 [44.1 kB] 142s Get:49 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-any ppc64el 2:1.25~1 [2840 B] 142s Get:50 http://ftpmaster.internal/ubuntu resolute/main ppc64el libxxhash-dev ppc64el 0.8.3-2 [91.7 kB] 143s Get:51 http://ftpmaster.internal/ubuntu resolute/main ppc64el liblz4-dev ppc64el 1.10.0-4build1 [118 kB] 143s Get:52 http://ftpmaster.internal/ubuntu resolute/main ppc64el libuv1-dev ppc64el 1.50.0-2ubuntu1 [157 kB] 143s Get:53 http://ftpmaster.internal/ubuntu resolute/main ppc64el libsqlite3-dev ppc64el 3.46.1-8 [1053 kB] 144s Get:54 http://ftpmaster.internal/ubuntu resolute/universe ppc64el libdqlite-dev ppc64el 1.18.1-1 [302 kB] 144s Get:55 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-gopkg-yaml.v2-dev all 2.4.0-5 [62.0 kB] 145s Get:56 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-ghodss-yaml-dev all 1.0.0+git20220118.d8423dc-2 [16.6 kB] 145s Get:57 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-google-renameio-dev all 2.0.0-2 [10.3 kB] 145s Get:58 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-mattn-go-sqlite3-dev ppc64el 1.14.27~ds1-1 [86.3 kB] 145s Get:59 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-rivo-uniseg-dev all 0.4.7-1 [258 kB] 145s Get:60 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-mattn-go-runewidth-dev all 0.0.16-2 [17.6 kB] 145s Get:61 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-peterh-liner-dev all 1.2.2-1 [21.3 kB] 145s Get:62 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-pkg-errors-dev all 0.9.1-3 [13.2 kB] 145s Get:63 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-rican7-retry-dev all 0.3.1-2 [7740 B] 145s Get:64 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-pmezard-go-difflib-dev all 1.0.0-3 [12.4 kB] 145s Get:65 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-shurcool-sanitized-anchor-name-dev all 1.0.0-3 [3590 B] 145s Get:66 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-russross-blackfriday-v2-dev all 2.1.0-1 [79.6 kB] 145s Get:67 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el golang-github-cpuguy83-go-md2man-v2-dev all 2.0.7-1 [12.4 kB] 145s Get:68 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el golang-github-spf13-pflag-dev all 1.0.10-1 [50.1 kB] 146s Get:69 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-golang-x-sys-dev all 0.22.0-1 [457 kB] 146s Get:70 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-fsnotify-fsnotify-dev all 1.8.0-3 [55.5 kB] 146s Get:71 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-davecgh-go-spew-dev all 1.1.1-3 [31.1 kB] 146s Get:72 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-hashicorp-hcl-dev all 1.0.0-1.1 [58.9 kB] 146s Get:73 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-magiconair-properties-dev all 1.8.7-1 [27.3 kB] 146s Get:74 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-mitchellh-mapstructure-dev all 1.5.0+git20231216.8508981-1 [28.3 kB] 146s Get:75 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-burntsushi-toml-dev all 1.4.0-1 [119 kB] 146s Get:76 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-pelletier-go-toml-dev all 1.9.5-1build1 [79.6 kB] 146s Get:77 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-pelletier-go-toml.v2-dev all 2.2.1-1 [877 kB] 147s Get:78 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-kr-fs-dev all 0.1.0-2 [6344 B] 147s Get:79 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-golang-x-term-dev all 0.22.0-1 [14.6 kB] 147s Get:80 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-golang-x-text-dev all 0.22.0-1 [4616 kB] 149s Get:81 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-golang-x-crypto-dev all 1:0.25.0-1 [1724 kB] 151s Get:82 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-pkg-sftp-dev all 1.13.6-1 [104 kB] 151s Get:83 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-spf13-afero-dev all 1.11.0-1 [59.6 kB] 151s Get:84 http://ftpmaster.internal/ubuntu resolute/main ppc64el tzdata-legacy all 2025b-5ubuntu1 [99.3 kB] 151s Get:85 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-spf13-cast-dev all 1.6.0-2 [14.4 kB] 151s Get:86 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-stretchr-objx-dev all 0.5.2-1 [26.5 kB] 151s Get:87 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-gopkg-yaml.v3-dev all 3.0.1-3 [76.1 kB] 151s Get:88 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-stretchr-testify-dev all 1.10.0-1 [79.6 kB] 151s Get:89 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-spf13-jwalterweatherman-dev all 1.1.0+really1.1.0-2 [6828 B] 151s Get:90 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-subosito-gotenv-dev all 1.3.0-1 [8808 B] 151s Get:91 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-gopkg-ini.v1-dev all 1.67.0-1 [40.8 kB] 151s Get:92 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-spf13-viper-dev all 1.12.0-1 [71.0 kB] 152s Get:93 http://ftpmaster.internal/ubuntu resolute-proposed/universe ppc64el golang-github-spf13-cobra-dev all 1.10.1-1 [81.3 kB] 152s Get:94 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-golang-x-sync-dev all 0.7.0-1 [19.5 kB] 152s Get:95 http://ftpmaster.internal/ubuntu resolute/universe ppc64el golang-github-canonical-go-dqlite-dev all 2.0.0-1 [72.0 kB] 152s Get:96 http://ftpmaster.internal/ubuntu resolute/main ppc64el dh-golang all 1.63 [24.9 kB] 152s Fetched 121 MB in 1min 16s (1597 kB/s) 152s Selecting previously unselected package m4. 153s (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 ... 76559 files and directories currently installed.) 153s Preparing to unpack .../00-m4_1.4.19-8_ppc64el.deb ... 153s Unpacking m4 (1.4.19-8) ... 153s Selecting previously unselected package autoconf. 153s Preparing to unpack .../01-autoconf_2.72-3.1ubuntu1_all.deb ... 153s Unpacking autoconf (2.72-3.1ubuntu1) ... 153s Selecting previously unselected package autotools-dev. 153s Preparing to unpack .../02-autotools-dev_20240727.1_all.deb ... 153s Unpacking autotools-dev (20240727.1) ... 153s Selecting previously unselected package automake. 153s Preparing to unpack .../03-automake_1%3a1.17-4ubuntu1_all.deb ... 153s Unpacking automake (1:1.17-4ubuntu1) ... 153s Selecting previously unselected package autopoint. 153s Preparing to unpack .../04-autopoint_0.23.1-2build2_all.deb ... 153s Unpacking autopoint (0.23.1-2build2) ... 153s Selecting previously unselected package libisl23:ppc64el. 153s Preparing to unpack .../05-libisl23_0.27-1_ppc64el.deb ... 153s Unpacking libisl23:ppc64el (0.27-1) ... 153s Selecting previously unselected package libmpc3:ppc64el. 153s Preparing to unpack .../06-libmpc3_1.3.1-1build3_ppc64el.deb ... 153s Unpacking libmpc3:ppc64el (1.3.1-1build3) ... 153s Selecting previously unselected package cpp-15-powerpc64le-linux-gnu. 153s Preparing to unpack .../07-cpp-15-powerpc64le-linux-gnu_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking cpp-15-powerpc64le-linux-gnu (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package cpp-15. 153s Preparing to unpack .../08-cpp-15_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking cpp-15 (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package cpp-powerpc64le-linux-gnu. 153s Preparing to unpack .../09-cpp-powerpc64le-linux-gnu_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 153s Unpacking cpp-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 153s Selecting previously unselected package cpp. 153s Preparing to unpack .../10-cpp_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 153s Unpacking cpp (4:15.2.0-4ubuntu1) ... 153s Selecting previously unselected package libcc1-0:ppc64el. 153s Preparing to unpack .../11-libcc1-0_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking libcc1-0:ppc64el (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package libgomp1:ppc64el. 153s Preparing to unpack .../12-libgomp1_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking libgomp1:ppc64el (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package libitm1:ppc64el. 153s Preparing to unpack .../13-libitm1_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking libitm1:ppc64el (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package libasan8:ppc64el. 153s Preparing to unpack .../14-libasan8_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking libasan8:ppc64el (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package liblsan0:ppc64el. 153s Preparing to unpack .../15-liblsan0_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking liblsan0:ppc64el (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package libtsan2:ppc64el. 153s Preparing to unpack .../16-libtsan2_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking libtsan2:ppc64el (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package libubsan1:ppc64el. 153s Preparing to unpack .../17-libubsan1_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking libubsan1:ppc64el (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package libquadmath0:ppc64el. 153s Preparing to unpack .../18-libquadmath0_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking libquadmath0:ppc64el (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package libgcc-15-dev:ppc64el. 153s Preparing to unpack .../19-libgcc-15-dev_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking libgcc-15-dev:ppc64el (15.2.0-5ubuntu1) ... 153s Selecting previously unselected package gcc-15-powerpc64le-linux-gnu. 153s Preparing to unpack .../20-gcc-15-powerpc64le-linux-gnu_15.2.0-5ubuntu1_ppc64el.deb ... 153s Unpacking gcc-15-powerpc64le-linux-gnu (15.2.0-5ubuntu1) ... 154s Selecting previously unselected package gcc-15. 154s Preparing to unpack .../21-gcc-15_15.2.0-5ubuntu1_ppc64el.deb ... 154s Unpacking gcc-15 (15.2.0-5ubuntu1) ... 154s Selecting previously unselected package gcc-powerpc64le-linux-gnu. 154s Preparing to unpack .../22-gcc-powerpc64le-linux-gnu_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 154s Unpacking gcc-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 154s Selecting previously unselected package gcc. 154s Preparing to unpack .../23-gcc_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 154s Unpacking gcc (4:15.2.0-4ubuntu1) ... 154s Selecting previously unselected package libstdc++-15-dev:ppc64el. 154s Preparing to unpack .../24-libstdc++-15-dev_15.2.0-5ubuntu1_ppc64el.deb ... 154s Unpacking libstdc++-15-dev:ppc64el (15.2.0-5ubuntu1) ... 154s Selecting previously unselected package g++-15-powerpc64le-linux-gnu. 154s Preparing to unpack .../25-g++-15-powerpc64le-linux-gnu_15.2.0-5ubuntu1_ppc64el.deb ... 154s Unpacking g++-15-powerpc64le-linux-gnu (15.2.0-5ubuntu1) ... 154s Selecting previously unselected package g++-15. 154s Preparing to unpack .../26-g++-15_15.2.0-5ubuntu1_ppc64el.deb ... 154s Unpacking g++-15 (15.2.0-5ubuntu1) ... 154s Selecting previously unselected package g++-powerpc64le-linux-gnu. 154s Preparing to unpack .../27-g++-powerpc64le-linux-gnu_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 154s Unpacking g++-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 154s Selecting previously unselected package g++. 154s Preparing to unpack .../28-g++_4%3a15.2.0-4ubuntu1_ppc64el.deb ... 154s Unpacking g++ (4:15.2.0-4ubuntu1) ... 154s Selecting previously unselected package build-essential. 154s Preparing to unpack .../29-build-essential_12.12ubuntu1_ppc64el.deb ... 154s Unpacking build-essential (12.12ubuntu1) ... 154s Selecting previously unselected package libdebhelper-perl. 154s Preparing to unpack .../30-libdebhelper-perl_13.24.2ubuntu1_all.deb ... 154s Unpacking libdebhelper-perl (13.24.2ubuntu1) ... 154s Selecting previously unselected package libtool. 154s Preparing to unpack .../31-libtool_2.5.4-4build1_all.deb ... 154s Unpacking libtool (2.5.4-4build1) ... 154s Selecting previously unselected package dh-autoreconf. 154s Preparing to unpack .../32-dh-autoreconf_21_all.deb ... 154s Unpacking dh-autoreconf (21) ... 154s Selecting previously unselected package libarchive-zip-perl. 154s Preparing to unpack .../33-libarchive-zip-perl_1.68-1_all.deb ... 154s Unpacking libarchive-zip-perl (1.68-1) ... 154s Selecting previously unselected package libfile-stripnondeterminism-perl. 154s Preparing to unpack .../34-libfile-stripnondeterminism-perl_1.14.2-1_all.deb ... 154s Unpacking libfile-stripnondeterminism-perl (1.14.2-1) ... 154s Selecting previously unselected package dh-strip-nondeterminism. 154s Preparing to unpack .../35-dh-strip-nondeterminism_1.14.2-1_all.deb ... 154s Unpacking dh-strip-nondeterminism (1.14.2-1) ... 154s Selecting previously unselected package debugedit. 154s Preparing to unpack .../36-debugedit_1%3a5.2-3_ppc64el.deb ... 154s Unpacking debugedit (1:5.2-3) ... 154s Selecting previously unselected package dwz. 154s Preparing to unpack .../37-dwz_0.16-2_ppc64el.deb ... 154s Unpacking dwz (0.16-2) ... 154s Selecting previously unselected package gettext. 154s Preparing to unpack .../38-gettext_0.23.1-2build2_ppc64el.deb ... 154s Unpacking gettext (0.23.1-2build2) ... 154s Selecting previously unselected package intltool-debian. 154s Preparing to unpack .../39-intltool-debian_0.35.0+20060710.6_all.deb ... 154s Unpacking intltool-debian (0.35.0+20060710.6) ... 154s Selecting previously unselected package po-debconf. 154s Preparing to unpack .../40-po-debconf_1.0.21+nmu1_all.deb ... 154s Unpacking po-debconf (1.0.21+nmu1) ... 154s Selecting previously unselected package debhelper. 154s Preparing to unpack .../41-debhelper_13.24.2ubuntu1_all.deb ... 154s Unpacking debhelper (13.24.2ubuntu1) ... 155s Selecting previously unselected package libdqlite0:ppc64el. 155s Preparing to unpack .../42-libdqlite0_1.18.1-1_ppc64el.deb ... 155s Unpacking libdqlite0:ppc64el (1.18.1-1) ... 155s Selecting previously unselected package go-dqlite. 155s Preparing to unpack .../43-go-dqlite_2.0.0-1_ppc64el.deb ... 155s Unpacking go-dqlite (2.0.0-1) ... 155s Selecting previously unselected package golang-1.25-src. 155s Preparing to unpack .../44-golang-1.25-src_1.25.0-0ubuntu1_all.deb ... 155s Unpacking golang-1.25-src (1.25.0-0ubuntu1) ... 156s Selecting previously unselected package golang-1.25-go. 156s Preparing to unpack .../45-golang-1.25-go_1.25.0-0ubuntu1_ppc64el.deb ... 156s Unpacking golang-1.25-go (1.25.0-0ubuntu1) ... 157s Selecting previously unselected package golang-src. 157s Preparing to unpack .../46-golang-src_2%3a1.25~1_all.deb ... 157s Unpacking golang-src (2:1.25~1) ... 157s Selecting previously unselected package golang-go:ppc64el. 157s Preparing to unpack .../47-golang-go_2%3a1.25~1_ppc64el.deb ... 157s Unpacking golang-go:ppc64el (2:1.25~1) ... 157s Selecting previously unselected package golang-any:ppc64el. 157s Preparing to unpack .../48-golang-any_2%3a1.25~1_ppc64el.deb ... 157s Unpacking golang-any:ppc64el (2:1.25~1) ... 157s Selecting previously unselected package libxxhash-dev:ppc64el. 157s Preparing to unpack .../49-libxxhash-dev_0.8.3-2_ppc64el.deb ... 157s Unpacking libxxhash-dev:ppc64el (0.8.3-2) ... 157s Selecting previously unselected package liblz4-dev:ppc64el. 157s Preparing to unpack .../50-liblz4-dev_1.10.0-4build1_ppc64el.deb ... 157s Unpacking liblz4-dev:ppc64el (1.10.0-4build1) ... 157s Selecting previously unselected package libuv1-dev:ppc64el. 157s Preparing to unpack .../51-libuv1-dev_1.50.0-2ubuntu1_ppc64el.deb ... 157s Unpacking libuv1-dev:ppc64el (1.50.0-2ubuntu1) ... 157s Selecting previously unselected package libsqlite3-dev:ppc64el. 157s Preparing to unpack .../52-libsqlite3-dev_3.46.1-8_ppc64el.deb ... 157s Unpacking libsqlite3-dev:ppc64el (3.46.1-8) ... 157s Selecting previously unselected package libdqlite-dev:ppc64el. 157s Preparing to unpack .../53-libdqlite-dev_1.18.1-1_ppc64el.deb ... 157s Unpacking libdqlite-dev:ppc64el (1.18.1-1) ... 157s Selecting previously unselected package golang-gopkg-yaml.v2-dev. 157s Preparing to unpack .../54-golang-gopkg-yaml.v2-dev_2.4.0-5_all.deb ... 157s Unpacking golang-gopkg-yaml.v2-dev (2.4.0-5) ... 157s Selecting previously unselected package golang-github-ghodss-yaml-dev. 157s Preparing to unpack .../55-golang-github-ghodss-yaml-dev_1.0.0+git20220118.d8423dc-2_all.deb ... 157s Unpacking golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ... 157s Selecting previously unselected package golang-github-google-renameio-dev. 157s Preparing to unpack .../56-golang-github-google-renameio-dev_2.0.0-2_all.deb ... 157s Unpacking golang-github-google-renameio-dev (2.0.0-2) ... 157s Selecting previously unselected package golang-github-mattn-go-sqlite3-dev:ppc64el. 157s Preparing to unpack .../57-golang-github-mattn-go-sqlite3-dev_1.14.27~ds1-1_ppc64el.deb ... 157s Unpacking golang-github-mattn-go-sqlite3-dev:ppc64el (1.14.27~ds1-1) ... 157s Selecting previously unselected package golang-github-rivo-uniseg-dev. 157s Preparing to unpack .../58-golang-github-rivo-uniseg-dev_0.4.7-1_all.deb ... 157s Unpacking golang-github-rivo-uniseg-dev (0.4.7-1) ... 157s Selecting previously unselected package golang-github-mattn-go-runewidth-dev. 157s Preparing to unpack .../59-golang-github-mattn-go-runewidth-dev_0.0.16-2_all.deb ... 157s Unpacking golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 157s Selecting previously unselected package golang-github-peterh-liner-dev. 157s Preparing to unpack .../60-golang-github-peterh-liner-dev_1.2.2-1_all.deb ... 157s Unpacking golang-github-peterh-liner-dev (1.2.2-1) ... 157s Selecting previously unselected package golang-github-pkg-errors-dev. 157s Preparing to unpack .../61-golang-github-pkg-errors-dev_0.9.1-3_all.deb ... 157s Unpacking golang-github-pkg-errors-dev (0.9.1-3) ... 157s Selecting previously unselected package golang-github-rican7-retry-dev. 157s Preparing to unpack .../62-golang-github-rican7-retry-dev_0.3.1-2_all.deb ... 157s Unpacking golang-github-rican7-retry-dev (0.3.1-2) ... 157s Selecting previously unselected package golang-github-pmezard-go-difflib-dev. 157s Preparing to unpack .../63-golang-github-pmezard-go-difflib-dev_1.0.0-3_all.deb ... 157s Unpacking golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 157s Selecting previously unselected package golang-github-shurcool-sanitized-anchor-name-dev. 157s Preparing to unpack .../64-golang-github-shurcool-sanitized-anchor-name-dev_1.0.0-3_all.deb ... 157s Unpacking golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 157s Selecting previously unselected package golang-github-russross-blackfriday-v2-dev. 157s Preparing to unpack .../65-golang-github-russross-blackfriday-v2-dev_2.1.0-1_all.deb ... 157s Unpacking golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 157s Selecting previously unselected package golang-github-cpuguy83-go-md2man-v2-dev. 157s Preparing to unpack .../66-golang-github-cpuguy83-go-md2man-v2-dev_2.0.7-1_all.deb ... 157s Unpacking golang-github-cpuguy83-go-md2man-v2-dev (2.0.7-1) ... 157s Selecting previously unselected package golang-github-spf13-pflag-dev. 157s Preparing to unpack .../67-golang-github-spf13-pflag-dev_1.0.10-1_all.deb ... 157s Unpacking golang-github-spf13-pflag-dev (1.0.10-1) ... 157s Selecting previously unselected package golang-golang-x-sys-dev. 157s Preparing to unpack .../68-golang-golang-x-sys-dev_0.22.0-1_all.deb ... 157s Unpacking golang-golang-x-sys-dev (0.22.0-1) ... 157s Selecting previously unselected package golang-github-fsnotify-fsnotify-dev. 157s Preparing to unpack .../69-golang-github-fsnotify-fsnotify-dev_1.8.0-3_all.deb ... 157s Unpacking golang-github-fsnotify-fsnotify-dev (1.8.0-3) ... 157s Selecting previously unselected package golang-github-davecgh-go-spew-dev. 157s Preparing to unpack .../70-golang-github-davecgh-go-spew-dev_1.1.1-3_all.deb ... 157s Unpacking golang-github-davecgh-go-spew-dev (1.1.1-3) ... 157s Selecting previously unselected package golang-github-hashicorp-hcl-dev. 157s Preparing to unpack .../71-golang-github-hashicorp-hcl-dev_1.0.0-1.1_all.deb ... 157s Unpacking golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 157s Selecting previously unselected package golang-github-magiconair-properties-dev. 157s Preparing to unpack .../72-golang-github-magiconair-properties-dev_1.8.7-1_all.deb ... 157s Unpacking golang-github-magiconair-properties-dev (1.8.7-1) ... 157s Selecting previously unselected package golang-github-mitchellh-mapstructure-dev. 157s Preparing to unpack .../73-golang-github-mitchellh-mapstructure-dev_1.5.0+git20231216.8508981-1_all.deb ... 157s Unpacking golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 157s Selecting previously unselected package golang-github-burntsushi-toml-dev. 157s Preparing to unpack .../74-golang-github-burntsushi-toml-dev_1.4.0-1_all.deb ... 157s Unpacking golang-github-burntsushi-toml-dev (1.4.0-1) ... 157s Selecting previously unselected package golang-github-pelletier-go-toml-dev. 157s Preparing to unpack .../75-golang-github-pelletier-go-toml-dev_1.9.5-1build1_all.deb ... 157s Unpacking golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 157s Selecting previously unselected package golang-github-pelletier-go-toml.v2-dev. 157s Preparing to unpack .../76-golang-github-pelletier-go-toml.v2-dev_2.2.1-1_all.deb ... 157s Unpacking golang-github-pelletier-go-toml.v2-dev (2.2.1-1) ... 158s Selecting previously unselected package golang-github-kr-fs-dev. 158s Preparing to unpack .../77-golang-github-kr-fs-dev_0.1.0-2_all.deb ... 158s Unpacking golang-github-kr-fs-dev (0.1.0-2) ... 158s Selecting previously unselected package golang-golang-x-term-dev. 158s Preparing to unpack .../78-golang-golang-x-term-dev_0.22.0-1_all.deb ... 158s Unpacking golang-golang-x-term-dev (0.22.0-1) ... 158s Selecting previously unselected package golang-golang-x-text-dev. 158s Preparing to unpack .../79-golang-golang-x-text-dev_0.22.0-1_all.deb ... 158s Unpacking golang-golang-x-text-dev (0.22.0-1) ... 158s Selecting previously unselected package golang-golang-x-crypto-dev. 158s Preparing to unpack .../80-golang-golang-x-crypto-dev_1%3a0.25.0-1_all.deb ... 158s Unpacking golang-golang-x-crypto-dev (1:0.25.0-1) ... 158s Selecting previously unselected package golang-github-pkg-sftp-dev. 158s Preparing to unpack .../81-golang-github-pkg-sftp-dev_1.13.6-1_all.deb ... 158s Unpacking golang-github-pkg-sftp-dev (1.13.6-1) ... 158s Selecting previously unselected package golang-github-spf13-afero-dev. 158s Preparing to unpack .../82-golang-github-spf13-afero-dev_1.11.0-1_all.deb ... 158s Unpacking golang-github-spf13-afero-dev (1.11.0-1) ... 158s Selecting previously unselected package tzdata-legacy. 158s Preparing to unpack .../83-tzdata-legacy_2025b-5ubuntu1_all.deb ... 158s Unpacking tzdata-legacy (2025b-5ubuntu1) ... 158s Selecting previously unselected package golang-github-spf13-cast-dev. 158s Preparing to unpack .../84-golang-github-spf13-cast-dev_1.6.0-2_all.deb ... 158s Unpacking golang-github-spf13-cast-dev (1.6.0-2) ... 158s Selecting previously unselected package golang-github-stretchr-objx-dev. 158s Preparing to unpack .../85-golang-github-stretchr-objx-dev_0.5.2-1_all.deb ... 158s Unpacking golang-github-stretchr-objx-dev (0.5.2-1) ... 158s Selecting previously unselected package golang-gopkg-yaml.v3-dev. 158s Preparing to unpack .../86-golang-gopkg-yaml.v3-dev_3.0.1-3_all.deb ... 158s Unpacking golang-gopkg-yaml.v3-dev (3.0.1-3) ... 158s Selecting previously unselected package golang-github-stretchr-testify-dev. 158s Preparing to unpack .../87-golang-github-stretchr-testify-dev_1.10.0-1_all.deb ... 158s Unpacking golang-github-stretchr-testify-dev (1.10.0-1) ... 158s Selecting previously unselected package golang-github-spf13-jwalterweatherman-dev. 158s Preparing to unpack .../88-golang-github-spf13-jwalterweatherman-dev_1.1.0+really1.1.0-2_all.deb ... 158s Unpacking golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 158s Selecting previously unselected package golang-github-subosito-gotenv-dev. 158s Preparing to unpack .../89-golang-github-subosito-gotenv-dev_1.3.0-1_all.deb ... 158s Unpacking golang-github-subosito-gotenv-dev (1.3.0-1) ... 158s Selecting previously unselected package golang-gopkg-ini.v1-dev. 158s Preparing to unpack .../90-golang-gopkg-ini.v1-dev_1.67.0-1_all.deb ... 158s Unpacking golang-gopkg-ini.v1-dev (1.67.0-1) ... 158s Selecting previously unselected package golang-github-spf13-viper-dev. 158s Preparing to unpack .../91-golang-github-spf13-viper-dev_1.12.0-1_all.deb ... 158s Unpacking golang-github-spf13-viper-dev (1.12.0-1) ... 158s Selecting previously unselected package golang-github-spf13-cobra-dev. 158s Preparing to unpack .../92-golang-github-spf13-cobra-dev_1.10.1-1_all.deb ... 158s Unpacking golang-github-spf13-cobra-dev (1.10.1-1) ... 158s Selecting previously unselected package golang-golang-x-sync-dev. 158s Preparing to unpack .../93-golang-golang-x-sync-dev_0.7.0-1_all.deb ... 158s Unpacking golang-golang-x-sync-dev (0.7.0-1) ... 158s Selecting previously unselected package golang-github-canonical-go-dqlite-dev. 158s Preparing to unpack .../94-golang-github-canonical-go-dqlite-dev_2.0.0-1_all.deb ... 158s Unpacking golang-github-canonical-go-dqlite-dev (2.0.0-1) ... 158s Selecting previously unselected package dh-golang. 158s Preparing to unpack .../95-dh-golang_1.63_all.deb ... 158s Unpacking dh-golang (1.63) ... 158s Setting up golang-github-pelletier-go-toml.v2-dev (2.2.1-1) ... 158s Setting up libuv1-dev:ppc64el (1.50.0-2ubuntu1) ... 158s Setting up golang-gopkg-yaml.v2-dev (2.4.0-5) ... 158s Setting up golang-github-mitchellh-mapstructure-dev (1.5.0+git20231216.8508981-1) ... 158s Setting up golang-github-shurcool-sanitized-anchor-name-dev (1.0.0-3) ... 158s Setting up golang-github-stretchr-objx-dev (0.5.2-1) ... 158s Setting up golang-github-pkg-errors-dev (0.9.1-3) ... 158s Setting up libarchive-zip-perl (1.68-1) ... 158s Setting up golang-gopkg-yaml.v3-dev (3.0.1-3) ... 158s Setting up libdebhelper-perl (13.24.2ubuntu1) ... 158s Setting up golang-github-burntsushi-toml-dev (1.4.0-1) ... 158s Setting up golang-golang-x-sys-dev (0.22.0-1) ... 158s Setting up golang-github-kr-fs-dev (0.1.0-2) ... 158s Setting up golang-github-subosito-gotenv-dev (1.3.0-1) ... 158s Setting up golang-github-pmezard-go-difflib-dev (1.0.0-3) ... 158s Setting up m4 (1.4.19-8) ... 158s Setting up golang-golang-x-term-dev (0.22.0-1) ... 158s Setting up libgomp1:ppc64el (15.2.0-5ubuntu1) ... 158s Setting up tzdata-legacy (2025b-5ubuntu1) ... 158s Setting up golang-github-google-renameio-dev (2.0.0-2) ... 158s Setting up golang-github-spf13-pflag-dev (1.0.10-1) ... 158s Setting up golang-github-davecgh-go-spew-dev (1.1.1-3) ... 158s Setting up autotools-dev (20240727.1) ... 158s Setting up libsqlite3-dev:ppc64el (3.46.1-8) ... 158s Setting up golang-github-stretchr-testify-dev (1.10.0-1) ... 158s Setting up libquadmath0:ppc64el (15.2.0-5ubuntu1) ... 158s Setting up golang-gopkg-ini.v1-dev (1.67.0-1) ... 158s Setting up libmpc3:ppc64el (1.3.1-1build3) ... 158s Setting up golang-golang-x-sync-dev (0.7.0-1) ... 158s Setting up autopoint (0.23.1-2build2) ... 158s Setting up golang-github-magiconair-properties-dev (1.8.7-1) ... 158s Setting up golang-github-hashicorp-hcl-dev (1.0.0-1.1) ... 158s Setting up golang-github-rican7-retry-dev (0.3.1-2) ... 158s Setting up autoconf (2.72-3.1ubuntu1) ... 158s Setting up libxxhash-dev:ppc64el (0.8.3-2) ... 158s Setting up golang-github-rivo-uniseg-dev (0.4.7-1) ... 158s Setting up libubsan1:ppc64el (15.2.0-5ubuntu1) ... 158s Setting up dwz (0.16-2) ... 158s Setting up golang-1.25-src (1.25.0-0ubuntu1) ... 158s Setting up golang-golang-x-text-dev (0.22.0-1) ... 158s Setting up libasan8:ppc64el (15.2.0-5ubuntu1) ... 158s Setting up golang-github-spf13-cast-dev (1.6.0-2) ... 158s Setting up golang-github-fsnotify-fsnotify-dev (1.8.0-3) ... 158s Setting up debugedit (1:5.2-3) ... 158s Setting up golang-golang-x-crypto-dev (1:0.25.0-1) ... 158s Setting up golang-github-pelletier-go-toml-dev (1.9.5-1build1) ... 158s Setting up golang-github-spf13-jwalterweatherman-dev (1.1.0+really1.1.0-2) ... 158s Setting up libtsan2:ppc64el (15.2.0-5ubuntu1) ... 158s Setting up libdqlite0:ppc64el (1.18.1-1) ... 158s Setting up libisl23:ppc64el (0.27-1) ... 158s Setting up golang-github-ghodss-yaml-dev (1.0.0+git20220118.d8423dc-2) ... 158s Setting up golang-src (2:1.25~1) ... 158s Setting up libcc1-0:ppc64el (15.2.0-5ubuntu1) ... 158s Setting up liblsan0:ppc64el (15.2.0-5ubuntu1) ... 158s Setting up libitm1:ppc64el (15.2.0-5ubuntu1) ... 158s Setting up automake (1:1.17-4ubuntu1) ... 158s update-alternatives: using /usr/bin/automake-1.17 to provide /usr/bin/automake (automake) in auto mode 158s Setting up libfile-stripnondeterminism-perl (1.14.2-1) ... 158s Setting up golang-1.25-go (1.25.0-0ubuntu1) ... 158s Setting up golang-github-mattn-go-runewidth-dev (0.0.16-2) ... 158s Setting up gettext (0.23.1-2build2) ... 158s Setting up libgcc-15-dev:ppc64el (15.2.0-5ubuntu1) ... 158s Setting up golang-github-russross-blackfriday-v2-dev (2.1.0-1) ... 158s Setting up golang-go:ppc64el (2:1.25~1) ... 158s Setting up golang-github-pkg-sftp-dev (1.13.6-1) ... 158s Setting up golang-any:ppc64el (2:1.25~1) ... 158s Setting up intltool-debian (0.35.0+20060710.6) ... 158s Setting up golang-github-peterh-liner-dev (1.2.2-1) ... 158s Setting up golang-github-mattn-go-sqlite3-dev:ppc64el (1.14.27~ds1-1) ... 158s Setting up go-dqlite (2.0.0-1) ... 158s Setting up cpp-15-powerpc64le-linux-gnu (15.2.0-5ubuntu1) ... 158s Setting up libstdc++-15-dev:ppc64el (15.2.0-5ubuntu1) ... 158s Setting up liblz4-dev:ppc64el (1.10.0-4build1) ... 158s Setting up dh-strip-nondeterminism (1.14.2-1) ... 158s Setting up cpp-15 (15.2.0-5ubuntu1) ... 158s Setting up golang-github-spf13-afero-dev (1.11.0-1) ... 158s Setting up cpp-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 158s Setting up libdqlite-dev:ppc64el (1.18.1-1) ... 158s Setting up golang-github-cpuguy83-go-md2man-v2-dev (2.0.7-1) ... 158s Setting up gcc-15-powerpc64le-linux-gnu (15.2.0-5ubuntu1) ... 158s Setting up po-debconf (1.0.21+nmu1) ... 158s Setting up gcc-15 (15.2.0-5ubuntu1) ... 158s Setting up gcc-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 158s Setting up golang-github-spf13-viper-dev (1.12.0-1) ... 158s Setting up cpp (4:15.2.0-4ubuntu1) ... 158s Setting up g++-15-powerpc64le-linux-gnu (15.2.0-5ubuntu1) ... 158s Setting up libtool (2.5.4-4build1) ... 158s Setting up gcc (4:15.2.0-4ubuntu1) ... 158s Setting up dh-autoreconf (21) ... 158s Setting up golang-github-spf13-cobra-dev (1.10.1-1) ... 158s Setting up g++-15 (15.2.0-5ubuntu1) ... 158s Setting up g++-powerpc64le-linux-gnu (4:15.2.0-4ubuntu1) ... 158s Setting up debhelper (13.24.2ubuntu1) ... 158s Setting up golang-github-canonical-go-dqlite-dev (2.0.0-1) ... 158s Setting up g++ (4:15.2.0-4ubuntu1) ... 158s update-alternatives: using /usr/bin/g++ to provide /usr/bin/c++ (c++) in auto mode 158s Setting up build-essential (12.12ubuntu1) ... 158s Setting up dh-golang (1.63) ... 158s Processing triggers for man-db (2.13.1-1) ... 161s Processing triggers for install-info (7.1.1-1ubuntu1) ... 161s Processing triggers for libc-bin (2.42-0ubuntu3) ... 162s autopkgtest [06:02:17]: test dh-golang-autopkgtest: /usr/bin/dh_golang_autopkgtest 162s autopkgtest [06:02:17]: test dh-golang-autopkgtest: [----------------------- 162s [info] Testing github.com/canonical/go-dqlite... 162s [info] Source code installed by binary package, overriding dh_auto_configure... 162s dh build --builddirectory=_build --buildsystem=golang --with=golang 163s dh_update_autotools_config -O--builddirectory=_build -O--buildsystem=golang 163s dh_autoreconf -O--builddirectory=_build -O--buildsystem=golang 163s debian/rules override_dh_auto_configure 163s make[1]: Entering directory '/tmp/autopkgtest.xFPvB0/autopkgtest_tmp' 163s mkdir -p "_build" 163s # remove existing symlinks, since "cp -a" won't convert them 163s find "_build" -type l -exec rm "{}" \; 163s cp -a /usr/share/gocode/src "_build" 163s make[1]: Leaving directory '/tmp/autopkgtest.xFPvB0/autopkgtest_tmp' 163s dh_auto_build -O--builddirectory=_build -O--buildsystem=golang 163s 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 163s internal/goarch 163s internal/unsafeheader 163s internal/cpu 163s internal/abi 163s internal/bytealg 164s internal/byteorder 164s internal/chacha8rand 164s internal/coverage/rtcov 164s internal/godebugs 164s internal/goexperiment 164s internal/goos 164s internal/profilerecord 164s internal/runtime/atomic 164s internal/runtime/math 164s internal/runtime/strconv 164s internal/runtime/syscall 164s internal/runtime/cgroup 164s internal/runtime/exithook 164s internal/runtime/gc 164s internal/asan 164s internal/msan 164s internal/race 164s internal/runtime/sys 164s internal/stringslite 164s internal/runtime/maps 164s internal/trace/tracev2 164s sync/atomic 164s internal/sync 164s internal/synctest 164s math/bits 164s internal/itoa 164s cmp 164s math 164s runtime 164s unicode/utf8 164s unicode 164s container/list 164s crypto/internal/fips140/alias 164s crypto/internal/fips140deps/byteorder 164s crypto/internal/fips140/subtle 164s crypto/internal/fips140deps/cpu 164s crypto/internal/boring/sig 164s unicode/utf16 164s vendor/golang.org/x/crypto/cryptobyte/asn1 164s vendor/golang.org/x/crypto/internal/alias 164s internal/nettrace 165s encoding 165s log/internal 165s container/ring 165s github.com/rivo/uniseg 167s sync 167s internal/reflectlite 168s errors 168s internal/bisect 168s internal/oserror 168s iter 168s internal/godebug 168s slices 168s syscall 168s strconv 168s reflect 168s time 169s context 169s io 169s path 169s io/fs 169s internal/filepathlite 169s internal/syscall/unix 169s internal/poll 169s internal/fmtsort 169s internal/syscall/execenv 170s internal/testlog 170s bytes 170s os 170s hash 170s crypto 170s crypto/internal/fips140deps/godebug 170s strings 170s crypto/internal/fips140 170s crypto/internal/impl 170s crypto/internal/fips140/sha256 170s crypto/internal/fips140/sha3 170s crypto/internal/fips140/sha512 170s crypto/internal/fips140/hmac 170s crypto/internal/fips140/check 170s crypto/internal/fips140/aes 170s fmt 170s crypto/internal/sysrand 170s crypto/internal/entropy 170s math/rand/v2 170s crypto/internal/randutil 170s crypto/internal/fips140/drbg 171s crypto/internal/fips140/aes/gcm 171s crypto/internal/fips140only 171s crypto/subtle 171s crypto/internal/fips140/nistec/fiat 171s crypto/cipher 171s crypto/internal/boring 171s crypto/aes 171s crypto/des 171s crypto/internal/fips140/edwards25519/field 171s math/rand 171s math/big 171s crypto/internal/fips140/nistec 172s crypto/internal/boring/bbig 172s crypto/internal/fips140/bigmod 172s crypto/internal/fips140/ecdh 172s crypto/ecdh 172s crypto/elliptic 172s crypto/internal/fips140/ecdsa 172s weak 172s crypto/internal/fips140cache 172s crypto/sha3 172s crypto/internal/fips140hash 172s crypto/sha512 172s encoding/asn1 172s crypto/internal/fips140/edwards25519 172s crypto/internal/fips140/ed25519 172s vendor/golang.org/x/crypto/cryptobyte 172s crypto/rand 172s crypto/ed25519 173s crypto/ecdsa 173s crypto/internal/fips140/hkdf 173s crypto/hkdf 173s crypto/hmac 173s crypto/internal/fips140/mlkem 173s crypto/internal/fips140/tls12 173s crypto/internal/fips140/tls13 173s encoding/binary 173s crypto/md5 173s crypto/rc4 173s crypto/internal/fips140/rsa 173s vendor/golang.org/x/crypto/chacha20 173s vendor/golang.org/x/crypto/internal/poly1305 173s vendor/golang.org/x/crypto/chacha20poly1305 173s crypto/rsa 173s crypto/internal/hpke 173s crypto/sha1 173s crypto/sha256 173s crypto/fips140 173s crypto/dsa 173s crypto/tls/internal/fips140tls 173s encoding/hex 173s encoding/base64 173s crypto/x509/pkix 173s maps 173s encoding/pem 173s vendor/golang.org/x/net/dns/dnsmessage 174s internal/singleflight 174s unique 174s net/netip 174s runtime/cgo 174s net/url 174s path/filepath 174s sort 174s database/sql/driver 174s database/sql 175s github.com/Rican7/retry/backoff 175s github.com/Rican7/retry/jitter 175s github.com/Rican7/retry/strategy 175s github.com/Rican7/retry 175s flag 175s net 175s bufio 175s internal/sysinfo 175s runtime/debug 175s runtime/trace 175s testing 176s github.com/canonical/go-dqlite/logging 176s github.com/pkg/errors 176s golang.org/x/sync/semaphore 176s io/ioutil 176s github.com/google/renameio 176s github.com/mattn/go-sqlite3 177s crypto/x509 178s crypto/tls 180s github.com/canonical/go-dqlite/internal/protocol 180s regexp/syntax 180s regexp 181s gopkg.in/yaml.v2 181s github.com/canonical/go-dqlite/internal/bindings 181s github.com/canonical/go-dqlite/client 182s github.com/canonical/go-dqlite/tracing 182s github.com/canonical/go-dqlite/driver 182s golang.org/x/sys/unix 183s log 183s encoding/json 183s github.com/canonical/go-dqlite 183s github.com/canonical/go-dqlite/app 183s text/tabwriter 183s github.com/canonical/go-dqlite/internal/shell 183s github.com/canonical/go-dqlite/benchmark 183s github.com/mattn/go-runewidth 183s os/signal 183s encoding/csv 183s github.com/spf13/pflag 184s github.com/peterh/liner 184s text/template/parse 184s text/template 184s compress/flate 184s github.com/spf13/cobra 185s hash/crc32 185s compress/gzip 185s vendor/golang.org/x/text/transform 185s vendor/golang.org/x/text/unicode/bidi 185s github.com/canonical/go-dqlite/cmd/dqlite 185s github.com/canonical/go-dqlite/cmd/dqlite-benchmark 186s vendor/golang.org/x/text/secure/bidirule 186s vendor/golang.org/x/text/unicode/norm 186s net/textproto 186s vendor/golang.org/x/net/http2/hpack 186s mime 186s vendor/golang.org/x/net/idna 187s mime/quotedprintable 187s mime/multipart 187s vendor/golang.org/x/net/http/httpguts 187s vendor/golang.org/x/net/http/httpproxy 187s net/http/httptrace 187s net/http/internal 187s net/http/internal/ascii 187s net/http/internal/httpcommon 187s net/http 189s github.com/canonical/go-dqlite/cmd/dqlite-demo 190s create-stamp debian/debhelper-build-stamp 190s dh_auto_test -O--builddirectory=_build -O--buildsystem=golang 191s 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 212s === RUN ExampleLastEntryInfo 212s --- PASS: ExampleLastEntryInfo (0.00s) 212s PASS 212s ok github.com/canonical/go-dqlite 0.005s 389s === RUN TestNew_PristineDefault 389s --- PASS: TestNew_PristineDefault (0.10s) 389s === RUN TestNew_PristineJoiner 389s app_test.go:1207: 06:03:10.931 - 2: DEBUG: new connection from 127.0.0.1:53454 389s app_test.go:1207: 06:03:10.933 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.943 - 2: DEBUG: new connection from 127.0.0.1:53462 389s app_test.go:1207: 06:03:10.985 - 2: DEBUG: new connection from 127.0.0.1:53466 389s app_test.go:1207: 06:03:10.987 - 3: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.009 - 2: DEBUG: new connection from 127.0.0.1:53476 389s app_test.go:1207: 06:03:10.009 - 3: DEBUG: new connection from 127.0.0.1:33846 389s app_test.go:1207: 06:03:10.011 - 2: DEBUG: new connection from 127.0.0.1:53486 389s app_test.go:1207: 06:03:10.011 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s --- PASS: TestNew_PristineJoiner (0.18s) 389s === RUN TestNew_JoinerRestart 389s app_test.go:1207: 06:03:10.123 - 4: DEBUG: new connection from 127.0.0.1:53498 389s app_test.go:1207: 06:03:10.125 - 4: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.128 - 4: DEBUG: new connection from 127.0.0.1:53504 389s app_test.go:1207: 06:03:10.169 - 4: DEBUG: new connection from 127.0.0.1:53516 389s app_test.go:1207: 06:03:10.171 - 5: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.188 - 4: DEBUG: new connection from 127.0.0.1:53526 389s app_test.go:1207: 06:03:10.189 - 5: DEBUG: new connection from 127.0.0.1:33858 389s app_test.go:1207: 06:03:10.229 - 6: DEBUG: new connection from 127.0.0.1:33866 389s app_test.go:1207: 06:03:10.230 - 4: DEBUG: new connection from 127.0.0.1:53536 389s app_test.go:1207: 06:03:10.232 - 6: WARN: attempt 1: server 127.0.0.1:9002: no known leader 389s app_test.go:1207: 06:03:10.232 - 6: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.237 - 4: DEBUG: new connection from 127.0.0.1:53546 389s app_test.go:1207: 06:03:10.237 - 6: DEBUG: new connection from 127.0.0.1:33870 389s --- PASS: TestNew_JoinerRestart (0.24s) 389s === RUN TestNew_SecondJoiner 389s app_test.go:1207: 06:03:10.367 - 7: DEBUG: new connection from 127.0.0.1:53548 389s app_test.go:1207: 06:03:10.369 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.375 - 7: DEBUG: new connection from 127.0.0.1:53560 389s app_test.go:1207: 06:03:10.417 - 7: DEBUG: new connection from 127.0.0.1:53572 389s app_test.go:1207: 06:03:10.419 - 8: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.433 - 7: DEBUG: new connection from 127.0.0.1:53574 389s app_test.go:1207: 06:03:10.433 - 8: DEBUG: new connection from 127.0.0.1:33880 389s app_test.go:1207: 06:03:10.489 - 7: DEBUG: new connection from 127.0.0.1:53588 389s app_test.go:1207: 06:03:10.491 - 9: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.498 - 7: DEBUG: new connection from 127.0.0.1:53596 389s app_test.go:1207: 06:03:10.498 - 8: DEBUG: new connection from 127.0.0.1:33888 389s app_test.go:1207: 06:03:10.501 - 9: DEBUG: new connection from 127.0.0.1:46836 389s app_test.go:1207: 06:03:10.505 - 9: DEBUG: new connection from 127.0.0.1:46842 389s app_test.go:1207: 06:03:10.513 - 7: DEBUG: new connection from 127.0.0.1:53610 389s app_test.go:1207: 06:03:10.324 - 8: DEBUG: new connection from 127.0.0.1:59208 389s app_test.go:1207: 06:03:10.340 - 7: DEBUG: new connection from 127.0.0.1:35096 389s app_test.go:1207: 06:03:10.321 - 7: DEBUG: new connection from 127.0.0.1:35106 389s app_test.go:1207: 06:03:10.323 - 7: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s --- PASS: TestNew_SecondJoiner (2.16s) 389s === RUN TestNew_ThirdJoiner 389s app_test.go:1207: 06:03:10.533 - 10: DEBUG: new connection from 127.0.0.1:35108 389s app_test.go:1207: 06:03:10.535 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.543 - 10: DEBUG: new connection from 127.0.0.1:35118 389s app_test.go:1207: 06:03:10.593 - 10: DEBUG: new connection from 127.0.0.1:35132 389s app_test.go:1207: 06:03:10.595 - 11: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.613 - 11: DEBUG: new connection from 127.0.0.1:59222 389s app_test.go:1207: 06:03:10.613 - 10: DEBUG: new connection from 127.0.0.1:35148 389s app_test.go:1207: 06:03:10.687 - 10: DEBUG: new connection from 127.0.0.1:35162 389s app_test.go:1207: 06:03:10.689 - 12: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.699 - 11: DEBUG: new connection from 127.0.0.1:59232 389s app_test.go:1207: 06:03:10.699 - 10: DEBUG: new connection from 127.0.0.1:35178 389s app_test.go:1207: 06:03:10.700 - 12: DEBUG: new connection from 127.0.0.1:51804 389s app_test.go:1207: 06:03:10.702 - 12: DEBUG: new connection from 127.0.0.1:51810 389s app_test.go:1207: 06:03:10.717 - 10: DEBUG: new connection from 127.0.0.1:35180 389s app_test.go:1207: 06:03:10.485 - 11: DEBUG: new connection from 127.0.0.1:59248 389s app_test.go:1207: 06:03:10.492 - 10: DEBUG: new connection from 127.0.0.1:35184 389s app_test.go:1207: 06:03:10.550 - 10: DEBUG: new connection from 127.0.0.1:35190 389s app_test.go:1207: 06:03:10.551 - 13: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.564 - 10: DEBUG: new connection from 127.0.0.1:35204 389s app_test.go:1207: 06:03:10.565 - 11: DEBUG: new connection from 127.0.0.1:59258 389s app_test.go:1207: 06:03:10.566 - 12: DEBUG: new connection from 127.0.0.1:51820 389s app_test.go:1207: 06:03:10.567 - 13: DEBUG: new connection from 127.0.0.1:50058 389s app_test.go:1207: 06:03:10.569 - 13: DEBUG: new connection from 127.0.0.1:50072 389s app_test.go:1207: 06:03:10.570 - 10: DEBUG: new connection from 127.0.0.1:35208 389s app_test.go:1207: 06:03:10.575 - 10: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s --- PASS: TestNew_ThirdJoiner (2.24s) 389s === RUN TestNew_FourthJoiner 389s app_test.go:1207: 06:03:10.753 - 14: DEBUG: new connection from 127.0.0.1:35218 389s app_test.go:1207: 06:03:10.755 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.758 - 14: DEBUG: new connection from 127.0.0.1:35228 389s app_test.go:1207: 06:03:10.801 - 14: DEBUG: new connection from 127.0.0.1:35230 389s app_test.go:1207: 06:03:10.803 - 15: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.816 - 15: DEBUG: new connection from 127.0.0.1:59266 389s app_test.go:1207: 06:03:10.816 - 14: DEBUG: new connection from 127.0.0.1:35244 389s app_test.go:1207: 06:03:10.869 - 14: DEBUG: new connection from 127.0.0.1:35254 389s app_test.go:1207: 06:03:10.871 - 16: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.880 - 14: DEBUG: new connection from 127.0.0.1:35268 389s app_test.go:1207: 06:03:10.880 - 15: DEBUG: new connection from 127.0.0.1:59278 389s app_test.go:1207: 06:03:10.883 - 16: DEBUG: new connection from 127.0.0.1:51834 389s app_test.go:1207: 06:03:10.884 - 16: DEBUG: new connection from 127.0.0.1:51846 389s app_test.go:1207: 06:03:10.899 - 14: DEBUG: new connection from 127.0.0.1:35280 389s app_test.go:1207: 06:03:10.699 - 15: DEBUG: new connection from 127.0.0.1:59290 389s app_test.go:1207: 06:03:10.703 - 14: DEBUG: new connection from 127.0.0.1:35296 389s app_test.go:1207: 06:03:10.766 - 14: DEBUG: new connection from 127.0.0.1:35304 389s app_test.go:1207: 06:03:10.767 - 17: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.773 - 15: DEBUG: new connection from 127.0.0.1:59296 389s app_test.go:1207: 06:03:10.773 - 14: DEBUG: new connection from 127.0.0.1:35310 389s app_test.go:1207: 06:03:10.774 - 16: DEBUG: new connection from 127.0.0.1:51858 389s app_test.go:1207: 06:03:10.775 - 17: DEBUG: new connection from 127.0.0.1:50076 389s app_test.go:1207: 06:03:10.778 - 17: DEBUG: new connection from 127.0.0.1:50092 389s app_test.go:1207: 06:03:10.789 - 14: DEBUG: new connection from 127.0.0.1:35312 389s app_test.go:1207: 06:03:10.837 - 14: DEBUG: new connection from 127.0.0.1:35318 389s app_test.go:1207: 06:03:10.839 - 18: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.842 - 15: DEBUG: new connection from 127.0.0.1:59298 389s app_test.go:1207: 06:03:10.842 - 14: DEBUG: new connection from 127.0.0.1:35320 389s app_test.go:1207: 06:03:10.843 - 16: DEBUG: new connection from 127.0.0.1:51860 389s app_test.go:1207: 06:03:10.844 - 17: DEBUG: new connection from 127.0.0.1:50096 389s app_test.go:1207: 06:03:10.845 - 18: DEBUG: new connection from 127.0.0.1:46214 389s app_test.go:1207: 06:03:10.847 - 18: DEBUG: new connection from 127.0.0.1:46226 389s app_test.go:1207: 06:03:10.851 - 14: DEBUG: new connection from 127.0.0.1:35322 389s app_test.go:1207: 06:03:10.853 - 14: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s --- PASS: TestNew_FourthJoiner (2.30s) 389s === RUN TestNew_FifthJoiner 389s app_test.go:1207: 06:03:10.060 - 19: DEBUG: new connection from 127.0.0.1:35336 389s app_test.go:1207: 06:03:10.062 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.068 - 19: DEBUG: new connection from 127.0.0.1:35342 389s app_test.go:1207: 06:03:10.138 - 19: DEBUG: new connection from 127.0.0.1:35356 389s app_test.go:1207: 06:03:10.141 - 20: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.164 - 20: DEBUG: new connection from 127.0.0.1:59300 389s app_test.go:1207: 06:03:10.164 - 19: DEBUG: new connection from 127.0.0.1:35372 389s app_test.go:1207: 06:03:10.225 - 19: DEBUG: new connection from 127.0.0.1:35376 389s app_test.go:1207: 06:03:10.227 - 21: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.239 - 20: DEBUG: new connection from 127.0.0.1:59310 389s app_test.go:1207: 06:03:10.239 - 19: DEBUG: new connection from 127.0.0.1:35388 389s app_test.go:1207: 06:03:10.242 - 21: DEBUG: new connection from 127.0.0.1:51868 389s app_test.go:1207: 06:03:10.244 - 21: DEBUG: new connection from 127.0.0.1:51882 389s app_test.go:1207: 06:03:10.263 - 19: DEBUG: new connection from 127.0.0.1:35396 389s app_test.go:1207: 06:03:10.003 - 20: DEBUG: new connection from 127.0.0.1:59326 389s app_test.go:1207: 06:03:10.009 - 19: DEBUG: new connection from 127.0.0.1:35408 389s app_test.go:1207: 06:03:10.058 - 19: DEBUG: new connection from 127.0.0.1:35416 389s app_test.go:1207: 06:03:10.059 - 22: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.070 - 20: DEBUG: new connection from 127.0.0.1:59328 389s app_test.go:1207: 06:03:10.070 - 19: DEBUG: new connection from 127.0.0.1:35432 389s app_test.go:1207: 06:03:10.071 - 21: DEBUG: new connection from 127.0.0.1:51898 389s app_test.go:1207: 06:03:10.072 - 22: DEBUG: new connection from 127.0.0.1:50104 389s app_test.go:1207: 06:03:10.073 - 22: DEBUG: new connection from 127.0.0.1:50106 389s app_test.go:1207: 06:03:10.086 - 19: DEBUG: new connection from 127.0.0.1:35442 389s app_test.go:1207: 06:03:10.122 - 19: DEBUG: new connection from 127.0.0.1:35454 389s app_test.go:1207: 06:03:10.124 - 23: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.128 - 19: DEBUG: new connection from 127.0.0.1:35456 389s app_test.go:1207: 06:03:10.128 - 20: DEBUG: new connection from 127.0.0.1:59330 389s app_test.go:1207: 06:03:10.129 - 21: DEBUG: new connection from 127.0.0.1:51908 389s app_test.go:1207: 06:03:10.130 - 22: DEBUG: new connection from 127.0.0.1:50114 389s app_test.go:1207: 06:03:10.130 - 23: DEBUG: new connection from 127.0.0.1:46234 389s app_test.go:1207: 06:03:10.134 - 23: DEBUG: new connection from 127.0.0.1:46246 389s app_test.go:1207: 06:03:10.142 - 19: DEBUG: new connection from 127.0.0.1:35464 389s app_test.go:1207: 06:03:10.181 - 19: DEBUG: new connection from 127.0.0.1:35480 389s app_test.go:1207: 06:03:10.183 - 24: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.190 - 20: DEBUG: new connection from 127.0.0.1:59336 389s app_test.go:1207: 06:03:10.190 - 19: DEBUG: new connection from 127.0.0.1:35490 389s app_test.go:1207: 06:03:10.203 - 21: DEBUG: new connection from 127.0.0.1:51918 389s app_test.go:1207: 06:03:10.203 - 22: DEBUG: new connection from 127.0.0.1:50122 389s app_test.go:1207: 06:03:10.204 - 23: DEBUG: new connection from 127.0.0.1:46260 389s app_test.go:1207: 06:03:10.205 - 24: DEBUG: new connection from 127.0.0.1:46310 389s app_test.go:1207: 06:03:10.207 - 24: DEBUG: new connection from 127.0.0.1:46322 389s app_test.go:1207: 06:03:10.209 - 19: DEBUG: new connection from 127.0.0.1:35506 389s app_test.go:1207: 06:03:10.211 - 19: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s --- PASS: TestNew_FifthJoiner (2.35s) 389s === RUN TestNew_SixthJoiner 389s app_test.go:1207: 06:03:10.416 - 25: DEBUG: new connection from 127.0.0.1:35522 389s app_test.go:1207: 06:03:10.418 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.422 - 25: DEBUG: new connection from 127.0.0.1:35526 389s app_test.go:1207: 06:03:10.480 - 25: DEBUG: new connection from 127.0.0.1:35532 389s app_test.go:1207: 06:03:10.482 - 26: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.496 - 26: DEBUG: new connection from 127.0.0.1:59348 389s app_test.go:1207: 06:03:10.496 - 25: DEBUG: new connection from 127.0.0.1:35546 389s app_test.go:1207: 06:03:10.561 - 25: DEBUG: new connection from 127.0.0.1:35550 389s app_test.go:1207: 06:03:10.563 - 27: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.569 - 26: DEBUG: new connection from 127.0.0.1:59350 389s app_test.go:1207: 06:03:10.569 - 25: DEBUG: new connection from 127.0.0.1:35562 389s app_test.go:1207: 06:03:10.570 - 27: DEBUG: new connection from 127.0.0.1:51924 389s app_test.go:1207: 06:03:10.572 - 27: DEBUG: new connection from 127.0.0.1:51936 389s app_test.go:1207: 06:03:10.579 - 25: DEBUG: new connection from 127.0.0.1:35564 389s app_test.go:1207: 06:03:10.355 - 26: DEBUG: new connection from 127.0.0.1:59358 389s app_test.go:1207: 06:03:10.360 - 25: DEBUG: new connection from 127.0.0.1:35578 389s app_test.go:1207: 06:03:10.421 - 25: DEBUG: new connection from 127.0.0.1:46972 389s app_test.go:1207: 06:03:10.423 - 28: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.436 - 25: DEBUG: new connection from 127.0.0.1:46984 389s app_test.go:1207: 06:03:10.436 - 26: DEBUG: new connection from 127.0.0.1:59666 389s app_test.go:1207: 06:03:10.437 - 27: DEBUG: new connection from 127.0.0.1:44624 389s app_test.go:1207: 06:03:10.438 - 28: DEBUG: new connection from 127.0.0.1:59508 389s app_test.go:1207: 06:03:10.441 - 28: DEBUG: new connection from 127.0.0.1:59524 389s app_test.go:1207: 06:03:10.455 - 25: DEBUG: new connection from 127.0.0.1:46992 389s app_test.go:1207: 06:03:10.514 - 25: DEBUG: new connection from 127.0.0.1:47000 389s app_test.go:1207: 06:03:10.517 - 29: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.536 - 26: DEBUG: new connection from 127.0.0.1:59682 389s app_test.go:1207: 06:03:10.536 - 25: DEBUG: new connection from 127.0.0.1:47008 389s app_test.go:1207: 06:03:10.537 - 27: DEBUG: new connection from 127.0.0.1:44630 389s app_test.go:1207: 06:03:10.537 - 28: DEBUG: new connection from 127.0.0.1:59534 389s app_test.go:1207: 06:03:10.539 - 29: DEBUG: new connection from 127.0.0.1:38362 389s app_test.go:1207: 06:03:10.542 - 29: DEBUG: new connection from 127.0.0.1:38364 389s app_test.go:1207: 06:03:10.557 - 25: DEBUG: new connection from 127.0.0.1:47012 389s app_test.go:1207: 06:03:10.614 - 25: DEBUG: new connection from 127.0.0.1:47022 389s app_test.go:1207: 06:03:10.616 - 30: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.627 - 25: DEBUG: new connection from 127.0.0.1:47032 389s app_test.go:1207: 06:03:10.627 - 26: DEBUG: new connection from 127.0.0.1:59694 389s app_test.go:1207: 06:03:10.628 - 27: DEBUG: new connection from 127.0.0.1:44636 389s app_test.go:1207: 06:03:10.629 - 28: DEBUG: new connection from 127.0.0.1:59538 389s app_test.go:1207: 06:03:10.630 - 29: DEBUG: new connection from 127.0.0.1:38378 389s app_test.go:1207: 06:03:10.632 - 30: DEBUG: new connection from 127.0.0.1:49408 389s app_test.go:1207: 06:03:10.634 - 30: DEBUG: new connection from 127.0.0.1:49412 389s app_test.go:1207: 06:03:10.653 - 25: DEBUG: new connection from 127.0.0.1:47044 389s app_test.go:1207: 06:03:10.707 - 25: DEBUG: new connection from 127.0.0.1:47048 389s app_test.go:1207: 06:03:10.709 - 31: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.724 - 25: DEBUG: new connection from 127.0.0.1:47064 389s app_test.go:1207: 06:03:10.725 - 26: DEBUG: new connection from 127.0.0.1:59700 389s app_test.go:1207: 06:03:10.725 - 27: DEBUG: new connection from 127.0.0.1:44644 389s app_test.go:1207: 06:03:10.727 - 28: DEBUG: new connection from 127.0.0.1:59548 389s app_test.go:1207: 06:03:10.727 - 29: DEBUG: new connection from 127.0.0.1:38384 389s app_test.go:1207: 06:03:10.730 - 30: DEBUG: new connection from 127.0.0.1:49428 389s app_test.go:1207: 06:03:10.731 - 31: DEBUG: new connection from 127.0.0.1:38254 389s app_test.go:1207: 06:03:10.733 - 25: DEBUG: new connection from 127.0.0.1:47068 389s app_test.go:1207: 06:03:10.735 - 25: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s --- PASS: TestNew_SixthJoiner (2.52s) 389s === RUN TestHandover_Voter 389s app_test.go:1207: 06:03:10.932 - 32: DEBUG: new connection from 127.0.0.1:47070 389s app_test.go:1207: 06:03:10.934 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.942 - 32: DEBUG: new connection from 127.0.0.1:47084 389s app_test.go:1207: 06:03:10.014 - 32: DEBUG: new connection from 127.0.0.1:47088 389s app_test.go:1207: 06:03:10.016 - 33: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.038 - 33: DEBUG: new connection from 127.0.0.1:59704 389s app_test.go:1207: 06:03:10.038 - 32: DEBUG: new connection from 127.0.0.1:47100 389s app_test.go:1207: 06:03:10.085 - 32: DEBUG: new connection from 127.0.0.1:47104 389s app_test.go:1207: 06:03:10.087 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.093 - 33: DEBUG: new connection from 127.0.0.1:59706 389s app_test.go:1207: 06:03:10.093 - 32: DEBUG: new connection from 127.0.0.1:47110 389s app_test.go:1207: 06:03:10.096 - 34: DEBUG: new connection from 127.0.0.1:44650 389s app_test.go:1207: 06:03:10.098 - 34: DEBUG: new connection from 127.0.0.1:44666 389s app_test.go:1207: 06:03:10.103 - 32: DEBUG: new connection from 127.0.0.1:47116 389s app_test.go:1207: 06:03:10.885 - 33: DEBUG: new connection from 127.0.0.1:59712 389s app_test.go:1207: 06:03:10.888 - 32: DEBUG: new connection from 127.0.0.1:47124 389s app_test.go:1207: 06:03:10.969 - 32: DEBUG: new connection from 127.0.0.1:47134 389s app_test.go:1207: 06:03:10.971 - 35: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.985 - 33: DEBUG: new connection from 127.0.0.1:59716 389s app_test.go:1207: 06:03:10.985 - 32: DEBUG: new connection from 127.0.0.1:47144 389s app_test.go:1207: 06:03:10.986 - 34: DEBUG: new connection from 127.0.0.1:44674 389s app_test.go:1207: 06:03:10.987 - 35: DEBUG: new connection from 127.0.0.1:59562 389s app_test.go:1207: 06:03:10.990 - 35: DEBUG: new connection from 127.0.0.1:59566 389s app_test.go:1207: 06:03:10.993 - 32: DEBUG: new connection from 127.0.0.1:47146 389s app_test.go:1207: 06:03:10.000 - 32: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.000 - 32: DEBUG: new connection from 127.0.0.1:47154 389s app_test.go:1207: 06:03:10.000 - 32: DEBUG: new connection from 127.0.0.1:47168 389s app_test.go:1207: 06:03:10.003 - 34: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.005 - 32: DEBUG: new connection from 127.0.0.1:47182 389s app_test.go:1207: 06:03:10.005 - 33: DEBUG: new connection from 127.0.0.1:59722 389s app_test.go:1207: 06:03:10.008 - 35: DEBUG: new connection from 127.0.0.1:59576 389s app_test.go:1207: 06:03:10.008 - 34: DEBUG: new connection from 127.0.0.1:44686 389s app_test.go:1207: 06:03:10.888 - 34: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 389s --- PASS: TestHandover_Voter (3.17s) 389s === RUN TestHandover_TwoNodes 389s app_test.go:1207: 06:03:10.099 - 36: DEBUG: new connection from 127.0.0.1:47186 389s app_test.go:1207: 06:03:10.101 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.104 - 36: DEBUG: new connection from 127.0.0.1:47192 389s app_test.go:1207: 06:03:10.159 - 36: DEBUG: new connection from 127.0.0.1:47206 389s app_test.go:1207: 06:03:10.164 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.178 - 37: DEBUG: new connection from 127.0.0.1:59724 389s app_test.go:1207: 06:03:10.178 - 36: DEBUG: new connection from 127.0.0.1:47214 389s app_test.go:1207: 06:03:10.180 - 36: DEBUG: new connection from 127.0.0.1:47218 389s app_test.go:1207: 06:03:10.180 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.181 - 37: DEBUG: new connection from 127.0.0.1:59732 389s app_test.go:1207: 06:03:10.181 - 36: DEBUG: new connection from 127.0.0.1:47226 389s app_test.go:1207: 06:03:10.183 - 37: DEBUG: new connection from 127.0.0.1:59736 389s app_test.go:1207: 06:03:10.198 - 36: DEBUG: new connection from 127.0.0.1:47228 389s app_test.go:1207: 06:03:10.042 - 36: DEBUG: promoted 127.0.0.1:9002 from spare to voter 389s app_test.go:1207: 06:03:10.042 - 37: DEBUG: new connection from 127.0.0.1:59744 389s app_test.go:1207: 06:03:10.043 - 36: DEBUG: new connection from 127.0.0.1:47238 389s app_test.go:1207: 06:03:10.063 - 36: DEBUG: new connection from 127.0.0.1:47246 389s app_test.go:1207: 06:03:10.065 - 36: DEBUG: new connection from 127.0.0.1:47256 389s app_test.go:1207: 06:03:10.066 - 36: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 389s app_test.go:1207: 06:03:10.066 - 37: DEBUG: new connection from 127.0.0.1:59750 389s app_test.go:1207: 06:03:10.067 - 36: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 389s app_test.go:1207: 06:03:10.069 - 36: DEBUG: new connection from 127.0.0.1:47258 389s app_test.go:1207: 06:03:10.069 - 37: DEBUG: new connection from 127.0.0.1:59766 389s app_test.go:1207: 06:03:10.070 - 37: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 389s app_test.go:1207: 06:03:10.070 - 37: DEBUG: new connection from 127.0.0.1:59774 389s app_test.go:1207: 06:03:10.072 - 37: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 389s --- PASS: TestHandover_TwoNodes (1.12s) 389s === RUN TestHandover_VoterHonorFailureDomain 389s app_test.go:1207: 06:03:10.223 - 38: DEBUG: new connection from 127.0.0.1:47262 389s app_test.go:1207: 06:03:10.225 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.236 - 38: DEBUG: new connection from 127.0.0.1:47270 389s app_test.go:1207: 06:03:10.294 - 38: DEBUG: new connection from 127.0.0.1:47278 389s app_test.go:1207: 06:03:10.296 - 39: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.319 - 38: DEBUG: new connection from 127.0.0.1:47288 389s app_test.go:1207: 06:03:10.319 - 39: DEBUG: new connection from 127.0.0.1:59788 389s app_test.go:1207: 06:03:10.395 - 38: DEBUG: new connection from 127.0.0.1:47296 389s app_test.go:1207: 06:03:10.397 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.405 - 38: DEBUG: new connection from 127.0.0.1:47300 389s app_test.go:1207: 06:03:10.405 - 39: DEBUG: new connection from 127.0.0.1:59796 389s app_test.go:1207: 06:03:10.407 - 40: DEBUG: new connection from 127.0.0.1:44696 389s app_test.go:1207: 06:03:10.409 - 40: DEBUG: new connection from 127.0.0.1:44700 389s app_test.go:1207: 06:03:10.415 - 38: DEBUG: new connection from 127.0.0.1:47306 389s app_test.go:1207: 06:03:10.186 - 39: DEBUG: new connection from 127.0.0.1:59812 389s app_test.go:1207: 06:03:10.196 - 38: DEBUG: new connection from 127.0.0.1:47312 389s app_test.go:1207: 06:03:10.278 - 38: DEBUG: new connection from 127.0.0.1:47326 389s app_test.go:1207: 06:03:10.281 - 41: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.294 - 38: DEBUG: new connection from 127.0.0.1:47340 389s app_test.go:1207: 06:03:10.295 - 39: DEBUG: new connection from 127.0.0.1:59822 389s app_test.go:1207: 06:03:10.296 - 40: DEBUG: new connection from 127.0.0.1:44702 389s app_test.go:1207: 06:03:10.297 - 41: DEBUG: new connection from 127.0.0.1:59584 389s app_test.go:1207: 06:03:10.301 - 41: DEBUG: new connection from 127.0.0.1:59598 389s app_test.go:1207: 06:03:10.338 - 38: DEBUG: new connection from 127.0.0.1:47342 389s app_test.go:1207: 06:03:10.377 - 38: DEBUG: new connection from 127.0.0.1:47348 389s app_test.go:1207: 06:03:10.381 - 42: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.399 - 39: DEBUG: new connection from 127.0.0.1:59836 389s app_test.go:1207: 06:03:10.399 - 38: DEBUG: new connection from 127.0.0.1:47358 389s app_test.go:1207: 06:03:10.400 - 40: DEBUG: new connection from 127.0.0.1:44714 389s app_test.go:1207: 06:03:10.401 - 41: DEBUG: new connection from 127.0.0.1:59614 389s app_test.go:1207: 06:03:10.402 - 42: DEBUG: new connection from 127.0.0.1:38398 389s app_test.go:1207: 06:03:10.405 - 42: DEBUG: new connection from 127.0.0.1:38402 389s app_test.go:1207: 06:03:10.423 - 38: DEBUG: new connection from 127.0.0.1:47372 389s app_test.go:1207: 06:03:10.471 - 38: DEBUG: new connection from 127.0.0.1:47380 389s app_test.go:1207: 06:03:10.473 - 43: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.483 - 38: DEBUG: new connection from 127.0.0.1:47388 389s app_test.go:1207: 06:03:10.483 - 39: DEBUG: new connection from 127.0.0.1:59840 389s app_test.go:1207: 06:03:10.487 - 40: DEBUG: new connection from 127.0.0.1:44724 389s app_test.go:1207: 06:03:10.488 - 41: DEBUG: new connection from 127.0.0.1:59622 389s app_test.go:1207: 06:03:10.488 - 42: DEBUG: new connection from 127.0.0.1:38406 389s app_test.go:1207: 06:03:10.489 - 43: DEBUG: new connection from 127.0.0.1:49438 389s app_test.go:1207: 06:03:10.493 - 43: DEBUG: new connection from 127.0.0.1:49454 389s app_test.go:1207: 06:03:10.495 - 38: DEBUG: new connection from 127.0.0.1:47402 389s app_test.go:1207: 06:03:10.499 - 38: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.499 - 38: DEBUG: new connection from 127.0.0.1:47416 389s app_test.go:1207: 06:03:10.500 - 40: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.500 - 38: DEBUG: new connection from 127.0.0.1:47424 389s app_test.go:1207: 06:03:10.500 - 39: DEBUG: new connection from 127.0.0.1:59850 389s app_test.go:1207: 06:03:10.501 - 40: DEBUG: new connection from 127.0.0.1:44740 389s app_test.go:1207: 06:03:10.505 - 41: DEBUG: new connection from 127.0.0.1:59628 389s app_test.go:1207: 06:03:10.505 - 38: DEBUG: new connection from 127.0.0.1:47432 389s app_test.go:1207: 06:03:10.506 - 42: DEBUG: new connection from 127.0.0.1:38412 389s app_test.go:1207: 06:03:10.508 - 43: DEBUG: new connection from 127.0.0.1:49456 389s app_test.go:1207: 06:03:10.174 - 40: DEBUG: promoted 127.0.0.1:9006 from stand-by to voter 389s --- PASS: TestHandover_VoterHonorFailureDomain (3.26s) 389s === RUN TestHandover_SingleNode 389s --- PASS: TestHandover_SingleNode (0.12s) 389s === RUN TestHandover_GracefulShutdown 389s app_test.go:406: 06:03:10.617 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:406: 06:03:10.685 - 1: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:406: 06:03:10.787 - 2: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:406: 06:03:10.589 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:406: 06:03:10.604 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:406: 06:03:10.628 - 0: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 389s app_test.go:406: 06:03:10.628 - 0: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 389s app_test.go:406: 06:03:10.657 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 389s app_test.go:406: 06:03:10.658 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 389s app_test.go:406: 06:03:10.680 - 1: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 389s app_test.go:406: 06:03:10.680 - 1: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9003 389s app_test.go:406: 06:03:10.681 - 1: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 389s app_test.go:406: 06:03:10.737 - 2: WARN: attempt 1: server 127.0.0.1:9002: dial: dial tcp 127.0.0.1:9002: connect: connection refused 389s app_test.go:406: 06:03:10.737 - 2: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 389s app_test.go:406: 06:03:10.738 - 2: DEBUG: attempt 1: server 127.0.0.1:9003: connected on fallback path 389s --- PASS: TestHandover_GracefulShutdown (2.27s) 389s === RUN TestHandover_StandBy 389s app_test.go:1207: 06:03:10.910 - 44: DEBUG: new connection from 127.0.0.1:58778 389s app_test.go:1207: 06:03:10.914 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.922 - 44: DEBUG: new connection from 127.0.0.1:58780 389s app_test.go:1207: 06:03:10.016 - 44: DEBUG: new connection from 127.0.0.1:58786 389s app_test.go:1207: 06:03:10.021 - 45: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.083 - 44: DEBUG: new connection from 127.0.0.1:58796 389s app_test.go:1207: 06:03:10.083 - 45: DEBUG: new connection from 127.0.0.1:37122 389s app_test.go:1207: 06:03:10.162 - 44: DEBUG: new connection from 127.0.0.1:58798 389s app_test.go:1207: 06:03:10.164 - 46: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.176 - 44: DEBUG: new connection from 127.0.0.1:58800 389s app_test.go:1207: 06:03:10.176 - 45: DEBUG: new connection from 127.0.0.1:37136 389s app_test.go:1207: 06:03:10.177 - 46: DEBUG: new connection from 127.0.0.1:39356 389s app_test.go:1207: 06:03:10.179 - 46: DEBUG: new connection from 127.0.0.1:39358 389s app_test.go:1207: 06:03:10.191 - 44: DEBUG: new connection from 127.0.0.1:58812 389s app_test.go:1207: 06:03:10.871 - 45: DEBUG: new connection from 127.0.0.1:37152 389s app_test.go:1207: 06:03:10.895 - 44: DEBUG: new connection from 127.0.0.1:58828 389s app_test.go:1207: 06:03:10.934 - 44: DEBUG: new connection from 127.0.0.1:58840 389s app_test.go:1207: 06:03:10.937 - 47: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.957 - 44: DEBUG: new connection from 127.0.0.1:58856 389s app_test.go:1207: 06:03:10.957 - 45: DEBUG: new connection from 127.0.0.1:37162 389s app_test.go:1207: 06:03:10.961 - 47: DEBUG: new connection from 127.0.0.1:39728 389s app_test.go:1207: 06:03:10.961 - 46: DEBUG: new connection from 127.0.0.1:39372 389s app_test.go:1207: 06:03:10.963 - 47: DEBUG: new connection from 127.0.0.1:39744 389s app_test.go:1207: 06:03:10.983 - 44: DEBUG: new connection from 127.0.0.1:58862 389s app_test.go:1207: 06:03:10.045 - 44: DEBUG: new connection from 127.0.0.1:58870 389s app_test.go:1207: 06:03:10.047 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.053 - 45: DEBUG: new connection from 127.0.0.1:37174 389s app_test.go:1207: 06:03:10.053 - 44: DEBUG: new connection from 127.0.0.1:58874 389s app_test.go:1207: 06:03:10.054 - 46: DEBUG: new connection from 127.0.0.1:39376 389s app_test.go:1207: 06:03:10.054 - 47: DEBUG: new connection from 127.0.0.1:39754 389s app_test.go:1207: 06:03:10.057 - 48: DEBUG: new connection from 127.0.0.1:48768 389s app_test.go:1207: 06:03:10.059 - 48: DEBUG: new connection from 127.0.0.1:48772 389s app_test.go:1207: 06:03:10.081 - 44: DEBUG: new connection from 127.0.0.1:58876 389s app_test.go:1207: 06:03:10.125 - 44: DEBUG: new connection from 127.0.0.1:58890 389s app_test.go:1207: 06:03:10.127 - 49: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.132 - 44: DEBUG: new connection from 127.0.0.1:58894 389s app_test.go:1207: 06:03:10.132 - 45: DEBUG: new connection from 127.0.0.1:37188 389s app_test.go:1207: 06:03:10.134 - 46: DEBUG: new connection from 127.0.0.1:39388 389s app_test.go:1207: 06:03:10.134 - 47: DEBUG: new connection from 127.0.0.1:39760 389s app_test.go:1207: 06:03:10.135 - 48: DEBUG: new connection from 127.0.0.1:48780 389s app_test.go:1207: 06:03:10.138 - 49: DEBUG: new connection from 127.0.0.1:59776 389s app_test.go:1207: 06:03:10.140 - 49: DEBUG: new connection from 127.0.0.1:59788 389s app_test.go:1207: 06:03:10.150 - 44: DEBUG: new connection from 127.0.0.1:58908 389s app_test.go:1207: 06:03:10.201 - 44: DEBUG: new connection from 127.0.0.1:58916 389s app_test.go:1207: 06:03:10.203 - 50: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.211 - 45: DEBUG: new connection from 127.0.0.1:37204 389s app_test.go:1207: 06:03:10.211 - 44: DEBUG: new connection from 127.0.0.1:58932 389s app_test.go:1207: 06:03:10.213 - 46: DEBUG: new connection from 127.0.0.1:39404 389s app_test.go:1207: 06:03:10.213 - 47: DEBUG: new connection from 127.0.0.1:39764 389s app_test.go:1207: 06:03:10.214 - 48: DEBUG: new connection from 127.0.0.1:48790 389s app_test.go:1207: 06:03:10.215 - 49: DEBUG: new connection from 127.0.0.1:59796 389s app_test.go:1207: 06:03:10.218 - 50: DEBUG: new connection from 127.0.0.1:53110 389s app_test.go:1207: 06:03:10.219 - 44: DEBUG: new connection from 127.0.0.1:58934 389s app_test.go:1207: 06:03:10.221 - 44: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.221 - 44: DEBUG: new connection from 127.0.0.1:58936 389s app_test.go:1207: 06:03:10.222 - 48: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.222 - 45: DEBUG: new connection from 127.0.0.1:37208 389s app_test.go:1207: 06:03:10.222 - 44: DEBUG: new connection from 127.0.0.1:58938 389s app_test.go:1207: 06:03:10.223 - 46: DEBUG: new connection from 127.0.0.1:39414 389s app_test.go:1207: 06:03:10.224 - 47: DEBUG: new connection from 127.0.0.1:39768 389s app_test.go:1207: 06:03:10.225 - 48: DEBUG: new connection from 127.0.0.1:48794 389s app_test.go:1207: 06:03:10.226 - 49: DEBUG: new connection from 127.0.0.1:59806 389s app_test.go:1207: 06:03:10.227 - 50: DEBUG: new connection from 127.0.0.1:53120 389s app_test.go:1207: 06:03:10.228 - 50: DEBUG: new connection from 127.0.0.1:53122 389s app_test.go:1207: 06:03:10.230 - 48: DEBUG: promoted 127.0.0.1:9007 from spare to stand-by 389s --- PASS: TestHandover_StandBy (2.57s) 389s === RUN TestHandover_TransferLeadership 389s app_test.go:1207: 06:03:10.441 - 51: DEBUG: new connection from 127.0.0.1:58952 389s app_test.go:1207: 06:03:10.443 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.447 - 51: DEBUG: new connection from 127.0.0.1:58962 389s app_test.go:1207: 06:03:10.513 - 51: DEBUG: new connection from 127.0.0.1:58966 389s app_test.go:1207: 06:03:10.515 - 52: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.534 - 51: DEBUG: new connection from 127.0.0.1:58980 389s app_test.go:1207: 06:03:10.534 - 52: DEBUG: new connection from 127.0.0.1:37218 389s app_test.go:1207: 06:03:10.614 - 51: DEBUG: new connection from 127.0.0.1:58992 389s app_test.go:1207: 06:03:10.615 - 53: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.624 - 52: DEBUG: new connection from 127.0.0.1:37220 389s app_test.go:1207: 06:03:10.624 - 51: DEBUG: new connection from 127.0.0.1:59006 389s app_test.go:1207: 06:03:10.625 - 53: DEBUG: new connection from 127.0.0.1:39424 389s app_test.go:1207: 06:03:10.628 - 53: DEBUG: new connection from 127.0.0.1:39432 389s app_test.go:1207: 06:03:10.635 - 51: DEBUG: new connection from 127.0.0.1:59010 389s app_test.go:1207: 06:03:10.419 - 52: DEBUG: new connection from 127.0.0.1:37234 389s app_test.go:1207: 06:03:10.433 - 51: DEBUG: new connection from 127.0.0.1:59024 389s app_test.go:1207: 06:03:10.449 - 51: DEBUG: new connection from 127.0.0.1:59040 389s app_test.go:1207: 06:03:10.451 - 54: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.455 - 51: DEBUG: new connection from 127.0.0.1:59056 389s app_test.go:1207: 06:03:10.455 - 52: DEBUG: new connection from 127.0.0.1:37242 389s app_test.go:1207: 06:03:10.456 - 53: DEBUG: new connection from 127.0.0.1:39436 389s app_test.go:1207: 06:03:10.457 - 54: DEBUG: new connection from 127.0.0.1:39772 389s app_test.go:1207: 06:03:10.459 - 54: DEBUG: new connection from 127.0.0.1:39788 389s app_test.go:1207: 06:03:10.459 - 51: DEBUG: new connection from 127.0.0.1:59070 389s app_test.go:1207: 06:03:10.462 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.462 - 51: DEBUG: new connection from 127.0.0.1:59072 389s app_test.go:1207: 06:03:10.464 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.464 - 52: DEBUG: new connection from 127.0.0.1:37254 389s app_test.go:1207: 06:03:10.464 - 51: DEBUG: new connection from 127.0.0.1:59082 389s app_test.go:1207: 06:03:10.465 - 53: DEBUG: new connection from 127.0.0.1:39446 389s app_test.go:1207: 06:03:10.466 - 54: DEBUG: new connection from 127.0.0.1:39800 389s app_test.go:1207: 06:03:10.467 - 51: DEBUG: new connection from 127.0.0.1:59094 389s app_test.go:1207: 06:03:10.401 - 51: DEBUG: promoted 127.0.0.1:9004 from stand-by to voter 389s app_test.go:1207: 06:03:10.401 - 51: DEBUG: new connection from 127.0.0.1:59104 389s app_test.go:1207: 06:03:10.401 - 52: DEBUG: new connection from 127.0.0.1:37260 389s app_test.go:1207: 06:03:10.403 - 53: DEBUG: new connection from 127.0.0.1:39462 389s app_test.go:1207: 06:03:10.403 - 54: DEBUG: new connection from 127.0.0.1:39812 389s app_test.go:1207: 06:03:10.409 - 53: DEBUG: new connection from 127.0.0.1:39472 389s app_test.go:1207: 06:03:10.410 - 54: DEBUG: new connection from 127.0.0.1:39820 389s app_test.go:1207: 06:03:10.415 - 52: DEBUG: new connection from 127.0.0.1:37272 389s app_test.go:1207: 06:03:10.417 - 52: DEBUG: new connection from 127.0.0.1:37288 389s app_test.go:1207: 06:03:10.418 - 51: DEBUG: new connection from 127.0.0.1:59118 389s app_test.go:1207: 06:03:10.419 - 51: DEBUG: new connection from 127.0.0.1:59126 389s app_test.go:1207: 06:03:10.420 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 389s app_test.go:1207: 06:03:10.420 - 52: DEBUG: new connection from 127.0.0.1:37292 389s app_test.go:1207: 06:03:10.422 - 51: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 389s app_test.go:1207: 06:03:10.423 - 51: DEBUG: new connection from 127.0.0.1:59142 389s app_test.go:1207: 06:03:10.427 - 51: DEBUG: attempt 1: server 127.0.0.1:9001: connect to reported leader 127.0.0.1:9002 389s app_test.go:1207: 06:03:10.427 - 52: DEBUG: new connection from 127.0.0.1:37306 389s app_test.go:1207: 06:03:10.428 - 51: DEBUG: attempt 1: server 127.0.0.1:9002: connected on fallback path 389s --- PASS: TestHandover_TransferLeadership (3.20s) 389s === RUN TestRolesAdjustment_ReplaceVoter 389s app_test.go:1207: 06:03:10.633 - 55: DEBUG: new connection from 127.0.0.1:59156 389s app_test.go:1207: 06:03:10.634 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.638 - 55: DEBUG: new connection from 127.0.0.1:59162 389s app_test.go:1207: 06:03:10.689 - 55: DEBUG: new connection from 127.0.0.1:59166 389s app_test.go:1207: 06:03:10.691 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.713 - 55: DEBUG: new connection from 127.0.0.1:59180 389s app_test.go:1207: 06:03:10.713 - 56: DEBUG: new connection from 127.0.0.1:37310 389s app_test.go:1207: 06:03:10.766 - 55: DEBUG: new connection from 127.0.0.1:59192 389s app_test.go:1207: 06:03:10.769 - 57: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.775 - 55: DEBUG: new connection from 127.0.0.1:59198 389s app_test.go:1207: 06:03:10.775 - 56: DEBUG: new connection from 127.0.0.1:37322 389s app_test.go:1207: 06:03:10.776 - 57: DEBUG: new connection from 127.0.0.1:39488 389s app_test.go:1207: 06:03:10.778 - 57: DEBUG: new connection from 127.0.0.1:39492 389s app_test.go:1207: 06:03:10.784 - 55: DEBUG: new connection from 127.0.0.1:59206 389s app_test.go:1207: 06:03:10.616 - 56: DEBUG: new connection from 127.0.0.1:37328 389s app_test.go:1207: 06:03:10.622 - 55: DEBUG: new connection from 127.0.0.1:59216 389s app_test.go:1207: 06:03:10.639 - 55: DEBUG: new connection from 127.0.0.1:59218 389s app_test.go:1207: 06:03:10.641 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.646 - 56: DEBUG: new connection from 127.0.0.1:37336 389s app_test.go:1207: 06:03:10.646 - 55: DEBUG: new connection from 127.0.0.1:59232 389s app_test.go:1207: 06:03:10.647 - 57: DEBUG: new connection from 127.0.0.1:39498 389s app_test.go:1207: 06:03:10.665 - 55: DEBUG: new connection from 127.0.0.1:59242 389s app_test.go:1207: 06:03:10.668 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.680 - 55: DEBUG: new connection from 127.0.0.1:59246 389s app_test.go:1207: 06:03:10.680 - 56: DEBUG: new connection from 127.0.0.1:37346 389s app_test.go:1207: 06:03:10.684 - 57: DEBUG: new connection from 127.0.0.1:39514 389s app_test.go:1207: 06:03:10.684 - 58: DEBUG: new connection from 127.0.0.1:39834 389s app_test.go:1207: 06:03:10.688 - 58: DEBUG: new connection from 127.0.0.1:39838 389s app_test.go:1207: 06:03:10.697 - 55: DEBUG: new connection from 127.0.0.1:59260 389s app_test.go:1207: 06:03:10.716 - 55: DEBUG: new connection from 127.0.0.1:59274 389s app_test.go:1207: 06:03:10.717 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.650 - 55: DEBUG: new connection from 127.0.0.1:51206 389s app_test.go:1207: 06:03:10.651 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.656 - 56: DEBUG: new connection from 127.0.0.1:44860 389s app_test.go:1207: 06:03:10.656 - 55: DEBUG: new connection from 127.0.0.1:51210 389s app_test.go:1207: 06:03:10.657 - 58: DEBUG: new connection from 127.0.0.1:34136 389s app_test.go:1207: 06:03:10.661 - 55: DEBUG: new connection from 127.0.0.1:51212 389s app_test.go:1207: 06:03:10.661 - 56: DEBUG: new connection from 127.0.0.1:44866 389s app_test.go:1207: 06:03:10.664 - 58: DEBUG: new connection from 127.0.0.1:34150 389s app_test.go:1207: 06:03:10.667 - 56: DEBUG: new connection from 127.0.0.1:44876 389s app_test.go:1207: 06:03:10.667 - 55: DEBUG: new connection from 127.0.0.1:51222 389s app_test.go:1207: 06:03:10.669 - 58: DEBUG: new connection from 127.0.0.1:34160 389s app_test.go:1207: 06:03:10.690 - 55: DEBUG: new connection from 127.0.0.1:51234 389s app_test.go:1207: 06:03:10.691 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.724 - 55: DEBUG: new connection from 127.0.0.1:51250 389s app_test.go:1207: 06:03:10.725 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.671 - 55: DEBUG: new connection from 127.0.0.1:51258 389s app_test.go:1207: 06:03:10.673 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.699 - 55: DEBUG: new connection from 127.0.0.1:51266 389s app_test.go:1207: 06:03:10.700 - 55: DEBUG: new connection from 127.0.0.1:51282 389s app_test.go:1207: 06:03:10.701 - 56: DEBUG: new connection from 127.0.0.1:44892 389s app_test.go:1207: 06:03:10.702 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.704 - 58: DEBUG: new connection from 127.0.0.1:34170 389s app_test.go:1207: 06:03:10.732 - 55: DEBUG: new connection from 127.0.0.1:51298 389s app_test.go:1207: 06:03:10.733 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.706 - 55: DEBUG: new connection from 127.0.0.1:51310 389s app_test.go:1207: 06:03:10.708 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.723 - 55: DEBUG: new connection from 127.0.0.1:51314 389s app_test.go:1207: 06:03:10.724 - 55: DEBUG: new connection from 127.0.0.1:51330 389s app_test.go:1207: 06:03:10.724 - 56: DEBUG: new connection from 127.0.0.1:44908 389s app_test.go:1207: 06:03:10.725 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.725 - 58: DEBUG: new connection from 127.0.0.1:34182 389s app_test.go:1207: 06:03:10.738 - 55: DEBUG: new connection from 127.0.0.1:51340 389s app_test.go:1207: 06:03:10.739 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.729 - 55: DEBUG: new connection from 127.0.0.1:51342 389s app_test.go:1207: 06:03:10.730 - 56: DEBUG: new connection from 127.0.0.1:44924 389s app_test.go:1207: 06:03:10.731 - 58: DEBUG: new connection from 127.0.0.1:34196 389s app_test.go:1207: 06:03:10.731 - 55: DEBUG: new connection from 127.0.0.1:51352 389s app_test.go:1207: 06:03:10.731 - 55: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 389s app_test.go:1207: 06:03:10.732 - 55: DEBUG: new connection from 127.0.0.1:51354 389s app_test.go:1207: 06:03:10.734 - 55: DEBUG: attempt 1: server 127.0.0.1:9004: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:03:10.734 - 55: DEBUG: new connection from 127.0.0.1:51368 389s app_test.go:1207: 06:03:10.734 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.734 - 55: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.734 - 58: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.735 - 55: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:03:10.735 - 55: WARN: attempt 1: server 127.0.0.1:9001: context canceled 389s app_test.go:1207: 06:03:10.749 - 55: DEBUG: new connection from 127.0.0.1:51372 389s app_test.go:1207: 06:03:10.749 - 56: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.753 - 55: DEBUG: new connection from 127.0.0.1:51384 389s app_test.go:1207: 06:03:10.753 - 56: DEBUG: new connection from 127.0.0.1:44930 389s app_test.go:1207: 06:03:10.754 - 58: DEBUG: new connection from 127.0.0.1:34208 389s --- PASS: TestRolesAdjustment_ReplaceVoter (10.32s) 389s === RUN TestRolesAdjustment_ReplaceVoterHonorFailureDomain 389s app_test.go:1207: 06:03:10.957 - 59: DEBUG: new connection from 127.0.0.1:51386 389s app_test.go:1207: 06:03:10.960 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.965 - 59: DEBUG: new connection from 127.0.0.1:51390 389s app_test.go:1207: 06:03:10.013 - 59: DEBUG: new connection from 127.0.0.1:51398 389s app_test.go:1207: 06:03:10.015 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.037 - 60: DEBUG: new connection from 127.0.0.1:44938 389s app_test.go:1207: 06:03:10.037 - 59: DEBUG: new connection from 127.0.0.1:51400 389s app_test.go:1207: 06:03:10.094 - 59: DEBUG: new connection from 127.0.0.1:51412 389s app_test.go:1207: 06:03:10.096 - 61: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.102 - 59: DEBUG: new connection from 127.0.0.1:51418 389s app_test.go:1207: 06:03:10.102 - 60: DEBUG: new connection from 127.0.0.1:44952 389s app_test.go:1207: 06:03:10.104 - 61: DEBUG: new connection from 127.0.0.1:60002 389s app_test.go:1207: 06:03:10.106 - 61: DEBUG: new connection from 127.0.0.1:60018 389s app_test.go:1207: 06:03:10.112 - 59: DEBUG: new connection from 127.0.0.1:51430 389s app_test.go:1207: 06:03:10.919 - 60: DEBUG: new connection from 127.0.0.1:44962 389s app_test.go:1207: 06:03:10.925 - 59: DEBUG: new connection from 127.0.0.1:51442 389s app_test.go:1207: 06:03:10.974 - 59: DEBUG: new connection from 127.0.0.1:51448 389s app_test.go:1207: 06:03:10.976 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.984 - 60: DEBUG: new connection from 127.0.0.1:44976 389s app_test.go:1207: 06:03:10.984 - 59: DEBUG: new connection from 127.0.0.1:51452 389s app_test.go:1207: 06:03:10.985 - 61: DEBUG: new connection from 127.0.0.1:60034 389s app_test.go:1207: 06:03:10.986 - 62: DEBUG: new connection from 127.0.0.1:34224 389s app_test.go:1207: 06:03:10.987 - 62: DEBUG: new connection from 127.0.0.1:34230 389s app_test.go:1207: 06:03:10.001 - 59: DEBUG: new connection from 127.0.0.1:51456 389s app_test.go:1207: 06:03:10.049 - 59: DEBUG: new connection from 127.0.0.1:51464 389s app_test.go:1207: 06:03:10.053 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.064 - 60: DEBUG: new connection from 127.0.0.1:44988 389s app_test.go:1207: 06:03:10.065 - 59: DEBUG: new connection from 127.0.0.1:51466 389s app_test.go:1207: 06:03:10.065 - 61: DEBUG: new connection from 127.0.0.1:60048 389s app_test.go:1207: 06:03:10.066 - 62: DEBUG: new connection from 127.0.0.1:34236 389s app_test.go:1207: 06:03:10.068 - 63: DEBUG: new connection from 127.0.0.1:45926 389s app_test.go:1207: 06:03:10.070 - 63: DEBUG: new connection from 127.0.0.1:45930 389s app_test.go:1207: 06:03:10.081 - 59: DEBUG: new connection from 127.0.0.1:51476 389s app_test.go:1207: 06:03:10.125 - 59: DEBUG: new connection from 127.0.0.1:51486 389s app_test.go:1207: 06:03:10.128 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:03:10.133 - 59: DEBUG: new connection from 127.0.0.1:51490 389s app_test.go:1207: 06:03:10.133 - 60: DEBUG: new connection from 127.0.0.1:44998 389s app_test.go:1207: 06:03:10.134 - 61: DEBUG: new connection from 127.0.0.1:60054 389s app_test.go:1207: 06:03:10.135 - 62: DEBUG: new connection from 127.0.0.1:34240 389s app_test.go:1207: 06:03:10.136 - 63: DEBUG: new connection from 127.0.0.1:45942 389s app_test.go:1207: 06:03:10.137 - 64: DEBUG: new connection from 127.0.0.1:59254 389s app_test.go:1207: 06:03:10.139 - 64: DEBUG: new connection from 127.0.0.1:59256 389s app_test.go:1207: 06:03:10.145 - 59: DEBUG: new connection from 127.0.0.1:51498 389s app_test.go:1207: 06:03:10.967 - 59: DEBUG: new connection from 127.0.0.1:37282 389s app_test.go:1207: 06:03:10.969 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.977 - 59: DEBUG: new connection from 127.0.0.1:37298 389s app_test.go:1207: 06:03:10.977 - 60: DEBUG: new connection from 127.0.0.1:40870 389s app_test.go:1207: 06:03:10.979 - 62: DEBUG: new connection from 127.0.0.1:50846 389s app_test.go:1207: 06:03:10.981 - 63: DEBUG: new connection from 127.0.0.1:37546 389s app_test.go:1207: 06:03:10.981 - 64: DEBUG: new connection from 127.0.0.1:57090 389s app_test.go:1207: 06:03:10.985 - 59: DEBUG: new connection from 127.0.0.1:37306 389s app_test.go:1207: 06:03:10.986 - 60: DEBUG: new connection from 127.0.0.1:40872 389s app_test.go:1207: 06:03:10.990 - 62: DEBUG: new connection from 127.0.0.1:50854 389s app_test.go:1207: 06:03:10.991 - 63: DEBUG: new connection from 127.0.0.1:37562 389s app_test.go:1207: 06:03:10.992 - 64: DEBUG: new connection from 127.0.0.1:57104 389s app_test.go:1207: 06:03:10.997 - 59: DEBUG: new connection from 127.0.0.1:37316 389s app_test.go:1207: 06:03:10.997 - 60: DEBUG: new connection from 127.0.0.1:40874 389s app_test.go:1207: 06:03:10.999 - 63: DEBUG: new connection from 127.0.0.1:37576 389s app_test.go:1207: 06:03:10.999 - 62: DEBUG: new connection from 127.0.0.1:50864 389s app_test.go:1207: 06:03:10.001 - 64: DEBUG: new connection from 127.0.0.1:57114 389s app_test.go:1207: 06:03:10.040 - 59: DEBUG: new connection from 127.0.0.1:37326 389s app_test.go:1207: 06:03:10.041 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.990 - 59: DEBUG: new connection from 127.0.0.1:37328 389s app_test.go:1207: 06:03:10.991 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.072 - 59: DEBUG: new connection from 127.0.0.1:37344 389s app_test.go:1207: 06:03:10.074 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:03:10.141 - 59: DEBUG: new connection from 127.0.0.1:37358 389s app_test.go:1207: 06:03:10.142 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.003 - 59: DEBUG: new connection from 127.0.0.1:37360 389s app_test.go:1207: 06:04:10.004 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.009 - 59: DEBUG: new connection from 127.0.0.1:37364 389s app_test.go:1207: 06:04:10.009 - 60: DEBUG: new connection from 127.0.0.1:40884 389s app_test.go:1207: 06:04:10.010 - 62: DEBUG: new connection from 127.0.0.1:50874 389s app_test.go:1207: 06:04:10.011 - 63: DEBUG: new connection from 127.0.0.1:37578 389s app_test.go:1207: 06:04:10.012 - 64: DEBUG: new connection from 127.0.0.1:57122 389s app_test.go:1207: 06:04:10.046 - 59: DEBUG: new connection from 127.0.0.1:37380 389s app_test.go:1207: 06:04:10.047 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.996 - 59: DEBUG: new connection from 127.0.0.1:37394 389s app_test.go:1207: 06:04:10.998 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.079 - 59: DEBUG: new connection from 127.0.0.1:37400 389s app_test.go:1207: 06:04:10.080 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.148 - 59: DEBUG: new connection from 127.0.0.1:37416 389s app_test.go:1207: 06:04:10.149 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.016 - 59: DEBUG: new connection from 127.0.0.1:37418 389s app_test.go:1207: 06:04:10.019 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.023 - 60: DEBUG: new connection from 127.0.0.1:40892 389s app_test.go:1207: 06:04:10.023 - 59: DEBUG: new connection from 127.0.0.1:37422 389s app_test.go:1207: 06:04:10.024 - 62: DEBUG: new connection from 127.0.0.1:50876 389s app_test.go:1207: 06:04:10.025 - 63: DEBUG: new connection from 127.0.0.1:37584 389s app_test.go:1207: 06:04:10.026 - 64: DEBUG: new connection from 127.0.0.1:57132 389s app_test.go:1207: 06:04:10.052 - 59: DEBUG: new connection from 127.0.0.1:37430 389s app_test.go:1207: 06:04:10.054 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.012 - 59: DEBUG: new connection from 127.0.0.1:35650 389s app_test.go:1207: 06:04:10.013 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.090 - 59: DEBUG: new connection from 127.0.0.1:35658 389s app_test.go:1207: 06:04:10.091 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.156 - 59: DEBUG: new connection from 127.0.0.1:35670 389s app_test.go:1207: 06:04:10.157 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.029 - 59: DEBUG: new connection from 127.0.0.1:35674 389s app_test.go:1207: 06:04:10.031 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.035 - 60: DEBUG: new connection from 127.0.0.1:56778 389s app_test.go:1207: 06:04:10.036 - 59: DEBUG: new connection from 127.0.0.1:35684 389s app_test.go:1207: 06:04:10.038 - 62: DEBUG: new connection from 127.0.0.1:54840 389s app_test.go:1207: 06:04:10.038 - 63: DEBUG: new connection from 127.0.0.1:39462 389s app_test.go:1207: 06:04:10.040 - 64: DEBUG: new connection from 127.0.0.1:51414 389s app_test.go:1207: 06:04:10.058 - 59: DEBUG: new connection from 127.0.0.1:35696 389s app_test.go:1207: 06:04:10.059 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.019 - 59: DEBUG: new connection from 127.0.0.1:35706 389s app_test.go:1207: 06:04:10.021 - 62: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.096 - 59: DEBUG: new connection from 127.0.0.1:35720 389s app_test.go:1207: 06:04:10.097 - 63: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.164 - 59: DEBUG: new connection from 127.0.0.1:35722 389s app_test.go:1207: 06:04:10.166 - 64: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.044 - 59: DEBUG: new connection from 127.0.0.1:35736 389s app_test.go:1207: 06:04:10.046 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.053 - 60: DEBUG: new connection from 127.0.0.1:56780 389s app_test.go:1207: 06:04:10.054 - 59: DEBUG: new connection from 127.0.0.1:35744 389s app_test.go:1207: 06:04:10.055 - 62: DEBUG: new connection from 127.0.0.1:54842 389s app_test.go:1207: 06:04:10.056 - 63: DEBUG: new connection from 127.0.0.1:39478 389s app_test.go:1207: 06:04:10.056 - 64: DEBUG: new connection from 127.0.0.1:51420 389s app_test.go:1207: 06:04:10.062 - 59: DEBUG: new connection from 127.0.0.1:35750 389s app_test.go:1207: 06:04:10.063 - 60: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.182 - 63: DEBUG: new connection from 127.0.0.1:39484 389s app_test.go:1207: 06:04:10.182 - 62: DEBUG: new connection from 127.0.0.1:54848 389s app_test.go:1207: 06:04:10.182 - 60: DEBUG: new connection from 127.0.0.1:56792 389s app_test.go:1207: 06:04:10.182 - 59: DEBUG: new connection from 127.0.0.1:35754 389s app_test.go:1207: 06:04:10.182 - 64: DEBUG: new connection from 127.0.0.1:51422 389s app_test.go:1207: 06:04:10.182 - 59: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 389s app_test.go:1207: 06:04:10.185 - 59: WARN: attempt 1: server 127.0.0.1:9004: no known leader 389s app_test.go:1207: 06:04:10.186 - 59: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:04:10.186 - 59: DEBUG: new connection from 127.0.0.1:35766 389s app_test.go:1207: 06:04:10.186 - 59: DEBUG: attempt 1: server 127.0.0.1:9006: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:04:10.187 - 59: DEBUG: new connection from 127.0.0.1:35768 389s app_test.go:1207: 06:04:10.187 - 59: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.187 - 59: WARN: attempt 1: server 127.0.0.1:9005: no known leader 389s --- PASS: TestRolesAdjustment_ReplaceVoterHonorFailureDomain (20.53s) 389s === RUN TestRolesAdjustment_ImbalancedFailureDomain 389s app_test.go:1207: 06:04:10.492 - 65: DEBUG: new connection from 127.0.0.1:35776 389s app_test.go:1207: 06:04:10.494 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.496 - 65: DEBUG: new connection from 127.0.0.1:35780 389s app_test.go:1207: 06:04:10.546 - 65: DEBUG: new connection from 127.0.0.1:35788 389s app_test.go:1207: 06:04:10.550 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.570 - 66: DEBUG: new connection from 127.0.0.1:56804 389s app_test.go:1207: 06:04:10.570 - 65: DEBUG: new connection from 127.0.0.1:35804 389s app_test.go:1207: 06:04:10.621 - 65: DEBUG: new connection from 127.0.0.1:35818 389s app_test.go:1207: 06:04:10.623 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.629 - 66: DEBUG: new connection from 127.0.0.1:56814 389s app_test.go:1207: 06:04:10.629 - 65: DEBUG: new connection from 127.0.0.1:35826 389s app_test.go:1207: 06:04:10.630 - 67: DEBUG: new connection from 127.0.0.1:43920 389s app_test.go:1207: 06:04:10.632 - 67: DEBUG: new connection from 127.0.0.1:43922 389s app_test.go:1207: 06:04:10.636 - 65: DEBUG: new connection from 127.0.0.1:35828 389s app_test.go:1207: 06:04:10.451 - 66: DEBUG: new connection from 127.0.0.1:56820 389s app_test.go:1207: 06:04:10.461 - 65: DEBUG: new connection from 127.0.0.1:35844 389s app_test.go:1207: 06:04:10.509 - 65: DEBUG: new connection from 127.0.0.1:35858 389s app_test.go:1207: 06:04:10.511 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.520 - 66: DEBUG: new connection from 127.0.0.1:56822 389s app_test.go:1207: 06:04:10.520 - 65: DEBUG: new connection from 127.0.0.1:35874 389s app_test.go:1207: 06:04:10.521 - 67: DEBUG: new connection from 127.0.0.1:43938 389s app_test.go:1207: 06:04:10.522 - 68: DEBUG: new connection from 127.0.0.1:54858 389s app_test.go:1207: 06:04:10.524 - 68: DEBUG: new connection from 127.0.0.1:54860 389s app_test.go:1207: 06:04:10.545 - 65: DEBUG: new connection from 127.0.0.1:35882 389s app_test.go:1207: 06:04:10.581 - 65: DEBUG: new connection from 127.0.0.1:35888 389s app_test.go:1207: 06:04:10.583 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.592 - 65: DEBUG: new connection from 127.0.0.1:35898 389s app_test.go:1207: 06:04:10.592 - 66: DEBUG: new connection from 127.0.0.1:56824 389s app_test.go:1207: 06:04:10.595 - 67: DEBUG: new connection from 127.0.0.1:43942 389s app_test.go:1207: 06:04:10.597 - 68: DEBUG: new connection from 127.0.0.1:54864 389s app_test.go:1207: 06:04:10.598 - 69: DEBUG: new connection from 127.0.0.1:39492 389s app_test.go:1207: 06:04:10.601 - 69: DEBUG: new connection from 127.0.0.1:39500 389s app_test.go:1207: 06:04:10.616 - 65: DEBUG: new connection from 127.0.0.1:35904 389s app_test.go:1207: 06:04:10.661 - 65: DEBUG: new connection from 127.0.0.1:35916 389s app_test.go:1207: 06:04:10.663 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.670 - 65: DEBUG: new connection from 127.0.0.1:35920 389s app_test.go:1207: 06:04:10.670 - 66: DEBUG: new connection from 127.0.0.1:56828 389s app_test.go:1207: 06:04:10.670 - 67: DEBUG: new connection from 127.0.0.1:43944 389s app_test.go:1207: 06:04:10.673 - 68: DEBUG: new connection from 127.0.0.1:54874 389s app_test.go:1207: 06:04:10.674 - 69: DEBUG: new connection from 127.0.0.1:39504 389s app_test.go:1207: 06:04:10.675 - 70: DEBUG: new connection from 127.0.0.1:51434 389s app_test.go:1207: 06:04:10.677 - 70: DEBUG: new connection from 127.0.0.1:51450 389s app_test.go:1207: 06:04:10.685 - 65: DEBUG: new connection from 127.0.0.1:35930 389s app_test.go:1207: 06:04:10.734 - 65: DEBUG: new connection from 127.0.0.1:35934 389s app_test.go:1207: 06:04:10.737 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.748 - 65: DEBUG: new connection from 127.0.0.1:35950 389s app_test.go:1207: 06:04:10.748 - 66: DEBUG: new connection from 127.0.0.1:56834 389s app_test.go:1207: 06:04:10.749 - 67: DEBUG: new connection from 127.0.0.1:43958 389s app_test.go:1207: 06:04:10.750 - 68: DEBUG: new connection from 127.0.0.1:54886 389s app_test.go:1207: 06:04:10.753 - 69: DEBUG: new connection from 127.0.0.1:39518 389s app_test.go:1207: 06:04:10.756 - 70: DEBUG: new connection from 127.0.0.1:51454 389s app_test.go:1207: 06:04:10.757 - 71: DEBUG: new connection from 127.0.0.1:58320 389s app_test.go:1207: 06:04:10.810 - 65: DEBUG: new connection from 127.0.0.1:35958 389s app_test.go:1207: 06:04:10.812 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.819 - 66: DEBUG: new connection from 127.0.0.1:56838 389s app_test.go:1207: 06:04:10.819 - 65: DEBUG: new connection from 127.0.0.1:35964 389s app_test.go:1207: 06:04:10.819 - 67: DEBUG: new connection from 127.0.0.1:43966 389s app_test.go:1207: 06:04:10.820 - 68: DEBUG: new connection from 127.0.0.1:54894 389s app_test.go:1207: 06:04:10.821 - 69: DEBUG: new connection from 127.0.0.1:39534 389s app_test.go:1207: 06:04:10.822 - 70: DEBUG: new connection from 127.0.0.1:51468 389s app_test.go:1207: 06:04:10.823 - 71: DEBUG: new connection from 127.0.0.1:58330 389s app_test.go:1207: 06:04:10.824 - 72: DEBUG: new connection from 127.0.0.1:55532 389s app_test.go:1207: 06:04:10.498 - 65: DEBUG: new connection from 127.0.0.1:60124 389s app_test.go:1207: 06:04:10.500 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.518 - 65: DEBUG: new connection from 127.0.0.1:60126 389s app_test.go:1207: 06:04:10.518 - 66: DEBUG: new connection from 127.0.0.1:54310 389s app_test.go:1207: 06:04:10.520 - 67: DEBUG: new connection from 127.0.0.1:46292 389s app_test.go:1207: 06:04:10.520 - 68: DEBUG: new connection from 127.0.0.1:34444 389s app_test.go:1207: 06:04:10.522 - 69: DEBUG: new connection from 127.0.0.1:59048 389s app_test.go:1207: 06:04:10.522 - 70: DEBUG: new connection from 127.0.0.1:33036 389s app_test.go:1207: 06:04:10.523 - 71: DEBUG: new connection from 127.0.0.1:58546 389s app_test.go:1207: 06:04:10.525 - 72: DEBUG: new connection from 127.0.0.1:50604 389s app_test.go:1207: 06:04:10.527 - 72: DEBUG: new connection from 127.0.0.1:50620 389s app_test.go:1207: 06:04:10.536 - 65: DEBUG: new connection from 127.0.0.1:60132 389s app_test.go:1207: 06:04:10.572 - 65: DEBUG: new connection from 127.0.0.1:60146 389s app_test.go:1207: 06:04:10.573 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.460 - 66: DEBUG: new connection from 127.0.0.1:54316 389s app_test.go:1207: 06:04:10.461 - 65: DEBUG: new connection from 127.0.0.1:60152 389s app_test.go:1207: 06:04:10.463 - 67: DEBUG: new connection from 127.0.0.1:46306 389s app_test.go:1207: 06:04:10.464 - 68: DEBUG: new connection from 127.0.0.1:34448 389s app_test.go:1207: 06:04:10.467 - 69: DEBUG: new connection from 127.0.0.1:59062 389s app_test.go:1207: 06:04:10.469 - 70: DEBUG: new connection from 127.0.0.1:33042 389s app_test.go:1207: 06:04:10.469 - 71: DEBUG: new connection from 127.0.0.1:58556 389s app_test.go:1207: 06:04:10.471 - 72: DEBUG: new connection from 127.0.0.1:50628 389s app_test.go:1207: 06:04:10.475 - 66: DEBUG: new connection from 127.0.0.1:54322 389s app_test.go:1207: 06:04:10.475 - 65: DEBUG: new connection from 127.0.0.1:60168 389s app_test.go:1207: 06:04:10.478 - 67: DEBUG: new connection from 127.0.0.1:46318 389s app_test.go:1207: 06:04:10.478 - 68: DEBUG: new connection from 127.0.0.1:34454 389s app_test.go:1207: 06:04:10.480 - 70: DEBUG: new connection from 127.0.0.1:33052 389s app_test.go:1207: 06:04:10.480 - 69: DEBUG: new connection from 127.0.0.1:59066 389s app_test.go:1207: 06:04:10.482 - 71: DEBUG: new connection from 127.0.0.1:58562 389s app_test.go:1207: 06:04:10.482 - 72: DEBUG: new connection from 127.0.0.1:50634 389s app_test.go:1207: 06:04:10.447 - 65: DEBUG: new connection from 127.0.0.1:60180 389s app_test.go:1207: 06:04:10.448 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.525 - 65: DEBUG: new connection from 127.0.0.1:60184 389s app_test.go:1207: 06:04:10.526 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.604 - 65: DEBUG: new connection from 127.0.0.1:60200 389s app_test.go:1207: 06:04:10.605 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.679 - 65: DEBUG: new connection from 127.0.0.1:60204 389s app_test.go:1207: 06:04:10.680 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.759 - 65: DEBUG: new connection from 127.0.0.1:60214 389s app_test.go:1207: 06:04:10.762 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.826 - 65: DEBUG: new connection from 127.0.0.1:60224 389s app_test.go:1207: 06:04:10.830 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.582 - 65: DEBUG: new connection from 127.0.0.1:60228 389s app_test.go:1207: 06:04:10.583 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.485 - 65: DEBUG: new connection from 127.0.0.1:60236 389s app_test.go:1207: 06:04:10.488 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.494 - 65: DEBUG: new connection from 127.0.0.1:60240 389s app_test.go:1207: 06:04:10.494 - 66: DEBUG: new connection from 127.0.0.1:54324 389s app_test.go:1207: 06:04:10.495 - 67: DEBUG: new connection from 127.0.0.1:46328 389s app_test.go:1207: 06:04:10.496 - 68: DEBUG: new connection from 127.0.0.1:34460 389s app_test.go:1207: 06:04:10.496 - 69: DEBUG: new connection from 127.0.0.1:59070 389s app_test.go:1207: 06:04:10.497 - 70: DEBUG: new connection from 127.0.0.1:33056 389s app_test.go:1207: 06:04:10.498 - 71: DEBUG: new connection from 127.0.0.1:58570 389s app_test.go:1207: 06:04:10.499 - 72: DEBUG: new connection from 127.0.0.1:50636 389s app_test.go:1207: 06:04:10.514 - 67: DEBUG: new connection from 127.0.0.1:46334 389s app_test.go:1207: 06:04:10.515 - 72: DEBUG: new connection from 127.0.0.1:50642 389s app_test.go:1207: 06:04:10.518 - 66: DEBUG: new connection from 127.0.0.1:54328 389s app_test.go:1207: 06:04:10.520 - 66: DEBUG: new connection from 127.0.0.1:54334 389s app_test.go:1207: 06:04:10.460 - 65: DEBUG: new connection from 127.0.0.1:60246 389s app_test.go:1207: 06:04:10.462 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.535 - 65: DEBUG: new connection from 127.0.0.1:60262 389s app_test.go:1207: 06:04:10.536 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.618 - 65: DEBUG: new connection from 127.0.0.1:60272 389s app_test.go:1207: 06:04:10.619 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.693 - 65: DEBUG: new connection from 127.0.0.1:60286 389s app_test.go:1207: 06:04:10.694 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.773 - 65: DEBUG: new connection from 127.0.0.1:60296 389s app_test.go:1207: 06:04:10.774 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.844 - 65: DEBUG: new connection from 127.0.0.1:60310 389s app_test.go:1207: 06:04:10.846 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.591 - 65: DEBUG: new connection from 127.0.0.1:60318 389s app_test.go:1207: 06:04:10.593 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.503 - 65: DEBUG: new connection from 127.0.0.1:35490 389s app_test.go:1207: 06:04:10.506 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.520 - 66: DEBUG: new connection from 127.0.0.1:45916 389s app_test.go:1207: 06:04:10.521 - 65: DEBUG: new connection from 127.0.0.1:35502 389s app_test.go:1207: 06:04:10.523 - 67: DEBUG: new connection from 127.0.0.1:39758 389s app_test.go:1207: 06:04:10.524 - 68: DEBUG: new connection from 127.0.0.1:50632 389s app_test.go:1207: 06:04:10.525 - 69: DEBUG: new connection from 127.0.0.1:60656 389s app_test.go:1207: 06:04:10.525 - 70: DEBUG: new connection from 127.0.0.1:36814 389s app_test.go:1207: 06:04:10.527 - 71: DEBUG: new connection from 127.0.0.1:48920 389s app_test.go:1207: 06:04:10.527 - 72: DEBUG: new connection from 127.0.0.1:38736 389s app_test.go:1207: 06:04:10.471 - 65: DEBUG: new connection from 127.0.0.1:35518 389s app_test.go:1207: 06:04:10.472 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.547 - 65: DEBUG: new connection from 127.0.0.1:35530 389s app_test.go:1207: 06:04:10.547 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.628 - 65: DEBUG: new connection from 127.0.0.1:35532 389s app_test.go:1207: 06:04:10.629 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.708 - 65: DEBUG: new connection from 127.0.0.1:35534 389s app_test.go:1207: 06:04:10.709 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.780 - 65: DEBUG: new connection from 127.0.0.1:35538 389s app_test.go:1207: 06:04:10.782 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.854 - 65: DEBUG: new connection from 127.0.0.1:35542 389s app_test.go:1207: 06:04:10.859 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.607 - 65: DEBUG: new connection from 127.0.0.1:35556 389s app_test.go:1207: 06:04:10.607 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.530 - 65: DEBUG: new connection from 127.0.0.1:35558 389s app_test.go:1207: 06:04:10.534 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.540 - 66: DEBUG: new connection from 127.0.0.1:45922 389s app_test.go:1207: 06:04:10.540 - 65: DEBUG: new connection from 127.0.0.1:35574 389s app_test.go:1207: 06:04:10.541 - 67: DEBUG: new connection from 127.0.0.1:39760 389s app_test.go:1207: 06:04:10.542 - 68: DEBUG: new connection from 127.0.0.1:50640 389s app_test.go:1207: 06:04:10.543 - 69: DEBUG: new connection from 127.0.0.1:60664 389s app_test.go:1207: 06:04:10.544 - 70: DEBUG: new connection from 127.0.0.1:36822 389s app_test.go:1207: 06:04:10.545 - 71: DEBUG: new connection from 127.0.0.1:48932 389s app_test.go:1207: 06:04:10.545 - 72: DEBUG: new connection from 127.0.0.1:38738 389s app_test.go:1207: 06:04:10.479 - 65: DEBUG: new connection from 127.0.0.1:35578 389s app_test.go:1207: 06:04:10.481 - 67: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.555 - 65: DEBUG: new connection from 127.0.0.1:35582 389s app_test.go:1207: 06:04:10.556 - 68: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.633 - 65: DEBUG: new connection from 127.0.0.1:35598 389s app_test.go:1207: 06:04:10.633 - 69: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.717 - 65: DEBUG: new connection from 127.0.0.1:35610 389s app_test.go:1207: 06:04:10.721 - 70: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.794 - 65: DEBUG: new connection from 127.0.0.1:35622 389s app_test.go:1207: 06:04:10.795 - 71: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.869 - 65: DEBUG: new connection from 127.0.0.1:35632 389s app_test.go:1207: 06:04:10.870 - 72: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.613 - 65: DEBUG: new connection from 127.0.0.1:35648 389s app_test.go:1207: 06:04:10.614 - 66: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.831 - 66: DEBUG: new connection from 127.0.0.1:45932 389s app_test.go:1207: 06:04:10.831 - 72: DEBUG: new connection from 127.0.0.1:38750 389s app_test.go:1207: 06:04:10.831 - 70: DEBUG: new connection from 127.0.0.1:36826 389s app_test.go:1207: 06:04:10.831 - 67: DEBUG: new connection from 127.0.0.1:39766 389s app_test.go:1207: 06:04:10.831 - 69: DEBUG: new connection from 127.0.0.1:60676 389s app_test.go:1207: 06:04:10.831 - 65: DEBUG: new connection from 127.0.0.1:35650 389s app_test.go:1207: 06:04:10.831 - 68: DEBUG: new connection from 127.0.0.1:50650 389s app_test.go:1207: 06:04:10.831 - 71: DEBUG: new connection from 127.0.0.1:48938 389s app_test.go:1207: 06:04:10.835 - 65: WARN: attempt 1: server 127.0.0.1:9007: no known leader 389s app_test.go:1207: 06:04:10.835 - 65: WARN: attempt 1: server 127.0.0.1:9004: no known leader 389s app_test.go:1207: 06:04:10.835 - 65: WARN: attempt 1: server 127.0.0.1:9005: no known leader 389s app_test.go:1207: 06:04:10.836 - 65: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.836 - 65: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:04:10.836 - 65: WARN: attempt 1: server 127.0.0.1:9001: context canceled 389s app_test.go:1207: 06:04:10.837 - 65: WARN: attempt 1: server 127.0.0.1:9006: no known leader 389s app_test.go:1207: 06:04:10.837 - 65: DEBUG: attempt 1: server 127.0.0.1:9008: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:04:10.837 - 65: WARN: attempt 1: server 127.0.0.1:9001: context canceled 389s app_test.go:1207: 06:04:10.837 - 65: WARN: attempt 1: server 127.0.0.1:9002: no known leader 389s --- PASS: TestRolesAdjustment_ImbalancedFailureDomain (20.68s) 389s === RUN TestRolesAdjustment_ReplaceVoterHonorWeight 389s app_test.go:1207: 06:04:10.148 - 73: DEBUG: new connection from 127.0.0.1:35664 389s app_test.go:1207: 06:04:10.150 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.154 - 73: DEBUG: new connection from 127.0.0.1:35674 389s app_test.go:1207: 06:04:10.213 - 73: DEBUG: new connection from 127.0.0.1:35676 389s app_test.go:1207: 06:04:10.215 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.229 - 74: DEBUG: new connection from 127.0.0.1:45938 389s app_test.go:1207: 06:04:10.229 - 73: DEBUG: new connection from 127.0.0.1:35692 389s app_test.go:1207: 06:04:10.274 - 73: DEBUG: new connection from 127.0.0.1:35696 389s app_test.go:1207: 06:04:10.276 - 75: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.281 - 74: DEBUG: new connection from 127.0.0.1:45952 389s app_test.go:1207: 06:04:10.281 - 73: DEBUG: new connection from 127.0.0.1:35712 389s app_test.go:1207: 06:04:10.282 - 75: DEBUG: new connection from 127.0.0.1:39782 389s app_test.go:1207: 06:04:10.284 - 75: DEBUG: new connection from 127.0.0.1:39796 389s app_test.go:1207: 06:04:10.288 - 73: DEBUG: new connection from 127.0.0.1:35726 389s app_test.go:1207: 06:04:10.129 - 74: DEBUG: new connection from 127.0.0.1:45956 389s app_test.go:1207: 06:04:10.135 - 73: DEBUG: new connection from 127.0.0.1:35728 389s app_test.go:1207: 06:04:10.178 - 73: DEBUG: new connection from 127.0.0.1:35388 389s app_test.go:1207: 06:04:10.180 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.186 - 73: DEBUG: new connection from 127.0.0.1:35398 389s app_test.go:1207: 06:04:10.186 - 74: DEBUG: new connection from 127.0.0.1:51374 389s app_test.go:1207: 06:04:10.188 - 75: DEBUG: new connection from 127.0.0.1:58632 389s app_test.go:1207: 06:04:10.190 - 76: DEBUG: new connection from 127.0.0.1:56854 389s app_test.go:1207: 06:04:10.192 - 76: DEBUG: new connection from 127.0.0.1:56860 389s app_test.go:1207: 06:04:10.203 - 73: DEBUG: new connection from 127.0.0.1:35404 389s app_test.go:1207: 06:04:10.253 - 73: DEBUG: new connection from 127.0.0.1:35420 389s app_test.go:1207: 06:04:10.256 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.262 - 73: DEBUG: new connection from 127.0.0.1:35422 389s app_test.go:1207: 06:04:10.262 - 74: DEBUG: new connection from 127.0.0.1:51384 389s app_test.go:1207: 06:04:10.263 - 75: DEBUG: new connection from 127.0.0.1:58642 389s app_test.go:1207: 06:04:10.264 - 76: DEBUG: new connection from 127.0.0.1:56866 389s app_test.go:1207: 06:04:10.264 - 77: DEBUG: new connection from 127.0.0.1:53450 389s app_test.go:1207: 06:04:10.266 - 77: DEBUG: new connection from 127.0.0.1:53456 389s app_test.go:1207: 06:04:10.283 - 73: DEBUG: new connection from 127.0.0.1:35432 389s app_test.go:1207: 06:04:10.329 - 73: DEBUG: new connection from 127.0.0.1:35440 389s app_test.go:1207: 06:04:10.331 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.337 - 73: DEBUG: new connection from 127.0.0.1:35450 389s app_test.go:1207: 06:04:10.337 - 74: DEBUG: new connection from 127.0.0.1:51400 389s app_test.go:1207: 06:04:10.339 - 75: DEBUG: new connection from 127.0.0.1:58646 389s app_test.go:1207: 06:04:10.341 - 76: DEBUG: new connection from 127.0.0.1:56872 389s app_test.go:1207: 06:04:10.343 - 77: DEBUG: new connection from 127.0.0.1:53464 389s app_test.go:1207: 06:04:10.344 - 78: DEBUG: new connection from 127.0.0.1:46928 389s app_test.go:1207: 06:04:10.346 - 78: DEBUG: new connection from 127.0.0.1:46942 389s app_test.go:1207: 06:04:10.354 - 73: DEBUG: new connection from 127.0.0.1:35466 389s app_test.go:1207: 06:04:10.156 - 73: DEBUG: new connection from 127.0.0.1:35482 389s app_test.go:1207: 06:04:10.159 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.161 - 73: DEBUG: new connection from 127.0.0.1:35484 389s app_test.go:1207: 06:04:10.161 - 74: DEBUG: new connection from 127.0.0.1:51402 389s app_test.go:1207: 06:04:10.162 - 76: DEBUG: new connection from 127.0.0.1:56888 389s app_test.go:1207: 06:04:10.163 - 77: DEBUG: new connection from 127.0.0.1:53480 389s app_test.go:1207: 06:04:10.164 - 78: DEBUG: new connection from 127.0.0.1:46950 389s app_test.go:1207: 06:04:10.169 - 73: DEBUG: new connection from 127.0.0.1:35498 389s app_test.go:1207: 06:04:10.169 - 74: DEBUG: new connection from 127.0.0.1:51408 389s app_test.go:1207: 06:04:10.173 - 76: DEBUG: new connection from 127.0.0.1:56896 389s app_test.go:1207: 06:04:10.173 - 77: DEBUG: new connection from 127.0.0.1:53496 389s app_test.go:1207: 06:04:10.175 - 78: DEBUG: new connection from 127.0.0.1:46958 389s app_test.go:1207: 06:04:10.181 - 73: DEBUG: new connection from 127.0.0.1:35512 389s app_test.go:1207: 06:04:10.181 - 74: DEBUG: new connection from 127.0.0.1:51418 389s app_test.go:1207: 06:04:10.182 - 76: DEBUG: new connection from 127.0.0.1:56906 389s app_test.go:1207: 06:04:10.183 - 77: DEBUG: new connection from 127.0.0.1:53498 389s app_test.go:1207: 06:04:10.184 - 78: DEBUG: new connection from 127.0.0.1:46966 389s app_test.go:1207: 06:04:10.232 - 73: DEBUG: new connection from 127.0.0.1:35522 389s app_test.go:1207: 06:04:10.234 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.196 - 73: DEBUG: new connection from 127.0.0.1:35532 389s app_test.go:1207: 06:04:10.198 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.269 - 73: DEBUG: new connection from 127.0.0.1:35536 389s app_test.go:1207: 06:04:10.270 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.350 - 73: DEBUG: new connection from 127.0.0.1:35544 389s app_test.go:1207: 06:04:10.351 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.186 - 73: DEBUG: new connection from 127.0.0.1:35556 389s app_test.go:1207: 06:04:10.188 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.204 - 73: DEBUG: new connection from 127.0.0.1:35568 389s app_test.go:1207: 06:04:10.205 - 74: DEBUG: new connection from 127.0.0.1:51428 389s app_test.go:1207: 06:04:10.206 - 76: DEBUG: new connection from 127.0.0.1:56918 389s app_test.go:1207: 06:04:10.206 - 77: DEBUG: new connection from 127.0.0.1:53500 389s app_test.go:1207: 06:04:10.208 - 78: DEBUG: new connection from 127.0.0.1:46974 389s app_test.go:1207: 06:04:10.243 - 73: DEBUG: new connection from 127.0.0.1:35582 389s app_test.go:1207: 06:04:10.244 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.204 - 73: DEBUG: new connection from 127.0.0.1:35584 389s app_test.go:1207: 06:04:10.205 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.277 - 73: DEBUG: new connection from 127.0.0.1:35596 389s app_test.go:1207: 06:04:10.278 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.354 - 73: DEBUG: new connection from 127.0.0.1:35610 389s app_test.go:1207: 06:04:10.355 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.213 - 73: DEBUG: new connection from 127.0.0.1:33214 389s app_test.go:1207: 06:04:10.219 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.232 - 73: DEBUG: new connection from 127.0.0.1:33226 389s app_test.go:1207: 06:04:10.232 - 74: DEBUG: new connection from 127.0.0.1:35742 389s app_test.go:1207: 06:04:10.233 - 76: DEBUG: new connection from 127.0.0.1:33794 389s app_test.go:1207: 06:04:10.233 - 77: DEBUG: new connection from 127.0.0.1:56734 389s app_test.go:1207: 06:04:10.234 - 78: DEBUG: new connection from 127.0.0.1:37094 389s app_test.go:1207: 06:04:10.249 - 73: DEBUG: new connection from 127.0.0.1:33234 389s app_test.go:1207: 06:04:10.249 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.211 - 73: DEBUG: new connection from 127.0.0.1:33246 389s app_test.go:1207: 06:04:10.212 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.294 - 73: DEBUG: new connection from 127.0.0.1:33258 389s app_test.go:1207: 06:04:10.295 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.368 - 73: DEBUG: new connection from 127.0.0.1:33260 389s app_test.go:1207: 06:04:10.369 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.237 - 73: DEBUG: new connection from 127.0.0.1:33276 389s app_test.go:1207: 06:04:10.241 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.252 - 73: DEBUG: new connection from 127.0.0.1:33278 389s app_test.go:1207: 06:04:10.252 - 74: DEBUG: new connection from 127.0.0.1:35750 389s app_test.go:1207: 06:04:10.253 - 76: DEBUG: new connection from 127.0.0.1:33806 389s app_test.go:1207: 06:04:10.253 - 73: DEBUG: new connection from 127.0.0.1:33280 389s app_test.go:1207: 06:04:10.255 - 77: DEBUG: new connection from 127.0.0.1:56740 389s app_test.go:1207: 06:04:10.255 - 78: DEBUG: new connection from 127.0.0.1:37110 389s app_test.go:1207: 06:04:10.257 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.218 - 73: DEBUG: new connection from 127.0.0.1:33290 389s app_test.go:1207: 06:04:10.219 - 76: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.301 - 73: DEBUG: new connection from 127.0.0.1:33298 389s app_test.go:1207: 06:04:10.305 - 77: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.378 - 73: DEBUG: new connection from 127.0.0.1:33308 389s app_test.go:1207: 06:04:10.381 - 78: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.260 - 73: DEBUG: new connection from 127.0.0.1:33314 389s app_test.go:1207: 06:04:10.262 - 73: DEBUG: new connection from 127.0.0.1:33316 389s app_test.go:1207: 06:04:10.262 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.262 - 74: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.268 - 74: DEBUG: new connection from 127.0.0.1:35764 389s app_test.go:1207: 06:04:10.268 - 73: DEBUG: new connection from 127.0.0.1:33318 389s app_test.go:1207: 06:04:10.269 - 76: DEBUG: new connection from 127.0.0.1:33816 389s app_test.go:1207: 06:04:10.270 - 77: DEBUG: new connection from 127.0.0.1:56750 389s app_test.go:1207: 06:04:10.271 - 78: DEBUG: new connection from 127.0.0.1:37116 389s app_test.go:1207: 06:04:10.387 - 76: DEBUG: new connection from 127.0.0.1:33818 389s app_test.go:1207: 06:04:10.388 - 73: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 389s app_test.go:1207: 06:04:10.388 - 74: DEBUG: new connection from 127.0.0.1:35770 389s app_test.go:1207: 06:04:10.388 - 78: DEBUG: new connection from 127.0.0.1:37122 389s app_test.go:1207: 06:04:10.388 - 73: DEBUG: new connection from 127.0.0.1:33324 389s app_test.go:1207: 06:04:10.390 - 77: DEBUG: new connection from 127.0.0.1:56766 389s app_test.go:1207: 06:04:10.394 - 73: WARN: attempt 1: server 127.0.0.1:9004: no known leader 389s app_test.go:1207: 06:04:10.396 - 73: DEBUG: attempt 1: server 127.0.0.1:9005: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:04:10.396 - 73: DEBUG: new connection from 127.0.0.1:33338 389s app_test.go:1207: 06:04:10.396 - 73: WARN: attempt 1: server 127.0.0.1:9006: no known leader 389s app_test.go:1207: 06:04:10.396 - 73: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.396 - 73: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:04:10.397 - 73: WARN: attempt 1: server 127.0.0.1:9001: context canceled 389s --- PASS: TestRolesAdjustment_ReplaceVoterHonorWeight (20.49s) 389s === RUN TestRolesAdjustment_CantReplaceVoter 389s app_test.go:1207: 06:04:10.681 - 79: DEBUG: new connection from 127.0.0.1:33352 389s app_test.go:1207: 06:04:10.683 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.685 - 79: DEBUG: new connection from 127.0.0.1:33354 389s app_test.go:1207: 06:04:10.733 - 79: DEBUG: new connection from 127.0.0.1:33356 389s app_test.go:1207: 06:04:10.737 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.750 - 80: DEBUG: new connection from 127.0.0.1:35778 389s app_test.go:1207: 06:04:10.750 - 79: DEBUG: new connection from 127.0.0.1:33360 389s app_test.go:1207: 06:04:10.801 - 79: DEBUG: new connection from 127.0.0.1:33372 389s app_test.go:1207: 06:04:10.805 - 81: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.813 - 80: DEBUG: new connection from 127.0.0.1:35780 389s app_test.go:1207: 06:04:10.813 - 79: DEBUG: new connection from 127.0.0.1:33388 389s app_test.go:1207: 06:04:10.816 - 81: DEBUG: new connection from 127.0.0.1:60040 389s app_test.go:1207: 06:04:10.819 - 81: DEBUG: new connection from 127.0.0.1:60044 389s app_test.go:1207: 06:04:10.825 - 79: DEBUG: new connection from 127.0.0.1:33396 389s app_test.go:1207: 06:04:10.621 - 80: DEBUG: new connection from 127.0.0.1:35796 389s app_test.go:1207: 06:04:10.626 - 79: DEBUG: new connection from 127.0.0.1:33410 389s app_test.go:1207: 06:04:10.678 - 79: DEBUG: new connection from 127.0.0.1:50066 389s app_test.go:1207: 06:04:10.680 - 82: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:04:10.685 - 80: DEBUG: new connection from 127.0.0.1:38030 389s app_test.go:1207: 06:04:10.685 - 79: DEBUG: new connection from 127.0.0.1:50068 389s app_test.go:1207: 06:04:10.686 - 81: DEBUG: new connection from 127.0.0.1:58654 389s app_test.go:1207: 06:04:10.687 - 82: DEBUG: new connection from 127.0.0.1:60686 389s app_test.go:1207: 06:04:10.691 - 82: DEBUG: new connection from 127.0.0.1:60700 389s app_test.go:1207: 06:04:10.686 - 79: DEBUG: new connection from 127.0.0.1:50084 389s app_test.go:1207: 06:04:10.688 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:04:10.698 - 80: DEBUG: new connection from 127.0.0.1:38032 389s app_test.go:1207: 06:04:10.698 - 79: DEBUG: new connection from 127.0.0.1:50100 389s app_test.go:1207: 06:04:10.752 - 79: DEBUG: new connection from 127.0.0.1:50114 389s app_test.go:1207: 06:04:10.753 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.701 - 79: DEBUG: new connection from 127.0.0.1:50122 389s app_test.go:1207: 06:05:10.704 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.711 - 80: DEBUG: new connection from 127.0.0.1:38034 389s app_test.go:1207: 06:05:10.711 - 79: DEBUG: new connection from 127.0.0.1:50136 389s app_test.go:1207: 06:05:10.776 - 79: DEBUG: new connection from 127.0.0.1:50138 389s app_test.go:1207: 06:05:10.777 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.714 - 79: DEBUG: new connection from 127.0.0.1:36218 389s app_test.go:1207: 06:05:10.716 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.731 - 80: DEBUG: new connection from 127.0.0.1:33498 389s app_test.go:1207: 06:05:10.731 - 79: DEBUG: new connection from 127.0.0.1:36220 389s app_test.go:1207: 06:05:10.795 - 79: DEBUG: new connection from 127.0.0.1:36232 389s app_test.go:1207: 06:05:10.800 - 80: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.722 - 79: WARN: attempt 1: server 127.0.0.1:9003: dial: dial tcp 127.0.0.1:9003: connect: connection refused 389s app_test.go:1207: 06:05:10.722 - 79: WARN: attempt 1: server 127.0.0.1:9004: dial: dial tcp 127.0.0.1:9004: connect: connection refused 389s app_test.go:1207: 06:05:10.722 - 79: DEBUG: new connection from 127.0.0.1:36242 389s app_test.go:1207: 06:05:10.722 - 80: DEBUG: new connection from 127.0.0.1:33512 389s app_test.go:1207: 06:05:10.723 - 79: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:05:10.723 - 79: DEBUG: new connection from 127.0.0.1:36258 389s app_test.go:1207: 06:05:10.724 - 79: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s --- PASS: TestRolesAdjustment_CantReplaceVoter (14.22s) 389s === RUN TestRolesAdjustment_ReplaceStandBy 389s app_test.go:1207: 06:05:10.876 - 83: DEBUG: new connection from 127.0.0.1:36260 389s app_test.go:1207: 06:05:10.879 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.885 - 83: DEBUG: new connection from 127.0.0.1:36268 389s app_test.go:1207: 06:05:10.937 - 83: DEBUG: new connection from 127.0.0.1:36280 389s app_test.go:1207: 06:05:10.939 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.952 - 83: DEBUG: new connection from 127.0.0.1:36288 389s app_test.go:1207: 06:05:10.952 - 84: DEBUG: new connection from 127.0.0.1:33524 389s app_test.go:1207: 06:05:10.025 - 83: DEBUG: new connection from 127.0.0.1:36292 389s app_test.go:1207: 06:05:10.028 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.033 - 84: DEBUG: new connection from 127.0.0.1:33540 389s app_test.go:1207: 06:05:10.033 - 83: DEBUG: new connection from 127.0.0.1:36294 389s app_test.go:1207: 06:05:10.034 - 85: DEBUG: new connection from 127.0.0.1:46908 389s app_test.go:1207: 06:05:10.036 - 85: DEBUG: new connection from 127.0.0.1:46914 389s app_test.go:1207: 06:05:10.040 - 83: DEBUG: new connection from 127.0.0.1:36296 389s app_test.go:1207: 06:05:10.832 - 84: DEBUG: new connection from 127.0.0.1:33550 389s app_test.go:1207: 06:05:10.839 - 83: DEBUG: new connection from 127.0.0.1:36310 389s app_test.go:1207: 06:05:10.881 - 83: DEBUG: new connection from 127.0.0.1:36316 389s app_test.go:1207: 06:05:10.884 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.892 - 84: DEBUG: new connection from 127.0.0.1:33564 389s app_test.go:1207: 06:05:10.892 - 83: DEBUG: new connection from 127.0.0.1:36332 389s app_test.go:1207: 06:05:10.893 - 85: DEBUG: new connection from 127.0.0.1:46926 389s app_test.go:1207: 06:05:10.895 - 86: DEBUG: new connection from 127.0.0.1:45050 389s app_test.go:1207: 06:05:10.897 - 86: DEBUG: new connection from 127.0.0.1:45056 389s app_test.go:1207: 06:05:10.908 - 83: DEBUG: new connection from 127.0.0.1:36336 389s app_test.go:1207: 06:05:10.953 - 83: DEBUG: new connection from 127.0.0.1:36348 389s app_test.go:1207: 06:05:10.956 - 87: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.961 - 83: DEBUG: new connection from 127.0.0.1:36358 389s app_test.go:1207: 06:05:10.961 - 84: DEBUG: new connection from 127.0.0.1:33568 389s app_test.go:1207: 06:05:10.962 - 86: DEBUG: new connection from 127.0.0.1:45064 389s app_test.go:1207: 06:05:10.962 - 85: DEBUG: new connection from 127.0.0.1:46940 389s app_test.go:1207: 06:05:10.965 - 87: DEBUG: new connection from 127.0.0.1:49212 389s app_test.go:1207: 06:05:10.968 - 87: DEBUG: new connection from 127.0.0.1:49226 389s app_test.go:1207: 06:05:10.975 - 83: DEBUG: new connection from 127.0.0.1:36368 389s app_test.go:1207: 06:05:10.025 - 83: DEBUG: new connection from 127.0.0.1:36380 389s app_test.go:1207: 06:05:10.030 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.033 - 83: DEBUG: new connection from 127.0.0.1:36384 389s app_test.go:1207: 06:05:10.033 - 84: DEBUG: new connection from 127.0.0.1:33576 389s app_test.go:1207: 06:05:10.034 - 85: DEBUG: new connection from 127.0.0.1:46946 389s app_test.go:1207: 06:05:10.035 - 86: DEBUG: new connection from 127.0.0.1:45074 389s app_test.go:1207: 06:05:10.036 - 87: DEBUG: new connection from 127.0.0.1:49232 389s app_test.go:1207: 06:05:10.037 - 88: DEBUG: new connection from 127.0.0.1:60832 389s app_test.go:1207: 06:05:10.039 - 88: DEBUG: new connection from 127.0.0.1:60846 389s app_test.go:1207: 06:05:10.050 - 83: DEBUG: new connection from 127.0.0.1:36392 389s app_test.go:1207: 06:05:10.097 - 83: DEBUG: new connection from 127.0.0.1:36408 389s app_test.go:1207: 06:05:10.099 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.106 - 84: DEBUG: new connection from 127.0.0.1:33582 389s app_test.go:1207: 06:05:10.106 - 83: DEBUG: new connection from 127.0.0.1:36410 389s app_test.go:1207: 06:05:10.107 - 85: DEBUG: new connection from 127.0.0.1:46962 389s app_test.go:1207: 06:05:10.108 - 86: DEBUG: new connection from 127.0.0.1:45080 389s app_test.go:1207: 06:05:10.108 - 87: DEBUG: new connection from 127.0.0.1:49238 389s app_test.go:1207: 06:05:10.110 - 88: DEBUG: new connection from 127.0.0.1:60858 389s app_test.go:1207: 06:05:10.110 - 89: DEBUG: new connection from 127.0.0.1:54234 389s app_test.go:1207: 06:05:10.888 - 83: DEBUG: new connection from 127.0.0.1:36420 389s app_test.go:1207: 06:05:10.890 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.895 - 84: DEBUG: new connection from 127.0.0.1:33598 389s app_test.go:1207: 06:05:10.895 - 83: DEBUG: new connection from 127.0.0.1:36428 389s app_test.go:1207: 06:05:10.897 - 85: DEBUG: new connection from 127.0.0.1:46972 389s app_test.go:1207: 06:05:10.899 - 86: DEBUG: new connection from 127.0.0.1:45092 389s app_test.go:1207: 06:05:10.900 - 88: DEBUG: new connection from 127.0.0.1:60870 389s app_test.go:1207: 06:05:10.901 - 89: DEBUG: new connection from 127.0.0.1:54238 389s app_test.go:1207: 06:05:10.903 - 89: DEBUG: new connection from 127.0.0.1:54244 389s app_test.go:1207: 06:05:10.905 - 84: DEBUG: new connection from 127.0.0.1:33604 389s app_test.go:1207: 06:05:10.905 - 83: DEBUG: new connection from 127.0.0.1:36430 389s app_test.go:1207: 06:05:10.906 - 85: DEBUG: new connection from 127.0.0.1:46986 389s app_test.go:1207: 06:05:10.907 - 86: DEBUG: new connection from 127.0.0.1:45106 389s app_test.go:1207: 06:05:10.908 - 88: DEBUG: new connection from 127.0.0.1:60874 389s app_test.go:1207: 06:05:10.909 - 89: DEBUG: new connection from 127.0.0.1:54260 389s app_test.go:1207: 06:05:10.909 - 83: DEBUG: new connection from 127.0.0.1:36438 389s app_test.go:1207: 06:05:10.914 - 83: DEBUG: new connection from 127.0.0.1:36448 389s app_test.go:1207: 06:05:10.914 - 84: DEBUG: new connection from 127.0.0.1:33610 389s app_test.go:1207: 06:05:10.916 - 85: DEBUG: new connection from 127.0.0.1:46992 389s app_test.go:1207: 06:05:10.916 - 86: DEBUG: new connection from 127.0.0.1:45120 389s app_test.go:1207: 06:05:10.918 - 88: DEBUG: new connection from 127.0.0.1:60890 389s app_test.go:1207: 06:05:10.918 - 89: DEBUG: new connection from 127.0.0.1:54266 389s app_test.go:1207: 06:05:10.954 - 83: DEBUG: new connection from 127.0.0.1:36462 389s app_test.go:1207: 06:05:10.955 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.834 - 83: DEBUG: new connection from 127.0.0.1:36470 389s app_test.go:1207: 06:05:10.837 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.900 - 83: DEBUG: new connection from 127.0.0.1:36486 389s app_test.go:1207: 06:05:10.901 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.041 - 83: DEBUG: new connection from 127.0.0.1:46414 389s app_test.go:1207: 06:05:10.042 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.113 - 83: DEBUG: new connection from 127.0.0.1:46430 389s app_test.go:1207: 06:05:10.114 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.921 - 83: DEBUG: new connection from 127.0.0.1:46436 389s app_test.go:1207: 06:05:10.925 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.932 - 83: DEBUG: new connection from 127.0.0.1:46450 389s app_test.go:1207: 06:05:10.932 - 84: DEBUG: new connection from 127.0.0.1:42786 389s app_test.go:1207: 06:05:10.935 - 85: DEBUG: new connection from 127.0.0.1:40376 389s app_test.go:1207: 06:05:10.935 - 86: DEBUG: new connection from 127.0.0.1:44798 389s app_test.go:1207: 06:05:10.937 - 88: DEBUG: new connection from 127.0.0.1:55418 389s app_test.go:1207: 06:05:10.937 - 89: DEBUG: new connection from 127.0.0.1:43598 389s app_test.go:1207: 06:05:10.962 - 83: DEBUG: new connection from 127.0.0.1:46462 389s app_test.go:1207: 06:05:10.963 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.843 - 83: DEBUG: new connection from 127.0.0.1:46476 389s app_test.go:1207: 06:05:10.844 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.910 - 83: DEBUG: new connection from 127.0.0.1:46488 389s app_test.go:1207: 06:05:10.912 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.052 - 83: DEBUG: new connection from 127.0.0.1:46496 389s app_test.go:1207: 06:05:10.053 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.121 - 83: DEBUG: new connection from 127.0.0.1:46508 389s app_test.go:1207: 06:05:10.124 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.940 - 83: DEBUG: new connection from 127.0.0.1:46512 389s app_test.go:1207: 06:05:10.943 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.949 - 83: DEBUG: new connection from 127.0.0.1:46524 389s app_test.go:1207: 06:05:10.949 - 84: DEBUG: new connection from 127.0.0.1:42790 389s app_test.go:1207: 06:05:10.950 - 85: DEBUG: new connection from 127.0.0.1:40388 389s app_test.go:1207: 06:05:10.952 - 86: DEBUG: new connection from 127.0.0.1:44812 389s app_test.go:1207: 06:05:10.952 - 88: DEBUG: new connection from 127.0.0.1:55428 389s app_test.go:1207: 06:05:10.955 - 89: DEBUG: new connection from 127.0.0.1:43606 389s app_test.go:1207: 06:05:10.972 - 83: DEBUG: new connection from 127.0.0.1:46536 389s app_test.go:1207: 06:05:10.976 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.859 - 83: DEBUG: new connection from 127.0.0.1:46546 389s app_test.go:1207: 06:05:10.860 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.924 - 83: DEBUG: new connection from 127.0.0.1:46560 389s app_test.go:1207: 06:05:10.926 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.063 - 83: DEBUG: new connection from 127.0.0.1:34554 389s app_test.go:1207: 06:05:10.065 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.130 - 83: DEBUG: new connection from 127.0.0.1:34562 389s app_test.go:1207: 06:05:10.132 - 89: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.958 - 83: DEBUG: new connection from 127.0.0.1:34570 389s app_test.go:1207: 06:05:10.960 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.973 - 83: DEBUG: new connection from 127.0.0.1:34572 389s app_test.go:1207: 06:05:10.974 - 84: DEBUG: new connection from 127.0.0.1:35316 389s app_test.go:1207: 06:05:10.974 - 85: DEBUG: new connection from 127.0.0.1:55688 389s app_test.go:1207: 06:05:10.977 - 86: DEBUG: new connection from 127.0.0.1:38794 389s app_test.go:1207: 06:05:10.978 - 88: DEBUG: new connection from 127.0.0.1:41210 389s app_test.go:1207: 06:05:10.979 - 89: DEBUG: new connection from 127.0.0.1:37624 389s app_test.go:1207: 06:05:10.983 - 83: DEBUG: new connection from 127.0.0.1:34574 389s app_test.go:1207: 06:05:10.986 - 84: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.868 - 83: DEBUG: new connection from 127.0.0.1:34588 389s app_test.go:1207: 06:05:10.869 - 85: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.936 - 83: DEBUG: new connection from 127.0.0.1:34594 389s app_test.go:1207: 06:05:10.938 - 86: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.073 - 83: DEBUG: new connection from 127.0.0.1:34596 389s app_test.go:1207: 06:05:10.079 - 88: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.114 - 89: DEBUG: new connection from 127.0.0.1:37634 389s app_test.go:1207: 06:05:10.114 - 85: DEBUG: new connection from 127.0.0.1:55700 389s app_test.go:1207: 06:05:10.114 - 84: DEBUG: new connection from 127.0.0.1:35330 389s app_test.go:1207: 06:05:10.114 - 83: DEBUG: new connection from 127.0.0.1:34606 389s app_test.go:1207: 06:05:10.115 - 83: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 389s app_test.go:1207: 06:05:10.115 - 88: DEBUG: new connection from 127.0.0.1:41220 389s app_test.go:1207: 06:05:10.115 - 86: DEBUG: new connection from 127.0.0.1:38810 389s app_test.go:1207: 06:05:10.118 - 83: WARN: attempt 1: server 127.0.0.1:9007: no known leader 389s app_test.go:1207: 06:05:10.118 - 83: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:05:10.118 - 83: DEBUG: new connection from 127.0.0.1:34608 389s app_test.go:1207: 06:05:10.120 - 83: WARN: attempt 1: server 127.0.0.1:9004: no known leader 389s app_test.go:1207: 06:05:10.120 - 83: WARN: attempt 1: server 127.0.0.1:9006: no known leader 389s app_test.go:1207: 06:05:10.120 - 83: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.121 - 83: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:05:10.121 - 83: WARN: attempt 1: server 127.0.0.1:9001: context canceled 389s --- PASS: TestRolesAdjustment_ReplaceStandBy (22.52s) 389s === RUN TestRolesAdjustment_ReplaceStandByHonorFailureDomains 389s app_test.go:1207: 06:05:10.454 - 90: DEBUG: new connection from 127.0.0.1:34610 389s app_test.go:1207: 06:05:10.459 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.469 - 90: DEBUG: new connection from 127.0.0.1:34624 389s app_test.go:1207: 06:05:10.542 - 90: DEBUG: new connection from 127.0.0.1:34636 389s app_test.go:1207: 06:05:10.546 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.592 - 91: DEBUG: new connection from 127.0.0.1:35346 389s app_test.go:1207: 06:05:10.593 - 90: DEBUG: new connection from 127.0.0.1:34648 389s app_test.go:1207: 06:05:10.681 - 90: DEBUG: new connection from 127.0.0.1:34652 389s app_test.go:1207: 06:05:10.683 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.702 - 90: DEBUG: new connection from 127.0.0.1:34658 389s app_test.go:1207: 06:05:10.703 - 91: DEBUG: new connection from 127.0.0.1:35348 389s app_test.go:1207: 06:05:10.705 - 92: DEBUG: new connection from 127.0.0.1:55716 389s app_test.go:1207: 06:05:10.708 - 92: DEBUG: new connection from 127.0.0.1:55730 389s app_test.go:1207: 06:05:10.720 - 90: DEBUG: new connection from 127.0.0.1:34664 389s app_test.go:1207: 06:05:10.403 - 91: DEBUG: new connection from 127.0.0.1:35362 389s app_test.go:1207: 06:05:10.415 - 90: DEBUG: new connection from 127.0.0.1:34672 389s app_test.go:1207: 06:05:10.457 - 90: DEBUG: new connection from 127.0.0.1:34686 389s app_test.go:1207: 06:05:10.461 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.477 - 90: DEBUG: new connection from 127.0.0.1:34694 389s app_test.go:1207: 06:05:10.477 - 91: DEBUG: new connection from 127.0.0.1:35376 389s app_test.go:1207: 06:05:10.479 - 92: DEBUG: new connection from 127.0.0.1:55740 389s app_test.go:1207: 06:05:10.480 - 93: DEBUG: new connection from 127.0.0.1:38824 389s app_test.go:1207: 06:05:10.483 - 93: DEBUG: new connection from 127.0.0.1:38838 389s app_test.go:1207: 06:05:10.505 - 90: DEBUG: new connection from 127.0.0.1:34698 389s app_test.go:1207: 06:05:10.566 - 90: DEBUG: new connection from 127.0.0.1:34710 389s app_test.go:1207: 06:05:10.569 - 94: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.578 - 91: DEBUG: new connection from 127.0.0.1:35390 389s app_test.go:1207: 06:05:10.578 - 90: DEBUG: new connection from 127.0.0.1:34712 389s app_test.go:1207: 06:05:10.583 - 92: DEBUG: new connection from 127.0.0.1:55746 389s app_test.go:1207: 06:05:10.583 - 93: DEBUG: new connection from 127.0.0.1:38848 389s app_test.go:1207: 06:05:10.584 - 94: DEBUG: new connection from 127.0.0.1:50448 389s app_test.go:1207: 06:05:10.589 - 94: DEBUG: new connection from 127.0.0.1:50458 389s app_test.go:1207: 06:05:10.616 - 90: DEBUG: new connection from 127.0.0.1:34718 389s app_test.go:1207: 06:05:10.678 - 90: DEBUG: new connection from 127.0.0.1:34728 389s app_test.go:1207: 06:05:10.681 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.697 - 91: DEBUG: new connection from 127.0.0.1:35400 389s app_test.go:1207: 06:05:10.697 - 90: DEBUG: new connection from 127.0.0.1:34730 389s app_test.go:1207: 06:05:10.698 - 92: DEBUG: new connection from 127.0.0.1:55750 389s app_test.go:1207: 06:05:10.699 - 93: DEBUG: new connection from 127.0.0.1:38858 389s app_test.go:1207: 06:05:10.701 - 94: DEBUG: new connection from 127.0.0.1:50472 389s app_test.go:1207: 06:05:10.703 - 95: DEBUG: new connection from 127.0.0.1:41222 389s app_test.go:1207: 06:05:10.706 - 95: DEBUG: new connection from 127.0.0.1:41228 389s app_test.go:1207: 06:05:10.729 - 90: DEBUG: new connection from 127.0.0.1:34732 389s app_test.go:1207: 06:05:10.783 - 90: DEBUG: new connection from 127.0.0.1:34740 389s app_test.go:1207: 06:05:10.785 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.800 - 90: DEBUG: new connection from 127.0.0.1:34748 389s app_test.go:1207: 06:05:10.800 - 91: DEBUG: new connection from 127.0.0.1:35404 389s app_test.go:1207: 06:05:10.802 - 92: DEBUG: new connection from 127.0.0.1:55756 389s app_test.go:1207: 06:05:10.802 - 93: DEBUG: new connection from 127.0.0.1:38872 389s app_test.go:1207: 06:05:10.803 - 94: DEBUG: new connection from 127.0.0.1:50482 389s app_test.go:1207: 06:05:10.804 - 95: DEBUG: new connection from 127.0.0.1:41236 389s app_test.go:1207: 06:05:10.807 - 96: DEBUG: new connection from 127.0.0.1:37646 389s app_test.go:1207: 06:05:10.891 - 90: DEBUG: new connection from 127.0.0.1:34758 389s app_test.go:1207: 06:05:10.894 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.907 - 90: DEBUG: new connection from 127.0.0.1:34766 389s app_test.go:1207: 06:05:10.907 - 91: DEBUG: new connection from 127.0.0.1:35408 389s app_test.go:1207: 06:05:10.909 - 92: DEBUG: new connection from 127.0.0.1:55764 389s app_test.go:1207: 06:05:10.909 - 93: DEBUG: new connection from 127.0.0.1:38880 389s app_test.go:1207: 06:05:10.911 - 94: DEBUG: new connection from 127.0.0.1:50496 389s app_test.go:1207: 06:05:10.911 - 95: DEBUG: new connection from 127.0.0.1:41250 389s app_test.go:1207: 06:05:10.913 - 96: DEBUG: new connection from 127.0.0.1:37652 389s app_test.go:1207: 06:05:10.913 - 97: DEBUG: new connection from 127.0.0.1:40364 389s app_test.go:1207: 06:05:10.978 - 90: DEBUG: new connection from 127.0.0.1:34780 389s app_test.go:1207: 06:05:10.980 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1207: 06:05:10.988 - 90: DEBUG: new connection from 127.0.0.1:34782 389s app_test.go:1207: 06:05:10.988 - 91: DEBUG: new connection from 127.0.0.1:35412 389s app_test.go:1207: 06:05:10.990 - 92: DEBUG: new connection from 127.0.0.1:55770 389s app_test.go:1207: 06:05:10.990 - 93: DEBUG: new connection from 127.0.0.1:38888 389s app_test.go:1207: 06:05:10.992 - 94: DEBUG: new connection from 127.0.0.1:50504 389s app_test.go:1207: 06:05:10.992 - 95: DEBUG: new connection from 127.0.0.1:41258 389s app_test.go:1207: 06:05:10.993 - 96: DEBUG: new connection from 127.0.0.1:37658 389s app_test.go:1207: 06:05:10.994 - 97: DEBUG: new connection from 127.0.0.1:40380 389s app_test.go:1207: 06:05:10.995 - 98: DEBUG: new connection from 127.0.0.1:46382 389s app_test.go:1207: 06:05:10.472 - 90: DEBUG: new connection from 127.0.0.1:49802 389s app_test.go:1207: 06:05:10.476 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.489 - 90: DEBUG: new connection from 127.0.0.1:49804 389s app_test.go:1207: 06:05:10.490 - 91: DEBUG: new connection from 127.0.0.1:36220 389s app_test.go:1207: 06:05:10.491 - 92: DEBUG: new connection from 127.0.0.1:37522 389s app_test.go:1207: 06:05:10.492 - 93: DEBUG: new connection from 127.0.0.1:58992 389s app_test.go:1207: 06:05:10.493 - 95: DEBUG: new connection from 127.0.0.1:53476 389s app_test.go:1207: 06:05:10.494 - 96: DEBUG: new connection from 127.0.0.1:56320 389s app_test.go:1207: 06:05:10.494 - 97: DEBUG: new connection from 127.0.0.1:36640 389s app_test.go:1207: 06:05:10.496 - 98: DEBUG: new connection from 127.0.0.1:48548 389s app_test.go:1207: 06:05:10.499 - 97: DEBUG: new connection from 127.0.0.1:36656 389s app_test.go:1207: 06:05:10.501 - 90: DEBUG: new connection from 127.0.0.1:49810 389s app_test.go:1207: 06:05:10.502 - 91: DEBUG: new connection from 127.0.0.1:36222 389s app_test.go:1207: 06:05:10.506 - 90: DEBUG: new connection from 127.0.0.1:49818 389s app_test.go:1207: 06:05:10.506 - 92: DEBUG: new connection from 127.0.0.1:37536 389s app_test.go:1207: 06:05:10.507 - 93: DEBUG: new connection from 127.0.0.1:58998 389s app_test.go:1207: 06:05:10.511 - 95: DEBUG: new connection from 127.0.0.1:53486 389s app_test.go:1207: 06:05:10.513 - 96: DEBUG: new connection from 127.0.0.1:56322 389s app_test.go:1207: 06:05:10.515 - 97: DEBUG: new connection from 127.0.0.1:36664 389s app_test.go:1207: 06:05:10.515 - 98: DEBUG: new connection from 127.0.0.1:48556 389s app_test.go:1207: 06:05:10.522 - 90: DEBUG: new connection from 127.0.0.1:49824 389s app_test.go:1207: 06:05:10.522 - 91: DEBUG: new connection from 127.0.0.1:36226 389s app_test.go:1207: 06:05:10.524 - 92: DEBUG: new connection from 127.0.0.1:37550 389s app_test.go:1207: 06:05:10.525 - 93: DEBUG: new connection from 127.0.0.1:59012 389s app_test.go:1207: 06:05:10.528 - 95: DEBUG: new connection from 127.0.0.1:53490 389s app_test.go:1207: 06:05:10.528 - 96: DEBUG: new connection from 127.0.0.1:56336 389s app_test.go:1207: 06:05:10.530 - 97: DEBUG: new connection from 127.0.0.1:36678 389s app_test.go:1207: 06:05:10.530 - 98: DEBUG: new connection from 127.0.0.1:48564 389s app_test.go:1207: 06:05:10.595 - 90: DEBUG: new connection from 127.0.0.1:49836 389s app_test.go:1207: 06:05:10.597 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.386 - 90: DEBUG: new connection from 127.0.0.1:49842 389s app_test.go:1207: 06:05:10.387 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.485 - 90: DEBUG: new connection from 127.0.0.1:49850 389s app_test.go:1207: 06:05:10.485 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.709 - 90: DEBUG: new connection from 127.0.0.1:49864 389s app_test.go:1207: 06:05:10.713 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.810 - 90: DEBUG: new connection from 127.0.0.1:49880 389s app_test.go:1207: 06:05:10.811 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.915 - 90: DEBUG: new connection from 127.0.0.1:49884 389s app_test.go:1207: 06:05:10.917 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.999 - 90: DEBUG: new connection from 127.0.0.1:49890 389s app_test.go:1207: 06:05:10.005 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.533 - 90: DEBUG: new connection from 127.0.0.1:49902 389s app_test.go:1207: 06:05:10.535 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.551 - 91: DEBUG: new connection from 127.0.0.1:36228 389s app_test.go:1207: 06:05:10.551 - 90: DEBUG: new connection from 127.0.0.1:49912 389s app_test.go:1207: 06:05:10.552 - 92: DEBUG: new connection from 127.0.0.1:37558 389s app_test.go:1207: 06:05:10.553 - 93: DEBUG: new connection from 127.0.0.1:59014 389s app_test.go:1207: 06:05:10.554 - 95: DEBUG: new connection from 127.0.0.1:53492 389s app_test.go:1207: 06:05:10.555 - 96: DEBUG: new connection from 127.0.0.1:56340 389s app_test.go:1207: 06:05:10.556 - 97: DEBUG: new connection from 127.0.0.1:36692 389s app_test.go:1207: 06:05:10.557 - 98: DEBUG: new connection from 127.0.0.1:48572 389s app_test.go:1207: 06:05:10.606 - 90: DEBUG: new connection from 127.0.0.1:49924 389s app_test.go:1207: 06:05:10.609 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.397 - 90: DEBUG: new connection from 127.0.0.1:49930 389s app_test.go:1207: 06:05:10.398 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.493 - 90: DEBUG: new connection from 127.0.0.1:49938 389s app_test.go:1207: 06:05:10.496 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.726 - 90: DEBUG: new connection from 127.0.0.1:49942 389s app_test.go:1207: 06:05:10.727 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.827 - 90: DEBUG: new connection from 127.0.0.1:49954 389s app_test.go:1207: 06:05:10.828 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.934 - 90: DEBUG: new connection from 127.0.0.1:49968 389s app_test.go:1207: 06:05:10.935 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.021 - 90: DEBUG: new connection from 127.0.0.1:49980 389s app_test.go:1207: 06:05:10.022 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.560 - 90: DEBUG: new connection from 127.0.0.1:41612 389s app_test.go:1207: 06:05:10.564 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.581 - 91: DEBUG: new connection from 127.0.0.1:40148 389s app_test.go:1207: 06:05:10.581 - 90: DEBUG: new connection from 127.0.0.1:41616 389s app_test.go:1207: 06:05:10.583 - 92: DEBUG: new connection from 127.0.0.1:49548 389s app_test.go:1207: 06:05:10.584 - 93: DEBUG: new connection from 127.0.0.1:40084 389s app_test.go:1207: 06:05:10.587 - 95: DEBUG: new connection from 127.0.0.1:51350 389s app_test.go:1207: 06:05:10.587 - 96: DEBUG: new connection from 127.0.0.1:44866 389s app_test.go:1207: 06:05:10.589 - 97: DEBUG: new connection from 127.0.0.1:36530 389s app_test.go:1207: 06:05:10.589 - 98: DEBUG: new connection from 127.0.0.1:58626 389s app_test.go:1207: 06:05:10.623 - 90: DEBUG: new connection from 127.0.0.1:41626 389s app_test.go:1207: 06:05:10.625 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.405 - 90: DEBUG: new connection from 127.0.0.1:41636 389s app_test.go:1207: 06:05:10.405 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.506 - 90: DEBUG: new connection from 127.0.0.1:41644 389s app_test.go:1207: 06:05:10.507 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.738 - 90: DEBUG: new connection from 127.0.0.1:41648 389s app_test.go:1207: 06:05:10.738 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.841 - 90: DEBUG: new connection from 127.0.0.1:41650 389s app_test.go:1207: 06:05:10.842 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.950 - 90: DEBUG: new connection from 127.0.0.1:41660 389s app_test.go:1207: 06:05:10.951 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.033 - 90: DEBUG: new connection from 127.0.0.1:41670 389s app_test.go:1207: 06:05:10.035 - 98: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.592 - 90: DEBUG: new connection from 127.0.0.1:41682 389s app_test.go:1207: 06:05:10.593 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.608 - 90: DEBUG: new connection from 127.0.0.1:41692 389s app_test.go:1207: 06:05:10.608 - 91: DEBUG: new connection from 127.0.0.1:40160 389s app_test.go:1207: 06:05:10.609 - 92: DEBUG: new connection from 127.0.0.1:49550 389s app_test.go:1207: 06:05:10.610 - 93: DEBUG: new connection from 127.0.0.1:40096 389s app_test.go:1207: 06:05:10.611 - 95: DEBUG: new connection from 127.0.0.1:51354 389s app_test.go:1207: 06:05:10.612 - 96: DEBUG: new connection from 127.0.0.1:44876 389s app_test.go:1207: 06:05:10.613 - 97: DEBUG: new connection from 127.0.0.1:36540 389s app_test.go:1207: 06:05:10.614 - 98: DEBUG: new connection from 127.0.0.1:58630 389s app_test.go:1207: 06:05:10.637 - 90: DEBUG: new connection from 127.0.0.1:41702 389s app_test.go:1207: 06:05:10.638 - 91: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.425 - 90: DEBUG: new connection from 127.0.0.1:41714 389s app_test.go:1207: 06:05:10.427 - 92: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.520 - 90: DEBUG: new connection from 127.0.0.1:41730 389s app_test.go:1207: 06:05:10.521 - 93: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.749 - 90: DEBUG: new connection from 127.0.0.1:41738 389s app_test.go:1207: 06:05:10.750 - 95: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.856 - 90: DEBUG: new connection from 127.0.0.1:41754 389s app_test.go:1207: 06:05:10.857 - 96: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.961 - 90: DEBUG: new connection from 127.0.0.1:41768 389s app_test.go:1207: 06:05:10.962 - 97: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fast path 389s app_test.go:1207: 06:05:10.001 - 90: WARN: attempt 1: server 127.0.0.1:9005: dial: dial tcp 127.0.0.1:9005: connect: connection refused 389s app_test.go:1207: 06:05:10.001 - 96: DEBUG: new connection from 127.0.0.1:44878 389s app_test.go:1207: 06:05:10.001 - 97: DEBUG: new connection from 127.0.0.1:36550 389s app_test.go:1207: 06:05:10.001 - 95: DEBUG: new connection from 127.0.0.1:51368 389s app_test.go:1207: 06:05:10.002 - 93: DEBUG: new connection from 127.0.0.1:40110 389s app_test.go:1207: 06:05:10.002 - 92: DEBUG: new connection from 127.0.0.1:49558 389s app_test.go:1207: 06:05:10.002 - 91: DEBUG: new connection from 127.0.0.1:40168 389s app_test.go:1207: 06:05:10.003 - 90: WARN: attempt 1: server 127.0.0.1:9007: no known leader 389s app_test.go:1207: 06:05:10.003 - 90: DEBUG: new connection from 127.0.0.1:41778 389s app_test.go:1207: 06:05:10.003 - 98: DEBUG: new connection from 127.0.0.1:58644 389s app_test.go:1207: 06:05:10.008 - 90: WARN: attempt 1: server 127.0.0.1:9009: no known leader 389s app_test.go:1207: 06:05:10.010 - 90: WARN: attempt 1: server 127.0.0.1:9004: no known leader 389s app_test.go:1207: 06:05:10.010 - 90: WARN: attempt 1: server 127.0.0.1:9008: no known leader 389s app_test.go:1207: 06:05:10.010 - 90: DEBUG: attempt 1: server 127.0.0.1:9002: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:05:10.011 - 90: DEBUG: attempt 1: server 127.0.0.1:9003: connect to reported leader 127.0.0.1:9001 389s app_test.go:1207: 06:05:10.011 - 90: DEBUG: new connection from 127.0.0.1:41794 389s app_test.go:1207: 06:05:10.011 - 90: DEBUG: new connection from 127.0.0.1:41798 389s app_test.go:1207: 06:05:10.012 - 90: WARN: attempt 1: server 127.0.0.1:9006: no known leader 389s app_test.go:1207: 06:05:10.012 - 90: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s --- PASS: TestRolesAdjustment_ReplaceStandByHonorFailureDomains (22.96s) 389s === RUN TestOpen 389s app_test.go:1207: 06:05:10.358 - 99: DEBUG: new connection from 127.0.0.1:53228 389s app_test.go:1207: 06:05:10.358 - 99: DEBUG: new connection from 127.0.0.1:53238 389s app_test.go:1207: 06:05:10.359 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 389s app_test.go:1207: 06:05:10.362 - 99: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 389s app_test.go:1207: 06:05:10.372 - 99: DEBUG: new connection from 127.0.0.1:53248 389s --- PASS: TestOpen (0.16s) 389s === RUN TestOpenDisk 389s app_test.go:1207: 06:05:10.523 - 100: DEBUG: new connection from 127.0.0.1:53256 389s app_test.go:1207: 06:05:10.523 - 100: DEBUG: new connection from 127.0.0.1:53268 389s app_test.go:1207: 06:05:10.525 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 389s app_test.go:1207: 06:05:10.525 - 100: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 389s app_test.go:1207: 06:05:10.530 - 100: DEBUG: new connection from 127.0.0.1:53276 389s --- PASS: TestOpenDisk (0.14s) 389s === RUN TestOptions 389s --- PASS: TestOptions (0.09s) 389s === RUN TestProxy_Error 389s app_test.go:1207: 06:05:10.752 - 102: DEBUG: new connection from 127.0.0.1:53286 389s app_test.go:1207: 06:05:10.752 - 102: DEBUG: new connection from 127.0.0.1:53290 389s app_test.go:1207: 06:05:10.754 - 102: DEBUG: attempt 1: server 127.0.0.1:9000: connected on fallback path 389s app_test.go:1207: 06:05:10.758 - 102: DEBUG: new connection from 127.0.0.1:53304 389s --- PASS: TestProxy_Error (0.43s) 389s === RUN TestReady_Cancel 389s app_test.go:1207: 06:05:10.176 - 103: WARN: attempt 1: server 127.0.0.1:9001: dial: dial tcp 127.0.0.1:9001: connect: connection refused 389s --- PASS: TestReady_Cancel (0.27s) 389s === RUN TestExternalConnWithTCP 389s app_test.go:1232: 06:05:10.457 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 389s app_test.go:1232: 06:05:10.504 - 105: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 389s app_test.go:1232: 06:05:10.581 - 106: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 389s app_test.go:1232: 06:05:10.424 - 104: DEBUG: attempt 1: server 127.0.0.1:9191: connected on fallback path 389s --- PASS: TestExternalConnWithTCP (2.16s) 389s === RUN TestExternalConnWithPipe 389s app_test.go:1232: 06:05:10.643 - 107: DEBUG: attempt 1: server first: connected on fallback path 389s app_test.go:1232: 06:05:10.749 - 108: DEBUG: attempt 1: server first: connected on fallback path 389s app_test.go:1232: 06:05:10.830 - 109: DEBUG: attempt 1: server first: connected on fallback path 389s app_test.go:1232: 06:05:10.567 - 107: DEBUG: attempt 1: server first: connected on fallback path 389s --- PASS: TestExternalConnWithPipe (2.14s) 389s === RUN TestParallelNewApp 389s === PAUSE TestParallelNewApp 389s === RUN Test_TxRowsAffected 389s app_test.go:1232: 06:05:10.777 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s app_test.go:1232: 06:05:10.783 - 110: DEBUG: attempt 1: server 127.0.0.1:9001: connected on fallback path 389s --- PASS: Test_TxRowsAffected (0.20s) 389s === CONT TestParallelNewApp 389s === RUN TestParallelNewApp/run-0 389s === PAUSE TestParallelNewApp/run-0 389s === RUN TestParallelNewApp/run-1 389s === PAUSE TestParallelNewApp/run-1 389s === RUN TestParallelNewApp/run-2 389s === PAUSE TestParallelNewApp/run-2 389s === RUN TestParallelNewApp/run-3 389s === PAUSE TestParallelNewApp/run-3 389s === RUN TestParallelNewApp/run-4 389s === PAUSE TestParallelNewApp/run-4 389s === RUN TestParallelNewApp/run-5 389s === PAUSE TestParallelNewApp/run-5 389s === RUN TestParallelNewApp/run-6 389s === PAUSE TestParallelNewApp/run-6 389s === RUN TestParallelNewApp/run-7 389s === PAUSE TestParallelNewApp/run-7 389s === RUN TestParallelNewApp/run-8 389s === PAUSE TestParallelNewApp/run-8 389s === RUN TestParallelNewApp/run-9 389s === PAUSE TestParallelNewApp/run-9 389s === RUN TestParallelNewApp/run-10 389s === PAUSE TestParallelNewApp/run-10 389s === RUN TestParallelNewApp/run-11 389s === PAUSE TestParallelNewApp/run-11 389s === RUN TestParallelNewApp/run-12 389s === PAUSE TestParallelNewApp/run-12 389s === RUN TestParallelNewApp/run-13 389s === PAUSE TestParallelNewApp/run-13 389s === RUN TestParallelNewApp/run-14 389s === PAUSE TestParallelNewApp/run-14 389s === RUN TestParallelNewApp/run-15 389s === PAUSE TestParallelNewApp/run-15 389s === RUN TestParallelNewApp/run-16 389s === PAUSE TestParallelNewApp/run-16 389s === RUN TestParallelNewApp/run-17 389s === PAUSE TestParallelNewApp/run-17 389s === RUN TestParallelNewApp/run-18 389s === PAUSE TestParallelNewApp/run-18 389s === RUN TestParallelNewApp/run-19 389s === PAUSE TestParallelNewApp/run-19 389s === RUN TestParallelNewApp/run-20 389s === PAUSE TestParallelNewApp/run-20 389s === RUN TestParallelNewApp/run-21 389s === PAUSE TestParallelNewApp/run-21 389s === RUN TestParallelNewApp/run-22 389s === PAUSE TestParallelNewApp/run-22 389s === RUN TestParallelNewApp/run-23 389s === PAUSE TestParallelNewApp/run-23 389s === RUN TestParallelNewApp/run-24 389s === PAUSE TestParallelNewApp/run-24 389s === RUN TestParallelNewApp/run-25 389s === PAUSE TestParallelNewApp/run-25 389s === RUN TestParallelNewApp/run-26 389s === PAUSE TestParallelNewApp/run-26 389s === RUN TestParallelNewApp/run-27 389s === PAUSE TestParallelNewApp/run-27 389s === RUN TestParallelNewApp/run-28 389s === PAUSE TestParallelNewApp/run-28 389s === RUN TestParallelNewApp/run-29 389s === PAUSE TestParallelNewApp/run-29 389s === RUN TestParallelNewApp/run-30 389s === PAUSE TestParallelNewApp/run-30 389s === RUN TestParallelNewApp/run-31 389s === PAUSE TestParallelNewApp/run-31 389s === RUN TestParallelNewApp/run-32 389s === PAUSE TestParallelNewApp/run-32 389s === RUN TestParallelNewApp/run-33 389s === PAUSE TestParallelNewApp/run-33 389s === RUN TestParallelNewApp/run-34 389s === PAUSE TestParallelNewApp/run-34 389s === RUN TestParallelNewApp/run-35 389s === PAUSE TestParallelNewApp/run-35 389s === RUN TestParallelNewApp/run-36 389s === PAUSE TestParallelNewApp/run-36 389s === RUN TestParallelNewApp/run-37 389s === PAUSE TestParallelNewApp/run-37 389s === RUN TestParallelNewApp/run-38 389s === PAUSE TestParallelNewApp/run-38 389s === RUN TestParallelNewApp/run-39 389s === PAUSE TestParallelNewApp/run-39 389s === RUN TestParallelNewApp/run-40 389s === PAUSE TestParallelNewApp/run-40 389s === RUN TestParallelNewApp/run-41 389s === PAUSE TestParallelNewApp/run-41 389s === RUN TestParallelNewApp/run-42 389s === PAUSE TestParallelNewApp/run-42 389s === RUN TestParallelNewApp/run-43 389s === PAUSE TestParallelNewApp/run-43 389s === RUN TestParallelNewApp/run-44 389s === PAUSE TestParallelNewApp/run-44 389s === RUN TestParallelNewApp/run-45 389s === PAUSE TestParallelNewApp/run-45 389s === RUN TestParallelNewApp/run-46 389s === PAUSE TestParallelNewApp/run-46 389s === RUN TestParallelNewApp/run-47 389s === PAUSE TestParallelNewApp/run-47 389s === RUN TestParallelNewApp/run-48 389s === PAUSE TestParallelNewApp/run-48 389s === RUN TestParallelNewApp/run-49 389s === PAUSE TestParallelNewApp/run-49 389s === RUN TestParallelNewApp/run-50 389s === PAUSE TestParallelNewApp/run-50 389s === RUN TestParallelNewApp/run-51 389s === PAUSE TestParallelNewApp/run-51 389s === RUN TestParallelNewApp/run-52 389s === PAUSE TestParallelNewApp/run-52 389s === RUN TestParallelNewApp/run-53 389s === PAUSE TestParallelNewApp/run-53 389s === RUN TestParallelNewApp/run-54 389s === PAUSE TestParallelNewApp/run-54 389s === RUN TestParallelNewApp/run-55 389s === PAUSE TestParallelNewApp/run-55 389s === RUN TestParallelNewApp/run-56 389s === PAUSE TestParallelNewApp/run-56 389s === RUN TestParallelNewApp/run-57 389s === PAUSE TestParallelNewApp/run-57 389s === RUN TestParallelNewApp/run-58 389s === PAUSE TestParallelNewApp/run-58 389s === RUN TestParallelNewApp/run-59 389s === PAUSE TestParallelNewApp/run-59 389s === RUN TestParallelNewApp/run-60 389s === PAUSE TestParallelNewApp/run-60 389s === RUN TestParallelNewApp/run-61 389s === PAUSE TestParallelNewApp/run-61 389s === RUN TestParallelNewApp/run-62 389s === PAUSE TestParallelNewApp/run-62 389s === RUN TestParallelNewApp/run-63 389s === PAUSE TestParallelNewApp/run-63 389s === RUN TestParallelNewApp/run-64 389s === PAUSE TestParallelNewApp/run-64 389s === RUN TestParallelNewApp/run-65 389s === PAUSE TestParallelNewApp/run-65 389s === RUN TestParallelNewApp/run-66 389s === PAUSE TestParallelNewApp/run-66 389s === RUN TestParallelNewApp/run-67 389s === PAUSE TestParallelNewApp/run-67 389s === RUN TestParallelNewApp/run-68 389s === PAUSE TestParallelNewApp/run-68 389s === RUN TestParallelNewApp/run-69 389s === PAUSE TestParallelNewApp/run-69 389s === RUN TestParallelNewApp/run-70 389s === PAUSE TestParallelNewApp/run-70 389s === RUN TestParallelNewApp/run-71 389s === PAUSE TestParallelNewApp/run-71 389s === RUN TestParallelNewApp/run-72 389s === PAUSE TestParallelNewApp/run-72 389s === RUN TestParallelNewApp/run-73 389s === PAUSE TestParallelNewApp/run-73 389s === RUN TestParallelNewApp/run-74 389s === PAUSE TestParallelNewApp/run-74 389s === RUN TestParallelNewApp/run-75 389s === PAUSE TestParallelNewApp/run-75 389s === RUN TestParallelNewApp/run-76 389s === PAUSE TestParallelNewApp/run-76 389s === RUN TestParallelNewApp/run-77 389s === PAUSE TestParallelNewApp/run-77 389s === RUN TestParallelNewApp/run-78 389s === PAUSE TestParallelNewApp/run-78 389s === RUN TestParallelNewApp/run-79 389s === PAUSE TestParallelNewApp/run-79 389s === RUN TestParallelNewApp/run-80 389s === PAUSE TestParallelNewApp/run-80 389s === RUN TestParallelNewApp/run-81 389s === PAUSE TestParallelNewApp/run-81 389s === RUN TestParallelNewApp/run-82 389s === PAUSE TestParallelNewApp/run-82 389s === RUN TestParallelNewApp/run-83 389s === PAUSE TestParallelNewApp/run-83 389s === RUN TestParallelNewApp/run-84 389s === PAUSE TestParallelNewApp/run-84 389s === RUN TestParallelNewApp/run-85 389s === PAUSE TestParallelNewApp/run-85 389s === RUN TestParallelNewApp/run-86 389s === PAUSE TestParallelNewApp/run-86 389s === RUN TestParallelNewApp/run-87 389s === PAUSE TestParallelNewApp/run-87 389s === RUN TestParallelNewApp/run-88 389s === PAUSE TestParallelNewApp/run-88 389s === RUN TestParallelNewApp/run-89 389s === PAUSE TestParallelNewApp/run-89 389s === RUN TestParallelNewApp/run-90 389s === PAUSE TestParallelNewApp/run-90 389s === RUN TestParallelNewApp/run-91 389s === PAUSE TestParallelNewApp/run-91 389s === RUN TestParallelNewApp/run-92 389s === PAUSE TestParallelNewApp/run-92 389s === RUN TestParallelNewApp/run-93 389s === PAUSE TestParallelNewApp/run-93 389s === RUN TestParallelNewApp/run-94 389s === PAUSE TestParallelNewApp/run-94 389s === RUN TestParallelNewApp/run-95 389s === PAUSE TestParallelNewApp/run-95 389s === RUN TestParallelNewApp/run-96 389s === PAUSE TestParallelNewApp/run-96 389s === RUN TestParallelNewApp/run-97 389s === PAUSE TestParallelNewApp/run-97 389s === RUN TestParallelNewApp/run-98 389s === PAUSE TestParallelNewApp/run-98 389s === RUN TestParallelNewApp/run-99 389s === PAUSE TestParallelNewApp/run-99 389s === CONT TestParallelNewApp/run-0 389s === CONT TestParallelNewApp/run-50 389s === CONT TestParallelNewApp/run-99 389s === CONT TestParallelNewApp/run-98 389s === CONT TestParallelNewApp/run-97 389s === CONT TestParallelNewApp/run-96 389s === CONT TestParallelNewApp/run-95 389s === CONT TestParallelNewApp/run-94 389s === CONT TestParallelNewApp/run-93 389s === CONT TestParallelNewApp/run-92 389s === CONT TestParallelNewApp/run-91 389s === CONT TestParallelNewApp/run-90 389s === CONT TestParallelNewApp/run-89 389s === CONT TestParallelNewApp/run-88 389s === CONT TestParallelNewApp/run-87 389s === CONT TestParallelNewApp/run-86 389s === CONT TestParallelNewApp/run-85 389s === CONT TestParallelNewApp/run-84 389s === CONT TestParallelNewApp/run-83 389s === CONT TestParallelNewApp/run-82 389s === CONT TestParallelNewApp/run-81 389s === CONT TestParallelNewApp/run-80 389s === CONT TestParallelNewApp/run-79 389s === CONT TestParallelNewApp/run-78 389s === CONT TestParallelNewApp/run-77 389s === CONT TestParallelNewApp/run-76 389s === CONT TestParallelNewApp/run-75 389s === CONT TestParallelNewApp/run-74 389s === CONT TestParallelNewApp/run-73 389s === CONT TestParallelNewApp/run-72 389s === CONT TestParallelNewApp/run-71 389s === CONT TestParallelNewApp/run-70 389s === CONT TestParallelNewApp/run-69 389s === CONT TestParallelNewApp/run-68 389s === CONT TestParallelNewApp/run-67 389s === CONT TestParallelNewApp/run-66 389s === CONT TestParallelNewApp/run-65 389s === CONT TestParallelNewApp/run-64 389s === CONT TestParallelNewApp/run-63 389s === CONT TestParallelNewApp/run-62 389s === CONT TestParallelNewApp/run-61 389s === CONT TestParallelNewApp/run-60 389s === CONT TestParallelNewApp/run-59 389s === CONT TestParallelNewApp/run-58 389s === CONT TestParallelNewApp/run-57 389s === CONT TestParallelNewApp/run-56 389s === CONT TestParallelNewApp/run-55 389s === CONT TestParallelNewApp/run-54 389s === CONT TestParallelNewApp/run-53 389s === CONT TestParallelNewApp/run-52 389s === CONT TestParallelNewApp/run-51 389s === CONT TestParallelNewApp/run-25 389s === CONT TestParallelNewApp/run-49 389s === CONT TestParallelNewApp/run-48 389s === CONT TestParallelNewApp/run-47 389s === CONT TestParallelNewApp/run-46 389s === CONT TestParallelNewApp/run-45 389s === CONT TestParallelNewApp/run-44 389s === CONT TestParallelNewApp/run-43 389s === CONT TestParallelNewApp/run-42 389s === CONT TestParallelNewApp/run-41 389s === CONT TestParallelNewApp/run-40 389s === CONT TestParallelNewApp/run-39 389s === CONT TestParallelNewApp/run-38 389s === CONT TestParallelNewApp/run-37 389s === CONT TestParallelNewApp/run-36 389s === CONT TestParallelNewApp/run-35 389s === CONT TestParallelNewApp/run-34 389s === CONT TestParallelNewApp/run-33 389s === CONT TestParallelNewApp/run-32 389s === CONT TestParallelNewApp/run-31 389s === CONT TestParallelNewApp/run-30 389s === CONT TestParallelNewApp/run-29 389s === CONT TestParallelNewApp/run-28 389s === CONT TestParallelNewApp/run-27 389s === CONT TestParallelNewApp/run-26 389s === CONT TestParallelNewApp/run-13 389s === CONT TestParallelNewApp/run-24 389s === CONT TestParallelNewApp/run-23 389s === CONT TestParallelNewApp/run-22 389s === CONT TestParallelNewApp/run-21 389s === CONT TestParallelNewApp/run-20 389s === CONT TestParallelNewApp/run-19 389s === CONT TestParallelNewApp/run-18 389s === CONT TestParallelNewApp/run-17 389s === CONT TestParallelNewApp/run-16 389s === CONT TestParallelNewApp/run-15 389s === CONT TestParallelNewApp/run-14 389s === CONT TestParallelNewApp/run-7 389s === CONT TestParallelNewApp/run-12 389s === CONT TestParallelNewApp/run-11 389s === CONT TestParallelNewApp/run-10 389s === CONT TestParallelNewApp/run-9 389s === CONT TestParallelNewApp/run-8 389s === CONT TestParallelNewApp/run-4 389s === CONT TestParallelNewApp/run-6 389s === CONT TestParallelNewApp/run-5 389s === CONT TestParallelNewApp/run-2 389s === CONT TestParallelNewApp/run-3 389s === CONT TestParallelNewApp/run-1 389s --- PASS: TestParallelNewApp (0.00s) 389s --- PASS: TestParallelNewApp/run-0 (0.09s) 389s --- PASS: TestParallelNewApp/run-50 (0.09s) 389s --- PASS: TestParallelNewApp/run-98 (0.09s) 389s --- PASS: TestParallelNewApp/run-99 (0.10s) 389s --- PASS: TestParallelNewApp/run-97 (0.09s) 389s --- PASS: TestParallelNewApp/run-96 (0.10s) 389s --- PASS: TestParallelNewApp/run-95 (0.08s) 389s --- PASS: TestParallelNewApp/run-94 (0.09s) 389s --- PASS: TestParallelNewApp/run-93 (0.10s) 389s --- PASS: TestParallelNewApp/run-92 (0.11s) 389s --- PASS: TestParallelNewApp/run-91 (0.09s) 389s --- PASS: TestParallelNewApp/run-90 (0.08s) 389s --- PASS: TestParallelNewApp/run-89 (0.08s) 389s --- PASS: TestParallelNewApp/run-88 (0.08s) 389s --- PASS: TestParallelNewApp/run-87 (0.07s) 389s --- PASS: TestParallelNewApp/run-86 (0.07s) 389s --- PASS: TestParallelNewApp/run-85 (0.08s) 389s --- PASS: TestParallelNewApp/run-84 (0.08s) 389s --- PASS: TestParallelNewApp/run-83 (0.08s) 389s --- PASS: TestParallelNewApp/run-82 (0.06s) 389s --- PASS: TestParallelNewApp/run-80 (0.07s) 389s --- PASS: TestParallelNewApp/run-81 (0.09s) 389s --- PASS: TestParallelNewApp/run-79 (0.10s) 389s --- PASS: TestParallelNewApp/run-78 (0.10s) 389s --- PASS: TestParallelNewApp/run-77 (0.07s) 389s --- PASS: TestParallelNewApp/run-76 (0.08s) 389s --- PASS: TestParallelNewApp/run-74 (0.06s) 389s --- PASS: TestParallelNewApp/run-73 (0.07s) 389s --- PASS: TestParallelNewApp/run-75 (0.26s) 389s --- PASS: TestParallelNewApp/run-71 (0.07s) 389s --- PASS: TestParallelNewApp/run-70 (0.08s) 389s --- PASS: TestParallelNewApp/run-72 (0.28s) 389s --- PASS: TestParallelNewApp/run-69 (0.06s) 389s --- PASS: TestParallelNewApp/run-68 (0.06s) 389s --- PASS: TestParallelNewApp/run-67 (0.06s) 389s --- PASS: TestParallelNewApp/run-66 (0.07s) 389s --- PASS: TestParallelNewApp/run-65 (0.06s) 389s --- PASS: TestParallelNewApp/run-64 (0.07s) 389s --- PASS: TestParallelNewApp/run-63 (0.06s) 389s --- PASS: TestParallelNewApp/run-62 (0.06s) 389s --- PASS: TestParallelNewApp/run-60 (0.05s) 389s --- PASS: TestParallelNewApp/run-59 (0.06s) 389s --- PASS: TestParallelNewApp/run-58 (0.06s) 389s --- PASS: TestParallelNewApp/run-61 (0.26s) 389s --- PASS: TestParallelNewApp/run-57 (0.08s) 389s --- PASS: TestParallelNewApp/run-56 (0.27s) 389s --- PASS: TestParallelNewApp/run-55 (0.27s) 389s --- PASS: TestParallelNewApp/run-54 (0.05s) 389s --- PASS: TestParallelNewApp/run-53 (0.06s) 389s --- PASS: TestParallelNewApp/run-52 (0.07s) 389s --- PASS: TestParallelNewApp/run-51 (0.08s) 389s --- PASS: TestParallelNewApp/run-25 (0.08s) 389s --- PASS: TestParallelNewApp/run-49 (0.06s) 389s --- PASS: TestParallelNewApp/run-48 (0.05s) 389s --- PASS: TestParallelNewApp/run-47 (0.06s) 389s --- PASS: TestParallelNewApp/run-46 (0.08s) 389s --- PASS: TestParallelNewApp/run-45 (0.07s) 389s --- PASS: TestParallelNewApp/run-44 (0.07s) 389s --- PASS: TestParallelNewApp/run-43 (0.07s) 389s --- PASS: TestParallelNewApp/run-42 (0.07s) 389s --- PASS: TestParallelNewApp/run-41 (0.09s) 389s --- PASS: TestParallelNewApp/run-40 (0.08s) 389s --- PASS: TestParallelNewApp/run-39 (0.06s) 389s --- PASS: TestParallelNewApp/run-38 (0.07s) 389s --- PASS: TestParallelNewApp/run-37 (0.06s) 389s --- PASS: TestParallelNewApp/run-36 (0.05s) 389s --- PASS: TestParallelNewApp/run-35 (0.07s) 389s --- PASS: TestParallelNewApp/run-34 (0.05s) 389s --- PASS: TestParallelNewApp/run-33 (0.05s) 389s --- PASS: TestParallelNewApp/run-32 (0.05s) 389s --- PASS: TestParallelNewApp/run-31 (0.06s) 389s --- PASS: TestParallelNewApp/run-30 (0.07s) 389s --- PASS: TestParallelNewApp/run-28 (0.07s) 389s --- PASS: TestParallelNewApp/run-29 (0.07s) 389s --- PASS: TestParallelNewApp/run-27 (0.06s) 389s --- PASS: TestParallelNewApp/run-26 (0.07s) 389s --- PASS: TestParallelNewApp/run-24 (0.05s) 389s --- PASS: TestParallelNewApp/run-13 (0.06s) 389s --- PASS: TestParallelNewApp/run-23 (0.07s) 389s --- PASS: TestParallelNewApp/run-22 (0.08s) 389s --- PASS: TestParallelNewApp/run-21 (0.10s) 389s --- PASS: TestParallelNewApp/run-20 (0.09s) 389s --- PASS: TestParallelNewApp/run-18 (0.07s) 389s --- PASS: TestParallelNewApp/run-17 (0.05s) 389s --- PASS: TestParallelNewApp/run-19 (0.26s) 389s --- PASS: TestParallelNewApp/run-15 (0.08s) 389s --- PASS: TestParallelNewApp/run-14 (0.06s) 389s --- PASS: TestParallelNewApp/run-16 (0.28s) 389s --- PASS: TestParallelNewApp/run-12 (0.10s) 389s --- PASS: TestParallelNewApp/run-7 (0.11s) 389s --- PASS: TestParallelNewApp/run-10 (0.09s) 389s --- PASS: TestParallelNewApp/run-11 (0.09s) 389s --- PASS: TestParallelNewApp/run-9 (0.07s) 389s --- PASS: TestParallelNewApp/run-8 (0.08s) 389s --- PASS: TestParallelNewApp/run-4 (0.12s) 389s --- PASS: TestParallelNewApp/run-6 (0.11s) 389s --- PASS: TestParallelNewApp/run-2 (0.08s) 389s --- PASS: TestParallelNewApp/run-5 (0.09s) 389s --- PASS: TestParallelNewApp/run-3 (0.06s) 389s --- PASS: TestParallelNewApp/run-1 (0.07s) 389s === RUN Example 389s --- PASS: Example (0.52s) 389s PASS 389s ok github.com/canonical/go-dqlite/app 170.060s 398s === RUN TestNew_Default 398s Benchmark done. Results available here: 398s /tmp/dqlite-app-test-3738559319/results 398s --- PASS: TestNew_Default (1.17s) 398s === RUN TestNew_KvReadWrite 398s Benchmark done. Results available here: 398s /tmp/dqlite-app-test-3751327920/results 398s --- PASS: TestNew_KvReadWrite (1.13s) 398s === RUN TestNew_ClusteredKvReadWrite 398s Benchmark done. Results available here: 398s /tmp/dqlite-app-test-3094693915/results 398s --- PASS: TestNew_ClusteredKvReadWrite (4.10s) 398s === RUN TestNew_ClusteredTimeout 398s --- PASS: TestNew_ClusteredTimeout (2.15s) 398s PASS 398s ok github.com/canonical/go-dqlite/benchmark 8.607s 401s === RUN TestClient_Leader 401s --- PASS: TestClient_Leader (0.10s) 401s === RUN TestClient_Cluster 401s --- PASS: TestClient_Cluster (0.06s) 401s === RUN TestClient_Transfer 401s --- PASS: TestClient_Transfer (1.12s) 401s === RUN TestClient_Describe 401s --- PASS: TestClient_Describe (0.07s) 401s === RUN TestMembership 401s --- PASS: TestMembership (1.08s) 401s === RUN TestDefaultNodeStore 401s --- PASS: TestDefaultNodeStore (0.00s) 401s === RUN TestConfigMultiThread 401s --- PASS: TestConfigMultiThread (0.00s) 401s PASS 401s ok github.com/canonical/go-dqlite/client 2.436s 401s ? github.com/canonical/go-dqlite/cmd/dqlite [no test files] 401s ? github.com/canonical/go-dqlite/cmd/dqlite-benchmark [no test files] 401s ? github.com/canonical/go-dqlite/cmd/dqlite-demo [no test files] 429s === RUN TestDriver_Open 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestDriver_Open (0.07s) 429s === RUN TestDriver_Prepare 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestDriver_Prepare (0.06s) 429s === RUN TestConn_Exec 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestConn_Exec (0.09s) 429s === RUN TestConn_Query 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestConn_Query (0.06s) 429s === RUN TestConn_QueryRow 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestConn_QueryRow (0.08s) 429s === RUN TestConn_InterruptQuery 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestConn_InterruptQuery (0.37s) 429s === RUN TestConn_QueryBlob 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestConn_QueryBlob (0.06s) 429s === RUN TestStmt_Exec 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestStmt_Exec (0.05s) 429s === RUN TestStmt_ExecManyParams 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestStmt_ExecManyParams (0.05s) 429s === RUN TestStmt_Query 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestStmt_Query (0.06s) 429s === RUN TestStmt_QueryManyParams 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestStmt_QueryManyParams (0.07s) 429s === RUN TestConn_QueryParams 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestConn_QueryParams (0.05s) 429s === RUN TestConn_QueryManyParams 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestConn_QueryManyParams (0.06s) 429s === RUN TestConn_ExecManyParams 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestConn_ExecManyParams (0.06s) 429s === RUN Test_ColumnTypesEmpty 429s driver_test.go:472: this currently fails if the result set is empty, is dqlite skipping the header if empty set? 429s --- SKIP: Test_ColumnTypesEmpty (0.00s) 429s === RUN Test_ColumnTypesExists 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: Test_ColumnTypesExists (0.05s) 429s === RUN Test_ColumnTypesEnd 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: Test_ColumnTypesEnd (0.06s) 429s === RUN Test_ZeroColumns 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: Test_ZeroColumns (0.15s) 429s === RUN Test_DescribeLastEntry 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: Test_DescribeLastEntry (1.07s) 429s === RUN Test_Dump 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: Test_Dump (0.09s) 429s === RUN TestIntegration_DatabaseSQL 429s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 429s func.go:15: WARN: attempt 1: server @1: context canceled 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 1: server @1: context canceled 429s --- PASS: TestIntegration_DatabaseSQL (2.14s) 429s === RUN TestIntegration_ConstraintError 429s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s func.go:15: WARN: attempt 1: server @3: no known leader 429s --- PASS: TestIntegration_ConstraintError (2.12s) 429s === RUN TestIntegration_ExecBindError 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestIntegration_ExecBindError (0.10s) 429s === RUN TestIntegration_QueryBindError 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestIntegration_QueryBindError (0.06s) 429s === RUN TestIntegration_LargeQuery 429s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 429s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestIntegration_LargeQuery (2.17s) 429s === RUN TestIntegration_Recover 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 1: server @1: context canceled 429s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 429s func.go:15: DEBUG: attempt 1: server @1: connected on fast path 429s --- PASS: TestIntegration_Recover (1.28s) 429s === RUN TestIntegration_PingOnlyWorksOnceLeaderElected 429s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: WARN: attempt 1: server @1: no known leader 429s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 1: server @1: reported leader server is not the leader 429s func.go:15: DEBUG: attempt 2: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 2: server @1: reported leader server is not the leader 429s func.go:15: WARN: attempt 2: server @1: no known leader 429s func.go:15: DEBUG: attempt 3: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 3: server @1: no known leader 429s func.go:15: WARN: attempt 3: server @1: reported leader server is not the leader 429s func.go:15: DEBUG: attempt 4: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 4: server @1: no known leader 429s func.go:15: WARN: attempt 4: server @1: reported leader server is not the leader 429s func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 5: server @1: no known leader 429s func.go:15: WARN: attempt 5: server @1: reported leader server is not the leader 429s func.go:15: DEBUG: attempt 6: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 6: server @1: no known leader 429s func.go:15: WARN: attempt 6: server @1: reported leader server is not the leader 429s func.go:15: WARN: attempt 7: server @2: no known leader 429s func.go:15: WARN: attempt 7: server @1: no known leader 429s func.go:15: DEBUG: attempt 8: server @2: connect to reported leader @1 429s func.go:15: DEBUG: attempt 8: server @1: connected on fallback path 429s --- PASS: TestIntegration_PingOnlyWorksOnceLeaderElected (8.03s) 429s === RUN TestIntegration_HighAvailability 429s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 429s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s func.go:15: DEBUG: network connection lost: write unix @->@1: write: broken pipe 429s func.go:15: WARN: attempt 1: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: WARN: attempt 1: server @3: no known leader 429s func.go:15: WARN: attempt 1: server @2: no known leader 429s func.go:15: WARN: attempt 2: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: WARN: attempt 2: server @2: no known leader 429s func.go:15: WARN: attempt 2: server @3: no known leader 429s func.go:15: WARN: attempt 3: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: WARN: attempt 3: server @2: no known leader 429s func.go:15: WARN: attempt 3: server @3: no known leader 429s func.go:15: WARN: attempt 4: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: WARN: attempt 4: server @3: no known leader 429s func.go:15: WARN: attempt 4: server @2: no known leader 429s func.go:15: WARN: attempt 5: server @1: dial: dial unix @1: connect: connection refused 429s func.go:15: DEBUG: attempt 5: server @2: connect to reported leader @3 429s func.go:15: DEBUG: attempt 5: server @3: connected on fallback path 429s --- PASS: TestIntegration_HighAvailability (6.76s) 429s === RUN TestIntegration_LeadershipTransfer 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 429s func.go:15: WARN: attempt 1: server @1: context canceled 429s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 429s func.go:15: WARN: attempt 1: server @1: context canceled 429s func.go:15: DEBUG: leadership lost (10250 - not leader) 429s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 429s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 429s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 429s func.go:15: WARN: attempt 1: server @2: context canceled 429s --- PASS: TestIntegration_LeadershipTransfer (2.14s) 429s === RUN TestIntegration_LeadershipTransfer_Tx 429s func.go:15: DEBUG: attempt 1: server @2: connect to reported leader @1 429s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @1 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s func.go:15: WARN: attempt 1: server @1: context canceled 429s func.go:15: DEBUG: leadership lost (10250 - not leader) 429s func.go:15: DEBUG: attempt 1: server @3: connect to reported leader @2 429s func.go:15: DEBUG: attempt 1: server @2: connected on fallback path 429s func.go:15: DEBUG: attempt 1: server @1: connect to reported leader @2 429s func.go:15: WARN: attempt 1: server @2: context canceled 429s --- PASS: TestIntegration_LeadershipTransfer_Tx (2.14s) 429s === RUN TestOptions 429s --- PASS: TestOptions (0.00s) 429s === RUN TestIntegration_ColumnTypeName 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestIntegration_ColumnTypeName (0.12s) 429s === RUN TestIntegration_SqlNullTime 429s func.go:15: DEBUG: attempt 1: server @1: connected on fallback path 429s --- PASS: TestIntegration_SqlNullTime (0.15s) 429s PASS 429s ok github.com/canonical/go-dqlite/driver 29.794s 429s === RUN TestNode_Create 429s --- PASS: TestNode_Create (0.05s) 429s === RUN TestNode_Start 429s --- PASS: TestNode_Start (0.05s) 429s === RUN TestNode_Restart 429s --- PASS: TestNode_Restart (0.10s) 429s === RUN TestNode_Start_Inet 429s --- PASS: TestNode_Start_Inet (0.06s) 429s === RUN TestNode_Leader 429s --- PASS: TestNode_Leader (0.05s) 429s === RUN TestNode_Autorecovery 429s --- PASS: TestNode_Autorecovery (0.04s) 429s PASS 429s ok github.com/canonical/go-dqlite/internal/bindings 0.359s 429s === RUN TestMessage_StaticBytesAlignment 429s --- PASS: TestMessage_StaticBytesAlignment (0.00s) 429s === RUN TestMessage_putBlob 429s === RUN TestMessage_putBlob/16 429s === RUN TestMessage_putBlob/16#01 429s === RUN TestMessage_putBlob/24 429s --- PASS: TestMessage_putBlob (0.00s) 429s --- PASS: TestMessage_putBlob/16 (0.00s) 429s --- PASS: TestMessage_putBlob/16#01 (0.00s) 429s --- PASS: TestMessage_putBlob/24 (0.00s) 429s === RUN TestMessage_putString 429s === RUN TestMessage_putString/hello 429s === RUN TestMessage_putString/hello!! 429s === RUN TestMessage_putString/hello_world 429s --- PASS: TestMessage_putString (0.00s) 429s --- PASS: TestMessage_putString/hello (0.00s) 429s --- PASS: TestMessage_putString/hello!! (0.00s) 429s --- PASS: TestMessage_putString/hello_world (0.00s) 429s === RUN TestMessage_putUint8 429s --- PASS: TestMessage_putUint8 (0.00s) 429s === RUN TestMessage_putUint16 429s --- PASS: TestMessage_putUint16 (0.00s) 429s === RUN TestMessage_putUint32 429s --- PASS: TestMessage_putUint32 (0.00s) 429s === RUN TestMessage_putUint64 429s --- PASS: TestMessage_putUint64 (0.00s) 429s === RUN TestMessage_putNamedValues 429s --- PASS: TestMessage_putNamedValues (0.00s) 429s === RUN TestMessage_putNamedValues32 429s --- PASS: TestMessage_putNamedValues32 (0.00s) 429s === RUN TestMessage_putHeader 429s --- PASS: TestMessage_putHeader (0.00s) 429s === RUN TestMessage_getString 429s === RUN TestMessage_getString/hello 429s === RUN TestMessage_getString/hello!! 429s === RUN TestMessage_getString/hello!!! 429s === RUN TestMessage_getString/hello_world 429s --- PASS: TestMessage_getString (0.00s) 429s --- PASS: TestMessage_getString/hello (0.00s) 429s --- PASS: TestMessage_getString/hello!! (0.00s) 429s --- PASS: TestMessage_getString/hello!!! (0.00s) 429s --- PASS: TestMessage_getString/hello_world (0.00s) 429s === RUN TestMessage_getBlob 429s === RUN TestMessage_getBlob/16 429s === RUN TestMessage_getBlob/16#01 429s === RUN TestMessage_getBlob/24 429s --- PASS: TestMessage_getBlob (0.00s) 429s --- PASS: TestMessage_getBlob/16 (0.00s) 429s --- PASS: TestMessage_getBlob/16#01 (0.00s) 429s --- PASS: TestMessage_getBlob/24 (0.00s) 429s === RUN TestMessage_getString_Overflow_WordBoundary 429s --- PASS: TestMessage_getString_Overflow_WordBoundary (0.00s) 429s === RUN TestConnector_Success 429s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 429s --- PASS: TestConnector_Success (0.09s) 429s === RUN TestConnector_LeaderTracker 429s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 429s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fallback path 429s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 429s connector_test.go:398: DEBUG: attempt 1: server @test-0: connected on fast path 429s connector_test.go:398: DEBUG: reusing shared connection to @test-0 429s connector_test.go:398: DEBUG: discarding shared connection to @test-0 429s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 429s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 429s connector_test.go:398: DEBUG: reusing shared connection to @test-0 429s connector_test.go:398: DEBUG: discarding shared connection to @test-0 429s connector_test.go:398: WARN: attempt 1: server @test-0: context deadline exceeded 429s --- PASS: TestConnector_LeaderTracker (0.86s) 429s === RUN TestConnector_LimitRetries 429s connector_test.go:398: WARN: attempt 1: server @test-123: dial: dial unix @test-123: connect: connection refused 429s connector_test.go:398: WARN: attempt 2: server @test-123: dial: dial unix @test-123: connect: connection refused 429s connector_test.go:398: WARN: attempt 3: server @test-123: dial: dial unix @test-123: connect: connection refused 429s --- PASS: TestConnector_LimitRetries (0.60s) 429s === RUN TestConnector_DialTimeout 429s connector_test.go:158: Skipping network test 429s --- SKIP: TestConnector_DialTimeout (0.00s) 429s === RUN TestConnector_EmptyNodeStore 429s --- PASS: TestConnector_EmptyNodeStore (0.20s) 429s === RUN TestConnector_ContextCanceled 429s connector_test.go:193: Skipping network test 429s --- SKIP: TestConnector_ContextCanceled (0.00s) 429s === RUN TestConnector_AttemptTimeout 429s func.go:15: WARN: attempt 1: server @1234: call leader (budget 99.960999ms): receive: header: read unix @->@1234: i/o timeout 429s func.go:15: WARN: attempt 2: server @1234: call leader (budget 99.919519ms): receive: header: read unix @->@1234: i/o timeout 429s --- PASS: TestConnector_AttemptTimeout (0.40s) 429s === RUN TestProtocol_RequestWithDynamicBuffer 429s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 429s --- PASS: TestProtocol_RequestWithDynamicBuffer (0.13s) 429s === RUN TestProtocol_Prepare 429s func.go:15: DEBUG: attempt 1: server @test-0: connected on fallback path 429s --- PASS: TestProtocol_Prepare (0.08s) 429s PASS 429s ok github.com/canonical/go-dqlite/internal/protocol 2.366s 429s ? github.com/canonical/go-dqlite/internal/shell [no test files] 429s === RUN Test_TestFunc 429s func.go:15: INFO: hello 429s --- PASS: Test_TestFunc (0.00s) 429s === RUN TestLevel_String 429s --- PASS: TestLevel_String (0.00s) 429s PASS 429s ok github.com/canonical/go-dqlite/logging 0.002s 429s ? github.com/canonical/go-dqlite/tracing [no test files] 429s autopkgtest [06:06:44]: test dh-golang-autopkgtest: -----------------------] 430s autopkgtest [06:06:45]: test dh-golang-autopkgtest: - - - - - - - - - - results - - - - - - - - - - 430s dh-golang-autopkgtest PASS 430s autopkgtest [06:06:45]: @@@@@@@@@@@@@@@@@@@@ summary 430s dh-golang-autopkgtest PASS