You are viewing a plain text version of this content. The canonical link for it is here.
Posted to dev@lucene.apache.org by Policeman Jenkins Server <je...@thetaphi.de> on 2013/10/06 16:52:37 UTC
[JENKINS] Lucene-Solr-trunk-Windows (32bit/jdk1.8.0-ea-b106) -
Build # 3327 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3327/
Java: 32bit/jdk1.8.0-ea-b106 -client -XX:+UseParallelGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate
Error Message:
expected:<[in]active> but was:<[]active>
Stack Trace:
org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
at __randomizedtesting.SeedInfo.seed([D4FE494F8DD5F007:49CC403D3002A83]:0)
at org.junit.Assert.assertEquals(Assert.java:125)
at org.junit.Assert.assertEquals(Assert.java:147)
at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
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:491)
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:724)
Build Log:
[...truncated 10095 lines...]
[junit4] Suite: org.apache.solr.cloud.SliceStateUpdateTest
[junit4] 2> 954986 T3037 oas.SolrTestCaseJ4.setUp ###Starting testSliceStateUpdate
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459
[junit4] 2> 954989 T3037 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 954991 T3038 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 955120 T3037 oasc.ZkTestServer.run start zk server on port:59893
[junit4] 2> 955125 T3037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 955139 T3044 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1c559b5 name:ZooKeeperConnection Watcher:127.0.0.1:59893 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 955140 T3037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 955140 T3037 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 955157 T3037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 955165 T3046 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@102a57d name:ZooKeeperConnection Watcher:127.0.0.1:59893/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 955166 T3037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 955167 T3037 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 955184 T3037 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 955194 T3037 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 955199 T3037 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 955204 T3037 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 955206 T3037 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 955213 T3037 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 955214 T3037 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 955220 T3037 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 955222 T3037 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 955227 T3037 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 955227 T3037 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 955232 T3037 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 955233 T3037 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 955238 T3037 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 955238 T3037 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 955244 T3037 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 955244 T3037 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 955252 T3037 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 955252 T3037 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 955258 T3037 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 955258 T3037 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 955266 T3037 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 955266 T3037 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 955275 T3037 oasc.SliceStateUpdateTest.setUp ####SETUP_START testSliceStateUpdate
[junit4] 2> 955279 T3037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 955283 T3048 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13d32c5 name:ZooKeeperConnection Watcher:127.0.0.1:59893/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 955284 T3037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 955284 T3037 oascc.SolrZkClient.makePath makePath: /collections/testcore
[junit4] 2> 955290 T3037 oascc.SolrZkClient.makePath makePath: /collections/testcore/shards
[junit4] 2> 955312 T3037 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 955314 T3037 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 955314 T3037 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 955365 T3037 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4] 2> 955382 T3037 oasc.CoreContainer.<init> New CoreContainer 16010174
[junit4] 2> 955383 T3037 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 955384 T3037 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 955384 T3037 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 955407 T3037 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4] 2> 955418 T3037 oasc.CoreContainer.<init> New CoreContainer 24370327
[junit4] 2> 955418 T3037 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 955419 T3037 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 955419 T3037 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 955420 T3037 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 955420 T3037 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 955420 T3037 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 955422 T3037 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 955422 T3037 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 955422 T3037 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 955422 T3037 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 955422 T3037 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 955428 T3037 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 955428 T3037 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 955429 T3037 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:59893/solr
[junit4] 2> 955430 T3037 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 955433 T3037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 955437 T3050 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@3a8117 name:ZooKeeperConnection Watcher:127.0.0.1:59893 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 955437 T3037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 955439 T3037 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 955439 T3039 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1418e30ea980003, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 955443 T3037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 955447 T3052 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1d82287 name:ZooKeeperConnection Watcher:127.0.0.1:59893/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 955447 T3037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 955449 T3037 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 955458 T3037 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 955466 T3037 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 955470 T3037 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 955473 T3037 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 955480 T3037 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 955488 T3037 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 955493 T3037 oasc.Overseer.start Overseer (id=90509808269524996-127.0.0.1:8983_solr-n_0000000000) starting
[junit4] 2> 955497 T3037 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 955505 T3054 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 955505 T3037 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 955510 T3037 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 955512 T3037 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 955519 T3053 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 955524 T3055 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 955524 T3055 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 955526 T3055 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 957439 T3053 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 957441 T3053 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":null,
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 957443 T3053 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 957459 T3052 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 958070 T3055 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 958070 T3055 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 958071 T3055 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 958075 T3055 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 958075 T3055 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 958080 T3055 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 958089 T3055 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 958093 T3055 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 958166 T3055 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 958195 T3055 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 958199 T3055 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 958209 T3055 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 958387 T3055 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 958397 T3055 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 958399 T3055 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 958420 T3055 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 958425 T3055 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 958433 T3055 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 958434 T3055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 958434 T3055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 958434 T3055 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 958436 T3055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 958436 T3055 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 958438 T3055 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 958438 T3055 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\
[junit4] 2> 958438 T3055 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@13a5b95
[junit4] 2> 958439 T3055 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\
[junit4] 2> 958439 T3055 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index/
[junit4] 2> 958439 T3055 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index' doesn't exist. Creating new index...
[junit4] 2> 958439 T3055 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index
[junit4] 2> 958440 T3055 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.164667926841381]
[junit4] 2> 958440 T3055 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@2cefd9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2644b9))),segFN=segments_1,generation=1}
[junit4] 2> 958440 T3055 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 958443 T3055 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 958445 T3055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 958445 T3055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 958445 T3055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 958446 T3055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 958446 T3055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 958446 T3055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 958446 T3055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 958447 T3055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 958447 T3055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 958449 T3055 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 958449 T3055 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 958449 T3055 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 958449 T3055 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 958449 T3055 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 958451 T3055 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 958451 T3055 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 958452 T3055 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 958452 T3055 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 958454 T3055 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 958454 T3055 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 958454 T3055 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 958454 T3055 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 958455 T3055 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 958455 T3055 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 958456 T3055 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 958456 T3055 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 958457 T3055 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 958457 T3055 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 958457 T3055 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 958459 T3055 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 958459 T3055 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 958460 T3055 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 958460 T3055 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 958467 T3055 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 958469 T3055 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 958470 T3055 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML]
[junit4] 2> 958474 T3055 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 958474 T3055 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 958476 T3055 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4856017741335651]
[junit4] 2> 958477 T3055 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@2cefd9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@2644b9))),segFN=segments_1,generation=1}
[junit4] 2> 958477 T3055 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 958479 T3055 oass.SolrIndexSearcher.<init> Opening Searcher@19f47af main
[junit4] 2> 958479 T3055 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 958491 T3055 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 958564 T3056 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@19f47af main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 958570 T3055 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 958570 T3055 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 958570 T3055 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 958578 T3055 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 958590 T3055 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 958595 T3055 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 958595 T3055 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 958597 T3055 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 958597 T3055 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 959383 T3053 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 959414 T3052 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 959447 T3055 oasc.ZkController.register We are http://127.0.0.1:8983/solr/collection1/ and leader is http://127.0.0.1:8983/solr/collection1/
[junit4] 2> 959447 T3055 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 959449 T3055 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 959449 T3055 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 959450 T3055 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 959456 T3055 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 959460 T3037 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 959461 T3037 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 959462 T3037 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 959464 T3037 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 959464 T3037 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 959465 T3037 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 959466 T3037 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 959466 T3037 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 959467 T3037 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 959468 T3037 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 959470 T3037 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 959473 T3037 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 959475 T3037 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 959476 T3037 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:59893/solr
[junit4] 2> 959476 T3037 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 959481 T3037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 959488 T3059 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@583e6e name:ZooKeeperConnection Watcher:127.0.0.1:59893 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 959490 T3037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 959495 T3037 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 959495 T3039 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1418e30ea980005, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 959505 T3037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 959512 T3061 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@589cb5 name:ZooKeeperConnection Watcher:127.0.0.1:59893/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 959512 T3037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 959527 T3037 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 960798 T3037 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 960804 T3037 oasc.ZkController.createEphemeralLiveNode Found a previous node that still exists while trying to register a new live node /live_nodes/127.0.0.1:8983_solr - removing existing node to create another.
[junit4] 2> 960805 T3037 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 960807 T3052 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 960808 T3061 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 960816 T3052 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 960817 T3061 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 960837 T3062 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 960838 T3062 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 960842 T3062 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 960842 T3062 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 960843 T3062 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 960843 T3062 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 960845 T3062 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 960845 T3062 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 960849 T3062 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 960856 T3062 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 960860 T3062 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 960940 T3062 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 960969 T3062 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 960972 T3062 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 960980 T3062 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 961141 T3062 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 961151 T3062 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 961154 T3062 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 961174 T3062 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 961178 T3062 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 961181 T3062 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 961183 T3062 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 961183 T3062 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 961185 T3062 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 961186 T3062 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 961186 T3062 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 961186 T3062 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 961187 T3062 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\
[junit4] 2> 961187 T3062 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@13a5b95
[junit4] 2> 961187 T3062 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\
[junit4] 2> 961187 T3062 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index/
[junit4] 2> 961187 T3062 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index' doesn't exist. Creating new index...
[junit4] 2> 961189 T3062 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index
[junit4] 2> 961189 T3062 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogDocMergePolicy: [LogDocMergePolicy: minMergeSize=1000, mergeFactor=15, maxMergeSize=9223372036854775807, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.164667926841381]
[junit4] 2> 961190 T3062 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d6311a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@aaa3ff))),segFN=segments_1,generation=1}
[junit4] 2> 961190 T3062 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 961192 T3062 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 961195 T3062 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 961195 T3062 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 961195 T3062 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 961196 T3062 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 961196 T3062 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 961196 T3062 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 961196 T3062 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "uniq-fields"
[junit4] 2> 961197 T3062 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 961197 T3062 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 961197 T3062 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 961198 T3062 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 961198 T3062 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 961198 T3062 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 961198 T3062 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 961200 T3062 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 961202 T3062 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 961202 T3062 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 961203 T3062 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 961203 T3062 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 961204 T3062 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 961204 T3062 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 961204 T3062 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 961205 T3062 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 961205 T3062 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 961206 T3062 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 961207 T3062 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 961207 T3062 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 961209 T3062 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 961209 T3062 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 961211 T3062 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 961212 T3062 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 961212 T3062 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 961213 T3062 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 961218 T3062 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 961219 T3062 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 961222 T3062 oasc.SolrCore.initDeprecatedSupport WARN adding ShowFileRequestHandler with hidden files: [OLD_SYNONYMS.TXT, STOPWORDS.TXT, PROTWORDS.TXT, OPEN-EXCHANGE-RATES.JSON, SYNONYMS.TXT, CURRENCY.XML, MAPPING-ISOLATIN1ACCENT.TXT, SOLRCONFIG.SNIPPET.RANDOMINDEXCONFIG.XML]
[junit4] 2> 961225 T3062 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 961225 T3062 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 961226 T3062 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=17, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.4856017741335651]
[junit4] 2> 961227 T3062 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirectoryWrapper(RateLimitedDirectoryWrapper(org.apache.lucene.store.RAMDirectory@d6311a lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@aaa3ff))),segFN=segments_1,generation=1}
[junit4] 2> 961227 T3062 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 961227 T3062 oass.SolrIndexSearcher.<init> Opening Searcher@1f44d90 main
[junit4] 2> 961228 T3062 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 961232 T3062 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 961254 T3063 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f44d90 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 961260 T3062 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 961260 T3062 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 961261 T3062 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 961268 T3062 oasc.ZkController.register We are http://127.0.0.1:8983/solr/collection1/ and leader is http://127.0.0.1:8983/solr/collection1/
[junit4] 2> 961268 T3062 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 961268 T3062 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 961268 T3062 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 961269 T3062 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 961271 T3062 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 961272 T3037 oasc.SliceStateUpdateTest.setUp ####SETUP_END testSliceStateUpdate
[junit4] 2> 961274 T3037 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 961275 T3053 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 961275 T3053 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":null,
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 961278 T3066 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@16edd13 name:ZooKeeperConnection Watcher:127.0.0.1:59893/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 961278 T3037 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 961280 T3052 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 961280 T3061 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 961281 T3039 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1418e30ea980007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 961287 T3053 oasc.Overseer$ClusterStateUpdater.updateState node=core_node1 is already registered
[junit4] 2> 961287 T3053 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":null,
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 961287 T3053 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 961292 T3053 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":null,
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 961299 T3061 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 998402 T3037 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=16010174
[junit4] 2> 998506 T3053 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 998507 T3053 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":null,
[junit4] 2> "collection":"collection1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 998508 T3053 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 998518 T3052 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 998519 T3061 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1000073 T3037 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1000074 T3037 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1000077 T3037 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1ada9f1
[junit4] 2> 1000094 T3037 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1000094 T3037 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1000094 T3037 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1000094 T3037 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1000096 T3037 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1000096 T3037 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1000096 T3037 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index;done=false>>]
[junit4] 2> 1000097 T3037 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index
[junit4] 2> 1000097 T3037 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\;done=false>>]
[junit4] 2> 1000097 T3037 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\
[junit4] 2> 1000099 T3053 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90509808269524996-127.0.0.1:8983_solr-n_0000000000) am no longer a leader.
[junit4] 2> 1000100 T3037 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=24370327
[junit4] 2> 1000100 T3039 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1418e30ea980004, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 1000102 T3061 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 1000105 T3061 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4] 2> 1000105 T3061 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4] 2> 1000105 T3061 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 1000105 T3061 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 1000105 T3061 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 1000112 T3061 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 1000114 T3061 oasc.Overseer.start Overseer (id=90509808269524998-127.0.0.1:8983_solr-n_0000000001) starting
[junit4] 2> 1000120 T3068 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 1000121 T3067 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 1000123 T3067 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 1000124 T3067 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":null,
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 1000135 T3061 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 1000852 T3037 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 1000852 T3037 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 1000856 T3037 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@ba38ff
[junit4] 2> 1000878 T3037 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=0,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=0,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1000878 T3037 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1000878 T3037 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1000878 T3037 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1000880 T3037 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1000881 T3037 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1000881 T3037 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index;done=false>>]
[junit4] 2> 1000881 T3037 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\index
[junit4] 2> 1000881 T3037 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\;done=false>>]
[junit4] 2> 1000881 T3037 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1381070072459\data2\
[junit4] 2> 1000881 T3067 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90509808269524998-127.0.0.1:8983_solr-n_0000000001) am no longer a leader.
[junit4] 2> 1000884 T3061 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 1000886 T3037 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:59893 59893
[junit4] 2> 1002579 T3037 oas.SolrTestCaseJ4.tearDown ###Ending testSliceStateUpdate
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SliceStateUpdateTest -Dtests.method=testSliceStateUpdate -Dtests.seed=D4FE494F8DD5F007 -Dtests.slow=true -Dtests.locale=iw -Dtests.timezone=Atlantic/South_Georgia -Dtests.file.encoding=UTF-8
[junit4] FAILURE 47.6s | SliceStateUpdateTest.testSliceStateUpdate <<<
[junit4] > Throwable #1: org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
[junit4] > at __randomizedtesting.SeedInfo.seed([D4FE494F8DD5F007:49CC403D3002A83]:0)
[junit4] > at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 1002609 T3037 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=CheapBastard, sim=DefaultSimilarity, locale=iw, timezone=Atlantic/South_Georgia
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=1,free=31775816,total=282853376
[junit4] 2> NOTE: All tests run in this JVM: [TestPseudoReturnFields, ClusterStateTest, SolrCoreCheckLockOnStartupTest, FullSolrCloudDistribCmdsTest, TestPostingsSolrHighlighter, TestSystemIdResolver, URLClassifyProcessorTest, DirectUpdateHandlerTest, LoggingHandlerTest, ShardSplitTest, DefaultValueUpdateProcessorTest, ZkNodePropsTest, AlternateDirectoryTest, TestCollationField, BasicZkTest, CurrencyFieldOpenExchangeTest, HighlighterConfigTest, DocValuesMultiTest, TestRemoteStreaming, JsonLoaderTest, DocumentBuilderTest, TestRealTimeGet, SolrInfoMBeanTest, SimplePostToolTest, TestSolr4Spatial, HdfsCollectionsAPIDistributedZkTest, StatelessScriptUpdateProcessorFactoryTest, SpellPossibilityIteratorTest, BasicDistributedZk2Test, TestUniqueKeyFieldResource, DocumentAnalysisRequestHandlerTest, TestCloudManagedSchema, UniqFieldsUpdateProcessorFactoryTest, DistanceFunctionTest, TestSolrQueryParserDefaultOperatorResource, RequiredFieldsTest, MinimalSchemaTest, TestReloadAndDeleteDocs, TestDynamicFieldResource, DOMUtilTest, TestIndexSearcher, SpellingQueryConverterTest, QueryElevationComponentTest, BasicDistributedZkTest, TestValueSourceCache, TestNoOpRegenerator, SolrCoreTest, JSONWriterTest, TestAnalyzeInfixSuggestions, TimeZoneUtilsTest, TestManagedSchemaFieldResource, SpellCheckCollatorTest, OutputWriterTest, ChaosMonkeySafeLeaderTest, ConvertedLegacyTest, ZkSolrClientTest, TestSolrXmlPersistor, TestPerFieldSimilarity, TestSearchPerf, RAMDirectoryFactoryTest, BinaryUpdateRequestHandlerTest, TestDFRSimilarityFactory, TestStressLucene, SolrXmlInZkTest, TestSolrIndexConfig, TestBM25SimilarityFactory, NoCacheHeaderTest, TestWriterPerf, TermsComponentTest, DistributedSpellCheckComponentTest, SignatureUpdateProcessorFactoryTest, TestBinaryField, TestSchemaVersionResource, PreAnalyzedUpdateProcessorTest, TestElisionMultitermQuery, RequestHandlersTest, SearchHandlerTest, ShardRoutingTest, TestLMJelinekMercerSimilarityFactory, SolrCmdDistributorTest, XsltUpdateRequestHandlerTest, DocValuesMissingTest, TestLFUCache, CoreMergeIndexesAdminHandlerTest, CoreContainerCoreInitFailuresTest, TestCSVResponseWriter, UpdateParamsTest, TestFastLRUCache, TestRecovery, DebugComponentTest, TestQueryUtils, SyncSliceTest, BJQParserTest, CoreAdminHandlerTest, AssignTest, TestQuerySenderListener, TestIndexingPerformance, CSVRequestHandlerTest, LukeRequestHandlerTest, InfoHandlerTest, TestSolrXMLSerializer, CacheHeaderTest, TestConfig, TestImplicitCoreProperties, TestRandomFaceting, LeaderElectionIntegrationTest, TestFiltering, TestFastOutputStream, SuggesterWFSTTest, AutoCommitTest, SliceStateUpdateTest]
[junit4] Completed in 47.69s, 1 test, 1 failure <<< FAILURES!
[...truncated 650 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:422: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:402: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:451: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1230: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:873: There were test failures: 326 suites, 1448 tests, 1 failure, 43 ignored (11 assumptions)
Total time: 58 minutes 13 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b106 -client -XX:+UseParallelGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-trunk-Windows (64bit/jdk1.7.0_40) - Build #
3328 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-trunk-Windows/3328/
Java: 64bit/jdk1.7.0_40 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
1 tests failed.
REGRESSION: org.apache.solr.cloud.ShardSplitTest.testDistribSearch
Error Message:
Wrong doc count on shard1_0 expected:<126> but was:<86>
Stack Trace:
java.lang.AssertionError: Wrong doc count on shard1_0 expected:<126> but was:<86>
at __randomizedtesting.SeedInfo.seed([F7126FF511792A3D:76F4E1ED66264A01]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.junit.Assert.failNotEquals(Assert.java:647)
at org.junit.Assert.assertEquals(Assert.java:128)
at org.junit.Assert.assertEquals(Assert.java:472)
at org.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:326)
at org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:201)
at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:111)
at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
at sun.reflect.GeneratedMethodAccessor53.invoke(Unknown Source)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
at java.lang.reflect.Method.invoke(Method.java:606)
at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java: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:724)
Build Log:
[...truncated 10545 lines...]
[junit4] Suite: org.apache.solr.cloud.ShardSplitTest
[junit4] 2> 3033561 T8017 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /iw_smt/mj
[junit4] 2> 3033566 T8017 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\solrtest-ShardSplitTest-1381098750270
[junit4] 2> 3033571 T8017 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 3033573 T8018 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 3033681 T8017 oasc.ZkTestServer.run start zk server on port:54033
[junit4] 2> 3033686 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3033700 T8024 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@6a801f67 name:ZooKeeperConnection Watcher:127.0.0.1:54033 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3033702 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3033702 T8017 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 3033713 T8019 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1418fe681500000, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 3033720 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3033726 T8026 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5f1bffb5 name:ZooKeeperConnection Watcher:127.0.0.1:54033/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3033728 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3033729 T8017 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 3033743 T8017 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 3033755 T8017 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 3033762 T8017 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 3033774 T8017 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig-tlog.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 3033775 T8017 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 3033789 T8017 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema15.xml to /configs/conf1/schema.xml
[junit4] 2> 3033789 T8017 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 3033801 T8017 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3033802 T8017 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 3033811 T8017 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 3033812 T8017 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 3033823 T8017 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 3033824 T8017 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 3033832 T8017 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 3033835 T8017 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 3033845 T8017 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 3033846 T8017 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 3033855 T8017 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3033857 T8017 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 3033865 T8017 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 3033867 T8017 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 3033877 T8017 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 3033879 T8017 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 3034482 T8017 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3034495 T8017 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54040
[junit4] 2> 3034496 T8017 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3034497 T8017 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3034498 T8017 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1381098750593
[junit4] 2> 3034498 T8017 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1381098750593\'
[junit4] 2> 3034556 T8017 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1381098750593\solr.xml
[junit4] 2> 3034672 T8017 oasc.CoreContainer.<init> New CoreContainer 1602254133
[junit4] 2> 3034672 T8017 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1381098750593\]
[junit4] 2> 3034675 T8017 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 3034676 T8017 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 3034676 T8017 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3034676 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3034676 T8017 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3034677 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3034677 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3034677 T8017 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3034678 T8017 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3034678 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 3034683 T8017 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3034684 T8017 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3034684 T8017 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54033/solr
[junit4] 2> 3034685 T8017 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3034687 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3034703 T8037 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@33af5873 name:ZooKeeperConnection Watcher:127.0.0.1:54033 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3034704 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3034706 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 3034709 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3034713 T8039 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@181aa538 name:ZooKeeperConnection Watcher:127.0.0.1:54033/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3034714 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3034716 T8017 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 3034725 T8017 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 3034734 T8017 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 3034739 T8017 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54040_iw_smt%2Fmj
[junit4] 2> 3034741 T8017 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54040_iw_smt%2Fmj
[junit4] 2> 3034755 T8017 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 3034768 T8017 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 3034774 T8017 oasc.Overseer.start Overseer (id=90511687698874371-127.0.0.1:54040_iw_smt%2Fmj-n_0000000000) starting
[junit4] 2> 3034781 T8017 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 3034791 T8041 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 3034793 T8017 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 3034798 T8017 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 3034802 T8017 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3034808 T8040 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 3034820 T8042 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 3034820 T8042 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 3034822 T8042 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3036434 T8040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3036435 T8040 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:54040/iw_smt/mj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54040_iw_smt%2Fmj",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 3036435 T8040 oasc.Overseer$ClusterStateUpdater.createCollection Create collection control_collection with shards [shard1]
[junit4] 2> 3036435 T8040 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 3036441 T8039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 3036979 T8042 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 3036979 T8042 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1381098750593\collection1
[junit4] 2> 3036979 T8042 oasc.ZkController.createCollectionZkNode Check for collection zkNode:control_collection
[junit4] 2> 3036980 T8042 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3036980 T8042 oasc.ZkController.readConfigName Load collection config from:/collections/control_collection
[junit4] 2> 3036983 T8042 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1381098750593\collection1\'
[junit4] 2> 3036985 T8042 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-controljetty-1381098750593/collection1/lib/classes/' to classloader
[junit4] 2> 3036986 T8042 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-controljetty-1381098750593/collection1/lib/README' to classloader
[junit4] 2> 3037059 T8042 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 3037137 T8042 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3037139 T8042 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3037150 T8042 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3037843 T8042 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3037847 T8042 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3037849 T8042 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3037855 T8042 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3037891 T8042 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3037891 T8042 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-controljetty-1381098750593\collection1\, dataDir=.\org.apache.solr.cloud.ShardSplitTest-1381098750269/control/data\
[junit4] 2> 3037891 T8042 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2603826d
[junit4] 2> 3037892 T8042 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1381098750269/control/data\
[junit4] 2> 3037892 T8042 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ShardSplitTest-1381098750269/control/data\index/
[junit4] 2> 3037892 T8042 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ShardSplitTest-1381098750269\control\data\index' doesn't exist. Creating new index...
[junit4] 2> 3037892 T8042 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1381098750269/control/data\index
[junit4] 2> 3037893 T8042 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.873046875, noCFSRatio=0.0]
[junit4] 2> 3037893 T8042 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@101016a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a5f853d),segFN=segments_1,generation=1}
[junit4] 2> 3037894 T8042 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3037897 T8042 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 3037897 T8042 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3037898 T8042 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3037898 T8042 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3037898 T8042 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3037899 T8042 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3037899 T8042 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3037900 T8042 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3037900 T8042 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3037900 T8042 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3037902 T8042 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 3037902 T8042 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 3037903 T8042 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3037903 T8042 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3037903 T8042 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 3037913 T8042 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3037919 T8042 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3037920 T8042 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3037920 T8042 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 3037922 T8042 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@101016a7 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4a5f853d),segFN=segments_1,generation=1}
[junit4] 2> 3037922 T8042 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3037923 T8042 oass.SolrIndexSearcher.<init> Opening Searcher@59cae7e9 main
[junit4] 2> 3037926 T8043 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@59cae7e9 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 3037927 T8042 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3037927 T8042 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54040/iw_smt/mj collection:control_collection shard:shard1
[junit4] 2> 3037927 T8042 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 3037932 T8042 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leader_elect/shard1/election
[junit4] 2> 3037944 T8042 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 3037948 T8042 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 3037948 T8042 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 3037948 T8042 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54040/iw_smt/mj/collection1/
[junit4] 2> 3037948 T8042 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 3037948 T8042 oasc.SyncStrategy.syncToMe http://127.0.0.1:54040/iw_smt/mj/collection1/ has no replicas
[junit4] 2> 3037949 T8042 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54040/iw_smt/mj/collection1/ shard1
[junit4] 2> 3037949 T8042 oascc.SolrZkClient.makePath makePath: /collections/control_collection/leaders/shard1
[junit4] 2> 3038067 T8040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3038095 T8039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 3038123 T8042 oasc.ZkController.register We are http://127.0.0.1:54040/iw_smt/mj/collection1/ and leader is http://127.0.0.1:54040/iw_smt/mj/collection1/
[junit4] 2> 3038123 T8042 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54040/iw_smt/mj
[junit4] 2> 3038123 T8042 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 3038125 T8042 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 3038125 T8042 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 3038129 T8042 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3038133 T8017 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3038133 T8017 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3038135 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 3038138 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:
[junit4] 2> 3038145 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3038151 T8046 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@106906d1 name:ZooKeeperConnection Watcher:127.0.0.1:54033/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3038151 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3038155 T8017 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3038164 T8017 oasc.ChaosMonkey.monkeyLog monkey: init - expire sessions:true cause connection loss:true
[junit4] 2> 3038623 T8017 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3038628 T8017 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54052
[junit4] 2> 3038630 T8017 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3038631 T8017 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3038631 T8017 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.ShardSplitTest-jetty1-1381098754866
[junit4] 2> 3038631 T8017 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ShardSplitTest-jetty1-1381098754866\'
[junit4] 2> 3038665 T8017 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty1-1381098754866\solr.xml
[junit4] 2> 3038767 T8017 oasc.CoreContainer.<init> New CoreContainer 1651954137
[junit4] 2> 3038767 T8017 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.ShardSplitTest-jetty1-1381098754866\]
[junit4] 2> 3038770 T8017 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 3038770 T8017 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 3038770 T8017 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3038771 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3038771 T8017 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3038772 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3038772 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3038772 T8017 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3038773 T8017 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3038773 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 3038779 T8017 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3038779 T8017 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3038780 T8017 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54033/solr
[junit4] 2> 3038780 T8017 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3038782 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3038790 T8057 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@783b152a name:ZooKeeperConnection Watcher:127.0.0.1:54033 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3038790 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3038792 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 3038796 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3038800 T8059 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1b63030d name:ZooKeeperConnection Watcher:127.0.0.1:54033/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3038800 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3038807 T8017 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3039667 T8040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3039668 T8040 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=1 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:54040/iw_smt/mj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54040_iw_smt%2Fmj",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"control_collection",
[junit4] 2> "numShards":"1",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 3039675 T8046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 3039675 T8039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 3039675 T8059 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 1)
[junit4] 2> 3039813 T8017 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54052_iw_smt%2Fmj
[junit4] 2> 3039815 T8017 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54052_iw_smt%2Fmj
[junit4] 2> 3039822 T8059 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 3039823 T8046 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 3039823 T8039 oascc.ZkStateReader$3.process Updating live nodes... (2)
[junit4] 2> 3039843 T8060 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 3039843 T8060 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 3039846 T8060 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3041191 T8040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3041194 T8040 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:54052/iw_smt/mj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54052_iw_smt%2Fmj",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 3041195 T8040 oasc.Overseer$ClusterStateUpdater.createCollection Create collection collection1 with shards [shard1, shard2]
[junit4] 2> 3041195 T8040 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 3041209 T8059 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 3041210 T8039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 3041209 T8046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 3041846 T8060 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 3041847 T8060 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.cloud.ShardSplitTest-jetty1-1381098754866\collection1
[junit4] 2> 3041847 T8060 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 3041849 T8060 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3041849 T8060 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 3041854 T8060 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ShardSplitTest-jetty1-1381098754866\collection1\'
[junit4] 2> 3041859 T8060 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty1-1381098754866/collection1/lib/classes/' to classloader
[junit4] 2> 3041862 T8060 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty1-1381098754866/collection1/lib/README' to classloader
[junit4] 2> 3041947 T8060 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 3042030 T8060 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3042033 T8060 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3042042 T8060 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3042982 T8060 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3042989 T8060 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3042994 T8060 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3043005 T8060 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3043064 T8060 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3043066 T8060 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.cloud.ShardSplitTest-jetty1-1381098754866\collection1\, dataDir=.\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty1\
[junit4] 2> 3043066 T8060 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2603826d
[junit4] 2> 3043067 T8060 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty1\
[junit4] 2> 3043067 T8060 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty1\index/
[junit4] 2> 3043068 T8060 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ShardSplitTest-1381098750269\jetty1\index' doesn't exist. Creating new index...
[junit4] 2> 3043069 T8060 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty1\index
[junit4] 2> 3043070 T8060 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.873046875, noCFSRatio=0.0]
[junit4] 2> 3043072 T8060 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@73c3c2ee lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17831425),segFN=segments_1,generation=1}
[junit4] 2> 3043073 T8060 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3043082 T8060 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 3043083 T8060 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3043084 T8060 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3043085 T8060 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3043085 T8060 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3043086 T8060 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3043087 T8060 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3043088 T8060 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3043088 T8060 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3043090 T8060 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3043092 T8060 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 3043093 T8060 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 3043093 T8060 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3043094 T8060 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3043095 T8060 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 3043112 T8060 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3043122 T8060 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3043122 T8060 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3043124 T8060 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 3043125 T8060 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@73c3c2ee lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@17831425),segFN=segments_1,generation=1}
[junit4] 2> 3043126 T8060 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3043126 T8060 oass.SolrIndexSearcher.<init> Opening Searcher@2c6c1178 main
[junit4] 2> 3043134 T8061 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2c6c1178 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 3043136 T8060 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3043136 T8060 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54052/iw_smt/mj collection:collection1 shard:shard1
[junit4] 2> 3043137 T8060 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 3043139 T8060 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 3043155 T8060 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 3043166 T8060 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 3043167 T8060 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 3043167 T8060 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54052/iw_smt/mj/collection1/
[junit4] 2> 3043167 T8060 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 3043168 T8060 oasc.SyncStrategy.syncToMe http://127.0.0.1:54052/iw_smt/mj/collection1/ has no replicas
[junit4] 2> 3043168 T8060 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54052/iw_smt/mj/collection1/ shard1
[junit4] 2> 3043169 T8060 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 3044224 T8040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3044238 T8046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 3044239 T8039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 3044239 T8059 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 3044250 T8060 oasc.ZkController.register We are http://127.0.0.1:54052/iw_smt/mj/collection1/ and leader is http://127.0.0.1:54052/iw_smt/mj/collection1/
[junit4] 2> 3044250 T8060 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54052/iw_smt/mj
[junit4] 2> 3044250 T8060 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 3044251 T8060 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 3044251 T8060 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 3044254 T8060 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3044256 T8017 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3044257 T8017 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3044257 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 3044981 T8017 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3044991 T8017 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54061
[junit4] 2> 3044992 T8017 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3044996 T8017 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3044997 T8017 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty2-1381098760961
[junit4] 2> 3044998 T8017 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty2-1381098760961\'
[junit4] 2> 3045054 T8017 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty2-1381098760961\solr.xml
[junit4] 2> 3045194 T8017 oasc.CoreContainer.<init> New CoreContainer 997517648
[junit4] 2> 3045195 T8017 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty2-1381098760961\]
[junit4] 2> 3045199 T8017 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 3045200 T8017 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 3045201 T8017 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3045201 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3045202 T8017 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3045203 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3045203 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3045204 T8017 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3045204 T8017 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3045205 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 3045210 T8017 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3045211 T8017 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3045211 T8017 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54033/solr
[junit4] 2> 3045211 T8017 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3045214 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3045239 T8073 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@67f8853b name:ZooKeeperConnection Watcher:127.0.0.1:54033 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3045240 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3045244 T8019 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1418fe681500007, likely client has closed socket
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxn.doIO(NIOServerCnxn.java:220)
[junit4] 2> at org.apache.zookeeper.server.NIOServerCnxnFactory.run(NIOServerCnxnFactory.java:208)
[junit4] 2> at java.lang.Thread.run(Thread.java:724)
[junit4] 2>
[junit4] 2> 3045244 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 3045255 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3045259 T8075 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@39531f0c name:ZooKeeperConnection Watcher:127.0.0.1:54033/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3045260 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3045274 T8017 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3045753 T8040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3045754 T8040 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:54052/iw_smt/mj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54052_iw_smt%2Fmj",
[junit4] 2> "shard":"shard1",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node1"}
[junit4] 2> 3045762 T8046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 3045762 T8075 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 3045762 T8039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 3045762 T8059 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 2)
[junit4] 2> 3046280 T8017 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54061_iw_smt%2Fmj
[junit4] 2> 3046283 T8017 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54061_iw_smt%2Fmj
[junit4] 2> 3046288 T8059 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 3046289 T8039 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 3046289 T8075 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 3046290 T8046 oascc.ZkStateReader$3.process Updating live nodes... (3)
[junit4] 2> 3046304 T8076 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 3046304 T8076 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 3046307 T8076 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3047272 T8040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3047274 T8040 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:54061/iw_smt/mj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54061_iw_smt%2Fmj",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 3047274 T8040 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 3047274 T8040 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard2
[junit4] 2> 3047288 T8046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3047288 T8059 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3047289 T8039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3047290 T8075 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3047307 T8076 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 3047307 T8076 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty2-1381098760961\collection1
[junit4] 2> 3047307 T8076 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 3047310 T8076 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3047310 T8076 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 3047314 T8076 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty2-1381098760961\collection1\'
[junit4] 2> 3047318 T8076 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty2-1381098760961/collection1/lib/classes/' to classloader
[junit4] 2> 3047320 T8076 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty2-1381098760961/collection1/lib/README' to classloader
[junit4] 2> 3047390 T8076 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 3047468 T8076 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3047471 T8076 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3047487 T8076 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3048093 T8076 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3048097 T8076 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3048099 T8076 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3048106 T8076 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3048151 T8076 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3048152 T8076 oasc.SolrCore.<init> [collection1] Opening new SolrCore at ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty2-1381098760961\collection1\, dataDir=.\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty2\
[junit4] 2> 3048152 T8076 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2603826d
[junit4] 2> 3048153 T8076 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty2\
[junit4] 2> 3048153 T8076 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty2\index/
[junit4] 2> 3048153 T8076 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ShardSplitTest-1381098750269\jetty2\index' doesn't exist. Creating new index...
[junit4] 2> 3048154 T8076 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty2\index
[junit4] 2> 3048154 T8076 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.873046875, noCFSRatio=0.0]
[junit4] 2> 3048155 T8076 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@72d2108f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@442b6d03),segFN=segments_1,generation=1}
[junit4] 2> 3048155 T8076 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3048160 T8076 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 3048161 T8076 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3048161 T8076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3048161 T8076 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3048162 T8076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3048162 T8076 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3048163 T8076 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3048163 T8076 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3048163 T8076 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3048164 T8076 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3048165 T8076 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 3048165 T8076 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 3048165 T8076 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3048166 T8076 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3048167 T8076 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 3048176 T8076 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3048181 T8076 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3048181 T8076 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3048182 T8076 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 3048183 T8076 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@72d2108f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@442b6d03),segFN=segments_1,generation=1}
[junit4] 2> 3048183 T8076 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3048183 T8076 oass.SolrIndexSearcher.<init> Opening Searcher@5d0a5858 main
[junit4] 2> 3048192 T8077 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d0a5858 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 3048193 T8076 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3048193 T8076 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54061/iw_smt/mj collection:collection1 shard:shard2
[junit4] 2> 3048193 T8076 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 3048197 T8076 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard2/election
[junit4] 2> 3048206 T8076 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard2
[junit4] 2> 3048210 T8076 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 3048210 T8076 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 3048210 T8076 oasc.SyncStrategy.sync Sync replicas to http://127.0.0.1:54061/iw_smt/mj/collection1/
[junit4] 2> 3048211 T8076 oasc.SyncStrategy.syncReplicas Sync Success - now sync replicas to me
[junit4] 2> 3048211 T8076 oasc.SyncStrategy.syncToMe http://127.0.0.1:54061/iw_smt/mj/collection1/ has no replicas
[junit4] 2> 3048211 T8076 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:54061/iw_smt/mj/collection1/ shard2
[junit4] 2> 3048211 T8076 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard2
[junit4] 2> 3048803 T8040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3048827 T8046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3048828 T8039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3048829 T8075 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3048828 T8059 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3048881 T8076 oasc.ZkController.register We are http://127.0.0.1:54061/iw_smt/mj/collection1/ and leader is http://127.0.0.1:54061/iw_smt/mj/collection1/
[junit4] 2> 3048881 T8076 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54061/iw_smt/mj
[junit4] 2> 3048881 T8076 oasc.ZkController.checkRecovery I am the leader, no recovery necessary
[junit4] 2> 3048881 T8076 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 3048882 T8076 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 3048886 T8076 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3048889 T8017 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3048889 T8017 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3048891 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 3049324 T8017 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3049329 T8017 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54070
[junit4] 2> 3049329 T8017 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3049330 T8017 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3049330 T8017 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.cloud.ShardSplitTest-jetty3-1381098765596
[junit4] 2> 3049331 T8017 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ShardSplitTest-jetty3-1381098765596\'
[junit4] 2> 3049368 T8017 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.cloud.ShardSplitTest-jetty3-1381098765596\solr.xml
[junit4] 2> 3049444 T8017 oasc.CoreContainer.<init> New CoreContainer 719805507
[junit4] 2> 3049444 T8017 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.cloud.ShardSplitTest-jetty3-1381098765596\]
[junit4] 2> 3049447 T8017 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 3049447 T8017 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 3049447 T8017 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 3049448 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 3049448 T8017 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 3049448 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 3049448 T8017 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 3049449 T8017 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 3049449 T8017 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 3049449 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 3049453 T8017 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
[junit4] 2> 3049454 T8017 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
[junit4] 2> 3049454 T8017 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:54033/solr
[junit4] 2> 3049455 T8017 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 3049456 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3049463 T8089 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@46a4eb3 name:ZooKeeperConnection Watcher:127.0.0.1:54033 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3049464 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3049469 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 3049473 T8017 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 3049477 T8091 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@2100f55c name:ZooKeeperConnection Watcher:127.0.0.1:54033/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 3049478 T8017 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 3049485 T8017 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 3050347 T8040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3050349 T8040 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"active",
[junit4] 2> "base_url":"http://127.0.0.1:54061/iw_smt/mj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54061_iw_smt%2Fmj",
[junit4] 2> "shard":"shard2",
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node2"}
[junit4] 2> 3050363 T8046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3050364 T8059 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3050365 T8039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3050364 T8091 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3050365 T8075 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 3)
[junit4] 2> 3050492 T8017 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:54070_iw_smt%2Fmj
[junit4] 2> 3050495 T8017 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:54070_iw_smt%2Fmj
[junit4] 2> 3050504 T8059 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 3050505 T8039 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 3050505 T8075 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 3050506 T8046 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 3050506 T8091 oascc.ZkStateReader$3.process Updating live nodes... (4)
[junit4] 2> 3050526 T8092 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 3050526 T8092 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 3050530 T8092 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 3051878 T8040 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3051879 T8040 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=2 message={
[junit4] 2> "operation":"state",
[junit4] 2> "state":"down",
[junit4] 2> "base_url":"http://127.0.0.1:54070/iw_smt/mj",
[junit4] 2> "core":"collection1",
[junit4] 2> "roles":null,
[junit4] 2> "node_name":"127.0.0.1:54070_iw_smt%2Fmj",
[junit4] 2> "shard":null,
[junit4] 2> "shard_range":null,
[junit4] 2> "shard_state":"active",
[junit4] 2> "collection":"collection1",
[junit4] 2> "numShards":"2",
[junit4] 2> "core_node_name":"core_node3"}
[junit4] 2> 3051879 T8040 oasc.Overseer$ClusterStateUpdater.updateState Collection already exists with numShards=2
[junit4] 2> 3051879 T8040 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 3051887 T8059 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 3051888 T8091 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 3051887 T8046 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 3051888 T8039 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 3051889 T8075 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeDataChanged path:/clusterstate.json, has occurred - updating... (live nodes size: 4)
[junit4] 2> 3052530 T8092 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 3052530 T8092 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.cloud.ShardSplitTest-jetty3-1381098765596\collection1
[junit4] 2> 3052530 T8092 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 3052534 T8092 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 3052534 T8092 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 3052537 T8092 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.cloud.ShardSplitTest-jetty3-1381098765596\collection1\'
[junit4] 2> 3052542 T8092 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty3-1381098765596/collection1/lib/classes/' to classloader
[junit4] 2> 3052543 T8092 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-trunk-Windows/solr/build/solr-core/test/J0/org.apache.solr.cloud.ShardSplitTest-jetty3-1381098765596/collection1/lib/README' to classloader
[junit4] 2> 3052624 T8092 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_50
[junit4] 2> 3052690 T8092 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 3052692 T8092 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 3052701 T8092 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 3053313 T8092 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 3053317 T8092 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 3053318 T8092 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3053325 T8092 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 3053357 T8092 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 3053358 T8092 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.cloud.ShardSplitTest-jetty3-1381098765596\collection1\, dataDir=.\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty3\
[junit4] 2> 3053358 T8092 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@2603826d
[junit4] 2> 3053358 T8092 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty3\
[junit4] 2> 3053358 T8092 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty3\index/
[junit4] 2> 3053358 T8092 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.cloud.ShardSplitTest-1381098750269\jetty3\index' doesn't exist. Creating new index...
[junit4] 2> 3053360 T8092 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.cloud.ShardSplitTest-1381098750269/jetty3\index
[junit4] 2> 3053360 T8092 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=31, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=true, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=0.873046875, noCFSRatio=0.0]
[junit4] 2> 3053361 T8092 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3a24d96e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64e34883),segFN=segments_1,generation=1}
[junit4] 2> 3053361 T8092 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3053366 T8092 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
[junit4] 2> 3053366 T8092 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
[junit4] 2> 3053366 T8092 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 3053366 T8092 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
[junit4] 2> 3053367 T8092 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 3053367 T8092 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
[junit4] 2> 3053368 T8092 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3053368 T8092 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 3053368 T8092 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
[junit4] 2> 3053370 T8092 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 3053370 T8092 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 3053370 T8092 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 3053370 T8092 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 3053371 T8092 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 3053371 T8092 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 3053381 T8092 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 3053386 T8092 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 3053386 T8092 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 3053387 T8092 oasu.RandomMergePolicy.<init> RandomMergePolicy wrapping class org.apache.lucene.index.LogByteSizeMergePolicy: [LogByteSizeMergePolicy: minMergeSize=1677721, mergeFactor=41, maxMergeSize=2147483648, maxMergeSizeForForcedMerge=9223372036854775807, calibrateSizeByDeletes=false, maxMergeDocs=2147483647, maxCFSSegmentSizeMB=8.796093022207999E12, noCFSRatio=0.0]
[junit4] 2> 3053388 T8092 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@3a24d96e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@64e34883),segFN=segments_1,generation=1}
[junit4] 2> 3053388 T8092 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 3053388 T8092 oass.SolrIndexSearcher.<init> Opening Searcher@4a3be672 main
[junit4] 2> 3053393 T8093 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@4a3be672 main{StandardDirectoryReader(segments_1:1:nrt)}
[junit4] 2> 3053395 T8092 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 3053395 T8092 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:54070/iw_smt/mj collection:collection1 shard:shard1
[junit4] 2> 3053396 T8092 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 3053401 T8092 oasc.ZkController.register We are http://127.0.0.1:54070/iw_smt/mj/collection1/ and leader is http://127.0.0.1:54052/iw_smt/mj/collection1/
[junit4] 2> 3053402 T8092 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:54070/iw_smt/mj
[junit4] 2> 3053402 T8092 oasc.ZkController.checkRecovery Core needs to recover:collection1
[junit4] 2> 3053402 T8092 oasu.DefaultSolrCoreState.doRecovery Running recovery - first canceling any ongoing recovery
[junit4] 2> ASYNC NEW_CORE C10589 name=collection1 org.apache.solr.core.SolrCore@28db8766 url=http://127.0.0.1:54070/iw_smt/mj/collection1 node=127.0.0.1:54070_iw_smt%2Fmj C10589_STATE=coll:collection1 core:collection1 props:{state=down, base_url=http://127.0.0.1:54070/iw_smt/mj, core=collection1, node_name=127.0.0.1:54070_iw_smt%2Fmj}
[junit4] 2> 3053403 T8094 C10589 P54070 oasc.RecoveryStrategy.run Starting recovery process. core=collection1 recoveringAfterStartup=true
[junit4] 2> 3053404 T8094 C10589 P54070 oasc.RecoveryStrategy.doRecovery ###### startupVersions=[]
[junit4] 2> 3053404 T8092 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 3053404 T8094 C10589 P54070 oasc.ZkController.publish publishing core=collection1 state=recovering
[junit4] 2> 3053405 T8094 C10589 P54070 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 3053406 T8017 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build\solr-core\test\J0
[junit4] 2> 3053407 T8094 C10589 P54070 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 3053407 T8017 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 3053407 T8017 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 3053409 T8054 oasha.CoreAdminHandler.handleWaitForStateAction Going to wait for coreNodeName: core_node3, state: recovering, checkLive: true, onlyIfLeader: true
[junit4] 2> 3053809 T8017 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 3053813 T8017 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54080
[junit4] 2> 3053814 T8017 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 3053814 T8017 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 3053814 T8017 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: ..\..\..\..\..\..\..\..\..\Users\JenkinsSlave\
[...truncated too long message...]
80-127.0.0.1:54080_iw_smt%2Fmj-n_0000000004 (0)
[junit4] 1> /solr/overseer_elect/election/90511687698874378-127.0.0.1:54070_iw_smt%2Fmj-n_0000000003 (0)
[junit4] 1> /solr/overseer_elect/election/90511687698874374-127.0.0.1:54052_iw_smt%2Fmj-n_0000000001 (0)
[junit4] 1> /solr/overseer_elect/election/90511687698874371-127.0.0.1:54040_iw_smt%2Fmj-n_0000000000 (0)
[junit4] 1> /solr/overseer_elect/election/90511687698874376-127.0.0.1:54061_iw_smt%2Fmj-n_0000000002 (0)
[junit4] 1> /solr/overseer_elect/leader (0)
[junit4] 1> DATA:
[junit4] 1> {"id":"90511687698874371-127.0.0.1:54040_iw_smt%2Fmj-n_0000000000"}
[junit4] 1> /zookeeper (1)
[junit4] 1> DATA:
[junit4] 1>
[junit4] 1>
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=ShardSplitTest -Dtests.method=testDistribSearch -Dtests.seed=F7126FF511792A3D -Dtests.slow=true -Dtests.locale=no -Dtests.timezone=Mideast/Riyadh87 -Dtests.file.encoding=US-ASCII
[junit4] FAILURE 70.6s | ShardSplitTest.testDistribSearch <<<
[junit4] > Throwable #1: java.lang.AssertionError: Wrong doc count on shard1_0 expected:<126> but was:<86>
[junit4] > at __randomizedtesting.SeedInfo.seed([F7126FF511792A3D:76F4E1ED66264A01]:0)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.checkDocCountsAndShardStates(ShardSplitTest.java:326)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.splitByUniqueKeyTest(ShardSplitTest.java:201)
[junit4] > at org.apache.solr.cloud.ShardSplitTest.doTest(ShardSplitTest.java:111)
[junit4] > at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 3104140 T8017 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 70584 T8016 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=no, timezone=Mideast/Riyadh87
[junit4] 2> NOTE: Windows 7 6.1 amd64/Oracle Corporation 1.7.0_40 (64-bit)/cpus=2,threads=3,free=124046664,total=407547904
[junit4] 2> NOTE: All tests run in this JVM: [UnloadDistributedZkTest, ConvertedLegacyTest, TestBinaryField, TestSchemaNameResource, AlternateDirectoryTest, SolrCoreCheckLockOnStartupTest, HdfsChaosMonkeySafeLeaderTest, TestPerFieldSimilarity, TestRandomDVFaceting, FieldAnalysisRequestHandlerTest, TermsComponentTest, TestExtendedDismaxParser, DistributedTermsComponentTest, RecoveryZkTest, DistanceFunctionTest, TestImplicitCoreProperties, TestValueSourceCache, AnalysisAfterCoreReloadTest, TestLRUCache, TestCopyFieldCollectionResource, TestCoreDiscovery, AddSchemaFieldsUpdateProcessorFactoryTest, BJQParserTest, SolrTestCaseJ4Test, ShardRoutingCustomTest, BadIndexSchemaTest, UUIDFieldTest, TestQuerySenderNoQuery, RegexBoostProcessorTest, UniqFieldsUpdateProcessorFactoryTest, SuggesterTest, MBeansHandlerTest, TestLMJelinekMercerSimilarityFactory, SynonymTokenizerTest, TestDefaultSearchFieldResource, HdfsRecoveryZkTest, QueryEqualityTest, BasicDistributedZkTest, TestMultiCoreConfBootstrap, CoreAdminHandlerTest, RAMDirectoryFactoryTest, TestBM25SimilarityFactory, TestRandomMergePolicy, DisMaxRequestHandlerTest, TestRecoveryHdfs, TestReversedWildcardFilterFactory, TestIndexSearcher, SolrRequestParserTest, MoreLikeThisHandlerTest, TestGroupingSearch, TestRandomFaceting, TestUtils, BinaryUpdateRequestHandlerTest, TestStressRecovery, HardAutoCommitTest, TestPhraseSuggestions, TestRangeQuery, DebugComponentTest, OverseerCollectionProcessorTest, TestDefaultSimilarityFactory, TestJmxMonitoredMap, TestMaxScoreQueryParser, TestShardHandlerFactory, CollectionsAPIDistributedZkTest, XmlUpdateRequestHandlerTest, MinimalSchemaTest, TestRealTimeGet, SolrPluginUtilsTest, ZkCLITest, TestLMDirichletSimilarityFactory, QueryResultKeyTest, TestXIncludeConfig, SpatialFilterTest, TestRecovery, OverseerTest, TestAnalyzeInfixSuggestions, DistributedSpellCheckComponentTest, DocValuesTest, PrimitiveFieldTypeTest, TermVectorComponentDistributedTest, TestOmitPositions, TestCodecSupport, TestSolrDeletionPolicy2, SolrCoreTest, TestConfig, EchoParamsTest, HighlighterConfigTest, SolrIndexSplitterTest, XsltUpdateRequestHandlerTest, TestPHPSerializedResponseWriter, LeaderElectionIntegrationTest, CircularListTest, PingRequestHandlerTest, HdfsCollectionsAPIDistributedZkTest, CoreContainerCoreInitFailuresTest, HighlighterTest, TestCloudManagedSchemaAddField, BlockDirectoryTest, TestNoOpRegenerator, TestQueryUtils, StatsComponentTest, BadCopyFieldTest, TestQueryTypes, BlockCacheTest, LegacyHTMLStripCharFilterTest, ParsingFieldUpdateProcessorsTest, OutputWriterTest, BasicZkTest, SuggesterTSTTest, SpellPossibilityIteratorTest, TestSolrDeletionPolicy1, FieldMutatingUpdateProcessorTest, TestSolrJ, SystemInfoHandlerTest, TestSchemaVersionResource, TestSolrXmlPersistor, SolrCmdDistributorTest, SpellCheckComponentTest, DirectSolrConnectionTest, TestPseudoReturnFields, SimpleFacetsTest, PrimUtilsTest, HdfsUnloadDistributedZkTest, OpenExchangeRatesOrgProviderTest, SpellingQueryConverterTest, FileBasedSpellCheckerTest, TestNumberUtils, TimeZoneUtilsTest, IndexSchemaRuntimeFieldTest, TestSchemaSimilarityResource, HdfsSyncSliceTest, TermVectorComponentTest, CopyFieldTest, PeerSyncTest, SoftAutoCommitTest, TestManagedSchema, TestAnalyzedSuggestions, TestDistributedSearch, TestAddFieldRealTimeGet, ExternalFileFieldSortTest, TestSolrQueryParser, AssignTest, TestLazyCores, TestDynamicFieldResource, NoCacheHeaderTest, TestBadConfig, ChaosMonkeyNothingIsSafeTest, RequestHandlersTest, TestCSVResponseWriter, StatelessScriptUpdateProcessorFactoryTest, UpdateParamsTest, TestStressVersions, IndexBasedSpellCheckerTest, TestIndexingPerformance, TestSuggestSpellingConverter, TestZkChroot, ChaosMonkeySafeLeaderTest, BasicDistributedZk2Test, SyncSliceTest, OpenCloseCoreStressTest, ShardRoutingTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler, ClusterStateUpdateTest, LeaderElectionTest, ZkSolrClientTest, SliceStateUpdateTest, AutoCommitTest, TestDistributedGrouping, TestFaceting, TestHashPartitioner, ZkControllerTest, TestStressReorder, TestJoin, TestReload, TestCoreContainer, TestSolr4Spatial, QueryElevationComponentTest, TestSort, TestFiltering, TestFunctionQuery, BasicFunctionalityTest, DirectUpdateHandlerTest, ShowFileRequestHandlerTest, CurrencyFieldOpenExchangeTest, DistributedQueryElevationComponentTest, CurrencyFieldXmlFileTest, SimplePostToolTest, SignatureUpdateProcessorFactoryTest, SpellCheckCollatorTest, SuggesterFSTTest, TestFoldingMultitermQuery, TestStressLucene, TestTrie, SuggesterWFSTTest, TestCSVLoader, PolyFieldTest, WordBreakSolrSpellCheckerTest, SchemaVersionSpecificBehaviorTest, TestUpdate, TestAtomicUpdateErrorCases, TestWordDelimiterFilterFactory, DirectUpdateHandlerOptimizeTest, DefaultValueUpdateProcessorTest, SortByFunctionTest, TestRemoteStreaming, DocValuesMultiTest, SolrInfoMBeanTest, CacheHeaderTest, TestSurroundQueryParser, LukeRequestHandlerTest, StandardRequestHandlerTest, TestWriterPerf, DirectSolrSpellCheckerTest, DocumentAnalysisRequestHandlerTest, DocumentBuilderTest, PathHierarchyTokenizerFactoryTest, RequiredFieldsTest, TestArbitraryIndexDir, FastVectorHighlighterTest, LoggingHandlerTest, IndexSchemaTest, TestJmxIntegration, TestCollationField, ReturnFieldsTest, UpdateRequestProcessorFactoryTest, JSONWriterTest, QueryParsingTest, JsonLoaderTest, TestPartialUpdateDeduplication, CSVRequestHandlerTest, TestComponentsName, SearchHandlerTest, TestBinaryResponseWriter, TestLFUCache, SOLR749Test, TestQuerySenderListener, TestSolrIndexConfig, ResponseLogComponentTest, SolrIndexConfigTest, BadComponentTest, TestMergePolicyConfig, MultiTermTest, SampleTest, TestDocSet, TestSearchPerf, NumericFieldsTest, TestElisionMultitermQuery, TestFuzzyAnalyzedSuggestions, TestSolrCoreProperties, TestPostingsSolrHighlighter, NotRequiredUniqueKeyTest, TestLuceneMatchVersion, TestCharFilters, TestSweetSpotSimilarityFactory, TestDFRSimilarityFactory, TestIBSimilarityFactory, TestFastWriter, ResourceLoaderTest, TestFastOutputStream, ScriptEngineTest, URLClassifyProcessorTest, PluginInfoTest, TestFastLRUCache, DateMathParserTest, TestSolrXMLSerializer, PreAnalyzedFieldTest, TestSystemIdResolver, DateFieldTest, DOMUtilTest, ClusterStateTest, TestDocumentBuilder, ZkNodePropsTest, SliceStateTest, FileUtilsTest, TestRTGBase, AliasIntegrationTest, CustomCollectionTest, DeleteShardTest, ShardSplitTest]
[junit4] Completed in 70.86s, 1 test, 1 failure <<< FAILURES!
[...truncated 108 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:422: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:402: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\solr\common-build.xml:451: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:1230: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-trunk-Windows\lucene\common-build.xml:873: There were test failures: 326 suites, 1448 tests, 1 failure, 39 ignored (11 assumptions)
Total time: 80 minutes 3 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.7.0_40 -XX:+UseCompressedOops -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure