Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4301/ Java: 64bit/jdk1.7.0_72 -XX:-UseCompressedOops -XX:+UseParallelGC
1 tests failed. FAILED: org.apache.solr.cloud.ReplicationFactorTest.testDistribSearch Error Message: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:51554/repfacttest_c8n_1x3_shard1_replica2 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:51554/repfacttest_c8n_1x3_shard1_replica2 at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:581) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:890) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:793) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:736) at org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:276) at org.apache.solr.cloud.ReplicationFactorTest.doTest(ReplicationFactorTest.java:123) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868) at sun.reflect.GeneratedMethodAccessor64.invoke(Unknown Source) 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 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:51554/repfacttest_c8n_1x3_shard1_replica2 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:353) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:312) at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:578) ... 45 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) ... 50 more Build Log: [...truncated 10106 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 8A69D463757EDC73-001\init-core-data-001 [junit4] 2> 5120183 T18014 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 5120186 T18014 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 5120188 T18014 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 5120189 T18015 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 5120282 T18014 oasc.ZkTestServer.run start zk server on port:51547 [junit4] 2> 5120282 T18014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5120284 T18014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5120292 T18022 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f74b04d name:ZooKeeperConnection Watcher:127.0.0.1:51547 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5120293 T18014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5120294 T18014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5120294 T18014 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 5120301 T18016 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14ad0e2bbf20000, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 5120302 T18014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5120304 T18014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5120308 T18025 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3fe48362 name:ZooKeeperConnection Watcher:127.0.0.1:51547/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5120309 T18014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5120309 T18014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5120309 T18014 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 5120314 T18014 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 5120319 T18014 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 5120321 T18014 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 5120324 T18014 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> 5120324 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 5120331 T18014 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> 5120331 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 5120336 T18014 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> 5120337 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 5120341 T18014 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> 5120343 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 5120347 T18014 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> 5120348 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 5120351 T18014 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> 5120351 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 5120357 T18014 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> 5120357 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 5120362 T18014 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> 5120363 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 5120367 T18014 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> 5120368 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 5120374 T18014 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> 5120374 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 5120378 T18014 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> 5120378 T18014 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 5120384 T18016 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14ad0e2bbf20001, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 5121810 T18014 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5121814 T18014 oejs.AbstractConnector.doStart Started [email protected]:51555 [junit4] 2> 5121815 T18014 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@55172fb9 [junit4] 2> 5121815 T18014 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5121816 T18014 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 8A69D463757EDC73-001\tempDir-002 [junit4] 2> 5121816 T18014 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 8A69D463757EDC73-001\tempDir-002\' [junit4] 2> 5121860 T18014 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 8A69D463757EDC73-001\tempDir-002\solr.xml [junit4] 2> 5121918 T18014 oasc.CoreContainer.<init> New CoreContainer 936718249 [junit4] 2> 5121918 T18014 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 8A69D463757EDC73-001\tempDir-002\] [junit4] 2> 5121919 T18014 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5121920 T18014 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5121920 T18014 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5121920 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5121920 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5121921 T18014 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5121921 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5121921 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5121922 T18014 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5121922 T18014 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5121922 T18014 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 5121923 T18014 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5121923 T18014 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5121923 T18014 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5121924 T18014 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51547/solr [junit4] 2> 5121924 T18014 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5121924 T18014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5121925 T18014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5121928 T18037 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@41d01bca name:ZooKeeperConnection Watcher:127.0.0.1:51547 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5121929 T18014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5121930 T18014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5121932 T18016 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14ad0e2bbf20002, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 5121934 T18014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5121937 T18040 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4fccfd8 name:ZooKeeperConnection Watcher:127.0.0.1:51547/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5121937 T18014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5121941 T18014 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 5121947 T18014 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 5121953 T18014 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 5121959 T18014 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 5121963 T18014 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 5121970 T18014 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 5121972 T18014 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51554_ [junit4] 2> 5121976 T18014 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51554_ [junit4] 2> 5121981 T18014 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 5121985 T18014 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 5121988 T18014 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5121992 T18014 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:51554_ [junit4] 2> 5121992 T18014 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 5121996 T18014 oasc.Overseer.start Overseer (id=93116414548901891-127.0.0.1:51554_-n_0000000000) starting [junit4] 2> 5122000 T18014 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 5122016 T18014 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 5122017 T18014 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 5122018 T18042 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 5122021 T18014 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 5122025 T18014 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5122031 T18041 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 5122038 T18044 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 5122038 T18044 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5122040 T18040 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5122040 T18044 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5122046 T18041 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "node_name":"127.0.0.1:51554_", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"1", [junit4] 2> "shard":null, [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51554";, [junit4] 2> "state":"down"} current state version: 0 [junit4] 2> 5122047 T18041 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "node_name":"127.0.0.1:51554_", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"1", [junit4] 2> "shard":null, [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51554";, [junit4] 2> "state":"down"} [junit4] 2> 5122048 T18041 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 5122048 T18041 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 5122050 T18040 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> 5122963 T18044 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5122963 T18044 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 5122965 T18044 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5122965 T18044 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 5122968 T18044 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5122969 T18044 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 8A69D463757EDC73-001\tempDir-002\collection1\' [junit4] 2> 5122975 T18044 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest%208A69D463757EDC73-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 5122978 T18044 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest%208A69D463757EDC73-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 5122981 T18044 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest%208A69D463757EDC73-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 5123006 T18044 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5123011 T18044 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5123025 T18044 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5123046 T18044 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 5123092 T18044 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5123095 T18044 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5123104 T18044 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5123470 T18044 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5123478 T18044 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5123481 T18044 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5123501 T18044 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5123509 T18044 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5123517 T18044 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5123519 T18044 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5123519 T18044 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5123519 T18044 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5123521 T18044 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5123521 T18044 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5123521 T18044 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 5123521 T18044 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5123523 T18044 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 8A69D463757EDC73-001\tempDir-002\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 8A69D463757EDC73-001\tempDir-001/control/data\ [junit4] 2> 5123523 T18044 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f998f30 [junit4] 2> 5123525 T18044 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 8A69D463757EDC73-001\tempDir-001/control/data\ [junit4] 2> 5123525 T18044 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 8A69D463757EDC73-001\tempDir-001/control/data\index/ [junit4] 2> 5123525 T18044 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 8A69D463757EDC73-001\tempDir-001\control\data\index' doesn't exist. Creating new index... [junit4] 2> 5123526 T18044 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 8A69D463757EDC73-001\tempDir-001/control/data\index [junit4] 2> 5123527 T18044 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 5123528 T18044 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@75180eba lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7e6f1164),segFN=segments_1,generation=1} [junit4] 2> 5123528 T18044 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5123534 T18044 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5123534 T18044 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5123534 T18044 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5123534 T18044 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5123535 T18044 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5123535 T18044 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5123535 T18044 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5123536 T18044 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5123536 T18044 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5123536 T18044 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5123537 T18044 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5123537 T18044 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5123537 T18044 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5123537 T18044 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 5123537 T18044 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 5123539 T18044 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 5123539 T18044 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 5123539 T18044 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 5123540 T18044 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 5123541 T18044 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 5123541 T18044 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 5123542 T18044 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 5123542 T18044 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 5123542 T18044 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 5123543 T18044 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 5123543 T18044 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5123544 T18044 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5123549 T18044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5123551 T18044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5123552 T18044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5123554 T18044 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5123557 T18044 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 5123560 T18044 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5123560 T18044 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5123561 T18044 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=30.4365234375, floorSegmentMB=1.3359375, forceMergeDeletesPctAllowed=7.054243209782582, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 5123561 T18044 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@75180eba lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7e6f1164),segFN=segments_1,generation=1} [junit4] 2> 5123561 T18044 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5123562 T18044 oass.SolrIndexSearcher.<init> Opening Searcher@83bd9bb[collection1] main [junit4] 2> 5123562 T18044 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 5123565 T18044 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5123565 T18044 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5123567 T18044 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5123567 T18044 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5123568 T18044 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5123570 T18044 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 5123570 T18044 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5123570 T18044 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5123571 T18044 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 5123571 T18044 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5123572 T18045 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@83bd9bb[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5123573 T18048 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51554 collection:control_collection shard:shard1 [junit4] 2> 5123573 T18014 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 5123573 T18014 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5123574 T18014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5123574 T18048 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 5123575 T18014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5123577 T18052 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@695023d name:ZooKeeperConnection Watcher:127.0.0.1:51547/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5123578 T18014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5123578 T18014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5123580 T18014 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5123582 T18048 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 5123584 T18040 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5123586 T18048 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5123586 T18048 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7281 name=collection1 org.apache.solr.core.SolrCore@342b2b9b url=http://127.0.0.1:51554/collection1 node=127.0.0.1:51554_ C7281_STATE=coll:control_collection core:collection1 props:{node_name=127.0.0.1:51554_, core=collection1, base_url=http://127.0.0.1:51554, state=down} [junit4] 2> 5123586 T18048 C7281 P51554 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51554/collection1/ [junit4] 2> 5123586 T18048 C7281 P51554 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5123587 T18048 C7281 P51554 oasc.SyncStrategy.syncToMe http://127.0.0.1:51554/collection1/ has no replicas [junit4] 2> 5123587 T18048 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51554/collection1/ shard1 [junit4] 2> 5123587 T18048 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 5123587 T18041 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> 5123588 T18014 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 5123588 T18014 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 5123588 T18014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5123590 T18014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5123592 T18052 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> 5123594 T18040 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> 5123595 T18055 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@4ccec23b name:ZooKeeperConnection Watcher:127.0.0.1:51547/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5123597 T18014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5123597 T18014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5123601 T18016 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14ad0e2bbf20005, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 5123601 T18041 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:51554";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 5123608 T18041 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "stateFormat":2} current state version: 2 [junit4] 2> 5123608 T18041 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 5123610 T18052 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> 5123610 T18056 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5123710 T18041 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json [junit4] 2> 5123712 T18052 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> 5123743 T18048 oasc.ZkController.register We are http://127.0.0.1:51554/collection1/ and leader is http://127.0.0.1:51554/collection1/ [junit4] 2> 5123744 T18048 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51554 [junit4] 2> 5123744 T18048 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5123744 T18048 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 5123744 T18048 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5123747 T18040 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5123751 T18041 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:51554_", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"3", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51554";, [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 5123752 T18041 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "node_name":"127.0.0.1:51554_", [junit4] 2> "collection":"control_collection", [junit4] 2> "numShards":"3", [junit4] 2> "shard":"shard1", [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51554";, [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "state":"active"} [junit4] 2> 5123853 T18040 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> 5123854 T18052 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> 5125104 T18014 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 5125105 T18014 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5125109 T18014 oejs.AbstractConnector.doStart Started [email protected]:51571 [junit4] 2> 5125110 T18014 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@55172fb9 [junit4] 2> 5125111 T18014 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5125111 T18014 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 8A69D463757EDC73-001\tempDir-003 [junit4] 2> 5125111 T18014 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 8A69D463757EDC73-001\tempDir-003\' [junit4] 2> 5125148 T18014 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 8A69D463757EDC73-001\tempDir-003\solr.xml [junit4] 2> 5125204 T18014 oasc.CoreContainer.<init> New CoreContainer 1483191751 [junit4] 2> 5125204 T18014 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 8A69D463757EDC73-001\tempDir-003\] [junit4] 2> 5125205 T18014 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5125205 T18014 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5125205 T18014 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5125205 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5125205 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5125207 T18014 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5125207 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5125207 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5125207 T18014 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5125208 T18014 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5125208 T18014 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 5125209 T18014 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5125209 T18014 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5125209 T18014 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5125210 T18014 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51547/solr [junit4] 2> 5125210 T18014 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5125210 T18014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5125212 T18014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5125214 T18068 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@698364f5 name:ZooKeeperConnection Watcher:127.0.0.1:51547 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5125214 T18014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5125215 T18014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5125219 T18014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5125221 T18071 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@67a2f858 name:ZooKeeperConnection Watcher:127.0.0.1:51547/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5125222 T18014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5125234 T18014 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5126168 T18014 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51570_ [junit4] 2> 5126169 T18014 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51570_ [junit4] 2> 5126178 T18014 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5126193 T18072 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 5126193 T18072 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5126197 T18040 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5126198 T18072 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 5126199 T18072 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 5126200 T18041 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:51570_", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "shard":null, [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51570";, [junit4] 2> "state":"down"} current state version: 5 [junit4] 2> 5126201 T18072 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 5126201 T18041 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "node_name":"127.0.0.1:51570_", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "shard":null, [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51570";, [junit4] 2> "state":"down"} [junit4] 2> 5126201 T18041 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 5126201 T18041 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard3 [junit4] 2> 5126204 T18072 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 5126204 T18072 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5126204 T18041 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 5126206 T18071 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5126207 T18071 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 5127179 T18072 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5127179 T18072 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 5127180 T18072 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5127181 T18072 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5127183 T18072 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5127183 T18072 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 8A69D463757EDC73-001\tempDir-003\collection1\' [junit4] 2> 5127186 T18072 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest%208A69D463757EDC73-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 5127187 T18072 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest%208A69D463757EDC73-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 5127188 T18072 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest%208A69D463757EDC73-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 5127214 T18072 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5127219 T18072 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5127232 T18072 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5127255 T18072 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 5127318 T18072 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5127324 T18072 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5127332 T18072 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5127737 T18072 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5127745 T18072 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5127747 T18072 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5127766 T18072 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5127772 T18072 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5127779 T18072 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5127781 T18072 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5127781 T18072 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5127781 T18072 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5127783 T18072 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5127784 T18072 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5127784 T18072 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 5127784 T18072 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5127785 T18072 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 8A69D463757EDC73-001\tempDir-003\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 8A69D463757EDC73-001\tempDir-001/jetty1\ [junit4] 2> 5127785 T18072 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f998f30 [junit4] 2> 5127786 T18072 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 8A69D463757EDC73-001\tempDir-001/jetty1\ [junit4] 2> 5127786 T18072 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 8A69D463757EDC73-001\tempDir-001/jetty1\index/ [junit4] 2> 5127786 T18072 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 8A69D463757EDC73-001\tempDir-001\jetty1\index' doesn't exist. Creating new index... [junit4] 2> 5127787 T18072 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 8A69D463757EDC73-001\tempDir-001/jetty1\index [junit4] 2> 5127787 T18072 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 5127788 T18072 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3ac1c9f7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8b29544),segFN=segments_1,generation=1} [junit4] 2> 5127788 T18072 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5127794 T18072 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5127794 T18072 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5127795 T18072 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5127795 T18072 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5127796 T18072 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5127796 T18072 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5127796 T18072 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5127797 T18072 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5127797 T18072 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5127798 T18072 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5127798 T18072 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5127799 T18072 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5127799 T18072 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5127800 T18072 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 5127800 T18072 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 5127801 T18072 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 5127801 T18072 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 5127802 T18072 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 5127804 T18072 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 5127805 T18072 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 5127805 T18072 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 5127806 T18072 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 5127807 T18072 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 5127807 T18072 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 5127808 T18072 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 5127808 T18072 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5127809 T18072 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5127818 T18072 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5127820 T18072 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5127823 T18072 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5127825 T18072 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5127827 T18072 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 5127830 T18072 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5127830 T18072 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5127831 T18072 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=20, maxMergeAtOnceExplicit=15, maxMergedSegmentMB=30.4365234375, floorSegmentMB=1.3359375, forceMergeDeletesPctAllowed=7.054243209782582, segmentsPerTier=47.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 5127831 T18072 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3ac1c9f7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@8b29544),segFN=segments_1,generation=1} [junit4] 2> 5127832 T18072 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5127832 T18072 oass.SolrIndexSearcher.<init> Opening Searcher@4fbc7edc[collection1] main [junit4] 2> 5127832 T18072 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5127834 T18072 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5127834 T18072 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5127835 T18072 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5127835 T18072 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5127835 T18072 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5127838 T18072 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 5127838 T18072 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5127839 T18072 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5127839 T18072 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 5127839 T18073 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4fbc7edc[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5127840 T18072 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5127841 T18076 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:51570 collection:collection1 shard:shard3 [junit4] 2> 5127841 T18014 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 5127842 T18014 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5127842 T18076 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard3/election [junit4] 2> 5127856 T18076 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard3 [junit4] 2> 5127859 T18040 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5127860 T18076 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5127860 T18076 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7282 name=collection1 org.apache.solr.core.SolrCore@51674b36 url=http://127.0.0.1:51570/collection1 node=127.0.0.1:51570_ C7282_STATE=coll:collection1 core:collection1 props:{node_name=127.0.0.1:51570_, core=collection1, base_url=http://127.0.0.1:51570, state=down} [junit4] 2> 5127860 T18076 C7282 P51570 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:51570/collection1/ [junit4] 2> 5127860 T18076 C7282 P51570 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5127860 T18076 C7282 P51570 oasc.SyncStrategy.syncToMe http://127.0.0.1:51570/collection1/ has no replicas [junit4] 2> 5127860 T18076 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:51570/collection1/ shard3 [junit4] 2> 5127862 T18076 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard3 [junit4] 2> 5127864 T18041 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> 5127872 T18041 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 5127873 T18071 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5127875 T18071 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 5127886 T18041 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:51570";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 5127887 T18041 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 2 [junit4] 2> 5127888 T18071 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5127889 T18071 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 5127893 T18040 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5127936 T18076 oasc.ZkController.register We are http://127.0.0.1:51570/collection1/ and leader is http://127.0.0.1:51570/collection1/ [junit4] 2> 5127936 T18076 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51570 [junit4] 2> 5127936 T18076 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5127936 T18076 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 5127936 T18076 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5127939 T18040 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5127943 T18041 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:51570_", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "shard":"shard3", [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51570";, [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 5127944 T18041 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "node_name":"127.0.0.1:51570_", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "shard":"shard3", [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51570";, [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "state":"active"} [junit4] 2> 5127947 T18041 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 3 [junit4] 2> 5127949 T18071 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5127951 T18076 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 5127953 T18071 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 5129473 T18014 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 5129474 T18014 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5129479 T18014 oejs.AbstractConnector.doStart Started [email protected]:51581 [junit4] 2> 5129481 T18014 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@55172fb9 [junit4] 2> 5129481 T18014 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5129481 T18014 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 8A69D463757EDC73-001\tempDir-004 [junit4] 2> 5129481 T18014 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 8A69D463757EDC73-001\tempDir-004\' [junit4] 2> 5129535 T18014 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 8A69D463757EDC73-001\tempDir-004\solr.xml [junit4] 2> 5129643 T18014 oasc.CoreContainer.<init> New CoreContainer 702930721 [junit4] 2> 5129643 T18014 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 8A69D463757EDC73-001\tempDir-004\] [junit4] 2> 5129645 T18014 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5129645 T18014 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5129645 T18014 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5129645 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5129645 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5129645 T18014 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5129645 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5129645 T18014 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5129645 T18014 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5129645 T18014 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5129645 T18014 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 5129645 T18014 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5129649 T18014 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5129649 T18014 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 5129649 T18014 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:51547/solr [junit4] 2> 5129649 T18014 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5129650 T18014 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5129651 T18014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5129654 T18089 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6adf7877 name:ZooKeeperConnection Watcher:127.0.0.1:51547 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5129654 T18014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5129654 T18014 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5129658 T18014 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5129660 T18092 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@ab804b1 name:ZooKeeperConnection Watcher:127.0.0.1:51547/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5129661 T18014 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5129670 T18014 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5130694 T18014 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51580_ [junit4] 2> 5130697 T18014 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:51580_ [junit4] 2> 5130705 T18014 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5130719 T18093 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 5130719 T18093 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5130721 T18040 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5130723 T18093 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 5130723 T18093 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 5130723 T18041 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:51580_", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "shard":null, [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51580";, [junit4] 2> "state":"down"} current state version: 5 [junit4] 2> 5130724 T18093 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 5130724 T18041 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "node_name":"127.0.0.1:51580_", [junit4] 2> "collection":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "shard":null, [junit4] 2> "core":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:51580";, [junit4] 2> "state":"down"} [junit4] 2> 5130724 T18041 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 5130725 T18041 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 5130726 T18093 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 5130726 T18093 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5130727 T18041 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 4 [junit4] 2> 5130729 T18071 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5130729 T18092 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json, has occurred - updating... [junit4] 2> 5130730 T18071 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 5130731 T18092 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 5131741 T18093 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5131741 T18093 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 5131744 T18093 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5131744 T18093 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5131746 T18093 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5131747 T18093 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 8A69D463757EDC73-001\tempDir-004\collection1\' [junit4] 2> 5131749 T18093 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest%208A69D463757EDC73-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 5131753 T18093 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest%208A69D463757EDC73-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 5131756 T18093 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-5.x-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest%208A69D463757EDC73-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 5131785 T18093 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5131790 T18093 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5131805 T18093 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5131829 T18093 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.0.0 [junit4] 2> 5131879 T18093 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5131881 T18093 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5131890 T18093 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5132295 T18093 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5132304 T18093 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5132306 T18093 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5132335 T18093 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5132340 T18093 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5132347 T18093 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5132348 T18093 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5132348 T18093 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5132349 T18093 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5132350 T18093 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5132350 T18093 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5132350 T18093 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 5132350 T18093 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5132350 T18093 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 8A69D463757EDC73-001\tempDir-004\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 8A69D463757EDC73-001\tempDir-001/jetty2\ [junit4] 2> 5132351 T18093 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@5f998f30 [junit4] 2> 5132351 T18093 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 8A69D463757EDC73-001\tempDir-001/jetty2\ [junit4] 2> 5132351 T18093 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 8A69D463757EDC73-001\tempDir-001/jetty2\index/ [junit4] 2> 5132351 T18093 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 8A69D463757EDC73-001\tempDir-001\jetty2\index' doesn't exist. Creating new index... [junit4] 2> 5132354 T18093 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 8A69D463757EDC73-001\tempDir-001/jetty2\index [junit4] 2> 5132354 T18093 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=24, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 5132355 T18093 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3e901b4a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@66efb75c),segFN=segments_1,generation=1} [junit4] 2> 5132355 T18093 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5132362 T18093 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5132362 T18093 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5132363 T18093 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5132363 T18093 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5132363 T18093 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5132364 T18093 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5132364 T18093 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5132365 T18093 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5132365 T18093 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5132366 T18093 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateReq [...truncated too long message...] afeLeaderTest, RankQueryTest, TestQueryUtils, TestRecoveryHdfs, TestWordDelimiterFilterFactory, MigrateRouteKeyTest, TestOverriddenPrefixQueryForCustomFieldType, HdfsBasicDistributedZk2Test, DocValuesMissingTest, TestExpandComponent, RegexBoostProcessorTest, ExternalFileFieldSortTest, OverseerCollectionProcessorTest, TestIntervalFaceting, BasicFunctionalityTest, SpellPossibilityIteratorTest, TestSortingResponseWriter, TestCloudInspectUtil, PolyFieldTest, ParsingFieldUpdateProcessorsTest, TestDistribDocBasedVersion, CustomCollectionTest, TermVectorComponentTest, LegacyHTMLStripCharFilterTest, DocumentAnalysisRequestHandlerTest, AnalysisErrorHandlingTest, TestReplicationHandler, TestCloudSchemaless, JsonLoaderTest, ShowFileRequestHandlerTest, TestIndexSearcher, AddSchemaFieldsUpdateProcessorFactoryTest, TestSolr4Spatial, TestReplicationHandlerBackup, DateRangeFieldTest, TestSolr4Spatial2, TestSolrQueryParser, CoreAdminCreateDiscoverTest, DistributedFacetPivotSmallAdvancedTest, AutoCommitTest, TestStressUserVersions, TimeZoneUtilsTest, TestConfigSets, DateFieldTest, TestBlobHandler, RollingRestartTest, TestSolrXmlPersistence, SOLR749Test, CurrencyFieldXmlFileTest, SimplePostToolTest, QueryEqualityTest, TestFastLRUCache, DisMaxRequestHandlerTest, UUIDUpdateProcessorFallbackTest, TestComponentsName, SuggestComponentTest, CacheHeaderTest, TestCSVResponseWriter, TestSolrIndexConfig, TestIBSimilarityFactory, TestSolrQueryParserDefaultOperatorResource, TestSolrDeletionPolicy1, MultiThreadedOCPTest, TestSolrCoreProperties, TestNonDefinedSimilarityFactory, TestSerializedLuceneMatchVersion, TestUpdate, TestLMJelinekMercerSimilarityFactory, TestSchemaNameResource, TestCloudManagedSchemaConcurrent, TestSolrConfigHandlerConcurrent, DocValuesTest, TestImplicitCoreProperties, TestBinaryResponseWriter, SolrIndexSplitterTest, LukeRequestHandlerTest, TestRandomMergePolicy, TestExactSharedStatsCache, TestFieldTypeCollectionResource, TestRangeQuery, TestOrdValues, FacetPivotSmallTest, TestStressVersions, TestCloudPivotFacet, ConvertedLegacyTest, DistributedFacetPivotWhiteBoxTest, TestLeaderElectionZkExpiry, SignatureUpdateProcessorFactoryTest, TestManagedSchema, OverseerTest, ChaosMonkeyNothingIsSafeTest, ShardSplitTest, SchemaVersionSpecificBehaviorTest, TestSuggestSpellingConverter, HdfsWriteToMultipleCollectionsTest, SimpleFacetsTest, TestNRTOpen, SuggesterFSTTest, TestReRankQParserPlugin, TestDistributedMissingSort, DeleteReplicaTest, DocExpirationUpdateProcessorFactoryTest, AtomicUpdatesTest, TestBadConfig, ChangedSchemaMergeTest, TestCursorMarkWithoutUniqueKey, TestDistributedSearch, TestStandardQParsers, TestElisionMultitermQuery, OverriddenZkACLAndCredentialsProvidersTest, SpatialRPTFieldTypeTest, ScriptEngineTest, HdfsLockFactoryTest, UniqFieldsUpdateProcessorFactoryTest, TestQuerySenderListener, DistributedSuggestComponentTest, DebugComponentTest, SuggesterWFSTTest, IndexSchemaTest, HdfsBasicDistributedZkTest, ZkSolrClientTest, TestMissingGroups, DirectUpdateHandlerOptimizeTest, TestOmitPositions, TestRestManager, CursorPagingTest, SampleTest, TestBulkSchemaConcurrent, ClusterStateUpdateTest, SolrXmlInZkTest, TestSchemaResource, BJQParserTest, TestValueSourceCache, DeleteLastCustomShardedReplicaTest, TestSimpleQParserPlugin, DistributedQueueTest, TestSearcherReuse, SliceStateTest, TestInitQParser, BasicZkTest, PeerSyncTest, XsltUpdateRequestHandlerTest, BadComponentTest, StressHdfsTest, TestZkChroot, TestFaceting, PathHierarchyTokenizerFactoryTest, TestCopyFieldCollectionResource, TestCodecSupport, RequestHandlersTest, TestSolrXml, TestDynamicLoading, TestSolrJ, OpenExchangeRatesOrgProviderTest, TriLevelCompositeIdRoutingTest, BlockCacheTest, DistributedFacetPivotSmallTest, TestHighlightDedupGrouping, HdfsUnloadDistributedZkTest, TestFieldCollectionResource, DOMUtilTest, TestDocBasedVersionConstraints, TestPHPSerializedResponseWriter, ZkStateWriterTest, TestManagedSchemaDynamicFieldResource, TestBinaryField, TestSolrQueryParserResource, LeaderInitiatedRecoveryOnCommitTest, TestCoreDiscovery, TestPostingsSolrHighlighter, RemoteQueryErrorTest, DateMathParserTest, TestLazyCores, SyncSliceTest, SolrPluginUtilsTest, LeaderElectionIntegrationTest, TestCloudManagedSchema, AddBlockUpdateTest, TestRequestStatusCollectionAPI, SolrTestCaseJ4Test, ShardRoutingTest, SolrCoreTest, TestDynamicFieldCollectionResource, TestQuerySenderNoQuery, TestRealTimeGet, TestConfig, TestRTGBase, TestSolrXmlPersistor, TestIndexingPerformance, ZkNodePropsTest, DistanceUnitsTest, TestHighFrequencyDictionaryFactory, DirectSolrConnectionTest, TestJmxMonitoredMap, TestAddFieldRealTimeGet, BadIndexSchemaTest, TestAtomicUpdateErrorCases, PreAnalyzedUpdateProcessorTest, DistributedFacetPivotLargeTest, CoreContainerCoreInitFailuresTest, TestBM25SimilarityFactory, TestReloadAndDeleteDocs, SynonymTokenizerTest, TestFoldingMultitermQuery, RecoveryZkTest, TestInitParams, TestSolrDynamicMBean, SolrRequestParserTest, SystemInfoHandlerTest, HighlighterMaxOffsetTest, TestRemoteStreaming, PrimitiveFieldTypeTest, WordBreakSolrSpellCheckerTest, SharedFSAutoReplicaFailoverTest, BasicDistributedZkTest, BasicDistributedZk2Test, UnloadDistributedZkTest, CollectionsAPIDistributedZkTest, OpenCloseCoreStressTest, TestRandomFaceting, TestRandomDVFaceting, ZkCLITest, ShardRoutingCustomTest, TestDistributedGrouping, TestRecovery, TestHashPartitioner, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, ZkControllerTest, TestStressReorder, TestJoin, TestReload, HardAutoCommitTest, DistributedTermsComponentTest, TestCoreContainer, StatsComponentTest, SpellCheckComponentTest, TestGroupingSearch, SolrCmdDistributorTest, QueryElevationComponentTest, TestFiltering, TestFunctionQuery, DirectUpdateHandlerTest, HighlighterTest, SoftAutoCommitTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, AnalysisAfterCoreReloadTest, TestExtendedDismaxParser, SpellCheckCollatorTest, CoreAdminHandlerTest, SuggesterTSTTest, SuggesterTest, TestStressLucene, TestTrie, SpatialFilterTest, TestCSVLoader, NoCacheHeaderTest, SolrCoreCheckLockOnStartupTest, TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, DefaultValueUpdateProcessorTest, StatelessScriptUpdateProcessorFactoryTest, SortByFunctionTest, DocValuesMultiTest, DistanceFunctionTest, SolrInfoMBeanTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, StandardRequestHandlerTest, TestWriterPerf, DirectSolrSpellCheckerTest, TestReversedWildcardFilterFactory, TestQueryTypes, FileBasedSpellCheckerTest, TermsComponentTest, XmlUpdateRequestHandlerTest, DocumentBuilderTest, MoreLikeThisHandlerTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, FastVectorHighlighterTest, IndexSchemaRuntimeFieldTest, LoggingHandlerTest, TestJmxIntegration, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, JSONWriterTest, MBeansHandlerTest, QueryParsingTest, TestAnalyzedSuggestions, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, PingRequestHandlerTest, CSVRequestHandlerTest, SearchHandlerTest, TestLFUCache, HighlighterConfigTest, UpdateParamsTest, AlternateDirectoryTest, CopyFieldTest, ResponseLogComponentTest, SolrIndexConfigTest, TestStressRecovery, TestMergePolicyConfig, TestSolrDeletionPolicy2, MultiTermTest, TestDocSet, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, OutputWriterTest, TestFuzzyAnalyzedSuggestions, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestPhraseSuggestions, TestCharFilters, TestXIncludeConfig, EchoParamsTest, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestPerFieldSimilarity, TestLMDirichletSimilarityFactory, TestDefaultSimilarityFactory, TestFastWriter, ResourceLoaderTest, TestFastOutputStream, URLClassifyProcessorTest, PluginInfoTest, PreAnalyzedFieldTest, TestSystemIdResolver, PrimUtilsTest, SpellingQueryConverterTest, RAMDirectoryFactoryTest, ClusterStateTest, TestLRUCache, TestUtils, TestDocumentBuilder, UUIDFieldTest, FileUtilsTest, CircularListTest, DistributedIntervalFacetingTest, TestTolerantSearch, AliasIntegrationTest, AssignTest, AsyncMigrateRouteKeyTest, CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, DeleteInactiveReplicaTest, DeleteShardTest, DistribCursorPagingTest, DistribDocExpirationUpdateProcessorTest, ExternalCollectionsTest, HttpPartitionTest, LeaderFailoverAfterPartitionTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerRolesTest, OverseerStatusTest, ReplicationFactorTest] [junit4] Completed in 89.15s, 1 test, 1 error <<< FAILURES! [...truncated 254 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: 459 suites, 1870 tests, 1 error, 56 ignored (31 assumptions) Total time: 132 minutes 50 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 64bit/jdk1.7.0_72 -XX:-UseCompressedOops -XX:+UseParallelGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
