lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-trunk-Linux (64bit/jdk1.8.0-ea-b83) - Build # 5000 - Failure!
Date Wed, 03 Apr 2013 21:57:14 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Linux/5000/
Java: 64bit/jdk1.8.0-ea-b83 -XX:+UseG1GC

1 tests failed.
REGRESSION:  org.apache.solr.cloud.BasicDistributedZk2Test.testDistribSearch

Error Message:
Server at http://127.0.0.1:51874/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update

Stack Trace:
org.apache.solr.common.SolrException: Server at http://127.0.0.1:51874/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
	at __randomizedtesting.SeedInfo.seed([990F4E9A95D9A410:18E9C082E286C42C]:0)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:375)
	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
	at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:197)
	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:89)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
	at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
	at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
	at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
	at java.lang.reflect.Method.invoke(Method.java:487)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
	at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	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:358)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
	at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
	at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
	at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
	at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
	at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
	at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
	at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
	at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
	at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
	at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
	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:358)
	at java.lang.Thread.run(Thread.java:722)




Build Log:
[...truncated 9295 lines...]
[junit4:junit4] Suite: org.apache.solr.cloud.BasicDistributedZk2Test
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.370; org.apache.solr.BaseDistributedSearchTestCase; Setting hostContext system property: /
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.375; org.apache.solr.SolrTestCaseJ4; ###Starting testDistribSearch
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.376; org.apache.solr.cloud.ZkTestServer; STARTING ZK TEST SERVER
[junit4:junit4]   1> client port:0.0.0.0/0.0.0.0:0
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.376; org.apache.solr.cloud.ZkTestServer$ZKServerMain; Starting server
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.377; org.apache.zookeeper.server.ZooKeeperServer; tickTime set to 1000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.377; org.apache.zookeeper.server.ZooKeeperServer; minSessionTimeout set to -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.377; org.apache.zookeeper.server.ZooKeeperServer; maxSessionTimeout set to -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.377; org.apache.zookeeper.server.NIOServerCnxnFactory; binding to port 0.0.0.0/0.0.0.0:0
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.378; org.apache.zookeeper.server.persistence.FileTxnSnapLog; Snapshotting: 0x0 to /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/zookeeper/server1/data/version-2/snapshot.0
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.476; org.apache.solr.cloud.ZkTestServer; start zk server on port:58873
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.477; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873 sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@547cc0ce
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.478; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.478; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.478; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.479; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49851
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.479; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49851
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.479; org.apache.zookeeper.server.persistence.FileTxnLog; Creating new log file: log.1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.607; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb0000 with negotiated timeout 10000 for client /127.0.0.1:49851
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.607; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb0000, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.608; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@547cc0ce name:ZooKeeperConnection Watcher:127.0.0.1:58873 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.608; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.610; org.apache.solr.common.cloud.SolrZkClient; makePath: /solr
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.613; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dd1e1e1cb0000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.614; org.apache.zookeeper.ZooKeeper; Session: 0x13dd1e1e1cb0000 closed
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.614; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:49851 which had sessionid 0x13dd1e1e1cb0000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.614; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.614; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@5abb2431
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.616; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.616; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.616; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.616; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49852
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.617; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49852
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.617; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb0001 with negotiated timeout 10000 for client /127.0.0.1:49852
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.618; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb0001, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.618; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@5abb2431 name:ZooKeeperConnection Watcher:127.0.0.1:58873/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.618; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.619; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.622; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/shards
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.624; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/control_collection
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.627; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/control_collection/shards
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.629; org.apache.solr.cloud.AbstractZkTestCase; put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.630; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.634; org.apache.solr.cloud.AbstractZkTestCase; put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/schema.xml to /configs/conf1/schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.634; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.638; org.apache.solr.cloud.AbstractZkTestCase; put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/stopwords.txt to /configs/conf1/stopwords.txt
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.638; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/stopwords.txt
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.641; org.apache.solr.cloud.AbstractZkTestCase; put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/protwords.txt to /configs/conf1/protwords.txt
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.641; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/protwords.txt
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.643; org.apache.solr.cloud.AbstractZkTestCase; put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/currency.xml to /configs/conf1/currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.644; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.646; org.apache.solr.cloud.AbstractZkTestCase; put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.646; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/open-exchange-rates.json
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.648; org.apache.solr.cloud.AbstractZkTestCase; put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.649; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.651; org.apache.solr.cloud.AbstractZkTestCase; put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.652; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/old_synonyms.txt
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.654; org.apache.solr.cloud.AbstractZkTestCase; put /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test-files/solr/collection1/conf/synonyms.txt to /configs/conf1/synonyms.txt
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.654; org.apache.solr.common.cloud.SolrZkClient; makePath: /configs/conf1/synonyms.txt
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.656; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dd1e1e1cb0001
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.657; org.apache.zookeeper.ZooKeeper; Session: 0x13dd1e1e1cb0001 closed
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.657; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:49852 which had sessionid 0x13dd1e1e1cb0001
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.657; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.723; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.726; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:51314
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.727; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.727; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.728; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1365025874657
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.728; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1365025874657/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.728; org.apache.solr.core.CoreContainer; New CoreContainer 620888051
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.729; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1365025874657/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.729; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1365025874657/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.750; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.751; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.751; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 15000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.751; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.752; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.752; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.753; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.753; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.753; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.754; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.760; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.770; org.apache.solr.core.CoreContainer; Zookeeper client=127.0.0.1:58873/solr
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.771; org.apache.solr.cloud.ZkController; zkHost includes chroot
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.771; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@a758edd
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.772; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.772; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.773; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.773; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49855
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.774; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49855
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.775; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb0002 with negotiated timeout 20000 for client /127.0.0.1:49855
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.775; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb0002, negotiated timeout = 20000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.775; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@a758edd name:ZooKeeperConnection Watcher:127.0.0.1:58873 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.775; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.777; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dd1e1e1cb0002
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.777; org.apache.zookeeper.ZooKeeper; Session: 0x13dd1e1e1cb0002 closed
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.777; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:49855 which had sessionid 0x13dd1e1e1cb0002
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.777; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.778; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.781; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@4367320
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.782; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.782; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.783; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.783; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49856
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.783; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49856
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.784; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb0003 with negotiated timeout 20000 for client /127.0.0.1:49856
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.784; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb0003, negotiated timeout = 20000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.785; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@4367320 name:ZooKeeperConnection Watcher:127.0.0.1:58873/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.785; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.787; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/queue
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.789; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0003 type:create cxid:0x7 zxid:0x1b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.790; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/collection-queue-work
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.792; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.794; org.apache.solr.cloud.ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51314_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.795; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0003 type:delete cxid:0x12 zxid:0x1e txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51314_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51314_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.795; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:51314_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.799; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer_elect/election
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.802; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0003 type:delete cxid:0x20 zxid:0x23 txntype:-1 reqpath:n/a Error Path:/solr/overseer_elect/leader Error:KeeperErrorCode = NoNode for /solr/overseer_elect/leader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.803; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer_elect/leader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.804; org.apache.solr.cloud.Overseer; Overseer (id=89458335703302147-127.0.0.1:51314_-n_0000000000) starting
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.805; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0003 type:create cxid:0x25 zxid:0x25 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.806; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0003 type:create cxid:0x26 zxid:0x26 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.807; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0003 type:create cxid:0x28 zxid:0x27 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.807; org.apache.solr.common.cloud.SolrZkClient; makePath: /overseer/queue-work
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.809; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0003 type:create cxid:0x2e zxid:0x29 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.810; org.apache.solr.cloud.OverseerCollectionProcessor; Process current queue of collection creations
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.811; org.apache.solr.common.cloud.SolrZkClient; makePath: /clusterstate.json
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.813; org.apache.solr.common.cloud.SolrZkClient; makePath: /aliases.json
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.814; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.817; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Starting to work on the main queue
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.819; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1365025874657/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.820; org.apache.solr.cloud.ZkController; Check for collection zkNode:control_collection
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.820; org.apache.solr.cloud.ZkController; Collection zkNode exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.820; org.apache.solr.cloud.ZkController; Load collection config from:/collections/control_collection
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.821; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1365025874657/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.822; org.apache.solr.core.SolrResourceLoader; Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1365025874657/collection1/lib/README' to classloader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.822; org.apache.solr.core.SolrResourceLoader; Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1365025874657/collection1/lib/classes/' to classloader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.849; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.883; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.884; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:14.888; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.228; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.234; org.apache.solr.schema.IndexSchema; default search field in schema is text
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.236; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.246; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.250; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.252; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:15.254; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:15.254; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.254; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:15.255; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:15.255; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.255; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.255; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-controljetty-1365025874657/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/control/data/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.256; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@28b3ea47
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.256; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.257; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/control/data
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.257; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/control/data/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:15.257; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/control/data/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.257; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/control/data/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.259; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@176f4f44 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1fbe4a2b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.259; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.261; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.261; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.261; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.262; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.262; org.apache.solr.core.RequestHandlers; created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.262; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.263; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.263; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.263; org.apache.solr.core.RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.265; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.268; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@7a890989 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.268; org.apache.solr.update.UpdateHandler; Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/control/data/tlog
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.268; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.269; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.272; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@7a890989 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.272; org.apache.solr.cloud.ZkController; publishing core=collection1 state=down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:15.272; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:16.322; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:16.323; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=1 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:51314_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"down",
[junit4:junit4]   1>   "numShards":"1",
[junit4:junit4]   1>   "core_node_name":"1",
[junit4:junit4]   1>   "shard":null,
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:51314",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"control_collection"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:16.323; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Create collection control_collection with numShards 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:16.323; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:16.327; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.274; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.274; org.apache.solr.cloud.ZkController; Register replica - core:collection1 address:http://127.0.0.1:51314 collection:control_collection shard:shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.275; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/control_collection/leader_elect/shard1/election
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.281; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0003 type:delete cxid:0x84 zxid:0x35 txntype:-1 reqpath:n/a Error Path:/solr/collections/control_collection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/control_collection/leaders
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.282; org.apache.solr.cloud.ShardLeaderElectionContext; Running the leader process.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.282; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0003 type:create cxid:0x85 zxid:0x36 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.284; org.apache.solr.cloud.ShardLeaderElectionContext; Enough replicas found to continue.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.285; org.apache.solr.cloud.ShardLeaderElectionContext; I may be the new leader - try and sync
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.285; org.apache.solr.cloud.SyncStrategy; Sync replicas to http://127.0.0.1:51314/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.285; org.apache.solr.cloud.SyncStrategy; Sync Success - now sync replicas to me
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.285; org.apache.solr.cloud.SyncStrategy; http://127.0.0.1:51314/collection1/ has no replicas
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.286; org.apache.solr.cloud.ShardLeaderElectionContext; I am the new leader: http://127.0.0.1:51314/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.286; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/control_collection/leaders/shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.289; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0003 type:create cxid:0x90 zxid:0x3a txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.830; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.836; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.847; org.apache.solr.cloud.ZkController; We are http://127.0.0.1:51314/collection1/ and leader is http://127.0.0.1:51314/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.848; org.apache.solr.cloud.ZkController; No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51314
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.848; org.apache.solr.cloud.ZkController; I am the leader, no recovery necessary
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.848; org.apache.solr.cloud.ZkController; publishing core=collection1 state=active
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.848; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.850; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.851; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.851; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.852; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.856; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.857; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873/solr sessionTimeout=10000 watcher=org.apache.solr.common.cloud.ConnectionManager@2357ff77
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.858; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.858; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.859; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.859; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49859
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.859; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49859
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.860; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb0004 with negotiated timeout 10000 for client /127.0.0.1:49859
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.860; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb0004, negotiated timeout = 10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.860; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@2357ff77 name:ZooKeeperConnection Watcher:127.0.0.1:58873/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.861; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.862; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.865; org.apache.solr.cloud.ChaosMonkey; monkey: init - expire sessions:true cause connection loss:true
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.936; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.949; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:46752
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.950; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.950; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.950; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.951; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.951; org.apache.solr.core.CoreContainer; New CoreContainer 1782154368
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.952; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.952; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.972; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.973; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.973; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 15000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.973; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.974; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.974; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.975; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.975; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.975; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.976; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.981; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.991; org.apache.solr.core.CoreContainer; Zookeeper client=127.0.0.1:58873/solr
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.992; org.apache.solr.cloud.ZkController; zkHost includes chroot
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.992; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@7218074
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.993; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.993; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.994; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.994; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49860
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.995; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49860
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.995; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb0005 with negotiated timeout 20000 for client /127.0.0.1:49860
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.995; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb0005, negotiated timeout = 20000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.996; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@7218074 name:ZooKeeperConnection Watcher:127.0.0.1:58873 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.996; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.997; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dd1e1e1cb0005
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.998; org.apache.zookeeper.ZooKeeper; Session: 0x13dd1e1e1cb0005 closed
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.998; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:49860 which had sessionid 0x13dd1e1e1cb0005
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.998; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:17.998; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.001; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@76862294
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.002; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.002; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.003; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.003; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49861
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.003; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49861
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.004; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb0006 with negotiated timeout 20000 for client /127.0.0.1:49861
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.004; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb0006, negotiated timeout = 20000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.004; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@76862294 name:ZooKeeperConnection Watcher:127.0.0.1:58873/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.004; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.006; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0006 type:create cxid:0x1 zxid:0x48 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.007; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0006 type:create cxid:0x3 zxid:0x49 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:18.009; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.011; org.apache.solr.cloud.ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:46752_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.012; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0006 type:delete cxid:0xe zxid:0x4a txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:46752_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:46752_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.013; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:46752_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.015; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.016; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.015; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.016; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.020; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.020; org.apache.solr.cloud.ZkController; Check for collection zkNode:collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.021; org.apache.solr.cloud.ZkController; Collection zkNode exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.021; org.apache.solr.cloud.ZkController; Load collection config from:/collections/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.022; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.023; org.apache.solr.core.SolrResourceLoader; Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/collection1/lib/README' to classloader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.023; org.apache.solr.core.SolrResourceLoader; Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/collection1/lib/classes/' to classloader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.045; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.073; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.074; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.078; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.340; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.341; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=1 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:51314_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "numShards":"1",
[junit4:junit4]   1>   "core_node_name":"1",
[junit4:junit4]   1>   "shard":"shard1",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:51314",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"control_collection"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.344; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.344; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.344; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.386; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.392; org.apache.solr.schema.IndexSchema; default search field in schema is text
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.394; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.404; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.407; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.410; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:19.410; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:19.411; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.411; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:19.412; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:19.412; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.412; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.413; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.413; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@28b3ea47
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.414; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.414; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.414; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty1/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:19.414; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty1/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.415; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty1/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.416; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6cabe12e lockFactory=org.apache.lucene.store.NativeFSLockFactory@78a8589),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.417; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.418; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.419; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.419; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.420; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.420; org.apache.solr.core.RequestHandlers; created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.420; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.421; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.421; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.422; org.apache.solr.core.RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.424; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.426; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@ada7620 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.427; org.apache.solr.update.UpdateHandler; Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty1/tlog
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.427; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.427; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.431; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@ada7620 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.432; org.apache.solr.cloud.ZkController; publishing core=collection1 state=down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:19.432; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:20.849; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:20.849; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=2 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:46752_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"down",
[junit4:junit4]   1>   "numShards":"2",
[junit4:junit4]   1>   "core_node_name":"2",
[junit4:junit4]   1>   "shard":null,
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:46752",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"collection1"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:20.850; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Create collection collection1 with numShards 2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:20.850; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:20.853; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:20.853; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:20.853; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.433; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.434; org.apache.solr.cloud.ZkController; Register replica - core:collection1 address:http://127.0.0.1:46752 collection:collection1 shard:shard2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.435; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leader_elect/shard2/election
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.440; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0006 type:delete cxid:0x4c zxid:0x5a txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.441; org.apache.solr.cloud.ShardLeaderElectionContext; Running the leader process.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.441; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0006 type:create cxid:0x4d zxid:0x5b txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.443; org.apache.solr.cloud.ShardLeaderElectionContext; Enough replicas found to continue.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.443; org.apache.solr.cloud.ShardLeaderElectionContext; I may be the new leader - try and sync
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.443; org.apache.solr.cloud.SyncStrategy; Sync replicas to http://127.0.0.1:46752/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.443; org.apache.solr.cloud.SyncStrategy; Sync Success - now sync replicas to me
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.444; org.apache.solr.cloud.SyncStrategy; http://127.0.0.1:46752/collection1/ has no replicas
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.444; org.apache.solr.cloud.ShardLeaderElectionContext; I am the new leader: http://127.0.0.1:46752/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.444; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leaders/shard2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:21.447; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0006 type:create cxid:0x58 zxid:0x5f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.357; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.364; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.364; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.364; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.401; org.apache.solr.cloud.ZkController; We are http://127.0.0.1:46752/collection1/ and leader is http://127.0.0.1:46752/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.401; org.apache.solr.cloud.ZkController; No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:46752
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.401; org.apache.solr.cloud.ZkController; I am the leader, no recovery necessary
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.402; org.apache.solr.cloud.ZkController; publishing core=collection1 state=active
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.402; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.403; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.405; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.405; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.406; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.481; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.488; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:51874
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.488; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.489; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.489; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1365025882410
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.490; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1365025882410/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.490; org.apache.solr.core.CoreContainer; New CoreContainer 1010507651
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.490; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1365025882410/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.491; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1365025882410/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.510; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.510; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.511; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 15000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.511; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.511; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.512; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.512; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.512; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.513; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.513; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.519; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.528; org.apache.solr.core.CoreContainer; Zookeeper client=127.0.0.1:58873/solr
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.529; org.apache.solr.cloud.ZkController; zkHost includes chroot
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.529; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@29d53ae1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.530; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.530; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.531; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.531; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49872
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.532; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49872
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.533; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb0007 with negotiated timeout 20000 for client /127.0.0.1:49872
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.533; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb0007, negotiated timeout = 20000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.533; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@29d53ae1 name:ZooKeeperConnection Watcher:127.0.0.1:58873 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.533; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.535; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dd1e1e1cb0007
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.535; org.apache.zookeeper.ZooKeeper; Session: 0x13dd1e1e1cb0007 closed
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.535; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:49872 which had sessionid 0x13dd1e1e1cb0007
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.535; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.536; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.539; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@49c2dc77
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.540; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.540; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.540; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.541; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49873
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.541; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49873
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.542; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb0008 with negotiated timeout 20000 for client /127.0.0.1:49873
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.542; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb0008, negotiated timeout = 20000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.542; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@49c2dc77 name:ZooKeeperConnection Watcher:127.0.0.1:58873/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.543; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.544; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0008 type:create cxid:0x1 zxid:0x6c txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.545; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0008 type:create cxid:0x3 zxid:0x6d txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:22.547; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.550; org.apache.solr.cloud.ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:51874_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.551; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0008 type:delete cxid:0xe zxid:0x6e txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:51874_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:51874_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.552; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:51874_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.554; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.554; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 2)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.555; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.555; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.555; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.556; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.559; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.562; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1365025882410/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.562; org.apache.solr.cloud.ZkController; Check for collection zkNode:collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.563; org.apache.solr.cloud.ZkController; Collection zkNode exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.563; org.apache.solr.cloud.ZkController; Load collection config from:/collections/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.565; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1365025882410/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.565; org.apache.solr.core.SolrResourceLoader; Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1365025882410/collection1/lib/README' to classloader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.565; org.apache.solr.core.SolrResourceLoader; Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1365025882410/collection1/lib/classes/' to classloader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.588; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.616; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.617; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.621; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.869; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.870; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=2 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:46752_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "numShards":"2",
[junit4:junit4]   1>   "core_node_name":"2",
[junit4:junit4]   1>   "shard":"shard2",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:46752",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"collection1"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.873; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.873; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.873; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.873; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.924; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.929; org.apache.solr.schema.IndexSchema; default search field in schema is text
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.932; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.942; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.945; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.947; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:23.948; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:23.949; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.949; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:23.950; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:23.950; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.950; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.950; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty2-1365025882410/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty2/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.950; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@28b3ea47
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.951; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.951; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.952; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty2/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:23.952; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty2/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.952; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty2/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.953; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6e05c843 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a603c22),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.954; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.955; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.956; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.956; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.957; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.957; org.apache.solr.core.RequestHandlers; created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.957; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.957; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.958; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.958; org.apache.solr.core.RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.960; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.962; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@3b0351c1 main
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.963; org.apache.solr.update.UpdateHandler; Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty2/tlog
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.963; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.964; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.967; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@3b0351c1 main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.968; org.apache.solr.cloud.ZkController; publishing core=collection1 state=down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:23.969; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.377; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.377; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=2 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:51874_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"down",
[junit4:junit4]   1>   "numShards":"2",
[junit4:junit4]   1>   "core_node_name":"3",
[junit4:junit4]   1>   "shard":null,
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:51874",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"collection1"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.378; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Collection already exists with numShards=2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.378; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.381; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.381; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.381; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.381; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.970; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.970; org.apache.solr.cloud.ZkController; Register replica - core:collection1 address:http://127.0.0.1:51874 collection:collection1 shard:shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.971; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leader_elect/shard1/election
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.976; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0008 type:delete cxid:0x4b zxid:0x7d txntype:-1 reqpath:n/a Error Path:/solr/collections/collection1/leaders/shard1 Error:KeeperErrorCode = NoNode for /solr/collections/collection1/leaders/shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.976; org.apache.solr.cloud.ShardLeaderElectionContext; Running the leader process.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.977; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0008 type:create cxid:0x4c zxid:0x7e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.978; org.apache.solr.cloud.ShardLeaderElectionContext; Enough replicas found to continue.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.979; org.apache.solr.cloud.ShardLeaderElectionContext; I may be the new leader - try and sync
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.979; org.apache.solr.cloud.SyncStrategy; Sync replicas to http://127.0.0.1:51874/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.979; org.apache.solr.cloud.SyncStrategy; Sync Success - now sync replicas to me
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.979; org.apache.solr.cloud.SyncStrategy; http://127.0.0.1:51874/collection1/ has no replicas
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.979; org.apache.solr.cloud.ShardLeaderElectionContext; I am the new leader: http://127.0.0.1:51874/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.980; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/collection1/leaders/shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:25.982; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0008 type:create cxid:0x56 zxid:0x81 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.886; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.893; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.893; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.893; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.893; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.937; org.apache.solr.cloud.ZkController; We are http://127.0.0.1:51874/collection1/ and leader is http://127.0.0.1:51874/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.937; org.apache.solr.cloud.ZkController; No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:51874
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.937; org.apache.solr.cloud.ZkController; I am the leader, no recovery necessary
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.937; org.apache.solr.cloud.ZkController; publishing core=collection1 state=active
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.937; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.939; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.940; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.941; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:26.941; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.016; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.019; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:33532
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.020; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.020; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.021; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1365025886946
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.021; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1365025886946/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.022; org.apache.solr.core.CoreContainer; New CoreContainer 1412439096
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.022; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1365025886946/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.022; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1365025886946/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.043; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.044; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.044; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 15000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.044; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.045; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.045; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.045; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.046; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.046; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.047; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.053; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.063; org.apache.solr.core.CoreContainer; Zookeeper client=127.0.0.1:58873/solr
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.063; org.apache.solr.cloud.ZkController; zkHost includes chroot
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.064; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@4913852a
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.065; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.066; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.066; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.066; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49876
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.067; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49876
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.068; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb0009 with negotiated timeout 20000 for client /127.0.0.1:49876
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.068; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb0009, negotiated timeout = 20000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.068; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@4913852a name:ZooKeeperConnection Watcher:127.0.0.1:58873 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.068; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.069; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dd1e1e1cb0009
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.070; org.apache.zookeeper.ZooKeeper; Session: 0x13dd1e1e1cb0009 closed
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.070; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:49876 which had sessionid 0x13dd1e1e1cb0009
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.070; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.071; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.074; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@6338cbff
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.075; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.075; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.076; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.076; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49877
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.077; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49877
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.077; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb000a with negotiated timeout 20000 for client /127.0.0.1:49877
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.077; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb000a, negotiated timeout = 20000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.078; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@6338cbff name:ZooKeeperConnection Watcher:127.0.0.1:58873/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.078; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.079; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb000a type:create cxid:0x1 zxid:0x8e txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.080; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb000a type:create cxid:0x3 zxid:0x8f txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:27.082; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.085; org.apache.solr.cloud.ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:33532_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.086; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb000a type:delete cxid:0xe zxid:0x90 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:33532_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:33532_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.087; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:33532_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.089; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.089; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 3)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.090; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.090; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.090; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.090; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.091; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.091; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.092; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.096; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1365025886946/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.096; org.apache.solr.cloud.ZkController; Check for collection zkNode:collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.097; org.apache.solr.cloud.ZkController; Collection zkNode exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.097; org.apache.solr.cloud.ZkController; Load collection config from:/collections/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.098; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1365025886946/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.099; org.apache.solr.core.SolrResourceLoader; Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1365025886946/collection1/lib/README' to classloader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.099; org.apache.solr.core.SolrResourceLoader; Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1365025886946/collection1/lib/classes/' to classloader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.121; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.166; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.168; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.171; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.398; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.399; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=2 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:51874_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "numShards":"2",
[junit4:junit4]   1>   "core_node_name":"3",
[junit4:junit4]   1>   "shard":"shard1",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:51874",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"collection1"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.402; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.402; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.402; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.402; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.402; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.497; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.502; org.apache.solr.schema.IndexSchema; default search field in schema is text
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.504; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.515; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.519; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.522; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:28.523; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:28.523; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.523; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:28.524; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:28.525; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.525; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.525; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty3-1365025886946/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.525; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@28b3ea47
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.526; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.526; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.526; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:28.527; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.527; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.528; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7a51015b lockFactory=org.apache.lucene.store.NativeFSLockFactory@515ce389),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.528; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.530; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.530; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.531; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.531; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.532; org.apache.solr.core.RequestHandlers; created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.532; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.532; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.533; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.533; org.apache.solr.core.RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.535; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.537; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@a7d05db main
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.537; org.apache.solr.update.UpdateHandler; Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3/tlog
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.538; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.538; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.542; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@a7d05db main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.543; org.apache.solr.cloud.ZkController; publishing core=collection1 state=down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:28.544; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:29.907; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:29.908; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=2 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:33532_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"down",
[junit4:junit4]   1>   "numShards":"2",
[junit4:junit4]   1>   "core_node_name":"4",
[junit4:junit4]   1>   "shard":null,
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:33532",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"collection1"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:29.908; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Collection already exists with numShards=2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:29.909; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:29.914; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:29.914; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:29.915; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:29.914; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:29.914; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.545; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.545; org.apache.solr.cloud.ZkController; Register replica - core:collection1 address:http://127.0.0.1:33532 collection:collection1 shard:shard2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.549; org.apache.solr.cloud.ZkController; We are http://127.0.0.1:33532/collection1/ and leader is http://127.0.0.1:46752/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.549; org.apache.solr.cloud.ZkController; No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:33532
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.549; org.apache.solr.cloud.ZkController; Core needs to recover:collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.550; org.apache.solr.update.DefaultSolrCoreState; Running recovery - first canceling any ongoing recovery
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.550; org.apache.solr.cloud.RecoveryStrategy; Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.551; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.552; org.apache.solr.cloud.RecoveryStrategy; ###### startupVersions=[]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.552; org.apache.solr.cloud.ZkController; publishing core=collection1 state=recovering
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.553; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.554; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.555; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.554; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.555; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.661; org.eclipse.jetty.server.Server; jetty-8.1.8.v20121106
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.669; org.eclipse.jetty.server.AbstractConnector; Started SelectChannelConnector@127.0.0.1:38235
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.671; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init()
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.671; org.apache.solr.core.SolrResourceLoader; JNDI not configured for solr (NoInitialContextEx)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.672; org.apache.solr.core.SolrResourceLoader; using system property solr.solr.home: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1365025890560
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.673; org.apache.solr.core.CoreContainer$Initializer; looking for solr config file: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1365025890560/solr.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.673; org.apache.solr.core.CoreContainer; New CoreContainer 1792498406
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.674; org.apache.solr.core.CoreContainer; Loading CoreContainer using Solr Home: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1365025890560/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.675; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1365025890560/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.705; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting socketTimeout to: 120000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.706; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting urlScheme to: http://
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.707; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting connTimeout to: 15000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.707; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxConnectionsPerHost to: 20
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.708; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting corePoolSize to: 0
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.708; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maximumPoolSize to: 2147483647
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.709; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting maxThreadIdleTime to: 5
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.710; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting sizeOfQueue to: -1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.710; org.apache.solr.handler.component.HttpShardHandlerFactory; Setting fairnessPolicy to: false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.711; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.720; org.apache.solr.core.CoreContainer; Registering Log Listener
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.736; org.apache.solr.core.CoreContainer; Zookeeper client=127.0.0.1:58873/solr
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.737; org.apache.solr.cloud.ZkController; zkHost includes chroot
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.737; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873 sessionTimeout=60000 watcher=org.apache.solr.common.cloud.ConnectionManager@6e1645b3
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.739; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.746; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.746; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49906
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.746; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.748; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49906
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.749; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb000b with negotiated timeout 20000 for client /127.0.0.1:49906
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.749; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb000b, negotiated timeout = 20000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.753; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@6e1645b3 name:ZooKeeperConnection Watcher:127.0.0.1:58873 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.754; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.757; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dd1e1e1cb000b
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.758; org.apache.zookeeper.ZooKeeper; Session: 0x13dd1e1e1cb000b closed
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.760; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.763; org.apache.zookeeper.ZooKeeper; Initiating client connection, connectString=127.0.0.1:58873/solr sessionTimeout=30000 watcher=org.apache.solr.common.cloud.ConnectionManager@4ca5f355
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.759; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.758; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:49906 which had sessionid 0x13dd1e1e1cb000b
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.765; org.apache.zookeeper.ClientCnxn$SendThread; Opening socket connection to server localhost.localdomain/127.0.0.1:58873. Will not attempt to authenticate using SASL (access denied ("javax.security.auth.AuthPermission" "getLoginConfiguration"))
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.766; org.apache.zookeeper.ClientCnxn$SendThread; Socket connection established to localhost.localdomain/127.0.0.1:58873, initiating session
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.766; org.apache.zookeeper.server.NIOServerCnxnFactory; Accepted socket connection from /127.0.0.1:49907
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.766; org.apache.solr.common.cloud.ConnectionManager; Waiting for client to connect to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.772; org.apache.zookeeper.server.ZooKeeperServer; Client attempting to establish new session at /127.0.0.1:49907
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.774; org.apache.zookeeper.server.ZooKeeperServer; Established session 0x13dd1e1e1cb000c with negotiated timeout 20000 for client /127.0.0.1:49907
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.777; org.apache.zookeeper.ClientCnxn$SendThread; Session establishment complete on server localhost.localdomain/127.0.0.1:58873, sessionid = 0x13dd1e1e1cb000c, negotiated timeout = 20000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.778; org.apache.solr.common.cloud.ConnectionManager; Watcher org.apache.solr.common.cloud.ConnectionManager@4ca5f355 name:ZooKeeperConnection Watcher:127.0.0.1:58873/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.778; org.apache.solr.common.cloud.ConnectionManager; Client is connected to ZooKeeper
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.779; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb000c type:create cxid:0x1 zxid:0xa1 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.781; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb000c type:create cxid:0x3 zxid:0xa2 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:30.783; org.apache.solr.common.cloud.ZkStateReader; Updating cluster state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.421; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.421; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=2 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:33532_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"recovering",
[junit4:junit4]   1>   "numShards":"2",
[junit4:junit4]   1>   "core_node_name":"4",
[junit4:junit4]   1>   "shard":"shard2",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:33532",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"collection1"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.425; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.425; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.425; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.426; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.425; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.425; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.567; org.apache.solr.servlet.SolrDispatchFilter; [admin] webapp=null path=/admin/cores params={checkLive=true&state=recovering&nodeName=127.0.0.1:33532_&coreNodeName=4&wt=javabin&core=collection1&onlyIfLeader=true&version=2&action=PREPRECOVERY} status=0 QTime=1000 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.787; org.apache.solr.cloud.ZkController; Register node as live in ZooKeeper:/live_nodes/127.0.0.1:38235_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.788; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb000c type:delete cxid:0x10 zxid:0xa7 txntype:-1 reqpath:n/a Error Path:/solr/live_nodes/127.0.0.1:38235_ Error:KeeperErrorCode = NoNode for /solr/live_nodes/127.0.0.1:38235_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.788; org.apache.solr.common.cloud.SolrZkClient; makePath: /live_nodes/127.0.0.1:38235_
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.790; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.790; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.790; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 4)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.791; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.792; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.792; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.792; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.792; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.792; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.793; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.793; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.794; org.apache.solr.common.cloud.ZkStateReader$3; Updating live nodes... (5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.798; org.apache.solr.core.CoreContainer; Creating SolrCore 'collection1' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1365025890560/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.798; org.apache.solr.cloud.ZkController; Check for collection zkNode:collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.798; org.apache.solr.cloud.ZkController; Collection zkNode exists
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.799; org.apache.solr.cloud.ZkController; Load collection config from:/collections/collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.800; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1365025890560/collection1/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.800; org.apache.solr.core.SolrResourceLoader; Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1365025890560/collection1/lib/README' to classloader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.800; org.apache.solr.core.SolrResourceLoader; Adding 'file:/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1365025890560/collection1/lib/classes/' to classloader
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.823; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.851; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.852; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:31.856; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.215; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.220; org.apache.solr.schema.IndexSchema; default search field in schema is text
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.223; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.233; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.235; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.238; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:32.239; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:32.239; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.240; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:32.240; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:32.241; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.241; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.241; org.apache.solr.core.SolrCore; [collection1] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty4-1365025890560/collection1/, dataDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.241; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@28b3ea47
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.242; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.242; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.242; org.apache.solr.core.SolrCore; New index directory detected: old=null new=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:32.243; org.apache.solr.core.SolrCore; [collection1] Solr index directory './org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.243; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.244; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1aa5f632 lockFactory=org.apache.lucene.store.NativeFSLockFactory@2ea8b69c),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.244; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.246; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.246; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.247; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.247; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.248; org.apache.solr.core.RequestHandlers; created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.248; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.248; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.249; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.249; org.apache.solr.core.RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.251; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.253; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@5433ed2d main
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.254; org.apache.solr.update.UpdateHandler; Clearing tlog files, tlogDir=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4/tlog
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.254; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.254; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.259; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@5433ed2d main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.261; org.apache.solr.cloud.ZkController; publishing core=collection1 state=down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.261; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.930; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.931; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=2 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:38235_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"down",
[junit4:junit4]   1>   "numShards":"2",
[junit4:junit4]   1>   "core_node_name":"5",
[junit4:junit4]   1>   "shard":null,
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:38235",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"collection1"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.931; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Collection already exists with numShards=2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.931; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.934; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.934; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.935; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.934; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.934; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:32.934; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.263; org.apache.solr.core.CoreContainer; registering core: collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.263; org.apache.solr.cloud.ZkController; Register replica - core:collection1 address:http://127.0.0.1:38235 collection:collection1 shard:shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.266; org.apache.solr.cloud.ZkController; We are http://127.0.0.1:38235/collection1/ and leader is http://127.0.0.1:51874/collection1/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.267; org.apache.solr.cloud.ZkController; No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:38235
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.267; org.apache.solr.cloud.ZkController; Core needs to recover:collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.267; org.apache.solr.update.DefaultSolrCoreState; Running recovery - first canceling any ongoing recovery
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.268; org.apache.solr.cloud.RecoveryStrategy; Starting recovery process.  core=collection1 recoveringAfterStartup=true
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.268; org.apache.solr.cloud.RecoveryStrategy; ###### startupVersions=[]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.268; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.268; org.apache.solr.cloud.ZkController; publishing core=collection1 state=recovering
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.269; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.270; org.apache.solr.servlet.SolrDispatchFilter; user.dir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.270; org.apache.solr.servlet.SolrDispatchFilter; SolrDispatchFilter.init() done
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.271; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.271; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.275; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.277; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.281; org.apache.solr.core.CoreContainer; Creating SolrCore 'onenodecollectioncore' using instanceDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/onenodecollectioncore
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.281; org.apache.solr.cloud.ZkController; Check for collection zkNode:onenodecollection
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.282; org.apache.solr.cloud.ZkController; Creating collection in ZooKeeper:onenodecollection
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.282; org.apache.solr.cloud.ZkController; Looking for collection configName
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.283; org.apache.solr.cloud.ZkController; Only one config set found in zk - using it:conf1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.284; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/onenodecollection
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.285; org.apache.solr.cloud.ZkController; Load collection config from:/collections/onenodecollection
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.287; org.apache.solr.core.SolrResourceLoader; new SolrResourceLoader for directory: '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/onenodecollectioncore/'
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.314; org.apache.solr.core.SolrConfig; Using Lucene MatchVersion: LUCENE_50
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.345; org.apache.solr.core.SolrConfig; Loaded SolrConfig: solrconfig.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.347; org.apache.solr.schema.IndexSchema; Reading Solr Schema from schema.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.351; org.apache.solr.schema.IndexSchema; Schema name=test
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.568; org.apache.solr.cloud.RecoveryStrategy; Attempting to PeerSync from http://127.0.0.1:46752/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.569; org.apache.solr.update.PeerSync; PeerSync: core=collection1 url=http://127.0.0.1:33532 START replicas=[http://127.0.0.1:46752/collection1/] nUpdates=100
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:33.569; org.apache.solr.update.PeerSync; no frame of reference to tell of we've missed updates
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.570; org.apache.solr.cloud.RecoveryStrategy; PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.570; org.apache.solr.cloud.RecoveryStrategy; Starting Replication Recovery. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.570; org.apache.solr.cloud.RecoveryStrategy; Begin buffering updates. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.570; org.apache.solr.update.UpdateLog; Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.571; org.apache.solr.cloud.RecoveryStrategy; Attempting to replicate from http://127.0.0.1:46752/collection1/. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.571; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.575; org.apache.solr.core.SolrCore; [collection1] webapp= path=/get params={wt=javabin&distrib=false&getVersions=100&version=2&qt=/get} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.576; org.apache.solr.update.DirectUpdateHandler2; start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.578; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6cabe12e lockFactory=org.apache.lucene.store.NativeFSLockFactory@78a8589),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.578; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.579; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6cabe12e lockFactory=org.apache.lucene.store.NativeFSLockFactory@78a8589),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6cabe12e lockFactory=org.apache.lucene.store.NativeFSLockFactory@78a8589),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.579; org.apache.solr.core.SolrDeletionPolicy; newest commit = 2[segments_2]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.580; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@6573f1fd realtime
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.580; org.apache.solr.update.DirectUpdateHandler2; end_commit_flush
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.580; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp= path=/update params={waitSearcher=true&wt=javabin&openSearcher=false&commit=true&commit_end_point=true&softCommit=false&version=2} {commit=} 0 4
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.581; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.581; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.585; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.585; org.apache.solr.core.SolrCore; [collection1] webapp= path=/replication params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 QTime=3 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.586; org.apache.solr.handler.SnapPuller; Master's generation: 2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.586; org.apache.solr.handler.SnapPuller; Slave's generation: 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.586; org.apache.solr.handler.SnapPuller; Starting replication process
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.588; org.apache.solr.core.SolrCore; [collection1] webapp= path=/replication params={wt=javabin&command=filelist&generation=2&version=2&qt=/replication} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.589; org.apache.solr.handler.SnapPuller; Number of files in latest index in master: 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.590; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3/index.20130404085133589
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.590; org.apache.solr.handler.SnapPuller; Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@52337f02 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1c973f5) fullCopy=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.592; org.apache.solr.core.SolrCore; [collection1] webapp= path=/replication params={wt=filestream&file=segments_2&command=filecontent&generation=2&checksum=true&qt=/replication} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.594; org.apache.solr.handler.SnapPuller; Total time taken for download : 0 secs
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.595; org.apache.solr.update.DefaultSolrCoreState; Creating new IndexWriter...
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.595; org.apache.solr.update.DefaultSolrCoreState; Waiting until IndexWriter is unused... core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.596; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7a51015b lockFactory=org.apache.lucene.store.NativeFSLockFactory@515ce389),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@7a51015b lockFactory=org.apache.lucene.store.NativeFSLockFactory@515ce389),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.596; org.apache.solr.core.SolrDeletionPolicy; newest commit = 2[segments_2]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.597; org.apache.solr.update.DefaultSolrCoreState; New IndexWriter is ready to be used.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.597; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@4391992b main
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.598; org.apache.solr.core.SolrCore; [collection1] Registered new searcher Searcher@4391992b main{StandardDirectoryReader(segments_2:1:nrt)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.599; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3/index.20130404085133589 [CachedDir<<refCount=0;path=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3/index.20130404085133589;done=true>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.599; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3/index.20130404085133589
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.599; org.apache.solr.core.CachingDirectoryFactory; Removing directory before core close: ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty3/index.20130404085133589
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.599; org.apache.solr.cloud.RecoveryStrategy; No replay needed. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.599; org.apache.solr.cloud.RecoveryStrategy; Replication Recovery was successful - registering as Active. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.600; org.apache.solr.cloud.ZkController; publishing core=collection1 state=active
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.600; org.apache.solr.cloud.ZkController; numShards not found on descriptor - reading it from system property
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.601; org.apache.solr.cloud.RecoveryStrategy; Finished recovery process. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.691; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.697; org.apache.solr.schema.IndexSchema; default search field in schema is text
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.699; org.apache.solr.schema.IndexSchema; unique key field: id
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.714; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.717; org.apache.solr.schema.FileExchangeRateProvider; Reloading exchange rates from file currency.xml
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.721; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:33.722; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:33.722; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.723; org.apache.solr.schema.OpenExchangeRatesOrgProvider; Reloading exchange rates from open-exchange-rates.json
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:33.724; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Unknown key IMPORTANT NOTE
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:33.724; org.apache.solr.schema.OpenExchangeRatesOrgProvider$OpenExchangeRates; Expected key, got STRING
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.724; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.725; org.apache.solr.core.SolrCore; [onenodecollectioncore] Opening new SolrCore at /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./org.apache.solr.cloud.BasicDistributedZk2Test-jetty1-1365025877865/onenodecollectioncore/, dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1365025874375/onenodecollection/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.725; org.apache.solr.core.JmxMonitoredMap; JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@28b3ea47
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.725; org.apache.solr.core.SolrCore; org.apache.solr.core.MockDirectoryFactory
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.726; org.apache.solr.core.CachingDirectoryFactory; return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1365025874375/onenodecollection
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.726; org.apache.solr.core.SolrCore; New index directory detected: old=null new=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1365025874375/onenodecollection/index/
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:33.727; org.apache.solr.core.SolrCore; [onenodecollectioncore] Solr index directory '/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1365025874375/onenodecollection/index' doesn't exist. Creating new index...
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.727; org.apache.solr.core.CachingDirectoryFactory; return new directory for /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1365025874375/onenodecollection/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.729; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@30957804 lockFactory=org.apache.lucene.store.NativeFSLockFactory@123ba4b),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.729; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.731; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.731; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.731; org.apache.solr.update.processor.UpdateRequestProcessorChain; inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.732; org.apache.solr.core.RequestHandlers; created standard: solr.StandardRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.732; org.apache.solr.core.RequestHandlers; created /get: solr.RealTimeGetHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.733; org.apache.solr.core.RequestHandlers; adding lazy requestHandler: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.733; org.apache.solr.core.RequestHandlers; created /replication: solr.ReplicationHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.733; org.apache.solr.core.RequestHandlers; created /update: solr.UpdateRequestHandler
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.734; org.apache.solr.core.RequestHandlers; created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.736; org.apache.solr.handler.loader.XMLLoader; xsltCacheLifetimeSeconds=60
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.738; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@2daa39bf main
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.739; org.apache.solr.update.UpdateHandler; Clearing tlog files, tlogDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1365025874375/onenodecollection/tlog
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.739; org.apache.solr.update.CommitTracker; Hard AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.740; org.apache.solr.update.CommitTracker; Soft AutoCommit: disabled
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.743; org.apache.solr.core.SolrCore; [onenodecollectioncore] Registered new searcher Searcher@2daa39bf main{StandardDirectoryReader(segments_1:1)}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:33.743; org.apache.solr.cloud.ZkController; publishing core=onenodecollectioncore state=down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.439; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.440; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=2 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:38235_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"recovering",
[junit4:junit4]   1>   "numShards":"2",
[junit4:junit4]   1>   "core_node_name":"5",
[junit4:junit4]   1>   "shard":"shard1",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:38235",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"collection1"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.442; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=2 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:33532_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"active",
[junit4:junit4]   1>   "numShards":"2",
[junit4:junit4]   1>   "core_node_name":"4",
[junit4:junit4]   1>   "shard":"shard2",
[junit4:junit4]   1>   "core":"collection1",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:33532",
[junit4:junit4]   1>   "roles":null,
[junit4:junit4]   1>   "collection":"collection1"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.445; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Update state numShards=1 message={
[junit4:junit4]   1>   "node_name":"127.0.0.1:46752_",
[junit4:junit4]   1>   "operation":"state",
[junit4:junit4]   1>   "state":"down",
[junit4:junit4]   1>   "numShards":"1",
[junit4:junit4]   1>   "core_node_name":null,
[junit4:junit4]   1>   "shard":null,
[junit4:junit4]   1>   "core":"onenodecollectioncore",
[junit4:junit4]   1>   "base_url":"http://127.0.0.1:46752",
[junit4:junit4]   1>   "roles":"none",
[junit4:junit4]   1>   "collection":"onenodecollection"}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.445; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Create collection onenodecollection with numShards 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.445; org.apache.solr.cloud.Overseer$ClusterStateUpdater; Assigning new node to shard shard=shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.448; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.448; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.448; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.449; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.449; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.448; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.745; org.apache.solr.core.CoreContainer; registering core: onenodecollectioncore
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.746; org.apache.solr.cloud.ZkController; Register replica - core:onenodecollectioncore address:http://127.0.0.1:46752 collection:onenodecollection shard:shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.747; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/onenodecollection/leader_elect/shard1/election
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.753; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0006 type:delete cxid:0xaf zxid:0xc2 txntype:-1 reqpath:n/a Error Path:/solr/collections/onenodecollection/leaders Error:KeeperErrorCode = NoNode for /solr/collections/onenodecollection/leaders
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.754; org.apache.solr.cloud.ShardLeaderElectionContext; Running the leader process.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.754; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0006 type:create cxid:0xb0 zxid:0xc3 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.756; org.apache.solr.cloud.ShardLeaderElectionContext; Enough replicas found to continue.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.756; org.apache.solr.cloud.ShardLeaderElectionContext; I may be the new leader - try and sync
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.757; org.apache.solr.cloud.SyncStrategy; Sync replicas to http://127.0.0.1:46752/onenodecollectioncore/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.757; org.apache.solr.cloud.SyncStrategy; Sync Success - now sync replicas to me
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.758; org.apache.solr.cloud.SyncStrategy; http://127.0.0.1:46752/onenodecollectioncore/ has no replicas
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.758; org.apache.solr.cloud.ShardLeaderElectionContext; I am the new leader: http://127.0.0.1:46752/onenodecollectioncore/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.758; org.apache.solr.common.cloud.SolrZkClient; makePath: /collections/onenodecollection/leaders/shard1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:34.762; org.apache.zookeeper.server.PrepRequestProcessor; Got user-level KeeperException when processing sessionid:0x13dd1e1e1cb0006 type:create cxid:0xbb zxid:0xc7 txntype:-1 reqpath:n/a Error Path:/solr/overseer Error:KeeperErrorCode = NodeExists for /solr/overseer
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.277; org.apache.solr.servlet.SolrDispatchFilter; [admin] webapp=null path=/admin/cores params={checkLive=true&state=recovering&nodeName=127.0.0.1:38235_&coreNodeName=5&wt=javabin&core=collection1&onlyIfLeader=true&version=2&action=PREPRECOVERY} status=0 QTime=2000 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.955; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.962; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.962; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.962; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.962; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.962; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.962; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 5)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.967; org.apache.solr.cloud.ZkController; We are http://127.0.0.1:46752/onenodecollectioncore/ and leader is http://127.0.0.1:46752/onenodecollectioncore/
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.967; org.apache.solr.cloud.ZkController; No LogReplay needed for core=onenodecollectioncore baseURL=http://127.0.0.1:46752
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.968; org.apache.solr.cloud.ZkController; I am the leader, no recovery necessary
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.968; org.apache.solr.cloud.ZkController; publishing core=onenodecollectioncore state=active
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.969; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.970; org.apache.solr.servlet.SolrDispatchFilter; [admin] webapp=null path=/admin/cores params={numShards=1&dataDir=/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1365025874375/onenodecollection&wt=javabin&name=onenodecollectioncore&roles=none&version=2&collection=onenodecollection&action=CREATE} status=0 QTime=2689 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.971; org.apache.solr.cloud.AbstractDistribZkTestBase; Wait for recoveries to finish - collection: onenodecollection failOnTimeout:true timeout (sec):230
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:35.972; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:36.973; org.apache.solr.common.cloud.ZkStateReader; Updating cloud state from ZooKeeper... 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.278; org.apache.solr.cloud.RecoveryStrategy; Attempting to PeerSync from http://127.0.0.1:51874/collection1/ core=collection1 - recoveringAfterStartup=true
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.278; org.apache.solr.update.PeerSync; PeerSync: core=collection1 url=http://127.0.0.1:38235 START replicas=[http://127.0.0.1:51874/collection1/] nUpdates=100
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:37.278; org.apache.solr.update.PeerSync; no frame of reference to tell of we've missed updates
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.279; org.apache.solr.cloud.RecoveryStrategy; PeerSync Recovery was not successful - trying replication. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.279; org.apache.solr.cloud.RecoveryStrategy; Starting Replication Recovery. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.279; org.apache.solr.cloud.RecoveryStrategy; Begin buffering updates. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.279; org.apache.solr.update.UpdateLog; Starting to buffer updates. FSUpdateLog{state=ACTIVE, tlog=null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.279; org.apache.solr.cloud.RecoveryStrategy; Attempting to replicate from http://127.0.0.1:51874/collection1/. core=collection1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.279; org.apache.solr.client.solrj.impl.HttpClientUtil; Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.283; org.apache.solr.core.SolrCore; [collection1] webapp= path=/get params={wt=javabin&distrib=false&getVersions=100&version=2&qt=/get} status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.284; org.apache.solr.update.DirectUpdateHandler2; start commit{,optimize=false,openSearcher=false,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.286; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onInit: commits:num=1
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6e05c843 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a603c22),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.286; org.apache.solr.core.SolrDeletionPolicy; newest commit = 1[segments_1]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.287; org.apache.solr.core.SolrDeletionPolicy; SolrDeletionPolicy.onCommit: commits:num=2
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6e05c843 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a603c22),segFN=segments_1,generation=1,filenames=[segments_1]
[junit4:junit4]   1> 	commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6e05c843 lockFactory=org.apache.lucene.store.NativeFSLockFactory@1a603c22),segFN=segments_2,generation=2,filenames=[segments_2]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.287; org.apache.solr.core.SolrDeletionPolicy; newest commit = 2[segments_2]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.288; org.apache.solr.search.SolrIndexSearcher; Opening Searcher@1ac75457 realtime
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.288; org.apache.solr.update.DirectUpdateHandler2; end_commit_flush
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.288; org.apache.solr.update.processor.LogUpdateProcessor; [collection1] webapp= path=/update params={waitSearcher=true&wt=javabin&openSearcher=false&commit=true&commit_end_point=true&softCommit=false&version=2} {commit=} 0 4
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.289; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.289; org.apache.solr.handler.SnapPuller;  No value set for 'pollInterval'. Timer Task not started.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.291; org.apache.solr.handler.ReplicationHandler; Commits will be reserved for  10000
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.291; org.apache.solr.core.SolrCore; [collection1] webapp= path=/replication params={wt=javabin&command=indexversion&version=2&qt=/replication} status=0 QTime=1 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.292; org.apache.solr.handler.SnapPuller; Master's generation: 2
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.292; org.apache.solr.handler.SnapPuller; Slave's generation: 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.292; org.apache.solr.handler.SnapPuller; Starting replication process
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.294; org.apache.solr.core.SolrCore; [collection1] webapp= path=/replication params={wt=javabin&command=filelist&generation=2&version=2&qt=/replication} status=0 QTime=0 
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.294; org.apache.solr.handler.SnapPuller; Number of files in latest index in master: 1
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.295; org.apache.solr.core.CachingDirectoryFactory; return new directory for ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4/index.20130404085137294
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:37.295; org.apache.solr.handler.SnapPuller; Starting download to MockDirWrapper(org.apache.luce

[...truncated too long message...]

 1> INFO  - 2013-04-03 21:51:46.417; org.apache.solr.core.SolrCore; [collection1] Closing main searcher on request.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.418; org.apache.solr.core.CachingDirectoryFactory; Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.418; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4 [CachedDir<<refCount=0;path=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.418; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.419; org.apache.solr.core.CachingDirectoryFactory; looking to close ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4/index [CachedDir<<refCount=0;path=./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4/index;done=false>>]
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.419; org.apache.solr.core.CachingDirectoryFactory; Closing directory: ./org.apache.solr.cloud.BasicDistributedZk2Test-1365025874375/jetty4/index
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.420; org.apache.solr.cloud.Overseer$ClusterStateUpdater; According to ZK I (id=89458335703302156-127.0.0.1:38235_-n_0000000004) am no longer a leader.
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.420; org.apache.zookeeper.server.PrepRequestProcessor; Processed session termination for sessionid: 0x13dd1e1e1cb000c
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.421; org.apache.zookeeper.ZooKeeper; Session: 0x13dd1e1e1cb000c closed
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.421; org.apache.zookeeper.server.NIOServerCnxn; Closed socket connection for client /127.0.0.1:49907 which had sessionid 0x13dd1e1e1cb000c
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.444; org.eclipse.jetty.server.handler.ContextHandler; stopped o.e.j.s.ServletContextHandler{/,null}
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.499; org.apache.solr.SolrTestCaseJ4; ###Ending testDistribSearch
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.499; org.apache.zookeeper.server.ZooKeeperServer; shutting down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.499; org.apache.zookeeper.server.SessionTrackerImpl; Shutting down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.500; org.apache.zookeeper.server.PrepRequestProcessor; Shutting down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.500; org.apache.zookeeper.server.SyncRequestProcessor; Shutting down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.500; org.apache.zookeeper.server.PrepRequestProcessor; PrepRequestProcessor exited loop!
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.500; org.apache.zookeeper.server.SyncRequestProcessor; SyncRequestProcessor exited!
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.501; org.apache.zookeeper.server.FinalRequestProcessor; shutdown of request processor complete
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.502; org.apache.solr.cloud.ZkTestServer; connecting to 127.0.0.1:58873 58873
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.503; org.apache.zookeeper.server.NIOServerCnxnFactory; NIOServerCnxn factory exited run method
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.504; org.apache.zookeeper.server.ZooKeeperServer; shutting down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.504; org.apache.zookeeper.server.SessionTrackerImpl; Shutting down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.505; org.apache.zookeeper.server.PrepRequestProcessor; Shutting down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.505; org.apache.zookeeper.server.SyncRequestProcessor; Shutting down
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.505; org.apache.zookeeper.server.FinalRequestProcessor; shutdown of request processor complete
[junit4:junit4]   2> Creating dataDir: /mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build/solr-core/test/J1/./solrtest-BasicDistributedZk2Test-1365025874375
[junit4:junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=BasicDistributedZk2Test -Dtests.method=testDistribSearch -Dtests.seed=990F4E9A95D9A410 -Dtests.multiplier=3 -Dtests.slow=true -Dtests.locale=ga -Dtests.timezone=Asia/Sakhalin -Dtests.file.encoding=US-ASCII
[junit4:junit4] ERROR   32.2s J1 | BasicDistributedZk2Test.testDistribSearch <<<
[junit4:junit4]    > Throwable #1: org.apache.solr.common.SolrException: Server at http://127.0.0.1:51874/onenodecollectioncore returned non ok status:404, message:Can not find: /onenodecollectioncore/update
[junit4:junit4]    > 	at __randomizedtesting.SeedInfo.seed([990F4E9A95D9A410:18E9C082E286C42C]:0)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:375)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.impl.HttpSolrServer.request(HttpSolrServer.java:181)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.request.AbstractUpdateRequest.process(AbstractUpdateRequest.java:117)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:116)
[junit4:junit4]    > 	at org.apache.solr.client.solrj.SolrServer.add(SolrServer.java:102)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZk2Test.testNodeWithoutCollectionForwarding(BasicDistributedZk2Test.java:197)
[junit4:junit4]    > 	at org.apache.solr.cloud.BasicDistributedZk2Test.doTest(BasicDistributedZk2Test.java:89)
[junit4:junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:806)
[junit4:junit4]    > 	at java.lang.Thread.run(Thread.java:722)
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:46.522; org.apache.solr.SolrTestCaseJ4; ###deleteCore
[junit4:junit4]   2> 32156 T2104 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 2 leaked thread(s).
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:47.000; org.apache.zookeeper.server.SessionTrackerImpl; SessionTrackerImpl exited loop!
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:47.721; org.apache.solr.common.cloud.ZkStateReader$3; ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:47.722; org.apache.solr.common.cloud.ZkStateReader$2; A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4:junit4]   1> WARN  - 2013-04-03 21:51:47.722; org.apache.solr.common.cloud.ZkStateReader$2; ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4:junit4]   1> INFO  - 2013-04-03 21:51:47.722; org.apache.zookeeper.ClientCnxn$EventThread; EventThread shut down
[junit4:junit4]   2> NOTE: test params are: codec=SimpleText, sim=RandomSimilarityProvider(queryNorm=true,coord=no): {}, locale=ga, timezone=Asia/Sakhalin
[junit4:junit4]   2> NOTE: Linux 3.2.0-39-generic amd64/Oracle Corporation 1.8.0-ea (64-bit)/cpus=8,threads=2,free=206116272,total=347078656
[junit4:junit4]   2> NOTE: All tests run in this JVM: [TestDistributedGrouping, TestBinaryResponseWriter, TestRandomFaceting, TestLazyCores, OpenCloseCoreStressTest, CurrencyFieldXmlFileTest, PathHierarchyTokenizerFactoryTest, FileUtilsTest, DocValuesMultiTest, CurrencyFieldOpenExchangeTest, ConvertedLegacyTest, TestCSVLoader, CopyFieldTest, TestQueryTypes, TestDistributedSearch, BadIndexSchemaTest, FullSolrCloudDistribCmdsTest, TestDFRSimilarityFactory, TestStressLucene, SolrTestCaseJ4Test, LeaderElectionIntegrationTest, SimplePostToolTest, DirectSolrSpellCheckerTest, TestZkChroot, SolrCmdDistributorTest, RequestHandlersTest, TestJmxIntegration, TestBM25SimilarityFactory, TestFiltering, SpellCheckCollatorTest, TestMultiCoreConfBootstrap, TestIndexSearcher, TestSolrQueryParserDefaultOperatorResource, TestFoldingMultitermQuery, TestUniqueKeyFieldResource, NotRequiredUniqueKeyTest, TestArbitraryIndexDir, DefaultValueUpdateProcessorTest, TestReload, SpellPossibilityIteratorTest, TestSolr4Spatial, TestShardHandlerFactory, DOMUtilTest, TestSystemIdResolver, UniqFieldsUpdateProcessorFactoryTest, TestStressReorder, TestComponentsName, TestQuerySenderNoQuery, DistanceFunctionTest, ClusterStateTest, TestRecovery, IndexReaderFactoryTest, TestJmxMonitoredMap, JSONWriterTest, TestCollationField, TestSchemaSimilarityResource, StatsComponentTest, TestPropInject, SortByFunctionTest, TestUtils, SpellCheckComponentTest, TestFieldTypeResource, TestAtomicUpdateErrorCases, TestSolrXMLSerializer, ShardRoutingCustomTest, URLClassifyProcessorTest, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test]
[junit4:junit4] Completed on J1 in 33.43s, 1 test, 1 error <<< FAILURES!

[...truncated 490 lines...]
BUILD FAILED
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:375: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:355: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/build.xml:39: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/build.xml:183: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/solr/common-build.xml:448: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:1231: The following error occurred while executing this line:
/mnt/ssd/jenkins/workspace/Lucene-Solr-trunk-Linux/lucene/common-build.xml:875: There were test failures: 284 suites, 1173 tests, 1 error, 13 ignored (7 assumptions)

Total time: 43 minutes 59 seconds
Build step 'Invoke Ant' marked build as failure
Archiving artifacts
Recording test results
Description set: Java: 64bit/jdk1.8.0-ea-b83 -XX:+UseG1GC
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message