Build: http://jenkins.thetaphi.de/job/Lucene-Solr-5.x-Windows/4412/ Java: 32bit/jdk1.7.0_76 -server -XX:+UseG1GC
1 tests failed. FAILED: org.apache.solr.cloud.ReplicationFactorTest.test Error Message: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:61337/repfacttest_c8n_1x3_shard1_replica3 Stack Trace: org.apache.solr.client.solrj.SolrServerException: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:61337/repfacttest_c8n_1x3_shard1_replica3 at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:597) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:918) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:809) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:752) at org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:283) at org.apache.solr.cloud.ReplicationFactorTest.test(ReplicationFactorTest.java:112) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:606) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:950) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:925) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:61337/repfacttest_c8n_1x3_shard1_replica3 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:572) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:594) ... 46 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) ... 51 more Build Log: [...truncated 10381 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 B00F427E937560E5-001\init-core-data-001 [junit4] 2> 5299883 T19156 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: / [junit4] 2> 5299890 T19156 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 5299891 T19157 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 5300008 T19156 oasc.ZkTestServer.run start zk server on port:61314 [junit4] 2> 5300008 T19156 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5300010 T19156 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5300020 T19164 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1dfc30a name:ZooKeeperConnection Watcher:127.0.0.1:61314 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5300021 T19156 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5300023 T19156 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5300023 T19156 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 5300034 T19156 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5300036 T19156 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5300039 T19167 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1bc0e45 name:ZooKeeperConnection Watcher:127.0.0.1:61314/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5300039 T19156 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5300106 T19156 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5300107 T19156 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 5300112 T19156 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 5300115 T19156 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 5300121 T19156 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 5300124 T19156 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> 5300125 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 5300130 T19156 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> 5300131 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 5300136 T19156 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> 5300137 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 5300140 T19156 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> 5300141 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 5300146 T19156 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> 5300146 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 5300150 T19156 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> 5300150 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 5300155 T19156 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> 5300155 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 5300161 T19156 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> 5300161 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 5300167 T19156 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> 5300167 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 5300172 T19156 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> 5300173 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 5300178 T19156 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> 5300178 T19156 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 5301952 T19156 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\control-001\cores\collection1 [junit4] 2> 5301957 T19156 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5301965 T19156 oejs.AbstractConnector.doStart Started [email protected]:61322 [junit4] 2> 5301965 T19156 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@1df59bd [junit4] 2> 5301965 T19156 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5301965 T19156 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 B00F427E937560E5-001\control-001 [junit4] 2> 5301965 T19156 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 B00F427E937560E5-001\control-001\' [junit4] 2> 5302031 T19156 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\control-001\solr.xml [junit4] 2> 5302130 T19156 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\control-001\cores [junit4] 2> 5302130 T19156 oasc.CoreContainer.<init> New CoreContainer 9739598 [junit4] 2> 5302130 T19156 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 B00F427E937560E5-001\control-001\] [junit4] 2> 5302131 T19156 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\control-001\lib [junit4] 2> 5302132 T19156 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\control-001\lib). [junit4] 2> 5302150 T19156 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5302151 T19156 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5302151 T19156 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5302151 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5302152 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5302152 T19156 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5302152 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5302152 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5302152 T19156 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5302152 T19156 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5302152 T19156 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 5302152 T19156 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 5302152 T19156 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5302154 T19156 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5302155 T19156 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 5302155 T19156 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:61314/solr [junit4] 2> 5302155 T19156 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5302155 T19156 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5302157 T19156 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5302161 T19179 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@195421e name:ZooKeeperConnection Watcher:127.0.0.1:61314 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5302161 T19156 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5302161 T19156 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5302170 T19156 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5302171 T19182 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15c49a9 name:ZooKeeperConnection Watcher:127.0.0.1:61314/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5302174 T19156 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5302177 T19156 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 5302184 T19156 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 5302190 T19156 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 5302196 T19156 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 5302201 T19156 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 5302208 T19156 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 5302210 T19156 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61321_ [junit4] 2> 5302213 T19156 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61321_ [junit4] 2> 5302218 T19156 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 5302220 T19156 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 5302224 T19156 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5302228 T19156 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:61321_ [junit4] 2> 5302229 T19156 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 5302234 T19156 oasc.Overseer.start Overseer (id=93414932257374211-127.0.0.1:61321_-n_0000000000) starting [junit4] 2> 5302239 T19156 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 5302255 T19156 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 5302257 T19183 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 5302258 T19184 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 5302257 T19156 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 5302263 T19156 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 5302264 T19156 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5302277 T19156 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\control-001\cores [junit4] 2> 5302279 T19156 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\control-001\cores\collection1\ [junit4] 2> 5302279 T19156 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 5302280 T19186 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 5302282 T19186 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5302283 T19182 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5302283 T19186 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5302284 T19183 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:61321_", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "base_url":"http://127.0.0.1:61321";, [junit4] 2> "roles":null, [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "core":"collection1"} current state version: 0 [junit4] 2> 5302285 T19183 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "node_name":"127.0.0.1:61321_", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "base_url":"http://127.0.0.1:61321";, [junit4] 2> "roles":null, [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "core":"collection1"} [junit4] 2> 5302285 T19183 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 5302287 T19183 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 5302288 T19182 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> 5303568 T19186 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5303568 T19186 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 5303570 T19186 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5303570 T19186 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 5303573 T19186 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5303573 T19186 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 B00F427E937560E5-001\control-001\cores\collection1\' [junit4] 2> 5303622 T19186 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5303629 T19186 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5303648 T19186 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5303698 T19186 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 5303771 T19186 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5303775 T19186 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5303790 T19186 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5304265 T19186 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5304276 T19186 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5304280 T19186 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5304298 T19186 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5304303 T19186 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5304309 T19186 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5304312 T19186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5304312 T19186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5304312 T19186 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5304314 T19186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5304314 T19186 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5304315 T19186 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 5304315 T19186 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5304315 T19186 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 B00F427E937560E5-001\control-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\tempDir-001/control/data\ [junit4] 2> 5304315 T19186 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1131db5 [junit4] 2> 5304317 T19186 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 B00F427E937560E5-001\tempDir-001/control/data\ [junit4] 2> 5304317 T19186 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 B00F427E937560E5-001\tempDir-001/control/data\index/ [junit4] 2> 5304318 T19186 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 B00F427E937560E5-001\tempDir-001\control\data\index' doesn't exist. Creating new index... [junit4] 2> 5304318 T19186 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 B00F427E937560E5-001\tempDir-001/control/data\index [junit4] 2> 5304319 T19186 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=37, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 5304319 T19186 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1aee4c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12e7c46),segFN=segments_1,generation=1} [junit4] 2> 5304323 T19186 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5304328 T19186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5304329 T19186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5304329 T19186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5304329 T19186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5304330 T19186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5304330 T19186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5304330 T19186 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5304332 T19186 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5304332 T19186 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5304333 T19186 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5304333 T19186 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5304333 T19186 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5304334 T19186 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5304334 T19186 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 5304335 T19186 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 5304335 T19186 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 5304337 T19186 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 5304337 T19186 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 5304338 T19186 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 5304339 T19186 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 5304339 T19186 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 5304340 T19186 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 5304340 T19186 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 5304342 T19186 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 5304342 T19186 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5304343 T19186 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 5304343 T19186 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5304350 T19186 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5304353 T19186 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5304355 T19186 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5304358 T19186 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5304360 T19186 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 5304362 T19186 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 5304364 T19186 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5304364 T19186 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5304365 T19186 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=59.0380859375, floorSegmentMB=0.44140625, forceMergeDeletesPctAllowed=17.13220886015005, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.39561141484372564 [junit4] 2> 5304365 T19186 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1aee4c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12e7c46),segFN=segments_1,generation=1} [junit4] 2> 5304367 T19186 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5304367 T19186 oass.SolrIndexSearcher.<init> Opening Searcher@1156fb0[collection1] main [junit4] 2> 5304367 T19186 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 5304369 T19186 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5304369 T19186 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5304371 T19186 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5304371 T19186 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5304371 T19186 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5304372 T19186 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 5304372 T19186 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5304373 T19186 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5304373 T19186 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 5304373 T19186 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5304373 T19187 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1156fb0[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5304376 T19190 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:61321 collection:control_collection shard:shard1 [junit4] 2> 5304377 T19156 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 5304377 T19156 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5304378 T19190 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 5304378 T19156 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5304382 T19156 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5304383 T19194 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1fa8926 name:ZooKeeperConnection Watcher:127.0.0.1:61314/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5304383 T19156 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5304384 T19156 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5304386 T19156 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5304389 T19190 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 5304396 T19182 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5304397 T19156 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 5304397 T19190 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5304398 T19190 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7341 name=collection1 org.apache.solr.core.SolrCore@96de35 url=http://127.0.0.1:61321/collection1 node=127.0.0.1:61321_ C7341_STATE=coll:control_collection core:collection1 props:{node_name=127.0.0.1:61321_, base_url=http://127.0.0.1:61321, state=down, core=collection1} [junit4] 2> 5304398 T19190 C7341 P61321 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:61321/collection1/ [junit4] 2> 5304398 T19190 C7341 P61321 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5304399 T19190 C7341 P61321 oasc.SyncStrategy.syncToMe http://127.0.0.1:61321/collection1/ has no replicas [junit4] 2> 5304399 T19190 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:61321/collection1/ shard1 [junit4] 2> 5304399 T19190 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 5304398 T19156 oasc.AbstractFullDistribZkTestBase.createJettys Creating collection1 with stateFormat=2 [junit4] 2> 5304401 T19156 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5304399 T19183 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> 5304403 T19156 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5304404 T19194 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> 5304407 T19182 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> 5304413 T19197 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d01ea5 name:ZooKeeperConnection Watcher:127.0.0.1:61314/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5304413 T19156 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5304414 T19156 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5304417 T19183 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "base_url":"http://127.0.0.1:61321";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 2 [junit4] 2> 5304418 T19182 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5304423 T19183 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"create", [junit4] 2> "name":"collection1", [junit4] 2> "numShards":"3", [junit4] 2> "stateFormat":"2"} current state version: 2 [junit4] 2> 5304424 T19183 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 5304426 T19182 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> 5304427 T19194 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> 5304437 T19182 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5304482 T19190 oasc.ZkController.register We are http://127.0.0.1:61321/collection1/ and leader is http://127.0.0.1:61321/collection1/ [junit4] 2> 5304482 T19190 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61321 [junit4] 2> 5304483 T19190 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5304483 T19190 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 5304483 T19190 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5304487 T19182 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5304490 T19183 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:61321_", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "shard":"shard1", [junit4] 2> "base_url":"http://127.0.0.1:61321";, [junit4] 2> "roles":null, [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "core":"collection1"} current state version: 3 [junit4] 2> 5304491 T19183 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "node_name":"127.0.0.1:61321_", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state", [junit4] 2> "shard":"shard1", [junit4] 2> "base_url":"http://127.0.0.1:61321";, [junit4] 2> "roles":null, [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "core":"collection1"} [junit4] 2> 5304493 T19183 oasco.ZkStateWriter.writePendingUpdates going to create_collection /collections/collection1/state.json [junit4] 2> 5304496 T19182 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> 5304497 T19194 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> 5304635 T19182 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> 5304636 T19194 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> 5306270 T19156 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-1-001\cores\collection1 [junit4] 2> 5306274 T19156 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 5306274 T19156 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5306280 T19156 oejs.AbstractConnector.doStart Started [email protected]:61338 [junit4] 2> 5306283 T19156 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@1df59bd [junit4] 2> 5306283 T19156 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5306283 T19156 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 B00F427E937560E5-001\shard-1-001 [junit4] 2> 5306283 T19156 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 B00F427E937560E5-001\shard-1-001\' [junit4] 2> 5306330 T19156 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-1-001\solr.xml [junit4] 2> 5306372 T19156 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-1-001\cores [junit4] 2> 5306372 T19156 oasc.CoreContainer.<init> New CoreContainer 19118412 [junit4] 2> 5306372 T19156 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 B00F427E937560E5-001\shard-1-001\] [junit4] 2> 5306372 T19156 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-1-001\lib [junit4] 2> 5306372 T19156 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-1-001\lib). [junit4] 2> 5306387 T19156 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5306387 T19156 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5306387 T19156 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5306387 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5306387 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5306387 T19156 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5306390 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5306390 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5306390 T19156 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5306390 T19156 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5306392 T19156 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 5306392 T19156 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 5306392 T19156 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5306392 T19156 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5306393 T19156 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 5306394 T19156 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:61314/solr [junit4] 2> 5306394 T19156 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5306394 T19156 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5306398 T19156 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5306400 T19209 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@15a3ad3 name:ZooKeeperConnection Watcher:127.0.0.1:61314 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5306402 T19156 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5306402 T19156 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5306408 T19156 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5306410 T19212 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13a6f98 name:ZooKeeperConnection Watcher:127.0.0.1:61314/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5306410 T19156 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5306422 T19156 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5307715 T19156 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61337_ [junit4] 2> 5307718 T19156 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61337_ [junit4] 2> 5307727 T19156 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5307733 T19156 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-1-001\cores [junit4] 2> 5307734 T19156 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-1-001\cores\collection1\ [junit4] 2> 5307734 T19156 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 5307740 T19213 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 5307740 T19213 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5307744 T19182 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5307745 T19213 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 5307747 T19183 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:61337_", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "base_url":"http://127.0.0.1:61337";, [junit4] 2> "roles":null, [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "core":"collection1"} current state version: 5 [junit4] 2> 5307747 T19213 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 5307748 T19183 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "node_name":"127.0.0.1:61337_", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "base_url":"http://127.0.0.1:61337";, [junit4] 2> "roles":null, [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "core":"collection1"} [junit4] 2> 5307748 T19183 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 5307748 T19183 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 5307749 T19213 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 5307752 T19183 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 0 [junit4] 2> 5307752 T19213 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 0 [junit4] 2> 5307753 T19213 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5307753 T19212 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2) [junit4] 2> 5307757 T19212 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 1 [junit4] 2> 5309038 T19213 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5309038 T19213 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 5309039 T19213 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5309040 T19213 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5309041 T19213 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5309043 T19213 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 B00F427E937560E5-001\shard-1-001\cores\collection1\' [junit4] 2> 5309085 T19213 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5309093 T19213 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5309113 T19213 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5309228 T19213 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 5309302 T19213 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5309306 T19213 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5309319 T19213 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5309886 T19213 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5309895 T19213 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5309898 T19213 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5309918 T19213 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5309928 T19213 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5309938 T19213 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5309941 T19213 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5309941 T19213 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5309942 T19213 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5309945 T19213 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5309945 T19213 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5309946 T19213 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 5309946 T19213 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5309947 T19213 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 B00F427E937560E5-001\shard-1-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\tempDir-001/jetty1\ [junit4] 2> 5309948 T19213 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1131db5 [junit4] 2> 5309950 T19213 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 B00F427E937560E5-001\tempDir-001/jetty1\ [junit4] 2> 5309950 T19213 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 B00F427E937560E5-001\tempDir-001/jetty1\index/ [junit4] 2> 5309951 T19213 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 B00F427E937560E5-001\tempDir-001\jetty1\index' doesn't exist. Creating new index... [junit4] 2> 5309951 T19213 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 B00F427E937560E5-001\tempDir-001/jetty1\index [junit4] 2> 5309952 T19213 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=37, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0] [junit4] 2> 5309953 T19213 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1c29e59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a0ae4),segFN=segments_1,generation=1} [junit4] 2> 5309955 T19213 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5309962 T19213 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 5309964 T19213 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 5309964 T19213 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 5309965 T19213 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 5309965 T19213 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 5309965 T19213 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 5309966 T19213 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5309967 T19213 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 5309967 T19213 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 5309969 T19213 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5309970 T19213 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5309970 T19213 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5309971 T19213 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 5309972 T19213 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 5309974 T19213 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 5309975 T19213 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 5309975 T19213 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 5309976 T19213 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 5309980 T19213 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 5309980 T19213 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 5309981 T19213 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 5309982 T19213 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 5309984 T19213 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 5309985 T19213 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 5309986 T19213 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 5309986 T19213 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 5309987 T19213 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 5309999 T19213 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5310001 T19213 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5310004 T19213 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5310009 T19213 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 5310011 T19213 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 5310014 T19213 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 5310016 T19213 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 5310016 T19213 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 5310017 T19213 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=17, maxMergeAtOnceExplicit=21, maxMergedSegmentMB=59.0380859375, floorSegmentMB=0.44140625, forceMergeDeletesPctAllowed=17.13220886015005, segmentsPerTier=15.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.39561141484372564 [junit4] 2> 5310019 T19213 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@1c29e59 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a0ae4),segFN=segments_1,generation=1} [junit4] 2> 5310019 T19213 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 5310021 T19213 oass.SolrIndexSearcher.<init> Opening Searcher@f64440[collection1] main [junit4] 2> 5310022 T19213 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5310025 T19213 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5310025 T19213 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 5310026 T19213 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 5310026 T19213 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 5310026 T19213 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5310028 T19213 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 5310028 T19213 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 5310028 T19213 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 5310028 T19213 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 5310030 T19214 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@f64440[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 5310031 T19213 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 5310034 T19156 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0 [junit4] 2> 5310034 T19156 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 5310035 T19217 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:61337 collection:collection1 shard:shard1 [junit4] 2> 5310038 T19217 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election [junit4] 2> 5310049 T19217 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 5310053 T19182 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5310054 T19217 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 5310054 T19217 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7342 name=collection1 org.apache.solr.core.SolrCore@1759c68 url=http://127.0.0.1:61337/collection1 node=127.0.0.1:61337_ C7342_STATE=coll:collection1 core:collection1 props:{node_name=127.0.0.1:61337_, base_url=http://127.0.0.1:61337, state=down, core=collection1} [junit4] 2> 5310054 T19217 C7342 P61337 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:61337/collection1/ [junit4] 2> 5310054 T19217 C7342 P61337 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 5310056 T19217 C7342 P61337 oasc.SyncStrategy.syncToMe http://127.0.0.1:61337/collection1/ has no replicas [junit4] 2> 5310056 T19217 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:61337/collection1/ shard1 [junit4] 2> 5310058 T19217 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1 [junit4] 2> 5310059 T19183 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1"} current state version: 5 [junit4] 2> 5310061 T19183 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 1 [junit4] 2> 5310064 T19212 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2) [junit4] 2> 5310066 T19212 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 2 [junit4] 2> 5310076 T19183 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:61337";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 5 [junit4] 2> 5310079 T19183 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 2 [junit4] 2> 5310080 T19212 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2) [junit4] 2> 5310081 T19212 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 3 [junit4] 2> 5310086 T19182 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5310139 T19217 oasc.ZkController.register We are http://127.0.0.1:61337/collection1/ and leader is http://127.0.0.1:61337/collection1/ [junit4] 2> 5310139 T19217 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:61337 [junit4] 2> 5310139 T19217 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 5310139 T19217 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 5310139 T19217 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5310144 T19182 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5310148 T19183 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:61337_", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "shard":"shard1", [junit4] 2> "base_url":"http://127.0.0.1:61337";, [junit4] 2> "roles":null, [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "core":"collection1"} current state version: 5 [junit4] 2> 5310149 T19183 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "node_name":"127.0.0.1:61337_", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "shard":"shard1", [junit4] 2> "base_url":"http://127.0.0.1:61337";, [junit4] 2> "roles":null, [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "core":"collection1"} [junit4] 2> 5310152 T19183 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 3 [junit4] 2> 5310154 T19212 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 2) [junit4] 2> 5310157 T19217 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 5310158 T19212 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 5312129 T19156 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-2-001\cores\collection1 [junit4] 2> 5312133 T19156 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 5312134 T19156 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 5312140 T19156 oejs.AbstractConnector.doStart Started [email protected]:61348 [junit4] 2> 5312144 T19156 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@1df59bd [junit4] 2> 5312144 T19156 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 5312144 T19156 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 B00F427E937560E5-001\shard-2-001 [junit4] 2> 5312147 T19156 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 B00F427E937560E5-001\shard-2-001\' [junit4] 2> 5312207 T19156 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-2-001\solr.xml [junit4] 2> 5312263 T19156 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-2-001\cores [junit4] 2> 5312263 T19156 oasc.CoreContainer.<init> New CoreContainer 21994967 [junit4] 2> 5312264 T19156 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 B00F427E937560E5-001\shard-2-001\] [junit4] 2> 5312264 T19156 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-2-001\lib [junit4] 2> 5312264 T19156 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-2-001\lib). [junit4] 2> 5312281 T19156 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 5312282 T19156 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 5312282 T19156 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 5312283 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 5312283 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 5312283 T19156 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 5312283 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 5312283 T19156 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 5312283 T19156 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 5312283 T19156 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 5312283 T19156 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 5312285 T19156 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 5312285 T19156 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 5312285 T19156 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 5312285 T19156 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 5312285 T19156 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:61314/solr [junit4] 2> 5312285 T19156 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 5312285 T19156 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 5312287 T19156 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5312291 T19230 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b1df63 name:ZooKeeperConnection Watcher:127.0.0.1:61314 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5312292 T19156 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5312293 T19156 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 5312297 T19156 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 5312300 T19233 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@111ea01 name:ZooKeeperConnection Watcher:127.0.0.1:61314/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 5312301 T19156 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 5312313 T19156 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 5313611 T19156 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:61347_ [junit4] 2> 5313613 T19156 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:61347_ [junit4] 2> 5313623 T19156 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 5313632 T19156 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-2-001\cores [junit4] 2> 5313634 T19156 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\shard-2-001\cores\collection1\ [junit4] 2> 5313634 T19156 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 5313642 T19234 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 5313643 T19234 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 5313647 T19182 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 5313648 T19234 oasc.ZkController.preRegister Registering watch for external collection collection1 [junit4] 2> 5313648 T19234 oascc.ZkStateReader.addZkWatch addZkWatch collection1 [junit4] 2> 5313650 T19183 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "node_name":"127.0.0.1:61347_", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "base_url":"http://127.0.0.1:61347";, [junit4] 2> "roles":null, [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "core":"collection1"} current state version: 5 [junit4] 2> 5313650 T19234 oascc.ZkStateReader.addZkWatch Updating collection state at /collections/collection1/state.json from ZooKeeper... [junit4] 2> 5313651 T19183 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "node_name":"127.0.0.1:61347_", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state", [junit4] 2> "shard":null, [junit4] 2> "base_url":"http://127.0.0.1:61347";, [junit4] 2> "roles":null, [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "core":"collection1"} [junit4] 2> 5313651 T19183 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 5313651 T19183 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard3 [junit4] 2> 5313653 T19183 oasco.ZkStateWriter.writePendingUpdates going to update_collection /collections/collection1/state.json version: 4 [junit4] 2> 5313655 T19234 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 4 [junit4] 2> 5313655 T19234 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 5313655 T19233 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 3) [junit4] 2> 5313656 T19212 oascc.ZkStateReader$7.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/collections/collection1/state.json for collection collection1 has occurred - updating... (live nodes size: 3) [junit4] 2> 5313657 T19233 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 5313660 T19212 oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to ver 5 [junit4] 2> 5315038 T19234 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 5315038 T19234 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 5315042 T19234 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 5315042 T19234 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 5315046 T19234 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 5315047 T19234 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 B00F427E937560E5-001\shard-2-001\cores\collection1\' [junit4] 2> 5315087 T19234 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 5315092 T19234 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 5315105 T19234 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 5315137 T19234 oasc.SolrConfig.<init> Using Lucene MatchVersion: 5.1.0 [junit4] 2> 5315274 T19234 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 5315278 T19234 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 5315297 T19234 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 5315831 T19234 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 5315842 T19234 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 5315847 T19234 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 5315867 T19234 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5315874 T19234 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 5315881 T19234 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5315884 T19234 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5315884 T19234 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5315884 T19234 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 5315887 T19234 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 5315887 T19234 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 5315888 T19234 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 5315888 T19234 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 5315889 T19234 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 B00F427E937560E5-001\shard-2-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest B00F427E937560E5-001\tempDir-001/jetty2\ [junit4] 2> 5315889 T19234 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1131db5 [junit4] 2> 5315891 T19234 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 B00F427E937560E5-001\tempDir-001/jetty2\ [junit4] 2> 5315891 T19234 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 B00F427E937560E5-001\tempDir-001/jetty2\index/ [junit4] 2> 5315891 T19234 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 B00F427E937560E5-001\tempDir-001\jetty2\index' doesn't exist. Creating new index... [junit4] 2> 5315892 T19234 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 B00F427E937560E5-001\tempDir-001/jetty2\index [junit4] 2> 5315893 T19234 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteS [...truncated too long message...] 2> NOTE: All tests run in this JVM: [TestInfoStreamLogging, TestExceedMaxTermLength, TestIBSimilarityFactory, TestBlobHandler, SolrCoreCheckLockOnStartupTest, TestConfig, DocumentAnalysisRequestHandlerTest, TestHdfsUpdateLog, TestSuggestSpellingConverter, UniqFieldsUpdateProcessorFactoryTest, HdfsWriteToMultipleCollectionsTest, SolrTestCaseJ4Test, CoreAdminRequestStatusTest, LeaderFailoverAfterPartitionTest, TestChildDocTransformer, TestReplicationHandlerBackup, TestTrie, TestNonDefinedSimilarityFactory, DirectUpdateHandlerTest, TestSolrQueryParserResource, HdfsSyncSliceTest, OverseerRolesTest, TestRTGBase, BasicFunctionalityTest, CollectionsAPIAsyncDistributedZkTest, BadComponentTest, BinaryUpdateRequestHandlerTest, CloudExitableDirectoryReaderTest, TestRebalanceLeaders, FieldAnalysisRequestHandlerTest, TestExpandComponent, AutoCommitTest, SpellPossibilityIteratorTest, TestObjectReleaseTracker, TestBinaryField, SchemaVersionSpecificBehaviorTest, SortByFunctionTest, TestJmxIntegration, TestBM25SimilarityFactory, TestStressRecovery, SolrCmdDistributorTest, HdfsBasicDistributedZkTest, TestDynamicLoading, TestSchemaSimilarityResource, DistanceUnitsTest, TestAtomicUpdateErrorCases, DistributedSpellCheckComponentTest, SystemInfoHandlerTest, TestManagedSynonymFilterFactory, TestRecoveryHdfs, StandardRequestHandlerTest, TestAddFieldRealTimeGet, DateRangeFieldTest, CSVRequestHandlerTest, TestReRankQParserPlugin, TestManagedSchemaDynamicFieldResource, UpdateRequestProcessorFactoryTest, RollingRestartTest, BasicDistributedZkTest, PreAnalyzedFieldTest, TestSimpleTrackingShardHandler, RegexBoostProcessorTest, TestSolrDeletionPolicy2, TestIntervalFaceting, TestMissingGroups, TestManagedSchema, OverseerCollectionProcessorTest, TestLMJelinekMercerSimilarityFactory, TestSchemaNameResource, SuggesterTest, TestRemoteStreaming, PrimitiveFieldTypeTest, QueryElevationComponentTest, TestSolrCoreProperties, TestShardHandlerFactory, TermVectorComponentDistributedTest, TestCursorMarkWithoutUniqueKey, TestLMDirichletSimilarityFactory, SpatialHeatmapFacetsTest, BlockDirectoryTest, AddBlockUpdateTest, DistributedQueryComponentOptimizationTest, DateMathParserTest, ConvertedLegacyTest, LeaderInitiatedRecoveryOnCommitTest, TestPhraseSuggestions, DirectSolrSpellCheckerTest, DeleteReplicaTest, OutOfBoxZkACLAndCredentialsProvidersTest, TestPostingsSolrHighlighter, DistanceFunctionTest, SuggestComponentTest, QueryParsingTest, FileUtilsTest, TestExactStatsCache, CursorPagingTest, TermsComponentTest, UnloadDistributedZkTest, DistribCursorPagingTest, TestFreeTextSuggestions, SOLR749Test, TestSortingResponseWriter, TestFieldTypeResource, TestInitParams, IndexSchemaRuntimeFieldTest, OverriddenZkACLAndCredentialsProvidersTest, AsyncMigrateRouteKeyTest, TestSolrConfigHandler, CoreAdminCreateDiscoverTest, TestFastWriter, RankQueryTest, ZkNodePropsTest, TestDocSet, SaslZkACLProviderTest, SpatialRPTFieldTypeTest, TestUpdate, OpenExchangeRatesOrgProviderTest, DistributedIntervalFacetingTest, ResponseLogComponentTest, TestBadConfig, AtomicUpdatesTest, TestClusterStateMutator, TestPHPSerializedResponseWriter, DefaultValueUpdateProcessorTest, TestRandomMergePolicy, TestComplexPhraseQParserPlugin, TestCloudManagedSchemaConcurrent, ConnectionManagerTest, TestReload, TestCSVLoader, MultiThreadedOCPTest, DocValuesMultiTest, SolrXmlInZkTest, TestLFUCache, UUIDUpdateProcessorFallbackTest, SuggesterFSTTest, HdfsCollectionsAPIDistributedZkTest, LeaderElectionIntegrationTest, DistribDocExpirationUpdateProcessorTest, TestManagedSchemaFieldTypeResource, TestBlendedInfixSuggestions, BlockCacheTest, TestSchemaResource, TestCodecSupport, SolrPluginUtilsTest, TestFunctionQuery, TestBulkSchemaConcurrent, TestHighFrequencyDictionaryFactory, DistributedFacetPivotWhiteBoxTest, SharedFSAutoReplicaFailoverTest, TestLazyCores, BufferStoreTest, TestSort, TestConfigReload, SolrRequestParserTest, InfoHandlerTest, BJQParserTest, TestCloudPivotFacet, ChaosMonkeySafeLeaderTest, HdfsRecoveryZkTest, TestAnalyzedSuggestions, TestZkChroot, TestSolrJ, FullSolrCloudDistribCmdsTest, TestSolr4Spatial2, TestStressVersions, TestAnalyzeInfixSuggestions, MoreLikeThisHandlerTest, TestFieldResource, SliceStateTest, TestSolrXml, CoreMergeIndexesAdminHandlerTest, CustomCollectionTest, TestConfigOverlay, TestUniqueKeyFieldResource, TermVectorComponentTest, DateFieldTest, ZkStateWriterTest, TestFaceting, StressHdfsTest, PolyFieldTest, AddSchemaFieldsUpdateProcessorFactoryTest, TestJmxMonitoredMap, TestDistributedMissingSort, DistributedDebugComponentTest, OverseerStatusTest, TestInitQParser, TestCSVResponseWriter, TestDistributedGrouping, TestDistribDocBasedVersion, TestWriterPerf, TestTolerantSearch, TestImplicitCoreProperties, BadIndexSchemaTest, DisMaxRequestHandlerTest, URLClassifyProcessorTest, TestPartialUpdateDeduplication, TestIndexSearcher, TestDocumentBuilder, TestBinaryResponseWriter, DocValuesTest, AnalyticsMergeStrategyTest, IndexBasedSpellCheckerTest, HdfsDirectoryTest, TestDefaultSimilarityFactory, TestSolrIndexConfig, TestJoin, TestGroupingSearch, TestElisionMultitermQuery, WordBreakSolrSpellCheckerTest, LoggingHandlerTest, SyncSliceTest, TestDocBasedVersionConstraints, UUIDFieldTest, TestSerializedLuceneMatchVersion, TestExtendedDismaxParser, TestSweetSpotSimilarityFactory, XmlUpdateRequestHandlerTest, RemoteQueryErrorTest, RecoveryAfterSoftCommitTest, CircularListTest, SuggesterTSTTest, CopyFieldTest, TestWordDelimiterFilterFactory, SolrIndexConfigTest, XsltUpdateRequestHandlerTest, ShardRoutingCustomTest, CollectionsAPIDistributedZkTest, BadCopyFieldTest, BasicDistributedZk2Test, OpenCloseCoreStressTest, OverseerTest, ShardRoutingTest, BasicZkTest, RecoveryZkTest, TestReplicationHandler, ClusterStateUpdateTest, TestRandomFaceting, LeaderElectionTest, ZkSolrClientTest, TestRandomDVFaceting, ZkCLITest, TestDistributedSearch, TestRecovery, TestHashPartitioner, ZkControllerTest, TestRealTimeGet, TestStressReorder, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery, TestCoreContainer, SimpleFacetsTest, TestSolr4Spatial, SolrCoreTest, StatsComponentTest, SpellCheckComponentTest, PeerSyncTest, TestFiltering, HighlighterTest, SoftAutoCommitTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, SolrIndexSplitterTest, SimplePostToolTest, TestCoreDiscovery, AnalysisAfterCoreReloadTest, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, CoreAdminHandlerTest, TestFoldingMultitermQuery, TestStressLucene, SpatialFilterTest, SuggesterWFSTTest, NoCacheHeaderTest, TestPseudoReturnFields, FieldMutatingUpdateProcessorTest, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, StatelessScriptUpdateProcessorFactoryTest, TestSolrDeletionPolicy1, SolrInfoMBeanTest, DebugComponentTest, CacheHeaderTest, TestSurroundQueryParser, LukeRequestHandlerTest, TestQueryUtils, TestReversedWildcardFilterFactory, TestQueryTypes, TestOmitPositions, FileBasedSpellCheckerTest, RequestHandlersTest, DocumentBuilderTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, RequiredFieldsTest, TestSolrQueryParser, TestArbitraryIndexDir, FastVectorHighlighterTest, IndexSchemaTest, TestCollationField, ReturnFieldsTest, JSONWriterTest, MBeansHandlerTest, JsonLoaderTest, PingRequestHandlerTest, TestComponentsName, SearchHandlerTest, HighlighterConfigTest, TestQuerySenderListener, UpdateParamsTest, AlternateDirectoryTest, TestQuerySenderNoQuery, TestMergePolicyConfig, MultiTermTest, SampleTest, TestSearchPerf, NumericFieldsTest, MinimalSchemaTest, OutputWriterTest, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, DirectSolrConnectionTest, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestCharFilters, SynonymTokenizerTest, TestXIncludeConfig, EchoParamsTest, TestDFRSimilarityFactory, TestPerFieldSimilarity, TimeZoneUtilsTest, ResourceLoaderTest, TestFastOutputStream, ScriptEngineTest, PluginInfoTest, TestFastLRUCache, ChaosMonkeyNothingIsSafeTest, TestSystemIdResolver, PrimUtilsTest, SpellingQueryConverterTest, DOMUtilTest, RAMDirectoryFactoryTest, ClusterStateTest, TestLRUCache, TestUtils, DistributedMLTComponentTest, TestHighlightDedupGrouping, ActionThrottleTest, AliasIntegrationTest, AssignTest, DeleteInactiveReplicaTest, DeleteLastCustomShardedReplicaTest, DeleteShardTest, DistribJoinFromCollectionTest, DistributedQueueTest, ExternalCollectionsTest, HttpPartitionTest, MigrateRouteKeyTest, ReplicationFactorTest] [junit4] Completed in 85.84s, 1 test, 1 error <<< FAILURES! [...truncated 300 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:191: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\solr\common-build.xml:509: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:1370: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-5.x-Windows\lucene\common-build.xml:976: There were test failures: 469 suites, 1871 tests, 1 error, 57 ignored (30 assumptions) Total time: 147 minutes 9 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/jdk1.7.0_76 -server -XX:+UseG1GC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: [email protected] For additional commands, e-mail: [email protected]
