Diff of the two buildlogs: -- --- b1/build.log 2024-05-09 10:57:21.571773894 +0000 +++ b2/build.log 2024-05-09 10:59:01.667515053 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Wed May 8 22:55:11 -12 2024 -I: pbuilder-time-stamp: 1715252111 +I: Current time: Fri May 10 00:57:24 +14 2024 +I: pbuilder-time-stamp: 1715252244 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -31,52 +31,84 @@ dpkg-source: info: applying 0004-Fix-untyped-int-overflow-on-32-bit-arch.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/3595460/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/2739152/tmp/hooks/D01_modify_environment starting +debug: Running on codethink04-arm64. +I: Changing host+domainname to test build reproducibility +I: Adding a custom variable just for the fun of it... +I: Changing /bin/sh to bash +'/bin/sh' -> '/bin/bash' +lrwxrwxrwx 1 root root 9 May 9 10:57 /bin/sh -> /bin/bash +I: Setting pbuilder2's login shell to /bin/bash +I: Setting pbuilder2's GECOS to second user,second room,second work-phone,second home-phone,second other +I: user script /srv/workspace/pbuilder/2739152/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/2739152/tmp/hooks/D02_print_environment starting I: set - BUILDDIR='/build/reproducible-path' - BUILDUSERGECOS='first user,first room,first work-phone,first home-phone,first other' - BUILDUSERNAME='pbuilder1' - BUILD_ARCH='arm64' - DEBIAN_FRONTEND='noninteractive' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="21" [3]="1" [4]="release" [5]="aarch64-unknown-linux-gnu") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=arm64 + DEBIAN_FRONTEND=noninteractive DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=12 ' - DISTRIBUTION='trixie' - HOME='/root' - HOST_ARCH='arm64' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=aarch64 + HOST_ARCH=arm64 IFS=' ' - INVOCATION_ID='9835aba0d0ff4db9b2fac55164065656' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - MAIL='/var/mail/root' - OPTIND='1' - PATH='/usr/sbin:/usr/bin:/sbin:/bin:/usr/games' - PBCURRENTCOMMANDLINEOPERATION='build' - PBUILDER_OPERATION='build' - PBUILDER_PKGDATADIR='/usr/share/pbuilder' - PBUILDER_PKGLIBDIR='/usr/lib/pbuilder' - PBUILDER_SYSCONFDIR='/etc' - PPID='3595460' - PS1='# ' - PS2='> ' + INVOCATION_ID=17c37a7694764ba9a3fa434a95610a74 + LANG=C + LANGUAGE=nl_BE:nl + LC_ALL=C + MACHTYPE=aarch64-unknown-linux-gnu + MAIL=/var/mail/root + OPTERR=1 + OPTIND=1 + OSTYPE=linux-gnu + PATH=/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path + PBCURRENTCOMMANDLINEOPERATION=build + PBUILDER_OPERATION=build + PBUILDER_PKGDATADIR=/usr/share/pbuilder + PBUILDER_PKGLIBDIR=/usr/lib/pbuilder + PBUILDER_SYSCONFDIR=/etc + PIPESTATUS=([0]="0") + POSIXLY_CORRECT=y + PPID=2739152 PS4='+ ' - PWD='/' - SHELL='/bin/bash' - SHLVL='2' - SUDO_COMMAND='/usr/bin/timeout -k 18.1h 18h /usr/bin/ionice -c 3 /usr/bin/nice /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.scMswUP9/pbuilderrc_7lHk --distribution trixie --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.scMswUP9/b1 --logfile b1/build.log golang-github-hashicorp-memberlist_0.5.0-1.dsc' - SUDO_GID='109' - SUDO_UID='104' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://192.168.101.4:3128' + PWD=/ + SHELL=/bin/bash + SHELLOPTS=braceexpand:errexit:hashall:interactive-comments:posix + SHLVL=3 + SUDO_COMMAND='/usr/bin/timeout -k 24.1h 24h /usr/bin/ionice -c 3 /usr/bin/nice -n 11 /usr/bin/unshare --uts -- /usr/sbin/pbuilder --build --configfile /srv/reproducible-results/rbuild-debian/r-b-build.scMswUP9/pbuilderrc_SVV9 --distribution trixie --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.scMswUP9/b2 --logfile b2/build.log golang-github-hashicorp-memberlist_0.5.0-1.dsc' + SUDO_GID=109 + SUDO_UID=104 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://192.168.101.4:3128 I: uname -a - Linux codethink02-arm64 6.1.0-21-cloud-arm64 #1 SMP Debian 6.1.90-1 (2024-05-03) aarch64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-21-cloud-arm64 #1 SMP Debian 6.1.90-1 (2024-05-03) aarch64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 6 11:24 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/3595460/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 May 8 11:23 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/2739152/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -238,7 +270,7 @@ Get: 106 http://deb.debian.org/debian trixie/main arm64 golang-github-hashicorp-go-multierror-dev all 1.1.1-2 [7804 B] Get: 107 http://deb.debian.org/debian trixie/main arm64 golang-github-hashicorp-go-sockaddr-dev all 1.0.2-2 [54.4 kB] Get: 108 http://deb.debian.org/debian trixie/main arm64 golang-github-miekg-dns-dev all 1.1.58-1 [176 kB] -Fetched 87.0 MB in 1s (111 MB/s) +Fetched 87.0 MB in 0s (219 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package sensible-utils. (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 ... 19744 files and directories currently installed.) @@ -685,7 +717,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/golang-github-hashicorp-memberlist-0.5.0/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games" HOME="/nonexistent/first-build" dpkg-genchanges -S > ../golang-github-hashicorp-memberlist_0.5.0-1_source.changes +I: user script /srv/workspace/pbuilder/2739152/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/2739152/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/golang-github-hashicorp-memberlist-0.5.0/ && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-buildpackage -us -uc -b && env PATH="/usr/sbin:/usr/bin:/sbin:/bin:/usr/games:/i/capture/the/path" HOME="/nonexistent/second-build" dpkg-genchanges -S > ../golang-github-hashicorp-memberlist_0.5.0-1_source.changes dpkg-buildpackage: info: source package golang-github-hashicorp-memberlist dpkg-buildpackage: info: source version 0.5.0-1 dpkg-buildpackage: info: source distribution unstable @@ -704,40 +740,40 @@ dh_auto_configure -O--buildsystem=golang dh_auto_build -O--buildsystem=golang cd obj-aarch64-linux-gnu && go install -trimpath -v -p 12 github.com/hashicorp/memberlist github.com/hashicorp/memberlist/internal/retry -internal/godebugs -internal/race -internal/goexperiment -internal/goos -internal/coverage/rtcov -internal/unsafeheader internal/goarch -internal/cpu +internal/race sync/atomic -internal/itoa -math/bits +internal/cpu +internal/unsafeheader unicode/utf8 -cmp -runtime/internal/syscall -container/list unicode -crypto/internal/alias -crypto/subtle +internal/godebugs +internal/goos +runtime/internal/syscall +internal/itoa +internal/coverage/rtcov +internal/goexperiment +math/bits internal/abi internal/chacha8rand runtime/internal/math +cmp runtime/internal/sys -crypto/internal/boring/sig -math +container/list +crypto/internal/alias +crypto/subtle slices +crypto/internal/boring/sig log/internal internal/nettrace unicode/utf16 +math vendor/golang.org/x/crypto/cryptobyte/asn1 +runtime/internal/atomic vendor/golang.org/x/crypto/internal/alias encoding -golang.org/x/net/internal/iana internal/bytealg -runtime/internal/atomic +golang.org/x/net/internal/iana runtime internal/reflectlite sync @@ -745,7 +781,6 @@ internal/bisect internal/singleflight runtime/cgo -internal/godebug errors sort io @@ -753,23 +788,24 @@ strconv internal/safefilepath path -bytes -internal/intern +internal/godebug github.com/hashicorp/golang-lru/simplelru -crypto/internal/nistec/fiat vendor/golang.org/x/net/dns/dnsmessage -math/rand -reflect +crypto/internal/nistec/fiat hash -net/netip +crypto/internal/randutil +bytes +internal/intern strings -crypto/rc4 internal/saferio -crypto/internal/randutil -vendor/golang.org/x/text/transform -crypto +math/rand syscall hash/crc32 +reflect +crypto +net/netip +vendor/golang.org/x/text/transform +crypto/rc4 encoding/base32 bufio github.com/hashicorp/go-immutable-radix @@ -778,51 +814,51 @@ regexp/syntax regexp internal/syscall/unix -time internal/syscall/execenv +time +internal/poll +context +io/fs internal/fmtsort encoding/binary github.com/hashicorp/errwrap -io/fs -context -internal/poll embed +os crypto/cipher +crypto/internal/nistec crypto/internal/edwards25519/field crypto/md5 encoding/base64 vendor/golang.org/x/crypto/internal/poly1305 golang.org/x/sys/unix -crypto/internal/nistec -encoding/pem -os crypto/internal/boring vendor/golang.org/x/crypto/chacha20 crypto/des +encoding/pem +crypto/internal/edwards25519 crypto/aes crypto/sha512 -crypto/sha256 -crypto/hmac crypto/sha1 -crypto/internal/edwards25519 +crypto/hmac +crypto/sha256 vendor/golang.org/x/crypto/hkdf vendor/golang.org/x/crypto/chacha20poly1305 -crypto/ecdh fmt path/filepath os/signal -os/exec net +crypto/ecdh +os/exec compress/lzw math/big log -mime/quotedprintable compress/flate encoding/hex net/url vendor/golang.org/x/text/unicode/norm vendor/golang.org/x/net/http2/hpack mime +mime/quotedprintable net/http/internal github.com/google/btree encoding/gob @@ -836,23 +872,23 @@ compress/gzip vendor/golang.org/x/text/secure/bidirule text/template -vendor/golang.org/x/net/idna crypto/rand crypto/elliptic crypto/internal/bigmod crypto/internal/boring/bbig encoding/asn1 crypto/dsa +vendor/golang.org/x/net/idna crypto/ed25519 github.com/hashicorp/memberlist/vendor/github.com/sean-/seed -html/template crypto/rsa vendor/golang.org/x/crypto/cryptobyte crypto/x509/pkix +html/template crypto/ecdsa -crypto/x509 -net/textproto vendor/golang.org/x/net/http/httpproxy +net/textproto +crypto/x509 github.com/hashicorp/go-sockaddr golang.org/x/net/internal/socket vendor/golang.org/x/net/http/httpguts @@ -863,8 +899,8 @@ net/http/httptrace github.com/miekg/dns net/http -github.com/armon/go-metrics net/rpc +github.com/armon/go-metrics github.com/hashicorp/go-msgpack/codec github.com/hashicorp/memberlist dh_auto_test -O--buildsystem=golang @@ -892,83 +928,83 @@ === RUN TestKeyRing_MultiKeyEncryptDecrypt --- PASS: TestKeyRing_MultiKeyEncryptDecrypt (0.00s) === RUN TestAddLabelHeaderToPacket +=== RUN TestAddLabelHeaderToPacket/message_with_no_label === RUN TestAddLabelHeaderToPacket/message_with_almost_too_long_label === RUN TestAddLabelHeaderToPacket/label_too_long_by_one_byte === RUN TestAddLabelHeaderToPacket/nil_buf_with_no_label === RUN TestAddLabelHeaderToPacket/nil_buf_with_label === RUN TestAddLabelHeaderToPacket/message_with_label -=== RUN TestAddLabelHeaderToPacket/message_with_no_label --- PASS: TestAddLabelHeaderToPacket (0.00s) + --- PASS: TestAddLabelHeaderToPacket/message_with_no_label (0.00s) --- PASS: TestAddLabelHeaderToPacket/message_with_almost_too_long_label (0.00s) --- PASS: TestAddLabelHeaderToPacket/label_too_long_by_one_byte (0.00s) --- PASS: TestAddLabelHeaderToPacket/nil_buf_with_no_label (0.00s) --- PASS: TestAddLabelHeaderToPacket/nil_buf_with_label (0.00s) --- PASS: TestAddLabelHeaderToPacket/message_with_label (0.00s) - --- PASS: TestAddLabelHeaderToPacket/message_with_no_label (0.00s) === RUN TestRemoveLabelHeaderFromPacket +=== RUN TestRemoveLabelHeaderFromPacket/ping_with_no_label === RUN TestRemoveLabelHeaderFromPacket/error_with_no_label === RUN TestRemoveLabelHeaderFromPacket/buf_too_small_for_label -=== RUN TestRemoveLabelHeaderFromPacket/buf_too_small_for_label_size -=== RUN TestRemoveLabelHeaderFromPacket/label_empty -=== RUN TestRemoveLabelHeaderFromPacket/ping_with_label === RUN TestRemoveLabelHeaderFromPacket/v1_encrypt_with_label === RUN TestRemoveLabelHeaderFromPacket/empty_buf -=== RUN TestRemoveLabelHeaderFromPacket/ping_with_no_label === RUN TestRemoveLabelHeaderFromPacket/v1_encrypt_with_no_label +=== RUN TestRemoveLabelHeaderFromPacket/buf_too_small_for_label_size +=== RUN TestRemoveLabelHeaderFromPacket/label_empty === RUN TestRemoveLabelHeaderFromPacket/label_truncated +=== RUN TestRemoveLabelHeaderFromPacket/ping_with_label === RUN TestRemoveLabelHeaderFromPacket/error_with_label --- PASS: TestRemoveLabelHeaderFromPacket (0.00s) + --- PASS: TestRemoveLabelHeaderFromPacket/ping_with_no_label (0.00s) --- PASS: TestRemoveLabelHeaderFromPacket/error_with_no_label (0.00s) --- PASS: TestRemoveLabelHeaderFromPacket/buf_too_small_for_label (0.00s) - --- PASS: TestRemoveLabelHeaderFromPacket/buf_too_small_for_label_size (0.00s) - --- PASS: TestRemoveLabelHeaderFromPacket/label_empty (0.00s) - --- PASS: TestRemoveLabelHeaderFromPacket/ping_with_label (0.00s) --- PASS: TestRemoveLabelHeaderFromPacket/v1_encrypt_with_label (0.00s) --- PASS: TestRemoveLabelHeaderFromPacket/empty_buf (0.00s) - --- PASS: TestRemoveLabelHeaderFromPacket/ping_with_no_label (0.00s) --- PASS: TestRemoveLabelHeaderFromPacket/v1_encrypt_with_no_label (0.00s) + --- PASS: TestRemoveLabelHeaderFromPacket/buf_too_small_for_label_size (0.00s) + --- PASS: TestRemoveLabelHeaderFromPacket/label_empty (0.00s) --- PASS: TestRemoveLabelHeaderFromPacket/label_truncated (0.00s) + --- PASS: TestRemoveLabelHeaderFromPacket/ping_with_label (0.00s) --- PASS: TestRemoveLabelHeaderFromPacket/error_with_label (0.00s) === RUN TestAddLabelHeaderToStream -=== RUN TestAddLabelHeaderToStream/label_too_long_by_one_byte === RUN TestAddLabelHeaderToStream/no_label === RUN TestAddLabelHeaderToStream/with_label === RUN TestAddLabelHeaderToStream/almost_too_long_label +=== RUN TestAddLabelHeaderToStream/label_too_long_by_one_byte --- PASS: TestAddLabelHeaderToStream (0.00s) - --- PASS: TestAddLabelHeaderToStream/label_too_long_by_one_byte (0.00s) --- PASS: TestAddLabelHeaderToStream/no_label (0.00s) --- PASS: TestAddLabelHeaderToStream/with_label (0.00s) --- PASS: TestAddLabelHeaderToStream/almost_too_long_label (0.00s) + --- PASS: TestAddLabelHeaderToStream/label_too_long_by_one_byte (0.00s) === RUN TestRemoveLabelHeaderFromStream +=== RUN TestRemoveLabelHeaderFromStream/label_truncated +=== RUN TestRemoveLabelHeaderFromStream/v1_encrypt_with_label === RUN TestRemoveLabelHeaderFromStream/empty_buf -=== RUN TestRemoveLabelHeaderFromStream/v1_encrypt_with_no_label +=== RUN TestRemoveLabelHeaderFromStream/error_with_no_label +=== RUN TestRemoveLabelHeaderFromStream/buf_too_small_for_label === RUN TestRemoveLabelHeaderFromStream/label_empty +=== RUN TestRemoveLabelHeaderFromStream/ping_with_label === RUN TestRemoveLabelHeaderFromStream/error_with_label -=== RUN TestRemoveLabelHeaderFromStream/v1_encrypt_with_label === RUN TestRemoveLabelHeaderFromStream/ping_with_no_label -=== RUN TestRemoveLabelHeaderFromStream/error_with_no_label -=== RUN TestRemoveLabelHeaderFromStream/buf_too_small_for_label +=== RUN TestRemoveLabelHeaderFromStream/v1_encrypt_with_no_label === RUN TestRemoveLabelHeaderFromStream/buf_too_small_for_label_size -=== RUN TestRemoveLabelHeaderFromStream/label_truncated -=== RUN TestRemoveLabelHeaderFromStream/ping_with_label --- PASS: TestRemoveLabelHeaderFromStream (0.00s) + --- PASS: TestRemoveLabelHeaderFromStream/label_truncated (0.00s) + --- PASS: TestRemoveLabelHeaderFromStream/v1_encrypt_with_label (0.00s) --- PASS: TestRemoveLabelHeaderFromStream/empty_buf (0.00s) - --- PASS: TestRemoveLabelHeaderFromStream/v1_encrypt_with_no_label (0.00s) + --- PASS: TestRemoveLabelHeaderFromStream/error_with_no_label (0.00s) + --- PASS: TestRemoveLabelHeaderFromStream/buf_too_small_for_label (0.00s) --- PASS: TestRemoveLabelHeaderFromStream/label_empty (0.00s) + --- PASS: TestRemoveLabelHeaderFromStream/ping_with_label (0.00s) --- PASS: TestRemoveLabelHeaderFromStream/error_with_label (0.00s) - --- PASS: TestRemoveLabelHeaderFromStream/v1_encrypt_with_label (0.00s) --- PASS: TestRemoveLabelHeaderFromStream/ping_with_no_label (0.00s) - --- PASS: TestRemoveLabelHeaderFromStream/error_with_no_label (0.00s) - --- PASS: TestRemoveLabelHeaderFromStream/buf_too_small_for_label (0.00s) + --- PASS: TestRemoveLabelHeaderFromStream/v1_encrypt_with_no_label (0.00s) --- PASS: TestRemoveLabelHeaderFromStream/buf_too_small_for_label_size (0.00s) - --- PASS: TestRemoveLabelHeaderFromStream/label_truncated (0.00s) - --- PASS: TestRemoveLabelHeaderFromStream/ping_with_label (0.00s) === RUN TestLabelOverhead --- PASS: TestLabelOverhead (0.00s) === RUN TestLogging_Address --- PASS: TestLogging_Address (0.00s) === RUN TestLogging_Conn ---- PASS: TestLogging_Conn (0.01s) +--- PASS: TestLogging_Conn (0.00s) === RUN TestDefaultLANConfig_protocolVersion --- PASS: TestDefaultLANConfig_protocolVersion (0.00s) === RUN TestCreate_protocolVersion @@ -1002,13 +1038,13 @@ === RUN TestCreate_invalidLoggerSettings --- PASS: TestCreate_invalidLoggerSettings (0.00s) === RUN TestCreate -127.0.0.232024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 33149 ---- PASS: TestCreate (0.26s) +127.0.0.232024/05/09 10:58:19 [DEBUG] memberlist: Using dynamic bind port 34395 +--- PASS: TestCreate (0.25s) === RUN TestMemberList_CreateShutdown -127.0.0.242024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 37373 +127.0.0.242024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 36049 --- PASS: TestMemberList_CreateShutdown (0.00s) === RUN TestMemberList_ResolveAddr -127.0.0.252024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 36533 +127.0.0.252024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 44735 === RUN TestMemberList_ResolveAddr/localhost === PAUSE TestMemberList_ResolveAddr/localhost === RUN TestMemberList_ResolveAddr/localhost_(tagged) @@ -1052,320 +1088,331 @@ === RUN TestMemberList_ResolveAddr/ipv6_only_(tagged) === PAUSE TestMemberList_ResolveAddr/ipv6_only_(tagged) === CONT TestMemberList_ResolveAddr/localhost -=== CONT TestMemberList_ResolveAddr/ipv6_only_(tagged) -=== CONT TestMemberList_ResolveAddr/ipv6_only +=== CONT TestMemberList_ResolveAddr/ipv4_port_combo_(tagged) +=== CONT TestMemberList_ResolveAddr/hostless_port_(tagged) === CONT TestMemberList_ResolveAddr/ipv4_only_(tagged) +=== CONT TestMemberList_ResolveAddr/ipv6_only_(tagged) === CONT TestMemberList_ResolveAddr/ipv4_only -=== CONT TestMemberList_ResolveAddr/ipv4_port_combo_with_empty_tag -=== CONT TestMemberList_ResolveAddr/ipv6_port_combo_(tagged) === CONT TestMemberList_ResolveAddr/ipv6_port_combo -=== CONT TestMemberList_ResolveAddr/ipv4_port_combo_(tagged) +=== CONT TestMemberList_ResolveAddr/ipv6_non-pair +=== CONT TestMemberList_ResolveAddr/ipv6_only +=== CONT TestMemberList_ResolveAddr/hostless_port === CONT TestMemberList_ResolveAddr/ipv4_port_combo -=== CONT TestMemberList_ResolveAddr/too_high_port_(tagged) +=== CONT TestMemberList_ResolveAddr/ipv6_pair +=== CONT TestMemberList_ResolveAddr/ipv6_pair_(tagged) === CONT TestMemberList_ResolveAddr/too_high_port === CONT TestMemberList_ResolveAddr/hostname_port_combo_(tagged) === CONT TestMemberList_ResolveAddr/hostname_port_combo -=== CONT TestMemberList_ResolveAddr/hostless_port_(tagged) -=== CONT TestMemberList_ResolveAddr/hostless_port +=== CONT TestMemberList_ResolveAddr/ipv6_port_combo_(tagged) +=== CONT TestMemberList_ResolveAddr/ipv4_port_combo_with_empty_tag +=== CONT TestMemberList_ResolveAddr/too_high_port_(tagged) === CONT TestMemberList_ResolveAddr/ipv6_non-pair_(tagged) -=== CONT TestMemberList_ResolveAddr/ipv6_non-pair -=== CONT TestMemberList_ResolveAddr/ipv6_pair_(tagged) -=== CONT TestMemberList_ResolveAddr/ipv6_pair === CONT TestMemberList_ResolveAddr/localhost_(tagged) --- PASS: TestMemberList_ResolveAddr (0.00s) - --- PASS: TestMemberList_ResolveAddr/localhost (0.00s) + --- PASS: TestMemberList_ResolveAddr/ipv4_port_combo_(tagged) (0.00s) + --- PASS: TestMemberList_ResolveAddr/hostless_port_(tagged) (0.00s) + --- PASS: TestMemberList_ResolveAddr/ipv4_only_(tagged) (0.00s) + --- PASS: TestMemberList_ResolveAddr/ipv6_port_combo (0.00s) + --- PASS: TestMemberList_ResolveAddr/ipv6_non-pair (0.00s) --- PASS: TestMemberList_ResolveAddr/ipv6_only_(tagged) (0.00s) --- PASS: TestMemberList_ResolveAddr/ipv6_only (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv4_only_(tagged) (0.00s) + --- PASS: TestMemberList_ResolveAddr/hostless_port (0.00s) + --- PASS: TestMemberList_ResolveAddr/ipv4_port_combo (0.00s) + --- PASS: TestMemberList_ResolveAddr/ipv6_pair_(tagged) (0.00s) + --- PASS: TestMemberList_ResolveAddr/ipv6_pair (0.00s) + --- PASS: TestMemberList_ResolveAddr/too_high_port (0.00s) --- PASS: TestMemberList_ResolveAddr/ipv4_only (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv4_port_combo_with_empty_tag (0.00s) --- PASS: TestMemberList_ResolveAddr/ipv6_port_combo_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv6_port_combo (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv4_port_combo_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv4_port_combo (0.00s) + --- PASS: TestMemberList_ResolveAddr/ipv4_port_combo_with_empty_tag (0.00s) + --- PASS: TestMemberList_ResolveAddr/hostname_port_combo (0.00s) + --- PASS: TestMemberList_ResolveAddr/localhost (0.00s) --- PASS: TestMemberList_ResolveAddr/too_high_port_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/too_high_port (0.00s) --- PASS: TestMemberList_ResolveAddr/hostname_port_combo_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/hostname_port_combo (0.00s) - --- PASS: TestMemberList_ResolveAddr/hostless_port_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/hostless_port (0.00s) --- PASS: TestMemberList_ResolveAddr/ipv6_non-pair_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv6_non-pair (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv6_pair_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv6_pair (0.00s) --- PASS: TestMemberList_ResolveAddr/localhost_(tagged) (0.00s) === RUN TestMemberList_ResolveAddr_TCP_First -127.0.0.262024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 37007 +127.0.0.262024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 35799 --- PASS: TestMemberList_ResolveAddr_TCP_First (0.00s) === RUN TestMemberList_Members --- PASS: TestMemberList_Members (0.00s) === RUN TestMemberlist_Join -127.0.0.272024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 34363 -127.0.0.272024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:58114 -127.0.0.282024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.27 127.0.0.27:34363 +127.0.0.272024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 40527 +127.0.0.282024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.27 127.0.0.27:40527 +127.0.0.272024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:58066 --- PASS: TestMemberlist_Join (0.00s) === RUN TestMemberlist_Join_with_Labels -127.0.0.292024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 35697 +127.0.0.292024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 41589 === RUN TestMemberlist_Join_with_Labels/same_label_can_join -127.0.0.292024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:40694 -127.0.0.302024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.29 127.0.0.29:35697 +127.0.0.302024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.29 127.0.0.29:41589 +127.0.0.292024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:59878 === RUN TestMemberlist_Join_with_Labels/no_label_cannot_join -127.0.0.292024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:40698 -127.0.0.312024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.29 127.0.0.29:35697 -127.0.0.292024/05/09 10:56:38 [ERR] memberlist: discarding stream with unacceptable label "": from=127.0.0.1:40698 -127.0.0.312024/05/09 10:56:38 [DEBUG] memberlist: Failed to join 127.0.0.29:35697: EOF +127.0.0.312024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.29 127.0.0.29:41589 +127.0.0.292024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:59886 +127.0.0.292024/05/09 10:58:20 [ERR] memberlist: discarding stream with unacceptable label "": from=127.0.0.1:59886 +127.0.0.312024/05/09 10:58:20 [DEBUG] memberlist: Failed to join 127.0.0.29:41589: EOF === RUN TestMemberlist_Join_with_Labels/mismatched_label_cannot_join -127.0.0.322024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.29 127.0.0.29:35697 -127.0.0.292024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:40700 -127.0.0.292024/05/09 10:56:38 [ERR] memberlist: discarding stream with unacceptable label "not-blah": from=127.0.0.1:40700 -127.0.0.322024/05/09 10:56:38 [DEBUG] memberlist: Failed to join 127.0.0.29:35697: EOF ---- PASS: TestMemberlist_Join_with_Labels (0.05s) +127.0.0.322024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.29 127.0.0.29:41589 +127.0.0.292024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:59900 +127.0.0.292024/05/09 10:58:20 [ERR] memberlist: discarding stream with unacceptable label "not-blah": from=127.0.0.1:59900 +127.0.0.322024/05/09 10:58:20 [DEBUG] memberlist: Failed to join 127.0.0.29:41589: EOF +--- PASS: TestMemberlist_Join_with_Labels (0.01s) --- PASS: TestMemberlist_Join_with_Labels/same_label_can_join (0.00s) - --- PASS: TestMemberlist_Join_with_Labels/no_label_cannot_join (0.03s) - --- PASS: TestMemberlist_Join_with_Labels/mismatched_label_cannot_join (0.02s) + --- PASS: TestMemberlist_Join_with_Labels/no_label_cannot_join (0.00s) + --- PASS: TestMemberlist_Join_with_Labels/mismatched_label_cannot_join (0.00s) === RUN TestMemberlist_Join_with_Labels_and_Encryption -127.0.0.332024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 45369 +127.0.0.332024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 42835 === RUN TestMemberlist_Join_with_Labels_and_Encryption/same_label_can_join -127.0.0.332024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:55062 -127.0.0.342024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.33 127.0.0.33:45369 +127.0.0.342024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.33 127.0.0.33:42835 +127.0.0.332024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:36750 === RUN TestMemberlist_Join_with_Labels_and_Encryption/no_label_cannot_join -127.0.0.352024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.33 127.0.0.33:45369 -127.0.0.332024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:55074 -127.0.0.332024/05/09 10:56:38 [ERR] memberlist: discarding stream with unacceptable label "": from=127.0.0.1:55074 -127.0.0.352024/05/09 10:56:38 [DEBUG] memberlist: Failed to join 127.0.0.33:45369: EOF +127.0.0.352024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.33 127.0.0.33:42835 +127.0.0.332024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:36764 +127.0.0.332024/05/09 10:58:20 [ERR] memberlist: discarding stream with unacceptable label "": from=127.0.0.1:36764 +127.0.0.352024/05/09 10:58:20 [DEBUG] memberlist: Failed to join 127.0.0.33:42835: EOF === RUN TestMemberlist_Join_with_Labels_and_Encryption/mismatched_label_cannot_join -127.0.0.362024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.33 127.0.0.33:45369 -127.0.0.332024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:55082 -127.0.0.332024/05/09 10:56:38 [ERR] memberlist: discarding stream with unacceptable label "not-blah": from=127.0.0.1:55082 -127.0.0.362024/05/09 10:56:38 [DEBUG] memberlist: Failed to join 127.0.0.33:45369: EOF ---- PASS: TestMemberlist_Join_with_Labels_and_Encryption (0.01s) +127.0.0.362024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.33 127.0.0.33:42835 +127.0.0.332024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:36770 +127.0.0.332024/05/09 10:58:20 [ERR] memberlist: discarding stream with unacceptable label "not-blah": from=127.0.0.1:36770 +127.0.0.362024/05/09 10:58:20 [DEBUG] memberlist: Failed to join 127.0.0.33:42835: read tcp 127.0.0.1:36770->127.0.0.33:42835: read: connection reset by peer +--- PASS: TestMemberlist_Join_with_Labels_and_Encryption (0.00s) --- PASS: TestMemberlist_Join_with_Labels_and_Encryption/same_label_can_join (0.00s) --- PASS: TestMemberlist_Join_with_Labels_and_Encryption/no_label_cannot_join (0.00s) --- PASS: TestMemberlist_Join_with_Labels_and_Encryption/mismatched_label_cannot_join (0.00s) === RUN TestMemberlist_JoinDifferentNetworksUniqueMask -127.0.0.372024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 40577 -127.0.0.372024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:56440 -127.0.1.382024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.37 127.0.0.37:40577 +127.0.0.372024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 35247 +127.0.1.382024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.37 127.0.0.37:35247 +127.0.0.372024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:40654 --- PASS: TestMemberlist_JoinDifferentNetworksUniqueMask (0.00s) === RUN TestMemberlist_JoinDifferentNetworksMultiMasks -127.0.0.392024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 37165 -127.0.0.392024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:37920 -127.0.1.402024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.39 127.0.0.39:37165 -127.0.2.412024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.39 127.0.0.39:37165 -127.0.0.392024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:37926 -127.0.0.392024/05/09 10:56:38 [WARN] memberlist: Rejected node 127.0.2.41 (127.0.2.41): 127.0.2.41 is not allowed -127.0.2.422024/05/09 10:56:38 [WARN] memberlist: Rejected node 127.0.2.42 (127.0.2.42): 127.0.2.42 is not allowed -127.0.2.422024/05/09 10:56:38 [DEBUG] memberlist: Failed to join 127.0.0.39:37165: memberlist: node names are required by configuration but one was not provided -127.0.2.422024/05/09 10:56:38 [DEBUG] memberlist: Failed to join 127.0.1.40:37165: memberlist: node names are required by configuration but one was not provided ---- PASS: TestMemberlist_JoinDifferentNetworksMultiMasks (0.01s) +127.0.0.392024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 43987 +127.0.0.392024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:57416 +127.0.1.402024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.39 127.0.0.39:43987 +127.0.2.412024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.39 127.0.0.39:43987 +127.0.0.392024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:57430 +127.0.0.392024/05/09 10:58:20 [WARN] memberlist: Rejected node 127.0.2.41 (127.0.2.41): 127.0.2.41 is not allowed +127.0.2.422024/05/09 10:58:20 [WARN] memberlist: Rejected node 127.0.2.42 (127.0.2.42): 127.0.2.42 is not allowed +127.0.2.422024/05/09 10:58:20 [DEBUG] memberlist: Failed to join 127.0.0.39:43987: memberlist: node names are required by configuration but one was not provided +127.0.2.422024/05/09 10:58:20 [DEBUG] memberlist: Failed to join 127.0.1.40:43987: memberlist: node names are required by configuration but one was not provided +--- PASS: TestMemberlist_JoinDifferentNetworksMultiMasks (0.00s) === RUN TestMemberlist_Join_Cancel -127.0.0.432024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 34129 -127.0.0.442024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.43 127.0.0.43:34129 -127.0.0.432024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:60666 +127.0.0.432024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 44453 +127.0.0.442024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.43 127.0.0.43:44453 +127.0.0.432024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:46322 memberlist_test.go:873: Cancel merge -127.0.0.432024/05/09 10:56:38 [ERR] memberlist: Failed push/pull merge: Custom merge canceled from=127.0.0.1:60666 +127.0.0.432024/05/09 10:58:20 [ERR] memberlist: Failed push/pull merge: Custom merge canceled from=127.0.0.1:46322 memberlist_test.go:873: Cancel merge -127.0.0.442024/05/09 10:56:38 [DEBUG] memberlist: Failed to join 127.0.0.43:34129: Custom merge canceled ---- PASS: TestMemberlist_Join_Cancel (0.02s) +127.0.0.442024/05/09 10:58:20 [DEBUG] memberlist: Failed to join 127.0.0.43:44453: Custom merge canceled +--- PASS: TestMemberlist_Join_Cancel (0.00s) === RUN TestMemberlist_Join_Cancel_Passive -127.0.0.452024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 42023 -127.0.0.452024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:33608 -127.0.0.462024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.45 127.0.0.45:42023 +127.0.0.452024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 40187 +127.0.0.462024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.45 127.0.0.45:40187 +127.0.0.452024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:46482 memberlist_test.go:936: Cancel alive -127.0.0.452024/05/09 10:56:38 [WARN] memberlist: ignoring alive message for '127.0.0.46': Custom alive canceled +127.0.0.452024/05/09 10:58:20 [WARN] memberlist: ignoring alive message for '127.0.0.46': Custom alive canceled memberlist_test.go:936: Cancel alive -127.0.0.462024/05/09 10:56:38 [WARN] memberlist: ignoring alive message for '127.0.0.45': Custom alive canceled +127.0.0.462024/05/09 10:58:20 [WARN] memberlist: ignoring alive message for '127.0.0.45': Custom alive canceled --- PASS: TestMemberlist_Join_Cancel_Passive (0.00s) === RUN TestMemberlist_Join_protocolVersions -127.0.0.472024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 39629 -127.0.0.482024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:59938 -127.0.0.472024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.48 127.0.0.48:39629 -127.0.0.472024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.49 127.0.0.49:39629 -127.0.0.492024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:45196 ---- PASS: TestMemberlist_Join_protocolVersions (0.26s) +127.0.0.472024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 45237 +127.0.0.472024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.48 127.0.0.48:45237 +127.0.0.482024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:56356 +127.0.0.472024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.49 127.0.0.49:45237 +127.0.0.492024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:42662 +--- PASS: TestMemberlist_Join_protocolVersions (0.25s) === RUN TestMemberlist_Leave -127.0.0.502024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 39129 -127.0.0.502024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:45874 -127.0.0.512024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.50 127.0.0.50:39129 ---- PASS: TestMemberlist_Leave (0.05s) +127.0.0.502024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 45917 +127.0.0.512024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.50 127.0.0.50:45917 +127.0.0.502024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:43370 +--- PASS: TestMemberlist_Leave (0.02s) === RUN TestMemberlist_JoinShutdown -127.0.0.522024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 39539 -127.0.0.522024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:36422 -127.0.0.532024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.52 127.0.0.52:39539 -127.0.0.532024/05/09 10:56:38 [DEBUG] memberlist: Failed UDP ping: 127.0.0.52 (timeout reached) -127.0.0.532024/05/09 10:56:38 [INFO] memberlist: Suspect 127.0.0.52 has failed, no acks received -127.0.0.532024/05/09 10:56:38 [INFO] memberlist: Marking 127.0.0.52 as failed, suspect timeout reached (0 peer confirmations) -127.0.0.532024/05/09 10:56:38 [DEBUG] memberlist: Failed UDP ping: 127.0.0.52 (timeout reached) -127.0.0.532024/05/09 10:56:38 [INFO] memberlist: Suspect 127.0.0.52 has failed, no acks received ---- PASS: TestMemberlist_JoinShutdown (0.02s) +127.0.0.522024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 46163 +127.0.0.522024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:50794 +127.0.0.532024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.52 127.0.0.52:46163 +127.0.0.522024/05/09 10:58:20 [DEBUG] memberlist: Failed UDP ping: 127.0.0.53 (timeout reached) +127.0.0.532024/05/09 10:58:20 [DEBUG] memberlist: Failed UDP ping: 127.0.0.52 (timeout reached) +127.0.0.532024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:51274 +127.0.0.522024/05/09 10:58:20 [WARN] memberlist: Was able to connect to 127.0.0.53 over TCP but UDP probes failed, network may be misconfigured +127.0.0.532024/05/09 10:58:20 [INFO] memberlist: Suspect 127.0.0.52 has failed, no acks received +127.0.0.532024/05/09 10:58:20 [DEBUG] memberlist: Failed UDP ping: 127.0.0.52 (timeout reached) +127.0.0.532024/05/09 10:58:20 [INFO] memberlist: Suspect 127.0.0.52 has failed, no acks received +127.0.0.532024/05/09 10:58:20 [INFO] memberlist: Marking 127.0.0.52 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.532024/05/09 10:58:20 [DEBUG] memberlist: Failed UDP ping: 127.0.0.52 (timeout reached) +127.0.0.532024/05/09 10:58:20 [INFO] memberlist: Suspect 127.0.0.52 has failed, no acks received +--- PASS: TestMemberlist_JoinShutdown (0.01s) === RUN TestMemberlist_delegateMeta -127.0.0.542024/05/09 10:56:38 [DEBUG] memberlist: Using dynamic bind port 35463 -127.0.0.552024/05/09 10:56:38 [DEBUG] memberlist: Stream connection from=127.0.0.1:57526 -127.0.0.542024/05/09 10:56:38 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.55 127.0.0.55:35463 ---- PASS: TestMemberlist_delegateMeta (0.26s) +127.0.0.542024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 38179 +127.0.0.542024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.55 127.0.0.55:38179 +127.0.0.552024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:53558 +--- PASS: TestMemberlist_delegateMeta (0.25s) === RUN TestMemberlist_delegateMeta_Update -127.0.0.562024/05/09 10:56:39 [DEBUG] memberlist: Using dynamic bind port 38549 -127.0.0.572024/05/09 10:56:39 [DEBUG] memberlist: Stream connection from=127.0.0.1:51518 -127.0.0.562024/05/09 10:56:39 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.57 127.0.0.57:38549 ---- PASS: TestMemberlist_delegateMeta_Update (1.66s) +127.0.0.562024/05/09 10:58:20 [DEBUG] memberlist: Using dynamic bind port 34191 +127.0.0.562024/05/09 10:58:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.57 127.0.0.57:34191 +127.0.0.572024/05/09 10:58:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:60572 +--- PASS: TestMemberlist_delegateMeta_Update (1.85s) === RUN TestMemberlist_UserData -127.0.0.582024/05/09 10:56:40 [DEBUG] memberlist: Using dynamic bind port 44797 -127.0.0.582024/05/09 10:56:40 [DEBUG] memberlist: Stream connection from=127.0.0.1:56514 -127.0.0.592024/05/09 10:56:40 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.58 127.0.0.58:44797 -127.0.0.592024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.58 127.0.0.58:44797 -127.0.0.582024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:56516 +127.0.0.582024/05/09 10:58:22 [DEBUG] memberlist: Using dynamic bind port 41341 +127.0.0.592024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.58 127.0.0.58:41341 +127.0.0.582024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:60412 +127.0.0.582024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.59 127.0.0.59:41341 +127.0.0.592024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:58004 --- PASS: TestMemberlist_UserData (0.13s) === RUN TestMemberlist_SendTo -127.0.0.602024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 41993 -127.0.0.602024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:57522 -127.0.0.612024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.60 127.0.0.60:41993 -127.0.0.612024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.60 127.0.0.60:41993 -127.0.0.602024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:57528 -127.0.0.602024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.61 127.0.0.61:41993 -127.0.0.612024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:40326 ---- PASS: TestMemberlist_SendTo (0.03s) +127.0.0.602024/05/09 10:58:22 [DEBUG] memberlist: Using dynamic bind port 44085 +127.0.0.612024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.60 127.0.0.60:44085 +127.0.0.602024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:53084 +127.0.0.602024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.61 127.0.0.61:44085 +127.0.0.612024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:39540 +127.0.0.612024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.60 127.0.0.60:44085 +127.0.0.602024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.61 127.0.0.61:44085 +127.0.0.612024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:39556 +127.0.0.602024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:53092 +127.0.0.602024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:53104 +127.0.0.612024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:39564 +127.0.0.612024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.60 127.0.0.60:44085 +127.0.0.602024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.61 127.0.0.61:44085 +--- PASS: TestMemberlist_SendTo (0.01s) === RUN TestMemberlistProtocolVersion -127.0.0.622024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 44083 -127.0.0.602024/05/09 10:56:41 [ERR] memberlist: Failed to send gossip to 127.0.0.61:41993: write udp 127.0.0.60:41993->127.0.0.61:41993: use of closed network connection +127.0.0.622024/05/09 10:58:22 [DEBUG] memberlist: Using dynamic bind port 38547 --- PASS: TestMemberlistProtocolVersion (0.00s) === RUN TestMemberlist_Join_DeadNode -127.0.0.632024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 37507 -127.0.0.632024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: fake 127.0.0.64:37507 -127.0.0.632024/05/09 10:56:41 [DEBUG] memberlist: Failed to join 127.0.0.64:37507: read tcp 127.0.0.1:44296->127.0.0.64:37507: i/o timeout ---- PASS: TestMemberlist_Join_DeadNode (0.06s) +127.0.0.632024/05/09 10:58:22 [DEBUG] memberlist: Using dynamic bind port 43635 +127.0.0.632024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: fake 127.0.0.64:43635 +127.0.0.632024/05/09 10:58:22 [DEBUG] memberlist: Failed to join 127.0.0.64:43635: read tcp 127.0.0.1:52726->127.0.0.64:43635: i/o timeout +--- PASS: TestMemberlist_Join_DeadNode (0.05s) === RUN TestMemberlist_Join_Protocol_Compatibility === RUN TestMemberlist_Join_Protocol_Compatibility/2,1 -127.0.0.652024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 34131 -127.0.0.652024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:43152 -127.0.0.662024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.65 127.0.0.65:34131 +127.0.0.652024/05/09 10:58:22 [DEBUG] memberlist: Using dynamic bind port 39017 +127.0.0.662024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.65 127.0.0.65:39017 +127.0.0.652024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:45212 === RUN TestMemberlist_Join_Protocol_Compatibility/2,3 -127.0.0.672024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 38453 -127.0.0.672024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:47952 -127.0.0.682024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.67 127.0.0.67:38453 +127.0.0.672024/05/09 10:58:22 [DEBUG] memberlist: Using dynamic bind port 44449 +127.0.0.682024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.67 127.0.0.67:44449 +127.0.0.672024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:45820 === RUN TestMemberlist_Join_Protocol_Compatibility/3,2 -127.0.0.692024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 35659 -127.0.0.692024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:51456 -127.0.0.702024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.69 127.0.0.69:35659 +127.0.0.692024/05/09 10:58:22 [DEBUG] memberlist: Using dynamic bind port 37045 +127.0.0.702024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.69 127.0.0.69:37045 +127.0.0.692024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:49848 === RUN TestMemberlist_Join_Protocol_Compatibility/3,1 -127.0.0.712024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 33809 -127.0.0.722024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.71 127.0.0.71:33809 -127.0.0.712024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:59056 ---- PASS: TestMemberlist_Join_Protocol_Compatibility (0.02s) +127.0.0.712024/05/09 10:58:22 [DEBUG] memberlist: Using dynamic bind port 44993 +127.0.0.722024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.71 127.0.0.71:44993 +127.0.0.712024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:41220 +--- PASS: TestMemberlist_Join_Protocol_Compatibility (0.01s) --- PASS: TestMemberlist_Join_Protocol_Compatibility/2,1 (0.00s) --- PASS: TestMemberlist_Join_Protocol_Compatibility/2,3 (0.00s) --- PASS: TestMemberlist_Join_Protocol_Compatibility/3,2 (0.00s) - --- PASS: TestMemberlist_Join_Protocol_Compatibility/3,1 (0.02s) + --- PASS: TestMemberlist_Join_Protocol_Compatibility/3,1 (0.00s) === RUN TestMemberlist_Join_IPv6 -A2024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 33279 -B2024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 45619 -A2024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=[::1]:45954 -B2024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: A [::1]:33279 +A2024/05/09 10:58:22 [DEBUG] memberlist: Using dynamic bind port 34505 +B2024/05/09 10:58:22 [DEBUG] memberlist: Using dynamic bind port 44735 +B2024/05/09 10:58:22 [DEBUG] memberlist: Initiating push/pull sync with: A [::1]:34505 +A2024/05/09 10:58:22 [DEBUG] memberlist: Stream connection from=[::1]:49684 --- PASS: TestMemberlist_Join_IPv6 (0.00s) === RUN TestAdvertiseAddr - memberlist_test.go:1647: Using dynamic bind port 46661 for BIND - memberlist_test.go:1647: Using dynamic bind port 37015 for ADVERTISE + memberlist_test.go:1647: Using dynamic bind port 40965 for BIND + memberlist_test.go:1647: Using dynamic bind port 35209 for ADVERTISE --- PASS: TestAdvertiseAddr (0.25s) === RUN TestMemberlist_conflictDelegate -127.0.0.752024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 34371 -127.0.0.762024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:44506 -127.0.0.752024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.75 127.0.0.76:34371 -127.0.0.762024/05/09 10:56:41 [ERR] memberlist: Conflicting address for 127.0.0.75. Mine: 127.0.0.76:34371 Theirs: 127.0.0.75:34371 Old state: 0 -127.0.0.752024/05/09 10:56:41 [ERR] memberlist: Conflicting address for 127.0.0.75. Mine: 127.0.0.75:34371 Theirs: 127.0.0.76:34371 Old state: 0 +127.0.0.752024/05/09 10:58:23 [DEBUG] memberlist: Using dynamic bind port 36423 +127.0.0.752024/05/09 10:58:23 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.75 127.0.0.76:36423 +127.0.0.762024/05/09 10:58:23 [DEBUG] memberlist: Stream connection from=127.0.0.1:53664 +127.0.0.762024/05/09 10:58:23 [ERR] memberlist: Conflicting address for 127.0.0.75. Mine: 127.0.0.76:36423 Theirs: 127.0.0.75:36423 Old state: 0 +127.0.0.752024/05/09 10:58:23 [ERR] memberlist: Conflicting address for 127.0.0.75. Mine: 127.0.0.75:36423 Theirs: 127.0.0.76:36423 Old state: 0 --- PASS: TestMemberlist_conflictDelegate (0.25s) === RUN TestMemberlist_PingDelegate -127.0.0.772024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 45099 -127.0.0.772024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:37070 -127.0.0.782024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.77 127.0.0.77:45099 +127.0.0.772024/05/09 10:58:23 [DEBUG] memberlist: Using dynamic bind port 39021 +127.0.0.782024/05/09 10:58:23 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.77 127.0.0.77:39021 +127.0.0.772024/05/09 10:58:23 [DEBUG] memberlist: Stream connection from=127.0.0.1:37538 --- PASS: TestMemberlist_PingDelegate (0.20s) === RUN TestMemberlist_EncryptedGossipTransition -m02024/05/09 10:56:41 [DEBUG] memberlist: Using dynamic bind port 33705 +m02024/05/09 10:58:23 [DEBUG] memberlist: Using dynamic bind port 42623 memberlist_test.go:1977: Node m1[127.0.0.80] joining node m0[127.0.0.79] -127.0.0.772024/05/09 10:56:41 [ERR] memberlist: Failed to send gossip to 127.0.0.78:45099: write udp 127.0.0.77:45099->127.0.0.78:45099: use of closed network connection -127.0.0.782024/05/09 10:56:41 [ERR] memberlist: Failed to send gossip to 127.0.0.77:45099: write udp 127.0.0.78:45099->127.0.0.77:45099: use of closed network connection -m02024/05/09 10:56:41 [DEBUG] memberlist: Stream connection from=127.0.0.1:43734 -m12024/05/09 10:56:41 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.79 127.0.0.79:33705 +m12024/05/09 10:58:23 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.79 127.0.0.79:42623 +m02024/05/09 10:58:23 [DEBUG] memberlist: Stream connection from=127.0.0.1:40716 memberlist_test.go:1979: ==== STEP 0 complete: two node unencrypted cluster ==== memberlist_test.go:1984: Node m0[127.0.0.79] is leaving to upgrade gossip to first stage memberlist_test.go:1984: Node m0[127.0.0.79] is shutting down to upgrade gossip to first stage memberlist_test.go:1996: Node m0[127.0.0.79] joining node m1[127.0.0.80] -m02024/05/09 10:56:42 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.80 127.0.0.80:33705 -m12024/05/09 10:56:42 [DEBUG] memberlist: Stream connection from=127.0.0.1:41198 -m02024/05/09 10:56:42 [WARN] memberlist: Refuting a dead message (from: 127.0.0.79) +m02024/05/09 10:58:23 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.80 127.0.0.80:42623 +m12024/05/09 10:58:23 [DEBUG] memberlist: Stream connection from=127.0.0.1:52554 +m02024/05/09 10:58:23 [WARN] memberlist: Refuting a dead message (from: 127.0.0.79) memberlist_test.go:1998: Node m1[127.0.0.80] is leaving to upgrade gossip to first stage memberlist_test.go:1998: Node m1[127.0.0.80] is shutting down to upgrade gossip to first stage memberlist_test.go:2010: Node m1[127.0.0.80] joining node m0[127.0.0.79] -m12024/05/09 10:56:43 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.79 127.0.0.79:33705 -m02024/05/09 10:56:43 [DEBUG] memberlist: Stream connection from=127.0.0.1:51662 -m12024/05/09 10:56:43 [WARN] memberlist: Refuting a dead message (from: 127.0.0.80) +m12024/05/09 10:58:24 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.79 127.0.0.79:42623 +m02024/05/09 10:58:24 [DEBUG] memberlist: Stream connection from=127.0.0.1:40720 +m12024/05/09 10:58:24 [WARN] memberlist: Refuting a dead message (from: 127.0.0.80) memberlist_test.go:2012: ==== STEP 1 complete: two node encryption-aware cluster ==== memberlist_test.go:2017: Node m0[127.0.0.79] is leaving to upgrade gossip to second stage memberlist_test.go:2017: Node m0[127.0.0.79] is shutting down to upgrade gossip to second stage memberlist_test.go:2028: Node m0[127.0.0.79] joining node m1[127.0.0.80] -m02024/05/09 10:56:43 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.80 127.0.0.80:33705 -m12024/05/09 10:56:43 [DEBUG] memberlist: Stream connection from=127.0.0.1:41212 -m02024/05/09 10:56:43 [WARN] memberlist: Refuting a dead message (from: 127.0.0.79) +m02024/05/09 10:58:25 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.80 127.0.0.80:42623 +m12024/05/09 10:58:25 [DEBUG] memberlist: Stream connection from=127.0.0.1:52556 +m02024/05/09 10:58:25 [WARN] memberlist: Refuting a dead message (from: 127.0.0.79) memberlist_test.go:2030: Node m1[127.0.0.80] is leaving to upgrade gossip to second stage memberlist_test.go:2030: Node m1[127.0.0.80] is shutting down to upgrade gossip to second stage memberlist_test.go:2042: Node m1[127.0.0.80] joining node m0[127.0.0.79] -m12024/05/09 10:56:44 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.79 127.0.0.79:33705 -m02024/05/09 10:56:44 [DEBUG] memberlist: Stream connection from=127.0.0.1:51672 -m12024/05/09 10:56:44 [WARN] memberlist: Refuting a dead message (from: 127.0.0.80) +m12024/05/09 10:58:26 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.79 127.0.0.79:42623 +m02024/05/09 10:58:26 [DEBUG] memberlist: Stream connection from=127.0.0.1:40722 +m12024/05/09 10:58:26 [WARN] memberlist: Refuting a dead message (from: 127.0.0.80) +m12024/05/09 10:58:27 [DEBUG] memberlist: Failed UDP ping: 127.0.0.79 (timeout reached) +m02024/05/09 10:58:27 [DEBUG] memberlist: Stream connection from=127.0.0.1:40732 memberlist_test.go:2044: ==== STEP 2 complete: two node encryption-aware cluster being encrypted ==== memberlist_test.go:2049: Node m0[127.0.0.79] is leaving to upgrade gossip to final stage memberlist_test.go:2049: Node m0[127.0.0.79] is shutting down to upgrade gossip to final stage memberlist_test.go:2060: Node m0[127.0.0.79] joining node m1[127.0.0.80] -m02024/05/09 10:56:45 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.80 127.0.0.80:33705 -m12024/05/09 10:56:45 [DEBUG] memberlist: Stream connection from=127.0.0.1:41226 -m02024/05/09 10:56:45 [WARN] memberlist: Refuting a dead message (from: 127.0.0.79) +m02024/05/09 10:58:27 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.80 127.0.0.80:42623 +m12024/05/09 10:58:27 [DEBUG] memberlist: Stream connection from=127.0.0.1:52572 +m02024/05/09 10:58:27 [WARN] memberlist: Refuting a dead message (from: 127.0.0.79) memberlist_test.go:2062: Node m1[127.0.0.80] is leaving to upgrade gossip to final stage memberlist_test.go:2062: Node m1[127.0.0.80] is shutting down to upgrade gossip to final stage memberlist_test.go:2072: Node m1[127.0.0.80] joining node m0[127.0.0.79] -m12024/05/09 10:56:46 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.79 127.0.0.79:33705 -m02024/05/09 10:56:46 [DEBUG] memberlist: Stream connection from=127.0.0.1:51686 -m12024/05/09 10:56:46 [WARN] memberlist: Refuting a dead message (from: 127.0.0.80) +m12024/05/09 10:58:28 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.79 127.0.0.79:42623 +m02024/05/09 10:58:28 [DEBUG] memberlist: Stream connection from=127.0.0.1:42354 +m12024/05/09 10:58:28 [WARN] memberlist: Refuting a dead message (from: 127.0.0.80) +m12024/05/09 10:58:28 [WARN] memberlist: Was able to connect to 127.0.0.79 over TCP but UDP probes failed, network may be misconfigured memberlist_test.go:2074: ==== STEP 3 complete: two node encrypted cluster locked down ==== ---- PASS: TestMemberlist_EncryptedGossipTransition (5.42s) +--- PASS: TestMemberlist_EncryptedGossipTransition (5.51s) === RUN TestHandleCompoundPing -127.0.0.812024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 34781 +127.0.0.812024/05/09 10:58:28 [DEBUG] memberlist: Using dynamic bind port 35507 --- PASS: TestHandleCompoundPing (0.00s) === RUN TestHandlePing -127.0.0.822024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 41785 +127.0.0.822024/05/09 10:58:28 [DEBUG] memberlist: Using dynamic bind port 37251 --- PASS: TestHandlePing (0.00s) === RUN TestHandlePing_WrongNode -127.0.0.832024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 45343 -127.0.0.832024/05/09 10:56:47 [WARN] memberlist: Got ping for unexpected node '127.0.0.83-bad' from=127.0.0.1:60000 +127.0.0.832024/05/09 10:58:28 [DEBUG] memberlist: Using dynamic bind port 42287 +127.0.0.832024/05/09 10:58:28 [WARN] memberlist: Got ping for unexpected node '127.0.0.83-bad' from=127.0.0.1:60000 --- PASS: TestHandlePing_WrongNode (0.05s) === RUN TestHandleIndirectPing -127.0.0.842024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 44233 +127.0.0.842024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 38153 --- PASS: TestHandleIndirectPing (0.00s) === RUN TestTCPPing -127.0.0.852024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 32955 +127.0.0.852024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 46213 --- PASS: TestTCPPing (0.00s) === RUN TestTCPPushPull -127.0.0.862024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 43085 -127.0.0.862024/05/09 10:56:47 [DEBUG] memberlist: Stream connection from=127.0.0.1:34436 +127.0.0.862024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 43619 +127.0.0.862024/05/09 10:58:29 [DEBUG] memberlist: Stream connection from=127.0.0.1:35786 --- PASS: TestTCPPushPull (0.00s) === RUN TestSendMsg_Piggyback -127.0.0.872024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 40095 +127.0.0.872024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 45593 --- PASS: TestSendMsg_Piggyback (0.00s) === RUN TestEncryptDecryptState -2024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 35755 +2024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 35739 --- PASS: TestEncryptDecryptState (0.00s) === RUN TestRawSendUdp_CRC -127.0.0.882024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 44997 +127.0.0.882024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 36527 --- PASS: TestRawSendUdp_CRC (0.00s) === RUN TestIngestPacket_CRC -127.0.0.892024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 45081 +127.0.0.892024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 32783 --- PASS: TestIngestPacket_CRC (0.00s) === RUN TestIngestPacket_ExportedFunc_EmptyMessage -127.0.0.902024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 40027 +127.0.0.902024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 41867 --- PASS: TestIngestPacket_ExportedFunc_EmptyMessage (0.00s) === RUN TestGossip_MismatchedKeys -127.0.0.912024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 39603 -127.0.0.912024/05/09 10:56:47 [DEBUG] memberlist: Stream connection from=127.0.0.1:47806 -127.0.0.922024/05/09 10:56:47 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.91 127.0.0.91:39603 -127.0.0.912024/05/09 10:56:47 [ERR] memberlist: failed to receive: No installed keys could decrypt the message from=127.0.0.1:47806 -127.0.0.922024/05/09 10:56:47 [DEBUG] memberlist: Failed to join 127.0.0.91:39603: No installed keys could decrypt the message +127.0.0.912024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 46795 +127.0.0.922024/05/09 10:58:29 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.91 127.0.0.91:46795 +127.0.0.912024/05/09 10:58:29 [DEBUG] memberlist: Stream connection from=127.0.0.1:60302 +127.0.0.912024/05/09 10:58:29 [ERR] memberlist: failed to receive: No installed keys could decrypt the message from=127.0.0.1:60302 +127.0.0.922024/05/09 10:58:29 [DEBUG] memberlist: Failed to join 127.0.0.91:46795: No installed keys could decrypt the message --- PASS: TestGossip_MismatchedKeys (0.00s) === RUN TestHandleCommand --- PASS: TestHandleCommand (0.00s) @@ -1394,136 +1441,137 @@ === RUN TestEncryptDecrypt_V1 --- PASS: TestEncryptDecrypt_V1 (0.00s) === RUN TestMemberList_Probe -127.0.0.932024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 33727 -127.0.0.942024/05/09 10:56:47 [WARN] memberlist: Refuting an alive message for '127.0.0.94' (127.0.0.94:33727) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.932024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 46105 +127.0.0.942024/05/09 10:58:29 [WARN] memberlist: Refuting an alive message for '127.0.0.94' (127.0.0.94:46105) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) --- PASS: TestMemberList_Probe (0.00s) === RUN TestMemberList_ProbeNode_Suspect -127.0.0.952024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 44443 -127.0.0.952024/05/09 10:56:47 [DEBUG] memberlist: Failed UDP ping: 127.0.0.98 (timeout reached) -127.0.0.962024/05/09 10:56:47 [WARN] memberlist: Refuting an alive message for '127.0.0.96' (127.0.0.96:44443) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.972024/05/09 10:56:47 [WARN] memberlist: Refuting an alive message for '127.0.0.97' (127.0.0.97:44443) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.952024/05/09 10:56:47 [INFO] memberlist: Suspect 127.0.0.98 has failed, no acks received ---- PASS: TestMemberList_ProbeNode_Suspect (0.03s) +127.0.0.952024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 42465 +127.0.0.952024/05/09 10:58:29 [DEBUG] memberlist: Failed UDP ping: 127.0.0.98 (timeout reached) +127.0.0.962024/05/09 10:58:29 [WARN] memberlist: Refuting an alive message for '127.0.0.96' (127.0.0.96:42465) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.972024/05/09 10:58:29 [WARN] memberlist: Refuting an alive message for '127.0.0.97' (127.0.0.97:42465) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.952024/05/09 10:58:29 [INFO] memberlist: Suspect 127.0.0.98 has failed, no acks received +--- PASS: TestMemberList_ProbeNode_Suspect (0.02s) === RUN TestMemberList_ProbeNode_Suspect_Dogpile === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=2,_k=3_(max_timeout_disabled) -127.0.0.992024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 42771 -127.0.0.992024/05/09 10:56:47 [DEBUG] memberlist: Failed UDP ping: 127.0.0.100 (timeout reached) -127.0.0.992024/05/09 10:56:47 [INFO] memberlist: Suspect 127.0.0.100 has failed, no acks received -127.0.0.952024/05/09 10:56:47 [INFO] memberlist: Marking 127.0.0.98 as failed, suspect timeout reached (0 peer confirmations) -127.0.0.972024/05/09 10:56:47 [ERR] memberlist: Failed to send nack: write udp 127.0.0.97:44443->127.0.0.95:44443: use of closed network connection from=127.0.0.95:44443 -127.0.0.962024/05/09 10:56:47 [ERR] memberlist: Failed to send nack: write udp 127.0.0.96:44443->127.0.0.95:44443: use of closed network connection from=127.0.0.95:44443 -127.0.0.992024/05/09 10:56:47 [INFO] memberlist: Marking 127.0.0.100 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.992024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 42919 +127.0.0.992024/05/09 10:58:29 [DEBUG] memberlist: Failed UDP ping: 127.0.0.100 (timeout reached) +127.0.0.992024/05/09 10:58:29 [INFO] memberlist: Suspect 127.0.0.100 has failed, no acks received +127.0.0.952024/05/09 10:58:29 [INFO] memberlist: Marking 127.0.0.98 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.972024/05/09 10:58:29 [ERR] memberlist: Failed to send nack: write udp 127.0.0.97:42465->127.0.0.95:42465: use of closed network connection from=127.0.0.95:42465 +127.0.0.962024/05/09 10:58:29 [ERR] memberlist: Failed to send nack: write udp 127.0.0.96:42465->127.0.0.95:42465: use of closed network connection from=127.0.0.95:42465 +127.0.0.992024/05/09 10:58:29 [INFO] memberlist: Marking 127.0.0.100 as failed, suspect timeout reached (0 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=3,_k=3 -127.0.0.1012024/05/09 10:56:47 [DEBUG] memberlist: Using dynamic bind port 44555 -127.0.0.1012024/05/09 10:56:47 [DEBUG] memberlist: Failed UDP ping: 127.0.0.103 (timeout reached) -127.0.0.1012024/05/09 10:56:48 [INFO] memberlist: Suspect 127.0.0.103 has failed, no acks received -127.0.0.1012024/05/09 10:56:48 [INFO] memberlist: Marking 127.0.0.103 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.1012024/05/09 10:58:29 [DEBUG] memberlist: Using dynamic bind port 37169 +127.0.0.1012024/05/09 10:58:29 [DEBUG] memberlist: Failed UDP ping: 127.0.0.103 (timeout reached) +127.0.0.1022024/05/09 10:58:29 [WARN] memberlist: Refuting an alive message for '127.0.0.102' (127.0.0.102:37169) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1012024/05/09 10:58:29 [INFO] memberlist: Suspect 127.0.0.103 has failed, no acks received +127.0.0.1012024/05/09 10:58:30 [INFO] memberlist: Marking 127.0.0.103 as failed, suspect timeout reached (0 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=4,_k=3 -127.0.0.1042024/05/09 10:56:48 [DEBUG] memberlist: Using dynamic bind port 40483 -127.0.0.1042024/05/09 10:56:48 [DEBUG] memberlist: Failed UDP ping: 127.0.0.107 (timeout reached) -127.0.0.1062024/05/09 10:56:48 [WARN] memberlist: Refuting an alive message for '127.0.0.106' (127.0.0.106:40483) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1052024/05/09 10:56:48 [WARN] memberlist: Refuting an alive message for '127.0.0.105' (127.0.0.105:40483) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1042024/05/09 10:56:48 [INFO] memberlist: Suspect 127.0.0.107 has failed, no acks received -127.0.0.1042024/05/09 10:56:49 [INFO] memberlist: Marking 127.0.0.107 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.1042024/05/09 10:58:30 [DEBUG] memberlist: Using dynamic bind port 44641 +127.0.0.1042024/05/09 10:58:30 [DEBUG] memberlist: Failed UDP ping: 127.0.0.107 (timeout reached) +127.0.0.1062024/05/09 10:58:30 [WARN] memberlist: Refuting an alive message for '127.0.0.106' (127.0.0.106:44641) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1052024/05/09 10:58:30 [WARN] memberlist: Refuting an alive message for '127.0.0.105' (127.0.0.105:44641) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1042024/05/09 10:58:30 [INFO] memberlist: Suspect 127.0.0.107 has failed, no acks received +127.0.0.1042024/05/09 10:58:30 [INFO] memberlist: Marking 127.0.0.107 as failed, suspect timeout reached (0 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=5,_k=3_(max_timeout_starts_to_take_effect) -127.0.0.1082024/05/09 10:56:49 [DEBUG] memberlist: Using dynamic bind port 35371 -127.0.0.1082024/05/09 10:56:49 [DEBUG] memberlist: Failed UDP ping: 127.0.0.112 (timeout reached) -127.0.0.1092024/05/09 10:56:49 [WARN] memberlist: Refuting an alive message for '127.0.0.109' (127.0.0.109:35371) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1112024/05/09 10:56:49 [WARN] memberlist: Refuting an alive message for '127.0.0.111' (127.0.0.111:35371) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1102024/05/09 10:56:49 [WARN] memberlist: Refuting an alive message for '127.0.0.110' (127.0.0.110:35371) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1082024/05/09 10:56:49 [INFO] memberlist: Suspect 127.0.0.112 has failed, no acks received -127.0.0.1082024/05/09 10:56:50 [INFO] memberlist: Marking 127.0.0.112 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.1082024/05/09 10:58:30 [DEBUG] memberlist: Using dynamic bind port 46207 +127.0.0.1082024/05/09 10:58:30 [DEBUG] memberlist: Failed UDP ping: 127.0.0.112 (timeout reached) +127.0.0.1092024/05/09 10:58:30 [WARN] memberlist: Refuting an alive message for '127.0.0.109' (127.0.0.109:46207) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1112024/05/09 10:58:30 [WARN] memberlist: Refuting an alive message for '127.0.0.111' (127.0.0.111:46207) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1102024/05/09 10:58:30 [WARN] memberlist: Refuting an alive message for '127.0.0.110' (127.0.0.110:46207) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1082024/05/09 10:58:31 [INFO] memberlist: Suspect 127.0.0.112 has failed, no acks received +127.0.0.1082024/05/09 10:58:32 [INFO] memberlist: Marking 127.0.0.112 as failed, suspect timeout reached (0 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3 -127.0.0.1132024/05/09 10:56:50 [DEBUG] memberlist: Using dynamic bind port 46871 -127.0.0.1132024/05/09 10:56:50 [DEBUG] memberlist: Failed UDP ping: 127.0.0.118 (timeout reached) -127.0.0.1172024/05/09 10:56:50 [WARN] memberlist: Refuting an alive message for '127.0.0.117' (127.0.0.117:46871) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1152024/05/09 10:56:50 [WARN] memberlist: Refuting an alive message for '127.0.0.115' (127.0.0.115:46871) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1162024/05/09 10:56:50 [WARN] memberlist: Refuting an alive message for '127.0.0.116' (127.0.0.116:46871) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1132024/05/09 10:56:50 [INFO] memberlist: Suspect 127.0.0.118 has failed, no acks received -127.0.0.1132024/05/09 10:56:51 [INFO] memberlist: Marking 127.0.0.118 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.1132024/05/09 10:58:32 [DEBUG] memberlist: Using dynamic bind port 35371 +127.0.0.1132024/05/09 10:58:32 [DEBUG] memberlist: Failed UDP ping: 127.0.0.118 (timeout reached) +127.0.0.1172024/05/09 10:58:32 [WARN] memberlist: Refuting an alive message for '127.0.0.117' (127.0.0.117:35371) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1142024/05/09 10:58:32 [WARN] memberlist: Refuting an alive message for '127.0.0.114' (127.0.0.114:35371) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1152024/05/09 10:58:32 [WARN] memberlist: Refuting an alive message for '127.0.0.115' (127.0.0.115:35371) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1132024/05/09 10:58:32 [INFO] memberlist: Suspect 127.0.0.118 has failed, no acks received +127.0.0.1132024/05/09 10:58:33 [INFO] memberlist: Marking 127.0.0.118 as failed, suspect timeout reached (0 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3_(confirmations_start_to_lower_timeout) -127.0.0.1192024/05/09 10:56:51 [DEBUG] memberlist: Using dynamic bind port 46843 -127.0.0.1192024/05/09 10:56:51 [DEBUG] memberlist: Failed UDP ping: 127.0.0.124 (timeout reached) -127.0.0.1202024/05/09 10:56:51 [WARN] memberlist: Refuting an alive message for '127.0.0.120' (127.0.0.120:46843) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1212024/05/09 10:56:51 [WARN] memberlist: Refuting an alive message for '127.0.0.121' (127.0.0.121:46843) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1232024/05/09 10:56:51 [WARN] memberlist: Refuting an alive message for '127.0.0.123' (127.0.0.123:46843) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1192024/05/09 10:56:51 [INFO] memberlist: Suspect 127.0.0.124 has failed, no acks received -127.0.0.1192024/05/09 10:56:52 [INFO] memberlist: Marking 127.0.0.124 as failed, suspect timeout reached (1 peer confirmations) +127.0.0.1192024/05/09 10:58:33 [DEBUG] memberlist: Using dynamic bind port 44427 +127.0.0.1192024/05/09 10:58:33 [DEBUG] memberlist: Failed UDP ping: 127.0.0.124 (timeout reached) +127.0.0.1212024/05/09 10:58:33 [WARN] memberlist: Refuting an alive message for '127.0.0.121' (127.0.0.121:44427) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1222024/05/09 10:58:33 [WARN] memberlist: Refuting an alive message for '127.0.0.122' (127.0.0.122:44427) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1232024/05/09 10:58:33 [WARN] memberlist: Refuting an alive message for '127.0.0.123' (127.0.0.123:44427) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1192024/05/09 10:58:33 [INFO] memberlist: Suspect 127.0.0.124 has failed, no acks received +127.0.0.1192024/05/09 10:58:34 [INFO] memberlist: Marking 127.0.0.124 as failed, suspect timeout reached (1 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3#01 -127.0.0.1252024/05/09 10:56:52 [DEBUG] memberlist: Using dynamic bind port 35879 -127.0.0.1252024/05/09 10:56:52 [DEBUG] memberlist: Failed UDP ping: 127.0.0.130 (timeout reached) -127.0.0.1282024/05/09 10:56:52 [WARN] memberlist: Refuting an alive message for '127.0.0.128' (127.0.0.128:35879) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1262024/05/09 10:56:52 [WARN] memberlist: Refuting an alive message for '127.0.0.126' (127.0.0.126:35879) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1272024/05/09 10:56:52 [WARN] memberlist: Refuting an alive message for '127.0.0.127' (127.0.0.127:35879) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1252024/05/09 10:56:52 [INFO] memberlist: Suspect 127.0.0.130 has failed, no acks received -127.0.0.1252024/05/09 10:56:53 [INFO] memberlist: Marking 127.0.0.130 as failed, suspect timeout reached (2 peer confirmations) +127.0.0.1252024/05/09 10:58:34 [DEBUG] memberlist: Using dynamic bind port 33783 +127.0.0.1252024/05/09 10:58:34 [DEBUG] memberlist: Failed UDP ping: 127.0.0.130 (timeout reached) +127.0.0.1272024/05/09 10:58:34 [WARN] memberlist: Refuting an alive message for '127.0.0.127' (127.0.0.127:33783) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1262024/05/09 10:58:34 [WARN] memberlist: Refuting an alive message for '127.0.0.126' (127.0.0.126:33783) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1292024/05/09 10:58:34 [WARN] memberlist: Refuting an alive message for '127.0.0.129' (127.0.0.129:33783) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1252024/05/09 10:58:34 [INFO] memberlist: Suspect 127.0.0.130 has failed, no acks received +127.0.0.1252024/05/09 10:58:34 [INFO] memberlist: Marking 127.0.0.130 as failed, suspect timeout reached (2 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3_(timeout_driven_to_nominal_value) -127.0.0.1312024/05/09 10:56:53 [DEBUG] memberlist: Using dynamic bind port 32779 -127.0.0.1312024/05/09 10:56:53 [DEBUG] memberlist: Failed UDP ping: 127.0.0.136 (timeout reached) -127.0.0.1322024/05/09 10:56:53 [WARN] memberlist: Refuting an alive message for '127.0.0.132' (127.0.0.132:32779) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1352024/05/09 10:56:53 [WARN] memberlist: Refuting an alive message for '127.0.0.135' (127.0.0.135:32779) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1332024/05/09 10:56:53 [WARN] memberlist: Refuting an alive message for '127.0.0.133' (127.0.0.133:32779) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1312024/05/09 10:56:53 [INFO] memberlist: Suspect 127.0.0.136 has failed, no acks received -127.0.0.1312024/05/09 10:56:53 [INFO] memberlist: Marking 127.0.0.136 as failed, suspect timeout reached (3 peer confirmations) +127.0.0.1312024/05/09 10:58:34 [DEBUG] memberlist: Using dynamic bind port 34265 +127.0.0.1312024/05/09 10:58:34 [DEBUG] memberlist: Failed UDP ping: 127.0.0.136 (timeout reached) +127.0.0.1332024/05/09 10:58:34 [WARN] memberlist: Refuting an alive message for '127.0.0.133' (127.0.0.133:34265) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1322024/05/09 10:58:34 [WARN] memberlist: Refuting an alive message for '127.0.0.132' (127.0.0.132:34265) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1342024/05/09 10:58:34 [WARN] memberlist: Refuting an alive message for '127.0.0.134' (127.0.0.134:34265) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1312024/05/09 10:58:34 [INFO] memberlist: Suspect 127.0.0.136 has failed, no acks received +127.0.0.1312024/05/09 10:58:35 [INFO] memberlist: Marking 127.0.0.136 as failed, suspect timeout reached (3 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3#02 -127.0.0.1372024/05/09 10:56:53 [DEBUG] memberlist: Using dynamic bind port 34247 -127.0.0.1372024/05/09 10:56:53 [DEBUG] memberlist: Failed UDP ping: 127.0.0.142 (timeout reached) -127.0.0.1412024/05/09 10:56:53 [WARN] memberlist: Refuting an alive message for '127.0.0.141' (127.0.0.141:34247) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1402024/05/09 10:56:53 [WARN] memberlist: Refuting an alive message for '127.0.0.140' (127.0.0.140:34247) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1392024/05/09 10:56:53 [WARN] memberlist: Refuting an alive message for '127.0.0.139' (127.0.0.139:34247) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1372024/05/09 10:56:53 [INFO] memberlist: Suspect 127.0.0.142 has failed, no acks received -127.0.0.1372024/05/09 10:56:54 [INFO] memberlist: Marking 127.0.0.142 as failed, suspect timeout reached (3 peer confirmations) ---- PASS: TestMemberList_ProbeNode_Suspect_Dogpile (7.05s) +127.0.0.1372024/05/09 10:58:35 [DEBUG] memberlist: Using dynamic bind port 40143 +127.0.0.1372024/05/09 10:58:35 [DEBUG] memberlist: Failed UDP ping: 127.0.0.142 (timeout reached) +127.0.0.1392024/05/09 10:58:35 [WARN] memberlist: Refuting an alive message for '127.0.0.139' (127.0.0.139:40143) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1382024/05/09 10:58:35 [WARN] memberlist: Refuting an alive message for '127.0.0.138' (127.0.0.138:40143) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1402024/05/09 10:58:35 [WARN] memberlist: Refuting an alive message for '127.0.0.140' (127.0.0.140:40143) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1372024/05/09 10:58:35 [INFO] memberlist: Suspect 127.0.0.142 has failed, no acks received +127.0.0.1372024/05/09 10:58:36 [INFO] memberlist: Marking 127.0.0.142 as failed, suspect timeout reached (3 peer confirmations) +--- PASS: TestMemberList_ProbeNode_Suspect_Dogpile (7.01s) --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=2,_k=3_(max_timeout_disabled) (0.63s) --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=3,_k=3 (0.63s) --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=4,_k=3 (0.63s) --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=5,_k=3_(max_timeout_starts_to_take_effect) (1.13s) --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3 (1.13s) - --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3_(confirmations_start_to_lower_timeout) (0.89s) - --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3#01 (0.74s) + --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3_(confirmations_start_to_lower_timeout) (0.88s) + --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3#01 (0.73s) --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3_(timeout_driven_to_nominal_value) (0.63s) --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3#02 (0.63s) === RUN TestMemberList_ProbeNode_Awareness_Degraded -127.0.0.1432024/05/09 10:56:54 [DEBUG] memberlist: Using dynamic bind port 41493 -127.0.0.1432024/05/09 10:56:54 [DEBUG] memberlist: Failed UDP ping: 127.0.0.146 (timeout reached) -127.0.0.1442024/05/09 10:56:54 [WARN] memberlist: Refuting an alive message for '127.0.0.144' (127.0.0.144:41493) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1452024/05/09 10:56:54 [WARN] memberlist: Refuting an alive message for '127.0.0.145' (127.0.0.145:41493) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1432024/05/09 10:56:54 [INFO] memberlist: Suspect 127.0.0.146 has failed, no acks received +127.0.0.1432024/05/09 10:58:36 [DEBUG] memberlist: Using dynamic bind port 43597 +127.0.0.1432024/05/09 10:58:36 [DEBUG] memberlist: Failed UDP ping: 127.0.0.146 (timeout reached) +127.0.0.1442024/05/09 10:58:36 [WARN] memberlist: Refuting an alive message for '127.0.0.144' (127.0.0.144:43597) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1452024/05/09 10:58:36 [WARN] memberlist: Refuting an alive message for '127.0.0.145' (127.0.0.145:43597) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1432024/05/09 10:58:36 [INFO] memberlist: Suspect 127.0.0.146 has failed, no acks received --- PASS: TestMemberList_ProbeNode_Awareness_Degraded (0.40s) === RUN TestMemberList_ProbeNode_Wrong_VSN -127.0.0.1472024/05/09 10:56:54 [DEBUG] memberlist: Using dynamic bind port 45155 -127.0.0.1472024/05/09 10:56:54 [WARN] memberlist: Ignoring an alive message for '127.0.0.150' (127.0.0.150:45155) because protocol version(s) are wrong: 0 <= 0 <= 0 should be >0 +127.0.0.1472024/05/09 10:58:36 [DEBUG] memberlist: Using dynamic bind port 41799 +127.0.0.1472024/05/09 10:58:36 [WARN] memberlist: Ignoring an alive message for '127.0.0.150' (127.0.0.150:41799) because protocol version(s) are wrong: 0 <= 0 <= 0 should be >0 --- PASS: TestMemberList_ProbeNode_Wrong_VSN (0.00s) === RUN TestMemberList_ProbeNode_Awareness_Improved -127.0.0.1512024/05/09 10:56:54 [DEBUG] memberlist: Using dynamic bind port 36533 -127.0.0.1522024/05/09 10:56:54 [WARN] memberlist: Refuting an alive message for '127.0.0.152' (127.0.0.152:36533) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1512024/05/09 10:58:36 [DEBUG] memberlist: Using dynamic bind port 46093 +127.0.0.1522024/05/09 10:58:36 [WARN] memberlist: Refuting an alive message for '127.0.0.152' (127.0.0.152:46093) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) --- PASS: TestMemberList_ProbeNode_Awareness_Improved (0.00s) === RUN TestMemberList_ProbeNode_Awareness_MissedNack -127.0.0.1532024/05/09 10:56:54 [DEBUG] memberlist: Using dynamic bind port 46733 -127.0.0.1532024/05/09 10:56:54 [DEBUG] memberlist: Failed UDP ping: 127.0.0.156 (timeout reached) -127.0.0.1542024/05/09 10:56:54 [WARN] memberlist: Refuting an alive message for '127.0.0.154' (127.0.0.154:46733) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) -127.0.0.1532024/05/09 10:56:54 [INFO] memberlist: Suspect 127.0.0.156 has failed, no acks received ---- PASS: TestMemberList_ProbeNode_Awareness_MissedNack (0.21s) +127.0.0.1532024/05/09 10:58:36 [DEBUG] memberlist: Using dynamic bind port 39013 +127.0.0.1532024/05/09 10:58:36 [DEBUG] memberlist: Failed UDP ping: 127.0.0.156 (timeout reached) +127.0.0.1542024/05/09 10:58:36 [WARN] memberlist: Refuting an alive message for '127.0.0.154' (127.0.0.154:39013) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1532024/05/09 10:58:36 [INFO] memberlist: Suspect 127.0.0.156 has failed, no acks received +--- PASS: TestMemberList_ProbeNode_Awareness_MissedNack (0.20s) === RUN TestMemberList_ProbeNode_Awareness_OldProtocol -127.0.0.1572024/05/09 10:56:54 [DEBUG] memberlist: Using dynamic bind port 38749 -127.0.0.1572024/05/09 10:56:55 [DEBUG] memberlist: Failed UDP ping: 127.0.0.160 (timeout reached) -127.0.0.1592024/05/09 10:56:55 [WARN] memberlist: Refuting an alive message for '127.0.0.159' (127.0.0.159:38749) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) -127.0.0.1582024/05/09 10:56:55 [WARN] memberlist: Refuting an alive message for '127.0.0.158' (127.0.0.158:38749) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) -127.0.0.1572024/05/09 10:56:55 [INFO] memberlist: Suspect 127.0.0.160 has failed, no acks received +127.0.0.1572024/05/09 10:58:36 [DEBUG] memberlist: Using dynamic bind port 42733 +127.0.0.1572024/05/09 10:58:36 [DEBUG] memberlist: Failed UDP ping: 127.0.0.160 (timeout reached) +127.0.0.1592024/05/09 10:58:36 [WARN] memberlist: Refuting an alive message for '127.0.0.159' (127.0.0.159:42733) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) +127.0.0.1582024/05/09 10:58:36 [WARN] memberlist: Refuting an alive message for '127.0.0.158' (127.0.0.158:42733) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) +127.0.0.1572024/05/09 10:58:36 [INFO] memberlist: Suspect 127.0.0.160 has failed, no acks received --- PASS: TestMemberList_ProbeNode_Awareness_OldProtocol (0.42s) === RUN TestMemberList_ProbeNode_Buddy -127.0.0.1612024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 39627 -127.0.0.1622024/05/09 10:56:55 [WARN] memberlist: Refuting a suspect message (from: 127.0.0.161) +127.0.0.1612024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 45793 +127.0.0.1622024/05/09 10:58:37 [WARN] memberlist: Refuting a suspect message (from: 127.0.0.161) --- PASS: TestMemberList_ProbeNode_Buddy (0.00s) === RUN TestMemberList_ProbeNode -127.0.0.1632024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 34323 -127.0.0.1642024/05/09 10:56:55 [WARN] memberlist: Refuting an alive message for '127.0.0.164' (127.0.0.164:34323) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) +127.0.0.1632024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 35407 +127.0.0.1642024/05/09 10:58:37 [WARN] memberlist: Refuting an alive message for '127.0.0.164' (127.0.0.164:35407) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) --- PASS: TestMemberList_ProbeNode (0.00s) === RUN TestMemberList_Ping state_test.go:978: Skip randomly failing test --- SKIP: TestMemberList_Ping (0.00s) === RUN TestMemberList_ResetNodes -127.0.0.1652024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 45507 +127.0.0.1652024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 37969 --- PASS: TestMemberList_ResetNodes (0.20s) === RUN TestMemberList_NextSeq --- PASS: TestMemberList_NextSeq (0.00s) @@ -1538,78 +1586,78 @@ === RUN TestMemberList_invokeAckHandler_Channel_Nack --- PASS: TestMemberList_invokeAckHandler_Channel_Nack (0.00s) === RUN TestMemberList_AliveNode_NewNode -127.0.0.1662024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 42617 +127.0.0.1662024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 41255 --- PASS: TestMemberList_AliveNode_NewNode (0.00s) === RUN TestMemberList_AliveNode_SuspectNode -127.0.0.1672024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 40529 +127.0.0.1672024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 45767 --- PASS: TestMemberList_AliveNode_SuspectNode (0.00s) === RUN TestMemberList_AliveNode_Idempotent -127.0.0.1682024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 34039 +127.0.0.1682024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 45289 --- PASS: TestMemberList_AliveNode_Idempotent (0.00s) === RUN TestMemberList_AliveNode_ChangeMeta -127.0.0.1692024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 37551 +127.0.0.1692024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 34379 --- PASS: TestMemberList_AliveNode_ChangeMeta (0.00s) === RUN TestMemberList_AliveNode_Refute -127.0.0.1702024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 34467 -127.0.0.1702024/05/09 10:56:55 [WARN] memberlist: Refuting an alive message for '127.0.0.170' (127.0.0.1:0) meta:([102 111 111] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1702024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 32789 +127.0.0.1702024/05/09 10:58:37 [WARN] memberlist: Refuting an alive message for '127.0.0.170' (127.0.0.1:0) meta:([102 111 111] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) --- PASS: TestMemberList_AliveNode_Refute (0.00s) === RUN TestMemberList_AliveNode_Conflict -127.0.0.1712024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 41769 -127.0.0.1712024/05/09 10:56:55 [ERR] memberlist: Conflicting address for test. Mine: 127.0.0.1:8000 Theirs: 127.0.0.2:9000 Old state: 0 -127.0.0.1712024/05/09 10:56:55 [INFO] memberlist: Updating address for left or failed node test from 127.0.0.1:8000 to 127.0.0.2:9000 +127.0.0.1712024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 46053 +127.0.0.1712024/05/09 10:58:37 [ERR] memberlist: Conflicting address for test. Mine: 127.0.0.1:8000 Theirs: 127.0.0.2:9000 Old state: 0 +127.0.0.1712024/05/09 10:58:37 [INFO] memberlist: Updating address for left or failed node test from 127.0.0.1:8000 to 127.0.0.2:9000 --- PASS: TestMemberList_AliveNode_Conflict (0.01s) === RUN TestMemberList_SuspectNode_NoNode -127.0.0.1722024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 36227 +127.0.0.1722024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 42565 --- PASS: TestMemberList_SuspectNode_NoNode (0.00s) === RUN TestMemberList_SuspectNode -127.0.0.1732024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 42895 -127.0.0.1732024/05/09 10:56:55 [INFO] memberlist: Marking test as failed, suspect timeout reached (0 peer confirmations) +127.0.0.1732024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 46735 +127.0.0.1732024/05/09 10:58:37 [INFO] memberlist: Marking test as failed, suspect timeout reached (0 peer confirmations) --- PASS: TestMemberList_SuspectNode (0.01s) === RUN TestMemberList_SuspectNode_DoubleSuspect -127.0.0.1742024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 39987 +127.0.0.1742024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 45083 --- PASS: TestMemberList_SuspectNode_DoubleSuspect (0.00s) === RUN TestMemberList_SuspectNode_OldSuspect -127.0.0.1752024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 37293 +127.0.0.1752024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 40631 --- PASS: TestMemberList_SuspectNode_OldSuspect (0.00s) === RUN TestMemberList_SuspectNode_Refute -127.0.0.1762024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 39469 -127.0.0.1762024/05/09 10:56:55 [WARN] memberlist: Refuting a suspect message (from: ) +127.0.0.1762024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 46433 +127.0.0.1762024/05/09 10:58:37 [WARN] memberlist: Refuting a suspect message (from: ) --- PASS: TestMemberList_SuspectNode_Refute (0.00s) === RUN TestMemberList_DeadNode_NoNode -127.0.0.1772024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 39581 +127.0.0.1772024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 34973 --- PASS: TestMemberList_DeadNode_NoNode (0.00s) === RUN TestMemberList_DeadNodeLeft -127.0.0.1782024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 38549 -127.0.0.1782024/05/09 10:56:55 [INFO] memberlist: Updating address for left or failed node node1 from 127.0.0.1:8000 to 127.0.0.2:9000 +127.0.0.1782024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 32787 +127.0.0.1782024/05/09 10:58:37 [INFO] memberlist: Updating address for left or failed node node1 from 127.0.0.1:8000 to 127.0.0.2:9000 --- PASS: TestMemberList_DeadNodeLeft (0.00s) === RUN TestMemberList_DeadNode -127.0.0.1792024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 36451 +127.0.0.1792024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 43257 --- PASS: TestMemberList_DeadNode (0.00s) === RUN TestMemberList_DeadNode_Double -127.0.0.1802024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 46209 +127.0.0.1802024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 33129 --- PASS: TestMemberList_DeadNode_Double (0.00s) === RUN TestMemberList_DeadNode_OldDead -127.0.0.1812024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 36699 +127.0.0.1812024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 36673 --- PASS: TestMemberList_DeadNode_OldDead (0.00s) === RUN TestMemberList_DeadNode_AliveReplay -127.0.0.1822024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 35279 +127.0.0.1822024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 39459 --- PASS: TestMemberList_DeadNode_AliveReplay (0.00s) === RUN TestMemberList_DeadNode_Refute -127.0.0.1832024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 40633 -127.0.0.1832024/05/09 10:56:55 [WARN] memberlist: Refuting a dead message (from: ) +127.0.0.1832024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 37341 +127.0.0.1832024/05/09 10:58:37 [WARN] memberlist: Refuting a dead message (from: ) --- PASS: TestMemberList_DeadNode_Refute (0.00s) === RUN TestMemberList_MergeState -127.0.0.1842024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 39283 +127.0.0.1842024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 40261 --- PASS: TestMemberList_MergeState (0.00s) === RUN TestMemberlist_Gossip -127.0.0.1852024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 41449 -127.0.0.1862024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 46025 -127.0.0.1862024/05/09 10:56:55 [WARN] memberlist: Refuting an alive message for '127.0.0.186' (127.0.0.186:41449) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) ---- PASS: TestMemberlist_Gossip (0.01s) +127.0.0.1852024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 36935 +127.0.0.1862024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 33439 +127.0.0.1862024/05/09 10:58:37 [WARN] memberlist: Refuting an alive message for '127.0.0.186' (127.0.0.186:36935) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +--- PASS: TestMemberlist_Gossip (0.00s) === RUN TestMemberlist_GossipToDead -127.0.0.1882024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 46167 -127.0.0.1892024/05/09 10:56:55 [WARN] memberlist: Refuting an alive message for '127.0.0.189' (127.0.0.189:46167) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) ---- PASS: TestMemberlist_GossipToDead (0.06s) +127.0.0.1882024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 34811 +127.0.0.1892024/05/09 10:58:37 [WARN] memberlist: Refuting an alive message for '127.0.0.189' (127.0.0.189:34811) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +--- PASS: TestMemberlist_GossipToDead (0.05s) === RUN TestMemberlist_FailedRemote === RUN TestMemberlist_FailedRemote/nil_error === RUN TestMemberlist_FailedRemote/normal_error @@ -1641,81 +1689,81 @@ --- PASS: TestMemberlist_FailedRemote/net.OpError_for_udp_with_write (0.00s) --- PASS: TestMemberlist_FailedRemote/net.OpError_for_udp_with_read (0.00s) === RUN TestMemberlist_PushPull -127.0.0.1902024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 36251 -127.0.0.1912024/05/09 10:56:55 [DEBUG] memberlist: Stream connection from=127.0.0.1:54196 -127.0.0.1902024/05/09 10:56:55 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.191 127.0.0.191:36251 -127.0.0.1912024/05/09 10:56:55 [WARN] memberlist: Refuting an alive message for '127.0.0.191' (127.0.0.191:36251) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +127.0.0.1902024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 43333 +127.0.0.1902024/05/09 10:58:37 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.191 127.0.0.191:43333 +127.0.0.1912024/05/09 10:58:37 [DEBUG] memberlist: Stream connection from=127.0.0.1:36178 +127.0.0.1912024/05/09 10:58:37 [WARN] memberlist: Refuting an alive message for '127.0.0.191' (127.0.0.191:43333) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) --- PASS: TestMemberlist_PushPull (0.01s) === RUN TestVerifyProtocol -127.0.0.1922024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 44633 -127.0.0.1932024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 41317 -127.0.0.1942024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 46847 -127.0.0.1952024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 36111 -127.0.0.1962024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 38035 -127.0.0.1972024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 38035 -127.0.0.1982024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 44701 -127.0.0.1992024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 35987 -127.0.0.2002024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 46873 -127.0.0.2012024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 38637 -127.0.0.2022024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 35453 -127.0.0.2032024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 33359 -127.0.0.2042024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 46851 -127.0.0.2052024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 43595 -127.0.0.2062024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 41641 -127.0.0.2072024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 46307 -127.0.0.2082024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 45929 -127.0.0.2092024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 43639 -127.0.0.2102024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 43785 -127.0.0.2112024/05/09 10:56:55 [DEBUG] memberlist: Using dynamic bind port 40371 ---- PASS: TestVerifyProtocol (0.01s) +127.0.0.1922024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 35013 +127.0.0.1932024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 38567 +127.0.0.1942024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 39891 +127.0.0.1952024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 43507 +127.0.0.1962024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 41705 +127.0.0.1972024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 34413 +127.0.0.1982024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 45621 +127.0.0.1992024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 39137 +127.0.0.2002024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 37807 +127.0.0.2012024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 43807 +127.0.0.2022024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 40165 +127.0.0.2032024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 45901 +127.0.0.2042024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 39151 +127.0.0.2052024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 39863 +127.0.0.2062024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 38477 +127.0.0.2072024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 43353 +127.0.0.2082024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 40455 +127.0.0.2092024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 39389 +127.0.0.2102024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 43977 +127.0.0.2112024/05/09 10:58:37 [DEBUG] memberlist: Using dynamic bind port 44657 +--- PASS: TestVerifyProtocol (0.00s) === RUN TestSuspicion_remainingSuspicionTime --- PASS: TestSuspicion_remainingSuspicionTime (0.00s) === RUN TestSuspicion_Timer -127.0.0.1432024/05/09 10:56:59 [INFO] memberlist: Marking 127.0.0.146 as failed, suspect timeout reached (0 peer confirmations) -127.0.0.1842024/05/09 10:56:59 [INFO] memberlist: Marking test3 as failed, suspect timeout reached (0 peer confirmations) -127.0.0.1742024/05/09 10:56:59 [INFO] memberlist: Marking test as failed, suspect timeout reached (0 peer confirmations) -127.0.0.1842024/05/09 10:56:59 [INFO] memberlist: Marking test2 as failed, suspect timeout reached (0 peer confirmations) -127.0.0.1532024/05/09 10:56:59 [INFO] memberlist: Marking 127.0.0.156 as failed, suspect timeout reached (0 peer confirmations) -127.0.0.1572024/05/09 10:57:00 [INFO] memberlist: Marking 127.0.0.160 as failed, suspect timeout reached (0 peer confirmations) ---- PASS: TestSuspicion_Timer (13.16s) +127.0.0.1432024/05/09 10:58:41 [INFO] memberlist: Marking 127.0.0.146 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.1742024/05/09 10:58:41 [INFO] memberlist: Marking test as failed, suspect timeout reached (0 peer confirmations) +127.0.0.1842024/05/09 10:58:41 [INFO] memberlist: Marking test3 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.1842024/05/09 10:58:41 [INFO] memberlist: Marking test2 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.1532024/05/09 10:58:41 [INFO] memberlist: Marking 127.0.0.156 as failed, suspect timeout reached (0 peer confirmations) +127.0.0.1572024/05/09 10:58:41 [INFO] memberlist: Marking 127.0.0.160 as failed, suspect timeout reached (0 peer confirmations) +--- PASS: TestSuspicion_Timer (13.09s) === RUN TestSuspicion_Timer_ZeroK --- PASS: TestSuspicion_Timer_ZeroK (0.03s) === RUN TestSuspicion_Timer_Immediate --- PASS: TestSuspicion_Timer_Immediate (0.20s) === RUN TestTransport_Join -2024/05/09 10:57:09 [DEBUG] memberlist: Stream connection from=pipe -2024/05/09 10:57:09 [DEBUG] memberlist: Initiating push/pull sync with: node1 pipe +2024/05/09 10:58:50 [DEBUG] memberlist: Initiating push/pull sync with: node1 pipe +2024/05/09 10:58:50 [DEBUG] memberlist: Stream connection from=pipe --- PASS: TestTransport_Join (0.00s) === RUN TestTransport_Send -2024/05/09 10:57:09 [DEBUG] memberlist: Stream connection from=pipe -2024/05/09 10:57:09 [DEBUG] memberlist: Initiating push/pull sync with: node1 pipe -2024/05/09 10:57:09 [DEBUG] memberlist: Stream connection from=pipe -2024/05/09 10:57:09 [DEBUG] memberlist: Stream connection from=pipe +2024/05/09 10:58:50 [DEBUG] memberlist: Initiating push/pull sync with: node1 pipe +2024/05/09 10:58:50 [DEBUG] memberlist: Stream connection from=pipe +2024/05/09 10:58:50 [DEBUG] memberlist: Stream connection from=pipe +2024/05/09 10:58:50 [DEBUG] memberlist: Stream connection from=pipe --- PASS: TestTransport_Send (0.10s) === RUN TestTransport_TcpListenBackoff - transport_test.go:147: countingWriter: test2024/05/09 10:57:09 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:50 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection - transport_test.go:147: countingWriter: test2024/05/09 10:57:09 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:50 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection - transport_test.go:147: countingWriter: test2024/05/09 10:57:09 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:50 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection - transport_test.go:147: countingWriter: test2024/05/09 10:57:09 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:50 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection - transport_test.go:147: countingWriter: test2024/05/09 10:57:09 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:50 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection - transport_test.go:147: countingWriter: test2024/05/09 10:57:09 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:51 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection - transport_test.go:147: countingWriter: test2024/05/09 10:57:09 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:51 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection - transport_test.go:147: countingWriter: test2024/05/09 10:57:09 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:51 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection - transport_test.go:147: countingWriter: test2024/05/09 10:57:10 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:52 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection - transport_test.go:147: countingWriter: test2024/05/09 10:57:11 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:53 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection - transport_test.go:147: countingWriter: test2024/05/09 10:57:12 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35965: use of closed network connection + transport_test.go:147: countingWriter: test2024/05/09 10:58:54 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:35319: use of closed network connection ---- PASS: TestTransport_TcpListenBackoff (4.31s) +--- PASS: TestTransport_TcpListenBackoff (4.28s) === RUN TestUtil_PortFunctions === RUN TestUtil_PortFunctions/1.2.3.4 === RUN TestUtil_PortFunctions/1.2.3.4:1234 @@ -1776,15 +1824,15 @@ --- PASS: Test_IsValidAddressOverride/Only_IPv6 (0.00s) --- PASS: Test_IsValidAddressOverride/Only_127.0.0.0/8_and_::1 (0.00s) PASS -ok github.com/hashicorp/memberlist 38.318s +ok github.com/hashicorp/memberlist 38.274s === RUN TestRetryer === RUN TestRetryer/counter === RUN TestRetryer/timer ---- PASS: TestRetryer (0.41s) +--- PASS: TestRetryer (0.40s) --- PASS: TestRetryer/counter (0.20s) --- PASS: TestRetryer/timer (0.20s) PASS -ok github.com/hashicorp/memberlist/internal/retry 0.444s +ok github.com/hashicorp/memberlist/internal/retry 0.408s create-stamp debian/debhelper-build-stamp dh_testroot -O--buildsystem=golang dh_prep -O--buildsystem=golang @@ -1811,12 +1859,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: including full source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/2739152/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/2739152/tmp/hooks/B01_cleanup finished I: unmounting dev/ptmx filesystem I: unmounting dev/pts filesystem I: unmounting dev/shm filesystem I: unmounting proc filesystem I: unmounting sys filesystem I: cleaning the build env -I: removing directory /srv/workspace/pbuilder/3595460 and its subdirectories -I: Current time: Wed May 8 22:57:20 -12 2024 -I: pbuilder-time-stamp: 1715252240 +I: removing directory /srv/workspace/pbuilder/2739152 and its subdirectories +I: Current time: Fri May 10 00:59:00 +14 2024 +I: pbuilder-time-stamp: 1715252340