Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4528/ Java: 64bit/jdk1.8.0_31 -XX:+UseCompressedOops -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.cloud.ReplicationFactorTest.test Error Message: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:53533/repfacttest_c8n_1x3_shard1_replica1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:53533/repfacttest_c8n_1x3_shard1_replica1 at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:597) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:920) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:811) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:754) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) 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:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933) 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 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:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) 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 com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:53533/repfacttest_c8n_1x3_shard1_replica1 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:570) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:211) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:207) 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.directUpdate(CloudSolrClient.java:594) ... 47 more 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:461) ... 52 more Build Log: [...truncated 10120 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 4AEFF95D39E657BA-001\init-core-data-001 [junit4] 2> 4488016 T20823 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 4488023 T20823 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4488024 T20824 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 4488133 T20823 oasc.ZkTestServer.run start zk server on port:53526 [junit4] 2> 4488133 T20823 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4488136 T20823 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4488140 T20831 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@195db245 name:ZooKeeperConnection Watcher:127.0.0.1:53526 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4488141 T20823 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4488141 T20823 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4488141 T20823 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 4488148 T20823 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4488149 T20823 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4488151 T20834 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4f0c7387 name:ZooKeeperConnection Watcher:127.0.0.1:53526/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4488151 T20823 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4488152 T20823 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4488152 T20823 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 4488156 T20823 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 4488159 T20823 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 4488162 T20823 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 4488166 T20823 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> 4488166 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4488171 T20823 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> 4488171 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 4488176 T20823 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> 4488177 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4488180 T20823 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> 4488180 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 4488184 T20823 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> 4488184 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 4488188 T20823 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> 4488188 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 4488192 T20823 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> 4488193 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4488196 T20823 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> 4488197 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4488200 T20823 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> 4488200 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4488203 T20823 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> 4488204 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4488208 T20823 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> 4488208 T20823 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 4490153 T20823 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 4AEFF95D39E657BA-001\control-001\cores\collection1 [junit4] 2> 4490158 T20823 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 4490161 T20823 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@6574a19f{/,null,AVAILABLE} [junit4] 2> 4490190 T20823 oejs.AbstractConnector.doStart Started ServerConnector@32616b72{HTTP/1.1}{127.0.0.1:53534} [junit4] 2> 4490191 T20823 oejs.Server.doStart Started @4485344ms [junit4] 2> 4490191 T20823 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\tempDir-001/control/data, hostContext=/, hostPort=53533, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\control-001\cores} [junit4] 2> 4490191 T20823 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 4490191 T20823 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 4AEFF95D39E657BA-001\control-001\' [junit4] 2> 4490232 T20823 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 4AEFF95D39E657BA-001\control-001\solr.xml [junit4] 2> 4490240 T20823 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 4AEFF95D39E657BA-001\control-001\cores [junit4] 2> 4490240 T20823 oasc.CoreContainer.<init> New CoreContainer 1745677868 [junit4] 2> 4490241 T20823 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 4AEFF95D39E657BA-001\control-001\] [junit4] 2> 4490243 T20823 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\control-001\lib [junit4] 2> 4490243 T20823 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 4AEFF95D39E657BA-001\control-001\lib). [junit4] 2> 4490255 T20823 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4490255 T20823 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4490255 T20823 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4490255 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4490255 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4490255 T20823 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4490257 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4490257 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4490257 T20823 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4490257 T20823 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4490257 T20823 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4490257 T20823 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4490258 T20823 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4490259 T20823 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4490259 T20823 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 4490259 T20823 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53526/solr [junit4] 2> 4490259 T20823 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4490259 T20823 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4490263 T20823 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4490265 T20848 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f147c1 name:ZooKeeperConnection Watcher:127.0.0.1:53526 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4490265 T20823 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4490265 T20823 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4490270 T20823 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4490271 T20851 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@54afaaa6 name:ZooKeeperConnection Watcher:127.0.0.1:53526/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4490272 T20823 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4490274 T20823 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 4490278 T20823 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 4490282 T20823 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 4490286 T20823 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 4490290 T20823 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 4490293 T20823 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 4490295 T20823 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53533_ [junit4] 2> 4490295 T20823 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53533_ [junit4] 2> 4490298 T20823 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 4490302 T20823 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 4490304 T20823 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4490306 T20823 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:53533_ [junit4] 2> 4490306 T20823 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 4490308 T20823 oasc.Overseer.start Overseer (id=93433454608515075-127.0.0.1:53533_-n_0000000000) starting [junit4] 2> 4490311 T20823 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 4490322 T20823 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4490323 T20852 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 4490325 T20853 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 4490326 T20823 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 4490331 T20823 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 4490331 T20823 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4490338 T20823 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 4AEFF95D39E657BA-001\control-001\cores [junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\control-001\cores\collection1, collection=control_collection, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\control-001\cores\collection1\, coreNodeName=, dataDir=data\, shard=} [junit4] 2> 4490339 T20823 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 4AEFF95D39E657BA-001\control-001\cores\collection1\ [junit4] 2> 4490339 T20823 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4490341 T20855 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 4490341 T20855 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4490342 T20851 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4490342 T20855 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4490343 T20852 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:53533";, [junit4] 2> "node_name":"127.0.0.1:53533_", [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> 4490344 T20852 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:53533";, [junit4] 2> "node_name":"127.0.0.1:53533_", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4490344 T20852 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 4490344 T20852 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 4490347 T20851 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> 4491436 T20855 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4491436 T20855 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 4491437 T20855 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4491437 T20855 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4491439 T20855 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4491439 T20855 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 4AEFF95D39E657BA-001\control-001\cores\collection1\' [junit4] 2> 4491466 T20855 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4491469 T20855 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4491477 T20855 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4491480 T20855 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4491485 T20855 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4491488 T20855 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4491492 T20855 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4491623 T20855 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4491629 T20855 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4491630 T20855 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4491645 T20855 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4491647 T20855 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4491650 T20855 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4491652 T20855 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4491652 T20855 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4491652 T20855 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4491655 T20855 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4491655 T20855 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4491655 T20855 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4491655 T20855 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4491656 T20855 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 4AEFF95D39E657BA-001\control-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\control-001\cores\collection1\data\ [junit4] 2> 4491656 T20855 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f56422d [junit4] 2> 4491656 T20855 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 4AEFF95D39E657BA-001\control-001\cores\collection1\data\ [junit4] 2> 4491656 T20855 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 4AEFF95D39E657BA-001\control-001\cores\collection1\data\index/ [junit4] 2> 4491656 T20855 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 4AEFF95D39E657BA-001\control-001\cores\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 4491657 T20855 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 4AEFF95D39E657BA-001\control-001\cores\collection1\data\index [junit4] 2> 4491658 T20855 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=33, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1780976631702189] [junit4] 2> 4491658 T20855 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2338025 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2e7894e5),segFN=segments_1,generation=1} [junit4] 2> 4491658 T20855 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4491661 T20855 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4491661 T20855 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4491662 T20855 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4491662 T20855 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4491662 T20855 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4491662 T20855 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4491663 T20855 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4491663 T20855 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4491663 T20855 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4491664 T20855 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4491664 T20855 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4491665 T20855 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4491665 T20855 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4491665 T20855 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4491666 T20855 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4491666 T20855 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4491668 T20855 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4491668 T20855 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4491669 T20855 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4491669 T20855 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4491670 T20855 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4491670 T20855 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4491670 T20855 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4491672 T20855 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4491672 T20855 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4491672 T20855 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4491673 T20855 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4491678 T20855 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4491679 T20855 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4491680 T20855 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4491681 T20855 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4491682 T20855 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4491684 T20855 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4491686 T20855 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4491686 T20855 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4491686 T20855 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 4491687 T20855 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@2338025 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2e7894e5),segFN=segments_1,generation=1} [junit4] 2> 4491687 T20855 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4491687 T20855 oass.SolrIndexSearcher.<init> Opening Searcher@4b82b193[collection1] main [junit4] 2> 4491687 T20855 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4491688 T20855 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4491688 T20855 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4491690 T20855 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4491690 T20855 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4491690 T20855 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4491690 T20855 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4491690 T20855 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4491690 T20855 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4491690 T20855 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4491690 T20855 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4491691 T20856 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4b82b193[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4491693 T20823 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 4491693 T20823 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4491694 T20823 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4491695 T20859 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53533 collection:control_collection shard:shard1 [junit4] 2> 4491696 T20823 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4491697 T20859 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 4491703 T20859 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 4491704 T20851 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4491705 T20859 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4491705 T20859 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C11174 name=collection1 org.apache.solr.core.SolrCore@d608617 url=http://127.0.0.1:53533/collection1 node=127.0.0.1:53533_ C11174_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:53533, node_name=127.0.0.1:53533_, state=down} [junit4] 2> 4491705 T20859 C11174 P53533 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53533/collection1/ [junit4] 2> 4491705 T20859 C11174 P53533 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4491705 T20859 C11174 P53533 oasc.SyncStrategy.syncToMe http://127.0.0.1:53533/collection1/ has no replicas [junit4] 2> 4491705 T20859 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53533/collection1/ shard1 [junit4] 2> 4491706 T20859 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4491708 T20852 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> 4491712 T20851 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4491713 T20852 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:53533";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 4491717 T20863 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6465286 name:ZooKeeperConnection Watcher:127.0.0.1:53526/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4491717 T20823 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4491717 T20823 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4491719 T20823 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4491722 T20823 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4491853 T20851 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> 4491853 T20863 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> 4491890 T20859 oasc.ZkController.register We are http://127.0.0.1:53533/collection1/ and leader is http://127.0.0.1:53533/collection1/ [junit4] 2> 4491891 T20859 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53533 [junit4] 2> 4491891 T20859 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4491891 T20859 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 4491891 T20859 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4491929 T20851 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4492024 T20852 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:53533";, [junit4] 2> "node_name":"127.0.0.1:53533_", [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> 4492079 T20852 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:53533";, [junit4] 2> "node_name":"127.0.0.1:53533_", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4492272 T20851 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> 4492286 T20863 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> 4493008 T20823 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 4AEFF95D39E657BA-001\shard-1-001\cores\collection1 [junit4] 2> 4493012 T20823 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\shard-1-001 [junit4] 2> 4493013 T20823 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 4493015 T20823 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@1fc249b9{/,null,AVAILABLE} [junit4] 2> 4493017 T20823 oejs.AbstractConnector.doStart Started ServerConnector@2233e7b3{HTTP/1.1}{127.0.0.1:53547} [junit4] 2> 4493018 T20823 oejs.Server.doStart Started @4488134ms [junit4] 2> 4493018 T20823 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/, hostPort=53546, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\shard-1-001\cores} [junit4] 2> 4493018 T20823 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 4493018 T20823 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 4AEFF95D39E657BA-001\shard-1-001\' [junit4] 2> 4493051 T20823 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 4AEFF95D39E657BA-001\shard-1-001\solr.xml [junit4] 2> 4493058 T20823 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 4AEFF95D39E657BA-001\shard-1-001\cores [junit4] 2> 4493060 T20823 oasc.CoreContainer.<init> New CoreContainer 1332074412 [junit4] 2> 4493060 T20823 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 4AEFF95D39E657BA-001\shard-1-001\] [junit4] 2> 4493060 T20823 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\shard-1-001\lib [junit4] 2> 4493060 T20823 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 4AEFF95D39E657BA-001\shard-1-001\lib). [junit4] 2> 4493071 T20823 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4493071 T20823 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4493071 T20823 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4493071 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4493071 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4493071 T20823 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4493073 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4493073 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4493073 T20823 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4493073 T20823 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4493073 T20823 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4493073 T20823 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4493075 T20823 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4493075 T20823 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4493075 T20823 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 4493075 T20823 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53526/solr [junit4] 2> 4493075 T20823 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4493075 T20823 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4493079 T20823 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4493081 T20877 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@b851009 name:ZooKeeperConnection Watcher:127.0.0.1:53526 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4493081 T20823 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4493081 T20823 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4493083 T20823 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4493085 T20880 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@674ed99c name:ZooKeeperConnection Watcher:127.0.0.1:53526/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4493085 T20823 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4493093 T20823 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4494081 T20823 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53546_ [junit4] 2> 4494083 T20823 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53546_ [junit4] 2> 4494088 T20823 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4494091 T20823 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 4AEFF95D39E657BA-001\shard-1-001\cores [junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\shard-1-001\cores\collection1, collection=collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\shard-1-001\cores\collection1\, coreNodeName=, dataDir=data\, shard=} [junit4] 2> 4494092 T20823 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 4AEFF95D39E657BA-001\shard-1-001\cores\collection1\ [junit4] 2> 4494092 T20823 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4494094 T20881 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4494094 T20881 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4494095 T20851 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4494095 T20881 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4494096 T20852 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:53546";, [junit4] 2> "node_name":"127.0.0.1:53546_", [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> 4494097 T20852 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:53546";, [junit4] 2> "node_name":"127.0.0.1:53546_", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4494097 T20852 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 4494097 T20852 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 4494099 T20851 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> 4494099 T20880 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> 4494099 T20863 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> 4495080 T20881 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4495081 T20881 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4495082 T20881 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4495082 T20881 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4495083 T20881 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4495083 T20881 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 4AEFF95D39E657BA-001\shard-1-001\cores\collection1\' [junit4] 2> 4495106 T20881 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4495109 T20881 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4495117 T20881 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4495121 T20881 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4495128 T20881 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4495130 T20881 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4495132 T20881 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4495244 T20881 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4495251 T20881 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4495253 T20881 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4495265 T20881 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4495268 T20881 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4495271 T20881 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4495272 T20881 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4495272 T20881 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4495272 T20881 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4495274 T20881 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4495274 T20881 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4495274 T20881 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4495274 T20881 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4495275 T20881 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 4AEFF95D39E657BA-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 4AEFF95D39E657BA-001\shard-1-001\cores\collection1\data\ [junit4] 2> 4495275 T20881 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f56422d [junit4] 2> 4495275 T20881 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 4AEFF95D39E657BA-001\shard-1-001\cores\collection1\data\ [junit4] 2> 4495276 T20881 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 4AEFF95D39E657BA-001\shard-1-001\cores\collection1\data\index/ [junit4] 2> 4495276 T20881 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 4AEFF95D39E657BA-001\shard-1-001\cores\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 4495276 T20881 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 4AEFF95D39E657BA-001\shard-1-001\cores\collection1\data\index [junit4] 2> 4495277 T20881 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=33, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1780976631702189] [junit4] 2> 4495278 T20881 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@58614a83 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31a196db),segFN=segments_1,generation=1} [junit4] 2> 4495278 T20881 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4495281 T20881 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4495281 T20881 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4495281 T20881 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4495281 T20881 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4495281 T20881 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4495281 T20881 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4495282 T20881 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4495282 T20881 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4495282 T20881 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4495283 T20881 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4495283 T20881 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4495284 T20881 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4495284 T20881 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4495284 T20881 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4495285 T20881 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4495286 T20881 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4495286 T20881 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4495287 T20881 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4495288 T20881 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4495288 T20881 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4495289 T20881 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4495289 T20881 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4495290 T20881 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4495290 T20881 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4495291 T20881 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4495291 T20881 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4495292 T20881 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4495296 T20881 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4495297 T20881 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4495298 T20881 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4495300 T20881 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4495300 T20881 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4495301 T20881 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4495303 T20881 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4495304 T20881 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4495304 T20881 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 4495305 T20881 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@58614a83 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@31a196db),segFN=segments_1,generation=1} [junit4] 2> 4495305 T20881 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4495305 T20881 oass.SolrIndexSearcher.<init> Opening Searcher@462b9621[collection1] main [junit4] 2> 4495305 T20881 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4495306 T20881 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4495306 T20881 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4495307 T20881 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4495307 T20881 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4495307 T20881 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4495308 T20881 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4495308 T20881 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4495308 T20881 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4495308 T20881 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4495309 T20882 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@462b9621[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4495310 T20881 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4495311 T20885 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:53546 collection:collection1 shard:shard2 [junit4] 2> 4495311 T20823 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 4495311 T20823 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4495313 T20885 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 4495319 T20885 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 4495321 T20851 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4495322 T20885 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4495322 T20885 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C11175 name=collection1 org.apache.solr.core.SolrCore@4ed61455 url=http://127.0.0.1:53546/collection1 node=127.0.0.1:53546_ C11175_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:53546, node_name=127.0.0.1:53546_, state=down} [junit4] 2> 4495322 T20885 C11175 P53546 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:53546/collection1/ [junit4] 2> 4495322 T20885 C11175 P53546 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4495322 T20885 C11175 P53546 oasc.SyncStrategy.syncToMe http://127.0.0.1:53546/collection1/ has no replicas [junit4] 2> 4495322 T20885 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:53546/collection1/ shard2 [junit4] 2> 4495323 T20885 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 4495329 T20852 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> 4495334 T20851 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4495336 T20852 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:53546";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 4495439 T20880 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> 4495440 T20851 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> 4495440 T20863 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> 4495486 T20885 oasc.ZkController.register We are http://127.0.0.1:53546/collection1/ and leader is http://127.0.0.1:53546/collection1/ [junit4] 2> 4495486 T20885 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:53546 [junit4] 2> 4495487 T20885 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4495487 T20885 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 4495487 T20885 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4495489 T20851 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4495492 T20852 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:53546";, [junit4] 2> "node_name":"127.0.0.1:53546_", [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> 4495493 T20852 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:53546";, [junit4] 2> "node_name":"127.0.0.1:53546_", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4495597 T20880 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> 4495597 T20851 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> 4495598 T20863 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> 4496677 T20823 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 4AEFF95D39E657BA-001\shard-2-001\cores\collection1 [junit4] 2> 4496682 T20823 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 in directory C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\shard-2-001 [junit4] 2> 4496684 T20823 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 4496687 T20823 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@39de5a4c{/,null,AVAILABLE} [junit4] 2> 4496689 T20823 oejs.AbstractConnector.doStart Started ServerConnector@7c20e3f4{HTTP/1.1}{127.0.0.1:53557} [junit4] 2> 4496689 T20823 oejs.Server.doStart Started @4491898ms [junit4] 2> 4496689 T20823 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/, hostPort=53556, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\shard-2-001\cores} [junit4] 2> 4496689 T20823 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@c387f44 [junit4] 2> 4496689 T20823 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 4AEFF95D39E657BA-001\shard-2-001\' [junit4] 2> 4496748 T20823 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 4AEFF95D39E657BA-001\shard-2-001\solr.xml [junit4] 2> 4496762 T20823 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 4AEFF95D39E657BA-001\shard-2-001\cores [junit4] 2> 4496762 T20823 oasc.CoreContainer.<init> New CoreContainer 713408 [junit4] 2> 4496762 T20823 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 4AEFF95D39E657BA-001\shard-2-001\] [junit4] 2> 4496764 T20823 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\shard-2-001\lib [junit4] 2> 4496764 T20823 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 4AEFF95D39E657BA-001\shard-2-001\lib). [junit4] 2> 4496779 T20823 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4496780 T20823 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4496780 T20823 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4496780 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4496780 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4496781 T20823 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4496781 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4496781 T20823 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4496781 T20823 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4496781 T20823 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4496782 T20823 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4496782 T20823 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4496783 T20823 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4496783 T20823 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4496783 T20823 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 4496783 T20823 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:53526/solr [junit4] 2> 4496784 T20823 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4496784 T20823 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4496787 T20823 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4496789 T20900 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@33d296da name:ZooKeeperConnection Watcher:127.0.0.1:53526 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4496789 T20823 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4496789 T20823 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4496794 T20823 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4496797 T20903 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@37acd07 name:ZooKeeperConnection Watcher:127.0.0.1:53526/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4496797 T20823 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4496811 T20823 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4497801 T20823 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:53556_ [junit4] 2> 4497802 T20823 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:53556_ [junit4] 2> 4497808 T20823 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4497811 T20823 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 4AEFF95D39E657BA-001\shard-2-001\cores [junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\shard-2-001\cores\collection1, collection=collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 4AEFF95D39E657BA-001\shard-2-001\cores\collection1\, coreNodeName=, dataDir=data\, shard=} [junit4] 2> 4497813 T20823 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 4AEFF95D39E657BA-001\shard-2-001\cores\collection1\ [junit4] 2> 4497813 T20823 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4497816 T20904 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4497816 T20904 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4497817 T20851 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4497817 T20904 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4497819 T20852 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:53556";, [junit4] 2> "node_name":"127.0.0.1:53556_", [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> 4497820 T20852 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:53556";, [junit4] 2> "node_name":"127.0.0.1:53556_", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4497820 T20852 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 4497820 T20852 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard3 [junit4] 2> 4497822 T20880 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> 4497822 T20903 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> 4497822 T20851 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> 4497822 T20863 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> 4498802 T20904 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4498802 T20904 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4498803 T20904 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4498803 T20904 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4498805 T20904 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4498805 T20904 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 4AEFF95D39E657BA-001\shard-2-001\cores\collection1\' [junit4] 2> 4498838 T20904 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4498842 T20904 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4498855 T20904 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4498858 T20904 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4498867 T20904 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4498869 T20904 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4498873 T20904 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4498992 T20904 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4498999 T20904 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4498999 T20904 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4499015 T20904 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4499021 T20904 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4499025 T20904 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4499027 T20904 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4499027 T20904 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4499027 T20904 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4499029 T20904 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4499030 T20904 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4499030 T20904 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4499030 T20904 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4499031 T20904 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 4AEFF95D39E657BA-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 4AEFF95D39E657BA-001\shard-2-001\cores\collection1\data\ [junit4] 2> 4499031 T20904 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@7f56422d [junit4] 2> 4499032 T20904 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 4AEFF95D39E657BA-001\shard-2-001\cores\collection1\data\ [junit4] 2> 4499032 T20904 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 4AEFF95D39E657BA-001\shard-2-001\cores\collection1\data\index/ [junit4] 2> 4499033 T20904 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 4AEFF95D39E657BA-001\shard-2-001\cores\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 4499033 T20904 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 4AEFF95D39E657BA-001\shard-2-001\cores\collection1\data\index [junit4] 2> 4499034 T20904 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=33, maxMergeSize=9223372036854775807, maxMe [...truncated too long message...] SpellCheckerTest, TestSerializedLuceneMatchVersion, DistributedQueueTest, DocValuesTest, UnloadDistributedZkTest, SOLR749Test, DistributedSuggestComponentTest, SearchHandlerTest, TestPerFieldSimilarity, TestSolr4Spatial, QueryResultKeyTest, TestSolrXml, TestFileDictionaryLookup, TestReqParamsAPI, TestRemoteStreaming, TestDistribDocBasedVersion, DistributedQueryComponentOptimizationTest, PreAnalyzedUpdateProcessorTest, TermsComponentTest, CoreMergeIndexesAdminHandlerTest, BasicZkTest, TestFaceting, OverriddenZkACLAndCredentialsProvidersTest, SuggesterWFSTTest, HdfsCollectionsAPIDistributedZkTest, ZkCLITest, SolrPluginUtilsTest, TestFieldSortValues, SpellCheckCollatorTest, TestDefaultSearchFieldResource, TestBM25SimilarityFactory, TestAddFieldRealTimeGet, DeleteShardTest, AliasIntegrationTest, TestCoreDiscovery, NumericFieldsTest, TestSystemIdResolver, TestClassNameShortening, HdfsRecoveryZkTest, SuggesterTest, TestCollationFieldDocValues, TestStandardQParsers, BasicDistributedZkTest, CustomCollectionTest, TestBlendedInfixSuggestions, TestComplexPhraseQParserPlugin, SolrTestCaseJ4Test, DistributedDebugComponentTest, HdfsBasicDistributedZkTest, CursorPagingTest, TestCloudPivotFacet, BJQParserTest, TestSolr4Spatial2, RequestLoggingTest, HardAutoCommitTest, RollingRestartTest, ZkSolrClientTest, TestRestManager, AddBlockUpdateTest, SoftAutoCommitTest, BufferStoreTest, HighlighterTest, CloudExitableDirectoryReaderTest, OverseerTest, TestJettySolrRunner, TestFreeTextSuggestions, BadComponentTest, MigrateRouteKeyTest, TestCollectionAPI, IgnoreCommitOptimizeUpdateProcessorFactoryTest, SuggesterTSTTest, TestIndexSearcher, TestMissingGroups, TestChildDocTransformer, ConvertedLegacyTest, TestSweetSpotSimilarityFactory, DocValuesMultiTest, SortByFunctionTest, HighlighterMaxOffsetTest, EchoParamsTest, TestJmxIntegration, TestCustomSort, TestNonDefinedSimilarityFactory, TestWriterPerf, TestInitParams, TestPartialUpdateDeduplication, TestSolrDeletionPolicy2, TestDefaultSimilarityFactory, TestFieldTypeCollectionResource, TestManagedSchemaDynamicFieldResource, HighlighterConfigTest, DirectUpdateHandlerTest, ZkControllerTest, TestShortCircuitedRequests, FileBasedSpellCheckerTest, TestPseudoReturnFields, TestManagedSchemaFieldResource, SharedFSAutoReplicaFailoverUtilsTest, TestDynamicLoading, ShowFileRequestHandlerTest, TestStressReorder, TestSolrConfigHandlerConcurrent, DistanceFunctionTest, SolrInfoMBeanTest, TestRecovery, TestManagedSynonymFilterFactory, SystemInfoHandlerTest, LeaderElectionIntegrationTest, TestSolrConfigHandlerCloud, TestPostingsSolrHighlighter, OpenCloseCoreStressTest, TestFastWriter, TestRecoveryHdfs, SolrIndexSplitterTest, TestGroupingSearch, ParsingFieldUpdateProcessorsTest, AutoCommitTest, CollectionsAPIAsyncDistributedZkTest, SpatialFilterTest, DateRangeFieldTest, TestSortingResponseWriter, FileUtilsTest, TestTolerantSearch, TestJmxMonitoredMap, TestManagedResource, TestMiniSolrCloudClusterSSL, SynonymTokenizerTest, TestLMJelinekMercerSimilarityFactory, TestXIncludeConfig, FastVectorHighlighterTest, LeaderFailoverAfterPartitionTest, TestComponentsName, TestShardHandlerFactory, TestQuerySenderListener, DistributedSpellCheckComponentTest, SpellingQueryConverterTest, TestUpdate, TestPHPSerializedResponseWriter, DistributedQueryElevationComponentTest, TestSolrDynamicMBean, DistributedFacetPivotSmallAdvancedTest, TestRTGBase, TestSort, HdfsChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, TestReplicationHandlerBackup, PolyFieldTest, QueryParsingTest, StressHdfsTest, TestUniqueKeyFieldResource, TestDefaultStatsCache, HdfsUnloadDistributedZkTest, LukeRequestHandlerTest, SimpleFacetsTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestTrie, DistributedFacetPivotLongTailTest, XsltUpdateRequestHandlerTest, MBeansHandlerTest, RecoveryZkTest, TestSchemaVersionResource, TestJoin, TestRandomMergePolicy, BlockCacheTest, TestSolrQueryParserResource, TermVectorComponentTest, ActionThrottleTest, TestReversedWildcardFilterFactory, CurrencyFieldOpenExchangeTest, SolrIndexConfigTest, TestFieldTypeResource, TestCSVResponseWriter, DistributedQueryComponentCustomSortTest, TestCloudManagedSchemaConcurrent, TestCodecSupport, DistributedExpandComponentTest, TestTrackingShardHandlerFactory, TestConfigReload, TestConfig, TestHighlightDedupGrouping, DocExpirationUpdateProcessorFactoryTest, TestCloudInspectUtil, SaslZkACLProviderTest, AtomicUpdatesTest, PathHierarchyTokenizerFactoryTest, TestConfigSets, DistributedFacetPivotWhiteBoxTest, OverseerRolesTest, TestExpandComponent, PeerSyncTest, TestQuerySenderNoQuery, UpdateRequestProcessorFactoryTest, RecoveryAfterSoftCommitTest, BlockDirectoryTest, OverseerStatusTest, PrimUtilsTest, SpellCheckComponentTest, AsyncMigrateRouteKeyTest, DirectSolrSpellCheckerTest, TestBlobHandler, TestSchemaSimilarityResource, SolrRequestParserTest, TestRebalanceLeaders, ShardRoutingCustomTest, DocumentBuilderTest, CacheHeaderTest, ResponseLogComponentTest, TestAnalyzedSuggestions, MultiTermTest, ResponseHeaderTest, TestCollationField, DistributedMLTComponentTest, CircularListTest, TestCSVLoader, TestOrdValues, ChaosMonkeySafeLeaderTest, CollectionsAPIDistributedZkTest, SyncSliceTest, ShardRoutingTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, TestZkChroot, TestRandomDVFaceting, TestDistributedSearch, TestDistributedGrouping, TestHashPartitioner, TermVectorComponentDistributedTest, TestRealTimeGet, TestReload, TestStressVersions, DistributedTermsComponentTest, TestRangeQuery, TestCoreContainer, SolrCoreTest, StatsComponentTest, SolrCmdDistributorTest, QueryElevationComponentTest, BadIndexSchemaTest, TestFiltering, TestFunctionQuery, BasicFunctionalityTest, TestLazyCores, CurrencyFieldXmlFileTest, SimplePostToolTest, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, TestExtendedDismaxParser, SuggesterFSTTest, CoreAdminHandlerTest, TestFoldingMultitermQuery, TestStressLucene, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, TestSolrDeletionPolicy1, DebugComponentTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest, DocumentAnalysisRequestHandlerTest, TestQueryTypes, TestOmitPositions, XmlUpdateRequestHandlerTest, RequestHandlersTest, TestValueSourceCache, TestIndexingPerformance, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestSolrQueryParser, TestArbitraryIndexDir, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, RegexBoostProcessorTest, IndexSchemaTest, JSONWriterTest, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, PingRequestHandlerTest, CSVRequestHandlerTest, TestBinaryResponseWriter, TestLFUCache, UpdateParamsTest, AlternateDirectoryTest, TestSolrIndexConfig, CopyFieldTest, TestStressRecovery, TestMergePolicyConfig, SampleTest, TestDocSet, TestBinaryField, TestSearchPerf, MinimalSchemaTest, TestElisionMultitermQuery, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, TestSolrCoreProperties, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, SpellPossibilityIteratorTest, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TimeZoneUtilsTest, ResourceLoaderTest, TestFastOutputStream, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, PreAnalyzedFieldTest, TestSuggestSpellingConverter, DateFieldTest, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, TestSolrJ, TestLRUCache, TestUtils, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, UUIDFieldTest, DistributedIntervalFacetingTest, TestCursorMarkWithoutUniqueKey, TestDistributedMissingSort, TestSimpleTrackingShardHandler, AssignTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DeleteReplicaTest, DistribCursorPagingTest, DistribDocExpirationUpdateProcessorTest, DistribJoinFromCollectionTest, ExternalCollectionsTest, HttpPartitionTest, LeaderInitiatedRecoveryOnCommitTest, MultiThreadedOCPTest, OutOfBoxZkACLAndCredentialsProvidersTest, RemoteQueryErrorTest, ReplicationFactorTest] [junit4] Completed in 61.62s, 1 test, 1 error <<< FAILURES! [...truncated 277 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: 471 suites, 1872 tests, 1 error, 57 ignored (30 assumptions) Total time: 121 minutes 41 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.8.0_31 -XX:+UseCompressedOops -XX:+UseG1GC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
