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/08/11 23:52:41 UTC
[JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.8.0-ea-b102) - Build
# 3068 - Failure!
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3068/
Java: 32bit/jdk1.8.0-ea-b102 -server -XX:+UseConcMarkSweepGC
2 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([C0AE05C640AAB9FE:10CC888A1E7F637A]: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)
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestStressReplication
Error Message:
timed out waiting for collection1 startAt time to exceed: Sun Aug 11 21:30:01 GMT 2013
Stack Trace:
java.lang.AssertionError: timed out waiting for collection1 startAt time to exceed: Sun Aug 11 21:30:01 GMT 2013
at __randomizedtesting.SeedInfo.seed([C0AE05C640AAB9FE:1B0505004582D04D]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.watchCoreStartAt(TestReplicationHandler.java:1507)
at org.apache.solr.handler.TestReplicationHandler.doTestStressReplication(TestReplicationHandler.java:811)
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 9838 lines...]
[junit4] Suite: org.apache.solr.cloud.SliceStateUpdateTest
[junit4] 2> 926421 T2095 oas.SolrTestCaseJ4.setUp ###Starting testSliceStateUpdate
[junit4] 2> Creating dataDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107
[junit4] 2> 926423 T2095 oasc.ZkTestServer.run STARTING ZK TEST SERVER
[junit4] 2> 926424 T2096 oasc.ZkTestServer$ZKServerMain.runFromConfig Starting server
[junit4] 2> 926522 T2095 oasc.ZkTestServer.run start zk server on port:57567
[junit4] 2> 926524 T2095 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 926538 T2102 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1446462 name:ZooKeeperConnection Watcher:127.0.0.1:57567 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 926539 T2095 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 926539 T2095 oascc.SolrZkClient.makePath makePath: /solr
[junit4] 2> 926555 T2095 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 926561 T2104 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1839b7a name:ZooKeeperConnection Watcher:127.0.0.1:57567/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 926562 T2095 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 926562 T2095 oascc.SolrZkClient.makePath makePath: /collections/collection1
[junit4] 2> 926570 T2095 oascc.SolrZkClient.makePath makePath: /collections/collection1/shards
[junit4] 2> 926577 T2095 oascc.SolrZkClient.makePath makePath: /collections/control_collection
[junit4] 2> 926582 T2095 oascc.SolrZkClient.makePath makePath: /collections/control_collection/shards
[junit4] 2> 926590 T2095 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.xml to /configs/conf1/solrconfig.xml
[junit4] 2> 926591 T2095 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.xml
[junit4] 2> 926600 T2095 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\schema.xml to /configs/conf1/schema.xml
[junit4] 2> 926600 T2095 oascc.SolrZkClient.makePath makePath: /configs/conf1/schema.xml
[junit4] 2> 926607 T2095 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\solrconfig.snippet.randomindexconfig.xml to /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 926609 T2095 oascc.SolrZkClient.makePath makePath: /configs/conf1/solrconfig.snippet.randomindexconfig.xml
[junit4] 2> 926616 T2095 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\stopwords.txt to /configs/conf1/stopwords.txt
[junit4] 2> 926617 T2095 oascc.SolrZkClient.makePath makePath: /configs/conf1/stopwords.txt
[junit4] 2> 926623 T2095 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\protwords.txt to /configs/conf1/protwords.txt
[junit4] 2> 926624 T2095 oascc.SolrZkClient.makePath makePath: /configs/conf1/protwords.txt
[junit4] 2> 926630 T2095 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\currency.xml to /configs/conf1/currency.xml
[junit4] 2> 926631 T2095 oascc.SolrZkClient.makePath makePath: /configs/conf1/currency.xml
[junit4] 2> 926636 T2095 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\open-exchange-rates.json to /configs/conf1/open-exchange-rates.json
[junit4] 2> 926637 T2095 oascc.SolrZkClient.makePath makePath: /configs/conf1/open-exchange-rates.json
[junit4] 2> 926643 T2095 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\mapping-ISOLatin1Accent.txt to /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 926644 T2095 oascc.SolrZkClient.makePath makePath: /configs/conf1/mapping-ISOLatin1Accent.txt
[junit4] 2> 926651 T2095 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\old_synonyms.txt to /configs/conf1/old_synonyms.txt
[junit4] 2> 926651 T2095 oascc.SolrZkClient.makePath makePath: /configs/conf1/old_synonyms.txt
[junit4] 2> 926657 T2095 oasc.AbstractZkTestCase.putConfig put C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\conf\synonyms.txt to /configs/conf1/synonyms.txt
[junit4] 2> 926659 T2095 oascc.SolrZkClient.makePath makePath: /configs/conf1/synonyms.txt
[junit4] 2> 926672 T2095 oasc.SliceStateUpdateTest.setUp ####SETUP_START testSliceStateUpdate
[junit4] 2> 926675 T2095 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 926682 T2106 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1bc0f12 name:ZooKeeperConnection Watcher:127.0.0.1:57567/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 926682 T2095 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 926683 T2095 oascc.SolrZkClient.makePath makePath: /collections/testcore
[junit4] 2> 926687 T2095 oascc.SolrZkClient.makePath makePath: /collections/testcore/shards
[junit4] 2> 926714 T2095 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 926714 T2095 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 926714 T2095 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 926764 T2095 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4] 2> 926792 T2095 oasc.CoreContainer.<init> New CoreContainer 18455849
[junit4] 2> 926792 T2095 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 926792 T2095 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr
[junit4] 2> 926793 T2095 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\'
[junit4] 2> 926821 T2095 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\solr.xml
[junit4] 2> 926831 T2095 oasc.CoreContainer.<init> New CoreContainer 25463656
[junit4] 2> 926831 T2095 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 926831 T2095 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 926832 T2095 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 926832 T2095 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 926832 T2095 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 926833 T2095 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 926834 T2095 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 926834 T2095 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 926835 T2095 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 926836 T2095 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 926836 T2095 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 926853 T2095 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57567/solr
[junit4] 2> 926853 T2095 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 926860 T2095 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 926884 T2108 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@c434d8 name:ZooKeeperConnection Watcher:127.0.0.1:57567 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 926885 T2095 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 926891 T2095 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 926900 T2095 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 926908 T2110 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@162e0c0 name:ZooKeeperConnection Watcher:127.0.0.1:57567/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 926908 T2095 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 926919 T2095 oascc.SolrZkClient.makePath makePath: /overseer/queue
[junit4] 2> 926942 T2095 oascc.SolrZkClient.makePath makePath: /overseer/collection-queue-work
[junit4] 2> 926952 T2095 oascc.SolrZkClient.makePath makePath: /live_nodes
[junit4] 2> 926957 T2095 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 926961 T2095 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 926969 T2095 oascc.SolrZkClient.makePath makePath: /overseer_elect/election
[junit4] 2> 926979 T2095 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 926984 T2095 oasc.Overseer.start Overseer (id=90194330066354180-127.0.0.1:8983_solr-n_0000000000) starting
[junit4] 2> 926994 T2095 oascc.SolrZkClient.makePath makePath: /overseer/queue-work
[junit4] 2> 927004 T2095 oascc.SolrZkClient.makePath makePath: /clusterstate.json
[junit4] 2> 927006 T2112 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 927012 T2095 oascc.SolrZkClient.makePath makePath: /aliases.json
[junit4] 2> 927016 T2095 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 927021 T2111 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 927025 T2113 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 927026 T2113 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 927031 T2113 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 928484 T2111 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 928485 T2111 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 928485 T2111 oasc.Overseer$ClusterStateUpdater.updateState Assigning new node to shard shard=shard1
[junit4] 2> 928500 T2110 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> 928933 T2113 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 928933 T2113 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 928933 T2113 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 928934 T2113 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 928935 T2113 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 928937 T2113 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 928940 T2113 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 928941 T2113 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 929011 T2113 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 929047 T2113 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 929050 T2113 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 929058 T2113 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 929226 T2113 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 929228 T2113 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 929228 T2113 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 929238 T2113 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 929241 T2113 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 929264 T2113 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 929268 T2113 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 929272 T2113 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 929275 T2113 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 929275 T2113 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 929275 T2113 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 929276 T2113 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 929277 T2113 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 929277 T2113 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 929278 T2113 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\
[junit4] 2> 929278 T2113 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ee9719
[junit4] 2> 929279 T2113 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\
[junit4] 2> 929279 T2113 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index/
[junit4] 2> 929279 T2113 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index' doesn't exist. Creating new index...
[junit4] 2> 929280 T2113 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index
[junit4] 2> 929281 T2113 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@135bdb1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@12e6fcc)),segFN=segments_1,generation=1}
[junit4] 2> 929281 T2113 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 929284 T2113 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 929291 T2113 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 929291 T2113 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 929292 T2113 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 929292 T2113 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 929293 T2113 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 929293 T2113 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 929293 T2113 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 929294 T2113 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 929294 T2113 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 929295 T2113 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 929295 T2113 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 929295 T2113 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 929295 T2113 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 929295 T2113 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 929295 T2113 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 929296 T2113 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 929296 T2113 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 929297 T2113 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 929298 T2113 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 929298 T2113 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 929298 T2113 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 929299 T2113 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 929299 T2113 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 929299 T2113 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 929300 T2113 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 929300 T2113 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 929308 T2113 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 929309 T2113 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 929316 T2113 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> 929318 T2113 oass.SolrIndexSearcher.<init> Opening Searcher@57eb0f main
[junit4] 2> 929321 T2113 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 929321 T2113 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 929322 T2113 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 929342 T2113 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 929415 T2113 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 929415 T2113 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 929415 T2113 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 929417 T2114 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@57eb0f main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 929425 T2113 oascc.SolrZkClient.makePath makePath: /collections/collection1/leader_elect/shard1/election
[junit4] 2> 929467 T2113 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 929472 T2113 oasc.ShardLeaderElectionContext.waitForReplicasToComeUp Enough replicas found to continue.
[junit4] 2> 929473 T2113 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 929473 T2113 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 929473 T2113 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 929847 T2111 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 929868 T2110 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> 929893 T2113 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> 929893 T2113 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 929893 T2113 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 929893 T2113 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 929893 T2113 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 929897 T2113 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 929898 T2095 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\]
[junit4] 2> 929899 T2095 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 120000
[junit4] 2> 929899 T2095 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 929899 T2095 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
[junit4] 2> 929899 T2095 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 929900 T2095 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 929900 T2095 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 929901 T2095 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 929901 T2095 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 929902 T2095 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 929902 T2095 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=120000&connTimeout=15000&retry=false
[junit4] 2> 929912 T2095 oasc.ZkContainer.initZooKeeper Zookeeper client=127.0.0.1:57567/solr
[junit4] 2> 929913 T2095 oasc.ZkController.checkChrootPath zkHost includes chroot
[junit4] 2> 929920 T2095 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 929921 T2117 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@5f7ca4 name:ZooKeeperConnection Watcher:127.0.0.1:57567 got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 929922 T2095 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 929925 T2095 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=500&maxConnectionsPerHost=16&socketTimeout=120000&connTimeout=15000
[junit4] 2> 929934 T2095 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 929942 T2119 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@13a1947 name:ZooKeeperConnection Watcher:127.0.0.1:57567/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 929942 T2095 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 929950 T2095 oascc.ZkStateReader.createClusterStateWatchersAndUpdate Updating cluster state from ZooKeeper...
[junit4] 2> 930845 T2095 oasc.ZkController.createEphemeralLiveNode Register node as live in ZooKeeper:/live_nodes/127.0.0.1:8983_solr
[junit4] 2> 930849 T2095 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> 930849 T2095 oascc.SolrZkClient.makePath makePath: /live_nodes/127.0.0.1:8983_solr
[junit4] 2> 930849 T2119 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 930849 T2110 oascc.ZkStateReader$3.process Updating live nodes... (0)
[junit4] 2> 930849 T2110 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 0)
[junit4] 2> 930866 T2110 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 930866 T2119 oascc.ZkStateReader$3.process Updating live nodes... (1)
[junit4] 2> 930866 T2110 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 930876 T2120 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 930876 T2120 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 930884 T2120 oasc.ZkController.waitForCoreNodeName look for our core node name
[junit4] 2> 930884 T2120 oasc.ZkController.waitForShardId waiting to find shard id in clusterstate for collection1
[junit4] 2> 930884 T2120 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1
[junit4] 2> 930884 T2120 oasc.ZkController.createCollectionZkNode Check for collection zkNode:collection1
[junit4] 2> 930886 T2120 oasc.ZkController.createCollectionZkNode Collection zkNode exists
[junit4] 2> 930886 T2120 oasc.ZkController.readConfigName Load collection config from:/collections/collection1
[junit4] 2> 930889 T2120 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\'
[junit4] 2> 930892 T2120 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/classes/' to classloader
[junit4] 2> 930893 T2120 oasc.SolrResourceLoader.replaceClassLoader Adding 'file:/C:/Users/JenkinsSlave/workspace/Lucene-Solr-4.x-Windows/solr/build/solr-core/test-files/solr/collection1/lib/README' to classloader
[junit4] 2> 930957 T2120 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 930989 T2120 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 930993 T2120 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 930996 T2120 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 931141 T2120 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 931142 T2120 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
[junit4] 2> 931142 T2120 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
[junit4] 2> 931148 T2120 oass.IndexSchema.readSchema default search field in schema is text
[junit4] 2> 931150 T2120 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 931168 T2120 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 931171 T2120 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
[junit4] 2> 931176 T2120 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 931177 T2120 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 931177 T2120 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 931177 T2120 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
[junit4] 2> 931180 T2120 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
[junit4] 2> 931180 T2120 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
[junit4] 2> 931181 T2120 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 931181 T2120 oasc.SolrCore.<init> [collection1] Opening new SolrCore at C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test-files\solr\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\
[junit4] 2> 931181 T2120 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@1ee9719
[junit4] 2> 931182 T2120 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\
[junit4] 2> 931182 T2120 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index/
[junit4] 2> 931182 T2120 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index' doesn't exist. Creating new index...
[junit4] 2> 931183 T2120 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index
[junit4] 2> 931184 T2120 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=RateLimitedDirectoryWrapper(MockDirWrapper(org.apache.lucene.store.RAMDirectory@12847c9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1f8dc03)),segFN=segments_1,generation=1}
[junit4] 2> 931184 T2120 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 931184 T2120 oasc.SolrCore.initWriters created xml: solr.XMLResponseWriter
[junit4] 2> 931187 T2120 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
[junit4] 2> 931187 T2120 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe-allfields"
[junit4] 2> 931188 T2120 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
[junit4] 2> 931189 T2120 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "uniq-fields"
[junit4] 2> 931189 T2120 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
[junit4] 2> 931190 T2120 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
[junit4] 2> 931190 T2120 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 931190 T2120 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 931191 T2120 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
[junit4] 2> 931191 T2120 oasc.RequestHandlers.initHandlersFromConfig created dismax: solr.SearchHandler
[junit4] 2> 931192 T2120 oasc.RequestHandlers.initHandlersFromConfig created mock: org.apache.solr.core.MockQuerySenderListenerReqHandler
[junit4] 2> 931192 T2120 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
[junit4] 2> 931192 T2120 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 931193 T2120 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 931193 T2120 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 931194 T2120 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 931194 T2120 oasc.RequestHandlers.initHandlersFromConfig created /terms: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 931195 T2120 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 931195 T2120 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 931196 T2120 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 931196 T2120 oasc.RequestHandlers.initHandlersFromConfig created spellCheckWithWordbreak_Direct: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 931197 T2120 oasc.RequestHandlers.initHandlersFromConfig created spellCheckCompRH1: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 931197 T2120 oasc.RequestHandlers.initHandlersFromConfig created mltrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 931197 T2120 oasc.RequestHandlers.initHandlersFromConfig created tvrh: org.apache.solr.handler.component.SearchHandler
[junit4] 2> 931198 T2120 oasc.RequestHandlers.initHandlersFromConfig created /mlt: solr.MoreLikeThisHandler
[junit4] 2> 931198 T2120 oasc.RequestHandlers.initHandlersFromConfig created /debug/dump: solr.DumpRequestHandler
[junit4] 2> 931206 T2120 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 931207 T2120 oasc.SolrCore.initDeprecatedSupport WARN solrconfig.xml uses deprecated <admin/gettableFiles>, Please update your config to use the ShowFileRequestHandler.
[junit4] 2> 931209 T2120 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> 931211 T2120 oass.SolrIndexSearcher.<init> Opening Searcher@1ab6f89 main
[junit4] 2> 931213 T2120 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 931213 T2120 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 931213 T2120 oashc.SpellCheckComponent.inform Initializing spell checkers
[junit4] 2> 931218 T2120 oass.DirectSolrSpellChecker.init init: {name=direct,classname=DirectSolrSpellChecker,field=lowerfilt,minQueryLength=3}
[junit4] 2> 931267 T2120 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 931268 T2120 oasc.ZkController.register Register replica - core:collection1 address:http://127.0.0.1:8983/solr collection:collection1 shard:shard1
[junit4] 2> 931268 T2120 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=10000&maxConnectionsPerHost=20&connTimeout=15000&socketTimeout=30000&retry=false
[junit4] 2> 931288 T2121 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ab6f89 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 931296 T2120 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> 931296 T2120 oasc.ZkController.register No LogReplay needed for core=collection1 baseURL=http://127.0.0.1:8983/solr
[junit4] 2> 931296 T2120 oasc.ZkController.checkRecovery WARN Skipping recovery according to sys prop solrcloud.skip.autorecovery
[junit4] 2> 931297 T2120 oasc.ZkController.publish publishing core=collection1 state=active
[junit4] 2> 931297 T2120 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 931300 T2120 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 931301 T2111 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 931304 T2111 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 931305 T2095 oasc.SliceStateUpdateTest.setUp ####SETUP_END testSliceStateUpdate
[junit4] 2> 931307 T2095 oascc.ConnectionManager.waitForConnected Waiting for client to connect to ZooKeeper
[junit4] 2> 931317 T2124 oascc.ConnectionManager.process Watcher org.apache.solr.common.cloud.ConnectionManager@1742e90 name:ZooKeeperConnection Watcher:127.0.0.1:57567/solr got event WatchedEvent state:SyncConnected type:None path:null path:null type:None
[junit4] 2> 931317 T2095 oascc.ConnectionManager.waitForConnected Client is connected to ZooKeeper
[junit4] 2> 931322 T2119 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> 931323 T2110 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> 931329 T2111 oasc.Overseer$ClusterStateUpdater.updateState node=core_node1 is already registered
[junit4] 2> 931329 T2111 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 931329 T2111 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 931346 T2111 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"active",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 931354 T2119 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> 970576 T2095 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=18455849
[junit4] 2> 970750 T2111 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 970755 T2111 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "shard":null,
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 970755 T2111 oasc.Overseer$ClusterStateUpdater.updateState shard=shard1 is already registered
[junit4] 2> 970769 T2119 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> 970770 T2110 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> 972589 T2095 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 972589 T2095 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 972597 T2095 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@c12bed
[junit4] 2> 972604 T2095 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> 972605 T2095 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 972605 T2095 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 972605 T2095 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 972607 T2095 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 972608 T2095 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 972608 T2095 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\;done=false>>]
[junit4] 2> 972609 T2095 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\
[junit4] 2> 972609 T2095 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index;done=false>>]
[junit4] 2> 972609 T2095 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index
[junit4] 2> 972612 T2111 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90194330066354180-127.0.0.1:8983_solr-n_0000000000) am no longer a leader.
[junit4] 2> 972615 T2095 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=25463656
[junit4] 2> 972615 T2097 oazs.NIOServerCnxn.doIO WARN caught end of stream exception EndOfStreamException: Unable to read additional data from client sessionid 0x1406f43ec5c0004, 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> 972618 T2119 oascc.SolrZkClient.makePath makePath: /overseer_elect/leader
[junit4] 2> 972623 T2119 oasc.Overseer.start Overseer (id=90194330066354182-127.0.0.1:8983_solr-n_0000000001) starting
[junit4] 2> 972635 T2126 oasc.OverseerCollectionProcessor.run Process current queue of collection creations
[junit4] 2> 972636 T2125 oasc.Overseer$ClusterStateUpdater.run Starting to work on the main queue
[junit4] 2> 972640 T2119 oasc.ShardLeaderElectionContext.runLeaderProcess Running the leader process for shard shard1
[junit4] 2> 972647 T2125 oascc.ZkStateReader.updateClusterState Updating cloud state from ZooKeeper...
[junit4] 2> 972647 T2119 oasc.ShardLeaderElectionContext.shouldIBeLeader Checking if I should try and be the leader.
[junit4] 2> 972647 T2119 oasc.ShardLeaderElectionContext.shouldIBeLeader My last published State was Active, it's okay to be the leader.
[junit4] 2> 972648 T2119 oasc.ShardLeaderElectionContext.runLeaderProcess I may be the new leader - try and sync
[junit4] 2> 972648 T2119 oasc.ShardLeaderElectionContext.runLeaderProcess I am the new leader: http://127.0.0.1:8983/solr/collection1/ shard1
[junit4] 2> 972648 T2119 oascc.SolrZkClient.makePath makePath: /collections/collection1/leaders/shard1
[junit4] 2> 972649 T2125 oasc.Overseer$ClusterStateUpdater.updateState Update state numShards=null message={
[junit4] 2> "operation":"state",
[junit4] 2> "core_node_name":"core_node1",
[junit4] 2> "numShards":null,
[junit4] 2> "shard":"shard1",
[junit4] 2> "roles":null,
[junit4] 2> "state":"down",
[junit4] 2> "shard_state":"active",
[junit4] 2> "core":"collection1",
[junit4] 2> "collection":"collection1",
[junit4] 2> "shard_range":null,
[junit4] 2> "node_name":"127.0.0.1:8983_solr",
[junit4] 2> "base_url":"http://127.0.0.1:8983/solr"}
[junit4] 2> 972679 T2119 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> 973616 T2095 oasc.ZkController.publish publishing core=collection1 state=down
[junit4] 2> 973616 T2095 oasc.ZkController.publish numShards not found on descriptor - reading it from system property
[junit4] 2> 973637 T2095 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@1e0800a
[junit4] 2> 973640 T2095 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> 973641 T2095 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 973641 T2095 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 973641 T2095 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 973643 T2095 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 973644 T2095 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 973644 T2095 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\;done=false>>]
[junit4] 2> 973644 T2095 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\
[junit4] 2> 973644 T2095 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index;done=false>>]
[junit4] 2> 973644 T2095 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\solrtest-SliceStateUpdateTest-1376256257107\data2\index
[junit4] 2> 973659 T2125 oasc.Overseer$ClusterStateUpdater.amILeader According to ZK I (id=90194330066354182-127.0.0.1:8983_solr-n_0000000001) am no longer a leader.
[junit4] 2> 973715 T2119 oascc.ZkStateReader$2.process A cluster state change: WatchedEvent state:SyncConnected type:NodeChildrenChanged path:/live_nodes, has occurred - updating... (live nodes size: 1)
[junit4] 2> 973717 T2095 oasc.ZkTestServer.send4LetterWord connecting to 127.0.0.1:57567 57567
[junit4] 2> 975017 T2119 oascc.ZkStateReader$2.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> 975970 T2095 oas.SolrTestCaseJ4.tearDown ###Ending testSliceStateUpdate
[junit4] 1> client port:0.0.0.0/0.0.0.0:0
[junit4] 2> 975990 T2119 oascc.ZkStateReader$3.process WARN ZooKeeper watch triggered, but Solr cannot talk to ZK
[junit4] 2> NOTE: reproduce with: ant test -Dtestcase=SliceStateUpdateTest -Dtests.method=testSliceStateUpdate -Dtests.seed=C0AE05C640AAB9FE -Dtests.slow=true -Dtests.locale=ar_DZ -Dtests.timezone=Canada/Eastern -Dtests.file.encoding=UTF-8
[junit4] FAILURE 49.6s | SliceStateUpdateTest.testSliceStateUpdate <<<
[junit4] > Throwable #1: org.junit.ComparisonFailure: expected:<[in]active> but was:<[]active>
[junit4] > at __randomizedtesting.SeedInfo.seed([C0AE05C640AAB9FE:10CC888A1E7F637A]:0)
[junit4] > at org.apache.solr.cloud.SliceStateUpdateTest.testSliceStateUpdate(SliceStateUpdateTest.java:183)
[junit4] > at java.lang.Thread.run(Thread.java:724)
[junit4] 2> 975991 T2095 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> NOTE: test params are: codec=DummyCompressingStoredFields(storedFieldsFormat=CompressingStoredFieldsFormat(compressionMode=DUMMY, chunkSize=285), termVectorsFormat=CompressingTermVectorsFormat(compressionMode=DUMMY, chunkSize=285)), sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=ar_DZ, timezone=Canada/Eastern
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=1,free=75708480,total=200654848
[junit4] 2> NOTE: All tests run in this JVM: [InfoHandlerTest, ShardRoutingTest, TestExtendedDismaxParser, TestCoreDiscovery, TestPartialUpdateDeduplication, TestElisionMultitermQuery, TestCSVResponseWriter, SynonymTokenizerTest, XsltUpdateRequestHandlerTest, PluginInfoTest, PreAnalyzedUpdateProcessorTest, AliasIntegrationTest, ExternalFileFieldSortTest, SliceStateTest, TestHashPartitioner, SpellCheckCollatorTest, TestSchemaNameResource, TestUniqueKeyFieldResource, SOLR749Test, SpatialFilterTest, ScriptEngineTest, CircularListTest, QueryElevationComponentTest, TestGroupingSearch, TestPseudoReturnFields, HdfsRecoveryZkTest, CurrencyFieldOpenExchangeTest, BinaryUpdateRequestHandlerTest, StatelessScriptUpdateProcessorFactoryTest, PreAnalyzedFieldTest, SoftAutoCommitTest, BadCopyFieldTest, SuggesterTest, DistributedSpellCheckComponentTest, DateMathParserTest, CopyFieldTest, TestRangeQuery, DocumentBuilderTest, TestSort, TestSchemaSimilarityResource, OutputWriterTest, TestLuceneMatchVersion, TestIndexingPerformance, TestSolrCoreProperties, TestRemoteStreaming, TestDynamicFieldResource, ChaosMonkeyNothingIsSafeTest, TestValueSourceCache, PrimUtilsTest, PathHierarchyTokenizerFactoryTest, TestFastWriter, TestSolrJ, TestSchemaVersionResource, SolrIndexSplitterTest, TestPerFieldSimilarity, AddSchemaFieldsUpdateProcessorFactoryTest, TestSolrQueryParserDefaultOperatorResource, HdfsUnloadDistributedZkTest, TestUpdate, HdfsBasicDistributedZkTest, SolrPluginUtilsTest, TestSolr4Spatial, SimplePostToolTest, SortByFunctionTest, SimpleFacetsTest, TestCoreContainer, SignatureUpdateProcessorFactoryTest, TestBinaryResponseWriter, TestPhraseSuggestions, TestPostingsSolrHighlighter, IndexSchemaTest, TestFuzzyAnalyzedSuggestions, TestCodecSupport, TestLMDirichletSimilarityFactory, TestBadConfig, TestMultiCoreConfBootstrap, AutoCommitTest, EchoParamsTest, CoreContainerCoreInitFailuresTest, HdfsSyncSliceTest, SuggesterWFSTTest, QueryParsingTest, FileBasedSpellCheckerTest, TestSolrXml, SearchHandlerTest, SolrCoreTest, StandardRequestHandlerTest, LegacyHTMLStripCharFilterTest, BasicDistributedZk2Test, TestAtomicUpdateErrorCases, TestBinaryField, ShardSplitTest, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, OverseerTest, NumericFieldsTest, TestFastLRUCache, UpdateRequestProcessorFactoryTest, ZkControllerTest, TestReversedWildcardFilterFactory, TestIndexSearcher, TestQueryUtils, SliceStateUpdateTest]
[junit4] Completed in 49.71s, 1 test, 1 failure <<< FAILURES!
[...truncated 144 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 1264008 T2852 oas.SolrTestCaseJ4.setUp ###Starting doTestStressReplication
[junit4] 2> 1264032 T2852 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1264059 T2852 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57975
[junit4] 2> 1264059 T2852 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1264060 T2852 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1264060 T2852 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master
[junit4] 2> 1264061 T2852 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\'
[junit4] 2> 1264103 T2852 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\solr.xml
[junit4] 2> 1264105 T2852 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\solr.xml does not exist, using default configuration
[junit4] 2> 1264143 T2852 oasc.CoreContainer.<init> New CoreContainer 10713466
[junit4] 2> 1264144 T2852 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\]
[junit4] 2> 1264145 T2852 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1264145 T2852 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1264145 T2852 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1264146 T2852 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1264147 T2852 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1264147 T2852 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1264147 T2852 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1264148 T2852 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1264148 T2852 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1264149 T2852 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1264165 T2863 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\collection1
[junit4] 2> 1264165 T2863 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\collection1\'
[junit4] 2> 1264203 T2863 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1264216 T2863 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1264217 T2863 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1264222 T2863 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1264228 T2863 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1264228 T2863 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1264229 T2863 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\collection1\data\
[junit4] 2> 1264229 T2863 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1264230 T2863 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\collection1\data\
[junit4] 2> 1264230 T2863 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\collection1\data\index/
[junit4] 2> 1264230 T2863 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1264231 T2863 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594694\master\collection1\data\index
[junit4] 2> 1264231 T2863 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@3f89d3 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@15fd96d),segFN=segments_1,generation=1}
[junit4] 2> 1264231 T2863 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1264233 T2863 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1264234 T2863 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1264234 T2863 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1264234 T2863 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1264234 T2863 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1264235 T2863 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1264242 T2863 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1264244 T2863 oass.SolrIndexSearcher.<init> Opening Searcher@184f0c0 main
[junit4] 2> 1264244 T2863 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1264244 T2863 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1264245 T2863 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 1264245 T2863 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 1264245 T2863 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1264246 T2864 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@184f0c0 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1264248 T2852 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1264248 T2852 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1264248 T2852 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1264272 T2852 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1264278 T2852 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57978
[junit4] 2> 1264279 T2852 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1264279 T2852 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1264280 T2852 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave
[junit4] 2> 1264280 T2852 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\'
[junit4] 2> 1264337 T2852 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\solr.xml
[junit4] 2> 1264339 T2852 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\solr.xml does not exist, using default configuration
[junit4] 2> 1264365 T2852 oasc.CoreContainer.<init> New CoreContainer 14389969
[junit4] 2> 1264366 T2852 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\]
[junit4] 2> 1264367 T2852 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1264367 T2852 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1264367 T2852 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1264368 T2852 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1264368 T2852 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1264368 T2852 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1264369 T2852 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1264370 T2852 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1264370 T2852 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1264371 T2852 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1264392 T2877 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1
[junit4] 2> 1264392 T2877 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\'
[junit4] 2> 1264421 T2877 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1264433 T2877 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1264434 T2877 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 1264438 T2877 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 1264449 T2877 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 1264450 T2877 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 1264450 T2877 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\
[junit4] 2> 1264450 T2877 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 1264451 T2877 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\
[junit4] 2> 1264451 T2877 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\index/
[junit4] 2> 1264451 T2877 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 1264452 T2877 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\index
[junit4] 2> 1264452 T2877 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@1fade1f lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1136808),segFN=segments_1,generation=1}
[junit4] 2> 1264453 T2877 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 1264454 T2877 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 1264454 T2877 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 1264454 T2877 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 1264454 T2877 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 1264454 T2877 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 1264454 T2877 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 1264457 T2877 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 1264458 T2877 oass.SolrIndexSearcher.<init> Opening Searcher@b421c9 main
[junit4] 2> 1264458 T2877 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 1264458 T2877 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 1264467 T2877 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 1264467 T2877 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 1264467 T2878 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@b421c9 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 1264468 T2852 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 1264469 T2852 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 1264469 T2852 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 1264496 T2852 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=14389969
[junit4] 2> 1264500 T2852 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@e92945
[junit4] 2> 1264503 T2852 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> 1264503 T2852 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1264504 T2852 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1264504 T2852 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1264505 T2852 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1264506 T2852 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1264506 T2852 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\;done=false>>]
[junit4] 2> 1264506 T2852 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\
[junit4] 2> 1264507 T2852 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\index;done=false>>]
[junit4] 2> 1264507 T2852 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\data\index
[junit4] 2> 1264508 T2852 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1265015 T2852 oejut.QueuedThreadPool.doStop WARN 7 threads could not be stopped
[junit4] 2> 1265017 T2852 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 1265024 T2852 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57981
[junit4] 2> 1265025 T2852 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 1265025 T2852 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 1265026 T2852 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave
[junit4] 2> 1265027 T2852 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\'
[junit4] 2> 1265049 T2852 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\solr.xml
[junit4] 2> 1265050 T2852 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\solr.xml does not exist, using default configuration
[junit4] 2> 1265069 T2852 oasc.CoreContainer.<init> New CoreContainer 4128015
[junit4] 2> 1265070 T2852 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\]
[junit4] 2> 1265071 T2852 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 1265072 T2852 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 1265072 T2852 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 1265073 T2852 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 1265073 T2852 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 1265073 T2852 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 1265073 T2852 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 1265074 T2852 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 1265074 T2852 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 1265075 T2852 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 1265094 T2890 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1
[junit4] 2> 1265094 T2890 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256594941\slave\collection1\'
[junit4] 2> 1265130 T2890 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 1265144 T2890 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 1265145 T2890 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit
[...truncated too long message...]
oryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1349606 T2852 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676670\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676670\master\collection1\data\;done=false>>]
[junit4] 2> 1349607 T2852 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676670\master\collection1\data\
[junit4] 2> 1349607 T2852 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676670\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676670\master\collection1\data\index;done=false>>]
[junit4] 2> 1349608 T2852 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676670\master\collection1\data\index
[junit4] 2> 1349608 T2852 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1349677 T2852 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=15690685
[junit4] 2> 1349679 T2852 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@168bb5f
[junit4] 2> 1349679 T2852 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=1,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 1349680 T2852 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 1349680 T2852 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 1349680 T2852 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 1349682 T2852 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 1349683 T2852 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 1349683 T2852 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676814\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676814\slave\collection1\data\;done=false>>]
[junit4] 2> 1349683 T2852 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676814\slave\collection1\data\
[junit4] 2> 1349685 T2852 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676814\slave\collection1\data\index.20130811213119994 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676814\slave\collection1\data\index.20130811213119994;done=false>>]
[junit4] 2> 1349685 T2852 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376256676814\slave\collection1\data\index.20130811213119994
[junit4] 2> 1349686 T2852 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 1350327 T2852 oejut.QueuedThreadPool.doStop WARN 7 threads could not be stopped
[junit4] 2> 1350372 T2852 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 86375 T2851 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 6 leaked thread(s).
[junit4] 2> NOTE: test params are: codec=Lucene41, sim=DefaultSimilarity, locale=el_CY, timezone=Africa/Dakar
[junit4] 2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=1,free=374151080,total=523501568
[junit4] 2> NOTE: All tests run in this JVM: [InfoHandlerTest, ShardRoutingTest, TestExtendedDismaxParser, TestCoreDiscovery, TestPartialUpdateDeduplication, TestElisionMultitermQuery, TestCSVResponseWriter, SynonymTokenizerTest, XsltUpdateRequestHandlerTest, PluginInfoTest, PreAnalyzedUpdateProcessorTest, AliasIntegrationTest, ExternalFileFieldSortTest, SliceStateTest, TestHashPartitioner, SpellCheckCollatorTest, TestSchemaNameResource, TestUniqueKeyFieldResource, SOLR749Test, SpatialFilterTest, ScriptEngineTest, CircularListTest, QueryElevationComponentTest, TestGroupingSearch, TestPseudoReturnFields, HdfsRecoveryZkTest, CurrencyFieldOpenExchangeTest, BinaryUpdateRequestHandlerTest, StatelessScriptUpdateProcessorFactoryTest, PreAnalyzedFieldTest, SoftAutoCommitTest, BadCopyFieldTest, SuggesterTest, DistributedSpellCheckComponentTest, DateMathParserTest, CopyFieldTest, TestRangeQuery, DocumentBuilderTest, TestSort, TestSchemaSimilarityResource, OutputWriterTest, TestLuceneMatchVersion, TestIndexingPerformance, TestSolrCoreProperties, TestRemoteStreaming, TestDynamicFieldResource, ChaosMonkeyNothingIsSafeTest, TestValueSourceCache, PrimUtilsTest, PathHierarchyTokenizerFactoryTest, TestFastWriter, TestSolrJ, TestSchemaVersionResource, SolrIndexSplitterTest, TestPerFieldSimilarity, AddSchemaFieldsUpdateProcessorFactoryTest, TestSolrQueryParserDefaultOperatorResource, HdfsUnloadDistributedZkTest, TestUpdate, HdfsBasicDistributedZkTest, SolrPluginUtilsTest, TestSolr4Spatial, SimplePostToolTest, SortByFunctionTest, SimpleFacetsTest, TestCoreContainer, SignatureUpdateProcessorFactoryTest, TestBinaryResponseWriter, TestPhraseSuggestions, TestPostingsSolrHighlighter, IndexSchemaTest, TestFuzzyAnalyzedSuggestions, TestCodecSupport, TestLMDirichletSimilarityFactory, TestBadConfig, TestMultiCoreConfBootstrap, AutoCommitTest, EchoParamsTest, CoreContainerCoreInitFailuresTest, HdfsSyncSliceTest, SuggesterWFSTTest, QueryParsingTest, FileBasedSpellCheckerTest, TestSolrXml, SearchHandlerTest, SolrCoreTest, StandardRequestHandlerTest, LegacyHTMLStripCharFilterTest, BasicDistributedZk2Test, TestAtomicUpdateErrorCases, TestBinaryField, ShardSplitTest, DocumentAnalysisRequestHandlerTest, PrimitiveFieldTypeTest, OverseerTest, NumericFieldsTest, TestFastLRUCache, UpdateRequestProcessorFactoryTest, ZkControllerTest, TestReversedWildcardFilterFactory, TestIndexSearcher, TestQueryUtils, SliceStateUpdateTest, HighlighterConfigTest, PingRequestHandlerTest, TestManagedSchema, SpellingQueryConverterTest, AssignTest, DeleteShardTest, FastVectorHighlighterTest, TestDefaultSearchFieldResource, ParsingFieldUpdateProcessorsTest, TestDFRSimilarityFactory, DistanceFunctionTest, TestRandomDVFaceting, CoreMergeIndexesAdminHandlerTest, TestReload, DirectUpdateHandlerOptimizeTest, OpenCloseCoreStressTest, TestReloadAndDeleteDocs, ResponseLogComponentTest, TestIBSimilarityFactory, TestJoin, SolrRequestParserTest, FieldAnalysisRequestHandlerTest, RAMDirectoryFactoryTest, TestComponentsName, TestOmitPositions, CachingDirectoryFactoryTest, URLClassifyProcessorTest, UniqFieldsUpdateProcessorFactoryTest, DirectUpdateHandlerTest, HdfsBasicDistributedZk2Test, ZkCLITest, StressHdfsTest, TestRandomFaceting, TestSolrDeletionPolicy1, FileUtilsTest, TestXIncludeConfig, ReturnFieldsTest, BadComponentTest, TestAddFieldRealTimeGet, DateFieldTest, QueryResultKeyTest, DefaultValueUpdateProcessorTest, TestReplicationHandler]
[junit4] Completed in 86.67s, 14 tests, 1 failure <<< FAILURES!
[...truncated 517 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:395: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:375: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:181: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:449: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1233: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:876: There were test failures: 319 suites, 1350 tests, 2 failures, 44 ignored (11 assumptions)
Total time: 66 minutes 39 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b102 -server -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure
[JENKINS] Lucene-Solr-4.x-Windows (64bit/jdk1.6.0_45) - Build #
3069 - Still Failing!
Posted by Policeman Jenkins Server <je...@thetaphi.de>.
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3069/
Java: 64bit/jdk1.6.0_45 -XX:+UseCompressedOops -XX:+UseSerialGC
1 tests failed.
REGRESSION: org.apache.solr.handler.TestReplicationHandler.doTestBackup
Error Message:
Backup success not detected:<?xml version="1.0" encoding="UTF-8"?> <response> <lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><lst name="details"><str name="indexSize">21.75 KB</str><str name="indexPath">C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282526642\master\collection1\data\index/</str><arr name="commits"><lst><long name="indexVersion">1376282528982</long><long name="generation">2</long><arr name="filelist"><str>_0.cfe</str><str>_0.cfs</str><str>_0.si</str><str>segments_2</str></arr></lst></arr><str name="isMaster">true</str><str name="isSlave">false</str><long name="indexVersion">1376282528982</long><long name="generation">2</long><lst name="master"><str name="confFiles">schema-replication2.xml:schema.xml</str><arr name="replicateAfter"><str>commit</str></arr><str name="replicationEnabled">true</str><long name="replicableVersion">1376282528982</long><long name="replicableGeneration">2</long></lst><lst name="backup"><str name="startTime">Mon Aug 12 14:42:09 EST 2013</str><int name="fileCount">4</int><str name="status">success</str><str name="snapshotCompletedAt">Mon Aug 12 14:42:09 EST 2013</str></lst></lst><str name="WARNING">This response format is experimental. It is likely to change in the future.</str> </response>
Stack Trace:
java.lang.AssertionError: Backup success not detected:<?xml version="1.0" encoding="UTF-8"?>
<response>
<lst name="responseHeader"><int name="status">0</int><int name="QTime">0</int></lst><lst name="details"><str name="indexSize">21.75 KB</str><str name="indexPath">C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282526642\master\collection1\data\index/</str><arr name="commits"><lst><long name="indexVersion">1376282528982</long><long name="generation">2</long><arr name="filelist"><str>_0.cfe</str><str>_0.cfs</str><str>_0.si</str><str>segments_2</str></arr></lst></arr><str name="isMaster">true</str><str name="isSlave">false</str><long name="indexVersion">1376282528982</long><long name="generation">2</long><lst name="master"><str name="confFiles">schema-replication2.xml:schema.xml</str><arr name="replicateAfter"><str>commit</str></arr><str name="replicationEnabled">true</str><long name="replicableVersion">1376282528982</long><long name="replicableGeneration">2</long></lst><lst name="backup"><str name="startTime">Mon Aug 12 14:42:09 EST 2013</str><int name="fileCount">4</int><str name="status">success</str><str name="snapshotCompletedAt">Mon Aug 12 14:42:09 EST 2013</str></lst></lst><str name="WARNING">This response format is experimental. It is likely to change in the future.</str>
</response>
at __randomizedtesting.SeedInfo.seed([8ABE15EEA4AE81B3:CB35358B831072FC]:0)
at org.junit.Assert.fail(Assert.java:93)
at org.apache.solr.handler.TestReplicationHandler.doTestBackup(TestReplicationHandler.java:1395)
at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:39)
at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:25)
at java.lang.reflect.Method.invoke(Method.java:597)
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:662)
Build Log:
[...truncated 9285 lines...]
[junit4] Suite: org.apache.solr.handler.TestReplicationHandler
[junit4] 2> 2426315 T5594 oas.SolrTestCaseJ4.setUp ###Starting doTestDetails
[junit4] 2> 2426329 T5594 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2426335 T5594 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57204
[junit4] 2> 2426336 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2426336 T5594 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2426336 T5594 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master
[junit4] 2> 2426337 T5594 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\'
[junit4] 2> 2426362 T5594 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\solr.xml
[junit4] 2> 2426364 T5594 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\solr.xml does not exist, using default configuration
[junit4] 2> 2426428 T5594 oasc.CoreContainer.<init> New CoreContainer 1937434737
[junit4] 2> 2426428 T5594 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\]
[junit4] 2> 2426429 T5594 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2426429 T5594 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2426430 T5594 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2426430 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2426430 T5594 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2426431 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2426431 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2426431 T5594 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2426431 T5594 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2426432 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2426446 T5604 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1
[junit4] 2> 2426446 T5604 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\'
[junit4] 2> 2426522 T5604 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 2426583 T5604 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2426583 T5604 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2426589 T5604 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2426611 T5604 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2426613 T5604 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2426613 T5604 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\
[junit4] 2> 2426613 T5604 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2426613 T5604 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\
[junit4] 2> 2426613 T5604 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\index/
[junit4] 2> 2426613 T5604 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2426614 T5604 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\index
[junit4] 2> 2426615 T5604 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6f03ec19 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36b52bc9),segFN=segments_1,generation=1}
[junit4] 2> 2426615 T5604 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2426617 T5604 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2426617 T5604 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2426618 T5604 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2426618 T5604 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2426618 T5604 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2426618 T5604 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2426624 T5604 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2426626 T5604 oass.SolrIndexSearcher.<init> Opening Searcher@47abced0 main
[junit4] 2> 2426626 T5604 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2426626 T5604 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2426626 T5604 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2426626 T5604 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2426627 T5604 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2426628 T5605 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@47abced0 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2426628 T5594 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2426628 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2426629 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 2426644 T5594 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2426647 T5594 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57207
[junit4] 2> 2426647 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2426649 T5594 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2426649 T5594 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave
[junit4] 2> 2426649 T5594 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\'
[junit4] 2> 2426664 T5594 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\solr.xml
[junit4] 2> 2426665 T5594 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\solr.xml does not exist, using default configuration
[junit4] 2> 2426726 T5594 oasc.CoreContainer.<init> New CoreContainer 97874532
[junit4] 2> 2426726 T5594 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\]
[junit4] 2> 2426728 T5594 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2426728 T5594 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2426728 T5594 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2426729 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2426729 T5594 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2426730 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2426730 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2426730 T5594 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2426731 T5594 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2426731 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2426741 T5616 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1
[junit4] 2> 2426741 T5616 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\'
[junit4] 2> 2426813 T5616 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 2426869 T5616 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2426869 T5616 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2426876 T5616 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2426896 T5616 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2426897 T5616 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2426897 T5616 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\
[junit4] 2> 2426898 T5616 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2426898 T5616 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\
[junit4] 2> 2426898 T5616 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index/
[junit4] 2> 2426898 T5616 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2426899 T5616 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index
[junit4] 2> 2426899 T5616 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11ae29f9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f2fde6b),segFN=segments_1,generation=1}
[junit4] 2> 2426899 T5616 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2426900 T5616 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2426900 T5616 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2426900 T5616 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2426900 T5616 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2426901 T5616 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2426901 T5616 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2426903 T5616 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2426906 T5616 oass.SolrIndexSearcher.<init> Opening Searcher@3677da68 main
[junit4] 2> 2426906 T5616 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2426906 T5616 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2426907 T5616 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2426907 T5616 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2426907 T5617 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3677da68 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2426908 T5594 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2426908 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2426909 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C5125 name=collection1 org.apache.solr.core.SolrCore@331d09e3
[junit4] 2> 2426921 T5599 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/select params={sort=id+desc&q=*:*&wt=javabin&version=2} hits=0 status=0 QTime=1
[junit4] 2> 2426924 T5600 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2426938 T5601 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C5126 name=collection1 org.apache.solr.core.SolrCore@282f439b
[junit4] 2> 2426939 T5610 C5126 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=4
[junit4] 2> 2426942 T5602 C5125 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6f03ec19 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36b52bc9),segFN=segments_1,generation=1}
[junit4] 2> 2426943 T5602 C5125 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2426943 T5602 C5125 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[0]} 0 2
[junit4] 2> 2426945 T5603 C5125 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2426946 T5603 C5125 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6f03ec19 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36b52bc9),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6f03ec19 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36b52bc9),segFN=segments_2,generation=2}
[junit4] 2> 2426946 T5603 C5125 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2426947 T5603 C5125 oass.SolrIndexSearcher.<init> Opening Searcher@479fcab5 main
[junit4] 2> 2426948 T5603 C5125 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2426948 T5605 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@479fcab5 main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):c1)}
[junit4] 2> 2426949 T5603 C5125 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 4
[junit4] 2> 2426951 T5620 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2426951 T5620 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2426953 T5598 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2426954 T5620 oash.SnapPuller.fetchLatestIndex Master's generation: 2
[junit4] 2> 2426954 T5620 oash.SnapPuller.fetchLatestIndex Slave's generation: 1
[junit4] 2> 2426954 T5620 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2426958 T5599 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=2&version=2} status=0 QTime=2
[junit4] 2> 2426959 T5620 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 4
[junit4] 2> 2426960 T5620 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159470
[junit4] 2> 2426960 T5620 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@236149a9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7d7fb85d) fullCopy=false
[junit4] 2> 2426961 T5600 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2426965 T5601 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2426968 T5602 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_2&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2426972 T5603 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_0.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=2} status=0 QTime=0
[junit4] 2> 2426973 T5620 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2426975 T5620 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2426975 T5620 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2426975 T5620 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2426976 T5620 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11ae29f9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f2fde6b),segFN=segments_1,generation=1}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11ae29f9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f2fde6b),segFN=segments_2,generation=2}
[junit4] 2> 2426976 T5620 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
[junit4] 2> 2426976 T5620 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2426977 T5620 oass.SolrIndexSearcher.<init> Opening Searcher@78664e55 main
[junit4] 2> 2426978 T5617 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@78664e55 main{StandardDirectoryReader(segments_2:3:nrt _0(4.5):c1)}
[junit4] 2> 2426978 T5620 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159470 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159470;done=true>>]
[junit4] 2> 2426978 T5620 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159470
[junit4] 2> 2426979 T5611 C5126 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:57204/solr/replication&command=fetchindex&wait=true} status=0 QTime=29
[junit4] 2> 2426985 T5598 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2426987 T5612 C5126 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=5
[junit4] 2> 2426990 T5599 C5125 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[1]} 0 1
[junit4] 2> 2426992 T5600 C5125 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2426995 T5600 C5125 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6f03ec19 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36b52bc9),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6f03ec19 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36b52bc9),segFN=segments_3,generation=3}
[junit4] 2> 2426995 T5600 C5125 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2426997 T5600 C5125 oass.SolrIndexSearcher.<init> Opening Searcher@2caed897 main
[junit4] 2> 2426998 T5600 C5125 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2426998 T5605 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2caed897 main{StandardDirectoryReader(segments_3:5:nrt _0(4.5):c1 _1(4.5):c1)}
[junit4] 2> 2427000 T5600 C5125 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 8
[junit4] 2> 2427002 T5623 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2427002 T5623 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2427005 T5601 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2427005 T5623 oash.SnapPuller.fetchLatestIndex Master's generation: 3
[junit4] 2> 2427005 T5623 oash.SnapPuller.fetchLatestIndex Slave's generation: 2
[junit4] 2> 2427005 T5623 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2427009 T5602 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=3&version=2} status=0 QTime=1
[junit4] 2> 2427010 T5623 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 7
[junit4] 2> 2427010 T5623 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159521
[junit4] 2> 2427010 T5623 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@74180a67 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@76b7a440) fullCopy=false
[junit4] 2> 2427011 T5623 oash.SnapPuller.downloadIndexFiles Skipping download for _0.cfs because it already exists
[junit4] 2> 2427012 T5623 oash.SnapPuller.downloadIndexFiles Skipping download for _0.cfe because it already exists
[junit4] 2> 2427014 T5603 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2427016 T5623 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 2427018 T5598 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2427021 T5599 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_3&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2427025 T5600 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_1.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=3} status=0 QTime=0
[junit4] 2> 2427027 T5623 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2427031 T5623 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2427031 T5623 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2427032 T5623 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2427034 T5623 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2427035 T5623 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11ae29f9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f2fde6b),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11ae29f9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f2fde6b),segFN=segments_3,generation=3}
[junit4] 2> 2427036 T5623 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
[junit4] 2> 2427036 T5623 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2427039 T5623 oass.SolrIndexSearcher.<init> Opening Searcher@531e286a main
[junit4] 2> 2427039 T5617 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@531e286a main{StandardDirectoryReader(segments_3:5:nrt _0(4.5):c1 _1(4.5):c1)}
[junit4] 2> 2427040 T5623 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159521 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159521;done=true>>]
[junit4] 2> 2427040 T5623 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159521
[junit4] 2> 2427041 T5613 C5126 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:57204/solr/replication&command=fetchindex&wait=true} status=0 QTime=40
[junit4] 2> 2427047 T5601 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2427050 T5614 C5126 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=6
[junit4] 2> 2427053 T5602 C5125 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={wt=javabin&version=2} {add=[2]} 0 1
[junit4] 2> 2427057 T5603 C5125 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
[junit4] 2> 2427060 T5603 C5125 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=3
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6f03ec19 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36b52bc9),segFN=segments_2,generation=2}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6f03ec19 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36b52bc9),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@6f03ec19 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@36b52bc9),segFN=segments_4,generation=4}
[junit4] 2> 2427060 T5603 C5125 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 2427062 T5603 C5125 oass.SolrIndexSearcher.<init> Opening Searcher@182dc007 main
[junit4] 2> 2427063 T5603 C5125 oasu.DirectUpdateHandler2.commit end_commit_flush
[junit4] 2> 2427063 T5605 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@182dc007 main{StandardDirectoryReader(segments_4:7:nrt _0(4.5):c1 _1(4.5):c1 _2(4.5):c1)}
[junit4] 2> 2427065 T5603 C5125 oasup.LogUpdateProcessor.finish [collection1] webapp=/solr path=/update params={waitSearcher=true&commit=true&wt=javabin&version=2&softCommit=false} {commit=} 0 9
[junit4] 2> 2427066 T5625 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2427066 T5625 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2427068 T5598 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2427069 T5625 oash.SnapPuller.fetchLatestIndex Master's generation: 4
[junit4] 2> 2427069 T5625 oash.SnapPuller.fetchLatestIndex Slave's generation: 3
[junit4] 2> 2427070 T5625 oash.SnapPuller.fetchLatestIndex Starting replication process
[junit4] 2> 2427074 T5599 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=filelist&qt=/replication&wt=javabin&generation=4&version=2} status=0 QTime=2
[junit4] 2> 2427075 T5625 oash.SnapPuller.fetchLatestIndex Number of files in latest index in master: 10
[junit4] 2> 2427076 T5625 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159587
[junit4] 2> 2427076 T5625 oash.SnapPuller.fetchLatestIndex Starting download to MockDirWrapper(org.apache.lucene.store.RAMDirectory@33127c03 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@22b948f2) fullCopy=false
[junit4] 2> 2427080 T5600 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.si&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=1
[junit4] 2> 2427081 T5625 oash.SnapPuller.downloadIndexFiles Skipping download for _0.cfs because it already exists
[junit4] 2> 2427081 T5625 oash.SnapPuller.downloadIndexFiles Skipping download for _0.cfe because it already exists
[junit4] 2> 2427081 T5625 oash.SnapPuller.downloadIndexFiles Skipping download for _1.si because it already exists
[junit4] 2> 2427081 T5625 oash.SnapPuller.downloadIndexFiles Skipping download for _0.si because it already exists
[junit4] 2> 2427081 T5625 oash.SnapPuller.downloadIndexFiles Skipping download for _1.cfs because it already exists
[junit4] 2> 2427085 T5601 C5125 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.cfs&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> ASYNC NEW_CORE C5127 name=collection1 org.apache.solr.core.SolrCore@331d09e3
[junit4] 2> 2427102 T5602 C5127 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=_2.cfe&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2427105 T5603 C5127 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={file=segments_4&command=filecontent&checksum=true&wt=filestream&qt=/replication&generation=4} status=0 QTime=0
[junit4] 2> 2427105 T5625 oash.SnapPuller.downloadIndexFiles Skipping download for _1.cfe because it already exists
[junit4] 2> 2427106 T5625 oash.SnapPuller.fetchLatestIndex Total time taken for download : 0 secs
[junit4] 2> 2427107 T5625 oasu.DefaultSolrCoreState.closeIndexWriter Closing IndexWriter...
[junit4] 2> 2427107 T5625 oasu.DefaultSolrCoreState.closeIndexWriter Waiting until IndexWriter is unused... core=collection1
[junit4] 2> 2427107 T5625 oasu.DefaultSolrCoreState.closeIndexWriter Rollback old IndexWriter... core=collection1
[junit4] 2> 2427108 T5625 oasu.DefaultSolrCoreState.openIndexWriter Creating new IndexWriter...
[junit4] 2> 2427157 T5625 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=2
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11ae29f9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f2fde6b),segFN=segments_3,generation=3}
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@11ae29f9 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@6f2fde6b),segFN=segments_4,generation=4}
[junit4] 2> 2427157 T5625 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
[junit4] 2> 2427157 T5625 oasu.DefaultSolrCoreState.openIndexWriter New IndexWriter is ready to be used.
[junit4] 2> 2427160 T5625 oass.SolrIndexSearcher.<init> Opening Searcher@29bd07a6 main
[junit4] 2> 2427161 T5617 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@29bd07a6 main{StandardDirectoryReader(segments_4:7:nrt _0(4.5):c1 _1(4.5):c1 _2(4.5):c1)}
[junit4] 2> 2427161 T5625 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159587 [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159587;done=true>>]
[junit4] 2> 2427162 T5625 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index.20130812144159587
[junit4] 2> ASYNC NEW_CORE C5128 name=collection1 org.apache.solr.core.SolrCore@282f439b
[junit4] 2> 2427162 T5610 C5128 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={masterUrl=http://127.0.0.1:57204/solr/replication&command=fetchindex&wait=true} status=0 QTime=96
[junit4] 2> 2427189 T5594 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2427193 T5594 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57214
[junit4] 2> 2427193 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2427194 T5594 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2427194 T5594 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater
[junit4] 2> 2427194 T5594 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\'
[junit4] 2> 2427216 T5594 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\solr.xml
[junit4] 2> 2427217 T5594 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\solr.xml does not exist, using default configuration
[junit4] 2> 2427276 T5594 oasc.CoreContainer.<init> New CoreContainer 853456332
[junit4] 2> 2427277 T5594 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\]
[junit4] 2> 2427278 T5594 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2427278 T5594 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2427278 T5594 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2427278 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2427279 T5594 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2427279 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2427279 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2427279 T5594 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2427279 T5594 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2427281 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2427292 T5637 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1
[junit4] 2> 2427292 T5637 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\'
[junit4] 2> 2427345 T5637 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 2427401 T5637 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2427401 T5637 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2427407 T5637 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2427442 T5637 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2427444 T5637 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2427445 T5637 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\
[junit4] 2> 2427445 T5637 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2427445 T5637 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\
[junit4] 2> 2427445 T5637 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\index/
[junit4] 2> 2427445 T5637 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2427445 T5637 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\index
[junit4] 2> 2427446 T5637 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@51f43b12 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@4780f067),segFN=segments_1,generation=1}
[junit4] 2> 2427446 T5637 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2427448 T5637 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2427448 T5637 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2427448 T5637 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2427448 T5637 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2427449 T5637 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2427449 T5637 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2427450 T5637 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2427454 T5637 oass.SolrIndexSearcher.<init> Opening Searcher@1a9100af main
[junit4] 2> 2427455 T5637 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2427455 T5637 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2427455 T5637 oash.SnapPuller.<init> WARN 'masterUrl' must be specified without the /replication suffix
[junit4] 2> 2427455 T5637 oash.SnapPuller.<init> No value set for 'pollInterval'. Timer Task not started.
[junit4] 2> 2427455 T5637 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml
[junit4] 2> 2427456 T5637 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2427456 T5637 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2427456 T5638 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1a9100af main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2427457 T5594 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2427457 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2427457 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> ASYNC NEW_CORE C5129 name=collection1 org.apache.solr.core.SolrCore@331d09e3
[junit4] 2> 2427470 T5600 C5129 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=indexversion&qt=/replication&wt=javabin&version=2} status=0 QTime=0
[junit4] 2> 2427470 T5599 C5129 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={slave=false&command=details&wt=javabin&qt=/replication&version=2} status=0 QTime=0
[junit4] 2> 2427471 T5618 oash.SnapPuller.fetchLatestIndex Slave in sync with master.
[junit4] 2> ASYNC NEW_CORE C5130 name=collection1 org.apache.solr.core.SolrCore@776498ff
[junit4] 2> 2427471 T5630 C5130 oasc.SolrCore.execute [collection1] webapp=/solr path=/replication params={command=details&_trace=getDetails&wt=javabin&qt=/replication&version=2} status=0 QTime=3
[junit4] 2> 2427484 T5594 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=853456332
[junit4] 2> 2427486 T5594 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@776498ff
[junit4] 2> 2427486 T5594 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> 2427487 T5594 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2427487 T5594 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2427488 T5594 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2427489 T5594 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2427489 T5594 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2427490 T5594 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\;done=false>>]
[junit4] 2> 2427490 T5594 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\
[junit4] 2> 2427490 T5594 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\index;done=false>>]
[junit4] 2> 2427491 T5594 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519675\repeater\collection1\data\index
[junit4] 2> 2427493 T5594 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2427561 T5594 oas.SolrTestCaseJ4.tearDown ###Ending doTestDetails
[junit4] 2> 2427571 T5594 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1937434737
[junit4] 2> 2427573 T5594 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@331d09e3
[junit4] 2> 2427573 T5594 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=3,autocommits=0,soft autocommits=0,optimizes=0,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=3,cumulative_deletesById=0,cumulative_deletesByQuery=0,cumulative_errors=0}
[junit4] 2> 2427573 T5594 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2427574 T5594 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2427574 T5594 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2427576 T5594 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2427578 T5594 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2427578 T5594 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\index;done=false>>]
[junit4] 2> 2427579 T5594 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\index
[junit4] 2> 2427579 T5594 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\;done=false>>]
[junit4] 2> 2427579 T5594 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282518827\master\collection1\data\
[junit4] 2> 2427581 T5594 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2427644 T5594 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=97874532
[junit4] 2> 2427645 T5594 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@282f439b
[junit4] 2> 2427646 T5594 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> 2427646 T5594 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2427648 T5594 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2427648 T5594 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2427651 T5594 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2427651 T5594 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2427652 T5594 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index;done=false>>]
[junit4] 2> 2427654 T5594 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\index
[junit4] 2> 2427655 T5594 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\;done=false>>]
[junit4] 2> 2427655 T5594 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282519145\slave\collection1\data\
[junit4] 2> 2427656 T5594 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2427737 T5594 oas.SolrTestCaseJ4.setUp ###Starting doTestStressReplication
[junit4] 2> 2427749 T5594 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2427752 T5594 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57220
[junit4] 2> 2427752 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2427752 T5594 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2427752 T5594 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master
[junit4] 2> 2427752 T5594 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\'
[junit4] 2> 2427776 T5594 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\solr.xml
[junit4] 2> 2427777 T5594 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\solr.xml does not exist, using default configuration
[junit4] 2> 2427837 T5594 oasc.CoreContainer.<init> New CoreContainer 270636498
[junit4] 2> 2427837 T5594 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\]
[junit4] 2> 2427839 T5594 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2427839 T5594 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2427839 T5594 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2427840 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2427840 T5594 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2427840 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2427840 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2427840 T5594 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2427841 T5594 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2427841 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2427854 T5649 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\collection1
[junit4] 2> 2427854 T5649 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\collection1\'
[junit4] 2> 2427904 T5649 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 2427965 T5649 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2427966 T5649 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2427971 T5649 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2427991 T5649 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2427991 T5649 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2427991 T5649 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\collection1\data\
[junit4] 2> 2427992 T5649 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2427992 T5649 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\collection1\data\
[junit4] 2> 2427992 T5649 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\collection1\data\index/
[junit4] 2> 2427992 T5649 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2427993 T5649 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520248\master\collection1\data\index
[junit4] 2> 2427994 T5649 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@75f7ae1e lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@7883c96c),segFN=segments_1,generation=1}
[junit4] 2> 2427994 T5649 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2427995 T5649 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2427995 T5649 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2427995 T5649 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2427995 T5649 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2427995 T5649 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2427996 T5649 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2428001 T5649 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2428003 T5649 oass.SolrIndexSearcher.<init> Opening Searcher@1f18a994 main
[junit4] 2> 2428003 T5649 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2428004 T5649 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2428004 T5649 oash.ReplicationHandler.inform Replication enabled for following config files: schema.xml,xslt/dummy.xsl
[junit4] 2> 2428004 T5649 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2428005 T5649 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2428005 T5650 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1f18a994 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2428005 T5594 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2428005 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2428006 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 2428024 T5594 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2428027 T5594 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57223
[junit4] 2> 2428027 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2428027 T5594 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2428028 T5594 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave
[junit4] 2> 2428028 T5594 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\'
[junit4] 2> 2428040 T5594 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\solr.xml
[junit4] 2> 2428041 T5594 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\solr.xml does not exist, using default configuration
[junit4] 2> 2428094 T5594 oasc.CoreContainer.<init> New CoreContainer 1314754545
[junit4] 2> 2428095 T5594 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\]
[junit4] 2> 2428097 T5594 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2428097 T5594 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2428097 T5594 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2428097 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2428097 T5594 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2428098 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2428098 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2428099 T5594 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2428099 T5594 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2428099 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2428109 T5661 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1
[junit4] 2> 2428109 T5661 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\'
[junit4] 2> 2428162 T5661 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 2428228 T5661 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2428229 T5661 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2428234 T5661 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2428253 T5661 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2428256 T5661 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
[junit4] 2> 2428257 T5661 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\
[junit4] 2> 2428257 T5661 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2428257 T5661 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\
[junit4] 2> 2428257 T5661 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\index/
[junit4] 2> 2428258 T5661 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2428258 T5661 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\index
[junit4] 2> 2428260 T5661 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=MockDirWrapper(org.apache.lucene.store.RAMDirectory@391dcfd1 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@b74e5c6),segFN=segments_1,generation=1}
[junit4] 2> 2428260 T5661 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2428262 T5661 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2428262 T5661 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2428262 T5661 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2428262 T5661 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2428263 T5661 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2428264 T5661 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2428266 T5661 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2428268 T5661 oass.SolrIndexSearcher.<init> Opening Searcher@6eeb6d9a main
[junit4] 2> 2428269 T5661 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2428269 T5661 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2428270 T5661 oash.SnapPuller.startExecutorService Poll Scheduled at an interval of 1000ms
[junit4] 2> 2428270 T5661 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2428270 T5662 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@6eeb6d9a main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2428270 T5594 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2428270 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2428271 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 2428292 T5594 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1314754545
[junit4] 2> 2428294 T5594 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@5143ded8
[junit4] 2> 2428295 T5594 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> 2428295 T5594 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2428295 T5594 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2428297 T5594 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2428298 T5594 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2428298 T5594 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2428299 T5594 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\index;done=false>>]
[junit4] 2> 2428300 T5594 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\index
[junit4] 2> 2428300 T5594 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\;done=false>>]
[junit4] 2> 2428301 T5594 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\
[junit4] 2> 2428303 T5594 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2428355 T5594 oejs.Server.doStart jetty-8.1.10.v20130312
[junit4] 2> 2428360 T5594 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:57226
[junit4] 2> 2428362 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
[junit4] 2> 2428363 T5594 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
[junit4] 2> 2428364 T5594 oasc.SolrResourceLoader.locateSolrHome using system property solr.solr.home: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave
[junit4] 2> 2428364 T5594 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\'
[junit4] 2> 2428387 T5594 oasc.ConfigSolr.fromFile Loading container configuration from C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\solr.xml
[junit4] 2> 2428388 T5594 oasc.ConfigSolr.fromFile C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\solr.xml does not exist, using default configuration
[junit4] 2> 2428439 T5594 oasc.CoreContainer.<init> New CoreContainer 1602198570
[junit4] 2> 2428440 T5594 oasc.CoreContainer.load Loading cores into CoreContainer [instanceDir=.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\]
[junit4] 2> 2428441 T5594 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 0
[junit4] 2> 2428442 T5594 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
[junit4] 2> 2428442 T5594 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 0
[junit4] 2> 2428442 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
[junit4] 2> 2428442 T5594 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
[junit4] 2> 2428443 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
[junit4] 2> 2428443 T5594 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
[junit4] 2> 2428443 T5594 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
[junit4] 2> 2428444 T5594 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
[junit4] 2> 2428444 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnectionsPerHost=20&maxConnections=10000&socketTimeout=0&connTimeout=0&retry=false
[junit4] 2> 2428452 T5674 oasc.CoreContainer.create Creating SolrCore 'collection1' using instanceDir: .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1
[junit4] 2> 2428452 T5674 oasc.SolrResourceLoader.<init> new SolrResourceLoader for directory: '.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\'
[junit4] 2> 2428512 T5674 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_45
[junit4] 2> 2428571 T5674 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2428571 T5674 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2428577 T5674 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2428594 T5674 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2428595 T5674 oasc.SolrCore.initDirectoryFactory solr.StandardDirectoryFactory
[junit4] 2> 2428595 T5674 oasc.SolrCore.<init> [collection1] Opening new SolrCore at .\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\, dataDir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\
[junit4] 2> 2428596 T5674 oasc.SolrCore.<init> JMX monitoring not detected for core: collection1
[junit4] 2> 2428596 T5674 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data
[junit4] 2> 2428597 T5674 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\index/
[junit4] 2> 2428598 T5674 oasc.SolrCore.initIndex WARN [collection1] Solr index directory 'C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\index' doesn't exist. Creating new index...
[junit4] 2> 2428598 T5674 oasc.CachingDirectoryFactory.get return new directory for C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\index
[junit4] 2> 2428609 T5674 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
[junit4] 2> commit{dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282520519\slave\collection1\data\index,segFN=segments_1,generation=1}
[junit4] 2> 2428609 T5674 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
[junit4] 2> 2428611 T5674 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
[junit4] 2> 2428611 T5674 oasc.RequestHandlers.initHandlersFromConfig created defaults: solr.StandardRequestHandler
[junit4] 2> 2428611 T5674 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.StandardRequestHandler
[junit4] 2> 2428612 T5674 oasc.RequestHandlers.initHandlersFromConfig created lazy: solr.StandardRequestHandler
[junit4] 2> 2428612 T5674 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
[junit4] 2> 2428613 T5674 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
[junit4] 2> 2428615 T5674 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
[junit4] 2> 2428622 T5674 oass.SolrIndexSearcher.<init> Opening Searcher@2a13cda7 main
[junit4] 2> 2428623 T5674 oasu.CommitTracker.<init> Hard AutoCommit: disabled
[junit4] 2> 2428623 T5674 oasu.CommitTracker.<init> Soft AutoCommit: disabled
[junit4] 2> 2428624 T5674 oash.ReplicationHandler.inform Commits will be reserved for 10000
[junit4] 2> 2428625 T5674 oasc.CoreContainer.registerCore registering core: collection1
[junit4] 2> 2428625 T5675 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@2a13cda7 main{StandardDirectoryReader(segments_1:1)}
[junit4] 2> 2428625 T5594 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
[junit4] 2> 2428625 T5594 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
[junit4] 2> 2428626 T5594 oascsi.HttpClientUtil.createClient Creating new http client, config:maxConnections=128&maxConnectionsPerHost=32&followRedirects=false
[junit4] 2> 2428643 T5594 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=270636498
[junit4] 2> 2428643 T5594 oasc.SolrCore.close [collection1] CLOSING SolrCore org.apache.solr.core.SolrCore@3439ae7d
[junit4] 2> 2428643 T5594 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> 2428643 T5594 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4]
[...truncated too long message...]
CLOSING SolrCore org.apache.solr.core.SolrCore@60a6e6e
[junit4] 2> 2480834 T5594 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> 2480835 T5594 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
[junit4] 2> 2480835 T5594 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
[junit4] 2> 2480836 T5594 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
[junit4] 2> 2480840 T5594 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
[junit4] 2> 2480841 T5594 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
[junit4] 2> 2480841 T5594 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282568711\slave\collection1\data\index [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282568711\slave\collection1\data\index;done=false>>]
[junit4] 2> 2480841 T5594 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282568711\slave\collection1\data\index
[junit4] 2> 2480842 T5594 oasc.CachingDirectoryFactory.closeCacheValue looking to close C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282568711\slave\collection1\data\ [CachedDir<<refCount=0;path=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282568711\slave\collection1\data\;done=false>>]
[junit4] 2> 2480843 T5594 oasc.CachingDirectoryFactory.close Closing directory: C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0\.\org.apache.solr.handler.TestReplicationHandler$SolrInstance-1376282568711\slave\collection1\data\
[junit4] 2> 2480844 T5594 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/solr,null}
[junit4] 2> 2480901 T6283 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig.xml
[junit4] 2> 2480901 T6283 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
[junit4] 2> 2480910 T6283 oass.IndexSchema.readSchema [collection1] Schema name=test
[junit4] 2> 2480976 T5594 oas.SolrTestCaseJ4.deleteCore ###deleteCore
[junit4] 2> 54673 T5593 ccr.ThreadLeakControl.checkThreadLeaks WARNING Will linger awaiting termination of 1 leaked thread(s).
[junit4] 2> 2480999 T6283 oass.IndexSchema.readSchema unique key field: id
[junit4] 2> 2481001 T6283 oasc.CoreContainer.recordAndThrow ERROR Unable to reload core: collection1 java.lang.IllegalStateException: IndexWriter has been closed
[junit4] 2> at org.apache.solr.update.SolrCoreState.increfSolrCoreState(SolrCoreState.java:52)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:406)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:675)
[junit4] 2> at org.apache.solr.handler.SnapPuller$2.run(SnapPuller.java:688)
[junit4] 2>
[junit4] 2> 2481002 T6283 oash.SnapPuller$2.run ERROR Could not reload core org.apache.solr.common.SolrException: Unable to reload core: collection1
[junit4] 2> at org.apache.solr.core.CoreContainer.recordAndThrow(CoreContainer.java:930)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:685)
[junit4] 2> at org.apache.solr.handler.SnapPuller$2.run(SnapPuller.java:688)
[junit4] 2> Caused by: java.lang.IllegalStateException: IndexWriter has been closed
[junit4] 2> at org.apache.solr.update.SolrCoreState.increfSolrCoreState(SolrCoreState.java:52)
[junit4] 2> at org.apache.solr.core.SolrCore.reload(SolrCore.java:406)
[junit4] 2> at org.apache.solr.core.CoreContainer.reload(CoreContainer.java:675)
[junit4] 2> ... 1 more
[junit4] 2>
[junit4] 2> NOTE: test params are: codec=Lucene40, sim=DefaultSimilarity, locale=ca_ES, timezone=Australia/Melbourne
[junit4] 2> NOTE: Windows 7 6.1 amd64/Sun Microsystems Inc. 1.6.0_45 (64-bit)/cpus=2,threads=1,free=129381160,total=281227264
[junit4] 2> NOTE: All tests run in this JVM: [TestShardHandlerFactory, TestPartialUpdateDeduplication, StatsComponentTest, DateMathParserTest, TestIndexingPerformance, TestPerFieldSimilarity, CurrencyFieldOpenExchangeTest, HdfsChaosMonkeySafeLeaderTest, PathHierarchyTokenizerFactoryTest, TestDynamicFieldResource, SpellCheckComponentTest, TestDefaultSearchFieldResource, DebugComponentTest, ZkNodePropsTest, TestPseudoReturnFields, MoreLikeThisHandlerTest, PeerSyncTest, SuggesterWFSTTest, TestReloadAndDeleteDocs, FileUtilsTest, SoftAutoCommitTest, TermsComponentTest, TestComponentsName, CoreAdminCreateDiscoverTest, NotRequiredUniqueKeyTest, HighlighterConfigTest, TestCoreDiscovery, DocValuesMultiTest, IndexBasedSpellCheckerTest, TimeZoneUtilsTest, SpellingQueryConverterTest, DefaultValueUpdateProcessorTest, ChaosMonkeySafeLeaderTest, BasicDistributedZkTest, AddSchemaFieldsUpdateProcessorFactoryTest, DocValuesTest, SliceStateUpdateTest, TestLMJelinekMercerSimilarityFactory, BasicFunctionalityTest, LukeRequestHandlerTest, TestSolrXmlPersistence, TestCloudManagedSchemaAddField, BinaryUpdateRequestHandlerTest, TestRandomFaceting, UpdateRequestProcessorFactoryTest, DirectUpdateHandlerOptimizeTest, SolrInfoMBeanTest, XmlUpdateRequestHandlerTest, TestInfoStreamLogging, TestSweetSpotSimilarityFactory, TestDistributedGrouping, SystemInfoHandlerTest, EchoParamsTest, MultiTermTest, DistanceFunctionTest, TestBinaryField, CoreAdminHandlerTest, SuggesterFSTTest, TestRemoteStreaming, ConvertedLegacyTest, ShardRoutingTest, TestSolrXMLSerializer, TestFieldResource, CustomCollectionTest, TestTrie, TestSolrDeletionPolicy1, DistributedQueryElevationComponentTest, DistributedTermsComponentTest, NoCacheHeaderTest, TestFaceting, ChaosMonkeyNothingIsSafeTest, PolyFieldTest, TestRandomMergePolicy, TestSurroundQueryParser, UUIDFieldTest, SortByFunctionTest, TestGroupingSearch, HdfsLockFactoryTest, FileBasedSpellCheckerTest, BlockCacheTest, OverseerTest, RequestHandlersTest, SpellPossibilityIteratorTest, TestBinaryResponseWriter, TestReload, TestSuggestSpellingConverter, TestStressVersions, TestWordDelimiterFilterFactory, TestFieldCollectionResource, TestMaxScoreQueryParser, TestSchemaSimilarityResource, TermVectorComponentTest, TestManagedSchemaFieldResource, RecoveryZkTest, TestLuceneMatchVersion, SliceStateTest, ResponseLogComponentTest, TestOmitPositions, OverseerCollectionProcessorTest, SolrPluginUtilsTest, TestPhraseSuggestions, TestDistributedSearch, BadComponentTest, URLClassifyProcessorTest, RequiredFieldsTest, TestSolrCoreProperties, PrimitiveFieldTypeTest, TestQuerySenderNoQuery, SuggesterTSTTest, AlternateDirectoryTest, ParsingFieldUpdateProcessorsTest, ShardRoutingCustomTest, DeleteShardTest, TestRecovery, ResourceLoaderTest, StatelessScriptUpdateProcessorFactoryTest, BasicZkTest, TestHashPartitioner, BlockDirectoryTest, TestSearchPerf, SpatialFilterTest, TermVectorComponentDistributedTest, TestCopyFieldCollectionResource, TestDFRSimilarityFactory, UnloadDistributedZkTest, TestPHPSerializedResponseWriter, IndexSchemaTest, RegexBoostProcessorTest, TestStressRecovery, TestSolrQueryParserResource, SolrCoreTest, SolrRequestParserTest, TestFiltering, HdfsBasicDistributedZkTest, PreAnalyzedUpdateProcessorTest, SampleTest, CoreMergeIndexesAdminHandlerTest, TestJmxMonitoredMap, CacheHeaderTest, NumericFieldsTest, TestManagedSchema, PingRequestHandlerTest, InfoHandlerTest, TestFunctionQuery, TestSchemaNameResource, SynonymTokenizerTest, TestLMDirichletSimilarityFactory, HardAutoCommitTest, HighlighterTest, TestStressLucene, UniqFieldsUpdateProcessorFactoryTest, BasicDistributedZk2Test, CollectionsAPIDistributedZkTest, SyncSliceTest, OpenCloseCoreStressTest, LeaderElectionIntegrationTest, FullSolrCloudDistribCmdsTest, TestReplicationHandler]
[junit4] Completed in 54.98s, 14 tests, 1 failure <<< FAILURES!
[...truncated 473 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:395: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:375: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:39: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:181: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:449: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1233: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:876: There were test failures: 319 suites, 1350 tests, 1 failure, 42 ignored (13 assumptions)
Total time: 84 minutes 5 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 64bit/jdk1.6.0_45 -XX:+UseCompressedOops -XX:+UseSerialGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure