You are viewing a plain text version of this content. The canonical link for it is here.
Posted to builds@lucene.apache.org by Apache Jenkins Server <je...@builds.apache.org> on 2021/10/13 02:47:44 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 3337 - Still Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/3337/
1 tests failed.
FAILED: org.apache.solr.s3.S3IncrementalBackupTest.testBackupIncremental
Error Message:
expected:<COMPLETED> but was:<FAILED>
Stack Trace:
java.lang.AssertionError: expected:<COMPLETED> but was:<FAILED>
at __randomizedtesting.SeedInfo.seed([863AAA8559AFBE2:FA605636972C59F]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.failNotEquals(Assert.java:835)
at org.junit.Assert.assertEquals(Assert.java:120)
at org.junit.Assert.assertEquals(Assert.java:146)
at org.apache.solr.cloud.api.collections.AbstractIncrementalBackupTest$IncrementalBackupVerifier.backupThenWait(AbstractIncrementalBackupTest.java:471)
at org.apache.solr.cloud.api.collections.AbstractIncrementalBackupTest$IncrementalBackupVerifier.incrementalBackupThenVerify(AbstractIncrementalBackupTest.java:487)
at org.apache.solr.cloud.api.collections.AbstractIncrementalBackupTest.backupRestoreThenCheck(AbstractIncrementalBackupTest.java:373)
at org.apache.solr.cloud.api.collections.AbstractIncrementalBackupTest.testBackupIncremental(AbstractIncrementalBackupTest.java:246)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:938)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:974)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:988)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:49)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:817)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:468)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:947)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:832)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:883)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:894)
at com.adobe.testing.s3mock.junit4.S3MockRule$1.evaluate(S3MockRule.java:66)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 26115 lines...]
[junit4] Suite: org.apache.solr.s3.S3IncrementalBackupTest
[junit4] 2> 21149 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 21239 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.s.h.C.application Initializing Spring embedded WebApplicationContext
[junit4] 2> 21317 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 21317 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 21317 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 21324 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.s.h.ContextHandler Started o.s.b.w.e.j.JettyEmbeddedWebAppContext@7f44d217{application,/,[file:///home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/contrib/solr-s3-repository/test/J2/temp/jetty-docbase.0.2565214640512800381/],AVAILABLE}
[junit4] 2> 21324 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.s.Server Started @21354ms
[junit4] 2> 21678 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.s.h.C.application Initializing Spring DispatcherServlet 'dispatcherServlet'
[junit4] 2> 22161 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.u.s.SslContextFactory x509=X509@a9cd016(selfsigned,h=[unknown],a=[],w=[]) for Server@1accb794[provider=null,keyStore=jar:file:/home/jenkins/.ivy2/cache/com.adobe.testing/s3mock/jars/s3mock-2.1.34.jar!/s3mock.jks,trustStore=null]
[junit4] 2> 22266 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.s.AbstractConnector Started SslValidatingServerConnector@2a4d008e{SSL, (ssl, http/1.1)}{0.0.0.0:32911}
[junit4] 2> 22270 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.e.j.s.AbstractConnector Started ServerConnector@543ea8c3{HTTP/1.1, (http/1.1)}{0.0.0.0:45157}
[junit4] 2> 22301 WARN (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [ ] o.a.s.c.ZkTestServer TEST_HOME() does not exist - solrj test?
[junit4] 2> 22629 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 23375 WARN (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker-SendThread(127.0.0.1:35931)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101d10a68a50002.
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101d10a68a50002, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 23533 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 23533 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 23545 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 23545 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 23545 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 23545 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 23545 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 23545 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 23550 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@50074227{/solr,null,AVAILABLE}
[junit4] 2> 23550 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@421dfd26{/solr,null,AVAILABLE}
[junit4] 2> 23565 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@45f56bac{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:34611}
[junit4] 2> 23565 INFO (jetty-launcher-9-thread-2) [ ] o.e.j.s.Server Started @23595ms
[junit4] 2> 23569 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@69d6bf18{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:39765}
[junit4] 2> 23569 INFO (jetty-launcher-9-thread-1) [ ] o.e.j.s.Server Started @23598ms
[junit4] 2> 23644 ERROR (jetty-launcher-9-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 23645 ERROR (jetty-launcher-9-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 23929 WARN (jetty-launcher-9-thread-2-SendThread(127.0.0.1:35931)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101d10a68a50003.
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101d10a68a50003, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 23929 WARN (jetty-launcher-9-thread-1-SendThread(127.0.0.1:35931)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101d10a68a50004.
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101d10a68a50004, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 25612 WARN (jetty-launcher-9-thread-2-SendThread(127.0.0.1:35931)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101d10a68a50005.
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101d10a68a50005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 25618 WARN (jetty-launcher-9-thread-1-SendThread(127.0.0.1:35931)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101d10a68a50006.
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101d10a68a50006, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 25856 WARN (jetty-launcher-9-thread-1) [n:127.0.0.1:39765_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 25926 WARN (jetty-launcher-9-thread-2) [n:127.0.0.1:34611_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 26481 WARN (jetty-launcher-9-thread-1) [n:127.0.0.1:39765_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 26587 WARN (jetty-launcher-9-thread-2) [n:127.0.0.1:34611_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 35530 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0.kdm did not match. expected checksum is 2469517170 and actual is checksum 331000283. expected length is 143 and actual length is 143
[junit4] 2> 35530 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0.kdm did not match. expected checksum is 1069035078 and actual is checksum 3207811311. expected length is 143 and actual length is 143
[junit4] 2> 35538 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0_Lucene84_0.pos did not match. expected checksum is 1020843412 and actual is checksum 3149886717. expected length is 129 and actual length is 129
[junit4] 2> 35551 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0_Lucene84_0.pos did not match. expected checksum is 2150411105 and actual is checksum 4049032664. expected length is 126 and actual length is 126
[junit4] 2> 35560 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0.si did not match. expected checksum is 712186711 and actual is checksum 4031971851. expected length is 620 and actual length is 620
[junit4] 2> 35560 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0.si did not match. expected checksum is 141855836 and actual is checksum 2002638257. expected length is 620 and actual length is 620
[junit4] 2> 35568 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0.kdi did not match. expected checksum is 705409733 and actual is checksum 1919355652. expected length is 68 and actual length is 68
[junit4] 2> 35575 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0.kdi did not match. expected checksum is 3951389957 and actual is checksum 3018446020. expected length is 68 and actual length is 68
[junit4] 2> 35576 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0.fdm did not match. expected checksum is 4146852439 and actual is checksum 3869006273. expected length is 158 and actual length is 158
[junit4] 2> 35588 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0_Lucene84_0.doc did not match. expected checksum is 2212889685 and actual is checksum 1730282792. expected length is 78 and actual length is 78
[junit4] 2> 35591 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0_Lucene84_0.doc did not match. expected checksum is 473728971 and actual is checksum 4177036982. expected length is 78 and actual length is 78
[junit4] 2> 35593 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0.fdm did not match. expected checksum is 3890534803 and actual is checksum 4132663813. expected length is 158 and actual length is 158
[junit4] 2> 35598 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0_Lucene84_0.tim did not match. expected checksum is 2491426025 and actual is checksum 3436459363. expected length is 1949 and actual length is 1949
[junit4] 2> 35602 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0_Lucene84_0.tim did not match. expected checksum is 3221697504 and actual is checksum 288701931. expected length is 2063 and actual length is 2063
[junit4] 2> 35609 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0.nvd did not match. expected checksum is 1555121817 and actual is checksum 1480890020. expected length is 59 and actual length is 59
[junit4] 2> 35609 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0.nvd did not match. expected checksum is 3010669991 and actual is checksum 3079077274. expected length is 59 and actual length is 59
[junit4] 2> 35630 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0_Lucene84_0.tip did not match. expected checksum is 3771307353 and actual is checksum 67998756. expected length is 72 and actual length is 72
[junit4] 2> 35633 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0_Lucene84_0.tip did not match. expected checksum is 2131957447 and actual is checksum 2614564794. expected length is 72 and actual length is 72
[junit4] 2> 35637 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0.fdx did not match. expected checksum is 1159144113 and actual is checksum 1105392268. expected length is 64 and actual length is 64
[junit4] 2> 35638 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0.fdx did not match. expected checksum is 2866104719 and actual is checksum 2921372082. expected length is 64 and actual length is 64
[junit4] 2> 35643 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0_Asserting_0.tmd did not match. expected checksum is 2030070438 and actual is checksum 3746197801. expected length is 308 and actual length is 308
[junit4] 2> 35644 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0.fdt did not match. expected checksum is 7561835 and actual is checksum 2648557240. expected length is 2938 and actual length is 2938
[junit4] 2> 35648 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0.fdt did not match. expected checksum is 1676888604 and actual is checksum 191285699. expected length is 3096 and actual length is 3096
[junit4] 2> 35648 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0_Asserting_0.tmd did not match. expected checksum is 966177804 and actual is checksum 2682040195. expected length is 308 and actual length is 308
[junit4] 2> 35667 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0_Asserting_0.pos did not match. expected checksum is 40032218 and actual is checksum 3643872249. expected length is 182 and actual length is 182
[junit4] 2> 35668 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0_Asserting_0.pos did not match. expected checksum is 114428183 and actual is checksum 3554059659. expected length is 176 and actual length is 176
[junit4] 2> 35672 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0_Lucene84_0.tmd did not match. expected checksum is 1175641159 and actual is checksum 184156906. expected length is 255 and actual length is 255
[junit4] 2> 35672 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0_Lucene84_0.tmd did not match. expected checksum is 96608322 and actual is checksum 1227554543. expected length is 255 and actual length is 255
[junit4] 2> 35677 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0.kdd did not match. expected checksum is 1528203996 and actual is checksum 164541679. expected length is 292 and actual length is 292
[junit4] 2> 35678 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0.kdd did not match. expected checksum is 970863835 and actual is checksum 2911850077. expected length is 280 and actual length is 280
[junit4] 2> 35682 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0_Asserting_0.tim did not match. expected checksum is 1204787616 and actual is checksum 886674257. expected length is 2099 and actual length is 2099
[junit4] 2> 35683 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0_Asserting_0.doc did not match. expected checksum is 2725953156 and actual is checksum 3447194901. expected length is 128 and actual length is 128
[junit4] 2> 35687 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0_Asserting_0.doc did not match. expected checksum is 3624195246 and actual is checksum 3454668889. expected length is 131 and actual length is 131
[junit4] 2> 35688 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0_Asserting_0.tim did not match. expected checksum is 542026796 and actual is checksum 55726552. expected length is 1985 and actual length is 1985
[junit4] 2> 35697 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0_Asserting_0.tip did not match. expected checksum is 3956575975 and actual is checksum 105012494. expected length is 74 and actual length is 74
[junit4] 2> 35698 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0_Asserting_0.tip did not match. expected checksum is 1890018355 and actual is checksum 2637281242. expected length is 74 and actual length is 74
[junit4] 2> 35702 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0.fnm did not match. expected checksum is 719895683 and actual is checksum 1812681452. expected length is 362 and actual length is 362
[junit4] 2> 35703 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0.fnm did not match. expected checksum is 3827419284 and actual is checksum 2730632955. expected length is 362 and actual length is 362
[junit4] 2> 35707 WARN (parallelCoreAdminExecutor-50-thread-2-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351049078 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard1 r:core_node2 x:backuprestore_testbackuprestoretooriginal_shard1_replica_n1] o.a.s.h.IndexFetcher File _0.nvm did not match. expected checksum is 387822997 and actual is checksum 3872113448. expected length is 175 and actual length is 175
[junit4] 2> 35708 WARN (parallelCoreAdminExecutor-50-thread-1-processing-n:127.0.0.1:34611_solr x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5 4f512f5c-3f68-49ff-9b14-e4ef40d775127802100351177042 RESTORECORE) [n:127.0.0.1:34611_solr c:backuprestore_testbackuprestoretooriginal s:shard2 r:core_node7 x:backuprestore_testbackuprestoretooriginal_shard2_replica_n5] o.a.s.h.IndexFetcher File _0.nvm did not match. expected checksum is 1557083718 and actual is checksum 2904113403. expected length is 175 and actual length is 175
[junit4] 2> 49285 ERROR (qtp736472456-233) [ ] c.a.t.s.d.FileStore File can not be read
[junit4] 2> com.fasterxml.jackson.databind.exc.MismatchedInputException: No content to map due to end-of-input
[junit4] 2> at [Source: (File); line: 1, column: 0]
[junit4] 2> at com.fasterxml.jackson.databind.exc.MismatchedInputException.from(MismatchedInputException.java:59) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._initForReading(ObjectMapper.java:4688) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:4586) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:3413) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.adobe.testing.s3mock.domain.FileStore.getS3Object(FileStore.java:510) ~[s3mock-2.1.34.jar:?]
[junit4] 2> at com.adobe.testing.s3mock.FileStoreController.headObject(FileStoreController.java:257) ~[s3mock-2.1.34.jar:?]
[junit4] 2> at sun.reflect.GeneratedMethodAccessor81.invoke(Unknown Source) ~[?:?]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_291]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_291]
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:190) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:138) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:105) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:878) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:792) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:87) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1040) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:943) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1006) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:898) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at javax.servlet.http.HttpServlet.doHead(HttpServlet.java:288) ~[javax.servlet-api-3.1.0.jar:3.1.0]
[junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:704) ~[javax.servlet-api-3.1.0.jar:3.1.0]
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:883) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) ~[javax.servlet-api-3.1.0.jar:3.1.0]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1631) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at com.adobe.testing.s3mock.KmsValidationFilter.doFilterInternal(KmsValidationFilter.java:91) ~[s3mock-2.1.34.jar:?]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:100) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:103) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.boot.actuate.metrics.web.servlet.WebMvcMetricsFilter.doFilterInternal(WebMvcMetricsFilter.java:97) ~[spring-boot-actuator-2.3.12.RELEASE.jar:2.3.12.RELEASE]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:201) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600) ~[jetty-security-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:400) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:645) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:392) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291]
[junit4] 2> com.fasterxml.jackson.databind.exc.MismatchedInputException: No content to map due to end-of-input
[junit4] 2> at [Source: (File); line: 1, column: 0]
[junit4] 2> at com.fasterxml.jackson.databind.exc.MismatchedInputException.from(MismatchedInputException.java:59)
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._initForReading(ObjectMapper.java:4688)
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:4586)
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:3413)
[junit4] 2> at com.adobe.testing.s3mock.domain.FileStore.getS3Object(FileStore.java:510)
[junit4] 2> at com.adobe.testing.s3mock.FileStoreController.headObject(FileStoreController.java:257)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor81.invoke(Unknown Source)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:190)
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:138)
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:105)
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:878)
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:792)
[junit4] 2> at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:87)
[junit4] 2> at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1040)
[junit4] 2> at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:943)
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1006)
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:898)
[junit4] 2> at javax.servlet.http.HttpServlet.doHead(HttpServlet.java:288)
[junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:704)
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:883)
[junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1631)
[junit4] 2> at com.adobe.testing.s3mock.KmsValidationFilter.doFilterInternal(KmsValidationFilter.java:91)
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:100)
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:103)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.springframework.boot.actuate.metrics.web.servlet.WebMvcMetricsFilter.doFilterInternal(WebMvcMetricsFilter.java:97)
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:201)
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
[junit4] 2> at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:400)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:645)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:392)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 52340 ERROR (qtp736472456-231) [ ] c.a.t.s.d.FileStore File can not be read
[junit4] 2> com.fasterxml.jackson.databind.exc.MismatchedInputException: No content to map due to end-of-input
[junit4] 2> at [Source: (File); line: 1, column: 0]
[junit4] 2> at com.fasterxml.jackson.databind.exc.MismatchedInputException.from(MismatchedInputException.java:59) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._initForReading(ObjectMapper.java:4688) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:4586) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:3413) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.adobe.testing.s3mock.domain.FileStore.getS3Object(FileStore.java:510) ~[s3mock-2.1.34.jar:?]
[junit4] 2> at com.adobe.testing.s3mock.FileStoreController.headObject(FileStoreController.java:257) ~[s3mock-2.1.34.jar:?]
[junit4] 2> at sun.reflect.GeneratedMethodAccessor81.invoke(Unknown Source) ~[?:?]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_291]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_291]
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:190) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:138) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:105) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:878) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:792) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:87) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1040) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:943) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1006) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:898) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at javax.servlet.http.HttpServlet.doHead(HttpServlet.java:288) ~[javax.servlet-api-3.1.0.jar:3.1.0]
[junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:704) ~[javax.servlet-api-3.1.0.jar:3.1.0]
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:883) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) ~[javax.servlet-api-3.1.0.jar:3.1.0]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1631) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at com.adobe.testing.s3mock.KmsValidationFilter.doFilterInternal(KmsValidationFilter.java:91) ~[s3mock-2.1.34.jar:?]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:100) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:103) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.boot.actuate.metrics.web.servlet.WebMvcMetricsFilter.doFilterInternal(WebMvcMetricsFilter.java:97) ~[spring-boot-actuator-2.3.12.RELEASE.jar:2.3.12.RELEASE]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:201) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600) ~[jetty-security-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:400) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:645) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:392) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291]
[junit4] 2> com.fasterxml.jackson.databind.exc.MismatchedInputException: No content to map due to end-of-input
[junit4] 2> at [Source: (File); line: 1, column: 0]
[junit4] 2> at com.fasterxml.jackson.databind.exc.MismatchedInputException.from(MismatchedInputException.java:59)
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._initForReading(ObjectMapper.java:4688)
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:4586)
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:3413)
[junit4] 2> at com.adobe.testing.s3mock.domain.FileStore.getS3Object(FileStore.java:510)
[junit4] 2> at com.adobe.testing.s3mock.FileStoreController.headObject(FileStoreController.java:257)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor81.invoke(Unknown Source)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:190)
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:138)
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:105)
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:878)
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:792)
[junit4] 2> at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:87)
[junit4] 2> at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1040)
[junit4] 2> at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:943)
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1006)
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:898)
[junit4] 2> at javax.servlet.http.HttpServlet.doHead(HttpServlet.java:288)
[junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:704)
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:883)
[junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:790)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1631)
[junit4] 2> at com.adobe.testing.s3mock.KmsValidationFilter.doFilterInternal(KmsValidationFilter.java:91)
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:100)
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:103)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.springframework.boot.actuate.metrics.web.servlet.WebMvcMetricsFilter.doFilterInternal(WebMvcMetricsFilter.java:97)
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:201)
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143)
[junit4] 2> at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:400)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:645)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:392)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131)
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 52347 ERROR (qtp736472456-233) [ ] c.a.t.s.d.FileStore File can not be read
[junit4] 2> com.fasterxml.jackson.databind.exc.MismatchedInputException: No content to map due to end-of-input
[junit4] 2> at [Source: (File); line: 1, column: 0]
[junit4] 2> at com.fasterxml.jackson.databind.exc.MismatchedInputException.from(MismatchedInputException.java:59) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._initForReading(ObjectMapper.java:4688) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:4586) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:3413) ~[jackson-databind-2.12.3.jar:2.12.3]
[junit4] 2> at com.adobe.testing.s3mock.domain.FileStore.getS3Object(FileStore.java:510) ~[s3mock-2.1.34.jar:?]
[junit4] 2> at com.adobe.testing.s3mock.FileStoreController.headObject(FileStoreController.java:257) ~[s3mock-2.1.34.jar:?]
[junit4] 2> at sun.reflect.GeneratedMethodAccessor81.invoke(Unknown Source) ~[?:?]
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) ~[?:1.8.0_291]
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498) ~[?:1.8.0_291]
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:190) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:138) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:105) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.invokeHandlerMethod(RequestMappingHandlerAdapter.java:878) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.RequestMappingHandlerAdapter.handleInternal(RequestMappingHandlerAdapter.java:792) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.mvc.method.AbstractHandlerMethodAdapter.handle(AbstractHandlerMethodAdapter.java:87) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.DispatcherServlet.doDispatch(DispatcherServlet.java:1040) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.DispatcherServlet.doService(DispatcherServlet.java:943) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.processRequest(FrameworkServlet.java:1006) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.doGet(FrameworkServlet.java:898) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at javax.servlet.http.HttpServlet.doHead(HttpServlet.java:288) ~[javax.servlet-api-3.1.0.jar:3.1.0]
[junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:704) ~[javax.servlet-api-3.1.0.jar:3.1.0]
[junit4] 2> at org.springframework.web.servlet.FrameworkServlet.service(FrameworkServlet.java:883) ~[spring-webmvc-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at javax.servlet.http.HttpServlet.service(HttpServlet.java:790) ~[javax.servlet-api-3.1.0.jar:3.1.0]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHolder.handle(ServletHolder.java:799) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$ChainEnd.doFilter(ServletHandler.java:1631) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at com.adobe.testing.s3mock.KmsValidationFilter.doFilterInternal(KmsValidationFilter.java:91) ~[s3mock-2.1.34.jar:?]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.web.filter.RequestContextFilter.doFilterInternal(RequestContextFilter.java:100) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:103) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.boot.actuate.metrics.web.servlet.WebMvcMetricsFilter.doFilterInternal(WebMvcMetricsFilter.java:97) ~[spring-boot-actuator-2.3.12.RELEASE.jar:2.3.12.RELEASE]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.springframework.web.filter.CharacterEncodingFilter.doFilterInternal(CharacterEncodingFilter.java:201) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.springframework.web.filter.OncePerRequestFilter.doFilter(OncePerRequestFilter.java:119) ~[spring-web-5.2.15.RELEASE.jar:5.2.15.RELEASE]
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:143) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.security.SecurityHandler.handle(SecurityHandler.java:600) ~[jetty-security-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:235) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501) ~[jetty-servlet-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:400) ~[jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:645) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:392) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277) [jetty-server-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104) [jetty-io-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.runTask(EatWhatYouKill.java:338) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.doProduce(EatWhatYouKill.java:315) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.tryProduce(EatWhatYouKill.java:173) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.EatWhatYouKill.run(EatWhatYouKill.java:131) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.ReservedThreadExecutor$ReservedThread.run(ReservedThreadExecutor.java:409) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034) [jetty-util-9.4.44.v20210927.jar:9.4.44.v20210927]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291]
[junit4] 2> com.fasterxml.jackson.databind.exc.MismatchedInputException: No content to map due to end-of-input
[junit4] 2> at [Source: (File); line: 1, column: 0]
[junit4] 2> at com.fasterxml.jackson.databind.exc.MismatchedInputException.from(MismatchedInputException.java:59)
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._initForReading(ObjectMapper.java:4688)
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper._readMapAndClose(ObjectMapper.java:4586)
[junit4] 2> at com.fasterxml.jackson.databind.ObjectMapper.readValue(ObjectMapper.java:3413)
[junit4] 2> at com.adobe.testing.s3mock.domain.FileStore.getS3Object(FileStore.java:510)
[junit4] 2> at com.adobe.testing.s3mock.FileStoreController.headObject(FileStoreController.java:257)
[junit4] 2> at sun.reflect.GeneratedMethodAccessor81.invoke(Unknown Source)
[junit4] 2> at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] 2> at java.lang.reflect.Method.invoke(Method.java:498)
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.doInvoke(InvocableHandlerMethod.java:190)
[junit4] 2> at org.springframework.web.method.support.InvocableHandlerMethod.invokeForRequest(InvocableHandlerMethod.java:138)
[junit4] 2> at org.springframework.web.servlet.mvc.method.annotation.ServletInvocableHandlerMethod.invokeAndHandle(ServletInvocableHandlerMethod.java:105)
[junit4] 2> at org
[...truncated too long message...]
ections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:271) ~[java/:?]
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:524) ~[java/:?]
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218) ~[java/:?]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149) [?:1.8.0_291]
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624) [?:1.8.0_291]
[junit4] 2> at java.lang.Thread.run(Thread.java:748) [?:1.8.0_291]
[junit4] 2> 53544 ERROR (OverseerThreadFactory-49-thread-5-processing-n:127.0.0.1:34611_solr) [n:127.0.0.1:34611_solr c:backuprestore_testbackupinc ] o.a.s.s.S3StorageClient An AmazonServiceException was thrown! [serviceName=S3] [awsRequestId=] [httpStatus=404] [s3ErrorCode=NoSuchKey] [message=The specified key does not exist.]
[junit4] 2> 53546 ERROR (OverseerThreadFactory-49-thread-5-processing-n:127.0.0.1:34611_solr) [n:127.0.0.1:34611_solr c:backuprestore_testbackupinc ] o.a.s.c.a.c.OverseerCollectionMessageHandler Collection: backuprestore_testbackupinc operation: backup failed:org.apache.solr.common.SolrException: Could not backup all shards
[junit4] 2> at org.apache.solr.cloud.api.collections.BackupCmd.incrementalCopyIndexFiles(BackupCmd.java:254)
[junit4] 2> at org.apache.solr.cloud.api.collections.BackupCmd.call(BackupCmd.java:105)
[junit4] 2> at org.apache.solr.cloud.api.collections.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:271)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:524)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:218)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=S3IncrementalBackupTest -Dtests.method=testBackupIncremental -Dtests.seed=863AAA8559AFBE2 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=el-GR -Dtests.timezone=Antarctica/DumontDUrville -Dtests.asserts=true -Dtests.file.encoding=ISO-8859-1
[junit4] FAILURE 7.68s J2 | S3IncrementalBackupTest.testBackupIncremental <<<
[junit4] > Throwable #1: java.lang.AssertionError: expected:<COMPLETED> but was:<FAILED>
[junit4] > at __randomizedtesting.SeedInfo.seed([863AAA8559AFBE2:FA605636972C59F]:0)
[junit4] > at org.apache.solr.cloud.api.collections.AbstractIncrementalBackupTest$IncrementalBackupVerifier.backupThenWait(AbstractIncrementalBackupTest.java:471)
[junit4] > at org.apache.solr.cloud.api.collections.AbstractIncrementalBackupTest$IncrementalBackupVerifier.incrementalBackupThenVerify(AbstractIncrementalBackupTest.java:487)
[junit4] > at org.apache.solr.cloud.api.collections.AbstractIncrementalBackupTest.backupRestoreThenCheck(AbstractIncrementalBackupTest.java:373)
[junit4] > at org.apache.solr.cloud.api.collections.AbstractIncrementalBackupTest.testBackupIncremental(AbstractIncrementalBackupTest.java:246)
[junit4] > at com.adobe.testing.s3mock.junit4.S3MockRule$1.evaluate(S3MockRule.java:66)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 54378 WARN (jetty-launcher-9-thread-2-SendThread(127.0.0.1:35931)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x101d10a68a50007.
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x101d10a68a50007, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doTransport(ClientCnxnSocketNIO.java:350) ~[zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> at org.apache.zookeeper.ClientCnxn$SendThread.run(ClientCnxn.java:1275) [zookeeper-3.6.2.jar:3.6.2]
[junit4] 2> 54480 INFO (jetty-closer-197-thread-2-processing-n:127.0.0.1:39765_solr) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@45f56bac{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 54481 INFO (jetty-closer-197-thread-2-processing-n:127.0.0.1:39765_solr) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@421dfd26{/solr,null,STOPPED}
[junit4] 2> 54481 INFO (jetty-closer-197-thread-2-processing-n:127.0.0.1:39765_solr) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 54580 INFO (jetty-closer-197-thread-1-processing-n:127.0.0.1:39765_solr) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@69d6bf18{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 54581 INFO (jetty-closer-197-thread-1-processing-n:127.0.0.1:39765_solr) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@50074227{/solr,null,STOPPED}
[junit4] 2> 54581 INFO (jetty-closer-197-thread-1-processing-n:127.0.0.1:39765_solr) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 54786 WARN (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 7 /solr/collections/backuprestore_testbackupinc/terms/shard2
[junit4] 2> 7 /solr/collections/backuprestore_testbackupinc/terms/shard1
[junit4] 2> 7 /solr/collections/backuprestore_testbackuprestoretooriginal/terms/shard2
[junit4] 2> 7 /solr/collections/backuprestore_testbackuprestoretooriginal/terms/shard1
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 3 /solr/collections/backuprestore_testbackupincsimple_restore/terms/shard2
[junit4] 2> 3 /solr/aliases.json
[junit4] 2> 3 /solr/collections/backuprestore_testbackupincsimple_restore/terms/shard1
[junit4] 2> 3 /solr/collections/backuprestore_testbackupincsimple/terms/shard2
[junit4] 2> 3 /solr/collections/backuprestore_testbackupincsimple/terms/shard1
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/backuprestore_testbackupincsimple/collectionprops.json
[junit4] 2> 2 /solr/configs/conf1
[junit4] 2> 2 /solr/collections/backuprestore_testbackuprestoretooriginal/collectionprops.json
[junit4] 2> 2 /solr/collections/backuprestore_testbackupinc/collectionprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 38 /solr/collections/backuprestore_testbackuprestoretooriginal/state.json
[junit4] 2> 36 /solr/collections/backuprestore_testbackupinc/state.json
[junit4] 2> 31 /solr/collections/backuprestore_testbackupincsimple_restore/state.json
[junit4] 2> 30 /solr/collections/backuprestore_testbackupincsimple/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 84 /solr/overseer/queue
[junit4] 2> 28 /solr/overseer/collection-queue-work
[junit4] 2> 15 /solr/collections
[junit4] 2> 11 /solr/collections/backuprestore_testbackupinc/state.json
[junit4] 2> 9 /solr/collections/backuprestore_testbackupincsimple_restore/state.json
[junit4] 2> 8 /solr/collections/backuprestore_testbackuprestoretooriginal/state.json
[junit4] 2> 8 /solr/collections/backuprestore_testbackupincsimple/state.json
[junit4] 2> 7 /solr/live_nodes
[junit4] 2>
[junit4] 2> 54797 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [n:127.0.0.1:39765_solr ] o.e.j.s.AbstractConnector Stopped SslValidatingServerConnector@2a4d008e{SSL, (ssl, http/1.1)}{0.0.0.0:0}
[junit4] 2> 54798 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [n:127.0.0.1:39765_solr ] o.e.j.s.AbstractConnector Stopped ServerConnector@543ea8c3{HTTP/1.1, (http/1.1)}{0.0.0.0:0}
[junit4] 2> 54798 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [n:127.0.0.1:39765_solr ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 54798 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [n:127.0.0.1:39765_solr ] o.e.j.s.h.C.application Destroying Spring FrameworkServlet 'dispatcherServlet'
[junit4] 2> 54798 INFO (SUITE-S3IncrementalBackupTest-seed#[863AAA8559AFBE2]-worker) [n:127.0.0.1:39765_solr ] o.e.j.s.h.ContextHandler Stopped o.s.b.w.e.j.JettyEmbeddedWebAppContext@7f44d217{application,/,[file:///home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/contrib/solr-s3-repository/test/J2/temp/jetty-docbase.0.2565214640512800381/],STOPPED}
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/contrib/solr-s3-repository/test/J2/temp/solr.s3.S3IncrementalBackupTest_863AAA8559AFBE2-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {shard_s=PostingsFormat(name=Asserting), _root_=PostingsFormat(name=Asserting), id=Lucene84}, docValues:{}, maxPointsInLeafNode=252, maxMBSortInHeap=7.658557514792603, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=el-GR, timezone=Antarctica/DumontDUrville
[junit4] 2> NOTE: Linux 4.15.0-147-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=260128736,total=504365056
[junit4] 2> NOTE: All tests run in this JVM: [S3ReadWriteTest, S3IncrementalBackupTest]
[junit4] Completed [7/7 (1!)] on J2 in 35.78s, 3 tests, 1 failure <<< FAILURES!
[...truncated 44702 lines...]
[JENKINS] Lucene » Lucene-Solr-Tests-8.x - Build # 3338 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.x/3338/
2 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.handler.designer.TestSchemaDesignerAPI
Error Message:
Timed out waiting for all collections to be fully removed.
Stack Trace:
java.util.concurrent.TimeoutException: Timed out waiting for all collections to be fully removed.
at __randomizedtesting.SeedInfo.seed([A62F13C84E8B18A6]:0)
at org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:581)
at org.apache.solr.handler.designer.TestSchemaDesignerAPI.tearDownCluster(TestSchemaDesignerAPI.java:82)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:498)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1750)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:901)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:45)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:41)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:40)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:53)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:47)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:64)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:54)
at org.junit.rules.RunRules.evaluate(RunRules.java:20)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.lang.Thread.run(Thread.java:748)
FAILED: junit.framework.TestSuite.org.apache.solr.handler.designer.TestSchemaDesignerAPI
Error Message:
Captured an uncaught exception in thread: Thread[id=35067, name=Thread-6949, state=RUNNABLE, group=TGRP-TestSchemaDesignerAPI]
Stack Trace:
com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=35067, name=Thread-6949, state=RUNNABLE, group=TGRP-TestSchemaDesignerAPI]
Caused by: java.lang.AssertionError
at __randomizedtesting.SeedInfo.seed([A62F13C84E8B18A6]:0)
at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1750)
at org.apache.solr.core.SolrCore.lambda$getConfListener$19(SolrCore.java:3147)
at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$15(ZkController.java:2630)
at java.lang.Thread.run(Thread.java:748)
Build Log:
[...truncated 16019 lines...]
[junit4] Suite: org.apache.solr.handler.designer.TestSchemaDesignerAPI
[junit4] 2> 2174140 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.SolrTestCase Setting 'solr.default.confdir' system property to test-framework derived value of '/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2174140 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2174140 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/data-dir-165-001
[junit4] 2> 2174141 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2174143 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 2174143 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/tempDir-001
[junit4] 2> 2174143 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 2174143 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 2174143 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 2174144 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 2174243 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port: 38693
[junit4] 2> 2174243 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:38693
[junit4] 2> 2174243 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38693
[junit4] 2> 2174243 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38693
[junit4] 2> 2174246 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2174248 INFO (zkConnectionManagerCallback-19425-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2174249 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2174252 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2174254 INFO (zkConnectionManagerCallback-19427-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2174254 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2174255 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2174255 INFO (zkConnectionManagerCallback-19429-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2174255 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2174368 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2174368 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2174368 INFO (jetty-launcher-19430-thread-1) [ ] o.e.j.s.Server jetty-9.4.44.v20210927; built: 2021-09-27T23:02:44.612Z; git: 8da83308eeca865e495e53ef315a249d63ba9332; jvm 1.8.0_291-b10
[junit4] 2> 2174370 INFO (jetty-launcher-19430-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2174370 INFO (jetty-launcher-19430-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2174370 INFO (jetty-launcher-19430-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 2174370 INFO (jetty-launcher-19430-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@210574c7{/solr,null,AVAILABLE}
[junit4] 2> 2174371 INFO (jetty-launcher-19430-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@22e5bc44{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:46833}
[junit4] 2> 2174371 INFO (jetty-launcher-19430-thread-1) [ ] o.e.j.s.Server Started @2174400ms
[junit4] 2> 2174371 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:38693/solr, hostPort=46833}
[junit4] 2> 2174371 ERROR (jetty-launcher-19430-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2174371 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2174371 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.11.0
[junit4] 2> 2174371 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 2174371 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr
[junit4] 2> 2174371 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-10-13T06:07:30.079Z
[junit4] 2> 2174372 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2174374 INFO (zkConnectionManagerCallback-19432-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2174374 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2174374 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 2174379 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.c.SolrXmlConfig Metrics collection is disabled.
[junit4] 2> 2175526 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 2175526 WARN (jetty-launcher-19430-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4218403d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2175527 WARN (jetty-launcher-19430-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4218403d[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2175529 WARN (jetty-launcher-19430-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3080b62c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2175529 WARN (jetty-launcher-19430-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3080b62c[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2175530 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38693/solr
[junit4] 2> 2175531 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2175532 INFO (zkConnectionManagerCallback-19443-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2175532 INFO (jetty-launcher-19430-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2175634 INFO (jetty-launcher-19430-thread-1) [n:127.0.0.1:46833_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2175635 INFO (zkConnectionManagerCallback-19445-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2175635 INFO (jetty-launcher-19430-thread-1) [n:127.0.0.1:46833_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2175749 WARN (jetty-launcher-19430-thread-1) [n:127.0.0.1:46833_solr ] o.a.s.c.ZkController Contents of zookeeper /security.json are world-readable; consider setting up ACLs as described in https://solr.apache.org/guide/zookeeper-access-control.html
[junit4] 2> 2175759 INFO (jetty-launcher-19430-thread-1) [n:127.0.0.1:46833_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:46833_solr
[junit4] 2> 2175760 INFO (jetty-launcher-19430-thread-1) [n:127.0.0.1:46833_solr ] o.a.s.c.Overseer Overseer (id=74361279027019781-127.0.0.1:46833_solr-n_0000000000) starting
[junit4] 2> 2175767 INFO (OverseerStateUpdate-74361279027019781-127.0.0.1:46833_solr-n_0000000000) [n:127.0.0.1:46833_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:46833_solr
[junit4] 2> 2175767 INFO (jetty-launcher-19430-thread-1) [n:127.0.0.1:46833_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46833_solr
[junit4] 2> 2175769 INFO (OverseerStateUpdate-74361279027019781-127.0.0.1:46833_solr-n_0000000000) [n:127.0.0.1:46833_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2175773 INFO (jetty-launcher-19430-thread-1) [n:127.0.0.1:46833_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 2175774 WARN (jetty-launcher-19430-thread-1) [n:127.0.0.1:46833_solr ] o.a.s.c.CoreContainer Not all security plugins configured! authentication=disabled authorization=disabled. Solr is only as secure as you make it. Consider configuring authentication/authorization before exposing Solr to users internal or external. See https://s.apache.org/solrsecurity for more info
[junit4] 2> 2175822 INFO (jetty-launcher-19430-thread-1) [n:127.0.0.1:46833_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/tempDir-001/node1
[junit4] 2> 2175835 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=1
[junit4] 2> 2175835 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2175836 INFO (zkConnectionManagerCallback-19460-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2175836 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2175838 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2175839 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38693/solr ready
[junit4] 2> 2175995 INFO (OverseerThreadFactory-19452-thread-1-processing-n:127.0.0.1:46833_solr) [n:127.0.0.1:46833_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection .system
[junit4] 2> 2176111 INFO (OverseerStateUpdate-74361279027019781-127.0.0.1:46833_solr-n_0000000000) [n:127.0.0.1:46833_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":".system_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46833_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46833/solr",
[junit4] 2> "collection":".system",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2176314 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr x:.system_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2176315 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr x:.system_shard1_replica_n1 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient core cache for max 2147483647 cores with initial capacity of 1024
[junit4] 2> 2176333 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 2176334 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory The schema is configured as managed, but managed schema resource managed-schema not found - loading non-managed schema schema.xml instead
[junit4] 2> 2176338 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=_system collection or core
[junit4] 2> 2176339 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema _system collection or core/1.1 with uniqueid field id
[junit4] 2> 2176340 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchema Created and persisted managed schema znode at /configs/.system/managed-schema
[junit4] 2> 2176344 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ManagedIndexSchemaFactory After upgrading to managed schema in ZooKeeper, renamed the non-managed schema /configs/.system/schema.xml to /configs/.system/schema.xml.bak
[junit4] 2> 2176345 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore '.system_shard1_replica_n1' using configuration from configset .system, trusted=true
[junit4] 2> 2176346 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [[.system_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/tempDir-001/node1/.system_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/tempDir-001/node1/.system_shard1_replica_n1/data/]
[junit4] 2> 2176377 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2176377 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2176377 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if 1 uncommitted docs;
[junit4] 2> 2176377 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2176381 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/.system
[junit4] 2> 2176381 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/.system
[junit4] 2> 2176381 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/.system/managed-schema
[junit4] 2> 2176382 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 2176382 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2176382 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1713483548815523840
[junit4] 2> 2176386 INFO (searcherExecutor-19462-thread-1-processing-n:127.0.0.1:46833_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2176402 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2176402 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/.system/leaders/shard1
[junit4] 2> 2176408 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2176408 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2176408 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46833/solr/.system_shard1_replica_n1/
[junit4] 2> 2176410 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2176411 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:46833/solr/.system_shard1_replica_n1/ has no replicas
[junit4] 2> 2176411 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/.system/leaders/shard1/leader after winning as /collections/.system/leader_elect/shard1/election/74361279027019781-core_node2-n_0000000000
[junit4] 2> 2176414 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:46833/solr/.system_shard1_replica_n1/ shard1
[junit4] 2> 2176516 INFO (zkCallback-19444-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2176516 INFO (zkCallback-19444-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2176516 INFO (zkCallback-19444-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2176519 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2176525 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=.system&newCollection=true&name=.system_shard1_replica_n1&action=CREATE&numShards=1&collection=.system&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=211
[junit4] 2> 2176531 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2176623 INFO (zkCallback-19444-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2176623 INFO (zkCallback-19444-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2176623 INFO (zkCallback-19444-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2176623 INFO (zkCallback-19444-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/.system/state.json] for collection [.system] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2176624 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=.system&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&wt=javabin&version=2} status=0 QTime=637
[junit4] 2> 2176624 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.MiniSolrCloudCluster waitForActiveCollection: .system
[junit4] 2> 2176645 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testBasicUserWorkflow
[junit4] 2> 2176720 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/._designer_testJson
[junit4] 2> 2176722 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/._designer_testJson/protwords.txt
[junit4] 2> 2176724 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/._designer_testJson/managed-schema
[junit4] 2> 2176727 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/._designer_testJson/solrconfig.xml
[junit4] 2> 2176730 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/._designer_testJson/lang/contractions_it.txt
[junit4] 2> 2176733 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/._designer_testJson/lang/contractions_ca.txt
[junit4] 2> 2176735 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/._designer_testJson/lang/stopwords_no.txt
[junit4] 2> 2176738 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/._designer_testJson/lang/stemdict_nl.txt
[junit4] 2> 2176741 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/._designer_testJson/lang/stopwords_hy.txt
[junit4] 2> 2176743 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/._designer_testJson/lang/stopwords_id.txt
[junit4] 2> 2176745 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/._designer_testJson/lang/stopwords_et.txt
[junit4] 2> 2176747 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/._designer_testJson/lang/stopwords_hi.txt
[junit4] 2> 2176749 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/._designer_testJson/lang/stopwords_da.txt
[junit4] 2> 2176752 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/._designer_testJson/lang/stopwords_ga.txt
[junit4] 2> 2176758 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/._designer_testJson/lang/stopwords_ja.txt
[junit4] 2> 2176762 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/._designer_testJson/lang/stopwords_pt.txt
[junit4] 2> 2176764 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/._designer_testJson/lang/stopwords_hu.txt
[junit4] 2> 2176768 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/._designer_testJson/lang/stopwords_el.txt
[junit4] 2> 2176772 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/._designer_testJson/lang/stopwords_ru.txt
[junit4] 2> 2176775 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/._designer_testJson/lang/stopwords_tr.txt
[junit4] 2> 2176777 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/._designer_testJson/lang/stopwords_ar.txt
[junit4] 2> 2176780 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/._designer_testJson/lang/userdict_ja.txt
[junit4] 2> 2176782 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/._designer_testJson/lang/stopwords_it.txt
[junit4] 2> 2176785 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/._designer_testJson/lang/stopwords_eu.txt
[junit4] 2> 2176787 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/._designer_testJson/lang/stopwords_cz.txt
[junit4] 2> 2176789 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/._designer_testJson/lang/stoptags_ja.txt
[junit4] 2> 2176791 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/._designer_testJson/lang/contractions_fr.txt
[junit4] 2> 2176792 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/._designer_testJson/lang/stopwords_fa.txt
[junit4] 2> 2176795 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/._designer_testJson/lang/stopwords_de.txt
[junit4] 2> 2176798 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/._designer_testJson/lang/contractions_ga.txt
[junit4] 2> 2176800 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/._designer_testJson/lang/stopwords_ca.txt
[junit4] 2> 2176802 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/._designer_testJson/lang/stopwords_nl.txt
[junit4] 2> 2176805 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/._designer_testJson/lang/stopwords_bg.txt
[junit4] 2> 2176808 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/._designer_testJson/lang/stopwords_es.txt
[junit4] 2> 2176811 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/._designer_testJson/lang/stopwords_en.txt
[junit4] 2> 2176824 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/._designer_testJson/lang/stopwords_lv.txt
[junit4] 2> 2176826 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/._designer_testJson/lang/stopwords_th.txt
[junit4] 2> 2176827 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/._designer_testJson/lang/stopwords_ro.txt
[junit4] 2> 2176830 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/._designer_testJson/lang/stopwords_fr.txt
[junit4] 2> 2176832 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/._designer_testJson/lang/stopwords_sv.txt
[junit4] 2> 2176834 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/._designer_testJson/lang/stopwords_fi.txt
[junit4] 2> 2176835 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/._designer_testJson/lang/hyphenations_ga.txt
[junit4] 2> 2176838 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/._designer_testJson/lang/stopwords_gl.txt
[junit4] 2> 2176840 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/._designer_testJson/synonyms.txt
[junit4] 2> 2176842 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/._designer_testJson/stopwords.txt
[junit4] 2> 2176851 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 2176851 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 2176855 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 2176930 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 2177092 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.ManagedIndexSchema Persisted managed schema version 1 at /configs/._designer_testJson/managed-schema
[junit4] 2> 2177094 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 2177096 INFO (zkConnectionManagerCallback-19471-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 2177097 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 2177099 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 2177100 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38693/solr ready
[junit4] 2> 2177106 INFO (OverseerCollectionConfigSetProcessor-74361279027019781-127.0.0.1:46833_solr-n_0000000000) [n:127.0.0.1:46833_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000000 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2177106 INFO (OverseerThreadFactory-19452-thread-2-processing-n:127.0.0.1:46833_solr) [n:127.0.0.1:46833_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection ._designer_testJson
[junit4] 2> 2177436 INFO (OverseerStateUpdate-74361279027019781-127.0.0.1:46833_solr-n_0000000000) [n:127.0.0.1:46833_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "core":"._designer_testJson_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:46833_solr",
[junit4] 2> "base_url":"http://127.0.0.1:46833/solr",
[junit4] 2> "collection":"._designer_testJson",
[junit4] 2> "shard":"shard1",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 2177639 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=._designer_testJson&newCollection=true&name=._designer_testJson_shard1_replica_n1&action=CREATE&numShards=1&collection=._designer_testJson&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 2177655 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 2177655 WARN (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 2177661 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 2177668 WARN (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitNorms to be specified in schema, hardcoded behavior is omitNorms=true
[junit4] 2> 2177668 WARN (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitTermFreqAndPositions to be specified in schema, hardcoded behavior is omitTermFreqAndPositions=true
[junit4] 2> 2177668 WARN (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termOffsets to be specified in schema, hardcoded behavior is termOffsets=false
[junit4] 2> 2177668 WARN (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termPositions to be specified in schema, hardcoded behavior is termPositions=false
[junit4] 2> 2177668 WARN (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitPositions to be specified in schema, hardcoded behavior is omitPositions=true
[junit4] 2> 2177765 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 2177857 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore '._designer_testJson_shard1_replica_n1' using configuration from configset ._designer_testJson, trusted=true
[junit4] 2> 2177857 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SolrCore [[._designer_testJson_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/tempDir-001/node1/._designer_testJson_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/tempDir-001/node1/._designer_testJson_shard1_replica_n1/data/]
[junit4] 2> 2177903 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2177903 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2177904 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 2177904 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2177905 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/._designer_testJson
[junit4] 2> 2177906 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/._designer_testJson
[junit4] 2> 2177906 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/._designer_testJson/managed-schema
[junit4] 2> 2177906 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 2177906 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2177906 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxQueryFrequency=0.01,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4}
[junit4] 2> 2177907 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2177908 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1713483550415650816
[junit4] 2> 2177911 INFO (searcherExecutor-19473-thread-1-processing-n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 c:._designer_testJson s:shard1 r:core_node2) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 2177912 INFO (searcherExecutor-19473-thread-1-processing-n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 c:._designer_testJson s:shard1 r:core_node2) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2177912 INFO (searcherExecutor-19473-thread-1-processing-n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 c:._designer_testJson s:shard1 r:core_node2) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SolrCore [._designer_testJson_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2177921 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/._designer_testJson/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 2177921 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/._designer_testJson/leaders/shard1
[junit4] 2> 2177927 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 2177927 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 2177927 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:46833/solr/._designer_testJson_shard1_replica_n1/
[junit4] 2> 2177928 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 2177929 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SyncStrategy http://127.0.0.1:46833/solr/._designer_testJson_shard1_replica_n1/ has no replicas
[junit4] 2> 2177929 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/._designer_testJson/leaders/shard1/leader after winning as /collections/._designer_testJson/leader_elect/shard1/election/74361279027019781-core_node2-n_0000000000
[junit4] 2> 2177935 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:46833/solr/._designer_testJson_shard1_replica_n1/ shard1
[junit4] 2> 2178036 INFO (zkCallback-19444-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/._designer_testJson/state.json] for collection [._designer_testJson] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2178036 INFO (zkCallback-19444-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/._designer_testJson/state.json] for collection [._designer_testJson] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2178037 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 2178043 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=._designer_testJson&newCollection=true&name=._designer_testJson_shard1_replica_n1&action=CREATE&numShards=1&collection=._designer_testJson&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=404
[junit4] 2> 2178046 INFO (qtp740448176-34800) [n:127.0.0.1:46833_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2178140 INFO (zkCallback-19444-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/._designer_testJson/state.json] for collection [._designer_testJson] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2178140 INFO (zkCallback-19444-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/._designer_testJson/state.json] for collection [._designer_testJson] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2178140 INFO (zkCallback-19444-thread-4) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/._designer_testJson/state.json] for collection [._designer_testJson] has occurred - updating... (live nodes size: [1])
[junit4] 2> 2178141 INFO (qtp740448176-34800) [n:127.0.0.1:46833_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={collection.configName=._designer_testJson&maxShardsPerNode=-1&name=._designer_testJson&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=1039
[junit4] 2> 2178141 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 45 seconds. Check all shard replicas
[junit4] 2> 2178145 INFO (Thread-6929) [ ] o.a.s.c.SolrCore config update listener called for core ._designer_testJson_shard1_replica_n1
[junit4] 2> 2178149 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.c.SolrCore core reload ._designer_testJson_shard1_replica_n1
[junit4] 2> 2178158 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 2178158 WARN (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 2178159 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 2178181 WARN (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitNorms to be specified in schema, hardcoded behavior is omitNorms=true
[junit4] 2> 2178181 WARN (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitTermFreqAndPositions to be specified in schema, hardcoded behavior is omitTermFreqAndPositions=true
[junit4] 2> 2178181 WARN (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termOffsets to be specified in schema, hardcoded behavior is termOffsets=false
[junit4] 2> 2178181 WARN (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termPositions to be specified in schema, hardcoded behavior is termPositions=false
[junit4] 2> 2178181 WARN (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitPositions to be specified in schema, hardcoded behavior is omitPositions=true
[junit4] 2> 2178198 ERROR (qtp740448176-34798) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: Invalid combination of blobName testJson_sample and version -1
[junit4] 2> at org.apache.solr.handler.BlobHandler.handleRequestBody(BlobHandler.java:203)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:216)
[junit4] 2> at org.apache.solr.core.SolrCore.execute(SolrCore.java:2637)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:794)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:567)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:427)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:357)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:166)
[junit4] 2> at org.eclipse.jetty.servlet.FilterHolder.doFilter(FilterHolder.java:193)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$Chain.doFilter(ServletHandler.java:1601)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:548)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:1624)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextHandle(ScopedHandler.java:233)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1434)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:188)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:501)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:1594)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.nextScope(ScopedHandler.java:186)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1349)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.rewrite.handler.RewriteHandler.handle(RewriteHandler.java:322)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:763)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:127)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:516)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.lambda$handle$1(HttpChannel.java:400)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.dispatch(HttpChannel.java:645)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:392)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:277)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:311)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:105)
[junit4] 2> at org.eclipse.jetty.io.ChannelEndPoint$1.run(ChannelEndPoint.java:104)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:883)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$Runner.run(QueuedThreadPool.java:1034)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 2178198 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.S.Request [.system_shard1_replica_n1] webapp=/solr path=/blob/testJson_sample params={wt=filestream} status=404 QTime=0
[junit4] 2> 2178202 INFO (qtp740448176-34795) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.BlobHandler inserting new blob testJson_sample/1 ,size 582, md5 070ace8bdbc547d9c3aa69ee5da31d8e
[junit4] 2> 2178203 INFO (qtp740448176-34795) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.BlobHandler Adding doc: {id=testJson_sample/1, type=blob, md5=070ace8bdbc547d9c3aa69ee5da31d8e, blobName=testJson_sample, version=1, timestamp=2021-10-13T02:07:33.908-0400, size=582, blob=java.nio.HeapByteBuffer[pos=0 lim=582 cap=1024]}
[junit4] 2> 2178205 INFO (qtp740448176-34795) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.BlobHandler committing doc: {id=testJson_sample/1, type=blob, md5=070ace8bdbc547d9c3aa69ee5da31d8e, blobName=testJson_sample, version=1, timestamp=2021-10-13T02:07:33.908-0400, size=582, blob=java.nio.HeapByteBuffer[pos=0 lim=582 cap=1024]}
[junit4] 2> 2178210 INFO (searcherExecutor-19462-thread-1-processing-n:127.0.0.1:46833_solr x:.system_shard1_replica_n1 c:.system s:shard1 r:core_node2) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.SolrCore [.system_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2178212 INFO (qtp740448176-34795) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/.system/terms/shard1 to Terms{values={core_node2=1}, version=1}
[junit4] 2> 2178212 INFO (qtp740448176-34795) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [.system_shard1_replica_n1] webapp=/solr path=/blob/testJson_sample params={}{add=[testJson_sample/1 (1713483550724980736)],commit=} 0 12
[junit4] 2> 2178212 INFO (qtp740448176-34795) [n:127.0.0.1:46833_solr c:.system s:shard1 r:core_node2 x:.system_shard1_replica_n1 ] o.a.s.h.BlobHandler Successfully Added and committed a blob with id testJson_sample/1 and size 582
[junit4] 2> 2178218 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 2178218 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 2178223 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 2178230 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitNorms to be specified in schema, hardcoded behavior is omitNorms=true
[junit4] 2> 2178230 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitTermFreqAndPositions to be specified in schema, hardcoded behavior is omitTermFreqAndPositions=true
[junit4] 2> 2178230 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termOffsets to be specified in schema, hardcoded behavior is termOffsets=false
[junit4] 2> 2178230 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termPositions to be specified in schema, hardcoded behavior is termPositions=false
[junit4] 2> 2178230 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitPositions to be specified in schema, hardcoded behavior is omitPositions=true
[junit4] 2> 2178279 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 2178353 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 2178405 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.c.CoreContainer Reloading SolrCore '._designer_testJson_shard1_replica_n1' using configuration from configset ._designer_testJson
[junit4] 2> 2178406 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.c.SolrCore [[._designer_testJson_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/tempDir-001/node1/._designer_testJson_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/tempDir-001/node1/._designer_testJson_shard1_replica_n1/data/]
[junit4] 2> 2178442 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 2178442 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2178446 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/._designer_testJson
[junit4] 2> 2178447 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/._designer_testJson
[junit4] 2> 2178447 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/._designer_testJson/managed-schema
[junit4] 2> 2178448 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.s.ZkIndexSchemaReader Current schema version 1 is already the latest
[junit4] 2> 2178448 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2178448 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxQueryFrequency=0.01,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4}
[junit4] 2> 2178449 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2178450 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1713483550983979008
[junit4] 2> 2178452 INFO (searcherExecutor-19479-thread-1-processing-n:127.0.0.1:46833_solr) [n:127.0.0.1:46833_solr ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 2178452 INFO (searcherExecutor-19479-thread-1-processing-n:127.0.0.1:46833_solr) [n:127.0.0.1:46833_solr ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2178452 INFO (searcherExecutor-19479-thread-1-processing-n:127.0.0.1:46833_solr) [n:127.0.0.1:46833_solr ] o.a.s.c.SolrCore [._designer_testJson_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2178453 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 2178454 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.c.SolrCore [._designer_testJson_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@747dd7cf
[junit4] 2> 2178454 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.._designer_testJson.shard1.replica_n1 tag=SolrCore@747dd7cf
[junit4] 2> 2178454 INFO (Thread-6929) [n:127.0.0.1:46833_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.._designer_testJson.shard1.leader tag=SolrCore@747dd7cf
[junit4] 2> 2178454 INFO (searcherExecutor-19479-thread-1-processing-n:127.0.0.1:46833_solr) [n:127.0.0.1:46833_solr ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 2178454 INFO (searcherExecutor-19479-thread-1-processing-n:127.0.0.1:46833_solr) [n:127.0.0.1:46833_solr ] o.a.s.c.SolrCore [._designer_testJson_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2178546 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.ManagedIndexSchema Persisted managed schema version 2 at /configs/._designer_testJson/managed-schema
[junit4] 2> 2178546 INFO (zkCallback-19444-thread-2) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/._designer_testJson/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 2178546 INFO (zkCallback-19444-thread-2) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 2 from Zookeeper
[junit4] 2> 2178548 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.ManagedIndexSchema Persisted managed schema version 3 at /configs/._designer_testJson/managed-schema
[junit4] 2> 2178549 INFO (zkCallback-19444-thread-2) [ ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 2178550 INFO (zkCallback-19444-thread-4) [ ] o.a.s.s.ZkIndexSchemaReader A schema change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/configs/._designer_testJson/managed-schema, has occurred - updating schema from ZooKeeper ...
[junit4] 2> 2178551 WARN (zkCallback-19444-thread-2) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitNorms to be specified in schema, hardcoded behavior is omitNorms=true
[junit4] 2> 2178551 WARN (zkCallback-19444-thread-2) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitTermFreqAndPositions to be specified in schema, hardcoded behavior is omitTermFreqAndPositions=true
[junit4] 2> 2178551 WARN (zkCallback-19444-thread-2) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termOffsets to be specified in schema, hardcoded behavior is termOffsets=false
[junit4] 2> 2178551 WARN (zkCallback-19444-thread-2) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termPositions to be specified in schema, hardcoded behavior is termPositions=false
[junit4] 2> 2178551 WARN (zkCallback-19444-thread-2) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitPositions to be specified in schema, hardcoded behavior is omitPositions=true
[junit4] 2> 2178552 INFO (OverseerThreadFactory-19452-thread-3-processing-n:127.0.0.1:46833_solr) [n:127.0.0.1:46833_solr ] o.a.s.c.a.c.OverseerCollectionMessageHandler Executing Collection Cmd=action=RELOAD, asyncId=null
[junit4] 2> 2178552 INFO (OverseerCollectionConfigSetProcessor-74361279027019781-127.0.0.1:46833_solr-n_0000000000) [n:127.0.0.1:46833_solr ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 2178570 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 2178570 WARN (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 2178577 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 2178584 WARN (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitNorms to be specified in schema, hardcoded behavior is omitNorms=true
[junit4] 2> 2178584 WARN (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitTermFreqAndPositions to be specified in schema, hardcoded behavior is omitTermFreqAndPositions=true
[junit4] 2> 2178584 WARN (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termOffsets to be specified in schema, hardcoded behavior is termOffsets=false
[junit4] 2> 2178584 WARN (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termPositions to be specified in schema, hardcoded behavior is termPositions=false
[junit4] 2> 2178584 WARN (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitPositions to be specified in schema, hardcoded behavior is omitPositions=true
[junit4] 2> 2178657 INFO (zkCallback-19444-thread-2) [ ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 2178657 INFO (zkCallback-19444-thread-2) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 110 ms
[junit4] 2> 2178658 INFO (zkCallback-19444-thread-4) [ ] o.a.s.s.ZkIndexSchemaReader Retrieved schema version 3 from Zookeeper
[junit4] 2> 2178660 INFO (zkCallback-19444-thread-4) [ ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 2178663 WARN (zkCallback-19444-thread-4) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitNorms to be specified in schema, hardcoded behavior is omitNorms=true
[junit4] 2> 2178663 WARN (zkCallback-19444-thread-4) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitTermFreqAndPositions to be specified in schema, hardcoded behavior is omitTermFreqAndPositions=true
[junit4] 2> 2178663 WARN (zkCallback-19444-thread-4) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termOffsets to be specified in schema, hardcoded behavior is termOffsets=false
[junit4] 2> 2178663 WARN (zkCallback-19444-thread-4) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow termPositions to be specified in schema, hardcoded behavior is termPositions=false
[junit4] 2> 2178663 WARN (zkCallback-19444-thread-4) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitPositions to be specified in schema, hardcoded behavior is omitPositions=true
[junit4] 2> 2178695 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 2178750 INFO (zkCallback-19444-thread-4) [ ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 2178750 INFO (zkCallback-19444-thread-4) [ ] o.a.s.s.ZkIndexSchemaReader Finished refreshing schema in 91 ms
[junit4] 2> 2178764 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.CoreContainer Reloading SolrCore '._designer_testJson_shard1_replica_n1' using configuration from configset ._designer_testJson
[junit4] 2> 2178765 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SolrCore [[._designer_testJson_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/tempDir-001/node1/._designer_testJson_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.x/solr/build/solr-core/test/J3/temp/solr.handler.designer.TestSchemaDesignerAPI_A62F13C84E8B18A6-001/tempDir-001/node1/._designer_testJson_shard1_replica_n1/data/]
[junit4] 2> 2178808 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 2178809 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2178810 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/._designer_testJson
[junit4] 2> 2178810 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/._designer_testJson
[junit4] 2> 2178811 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/._designer_testJson/managed-schema
[junit4] 2> 2178812 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 3 is already the latest
[junit4] 2> 2178812 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2178812 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxQueryFrequency=0.01,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4}
[junit4] 2> 2178813 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2178813 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1713483551364612096
[junit4] 2> 2178815 INFO (searcherExecutor-19484-thread-1-processing-n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 2178815 INFO (searcherExecutor-19484-thread-1-processing-n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2178815 INFO (searcherExecutor-19484-thread-1-processing-n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SolrCore [._designer_testJson_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2178816 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.DefaultSolrCoreState New IndexWriter is ready to be used.
[junit4] 2> 2178817 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr ] o.a.s.c.SolrCore [._designer_testJson_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@bded6df
[junit4] 2> 2178817 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.._designer_testJson.shard1.replica_n1 tag=SolrCore@bded6df
[junit4] 2> 2178817 INFO (searcherExecutor-19484-thread-1-processing-n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 2178817 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.._designer_testJson.shard1.leader tag=SolrCore@bded6df
[junit4] 2> 2178817 INFO (searcherExecutor-19484-thread-1-processing-n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1) [n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SolrCore [._designer_testJson_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2178820 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={core=._designer_testJson_shard1_replica_n1&qt=/admin/cores&action=RELOAD&wt=javabin&version=2} status=0 QTime=267
[junit4] 2> 2178825 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={name=._designer_testJson&action=RELOAD&wt=javabin&version=2} status=0 QTime=276
[junit4] 2> 2178829 INFO (qtp740448176-34795) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/._designer_testJson/terms/shard1 to Terms{values={core_node2=1}, version=1}
[junit4] 2> 2178830 INFO (qtp740448176-34795) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [._designer_testJson_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{deleteByQuery=*:* (-1713483551380340736)} 0 3
[junit4] 2> 2178832 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [._designer_testJson_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=._designer_testJson:4&optimize=true&maxSegments=1&waitSearcher=true&wt=javabin&version=2}{optimize=} 0 1
[junit4] 2> 2178834 INFO (qtp740448176-34800) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [._designer_testJson_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[978-0641723445 (1713483551385583616)]} 0 1
[junit4] 2> 2178835 INFO (qtp740448176-34798) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [._designer_testJson_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[978-1423103349 (1713483551387680768)]} 0 0
[junit4] 2> 2178836 INFO (qtp740448176-34797) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [._designer_testJson_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[978-1857995879 (1713483551388729344)]} 0 0
[junit4] 2> 2178837 INFO (qtp740448176-34795) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [._designer_testJson_shard1_replica_n1] webapp=/solr path=/update params={wt=javabin&version=2}{add=[978-1933988177 (1713483551388729345)]} 0 0
[junit4] 2> 2178841 INFO (searcherExecutor-19484-thread-1-processing-n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 c:._designer_testJson s:shard1 r:core_node2) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 2178841 INFO (searcherExecutor-19484-thread-1-processing-n:127.0.0.1:46833_solr x:._designer_testJson_shard1_replica_n1 c:._designer_testJson s:shard1 r:core_node2) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.SolrCore [._designer_testJson_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2178841 INFO (qtp740448176-34799) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.u.p.LogUpdateProcessorFactory [._designer_testJson_shard1_replica_n1] webapp=/solr path=/update params={_stateVer_=._designer_testJson:4&waitSearcher=true&commit=true&softCommit=true&wt=javabin&version=2}{commit=} 0 3
[junit4] 2> 2178842 INFO (qtp740448176-34800) [n:127.0.0.1:46833_solr c:._designer_testJson s:shard1 r:core_node2 x:._designer_testJson_shard1_replica_n1 ] o.a.s.c.S.Request [._designer_testJson_shard1_replica_n1] webapp=/solr path=/select params={q=*:*&_stateVer_=._designer_testJson:4&rows=0&wt=javabin&version=2} hits=4 status=0 QTime=0
[junit4] 2> 2178844 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.11.0
[junit4] 2> 2178844 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.c.SolrConfig solrconfig.xml: <jmx> is no longer supported, use solr.xml:/metrics/reporter section instead
[junit4] 2> 2178847 INFO (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 2178855 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitNorms to be specified in schema, hardcoded behavior is omitNorms=true
[junit4] 2> 2178855 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.AbstractSpatialPrefixTreeFieldType FieldType SpatialRecursivePrefixTreeFieldType does not allow omitTermFreqAndPositions to be specified in schema, hardcoded behavior is omitTermFreqAndPositions=true
[junit4] 2> 2178855 WARN (TEST-TestSchemaDesignerAPI.testBasicUserWorkflow-seed#[A62F13C84E8B18A6]) [ ] o.a.s.s.AbstractSpatialPrefixTreeFiel
[...truncated too long message...]
s/._designer_techproducts
[junit4] 2> 3 /solr/configs/._designer_films/managed-schema
[junit4] 2> 3 /solr/collections/._designer_fieldUpdates/terms/shard1
[junit4] 2> 3 /solr/collections/._designer_testTSV/terms/shard1
[junit4] 2> 3 /solr/configs/._designer_films
[junit4] 2> 3 /solr/collections/diff456/terms/shard1
[junit4] 2> 2 /solr/configs/techproducts
[junit4] 2> 2 /solr/configs/techproducts/managed-schema
[junit4] 2> 2 /solr/configs/._designer_testTSV
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 30 /solr/collections/._designer_testJson/state.json
[junit4] 2> 24 /solr/configs/._designer_techproducts/managed-schema
[junit4] 2> 18 /solr/collections/._designer_testDiff/state.json
[junit4] 2> 14 /solr/configs/._designer_testJson/managed-schema
[junit4] 2> 12 /solr/collections/.system/state.json
[junit4] 2> 12 /solr/collections/._designer_films/state.json
[junit4] 2> 9 /solr/collections/._designer_techproducts/state.json
[junit4] 2> 9 /solr/collections/test123/state.json
[junit4] 2> 9 /solr/collections/._designer_fieldUpdates/state.json
[junit4] 2> 9 /solr/collections/diff456/state.json
[junit4] 2> 7 /solr/collections/._designer_testTSV/state.json
[junit4] 2> 7 /solr/collections/techproducts/state.json
[junit4] 2> 6 /solr/configs/._designer_fieldUpdates/managed-schema
[junit4] 2> 4 /solr/configs/._designer_testDiff/managed-schema
[junit4] 2> 4 /solr/aliases.json
[junit4] 2> 4 /solr/clusterstate.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 183 /solr/overseer/collection-queue-work
[junit4] 2> 170 /solr/overseer/queue
[junit4] 2> 88 /solr/collections
[junit4] 2> 12 /solr/collections/._designer_testJson/state.json
[junit4] 2> 8 /solr/collections/._designer_testDiff/state.json
[junit4] 2> 5 /solr/collections/.system/state.json
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 4 /solr/collections/._designer_testTSV/state.json
[junit4] 2> 4 /solr/collections/._designer_techproducts/state.json
[junit4] 2> 4 /solr/collections/test123/state.json
[junit4] 2> 4 /solr/collections/._designer_films/state.json
[junit4] 2> 4 /solr/collections/techproducts/state.json
[junit4] 2> 4 /solr/collections/._designer_fieldUpdates/state.json
[junit4] 2> 4 /solr/collections/diff456/state.json
[junit4] 2>
[junit4] 2> 2255277 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:38693
[junit4] 2> 2255277 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38693
[junit4] 2> 2255277 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38693
[junit4] 2> 2255278 INFO (SUITE-TestSchemaDesignerAPI-seed#[A62F13C84E8B18A6]-worker) [ ] o.a.s.SolrTestCaseJ4 ------------------------------------------------------- Done waiting for tracked resources to be released
[junit4] 2> Oct 13, 2021 6:08:51 AM com.carrotsearch.randomizedtesting.ThreadLeakControl checkThreadLeaks
[junit4] 2> WARNING: Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {blobName=Lucene84, id=FST50, type=Lucene84, md5=Lucene84}, docValues:{size=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Asserting), version=DocValuesFormat(name=Asserting), timestamp=DocValuesFormat(name=Lucene80)}, maxPointsInLeafNode=1225, maxMBSortInHeap=7.484451580562929, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=vi-VN, timezone=America/Anguilla
[junit4] 2> NOTE: Linux 5.4.0-45-generic amd64/Oracle Corporation 1.8.0_291 (64-bit)/cpus=4,threads=1,free=268946832,total=536346624
[junit4] 2> NOTE: All tests run in this JVM: [FullHLLTest, CollectionPropsTest, ZkShardTermsTest, TestTlogReplayVsRecovery, MultiDestinationAuditLoggerTest, TestInPlaceUpdateWithRouteField, LegacyCloudClusterPropTest, TestLFUCache, HighlighterMaxOffsetTest, CacheHeaderTest, TestDocSet, TestGraphTermsQParserPlugin, TestAuthenticationFramework, CloudReplicaSourceTest, TestAddFieldRealTimeGet, DistanceUnitsTest, AuditLoggerPluginTest, EnumFieldTest, TestSolrCLIRunExample, DistributedFacetPivotLongTailTest, TestNumericTerms64, SolrXmlInZkTest, TestRestoreCore, ForceLeaderWithTlogReplicasTest, TestFieldSortValues, TestSolrJ, WordBreakSolrSpellCheckerTest, TestQueryingOnDownCollection, StatsReloadRaceTest, BigEndianAscendingWordDeserializerTest, TestUtils, TestFieldCollectionResource, TestPackages, SortByFunctionTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, TestStreamBody, MissingSegmentRecoveryTest, TestTrackingShardHandlerFactory, ChangedSchemaMergeTest, TestQuerySenderNoQuery, TestLogWatcher, LeaderFailoverAfterPartitionTest, TestPrepRecovery, TestCoreContainer, ReplaceNodeNoTargetTest, ClassificationUpdateProcessorIntegrationTest, MinimalSchemaTest, TestSimGenericDistributedQueue, TestFieldCacheVsDocValues, CachingDirectoryFactoryTest, TestPartialUpdateDeduplication, TokenizerChainTest, SaslZkACLProviderTest, TestRequestForwarding, TestJsonFacetsStatsParsing, HdfsTlogReplayBufferedWhileIndexingTest, TestStressThreadBackup, MetricsHandlerTest, TestValueSourceCache, TestUseDocValuesAsStored, TestIndexingPerformance, TestStressLucene, TestChildDocTransformerHierarchy, TestExternalRoleRuleBasedAuthorizationPlugin, TriggerIntegrationTest, TestSolrCloudWithSecureImpersonation, QueryParsingTest, ChaosMonkeySafeLeaderTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestFacetMethods, TestSweetSpotSimilarityFactory, TestRangeQuery, CloudExitableDirectoryReaderTest, PeerSyncWithIndexFingerprintCachingTest, TestUtilizeNode, ResponseHeaderTest, RangeFacetCloudTest, SpellCheckCollatorWithCollapseTest, TestQueryTypes, BlockJoinFacetRandomTest, TestImpersonationWithHadoopAuth, TestBadConfig, ParsingFieldUpdateProcessorsTest, TestSchemaNameResource, TestConfigOverlay, SolrIndexSplitterTest, SolrGraphiteReporterTest, TestRandomFaceting, TestConfigReload, TestSnapshotCloudManager, HdfsBackupRepositoryIntegrationTest, SuggestComponentTest, CollectionsAPIDistributedZkTest, SolrCloudReportersTest, AtomicUpdateProcessorFactoryTest, TestLegacyBM25SimilarityFactory, CdcrOpsAndBoundariesTest, HdfsRecoveryZkTest, DateRangeFieldTest, TestSolrXml, TestSerializedLuceneMatchVersion, SOLR749Test, HttpPartitionWithTlogReplicasTest, TestMultiValuedNumericRangeQuery, SoftAutoCommitTest, BasicDistributedZkTest, TestLegacyField, TlogReplayBufferedWhileIndexingTest, ShardsWhitelistTest, TestSimDistribStateManager, BJQFilterAccessibleTest, TestBulkSchemaAPI, TestUnifiedSolrHighlighter, AddReplicaTest, TestCloudPhrasesIdentificationComponent, DocumentBuilderTest, TestPhraseSuggestions, BasicDistributedZk2Test, CreateCollectionCleanupTest, DeleteInactiveReplicaTest, DocValuesNotIndexedTest, LeaderFailureAfterFreshStartTest, NestedShardedAtomicUpdateTest, NodeMutatorTest, OverseerRolesTest, OverseerTaskQueueTest, ReplicationFactorTest, TestCloudSearcherWarming, TestRandomRequestDistribution, TestSizeLimitedDistributedMap, TestStressCloudBlindAtomicUpdates, VMParamsZkACLAndCredentialsProvidersTest, BackupRestoreApiErrorConditionsTest, CollectionReloadTest, CollectionTooManyReplicasTest, HdfsCloudIncrementalBackupTest, PurgeGraphTest, ShardSplitTest, TriggerCooldownIntegrationTest, TestSimUtils, BaseCdcrDistributedZkTest, CdcrBidirectionalTest, CdcrBootstrapTest, ImplicitSnitchTest, RuleEngineTest, RulesTest, TestBackupRepositoryFactory, TestCodecSupport, TestConfig, TestConfigSetProperties, TestConfigSets, TestDynamicLoadingUrl, TestReloadAndDeleteDocs, TestSimpleTextCodec, TestXIncludeConfig, BackupIdTest, TestSolrCloudSnapshots, TestSQLHandler, TestStressIncrementalBackup, CoreAdminRequestStatusTest, MetricsHistoryHandlerTest, SegmentsInfoRequestHandlerTest, DistributedExpandComponentTest, DistributedMLTComponentTest, DistributedTermsComponentTest, ResponseLogComponentTest, SearchHandlerTest, TestExpandComponent, TestHttpShardHandlerFactory, TestSchemaDesignerAPI]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestSchemaDesignerAPI -Dtests.seed=A62F13C84E8B18A6 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=vi-VN -Dtests.timezone=America/Anguilla -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J3 | TestSchemaDesignerAPI (suite) <<<
[junit4] > Throwable #1: java.util.concurrent.TimeoutException: Timed out waiting for all collections to be fully removed.
[junit4] > at __randomizedtesting.SeedInfo.seed([A62F13C84E8B18A6]:0)
[junit4] > at org.apache.solr.cloud.MiniSolrCloudCluster.deleteAllCollections(MiniSolrCloudCluster.java:581)
[junit4] > at org.apache.solr.handler.designer.TestSchemaDesignerAPI.tearDownCluster(TestSchemaDesignerAPI.java:82)
[junit4] > at java.lang.Thread.run(Thread.java:748)Throwable #2: com.carrotsearch.randomizedtesting.UncaughtExceptionError: Captured an uncaught exception in thread: Thread[id=35067, name=Thread-6949, state=RUNNABLE, group=TGRP-TestSchemaDesignerAPI]
[junit4] > Caused by: java.lang.AssertionError
[junit4] > at __randomizedtesting.SeedInfo.seed([A62F13C84E8B18A6]:0)
[junit4] > at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1750)
[junit4] > at org.apache.solr.core.SolrCore.lambda$getConfListener$19(SolrCore.java:3147)
[junit4] > at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$15(ZkController.java:2630)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] Completed [774/947 (1!)] on J3 in 81.88s, 6 tests, 2 errors <<< FAILURES!
[...truncated 54802 lines...]