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/01/18 08:22:41 UTC
[JENKINS] Lucene » Lucene-Solr-Tests-8.8 - Build # 101 - Unstable!
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.8/101/
1 tests failed.
FAILED: org.apache.solr.cloud.TestWithCollection.testNodeAdded
Error Message:
Action was not fired till 30 seconds
Stack Trace:
java.lang.AssertionError: Action was not fired till 30 seconds
at __randomizedtesting.SeedInfo.seed([18A46F66ED756C74:7D6739114FD6C477]:0)
at org.junit.Assert.fail(Assert.java:89)
at org.junit.Assert.assertTrue(Assert.java:42)
at org.apache.solr.cloud.TestWithCollection.testNodeAdded(TestWithCollection.java:471)
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.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 13801 lines...]
[junit4] Suite: org.apache.solr.cloud.TestWithCollection
[junit4] 2> 312709 INFO (SUITE-TestWithCollection-seed#[18A46F66ED756C74]-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.8/solr/server/solr/configsets/_default/conf'
[junit4] 2> 312710 INFO (SUITE-TestWithCollection-seed#[18A46F66ED756C74]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_18A46F66ED756C74-001/data-dir-45-001
[junit4] 2> 312710 WARN (SUITE-TestWithCollection-seed#[18A46F66ED756C74]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=7 numCloses=7
[junit4] 2> 312710 INFO (SUITE-TestWithCollection-seed#[18A46F66ED756C74]-worker) [ ] o.a.s.SolrTestCaseJ4 Using TrieFields (NUMERIC_POINTS_SYSPROP=false) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 312711 INFO (SUITE-TestWithCollection-seed#[18A46F66ED756C74]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (false) via: @org.apache.solr.util.RandomizeSSL(reason=, value=NaN, ssl=NaN, clientAuth=NaN)
[junit4] 2> 312712 INFO (SUITE-TestWithCollection-seed#[18A46F66ED756C74]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 312714 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testAddReplicaSimple
[junit4] 2> 312715 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 2 servers in /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_18A46F66ED756C74-001/tempDir-001
[junit4] 2> 312715 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 312715 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer client port: 0.0.0.0/0.0.0.0:0
[junit4] 2> 312716 INFO (ZkTestServer Run Thread) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 312716 WARN (ZkTestServer Run Thread) [ ] o.a.z.s.ServerCnxnFactory maxCnxns is not configured, using default value 0.
[junit4] 2> 312815 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.ZkTestServer start zk server on port: 38245
[junit4] 2> 312816 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.ZkTestServer waitForServerUp: 127.0.0.1:38245
[junit4] 2> 312816 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:38245
[junit4] 2> 312816 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 38245
[junit4] 2> 312817 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 312820 INFO (zkConnectionManagerCallback-2402-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312820 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 312823 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 312824 INFO (zkConnectionManagerCallback-2404-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312824 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 312825 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 312826 INFO (zkConnectionManagerCallback-2406-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312826 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 312828 WARN (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]-SendThread(127.0.0.1:38245)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102cb512e6a0002.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102cb512e6a0002, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x102cb512e6a0002, 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> 312932 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 312932 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 312932 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 312932 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 312932 INFO (jetty-launcher-2407-thread-1) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 312932 INFO (jetty-launcher-2407-thread-2) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 312933 INFO (jetty-launcher-2407-thread-2) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 312933 INFO (jetty-launcher-2407-thread-2) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 312933 INFO (jetty-launcher-2407-thread-2) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 312933 INFO (jetty-launcher-2407-thread-1) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 312933 INFO (jetty-launcher-2407-thread-1) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 312933 INFO (jetty-launcher-2407-thread-1) [ ] o.e.j.s.session node0 Scavenging every 660000ms
[junit4] 2> 312934 INFO (jetty-launcher-2407-thread-2) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3738f530{/solr,null,AVAILABLE}
[junit4] 2> 312934 INFO (jetty-launcher-2407-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@3ed08003{/solr,null,AVAILABLE}
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@415a70fd{SSL, (ssl, http/1.1)}{127.0.0.1:35713}
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-1) [ ] o.e.j.s.Server Started @312969ms
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:38245/solr, hostPort=35713}
[junit4] 2> 312935 ERROR (jetty-launcher-2407-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-2) [ ] o.e.j.s.AbstractConnector Started ServerConnector@23c810c7{SSL, (ssl, http/1.1)}{127.0.0.1:38183}
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-2) [ ] o.e.j.s.Server Started @312970ms
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-18T07:29:40.612Z
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, zkHost=127.0.0.1:38245/solr, hostPort=38183}
[junit4] 2> 312935 ERROR (jetty-launcher-2407-thread-2) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 8.8.0
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 312935 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-18T07:29:40.612Z
[junit4] 2> 312939 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 312939 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 312940 INFO (zkConnectionManagerCallback-2409-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312940 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 312943 INFO (zkConnectionManagerCallback-2411-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 312943 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 312944 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 312944 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 313878 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 313879 WARN (jetty-launcher-2407-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 313881 WARN (jetty-launcher-2407-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@453055ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 313881 WARN (jetty-launcher-2407-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@453055ab[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 313888 WARN (jetty-launcher-2407-thread-1) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 313908 WARN (jetty-launcher-2407-thread-1) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@4556b1b0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 313908 WARN (jetty-launcher-2407-thread-1) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@4556b1b0[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 313910 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38245/solr
[junit4] 2> 313929 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 313935 INFO (zkConnectionManagerCallback-2425-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 313935 INFO (jetty-launcher-2407-thread-1) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 313945 WARN (jetty-launcher-2407-thread-1-SendThread(127.0.0.1:38245)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102cb512e6a0005.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102cb512e6a0005, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x102cb512e6a0005, 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> 314051 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 314053 INFO (zkConnectionManagerCallback-2427-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 314053 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 314132 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:35713_solr
[junit4] 2> 314132 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.c.Overseer Overseer (id=72844093522837510-127.0.0.1:35713_solr-n_0000000000) starting
[junit4] 2> 314145 INFO (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.Overseer Starting to work on the main queue : 127.0.0.1:35713_solr
[junit4] 2> 314163 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:35713_solr
[junit4] 2> 314164 DEBUG (OverseerAutoScalingTriggerThread-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Adding .auto_add_replicas and .scheduled_maintenance triggers
[junit4] 2> 314166 DEBUG (OverseerAutoScalingTriggerThread-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 1
[junit4] 2> 314168 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 314168 DEBUG (OverseerAutoScalingTriggerThread-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 0
[junit4] 2> 314168 DEBUG (OverseerAutoScalingTriggerThread-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 1
[junit4] 2> 314168 WARN (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_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> 314191 INFO (zkCallback-2426-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 314193 DEBUG (OverseerAutoScalingTriggerThread-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [ ] o.a.s.c.a.NodeLostTrigger NodeLostTrigger .auto_add_replicas - Initial livenodes: [127.0.0.1:35713_solr]
[junit4] 2> 314203 DEBUG (OverseerAutoScalingTriggerThread-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 314204 DEBUG (OverseerAutoScalingTriggerThread-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 1, lastZnodeVersion 1
[junit4] 2> 314204 DEBUG (ScheduledTrigger-2432-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Running NodeLostTrigger: .auto_add_replicas with currently live nodes: 1 and last live nodes: 1
[junit4] 2> 314204 TRACE (ScheduledTrigger-2432-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Current Live Nodes for .auto_add_replicas: [127.0.0.1:35713_solr]
[junit4] 2> 314204 TRACE (ScheduledTrigger-2432-thread-3) [ ] o.a.s.c.a.NodeLostTrigger Last Live Nodes for .auto_add_replicas: [127.0.0.1:35713_solr]
[junit4] 2> 314212 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 314241 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35713.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e6ff7a0
[junit4] 2> 314248 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35713.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e6ff7a0
[junit4] 2> 314248 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35713.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e6ff7a0
[junit4] 2> 314249 INFO (jetty-launcher-2407-thread-1) [n:127.0.0.1:35713_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_18A46F66ED756C74-001/tempDir-001/node1
[junit4] 2> 314760 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=true]
[junit4] 2> 314761 WARN (jetty-launcher-2407-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 314762 WARN (jetty-launcher-2407-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@65f98287[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 314762 WARN (jetty-launcher-2407-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@65f98287[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 314764 WARN (jetty-launcher-2407-thread-2) [ ] o.a.s.c.s.i.Http2SolrClient Create Http2SolrClient with HTTP/1.1 transport since Java 8 or lower versions does not support SSL + HTTP/2
[junit4] 2> 314766 WARN (jetty-launcher-2407-thread-2) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@366eb3fe[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 314766 WARN (jetty-launcher-2407-thread-2) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@366eb3fe[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 314767 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:38245/solr
[junit4] 2> 314767 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 314769 INFO (zkConnectionManagerCallback-2447-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 314769 INFO (jetty-launcher-2407-thread-2) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 314871 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 314872 INFO (zkConnectionManagerCallback-2449-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 314872 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 314877 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 314880 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.c.ZkController Publish node=127.0.0.1:38183_solr as DOWN
[junit4] 2> 314881 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 314881 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38183_solr
[junit4] 2> 314882 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.NodeMutator DownNode state invoked for node: 127.0.0.1:38183_solr
[junit4] 2> 314883 INFO (zkCallback-2426-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 314883 INFO (zkCallback-2448-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 314885 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.p.PackageLoader /packages.json updated to version -1
[junit4] 2> 314885 WARN (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_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> 314896 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 314913 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38183.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e6ff7a0
[junit4] 2> 314922 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38183.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e6ff7a0
[junit4] 2> 314922 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38183.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e6ff7a0
[junit4] 2> 314923 INFO (jetty-launcher-2407-thread-2) [n:127.0.0.1:38183_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_18A46F66ED756C74-001/tempDir-001/node2
[junit4] 2> 314948 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.MiniSolrCloudCluster waitForAllNodes: numServers=2
[junit4] 2> 314949 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.c.ConnectionManager Waiting for client to connect to ZooKeeper
[junit4] 2> 314950 INFO (zkConnectionManagerCallback-2462-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 314950 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.c.ConnectionManager Client is connected to ZooKeeper
[junit4] 2> 314952 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 314954 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:38245/solr ready
[junit4] 2> 314957 DEBUG (zkCallback-2426-thread-1) [ ] o.a.s.c.a.OverseerTriggerThread Refreshing /autoscaling.json with znode version 2
[junit4] 2> 314958 DEBUG (OverseerAutoScalingTriggerThread-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Processed trigger updates upto znodeVersion 2
[junit4] 2> 314960 DEBUG (OverseerAutoScalingTriggerThread-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread -- deactivating old nodeLost / nodeAdded markers
[junit4] 2> 314961 DEBUG (OverseerAutoScalingTriggerThread-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [ ] o.a.s.c.a.OverseerTriggerThread Current znodeVersion 2, lastZnodeVersion 2
[junit4] 2> 314961 INFO (TEST-TestWithCollection.testAddReplicaSimple-seed#[18A46F66ED756C74]) [ ] o.a.s.c.TestWithCollection Chosen node 127.0.0.1:38183_solr for collection testAddReplica_abc
[junit4] 2> 314990 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testAddReplica_abc
[junit4] 2> 314990 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/testAddReplica_abc.AUTOCREATED
[junit4] 2> 314991 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testAddReplica_abc.AUTOCREATED/protwords.txt
[junit4] 2> 314993 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testAddReplica_abc.AUTOCREATED/managed-schema
[junit4] 2> 314994 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testAddReplica_abc.AUTOCREATED/solrconfig.xml
[junit4] 2> 314997 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 314998 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 314999 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 315001 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 315002 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 315003 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 315005 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 315006 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 315007 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 315009 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 315010 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 315012 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 315013 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 315014 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 315016 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 315018 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 315020 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 315021 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 315023 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 315024 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 315026 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 315027 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 315029 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 315030 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 315031 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 315033 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 315034 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 315035 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 315036 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 315038 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 315039 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 315040 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 315043 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 315044 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 315046 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 315047 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 315049 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 315050 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 315052 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testAddReplica_abc.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 315054 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testAddReplica_abc.AUTOCREATED/synonyms.txt
[junit4] 2> 315061 INFO (OverseerThreadFactory-2434-thread-1-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testAddReplica_abc.AUTOCREATED/stopwords.txt
[junit4] 2> 315065 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testAddReplica_abc
[junit4] 2> 315065 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testAddReplica_abc/state.json
[junit4] 2> 315169 INFO (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"ADDREPLICA",
[junit4] 2> "collection":"testAddReplica_abc",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "type":"NRT",
[junit4] 2> "waitForFinalState":"false"}
[junit4] 2> 315170 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{}}
[junit4] 2> 315170 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 315271 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplica_abc/state.json version: 0
[junit4] 2> 315376 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node2&collection.configName=testAddReplica_abc.AUTOCREATED&newCollection=true&name=testAddReplica_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testAddReplica_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 315383 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 315387 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testAddReplica_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 315387 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"https://127.0.0.1:38183/solr"}
[junit4] 2> 315387 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testAddReplica_abc//collections/testAddReplica_abc/state.json/1 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 315399 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 315420 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 315488 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplica_abc/state.json version: 1
[junit4] 2> 315501 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 315531 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.CoreContainer Creating SolrCore 'testAddReplica_abc_shard1_replica_n1' using configuration from configset testAddReplica_abc.AUTOCREATED, trusted=true
[junit4] 2> 315532 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_38183.solr.core.testAddReplica_abc.shard1.replica_n1' (registry 'solr.core.testAddReplica_abc.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e6ff7a0
[junit4] 2> 315532 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [[testAddReplica_abc_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_18A46F66ED756C74-001/tempDir-001/node2/testAddReplica_abc_shard1_replica_n1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_18A46F66ED756C74-001/tempDir-001/node2/testAddReplica_abc_shard1_replica_n1/data/]
[junit4] 2> 315761 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 315761 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 315762 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 315762 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 315765 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testAddReplica_abc.AUTOCREATED
[junit4] 2> 315768 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testAddReplica_abc.AUTOCREATED
[junit4] 2> 315768 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Creating ZooKeeper watch for the managed schema at /configs/testAddReplica_abc.AUTOCREATED/managed-schema
[junit4] 2> 315769 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.s.ZkIndexSchemaReader Current schema version 0 is already the latest
[junit4] 2> 315769 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 315769 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.s.DirectSolrSpellChecker init: {name=default,field=_text_,classname=solr.DirectSolrSpellChecker,distanceMeasure=internal,accuracy=0.5,maxEdits=2,minPrefix=1,maxInspections=5,minQueryLength=4,maxQueryFrequency=0.01}
[junit4] 2> 315781 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 315781 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1689208732734455808
[junit4] 2> 315783 INFO (searcherExecutor-2464-thread-1-processing-n:127.0.0.1:38183_solr x:testAddReplica_abc_shard1_replica_n1 c:testAddReplica_abc s:shard1 r:core_node2) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.QuerySenderListener QuerySenderListener done.
[junit4] 2> 315783 INFO (searcherExecutor-2464-thread-1-processing-n:127.0.0.1:38183_solr x:testAddReplica_abc_shard1_replica_n1 c:testAddReplica_abc s:shard1 r:core_node2) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 315783 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 315785 INFO (searcherExecutor-2464-thread-1-processing-n:127.0.0.1:38183_solr x:testAddReplica_abc_shard1_replica_n1 c:testAddReplica_abc s:shard1 r:core_node2) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.SolrCore [testAddReplica_abc_shard1_replica_n1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 315789 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.ZkShardTerms Successful update of terms at /collections/testAddReplica_abc/terms/shard1 to Terms{values={core_node2=0}, version=0}
[junit4] 2> 315789 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase make sure parent is created /collections/testAddReplica_abc/leaders/shard1
[junit4] 2> 315793 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 315793 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 315793 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:38183/solr/testAddReplica_abc_shard1_replica_n1/
[junit4] 2> 315793 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 315794 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.SyncStrategy https://127.0.0.1:38183/solr/testAddReplica_abc_shard1_replica_n1/ has no replicas
[junit4] 2> 315794 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContextBase Creating leader registration node /collections/testAddReplica_abc/leaders/shard1/leader after winning as /collections/testAddReplica_abc/leader_elect/shard1/election/72844093522837512-core_node2-n_0000000000
[junit4] 2> 315796 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:38183/solr/testAddReplica_abc_shard1_replica_n1/ shard1
[junit4] 2> 315797 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 315898 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplica_abc/state.json version: 2
[junit4] 2> 315898 INFO (zkCallback-2448-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 315898 INFO (zkCallback-2448-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 315900 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 315904 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr c:testAddReplica_abc s:shard1 r:core_node2 x:testAddReplica_abc_shard1_replica_n1 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 315904 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=1 message={
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "core_node_name":"core_node2",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testAddReplica_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 315904 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node2:{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "leader":"true",
[junit4] 2> "base_url":"https://127.0.0.1:38183/solr"}
[junit4] 2> 315904 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testAddReplica_abc//collections/testAddReplica_abc/state.json/3 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 315906 INFO (qtp691043255-5113) [n:127.0.0.1:38183_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&coreNodeName=core_node2&collection.configName=testAddReplica_abc.AUTOCREATED&newCollection=true&name=testAddReplica_abc_shard1_replica_n1&action=CREATE&numShards=1&collection=testAddReplica_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=529
[junit4] 2> 315908 INFO (qtp691043255-5116) [n:127.0.0.1:38183_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> 315908 INFO (qtp691043255-5116) [n:127.0.0.1:38183_solr ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 316005 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplica_abc/state.json version: 3
[junit4] 2> 316005 INFO (zkCallback-2448-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 316005 INFO (zkCallback-2448-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 316006 INFO (zkCallback-2448-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 316006 INFO (qtp691043255-5116) [n:127.0.0.1:38183_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={pullReplicas=0&name=testAddReplica_abc&nrtReplicas=1&action=CREATE&numShards=1&tlogReplicas=0&createNodeSet=127.0.0.1:38183_solr&wt=javabin&version=2} status=0 QTime=1019
[junit4] 2> 316010 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.a.c.CreateCollectionCmd Create collection testAddReplica_xyz
[junit4] 2> 316010 INFO (OverseerCollectionConfigSetProcessor-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_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> 316012 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default to /configs/testAddReplica_xyz.AUTOCREATED
[junit4] 2> 316013 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/protwords.txt to /configs/testAddReplica_xyz.AUTOCREATED/protwords.txt
[junit4] 2> 316014 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/managed-schema to /configs/testAddReplica_xyz.AUTOCREATED/managed-schema
[junit4] 2> 316015 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/solrconfig.xml to /configs/testAddReplica_xyz.AUTOCREATED/solrconfig.xml
[junit4] 2> 316017 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_it.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/contractions_it.txt
[junit4] 2> 316018 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_no.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_no.txt
[junit4] 2> 316020 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ca.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/contractions_ca.txt
[junit4] 2> 316021 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stemdict_nl.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stemdict_nl.txt
[junit4] 2> 316023 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hy.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_hy.txt
[junit4] 2> 316024 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_id.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_id.txt
[junit4] 2> 316025 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_et.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_et.txt
[junit4] 2> 316027 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ga.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_ga.txt
[junit4] 2> 316028 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_da.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_da.txt
[junit4] 2> 316029 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hi.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_hi.txt
[junit4] 2> 316031 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ja.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_ja.txt
[junit4] 2> 316032 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_pt.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_pt.txt
[junit4] 2> 316033 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_hu.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_hu.txt
[junit4] 2> 316035 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_el.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_el.txt
[junit4] 2> 316036 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ru.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_ru.txt
[junit4] 2> 316038 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_tr.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_tr.txt
[junit4] 2> 316039 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ar.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_ar.txt
[junit4] 2> 316041 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/userdict_ja.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/userdict_ja.txt
[junit4] 2> 316042 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_it.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_it.txt
[junit4] 2> 316048 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_eu.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_eu.txt
[junit4] 2> 316049 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_cz.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_cz.txt
[junit4] 2> 316050 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stoptags_ja.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stoptags_ja.txt
[junit4] 2> 316052 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_fr.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/contractions_fr.txt
[junit4] 2> 316053 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_de.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_de.txt
[junit4] 2> 316055 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fa.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_fa.txt
[junit4] 2> 316056 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/contractions_ga.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/contractions_ga.txt
[junit4] 2> 316058 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ca.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_ca.txt
[junit4] 2> 316059 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_nl.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_nl.txt
[junit4] 2> 316061 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_bg.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_bg.txt
[junit4] 2> 316064 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_en.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_en.txt
[junit4] 2> 316066 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_lv.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_lv.txt
[junit4] 2> 316067 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_es.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_es.txt
[junit4] 2> 316068 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_th.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_th.txt
[junit4] 2> 316069 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_ro.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_ro.txt
[junit4] 2> 316071 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fr.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_fr.txt
[junit4] 2> 316073 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_sv.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_sv.txt
[junit4] 2> 316074 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_fi.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_fi.txt
[junit4] 2> 316075 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/hyphenations_ga.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/hyphenations_ga.txt
[junit4] 2> 316077 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/lang/stopwords_gl.txt to /configs/testAddReplica_xyz.AUTOCREATED/lang/stopwords_gl.txt
[junit4] 2> 316078 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/synonyms.txt to /configs/testAddReplica_xyz.AUTOCREATED/synonyms.txt
[junit4] 2> 316079 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkConfigManager Copying zk node /configs/_default/stopwords.txt to /configs/testAddReplica_xyz.AUTOCREATED/stopwords.txt
[junit4] 2> 316082 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ClusterStateMutator building a new cName: testAddReplica_xyz
[junit4] 2> 316183 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ZkStateWriter going to create_collection /collections/testAddReplica_xyz/state.json
[junit4] 2> 316289 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.a.c.AddReplicaCmd Node Identified 127.0.0.1:35713_solr for creating new replica of shard shard1 for collection testAddReplica_abc
[junit4] 2> 316292 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.a.c.AddReplicaCmd Returning CreateReplica command.
[junit4] 2> 316295 INFO (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.SliceMutator createReplica() {
[junit4] 2> "operation":"addreplica",
[junit4] 2> "collection":"testAddReplica_abc",
[junit4] 2> "shard":"shard1",
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n3",
[junit4] 2> "state":"down",
[junit4] 2> "node_name":"127.0.0.1:35713_solr",
[junit4] 2> "type":"NRT"}
[junit4] 2> 316296 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.SliceMutator Old Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{"core_node2":{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}
[junit4] 2> 316296 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.SliceMutator New Slice: shard1:{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:35713_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT"}}}
[junit4] 2> 316397 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplica_abc/state.json version: 4
[junit4] 2> 316398 INFO (zkCallback-2448-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 316398 INFO (zkCallback-2448-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 316398 INFO (zkCallback-2448-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 316506 INFO (OverseerThreadFactory-2434-thread-2-processing-n:127.0.0.1:35713_solr) [n:127.0.0.1:35713_solr ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 316535 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&coreNodeName=core_node4&collection.configName=testAddReplica_abc.AUTOCREATED&name=testAddReplica_abc_shard1_replica_n3&action=CREATE&collection=testAddReplica_abc&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 316535 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 316536 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.c.c.ZkStateReader already watching , added to stateWatchers
[junit4] 2> 316538 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ReplicaMutator Update state numShards=null message={
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n3",
[junit4] 2> "core_node_name":"core_node4",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:35713_solr",
[junit4] 2> "state":"down",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"testAddReplica_abc",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "operation":"state"}
[junit4] 2> 316538 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ReplicaMutator Will update state for replica: core_node4:{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:35713_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false",
[junit4] 2> "base_url":"https://127.0.0.1:35713/solr"}
[junit4] 2> 316538 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ReplicaMutator Collection is now: DocCollection(testAddReplica_abc//collections/testAddReplica_abc/state.json/5 )={
[junit4] 2> "pullReplicas":"0",
[junit4] 2> "replicationFactor":"1",
[junit4] 2> "router":{"name":"compositeId"},
[junit4] 2> "maxShardsPerNode":"1",
[junit4] 2> "autoAddReplicas":"false",
[junit4] 2> "nrtReplicas":"1",
[junit4] 2> "tlogReplicas":"0",
[junit4] 2> "shards":{"shard1":{
[junit4] 2> "range":"80000000-7fffffff",
[junit4] 2> "state":"active",
[junit4] 2> "replicas":{
[junit4] 2> "core_node2":{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n1",
[junit4] 2> "leader":"true",
[junit4] 2> "node_name":"127.0.0.1:38183_solr",
[junit4] 2> "state":"active",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"},
[junit4] 2> "core_node4":{
[junit4] 2> "core":"testAddReplica_abc_shard1_replica_n3",
[junit4] 2> "node_name":"127.0.0.1:35713_solr",
[junit4] 2> "state":"down",
[junit4] 2> "type":"NRT",
[junit4] 2> "force_set_state":"false"}}}}}
[junit4] 2> 316544 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 316570 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.s.IndexSchema Schema name=default-config
[junit4] 2> 316610 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.s.IndexSchema Loaded schema default-config/1.6 with uniqueid field id
[junit4] 2> 316639 DEBUG (OverseerStateUpdate-72844093522837510-127.0.0.1:35713_solr-n_0000000000) [n:127.0.0.1:35713_solr ] o.a.s.c.o.ZkStateWriter going to update_collection /collections/testAddReplica_abc/state.json version: 5
[junit4] 2> 316640 INFO (zkCallback-2448-thread-3) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 316640 INFO (zkCallback-2448-thread-2) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 316640 INFO (zkCallback-2448-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 316640 INFO (zkCallback-2426-thread-1) [ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/testAddReplica_abc/state.json] for collection [testAddReplica_abc] has occurred - updating... (live nodes size: [2])
[junit4] 2> 316658 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.c.CoreContainer Creating SolrCore 'testAddReplica_abc_shard1_replica_n3' using configuration from configset testAddReplica_abc.AUTOCREATED, trusted=true
[junit4] 2> 316659 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_35713.solr.core.testAddReplica_abc.shard1.replica_n3' (registry 'solr.core.testAddReplica_abc.shard1.replica_n3') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@e6ff7a0
[junit4] 2> 316659 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.c.SolrCore [[testAddReplica_abc_shard1_replica_n3] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_18A46F66ED756C74-001/tempDir-001/node1/testAddReplica_abc_shard1_replica_n3], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_18A46F66ED756C74-001/tempDir-001/node1/testAddReplica_abc_shard1_replica_n3/data/]
[junit4] 2> 316731 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 316731 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 316732 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.u.CommitTracker Hard AutoCommit: if uncommitted for 15000ms;
[junit4] 2> 316732 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 316736 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/testAddReplica_abc.AUTOCREATED
[junit4] 2> 316736 INFO (qtp1268915085-5114) [n:127.0.0.1:35713_solr c:testAddReplica_abc s:shard1 r:core_node4 x:testAddReplica_abc_shard1_replica_n3 ] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/testAddReplica_abc.AUTOCRE
[...truncated too long message...]
hreadPool-3371-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Shutting down action executor now
[junit4] 2> 407852 DEBUG (closeThreadPool-3371-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for action executor
[junit4] 2> 407852 DEBUG (closeThreadPool-3371-thread-2) [ ] o.a.s.c.a.ScheduledTriggers Awaiting termination for scheduled thread pool executor
[junit4] 2> 407852 DEBUG (closeThreadPool-3371-thread-2) [ ] o.a.s.c.a.ScheduledTriggers ScheduledTriggers closed completely
[junit4] 2> 407852 DEBUG (closeThreadPool-3371-thread-2) [ ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread has been closed explicitly
[junit4] 2> 407852 INFO (closeThreadPool-3371-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72844099513221126-127.0.0.1:33677_solr-n_0000000000) closing
[junit4] 2> 407856 INFO (coreCloseExecutor-3370-thread-1) [n:127.0.0.1:33597_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testMoveReplicaWithCollection_abc.shard1.leader tag=SolrCore@66293ee6
[junit4] 2> 407856 INFO (coreCloseExecutor-3370-thread-2) [n:127.0.0.1:33597_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.testMoveReplicaWithCollection_xyz.shard1.replica_n1 tag=SolrCore@b64f724
[junit4] 2> 407856 INFO (coreCloseExecutor-3370-thread-2) [n:127.0.0.1:33597_solr ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4e49dcc: rootName = solr_33597, domain = solr.core.testMoveReplicaWithCollection_xyz.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.testMoveReplicaWithCollection_xyz.shard1.replica_n1/com.codahale.metrics.MetricRegistry@46b2eb3a
[junit4] 2> 407857 INFO (coreCloseExecutor-3370-thread-1) [n:127.0.0.1:33597_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 407887 INFO (coreCloseExecutor-3370-thread-2) [n:127.0.0.1:33597_solr ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.testMoveReplicaWithCollection_xyz.shard1.leader tag=SolrCore@b64f724
[junit4] 2> 407888 INFO (coreCloseExecutor-3370-thread-2) [n:127.0.0.1:33597_solr ] o.a.s.u.DirectUpdateHandler2 Committing on IndexWriter.close() ... SKIPPED (unnecessary).
[junit4] 2> 407891 INFO (jetty-closer-3362-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 407891 INFO (jetty-closer-3362-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@13e7337f: rootName = solr_33597, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@693dfebd
[junit4] 2> 407896 INFO (jetty-closer-3362-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 407897 INFO (jetty-closer-3362-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@50de042b: rootName = solr_33597, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@9c694d0
[junit4] 2> 407899 INFO (jetty-closer-3362-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 407899 INFO (jetty-closer-3362-thread-2) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@6f578481: rootName = solr_33597, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@6e362eeb
[junit4] 2> 407899 INFO (jetty-closer-3362-thread-2) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster tag=null
[junit4] 2> 407939 INFO (jetty-closer-3362-thread-1) [ ] o.a.s.c.Overseer Overseer (id=72844099513221126-127.0.0.1:33677_solr-n_0000000000) closing
[junit4] 2> 408042 INFO (jetty-closer-3362-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@710c9e42{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 408042 INFO (jetty-closer-3362-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@854454a{/solr,null,STOPPED}
[junit4] 2> 408042 INFO (jetty-closer-3362-thread-1) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 408043 INFO (jetty-closer-3362-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@230426b7{SSL, (ssl, http/1.1)}{127.0.0.1:0}
[junit4] 2> 408043 INFO (jetty-closer-3362-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@36c83540{/solr,null,STOPPED}
[junit4] 2> 408043 INFO (jetty-closer-3362-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 408045 INFO (TEST-TestWithCollection.testMoveReplicaWithCollection-seed#[18A46F66ED756C74]) [ ] o.a.s.c.ZkTestServer Shutting down ZkTestServer.
[junit4] 2> 408147 WARN (TEST-TestWithCollection.testMoveReplicaWithCollection-seed#[18A46F66ED756C74]-SendThread(127.0.0.1:36307)) [ ] o.a.z.ClientCnxn An exception was thrown while closing send thread for session 0x102cb5293780001.
[junit4] 2> => EndOfStreamException: Unable to read additional data from server sessionid 0x102cb5293780001, likely server has closed socket
[junit4] 2> at org.apache.zookeeper.ClientCnxnSocketNIO.doIO(ClientCnxnSocketNIO.java:77)
[junit4] 2> org.apache.zookeeper.ClientCnxn$EndOfStreamException: Unable to read additional data from server sessionid 0x102cb5293780001, 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> 408249 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> 3 /solr/aliases.json
[junit4] 2> 2 /solr/packages.json
[junit4] 2> 2 /solr/security.json
[junit4] 2> 2 /solr/collections/testMoveReplicaWithCollection_xyz/terms/shard1
[junit4] 2> 2 /solr/collections/testMoveReplicaWithCollection_abc/terms/shard1
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 14 /solr/collections/testMoveReplicaWithCollection_abc/state.json
[junit4] 2> 9 /solr/collections/testMoveReplicaWithCollection_xyz/state.json
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/autoscaling.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 26 /solr/overseer/queue
[junit4] 2> 12 /solr/overseer/collection-queue-work
[junit4] 2> 9 /solr/collections
[junit4] 2> 7 /solr/live_nodes
[junit4] 2> 5 /solr/collections/testMoveReplicaWithCollection_abc/state.json
[junit4] 2> 4 /solr/collections/testMoveReplicaWithCollection_xyz/state.json
[junit4] 2>
[junit4] 2> 408253 INFO (TEST-TestWithCollection.testMoveReplicaWithCollection-seed#[18A46F66ED756C74]) [ ] o.a.s.c.ZkTestServer waitForServerDown: 127.0.0.1:36307
[junit4] 2> 408253 INFO (TEST-TestWithCollection.testMoveReplicaWithCollection-seed#[18A46F66ED756C74]) [ ] o.a.s.c.ZkTestServer parse host and port list: 127.0.0.1:36307
[junit4] 2> 408253 INFO (TEST-TestWithCollection.testMoveReplicaWithCollection-seed#[18A46F66ED756C74]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1 36307
[junit4] 2> 408254 INFO (TEST-TestWithCollection.testMoveReplicaWithCollection-seed#[18A46F66ED756C74]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testMoveReplicaWithCollection
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.cloud.TestWithCollection_18A46F66ED756C74-001
[junit4] 2> Jan 18, 2021 7:31:15 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): {}, docValues:{}, maxPointsInLeafNode=104, maxMBSortInHeap=5.854637357576843, sim=Asserting(RandomSimilarity(queryNorm=true): {}), locale=es-DO, timezone=Australia/North
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=119299608,total=490209280
[junit4] 2> NOTE: All tests run in this JVM: [TestNumericTerms32, TestSystemCollAutoCreate, ClassificationUpdateProcessorIntegrationTest, HdfsDirectoryFactoryTest, TestIndexingPerformance, AnalysisAfterCoreReloadTest, TestMultiWordSynonyms, TestNumericRangeQuery32, SolrShardReporterTest, SpatialRPTFieldTypeTest, TriggerCooldownIntegrationTest, BJQParserTest, RequestHandlersTest, TestSmileRequest, TestSimpleTextCodec, TestReloadDeadlock, BufferStoreTest, CreateCollectionCleanupTest, HighlighterTest, BasicZkTest, RankQParserPluginTest, TestExplainDocTransformer, HdfsWriteToMultipleCollectionsTest, CheckHdfsIndexTest, TestHalfAndHalfDocValues, PeerSyncWithIndexFingerprintCachingTest, ClusterStateMockUtilTest, TestOmitPositions, ChaosMonkeySafeLeaderWithPullReplicasTest, SpellCheckCollatorTest, TestTolerantUpdateProcessorCloud, TestExtendedDismaxParser, TestWithCollection]
[junit4] Completed [173/923 (1!)] on J0 in 96.35s, 9 tests, 1 failure <<< FAILURES!
[...truncated 53328 lines...]
[JENKINS] Lucene » Lucene-Solr-Tests-8.8 - Build # 102 - Still Unstable!
Posted by Apache Jenkins Server <je...@builds.apache.org>.
Build: https://ci-builds.apache.org/job/Lucene/job/Lucene-Solr-Tests-8.8/102/
1 tests failed.
FAILED: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test
Error Message:
.responseHeader.status:200!=0
Stack Trace:
junit.framework.AssertionFailedError: .responseHeader.status:200!=0
at __randomizedtesting.SeedInfo.seed([B1046B49EC138550:3950549342EFE8A8]:0)
at junit.framework.Assert.fail(Assert.java:57)
at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:1006)
at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:1033)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:679)
at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:642)
at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:142)
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 org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
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.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 15690 lines...]
[junit4] Suite: org.apache.solr.handler.component.TestDistributedStatsComponentCardinality
[junit4] 2> 2027696 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[B1046B49EC138550]-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.8/solr/server/solr/configsets/_default/conf'
[junit4] 2> 2027696 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[B1046B49EC138550]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 2027696 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[B1046B49EC138550]-worker) [ ] o.a.s.SolrTestCaseJ4 Created dataDir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/data-dir-189-001
[junit4] 2> 2027696 WARN (SUITE-TestDistributedStatsComponentCardinality-seed#[B1046B49EC138550]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=5 numCloses=5
[junit4] 2> 2027696 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[B1046B49EC138550]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 2027698 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[B1046B49EC138550]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (false) and clientAuth (false) via: @org.apache.solr.SolrTestCaseJ4$SuppressSSL(bugUrl=https://issues.apache.org/jira/browse/SOLR-9062)
[junit4] 2> 2027698 INFO (SUITE-TestDistributedStatsComponentCardinality-seed#[B1046B49EC138550]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /vch/ea
[junit4] 2> 2027809 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/control/cores/collection1
[junit4] 2> 2027810 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2027810 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2027810 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 2027815 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2027815 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2027815 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2027816 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6a9104ef{/vch/ea,null,AVAILABLE}
[junit4] 2> 2027816 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@4da286dd{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:45941}
[junit4] 2> 2027816 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.Server Started @2027841ms
[junit4] 2> 2027816 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/vch/ea, hostPort=45941, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/control/cores}
[junit4] 2> 2027816 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2027816 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2027816 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.0
[junit4] 2> 2027816 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 2027816 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 2027816 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-18T17:03:58.084Z
[junit4] 2> 2027816 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/control/solr.xml
[junit4] 2> 2027819 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2027819 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2027820 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2eb0d115, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2028142 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2028143 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@2e3cfd8a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2028143 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@2e3cfd8a[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2028146 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@5f63eb11[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2028146 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@5f63eb11[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2028147 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] 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> 2028159 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2028159 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2028178 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2eb0d115
[junit4] 2> 2028185 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2eb0d115
[junit4] 2> 2028185 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2eb0d115
[junit4] 2> 2028187 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/control/cores
[junit4] 2> 2028187 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2028194 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2028211 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 2028237 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2028245 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2028252 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2028253 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2eb0d115
[junit4] 2> 2028253 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/control/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/control/cores/collection1/data/]
[junit4] 2> 2028254 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=54.037109375, floorSegmentMB=0.65234375, forceMergeDeletesPctAllowed=5.743021133844035, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=35.78089851769675
[junit4] 2> 2028319 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2028319 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2028321 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2028321 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2028322 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5317656005957059]
[junit4] 2> 2028324 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2028338 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2028412 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2028412 INFO (coreLoadExecutor-21638-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1689244861653319680
[junit4] 2> 2028413 INFO (searcherExecutor-21640-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2028414 INFO (searcherExecutor-21640-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2028414 INFO (searcherExecutor-21640-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2028414 INFO (searcherExecutor-21640-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2028414 INFO (searcherExecutor-21640-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2028414 INFO (searcherExecutor-21640-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2028415 INFO (searcherExecutor-21640-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2028415 INFO (searcherExecutor-21640-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2028416 INFO (searcherExecutor-21640-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2028418 INFO (searcherExecutor-21640-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2028525 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.SolrTestCaseJ4 Writing core.properties file to /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/shard0/cores/collection1
[junit4] 2> 2028526 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.s.e.JettySolrRunner Start Jetty (configured port=0, binding port=0)
[junit4] 2> 2028526 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.s.e.JettySolrRunner Trying to start Jetty on port 0 try number 2 ...
[junit4] 2> 2028526 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.Server jetty-9.4.34.v20201102; built: 2020-11-02T14:15:39.302Z; git: e46af88704a893fc12cb0e3bf46e2c7b48a009e7; jvm 1.8.0_252-b09
[junit4] 2> 2028529 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.session DefaultSessionIdManager workerName=node0
[junit4] 2> 2028529 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.session No SessionScavenger set, using defaults
[junit4] 2> 2028529 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.session node0 Scavenging every 600000ms
[junit4] 2> 2028530 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@2f3dd687{/vch/ea,null,AVAILABLE}
[junit4] 2> 2028530 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@39d736a9{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:40519}
[junit4] 2> 2028530 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.s.Server Started @2028555ms
[junit4] 2> 2028530 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/vch/ea, hostPort=40519, coreRootDirectory=/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/shard0/cores}
[junit4] 2> 2028530 ERROR (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 2028530 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.s.SolrDispatchFilter Using logger factory org.apache.logging.slf4j.Log4jLoggerFactory
[junit4] 2> 2028530 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 8.8.0
[junit4] 2> 2028530 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in standalone mode on port null
[junit4] 2> 2028530 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr
[junit4] 2> 2028530 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2021-01-18T17:03:58.798Z
[junit4] 2> 2028531 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/shard0/solr.xml
[junit4] 2> 2028533 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 2028533 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 2028535 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@2eb0d115, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 2029142 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.h.c.HttpShardHandlerFactory Host whitelist initialized: WhitelistHostChecker [whitelistHosts=null, whitelistHostCheckingEnabled=false]
[junit4] 2> 2029143 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@33e13480[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2029143 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@33e13480[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2029145 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.u.s.S.config Trusting all certificates configured for Client@3b3aa5a7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2029145 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.e.j.u.s.S.config No Client EndPointIdentificationAlgorithm configured for Client@3b3aa5a7[provider=null,keyStore=null,trustStore=null]
[junit4] 2> 2029147 WARN (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] 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> 2029158 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 2029158 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.h.a.MetricsHistoryHandler No .system collection, keeping metrics history in memory.
[junit4] 2> 2029180 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2eb0d115
[junit4] 2> 2029192 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2eb0d115
[junit4] 2> 2029192 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2eb0d115
[junit4] 2> 2029194 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/shard0/cores
[junit4] 2> 2029194 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.c.CorePropertiesLocator Cores are: [collection1]
[junit4] 2> 2029206 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.c.SolrResourceLoader Added 2 libs to classloader, from paths: [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/lib, /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1/lib/classes]
[junit4] 2> 2029222 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 8.8.0
[junit4] 2> 2029246 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Schema name=test
[junit4] 2> 2029253 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 2029260 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.c.CoreContainer Creating SolrCore 'collection1' using configuration from configset /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/core/src/test-files/solr/collection1, trusted=true
[junit4] 2> 2029260 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1' (registry 'solr.core.collection1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@2eb0d115
[junit4] 2> 2029261 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.c.SolrCore [[collection1] ] Opening new SolrCore at [/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/shard0/cores/collection1], dataDir=[/home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001/tempDir-001/shard0/cores/collection1/data/]
[junit4] 2> 2029262 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=49, maxMergeAtOnceExplicit=47, maxMergedSegmentMB=54.037109375, floorSegmentMB=0.65234375, forceMergeDeletesPctAllowed=5.743021133844035, segmentsPerTier=19.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0, deletesPctAllowed=35.78089851769675
[junit4] 2> 2029331 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 2029331 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 2029332 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 2029332 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 2029333 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=12, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5317656005957059]
[junit4] 2> 2029334 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Initializing spell checkers
[junit4] 2> 2029348 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.s.DirectSolrSpellChecker init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 2029410 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.h.ReplicationHandler Commits will be reserved for 10000 ms
[junit4] 2> 2029410 INFO (coreLoadExecutor-21657-thread-1) [ x:collection1 ] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1689244862699798528
[junit4] 2> 2029412 INFO (searcherExecutor-21659-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: default
[junit4] 2> 2029412 INFO (searcherExecutor-21659-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: direct
[junit4] 2> 2029412 INFO (searcherExecutor-21659-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: wordbreak
[junit4] 2> 2029412 INFO (searcherExecutor-21659-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: multipleFields
[junit4] 2> 2029412 INFO (searcherExecutor-21659-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: jarowinkler
[junit4] 2> 2029412 INFO (searcherExecutor-21659-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: external
[junit4] 2> 2029413 INFO (searcherExecutor-21659-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: freq
[junit4] 2> 2029413 INFO (searcherExecutor-21659-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: fqcn
[junit4] 2> 2029413 INFO (searcherExecutor-21659-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.h.c.SpellCheckComponent Loading spell index for spellchecker: perDict
[junit4] 2> 2029415 INFO (searcherExecutor-21659-thread-1-processing-x:collection1) [ x:collection1 ] o.a.s.c.SolrCore [collection1] Registered new searcher autowarm time: 0 ms
[junit4] 2> 2029920 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.SolrTestCaseJ4 ###Starting test
[junit4] 2> 2029920 INFO (TEST-TestDistributedStatsComponentCardinality.test-seed#[B1046B49EC138550]) [ ] o.a.s.h.c.TestDistributedStatsComponentCardinality Building an index of 14305 docs
[junit4] 2> 2029939 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[1 (1689244863248203776)]} 0 6
[junit4] 2> 2029948 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[1 (1689244863262883840)]} 0 1
[junit4] 2> 2029950 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[2 (1689244863264980992)]} 0 0
[junit4] 2> 2029950 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[2 (1689244863266029568)]} 0 0
[junit4] 2> 2029951 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[3 (1689244863267078144)]} 0 0
[junit4] 2> 2029951 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[3 (1689244863267078144)]} 0 0
[junit4] 2> 2029952 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[4 (1689244863268126720)]} 0 0
[junit4] 2> 2029953 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[4 (1689244863269175296)]} 0 0
[junit4] 2> 2029953 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[5 (1689244863269175296)]} 0 0
[junit4] 2> 2029954 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[5 (1689244863270223872)]} 0 0
[junit4] 2> 2029955 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[6 (1689244863271272448)]} 0 0
[junit4] 2> 2029955 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[6 (1689244863271272448)]} 0 0
[junit4] 2> 2029956 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[7 (1689244863271272449)]} 0 0
[junit4] 2> 2029956 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[7 (1689244863272321024)]} 0 0
[junit4] 2> 2029956 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[8 (1689244863272321024)]} 0 0
[junit4] 2> 2029957 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[8 (1689244863273369600)]} 0 0
[junit4] 2> 2029957 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[9 (1689244863273369600)]} 0 0
[junit4] 2> 2029958 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[9 (1689244863274418176)]} 0 0
[junit4] 2> 2029958 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[10 (1689244863274418176)]} 0 0
[junit4] 2> 2029959 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[10 (1689244863275466752)]} 0 0
[junit4] 2> 2029959 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[11 (1689244863275466752)]} 0 0
[junit4] 2> 2029959 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[11 (1689244863275466753)]} 0 0
[junit4] 2> 2029960 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[12 (1689244863276515328)]} 0 0
[junit4] 2> 2029960 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[12 (1689244863276515328)]} 0 0
[junit4] 2> 2029961 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[13 (1689244863277563904)]} 0 0
[junit4] 2> 2029961 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[13 (1689244863277563904)]} 0 0
[junit4] 2> 2029962 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[14 (1689244863278612480)]} 0 0
[junit4] 2> 2029962 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[14 (1689244863278612480)]} 0 0
[junit4] 2> 2029963 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[15 (1689244863279661056)]} 0 0
[junit4] 2> 2029963 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[15 (1689244863279661056)]} 0 0
[junit4] 2> 2029964 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[16 (1689244863280709632)]} 0 0
[junit4] 2> 2029964 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[16 (1689244863280709632)]} 0 0
[junit4] 2> 2029965 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[17 (1689244863281758208)]} 0 0
[junit4] 2> 2029965 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[17 (1689244863281758208)]} 0 0
[junit4] 2> 2029966 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[18 (1689244863282806784)]} 0 0
[junit4] 2> 2029967 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[18 (1689244863282806784)]} 0 0
[junit4] 2> 2029967 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[19 (1689244863283855360)]} 0 0
[junit4] 2> 2029968 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[19 (1689244863284903936)]} 0 0
[junit4] 2> 2029969 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[20 (1689244863284903936)]} 0 0
[junit4] 2> 2029969 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[20 (1689244863285952512)]} 0 0
[junit4] 2> 2029970 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[21 (1689244863287001088)]} 0 0
[junit4] 2> 2029970 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[21 (1689244863287001088)]} 0 0
[junit4] 2> 2029971 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[22 (1689244863288049664)]} 0 0
[junit4] 2> 2029971 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[22 (1689244863288049664)]} 0 0
[junit4] 2> 2029972 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[23 (1689244863289098240)]} 0 0
[junit4] 2> 2029972 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[23 (1689244863289098240)]} 0 0
[junit4] 2> 2029973 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[24 (1689244863290146816)]} 0 0
[junit4] 2> 2029974 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[24 (1689244863291195392)]} 0 0
[junit4] 2> 2029974 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[25 (1689244863291195392)]} 0 0
[junit4] 2> 2029975 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[25 (1689244863292243968)]} 0 0
[junit4] 2> 2029975 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[26 (1689244863292243968)]} 0 0
[junit4] 2> 2029976 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[26 (1689244863293292544)]} 0 0
[junit4] 2> 2029976 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[27 (1689244863293292544)]} 0 0
[junit4] 2> 2029977 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[27 (1689244863294341120)]} 0 0
[junit4] 2> 2029977 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[28 (1689244863294341120)]} 0 0
[junit4] 2> 2029978 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[28 (1689244863295389696)]} 0 0
[junit4] 2> 2029979 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[29 (1689244863296438272)]} 0 0
[junit4] 2> 2029980 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[29 (1689244863297486848)]} 0 0
[junit4] 2> 2029981 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[30 (1689244863297486848)]} 0 0
[junit4] 2> 2029981 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[30 (1689244863298535424)]} 0 0
[junit4] 2> 2029991 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[31 (1689244863298535424)]} 0 9
[junit4] 2> 2029992 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[31 (1689244863310069760)]} 0 0
[junit4] 2> 2029993 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[32 (1689244863311118336)]} 0 0
[junit4] 2> 2029994 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[32 (1689244863312166912)]} 0 0
[junit4] 2> 2029995 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[33 (1689244863313215488)]} 0 0
[junit4] 2> 2029996 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[33 (1689244863314264064)]} 0 0
[junit4] 2> 2029997 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[34 (1689244863315312640)]} 0 0
[junit4] 2> 2029998 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[34 (1689244863316361216)]} 0 0
[junit4] 2> 2029999 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[35 (1689244863317409792)]} 0 0
[junit4] 2> 2030000 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[35 (1689244863318458368)]} 0 0
[junit4] 2> 2030000 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[36 (1689244863318458368)]} 0 0
[junit4] 2> 2030001 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[36 (1689244863319506944)]} 0 0
[junit4] 2> 2030001 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[37 (1689244863319506944)]} 0 0
[junit4] 2> 2030002 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[37 (1689244863320555520)]} 0 0
[junit4] 2> 2030002 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[38 (1689244863320555520)]} 0 0
[junit4] 2> 2030003 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[38 (1689244863321604096)]} 0 0
[junit4] 2> 2030003 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[39 (1689244863321604096)]} 0 0
[junit4] 2> 2030004 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[39 (1689244863322652672)]} 0 0
[junit4] 2> 2030005 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[40 (1689244863323701248)]} 0 0
[junit4] 2> 2030006 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[40 (1689244863323701248)]} 0 0
[junit4] 2> 2030006 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[41 (1689244863324749824)]} 0 0
[junit4] 2> 2030007 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[41 (1689244863325798400)]} 0 0
[junit4] 2> 2030008 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[42 (1689244863325798400)]} 0 0
[junit4] 2> 2030008 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[42 (1689244863326846976)]} 0 0
[junit4] 2> 2030009 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[43 (1689244863327895552)]} 0 0
[junit4] 2> 2030010 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[43 (1689244863328944128)]} 0 0
[junit4] 2> 2030010 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[44 (1689244863328944128)]} 0 0
[junit4] 2> 2030011 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[44 (1689244863329992704)]} 0 0
[junit4] 2> 2030012 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[45 (1689244863331041280)]} 0 0
[junit4] 2> 2030014 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[45 (1689244863333138432)]} 0 0
[junit4] 2> 2030015 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[46 (1689244863334187008)]} 0 0
[junit4] 2> 2030016 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[46 (1689244863334187008)]} 0 0
[junit4] 2> 2030016 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[47 (1689244863335235584)]} 0 0
[junit4] 2> 2030017 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[47 (1689244863336284160)]} 0 0
[junit4] 2> 2030018 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[48 (1689244863337332736)]} 0 0
[junit4] 2> 2030019 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[48 (1689244863338381312)]} 0 0
[junit4] 2> 2030021 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[49 (1689244863340478464)]} 0 0
[junit4] 2> 2030022 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[49 (1689244863341527040)]} 0 0
[junit4] 2> 2030023 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[50 (1689244863342575616)]} 0 0
[junit4] 2> 2030024 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[50 (1689244863343624192)]} 0 0
[junit4] 2> 2030025 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[51 (1689244863344672768)]} 0 0
[junit4] 2> 2030027 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[51 (1689244863345721344)]} 0 0
[junit4] 2> 2030027 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[52 (1689244863346769920)]} 0 0
[junit4] 2> 2030028 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[52 (1689244863347818496)]} 0 0
[junit4] 2> 2030029 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[53 (1689244863348867072)]} 0 0
[junit4] 2> 2030030 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[53 (1689244863349915648)]} 0 0
[junit4] 2> 2030031 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[54 (1689244863350964224)]} 0 0
[junit4] 2> 2030031 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[54 (1689244863350964224)]} 0 0
[junit4] 2> 2030032 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[55 (1689244863352012800)]} 0 0
[junit4] 2> 2030032 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[55 (1689244863352012800)]} 0 0
[junit4] 2> 2030033 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[56 (1689244863353061376)]} 0 0
[junit4] 2> 2030033 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[56 (1689244863353061376)]} 0 0
[junit4] 2> 2030034 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[57 (1689244863354109952)]} 0 0
[junit4] 2> 2030034 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[57 (1689244863354109952)]} 0 0
[junit4] 2> 2030035 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[58 (1689244863354109953)]} 0 0
[junit4] 2> 2030035 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[58 (1689244863355158528)]} 0 0
[junit4] 2> 2030035 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[59 (1689244863355158528)]} 0 0
[junit4] 2> 2030036 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[59 (1689244863356207104)]} 0 0
[junit4] 2> 2030036 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[60 (1689244863356207104)]} 0 0
[junit4] 2> 2030037 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[60 (1689244863357255680)]} 0 0
[junit4] 2> 2030037 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[61 (1689244863357255680)]} 0 0
[junit4] 2> 2030038 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[61 (1689244863358304256)]} 0 0
[junit4] 2> 2030038 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[62 (1689244863358304256)]} 0 0
[junit4] 2> 2030039 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[62 (1689244863359352832)]} 0 0
[junit4] 2> 2030039 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[63 (1689244863359352832)]} 0 0
[junit4] 2> 2030040 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[63 (1689244863360401408)]} 0 0
[junit4] 2> 2030040 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[64 (1689244863360401408)]} 0 0
[junit4] 2> 2030041 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[64 (1689244863360401409)]} 0 0
[junit4] 2> 2030041 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[65 (1689244863361449984)]} 0 0
[junit4] 2> 2030041 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[65 (1689244863361449984)]} 0 0
[junit4] 2> 2030042 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[66 (1689244863362498560)]} 0 0
[junit4] 2> 2030042 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[66 (1689244863362498560)]} 0 0
[junit4] 2> 2030043 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[67 (1689244863363547136)]} 0 0
[junit4] 2> 2030043 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[67 (1689244863363547136)]} 0 0
[junit4] 2> 2030044 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[68 (1689244863364595712)]} 0 0
[junit4] 2> 2030044 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[68 (1689244863364595712)]} 0 0
[junit4] 2> 2030045 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[69 (1689244863365644288)]} 0 0
[junit4] 2> 2030045 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[69 (1689244863365644288)]} 0 0
[junit4] 2> 2030046 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[70 (1689244863366692864)]} 0 0
[junit4] 2> 2030046 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[70 (1689244863366692864)]} 0 0
[junit4] 2> 2030047 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[71 (1689244863366692865)]} 0 0
[junit4] 2> 2030047 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[71 (1689244863367741440)]} 0 0
[junit4] 2> 2030047 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[72 (1689244863367741440)]} 0 0
[junit4] 2> 2030048 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[72 (1689244863368790016)]} 0 0
[junit4] 2> 2030048 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[73 (1689244863368790016)]} 0 0
[junit4] 2> 2030049 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[73 (1689244863369838592)]} 0 0
[junit4] 2> 2030049 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[74 (1689244863369838592)]} 0 0
[junit4] 2> 2030050 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[74 (1689244863369838593)]} 0 0
[junit4] 2> 2030050 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[75 (1689244863370887168)]} 0 0
[junit4] 2> 2030050 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[75 (1689244863370887168)]} 0 0
[junit4] 2> 2030051 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[76 (1689244863371935744)]} 0 0
[junit4] 2> 2030051 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[76 (1689244863371935744)]} 0 0
[junit4] 2> 2030052 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[77 (1689244863372984320)]} 0 0
[junit4] 2> 2030269 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[77 (1689244863600525312)]} 0 0
[junit4] 2> 2030273 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[78 (1689244863603671040)]} 0 0
[junit4] 2> 2030273 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[78 (1689244863604719616)]} 0 0
[junit4] 2> 2030274 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[79 (1689244863605768192)]} 0 0
[junit4] 2> 2030275 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[79 (1689244863606816768)]} 0 0
[junit4] 2> 2030276 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[80 (1689244863607865344)]} 0 0
[junit4] 2> 2030278 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[80 (1689244863609962496)]} 0 0
[junit4] 2> 2030278 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[81 (1689244863609962496)]} 0 0
[junit4] 2> 2030280 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[81 (1689244863612059648)]} 0 0
[junit4] 2> 2030281 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[82 (1689244863613108224)]} 0 0
[junit4] 2> 2030281 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[82 (1689244863613108224)]} 0 0
[junit4] 2> 2030282 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[83 (1689244863614156800)]} 0 0
[junit4] 2> 2030282 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[83 (1689244863614156800)]} 0 0
[junit4] 2> 2030283 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[84 (1689244863615205376)]} 0 0
[junit4] 2> 2030283 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[84 (1689244863615205376)]} 0 0
[junit4] 2> 2030284 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[85 (1689244863616253952)]} 0 0
[junit4] 2> 2030286 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[85 (1689244863618351104)]} 0 0
[junit4] 2> 2030287 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[86 (1689244863619399680)]} 0 0
[junit4] 2> 2030287 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[86 (1689244863619399680)]} 0 0
[junit4] 2> 2030287 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[87 (1689244863619399681)]} 0 0
[junit4] 2> 2030288 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[87 (1689244863620448256)]} 0 0
[junit4] 2> 2030288 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[88 (1689244863620448256)]} 0 0
[junit4] 2> 2030289 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[88 (1689244863621496832)]} 0 0
[junit4] 2> 2030289 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[89 (1689244863621496832)]} 0 0
[junit4] 2> 2030290 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[89 (1689244863622545408)]} 0 0
[junit4] 2> 2030290 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[90 (1689244863622545408)]} 0 0
[junit4] 2> 2030291 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[90 (1689244863623593984)]} 0 0
[junit4] 2> 2030291 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[91 (1689244863623593984)]} 0 0
[junit4] 2> 2030292 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[91 (1689244863624642560)]} 0 0
[junit4] 2> 2030292 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[92 (1689244863624642560)]} 0 0
[junit4] 2> 2030293 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[92 (1689244863625691136)]} 0 0
[junit4] 2> 2030293 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[93 (1689244863625691136)]} 0 0
[junit4] 2> 2030294 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[93 (1689244863626739712)]} 0 0
[junit4] 2> 2030294 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[94 (1689244863626739712)]} 0 0
[junit4] 2> 2030295 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[94 (1689244863627788288)]} 0 0
[junit4] 2> 2030295 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[95 (1689244863627788288)]} 0 0
[junit4] 2> 2030296 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[95 (1689244863628836864)]} 0 0
[junit4] 2> 2030296 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[96 (1689244863628836864)]} 0 0
[junit4] 2> 2030297 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[96 (1689244863629885440)]} 0 0
[junit4] 2> 2030298 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[97 (1689244863630934016)]} 0 0
[junit4] 2> 2030298 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[97 (1689244863630934016)]} 0 0
[junit4] 2> 2030299 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[98 (1689244863631982592)]} 0 0
[junit4] 2> 2030299 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[98 (1689244863631982592)]} 0 0
[junit4] 2> 2030300 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[99 (1689244863633031168)]} 0 0
[junit4] 2> 2030300 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[99 (1689244863633031168)]} 0 0
[junit4] 2> 2030300 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[100 (1689244863633031169)]} 0 0
[junit4] 2> 2030301 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[100 (1689244863634079744)]} 0 0
[junit4] 2> 2030301 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[101 (1689244863634079744)]} 0 0
[junit4] 2> 2030302 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[101 (1689244863635128320)]} 0 0
[junit4] 2> 2030302 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[102 (1689244863635128320)]} 0 0
[junit4] 2> 2030303 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[102 (1689244863636176896)]} 0 0
[junit4] 2> 2030303 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[103 (1689244863636176896)]} 0 0
[junit4] 2> 2030304 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[103 (1689244863636176897)]} 0 0
[junit4] 2> 2030304 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[104 (1689244863637225472)]} 0 0
[junit4] 2> 2030304 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[104 (1689244863637225472)]} 0 0
[junit4] 2> 2030305 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[105 (1689244863638274048)]} 0 0
[junit4] 2> 2030305 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[105 (1689244863638274048)]} 0 0
[junit4] 2> 2030306 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[106 (1689244863639322624)]} 0 0
[junit4] 2> 2030306 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[106 (1689244863639322624)]} 0 0
[junit4] 2> 2030306 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[107 (1689244863639322625)]} 0 0
[junit4] 2> 2030307 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[107 (1689244863640371200)]} 0 0
[junit4] 2> 2030307 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[108 (1689244863640371200)]} 0 0
[junit4] 2> 2030308 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[108 (1689244863641419776)]} 0 0
[junit4] 2> 2030308 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[109 (1689244863641419776)]} 0 0
[junit4] 2> 2030309 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[109 (1689244863642468352)]} 0 0
[junit4] 2> 2030309 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[110 (1689244863642468352)]} 0 0
[junit4] 2> 2030309 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[110 (1689244863642468353)]} 0 0
[junit4] 2> 2030310 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[111 (1689244863643516928)]} 0 0
[junit4] 2> 2030310 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[111 (1689244863643516928)]} 0 0
[junit4] 2> 2030311 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[112 (1689244863644565504)]} 0 0
[junit4] 2> 2030311 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[112 (1689244863644565504)]} 0 0
[junit4] 2> 2030312 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[113 (1689244863645614080)]} 0 0
[junit4] 2> 2030312 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[113 (1689244863645614080)]} 0 0
[junit4] 2> 2030313 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[114 (1689244863645614081)]} 0 0
[junit4] 2> 2030313 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[114 (1689244863646662656)]} 0 0
[junit4] 2> 2030313 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[115 (1689244863646662656)]} 0 0
[junit4] 2> 2030314 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[115 (1689244863647711232)]} 0 0
[junit4] 2> 2030314 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[116 (1689244863647711232)]} 0 0
[junit4] 2> 2030315 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[116 (1689244863648759808)]} 0 0
[junit4] 2> 2030315 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[117 (1689244863648759808)]} 0 0
[junit4] 2> 2030316 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[117 (1689244863648759809)]} 0 0
[junit4] 2> 2030316 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[118 (1689244863649808384)]} 0 0
[junit4] 2> 2030316 INFO (qtp762534979-36645) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[118 (1689244863649808384)]} 0 0
[junit4] 2> 2030317 INFO (qtp1088864970-36625) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[119 (1689244863650856960)]} 0 0
[junit4] 2> 2030317 INFO (qtp762534979-36646) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[119 (1689244863650856960)]} 0 0
[junit4] 2> 2030318 INFO (qtp1088864970-36621) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[120 (1689244863651905536)]} 0 0
[junit4] 2> 2030318 INFO (qtp762534979-36642) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[120 (1689244863651905536)]} 0 0
[junit4] 2> 2030319 INFO (qtp1088864970-36622) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[121 (1689244863652954112)]} 0 0
[junit4] 2> 2030319 INFO (qtp762534979-36643) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[121 (1689244863652954112)]} 0 0
[junit4] 2> 2030319 INFO (qtp1088864970-36623) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[122 (1689244863652954113)]} 0 0
[junit4] 2> 2030320 INFO (qtp762534979-36644) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=javabin&version=2}{add=[122 (1689244863654002688)]} 0 0
[junit4] 2> 2030320 INFO (qtp1088864970-36624) [ x:collection1 ] o.a.s.u.p.LogUpdateProcessorFactory [collection1] webapp=/vch/ea path=/update params={wt=java
[...truncated too long message...]
loseThreadPool-21665-thread-2) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@4da286dd{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2070837 INFO (closeThreadPool-21665-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node tag=null
[junit4] 2> 2070837 INFO (closeThreadPool-21665-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@75616ce7: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node/com.codahale.metrics.MetricRegistry@7beabba
[junit4] 2> 2070842 INFO (closeThreadPool-21665-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm tag=null
[junit4] 2> 2070842 INFO (closeThreadPool-21665-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@791caa91: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm/com.codahale.metrics.MetricRegistry@4d587a99
[junit4] 2> 2070843 INFO (closeThreadPool-21665-thread-2) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6a9104ef{/vch/ea,null,STOPPED}
[junit4] 2> 2070843 INFO (closeThreadPool-21665-thread-2) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> 2070844 INFO (closeThreadPool-21665-thread-4) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty tag=null
[junit4] 2> 2070844 INFO (closeThreadPool-21665-thread-4) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@5367f1c9: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty/com.codahale.metrics.MetricRegistry@5f3108ed
[junit4] 2> 2070847 INFO (closeThreadPool-21665-thread-4) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@39d736a9{HTTP/1.1, (http/1.1, h2c)}{127.0.0.1:0}
[junit4] 2> 2070847 INFO (closeThreadPool-21665-thread-4) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@2f3dd687{/vch/ea,null,STOPPED}
[junit4] 2> 2070847 INFO (closeThreadPool-21665-thread-4) [ ] o.e.j.s.session node0 Stopped scavenging
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=TestDistributedStatsComponentCardinality -Dtests.method=test -Dtests.seed=B1046B49EC138550 -Dtests.multiplier=2 -Dtests.slow=true -Dtests.locale=sr-ME -Dtests.timezone=NET -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 43.2s J0 | TestDistributedStatsComponentCardinality.test <<<
[junit4] > Throwable #1: junit.framework.AssertionFailedError: .responseHeader.status:200!=0
[junit4] > at __randomizedtesting.SeedInfo.seed([B1046B49EC138550:3950549342EFE8A8]:0)
[junit4] > at junit.framework.Assert.fail(Assert.java:57)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareSolrResponses(BaseDistributedSearchTestCase.java:1006)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.compareResponses(BaseDistributedSearchTestCase.java:1033)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:679)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.query(BaseDistributedSearchTestCase.java:642)
[junit4] > at org.apache.solr.handler.component.TestDistributedStatsComponentCardinality.test(TestDistributedStatsComponentCardinality.java:142)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:1090)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:1061)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/jenkins-slave/workspace/Lucene/Lucene-Solr-Tests-8.8/solr/build/solr-core/test/J0/temp/solr.handler.component.TestDistributedStatsComponentCardinality_B1046B49EC138550-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene87): {multiDefault=Lucene84, _root_=PostingsFormat(name=LuceneVarGapDocFreqInterval), string_s=PostingsFormat(name=Asserting), id=Lucene84}, docValues:{int_i=DocValuesFormat(name=Lucene80), range_facet_l_dv=DocValuesFormat(name=Direct), intDefault=DocValuesFormat(name=Asserting), int_i_prehashed_l=DocValuesFormat(name=Lucene80), range_facet_l=DocValuesFormat(name=Asserting), long_l_prehashed_l=DocValuesFormat(name=Lucene80), long_l=DocValuesFormat(name=Direct), _version_=DocValuesFormat(name=Asserting), string_s_prehashed_l=DocValuesFormat(name=Direct), id_i1=DocValuesFormat(name=Lucene80), range_facet_i_dv=DocValuesFormat(name=Asserting), intDvoDefault=DocValuesFormat(name=Lucene80), timestamp=DocValuesFormat(name=Asserting)}, maxPointsInLeafNode=1700, maxMBSortInHeap=6.496248782779806, sim=Asserting(RandomSimilarity(queryNorm=false): {}), locale=sr-ME, timezone=NET
[junit4] 2> NOTE: Linux 4.15.0-112-generic amd64/Oracle Corporation 1.8.0_252 (64-bit)/cpus=4,threads=1,free=279510336,total=530579456
[junit4] 2> NOTE: All tests run in this JVM: [HdfsAutoAddReplicasIntegrationTest, SpellCheckCollatorTest, AssignTest, CircularListTest, ShardsWhitelistTest, TestReplicationHandlerDiskOverFlow, TestCloudJSONFacetJoinDomain, SolrIndexSearcherTest, UnloadDistributedZkTest, TestLuceneIndexBackCompat, TestCustomDocTransformer, TestFieldResource, TestConfig, MetricUtilsTest, DistributedFacetPivotWhiteBoxTest, IgnoreCommitOptimizeUpdateProcessorFactoryTest, CdcrRequestHandlerTest, DimensionalRoutedAliasUpdateProcessorTest, TestHighlightDedupGrouping, SliceStateTest, TestSolrCoreParser, AutoAddReplicasPlanActionTest, TestDistribDocBasedVersion, TestTermsQParserPlugin, TestCircuitBreaker, ParsingFieldUpdateProcessorsTest, AtomicUpdateProcessorFactoryTest, CoreSorterTest, TestIndexSearcher, TestTolerantUpdateProcessorCloud, OverseerSolrResponseTest, TestComponentsName, CdcrBidirectionalTest, TestSimNodeAddedTrigger, JSONWriterTest, TestCoreAdminApis, SoftAutoCommitTest, MoreLikeThisHandlerTest, SplitByPrefixTest, TestDefaultStatsCache, ConjunctionSolrSpellCheckerTest, TestSystemCollAutoCreate, PathHierarchyTokenizerFactoryTest, TestPullReplica, TestInPlaceUpdateWithRouteField, ReplaceNodeTest, SplitShardTest, RandomizedTaggerTest, MultiThreadedOCPTest, TestImplicitCoreProperties, TestDocTermOrdsUninvertLimit, TestMinMaxOnMultiValuedField, TestSort, DynamicMapsTest, SolrJmxReporterCloudTest, TestBlobHandler, BadComponentTest, TestReloadDeadlock, HdfsWriteToMultipleCollectionsTest, LeaderElectionContextKeyTest, TestSuggestSpellingConverter, TestNumericRangeQuery32, ShardRoutingCustomTest, HighlighterTest, MetricsHistoryHandlerTest, TestSha256AuthenticationProvider, BasicZkTest, TestNumericTerms32, TestTrackingShardHandlerFactory, DisMaxRequestHandlerTest, TestSmileRequest, ChaosMonkeySafeLeaderWithPullReplicasTest, MaxScoreCollectorTest, BJQParserTest, HdfsNNFailoverTest, TestSimExecutePlanAction, HLLSerializationTest, TestUseDocValuesAsStored2, OutputWriterTest, TestHighFrequencyDictionaryFactory, TestPerFieldSimilarity, TimeRoutedAliasUpdateProcessorTest, RequiredFieldsTest, TestWithCollection, HdfsSyncSliceTest, TestExclusionRuleCollectionAccess, TestUniqueKeyFieldResource, SolrLogAuditLoggerPluginTest, TestCoreBackup, PeerSyncWithIndexFingerprintCachingTest, BasicAuthOnSingleNodeTest, TestScoreJoinQPNoScore, SpatialRPTFieldTypeTest, AnalysisAfterCoreReloadTest, ClusterStateMockUtilTest, RecoveryZkTest, TestSchemaResource, DistributedQueryElevationComponentTest, XmlInterpolationTest, TestFaceting, ScriptEngineTest, TestLocalFSCloudBackupRestore, TriggerCooldownIntegrationTest, TestSchemalessBufferedUpdates, MoveReplicaHDFSFailoverTest, TestLegacyFieldReuse, ShowFileRequestHandlerTest, TestOmitPositions, TestSQLHandlerNonCloud, TestLMDirichletSimilarityFactory, TestStressReorder, TestJsonFacetRefinement, TestSolrCloudSnapshots, OverriddenZkACLAndCredentialsProvidersTest, CursorPagingTest, SolrTestCaseJ4DeleteCoreTest, SolrTestCaseJ4Test, TestCrossCoreJoin, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestDistributedSearch, TestSimpleTrackingShardHandler, TestSolrCoreProperties, TestTolerantSearch, TestTrie, TestDeprecatedFilters, TestLuceneMatchVersion, TestReversedWildcardFilterFactory, TestWordDelimiterFilterFactory, TokenizerChainTest, TestEmbeddedSolrServerSchemaAPI, ConnectionReuseTest, AssignBackwardCompatibilityTest, BasicDistributedZk2Test, ConcurrentCreateRoutedAliasTest, ConnectionManagerTest, CreateCollectionCleanupTest, CreateRoutedAliasTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteNodeTest, DeleteReplicaTest, DistributedQueueTest, DocValuesNotIndexedTest, ForceLeaderTest, FullSolrCloudDistribCmdsTest, LeaderFailureAfterFreshStartTest, MetricsHistoryIntegrationTest, MissingSegmentRecoveryTest, OverseerTest, ReplaceNodeNoTargetTest, RollingRestartTest, TestCloudConsistency, TestLockTree, TestMiniSolrCloudClusterSSL, TestSegmentSorting, TestStressLiveNodes, IndexSizeTriggerTest, MetricTriggerIntegrationTest, NodeAddedTriggerIntegrationTest, NodeLostTriggerIntegrationTest, NodeMarkersRegistrationTest, ScheduledMaintenanceTriggerTest, TestSimComputePlanAction, TestSimDistribStateManager, TestSimLargeCluster, TestDynamicLoading, TestXIncludeConfig, DocumentAnalysisRequestHandlerTest, JsonLoaderTest, TestReplicationHandlerBackup, XmlUpdateRequestHandlerTest, XsltUpdateRequestHandlerTest, AdminHandlersProxyTest, CoreAdminHandlerTest, CoreAdminRequestStatusTest, IndexSizeEstimatorTest, MBeansHandlerTest, FacetPivotSmallTest, QueryElevationComponentTest, TermVectorComponentTest, TestDistributedStatsComponentCardinality]
[junit4] Completed [721/923 (1!)] on J0 in 43.25s, 1 test, 1 failure <<< FAILURES!
[...truncated 51429 lines...]