lucene-dev mailing list archives

Site index · List index
Message view « Date » · « Thread »
Top « Date » · « Thread »
From Policeman Jenkins Server <jenk...@thetaphi.de>
Subject [JENKINS] Lucene-Solr-4.x-Windows (32bit/jdk1.8.0-ea-b116) - Build # 3431 - Still Failing!
Date Tue, 26 Nov 2013 14:03:12 GMT
Build: http://jenkins.thetaphi.de/job/Lucene-Solr-4.x-Windows/3431/
Java: 32bit/jdk1.8.0-ea-b116 -server -XX:+UseConcMarkSweepGC

1 tests failed.
FAILED:  org.apache.solr.update.SolrCmdDistributorTest.testDistribSearch

Error Message:
expected:<161> but was:<162>

Stack Trace:
java.lang.AssertionError: expected:<161> but was:<162>
	at __randomizedtesting.SeedInfo.seed([7ED18E75C3A321BE:FF37006DB4FC4182]:0)
	at org.junit.Assert.fail(Assert.java:93)
	at org.junit.Assert.failNotEquals(Assert.java:647)
	at org.junit.Assert.assertEquals(Assert.java:128)
	at org.junit.Assert.assertEquals(Assert.java:472)
	at org.junit.Assert.assertEquals(Assert.java:456)
	at org.apache.solr.update.SolrCmdDistributorTest.testRetryNode(SolrCmdDistributorTest.java:443)
	at org.apache.solr.update.SolrCmdDistributorTest.doTest(SolrCmdDistributorTest.java:321)
	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
	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:483)
	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:744)




Build Log:
[...truncated 10449 lines...]
   [junit4] Suite: org.apache.solr.update.SolrCmdDistributorTest
   [junit4]   2> 745504 T2602 oas.BaseDistributedSearchTestCase.initHostContext Setting hostContext system property: /
   [junit4]   2> 745514 T2602 oas.SolrTestCaseJ4.setUp ###Starting testDistribSearch
   [junit4]   2> 745517 T2602 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 745524 T2602 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54249
   [junit4]   2> 745524 T2602 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 745525 T2602 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 745526 T2602 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> 745526 T2602 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> 745557 T2602 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> 745600 T2602 oasc.CoreContainer.<init> New CoreContainer 4046233
   [junit4]   2> 745600 T2602 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> 745604 T2602 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 745604 T2602 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 745605 T2602 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 745605 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 745605 T2602 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 745605 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 745606 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 745606 T2602 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 745606 T2602 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 745609 T2602 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 745611 T2602 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 745612 T2602 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 745658 T2612 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> 745659 T2612 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> 745660 T2612 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> 745662 T2612 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> 745698 T2612 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 745782 T2612 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 745783 T2612 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 745790 T2612 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 746006 T2612 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 746006 T2612 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 746008 T2612 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 746013 T2612 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 746015 T2612 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 746022 T2612 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 746024 T2612 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 746027 T2612 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 746027 T2612 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 746027 T2612 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 746027 T2612 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 746027 T2612 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 746027 T2612 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 746027 T2612 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 746029 T2612 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=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/control/data\
   [junit4]   2> 746029 T2612 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@162161
   [junit4]   2> 746030 T2612 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/control/data\
   [junit4]   2> 746030 T2612 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/control/data\index/
   [junit4]   2> 746030 T2612 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063\control\data\index' doesn't exist. Creating new index...
   [junit4]   2> 746031 T2612 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/control/data\index
   [junit4]   2> 746032 T2612 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@12a9754 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@144b164),segFN=segments_1,generation=1}
   [junit4]   2> 746032 T2612 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 746043 T2612 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 746043 T2612 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 746043 T2612 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 746043 T2612 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 746044 T2612 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 746044 T2612 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 746045 T2612 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 746045 T2612 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 746045 T2612 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 746045 T2612 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 746046 T2612 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 746046 T2612 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 746046 T2612 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 746046 T2612 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 746047 T2612 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 746047 T2612 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 746051 T2612 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 746052 T2612 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 746052 T2612 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 746055 T2612 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@12a9754 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@144b164),segFN=segments_1,generation=1}
   [junit4]   2> 746055 T2612 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 746055 T2612 oass.SolrIndexSearcher.<init> Opening Searcher@104b5e7 main
   [junit4]   2> 746057 T2612 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 746058 T2613 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@104b5e7 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 746058 T2602 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 746058 T2602 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 746060 T2602 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 746064 T2602 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54252
   [junit4]   2> 746077 T2602 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 746077 T2602 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 746077 T2602 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> 746077 T2602 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> 746090 T2602 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> 746103 T2602 oasc.CoreContainer.<init> New CoreContainer 6173997
   [junit4]   2> 746103 T2602 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> 746105 T2602 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 746105 T2602 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 746105 T2602 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 746105 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 746106 T2602 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 746106 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 746107 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 746107 T2602 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 746107 T2602 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 746110 T2602 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 746111 T2602 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 746111 T2602 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 746118 T2624 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> 746119 T2624 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> 746119 T2624 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> 746119 T2624 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> 746153 T2624 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 746165 T2624 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 746168 T2624 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 746173 T2624 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 746296 T2624 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 746297 T2624 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 746297 T2624 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 746300 T2624 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 746300 T2624 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 746307 T2624 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 746310 T2624 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 746312 T2624 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 746312 T2624 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 746313 T2624 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 746313 T2624 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 746313 T2624 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 746313 T2624 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 746313 T2624 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 746313 T2624 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=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard0/data\
   [junit4]   2> 746313 T2624 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@162161
   [junit4]   2> 746314 T2624 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard0/data\
   [junit4]   2> 746314 T2624 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard0/data\index/
   [junit4]   2> 746314 T2624 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063\shard0\data\index' doesn't exist. Creating new index...
   [junit4]   2> 746315 T2624 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard0/data\index
   [junit4]   2> 746316 T2624 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dcf087 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@edd061),segFN=segments_1,generation=1}
   [junit4]   2> 746316 T2624 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 746319 T2624 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 746319 T2624 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 746319 T2624 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 746320 T2624 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 746320 T2624 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 746320 T2624 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 746320 T2624 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 746321 T2624 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 746321 T2624 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 746321 T2624 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 746321 T2624 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 746322 T2624 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 746322 T2624 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 746322 T2624 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 746322 T2624 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 746323 T2624 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 746324 T2624 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 746327 T2624 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 746327 T2624 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 746328 T2624 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dcf087 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@edd061),segFN=segments_1,generation=1}
   [junit4]   2> 746328 T2624 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 746328 T2624 oass.SolrIndexSearcher.<init> Opening Searcher@68970f main
   [junit4]   2> 746331 T2624 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 746332 T2602 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 746332 T2602 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 746334 T2602 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 746352 T2625 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@68970f main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 746362 T2602 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54255
   [junit4]   2> 746363 T2602 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 746363 T2602 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 746363 T2602 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> 746363 T2602 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> 746376 T2602 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> 746382 T2602 oasc.CoreContainer.<init> New CoreContainer 25232169
   [junit4]   2> 746382 T2602 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> 746385 T2602 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 746385 T2602 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 746385 T2602 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 746385 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 746385 T2602 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 746385 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 746385 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 746385 T2602 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 746385 T2602 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 746390 T2602 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 746390 T2602 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 746392 T2602 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 746395 T2636 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> 746395 T2636 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> 746395 T2636 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> 746395 T2636 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> 746437 T2636 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 746483 T2636 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 746483 T2636 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 746489 T2636 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 746659 T2636 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 746661 T2636 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 746661 T2636 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 746664 T2636 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 746664 T2636 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 746670 T2636 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 746672 T2636 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 746674 T2636 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 746674 T2636 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 746674 T2636 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 746674 T2636 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 746674 T2636 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 746676 T2636 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 746676 T2636 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 746677 T2636 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=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard1/data\
   [junit4]   2> 746677 T2636 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@162161
   [junit4]   2> 746678 T2636 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard1/data\
   [junit4]   2> 746678 T2636 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard1/data\index/
   [junit4]   2> 746678 T2636 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063\shard1\data\index' doesn't exist. Creating new index...
   [junit4]   2> 746678 T2636 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard1/data\index
   [junit4]   2> 746679 T2636 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@531a9b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f7f39a),segFN=segments_1,generation=1}
   [junit4]   2> 746679 T2636 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 746684 T2636 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 746684 T2636 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 746684 T2636 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 746684 T2636 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 746684 T2636 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 746684 T2636 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 746686 T2636 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 746687 T2636 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 746687 T2636 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 746687 T2636 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 746687 T2636 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 746687 T2636 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 746687 T2636 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 746688 T2636 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 746688 T2636 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 746688 T2636 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 746690 T2636 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 746693 T2636 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 746693 T2636 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 746695 T2636 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@531a9b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f7f39a),segFN=segments_1,generation=1}
   [junit4]   2> 746695 T2636 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 746695 T2636 oass.SolrIndexSearcher.<init> Opening Searcher@deba51 main
   [junit4]   2> 746698 T2636 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 746698 T2637 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@deba51 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 746698 T2602 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 746698 T2602 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 746705 T2602 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 746708 T2602 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54258
   [junit4]   2> 746708 T2602 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 746708 T2602 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 746708 T2602 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> 746708 T2602 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> 746723 T2602 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> 746735 T2602 oasc.CoreContainer.<init> New CoreContainer 27433234
   [junit4]   2> 746735 T2602 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> 746737 T2602 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 746738 T2602 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 746738 T2602 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 746738 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 746738 T2602 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 746738 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 746739 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 746739 T2602 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 746739 T2602 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 746743 T2602 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 746743 T2602 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 746743 T2602 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 746745 T2648 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> 746746 T2648 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> 746746 T2648 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> 746746 T2648 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> 746765 T2648 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 746779 T2648 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 746779 T2648 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 746789 T2648 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 746895 T2648 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 746897 T2648 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 746897 T2648 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 746901 T2648 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 746901 T2648 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 746907 T2648 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 746909 T2648 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 746911 T2648 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 746911 T2648 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 746911 T2648 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 746911 T2648 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 746911 T2648 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 746911 T2648 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 746914 T2648 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 746914 T2648 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=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard2/data\
   [junit4]   2> 746914 T2648 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@162161
   [junit4]   2> 746915 T2648 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard2/data\
   [junit4]   2> 746915 T2648 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard2/data\index/
   [junit4]   2> 746915 T2648 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063\shard2\data\index' doesn't exist. Creating new index...
   [junit4]   2> 746915 T2648 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard2/data\index
   [junit4]   2> 746915 T2648 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@9442 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cff871),segFN=segments_1,generation=1}
   [junit4]   2> 746915 T2648 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 746918 T2648 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 746918 T2648 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 746918 T2648 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 746918 T2648 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 746918 T2648 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 746918 T2648 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 746919 T2648 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 746919 T2648 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 746919 T2648 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 746919 T2648 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 746920 T2648 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 746920 T2648 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 746920 T2648 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 746920 T2648 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 746921 T2648 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 746921 T2648 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 746922 T2648 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 746926 T2648 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 746926 T2648 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 746927 T2648 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@9442 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cff871),segFN=segments_1,generation=1}
   [junit4]   2> 746927 T2648 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 746927 T2648 oass.SolrIndexSearcher.<init> Opening Searcher@1ff1ae5 main
   [junit4]   2> 746930 T2648 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 746930 T2649 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ff1ae5 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 746932 T2602 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 746932 T2602 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> 746932 T2602 oejs.Server.doStart jetty-8.1.10.v20130312
   [junit4]   2> 746936 T2602 oejs.AbstractConnector.doStart Started SelectChannelConnector@127.0.0.1:54261
   [junit4]   2> 746936 T2602 oass.SolrDispatchFilter.init SolrDispatchFilter.init()
   [junit4]   2> 746936 T2602 oasc.SolrResourceLoader.locateSolrHome JNDI not configured for solr (NoInitialContextEx)
   [junit4]   2> 746936 T2602 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> 746936 T2602 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> 746950 T2602 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> 746959 T2602 oasc.CoreContainer.<init> New CoreContainer 1729471
   [junit4]   2> 746959 T2602 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> 746960 T2602 oashc.HttpShardHandlerFactory.getParameter Setting socketTimeout to: 90000
   [junit4]   2> 746960 T2602 oashc.HttpShardHandlerFactory.getParameter Setting urlScheme to: http://
   [junit4]   2> 746960 T2602 oashc.HttpShardHandlerFactory.getParameter Setting connTimeout to: 15000
   [junit4]   2> 746960 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maxConnectionsPerHost to: 20
   [junit4]   2> 746960 T2602 oashc.HttpShardHandlerFactory.getParameter Setting corePoolSize to: 0
   [junit4]   2> 746960 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maximumPoolSize to: 2147483647
   [junit4]   2> 746960 T2602 oashc.HttpShardHandlerFactory.getParameter Setting maxThreadIdleTime to: 5
   [junit4]   2> 746961 T2602 oashc.HttpShardHandlerFactory.getParameter Setting sizeOfQueue to: -1
   [junit4]   2> 746962 T2602 oashc.HttpShardHandlerFactory.getParameter Setting fairnessPolicy to: false
   [junit4]   2> 746965 T2602 oasl.LogWatcher.createWatcher SLF4J impl is org.slf4j.impl.Log4jLoggerFactory
   [junit4]   2> 746965 T2602 oasl.LogWatcher.newRegisteredLogWatcher Registering Log Listener [Log4j (org.slf4j.impl.Log4jLoggerFactory)]
   [junit4]   2> 746965 T2602 oasc.CoreContainer.load Host Name: 127.0.0.1
   [junit4]   2> 746973 T2660 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> 746974 T2660 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> 746974 T2660 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> 746974 T2660 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> 746994 T2660 oasc.SolrConfig.<init> Using Lucene MatchVersion: LUCENE_47
   [junit4]   2> 747001 T2660 oasc.SolrConfig.<init> Loaded SolrConfig: solrconfig-tlog.xml
   [junit4]   2> 747001 T2660 oass.IndexSchema.readSchema Reading Solr Schema from schema.xml
   [junit4]   2> 747006 T2660 oass.IndexSchema.readSchema [collection1] Schema name=test
   [junit4]   2> 747131 T2660 oass.ByteField.init WARN ByteField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 747133 T2660 oass.ShortField.init WARN ShortField is deprecated and will be removed in 5.0. You should use TrieIntField instead.
   [junit4]   2> 747133 T2660 oass.OpenExchangeRatesOrgProvider.init Initialized with rates=open-exchange-rates.json, refreshInterval=1440.
   [junit4]   2> 747136 T2660 oass.IndexSchema.readSchema default search field in schema is text
   [junit4]   2> 747136 T2660 oass.IndexSchema.readSchema unique key field: id
   [junit4]   2> 747143 T2660 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 747146 T2660 oass.FileExchangeRateProvider.reload Reloading exchange rates from file currency.xml
   [junit4]   2> 747148 T2660 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 747148 T2660 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 747148 T2660 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 747148 T2660 oass.OpenExchangeRatesOrgProvider.reload Reloading exchange rates from open-exchange-rates.json
   [junit4]   2> 747148 T2660 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Unknown key IMPORTANT NOTE
   [junit4]   2> 747148 T2660 oass.OpenExchangeRatesOrgProvider$OpenExchangeRates.<init> WARN Expected key, got STRING
   [junit4]   2> 747150 T2660 oasc.SolrCore.initDirectoryFactory org.apache.solr.core.MockDirectoryFactory
   [junit4]   2> 747150 T2660 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=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard3/data\
   [junit4]   2> 747150 T2660 oasc.JmxMonitoredMap.<init> JMX monitoring is enabled. Adding Solr mbeans to JMX Server: com.sun.jmx.mbeanserver.JmxMBeanServer@162161
   [junit4]   2> 747151 T2660 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard3/data\
   [junit4]   2> 747151 T2660 oasc.SolrCore.getNewIndexDir New index directory detected: old=null new=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard3/data\index/
   [junit4]   2> 747151 T2660 oasc.SolrCore.initIndex WARN [collection1] Solr index directory '.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063\shard3\data\index' doesn't exist. Creating new index...
   [junit4]   2> 747152 T2660 oasc.CachingDirectoryFactory.get return new directory for .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard3/data\index
   [junit4]   2> 747153 T2660 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1d2c012 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30ec7f),segFN=segments_1,generation=1}
   [junit4]   2> 747153 T2660 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 747156 T2660 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "nodistrib"
   [junit4]   2> 747156 T2660 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "dedupe"
   [junit4]   2> 747157 T2660 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "dedupe"
   [junit4]   2> 747157 T2660 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "stored_sig"
   [junit4]   2> 747157 T2660 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "stored_sig"
   [junit4]   2> 747157 T2660 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-explicit"
   [junit4]   2> 747157 T2660 oasup.UpdateRequestProcessorChain.init creating updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 747158 T2660 oasup.UpdateRequestProcessorChain.init inserting DistributedUpdateProcessorFactory into updateRequestProcessorChain "distrib-dup-test-chain-implicit"
   [junit4]   2> 747158 T2660 oasc.SolrCore.loadUpdateProcessorChains no updateRequestProcessorChain defined as default, creating implicit default
   [junit4]   2> 747158 T2660 oasc.RequestHandlers.initHandlersFromConfig created standard: solr.StandardRequestHandler
   [junit4]   2> 747159 T2660 oasc.RequestHandlers.initHandlersFromConfig created /get: solr.RealTimeGetHandler
   [junit4]   2> 747159 T2660 oasc.RequestHandlers.initHandlersFromConfig adding lazy requestHandler: solr.ReplicationHandler
   [junit4]   2> 747159 T2660 oasc.RequestHandlers.initHandlersFromConfig created /replication: solr.ReplicationHandler
   [junit4]   2> 747159 T2660 oasc.RequestHandlers.initHandlersFromConfig created /update: solr.UpdateRequestHandler
   [junit4]   2> 747160 T2660 oasc.RequestHandlers.initHandlersFromConfig created /admin/: org.apache.solr.handler.admin.AdminHandlers
   [junit4]   2> 747160 T2660 oasc.RequestHandlers.initHandlersFromConfig created /admin/file: solr.admin.ShowFileRequestHandler
   [junit4]   2> 747161 T2660 oashl.XMLLoader.init xsltCacheLifetimeSeconds=60
   [junit4]   2> 747165 T2660 oasu.CommitTracker.<init> Hard AutoCommit: disabled
   [junit4]   2> 747165 T2660 oasu.CommitTracker.<init> Soft AutoCommit: disabled
   [junit4]   2> 747170 T2660 oasc.SolrDeletionPolicy.onInit SolrDeletionPolicy.onInit: commits: num=1
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1d2c012 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30ec7f),segFN=segments_1,generation=1}
   [junit4]   2> 747170 T2660 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 1
   [junit4]   2> 747170 T2660 oass.SolrIndexSearcher.<init> Opening Searcher@113d075 main
   [junit4]   2> 747174 T2660 oasc.CoreContainer.registerCore registering core: collection1
   [junit4]   2> 747175 T2602 oass.SolrDispatchFilter.init user.dir=C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build\solr-core\test\J0
   [junit4]   2> 747175 T2602 oass.SolrDispatchFilter.init SolrDispatchFilter.init() done
   [junit4]   2> ASYNC  NEW_CORE C2425 name=collection1 org.apache.solr.core.SolrCore@192ab38
   [junit4]   2> 747182 T2608 C2425 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1452773423687139328)} 0 4
   [junit4]   2> ASYNC  NEW_CORE C2426 name=collection1 org.apache.solr.core.SolrCore@7ac031
   [junit4]   2> 747188 T2622 C2426 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1452773423693430784)} 0 4
   [junit4]   2> 747191 T2661 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@113d075 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> ASYNC  NEW_CORE C2427 name=collection1 org.apache.solr.core.SolrCore@cfd877
   [junit4]   2> 747196 T2631 C2427 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1452773423700770816)} 0 5
   [junit4]   2> ASYNC  NEW_CORE C2428 name=collection1 org.apache.solr.core.SolrCore@13743dd
   [junit4]   2> 747202 T2646 C2428 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1452773423708110848)} 0 4
   [junit4]   2> ASYNC  NEW_CORE C2429 name=collection1 org.apache.solr.core.SolrCore@5ed2ca
   [junit4]   2> 747208 T2657 C2429 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {deleteByQuery=*:* (-1452773423714402304)} 0 4
   [junit4]   2> 747372 T2610 C2425 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[1 (1452773423887417344)]} 0 2
   [junit4]   2> 747375 T2611 C2425 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747377 T2611 C2425 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@12a9754 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@144b164),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@12a9754 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@144b164),segFN=segments_2,generation=2}
   [junit4]   2> 747378 T2611 C2425 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 747384 T2611 C2425 oass.SolrIndexSearcher.<init> Opening Searcher@3acfcb main
   [junit4]   2> 747384 T2611 C2425 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747384 T2613 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3acfcb main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):c1)}
   [junit4]   2> 747385 T2611 C2425 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 10
   [junit4]   2> 747387 T2606 C2425 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&wt=javabin&version=2} hits=1 status=0 QTime=0 
   [junit4]   2> 747396 T2607 C2425 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={commit_end_point=true&wt=javabin&version=2} {add=[2 (1452773423913631744), 3 (1452773423914680320)]} 0 1
   [junit4]   2> 747402 T2618 C2426 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={commit_end_point=true&wt=javabin&version=2} {add=[2 (1452773423918874624), 3 (1452773423919923200), 4 (1452773423919923201)]} 0 2
   [junit4]   2> 747405 T2608 C2425 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747408 T2608 C2425 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@12a9754 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@144b164),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@12a9754 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@144b164),segFN=segments_3,generation=3}
   [junit4]   2> 747409 T2608 C2425 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 747409 T2608 C2425 oass.SolrIndexSearcher.<init> Opening Searcher@10dd035 main
   [junit4]   2> 747410 T2613 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10dd035 main{StandardDirectoryReader(segments_3:5:nrt _0(4.7):c1 _1(4.7):c2)}
   [junit4]   2> 747412 T2608 C2425 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747413 T2608 C2425 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 8
   [junit4]   2> 747416 T2616 C2426 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747417 T2616 C2426 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dcf087 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@edd061),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dcf087 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@edd061),segFN=segments_2,generation=2}
   [junit4]   2> 747418 T2616 C2426 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 747418 T2616 C2426 oass.SolrIndexSearcher.<init> Opening Searcher@5d46b main
   [junit4]   2> 747418 T2616 C2426 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747420 T2625 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5d46b main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):c3)}
   [junit4]   2> 747420 T2616 C2426 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 4
   [junit4]   2> 747431 T2609 C2425 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0 
   [junit4]   2> 747434 T2619 C2426 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&wt=javabin&version=2} hits=3 status=0 QTime=0 
   [junit4]   2> 747450 T2621 C2426 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={commit_end_point=true&wt=javabin&version=2} {delete=[3 (-1452773423969206272)]} 0 2
   [junit4]   2> 747450 T2610 C2425 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={commit_end_point=true&wt=javabin&version=2} {delete=[3 (-1452773423969206272)]} 0 2
   [junit4]   2> 747453 T2611 C2425 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747454 T2611 C2425 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@12a9754 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@144b164),segFN=segments_3,generation=3}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@12a9754 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@144b164),segFN=segments_4,generation=4}
   [junit4]   2> 747454 T2611 C2425 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 747454 T2611 C2425 oass.SolrIndexSearcher.<init> Opening Searcher@1bacab0 main
   [junit4]   2> 747456 T2611 C2425 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747456 T2613 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1bacab0 main{StandardDirectoryReader(segments_4:6:nrt _0(4.7):c1 _1(4.7):c2/1:delGen=1)}
   [junit4]   2> 747458 T2611 C2425 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 5
   [junit4]   2> 747460 T2620 C2426 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747462 T2620 C2426 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dcf087 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@edd061),segFN=segments_2,generation=2}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dcf087 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@edd061),segFN=segments_3,generation=3}
   [junit4]   2> 747462 T2620 C2426 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 3
   [junit4]   2> 747462 T2620 C2426 oass.SolrIndexSearcher.<init> Opening Searcher@974ccb main
   [junit4]   2> 747464 T2620 C2426 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747464 T2625 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@974ccb main{StandardDirectoryReader(segments_3:4:nrt _0(4.7):c3/1:delGen=1)}
   [junit4]   2> 747465 T2620 C2426 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 5
   [junit4]   2> 747467 T2606 C2425 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0 
   [junit4]   2> 747470 T2622 C2426 oasc.SolrCore.execute [collection1] webapp= path=/select params={q=*:*&wt=javabin&version=2} hits=2 status=0 QTime=0 
   [junit4]   2> 747474 T2618 C2426 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747477 T2618 C2426 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dcf087 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@edd061),segFN=segments_3,generation=3}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dcf087 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@edd061),segFN=segments_4,generation=4}
   [junit4]   2> 747477 T2618 C2426 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 4
   [junit4]   2> 747479 T2618 C2426 oass.SolrIndexSearcher.<init> Opening Searcher@c6eb00 main
   [junit4]   2> 747480 T2618 C2426 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747480 T2625 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@c6eb00 main{StandardDirectoryReader(segments_4:6:nrt _1(4.7):c2)}
   [junit4]   2> 747482 T2618 C2426 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={optimize=true&maxSegments=1&waitSearcher=true&wt=javabin&version=2} {optimize=} 0 8
   [junit4]   2> 747485 T2634 C2427 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747485 T2634 C2427 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 747485 T2634 C2427 oass.SolrIndexSearcher.<init> Opening Searcher@13511b3 main
   [junit4]   2> 747489 T2634 C2427 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747489 T2637 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@13511b3 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 747490 T2634 C2427 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={optimize=true&maxSegments=1&waitSearcher=true&wt=javabin&version=2} {optimize=} 0 5
   [junit4]   2> 747494 T2644 C2428 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747494 T2644 C2428 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 747494 T2644 C2428 oass.SolrIndexSearcher.<init> Opening Searcher@1ef0ddd main
   [junit4]   2> 747498 T2644 C2428 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747498 T2649 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1ef0ddd main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 747499 T2644 C2428 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={optimize=true&maxSegments=1&waitSearcher=true&wt=javabin&version=2} {optimize=} 0 5
   [junit4]   2> 747503 T2659 C2429 oasu.DirectUpdateHandler2.commit start commit{,optimize=true,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747503 T2659 C2429 oasu.DirectUpdateHandler2.commit No uncommitted changes. Skipping IW.commit.
   [junit4]   2> 747504 T2659 C2429 oass.SolrIndexSearcher.<init> Opening Searcher@7688c0 main
   [junit4]   2> 747506 T2661 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@7688c0 main{StandardDirectoryReader(segments_1:1:nrt)}
   [junit4]   2> 747511 T2659 C2429 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747511 T2659 C2429 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={optimize=true&maxSegments=1&waitSearcher=true&wt=javabin&version=2} {optimize=} 0 8
   [junit4]   2> ASYNC  NEW_CORE C2430 name=collection1 org.apache.solr.core.SolrCore@5ed2ca
   [junit4]   2> 747565 T2656 C2430 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1452773424054140928), 7 (1452773424068820992), 8 (1452773424068820993), 11 (1452773424068820994), 12 (1452773424068820995), 13 (1452773424068820996), 16 (1452773424068820997), 17 (1452773424068820998), 21 (1452773424068820999), 29 (1452773424068821000), ... (151 adds)]} 0 37
   [junit4]   2> ASYNC  NEW_CORE C2431 name=collection1 org.apache.solr.core.SolrCore@7ac031
   [junit4]   2> 747570 T2616 C2431 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1452773424049946624), 7 (1452773424064626688), 16 (1452773424064626689), 17 (1452773424064626690), 20 (1452773424064626691), 22 (1452773424064626692), 23 (1452773424064626693), 24 (1452773424064626694), 25 (1452773424067772416), 26 (1452773424067772417), ... (56 adds)]} 0 45
   [junit4]   2> ASYNC  NEW_CORE C2432 name=collection1 org.apache.solr.core.SolrCore@13743dd
   [junit4]   2> 747581 T2642 C2432 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[7 (1452773424065675264), 8 (1452773424068820992), 10 (1452773424068820993), 11 (1452773424068820994), 12 (1452773424068820995), 13 (1452773424068820996), 17 (1452773424068820997), 18 (1452773424081403904), 20 (1452773424081403905), 21 (1452773424081403906), ... (130 adds)]} 0 41
   [junit4]   2> ASYNC  NEW_CORE C2433 name=collection1 org.apache.solr.core.SolrCore@cfd877
   [junit4]   2> 747612 T2628 C2433 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[5 (1452773424066723840), 6 (1452773424082452480), 7 (1452773424082452481), 8 (1452773424082452482), 10 (1452773424082452483), 11 (1452773424082452484), 13 (1452773424082452485), 16 (1452773424116006912), 22 (1452773424116006913), 23 (1452773424116006914), ... (130 adds)]} 0 72
   [junit4]   2> 747627 T2619 C2431 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[125 (1452773424114958336), 126 (1452773424116006912), 127 (1452773424116006913), 130 (1452773424116006914), 131 (1452773424116006915), 132 (1452773424116006916), 134 (1452773424117055488), 135 (1452773424117055489), 137 (1452773424117055490), 141 (1452773424117055491), ... (101 adds)]} 0 40
   [junit4]   2> 747635 T2620 C2431 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[320 (1452773424165289984)]} 0 0
   [junit4]   2> 747636 T2647 C2432 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[267 (1452773424145367040), 268 (1452773424146415616), 269 (1452773424146415617), 270 (1452773424146415618), 271 (1452773424146415619), 274 (1452773424147464192), 276 (1452773424147464193), 277 (1452773424147464194), 279 (1452773424147464195), 281 (1452773424147464196), ... (36 adds)]} 0 20
   [junit4]   2> 747636 T2654 C2430 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[319 (1452773424128589824)]} 0 36
   [junit4]   2> 747637 T2630 C2433 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={wt=javabin&version=2} {add=[266 (1452773424150609920), 268 (1452773424150609921), 269 (1452773424150609922), 271 (1452773424150609923), 274 (1452773424150609924), 275 (1452773424151658496), 276 (1452773424151658497), 277 (1452773424151658498), 278 (1452773424151658499), 279 (1452773424151658500), ... (32 adds)]} 0 17
   [junit4]   2> 747644 T2622 C2431 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747655 T2622 C2431 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dcf087 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@edd061),segFN=segments_4,generation=4}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1dcf087 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@edd061),segFN=segments_5,generation=5}
   [junit4]   2> 747655 T2622 C2431 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 5
   [junit4]   2> 747661 T2622 C2431 oass.SolrIndexSearcher.<init> Opening Searcher@10b2ca5 main
   [junit4]   2> 747662 T2625 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@10b2ca5 main{StandardDirectoryReader(segments_5:8:nrt _1(4.7):c2 _2(4.7):c158)}
   [junit4]   2> 747663 T2622 C2431 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747663 T2622 C2431 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 19
   [junit4]   2> 747667 T2631 C2433 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747678 T2631 C2433 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@531a9b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f7f39a),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@531a9b lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@f7f39a),segFN=segments_2,generation=2}
   [junit4]   2> 747679 T2631 C2433 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 747679 T2631 C2433 oass.SolrIndexSearcher.<init> Opening Searcher@1605f47 main
   [junit4]   2> 747683 T2631 C2433 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747683 T2637 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@1605f47 main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):c162)}
   [junit4]   2> 747684 T2631 C2433 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 17
   [junit4]   2> 747686 T2645 C2432 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747691 T2645 C2432 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@9442 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cff871),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@9442 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@1cff871),segFN=segments_2,generation=2}
   [junit4]   2> 747691 T2645 C2432 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 747691 T2645 C2432 oass.SolrIndexSearcher.<init> Opening Searcher@3000ac main
   [junit4]   2> 747692 T2649 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@3000ac main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):c166)}
   [junit4]   2> 747693 T2645 C2432 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747693 T2645 C2432 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 7
   [junit4]   2> 747711 T2652 C2430 oasu.DirectUpdateHandler2.commit start commit{,optimize=false,openSearcher=true,waitSearcher=true,expungeDeletes=false,softCommit=false,prepareCommit=false}
   [junit4]   2> 747723 T2652 C2430 oasc.SolrDeletionPolicy.onCommit SolrDeletionPolicy.onCommit: commits: num=2
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1d2c012 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30ec7f),segFN=segments_1,generation=1}
   [junit4]   2> 		commit{dir=MockDirectoryWrapper(org.apache.lucene.store.RAMDirectory@1d2c012 lockFactory=org.apache.lucene.store.SingleInstanceLockFactory@30ec7f),segFN=segments_2,generation=2}
   [junit4]   2> 747725 T2652 C2430 oasc.SolrDeletionPolicy.updateCommits newest commit generation = 2
   [junit4]   2> 747725 T2652 C2430 oass.SolrIndexSearcher.<init> Opening Searcher@5468f0 main
   [junit4]   2> 747726 T2661 oasc.SolrCore.registerSearcher [collection1] Registered new searcher Searcher@5468f0 main{StandardDirectoryReader(segments_2:3:nrt _0(4.7):c152)}
   [junit4]   2> 747726 T2652 C2430 oasu.DirectUpdateHandler2.commit end_commit_flush
   [junit4]   2> 747726 T2652 C2430 oasup.LogUpdateProcessor.finish [collection1] webapp= path=/update params={waitSearcher=true&commit=true&softCommit=false&commit_end_point=true&wt=javabin&version=2&expungeDeletes=false} {commit=} 0 28
   [junit4]   2> 747768 T2618 C2431 oasc.SolrCore.execute [collection1] webapp= path=/admin/luke params={wt=javabin&version=2} status=0 QTime=34 
   [junit4]   2> 747775 T2633 C2433 oasc.SolrCore.execute [collection1] webapp= path=/admin/luke params={wt=javabin&version=2} status=0 QTime=4 
   [junit4]   2> 747784 T2646 C2432 oasc.SolrCore.execute [collection1] webapp= path=/admin/luke params={wt=javabin&version=2} status=0 QTime=6 
   [junit4]   2> 747790 T2657 C2430 oasc.SolrCore.execute [collection1] webapp= path=/admin/luke params={wt=javabin&version=2} status=0 QTime=4 
   [junit4]   2> 747796 T2602 oasc.SolrException.log ERROR java.net.ConnectException
   [junit4]   2> 		at org.apache.solr.update.MockStreamingSolrServers.exception(MockStreamingSolrServers.java:55)
   [junit4]   2> 		at org.apache.solr.update.MockStreamingSolrServers.access$100(MockStreamingSolrServers.java:30)
   [junit4]   2> 		at org.apache.solr.update.MockStreamingSolrServers$MockSolrServer.request(MockStreamingSolrServers.java:75)
   [junit4]   2> 		at org.apache.solr.update.SolrCmdDistributor.submit(SolrCmdDistributor.java:234)
   [junit4]   2> 		at org.apache.solr.update.SolrCmdDistributor.distribAdd(SolrCmdDistributor.java:183)
   [junit4]   2> 		at org.apache.solr.update.SolrCmdDistributor.distribAdd(SolrCmdDistributor.java:174)
   [junit4]   2> 		at org.apache.solr.update.SolrCmdDistributorTest.testMaxRetries(SolrCmdDistributorTest.java:347)
   [junit4]   2> 		at org.apache.solr.update.SolrCmdDistributorTest.doTest(SolrCmdDistributorTest.java:319)
   [junit4]   2> 		at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
   [junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   [junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:483)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
   [junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleThreadAndTestName$1.evaluate(TestRuleThreadAndTestName.java:49)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl.forkTimeoutingTask(ThreadLeakControl.java:782)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$3.evaluate(ThreadLeakControl.java:442)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.runSingleTest(RandomizedRunner.java:746)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$3.evaluate(RandomizedRunner.java:648)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$4.evaluate(RandomizedRunner.java:682)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$5.evaluate(RandomizedRunner.java:693)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
   [junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleStoreClassName$1.evaluate(TestRuleStoreClassName.java:42)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvariantRule.java:55)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.NoShadowingOrOverridesOnMethodsRule$1.evaluate(NoShadowingOrOverridesOnMethodsRule.java:39)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleAssertionsRequired$1.evaluate(TestRuleAssertionsRequired.java:43)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleMarkFailure$1.evaluate(TestRuleMarkFailure.java:48)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreAfterMaxFailures$1.evaluate(TestRuleIgnoreAfterMaxFailures.java:70)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleIgnoreTestSuites$1.evaluate(TestRuleIgnoreTestSuites.java:55)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.StatementAdapter.evaluate(StatementAdapter.java:36)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.ThreadLeakControl$StatementRunner.run(ThreadLeakControl.java:358)
   [junit4]   2> 		at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 	
   [junit4]   2> 747798 T2602 oasc.SolrException.log ERROR forwarding update to http://127.0.0.1:54252/ failed - retrying ... retries: 1
   [junit4]   2> 747798 T2602 oasc.SolrException.log ERROR java.net.ConnectException
   [junit4]   2> 		at org.apache.solr.update.MockStreamingSolrServers.exception(MockStreamingSolrServers.java:55)
   [junit4]   2> 		at org.apache.solr.update.MockStreamingSolrServers.access$100(MockStreamingSolrServers.java:30)
   [junit4]   2> 		at org.apache.solr.update.MockStreamingSolrServers$MockSolrServer.request(MockStreamingSolrServers.java:75)
   [junit4]   2> 		at org.apache.solr.update.SolrCmdDistributor.submit(SolrCmdDistributor.java:234)
   [junit4]   2> 		at org.apache.solr.update.SolrCmdDistributor.doRetriesIfNeeded(SolrCmdDistributor.java:145)
   [junit4]   2> 		at org.apache.solr.update.SolrCmdDistributor.finish(SolrCmdDistributor.java:71)
   [junit4]   2> 		at org.apache.solr.update.SolrCmdDistributorTest.testMaxRetries(SolrCmdDistributorTest.java:348)
   [junit4]   2> 		at org.apache.solr.update.SolrCmdDistributorTest.doTest(SolrCmdDistributorTest.java:319)
   [junit4]   2> 		at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
   [junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke0(Native Method)
   [junit4]   2> 		at sun.reflect.NativeMethodAccessorImpl.invoke(NativeMethodAccessorImpl.java:57)
   [junit4]   2> 		at sun.reflect.DelegatingMethodAccessorImpl.invoke(DelegatingMethodAccessorImpl.java:43)
   [junit4]   2> 		at java.lang.reflect.Method.invoke(Method.java:483)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.invoke(RandomizedRunner.java:1559)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner.access$600(RandomizedRunner.java:79)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$6.evaluate(RandomizedRunner.java:737)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$7.evaluate(RandomizedRunner.java:773)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.RandomizedRunner$8.evaluate(RandomizedRunner.java:787)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesRestoreRule$1.evaluate(SystemPropertiesRestoreRule.java:53)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleSetupTeardownChained$1.evaluate(TestRuleSetupTeardownChained.java:50)
   [junit4]   2> 		at org.apache.lucene.util.TestRuleFieldCacheSanity$1.evaluate(TestRuleFieldCacheSanity.java:51)
   [junit4]   2> 		at org.apache.lucene.util.AbstractBeforeAfterRule$1.evaluate(AbstractBeforeAfterRule.java:46)
   [junit4]   2> 		at com.carrotsearch.randomizedtesting.rules.SystemPropertiesInvariantRule$1.evaluate(SystemPropertiesInvari

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

CoreContainer instance=27433234
   [junit4]   2> 750550 T2602 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@13743dd
   [junit4]   2> 750556 T2602 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=1,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=166,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=5865,transaction_logs_total_number=1}
   [junit4]   2> 750556 T2602 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 750556 T2602 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 750556 T2602 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 750561 T2602 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 750562 T2602 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 750563 T2602 oasc.CachingDirectoryFactory.closeCacheValue looking to close .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard2/data\index [CachedDir<<refCount=0;path=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard2/data\index;done=false>>]
   [junit4]   2> 750563 T2602 oasc.CachingDirectoryFactory.close Closing directory: .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard2/data\index
   [junit4]   2> 750563 T2602 oasc.CachingDirectoryFactory.closeCacheValue looking to close .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard2/data\ [CachedDir<<refCount=0;path=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard2/data\;done=false>>]
   [junit4]   2> 750563 T2602 oasc.CachingDirectoryFactory.close Closing directory: .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard2/data\
   [junit4]   2> 750564 T2602 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
   [junit4]   2> 750632 T2602 oasc.CoreContainer.shutdown Shutting down CoreContainer instance=1729471
   [junit4]   2> 750634 T2602 oasc.SolrCore.close [collection1]  CLOSING SolrCore org.apache.solr.core.SolrCore@5ed2ca
   [junit4]   2> 750645 T2602 oasu.DirectUpdateHandler2.close closing DirectUpdateHandler2{commits=1,autocommits=0,soft autocommits=0,optimizes=1,rollbacks=0,expungeDeletes=0,docsPending=0,adds=0,deletesById=0,deletesByQuery=0,errors=0,cumulative_adds=152,cumulative_deletesById=0,cumulative_deletesByQuery=1,cumulative_errors=0,transaction_logs_total_size=5375,transaction_logs_total_number=1}
   [junit4]   2> 750645 T2602 oasu.SolrCoreState.decrefSolrCoreState Closing SolrCoreState
   [junit4]   2> 750645 T2602 oasu.DefaultSolrCoreState.closeIndexWriter SolrCoreState ref count has reached 0 - closing IndexWriter
   [junit4]   2> 750645 T2602 oasu.DefaultSolrCoreState.closeIndexWriter closing IndexWriter with IndexWriterCloser
   [junit4]   2> 750651 T2602 oasc.SolrCore.closeSearcher [collection1] Closing main searcher on request.
   [junit4]   2> 750651 T2602 oasc.CachingDirectoryFactory.close Closing MockDirectoryFactory - 2 directories currently being tracked
   [junit4]   2> 750651 T2602 oasc.CachingDirectoryFactory.closeCacheValue looking to close .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard3/data\ [CachedDir<<refCount=0;path=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard3/data\;done=false>>]
   [junit4]   2> 750651 T2602 oasc.CachingDirectoryFactory.close Closing directory: .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard3/data\
   [junit4]   2> 750651 T2602 oasc.CachingDirectoryFactory.closeCacheValue looking to close .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard3/data\index [CachedDir<<refCount=0;path=.\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard3/data\index;done=false>>]
   [junit4]   2> 750651 T2602 oasc.CachingDirectoryFactory.close Closing directory: .\org.apache.solr.update.SolrCmdDistributorTest-1385472700063/shard3/data\index
   [junit4]   2> 750651 T2602 oejsh.ContextHandler.doStop stopped o.e.j.s.ServletContextHandler{/,null}
   [junit4]   2> 750764 T2602 oas.SolrTestCaseJ4.tearDown ###Ending testDistribSearch
   [junit4]   2> NOTE: reproduce with: ant test  -Dtestcase=SolrCmdDistributorTest -Dtests.method=testDistribSearch -Dtests.seed=7ED18E75C3A321BE -Dtests.slow=true -Dtests.locale=de_GR -Dtests.timezone=Antarctica/Rothera -Dtests.file.encoding=ISO-8859-1
   [junit4] FAILURE 5.27s | SolrCmdDistributorTest.testDistribSearch <<<
   [junit4]    > Throwable #1: java.lang.AssertionError: expected:<161> but was:<162>
   [junit4]    > 	at __randomizedtesting.SeedInfo.seed([7ED18E75C3A321BE:FF37006DB4FC4182]:0)
   [junit4]    > 	at org.apache.solr.update.SolrCmdDistributorTest.testRetryNode(SolrCmdDistributorTest.java:443)
   [junit4]    > 	at org.apache.solr.update.SolrCmdDistributorTest.doTest(SolrCmdDistributorTest.java:321)
   [junit4]    > 	at org.apache.solr.BaseDistributedSearchTestCase.testDistribSearch(BaseDistributedSearchTestCase.java:835)
   [junit4]    > 	at java.lang.Thread.run(Thread.java:744)
   [junit4]   2> 750777 T2602 oas.SolrTestCaseJ4.deleteCore ###deleteCore
   [junit4]   2> NOTE: test params are: codec=Lucene40, sim=RandomSimilarityProvider(queryNorm=true,coord=yes): {}, locale=de_GR, timezone=Antarctica/Rothera
   [junit4]   2> NOTE: Windows 7 6.1 x86/Oracle Corporation 1.8.0-ea (32-bit)/cpus=2,threads=1,free=38394312,total=130973696
   [junit4]   2> NOTE: All tests run in this JVM: [SliceStateUpdateTest, TestRTGBase, DebugComponentTest, TermVectorComponentDistributedTest, BadIndexSchemaTest, TestCoreDiscovery, QueryResultKeyTest, TestPHPSerializedResponseWriter, TestStressVersions, TestWordDelimiterFilterFactory, AssignTest, TestIndexingPerformance, AlternateDirectoryTest, StandardRequestHandlerTest, DisMaxRequestHandlerTest, TestLMJelinekMercerSimilarityFactory, RequiredFieldsTest, CircularListTest, SolrTestCaseJ4Test, DirectUpdateHandlerOptimizeTest, TestCloudManagedSchemaAddField, TestSolrQueryParserResource, ChangedSchemaMergeTest, CurrencyFieldOpenExchangeTest, SynonymTokenizerTest, IndexSchemaTest, CollectionsAPIDistributedZkTest, AliasIntegrationTest, AnalysisAfterCoreReloadTest, LeaderElectionIntegrationTest, InfoHandlerTest, ZkControllerTest, UpdateParamsTest, TestShardHandlerFactory, BinaryUpdateRequestHandlerTest, TestLRUCache, TestBM25SimilarityFactory, RAMDirectoryFactoryTest, UUIDFieldTest, EnumFieldTest, TestWriterPerf, TermVectorComponentTest, TestExtendedDismaxParser, CacheHeaderTest, TestStressUserVersions, ReturnFieldsTest, BasicZkTest, DocumentBuilderTest, SystemInfoHandlerTest, HdfsBasicDistributedZk2Test, FieldMutatingUpdateProcessorTest, ZkSolrClientTest, CurrencyFieldXmlFileTest, DOMUtilTest, TestFunctionQuery, CoreContainerCoreInitFailuresTest, FastVectorHighlighterTest, MinimalSchemaTest, ShardRoutingCustomTest, SyncSliceTest, TestRecoveryHdfs, TestCollationField, CoreAdminCreateDiscoverTest, RecoveryZkTest, DateFieldTest, TestRandomMergePolicy, TestCopyFieldCollectionResource, FileUtilsTest, TestRandomDVFaceting, TestCSVLoader, TestComponentsName, ParsingFieldUpdateProcessorsTest, TestDynamicFieldCollectionResource, TestFastLRUCache, DefaultValueUpdateProcessorTest, TestMergePolicyConfig, DirectUpdateHandlerTest, TestPerFieldSimilarity, SuggesterFSTTest, EchoParamsTest, TestValueSourceCache, DistributedQueryElevationComponentTest, MoreLikeThisHandlerTest, TestFieldTypeCollectionResource, IndexBasedSpellCheckerTest, ExternalFileFieldSortTest, BadComponentTest, StatsComponentTest, TestCollapseQParserPlugin, DeleteReplicaTest, HdfsLockFactoryTest, ZkNodePropsTest, TestSchemaVersionResource, HdfsChaosMonkeySafeLeaderTest, WordBreakSolrSpellCheckerTest, ConvertedLegacyTest, XmlUpdateRequestHandlerTest, BadCopyFieldTest, TestDistribDocBasedVersion, DeleteShardTest, BlockCacheTest, TestDefaultSearchFieldResource, ScriptEngineTest, TestUtils, TestQuerySenderListener, TestElisionMultitermQuery, QueryEqualityTest, TestPartialUpdateDeduplication, DistributedDebugComponentTest, TestSchemaNameResource, TestHighFrequencyDictionaryFactory, QueryElevationComponentTest, HdfsRecoveryZkTest, SolrInfoMBeanTest, OutputWriterTest, SolrCmdDistributorTest]
   [junit4] Completed in 5.29s, 1 test, 1 failure <<< FAILURES!

[...truncated 714 lines...]
BUILD FAILED
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:426: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\build.xml:406: 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\extra-targets.xml:37: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\build.xml:189: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\solr\common-build.xml:491: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:1279: The following error occurred while executing this line:
C:\Users\JenkinsSlave\workspace\Lucene-Solr-4.x-Windows\lucene\common-build.xml:912: There were test failures: 345 suites, 1505 tests, 1 failure, 39 ignored (11 assumptions)

Total time: 69 minutes 4 seconds
Build step 'Invoke Ant' marked build as failure
Description set: Java: 32bit/jdk1.8.0-ea-b116 -server -XX:+UseConcMarkSweepGC
Archiving artifacts
Recording test results
Email was triggered for: Failure
Sending email for trigger: Failure



Mime
View raw message