Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4534/ Java: 32bit/jdk1.8.0_40-ea-b09 -client -XX:+UseConcMarkSweepGC (asserts: true)
1 tests failed. FAILED: org.apache.solr.cloud.ReplicationFactorTest.testDistribSearch Error Message: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:54216/_yb/is/repfacttest_c8n_1x3_shard1_replica1 Stack Trace: org.apache.solr.client.solrj.SolrServerException: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:54216/_yb/is/repfacttest_c8n_1x3_shard1_replica1 at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:581) at org.apache.solr.client.solrj.impl.CloudSolrClient.sendRequest(CloudSolrClient.java:890) at org.apache.solr.client.solrj.impl.CloudSolrClient.requestWithRetryOnStaleState(CloudSolrClient.java:793) at org.apache.solr.client.solrj.impl.CloudSolrClient.request(CloudSolrClient.java:736) at org.apache.solr.cloud.ReplicationFactorTest.testRf3(ReplicationFactorTest.java:277) at org.apache.solr.cloud.ReplicationFactorTest.doTest(ReplicationFactorTest.java:123) at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:868) at sun.reflect.GeneratedMethodAccessor115.invoke(Unknown Source) at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43) at java.lang.reflect.Method.invoke(Method.java:497) at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1618) at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:827) at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:863) at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:877) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798) at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458) at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:836) at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:738) at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:772) at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:783) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53) at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46) at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42) at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54) at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48) at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65) at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55) at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36) at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365) at java.lang.Thread.run(Thread.java:745) Caused by: org.apache.solr.client.solrj.SolrServerException: IOException occured when talking to server at: http://127.0.0.1:54216/_yb/is/repfacttest_c8n_1x3_shard1_replica1 at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:573) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:214) at org.apache.solr.client.solrj.impl.HttpSolrClient.request(HttpSolrClient.java:210) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.doRequest(LBHttpSolrClient.java:353) at org.apache.solr.client.solrj.impl.LBHttpSolrClient.request(LBHttpSolrClient.java:312) at org.apache.solr.client.solrj.impl.CloudSolrClient.directUpdate(CloudSolrClient.java:578) ... 45 more Caused by: org.apache.http.NoHttpResponseException: The target server failed to respond at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:143) at org.apache.http.impl.conn.DefaultHttpResponseParser.parseHead(DefaultHttpResponseParser.java:57) at org.apache.http.impl.io.AbstractMessageParser.parse(AbstractMessageParser.java:260) at org.apache.http.impl.AbstractHttpClientConnection.receiveResponseHeader(AbstractHttpClientConnection.java:283) at org.apache.http.impl.conn.DefaultClientConnection.receiveResponseHeader(DefaultClientConnection.java:251) at org.apache.http.impl.conn.ManagedClientConnectionImpl.receiveResponseHeader(ManagedClientConnectionImpl.java:197) at org.apache.http.protocol.HttpRequestExecutor.doReceiveResponse(HttpRequestExecutor.java:271) at org.apache.http.protocol.HttpRequestExecutor.execute(HttpRequestExecutor.java:123) at org.apache.http.impl.client.DefaultRequestDirector.tryExecute(DefaultRequestDirector.java:682) at org.apache.http.impl.client.DefaultRequestDirector.execute(DefaultRequestDirector.java:486) at org.apache.http.impl.client.AbstractHttpClient.doExecute(AbstractHttpClient.java:863) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:82) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:106) at org.apache.http.impl.client.CloseableHttpClient.execute(CloseableHttpClient.java:57) at org.apache.solr.client.solrj.impl.HttpSolrClient.executeMethod(HttpSolrClient.java:464) ... 50 more Build Log: [...truncated 9815 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-2264A9F031B6CD6-001\init-core-data-001 [junit4] 2> 4165637 T15423 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /_yb/is [junit4] 2> 4165644 T15423 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch [junit4] 2> 4165645 T15423 oasc.ZkTestServer.run STARTING ZK TEST SERVER [junit4] 1> client port:0.0.0.0/0.0.0.0:0 [junit4] 2> 4165648 T15424 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server [junit4] 2> 4165737 T15423 oasc.ZkTestServer.run start zk server on port:54209 [junit4] 2> 4165738 T15423 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4165741 T15423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4165748 T15431 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@a506f5 name:ZooKeeperConnection Watcher:127.0.0.1:54209 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4165748 T15423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4165750 T15423 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4165752 T15423 oascc.SolrZkClient.makePath makePath: /solr [junit4] 2> 4165760 T15423 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4165764 T15423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4165769 T15434 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@11f359f name:ZooKeeperConnection Watcher:127.0.0.1:54209/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4165770 T15423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4165771 T15423 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4165772 T15423 oascc.SolrZkClient.makePath makePath: /collections/collection1 [junit4] 2> 4165777 T15423 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards [junit4] 2> 4165783 T15423 oascc.SolrZkClient.makePath makePath: /collections/control_collection [junit4] 2> 4165788 T15423 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards [junit4] 2> 4165792 T15423 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> 4165792 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml [junit4] 2> 4165801 T15423 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> 4165801 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml [junit4] 2> 4165808 T15423 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> 4165808 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml [junit4] 2> 4165815 T15423 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> 4165815 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt [junit4] 2> 4165822 T15423 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> 4165823 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt [junit4] 2> 4165828 T15423 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> 4165828 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml [junit4] 2> 4165835 T15423 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> 4165835 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/enumsConfig.xml [junit4] 2> 4165842 T15423 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> 4165845 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json [junit4] 2> 4165852 T15423 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> 4165853 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt [junit4] 2> 4165860 T15423 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> 4165862 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt [junit4] 2> 4165868 T15423 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> 4165868 T15423 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt [junit4] 2> 4167799 T15423 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 4167806 T15423 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54217 [junit4] 2> 4167807 T15423 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44 [junit4] 2> 4167807 T15423 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4167809 T15423 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001\tempDir-002 [junit4] 2> 4167809 T15423 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-2264A9F031B6CD6-001\tempDir-002\' [junit4] 2> 4167851 T15423 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001\tempDir-002\solr.xml [junit4] 2> 4167869 T15423 oasc.CoreContainer.<init> New CoreContainer 32000945 [junit4] 2> 4167870 T15423 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-2264A9F031B6CD6-001\tempDir-002\] [junit4] 2> 4167871 T15423 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4167872 T15423 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4167872 T15423 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4167872 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4167873 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4167873 T15423 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4167873 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4167873 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4167873 T15423 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4167875 T15423 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4167875 T15423 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 4167875 T15423 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4167875 T15423 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4167875 T15423 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4167875 T15423 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54209/solr [junit4] 2> 4167878 T15423 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4167879 T15423 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4167883 T15423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4167890 T15446 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1868650 name:ZooKeeperConnection Watcher:127.0.0.1:54209 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4167892 T15423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4167894 T15423 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4167898 T15425 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14ab8fa5bff0002, 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> 4167903 T15423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4167907 T15449 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@9c838e name:ZooKeeperConnection Watcher:127.0.0.1:54209/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4167908 T15423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4167909 T15423 oascc.SolrZkClient.makePath makePath: /overseer/queue [junit4] 2> 4167915 T15423 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work [junit4] 2> 4167921 T15423 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-running [junit4] 2> 4167926 T15423 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-completed [junit4] 2> 4167931 T15423 oascc.SolrZkClient.makePath makePath: /overseer/collection-map-failure [junit4] 2> 4167936 T15423 oascc.SolrZkClient.makePath makePath: /live_nodes [junit4] 2> 4167939 T15423 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54216__yb%2Fis [junit4] 2> 4167941 T15423 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54216__yb%2Fis [junit4] 2> 4167944 T15423 oascc.SolrZkClient.makePath makePath: /overseer_elect [junit4] 2> 4167948 T15423 oascc.SolrZkClient.makePath makePath: /overseer_elect/election [junit4] 2> 4167952 T15423 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4167956 T15423 oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader 127.0.0.1:54216__yb%2Fis [junit4] 2> 4167956 T15423 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader [junit4] 2> 4167960 T15423 oasc.Overseer.start Overseer (id=93090127739289603-127.0.0.1:54216__yb%2Fis-n_0000000000) starting [junit4] 2> 4167964 T15423 oascc.SolrZkClient.makePath makePath: /overseer/queue-work [junit4] 2> 4167981 T15423 oasc.OverseerAutoReplicaFailoverThread.<init> Starting OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000 autoReplicaFailoverWaitAfterExpiration=30000 autoReplicaFailoverBadNodeExpiration=60000 [junit4] 2> 4167982 T15451 oasc.OverseerCollectionProcessor.run Process current queue of collection creations [junit4] 2> 4167983 T15423 oascc.SolrZkClient.makePath makePath: /clusterstate.json [junit4] 2> 4167990 T15423 oascc.SolrZkClient.makePath makePath: /aliases.json [junit4] 2> 4167992 T15423 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4167999 T15450 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue [junit4] 2> 4168004 T15453 oasc.ZkController.publish publishing core=collection1 state=down collection=control_collection [junit4] 2> 4168005 T15453 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4168006 T15453 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4168007 T15449 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4168011 T15450 oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 0, message = { [junit4] 2> "core":"collection1", [junit4] 2> "roles":null, [junit4] 2> "base_url":"http://127.0.0.1:54216/_yb/is";, [junit4] 2> "node_name":"127.0.0.1:54216__yb%2Fis", [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> 4168011 T15450 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:54216/_yb/is";, [junit4] 2> "node_name":"127.0.0.1:54216__yb%2Fis", [junit4] 2> "numShards":"1", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4168011 T15450 oasco.ClusterStateMutator.createCollection building a new cName: control_collection [junit4] 2> 4168012 T15450 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard1 [junit4] 2> 4168016 T15449 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> 4168982 T15453 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4168984 T15453 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection [junit4] 2> 4168985 T15453 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4168985 T15453 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4168987 T15453 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4168987 T15453 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-2264A9F031B6CD6-001\tempDir-002\collection1\' [junit4] 2> 4168990 T15453 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001/tempDir-002/collection1/lib/.svn/' to classloader [junit4] 2> 4168990 T15453 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001/tempDir-002/collection1/lib/classes/' to classloader [junit4] 2> 4168993 T15453 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001/tempDir-002/collection1/lib/README' to classloader [junit4] 2> 4169026 T15453 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4169032 T15453 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4169047 T15453 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4169053 T15453 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4169067 T15453 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4169071 T15453 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4169086 T15453 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4169243 T15453 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4169252 T15453 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4169254 T15453 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4169282 T15453 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4169288 T15453 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4169293 T15453 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4169297 T15453 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4169297 T15453 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4169297 T15453 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4169300 T15453 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4169300 T15453 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4169300 T15453 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection control_collection [junit4] 2> 4169300 T15453 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4169302 T15453 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-2264A9F031B6CD6-001\tempDir-002\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001\tempDir-001/control/data\ [junit4] 2> 4169302 T15453 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1cabbf4 [junit4] 2> 4169304 T15453 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-2264A9F031B6CD6-001\tempDir-001/control/data\ [junit4] 2> 4169304 T15453 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-2264A9F031B6CD6-001\tempDir-001/control/data\index/ [junit4] 2> 4169304 T15453 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-2264A9F031B6CD6-001\tempDir-001\control\data\index' doesn't exist. Creating new index... [junit4] 2> 4169305 T15453 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-2264A9F031B6CD6-001\tempDir-001/control/data\index [junit4] 2> 4169306 T15453 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1834575044, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 4169307 T15453 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7d44f0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@180ff23),segFN=segments_1,generation=1} [junit4] 2> 4169307 T15453 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4169313 T15453 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4169313 T15453 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4169314 T15453 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4169314 T15453 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4169315 T15453 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4169315 T15453 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4169316 T15453 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4169316 T15453 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4169317 T15453 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4169318 T15453 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4169318 T15453 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4169319 T15453 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4169319 T15453 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4169321 T15453 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4169321 T15453 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4169322 T15453 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4169323 T15453 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4169323 T15453 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4169325 T15453 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4169327 T15453 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4169327 T15453 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4169328 T15453 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4169329 T15453 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4169330 T15453 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4169331 T15453 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4169332 T15453 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4169332 T15453 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4169334 T15453 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 4169341 T15453 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4169343 T15453 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4169346 T15453 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4169348 T15453 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4169348 T15453 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4169356 T15453 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4169357 T15453 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4169359 T15453 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 4169360 T15453 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@7d44f0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@180ff23),segFN=segments_1,generation=1} [junit4] 2> 4169362 T15453 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4169363 T15453 oass.SolrIndexSearcher.<init> Opening Searcher@3a2eed[collection1] main [junit4] 2> 4169363 T15453 oascc.ZkStateReader.readConfigName Load collection config from:/collections/control_collection [junit4] 2> 4169367 T15453 oascc.ZkStateReader.readConfigName path=/collections/control_collection configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4169367 T15453 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4169369 T15453 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4169369 T15453 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4169369 T15453 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4169372 T15453 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4169372 T15453 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4169372 T15453 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4169375 T15453 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4169377 T15454 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3a2eed[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4169378 T15453 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4169394 T15457 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54216/_yb/is collection:control_collection shard:shard1 [junit4] 2> 4169395 T15423 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 4169397 T15423 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4169398 T15423 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4169400 T15457 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election [junit4] 2> 4169401 T15423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4169405 T15461 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@bcb3b8 name:ZooKeeperConnection Watcher:127.0.0.1:54209/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4169406 T15423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4169407 T15423 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4169409 T15423 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4169416 T15457 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1 [junit4] 2> 4169418 T15423 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:false cause connection loss:false [junit4] 2> 4169425 T15449 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4169428 T15457 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4169428 T15457 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7959 name=collection1 org.apache.solr.core.SolrCore@be7972 url=http://127.0.0.1:54216/_yb/is/collection1 node=127.0.0.1:54216__yb%2Fis C7959_STATE=coll:control_collection core:collection1 props:{core=collection1, base_url=http://127.0.0.1:54216/_yb/is, node_name=127.0.0.1:54216__yb%2Fis, state=down} [junit4] 2> 4169428 T15457 C7959 P54216 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54216/_yb/is/collection1/ [junit4] 2> 4169428 T15457 C7959 P54216 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4169428 T15457 C7959 P54216 oasc.SyncStrategy.syncToMe http://127.0.0.1:54216/_yb/is/collection1/ has no replicas [junit4] 2> 4169431 T15457 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54216/_yb/is/collection1/ shard1 [junit4] 2> 4169431 T15457 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1 [junit4] 2> 4169434 T15450 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> 4169453 T15449 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4169458 T15450 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:54216/_yb/is";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 1 [junit4] 2> 4169564 T15449 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> 4169565 T15461 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> 4169608 T15457 oasc.ZkController.register We are http://127.0.0.1:54216/_yb/is/collection1/ and leader is http://127.0.0.1:54216/_yb/is/collection1/ [junit4] 2> 4169608 T15457 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54216/_yb/is [junit4] 2> 4169609 T15457 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4169609 T15457 oasc.ZkController.publish publishing core=collection1 state=active collection=control_collection [junit4] 2> 4169609 T15457 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4169612 T15449 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4169621 T15450 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:54216/_yb/is";, [junit4] 2> "node_name":"127.0.0.1:54216__yb%2Fis", [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> 4169623 T15450 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:54216/_yb/is";, [junit4] 2> "node_name":"127.0.0.1:54216__yb%2Fis", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard1", [junit4] 2> "collection":"control_collection", [junit4] 2> "operation":"state"} [junit4] 2> 4169734 T15461 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> 4169734 T15449 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> 4171350 T15423 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 1 [junit4] 2> 4171352 T15423 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 4171357 T15423 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54230 [junit4] 2> 4171358 T15423 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44 [junit4] 2> 4171359 T15423 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4171360 T15423 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001\tempDir-003 [junit4] 2> 4171360 T15423 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-2264A9F031B6CD6-001\tempDir-003\' [junit4] 2> 4171403 T15423 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001\tempDir-003\solr.xml [junit4] 2> 4171421 T15423 oasc.CoreContainer.<init> New CoreContainer 18962174 [junit4] 2> 4171424 T15423 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-2264A9F031B6CD6-001\tempDir-003\] [junit4] 2> 4171425 T15423 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4171425 T15423 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4171425 T15423 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4171425 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4171426 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4171426 T15423 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4171426 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4171426 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4171428 T15423 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4171428 T15423 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4171428 T15423 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 4171430 T15423 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4171430 T15423 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4171430 T15423 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4171430 T15423 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54209/solr [junit4] 2> 4171432 T15423 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4171432 T15423 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4171436 T15423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4171439 T15473 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@7b8915 name:ZooKeeperConnection Watcher:127.0.0.1:54209 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4171440 T15423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4171440 T15423 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4171445 T15423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4171448 T15476 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@18cced5 name:ZooKeeperConnection Watcher:127.0.0.1:54209/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4171449 T15423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4171460 T15423 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4172453 T15423 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54229__yb%2Fis [junit4] 2> 4172455 T15423 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54229__yb%2Fis [junit4] 2> 4172466 T15423 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4172477 T15477 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4172477 T15477 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4172479 T15449 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4172479 T15477 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4172481 T15450 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:54229/_yb/is";, [junit4] 2> "node_name":"127.0.0.1:54229__yb%2Fis", [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> 4172483 T15450 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:54229/_yb/is";, [junit4] 2> "node_name":"127.0.0.1:54229__yb%2Fis", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4172483 T15450 oasco.ClusterStateMutator.createCollection building a new cName: collection1 [junit4] 2> 4172483 T15450 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard2 [junit4] 2> 4172488 T15476 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> 4172488 T15449 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> 4172488 T15461 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> 4173463 T15477 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4173463 T15477 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4173465 T15477 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4173465 T15477 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4173469 T15477 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4173469 T15477 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-2264A9F031B6CD6-001\tempDir-003\collection1\' [junit4] 2> 4173472 T15477 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001/tempDir-003/collection1/lib/.svn/' to classloader [junit4] 2> 4173475 T15477 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001/tempDir-003/collection1/lib/classes/' to classloader [junit4] 2> 4173477 T15477 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001/tempDir-003/collection1/lib/README' to classloader [junit4] 2> 4173516 T15477 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4173521 T15477 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4173535 T15477 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4173541 T15477 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4173554 T15477 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4173558 T15477 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4173570 T15477 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4173717 T15477 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4173728 T15477 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4173730 T15477 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4173756 T15477 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4173769 T15477 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4173789 T15477 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4173793 T15477 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4173793 T15477 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4173793 T15477 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4173797 T15477 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4173797 T15477 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4173797 T15477 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4173797 T15477 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4173799 T15477 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-2264A9F031B6CD6-001\tempDir-003\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001\tempDir-001/jetty1\ [junit4] 2> 4173799 T15477 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1cabbf4 [junit4] 2> 4173800 T15477 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-2264A9F031B6CD6-001\tempDir-001/jetty1\ [junit4] 2> 4173800 T15477 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-2264A9F031B6CD6-001\tempDir-001/jetty1\index/ [junit4] 2> 4173800 T15477 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-2264A9F031B6CD6-001\tempDir-001\jetty1\index' doesn't exist. Creating new index... [junit4] 2> 4173803 T15477 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-2264A9F031B6CD6-001\tempDir-001/jetty1\index [junit4] 2> 4173803 T15477 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1834575044, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 4173805 T15477 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15fdcf0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@89e69c),segFN=segments_1,generation=1} [junit4] 2> 4173805 T15477 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4173812 T15477 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4173812 T15477 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4173813 T15477 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4173813 T15477 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4173814 T15477 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4173815 T15477 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4173815 T15477 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4173816 T15477 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4173816 T15477 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4173818 T15477 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4173819 T15477 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4173820 T15477 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4173821 T15477 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4173822 T15477 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4173823 T15477 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4173824 T15477 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4173826 T15477 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4173827 T15477 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4173829 T15477 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4173831 T15477 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4173832 T15477 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4173833 T15477 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4173834 T15477 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4173836 T15477 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4173837 T15477 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4173838 T15477 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4173839 T15477 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4173840 T15477 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 4173848 T15477 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4173851 T15477 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4173852 T15477 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4173855 T15477 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4173856 T15477 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4173872 T15477 oasu.CommitTracker.<init> Hard AutoCommit: disabled [junit4] 2> 4173873 T15477 oasu.CommitTracker.<init> Soft AutoCommit: disabled [junit4] 2> 4173874 T15477 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=27, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0] [junit4] 2> 4173875 T15477 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@15fdcf0 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@89e69c),segFN=segments_1,generation=1} [junit4] 2> 4173875 T15477 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4173876 T15477 oass.SolrIndexSearcher.<init> Opening Searcher@1a49f9b[collection1] main [junit4] 2> 4173876 T15477 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4173878 T15477 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4173878 T15477 oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for the RestManager with znodeBase: /configs/conf1 [junit4] 2> 4173882 T15477 oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured ZooKeeperStorageIO with znodeBase: /configs/conf1 [junit4] 2> 4173884 T15477 oasr.RestManager.init Initializing RestManager with initArgs: {} [junit4] 2> 4173884 T15477 oasr.ManagedResourceStorage.load Reading _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4173886 T15477 oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found for znode /configs/conf1/_rest_managed.json [junit4] 2> 4173887 T15477 oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using ZooKeeperStorageIO:path=/configs/conf1 [junit4] 2> 4173887 T15477 oasr.RestManager.init Initializing 0 registered ManagedResources [junit4] 2> 4173887 T15477 oash.ReplicationHandler.inform Commits will be reserved for 10000 [junit4] 2> 4173888 T15477 oasc.CoreContainer.registerCore registering core: collection1 [junit4] 2> 4173891 T15478 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a49f9b[collection1] main{ExitableDirectoryReader(UninvertingDirectoryReader())} [junit4] 2> 4173893 T15481 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54229/_yb/is collection:collection1 shard:shard2 [junit4] 2> 4173893 T15423 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0 [junit4] 2> 4173894 T15423 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done [junit4] 2> 4173895 T15481 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election [junit4] 2> 4173910 T15481 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2 [junit4] 2> 4173914 T15449 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4173916 T15481 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue. [junit4] 2> 4173916 T15481 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync [junit4] 2> ASYNC NEW_CORE C7960 name=collection1 org.apache.solr.core.SolrCore@bc862c url=http://127.0.0.1:54229/_yb/is/collection1 node=127.0.0.1:54229__yb%2Fis C7960_STATE=coll:collection1 core:collection1 props:{core=collection1, base_url=http://127.0.0.1:54229/_yb/is, node_name=127.0.0.1:54229__yb%2Fis, state=down} [junit4] 2> 4173916 T15481 C7960 P54229 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54229/_yb/is/collection1/ [junit4] 2> 4173917 T15481 C7960 P54229 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me [junit4] 2> 4173917 T15481 C7960 P54229 oasc.SyncStrategy.syncToMe http://127.0.0.1:54229/_yb/is/collection1/ has no replicas [junit4] 2> 4173917 T15481 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54229/_yb/is/collection1/ shard2 [junit4] 2> 4173918 T15481 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2 [junit4] 2> 4173922 T15450 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> 4173935 T15449 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4173940 T15450 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:54229/_yb/is";, [junit4] 2> "core":"collection1", [junit4] 2> "state":"active"} current state version: 4 [junit4] 2> 4174047 T15476 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> 4174048 T15449 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> 4174048 T15461 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> 4174089 T15481 oasc.ZkController.register We are http://127.0.0.1:54229/_yb/is/collection1/ and leader is http://127.0.0.1:54229/_yb/is/collection1/ [junit4] 2> 4174089 T15481 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54229/_yb/is [junit4] 2> 4174089 T15481 oasc.ZkController.checkRecovery I am the leader, no recovery necessary [junit4] 2> 4174089 T15481 oasc.ZkController.publish publishing core=collection1 state=active collection=collection1 [junit4] 2> 4174091 T15481 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4174094 T15449 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4174098 T15450 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:54229/_yb/is";, [junit4] 2> "node_name":"127.0.0.1:54229__yb%2Fis", [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> 4174101 T15450 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:54229/_yb/is";, [junit4] 2> "node_name":"127.0.0.1:54229__yb%2Fis", [junit4] 2> "numShards":"3", [junit4] 2> "state":"active", [junit4] 2> "shard":"shard2", [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4174205 T15461 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> 4174206 T15449 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> 4174206 T15476 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> 4175676 T15423 oasc.AbstractFullDistribZkTestBase.createJettys create jetty 2 [junit4] 2> 4175678 T15423 oejs.Server.doStart jetty-8.1.10.v20130312 [junit4] 2> 4175684 T15423 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54240 [junit4] 2> 4175685 T15423 oass.SolrDispatchFilter.init SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@387f44 [junit4] 2> 4175686 T15423 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx) [junit4] 2> 4175686 T15423 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001\tempDir-004 [junit4] 2> 4175687 T15423 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-2264A9F031B6CD6-001\tempDir-004\' [junit4] 2> 4175735 T15423 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001\tempDir-004\solr.xml [junit4] 2> 4175757 T15423 oasc.CoreContainer.<init> New CoreContainer 30246385 [junit4] 2> 4175757 T15423 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-2264A9F031B6CD6-001\tempDir-004\] [junit4] 2> 4175760 T15423 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000 [junit4] 2> 4175760 T15423 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: [junit4] 2> 4175762 T15423 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000 [junit4] 2> 4175762 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20 [junit4] 2> 4175762 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maxConnections to: 10000 [junit4] 2> 4175762 T15423 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0 [junit4] 2> 4175762 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647 [junit4] 2> 4175764 T15423 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5 [junit4] 2> 4175764 T15423 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1 [junit4] 2> 4175764 T15423 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false [junit4] 2> 4175764 T15423 oasu.UpdateShardHandler.<init> Creating UpdateShardHandler HTTP client with params: socketTimeout=340000&connTimeout=45000&retry=false [junit4] 2> 4175767 T15423 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory [junit4] 2> 4175767 T15423 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)] [junit4] 2> 4175767 T15423 oasc.CoreContainer.load Host Name: 127.0.0.1 [junit4] 2> 4175767 T15423 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54209/solr [junit4] 2> 4175769 T15423 oasc.ZkController.checkChrootPath zkHost includes chroot [junit4] 2> 4175769 T15423 oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default ZkCredentialsProvider [junit4] 2> 4175773 T15423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4175779 T15494 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@8f54db name:ZooKeeperConnection Watcher:127.0.0.1:54209 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4175780 T15423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4175780 T15423 oascc.SolrZkClient.createZkACLProvider Using default ZkACLProvider [junit4] 2> 4175784 T15425 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x14ab8fa5bff0007, 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> 4175786 T15423 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper [junit4] 2> 4175788 T15497 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@fefd48 name:ZooKeeperConnection Watcher:127.0.0.1:54209/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None [junit4] 2> 4175789 T15423 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper [junit4] 2> 4175803 T15423 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper... [junit4] 2> 4176794 T15423 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54239__yb%2Fis [junit4] 2> 4176796 T15423 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54239__yb%2Fis [junit4] 2> 4176810 T15423 oasc.Overseer.close Overseer (id=null) closing [junit4] 2> 4176821 T15498 oasc.ZkController.publish publishing core=collection1 state=down collection=collection1 [junit4] 2> 4176821 T15498 oasc.ZkController.publish numShards not found on descriptor - reading it from system property [junit4] 2> 4176825 T15498 oasc.ZkController.waitForCoreNodeName look for our core node name [junit4] 2> 4176825 T15449 oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path /overseer/queue state SyncConnected [junit4] 2> 4176828 T15450 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:54239/_yb/is";, [junit4] 2> "node_name":"127.0.0.1:54239__yb%2Fis", [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> 4176829 T15450 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:54239/_yb/is";, [junit4] 2> "node_name":"127.0.0.1:54239__yb%2Fis", [junit4] 2> "numShards":"3", [junit4] 2> "state":"down", [junit4] 2> "shard":null, [junit4] 2> "collection":"collection1", [junit4] 2> "operation":"state"} [junit4] 2> 4176829 T15450 oasco.ReplicaMutator.updateState Collection already exists with numShards=3 [junit4] 2> 4176829 T15450 oasco.ReplicaMutator.updateState Assigning new node to shard shard=shard3 [junit4] 2> 4176833 T15461 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> 4176833 T15449 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> 4176833 T15476 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> 4176833 T15497 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> 4177809 T15498 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1 [junit4] 2> 4177809 T15498 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1 [junit4] 2> 4177812 T15498 oasc.ZkController.createCollectionZkNode Collection zkNode exists [junit4] 2> 4177812 T15498 oascc.ZkStateReader.readConfigName Load collection config from:/collections/collection1 [junit4] 2> 4177814 T15498 oascc.ZkStateReader.readConfigName path=/collections/collection1 configName=conf1 specified config exists in ZooKeeper [junit4] 2> 4177814 T15498 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-2264A9F031B6CD6-001\tempDir-004\collection1\' [junit4] 2> 4177816 T15498 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001/tempDir-004/collection1/lib/.svn/' to classloader [junit4] 2> 4177819 T15498 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001/tempDir-004/collection1/lib/classes/' to classloader [junit4] 2> 4177820 T15498 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/temp/solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001/tempDir-004/collection1/lib/README' to classloader [junit4] 2> 4177853 T15498 oasc.ZkController.watchZKConfDir watch zkdir /configs/conf1 [junit4] 2> 4177861 T15498 oasc.Config.<init> loaded config solrconfig.xml with version 0 [junit4] 2> 4177878 T15498 oasc.SolrConfig.refreshRequestParams current version of requestparams : -1 [junit4] 2> 4177883 T15498 oasc.SolrConfig.<init> Using Lucene MatchVersion: 6.0.0 [junit4] 2> 4177896 T15498 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml [junit4] 2> 4177898 T15498 oass.IndexSchema.readSchema Reading Solr Schema from /configs/conf1/schema.xml [junit4] 2> 4177910 T15498 oass.IndexSchema.readSchema [collection1] Schema name=test [junit4] 2> 4178074 T15498 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440. [junit4] 2> 4178085 T15498 oass.IndexSchema.readSchema default search field in schema is text [junit4] 2> 4178087 T15498 oass.IndexSchema.readSchema unique key field: id [junit4] 2> 4178109 T15498 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4178116 T15498 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml [junit4] 2> 4178122 T15498 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4178125 T15498 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4178125 T15498 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4178126 T15498 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json [junit4] 2> 4178129 T15498 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE [junit4] 2> 4178129 T15498 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING [junit4] 2> 4178129 T15498 oasc.CoreContainer.create Creating SolrCore 'collection1' using configuration from collection collection1 [junit4] 2> 4178130 T15498 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory [junit4] 2> 4178131 T15498 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-2264A9F031B6CD6-001\tempDir-004\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\temp\solr.cloud.ReplicationFactorTest-2264A9F031B6CD6-001\tempDir-001/jetty2\ [junit4] 2> 4178131 T15498 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1cabbf4 [junit4] 2> 4178133 T15498 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-2264A9F031B6CD6-001\tempDir-001/jetty2\ [junit4] 2> 4178133 T15498 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-2264A9F031B6CD6-001\tempDir-001/jetty2\index/ [junit4] 2> 4178133 T15498 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-2264A9F031B6CD6-001\tempDir-001\jetty2\index' doesn't exist. Creating new index... [junit4] 2> 4178135 T15498 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-2264A9F031B6CD6-001\tempDir-001/jetty2\index [junit4] 2> 4178135 T15498 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.AlcoholicMergePolicy: [AlcoholicMergePolicy: minMergeSize=0, mergeFactor=10, maxMergeSize=1834575044, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.1] [junit4] 2> 4178136 T15498 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1 [junit4] 2> commit{dir=MockDirectoryWrapper(RAMDirectory@3975e2 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6b2d57),segFN=segments_1,generation=1} [junit4] 2> 4178136 T15498 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1 [junit4] 2> 4178143 T15498 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib" [junit4] 2> 4178144 T15498 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe" [junit4] 2> 4178144 T15498 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe" [junit4] 2> 4178145 T15498 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig" [junit4] 2> 4178146 T15498 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig" [junit4] 2> 4178146 T15498 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit" [junit4] 2> 4178148 T15498 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4178148 T15498 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit" [junit4] 2> 4178148 T15498 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default [junit4] 2> 4178150 T15498 oasc.RequestHandlers.initHandlersFromConfig created /update: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4178151 T15498 oasc.RequestHandlers.initHandlersFromConfig created /update/json: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4178156 T15498 oasc.RequestHandlers.initHandlersFromConfig created /update/csv: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4178156 T15498 oasc.RequestHandlers.initHandlersFromConfig created /update/json/docs: org.apache.solr.handler.UpdateRequestHandler [junit4] 2> 4178156 T15498 oasc.RequestHandlers.initHandlersFromConfig created /config: org.apache.solr.handler.SolrConfigHandler [junit4] 2> 4178158 T15498 oasc.RequestHandlers.initHandlersFromConfig created /schema: org.apache.solr.handler.SchemaHandler [junit4] 2> 4178159 T15498 oasc.RequestHandlers.initHandlersFromConfig created /replication: org.apache.solr.handler.ReplicationHandler [junit4] 2> 4178160 T15498 oasc.RequestHandlers.initHandlersFromConfig created /get: org.apache.solr.handler.RealTimeGetHandler [junit4] 2> 4178163 T15498 oasc.RequestHandlers.initHandlersFromConfig created /admin/luke: org.apache.solr.handler.admin.LukeRequestHandler [junit4] 2> 4178168 T15498 oasc.RequestHandlers.initHandlersFromConfig created /admin/system: org.apache.solr.handler.admin.SystemInfoHandler [junit4] 2> 4178170 T15498 oasc.RequestHandlers.initHandlersFromConfig created /admin/mbeans: org.apache.solr.handler.admin.SolrInfoMBeanHandler [junit4] 2> 4178173 T15498 oasc.RequestHandlers.initHandlersFromConfig created /admin/plugins: org.apache.solr.handler.admin.PluginInfoHandler [junit4] 2> 4178175 T15498 oasc.RequestHandlers.initHandlersFromConfig created /admin/threads: org.apache.solr.handler.admin.ThreadDumpHandler [junit4] 2> 4178175 T15498 oasc.RequestHandlers.initHandlersFromConfig created /admin/properties: org.apache.solr.handler.admin.PropertiesRequestHandler [junit4] 2> 4178176 T15498 oasc.RequestHandlers.initHandlersFromConfig created /admin/logging: org.apache.solr.handler.admin.LoggingHandler [junit4] 2> 4178177 T15498 oasc.RequestHandlers.initHandlersFromConfig created /admin/ping: org.apache.solr.handler.PingRequestHandler [junit4] 2> 4178179 T15498 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler [junit4] 2> 4178193 T15498 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler [junit4] 2> 4178194 T15498 oasc.RequestHandlers.initHandlersFromConfig created /admin/fileedit: solr.admin.EditFileRequestHandler [junit4] 2> 4178202 T15498 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4178205 T15498 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4178208 T15498 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4178211 T15498 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60 [junit4] 2> 4178211 T15498 oasc.SolrCore.initStatsCache Using default statsCache cache: org.apache.solr.search.stats.LocalStatsCache [junit4] 2> 4178216 T15498 oasu.CommitTracker [...truncated too long message...] NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_40-ea (32-bit)/cpus=2,threads=1,free=186767872,total=426745856 [junit4] 2> NOTE: All tests run in this JVM: [TriLevelCompositeIdRoutingTest, SuggesterFSTTest, HighlighterConfigTest, OutOfBoxZkACLAndCredentialsProvidersTest, HardAutoCommitTest, LeaderElectionIntegrationTest, DocValuesTest, BasicZkTest, SliceStateTest, TimeZoneUtilsTest, TestCoreDiscovery, TestPseudoReturnFields, RegexBoostProcessorTest, TestIBSimilarityFactory, SuggesterTest, TestRandomDVFaceting, TestLuceneMatchVersion, TestLFUCache, TestSystemIdResolver, AnalysisAfterCoreReloadTest, DisMaxRequestHandlerTest, ExternalFileFieldSortTest, ConvertedLegacyTest, BasicFunctionalityTest, TestDynamicLoading, CircularListTest, UUIDFieldTest, TestHighFrequencyDictionaryFactory, CSVRequestHandlerTest, TestSolr4Spatial2, TestSolrCoreProperties, SpellCheckCollatorTest, TestFieldTypeCollectionResource, TestDFRSimilarityFactory, TestNonNRTOpen, DistributedQueryElevationComponentTest, TestInitQParser, IndexSchemaRuntimeFieldTest, TestSolrConfigHandlerCloud, SuggestComponentTest, PluginInfoTest, SuggesterWFSTTest, TestDefaultSimilarityFactory, TestOrdValues, TestReplicationHandler, AssignTest, ZkNodePropsTest, TestRequestStatusCollectionAPI, TestBlobHandler, SoftAutoCommitTest, TestLRUCache, OverseerCollectionProcessorTest, HdfsWriteToMultipleCollectionsTest, SpellPossibilityIteratorTest, CopyFieldTest, SignatureUpdateProcessorFactoryTest, ResponseLogComponentTest, TestSolrXmlPersistence, TestSolrDeletionPolicy2, TestCharFilters, TestRecovery, CustomCollectionTest, UpdateParamsTest, NumericFieldsTest, TestQuerySenderListener, RAMDirectoryFactoryTest, TestSolrXml, TermVectorComponentTest, TestDocumentBuilder, TestFaceting, SampleTest, InfoHandlerTest, TestExtendedDismaxParser, StatelessScriptUpdateProcessorFactoryTest, TestSolrIndexConfig, TestStressVersions, TestBulkSchemaAPI, SynonymTokenizerTest, TestFunctionQuery, ExternalCollectionsTest, PeerSyncTest, SortByFunctionTest, MultiThreadedOCPTest, SearchHandlerTest, TestSearcherReuse, TestDistributedMissingSort, SharedFSAutoReplicaFailoverTest, DeleteReplicaTest, TestSolrQueryParserDefaultOperatorResource, TestDistributedGrouping, TestPerFieldSimilarity, TestRealTimeGet, TestDistribDocBasedVersion, DirectUpdateHandlerTest, TestSolrQueryParserResource, ZkControllerTest, HdfsUnloadDistributedZkTest, SpellingQueryConverterTest, FieldAnalysisRequestHandlerTest, TestHashPartitioner, TestInfoStreamLogging, TestExactStatsCache, DirectSolrSpellCheckerTest, TestCloudSchemaless, SpatialRPTFieldTypeTest, CurrencyFieldXmlFileTest, TestShardHandlerFactory, QueryElevationComponentTest, TestFastLRUCache, TestAnalyzeInfixSuggestions, ChaosMonkeyNothingIsSafeTest, ModifyConfFileTest, SharedFSAutoReplicaFailoverUtilsTest, TestManagedResourceStorage, ClusterStateTest, TestSchemaResource, FileBasedSpellCheckerTest, TestConfig, TestModifyConfFiles, CoreContainerCoreInitFailuresTest, TestBlendedInfixSuggestions, DocValuesMissingTest, HttpPartitionTest, DistanceFunctionTest, ChangedSchemaMergeTest, DistributedIntervalFacetingTest, TestFieldResource, TestMiniSolrCloudCluster, ShowFileRequestHandlerTest, OverriddenZkACLAndCredentialsProvidersTest, TestChildDocTransformer, OverseerTest, DistributedQueueTest, TestFileDictionaryLookup, TestCloudPivotFacet, LoggingHandlerTest, SolrIndexConfigTest, CloudMLTQParserTest, TestFastOutputStream, TestImplicitCoreProperties, MBeansHandlerTest, BlockDirectoryTest, TestStressRecovery, FieldMutatingUpdateProcessorTest, TestNRTOpen, TestSuggestSpellingConverter, CollectionsAPIDistributedZkTest, CoreMergeIndexesAdminHandlerTest, TestCoreContainer, TestBulkSchemaConcurrent, QueryParsingTest, TestXIncludeConfig, FileUtilsTest, TestManagedResource, TestLMJelinekMercerSimilarityFactory, DistributedFacetPivotSmallTest, HighlighterTest, TestRawResponseWriter, TestComponentsName, ChaosMonkeySafeLeaderTest, TestJoin, DocExpirationUpdateProcessorFactoryTest, TestFieldCollectionResource, TestPHPSerializedResponseWriter, AddSchemaFieldsUpdateProcessorFactoryTest, OpenExchangeRatesOrgProviderTest, LeaderElectionTest, TestMissingGroups, TestUniqueKeyFieldResource, HdfsCollectionsAPIDistributedZkTest, TestAddFieldRealTimeGet, TestWriterPerf, HdfsLockFactoryTest, CollectionsAPIAsyncDistributedZkTest, TestSchemaVersionResource, VMParamsZkACLAndCredentialsProvidersTest, DocValuesMultiTest, TestSolrXmlPersistor, TestDynamicFieldResource, TestRTGBase, SolrIndexSplitterTest, TestStressLucene, FastVectorHighlighterTest, TestFoldingMultitermQuery, SolrCoreCheckLockOnStartupTest, SpellCheckComponentTest, DateMathParserTest, TestReloadAndDeleteDocs, NotRequiredUniqueKeyTest, TestManagedStopFilterFactory, LegacyHTMLStripCharFilterTest, DirectSolrConnectionTest, TestArbitraryIndexDir, DistributedExpandComponentTest, TestSchemaManager, TestLRUStatsCache, TestLazyCores, TestRandomFaceting, HdfsBasicDistributedZkTest, JSONWriterTest, UnloadDistributedZkTest, TestWordDelimiterFilterFactory, TestReplicaProperties, DistributedFacetPivotLongTailTest, AnalyticsQueryTest, TestBadConfig, HdfsSyncSliceTest, TestDocBasedVersionConstraints, SimpleMLTQParserTest, DistributedFacetPivotWhiteBoxTest, TestPivotHelperCode, BJQParserTest, TestCSVResponseWriter, TestReplicationHandlerBackup, TestCustomSort, OverseerStatusTest, TestExceedMaxTermLength, DirectUpdateHandlerOptimizeTest, DeleteInactiveReplicaTest, MultiTermTest, OutputWriterTest, BasicDistributedZkTest, BasicDistributedZk2Test, SyncSliceTest, OpenCloseCoreStressTest, ShardRoutingTest, RecoveryZkTest, FullSolrCloudDistribCmdsTest, ClusterStateUpdateTest, ZkSolrClientTest, TestZkChroot, ZkCLITest, ShardRoutingCustomTest, TestDistributedSearch, AutoCommitTest, DistributedSpellCheckComponentTest, TermVectorComponentDistributedTest, TestStressReorder, TestReload, DistributedTermsComponentTest, TestRangeQuery, SimpleFacetsTest, TestSolr4Spatial, SolrCoreTest, StatsComponentTest, TestGroupingSearch, SolrCmdDistributorTest, BadIndexSchemaTest, TestSort, TestFiltering, TestIndexSearcher, CurrencyFieldOpenExchangeTest, SimplePostToolTest, CoreAdminHandlerTest, SolrRequestParserTest, SuggesterTSTTest, TestTrie, SpatialFilterTest, TestCSVLoader, PolyFieldTest, NoCacheHeaderTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestUpdate, TestAtomicUpdateErrorCases, QueryEqualityTest, DefaultValueUpdateProcessorTest, TestRemoteStreaming, TestSolrDeletionPolicy1, SolrInfoMBeanTest, XsltUpdateRequestHandlerTest, DebugComponentTest, CacheHeaderTest, IndexBasedSpellCheckerTest, TestSurroundQueryParser, LukeRequestHandlerTest, TestQueryUtils, StandardRequestHandlerTest, TestReversedWildcardFilterFactory, DocumentAnalysisRequestHandlerTest, TestQueryTypes, PrimitiveFieldTypeTest, TestOmitPositions, TermsComponentTest, XmlUpdateRequestHandlerTest, RequestHandlersTest, DocumentBuilderTest, TestValueSourceCache, PathHierarchyTokenizerFactoryTest, TestIndexingPerformance, MoreLikeThisHandlerTest, RequiredFieldsTest, TestSolrQueryParser, SolrPluginUtilsTest, IndexSchemaTest, TestJmxIntegration, TestCollationField, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, TestAnalyzedSuggestions, UniqFieldsUpdateProcessorFactoryTest, JsonLoaderTest, BinaryUpdateRequestHandlerTest, TestPartialUpdateDeduplication, PingRequestHandlerTest, TestBinaryResponseWriter, SOLR749Test, AlternateDirectoryTest, TestQuerySenderNoQuery, BadComponentTest, TestMergePolicyConfig, TestDocSet, TestBinaryField, TestSearchPerf, MinimalSchemaTest, TestElisionMultitermQuery, TestFuzzyAnalyzedSuggestions, TestPostingsSolrHighlighter, TestPhraseSuggestions, TestCodecSupport, EchoParamsTest, TestSweetSpotSimilarityFactory, TestLMDirichletSimilarityFactory, TestBM25SimilarityFactory, TestFastWriter, TestJmxMonitoredMap, ResourceLoaderTest, ScriptEngineTest, URLClassifyProcessorTest, PreAnalyzedFieldTest, PrimUtilsTest, DateFieldTest, DOMUtilTest, TestSolrJ, TestUtils, SystemInfoHandlerTest, CursorPagingTest, SolrTestCaseJ4Test, TestCursorMarkWithoutUniqueKey, TestHighlightDedupGrouping, TestTolerantSearch, AliasIntegrationTest, AsyncMigrateRouteKeyTest, CloudExitableDirectoryReaderTest, ConnectionManagerTest, DeleteShardTest, DistribCursorPagingTest, DistribDocExpirationUpdateProcessorTest, LeaderFailoverAfterPartitionTest, LeaderInitiatedRecoveryOnCommitTest, MigrateRouteKeyTest, OverseerRolesTest, RemoteQueryErrorTest, ReplicationFactorTest] [junit4] Completed in 96.52s, 1 test, 1 error <<< FAILURES! [...truncated 304 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:187: 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:1349: The following error occurred while executing this line: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:956: There were test failures: 462 suites, 1868 tests, 1 error, 56 ignored (31 assumptions) Total time: 134 minutes 7 seconds Build step 'Invoke Ant' marked build as failure [description-setter] Description set: Java: 32bit/jdk1.8.0_40-ea-b09 -client -XX:+UseConcMarkSweepGC (asserts: true) 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
