Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/4723/
Java: 32bit/jdk1.8.0_40 -server -XX:+UseSerialGC
1 tests failed.
FAILED: org.apache.solr.client.solrj.impl.CloudSolrClientTest.test
Error Message:
There should be 3 documents because there should be two id=1 docs due to
overwrite=false expected:<3> but was:<1>
Stack Trace:
java.lang.AssertionError: There should be 3 documents because there should be
two id=1 docs due to overwrite=false expected:<3> but was:<1>
at
__randomizedtesting.SeedInfo.seed([B991998ABE83A57F:31C5A650107FC887]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at
org.apache.solr.client.solrj.impl.CloudSolrClientTest.testOverwriteOption(CloudSolrClientTest.java:171)
at
org.apache.solr.client.solrj.impl.CloudSolrClientTest.test(CloudSolrClientTest.java:129)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at
sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:62)
at
sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:497)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1627)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:836)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:872)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:886)
at
org.junit.rules.ExpectedException$ExpectedExceptionStatement.evaluate(ExpectedException.java:110)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsFixedStatement.callStatement(BaseDistributedSearchTestCase.java:960)
at
org.apache.solr.BaseDistributedSearchTestCase$ShardsRepeatRule$ShardsStatement.evaluate(BaseDistributedSearchTestCase.java:935)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:798)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:458)
at
com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:845)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:747)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:781)
at
com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:792)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:57)
at
org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:54)
at
org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
at
org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:65)
at
org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
at
com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
at
com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:365)
at java.lang.Thread.run(Thread.java:745)
Build Log:
[...truncated 11383 lines...]
[junit4] Suite: org.apache.solr.client.solrj.impl.CloudSolrClientTest
[junit4] 2> Creating dataDir:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\init-core-data-001
[junit4] 2> 79859 T412 oas.SolrTestCaseJ4.buildSSLConfig Randomized ssl
(false) and clientAuth (true)
[junit4] 2> 79859 T412 oas.BaseDistributedSearchTestCase.initHostContext
Setting hostContext system property: /
[junit4] 2> 79863 T412 oasc.AbstractZkTestCase.<clinit> WARN TEST_HOME()
does not exist - solrj test?
[junit4] 2> 79874 T412 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 79876 T413 oasc.ZkTestServer$2$1.setClientPort client
port:0.0.0.0/0.0.0.0:0
[junit4] 2> 79876 T413 oasc.ZkTestServer$ZKServerMain.runFromConfig
Starting server
[junit4] 2> 79991 T412 oasc.ZkTestServer.run start zk server on port:53770
[junit4] 2> 79991 T412
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 79993 T412 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 80001 T420 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@15a422a name:ZooKeeperConnection
Watcher:127.0.0.1:53770 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 80001 T412 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 80002 T412 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 80002 T412 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 80009 T412
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 80011 T412 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 80014 T423 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@deb71b name:ZooKeeperConnection
Watcher:127.0.0.1:53770/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 80015 T412 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 80017 T412 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 80017 T412 oascc.SolrZkClient.makePath makePath:
/collections/collection1
[junit4] 2> 80023 T412 oascc.SolrZkClient.makePath makePath:
/collections/collection1/shards
[junit4] 2> 80029 T412 oascc.SolrZkClient.makePath makePath:
/collections/control_collection
[junit4] 2> 80032 T412 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/shards
[junit4] 2> 80040 T412 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.xml
to /configs/conf1/solrconfig.xml
[junit4] 2> 80041 T412 oascc.SolrZkClient.makePath makePath:
/configs/conf1/solrconfig.xml
[junit4] 2> 80048 T412 oasc.AbstractZkTestCase.putConfig put
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\schema.xml
to /configs/conf1/schema.xml
[junit4] 2> 80049 T412 oascc.SolrZkClient.makePath makePath:
/configs/conf1/schema.xml
[junit4] 2> 80053 T412 oasc.AbstractZkTestCase.putConfig skipping
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml
because it doesn't exist
[junit4] 2> 80054 T412 oasc.AbstractZkTestCase.putConfig skipping
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\stopwords.txt
because it doesn't exist
[junit4] 2> 80054 T412 oasc.AbstractZkTestCase.putConfig skipping
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\protwords.txt
because it doesn't exist
[junit4] 2> 80054 T412 oasc.AbstractZkTestCase.putConfig skipping
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\currency.xml
because it doesn't exist
[junit4] 2> 80054 T412 oasc.AbstractZkTestCase.putConfig skipping
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\enumsConfig.xml
because it doesn't exist
[junit4] 2> 80056 T412 oasc.AbstractZkTestCase.putConfig skipping
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\open-exchange-rates.json
because it doesn't exist
[junit4] 2> 80056 T412 oasc.AbstractZkTestCase.putConfig skipping
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\mapping-ISOLatin1Accent.txt
because it doesn't exist
[junit4] 2> 80057 T412 oasc.AbstractZkTestCase.putConfig skipping
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\old_synonyms.txt
because it doesn't exist
[junit4] 2> 80057 T412 oasc.AbstractZkTestCase.putConfig skipping
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\solrj\src\test-files\solrj\solr\collection1\conf\synonyms.txt
because it doesn't exist
[junit4] 2> 80140 T412 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores\collection1
[junit4] 2> 80146 T412 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 80151 T412 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@473f3d{/,null,AVAILABLE}
[junit4] 2> 80162 T412 oejs.AbstractConnector.doStart Started
ServerConnector@7fe054{HTTP/1.1}{127.0.0.1:53777}
[junit4] 2> 80163 T412 oejs.Server.doStart Started @94547ms
[junit4] 2> 80163 T412 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties:
{solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\tempDir-001/control/data, hostContext=/, hostPort=53777,
coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores}
[junit4] 2> 80164 T412 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@19e0bfd
[junit4] 2> 80164 T412 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\'
[junit4] 2> 80189 T412 oasc.SolrXmlConfig.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\solr.xml
[junit4] 2> 80199 T412 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores
[junit4] 2> 80199 T412 oasc.CoreContainer.<init> New CoreContainer 31717897
[junit4] 2> 80199 T412 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\]
[junit4] 2> 80200 T412 oasc.CoreContainer.load loading shared library:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\lib
[junit4] 2> 80200 T412 oasc.SolrResourceLoader.addToClassLoader WARN Can't
find (or read) directory to add to classloader: lib (resolved as:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\lib).
[junit4] 2> 80208 T412 oashc.HttpShardHandlerFactory.init created with
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost :
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize :
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy :
false,useRetries : false,
[junit4] 2> 80211 T412 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 80212 T412 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 80212 T412 oasl.LogWatcher.newRegisteredLogWatcher Registering
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 80212 T412 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 80212 T412 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:53770/solr
[junit4] 2> 80212 T412 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 80212 T412
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 80215 T412 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 80218 T437 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@119e33e name:ZooKeeperConnection
Watcher:127.0.0.1:53770 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 80218 T412 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 80219 T412 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 80223 T412 N:127.0.0.1:53777_
oascc.ConnectionManager.waitForConnected Waiting for client to connect to
ZooKeeper
[junit4] 2> 80228 T440 N:127.0.0.1:53777_ oascc.ConnectionManager.process
Watcher org.apache.solr.common.cloud.ConnectionManager@9c164
name:ZooKeeperConnection Watcher:127.0.0.1:53770/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 80229 T412 N:127.0.0.1:53777_
oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 80231 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /overseer/queue
[junit4] 2> 80236 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /overseer/collection-queue-work
[junit4] 2> 80245 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /overseer/collection-map-running
[junit4] 2> 80258 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /overseer/collection-map-completed
[junit4] 2> 80262 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /overseer/collection-map-failure
[junit4] 2> 80266 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /live_nodes
[junit4] 2> 80269 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /aliases.json
[junit4] 2> 80271 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /clusterstate.json
[junit4] 2> 80273 T412 N:127.0.0.1:53777_
oasc.ZkController.createEphemeralLiveNode Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53777_
[junit4] 2> 80275 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /live_nodes/127.0.0.1:53777_
[junit4] 2> 80278 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /overseer_elect
[junit4] 2> 80279 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /overseer_elect/election
[junit4] 2> 80281 T412 N:127.0.0.1:53777_ oasc.Overseer.close Overseer
(id=null) closing
[junit4] 2> 80283 T412 N:127.0.0.1:53777_
oasc.OverseerElectionContext.runLeaderProcess I am going to be the leader
127.0.0.1:53777_
[junit4] 2> 80283 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /overseer_elect/leader
[junit4] 2> 80286 T412 N:127.0.0.1:53777_ oasc.Overseer.start Overseer
(id=93710052573904899-127.0.0.1:53777_-n_0000000000) starting
[junit4] 2> 80288 T412 N:127.0.0.1:53777_ oascc.SolrZkClient.makePath
makePath: /overseer/queue-work
[junit4] 2> 80296 T412 N:127.0.0.1:53777_
oasc.OverseerAutoReplicaFailoverThread.<init> Starting
OverseerAutoReplicaFailoverThread autoReplicaFailoverWorkLoopDelay=10000
autoReplicaFailoverWaitAfterExpiration=30000
autoReplicaFailoverBadNodeExpiration=60000
[junit4] 2> 80299 T412 N:127.0.0.1:53777_
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 80299 T442 N:127.0.0.1:53777_
oasc.OverseerCollectionProcessor.run Process current queue of collection
creations
[junit4] 2> 80302 T441 N:127.0.0.1:53777_
oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 80310 T412 N:127.0.0.1:53777_
oasc.CorePropertiesLocator.discover Looking for core definitions underneath
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores
[junit4] 2> 80320 T412 N:127.0.0.1:53777_ oasc.CoreDescriptor.<init> CORE
DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false,
schema=schema.xml, loadOnStartup=true,
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores\collection1,
collection=control_collection,
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores\collection1\, coreNodeName=,
dataDir=data\, shard=}
[junit4] 2> 80321 T412 N:127.0.0.1:53777_
oasc.CorePropertiesLocator.discoverUnder Found core collection1 in
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores\collection1\
[junit4] 2> 80321 T412 N:127.0.0.1:53777_
oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 80324 T444 N:127.0.0.1:53777_ C:control_collection
c:collection1 oasc.ZkController.publish publishing core=collection1 state=down
collection=control_collection
[junit4] 2> 80324 T444 N:127.0.0.1:53777_ C:control_collection
c:collection1 oasc.ZkController.publish numShards not found on descriptor -
reading it from system property
[junit4] 2> 80329 T444 N:127.0.0.1:53777_
oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 80329 T440 N:127.0.0.1:53777_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 80334 T441 N:127.0.0.1:53777_
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:53777";,
[junit4] 2> "node_name":"127.0.0.1:53777_",
[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> 80354 T441 N:127.0.0.1:53777_ 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:53777";,
[junit4] 2> "node_name":"127.0.0.1:53777_",
[junit4] 2> "numShards":"1",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 80358 T441 N:127.0.0.1:53777_
oasco.ClusterStateMutator.createCollection building a new cName:
control_collection
[junit4] 2> 80379 T441 N:127.0.0.1:53777_ oasco.ReplicaMutator.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 80389 T440 N:127.0.0.1:53777_ 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> 81561 T444 N:127.0.0.1:53777_ oasc.ZkController.waitForShardId
waiting to find shard id in clusterstate for collection1
[junit4] 2> 81561 T444 N:127.0.0.1:53777_
oasc.ZkController.createCollectionZkNode Check for collection
zkNode:control_collection
[junit4] 2> 81562 T444 N:127.0.0.1:53777_
oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 81562 T444 N:127.0.0.1:53777_
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/control_collection
[junit4] 2> 81564 T444 N:127.0.0.1:53777_
oascc.ZkStateReader.readConfigName path=/collections/control_collection
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 81564 T444 N:127.0.0.1:53777_ oasc.SolrResourceLoader.<init>
new SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores\collection1\'
[junit4] 2> 81615 T444 N:127.0.0.1:53777_ oasc.Config.<init> loaded config
solrconfig.xml with version 0
[junit4] 2> 81627 T444 N:127.0.0.1:53777_
oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 81630 T444 N:127.0.0.1:53777_ oasc.SolrConfig.<init> Using
Lucene MatchVersion: 6.0.0
[junit4] 2> 81645 T444 N:127.0.0.1:53777_ oasc.SolrConfig.<init> Loaded
SolrConfig: solrconfig.xml
[junit4] 2> 81648 T444 N:127.0.0.1:53777_ oass.IndexSchema.readSchema
Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 81656 T444 N:127.0.0.1:53777_ oass.IndexSchema.readSchema
[collection1] Schema name=test
[junit4] 2> 81848 T444 N:127.0.0.1:53777_ oass.IndexSchema.readSchema
default search field in schema is text
[junit4] 2> 81849 T444 N:127.0.0.1:53777_ oass.IndexSchema.readSchema
unique key field: id
[junit4] 2> 81851 T444 N:127.0.0.1:53777_ oass.IndexSchema.loadCopyFields
WARN Field lowerfilt1and2 is not multivalued and destination for multiple
copyFields (2)
[junit4] 2> 81851 T444 N:127.0.0.1:53777_ oass.IndexSchema.loadCopyFields
WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 81859 T444 N:127.0.0.1:53777_ oasc.CoreContainer.create
Creating SolrCore 'collection1' using configuration from collection
control_collection
[junit4] 2> 81859 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 81859 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores\collection1\], dataDir=[null]
[junit4] 2> 81859 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
[junit4] 2> 81860 T444 N:127.0.0.1:53777_ c:collection1
oasc.CachingDirectoryFactory.get return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores\collection1\data\
[junit4] 2> 81860 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrCore.getNewIndexDir New index directory detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores\collection1\data\index/
[junit4] 2> 81860 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrCore.initIndex WARN [collection1] Solr index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores\collection1\data\index' doesn't exist.
Creating new index...
[junit4] 2> 81862 T444 N:127.0.0.1:53777_ c:collection1
oasc.CachingDirectoryFactory.get return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\control-001\cores\collection1\data\index
[junit4] 2> 81862 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1df80d1
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a9272),segFN=segments_1,generation=1}
[junit4] 2> 81862 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 81864 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined
as default, creating implicit default
[junit4] 2> 81869 T444 N:127.0.0.1:53777_ c:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 81870 T444 N:127.0.0.1:53777_ c:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 81873 T444 N:127.0.0.1:53777_ c:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 81873 T444 N:127.0.0.1:53777_ c:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 81874 T444 N:127.0.0.1:53777_ c:collection1
oasc.PluginBag.createPlugin requestHandler : '/replication' created with
startup=lazy
[junit4] 2> 81904 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis
class [org.apache.solr.handler.admin.AdminHandlers]. Please consult
documentation how to replace it accordingly.
[junit4] 2> 81904 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis
class [org.apache.solr.handler.admin.AdminHandlers]. Please consult
documentation how to replace it accordingly.
[junit4] 2> 81906 T444 N:127.0.0.1:53777_ c:collection1
oasc.RequestHandlers.initHandlersFromConfig Registered paths:
/admin/mbeans,standard,,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 81906 T444 N:127.0.0.1:53777_ c:collection1
oasc.RequestHandlers.initHandlersFromConfig WARN no default request handler is
registered (either '/select' or 'standard')
[junit4] 2> 81906 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrCore.initStatsCache Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 81908 T444 N:127.0.0.1:53777_ c:collection1
oasu.UpdateHandler.<init> Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 81908 T444 N:127.0.0.1:53777_ c:collection1
oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 81909 T444 N:127.0.0.1:53777_ c:collection1
oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 81909 T444 N:127.0.0.1:53777_ c:collection1
oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 81911 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@1df80d1
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2a9272),segFN=segments_1,generation=1}
[junit4] 2> 81911 T444 N:127.0.0.1:53777_ c:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 81912 T444 N:127.0.0.1:53777_ c:collection1
oass.SolrIndexSearcher.<init> Opening Searcher@2759f6[collection1] main
[junit4] 2> 81912 T444 N:127.0.0.1:53777_ c:collection1
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/control_collection
[junit4] 2> 81914 T444 N:127.0.0.1:53777_ c:collection1
oascc.ZkStateReader.readConfigName path=/collections/control_collection
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 81914 T444 N:127.0.0.1:53777_ c:collection1
oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for
the RestManager with znodeBase: /configs/conf1
[junit4] 2> 81923 T444 N:127.0.0.1:53777_ c:collection1
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 81923 T444 N:127.0.0.1:53777_ c:collection1
oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 81923 T444 N:127.0.0.1:53777_ c:collection1
oasr.ManagedResourceStorage.load Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 81924 T444 N:127.0.0.1:53777_ c:collection1
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 81924 T444 N:127.0.0.1:53777_ c:collection1
oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 81924 T444 N:127.0.0.1:53777_ c:collection1
oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 81926 T444 N:127.0.0.1:53777_ c:collection1
oasha.AdminHandlers.inform WARN <requestHandler name="/admin/"
[junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It
is not required anymore
[junit4] 2> 81926 T445 N:127.0.0.1:53777_ c:collection1
oasc.SolrCore.registerSearcher [collection1] Registered new searcher
Searcher@2759f6[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 81931 T444 N:127.0.0.1:53777_ c:collection1
oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1
[junit4] 2> 81935 T444 N:127.0.0.1:53777_ c:collection1
oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 81936 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oasc.ZkController.register Register replica - core:collection1
address:http://127.0.0.1:53777 collection:control_collection shard:shard1
[junit4] 2> 81937 T412 N:127.0.0.1:53777_ oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 81937 T412 N:127.0.0.1:53777_ oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 81942 T412
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 81944 T412 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 81956 T451 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2b1651 name:ZooKeeperConnection
Watcher:127.0.0.1:53770/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 81957 T412 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 81957 T412 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 81957 T412
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 81963 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leader_elect/shard1/election
[junit4] 2> 81974 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the
leader process for shard shard1
[junit4] 2> 81975 T412 oasc.ChaosMonkey.monkeyLog monkey: init - expire
sessions:false cause connection loss:false
[junit4] 2> 81975 T412 oasc.AbstractFullDistribZkTestBase.createJettys
Creating collection1 with stateFormat=2
[junit4] 2> 81975 T412
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 81978 T412 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 81983 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough
replicas found to continue.
[junit4] 2> 81983 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new
leader - try and sync
[junit4] 2> ASYNC NEW_CORE C99 name=collection1
org.apache.solr.core.SolrCore@1eae662 url=http://127.0.0.1:53777/collection1
node=127.0.0.1:53777_ C99_STATE=coll:control_collection core:collection1
props:{core=collection1, base_url=http://127.0.0.1:53777,
node_name=127.0.0.1:53777_, state=down}
[junit4] 2> 81986 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 C99 oasc.SyncStrategy.sync Sync replicas to
http://127.0.0.1:53777/collection1/
[junit4] 2> 81988 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 C99 oasc.SyncStrategy.syncReplicas Sync Success - now sync
replicas to me
[junit4] 2> 81988 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 C99 oasc.SyncStrategy.syncToMe
http://127.0.0.1:53777/collection1/ has no replicas
[junit4] 2> 81986 T440 N:127.0.0.1:53777_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 81988 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new
leader: http://127.0.0.1:53777/collection1/ shard1
[junit4] 2> 81994 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oascc.SolrZkClient.makePath makePath:
/collections/control_collection/leaders/shard1
[junit4] 2> 82007 T454 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@1e9ddc5 name:ZooKeeperConnection
Watcher:127.0.0.1:53770/solr got event WatchedEvent state:SyncConnected
type:None path:null path:null type:None
[junit4] 2> 82007 T412 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 82008 T412 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 82013 T441 N:127.0.0.1:53777_
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> 82049 T441 N:127.0.0.1:53777_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"create",
[junit4] 2> "name":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "stateFormat":"2"} current state version: 1
[junit4] 2> 82049 T441 N:127.0.0.1:53777_
oasco.ClusterStateMutator.createCollection building a new cName: collection1
[junit4] 2> 82052 T451 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> 82057 T440 N:127.0.0.1:53777_ 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> 82068 T440 N:127.0.0.1:53777_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 82070 T441 N:127.0.0.1:53777_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "base_url":"http://127.0.0.1:53777";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 2
[junit4] 2> 82072 T441 N:127.0.0.1:53777_
oasco.ZkStateWriter.writePendingUpdates going to create_collection
/collections/collection1/state.json
[junit4] 2> 82077 T451 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> 82081 T440 N:127.0.0.1:53777_ 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> 82111 T412 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores\collection1
[junit4] 2> 82114 T412 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 1 in directory
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001
[junit4] 2> 82116 T412 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 82122 T412 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@c5c1f2{/,null,AVAILABLE}
[junit4] 2> 82126 T412 oejs.AbstractConnector.doStart Started
ServerConnector@15bb833{HTTP/1.1}{127.0.0.1:53792}
[junit4] 2> 82126 T412 oejs.Server.doStart Started @96159ms
[junit4] 2> 82126 T412 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties:
{solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\tempDir-001/jetty1, solrconfig=solrconfig.xml,
hostContext=/, hostPort=53792,
coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores}
[junit4] 2> 82127 T412 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@19e0bfd
[junit4] 2> 82127 T412 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\'
[junit4] 2> 82165 T412 oasc.SolrXmlConfig.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\solr.xml
[junit4] 2> 82178 T412 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores
[junit4] 2> 82180 T412 oasc.CoreContainer.<init> New CoreContainer 31475457
[junit4] 2> 82180 T412 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\]
[junit4] 2> 82180 T412 oasc.CoreContainer.load loading shared library:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\lib
[junit4] 2> 82181 T412 oasc.SolrResourceLoader.addToClassLoader WARN Can't
find (or read) directory to add to classloader: lib (resolved as:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\lib).
[junit4] 2> 82192 T412 oashc.HttpShardHandlerFactory.init created with
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost :
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize :
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy :
false,useRetries : false,
[junit4] 2> 82194 T412 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 82196 T412 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 82197 T412 oasl.LogWatcher.newRegisteredLogWatcher Registering
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 82197 T412 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 82197 T412 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:53770/solr
[junit4] 2> 82197 T412 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 82198 T412
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 82199 T412 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 82204 T471 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@12a0dcd name:ZooKeeperConnection
Watcher:127.0.0.1:53770 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 82204 T412 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 82204 T412 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 82210 T414 oazs.NIOServerCnxn.doIO WARN caught end of stream
exception EndOfStreamException: Unable to read additional data from client
sessionid 0x14ceccbdb8d0006, 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> 82213 T412 N:127.0.0.1:53792_
oascc.ConnectionManager.waitForConnected Waiting for client to connect to
ZooKeeper
[junit4] 2> 82220 T474 N:127.0.0.1:53792_ oascc.ConnectionManager.process
Watcher org.apache.solr.common.cloud.ConnectionManager@1e59a72
name:ZooKeeperConnection Watcher:127.0.0.1:53770/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 82221 T412 N:127.0.0.1:53792_
oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 82231 T451 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> 82232 T440 N:127.0.0.1:53777_ 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> 82242 T412 N:127.0.0.1:53792_
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 82306 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oasc.ZkController.register We are
http://127.0.0.1:53777/collection1/ and leader is
http://127.0.0.1:53777/collection1/
[junit4] 2> 82306 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oasc.ZkController.register No LogReplay needed for
core=collection1 baseURL=http://127.0.0.1:53777
[junit4] 2> 82306 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery
necessary
[junit4] 2> 82306 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oasc.ZkController.publish publishing core=collection1
state=active collection=control_collection
[junit4] 2> 82306 T448 N:127.0.0.1:53777_ C:control_collection S:shard1
c:collection1 oasc.ZkController.publish numShards not found on descriptor -
reading it from system property
[junit4] 2> 82308 T440 N:127.0.0.1:53777_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 82309 T441 N:127.0.0.1:53777_
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:53777";,
[junit4] 2> "node_name":"127.0.0.1:53777_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"} current state version: 4
[junit4] 2> 82311 T441 N:127.0.0.1:53777_ oasco.ReplicaMutator.updateState
Update state numShards=2 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:53777";,
[junit4] 2> "node_name":"127.0.0.1:53777_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard1",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "operation":"state"}
[junit4] 2> 82441 T440 N:127.0.0.1:53777_ 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> 82441 T474 N:127.0.0.1:53792_ 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> 82441 T451 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> 83507 T412 N:127.0.0.1:53792_
oasc.ZkController.createEphemeralLiveNode Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53792_
[junit4] 2> 83509 T412 N:127.0.0.1:53792_ oascc.SolrZkClient.makePath
makePath: /live_nodes/127.0.0.1:53792_
[junit4] 2> 83516 T412 N:127.0.0.1:53792_ oasc.Overseer.close Overseer
(id=null) closing
[junit4] 2> 83527 T412 N:127.0.0.1:53792_
oasc.CorePropertiesLocator.discover Looking for core definitions underneath
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores
[junit4] 2> 83531 T412 N:127.0.0.1:53792_ oasc.CoreDescriptor.<init> CORE
DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false,
schema=schema.xml, loadOnStartup=true,
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores\collection1, collection=collection1,
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores\collection1\, coreNodeName=,
dataDir=data\, shard=}
[junit4] 2> 83533 T412 N:127.0.0.1:53792_
oasc.CorePropertiesLocator.discoverUnder Found core collection1 in
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores\collection1\
[junit4] 2> 83533 T412 N:127.0.0.1:53792_
oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 83536 T475 N:127.0.0.1:53792_ C:collection1 c:collection1
oasc.ZkController.publish publishing core=collection1 state=down
collection=collection1
[junit4] 2> 83536 T475 N:127.0.0.1:53792_ C:collection1 c:collection1
oasc.ZkController.publish numShards not found on descriptor - reading it from
system property
[junit4] 2> 83539 T440 N:127.0.0.1:53777_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 83541 T475 N:127.0.0.1:53792_ oasc.ZkController.preRegister
Registering watch for external collection collection1
[junit4] 2> 83541 T475 N:127.0.0.1:53792_ oascc.ZkStateReader.addZkWatch
addZkWatch collection1
[junit4] 2> 83541 T441 N:127.0.0.1:53777_
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:53792";,
[junit4] 2> "node_name":"127.0.0.1:53792_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 83542 T475 N:127.0.0.1:53792_ oascc.ZkStateReader.addZkWatch
Updating collection state at /collections/collection1/state.json from
ZooKeeper...
[junit4] 2> 83542 T441 N:127.0.0.1:53777_ oasco.ReplicaMutator.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53792";,
[junit4] 2> "node_name":"127.0.0.1:53792_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 83544 T441 N:127.0.0.1:53777_ oasco.ReplicaMutator.updateState
Collection already exists with numShards=2
[junit4] 2> 83544 T441 N:127.0.0.1:53777_ oasco.ReplicaMutator.updateState
Assigning new node to shard shard=shard2
[junit4] 2> 83549 T475 N:127.0.0.1:53792_
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to
ver 0
[junit4] 2> 83549 T475 N:127.0.0.1:53792_
oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 83676 T441 N:127.0.0.1:53777_
oasco.ZkStateWriter.writePendingUpdates going to update_collection
/collections/collection1/state.json version: 0
[junit4] 2> 83677 T474 N:127.0.0.1:53792_ oascc.ZkStateReader$7.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json for collection collection1 has
occurred - updating... (live nodes size: 2)
[junit4] 2> 83679 T474 N:127.0.0.1:53792_
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to
ver 1
[junit4] 2> 84801 T475 N:127.0.0.1:53792_ oasc.ZkController.waitForShardId
waiting to find shard id in clusterstate for collection1
[junit4] 2> 84801 T475 N:127.0.0.1:53792_
oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 84802 T475 N:127.0.0.1:53792_
oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 84802 T475 N:127.0.0.1:53792_
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/collection1
[junit4] 2> 84804 T475 N:127.0.0.1:53792_
oascc.ZkStateReader.readConfigName path=/collections/collection1
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 84804 T475 N:127.0.0.1:53792_ oasc.SolrResourceLoader.<init>
new SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores\collection1\'
[junit4] 2> 84827 T475 N:127.0.0.1:53792_ oasc.Config.<init> loaded config
solrconfig.xml with version 0
[junit4] 2> 84834 T475 N:127.0.0.1:53792_
oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 84837 T475 N:127.0.0.1:53792_ oasc.SolrConfig.<init> Using
Lucene MatchVersion: 6.0.0
[junit4] 2> 84849 T475 N:127.0.0.1:53792_ oasc.SolrConfig.<init> Loaded
SolrConfig: solrconfig.xml
[junit4] 2> 84851 T475 N:127.0.0.1:53792_ oass.IndexSchema.readSchema
Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 84859 T475 N:127.0.0.1:53792_ oass.IndexSchema.readSchema
[collection1] Schema name=test
[junit4] 2> 84955 T475 N:127.0.0.1:53792_ oass.IndexSchema.readSchema
default search field in schema is text
[junit4] 2> 84957 T475 N:127.0.0.1:53792_ oass.IndexSchema.readSchema
unique key field: id
[junit4] 2> 84958 T475 N:127.0.0.1:53792_ oass.IndexSchema.loadCopyFields
WARN Field lowerfilt1and2 is not multivalued and destination for multiple
copyFields (2)
[junit4] 2> 84959 T475 N:127.0.0.1:53792_ oass.IndexSchema.loadCopyFields
WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 84959 T475 N:127.0.0.1:53792_ oasc.CoreContainer.create
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 84959 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 84960 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores\collection1\], dataDir=[null]
[junit4] 2> 84960 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
[junit4] 2> 84960 T475 N:127.0.0.1:53792_ c:collection1
oasc.CachingDirectoryFactory.get return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores\collection1\data\
[junit4] 2> 84960 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrCore.getNewIndexDir New index directory detected: old=null
new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores\collection1\data\index/
[junit4] 2> 84960 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrCore.initIndex WARN [collection1] Solr index directory
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores\collection1\data\index' doesn't exist.
Creating new index...
[junit4] 2> 84961 T475 N:127.0.0.1:53792_ c:collection1
oasc.CachingDirectoryFactory.get return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-1-001\cores\collection1\data\index
[junit4] 2> 84961 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4c3ba
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11076c9),segFN=segments_1,generation=1}
[junit4] 2> 84961 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 84964 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined
as default, creating implicit default
[junit4] 2> 84970 T475 N:127.0.0.1:53792_ c:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 84971 T475 N:127.0.0.1:53792_ c:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 84973 T475 N:127.0.0.1:53792_ c:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 84974 T475 N:127.0.0.1:53792_ c:collection1
oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 84974 T475 N:127.0.0.1:53792_ c:collection1
oasc.PluginBag.createPlugin requestHandler : '/replication' created with
startup=lazy
[junit4] 2> 84976 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis
class [org.apache.solr.handler.admin.AdminHandlers]. Please consult
documentation how to replace it accordingly.
[junit4] 2> 84976 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrResourceLoader.findClass WARN Solr loaded a deprecated plugin/analysis
class [org.apache.solr.handler.admin.AdminHandlers]. Please consult
documentation how to replace it accordingly.
[junit4] 2> 84976 T475 N:127.0.0.1:53792_ c:collection1
oasc.RequestHandlers.initHandlersFromConfig Registered paths:
/admin/mbeans,standard,,/update/csv,/update/json/docs,/admin/luke,/admin/segments,/get,/admin/system,/replication,/admin/properties,/config,/schema,/admin/plugins,/admin/logging,/update/json,/admin,/admin/threads,/admin/ping,/update,/admin/file
[junit4] 2> 84976 T475 N:127.0.0.1:53792_ c:collection1
oasc.RequestHandlers.initHandlersFromConfig WARN no default request handler is
registered (either '/select' or 'standard')
[junit4] 2> 84976 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrCore.initStatsCache Using default statsCache cache:
org.apache.solr.search.stats.LocalStatsCache
[junit4] 2> 84979 T475 N:127.0.0.1:53792_ c:collection1
oasu.UpdateHandler.<init> Using UpdateLog implementation:
org.apache.solr.update.UpdateLog
[junit4] 2> 84979 T475 N:127.0.0.1:53792_ c:collection1
oasu.UpdateLog.init Initializing UpdateLog: dataDir= defaultSyncLevel=FLUSH
numRecordsToKeep=100 maxNumLogsToKeep=10
[junit4] 2> 84981 T475 N:127.0.0.1:53792_ c:collection1
oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 84981 T475 N:127.0.0.1:53792_ c:collection1
oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 84981 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2>
commit{dir=MockDirectoryWrapper(RAMDirectory@4c3ba
lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@11076c9),segFN=segments_1,generation=1}
[junit4] 2> 84981 T475 N:127.0.0.1:53792_ c:collection1
oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 84982 T475 N:127.0.0.1:53792_ c:collection1
oass.SolrIndexSearcher.<init> Opening Searcher@12fe494[collection1] main
[junit4] 2> 84982 T475 N:127.0.0.1:53792_ c:collection1
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/collection1
[junit4] 2> 84984 T475 N:127.0.0.1:53792_ c:collection1
oascc.ZkStateReader.readConfigName path=/collections/collection1
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 84984 T475 N:127.0.0.1:53792_ c:collection1
oasr.ManagedResourceStorage.newStorageIO Setting up ZooKeeper-based storage for
the RestManager with znodeBase: /configs/conf1
[junit4] 2> 84985 T475 N:127.0.0.1:53792_ c:collection1
oasr.ManagedResourceStorage$ZooKeeperStorageIO.configure Configured
ZooKeeperStorageIO with znodeBase: /configs/conf1
[junit4] 2> 84985 T475 N:127.0.0.1:53792_ c:collection1
oasr.RestManager.init Initializing RestManager with initArgs: {}
[junit4] 2> 84985 T475 N:127.0.0.1:53792_ c:collection1
oasr.ManagedResourceStorage.load Reading _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 84986 T475 N:127.0.0.1:53792_ c:collection1
oasr.ManagedResourceStorage$ZooKeeperStorageIO.openInputStream No data found
for znode /configs/conf1/_rest_managed.json
[junit4] 2> 84986 T475 N:127.0.0.1:53792_ c:collection1
oasr.ManagedResourceStorage.load Loaded null at path _rest_managed.json using
ZooKeeperStorageIO:path=/configs/conf1
[junit4] 2> 84986 T475 N:127.0.0.1:53792_ c:collection1
oasr.RestManager.init Initializing 0 registered ManagedResources
[junit4] 2> 84987 T475 N:127.0.0.1:53792_ c:collection1
oasha.AdminHandlers.inform WARN <requestHandler name="/admin/"
[junit4] 2> class="solr.admin.AdminHandlers" /> is deprecated . It
is not required anymore
[junit4] 2> 84987 T476 N:127.0.0.1:53792_ c:collection1
oasc.SolrCore.registerSearcher [collection1] Registered new searcher
Searcher@12fe494[collection1]
main{ExitableDirectoryReader(UninvertingDirectoryReader())}
[junit4] 2> 84987 T475 N:127.0.0.1:53792_ c:collection1
oasc.ZkController.getConfDirListeners watch zkdir /configs/conf1
[junit4] 2> 84990 T475 N:127.0.0.1:53792_ c:collection1
oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 84991 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oasc.ZkController.register Register replica - core:collection1
address:http://127.0.0.1:53792 collection:collection1 shard:shard2
[junit4] 2> 84991 T412 N:127.0.0.1:53792_ oass.SolrDispatchFilter.init
user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0
[junit4] 2> 84992 T412 N:127.0.0.1:53792_ oass.SolrDispatchFilter.init
SolrDispatchFilter.init() done
[junit4] 2> 84992 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leader_elect/shard2/election
[junit4] 2> 85002 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess Running the
leader process for shard shard2
[junit4] 2> 85004 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough
replicas found to continue.
[junit4] 2> 85004 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new
leader - try and sync
[junit4] 2> ASYNC NEW_CORE C100 name=collection1
org.apache.solr.core.SolrCore@14e4862 url=http://127.0.0.1:53792/collection1
node=127.0.0.1:53792_ C100_STATE=coll:collection1 core:collection1
props:{core=collection1, base_url=http://127.0.0.1:53792,
node_name=127.0.0.1:53792_, state=down}
[junit4] 2> 85004 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 C100 oasc.SyncStrategy.sync Sync replicas to
http://127.0.0.1:53792/collection1/
[junit4] 2> 85006 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 C100 oasc.SyncStrategy.syncReplicas Sync Success - now sync
replicas to me
[junit4] 2> 85006 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 C100 oasc.SyncStrategy.syncToMe
http://127.0.0.1:53792/collection1/ has no replicas
[junit4] 2> 85006 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new
leader: http://127.0.0.1:53792/collection1/ shard2
[junit4] 2> 85006 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oascc.SolrZkClient.makePath makePath:
/collections/collection1/leaders/shard2
[junit4] 2> 85012 T440 N:127.0.0.1:53777_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 85013 T441 N:127.0.0.1:53777_
oasc.Overseer$ClusterStateUpdater.run processMessage: queueSize: 2, message = {
[junit4] 2> "operation":"leader",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1"} current state version: 5
[junit4] 2> 85015 T441 N:127.0.0.1:53777_
oasco.ZkStateWriter.writePendingUpdates going to update_collection
/collections/collection1/state.json version: 1
[junit4] 2> 85015 T474 N:127.0.0.1:53792_ oascc.ZkStateReader$7.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json for collection collection1 has
occurred - updating... (live nodes size: 2)
[junit4] 2> 85018 T474 N:127.0.0.1:53792_
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to
ver 2
[junit4] 2> 85023 T441 N:127.0.0.1:53777_
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:53792";,
[junit4] 2> "core":"collection1",
[junit4] 2> "state":"active"} current state version: 5
[junit4] 2> 85024 T441 N:127.0.0.1:53777_
oasco.ZkStateWriter.writePendingUpdates going to update_collection
/collections/collection1/state.json version: 2
[junit4] 2> 85036 T474 N:127.0.0.1:53792_ oascc.ZkStateReader$7.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json for collection collection1 has
occurred - updating... (live nodes size: 2)
[junit4] 2> 85037 T474 N:127.0.0.1:53792_
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to
ver 3
[junit4] 2> 85041 T440 N:127.0.0.1:53777_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 85054 T412 oas.SolrTestCaseJ4.writeCoreProperties Writing
core.properties file to
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\cores\collection1
[junit4] 2> 85057 T412 oasc.AbstractFullDistribZkTestBase.createJettys
create jetty 2 in directory
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001
[junit4] 2> 85058 T412 oejs.Server.doStart jetty-9.2.10.v20150310
[junit4] 2> 85063 T412 oejsh.ContextHandler.doStart Started
o.e.j.s.ServletContextHandler@fd056e{/,null,AVAILABLE}
[junit4] 2> 85065 T412 oejs.AbstractConnector.doStart Started
ServerConnector@77058c{HTTP/1.1}{127.0.0.1:53803}
[junit4] 2> 85065 T412 oejs.Server.doStart Started @98532ms
[junit4] 2> 85065 T412 oascse.JettySolrRunner$1.lifeCycleStarted Jetty
properties:
{solr.data.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\tempDir-001/jetty2, solrconfig=solrconfig.xml,
hostContext=/, hostPort=53803,
coreRootDirectory=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\cores}
[junit4] 2> 85065 T412 oass.SolrDispatchFilter.init
SolrDispatchFilter.init()sun.misc.Launcher$AppClassLoader@19e0bfd
[junit4] 2> 85067 T412 oasc.SolrResourceLoader.<init> new
SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\'
[junit4] 2> 85075 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oasc.ZkController.register We are
http://127.0.0.1:53792/collection1/ and leader is
http://127.0.0.1:53792/collection1/
[junit4] 2> 85075 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oasc.ZkController.register No LogReplay needed for
core=collection1 baseURL=http://127.0.0.1:53792
[junit4] 2> 85075 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oasc.ZkController.checkRecovery I am the leader, no recovery
necessary
[junit4] 2> 85075 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oasc.ZkController.publish publishing core=collection1
state=active collection=collection1
[junit4] 2> 85075 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oasc.ZkController.publish numShards not found on descriptor -
reading it from system property
[junit4] 2> 85080 T440 N:127.0.0.1:53777_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 85085 T441 N:127.0.0.1:53777_
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:53792";,
[junit4] 2> "node_name":"127.0.0.1:53792_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 85087 T441 N:127.0.0.1:53777_ oasco.ReplicaMutator.updateState
Update state numShards=2 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:53792";,
[junit4] 2> "node_name":"127.0.0.1:53792_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"active",
[junit4] 2> "shard":"shard2",
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 85090 T441 N:127.0.0.1:53777_
oasco.ZkStateWriter.writePendingUpdates going to update_collection
/collections/collection1/state.json version: 3
[junit4] 2> 85091 T474 N:127.0.0.1:53792_ oascc.ZkStateReader$7.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json for collection collection1 has
occurred - updating... (live nodes size: 2)
[junit4] 2> 85092 T479 N:127.0.0.1:53792_ C:collection1 S:shard2
c:collection1 oascc.ZkStateReader.updateWatchedCollection Updating data for
collection1 to ver 4
[junit4] 2> 85092 T474 N:127.0.0.1:53792_
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to
ver 4
[junit4] 2> 85105 T412 oasc.SolrXmlConfig.fromFile Loading container
configuration from
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\solr.xml
[junit4] 2> 85121 T412 oasc.CorePropertiesLocator.<init> Config-defined
core root directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\cores
[junit4] 2> 85121 T412 oasc.CoreContainer.<init> New CoreContainer 24480568
[junit4] 2> 85122 T412 oasc.CoreContainer.load Loading cores into
CoreContainer
[instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\]
[junit4] 2> 85122 T412 oasc.CoreContainer.load loading shared library:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\lib
[junit4] 2> 85123 T412 oasc.SolrResourceLoader.addToClassLoader WARN Can't
find (or read) directory to add to classloader: lib (resolved as:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\lib).
[junit4] 2> 85132 T412 oashc.HttpShardHandlerFactory.init created with
socketTimeout : 90000,urlScheme : ,connTimeout : 15000,maxConnectionsPerHost :
20,maxConnections : 10000,corePoolSize : 0,maximumPoolSize :
2147483647,maxThreadIdleTime : 5,sizeOfQueue : -1,fairnessPolicy :
false,useRetries : false,
[junit4] 2> 85134 T412 oasu.UpdateShardHandler.<init> Creating
UpdateShardHandler HTTP client with params:
socketTimeout=340000&connTimeout=45000&retry=true
[junit4] 2> 85134 T412 oasl.LogWatcher.createWatcher SLF4J impl is
org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 85135 T412 oasl.LogWatcher.newRegisteredLogWatcher Registering
Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 85135 T412 oasc.CoreContainer.load Node Name: 127.0.0.1
[junit4] 2> 85135 T412 oasc.ZkContainer.initZooKeeper Zookeeper
client=127.0.0.1:53770/solr
[junit4] 2> 85135 T412 oasc.ZkController.checkChrootPath zkHost includes
chroot
[junit4] 2> 85135 T412
oascc.SolrZkClient.createZkCredentialsToAddAutomatically Using default
ZkCredentialsProvider
[junit4] 2> 85138 T412 oascc.ConnectionManager.waitForConnected Waiting
for client to connect to ZooKeeper
[junit4] 2> 85141 T493 oascc.ConnectionManager.process Watcher
org.apache.solr.common.cloud.ConnectionManager@2f4a4c name:ZooKeeperConnection
Watcher:127.0.0.1:53770 got event WatchedEvent state:SyncConnected type:None
path:null path:null type:None
[junit4] 2> 85143 T412 oascc.ConnectionManager.waitForConnected Client is
connected to ZooKeeper
[junit4] 2> 85143 T412 oascc.SolrZkClient.createZkACLProvider Using
default ZkACLProvider
[junit4] 2> 85150 T412 N:127.0.0.1:53803_
oascc.ConnectionManager.waitForConnected Waiting for client to connect to
ZooKeeper
[junit4] 2> 85152 T496 N:127.0.0.1:53803_ oascc.ConnectionManager.process
Watcher org.apache.solr.common.cloud.ConnectionManager@1634e51
name:ZooKeeperConnection Watcher:127.0.0.1:53770/solr got event WatchedEvent
state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 85154 T412 N:127.0.0.1:53803_
oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 85162 T412 N:127.0.0.1:53803_
oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state
from ZooKeeper...
[junit4] 2> 86425 T412 N:127.0.0.1:53803_
oasc.ZkController.createEphemeralLiveNode Register node as live in
ZooKeeper:/live_nodes/127.0.0.1:53803_
[junit4] 2> 86426 T412 N:127.0.0.1:53803_ oascc.SolrZkClient.makePath
makePath: /live_nodes/127.0.0.1:53803_
[junit4] 2> 86432 T412 N:127.0.0.1:53803_ oasc.Overseer.close Overseer
(id=null) closing
[junit4] 2> 86436 T412 N:127.0.0.1:53803_
oasc.CorePropertiesLocator.discover Looking for core definitions underneath
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\cores
[junit4] 2> 86439 T412 N:127.0.0.1:53803_ oasc.CoreDescriptor.<init> CORE
DESCRIPTOR: {name=collection1, config=solrconfig.xml, transient=false,
schema=schema.xml, loadOnStartup=true,
instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\cores\collection1, collection=collection1,
absoluteInstDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\cores\collection1\, coreNodeName=,
dataDir=data\, shard=}
[junit4] 2> 86439 T412 N:127.0.0.1:53803_
oasc.CorePropertiesLocator.discoverUnder Found core collection1 in
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\cores\collection1\
[junit4] 2> 86440 T412 N:127.0.0.1:53803_
oasc.CorePropertiesLocator.discover Found 1 core definitions
[junit4] 2> 86445 T497 N:127.0.0.1:53803_ C:collection1 c:collection1
oasc.ZkController.publish publishing core=collection1 state=down
collection=collection1
[junit4] 2> 86445 T497 N:127.0.0.1:53803_ C:collection1 c:collection1
oasc.ZkController.publish numShards not found on descriptor - reading it from
system property
[junit4] 2> 86448 T440 N:127.0.0.1:53777_
oasc.DistributedQueue$LatchWatcher.process NodeChildrenChanged fired on path
/overseer/queue state SyncConnected
[junit4] 2> 86449 T497 N:127.0.0.1:53803_ oasc.ZkController.preRegister
Registering watch for external collection collection1
[junit4] 2> 86449 T497 N:127.0.0.1:53803_ oascc.ZkStateReader.addZkWatch
addZkWatch collection1
[junit4] 2> 86449 T441 N:127.0.0.1:53777_
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:53803";,
[junit4] 2> "node_name":"127.0.0.1:53803_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"} current state version: 5
[junit4] 2> 86450 T497 N:127.0.0.1:53803_ oascc.ZkStateReader.addZkWatch
Updating collection state at /collections/collection1/state.json from
ZooKeeper...
[junit4] 2> 86450 T441 N:127.0.0.1:53777_ oasco.ReplicaMutator.updateState
Update state numShards=2 message={
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "base_url":"http://127.0.0.1:53803";,
[junit4] 2> "node_name":"127.0.0.1:53803_",
[junit4] 2> "numShards":"2",
[junit4] 2> "state":"down",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "operation":"state"}
[junit4] 2> 86450 T441 N:127.0.0.1:53777_ oasco.ReplicaMutator.updateState
Collection already exists with numShards=2
[junit4] 2> 86450 T441 N:127.0.0.1:53777_ oasco.ReplicaMutator.updateState
Assigning new node to shard shard=shard1
[junit4] 2> 86451 T497 N:127.0.0.1:53803_
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to
ver 4
[junit4] 2> 86451 T497 N:127.0.0.1:53803_
oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 86453 T441 N:127.0.0.1:53777_
oasco.ZkStateWriter.writePendingUpdates going to update_collection
/collections/collection1/state.json version: 4
[junit4] 2> 86454 T496 N:127.0.0.1:53803_ oascc.ZkStateReader$7.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json for collection collection1 has
occurred - updating... (live nodes size: 3)
[junit4] 2> 86454 T474 N:127.0.0.1:53792_ oascc.ZkStateReader$7.process A
cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged
path:/collections/collection1/state.json for collection collection1 has
occurred - updating... (live nodes size: 3)
[junit4] 2> 86455 T496 N:127.0.0.1:53803_
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to
ver 5
[junit4] 2> 86455 T474 N:127.0.0.1:53792_
oascc.ZkStateReader.updateWatchedCollection Updating data for collection1 to
ver 5
[junit4] 2> 87705 T497 N:127.0.0.1:53803_ oasc.ZkController.waitForShardId
waiting to find shard id in clusterstate for collection1
[junit4] 2> 87705 T497 N:127.0.0.1:53803_
oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 87707 T497 N:127.0.0.1:53803_
oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 87707 T497 N:127.0.0.1:53803_
oascc.ZkStateReader.readConfigName Load collection config
from:/collections/collection1
[junit4] 2> 87708 T497 N:127.0.0.1:53803_
oascc.ZkStateReader.readConfigName path=/collections/collection1
configName=conf1 specified config exists in ZooKeeper
[junit4] 2> 87708 T497 N:127.0.0.1:53803_ oasc.SolrResourceLoader.<init>
new SolrResourceLoader for directory:
'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\cores\collection1\'
[junit4] 2> 87726 T497 N:127.0.0.1:53803_ oasc.Config.<init> loaded config
solrconfig.xml with version 0
[junit4] 2> 87733 T497 N:127.0.0.1:53803_
oasc.SolrConfig.refreshRequestParams current version of requestparams : -1
[junit4] 2> 87736 T497 N:127.0.0.1:53803_ oasc.SolrConfig.<init> Using
Lucene MatchVersion: 6.0.0
[junit4] 2> 87749 T497 N:127.0.0.1:53803_ oasc.SolrConfig.<init> Loaded
SolrConfig: solrconfig.xml
[junit4] 2> 87752 T497 N:127.0.0.1:53803_ oass.IndexSchema.readSchema
Reading Solr Schema from /configs/conf1/schema.xml
[junit4] 2> 87757 T497 N:127.0.0.1:53803_ oass.IndexSchema.readSchema
[collection1] Schema name=test
[junit4] 2> 87961 T497 N:127.0.0.1:53803_ oass.IndexSchema.readSchema
default search field in schema is text
[junit4] 2> 87962 T497 N:127.0.0.1:53803_ oass.IndexSchema.readSchema
unique key field: id
[junit4] 2> 87963 T497 N:127.0.0.1:53803_ oass.IndexSchema.loadCopyFields
WARN Field lowerfilt1and2 is not multivalued and destination for multiple
copyFields (2)
[junit4] 2> 87963 T497 N:127.0.0.1:53803_ oass.IndexSchema.loadCopyFields
WARN Field text is not multivalued and destination for multiple copyFields (3)
[junit4] 2> 87965 T497 N:127.0.0.1:53803_ oasc.CoreContainer.create
Creating SolrCore 'collection1' using configuration from collection collection1
[junit4] 2> 87965 T497 N:127.0.0.1:53803_ c:collection1
oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 87965 T497 N:127.0.0.1:53803_ c:collection1
oasc.SolrCore.<init> [[collection1] ] Opening new SolrCore at
[C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-2-001\cores\collection1\], dataDir=[null]
[junit4] 2> 87965 T497 N:127.0.0.1:53803_ c:collection1
oasc.SolrCore.initInfoRegistry JMX monitoring not detected for core: collection1
[junit4] 2> 87966 T497 N:127.0.0.1:53803_ c:collection1
oasc.CachingDirectoryFactory.get return new directory for
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\
[...truncated too long message...]
loudSolrClientTest B991998ABE83A57F-001\shard-3-003\cores\collection1\data\
[junit4] 2> 168051 T931 N:127.0.0.1:54261_
oasc.CachingDirectoryFactory.closeCacheValue looking to close
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-3-003\cores\collection1\data\index
[CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-3-003\cores\collection1\data\index;done=false>>]
[junit4] 2> 168051 T931 N:127.0.0.1:54261_
oasc.CachingDirectoryFactory.close Closing directory:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001\shard-3-003\cores\collection1\data\index
[junit4] 2> 168052 T931 N:127.0.0.1:54261_
oasc.LeaderElector$ElectionWatcher.process WARN
org.apache.zookeeper.KeeperException$SessionExpiredException: KeeperErrorCode =
Session expired for /overseer/queue/qn-
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:127)
[junit4] 2> at
org.apache.zookeeper.KeeperException.create(KeeperException.java:51)
[junit4] 2> at
org.apache.zookeeper.ZooKeeper.create(ZooKeeper.java:783)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:380)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$9.execute(SolrZkClient.java:377)
[junit4] 2> at
org.apache.solr.common.cloud.ZkCmdExecutor.retryOperation(ZkCmdExecutor.java:61)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient.create(SolrZkClient.java:377)
[junit4] 2> at
org.apache.solr.cloud.DistributedQueue.createData(DistributedQueue.java:380)
[junit4] 2> at
org.apache.solr.cloud.DistributedQueue.offer(DistributedQueue.java:364)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:230)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:198)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:159)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:348)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.joinElection(LeaderElector.java:256)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContext.rejoinLeaderElection(ElectionContext.java:492)
[junit4] 2> at
org.apache.solr.cloud.ShardLeaderElectionContext.runLeaderProcess(ElectionContext.java:248)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.runIamLeaderProcess(LeaderElector.java:198)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.checkIfIamLeader(LeaderElector.java:159)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector.access$200(LeaderElector.java:56)
[junit4] 2> at
org.apache.solr.cloud.LeaderElector$ElectionWatcher.process(LeaderElector.java:390)
[junit4] 2> at
org.apache.solr.common.cloud.SolrZkClient$3$1.run(SolrZkClient.java:264)
[junit4] 2> at
java.util.concurrent.Executors$RunnableAdapter.call(Executors.java:511)
[junit4] 2> at
java.util.concurrent.FutureTask.run(FutureTask.java:266)
[junit4] 2> at
org.apache.solr.common.util.ExecutorUtil$MDCAwareThreadPoolExecutor$1.run(ExecutorUtil.java:148)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1142)
[junit4] 2> at
java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:617)
[junit4] 2> at java.lang.Thread.run(Thread.java:745)
[junit4] 2>
[junit4] 2> 168052 T412 oejs.AbstractConnector.doStop Stopped
ServerConnector@17f4fc0{HTTP/1.1}{127.0.0.1:0}
[junit4] 2> 168053 T412 oejsh.ContextHandler.doStop Stopped
o.e.j.s.ServletContextHandler@18d4fde{/,null,UNAVAILABLE}
[junit4] 2> 168055 T412 C:control_collection S:shard1 c:collection1
oasc.ChaosMonkey.monkeyLog monkey: stop shard! 54228
[junit4] 2> 168056 T412 C:control_collection S:shard1 c:collection1
oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:54221 54221
[junit4] 2> 169870 T814 C:control_collection S:shard1 c:collection1
oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:54221 54221
[junit4] 2> 169871 T814 C:control_collection S:shard1 c:collection1
oasc.ZkTestServer$ZKServerMain.runFromConfig WARN Watch limit violations:
[junit4] 2> Maximum concurrent create/delete watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/aliases.json
[junit4] 2> 5 /solr/clusterstate.json
[junit4] 2> 4 /solr/configs/conf1
[junit4] 2> 3 /solr/collections/collection1/state.json
[junit4] 2>
[junit4] 2> Maximum concurrent children watches above limit:
[junit4] 2>
[junit4] 2> 5 /solr/live_nodes
[junit4] 2> 3 /solr/overseer/collection-queue-work
[junit4] 2>
[junit4] 2> 169872 T412 C:control_collection S:shard1 c:collection1
oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: leaving temporary files on disk at:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-solrj\test\J0\temp\solr.client.solrj.impl.CloudSolrClientTest
B991998ABE83A57F-001
[junit4] 2> NOTE: test params are: codec=Asserting(Lucene50),
sim=RandomSimilarityProvider(queryNorm=true,coord=crazy): {}, locale=el_GR,
timezone=SystemV/AST4ADT
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0_40
(32-bit)/cpus=3,threads=1,free=24160432,total=79192064
[junit4] 2> NOTE: All tests run in this JVM: [TestXMLEscaping,
FieldAnalysisResponseTest, TestCloudSolrClientConnections, TermsResponseTest,
GetByIdTest, SolrDocumentTest, TestEmbeddedSolrServer, TestJsonRecordReader,
TestJavaBinCodec, TestHash, JettyWebappTest, TestSolrProperties, NamedListTest,
DocumentAnalysisResponseTest, ConcurrentUpdateSolrClientTest,
SolrExampleStreamingTest, SolrExampleBinaryTest, SolrExampleXMLTest,
SolrExampleEmbeddedTest, LargeVolumeBinaryJettyTest, TestBatchUpdate,
TestSpellCheckResponse, MergeIndexesEmbeddedTest, QueryResponseTest,
TestCoreAdmin, TestDocumentObjectBinder, SolrQueryTest, ContentStreamTest,
ModifiableSolrParamsTest, TestFastInputStream, SolrExceptionTest,
TestUpdateRequestCodec, AnlysisResponseBaseTest, FacetFieldTest,
BasicHttpSolrClientTest, CloudSolrClientTest]
[junit4] Completed [57/57] on J0 in 90.05s, 3 tests, 1 failure <<< FAILURES!
[...truncated 10 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:526: The
following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:474: 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:242:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:512:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1415:
The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:973:
There were test failures: 57 suites, 381 tests, 1 failure
Total time: 59 minutes 15 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
ERROR: Failed to archive artifacts: **/*.events,heapdumps/**,**/hs_err_pid*
java.io.IOException: Failed to extract
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows/transfer of 2 files
at hudson.FilePath.readFromTar(FilePath.java:2299)
at hudson.FilePath.copyRecursiveTo(FilePath.java:2208)
at
jenkins.model.StandardArtifactManager.archive(StandardArtifactManager.java:61)
at hudson.tasks.ArtifactArchiver.perform(ArtifactArchiver.java:219)
at
hudson.tasks.BuildStepCompatibilityLayer.perform(BuildStepCompatibilityLayer.java:74)
at hudson.tasks.BuildStepMonitor$1.perform(BuildStepMonitor.java:20)
at
hudson.model.AbstractBuild$AbstractBuildExecution.perform(AbstractBuild.java:761)
at
hudson.model.AbstractBuild$AbstractBuildExecution.performAllBuildSteps(AbstractBuild.java:721)
at hudson.model.Build$BuildExecution.post2(Build.java:183)
at
hudson.model.AbstractBuild$AbstractBuildExecution.post(AbstractBuild.java:670)
at hudson.model.Run.execute(Run.java:1766)
at hudson.model.FreeStyleBuild.run(FreeStyleBuild.java:43)
at hudson.model.ResourceController.execute(ResourceController.java:98)
at hudson.model.Executor.run(Executor.java:374)
Caused by: java.io.IOException: Truncated TAR archive
at
org.apache.commons.compress.archivers.tar.TarArchiveInputStream.read(TarArchiveInputStream.java:614)
at java.io.InputStream.read(InputStream.java:101)
at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1792)
at org.apache.commons.io.IOUtils.copyLarge(IOUtils.java:1769)
at org.apache.commons.io.IOUtils.copy(IOUtils.java:1744)
at hudson.util.IOUtils.copy(IOUtils.java:40)
at hudson.FilePath.readFromTar(FilePath.java:2289)
... 13 more
Recording test results
Email was triggered for: Failure - Any
Sending email for trigger: Failure - Any
---------------------------------------------------------------------
To unsubscribe, e-mail: [email protected]
For additional commands, e-mail: [email protected]
