Diff of the two buildlogs: -- --- b1/build.log 2024-01-08 13:29:49.609317328 +0000 +++ b2/build.log 2024-01-08 13:33:29.699676894 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Sun Feb 9 07:51:28 -12 2025 -I: pbuilder-time-stamp: 1739130688 +I: Current time: Tue Jan 9 03:29:52 +14 2024 +I: pbuilder-time-stamp: 1704720592 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/bookworm-reproducible-base.tgz] I: copying local configuration @@ -31,49 +31,81 @@ dpkg-source: info: applying test--skip_TestMemberList_Ping.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/3138085/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/924152/tmp/hooks/D01_modify_environment starting +debug: Running on ionos11-amd64. +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 Jan 8 13:30 /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/924152/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/924152/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='amd64' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=16 ' - DISTRIBUTION='bookworm' - HOME='/root' - HOST_ARCH='amd64' + 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]="15" [3]="1" [4]="release" [5]="x86_64-pc-linux-gnu") + BASH_VERSION='5.2.15(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=amd64 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=15 ' + DIRSTACK=() + DISTRIBUTION=bookworm + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=x86_64 + HOST_ARCH=amd64 IFS=' ' - INVOCATION_ID='604bd3f3503640bb851bbe12ec2e1d5b' - 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='3138085' - PS1='# ' - PS2='> ' + INVOCATION_ID=ed012e73d5564c138c514cd6b68d2fa4 + LANG=C + LANGUAGE=et_EE:et + LC_ALL=C + MACHTYPE=x86_64-pc-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=924152 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.soDjSPGT/pbuilderrc_sjtW --distribution bookworm --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.soDjSPGT/b1 --logfile b1/build.log golang-github-hashicorp-memberlist_0.2.2-2.dsc' - SUDO_GID='110' - SUDO_UID='105' - SUDO_USER='jenkins' - TERM='unknown' - TZ='/usr/share/zoneinfo/Etc/GMT+12' - USER='root' - _='/usr/bin/systemd-run' - http_proxy='http://85.184.249.68: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.soDjSPGT/pbuilderrc_CFD3 --distribution bookworm --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/bookworm-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.soDjSPGT/b2 --logfile b2/build.log golang-github-hashicorp-memberlist_0.2.2-2.dsc' + SUDO_GID=111 + SUDO_UID=106 + SUDO_USER=jenkins + TERM=unknown + TZ=/usr/share/zoneinfo/Etc/GMT-14 + UID=0 + USER=root + _='I: set' + http_proxy=http://78.137.99.97:3128 I: uname -a - Linux ionos5-amd64 6.5.0-0.deb12.4-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.5.10-1~bpo12+1 (2023-11-23) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-17-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.69-1 (2023-12-30) x86_64 GNU/Linux I: ls -l /bin total 5632 -rwxr-xr-x 1 root root 1265648 Apr 23 2023 bash @@ -131,15 +163,15 @@ -rwxr-xr-x 1 root root 52112 Sep 20 2022 readlink -rwxr-xr-x 1 root root 72752 Sep 20 2022 rm -rwxr-xr-x 1 root root 56240 Sep 20 2022 rmdir - -rwxr-xr-x 1 root root 27560 Jul 28 2023 run-parts + -rwxr-xr-x 1 root root 27560 Jul 28 23:46 run-parts -rwxr-xr-x 1 root root 126424 Jan 5 2023 sed - lrwxrwxrwx 1 root root 4 Jan 5 2023 sh -> dash + lrwxrwxrwx 1 root root 9 Jan 8 13:30 sh -> /bin/bash -rwxr-xr-x 1 root root 43888 Sep 20 2022 sleep -rwxr-xr-x 1 root root 85008 Sep 20 2022 stty -rwsr-xr-x 1 root root 72000 Mar 23 2023 su -rwxr-xr-x 1 root root 39824 Sep 20 2022 sync -rwxr-xr-x 1 root root 531984 Apr 6 2023 tar - -rwxr-xr-x 1 root root 14520 Jul 28 2023 tempfile + -rwxr-xr-x 1 root root 14520 Jul 28 23:46 tempfile -rwxr-xr-x 1 root root 109616 Sep 20 2022 touch -rwxr-xr-x 1 root root 35664 Sep 20 2022 true -rwxr-xr-x 1 root root 14568 Mar 23 2023 ulockmgr_server @@ -159,7 +191,7 @@ -rwxr-xr-x 1 root root 2206 Apr 10 2022 zless -rwxr-xr-x 1 root root 1842 Apr 10 2022 zmore -rwxr-xr-x 1 root root 4577 Apr 10 2022 znew -I: user script /srv/workspace/pbuilder/3138085/tmp/hooks/D02_print_environment finished +I: user script /srv/workspace/pbuilder/924152/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -316,7 +348,7 @@ Get: 101 http://deb.debian.org/debian bookworm/main amd64 golang-github-hashicorp-go-multierror-dev all 1.1.1-2 [7804 B] Get: 102 http://deb.debian.org/debian bookworm/main amd64 golang-github-hashicorp-go-sockaddr-dev all 1.0.2-2 [54.4 kB] Get: 103 http://deb.debian.org/debian bookworm/main amd64 golang-github-miekg-dns-dev all 1.1.50-2 [171 kB] -Fetched 119 MB in 1s (93.3 MB/s) +Fetched 119 MB in 12s (9685 kB/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 ... 18148 files and directories currently installed.) @@ -743,7 +775,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/golang-github-hashicorp-memberlist-0.2.2/ && 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.2.2-2_source.changes +I: user script /srv/workspace/pbuilder/924152/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for bookworm +I: user script /srv/workspace/pbuilder/924152/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/golang-github-hashicorp-memberlist-0.2.2/ && 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.2.2-2_source.changes dpkg-buildpackage: info: source package golang-github-hashicorp-memberlist dpkg-buildpackage: info: source version 0.2.2-2 dpkg-buildpackage: info: source distribution unstable @@ -761,31 +797,31 @@ dh_autoreconf -O--buildsystem=golang dh_auto_configure -O--buildsystem=golang dh_auto_build -O--buildsystem=golang - cd obj-x86_64-linux-gnu && go install -trimpath -v -p 16 github.com/hashicorp/memberlist github.com/hashicorp/memberlist/internal/retry + cd obj-x86_64-linux-gnu && go install -trimpath -v -p 15 github.com/hashicorp/memberlist github.com/hashicorp/memberlist/internal/retry internal/goarch internal/unsafeheader -math/bits +crypto/internal/subtle internal/goexperiment -internal/itoa -internal/race internal/goos -crypto/internal/subtle -crypto/subtle +internal/race +unicode +unicode/utf8 +internal/itoa container/list +math/bits +crypto/subtle internal/cpu -unicode/utf8 -sync/atomic +internal/abi +runtime/internal/atomic runtime/internal/syscall +runtime/internal/math crypto/internal/boring/sig -unicode -runtime/internal/atomic +sync/atomic +runtime/internal/sys internal/nettrace -internal/abi -runtime/internal/math unicode/utf16 -runtime/internal/sys -vendor/golang.org/x/crypto/internal/subtle vendor/golang.org/x/crypto/cryptobyte/asn1 +vendor/golang.org/x/crypto/internal/subtle encoding golang.org/x/net/internal/iana internal/bytealg @@ -799,111 +835,111 @@ runtime/cgo errors sort -internal/oserror io -path +strconv +internal/oserror internal/safefilepath +path github.com/hashicorp/golang-lru/simplelru -strconv vendor/golang.org/x/net/dns/dnsmessage crypto/internal/nistec/fiat syscall bytes -strings hash +strings crypto/internal/randutil hash/crc32 -crypto +vendor/golang.org/x/text/transform reflect crypto/rc4 +crypto encoding/base32 -vendor/golang.org/x/text/transform bufio -github.com/hashicorp/go-immutable-radix net/http/internal/ascii html regexp/syntax -internal/syscall/execenv +github.com/hashicorp/go-immutable-radix internal/syscall/unix time +internal/syscall/execenv regexp -io/fs internal/poll +io/fs context embed -os internal/fmtsort -github.com/hashicorp/errwrap encoding/binary -encoding/base64 +github.com/hashicorp/errwrap +os +crypto/cipher +crypto/internal/nistec crypto/internal/edwards25519/field crypto/md5 -crypto/cipher -vendor/golang.org/x/crypto/curve25519/internal/field +encoding/base64 vendor/golang.org/x/crypto/internal/poly1305 -crypto/internal/nistec +vendor/golang.org/x/crypto/curve25519/internal/field golang.org/x/sys/unix +encoding/pem +crypto/des +vendor/golang.org/x/crypto/chacha20 +crypto/internal/boring +crypto/internal/edwards25519 fmt internal/godebug path/filepath io/ioutil os/signal -encoding/pem -internal/intern -crypto/internal/edwards25519 -vendor/golang.org/x/sys/cpu -crypto/internal/boring -crypto/des -vendor/golang.org/x/crypto/chacha20 -net/netip -crypto/aes crypto/hmac crypto/sha512 +crypto/aes crypto/sha1 crypto/sha256 +internal/intern +vendor/golang.org/x/sys/cpu +vendor/golang.org/x/crypto/hkdf +net/netip os/exec vendor/golang.org/x/crypto/chacha20poly1305 -vendor/golang.org/x/crypto/hkdf net compress/lzw -log -compress/flate encoding/hex +net/url +log +vendor/golang.org/x/crypto/curve25519 vendor/golang.org/x/net/http2/hpack mime +compress/flate math/big -net/url +vendor/golang.org/x/text/unicode/norm net/http/internal mime/quotedprintable -vendor/golang.org/x/crypto/curve25519 github.com/google/btree encoding/gob -vendor/golang.org/x/text/unicode/norm go/token -text/template/parse encoding/json +text/template/parse github.com/hashicorp/go-multierror -golang.org/x/net/bpf vendor/golang.org/x/text/unicode/bidi +golang.org/x/net/bpf github.com/hashicorp/memberlist/internal/retry compress/gzip vendor/golang.org/x/text/secure/bidirule text/template vendor/golang.org/x/net/idna crypto/rand -crypto/elliptic crypto/internal/boring/bbig -encoding/asn1 +crypto/elliptic crypto/dsa +encoding/asn1 crypto/ed25519 -github.com/hashicorp/memberlist/vendor/github.com/sean-/seed crypto/rsa -crypto/x509/pkix +github.com/hashicorp/memberlist/vendor/github.com/sean-/seed vendor/golang.org/x/crypto/cryptobyte +crypto/x509/pkix html/template crypto/ecdsa -vendor/golang.org/x/net/http/httpproxy net/textproto +vendor/golang.org/x/net/http/httpproxy crypto/x509 golang.org/x/net/internal/socket github.com/hashicorp/go-sockaddr @@ -920,7 +956,7 @@ github.com/hashicorp/go-msgpack/codec github.com/hashicorp/memberlist dh_auto_test -O--buildsystem=golang - cd obj-x86_64-linux-gnu && go test -vet=off -v -p 16 github.com/hashicorp/memberlist github.com/hashicorp/memberlist/internal/retry + cd obj-x86_64-linux-gnu && go test -vet=off -v -p 15 github.com/hashicorp/memberlist github.com/hashicorp/memberlist/internal/retry === RUN TestAwareness --- PASS: TestAwareness (0.00s) === RUN TestMemberlistBroadcast_Invalidates @@ -978,13 +1014,13 @@ === RUN TestCreate_invalidLoggerSettings --- PASS: TestCreate_invalidLoggerSettings (0.00s) === RUN TestCreate - log.go:198: test[127.0.0.22]: 2025/02/09 19:52:17 [DEBUG] memberlist: Using dynamic bind port 44553 + log.go:198: test[127.0.0.22]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 46337 --- PASS: TestCreate (0.25s) === RUN TestMemberList_CreateShutdown - log.go:198: test[127.0.0.23]: 2025/02/09 19:52:17 [DEBUG] memberlist: Using dynamic bind port 36641 + log.go:198: test[127.0.0.23]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 38451 --- PASS: TestMemberList_CreateShutdown (0.00s) === RUN TestMemberList_ResolveAddr - log.go:198: test[127.0.0.24]: 2025/02/09 19:52:17 [DEBUG] memberlist: Using dynamic bind port 46755 + log.go:198: test[127.0.0.24]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 46667 === RUN TestMemberList_ResolveAddr/localhost === PAUSE TestMemberList_ResolveAddr/localhost === RUN TestMemberList_ResolveAddr/localhost_(tagged) @@ -1028,293 +1064,283 @@ === RUN TestMemberList_ResolveAddr/ipv6_only_(tagged) === PAUSE TestMemberList_ResolveAddr/ipv6_only_(tagged) === CONT TestMemberList_ResolveAddr/localhost -=== CONT TestMemberList_ResolveAddr/ipv4_port_combo -=== CONT TestMemberList_ResolveAddr/ipv6_non-pair -=== CONT TestMemberList_ResolveAddr/ipv4_only_(tagged) -=== CONT TestMemberList_ResolveAddr/ipv6_pair -=== CONT TestMemberList_ResolveAddr/ipv6_port_combo -=== CONT TestMemberList_ResolveAddr/ipv4_port_combo_(tagged) === CONT TestMemberList_ResolveAddr/ipv6_only_(tagged) -=== CONT TestMemberList_ResolveAddr/localhost_(tagged) === CONT TestMemberList_ResolveAddr/ipv6_only -=== CONT TestMemberList_ResolveAddr/ipv6_port_combo_(tagged) +=== CONT TestMemberList_ResolveAddr/ipv4_only_(tagged) === CONT TestMemberList_ResolveAddr/ipv4_only === CONT TestMemberList_ResolveAddr/ipv4_port_combo_with_empty_tag -=== CONT TestMemberList_ResolveAddr/ipv6_pair_(tagged) -=== CONT TestMemberList_ResolveAddr/ipv6_non-pair_(tagged) -=== CONT TestMemberList_ResolveAddr/hostless_port +=== CONT TestMemberList_ResolveAddr/ipv6_port_combo_(tagged) +=== CONT TestMemberList_ResolveAddr/ipv6_port_combo +=== CONT TestMemberList_ResolveAddr/ipv4_port_combo_(tagged) +=== CONT TestMemberList_ResolveAddr/ipv4_port_combo === CONT TestMemberList_ResolveAddr/too_high_port_(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_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/ipv6_non-pair (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv4_port_combo (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv6_pair (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv4_only_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv6_port_combo (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv4_port_combo_(tagged) (0.00s) + --- PASS: TestMemberList_ResolveAddr/localhost (0.00s) --- PASS: TestMemberList_ResolveAddr/ipv6_only_(tagged) (0.00s) --- PASS: TestMemberList_ResolveAddr/ipv6_only (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv6_port_combo_(tagged) (0.00s) + --- PASS: TestMemberList_ResolveAddr/ipv4_only_(tagged) (0.00s) --- PASS: TestMemberList_ResolveAddr/ipv4_only (0.00s) --- PASS: TestMemberList_ResolveAddr/ipv4_port_combo_with_empty_tag (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv6_pair_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/ipv6_non-pair_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/hostless_port (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/too_high_port_(tagged) (0.00s) --- PASS: TestMemberList_ResolveAddr/too_high_port (0.00s) - --- PASS: TestMemberList_ResolveAddr/hostless_port_(tagged) (0.00s) - --- PASS: TestMemberList_ResolveAddr/hostname_port_combo (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) - --- PASS: TestMemberList_ResolveAddr/localhost (0.00s) === RUN TestMemberList_ResolveAddr_TCP_First - log.go:198: test[127.0.0.25]: 2025/02/09 19:52:17 [DEBUG] memberlist: Using dynamic bind port 45351 + log.go:198: test[127.0.0.25]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 41611 --- PASS: TestMemberList_ResolveAddr_TCP_First (0.00s) === RUN TestMemberList_Members --- PASS: TestMemberList_Members (0.00s) === RUN TestMemberlist_Join - log.go:198: test[127.0.0.26]: 2025/02/09 19:52:17 [DEBUG] memberlist: Using dynamic bind port 45195 - log.go:198: test[127.0.0.27]: 2025/02/09 19:52:17 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.26 127.0.0.26:45195 - log.go:198: test[127.0.0.26]: 2025/02/09 19:52:17 [DEBUG] memberlist: Stream connection from=127.0.0.1:50972 ---- PASS: TestMemberlist_Join (0.00s) + log.go:198: test[127.0.0.26]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 41213 + log.go:198: test[127.0.0.26]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:59200 + log.go:198: test[127.0.0.27]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.26 127.0.0.26:41213 +--- PASS: TestMemberlist_Join (0.02s) === RUN TestMemberlist_JoinDifferentNetworksUniqueMask - log.go:198: test[127.0.0.28]: 2025/02/09 19:52:17 [DEBUG] memberlist: Using dynamic bind port 44891 - log.go:198: test[127.0.1.29]: 2025/02/09 19:52:17 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.28 127.0.0.28:44891 - log.go:198: test[127.0.0.28]: 2025/02/09 19:52:17 [DEBUG] memberlist: Stream connection from=127.0.0.1:49872 ---- PASS: TestMemberlist_JoinDifferentNetworksUniqueMask (0.00s) + log.go:198: test[127.0.0.28]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 40767 + log.go:198: test[127.0.1.29]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.28 127.0.0.28:40767 + log.go:198: test[127.0.0.28]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:46486 +--- PASS: TestMemberlist_JoinDifferentNetworksUniqueMask (0.10s) === RUN TestMemberlist_JoinDifferentNetworksMultiMasks - log.go:198: test[127.0.0.30]: 2025/02/09 19:52:17 [DEBUG] memberlist: Using dynamic bind port 37261 - log.go:198: test[127.0.1.31]: 2025/02/09 19:52:17 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.30 127.0.0.30:37261 - log.go:198: test[127.0.0.30]: 2025/02/09 19:52:17 [DEBUG] memberlist: Stream connection from=127.0.0.1:33906 - log.go:198: test[127.0.2.32]: 2025/02/09 19:52:17 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.30 127.0.0.30:37261 - log.go:198: test[127.0.0.30]: 2025/02/09 19:52:17 [DEBUG] memberlist: Stream connection from=127.0.0.1:33918 - log.go:198: test[127.0.0.30]: 2025/02/09 19:52:17 [WARN] memberlist: Rejected node 127.0.2.32 (127.0.2.32): 127.0.2.32 is not allowed - log.go:198: test[127.0.2.33]: 2025/02/09 19:52:17 [WARN] memberlist: Rejected node 127.0.2.33 (127.0.2.33): 127.0.2.33 is not allowed - log.go:198: test[127.0.2.33]: 2025/02/09 19:52:17 [DEBUG] memberlist: Failed to join 127.0.0.30: memberlist: node names are required by configuration but one was not provided - log.go:198: test[127.0.2.33]: 2025/02/09 19:52:17 [DEBUG] memberlist: Failed to join 127.0.1.31: memberlist: node names are required by configuration but one was not provided + log.go:198: test[127.0.0.30]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 37119 + log.go:198: test[127.0.0.30]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:33320 + log.go:198: test[127.0.1.31]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.30 127.0.0.30:37119 + log.go:198: test[127.0.0.30]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:33332 + log.go:198: test[127.0.2.32]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.30 127.0.0.30:37119 + log.go:198: test[127.0.0.30]: 2024/01/08 13:32:52 [WARN] memberlist: Rejected node 127.0.2.32 (127.0.2.32): 127.0.2.32 is not allowed + log.go:198: test[127.0.2.33]: 2024/01/08 13:32:52 [WARN] memberlist: Rejected node 127.0.2.33 (127.0.2.33): 127.0.2.33 is not allowed + log.go:198: test[127.0.2.33]: 2024/01/08 13:32:52 [DEBUG] memberlist: Failed to join 127.0.0.30: memberlist: node names are required by configuration but one was not provided + log.go:198: test[127.0.2.33]: 2024/01/08 13:32:52 [DEBUG] memberlist: Failed to join 127.0.1.31: memberlist: node names are required by configuration but one was not provided --- PASS: TestMemberlist_JoinDifferentNetworksMultiMasks (0.00s) === RUN TestMemberlist_Join_Cancel - log.go:198: test[127.0.0.34]: 2025/02/09 19:52:17 [DEBUG] memberlist: Using dynamic bind port 39261 - log.go:198: test[127.0.0.35]: 2025/02/09 19:52:17 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.34 127.0.0.34:39261 - log.go:198: test[127.0.0.34]: 2025/02/09 19:52:17 [DEBUG] memberlist: Stream connection from=127.0.0.1:34506 + log.go:198: test[127.0.0.34]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 43421 + log.go:198: test[127.0.0.34]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:58742 + log.go:198: test[127.0.0.35]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.34 127.0.0.34:43421 memberlist_test.go:777: Cancel merge - log.go:198: test[127.0.0.34]: 2025/02/09 19:52:17 [ERR] memberlist: Failed push/pull merge: Custom merge canceled from=127.0.0.1:34506 + log.go:198: test[127.0.0.34]: 2024/01/08 13:32:52 [ERR] memberlist: Failed push/pull merge: Custom merge canceled from=127.0.0.1:58742 memberlist_test.go:777: Cancel merge - log.go:198: test[127.0.0.35]: 2025/02/09 19:52:17 [DEBUG] memberlist: Failed to join 127.0.0.34: Custom merge canceled + log.go:198: test[127.0.0.35]: 2024/01/08 13:32:52 [DEBUG] memberlist: Failed to join 127.0.0.34: Custom merge canceled --- PASS: TestMemberlist_Join_Cancel (0.00s) === RUN TestMemberlist_Join_Cancel_Passive - log.go:198: test[127.0.0.36]: 2025/02/09 19:52:17 [DEBUG] memberlist: Using dynamic bind port 39601 - log.go:198: test[127.0.0.37]: 2025/02/09 19:52:17 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.36 127.0.0.36:39601 - log.go:198: test[127.0.0.36]: 2025/02/09 19:52:17 [DEBUG] memberlist: Stream connection from=127.0.0.1:42306 + log.go:198: test[127.0.0.36]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 38799 + log.go:198: test[127.0.0.36]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:52452 + log.go:198: test[127.0.0.37]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.36 127.0.0.36:38799 memberlist_test.go:840: Cancel alive - log.go:198: test[127.0.0.36]: 2025/02/09 19:52:17 [WARN] memberlist: ignoring alive message for '127.0.0.37': Custom alive canceled + log.go:198: test[127.0.0.36]: 2024/01/08 13:32:52 [WARN] memberlist: ignoring alive message for '127.0.0.37': Custom alive canceled memberlist_test.go:840: Cancel alive - log.go:198: test[127.0.0.37]: 2025/02/09 19:52:17 [WARN] memberlist: ignoring alive message for '127.0.0.36': Custom alive canceled ---- PASS: TestMemberlist_Join_Cancel_Passive (0.00s) + log.go:198: test[127.0.0.37]: 2024/01/08 13:32:52 [WARN] memberlist: ignoring alive message for '127.0.0.36': Custom alive canceled +--- PASS: TestMemberlist_Join_Cancel_Passive (0.01s) === RUN TestMemberlist_Join_protocolVersions - log.go:198: test[127.0.0.38]: 2025/02/09 19:52:17 [DEBUG] memberlist: Using dynamic bind port 35967 - log.go:198: test[127.0.0.38]: 2025/02/09 19:52:17 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.39 127.0.0.39:35967 - log.go:198: test[127.0.0.39]: 2025/02/09 19:52:17 [DEBUG] memberlist: Stream connection from=127.0.0.1:52192 - log.go:198: test[127.0.0.38]: 2025/02/09 19:52:18 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.40 127.0.0.40:35967 - log.go:198: test[127.0.0.40]: 2025/02/09 19:52:18 [DEBUG] memberlist: Stream connection from=127.0.0.1:45188 + log.go:198: test[127.0.0.38]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 34579 + log.go:198: test[127.0.0.39]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:58492 + log.go:198: test[127.0.0.38]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.39 127.0.0.39:34579 + log.go:198: test[127.0.0.38]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.40 127.0.0.40:34579 + log.go:198: test[127.0.0.40]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:40828 --- PASS: TestMemberlist_Join_protocolVersions (0.25s) === RUN TestMemberlist_Leave - log.go:198: test[127.0.0.41]: 2025/02/09 19:52:18 [DEBUG] memberlist: Using dynamic bind port 40197 - log.go:198: test[127.0.0.41]: 2025/02/09 19:52:18 [DEBUG] memberlist: Stream connection from=127.0.0.1:40182 - log.go:198: test[127.0.0.42]: 2025/02/09 19:52:18 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.41 127.0.0.41:40197 ---- PASS: TestMemberlist_Leave (0.02s) + log.go:198: test[127.0.0.41]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 43743 + log.go:198: test[127.0.0.41]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:48514 + log.go:198: test[127.0.0.42]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.41 127.0.0.41:43743 +--- PASS: TestMemberlist_Leave (0.03s) === RUN TestMemberlist_JoinShutdown - log.go:198: test[127.0.0.43]: 2025/02/09 19:52:18 [DEBUG] memberlist: Using dynamic bind port 36503 - log.go:198: test[127.0.0.44]: 2025/02/09 19:52:18 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.43 127.0.0.43:36503 - log.go:198: test[127.0.0.43]: 2025/02/09 19:52:18 [DEBUG] memberlist: Stream connection from=127.0.0.1:54110 - log.go:198: test[127.0.0.44]: 2025/02/09 19:52:18 [DEBUG] memberlist: Failed ping: 127.0.0.43 (timeout reached) - log.go:198: test[127.0.0.44]: 2025/02/09 19:52:18 [INFO] memberlist: Suspect 127.0.0.43 has failed, no acks received - log.go:198: test[127.0.0.44]: 2025/02/09 19:52:18 [DEBUG] memberlist: Failed ping: 127.0.0.43 (timeout reached) - log.go:198: test[127.0.0.44]: 2025/02/09 19:52:18 [INFO] memberlist: Suspect 127.0.0.43 has failed, no acks received - log.go:198: test[127.0.0.44]: 2025/02/09 19:52:18 [INFO] memberlist: Marking 127.0.0.43 as failed, suspect timeout reached (0 peer confirmations) - log.go:198: test[127.0.0.44]: 2025/02/09 19:52:18 [DEBUG] memberlist: Failed ping: 127.0.0.43 (timeout reached) - log.go:198: test[127.0.0.44]: 2025/02/09 19:52:18 [INFO] memberlist: Suspect 127.0.0.43 has failed, no acks received ---- PASS: TestMemberlist_JoinShutdown (0.01s) + log.go:198: test[127.0.0.43]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 36087 + log.go:198: test[127.0.0.43]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:60526 + log.go:198: test[127.0.0.44]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.43 127.0.0.43:36087 + log.go:198: test[127.0.0.43]: 2024/01/08 13:32:52 [INFO] memberlist: Suspect 127.0.0.44 has failed, no acks received + log.go:198: test[127.0.0.43]: 2024/01/08 13:32:52 [ERR] memberlist: Failed to send compound ping and suspect message to 127.0.0.44:36087: write udp 127.0.0.43:36087->127.0.0.44:36087: use of closed network connection + log.go:198: test[127.0.0.44]: 2024/01/08 13:32:52 [DEBUG] memberlist: Failed ping: 127.0.0.43 (timeout reached) + log.go:198: test[127.0.0.43]: 2024/01/08 13:32:52 [INFO] memberlist: Marking 127.0.0.44 as failed, suspect timeout reached (0 peer confirmations) + log.go:198: test[127.0.0.44]: 2024/01/08 13:32:52 [INFO] memberlist: Suspect 127.0.0.43 has failed, no acks received + log.go:198: test[127.0.0.44]: 2024/01/08 13:32:52 [DEBUG] memberlist: Failed ping: 127.0.0.43 (timeout reached) + log.go:198: test[127.0.0.44]: 2024/01/08 13:32:52 [INFO] memberlist: Suspect 127.0.0.43 has failed, no acks received + log.go:198: test[127.0.0.44]: 2024/01/08 13:32:52 [INFO] memberlist: Marking 127.0.0.43 as failed, suspect timeout reached (0 peer confirmations) + log.go:198: test[127.0.0.44]: 2024/01/08 13:32:52 [DEBUG] memberlist: Failed ping: 127.0.0.43 (timeout reached) +--- PASS: TestMemberlist_JoinShutdown (0.05s) === RUN TestMemberlist_delegateMeta - log.go:198: test[127.0.0.45]: 2025/02/09 19:52:18 [DEBUG] memberlist: Using dynamic bind port 35947 - log.go:198: test[127.0.0.45]: 2025/02/09 19:52:18 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.46 127.0.0.46:35947 - log.go:198: test[127.0.0.46]: 2025/02/09 19:52:18 [DEBUG] memberlist: Stream connection from=127.0.0.1:46396 ---- PASS: TestMemberlist_delegateMeta (0.25s) + log.go:198: test[127.0.0.45]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 44945 + log.go:198: test[127.0.0.46]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:50100 + log.go:198: test[127.0.0.45]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.46 127.0.0.46:44945 +--- PASS: TestMemberlist_delegateMeta (0.26s) === RUN TestMemberlist_delegateMeta_Update - log.go:198: test[127.0.0.47]: 2025/02/09 19:52:18 [DEBUG] memberlist: Using dynamic bind port 42695 - log.go:198: test[127.0.0.47]: 2025/02/09 19:52:18 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.48 127.0.0.48:42695 - log.go:198: test[127.0.0.48]: 2025/02/09 19:52:18 [DEBUG] memberlist: Stream connection from=127.0.0.1:39670 ---- PASS: TestMemberlist_delegateMeta_Update (1.85s) + log.go:198: test[127.0.0.47]: 2024/01/08 13:32:52 [DEBUG] memberlist: Using dynamic bind port 43907 + log.go:198: test[127.0.0.48]: 2024/01/08 13:32:52 [DEBUG] memberlist: Stream connection from=127.0.0.1:35280 + log.go:198: test[127.0.0.47]: 2024/01/08 13:32:52 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.48 127.0.0.48:43907 +--- PASS: TestMemberlist_delegateMeta_Update (1.66s) === RUN TestMemberlist_UserData - log.go:198: test[127.0.0.49]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 39833 - log.go:198: test[127.0.0.50]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.49 127.0.0.49:39833 - log.go:198: test[127.0.0.49]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:40002 ---- PASS: TestMemberlist_UserData (0.11s) + log.go:198: test[127.0.0.49]: 2024/01/08 13:32:54 [DEBUG] memberlist: Using dynamic bind port 39785 + log.go:198: test[127.0.0.49]: 2024/01/08 13:32:54 [DEBUG] memberlist: Stream connection from=127.0.0.1:40240 + log.go:198: test[127.0.0.50]: 2024/01/08 13:32:54 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.49 127.0.0.49:39785 +--- PASS: TestMemberlist_UserData (0.13s) === RUN TestMemberlist_SendTo - log.go:198: test[127.0.0.51]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 46789 - log.go:198: test[127.0.0.51]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:56668 - log.go:198: test[127.0.0.52]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.51 127.0.0.51:46789 - log.go:198: test[127.0.0.51]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.52 127.0.0.52:46789 - log.go:198: test[127.0.0.52]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.51 127.0.0.51:46789 - log.go:198: test[127.0.0.51]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:56676 - log.go:198: test[127.0.0.52]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:54310 - log.go:198: test[127.0.0.51]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.52 127.0.0.52:46789 - log.go:198: test[127.0.0.52]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.51 127.0.0.51:46789 - log.go:198: test[127.0.0.51]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:56688 - log.go:198: test[127.0.0.52]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:54318 - log.go:198: test[127.0.0.52]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.51 127.0.0.51:46789 - log.go:198: test[127.0.0.51]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:56690 - log.go:198: test[127.0.0.52]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:54326 - log.go:198: test[127.0.0.51]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.52 127.0.0.52:46789 - log.go:198: test[127.0.0.51]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.52 127.0.0.52:46789 - log.go:198: test[127.0.0.52]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.51 127.0.0.51:46789 - log.go:198: test[127.0.0.51]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:56692 - log.go:198: test[127.0.0.52]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:54340 - log.go:198: test[127.0.0.52]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.51 127.0.0.51:46789 ---- PASS: TestMemberlist_SendTo (0.01s) + log.go:198: test[127.0.0.51]: 2024/01/08 13:32:54 [DEBUG] memberlist: Using dynamic bind port 41941 + log.go:198: test[127.0.0.51]: 2024/01/08 13:32:54 [DEBUG] memberlist: Stream connection from=127.0.0.1:33618 + log.go:198: test[127.0.0.52]: 2024/01/08 13:32:54 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.51 127.0.0.51:41941 + log.go:198: test[127.0.0.51]: 2024/01/08 13:32:54 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.52 127.0.0.52:41941 + log.go:198: test[127.0.0.52]: 2024/01/08 13:32:54 [DEBUG] memberlist: Stream connection from=127.0.0.1:40164 + log.go:198: test[127.0.0.51]: 2024/01/08 13:32:54 [DEBUG] memberlist: Stream connection from=127.0.0.1:33626 + log.go:198: test[127.0.0.52]: 2024/01/08 13:32:54 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.51 127.0.0.51:41941 + log.go:198: test[127.0.0.52]: 2024/01/08 13:32:54 [ERR] memberlist: Failed to send gossip to 127.0.0.51:41941: write udp 127.0.0.52:41941->127.0.0.51:41941: use of closed network connection +--- PASS: TestMemberlist_SendTo (0.03s) === RUN TestMemberlistProtocolVersion - log.go:198: test[127.0.0.53]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 43897 ---- PASS: TestMemberlistProtocolVersion (0.00s) + log.go:198: test[127.0.0.53]: 2024/01/08 13:32:54 [DEBUG] memberlist: Using dynamic bind port 44581 +--- PASS: TestMemberlistProtocolVersion (0.01s) === RUN TestMemberlist_Join_DeadNode - log.go:198: test[127.0.0.54]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 39269 - log.go:198: test[127.0.0.54]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: fake 127.0.0.55:39269 - log.go:198: test[127.0.0.54]: 2025/02/09 19:52:20 [DEBUG] memberlist: Failed to join 127.0.0.55: read tcp 127.0.0.1:58336->127.0.0.55:39269: i/o timeout ---- PASS: TestMemberlist_Join_DeadNode (0.05s) + log.go:198: test[127.0.0.54]: 2024/01/08 13:32:54 [DEBUG] memberlist: Using dynamic bind port 46861 + log.go:198: test[127.0.0.54]: 2024/01/08 13:32:54 [DEBUG] memberlist: Initiating push/pull sync with: fake 127.0.0.55:46861 + log.go:198: test[127.0.0.54]: 2024/01/08 13:32:54 [DEBUG] memberlist: Failed to join 127.0.0.55: read tcp 127.0.0.1:39616->127.0.0.55:46861: i/o timeout +--- PASS: TestMemberlist_Join_DeadNode (0.06s) === RUN TestMemberlist_Join_Protocol_Compatibility === RUN TestMemberlist_Join_Protocol_Compatibility/2,1 - log.go:198: test[127.0.0.56]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 34341 - log.go:198: test[127.0.0.57]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.56 127.0.0.56:34341 - log.go:198: test[127.0.0.56]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:35744 + log.go:198: test[127.0.0.56]: 2024/01/08 13:32:54 [DEBUG] memberlist: Using dynamic bind port 44693 + log.go:198: test[127.0.0.56]: 2024/01/08 13:32:54 [DEBUG] memberlist: Stream connection from=127.0.0.1:60672 + log.go:198: test[127.0.0.57]: 2024/01/08 13:32:54 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.56 127.0.0.56:44693 === RUN TestMemberlist_Join_Protocol_Compatibility/2,3 - log.go:198: test[127.0.0.58]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 43491 - log.go:198: test[127.0.0.59]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.58 127.0.0.58:43491 - log.go:198: test[127.0.0.58]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:34162 + log.go:198: test[127.0.0.58]: 2024/01/08 13:32:54 [DEBUG] memberlist: Using dynamic bind port 33377 + log.go:198: test[127.0.0.58]: 2024/01/08 13:32:54 [DEBUG] memberlist: Stream connection from=127.0.0.1:59212 + log.go:198: test[127.0.0.59]: 2024/01/08 13:32:54 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.58 127.0.0.58:33377 === RUN TestMemberlist_Join_Protocol_Compatibility/3,2 - log.go:198: test[127.0.0.60]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 38219 - log.go:198: test[127.0.0.61]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.60 127.0.0.60:38219 - log.go:198: test[127.0.0.60]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:43702 + log.go:198: test[127.0.0.60]: 2024/01/08 13:32:54 [DEBUG] memberlist: Using dynamic bind port 45185 + log.go:198: test[127.0.0.60]: 2024/01/08 13:32:54 [DEBUG] memberlist: Stream connection from=127.0.0.1:58500 + log.go:198: test[127.0.0.61]: 2024/01/08 13:32:54 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.60 127.0.0.60:45185 === RUN TestMemberlist_Join_Protocol_Compatibility/3,1 - log.go:198: test[127.0.0.62]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 40333 - log.go:198: test[127.0.0.63]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.62 127.0.0.62:40333 - log.go:198: test[127.0.0.62]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:59462 ---- PASS: TestMemberlist_Join_Protocol_Compatibility (0.01s) + log.go:198: test[127.0.0.62]: 2024/01/08 13:32:54 [DEBUG] memberlist: Using dynamic bind port 33049 + log.go:198: test[127.0.0.63]: 2024/01/08 13:32:54 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.62 127.0.0.62:33049 + log.go:198: test[127.0.0.62]: 2024/01/08 13:32:54 [DEBUG] memberlist: Stream connection from=127.0.0.1:35936 +--- PASS: TestMemberlist_Join_Protocol_Compatibility (0.02s) --- 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.00s) + --- PASS: TestMemberlist_Join_Protocol_Compatibility/3,1 (0.02s) === RUN TestMemberlist_Join_IPv6 - log.go:198: test[A]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 40149 - log.go:198: test[B]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 36291 - log.go:198: test[B]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: A [::1]:40149 - log.go:198: test[A]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=[::1]:48900 ---- PASS: TestMemberlist_Join_IPv6 (0.00s) + log.go:198: test[A]: 2024/01/08 13:32:54 [DEBUG] memberlist: Using dynamic bind port 45527 + log.go:198: test[B]: 2024/01/08 13:32:54 [DEBUG] memberlist: Using dynamic bind port 43173 + log.go:198: test[A]: 2024/01/08 13:32:54 [DEBUG] memberlist: Stream connection from=[::1]:40658 + log.go:198: test[B]: 2024/01/08 13:32:54 [DEBUG] memberlist: Initiating push/pull sync with: A [::1]:45527 +--- PASS: TestMemberlist_Join_IPv6 (0.03s) === RUN TestAdvertiseAddr - memberlist_test.go:1539: Using dynamic bind port 45991 for BIND - memberlist_test.go:1539: Using dynamic bind port 38651 for ADVERTISE ---- PASS: TestAdvertiseAddr (0.25s) + memberlist_test.go:1539: Using dynamic bind port 46193 for BIND + memberlist_test.go:1539: Using dynamic bind port 33003 for ADVERTISE +--- PASS: TestAdvertiseAddr (0.26s) === RUN TestMemberlist_conflictDelegate - log.go:198: test[127.0.0.66]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 41137 - log.go:198: test[127.0.0.66]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.66 127.0.0.67:41137 - log.go:198: test[127.0.0.67]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:54914 - log.go:198: test[127.0.0.67]: 2025/02/09 19:52:20 [ERR] memberlist: Conflicting address for 127.0.0.66. Mine: 127.0.0.67:41137 Theirs: 127.0.0.66:41137 Old state: 0 - log.go:198: test[127.0.0.66]: 2025/02/09 19:52:20 [ERR] memberlist: Conflicting address for 127.0.0.66. Mine: 127.0.0.66:41137 Theirs: 127.0.0.67:41137 Old state: 0 ---- PASS: TestMemberlist_conflictDelegate (0.25s) + log.go:198: test[127.0.0.66]: 2024/01/08 13:32:55 [DEBUG] memberlist: Using dynamic bind port 39985 + log.go:198: test[127.0.0.67]: 2024/01/08 13:32:55 [DEBUG] memberlist: Stream connection from=127.0.0.1:46538 + log.go:198: test[127.0.0.66]: 2024/01/08 13:32:55 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.66 127.0.0.67:39985 + log.go:198: test[127.0.0.67]: 2024/01/08 13:32:55 [ERR] memberlist: Conflicting address for 127.0.0.66. Mine: 127.0.0.67:39985 Theirs: 127.0.0.66:39985 Old state: 0 + log.go:198: test[127.0.0.66]: 2024/01/08 13:32:55 [ERR] memberlist: Conflicting address for 127.0.0.66. Mine: 127.0.0.66:39985 Theirs: 127.0.0.67:39985 Old state: 0 +--- PASS: TestMemberlist_conflictDelegate (0.26s) === RUN TestMemberlist_PingDelegate - log.go:198: test[127.0.0.68]: 2025/02/09 19:52:20 [DEBUG] memberlist: Using dynamic bind port 40087 - log.go:198: test[127.0.0.69]: 2025/02/09 19:52:20 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.68 127.0.0.68:40087 - log.go:198: test[127.0.0.68]: 2025/02/09 19:52:20 [DEBUG] memberlist: Stream connection from=127.0.0.1:50858 + log.go:198: test[127.0.0.68]: 2024/01/08 13:32:55 [DEBUG] memberlist: Using dynamic bind port 44947 + log.go:198: test[127.0.0.68]: 2024/01/08 13:32:55 [DEBUG] memberlist: Stream connection from=127.0.0.1:58510 + log.go:198: test[127.0.0.69]: 2024/01/08 13:32:55 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.68 127.0.0.68:44947 --- PASS: TestMemberlist_PingDelegate (0.20s) === RUN TestMemberlist_EncryptedGossipTransition - log.go:198: test[m0]: 2025/02/09 19:52:21 [DEBUG] memberlist: Using dynamic bind port 37839 + log.go:198: test[m0]: 2024/01/08 13:32:55 [DEBUG] memberlist: Using dynamic bind port 36313 memberlist_test.go:1870: Node m1[127.0.0.71] joining node m0[127.0.0.70] - log.go:198: test[m1]: 2025/02/09 19:52:21 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.70 127.0.0.70:37839 - log.go:198: test[m0]: 2025/02/09 19:52:21 [DEBUG] memberlist: Stream connection from=127.0.0.1:56484 + log.go:198: test[m0]: 2024/01/08 13:32:55 [DEBUG] memberlist: Stream connection from=127.0.0.1:45400 + log.go:198: test[m1]: 2024/01/08 13:32:55 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.70 127.0.0.70:36313 memberlist_test.go:1872: ==== STEP 0 complete: two node unencrypted cluster ==== memberlist_test.go:1877: Node m0[127.0.0.70] is leaving to upgrade gossip to first stage memberlist_test.go:1877: Node m0[127.0.0.70] is shutting down to upgrade gossip to first stage memberlist_test.go:1889: Node m0[127.0.0.70] joining node m1[127.0.0.71] - log.go:198: test[m0]: 2025/02/09 19:52:21 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.71 127.0.0.71:37839 - log.go:198: test[m1]: 2025/02/09 19:52:21 [DEBUG] memberlist: Stream connection from=127.0.0.1:45398 - log.go:198: test[m0]: 2025/02/09 19:52:21 [WARN] memberlist: Refuting a dead message (from: 127.0.0.70) + log.go:198: test[m0]: 2024/01/08 13:32:56 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.71 127.0.0.71:36313 + log.go:198: test[m1]: 2024/01/08 13:32:56 [DEBUG] memberlist: Stream connection from=127.0.0.1:36048 + log.go:198: test[m0]: 2024/01/08 13:32:56 [WARN] memberlist: Refuting a dead message (from: 127.0.0.70) memberlist_test.go:1891: Node m1[127.0.0.71] is leaving to upgrade gossip to first stage memberlist_test.go:1891: Node m1[127.0.0.71] is shutting down to upgrade gossip to first stage memberlist_test.go:1903: Node m1[127.0.0.71] joining node m0[127.0.0.70] - log.go:198: test[m1]: 2025/02/09 19:52:22 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.70 127.0.0.70:37839 - log.go:198: test[m0]: 2025/02/09 19:52:22 [DEBUG] memberlist: Stream connection from=127.0.0.1:56488 - log.go:198: test[m1]: 2025/02/09 19:52:22 [WARN] memberlist: Refuting a dead message (from: 127.0.0.71) + log.go:198: test[m1]: 2024/01/08 13:32:56 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.70 127.0.0.70:36313 + log.go:198: test[m0]: 2024/01/08 13:32:56 [DEBUG] memberlist: Stream connection from=127.0.0.1:45404 + log.go:198: test[m1]: 2024/01/08 13:32:56 [WARN] memberlist: Refuting a dead message (from: 127.0.0.71) memberlist_test.go:1905: ==== STEP 1 complete: two node encryption-aware cluster ==== memberlist_test.go:1910: Node m0[127.0.0.70] is leaving to upgrade gossip to second stage memberlist_test.go:1910: Node m0[127.0.0.70] is shutting down to upgrade gossip to second stage memberlist_test.go:1921: Node m0[127.0.0.70] joining node m1[127.0.0.71] - log.go:198: test[m0]: 2025/02/09 19:52:23 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.71 127.0.0.71:37839 - log.go:198: test[m1]: 2025/02/09 19:52:23 [DEBUG] memberlist: Stream connection from=127.0.0.1:45404 - log.go:198: test[m0]: 2025/02/09 19:52:23 [WARN] memberlist: Refuting a dead message (from: 127.0.0.70) + log.go:198: test[m1]: 2024/01/08 13:32:57 [DEBUG] memberlist: Stream connection from=127.0.0.1:36052 + log.go:198: test[m0]: 2024/01/08 13:32:57 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.71 127.0.0.71:36313 + log.go:198: test[m0]: 2024/01/08 13:32:57 [WARN] memberlist: Refuting a dead message (from: 127.0.0.70) memberlist_test.go:1923: Node m1[127.0.0.71] is leaving to upgrade gossip to second stage memberlist_test.go:1923: Node m1[127.0.0.71] is shutting down to upgrade gossip to second stage memberlist_test.go:1935: Node m1[127.0.0.71] joining node m0[127.0.0.70] - log.go:198: test[m1]: 2025/02/09 19:52:24 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.70 127.0.0.70:37839 - log.go:198: test[m0]: 2025/02/09 19:52:24 [DEBUG] memberlist: Stream connection from=127.0.0.1:56502 - log.go:198: test[m1]: 2025/02/09 19:52:24 [WARN] memberlist: Refuting a dead message (from: 127.0.0.71) + log.go:198: test[m1]: 2024/01/08 13:32:58 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.70 127.0.0.70:36313 + log.go:198: test[m0]: 2024/01/08 13:32:58 [DEBUG] memberlist: Stream connection from=127.0.0.1:45410 + log.go:198: test[m1]: 2024/01/08 13:32:58 [WARN] memberlist: Refuting a dead message (from: 127.0.0.71) memberlist_test.go:1937: ==== STEP 2 complete: two node encryption-aware cluster being encrypted ==== memberlist_test.go:1942: Node m0[127.0.0.70] is leaving to upgrade gossip to final stage memberlist_test.go:1942: Node m0[127.0.0.70] is shutting down to upgrade gossip to final stage memberlist_test.go:1953: Node m0[127.0.0.70] joining node m1[127.0.0.71] - log.go:198: test[m0]: 2025/02/09 19:52:24 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.71 127.0.0.71:37839 - log.go:198: test[m1]: 2025/02/09 19:52:24 [DEBUG] memberlist: Stream connection from=127.0.0.1:45408 - log.go:198: test[m0]: 2025/02/09 19:52:24 [WARN] memberlist: Refuting a dead message (from: 127.0.0.70) + log.go:198: test[m0]: 2024/01/08 13:32:59 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.71 127.0.0.71:36313 + log.go:198: test[m1]: 2024/01/08 13:32:59 [DEBUG] memberlist: Stream connection from=127.0.0.1:36058 + log.go:198: test[m0]: 2024/01/08 13:32:59 [WARN] memberlist: Refuting a dead message (from: 127.0.0.70) memberlist_test.go:1955: Node m1[127.0.0.71] is leaving to upgrade gossip to final stage memberlist_test.go:1955: Node m1[127.0.0.71] is shutting down to upgrade gossip to final stage memberlist_test.go:1965: Node m1[127.0.0.71] joining node m0[127.0.0.70] - log.go:198: test[m1]: 2025/02/09 19:52:25 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.70 127.0.0.70:37839 - log.go:198: test[m0]: 2025/02/09 19:52:25 [DEBUG] memberlist: Stream connection from=127.0.0.1:56518 - log.go:198: test[m1]: 2025/02/09 19:52:25 [WARN] memberlist: Refuting a dead message (from: 127.0.0.71) + log.go:198: test[m1]: 2024/01/08 13:33:00 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.70 127.0.0.70:36313 + log.go:198: test[m0]: 2024/01/08 13:33:00 [DEBUG] memberlist: Stream connection from=127.0.0.1:45420 + log.go:198: test[m1]: 2024/01/08 13:33:00 [WARN] memberlist: Refuting a dead message (from: 127.0.0.71) memberlist_test.go:1967: ==== STEP 3 complete: two node encrypted cluster locked down ==== ---- PASS: TestMemberlist_EncryptedGossipTransition (5.20s) +--- PASS: TestMemberlist_EncryptedGossipTransition (5.32s) === RUN TestHandleCompoundPing - log.go:198: test[127.0.0.72]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 37987 + log.go:198: test[127.0.0.72]: 2024/01/08 13:33:00 [DEBUG] memberlist: Using dynamic bind port 41571 --- PASS: TestHandleCompoundPing (0.00s) === RUN TestHandlePing - log.go:198: test[127.0.0.73]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 46573 + log.go:198: test[127.0.0.73]: 2024/01/08 13:33:00 [DEBUG] memberlist: Using dynamic bind port 41241 --- PASS: TestHandlePing (0.00s) === RUN TestHandlePing_WrongNode - log.go:198: test[127.0.0.74]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 45855 - log.go:198: test[127.0.0.74]: 2025/02/09 19:52:26 [WARN] memberlist: Got ping for unexpected node '127.0.0.74-bad' from=127.0.0.1:60000 + log.go:198: test[127.0.0.74]: 2024/01/08 13:33:00 [DEBUG] memberlist: Using dynamic bind port 46575 + log.go:198: test[127.0.0.74]: 2024/01/08 13:33:00 [WARN] memberlist: Got ping for unexpected node '127.0.0.74-bad' from=127.0.0.1:60000 --- PASS: TestHandlePing_WrongNode (0.05s) === RUN TestHandleIndirectPing - log.go:198: test[127.0.0.75]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 36413 + log.go:198: test[127.0.0.75]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 45985 --- PASS: TestHandleIndirectPing (0.00s) === RUN TestTCPPing - log.go:198: test[127.0.0.76]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 38747 + log.go:198: test[127.0.0.76]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 42341 --- PASS: TestTCPPing (0.00s) === RUN TestTCPPushPull - log.go:198: test[127.0.0.77]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 37587 - log.go:198: test[127.0.0.77]: 2025/02/09 19:52:26 [DEBUG] memberlist: Stream connection from=127.0.0.1:50934 + log.go:198: test[127.0.0.77]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 44585 + log.go:198: test[127.0.0.77]: 2024/01/08 13:33:01 [DEBUG] memberlist: Stream connection from=127.0.0.1:59546 --- PASS: TestTCPPushPull (0.00s) === RUN TestSendMsg_Piggyback - log.go:198: test[127.0.0.78]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 37605 ---- PASS: TestSendMsg_Piggyback (0.00s) + log.go:198: test[127.0.0.78]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 37277 +--- PASS: TestSendMsg_Piggyback (0.01s) === RUN TestEncryptDecryptState - log.go:198: test: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 39147 ---- PASS: TestEncryptDecryptState (0.00s) + log.go:198: test: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 37217 +--- PASS: TestEncryptDecryptState (0.01s) === RUN TestRawSendUdp_CRC - log.go:198: test[127.0.0.79]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 38531 + log.go:198: test[127.0.0.79]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 46489 --- PASS: TestRawSendUdp_CRC (0.00s) === RUN TestIngestPacket_CRC - log.go:198: test[127.0.0.80]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 46633 + log.go:198: test[127.0.0.80]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 40163 --- PASS: TestIngestPacket_CRC (0.00s) === RUN TestIngestPacket_ExportedFunc_EmptyMessage - log.go:198: test[127.0.0.81]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 45491 + log.go:198: test[127.0.0.81]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 38375 --- PASS: TestIngestPacket_ExportedFunc_EmptyMessage (0.00s) === RUN TestGossip_MismatchedKeys - log.go:198: test[127.0.0.82]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 45785 - log.go:198: test[127.0.0.83]: 2025/02/09 19:52:26 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.82 127.0.0.82:45785 - log.go:198: test[127.0.0.82]: 2025/02/09 19:52:26 [DEBUG] memberlist: Stream connection from=127.0.0.1:46232 - log.go:198: test[127.0.0.82]: 2025/02/09 19:52:26 [ERR] memberlist: failed to receive: No installed keys could decrypt the message from=127.0.0.1:46232 - log.go:198: test[127.0.0.83]: 2025/02/09 19:52:26 [DEBUG] memberlist: Failed to join 127.0.0.82: No installed keys could decrypt the message ---- PASS: TestGossip_MismatchedKeys (0.00s) + log.go:198: test[127.0.0.82]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 42287 + log.go:198: test[127.0.0.82]: 2024/01/08 13:33:01 [DEBUG] memberlist: Stream connection from=127.0.0.1:58796 + log.go:198: test[127.0.0.83]: 2024/01/08 13:33:01 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.82 127.0.0.82:42287 + log.go:198: test[127.0.0.82]: 2024/01/08 13:33:01 [ERR] memberlist: failed to receive: No installed keys could decrypt the message from=127.0.0.1:58796 + log.go:198: test[127.0.0.83]: 2024/01/08 13:33:01 [DEBUG] memberlist: Failed to join 127.0.0.82: No installed keys could decrypt the message +--- PASS: TestGossip_MismatchedKeys (0.01s) === RUN TestLimitedBroadcastLess === RUN TestLimitedBroadcastLess/diff-transmits === RUN TestLimitedBroadcastLess/same-transmits--diff-len @@ -1340,134 +1366,134 @@ === RUN TestEncryptDecrypt_V1 --- PASS: TestEncryptDecrypt_V1 (0.00s) === RUN TestMemberList_Probe - log.go:198: test[127.0.0.84]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 35467 - log.go:198: test[127.0.0.85]: 2025/02/09 19:52:26 [WARN] memberlist: Refuting an alive message for '127.0.0.85' (127.0.0.85:35467) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.84]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 33753 + log.go:198: test[127.0.0.85]: 2024/01/08 13:33:01 [WARN] memberlist: Refuting an alive message for '127.0.0.85' (127.0.0.85:33753) 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 - log.go:198: test[127.0.0.86]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 35585 - log.go:198: test[127.0.0.86]: 2025/02/09 19:52:26 [DEBUG] memberlist: Failed ping: 127.0.0.89 (timeout reached) - log.go:198: test[127.0.0.87]: 2025/02/09 19:52:26 [WARN] memberlist: Refuting an alive message for '127.0.0.87' (127.0.0.87:35585) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.88]: 2025/02/09 19:52:26 [WARN] memberlist: Refuting an alive message for '127.0.0.88' (127.0.0.88:35585) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.86]: 2025/02/09 19:52:26 [INFO] memberlist: Suspect 127.0.0.89 has failed, no acks received ---- PASS: TestMemberList_ProbeNode_Suspect (0.02s) + log.go:198: test[127.0.0.86]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 33071 + log.go:198: test[127.0.0.86]: 2024/01/08 13:33:01 [DEBUG] memberlist: Failed ping: 127.0.0.89 (timeout reached) + log.go:198: test[127.0.0.87]: 2024/01/08 13:33:01 [WARN] memberlist: Refuting an alive message for '127.0.0.87' (127.0.0.87:33071) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.86]: 2024/01/08 13:33:01 [INFO] memberlist: Suspect 127.0.0.89 has failed, no acks received +--- PASS: TestMemberList_ProbeNode_Suspect (0.03s) === RUN TestMemberList_ProbeNode_Suspect_Dogpile === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=2,_k=3_(max_timeout_disabled) - log.go:198: test[127.0.0.90]: 2025/02/09 19:52:26 [DEBUG] memberlist: Using dynamic bind port 35335 - log.go:198: test[127.0.0.90]: 2025/02/09 19:52:26 [DEBUG] memberlist: Failed ping: 127.0.0.91 (timeout reached) - log.go:198: test[127.0.0.90]: 2025/02/09 19:52:26 [INFO] memberlist: Suspect 127.0.0.91 has failed, no acks received - log.go:198: test[127.0.0.90]: 2025/02/09 19:52:27 [INFO] memberlist: Marking 127.0.0.91 as failed, suspect timeout reached (0 peer confirmations) + log.go:198: test[127.0.0.90]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 36641 + log.go:198: test[127.0.0.90]: 2024/01/08 13:33:01 [DEBUG] memberlist: Failed ping: 127.0.0.91 (timeout reached) + log.go:198: test[127.0.0.90]: 2024/01/08 13:33:01 [INFO] memberlist: Suspect 127.0.0.91 has failed, no acks received + log.go:198: test[127.0.0.90]: 2024/01/08 13:33:01 [INFO] memberlist: Marking 127.0.0.91 as failed, suspect timeout reached (0 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=3,_k=3 - log.go:198: test[127.0.0.92]: 2025/02/09 19:52:27 [DEBUG] memberlist: Using dynamic bind port 46659 - log.go:198: test[127.0.0.92]: 2025/02/09 19:52:27 [DEBUG] memberlist: Failed ping: 127.0.0.94 (timeout reached) - log.go:198: test[127.0.0.93]: 2025/02/09 19:52:27 [WARN] memberlist: Refuting an alive message for '127.0.0.93' (127.0.0.93:46659) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.92]: 2025/02/09 19:52:27 [INFO] memberlist: Suspect 127.0.0.94 has failed, no acks received - log.go:198: test[127.0.0.92]: 2025/02/09 19:52:27 [INFO] memberlist: Marking 127.0.0.94 as failed, suspect timeout reached (0 peer confirmations) + log.go:198: test[127.0.0.92]: 2024/01/08 13:33:01 [DEBUG] memberlist: Using dynamic bind port 35371 + log.go:198: test[127.0.0.92]: 2024/01/08 13:33:01 [DEBUG] memberlist: Failed ping: 127.0.0.94 (timeout reached) + log.go:198: test[127.0.0.93]: 2024/01/08 13:33:01 [WARN] memberlist: Refuting an alive message for '127.0.0.93' (127.0.0.93:35371) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.92]: 2024/01/08 13:33:01 [INFO] memberlist: Suspect 127.0.0.94 has failed, no acks received + log.go:198: test[127.0.0.92]: 2024/01/08 13:33:02 [INFO] memberlist: Marking 127.0.0.94 as failed, suspect timeout reached (0 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=4,_k=3 - log.go:198: test[127.0.0.95]: 2025/02/09 19:52:27 [DEBUG] memberlist: Using dynamic bind port 38763 - log.go:198: test[127.0.0.95]: 2025/02/09 19:52:27 [DEBUG] memberlist: Failed ping: 127.0.0.98 (timeout reached) - log.go:198: test[127.0.0.97]: 2025/02/09 19:52:27 [WARN] memberlist: Refuting an alive message for '127.0.0.97' (127.0.0.97:38763) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.96]: 2025/02/09 19:52:27 [WARN] memberlist: Refuting an alive message for '127.0.0.96' (127.0.0.96:38763) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.95]: 2025/02/09 19:52:27 [INFO] memberlist: Suspect 127.0.0.98 has failed, no acks received - log.go:198: test[127.0.0.95]: 2025/02/09 19:52:28 [INFO] memberlist: Marking 127.0.0.98 as failed, suspect timeout reached (0 peer confirmations) + log.go:198: test[127.0.0.95]: 2024/01/08 13:33:02 [DEBUG] memberlist: Using dynamic bind port 43507 + log.go:198: test[127.0.0.95]: 2024/01/08 13:33:02 [DEBUG] memberlist: Failed ping: 127.0.0.98 (timeout reached) + log.go:198: test[127.0.0.96]: 2024/01/08 13:33:02 [WARN] memberlist: Refuting an alive message for '127.0.0.96' (127.0.0.96:43507) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.97]: 2024/01/08 13:33:02 [WARN] memberlist: Refuting an alive message for '127.0.0.97' (127.0.0.97:43507) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.95]: 2024/01/08 13:33:02 [INFO] memberlist: Suspect 127.0.0.98 has failed, no acks received + log.go:198: test[127.0.0.95]: 2024/01/08 13:33:02 [INFO] memberlist: Marking 127.0.0.98 as failed, suspect timeout reached (0 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=5,_k=3_(max_timeout_starts_to_take_effect) - log.go:198: test[127.0.0.99]: 2025/02/09 19:52:28 [DEBUG] memberlist: Using dynamic bind port 42155 - log.go:198: test[127.0.0.99]: 2025/02/09 19:52:28 [DEBUG] memberlist: Failed ping: 127.0.0.103 (timeout reached) - log.go:198: test[127.0.0.101]: 2025/02/09 19:52:28 [WARN] memberlist: Refuting an alive message for '127.0.0.101' (127.0.0.101:42155) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.102]: 2025/02/09 19:52:28 [WARN] memberlist: Refuting an alive message for '127.0.0.102' (127.0.0.102:42155) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.100]: 2025/02/09 19:52:28 [WARN] memberlist: Refuting an alive message for '127.0.0.100' (127.0.0.100:42155) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.99]: 2025/02/09 19:52:28 [INFO] memberlist: Suspect 127.0.0.103 has failed, no acks received - log.go:198: test[127.0.0.99]: 2025/02/09 19:52:29 [INFO] memberlist: Marking 127.0.0.103 as failed, suspect timeout reached (0 peer confirmations) + log.go:198: test[127.0.0.99]: 2024/01/08 13:33:02 [DEBUG] memberlist: Using dynamic bind port 39281 + log.go:198: test[127.0.0.99]: 2024/01/08 13:33:02 [DEBUG] memberlist: Failed ping: 127.0.0.103 (timeout reached) + log.go:198: test[127.0.0.101]: 2024/01/08 13:33:03 [WARN] memberlist: Refuting an alive message for '127.0.0.101' (127.0.0.101:39281) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.102]: 2024/01/08 13:33:03 [WARN] memberlist: Refuting an alive message for '127.0.0.102' (127.0.0.102:39281) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.100]: 2024/01/08 13:33:03 [WARN] memberlist: Refuting an alive message for '127.0.0.100' (127.0.0.100:39281) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.99]: 2024/01/08 13:33:03 [INFO] memberlist: Suspect 127.0.0.103 has failed, no acks received + log.go:198: test[127.0.0.99]: 2024/01/08 13:33:04 [INFO] memberlist: Marking 127.0.0.103 as failed, suspect timeout reached (0 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3 - log.go:198: test[127.0.0.104]: 2025/02/09 19:52:29 [DEBUG] memberlist: Using dynamic bind port 42829 - log.go:198: test[127.0.0.104]: 2025/02/09 19:52:29 [DEBUG] memberlist: Failed ping: 127.0.0.109 (timeout reached) - log.go:198: test[127.0.0.105]: 2025/02/09 19:52:29 [WARN] memberlist: Refuting an alive message for '127.0.0.105' (127.0.0.105:42829) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.108]: 2025/02/09 19:52:29 [WARN] memberlist: Refuting an alive message for '127.0.0.108' (127.0.0.108:42829) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.107]: 2025/02/09 19:52:29 [WARN] memberlist: Refuting an alive message for '127.0.0.107' (127.0.0.107:42829) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.104]: 2025/02/09 19:52:29 [INFO] memberlist: Suspect 127.0.0.109 has failed, no acks received - log.go:198: test[127.0.0.104]: 2025/02/09 19:52:30 [INFO] memberlist: Marking 127.0.0.109 as failed, suspect timeout reached (0 peer confirmations) + log.go:198: test[127.0.0.104]: 2024/01/08 13:33:04 [DEBUG] memberlist: Using dynamic bind port 34417 + log.go:198: test[127.0.0.104]: 2024/01/08 13:33:04 [DEBUG] memberlist: Failed ping: 127.0.0.109 (timeout reached) + log.go:198: test[127.0.0.107]: 2024/01/08 13:33:04 [WARN] memberlist: Refuting an alive message for '127.0.0.107' (127.0.0.107:34417) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.106]: 2024/01/08 13:33:04 [WARN] memberlist: Refuting an alive message for '127.0.0.106' (127.0.0.106:34417) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.105]: 2024/01/08 13:33:04 [WARN] memberlist: Refuting an alive message for '127.0.0.105' (127.0.0.105:34417) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.104]: 2024/01/08 13:33:04 [INFO] memberlist: Suspect 127.0.0.109 has failed, no acks received + log.go:198: test[127.0.0.104]: 2024/01/08 13:33:05 [INFO] memberlist: Marking 127.0.0.109 as failed, suspect timeout reached (0 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3_(confirmations_start_to_lower_timeout) - log.go:198: test[127.0.0.110]: 2025/02/09 19:52:30 [DEBUG] memberlist: Using dynamic bind port 39477 - log.go:198: test[127.0.0.110]: 2025/02/09 19:52:30 [DEBUG] memberlist: Failed ping: 127.0.0.115 (timeout reached) - log.go:198: test[127.0.0.114]: 2025/02/09 19:52:30 [WARN] memberlist: Refuting an alive message for '127.0.0.114' (127.0.0.114:39477) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.113]: 2025/02/09 19:52:30 [WARN] memberlist: Refuting an alive message for '127.0.0.113' (127.0.0.113:39477) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.112]: 2025/02/09 19:52:30 [WARN] memberlist: Refuting an alive message for '127.0.0.112' (127.0.0.112:39477) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.110]: 2025/02/09 19:52:30 [INFO] memberlist: Suspect 127.0.0.115 has failed, no acks received - log.go:198: test[127.0.0.110]: 2025/02/09 19:52:31 [INFO] memberlist: Marking 127.0.0.115 as failed, suspect timeout reached (1 peer confirmations) + log.go:198: test[127.0.0.110]: 2024/01/08 13:33:05 [DEBUG] memberlist: Using dynamic bind port 42559 + log.go:198: test[127.0.0.110]: 2024/01/08 13:33:05 [DEBUG] memberlist: Failed ping: 127.0.0.115 (timeout reached) + log.go:198: test[127.0.0.114]: 2024/01/08 13:33:05 [WARN] memberlist: Refuting an alive message for '127.0.0.114' (127.0.0.114:42559) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.112]: 2024/01/08 13:33:05 [WARN] memberlist: Refuting an alive message for '127.0.0.112' (127.0.0.112:42559) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.113]: 2024/01/08 13:33:05 [WARN] memberlist: Refuting an alive message for '127.0.0.113' (127.0.0.113:42559) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.110]: 2024/01/08 13:33:05 [INFO] memberlist: Suspect 127.0.0.115 has failed, no acks received + log.go:198: test[127.0.0.110]: 2024/01/08 13:33:06 [INFO] memberlist: Marking 127.0.0.115 as failed, suspect timeout reached (1 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3#01 - log.go:198: test[127.0.0.116]: 2025/02/09 19:52:31 [DEBUG] memberlist: Using dynamic bind port 33297 - log.go:198: test[127.0.0.116]: 2025/02/09 19:52:31 [DEBUG] memberlist: Failed ping: 127.0.0.121 (timeout reached) - log.go:198: test[127.0.0.120]: 2025/02/09 19:52:31 [WARN] memberlist: Refuting an alive message for '127.0.0.120' (127.0.0.120:33297) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.118]: 2025/02/09 19:52:31 [WARN] memberlist: Refuting an alive message for '127.0.0.118' (127.0.0.118:33297) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.119]: 2025/02/09 19:52:31 [WARN] memberlist: Refuting an alive message for '127.0.0.119' (127.0.0.119:33297) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.116]: 2025/02/09 19:52:31 [INFO] memberlist: Suspect 127.0.0.121 has failed, no acks received - log.go:198: test[127.0.0.116]: 2025/02/09 19:52:32 [INFO] memberlist: Marking 127.0.0.121 as failed, suspect timeout reached (2 peer confirmations) + log.go:198: test[127.0.0.116]: 2024/01/08 13:33:06 [DEBUG] memberlist: Using dynamic bind port 46557 + log.go:198: test[127.0.0.116]: 2024/01/08 13:33:06 [DEBUG] memberlist: Failed ping: 127.0.0.121 (timeout reached) + log.go:198: test[127.0.0.120]: 2024/01/08 13:33:06 [WARN] memberlist: Refuting an alive message for '127.0.0.120' (127.0.0.120:46557) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.118]: 2024/01/08 13:33:06 [WARN] memberlist: Refuting an alive message for '127.0.0.118' (127.0.0.118:46557) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.117]: 2024/01/08 13:33:06 [WARN] memberlist: Refuting an alive message for '127.0.0.117' (127.0.0.117:46557) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.116]: 2024/01/08 13:33:06 [INFO] memberlist: Suspect 127.0.0.121 has failed, no acks received + log.go:198: test[127.0.0.116]: 2024/01/08 13:33:06 [INFO] memberlist: Marking 127.0.0.121 as failed, suspect timeout reached (2 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3_(timeout_driven_to_nominal_value) - log.go:198: test[127.0.0.122]: 2025/02/09 19:52:32 [DEBUG] memberlist: Using dynamic bind port 36987 - log.go:198: test[127.0.0.122]: 2025/02/09 19:52:32 [DEBUG] memberlist: Failed ping: 127.0.0.127 (timeout reached) - log.go:198: test[127.0.0.125]: 2025/02/09 19:52:32 [WARN] memberlist: Refuting an alive message for '127.0.0.125' (127.0.0.125:36987) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.126]: 2025/02/09 19:52:32 [WARN] memberlist: Refuting an alive message for '127.0.0.126' (127.0.0.126:36987) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.123]: 2025/02/09 19:52:32 [WARN] memberlist: Refuting an alive message for '127.0.0.123' (127.0.0.123:36987) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.122]: 2025/02/09 19:52:32 [INFO] memberlist: Suspect 127.0.0.127 has failed, no acks received - log.go:198: test[127.0.0.122]: 2025/02/09 19:52:32 [INFO] memberlist: Marking 127.0.0.127 as failed, suspect timeout reached (3 peer confirmations) + log.go:198: test[127.0.0.122]: 2024/01/08 13:33:06 [DEBUG] memberlist: Using dynamic bind port 34147 + log.go:198: test[127.0.0.122]: 2024/01/08 13:33:06 [DEBUG] memberlist: Failed ping: 127.0.0.127 (timeout reached) + log.go:198: test[127.0.0.123]: 2024/01/08 13:33:06 [WARN] memberlist: Refuting an alive message for '127.0.0.123' (127.0.0.123:34147) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.126]: 2024/01/08 13:33:06 [WARN] memberlist: Refuting an alive message for '127.0.0.126' (127.0.0.126:34147) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.125]: 2024/01/08 13:33:06 [WARN] memberlist: Refuting an alive message for '127.0.0.125' (127.0.0.125:34147) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.122]: 2024/01/08 13:33:06 [INFO] memberlist: Suspect 127.0.0.127 has failed, no acks received + log.go:198: test[127.0.0.122]: 2024/01/08 13:33:07 [INFO] memberlist: Marking 127.0.0.127 as failed, suspect timeout reached (3 peer confirmations) === RUN TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3#02 - log.go:198: test[127.0.0.128]: 2025/02/09 19:52:32 [DEBUG] memberlist: Using dynamic bind port 36519 - log.go:198: test[127.0.0.128]: 2025/02/09 19:52:32 [DEBUG] memberlist: Failed ping: 127.0.0.133 (timeout reached) - log.go:198: test[127.0.0.129]: 2025/02/09 19:52:32 [WARN] memberlist: Refuting an alive message for '127.0.0.129' (127.0.0.129:36519) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.132]: 2025/02/09 19:52:32 [WARN] memberlist: Refuting an alive message for '127.0.0.132' (127.0.0.132:36519) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.130]: 2025/02/09 19:52:32 [WARN] memberlist: Refuting an alive message for '127.0.0.130' (127.0.0.130:36519) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.128]: 2025/02/09 19:52:32 [INFO] memberlist: Suspect 127.0.0.133 has failed, no acks received - log.go:198: test[127.0.0.128]: 2025/02/09 19:52:33 [INFO] memberlist: Marking 127.0.0.133 as failed, suspect timeout reached (3 peer confirmations) ---- PASS: TestMemberList_ProbeNode_Suspect_Dogpile (7.00s) + log.go:198: test[127.0.0.128]: 2024/01/08 13:33:07 [DEBUG] memberlist: Using dynamic bind port 45361 + log.go:198: test[127.0.0.128]: 2024/01/08 13:33:07 [DEBUG] memberlist: Failed ping: 127.0.0.133 (timeout reached) + log.go:198: test[127.0.0.130]: 2024/01/08 13:33:07 [WARN] memberlist: Refuting an alive message for '127.0.0.130' (127.0.0.130:45361) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.131]: 2024/01/08 13:33:07 [WARN] memberlist: Refuting an alive message for '127.0.0.131' (127.0.0.131:45361) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.132]: 2024/01/08 13:33:07 [WARN] memberlist: Refuting an alive message for '127.0.0.132' (127.0.0.132:45361) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.128]: 2024/01/08 13:33:07 [INFO] memberlist: Suspect 127.0.0.133 has failed, no acks received + log.go:198: test[127.0.0.128]: 2024/01/08 13:33:08 [INFO] memberlist: Marking 127.0.0.133 as failed, suspect timeout reached (3 peer confirmations) +--- PASS: TestMemberList_ProbeNode_Suspect_Dogpile (7.05s) --- 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=5,_k=3_(max_timeout_starts_to_take_effect) (1.14s) + --- PASS: TestMemberList_ProbeNode_Suspect_Dogpile/n=6,_k=3 (1.14s) --- 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#01 (0.74s) --- 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 - log.go:198: test[127.0.0.134]: 2025/02/09 19:52:33 [DEBUG] memberlist: Using dynamic bind port 33003 - log.go:198: test[127.0.0.134]: 2025/02/09 19:52:33 [DEBUG] memberlist: Failed ping: 127.0.0.137 (timeout reached) - log.go:198: test[127.0.0.135]: 2025/02/09 19:52:33 [WARN] memberlist: Refuting an alive message for '127.0.0.135' (127.0.0.135:33003) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.136]: 2025/02/09 19:52:33 [WARN] memberlist: Refuting an alive message for '127.0.0.136' (127.0.0.136:33003) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.134]: 2025/02/09 19:52:33 [INFO] memberlist: Suspect 127.0.0.137 has failed, no acks received + log.go:198: test[127.0.0.134]: 2024/01/08 13:33:08 [DEBUG] memberlist: Using dynamic bind port 38417 + log.go:198: test[127.0.0.134]: 2024/01/08 13:33:08 [DEBUG] memberlist: Failed ping: 127.0.0.137 (timeout reached) + log.go:198: test[127.0.0.136]: 2024/01/08 13:33:08 [WARN] memberlist: Refuting an alive message for '127.0.0.136' (127.0.0.136:38417) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.135]: 2024/01/08 13:33:08 [WARN] memberlist: Refuting an alive message for '127.0.0.135' (127.0.0.135:38417) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.134]: 2024/01/08 13:33:08 [INFO] memberlist: Suspect 127.0.0.137 has failed, no acks received --- PASS: TestMemberList_ProbeNode_Awareness_Degraded (0.40s) === RUN TestMemberList_ProbeNode_Wrong_VSN - log.go:198: test[127.0.0.138]: 2025/02/09 19:52:33 [DEBUG] memberlist: Using dynamic bind port 37763 - log.go:198: test[127.0.0.138]: 2025/02/09 19:52:33 [WARN] memberlist: Ignoring an alive message for '127.0.0.141' (127.0.0.141:37763) because protocol version(s) are wrong: 0 <= 0 <= 0 should be >0 ---- PASS: TestMemberList_ProbeNode_Wrong_VSN (0.00s) + log.go:198: test[127.0.0.138]: 2024/01/08 13:33:08 [DEBUG] memberlist: Using dynamic bind port 35785 + log.go:198: test[127.0.0.138]: 2024/01/08 13:33:08 [WARN] memberlist: Ignoring an alive message for '127.0.0.141' (127.0.0.141:35785) because protocol version(s) are wrong: 0 <= 0 <= 0 should be >0 +--- PASS: TestMemberList_ProbeNode_Wrong_VSN (0.02s) === RUN TestMemberList_ProbeNode_Awareness_Improved - log.go:198: test[127.0.0.142]: 2025/02/09 19:52:33 [DEBUG] memberlist: Using dynamic bind port 34787 - log.go:198: test[127.0.0.143]: 2025/02/09 19:52:33 [WARN] memberlist: Refuting an alive message for '127.0.0.143' (127.0.0.143:34787) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.142]: 2024/01/08 13:33:08 [DEBUG] memberlist: Using dynamic bind port 41027 + log.go:198: test[127.0.0.143]: 2024/01/08 13:33:08 [WARN] memberlist: Refuting an alive message for '127.0.0.143' (127.0.0.143:41027) 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 - log.go:198: test[127.0.0.144]: 2025/02/09 19:52:33 [DEBUG] memberlist: Using dynamic bind port 42159 - log.go:198: test[127.0.0.144]: 2025/02/09 19:52:33 [DEBUG] memberlist: Failed ping: 127.0.0.147 (timeout reached) - log.go:198: test[127.0.0.145]: 2025/02/09 19:52:33 [WARN] memberlist: Refuting an alive message for '127.0.0.145' (127.0.0.145:42159) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) - log.go:198: test[127.0.0.144]: 2025/02/09 19:52:34 [INFO] memberlist: Suspect 127.0.0.147 has failed, no acks received + log.go:198: test[127.0.0.144]: 2024/01/08 13:33:08 [DEBUG] memberlist: Using dynamic bind port 45705 + log.go:198: test[127.0.0.144]: 2024/01/08 13:33:08 [DEBUG] memberlist: Failed ping: 127.0.0.147 (timeout reached) + log.go:198: test[127.0.0.145]: 2024/01/08 13:33:08 [WARN] memberlist: Refuting an alive message for '127.0.0.145' (127.0.0.145:45705) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.144]: 2024/01/08 13:33:08 [INFO] memberlist: Suspect 127.0.0.147 has failed, no acks received --- PASS: TestMemberList_ProbeNode_Awareness_MissedNack (0.20s) === RUN TestMemberList_ProbeNode_Awareness_OldProtocol - log.go:198: test[127.0.0.148]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 43031 - log.go:198: test[127.0.0.148]: 2025/02/09 19:52:34 [DEBUG] memberlist: Failed ping: 127.0.0.151 (timeout reached) - log.go:198: test[127.0.0.150]: 2025/02/09 19:52:34 [WARN] memberlist: Refuting an alive message for '127.0.0.150' (127.0.0.150:43031) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) - log.go:198: test[127.0.0.149]: 2025/02/09 19:52:34 [WARN] memberlist: Refuting an alive message for '127.0.0.149' (127.0.0.149:43031) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) - log.go:198: test[127.0.0.148]: 2025/02/09 19:52:34 [INFO] memberlist: Suspect 127.0.0.151 has failed, no acks received + log.go:198: test[127.0.0.148]: 2024/01/08 13:33:08 [DEBUG] memberlist: Using dynamic bind port 34927 + log.go:198: test[127.0.0.148]: 2024/01/08 13:33:08 [DEBUG] memberlist: Failed ping: 127.0.0.151 (timeout reached) + log.go:198: test[127.0.0.149]: 2024/01/08 13:33:08 [WARN] memberlist: Refuting an alive message for '127.0.0.149' (127.0.0.149:34927) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) + log.go:198: test[127.0.0.150]: 2024/01/08 13:33:08 [WARN] memberlist: Refuting an alive message for '127.0.0.150' (127.0.0.150:34927) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) + log.go:198: test[127.0.0.148]: 2024/01/08 13:33:08 [INFO] memberlist: Suspect 127.0.0.151 has failed, no acks received --- PASS: TestMemberList_ProbeNode_Awareness_OldProtocol (0.42s) === RUN TestMemberList_ProbeNode_Buddy - log.go:198: test[127.0.0.152]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 37027 - log.go:198: test[127.0.0.153]: 2025/02/09 19:52:34 [WARN] memberlist: Refuting a suspect message (from: 127.0.0.152) ---- PASS: TestMemberList_ProbeNode_Buddy (0.00s) + log.go:198: test[127.0.0.152]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 39497 +--- PASS: TestMemberList_ProbeNode_Buddy (0.01s) === RUN TestMemberList_ProbeNode - log.go:198: test[127.0.0.154]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 33313 - log.go:198: test[127.0.0.155]: 2025/02/09 19:52:34 [WARN] memberlist: Refuting an alive message for '127.0.0.155' (127.0.0.155:33313) meta:([] VS []), vsn:([] VS [0 0 0 0 0 0]) ---- PASS: TestMemberList_ProbeNode (0.00s) + log.go:198: test[127.0.0.154]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 44023 + log.go:198: test[127.0.0.154]: 2024/01/08 13:33:09 [DEBUG] memberlist: Failed ping: 127.0.0.155 (timeout reached) + log.go:198: test[127.0.0.154]: 2024/01/08 13:33:09 [INFO] memberlist: Suspect 127.0.0.155 has failed, no acks received + state_test.go:960: Expect node to be alive +--- FAIL: TestMemberList_ProbeNode (0.01s) === RUN TestMemberList_Ping state_test.go:970: Skip randomly failing test --- SKIP: TestMemberList_Ping (0.00s) === RUN TestMemberList_ResetNodes - log.go:198: test[127.0.0.156]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 34693 + log.go:198: test[127.0.0.156]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 42285 --- PASS: TestMemberList_ResetNodes (0.20s) === RUN TestMemberList_NextSeq --- PASS: TestMemberList_NextSeq (0.00s) @@ -1482,78 +1508,78 @@ === RUN TestMemberList_invokeAckHandler_Channel_Nack --- PASS: TestMemberList_invokeAckHandler_Channel_Nack (0.00s) === RUN TestMemberList_AliveNode_NewNode - log.go:198: test[127.0.0.157]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 39687 + log.go:198: test[127.0.0.157]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 37819 --- PASS: TestMemberList_AliveNode_NewNode (0.00s) === RUN TestMemberList_AliveNode_SuspectNode - log.go:198: test[127.0.0.158]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 35041 + log.go:198: test[127.0.0.158]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 37991 --- PASS: TestMemberList_AliveNode_SuspectNode (0.00s) === RUN TestMemberList_AliveNode_Idempotent - log.go:198: test[127.0.0.159]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 41963 + log.go:198: test[127.0.0.159]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 45861 --- PASS: TestMemberList_AliveNode_Idempotent (0.00s) === RUN TestMemberList_AliveNode_ChangeMeta - log.go:198: test[127.0.0.160]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 37173 + log.go:198: test[127.0.0.160]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 33637 --- PASS: TestMemberList_AliveNode_ChangeMeta (0.00s) === RUN TestMemberList_AliveNode_Refute - log.go:198: test[127.0.0.161]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 46353 - log.go:198: test[127.0.0.161]: 2025/02/09 19:52:34 [WARN] memberlist: Refuting an alive message for '127.0.0.161' (127.0.0.1:0) meta:([102 111 111] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) + log.go:198: test[127.0.0.161]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 39625 + log.go:198: test[127.0.0.161]: 2024/01/08 13:33:09 [WARN] memberlist: Refuting an alive message for '127.0.0.161' (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 - log.go:198: test[127.0.0.162]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 32923 - log.go:198: test[127.0.0.162]: 2025/02/09 19:52:34 [ERR] memberlist: Conflicting address for test. Mine: 127.0.0.1:8000 Theirs: 127.0.0.2:9000 Old state: 0 - log.go:198: test[127.0.0.162]: 2025/02/09 19:52:34 [INFO] memberlist: Updating address for left or failed node test from 127.0.0.1:8000 to 127.0.0.2:9000 + log.go:198: test[127.0.0.162]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 36637 + log.go:198: test[127.0.0.162]: 2024/01/08 13:33:09 [ERR] memberlist: Conflicting address for test. Mine: 127.0.0.1:8000 Theirs: 127.0.0.2:9000 Old state: 0 + log.go:198: test[127.0.0.162]: 2024/01/08 13:33:09 [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 - log.go:198: test[127.0.0.163]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 34043 + log.go:198: test[127.0.0.163]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 46685 --- PASS: TestMemberList_SuspectNode_NoNode (0.00s) === RUN TestMemberList_SuspectNode - log.go:198: test[127.0.0.164]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 40687 - log.go:198: test[127.0.0.164]: 2025/02/09 19:52:34 [INFO] memberlist: Marking test as failed, suspect timeout reached (0 peer confirmations) + log.go:198: test[127.0.0.164]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 38049 + log.go:198: test[127.0.0.164]: 2024/01/08 13:33:09 [INFO] memberlist: Marking test as failed, suspect timeout reached (0 peer confirmations) --- PASS: TestMemberList_SuspectNode (0.01s) === RUN TestMemberList_SuspectNode_DoubleSuspect - log.go:198: test[127.0.0.165]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 40645 + log.go:198: test[127.0.0.165]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 45831 --- PASS: TestMemberList_SuspectNode_DoubleSuspect (0.00s) === RUN TestMemberList_SuspectNode_OldSuspect - log.go:198: test[127.0.0.166]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 44305 + log.go:198: test[127.0.0.166]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 40199 --- PASS: TestMemberList_SuspectNode_OldSuspect (0.00s) === RUN TestMemberList_SuspectNode_Refute - log.go:198: test[127.0.0.167]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 44237 - log.go:198: test[127.0.0.167]: 2025/02/09 19:52:34 [WARN] memberlist: Refuting a suspect message (from: ) + log.go:198: test[127.0.0.167]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 34467 + log.go:198: test[127.0.0.167]: 2024/01/08 13:33:09 [WARN] memberlist: Refuting a suspect message (from: ) --- PASS: TestMemberList_SuspectNode_Refute (0.00s) === RUN TestMemberList_DeadNode_NoNode - log.go:198: test[127.0.0.168]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 36161 + log.go:198: test[127.0.0.168]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 32889 --- PASS: TestMemberList_DeadNode_NoNode (0.00s) === RUN TestMemberList_DeadNodeLeft - log.go:198: test[127.0.0.169]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 41379 - log.go:198: test[127.0.0.169]: 2025/02/09 19:52:34 [INFO] memberlist: Updating address for left or failed node node1 from 127.0.0.1:8000 to 127.0.0.2:9000 + log.go:198: test[127.0.0.169]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 35859 + log.go:198: test[127.0.0.169]: 2024/01/08 13:33:09 [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 - log.go:198: test[127.0.0.170]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 44443 + log.go:198: test[127.0.0.170]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 35575 --- PASS: TestMemberList_DeadNode (0.00s) === RUN TestMemberList_DeadNode_Double - log.go:198: test[127.0.0.171]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 40597 + log.go:198: test[127.0.0.171]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 38389 --- PASS: TestMemberList_DeadNode_Double (0.00s) === RUN TestMemberList_DeadNode_OldDead - log.go:198: test[127.0.0.172]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 33571 + log.go:198: test[127.0.0.172]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 44687 --- PASS: TestMemberList_DeadNode_OldDead (0.00s) === RUN TestMemberList_DeadNode_AliveReplay - log.go:198: test[127.0.0.173]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 40937 + log.go:198: test[127.0.0.173]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 37749 --- PASS: TestMemberList_DeadNode_AliveReplay (0.00s) === RUN TestMemberList_DeadNode_Refute - log.go:198: test[127.0.0.174]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 39949 - log.go:198: test[127.0.0.174]: 2025/02/09 19:52:34 [WARN] memberlist: Refuting a dead message (from: ) + log.go:198: test[127.0.0.174]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 41329 + log.go:198: test[127.0.0.174]: 2024/01/08 13:33:09 [WARN] memberlist: Refuting a dead message (from: ) --- PASS: TestMemberList_DeadNode_Refute (0.00s) === RUN TestMemberList_MergeState - log.go:198: test[127.0.0.175]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 39405 + log.go:198: test[127.0.0.175]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 45443 --- PASS: TestMemberList_MergeState (0.00s) === RUN TestMemberlist_Gossip - log.go:198: test[127.0.0.176]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 46553 - log.go:198: test[127.0.0.177]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 45573 - log.go:198: test[127.0.0.177]: 2025/02/09 19:52:34 [WARN] memberlist: Refuting an alive message for '127.0.0.177' (127.0.0.177:46553) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) ---- PASS: TestMemberlist_Gossip (0.00s) + log.go:198: test[127.0.0.176]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 39641 + log.go:198: test[127.0.0.177]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 40725 + log.go:198: test[127.0.0.177]: 2024/01/08 13:33:09 [WARN] memberlist: Refuting an alive message for '127.0.0.177' (127.0.0.177:39641) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +--- PASS: TestMemberlist_Gossip (0.01s) === RUN TestMemberlist_GossipToDead - log.go:198: test[127.0.0.179]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 34529 - log.go:198: test[127.0.0.180]: 2025/02/09 19:52:34 [WARN] memberlist: Refuting an alive message for '127.0.0.180' (127.0.0.180:34529) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) ---- PASS: TestMemberlist_GossipToDead (0.05s) + log.go:198: test[127.0.0.179]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 40207 + log.go:198: test[127.0.0.180]: 2024/01/08 13:33:09 [WARN] memberlist: Refuting an alive message for '127.0.0.180' (127.0.0.180:40207) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +--- PASS: TestMemberlist_GossipToDead (0.06s) === RUN TestMemberlist_FailedRemote === RUN TestMemberlist_FailedRemote/nil_error === RUN TestMemberlist_FailedRemote/normal_error @@ -1581,75 +1607,75 @@ --- PASS: TestMemberlist_FailedRemote/net.OpError_for_tcp_with_write (0.00s) --- PASS: TestMemberlist_FailedRemote/net.OpError_for_tcp_with_read (0.00s) === RUN TestMemberlist_PushPull - log.go:198: test[127.0.0.181]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 36661 - log.go:198: test[127.0.0.181]: 2025/02/09 19:52:34 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.182 127.0.0.182:36661 - log.go:198: test[127.0.0.182]: 2025/02/09 19:52:34 [DEBUG] memberlist: Stream connection from=127.0.0.1:36650 - log.go:198: test[127.0.0.182]: 2025/02/09 19:52:34 [WARN] memberlist: Refuting an alive message for '127.0.0.182' (127.0.0.182:36661) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) ---- PASS: TestMemberlist_PushPull (0.00s) + log.go:198: test[127.0.0.181]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 36123 + log.go:198: test[127.0.0.182]: 2024/01/08 13:33:09 [DEBUG] memberlist: Stream connection from=127.0.0.1:48902 + log.go:198: test[127.0.0.181]: 2024/01/08 13:33:09 [DEBUG] memberlist: Initiating push/pull sync with: 127.0.0.182 127.0.0.182:36123 + log.go:198: test[127.0.0.182]: 2024/01/08 13:33:09 [WARN] memberlist: Refuting an alive message for '127.0.0.182' (127.0.0.182:36123) meta:([] VS []), vsn:([1 5 2 0 0 0] VS [1 5 2 0 0 0]) +--- PASS: TestMemberlist_PushPull (0.01s) === RUN TestVerifyProtocol - log.go:198: test[127.0.0.183]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 34951 - log.go:198: test[127.0.0.184]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 41119 - log.go:198: test[127.0.0.185]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 40139 - log.go:198: test[127.0.0.186]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 38929 - log.go:198: test[127.0.0.187]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 41921 - log.go:198: test[127.0.0.188]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 46461 - log.go:198: test[127.0.0.189]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 46383 - log.go:198: test[127.0.0.190]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 46083 - log.go:198: test[127.0.0.191]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 36275 - log.go:198: test[127.0.0.192]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 42173 - log.go:198: test[127.0.0.193]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 45775 - log.go:198: test[127.0.0.194]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 40135 - log.go:198: test[127.0.0.195]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 35759 - log.go:198: test[127.0.0.196]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 37107 - log.go:198: test[127.0.0.197]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 39029 - log.go:198: test[127.0.0.198]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 42115 - log.go:198: test[127.0.0.199]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 43565 - log.go:198: test[127.0.0.200]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 45383 - log.go:198: test[127.0.0.201]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 33727 - log.go:198: test[127.0.0.202]: 2025/02/09 19:52:34 [DEBUG] memberlist: Using dynamic bind port 43943 + log.go:198: test[127.0.0.183]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 40267 + log.go:198: test[127.0.0.184]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 39841 + log.go:198: test[127.0.0.185]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 37657 + log.go:198: test[127.0.0.186]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 35791 + log.go:198: test[127.0.0.187]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 36717 + log.go:198: test[127.0.0.188]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 34291 + log.go:198: test[127.0.0.189]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 44427 + log.go:198: test[127.0.0.190]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 40957 + log.go:198: test[127.0.0.191]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 44823 + log.go:198: test[127.0.0.192]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 41295 + log.go:198: test[127.0.0.193]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 46761 + log.go:198: test[127.0.0.194]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 34981 + log.go:198: test[127.0.0.195]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 42641 + log.go:198: test[127.0.0.196]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 38907 + log.go:198: test[127.0.0.197]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 45923 + log.go:198: test[127.0.0.198]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 41941 + log.go:198: test[127.0.0.199]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 46817 + log.go:198: test[127.0.0.200]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 40221 + log.go:198: test[127.0.0.201]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 34209 + log.go:198: test[127.0.0.202]: 2024/01/08 13:33:09 [DEBUG] memberlist: Using dynamic bind port 38579 --- PASS: TestVerifyProtocol (0.00s) === RUN TestSuspicion_remainingSuspicionTime --- PASS: TestSuspicion_remainingSuspicionTime (0.00s) === RUN TestSuspicion_Timer ---- PASS: TestSuspicion_Timer (13.10s) +--- PASS: TestSuspicion_Timer (13.17s) === RUN TestSuspicion_Timer_ZeroK --- PASS: TestSuspicion_Timer_ZeroK (0.03s) === RUN TestSuspicion_Timer_Immediate --- PASS: TestSuspicion_Timer_Immediate (0.20s) === RUN TestTransport_Join - log.go:198: test: 2025/02/09 19:52:48 [DEBUG] memberlist: Initiating push/pull sync with: node1 pipe - log.go:198: test: 2025/02/09 19:52:48 [DEBUG] memberlist: Stream connection from=pipe + log.go:198: test: 2024/01/08 13:33:22 [DEBUG] memberlist: Stream connection from=pipe + log.go:198: test: 2024/01/08 13:33:22 [DEBUG] memberlist: Initiating push/pull sync with: node1 pipe --- PASS: TestTransport_Join (0.00s) === RUN TestTransport_Send - log.go:198: test: 2025/02/09 19:52:48 [DEBUG] memberlist: Initiating push/pull sync with: node1 pipe - log.go:198: test: 2025/02/09 19:52:48 [DEBUG] memberlist: Stream connection from=pipe - log.go:198: test: 2025/02/09 19:52:48 [DEBUG] memberlist: Stream connection from=pipe - log.go:198: test: 2025/02/09 19:52:48 [DEBUG] memberlist: Stream connection from=pipe ---- PASS: TestTransport_Send (0.10s) + log.go:198: test: 2024/01/08 13:33:22 [DEBUG] memberlist: Stream connection from=pipe + log.go:198: test: 2024/01/08 13:33:22 [DEBUG] memberlist: Initiating push/pull sync with: node1 pipe + log.go:198: test: 2024/01/08 13:33:22 [DEBUG] memberlist: Stream connection from=pipe + log.go:198: test: 2024/01/08 13:33:22 [DEBUG] memberlist: Stream connection from=pipe +--- PASS: TestTransport_Send (0.12s) === RUN TestTransport_TcpListenBackoff - transport_test.go:151: countingWriter: test2025/02/09 19:52:48 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:23 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection - transport_test.go:151: countingWriter: test2025/02/09 19:52:48 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:23 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection - transport_test.go:151: countingWriter: test2025/02/09 19:52:48 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:23 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection - transport_test.go:151: countingWriter: test2025/02/09 19:52:48 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:23 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection - transport_test.go:151: countingWriter: test2025/02/09 19:52:48 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:23 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection - transport_test.go:151: countingWriter: test2025/02/09 19:52:48 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:23 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection - transport_test.go:151: countingWriter: test2025/02/09 19:52:48 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:23 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection - transport_test.go:151: countingWriter: test2025/02/09 19:52:48 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:23 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection - transport_test.go:151: countingWriter: test2025/02/09 19:52:49 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:24 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection - transport_test.go:151: countingWriter: test2025/02/09 19:52:50 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:25 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection - transport_test.go:151: countingWriter: test2025/02/09 19:52:51 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:38631: use of closed network connection + transport_test.go:151: countingWriter: test2024/01/08 13:33:26 [ERR] memberlist: Error accepting TCP connection: accept tcp [::]:41455: use of closed network connection ---- PASS: TestTransport_TcpListenBackoff (4.29s) +--- PASS: TestTransport_TcpListenBackoff (4.31s) === RUN TestUtil_PortFunctions === RUN TestUtil_PortFunctions/1.2.3.4 === RUN TestUtil_PortFunctions/1.2.3.4:1234 @@ -1707,49 +1733,32 @@ --- PASS: Test_IsValidAddressOverride/Only_IPv4 (0.00s) --- 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 34.925s +FAIL +FAIL github.com/hashicorp/memberlist 35.596s === RUN TestRetryer === RUN TestRetryer/counter === RUN TestRetryer/timer ---- PASS: TestRetryer (0.40s) +--- PASS: TestRetryer (0.41s) --- PASS: TestRetryer/counter (0.20s) - --- PASS: TestRetryer/timer (0.20s) + --- PASS: TestRetryer/timer (0.21s) PASS -ok github.com/hashicorp/memberlist/internal/retry 0.406s - create-stamp debian/debhelper-build-stamp - dh_testroot -O--buildsystem=golang - dh_prep -O--buildsystem=golang - dh_auto_install --destdir=debian/golang-github-hashicorp-memberlist-dev/ -O--buildsystem=golang - dh_installdocs -O--buildsystem=golang - dh_installchangelogs -O--buildsystem=golang - dh_installinit -O--buildsystem=golang - dh_installsystemduser -O--buildsystem=golang - dh_perl -O--buildsystem=golang - dh_link -O--buildsystem=golang - dh_strip_nondeterminism -O--buildsystem=golang - dh_compress -O--buildsystem=golang - dh_fixperms -O--buildsystem=golang - dh_missing -O--buildsystem=golang - dh_installdeb -O--buildsystem=golang - dh_golang -O--buildsystem=golang - dh_gencontrol -O--buildsystem=golang - dh_md5sums -O--buildsystem=golang - dh_builddeb -O--buildsystem=golang -dpkg-deb: building package 'golang-github-hashicorp-memberlist-dev' in '../golang-github-hashicorp-memberlist-dev_0.2.2-2_all.deb'. - dpkg-genbuildinfo --build=binary -O../golang-github-hashicorp-memberlist_0.2.2-2_amd64.buildinfo - dpkg-genchanges --build=binary -O../golang-github-hashicorp-memberlist_0.2.2-2_amd64.changes -dpkg-genchanges: info: binary-only upload (no source code included) - dpkg-source --after-build . -dpkg-buildpackage: info: binary-only upload (no source included) -dpkg-genchanges: info: not including original source code in upload +ok github.com/hashicorp/memberlist/internal/retry 0.453s +FAIL +dh_auto_test: error: cd obj-x86_64-linux-gnu && go test -vet=off -v -p 15 github.com/hashicorp/memberlist github.com/hashicorp/memberlist/internal/retry returned exit code 1 +make: *** [debian/rules:4: binary] Error 25 +dpkg-buildpackage: error: debian/rules binary subprocess returned exit status 2 I: copying local configuration +E: Failed autobuilding of package +I: user script /srv/workspace/pbuilder/924152/tmp/hooks/C01_cleanup starting +debug output: disk usage on i-capture-the-hostname at Mon Jan 8 13:33:27 UTC 2024 +Filesystem Size Used Avail Use% Mounted on +tmpfs 32G 0 32G 0% /dev/shm + +I: user script /srv/workspace/pbuilder/924152/tmp/hooks/C01_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/3138085 and its subdirectories -I: Current time: Sun Feb 9 07:52:57 -12 2025 -I: pbuilder-time-stamp: 1739130777 +I: removing directory /srv/workspace/pbuilder/924152 and its subdirectories