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 2015/02/28 19:02:27 UTC
[JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0_31) - Build #
11898 - Still Failing!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/11898/
Java: 64bit/jdk1.8.0_31 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed.
FAILED: org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test
Error Message:
No live SolrServers available to handle this request:[https://127.0.0.1:60811, https://127.0.0.1:50092, https://127.0.0.1:46672]
Stack Trace:
org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:60811, https://127.0.0.1:50092, https://127.0.0.1:46672]
at __randomizedtesting.SeedInfo.seed([A9A51ACA5447870:82CE6E760BB81588]:0)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:349)
at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1043)
at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:809)
at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:752)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.addReplicaTest(CollectionsAPIDistributedZkTest.java:1125)
at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:202)
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:483)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618)
at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827)
at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863)
at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:945)
at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:920)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836)
at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738)
at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772)
at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
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:54)
at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:60811: ADDREPLICA failed to create replica
at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370)
at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
... 47 more
Build Log:
[...truncated 8871 lines...]
[junit4] Suite: org.apache.solr.cloud.CollectionsAPIDistributedZkTest
[junit4] 2> log4j:WARN No such property [conversionPattern] in org.apache.solr.util.SolrLogLayout.
[junit4] 2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/init-core-data-001
[junit4] 2> 1230 T14 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false)
[junit4] 2> 1277 T14 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
[junit4] 2> 1320 T14 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 1349 T15 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 1447 T14 oasc.ZkTestServer.run start zk server on port:53803
[junit4] 2> 1473 T14 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1517 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1596 T22 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@25d61b7b name:ZooKeeperConnection Watcher:127.0.0.1:53803 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1596 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1599 T14 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1601 T14 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 1628 T14 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1629 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1632 T25 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@31b9261e name:ZooKeeperConnection Watcher:127.0.0.1:53803/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1632 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1633 T14 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 1639 T14 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 1644 T14 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 1647 T14 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 1650 T14 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 1654 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 1666 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 1671 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4] 2> 1672 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 1676 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1677 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 1681 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 1682 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 1685 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 1686 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 1692 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4] 2> 1693 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 1698 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml
[junit4] 2> 1698 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml
[junit4] 2> 1714 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 1714 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 1722 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1723 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 1731 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 1732 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 1735 T14 oasc.AbstractZkTestCase.putConfig put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 1736 T14 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 1742 T14 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 1743 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 1745 T28 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1ba5ceac name:ZooKeeperConnection Watcher:127.0.0.1:53803/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 1745 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 1746 T14 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 2268 T14 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores/collection1
[junit4] 2> 2316 T14 oejul.Log.initialized Logging initialized @3837ms
[junit4] 2> 2527 T14 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 2617 T14 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@273c2b68{/,null,AVAILABLE}
[junit4] 2> 3358 T14 oejs.AbstractConnector.doStart Started ServerConnector@13317c92{SSL-http/1.1}{127.0.0.1:60811}
[junit4] 2> 3359 T14 oejs.Server.doStart Started @4884ms
[junit4] 2> 3570 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 3680 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3681 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001
[junit4] 2> 3682 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/'
[junit4] 2> 3926 T14 oasc.SolrXmlConfig.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/solr.xml
[junit4] 2> 4211 T14 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores
[junit4] 2> 4217 T14 oasc.CoreContainer.<init> New CoreContainer 1008613381
[junit4] 2> 4218 T14 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/]
[junit4] 2> 4219 T14 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/lib
[junit4] 2> 4219 T14 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/lib).
[junit4] 2> 4290 T14 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 4290 T14 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 4298 T14 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 4299 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 4299 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 4300 T14 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 4300 T14 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 4301 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 4302 T14 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 4302 T14 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 4303 T14 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 4349 T14 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 4356 T14 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 4358 T14 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 4361 T14 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 4362 T14 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53803/solr
[junit4] 2> 4380 T14 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 4381 T14 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 4382 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4386 T43 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@57290e4a name:ZooKeeperConnection Watcher:127.0.0.1:53803 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4387 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4388 T14 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 4411 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 4420 T46 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@73c4abf2 name:ZooKeeperConnection Watcher:127.0.0.1:53803/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 4421 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 4430 T14 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 4442 T14 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 4450 T14 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running
[junit4] 2> 4460 T14 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed
[junit4] 2> 4464 T14 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure
[junit4] 2> 4483 T14 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 4486 T14 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:60811_
[junit4] 2> 4498 T14 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:60811_
[junit4] 2> 4504 T14 oascc.SolrZkClient.makePath makePath: /overseer_elect
[junit4] 2> 4507 T14 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 4509 T14 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 4519 T14 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:60811_
[junit4] 2> 4520 T14 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 4523 T14 oasc.Overseer.start Overseer (id=93398250006577156-127.0.0.1:60811_-n_0000000000) starting
[junit4] 2> 4529 T14 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 4584 T14 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=10000 autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 4683 T48 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 4685 T14 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 4689 T14 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 4694 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 4702 T47 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 4768 T14 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores
[junit4] 2> 4791 T14 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores/collection1/
[junit4] 2> 4792 T14 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 4796 T50 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection
[junit4] 2> 4797 T50 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 4802 T46 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 4802 T50 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 4805 T47 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60811",
[junit4] 2> "node_name":"127.0.0.1:60811_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 0
[junit4] 2> 4815 T47 oasco.ReplicaMutator.updateState Update state numShards=1 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60811",
[junit4] 2> "node_name":"127.0.0.1:60811_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 4817 T47 oasco.ClusterStateMutator.createCollection building a new cName: control_collection
[junit4] 2> 4825 T47 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 4831 T46 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 5804 T50 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 5804 T50 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 5805 T50 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 5806 T50 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 5807 T50 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 5808 T50 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores/collection1/'
[junit4] 2> 5832 T50 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 5857 T50 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 5913 T50 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 5940 T50 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 6007 T50 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 6016 T50 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 6121 T50 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 7225 T50 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 7252 T50 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 7254 T50 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 7445 T50 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 7452 T50 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 7457 T50 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 7472 T50 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 7472 T50 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 7473 T50 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 7475 T50 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 7475 T50 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 7479 T50 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection
[junit4] 2> 7515 T50 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 7520 T50 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores/collection1/data/
[junit4] 2> 7524 T50 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@737d2a83
[junit4] 2> 7586 T50 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores/collection1/data
[junit4] 2> 7588 T50 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores/collection1/data/index/
[junit4] 2> 7588 T50 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 7602 T50 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/control-001/cores/collection1/data/index
[junit4] 2> 7603 T50 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=96.6611328125, floorSegmentMB=1.4775390625, forceMergeDeletesPctAllowed=28.852509199252363, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20338264693827954
[junit4] 2> 7674 T50 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@323ec63b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@59388c3d),segFN=segments_1,generation=1}
[junit4] 2> 7676 T50 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 7934 T50 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 7962 T50 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 7968 T50 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 7969 T50 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 7969 T50 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 7970 T50 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 8001 T50 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 8001 T50 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 8002 T50 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 8042 T50 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 8044 T50 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 8049 T50 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 8056 T50 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 8058 T50 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 8061 T50 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 8082 T50 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 8086 T50 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 8089 T50 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 8091 T50 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 8092 T50 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 8094 T50 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 8116 T50 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 8117 T50 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 8118 T50 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 8126 T50 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 8128 T50 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 8136 T50 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 8234 T50 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 8240 T50 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 8241 T50 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 8243 T50 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 8245 T50 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 8268 T50 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 8280 T50 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 8280 T50 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 8282 T50 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=46.650390625, floorSegmentMB=1.572265625, forceMergeDeletesPctAllowed=21.43306147359764, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31335281901899237
[junit4] 2> 8285 T50 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@323ec63b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@59388c3d),segFN=segments_1,generation=1}
[junit4] 2> 8285 T50 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 8302 T50 oass.SolrIndexSearcher.<init> Opening Searcher@3e32af7c[collection1] main
[junit4] 2> 8311 T50 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 8327 T50 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 8327 T50 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 8330 T50 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 8331 T50 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 8352 T50 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 8353 T50 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 8354 T50 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 8354 T50 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 8378 T50 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 8380 T51 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3e32af7c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 8383 T50 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 8388 T54 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:60811 collection:control_collection shard:shard1
[junit4] 2> 8389 T14 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 8389 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 8410 T54 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 8426 T54 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 8444 T46 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 8446 T47 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection"} current state version: 1
[junit4] 2> 8450 T54 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 8452 T46 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 8463 T54 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 8463 T14 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> ASYNC NEW_CORE C0 name=collection1 org.apache.solr.core.SolrCore@3f663b03 url=https://127.0.0.1:60811/collection1 node=127.0.0.1:60811_ C0_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:60811, node_name=127.0.0.1:60811_, state=down}
[junit4] 2> 8468 T54 C0 P60811 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:60811/collection1/
[junit4] 2> 8472 T54 C0 P60811 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 8472 T54 C0 P60811 oasc.SyncStrategy.syncToMe https://127.0.0.1:60811/collection1/ has no replicas
[junit4] 2> 8482 T54 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:60811/collection1/ shard1
[junit4] 2> 8481 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 8490 T57 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@21107ead name:ZooKeeperConnection Watcher:127.0.0.1:53803/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 8490 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 8491 T14 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 8497 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 8498 T54 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 8508 T46 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 8510 T47 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"https://127.0.0.1:60811",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 8511 T14 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false
[junit4] 2> 8618 T57 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 8618 T46 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 8662 T54 oasc.ZkController.register We are https://127.0.0.1:60811/collection1/ and leader is https://127.0.0.1:60811/collection1/
[junit4] 2> 8664 T54 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:60811
[junit4] 2> 8664 T54 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 8665 T54 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection
[junit4] 2> 8665 T54 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 8668 T46 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 8670 T47 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60811",
[junit4] 2> "node_name":"127.0.0.1:60811_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 3
[junit4] 2> 8671 T47 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:60811",
[junit4] 2> "node_name":"127.0.0.1:60811_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 8785 T57 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 8785 T46 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 9000 T14 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores/collection1
[junit4] 2> 9002 T14 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1
[junit4] 2> 9006 T14 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 9018 T14 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@4866275e{/,null,AVAILABLE}
[junit4] 2> 9041 T14 oejs.AbstractConnector.doStart Started ServerConnector@7c4ed904{SSL-http/1.1}{127.0.0.1:44469}
[junit4] 2> 9042 T14 oejs.Server.doStart Started @10568ms
[junit4] 2> 9047 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 9048 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 9049 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001
[junit4] 2> 9049 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/'
[junit4] 2> 9075 T14 oasc.SolrXmlConfig.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/solr.xml
[junit4] 2> 9099 T14 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores
[junit4] 2> 9099 T14 oasc.CoreContainer.<init> New CoreContainer 1326700639
[junit4] 2> 9100 T14 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/]
[junit4] 2> 9100 T14 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/lib
[junit4] 2> 9101 T14 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/lib).
[junit4] 2> 9118 T14 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 9119 T14 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 9119 T14 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 9120 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 9120 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 9120 T14 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 9121 T14 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 9121 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 9122 T14 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 9122 T14 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 9123 T14 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 9131 T14 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 9133 T14 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 9134 T14 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 9134 T14 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 9135 T14 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53803/solr
[junit4] 2> 9135 T14 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 9136 T14 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 9147 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 9161 T71 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6aceca75 name:ZooKeeperConnection Watcher:127.0.0.1:53803 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 9162 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 9163 T14 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 9183 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 9186 T74 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4a1e214f name:ZooKeeperConnection Watcher:127.0.0.1:53803/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 9187 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 9195 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 10199 T14 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:44469_
[junit4] 2> 10201 T14 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:44469_
[junit4] 2> 10204 T14 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 10208 T14 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores
[junit4] 2> 10209 T14 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores/collection1/
[junit4] 2> 10209 T14 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 10212 T75 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 10212 T75 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 10213 T46 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 10213 T75 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 10214 T47 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:44469",
[junit4] 2> "node_name":"127.0.0.1:44469_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 10215 T47 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:44469",
[junit4] 2> "node_name":"127.0.0.1:44469_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 10216 T47 oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 10216 T47 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2
[junit4] 2> 10320 T46 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 10320 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 10320 T57 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 11214 T75 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 11214 T75 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 11215 T75 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 11215 T75 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 11216 T75 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 11217 T75 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores/collection1/'
[junit4] 2> 11231 T75 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 11233 T75 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 11251 T75 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 11260 T75 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 11280 T75 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 11282 T75 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 11328 T75 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 11601 T75 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 11610 T75 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 11612 T75 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 11650 T75 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 11658 T75 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 11671 T75 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 11673 T75 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 11673 T75 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 11673 T75 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 11674 T75 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 11675 T75 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 11675 T75 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 11676 T75 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 11676 T75 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores/collection1/data/
[junit4] 2> 11676 T75 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@737d2a83
[junit4] 2> 11679 T75 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores/collection1/data
[junit4] 2> 11679 T75 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores/collection1/data/index/
[junit4] 2> 11680 T75 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 11680 T75 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-1-001/cores/collection1/data/index
[junit4] 2> 11681 T75 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=96.6611328125, floorSegmentMB=1.4775390625, forceMergeDeletesPctAllowed=28.852509199252363, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20338264693827954
[junit4] 2> 11682 T75 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6af5c8ee lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6091f895),segFN=segments_1,generation=1}
[junit4] 2> 11682 T75 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 11692 T75 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 11693 T75 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 11693 T75 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 11693 T75 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 11694 T75 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 11694 T75 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 11695 T75 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 11695 T75 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 11696 T75 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 11697 T75 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 11699 T75 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 11700 T75 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 11701 T75 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 11702 T75 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 11704 T75 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 11709 T75 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 11711 T75 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 11712 T75 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 11714 T75 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 11715 T75 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 11716 T75 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 11717 T75 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 11721 T75 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 11723 T75 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 11724 T75 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 11730 T75 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 11731 T75 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 11737 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 11744 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 11747 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 11752 T75 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 11754 T75 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 11761 T75 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 11762 T75 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 11775 T75 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 11788 T75 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=13, maxMergedSegmentMB=46.650390625, floorSegmentMB=1.572265625, forceMergeDeletesPctAllowed=21.43306147359764, segmentsPerTier=29.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.31335281901899237
[junit4] 2> 11789 T75 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6af5c8ee lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6091f895),segFN=segments_1,generation=1}
[junit4] 2> 11790 T75 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 11795 T75 oass.SolrIndexSearcher.<init> Opening Searcher@333b418c[collection1] main
[junit4] 2> 11796 T75 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 11797 T75 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 11798 T75 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1
[junit4] 2> 11799 T75 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 11799 T75 oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 11799 T75 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 11800 T75 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json
[junit4] 2> 11801 T75 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 11801 T75 oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 11801 T75 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 11804 T76 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@333b418c[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 11805 T75 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 11816 T14 oass.SolrDispatchFilter.init user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4] 2> 11816 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 11817 T79 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:44469 collection:collection1 shard:shard2
[junit4] 2> 11820 T79 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 11845 T79 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 11848 T46 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 11850 T79 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 11851 T79 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> ASYNC NEW_CORE C1 name=collection1 org.apache.solr.core.SolrCore@60ce8f11 url=https://127.0.0.1:44469/collection1 node=127.0.0.1:44469_ C1_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:44469, node_name=127.0.0.1:44469_, state=down}
[junit4] 2> 11851 T79 C1 P44469 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:44469/collection1/
[junit4] 2> 11851 T47 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 11852 T79 C1 P44469 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 11853 T79 C1 P44469 oasc.SyncStrategy.syncToMe https://127.0.0.1:44469/collection1/ has no replicas
[junit4] 2> 11853 T79 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:44469/collection1/ shard2
[junit4] 2> 11854 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 11854 T57 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 11854 T46 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 11866 T79 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 11879 T46 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 11881 T47 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "base_url":"https://127.0.0.1:44469",
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 6
[junit4] 2> 11989 T57 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 11989 T46 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 11989 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 12031 T79 oasc.ZkController.register We are https://127.0.0.1:44469/collection1/ and leader is https://127.0.0.1:44469/collection1/
[junit4] 2> 12032 T79 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:44469
[junit4] 2> 12032 T79 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 12032 T79 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1
[junit4] 2> 12032 T79 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 12038 T46 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 12040 T47 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:44469",
[junit4] 2> "node_name":"127.0.0.1:44469_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 7
[junit4] 2> 12042 T47 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:44469",
[junit4] 2> "node_name":"127.0.0.1:44469_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 12167 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 12167 T46 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 12168 T57 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 12225 T14 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores/collection1
[junit4] 2> 12226 T14 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2
[junit4] 2> 12231 T14 oejs.Server.doStart jetty-9.2.6.v20141205
[junit4] 2> 12245 T14 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@7bf2ed{/,null,AVAILABLE}
[junit4] 2> 12272 T14 oejs.AbstractConnector.doStart Started ServerConnector@57c62df9{SSL-http/1.1}{127.0.0.1:43827}
[junit4] 2> 12274 T14 oejs.Server.doStart Started @13799ms
[junit4] 2> 12278 T14 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44
[junit4] 2> 12278 T14 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 12279 T14 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001
[junit4] 2> 12279 T14 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/'
[junit4] 2> 12313 T14 oasc.SolrXmlConfig.fromFile Loading container configuration from /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/solr.xml
[junit4] 2> 12323 T14 oasc.CorePropertiesLocator.<init> Config-defined core root directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores
[junit4] 2> 12324 T14 oasc.CoreContainer.<init> New CoreContainer 1572400079
[junit4] 2> 12325 T14 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/]
[junit4] 2> 12325 T14 oasc.CoreContainer.load loading shared library: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/lib
[junit4] 2> 12326 T14 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/../../../../../../../../../../mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/lib).
[junit4] 2> 12339 T14 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
[junit4] 2> 12340 T14 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to:
[junit4] 2> 12340 T14 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 12341 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 12341 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000
[junit4] 2> 12342 T14 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 12342 T14 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 12342 T14 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 12343 T14 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 12343 T14 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 12344 T14 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false
[junit4] 2> 12346 T14 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 12348 T14 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 12349 T14 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 12349 T14 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 12350 T14 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53803/solr
[junit4] 2> 12350 T14 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 12350 T14 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider
[junit4] 2> 12359 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 12362 T93 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@23efd8fd name:ZooKeeperConnection Watcher:127.0.0.1:53803 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 12363 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 12364 T14 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider
[junit4] 2> 12391 T14 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 12394 T96 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@437edd74 name:ZooKeeperConnection Watcher:127.0.0.1:53803/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 12394 T14 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 12403 T14 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 13408 T14 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:43827_
[junit4] 2> 13410 T14 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:43827_
[junit4] 2> 13413 T14 oasc.Overseer.close Overseer (id=null) closing
[junit4] 2> 13416 T14 oasc.CorePropertiesLocator.discover Looking for core definitions underneath /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores
[junit4] 2> 13417 T14 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores/collection1/
[junit4] 2> 13418 T14 oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 13419 T97 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1
[junit4] 2> 13420 T97 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 13420 T46 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected
[junit4] 2> 13421 T97 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 13422 T47 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = {
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:43827",
[junit4] 2> "node_name":"127.0.0.1:43827_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 8
[junit4] 2> 13423 T47 oasco.ReplicaMutator.updateState Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"https://127.0.0.1:43827",
[junit4] 2> "node_name":"127.0.0.1:43827_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 13423 T47 oasco.ReplicaMutator.updateState Collection already exists with numShards=2
[junit4] 2> 13423 T47 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1
[junit4] 2> 13527 T74 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 13527 T46 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 13527 T57 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 13527 T96 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 14421 T97 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 14421 T97 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 14422 T97 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 14423 T97 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 14424 T97 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 14425 T97 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores/collection1/'
[junit4] 2> 14456 T97 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1
[junit4] 2> 14458 T97 oasc.Config.<init> loaded config solrconfig.xml with version 0
[junit4] 2> 14479 T97 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 14488 T97 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0
[junit4] 2> 14542 T97 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 14543 T97 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 14582 T97 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 14767 T97 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 14811 T97 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 14815 T97 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 14897 T97 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 14907 T97 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 14913 T97 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 14914 T97 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 14915 T97 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 14915 T97 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 14916 T97 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 14916 T97 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 14917 T97 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 14918 T97 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 14920 T97 oasc.SolrCore.<init> [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores/collection1/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores/collection1/data/
[junit4] 2> 14920 T97 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@737d2a83
[junit4] 2> 14927 T97 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores/collection1/data
[junit4] 2> 14927 T97 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores/collection1/data/index/
[junit4] 2> 14927 T97 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores/collection1/data/index' doesn't exist. Creating new index...
[junit4] 2> 14932 T97 oasc.CachingDirectoryFactory.get return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-2-001/cores/collection1/data/index
[junit4] 2> 14934 T97 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=34, maxMergeAtOnceExplicit=33, maxMergedSegmentMB=96.6611328125, floorSegmentMB=1.4775390625, forceMergeDeletesPctAllowed=28.852509199252363, segmentsPerTier=34.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.20338264693827954
[junit4] 2> 14936 T97 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@56d8f89e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13b48d58),segFN=segments_1,generation=1}
[junit4] 2> 14937 T97 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 14953 T97 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 14954 T97 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 14955 T97 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 14955 T97 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 14955 T97 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 14956 T97 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 14956 T97 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 14957 T97 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 14957 T97 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 14958 T97 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 14959 T97 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 14960 T97 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 14961 T97 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler
[junit4] 2> 14962 T97 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler
[junit4] 2> 14964 T97 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler
[junit4] 2> 14980 T97 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler
[junit4] 2> 14981 T97 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler
[junit4] 2> 14981 T97 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler
[junit4] 2> 14982 T97 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler
[junit4] 2> 14983 T97 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler
[junit4] 2> 14984 T97 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler
[junit4] 2> 14985 T97 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler
[junit4] 2> 14985 T97 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler
[junit4] 2> 14987 T97 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler
[junit4] 2> 14988 T97 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
[junit4] 2> 14991 T97 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler
[junit4] 2> 14993 T97 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 14995 T97 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 14997 T97 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 14998 T97 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 15007 T97 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 15008 T97 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 15009 T97 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog
[junit4] 2> 15010 T97 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 15010 T97 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 15018 T97 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.inde
[...truncated too long message...]
to close /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-4-001/cores/acollectionafterbaddelete_shard1_replica2/data [CachedDir<<refCount=0;path=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-4-001/cores/acollectionafterbaddelete_shard1_replica2/data;done=false>>]
[junit4] 2> 128643 T580 oasc.CachingDirectoryFactory.close Closing directory: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001/shard-4-001/cores/acollectionafterbaddelete_shard1_replica2/data
[junit4] 2> 128643 T580 oascc.SolrZkClient.makePath makePath: /collections/acollectionafterbaddelete/leaders/shard1
[junit4] 2> 128644 T580 oasc.SolrException.log ERROR There was a problem trying to register as the leader:org.apache.solr.common.SolrException: Could not register as the leader because creating the ephemeral registration node in ZooKeeper failed
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:147)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:323)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:198)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:159)
[junit4] 2> at org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
[junit4] 2> at org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:390)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:262)
[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 java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2> Caused by: org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode = Session expired for /collections
[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.exists(ZooKeeper.java:1045)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:292)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient$4.execute(SolrZkClient.java:289)
[junit4] 2> at org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.exists(SolrZkClient.java:289)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:484)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:453)
[junit4] 2> at org.apache.solr.common.cloud.SolrZkClient.makePath(SolrZkClient.java:440)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase$1.execute(ElectionContext.java:139)
[junit4] 2> at org.apache.solr.common.util.RetryUtil.retryOnThrowable(RetryUtil.java:31)
[junit4] 2> at org.apache.solr.cloud.ShardLeaderElectionContextBase.runLeaderProcess(ElectionContext.java:135)
[junit4] 2> ... 11 more
[junit4] 2>
[junit4] 2> 128648 T14 oejs.AbstractConnector.doStop Stopped ServerConnector@3acf69a3{SSL-http/1.1}{127.0.0.1:0}
[junit4] 2> 128648 T14 oejsh.ContextHandler.doStop Stopped o.e.j.s.ServletContextHandler@75bd2be2{/,null,UNAVAILABLE}
[junit4] 2> 128650 T14 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:53803 53803
[junit4] 2> 129228 T293 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:53803 53803
[junit4] 2> 129229 T293 oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 6 /solr/aliases.json
[junit4] 2> 6 /solr/clusterstate.json
[junit4] 2> 2 /solr/collections/acollectionafterbaddelete/state.json
[junit4] 2> 2 /solr/collections/halfdeletedcollection/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent data watches above limit:
[junit4] 2>
[junit4] 2> 2 /solr/overseer_elect/election/93398250006577156-127.0.0.1:60811_-n_0000000000
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=CollectionsAPIDistributedZkTest -Dtests.method=test -Dtests.seed=A9A51ACA5447870 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=es_CO -Dtests.timezone=Etc/Greenwich -Dtests.asserts=true -Dtests.file.encoding=US-ASCII
[junit4] ERROR 128s J1 | CollectionsAPIDistributedZkTest.test <<<
[junit4] > Throwable #1: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:60811, https://127.0.0.1:50092, https://127.0.0.1:46672]
[junit4] > at __randomizedtesting.SeedInfo.seed([A9A51ACA5447870:82CE6E760BB81588]:0)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:349)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1043)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:809)
[junit4] > at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:752)
[junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.addReplicaTest(CollectionsAPIDistributedZkTest.java:1125)
[junit4] > at org.apache.solr.cloud.CollectionsAPIDistributedZkTest.test(CollectionsAPIDistributedZkTest.java:202)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:945)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:920)
[junit4] > at java.lang.Thread.run(Thread.java:745)
[junit4] > Caused by: org.apache.solr.client.solrj.impl.HttpSolrClient$RemoteSolrException: Error from server at https://127.0.0.1:60811: ADDREPLICA failed to create replica
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:558)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214)
[junit4] > at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370)
[junit4] > at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325)
[junit4] > ... 47 more
[junit4] 2> 129259 T14 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/temp/solr.cloud.CollectionsAPIDistributedZkTest A9A51ACA5447870-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50): {other_tl1=Lucene50(blocksize=128), _version_=Lucene50(blocksize=128), multiDefault=PostingsFormat(name=LuceneVarGapDocFreqInterval), a_t=Lucene50(blocksize=128), intDefault=Lucene50(blocksize=128), a_i1=Lucene50(blocksize=128), id=BlockTreeOrds(blocksize=128), text=PostingsFormat(name=Asserting), range_facet_l=Lucene50(blocksize=128), timestamp=Lucene50(blocksize=128)}, docValues:{timestamp=DocValuesFormat(name=Lucene50)}, sim=DefaultSimilarity, locale=es_CO, timezone=Etc/Greenwich
[junit4] 2> NOTE: Linux 3.13.0-45-generic amd64/Oracle Corporation 1.8.0_31 (64-bit)/cpus=8,threads=1,free=238081304,total=482344960
[junit4] 2> NOTE: All tests run in this JVM: [CollectionsAPIDistributedZkTest]
[junit4] Completed on J1 in 129.51s, 1 test, 1 error <<< FAILURES!
[...truncated 1438 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:519: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:467: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:61: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/extra-targets.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:191: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:510: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1351: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:958: There were test failures: 468 suites, 1868 tests, 1 error, 48 ignored (21 assumptions)
Total time: 71 minutes 14 seconds
Build step 'Invoke Ant' marked build as failure
[description-setter] Description set: Java: 64bit/jdk1.8.0_31 -XX:+UseCompressedOops -XX:+UseG1GC
Archiving artifacts
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any