Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4333/ Java: 32bit/jdk1.7.0_76 -server -XX:+UseConcMarkSweepGC
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:58677/repfacttest_c8n_1x3_shard1_replica3 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:58677/repfacttest_c8n_1x3_shard1_replica3 at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:575) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:884) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:787) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:730) at org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:263) at org.apache.solr.cloud.ReplicationFactorTest.test(ReplicationFactorTest.java:110) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:940) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:915) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:58677/repfacttest_c8n_1x3_shard1_replica3 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:572) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:572) ... 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:464) ... 52 more Build Log: [...truncated 20245 lines...] [junit4] Suite: org.apache.solr.cloud.ReplicationFactorTest [junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\init-core-data-001 [junit4] 2> 5255193 T18392 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 5255201 T18392 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 5255202 T18393 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 5255326 T18392 oasc.ZkTestServer.run start zk server on port:58637 [junit4] 2> 5255327 T18392 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5255332 T18392 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5255345 T18400 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@42608e name:ZooKeeperConnection Watcher:127.0.0.1:58637 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5255347 T18392 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5255347 T18392 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5255348 T18392 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 5255355 T18392 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5255358 T18392 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5255361 T18403 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1da5bcd name:ZooKeeperConnection Watcher:127.0.0.1:58637/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5255361 T18392 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5255362 T18392 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5255362 T18392 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 5255370 T18392 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 5255375 T18392 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 5255379 T18392 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 5255385 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 5255387 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 5255395 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml [junit4] 2> 5255396 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 5255403 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 5255404 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 5255409 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 5255410 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 5255417 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 5255417 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 5255423 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4] 2> 5255424 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 5255430 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 5255430 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 5255440 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 5255440 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 5255448 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 5255449 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 5255455 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 5255455 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 5255461 T18392 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 5255462 T18392 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 5257768 T18392 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\control-001\cores\collection1 [junit4] 2> 5257775 T18392 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5257792 T18392 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58645 [junit4] 2> 5257792 T18392 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@1e91485 [junit4] 2> 5257793 T18392 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5257793 T18392 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\control-001 [junit4] 2> 5257795 T18392 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\control-001\' [junit4] 2> 5257870 T18392 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\control-001\solr.xml [junit4] 2> 5257917 T18392 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\control-001\cores [junit4] 2> 5257918 T18392 oasc.CoreContainer.<init> New CoreContainer 26600908 [junit4] 2> 5257918 T18392 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\control-001\] [junit4] 2> 5257921 T18392 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5257921 T18392 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5257922 T18392 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5257923 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5257923 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5257925 T18392 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5257925 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5257925 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5257926 T18392 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5257926 T18392 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5257926 T18392 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 5257927 T18392 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 5257927 T18392 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5257929 T18392 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5257929 T18392 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5257929 T18392 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58637/solr [junit4] 2> 5257930 T18392 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5257930 T18392 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5257934 T18392 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5257937 T18415 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2f590d name:ZooKeeperConnection Watcher:127.0.0.1:58637 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5257938 T18392 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5257939 T18392 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5257946 T18392 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5257950 T18418 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@177377c name:ZooKeeperConnection Watcher:127.0.0.1:58637/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5257951 T18392 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5257955 T18392 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 5257965 T18392 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 5257974 T18392 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 5257985 T18392 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 5257993 T18392 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 5258001 T18392 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 5258005 T18392 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58644_ [junit4] 2> 5258006 T18392 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58644_ [junit4] 2> 5258014 T18392 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 5258020 T18392 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 5258026 T18392 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5258032 T18392 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:58644_ [junit4] 2> 5258033 T18392 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 5258041 T18392 oasc.Overseer.start Overseer (id=93204959226560515-127.0.0.1:58644_-n_0000000000) starting [junit4] 2> 5258048 T18392 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 5258068 T18392 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 5258072 T18392 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 5258072 T18420 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 5258076 T18392 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 5258080 T18392 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5258092 T18419 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 5258095 T18392 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\control-001\cores [junit4] 2> 5258095 T18392 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\control-001\cores\collection1\ [junit4] 2> 5258099 T18392 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 5258101 T18422 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 5258101 T18422 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5258104 T18418 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5258104 T18422 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5258112 T18419 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:58644_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:58644";, [junit4] 2> "state":"down", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 5258115 T18419 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:58644_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"1", [junit4] 2> "base_url":"http://127.0.0.1:58644";, [junit4] 2> "state":"down", [junit4] 2> "operation":"state"} [junit4] 2> 5258115 T18419 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 5258115 T18419 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 5258119 T18418 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> 5259474 T18422 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5259474 T18422 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 5259478 T18422 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5259478 T18422 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 5259485 T18422 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5259486 T18422 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\control-001\cores\collection1\' [junit4] 2> 5259536 T18422 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5259542 T18422 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5259562 T18422 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5259600 T18422 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 5259671 T18422 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5259676 T18422 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5259691 T18422 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5260393 T18422 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5260405 T18422 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5260409 T18422 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5260440 T18422 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5260448 T18422 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5260456 T18422 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5260459 T18422 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5260459 T18422 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5260459 T18422 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5260464 T18422 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5260466 T18422 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5260466 T18422 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 5260467 T18422 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5260467 T18422 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\control-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/control/data\ [junit4] 2> 5260468 T18422 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@bd49f0 [junit4] 2> 5260470 T18422 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/control/data\ [junit4] 2> 5260470 T18422 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/control/data\index/ [junit4] 2> 5260470 T18422 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001\control\data\index' doesn't exist. Creating new index... [junit4] 2> 5260471 T18422 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/control/data\index [junit4] 2> 5260472 T18422 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 5260474 T18422 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d91d75 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0560),segFN=segments_1,generation=1} [junit4] 2> 5260474 T18422 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5260480 T18422 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5260482 T18422 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5260483 T18422 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5260483 T18422 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5260483 T18422 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5260484 T18422 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5260486 T18422 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5260486 T18422 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5260486 T18422 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5260487 T18422 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5260488 T18422 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5260488 T18422 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5260490 T18422 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5260491 T18422 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 5260491 T18422 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 5260492 T18422 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 5260494 T18422 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 5260495 T18422 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 5260496 T18422 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 5260498 T18422 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 5260499 T18422 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 5260499 T18422 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 5260500 T18422 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 5260502 T18422 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 5260503 T18422 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 5260503 T18422 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5260506 T18422 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5260519 T18422 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5260522 T18422 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5260525 T18422 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5260529 T18422 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5260531 T18422 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 5260538 T18422 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5260538 T18422 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5260541 T18422 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=13, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=33.3203125, floorSegmentMB=0.3203125, forceMergeDeletesPctAllowed=20.918326864791897, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15753032003688772 [junit4] 2> 5260541 T18422 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@d91d75 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1d0560),segFN=segments_1,generation=1} [junit4] 2> 5260541 T18422 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5260542 T18422 oass.SolrIndexSearcher.<init> Opening Searcher@ad59fb[collection1] main [junit4] 2> 5260542 T18422 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 5260549 T18422 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5260549 T18422 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5260550 T18422 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5260551 T18422 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5260551 T18422 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5260554 T18422 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 5260554 T18422 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5260554 T18422 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5260554 T18422 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 5260555 T18422 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5260555 T18423 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@ad59fb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5260558 T18426 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:58644 collection:control_collection shard:shard1 [junit4] 2> 5260558 T18392 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 5260558 T18392 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5260561 T18392 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5260562 T18426 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 5260565 T18392 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5260570 T18430 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@196203c name:ZooKeeperConnection Watcher:127.0.0.1:58637/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5260571 T18392 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5260573 T18392 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5260577 T18392 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5260582 T18426 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 5260586 T18418 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5260587 T18426 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5260587 T18426 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C8730 name=collection1 org.apache.solr.core.SolrCore@1428d45 url=http://127.0.0.1:58644/collection1 node=127.0.0.1:58644_ C8730_STATE=coll:control_collection core:collection1 props:{node_name=127.0.0.1:58644_, core=collection1, base_url=http://127.0.0.1:58644, state=down} [junit4] 2> 5260587 T18426 C8730 P58644 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:58644/collection1/ [junit4] 2> 5260587 T18426 C8730 P58644 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5260587 T18426 C8730 P58644 oasc.SyncStrategy.syncToMe http://127.0.0.1:58644/collection1/ has no replicas [junit4] 2> 5260587 T18426 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58644/collection1/ shard1 [junit4] 2> 5260589 T18426 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 5260589 T18419 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> 5260590 T18392 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 5260595 T18418 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> 5260597 T18430 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> 5260620 T18419 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:58644";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 5260628 T18431 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5260630 T18430 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> 5260690 T18426 oasc.ZkController.register We are http://127.0.0.1:58644/collection1/ and leader is http://127.0.0.1:58644/collection1/ [junit4] 2> 5260690 T18426 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58644 [junit4] 2> 5260690 T18426 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5260690 T18426 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 5260690 T18426 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5260693 T18418 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5260700 T18419 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "shard":"shard1", [junit4] 2> "node_name":"127.0.0.1:58644_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"3", [junit4] 2> "base_url":"http://127.0.0.1:58644";, [junit4] 2> "state":"active", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 5260701 T18419 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "shard":"shard1", [junit4] 2> "node_name":"127.0.0.1:58644_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"3", [junit4] 2> "base_url":"http://127.0.0.1:58644";, [junit4] 2> "state":"active", [junit4] 2> "operation":"state"} [junit4] 2> 5260848 T18418 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> 5260850 T18430 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> 5263150 T18392 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-1-001\cores\collection1 [junit4] 2> 5263157 T18392 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 5263158 T18392 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5263163 T18392 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58658 [junit4] 2> 5263166 T18392 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@1e91485 [junit4] 2> 5263166 T18392 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5263166 T18392 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-1-001 [junit4] 2> 5263166 T18392 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-1-001\' [junit4] 2> 5263237 T18392 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-1-001\solr.xml [junit4] 2> 5263278 T18392 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-1-001\cores [junit4] 2> 5263278 T18392 oasc.CoreContainer.<init> New CoreContainer 11444061 [junit4] 2> 5263278 T18392 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-1-001\] [junit4] 2> 5263282 T18392 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5263282 T18392 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5263282 T18392 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5263282 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5263282 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5263284 T18392 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5263284 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5263284 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5263284 T18392 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5263284 T18392 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5263288 T18392 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 5263288 T18392 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 5263288 T18392 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5263288 T18392 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5263288 T18392 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5263288 T18392 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58637/solr [junit4] 2> 5263288 T18392 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5263288 T18392 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5263294 T18392 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5263300 T18443 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@916694 name:ZooKeeperConnection Watcher:127.0.0.1:58637 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5263301 T18392 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5263301 T18392 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5263306 T18392 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5263309 T18446 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1e969f name:ZooKeeperConnection Watcher:127.0.0.1:58637/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5263310 T18392 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5263327 T18392 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5264710 T18392 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58657_ [junit4] 2> 5264715 T18392 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58657_ [junit4] 2> 5264740 T18392 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5264750 T18392 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-1-001\cores [junit4] 2> 5264752 T18392 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-1-001\cores\collection1\ [junit4] 2> 5264752 T18392 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 5264756 T18447 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 5264756 T18447 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5264758 T18447 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5264758 T18418 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5264762 T18419 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:58657_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "base_url":"http://127.0.0.1:58657";, [junit4] 2> "state":"down", [junit4] 2> "operation":"state"} current state version: 4 [junit4] 2> 5264764 T18419 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:58657_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "base_url":"http://127.0.0.1:58657";, [junit4] 2> "state":"down", [junit4] 2> "operation":"state"} [junit4] 2> 5264764 T18419 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 5264764 T18419 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard3 [junit4] 2> 5264768 T18446 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> 5264769 T18418 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> 5264769 T18430 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> 5266130 T18447 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5266130 T18447 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 5266134 T18447 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5266135 T18447 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5266141 T18447 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5266141 T18447 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-1-001\cores\collection1\' [junit4] 2> 5266194 T18447 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5266202 T18447 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5266222 T18447 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5266316 T18447 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 5266388 T18447 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5266392 T18447 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5266407 T18447 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5266989 T18447 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5267001 T18447 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5267006 T18447 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5267034 T18447 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5267042 T18447 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5267052 T18447 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5267054 T18447 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5267056 T18447 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5267056 T18447 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5267060 T18447 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5267060 T18447 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5267060 T18447 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 5267061 T18447 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5267061 T18447 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-1-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/jetty1\ [junit4] 2> 5267062 T18447 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@bd49f0 [junit4] 2> 5267064 T18447 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/jetty1\ [junit4] 2> 5267064 T18447 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/jetty1\index/ [junit4] 2> 5267065 T18447 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001\jetty1\index' doesn't exist. Creating new index... [junit4] 2> 5267067 T18447 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/jetty1\index [junit4] 2> 5267068 T18447 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 5267069 T18447 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5c0021 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c8b3a),segFN=segments_1,generation=1} [junit4] 2> 5267071 T18447 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5267080 T18447 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5267081 T18447 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5267081 T18447 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5267083 T18447 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5267083 T18447 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5267083 T18447 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5267084 T18447 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5267085 T18447 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5267085 T18447 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5267087 T18447 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5267087 T18447 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5267088 T18447 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5267088 T18447 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5267089 T18447 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 5267091 T18447 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 5267092 T18447 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 5267092 T18447 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 5267093 T18447 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 5267096 T18447 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 5267096 T18447 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 5267097 T18447 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 5267099 T18447 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 5267100 T18447 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 5267100 T18447 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 5267103 T18447 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 5267104 T18447 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5267104 T18447 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5267117 T18447 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5267121 T18447 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5267121 T18447 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5267129 T18447 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5267132 T18447 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 5267136 T18447 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5267136 T18447 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5267137 T18447 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=13, maxMergeAtOnceExplicit=45, maxMergedSegmentMB=33.3203125, floorSegmentMB=0.3203125, forceMergeDeletesPctAllowed=20.918326864791897, segmentsPerTier=20.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.15753032003688772 [junit4] 2> 5267139 T18447 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@5c0021 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@c8b3a),segFN=segments_1,generation=1} [junit4] 2> 5267139 T18447 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5267140 T18447 oass.SolrIndexSearcher.<init> Opening Searcher@472c75[collection1] main [junit4] 2> 5267140 T18447 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5267143 T18447 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5267143 T18447 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5267144 T18447 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5267144 T18447 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5267144 T18447 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5267147 T18447 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 5267147 T18447 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5267148 T18447 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5267148 T18447 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 5267149 T18448 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@472c75[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5267151 T18447 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5267152 T18451 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:58657 collection:collection1 shard:shard3 [junit4] 2> 5267153 T18392 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 5267153 T18392 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5267156 T18451 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election [junit4] 2> 5267174 T18451 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3 [junit4] 2> 5267178 T18418 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5267180 T18451 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5267180 T18451 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C8731 name=collection1 org.apache.solr.core.SolrCore@99c30f url=http://127.0.0.1:58657/collection1 node=127.0.0.1:58657_ C8731_STATE=coll:collection1 core:collection1 props:{node_name=127.0.0.1:58657_, core=collection1, base_url=http://127.0.0.1:58657, state=down} [junit4] 2> 5267180 T18451 C8731 P58657 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:58657/collection1/ [junit4] 2> 5267180 T18451 C8731 P58657 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5267180 T18451 C8731 P58657 oasc.SyncStrategy.syncToMe http://127.0.0.1:58657/collection1/ has no replicas [junit4] 2> 5267180 T18451 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:58657/collection1/ shard3 [junit4] 2> 5267183 T18451 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3 [junit4] 2> 5267186 T18419 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard3", [junit4] 2> "collection":"collection1"} current state version: 5 [junit4] 2> 5267191 T18430 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> 5267192 T18446 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> 5267194 T18418 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> 5267212 T18419 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard3", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:58657";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 6 [junit4] 2> 5267219 T18431 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5267362 T18446 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> 5267364 T18430 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> 5267428 T18451 oasc.ZkController.register We are http://127.0.0.1:58657/collection1/ and leader is http://127.0.0.1:58657/collection1/ [junit4] 2> 5267428 T18451 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:58657 [junit4] 2> 5267428 T18451 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5267428 T18451 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 5267429 T18451 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5267433 T18418 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5267437 T18419 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "shard":"shard3", [junit4] 2> "node_name":"127.0.0.1:58657_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "base_url":"http://127.0.0.1:58657";, [junit4] 2> "state":"active", [junit4] 2> "operation":"state"} current state version: 7 [junit4] 2> 5267440 T18419 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "shard":"shard3", [junit4] 2> "node_name":"127.0.0.1:58657_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "base_url":"http://127.0.0.1:58657";, [junit4] 2> "state":"active", [junit4] 2> "operation":"state"} [junit4] 2> 5267591 T18418 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> 5267591 T18430 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> 5267592 T18446 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> 5269739 T18392 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-2-001\cores\collection1 [junit4] 2> 5269748 T18392 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 5269751 T18392 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5269759 T18392 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:58668 [junit4] 2> 5269764 T18392 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@1e91485 [junit4] 2> 5269764 T18392 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5269767 T18392 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-2-001 [junit4] 2> 5269767 T18392 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-2-001\' [junit4] 2> 5269856 T18392 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-2-001\solr.xml [junit4] 2> 5269919 T18392 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-2-001\cores [junit4] 2> 5269920 T18392 oasc.CoreContainer.<init> New CoreContainer 20416 [junit4] 2> 5269920 T18392 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-2-001\] [junit4] 2> 5269923 T18392 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5269923 T18392 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5269925 T18392 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5269925 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5269925 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5269926 T18392 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5269926 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5269928 T18392 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5269928 T18392 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5269928 T18392 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5269929 T18392 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 5269929 T18392 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 5269931 T18392 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5269931 T18392 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5269932 T18392 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5269932 T18392 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:58637/solr [junit4] 2> 5269932 T18392 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5269934 T18392 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5269937 T18392 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5269941 T18464 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@da6b07 name:ZooKeeperConnection Watcher:127.0.0.1:58637 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5269942 T18392 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5269944 T18392 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5269953 T18392 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5269957 T18467 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a18138 name:ZooKeeperConnection Watcher:127.0.0.1:58637/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5269959 T18392 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5269979 T18392 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5271492 T18392 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:58667_ [junit4] 2> 5271500 T18392 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:58667_ [junit4] 2> 5271520 T18392 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5271535 T18392 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-2-001\cores [junit4] 2> 5271539 T18392 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-2-001\cores\collection1\ [junit4] 2> 5271539 T18392 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 5271543 T18468 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 5271545 T18468 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5271548 T18468 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5271548 T18418 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5271552 T18419 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:58667_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "base_url":"http://127.0.0.1:58667";, [junit4] 2> "state":"down", [junit4] 2> "operation":"state"} current state version: 8 [junit4] 2> 5271555 T18419 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "shard":null, [junit4] 2> "node_name":"127.0.0.1:58667_", [junit4] 2> "roles":null, [junit4] 2> "core":"collection1", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "base_url":"http://127.0.0.1:58667";, [junit4] 2> "state":"down", [junit4] 2> "operation":"state"} [junit4] 2> 5271555 T18419 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 5271555 T18419 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 5271560 T18418 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> 5271561 T18430 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> 5271561 T18446 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> 5271561 T18467 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> 5273048 T18468 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5273048 T18468 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 5273052 T18468 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5273052 T18468 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5273058 T18468 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5273058 T18468 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-2-001\cores\collection1\' [junit4] 2> 5273120 T18468 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5273127 T18468 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5273155 T18468 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5273193 T18468 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 5273273 T18468 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5273278 T18468 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5273291 T18468 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5274025 T18468 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5274039 T18468 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5274045 T18468 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5274070 T18468 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5274079 T18468 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5274089 T18468 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5274092 T18468 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5274092 T18468 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5274092 T18468 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5274098 T18468 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5274098 T18468 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5274100 T18468 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 5274100 T18468 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5274101 T18468 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\shard-2-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/jetty2\ [junit4] 2> 5274101 T18468 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@bd49f0 [junit4] 2> 5274102 T18468 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/jetty2\ [junit4] 2> 5274104 T18468 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/jetty2\index/ [junit4] 2> 5274104 T18468 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001\jetty2\index' doesn't exist. Creating new index... [junit4] 2> 5274105 T18468 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 720D6B660013774A-001\tempDir-001/jetty2\index [junit4] 2> 5274105 T18468 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=28, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 5274107 T18468 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@6fb6ea lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@51c200),segFN=segments_1,generation=1} [junit4] 2> 5274108 T18468 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5274116 T18468 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5274117 T18468 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5274119 T18468 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5274119 T18468 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5274119 T18468 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5274120 T18468 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5274123 T18468 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5274123 T18468 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5274123 T18468 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5274124 T18468 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5274126 T18468 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5274126 T18468 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5274127 T18468 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5274129 T18468 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 5274130 T18468 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 5274132 T18468 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 5274133 T18468 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 5274133 T18468 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 5274136 T18468 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 5274138 T18468 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 5274138 T18468 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 5274139 T18468 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 5274141 T18468 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 5274142 T18468 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 5274142 T18468 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 5274143 T18468 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5274145 T18468 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5274160 T18468 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5274164 T18468 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5274167 T18468 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5274170 T18468 [...truncated too long message...] timestamp=DocValuesFormat(name=Direct)}, sim=DefaultSimilarity, locale=sr__#Latn, timezone=Pacific/Easter [junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.7.0_76 (32-bit)/cpus=2,threads=1,free=220636480,total=523501568 [junit4] 2> NOTE: All tests run in this JVM: [TestRecovery, QueryElevationComponentTest, ParsingFieldUpdateProcessorsTest, TestRawResponseWriter, DistribCursorPagingTest, CoreAdminRequestStatusTest, TestSolrQueryParser, TestComplexPhraseQParserPlugin, SynonymTokenizerTest, OverseerTest, TestCSVResponseWriter, RegexBoostProcessorTest, TestSort, TestFreeTextSuggestions, TestCloudManagedSchemaConcurrent, SolrTestCaseJ4Test, DocValuesMultiTest, TestConfigOverlay, CollectionsAPIAsyncDistributedZkTest, TestInfoStreamLogging, TestRealTimeGet, OpenCloseCoreStressTest, TestQueryTypes, MoreLikeThisHandlerTest, SpellCheckComponentTest, ClusterStateUpdateTest, ZkStateWriterTest, TestLFUCache, TestExtendedDismaxParser, TestSolrConfigHandlerCloud, TestExactSharedStatsCache, MultiThreadedOCPTest, DefaultValueUpdateProcessorTest, MBeansHandlerTest, DateMathParserTest, TestChildDocTransformer, DistributedQueueTest, ChaosMonkeySafeLeaderTest, TestStandardQParsers, TestPHPSerializedResponseWriter, TestCloudPivotFacet, PathHierarchyTokenizerFactoryTest, TestLMJelinekMercerSimilarityFactory, TestRangeQuery, TestAnalyzedSuggestions, TestXIncludeConfig, AnalysisAfterCoreReloadTest, RAMDirectoryFactoryTest, TimeZoneUtilsTest, TestRandomFaceting, TestManagedSchemaFieldTypeResource, TestStressRecovery, DistributedQueryComponentCustomSortTest, TestSearchPerf, SolrRequestParserTest, AddBlockUpdateTest, ZkControllerTest, AlternateDirectoryTest, EnumFieldTest, TestShardHandlerFactory, ShowFileRequestHandlerTest, HighlighterTest, SimpleFacetsTest, SimpleCollectionCreateDeleteTest, TestMiniSolrCloudCluster, HdfsBasicDistributedZkTest, SystemInfoHandlerTest, TestQueryUtils, CollectionsAPIDistributedZkTest, TestAnalyzeInfixSuggestions, TestManagedSynonymFilterFactory, TestComponentsName, DateFieldTest, HardAutoCommitTest, ReturnFieldsTest, ExternalFileFieldSortTest, SuggesterTest, RecoveryZkTest, TestPartialUpdateDeduplication, TestIntervalFaceting, LukeRequestHandlerTest, LeaderFailoverAfterPartitionTest, DistributedSuggestComponentTest, AssignTest, HighlighterConfigTest, TriLevelCompositeIdRoutingTest, SolrPluginUtilsTest, TestSuggestSpellingConverter, TestCoreDiscovery, TestNoOpRegenerator, StatsComponentTest, ChaosMonkeyNothingIsSafeTest, DOMUtilTest, DocumentBuilderTest, FileUtilsTest, TestObjectReleaseTracker, TestManagedSchemaFieldResource, DocValuesMissingTest, URLClassifyProcessorTest, TestReRankQParserPlugin, PrimitiveFieldTypeTest, CSVRequestHandlerTest, TestManagedSchema, AliasIntegrationTest, RankQueryTest, DebugComponentTest, ResponseHeaderTest, AddSchemaFieldsUpdateProcessorFactoryTest, BJQParserTest, CursorPagingTest, DistributedFacetPivotLargeTest, TestSolrQueryParserResource, TestAtomicUpdateErrorCases, OverseerRolesTest, TestRemoteStreaming, TestSearcherReuse, TestFieldSortValues, IndexBasedSpellCheckerTest, TestMissingGroups, SharedFSAutoReplicaFailoverUtilsTest, TestFieldTypeResource, OutOfBoxZkACLAndCredentialsProvidersTest, TestWriterPerf, TestWordDelimiterFilterFactory, CachingDirectoryFactoryTest, InfoHandlerTest, SpatialRPTFieldTypeTest, HdfsSyncSliceTest, OverseerStatusTest, TestDynamicFieldResource, NoCacheHeaderTest, TestFieldTypeCollectionResource, TestBulkSchemaAPI, UniqFieldsUpdateProcessorFactoryTest, TestSolrDeletionPolicy2, TestSchemaNameResource, TestValueSourceCache, TestUtils, NumericFieldsTest, TestPerFieldSimilarity, RequiredFieldsTest, LoggingHandlerTest, TestHashPartitioner, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestFoldingMultitermQuery, TestCoreContainer, MultiTermTest, ChangedSchemaMergeTest, TestManagedResource, TestCloudInspectUtil, TestIndexingPerformance, PolyFieldTest, ConnectionManagerTest, TestFastOutputStream, TestSolr4Spatial, TestShortCircuitedRequests, TestReplicationHandler, TestRTGBase, VMParamsZkACLAndCredentialsProvidersTest, TestSolrJ, TestQuerySenderNoQuery, TestDFRSimilarityFactory, TestSchemaManager, TestDistributedMissingSort, BlockDirectoryTest, DistributedQueryElevationComponentTest, BadIndexSchemaTest, TestLeaderElectionZkExpiry, PluginInfoTest, TestMaxScoreQueryParser, CacheHeaderTest, TestPostingsSolrHighlighter, SchemaVersionSpecificBehaviorTest, TestOrdValues, ConvertedLegacyTest, TestRandomDVFaceting, TestLRUStatsCache, DistributedQueryComponentOptimizationTest, TestReversedWildcardFilterFactory, SolrCoreCheckLockOnStartupTest, ShardRoutingCustomTest, SpellPossibilityIteratorTest, HdfsDirectoryTest, TestPseudoReturnFields, CurrencyFieldOpenExchangeTest, FieldAnalysisRequestHandlerTest, TestDefaultStatsCache, TermsComponentTest, DirectUpdateHandlerOptimizeTest, TestFileDictionaryLookup, TestSolrXml, TestJmxMonitoredMap, PreAnalyzedFieldTest, TestSimpleQParserPlugin, TestSystemIdResolver, SpatialFilterTest, AsyncMigrateRouteKeyTest, PrimUtilsTest, SuggesterTSTTest, SSLMigrationTest, TestZkChroot, TestDynamicLoading, TestReload, TestTolerantSearch, SortByFunctionTest, HighlighterMaxOffsetTest, TermVectorComponentTest, MinimalSchemaTest, BinaryUpdateRequestHandlerTest, SimpleMLTQParserTest, DateRangeFieldTest, ShardSplitTest, TestLazyCores, ResourceLoaderTest, TestSolrCoreProperties, DirectSolrConnectionTest, TestCloudSchemaless, TestNRTOpen, RecoveryAfterSoftCommitTest, HdfsDirectoryFactoryTest, ScriptEngineTest, TestOverriddenPrefixQueryForCustomFieldType, BasicDistributedZkTest, BasicDistributedZk2Test, UnloadDistributedZkTest, SyncSliceTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, FullSolrCloudDistribCmdsTest, LeaderElectionTest, ZkSolrClientTest, ZkCLITest, TestDistributedSearch, AutoCommitTest, TestDistributedGrouping, TestFaceting, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestStressReorder, TestJoin, TestStressVersions, DistributedTermsComponentTest, SolrCoreTest, TestGroupingSearch, SolrCmdDistributorTest, PeerSyncTest, TestFiltering, TestFunctionQuery, BasicFunctionalityTest, DirectUpdateHandlerTest, TestBadConfig, OverseerCollectionProcessorTest, TestIndexSearcher, SoftAutoCommitTest, CurrencyFieldXmlFileTest, SolrIndexSplitterTest, SimplePostToolTest, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, SuggesterFSTTest, CoreAdminHandlerTest, DocValuesTest, TestStressLucene, TestTrie, SuggesterWFSTTest, TestCSVLoader, WordBreakSolrSpellCheckerTest, TestUpdate, FieldMutatingUpdateProcessorTest, QueryEqualityTest, StatelessScriptUpdateProcessorFactoryTest, DistanceFunctionTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, TestSurroundQueryParser, DisMaxRequestHandlerTest, StandardRequestHandlerTest, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, TestOmitPositions, FileBasedSpellCheckerTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, TestArbitraryIndexDir, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, IndexSchemaTest, TestJmxIntegration, TestCollationField, UpdateRequestProcessorFactoryTest, JSONWriterTest, QueryParsingTest, JsonLoaderTest, PingRequestHandlerTest, SearchHandlerTest, TestBinaryResponseWriter, SOLR749Test, TestQuerySenderListener, UpdateParamsTest, TestSolrIndexConfig, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestMergePolicyConfig, SampleTest, TestDocSet, TestBinaryField, TestElisionMultitermQuery, TestConfig, OutputWriterTest, TestFuzzyAnalyzedSuggestions, TestPhraseSuggestions, TestCharFilters, TestCodecSupport, EchoParamsTest, TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, TestIBSimilarityFactory, TestBM25SimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, OpenExchangeRatesOrgProviderTest, TestFastLRUCache, SpellingQueryConverterTest, ClusterStateTest, TestLRUCache, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, UUIDFieldTest, CircularListTest, DistributedIntervalFacetingTest, TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, CloudExitableDirectoryReaderTest, CustomCollectionTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteReplicaTest, DeleteShardTest, DistribDocExpirationUpdateProcessorTest, ExternalCollectionsTest, HttpPartitionTest, LeaderInitiatedRecoveryOnCommitTest, MigrateRouteKeyTest, OverriddenZkACLAndCredentialsProvidersTest, RemoteQueryErrorTest, ReplicationFactorTest] [junit4] Completed in 104.20s, 1 test, 1 error <<< FAILURES! [...truncated 306 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:529: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:477: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\build.xml:61: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\extra-targets.xml:39: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build.xml:190: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:508: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1363: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:970: There were test failures: 461 suites, 1846 tests, 1 error, 54 ignored (29 assumptions) Total time: 151 minutes 0 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/jdk1.7.0_76 -server -XX:+UseConcMarkSweepGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org
