Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4534/ Java: 32bit/jdk1.8.0_31 -client -XX:+UseConcMarkSweepGC
1 tests failed. FAILED: org.apache.solr.cloud.ReplicationFactorTest.test Error Message: org.apache.http.NoHttpResponseException: The target server failed to respond Stack Trace: org.apache.solr.client.solrj.SolrServerException: org.apache.http.NoHttpResponseException: The target server failed to respond at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:901) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:754) at org.apache.solr.client.solrj.SolrClient.request(SolrClient.java:1220) at org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:284) at org.apache.solr.cloud.ReplicationFactorTest.test(ReplicationFactorTest.java:112) at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method) at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:483) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:958) at org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:933) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:461) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:211) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:207) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:370) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:325) at org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:568) at org.apache.solr.client.solrj.impl.CloudSolrClient$2.call(CloudSolrClient.java:565) at java.util.concurrent.FutureTask.run(FutureTask.java:266) at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142) at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617) ... 1 more Build Log: [...truncated 10157 lines...] [junit4] Suite: org.apache.solr.cloud.ReplicationFactorTest [junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\init-core-data-001 [junit4] 2> 4760805 T20711 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /shd/ik [junit4] 2> 4760818 T20711 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4760819 T20712 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 4760926 T20711 oasc.ZkTestServer.run start zk server on port:56111 [junit4] 2> 4760926 T20711 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4760929 T20711 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4760935 T20719 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1cdba40 name:ZooKeeperConnection Watcher:127.0.0.1:56111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4760937 T20711 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4760937 T20711 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4760938 T20711 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 4760943 T20711 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4760945 T20711 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4760949 T20722 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a442fc name:ZooKeeperConnection Watcher:127.0.0.1:56111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4760949 T20711 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4760949 T20711 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4760949 T20711 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 4760952 T20711 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 4760958 T20711 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 4760962 T20711 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 4760967 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml [junit4] 2> 4760967 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4760973 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml [junit4] 2> 4760973 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 4760984 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4760985 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4760991 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt [junit4] 2> 4760992 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 4760996 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt [junit4] 2> 4760996 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 4761002 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml [junit4] 2> 4761004 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 4761008 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\enumsConfig.xml to /configs/conf1/enumsConfig.xml [junit4] 2> 4761009 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4761013 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json [junit4] 2> 4761014 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4761018 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4761018 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4761023 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt [junit4] 2> 4761023 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4761030 T20711 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\core\src\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt [junit4] 2> 4761031 T20711 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 4762829 T20711 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1 [junit4] 2> 4762836 T20711 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 4762841 T20711 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@1dfa03b{/shd/ik,null,AVAILABLE} [junit4] 2> 4762847 T20711 oejs.AbstractConnector.doStart Started ServerConnector@115fa68{HTTP/1.1}{127.0.0.1:56119} [junit4] 2> 4762850 T20711 oejs.Server.doStart Started @4750222ms [junit4] 2> 4762850 T20711 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\tempDir-001/control/data, hostContext=/shd/ik, hostPort=56118, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores} [junit4] 2> 4762854 T20711 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44 [junit4] 2> 4762854 T20711 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\' [junit4] 2> 4762911 T20711 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\solr.xml [junit4] 2> 4762920 T20711 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores [junit4] 2> 4762921 T20711 oasc.CoreContainer.<init> New CoreContainer 24459565 [junit4] 2> 4762921 T20711 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\] [junit4] 2> 4762921 T20711 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\lib [junit4] 2> 4762921 T20711 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\lib). [junit4] 2> 4762938 T20711 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4762938 T20711 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4762939 T20711 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4762939 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4762940 T20711 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4762941 T20711 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4762941 T20711 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4762942 T20711 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4762942 T20711 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 4762942 T20711 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56111/solr [junit4] 2> 4762942 T20711 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4762942 T20711 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4762944 T20711 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4762947 T20736 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@65c1ca name:ZooKeeperConnection Watcher:127.0.0.1:56111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4762947 T20711 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4762948 T20711 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4762952 T20711 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4762955 T20739 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@12974a6 name:ZooKeeperConnection Watcher:127.0.0.1:56111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4762955 T20711 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4762959 T20711 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 4762965 T20711 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 4762970 T20711 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 4762974 T20711 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 4762978 T20711 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 4762984 T20711 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 4762987 T20711 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56118_shd%2Fik [junit4] 2> 4762988 T20711 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56118_shd%2Fik [junit4] 2> 4762992 T20711 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 4762996 T20711 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 4762999 T20711 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4763001 T20711 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:56118_shd%2Fik [junit4] 2> 4763002 T20711 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 4763005 T20711 oasc.Overseer.start Overseer (id=93446827646255107-127.0.0.1:56118_shd%2Fik-n_0000000000) starting [junit4] 2> 4763010 T20711 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 4763023 T20711 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4763025 T20740 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 4763025 T20741 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 4763026 T20711 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 4763032 T20711 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 4763036 T20711 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4763046 T20711 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores [junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1, collection=control_collection, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1\, coreNodeName=, dataDir=data\, shard=} [junit4] 2> 4763048 T20711 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1\ [junit4] 2> 4763049 T20711 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4763051 T20743 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 4763052 T20743 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4763053 T20739 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4763053 T20743 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4763055 T20740 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56118/shd/ik", [junit4] 2> "node_name":"127.0.0.1:56118_shd%2Fik", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 0 [junit4] 2> 4763057 T20740 oasco.ReplicaMutator.updateState Update state numShards=1 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56118/shd/ik", [junit4] 2> "node_name":"127.0.0.1:56118_shd%2Fik", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4763058 T20740 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 4763058 T20740 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 4763060 T20739 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> 4764087 T20743 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4764087 T20743 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 4764089 T20743 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4764090 T20743 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4764092 T20743 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4764092 T20743 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1\' [junit4] 2> 4764129 T20743 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4764133 T20743 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4764146 T20743 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4764155 T20743 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4764161 T20743 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4764165 T20743 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4764174 T20743 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4764316 T20743 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4764322 T20743 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4764323 T20743 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4764342 T20743 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4764347 T20743 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4764353 T20743 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4764355 T20743 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4764365 T20743 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4764365 T20743 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4764369 T20743 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4764369 T20743 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4764369 T20743 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4764370 T20743 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4764370 T20743 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1\data\ [junit4] 2> 4764370 T20743 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1844159 [junit4] 2> 4764371 T20743 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1\data\ [junit4] 2> 4764371 T20743 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1\data\index/ [junit4] 2> 4764371 T20743 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 4764372 T20743 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\control-001\cores\collection1\data\index [junit4] 2> 4764373 T20743 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=95.150390625, floorSegmentMB=0.251953125, forceMergeDeletesPctAllowed=7.370749593348257, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 4764376 T20743 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@10d7970 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5dbfa6),segFN=segments_1,generation=1} [junit4] 2> 4764376 T20743 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4764385 T20743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4764386 T20743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4764387 T20743 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4764387 T20743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4764388 T20743 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4764388 T20743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4764390 T20743 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4764396 T20743 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4764396 T20743 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4764403 T20743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4764406 T20743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4764408 T20743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4764413 T20743 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4764423 T20743 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 4764424 T20743 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4764426 T20743 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4764426 T20743 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 4764428 T20743 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4764428 T20743 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4764428 T20743 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=7, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=86.7216796875, floorSegmentMB=2.1728515625, forceMergeDeletesPctAllowed=1.7278524219281666, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 4764429 T20743 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@10d7970 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@5dbfa6),segFN=segments_1,generation=1} [junit4] 2> 4764429 T20743 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4764430 T20743 oass.SolrIndexSearcher.<init> Opening Searcher@19b3949[collection1] main [junit4] 2> 4764430 T20743 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4764432 T20743 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4764432 T20743 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4764433 T20743 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4764434 T20743 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4764434 T20743 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4764435 T20743 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4764436 T20743 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4764436 T20743 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4764436 T20743 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4764437 T20743 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4764438 T20744 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19b3949[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4764440 T20747 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:56118/shd/ik collection:control_collection shard:shard1 [junit4] 2> 4764441 T20711 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 4764441 T20711 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4764442 T20747 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 4764443 T20711 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4764443 T20711 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4764446 T20751 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@efb8ee name:ZooKeeperConnection Watcher:127.0.0.1:56111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4764447 T20711 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4764448 T20711 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4764450 T20711 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4764454 T20747 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 4764458 T20739 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4764458 T20747 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4764459 T20747 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7187 name=collection1 org.apache.solr.core.SolrCore@1535745 url=http://127.0.0.1:56118/shd/ik/collection1 node=127.0.0.1:56118_shd%2Fik C7187_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:56118/shd/ik, node_name=127.0.0.1:56118_shd%2Fik, state=down} [junit4] 2> 4764459 T20747 C7187 P56118 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:56118/shd/ik/collection1/ [junit4] 2> 4764459 T20747 C7187 P56118 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4764459 T20747 C7187 P56118 oasc.SyncStrategy.syncToMe http://127.0.0.1:56118/shd/ik/collection1/ has no replicas [junit4] 2> 4764459 T20747 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:56118/shd/ik/collection1/ shard1 [junit4] 2> 4764460 T20747 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4764461 T20740 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> 4764462 T20711 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4764476 T20739 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4764480 T20740 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:56118/shd/ik", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 4764585 T20739 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> 4764586 T20751 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> 4764627 T20747 oasc.ZkController.register We are http://127.0.0.1:56118/shd/ik/collection1/ and leader is http://127.0.0.1:56118/shd/ik/collection1/ [junit4] 2> 4764627 T20747 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56118/shd/ik [junit4] 2> 4764627 T20747 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4764627 T20747 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 4764627 T20747 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4764633 T20739 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4764640 T20740 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56118/shd/ik", [junit4] 2> "node_name":"127.0.0.1:56118_shd%2Fik", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} current state version: 2 [junit4] 2> 4764642 T20740 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56118/shd/ik", [junit4] 2> "node_name":"127.0.0.1:56118_shd%2Fik", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4764749 T20739 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> 4764751 T20751 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> 4766097 T20711 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1 [junit4] 2> 4766102 T20711 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 in directory C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001 [junit4] 2> 4766103 T20711 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 4766106 T20711 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@13f2e05{/shd/ik,null,AVAILABLE} [junit4] 2> 4766109 T20711 oejs.AbstractConnector.doStart Started ServerConnector@173f165{HTTP/1.1}{127.0.0.1:56132} [junit4] 2> 4766109 T20711 oejs.Server.doStart Started @4753565ms [junit4] 2> 4766110 T20711 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\tempDir-001/jetty1, solrconfig=solrconfig.xml, hostContext=/shd/ik, hostPort=56131, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores} [junit4] 2> 4766110 T20711 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44 [junit4] 2> 4766112 T20711 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\' [junit4] 2> 4766152 T20711 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\solr.xml [junit4] 2> 4766163 T20711 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores [junit4] 2> 4766163 T20711 oasc.CoreContainer.<init> New CoreContainer 27072979 [junit4] 2> 4766163 T20711 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\] [junit4] 2> 4766164 T20711 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\lib [junit4] 2> 4766164 T20711 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\lib). [junit4] 2> 4766178 T20711 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4766179 T20711 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4766179 T20711 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4766180 T20711 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4766180 T20711 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4766180 T20711 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4766182 T20711 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4766182 T20711 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 4766182 T20711 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56111/solr [junit4] 2> 4766182 T20711 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4766182 T20711 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4766184 T20711 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4766187 T20765 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16d6064 name:ZooKeeperConnection Watcher:127.0.0.1:56111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4766187 T20711 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4766187 T20711 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4766191 T20713 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14bfd65073e0005, likely client has closed socket [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:228) [junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208) [junit4] 2> at java.lang.Thread.run(Thread.java:745) [junit4] 2> [junit4] 2> 4766192 T20711 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4766195 T20768 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@f0e83e name:ZooKeeperConnection Watcher:127.0.0.1:56111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4766195 T20711 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4766208 T20711 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4767175 T20711 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56131_shd%2Fik [junit4] 2> 4767177 T20711 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56131_shd%2Fik [junit4] 2> 4767184 T20711 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4767190 T20711 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores [junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1, collection=collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1\, coreNodeName=, dataDir=data\, shard=} [junit4] 2> 4767193 T20711 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1\ [junit4] 2> 4767193 T20711 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4767196 T20769 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4767197 T20769 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4767198 T20739 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4767198 T20769 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4767199 T20740 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56131/shd/ik", [junit4] 2> "node_name":"127.0.0.1:56131_shd%2Fik", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 3 [junit4] 2> 4767201 T20740 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56131/shd/ik", [junit4] 2> "node_name":"127.0.0.1:56131_shd%2Fik", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4767201 T20740 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 4767201 T20740 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 4767204 T20751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4767204 T20768 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4767204 T20739 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4768158 T20769 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4768158 T20769 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4768160 T20769 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4768160 T20769 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4768162 T20769 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4768162 T20769 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1\' [junit4] 2> 4768190 T20769 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4768195 T20769 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4768208 T20769 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4768216 T20769 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4768230 T20769 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4768232 T20769 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4768247 T20769 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4768425 T20769 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4768436 T20769 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4768436 T20769 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4768462 T20769 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4768467 T20769 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4768472 T20769 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4768474 T20769 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4768474 T20769 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4768475 T20769 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4768477 T20769 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4768477 T20769 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4768478 T20769 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4768478 T20769 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4768479 T20769 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1\data\ [junit4] 2> 4768479 T20769 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1844159 [junit4] 2> 4768481 T20769 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1\data\ [junit4] 2> 4768481 T20769 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1\data\index/ [junit4] 2> 4768481 T20769 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 4768483 T20769 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-1-001\cores\collection1\data\index [junit4] 2> 4768484 T20769 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=95.150390625, floorSegmentMB=0.251953125, forceMergeDeletesPctAllowed=7.370749593348257, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 4768486 T20769 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@171766b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13e9574),segFN=segments_1,generation=1} [junit4] 2> 4768486 T20769 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4768506 T20769 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4768507 T20769 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4768508 T20769 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4768508 T20769 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4768509 T20769 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4768509 T20769 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4768510 T20769 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4768511 T20769 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4768511 T20769 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4768520 T20769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4768524 T20769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4768528 T20769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4768530 T20769 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4768544 T20769 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 4768545 T20769 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4768546 T20769 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4768546 T20769 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 4768548 T20769 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4768548 T20769 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4768550 T20769 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=7, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=86.7216796875, floorSegmentMB=2.1728515625, forceMergeDeletesPctAllowed=1.7278524219281666, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 4768550 T20769 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@171766b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@13e9574),segFN=segments_1,generation=1} [junit4] 2> 4768551 T20769 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4768551 T20769 oass.SolrIndexSearcher.<init> Opening Searcher@11132f9[collection1] main [junit4] 2> 4768552 T20769 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4768554 T20769 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4768554 T20769 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4768555 T20769 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4768556 T20769 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4768556 T20769 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4768557 T20769 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4768557 T20769 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4768558 T20769 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4768558 T20769 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4768560 T20769 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4768561 T20770 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@11132f9[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4768562 T20773 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:56131/shd/ik collection:collection1 shard:shard2 [junit4] 2> 4768563 T20711 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 4768564 T20711 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4768565 T20773 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 4768579 T20773 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 4768585 T20739 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4768586 T20773 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4768586 T20773 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7188 name=collection1 org.apache.solr.core.SolrCore@342579 url=http://127.0.0.1:56131/shd/ik/collection1 node=127.0.0.1:56131_shd%2Fik C7188_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:56131/shd/ik, node_name=127.0.0.1:56131_shd%2Fik, state=down} [junit4] 2> 4768586 T20773 C7188 P56131 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:56131/shd/ik/collection1/ [junit4] 2> 4768587 T20773 C7188 P56131 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4768587 T20773 C7188 P56131 oasc.SyncStrategy.syncToMe http://127.0.0.1:56131/shd/ik/collection1/ has no replicas [junit4] 2> 4768587 T20773 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:56131/shd/ik/collection1/ shard2 [junit4] 2> 4768589 T20773 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 4768592 T20740 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1"} current state version: 4 [junit4] 2> 4768606 T20739 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4768609 T20740 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "operation":"leader", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "base_url":"http://127.0.0.1:56131/shd/ik", [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 4768713 T20768 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4768714 T20739 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4768716 T20751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4768756 T20773 oasc.ZkController.register We are http://127.0.0.1:56131/shd/ik/collection1/ and leader is http://127.0.0.1:56131/shd/ik/collection1/ [junit4] 2> 4768756 T20773 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:56131/shd/ik [junit4] 2> 4768756 T20773 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4768756 T20773 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 4768756 T20773 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4768760 T20739 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4768764 T20740 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56131/shd/ik", [junit4] 2> "node_name":"127.0.0.1:56131_shd%2Fik", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 5 [junit4] 2> 4768765 T20740 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "core_node_name":"core_node1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56131/shd/ik", [junit4] 2> "node_name":"127.0.0.1:56131_shd%2Fik", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4768867 T20751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4768869 T20768 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4768870 T20739 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2) [junit4] 2> 4770079 T20711 oas.SolrTestCaseJ4.writeCoreProperties Writing core.properties file to C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1 [junit4] 2> 4770083 T20711 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 in directory C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001 [junit4] 2> 4770085 T20711 oejs.Server.doStart jetty-9.2.9.v20150224 [junit4] 2> 4770092 T20711 oejsh.ContextHandler.doStart Started o.e.j.s.ServletContextHandler@b03e13{/shd/ik,null,AVAILABLE} [junit4] 2> 4770093 T20711 oejs.AbstractConnector.doStart Started ServerConnector@15e66c1{HTTP/1.1}{127.0.0.1:56142} [junit4] 2> 4770094 T20711 oejs.Server.doStart Started @4757759ms [junit4] 2> 4770094 T20711 oascse.JettySolrRunner$1.lifeCycleStarted Jetty properties: {solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\tempDir-001/jetty2, solrconfig=solrconfig.xml, hostContext=/shd/ik, hostPort=56141, coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores} [junit4] 2> 4770094 T20711 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44 [junit4] 2> 4770096 T20711 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\' [junit4] 2> 4770144 T20711 oasc.SolrXmlConfig.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\solr.xml [junit4] 2> 4770157 T20711 oasc.CorePropertiesLocator.<init> Config-defined core root directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores [junit4] 2> 4770157 T20711 oasc.CoreContainer.<init> New CoreContainer 7964319 [junit4] 2> 4770157 T20711 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\] [junit4] 2> 4770157 T20711 oasc.CoreContainer.load loading shared library: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\lib [junit4] 2> 4770157 T20711 oasc.SolrResourceLoader.addToClassLoader WARN Can't find (or read) directory to add to classloader: lib (resolved as: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\lib). [junit4] 2> 4770176 T20711 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4770176 T20711 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4770176 T20711 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4770176 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4770176 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4770179 T20711 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4770179 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4770179 T20711 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4770179 T20711 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4770179 T20711 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4770180 T20711 oashc.HttpShardHandlerFactory.getParameter Setting useRetries to: false [junit4] 2> 4770181 T20711 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=true [junit4] 2> 4770181 T20711 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4770182 T20711 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4770183 T20711 oasc.CoreContainer.load Node Name: 127.0.0.1 [junit4] 2> 4770183 T20711 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:56111/solr [junit4] 2> 4770183 T20711 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4770183 T20711 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4770187 T20711 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4770189 T20791 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@124d2c2 name:ZooKeeperConnection Watcher:127.0.0.1:56111 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4770190 T20711 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4770191 T20711 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4770194 T20711 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4770198 T20794 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a6cb8d name:ZooKeeperConnection Watcher:127.0.0.1:56111/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4770199 T20711 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4770211 T20711 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4771180 T20711 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:56141_shd%2Fik [junit4] 2> 4771181 T20711 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:56141_shd%2Fik [junit4] 2> 4771189 T20711 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4771194 T20711 oasc.CorePropertiesLocator.discover Looking for core definitions underneath C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores [junit4] 1> CORE DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false, schema=schema.xml, loadOnStartup=true, instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1, collection=collection1, absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1\, coreNodeName=, dataDir=data\, shard=} [junit4] 2> 4771194 T20711 oasc.CorePropertiesLocator.discoverUnder Found core collection1 in C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1\ [junit4] 2> 4771196 T20711 oasc.CorePropertiesLocator.discover Found 1 core definitions [junit4] 2> 4771197 T20795 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4771198 T20795 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4771199 T20739 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4771199 T20795 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4771201 T20740 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 1, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56141/shd/ik", [junit4] 2> "node_name":"127.0.0.1:56141_shd%2Fik", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} current state version: 6 [junit4] 2> 4771202 T20740 oasco.ReplicaMutator.updateState Update state numShards=3 message={ [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:56141/shd/ik", [junit4] 2> "node_name":"127.0.0.1:56141_shd%2Fik", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4771203 T20740 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 4771204 T20740 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard3 [junit4] 2> 4771207 T20751 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 4771207 T20768 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 4771208 T20794 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 4771208 T20739 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3) [junit4] 2> 4772160 T20795 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4772160 T20795 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4772161 T20795 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4772162 T20795 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4772164 T20795 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4772164 T20795 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1\' [junit4] 2> 4772197 T20795 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4772201 T20795 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4772215 T20795 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4772219 T20795 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4772229 T20795 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4772231 T20795 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4772245 T20795 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4772395 T20795 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4772403 T20795 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4772404 T20795 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4772422 T20795 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4772436 T20795 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4772442 T20795 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4772445 T20795 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4772445 T20795 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4772446 T20795 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4772448 T20795 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4772448 T20795 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4772449 T20795 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4772449 T20795 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4772450 T20795 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1\data\ [junit4] 2> 4772450 T20795 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1844159 [junit4] 2> 4772451 T20795 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1\data\ [junit4] 2> 4772452 T20795 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1\data\index/ [junit4] 2> 4772452 T20795 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1\data\index' doesn't exist. Creating new index... [junit4] 2> 4772453 T20795 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest 32516BE7A65674CF-001\shard-2-001\cores\collection1\data\index [junit4] 2> 4772453 T20795 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=18, maxMergeAtOnceExplicit=28, maxMergedSegmentMB=95.150390625, floorSegmentMB=0.251953125, forceMergeDeletesPctAllowed=7.370749593348257, segmentsPerTier=40.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 4772455 T20795 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@100896b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@19163c),segFN=segments_1,generation=1} [junit4] 2> 4772455 T20795 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4772463 T20795 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4772464 T20795 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4772465 T20795 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4772465 T20795 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4772466 T20795 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4772466 T20795 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4772467 T20795 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4772467 T20795 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4772467 T20795 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4772472 T20795 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4772477 T20795 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4772480 T20795 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4772482 T20795 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4772495 T20795 oasc.RequestHandlers.initHandlersFromConfig Registered paths: /admin/mbeans,standard,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin/threads,/admin/ping,/update,/admin/file [junit4] 2> 4772495 T20795 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4772497 T20795 oasu.UpdateHandler.<init> Using UpdateLog implementation: org.apache.solr.update.UpdateLog [junit4] 2> 4772497 T20795 oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH numRecordsToKeep=100 maxNumLogsToKeep=10 [junit4] 2> 4772500 T20795 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4772500 T20795 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4772500 T20795 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.TieredMergePolicy: [TieredMergePolicy: maxMergeAtOnce=7, maxMergeAtOnceExplicit=5, maxMergedSegmentMB=86.7216796875, floorSegmentMB=2.1728515625, forceMergeDeletesPctAllowed=1.7278524219281666, segmentsPerTier=31.0, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=1.0 [junit4] 2> 4772500 T20795 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDir [...truncated too long message...] perties, SpatialRPTFieldTypeTest, FullSolrCloudDistribCmdsTest, SolrIndexSplitterTest, SolrTestCaseJ4Test, TestSolrConfigHandler, TestJmxMonitoredMap, ExternalCollectionsTest, QueryResultKeyTest, ResponseHeaderTest, TestFileDictionaryLookup, DistributedSpellCheckComponentTest, TestCloudPivotFacet, BlockCacheTest, InfoHandlerTest, TestReplicaProperties, TestSweetSpotSimilarityFactory, SuggestComponentTest, TestIBSimilarityFactory, TestPostingsSolrHighlighter, TestCloudInspectUtil, TestPHPSerializedResponseWriter, UUIDUpdateProcessorFallbackTest, TestRawResponseWriter, TestCharFilters, TestSolr4Spatial2, DistributedFacetPivotLongTailTest, BasicDistributedZkTest, TestCodecSupport, DirectSolrConnectionTest, TestReRankQParserPlugin, SaslZkACLProviderTest, SpellCheckComponentTest, PreAnalyzedUpdateProcessorTest, TestSystemIdResolver, PrimUtilsTest, TestStressLucene, UniqFieldsUpdateProcessorFactoryTest, DistributedQueryComponentCustomSortTest, TestManagedResource, TestWordDelimiterFilterFactory, CacheHeaderTest, DistribDocExpirationUpdateProcessorTest, ConvertedLegacyTest, BlockDirectoryTest, AnalysisAfterCoreReloadTest, TestHighlightDedupGrouping, XmlUpdateRequestHandlerTest, TestCoreContainer, TestManagedResourceStorage, BadIndexSchemaTest, ClusterStateTest, TestSolrDeletionPolicy1, TestLeaderElectionZkExpiry, TestDocBasedVersionConstraints, TestSolrQueryParser, TestBinaryField, TestLuceneMatchVersion, UpdateParamsTest, TestSolrDynamicMBean, AsyncMigrateRouteKeyTest, TestHashPartitioner, TestRestManager, SpellPossibilityIteratorTest, SpellingQueryConverterTest, TestJoin, TestUpdate, TestBM25SimilarityFactory, TestQueryTypes, TestReversedWildcardFilterFactory, DistributedDebugComponentTest, CurrencyFieldXmlFileTest, HdfsRecoveryZkTest, TestDefaultSimilarityFactory, TestSchemaNameResource, ParsingFieldUpdateProcessorsTest, SolrCoreTest, SSLMigrationTest, TestStandardQParsers, ExitableDirectoryReaderTest, JsonLoaderTest, OutputWriterTest, SoftAutoCommitTest, TestDistributedMissingSort, TestImplicitCoreProperties, BinaryUpdateRequestHandlerTest, TestReqParamsAPI, TestMiniSolrCloudClusterSSL, DistributedSuggestComponentTest, TestComplexPhraseQParserPlugin, TestLRUStatsCache, TestStressUserVersions, TestCloudManagedSchemaConcurrent, TestManagedSchemaFieldResource, SimpleFacetsTest, TestBlendedInfixSuggestions, DateFieldTest, UUIDFieldTest, TestFoldingMultitermQuery, LeaderInitiatedRecoveryOnCommitTest, ZkSolrClientTest, SuggesterTSTTest, TestSchemaResource, TriLevelCompositeIdRoutingTest, HdfsBasicDistributedZk2Test, SharedFSAutoReplicaFailoverTest, TestObjectReleaseTracker, TestManagedSchemaDynamicFieldResource, TestExactStatsCache, LeaderElectionTest, HdfsSyncSliceTest, TestHdfsUpdateLog, SampleTest, DistribJoinFromCollectionTest, EnumFieldTest, MigrateRouteKeyTest, VMParamsZkACLAndCredentialsProvidersTest, TestNonDefinedSimilarityFactory, CloudMLTQParserTest, SolrRequestParserTest, TestFieldSortValues, CollectionsAPIDistributedZkTest, TestOverriddenPrefixQueryForCustomFieldType, TestConfigReload, TestSolrQueryParserDefaultOperatorResource, TestStressRecovery, TestIntervalFaceting, TestCoreDiscovery, RecoveryZkTest, ChangedSchemaMergeTest, TestJmxIntegration, TestAnalyzedSuggestions, PreAnalyzedFieldTest, AtomicUpdatesTest, TestMergePolicyConfig, DirectUpdateHandlerTest, TestSearcherReuse, DebugComponentTest, TestRecovery, FacetPivotSmallTest, TestSchemaVersionResource, DistributedFacetPivotLargeTest, TestRandomFaceting, CursorPagingTest, TestReloadAndDeleteDocs, TestJettySolrRunner, TestAnalyzeInfixSuggestions, TestFieldCollectionResource, TestZkChroot, RAMDirectoryFactoryTest, EchoParamsTest, TestSolrDeletionPolicy2, SpatialHeatmapFacetsTest, TestPerFieldSimilarity, DistanceFunctionTest, LukeRequestHandlerTest, SortByFunctionTest, SignatureUpdateProcessorFactoryTest, TestShardHandlerFactory, TestDefaultStatsCache, TestFreeTextSuggestions, BasicFunctionalityTest, TestRebalanceLeaders, RollingRestartTest, SuggesterWFSTTest, StatelessScriptUpdateProcessorFactoryTest, TestPhraseSuggestions, TestPseudoReturnFields, TestRandomDVFaceting, DirectSolrSpellCheckerTest, TestDistributedGrouping, TestManagedSynonymFilterFactory, TestPartialUpdateDeduplication, RemoteQueryErrorTest, QueryParsingTest, ResourceLoaderTest, TestDefaultSearchFieldResource, TestFaceting, RegexBoostProcessorTest, TestIndexingPerformance, ReturnFieldsTest, AddSchemaFieldsUpdateProcessorFactoryTest, DeleteInactiveReplicaTest, CurrencyFieldOpenExchangeTest, TestSchemaManager, DisMaxRequestHandlerTest, PolyFieldTest, AliasIntegrationTest, HdfsChaosMonkeySafeLeaderTest, OverriddenZkACLAndCredentialsProvidersTest, TestRTGBase, PeerSyncTest, TestSolrIndexConfig, RequestHandlersTest, TestAddFieldRealTimeGet, MinimalSchemaTest, ResponseLogComponentTest, IndexBasedSpellCheckerTest, ZkCLITest, SimpleCollectionCreateDeleteTest, TestUniqueKeyFieldResource, HdfsWriteToMultipleCollectionsTest, TestCSVLoader, DOMUtilTest, CSVRequestHandlerTest, DocValuesMissingTest, MoreLikeThisHandlerTest, TestLFUCache, TestDynamicFieldResource, TestGroupingSearch, TestNoOpRegenerator, MultiThreadedOCPTest, BJQParserTest, UnloadDistributedZkTest, DefaultValueUpdateProcessorTest, NumericFieldsTest, DistributedMLTComponentTest, PrimitiveFieldTypeTest, TestRequestStatusCollectionAPI, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, SyncSliceTest, OpenCloseCoreStressTest, OverseerTest, LeaderElectionIntegrationTest, ShardRoutingTest, BasicZkTest, TestReplicationHandler, ClusterStateUpdateTest, ShardRoutingCustomTest, TestDistributedSearch, AutoCommitTest, TermVectorComponentDistributedTest, ZkControllerTest, TestRealTimeGet, TestStressReorder, TestReload, TestStressVersions, HardAutoCommitTest, DistributedTermsComponentTest, TestRangeQuery, TestSolr4Spatial, StatsComponentTest, SolrCmdDistributorTest, TestSort, TestFiltering, TestFunctionQuery, TestLazyCores, OverseerCollectionProcessorTest, TestIndexSearcher, HighlighterTest, ShowFileRequestHandlerTest, DistributedQueryElevationComponentTest, SimplePostToolTest, TestExtendedDismaxParser, SpellCheckCollatorTest, SuggesterFSTTest, CoreAdminHandlerTest, DocValuesTest, SuggesterTest, TestTrie, SpatialFilterTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, SolrCoreCheckLockOnStartupTest, FieldMutatingUpdateProcessorTest, TestAtomicUpdateErrorCases, QueryEqualityTest, DirectUpdateHandlerOptimizeTest, TestRemoteStreaming, DocValuesMultiTest, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, TestSurroundQueryParser, TestQueryUtils, StandardRequestHandlerTest, TestWriterPerf, TestOmitPositions, FileBasedSpellCheckerTest, TermsComponentTest, DocumentBuilderTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, RequiredFieldsTest, FieldAnalysisRequestHandlerTest, TestArbitraryIndexDir, FastVectorHighlighterTest, LoggingHandlerTest, SolrPluginUtilsTest, IndexSchemaTest, TestCollationField, UpdateRequestProcessorFactoryTest, JSONWriterTest, TestCSVResponseWriter, MBeansHandlerTest, PingRequestHandlerTest, TestComponentsName, SearchHandlerTest, TestBinaryResponseWriter, HighlighterConfigTest, SOLR749Test, TestQuerySenderListener, AlternateDirectoryTest, TestQuerySenderNoQuery, CopyFieldTest, SolrIndexConfigTest, BadComponentTest, MultiTermTest, TestDocSet, TestSearchPerf, TestConfig, TestFuzzyAnalyzedSuggestions, ExternalFileFieldSortTest, NotRequiredUniqueKeyTest, SynonymTokenizerTest, TestXIncludeConfig, TestDFRSimilarityFactory, TestLMDirichletSimilarityFactory, TestLMJelinekMercerSimilarityFactory, TestFastWriter, TimeZoneUtilsTest, TestFastOutputStream, ScriptEngineTest, OpenExchangeRatesOrgProviderTest, URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, DateMathParserTest, ChaosMonkeyNothingIsSafeTest, TestSuggestSpellingConverter, TestSolrJ, TestLRUCache, TestUtils, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, SystemInfoHandlerTest, FileUtilsTest, CircularListTest, DistributedIntervalFacetingTest, TestCursorMarkWithoutUniqueKey, TestSimpleTrackingShardHandler, TestTolerantSearch, ActionThrottleTest, AssignTest, CloudExitableDirectoryReaderTest, CollectionsAPIAsyncDistributedZkTest, ConnectionManagerTest, CustomCollectionTest, DeleteReplicaTest, DeleteShardTest, DistribCursorPagingTest, DistributedQueueTest, HttpPartitionTest, LeaderFailoverAfterPartitionTest, OutOfBoxZkACLAndCredentialsProvidersTest, OverseerRolesTest, RecoveryAfterSoftCommitTest, ReplicationFactorTest] [junit4] Completed in 57.74s, 1 test, 1 error <<< FAILURES! [...truncated 273 lines...] BUILD FAILED C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:519: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:467: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:61: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\extra-targets.xml:39: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:191: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:510: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1351: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:958: There were test failures: 472 suites, 1874 tests, 1 error, 57 ignored (30 assumptions) Total time: 133 minutes 31 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/jdk1.8.0_31 -client -XX:+UseConcMarkSweepGC Archiving artifacts Recording test results Email was triggered for: Failure - Any Sending email for trigger: Failure - Any
--------------------------------------------------------------------- To unsubscribe, e-mail: dev-unsubscr...@lucene.apache.org For additional commands, e-mail: dev-h...@lucene.apache.org