Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-MacOSX/1920/ Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.cloud.TestReplicaProperties.testDistribSearch Error Message: No live SolrServers available to handle this request:[https://127.0.0.1:53632/kefru, https://127.0.0.1:53625/kefru, https://127.0.0.1:53628/kefru, https://127.0.0.1:53622/kefru, https://127.0.0.1:53618/kefru] Stack Trace: org.apache.solr.client.solrj.SolrServerException: No live SolrServers available to handle this request:[https://127.0.0.1:53632/kefru, https://127.0.0.1:53625/kefru, https://127.0.0.1:53628/kefru, https://127.0.0.1:53622/kefru, https://127.0.0.1:53618/kefru] at __randomizedtesting.SeedInfo.seed([DBBF5F5F6F6B69E0:5A59D147183409DC]:0) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:332) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:1015) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:793) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:736) at org.apache.solr.cloud.ReplicaPropertiesBase.doPropertyAction(ReplicaPropertiesBase.java:51) at org.apache.solr.cloud.TestReplicaProperties.clusterAssignPropertyTest(TestReplicaProperties.java:196) at org.apache.solr.cloud.TestReplicaProperties.doTest(TestReplicaProperties.java:80) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868) at sun.reflect.GeneratedMethodAccessor40.invoke(Unknown Source) 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 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:53628/kefru: java.lang.NullPointerException at org.apache.solr.common.cloud.ZkStateReader.fromJSON(ZkStateReader.java:140) at org.apache.solr.common.cloud.ZkNodeProps.load(ZkNodeProps.java:92) at org.apache.solr.cloud.DistributedQueue.containsTaskWithRequestId(DistributedQueue.java:125) at org.apache.solr.handler.admin.CollectionsHandler.overseerCollectionQueueContains(CollectionsHandler.java:688) at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:713) at org.apache.solr.handler.admin.CollectionsHandler.handleResponse(CollectionsHandler.java:693) at org.apache.solr.handler.admin.CollectionsHandler.rejoinElection(CollectionsHandler.java:488) at org.apache.solr.handler.admin.CollectionsHandler.insurePreferredIsLeader(CollectionsHandler.java:403) at org.apache.solr.handler.admin.CollectionsHandler.handleBalanceLeaders(CollectionsHandler.java:310) at org.apache.solr.handler.admin.CollectionsHandler.handleRequestBody(CollectionsHandler.java:276) at org.apache.solr.handler.RequestHandlerBase.handleRequest(RequestHandlerBase.java:144) at org.apache.solr.servlet.SolrDispatchFilter.handleAdminRequest(SolrDispatchFilter.java:740) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:266) at org.apache.solr.servlet.SolrDispatchFilter.doFilter(SolrDispatchFilter.java:204) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.apache.solr.client.solrj.embedded.JettySolrRunner$DebugFilter.doFilter(JettySolrRunner.java:138) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlets.UserAgentFilter.doFilter(UserAgentFilter.java:83) at org.eclipse.jetty.servlets.GzipFilter.doFilter(GzipFilter.java:364) at org.eclipse.jetty.servlet.ServletHandler$CachedChain.doFilter(ServletHandler.java:1652) at org.eclipse.jetty.servlet.ServletHandler.doHandle(ServletHandler.java:585) at org.eclipse.jetty.server.session.SessionHandler.doHandle(SessionHandler.java:221) at org.eclipse.jetty.server.handler.ContextHandler.doHandle(ContextHandler.java:1125) at org.eclipse.jetty.servlet.ServletHandler.doScope(ServletHandler.java:515) at org.eclipse.jetty.server.session.SessionHandler.doScope(SessionHandler.java:185) at org.eclipse.jetty.server.handler.ContextHandler.doScope(ContextHandler.java:1059) at org.eclipse.jetty.server.handler.ScopedHandler.handle(ScopedHandler.java:141) at org.eclipse.jetty.server.handler.HandlerWrapper.handle(HandlerWrapper.java:97) at org.eclipse.jetty.server.Server.handle(Server.java:497) at org.eclipse.jetty.server.HttpChannel.handle(HttpChannel.java:313) at org.eclipse.jetty.server.HttpConnection.onFillable(HttpConnection.java:248) at org.eclipse.jetty.io.AbstractConnection$2.run(AbstractConnection.java:540) at org.eclipse.jetty.util.thread.QueuedThreadPool.runJob(QueuedThreadPool.java:626) at org.eclipse.jetty.util.thread.QueuedThreadPool$3.run(QueuedThreadPool.java:546) at java.lang.Thread.run(Thread.java:745) 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:353) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:312) ... 46 more Build Log: [...truncated 9906 lines...] [junit4] Suite: org.apache.solr.cloud.TestReplicaProperties [junit4] 2> Creating dataDir: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/init-core-data-001 [junit4] 2> 4071727 T18038 oas.SolrTestCaseJ4.startTrackingSearchers WARN startTrackingSearchers: numOpens=6 numCloses=6 [junit4] 2> 4071728 T18038 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl (true) and clientAuth (false) [junit4] 2> 4071729 T18038 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /kefru/ [junit4] 2> 4071735 T18038 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 4071737 T18038 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4071738 T18039 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 4071839 T18038 oasc.ZkTestServer.run start zk server on port:53614 [junit4] 2> 4071839 T18038 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4071842 T18038 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4071848 T18046 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f67330d name:ZooKeeperConnection Watcher:127.0.0.1:53614 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4071850 T18038 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4071851 T18038 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4071851 T18038 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 4071861 T18038 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4071862 T18038 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4071866 T18049 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b397ff8 name:ZooKeeperConnection Watcher:127.0.0.1:53614/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4071867 T18038 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4071868 T18038 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4071869 T18038 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 4071879 T18038 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 4071887 T18038 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 4071894 T18038 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 4071902 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 4071904 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4071915 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/schema15.xml to /configs/conf1/schema.xml [junit4] 2> 4071916 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 4071924 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4071925 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4071933 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 4071936 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 4071944 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 4071945 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 4071954 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml [junit4] 2> 4071955 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 4071964 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 4071965 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4071975 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 4071975 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4071984 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4071985 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4071994 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 4071995 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4072002 T18038 oasc.AbstractZkTestCase.putConfig put /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/core/src/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 4072003 T18038 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 4072015 T18038 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4072016 T18038 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4072020 T18052 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@22e1eb3c name:ZooKeeperConnection Watcher:127.0.0.1:53614/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4072020 T18038 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4072021 T18038 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4074075 T18038 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 4074084 T18038 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@7a6bf4ad{/kefru,null,AVAILABLE} [junit4] 2> 4074105 T18038 oejs.AbstractConnector.doStart Started ServerConnector@13e4c8d5{SSL-http/1.1}{127.0.0.1:53618} [junit4] 2> 4074105 T18038 oejs.Server.doStart Started @4078356ms [junit4] 2> 4074108 T18038 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 4074109 T18038 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4074109 T18038 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-002 [junit4] 2> 4074110 T18038 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-002/' [junit4] 2> 4074158 T18038 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-002/solr.xml [junit4] 2> 4074175 T18038 oasc.CoreContainer.<init> New CoreContainer 1229998021 [junit4] 2> 4074176 T18038 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-002/] [junit4] 2> 4074176 T18038 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4074177 T18038 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4074177 T18038 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4074178 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4074178 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4074178 T18038 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4074179 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4074179 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4074180 T18038 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4074180 T18038 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4074182 T18038 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 4074186 T18038 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4074186 T18038 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4074186 T18038 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4074187 T18038 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53614/solr [junit4] 2> 4074187 T18038 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4074188 T18038 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4074189 T18038 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4074193 T18066 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@34129ee4 name:ZooKeeperConnection Watcher:127.0.0.1:53614 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4074193 T18038 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4074194 T18038 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4074200 T18038 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4074202 T18069 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@724a88ff name:ZooKeeperConnection Watcher:127.0.0.1:53614/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4074202 T18038 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4074205 T18038 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 4074214 T18038 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 4074220 T18038 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 4074227 T18038 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 4074238 T18038 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 4074250 T18038 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 4074255 T18038 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53618_kefru [junit4] 2> 4074258 T18038 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53618_kefru [junit4] 2> 4074268 T18038 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 4074274 T18038 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 4074280 T18038 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4074285 T18038 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53618_kefru [junit4] 2> 4074286 T18038 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 4074292 T18038 oasc.Overseer.start Overseer (id=93126790501171204-127.0.0.1:53618_kefru-n_0000000000) starting [junit4] 2> 4074301 T18038 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 4074329 T18038 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4074331 T18071 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 4074333 T18038 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 4074339 T18038 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 4074344 T18038 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4074356 T18070 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 4074368 T18073 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 4074368 T18073 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4074370 T18069 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4074370 T18073 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4074381 T18070 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"https://127.0.0.1:53618/kefru", [junit4] 2> "node_name":"127.0.0.1:53618_kefru", [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> 4074384 T18070 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:53618/kefru", [junit4] 2> "node_name":"127.0.0.1:53618_kefru", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4074385 T18070 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 4074385 T18070 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 4074389 T18069 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> 4075373 T18073 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4075374 T18073 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 4075376 T18073 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4075376 T18073 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4075379 T18073 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4075379 T18073 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-002/collection1/' [junit4] 2> 4075381 T18073 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties%20DBBF5F5F6F6B69E0-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 4075382 T18073 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties%20DBBF5F5F6F6B69E0-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 4075383 T18073 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties%20DBBF5F5F6F6B69E0-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 4075448 T18073 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4075465 T18073 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4075481 T18073 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4075485 T18073 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4075499 T18073 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4075502 T18073 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4075509 T18073 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4075680 T18073 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4075681 T18073 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4075683 T18073 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4075688 T18073 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4075766 T18073 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4075766 T18073 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4075767 T18073 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-002/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/control/data/ [junit4] 2> 4075768 T18073 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f450d7 [junit4] 2> 4075768 T18073 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/control/data [junit4] 2> 4075769 T18073 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/control/data/index/ [junit4] 2> 4075769 T18073 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/control/data/index' doesn't exist. Creating new index... [junit4] 2> 4075770 T18073 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/control/data/index [junit4] 2> 4075772 T18073 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=26, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5343918058986418] [junit4] 2> 4075773 T18073 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@648593c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ee949e1; maxCacheMB=0.83203125 maxMergeSizeMB=0.3271484375)),segFN=segments_1,generation=1} [junit4] 2> 4075773 T18073 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4075779 T18073 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4075780 T18073 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4075780 T18073 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4075781 T18073 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4075782 T18073 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4075783 T18073 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4075784 T18073 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4075785 T18073 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4075786 T18073 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4075786 T18073 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4075787 T18073 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4075787 T18073 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4075788 T18073 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4075788 T18073 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4075789 T18073 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4075789 T18073 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4075790 T18073 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4075790 T18073 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4075794 T18073 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4075795 T18073 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4075796 T18073 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4075796 T18073 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4075797 T18073 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4075797 T18073 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4075799 T18073 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4075799 T18073 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4075800 T18073 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4075809 T18073 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4075811 T18073 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4075814 T18073 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4075818 T18073 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4075819 T18073 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4075824 T18073 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4075824 T18073 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4075825 T18073 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=25.154296875, floorSegmentMB=1.1044921875, forceMergeDeletesPctAllowed=21.65621990141284, segmentsPerTier=8.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2091450780463255 [junit4] 2> 4075825 T18073 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@648593c lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7ee949e1; maxCacheMB=0.83203125 maxMergeSizeMB=0.3271484375)),segFN=segments_1,generation=1} [junit4] 2> 4075826 T18073 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4075826 T18073 oass.SolrIndexSearcher.<init> Opening Searcher@1ccf69ec[collection1] main [junit4] 2> 4075826 T18073 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4075831 T18073 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4075831 T18073 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4075832 T18073 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4075833 T18073 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4075833 T18073 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4075835 T18073 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4075835 T18073 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4075836 T18073 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4075836 T18073 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4075837 T18074 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ccf69ec[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4075837 T18073 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4075843 T18077 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:53618/kefru collection:control_collection shard:shard1 [junit4] 2> 4075843 T18038 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 4075844 T18038 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4075846 T18077 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 4075853 T18038 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4075856 T18038 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4075862 T18080 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@250a67cb name:ZooKeeperConnection Watcher:127.0.0.1:53614/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4075863 T18038 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4075864 T18038 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4075868 T18038 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4075869 T18077 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 4075875 T18069 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4075877 T18077 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4075878 T18077 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2352 name=collection1 org.apache.solr.core.SolrCore@2180a05d url=https://127.0.0.1:53618/kefru/collection1 node=127.0.0.1:53618_kefru C2352_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=https://127.0.0.1:53618/kefru, node_name=127.0.0.1:53618_kefru, state=down} [junit4] 2> 4075878 T18077 C2352 P53618 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:53618/kefru/collection1/ [junit4] 2> 4075878 T18077 C2352 P53618 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4075879 T18077 C2352 P53618 oasc.SyncStrategy.syncToMe https://127.0.0.1:53618/kefru/collection1/ has no replicas [junit4] 2> 4075879 T18077 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:53618/kefru/collection1/ shard1 [junit4] 2> 4075880 T18077 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4075881 T18070 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> 4075881 T18038 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4075902 T18069 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4075914 T18070 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:53618/kefru", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 4075920 T18080 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> 4075921 T18069 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> 4076067 T18077 oasc.ZkController.register We are https://127.0.0.1:53618/kefru/collection1/ and leader is https://127.0.0.1:53618/kefru/collection1/ [junit4] 2> 4076067 T18077 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:53618/kefru [junit4] 2> 4076067 T18077 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4076068 T18077 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 4076068 T18077 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4076072 T18069 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4076088 T18070 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:53618/kefru", [junit4] 2> "node_name":"127.0.0.1:53618_kefru", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 4076093 T18070 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:53618/kefru", [junit4] 2> "node_name":"127.0.0.1:53618_kefru", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4076207 T18069 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> 4076208 T18080 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> 4078332 T18038 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 4078334 T18038 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 4078339 T18038 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@e15fd7c{/kefru,null,AVAILABLE} [junit4] 2> 4078347 T18038 oejs.AbstractConnector.doStart Started ServerConnector@17b7329e{SSL-http/1.1}{127.0.0.1:53622} [junit4] 2> 4078348 T18038 oejs.Server.doStart Started @4082598ms [junit4] 2> 4078352 T18038 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 4078353 T18038 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4078355 T18038 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-003 [junit4] 2> 4078355 T18038 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-003/' [junit4] 2> 4078402 T18038 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-003/solr.xml [junit4] 2> 4078421 T18038 oasc.CoreContainer.<init> New CoreContainer 415616493 [junit4] 2> 4078422 T18038 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-003/] [junit4] 2> 4078423 T18038 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4078424 T18038 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4078425 T18038 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4078425 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4078426 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4078426 T18038 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4078427 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4078428 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4078428 T18038 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4078429 T18038 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4078435 T18038 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 4078438 T18038 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4078439 T18038 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4078439 T18038 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4078441 T18038 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53614/solr [junit4] 2> 4078441 T18038 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4078442 T18038 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4078444 T18038 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4078449 T18094 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3d33c521 name:ZooKeeperConnection Watcher:127.0.0.1:53614 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4078450 T18038 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4078452 T18038 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4078459 T18038 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4078462 T18097 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6d1bf237 name:ZooKeeperConnection Watcher:127.0.0.1:53614/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4078463 T18038 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4078491 T18038 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4079506 T18038 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53622_kefru [junit4] 2> 4079508 T18038 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53622_kefru [junit4] 2> 4079519 T18038 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4079540 T18098 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4079540 T18098 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4079543 T18069 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4079543 T18098 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4079548 T18070 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:53622/kefru", [junit4] 2> "node_name":"127.0.0.1:53622_kefru", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 4079552 T18070 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:53622/kefru", [junit4] 2> "node_name":"127.0.0.1:53622_kefru", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4079552 T18070 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 4079553 T18070 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 4079556 T18080 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> 4079556 T18097 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> 4079556 T18069 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> 4080545 T18098 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4080546 T18098 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4080549 T18098 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4080549 T18098 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4080554 T18098 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4080555 T18098 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-003/collection1/' [junit4] 2> 4080557 T18098 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties%20DBBF5F5F6F6B69E0-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 4080557 T18098 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties%20DBBF5F5F6F6B69E0-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 4080558 T18098 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties%20DBBF5F5F6F6B69E0-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 4080621 T18098 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4080627 T18098 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4080643 T18098 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4080650 T18098 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4080671 T18098 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4080677 T18098 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4080682 T18098 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4080854 T18098 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4080857 T18098 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4080859 T18098 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4080867 T18098 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4080935 T18098 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4080936 T18098 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4080937 T18098 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-003/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/jetty1/ [junit4] 2> 4080937 T18098 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f450d7 [junit4] 2> 4080939 T18098 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/jetty1 [junit4] 2> 4080939 T18098 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/jetty1/index/ [junit4] 2> 4080940 T18098 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/jetty1/index' doesn't exist. Creating new index... [junit4] 2> 4080940 T18098 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/jetty1/index [junit4] 2> 4080941 T18098 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=26, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5343918058986418] [junit4] 2> 4080942 T18098 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@4873a522 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c7ff554; maxCacheMB=0.83203125 maxMergeSizeMB=0.3271484375)),segFN=segments_1,generation=1} [junit4] 2> 4080942 T18098 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4080949 T18098 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4080950 T18098 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4080950 T18098 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4080950 T18098 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4080951 T18098 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4080951 T18098 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4080951 T18098 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4080952 T18098 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4080952 T18098 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4080953 T18098 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4080954 T18098 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4080955 T18098 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4080956 T18098 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4080957 T18098 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4080957 T18098 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4080960 T18098 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4080962 T18098 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4080963 T18098 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4080965 T18098 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4080965 T18098 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4080966 T18098 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4080967 T18098 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4080968 T18098 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4080969 T18098 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4080969 T18098 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4080970 T18098 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4080971 T18098 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4080986 T18098 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4080989 T18098 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4080991 T18098 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4080993 T18098 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4080993 T18098 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4080998 T18098 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4080998 T18098 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4080999 T18098 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=12, maxMergedSegmentMB=25.154296875, floorSegmentMB=1.1044921875, forceMergeDeletesPctAllowed=21.65621990141284, segmentsPerTier=8.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.2091450780463255 [junit4] 2> 4081000 T18098 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@4873a522 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1c7ff554; maxCacheMB=0.83203125 maxMergeSizeMB=0.3271484375)),segFN=segments_1,generation=1} [junit4] 2> 4081001 T18098 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4081001 T18098 oass.SolrIndexSearcher.<init> Opening Searcher@416f5b17[collection1] main [junit4] 2> 4081002 T18098 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4081006 T18098 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4081006 T18098 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4081009 T18098 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4081009 T18098 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4081009 T18098 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4081011 T18098 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4081011 T18098 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4081012 T18098 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4081012 T18098 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4081013 T18099 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@416f5b17[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4081014 T18098 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4081016 T18102 oasc.ZkController.register Register replica - core:collection1 address:https://127.0.0.1:53622/kefru collection:collection1 shard:shard2 [junit4] 2> 4081017 T18038 oass.SolrDispatchFilter.init user.dir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0 [junit4] 2> 4081018 T18038 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4081018 T18102 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 4081033 T18102 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 4081050 T18069 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4081051 T18102 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4081052 T18102 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C2353 name=collection1 org.apache.solr.core.SolrCore@710a928f url=https://127.0.0.1:53622/kefru/collection1 node=127.0.0.1:53622_kefru C2353_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=https://127.0.0.1:53622/kefru, node_name=127.0.0.1:53622_kefru, state=down} [junit4] 2> 4081052 T18102 C2353 P53622 oasc.SyncStrategy.sync Sync replicas to https://127.0.0.1:53622/kefru/collection1/ [junit4] 2> 4081052 T18102 C2353 P53622 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4081053 T18102 C2353 P53622 oasc.SyncStrategy.syncToMe https://127.0.0.1:53622/kefru/collection1/ has no replicas [junit4] 2> 4081054 T18102 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: https://127.0.0.1:53622/kefru/collection1/ shard2 [junit4] 2> 4081054 T18102 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 4081066 T18070 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 4 [junit4] 2> 4081074 T18069 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> 4081075 T18097 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> 4081080 T18070 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:53622/kefru", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 4081080 T18080 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> 4081097 T18103 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4081101 T18097 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> 4081102 T18080 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> 4081127 T18102 oasc.ZkController.register We are https://127.0.0.1:53622/kefru/collection1/ and leader is https://127.0.0.1:53622/kefru/collection1/ [junit4] 2> 4081128 T18102 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=https://127.0.0.1:53622/kefru [junit4] 2> 4081129 T18102 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4081129 T18102 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 4081130 T18102 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4081134 T18069 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4081138 T18070 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:53622/kefru", [junit4] 2> "node_name":"127.0.0.1:53622_kefru", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 4081141 T18070 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:53622/kefru", [junit4] 2> "node_name":"127.0.0.1:53622_kefru", [junit4] 2> "numShards":"2", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4081253 T18069 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> 4081254 T18080 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> 4081257 T18097 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> 4083305 T18038 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 4083307 T18038 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 4083313 T18038 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@26c2b753{/kefru,null,AVAILABLE} [junit4] 2> 4083323 T18038 oejs.AbstractConnector.doStart Started ServerConnector@299cfdbc{SSL-http/1.1}{127.0.0.1:53625} [junit4] 2> 4083324 T18038 oejs.Server.doStart Started @4087574ms [junit4] 2> 4083328 T18038 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@4554617c [junit4] 2> 4083329 T18038 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4083329 T18038 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-004 [junit4] 2> 4083330 T18038 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-004/' [junit4] 2> 4083393 T18038 oasc.ConfigSolr.fromFile Loading container configuration from /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-004/solr.xml [junit4] 2> 4083414 T18038 oasc.CoreContainer.<init> New CoreContainer 254749637 [junit4] 2> 4083415 T18038 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-004/] [junit4] 2> 4083416 T18038 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4083416 T18038 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4083417 T18038 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4083418 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4083419 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4083419 T18038 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4083420 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4083421 T18038 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4083421 T18038 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4083422 T18038 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4083425 T18038 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 4083430 T18038 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4083430 T18038 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4083431 T18038 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4083431 T18038 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53614/solr [junit4] 2> 4083431 T18038 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4083432 T18038 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4083434 T18038 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4083443 T18117 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b2fb4fa name:ZooKeeperConnection Watcher:127.0.0.1:53614 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4083443 T18038 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4083444 T18038 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4083450 T18038 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4083454 T18120 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6c7eb35 name:ZooKeeperConnection Watcher:127.0.0.1:53614/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4083454 T18038 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4083483 T18038 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4084505 T18038 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53625_kefru [junit4] 2> 4084506 T18038 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53625_kefru [junit4] 2> 4084521 T18038 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4084535 T18121 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4084535 T18121 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4084537 T18121 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4084537 T18069 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4084541 T18070 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:53625/kefru", [junit4] 2> "node_name":"127.0.0.1:53625_kefru", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 7 [junit4] 2> 4084545 T18070 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:53625/kefru", [junit4] 2> "node_name":"127.0.0.1:53625_kefru", [junit4] 2> "numShards":"2", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4084545 T18070 oasco.ReplicaMutator.updateState Collection already exists with numShards=2 [junit4] 2> 4084545 T18070 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 4084549 T18120 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> 4084550 T18069 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> 4084550 T18097 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> 4084550 T18080 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> 4085538 T18121 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4085539 T18121 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4085540 T18121 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4085541 T18121 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4085544 T18121 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4085544 T18121 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-004/collection1/' [junit4] 2> 4085545 T18121 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties%20DBBF5F5F6F6B69E0-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 4085546 T18121 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties%20DBBF5F5F6F6B69E0-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 4085546 T18121 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties%20DBBF5F5F6F6B69E0-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 4085578 T18121 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4085583 T18121 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4085595 T18121 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4085600 T18121 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4085607 T18121 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4085614 T18121 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4085618 T18121 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4085758 T18121 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4085760 T18121 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4085762 T18121 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4085768 T18121 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4085816 T18121 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4085816 T18121 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4085816 T18121 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ../../../../../../../../../Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-004/collection1/, dataDir=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/jetty2/ [junit4] 2> 4085817 T18121 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@9f450d7 [junit4] 2> 4085817 T18121 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/jetty2 [junit4] 2> 4085817 T18121 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/jetty2/index/ [junit4] 2> 4085818 T18121 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '/Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/jetty2/index' doesn't exist. Creating new index... [junit4] 2> 4085818 T18121 oasc.CachingDirectoryFactory.get return new directory for /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build/solr-core/test/J0/temp/solr.cloud.TestReplicaProperties DBBF5F5F6F6B69E0-001/tempDir-001/jetty2/index [junit4] 2> 4085819 T18121 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=26, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.5343918058986418] [junit4] 2> 4085819 T18121 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(NRTCachingDirectory(RAMDirectory@346dbd82 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@727c8173; maxCacheMB=0.83203125 maxMergeSizeMB=0.3271484375)),segFN=segments_1,generation=1} [junit4] 2> 4085820 T18121 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4085826 T18121 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4085827 T18121 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4085827 T18121 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4085827 T18121 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4085827 T18121 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4085828 T18121 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4085828 T18121 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4085828 T18121 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4085829 T18121 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4085829 T18121 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4085830 T18121 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4085830 T18121 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4085831 T18121 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4085831 T18121 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4085832 T18121 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4085833 T18121 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4085834 T18121 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4085835 T18121 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4085836 T18121 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4085838 T18121 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4085839 T18121 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4085840 T18121 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4085841 T18121 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4085842 T18121 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4085843 T18121 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4085843 T18121 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4085844 T18121 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4085853 T18121 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4085857 T18121 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4085858 T18121 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4085861 T18121 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4085862 T18121 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4085866 T18121 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4085866 T18121 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4085867 T18121 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=32, maxMergeAtOnceExplicit=12 [...truncated too long message...] All tests run in this JVM: [SimpleCollectionCreateDeleteTest, SolrXmlInZkTest, TestSolrConfigHandlerCloud, TestSuggestSpellingConverter, DistribCursorPagingTest, TestReplicationHandler, ResourceLoaderTest, TestSolrDeletionPolicy1, TestManagedResourceStorage, TestJoin, TestAtomicUpdateErrorCases, SolrCoreCheckLockOnStartupTest, TestInitParams, TestChildDocTransformer, DocExpirationUpdateProcessorFactoryTest, TestNRTOpen, PingRequestHandlerTest, DocValuesMultiTest, TestBinaryField, TestSolr4Spatial2, DistributedExpandComponentTest, HdfsUnloadDistributedZkTest, TestFiltering, TestConfig, TestSolrXmlPersistor, DateFieldTest, TestSolrConfigHandlerConcurrent, DirectSolrConnectionTest, AutoCommitTest, TestValueSourceCache, ShardRoutingCustomTest, OpenExchangeRatesOrgProviderTest, TestHashPartitioner, BlockCacheTest, TestLRUCache, HdfsLockFactoryTest, MigrateRouteKeyTest, TestStressRecovery, DistanceUnitsTest, TestFieldTypeResource, TestShortCircuitedRequests, AtomicUpdatesTest, ConnectionManagerTest, TestSurroundQueryParser, ZkControllerTest, JsonLoaderTest, TestOmitPositions, HighlighterConfigTest, TestTolerantSearch, DistributedQueueTest, TestIndexingPerformance, OutputWriterTest, TestSort, XsltUpdateRequestHandlerTest, FacetPivotSmallTest, FieldMutatingUpdateProcessorTest, OverriddenZkACLAndCredentialsProvidersTest, BinaryUpdateRequestHandlerTest, BufferStoreTest, TestQueryTypes, MoreLikeThisHandlerTest, SolrTestCaseJ4Test, BadCopyFieldTest, TestConfigReload, TestRequestStatusCollectionAPI, NoCacheHeaderTest, TestConfigSets, PolyFieldTest, LeaderElectionTest, PathHierarchyTokenizerFactoryTest, ResponseHeaderTest, CollectionsAPIAsyncDistributedZkTest, ReturnFieldsTest, TestCharFilters, TestNonDefinedSimilarityFactory, TestCoreDiscovery, TestLFUCache, ClusterStateUpdateTest, TestFileDictionaryLookup, TestAddFieldRealTimeGet, TestDocumentBuilder, TestBulkSchemaAPI, TestRangeQuery, RequiredFieldsTest, TestRTGBase, TestDocSet, ShowFileRequestHandlerTest, MinimalSchemaTest, TestSolrJ, TestMissingGroups, PreAnalyzedUpdateProcessorTest, PreAnalyzedFieldTest, QueryParsingTest, TestBinaryResponseWriter, TestDynamicLoading, TestBlobHandler, SolrPluginUtilsTest, ChangedSchemaMergeTest, TestRawResponseWriter, HdfsWriteToMultipleCollectionsTest, TestSolrQueryParserResource, TestClassNameShortening, TestImplicitCoreProperties, OverseerCollectionProcessorTest, SystemInfoHandlerTest, TestFastWriter, TestSerializedLuceneMatchVersion, SpellCheckComponentTest, HdfsSyncSliceTest, SuggestComponentTest, TestOverriddenPrefixQueryForCustomFieldType, TestPostingsSolrHighlighter, CursorMarkTest, RemoteQueryErrorTest, UpdateParamsTest, CurrencyFieldXmlFileTest, ParsingFieldUpdateProcessorsTest, CurrencyFieldOpenExchangeTest, TestAnalyzeInfixSuggestions, EchoParamsTest, HttpPartitionTest, UnloadDistributedZkTest, TestPseudoReturnFields, CoreAdminHandlerTest, TestTrie, TestRebalanceLeaders, TestLazyCores, AlternateDirectoryTest, TestLRUStatsCache, TestCSVLoader, TestPartialUpdateDeduplication, TestDistributedMissingSort, ClusterStateTest, SpatialFilterTest, LeaderFailoverAfterPartitionTest, TestBadConfig, TestSchemaSimilarityResource, LeaderElectionIntegrationTest, SyncSliceTest, IndexBasedSpellCheckerTest, TestCollationFieldDocValues, TestFastOutputStream, TestObjectReleaseTracker, TestCollectionAPI, ZkStateWriterTest, TestSolr4Spatial, TestDefaultSearchFieldResource, FileUtilsTest, TestSolrCoreProperties, TestLuceneMatchVersion, TestLeaderElectionZkExpiry, URLClassifyProcessorTest, SortByFunctionTest, DistributedTermsComponentTest, DateRangeFieldTest, TestIBSimilarityFactory, TestShardHandlerFactory, TermVectorComponentTest, MBeansHandlerTest, SolrCmdDistributorTest, TestStressUserVersions, TestComplexPhraseQParserPlugin, TestBulkSchemaConcurrent, TestHighlightDedupGrouping, DocumentAnalysisRequestHandlerTest, TestManagedResource, TestSolrXmlPersistence, SimplePostToolTest, PeerSyncTest, EnumFieldTest, TestSchemaNameResource, TestCollationField, AddBlockUpdateTest, TestZkChroot, TestCloudManagedSchema, TestExpandComponent, TestGroupingSearch, DistributedFacetPivotLargeTest, TestQueryUtils, DistribDocExpirationUpdateProcessorTest, LeaderInitiatedRecoveryOnCommitTest, TestSchemaVersionResource, DeleteShardTest, TestDistributedSearch, VMParamsZkACLAndCredentialsProvidersTest, TestJmxMonitoredMap, XmlUpdateRequestHandlerTest, SOLR749Test, DistributedSuggestComponentTest, AliasIntegrationTest, PrimUtilsTest, DirectUpdateHandlerOptimizeTest, TestSearchPerf, TestDocBasedVersionConstraints, InfoHandlerTest, TestQuerySenderListener, BasicDistributedZkTest, HighlighterTest, NumericFieldsTest, DirectSolrSpellCheckerTest, AnalysisAfterCoreReloadTest, TestExactStatsCache, SolrIndexConfigTest, TestStandardQParsers, TestFuzzyAnalyzedSuggestions, TestFieldTypeCollectionResource, DistributedFacetPivotWhiteBoxTest, TestSchemaResource, TestSearcherReuse, TestRecovery, TestPhraseSuggestions, ShardRoutingTest, SharedFSAutoReplicaFailoverTest, TestManagedSchemaDynamicFieldResource, TestFieldSortValues, TestRandomFaceting, TestAnalyzedSuggestions, OverseerStatusTest, TestUtils, TestPHPSerializedResponseWriter, StatelessScriptUpdateProcessorFactoryTest, HardAutoCommitTest, DefaultValueUpdateProcessorTest, TimeZoneUtilsTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, OverseerTest, BasicZkTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ZkSolrClientTest, TestRandomDVFaceting, ZkCLITest, TestDistributedGrouping, TestFaceting, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestRealTimeGet, TestStressReorder, TestReload, TestStressVersions, TestCoreContainer, SimpleFacetsTest, SolrCoreTest, StatsComponentTest, QueryElevationComponentTest, BadIndexSchemaTest, ConvertedLegacyTest, TestFunctionQuery, BasicFunctionalityTest, DirectUpdateHandlerTest, TestIndexSearcher, SoftAutoCommitTest, DistributedQueryElevationComponentTest, SolrIndexSplitterTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterFSTTest, SolrRequestParserTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, SuggesterWFSTTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestUpdate, TestWordDelimiterFilterFactory, QueryEqualityTest, TestRemoteStreaming, DistanceFunctionTest, SolrInfoMBeanTest, DebugComponentTest, CacheHeaderTest, LukeRequestHandlerTest, DisMaxRequestHandlerTest, StandardRequestHandlerTest, TestWriterPerf, TestReversedWildcardFilterFactory, PrimitiveFieldTypeTest, FileBasedSpellCheckerTest, TermsComponentTest, RequestHandlersTest, DocumentBuilderTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, RegexBoostProcessorTest, IndexSchemaTest, TestJmxIntegration, UpdateRequestProcessorFactoryTest, JSONWriterTest, TestCSVResponseWriter, UniqFieldsUpdateProcessorFactoryTest, CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest, CoreContainerCoreInitFailuresTest, TestSolrIndexConfig, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, BadComponentTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, MultiTermTest, SampleTest, TestElisionMultitermQuery, ExternalFileFieldSortTest, NotRequiredUniqueKeyTest, SpellPossibilityIteratorTest, TestCodecSupport, SynonymTokenizerTest, TestXIncludeConfig, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, LegacyHTMLStripCharFilterTest, ScriptEngineTest, PluginInfoTest, TestFastLRUCache, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, TestSystemIdResolver, SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, ZkNodePropsTest, SliceStateTest, UUIDFieldTest, CircularListTest, CursorPagingTest, DistributedIntervalFacetingTest, TestCursorMarkWithoutUniqueKey, AssignTest, AsyncMigrateRouteKeyTest, CloudExitableDirectoryReaderTest, CustomCollectionTest, DeleteInactiveReplicaTest, DeleteReplicaTest, ExternalCollectionsTest, MultiThreadedOCPTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerRolesTest, ReplicationFactorTest, RollingRestartTest, SSLMigrationTest, ShardSplitTest, SharedFSAutoReplicaFailoverUtilsTest, TestCloudInspectUtil, TestCloudPivotFacet, TestDistribDocBasedVersion, TestMiniSolrCloudCluster, TestReplicaProperties] [junit4] Completed in 102.76s, 1 test, 1 error <<< FAILURES! [...truncated 263 lines...] BUILD FAILED /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:519: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:467: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/build.xml:61: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/extra-targets.xml:39: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/build.xml:187: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/solr/common-build.xml:510: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:1348: The following error occurred while executing this line: /Users/jenkins/workspace/Lucene-Solr-trunk-MacOSX/lucene/common-build.xml:956: There were test failures: 461 suites, 1870 tests, 1 error, 52 ignored (21 assumptions) Total time: 125 minutes 58 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0 -XX:+UseCompressedOops -XX:+UseG1GC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org