Diff of the two buildlogs: -- --- b1/build.log 2024-04-12 15:01:37.584963981 +0000 +++ b2/build.log 2024-04-12 15:03:38.543806992 +0000 @@ -1,6 +1,6 @@ I: pbuilder: network access will be disabled during build -I: Current time: Thu May 15 09:23:07 -12 2025 -I: pbuilder-time-stamp: 1747344187 +I: Current time: Sat Apr 13 05:01:40 +14 2024 +I: pbuilder-time-stamp: 1712934100 I: Building the build Environment I: extracting base tarball [/var/cache/pbuilder/trixie-reproducible-base.tgz] I: copying local configuration @@ -28,54 +28,86 @@ dpkg-source: info: applying 0001-Disable-failing-tests-with-JDK-17.patch I: Not using root during the build. I: Installing the build-deps -I: user script /srv/workspace/pbuilder/52514/tmp/hooks/D02_print_environment starting +I: user script /srv/workspace/pbuilder/6794/tmp/hooks/D01_modify_environment starting +debug: Running on ionos2-i386. +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 Apr 12 15:01 /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/6794/tmp/hooks/D01_modify_environment finished +I: user script /srv/workspace/pbuilder/6794/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='i386' - DEBIAN_FRONTEND='noninteractive' - DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=22 ' - DISTRIBUTION='trixie' - HOME='/root' - HOST_ARCH='i386' + BASH=/bin/sh + BASHOPTS=checkwinsize:cmdhist:complete_fullquote:extquote:force_fignore:globasciiranges:globskipdots:hostcomplete:interactive_comments:patsub_replacement:progcomp:promptvars:sourcepath + BASH_ALIASES=() + BASH_ARGC=() + BASH_ARGV=() + BASH_CMDS=() + BASH_LINENO=([0]="12" [1]="0") + BASH_LOADABLES_PATH=/usr/local/lib/bash:/usr/lib/bash:/opt/local/lib/bash:/usr/pkg/lib/bash:/opt/pkg/lib/bash:. + BASH_SOURCE=([0]="/tmp/hooks/D02_print_environment" [1]="/tmp/hooks/D02_print_environment") + BASH_VERSINFO=([0]="5" [1]="2" [2]="21" [3]="1" [4]="release" [5]="i686-pc-linux-gnu") + BASH_VERSION='5.2.21(1)-release' + BUILDDIR=/build/reproducible-path + BUILDUSERGECOS='second user,second room,second work-phone,second home-phone,second other' + BUILDUSERNAME=pbuilder2 + BUILD_ARCH=i386 + DEBIAN_FRONTEND=noninteractive + DEB_BUILD_OPTIONS='buildinfo=+all reproducible=+all parallel=7 ' + DIRSTACK=() + DISTRIBUTION=trixie + EUID=0 + FUNCNAME=([0]="Echo" [1]="main") + GROUPS=() + HOME=/root + HOSTNAME=i-capture-the-hostname + HOSTTYPE=i686 + HOST_ARCH=i386 IFS=' ' - INVOCATION_ID='7a0edff43ec14da7820ca31e2a61feda' - LANG='C' - LANGUAGE='en_US:en' - LC_ALL='C' - LD_LIBRARY_PATH='/usr/lib/libeatmydata' - LD_PRELOAD='libeatmydata.so' - 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='52514' - PS1='# ' - PS2='> ' + INVOCATION_ID=6d5d36c0ccfb4a0abc10c0511c5775b2 + LANG=C + LANGUAGE=de_CH:de + LC_ALL=C + LD_LIBRARY_PATH=/usr/lib/libeatmydata + LD_PRELOAD=libeatmydata.so + MACHTYPE=i686-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=6794 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.2SHWQbGp/pbuilderrc_LKGM --distribution trixie --hookdir /etc/pbuilder/first-build-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.2SHWQbGp/b1 --logfile b1/build.log spymemcached_2.12.3+dfsg-3.dsc' - SUDO_GID='112' - SUDO_UID='107' - 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.2SHWQbGp/pbuilderrc_qpgx --distribution trixie --hookdir /etc/pbuilder/rebuild-hooks --debbuildopts -b --basetgz /var/cache/pbuilder/trixie-reproducible-base.tgz --buildresult /srv/reproducible-results/rbuild-debian/r-b-build.2SHWQbGp/b2 --logfile b2/build.log spymemcached_2.12.3+dfsg-3.dsc' + SUDO_GID=112 + SUDO_UID=107 + 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 ionos6-i386 6.1.0-18-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.76-1 (2024-02-01) x86_64 GNU/Linux + Linux i-capture-the-hostname 6.1.0-18-amd64 #1 SMP PREEMPT_DYNAMIC Debian 6.1.76-1 (2024-02-01) x86_64 GNU/Linux I: ls -l /bin - lrwxrwxrwx 1 root root 7 May 14 17:47 /bin -> usr/bin -I: user script /srv/workspace/pbuilder/52514/tmp/hooks/D02_print_environment finished + lrwxrwxrwx 1 root root 7 Apr 10 11:24 /bin -> usr/bin +I: user script /srv/workspace/pbuilder/6794/tmp/hooks/D02_print_environment finished -> Attempting to satisfy build-dependencies -> Creating pbuilder-satisfydepends-dummy package Package: pbuilder-satisfydepends-dummy @@ -407,7 +439,7 @@ Get: 274 http://deb.debian.org/debian trixie/main i386 maven-repo-helper all 1.11 [142 kB] Get: 275 http://deb.debian.org/debian trixie/main i386 maven-debian-helper all 2.6.4 [108 kB] Get: 276 http://deb.debian.org/debian trixie/main i386 net-tools i386 2.10-0.1 [245 kB] -Fetched 268 MB in 4s (69.0 MB/s) +Fetched 268 MB in 4s (65.4 MB/s) debconf: delaying package configuration, since apt-utils is not installed Selecting previously unselected package libevent-2.1-7:i386. (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 ... 19874 files and directories currently installed.) @@ -1722,7 +1754,11 @@ Building tag database... -> Finished parsing the build-deps I: Building the package -I: Running cd /build/reproducible-path/spymemcached-2.12.3+dfsg/ && 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 > ../spymemcached_2.12.3+dfsg-3_source.changes +I: user script /srv/workspace/pbuilder/6794/tmp/hooks/A99_set_merged_usr starting +Not re-configuring usrmerge for trixie +I: user script /srv/workspace/pbuilder/6794/tmp/hooks/A99_set_merged_usr finished +hostname: Name or service not known +I: Running cd /build/reproducible-path/spymemcached-2.12.3+dfsg/ && 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 > ../spymemcached_2.12.3+dfsg-3_source.changes dpkg-buildpackage: info: source package spymemcached dpkg-buildpackage: info: source version 2.12.3+dfsg-3 dpkg-buildpackage: info: source distribution unstable @@ -1793,13 +1829,13 @@ [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ -[INFO] Total time: 4.658 s -[INFO] Finished at: 2025-05-15T21:24:03Z +[INFO] Total time: 7.855 s +[INFO] Finished at: 2024-04-12T15:03:04Z [INFO] ------------------------------------------------------------------------ debian/rules override_dh_auto_test make[1]: Entering directory '/build/reproducible-path/spymemcached-2.12.3+dfsg' mkdir -p /build/reproducible-path/spymemcached-2.12.3+dfsg/debian/build -memcached -u pbuilder1 -p 20000 -P /build/reproducible-path/spymemcached-2.12.3+dfsg/debian/build/memcached.pid -d +memcached -u pbuilder2 -p 20000 -P /build/reproducible-path/spymemcached-2.12.3+dfsg/debian/build/memcached.pid -d export SPY_MC_TEST_SERVER=localhost:20000 SPYMC_TEST_SERVER_V4=localhost:20000 SPYMC_TEST_SERVER_v6=::1:20000;\ dh_auto_test -- -Dserver.port_number=20000 -Dtest.type=ci test /usr/lib/jvm/default-java/bin/java -noverify -cp /usr/share/maven/boot/plexus-classworlds-2.x.jar -Dmaven.home=/usr/share/maven -Dmaven.multiModuleProjectDirectory=/build/reproducible-path/spymemcached-2.12.3\+dfsg -Dclassworlds.conf=/etc/maven/m2-debian.conf -Dproperties.file.manual=/build/reproducible-path/spymemcached-2.12.3\+dfsg/debian/maven.properties org.codehaus.plexus.classworlds.launcher.Launcher -s/etc/maven/settings-debian.xml -Ddebian.dir=/build/reproducible-path/spymemcached-2.12.3\+dfsg/debian -Dmaven.repo.local=/build/reproducible-path/spymemcached-2.12.3\+dfsg/debian/maven-repo --batch-mode -Dserver.port_number=20000 -Dtest.type=ci test @@ -1830,98 +1866,199 @@ [INFO] ------------------------------------------------------- [INFO] T E S T S [INFO] ------------------------------------------------------- -[INFO] Running net.spy.memcached.transcoders.TranscoderUtilsTest -[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.016 s - in net.spy.memcached.transcoders.TranscoderUtilsTest -[INFO] Running net.spy.memcached.transcoders.CachedDataTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.transcoders.CachedDataTest -[INFO] Running net.spy.memcached.transcoders.WhalinTranscoderTest -2025-05-15 21:24:05.872 INFO net.spy.memcached.transcoders.WhalinTranscoder: Compression increased the size of java.lang.String from 57 to 73 -2025-05-15 21:24:05.898 WARN net.spy.memcached.transcoders.WhalinTranscoder: Caught IOException decoding 4 bytes of data -java.io.StreamCorruptedException: invalid stream header: 7FFFFFFF - at java.base/java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:958) - at java.base/java.io.ObjectInputStream.(ObjectInputStream.java:392) - at net.spy.memcached.transcoders.BaseSerializingTranscoder.deserialize(BaseSerializingTranscoder.java:128) - at net.spy.memcached.transcoders.WhalinTranscoder.decode(WhalinTranscoder.java:70) - at net.spy.memcached.transcoders.WhalinTranscoderTest.testUndecodeableSerialized(WhalinTranscoderTest.java:124) - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) - at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) - at java.base/java.lang.reflect.Method.invoke(Method.java:568) - at junit.framework.TestCase.runTest(TestCase.java:177) - at org.jmock.core.VerifyingTestCase.runBare(VerifyingTestCase.java:39) - at junit.framework.TestResult$1.protect(TestResult.java:122) - at junit.framework.TestResult.runProtected(TestResult.java:142) - at junit.framework.TestResult.run(TestResult.java:125) - at junit.framework.TestCase.run(TestCase.java:130) - at junit.framework.TestSuite.runTest(TestSuite.java:241) - at junit.framework.TestSuite.run(TestSuite.java:236) - at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:90) - at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365) - at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273) - at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238) - at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159) - at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) - at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) - at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) - at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) -2025-05-15 21:24:05.901 WARN net.spy.memcached.transcoders.WhalinTranscoder: Failed to decompress data -java.util.zip.ZipException: Not in GZIP format - at java.base/java.util.zip.GZIPInputStream.readHeader(GZIPInputStream.java:165) - at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:79) - at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:91) - at net.spy.memcached.transcoders.BaseSerializingTranscoder.decompress(BaseSerializingTranscoder.java:181) - at net.spy.memcached.transcoders.WhalinTranscoder.decode(WhalinTranscoder.java:67) - at net.spy.memcached.transcoders.WhalinTranscoderTest.testUndecodeableCompressed(WhalinTranscoderTest.java:130) - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) - at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) - at java.base/java.lang.reflect.Method.invoke(Method.java:568) - at junit.framework.TestCase.runTest(TestCase.java:177) - at org.jmock.core.VerifyingTestCase.runBare(VerifyingTestCase.java:39) - at junit.framework.TestResult$1.protect(TestResult.java:122) - at junit.framework.TestResult.runProtected(TestResult.java:142) - at junit.framework.TestResult.run(TestResult.java:125) - at junit.framework.TestCase.run(TestCase.java:130) - at junit.framework.TestSuite.runTest(TestSuite.java:241) - at junit.framework.TestSuite.run(TestSuite.java:236) - at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:90) - at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365) - at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273) - at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238) - at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159) - at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) - at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) - at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) - at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) -2025-05-15 21:24:05.902 WARN net.spy.memcached.transcoders.WhalinTranscoder: Cannot handle data with flags 0 -2025-05-15 21:24:05.904 WARN net.spy.memcached.transcoders.WhalinTranscoder: Cannot handle data with flags 7ffffff5 -[INFO] Tests run: 28, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.115 s - in net.spy.memcached.transcoders.WhalinTranscoderTest -[INFO] Running net.spy.memcached.transcoders.TranscodeServiceTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.transcoders.TranscodeServiceTest -[INFO] Running net.spy.memcached.transcoders.LongTranscoderTest -2025-05-15 21:24:05.939 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 769 wanted 768 -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.transcoders.LongTranscoderTest -[INFO] Running net.spy.memcached.transcoders.WhalinV1TranscoderTest -2025-05-15 21:24:05.951 INFO net.spy.memcached.transcoders.WhalinV1Transcoder: Compressed java.util.ArrayList from 17491 to 193 -[INFO] Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.016 s - in net.spy.memcached.transcoders.WhalinV1TranscoderTest -[INFO] Running net.spy.memcached.transcoders.BaseSerializingTranscoderTest -2025-05-15 21:24:05.965 WARN net.spy.memcached.transcoders.BaseSerializingTranscoderTest$Exposer: Caught CNFE decoding 41 bytes of data -java.lang.ClassNotFoundException: Test - at java.base/jdk.internal.loader.BuiltinClassLoader.loadClass(BuiltinClassLoader.java:641) - at java.base/jdk.internal.loader.ClassLoaders$AppClassLoader.loadClass(ClassLoaders.java:188) - at java.base/java.lang.ClassLoader.loadClass(ClassLoader.java:525) - at java.base/java.lang.Class.forName0(Native Method) - at java.base/java.lang.Class.forName(Class.java:467) - at java.base/java.io.ObjectInputStream.resolveClass(ObjectInputStream.java:773) - at java.base/java.io.ObjectInputStream.readNonProxyDesc(ObjectInputStream.java:2034) - at java.base/java.io.ObjectInputStream.readClassDesc(ObjectInputStream.java:1898) - at java.base/java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:2224) - at java.base/java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1733) - at java.base/java.io.ObjectInputStream.readObject(ObjectInputStream.java:509) - at java.base/java.io.ObjectInputStream.readObject(ObjectInputStream.java:467) - at net.spy.memcached.transcoders.BaseSerializingTranscoder.deserialize(BaseSerializingTranscoder.java:129) - at net.spy.memcached.transcoders.BaseSerializingTranscoderTest$Exposer.deserialize(BaseSerializingTranscoderTest.java:154) - at net.spy.memcached.transcoders.BaseSerializingTranscoderTest.testUndeserializable(BaseSerializingTranscoderTest.java:98) +[INFO] Running net.spy.memcached.QueueOverflowTest +2024-04-12 15:03:08.104 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.116 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.121 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.122 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.123 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.129 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.131 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.131 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.133 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.133 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.134 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.136 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.157 s - in net.spy.memcached.QueueOverflowTest +[INFO] Running net.spy.memcached.KetamaNodeLocatorTest +[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.21 s - in net.spy.memcached.KetamaNodeLocatorTest +[INFO] Running net.spy.memcached.CancelFailureModeTest +2024-04-12 15:03:08.378 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.378 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.379 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.381 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +java.net.ConnectException: Connection refused + at java.base/sun.nio.ch.Net.pollConnect(Native Method) + at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) + at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) + at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) +2024-04-12 15:03:08.383 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:08.384 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.385 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.386 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.488 INFO [INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.105 s - in net.spy.memcached.CancelFailureModeTest +net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Running net.spy.memcached.CASMutatorTest +2024-04-12 15:03:08.495 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.496 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.498 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.500 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.501 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.504 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.505 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.506 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.574 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.574 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.575 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.580 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.581 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.582 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.586 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.587 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.589 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.592 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.594 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.595 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.596 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.598 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.604 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.605 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.606 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.608 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.609 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.610 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.616 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.620 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.622 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.623 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.623 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.625 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.638 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.641 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.642 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.643 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.644 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.645 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.647 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.649 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.651 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.652 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.654 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.655 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.656 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.657 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.658 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.664 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.665 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.665 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.670 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.671 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.672 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.673 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.673 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.674 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.675 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.675 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.676 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.677 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.678 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.678 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 +2024-04-12 15:03:08.679 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.679 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.680 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.682 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.682 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.682 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.684 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.684 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.684 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.685 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.686 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.686 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.690 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.690 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.690 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +Exception in thread "Memcached IO over {MemcachedConnection to /127.0.0.1:20000} - SHUTTING DOWN (informed client)" java.lang.AssertionError: Not connected, and not watching for connect: 0 + at net.spy.memcached.MemcachedConnection.selectorsMakeSense(MemcachedConnection.java:402) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:433) + at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) +2024-04-12 15:03:08.692 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.693 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:08.693 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:08.694 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.694 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.198 s - in net.spy.memcached.CASMutatorTest +2024-04-12 15:03:08.695 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Running net.spy.memcached.ErrorCodeTest +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.004 s - in net.spy.memcached.ErrorCodeTest +[INFO] Running net.spy.memcached.TimeoutNowriteTest +2024-04-12 15:03:08.709 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:08.709 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +Operation attempted: +net.spy.memcached.TimeoutNowriteTest$3@267862 +Trying to get: +Received a line. +Complete. + +Op timed out is true +2024-04-12 15:03:09.820 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.112 s - in net.spy.memcached.TimeoutNowriteTest +[INFO] Running net.spy.memcached.MemcachedConnectionTest +2024-04-12 15:03:09.824 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:09.824 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:09.825 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +java.net.ConnectException: Connection refused + at java.base/sun.nio.ch.Net.pollConnect(Native Method) + at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) + at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) + at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) +2024-04-12 15:03:09.826 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:09.828 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:09.828 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.007 s - in net.spy.memcached.MemcachedConnectionTest +[INFO] Running net.spy.memcached.ToStringTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.ToStringTest +[INFO] Running net.spy.memcached.ConsistentHashingTest +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.ConsistentHashingTest +[INFO] Running net.spy.memcached.ConnectionFactoryBuilderTest +[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in net.spy.memcached.ConnectionFactoryBuilderTest +[INFO] Running net.spy.memcached.ObserverTest +2024-04-12 15:03:09.859 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:09.860 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:09.861 INFO net.spy.memcached.ObserverTest$LoggingObserver: Connection established to /127.0.0.1:20000 (1) +2024-04-12 15:03:09.862 INFO net.spy.memcached.ObserverTest$LoggingObserver: Connection established to /127.0.0.1:20000 (-1) +2024-04-12 15:03:09.862 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:09.862 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:09.863 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:09.864 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:09.864 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:09.865 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:09.870 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:09.876 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:09.877 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:09.878 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:09.878 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:09.879 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:09.880 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.02 s - in net.spy.memcached.ObserverTest +[INFO] Running net.spy.memcached.compat.log.LoggingTest +2024-04-12 15:03:09.884 WARN net.spy.memcached.compat.log.LoggingTest: warn message +2024-04-12 15:03:09.885 WARN net.spy.memcached.compat.log.LoggingTest: test message +2024-04-12 15:03:09.885 ERROR net.spy.memcached.compat.log.LoggingTest: error message +2024-04-12 15:03:09.885 ERROR net.spy.memcached.compat.log.LoggingTest: test message +2024-04-12 15:03:09.885 FATAL net.spy.memcached.compat.log.LoggingTest: fatal message +2024-04-12 15:03:09.885 FATAL net.spy.memcached.compat.log.LoggingTest: test message +2024-04-12 15:03:09.886 INFO net.spy.memcached.compat.log.LoggingTest: info message +2024-04-12 15:03:09.887 WARN net.spy.memcached.compat.log.LoggingTest: warn message +2024-04-12 15:03:09.887 ERROR net.spy.memcached.compat.log.LoggingTest: error message +2024-04-12 15:03:09.887 FATAL net.spy.memcached.compat.log.LoggingTest: fatal message +2024-04-12 15:03:09.887 FATAL net.spy.memcached.compat.log.LoggingTest: fatal message with exception +java.lang.Exception + at net.spy.memcached.compat.log.LoggingTest.testMyLogger(LoggingTest.java:119) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) @@ -1943,51 +2080,25 @@ at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) -[INFO] Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.018 s - in net.spy.memcached.transcoders.BaseSerializingTranscoderTest -[INFO] Running net.spy.memcached.transcoders.SerializingTranscoderTest -2025-05-15 21:24:05.987 INFO net.spy.memcached.transcoders.SerializingTranscoder: Compression increased the size of java.lang.String from 57 to 73 -2025-05-15 21:24:05.990 WARN net.spy.memcached.transcoders.SerializingTranscoder: Caught IOException decoding 4 bytes of data -java.io.StreamCorruptedException: invalid stream header: 7FFFFFFF - at java.base/java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:958) - at java.base/java.io.ObjectInputStream.(ObjectInputStream.java:392) - at net.spy.memcached.transcoders.BaseSerializingTranscoder.deserialize(BaseSerializingTranscoder.java:128) - at net.spy.memcached.transcoders.SerializingTranscoder.decode(SerializingTranscoder.java:88) - at net.spy.memcached.transcoders.SerializingTranscoderTest.testUndecodeableSerialized(SerializingTranscoderTest.java:125) - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) - at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) - at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) - at java.base/java.lang.reflect.Method.invoke(Method.java:568) - at junit.framework.TestCase.runTest(TestCase.java:177) - at org.jmock.core.VerifyingTestCase.runBare(VerifyingTestCase.java:39) - at junit.framework.TestResult$1.protect(TestResult.java:122) - at junit.framework.TestResult.runProtected(TestResult.java:142) - at junit.framework.TestResult.run(TestResult.java:125) - at junit.framework.TestCase.run(TestCase.java:130) - at junit.framework.TestSuite.runTest(TestSuite.java:241) - at junit.framework.TestSuite.run(TestSuite.java:236) - at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:90) - at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365) - at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273) - at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238) - at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159) - at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) - at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) - at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) - at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) -2025-05-15 21:24:05.992 WARN net.spy.memcached.transcoders.SerializingTranscoder: Failed to decompress data -java.util.zip.ZipException: Not in GZIP format - at java.base/java.util.zip.GZIPInputStream.readHeader(GZIPInputStream.java:165) - at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:79) - at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:91) - at net.spy.memcached.transcoders.BaseSerializingTranscoder.decompress(BaseSerializingTranscoder.java:181) - at net.spy.memcached.transcoders.SerializingTranscoder.decode(SerializingTranscoder.java:84) - at net.spy.memcached.transcoders.SerializingTranscoderTest.testUndecodeableCompressed(SerializingTranscoderTest.java:132) +2024-04-12 15:03:09.889 INFO net.spy.memcached.compat.log.LoggingTest: info message +Apr 12, 2024 3:03:09 PM net.spy.memcached.compat.log.LoggingTest testSunLogger +INFO: info message +Apr 12, 2024 3:03:09 PM net.spy.memcached.compat.log.LoggingTest testSunLogger +WARNING: warn message +Apr 12, 2024 3:03:09 PM net.spy.memcached.compat.log.LoggingTest testSunLogger +SEVERE: error message +Apr 12, 2024 3:03:09 PM net.spy.memcached.compat.log.LoggingTest testSunLogger +SEVERE: fatal message +Apr 12, 2024 3:03:09 PM net.spy.memcached.compat.log.LoggingTest testSunLogger +SEVERE: fatal message with exception +java.lang.Exception + at net.spy.memcached.compat.log.LoggingTest.testSunLogger(LoggingTest.java:101) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:568) at junit.framework.TestCase.runTest(TestCase.java:177) - at org.jmock.core.VerifyingTestCase.runBare(VerifyingTestCase.java:39) + at junit.framework.TestCase.runBare(TestCase.java:142) at junit.framework.TestResult$1.protect(TestResult.java:122) at junit.framework.TestResult.runProtected(TestResult.java:142) at junit.framework.TestResult.run(TestResult.java:125) @@ -2003,21 +2114,19 @@ at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) -got null -2025-05-15 21:24:05.994 WARN net.spy.memcached.transcoders.SerializingTranscoder: Failed to decompress data -java.util.zip.ZipException: Not in GZIP format - at java.base/java.util.zip.GZIPInputStream.readHeader(GZIPInputStream.java:165) - at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:79) - at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:91) - at net.spy.memcached.transcoders.BaseSerializingTranscoder.decompress(BaseSerializingTranscoder.java:181) - at net.spy.memcached.transcoders.SerializingTranscoder.decode(SerializingTranscoder.java:84) - at net.spy.memcached.transcoders.SerializingTranscoderTest.testUndecodeableCompressed(SerializingTranscoderTest.java:133) + +Apr 12, 2024 3:03:09 PM net.spy.memcached.compat.log.SunLogger log +SEVERE: test null +Apr 12, 2024 3:03:09 PM net.spy.memcached.compat.log.SunLogger log +SEVERE: null message with exception and no requestor +java.lang.Exception + at net.spy.memcached.compat.log.LoggingTest.testSunLogger(LoggingTest.java:103) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:568) at junit.framework.TestCase.runTest(TestCase.java:177) - at org.jmock.core.VerifyingTestCase.runBare(VerifyingTestCase.java:39) + at junit.framework.TestCase.runBare(TestCase.java:142) at junit.framework.TestResult$1.protect(TestResult.java:122) at junit.framework.TestResult.runProtected(TestResult.java:142) at junit.framework.TestResult.run(TestResult.java:125) @@ -2033,44 +2142,23 @@ at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) -2025-05-15 21:24:05.997 WARN net.spy.memcached.transcoders.SerializingTranscoder: Undecodeable with flags ff00 -[INFO] Tests run: 28, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.027 s - in net.spy.memcached.transcoders.SerializingTranscoderTest -[INFO] Running net.spy.memcached.transcoders.IntegerTranscoderTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.transcoders.IntegerTranscoderTest -[INFO] Running net.spy.memcached.CacheMapTest -[INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.028 s - in net.spy.memcached.CacheMapTest -[INFO] Running net.spy.memcached.util.CacheLoaderTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.252 s - in net.spy.memcached.util.CacheLoaderTest -[INFO] Running net.spy.memcached.util.StringUtilsTest -[INFO] Tests run: 16, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.03 s - in net.spy.memcached.util.StringUtilsTest -[INFO] Running net.spy.memcached.BinaryCancellationTest -2025-05-15 21:24:06.350 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.362 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.365 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} -java.net.ConnectException: Connection refused - at java.base/sun.nio.ch.Net.pollConnect(Native Method) - at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) - at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) - at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) - at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) - at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.366 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.368 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.368 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.368 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.369 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} -java.net.ConnectException: Connection refused - at java.base/sun.nio.ch.Net.pollConnect(Native Method) - at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) - at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) - at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) - at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) - at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.369 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.382 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.382 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.382 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.388 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} + +[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.021 s - in net.spy.memcached.compat.log.LoggingTest +[INFO] Running net.spy.memcached.LongClientTest +2024-04-12 15:03:09.948 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:09.949 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:09.950 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:09.950 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:09.950 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.491 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.491 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.492 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.496 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.55 s - in net.spy.memcached.LongClientTest +[INFO] Running net.spy.memcached.AsciiCancellationTest +2024-04-12 15:03:10.499 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.500 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.507 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2078,10 +2166,10 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.389 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.389 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.389 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.390 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.508 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.511 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.513 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.514 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2089,11 +2177,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.390 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.400 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.401 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.401 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.402 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.515 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.525 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.526 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.526 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.530 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2101,11 +2189,10 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.402 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.412 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.413 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.413 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.413 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.530 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.530 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.531 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.536 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: gets Keys: kExp: 0, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2113,11 +2200,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.414 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.425 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.425 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.425 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.426 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.536 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: gets Keys: kExp: 0, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.546 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.547 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.547 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.550 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2125,11 +2212,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.426 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.436 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.436 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.436 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.437 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.551 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.563 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.563 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.564 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.565 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2137,11 +2224,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.437 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.448 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.449 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.449 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.449 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.565 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.575 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.576 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.577 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.578 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2149,11 +2236,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.450 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.460 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.460 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.461 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.461 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.578 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.588 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.589 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.590 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.590 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2161,11 +2248,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.461 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.471 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.472 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.472 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.472 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.591 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.601 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.602 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.602 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.603 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2173,11 +2260,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.473 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.483 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.483 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.483 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.484 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.604 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.614 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.614 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.615 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.616 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2185,11 +2272,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.484 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.494 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.494 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.494 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.495 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.617 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.627 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.627 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.628 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.629 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2197,189 +2284,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.495 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.506 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.169 s - in net.spy.memcached.BinaryCancellationTest -[INFO] Running net.spy.memcached.MemcachedClientConstructorTest -2025-05-15 21:24:06.509 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.510 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.511 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.512 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.513 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.515 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.516 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.517 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.521 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -[INFO] Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.012 s - in net.spy.memcached.MemcachedClientConstructorTest -[INFO] Running net.spy.memcached.KetamaNodeKeyFormatterTest -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in net.spy.memcached.KetamaNodeKeyFormatterTest -[INFO] Running net.spy.memcached.KetamaConnectionFactoryTest -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.KetamaConnectionFactoryTest -[INFO] Running net.spy.memcached.CASMutatorTest -2025-05-15 21:24:06.532 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.532 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.533 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.533 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.534 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.535 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.535 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.536 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.567 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.568 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.568 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.569 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.569 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.570 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.570 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.571 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.572 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.572 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.573 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.574 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.574 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.575 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.575 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.576 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.577 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.577 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.578 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.578 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.579 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.580 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.580 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.581 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.582 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.582 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.583 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.584 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.584 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.585 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.585 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.586 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.586 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.587 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.588 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.588 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.589 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.589 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.590 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.591 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.591 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.592 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.592 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.593 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.593 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.594 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.595 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.595 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.596 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.596 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.597 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.598 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.598 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.599 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.599 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.600 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 0 wanted 768 -2025-05-15 21:24:06.600 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.600 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.601 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.601 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.602 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.602 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.603 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.603 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.603 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.604 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.604 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.604 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.606 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.606 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.606 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.607 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.607 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.607 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.608 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.608 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.608 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.076 s - in net.spy.memcached.CASMutatorTest -2025-05-15 21:24:06.609 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -[INFO] Running net.spy.memcached.MemcachedConnectionTest -2025-05-15 21:24:06.610 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.610 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.611 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} -java.net.ConnectException: Connection refused - at java.base/sun.nio.ch.Net.pollConnect(Native Method) - at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) - at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) - at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) - at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) - at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.611 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:06.613 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.613 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.MemcachedConnectionTest -[INFO] Running net.spy.memcached.ArrayModNodeLocatorTest -[INFO] Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.006 s - in net.spy.memcached.ArrayModNodeLocatorTest -[INFO] Running net.spy.memcached.DefaultHashAlgorithmTest -[INFO] Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.DefaultHashAlgorithmTest -[INFO] Running net.spy.memcached.tapmessage.ResponseMessageFixedOrderTest -[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.01 s - in net.spy.memcached.tapmessage.ResponseMessageFixedOrderTest -[INFO] Running net.spy.memcached.tapmessage.ResponseMessageWrongOrderTest -[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.tapmessage.ResponseMessageWrongOrderTest -[INFO] Running net.spy.memcached.tapmessage.TapDeleteTest -[INFO] Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in net.spy.memcached.tapmessage.TapDeleteTest -[INFO] Running net.spy.memcached.MetricsTest -2025-05-15 21:24:06.646 INFO net.spy.memcached.ConnectionFactoryBuilder$1: Metric collection enabled (Profile PERFORMANCE). -SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". -SLF4J: Defaulting to no-operation (NOP) logger implementation -SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. -2025-05-15 21:24:06.658 INFO net.spy.memcached.ConnectionFactoryBuilder$1: Metric collection enabled (Profile PERFORMANCE). -2025-05-15 21:24:06.659 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.659 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.660 WARN net.spy.memcached.MemcachedConnection: Could not redistribute to another node, retrying primary node for metrics:test. -2025-05-15 21:24:06.661 [INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.015 s - in net.spy.memcached.MetricsTest -INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -[INFO] Running net.spy.memcached.ToStringTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.ToStringTest -[INFO] Running net.spy.memcached.ConsistentHashingTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in net.spy.memcached.ConsistentHashingTest -[INFO] Running net.spy.memcached.KetamaNodeLocatorTest -[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.073 s - in net.spy.memcached.KetamaNodeLocatorTest -[INFO] Running net.spy.memcached.ErrorCodeTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.ErrorCodeTest -[INFO] Running net.spy.memcached.CancelFailureModeTest -2025-05-15 21:24:06.747 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.748 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.748 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.749 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to exception on {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} -java.nio.channels.CancelledKeyException - at java.base/sun.nio.ch.SelectionKeyImpl.ensureValid(SelectionKeyImpl.java:75) - at java.base/sun.nio.ch.SelectionKeyImpl.readyOps(SelectionKeyImpl.java:138) - at java.base/java.nio.channels.SelectionKey.isReadable(SelectionKey.java:355) - at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:686) - at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) - at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:06.749 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.749 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:06.749 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:06.850 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.103 s - in net.spy.memcached.CancelFailureModeTest -[INFO] Running net.spy.memcached.TimeoutNowriteTest -2025-05-15 21:24:06.852 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:06.852 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -Operation attempted: -net.spy.memcached.TimeoutNowriteTest$3@ac7fb -Trying to get: -Received a line. -Complete. - -Op timed out is true -2025-05-15 21:24:07.954 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 1.102 s - in net.spy.memcached.TimeoutNowriteTest -[INFO] Running net.spy.memcached.TimeoutTest -2025-05-15 21:24:07.955 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:07.956 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:07.956 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.629 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.639 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.640 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.641 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.642 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: replace Key: x Flags: 0 Exp: 3 Data Length: 1, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2387,11 +2296,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:07.957 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:07.977 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:07.978 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:07.978 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:07.978 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.642 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: replace Key: x Flags: 0 Exp: 3 Data Length: 1, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.657 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.657 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.658 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.658 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2399,23 +2308,25 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:07.978 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:07.998 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:07.999 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:07.999 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:07.999 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.659 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.668 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.164 s - in net.spy.memcached.AsciiCancellationTest +[INFO] Running net.spy.memcached.spring.MemcachedClientFactoryBeanTest +2024-04-12 15:03:10.674 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.674 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.674 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.spring.MemcachedClientFactoryBeanTest +2024-04-12 15:03:10.675 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused +[INFO] Running net.spy.memcached.MemcachedNodeROImplTest at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:07.999 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:08.020 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:08.020 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:08.020 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:08.021 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.676 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:10.677 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2423,11 +2334,13 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:08.021 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:08.041 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:08.041 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:08.041 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:08.041 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.678 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in net.spy.memcached.MemcachedNodeROImplTest +[INFO] Running net.spy.memcached.RedistributeFailureModeTest +2024-04-12 15:03:10.685 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.685 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.686 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.687 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2435,11 +2348,71 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:08.042 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:08.062 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:08.062 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:08.062 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:08.063 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:10.688 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +testMixedSetsAndUpdates complete. +2024-04-12 15:03:10.820 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:10.820 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:10.821 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:10.927 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.241 s - in net.spy.memcached.RedistributeFailureModeTest +[INFO] Running net.spy.memcached.DefaultHashAlgorithmTest +[INFO] Tests run: 7, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.DefaultHashAlgorithmTest +[INFO] Running net.spy.memcached.AddrUtilTest +java.lang.NumberFormatException: For input string: "more" + at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:67) + at java.base/java.lang.Integer.parseInt(Integer.java:668) + at java.base/java.lang.Integer.parseInt(Integer.java:786) + at net.spy.memcached.AddrUtil.getAddresses(AddrUtil.java:70) + at net.spy.memcached.AddrUtilTest.testBrokenHost(AddrUtilTest.java:67) + at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) + at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) + at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) + at java.base/java.lang.reflect.Method.invoke(Method.java:568) + at junit.framework.TestCase.runTest(TestCase.java:177) + at junit.framework.TestCase.runBare(TestCase.java:142) + at junit.framework.TestResult$1.protect(TestResult.java:122) + at junit.framework.TestResult.runProtected(TestResult.java:142) + at junit.framework.TestResult.run(TestResult.java:125) + at junit.framework.TestCase.run(TestCase.java:130) + at junit.framework.TestSuite.runTest(TestSuite.java:241) + at junit.framework.TestSuite.run(TestSuite.java:236) + at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:90) + at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365) + at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273) + at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238) + at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159) + at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) + at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) + at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) + at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) +[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 s - in net.spy.memcached.AddrUtilTest +[INFO] Running net.spy.memcached.ArrayModNodeLocatorTest +[INFO] Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.007 s - in net.spy.memcached.ArrayModNodeLocatorTest +[INFO] Running net.spy.memcached.tapmessage.ResponseMessageWrongOrderTest +[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.047 s - in net.spy.memcached.tapmessage.ResponseMessageWrongOrderTest +[INFO] Running net.spy.memcached.tapmessage.TapDeleteTest +[INFO] Tests run: 14, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in net.spy.memcached.tapmessage.TapDeleteTest +[INFO] Running net.spy.memcached.tapmessage.ResponseMessageFixedOrderTest +[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.tapmessage.ResponseMessageFixedOrderTest +[INFO] Running net.spy.memcached.KetamaNodeKeyFormatterTest +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.KetamaNodeKeyFormatterTest +[INFO] Running net.spy.memcached.MemcachedClientConstructorTest +2024-04-12 15:03:11.036 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.037 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.038 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.038 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.039 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.045 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.046 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.047 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.049 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Tests run: 11, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.009 s - in net.spy.memcached.MemcachedClientConstructorTest +[INFO] Running net.spy.memcached.CacheMapTest +[INFO] Tests run: 18, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.026 s - in net.spy.memcached.CacheMapTest +[INFO] Running net.spy.memcached.TimeoutTest +2024-04-12 15:03:11.083 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.083 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.084 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2447,16 +2420,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:08.063 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:08.083 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.127 s - in net.spy.memcached.TimeoutTest -[INFO] Running net.spy.memcached.spring.MemcachedClientFactoryBeanTest -2025-05-15 21:24:08.086 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:08.086 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:08.086 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.spring.MemcachedClientFactoryBeanTest -[INFO] Running net.spy.memcached.internal.SingleElementInfiniteIteratorTest -2025-05-15 21:24:08.087 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.085 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.106 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.106 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.107 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.107 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2464,8 +2432,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:08.087 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:08.087 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.108 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.128 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.128 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.129 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.129 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2473,32 +2444,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:08.088 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.internal.SingleElementInfiniteIteratorTest -[INFO] Running net.spy.memcached.internal.ListenableFutureTest -[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.499 s - in net.spy.memcached.internal.ListenableFutureTest -[INFO] Running net.spy.memcached.internal.CheckedOperationTimeoutExceptionTest -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.internal.CheckedOperationTimeoutExceptionTest -[INFO] Running net.spy.memcached.ConnectionFactoryBuilderTest -[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in net.spy.memcached.ConnectionFactoryBuilderTest -[INFO] Running net.spy.memcached.LongClientTest -2025-05-15 21:24:08.598 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:08.599 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:08.607 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:08.608 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:08.608 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:08.934 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:08.934 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:08.934 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:08.935 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.337 s - in net.spy.memcached.LongClientTest -[INFO] Running net.spy.memcached.protocol.ascii.BaseOpTest -[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.protocol.ascii.BaseOpTest -[INFO] Running net.spy.memcached.protocol.ascii.OperationExceptionTest -[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.protocol.ascii.OperationExceptionTest -[INFO] Running net.spy.memcached.protocol.ascii.OperationFactoryTest -2025-05-15 21:24:10.631 INFO net.spy.memcached.MemcachedConnection: Reconnecting {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} -2025-05-15 21:24:10.632 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.130 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.150 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.151 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.151 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.152 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2506,18 +2456,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:10.632 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 2. -[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 2.954 s - in net.spy.memcached.protocol.ascii.OperationFactoryTest -[INFO] Running net.spy.memcached.protocol.ascii.AsciiToStringTest -[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 s - in net.spy.memcached.protocol.ascii.AsciiToStringTest -[INFO] Running net.spy.memcached.protocol.binary.BinaryToStringTest -[INFO] Tests run: 23, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.009 s - in net.spy.memcached.protocol.binary.BinaryToStringTest -[INFO] Running net.spy.memcached.protocol.binary.OperatonTest -[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.protocol.binary.OperatonTest -[INFO] Running net.spy.memcached.protocol.binary.OperationFactoryTest -2025-05-15 21:24:12.107 INFO net.spy.memcached.MemcachedConnection: Reconnecting {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} -2025-05-15 21:24:12.107 INFO net.spy.memcached.MemcachedConnection: Reconnecting {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} -2025-05-15 21:24:12.108 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.152 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.172 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.173 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.173 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.174 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2525,8 +2468,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:12.108 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 2. -2025-05-15 21:24:12.108 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.175 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.195 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.195 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.196 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.196 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2534,23 +2480,28 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:12.108 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 2. -[INFO] Tests run: 15, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 3.367 s - in net.spy.memcached.protocol.binary.OperationFactoryTest -[INFO] Running net.spy.memcached.MemcachedNodeROImplTest -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.MemcachedNodeROImplTest -[INFO] Running net.spy.memcached.AddrUtilTest -java.lang.NumberFormatException: For input string: "more" - at java.base/java.lang.NumberFormatException.forInputString(NumberFormatException.java:67) - at java.base/java.lang.Integer.parseInt(Integer.java:668) - at java.base/java.lang.Integer.parseInt(Integer.java:786) - at net.spy.memcached.AddrUtil.getAddresses(AddrUtil.java:70) - at net.spy.memcached.AddrUtilTest.testBrokenHost(AddrUtilTest.java:67) +2024-04-12 15:03:11.197 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.217 [INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.124 s - in net.spy.memcached.TimeoutTest +[INFO] Running net.spy.memcached.KetamaConnectionFactoryTest +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.KetamaConnectionFactoryTest +[INFO] Running net.spy.memcached.transcoders.IntegerTranscoderTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.transcoders.IntegerTranscoderTest +[INFO] Running net.spy.memcached.transcoders.SerializingTranscoderTest +INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.244 INFO net.spy.memcached.transcoders.SerializingTranscoder: Compression increased the size of java.lang.String from 57 to 73 +2024-04-12 15:03:11.265 WARN net.spy.memcached.transcoders.SerializingTranscoder: Caught IOException decoding 4 bytes of data +java.io.StreamCorruptedException: invalid stream header: 7FFFFFFF + at java.base/java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:958) + at java.base/java.io.ObjectInputStream.(ObjectInputStream.java:392) + at net.spy.memcached.transcoders.BaseSerializingTranscoder.deserialize(BaseSerializingTranscoder.java:128) + at net.spy.memcached.transcoders.SerializingTranscoder.decode(SerializingTranscoder.java:88) + at net.spy.memcached.transcoders.SerializingTranscoderTest.testUndecodeableSerialized(SerializingTranscoderTest.java:125) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:568) at junit.framework.TestCase.runTest(TestCase.java:177) - at junit.framework.TestCase.runBare(TestCase.java:142) + at org.jmock.core.VerifyingTestCase.runBare(VerifyingTestCase.java:39) at junit.framework.TestResult$1.protect(TestResult.java:122) at junit.framework.TestResult.runProtected(TestResult.java:142) at junit.framework.TestResult.run(TestResult.java:125) @@ -2566,27 +2517,20 @@ at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) -[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.AddrUtilTest -[INFO] Running net.spy.memcached.compat.log.LoggingTest -2025-05-15 21:24:15.293 WARN net.spy.memcached.compat.log.LoggingTest: warn message -2025-05-15 21:24:15.293 WARN net.spy.memcached.compat.log.LoggingTest: test message -2025-05-15 21:24:15.293 ERROR net.spy.memcached.compat.log.LoggingTest: error message -2025-05-15 21:24:15.293 ERROR net.spy.memcached.compat.log.LoggingTest: test message -2025-05-15 21:24:15.293 FATAL net.spy.memcached.compat.log.LoggingTest: fatal message -2025-05-15 21:24:15.293 FATAL net.spy.memcached.compat.log.LoggingTest: test message -2025-05-15 21:24:15.294 INFO net.spy.memcached.compat.log.LoggingTest: info message -2025-05-15 21:24:15.294 WARN net.spy.memcached.compat.log.LoggingTest: warn message -2025-05-15 21:24:15.294 ERROR net.spy.memcached.compat.log.LoggingTest: error message -2025-05-15 21:24:15.294 FATAL net.spy.memcached.compat.log.LoggingTest: fatal message -2025-05-15 21:24:15.294 FATAL net.spy.memcached.compat.log.LoggingTest: fatal message with exception -java.lang.Exception - at net.spy.memcached.compat.log.LoggingTest.testMyLogger(LoggingTest.java:119) +2024-04-12 15:03:11.266 WARN net.spy.memcached.transcoders.SerializingTranscoder: Failed to decompress data +java.util.zip.ZipException: Not in GZIP format + at java.base/java.util.zip.GZIPInputStream.readHeader(GZIPInputStream.java:165) + at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:79) + at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:91) + at net.spy.memcached.transcoders.BaseSerializingTranscoder.decompress(BaseSerializingTranscoder.java:181) + at net.spy.memcached.transcoders.SerializingTranscoder.decode(SerializingTranscoder.java:84) + at net.spy.memcached.transcoders.SerializingTranscoderTest.testUndecodeableCompressed(SerializingTranscoderTest.java:132) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:568) at junit.framework.TestCase.runTest(TestCase.java:177) - at junit.framework.TestCase.runBare(TestCase.java:142) + at org.jmock.core.VerifyingTestCase.runBare(VerifyingTestCase.java:39) at junit.framework.TestResult$1.protect(TestResult.java:122) at junit.framework.TestResult.runProtected(TestResult.java:142) at junit.framework.TestResult.run(TestResult.java:125) @@ -2602,25 +2546,21 @@ at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) -2025-05-15 21:24:15.295 INFO net.spy.memcached.compat.log.LoggingTest: info message -May 15, 2025 9:24:15 PM net.spy.memcached.compat.log.LoggingTest testSunLogger -INFO: info message -May 15, 2025 9:24:15 PM net.spy.memcached.compat.log.LoggingTest testSunLogger -WARNING: warn message -May 15, 2025 9:24:15 PM net.spy.memcached.compat.log.LoggingTest testSunLogger -SEVERE: error message -May 15, 2025 9:24:15 PM net.spy.memcached.compat.log.LoggingTest testSunLogger -SEVERE: fatal message -May 15, 2025 9:24:15 PM net.spy.memcached.compat.log.LoggingTest testSunLogger -SEVERE: fatal message with exception -java.lang.Exception - at net.spy.memcached.compat.log.LoggingTest.testSunLogger(LoggingTest.java:101) +got null +2024-04-12 15:03:11.267 WARN net.spy.memcached.transcoders.SerializingTranscoder: Failed to decompress data +java.util.zip.ZipException: Not in GZIP format + at java.base/java.util.zip.GZIPInputStream.readHeader(GZIPInputStream.java:165) + at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:79) + at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:91) + at net.spy.memcached.transcoders.BaseSerializingTranscoder.decompress(BaseSerializingTranscoder.java:181) + at net.spy.memcached.transcoders.SerializingTranscoder.decode(SerializingTranscoder.java:84) + at net.spy.memcached.transcoders.SerializingTranscoderTest.testUndecodeableCompressed(SerializingTranscoderTest.java:133) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.base/java.lang.reflect.Method.invoke(Method.java:568) at junit.framework.TestCase.runTest(TestCase.java:177) - at junit.framework.TestCase.runBare(TestCase.java:142) + at org.jmock.core.VerifyingTestCase.runBare(VerifyingTestCase.java:39) at junit.framework.TestResult$1.protect(TestResult.java:122) at junit.framework.TestResult.runProtected(TestResult.java:142) at junit.framework.TestResult.run(TestResult.java:125) @@ -2636,13 +2576,35 @@ at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) - -May 15, 2025 9:24:15 PM net.spy.memcached.compat.log.SunLogger log -SEVERE: test null -May 15, 2025 9:24:15 PM net.spy.memcached.compat.log.SunLogger log -SEVERE: null message with exception and no requestor -java.lang.Exception - at net.spy.memcached.compat.log.LoggingTest.testSunLogger(LoggingTest.java:103) +2024-04-12 15:03:11.270 WARN net.spy.memcached.transcoders.SerializingTranscoder: Undecodeable with flags ff00 +[INFO] Tests run: 28, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.099 s - in net.spy.memcached.transcoders.SerializingTranscoderTest +[INFO] Running net.spy.memcached.transcoders.TranscoderUtilsTest +[INFO] Tests run: 6, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.transcoders.TranscoderUtilsTest +[INFO] Running net.spy.memcached.transcoders.TranscodeServiceTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.002 s - in net.spy.memcached.transcoders.TranscodeServiceTest +[INFO] Running net.spy.memcached.transcoders.CachedDataTest +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.transcoders.CachedDataTest +[INFO] Running net.spy.memcached.transcoders.LongTranscoderTest +2024-04-12 15:03:11.343 ERROR net.spy.memcached.transcoders.LongTranscoder: Unexpected flags for long: 769 wanted 768 +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.transcoders.LongTranscoderTest +[INFO] Running net.spy.memcached.transcoders.BaseSerializingTranscoderTest +2024-04-12 15:03:11.355 WARN net.spy.memcached.transcoders.BaseSerializingTranscoderTest$Exposer: Caught CNFE decoding 41 bytes of data +java.lang.ClassNotFoundException: Test + at java.base/jdk.internal.loader.BuiltinClassLoader.loadClass(BuiltinClassLoader.java:641) + at java.base/jdk.internal.loader.ClassLoaders$AppClassLoader.loadClass(ClassLoaders.java:188) + at java.base/java.lang.ClassLoader.loadClass(ClassLoader.java:525) + at java.base/java.lang.Class.forName0(Native Method) + at java.base/java.lang.Class.forName(Class.java:467) + at java.base/java.io.ObjectInputStream.resolveClass(ObjectInputStream.java:773) + at java.base/java.io.ObjectInputStream.readNonProxyDesc(ObjectInputStream.java:2034) + at java.base/java.io.ObjectInputStream.readClassDesc(ObjectInputStream.java:1898) + at java.base/java.io.ObjectInputStream.readOrdinaryObject(ObjectInputStream.java:2224) + at java.base/java.io.ObjectInputStream.readObject0(ObjectInputStream.java:1733) + at java.base/java.io.ObjectInputStream.readObject(ObjectInputStream.java:509) + at java.base/java.io.ObjectInputStream.readObject(ObjectInputStream.java:467) + at net.spy.memcached.transcoders.BaseSerializingTranscoder.deserialize(BaseSerializingTranscoder.java:129) + at net.spy.memcached.transcoders.BaseSerializingTranscoderTest$Exposer.deserialize(BaseSerializingTranscoderTest.java:154) + at net.spy.memcached.transcoders.BaseSerializingTranscoderTest.testUndeserializable(BaseSerializingTranscoderTest.java:98) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) @@ -2664,12 +2626,76 @@ at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) - -[INFO] Tests run: 9, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.026 s - in net.spy.memcached.compat.log.LoggingTest -[INFO] Running net.spy.memcached.AsciiCancellationTest -2025-05-15 21:24:15.321 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.322 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.322 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +[INFO] Tests run: 12, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.027 s - in net.spy.memcached.transcoders.BaseSerializingTranscoderTest +[INFO] Running net.spy.memcached.transcoders.WhalinTranscoderTest +2024-04-12 15:03:11.385 INFO net.spy.memcached.transcoders.WhalinTranscoder: Compression increased the size of java.lang.String from 57 to 73 +2024-04-12 15:03:11.390 WARN net.spy.memcached.transcoders.WhalinTranscoder: Caught IOException decoding 4 bytes of data +java.io.StreamCorruptedException: invalid stream header: 7FFFFFFF + at java.base/java.io.ObjectInputStream.readStreamHeader(ObjectInputStream.java:958) + at java.base/java.io.ObjectInputStream.(ObjectInputStream.java:392) + at net.spy.memcached.transcoders.BaseSerializingTranscoder.deserialize(BaseSerializingTranscoder.java:128) + at net.spy.memcached.transcoders.WhalinTranscoder.decode(WhalinTranscoder.java:70) + at net.spy.memcached.transcoders.WhalinTranscoderTest.testUndecodeableSerialized(WhalinTranscoderTest.java:124) + at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) + at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) + at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) + at java.base/java.lang.reflect.Method.invoke(Method.java:568) + at junit.framework.TestCase.runTest(TestCase.java:177) + at org.jmock.core.VerifyingTestCase.runBare(VerifyingTestCase.java:39) + at junit.framework.TestResult$1.protect(TestResult.java:122) + at junit.framework.TestResult.runProtected(TestResult.java:142) + at junit.framework.TestResult.run(TestResult.java:125) + at junit.framework.TestCase.run(TestCase.java:130) + at junit.framework.TestSuite.runTest(TestSuite.java:241) + at junit.framework.TestSuite.run(TestSuite.java:236) + at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:90) + at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365) + at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273) + at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238) + at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159) + at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) + at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) + at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) + at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) +2024-04-12 15:03:11.391 WARN net.spy.memcached.transcoders.WhalinTranscoder: Failed to decompress data +java.util.zip.ZipException: Not in GZIP format + at java.base/java.util.zip.GZIPInputStream.readHeader(GZIPInputStream.java:165) + at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:79) + at java.base/java.util.zip.GZIPInputStream.(GZIPInputStream.java:91) + at net.spy.memcached.transcoders.BaseSerializingTranscoder.decompress(BaseSerializingTranscoder.java:181) + at net.spy.memcached.transcoders.WhalinTranscoder.decode(WhalinTranscoder.java:67) + at net.spy.memcached.transcoders.WhalinTranscoderTest.testUndecodeableCompressed(WhalinTranscoderTest.java:130) + at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method) + at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:77) + at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) + at java.base/java.lang.reflect.Method.invoke(Method.java:568) + at junit.framework.TestCase.runTest(TestCase.java:177) + at org.jmock.core.VerifyingTestCase.runBare(VerifyingTestCase.java:39) + at junit.framework.TestResult$1.protect(TestResult.java:122) + at junit.framework.TestResult.runProtected(TestResult.java:142) + at junit.framework.TestResult.run(TestResult.java:125) + at junit.framework.TestCase.run(TestCase.java:130) + at junit.framework.TestSuite.runTest(TestSuite.java:241) + at junit.framework.TestSuite.run(TestSuite.java:236) + at org.junit.internal.runners.JUnit38ClassRunner.run(JUnit38ClassRunner.java:90) + at org.apache.maven.surefire.junit4.JUnit4Provider.execute(JUnit4Provider.java:365) + at org.apache.maven.surefire.junit4.JUnit4Provider.executeWithRerun(JUnit4Provider.java:273) + at org.apache.maven.surefire.junit4.JUnit4Provider.executeTestSet(JUnit4Provider.java:238) + at org.apache.maven.surefire.junit4.JUnit4Provider.invoke(JUnit4Provider.java:159) + at org.apache.maven.surefire.booter.ForkedBooter.invokeProviderInSameClassLoader(ForkedBooter.java:384) + at org.apache.maven.surefire.booter.ForkedBooter.runSuitesInProcess(ForkedBooter.java:345) + at org.apache.maven.surefire.booter.ForkedBooter.execute(ForkedBooter.java:126) + at org.apache.maven.surefire.booter.ForkedBooter.main(ForkedBooter.java:418) +2024-04-12 15:03:11.392 WARN net.spy.memcached.transcoders.WhalinTranscoder: Cannot handle data with flags 0 +2024-04-12 15:03:11.394 WARN net.spy.memcached.transcoders.WhalinTranscoder: Cannot handle data with flags 7ffffff5 +[INFO] Tests run: 28, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.028 s - in net.spy.memcached.transcoders.WhalinTranscoderTest +[INFO] Running net.spy.memcached.transcoders.WhalinV1TranscoderTest +2024-04-12 15:03:11.422 INFO net.spy.memcached.transcoders.WhalinV1Transcoder: Compressed java.util.ArrayList from 17491 to 194 +[INFO] Tests run: 19, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.014 s - in net.spy.memcached.transcoders.WhalinV1TranscoderTest +[INFO] Running net.spy.memcached.BinaryCancellationTest +2024-04-12 15:03:11.431 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.432 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.433 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2677,10 +2703,10 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.322 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.322 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.323 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.323 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.434 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.434 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.434 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.435 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2688,14 +2714,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.323 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.334 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.334 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.334 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.335 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.335 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.335 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.335 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: gets Keys: kExp: 0, toWrite=0, interested=0} +2024-04-12 15:03:11.435 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.448 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.449 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.449 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.450 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2703,11 +2726,10 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.335 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: gets Keys: kExp: 0, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.345 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.346 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.346 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.346 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.450 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.450 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.451 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.451 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2715,11 +2737,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.346 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.356 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.357 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.357 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.357 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: flush_all Delay: -1, toWrite=0, interested=0} +2024-04-12 15:03:11.452 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.462 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.463 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.463 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.464 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2727,11 +2749,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.358 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: flush_all Delay: -1, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.367 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.368 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.368 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.368 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.464 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.475 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.476 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.476 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.477 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2739,11 +2761,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.368 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.378 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.378 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.379 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.379 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.477 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.488 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.488 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.489 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.489 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: 8 Opaque: 22 Delay: 0, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2751,11 +2773,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.379 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.389 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.389 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.389 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.390 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.490 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: 8 Opaque: 22 Delay: 0, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.499 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.500 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.500 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.501 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2763,11 +2785,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.390 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.400 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.400 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.400 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.401 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: set Key: x Flags: 0 Exp: 3 Data Length: 1, toWrite=0, interested=0} +2024-04-12 15:03:11.501 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.513 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.513 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.514 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.514 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2775,11 +2797,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.401 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: set Key: x Flags: 0 Exp: 3 Data Length: 1, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.411 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.411 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.411 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.412 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.515 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.524 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.525 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.525 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.526 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: 1 Opaque: 25 Key: x Cas: 0 Exp: 3 Flags: 0 Data Length: 1, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2787,11 +2809,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.412 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.422 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.422 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.422 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.423 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:11.527 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: 1 Opaque: 25 Key: x Cas: 0 Exp: 3 Flags: 0 Data Length: 1, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.536 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.537 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.537 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.538 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2799,11 +2821,11 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.423 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.433 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.433 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.433 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.433 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: get Keys: kExp: 0, toWrite=0, interested=0} +2024-04-12 15:03:11.538 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.548 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.548 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.549 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.549 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2811,33 +2833,47 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.434 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: get Keys: kExp: 0, toWrite=0, interested=0}, attempt 1. -2025-05-15 21:24:15.444 INFO net.spy.memcached.MemcachedConnection[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.121 s - in net.spy.memcached.AsciiCancellationTest -: Shut down memcached client +2024-04-12 15:03:11.550 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=1, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. +2024-04-12 15:03:11.559 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:11.560 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.560 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.561 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: 0 Opaque: 30 Key: k, toWrite=0, interested=0} +java.net.ConnectException: Connection refused + at java.base/sun.nio.ch.Net.pollConnect(Native Method) + at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) + at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) + at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) +2024-04-12 15:03:11.561 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:64213, #Rops=0, #Wops=1, #iq=0, topRop=null, topWop=Cmd: 0 Opaque: 30 Key: k, toWrite=0, interested=0}, attempt 1. +[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.138 s - in net.spy.memcached.BinaryCancellationTest +2024-04-12 15:03:11.571 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Running net.spy.memcached.util.StringUtilsTest +[INFO] Tests run: 16, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.util.StringUtilsTest +[INFO] Running net.spy.memcached.util.CacheLoaderTest +[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.252 s - in net.spy.memcached.util.CacheLoaderTest [INFO] Running net.spy.memcached.ConnectionFactoryTest [INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.ConnectionFactoryTest -[INFO] Running net.spy.memcached.ObserverTest -2025-05-15 21:24:15.445 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.446 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.446 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.446 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.446 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.447 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.447 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.447 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.448 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.448 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.448 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.449 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.449 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.449 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.450 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.ObserverTest -[INFO] Running net.spy.memcached.RedistributeFailureModeTest -2025-05-15 21:24:15.451 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.451 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.451 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.452 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +[INFO] Running net.spy.memcached.MetricsTest +2024-04-12 15:03:11.838 INFO net.spy.memcached.ConnectionFactoryBuilder$1: Metric collection enabled (Profile PERFORMANCE). +SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder". +SLF4J: Defaulting to no-operation (NOP) logger implementation +SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details. +2024-04-12 15:03:11.852 INFO net.spy.memcached.ConnectionFactoryBuilder$1: Metric collection enabled (Profile PERFORMANCE). +2024-04-12 15:03:11.853 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 +2024-04-12 15:03:11.853 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue +2024-04-12 15:03:11.854 WARN net.spy.memcached.MemcachedConnection: Could not redistribute to another node, retrying primary node for metrics:test. +[INFO] Tests run: 3, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.017 s - in net.spy.memcached.MetricsTest +2024-04-12 15:03:11.858 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +[INFO] Running net.spy.memcached.internal.ListenableFutureTest +[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.5 s - in net.spy.memcached.internal.ListenableFutureTest +[INFO] Running net.spy.memcached.internal.SingleElementInfiniteIteratorTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.internal.SingleElementInfiniteIteratorTest +[INFO] Running net.spy.memcached.internal.CheckedOperationTimeoutExceptionTest +[INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.internal.CheckedOperationTimeoutExceptionTest +[INFO] Running net.spy.memcached.protocol.binary.OperationFactoryTest +2024-04-12 15:03:13.844 INFO net.spy.memcached.MemcachedConnection: Reconnecting {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:13.845 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} java.net.ConnectException: Connection refused at java.base/sun.nio.ch.Net.pollConnect(Native Method) at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) @@ -2845,27 +2881,70 @@ at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) -2025-05-15 21:24:15.452 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:11311, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 1. -testMixedSetsAndUpdates complete. -2025-05-15 21:24:15.557 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.557 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.558 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.659 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -[INFO] Tests run: 1, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.206 s - in net.spy.memcached.RedistributeFailureModeTest -[INFO] Running net.spy.memcached.QueueOverflowTest -2025-05-15 21:24:15.660 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.660 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.661 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.661 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.661 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.662 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.662 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.662 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.663 INFO net.spy.memcached.MemcachedConnection: Shut down memcached client -2025-05-15 21:24:15.663 INFO net.spy.memcached.MemcachedConnection: Setting retryQueueSize to -1 -2025-05-15 21:24:15.663 INFO net.spy.memcached.MemcachedConnection: Added {QA sa=/127.0.0.1:20000, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} to connect queue -2025-05-15 21:24:15.664 [INFO] Tests run: 2, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.003 s - in net.spy.memcached.QueueOverflowTest -INFO net.spy.memcached.MemcachedConnection: Shut down memcached client +2024-04-12 15:03:13.845 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 2. +2024-04-12 15:03:14.696 INFO net.spy.memcached.MemcachedConnection: Reconnecting {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:14.697 INFO net.spy.memcached.MemcachedConnection: Reconnecting {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:14.697 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +java.net.ConnectException: Connection refused + at java.base/sun.nio.ch.Net.pollConnect(Native Method) + at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) + at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) + at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) +2024-04-12 15:03:14.698 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 2. +2024-04-12 15:03:14.699 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +java.net.ConnectException: Connection refused + at java.base/sun.nio.ch.Net.pollConnect(Native Method) + at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) + at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) + at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) +2024-04-12 15:03:14.699 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 2. +[INFO] Tests run: 15, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 5.408 s - in net.spy.memcached.protocol.binary.OperationFactoryTest +[INFO] Running net.spy.memcached.protocol.binary.OperatonTest +[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.protocol.binary.OperatonTest +[INFO] Running net.spy.memcached.protocol.binary.BinaryToStringTest +[INFO] Tests run: 23, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.016 s - in net.spy.memcached.protocol.binary.BinaryToStringTest +[INFO] Running net.spy.memcached.protocol.ascii.OperationFactoryTest +2024-04-12 15:03:21.876 INFO net.spy.memcached.MemcachedConnection: Reconnecting {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:21.877 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +java.net.ConnectException: Connection refused + at java.base/sun.nio.ch.Net.pollConnect(Native Method) + at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) + at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) + at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) +2024-04-12 15:03:21.877 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:11211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 3. +2024-04-12 15:03:22.739 INFO net.spy.memcached.MemcachedConnection: Reconnecting {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:22.740 INFO net.spy.memcached.MemcachedConnection: Reconnecting {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +2024-04-12 15:03:22.740 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +java.net.ConnectException: Connection refused + at java.base/sun.nio.ch.Net.pollConnect(Native Method) + at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) + at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) + at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) +2024-04-12 15:03:22.741 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:22211, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 3. +2024-04-12 15:03:22.741 INFO net.spy.memcached.MemcachedConnection: Reconnecting due to failure to connect to {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0} +java.net.ConnectException: Connection refused + at java.base/sun.nio.ch.Net.pollConnect(Native Method) + at java.base/sun.nio.ch.Net.pollConnectNow(Net.java:672) + at java.base/sun.nio.ch.SocketChannelImpl.finishConnect(SocketChannelImpl.java:946) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:691) + at net.spy.memcached.MemcachedConnection.handleIO(MemcachedConnection.java:450) + at net.spy.memcached.MemcachedConnection.run(MemcachedConnection.java:1457) +2024-04-12 15:03:22.741 WARN net.spy.memcached.MemcachedConnection: Closing, and reopening {QA sa=/127.0.0.1:22212, #Rops=0, #Wops=0, #iq=0, topRop=null, topWop=null, toWrite=0, interested=0}, attempt 3. +[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 6.045 s - in net.spy.memcached.protocol.ascii.OperationFactoryTest +[INFO] Running net.spy.memcached.protocol.ascii.AsciiToStringTest +[INFO] Tests run: 13, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.protocol.ascii.AsciiToStringTest +[INFO] Running net.spy.memcached.protocol.ascii.BaseOpTest +[INFO] Tests run: 5, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0.001 s - in net.spy.memcached.protocol.ascii.BaseOpTest +[INFO] Running net.spy.memcached.protocol.ascii.OperationExceptionTest +[INFO] Tests run: 4, Failures: 0, Errors: 0, Skipped: 0, Time elapsed: 0 s - in net.spy.memcached.protocol.ascii.OperationExceptionTest [INFO] [INFO] Results: [INFO] @@ -2874,8 +2953,8 @@ [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ -[INFO] Total time: 20.647 s -[INFO] Finished at: 2025-05-15T21:24:25Z +[INFO] Total time: 21.132 s +[INFO] Finished at: 2024-04-12T15:03:27Z [INFO] ------------------------------------------------------------------------ [ ! -s /build/reproducible-path/spymemcached-2.12.3+dfsg/debian/build/memcached.pid ] || /bin/kill `cat /build/reproducible-path/spymemcached-2.12.3+dfsg/debian/build/memcached.pid` make[1]: Leaving directory '/build/reproducible-path/spymemcached-2.12.3+dfsg' @@ -2901,8 +2980,8 @@ [INFO] ------------------------------------------------------------------------ [INFO] BUILD SUCCESS [INFO] ------------------------------------------------------------------------ -[INFO] Total time: 0.385 s -[INFO] Finished at: 2025-05-15T21:24:26Z +[INFO] Total time: 0.716 s +[INFO] Finished at: 2024-04-12T15:03:30Z [INFO] ------------------------------------------------------------------------ mh_resolve_dependencies --non-interactive --offline --build -plibspymemcached-java --base-directory=/build/reproducible-path/spymemcached-2.12.3\+dfsg --non-explore Analysing pom.xml... @@ -2929,12 +3008,14 @@ dpkg-buildpackage: info: binary-only upload (no source included) dpkg-genchanges: info: not including original source code in upload I: copying local configuration +I: user script /srv/workspace/pbuilder/6794/tmp/hooks/B01_cleanup starting +I: user script /srv/workspace/pbuilder/6794/tmp/hooks/B01_cleanup finished I: unmounting dev/ptmx filesystem I: unmounting dev/pts filesystem I: unmounting dev/shm filesystem I: unmounting proc filesystem I: unmounting sys filesystem I: cleaning the build env -I: removing directory /srv/workspace/pbuilder/52514 and its subdirectories -I: Current time: Thu May 15 09:24:31 -12 2025 -I: pbuilder-time-stamp: 1747344271 +I: removing directory /srv/workspace/pbuilder/6794 and its subdirectories +I: Current time: Sat Apr 13 05:03:37 +14 2024 +I: pbuilder-time-stamp: 1712934217