You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2017/12/10 21:27:51 UTC
[JENKINS] Lucene-Solr-7.2-Linux (64bit/jdk-9.0.1) - Build # 42 -
Still Unstable!
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.2-Linux/42/
Java: 64bit/jdk-9.0.1 -XX:+UseCompressedOops -XX:+UseParallelGC
1 tests failed.
FAILED: org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud
Error Message:
Error from server at https://127.0.0.1:44025/solr: Could not fully create collection: legacyFalse
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:44025/solr: Could not fully create collection: legacyFalse
at __randomizedtesting.SeedInfo.seed([A706FB3522ABD877:760109B086A45345]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1103)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:883)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.cloud.LegacyCloudClusterPropTest.createAndTest(LegacyCloudClusterPropTest.java:86)
at org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud(LegacyCloudClusterPropTest.java:70)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 11960 lines...]
[junit4] Suite: org.apache.solr.cloud.LegacyCloudClusterPropTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LegacyCloudClusterPropTest_A706FB3522ABD877-001/init-core-data-001
[junit4] 2> 287242 INFO (SUITE-LegacyCloudClusterPropTest-seed#[A706FB3522ABD877]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 287242 INFO (SUITE-LegacyCloudClusterPropTest-seed#[A706FB3522ABD877]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason="", ssl=0.0/0.0, value=0.0/0.0, clientAuth=0.0/0.0)
[junit4] 2> 287243 INFO (SUITE-LegacyCloudClusterPropTest-seed#[A706FB3522ABD877]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 287243 INFO (SUITE-LegacyCloudClusterPropTest-seed#[A706FB3522ABD877]-worker) [ ] o.a.s.c.MiniSolrCloudCluster Starting cluster of 1 servers in /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LegacyCloudClusterPropTest_A706FB3522ABD877-001/tempDir-001
[junit4] 2> 287243 INFO (SUITE-LegacyCloudClusterPropTest-seed#[A706FB3522ABD877]-worker) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 287244 INFO (Thread-1049) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 287244 INFO (Thread-1049) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 287247 ERROR (Thread-1049) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 287344 INFO (SUITE-LegacyCloudClusterPropTest-seed#[A706FB3522ABD877]-worker) [ ] o.a.s.c.ZkTestServer start zk server on port:44699
[junit4] 2> 287346 INFO (zkConnectionManagerCallback-511-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 287352 INFO (jetty-launcher-508-thread-1) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 287353 INFO (jetty-launcher-508-thread-1) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@6681b7de{/solr,null,AVAILABLE}
[junit4] 2> 287355 INFO (jetty-launcher-508-thread-1) [ ] o.e.j.s.AbstractConnector Started ServerConnector@14906341{SSL,[ssl, http/1.1]}{127.0.0.1:44025}
[junit4] 2> 287355 INFO (jetty-launcher-508-thread-1) [ ] o.e.j.s.Server Started @289238ms
[junit4] 2> 287355 INFO (jetty-launcher-508-thread-1) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44025}
[junit4] 2> 287356 ERROR (jetty-launcher-508-thread-1) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 287356 INFO (jetty-launcher-508-thread-1) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.2.0
[junit4] 2> 287356 INFO (jetty-launcher-508-thread-1) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 287356 INFO (jetty-launcher-508-thread-1) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 287356 INFO (jetty-launcher-508-thread-1) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-10T20:44:30.060227Z
[junit4] 2> 287357 INFO (zkConnectionManagerCallback-513-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 287358 INFO (jetty-launcher-508-thread-1) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 287365 INFO (jetty-launcher-508-thread-1) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44699/solr
[junit4] 2> 287366 INFO (zkConnectionManagerCallback-517-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 287369 INFO (zkConnectionManagerCallback-519-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 287424 INFO (jetty-launcher-508-thread-1) [n:127.0.0.1:44025_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 287425 INFO (jetty-launcher-508-thread-1) [n:127.0.0.1:44025_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44025_solr
[junit4] 2> 287425 INFO (jetty-launcher-508-thread-1) [n:127.0.0.1:44025_solr ] o.a.s.c.Overseer Overseer (id=99151948673712131-127.0.0.1:44025_solr-n_0000000000) starting
[junit4] 2> 287432 INFO (jetty-launcher-508-thread-1) [n:127.0.0.1:44025_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44025_solr
[junit4] 2> 287433 INFO (OverseerStateUpdate-99151948673712131-127.0.0.1:44025_solr-n_0000000000) [n:127.0.0.1:44025_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 287484 INFO (jetty-launcher-508-thread-1) [n:127.0.0.1:44025_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c94c77e
[junit4] 2> 287489 INFO (jetty-launcher-508-thread-1) [n:127.0.0.1:44025_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c94c77e
[junit4] 2> 287489 INFO (jetty-launcher-508-thread-1) [n:127.0.0.1:44025_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c94c77e
[junit4] 2> 287490 INFO (jetty-launcher-508-thread-1) [n:127.0.0.1:44025_solr ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LegacyCloudClusterPropTest_A706FB3522ABD877-001/tempDir-001/node1/.
[junit4] 2> 287497 INFO (zkConnectionManagerCallback-523-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 287499 INFO (zkConnectionManagerCallback-527-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 287500 INFO (SUITE-LegacyCloudClusterPropTest-seed#[A706FB3522ABD877]-worker) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 287500 INFO (SUITE-LegacyCloudClusterPropTest-seed#[A706FB3522ABD877]-worker) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:44699/solr ready
[junit4] 2> 287504 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.SolrTestCaseJ4 ###Starting testCreateCollectionSwitchLegacyCloud
[junit4] 2> 287514 INFO (qtp537261048-3565) [n:127.0.0.1:44025_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params val=true&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 287515 INFO (qtp537261048-3565) [n:127.0.0.1:44025_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={val=true&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2} status=0 QTime=1
[junit4] 2> 287516 INFO (qtp537261048-3570) [n:127.0.0.1:44025_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=legacyTrue&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 287520 INFO (OverseerThreadFactory-1178-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.CreateCollectionCmd Create collection legacyTrue
[junit4] 2> 287626 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf&newCollection=true&name=legacyTrue_shard1_replica_n1&action=CREATE&numShards=1&collection=legacyTrue&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 287627 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 287729 INFO (zkCallback-518-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 288636 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 288642 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 288644 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 288644 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'legacyTrue_shard1_replica_n1' using configuration from collection legacyTrue, trusted=true
[junit4] 2> 288644 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.core.legacyTrue.shard1.replica_n1' (registry 'solr.core.legacyTrue.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c94c77e
[junit4] 2> 288644 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 288644 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore [[legacyTrue_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LegacyCloudClusterPropTest_A706FB3522ABD877-001/tempDir-001/node1/legacyTrue_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LegacyCloudClusterPropTest_A706FB3522ABD877-001/tempDir-001/node1/./legacyTrue_shard1_replica_n1/data/]
[junit4] 2> 288681 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 288681 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 288682 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 288682 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 288683 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@641ca904[legacyTrue_shard1_replica_n1] main]
[junit4] 2> 288683 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 288683 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 288684 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 288684 INFO (searcherExecutor-1181-thread-1-processing-n:127.0.0.1:44025_solr x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] Registered new searcher Searcher@641ca904[legacyTrue_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 288684 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586431180289343488
[junit4] 2> 288687 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 288687 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 288687 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:44025/solr/legacyTrue_shard1_replica_n1/
[junit4] 2> 288687 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 288687 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:44025/solr/legacyTrue_shard1_replica_n1/ has no replicas
[junit4] 2> 288687 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 288688 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:44025/solr/legacyTrue_shard1_replica_n1/ shard1
[junit4] 2> 288789 INFO (zkCallback-518-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 288839 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 288845 INFO (qtp537261048-3572) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf&newCollection=true&name=legacyTrue_shard1_replica_n1&action=CREATE&numShards=1&collection=legacyTrue&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1218
[junit4] 2> 288848 INFO (qtp537261048-3570) [n:127.0.0.1:44025_solr ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 288942 INFO (zkCallback-518-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 289521 INFO (OverseerCollectionConfigSetProcessor-99151948673712131-127.0.0.1:44025_solr-n_0000000000) [n:127.0.0.1:44025_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> 289848 INFO (qtp537261048-3570) [n:127.0.0.1:44025_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=legacyTrue&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=0 QTime=2332
[junit4] 2> 289854 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@14906341{SSL,[ssl, http/1.1]}{127.0.0.1:0}
[junit4] 2> 289854 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=1168783369
[junit4] 2> 289855 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 289855 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ca25fe: rootName = solr_44025, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@475366f1
[junit4] 2> 289858 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 289858 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@75aa9353: rootName = solr_44025, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@6d62bdc2
[junit4] 2> 289861 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 289861 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3128ef98: rootName = solr_44025, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7025b30b
[junit4] 2> 289862 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44025_solr
[junit4] 2> 289862 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 289862 INFO (zkCallback-518-thread-2-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 289862 INFO (zkCallback-526-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 289863 INFO (coreCloseExecutor-1186-thread-1) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@658374da
[junit4] 2> 289863 INFO (coreCloseExecutor-1186-thread-1) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.legacyTrue.shard1.replica_n1, tag=1703113946
[junit4] 2> 289863 INFO (coreCloseExecutor-1186-thread-1) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@190d8673: rootName = solr_44025, domain = solr.core.legacyTrue.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.legacyTrue.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@2104f540
[junit4] 2> 289867 INFO (coreCloseExecutor-1186-thread-1) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.legacyTrue.shard1.leader, tag=1703113946
[junit4] 2> 289869 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.c.Overseer Overseer (id=99151948673712131-127.0.0.1:44025_solr-n_0000000000) closing
[junit4] 2> 289869 INFO (OverseerStateUpdate-99151948673712131-127.0.0.1:44025_solr-n_0000000000) [n:127.0.0.1:44025_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:44025_solr
[junit4] 2> 289869 WARN (OverseerAutoScalingTriggerThread-99151948673712131-127.0.0.1:44025_solr-n_0000000000) [n:127.0.0.1:44025_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 289870 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@6681b7de{/solr,null,UNAVAILABLE}
[junit4] 2> 289871 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 289871 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@175d754{/solr,null,AVAILABLE}
[junit4] 2> 289872 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@5220098d{SSL,[ssl, http/1.1]}{127.0.0.1:44025}
[junit4] 2> 289872 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.e.j.s.Server Started @291754ms
[junit4] 2> 289872 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {hostContext=/solr, hostPort=44025}
[junit4] 2> 289872 ERROR (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 289872 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.2.0
[junit4] 2> 289872 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 289872 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 289872 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-10T20:44:32.576656Z
[junit4] 2> 289875 INFO (zkConnectionManagerCallback-529-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 289875 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.s.SolrDispatchFilter solr.xml found in ZooKeeper. Loading...
[junit4] 2> 289880 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:44699/solr
[junit4] 2> 289881 INFO (zkConnectionManagerCallback-533-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 289882 INFO (zkConnectionManagerCallback-535-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 289884 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [n:127.0.0.1:44025_solr ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 289885 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [n:127.0.0.1:44025_solr ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:44025_solr
[junit4] 2> 289885 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [n:127.0.0.1:44025_solr ] o.a.s.c.Overseer Overseer (id=99151948673712136-127.0.0.1:44025_solr-n_0000000001) starting
[junit4] 2> 289887 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [n:127.0.0.1:44025_solr ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44025_solr
[junit4] 2> 289887 INFO (OverseerStateUpdate-99151948673712136-127.0.0.1:44025_solr-n_0000000001) [n:127.0.0.1:44025_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 289887 INFO (zkCallback-526-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 289915 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [n:127.0.0.1:44025_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c94c77e
[junit4] 2> 289921 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [n:127.0.0.1:44025_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c94c77e
[junit4] 2> 289921 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [n:127.0.0.1:44025_solr ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c94c77e
[junit4] 2> 289922 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [n:127.0.0.1:44025_solr ] o.a.s.c.CorePropertiesLocator Found 1 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LegacyCloudClusterPropTest_A706FB3522ABD877-001/tempDir-001/node1/.
[junit4] 2> 289922 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [n:127.0.0.1:44025_solr ] o.a.s.c.CorePropertiesLocator Cores are: [legacyTrue_shard1_replica_n1]
[junit4] 2> 289923 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 2147483647 transient cores
[junit4] 2> 289926 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 289929 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 289930 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 289941 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 289944 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.s.IndexSchema [legacyTrue_shard1_replica_n1] Schema name=minimal
[junit4] 2> 289945 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema minimal/1.1 with uniqueid field id
[junit4] 2> 289945 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'legacyTrue_shard1_replica_n1' using configuration from collection legacyTrue, trusted=true
[junit4] 2> 289945 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr_44025.solr.core.legacyTrue.shard1.replica_n1' (registry 'solr.core.legacyTrue.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@5c94c77e
[junit4] 2> 289945 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 289945 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore [[legacyTrue_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LegacyCloudClusterPropTest_A706FB3522ABD877-001/tempDir-001/node1/legacyTrue_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LegacyCloudClusterPropTest_A706FB3522ABD877-001/tempDir-001/node1/./legacyTrue_shard1_replica_n1/data/]
[junit4] 2> 289965 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 289965 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir=null defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 289965 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 289965 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 289966 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2bf6f119[legacyTrue_shard1_replica_n1] main]
[junit4] 2> 289966 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf
[junit4] 2> 289966 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf
[junit4] 2> 289967 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 289967 INFO (searcherExecutor-1198-thread-1-processing-n:127.0.0.1:44025_solr x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] Registered new searcher Searcher@2bf6f119[legacyTrue_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 289967 INFO (coreLoadExecutor-1197-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 x:legacyTrue_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586431181634666496
[junit4] 2> 289970 INFO (coreZkRegister-1188-thread-1-processing-n:127.0.0.1:44025_solr x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 289970 INFO (coreZkRegister-1188-thread-1-processing-n:127.0.0.1:44025_solr x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 289971 INFO (coreZkRegister-1188-thread-1-processing-n:127.0.0.1:44025_solr x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:44025/solr/legacyTrue_shard1_replica_n1/
[junit4] 2> 289971 INFO (coreZkRegister-1188-thread-1-processing-n:127.0.0.1:44025_solr x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 289971 INFO (coreZkRegister-1188-thread-1-processing-n:127.0.0.1:44025_solr x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:44025/solr/legacyTrue_shard1_replica_n1/ has no replicas
[junit4] 2> 289971 INFO (coreZkRegister-1188-thread-1-processing-n:127.0.0.1:44025_solr x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 289971 INFO (coreZkRegister-1188-thread-1-processing-n:127.0.0.1:44025_solr x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:44025/solr/legacyTrue_shard1_replica_n1/ shard1
[junit4] 2> 289971 INFO (coreZkRegister-1188-thread-1-processing-n:127.0.0.1:44025_solr x:legacyTrue_shard1_replica_n1 s:shard1 c:legacyTrue) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 290072 INFO (zkCallback-534-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/legacyTrue/state.json] for collection [legacyTrue] has occurred - updating... (live nodes size: [1])
[junit4] 2> 290971 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.h.i.e.RetryExec I/O exception (org.apache.http.NoHttpResponseException) caught when processing request to {s}->https://127.0.0.1:44025: The target server failed to respond
[junit4] 2> 290971 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.h.i.e.RetryExec Retrying request to {s}->https://127.0.0.1:44025
[junit4] 2> 290991 INFO (qtp357279851-3615) [n:127.0.0.1:44025_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :clusterprop with params val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 290991 INFO (qtp357279851-3615) [n:127.0.0.1:44025_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={val=false&name=legacyCloud&action=CLUSTERPROP&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 290993 INFO (qtp357279851-3620) [n:127.0.0.1:44025_solr ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=legacyFalse&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 290995 INFO (OverseerThreadFactory-1195-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.CreateCollectionCmd Create collection legacyFalse
[junit4] 2> 321020 ERROR (OverseerThreadFactory-1195-thread-1-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.OverseerCollectionMessageHandler Collection: legacyFalse operation: create failed:org.apache.solr.common.SolrException: Could not fully create collection: legacyFalse
[junit4] 2> at org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:215)
[junit4] 2> at org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:233)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
[junit4] 2> at java.base/java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 321022 INFO (qtp357279851-3620) [n:127.0.0.1:44025_solr ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf&maxShardsPerNode=1&name=legacyFalse&nrtReplicas=1&action=CREATE&numShards=1&wt=javabin&version=2} status=500 QTime=30029
[junit4] 2> 321022 ERROR (qtp357279851-3620) [n:127.0.0.1:44025_solr ] o.a.s.s.HttpSolrCall null:org.apache.solr.common.SolrException: Could not fully create collection: legacyFalse
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:309)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:246)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:224)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:735)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:716)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:497)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2>
[junit4] 2> 321023 INFO (TEST-LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud-seed#[A706FB3522ABD877]) [ ] o.a.s.SolrTestCaseJ4 ###Ending testCreateCollectionSwitchLegacyCloud
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=LegacyCloudClusterPropTest -Dtests.method=testCreateCollectionSwitchLegacyCloud -Dtests.seed=A706FB3522ABD877 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=smn-FI -Dtests.timezone=America/Panama -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 33.5s J1 | LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:44025/solr: Could not fully create collection: legacyFalse
[junit4] > at __randomizedtesting.SeedInfo.seed([A706FB3522ABD877:760109B086A45345]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1103)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:883)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
[junit4] > at org.apache.solr.cloud.LegacyCloudClusterPropTest.createAndTest(LegacyCloudClusterPropTest.java:86)
[junit4] > at org.apache.solr.cloud.LegacyCloudClusterPropTest.testCreateCollectionSwitchLegacyCloud(LegacyCloudClusterPropTest.java:70)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] 2> 321025 INFO (jetty-closer-509-thread-1) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@5220098d{SSL,[ssl, http/1.1]}{127.0.0.1:44025}
[junit4] 2> 321026 INFO (jetty-closer-509-thread-1) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=916026417
[junit4] 2> 321026 INFO (jetty-closer-509-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 321026 INFO (jetty-closer-509-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4155e47d: rootName = solr_44025, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@78c382df
[junit4] 2> 321029 INFO (jetty-closer-509-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 321029 INFO (jetty-closer-509-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@61bb8bc: rootName = solr_44025, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@6d62bdc2
[junit4] 2> 321032 INFO (jetty-closer-509-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 321032 INFO (jetty-closer-509-thread-1) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@4062c5f2: rootName = solr_44025, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@7025b30b
[junit4] 2> 321032 INFO (jetty-closer-509-thread-1) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:44025_solr
[junit4] 2> 321033 INFO (jetty-closer-509-thread-1) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 321033 INFO (zkCallback-534-thread-2-processing-n:127.0.0.1:44025_solr) [n:127.0.0.1:44025_solr ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 321033 INFO (coreCloseExecutor-1203-thread-1) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.c.SolrCore [legacyTrue_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@4373eb7c
[junit4] 2> 321033 INFO (coreCloseExecutor-1203-thread-1) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.legacyTrue.shard1.replica_n1, tag=1131670396
[junit4] 2> 321033 INFO (coreCloseExecutor-1203-thread-1) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@3d6823b7: rootName = solr_44025, domain = solr.core.legacyTrue.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.legacyTrue.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@d993b25
[junit4] 2> 321040 INFO (coreCloseExecutor-1203-thread-1) [n:127.0.0.1:44025_solr c:legacyTrue s:shard1 r:core_node2 x:legacyTrue_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.legacyTrue.shard1.leader, tag=1131670396
[junit4] 2> 321042 INFO (jetty-closer-509-thread-1) [ ] o.a.s.c.Overseer Overseer (id=99151948673712136-127.0.0.1:44025_solr-n_0000000001) closing
[junit4] 2> 321042 INFO (OverseerStateUpdate-99151948673712136-127.0.0.1:44025_solr-n_0000000001) [n:127.0.0.1:44025_solr ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:44025_solr
[junit4] 2> 321042 WARN (OverseerAutoScalingTriggerThread-99151948673712136-127.0.0.1:44025_solr-n_0000000001) [n:127.0.0.1:44025_solr ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 321043 INFO (jetty-closer-509-thread-1) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@175d754{/solr,null,UNAVAILABLE}
[junit4] 2> 321043 ERROR (SUITE-LegacyCloudClusterPropTest-seed#[A706FB3522ABD877]-worker) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 321043 INFO (SUITE-LegacyCloudClusterPropTest-seed#[A706FB3522ABD877]-worker) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44699 44699
[junit4] 2> 326995 INFO (Thread-1049) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:44699 44699
[junit4] 2> 326996 WARN (Thread-1049) [ ] 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/security.json
[junit4] 2> 2 /solr/configs/conf
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/clusterstate.json
[junit4] 2> 3 /solr/clusterprops.json
[junit4] 2> 2 /solr/autoscaling.json
[junit4] 2> 2 /solr/collections/legacyTrue/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 3 /solr/live_nodes
[junit4] 2> 3 /solr/collections
[junit4] 2> 2 /solr/overseer/queue
[junit4] 2> 2 /solr/autoscaling/events/.auto_add_replicas
[junit4] 2> 2 /solr/overseer/collection-queue-work
[junit4] 2> 2 /solr/overseer/queue-work
[junit4] 2>
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.LegacyCloudClusterPropTest_A706FB3522ABD877-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene70), sim=RandomSimilarity(queryNorm=false): {}, locale=smn-FI, timezone=America/Panama
[junit4] 2> NOTE: Linux 4.10.0-40-generic amd64/Oracle Corporation 9.0.1 (64-bit)/cpus=8,threads=1,free=135835032,total=527433728
[junit4] 2> NOTE: All tests run in this JVM: [CollectionsAPIDistributedZkTest, DistributedQueryComponentCustomSortTest, TestSimpleQParserPlugin, TestUseDocValuesAsStored2, SuggesterTSTTest, PKIAuthenticationIntegrationTest, BinaryUpdateRequestHandlerTest, CoreAdminCreateDiscoverTest, TestPerFieldSimilarityWithDefaultOverride, TestRemoteStreaming, TestCollationFieldDocValues, TestReplicaProperties, ShardRoutingCustomTest, DocValuesMultiTest, SolrIndexSplitterTest, TestFastLRUCache, TestFaceting, RollingRestartTest, TestJsonRequest, QueryEqualityTest, SolrIndexMetricsTest, BlockCacheTest, HighlighterConfigTest, BJQParserTest, TestAnalyzedSuggestions, NotRequiredUniqueKeyTest, TestMinMaxOnMultiValuedField, DOMUtilTest, TestSolrCoreSnapshots, MoveReplicaHDFSFailoverTest, ChaosMonkeySafeLeaderTest, SampleTest, TestCustomSort, JSONWriterTest, AssignTest, LegacyCloudClusterPropTest]
[junit4] Completed [104/751 (1!)] on J1 in 39.76s, 1 test, 1 error <<< FAILURES!
[...truncated 42291 lines...]
[JENKINS-EA] Lucene-Solr-7.2-Linux (64bit/jdk-10-ea+32) - Build #
45 - Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.2-Linux/45/
Java: 64bit/jdk-10-ea+32 -XX:-UseCompressedOops -XX:+UseSerialGC
1 tests failed.
FAILED: junit.framework.TestSuite.org.apache.solr.analytics.facet.PivotFacetTest
Error Message:
Error from server at https://127.0.0.1:34525/solr/collection1: Async exception during distributed update: 127.0.0.1:43377 failed to respond
Stack Trace:
org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:34525/solr/collection1: Async exception during distributed update: 127.0.0.1:43377 failed to respond
at __randomizedtesting.SeedInfo.seed([61E61E2EB46BB251]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1103)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:883)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
at org.apache.solr.analytics.SolrAnalyticsTestCase.setupCollection(SolrAnalyticsTestCase.java:68)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.base/java.lang.reflect.Method.invoke(Method.java:564)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:874)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:368)
at java.base/java.lang.Thread.run(Thread.java:844)
Build Log:
[...truncated 17020 lines...]
[junit4] Suite: org.apache.solr.analytics.facet.PivotFacetTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/contrib/solr-analytics/test/J0/temp/solr.analytics.facet.PivotFacetTest_61E61E2EB46BB251-001/init-core-data-001
[junit4] 2> Dec 11, 2017 11:30:59 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(Lucene70): {}, docValues:{}, maxPointsInLeafNode=1947, maxMBSortInHeap=5.239306382390618, sim=RandomSimilarity(queryNorm=true): {}, locale=fr, timezone=America/Thunder_Bay
[junit4] 2> NOTE: Linux 4.10.0-40-generic amd64/Oracle Corporation 10-ea (64-bit)/cpus=8,threads=1,free=369372696,total=518979584
[junit4] 2> NOTE: All tests run in this JVM: [LegacyAbstractAnalyticsCloudTest, BottomFunctionTest, AbsoluteValueFunctionTest, ExpressionFactoryTest, LTFunctionTest, LegacyAbstractAnalyticsFacetCloudTest, IntFieldsTest, LegacyFieldFacetTest, CastingBooleanValueStreamTest, PivotFacetTest]
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=PivotFacetTest -Dtests.seed=61E61E2EB46BB251 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=fr -Dtests.timezone=America/Thunder_Bay -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 0.00s J0 | PivotFacetTest (suite) <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:34525/solr/collection1: Async exception during distributed update: 127.0.0.1:43377 failed to respond
[junit4] > at __randomizedtesting.SeedInfo.seed([61E61E2EB46BB251]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:643)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1103)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:883)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
[junit4] > at org.apache.solr.client.solrj.request.UpdateRequest.commit(UpdateRequest.java:233)
[junit4] > at org.apache.solr.analytics.SolrAnalyticsTestCase.setupCollection(SolrAnalyticsTestCase.java:68)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
[junit4] > at java.base/jdk.internal.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
[junit4] > at java.base/jdk.internal.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
[junit4] > at java.base/java.lang.reflect.Method.invoke(Method.java:564)
[junit4] > at java.base/java.lang.Thread.run(Thread.java:844)
[junit4] Completed [16/78 (1!)] on J0 in 8.67s, 0 tests, 1 error <<< FAILURES!
[...truncated 37280 lines...]
[JENKINS] Lucene-Solr-7.2-Linux (32bit/jdk1.8.0_144) - Build # 44 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.2-Linux/44/
Java: 32bit/jdk1.8.0_144 -server -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.handler.TestSolrConfigHandlerCloud.test
Error Message:
{ "responseHeader":{ "status":500, "QTime":30004}, "errorMessages":["1 out of 5 the property overlay to be of version 9 within 30 seconds! Failed cores: [https://127.0.0.1:44817/collection1_shard1_replica_n47/]\n"], "WARNING":"This response format is experimental. It is likely to change in the future.", "error":{ "metadata":[ "error-class","org.apache.solr.common.SolrException", "root-error-class","org.apache.solr.common.SolrException"], "msg":"1 out of 5 the property overlay to be of version 9 within 30 seconds! Failed cores: [https://127.0.0.1:44817/collection1_shard1_replica_n47/]", "trace":"org.apache.solr.common.SolrException: 1 out of 5 the property overlay to be of version 9 within 30 seconds! Failed cores: [https://127.0.0.1:44817/collection1_shard1_replica_n47/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:771)\n\tat org.apache.solr.handler.SolrConfigHandler.access$400(SolrConfigHandler.java:101)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:502)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:345)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:158)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:136)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:534)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)\n\tat org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)\n\tat org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)\n\tat java.lang.Thread.run(Thread.java:748)\n", "code":500}}
Stack Trace:
java.lang.AssertionError: {
"responseHeader":{
"status":500,
"QTime":30004},
"errorMessages":["1 out of 5 the property overlay to be of version 9 within 30 seconds! Failed cores: [https://127.0.0.1:44817/collection1_shard1_replica_n47/]\n"],
"WARNING":"This response format is experimental. It is likely to change in the future.",
"error":{
"metadata":[
"error-class","org.apache.solr.common.SolrException",
"root-error-class","org.apache.solr.common.SolrException"],
"msg":"1 out of 5 the property overlay to be of version 9 within 30 seconds! Failed cores: [https://127.0.0.1:44817/collection1_shard1_replica_n47/]",
"trace":"org.apache.solr.common.SolrException: 1 out of 5 the property overlay to be of version 9 within 30 seconds! Failed cores: [https://127.0.0.1:44817/collection1_shard1_replica_n47/]\n\tat org.apache.solr.handler.SolrConfigHandler.waitForAllReplicasState(SolrConfigHandler.java:771)\n\tat org.apache.solr.handler.SolrConfigHandler.access$400(SolrConfigHandler.java:101)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handleCommands(SolrConfigHandler.java:502)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.handlePOST(SolrConfigHandler.java:345)\n\tat org.apache.solr.handler.SolrConfigHandler$Command.access$100(SolrConfigHandler.java:158)\n\tat org.apache.solr.handler.SolrConfigHandler.handleRequestBody(SolrConfigHandler.java:136)\n\tat org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)\n\tat org.apache.solr.core.SolrCore.execute(SolrCore.java:2503)\n\tat org.apache.solr.servlet.HttpSolrCall.execute(HttpSolrCall.java:710)\n\tat org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:516)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)\n\tat org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)\n\tat org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)\n\tat org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)\n\tat org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)\n\tat org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)\n\tat org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)\n\tat org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)\n\tat org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)\n\tat org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)\n\tat org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)\n\tat org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)\n\tat org.eclipse.jetty.server.Server.handle(Server.java:534)\n\tat org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)\n\tat org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)\n\tat org.eclipse.jetty.io.ssl.SslConnection.onFillable(SslConnection.java:251)\n\tat org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)\n\tat org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)\n\tat org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)\n\tat org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)\n\tat org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)\n\tat java.lang.Thread.run(Thread.java:748)\n",
"code":500}}
at __randomizedtesting.SeedInfo.seed([9876B778A5A9FE8D:102288A20B559375]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.assertTrue(Assert.java:43)
at org.junit.Assert.assertNull(Assert.java:551)
at org.apache.solr.core.TestSolrConfigHandler.runConfigCommand(TestSolrConfigHandler.java:185)
at org.apache.solr.core.TestSolrConfigHandler.reqhandlertests(TestSolrConfigHandler.java:330)
at org.apache.solr.handler.TestSolrConfigHandlerCloud.testReqHandlerAPIs(TestSolrConfigHandlerCloud.java:97)
at org.apache.solr.handler.TestSolrConfigHandlerCloud.test(TestSolrConfigHandlerCloud.java:67)
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:1737)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:934)
at com.carrotsearch.randomizedtesting.RandomizedRunner$9.evaluate(RandomizedRunner.java:970)
at com.carrotsearch.randomizedtesting.RandomizedRunner$10.evaluate(RandomizedRunner.java:984)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:993)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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 12126 lines...]
[junit4] Suite: org.apache.solr.handler.TestSolrConfigHandlerCloud
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/init-core-data-001
[junit4] 2> 347778 WARN (SUITE-TestSolrConfigHandlerCloud-seed#[9876B778A5A9FE8D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 347778 INFO (SUITE-TestSolrConfigHandlerCloud-seed#[9876B778A5A9FE8D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=true
[junit4] 2> 347779 INFO (SUITE-TestSolrConfigHandlerCloud-seed#[9876B778A5A9FE8D]-worker) [ ] o.a.s.SolrTestCaseJ4 Randomized ssl (true) and clientAuth (true) via: @org.apache.solr.util.RandomizeSSL(reason=, ssl=NaN, value=NaN, clientAuth=NaN)
[junit4] 2> 347779 INFO (SUITE-TestSolrConfigHandlerCloud-seed#[9876B778A5A9FE8D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 347779 INFO (SUITE-TestSolrConfigHandlerCloud-seed#[9876B778A5A9FE8D]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /
[junit4] 2> 347781 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 347781 INFO (Thread-2912) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 347781 INFO (Thread-2912) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 347783 ERROR (Thread-2912) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 347881 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.ZkTestServer start zk server on port:37537
[junit4] 2> 347883 INFO (zkConnectionManagerCallback-796-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347886 INFO (zkConnectionManagerCallback-798-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347887 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 347888 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 347889 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 347889 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 347889 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 347890 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 347890 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 347890 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 347891 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 347891 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 347891 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 347892 INFO (zkConnectionManagerCallback-801-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347893 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 347961 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 347962 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@d20449{/,null,AVAILABLE}
[junit4] 2> 347962 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@69f171{SSL,[ssl, http/1.1]}{127.0.0.1:45035}
[junit4] 2> 347962 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.Server Started @349529ms
[junit4] 2> 347962 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/tempDir-001/control/data, hostContext=/, hostPort=45035, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/control-001/cores}
[junit4] 2> 347963 ERROR (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 347963 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0
[junit4] 2> 347963 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 347963 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 347963 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-11T05:47:13.796Z
[junit4] 2> 347964 INFO (zkConnectionManagerCallback-803-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347965 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 347965 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/control-001/solr.xml
[junit4] 2> 347968 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 347969 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 347969 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 347972 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37537/solr
[junit4] 2> 347974 INFO (zkConnectionManagerCallback-807-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 347979 INFO (zkConnectionManagerCallback-809-thread-1-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348005 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:45035_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 348006 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:45035_ ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:45035_
[junit4] 2> 348006 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:45035_ ] o.a.s.c.Overseer Overseer (id=99154082766192645-127.0.0.1:45035_-n_0000000000) starting
[junit4] 2> 348009 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:45035_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:45035_
[junit4] 2> 348011 INFO (zkCallback-808-thread-1-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 348086 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:45035_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 348092 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:45035_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 348092 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:45035_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 348093 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:45035_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/control-001/cores
[junit4] 2> 348105 INFO (zkConnectionManagerCallback-815-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 348106 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 348106 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37537/solr ready
[junit4] 2> 348138 INFO (qtp1071204-5639) [n:127.0.0.1:45035_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:45035_&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 348140 INFO (OverseerThreadFactory-1490-thread-1-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 348248 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 348249 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 348353 INFO (zkCallback-808-thread-1-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 349269 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 349277 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 349336 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 349343 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
[junit4] 2> 349344 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 349344 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 349344 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 349346 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=40.42578125, floorSegmentMB=1.4833984375, forceMergeDeletesPctAllowed=29.138433222970903, segmentsPerTier=9.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.841189292462521
[junit4] 2> 349347 WARN (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 349378 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 349378 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 349379 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 349379 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 349380 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3925476477203692]
[junit4] 2> 349380 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@41482c[control_collection_shard1_replica_n1] main]
[junit4] 2> 349381 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 349381 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 349381 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 349381 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586465325935755264
[junit4] 2> 349382 INFO (searcherExecutor-1493-thread-1-processing-n:127.0.0.1:45035_ x:control_collection_shard1_replica_n1 s:shard1 c:control_collection) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@41482c[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 349384 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 349384 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 349384 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:45035/control_collection_shard1_replica_n1/
[junit4] 2> 349384 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 349384 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy https://127.0.0.1:45035/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 349384 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 349385 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:45035/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 349487 INFO (zkCallback-808-thread-2-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 349536 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 349545 INFO (qtp1071204-5640) [n:127.0.0.1:45035_ c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1296
[junit4] 2> 349548 INFO (qtp1071204-5639) [n:127.0.0.1:45035_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 349639 INFO (zkCallback-808-thread-2-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 350141 INFO (OverseerCollectionConfigSetProcessor-99154082766192645-127.0.0.1:45035_-n_0000000000) [n:127.0.0.1:45035_ ] 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> 350548 INFO (qtp1071204-5639) [n:127.0.0.1:45035_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:45035_&wt=javabin&version=2} status=0 QTime=2410
[junit4] 2> 350552 INFO (zkConnectionManagerCallback-819-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 350553 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 350553 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37537/solr ready
[junit4] 2> 350553 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.ChaosMonkey monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 350556 INFO (qtp1071204-5646) [n:127.0.0.1:45035_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 350557 INFO (OverseerThreadFactory-1490-thread-2-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.CreateCollectionCmd Create collection collection1
[junit4] 2> 350559 WARN (OverseerThreadFactory-1490-thread-2-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.CreateCollectionCmd It is unusual to create a collection (collection1) without cores.
[junit4] 2> 350761 INFO (qtp1071204-5646) [n:127.0.0.1:45035_ ] o.a.s.h.a.CollectionsHandler Wait for new collection to be active for at most 30 seconds. Check all shard replicas
[junit4] 2> 350762 INFO (qtp1071204-5646) [n:127.0.0.1:45035_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=collection1&nrtReplicas=1&action=CREATE&numShards=2&createNodeSet=&stateFormat=1&wt=javabin&version=2} status=0 QTime=205
[junit4] 2> 350835 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 1 in directory /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-1-001 of type NRT
[junit4] 2> 350836 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 350837 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1f211ce{/,null,AVAILABLE}
[junit4] 2> 350837 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@1c151fa{SSL,[ssl, http/1.1]}{127.0.0.1:40389}
[junit4] 2> 350838 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.Server Started @352405ms
[junit4] 2> 350838 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=40389, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-1-001/cores}
[junit4] 2> 350838 ERROR (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 350838 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0
[junit4] 2> 350838 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 350838 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 350838 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-11T05:47:16.671Z
[junit4] 2> 350840 INFO (zkConnectionManagerCallback-821-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 350841 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 350841 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-1-001/solr.xml
[junit4] 2> 350844 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 350844 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 350844 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 350847 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37537/solr
[junit4] 2> 350848 INFO (zkConnectionManagerCallback-825-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 350849 INFO (zkConnectionManagerCallback-827-thread-1-processing-n:127.0.0.1:40389_) [n:127.0.0.1:40389_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 350852 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:40389_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 350853 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:40389_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 350855 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:40389_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:40389_
[junit4] 2> 350856 INFO (zkCallback-818-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 350856 INFO (zkCallback-808-thread-2-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 350856 INFO (zkCallback-826-thread-1-processing-n:127.0.0.1:40389_) [n:127.0.0.1:40389_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (2)
[junit4] 2> 350949 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:40389_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 350960 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:40389_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 350960 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:40389_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 350962 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:40389_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-1-001/cores
[junit4] 2> 351018 INFO (qtp10037359-5688) [n:127.0.0.1:40389_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:40389_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 351020 INFO (OverseerCollectionConfigSetProcessor-99154082766192645-127.0.0.1:45035_-n_0000000000) [n:127.0.0.1:45035_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000002 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 351020 INFO (OverseerThreadFactory-1490-thread-3-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:40389_ for creating new replica
[junit4] 2> 351023 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 351023 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 352033 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 352041 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema [collection1_shard2_replica_n41] Schema name=test
[junit4] 2> 352165 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 352172 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n41' using configuration from collection collection1, trusted=true
[junit4] 2> 352172 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n41' (registry 'solr.core.collection1.shard2.replica_n41') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 352172 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 352172 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore [[collection1_shard2_replica_n41] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-1-001/cores/collection1_shard2_replica_n41], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-1-001/cores/collection1_shard2_replica_n41/data/]
[junit4] 2> 352174 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=40.42578125, floorSegmentMB=1.4833984375, forceMergeDeletesPctAllowed=29.138433222970903, segmentsPerTier=9.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.841189292462521
[junit4] 2> 352176 WARN (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 352203 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 352203 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 352204 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 352204 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 352205 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3925476477203692]
[junit4] 2> 352205 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.SolrIndexSearcher Opening [Searcher@1ee9fd9[collection1_shard2_replica_n41] main]
[junit4] 2> 352205 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 352206 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 352206 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 352207 INFO (searcherExecutor-1504-thread-1-processing-n:127.0.0.1:40389_ x:collection1_shard2_replica_n41 s:shard2 c:collection1) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SolrCore [collection1_shard2_replica_n41] Registered new searcher Searcher@1ee9fd9[collection1_shard2_replica_n41] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 352207 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586465328899031040
[junit4] 2> 352210 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 352210 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 352210 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:40389/collection1_shard2_replica_n41/
[junit4] 2> 352210 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 352210 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.SyncStrategy https://127.0.0.1:40389/collection1_shard2_replica_n41/ has no replicas
[junit4] 2> 352210 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 352211 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:40389/collection1_shard2_replica_n41/ shard2
[junit4] 2> 352361 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 352362 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ c:collection1 s:shard2 x:collection1_shard2_replica_n41] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n41&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1339
[junit4] 2> 352363 INFO (qtp10037359-5688) [n:127.0.0.1:40389_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:40389_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2} status=0 QTime=1345
[junit4] 2> 352429 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 2 in directory /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-2-001 of type NRT
[junit4] 2> 352430 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 352431 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@10f253f{/,null,AVAILABLE}
[junit4] 2> 352432 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@153ebb7{SSL,[ssl, http/1.1]}{127.0.0.1:44209}
[junit4] 2> 352432 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.Server Started @353999ms
[junit4] 2> 352432 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/tempDir-001/jetty2, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=44209, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-2-001/cores}
[junit4] 2> 352432 ERROR (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 352432 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0
[junit4] 2> 352432 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 352432 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 352432 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-11T05:47:18.265Z
[junit4] 2> 352434 INFO (zkConnectionManagerCallback-830-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 352434 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 352434 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-2-001/solr.xml
[junit4] 2> 352437 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 352437 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 352437 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 352439 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37537/solr
[junit4] 2> 352440 INFO (zkConnectionManagerCallback-834-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 352447 INFO (zkConnectionManagerCallback-836-thread-1-processing-n:127.0.0.1:44209_) [n:127.0.0.1:44209_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 352449 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:44209_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (2)
[junit4] 2> 352450 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:44209_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 352451 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:44209_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44209_
[junit4] 2> 352453 INFO (zkCallback-818-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 352453 INFO (zkCallback-826-thread-1-processing-n:127.0.0.1:40389_) [n:127.0.0.1:40389_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 352453 INFO (zkCallback-808-thread-2-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 352469 INFO (zkCallback-835-thread-1-processing-n:127.0.0.1:44209_) [n:127.0.0.1:44209_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (2) -> (3)
[junit4] 2> 352526 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:44209_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 352537 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:44209_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 352537 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:44209_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 352539 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:44209_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-2-001/cores
[junit4] 2> 352606 INFO (qtp23333373-5718) [n:127.0.0.1:44209_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:44209_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 352607 INFO (OverseerCollectionConfigSetProcessor-99154082766192645-127.0.0.1:45035_-n_0000000000) [n:127.0.0.1:45035_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000004 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 352607 INFO (OverseerThreadFactory-1490-thread-4-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:44209_ for creating new replica
[junit4] 2> 352611 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 352611 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 353631 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 353640 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.IndexSchema [collection1_shard1_replica_n43] Schema name=test
[junit4] 2> 353724 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 353730 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard1_replica_n43' using configuration from collection collection1, trusted=true
[junit4] 2> 353731 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard1.replica_n43' (registry 'solr.core.collection1.shard1.replica_n43') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 353731 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 353731 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore [[collection1_shard1_replica_n43] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-2-001/cores/collection1_shard1_replica_n43], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-2-001/cores/collection1_shard1_replica_n43/data/]
[junit4] 2> 353733 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=40.42578125, floorSegmentMB=1.4833984375, forceMergeDeletesPctAllowed=29.138433222970903, segmentsPerTier=9.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.841189292462521
[junit4] 2> 353734 WARN (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 358776 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 358776 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 358778 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 358778 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 358780 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3925476477203692]
[junit4] 2> 358780 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.SolrIndexSearcher Opening [Searcher@1d53bb8[collection1_shard1_replica_n43] main]
[junit4] 2> 358782 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 358782 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 358784 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 358785 INFO (searcherExecutor-1515-thread-1-processing-n:127.0.0.1:44209_ x:collection1_shard1_replica_n43 s:shard1 c:collection1) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SolrCore [collection1_shard1_replica_n43] Registered new searcher Searcher@1d53bb8[collection1_shard1_replica_n43] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 358785 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586465335796563968
[junit4] 2> 358792 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 358792 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 358792 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy Sync replicas to https://127.0.0.1:44209/collection1_shard1_replica_n43/
[junit4] 2> 358792 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 358792 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.SyncStrategy https://127.0.0.1:44209/collection1_shard1_replica_n43/ has no replicas
[junit4] 2> 358792 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 358794 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ShardLeaderElectionContext I am the new leader: https://127.0.0.1:44209/collection1_shard1_replica_n43/ shard1
[junit4] 2> 358844 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.c.ZkController I am the leader, no recovery necessary
[junit4] 2> 358846 INFO (qtp23333373-5719) [n:127.0.0.1:44209_ c:collection1 s:shard1 x:collection1_shard1_replica_n43] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard1_replica_n43&action=CREATE&collection=collection1&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=6235
[junit4] 2> 358849 INFO (qtp23333373-5718) [n:127.0.0.1:44209_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:44209_&action=ADDREPLICA&collection=collection1&shard=shard1&type=NRT&wt=javabin&version=2} status=0 QTime=6242
[junit4] 2> 358931 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 3 in directory /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-3-001 of type NRT
[junit4] 2> 358931 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 358932 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@1a224b{/,null,AVAILABLE}
[junit4] 2> 358932 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@f5e4ed{SSL,[ssl, http/1.1]}{127.0.0.1:37645}
[junit4] 2> 358933 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.Server Started @360500ms
[junit4] 2> 358933 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/tempDir-001/jetty3, replicaType=NRT, solrconfig=solrconfig.xml, hostContext=/, hostPort=37645, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-3-001/cores}
[junit4] 2> 358933 ERROR (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 358933 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr? version 7.2.0
[junit4] 2> 358933 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 358933 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 358933 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-11T05:47:24.766Z
[junit4] 2> 358934 INFO (zkConnectionManagerCallback-839-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 358935 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 358935 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-3-001/solr.xml
[junit4] 2> 358938 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 358938 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 358938 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 358940 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37537/solr
[junit4] 2> 358941 INFO (zkConnectionManagerCallback-843-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 358942 INFO (zkConnectionManagerCallback-845-thread-1-processing-n:127.0.0.1:37645_) [n:127.0.0.1:37645_ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 358944 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:37645_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (3)
[junit4] 2> 358945 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:37645_ ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 358946 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:37645_ ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:37645_
[junit4] 2> 358946 INFO (zkCallback-818-thread-1) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 358946 INFO (zkCallback-835-thread-1-processing-n:127.0.0.1:44209_) [n:127.0.0.1:44209_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 358946 INFO (zkCallback-826-thread-1-processing-n:127.0.0.1:40389_) [n:127.0.0.1:40389_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 358946 INFO (zkCallback-808-thread-2-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 358946 INFO (zkCallback-844-thread-1-processing-n:127.0.0.1:37645_) [n:127.0.0.1:37645_ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (3) -> (4)
[junit4] 2> 360610 INFO (OverseerCollectionConfigSetProcessor-99154082766192645-127.0.0.1:45035_-n_0000000000) [n:127.0.0.1:45035_ ] o.a.s.c.OverseerTaskQueue Response ZK path: /overseer/collection-queue-work/qnr-0000000006 doesn't exist. Requestor may have disconnected from ZooKeeper
[junit4] 2> 364052 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:37645_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 364064 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:37645_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 364064 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:37645_ ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 364065 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [n:127.0.0.1:37645_ ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-3-001/cores
[junit4] 2> 364107 INFO (qtp23333373-5723) [n:127.0.0.1:44209_ ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :addreplica with params node=127.0.0.1:37645_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 364108 INFO (OverseerThreadFactory-1490-thread-5-processing-n:127.0.0.1:45035_) [n:127.0.0.1:45035_ ] o.a.s.c.AddReplicaCmd Node Identified 127.0.0.1:37645_ for creating new replica
[junit4] 2> 364166 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n45&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 364166 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 365183 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 365212 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.s.IndexSchema [collection1_shard2_replica_n45] Schema name=test
[junit4] 2> 365274 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 365282 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.CoreContainer Creating SolrCore 'collection1_shard2_replica_n45' using configuration from collection collection1, trusted=true
[junit4] 2> 365282 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.collection1.shard2.replica_n45' (registry 'solr.core.collection1.shard2.replica_n45') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@369f14
[junit4] 2> 365282 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 365282 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrCore [[collection1_shard2_replica_n45] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-3-001/cores/collection1_shard2_replica_n45], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-3-001/cores/collection1_shard2_replica_n45/data/]
[junit4] 2> 365284 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=12, maxMergeAtOnceExplicit=29, maxMergedSegmentMB=40.42578125, floorSegmentMB=1.4833984375, forceMergeDeletesPctAllowed=29.138433222970903, segmentsPerTier=9.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.841189292462521
[junit4] 2> 365286 WARN (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 365318 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 365318 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 365320 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 365320 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 365321 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.3925476477203692]
[junit4] 2> 365321 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.s.SolrIndexSearcher Opening [Searcher@dd3b40[collection1_shard2_replica_n45] main]
[junit4] 2> 365322 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 365322 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 365323 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 365324 INFO (searcherExecutor-1526-thread-1-processing-n:127.0.0.1:37645_ x:collection1_shard2_replica_n45 s:shard2 c:collection1) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.SolrCore [collection1_shard2_replica_n45] Registered new searcher Searcher@dd3b40[collection1_shard2_replica_n45] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 365324 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586465342653202432
[junit4] 2> 365326 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.c.ZkController Core needs to recover:collection1_shard2_replica_n45
[junit4] 2> 365326 INFO (updateExecutor-840-thread-1-processing-n:127.0.0.1:37645_ x:collection1_shard2_replica_n45 s:shard2 c:collection1) [n:127.0.0.1:37645_ c:collection1 s:shard2 r:core_node46 x:collection1_shard2_replica_n45] o.a.s.u.DefaultSolrCoreState Running recovery
[junit4] 2> 365327 INFO (recoveryExecutor-841-thread-1-processing-n:127.0.0.1:37645_ x:collection1_shard2_replica_n45 s:shard2 c:collection1 r:core_node46) [n:127.0.0.1:37645_ c:collection1 s:shard2 r:core_node46 x:collection1_shard2_replica_n45] o.a.s.c.RecoveryStrategy Starting recovery process. recoveringAfterStartup=true
[junit4] 2> 365327 INFO (recoveryExecutor-841-thread-1-processing-n:127.0.0.1:37645_ x:collection1_shard2_replica_n45 s:shard2 c:collection1 r:core_node46) [n:127.0.0.1:37645_ c:collection1 s:shard2 r:core_node46 x:collection1_shard2_replica_n45] o.a.s.c.RecoveryStrategy ###### startupVersions=[[]]
[junit4] 2> 365327 INFO (recoveryExecutor-841-thread-1-processing-n:127.0.0.1:37645_ x:collection1_shard2_replica_n45 s:shard2 c:collection1 r:core_node46) [n:127.0.0.1:37645_ c:collection1 s:shard2 r:core_node46 x:collection1_shard2_replica_n45] o.a.s.c.RecoveryStrategy Begin buffering updates. core=[collection1_shard2_replica_n45]
[junit4] 2> 365327 INFO (recoveryExecutor-841-thread-1-processing-n:127.0.0.1:37645_ x:collection1_shard2_replica_n45 s:shard2 c:collection1 r:core_node46) [n:127.0.0.1:37645_ c:collection1 s:shard2 r:core_node46 x:collection1_shard2_replica_n45] o.a.s.u.UpdateLog Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4] 2> 365327 INFO (recoveryExecutor-841-thread-1-processing-n:127.0.0.1:37645_ x:collection1_shard2_replica_n45 s:shard2 c:collection1 r:core_node46) [n:127.0.0.1:37645_ c:collection1 s:shard2 r:core_node46 x:collection1_shard2_replica_n45] o.a.s.c.RecoveryStrategy Publishing state of core [collection1_shard2_replica_n45] as recovering, leader is [https://127.0.0.1:40389/collection1_shard2_replica_n41/] and I am [https://127.0.0.1:37645/collection1_shard2_replica_n45/]
[junit4] 2> 365327 INFO (qtp8395668-5748) [n:127.0.0.1:37645_ c:collection1 s:shard2 x:collection1_shard2_replica_n45] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&name=collection1_shard2_replica_n45&action=CREATE&collection=collection1&shard=shard2&wt=javabin&version=2&replicaType=NRT} status=0 QTime=1161
[junit4] 2> 365328 INFO (qtp23333373-5723) [n:127.0.0.1:44209_ ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={node=127.0.0.1:37645_&action=ADDREPLICA&collection=collection1&shard=shard2&type=NRT&wt=javabin&version=2} status=0 QTime=1221
[junit4] 2> 365331 INFO (recoveryExecutor-841-thread-1-processing-n:127.0.0.1:37645_ x:collection1_shard2_replica_n45 s:shard2 c:collection1 r:core_node46) [n:127.0.0.1:37645_ c:collection1 s:shard2 r:core_node46 x:collection1_shard2_replica_n45] o.a.s.c.RecoveryStrategy Sending prep recovery command to [https://127.0.0.1:40389]; [WaitForState: action=PREPRECOVERY&core=collection1_shard2_replica_n41&nodeName=127.0.0.1:37645_&coreNodeName=core_node46&state=recovering&checkLive=true&onlyIfLeader=true&onlyIfLeaderActive=true]
[junit4] 2> 365345 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ ] o.a.s.h.a.PrepRecoveryOp Going to wait for coreNodeName: core_node46, state: recovering, checkLive: true, onlyIfLeader: true, onlyIfLeaderActive: true, maxTime: 183 s
[junit4] 2> 365345 INFO (qtp10037359-5695) [n:127.0.0.1:40389_ ] o.a.s.h.a.PrepRecoveryOp In WaitForState(recovering): collection=collection1, shard=shard2, thisCore=collection1_shard2_replica_n41, leaderDoesNotNeedRecovery=false, isLeader? true, live=true, checkLive=true, currentState=down, localState=active, nodeName=127.0.0.1:37645_, coreNodeName=core_node46, onlyIfActiveCheckResult=false, nodeProps: core_node46:{"core":"collection1_shard2_replica_n45","base_url":"https://127.0.0.1:37645","node_name":"127.0.0.1:37645_","state":"down","type":"NRT"}
[junit4] 2> 365435 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase create jetty 4 in directory /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/shard-4-001 of type NRT
[junit4] 2> 365436 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 365437 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@17d92fc{/,null,AVAILABLE}
[junit4] 2> 365438 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@124bd65{SSL,[ssl, http/1.1]}{127.0.0.1:44817}
[junit4] 2> 365438 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.e.j.s.Server Started @367005ms
[junit4] 2> 365438 INFO (TEST-TestSolrConfigHandlerCloud.test-seed#[9876B778A5A9FE8D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J1/temp/solr.handler.TestSolrConfigHandlerCloud_9876B778A5A9FE8D-001/tempDir-001/jetty4, solrconfig=solrconfig.xml, hostContext=/, hostPort=44817, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/sol
[...truncated too long message...]
junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.readConfigName(ZkStateReader.java:231)
[junit4] 2> ... 8 more
[junit4] 2> 414722 WARN (Thread-3005) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.h.a.SystemInfoHandler Resolving canonical hostname for local host took 44.120 seconds, possible DNS misconfiguration. Set the 'solr.dns.prevent.reverse.lookup' sysprop to true on startup to prevent future lookups if DNS can not be fixed.
[junit4] 2> 414725 INFO (Thread-3005) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 414725 INFO (Thread-3005) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 414726 INFO (Thread-3005) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@2128b6[control_collection_shard1_replica_n1] main]
[junit4] 2> 414726 ERROR (Thread-3005) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Failed to get config name due to
[junit4] 2> org.apache.solr.common.SolrException: Error loading config name for collection control_collection
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.readConfigName(ZkStateReader.java:251)
[junit4] 2> at org.apache.solr.rest.ManagedResourceStorage.newStorageIO(ManagedResourceStorage.java:95)
[junit4] 2> at org.apache.solr.core.SolrCore.initRestManager(SolrCore.java:2798)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:983)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:656)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1285)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$20(SolrCore.java:2969)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$5(ZkController.java:2572)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/control_collection
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.readConfigName(ZkStateReader.java:231)
[junit4] 2> ... 8 more
[junit4] 2> 414726 INFO (Thread-3005) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@1d6d6b0
[junit4] 2> 414727 INFO (Thread-3005) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.control_collection.shard1.replica_n1, tag=30856880
[junit4] 2> 414727 INFO (Thread-3005) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1cce733: rootName = null, domain = solr.core.control_collection.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.control_collection.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@8aed07
[junit4] 2> 414727 INFO (searcherExecutor-1733-thread-1-processing-n:127.0.0.1:45035_ x:control_collection_shard1_replica_n1 s:shard1 c:control_collection r:core_node2) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@2128b6[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 414733 INFO (Thread-3005) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.control_collection.shard1.leader, tag=30856880
[junit4] 2> 414734 WARN (Thread-3005) [n:127.0.0.1:45035_ c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.c.ZkController listener throws error
[junit4] 2> org.apache.solr.common.SolrException: Unable to reload core [control_collection_shard1_replica_n1]
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1303)
[junit4] 2> at org.apache.solr.core.SolrCore.lambda$getConfListener$20(SolrCore.java:2969)
[junit4] 2> at org.apache.solr.cloud.ZkController.lambda$fireEventListeners$5(ZkController.java:2572)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Failed to load config name for collection:control_collection due to:
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:1008)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:656)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:1285)
[junit4] 2> ... 3 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Failed to load config name for collection:control_collection due to:
[junit4] 2> at org.apache.solr.rest.ManagedResourceStorage.newStorageIO(ManagedResourceStorage.java:98)
[junit4] 2> at org.apache.solr.core.SolrCore.initRestManager(SolrCore.java:2798)
[junit4] 2> at org.apache.solr.core.SolrCore.<init>(SolrCore.java:983)
[junit4] 2> ... 5 more
[junit4] 2> Caused by: org.apache.solr.common.SolrException: Error loading config name for collection control_collection
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.readConfigName(ZkStateReader.java:251)
[junit4] 2> at org.apache.solr.rest.ManagedResourceStorage.newStorageIO(ManagedResourceStorage.java:95)
[junit4] 2> ... 7 more
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections/control_collection
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at org.apache.zookeeper.ZooKeeper.getData(ZooKeeper.java:1212)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.lambda$getData$5(SolrZkClient.java:339)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:60)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.getData(SolrZkClient.java:339)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.readConfigName(ZkStateReader.java:231)
[junit4] 2> ... 8 more
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=RandomSimilarity(queryNorm=false): {}, locale=is, timezone=America/Rainy_River
[junit4] 2> NOTE: Linux 4.10.0-40-generic i386/Oracle Corporation 1.8.0_144 (32-bit)/cpus=8,threads=1,free=124553704,total=536870912
[junit4] 2> NOTE: All tests run in this JVM: [HttpSolrCallGetCoreTest, TestConfigSetProperties, DocValuesMultiTest, TestCustomStream, MoreLikeThisHandlerTest, RequestHandlersTest, TestRandomCollapseQParserPlugin, AddReplicaTest, ImplicitSnitchTest, SolrCoreMetricManagerTest, PingRequestHandlerTest, TestGraphMLResponseWriter, TestMaxTokenLenTokenizer, NumericFieldsTest, PathHierarchyTokenizerFactoryTest, HdfsChaosMonkeySafeLeaderTest, HdfsSyncSliceTest, TestAuthenticationFramework, ClassificationUpdateProcessorIntegrationTest, TestCursorMarkWithoutUniqueKey, UUIDUpdateProcessorFallbackTest, SolrIndexSplitterTest, TestSchemaManager, TestGraphTermsQParserPlugin, V2ApiIntegrationTest, TestSchemaSimilarityResource, TestRandomFlRTGCloud, TestSizeLimitedDistributedMap, ConvertedLegacyTest, TestFieldCache, TestFastLRUCache, TestImpersonationWithHadoopAuth, SuggestComponentTest, TestCopyFieldCollectionResource, TestCollationFieldDocValues, TestLegacyFieldCache, SolrCloudReportersTest, HLLSerializationTest, TestCoreAdminApis, SchemaVersionSpecificBehaviorTest, SolrJmxReporterCloudTest, TestPayloadCheckQParserPlugin, TestSolrCLIRunExample, TestJmxIntegration, TestXmlQParserPlugin, TestCustomSort, TestJsonRequest, TestClusterStateMutator, DOMUtilTest, TestDocumentBuilder, TestHdfsUpdateLog, TestXIncludeConfig, DeleteShardTest, ShardRoutingTest, TestMinMaxOnMultiValuedField, TestUninvertingReader, TestSolrCoreParser, TestFileDictionaryLookup, DocExpirationUpdateProcessorFactoryTest, TestSolrConfigHandlerCloud]
[junit4] Completed [177/751 (1!)] on J1 in 67.00s, 1 test, 1 failure <<< FAILURES!
[...truncated 45154 lines...]
[JENKINS] Lucene-Solr-7.2-Linux (32bit/jdk1.8.0_144) - Build # 43 -
Still Unstable!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: https://jenkins.thetaphi.de/job/Lucene-Solr-7.2-Linux/43/
Java: 32bit/jdk1.8.0_144 -client -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.UnloadDistributedZkTest.test
Error Message:
Timeout occured while waiting response from server at: http://127.0.0.1:34067/_l/n
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:34067/_l/n
at __randomizedtesting.SeedInfo.seed([E47266BE4364827D:6C265964ED98EF85]:0)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:654)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1103)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:883)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:314)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:991)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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:943)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:829)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:879)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:890)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
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 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)
Caused by: java.net.SocketTimeoutException: Read timed out
at java.net.SocketInputStream.socketRead0(Native Method)
at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
at java.net.SocketInputStream.read(SocketInputStream.java:171)
at java.net.SocketInputStream.read(SocketInputStream.java:141)
at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542)
... 43 more
Build Log:
[...truncated 12631 lines...]
[junit4] Suite: org.apache.solr.cloud.UnloadDistributedZkTest
[junit4] 2> Creating dataDir: /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E47266BE4364827D-001/init-core-data-001
[junit4] 2> 810749 WARN (SUITE-UnloadDistributedZkTest-seed#[E47266BE4364827D]-worker) [ ] o.a.s.SolrTestCaseJ4 startTrackingSearchers: numOpens=1 numCloses=1
[junit4] 2> 810749 INFO (SUITE-UnloadDistributedZkTest-seed#[E47266BE4364827D]-worker) [ ] o.a.s.SolrTestCaseJ4 Using PointFields (NUMERIC_POINTS_SYSPROP=true) w/NUMERIC_DOCVALUES_SYSPROP=false
[junit4] 2> 810751 INFO (SUITE-UnloadDistributedZkTest-seed#[E47266BE4364827D]-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-5776)
[junit4] 2> 810751 INFO (SUITE-UnloadDistributedZkTest-seed#[E47266BE4364827D]-worker) [ ] o.a.s.SolrTestCaseJ4 SecureRandom sanity checks: test.solr.allowed.securerandom=null & java.security.egd=file:/dev/./urandom
[junit4] 2> 810751 INFO (SUITE-UnloadDistributedZkTest-seed#[E47266BE4364827D]-worker) [ ] o.a.s.BaseDistributedSearchTestCase Setting hostContext system property: /_l/n
[junit4] 2> 810754 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.ZkTestServer STARTING ZK TEST SERVER
[junit4] 2> 810754 INFO (Thread-2595) [ ] o.a.s.c.ZkTestServer client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 810754 INFO (Thread-2595) [ ] o.a.s.c.ZkTestServer Starting server
[junit4] 2> 810756 ERROR (Thread-2595) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 810854 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.ZkTestServer start zk server on port:37655
[junit4] 2> 810858 INFO (zkConnectionManagerCallback-1357-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 810863 INFO (zkConnectionManagerCallback-1359-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 810871 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 810874 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 810876 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 810877 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 810879 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 810882 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 810884 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 810885 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 810887 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 810889 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 810891 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractZkTestCase put /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 810893 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.AbstractFullDistribZkTestBase Will use NRT replicas unless explicitly asked otherwise
[junit4] 2> 811012 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.e.j.s.Server jetty-9.3.20.v20170531
[junit4] 2> 811016 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.e.j.s.h.ContextHandler Started o.e.j.s.ServletContextHandler@16394da{/_l/n,null,AVAILABLE}
[junit4] 2> 811017 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.e.j.s.AbstractConnector Started ServerConnector@2e4b3e{HTTP/1.1,[http/1.1]}{127.0.0.1:34067}
[junit4] 2> 811017 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.e.j.s.Server Started @812128ms
[junit4] 2> 811017 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.s.e.JettySolrRunner Jetty properties: {solr.data.dir=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E47266BE4364827D-001/tempDir-001/control/data, replicaType=NRT, hostContext=/_l/n, hostPort=34067, coreRootDirectory=/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E47266BE4364827D-001/control-001/cores}
[junit4] 2> 811017 ERROR (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.u.StartupLoggingUtils Missing Java Option solr.log.dir. Logging may be missing or incomplete.
[junit4] 2> 811017 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.s.SolrDispatchFilter ___ _ Welcome to Apache Solr™ version 7.2.0
[junit4] 2> 811017 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.s.SolrDispatchFilter / __| ___| |_ _ Starting in cloud mode on port null
[junit4] 2> 811017 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.s.SolrDispatchFilter \__ \/ _ \ | '_| Install dir: null
[junit4] 2> 811017 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.s.SolrDispatchFilter |___/\___/_|_| Start time: 2017-12-11T01:28:19.996Z
[junit4] 2> 811020 INFO (zkConnectionManagerCallback-1361-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 811027 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.s.SolrDispatchFilter Loading solr.xml from SolrHome (not found in ZooKeeper)
[junit4] 2> 811027 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.SolrXmlConfig Loading container configuration from /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E47266BE4364827D-001/control-001/solr.xml
[junit4] 2> 811032 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverWorkLoopDelay is ignored
[junit4] 2> 811032 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.SolrXmlConfig Configuration parameter autoReplicaFailoverBadNodeExpiration is ignored
[junit4] 2> 811033 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.SolrXmlConfig MBean server found: com.sun.jmx.mbeanserver.JmxMBeanServer@1daf2c6, but no JMX reporters were configured - adding default JMX reporter.
[junit4] 2> 811039 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.ZkContainer Zookeeper client=127.0.0.1:37655/solr
[junit4] 2> 811040 INFO (zkConnectionManagerCallback-1365-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 811044 INFO (zkConnectionManagerCallback-1367-thread-1-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 811178 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.Overseer Overseer (id=null) closing
[junit4] 2> 811178 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.OverseerElectionContext I am going to be the leader 127.0.0.1:34067__l%2Fn
[junit4] 2> 811179 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.Overseer Overseer (id=99153064737767428-127.0.0.1:34067__l%2Fn-n_0000000000) starting
[junit4] 2> 811188 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.ZkController Register node as live in ZooKeeper:/live_nodes/127.0.0.1:34067__l%2Fn
[junit4] 2> 811196 INFO (OverseerStateUpdate-99153064737767428-127.0.0.1:34067__l%2Fn-n_0000000000) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 811510 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [n:127.0.0.1:34067__l%2Fn ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.node' (registry 'solr.node') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1daf2c6
[junit4] 2> 811532 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [n:127.0.0.1:34067__l%2Fn ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jvm' (registry 'solr.jvm') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1daf2c6
[junit4] 2> 811532 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [n:127.0.0.1:34067__l%2Fn ] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.jetty' (registry 'solr.jetty') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1daf2c6
[junit4] 2> 811535 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.CorePropertiesLocator Found 0 core definitions underneath /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E47266BE4364827D-001/control-001/cores
[junit4] 2> 811570 INFO (zkConnectionManagerCallback-1373-thread-1) [ ] o.a.s.c.c.ConnectionManager zkClient has connected
[junit4] 2> 811571 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (0) -> (1)
[junit4] 2> 811572 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.s.i.ZkClientClusterStateProvider Cluster at 127.0.0.1:37655/solr ready
[junit4] 2> 811575 INFO (qtp32319703-7360) [n:127.0.0.1:34067__l%2Fn ] o.a.s.h.a.CollectionsHandler Invoked Collection Action :create with params replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:34067__l%252Fn&wt=javabin&version=2 and sendToOCPQueue=true
[junit4] 2> 811581 INFO (OverseerThreadFactory-2776-thread-1-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.CreateCollectionCmd Create collection control_collection
[junit4] 2> 811695 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn ] o.a.s.h.a.CoreAdminOperation core create command qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT
[junit4] 2> 811696 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.TransientSolrCoreCacheDefault Allocating transient cache for 4 transient cores
[junit4] 2> 811802 INFO (zkCallback-1366-thread-1-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [1])
[junit4] 2> 812726 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrConfig Using Lucene MatchVersion: 7.2.0
[junit4] 2> 812752 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema [control_collection_shard1_replica_n1] Schema name=test
[junit4] 2> 812922 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.IndexSchema Loaded schema test/1.0 with uniqueid field id
[junit4] 2> 812949 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.CoreContainer Creating SolrCore 'control_collection_shard1_replica_n1' using configuration from collection control_collection, trusted=true
[junit4] 2> 812950 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter JMX monitoring for 'solr.core.control_collection.shard1.replica_n1' (registry 'solr.core.control_collection.shard1.replica_n1') enabled at server: com.sun.jmx.mbeanserver.JmxMBeanServer@1daf2c6
[junit4] 2> 812950 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore solr.RecoveryStrategy.Builder
[junit4] 2> 812950 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [[control_collection_shard1_replica_n1] ] Opening new SolrCore at [/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E47266BE4364827D-001/control-001/cores/control_collection_shard1_replica_n1], dataDir=[/home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/../../../../../../../../../home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E47266BE4364827D-001/control-001/cores/control_collection_shard1_replica_n1/data/]
[junit4] 2> 812956 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=12, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0]
[junit4] 2> 812960 WARN (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.RequestHandlers INVALID paramSet a in requestHandler {type = requestHandler,name = /dump,class = DumpRequestHandler,attributes = {initParams=a, name=/dump, class=DumpRequestHandler},args = {defaults={a=A,b=B}}}
[junit4] 2> 813045 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateHandler Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 813045 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 numVersionBuckets=65536
[junit4] 2> 813047 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Hard AutoCommit: disabled
[junit4] 2> 813047 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.CommitTracker Soft AutoCommit: disabled
[junit4] 2> 813050 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.RandomMergePolicy RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=27, maxMergeAtOnceExplicit=25, maxMergedSegmentMB=63.00390625, floorSegmentMB=0.3759765625, forceMergeDeletesPctAllowed=6.722459533314317, segmentsPerTier=48.0, maxCFSSegmentSizeMB=1.84765625, noCFSRatio=1.0
[junit4] 2> 813051 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.SolrIndexSearcher Opening [Searcher@3aafb9[control_collection_shard1_replica_n1] main]
[junit4] 2> 813052 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 813053 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.r.ManagedResourceStorage Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 813054 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.h.ReplicationHandler Commits will be reserved for 10000ms.
[junit4] 2> 813057 INFO (searcherExecutor-2779-thread-1-processing-n:127.0.0.1:34067__l%2Fn x:control_collection_shard1_replica_n1 s:shard1 c:control_collection) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] Registered new searcher Searcher@3aafb9[control_collection_shard1_replica_n1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 813057 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.u.UpdateLog Could not find max version in index or recent updates, using new clock 1586449038218100736
[junit4] 2> 813065 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Enough replicas found to continue.
[junit4] 2> 813065 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I may be the new leader - try and sync
[junit4] 2> 813065 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync replicas to http://127.0.0.1:34067/_l/n/control_collection_shard1_replica_n1/
[junit4] 2> 813065 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy Sync Success - now sync replicas to me
[junit4] 2> 813065 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.SyncStrategy http://127.0.0.1:34067/_l/n/control_collection_shard1_replica_n1/ has no replicas
[junit4] 2> 813065 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext Found all replicas participating in election, clear LIR
[junit4] 2> 813071 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ShardLeaderElectionContext I am the new leader: http://127.0.0.1:34067/_l/n/control_collection_shard1_replica_n1/ shard1
[junit4] 2> 871628 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.ChaosMonkey monkey: stop jetty! 34067
[junit4] 2> 871628 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.CoreContainer Shutting down CoreContainer instance=14858708
[junit4] 2> 871628 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.node, tag=null
[junit4] 2> 871628 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@1c1d5e3: rootName = null, domain = solr.node, service url = null, agent id = null] for registry solr.node / com.codahale.metrics.MetricRegistry@1a2d3bc
[junit4] 2> 871630 WARN (NIOServerCxn.Factory:0.0.0.0/0.0.0.0:0) [ ] o.a.z.s.NIOServerCnxn caught end of stream exception
[junit4] 2> EndOfStreamException: Unable to read additional data from client sessionid 0x160432fe2160005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:239)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:203)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 871634 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jvm, tag=null
[junit4] 2> 871634 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@a68f7d: rootName = null, domain = solr.jvm, service url = null, agent id = null] for registry solr.jvm / com.codahale.metrics.MetricRegistry@18062cd
[junit4] 2> 871640 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.jetty, tag=null
[junit4] 2> 871640 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@d03f8f: rootName = null, domain = solr.jetty, service url = null, agent id = null] for registry solr.jetty / com.codahale.metrics.MetricRegistry@89f89
[junit4] 2> 871641 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.ZkController Remove node as live in ZooKeeper:/live_nodes/127.0.0.1:34067__l%2Fn
[junit4] 2> 871642 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.cluster, tag=null
[junit4] 2> 871642 INFO (zkCallback-1366-thread-2-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.c.ZkStateReader Updated live nodes from ZooKeeper... (1) -> (0)
[junit4] 2> 871643 INFO (coreCloseExecutor-2784-thread-1) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.c.SolrCore [control_collection_shard1_replica_n1] CLOSING SolrCore org.apache.solr.core.SolrCore@ddfb2c
[junit4] 2> 871644 INFO (coreCloseExecutor-2784-thread-1) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.core.control_collection.shard1.replica_n1, tag=14547756
[junit4] 2> 871644 INFO (coreCloseExecutor-2784-thread-1) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.m.r.SolrJmxReporter Closing reporter [org.apache.solr.metrics.reporters.SolrJmxReporter@ec33b9: rootName = null, domain = solr.core.control_collection.shard1.replica_n1, service url = null, agent id = null] for registry solr.core.control_collection.shard1.replica_n1 / com.codahale.metrics.MetricRegistry@17c4b3e
[junit4] 2> 871654 INFO (coreCloseExecutor-2784-thread-1) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 r:core_node2 x:control_collection_shard1_replica_n1] o.a.s.m.SolrMetricManager Closing metric reporters for registry=solr.collection.control_collection.shard1.leader, tag=14547756
[junit4] 2> 882220 INFO (ScheduledTrigger-2774-thread-4) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.a.SystemLogListener Collection .system does not exist, disabling logging.
[junit4] 2> 882222 INFO (AutoscalingActionExecutor-2775-thread-1-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.a.ExecutePlanAction No operations to execute for event: {
[junit4] 2> "id":"14ff193fb2ca1c40T4nkwwcazqwho2l20uqazl177x",
[junit4] 2> "source":".auto_add_replicas",
[junit4] 2> "eventTime":1512955761193000000,
[junit4] 2> "eventType":"NODELOST",
[junit4] 2> "properties":{
[junit4] 2> "eventTimes":[1512955761193000000],
[junit4] 2> "_enqueue_time_":1512955771199000000,
[junit4] 2> "nodeNames":["127.0.0.1:34067__l%2Fn"]}}
[junit4] 2> 991580 ERROR (qtp32319703-7360) [n:127.0.0.1:34067__l%2Fn ] o.a.s.h.RequestHandlerBase org.apache.solr.common.SolrException: create the collection time out:180s
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:314)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:246)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:224)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:735)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:716)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:497)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 991580 INFO (qtp32319703-7360) [n:127.0.0.1:34067__l%2Fn ] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/collections params={replicationFactor=1&collection.configName=conf1&name=control_collection&nrtReplicas=1&action=CREATE&numShards=1&createNodeSet=127.0.0.1:34067__l%252Fn&wt=javabin&version=2} status=500 QTime=180005
[junit4] 2> 991580 ERROR (qtp32319703-7360) [n:127.0.0.1:34067__l%2Fn ] o.a.s.s.HttpSolrCall null:org.apache.solr.common.SolrException: create the collection time out:180s
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:314)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.invokeAction(CollectionsHandler.java:246)
[junit4] 2> at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:224)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:735)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:716)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:497)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:493)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1411726 ERROR (OverseerThreadFactory-2776-thread-1-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.OverseerCollectionMessageHandler Error from shard: http://127.0.0.1:34067/_l/n
[junit4] 2> org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:34067/_l/n
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:654)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
[junit4] 2> at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1219)
[junit4] 2> at org.apache.solr.handler.component.HttpShardHandler.lambda$submit$0(HttpShardHandler.java:172)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at com.codahale.metrics.InstrumentedExecutorService$InstrumentedRunnable.run(InstrumentedExecutorService.java:176)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] 2> at java.net.SocketInputStream.socketRead0(Native Method)
[junit4] 2> at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
[junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:171)
[junit4] 2> at java.net.SocketInputStream.read(SocketInputStream.java:141)
[junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
[junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
[junit4] 2> at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
[junit4] 2> at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
[junit4] 2> at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
[junit4] 2> at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
[junit4] 2> at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
[junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
[junit4] 2> at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] 2> at org.apache.solr.util.stats.InstrumentedHttpRequestExecutor.execute(InstrumentedHttpRequestExecutor.java:118)
[junit4] 2> at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
[junit4] 2> at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
[junit4] 2> at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[junit4] 2> at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
[junit4] 2> at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[junit4] 2> at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
[junit4] 2> at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542)
[junit4] 2> ... 12 more
[junit4] 2> 1411728 ERROR (OverseerThreadFactory-2776-thread-1-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.OverseerCollectionMessageHandler Cleaning up collection [control_collection].
[junit4] 2> 1411727 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.Overseer Overseer (id=99153064737767428-127.0.0.1:34067__l%2Fn-n_0000000000) closing
[junit4] 2> 1411729 INFO (OverseerStateUpdate-99153064737767428-127.0.0.1:34067__l%2Fn-n_0000000000) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.Overseer Overseer Loop exiting : 127.0.0.1:34067__l%2Fn
[junit4] 2> 1411730 INFO (OverseerThreadFactory-2776-thread-1-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.OverseerCollectionMessageHandler Executing Collection Cmd : action=UNLOAD&deleteInstanceDir=true&deleteDataDir=true
[junit4] 2> 1441772 INFO (zkCallback-1366-thread-5-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.c.ZkStateReader A cluster state change: [WatchedEvent state:SyncConnected type:NodeDeleted path:/collections/control_collection/state.json] for collection [control_collection] has occurred - updating... (live nodes size: [0])
[junit4] 2> 1441774 ERROR (OverseerThreadFactory-2776-thread-1-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.OverseerCollectionMessageHandler Collection: control_collection operation: create failed:org.apache.solr.common.SolrException: Could not fully remove collection: control_collection
[junit4] 2> at org.apache.solr.cloud.DeleteCollectionCmd.call(DeleteCollectionCmd.java:109)
[junit4] 2> at org.apache.solr.cloud.OverseerCollectionMessageHandler.cleanupCollection(OverseerCollectionMessageHandler.java:679)
[junit4] 2> at org.apache.solr.cloud.CreateCollectionCmd.call(CreateCollectionCmd.java:302)
[junit4] 2> at org.apache.solr.cloud.OverseerCollectionMessageHandler.processMessage(OverseerCollectionMessageHandler.java:233)
[junit4] 2> at org.apache.solr.cloud.OverseerTaskProcessor$Runner.run(OverseerTaskProcessor.java:464)
[junit4] 2> at org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor.lambda$execute$0(ExecutorUtil.java:188)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1149)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:624)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2>
[junit4] 2> 1441776 INFO (OverseerThreadFactory-2776-thread-1-processing-n:127.0.0.1:34067__l%2Fn) [n:127.0.0.1:34067__l%2Fn ] 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> 1441777 WARN (OverseerAutoScalingTriggerThread-99153064737767428-127.0.0.1:34067__l%2Fn-n_0000000000) [n:127.0.0.1:34067__l%2Fn ] o.a.s.c.a.OverseerTriggerThread OverseerTriggerThread woken up but we are closed, exiting.
[junit4] 2> 1441779 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.e.j.s.AbstractConnector Stopped ServerConnector@2e4b3e{HTTP/1.1,[http/1.1]}{127.0.0.1:0}
[junit4] 2> 1441779 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.e.j.s.h.ContextHandler Stopped o.e.j.s.ServletContextHandler@16394da{/_l/n,null,UNAVAILABLE}
[junit4] 2> 1441806 ERROR (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkController Error getting leader from zk
[junit4] 2> org.apache.solr.common.SolrException: No registered leader was found after waiting for 1220000ms , collection: control_collection slice: shard1 saw state=null with live_nodes=[]
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:772)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderUrl(ZkStateReader.java:737)
[junit4] 2> at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:1178)
[junit4] 2> at org.apache.solr.cloud.ZkController.register(ZkController.java:1079)
[junit4] 2> at org.apache.solr.cloud.ZkController.register(ZkController.java:1014)
[junit4] 2> at org.apache.solr.core.ZkContainer.lambda$registerInZk$0(ZkContainer.java:181)
[junit4] 2> at org.apache.solr.core.ZkContainer.registerInZk(ZkContainer.java:208)
[junit4] 2> at org.apache.solr.core.CoreContainer.registerCore(CoreContainer.java:886)
[junit4] 2> at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1047)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:948)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:91)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:384)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:389)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:174)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:735)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:716)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:497)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> 1441807 ERROR (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.c.ZkContainer :org.apache.solr.common.SolrException: Error getting leader from zk for shard shard1
[junit4] 2> at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:1207)
[junit4] 2> at org.apache.solr.cloud.ZkController.register(ZkController.java:1079)
[junit4] 2> at org.apache.solr.cloud.ZkController.register(ZkController.java:1014)
[junit4] 2> at org.apache.solr.core.ZkContainer.lambda$registerInZk$0(ZkContainer.java:181)
[junit4] 2> at org.apache.solr.core.ZkContainer.registerInZk(ZkContainer.java:208)
[junit4] 2> at org.apache.solr.core.CoreContainer.registerCore(CoreContainer.java:886)
[junit4] 2> at org.apache.solr.core.CoreContainer.createFromDescriptor(CoreContainer.java:1047)
[junit4] 2> at org.apache.solr.core.CoreContainer.create(CoreContainer.java:948)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.lambda$static$0(CoreAdminOperation.java:91)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminOperation.execute(CoreAdminOperation.java:384)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler$CallInfo.call(CoreAdminHandler.java:389)
[junit4] 2> at org.apache.solr.handler.admin.CoreAdminHandler.handleRequestBody(CoreAdminHandler.java:174)
[junit4] 2> at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:177)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdmin(HttpSolrCall.java:735)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.handleAdminRequest(HttpSolrCall.java:716)
[junit4] 2> at org.apache.solr.servlet.HttpSolrCall.call(HttpSolrCall.java:497)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:382)
[junit4] 2> at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:326)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:139)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1759)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:582)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:224)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1180)
[junit4] 2> at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:512)
[junit4] 2> at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185)
[junit4] 2> at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1112)
[junit4] 2> at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141)
[junit4] 2> at org.eclipse.jetty.server.handler.gzip.GzipHandler.handle(GzipHandler.java:426)
[junit4] 2> at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:134)
[junit4] 2> at org.eclipse.jetty.server.Server.handle(Server.java:534)
[junit4] 2> at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:320)
[junit4] 2> at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:251)
[junit4] 2> at org.eclipse.jetty.io.AbstractConnection$ReadCallback.succeeded(AbstractConnection.java:283)
[junit4] 2> at org.eclipse.jetty.io.FillInterest.fillable(FillInterest.java:108)
[junit4] 2> at org.eclipse.jetty.io.SelectChannelEndPoint$2.run(SelectChannelEndPoint.java:93)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.executeProduceConsume(ExecuteProduceConsume.java:303)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.produceConsume(ExecuteProduceConsume.java:148)
[junit4] 2> at org.eclipse.jetty.util.thread.strategy.ExecuteProduceConsume.run(ExecuteProduceConsume.java:136)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:671)
[junit4] 2> at org.eclipse.jetty.util.thread.QueuedThreadPool$2.run(QueuedThreadPool.java:589)
[junit4] 2> at java.lang.Thread.run(Thread.java:748)
[junit4] 2> Caused by: org.apache.solr.common.SolrException: No registered leader was found after waiting for 1220000ms , collection: control_collection slice: shard1 saw state=null with live_nodes=[]
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderRetry(ZkStateReader.java:772)
[junit4] 2> at org.apache.solr.common.cloud.ZkStateReader.getLeaderUrl(ZkStateReader.java:737)
[junit4] 2> at org.apache.solr.cloud.ZkController.getLeader(ZkController.java:1178)
[junit4] 2> ... 41 more
[junit4] 2>
[junit4] 2> 1441819 INFO (qtp32319703-7362) [n:127.0.0.1:34067__l%2Fn c:control_collection s:shard1 x:control_collection_shard1_replica_n1] o.a.s.s.HttpSolrCall [admin] webapp=null path=/admin/cores params={qt=/admin/cores&collection.configName=conf1&newCollection=true&name=control_collection_shard1_replica_n1&action=CREATE&numShards=1&collection=control_collection&shard=shard1&wt=javabin&version=2&replicaType=NRT} status=0 QTime=630123
[junit4] 2> 1441819 ERROR (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.z.s.ZooKeeperServer ZKShutdownHandler is not registered, so ZooKeeper server won't take any action on ERROR or SHUTDOWN server state changes
[junit4] 2> 1441820 INFO (TEST-UnloadDistributedZkTest.test-seed#[E47266BE4364827D]) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37655 37655
[junit4] 2> 1442671 INFO (Thread-2595) [ ] o.a.s.c.ZkTestServer connecting to 127.0.0.1:37655 37655
[junit4] 2> 1442672 WARN (Thread-2595) [ ] o.a.s.c.ZkTestServer Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/aliases.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/clusterstate.json
[junit4] 2> 2 /solr/clusterprops.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/live_nodes
[junit4] 2> 2 /solr/collections
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=UnloadDistributedZkTest -Dtests.method=test -Dtests.seed=E47266BE4364827D -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=lt-LT -Dtests.timezone=GB -Dtests.asserts=true -Dtests.file.encoding=UTF-8
[junit4] ERROR 632s J2 | UnloadDistributedZkTest.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: Timeout occured while waiting response from server at: http://127.0.0.1:34067/_l/n
[junit4] > at __randomizedtesting.SeedInfo.seed([E47266BE4364827D:6C265964ED98EF85]:0)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:654)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:255)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:244)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:483)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:413)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1103)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:883)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:816)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:194)
[junit4] > at org.apache.solr.client.solrj.SolrRequest.process(SolrRequest.java:211)
[junit4] > at org.apache.solr.cloud.AbstractFullDistribZkTestBase.createServers(AbstractFullDistribZkTestBase.java:314)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:991)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:968)
[junit4] > at java.lang.Thread.run(Thread.java:748)
[junit4] > Caused by: java.net.SocketTimeoutException: Read timed out
[junit4] > at java.net.SocketInputStream.socketRead0(Native Method)
[junit4] > at java.net.SocketInputStream.socketRead(SocketInputStream.java:116)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:171)
[junit4] > at java.net.SocketInputStream.read(SocketInputStream.java:141)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.streamRead(SessionInputBufferImpl.java:137)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.fillBuffer(SessionInputBufferImpl.java:153)
[junit4] > at org.apache.http.impl.io.SessionInputBufferImpl.readLine(SessionInputBufferImpl.java:282)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:138)
[junit4] > at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:56)
[junit4] > at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:259)
[junit4] > at org.apache.http.impl.DefaultBHttpClientConnection.receiveResponseHeader(DefaultBHttpClientConnection.java:163)
[junit4] > at org.apache.http.impl.conn.CPoolProxy.receiveResponseHeader(CPoolProxy.java:165)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:273)
[junit4] > at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:125)
[junit4] > at org.apache.http.impl.execchain.MainClientExec.execute(MainClientExec.java:272)
[junit4] > at org.apache.http.impl.execchain.ProtocolExec.execute(ProtocolExec.java:185)
[junit4] > at org.apache.http.impl.execchain.RetryExec.execute(RetryExec.java:89)
[junit4] > at org.apache.http.impl.execchain.RedirectExec.execute(RedirectExec.java:111)
[junit4] > at org.apache.http.impl.client.InternalHttpClient.doExecute(InternalHttpClient.java:185)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:83)
[junit4] > at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:56)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:542)
[junit4] > ... 43 more
[junit4] 2> NOTE: leaving temporary files on disk at: /home/jenkins/workspace/Lucene-Solr-7.2-Linux/solr/build/solr-core/test/J2/temp/solr.cloud.UnloadDistributedZkTest_E47266BE4364827D-001
[junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=6, maxDocsPerChunk=476, blockSize=8), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=6, blockSize=8)), sim=RandomSimilarity(queryNorm=true): {}, locale=lt-LT, timezone=GB
[junit4] 2> NOTE: Linux 4.10.0-40-generic i386/Oracle Corporation 1.8.0_144 (32-bit)/cpus=8,threads=1,free=180773464,total=469762048
[junit4] 2> NOTE: All tests run in this JVM: [TestSolrFieldCacheBean, RecoveryZkTest, SharedFSAutoReplicaFailoverTest, DataDrivenBlockJoinTest, ClassificationUpdateProcessorTest, TestLRUCache, IgnoreCommitOptimizeUpdateProcessorFactoryTest, IndexBasedSpellCheckerTest, FieldMutatingUpdateProcessorTest, TestSolrCoreProperties, TestAnalyzeInfixSuggestions, DistributedDebugComponentTest, TestSubQueryTransformerDistrib, TestSegmentSorting, TestFieldCacheReopen, HdfsRecoveryZkTest, VersionInfoTest, BadComponentTest, ResponseHeaderTest, SolrShardReporterTest, RequestLoggingTest, AnalyticsMergeStrategyTest, TestDynamicFieldCollectionResource, TestExactSharedStatsCache, TestSolrXml, AddBlockUpdateTest, TestSchemalessBufferedUpdates, LargeFieldTest, HdfsCollectionsAPIDistributedZkTest, TestQueryTypes, TestSchemaNameResource, TestCoreDiscovery, AsyncCallRequestStatusResponseTest, TestSmileRequest, AutoAddReplicasIntegrationTest, TestNumericTerms32, DocValuesNotIndexedTest, SolrTestCaseJ4Test, TestFieldSortValues, ReplicationFactorTest, TestConfigOverlay, SpatialHeatmapFacetsTest, TestFuzzyAnalyzedSuggestions, TestLegacyTerms, NodeMutatorTest, ConfigSetsAPITest, HLLUtilTest, CoreSorterTest, TestInPlaceUpdatesDistrib, TestReplicationHandlerBackup, TestSha256AuthenticationProvider, TestCoreContainer, TriggerIntegrationTest, PluginInfoTest, ZkStateReaderTest, TestConfigSetsAPIZkFailure, TestHashPartitioner, TestLeaderInitiatedRecoveryThread, HdfsLockFactoryTest, HdfsThreadLeakTest, TestDocBasedVersionConstraints, TestCollectionAPIs, TestDFISimilarityFactory, TestJavabinTupleStreamParser, TestNoOpRegenerator, MigrateRouteKeyTest, TestAuthorizationFramework, BlockJoinFacetDistribTest, ExplicitHLLTest, HdfsBasicDistributedZkTest, TestCloudSearcherWarming, DirectSolrConnectionTest, DistributedFacetPivotLargeTest, TestValueSourceCache, CSVRequestHandlerTest, TestExtendedDismaxParser, AlternateDirectoryTest, SearchRateTriggerTest, UnloadDistributedZkTest]
[junit4] Completed [311/751 (1!)] on J2 in 631.96s, 1 test, 1 error <<< FAILURES!
[...truncated 44645 lines...]