Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4514/ Java: 32bit/jdk1.8.0_31 -server -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.cloud.ReplicationFactorTest.test Error Message: org.apache.http.NoHttpResponseException: The target server failed to respond Stack Trace: org.apache.solr.client.solrj.SolrServerException: org.apache.http.NoHttpResponseException: The target server failed to respond at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:899) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:752) at org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:284) at org.apache.solr.cloud.ReplicationFactorTest.test(ReplicationFactorTest.java:112) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:945) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:920) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:464) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:568) at org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:565) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) ... 1 more Build Log: [...truncated 10101 lines...] [junit4] Suite: org.apache.solr.cloud.ReplicationFactorTest [junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\init-core-data-001 [junit4] 2> 3699690 T18819 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /sc_/ci [junit4] 2> 3699694 T18819 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 3699695 T18820 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 3699790 T18819 oasc.ZkTestServer.run start zk server on port:59046 [junit4] 2> 3699790 T18819 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3699792 T18819 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3699796 T18827 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8a5011 name:ZooKeeperConnection Watcher:127.0.0.1:59046 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3699796 T18819 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3699797 T18819 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3699797 T18819 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 3699802 T18819 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3699802 T18819 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3699804 T18830 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@898723 name:ZooKeeperConnection Watcher:127.0.0.1:59046/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3699804 T18819 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3699805 T18819 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3699805 T18819 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 3699808 T18819 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 3699811 T18819 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 3699814 T18819 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 3699817 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 3699817 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 3699821 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml [junit4] 2> 3699821 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 3699825 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3699825 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 3699829 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 3699829 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 3699832 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 3699832 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 3699836 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4] 2> 3699836 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 3699839 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 3699839 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 3699843 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 3699843 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 3699847 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3699847 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 3699850 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 3699850 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 3699854 T18819 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 3699854 T18819 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 3701219 T18819 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\cores\collection1 [junit4] 2> 3701224 T18819 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 3701227 T18819 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@1c124aa{/sc_/ci,null,AVAILABLE} [junit4] 2> 3701229 T18819 oejs.AbstractConnector.doStart Started ServerConnector@3bf7af{HTTP/1.1}{127.0.0.1:59054} [junit4] 2> 3701230 T18819 oejs.Server.doStart Started @3691233ms [junit4] 2> 3701230 T18819 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44 [junit4] 2> 3701230 T18819 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3701230 T18819 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001 [junit4] 2> 3701230 T18819 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\' [junit4] 2> 3701262 T18819 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\solr.xml [junit4] 2> 3701269 T18819 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\cores [junit4] 2> 3701270 T18819 oasc.CoreContainer.<init> New CoreContainer 24904307 [junit4] 2> 3701270 T18819 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\] [junit4] 2> 3701270 T18819 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\lib [junit4] 2> 3701270 T18819 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\lib). [junit4] 2> 3701280 T18819 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3701280 T18819 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3701281 T18819 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3701281 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3701281 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3701281 T18819 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3701281 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3701281 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3701281 T18819 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3701281 T18819 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3701283 T18819 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 3701283 T18819 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3701283 T18819 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3701283 T18819 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3701283 T18819 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 3701283 T18819 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:59046/solr [junit4] 2> 3701283 T18819 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3701283 T18819 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3701286 T18819 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3701287 T18844 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@156e3b5 name:ZooKeeperConnection Watcher:127.0.0.1:59046 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3701287 T18819 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3701287 T18819 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3701289 T18821 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14bd3bd93040002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 3701291 T18819 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3701292 T18847 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b5a010 name:ZooKeeperConnection Watcher:127.0.0.1:59046/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3701293 T18819 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3701295 T18819 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 3701299 T18819 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 3701302 T18819 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 3701305 T18819 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 3701308 T18819 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 3701312 T18819 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 3701314 T18819 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59053_sc_%2Fci [junit4] 2> 3701315 T18819 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59053_sc_%2Fci [junit4] 2> 3701318 T18819 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 3701319 T18819 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 3701321 T18819 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3701323 T18819 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:59053_sc_%2Fci [junit4] 2> 3701323 T18819 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 3701327 T18819 oasc.Overseer.start Overseer (id=93401028460019715-127.0.0.1:59053_sc_%2Fci-n_0000000000) starting [junit4] 2> 3701330 T18819 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 3701339 T18819 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 3701341 T18848 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 3701341 T18849 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 3701342 T18819 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 3701345 T18819 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 3701347 T18819 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3701352 T18819 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\cores [junit4] 2> 3701353 T18819 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\cores\collection1\ [junit4] 2> 3701354 T18819 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 3701355 T18851 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 3701355 T18851 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3701356 T18847 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3701356 T18851 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3701357 T18848 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:59053/sc_/ci", [junit4] 2> "node_name":"127.0.0.1:59053_sc_%2Fci", [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> 3701358 T18848 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:59053/sc_/ci", [junit4] 2> "node_name":"127.0.0.1:59053_sc_%2Fci", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3701358 T18848 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 3701358 T18848 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 3701360 T18847 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> 3702318 T18851 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3702318 T18851 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 3702318 T18851 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3702318 T18851 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3702319 T18851 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3702319 T18851 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\cores\collection1\' [junit4] 2> 3702336 T18851 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 3702339 T18851 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 3702348 T18851 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 3702349 T18851 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3702357 T18851 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3702361 T18851 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 3702364 T18851 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3702473 T18851 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3702480 T18851 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3702481 T18851 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3702492 T18851 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3702495 T18851 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3702498 T18851 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3702500 T18851 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3702500 T18851 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3702500 T18851 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3702501 T18851 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3702501 T18851 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3702501 T18851 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 3702501 T18851 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3702502 T18851 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\control-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/control/data\ [junit4] 2> 3702502 T18851 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f84b77 [junit4] 2> 3702503 T18851 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/control/data\ [junit4] 2> 3702503 T18851 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/control/data\index/ [junit4] 2> 3702503 T18851 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001\control\data\index' doesn't exist. Creating new index... [junit4] 2> 3702503 T18851 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/control/data\index [junit4] 2> 3702504 T18851 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=65.291015625, floorSegmentMB=2.14453125, forceMergeDeletesPctAllowed=9.324925307347009, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4902141643955431 [junit4] 2> 3702505 T18851 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@98396f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1002b47),segFN=segments_1,generation=1} [junit4] 2> 3702505 T18851 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3702507 T18851 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3702508 T18851 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3702509 T18851 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3702509 T18851 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3702509 T18851 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3702509 T18851 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3702510 T18851 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3702510 T18851 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3702510 T18851 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3702511 T18851 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3702511 T18851 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3702511 T18851 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3702512 T18851 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3702512 T18851 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 3702512 T18851 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 3702513 T18851 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 3702514 T18851 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 3702514 T18851 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 3702515 T18851 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 3702515 T18851 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 3702515 T18851 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 3702515 T18851 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 3702516 T18851 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 3702516 T18851 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 3702516 T18851 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3702517 T18851 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 3702518 T18851 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3702523 T18851 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3702525 T18851 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3702526 T18851 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3702528 T18851 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3702528 T18851 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 3702530 T18851 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 3702531 T18851 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3702532 T18851 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3702532 T18851 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=46.3154296875, floorSegmentMB=1.873046875, forceMergeDeletesPctAllowed=9.826776625018455, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 3702533 T18851 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@98396f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1002b47),segFN=segments_1,generation=1} [junit4] 2> 3702533 T18851 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3702533 T18851 oass.SolrIndexSearcher.<init> Opening Searcher@1e6ac28[collection1] main [junit4] 2> 3702534 T18851 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 3702535 T18851 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3702535 T18851 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3702536 T18851 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3702536 T18851 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3702537 T18851 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3702538 T18851 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 3702538 T18851 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3702538 T18851 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3702538 T18851 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 3702539 T18851 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3702539 T18852 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1e6ac28[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3702540 T18855 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:59053/sc_/ci collection:control_collection shard:shard1 [junit4] 2> 3702541 T18819 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 3702541 T18819 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3702542 T18855 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 3702542 T18819 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3702545 T18819 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3702549 T18859 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@973319 name:ZooKeeperConnection Watcher:127.0.0.1:59046/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3702549 T18819 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3702549 T18819 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3702552 T18819 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3702553 T18855 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 3702555 T18847 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3702556 T18855 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3702556 T18855 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C3558 name=collection1 org.apache.solr.core.SolrCore@130729 url=http://127.0.0.1:59053/sc_/ci/collection1 node=127.0.0.1:59053_sc_%2Fci C3558_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:59053/sc_/ci, node_name=127.0.0.1:59053_sc_%2Fci, state=down} [junit4] 2> 3702556 T18855 C3558 P59053 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:59053/sc_/ci/collection1/ [junit4] 2> 3702556 T18855 C3558 P59053 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3702557 T18855 C3558 P59053 oasc.SyncStrategy.syncToMe http://127.0.0.1:59053/sc_/ci/collection1/ has no replicas [junit4] 2> 3702557 T18848 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> 3702557 T18819 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 3702557 T18855 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:59053/sc_/ci/collection1/ shard1 [junit4] 2> 3702587 T18855 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 3702591 T18847 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3702592 T18848 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":"http://127.0.0.1:59053/sc_/ci", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 3702689 T18859 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> 3702690 T18847 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> 3702739 T18855 oasc.ZkController.register We are http://127.0.0.1:59053/sc_/ci/collection1/ and leader is http://127.0.0.1:59053/sc_/ci/collection1/ [junit4] 2> 3702739 T18855 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:59053/sc_/ci [junit4] 2> 3702739 T18855 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3702739 T18855 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 3702739 T18855 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3702741 T18847 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3702742 T18848 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":"http://127.0.0.1:59053/sc_/ci", [junit4] 2> "node_name":"127.0.0.1:59053_sc_%2Fci", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 3702742 T18848 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:59053/sc_/ci", [junit4] 2> "node_name":"127.0.0.1:59053_sc_%2Fci", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 3702841 T18847 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> 3702842 T18859 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> 3703923 T18819 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\cores\collection1 [junit4] 2> 3703926 T18819 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 3703927 T18819 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 3703931 T18819 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@17aa6f4{/sc_/ci,null,AVAILABLE} [junit4] 2> 3703932 T18819 oejs.AbstractConnector.doStart Started ServerConnector@192ffb4{HTTP/1.1}{127.0.0.1:59067} [junit4] 2> 3703932 T18819 oejs.Server.doStart Started @3694080ms [junit4] 2> 3703933 T18819 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44 [junit4] 2> 3703933 T18819 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3703933 T18819 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001 [junit4] 2> 3703933 T18819 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\' [junit4] 2> 3703970 T18819 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\solr.xml [junit4] 2> 3703979 T18819 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\cores [junit4] 2> 3703979 T18819 oasc.CoreContainer.<init> New CoreContainer 16302105 [junit4] 2> 3703979 T18819 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\] [junit4] 2> 3703980 T18819 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\lib [junit4] 2> 3703980 T18819 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\lib). [junit4] 2> 3703996 T18819 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3703997 T18819 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3703997 T18819 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3703997 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3703998 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3703998 T18819 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3703998 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3703998 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3703998 T18819 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3703998 T18819 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3703999 T18819 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 3704000 T18819 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3704000 T18819 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3704000 T18819 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3704000 T18819 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 3704000 T18819 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:59046/solr [junit4] 2> 3704000 T18819 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3704000 T18819 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3704003 T18819 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3704006 T18873 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15aed25 name:ZooKeeperConnection Watcher:127.0.0.1:59046 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3704007 T18819 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3704007 T18819 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3704011 T18819 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3704013 T18876 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1914375 name:ZooKeeperConnection Watcher:127.0.0.1:59046/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3704014 T18819 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3704021 T18819 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3704987 T18819 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59066_sc_%2Fci [junit4] 2> 3704989 T18819 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59066_sc_%2Fci [junit4] 2> 3704994 T18819 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3704996 T18819 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\cores [junit4] 2> 3704996 T18819 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\cores\collection1\ [junit4] 2> 3704997 T18819 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 3704997 T18877 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3704997 T18877 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3704998 T18877 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3704998 T18847 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3704999 T18848 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:59066/sc_/ci", [junit4] 2> "node_name":"127.0.0.1:59066_sc_%2Fci", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 3705000 T18848 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:59066/sc_/ci", [junit4] 2> "node_name":"127.0.0.1:59066_sc_%2Fci", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3705000 T18848 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 3705000 T18848 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 3705000 T18859 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> 3705000 T18847 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> 3705000 T18876 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> 3705958 T18877 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3705958 T18877 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3705959 T18877 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3705959 T18877 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3705961 T18877 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3705961 T18877 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\cores\collection1\' [junit4] 2> 3705973 T18877 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 3705976 T18877 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 3705985 T18877 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 3705987 T18877 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3706000 T18877 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3706002 T18877 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 3706006 T18877 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3706105 T18877 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3706111 T18877 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3706111 T18877 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3706125 T18877 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3706127 T18877 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3706130 T18877 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3706132 T18877 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3706132 T18877 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3706132 T18877 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3706135 T18877 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3706135 T18877 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3706135 T18877 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3706135 T18877 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3706136 T18877 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-1-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/jetty1\ [junit4] 2> 3706136 T18877 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f84b77 [junit4] 2> 3706136 T18877 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/jetty1\ [junit4] 2> 3706136 T18877 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/jetty1\index/ [junit4] 2> 3706136 T18877 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001\jetty1\index' doesn't exist. Creating new index... [junit4] 2> 3706137 T18877 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/jetty1\index [junit4] 2> 3706137 T18877 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=65.291015625, floorSegmentMB=2.14453125, forceMergeDeletesPctAllowed=9.324925307347009, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4902141643955431 [junit4] 2> 3706137 T18877 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13731e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@656b60),segFN=segments_1,generation=1} [junit4] 2> 3706137 T18877 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3706140 T18877 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3706141 T18877 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3706141 T18877 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3706141 T18877 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3706142 T18877 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3706142 T18877 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3706142 T18877 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3706143 T18877 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 3706143 T18877 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 3706143 T18877 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3706144 T18877 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3706144 T18877 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3706145 T18877 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 3706145 T18877 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 3706146 T18877 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 3706146 T18877 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 3706147 T18877 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 3706148 T18877 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 3706149 T18877 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 3706149 T18877 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 3706150 T18877 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 3706150 T18877 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 3706151 T18877 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 3706151 T18877 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 3706152 T18877 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 3706152 T18877 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 3706152 T18877 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 3706157 T18877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3706157 T18877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3706160 T18877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3706161 T18877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 3706179 T18877 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 3706180 T18877 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 3706182 T18877 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 3706182 T18877 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 3706182 T18877 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=42, maxMergeAtOnceExplicit=14, maxMergedSegmentMB=46.3154296875, floorSegmentMB=1.873046875, forceMergeDeletesPctAllowed=9.826776625018455, segmentsPerTier=43.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0 [junit4] 2> 3706182 T18877 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@13731e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@656b60),segFN=segments_1,generation=1} [junit4] 2> 3706183 T18877 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3706183 T18877 oass.SolrIndexSearcher.<init> Opening Searcher@611bbb[collection1] main [junit4] 2> 3706183 T18877 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3706185 T18877 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3706185 T18877 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 3706186 T18877 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 3706186 T18877 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 3706186 T18877 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3706187 T18877 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 3706187 T18877 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 3706187 T18877 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 3706187 T18877 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 3706189 T18878 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@611bbb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 3706189 T18877 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 3706190 T18881 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:59066/sc_/ci collection:collection1 shard:shard2 [junit4] 2> 3706191 T18819 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 3706192 T18819 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 3706192 T18881 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 3706199 T18881 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 3706201 T18847 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3706202 T18881 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 3706202 T18881 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C3559 name=collection1 org.apache.solr.core.SolrCore@1eb849d url=http://127.0.0.1:59066/sc_/ci/collection1 node=127.0.0.1:59066_sc_%2Fci C3559_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:59066/sc_/ci, node_name=127.0.0.1:59066_sc_%2Fci, state=down} [junit4] 2> 3706202 T18881 C3559 P59066 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:59066/sc_/ci/collection1/ [junit4] 2> 3706202 T18881 C3559 P59066 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 3706202 T18881 C3559 P59066 oasc.SyncStrategy.syncToMe http://127.0.0.1:59066/sc_/ci/collection1/ has no replicas [junit4] 2> 3706202 T18881 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:59066/sc_/ci/collection1/ shard2 [junit4] 2> 3706204 T18881 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 3706206 T18848 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> 3706213 T18847 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3706215 T18848 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:59066/sc_/ci", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 3706316 T18847 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> 3706316 T18876 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> 3706317 T18859 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> 3706361 T18881 oasc.ZkController.register We are http://127.0.0.1:59066/sc_/ci/collection1/ and leader is http://127.0.0.1:59066/sc_/ci/collection1/ [junit4] 2> 3706361 T18881 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:59066/sc_/ci [junit4] 2> 3706361 T18881 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 3706361 T18881 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 3706361 T18881 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3706363 T18847 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3706365 T18848 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":"http://127.0.0.1:59066/sc_/ci", [junit4] 2> "node_name":"127.0.0.1:59066_sc_%2Fci", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 3706366 T18848 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:59066/sc_/ci", [junit4] 2> "node_name":"127.0.0.1:59066_sc_%2Fci", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3706467 T18847 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> 3706468 T18859 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> 3706468 T18876 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> 3707511 T18819 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\cores\collection1 [junit4] 2> 3707515 T18819 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 3707516 T18819 oejs.Server.doStart jetty-9.2.6.v20141205 [junit4] 2> 3707519 T18819 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@1c0ae5f{/sc_/ci,null,AVAILABLE} [junit4] 2> 3707520 T18819 oejs.AbstractConnector.doStart Started ServerConnector@1574f8b{HTTP/1.1}{127.0.0.1:59077} [junit4] 2> 3707520 T18819 oejs.Server.doStart Started @3697841ms [junit4] 2> 3707521 T18819 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44 [junit4] 2> 3707521 T18819 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 3707521 T18819 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001 [junit4] 2> 3707521 T18819 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\' [junit4] 2> 3707551 T18819 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\solr.xml [junit4] 2> 3707560 T18819 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\cores [junit4] 2> 3707560 T18819 oasc.CoreContainer.<init> New CoreContainer 3979202 [junit4] 2> 3707560 T18819 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\] [junit4] 2> 3707560 T18819 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\lib [junit4] 2> 3707561 T18819 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\lib). [junit4] 2> 3707571 T18819 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 3707571 T18819 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 3707571 T18819 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 3707572 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 3707572 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 3707572 T18819 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 3707572 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 3707573 T18819 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 3707573 T18819 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 3707573 T18819 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 3707573 T18819 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 3707573 T18819 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 3707574 T18819 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 3707574 T18819 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 3707574 T18819 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 3707574 T18819 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:59046/solr [junit4] 2> 3707575 T18819 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 3707576 T18819 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 3707577 T18819 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3707580 T18896 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@10b928e name:ZooKeeperConnection Watcher:127.0.0.1:59046 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3707580 T18819 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3707581 T18819 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 3707583 T18821 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14bd3bd93040007, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 3707585 T18819 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 3707586 T18899 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b1047a name:ZooKeeperConnection Watcher:127.0.0.1:59046/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 3707587 T18819 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 3707593 T18819 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 3708493 T18819 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:59076_sc_%2Fci [junit4] 2> 3708493 T18819 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:59076_sc_%2Fci [junit4] 2> 3708498 T18819 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 3708502 T18819 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\cores [junit4] 2> 3708503 T18819 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\cores\collection1\ [junit4] 2> 3708503 T18819 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 3708505 T18900 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 3708505 T18900 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 3708506 T18847 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 3708506 T18900 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 3708507 T18848 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:59076/sc_/ci", [junit4] 2> "node_name":"127.0.0.1:59076_sc_%2Fci", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 3708508 T18848 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:59076/sc_/ci", [junit4] 2> "node_name":"127.0.0.1:59076_sc_%2Fci", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 3708508 T18848 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 3708508 T18848 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard3 [junit4] 2> 3708509 T18859 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> 3708509 T18899 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> 3708509 T18876 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> 3708509 T18847 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> 3709371 T18900 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 3709371 T18900 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 3709371 T18900 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 3709371 T18900 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 3709373 T18900 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 3709373 T18900 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\cores\collection1\' [junit4] 2> 3709388 T18900 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 3709390 T18900 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 3709399 T18900 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 3709400 T18900 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 3709407 T18900 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 3709410 T18900 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 3709415 T18900 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 3709499 T18900 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 3709503 T18900 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 3709505 T18900 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 3709516 T18900 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3709519 T18900 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 3709522 T18900 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3709523 T18900 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3709523 T18900 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3709523 T18900 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 3709524 T18900 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 3709525 T18900 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 3709525 T18900 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 3709525 T18900 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 3709526 T18900 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\shard-2-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/jetty2\ [junit4] 2> 3709526 T18900 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1f84b77 [junit4] 2> 3709527 T18900 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/jetty2\ [junit4] 2> 3709527 T18900 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/jetty2\index/ [junit4] 2> 3709527 T18900 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001\jetty2\index' doesn't exist. Creating new index... [junit4] 2> 3709527 T18900 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32ABC34EF864E0C0-001\tempDir-001/jetty2\index [junit4] 2> 3709528 T18900 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=24, maxMergeAtOnceExplicit=38, maxMergedSegmentMB=65.291015625, floorSegmentMB=2.14453125, forceMergeDeletesPctAllowed=9.324925307347009, segmentsPerTier=35.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4902141643955431 [junit4] 2> 3709528 T18900 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@120211d lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c60467),segFN=segments_1,generation=1} [junit4] 2> 3709528 T18900 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 3709531 T18900 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 3709531 T18900 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 3709531 T18900 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 3709532 T18900 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 3709532 T18900 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 3709532 T18900 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 3709533 T18900 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] [...truncated too long message...] 7576,total=438304768 [junit4] 2> NOTE: All tests run in this JVM: [CursorMarkTest, TestFuzzyAnalyzedSuggestions, CurrencyFieldXmlFileTest, HdfsLockFactoryTest, AutoCommitTest, CoreAdminCreateDiscoverTest, PreAnalyzedUpdateProcessorTest, FileBasedSpellCheckerTest, DistributedDebugComponentTest, DeleteReplicaTest, AssignTest, TestImplicitCoreProperties, TestDistributedGrouping, PrimUtilsTest, EnumFieldTest, SyncSliceTest, TestFastWriter, TestConfig, ShardRoutingTest, ReturnFieldsTest, ConnectionManagerTest, TestRandomFaceting, TestHighlightDedupGrouping, TestCollapseQParserPlugin, TestHighFrequencyDictionaryFactory, TestLFUCache, ConvertedLegacyTest, TestStandardQParsers, BadComponentTest, OverseerTest, DistributedFacetPivotLargeTest, SearchHandlerTest, TestValueSourceCache, MergeStrategyTest, TestPseudoReturnFields, TestSchemaVersionResource, DistributedFacetPivotLongTailTest, SolrIndexSplitterTest, ResourceLoaderTest, TestSuggestSpellingConverter, OverseerStatusTest, TestInitParams, TestFaceting, SampleTest, TestManagedSchemaDynamicFieldResource, TestSimpleQParserPlugin, TestSchemaResource, TestSchemaSimilarityResource, BasicDistributedZk2Test, CustomCollectionTest, ZkStateWriterTest, TestFieldSortValues, TestSolr4Spatial, TestCloudSchemaless, TestTolerantSearch, TestFastOutputStream, TestLMDirichletSimilarityFactory, TestGroupingSearch, StatsComponentTest, TestUtils, HdfsWriteToMultipleCollectionsTest, TestDistributedMissingSort, SpellingQueryConverterTest, BadIndexSchemaTest, ZkNodePropsTest, TestNoOpRegenerator, ClusterStateUpdateTest, SolrPluginUtilsTest, TestStressLucene, TestSolrCoreProperties, TestManagedStopFilterFactory, TestCSVLoader, TestRangeQuery, TestRTGBase, TestChildDocTransformer, TestOverriddenPrefixQueryForCustomFieldType, SolrCoreTest, UpdateRequestProcessorFactoryTest, ExternalFileFieldSortTest, DistributedSpellCheckComponentTest, TestNRTOpen, SolrIndexConfigTest, HdfsUnloadDistributedZkTest, ChaosMonkeySafeLeaderTest, TestCharFilters, TestSort, TestSolrDeletionPolicy1, SuggestComponentTest, DateMathParserTest, DistanceFunctionTest, HdfsRecoveryZkTest, DisMaxRequestHandlerTest, TestClusterStateMutator, DistributedQueryComponentOptimizationTest, TestCollationField, CSVRequestHandlerTest, CoreAdminRequestStatusTest, DistributedQueryElevationComponentTest, TestCoreDiscovery, TestSolrQueryParser, SSLMigrationTest, CachingDirectoryFactoryTest, TestBinaryField, TestAnalyzeInfixSuggestions, PingRequestHandlerTest, SuggesterTSTTest, PeerSyncTest, HdfsDirectoryTest, StandardRequestHandlerTest, SaslZkACLProviderTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestDistribDocBasedVersion, TestHashPartitioner, UUIDFieldTest, DistributedMLTComponentTest, SolrInfoMBeanTest, DistributedExpandComponentTest, CoreAdminHandlerTest, RequiredFieldsTest, TestLeaderElectionZkExpiry, TestExtendedDismaxParser, TestUniqueKeyFieldResource, TestManagedResource, TermVectorComponentTest, LoggingHandlerTest, TestSolrConfigHandlerCloud, DocumentBuilderTest, TestExactStatsCache, TestSolr4Spatial2, SoftAutoCommitTest, TermVectorComponentDistributedTest, SpatialHeatmapFacetsTest, TestUpdate, TestCursorMarkWithoutUniqueKey, TestReloadAndDeleteDocs, TestManagedSchema, TestStressVersions, TestSearchPerf, TestReplicaProperties, TestFieldResource, DirectUpdateHandlerOptimizeTest, TestInfoStreamLogging, HdfsChaosMonkeySafeLeaderTest, FileUtilsTest, RAMDirectoryFactoryTest, LeaderElectionIntegrationTest, RequestLoggingTest, TestCloudPivotFacet, CollectionsAPIDistributedZkTest, SortByFunctionTest, RankQueryTest, ChangedSchemaMergeTest, FullSolrCloudDistribCmdsTest, RecoveryZkTest, TestFieldTypeCollectionResource, TestSolrQueryParserResource, LeaderElectionTest, TestFileDictionaryLookup, BadCopyFieldTest, CloudExitableDirectoryReaderTest, BinaryUpdateRequestHandlerTest, TestIndexingPerformance, TestRestManager, TestXIncludeConfig, TestComponentsName, DateFieldTest, PolyFieldTest, LukeRequestHandlerTest, TestBlobHandler, URLClassifyProcessorTest, TestRandomDVFaceting, ShardSplitTest, MultiThreadedOCPTest, TestLMJelinekMercerSimilarityFactory, CursorPagingTest, DistribJoinFromCollectionTest, CircularListTest, AsyncMigrateRouteKeyTest, NotRequiredUniqueKeyTest, TestIntervalFaceting, SpatialFilterTest, SpellCheckCollatorTest, CloudMLTQParserTest, QueryParsingTest, AnalyticsMergeStrategyTest, TestReplicationHandler, TestDynamicFieldCollectionResource, ZkSolrClientTest, UnloadDistributedZkTest, TestJoin, DistribCursorPagingTest, TestStressReorder, SolrRequestParserTest, TestSolrIndexConfig, TestTrie, TestStressRecovery, TestManagedSynonymFilterFactory, DefaultValueUpdateProcessorTest, UniqFieldsUpdateProcessorFactoryTest, MigrateRouteKeyTest, MinimalSchemaTest, TestExactSharedStatsCache, XsltUpdateRequestHandlerTest, UUIDUpdateProcessorFallbackTest, TestManagedSchemaFieldResource, AtomicUpdatesTest, DOMUtilTest, TestFiltering, HdfsDirectoryFactoryTest, TimeZoneUtilsTest, DistributedFacetPivotWhiteBoxTest, TestWriterPerf, OverseerRolesTest, SolrXmlInZkTest, DistributedTermsComponentTest, AddBlockUpdateTest, TestTrackingShardHandlerFactory, DirectSolrConnectionTest, ClusterStateTest, TestClassNameShortening, OutputWriterTest, BasicDistributedZkTest, DistributedSuggestComponentTest, TestMaxScoreQueryParser, SystemInfoHandlerTest, ZkControllerTest, TestConfigSets, TestDefaultSimilarityFactory, OpenCloseCoreStressTest, BasicZkTest, TestZkChroot, ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch, TestRecovery, TestRealTimeGet, TestReload, HardAutoCommitTest, TestCoreContainer, SimpleFacetsTest, SpellCheckComponentTest, SolrCmdDistributorTest, QueryElevationComponentTest, TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, DirectUpdateHandlerTest, TestBadConfig, OverseerCollectionProcessorTest, TestIndexSearcher, HighlighterTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, SimplePostToolTest, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, SuggesterFSTTest, TestFoldingMultitermQuery, DocValuesTest, SuggesterTest, SuggesterWFSTTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest, StatelessScriptUpdateProcessorFactoryTest, TestRemoteStreaming, DocValuesMultiTest, DebugComponentTest, CacheHeaderTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, TestQueryUtils, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, TestQueryTypes, PrimitiveFieldTypeTest, TestOmitPositions, TermsComponentTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, PathHierarchyTokenizerFactoryTest, MoreLikeThisHandlerTest, FieldAnalysisRequestHandlerTest, TestArbitraryIndexDir, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, RegexBoostProcessorTest, IndexSchemaTest, TestJmxIntegration, JSONWriterTest, TestCSVResponseWriter, MBeansHandlerTest, TestAnalyzedSuggestions, JsonLoaderTest, TestPartialUpdateDeduplication, TestPHPSerializedResponseWriter, TestBinaryResponseWriter, HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, TestQuerySenderNoQuery, CopyFieldTest, ResponseLogComponentTest, TestMergePolicyConfig, TestSolrDeletionPolicy2, MultiTermTest, TestDocSet, NumericFieldsTest, TestElisionMultitermQuery, TestPostingsSolrHighlighter, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestCodecSupport, SynonymTokenizerTest, EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestJmxMonitoredMap, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, PluginInfoTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, PreAnalyzedFieldTest, TestSystemIdResolver, TestSolrJ, TestLRUCache, TestDocumentBuilder, SliceStateTest, DistributedIntervalFacetingTest, SolrTestCaseJ4Test, ActionThrottleTest, AliasIntegrationTest, CollectionsAPIAsyncDistributedZkTest, DeleteInactiveReplicaTest, DeleteShardTest, DistribDocExpirationUpdateProcessorTest, DistributedQueueTest, ExternalCollectionsTest, HttpPartitionTest, LeaderFailoverAfterPartitionTest, LeaderInitiatedRecoveryOnCommitTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverriddenZkACLAndCredentialsProvidersTest, RecoveryAfterSoftCommitTest, RemoteQueryErrorTest, ReplicationFactorTest] [junit4] Completed in 64.83s, 1 test, 1 error <<< FAILURES! [...truncated 314 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:519: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:191: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:510: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1351: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:958: There were test failures: 468 suites, 1868 tests, 1 error, 57 ignored (30 assumptions) Total time: 115 minutes 13 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/jdk1.8.0_31 -server -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